[ 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)) #56 SMP Tue Nov 27 11:34:43 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 initcall_debug [ 0.000000] Memory: 998852K/2097152K available (14288K kernel code, 3358K rwdata, 4656K rodata, 4096K init, 21766K bss, 84676K 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.000172] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.013800] Console: colour dummy device 80x25 [ 0.018565] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.019103] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.019506] ... MAX_LOCK_DEPTH: 48 [ 0.019871] ... MAX_LOCKDEP_KEYS: 8191 [ 0.020272] ... CLASSHASH_SIZE: 4096 [ 0.020677] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.021425] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.021805] ... CHAINHASH_SIZE: 32768 [ 0.022199] memory used by lock dependency info: 7775 kB [ 0.022615] per task-struct memory footprint: 2688 bytes [ 0.023064] ------------------------ [ 0.023453] | Locking API testsuite: [ 0.023809] ---------------------------------------------------------------------------- [ 0.024273] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.024746] -------------------------------------------------------------------------- [ 0.025862] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.037422] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.049079] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.061865] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.074107] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.087800] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.101633] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.115532] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.126239] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.136458] -------------------------------------------------------------------------- [ 0.136975] recursive read-lock: | ok | | ok | [ 0.140709] recursive read-lock #2: | ok | | ok | [ 0.144400] mixed read-write-lock: | ok | | ok | [ 0.148122] mixed write-read-lock: | ok | | ok | [ 0.151546] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.155028] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.158911] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.162712] -------------------------------------------------------------------------- [ 0.163569] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.168775] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.174168] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.179250] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.184405] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.189704] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.194953] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.200208] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.205579] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.211691] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.216952] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.222461] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.227934] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.233309] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.238808] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.244364] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.249983] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.255399] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.260759] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.266140] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.271490] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.277377] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.282649] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.288036] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.293388] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.298612] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.303990] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.309505] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.314952] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.320308] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.325536] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.330820] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.336444] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.341803] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.347215] hard-irq lock-inversion/123: ok | ok | ok | [ 0.352434] soft-irq lock-inversion/123: ok | ok | ok | [ 0.357880] hard-irq lock-inversion/132: ok | ok | ok | [ 0.363452] soft-irq lock-inversion/132: ok | ok | ok | [ 0.368925] hard-irq lock-inversion/213: ok | ok | ok | [ 0.374282] soft-irq lock-inversion/213: ok | ok | ok | [ 0.379604] hard-irq lock-inversion/231: ok | ok | ok | [ 0.384862] soft-irq lock-inversion/231: ok | ok | ok | [ 0.390200] hard-irq lock-inversion/312: ok | ok | ok | [ 0.395464] soft-irq lock-inversion/312: ok | ok | ok | [ 0.400812] hard-irq lock-inversion/321: ok | ok | ok | [ 0.406139] soft-irq lock-inversion/321: ok | ok | ok | [ 0.411786] hard-irq read-recursion/123: ok | [ 0.413833] soft-irq read-recursion/123: ok | [ 0.416270] hard-irq read-recursion/132: ok | [ 0.418316] soft-irq read-recursion/132: ok | [ 0.420760] hard-irq read-recursion/213: ok | [ 0.422820] soft-irq read-recursion/213: ok | [ 0.425285] hard-irq read-recursion/231: ok | [ 0.427356] soft-irq read-recursion/231: ok | [ 0.429757] hard-irq read-recursion/312: ok | [ 0.431830] soft-irq read-recursion/312: ok | [ 0.434237] hard-irq read-recursion/321: ok | [ 0.436286] soft-irq read-recursion/321: ok | [ 0.438731] -------------------------------------------------------------------------- [ 0.439227] | Wound/wait tests | [ 0.439573] --------------------- [ 0.439931] ww api failures: ok | ok | ok | [ 0.446636] ww contexts mixing: ok | ok | [ 0.450192] finishing ww context: ok | ok | ok | ok | [ 0.456728] locking mismatches: ok | ok | ok | [ 0.461684] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.479058] spinlock nest unlocked: ok | [ 0.481232] ----------------------------------------------------- [ 0.481664] |block | try |context| [ 0.482095] ----------------------------------------------------- [ 0.482537] context: ok | ok | ok | [ 0.487800] try: ok | ok | ok | [ 0.492767] block: ok | ok | ok | [ 0.497806] spinlock: ok | ok | ok | [ 0.503310] ------------------------------------------------------- [ 0.503774] Good, all 261 testcases passed! | [ 0.504162] --------------------------------- [ 0.508781] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.510063] pid_max: default: 32768 minimum: 301 [ 0.520557] LSM: Security Framework initializing [ 0.521305] Yama: becoming mindful. [ 0.524074] LoadPin: ready to pin (currently enforcing) [ 0.524959] AppArmor: AppArmor disabled by boot time parameter [ 0.551897] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.562400] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.564983] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.566430] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.599999] *** VALIDATE proc *** [ 0.617528] *** VALIDATE cgroup1 *** [ 0.617988] *** VALIDATE cgroup2 *** [ 0.680675] ASID allocator initialised with 32768 entries [ 0.689944] rcu: Hierarchical SRCU implementation. [ 0.765331] EFI services will not be available. [ 0.790088] smp: Bringing up secondary CPUs ... [ 0.790659] smp: Brought up 1 node, 1 CPU [ 0.791600] SMP: Total of 1 processors activated. [ 0.792214] CPU features: detected: 32-bit EL0 Support [ 0.792859] CPU features: detected: CRC32 instructions [ 1.072800] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.073700] CPU: All CPU(s) started at EL1 [ 1.075175] alternatives: patching kernel code [ 1.550736] node 0 initialised, 249310 pages in 4ms [ 1.558379] pgdatinit0 (15) used greatest stack depth: 13408 bytes left [ 1.581205] _warn_unseeded_randomness: 80 callbacks suppressed [ 1.581604] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 1.640834] devtmpfs: initialized [ 1.840497] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=0 [ 1.842832] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 1.856115] Registered cp15_barrier emulation handler [ 2.454913] workqueue: round-robin CPU selection forced, expect performance impact [ 7.725792] DMA-API: preallocated 65536 debug entries [ 7.726285] DMA-API: debugging enabled by kernel config [ 7.727452] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 7.729795] futex hash table entries: 256 (order: 3, 32768 bytes) [ 7.734540] Running postponed tracer tests: [ 7.735778] Testing tracer function: [ 7.849339] ftrace_replace_code: 1 [ 18.325529] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 18.624739] ftrace_replace_code: 1 [ 26.804957] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 26.813682] PASSED [ 26.814067] Testing dynamic ftrace: [ 27.724897] ftrace_replace_code: 1 [ 27.856973] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 28.093805] PASSED [ 28.094399] Testing dynamic ftrace ops #1: [ 31.698639] ftrace_replace_code: 1 [ 31.834187] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 31.847244] (1 0 1 0 0) [ 31.847417] (1 1 2 0 0) [ 31.924676] ftrace_replace_code: 1 [ 49.384970] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 49.390388] (2 1 3 0 1063535) [ 49.390724] (2 2 4 0 1063550) [ 49.473029] ftrace_replace_code: 1 [ 59.801290] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 59.839722] ftrace_replace_code: 1 [ 59.971045] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 59.984947] ftrace_replace_code: 1 [ 60.117408] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 60.126053] PASSED [ 60.126434] Testing dynamic ftrace ops #2: [ 63.726226] ftrace_replace_code: 1 [ 63.863261] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 63.875174] ftrace_replace_code: 1 [ 64.006836] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 64.118971] ftrace_replace_code: 1 [ 80.081213] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 80.086351] (1 0 1 1029391 0) [ 80.086641] (1 1 2 1029406 0) [ 80.179960] (2 1 3 1 3731) [ 80.183690] (2 2 4 351 4081) [ 80.395356] ftrace_replace_code: 1 [ 88.673029] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 88.681082] PASSED [ 88.681527] Testing ftrace recursion: [ 89.584787] ftrace_replace_code: 1 [ 89.718190] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 89.731084] ftrace_replace_code: 1 [ 89.861296] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 89.868755] PASSED [ 89.869170] Testing ftrace recursion safe: [ 90.773251] ftrace_replace_code: 1 [ 90.905447] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 90.918006] ftrace_replace_code: 1 [ 91.048171] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 91.055477] PASSED [ 91.055916] Testing ftrace regs(no arch support): [ 91.960685] ftrace_replace_code: 1 [ 92.092316] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 92.104887] ftrace_replace_code: 1 [ 92.235126] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 92.242879] PASSED [ 92.243711] Testing tracer nop: PASSED [ 92.246020] Testing tracer irqsoff: [ 92.344474] ftrace_replace_code: 1 [ 103.245717] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 103.338381] ftrace_replace_code: 1 [ 111.585848] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 111.594056] PASSED [ 111.595618] Testing tracer function_graph: [ 111.702681] ftrace_replace_code: 1 [ 121.959992] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 122.579806] ftrace_replace_code: 1 [ 131.278634] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 131.286464] PASSED [ 131.300346] _warn_unseeded_randomness: 3 callbacks suppressed [ 131.300526] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 131.301565] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 131.303225] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 131.317339] prandom: seed boundary self test passed [ 131.337408] prandom: 100 self tests passed [ 131.343510] pinctrl core: initialized pinctrl subsystem [ 131.419336] regulator-dummy: Failed to create debugfs directory [ 131.426662] [ 131.427026] ************************************************************* [ 131.427492] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 131.427923] ** ** [ 131.428737] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 131.429189] ** ** [ 131.429635] ** This means that this kernel is built to expose internal ** [ 131.430065] ** IOMMU data structures, which may compromise security on ** [ 131.430448] ** your system. ** [ 131.430828] ** ** [ 131.431293] ** If you see this message and you are not debugging the ** [ 131.431757] ** kernel, report this immediately to your vendor! ** [ 131.432170] ** ** [ 131.432569] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 131.433412] ************************************************************* [ 131.456854] DMI not present or invalid. [ 131.493066] NET: Registered protocol family 16 [ 131.515922] audit: initializing netlink subsys (disabled) [ 131.628931] cpuidle: using governor ladder [ 131.630671] cpuidle: using governor menu [ 131.645641] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 131.646995] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 131.650457] audit: type=2000 audit(109.996:1): state=initialized audit_enabled=0 res=1 [ 131.708267] DMA: preallocated 256 KiB pool for atomic allocations [ 131.775185] Serial: AMBA PL011 UART driver [ 132.355640] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 132.358626] printk: console [ttyAMA0] enabled [ 132.358626] printk: console [ttyAMA0] enabled [ 132.359691] printk: bootconsole [pl11] disabled [ 132.359691] printk: bootconsole [pl11] disabled [ 132.444168] _warn_unseeded_randomness: 39 callbacks suppressed [ 132.444335] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 132.445731] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 132.855710] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 132.898783] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 133.056762] arm-v7s io-pgtable: self test ok [ 133.058032] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 133.063844] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 133.067388] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 133.071337] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 133.075675] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 133.079440] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 133.083477] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 133.089061] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 133.095616] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 133.102000] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 133.108671] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 133.115303] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 133.123354] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 133.139840] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 133.156330] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 133.173439] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 133.192881] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 133.212176] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 133.231942] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 133.257118] vgaarb: loaded [ 133.303854] pps_core: LinuxPPS API ver. 1 registered [ 133.304454] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 133.308049] PTP clock support registered [ 133.443986] NetLabel: Initializing [ 133.444979] NetLabel: domain hash size = 128 [ 133.445496] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 133.452476] NetLabel: unlabeled traffic allowed by default [ 133.468828] clocksource: Switched to clocksource arch_sys_counter [ 136.084477] _warn_unseeded_randomness: 39 callbacks suppressed [ 136.084647] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 136.086012] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 136.088618] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 136.099588] VFS: Disk quotas dquot_6.6.0 [ 136.102930] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 136.122801] *** VALIDATE hugetlbfs *** [ 136.738515] NET: Registered protocol family 2 [ 136.771042] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 136.774624] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 136.788492] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 136.797135] TCP: Hash tables configured (established 16384 bind 16384) [ 136.803145] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 136.807096] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 136.817862] NET: Registered protocol family 1 [ 136.890335] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 136.898699] kvm [1]: HYP mode not available [ 136.924382] kworker/u2:1 (34) used greatest stack depth: 11920 bytes left [ 137.112844] Initialise system trusted keyrings [ 137.114268] _warn_unseeded_randomness: 55 callbacks suppressed [ 137.114428] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 137.119100] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 137.131053] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 138.443332] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 138.444752] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 138.461362] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 138.606207] 9p: Installing v9fs 9p2000 file system support [ 139.283035] Key type asymmetric registered [ 139.286686] Asymmetric key parser 'x509' registered [ 139.291582] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 139.713933] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 139.719267] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 139.737525] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 139.787106] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 139.841065] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 139.843085] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 139.845697] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 139.846847] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 139.850555] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 139.863004] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 139.864514] pci_bus 0000:00: root bus resource [bus 00-0f] [ 139.865334] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 139.866068] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 139.866832] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 139.870934] pci 0000:00:00.0: calling quirk_mmio_always_on+0x0/0x38 @ 1 [ 139.871854] pci 0000:00:00.0: quirk_mmio_always_on+0x0/0x38 took 67 usecs [ 139.944361] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 139.945497] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 139.946775] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 139.948337] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 139.949325] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 139.950112] pci 0000:00:03.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 139.950920] pci 0000:00:03.0: BAR 0: assigned [io 0x1000-0x103f] [ 139.951727] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 139.952917] pci 0000:00:02.0: BAR 0: assigned [io 0x1060-0x107f] [ 139.955049] pci 0000:00:00.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 139.956411] pci 0000:00:00.0: tegra_pcie_relax_enable+0x0/0x40 took 581 usecs [ 139.958599] pci 0000:00:01.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 139.959380] pci 0000:00:01.0: tegra_pcie_relax_enable+0x0/0x40 took 30 usecs [ 139.961148] pci 0000:00:02.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 139.961903] pci 0000:00:02.0: tegra_pcie_relax_enable+0x0/0x40 took 30 usecs [ 139.963162] pci 0000:00:03.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 139.964429] pci 0000:00:03.0: tegra_pcie_relax_enable+0x0/0x40 took 30 usecs [ 140.442653] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 140.493801] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 140.538692] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 144.293122] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 144.591589] SuperH (H)SCI(F) driver initialized [ 144.642796] _warn_unseeded_randomness: 38 callbacks suppressed [ 144.642977] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 144.707592] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 144.775027] libphy: Fixed MDIO Bus: probed [ 145.196917] ledtrig-cpu: registered to indicate activity on CPUs [ 145.227770] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 145.229164] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 145.265078] ashmem: initialized [ 145.433645] 9pnet: Installing 9P2000 support [ 145.700929] _warn_unseeded_randomness: 5 callbacks suppressed [ 145.701080] random: get_random_bytes called from init_oops_id+0x38/0x60 with crng_init=0 [ 145.748443] registered taskstats version 1 [ 145.749609] Running tests on trace events: [ 145.750149] Testing event initcall_finish: [ 145.753825] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 145.778322] OK [ 145.778834] Testing event initcall_start: OK [ 145.804779] Testing event initcall_level: OK [ 145.830980] Testing event sys_exit: OK [ 145.858941] Testing event sys_enter: OK [ 145.887054] Testing event ipi_exit: OK [ 145.910434] Testing event ipi_entry: OK [ 145.938717] Testing event ipi_raise: OK [ 145.969647] Testing event instruction_emulation: OK [ 145.994873] Testing event kvm_halt_poll_ns: OK [ 146.021360] Testing event kvm_age_page: OK [ 146.047058] Testing event kvm_fpu: OK [ 146.073059] Testing event kvm_mmio: OK [ 146.098902] Testing event kvm_ack_irq: OK [ 146.122367] Testing event kvm_set_irq: OK [ 146.146621] Testing event kvm_vcpu_wakeup: OK [ 146.173218] Testing event kvm_userspace_exit: OK [ 146.199332] Testing event kvm_timer_update_irq: OK [ 146.226804] Testing event kvm_toggle_cache: OK [ 146.250575] Testing event kvm_set_way_flush: OK [ 146.274454] Testing event kvm_test_age_hva: OK [ 146.301847] Testing event kvm_age_hva: OK [ 146.329284] Testing event kvm_set_spte_hva: OK [ 146.354634] Testing event kvm_unmap_hva_range: OK [ 146.381201] Testing event kvm_mmio_emulate: OK [ 146.407032] Testing event kvm_irq_line: OK [ 146.433181] Testing event kvm_access_fault: OK [ 146.458710] Testing event kvm_guest_fault: OK [ 146.482432] Testing event kvm_exit: OK [ 146.506470] Testing event kvm_entry: OK [ 146.530391] Testing event kvm_set_guest_debug: OK [ 146.554608] Testing event kvm_handle_sys_reg: OK [ 146.578610] Testing event trap_reg: OK [ 146.606897] Testing event kvm_arm_set_regset: OK [ 146.633590] Testing event kvm_arm_set_dreg32: OK [ 146.659613] Testing event kvm_arm_clear_debug: OK [ 146.686817] Testing event kvm_arm_setup_debug: OK [ 146.710563] Testing event kvm_hvc_arm64: OK [ 146.737244] Testing event kvm_wfx_arm64: OK [ 146.763320] Testing event vgic_update_irq_pending: OK [ 146.790822] Testing event task_rename: OK [ 146.817118] Testing event task_newtask: OK [ 146.842634] Testing event cpuhp_exit: OK [ 146.870380] Testing event cpuhp_multi_enter: OK [ 146.897640] Testing event cpuhp_enter: OK [ 146.923127] Testing event softirq_raise: OK [ 146.949294] Testing event softirq_exit: OK [ 146.975616] Testing event softirq_entry: OK [ 147.002613] Testing event irq_handler_exit: OK [ 147.029447] Testing event irq_handler_entry: OK [ 147.055219] Testing event signal_deliver: OK [ 147.081792] Testing event signal_generate: OK [ 147.107037] Testing event workqueue_execute_end: OK [ 147.133611] Testing event workqueue_execute_start: OK [ 147.161455] Testing event workqueue_activate_work: OK [ 147.189445] Testing event workqueue_queue_work: OK [ 147.215269] Testing event sched_wake_idle_without_ipi: OK [ 147.243161] Testing event sched_swap_numa: OK [ 147.269174] Testing event sched_stick_numa: OK [ 147.295503] Testing event sched_move_numa: OK [ 147.322611] Testing event sched_process_hang: OK [ 147.346617] Testing event sched_pi_setprio: OK [ 147.370441] Testing event sched_stat_runtime: [ 147.373764] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 147.399208] OK [ 147.400821] Testing event sched_stat_blocked: OK [ 147.427151] Testing event sched_stat_iowait: OK [ 147.453445] Testing event sched_stat_sleep: OK [ 147.479224] Testing event sched_stat_wait: OK [ 147.507417] Testing event sched_process_exec: OK [ 147.533222] Testing event sched_process_fork: OK [ 147.559181] Testing event sched_process_wait: OK [ 147.585659] Testing event sched_wait_task: OK [ 147.611007] Testing event sched_process_exit: OK [ 147.637616] Testing event sched_process_free: OK [ 147.663417] Testing event sched_migrate_task: OK [ 147.689651] Testing event sched_switch: OK [ 147.714989] Testing event sched_wakeup_new: OK [ 147.741155] Testing event sched_wakeup: OK [ 147.767365] Testing event sched_waking: OK [ 147.795386] Testing event sched_kthread_stop_ret: OK [ 147.821572] Testing event sched_kthread_stop: OK [ 147.847379] Testing event lock_acquired: OK [ 147.881116] Testing event lock_contended: OK [ 147.904601] Testing event lock_release: OK [ 147.941076] Testing event lock_acquire: OK [ 147.974415] Testing event console: OK [ 147.998635] Testing event rcu_barrier: OK [ 148.025182] Testing event rcu_torture_read: OK [ 148.051198] Testing event rcu_batch_end: OK [ 148.077836] Testing event rcu_invoke_kfree_callback: OK [ 148.103097] Testing event rcu_invoke_callback: OK [ 148.129263] Testing event rcu_batch_start: OK [ 148.155561] Testing event rcu_kfree_callback: OK [ 148.182612] Testing event rcu_callback: OK [ 148.206816] Testing event rcu_dyntick: OK [ 148.235616] Testing event rcu_fqs: OK [ 148.262840] Testing event rcu_quiescent_state_report: OK [ 148.286584] Testing event rcu_unlock_preempted_task: OK [ 148.310498] Testing event rcu_preempt_task: OK [ 148.334356] Testing event rcu_exp_funnel_lock: OK [ 148.361509] Testing event rcu_exp_grace_period: OK [ 148.390298] Testing event rcu_grace_period_init: OK [ 148.418105] Testing event rcu_future_grace_period: OK [ 148.445143] Testing event rcu_grace_period: OK [ 148.475198] Testing event rcu_utilization: OK [ 148.503466] Testing event swiotlb_bounced: OK [ 148.530778] Testing event tick_stop: OK [ 148.554501] Testing event itimer_expire: OK [ 148.578681] Testing event itimer_state: OK [ 148.605396] Testing event hrtimer_cancel: OK [ 148.634220] Testing event hrtimer_expire_exit: OK [ 148.661736] Testing event hrtimer_expire_entry: OK [ 148.686696] Testing event hrtimer_start: OK [ 148.713211] Testing event hrtimer_init: OK [ 148.739297] Testing event timer_cancel: OK [ 148.766969] Testing event timer_expire_exit: OK [ 148.793236] Testing event timer_expire_entry: OK [ 148.818775] Testing event timer_start: OK [ 148.847243] Testing event timer_init: OK [ 148.872956] Testing event alarmtimer_cancel: OK [ 148.898447] Testing event alarmtimer_start: OK [ 148.922565] Testing event alarmtimer_fired: OK [ 148.948889] Testing event alarmtimer_suspend: OK [ 148.974477] Testing event module_request: OK [ 148.998592] Testing event module_put: OK [ 149.025282] Testing event module_get: OK [ 149.051320] Testing event module_free: OK [ 149.078622] Testing event module_load: OK [ 149.102610] Testing event cgroup_transfer_tasks: OK [ 149.126752] Testing event cgroup_attach_task: OK [ 149.153409] Testing event cgroup_rename: OK [ 149.179328] Testing event cgroup_release: OK [ 149.206753] Testing event cgroup_rmdir: OK [ 149.230548] Testing event cgroup_mkdir: OK [ 149.257560] Testing event cgroup_remount: OK [ 149.283084] Testing event cgroup_destroy_root: OK [ 149.309862] Testing event cgroup_setup_root: OK [ 149.334942] Testing event irq_enable: OK [ 149.368676] Testing event irq_disable: OK [ 149.401866] Testing event ftrace_test_filter: OK [ 149.426162] Testing event dev_pm_qos_remove_request: OK [ 149.450591] Testing event dev_pm_qos_update_request: OK [ 149.474616] Testing event dev_pm_qos_add_request: OK [ 149.498647] Testing event pm_qos_update_flags: OK [ 149.522619] Testing event pm_qos_update_target: OK [ 149.546493] Testing event pm_qos_update_request_timeout: OK [ 149.573499] Testing event pm_qos_remove_request: OK [ 149.598991] Testing event pm_qos_update_request: OK [ 149.625827] Testing event pm_qos_add_request: OK [ 149.650932] Testing event power_domain_target: OK [ 149.674520] Testing event clock_set_rate: OK [ 149.698348] Testing event clock_disable: OK [ 149.722427] Testing event clock_enable: OK [ 149.746314] Testing event wakeup_source_deactivate: OK [ 149.770687] Testing event wakeup_source_activate: OK [ 149.797290] Testing event suspend_resume: OK [ 149.834690] Testing event device_pm_callback_end: OK [ 149.862101] Testing event device_pm_callback_start: OK [ 149.887311] Testing event cpu_frequency_limits: OK [ 149.913292] Testing event cpu_frequency: OK [ 149.939396] Testing event pstate_sample: OK [ 149.965531] Testing event powernv_throttle: OK [ 149.990518] Testing event cpu_idle: OK [ 150.015223] Testing event rpm_return_int: OK [ 150.041730] Testing event rpm_idle: OK [ 150.066996] Testing event rpm_resume: OK [ 150.093789] Testing event rpm_suspend: OK [ 150.118899] Testing event benchmark_event: OK [ 150.246399] Testing event xdp_devmap_xmit: OK [ 150.274837] Testing event xdp_cpumap_enqueue: OK [ 150.298677] Testing event xdp_cpumap_kthread: OK [ 150.325461] Testing event xdp_redirect_map_err: OK [ 150.354053] Testing event xdp_redirect_map: OK [ 150.381353] Testing event xdp_redirect_err: OK [ 150.407431] Testing event xdp_redirect: OK [ 150.434870] Testing event xdp_exception: OK [ 150.462122] Testing event rseq_ip_fixup: OK [ 150.487086] Testing event rseq_update: OK [ 150.514911] Testing event file_check_and_advance_wb_err: OK [ 150.540911] Testing event filemap_set_wb_err: OK [ 150.567194] Testing event mm_filemap_add_to_page_cache: OK [ 150.594907] Testing event mm_filemap_delete_from_page_cache: OK [ 150.622046] Testing event compact_retry: OK [ 150.647065] Testing event skip_task_reaping: OK [ 150.673581] Testing event finish_task_reaping: OK [ 150.699211] Testing event start_task_reaping: OK [ 150.725909] Testing event wake_reaper: OK [ 150.750975] Testing event mark_victim: OK [ 150.777534] Testing event reclaim_retry_zone: OK [ 150.803212] Testing event oom_score_adj_update: OK [ 150.829950] Testing event mm_lru_activate: OK [ 150.855030] Testing event mm_lru_insertion: OK [ 150.881340] Testing event mm_vmscan_inactive_list_is_low: OK [ 150.907390] Testing event mm_vmscan_lru_shrink_active: OK [ 150.934867] Testing event mm_vmscan_lru_shrink_inactive: OK [ 150.958643] Testing event mm_vmscan_writepage: OK [ 150.982652] Testing event mm_vmscan_lru_isolate: OK [ 151.009320] Testing event mm_shrink_slab_end: OK [ 151.035428] Testing event mm_shrink_slab_start: OK [ 151.062777] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 151.086740] Testing event mm_vmscan_memcg_reclaim_end: OK [ 151.113460] Testing event mm_vmscan_direct_reclaim_end: OK [ 151.139389] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 151.166789] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 151.193410] Testing event mm_vmscan_direct_reclaim_begin: OK [ 151.219361] Testing event mm_vmscan_wakeup_kswapd: OK [ 151.246892] Testing event mm_vmscan_kswapd_wake: OK [ 151.270473] Testing event mm_vmscan_kswapd_sleep: OK [ 151.297472] Testing event percpu_destroy_chunk: OK [ 151.323207] Testing event percpu_create_chunk: OK [ 151.351066] Testing event percpu_alloc_percpu_fail: OK [ 151.376763] Testing event percpu_free_percpu: OK [ 151.401792] Testing event percpu_alloc_percpu: OK [ 151.426633] Testing event mm_page_alloc_extfrag: OK [ 151.453683] Testing event mm_page_pcpu_drain: OK [ 151.479184] Testing event mm_page_alloc_zone_locked: OK [ 151.507637] Testing event mm_page_alloc: OK [ 151.533100] Testing event mm_page_free_batched: OK [ 151.559500] Testing event mm_page_free: OK [ 151.586706] Testing event kmem_cache_free: OK [ 151.610782] Testing event kfree: OK [ 151.637405] Testing event kmem_cache_alloc_node: OK [ 151.663191] Testing event kmalloc_node: OK [ 151.691258] Testing event kmem_cache_alloc: OK [ 151.717361] Testing event kmalloc: OK [ 151.745841] Testing event mm_compaction_kcompactd_wake: OK [ 151.773661] Testing event mm_compaction_wakeup_kcompactd: OK [ 151.798976] Testing event mm_compaction_kcompactd_sleep: OK [ 151.825513] Testing event mm_compaction_defer_reset: OK [ 151.850962] Testing event mm_compaction_defer_compaction: OK [ 151.877505] Testing event mm_compaction_deferred: OK [ 151.903133] Testing event mm_compaction_suitable: OK [ 151.929431] Testing event mm_compaction_finished: OK [ 151.955213] Testing event mm_compaction_try_to_compact_pages: OK [ 151.981942] Testing event mm_compaction_end: OK [ 152.006898] Testing event mm_compaction_begin: OK [ 152.033501] Testing event mm_compaction_migratepages: OK [ 152.059214] Testing event mm_compaction_isolate_freepages: OK [ 152.085891] Testing event mm_compaction_isolate_migratepages: OK [ 152.110924] Testing event mm_migrate_pages: OK [ 152.137493] Testing event mm_collapse_huge_page_swapin: OK [ 152.166330] Testing event mm_collapse_huge_page_isolate: OK [ 152.194264] Testing event mm_collapse_huge_page: OK [ 152.221507] Testing event mm_khugepaged_scan_pmd: OK [ 152.247123] Testing event test_pages_isolated: OK [ 152.273306] Testing event cma_release: OK [ 152.299388] Testing event cma_alloc: OK [ 152.326714] Testing event page_ref_unfreeze: OK [ 152.356705] Testing event page_ref_freeze: OK [ 152.385262] Testing event page_ref_mod_unless: OK [ 152.418171] Testing event page_ref_mod_and_return: OK [ 152.443231] Testing event page_ref_mod_and_test: OK [ 152.579235] Testing event page_ref_mod: OK [ 152.639677] Testing event page_ref_set: OK [ 152.670439] Testing event sb_clear_inode_writeback: OK [ 152.695373] Testing event sb_mark_inode_writeback: OK [ 152.721896] Testing event writeback_dirty_inode_enqueue: OK [ 152.747047] Testing event writeback_lazytime_iput: OK [ 152.773440] Testing event writeback_lazytime: OK [ 152.799518] Testing event writeback_single_inode: OK [ 152.826888] Testing event writeback_single_inode_start: OK [ 152.850462] Testing event writeback_wait_iff_congested: OK [ 152.874701] Testing event writeback_congestion_wait: OK [ 152.901329] Testing event writeback_sb_inodes_requeue: OK [ 152.927516] Testing event balance_dirty_pages: OK [ 152.954862] Testing event bdi_dirty_ratelimit: OK [ 152.981302] Testing event global_dirty_state: OK [ 153.007339] Testing event writeback_queue_io: OK [ 153.034844] Testing event wbc_writepage: OK [ 153.058579] Testing event writeback_bdi_register: OK [ 153.085424] Testing event writeback_wake_background: OK [ 153.111267] Testing event writeback_pages_written: OK [ 153.139161] Testing event writeback_wait: OK [ 153.165343] Testing event writeback_written: OK [ 153.191461] Testing event writeback_start: OK [ 153.218953] Testing event writeback_exec: OK [ 153.245302] Testing event writeback_queue: OK [ 153.271381] Testing event writeback_write_inode: OK [ 153.298778] Testing event writeback_write_inode_start: OK [ 153.322567] Testing event writeback_dirty_inode: OK [ 153.349356] Testing event writeback_dirty_inode_start: OK [ 153.375340] Testing event writeback_mark_inode_dirty: OK [ 153.402873] Testing event writeback_dirty_page: OK [ 153.426614] Testing event dax_writeback_one: OK [ 153.453517] Testing event dax_writeback_range_done: OK [ 153.479152] Testing event dax_writeback_range: OK [ 153.505863] Testing event dax_insert_mapping: OK [ 153.531036] Testing event dax_insert_pfn_mkwrite: OK [ 153.557443] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 153.583547] Testing event dax_load_hole: OK [ 153.610819] Testing event dax_pte_fault_done: OK [ 153.634908] Testing event dax_pte_fault: OK [ 153.661269] Testing event dax_pmd_insert_mapping: OK [ 153.686586] Testing event dax_pmd_load_hole_fallback: OK [ 153.713318] Testing event dax_pmd_load_hole: OK [ 153.738418] Testing event dax_pmd_fault_done: OK [ 153.762515] Testing event dax_pmd_fault: OK [ 153.786411] Testing event generic_add_lease: OK [ 153.810467] Testing event time_out_leases: OK [ 153.835695] Testing event generic_delete_lease: OK [ 153.863701] Testing event break_lease_unblock: OK [ 153.890976] Testing event break_lease_block: OK [ 153.917597] Testing event break_lease_noblock: OK [ 153.943157] Testing event flock_lock_inode: OK [ 153.969781] Testing event locks_remove_posix: OK [ 153.994999] Testing event fcntl_setlk: OK [ 154.021387] Testing event posix_lock_inode: OK [ 154.047237] Testing event locks_get_lock_context: OK [ 154.073894] Testing event block_rq_remap: OK [ 154.098760] Testing event block_bio_remap: OK [ 154.125585] Testing event block_split: OK [ 154.151211] Testing event block_unplug: OK [ 154.178994] Testing event block_plug: OK [ 154.204752] Testing event block_sleeprq: OK [ 154.229393] Testing event block_getrq: OK [ 154.254575] Testing event block_bio_queue: OK [ 154.281381] Testing event block_bio_frontmerge: OK [ 154.307308] Testing event block_bio_backmerge: OK [ 154.335138] Testing event block_bio_complete: OK [ 154.358593] Testing event block_bio_bounce: OK [ 154.382527] Testing event block_rq_issue: OK [ 154.406199] Testing event block_rq_insert: OK [ 154.433585] Testing event block_rq_complete: OK [ 154.458880] Testing event block_rq_requeue: OK [ 154.485483] Testing event block_dirty_buffer: OK [ 154.513527] Testing event block_touch_buffer: OK [ 154.541614] Testing event wbt_timer: OK [ 154.567162] Testing event wbt_step: OK [ 154.593830] Testing event wbt_lat: OK [ 154.619093] Testing event wbt_stat: OK [ 154.645621] Testing event gpio_value: OK [ 154.673972] Testing event gpio_direction: OK [ 154.701566] Testing event clk_set_duty_cycle_complete: OK [ 154.726607] Testing event clk_set_duty_cycle: OK [ 154.753575] Testing event clk_set_phase_complete: OK [ 154.779189] Testing event clk_set_phase: OK [ 154.805876] Testing event clk_set_parent_complete: OK [ 154.831141] Testing event clk_set_parent: OK [ 154.857513] Testing event clk_set_rate_complete: OK [ 154.883251] Testing event clk_set_rate: OK [ 154.909873] Testing event clk_unprepare_complete: OK [ 154.935181] Testing event clk_unprepare: OK [ 154.961561] Testing event clk_prepare_complete: OK [ 154.987158] Testing event clk_prepare: OK [ 155.013856] Testing event clk_disable_complete: OK [ 155.039162] Testing event clk_disable: OK [ 155.065470] Testing event clk_enable_complete: OK [ 155.091520] Testing event clk_enable: OK [ 155.118853] Testing event rpmh_send_msg: OK [ 155.142706] Testing event rpmh_tx_done: OK [ 155.169427] Testing event regulator_set_voltage_complete: OK [ 155.195225] Testing event regulator_set_voltage: OK [ 155.221902] Testing event regulator_disable_complete: OK [ 155.247008] Testing event regulator_disable: OK [ 155.273518] Testing event regulator_enable_complete: OK [ 155.299140] Testing event regulator_enable_delay: OK [ 155.325879] Testing event regulator_enable: OK [ 155.351026] Testing event urandom_read: OK [ 155.377274] Testing event random_read: OK [ 155.403288] Testing event extract_entropy_user: OK [ 155.430903] Testing event extract_entropy: OK [ 155.456701] Testing event get_random_bytes_arch: OK [ 155.481724] Testing event get_random_bytes: OK [ 155.506592] Testing event xfer_secondary_pool: OK [ 155.533532] Testing event add_disk_randomness: OK [ 155.559242] Testing event add_input_randomness: OK [ 155.585897] Testing event debit_entropy: OK [ 155.610957] Testing event push_to_pool: OK [ 155.637758] Testing event credit_entropy_bits: OK [ 155.665570] Testing event mix_pool_bytes_nolock: OK [ 155.693201] Testing event mix_pool_bytes: OK [ 155.718361] Testing event add_device_randomness: OK [ 155.743216] Testing event io_page_fault: OK [ 155.769211] Testing event unmap: OK [ 155.795445] Testing event map: OK [ 155.822610] Testing event detach_device_from_domain: OK [ 155.846656] Testing event attach_device_to_domain: OK [ 155.873466] Testing event remove_device_from_group: OK [ 155.899097] Testing event add_device_to_group: OK [ 155.925322] Testing event regcache_drop_region: OK [ 155.951317] Testing event regmap_async_complete_done: OK [ 155.977351] Testing event regmap_async_complete_start: OK [ 156.003493] Testing event regmap_async_io_complete: OK [ 156.030722] Testing event regmap_async_write_start: OK [ 156.054738] Testing event regmap_cache_bypass: OK [ 156.078551] Testing event regmap_cache_only: OK [ 156.105462] Testing event regcache_sync: OK [ 156.132934] Testing event regmap_hw_write_done: OK [ 156.159431] Testing event regmap_hw_write_start: OK [ 156.186167] Testing event regmap_hw_read_done: OK [ 156.210995] Testing event regmap_hw_read_start: OK [ 156.237464] Testing event regmap_reg_read_cache: OK [ 156.263176] Testing event regmap_reg_read: OK [ 156.289257] Testing event regmap_reg_write: OK [ 156.315576] Testing event dma_fence_wait_end: OK [ 156.342706] Testing event dma_fence_wait_start: OK [ 156.369255] Testing event dma_fence_signaled: OK [ 156.395467] Testing event dma_fence_enable_signal: OK [ 156.423425] Testing event dma_fence_destroy: OK [ 156.449158] Testing event dma_fence_init: OK [ 156.475518] Testing event dma_fence_emit: OK [ 156.502558] Testing event sync_timeline: OK [ 156.526654] Testing event spi_transfer_stop: OK [ 156.550619] Testing event spi_transfer_start: OK [ 156.577414] Testing event spi_message_done: OK [ 156.603208] Testing event spi_message_start: OK [ 156.629868] Testing event spi_message_submit: OK [ 156.654997] Testing event spi_controller_busy: OK [ 156.681513] Testing event spi_controller_idle: OK [ 156.707175] Testing event mdio_access: OK [ 156.733834] Testing event rtc_timer_fired: OK [ 156.758993] Testing event rtc_timer_dequeue: OK [ 156.785764] Testing event rtc_timer_enqueue: OK [ 156.811492] Testing event rtc_read_offset: OK [ 156.838803] Testing event rtc_set_offset: OK [ 156.862596] Testing event rtc_alarm_irq_enable: OK [ 156.889731] Testing event rtc_irq_set_state: OK [ 156.915047] Testing event rtc_irq_set_freq: OK [ 156.941353] Testing event rtc_read_alarm: OK [ 156.966826] Testing event rtc_set_alarm: OK [ 156.990698] Testing event rtc_read_time: OK [ 157.017327] Testing event rtc_set_time: OK [ 157.043295] Testing event memory_failure_event: OK [ 157.069757] Testing event aer_event: OK [ 157.095073] Testing event non_standard_event: OK [ 157.121281] Testing event arm_event: OK [ 157.147556] Testing event mc_event: OK [ 157.174586] Testing event binder_return: OK [ 157.198689] Testing event binder_command: OK [ 157.222596] Testing event binder_unmap_kernel_end: OK [ 157.249450] Testing event binder_unmap_kernel_start: OK [ 157.275256] Testing event binder_unmap_user_end: OK [ 157.301911] Testing event binder_unmap_user_start: OK [ 157.326979] Testing event binder_alloc_page_end: OK [ 157.353589] Testing event binder_alloc_page_start: OK [ 157.378983] Testing event binder_free_lru_end: OK [ 157.405382] Testing event binder_free_lru_start: OK [ 157.430744] Testing event binder_alloc_lru_end: OK [ 157.457332] Testing event binder_alloc_lru_start: OK [ 157.483307] Testing event binder_update_page_range: OK [ 157.510592] Testing event binder_transaction_failed_buffer_release: OK [ 157.535022] Testing event binder_transaction_buffer_release: OK [ 157.559697] Testing event binder_transaction_alloc_buf: OK [ 157.585873] Testing event binder_transaction_fd_recv: OK [ 157.613540] Testing event binder_transaction_fd_send: OK [ 157.639119] Testing event binder_transaction_ref_to_ref: OK [ 157.665435] Testing event binder_transaction_ref_to_node: OK [ 157.691569] Testing event binder_transaction_node_to_ref: OK [ 157.718821] Testing event binder_transaction_received: OK [ 157.742632] Testing event binder_transaction: OK [ 157.769476] Testing event binder_wait_for_work: OK [ 157.795195] Testing event binder_read_done: OK [ 157.821862] Testing event binder_write_done: OK [ 157.847042] Testing event binder_ioctl_done: OK [ 157.873542] Testing event binder_unlock: OK [ 157.898978] Testing event binder_locked: OK [ 157.925243] Testing event binder_lock: OK [ 157.950570] Testing event binder_ioctl: OK [ 157.974689] Testing event br_fdb_update: OK [ 157.998432] Testing event fdb_delete: OK [ 158.023565] Testing event br_fdb_external_learn_add: OK [ 158.049758] Testing event br_fdb_add: OK [ 158.075120] Testing event qdisc_dequeue: OK [ 158.101382] Testing event fib_table_lookup: OK [ 158.127538] Testing event tcp_probe: OK [ 158.153414] Testing event tcp_retransmit_synack: OK [ 158.179328] Testing event tcp_rcv_space_adjust: OK [ 158.205763] Testing event tcp_destroy_sock: OK [ 158.230891] Testing event tcp_receive_reset: OK [ 158.257388] Testing event tcp_send_reset: OK [ 158.283588] Testing event tcp_retransmit_skb: OK [ 158.310677] Testing event udp_fail_queue_rcv_skb: OK [ 158.334977] Testing event inet_sock_set_state: OK [ 158.360658] Testing event sock_exceed_buf_limit: OK [ 158.386053] Testing event sock_rcvqueue_full: OK [ 158.411181] Testing event napi_poll: OK [ 158.439233] Testing event netif_rx_ni_entry: OK [ 158.465265] Testing event netif_rx_entry: OK [ 158.493086] Testing event netif_receive_skb_list_entry: OK [ 158.520619] Testing event netif_receive_skb_entry: OK [ 158.545187] Testing event napi_gro_receive_entry: OK [ 158.570763] Testing event napi_gro_frags_entry: OK [ 158.597578] Testing event netif_rx: OK [ 158.623013] Testing event netif_receive_skb: OK [ 158.649433] Testing event net_dev_queue: OK [ 158.675165] Testing event net_dev_xmit: OK [ 158.701400] Testing event net_dev_start_xmit: OK [ 158.727495] Testing event skb_copy_datagram_iovec: OK [ 158.754842] Testing event consume_skb: OK [ 158.778868] Testing event kfree_skb: OK [ 158.805633] Testing event 9p_protocol_dump: OK [ 158.838990] Testing event 9p_client_res: OK [ 158.865708] Testing event 9p_client_req: OK [ 158.891003] Running tests on trace event systems: [ 158.891521] Testing event system 9p: OK [ 158.928797] Testing event system skb: OK [ 158.957120] Testing event system net: OK [ 158.991368] Testing event system napi: OK [ 159.019672] Testing event system sock: OK [ 159.050800] Testing event system udp: OK [ 159.077551] Testing event system tcp: OK [ 159.110328] Testing event system fib: OK [ 159.138035] Testing event system qdisc: OK [ 159.163572] Testing event system bridge: OK [ 159.193015] Testing event system binder: OK [ 159.240343] Testing event system ras: OK [ 159.270352] Testing event system rtc: OK [ 159.312943] Testing event system mdio: OK [ 159.341124] Testing event system spi: OK [ 159.374268] Testing event system sync_trace: OK [ 159.402046] Testing event system dma_fence: OK [ 159.434241] Testing event system regmap: OK [ 159.473531] Testing event system iommu: OK [ 159.505720] Testing event system random: OK [ 159.544711] Testing event system regulator: OK [ 159.577282] Testing event system rpmh: OK [ 159.606278] Testing event system clk: OK [ 159.647032] Testing event system gpio: OK [ 159.677483] Testing event system wbt: OK [ 159.709364] Testing event system block: OK [ 159.749394] Testing event system filelock: OK [ 159.782814] Testing event system fs_dax: OK [ 159.818451] Testing event system writeback: OK [ 159.866588] Testing event system page_ref: OK [ 160.055701] Testing event system cma: OK [ 160.088675] Testing event system page_isolation: OK [ 160.115045] Testing event system huge_memory: OK [ 160.146607] Testing event system migrate: OK [ 160.173720] Testing event system compaction: OK [ 160.211513] Testing event system kmem: OK [ 160.248841] Testing event system percpu: OK [ 160.280972] Testing event system vmscan: OK [ 160.318531] Testing event system pagemap: OK [ 160.348668] Testing event system oom: OK [ 160.382032] Testing event system filemap: OK [ 160.411031] Testing event system rseq: OK [ 160.440924] Testing event system xdp: OK [ 160.476437] Testing event system benchmark: OK [ 160.598159] Testing event system rpm: OK [ 160.631132] Testing event system power: OK [ 160.685072] Testing event system test: OK [ 160.711425] Testing event system preemptirq: OK [ 160.764961] Testing event system cgroup: OK [ 160.799706] Testing event system module: OK [ 160.829017] Testing event system alarmtimer: OK [ 160.859601] Testing event system timer: OK [ 160.899347] Testing event system swiotlb: OK [ 160.927468] Testing event system rcu: OK [ 160.981326] Testing event system printk: OK [ 161.009148] Testing event system lock: OK [ 161.083361] Testing event system sched: OK [ 161.128723] Testing event system workqueue: OK [ 161.169342] Testing event system signal: OK [ 161.195699] Testing event system irq: OK [ 161.227406] Testing event system cpuhp: OK [ 161.257254] Testing event system task: OK [ 161.286721] Testing event system kvm: OK [ 161.338271] Testing event system emulation: OK [ 161.363378] Testing event system ipi: OK [ 161.396665] Testing event system raw_syscalls: OK [ 161.423473] Testing event system initcall: OK [ 161.453432] Running tests on all trace events: [ 161.453911] Testing all events: OK [ 163.422924] ftrace_replace_code: 1 [ 178.356930] hrtimer: interrupt took 11806304 ns [ 340.909379] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 340.929086] Running tests again, along with the function tracer [ 340.936967] Running tests on trace events: [ 340.953883] Testing event initcall_finish: OK [ 341.535525] Testing event initcall_start: OK [ 342.231661] Testing event initcall_level: OK [ 342.846502] Testing event sys_exit: OK [ 343.585420] Testing event sys_enter: OK [ 343.943526] Testing event ipi_exit: OK [ 344.536663] Testing event ipi_entry: OK [ 345.159449] Testing event ipi_raise: OK [ 345.880705] Testing event instruction_emulation: OK [ 346.554348] Testing event kvm_halt_poll_ns: OK [ 347.085537] Testing event kvm_age_page: OK [ 347.798272] Testing event kvm_fpu: OK [ 348.493933] Testing event kvm_mmio: OK [ 349.074194] Testing event kvm_ack_irq: OK [ 349.796583] Testing event kvm_set_irq: OK [ 350.483631] Testing event kvm_vcpu_wakeup: OK [ 351.080971] Testing event kvm_userspace_exit: OK [ 351.779584] Testing event kvm_timer_update_irq: OK [ 352.615607] Testing event kvm_toggle_cache: OK [ 353.213010] Testing event kvm_set_way_flush: OK [ 353.795649] Testing event kvm_test_age_hva: OK [ 354.816805] Testing event kvm_age_hva: OK [ 355.499630] Testing event kvm_set_spte_hva: OK [ 355.927644] Testing event kvm_unmap_hva_range: OK [ 356.808854] Testing event kvm_mmio_emulate: OK [ 357.328606] Testing event kvm_irq_line: OK [ 358.087458] Testing event kvm_access_fault: OK [ 358.972663] Testing event kvm_guest_fault: OK [ 359.983637] Testing event kvm_exit: OK [ 360.767691] Testing event kvm_entry: OK [ 361.319633] Testing event kvm_set_guest_debug: OK [ 361.942212] Testing event kvm_handle_sys_reg: OK [ 362.658443] Testing event trap_reg: OK [ 363.315793] Testing event kvm_arm_set_regset: OK [ 363.834454] Testing event kvm_arm_set_dreg32: OK [ 364.469336] Testing event kvm_arm_clear_debug: OK [ 364.951515] Testing event kvm_arm_setup_debug: OK [ 365.647492] Testing event kvm_hvc_arm64: OK [ 366.239608] Testing event kvm_wfx_arm64: OK [ 366.771790] Testing event vgic_update_irq_pending: OK [ 367.525069] Testing event task_rename: OK [ 368.163811] Testing event task_newtask: OK [ 368.836619] Testing event cpuhp_exit: OK [ 369.565453] Testing event cpuhp_multi_enter: OK [ 370.149481] Testing event cpuhp_enter: OK [ 370.783230] Testing event softirq_raise: OK [ 371.291562] Testing event softirq_exit: OK [ 371.996835] Testing event softirq_entry: OK [ 372.699744] Testing event irq_handler_exit: OK [ 373.380955] Testing event irq_handler_entry: OK [ 374.102567] Testing event signal_deliver: OK [ 374.675833] Testing event signal_generate: OK [ 375.475823] Testing event workqueue_execute_end: OK [ 375.982089] Testing event workqueue_execute_start: OK [ 376.745766] Testing event workqueue_activate_work: OK [ 377.185299] Testing event workqueue_queue_work: OK [ 377.715647] Testing event sched_wake_idle_without_ipi: OK [ 378.205848] Testing event sched_swap_numa: OK [ 378.726712] Testing event sched_stick_numa: OK [ 379.716748] Testing event sched_move_numa: OK [ 380.184862] Testing event sched_process_hang: OK [ 380.703697] Testing event sched_pi_setprio: OK [ 381.559786] Testing event sched_stat_runtime: OK [ 382.179545] Testing event sched_stat_blocked: OK [ 382.766329] Testing event sched_stat_iowait: OK [ 383.520807] Testing event sched_stat_sleep: OK [ 384.207728] Testing event sched_stat_wait: OK [ 384.847777] Testing event sched_process_exec: OK [ 385.495622] Testing event sched_process_fork: OK [ 385.923656] Testing event sched_process_wait: OK [ 386.530449] Testing event sched_wait_task: OK [ 387.240638] Testing event sched_process_exit: OK [ 387.922578] Testing event sched_process_free: OK [ 388.606336] Testing event sched_migrate_task: OK [ 389.282213] Testing event sched_switch: OK [ 390.055382] Testing event sched_wakeup_new: OK [ 390.673236] Testing event sched_wakeup: OK [ 391.313568] Testing event sched_waking: OK [ 392.105285] Testing event sched_kthread_stop_ret: OK [ 392.799716] Testing event sched_kthread_stop: OK [ 393.466231] Testing event lock_acquired: OK [ 394.359590] Testing event lock_contended: OK [ 394.824155] Testing event lock_release: OK [ 395.509792] Testing event lock_acquire: OK [ 396.175187] Testing event console: OK [ 396.893618] Testing event rcu_barrier: OK [ 397.538000] Testing event rcu_torture_read: OK [ 398.176935] Testing event rcu_batch_end: OK [ 398.909827] Testing event rcu_invoke_kfree_callback: OK [ 399.358511] Testing event rcu_invoke_callback: OK [ 399.943720] Testing event rcu_batch_start: OK [ 400.962329] Testing event rcu_kfree_callback: OK [ 401.615409] Testing event rcu_callback: OK [ 402.491822] Testing event rcu_dyntick: OK [ 403.200647] Testing event rcu_fqs: OK [ 404.038679] Testing event rcu_quiescent_state_report: OK [ 404.816791] Testing event rcu_unlock_preempted_task: OK [ 405.341542] Testing event rcu_preempt_task: OK [ 405.959311] Testing event rcu_exp_funnel_lock: OK [ 406.987600] Testing event rcu_exp_grace_period: OK [ 407.979745] Testing event rcu_grace_period_init: OK [ 408.634791] Testing event rcu_future_grace_period: OK [ 409.153622] Testing event rcu_grace_period: OK [ 409.859045] Testing event rcu_utilization: OK [ 410.532628] Testing event swiotlb_bounced: OK [ 411.383738] Testing event tick_stop: OK [ 412.065083] Testing event itimer_expire: OK [ 412.631808] Testing event itimer_state: OK [ 413.367764] Testing event hrtimer_cancel: OK [ 414.076857] Testing event hrtimer_expire_exit: OK [ 414.941247] Testing event hrtimer_expire_entry: OK [ 415.765222] Testing event hrtimer_start: OK [ 416.263565] Testing event hrtimer_init: OK [ 417.008980] Testing event timer_cancel: OK [ 417.659706] Testing event timer_expire_exit: OK [ 418.144634] Testing event timer_expire_entry: OK [ 419.155765] Testing event timer_start: OK [ 419.935389] Testing event timer_init: OK [ 420.680756] Testing event alarmtimer_cancel: OK [ 421.200883] Testing event alarmtimer_start: OK [ 422.046380] Testing event alarmtimer_fired: OK [ 422.597815] Testing event alarmtimer_suspend: OK [ 423.343778] Testing event module_request: OK [ 423.775881] Testing event module_put: OK [ 424.331705] Testing event module_get: OK [ 424.999611] Testing event module_free: OK [ 425.811934] Testing event module_load: OK [ 426.338149] Testing event cgroup_transfer_tasks: OK [ 427.175529] Testing event cgroup_attach_task: OK [ 427.821419] Testing event cgroup_rename: OK [ 428.668745] Testing event cgroup_release: OK [ 429.216892] Testing event cgroup_rmdir: OK [ 429.744232] Testing event cgroup_mkdir: OK [ 430.577928] Testing event cgroup_remount: OK [ 431.170290] Testing event cgroup_destroy_root: OK [ 431.777708] Testing event cgroup_setup_root: OK [ 432.497865] Testing event irq_enable: OK [ 433.670649] Testing event irq_disable: OK [ 434.610366] Testing event ftrace_test_filter: OK [ 435.193092] Testing event dev_pm_qos_remove_request: OK [ 435.903500] Testing event dev_pm_qos_update_request: OK [ 436.601218] Testing event dev_pm_qos_add_request: OK [ 437.537289] Testing event pm_qos_update_flags: OK [ 438.030306] Testing event pm_qos_update_target: OK [ 438.621087] Testing event pm_qos_update_request_timeout: OK [ 439.107814] Testing event pm_qos_remove_request: OK [ 439.755787] Testing event pm_qos_update_request: OK [ 440.511840] Testing event pm_qos_add_request: OK [ 441.139644] Testing event power_domain_target: OK [ 441.721138] Testing event clock_set_rate: OK [ 442.377090] Testing event clock_disable: OK [ 442.803624] Testing event clock_enable: OK [ 443.631576] Testing event wakeup_source_deactivate: OK [ 444.573692] Testing event wakeup_source_activate: OK [ 445.109866] Testing event suspend_resume: OK [ 445.875548] Testing event device_pm_callback_end: OK [ 446.427426] Testing event device_pm_callback_start: OK [ 447.117719] Testing event cpu_frequency_limits: OK [ 447.848608] Testing event cpu_frequency: OK [ 448.598138] Testing event pstate_sample: OK [ 449.203636] Testing event powernv_throttle: OK [ 449.613372] Testing event cpu_idle: OK [ 450.362112] Testing event rpm_return_int: OK [ 450.842588] Testing event rpm_idle: OK [ 451.339693] Testing event rpm_resume: OK [ 451.976664] Testing event rpm_suspend: OK [ 452.439751] Testing event benchmark_event: OK [ 453.463686] Testing event xdp_devmap_xmit: OK [ 454.087743] Testing event xdp_cpumap_enqueue: OK [ 454.613299] Testing event xdp_cpumap_kthread: OK [ 455.049868] Testing event xdp_redirect_map_err: OK [ 455.819407] Testing event xdp_redirect_map: OK [ 456.382635] Testing event xdp_redirect_err: OK [ 456.886480] Testing event xdp_redirect: OK [ 457.519664] Testing event xdp_exception: OK [ 458.267495] Testing event rseq_ip_fixup: OK [ 459.069640] Testing event rseq_update: OK [ 459.783507] Testing event file_check_and_advance_wb_err: OK [ 460.509682] Testing event filemap_set_wb_err: OK [ 461.021150] Testing event mm_filemap_add_to_page_cache: OK [ 461.630049] Testing event mm_filemap_delete_from_page_cache: OK [ 462.111406] Testing event compact_retry: OK [ 462.469103] Testing event skip_task_reaping: OK [ 463.011447] Testing event finish_task_reaping: OK [ 463.723372] Testing event start_task_reaping: OK [ 464.047410] Testing event wake_reaper: OK [ 464.783684] Testing event mark_victim: OK [ 465.219505] Testing event reclaim_retry_zone: OK [ 465.967663] Testing event oom_score_adj_update: OK [ 466.463784] Testing event mm_lru_activate: OK [ 467.051480] Testing event mm_lru_insertion: OK [ 468.043705] Testing event mm_vmscan_inactive_list_is_low: OK [ 468.549904] Testing event mm_vmscan_lru_shrink_active: OK [ 469.223815] Testing event mm_vmscan_lru_shrink_inactive: OK [ 469.930083] Testing event mm_vmscan_writepage: OK [ 470.605290] Testing event mm_vmscan_lru_isolate: OK [ 471.198450] Testing event mm_shrink_slab_end: OK [ 471.944876] Testing event mm_shrink_slab_start: OK [ 472.615788] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 473.181212] Testing event mm_vmscan_memcg_reclaim_end: OK [ 473.723638] Testing event mm_vmscan_direct_reclaim_end: OK [ 474.331724] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 474.895807] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 475.586582] Testing event mm_vmscan_direct_reclaim_begin: OK [ 476.318062] Testing event mm_vmscan_wakeup_kswapd: OK [ 476.970476] Testing event mm_vmscan_kswapd_wake: OK [ 477.758696] Testing event mm_vmscan_kswapd_sleep: OK [ 478.420907] Testing event percpu_destroy_chunk: OK [ 479.191611] Testing event percpu_create_chunk: OK [ 479.837960] Testing event percpu_alloc_percpu_fail: OK [ 480.558378] Testing event percpu_free_percpu: OK [ 481.204896] Testing event percpu_alloc_percpu: OK [ 482.097457] Testing event mm_page_alloc_extfrag: OK [ 482.787761] Testing event mm_page_pcpu_drain: OK [ 483.242115] Testing event mm_page_alloc_zone_locked: OK [ 483.906192] Testing event mm_page_alloc: OK [ 484.645447] Testing event mm_page_free_batched: OK [ 485.298570] Testing event mm_page_free: OK [ 485.842318] Testing event kmem_cache_free: OK [ 486.214754] Testing event kfree: OK [ 486.653422] Testing event kmem_cache_alloc_node: OK [ 487.335604] Testing event kmalloc_node: OK [ 488.023783] Testing event kmem_cache_alloc: OK [ 488.650414] Testing event kmalloc: OK [ 489.546392] Testing event mm_compaction_kcompactd_wake: OK [ 490.151721] Testing event mm_compaction_wakeup_kcompactd: OK [ 490.699517] Testing event mm_compaction_kcompactd_sleep: OK [ 491.459761] Testing event mm_compaction_defer_reset: OK [ 492.079791] Testing event mm_compaction_defer_compaction: OK [ 493.151682] Testing event mm_compaction_deferred: OK [ 493.896626] Testing event mm_compaction_suitable: OK [ 494.572888] Testing event mm_compaction_finished: OK [ 495.047135] Testing event mm_compaction_try_to_compact_pages: OK [ 495.625117] Testing event mm_compaction_end: OK [ 496.135559] Testing event mm_compaction_begin: OK [ 496.859830] Testing event mm_compaction_migratepages: OK [ 497.527783] Testing event mm_compaction_isolate_freepages: OK [ 498.368976] Testing event mm_compaction_isolate_migratepages: OK [ 499.133736] Testing event mm_migrate_pages: OK [ 499.655807] Testing event mm_collapse_huge_page_swapin: OK [ 500.445891] Testing event mm_collapse_huge_page_isolate: OK [ 501.266565] Testing event mm_collapse_huge_page: OK [ 501.761836] Testing event mm_khugepaged_scan_pmd: OK [ 502.331405] Testing event test_pages_isolated: OK [ 502.950365] Testing event cma_release: OK [ 503.600826] Testing event cma_alloc: OK [ 504.058305] Testing event page_ref_unfreeze: OK [ 505.090366] Testing event page_ref_freeze: OK [ 505.683816] Testing event page_ref_mod_unless: OK [ 506.457204] Testing event page_ref_mod_and_return: OK [ 507.359815] Testing event page_ref_mod_and_test: OK [ 509.498164] Testing event page_ref_mod: OK [ 510.677064] Testing event page_ref_set: OK [ 511.831776] Testing event sb_clear_inode_writeback: OK [ 512.467354] Testing event sb_mark_inode_writeback: OK [ 513.196778] Testing event writeback_dirty_inode_enqueue: OK [ 513.743657] Testing event writeback_lazytime_iput: OK [ 514.340976] Testing event writeback_lazytime: OK [ 515.081130] Testing event writeback_single_inode: OK [ 515.650617] Testing event writeback_single_inode_start: OK [ 516.297167] Testing event writeback_wait_iff_congested: OK [ 516.829054] Testing event writeback_congestion_wait: OK [ 517.638529] Testing event writeback_sb_inodes_requeue: OK [ 518.253554] Testing event balance_dirty_pages: OK [ 518.909679] Testing event bdi_dirty_ratelimit: OK [ 519.587822] Testing event global_dirty_state: OK [ 520.287668] Testing event writeback_queue_io: OK [ 521.040954] Testing event wbc_writepage: OK [ 521.736661] Testing event writeback_bdi_register: OK [ 522.543401] Testing event writeback_wake_background: OK [ 523.044768] Testing event writeback_pages_written: OK [ 523.769192] Testing event writeback_wait: OK [ 524.475735] Testing event writeback_written: OK [ 525.103699] Testing event writeback_start: OK [ 525.670707] Testing event writeback_exec: OK [ 526.207448] Testing event writeback_queue: OK [ 526.781520] Testing event writeback_write_inode: OK [ 527.543521] Testing event writeback_write_inode_start: OK [ 528.001449] Testing event writeback_dirty_inode: OK [ 528.531695] Testing event writeback_dirty_inode_start: OK [ 529.226603] Testing event writeback_mark_inode_dirty: OK [ 529.892950] Testing event writeback_dirty_page: OK [ 530.569125] Testing event dax_writeback_one: OK [ 531.232927] Testing event dax_writeback_range_done: OK [ 531.896737] Testing event dax_writeback_range: OK [ 532.511782] Testing event dax_insert_mapping: OK [ 532.988774] Testing event dax_insert_pfn_mkwrite: OK [ 533.600617] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 534.483819] Testing event dax_load_hole: OK [ 535.078473] Testing event dax_pte_fault_done: OK [ 535.493436] Testing event dax_pte_fault: OK [ 536.169428] Testing event dax_pmd_insert_mapping: OK [ 536.760623] Testing event dax_pmd_load_hole_fallback: OK [ 537.678680] Testing event dax_pmd_load_hole: OK [ 538.395747] Testing event dax_pmd_fault_done: OK [ 539.167773] Testing event dax_pmd_fault: OK [ 539.813955] Testing event generic_add_lease: OK [ 540.591696] Testing event time_out_leases: OK [ 541.223358] Testing event generic_delete_lease: OK [ 541.943674] Testing event break_lease_unblock: OK [ 542.793252] Testing event break_lease_block: OK [ 543.303657] Testing event break_lease_noblock: OK [ 543.915493] Testing event flock_lock_inode: OK [ 544.443781] Testing event locks_remove_posix: OK [ 545.063478] Testing event fcntl_setlk: OK [ 545.758913] Testing event posix_lock_inode: OK [ 546.275811] Testing event locks_get_lock_context: OK [ 546.746373] Testing event block_rq_remap: OK [ 547.396887] Testing event block_bio_remap: OK [ 548.055591] Testing event block_split: OK [ 549.064646] Testing event block_unplug: OK [ 549.629182] Testing event block_plug: OK [ 550.131833] Testing event block_sleeprq: OK [ 550.741448] Testing event block_getrq: OK [ 551.437344] Testing event block_bio_queue: OK [ 552.161362] Testing event block_bio_frontmerge: OK [ 553.143465] Testing event block_bio_backmerge: OK [ 553.728921] Testing event block_bio_complete: OK [ 554.671516] Testing event block_bio_bounce: OK [ 555.427819] Testing event block_rq_issue: OK [ 556.162136] Testing event block_rq_insert: OK [ 557.015441] Testing event block_rq_complete: OK [ 557.500850] Testing event block_rq_requeue: OK [ 558.199775] Testing event block_dirty_buffer: OK [ 558.759649] Testing event block_touch_buffer: OK [ 559.335755] Testing event wbt_timer: OK [ 559.898006] Testing event wbt_step: OK [ 560.712653] Testing event wbt_lat: OK [ 561.373274] Testing event wbt_stat: OK [ 562.247819] Testing event gpio_value: OK [ 562.688940] Testing event gpio_direction: OK [ 563.353457] Testing event clk_set_duty_cycle_complete: OK [ 563.991655] Testing event clk_set_duty_cycle: OK [ 564.561483] Testing event clk_set_phase_complete: OK [ 564.962193] Testing event clk_set_phase: OK [ 565.635818] Testing event clk_set_parent_complete: OK [ 566.610015] Testing event clk_set_parent: OK [ 567.113396] Testing event clk_set_rate_complete: OK [ 567.617374] Testing event clk_set_rate: OK [ 568.220699] Testing event clk_unprepare_complete: OK [ 568.731790] Testing event clk_unprepare: OK [ 569.363726] Testing event clk_prepare_complete: OK [ 570.110421] Testing event clk_prepare: OK [ 570.689713] Testing event clk_disable_complete: OK [ 571.267583] Testing event clk_disable: OK [ 571.907473] Testing event clk_enable_complete: OK [ 572.457093] Testing event clk_enable: OK [ 573.191754] Testing event rpmh_send_msg: OK [ 573.948974] Testing event rpmh_tx_done: OK [ 575.030421] Testing event regulator_set_voltage_complete: OK [ 575.910391] Testing event regulator_set_voltage: OK [ 576.637179] Testing event regulator_disable_complete: OK [ 577.305915] Testing event regulator_disable: OK [ 578.097013] Testing event regulator_enable_complete: OK [ 578.621927] Testing event regulator_enable_delay: OK [ 579.293765] Testing event regulator_enable: OK [ 579.773575] Testing event urandom_read: OK [ 580.372653] Testing event random_read: OK [ 580.914733] Testing event extract_entropy_user: OK [ 581.721305] Testing event extract_entropy: OK [ 582.473165] Testing event get_random_bytes_arch: OK [ 583.055756] Testing event get_random_bytes: OK [ 583.764659] Testing event xfer_secondary_pool: OK [ 584.437674] Testing event add_disk_randomness: OK [ 585.048582] Testing event add_input_randomness: OK [ 585.835786] Testing event debit_entropy: OK [ 586.523782] Testing event push_to_pool: OK [ 587.393367] Testing event credit_entropy_bits: OK [ 587.859367] Testing event mix_pool_bytes_nolock: OK [ 588.776662] Testing event mix_pool_bytes: OK [ 589.381839] Testing event add_device_randomness: OK [ 590.072704] Testing event io_page_fault: OK [ 590.784697] Testing event unmap: OK [ 591.619752] Testing event map: OK [ 592.231729] Testing event detach_device_from_domain: OK [ 592.686560] Testing event attach_device_to_domain: OK [ 593.263764] Testing event remove_device_from_group: OK [ 593.833759] Testing event add_device_to_group: OK [ 594.412594] Testing event regcache_drop_region: OK [ 594.979095] Testing event regmap_async_complete_done: OK [ 595.525232] Testing event regmap_async_complete_start: OK [ 596.097877] Testing event regmap_async_io_complete: OK [ 596.707807] Testing event regmap_async_write_start: OK [ 597.481588] Testing event regmap_cache_bypass: OK [ 597.860960] Testing event regmap_cache_only: OK [ 598.474672] Testing event regcache_sync: OK [ 599.240326] Testing event regmap_hw_write_done: OK [ 600.057104] Testing event regmap_hw_write_start: OK [ 601.053798] Testing event regmap_hw_read_done: OK [ 601.907666] Testing event regmap_hw_read_start: OK [ 602.463486] Testing event regmap_reg_read_cache: OK [ 603.229618] Testing event regmap_reg_read: OK [ 603.895790] Testing event regmap_reg_write: OK [ 604.635363] Testing event dma_fence_wait_end: OK [ 605.305315] Testing event dma_fence_wait_start: OK [ 605.780654] Testing event dma_fence_signaled: OK [ 606.364015] Testing event dma_fence_enable_signal: OK [ 607.097405] Testing event dma_fence_destroy: OK [ 607.689041] Testing event dma_fence_init: OK [ 608.306238] Testing event dma_fence_emit: OK [ 609.025706] Testing event sync_timeline: OK [ 609.651738] Testing event spi_transfer_stop: OK [ 610.473157] Testing event spi_transfer_start: OK [ 610.906068] Testing event spi_message_done: OK [ 611.296607] Testing event spi_message_start: OK [ 612.101881] Testing event spi_message_submit: OK [ 612.871821] Testing event spi_controller_busy: OK [ 613.431741] Testing event spi_controller_idle: OK [ 613.969132] Testing event mdio_access: OK [ 614.435745] Testing event rtc_timer_fired: OK [ 614.963578] Testing event rtc_timer_dequeue: OK [ 615.772670] Testing event rtc_timer_enqueue: OK [ 616.319790] Testing event rtc_read_offset: OK [ 616.897705] Testing event rtc_set_offset: OK [ 617.571564] Testing event rtc_alarm_irq_enable: OK [ 618.196927] Testing event rtc_irq_set_state: OK [ 618.949699] Testing event rtc_irq_set_freq: OK [ 619.468647] Testing event rtc_read_alarm: OK [ 620.003788] Testing event rtc_set_alarm: OK [ 620.620868] Testing event rtc_read_time: OK [ 621.451582] Testing event rtc_set_time: OK [ 622.305577] Testing event memory_failure_event: OK [ 623.117390] Testing event aer_event: OK [ 623.719609] Testing event non_standard_event: OK [ 624.375730] Testing event arm_event: OK [ 625.446044] Testing event mc_event: OK [ 626.132636] Testing event binder_return: OK [ 626.791776] Testing event binder_command: OK [ 627.480868] Testing event binder_unmap_kernel_end: OK [ 627.971789] Testing event binder_unmap_kernel_start: OK [ 628.607636] Testing event binder_unmap_user_end: OK [ 629.107400] Testing event binder_unmap_user_start: OK [ 629.809257] Testing event binder_alloc_page_end: OK [ 630.551328] Testing event binder_alloc_page_start: OK [ 631.271712] Testing event binder_free_lru_end: OK [ 631.989029] Testing event binder_free_lru_start: OK [ 632.542399] Testing event binder_alloc_lru_end: OK [ 633.240969] Testing event binder_alloc_lru_start: OK [ 633.622106] Testing event binder_update_page_range: OK [ 634.060666] Testing event binder_transaction_failed_buffer_release: OK [ 634.700705] Testing event binder_transaction_buffer_release: OK [ 635.499400] Testing event binder_transaction_alloc_buf: OK [ 636.192690] Testing event binder_transaction_fd_recv: OK [ 636.653860] Testing event binder_transaction_fd_send: OK [ 637.454442] Testing event binder_transaction_ref_to_ref: OK [ 638.103370] Testing event binder_transaction_ref_to_node: OK [ 638.896672] Testing event binder_transaction_node_to_ref: OK [ 639.478372] Testing event binder_transaction_received: OK [ 640.008662] Testing event binder_transaction: OK [ 640.781262] Testing event binder_wait_for_work: OK [ 641.376624] Testing event binder_read_done: OK [ 642.395657] Testing event binder_write_done: OK [ 642.951623] Testing event binder_ioctl_done: OK [ 643.784974] Testing event binder_unlock: OK [ 644.398514] Testing event binder_locked: OK [ 645.020932] Testing event binder_lock: OK [ 645.785378] Testing event binder_ioctl: OK [ 646.498293] Testing event br_fdb_update: OK [ 647.311903] Testing event fdb_delete: OK [ 647.862308] Testing event br_fdb_external_learn_add: OK [ 648.363539] Testing event br_fdb_add: OK [ 649.129606] Testing event qdisc_dequeue: OK [ 649.719790] Testing event fib_table_lookup: OK [ 650.223564] Testing event tcp_probe: OK [ 650.779348] Testing event tcp_retransmit_synack: OK [ 651.733876] Testing event tcp_rcv_space_adjust: OK [ 652.365880] Testing event tcp_destroy_sock: OK [ 653.266736] Testing event tcp_receive_reset: OK [ 653.769725] Testing event tcp_send_reset: OK [ 654.378411] Testing event tcp_retransmit_skb: OK [ 655.365520] Testing event udp_fail_queue_rcv_skb: OK [ 656.150289] Testing event inet_sock_set_state: OK [ 656.688658] Testing event sock_exceed_buf_limit: OK [ 657.327803] Testing event sock_rcvqueue_full: OK [ 658.036794] Testing event napi_poll: OK [ 658.627453] Testing event netif_rx_ni_entry: OK [ 659.162476] Testing event netif_rx_entry: OK [ 659.726284] Testing event netif_receive_skb_list_entry: OK [ 660.316611] Testing event netif_receive_skb_entry: OK [ 660.907542] Testing event napi_gro_receive_entry: OK [ 661.595812] Testing event napi_gro_frags_entry: OK [ 662.086619] Testing event netif_rx: OK [ 662.920775] Testing event netif_receive_skb: OK [ 663.482365] Testing event net_dev_queue: OK [ 664.273887] Testing event net_dev_xmit: OK [ 664.827637] Testing event net_dev_start_xmit: OK [ 665.306630] Testing event skb_copy_datagram_iovec: OK [ 666.057221] Testing event consume_skb: OK [ 666.894586] Testing event kfree_skb: OK [ 667.334511] Testing event 9p_protocol_dump: OK [ 668.193522] Testing event 9p_client_res: OK [ 668.876779] Testing event 9p_client_req: OK [ 669.187815] Running tests on trace event systems: [ 669.215462] Testing event system 9p: OK [ 670.059567] Testing event system skb: OK [ 670.910350] Testing event system net: OK [ 671.700598] Testing event system napi: OK [ 672.554723] Testing event system sock: OK [ 673.298917] Testing event system udp: OK [ 673.955577] Testing event system tcp: OK [ 674.798220] Testing event system fib: OK [ 675.356974] Testing event system qdisc: OK [ 676.130273] Testing event system bridge: OK [ 676.888868] Testing event system binder: OK [ 677.590243] Testing event system ras: OK [ 679.021757] Testing event system rtc: OK [ 679.762377] Testing event system mdio: OK [ 680.471376] Testing event system spi: OK [ 681.413364] Testing event system sync_trace: OK [ 681.983809] Testing event system dma_fence: OK [ 682.931766] Testing event system regmap: OK [ 683.955818] Testing event system iommu: OK [ 685.151419] Testing event system random: OK [ 686.310261] Testing event system regulator: OK [ 687.391661] Testing event system rpmh: OK [ 688.422065] Testing event system clk: OK [ 689.234047] Testing event system gpio: OK [ 690.075744] Testing event system wbt: OK [ 690.931669] Testing event system block: OK [ 691.646026] Testing event system filelock: OK [ 692.815636] Testing event system fs_dax: OK [ 693.921382] Testing event system writeback: OK [ 694.823820] Testing event system page_ref: OK [ 698.157915] Testing event system cma: OK [ 699.214224] Testing event system page_isolation: OK [ 700.055643] Testing event system huge_memory: OK [ 700.737634] Testing event system migrate: OK [ 701.352680] Testing event system compaction: OK [ 702.216608] Testing event system kmem: OK [ 703.191552] Testing event system percpu: OK [ 704.168634] Testing event system vmscan: OK [ 705.225321] Testing event system pagemap: OK [ 706.063382] Testing event system oom: OK [ 706.874084] Testing event system filemap: OK [ 707.609960] Testing event system rseq: OK [ 708.515706] Testing event system xdp: OK [ 709.191552] Testing event system benchmark: OK [ 710.249179] Testing event system rpm: OK [ 710.951568] Testing event system power: OK [ 712.209324] Testing event system test: OK [ 712.938360] Testing event system preemptirq: OK [ 715.092856] Testing event system cgroup: OK [ 715.830204] Testing event system module: OK [ 716.387465] Testing event system alarmtimer: OK [ 717.247815] Testing event system timer: OK [ 718.018876] Testing event system swiotlb: OK [ 718.858560] Testing event system rcu: OK [ 720.310045] Testing event system printk: OK [ 721.083608] Testing event system lock: OK [ 721.631445] Testing event system sched: OK [ 723.072653] Testing event system workqueue: OK [ 724.033024] Testing event system signal: OK [ 724.775343] Testing event system irq: OK [ 725.284233] Testing event system cpuhp: OK [ 725.933368] Testing event system task: OK [ 726.687718] Testing event system kvm: OK [ 728.503492] Testing event system emulation: OK [ 729.549146] Testing event system ipi: OK [ 730.088625] Testing event system raw_syscalls: OK [ 730.831604] Testing event system initcall: OK [ 731.341735] Running tests on all trace events: [ 731.348373] Testing all events: OK [ 755.685922] ftrace_replace_code: 1 [ 764.541614] ../kernel/trace/ftrace.c:2443, loop_counter: 41424 [ 764.552532] Testing ftrace filter: OK [ 764.662724] trace_kprobe: Testing kprobe tracing: OK [ 765.031099] Loading compiled-in X.509 certificates [ 765.142547] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 765.144960] Loaded X.509 cert 'Build time autogenerated kernel key: 4054958e39d9099bc90ea5e944013505afd1c71c' [ 765.152719] page_owner is disabled [ 765.270675] Key type big_key registered [ 765.334784] Key type encrypted registered [ 765.339246] ima: No TPM chip found, activating TPM-bypass! [ 765.340622] ima: Allocated hash algorithm: sha1 [ 765.344453] No architecture policies found [ 765.350283] random: get_random_bytes called from prandom_seed_full_state+0xc0/0x198 with crng_init=0 [ 765.380506] hctosys: unable to open rtc device (rtc0) [ 765.395696] psci_checker: PSCI checker started using 1 CPUs [ 765.396679] psci_checker: Starting hotplug tests [ 765.397581] psci_checker: Trying to turn off and on again all CPUs [ 765.398595] psci_checker: Trying to turn off and on again group 0 (CPUs 0) [ 765.399490] psci_checker: Hotplug tests passed OK [ 765.400481] psci_checker: Starting suspend tests (10 cycles per state) [ 765.401317] psci_checker: cpuidle not available on CPU 0, ignoring [ 765.402147] psci_checker: Could not start suspend tests on any CPU [ 765.402694] psci_checker: PSCI checker completed [ 765.450296] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 765.637585] ### dt-test ### start of unittest - you will see error messages [ 765.664426] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 765.665389] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 765.666400] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 765.667144] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 765.668452] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 765.669310] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 765.686054] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 765.688333] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 765.690084] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 766.093285] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status [ 766.142850] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status [ 766.165290] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status [ 766.212908] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status [ 766.286067] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status [ 766.350256] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status [ 766.393219] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status [ 766.468765] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status [ 766.511248] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo [ 766.513534] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 766.514308] OF: overlay: overlay #6 is not topmost [ 766.777205] OF: overlay: remove: Could not find overlay #7 [ 766.777833] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #7 [ 766.998017] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/status [ 767.003095] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/status [ 767.005776] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@30/incline-up [ 767.007198] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@40/incline-up [ 767.018924] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/status [ 767.020569] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/color [ 767.021850] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/rate [ 767.023564] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/hvac_2 [ 767.025490] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200 [ 767.026951] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_left [ 767.028667] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_right [ 767.125292] OF: overlay: ERROR: multiple fragments add and/or delete node /testcase-data-2/substation@100/motor-1/controller [ 767.129232] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/controller/name [ 767.140767] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 767.142119] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 767.143468] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/rpm_avail [ 767.161451] ### dt-test ### end of unittest - 217 passed, 0 failed [ 767.185863] uart-pl011 9000000.pl011: no DMA platform data [ 767.536389] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 767.537445] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 767.577248] VFS: Mounted root (9p filesystem) readonly on device 0:18. [ 767.598904] devtmpfs: mounted [ 768.269640] Freeing unused kernel memory: 4096K [ 768.291173] Checked W+X mappings: passed, no W+X pages found [ 768.292586] rodata_test: all tests were successful [ 768.293561] Run /sbin/init as init process [ 768.409741] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=0 [ 769.307357] random: fast init done [ 772.345454] systemd[1]: System time before build time, advancing clock. [ 772.825920] systemd[1]: Failed to insert module 'autofs4': No such file or directory [ 776.160819] random: systemd: uninitialized urandom read (16 bytes read) [ 776.518096] random: systemd: uninitialized urandom read (16 bytes read) [ 776.522843] random: systemd: uninitialized urandom read (16 bytes read) [ 781.953170] 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) [ 782.029900] systemd[1]: Detected virtualization qemu. [ 782.036730] systemd[1]: Detected architecture arm64. Welcome to Debian GNU/Linux 9 (stretch)! [ 782.179030] systemd[1]: Set hostname to . [ 782.358219] _warn_unseeded_randomness: 5 callbacks suppressed [ 782.358445] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=1 [ 782.416968] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 783.034155] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 783.543850] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 783.545701] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 783.546512] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 784.739450] _warn_unseeded_randomness: 20 callbacks suppressed [ 784.739656] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 784.789862] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 784.790841] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 785.981884] random: systemd: uninitialized urandom read (16 bytes read) [ 786.098413] _warn_unseeded_randomness: 9 callbacks suppressed [ 786.098610] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 786.099787] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 786.110708] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 787.798257] _warn_unseeded_randomness: 11 callbacks suppressed [ 787.798456] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 787.799781] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 787.801651] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 789.111993] _warn_unseeded_randomness: 7 callbacks suppressed [ 789.112190] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 789.730656] systemd-hiberna (1258) used greatest stack depth: 10576 bytes left [ 791.775678] random: systemd-cryptse: uninitialized urandom read (16 bytes read) [ 792.462942] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 793.115489] systemd-system- (1264) used greatest stack depth: 10544 bytes left [ 793.525491] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 795.286103] random: systemd: uninitialized urandom read (16 bytes read) [ 795.534485] random: systemd: uninitialized urandom read (16 bytes read) [ 795.538901] random: systemd: uninitialized urandom read (16 bytes read) [ 803.253796] systemd[1]: networking.service: Cannot add dependency job, ignoring: Unit networking.service is masked. [ 803.325051] random: get_random_bytes called from generate_random_uuid+0x20/0x50 with crng_init=1 [ 803.362999] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 803.439472] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 803.475085] systemd[1]: Listening on Journal Audit Socket. [ OK ] Listening on Journal Audit Socket. [ 803.507684] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ OK ] Listening on /dev/initctl Compatibility Named Pipe. [ 803.598440] systemd[1]: Created slice System Slice. [ OK ] Created slice System Slice. [ 803.671113] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ 803.866379] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 803.963728] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 804.003099] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 804.074972] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 804.616329] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 804.617258] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 804.618081] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 Mounting Debug File System... [ OK ] Listening on Syslog Socket. [ OK ] Listening on Journal Socket. [ 805.651513] _warn_unseeded_randomness: 7 callbacks suppressed [ 805.651714] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 805.691190] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 805.698307] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 Starting Load Kernel Modules... [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 806.801951] _warn_unseeded_randomness: 2 callbacks suppressed [ 806.802147] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 806.803409] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 806.804662] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target Slices. [ OK ] Reached target Sockets. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Encrypted Volumes. [ 807.857561] _warn_unseeded_randomness: 11 callbacks suppressed [ 807.857783] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 808.730736] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 808.881732] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 808.882646] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 808.883467] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 809.313948] systemd[1]: Starting Create Static Device Nodes in /dev... Starting Create Static Device Nodes in /dev... [ 810.432786] _warn_unseeded_randomness: 4 callbacks suppressed [ 810.432988] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 810.979034] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 810.980457] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 811.139327] systemd[1]: Starting Journal Service... Starting Journal Service... [ 811.688786] _warn_unseeded_randomness: 5 callbacks suppressed [ 811.689008] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 811.861112] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 811.862006] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 813.032621] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 813.205267] systemd[1]: Starting of Arbitrary Executable File Formats File System Automount Point not supported. [ 813.303036] _warn_unseeded_randomness: 4 callbacks suppressed [ 813.303237] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 813.304982] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 813.305715] 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. [ 815.374415] _warn_unseeded_randomness: 4 callbacks suppressed [ 815.374614] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 815.376318] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 815.603538] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 816.246658] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 817.757649] _warn_unseeded_randomness: 2 callbacks suppressed [ 817.757916] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 817.758991] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 817.762359] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 819.449173] _warn_unseeded_randomness: 3 callbacks suppressed [ 819.449378] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 819.617926] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 819.618813] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 820.558228] _warn_unseeded_randomness: 3 callbacks suppressed [ 820.558434] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 820.730202] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 820.731057] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 820.942920] systemd[1]: Mounted Debug File System. [ OK ] Mounted Debug File System. [ 821.030656] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 821.130115] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 822.191430] systemd[1]: Started Load Kernel Modules. [ 822.201957] _warn_unseeded_randomness: 4 callbacks suppressed [ 822.202147] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 822.203322] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 822.204696] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ OK ] Started Load Kernel Modules. [ 823.151253] systemd[1]: Started Create Static Device Nodes in /dev. [ OK ] Started Create Static Device Nodes in /dev. [ 823.636697] systemd-journald[1274]: /dev/kmsg buffer overrun, some messages lost. [ 825.190497] _warn_unseeded_randomness: 2 callbacks suppressed [ 825.190701] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 825.480833] systemd[1]: Starting udev Kernel Device Manager... Starting udev Kernel Device Manager... [ 826.427112] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 826.778710] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 826.779658] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 826.780972] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 828.039650] _warn_unseeded_randomness: 9 callbacks suppressed [ 828.040820] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 828.194989] systemd[1]: Mounting Configuration File System... [ 828.219618] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 Mounting Configuration File System... [ 828.278297] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 831.115006] _warn_unseeded_randomness: 2 callbacks suppressed [ 831.115218] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 831.116973] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 831.193204] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 833.218806] systemd[1]: Started Remount Root and Kernel File Systems. [ OK ] Started Remount Root and Kernel File Systems. [ 833.330332] _warn_unseeded_randomness: 2 callbacks suppressed [ 833.330527] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 833.513428] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 833.694640] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 834.605014] systemd[1]: Started Apply Kernel Variables. [ OK ] Started Apply Kernel Variables. [ 835.510457] systemd[1]: Reached target Local File Systems (Pre). [ OK ] Reached target Local File Systems (Pre). [ 835.622823] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 837.080528] _warn_unseeded_randomness: 3 callbacks suppressed [ 837.080733] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 837.265940] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 837.266845] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 838.171278] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 839.352428] _warn_unseeded_randomness: 2 callbacks suppressed [ 839.352657] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 839.954528] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 839.955421] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 840.020537] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 840.360615] _warn_unseeded_randomness: 2 callbacks suppressed [ 840.360814] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 842.001627] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 842.002457] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 842.257662] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 842.412471] systemd[1]: Mounted Configuration File System. [ OK ] Mounted Configuration File System. [ 843.854346] systemd[1]: Started udev Kernel Device Manager. [ OK ] Started udev Kernel Device Manager. [ 845.962094] _warn_unseeded_randomness: 1 callbacks suppressed [ 845.962299] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 846.141488] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 846.142401] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 846.473914] systemd[1]: Started Load/Save Random Seed. [ OK ] Started Load/Save Random Seed. [* ] (1 of 3) A start job is running for Journal Service (48s / 30min 7s) [** ] (1 of 3) A start job is running for Journal Service (48s / 30min 7s) [*** ] (1 of 3) A start job is running for Journal Service (49s / 30min 7s) [ *** ] (2 of 3) A start job is running for dev-ttyAMA0.device (49s / 30min) [ *** ] (2 of 3) A start job is running for dev-ttyAMA0.device (50s / 30min) [ ***] (2 of 3) A start job is running for dev-ttyAMA0.device (50s / 30min) [ **] (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 (51s / 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 Journal Service (52s / 30min 7s) [ *** ] (1 of 3) A start job is running for Journal Service (53s / 30min 7s) [ *** ] (1 of 3) A start job is running for Journal Service (53s / 30min 7s) [*** ] (2 of 3) A start job is running for dev-ttyAMA0.device (54s / 30min) [** ] (2 of 3) A start job is running for dev-ttyAMA0.device (54s / 30min) [* ] (2 of 3) A start job is running for dev-ttyAMA0.device (55s / 30min) [** ] (3 of 3) A start job is running for…dplug all Devices (55s / 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 (56s / no limit)[ 860.454372] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [ 861.029281] _warn_unseeded_randomness: 3 callbacks suppressed [ 861.029486] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 Starting Flush Journal to Persistent Storage... [ 862.588900] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 862.589936] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 862.590840] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 864.703771] _warn_unseeded_randomness: 4 callbacks suppressed [ 864.705364] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ *** ] (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 3s / 30min) [ **] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 3s / 30min) [ *] (2 of 3) A start job is running for…g all Devices (1min 4s / no limit) [ **] (2 of 3) A start job is running for…g all Devices (1min 4s / no limit) [ ***] (2 of 3) A start job is running for…g all Devices (1min 5s / no limit) [ *** ] (3 of 3) A start job is running for…stent Storage (1min 5s / 2min 27s) [ *** ] (3 of 3) A start job is running for…stent Storage (1min 6s / 2min 27s) [*** ] (3 of 3) A start job is running for…stent Storage (1min 6s / 2min 27s) [** ] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 7s / 30min) [* ] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 7s / 30min) [** ] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 8s / 30min) [*** ] (2 of 3) A start job is running for…g all Devices (1min 8s / no limit) [ *** ] (2 of 3) A start job is running for…g all Devices (1min 9s / no limit) [ *** ] (2 of 3) A start job is running for…g all Devices (1min 9s / no limit)[ 873.113550] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 873.114500] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 873.115279] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ ***] (3 of 3) A start job is running for…tent Storage (1min 11s / 2min 27s) [ **] (3 of 3) A start job is running for…tent Storage (1min 11s / 2min 27s)[ 875.193386] _warn_unseeded_randomness: 4 callbacks suppressed [ 875.193583] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 875.194683] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ *] (3 of 3) A start job is running for…tent Storage (1min 12s / 2min 27s)[ 875.402885] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ **] (1 of 3) A start job is running for…-ttyAMA0.device (1min 12s / 30min) [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (1min 13s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 13s / 30min) [ *** ] (2 of 3) A start job is running for… all Devices (1min 14s / no limit) [*** ] (2 of 3) A start job is running for… all Devices (1min 14s / no limit)[ 878.214658] random: crng init done [ 878.215164] random: 3 get_random_xx warning(s) missed due to ratelimiting [** ] (2 of 3) A start job is running for… all Devices (1min 15s / no limit) [* ] (3 of 3) A start job is running for…tent Storage (1min 15s / 2min 27s) [** ] (3 of 3) A start job is running for…tent Storage (1min 16s / 2min 27s) [*** ] (3 of 3) A start job is running for…tent Storage (1min 16s / 2min 27s) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 17s / 30min)[ 880.581689] systemd-journald[1274]: Received request to flush runtime journal from PID 1 [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 17s / 30min) [ OK ] Started Flush Journal to Persistent Storage. Starting Create Volatile Files and Directories... [ ***] (1 of 3) A start job is running for… Directories (1min 23s / no limit) [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 24s / 30min) [ *] (2 of 3) A start job is running for…-ttyAMA0.device (1min 24s / 30min) [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 25s / 30min) [ ***] (3 of 3) A start job is running for… all Devices (1min 25s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 26s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 26s / no limit) [*** ] (1 of 3) A start job is running for… Directories (1min 27s / no limit) [** ] (1 of 3) A start job is running for… Directories (1min 27s / no limit) [* ] (1 of 3) A start job is running for… Directories (1min 28s / no limit) [** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 28s / 30min) [*** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 29s / 30min) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 29s / 30min) [ *** ] (3 of 3) A start job is running for… all Devices (1min 30s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (1min 30s / no limit) [ **] (3 of 3) A start job is running for… all Devices (1min 31s / no limit) [ *] (1 of 3) A start job is running for… Directories (1min 31s / 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 32s / no limit) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 33s / 30min) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 33s / 30min) [*** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 34s / 30min) [** ] (3 of 3) A start job is running for… all Devices (1min 34s / no limit) [* ] (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 35s / no limit) [*** ] (1 of 3) A start job is running for… Directories (1min 36s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (1min 36s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (1min 37s / no limit) [ ***] (2 of 3) A start job is running for…-ttyAMA0.device (1min 37s / 30min) [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 38s / 30min) [ OK ] Started Create Volatile Files and Directories. Starting Network Time Synchronization... Starting Update UTMP about System Boot/Shutdown... [ *] (2 of 4) A start job is running for…-ttyAMA0.device (1min 45s / 30min) [ **] (3 of 4) A start job is running for…oot/Shutdown (1min 46s / no limit) [ ***] (3 of 4) A start job is running for…oot/Shutdown (1min 47s / no limit) [ *** ] (3 of 4) A start job is running for…oot/Shutdown (1min 47s / no limit) [ *** ] (4 of 4) A start job is running for… all Devices (1min 48s / no limit) [*** ] (4 of 4) A start job is running for… all Devices (1min 48s / no limit) [** ] (4 of 4) A start job is running for… all Devices (1min 49s / no limit) [* ] (1 of 4) A start job is running for…hronization (1min 49s / 31min 41s) [** ] (1 of 4) A start job is running for…hronization (1min 50s / 31min 41s) [*** ] (1 of 4) A start job is running for…hronization (1min 50s / 31min 41s) [ *** ] (2 of 4) A start job is running for…-ttyAMA0.device (1min 51s / 30min) [ OK ] Started Update UTMP about System Boot/Shutdown. [ *** ] (3 of 3) A start job is running for… all Devices (1min 57s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (1min 57s / no limit) [ **] (1 of 3) A start job is running for…hronization (1min 58s / 31min 41s) [ *] (1 of 3) A start job is running for…hronization (1min 58s / 31min 41s) [ **] (1 of 3) A start job is running for…hronization (1min 59s / 31min 41s)