2024-01-23T09:08:18.794536Z INFO kernel: [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x414fd0b1] 2024-01-23T09:08:18.794686Z NOTICE kernel: [ 0.000000] Linux version 6.1.77-v8-g21012295fe87-dirty (builty@fydebeast) (Chromium OS 17.0_pre498229-r26 clang version 17.0.0 (/mnt/host/source/src/third_party/llvm-project 14f0776550b5a49e1c42f49a00213f7f3fa047bf), LLD 17.0.0) #2 SMP PREEMPT Wed Apr 24 13:52:38 UTC 2024 2024-01-23T09:08:18.794689Z NOTICE kernel: [ 0.000000] random: crng init done 2024-01-23T09:08:18.794690Z INFO kernel: [ 0.000000] Machine model: Raspberry Pi 5 Model B Rev 1.0 2024-01-23T09:08:18.794692Z INFO kernel: [ 0.000000] efi: UEFI not found. 2024-01-23T09:08:18.794693Z INFO kernel: [ 0.000000] Reserved memory: created CMA memory pool at 0x000000000ec00000, size 512 MiB 2024-01-23T09:08:18.794694Z INFO kernel: [ 0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool 2024-01-23T09:08:18.794698Z INFO kernel: [ 0.000000] Zone ranges: 2024-01-23T09:08:18.794699Z INFO kernel: [ 0.000000] DMA [mem 0x0000000000000000-0x00000000ffffffff] 2024-01-23T09:08:18.794700Z INFO kernel: [ 0.000000] DMA32 empty 2024-01-23T09:08:18.794701Z INFO kernel: [ 0.000000] Normal empty 2024-01-23T09:08:18.794702Z INFO kernel: [ 0.000000] Movable zone start for each node 2024-01-23T09:08:18.794703Z INFO kernel: [ 0.000000] Early memory node ranges 2024-01-23T09:08:18.794706Z INFO kernel: [ 0.000000] node 0: [mem 0x0000000000000000-0x000000000007ffff] 2024-01-23T09:08:18.794707Z INFO kernel: [ 0.000000] node 0: [mem 0x0000000000080000-0x000000003f7fffff] 2024-01-23T09:08:18.794708Z INFO kernel: [ 0.000000] node 0: [mem 0x0000000040000000-0x00000000ffffffff] 2024-01-23T09:08:18.794710Z INFO kernel: [ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000000ffffffff] 2024-01-23T09:08:18.794711Z INFO kernel: [ 0.000000] On node 0, zone DMA: 2048 pages in unavailable ranges 2024-01-23T09:08:18.794712Z INFO kernel: [ 0.000000] psci: probing for conduit method from DT. 2024-01-23T09:08:18.794713Z INFO kernel: [ 0.000000] psci: PSCIv1.1 detected in firmware. 2024-01-23T09:08:18.794716Z INFO kernel: [ 0.000000] psci: Using standard PSCI v0.2 function IDs 2024-01-23T09:08:18.794717Z INFO kernel: [ 0.000000] psci: MIGRATE_INFO_TYPE not supported. 2024-01-23T09:08:18.794718Z INFO kernel: [ 0.000000] psci: SMC Calling Convention v1.2 2024-01-23T09:08:18.794719Z INFO kernel: [ 0.000000] percpu: Embedded 29 pages/cpu s80488 r8192 d30104 u118784 2024-01-23T09:08:18.794720Z DEBUG kernel: [ 0.000000] pcpu-alloc: s80488 r8192 d30104 u118784 alloc=29*4096 2024-01-23T09:08:18.794722Z DEBUG kernel: [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 2024-01-23T09:08:18.794725Z INFO kernel: [ 0.000000] Detected PIPT I-cache on CPU0 2024-01-23T09:08:18.794726Z INFO kernel: [ 0.000000] CPU features: detected: Virtualization Host Extensions 2024-01-23T09:08:18.794727Z INFO kernel: [ 0.000000] CPU features: detected: Hardware dirty bit management 2024-01-23T09:08:18.794728Z INFO kernel: [ 0.000000] CPU features: detected: Spectre-v4 2024-01-23T09:08:18.794729Z INFO kernel: [ 0.000000] CPU features: detected: Spectre-BHB 2024-01-23T09:08:18.794730Z INFO kernel: [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR 2024-01-23T09:08:18.794734Z INFO kernel: [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) 2024-01-23T09:08:18.794736Z INFO kernel: [ 0.000000] alternatives: applying boot alternatives 2024-01-23T09:08:18.794737Z INFO kernel: [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1030144 2024-01-23T09:08:18.794738Z NOTICE kernel: [ 0.000000] Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 smsc95xx.macaddr=2C:CF:67:1A:E0:8F vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 coherent_pool=6M smsc95xx.turbo_mode=N dwc_otg.lpm_enable=0 console=ttyAMA10,115200 earlyprintk=ttyAMA10,115200 log_level=7 root=PARTUUID=67076F0C-7507-8549-995D-ABFB687D792C rootfstype=ext2 rootwait ro cros_debug cros_secure 2024-01-23T09:08:18.794739Z NOTICE kernel: [ 0.000000] Unknown kernel command line parameters "cros_debug cros_secure earlyprintk=ttyAMA10,115200 log_level=7", will be passed to user space. 2024-01-23T09:08:18.794741Z INFO kernel: [ 0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) 2024-01-23T09:08:18.794744Z INFO kernel: [ 0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) 2024-01-23T09:08:18.794745Z INFO kernel: [ 0.000000] mem auto-init: stack:off, heap alloc:on, heap free:off 2024-01-23T09:08:18.794746Z INFO kernel: [ 0.000000] Memory: 3558000K/4186112K available (12736K kernel code, 1662K rwdata, 3424K rodata, 1472K init, 1006K bss, 103824K reserved, 524288K cma-reserved) 2024-01-23T09:08:18.794747Z INFO kernel: [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 2024-01-23T09:08:18.794748Z INFO kernel: [ 0.000000] ftrace: allocating 39187 entries in 154 pages 2024-01-23T09:08:18.794749Z INFO kernel: [ 0.000000] ftrace: allocated 154 pages with 4 groups 2024-01-23T09:08:18.794752Z INFO kernel: [ 0.000000] trace event string verifier disabled 2024-01-23T09:08:18.794754Z INFO kernel: [ 0.000000] rcu: Preemptible hierarchical RCU implementation. 2024-01-23T09:08:18.794755Z INFO kernel: [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4. 2024-01-23T09:08:18.794756Z INFO kernel: [ 0.000000] Trampoline variant of Tasks RCU enabled. 2024-01-23T09:08:18.794757Z INFO kernel: [ 0.000000] Rude variant of Tasks RCU enabled. 2024-01-23T09:08:18.794758Z INFO kernel: [ 0.000000] Tracing variant of Tasks RCU enabled. 2024-01-23T09:08:18.794761Z INFO kernel: [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. 2024-01-23T09:08:18.794762Z INFO kernel: [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 2024-01-23T09:08:18.794763Z INFO kernel: [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 2024-01-23T09:08:18.794764Z INFO kernel: [ 0.000000] Root IRQ handler: gic_handle_irq 2024-01-23T09:08:18.794765Z INFO kernel: [ 0.000000] GIC: Using split EOI/Deactivate mode 2024-01-23T09:08:18.794766Z INFO kernel: [ 0.000000] rcu: Offload RCU callbacks from CPUs: 0-3. 2024-01-23T09:08:18.794767Z INFO kernel: [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. 2024-01-23T09:08:18.794771Z INFO kernel: [ 0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys). 2024-01-23T09:08:18.794773Z INFO kernel: [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns 2024-01-23T09:08:18.794774Z INFO kernel: [ 0.000000] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns 2024-01-23T09:08:18.794775Z INFO kernel: [ 0.000201] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) 2024-01-23T09:08:18.794776Z INFO kernel: [ 0.000239] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) 2024-01-23T09:08:18.794779Z INFO kernel: [ 0.000244] pid_max: default: 32768 minimum: 301 2024-01-23T09:08:18.794781Z INFO kernel: [ 0.000279] LSM: Security Framework initializing 2024-01-23T09:08:18.794782Z INFO kernel: [ 0.000296] landlock: Up and running. 2024-01-23T09:08:18.794783Z INFO kernel: [ 0.000298] Yama: becoming mindful. 2024-01-23T09:08:18.794784Z INFO kernel: [ 0.000305] LoadPin: ready to pin (currently not enforcing) 2024-01-23T09:08:18.794785Z INFO kernel: [ 0.000311] Chromium OS LSM: enabled 2024-01-23T09:08:18.794786Z INFO kernel: [ 0.000312] SELinux: Initializing. 2024-01-23T09:08:18.794789Z INFO kernel: [ 0.000386] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) 2024-01-23T09:08:18.794790Z INFO kernel: [ 0.000405] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) 2024-01-23T09:08:18.794791Z INFO kernel: [ 0.001058] cblist_init_generic: Setting adjustable number of callback queues. 2024-01-23T09:08:18.794792Z INFO kernel: [ 0.001060] cblist_init_generic: Setting shift to 2 and lim to 1. 2024-01-23T09:08:18.794793Z INFO kernel: [ 0.001085] cblist_init_generic: Setting adjustable number of callback queues. 2024-01-23T09:08:18.794794Z INFO kernel: [ 0.001086] cblist_init_generic: Setting shift to 2 and lim to 1. 2024-01-23T09:08:18.794798Z INFO kernel: [ 0.001117] cblist_init_generic: Setting adjustable number of callback queues. 2024-01-23T09:08:18.794799Z INFO kernel: [ 0.001118] cblist_init_generic: Setting shift to 2 and lim to 1. 2024-01-23T09:08:18.794800Z INFO kernel: [ 0.001180] rcu: Hierarchical SRCU implementation. 2024-01-23T09:08:18.794801Z INFO kernel: [ 0.001181] rcu: Max phase no-delay instances is 1000. 2024-01-23T09:08:18.794802Z INFO kernel: [ 0.001810] EFI services will not be available. 2024-01-23T09:08:18.794803Z INFO kernel: [ 0.001914] smp: Bringing up secondary CPUs ... 2024-01-23T09:08:18.794804Z INFO kernel: [ 0.002126] Detected PIPT I-cache on CPU1 2024-01-23T09:08:18.794809Z INFO kernel: [ 0.002172] CPU1: Booted secondary processor 0x0000000100 [0x414fd0b1] 2024-01-23T09:08:18.794810Z INFO kernel: [ 0.002444] Detected PIPT I-cache on CPU2 2024-01-23T09:08:18.794811Z INFO kernel: [ 0.002484] CPU2: Booted secondary processor 0x0000000200 [0x414fd0b1] 2024-01-23T09:08:18.794812Z INFO kernel: [ 0.002699] Detected PIPT I-cache on CPU3 2024-01-23T09:08:18.794813Z INFO kernel: [ 0.002737] CPU3: Booted secondary processor 0x0000000300 [0x414fd0b1] 2024-01-23T09:08:18.794814Z INFO kernel: [ 0.002766] smp: Brought up 1 node, 4 CPUs 2024-01-23T09:08:18.794819Z INFO kernel: [ 0.002769] SMP: Total of 4 processors activated. 2024-01-23T09:08:18.794820Z INFO kernel: [ 0.002771] CPU features: detected: 32-bit EL0 Support 2024-01-23T09:08:18.794821Z INFO kernel: [ 0.002774] CPU features: detected: Data cache clean to the PoU not required for I/D coherence 2024-01-23T09:08:18.794822Z INFO kernel: [ 0.002776] CPU features: detected: Common not Private translations 2024-01-23T09:08:18.794823Z INFO kernel: [ 0.002778] CPU features: detected: CRC32 instructions 2024-01-23T09:08:18.794824Z INFO kernel: [ 0.002779] CPU features: detected: RCpc load-acquire (LDAPR) 2024-01-23T09:08:18.794827Z INFO kernel: [ 0.002780] CPU features: detected: LSE atomic instructions 2024-01-23T09:08:18.794828Z INFO kernel: [ 0.002782] CPU features: detected: Privileged Access Never 2024-01-23T09:08:18.794829Z INFO kernel: [ 0.002783] CPU features: detected: RAS Extension Support 2024-01-23T09:08:18.794830Z INFO kernel: [ 0.002785] CPU features: detected: Speculative Store Bypassing Safe (SSBS) 2024-01-23T09:08:18.794831Z INFO kernel: [ 0.002820] CPU: All CPU(s) started at EL2 2024-01-23T09:08:18.794832Z INFO kernel: [ 0.002821] alternatives: applying system-wide alternatives 2024-01-23T09:08:18.794833Z INFO kernel: [ 0.006249] devtmpfs: initialized 2024-01-23T09:08:18.794837Z NOTICE kernel: [ 0.009101] Enabled cp15_barrier support 2024-01-23T09:08:18.794838Z NOTICE kernel: [ 0.009107] Enabled setend support 2024-01-23T09:08:18.794839Z INFO kernel: [ 0.009167] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns 2024-01-23T09:08:18.794840Z INFO kernel: [ 0.009185] futex hash table entries: 1024 (order: 4, 65536 bytes, linear) 2024-01-23T09:08:18.794841Z INFO kernel: [ 0.014608] pinctrl core: initialized pinctrl subsystem 2024-01-23T09:08:18.794842Z INFO kernel: [ 0.014787] DMI not present or invalid. 2024-01-23T09:08:18.794845Z INFO kernel: [ 0.014940] NET: Registered PF_NETLINK/PF_ROUTE protocol family 2024-01-23T09:08:18.794936Z INFO kernel: [ 0.015767] DMA: preallocated 4096 KiB GFP_KERNEL pool for atomic allocations 2024-01-23T09:08:18.794938Z INFO kernel: [ 0.016370] DMA: preallocated 4096 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations 2024-01-23T09:08:18.794939Z INFO kernel: [ 0.016969] DMA: preallocated 4096 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations 2024-01-23T09:08:18.794940Z INFO kernel: [ 0.016987] audit: initializing netlink subsys (disabled) 2024-01-23T09:08:18.794941Z NOTICE kernel: [ 0.017070] audit: type=2000 audit(0.012:1): state=initialized audit_enabled=0 res=1 2024-01-23T09:08:18.794944Z INFO kernel: [ 0.017181] thermal_sys: Registered thermal governor 'step_wise' 2024-01-23T09:08:18.794946Z INFO kernel: [ 0.017193] cpuidle: using governor menu 2024-01-23T09:08:18.794947Z INFO kernel: [ 0.017252] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. 2024-01-23T09:08:18.794948Z INFO kernel: [ 0.017282] ASID allocator initialised with 32768 entries 2024-01-23T09:08:18.794949Z INFO kernel: [ 0.017571] Serial: AMBA PL011 UART driver 2024-01-23T09:08:18.794950Z INFO kernel: [ 0.018474] bcm2835-mbox 107c013880.mailbox: mailbox enabled 2024-01-23T09:08:18.794951Z INFO kernel: [ 0.018781] 107d001000.serial: ttyAMA10 at MMIO 0x107d001000 (irq = 15, base_baud = 0) is a PL011 rev2 2024-01-23T09:08:18.794954Z INFO kernel: [ 0.766644] printk: console [ttyAMA10] enabled 2024-01-23T09:08:18.794956Z INFO kernel: [ 0.779112] raspberrypi-firmware soc:firmware: Attached to firmware from 2024-04-20T10:53:30, variant start_cd 2024-01-23T09:08:18.794957Z INFO kernel: [ 0.793163] raspberrypi-firmware soc:firmware: Firmware hash is d1744d2100000000000000000000000000000000 2024-01-23T09:08:18.794958Z INFO kernel: [ 0.808884] KASLR enabled 2024-01-23T09:08:18.794959Z INFO kernel: [ 0.816618] bcm2835-dma 1000010000.dma: DMA legacy API manager, dmachans=0x1 2024-01-23T09:08:18.794960Z INFO kernel: [ 0.824300] iommu: Default domain type: Translated 2024-01-23T09:08:18.794963Z INFO kernel: [ 0.829196] iommu: DMA domain TLB invalidation policy: strict mode 2024-01-23T09:08:18.794965Z NOTICE kernel: [ 0.835579] SCSI subsystem initialized 2024-01-23T09:08:18.794966Z DEBUG kernel: [ 0.839343] libata version 3.00 loaded. 2024-01-23T09:08:18.794967Z INFO kernel: [ 0.839392] usbcore: registered new interface driver usbfs 2024-01-23T09:08:18.794968Z INFO kernel: [ 0.844903] usbcore: registered new interface driver hub 2024-01-23T09:08:18.794969Z INFO kernel: [ 0.850242] usbcore: registered new device driver usb 2024-01-23T09:08:18.794972Z WARNING kernel: [ 0.855351] usb_phy_generic phy: supply vcc not found, using dummy regulator 2024-01-23T09:08:18.794973Z INFO kernel: [ 0.862542] pps_core: LinuxPPS API ver. 1 registered 2024-01-23T09:08:18.794974Z INFO kernel: [ 0.867526] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti 2024-01-23T09:08:18.794975Z INFO kernel: [ 0.876705] PTP clock support registered 2024-01-23T09:08:18.794976Z INFO kernel: [ 0.880683] Advanced Linux Sound Architecture Driver Initialized. 2024-01-23T09:08:18.794977Z INFO kernel: [ 0.886981] vgaarb: loaded 2024-01-23T09:08:18.794980Z INFO kernel: [ 0.889790] clocksource: Switched to clocksource arch_sys_counter 2024-01-23T09:08:18.794981Z NOTICE kernel: [ 0.896052] VFS: Disk quotas dquot_6.6.0 2024-01-23T09:08:18.794982Z INFO kernel: [ 0.900019] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) 2024-01-23T09:08:18.794983Z INFO kernel: [ 0.907373] NET: Registered PF_INET protocol family 2024-01-23T09:08:18.794984Z INFO kernel: [ 0.912386] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) 2024-01-23T09:08:18.794986Z INFO kernel: [ 0.921061] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) 2024-01-23T09:08:18.794987Z INFO kernel: [ 0.929722] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) 2024-01-23T09:08:18.794989Z INFO kernel: [ 0.937538] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) 2024-01-23T09:08:18.794991Z INFO kernel: [ 0.945741] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) 2024-01-23T09:08:18.794992Z INFO kernel: [ 0.953959] TCP: Hash tables configured (established 32768 bind 32768) 2024-01-23T09:08:18.794993Z INFO kernel: [ 0.960615] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) 2024-01-23T09:08:18.794994Z INFO kernel: [ 0.967419] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) 2024-01-23T09:08:18.794995Z INFO kernel: [ 0.974697] NET: Registered PF_UNIX/PF_LOCAL protocol family 2024-01-23T09:08:18.794999Z INFO kernel: [ 0.980404] PCI: CLS 0 bytes, default 64 2024-01-23T09:08:18.795000Z INFO kernel: [ 0.984785] hw perfevents: enabled with armv8_cortex_a76 PMU driver, 7 counters available 2024-01-23T09:08:18.795001Z INFO kernel: [ 0.993129] kvm [1]: IPA Size Limit: 40 bits 2024-01-23T09:08:18.795002Z INFO kernel: [ 0.997462] kvm [1]: vgic interrupt IRQ9 2024-01-23T09:08:18.795003Z INFO kernel: [ 1.001434] kvm [1]: VHE mode initialized successfully 2024-01-23T09:08:18.795004Z NOTICE kernel: [ 1.006979] Initialise system trusted keyrings 2024-01-23T09:08:18.795007Z INFO kernel: [ 1.011486] workingset: timestamp_bits=62 max_order=20 bucket_order=0 2024-01-23T09:08:18.795008Z INFO kernel: [ 1.019527] squashfs: version 4.0 (2009/01/31) Phillip Lougher 2024-01-23T09:08:18.795009Z INFO kernel: [ 1.025385] Registering esdfs 0.2 2024-01-23T09:08:18.795010Z INFO kernel: [ 1.028808] alt_syscall: table 'read_write_test' available. 2024-01-23T09:08:18.795011Z INFO kernel: [ 1.034419] alt_syscall: table 'android' available. 2024-01-23T09:08:18.795012Z INFO kernel: [ 1.039335] alt_syscall: table 'android_permissive' available. 2024-01-23T09:08:18.795013Z INFO kernel: [ 1.045196] alt_syscall: table 'third_party' available. 2024-01-23T09:08:18.795016Z INFO kernel: [ 1.050444] alt_syscall: table 'third_party_permissive' available. 2024-01-23T09:08:18.795017Z INFO kernel: [ 1.056654] alt_syscall: table 'complete' available. 2024-01-23T09:08:18.795018Z INFO kernel: [ 1.061650] alt_syscall: table 'complete_permissive' available. 2024-01-23T09:08:18.795019Z NOTICE kernel: [ 1.067723] Key type asymmetric registered 2024-01-23T09:08:18.795020Z NOTICE kernel: [ 1.071832] Asymmetric key parser 'x509' registered 2024-01-23T09:08:18.795021Z INFO kernel: [ 1.076758] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) 2024-01-23T09:08:18.795024Z INFO kernel: [ 1.084183] io scheduler mq-deadline registered 2024-01-23T09:08:18.795026Z INFO kernel: [ 1.088726] io scheduler kyber registered 2024-01-23T09:08:18.795027Z INFO kernel: [ 1.092797] io scheduler bfq registered 2024-01-23T09:08:18.795028Z INFO kernel: [ 1.097018] irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7c502000, parent irq: 30) 2024-01-23T09:08:18.795029Z INFO kernel: [ 1.106165] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d503000, parent irq: 31) 2024-01-23T09:08:18.795030Z INFO kernel: [ 1.113892] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508380, parent irq: 32) 2024-01-23T09:08:18.795031Z INFO kernel: [ 1.121614] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d508400, parent irq: 33) 2024-01-23T09:08:18.795034Z INFO kernel: [ 1.129339] irq_brcmstb_l2: registered L2 intc (/soc/interrupt-controller@7d510600, parent irq: 34) 2024-01-23T09:08:18.795036Z INFO kernel: [ 1.138466] irq_brcmstb_l2: registered L2 intc (/soc/intc@7d517b00, parent irq: 35) 2024-01-23T09:08:18.795037Z INFO kernel: [ 1.147023] gpio-461 (RP1 RUN pin): hogged as output/high 2024-01-23T09:08:18.795038Z INFO kernel: [ 1.187216] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled 2024-01-23T09:08:18.795039Z INFO kernel: [ 1.193850] 107d50c000.serial: ttyS0 at MMIO 0x107d50c000 (irq = 37, base_baud = 6000000) is a 16550A 2024-01-23T09:08:18.795040Z INFO kernel: [ 1.203172] serial serial0: tty port ttyS0 registered 2024-01-23T09:08:18.795045Z INFO kernel: [ 1.208536] iproc-rng200 107d208000.rng: hwrng registered 2024-01-23T09:08:18.795046Z INFO kernel: [ 1.214014] vc-mem: phys_addr:0x00000000 mem_base=0x3fc00000 mem_size:0x40000000(1024 MiB) 2024-01-23T09:08:18.795047Z INFO kernel: [ 1.222545] bcm2712-iommu-cache 1000005b00.iommuc: bcm2712_iommu_cache_probe 2024-01-23T09:08:18.795048Z INFO kernel: [ 1.231547] loop: module loaded 2024-01-23T09:08:18.795049Z INFO kernel: [ 1.234799] lkdtm: No crash points registered, enable through debugfs 2024-01-23T09:08:18.795050Z INFO kernel: [ 1.241400] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver 2024-01-23T09:08:18.795054Z INFO kernel: [ 1.248804] Loading iSCSI transport class v2.0-870. 2024-01-23T09:08:18.795055Z INFO kernel: [ 1.254583] usbcore: registered new device driver r8152-cfgselector 2024-01-23T09:08:18.795056Z INFO kernel: [ 1.260886] usbcore: registered new interface driver r8152 2024-01-23T09:08:18.795057Z INFO kernel: [ 1.266399] usbcore: registered new interface driver lan78xx 2024-01-23T09:08:18.795058Z INFO kernel: [ 1.272084] usbcore: registered new interface driver smsc95xx 2024-01-23T09:08:18.795059Z INFO kernel: [ 1.277938] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) 2024-01-23T09:08:18.795062Z DEBUG kernel: [ 1.283724] dwc_otg: FIQ enabled 2024-01-23T09:08:18.795063Z DEBUG kernel: [ 1.283725] dwc_otg: NAK holdoff enabled 2024-01-23T09:08:18.795064Z DEBUG kernel: [ 1.283726] dwc_otg: FIQ split-transaction FSM enabled 2024-01-23T09:08:18.795065Z DEBUG kernel: [ 1.283729] Module dwc_common_port init 2024-01-23T09:08:18.795066Z INFO kernel: [ 1.283859] usbcore: registered new interface driver uas 2024-01-23T09:08:18.795067Z INFO kernel: [ 1.289201] usbcore: registered new interface driver usb-storage 2024-01-23T09:08:18.795068Z INFO kernel: [ 1.295314] mousedev: PS/2 mouse device common for all mice 2024-01-23T09:08:18.795083Z INFO kernel: [ 1.309359] rpi-rtc soc:rpi_rtc: registered as rtc0 2024-01-23T09:08:18.795087Z INFO kernel: [ 1.315777] rpi-rtc soc:rpi_rtc: setting system clock to 2024-01-23T09:08:10 UTC (1706000890) 2024-01-23T09:08:18.795088Z INFO kernel: [ 1.324362] i2c_dev: i2c /dev entries driver 2024-01-23T09:08:18.795089Z INFO kernel: [ 1.328819] brcmstb-i2c 107d508200.i2c: @200000hz registered in interrupt mode 2024-01-23T09:08:18.795090Z INFO kernel: [ 1.336246] brcmstb-i2c 107d508280.i2c: @200000hz registered in interrupt mode 2024-01-23T09:08:18.795091Z INFO kernel: [ 1.343915] bcm2835-wdt bcm2835-wdt: Poweroff handler already present! 2024-01-23T09:08:18.795098Z INFO kernel: [ 1.350470] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer 2024-01-23T09:08:18.795100Z INFO kernel: [ 1.356939] sdhci: Secure Digital Host Controller Interface driver 2024-01-23T09:08:18.795101Z INFO kernel: [ 1.363142] sdhci: Copyright(c) Pierre Ossman 2024-01-23T09:08:18.795102Z INFO kernel: [ 1.367553] sdhci-pltfm: SDHCI platform and OF driver helper 2024-01-23T09:08:18.795103Z INFO kernel: [ 1.373416] ledtrig-cpu: registered to indicate activity on CPUs 2024-01-23T09:08:18.795104Z INFO kernel: [ 1.379482] SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... 2024-01-23T09:08:18.795105Z INFO kernel: [ 1.385955] hid: raw HID events driver (C) Jiri Kosina 2024-01-23T09:08:18.795111Z INFO kernel: [ 1.391174] usbcore: registered new interface driver usbhid 2024-01-23T09:08:18.795112Z INFO kernel: [ 1.396767] usbhid: USB HID core driver 2024-01-23T09:08:18.795113Z INFO kernel: [ 1.400694] ashmem: initialized 2024-01-23T09:08:18.795114Z INFO kernel: [ 1.404498] Initializing XFRM netlink socket 2024-01-23T09:08:18.795115Z INFO kernel: [ 1.408843] NET: Registered PF_PACKET protocol family 2024-01-23T09:08:18.795116Z INFO kernel: [ 1.413913] NET: Registered PF_KEY protocol family 2024-01-23T09:08:18.795122Z INFO kernel: [ 1.418755] 9pnet: Installing 9P2000 support 2024-01-23T09:08:18.795124Z INFO kernel: [ 1.423088] NET: Registered PF_VSOCK protocol family 2024-01-23T09:08:18.795125Z INFO kernel: [ 1.428308] registered taskstats version 1 2024-01-23T09:08:18.795126Z NOTICE kernel: [ 1.432429] Loading compiled-in X.509 certificates 2024-01-23T09:08:18.795203Z NOTICE kernel: [ 1.437316] Key type .fscrypt registered 2024-01-23T09:08:18.795206Z NOTICE kernel: [ 1.441254] Key type fscrypt-provisioning registered 2024-01-23T09:08:18.795214Z INFO kernel: [ 1.447808] brcm-pcie 1000120000.pcie: host bridge /axi/pcie@120000 ranges: 2024-01-23T09:08:18.795216Z INFO kernel: [ 1.454811] brcm-pcie 1000120000.pcie: No bus range found for /axi/pcie@120000, using [bus 00-ff] 2024-01-23T09:08:18.795218Z INFO kernel: [ 1.463904] brcm-pcie 1000120000.pcie: MEM 0x1f00000000..0x1ffffffffb -> 0x0000000000 2024-01-23T09:08:18.795219Z INFO kernel: [ 1.472205] brcm-pcie 1000120000.pcie: MEM 0x1c00000000..0x1effffffff -> 0x0400000000 2024-01-23T09:08:18.795220Z INFO kernel: [ 1.480510] brcm-pcie 1000120000.pcie: IB MEM 0x1f00000000..0x1f003fffff -> 0x0000000000 2024-01-23T09:08:18.795221Z INFO kernel: [ 1.488809] brcm-pcie 1000120000.pcie: IB MEM 0x0000000000..0x0fffffffff -> 0x1000000000 2024-01-23T09:08:18.795222Z INFO kernel: [ 1.498286] brcm-pcie 1000120000.pcie: setting SCB_ACCESS_EN, READ_UR_MODE, MAX_BURST_SIZE 2024-01-23T09:08:18.795228Z INFO kernel: [ 1.506591] brcm-pcie 1000120000.pcie: Forcing gen 2 2024-01-23T09:08:18.795229Z INFO kernel: [ 1.511612] brcm-pcie 1000120000.pcie: PCI host bridge to bus 0000:00 2024-01-23T09:08:18.795230Z INFO kernel: [ 1.518077] pci_bus 0000:00: root bus resource [bus 00-ff] 2024-01-23T09:08:18.795231Z INFO kernel: [ 1.523583] pci_bus 0000:00: root bus resource [mem 0x1f00000000-0x1ffffffffb] (bus address [0x00000000-0xfffffffb]) 2024-01-23T09:08:18.795232Z INFO kernel: [ 1.534150] pci_bus 0000:00: root bus resource [mem 0x1c00000000-0x1effffffff pref] (bus address [0x400000000-0x6ffffffff]) 2024-01-23T09:08:18.795233Z INFO kernel: [ 1.545337] pci 0000:00:00.0: [14e4:2712] type 01 class 0x060400 2024-01-23T09:08:18.795239Z INFO kernel: [ 1.551388] pci 0000:00:00.0: PME# supported from D0 D3hot 2024-01-23T09:08:18.795241Z INFO kernel: [ 1.557828] pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring 2024-01-23T09:08:18.795242Z INFO kernel: [ 1.673793] brcm-pcie 1000120000.pcie: link up, 5.0 GT/s PCIe x4 (!SSC) 2024-01-23T09:08:18.795243Z INFO kernel: [ 1.680450] pci 0000:01:00.0: [1de4:0001] type 00 class 0x020000 2024-01-23T09:08:18.795244Z INFO kernel: [ 1.686491] pci 0000:01:00.0: reg 0x10: [mem 0xffffc000-0xffffffff] 2024-01-23T09:08:18.795245Z INFO kernel: [ 1.692786] pci 0000:01:00.0: reg 0x14: [mem 0xffc00000-0xffffffff] 2024-01-23T09:08:18.795251Z INFO kernel: [ 1.699080] pci 0000:01:00.0: reg 0x18: [mem 0xffff0000-0xffffffff] 2024-01-23T09:08:18.795254Z INFO kernel: [ 1.705436] pci 0000:01:00.0: supports D1 2024-01-23T09:08:18.795255Z INFO kernel: [ 1.709456] pci 0000:01:00.0: PME# supported from D0 D1 D3hot D3cold 2024-01-23T09:08:18.795256Z INFO kernel: [ 1.725802] pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 2024-01-23T09:08:18.795257Z INFO kernel: [ 1.732448] pci 0000:00:00.0: BAR 8: assigned [mem 0x1f00000000-0x1f005fffff] 2024-01-23T09:08:18.795258Z INFO kernel: [ 1.739613] pci 0000:01:00.0: BAR 1: assigned [mem 0x1f00000000-0x1f003fffff] 2024-01-23T09:08:18.795264Z INFO kernel: [ 1.746778] pci 0000:01:00.0: BAR 2: assigned [mem 0x1f00400000-0x1f0040ffff] 2024-01-23T09:08:18.795266Z INFO kernel: [ 1.753943] pci 0000:01:00.0: BAR 0: assigned [mem 0x1f00410000-0x1f00413fff] 2024-01-23T09:08:18.795267Z INFO kernel: [ 1.761108] pci 0000:00:00.0: PCI bridge to [bus 01] 2024-01-23T09:08:18.795268Z INFO kernel: [ 1.766088] pci 0000:00:00.0: bridge window [mem 0x1f00000000-0x1f005fffff] 2024-01-23T09:08:18.795269Z INFO kernel: [ 1.773252] pci 0000:00:00.0: Max Payload Size set to 256/ 512 (was 128), Max Read Rq 512 2024-01-23T09:08:18.795270Z INFO kernel: [ 1.781730] pci 0000:01:00.0: Max Payload Size set to 256/ 256 (was 128), Max Read Rq 512 2024-01-23T09:08:18.795271Z INFO kernel: [ 1.790259] pcieport 0000:00:00.0: enabling device (0000 -> 0002) 2024-01-23T09:08:18.795278Z INFO kernel: [ 1.796411] pcieport 0000:00:00.0: PME: Signaling with IRQ 40 2024-01-23T09:08:18.795280Z INFO kernel: [ 1.802241] pcieport 0000:00:00.0: AER: enabled with IRQ 40 2024-01-23T09:08:18.795281Z INFO kernel: [ 1.807906] rp1 0000:01:00.0: bar0 len 0x4000, start 0x1f00410000, end 0x1f00413fff, flags, 0x40200 2024-01-23T09:08:18.795282Z INFO kernel: [ 1.816991] rp1 0000:01:00.0: bar1 len 0x400000, start 0x1f00000000, end 0x1f003fffff, flags, 0x40200 2024-01-23T09:08:18.795283Z INFO kernel: [ 1.826255] rp1 0000:01:00.0: enabling device (0000 -> 0002) 2024-01-23T09:08:18.795284Z INFO kernel: [ 1.832575] rp1 0000:01:00.0: chip_id 0x20001927 2024-01-23T09:08:18.795292Z INFO kernel: [ 1.854963] macb 1f00100000.ethernet eth0: Cadence GEM rev 0x00070109 at 0x1f00100000 irq 108 (2c:cf:67:1a:e0:8f) 2024-01-23T09:08:18.795293Z INFO kernel: [ 1.865814] dw_axi_dmac_platform 1f00188000.dma: DesignWare AXI DMA Controller, 8 channels 2024-01-23T09:08:18.795294Z INFO kernel: [ 1.874414] xhci-hcd xhci-hcd.0: xHCI Host Controller 2024-01-23T09:08:18.795295Z INFO kernel: [ 1.879488] xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 1 2024-01-23T09:08:18.795297Z INFO kernel: [ 1.887236] xhci-hcd xhci-hcd.0: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000000000010810 2024-01-23T09:08:18.795298Z INFO kernel: [ 1.896239] xhci-hcd xhci-hcd.0: irq 133, io mem 0x1f00200000 2024-01-23T09:08:18.795303Z INFO kernel: [ 1.902085] xhci-hcd xhci-hcd.0: xHCI Host Controller 2024-01-23T09:08:18.795305Z INFO kernel: [ 1.907155] xhci-hcd xhci-hcd.0: new USB bus registered, assigned bus number 2 2024-01-23T09:08:18.795306Z INFO kernel: [ 1.914406] xhci-hcd xhci-hcd.0: Host supports USB 3.0 SuperSpeed 2024-01-23T09:08:18.795307Z INFO kernel: [ 1.920571] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 2024-01-23T09:08:18.795308Z INFO kernel: [ 1.928871] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 2024-01-23T09:08:18.795309Z INFO kernel: [ 1.936121] usb usb1: Product: xHCI Host Controller 2024-01-23T09:08:18.795317Z INFO kernel: [ 1.941014] usb usb1: Manufacturer: Linux 6.1.77-v8-g21012295fe87-dirty xhci-hcd 2024-01-23T09:08:18.795319Z INFO kernel: [ 1.948438] usb usb1: SerialNumber: xhci-hcd.0 2024-01-23T09:08:18.795320Z INFO kernel: [ 1.953036] hub 1-0:1.0: USB hub found 2024-01-23T09:08:18.795321Z INFO kernel: [ 1.956813] hub 1-0:1.0: 2 ports detected 2024-01-23T09:08:18.795322Z INFO kernel: [ 1.960978] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01 2024-01-23T09:08:18.795323Z INFO kernel: [ 1.969278] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 2024-01-23T09:08:18.795324Z INFO kernel: [ 1.976528] usb usb2: Product: xHCI Host Controller 2024-01-23T09:08:18.795331Z INFO kernel: [ 1.981423] usb usb2: Manufacturer: Linux 6.1.77-v8-g21012295fe87-dirty xhci-hcd 2024-01-23T09:08:18.795333Z INFO kernel: [ 1.988918] usb usb2: SerialNumber: xhci-hcd.0 2024-01-23T09:08:18.795334Z INFO kernel: [ 1.993540] hub 2-0:1.0: USB hub found 2024-01-23T09:08:18.795335Z INFO kernel: [ 1.997319] hub 2-0:1.0: 1 port detected 2024-01-23T09:08:18.795336Z INFO kernel: [ 2.001619] xhci-hcd xhci-hcd.1: xHCI Host Controller 2024-01-23T09:08:18.795337Z INFO kernel: [ 2.006693] xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 3 2024-01-23T09:08:18.795342Z INFO kernel: [ 2.014430] xhci-hcd xhci-hcd.1: hcc params 0x0240fe6d hci version 0x110 quirks 0x0000000000010810 2024-01-23T09:08:18.795344Z INFO kernel: [ 2.023442] xhci-hcd xhci-hcd.1: irq 138, io mem 0x1f00300000 2024-01-23T09:08:18.795345Z INFO kernel: [ 2.029274] xhci-hcd xhci-hcd.1: xHCI Host Controller 2024-01-23T09:08:18.795346Z INFO kernel: [ 2.034344] xhci-hcd xhci-hcd.1: new USB bus registered, assigned bus number 4 2024-01-23T09:08:18.795347Z INFO kernel: [ 2.041594] xhci-hcd xhci-hcd.1: Host supports USB 3.0 SuperSpeed 2024-01-23T09:08:18.795348Z INFO kernel: [ 2.047749] usb usb3: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 2024-01-23T09:08:18.795354Z INFO kernel: [ 2.056048] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1 2024-01-23T09:08:18.795355Z INFO kernel: [ 2.063298] usb usb3: Product: xHCI Host Controller 2024-01-23T09:08:18.795356Z INFO kernel: [ 2.068190] usb usb3: Manufacturer: Linux 6.1.77-v8-g21012295fe87-dirty xhci-hcd 2024-01-23T09:08:18.795357Z INFO kernel: [ 2.075614] usb usb3: SerialNumber: xhci-hcd.1 2024-01-23T09:08:18.795358Z INFO kernel: [ 2.080180] hub 3-0:1.0: USB hub found 2024-01-23T09:08:18.795359Z INFO kernel: [ 2.083952] hub 3-0:1.0: 2 ports detected 2024-01-23T09:08:18.795360Z INFO kernel: [ 2.088114] usb usb4: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 6.01 2024-01-23T09:08:18.795366Z INFO kernel: [ 2.096414] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1 2024-01-23T09:08:18.795368Z INFO kernel: [ 2.103697] usb usb4: Product: xHCI Host Controller 2024-01-23T09:08:18.795369Z INFO kernel: [ 2.108590] usb usb4: Manufacturer: Linux 6.1.77-v8-g21012295fe87-dirty xhci-hcd 2024-01-23T09:08:18.795370Z INFO kernel: [ 2.116022] usb usb4: SerialNumber: xhci-hcd.1 2024-01-23T09:08:18.795371Z INFO kernel: [ 2.120624] hub 4-0:1.0: USB hub found 2024-01-23T09:08:18.795372Z INFO kernel: [ 2.124395] hub 4-0:1.0: 1 port detected 2024-01-23T09:08:18.795378Z INFO kernel: [ 2.129458] bcm2712-iommu 1000005100.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 2024-01-23T09:08:18.795379Z INFO kernel: [ 2.137807] platform 1000800000.codec: bcm2712_iommu_probe_device: MMU 1000005100.iommu 2024-01-23T09:08:18.795381Z INFO kernel: [ 2.145881] platform 1000800000.codec: bcm2712_iommu_device_group: MMU 1000005100.iommu 2024-01-23T09:08:18.795382Z INFO kernel: [ 2.153919] platform 1000800000.codec: Adding to iommu group 0 2024-01-23T09:08:18.795383Z INFO kernel: [ 2.159791] platform 1000880000.pisp_be: bcm2712_iommu_probe_device: MMU 1000005100.iommu 2024-01-23T09:08:18.795384Z INFO kernel: [ 2.168014] platform 1000880000.pisp_be: bcm2712_iommu_device_group: MMU 1000005100.iommu 2024-01-23T09:08:18.795390Z INFO kernel: [ 2.176227] platform 1000880000.pisp_be: Adding to iommu group 0 2024-01-23T09:08:18.795391Z INFO kernel: [ 2.182276] platform 1000800000.codec: bcm2712_iommu_attach_dev: MMU 1000005100.iommu 2024-01-23T09:08:18.795392Z INFO kernel: [ 2.190136] platform 1000880000.pisp_be: bcm2712_iommu_attach_dev: MMU 1000005100.iommu 2024-01-23T09:08:18.795393Z INFO kernel: [ 2.198203] bcm2712-iommu 1000005100.iommu: bcm2712_iommu_probe: Success 2024-01-23T09:08:18.795395Z INFO kernel: [ 2.205876] bcm2712-iommu 1000005200.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 2024-01-23T09:08:18.795396Z INFO kernel: [ 2.214217] platform axi:gpu: bcm2712_iommu_probe_device: MMU 1000005200.iommu 2024-01-23T09:08:18.795397Z INFO kernel: [ 2.221467] platform axi:gpu: bcm2712_iommu_device_group: MMU 1000005200.iommu 2024-01-23T09:08:18.795403Z INFO kernel: [ 2.228741] platform axi:gpu: Adding to iommu group 1 2024-01-23T09:08:18.795405Z INFO kernel: [ 2.233973] platform axi:gpu: bcm2712_iommu_attach_dev: MMU 1000005200.iommu 2024-01-23T09:08:18.795406Z INFO kernel: [ 2.241066] bcm2712-iommu 1000005200.iommu: bcm2712_iommu_probe: Success 2024-01-23T09:08:18.795407Z INFO kernel: [ 2.248895] bcm2712-iommu 1000005280.iommu: bcm2712_iommu_init: DEBUG_INFO = 0x20804774 2024-01-23T09:08:18.795408Z INFO kernel: [ 2.257237] bcm2712-iommu 1000005280.iommu: bcm2712_iommu_probe: Success 2024-01-23T09:08:18.795409Z INFO kernel: [ 2.271312] [drm] Initialized v3d 1.0.0 20180419 for 1002000000.v3d on minor 0 2024-01-23T09:08:18.795486Z INFO kernel: [ 2.283067] vc4-drm axi:gpu: bcm2712_iommu_of_xlate: MMU 1000005200.iommu 2024-01-23T09:08:18.795490Z INFO kernel: [ 2.292501] vc4-drm axi:gpu: bound 107c580000.hvs (ops 0xffffffd00cd37018) 2024-01-23T09:08:18.795491Z INFO kernel: [ 2.302239] input: vc4-hdmi-0 HDMI Jack as /devices/platform/soc/107c701400.hdmi/sound/card0/input0 2024-01-23T09:08:18.795492Z INFO kernel: [ 2.311393] vc4-drm axi:gpu: bound 107c701400.hdmi (ops 0xffffffd00cd32e60) 2024-01-23T09:08:18.795493Z INFO kernel: [ 2.321167] input: vc4-hdmi-1 HDMI Jack as /devices/platform/soc/107c706400.hdmi/sound/card1/input1 2024-01-23T09:08:18.795494Z INFO kernel: [ 2.330320] vc4-drm axi:gpu: bound 107c706400.hdmi (ops 0xffffffd00cd32e60) 2024-01-23T09:08:18.795500Z INFO kernel: [ 2.337368] vc4-drm axi:gpu: bound 107c500000.mop (ops 0xffffffd00cd38090) 2024-01-23T09:08:18.795501Z INFO kernel: [ 2.344309] vc4-drm axi:gpu: bound 107c501000.moplet (ops 0xffffffd00cd38090) 2024-01-23T09:08:18.795502Z INFO kernel: [ 2.351518] vc4-drm axi:gpu: bound 107c410000.pixelvalve (ops 0xffffffd00cd2f560) 2024-01-23T09:08:18.795503Z INFO kernel: [ 2.359061] vc4-drm axi:gpu: bound 107c411000.pixelvalve (ops 0xffffffd00cd2f560) 2024-01-23T09:08:18.795505Z INFO kernel: [ 2.361794] usb 3-2: new full-speed USB device number 2 using xhci-hcd 2024-01-23T09:08:18.795506Z INFO kernel: [ 2.416288] [drm] Initialized vc4 0.0.0 20140616 for axi:gpu on minor 1 2024-01-23T09:08:18.795507Z INFO kernel: [ 2.423081] sdhci-brcmstb 1000fff000.mmc: there is not valid maps for state default 2024-01-23T09:08:18.795512Z INFO kernel: [ 2.423430] mmc1: CQHCI version 5.10 2024-01-23T09:08:18.795514Z INFO kernel: [ 2.424489] of_cfs_init 2024-01-23T09:08:18.795516Z INFO kernel: [ 2.424502] of_cfs_init: OK 2024-01-23T09:08:18.795517Z INFO kernel: [ 2.431547] ALSA device list: 2024-01-23T09:08:18.795518Z INFO kernel: [ 2.431757] mmc0: CQHCI version 5.10 2024-01-23T09:08:18.795519Z INFO kernel: [ 2.446167] #0: vc4-hdmi-0 2024-01-23T09:08:18.795525Z INFO kernel: [ 2.449050] #1: vc4-hdmi-1 2024-01-23T09:08:18.795526Z INFO kernel: [ 2.451981] uart-pl011 107d001000.serial: no DMA platform data 2024-01-23T09:08:18.795528Z INFO kernel: [ 2.467603] mmc0: SDHCI controller on 1000fff000.mmc [1000fff000.mmc] using ADMA 64-bit 2024-01-23T09:08:18.795529Z INFO kernel: [ 2.543778] usb 3-2: New USB device found, idVendor=046d, idProduct=c52b, bcdDevice=24.11 2024-01-23T09:08:18.795530Z INFO kernel: [ 2.552008] usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 2024-01-23T09:08:18.795531Z INFO kernel: [ 2.559171] usb 3-2: Product: USB Receiver 2024-01-23T09:08:18.795537Z INFO kernel: [ 2.563282] usb 3-2: Manufacturer: Logitech 2024-01-23T09:08:18.795539Z INFO kernel: [ 2.599905] mmc0: new ultra high speed SDR104 SDHC card at address 0001 2024-01-23T09:08:18.795540Z INFO kernel: [ 2.606781] mmcblk0: mmc0:0001 SD16G 29.4 GiB 2024-01-23T09:08:18.795541Z WARNING kernel: [ 2.614436] GPT:Primary header thinks Alt. header is not at the end of the disk. 2024-01-23T09:08:18.795542Z INFO kernel: [ 2.622124] input: Logitech USB Receiver as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.0/0003:046D:C52B.0001/input/input2 2024-01-23T09:08:18.795543Z INFO kernel: [ 2.624341] mmc1: SDHCI controller on 1001100000.mmc [1001100000.mmc] using ADMA 64-bit 2024-01-23T09:08:18.795544Z WARNING kernel: [ 2.636284] GPT:19959964 != 61739007 2024-01-23T09:08:18.795551Z WARNING kernel: [ 2.647889] GPT:Alternate GPT header not at the end of the disk. 2024-01-23T09:08:18.795552Z WARNING kernel: [ 2.653916] GPT:19959964 != 61739007 2024-01-23T09:08:18.795553Z WARNING kernel: [ 2.657497] GPT: Use GNU Parted to correct GPT errors. 2024-01-23T09:08:18.795554Z INFO kernel: [ 2.662661] mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8 p9 p10 p11 p12 2024-01-23T09:08:18.795555Z INFO kernel: [ 2.668766] mmcblk0: mmc0:0001 SD16G 29.4 GiB 2024-01-23T09:08:18.795556Z INFO kernel: [ 2.676573] mmc1: new ultra high speed DDR50 SDIO card at address 0001 2024-01-23T09:08:18.795564Z INFO kernel: [ 2.693931] hid-generic 0003:046D:C52B.0001: input,hidraw0: USB HID v1.11 Keyboard [Logitech USB Receiver] on usb-xhci-hcd.1-2/input0 2024-01-23T09:08:18.795566Z INFO kernel: [ 2.719805] input: Logitech USB Receiver Mouse as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.1/0003:046D:C52B.0002/input/input3 2024-01-23T09:08:18.795567Z INFO kernel: [ 2.734573] input: Logitech USB Receiver Consumer Control as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.1/0003:046D:C52B.0002/input/input4 2024-01-23T09:08:18.795568Z INFO kernel: [ 2.750253] input: Logitech USB Receiver System Control as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.1/0003:046D:C52B.0002/input/input5 2024-01-23T09:08:18.795569Z INFO kernel: [ 2.765809] hid-generic 0003:046D:C52B.0002: input,hiddev96,hidraw1: USB HID v1.11 Mouse [Logitech USB Receiver] on usb-xhci-hcd.1-2/input1 2024-01-23T09:08:18.795577Z INFO kernel: [ 2.783994] hid-generic 0003:046D:C52B.0003: hiddev97,hidraw2: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.1-2/input2 2024-01-23T09:08:18.795579Z INFO kernel: [ 2.797165] EXT4-fs (mmcblk0p3): mounting ext2 file system using the ext4 subsystem 2024-01-23T09:08:18.795580Z INFO kernel: [ 2.807717] EXT4-fs (mmcblk0p3): mounted filesystem without journal. Quota mode: none. 2024-01-23T09:08:18.795581Z INFO kernel: [ 2.815684] VFS: Mounted root (ext2 filesystem) readonly on device 179:3. 2024-01-23T09:08:18.795582Z INFO kernel: [ 2.825357] devtmpfs: mounted 2024-01-23T09:08:18.795583Z INFO kernel: [ 2.830043] Freeing unused kernel memory: 1472K 2024-01-23T09:08:18.795589Z INFO kernel: [ 2.834667] Run /sbin/init as init process 2024-01-23T09:08:18.795591Z DEBUG kernel: [ 2.838777] with arguments: 2024-01-23T09:08:18.795592Z DEBUG kernel: [ 2.838777] /sbin/init 2024-01-23T09:08:18.795593Z DEBUG kernel: [ 2.838779] cros_debug 2024-01-23T09:08:18.795594Z DEBUG kernel: [ 2.838780] cros_secure 2024-01-23T09:08:18.795595Z DEBUG kernel: [ 2.838781] with environment: 2024-01-23T09:08:18.795596Z DEBUG kernel: [ 2.838782] HOME=/ 2024-01-23T09:08:18.795601Z DEBUG kernel: [ 2.838783] TERM=linux 2024-01-23T09:08:18.795603Z DEBUG kernel: [ 2.838784] earlyprintk=ttyAMA10,115200 2024-01-23T09:08:18.795604Z DEBUG kernel: [ 2.838785] log_level=7 2024-01-23T09:08:18.795605Z INFO kernel: [ 2.977350] SELinux: Permission nlmsg_getneigh in class netlink_route_socket not defined in policy. 2024-01-23T09:08:18.795606Z INFO kernel: [ 2.986570] SELinux: Permission perfmon in class capability2 not defined in policy. 2024-01-23T09:08:18.795607Z INFO kernel: [ 2.994343] SELinux: Permission bpf in class capability2 not defined in policy. 2024-01-23T09:08:18.795613Z INFO kernel: [ 3.001766] SELinux: Permission checkpoint_restore in class capability2 not defined in policy. 2024-01-23T09:08:18.795614Z INFO kernel: [ 3.010505] SELinux: Permission perfmon in class cap2_userns not defined in policy. 2024-01-23T09:08:18.795615Z INFO kernel: [ 3.018277] SELinux: Permission bpf in class cap2_userns not defined in policy. 2024-01-23T09:08:18.795616Z INFO kernel: [ 3.025700] SELinux: Permission checkpoint_restore in class cap2_userns not defined in policy. 2024-01-23T09:08:18.795617Z INFO kernel: [ 3.034470] SELinux: Class mctp_socket not defined in policy. 2024-01-23T09:08:18.795618Z INFO kernel: [ 3.040324] SELinux: Class anon_inode not defined in policy. 2024-01-23T09:08:18.795624Z INFO kernel: [ 3.046088] SELinux: Class io_uring not defined in policy. 2024-01-23T09:08:18.795625Z INFO kernel: [ 3.051678] SELinux: Class user_namespace not defined in policy. 2024-01-23T09:08:18.795626Z INFO kernel: [ 3.057792] SELinux: the above unknown classes and permissions will be allowed 2024-01-23T09:08:18.795627Z INFO kernel: [ 3.067595] SELinux: policy capability network_peer_controls=1 2024-01-23T09:08:18.795628Z INFO kernel: [ 3.073549] SELinux: policy capability open_perms=1 2024-01-23T09:08:18.795629Z INFO kernel: [ 3.078529] SELinux: policy capability extended_socket_class=1 2024-01-23T09:08:18.795630Z INFO kernel: [ 3.084468] SELinux: policy capability always_check_network=0 2024-01-23T09:08:18.795636Z INFO kernel: [ 3.090320] SELinux: policy capability cgroup_seclabel=0 2024-01-23T09:08:18.795638Z INFO kernel: [ 3.095736] SELinux: policy capability nnp_nosuid_transition=1 2024-01-23T09:08:18.795639Z INFO kernel: [ 3.101675] SELinux: policy capability genfs_seclabel_symlinks=0 2024-01-23T09:08:18.795640Z INFO kernel: [ 3.107789] SELinux: policy capability ioctl_skip_cloexec=0 2024-01-23T09:08:18.795641Z NOTICE kernel: [ 3.145833] audit: type=1403 audit(1706000892.327:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 2024-01-23T09:08:18.795642Z NOTICE kernel: [ 3.441600] audit: type=1400 audit(1706000892.619:3): avc: granted { execute } for pid=133 comm="sh" name="crash_reporter" dev="mmcblk0p3" ino=24392 scontext=u:r:cros_init_scripts:s0 tcontext=u:object_r:cros_crash_reporter_exec:s0 tclass=file 2024-01-23T09:08:18.795649Z INFO kernel: [ 3.751564] loop0: detected capacity change from 0 to 8 2024-01-23T09:08:18.795650Z INFO kernel: [ 4.314926] LoadPin: mmcblk0p3 (179:3): writable 2024-01-23T09:08:18.795651Z INFO kernel: [ 4.319583] LoadPin: enforcement can be disabled. 2024-01-23T09:08:18.795652Z NOTICE kernel: [ 4.324314] LoadPin: kernel-module pinned obj="/lib/modules/6.1.77-v8-g21012295fe87-dirty/kernel/drivers/hid/hid-logitech-dj.ko.gz" pid=154 cmdline="udevd --daemon" 2024-01-23T09:08:18.795653Z INFO kernel: [ 4.436234] EXT4-fs (mmcblk0p1): mounted filesystem with ordered data mode. Quota mode: journalled. 2024-01-23T09:08:18.795659Z INFO kernel: [ 4.459613] EXT4-fs (mmcblk0p8): orphan cleanup on readonly fs 2024-01-23T09:08:18.795661Z INFO kernel: [ 4.465523] EXT4-fs (mmcblk0p8): mounted filesystem with ordered data mode. Quota mode: none. 2024-01-23T09:08:18.795662Z INFO kernel: [ 4.501994] EXT4-fs (mmcblk0p1): re-mounted. Quota mode: journalled. 2024-01-23T09:08:18.795663Z INFO kernel: [ 4.534765] logitech-djreceiver 0003:046D:C52B.0003: hiddev96,hidraw0: USB HID v1.11 Device [Logitech USB Receiver] on usb-xhci-hcd.1-2/input2 2024-01-23T09:08:18.795664Z INFO kernel: [ 4.668291] input: Logitech Wireless Device PID:4090 Mouse as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.2/0003:046D:C52B.0003/0003:046D:4090.0004/input/input7 2024-01-23T09:08:18.795666Z INFO kernel: [ 4.686047] hid-generic 0003:046D:4090.0004: input,hidraw1: USB HID v1.11 Mouse [Logitech Wireless Device PID:4090] on usb-xhci-hcd.1-2/input2:1 2024-01-23T09:08:18.795672Z INFO kernel: [ 4.699525] input: Logitech Wireless Device PID:404d Keyboard as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.2/0003:046D:C52B.0003/0003:046D:404D.0005/input/input11 2024-01-23T09:08:18.795674Z INFO kernel: [ 4.717519] input: Logitech Wireless Device PID:404d Mouse as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.2/0003:046D:C52B.0003/0003:046D:404D.0005/input/input12 2024-01-23T09:08:18.795675Z INFO kernel: [ 4.735211] hid-generic 0003:046D:404D.0005: input,hidraw2: USB HID v1.11 Keyboard [Logitech Wireless Device PID:404d] on usb-xhci-hcd.1-2/input2:2 2024-01-23T09:08:18.795700Z INFO kernel: [ 5.862071] input: Logitech MX Anywhere 3 as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.2/0003:046D:C52B.0003/0003:046D:4090.0004/input/input16 2024-01-23T09:08:18.795702Z INFO kernel: [ 5.878315] logitech-hidpp-device 0003:046D:4090.0004: input,hidraw1: USB HID v1.11 Mouse [Logitech MX Anywhere 3] on usb-xhci-hcd.1-2/input2:1 2024-01-23T09:08:18.795712Z INFO kernel: [ 5.998194] input: Logitech K400 Plus as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.2/0003:046D:C52B.0003/0003:046D:404D.0005/input/input17 2024-01-23T09:08:18.795714Z INFO kernel: [ 6.014208] logitech-hidpp-device 0003:046D:404D.0005: input,hidraw2: USB HID v1.11 Keyboard [Logitech K400 Plus] on usb-xhci-hcd.1-2/input2:2 2024-01-23T09:08:18.795715Z INFO kernel: [ 6.183621] loop1: detected capacity change from 0 to 20480 2024-01-23T09:08:18.795717Z ERR kernel: [ 6.198968] frecon[390]: Evdev device /dev/input/mouse1 grabbed by another process 2024-01-23T09:08:18.795718Z INFO kernel: [ 6.236806] EXT4-fs (loop1): mounted filesystem with ordered data mode. Quota mode: none. 2024-01-23T09:08:18.795719Z ERR kernel: [ 6.294906] tpm tpm0: A TPM error (256) occurred attempting the self test 2024-01-23T09:08:18.795725Z INFO kernel: [ 6.301777] tpm tpm0: starting up the TPM manually 2024-01-23T09:08:18.795728Z INFO kernel: [ 6.439315] frecon[390]: Frecon using drm driver vc4, version 0.0, date(20140616), desc(Broadcom VC4 graphics) using atomic 2024-01-23T09:08:18.795729Z INFO kernel: [ 6.486825] device-mapper: ioctl: 4.47.0-ioctl (2022-07-28) initialised: dm-devel@redhat.com 2024-01-23T09:08:18.795730Z INFO kernel: [ 6.498949] loop2: detected capacity change from 0 to 2427936 2024-01-23T09:08:18.795731Z NOTICE kernel: [ 6.679284] Key type encrypted registered 2024-01-23T09:08:18.795732Z ERR kernel: [ 6.718743] frecon[390]: could not find atomic property CTM 2024-01-23T09:08:18.795738Z ERR kernel: [ 6.718743] 2024-01-23T09:08:18.795739Z ERR kernel: [ 6.725945] frecon[390]: could not find atomic property DEGAMMA_LUT 2024-01-23T09:08:18.795741Z ERR kernel: [ 6.725945] 2024-01-23T09:08:18.795742Z ERR kernel: [ 6.734969] frecon[390]: could not find atomic property GAMMA_LUT 2024-01-23T09:08:18.795743Z ERR kernel: [ 6.734969] 2024-01-23T09:08:18.795744Z ERR kernel: [ 6.755639] frecon[390]: Evdev device /dev/input/mouse1 grabbed by another process 2024-01-23T09:08:18.795751Z INFO kernel: [ 9.048086] frecon[390]: Frecon using drm driver vc4, version 0.0, date(20140616), desc(Broadcom VC4 graphics) using atomic 2024-01-23T09:08:18.795753Z INFO kernel: [ 9.137406] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Quota mode: none. 2024-01-23T09:08:18.795754Z INFO kernel: [ 9.141449] frecon[390]: Frecon using drm driver vc4, version 0.0, date(20140616), desc(Broadcom VC4 graphics) using atomic 2024-01-23T09:08:18.795755Z NOTICE kernel: [ 9.222803] audit: type=1400 audit(1706000898.403:4): avc: granted { execute } for pid=480 comm="chromeos_startu" name="crash_reporter" dev="mmcblk0p3" ino=24392 scontext=u:r:chromeos_startup:s0 tcontext=u:object_r:cros_crash_reporter_exec:s0 tclass=file 2024-01-23T09:08:18.795757Z INFO kernel: [ 9.448340] EXT4-fs (mmcblk0p1): re-mounted. Quota mode: journalled. 2024-01-23T09:08:18.794089Z WARNING rsyslogd[508]: warning: ~ action is deprecated, consider using the 'stop' statement instead [v8.2112.0 try https://www.rsyslog.com/e/2307 ] 2024-01-23T09:08:18.794103Z WARNING rsyslogd[508]: warning: ~ action is deprecated, consider using the 'stop' statement instead [v8.2112.0 try https://www.rsyslog.com/e/2307 ] 2024-01-23T09:08:18.794108Z INFO rsyslogd[508]: rsyslogd's groupid changed to 202 2024-01-23T09:08:18.794114Z INFO rsyslogd[508]: rsyslogd's userid changed to 202 2024-01-23T09:08:18.794120Z INFO rsyslogd[508]: [origin software="rsyslogd" swVersion="8.2112.0" x-pid="508" x-info="https://www.rsyslog.com"] start 2024-01-23T09:08:18.822577Z NOTICE lockbox-cache[656]: Lockbox cache started 2024-01-23T09:08:18.856847Z NOTICE auditd[496]: Init complete, auditd 3.0.6 listening for events (startup state enable) 2024-01-23T09:08:18.915845Z INFO crash_reporter[651]: Enabling user crash handling 2024-01-23T09:08:19.032693Z NOTICE fydeos-shell-daemon[689]: shell daemon starting... 2024-01-23T09:08:19.990040Z INFO kernel: [ 10.796423] EXT4-fs (mmcblk0p1): resizing filesystem from 1048576 to 6269696 blocks 2024-01-23T09:08:21.026048Z INFO kernel: [ 11.834888] EXT4-fs (mmcblk0p1): resized filesystem to 6269696 2024-01-23T09:08:21.913049Z NOTICE cr50-result[705]: cr50-result for non-GSC case performs no-ops. 2024-01-23T09:08:21.946149Z NOTICE boot-update-firmware[747]: Update fwupd firmware. 2024-01-23T09:08:22.028322Z NOTICE daisydog[788]: HW watchdog interval is 15 seconds 2024-01-23T09:08:22.028609Z WARNING imageloader[724]: Could not read key file /usr/share/misc/oci-container-key-pub.der 2024-01-23T09:08:22.029134Z NOTICE daisydog[788]: /dev/watchdog reported boot status: normal-boot 2024-01-23T09:08:22.042878Z INFO process_flex_bluetooth_overrides[793]: Started process_flex_bluetooth_overrides. 2024-01-23T09:08:22.054428Z INFO mojo_service_manager[766]: DevMode is enabled, load extra configs from /usr/local/etc/mojo/service_manager/policy 2024-01-23T09:08:22.071632Z INFO imageloader[724]: The latest-version file does not exist. Component WidevineCdm is probably not installed. 2024-01-23T09:08:22.073810Z INFO imageloader[795]: parent socket has shutdown. 2024-01-23T09:08:22.094429Z NOTICE write-machine-info[830]: Unable to open FDT property firmware/chromeos/firmware-type#012Unable to open FDT property firmware/chromeos/vboot-shared-data#012Fallback to WPSW_BOOT (-1), which may be invalid 2024-01-23T09:08:22.126316Z INFO process_flex_bluetooth_overrides[793]: Didn't find a Bluetooth adapter. Removing overrides. 2024-01-23T09:08:22.126599Z INFO process_flex_bluetooth_overrides[793]: Exiting process_flex_bluetooth_overrides. 2024-01-23T09:08:22.142064Z INFO kernel: [ 12.952642] NET: Registered PF_INET6 protocol family 2024-01-23T09:08:22.151087Z INFO kernel: [ 12.959120] Segment Routing with IPv6 2024-01-23T09:08:22.151114Z INFO kernel: [ 12.962897] In-situ OAM (IOAM) with IPv6 2024-01-23T09:08:22.160647Z INFO iioservice[784]: INFO iioservice: [main.cc(19)] (2) main(): Daemon started 2024-01-23T09:08:22.182178Z INFO trunksd[783]: Initialized last errno: 0 2024-01-23T09:08:22.182262Z INFO trunksd[783]: TPM: /dev/tpm0 opened successfully 2024-01-23T09:08:22.212629Z INFO trunksd[869]: Trunks service started. 2024-01-23T09:08:22.217144Z INFO local_data_migration[872]: local data migration is non-applicable and performs no-ops. 2024-01-23T09:08:22.221576Z INFO mojo_service_manager[766]: mojo_service_manager started. 2024-01-23T09:08:22.221940Z INFO mojo_service_manager[766]: Receive connection from: u:r:cros_iioservice:s0 2024-01-23T09:08:22.235230Z INFO trunksd[869]: CheckState: TPM State verified. 2024-01-23T09:08:22.355918Z ERR spaced[759]: Failed to run command: No such file or directory (2) 2024-01-23T09:08:22.355966Z ERR spaced[759]: Failed to get output from pvs 2024-01-23T09:08:22.364411Z INFO root[894]: fydeos-license-utils get disk /dev/mmcblk0 serial by lsblk 2024-01-23T09:08:22.368850Z INFO root[900]: fydeos-license-utils get fix id by disk serial 2024-01-23T09:08:22.374276Z WARNING powerd[865]: libminijail[865]: SECURE_NOROOT not set, not dropping bounding set 2024-01-23T09:08:22.375448Z INFO root[895]: fydeos-license-utils get disk /dev/mmcblk0 serial by lsblk 2024-01-23T09:08:22.379302Z INFO root[911]: fydeos-license-utils get fix id by disk serial 2024-01-23T09:08:22.440345Z INFO tpm_managerd[901]: Starting TPM Manager... 2024-01-23T09:08:22.467597Z ERR spaced[759]: Failed to run command: No such file or directory (2) 2024-01-23T09:08:22.467643Z ERR spaced[759]: Failed to get output from pvs 2024-01-23T09:08:22.470244Z WARNING tpm_managerd[901]: WARNING: Verified boot PCR 2 is not initialized. 2024-01-23T09:08:22.472563Z WARNING tpm_managerd[901]: WARNING: Verified boot PCR 3 is not initialized. 2024-01-23T09:08:22.491015Z INFO tpm_managerd[901]: Read: the local data path does not exist. 2024-01-23T09:08:22.492800Z INFO kernel: [ 13.299512] Bluetooth: Core ver 2.22 2024-01-23T09:08:22.492815Z INFO kernel: [ 13.303203] NET: Registered PF_BLUETOOTH protocol family 2024-01-23T09:08:22.510920Z NOTICE kernel: [ 13.312481] cfg80211: Loading compiled-in X.509 certificates for regulatory database 2024-01-23T09:08:22.510952Z INFO kernel: [ 13.313163] Bluetooth: HCI device and connection manager initialized 2024-01-23T09:08:22.519678Z INFO kernel: [ 13.329837] Bluetooth: HCI socket layer initialized 2024-01-23T09:08:22.531240Z INFO kernel: [ 13.341898] Bluetooth: L2CAP socket layer initialized 2024-01-23T09:08:22.536670Z INFO kernel: [ 13.347497] Bluetooth: SCO socket layer initialized 2024-01-23T09:08:22.538645Z INFO bluetoothd[862]: Bluetooth daemon 5.54 2024-01-23T09:08:22.538807Z WARNING bluetoothd[862]: Unknown key MaxControllers for group General in /var/lib/bluetooth/main.conf 2024-01-23T09:08:22.538890Z INFO bluetoothd[862]: PageScanType=1(1) 2024-01-23T09:08:22.538902Z INFO bluetoothd[862]: PageScanInterval=1024(1024) 2024-01-23T09:08:22.538911Z INFO bluetoothd[862]: PageScanWindow=18(18) 2024-01-23T09:08:22.538928Z INFO bluetoothd[862]: EirMaxNameLength=180(180) 2024-01-23T09:08:22.538937Z INFO bluetoothd[862]: MinAdvertisementInterval=290(290) 2024-01-23T09:08:22.538945Z INFO bluetoothd[862]: MaxAdvertisementInterval=290(290) 2024-01-23T09:08:22.538954Z INFO bluetoothd[862]: ScanIntervalAutoConnect=588(588) 2024-01-23T09:08:22.538962Z INFO bluetoothd[862]: ScanWindowAutoConnect=60(60) 2024-01-23T09:08:22.538972Z INFO bluetoothd[862]: ScanIntervalDiscovery=36(36) 2024-01-23T09:08:22.538980Z INFO bluetoothd[862]: ScanWindowDiscovery=18(18) 2024-01-23T09:08:22.538988Z INFO bluetoothd[862]: ScanIntervalAdvMonitor=40(40) 2024-01-23T09:08:22.538995Z INFO bluetoothd[862]: ScanWindowAdvMonitor=20(20) 2024-01-23T09:08:22.539006Z INFO bluetoothd[862]: MinConnectionInterval=6(6) 2024-01-23T09:08:22.539013Z INFO bluetoothd[862]: MaxConnectionInterval=16(16) 2024-01-23T09:08:22.539022Z INFO bluetoothd[862]: ConnectionSupervisionTimeout=200(200) 2024-01-23T09:08:22.539030Z INFO bluetoothd[862]: Autoconnecttimeout=5000(5000) 2024-01-23T09:08:22.539075Z INFO bluetoothd[862]: EnableAdvMonInterleaveScan=1(1) 2024-01-23T09:08:22.557402Z INFO bluetoothd[862]: Starting SDP server 2024-01-23T09:08:22.594278Z INFO homedirs_initializer[986]: Restoring SELinux contexts for: /home/.shadow, recursive=true 2024-01-23T09:08:22.595652Z WARNING bluetoothd[862]: Unable to read debug levels from file 2024-01-23T09:08:22.596735Z NOTICE kernel: [ 13.393252] cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' 2024-01-23T09:08:22.596759Z NOTICE kernel: [ 13.402675] cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' 2024-01-23T09:08:22.605338Z NOTICE kernel: [ 13.412952] cfg80211: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' 2024-01-23T09:08:22.605473Z INFO bluetoothd[862]: Bluetooth management interface 1.22 initialized 2024-01-23T09:08:22.605875Z ERR homedirs_initializer[986]: Creating new salt at /home/.shadow/salt (0) 2024-01-23T09:08:22.612963Z NOTICE kernel: [ 13.422131] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' 2024-01-23T09:08:22.674062Z INFO kernel: [ 13.480724] loop3: detected capacity change from 0 to 178984 2024-01-23T09:08:22.716612Z INFO chapsd[981]: Starting PKCS #11 services. 2024-01-23T09:08:22.716983Z INFO chapsd[981]: Starting D-Bus dispatcher. 2024-01-23T09:08:22.725562Z INFO mojo_service_manager[766]: Receive connection from: u:r:cros_powerd:s0 2024-01-23T09:08:22.727366Z INFO cups_launcher[1097]: Waiting for events on /run/cups/cups.sock 2024-01-23T09:08:22.814631Z ERR session_manager[1046]: ERROR session_manager: [safe_fd.cc(704)] unlinkat failed for "SingletonLock": No such file or directory (2) 2024-01-23T09:08:22.814795Z ERR session_manager[1046]: ERROR session_manager: [safe_fd.cc(704)] unlinkat failed for "SingletonSocket": No such file or directory (2) 2024-01-23T09:08:22.828621Z INFO oobe_config_restore[1117]: Starting oobe_config_restore daemon 2024-01-23T09:08:22.828731Z WARNING session_manager[1046]: WARNING session_manager: [chrome_setup.cc(219)] Could not find both paths: /usr/share/chromeos-assets/wallpaper/oem_large.jpg and /usr/share/chromeos-assets/wallpaper/oem_small.jpg 2024-01-23T09:08:22.828768Z WARNING session_manager[1046]: WARNING session_manager: [chrome_setup.cc(228)] Could not find both paths: /usr/share/chromeos-assets/wallpaper/oem_large.png and /usr/share/chromeos-assets/wallpaper/oem_small.png 2024-01-23T09:08:22.829095Z ERR session_manager[1046]: ERROR session_manager: [chrome_setup.cc(563)] failed to load product_name dmi id file 2024-01-23T09:08:22.838789Z WARNING session_manager[1046]: WARNING session_manager: [libpolicy.cc(40)] Could not load the device policy file. 2024-01-23T09:08:22.842000Z INFO session_manager[1046]: INFO session_manager: [session_manager_main.cc(236)] Mount namespace created at /run/namespaces/mnt_chrome 2024-01-23T09:08:22.907521Z ERR attestationd[1131]: Get: /sys/firmware/vpd/ro/attested_device_id doesn't exist. 2024-01-23T09:08:22.907568Z INFO attestationd[1131]: No ADID found. 2024-01-23T09:08:22.907585Z INFO attestationd[1131]: Setting ADID to fake_attested_device_id_ for simulator. 2024-01-23T09:08:22.915437Z INFO attestationd[1131]: Attestation Daemon Started. 2024-01-23T09:08:22.922147Z INFO attestationd[1131]: Attestation service started. 2024-01-23T09:08:22.924477Z INFO attestationd[1131]: OnSignalConnected: Connected dbus signal successfully. 2024-01-23T09:08:22.929260Z INFO attestationd[1131]: Loading attestation database. 2024-01-23T09:08:22.929357Z ERR attestationd[1131]: Failed to read attestation database: No such file or directory (2) 2024-01-23T09:08:22.929368Z WARNING attestationd[1131]: Creating new attestation database. 2024-01-23T09:08:22.929437Z WARNING attestationd[1131]: Could not read hwid property. 2024-01-23T09:08:23.101739Z INFO vtpmd[1143]: OnSignalConnected: Connected dbus signal successfully. 2024-01-23T09:08:23.123892Z INFO session_manager[1046]: INFO session_manager: [session_manager_service.cc(183)] SessionManagerService starting 2024-01-23T09:08:23.125615Z INFO session_manager[1046]: INFO session_manager: [policy_key.cc(49)] No policy key on disk at /var/lib/devicesettings/owner.key 2024-01-23T09:08:23.125758Z INFO session_manager[1046]: INFO session_manager: [resilient_policy_store.cc(75)] No device policy file present. 2024-01-23T09:08:23.130033Z INFO session_manager[1046]: INFO session_manager: [dev_mode_unblock_broker.cc(68)] DevModeUnblockBroker awaiting_unblock_init_state_determination: 1 awaiting_unblock_enrollment: 1 awaiting_unblock_carrier_lock: 0 2024-01-23T09:08:23.132836Z INFO session_manager[1046]: INFO session_manager: [browser_job.cc(282)] Running browser /opt/google/chrome/chrome --enable-native-gpu-memory-buffers --disable-features=ArcVideoDecoder,FederatedService,CrostiniUseDlc --enable-webgl-image-chromium --enable-features=Pepper3DImageChromium,UmaStorageDimensions,ArcAdbSideloading,Crostini,CrostiniGpuSupport,SmartDim --gpu-sandbox-failures-fatal=no --video-capture-use-gpu-memory-buffer --enable-logging --log-level=1 --use-cras --enable-wayland-server --user-data-dir=/home/chronos --system-developer-mode --disable-mojo-broker --ash-use-cros-mojo-service-manager --cros-healthd-uses-service-manager --login-profile=user --has-chromeos-keyboard --disable-explicit-dma-fences --guest-wallpaper-large=/usr/share/chromeos-assets/wallpaper/guest_large.jpg --guest-wallpaper-small=/usr/share/chromeos-assets/wallpaper/guest_small.jpg --child-wallpaper-large=/usr/share/chromeos-assets/wallpaper/child_large.jpg --child-wallpaper-small=/usr/share/chromeos-assets/wallpaper/child_small.jpg --default-wallpaper-large=/usr/share/chromeos-assets/wallpaper/default_large.jpg --default-wallpaper-small=/usr/share/chromeos-assets/wallpaper/default_small.jpg --fyde-dynamic-default-wallpaper --enable-consumer-kiosk --device-help-content-id=rpi5-fydeos-io --ozone-nnpalm-properties={} --arc-availability=officially-supported --enable-arcvm --enterprise-enrollment-initial-modulus=15 --enterprise-enrollment-modulus-limit=19 --enable-crashpad --ondevice_handwriting=use_rootfs --ondevice_document_scanner=use_rootfs --feature-management-level=0 --feature-management-max-level=1 --feature-management-scope=0 --disable-arc-opt-in-verification --enable-hardware-overlays=single-fullscreen --load-media-router-component-extension=1 --enable-gpu-rasterization --use-gl=egl --enable-zero-copy --fydeos-arc-delay=5 --login-manager --first-exec-after-boot --enable-features=ArcAdbSideloading,Crostini,CrostiniGpuSupport,Pepper3DImageChromium,SmartDim,UmaStorageDimensions --disable-features=ArcVideoDecoder,CrostiniUseDlc,FederatedService 2024-01-23T09:08:23.134008Z INFO session_manager[1046]: INFO session_manager: [session_manager_service.cc(304)] Browser is 1153 2024-01-23T09:08:23.134193Z INFO tpm_managerd[901]: CreateStorageRootKeys: Created ECC SRK. 2024-01-23T09:08:23.140687Z INFO iioservice[784]: INFO iioservice: [iio_sensor.cc(56)] (2) Request(): Received SensorService from Mojo Service Manager 2024-01-23T09:08:23.146198Z INFO tpm_managerd[901]: CreateSaltingKey: Created ECC salting key. 2024-01-23T09:08:23.180682Z INFO tpm_managerd[901]: Ownership taken signal is sent. 2024-01-23T09:08:23.180999Z INFO vtpmd[1143]: OnOwnershipTaken: Received |OwnershipTakenSignal|. 2024-01-23T09:08:23.181066Z INFO attestationd[1131]: OnOwnershipTaken: Received |OwnershipTakenSignal|. 2024-01-23T09:08:23.181285Z INFO attestationd[1131]: Attestation: Preparing for enrollment... 2024-01-23T09:08:23.183630Z INFO mojo_service_manager[766]: Receive connection from: u:r:cros_shill:s0 2024-01-23T09:08:23.189637Z ERR tpm_managerd[901]: Unknown alerts family: 0 2024-01-23T09:08:23.189650Z INFO tpm_managerd[901]: The TPM chip does not support GetAlertsData. Stop UploadAlertsData task. 2024-01-23T09:08:23.193462Z INFO tpm_managerd[901]: PeriodicResetDictionaryAttackCounterTask: DA reset succeeded. 2024-01-23T09:08:23.194798Z ERR shill[1091]: ERROR shill: [utils.cc(88)] AddDBusError(...): Domain=dbus, Code=org.freedesktop.DBus.Error.ServiceUnknown, Message=The name org.chromium.PatchPanel was not provided by any .service files 2024-01-23T09:08:23.194968Z ERR shill[1091]: ERROR shill: [client.cc(543)] OnGetTrafficCountersError(): The name org.chromium.PatchPanel was not provided by any .service files 2024-01-23T09:08:23.199669Z INFO chapsd[981]: Adding slot: 0 2024-01-23T09:08:23.199743Z INFO chapsd[981]: Adding slot: 1 2024-01-23T09:08:23.202031Z ERR bootlockboxd[1020]: Failed to read NVRAM space. 2024-01-23T09:08:23.202085Z INFO bootlockboxd[1020]: Space is not defined, define it now 2024-01-23T09:08:23.213337Z INFO chapsd[981]: Opening database in: /var/lib/chaps 2024-01-23T09:08:23.213461Z INFO chapsd[981]: leveldb /var/lib/chaps/database directory did not exist. 2024-01-23T09:08:23.214359Z INFO attestationd[1131]: GetEndorsementPublicKey done. (from start: 33ms.) 2024-01-23T09:08:23.231619Z WARNING machine-id-regen[1157]: Could not read last regeneration time from /run/cros-machine-id-regen/timestamp-machine-id 2024-01-23T09:08:23.231646Z WARNING machine-id-regen[1157]: Reset last update time to 0 2024-01-23T09:08:23.250607Z INFO kernel: [ 14.047919] macb 1f00100000.ethernet eth0: PHY [1f00100000.ethernet-ffffffff:01] driver [Broadcom BCM54213PE] (irq=POLL) 2024-01-23T09:08:23.250622Z INFO kernel: [ 14.058850] macb 1f00100000.ethernet eth0: configuring for phy/rgmii-id link mode 2024-01-23T09:08:23.264054Z INFO kernel: [ 14.068850] pps pps0: new PPS source ptp0 2024-01-23T09:08:23.264079Z INFO kernel: [ 14.072982] macb 1f00100000.ethernet: gem-ptp-timer ptp clock registered. 2024-01-23T09:08:23.293378Z ERR machine-id-regen[1157]: Failed to call method: org.freedesktop.Avahi.Server.SetHostName: object_path= /: org.freedesktop.DBus.Error.Spawn.FileInvalid: Cannot do system-bus activation with no user#012 2024-01-23T09:08:23.293393Z WARNING machine-id-regen[1157]: SetHostName finished with org.freedesktop.DBus.Error.Spawn.FileInvalid error. 2024-01-23T09:08:23.293403Z WARNING machine-id-regen[1157]: Skip Avahi update assuming avahi deamon is offline. 2024-01-23T09:08:23.293747Z INFO machine-id-regen[1157]: Regenerated /var/lib/dbus/machine-id (reason: network) 2024-01-23T09:08:23.353189Z INFO attestationd[1131]: GetEndorsementCertificate done. (from start: 171ms.) 2024-01-23T09:08:23.353210Z INFO attestationd[1131]: Attestation: Creating identity 0 with IDENTITY_FEATURE_ENTERPRISE_ENROLLMENT_ID and ECC AIK. 2024-01-23T09:08:23.376863Z WARNING attestationd[1131]: Failed to get Hardware ID: Failed to read hwid property 2024-01-23T09:08:23.377065Z INFO attestationd[1131]: CreateIdentity done. (from start: 195ms.) 2024-01-23T09:08:23.377086Z INFO attestationd[1131]: Attestation: Encrypting endorsement credential for the default ACA. 2024-01-23T09:08:23.387113Z INFO attestationd[1131]: Attestation: Encrypting endorsement credential for the test ACA. 2024-01-23T09:08:23.672312Z INFO chapsd[981]: leveldb.stats:#012 Compactions#012Level Files Size(MB) Time(sec) Read(MB) Write(MB)#012--------------------------------------------------#012 2024-01-23T09:08:23.672345Z INFO chapsd[981]: leveldb contains 0 keys. 2024-01-23T09:08:23.711960Z INFO bootlockboxd[1020]: Space defined successfully. 2024-01-23T09:08:23.712831Z INFO bootlockboxd[1020]: BootLockboxd started 2024-01-23T09:08:23.968462Z INFO chapsd[981]: Initializing key hierarchy for token at /var/lib/chaps 2024-01-23T09:08:23.968526Z INFO chapsd[981]: Slot 0 ready for token at /var/lib/chaps 2024-01-23T09:08:23.974293Z INFO chapsd[981]: Created lock file: /run/lock/power_override/chapsd_token_init_slot_0.lock 2024-01-23T09:08:23.995183Z INFO attestationd[1131]: Attestation: Prepared successfully (813ms) with ECC EK. 2024-01-23T09:08:24.034628Z NOTICE chaps-restore[1164]: PKCS #11 restore TPM tokens call failed: (dbus, org.freedesktop.DBus.Error.ServiceUnknown, Error calling D-Bus method: org.chromium.CryptohomePkcs11Interface.Pkcs11RestoreTpmTokens: The name org.chromium.UserDataAuth was not provided by any .service files). 2024-01-23T09:08:24.119625Z INFO device_managementd[1031]: Install attributes reset back to first install. 2024-01-23T09:08:24.119663Z INFO bootlockboxd[1020]: Trying to define the nvspace, but the nvspace isn't undefined. 2024-01-23T09:08:24.163260Z INFO chapsd[981]: Root key is ready for token at /var/lib/chaps 2024-01-23T09:08:24.163477Z INFO chapsd[981]: Deleted lock file: /run/lock/power_override/chapsd_token_init_slot_0.lock 2024-01-23T09:08:24.753325Z INFO device_managementd[1031]: Load() called with unreadable FWMP. 2024-01-23T09:08:24.753712Z ERR cryptohomed[1167]: Failed to call GetFirmwareManagementParameters through proxy class: status 1 2024-01-23T09:08:24.754061Z ERR session_manager[1046]: ERROR session_manager: [dev_mode_unblock_broker.cc(195)] Failed to get firmware management parameters:26 2024-01-23T09:08:24.754084Z INFO session_manager[1046]: INFO session_manager: [dev_mode_unblock_broker.cc(140)] block_devmode_fwmp 0 2024-01-23T09:08:24.754267Z ERR session_manager[1046]: ERROR session_manager: [dev_mode_unblock_broker.cc(155)] Failed to read block_devmode flag! 2024-01-23T09:08:26.384612Z INFO mojo_service_manager[766]: Receive connection from: u:r:cros_browser:s0 2024-01-23T09:08:27.919671Z INFO dbus-daemon[685]: [system] Activating service name='org.chromium.Rgbkbd' requested by ':1.48' (uid=1000 pid=1153 comm="/opt/google/chrome/chrome --enable-native-gpu-memo" label="u:r:cros_browser:s0") (using servicehelper) 2024-01-23T09:08:27.972299Z INFO rgbkbd[1241]: Starting Chrome OS RGB Keyboard Daemon 2024-01-23T09:08:27.973984Z INFO dbus-daemon[685]: [system] Successfully activated service 'org.chromium.Rgbkbd' 2024-01-23T09:08:27.974313Z INFO rgbkbd[1241]: Checking RgbKeyboardCapabilities. 2024-01-23T09:08:27.974441Z INFO rgbkbd[1241]: Deducing Communication type 2024-01-23T09:08:27.974553Z ERR rgbkbd[1241]: Failed to open FD for EC with errno=2 2024-01-23T09:08:27.978727Z WARNING rgbkbd[1241]: Failed to initialize USB Endpoint after retry #2 2024-01-23T09:08:27.978750Z INFO rgbkbd[1241]: Failed to initialize EC USB Endpoint. 2024-01-23T09:08:27.978984Z ERR rgbkbd[1241]: Failed to deduce communication type for internal RGB Keyboard 2024-01-23T09:08:27.978995Z INFO rgbkbd[1241]: Device does not support an internal RGB keyboard 2024-01-23T09:08:27.979893Z INFO rgbkbd[1241]: Chrome OS RGB Keyboard Daemon exited successfully 2024-01-23T09:08:28.797070Z INFO session_manager[1046]: INFO session_manager: [upstart_signal_emitter.cc(51)] Emitting ash-initialized Upstart signal 2024-01-23T09:08:29.525106Z INFO oobe_config_restore[1117]: Chrome requested OOBE config. 2024-01-23T09:08:29.527591Z INFO oobe_config_restore[1117]: Looking for rollback state. 2024-01-23T09:08:29.527713Z INFO oobe_config_restore[1117]: No rollback oobe config found. 2024-01-23T09:08:29.678195Z INFO iioservice[784]: INFO iioservice: [iio_sensor.cc(56)] (2) Request(): Received SensorService from Mojo Service Manager 2024-01-23T09:08:29.719637Z INFO bluetoothd[862]: Bluetooth devcoredump state set to 1 2024-01-23T09:08:29.719791Z INFO bluetoothd[862]: LL Privacy status not changed: 0 2024-01-23T09:08:29.892045Z ERR arc-prepare-host-generated-dir[1268]: ERROR arc-prepare-host-generated-dir: [safe_fd.cc(704)] unlinkat failed for "combined.prop": No such file or directory (2) 2024-01-23T09:08:29.930279Z ERR arc-prepare-host-generated-dir[1268]: ERROR arc-prepare-host-generated-dir: [arc_property_util.cc(469)] Cannot get platform name 2024-01-23T09:08:33.557263Z WARNING chrome[1153]: cras_client: server connect failed: No such file or directory 2024-01-23T09:08:36.682056Z INFO kernel: [ 27.487201] logitech-hidpp-device 0003:046D:404D.0005: HID++ 4.1 device connected. 2024-01-23T09:08:37.557680Z WARNING chrome[1153]: message repeated 2 times: [ cras_client: server connect failed: No such file or directory] 2024-01-23T09:08:38.298268Z INFO session_manager[1046]: INFO session_manager: [upstart_signal_emitter.cc(51)] Emitting login-prompt-visible Upstart signal 2024-01-23T09:08:38.771018Z INFO kernel: [ 29.575083] EXT4-fs (mmcblk0p8): re-mounted. Quota mode: none. 2024-01-23T09:08:38.788038Z ERR periodic_scheduler[1461]: unlinkat failed for "cron-lite": No such file or directory (2) 2024-01-23T09:08:38.925499Z WARNING imageloader[1484]: Could not read key file /usr/share/misc/oci-container-key-pub.der 2024-01-23T09:08:38.927499Z NOTICE cros-disks[1534]: Loading FUSE module 2024-01-23T09:08:38.964111Z INFO imageloader[1484]: The latest-version file does not exist. Component WidevineCdm is probably not installed. 2024-01-23T09:08:39.008927Z ERR secanomalyd[1502]: opendir(/proc/1474) failed: No such file or directory (2) 2024-01-23T09:08:39.015395Z ERR secanomalyd[1502]: opendir(/proc/1484) failed: No such file or directory (2) 2024-01-23T09:08:39.016903Z ERR secanomalyd[1502]: opendir(/proc/1516) failed: No such file or directory (2) 2024-01-23T09:08:39.027521Z ERR secanomalyd[1502]: opendir(/proc/1545) failed: No such file or directory (2) 2024-01-23T09:08:39.027788Z ERR secanomalyd[1502]: opendir(/proc/1546) failed: No such file or directory (2) 2024-01-23T09:08:39.027964Z ERR secanomalyd[1502]: opendir(/proc/1547) failed: No such file or directory (2) 2024-01-23T09:08:39.028126Z ERR secanomalyd[1502]: opendir(/proc/1548) failed: No such file or directory (2) 2024-01-23T09:08:39.056738Z INFO imageloader[1541]: parent socket has shutdown. 2024-01-23T09:08:39.082057Z INFO kernel: [ 29.872653] fuse: init (API version 7.37) 2024-01-23T09:08:39.306065Z INFO kernel: [ 30.113537] mc: Linux media interface: v0.10 2024-01-23T09:08:39.338898Z INFO minijail0[1682]: libminijail[1682]: applying Landlock to process /usr/sbin/avahi-daemon 2024-01-23T09:08:39.398063Z INFO kernel: [ 30.152796] videodev: Linux video capture interface: v2.00 2024-01-23T09:08:39.442341Z INFO kernel: [ 30.246600] input: pwr_button as /devices/platform/pwr_button/input/input18 2024-01-23T09:08:39.494262Z INFO kernel: [ 30.287482] pispbe 1000880000.pisp_be: bcm2712_iommu_of_xlate: MMU 1000005100.iommu 2024-01-23T09:08:39.494289Z WARNING kernel: [ 30.296397] rpivid_hevc: module is from the staging directory, the quality is unknown, you have been warned. 2024-01-23T09:08:39.521644Z INFO kernel: [ 30.314990] pispbe 1000880000.pisp_be: pispbe_probe: HW version: 0x02252700 2024-01-23T09:08:39.521669Z INFO kernel: [ 30.330034] pispbe 1000880000.pisp_be: pispbe_probe: BatchStatus: 0x00000000 2024-01-23T09:08:39.530129Z INFO kernel: [ 30.338143] pispbe 1000880000.pisp_be: pispbe_probe: Status: 0x00000000 2024-01-23T09:08:39.537362Z INFO avahi-daemon[1682]: Found user 'avahi' (UID 238) and group 'avahi' (GID 238). 2024-01-23T09:08:39.537681Z INFO avahi-daemon[1682]: Successfully dropped root privileges. 2024-01-23T09:08:39.538055Z INFO kernel: [ 30.347712] pispbe 1000880000.pisp_be: Register nodes for group 0 2024-01-23T09:08:39.538070Z INFO kernel: [ 30.348123] rpivid 1000800000.codec: bcm2712_iommu_of_xlate: MMU 1000005100.iommu 2024-01-23T09:08:39.538116Z INFO avahi-daemon[1682]: avahi-daemon 0.8 starting up. 2024-01-23T09:08:39.545803Z INFO avahi-daemon[1682]: Successfully called chroot(). 2024-01-23T09:08:39.546002Z INFO avahi-daemon[1682]: Successfully dropped remaining capabilities. 2024-01-23T09:08:39.546277Z ERR avahi-daemon[1821]: chroot.c: open() failed: No such file or directory 2024-01-23T09:08:39.546411Z WARNING avahi-daemon[1682]: Failed to open /etc/resolv.conf: Invalid argument 2024-01-23T09:08:39.558436Z WARNING chrome[1153]: cras_client: server connect failed: No such file or directory 2024-01-23T09:08:39.565137Z INFO avahi-daemon[1682]: No service file found in /etc/avahi/services. 2024-01-23T09:08:39.590160Z INFO kernel: [ 30.394976] pispbe 1000880000.pisp_be: input device node registered as /dev/video20 2024-01-23T09:08:39.599135Z INFO avahi-daemon[1682]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. 2024-01-23T09:08:39.599332Z INFO avahi-daemon[1682]: New relevant interface lo.IPv4 for mDNS. 2024-01-23T09:08:39.599416Z INFO avahi-daemon[1682]: Network interface enumeration completed. 2024-01-23T09:08:39.599503Z INFO avahi-daemon[1682]: Registering new address record for 127.0.0.1 on lo.IPv4. 2024-01-23T09:08:39.619733Z INFO mojo_service_manager[766]: Receive connection from: u:r:cros_healthd:s0 2024-01-23T09:08:39.632537Z INFO kernel: [ 30.423890] pispbe 1000880000.pisp_be: tdn_input device node registered as /dev/video21 2024-01-23T09:08:39.632569Z INFO kernel: [ 30.438098] rpivid 1000800000.codec: Device registered as /dev/video19 2024-01-23T09:08:39.649800Z ERR cros_healthd[1537]: Failed to read cros_config: /hardware-properties/storage-type 2024-01-23T09:08:39.649839Z INFO cros_healthd[1537]: CrosHealthdDiagnosticsService is ready. 2024-01-23T09:08:39.662066Z INFO kernel: [ 30.456966] rpi-gpiomem 107d508500.gpiomem: window base 0x107d508500 size 0x00000040 2024-01-23T09:08:39.662075Z INFO iioservice[784]: INFO iioservice: [iio_sensor.cc(56)] (2) Request(): Received SensorService from Mojo Service Manager 2024-01-23T09:08:39.664611Z ERR cras_server[1715]: failed to initialize the cras_features backend: 1073741823 2024-01-23T09:08:39.675127Z INFO kernel: [ 30.482439] pispbe 1000880000.pisp_be: stitch_input device node registered as /dev/video22 2024-01-23T09:08:39.681549Z INFO cras_server[1715]: cras_feature_tier initialized with board="" cpu="" 2024-01-23T09:08:39.695338Z INFO cras_server[1715]: features updated: 001001010010 (LSB first) 2024-01-23T09:08:39.722057Z INFO kernel: [ 30.525071] rpi-gpiomem 107d508500.gpiomem: initialised 1 regions as /dev/gpiomem1 2024-01-23T09:08:39.749142Z INFO kernel: [ 30.544409] rpi-gpiomem 107d517c00.gpiomem: window base 0x107d517c00 size 0x00000040 2024-01-23T09:08:39.749168Z INFO kernel: [ 30.556672] pispbe 1000880000.pisp_be: hog_output device node registered as /dev/video23 2024-01-23T09:08:39.757511Z INFO kernel: [ 30.565331] pispbe 1000880000.pisp_be: output0 device node registered as /dev/video24 2024-01-23T09:08:39.774058Z INFO kernel: [ 30.577932] pispbe 1000880000.pisp_be: output1 device node registered as /dev/video25 2024-01-23T09:08:39.774080Z INFO kernel: [ 30.581682] rpi-gpiomem 107d517c00.gpiomem: initialised 1 regions as /dev/gpiomem2 2024-01-23T09:08:39.784978Z ERR usb_bouncer[1882]: Owner uid is 0 instead of 20124 2024-01-23T09:08:39.810067Z INFO kernel: [ 30.610080] rpi-gpiomem 107d504100.gpiomem: window base 0x107d504100 size 0x00000020 2024-01-23T09:08:39.836689Z INFO kernel: [ 30.626267] pispbe 1000880000.pisp_be: tdn_output device node registered as /dev/video26 2024-01-23T09:08:39.836718Z INFO kernel: [ 30.636228] pispbe 1000880000.pisp_be: stitch_output device node registered as /dev/video27 2024-01-23T09:08:39.836721Z INFO kernel: [ 30.636654] rpi-gpiomem 107d504100.gpiomem: initialised 1 regions as /dev/gpiomem3 2024-01-23T09:08:39.846064Z DEBUG kernel: [ 30.658233] brcmfmac: F1 signature read @0x18000000=0x15264345 2024-01-23T09:08:39.858055Z INFO kernel: [ 30.662363] pispbe 1000880000.pisp_be: config device node registered as /dev/video28 2024-01-23T09:08:39.858078Z INFO kernel: [ 30.664768] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 2024-01-23T09:08:39.863538Z INFO executor-delegate[1900]: Start cros_healthd executer delegate. 2024-01-23T09:08:39.871919Z INFO kernel: [ 30.679848] rpi-gpiomem 107d510700.gpiomem: window base 0x107d510700 size 0x00000020 2024-01-23T09:08:39.871949Z INFO kernel: [ 30.681874] usbcore: registered new interface driver brcmfmac 2024-01-23T09:08:39.922779Z INFO kernel: [ 30.714206] pispbe 1000880000.pisp_be: Register nodes for group 1 2024-01-23T09:08:39.922804Z INFO kernel: [ 30.733733] rpi-gpiomem 107d510700.gpiomem: initialised 1 regions as /dev/gpiomem4 2024-01-23T09:08:39.953790Z DEBUG chrome[1153]: file_wait->watch_id: 1, event->wd: 1, event->mask: 100, event->name: .cras_socket 2024-01-23T09:08:39.954359Z DEBUG chrome[1153]: file_wait->watch_id: 1, event->wd: 1, event->mask: 100, event->name: .cras_playback 2024-01-23T09:08:39.955667Z DEBUG chrome[1153]: file_wait->watch_id: 1, event->wd: 1, event->mask: 100, event->name: .cras_capture 2024-01-23T09:08:39.995078Z INFO kernel: [ 30.752402] rpi-gpiomem 1f000d0000.gpiomem: window base 0x1f000d0000 size 0x00030000 2024-01-23T09:08:39.995101Z INFO kernel: [ 30.752995] pispbe 1000880000.pisp_be: input device node registered as /dev/video29 2024-01-23T09:08:39.995106Z WARNING kernel: [ 30.770576] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,5-model-b.bin failed with error -2 2024-01-23T09:08:39.995109Z INFO kernel: [ 30.809110] pispbe 1000880000.pisp_be: tdn_input device node registered as /dev/video30 2024-01-23T09:08:40.000139Z ERR usb_bouncer[1999]: No active user session. 2024-01-23T09:08:40.011567Z INFO kernel: [ 30.819670] rpi-gpiomem 1f000d0000.gpiomem: initialised 1 regions as /dev/gpiomem0 2024-01-23T09:08:40.027464Z ERR usb_bouncer[1989]: No active user session. 2024-01-23T09:08:40.060384Z INFO kernel: [ 30.867800] pispbe 1000880000.pisp_be: stitch_input device node registered as /dev/video31 2024-01-23T09:08:40.089228Z INFO kernel: [ 30.896834] pispbe 1000880000.pisp_be: hog_output device node registered as /dev/video32 2024-01-23T09:08:40.161227Z INFO kernel: [ 30.930949] pispbe 1000880000.pisp_be: output0 device node registered as /dev/video33 2024-01-23T09:08:40.161269Z INFO kernel: [ 30.967778] pispbe 1000880000.pisp_be: output1 device node registered as /dev/video34 2024-01-23T09:08:40.209236Z ERR usb_bouncer[2112]: No active user session. 2024-01-23T09:08:40.220538Z INFO kernel: [ 31.028107] pispbe 1000880000.pisp_be: tdn_output device node registered as /dev/video35 2024-01-23T09:08:40.232852Z ERR usb_bouncer[2117]: No active user session. 2024-01-23T09:08:40.255758Z INFO kernel: [ 31.062807] pispbe 1000880000.pisp_be: stitch_output device node registered as /dev/video36 2024-01-23T09:08:40.272333Z INFO kernel: [ 31.075125] pispbe 1000880000.pisp_be: config device node registered as /dev/video37 2024-01-23T09:08:40.272361Z INFO kernel: [ 31.078535] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 2024-01-23T09:08:40.374817Z INFO kernel: [ 31.176412] Bluetooth: HCI UART driver ver 2.3 2024-01-23T09:08:40.374844Z INFO kernel: [ 31.185388] Bluetooth: HCI UART protocol H4 registered 2024-01-23T09:08:40.399039Z INFO kernel: [ 31.207959] Bluetooth: HCI UART protocol Three-wire (H5) registered 2024-01-23T09:08:40.451424Z WARNING kernel: [ 31.258151] hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator 2024-01-23T09:08:40.498129Z WARNING kernel: [ 31.270888] hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator 2024-01-23T09:08:40.498162Z INFO kernel: [ 31.278734] Bluetooth: HCI UART protocol Broadcom registered 2024-01-23T09:08:40.568421Z INFO sshd[2306]: Server listening on 0.0.0.0 port 22. 2024-01-23T09:08:40.568757Z INFO sshd[2306]: Server listening on :: port 22. 2024-01-23T09:08:40.653163Z INFO avahi-daemon[1682]: Server startup complete. Host name is 6d0bd0736cc7539494410ee90777247f.local. Local service cookie is 3471196577. 2024-01-23T09:08:40.670598Z INFO kernel: [ 31.475410] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled 2024-01-23T09:08:40.708804Z ERR usb_bouncer[2383]: No active user session. 2024-01-23T09:08:40.893834Z INFO kernel: [ 31.698284] Bluetooth: hci0: BCM: chip id 107 2024-01-23T09:08:40.893864Z INFO kernel: [ 31.705006] Bluetooth: hci0: BCM: features 0x2f 2024-01-23T09:08:40.907719Z INFO kernel: [ 31.710780] Bluetooth: hci0: BCM4345C0 2024-01-23T09:08:40.907746Z INFO kernel: [ 31.714574] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0000 2024-01-23T09:08:40.907750Z INFO kernel: [ 31.722862] Bluetooth: hci0: BCM4345C0 'brcm/BCM4345C0.hcd' Patch 2024-01-23T09:08:41.005693Z WARNING cras_server[1715]: [alsa-lib] snd_use_case_mgr_open() -> error: failed to import vc4-hdmi-1 use case configuration -2 2024-01-23T09:08:41.005775Z WARNING cras_server[1715]: Can not open ucm for card vc4-hdmi-1, rc = -2 2024-01-23T09:08:41.005784Z WARNING cras_server[1715]: No ucm config on internal card vc4-hdmi-1 2024-01-23T09:08:41.038563Z INFO dlcservice[1543]: INFO dlcservice: [dlc_service.cc(162)] Update Engine service available=1 2024-01-23T09:08:41.189248Z INFO root[2714]: fydeos-license-utils get disk /dev/mmcblk0 serial by lsblk 2024-01-23T09:08:41.192612Z INFO root[2717]: fydeos-license-utils get fix id by disk serial 2024-01-23T09:08:41.245472Z NOTICE send-boot-metrics[2764]: Missing timestamp in firmware log 2024-01-23T09:08:41.346459Z NOTICE temp_logger[2841]: cpu-thermal:36C 2024-01-23T09:08:41.366735Z NOTICE never-suspend[2858]: setup power manager policy to block suspend 2024-01-23T09:08:41.373798Z NOTICE chromeos-startup[2874]: 2024-01-23T09:08:13.658546Z WARNING chromeos_startup: [chromeos_startup.cc(926)] Failed to get boot information from crossystem 2024-01-23T09:08:41.373898Z NOTICE chromeos-startup[2874]: 2024-01-23T09:08:18.344156Z ERROR chromeos_startup: [safe_fd.cc(704)] unlinkat failed for "mount_encrypted_failed": No such file or directory (2) 2024-01-23T09:08:41.373928Z NOTICE chromeos-startup[2874]: 2024-01-23T09:08:18.344239Z ERROR chromeos_startup: [safe_fd.cc(704)] unlinkat failed for "version_pcr_extend_failed": No such file or directory (2) 2024-01-23T09:08:41.373954Z NOTICE chromeos-startup[2874]: 2024-01-23T09:08:18.430755Z WARNING chromeos_startup: [chromeos_startup.cc(845)] Unable to collect early logs and crashes.: No such file or directory (2) 2024-01-23T09:08:41.373980Z NOTICE chromeos-startup[2874]: 2024-01-23T09:08:18.630625Z ERROR chromeos_startup: [utils.cc(43)] restorecon: lstat(/home/.shadow) failed: No such file or directory 2024-01-23T09:08:41.374012Z NOTICE chromeos-startup[2874]: 2024-01-23T09:08:18.632478Z WARNING chromeos_startup: [stateful_mount.cc(560)] Failed to read from /mnt/stateful_partition/.update_available: No such file or directory (2) 2024-01-23T09:08:41.385190Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.597538Z INFO mount-encrypted: [mount_encrypted.cc(440)] Starting. 2024-01-23T09:08:41.385274Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.597847Z INFO mount-encrypted: [encrypted_fs.cc(501)] VFS mount state validity check ok. 2024-01-23T09:08:41.385299Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.599021Z INFO mount-encrypted: [tpm.cc(323)] TPM ready 2024-01-23T09:08:41.385321Z NOTICE mount-encrypted[2888]: Unable to open FDT property firmware/chromeos/firmware-type 2024-01-23T09:08:41.385343Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.599134Z INFO mount-encrypted: [mount_encrypted.cc(79)] Got System Property 'mainfw_type': FAIL 2024-01-23T09:08:41.385365Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.599163Z INFO mount-encrypted: [tpm2.cc(122)] Getting key from TPM2 NVRAM index 8388613 2024-01-23T09:08:41.385385Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.600255Z ERROR mount-encrypted: [tpm.cc(90)] Failed to read NVRAM space 8388613: 395 2024-01-23T09:08:41.385406Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.600331Z INFO mount-encrypted: [tpm.cc(472)] No encstateful NVRAM area defined. 2024-01-23T09:08:41.385426Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.600358Z INFO mount-encrypted: [tpm2.cc(130)] NVRAM area doesn't exist or is invalid 2024-01-23T09:08:41.385449Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.600381Z INFO mount-encrypted: [encryption_key.cc(208)] Using NVRAM as system key; finalization needed. 2024-01-23T09:08:41.385470Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.600431Z INFO mount-encrypted: [encryption_key.cc(270)] Attempting to generate fresh NVRAM system key. 2024-01-23T09:08:41.385473Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.604858Z ERROR mount-encrypted: [tpm.cc(255)] Failed to read NVRAM space info for index 8388613: 395 2024-01-23T09:08:41.385476Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.604974Z ERROR mount-encrypted: [tpm2.cc(49)] Failed to get NVRAM space attributes: 1 2024-01-23T09:08:41.385479Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.609468Z ERROR mount-encrypted: [encryption_key.cc(50)] Data read failed from /mnt/stateful_partition/encrypted.key 2024-01-23T09:08:41.385482Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.609537Z INFO mount-encrypted: [encryption_key.cc(306)] Failed to load encryption key from disk. 2024-01-23T09:08:41.385485Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.609643Z ERROR mount-encrypted: [encryption_key.cc(50)] Data read failed from /mnt/stateful_partition/encrypted.needs-finalization 2024-01-23T09:08:41.385487Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.609689Z INFO mount-encrypted: [encryption_key.cc(321)] Generating new encryption key. 2024-01-23T09:08:41.385490Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.609773Z INFO mount-encrypted: [encryption_key.cc(372)] Writing keyfile /mnt/stateful_partition/encrypted.key 2024-01-23T09:08:41.385493Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.636086Z ERROR mount-encrypted: [mount_encrypted.cc(369)] biod won't get a TPM seed without chromefw. 2024-01-23T09:08:41.385496Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.636329Z INFO mount-encrypted: [mount_encrypted.cc(274)] There is no hiberman binary, so skip sending TPM seed. 2024-01-23T09:08:41.385499Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.637727Z INFO mount-encrypted: [encrypted_fs.cc(311)] Purging block device 2024-01-23T09:08:41.386841Z NOTICE mount-encrypted[2888]: device-mapper: remove ioctl on encstateful failed: No such device or address 2024-01-23T09:08:41.386922Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.681547Z ERROR mount-encrypted: [device_mapper_task.cc(94)] dm_task_run failed 2024-01-23T09:08:41.386949Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.681764Z ERROR mount-encrypted: [device_mapper.cc(156)] Remove: Teardown failed. 2024-01-23T09:08:41.386972Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.681831Z ERROR mount-encrypted: [dmcrypt_container.cc(284)] Failed to teardown device mapper device. 2024-01-23T09:08:41.386994Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.682008Z ERROR mount-encrypted: [safe_fd.cc(704)] unlinkat failed for "encrypted.block": No such file or directory (2) 2024-01-23T09:08:41.387019Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.682552Z INFO mount-encrypted: [encrypted_fs.cc(346)] Creating sparse backing file with size 1243103232 2024-01-23T09:08:41.387043Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:15.902592Z INFO mount-encrypted: [dmcrypt_container.cc(162)] Removing provisioned dm-crypt key from kernel keyring. 2024-01-23T09:08:41.387067Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:18.198284Z INFO mount-encrypted: [encrypted_fs.cc(360)] Mounting /dev/mapper/encstateful onto /mnt/stateful_partition/encrypted 2024-01-23T09:08:41.387088Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:18.198581Z INFO mount-encrypted: [encrypted_fs.cc(136)] Using vm.dirty_expire_centisecs/100 as the commit interval 2024-01-23T09:08:41.387109Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:18.329809Z INFO mount-encrypted: [encrypted_fs.cc(106)] Resizing started in 2 second steps. 2024-01-23T09:08:41.387131Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:18.329900Z INFO mount-encrypted: [platform.cc(1383)] Resizing filesystem on /dev/mapper/encstateful to 303492 2024-01-23T09:08:41.387155Z NOTICE mount-encrypted[2888]: resize2fs 1.47.0 (5-Feb-2023) 2024-01-23T09:08:41.387179Z NOTICE mount-encrypted[2888]: The filesystem is already 303492 (4k) blocks long. Nothing to do! 2024-01-23T09:08:41.387200Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:41.387227Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:18.335201Z INFO mount-encrypted: [platform.cc(1388)] Resizing process started. 2024-01-23T09:08:41.387258Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:18.335287Z INFO mount-encrypted: [encrypted_fs.cc(120)] Resizing done. 2024-01-23T09:08:41.387281Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:18.335311Z INFO mount-encrypted: [encrypted_fs.cc(389)] Bind mounting /mnt/stateful_partition/encrypted/var onto /var 2024-01-23T09:08:41.387312Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:18.337052Z INFO mount-encrypted: [encrypted_fs.cc(389)] Bind mounting /mnt/stateful_partition/encrypted/chronos onto /home/chronos 2024-01-23T09:08:41.387343Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:18.339309Z ERROR mount-encrypted: [tpm.cc(90)] Failed to read NVRAM space 8388612: 395 2024-01-23T09:08:41.387367Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:18.340391Z ERROR mount-encrypted: [tpm.cc(90)] Failed to read NVRAM space 8388612: 395 2024-01-23T09:08:41.387393Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:18.340432Z INFO mount-encrypted: [tpm.cc(456)] No Lockbox NVRAM area defined. 2024-01-23T09:08:41.387416Z NOTICE mount-encrypted[2888]: 2024-01-23T09:08:18.340454Z INFO mount-encrypted: [mount_encrypted.cc(403)] Done. 2024-01-23T09:08:41.416736Z NOTICE preload-network[2911]: WiFi brcmfmac found after 1 seconds. 2024-01-23T09:08:41.453792Z NOTICE /usr/share/cros/init/crx-import.sh[2939]: Performing CRX import for: /usr/share/import_extensions/validation/fydeos-default-apps-1.0.0.validation 2024-01-23T09:08:41.472531Z NOTICE /usr/share/cros/init/crx-import.sh[2943]: Copy /mnt/stateful_partition/unencrypted/import_extensions -> /var/cache/external_cache.tmp, validated by /usr/share/import_extensions/validation/fydeos-default-apps-1.0.0.validation. 2024-01-23T09:08:41.477895Z WARNING minijail0[2930]: libminijail[2930]: realpath(/var/lib/ml_core/opencl_cache) failed: No such file or directory 2024-01-23T09:08:41.478146Z WARNING minijail0[2930]: libminijail[2930]: path '/var/lib/ml_core/opencl_cache' is not a canonical path 2024-01-23T09:08:41.478245Z WARNING minijail0[2930]: libminijail[2930]: src '/var/lib/ml_core/opencl_cache' is not a valid bind mount path 2024-01-23T09:08:41.482671Z INFO cras_server[1715]: test node:vc4-hdmi-1 HDMI Jack 2024-01-23T09:08:41.482714Z INFO cras_server[1715]: test node:HDMI 2024-01-23T09:08:41.482738Z INFO cras_server[1715]: card type: INTERNAL, Set active node. name: vc4-hdmi-1 HDMI Jack, id: 0, direction: output, type: HDMI, enable software volume: 1, intrinsic_sensitivity: 0, volume: 100, number_of_volume_steps: 25 2024-01-23T09:08:41.541182Z INFO missived[2929]: Starting Missive Service. 2024-01-23T09:08:41.550252Z WARNING missived[2929]: Service became available 2024-01-23T09:08:41.553008Z WARNING missived[2929]: Parameters updated 2024-01-23T09:08:41.560153Z WARNING missived[2929]: Flag `controlled_degradation` is initially enabled 2024-01-23T09:08:41.562327Z WARNING missived[2929]: Flag `compression` is initially enabled 2024-01-23T09:08:41.563394Z WARNING missived[2929]: Flag `encryption` is initially enabled 2024-01-23T09:08:41.564598Z WARNING missived[2929]: Flag `signature_verification_dev` is initially disabled 2024-01-23T09:08:41.571095Z WARNING missived[2929]: Encryption is enabled, but the key is not available yet, status=NOT_FOUND:No valid encryption key found 2024-01-23T09:08:41.574063Z INFO kernel: [ 32.380296] EXT4-fs (mmcblk0p8): re-mounted. Quota mode: none. 2024-01-23T09:08:41.627184Z INFO kernel: [ 32.422592] loop4: detected capacity change from 0 to 12328 2024-01-23T09:08:41.631225Z ERR debugd[2933]: WriteToFile(...): Domain=dbus, Code=org.chromium.debugd.error.DRMTrace, Message=Failed to open /sys/module/drm/parameters/trace, error: 4 2024-01-23T09:08:41.631271Z WARNING debugd[2933]: Failed to reset categories; drm_trace may have unexpected log entries. 2024-01-23T09:08:41.631380Z ERR debugd[2933]: WriteToFile(...): Domain=dbus, Code=org.chromium.debugd.error.DRMTrace, Message=Failed to open /sys/kernel/tracing/instances/drm/buffer_size_kb, error: 4 2024-01-23T09:08:41.631394Z WARNING debugd[2933]: Failed to reset trace buffer size; drm_trace may be larger than expected. 2024-01-23T09:08:41.637224Z ERR debugd[2933]: SetPolicy(...): Domain=dbus, Code=org.chromium.debugd.SchedulerConfigurationPolicyError, Message=Invalid architecture 2024-01-23T09:08:41.675231Z INFO kernel: [ 32.478547] Bluetooth: hci0: BCM: features 0x2f 2024-01-23T09:08:41.675262Z INFO kernel: [ 32.484739] Bluetooth: hci0: BCM43455 37.4MHz Raspberry Pi 3+-0190 2024-01-23T09:08:41.681325Z INFO kernel: [ 32.491095] Bluetooth: hci0: BCM4345C0 (003.001.025) build 0382 2024-01-23T09:08:41.682769Z WARNING cryptohomed[1167]: Could not load the device policy file. 2024-01-23T09:08:41.690919Z WARNING cras_server[1715]: [alsa-lib] snd_use_case_mgr_open() -> error: failed to import vc4-hdmi-0 use case configuration -2 2024-01-23T09:08:41.690952Z WARNING cras_server[1715]: Can not open ucm for card vc4-hdmi-0, rc = -2 2024-01-23T09:08:41.690962Z WARNING cras_server[1715]: No ucm config on internal card vc4-hdmi-0 2024-01-23T09:08:41.698158Z INFO kernel: [ 32.497581] Bluetooth: hci0: BCM: Using default device address (43:45:c0:00:1f:ac) 2024-01-23T09:08:41.718064Z INFO kernel: [ 32.528668] Bluetooth: MGMT ver 1.22 2024-01-23T09:08:41.758086Z INFO kernel: [ 32.566031] NET: Registered PF_ALG protocol family 2024-01-23T09:08:41.759585Z INFO u2fd[2988]: INFO u2fd: [main.cc(36)] Daemon version 0.0.1-r2058-04097b9df3 2024-01-23T09:08:41.790062Z INFO kernel: [ 32.598113] zram: Added device: zram0 2024-01-23T09:08:41.797365Z INFO resourced[3006]: Starting resourced 2024-01-23T09:08:41.804587Z INFO swap_management[2937]: Setting zram disksize to 8363540480 bytes 2024-01-23T09:08:41.823168Z INFO resourced[3006]: Power source DC 2024-01-23T09:08:41.854284Z INFO minijail0[3064]: libminijail[3064]: child process 3106 exited with status 1 2024-01-23T09:08:41.877412Z WARNING u2fd[2988]: WARNING u2fd: [libpolicy.cc(40)] Could not load the device policy file. 2024-01-23T09:08:41.918179Z INFO minijail0[3141]: libminijail[3141]: child process 3156 exited with status 1 2024-01-23T09:08:41.918844Z ERR debugd[2933]: RunHelper(...): Domain=dbus, Code=org.chromium.debugd.error.DevFeatures, Message=Unable to open FDT property firmware/chromeos/firmware-version#012 2024-01-23T09:08:41.928367Z INFO bluetoothd[862]: Battery Provider Manager created 2024-01-23T09:08:41.929142Z INFO bluetoothd[862]: Admin Policy Set interface registered 2024-01-23T09:08:41.929167Z INFO bluetoothd[862]: Admin Policy Status interface registered 2024-01-23T09:08:41.932208Z NOTICE /usr/share/cros/init/crx-import.sh[2943]: cp: cannot stat '/mnt/stateful_partition/unencrypted/import_extensions/iakadpgajjigiaojnbdmodlngmbkfhag-3.0.0.crx': No such file or directory 2024-01-23T09:08:41.938905Z INFO minijail0[3157]: libminijail[1]: applying Landlock to process /usr/bin/cros_camera_algo 2024-01-23T09:08:41.075161Z INFO kernel: [ 32.819268] Bluetooth: RFCOMM TTY layer initialized 2024-01-23T09:08:41.075168Z INFO kernel: [ 32.824803] Bluetooth: RFCOMM socket layer initialized 2024-01-23T09:08:41.079509Z INFO kernel: [ 32.830622] Bluetooth: RFCOMM ver 1.11 2024-01-23T09:08:41.080492Z INFO bluetoothd[862]: Set quality report adapter 0 enable 0 2024-01-23T09:08:41.082428Z INFO bluetoothd[862]: Adv Monitor Manager created with supported features:0x00000000, enabled features:0x00000000, max number of supported monitors:32, max number of supported patterns:16 2024-01-23T09:08:41.087381Z INFO kernel: [ 32.836419] zram0: detected capacity change from 0 to 16335040 2024-01-23T09:08:41.098750Z INFO swap_management[2937]: /sbin/mkswap: Setting up swapspace version 1, size = 7.8 GiB (8363536384 bytes)#012no label, UUID=31870b02-aab2-47c3-8053-7e7fcd6761b8#012 2024-01-23T09:08:41.126889Z INFO kernel: [ 32.864167] Adding 8167516k swap on /dev/zram0. Priority:-2 extents:1 across:8167516k SS 2024-01-23T09:08:41.128545Z INFO bluetoothd[862]: Path /org/chromium/bluetooth_advertisement_monitor reserved for Adv Monitor app :1.48 2024-01-23T09:08:41.204838Z INFO root[3236]: fydeos-license-utils get disk /dev/mmcblk0 serial by lsblk 2024-01-23T09:08:41.210902Z INFO root[3238]: fydeos-license-utils get fix id by disk serial 2024-01-23T09:08:41.266402Z INFO session_manager[1046]: INFO session_manager: [device_identifier_generator.cc(132)] Stable device secret missing! 2024-01-23T09:08:41.266815Z ERR session_manager[1046]: ERROR session_manager: [device_identifier_generator.cc(272)] No stable device secret available. 2024-01-23T09:08:41.282662Z INFO bluetoothd[862]: adapter /org/bluez/hci0 has been enabled 2024-01-23T09:08:41.297857Z WARNING kernel: [ 33.042604] Bluetooth: hci0: unsupported parameter 28 2024-01-23T09:08:41.297889Z WARNING kernel: [ 33.047695] Bluetooth: hci0: unsupported parameter 28 2024-01-23T09:08:41.303888Z INFO cras_server[1715]: test node:vc4-hdmi-0 HDMI Jack 2024-01-23T09:08:41.305970Z INFO cras_server[1715]: test node:HDMI 2024-01-23T09:08:41.306006Z INFO cras_server[1715]: card type: INTERNAL, Set active node. name: vc4-hdmi-0 HDMI Jack, id: 0, direction: output, type: HDMI, enable software volume: 1, intrinsic_sensitivity: 0, volume: 100, number_of_volume_steps: 25 2024-01-23T09:08:41.312938Z INFO bluetoothd[862]: Endpoint registered: sender=:1.79 path=/org/chromium/Cras/Bluetooth/A2DPSource 2024-01-23T09:08:41.314007Z INFO bluetoothd[862]: Player registered: sender=:1.79 path=/org/chromium/Cras/Bluetooth/DefaultPlayer 2024-01-23T09:08:41.314638Z INFO cras_server[1715]: DSP input effects are deactivated: non_echo=1 disallow=0 2024-01-23T09:08:41.391157Z INFO crash_reporter[3270]: OOBE path doesn't exist. Integration tests running? false Mock consent? false 2024-01-23T09:08:41.391354Z INFO crash_reporter[3270]: Running boot collector 2024-01-23T09:08:41.392287Z INFO crash_reporter[3270]: developer build - not testing - always dumping 2024-01-23T09:08:41.392463Z INFO crash_reporter[3270]: Unsupported GSC present on board. Unable to query GSC crashes. 2024-01-23T09:08:41.392552Z INFO crash_reporter[3270]: Failed to get the GSC flog output. 2024-01-23T09:08:41.392669Z INFO crash_reporter[3270]: Enabling kernel crash handling 2024-01-23T09:08:41.392810Z INFO crash_reporter[3270]: Found 0 kernel crashes in efi-pstore. 2024-01-23T09:08:41.392911Z INFO crash_reporter[3270]: /sys/firmware/log does not exist, skipping BIOS crash check. (This is normal for older boards.) 2024-01-23T09:08:41.393028Z WARNING crash_reporter[3270]: No console-ramoops file found after watchdog reset 2024-01-23T09:08:41.393389Z ERR crash_reporter[3270]: Unable to read /sys/class/watchdog/watchdog0/bootstatus: No such file or directory (2) 2024-01-23T09:08:41.393589Z INFO crash_reporter[3270]: Examining /run/crash_reporter/crash for crashes 2024-01-23T09:08:41.393753Z INFO crash_reporter[3270]: Examining /mnt/stateful_partition/reboot_vault/crash for crashes 2024-01-23T09:08:41.410078Z INFO cras_server[1715]: AudioFRA:11 context:{"type":"underrun"} 2024-01-23T09:08:41.410696Z INFO cras_server[1715]: AudioFRA:11 context:{"type":"underrun"} 2024-01-23T09:08:41.410725Z INFO cras_server[1715]: AudioFRA:11 context:{"type":"busyloop"} 2024-01-23T09:08:41.411610Z INFO cras_server[1715]: AudioFRA:11 context:{"type":"underrun"} 2024-01-23T09:08:41.421181Z WARNING cras_server[1715]: pcm_avail returned frames larger than buf_size: vc4-hdmi-0: MAI PCM i2s-hifi-0:0,0: 66040 > 65536 2024-01-23T09:08:41.422383Z INFO cras_server[1715]: AudioFRA:11 context:{"type":"underrun"} 2024-01-23T09:08:41.429029Z INFO minijail0[3284]: libminijail[3284]: applying Landlock to process /usr/bin/metrics_daemon 2024-01-23T09:08:41.472873Z ERR metrics_daemon[3284]: ERROR metrics_daemon: [vmlog_writer.cc(258)] RAPL info disabled (b/168594119).: No such file or directory (2) 2024-01-23T09:08:41.492286Z WARNING memd[3291]: memd started 2024-01-23T09:08:41.666005Z ERR patchpaneld[1505]: ERROR patchpaneld: [datapath.cc(458)] Failed to create fwmark tagging rule for uid {source: CHROME, uid: chronos, classid: 0, is_on_vpn: true} in apply_local_source_mark 2024-01-23T09:08:41.747672Z ERR patchpaneld[1505]: ERROR patchpaneld: [datapath.cc(458)] Failed to create fwmark tagging rule for uid {source: USER, uid: debugd, classid: 0, is_on_vpn: true} in apply_local_source_mark 2024-01-23T09:08:41.823722Z ERR patchpaneld[1505]: ERROR patchpaneld: [datapath.cc(458)] Failed to create fwmark tagging rule for uid {source: USER, uid: cups, classid: 0, is_on_vpn: true} in apply_local_source_mark 2024-01-23T09:08:41.901301Z ERR patchpaneld[1505]: ERROR patchpaneld: [datapath.cc(458)] Failed to create fwmark tagging rule for uid {source: USER, uid: lpadmin, classid: 0, is_on_vpn: true} in apply_local_source_mark 2024-01-23T09:08:41.978770Z ERR patchpaneld[1505]: ERROR patchpaneld: [datapath.cc(458)] Failed to create fwmark tagging rule for uid {source: SYSTEM, uid: kerberosd, classid: 0, is_on_vpn: true} in apply_local_source_mark 2024-01-23T09:08:42.073949Z ERR patchpaneld[1505]: ERROR patchpaneld: [datapath.cc(458)] Failed to create fwmark tagging rule for uid {source: SYSTEM, uid: kerberosd-exec, classid: 0, is_on_vpn: true} in apply_local_source_mark 2024-01-23T09:08:42.169385Z ERR patchpaneld[1505]: ERROR patchpaneld: [datapath.cc(458)] Failed to create fwmark tagging rule for uid {source: SYSTEM, uid: tlsdate, classid: 0, is_on_vpn: true} in apply_local_source_mark 2024-01-23T09:08:42.268269Z ERR patchpaneld[1505]: ERROR patchpaneld: [datapath.cc(458)] Failed to create fwmark tagging rule for uid {source: USER, uid: pluginvm, classid: 0, is_on_vpn: true} in apply_local_source_mark 2024-01-23T09:08:42.276839Z NOTICE dbus-daemon[685]: [system] Rejected send message, 2 matched rules; type="method_call", sender=":1.118" (uid=20130 pid=3097 comm="/usr/libexec/fwupd/fwupd --verbose --no-timestamp " label="u:r:cros_fwupd:s0") interface="org.freedesktop.DBus.ObjectManager" member="GetManagedObjects" error name="(unset)" requested_reply="0" destination=":1.12" (uid=218 pid=862 comm="/usr/libexec/bluetooth/bluetoothd --nodetach --co" label="u:r:cros_bluetoothd:s0") 2024-01-23T09:08:42.376281Z ERR patchpaneld[1505]: ERROR patchpaneld: [datapath.cc(458)] Failed to create fwmark tagging rule for uid {source: SYSTEM, uid: fuse-smbfs, classid: 0, is_on_vpn: true} in apply_local_source_mark 2024-01-23T09:08:42.533747Z ERR patchpaneld[1505]: ERROR patchpaneld: [datapath.cc(458)] Failed to create fwmark tagging rule for uid {source: UPDATE_ENGINE, uid: , classid: 65537, is_on_vpn: false} in apply_local_source_mark 2024-01-23T09:08:42.742842Z ERR patchpaneld[1505]: ERROR patchpaneld: [datapath.cc(499)] Failed to add jump rule to skip VPN mark chain in mangle OUTPUT chain 2024-01-23T09:08:42.824565Z ERR shill[1091]: ERROR shill: [utils.cc(88)] AddDBusError(...): Domain=dbus, Code=org.freedesktop.DBus.Error.ServiceUnknown, Message=The name org.chromium.PatchPanel was not provided by any .service files 2024-01-23T09:08:42.824596Z ERR shill[1091]: ERROR shill: [client.cc(543)] OnGetTrafficCountersError(): The name org.chromium.PatchPanel was not provided by any .service files 2024-01-23T09:08:44.351149Z INFO kernel: [ 36.098984] IPv6: ADDRCONF(NETDEV_CHANGE): arc_ns0: link becomes ready 2024-01-23T09:08:44.359019Z INFO kernel: [ 36.106832] IPv6: ADDRCONF(NETDEV_CHANGE): veth0: link becomes ready 2024-01-23T09:08:44.502919Z INFO kernel: [ 36.250923] IPv6: ADDRCONF(NETDEV_CHANGE): veth1: link becomes ready 2024-01-23T09:08:47.579036Z CRIT root[3684]: No new logout-started signal received after 10 seconds. 2024-01-23T09:08:49.012296Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(939)] Enabling DLC=sr-bt-dlc reserve. 2024-01-23T09:08:49.032995Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=sr-bt-dlc state to INSTALLING 2024-01-23T09:08:49.058923Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/sr-bt-dlc/package/dlc_a/dlc.img. Expected: 256681675B87FBFBAB94CF07CFADAFAF7EFD5BEDA7044FD4BDF9991CCD9E8DAD Found: D4AFC7DB5655884EA713B0EDFF6AD7F8CF71042B92F9C8953E254CA4122CB20E 2024-01-23T09:08:49.059046Z INFO dlcservice[1543]: INFO dlcservice: [dlc_service.cc(294)] Sending request to update_engine to install DLC=sr-bt-dlc 2024-01-23T09:08:49.063650Z WARNING cras_server[1715]: download_supported_dlc: retry downloading `sr-bt-dlc`, attempt #1. 2024-01-23T09:08:49.065597Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(392)] GetDlc(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.INVALID_DLC, Message=Passed unsupported DLC=nc-ap-dlc 2024-01-23T09:08:49.068057Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(392)] GetDlc(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.INVALID_DLC, Message=Passed unsupported DLC=nc-ap-dlc 2024-01-23T09:08:49.069447Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(222)] Failed to install DLC=nc-ap-dlc 2024-01-23T09:08:49.069712Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=nc-ap-dlc 2024-01-23T09:08:49.070023Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:5 2024-01-23T09:08:49.070538Z WARNING cras_server[1715]: cras_dlc_install(nc-ap-dlc) failed: D-Bus failure: Passed unsupported DLC=nc-ap-dlc 2024-01-23T09:08:49.070573Z ERR cras_server[1715]: download_supported_dlc: unable to connect to dlcservice during `cras_dlc_install`. 2024-01-23T09:08:49.070581Z WARNING cras_server[1715]: download_supported_dlc: retry downloading `nc-ap-dlc`, attempt #1. 2024-01-23T09:08:54.803005Z INFO kernel: [ 46.547944] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready 2024-01-23T09:08:54.864299Z ERR shill[1091]: ERROR shill: [routing_table.cc(204)] Cannot write to /proc/sys/net/ipv6/conf/wlan0/accept_ra_rt_table 2024-01-23T09:08:55.018993Z INFO kernel: [ 46.768446] 8021q: 802.1Q VLAN Support v1.8 2024-01-23T09:08:56.111245Z ERR dhcpcd[3749]: wlan0: dhcp_envoption 213: Operation not supported 2024-01-23T09:08:56.111257Z ERR /usr/bin/shill[1142]: wlan0: dhcp_envoption 213: Operation not supported 2024-01-23T09:08:56.111286Z ERR dhcpcd[3749]: wlan0: dhcp_envoption 213: Operation not supported 2024-01-23T09:08:56.111286Z ERR /usr/bin/shill[1142]: wlan0: dhcp_envoption 213: Operation not supported 2024-01-23T09:08:56.115120Z INFO avahi-daemon[1682]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.48. 2024-01-23T09:08:56.117063Z INFO avahi-daemon[1682]: New relevant interface wlan0.IPv4 for mDNS. 2024-01-23T09:08:56.117211Z INFO avahi-daemon[1682]: Registering new address record for 192.168.3.48 on wlan0.IPv4. 2024-01-23T09:08:56.525786Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=60, Message=SSL peer certificate or SSH remote key was not OK 2024-01-23T09:08:56.751938Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=60, Message=SSL peer certificate or SSH remote key was not OK 2024-01-23T09:08:58.334906Z ERR kernel: [ 50.080390] ieee80211 phy0: brcmf_p2p_send_action_frame: Unknown Frame: category 0xa, action 0x8 2024-01-23T09:09:00.455365Z ERR dhcpcd[3749]: wlan0: dhcp_envoption 213: Operation not supported 2024-01-23T09:09:00.455370Z ERR /usr/bin/shill[1142]: wlan0: dhcp_envoption 213: Operation not supported 2024-01-23T09:09:00.455415Z ERR /usr/bin/shill[1142]: wlan0: dhcp_envoption 213: Operation not supported 2024-01-23T09:09:00.455417Z ERR dhcpcd[3749]: wlan0: dhcp_envoption 213: Operation not supported 2024-01-23T09:09:00.467432Z ERR shill[1091]: ERROR shill: [rtnl_message.cc(1104)] RTNL address message does not have a valid IFA_ADDRESS 2024-01-23T09:09:00.477190Z ERR dhcpcd[3749]: wlan0: dhcp_envoption 213: Operation not supported 2024-01-23T09:09:00.477204Z ERR /usr/bin/shill[1142]: wlan0: dhcp_envoption 213: Operation not supported 2024-01-23T09:09:00.477233Z ERR dhcpcd[3749]: wlan0: dhcp_envoption 213: Operation not supported 2024-01-23T09:09:00.477233Z ERR /usr/bin/shill[1142]: wlan0: dhcp_envoption 213: Operation not supported 2024-01-23T09:09:00.493602Z ERR shill[1091]: ERROR shill: [rtnl_message.cc(1104)] RTNL address message does not have a valid IFA_ADDRESS 2024-01-23T09:09:00.584901Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=60, Message=SSL peer certificate or SSH remote key was not OK 2024-01-23T09:09:06.636783Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=28, Message=Timeout was reached 2024-01-23T09:09:07.760001Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=35, Message=SSL connect error 2024-01-23T09:09:09.071834Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(939)] Enabling DLC=sr-bt-dlc reserve. 2024-01-23T09:09:09.072488Z WARNING cras_server[1715]: download_supported_dlc: retry downloading `sr-bt-dlc`, attempt #2. 2024-01-23T09:09:09.074772Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(392)] GetDlc(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.INVALID_DLC, Message=Passed unsupported DLC=nc-ap-dlc 2024-01-23T09:09:09.077122Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(392)] GetDlc(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.INVALID_DLC, Message=Passed unsupported DLC=nc-ap-dlc 2024-01-23T09:09:09.077141Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(222)] Failed to install DLC=nc-ap-dlc 2024-01-23T09:09:09.077151Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=nc-ap-dlc 2024-01-23T09:09:09.077321Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:5 2024-01-23T09:09:09.077592Z WARNING cras_server[1715]: cras_dlc_install(nc-ap-dlc) failed: D-Bus failure: Passed unsupported DLC=nc-ap-dlc 2024-01-23T09:09:09.077617Z ERR cras_server[1715]: download_supported_dlc: unable to connect to dlcservice during `cras_dlc_install`. 2024-01-23T09:09:09.077626Z WARNING cras_server[1715]: download_supported_dlc: retry downloading `nc-ap-dlc`, attempt #2. 2024-01-23T09:09:09.545870Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=60, Message=SSL peer certificate or SSH remote key was not OK 2024-01-23T09:09:12.791223Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:12.804683Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:12.808639Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:12.808872Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:12.808987Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:12.809123Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:12.812706Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:12.813110Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:12.813307Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:12.813522Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:13.306885Z ERR kernel: [ 65.052523] ieee80211 phy0: brcmf_cfg80211_scan: Connecting: status (39) 2024-01-23T09:09:15.815971Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:15.828855Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:15.830678Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:15.830732Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:15.830755Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:15.830793Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:15.833177Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:15.833435Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:15.833508Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:15.833613Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:16.785332Z INFO device_managementd[1031]: Load() called with unreadable FWMP. 2024-01-23T09:09:16.785852Z ERR cryptohomed[1167]: Failed to call GetFirmwareManagementParameters through proxy class: status 1 2024-01-23T09:09:16.796084Z WARNING session_manager[1046]: WARNING session_manager: [device_policy_service.cc(588)] Clear block_devmode requested 2024-01-23T09:09:16.796580Z ERR session_manager[1046]: ERROR session_manager: [dbus_util.cc(13)] CreateError(...): Domain=dbus, Code=org.chromium.SessionManagerInterface.SystemPropertyUpdateFailed, Message=Failed to set block_devmode system property to 0. 2024-01-23T09:09:18.836660Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:18.850355Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:18.852623Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:18.853050Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:18.853220Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:18.854277Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:18.857507Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:18.858067Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:18.858235Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:18.858465Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:21.524600Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=60, Message=SSL peer certificate or SSH remote key was not OK 2024-01-23T09:09:21.860120Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:21.869723Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:21.871462Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:21.871505Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:21.871520Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:21.871556Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:21.873937Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:21.874377Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:21.874418Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:21.874525Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:24.875825Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:24.885521Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:24.887537Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:24.887651Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:24.887710Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:24.887785Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:24.890140Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:24.890466Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:24.890564Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:24.890703Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:27.892509Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:27.902432Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:27.904321Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:27.904423Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:27.904480Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:27.904551Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:27.906813Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:27.907261Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:27.907427Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:27.907682Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:30.494947Z ERR hardware_verifier[3968]: Failed to read the verification payload file. 2024-01-23T09:09:30.495160Z ERR hardware_verifier[3968]: Failed to read the verification payload file. 2024-01-23T09:09:30.496849Z INFO minijail0[3966]: libminijail[3966]: child process 3967 exited with status 2 2024-01-23T09:09:30.504742Z NOTICE hardware_verifier[3971]: hardware_verifier exits with status 2. 2024-01-23T09:09:30.909634Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:30.919510Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:30.922479Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:30.922713Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:30.922862Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:30.923008Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:30.925474Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:30.925767Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:30.925958Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:30.926160Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:31.504030Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=28, Message=Timeout was reached 2024-01-23T09:09:31.717805Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=60, Message=SSL peer certificate or SSH remote key was not OK 2024-01-23T09:09:33.928700Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:33.940265Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:33.942342Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:33.942530Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:33.942680Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:33.942820Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:33.945635Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:33.945889Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:33.945928Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:33.946087Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:36.947802Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:36.959302Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:36.961411Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:36.961430Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:36.961442Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:36.961471Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:36.963782Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:36.963925Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:36.963951Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:36.964012Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:39.775654Z ERR shill[1091]: ERROR shill: [http_request.cc(217)] wlan0 wifi_psk_2 IPv4 attempt=6 HTTP probe: Could not resolve api.fydeos.com: The network connection was timed out 2024-01-23T09:09:39.967723Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:39.978800Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:39.980673Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:39.980691Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:39.980704Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:39.980731Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:39.983379Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:39.983911Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:39.984102Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:39.984317Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:40.428134Z NOTICE temp_logger[4065]: cpu-thermal:39C 2024-01-23T09:09:41.321049Z INFO cryptohomed[1167]: AuthSession: started with is_ephemeral_user=0 intent=decrypt user_exists=0 factors=. 2024-01-23T09:09:41.331042Z INFO cryptohomed[1167]: Creating persistent user 2024-01-23T09:09:41.331489Z INFO cryptohomed[1167]: AuthSession: authorized for decrypt,verify-only. 2024-01-23T09:09:41.343024Z INFO cryptohomed[1167]: Preparing persistent vault 2024-01-23T09:09:41.413803Z INFO cryptohomed[1167]: Started mounting for: e59a8412168a29766df1f09113348366517cedd7 2024-01-23T09:09:41.418944Z INFO device_managementd[1031]: Finalizing() 2 bytes. 2024-01-23T09:09:41.545181Z INFO device_managementd[1031]: InstallAttributes have been finalized. 2024-01-23T09:09:41.545628Z WARNING cryptohomed[1167]: Could not load the device policy file. 2024-01-23T09:09:41.546581Z WARNING cryptohomed[1167]: Ecryptfs mount block flag has no effect for new vaults. 2024-01-23T09:09:41.624921Z INFO cryptohome-namespace-mounter[4157]: Performing mount of type DIR_CRYPTO 2024-01-23T09:09:41.625870Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 1000 2024-01-23T09:09:41.626138Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 1000 2024-01-23T09:09:41.626435Z INFO cryptohome-namespace-mounter[4157]: Finished ensuring user mount points 2024-01-23T09:09:41.626992Z INFO cryptohome-namespace-mounter[4157]: Setting up dircrypto mount 2024-01-23T09:09:41.627005Z INFO cryptohome-namespace-mounter[4157]: Creating vault directory structure 2024-01-23T09:09:41.634603Z ERR cryptohome-namespace-mounter[4157]: Owner gid is 0 instead of 400 2024-01-23T09:09:41.634806Z INFO cryptohome-namespace-mounter[4157]: Created vault subdirectory: /home/.shadow/e59a8412168a29766df1f09113348366517cedd7/mount/root 2024-01-23T09:09:41.634909Z INFO kernel: [ 93.382054] fscrypt: AES-256-CTS-CBC using implementation "cts-cbc-aes-ce" 2024-01-23T09:09:41.635258Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 1000 2024-01-23T09:09:41.635375Z INFO cryptohome-namespace-mounter[4157]: Created vault subdirectory: /home/.shadow/e59a8412168a29766df1f09113348366517cedd7/mount/user 2024-01-23T09:09:41.635860Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 1000 2024-01-23T09:09:41.635975Z INFO cryptohome-namespace-mounter[4157]: Created vault subdirectory: /home/.shadow/e59a8412168a29766df1f09113348366517cedd7/mount/user/MyFiles 2024-01-23T09:09:41.636364Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 1000 2024-01-23T09:09:41.636522Z INFO cryptohome-namespace-mounter[4157]: Created vault subdirectory: /home/.shadow/e59a8412168a29766df1f09113348366517cedd7/mount/user/MyFiles/Downloads 2024-01-23T09:09:41.636912Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 1000 2024-01-23T09:09:41.637039Z INFO cryptohome-namespace-mounter[4157]: Created vault subdirectory: /home/.shadow/e59a8412168a29766df1f09113348366517cedd7/mount/user/Downloads 2024-01-23T09:09:41.637429Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 1000 2024-01-23T09:09:41.637560Z INFO cryptohome-namespace-mounter[4157]: Created vault subdirectory: /home/.shadow/e59a8412168a29766df1f09113348366517cedd7/mount/user/GCache 2024-01-23T09:09:41.637937Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 1000 2024-01-23T09:09:41.638065Z INFO cryptohome-namespace-mounter[4157]: Created vault subdirectory: /home/.shadow/e59a8412168a29766df1f09113348366517cedd7/mount/user/Cache 2024-01-23T09:09:41.638463Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 1000 2024-01-23T09:09:41.638579Z INFO cryptohome-namespace-mounter[4157]: Created vault subdirectory: /home/.shadow/e59a8412168a29766df1f09113348366517cedd7/mount/user/GCache/v2 2024-01-23T09:09:41.638943Z ERR cryptohome-namespace-mounter[4157]: Owner gid is 0 instead of 400 2024-01-23T09:09:41.639060Z INFO cryptohome-namespace-mounter[4157]: Created vault subdirectory: /home/.shadow/e59a8412168a29766df1f09113348366517cedd7/mount/root/.cache 2024-01-23T09:09:41.639121Z INFO cryptohome-namespace-mounter[4157]: Setting tracking xattr 2024-01-23T09:09:41.639532Z INFO cryptohome-namespace-mounter[4157]: Finished setting up dircrypto mount 2024-01-23T09:09:41.639625Z INFO cryptohome-namespace-mounter[4157]: Checking if there has been a successful mount in the past 2024-01-23T09:09:41.651053Z INFO kernel: [ 93.395839] fscrypt: AES-256-XTS using implementation "xts-aes-ce" 2024-01-23T09:09:41.651540Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 1000 2024-01-23T09:09:41.660283Z ERR cryptohome-namespace-mounter[4157]: message repeated 3 times: [ Owner uid is 0 instead of 1000] 2024-01-23T09:09:41.662126Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 307 2024-01-23T09:09:41.662621Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 216 2024-01-23T09:09:41.662997Z ERR cryptohome-namespace-mounter[4157]: Owner gid is 0 instead of 1001 2024-01-23T09:09:41.663512Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 20124 2024-01-23T09:09:41.663942Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 20173 2024-01-23T09:09:41.664278Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 223 2024-01-23T09:09:41.664550Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 20137 2024-01-23T09:09:41.664833Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 297 2024-01-23T09:09:41.665163Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 20131 2024-01-23T09:09:41.665668Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 20128 2024-01-23T09:09:41.666263Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 299 2024-01-23T09:09:41.666793Z ERR cryptohome-namespace-mounter[4157]: Owner gid is 0 instead of 1001 2024-01-23T09:09:41.667366Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 20104 2024-01-23T09:09:41.667969Z ERR cryptohome-namespace-mounter[4157]: Owner gid is 0 instead of 1001 2024-01-23T09:09:41.668744Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 291 2024-01-23T09:09:41.669468Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 307 2024-01-23T09:09:41.670027Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 216 2024-01-23T09:09:41.670620Z ERR cryptohome-namespace-mounter[4157]: Owner gid is 0 instead of 1001 2024-01-23T09:09:41.671168Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 20124 2024-01-23T09:09:41.671738Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 20173 2024-01-23T09:09:41.672221Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 223 2024-01-23T09:09:41.672747Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 20137 2024-01-23T09:09:41.673174Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 297 2024-01-23T09:09:41.673625Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 20131 2024-01-23T09:09:41.674003Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 20128 2024-01-23T09:09:41.674444Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 299 2024-01-23T09:09:41.674965Z ERR cryptohome-namespace-mounter[4157]: Owner gid is 0 instead of 1001 2024-01-23T09:09:41.675386Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 20104 2024-01-23T09:09:41.675894Z ERR cryptohome-namespace-mounter[4157]: Owner gid is 0 instead of 1001 2024-01-23T09:09:41.676685Z ERR cryptohome-namespace-mounter[4157]: Owner uid is 0 instead of 291 2024-01-23T09:09:41.677273Z INFO cryptohomed[1167]: OOP mount helper started successfully 2024-01-23T09:09:41.677625Z INFO cryptohomed[1167]: Restoring SELinux contexts for: /home/.shadow/e59a8412168a29766df1f09113348366517cedd7, recursive=true 2024-01-23T09:09:41.776190Z INFO cryptohomed[1167]: Mount succeeded. 2024-01-23T09:09:41.806061Z INFO chapsd[981]: Opening database in: /run/daemon-store/chaps/e59a8412168a29766df1f09113348366517cedd7 2024-01-23T09:09:41.806366Z INFO chapsd[981]: leveldb /run/daemon-store/chaps/e59a8412168a29766df1f09113348366517cedd7/database directory did not exist. 2024-01-23T09:09:41.823149Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=28, Message=Timeout was reached 2024-01-23T09:09:41.911821Z INFO chapsd[981]: leveldb.stats:#012 Compactions#012Level Files Size(MB) Time(sec) Read(MB) Write(MB)#012--------------------------------------------------#012 2024-01-23T09:09:41.911859Z INFO chapsd[981]: leveldb contains 0 keys. 2024-01-23T09:09:41.943196Z INFO chapsd[981]: Initializing key hierarchy for token at /run/daemon-store/chaps/e59a8412168a29766df1f09113348366517cedd7 2024-01-23T09:09:41.943289Z INFO chapsd[981]: Slot 1 ready for token at /run/daemon-store/chaps/e59a8412168a29766df1f09113348366517cedd7 2024-01-23T09:09:41.943824Z INFO chapsd[981]: Created lock file: /run/lock/power_override/chapsd_token_init_slot_1.lock 2024-01-23T09:09:41.944901Z INFO cryptohomed[1167]: PKCS#11 initialization succeeded. 2024-01-23T09:09:41.959986Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=60, Message=SSL peer certificate or SSH remote key was not OK 2024-01-23T09:09:41.968369Z INFO chapsd[981]: Root key is ready for token at /run/daemon-store/chaps/e59a8412168a29766df1f09113348366517cedd7 2024-01-23T09:09:41.968860Z INFO chapsd[981]: Deleted lock file: /run/lock/power_override/chapsd_token_init_slot_1.lock 2024-01-23T09:09:42.128188Z INFO cryptohomed[1167]: AuthSession: added auth factor gaia into USS. 2024-01-23T09:09:42.147452Z INFO session_manager[1046]: INFO session_manager: [policy_key.cc(49)] No policy key on disk at /run/daemon-store/session_manager/e59a8412168a29766df1f09113348366517cedd7/policy/key 2024-01-23T09:09:42.154350Z INFO session_manager[1046]: INFO session_manager: [upstart_signal_emitter.cc(51)] Emitting start-user-session Upstart signal 2024-01-23T09:09:42.157192Z INFO session_manager[1046]: INFO session_manager: [session_manager_impl.cc(773)] Starting user session 2024-01-23T09:09:42.157656Z INFO session_manager[1046]: INFO session_manager: [session_manager_impl.cc(785)] Emitting D-Bus signal SessionStateChanged: started 2024-01-23T09:09:42.159015Z ERR debugd[2933]: WriteToFile(...): Domain=dbus, Code=org.chromium.debugd.error.DRMTrace, Message=Failed to open /sys/module/drm/parameters/trace, error: 4 2024-01-23T09:09:42.159057Z WARNING debugd[2933]: Failed to reset categories; drm_trace may have unexpected log entries. 2024-01-23T09:09:42.159212Z ERR debugd[2933]: WriteToFile(...): Domain=dbus, Code=org.chromium.debugd.error.DRMTrace, Message=Failed to open /sys/kernel/tracing/instances/drm/buffer_size_kb, error: 4 2024-01-23T09:09:42.159231Z WARNING debugd[2933]: Failed to reset trace buffer size; drm_trace may be larger than expected. 2024-01-23T09:09:42.161969Z ERR session_manager[1046]: ERROR session_manager: [safe_fd.cc(704)] unlinkat failed for "key.pub": No such file or directory (2) 2024-01-23T09:09:42.173404Z INFO session_manager[1046]: INFO session_manager: [key_generator.cc(60)] Generating key at /home/user/e59a8412168a29766df1f09113348366517cedd7/key.pub using nssdb under /home/user/e59a8412168a29766df1f09113348366517cedd7 2024-01-23T09:09:42.174402Z INFO debugd[2933]: Deleting the ARC bug report backup 2024-01-23T09:09:42.250355Z INFO root[4208]: fydeos-license-utils get disk /dev/mmcblk0 serial by lsblk 2024-01-23T09:09:42.253041Z INFO root[4210]: fydeos-license-utils get fix id by disk serial 2024-01-23T09:09:42.334779Z INFO crosdns[4205]: Starting CrOS DNS daemon 2024-01-23T09:09:42.407960Z INFO bluetoothd[862]: Applied debug levels: bluez(0), kernel(0) 2024-01-23T09:09:42.408285Z INFO bluetoothd[862]: Set quality report adapter 0 enable 0 2024-01-23T09:09:42.408306Z INFO bluetoothd[862]: SetQuality action 0 succeeded 2024-01-23T09:09:42.531405Z INFO dbus-daemon[685]: [system] Activating service name='org.chromium.SmbProvider' requested by ':1.48' (uid=1000 pid=1153 comm="/opt/google/chrome/chrome --enable-native-gpu-memo" label="u:r:cros_browser:s0") (using servicehelper) 2024-01-23T09:09:42.560976Z WARNING cros-disks[4248]: libminijail[1]: ioctl(SIOCSIFFLAGS) failed: Operation not permitted 2024-01-23T09:09:42.576774Z INFO usb_bouncer[4254]: Reporting metrics for /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb1 2024-01-23T09:09:42.577634Z INFO usb_bouncer[4254]: Reporting metrics for /devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb2 2024-01-23T09:09:42.578438Z INFO usb_bouncer[4254]: Reporting metrics for /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2 2024-01-23T09:09:42.579327Z INFO usb_bouncer[4254]: Reporting metrics for /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb4 2024-01-23T09:09:42.579929Z INFO usb_bouncer[4254]: Reporting metrics for /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3 2024-01-23T09:09:42.619698Z INFO fusebox[4256]: fusebox /dev/fd/11 [2] 2024-01-23T09:09:42.619741Z INFO fusebox[4256]: fusebox fuse_daemonizing 2024-01-23T09:09:42.650258Z INFO vmlog_forwarder[4217]: Connecting to dbus signal VmStartingUpSignal 2024-01-23T09:09:42.651192Z INFO vmlog_forwarder[4217]: Connecting to dbus signal VmStoppedSignal 2024-01-23T09:09:42.651549Z INFO vmlog_forwarder[4217]: Started RotateLogs timer 2024-01-23T09:09:42.659057Z INFO vmlog_forwarder[4217]: VM log forwarder listening on port 9999 2024-01-23T09:09:42.700479Z INFO vm_cicerone[4240]: Server listening on vsock:4294967295:8889, unix:///run/vm_cicerone/client/host.sock 2024-01-23T09:09:42.701386Z INFO vm_cicerone[4240]: Server listening on vsock:4294967295:7778 2024-01-23T09:09:42.702096Z INFO vm_cicerone[4240]: Server listening on vsock:4294967295:7779 2024-01-23T09:09:42.702235Z INFO vm_cicerone[4240]: Started tremplin grpc server 2024-01-23T09:09:42.717609Z INFO vm_concierge[4233]: Finished setting up signal handlers 2024-01-23T09:09:42.721987Z INFO vm_concierge[4233]: Finished setting up traces with 1 sources. 2024-01-23T09:09:42.757932Z INFO vm_concierge[4233]: Tbw history file is created at: /var/lib/vm_concierge/vmm_swap_policy/tbw_history2 2024-01-23T09:09:43.017634Z INFO smbproviderd[4257]: NTLM protocol is disabled 2024-01-23T09:09:43.018739Z INFO dbus-daemon[685]: [system] Successfully activated service 'org.chromium.SmbProvider' 2024-01-23T09:09:43.026806Z NOTICE never-suspend[4289]: setup power manager policy to block suspend 2024-01-23T09:09:44.117186Z INFO kernel: [ 95.862129] perf: interrupt took too long (2525 > 2500), lowering kernel.perf_event_max_sample_rate to 79000 2024-01-23T09:09:44.182741Z INFO kernel: [ 95.927707] perf: interrupt took too long (3162 > 3156), lowering kernel.perf_event_max_sample_rate to 63250 2024-01-23T09:09:44.254068Z INFO vm_cicerone[4240]: System timezone changed, updating container timezones 2024-01-23T09:09:44.487979Z INFO session_manager[1046]: INFO session_manager: [session_manager_impl.cc(1763)] Processing generated key at /home/user/e59a8412168a29766df1f09113348366517cedd7/key.pub 2024-01-23T09:09:44.513922Z WARNING featured[1458]: Bus::SendWithReplyAndBlock took 2344ms to process message: type=method_call, path=/org/chromium/ChromeFeaturesService, interface=org.chromium.ChromeFeaturesServiceInterface, member=IsFeatureEnabled 2024-01-23T09:09:44.514009Z ERR featured[1458]: Failed to call method: org.chromium.ChromeFeaturesServiceInterface.IsFeatureEnabled: object_path= /org/chromium/ChromeFeaturesService: org.freedesktop.DBus.Error.InvalidArgs: Chrome can't get state for 'CrOSLateBootSchedUtilHints80'; feature_library will decide 2024-01-23T09:09:44.585977Z WARNING vm_concierge[4233]: Bus::SendWithReplyAndBlock took 1827ms to process message: type=method_call, path=/org/chromium/ChromeFeaturesService, interface=org.chromium.ChromeFeaturesServiceInterface, member=GetFeatureParams 2024-01-23T09:09:44.586358Z INFO vm_concierge[4233]: VmMemoryManagementService feature flag not enabled 2024-01-23T09:09:44.587169Z INFO vm_concierge[4233]: Received request: GetVmMemoryManagementKillsConnection 2024-01-23T09:09:44.587187Z ERR vm_concierge[4233]: Service is not enabled. 2024-01-23T09:09:44.590176Z INFO resourced[3006]: VmMemoryManagementClient not activating: Failed to get VMMS kills connection Service is not enabled. 2024-01-23T09:09:44.591529Z INFO vm_concierge[4233]: Connected to interface name: org.chromium.VmCicerone for signal TremplinStarted 2024-01-23T09:09:44.591587Z INFO vm_concierge[4233]: Connected to interface name: org.chromium.VmPluginDispatcher for signal VmToolsStateChanged 2024-01-23T09:09:44.591838Z INFO vm_concierge[4233]: System timezone changed, updating VM timezones 2024-01-23T09:09:44.592018Z INFO vm_concierge[4233]: Received request: ListVms 2024-01-23T09:09:44.592151Z INFO vm_concierge[4233]: Received request: GetVmInfo 2024-01-23T09:09:44.592175Z ERR vm_concierge[4233]: Requested VM termina does not exist 2024-01-23T09:09:44.643288Z INFO session_manager[1046]: INFO session_manager: [policy_key.cc(118)] wrote 294 bytes to /var/lib/devicesettings/owner.key 2024-01-23T09:09:44.645070Z INFO session_manager[1046]: INFO session_manager: [policy_service.cc(182)] Persisted policy key to disk. 2024-01-23T09:09:44.698742Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:44.713201Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:44.715407Z ERR featured[1458]: Failed to call method: org.chromium.ChromeFeaturesServiceInterface.IsFeatureEnabled: object_path= /org/chromium/ChromeFeaturesService: org.freedesktop.DBus.Error.InvalidArgs: Chrome can't get state for 'CrOSLateBootSchedUtilHints40'; feature_library will decide 2024-01-23T09:09:44.727596Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:44.729842Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:44.730118Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:44.730269Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:44.732663Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:44.734898Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:44.735239Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:44.735618Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:44.766626Z INFO vm_cicerone[4240]: Received ListRunningContainers request 2024-01-23T09:09:44.776361Z INFO vm_concierge[4233]: Received request: GetVmLaunchAllowed 2024-01-23T09:09:44.858804Z INFO session_manager[1046]: INFO session_manager: [policy_store.cc(85)] Persisted policy to disk, path: /var/lib/devicesettings/policy.1 2024-01-23T09:09:44.859447Z INFO session_manager[1046]: INFO session_manager: [policy_service.cc(192)] Policy persisted with no completion, result: org.chromium.SessionManagerInterface.None 2024-01-23T09:09:44.859623Z INFO session_manager[1046]: INFO session_manager: [session_manager_impl.cc(1746)] Policy persisted result: 1 2024-01-23T09:09:44.860282Z INFO session_manager[1046]: INFO session_manager: [session_manager_impl.cc(870)] LoadShillProfile() method called. 2024-01-23T09:09:44.860458Z INFO session_manager[1046]: INFO session_manager: [upstart_signal_emitter.cc(51)] Emitting load-shill-profile Upstart signal 2024-01-23T09:09:44.864552Z INFO u2fd[2988]: INFO u2fd: [u2f_daemon.cc(372)] Requested Mode: Policy[unset], force_u2f[0], force_g2f[0] 2024-01-23T09:09:44.864599Z INFO u2fd[2988]: INFO u2fd: [u2f_daemon.cc(386)] Mode: Disabled (not supported) 2024-01-23T09:09:44.941630Z ERR debugd[2933]: Problem waiting for pid 4292: No child processes (10) 2024-01-23T09:09:44.941673Z ERR debugd[2933]: Unable to send signal to 4292: No such process (3) 2024-01-23T09:09:45.018974Z ERR featured[1458]: Failed to call method: org.chromium.ChromeFeaturesServiceInterface.IsFeatureEnabled: object_path= /org/chromium/ChromeFeaturesService: org.freedesktop.DBus.Error.InvalidArgs: Chrome can't get state for 'CrOSLateBootSchedUtilHints60'; feature_library will decide 2024-01-23T09:09:45.044065Z INFO tpm_managerd[901]: ChangeOwnerPassword: attempting to change old tpm2.0 owner password to a new owner password 2024-01-23T09:09:45.068246Z INFO tpm_managerd[901]: ClearStoredOwnerPasswordTaskReplaceOwnerPasswordResult: kSuccess 2024-01-23T09:09:45.127442Z ERR featured[1458]: Failed to call method: org.chromium.ChromeFeaturesServiceInterface.IsFeatureEnabled: object_path= /org/chromium/ChromeFeaturesService: org.freedesktop.DBus.Error.InvalidArgs: Chrome can't get state for 'CrOSLateBootLruMinTtlMs500'; feature_library will decide 2024-01-23T09:09:45.340663Z ERR featured[1458]: Failed to call method: org.chromium.ChromeFeaturesServiceInterface.IsFeatureEnabled: object_path= /org/chromium/ChromeFeaturesService: org.freedesktop.DBus.Error.InvalidArgs: Chrome can't get state for 'CrOSLateBootLruMinTtlMs100'; feature_library will decide 2024-01-23T09:09:45.444485Z INFO dbus-daemon[685]: [system] Activating service name='org.chromium.ArcKeymaster' requested by ':1.48' (uid=1000 pid=1153 comm="/opt/google/chrome/chrome --enable-native-gpu-memo" label="u:r:cros_browser:s0") (using servicehelper) 2024-01-23T09:09:45.450396Z ERR featured[1458]: Failed to call method: org.chromium.ChromeFeaturesServiceInterface.IsFeatureEnabled: object_path= /org/chromium/ChromeFeaturesService: org.freedesktop.DBus.Error.InvalidArgs: Chrome can't get state for 'CrOSLateBootLruMinTtlMs250'; feature_library will decide 2024-01-23T09:09:45.503487Z ERR featured[1458]: Failed to call method: org.chromium.ChromeFeaturesServiceInterface.IsFeatureEnabled: object_path= /org/chromium/ChromeFeaturesService: org.freedesktop.DBus.Error.InvalidArgs: Chrome can't get state for 'CrOSLateBootCpuidleMenu'; feature_library will decide 2024-01-23T09:09:45.565209Z ERR featured[1458]: Failed to call method: org.chromium.ChromeFeaturesServiceInterface.IsFeatureEnabled: object_path= /org/chromium/ChromeFeaturesService: org.freedesktop.DBus.Error.InvalidArgs: Chrome can't get state for 'CrOSLateBootCpuidleTEO'; feature_library will decide 2024-01-23T09:09:45.646826Z INFO dbus-daemon[685]: [system] Successfully activated service 'org.chromium.ArcKeymaster' 2024-01-23T09:09:45.662595Z ERR featured[1458]: Failed to call method: org.chromium.ChromeFeaturesServiceInterface.IsFeatureEnabled: object_path= /org/chromium/ChromeFeaturesService: org.freedesktop.DBus.Error.InvalidArgs: Chrome can't get state for 'CrOSLateBootSchedTrace'; feature_library will decide 2024-01-23T09:09:45.671105Z ERR kernel: [ 97.418383] ieee80211 phy0: brcmf_cfg80211_scan: Connecting: status (39) 2024-01-23T09:09:45.682897Z ERR kernel: [ 97.427067] ieee80211 phy0: brcmf_cfg80211_scan: Connecting: status (39) 2024-01-23T09:09:45.693135Z INFO root[4453]: fydeos-license-utils get disk /dev/mmcblk0 serial by lsblk 2024-01-23T09:09:45.699707Z INFO root[4454]: fydeos-license-utils get fix id by disk serial 2024-01-23T09:09:45.776146Z INFO session_manager[1046]: INFO session_manager: [policy_store.cc(85)] Persisted policy to disk, path: /var/lib/devicesettings/policy.1 2024-01-23T09:09:45.776749Z INFO session_manager[1046]: INFO session_manager: [session_manager_impl.cc(1746)] Policy persisted result: 1 2024-01-23T09:09:45.779644Z INFO u2fd[2988]: INFO u2fd: [u2f_daemon.cc(372)] Requested Mode: Policy[unset], force_u2f[0], force_g2f[0] 2024-01-23T09:09:45.779673Z INFO u2fd[2988]: INFO u2fd: [u2f_daemon.cc(386)] Mode: Disabled (not supported) 2024-01-23T09:09:46.217112Z INFO session_manager[1046]: INFO session_manager: [policy_store.cc(85)] Persisted policy to disk, path: /var/lib/devicesettings/policy.1 2024-01-23T09:09:46.217603Z INFO session_manager[1046]: INFO session_manager: [session_manager_impl.cc(1746)] Policy persisted result: 1 2024-01-23T09:09:46.220000Z INFO u2fd[2988]: INFO u2fd: [u2f_daemon.cc(372)] Requested Mode: Policy[unset], force_u2f[0], force_g2f[0] 2024-01-23T09:09:46.220020Z INFO u2fd[2988]: INFO u2fd: [u2f_daemon.cc(386)] Mode: Disabled (not supported) 2024-01-23T09:09:47.492987Z INFO cryptohomed[1167]: AuthSession: invalidated. 2024-01-23T09:09:48.021085Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:48.031770Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:48.033436Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:48.033458Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:48.033471Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:48.033502Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:48.035413Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:48.035612Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:48.035659Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:48.035758Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:49.079846Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(939)] Enabling DLC=sr-bt-dlc reserve. 2024-01-23T09:09:49.080269Z WARNING cras_server[1715]: download_supported_dlc: retry downloading `sr-bt-dlc`, attempt #3. 2024-01-23T09:09:49.082743Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(392)] GetDlc(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.INVALID_DLC, Message=Passed unsupported DLC=nc-ap-dlc 2024-01-23T09:09:49.085349Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(392)] GetDlc(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.INVALID_DLC, Message=Passed unsupported DLC=nc-ap-dlc 2024-01-23T09:09:49.085368Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(222)] Failed to install DLC=nc-ap-dlc 2024-01-23T09:09:49.085378Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=nc-ap-dlc 2024-01-23T09:09:49.085501Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:5 2024-01-23T09:09:49.085826Z WARNING cras_server[1715]: cras_dlc_install(nc-ap-dlc) failed: D-Bus failure: Passed unsupported DLC=nc-ap-dlc 2024-01-23T09:09:49.085857Z ERR cras_server[1715]: download_supported_dlc: unable to connect to dlcservice during `cras_dlc_install`. 2024-01-23T09:09:49.085866Z WARNING cras_server[1715]: download_supported_dlc: retry downloading `nc-ap-dlc`, attempt #3. 2024-01-23T09:09:49.221548Z INFO root[4701]: fydeos-license-utils get disk /dev/mmcblk0 serial by lsblk 2024-01-23T09:09:49.224342Z INFO root[4702]: fydeos-license-utils get fix id by disk serial 2024-01-23T09:09:49.856753Z ERR shill[1091]: ERROR shill: [http_request.cc(217)] wlan0 wifi_psk_2 IPv4 attempt=7 HTTP probe: Could not resolve api.fydeos.com: The network connection was timed out 2024-01-23T09:09:51.037535Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:51.048746Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:51.051404Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:51.051690Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:51.051823Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:51.051991Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:51.055372Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:51.055850Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:51.056049Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:51.056309Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:52.373404Z INFO iioservice[784]: INFO iioservice: [iio_sensor.cc(56)] (2) Request(): Received SensorService from Mojo Service Manager 2024-01-23T09:09:52.462286Z NOTICE dbus-daemon[685]: [system] Rejected send message, 1 matched rules; type="method_call", sender=":1.48" (uid=1000 pid=1153 comm="/opt/google/chrome/chrome --enable-native-gpu-memo" label="u:r:cros_browser:s0") interface="com.ubuntu.Upstart0_6.Job" member="Start" error name="(unset)" requested_reply="0" destination="com.ubuntu.Upstart" (uid=0 pid=1 comm="/sbin/init " label="u:r:cros_init:s0") 2024-01-23T09:09:52.606637Z INFO vm_cicerone[4240]: System timezone changed, updating container timezones 2024-01-23T09:09:52.606645Z INFO vm_concierge[4233]: System timezone changed, updating VM timezones 2024-01-23T09:09:52.606885Z INFO vm_concierge[4233]: System timezone changed, updating VM timezones 2024-01-23T09:09:52.619913Z INFO vm_cicerone[4240]: System timezone changed, updating container timezones 2024-01-23T09:09:52.731787Z WARNING vm_concierge[4233]: Startup traces aborted 2024-01-23T09:09:54.058629Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:54.067863Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:54.069325Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:54.069339Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:54.069352Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:54.069376Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:54.071215Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:54.071331Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:54.071353Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:54.071427Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:57.081816Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:09:57.091435Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:09:57.092924Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:09:57.092942Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:57.092955Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:09:57.092979Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:09:57.095026Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:09:57.095185Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:09:57.095216Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:09:57.095288Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:09:59.887556Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=28, Message=Timeout was reached 2024-01-23T09:10:00.097308Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:00.107536Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:00.109205Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:00.109220Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:00.109232Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:00.109255Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:00.111211Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:00.111330Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:00.111353Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:00.111410Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:02.482149Z INFO iioservice[784]: INFO iioservice: [sensor_service_impl.cc(423)] (2) OnSensorServiceDisconnect(): SensorDevice disconnected. ReceiverId: 3 2024-01-23T09:10:03.112709Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:03.122947Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:03.124578Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:03.124594Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:03.124605Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:03.124628Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:03.126464Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:03.126580Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:03.126602Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:03.126657Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:06.127804Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:06.137470Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:06.139081Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:06.139097Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:06.139111Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:06.139134Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:06.141009Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:06.141124Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:06.141148Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:06.141204Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:09.142413Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:09.152513Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:09.154135Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:09.154151Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:09.154162Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:09.154185Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:09.156075Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:09.156189Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:09.156212Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:09.156270Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:09.924417Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=28, Message=Timeout was reached 2024-01-23T09:10:12.158343Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:12.170230Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:12.171906Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:12.171926Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:12.171938Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:12.171962Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:12.173900Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:12.174024Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:12.174061Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:12.174119Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:15.175491Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:15.185601Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:15.187258Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:15.187273Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:15.187284Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:15.187307Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:15.189171Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:15.189283Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:15.189306Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:15.189361Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:18.191150Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:18.200963Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:18.202480Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:18.202494Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:18.202505Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:18.202527Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:18.204447Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:18.204580Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:18.204605Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:18.204663Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:19.939748Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=28, Message=Timeout was reached 2024-01-23T09:10:19.939864Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=28, Message=Timeout was reached 2024-01-23T09:10:21.206585Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:21.216391Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:21.218085Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:21.218104Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:21.218118Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:21.218145Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:21.220347Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:21.220479Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:21.220505Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:21.220568Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:24.222130Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:24.231652Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:24.233259Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:24.233274Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:24.233285Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:24.233308Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:24.235278Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:24.235388Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:24.235410Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:24.235466Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:27.236959Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:27.246485Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:27.248140Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:27.248157Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:27.248168Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:27.248195Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:27.250148Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:27.250263Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:27.250286Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:27.250344Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:28.257362Z ERR shill[1091]: ERROR shill: [http_request.cc(217)] wlan0 wifi_psk_2 IPv4 attempt=11 HTTPS probe: Could not resolve api.fydeos.com: The network connection was timed out 2024-01-23T09:10:30.251770Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:30.261135Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:30.262750Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:30.262765Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:30.262776Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:30.262798Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:30.264796Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:30.264914Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:30.264937Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:30.265010Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:33.266012Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:33.275953Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:33.277625Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:33.277642Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:33.277653Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:33.277677Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:33.279754Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:33.279868Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:33.279901Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:33.279957Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:36.281540Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:36.291293Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:36.292973Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:36.292988Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:36.292999Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:36.293021Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:36.295104Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:36.295220Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:36.295243Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:36.295300Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:38.292661Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=28, Message=Timeout was reached 2024-01-23T09:10:38.292795Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=28, Message=Timeout was reached 2024-01-23T09:10:38.627573Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=60, Message=SSL peer certificate or SSH remote key was not OK 2024-01-23T09:10:38.714127Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=60, Message=SSL peer certificate or SSH remote key was not OK 2024-01-23T09:10:39.297283Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:39.307479Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:39.309184Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:39.309199Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:39.309210Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:39.309232Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:39.311222Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:39.311337Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:39.311361Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:39.311431Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:40.437448Z NOTICE temp_logger[4879]: cpu-thermal:38C 2024-01-23T09:10:42.313479Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:42.323055Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:42.324750Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:42.324766Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:42.324777Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:42.324800Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:42.326770Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:42.326892Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:42.326915Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:42.326971Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:45.328517Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:45.338095Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:45.339750Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:45.339766Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:45.339778Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:45.339800Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:45.341759Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:45.341869Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:45.341892Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:45.341948Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:46.752990Z ERR shill[1091]: ERROR shill: [http_request.cc(217)] wlan0 wifi_psk_2 IPv4 attempt=15 HTTPS probe: Could not resolve api.fydeos.com: The network connection was timed out 2024-01-23T09:10:48.343106Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:48.353074Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:48.354708Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:48.354725Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:48.354736Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:48.354759Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:48.356836Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:48.356966Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:48.356991Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:48.357052Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:48.755648Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=28, Message=Timeout was reached 2024-01-23T09:10:51.357992Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:51.367933Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:51.369657Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:51.369675Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:51.369689Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:51.369715Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:51.371762Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:51.371886Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:51.371910Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:51.371967Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:54.373366Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:54.383210Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:54.385093Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:54.385207Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:54.385270Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:54.385352Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:54.387958Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:54.388106Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:54.388133Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:54.388194Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:57.389348Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(941)] Disabling DLC=fio-dlc reserve. 2024-01-23T09:10:57.399258Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to INSTALLING 2024-01-23T09:10:57.400928Z WARNING dlcservice[1543]: WARNING dlcservice: [dlc_base.cc(352)] Verification failed for image file: /var/cache/dlc/fio-dlc/package/dlc_a/dlc.img. Expected: EC2A1094569C421790E14065254323CF6CE39F1DB991418109D53B2BFD80433E Found: E1FB2DAC2FD6F205F53A6B9C2256735F2FB2DF13CCE25F7A952CCB2949F4F10C 2024-01-23T09:10:57.400944Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(238)] Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:57.400956Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(239)] Install(...): Domain=dbus, Code=org.chromium.DlcServiceInterface.BUSY, Message=Installation already in progress for (sr-bt-dlc), can't install fio-dlc right now. 2024-01-23T09:10:57.400979Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(918)] Changing DLC=fio-dlc state to NOT_INSTALLED 2024-01-23T09:10:57.402961Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/cache/dlc/fio-dlc 2024-01-23T09:10:57.403075Z INFO dlcservice[1543]: INFO dlcservice: [dlc_base.cc(811)] Deleted path=/var/lib/dlcservice/dlc/fio-dlc 2024-01-23T09:10:57.403099Z ERR dlcservice[1543]: ERROR dlcservice: [dlc_service.cc(181)] [CoreServicesAlert] Failed to install DLC=fio-dlc 2024-01-23T09:10:57.403154Z INFO dlcservice[1543]: INFO dlcservice: [metrics.cc(71)] InstallResult metric sent:7 2024-01-23T09:10:58.804108Z ERR shill[1091]: ERROR shill: [http_transport_curl.cc(556)] OnTransferComplete(...): Domain=curl_easy_error, Code=28, Message=Timeout was reached