[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 4.20.0-rc3-next-20181122-00007-g64e3bffc54df-dirty (anders@compilator) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05)) #54 SMP Tue Nov 27 11:08:20 CET 2018 [ 0.000000] Machine model: linux,dummy-virt [ 0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '') [ 0.000000] printk: bootconsole [pl11] enabled [ 0.000000] efi: Getting EFI parameters from FDT: [ 0.000000] efi: UEFI not found. [ 0.000000] cma: Reserved 16 MiB at 0x00000000bf000000 [ 0.000000] NUMA: No NUMA configuration found [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] NUMA: NODE_DATA [mem 0xbefea5c0-0xbefebfff] [ 0.000000] Zone ranges: [ 0.000000] DMA32 [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv0.2 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: Trusted OS migration not required [ 0.000000] random: get_random_bytes called from start_kernel+0xa8/0x498 with crng_init=0 [ 0.000000] percpu: Embedded 490 pages/cpu @(____ptrval____) s1967960 r8192 d30888 u2007040 [ 0.000000] Detected VIPT I-cache on CPU0 [ 0.000000] CPU features: enabling workaround for ARM erratum 843419 [ 0.000000] CPU features: enabling workaround for ARM erratum 845719 [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 516096 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: root=/dev/root rootfstype=9p rootflags=trans=virtio console=ttyAMA0,38400n8 earlycon=pl011,0x9000000 [ 0.000000] Memory: 998084K/2097152K available (15056K kernel code, 3358K rwdata, 4656K rodata, 4096K init, 21766K bss, 85444K reserved, 16384K cma-reserved) [ 0.000000] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 0.000000] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.000000] ftrace: allocating 41424 entries in 162 pages [ 0.000000] Running RCU self tests [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU lockdep checking is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=1. [ 0.000000] rcu: RCU debug extended QS entry/exit. [ 0.000000] Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] GICv2m: range[mem 0x08020000-0x08020fff], SPI[80:143] [ 0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns [ 0.000166] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.013404] Console: colour dummy device 80x25 [ 0.014610] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.015047] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.015397] ... MAX_LOCK_DEPTH: 48 [ 0.015760] ... MAX_LOCKDEP_KEYS: 8191 [ 0.016114] ... CLASSHASH_SIZE: 4096 [ 0.016478] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.017271] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.017626] ... CHAINHASH_SIZE: 32768 [ 0.017998] memory used by lock dependency info: 7775 kB [ 0.018388] per task-struct memory footprint: 2688 bytes [ 0.018793] ------------------------ [ 0.019152] | Locking API testsuite: [ 0.019487] ---------------------------------------------------------------------------- [ 0.019906] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.020328] -------------------------------------------------------------------------- [ 0.021437] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.032568] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.043778] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.056087] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.068286] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.081487] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.094816] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.107751] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.118222] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.128055] -------------------------------------------------------------------------- [ 0.128472] recursive read-lock: | ok | | ok | [ 0.132099] recursive read-lock #2: | ok | | ok | [ 0.135367] mixed read-write-lock: | ok | | ok | [ 0.138964] mixed write-read-lock: | ok | | ok | [ 0.142392] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.145437] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.149139] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.152927] -------------------------------------------------------------------------- [ 0.153676] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.158732] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.163781] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.168733] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.173736] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.178809] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.183794] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.188807] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.194423] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.204516] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.213628] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.220580] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.226449] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.232584] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.238414] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.244127] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.250251] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.256045] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.261758] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.267633] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.273609] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.279340] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.286567] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.293067] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.299183] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.304851] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.310619] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.316753] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.322592] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.328445] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.334619] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.340435] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.346262] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.352358] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.358239] hard-irq lock-inversion/123: ok | ok | ok | [ 0.364145] soft-irq lock-inversion/123: ok | ok | ok | [ 0.370461] hard-irq lock-inversion/132: ok | ok | ok | [ 0.376226] soft-irq lock-inversion/132: ok | ok | ok | [ 0.382009] hard-irq lock-inversion/213: ok | ok | ok | [ 0.388082] soft-irq lock-inversion/213: ok | ok | ok | [ 0.393945] hard-irq lock-inversion/231: ok | ok | ok | [ 0.399635] soft-irq lock-inversion/231: ok | ok | ok | [ 0.405699] hard-irq lock-inversion/312: ok | ok | ok | [ 0.411560] soft-irq lock-inversion/312: ok | ok | ok | [ 0.417420] hard-irq lock-inversion/321: ok | ok | ok | [ 0.423408] soft-irq lock-inversion/321: ok | ok | ok | [ 0.429227] hard-irq read-recursion/123: ok | [ 0.432021] soft-irq read-recursion/123: ok | [ 0.434458] hard-irq read-recursion/132: ok | [ 0.437248] soft-irq read-recursion/132: ok | [ 0.439757] hard-irq read-recursion/213: ok | [ 0.442519] soft-irq read-recursion/213: ok | [ 0.445020] hard-irq read-recursion/231: ok | [ 0.447831] soft-irq read-recursion/231: ok | [ 0.450336] hard-irq read-recursion/312: ok | [ 0.452739] soft-irq read-recursion/312: ok | [ 0.454756] hard-irq read-recursion/321: ok | [ 0.457041] soft-irq read-recursion/321: ok | [ 0.459061] -------------------------------------------------------------------------- [ 0.459469] | Wound/wait tests | [ 0.459790] --------------------- [ 0.460137] ww api failures: ok | ok | ok | [ 0.467038] ww contexts mixing: ok | ok | [ 0.470595] finishing ww context: ok | ok | ok | ok | [ 0.477089] locking mismatches: ok | ok | ok | [ 0.482062] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.499437] spinlock nest unlocked: ok | [ 0.501238] ----------------------------------------------------- [ 0.501599] |block | try |context| [ 0.501982] ----------------------------------------------------- [ 0.502384] context: ok | ok | ok | [ 0.507584] try: ok | ok | ok | [ 0.512442] block: ok | ok | ok | [ 0.517220] spinlock: ok | ok | ok | [ 0.522565] ------------------------------------------------------- [ 0.522952] Good, all 261 testcases passed! | [ 0.523632] --------------------------------- [ 0.528403] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.529088] pid_max: default: 32768 minimum: 301 [ 0.539675] LSM: Security Framework initializing [ 0.540672] Yama: becoming mindful. [ 0.543065] LoadPin: ready to pin (currently enforcing) [ 0.543881] AppArmor: AppArmor disabled by boot time parameter [ 0.568705] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.577843] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.580616] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.581589] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.614860] *** VALIDATE proc *** [ 0.632374] *** VALIDATE cgroup1 *** [ 0.632763] *** VALIDATE cgroup2 *** [ 0.695622] ASID allocator initialised with 32768 entries [ 0.702110] rcu: Hierarchical SRCU implementation. [ 0.765978] EFI services will not be available. [ 0.789093] smp: Bringing up secondary CPUs ... [ 0.789600] smp: Brought up 1 node, 1 CPU [ 0.789997] SMP: Total of 1 processors activated. [ 0.790897] CPU features: detected: 32-bit EL0 Support [ 0.791382] CPU features: detected: CRC32 instructions [ 1.069408] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.070163] CPU: All CPU(s) started at EL1 [ 1.071173] alternatives: patching kernel code [ 1.548215] node 0 initialised, 249310 pages in 4ms [ 1.555588] pgdatinit0 (15) used greatest stack depth: 13408 bytes left [ 1.634560] devtmpfs: initialized [ 1.842163] _warn_unseeded_randomness: 81 callbacks suppressed [ 1.842620] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=0 [ 1.843737] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 1.844505] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 1.857207] Registered cp15_barrier emulation handler [ 2.456288] workqueue: round-robin CPU selection forced, expect performance impact [ 8.182038] DMA-API: preallocated 65536 debug entries [ 8.182902] DMA-API: debugging enabled by kernel config [ 8.183435] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 8.184802] futex hash table entries: 256 (order: 3, 32768 bytes) [ 8.187360] Running postponed tracer tests: [ 8.188407] Testing tracer function: [ 18.769492] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 27.209443] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 27.217621] PASSED [ 27.217981] Testing dynamic ftrace: [ 28.266273] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 28.503862] PASSED [ 28.504451] Testing dynamic ftrace ops #1: [ 32.236708] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 32.249717] (1 0 1 0 0) [ 32.249886] (1 1 2 0 0) [ 49.659169] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 49.664144] (2 1 3 0 1062406) [ 49.664484] (2 2 4 0 1062421) [ 59.934678] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 60.111457] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 60.262778] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 60.270801] PASSED [ 60.271167] Testing dynamic ftrace ops #2: [ 63.978253] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 64.128172] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 80.125602] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 80.127324] (1 0 1 1025079 0) [ 80.127617] (1 1 2 1025094 0) [ 80.221915] (2 1 3 1 3731) [ 80.223329] (2 2 4 118 3848) [ 88.585253] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 88.592316] PASSED [ 88.592676] Testing ftrace recursion: [ 89.629396] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 89.779707] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 89.786305] PASSED [ 89.786658] Testing ftrace recursion safe: [ 90.822884] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 90.973802] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 90.980072] PASSED [ 90.980432] Testing ftrace regs(no arch support): [ 92.018029] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 92.168755] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 92.175458] PASSED [ 92.176042] Testing tracer nop: PASSED [ 92.176991] Testing tracer irqsoff: [ 103.101283] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 111.414445] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 111.421193] PASSED [ 111.422258] Testing tracer function_graph: [ 121.720776] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 130.907101] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 130.914983] PASSED [ 130.925728] _warn_unseeded_randomness: 2 callbacks suppressed [ 130.925981] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 130.927055] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 130.928257] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 130.938609] prandom: seed boundary self test passed [ 130.959301] prandom: 100 self tests passed [ 130.963728] pinctrl core: initialized pinctrl subsystem [ 131.034796] regulator-dummy: Failed to create debugfs directory [ 131.041422] [ 131.041747] ************************************************************* [ 131.042217] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 131.042647] ** ** [ 131.043072] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 131.043449] ** ** [ 131.044328] ** This means that this kernel is built to expose internal ** [ 131.044754] ** IOMMU data structures, which may compromise security on ** [ 131.045174] ** your system. ** [ 131.045594] ** ** [ 131.046014] ** If you see this message and you are not debugging the ** [ 131.046419] ** kernel, report this immediately to your vendor! ** [ 131.046875] ** ** [ 131.047276] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 131.047682] ************************************************************* [ 131.065690] DMI not present or invalid. [ 131.096586] NET: Registered protocol family 16 [ 131.118163] audit: initializing netlink subsys (disabled) [ 131.216336] cpuidle: using governor ladder [ 131.217091] cpuidle: using governor menu [ 131.229182] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 131.229997] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 131.233804] audit: type=2000 audit(109.844:1): state=initialized audit_enabled=0 res=1 [ 131.295363] DMA: preallocated 256 KiB pool for atomic allocations [ 131.347409] Serial: AMBA PL011 UART driver [ 131.936732] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 131.939659] printk: console [ttyAMA0] enabled [ 131.939659] printk: console [ttyAMA0] enabled [ 131.940565] printk: bootconsole [pl11] disabled [ 131.940565] printk: bootconsole [pl11] disabled [ 132.023400] _warn_unseeded_randomness: 39 callbacks suppressed [ 132.023584] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 132.025313] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 132.439363] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 132.474524] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 132.613030] arm-v7s io-pgtable: self test ok [ 132.613720] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 132.619992] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 132.623608] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 132.627142] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 132.631555] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 132.635365] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 132.639314] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 132.645309] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 132.651516] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 132.658265] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 132.664651] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 132.671444] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 132.679336] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 132.695605] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 132.712208] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 132.729384] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 132.748975] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 132.768907] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 132.788344] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 132.810825] vgaarb: loaded [ 132.851266] pps_core: LinuxPPS API ver. 1 registered [ 132.851768] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 132.854203] PTP clock support registered [ 132.973576] NetLabel: Initializing [ 132.974061] NetLabel: domain hash size = 128 [ 132.975043] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 132.982027] NetLabel: unlabeled traffic allowed by default [ 132.995614] clocksource: Switched to clocksource arch_sys_counter [ 135.752566] _warn_unseeded_randomness: 39 callbacks suppressed [ 135.752767] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 135.753963] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 135.756023] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 135.764853] VFS: Disk quotas dquot_6.6.0 [ 135.768335] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 135.777629] *** VALIDATE hugetlbfs *** [ 136.395423] NET: Registered protocol family 2 [ 136.427610] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 136.431279] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 136.444529] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 136.453293] TCP: Hash tables configured (established 16384 bind 16384) [ 136.459702] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 136.463644] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 136.473471] NET: Registered protocol family 1 [ 136.540790] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 136.546956] kvm [1]: HYP mode not available [ 136.572223] kworker/u2:1 (34) used greatest stack depth: 11920 bytes left [ 136.743502] Initialise system trusted keyrings [ 136.754075] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 138.120591] _warn_unseeded_randomness: 58 callbacks suppressed [ 138.120782] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 138.121951] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 138.137440] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 138.283988] 9p: Installing v9fs 9p2000 file system support [ 138.956128] Key type asymmetric registered [ 138.957229] Asymmetric key parser 'x509' registered [ 138.961162] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 139.348954] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 139.354332] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 139.372863] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 139.417550] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 139.466032] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 139.468449] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 139.470416] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 139.471972] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 139.475513] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 139.488089] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 139.489161] pci_bus 0000:00: root bus resource [bus 00-0f] [ 139.489962] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 139.491201] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 139.491919] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 139.568391] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 139.569502] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 139.571104] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 139.572121] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 139.573046] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 139.573763] pci 0000:00:03.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 139.574490] pci 0000:00:03.0: BAR 0: assigned [io 0x1000-0x103f] [ 139.575676] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 139.576367] pci 0000:00:02.0: BAR 0: assigned [io 0x1060-0x107f] [ 140.003146] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 140.054023] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 140.099766] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 143.905232] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 144.210370] SuperH (H)SCI(F) driver initialized [ 223.648296] _warn_unseeded_randomness: 38 callbacks suppressed [ 223.648500] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 223.710000] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 223.771852] libphy: Fixed MDIO Bus: probed [ 224.176627] ledtrig-cpu: registered to indicate activity on CPUs [ 224.203437] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 224.204327] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 224.240963] ashmem: initialized [ 224.395187] 9pnet: Installing 9P2000 support [ 224.637542] registered taskstats version 1 [ 224.638110] Running tests on trace events: [ 224.639162] Testing event initcall_finish: OK [ 224.665442] Testing event initcall_start: OK [ 224.689493] Testing event initcall_level: OK [ 224.717175] Testing event sys_exit: OK [ 224.748743] Testing event sys_enter: OK [ 224.773310] Testing event ipi_exit: OK [ 224.797155] Testing event ipi_entry: OK [ 224.821229] Testing event ipi_raise: OK [ 224.847332] Testing event instruction_emulation: OK [ 224.872535] Testing event kvm_halt_poll_ns: OK [ 224.898458] Testing event kvm_age_page: OK [ 224.923670] Testing event kvm_fpu: OK [ 224.951700] Testing event kvm_mmio: OK [ 224.977651] Testing event kvm_ack_irq: OK [ 225.003823] Testing event kvm_set_irq: OK [ 225.029994] Testing event kvm_vcpu_wakeup: OK [ 225.057404] Testing event kvm_userspace_exit: OK [ 225.081776] Testing event kvm_timer_update_irq: OK [ 225.107792] Testing event kvm_toggle_cache: OK [ 225.133410] Testing event kvm_set_way_flush: OK [ 225.159916] Testing event kvm_test_age_hva: OK [ 225.185666] Testing event kvm_age_hva: OK [ 225.211917] Testing event kvm_set_spte_hva: OK [ 225.237566] Testing event kvm_unmap_hva_range: OK [ 225.263833] Testing event kvm_mmio_emulate: OK [ 225.285126] Testing event kvm_irq_line: OK [ 225.311580] Testing event kvm_access_fault: OK [ 225.337597] Testing event kvm_guest_fault: OK [ 225.364694] Testing event kvm_exit: OK [ 225.392492] Testing event kvm_entry: OK [ 225.417318] Testing event kvm_set_guest_debug: OK [ 225.444043] Testing event kvm_handle_sys_reg: OK [ 225.469753] Testing event trap_reg: OK [ 225.497954] Testing event kvm_arm_set_regset: OK [ 225.523715] Testing event kvm_arm_set_dreg32: OK [ 225.550424] Testing event kvm_arm_clear_debug: OK [ 225.577475] Testing event kvm_arm_setup_debug: OK [ 225.604332] Testing event kvm_hvc_arm64: OK [ 225.629756] Testing event kvm_wfx_arm64: OK [ 225.656542] Testing event vgic_update_irq_pending: OK [ 225.681555] Testing event task_rename: OK [ 225.707970] Testing event task_newtask: OK [ 225.733650] Testing event cpuhp_exit: OK [ 225.762138] Testing event cpuhp_multi_enter: OK [ 225.788075] Testing event cpuhp_enter: OK [ 225.813862] Testing event softirq_raise: OK [ 225.840435] Testing event softirq_exit: OK [ 225.865580] Testing event softirq_entry: OK [ 225.892155] Testing event irq_handler_exit: OK [ 225.917838] Testing event irq_handler_entry: OK [ 225.944170] Testing event signal_deliver: OK [ 225.969679] Testing event signal_generate: OK [ 225.996105] Testing event workqueue_execute_end: OK [ 226.023830] Testing event workqueue_execute_start: OK [ 226.049250] Testing event workqueue_activate_work: OK [ 226.076053] Testing event workqueue_queue_work: OK [ 226.102373] Testing event sched_wake_idle_without_ipi: OK [ 226.130121] Testing event sched_swap_numa: OK [ 226.156411] Testing event sched_stick_numa: OK [ 226.181755] Testing event sched_move_numa: OK [ 226.208054] Testing event sched_process_hang: OK [ 226.235832] Testing event sched_pi_setprio: OK [ 226.261226] Testing event sched_stat_runtime: [ 226.263435] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 226.292404] OK [ 226.292902] Testing event sched_stat_blocked: OK [ 226.317898] Testing event sched_stat_iowait: OK [ 226.344507] Testing event sched_stat_sleep: OK [ 226.369598] Testing event sched_stat_wait: OK [ 226.396263] Testing event sched_process_exec: OK [ 226.421606] Testing event sched_process_fork: OK [ 226.447937] Testing event sched_process_wait: OK [ 226.473322] Testing event sched_wait_task: OK [ 226.497700] Testing event sched_process_exit: OK [ 226.522450] Testing event sched_process_free: OK [ 226.547414] Testing event sched_migrate_task: OK [ 226.572382] Testing event sched_switch: OK [ 226.599729] Testing event sched_wakeup_new: OK [ 226.625100] Testing event sched_wakeup: OK [ 226.651443] Testing event sched_waking: OK [ 226.677353] Testing event sched_kthread_stop_ret: OK [ 226.704695] Testing event sched_kthread_stop: OK [ 226.729688] Testing event lock_acquired: OK [ 226.761107] Testing event lock_contended: OK [ 226.785757] Testing event lock_release: OK [ 226.823669] Testing event lock_acquire: OK [ 226.858353] Testing event console: OK [ 226.884569] Testing event rcu_barrier: OK [ 226.909488] Testing event rcu_torture_read: OK [ 226.936248] Testing event rcu_batch_end: OK [ 226.962397] Testing event rcu_invoke_kfree_callback: OK [ 226.989080] Testing event rcu_invoke_callback: OK [ 227.013210] Testing event rcu_batch_start: OK [ 227.037674] Testing event rcu_kfree_callback: OK [ 227.063752] Testing event rcu_callback: OK [ 227.089616] Testing event rcu_dyntick: OK [ 227.120700] Testing event rcu_fqs: OK [ 227.145369] Testing event rcu_quiescent_state_report: OK [ 227.171863] Testing event rcu_unlock_preempted_task: OK [ 227.197836] Testing event rcu_preempt_task: OK [ 227.224219] Testing event rcu_exp_funnel_lock: OK [ 227.251669] Testing event rcu_exp_grace_period: OK [ 227.278101] Testing event rcu_grace_period_init: OK [ 227.304327] Testing event rcu_future_grace_period: OK [ 227.329721] Testing event rcu_grace_period: OK [ 227.362015] Testing event rcu_utilization: OK [ 227.389867] Testing event swiotlb_bounced: OK [ 227.415941] Testing event tick_stop: OK [ 227.441798] Testing event itimer_expire: OK [ 227.468361] Testing event itimer_state: OK [ 227.493961] Testing event hrtimer_cancel: OK [ 227.521839] Testing event hrtimer_expire_exit: OK [ 227.547890] Testing event hrtimer_expire_entry: OK [ 227.573480] Testing event hrtimer_start: OK [ 227.599967] Testing event hrtimer_init: OK [ 227.625022] Testing event timer_cancel: OK [ 227.652203] Testing event timer_expire_exit: OK [ 227.678001] Testing event timer_expire_entry: OK [ 227.705960] Testing event timer_start: OK [ 227.733972] Testing event timer_init: OK [ 227.759811] Testing event alarmtimer_cancel: OK [ 227.785551] Testing event alarmtimer_start: OK [ 227.811818] Testing event alarmtimer_fired: OK [ 227.837554] Testing event alarmtimer_suspend: OK [ 227.861180] Testing event module_request: OK [ 227.887689] Testing event module_put: OK [ 227.912686] Testing event module_get: OK [ 227.940368] Testing event module_free: OK [ 227.965500] Testing event module_load: OK [ 227.992230] Testing event cgroup_transfer_tasks: OK [ 228.018356] Testing event cgroup_attach_task: OK [ 228.045231] Testing event cgroup_rename: OK [ 228.069306] Testing event cgroup_release: OK [ 228.095990] Testing event cgroup_rmdir: OK [ 228.121817] Testing event cgroup_mkdir: OK [ 228.148502] Testing event cgroup_remount: OK [ 228.173481] Testing event cgroup_destroy_root: OK [ 228.200246] Testing event cgroup_setup_root: OK [ 228.226237] Testing event irq_enable: OK [ 228.264225] Testing event irq_disable: OK [ 228.297129] Testing event ftrace_test_filter: OK [ 228.321292] Testing event dev_pm_qos_remove_request: OK [ 228.345513] Testing event dev_pm_qos_update_request: OK [ 228.372328] Testing event dev_pm_qos_add_request: OK [ 228.397585] Testing event pm_qos_update_flags: OK [ 228.424056] Testing event pm_qos_update_target: OK [ 228.451637] Testing event pm_qos_update_request_timeout: OK [ 228.477075] Testing event pm_qos_remove_request: OK [ 228.501232] Testing event pm_qos_update_request: OK [ 228.525298] Testing event pm_qos_add_request: OK [ 228.551948] Testing event power_domain_target: OK [ 228.577632] Testing event clock_set_rate: OK [ 228.603915] Testing event clock_disable: OK [ 228.629268] Testing event clock_enable: OK [ 228.653294] Testing event wakeup_source_deactivate: OK [ 228.677402] Testing event wakeup_source_activate: OK [ 228.703718] Testing event suspend_resume: OK [ 228.743450] Testing event device_pm_callback_end: OK [ 228.769827] Testing event device_pm_callback_start: OK [ 228.796864] Testing event cpu_frequency_limits: OK [ 228.821758] Testing event cpu_frequency: OK [ 228.848650] Testing event pstate_sample: OK [ 228.873428] Testing event powernv_throttle: OK [ 228.900153] Testing event cpu_idle: OK [ 228.928540] Testing event rpm_return_int: OK [ 228.953555] Testing event rpm_idle: OK [ 228.980463] Testing event rpm_resume: OK [ 229.008283] Testing event rpm_suspend: OK [ 229.033538] Testing event benchmark_event: OK [ 229.161234] Testing event xdp_devmap_xmit: OK [ 229.189408] Testing event xdp_cpumap_enqueue: OK [ 229.213555] Testing event xdp_cpumap_kthread: OK [ 229.237292] Testing event xdp_redirect_map_err: OK [ 229.263676] Testing event xdp_redirect_map: OK [ 229.289741] Testing event xdp_redirect_err: OK [ 229.318071] Testing event xdp_redirect: OK [ 229.345260] Testing event xdp_exception: OK [ 229.372501] Testing event rseq_ip_fixup: OK [ 229.397672] Testing event rseq_update: OK [ 229.424084] Testing event file_check_and_advance_wb_err: OK [ 229.449703] Testing event filemap_set_wb_err: OK [ 229.476092] Testing event mm_filemap_add_to_page_cache: OK [ 229.501690] Testing event mm_filemap_delete_from_page_cache: OK [ 229.528164] Testing event compact_retry: OK [ 229.553759] Testing event skip_task_reaping: OK [ 229.580669] Testing event finish_task_reaping: OK [ 229.605580] Testing event start_task_reaping: OK [ 229.632109] Testing event wake_reaper: OK [ 229.659588] Testing event mark_victim: OK [ 229.685052] Testing event reclaim_retry_zone: OK [ 229.709257] Testing event oom_score_adj_update: OK [ 229.735356] Testing event mm_lru_activate: OK [ 229.760304] Testing event mm_lru_insertion: OK [ 229.785480] Testing event mm_vmscan_inactive_list_is_low: OK [ 229.811976] Testing event mm_vmscan_lru_shrink_active: OK [ 229.837772] Testing event mm_vmscan_lru_shrink_inactive: OK [ 229.864466] Testing event mm_vmscan_writepage: OK [ 229.891648] Testing event mm_vmscan_lru_isolate: OK [ 229.917025] Testing event mm_shrink_slab_end: OK [ 229.941324] Testing event mm_shrink_slab_start: OK [ 229.967899] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 229.993974] Testing event mm_vmscan_memcg_reclaim_end: OK [ 230.020451] Testing event mm_vmscan_direct_reclaim_end: OK [ 230.045957] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 230.072151] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 230.100193] Testing event mm_vmscan_direct_reclaim_begin: OK [ 230.125379] Testing event mm_vmscan_wakeup_kswapd: OK [ 230.151932] Testing event mm_vmscan_kswapd_wake: OK [ 230.177788] Testing event mm_vmscan_kswapd_sleep: OK [ 230.204446] Testing event percpu_destroy_chunk: OK [ 230.231624] Testing event percpu_create_chunk: OK [ 230.257255] Testing event percpu_alloc_percpu_fail: OK [ 230.283764] Testing event percpu_free_percpu: OK [ 230.309758] Testing event percpu_alloc_percpu: OK [ 230.340685] Testing event mm_page_alloc_extfrag: OK [ 230.365411] Testing event mm_page_pcpu_drain: OK [ 230.391956] Testing event mm_page_alloc_zone_locked: OK [ 230.418089] Testing event mm_page_alloc: OK [ 230.444413] Testing event mm_page_free_batched: OK [ 230.469637] Testing event mm_page_free: OK [ 230.496344] Testing event kmem_cache_free: OK [ 230.522413] Testing event kfree: OK [ 230.549180] Testing event kmem_cache_alloc_node: OK [ 230.573314] Testing event kmalloc_node: OK [ 230.600482] Testing event kmem_cache_alloc: OK [ 230.628141] Testing event kmalloc: OK [ 230.656489] Testing event mm_compaction_kcompactd_wake: OK [ 230.681588] Testing event mm_compaction_wakeup_kcompactd: OK [ 230.708126] Testing event mm_compaction_kcompactd_sleep: OK [ 230.733749] Testing event mm_compaction_defer_reset: OK [ 230.759987] Testing event mm_compaction_defer_compaction: OK [ 230.785402] Testing event mm_compaction_deferred: OK [ 230.809794] Testing event mm_compaction_suitable: OK [ 230.835993] Testing event mm_compaction_finished: OK [ 230.861066] Testing event mm_compaction_try_to_compact_pages: OK [ 230.885276] Testing event mm_compaction_end: OK [ 230.911931] Testing event mm_compaction_begin: OK [ 230.937877] Testing event mm_compaction_migratepages: OK [ 230.964434] Testing event mm_compaction_isolate_freepages: OK [ 230.989863] Testing event mm_compaction_isolate_migratepages: OK [ 231.016124] Testing event mm_migrate_pages: OK [ 231.042060] Testing event mm_collapse_huge_page_swapin: OK [ 231.069951] Testing event mm_collapse_huge_page_isolate: OK [ 231.096170] Testing event mm_collapse_huge_page: OK [ 231.121542] Testing event mm_khugepaged_scan_pmd: OK [ 231.147901] Testing event test_pages_isolated: OK [ 231.173869] Testing event cma_release: OK [ 231.200431] Testing event cma_alloc: OK [ 231.225874] Testing event page_ref_unfreeze: OK [ 231.255935] Testing event page_ref_freeze: OK [ 231.283569] Testing event page_ref_mod_unless: OK [ 231.315345] Testing event page_ref_mod_and_return: OK [ 231.339938] Testing event page_ref_mod_and_test: OK [ 231.471773] Testing event page_ref_mod: OK [ 231.532749] Testing event page_ref_set: OK [ 231.565111] Testing event sb_clear_inode_writeback: OK [ 231.592121] Testing event sb_mark_inode_writeback: OK [ 231.617688] Testing event writeback_dirty_inode_enqueue: OK [ 231.644122] Testing event writeback_lazytime_iput: OK [ 231.669692] Testing event writeback_lazytime: OK [ 231.696089] Testing event writeback_single_inode: OK [ 231.721753] Testing event writeback_single_inode_start: OK [ 231.748002] Testing event writeback_wait_iff_congested: OK [ 231.773433] Testing event writeback_congestion_wait: OK [ 231.797351] Testing event writeback_sb_inodes_requeue: OK [ 231.821684] Testing event balance_dirty_pages: OK [ 231.849951] Testing event bdi_dirty_ratelimit: OK [ 231.875995] Testing event global_dirty_state: OK [ 231.901038] Testing event writeback_queue_io: OK [ 231.925233] Testing event wbc_writepage: OK [ 231.949254] Testing event writeback_bdi_register: OK [ 231.973283] Testing event writeback_wake_background: OK [ 231.999923] Testing event writeback_pages_written: OK [ 232.025973] Testing event writeback_wait: OK [ 232.052444] Testing event writeback_written: OK [ 232.077615] Testing event writeback_start: OK [ 232.104090] Testing event writeback_exec: OK [ 232.129668] Testing event writeback_queue: OK [ 232.156088] Testing event writeback_write_inode: OK [ 232.183643] Testing event writeback_write_inode_start: OK [ 232.209046] Testing event writeback_dirty_inode: OK [ 232.233352] Testing event writeback_dirty_inode_start: OK [ 232.260386] Testing event writeback_mark_inode_dirty: OK [ 232.285706] Testing event writeback_dirty_page: OK [ 232.312094] Testing event dax_writeback_one: OK [ 232.337691] Testing event dax_writeback_range_done: OK [ 232.364026] Testing event dax_writeback_range: OK [ 232.389876] Testing event dax_insert_mapping: OK [ 232.415981] Testing event dax_insert_pfn_mkwrite: OK [ 232.441407] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 232.465845] Testing event dax_load_hole: OK [ 232.491447] Testing event dax_pte_fault_done: OK [ 232.516477] Testing event dax_pte_fault: OK [ 232.541716] Testing event dax_pmd_insert_mapping: OK [ 232.567849] Testing event dax_pmd_load_hole_fallback: OK [ 232.593745] Testing event dax_pmd_load_hole: OK [ 232.620390] Testing event dax_pmd_fault_done: OK [ 232.646430] Testing event dax_pmd_fault: OK [ 232.672989] Testing event generic_add_lease: OK [ 232.697327] Testing event time_out_leases: OK [ 232.723480] Testing event generic_delete_lease: OK [ 232.748676] Testing event break_lease_unblock: OK [ 232.776129] Testing event break_lease_block: OK [ 232.801628] Testing event break_lease_noblock: OK [ 232.828092] Testing event flock_lock_inode: OK [ 232.853641] Testing event locks_remove_posix: OK [ 232.880076] Testing event fcntl_setlk: OK [ 232.905712] Testing event posix_lock_inode: OK [ 232.931975] Testing event locks_get_lock_context: OK [ 232.957390] Testing event block_rq_remap: OK [ 232.981246] Testing event block_bio_remap: OK [ 233.005309] Testing event block_split: OK [ 233.031670] Testing event block_unplug: OK [ 233.057652] Testing event block_plug: OK [ 233.084852] Testing event block_sleeprq: OK [ 233.109426] Testing event block_getrq: OK [ 233.136323] Testing event block_bio_queue: OK [ 233.161557] Testing event block_bio_frontmerge: OK [ 233.188319] Testing event block_bio_backmerge: OK [ 233.213580] Testing event block_bio_complete: OK [ 233.240100] Testing event block_bio_bounce: OK [ 233.265398] Testing event block_rq_issue: OK [ 233.292304] Testing event block_rq_insert: OK [ 233.320572] Testing event block_rq_complete: OK [ 233.345459] Testing event block_rq_requeue: OK [ 233.371992] Testing event block_dirty_buffer: OK [ 233.397740] Testing event block_touch_buffer: OK [ 233.424542] Testing event wbt_timer: OK [ 233.449548] Testing event wbt_step: OK [ 233.476057] Testing event wbt_lat: OK [ 233.503745] Testing event wbt_stat: OK [ 233.529183] Testing event gpio_value: OK [ 233.555943] Testing event gpio_direction: OK [ 233.582479] Testing event clk_set_duty_cycle_complete: OK [ 233.610337] Testing event clk_set_duty_cycle: OK [ 233.637153] Testing event clk_set_phase_complete: OK [ 233.661243] Testing event clk_set_phase: OK [ 233.685318] Testing event clk_set_parent_complete: OK [ 233.712072] Testing event clk_set_parent: OK [ 233.737902] Testing event clk_set_rate_complete: OK [ 233.764432] Testing event clk_set_rate: OK [ 233.791625] Testing event clk_unprepare_complete: OK [ 233.817304] Testing event clk_unprepare: OK [ 233.843572] Testing event clk_prepare_complete: OK [ 233.868439] Testing event clk_prepare: OK [ 233.893504] Testing event clk_disable_complete: OK [ 233.920034] Testing event clk_disable: OK [ 233.945899] Testing event clk_enable_complete: OK [ 233.972419] Testing event clk_enable: OK [ 233.999605] Testing event rpmh_send_msg: OK [ 234.025023] Testing event rpmh_tx_done: OK [ 234.049370] Testing event regulator_set_voltage_complete: OK [ 234.075922] Testing event regulator_set_voltage: OK [ 234.102479] Testing event regulator_disable_complete: OK [ 234.129657] Testing event regulator_disable: OK [ 234.156099] Testing event regulator_enable_complete: OK [ 234.183644] Testing event regulator_enable_delay: OK [ 234.209211] Testing event regulator_enable: OK [ 234.235842] Testing event urandom_read: OK [ 234.261922] Testing event random_read: OK [ 234.289667] Testing event extract_entropy_user: OK [ 234.316088] Testing event extract_entropy: OK [ 234.341667] Testing event get_random_bytes_arch: OK [ 234.368065] Testing event get_random_bytes: OK [ 234.393679] Testing event xfer_secondary_pool: OK [ 234.420071] Testing event add_disk_randomness: OK [ 234.445727] Testing event add_input_randomness: OK [ 234.471974] Testing event debit_entropy: OK [ 234.497944] Testing event push_to_pool: OK [ 234.523845] Testing event credit_entropy_bits: OK [ 234.549697] Testing event mix_pool_bytes_nolock: OK [ 234.576008] Testing event mix_pool_bytes: OK [ 234.601464] Testing event add_device_randomness: OK [ 234.628286] Testing event io_page_fault: OK [ 234.653669] Testing event unmap: OK [ 234.680036] Testing event map: OK [ 234.707630] Testing event detach_device_from_domain: OK [ 234.733215] Testing event attach_device_to_domain: OK [ 234.759795] Testing event remove_device_from_group: OK [ 234.785910] Testing event add_device_to_group: OK [ 234.814204] Testing event regcache_drop_region: OK [ 234.841074] Testing event regmap_async_complete_done: OK [ 234.865245] Testing event regmap_async_complete_start: OK [ 234.889281] Testing event regmap_async_io_complete: OK [ 234.915972] Testing event regmap_async_write_start: OK [ 234.941775] Testing event regmap_cache_bypass: OK [ 234.968419] Testing event regmap_cache_only: OK [ 234.993719] Testing event regcache_sync: OK [ 235.020733] Testing event regmap_hw_write_done: OK [ 235.047806] Testing event regmap_hw_write_start: OK [ 235.073347] Testing event regmap_hw_read_done: OK [ 235.099839] Testing event regmap_hw_read_start: OK [ 235.126125] Testing event regmap_reg_read_cache: OK [ 235.153650] Testing event regmap_reg_read: OK [ 235.180075] Testing event regmap_reg_write: OK [ 235.205610] Testing event dma_fence_wait_end: OK [ 235.232142] Testing event dma_fence_wait_start: OK [ 235.257599] Testing event dma_fence_signaled: OK [ 235.284227] Testing event dma_fence_enable_signal: OK [ 235.310071] Testing event dma_fence_destroy: OK [ 235.335958] Testing event dma_fence_init: OK [ 235.361372] Testing event dma_fence_emit: OK [ 235.385311] Testing event sync_timeline: OK [ 235.409476] Testing event spi_transfer_stop: OK [ 235.436287] Testing event spi_transfer_start: OK [ 235.461593] Testing event spi_message_done: OK [ 235.488056] Testing event spi_message_start: OK [ 235.513713] Testing event spi_message_submit: OK [ 235.540145] Testing event spi_controller_busy: OK [ 235.565693] Testing event spi_controller_idle: OK [ 235.592103] Testing event mdio_access: OK [ 235.617812] Testing event rtc_timer_fired: OK [ 235.643976] Testing event rtc_timer_dequeue: OK [ 235.672051] Testing event rtc_timer_enqueue: OK [ 235.697461] Testing event rtc_read_offset: OK [ 235.723968] Testing event rtc_set_offset: OK [ 235.749722] Testing event rtc_alarm_irq_enable: OK [ 235.776556] Testing event rtc_irq_set_state: OK [ 235.801599] Testing event rtc_irq_set_freq: OK [ 235.828077] Testing event rtc_read_alarm: OK [ 235.855758] Testing event rtc_set_alarm: OK [ 235.881097] Testing event rtc_read_time: OK [ 235.905163] Testing event rtc_set_time: OK [ 235.929307] Testing event memory_failure_event: OK [ 235.955885] Testing event aer_event: OK [ 235.981749] Testing event non_standard_event: OK [ 236.008507] Testing event arm_event: OK [ 236.033504] Testing event mc_event: OK [ 236.060138] Testing event binder_return: OK [ 236.086292] Testing event binder_command: OK [ 236.113136] Testing event binder_unmap_kernel_end: OK [ 236.137362] Testing event binder_unmap_kernel_start: OK [ 236.163942] Testing event binder_unmap_user_end: OK [ 236.189779] Testing event binder_unmap_user_start: OK [ 236.216524] Testing event binder_alloc_page_end: OK [ 236.242301] Testing event binder_alloc_page_start: OK [ 236.269158] Testing event binder_free_lru_end: OK [ 236.293268] Testing event binder_free_lru_start: OK [ 236.317286] Testing event binder_alloc_lru_end: OK [ 236.343956] Testing event binder_alloc_lru_start: OK [ 236.369798] Testing event binder_update_page_range: OK [ 236.396450] Testing event binder_transaction_failed_buffer_release: OK [ 236.421633] Testing event binder_transaction_buffer_release: OK [ 236.448123] Testing event binder_transaction_alloc_buf: OK [ 236.473757] Testing event binder_transaction_fd_recv: OK [ 236.500461] Testing event binder_transaction_fd_send: OK [ 236.525652] Testing event binder_transaction_ref_to_ref: OK [ 236.552181] Testing event binder_transaction_ref_to_node: OK [ 236.577733] Testing event binder_transaction_node_to_ref: OK [ 236.604012] Testing event binder_transaction_received: OK [ 236.629440] Testing event binder_transaction: OK [ 236.653485] Testing event binder_wait_for_work: OK [ 236.680304] Testing event binder_read_done: OK [ 236.705572] Testing event binder_write_done: OK [ 236.732085] Testing event binder_ioctl_done: OK [ 236.759621] Testing event binder_unlock: OK [ 236.784989] Testing event binder_locked: OK [ 236.811516] Testing event binder_lock: OK [ 236.836246] Testing event binder_ioctl: OK [ 236.861471] Testing event br_fdb_update: OK [ 236.887866] Testing event fdb_delete: OK [ 236.913604] Testing event br_fdb_external_learn_add: OK [ 236.939938] Testing event br_fdb_add: OK [ 236.965247] Testing event qdisc_dequeue: OK [ 236.989252] Testing event fib_table_lookup: OK [ 237.015710] Testing event tcp_probe: OK [ 237.040325] Testing event tcp_retransmit_synack: OK [ 237.065751] Testing event tcp_rcv_space_adjust: OK [ 237.091993] Testing event tcp_destroy_sock: OK [ 237.117379] Testing event tcp_receive_reset: OK [ 237.141290] Testing event tcp_send_reset: OK [ 237.168082] Testing event tcp_retransmit_skb: OK [ 237.193690] Testing event udp_fail_queue_rcv_skb: OK [ 237.220522] Testing event inet_sock_set_state: OK [ 237.245750] Testing event sock_exceed_buf_limit: OK [ 237.272537] Testing event sock_rcvqueue_full: OK [ 237.298276] Testing event napi_poll: OK [ 237.325477] Testing event netif_rx_ni_entry: OK [ 237.351419] Testing event netif_rx_entry: OK [ 237.376404] Testing event netif_receive_skb_list_entry: OK [ 237.401915] Testing event netif_receive_skb_entry: OK [ 237.428529] Testing event napi_gro_receive_entry: OK [ 237.453617] Testing event napi_gro_frags_entry: OK [ 237.480098] Testing event netif_rx: OK [ 237.507593] Testing event netif_receive_skb: OK [ 237.533171] Testing event net_dev_queue: OK [ 237.559710] Testing event net_dev_xmit: OK [ 237.586137] Testing event net_dev_start_xmit: OK [ 237.613662] Testing event skb_copy_datagram_iovec: OK [ 237.640090] Testing event consume_skb: OK [ 237.667790] Testing event kfree_skb: OK [ 237.693291] Testing event 9p_protocol_dump: OK [ 237.726172] Testing event 9p_client_res: OK [ 237.752139] Testing event 9p_client_req: OK [ 237.777150] Running tests on trace event systems: [ 237.777750] Testing event system 9p: OK [ 237.816697] Testing event system skb: OK [ 237.845683] Testing event system net: OK [ 237.879784] Testing event system napi: OK [ 237.906282] Testing event system sock: OK [ 237.936271] Testing event system udp: OK [ 237.961781] Testing event system tcp: OK [ 237.995308] Testing event system fib: OK [ 238.022326] Testing event system qdisc: OK [ 238.050448] Testing event system bridge: OK [ 238.080045] Testing event system binder: OK [ 238.126434] Testing event system ras: OK [ 238.155608] Testing event system rtc: OK [ 238.197500] Testing event system mdio: OK [ 238.226122] Testing event system spi: OK [ 238.260509] Testing event system sync_trace: OK [ 238.286109] Testing event system dma_fence: OK [ 238.319454] Testing event system regmap: OK [ 238.357386] Testing event system iommu: OK [ 238.388468] Testing event system random: OK [ 238.428276] Testing event system regulator: OK [ 238.460883] Testing event system rpmh: OK [ 238.490192] Testing event system clk: OK [ 238.531781] Testing event system gpio: OK [ 238.561495] Testing event system wbt: OK [ 238.592706] Testing event system block: OK [ 238.633137] Testing event system filelock: OK [ 238.665392] Testing event system fs_dax: OK [ 238.702103] Testing event system writeback: OK [ 238.748428] Testing event system page_ref: OK [ 238.935756] Testing event system cma: OK [ 238.965579] Testing event system page_isolation: OK [ 238.992972] Testing event system huge_memory: OK [ 239.024447] Testing event system migrate: OK [ 239.052636] Testing event system compaction: OK [ 239.088685] Testing event system kmem: OK [ 239.127868] Testing event system percpu: OK [ 239.160558] Testing event system vmscan: OK [ 239.200418] Testing event system pagemap: OK [ 239.225867] Testing event system oom: OK [ 239.261266] Testing event system filemap: OK [ 239.291835] Testing event system rseq: OK [ 239.318472] Testing event system xdp: OK [ 239.353603] Testing event system benchmark: OK [ 239.481336] Testing event system rpm: OK [ 239.513459] Testing event system power: OK [ 239.567450] Testing event system test: OK [ 239.592981] Testing event system preemptirq: OK [ 239.641628] Testing event system cgroup: OK [ 239.673671] Testing event system module: OK [ 239.703840] Testing event system alarmtimer: OK [ 239.733669] Testing event system timer: OK [ 239.773384] Testing event system swiotlb: OK [ 239.800506] Testing event system rcu: OK [ 239.854199] Testing event system printk: OK [ 239.880387] Testing event system lock: OK [ 239.950007] Testing event system sched: OK [ 239.996003] Testing event system workqueue: OK [ 240.025485] Testing event system signal: OK [ 240.054465] Testing event system irq: OK [ 240.087283] Testing event system cpuhp: OK [ 240.116113] Testing event system task: OK [ 240.145289] Testing event system kvm: OK [ 240.196821] Testing event system emulation: OK [ 240.224795] Testing event system ipi: OK [ 240.259789] Testing event system raw_syscalls: OK [ 240.287739] Testing event system initcall: OK [ 240.317696] Running tests on all trace events: [ 240.318209] Testing all events: OK [ 261.660802] hrtimer: interrupt took 12460672 ns [ 391.501839] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 391.534325] Running tests again, along with the function tracer [ 391.556295] Running tests on trace events: [ 391.564073] Testing event initcall_finish: OK [ 392.044086] Testing event initcall_start: OK [ 392.680628] Testing event initcall_level: OK [ 393.268521] Testing event sys_exit: OK [ 393.882289] Testing event sys_enter: OK [ 394.504613] Testing event ipi_exit: OK [ 395.163767] Testing event ipi_entry: OK [ 395.690069] Testing event ipi_raise: OK [ 396.387871] Testing event instruction_emulation: OK [ 396.921662] Testing event kvm_halt_poll_ns: OK [ 397.543356] Testing event kvm_age_page: OK [ 398.198500] Testing event kvm_fpu: OK [ 398.839558] Testing event kvm_mmio: OK [ 399.500934] Testing event kvm_ack_irq: OK [ 400.202192] Testing event kvm_set_irq: OK [ 400.942126] Testing event kvm_vcpu_wakeup: OK [ 401.372157] Testing event kvm_userspace_exit: OK [ 402.042124] Testing event kvm_timer_update_irq: OK [ 402.643518] Testing event kvm_toggle_cache: OK [ 403.267578] Testing event kvm_set_way_flush: OK [ 403.820838] Testing event kvm_test_age_hva: OK [ 404.558520] Testing event kvm_age_hva: OK [ 405.095462] Testing event kvm_set_spte_hva: OK [ 405.371486] Testing event kvm_unmap_hva_range: OK [ 405.894315] Testing event kvm_mmio_emulate: OK [ 406.547453] Testing event kvm_irq_line: OK [ 407.080742] Testing event kvm_access_fault: OK [ 407.798411] Testing event kvm_guest_fault: OK [ 408.622311] Testing event kvm_exit: OK [ 409.357100] Testing event kvm_entry: OK [ 409.766524] Testing event kvm_set_guest_debug: OK [ 410.206208] Testing event kvm_handle_sys_reg: OK [ 410.881015] Testing event trap_reg: OK [ 411.396766] Testing event kvm_arm_set_regset: OK [ 412.155625] Testing event kvm_arm_set_dreg32: OK [ 412.646224] Testing event kvm_arm_clear_debug: OK [ 413.240789] Testing event kvm_arm_setup_debug: OK [ 413.732634] Testing event kvm_hvc_arm64: OK [ 414.398236] Testing event kvm_wfx_arm64: OK [ 414.854574] Testing event vgic_update_irq_pending: OK [ 415.610218] Testing event task_rename: OK [ 416.098362] Testing event task_newtask: OK [ 416.734509] Testing event cpuhp_exit: OK [ 417.488098] Testing event cpuhp_multi_enter: OK [ 418.403414] Testing event cpuhp_enter: OK [ 418.922333] Testing event softirq_raise: OK [ 419.554611] Testing event softirq_exit: OK [ 420.175491] Testing event softirq_entry: OK [ 420.876689] Testing event irq_handler_exit: OK [ 421.462212] Testing event irq_handler_entry: OK [ 422.146203] Testing event signal_deliver: OK [ 422.694398] Testing event signal_generate: OK [ 423.298355] Testing event workqueue_execute_end: OK [ 423.887493] Testing event workqueue_execute_start: OK [ 424.515989] Testing event workqueue_activate_work: OK [ 425.075926] Testing event workqueue_queue_work: OK [ 425.726425] Testing event sched_wake_idle_without_ipi: OK [ 426.105358] Testing event sched_swap_numa: OK [ 426.788998] Testing event sched_stick_numa: OK [ 427.177009] Testing event sched_move_numa: OK [ 427.589164] Testing event sched_process_hang: OK [ 428.127431] Testing event sched_pi_setprio: OK [ 428.547374] Testing event sched_stat_runtime: OK [ 429.315600] Testing event sched_stat_blocked: OK [ 430.093118] Testing event sched_stat_iowait: OK [ 430.722400] Testing event sched_stat_sleep: OK [ 431.288902] Testing event sched_stat_wait: OK [ 431.842602] Testing event sched_process_exec: OK [ 432.570580] Testing event sched_process_fork: OK [ 433.177414] Testing event sched_process_wait: OK [ 433.862222] Testing event sched_wait_task: OK [ 434.674282] Testing event sched_process_exit: OK [ 435.504905] Testing event sched_process_free: OK [ 435.986056] Testing event sched_migrate_task: OK [ 436.655551] Testing event sched_switch: OK [ 437.086431] Testing event sched_wakeup_new: OK [ 437.946251] Testing event sched_wakeup: OK [ 438.562084] Testing event sched_waking: OK [ 439.349978] Testing event sched_kthread_stop_ret: OK [ 440.094132] Testing event sched_kthread_stop: OK [ 440.619581] Testing event lock_acquired: OK [ 441.206309] Testing event lock_contended: OK [ 441.944618] Testing event lock_release: OK [ 442.435419] Testing event lock_acquire: OK [ 443.142226] Testing event console: OK [ 443.721014] Testing event rcu_barrier: OK [ 444.156626] Testing event rcu_torture_read: OK [ 444.758192] Testing event rcu_batch_end: OK [ 445.466293] Testing event rcu_invoke_kfree_callback: OK [ 446.162457] Testing event rcu_invoke_callback: OK [ 446.898432] Testing event rcu_batch_start: OK [ 447.642239] Testing event rcu_kfree_callback: OK [ 447.958319] Testing event rcu_callback: OK [ 448.470013] Testing event rcu_dyntick: OK [ 449.416894] Testing event rcu_fqs: OK [ 449.947670] Testing event rcu_quiescent_state_report: OK [ 450.386377] Testing event rcu_unlock_preempted_task: OK [ 450.806151] Testing event rcu_preempt_task: OK [ 451.490328] Testing event rcu_exp_funnel_lock: OK [ 451.936861] Testing event rcu_exp_grace_period: OK [ 452.662532] Testing event rcu_grace_period_init: OK [ 453.661987] Testing event rcu_future_grace_period: OK [ 454.178512] Testing event rcu_grace_period: OK [ 455.042169] Testing event rcu_utilization: OK [ 455.920622] Testing event swiotlb_bounced: OK [ 456.466232] Testing event tick_stop: OK [ 456.997000] Testing event itimer_expire: OK [ 457.362246] Testing event itimer_state: OK [ 457.847993] Testing event hrtimer_cancel: OK [ 458.614099] Testing event hrtimer_expire_exit: OK [ 459.142433] Testing event hrtimer_expire_entry: OK [ 459.622465] Testing event hrtimer_start: OK [ 460.146274] Testing event hrtimer_init: OK [ 460.943437] Testing event timer_cancel: OK [ 461.630168] Testing event timer_expire_exit: OK [ 462.134541] Testing event timer_expire_entry: OK [ 462.514098] Testing event timer_start: OK [ 463.035436] Testing event timer_init: OK [ 463.558417] Testing event alarmtimer_cancel: OK [ 463.954137] Testing event alarmtimer_start: OK [ 464.492268] Testing event alarmtimer_fired: OK [ 465.071410] Testing event alarmtimer_suspend: OK [ 465.538484] Testing event module_request: OK [ 466.302590] Testing event module_put: OK [ 466.950415] Testing event module_get: OK [ 467.747488] Testing event module_free: OK [ 468.347599] Testing event module_load: OK [ 468.922391] Testing event cgroup_transfer_tasks: OK [ 469.471983] Testing event cgroup_attach_task: OK [ 470.094396] Testing event cgroup_rename: OK [ 470.468970] Testing event cgroup_release: OK [ 471.018473] Testing event cgroup_rmdir: OK [ 471.611589] Testing event cgroup_mkdir: OK [ 472.163502] Testing event cgroup_remount: OK [ 472.925223] Testing event cgroup_destroy_root: OK [ 473.486474] Testing event cgroup_setup_root: OK [ 474.006513] Testing event irq_enable: OK [ 476.990503] Testing event irq_disable: OK [ 478.350595] Testing event ftrace_test_filter: OK [ 479.179492] Testing event dev_pm_qos_remove_request: OK [ 479.983962] Testing event dev_pm_qos_update_request: OK [ 480.570535] Testing event dev_pm_qos_add_request: OK [ 481.358589] Testing event pm_qos_update_flags: OK [ 481.898255] Testing event pm_qos_update_target: OK [ 482.730389] Testing event pm_qos_update_request_timeout: OK [ 483.436402] Testing event pm_qos_remove_request: OK [ 484.067898] Testing event pm_qos_update_request: OK [ 484.816021] Testing event pm_qos_add_request: OK [ 485.308149] Testing event power_domain_target: OK [ 486.002546] Testing event clock_set_rate: OK [ 486.746600] Testing event clock_disable: OK [ 487.434530] Testing event clock_enable: OK [ 488.286312] Testing event wakeup_source_deactivate: OK [ 488.826270] Testing event wakeup_source_activate: OK [ 489.490411] Testing event suspend_resume: OK [ 490.322404] Testing event device_pm_callback_end: OK [ 490.906601] Testing event device_pm_callback_start: OK [ 491.587793] Testing event cpu_frequency_limits: OK [ 492.003605] Testing event cpu_frequency: OK [ 492.594293] Testing event pstate_sample: OK [ 493.194578] Testing event powernv_throttle: OK [ 493.875406] Testing event cpu_idle: OK [ 494.396461] Testing event rpm_return_int: OK [ 495.222507] Testing event rpm_idle: OK [ 495.769349] Testing event rpm_resume: OK [ 496.266320] Testing event rpm_suspend: OK [ 496.884723] Testing event benchmark_event: OK [ 497.826508] Testing event xdp_devmap_xmit: OK [ 498.560445] Testing event xdp_cpumap_enqueue: OK [ 499.315410] Testing event xdp_cpumap_kthread: OK [ 499.864822] Testing event xdp_redirect_map_err: OK [ 500.374367] Testing event xdp_redirect_map: OK [ 501.168752] Testing event xdp_redirect_err: OK [ 501.875896] Testing event xdp_redirect: OK [ 502.688890] Testing event xdp_exception: OK [ 503.314533] Testing event rseq_ip_fixup: OK [ 504.124293] Testing event rseq_update: OK [ 504.656113] Testing event file_check_and_advance_wb_err: OK [ 505.323690] Testing event filemap_set_wb_err: OK [ 506.029025] Testing event mm_filemap_add_to_page_cache: OK [ 506.726489] Testing event mm_filemap_delete_from_page_cache: OK [ 507.354307] Testing event compact_retry: OK [ 508.023419] Testing event skip_task_reaping: OK [ 508.914556] Testing event finish_task_reaping: OK [ 509.340725] Testing event start_task_reaping: OK [ 510.104747] Testing event wake_reaper: OK [ 510.801084] Testing event mark_victim: OK [ 511.415905] Testing event reclaim_retry_zone: OK [ 512.054261] Testing event oom_score_adj_update: OK [ 512.610309] Testing event mm_lru_activate: OK [ 513.100118] Testing event mm_lru_insertion: OK [ 513.655953] Testing event mm_vmscan_inactive_list_is_low: OK [ 514.230582] Testing event mm_vmscan_lru_shrink_active: OK [ 514.899610] Testing event mm_vmscan_lru_shrink_inactive: OK [ 515.534289] Testing event mm_vmscan_writepage: OK [ 516.269179] Testing event mm_vmscan_lru_isolate: OK [ 516.928614] Testing event mm_shrink_slab_end: OK [ 517.712360] Testing event mm_shrink_slab_start: OK [ 518.256604] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 518.778158] Testing event mm_vmscan_memcg_reclaim_end: OK [ 519.637799] Testing event mm_vmscan_direct_reclaim_end: OK [ 520.338252] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 520.894594] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 521.398427] Testing event mm_vmscan_direct_reclaim_begin: OK [ 522.162469] Testing event mm_vmscan_wakeup_kswapd: OK [ 522.766512] Testing event mm_vmscan_kswapd_wake: OK [ 523.267736] Testing event mm_vmscan_kswapd_sleep: OK [ 523.762389] Testing event percpu_destroy_chunk: OK [ 524.415986] Testing event percpu_create_chunk: OK [ 525.034537] Testing event percpu_alloc_percpu_fail: OK [ 525.556692] Testing event percpu_free_percpu: OK [ 526.130295] Testing event percpu_alloc_percpu: OK [ 526.766387] Testing event mm_page_alloc_extfrag: OK [ 527.238410] Testing event mm_page_pcpu_drain: OK [ 527.742090] Testing event mm_page_alloc_zone_locked: OK [ 528.238469] Testing event mm_page_alloc: OK [ 528.866596] Testing event mm_page_free_batched: OK [ 529.598163] Testing event mm_page_free: OK [ 530.304139] Testing event kmem_cache_free: OK [ 531.014497] Testing event kfree: OK [ 531.641151] Testing event kmem_cache_alloc_node: OK [ 532.234274] Testing event kmalloc_node: OK [ 532.914247] Testing event kmem_cache_alloc: OK [ 533.757021] Testing event kmalloc: OK [ 534.294167] Testing event mm_compaction_kcompactd_wake: OK [ 534.955943] Testing event mm_compaction_wakeup_kcompactd: OK [ 535.842135] Testing event mm_compaction_kcompactd_sleep: OK [ 536.406495] Testing event mm_compaction_defer_reset: OK [ 536.739954] Testing event mm_compaction_defer_compaction: OK [ 537.160424] Testing event mm_compaction_deferred: OK [ 537.883753] Testing event mm_compaction_suitable: OK [ 538.486540] Testing event mm_compaction_finished: OK [ 539.166364] Testing event mm_compaction_try_to_compact_pages: OK [ 540.083564] Testing event mm_compaction_end: OK [ 540.675911] Testing event mm_compaction_begin: OK [ 541.138350] Testing event mm_compaction_migratepages: OK [ 541.718408] Testing event mm_compaction_isolate_freepages: OK [ 542.406514] Testing event mm_compaction_isolate_migratepages: OK [ 543.006373] Testing event mm_migrate_pages: OK [ 543.654324] Testing event mm_collapse_huge_page_swapin: OK [ 544.278326] Testing event mm_collapse_huge_page_isolate: OK [ 544.875743] Testing event mm_collapse_huge_page: OK [ 545.584276] Testing event mm_khugepaged_scan_pmd: OK [ 546.317997] Testing event test_pages_isolated: OK [ 546.831817] Testing event cma_release: OK [ 547.367406] Testing event cma_alloc: OK [ 548.166135] Testing event page_ref_unfreeze: OK [ 548.850331] Testing event page_ref_freeze: OK [ 549.707438] Testing event page_ref_mod_unless: OK [ 550.330498] Testing event page_ref_mod_and_return: OK [ 551.481116] Testing event page_ref_mod_and_test: OK [ 553.336335] Testing event page_ref_mod: OK [ 554.542588] Testing event page_ref_set: OK [ 555.422078] Testing event sb_clear_inode_writeback: OK [ 556.191781] Testing event sb_mark_inode_writeback: OK [ 556.702241] Testing event writeback_dirty_inode_enqueue: OK [ 557.369117] Testing event writeback_lazytime_iput: OK [ 557.918325] Testing event writeback_lazytime: OK [ 558.602150] Testing event writeback_single_inode: OK [ 559.462196] Testing event writeback_single_inode_start: OK [ 560.199564] Testing event writeback_wait_iff_congested: OK [ 560.700202] Testing event writeback_congestion_wait: OK [ 561.198516] Testing event writeback_sb_inodes_requeue: OK [ 561.691866] Testing event balance_dirty_pages: OK [ 562.315651] Testing event bdi_dirty_ratelimit: OK [ 563.290223] Testing event global_dirty_state: OK [ 563.880424] Testing event writeback_queue_io: OK [ 564.506548] Testing event wbc_writepage: OK [ 565.231650] Testing event writeback_bdi_register: OK [ 565.619602] Testing event writeback_wake_background: OK [ 566.299461] Testing event writeback_pages_written: OK [ 567.134398] Testing event writeback_wait: OK [ 567.578332] Testing event writeback_written: OK [ 568.119802] Testing event writeback_start: OK [ 568.538078] Testing event writeback_exec: OK [ 569.287487] Testing event writeback_queue: OK [ 569.874290] Testing event writeback_write_inode: OK [ 570.706109] Testing event writeback_write_inode_start: OK [ 571.386118] Testing event writeback_dirty_inode: OK [ 571.954445] Testing event writeback_dirty_inode_start: OK [ 572.854123] Testing event writeback_mark_inode_dirty: OK [ 573.502327] Testing event writeback_dirty_page: OK [ 574.134298] Testing event dax_writeback_one: OK [ 574.754221] Testing event dax_writeback_range_done: OK [ 575.316415] Testing event dax_writeback_range: OK [ 575.742487] Testing event dax_insert_mapping: OK [ 576.258537] Testing event dax_insert_pfn_mkwrite: OK [ 577.158455] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 577.978329] Testing event dax_load_hole: OK [ 578.606603] Testing event dax_pte_fault_done: OK [ 579.246263] Testing event dax_pte_fault: OK [ 579.846590] Testing event dax_pmd_insert_mapping: OK [ 580.448069] Testing event dax_pmd_load_hole_fallback: OK [ 580.986609] Testing event dax_pmd_load_hole: OK [ 581.567813] Testing event dax_pmd_fault_done: OK [ 582.308718] Testing event dax_pmd_fault: OK [ 582.944964] Testing event generic_add_lease: OK [ 583.602597] Testing event time_out_leases: OK [ 584.402453] Testing event generic_delete_lease: OK [ 585.266555] Testing event break_lease_unblock: OK [ 585.698244] Testing event break_lease_block: OK [ 586.464486] Testing event break_lease_noblock: OK [ 587.028774] Testing event flock_lock_inode: OK [ 587.772579] Testing event locks_remove_posix: OK [ 588.430576] Testing event fcntl_setlk: OK [ 589.075935] Testing event posix_lock_inode: OK [ 589.687543] Testing event locks_get_lock_context: OK [ 590.461154] Testing event block_rq_remap: OK [ 591.114585] Testing event block_bio_remap: OK [ 591.662432] Testing event block_split: OK [ 592.338050] Testing event block_unplug: OK [ 592.984667] Testing event block_plug: OK [ 593.428738] Testing event block_sleeprq: OK [ 593.962331] Testing event block_getrq: OK [ 594.616629] Testing event block_bio_queue: OK [ 595.299977] Testing event block_bio_frontmerge: OK [ 595.832054] Testing event block_bio_backmerge: OK [ 596.512405] Testing event block_bio_complete: OK [ 597.060557] Testing event block_bio_bounce: OK [ 597.767606] Testing event block_rq_issue: OK [ 598.594275] Testing event block_rq_insert: OK [ 599.183472] Testing event block_rq_complete: OK [ 599.836306] Testing event block_rq_requeue: OK [ 600.612100] Testing event block_dirty_buffer: OK [ 601.170272] Testing event block_touch_buffer: OK [ 601.817310] Testing event wbt_timer: OK [ 602.488164] Testing event wbt_step: OK [ 603.159331] Testing event wbt_lat: OK [ 603.791964] Testing event wbt_stat: OK [ 604.346400] Testing event gpio_value: OK [ 604.936097] Testing event gpio_direction: OK [ 605.596521] Testing event clk_set_duty_cycle_complete: OK [ 606.210573] Testing event clk_set_duty_cycle: OK [ 606.751754] Testing event clk_set_phase_complete: OK [ 607.300684] Testing event clk_set_phase: OK [ 608.008179] Testing event clk_set_parent_complete: OK [ 608.693941] Testing event clk_set_parent: OK [ 609.359575] Testing event clk_set_rate_complete: OK [ 610.200905] Testing event clk_set_rate: OK [ 610.883714] Testing event clk_unprepare_complete: OK [ 611.466093] Testing event clk_unprepare: OK [ 611.987961] Testing event clk_prepare_complete: OK [ 612.358240] Testing event clk_prepare: OK [ 612.766358] Testing event clk_disable_complete: OK [ 613.342495] Testing event clk_disable: OK [ 613.920540] Testing event clk_enable_complete: OK [ 614.317044] Testing event clk_enable: OK [ 614.986525] Testing event rpmh_send_msg: OK [ 615.532731] Testing event rpmh_tx_done: OK [ 616.124470] Testing event regulator_set_voltage_complete: OK [ 617.042283] Testing event regulator_set_voltage: OK [ 617.758578] Testing event regulator_disable_complete: OK [ 618.486444] Testing event regulator_disable: OK [ 619.044695] Testing event regulator_enable_complete: OK [ 619.593157] Testing event regulator_enable_delay: OK [ 620.274265] Testing event regulator_enable: OK [ 620.952547] Testing event urandom_read: OK [ 621.530610] Testing event random_read: OK [ 622.322093] Testing event extract_entropy_user: OK [ 622.995826] Testing event extract_entropy: OK [ 623.608241] Testing event get_random_bytes_arch: OK [ 624.294243] Testing event get_random_bytes: OK [ 624.918423] Testing event xfer_secondary_pool: OK [ 625.435564] Testing event add_disk_randomness: OK [ 625.967588] Testing event add_input_randomness: OK [ 626.606002] Testing event debit_entropy: OK [ 627.370397] Testing event push_to_pool: OK [ 628.043424] Testing event credit_entropy_bits: OK [ 628.755436] Testing event mix_pool_bytes_nolock: OK [ 629.174318] Testing event mix_pool_bytes: OK [ 629.946576] Testing event add_device_randomness: OK [ 630.478508] Testing event io_page_fault: OK [ 631.258284] Testing event unmap: OK [ 631.895401] Testing event map: OK [ 632.614271] Testing event detach_device_from_domain: OK [ 633.386452] Testing event attach_device_to_domain: OK [ 633.866326] Testing event remove_device_from_group: OK [ 634.474504] Testing event add_device_to_group: OK [ 635.199863] Testing event regcache_drop_region: OK [ 635.922117] Testing event regmap_async_complete_done: OK [ 636.688181] Testing event regmap_async_complete_start: OK [ 637.340219] Testing event regmap_async_io_complete: OK [ 637.978273] Testing event regmap_async_write_start: OK [ 638.446204] Testing event regmap_cache_bypass: OK [ 638.982094] Testing event regmap_cache_only: OK [ 639.490064] Testing event regcache_sync: OK [ 640.203396] Testing event regmap_hw_write_done: OK [ 641.078505] Testing event regmap_hw_write_start: OK [ 641.726413] Testing event regmap_hw_read_done: OK [ 642.194414] Testing event regmap_hw_read_start: OK [ 642.810364] Testing event regmap_reg_read_cache: OK [ 643.273047] Testing event regmap_reg_read: OK [ 643.877945] Testing event regmap_reg_write: OK [ 644.622213] Testing event dma_fence_wait_end: OK [ 645.264886] Testing event dma_fence_wait_start: OK [ 646.074410] Testing event dma_fence_signaled: OK [ 646.678155] Testing event dma_fence_enable_signal: OK [ 647.590346] Testing event dma_fence_destroy: OK [ 648.374317] Testing event dma_fence_init: OK [ 649.071393] Testing event dma_fence_emit: OK [ 649.794424] Testing event sync_timeline: OK [ 650.563372] Testing event spi_transfer_stop: OK [ 651.071326] Testing event spi_transfer_start: OK [ 651.718561] Testing event spi_message_done: OK [ 652.274279] Testing event spi_message_start: OK [ 652.760795] Testing event spi_message_submit: OK [ 653.306206] Testing event spi_controller_busy: OK [ 653.697980] Testing event spi_controller_idle: OK [ 654.617672] Testing event mdio_access: OK [ 655.252095] Testing event rtc_timer_fired: OK [ 655.975437] Testing event rtc_timer_dequeue: OK [ 656.854334] Testing event rtc_timer_enqueue: OK [ 657.478228] Testing event rtc_read_offset: OK [ 658.330361] Testing event rtc_set_offset: OK [ 659.142169] Testing event rtc_alarm_irq_enable: OK [ 659.603424] Testing event rtc_irq_set_state: OK [ 660.006490] Testing event rtc_irq_set_freq: OK [ 660.631452] Testing event rtc_read_alarm: OK [ 661.243402] Testing event rtc_set_alarm: OK [ 661.872945] Testing event rtc_read_time: OK [ 662.398539] Testing event rtc_set_time: OK [ 662.814420] Testing event memory_failure_event: OK [ 663.586360] Testing event aer_event: OK [ 664.361240] Testing event non_standard_event: OK [ 665.022345] Testing event arm_event: OK [ 665.546010] Testing event mc_event: OK [ 666.096906] Testing event binder_return: OK [ 666.456785] Testing event binder_command: OK [ 667.005045] Testing event binder_unmap_kernel_end: OK [ 667.575604] Testing event binder_unmap_kernel_start: OK [ 668.163477] Testing event binder_unmap_user_end: OK [ 668.695589] Testing event binder_unmap_user_start: OK [ 669.274048] Testing event binder_alloc_page_end: OK [ 669.863480] Testing event binder_alloc_page_start: OK [ 670.458574] Testing event binder_free_lru_end: OK [ 671.004854] Testing event binder_free_lru_start: OK [ 671.310298] Testing event binder_alloc_lru_end: OK [ 671.826605] Testing event binder_alloc_lru_start: OK [ 672.590568] Testing event binder_update_page_range: OK [ 673.118503] Testing event binder_transaction_failed_buffer_release: OK [ 673.888424] Testing event binder_transaction_buffer_release: OK [ 674.491712] Testing event binder_transaction_alloc_buf: OK [ 675.096220] Testing event binder_transaction_fd_recv: OK [ 675.685012] Testing event binder_transaction_fd_send: OK [ 676.227911] Testing event binder_transaction_ref_to_ref: OK [ 676.872806] Testing event binder_transaction_ref_to_node: OK [ 677.676795] Testing event binder_transaction_node_to_ref: OK [ 678.284056] Testing event binder_transaction_received: OK [ 678.772860] Testing event binder_transaction: OK [ 679.192311] Testing event binder_wait_for_work: OK [ 679.663624] Testing event binder_read_done: OK [ 680.494592] Testing event binder_write_done: OK [ 680.814355] Testing event binder_ioctl_done: OK [ 681.425997] Testing event binder_unlock: OK [ 682.230292] Testing event binder_locked: OK [ 682.834216] Testing event binder_lock: OK [ 683.802581] Testing event binder_ioctl: OK [ 684.495593] Testing event br_fdb_update: OK [ 685.323444] Testing event fdb_delete: OK [ 686.070503] Testing event br_fdb_external_learn_add: OK [ 686.759643] Testing event br_fdb_add: OK [ 687.426261] Testing event qdisc_dequeue: OK [ 687.986293] Testing event fib_table_lookup: OK [ 688.526330] Testing event tcp_probe: OK [ 689.010292] Testing event tcp_retransmit_synack: OK [ 689.597081] Testing event tcp_rcv_space_adjust: OK [ 690.320294] Testing event tcp_destroy_sock: OK [ 691.011347] Testing event tcp_receive_reset: OK [ 691.694438] Testing event tcp_send_reset: OK [ 692.211652] Testing event tcp_retransmit_skb: OK [ 692.850428] Testing event udp_fail_queue_rcv_skb: OK [ 693.426438] Testing event inet_sock_set_state: OK [ 694.354003] Testing event sock_exceed_buf_limit: OK [ 695.054591] Testing event sock_rcvqueue_full: OK [ 695.605821] Testing event napi_poll: OK [ 696.267449] Testing event netif_rx_ni_entry: OK [ 696.954455] Testing event netif_rx_entry: OK [ 697.602394] Testing event netif_receive_skb_list_entry: OK [ 698.254466] Testing event netif_receive_skb_entry: OK [ 699.019444] Testing event napi_gro_receive_entry: OK [ 699.367497] Testing event napi_gro_frags_entry: OK [ 699.874203] Testing event netif_rx: OK [ 700.605059] Testing event netif_receive_skb: OK [ 701.230333] Testing event net_dev_queue: OK [ 701.854434] Testing event net_dev_xmit: OK [ 702.508797] Testing event net_dev_start_xmit: OK [ 703.230407] Testing event skb_copy_datagram_iovec: OK [ 703.987472] Testing event consume_skb: OK [ 704.598480] Testing event kfree_skb: OK [ 705.390426] Testing event 9p_protocol_dump: OK [ 706.242565] Testing event 9p_client_res: OK [ 706.860622] Testing event 9p_client_req: OK [ 707.304310] Running tests on trace event systems: [ 707.310504] Testing event system 9p: OK [ 707.912346] Testing event system skb: OK [ 708.732712] Testing event system net: OK [ 709.689091] Testing event system napi: OK [ 710.064193] Testing event system sock: OK [ 710.698337] Testing event system udp: OK [ 711.334512] Testing event system tcp: OK [ 711.900150] Testing event system fib: OK [ 712.516713] Testing event system qdisc: OK [ 713.345140] Testing event system bridge: OK [ 714.123972] Testing event system binder: OK [ 715.233000] Testing event system ras: OK [ 716.178325] Testing event system rtc: OK [ 717.062357] Testing event system mdio: OK [ 717.898342] Testing event system spi: OK [ 718.833045] Testing event system sync_trace: OK [ 719.462467] Testing event system dma_fence: OK [ 720.198303] Testing event system regmap: OK [ 721.094480] Testing event system iommu: OK [ 722.015413] Testing event system random: OK [ 723.114213] Testing event system regulator: OK [ 723.842230] Testing event system rpmh: OK [ 724.577006] Testing event system clk: OK [ 725.525182] Testing event system gpio: OK [ 726.514591] Testing event system wbt: OK [ 727.290473] Testing event system block: OK [ 728.219460] Testing event system filelock: OK [ 728.919415] Testing event system fs_dax: OK [ 729.910336] Testing event system writeback: OK [ 731.206613] Testing event system page_ref: OK [ 734.951447] Testing event system cma: OK [ 735.786597] Testing event system page_isolation: OK [ 736.261188] Testing event system huge_memory: OK [ 737.107735] Testing event system migrate: OK [ 737.885257] Testing event system compaction: OK [ 738.738208] Testing event system kmem: OK [ 739.807488] Testing event system percpu: OK [ 740.426529] Testing event system vmscan: OK [ 741.213298] Testing event system pagemap: OK [ 742.078514] Testing event system oom: OK [ 743.010344] Testing event system filemap: OK [ 743.646129] Testing event system rseq: OK [ 744.198033] Testing event system xdp: OK [ 745.018569] Testing event system benchmark: OK [ 746.250529] Testing event system rpm: OK [ 747.262295] Testing event system power: OK [ 748.442265] Testing event system test: OK [ 749.438208] Testing event system preemptirq: OK [ 751.579559] Testing event system cgroup: OK [ 752.443839] Testing event system module: OK [ 753.111941] Testing event system alarmtimer: OK [ 753.903618] Testing event system timer: OK [ 754.934231] Testing event system swiotlb: OK [ 755.602237] Testing event system rcu: OK [ 757.098047] Testing event system printk: OK [ 757.968349] Testing event system lock: OK [ 758.439374] Testing event system sched: OK [ 759.918373] Testing event system workqueue: OK [ 761.010441] Testing event system signal: OK [ 761.658126] Testing event system irq: OK [ 762.615430] Testing event system cpuhp: OK [ 763.270518] Testing event system task: OK [ 763.877069] Testing event system kvm: OK [ 765.027487] Testing event system emulation: OK [ 765.791853] Testing event system ipi: OK [ 766.378332] Testing event system raw_syscalls: OK [ 767.348512] Testing event system initcall: OK [ 767.926599] Running tests on all trace events: [ 767.952191] Testing all events: OK [ 799.366479] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 799.376556] Testing ftrace filter: OK [ 799.488887] trace_kprobe: Testing kprobe tracing: OK [ 799.855212] Loading compiled-in X.509 certificates [ 799.964265] _warn_unseeded_randomness: 7 callbacks suppressed [ 799.964514] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 799.967756] Loaded X.509 cert 'Build time autogenerated kernel key: 4054958e39d9099bc90ea5e944013505afd1c71c' [ 799.971676] page_owner is disabled [ 800.086432] Key type big_key registered [ 800.146485] Key type encrypted registered [ 800.149180] ima: No TPM chip found, activating TPM-bypass! [ 800.150041] ima: Allocated hash algorithm: sha1 [ 800.153744] No architecture policies found [ 800.159650] random: get_random_bytes called from prandom_seed_full_state+0xc0/0x198 with crng_init=0 [ 800.184167] hctosys: unable to open rtc device (rtc0) [ 800.199360] psci_checker: PSCI checker started using 1 CPUs [ 800.199988] psci_checker: Starting hotplug tests [ 800.200840] psci_checker: Trying to turn off and on again all CPUs [ 800.201884] psci_checker: Trying to turn off and on again group 0 (CPUs 0) [ 800.203289] psci_checker: Hotplug tests passed OK [ 800.203853] psci_checker: Starting suspend tests (10 cycles per state) [ 800.204711] psci_checker: cpuidle not available on CPU 0, ignoring [ 800.205589] psci_checker: Could not start suspend tests on any CPU [ 800.206195] psci_checker: PSCI checker completed [ 800.250454] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 800.440742] ### dt-test ### start of unittest - you will see error messages [ 800.468031] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 800.469158] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 800.470247] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 800.471552] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 800.472454] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 800.473372] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 800.490256] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 800.492654] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 800.494459] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 800.895159] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status [ 800.944179] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status [ 800.967366] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status [ 801.015040] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status [ 801.088963] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status [ 801.153657] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status [ 801.196574] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status [ 801.272613] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status [ 801.316033] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo [ 801.318004] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 801.319492] OF: overlay: overlay #6 is not topmost [ 801.581324] OF: overlay: remove: Could not find overlay #7 [ 801.581979] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #7 [ 801.802995] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/status [ 801.808212] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/status [ 801.810490] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@30/incline-up [ 801.812493] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@40/incline-up [ 801.825022] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/status [ 801.826330] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/color [ 801.828268] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/rate [ 801.830090] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/hvac_2 [ 801.832032] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200 [ 801.833609] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_left [ 801.835500] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_right [ 801.933802] OF: overlay: ERROR: multiple fragments add and/or delete node /testcase-data-2/substation@100/motor-1/controller [ 801.937819] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/controller/name [ 801.949482] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 801.951416] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 801.952894] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/rpm_avail [ 801.970410] ### dt-test ### end of unittest - 217 passed, 0 failed [ 801.992284] uart-pl011 9000000.pl011: no DMA platform data [ 802.342381] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 802.343937] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 802.385867] VFS: Mounted root (9p filesystem) readonly on device 0:18. [ 802.402292] devtmpfs: mounted [ 803.073606] Freeing unused kernel memory: 4096K [ 803.094484] Checked W+X mappings: passed, no W+X pages found [ 803.095798] rodata_test: all tests were successful [ 803.096777] Run /sbin/init as init process [ 803.209403] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=0 [ 803.418480] _warn_unseeded_randomness: 6 callbacks suppressed [ 803.420312] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 804.137043] random: fast init done [ 807.330461] systemd[1]: System time before build time, advancing clock. [ 807.825384] systemd[1]: Failed to insert module 'autofs4': No such file or directory [ 811.135651] random: systemd: uninitialized urandom read (16 bytes read) [ 811.489702] random: systemd: uninitialized urandom read (16 bytes read) [ 811.494189] random: systemd: uninitialized urandom read (16 bytes read) [ 816.860260] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN) [ 816.937754] systemd[1]: Detected virtualization qemu. [ 816.945070] systemd[1]: Detected architecture arm64. Welcome to Debian GNU/Linux 9 (stretch)! [ 817.093046] systemd[1]: Set hostname to . [ 817.272213] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=1 [ 817.333361] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 817.963654] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 818.468216] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 818.469187] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 818.470035] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 819.592077] _warn_unseeded_randomness: 20 callbacks suppressed [ 819.592275] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 819.619322] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 819.620422] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 820.724574] random: systemd: uninitialized urandom read (16 bytes read) [ 820.888871] _warn_unseeded_randomness: 13 callbacks suppressed [ 820.889066] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 820.890458] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 820.924838] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 822.594536] _warn_unseeded_randomness: 10 callbacks suppressed [ 822.595952] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 822.596756] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 822.597452] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 823.619912] _warn_unseeded_randomness: 5 callbacks suppressed [ 823.620102] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 825.035585] systemd-hiberna (1258) used greatest stack depth: 10576 bytes left [ 826.582540] random: systemd-cryptse: uninitialized urandom read (16 bytes read) [ 827.357457] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 828.432209] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 828.693605] systemd-debug-g (1266) used greatest stack depth: 10000 bytes left [ 830.184511] random: systemd: uninitialized urandom read (16 bytes read) [ 830.426272] random: systemd: uninitialized urandom read (16 bytes read) [ 830.431609] random: systemd: uninitialized urandom read (16 bytes read) [ 838.158021] systemd[1]: networking.service: Cannot add dependency job, ignoring: Unit networking.service is masked. [ 838.230022] random: get_random_bytes called from generate_random_uuid+0x20/0x50 with crng_init=1 [ 838.272604] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 838.337669] systemd[1]: Listening on Syslog Socket. [ OK ] Listening on Syslog Socket. [ 838.432795] systemd[1]: Created slice System Slice. [ OK ] Created slice System Slice. [ 838.474195] systemd[1]: Listening on Journal Audit Socket. [ OK ] Listening on Journal Audit Socket. [ 838.507825] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ OK ] Listening on /dev/initctl Compatibility Named Pipe. [ 838.533385] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 838.548970] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 838.583129] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 838.796994] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 838.895418] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... Mounting Debug File System... [ OK ] Reached target Slices. [ 839.616106] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 839.616943] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 839.617622] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [UNSUPP] Starting of Arbitrary Executable Fi…tem Automount Point not supported. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-getty.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Encrypted Volumes. [ OK ] Listening on udev Kernel Socket. [ 841.154239] _warn_unseeded_randomness: 11 callbacks suppressed [ 841.154496] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 Mounting POSIX Message Queue File System... [ 841.599497] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [ OK ] Listening on Journal Socket. [ 842.039371] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 842.444727] _warn_unseeded_randomness: 4 callbacks suppressed [ 842.444937] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 842.542482] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 843.029100] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 843.291260] systemd[1]: Starting Journal Service... Starting Journal Service... [ 843.480248] _warn_unseeded_randomness: 10 callbacks suppressed [ 843.480437] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 844.833908] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 845.316624] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 845.317488] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 845.552616] systemd[1]: Starting Create Static Device Nodes in /dev... Starting Create Static Device Nodes in /dev... [ 846.867054] _warn_unseeded_randomness: 5 callbacks suppressed [ 846.867250] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 846.868510] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 846.912640] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 847.634015] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 849.475763] _warn_unseeded_randomness: 11 callbacks suppressed [ 849.476019] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 849.477381] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 849.478217] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 850.399751] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 850.629689] systemd[1]: Reached target Sockets. [ OK ] Reached target Sockets. [ 852.265243] _warn_unseeded_randomness: 5 callbacks suppressed [ 852.265441] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 852.267123] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 852.282266] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 853.860969] _warn_unseeded_randomness: 3 callbacks suppressed [ 853.861170] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 853.903162] systemd[1]: Mounted Debug File System. [ OK ] Mounted Debug File System. [ 854.008625] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 854.093452] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 854.769915] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 854.771522] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 854.968043] _warn_unseeded_randomness: 2 callbacks suppressed [ 854.968264] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 856.915757] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 857.573834] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 857.576473] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 857.957536] systemd[1]: Started Create Static Device Nodes in /dev. [ OK ] Started Create Static Device Nodes in /dev. [ 858.649751] _warn_unseeded_randomness: 2 callbacks suppressed [ 858.649973] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 858.980605] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 859.148323] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 859.770156] systemd[1]: Starting udev Kernel Device Manager... [ 859.874496] _warn_unseeded_randomness: 3 callbacks suppressed [ 859.875865] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 Starting udev Kernel Device Manager... [ 860.889301] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 861.093396] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 861.094265] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 861.811812] systemd[1]: Started Remount Root and Kernel File Systems. [ OK ] Started Remount Root and Kernel File Systems. [ 862.338315] _warn_unseeded_randomness: 3 callbacks suppressed [ 862.338551] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 862.340152] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 862.593306] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 862.669819] systemd[1]: Started Load Kernel Modules. [ OK ] Started Load Kernel Modules. [ 863.793193] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 864.284146] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 864.342074] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 865.872817] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 865.873701] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 865.874382] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 866.033790] systemd[1]: Mounting Configuration File System... Mounting Configuration File System... [ 866.222117] systemd[1]: Reached target Local File Systems (Pre). [ OK ] Reached target Local File Systems (Pre). [ 866.390370] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 868.528492] _warn_unseeded_randomness: 2 callbacks suppressed [ 868.528690] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 868.529889] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 868.956537] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 869.712514] _warn_unseeded_randomness: 3 callbacks suppressed [ 869.712708] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 870.010084] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 870.757446] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 870.919504] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 870.920517] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 872.107016] _warn_unseeded_randomness: 3 callbacks suppressed [ 872.107212] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 872.108585] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 872.136448] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 873.368568] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 875.840684] _warn_unseeded_randomness: 4 callbacks suppressed [ 875.840881] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 875.841928] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 875.914244] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 877.252346] systemd[1]: Mounted Configuration File System. [ OK ] Mounted Configuration File System. [ 878.088965] systemd[1]: Started Apply Kernel Variables. [ OK ] Started Apply Kernel Variables. [ 879.637024] systemd[1]: Started udev Kernel Device Manager. [ OK ] Started udev Kernel Device Manager. [ 881.165842] _warn_unseeded_randomness: 6 callbacks suppressed [ 881.166041] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 881.348492] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 881.349465] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 881.669582] systemd[1]: Started Load/Save Random Seed. [ OK ] Started Load/Save Random Seed. [* ] (1 of 3) A start job is running for dev-ttyAMA0.device (48s / 30min) [** ] (1 of 3) A start job is running for dev-ttyAMA0.device (49s / 30min) [*** ] (1 of 3) A start job is running for dev-ttyAMA0.device (49s / 30min) [ *** ] (2 of 3) A start job is running for Journal Service (50s / 30min 4s)[ 888.703784] _warn_unseeded_randomness: 3 callbacks suppressed [ 888.704152] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [ *** ] (2 of 3) A start job is running for Journal Service (50s / 30min 4s) [ ***] (2 of 3) A start job is running for Journal Service (51s / 30min 4s) [ **] (3 of 3) A start job is running for…dplug all Devices (51s / no limit) [ *] (3 of 3) A start job is running for…dplug all Devices (52s / no limit) [ **] (3 of 3) A start job is running for…dplug all Devices (52s / no limit) [ ***] (1 of 3) A start job is running for dev-ttyAMA0.device (53s / 30min) [ *** ] (1 of 3) A start job is running for dev-ttyAMA0.device (53s / 30min) [ *** ] (1 of 3) A start job is running for dev-ttyAMA0.device (54s / 30min) [*** ] (2 of 3) A start job is running for Journal Service (54s / 30min 4s) [** ] (2 of 3) A start job is running for Journal Service (55s / 30min 4s) [* ] (2 of 3) A start job is running for Journal Service (55s / 30min 4s) [** ] (3 of 3) A start job is running for…dplug all Devices (56s / no limit) [*** ] (3 of 3) A start job is running for…dplug all Devices (56s / no limit) [ *** ] (3 of 3) A start job is running for…dplug all Devices (57s / no limit) [ *** ] (1 of 3) A start job is running for dev-ttyAMA0.device (57s / 30min) [ ***] (1 of 3) A start job is running for dev-ttyAMA0.device (58s / 30min) [ **] (1 of 3) A start job is running for dev-ttyAMA0.device (58s / 30min) [ *] (2 of 3) A start job is running for Journal Service (59s / 30min 4s) [ **] (2 of 3) A start job is running for Journal Service (59s / 30min 4s) [ ***] (2 of 3) A start job is running for Journal Service (1min / 30min 4s) [ *** ] (3 of 3) A start job is running for…plug all Devices (1min / no limit) [ *** ] (3 of 3) A start job is running for…g all Devices (1min 1s / no limit) [*** ] (3 of 3) A start job is running for…g all Devices (1min 1s / no limit) [** ] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 2s / 30min) [* ] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 2s / 30min)[ 901.161902] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [ 901.749159] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 Starting Flush Journal to Persistent Storage... [ 903.445999] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 903.447736] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 903.448501] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 904.722161] _warn_unseeded_randomness: 5 callbacks suppressed [ 904.722404] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [** ] (1 of 3) A start job is running for…stent Storage (1min 8s / 2min 33s) [*** ] (2 of 3) A start job is running for…v-ttyAMA0.device (1min 8s / 30min) [ *** ] (2 of 3) A start job is running for…v-ttyAMA0.device (1min 9s / 30min) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 10s / 30min) [ ***] (3 of 3) A start job is running for… all Devices (1min 11s / no limit) [ **] (3 of 3) A start job is running for… all Devices (1min 11s / no limit) [ *] (3 of 3) A start job is running for… all Devices (1min 12s / no limit) [ **] (1 of 3) A start job is running for…tent Storage (1min 12s / 2min 33s) [ ***] (1 of 3) A start job is running for…tent Storage (1min 13s / 2min 33s) [ *** ] (1 of 3) A start job is running for…tent Storage (1min 13s / 2min 33s) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 14s / 30min) [*** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 14s / 30min) [** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 15s / 30min) [* ] (3 of 3) A start job is running for… all Devices (1min 15s / no limit)[ 914.288364] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 914.289300] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 914.290069] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [** ] (3 of 3) A start job is running for… all Devices (1min 17s / no limit) [*** ] (3 of 3) A start job is running for… all Devices (1min 17s / no limit)[ 916.472404] _warn_unseeded_randomness: 4 callbacks suppressed [ 916.472602] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 916.473858] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 916.478588] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ *** ] (1 of 3) A start job is running for…tent Storage (1min 18s / 2min 33s) [ *** ] (1 of 3) A start job is running for…tent Storage (1min 18s / 2min 33s) [ ***] (1 of 3) A start job is running for…tent Storage (1min 19s / 2min 33s) [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 19s / 30min) [ *] (2 of 3) A start job is running for…-ttyAMA0.device (1min 20s / 30min) [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 20s / 30min) [ ***] (3 of 3) A start job is running for… all Devices (1min 21s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 21s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 22s / no limit) [*** ] (1 of 3) A start job is running for…tent Storage (1min 22s / 2min 33s) [** ] (1 of 3) A start job is running for…tent Storage (1min 23s / 2min 33s) [* ] (1 of 3) A start job is running for…tent Storage (1min 23s / 2min 33s)[ 921.988425] random: crng init done [ 921.988901] random: 3 get_random_xx warning(s) missed due to ratelimiting [ 922.068749] systemd-journald[1272]: Received request to flush runtime journal from PID 1 [** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 24s / 30min) [ OK ] Started Flush Journal to Persistent Storage. Starting Create Volatile Files and Directories... [*** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 30s / 30min) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 30s / 30min) [ *** ] (3 of 3) A start job is running for… all Devices (1min 31s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (1min 31s / no limit) [ **] (3 of 3) A start job is running for… all Devices (1min 32s / no limit) [ *] (1 of 3) A start job is running for… Directories (1min 32s / no limit) [ **] (1 of 3) A start job is running for… Directories (1min 33s / no limit) [ ***] (1 of 3) A start job is running for… Directories (1min 33s / no limit) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 34s / 30min) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 34s / 30min) [*** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 35s / 30min) [** ] (3 of 3) A start job is running for… all Devices (1min 35s / no limit) [* ] (3 of 3) A start job is running for… all Devices (1min 36s / no limit) [** ] (3 of 3) A start job is running for… all Devices (1min 36s / no limit) [*** ] (1 of 3) A start job is running for… Directories (1min 37s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (1min 37s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (1min 38s / no limit)