[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 4.20.0-rc4-next-20181128-00009-g44c557177b8d (anders@compilator) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05)) #3 SMP Wed Nov 28 10:15:03 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] kmemleak: Kernel memory leak detector disabled [ 0.000000] NUMA: No NUMA configuration found [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] NUMA: NODE_DATA [mem 0xbebf45c0-0xbebf5fff] [ 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: 998096K/2097152K available (15040K kernel code, 3362K rwdata, 4676K rodata, 4096K init, 21767K bss, 89448K 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 41494 entries in 163 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] 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.000206] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.015268] Console: colour dummy device 80x25 [ 0.021557] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.022623] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.023095] ... MAX_LOCK_DEPTH: 48 [ 0.023526] ... MAX_LOCKDEP_KEYS: 8191 [ 0.023967] ... CLASSHASH_SIZE: 4096 [ 0.024404] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.024838] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.025274] ... CHAINHASH_SIZE: 32768 [ 0.025710] memory used by lock dependency info: 7775 kB [ 0.026182] per task-struct memory footprint: 2688 bytes [ 0.027135] ------------------------ [ 0.027616] | Locking API testsuite: [ 0.028032] ---------------------------------------------------------------------------- [ 0.028655] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.029166] -------------------------------------------------------------------------- [ 0.030062] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.042338] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.055466] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.069464] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.083351] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.098449] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.114075] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.129150] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.140494] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.151260] -------------------------------------------------------------------------- [ 0.151822] recursive read-lock: | ok | | ok | [ 0.155771] recursive read-lock #2: | ok | | ok | [ 0.159702] mixed read-write-lock: | ok | | ok | [ 0.163707] mixed write-read-lock: | ok | | ok | [ 0.167231] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.171138] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.175329] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.179552] -------------------------------------------------------------------------- [ 0.180888] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.186542] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.192144] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.197564] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.202941] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.208556] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.214383] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.219834] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.226130] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.231659] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.237573] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.243410] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.249290] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.255515] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.261378] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.267289] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.273641] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.279603] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.285556] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.291976] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.297540] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.303117] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.309255] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.315072] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.321467] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.327317] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.333317] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.339217] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.345560] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.351419] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.357468] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.363560] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.369514] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.375339] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.381738] hard-irq lock-inversion/123: ok | ok | ok | [ 0.387580] soft-irq lock-inversion/123: ok | ok | ok | [ 0.393504] hard-irq lock-inversion/132: ok | ok | ok | [ 0.399715] soft-irq lock-inversion/132: ok | ok | ok | [ 0.405780] hard-irq lock-inversion/213: ok | ok | ok | [ 0.411668] soft-irq lock-inversion/213: ok | ok | ok | [ 0.418059] hard-irq lock-inversion/231: ok | ok | ok | [ 0.423895] soft-irq lock-inversion/231: ok | ok | ok | [ 0.430357] hard-irq lock-inversion/312: ok | ok | ok | [ 0.436042] soft-irq lock-inversion/312: ok | ok | ok | [ 0.441934] hard-irq lock-inversion/321: ok | ok | ok | [ 0.447731] soft-irq lock-inversion/321: ok | ok | ok | [ 0.454051] hard-irq read-recursion/123: ok | [ 0.456298] soft-irq read-recursion/123: ok | [ 0.459014] hard-irq read-recursion/132: ok | [ 0.461208] soft-irq read-recursion/132: ok | [ 0.463969] hard-irq read-recursion/213: ok | [ 0.466232] soft-irq read-recursion/213: ok | [ 0.468942] hard-irq read-recursion/231: ok | [ 0.471237] soft-irq read-recursion/231: ok | [ 0.473965] hard-irq read-recursion/312: ok | [ 0.476257] soft-irq read-recursion/312: ok | [ 0.478987] hard-irq read-recursion/321: ok | [ 0.481236] soft-irq read-recursion/321: ok | [ 0.483981] -------------------------------------------------------------------------- [ 0.484578] | Wound/wait tests | [ 0.485016] --------------------- [ 0.485456] ww api failures: ok | ok | ok | [ 0.492966] ww contexts mixing: ok | ok | [ 0.497147] finishing ww context: ok | ok | ok | ok | [ 0.504254] locking mismatches: ok | ok | ok | [ 0.509707] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.528923] spinlock nest unlocked: ok | [ 0.531376] ----------------------------------------------------- [ 0.531840] |block | try |context| [ 0.532317] ----------------------------------------------------- [ 0.532850] context: ok | ok | ok | [ 0.538694] try: ok | ok | ok | [ 0.544002] block: ok | ok | ok | [ 0.549327] spinlock: ok | ok | ok | [ 0.555663] ------------------------------------------------------- [ 0.556168] Good, all 261 testcases passed! | [ 0.556609] --------------------------------- [ 0.562022] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.563034] pid_max: default: 32768 minimum: 301 [ 0.577384] LSM: Security Framework initializing [ 0.578277] Yama: becoming mindful. [ 0.581339] LoadPin: ready to pin (currently enforcing) [ 0.582466] AppArmor: AppArmor disabled by boot time parameter [ 0.618930] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.633120] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.636715] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.638009] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.679829] *** VALIDATE proc *** [ 0.702051] *** VALIDATE cgroup1 *** [ 0.702677] *** VALIDATE cgroup2 *** [ 0.776919] ASID allocator initialised with 32768 entries [ 0.788741] rcu: Hierarchical SRCU implementation. [ 0.885778] EFI services will not be available. [ 0.915655] smp: Bringing up secondary CPUs ... [ 0.916289] smp: Brought up 1 node, 1 CPU [ 0.916829] SMP: Total of 1 processors activated. [ 0.917458] CPU features: detected: 32-bit EL0 Support [ 0.918048] CPU features: detected: CRC32 instructions [ 1.248441] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.249543] CPU: All CPU(s) started at EL1 [ 1.251202] alternatives: patching kernel code [ 1.252812] _warn_unseeded_randomness: 76 callbacks suppressed [ 1.253174] random: get_random_u64 called from compute_layout+0x138/0x1b8 with crng_init=0 [ 1.281728] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 2.007906] node 0 initialised, 248306 pages in 4ms [ 2.016038] pgdatinit0 (15) used greatest stack depth: 13408 bytes left [ 2.028580] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 2.116361] devtmpfs: initialized [ 2.398321] Registered cp15_barrier emulation handler [ 2.752741] workqueue: round-robin CPU selection forced, expect performance impact [ 10.993638] DMA-API: preallocated 65536 debug entries [ 10.994213] DMA-API: debugging enabled by kernel config [ 10.995565] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 10.998567] futex hash table entries: 256 (order: 3, 32768 bytes) [ 11.004356] Running postponed tracer tests: [ 11.006101] Testing tracer function: [ 11.191048] ftrace_replace_code: 1 [ 24.320299] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 24.719544] ftrace_replace_code: 1 [ 34.291968] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 34.305464] PASSED [ 34.305890] Testing dynamic ftrace: [ 35.983104] ftrace_replace_code: 1 [ 36.163371] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 36.402216] PASSED [ 36.402881] Testing dynamic ftrace ops #1: [ 43.065597] ftrace_replace_code: 1 [ 43.251705] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 43.274304] (1 0 1 0 0) [ 43.274506] (1 1 2 0 0) [ 43.402394] ftrace_replace_code: 1 [ 73.158774] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 73.167371] (2 1 3 0 1338990) [ 73.167917] (2 2 4 0 1339005) [ 73.344089] ftrace_replace_code: 1 [ 86.978503] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 87.043591] ftrace_replace_code: 1 [ 87.224977] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 87.247908] ftrace_replace_code: 1 [ 87.429593] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 87.443246] PASSED [ 87.444742] Testing dynamic ftrace ops #2: [ 94.085556] ftrace_replace_code: 1 [ 94.278452] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 94.298931] ftrace_replace_code: 1 [ 94.480913] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 94.669898] ftrace_replace_code: 1 [ 119.951661] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 119.958897] (1 0 1 1248675 0) [ 119.959344] (1 1 2 1248690 0) [ 120.149822] (2 1 3 1 6294) [ 120.152110] (2 2 4 118 6411) [ 120.566793] ftrace_replace_code: 1 [ 130.146697] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 130.158770] PASSED [ 130.159200] Testing ftrace recursion: [ 131.830762] ftrace_replace_code: 1 [ 132.014289] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 132.034927] ftrace_replace_code: 1 [ 132.216694] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 132.228334] PASSED [ 132.228747] Testing ftrace recursion safe: [ 133.899387] ftrace_replace_code: 1 [ 134.083637] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 134.104357] ftrace_replace_code: 1 [ 134.287583] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 134.299266] PASSED [ 134.299695] Testing ftrace regs(no arch support): [ 135.969904] ftrace_replace_code: 1 [ 136.153293] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 136.173918] ftrace_replace_code: 1 [ 136.357313] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 136.368744] PASSED [ 136.369933] Testing tracer nop: PASSED [ 136.371891] Testing tracer irqsoff: [ 136.538198] ftrace_replace_code: 1 [ 150.305916] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 150.468444] ftrace_replace_code: 1 [ 160.107666] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 160.119996] PASSED [ 160.121578] Testing tracer function_graph: [ 160.297835] ftrace_replace_code: 1 [ 173.162394] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 174.356460] ftrace_replace_code: 1 [ 186.923285] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 186.936228] PASSED [ 186.954879] _warn_unseeded_randomness: 7 callbacks suppressed [ 186.955112] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 186.957043] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 186.959199] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 186.976799] prandom: seed boundary self test passed [ 187.010986] prandom: 100 self tests passed [ 187.018261] pinctrl core: initialized pinctrl subsystem [ 187.117687] regulator-dummy: Failed to create debugfs directory [ 187.127211] [ 187.127634] ************************************************************* [ 187.128256] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 187.129458] ** ** [ 187.130112] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 187.130730] ** ** [ 187.131287] ** This means that this kernel is built to expose internal ** [ 187.131848] ** IOMMU data structures, which may compromise security on ** [ 187.132396] ** your system. ** [ 187.133466] ** ** [ 187.134000] ** If you see this message and you are not debugging the ** [ 187.134565] ** kernel, report this immediately to your vendor! ** [ 187.135119] ** ** [ 187.135659] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 187.136200] ************************************************************* [ 187.168444] DMI not present or invalid. [ 187.215564] NET: Registered protocol family 16 [ 187.246399] audit: initializing netlink subsys (disabled) [ 187.398314] cpuidle: using governor ladder [ 187.400468] cpuidle: using governor menu [ 187.421358] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 187.423103] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 187.428177] audit: type=2000 audit(145.168:1): state=initialized audit_enabled=0 res=1 [ 187.502097] DMA: preallocated 256 KiB pool for atomic allocations [ 187.592014] Serial: AMBA PL011 UART driver [ 188.416362] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 188.419790] printk: console [ttyAMA0] enabled [ 188.419790] printk: console [ttyAMA0] enabled [ 188.421040] printk: bootconsole [pl11] disabled [ 188.421040] printk: bootconsole [pl11] disabled [ 188.535669] _warn_unseeded_randomness: 39 callbacks suppressed [ 188.535890] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 188.537767] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 189.096619] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 189.151867] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 189.360881] arm-v7s io-pgtable: self test ok [ 189.362520] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 189.370438] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 189.375508] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 189.381125] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 189.387848] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 189.393369] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 189.399611] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 189.408700] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 189.418431] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 189.428395] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 189.438841] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 189.448893] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 189.460850] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 189.487473] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 189.514081] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 189.542173] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 189.572714] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 189.603765] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 189.634538] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 189.667382] vgaarb: loaded [ 189.685694] _warn_unseeded_randomness: 32 callbacks suppressed [ 189.685917] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 189.688017] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 189.731738] pps_core: LinuxPPS API ver. 1 registered [ 189.732400] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 189.737129] PTP clock support registered [ 189.815420] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 189.918328] NetLabel: Initializing [ 189.918906] NetLabel: domain hash size = 128 [ 189.919556] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 189.929011] NetLabel: unlabeled traffic allowed by default [ 189.949506] clocksource: Switched to clocksource arch_sys_counter [ 193.641622] _warn_unseeded_randomness: 2 callbacks suppressed [ 193.641846] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 193.643373] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 193.646328] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 193.661294] VFS: Disk quotas dquot_6.6.0 [ 193.665575] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 193.690911] *** VALIDATE hugetlbfs *** [ 194.498044] NET: Registered protocol family 2 [ 194.538576] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 194.543357] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 194.564032] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 194.576229] TCP: Hash tables configured (established 16384 bind 16384) [ 194.584910] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 194.590310] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 194.603817] NET: Registered protocol family 1 [ 194.697453] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 194.708280] kvm [1]: HYP mode not available [ 194.739802] kworker/u2:0 (34) used greatest stack depth: 11920 bytes left [ 194.886940] _warn_unseeded_randomness: 49 callbacks suppressed [ 194.887164] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 194.889396] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 194.919135] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 195.002905] Initialise system trusted keyrings [ 195.021400] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 196.809167] _warn_unseeded_randomness: 5 callbacks suppressed [ 196.809404] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 196.810997] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 196.833769] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 197.031019] 9p: Installing v9fs 9p2000 file system support [ 197.981862] Key type asymmetric registered [ 197.983895] Asymmetric key parser 'x509' registered [ 197.990543] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 198.002760] _warn_unseeded_randomness: 26 callbacks suppressed [ 198.002984] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 198.005276] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 198.035709] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 198.571148] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 198.578468] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 198.602287] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 198.670405] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 198.743688] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 198.746967] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 198.750389] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 198.752071] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 198.757711] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 198.774333] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 198.775625] pci_bus 0000:00: root bus resource [bus 00-0f] [ 198.777199] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 198.778190] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 198.779163] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 198.783862] pci 0000:00:00.0: calling quirk_mmio_always_on+0x0/0x38 @ 1 [ 198.785554] pci 0000:00:00.0: quirk_mmio_always_on+0x0/0x38 took 82 usecs [ 198.883067] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 198.884341] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 198.886413] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 198.887832] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 198.889663] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 198.890724] pci 0000:00:03.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 198.891708] pci 0000:00:03.0: BAR 0: assigned [io 0x1000-0x103f] [ 198.893248] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 198.894248] pci 0000:00:02.0: BAR 0: assigned [io 0x1060-0x107f] [ 198.897600] pci 0000:00:00.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 198.898738] pci 0000:00:00.0: tegra_pcie_relax_enable+0x0/0x40 took 149 usecs [ 198.901941] pci 0000:00:01.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 198.903038] pci 0000:00:01.0: tegra_pcie_relax_enable+0x0/0x40 took 45 usecs [ 198.905222] pci 0000:00:02.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 198.906253] pci 0000:00:02.0: tegra_pcie_relax_enable+0x0/0x40 took 44 usecs [ 198.907953] pci 0000:00:03.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 198.909513] pci 0000:00:03.0: tegra_pcie_relax_enable+0x0/0x40 took 43 usecs [ 199.562807] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 199.634017] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 199.693843] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 204.629078] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 205.038650] SuperH (H)SCI(F) driver initialized [ 205.105261] _warn_unseeded_randomness: 9 callbacks suppressed [ 205.105504] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 205.193690] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 205.281825] libphy: Fixed MDIO Bus: probed [ 205.850144] ledtrig-cpu: registered to indicate activity on CPUs [ 205.891148] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 205.892349] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 205.938149] ashmem: initialized [ 206.144065] _warn_unseeded_randomness: 3 callbacks suppressed [ 206.144296] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 206.146728] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 206.166150] 9pnet: Installing 9P2000 support [ 206.532249] random: get_random_bytes called from init_oops_id+0x38/0x60 with crng_init=0 [ 206.593978] registered taskstats version 1 [ 206.595448] Running tests on trace events: [ 206.596112] Testing event initcall_finish: OK [ 206.631291] Testing event initcall_start: OK [ 206.664254] Testing event initcall_level: OK [ 206.694634] Testing event sys_exit: OK [ 206.722825] Testing event sys_enter: OK [ 206.751331] Testing event ipi_exit: OK [ 206.778672] Testing event ipi_entry: OK [ 206.810372] Testing event ipi_raise: OK [ 206.838374] Testing event instruction_emulation: OK [ 206.866736] Testing event kvm_halt_poll_ns: OK [ 206.895721] Testing event kvm_age_page: OK [ 206.926061] Testing event kvm_fpu: OK [ 206.953610] Testing event kvm_mmio: OK [ 206.982235] Testing event kvm_ack_irq: OK [ 207.009931] Testing event kvm_set_irq: OK [ 207.038000] Testing event kvm_vcpu_wakeup: OK [ 207.065840] Testing event kvm_userspace_exit: OK [ 207.094529] Testing event kvm_timer_update_irq: OK [ 207.126643] Testing event kvm_toggle_cache: OK [ 207.158047] Testing event kvm_set_way_flush: OK [ 207.183918] Testing event kvm_test_age_hva: OK [ 207.214264] Testing event kvm_age_hva: OK [ 207.241882] Testing event kvm_set_spte_hva: OK [ 207.269861] Testing event kvm_unmap_hva_range: OK [ 207.297863] Testing event kvm_mmio_emulate: OK [ 207.325986] Testing event kvm_irq_line: OK [ 207.354000] Testing event kvm_access_fault: OK [ 207.381867] Testing event kvm_guest_fault: OK [ 207.409795] Testing event kvm_exit: OK [ 207.437758] Testing event kvm_entry: OK [ 207.465818] Testing event kvm_set_guest_debug: OK [ 207.493809] Testing event kvm_handle_sys_reg: OK [ 207.521876] Testing event trap_reg: OK [ 207.551039] Testing event kvm_arm_set_regset: OK [ 207.585900] Testing event kvm_arm_set_dreg32: OK [ 207.614521] Testing event kvm_arm_clear_debug: OK [ 207.642358] Testing event kvm_arm_setup_debug: OK [ 207.669967] Testing event kvm_hvc_arm64: OK [ 207.697787] Testing event kvm_wfx_arm64: OK [ 207.726091] Testing event vgic_update_irq_pending: OK [ 207.753927] Testing event task_rename: OK [ 207.782839] Testing event task_newtask: OK [ 207.812158] Testing event cpuhp_exit: OK [ 207.842634] Testing event cpuhp_multi_enter: OK [ 207.870765] Testing event cpuhp_enter: OK [ 207.903495] Testing event softirq_raise: OK [ 207.935648] Testing event softirq_exit: OK [ 207.970170] Testing event softirq_entry: OK [ 207.997903] Testing event irq_handler_exit: OK [ 208.026200] Testing event irq_handler_entry: OK [ 208.054608] Testing event signal_deliver: OK [ 208.082892] Testing event signal_generate: OK [ 208.114008] Testing event workqueue_execute_end: OK [ 208.142581] Testing event workqueue_execute_start: OK [ 208.170920] Testing event workqueue_activate_work: OK [ 208.199957] Testing event workqueue_queue_work: OK [ 208.230610] Testing event sched_wake_idle_without_ipi: OK [ 208.260034] Testing event sched_swap_numa: OK [ 208.291358] Testing event sched_stick_numa: OK [ 208.325302] Testing event sched_move_numa: OK [ 208.356208] Testing event sched_process_hang: OK [ 208.388200] Testing event sched_pi_setprio: OK [ 208.420125] Testing event sched_stat_runtime: [ 208.423772] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 208.457453] OK [ 208.458100] Testing event sched_stat_blocked: OK [ 208.486185] Testing event sched_stat_iowait: OK [ 208.514654] Testing event sched_stat_sleep: OK [ 208.542869] Testing event sched_stat_wait: OK [ 208.573446] Testing event sched_process_exec: OK [ 208.601869] Testing event sched_process_fork: OK [ 208.629841] Testing event sched_process_wait: OK [ 208.657903] Testing event sched_wait_task: OK [ 208.685821] Testing event sched_process_exit: OK [ 208.714083] Testing event sched_process_free: OK [ 208.741845] Testing event sched_migrate_task: OK [ 208.769843] Testing event sched_switch: OK [ 208.798423] Testing event sched_wakeup_new: OK [ 208.826751] Testing event sched_wakeup: OK [ 208.857702] Testing event sched_waking: OK [ 208.886227] Testing event sched_kthread_stop_ret: OK [ 208.914732] Testing event sched_kthread_stop: OK [ 208.942960] Testing event lock_acquired: OK [ 208.983335] Testing event lock_contended: OK [ 209.010122] Testing event lock_release: OK [ 209.059229] Testing event lock_acquire: OK [ 209.106016] Testing event console: OK [ 209.135446] Testing event rcu_barrier: OK [ 209.166083] Testing event rcu_torture_read: OK [ 209.194603] Testing event rcu_batch_end: OK [ 209.222583] Testing event rcu_invoke_kfree_callback: OK [ 209.250672] Testing event rcu_invoke_callback: OK [ 209.278438] Testing event rcu_batch_start: OK [ 209.306887] Testing event rcu_kfree_callback: OK [ 209.337626] Testing event rcu_callback: OK [ 209.370461] Testing event rcu_dyntick: OK [ 209.406264] Testing event rcu_fqs: OK [ 209.434677] Testing event rcu_quiescent_state_report: OK [ 209.462503] Testing event rcu_unlock_preempted_task: OK [ 209.490498] Testing event rcu_preempt_task: OK [ 209.519022] Testing event rcu_exp_funnel_lock: OK [ 209.549746] Testing event rcu_exp_grace_period: OK [ 209.579513] Testing event rcu_grace_period_init: OK [ 209.608231] Testing event rcu_future_grace_period: OK [ 209.637908] Testing event rcu_grace_period: OK [ 209.673207] Testing event rcu_utilization: OK [ 209.703866] Testing event swiotlb_bounced: OK [ 209.735296] Testing event tick_stop: OK [ 209.769524] Testing event itimer_expire: OK [ 209.797491] Testing event itimer_state: OK [ 209.826750] Testing event hrtimer_cancel: OK [ 209.858718] Testing event hrtimer_expire_exit: OK [ 209.886562] Testing event hrtimer_expire_entry: OK [ 209.915060] Testing event hrtimer_start: OK [ 209.943678] Testing event hrtimer_init: OK [ 209.974859] Testing event timer_cancel: OK [ 210.003037] Testing event timer_expire_exit: OK [ 210.033441] Testing event timer_expire_entry: OK [ 210.062264] Testing event timer_start: OK [ 210.093924] Testing event timer_init: OK [ 210.121880] Testing event alarmtimer_cancel: OK [ 210.150611] Testing event alarmtimer_start: OK [ 210.179697] Testing event alarmtimer_fired: OK [ 210.210110] Testing event alarmtimer_suspend: OK [ 210.237857] Testing event module_request: OK [ 210.265895] Testing event module_put: OK [ 210.293851] Testing event module_get: OK [ 210.322348] Testing event module_free: OK [ 210.350780] Testing event module_load: OK [ 210.378864] Testing event cgroup_transfer_tasks: OK [ 210.409853] Testing event cgroup_attach_task: OK [ 210.438176] Testing event cgroup_rename: OK [ 210.466543] Testing event cgroup_release: OK [ 210.494834] Testing event cgroup_rmdir: OK [ 210.525504] Testing event cgroup_mkdir: OK [ 210.554091] Testing event cgroup_remount: OK [ 210.581823] Testing event cgroup_destroy_root: OK [ 210.609955] Testing event cgroup_setup_root: OK [ 210.639180] Testing event irq_enable: OK [ 210.683551] Testing event irq_disable: OK [ 210.722730] Testing event ftrace_test_filter: OK [ 210.749965] Testing event dev_pm_qos_remove_request: OK [ 210.777887] Testing event dev_pm_qos_update_request: OK [ 210.805888] Testing event dev_pm_qos_add_request: OK [ 210.834054] Testing event pm_qos_update_flags: OK [ 210.861886] Testing event pm_qos_update_target: OK [ 210.889895] Testing event pm_qos_update_request_timeout: OK [ 210.917860] Testing event pm_qos_remove_request: OK [ 210.945892] Testing event pm_qos_update_request: OK [ 210.974066] Testing event pm_qos_add_request: OK [ 211.001905] Testing event power_domain_target: OK [ 211.029824] Testing event clock_set_rate: OK [ 211.057719] Testing event clock_disable: OK [ 211.085808] Testing event clock_enable: OK [ 211.113729] Testing event wakeup_source_deactivate: OK [ 211.142518] Testing event wakeup_source_activate: OK [ 211.170809] Testing event suspend_resume: OK [ 211.217599] Testing event device_pm_callback_end: OK [ 211.245881] Testing event device_pm_callback_start: OK [ 211.278717] Testing event cpu_frequency_limits: OK [ 211.311362] Testing event cpu_frequency: OK [ 211.345514] Testing event pstate_sample: OK [ 211.379217] Testing event powernv_throttle: OK [ 211.406116] Testing event cpu_idle: OK [ 211.441557] Testing event rpm_return_int: OK [ 211.470574] Testing event rpm_idle: OK [ 211.498565] Testing event rpm_resume: OK [ 211.527057] Testing event rpm_suspend: OK [ 211.556151] Testing event benchmark_event: OK [ 211.691426] Testing event xdp_devmap_xmit: OK [ 211.718473] Testing event xdp_cpumap_enqueue: OK [ 211.750538] Testing event xdp_cpumap_kthread: OK [ 211.778813] Testing event xdp_redirect_map_err: OK [ 211.812024] Testing event xdp_redirect_map: OK [ 211.845451] Testing event xdp_redirect_err: OK [ 211.874502] Testing event xdp_redirect: OK [ 211.902885] Testing event xdp_exception: OK [ 211.934087] Testing event rseq_ip_fixup: OK [ 211.961936] Testing event rseq_update: OK [ 211.989834] Testing event file_check_and_advance_wb_err: OK [ 212.017938] Testing event filemap_set_wb_err: OK [ 212.045869] Testing event mm_filemap_add_to_page_cache: OK [ 212.073904] Testing event mm_filemap_delete_from_page_cache: OK [ 212.101913] Testing event compact_retry: OK [ 212.131034] Testing event skip_task_reaping: OK [ 212.161425] Testing event finish_task_reaping: OK [ 212.189840] Testing event start_task_reaping: OK [ 212.217922] Testing event wake_reaper: OK [ 212.245866] Testing event mark_victim: OK [ 212.273846] Testing event reclaim_retry_zone: OK [ 212.301936] Testing event oom_score_adj_update: OK [ 212.331359] Testing event mm_lru_activate: OK [ 212.360011] Testing event mm_lru_insertion: OK [ 212.389986] Testing event mm_vmscan_inactive_list_is_low: OK [ 212.417886] Testing event mm_vmscan_lru_shrink_active: OK [ 212.445742] Testing event mm_vmscan_lru_shrink_inactive: OK [ 212.474157] Testing event mm_vmscan_writepage: OK [ 212.503031] Testing event mm_vmscan_lru_isolate: OK [ 212.533439] Testing event mm_shrink_slab_end: OK [ 212.561910] Testing event mm_shrink_slab_start: OK [ 212.589984] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 212.617988] Testing event mm_vmscan_memcg_reclaim_end: OK [ 212.647156] Testing event mm_vmscan_direct_reclaim_end: OK [ 212.677785] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 212.705933] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 212.733999] Testing event mm_vmscan_direct_reclaim_begin: OK [ 212.762083] Testing event mm_vmscan_wakeup_kswapd: OK [ 212.789873] Testing event mm_vmscan_kswapd_wake: OK [ 212.817997] Testing event mm_vmscan_kswapd_sleep: OK [ 212.845908] Testing event percpu_destroy_chunk: OK [ 212.873916] Testing event percpu_create_chunk: OK [ 212.902045] Testing event percpu_alloc_percpu_fail: OK [ 212.929896] Testing event percpu_free_percpu: OK [ 212.958118] Testing event percpu_alloc_percpu: OK [ 212.986779] Testing event mm_page_alloc_extfrag: OK [ 213.014888] Testing event mm_page_pcpu_drain: OK [ 213.045752] Testing event mm_page_alloc_zone_locked: OK [ 213.074305] Testing event mm_page_alloc: OK [ 213.102919] Testing event mm_page_free_batched: OK [ 213.132244] Testing event mm_page_free: OK [ 213.162153] Testing event kmem_cache_free: OK [ 213.190513] Testing event kfree: OK [ 213.218446] Testing event kmem_cache_alloc_node: OK [ 213.246978] Testing event kmalloc_node: OK [ 213.277991] Testing event kmem_cache_alloc: OK [ 213.306476] Testing event kmalloc: OK [ 213.337870] Testing event mm_compaction_kcompactd_wake: OK [ 213.366057] Testing event mm_compaction_wakeup_kcompactd: OK [ 213.395044] Testing event mm_compaction_kcompactd_sleep: OK [ 213.423944] Testing event mm_compaction_defer_reset: OK [ 213.453686] Testing event mm_compaction_defer_compaction: OK [ 213.481934] Testing event mm_compaction_deferred: OK [ 213.509863] Testing event mm_compaction_suitable: OK [ 213.537899] Testing event mm_compaction_finished: OK [ 213.565877] Testing event mm_compaction_try_to_compact_pages: OK [ 213.594007] Testing event mm_compaction_end: OK [ 213.623174] Testing event mm_compaction_begin: OK [ 213.652259] Testing event mm_compaction_migratepages: OK [ 213.682018] Testing event mm_compaction_isolate_freepages: OK [ 213.709905] Testing event mm_compaction_isolate_migratepages: OK [ 213.737900] Testing event mm_migrate_pages: OK [ 213.765812] Testing event mm_collapse_huge_page_swapin: OK [ 213.794608] Testing event mm_collapse_huge_page_isolate: OK [ 213.829745] Testing event mm_collapse_huge_page: OK [ 213.857869] Testing event mm_khugepaged_scan_pmd: OK [ 213.885898] Testing event test_pages_isolated: OK [ 213.913848] Testing event cma_release: OK [ 213.941955] Testing event cma_alloc: OK [ 213.971147] Testing event page_ref_unfreeze: OK [ 214.005162] Testing event page_ref_freeze: OK [ 214.038407] Testing event page_ref_mod_unless: OK [ 214.077481] Testing event page_ref_mod_and_return: OK [ 214.105593] Testing event page_ref_mod_and_test: OK [ 214.261155] Testing event page_ref_mod: OK [ 214.325959] Testing event page_ref_set: OK [ 214.361855] Testing event sb_clear_inode_writeback: OK [ 214.390585] Testing event sb_mark_inode_writeback: OK [ 214.418509] Testing event writeback_dirty_inode_enqueue: OK [ 214.446567] Testing event writeback_lazytime_iput: OK [ 214.474447] Testing event writeback_lazytime: OK [ 214.501912] Testing event writeback_single_inode: OK [ 214.529775] Testing event writeback_single_inode_start: OK [ 214.558005] Testing event writeback_wait_iff_congested: OK [ 214.587177] Testing event writeback_congestion_wait: OK [ 214.616093] Testing event writeback_sb_inodes_requeue: OK [ 214.645879] Testing event balance_dirty_pages: OK [ 214.674333] Testing event bdi_dirty_ratelimit: OK [ 214.702500] Testing event global_dirty_state: OK [ 214.730850] Testing event writeback_queue_io: OK [ 214.761716] Testing event wbc_writepage: OK [ 214.787874] Testing event writeback_bdi_register: OK [ 214.818010] Testing event writeback_wake_background: OK [ 214.845921] Testing event writeback_pages_written: OK [ 214.873916] Testing event writeback_wait: OK [ 214.902041] Testing event writeback_written: OK [ 214.929795] Testing event writeback_start: OK [ 214.957859] Testing event writeback_exec: OK [ 214.985863] Testing event writeback_queue: OK [ 215.013832] Testing event writeback_write_inode: OK [ 215.041854] Testing event writeback_write_inode_start: OK [ 215.069967] Testing event writeback_dirty_inode: OK [ 215.100177] Testing event writeback_dirty_inode_start: OK [ 215.130622] Testing event writeback_mark_inode_dirty: OK [ 215.159709] Testing event writeback_dirty_page: OK [ 215.190004] Testing event dax_writeback_one: OK [ 215.217880] Testing event dax_writeback_range_done: OK [ 215.245973] Testing event dax_writeback_range: OK [ 215.273900] Testing event dax_insert_mapping: OK [ 215.301855] Testing event dax_insert_pfn_mkwrite: OK [ 215.331054] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 215.358765] Testing event dax_load_hole: OK [ 215.387712] Testing event dax_pte_fault_done: OK [ 215.417789] Testing event dax_pte_fault: OK [ 215.445878] Testing event dax_pmd_insert_mapping: OK [ 215.473790] Testing event dax_pmd_load_hole_fallback: OK [ 215.501866] Testing event dax_pmd_load_hole: OK [ 215.529777] Testing event dax_pmd_fault_done: OK [ 215.557652] Testing event dax_pmd_fault: OK [ 215.585756] Testing event generic_add_lease: OK [ 215.613843] Testing event time_out_leases: OK [ 215.641867] Testing event generic_delete_lease: OK [ 215.670064] Testing event break_lease_unblock: OK [ 215.697916] Testing event break_lease_block: OK [ 215.725828] Testing event break_lease_noblock: OK [ 215.753904] Testing event flock_lock_inode: OK [ 215.781886] Testing event locks_remove_posix: OK [ 215.809801] Testing event fcntl_setlk: OK [ 215.838133] Testing event posix_lock_inode: OK [ 215.866940] Testing event locks_get_lock_context: OK [ 215.897708] Testing event block_rq_remap: OK [ 215.924094] Testing event block_bio_remap: OK [ 215.953854] Testing event block_split: OK [ 215.981952] Testing event block_unplug: OK [ 216.009957] Testing event block_plug: OK [ 216.038020] Testing event block_sleeprq: OK [ 216.066749] Testing event block_getrq: OK [ 216.100017] Testing event block_bio_queue: OK [ 216.132244] Testing event block_bio_frontmerge: OK [ 216.164179] Testing event block_bio_backmerge: OK [ 216.197648] Testing event block_bio_complete: OK [ 216.224076] Testing event block_bio_bounce: OK [ 216.254126] Testing event block_rq_issue: OK [ 216.281958] Testing event block_rq_insert: OK [ 216.310800] Testing event block_rq_complete: OK [ 216.338824] Testing event block_rq_requeue: OK [ 216.369922] Testing event block_dirty_buffer: OK [ 216.397919] Testing event block_touch_buffer: OK [ 216.425927] Testing event wbt_timer: OK [ 216.453928] Testing event wbt_step: OK [ 216.481645] Testing event wbt_lat: OK [ 216.509953] Testing event wbt_stat: OK [ 216.538123] Testing event gpio_value: OK [ 216.568133] Testing event gpio_direction: OK [ 216.599640] Testing event clk_set_duty_cycle_complete: OK [ 216.632157] Testing event clk_set_duty_cycle: OK [ 216.665324] Testing event clk_set_phase_complete: OK [ 216.696085] Testing event clk_set_phase: OK [ 216.727426] Testing event clk_set_parent_complete: OK [ 216.754659] Testing event clk_set_parent: OK [ 216.783926] Testing event clk_set_rate_complete: OK [ 216.818284] Testing event clk_set_rate: OK [ 216.847094] Testing event clk_unprepare_complete: OK [ 216.877381] Testing event clk_unprepare: OK [ 216.906517] Testing event clk_prepare_complete: OK [ 216.934812] Testing event clk_prepare: OK [ 216.965679] Testing event clk_disable_complete: OK [ 216.993546] Testing event clk_disable: OK [ 217.021908] Testing event clk_enable_complete: OK [ 217.049855] Testing event clk_enable: OK [ 217.078073] Testing event rpmh_send_msg: OK [ 217.107018] Testing event rpmh_tx_done: OK [ 217.135901] Testing event regulator_set_voltage_complete: OK [ 217.166612] Testing event regulator_set_voltage: OK [ 217.194918] Testing event regulator_disable_complete: OK [ 217.225711] Testing event regulator_disable: OK [ 217.253496] Testing event regulator_enable_complete: OK [ 217.281836] Testing event regulator_enable_delay: OK [ 217.309944] Testing event regulator_enable: OK [ 217.338203] Testing event urandom_read: OK [ 217.365904] Testing event random_read: OK [ 217.393866] Testing event extract_entropy_user: OK [ 217.422014] Testing event extract_entropy: OK [ 217.451182] Testing event get_random_bytes_arch: OK [ 217.481435] Testing event get_random_bytes: OK [ 217.509968] Testing event xfer_secondary_pool: OK [ 217.537870] Testing event add_disk_randomness: OK [ 217.565891] Testing event add_input_randomness: OK [ 217.594111] Testing event debit_entropy: OK [ 217.621905] Testing event push_to_pool: OK [ 217.650996] Testing event credit_entropy_bits: OK [ 217.681936] Testing event mix_pool_bytes_nolock: OK [ 217.714856] Testing event mix_pool_bytes: OK [ 217.746100] Testing event add_device_randomness: OK [ 217.778403] Testing event io_page_fault: OK [ 217.806936] Testing event unmap: OK [ 217.841392] Testing event map: OK [ 217.869293] Testing event detach_device_from_domain: OK [ 217.898205] Testing event attach_device_to_domain: OK [ 217.925980] Testing event remove_device_from_group: OK [ 217.953942] Testing event add_device_to_group: OK [ 217.981925] Testing event regcache_drop_region: OK [ 218.009892] Testing event regmap_async_complete_done: OK [ 218.037997] Testing event regmap_async_complete_start: OK [ 218.067155] Testing event regmap_async_io_complete: OK [ 218.097413] Testing event regmap_async_write_start: OK [ 218.126042] Testing event regmap_cache_bypass: OK [ 218.154503] Testing event regmap_cache_only: OK [ 218.183668] Testing event regcache_sync: OK [ 218.215692] Testing event regmap_hw_write_done: OK [ 218.245414] Testing event regmap_hw_write_start: OK [ 218.274537] Testing event regmap_hw_read_done: OK [ 218.302823] Testing event regmap_hw_read_start: OK [ 218.333750] Testing event regmap_reg_read_cache: OK [ 218.362016] Testing event regmap_reg_read: OK [ 218.389992] Testing event regmap_reg_write: OK [ 218.417816] Testing event dma_fence_wait_end: OK [ 218.445872] Testing event dma_fence_wait_start: OK [ 218.473992] Testing event dma_fence_signaled: OK [ 218.501945] Testing event dma_fence_enable_signal: OK [ 218.530991] Testing event dma_fence_destroy: OK [ 218.561505] Testing event dma_fence_init: OK [ 218.589873] Testing event dma_fence_emit: OK [ 218.617757] Testing event sync_timeline: OK [ 218.645980] Testing event spi_transfer_stop: OK [ 218.673748] Testing event spi_transfer_start: OK [ 218.701941] Testing event spi_message_done: OK [ 218.729959] Testing event spi_message_start: OK [ 218.757897] Testing event spi_message_submit: OK [ 218.785855] Testing event spi_controller_busy: OK [ 218.813989] Testing event spi_controller_idle: OK [ 218.841973] Testing event mdio_access: OK [ 218.870878] Testing event rtc_timer_fired: OK [ 218.899369] Testing event rtc_timer_dequeue: OK [ 218.929531] Testing event rtc_timer_enqueue: OK [ 218.958308] Testing event rtc_read_offset: OK [ 218.986763] Testing event rtc_set_offset: OK [ 219.017928] Testing event rtc_alarm_irq_enable: OK [ 219.047441] Testing event rtc_irq_set_state: OK [ 219.080038] Testing event rtc_irq_set_freq: OK [ 219.112279] Testing event rtc_read_alarm: OK [ 219.140230] Testing event rtc_set_alarm: OK [ 219.170295] Testing event rtc_read_time: OK [ 219.197900] Testing event rtc_set_time: OK [ 219.225868] Testing event memory_failure_event: OK [ 219.253937] Testing event aer_event: OK [ 219.283128] Testing event non_standard_event: OK [ 219.313438] Testing event arm_event: OK [ 219.342230] Testing event mc_event: OK [ 219.371002] Testing event binder_return: OK [ 219.401500] Testing event binder_command: OK [ 219.429983] Testing event binder_unmap_kernel_end: OK [ 219.459203] Testing event binder_unmap_kernel_start: OK [ 219.488272] Testing event binder_unmap_user_end: OK [ 219.518023] Testing event binder_unmap_user_start: OK [ 219.547196] Testing event binder_alloc_page_end: OK [ 219.575863] Testing event binder_alloc_page_start: OK [ 219.604174] Testing event binder_free_lru_end: OK [ 219.632154] Testing event binder_free_lru_start: OK [ 219.660161] Testing event binder_alloc_lru_end: OK [ 219.690368] Testing event binder_alloc_lru_start: OK [ 219.719678] Testing event binder_update_page_range: OK [ 219.750057] Testing event binder_transaction_failed_buffer_release: OK [ 219.778139] Testing event binder_transaction_buffer_release: OK [ 219.805842] Testing event binder_transaction_alloc_buf: OK [ 219.834376] Testing event binder_transaction_fd_recv: OK [ 219.862948] Testing event binder_transaction_fd_send: OK [ 219.893495] Testing event binder_transaction_ref_to_ref: OK [ 219.921964] Testing event binder_transaction_ref_to_node: OK [ 219.949832] Testing event binder_transaction_node_to_ref: OK [ 219.977933] Testing event binder_transaction_received: OK [ 220.005835] Testing event binder_transaction: OK [ 220.034010] Testing event binder_wait_for_work: OK [ 220.061741] Testing event binder_read_done: OK [ 220.089911] Testing event binder_write_done: OK [ 220.118689] Testing event binder_ioctl_done: OK [ 220.147644] Testing event binder_unlock: OK [ 220.177758] Testing event binder_locked: OK [ 220.205835] Testing event binder_lock: OK [ 220.233705] Testing event binder_ioctl: OK [ 220.261787] Testing event br_fdb_update: OK [ 220.289768] Testing event fdb_delete: OK [ 220.317594] Testing event br_fdb_external_learn_add: OK [ 220.345931] Testing event br_fdb_add: OK [ 220.373880] Testing event qdisc_dequeue: OK [ 220.401957] Testing event fib_table_lookup: OK [ 220.431180] Testing event tcp_probe: OK [ 220.460288] Testing event tcp_retransmit_synack: OK [ 220.489921] Testing event tcp_rcv_space_adjust: OK [ 220.517792] Testing event tcp_destroy_sock: OK [ 220.545913] Testing event tcp_receive_reset: OK [ 220.575173] Testing event tcp_send_reset: OK [ 220.604199] Testing event tcp_retransmit_skb: OK [ 220.633951] Testing event udp_fail_queue_rcv_skb: OK [ 220.662068] Testing event inet_sock_set_state: OK [ 220.691219] Testing event sock_exceed_buf_limit: OK [ 220.721369] Testing event sock_rcvqueue_full: OK [ 220.749816] Testing event napi_poll: OK [ 220.778256] Testing event netif_rx_ni_entry: OK [ 220.805984] Testing event netif_rx_entry: OK [ 220.834045] Testing event netif_receive_skb_list_entry: OK [ 220.862827] Testing event netif_receive_skb_entry: OK [ 220.892154] Testing event napi_gro_receive_entry: OK [ 220.922756] Testing event napi_gro_frags_entry: OK [ 220.950772] Testing event netif_rx: OK [ 220.981539] Testing event netif_receive_skb: OK [ 221.010512] Testing event net_dev_queue: OK [ 221.039645] Testing event net_dev_xmit: OK [ 221.070607] Testing event net_dev_start_xmit: OK [ 221.098432] Testing event skb_copy_datagram_iovec: OK [ 221.127475] Testing event consume_skb: OK [ 221.159541] Testing event kfree_skb: OK [ 221.192114] Testing event 9p_protocol_dump: OK [ 221.233847] Testing event 9p_client_res: OK [ 221.262385] Testing event 9p_client_req: OK [ 221.289963] Running tests on trace event systems: [ 221.290632] Testing event system 9p: OK [ 221.332022] Testing event system skb: OK [ 221.366011] Testing event system net: OK [ 221.405705] Testing event system napi: OK [ 221.445228] Testing event system sock: OK [ 221.475767] Testing event system udp: OK [ 221.506677] Testing event system tcp: OK [ 221.542437] Testing event system fib: OK [ 221.574648] Testing event system qdisc: OK [ 221.606951] Testing event system bridge: OK [ 221.638076] Testing event system binder: OK [ 221.697092] Testing event system ras: OK [ 221.727578] Testing event system rtc: OK [ 221.777250] Testing event system mdio: OK [ 221.807423] Testing event system spi: OK [ 221.849663] Testing event system sync_trace: OK [ 221.878575] Testing event system dma_fence: OK [ 221.918913] Testing event system regmap: OK [ 221.961492] Testing event system iommu: OK [ 221.997538] Testing event system random: OK [ 222.047512] Testing event system regulator: OK [ 222.080256] Testing event system rpmh: OK [ 222.121949] Testing event system clk: OK [ 222.166633] Testing event system gpio: OK [ 222.206737] Testing event system wbt: OK [ 222.239343] Testing event system block: OK [ 222.285587] Testing event system filelock: OK [ 222.323175] Testing event system fs_dax: OK [ 222.366987] Testing event system writeback: OK [ 222.426474] Testing event system page_ref: OK [ 222.637238] Testing event system cma: OK [ 222.675468] Testing event system page_isolation: OK [ 222.706790] Testing event system huge_memory: OK [ 222.741913] Testing event system migrate: OK [ 222.772061] Testing event system compaction: OK [ 222.814159] Testing event system kmem: OK [ 222.867409] Testing event system percpu: OK [ 222.901538] Testing event system vmscan: OK [ 222.944214] Testing event system pagemap: OK [ 222.975754] Testing event system oom: OK [ 223.018261] Testing event system filemap: OK [ 223.057561] Testing event system rseq: OK [ 223.088019] Testing event system xdp: OK [ 223.129337] Testing event system benchmark: OK [ 223.262903] Testing event system rpm: OK [ 223.298228] Testing event system power: OK [ 223.358502] Testing event system test: OK [ 223.389802] Testing event system preemptirq: OK [ 223.459700] Testing event system cgroup: OK [ 223.503246] Testing event system module: OK [ 223.541916] Testing event system alarmtimer: OK [ 223.579520] Testing event system timer: OK [ 223.627334] Testing event system swiotlb: OK [ 223.663750] Testing event system rcu: OK [ 223.726590] Testing event system printk: OK [ 223.754654] Testing event system lock: OK [ 223.867499] Testing event system sched: OK [ 223.925842] Testing event system workqueue: OK [ 223.967120] Testing event system signal: OK [ 224.002324] Testing event system irq: OK [ 224.037561] Testing event system cpuhp: OK [ 224.079676] Testing event system task: OK [ 224.110569] Testing event system kvm: OK [ 224.173185] Testing event system emulation: OK [ 224.202474] Testing event system ipi: OK [ 224.245554] Testing event system raw_syscalls: OK [ 224.275451] Testing event system initcall: OK [ 224.307632] Running tests on all trace events: [ 224.308183] Testing all events: OK [ 227.542300] ftrace_replace_code: 1 [ 231.098785] hrtimer: interrupt took 11595936 ns [ 305.507219] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 305.536187] Running tests again, along with the function tracer [ 305.547542] Running tests on trace events: [ 305.573270] Testing event initcall_finish: OK [ 305.856679] Testing event initcall_start: OK [ 306.396631] Testing event initcall_level: OK [ 306.791193] Testing event sys_exit: OK [ 307.277524] Testing event sys_enter: OK [ 307.817013] Testing event ipi_exit: OK [ 308.285373] Testing event ipi_entry: OK [ 308.631485] Testing event ipi_raise: OK [ 309.061762] Testing event instruction_emulation: OK [ 309.570351] Testing event kvm_halt_poll_ns: OK [ 310.010936] Testing event kvm_age_page: OK [ 310.443093] Testing event kvm_fpu: OK [ 310.940686] Testing event kvm_mmio: OK [ 311.395784] Testing event kvm_ack_irq: OK [ 311.824941] Testing event kvm_set_irq: OK [ 312.246354] Testing event kvm_vcpu_wakeup: OK [ 312.722557] Testing event kvm_userspace_exit: OK [ 314.575133] Testing event kvm_timer_update_irq: OK [ 315.046772] Testing event kvm_toggle_cache: OK [ 315.451315] Testing event kvm_set_way_flush: OK [ 316.061988] Testing event kvm_test_age_hva: OK [ 316.519889] Testing event kvm_age_hva: OK [ 316.881136] Testing event kvm_set_spte_hva: OK [ 317.245624] Testing event kvm_unmap_hva_range: OK [ 317.755889] Testing event kvm_mmio_emulate: OK [ 318.158140] Testing event kvm_irq_line: OK [ 318.510269] Testing event kvm_access_fault: OK [ 319.004965] Testing event kvm_guest_fault: OK [ 319.458379] Testing event kvm_exit: OK [ 319.903114] Testing event kvm_entry: OK [ 320.360404] Testing event kvm_set_guest_debug: OK [ 320.864649] Testing event kvm_handle_sys_reg: OK [ 321.259994] Testing event trap_reg: OK [ 321.660608] Testing event kvm_arm_set_regset: OK [ 322.141193] Testing event kvm_arm_set_dreg32: OK [ 322.606592] Testing event kvm_arm_clear_debug: OK [ 323.089377] Testing event kvm_arm_setup_debug: OK [ 323.518382] Testing event kvm_hvc_arm64: OK [ 323.987360] Testing event kvm_wfx_arm64: OK [ 324.490722] Testing event vgic_update_irq_pending: OK [ 324.915679] Testing event task_rename: OK [ 325.363041] Testing event task_newtask: OK [ 325.844788] Testing event cpuhp_exit: OK [ 326.284090] Testing event cpuhp_multi_enter: OK [ 326.746597] Testing event cpuhp_enter: OK [ 327.244246] Testing event softirq_raise: OK [ 327.676778] Testing event softirq_exit: OK [ 328.025764] Testing event softirq_entry: OK [ 328.608901] Testing event irq_handler_exit: OK [ 329.000986] Testing event irq_handler_entry: OK [ 329.506243] Testing event signal_deliver: OK [ 329.840244] Testing event signal_generate: OK [ 330.285233] Testing event workqueue_execute_end: OK [ 330.682541] Testing event workqueue_execute_start: OK [ 331.158284] Testing event workqueue_activate_work: OK [ 331.565023] Testing event workqueue_queue_work: OK [ 331.978495] Testing event sched_wake_idle_without_ipi: OK [ 332.346171] Testing event sched_swap_numa: OK [ 332.790656] Testing event sched_stick_numa: OK [ 333.261230] Testing event sched_move_numa: OK [ 333.656482] Testing event sched_process_hang: OK [ 334.090260] Testing event sched_pi_setprio: OK [ 334.530536] Testing event sched_stat_runtime: OK [ 335.114973] Testing event sched_stat_blocked: OK [ 335.585200] Testing event sched_stat_iowait: OK [ 336.015521] Testing event sched_stat_sleep: OK [ 336.341599] Testing event sched_stat_wait: OK [ 336.813302] Testing event sched_process_exec: OK [ 337.154853] Testing event sched_process_fork: OK [ 337.539822] Testing event sched_process_wait: OK [ 337.971243] Testing event sched_wait_task: OK [ 338.376031] Testing event sched_process_exit: OK [ 338.765046] Testing event sched_process_free: OK [ 339.183802] Testing event sched_migrate_task: OK [ 339.678629] Testing event sched_switch: OK [ 340.145831] Testing event sched_wakeup_new: OK [ 340.632568] Testing event sched_wakeup: OK [ 341.057318] Testing event sched_waking: OK [ 341.578532] Testing event sched_kthread_stop_ret: OK [ 342.010620] Testing event sched_kthread_stop: OK [ 342.482608] Testing event lock_acquired: OK [ 342.942037] Testing event lock_contended: OK [ 343.416937] Testing event lock_release: OK [ 343.929664] Testing event lock_acquire: OK [ 344.358641] Testing event console: OK [ 344.848945] Testing event rcu_barrier: OK [ 345.199139] Testing event rcu_torture_read: OK [ 345.690201] Testing event rcu_batch_end: OK [ 346.186392] Testing event rcu_invoke_kfree_callback: OK [ 346.629756] Testing event rcu_invoke_callback: OK [ 347.062657] Testing event rcu_batch_start: OK [ 347.540808] Testing event rcu_kfree_callback: OK [ 347.876278] Testing event rcu_callback: OK [ 348.266391] Testing event rcu_dyntick: OK [ 348.724155] Testing event rcu_fqs: OK [ 349.113585] Testing event rcu_quiescent_state_report: OK [ 349.645291] Testing event rcu_unlock_preempted_task: OK [ 350.139997] Testing event rcu_preempt_task: OK [ 350.610454] Testing event rcu_exp_funnel_lock: OK [ 351.023450] Testing event rcu_exp_grace_period: OK [ 351.565678] Testing event rcu_grace_period_init: OK [ 352.054427] Testing event rcu_future_grace_period: OK [ 352.501560] Testing event rcu_grace_period: OK [ 352.980183] Testing event rcu_utilization: OK [ 353.465628] Testing event swiotlb_bounced: OK [ 354.051639] Testing event tick_stop: OK [ 354.342483] Testing event itimer_expire: OK [ 354.819554] Testing event itimer_state: OK [ 355.239768] Testing event hrtimer_cancel: OK [ 355.682427] Testing event hrtimer_expire_exit: OK [ 356.102770] Testing event hrtimer_expire_entry: OK [ 356.617685] Testing event hrtimer_start: OK [ 357.118214] Testing event hrtimer_init: OK [ 357.585431] Testing event timer_cancel: OK [ 358.062770] Testing event timer_expire_exit: OK [ 358.357490] Testing event timer_expire_entry: OK [ 358.758559] Testing event timer_start: OK [ 359.157236] Testing event timer_init: OK [ 359.634810] Testing event alarmtimer_cancel: OK [ 360.146937] Testing event alarmtimer_start: OK [ 360.593548] Testing event alarmtimer_fired: OK [ 361.109172] Testing event alarmtimer_suspend: OK [ 361.550978] Testing event module_request: OK [ 361.938761] Testing event module_put: OK [ 362.434121] Testing event module_get: OK [ 362.941238] Testing event module_free: OK [ 363.379833] Testing event module_load: OK [ 363.820002] Testing event cgroup_transfer_tasks: OK [ 364.246578] Testing event cgroup_attach_task: OK [ 364.595083] Testing event cgroup_rename: OK [ 365.107658] Testing event cgroup_release: OK [ 365.617208] Testing event cgroup_rmdir: OK [ 366.102325] Testing event cgroup_mkdir: OK [ 366.524776] Testing event cgroup_remount: OK [ 367.040182] Testing event cgroup_destroy_root: OK [ 367.398539] Testing event cgroup_setup_root: OK [ 368.112430] Testing event irq_enable: OK [ 368.872227] Testing event irq_disable: OK [ 369.565675] Testing event ftrace_test_filter: OK [ 369.943051] Testing event dev_pm_qos_remove_request: OK [ 370.461669] Testing event dev_pm_qos_update_request: OK [ 370.957442] Testing event dev_pm_qos_add_request: OK [ 371.431363] Testing event pm_qos_update_flags: OK [ 371.786501] Testing event pm_qos_update_target: OK [ 372.253647] Testing event pm_qos_update_request_timeout: OK [ 372.616072] Testing event pm_qos_remove_request: OK [ 373.018909] Testing event pm_qos_update_request: OK [ 373.557004] Testing event pm_qos_add_request: OK [ 374.043641] Testing event power_domain_target: OK [ 374.453940] Testing event clock_set_rate: OK [ 374.862741] Testing event clock_disable: OK [ 375.347026] Testing event clock_enable: OK [ 375.943417] Testing event wakeup_source_deactivate: OK [ 376.408056] Testing event wakeup_source_activate: OK [ 376.822890] Testing event suspend_resume: OK [ 377.401321] Testing event device_pm_callback_end: OK [ 379.240067] Testing event device_pm_callback_start: OK [ 379.715698] Testing event cpu_frequency_limits: OK [ 380.163981] Testing event cpu_frequency: OK [ 380.610283] Testing event pstate_sample: OK [ 381.765347] Testing event powernv_throttle: OK [ 382.119819] Testing event cpu_idle: OK [ 382.665511] Testing event rpm_return_int: OK [ 383.205506] Testing event rpm_idle: OK [ 383.650526] Testing event rpm_resume: OK [ 384.067538] Testing event rpm_suspend: OK [ 384.807994] Testing event benchmark_event: OK [ 385.556290] Testing event xdp_devmap_xmit: OK [ 385.904705] Testing event xdp_cpumap_enqueue: OK [ 386.337503] Testing event xdp_cpumap_kthread: OK [ 386.700050] Testing event xdp_redirect_map_err: OK [ 387.203828] Testing event xdp_redirect_map: OK [ 387.621216] Testing event xdp_redirect_err: OK [ 388.104835] Testing event xdp_redirect: OK [ 388.608985] Testing event xdp_exception: OK [ 389.066766] Testing event rseq_ip_fixup: OK [ 391.236520] Testing event rseq_update: OK [ 391.617762] Testing event file_check_and_advance_wb_err: OK [ 392.118065] Testing event filemap_set_wb_err: OK [ 392.572842] Testing event mm_filemap_add_to_page_cache: OK [ 393.053827] Testing event mm_filemap_delete_from_page_cache: OK [ 393.522293] Testing event compact_retry: OK [ 393.961717] Testing event skip_task_reaping: OK [ 394.460696] Testing event finish_task_reaping: OK [ 394.941936] Testing event start_task_reaping: OK [ 397.489445] Testing event wake_reaper: OK [ 398.575542] Testing event mark_victim: OK [ 399.063262] Testing event reclaim_retry_zone: OK [ 399.588550] Testing event oom_score_adj_update: OK [ 399.979497] Testing event mm_lru_activate: OK [ 400.490504] Testing event mm_lru_insertion: OK [ 400.894402] Testing event mm_vmscan_inactive_list_is_low: OK [ 401.355929] Testing event mm_vmscan_lru_shrink_active: OK [ 401.771758] Testing event mm_vmscan_lru_shrink_inactive: OK [ 402.252479] Testing event mm_vmscan_writepage: OK [ 402.668692] Testing event mm_vmscan_lru_isolate: OK [ 403.229695] Testing event mm_shrink_slab_end: OK [ 403.648981] Testing event mm_shrink_slab_start: OK [ 404.097871] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 404.537651] Testing event mm_vmscan_memcg_reclaim_end: OK [ 404.973752] Testing event mm_vmscan_direct_reclaim_end: OK [ 405.390568] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 405.847772] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 406.256415] Testing event mm_vmscan_direct_reclaim_begin: OK [ 406.733477] Testing event mm_vmscan_wakeup_kswapd: OK [ 407.312703] Testing event mm_vmscan_kswapd_wake: OK [ 407.645030] Testing event mm_vmscan_kswapd_sleep: OK [ 408.012775] Testing event percpu_destroy_chunk: OK [ 408.442000] Testing event percpu_create_chunk: OK [ 408.869100] Testing event percpu_alloc_percpu_fail: OK [ 409.320830] Testing event percpu_free_percpu: OK [ 409.757873] Testing event percpu_alloc_percpu: OK [ 410.228580] Testing event mm_page_alloc_extfrag: OK [ 410.633695] Testing event mm_page_pcpu_drain: OK [ 411.210236] Testing event mm_page_alloc_zone_locked: OK [ 411.625178] Testing event mm_page_alloc: OK [ 412.133183] Testing event mm_page_free_batched: OK [ 412.574503] Testing event mm_page_free: OK [ 413.056918] Testing event kmem_cache_free: OK [ 413.539088] Testing event kfree: OK [ 413.976780] Testing event kmem_cache_alloc_node: OK [ 414.381484] Testing event kmalloc_node: OK [ 414.838852] Testing event kmem_cache_alloc: OK [ 415.313330] Testing event kmalloc: OK [ 415.798648] Testing event mm_compaction_kcompactd_wake: OK [ 416.214371] Testing event mm_compaction_wakeup_kcompactd: OK [ 416.700340] Testing event mm_compaction_kcompactd_sleep: OK [ 417.178825] Testing event mm_compaction_defer_reset: OK [ 417.647351] Testing event mm_compaction_defer_compaction: OK [ 417.992146] Testing event mm_compaction_deferred: OK [ 419.278756] Testing event mm_compaction_suitable: OK [ 419.658464] Testing event mm_compaction_finished: OK [ 420.084817] Testing event mm_compaction_try_to_compact_pages: OK [ 420.541258] Testing event mm_compaction_end: OK [ 420.980951] Testing event mm_compaction_begin: OK [ 421.451249] Testing event mm_compaction_migratepages: OK [ 421.835495] Testing event mm_compaction_isolate_freepages: OK [ 422.304743] Testing event mm_compaction_isolate_migratepages: OK [ 422.728523] Testing event mm_migrate_pages: OK [ 423.200462] Testing event mm_collapse_huge_page_swapin: OK [ 423.706783] Testing event mm_collapse_huge_page_isolate: OK [ 424.133686] Testing event mm_collapse_huge_page: OK [ 424.597232] Testing event mm_khugepaged_scan_pmd: OK [ 425.084106] Testing event test_pages_isolated: OK [ 425.551986] Testing event cma_release: OK [ 426.036324] Testing event cma_alloc: OK [ 426.521709] Testing event page_ref_unfreeze: OK [ 427.091012] Testing event page_ref_freeze: OK [ 427.454733] Testing event page_ref_mod_unless: OK [ 428.056040] Testing event page_ref_mod_and_return: OK [ 428.528720] Testing event page_ref_mod_and_test: OK [ 429.917354] Testing event page_ref_mod: OK [ 430.638831] Testing event page_ref_set: OK [ 431.245287] Testing event sb_clear_inode_writeback: OK [ 431.725236] Testing event sb_mark_inode_writeback: OK [ 432.136266] Testing event writeback_dirty_inode_enqueue: OK [ 432.544357] Testing event writeback_lazytime_iput: OK [ 432.998258] Testing event writeback_lazytime: OK [ 433.377472] Testing event writeback_single_inode: OK [ 433.783134] Testing event writeback_single_inode_start: OK [ 434.247920] Testing event writeback_wait_iff_congested: OK [ 434.635111] Testing event writeback_congestion_wait: OK [ 435.086590] Testing event writeback_sb_inodes_requeue: OK [ 435.539050] Testing event balance_dirty_pages: OK [ 436.035077] Testing event bdi_dirty_ratelimit: OK [ 436.504759] Testing event global_dirty_state: OK [ 437.013878] Testing event writeback_queue_io: OK [ 437.462752] Testing event wbc_writepage: OK [ 438.011505] Testing event writeback_bdi_register: OK [ 438.461597] Testing event writeback_wake_background: OK [ 438.806629] Testing event writeback_pages_written: OK [ 439.360947] Testing event writeback_wait: OK [ 439.707548] Testing event writeback_written: OK [ 440.061086] Testing event writeback_start: OK [ 440.490446] Testing event writeback_exec: OK [ 441.148515] Testing event writeback_queue: OK [ 441.583947] Testing event writeback_write_inode: OK [ 441.982772] Testing event writeback_write_inode_start: OK [ 442.415028] Testing event writeback_dirty_inode: OK [ 442.957564] Testing event writeback_dirty_inode_start: OK [ 443.397755] Testing event writeback_mark_inode_dirty: OK [ 443.795488] Testing event writeback_dirty_page: OK [ 444.328664] Testing event dax_writeback_one: OK [ 444.747379] Testing event dax_writeback_range_done: OK [ 445.198733] Testing event dax_writeback_range: OK [ 445.712420] Testing event dax_insert_mapping: OK [ 446.177823] Testing event dax_insert_pfn_mkwrite: OK [ 446.589845] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 446.998283] Testing event dax_load_hole: OK [ 447.470316] Testing event dax_pte_fault_done: OK [ 447.968697] Testing event dax_pte_fault: OK [ 448.416037] Testing event dax_pmd_insert_mapping: OK [ 449.162306] Testing event dax_pmd_load_hole_fallback: OK [ 449.609007] Testing event dax_pmd_load_hole: OK [ 450.084153] Testing event dax_pmd_fault_done: OK [ 450.584915] Testing event dax_pmd_fault: OK [ 450.963331] Testing event generic_add_lease: OK [ 451.415817] Testing event time_out_leases: OK [ 451.820812] Testing event generic_delete_lease: OK [ 452.298206] Testing event break_lease_unblock: OK [ 452.744500] Testing event break_lease_block: OK [ 453.268618] Testing event break_lease_noblock: OK [ 453.665761] Testing event flock_lock_inode: OK [ 454.121830] Testing event locks_remove_posix: OK [ 454.616058] Testing event fcntl_setlk: OK [ 455.076379] Testing event posix_lock_inode: OK [ 455.480082] Testing event locks_get_lock_context: OK [ 455.935066] Testing event block_rq_remap: OK [ 456.320904] Testing event block_bio_remap: OK [ 456.599678] Testing event block_split: OK [ 457.038748] Testing event block_unplug: OK [ 457.457801] Testing event block_plug: OK [ 457.841612] Testing event block_sleeprq: OK [ 458.233567] Testing event block_getrq: OK [ 458.629748] Testing event block_bio_queue: OK [ 459.028150] Testing event block_bio_frontmerge: OK [ 459.524003] Testing event block_bio_backmerge: OK [ 460.048855] Testing event block_bio_complete: OK [ 460.471970] Testing event block_bio_bounce: OK [ 460.952824] Testing event block_rq_issue: OK [ 461.460079] Testing event block_rq_insert: OK [ 461.846449] Testing event block_rq_complete: OK [ 462.367672] Testing event block_rq_requeue: OK [ 462.766277] Testing event block_dirty_buffer: OK [ 463.234035] Testing event block_touch_buffer: OK [ 463.608871] Testing event wbt_timer: OK [ 464.066214] Testing event wbt_step: OK [ 464.555914] Testing event wbt_lat: OK [ 465.105482] Testing event wbt_stat: OK [ 465.405549] Testing event gpio_value: OK [ 465.933673] Testing event gpio_direction: OK [ 466.323608] Testing event clk_set_duty_cycle_complete: OK [ 466.795183] Testing event clk_set_duty_cycle: OK [ 467.214419] Testing event clk_set_phase_complete: OK [ 467.713544] Testing event clk_set_phase: OK [ 468.232247] Testing event clk_set_parent_complete: OK [ 468.678537] Testing event clk_set_parent: OK [ 469.071483] Testing event clk_set_rate_complete: OK [ 469.494291] Testing event clk_set_rate: OK [ 469.930768] Testing event clk_unprepare_complete: OK [ 470.501096] Testing event clk_unprepare: OK [ 470.892112] Testing event clk_prepare_complete: OK [ 471.340685] Testing event clk_prepare: OK [ 471.729236] Testing event clk_disable_complete: OK [ 472.141981] Testing event clk_disable: OK [ 472.517333] Testing event clk_enable_complete: OK [ 472.959427] Testing event clk_enable: OK [ 473.609749] Testing event rpmh_send_msg: OK [ 474.015111] Testing event rpmh_tx_done: OK [ 474.593958] Testing event regulator_set_voltage_complete: OK [ 475.136938] Testing event regulator_set_voltage: OK [ 475.555989] Testing event regulator_disable_complete: OK [ 476.219273] Testing event regulator_disable: OK [ 476.634736] Testing event regulator_enable_complete: OK [ 477.068684] Testing event regulator_enable_delay: OK [ 477.492694] Testing event regulator_enable: OK [ 477.948563] Testing event urandom_read: OK [ 478.396418] Testing event random_read: OK [ 478.842969] Testing event extract_entropy_user: OK [ 479.280077] Testing event extract_entropy: OK [ 479.743177] Testing event get_random_bytes_arch: OK [ 480.212989] Testing event get_random_bytes: OK [ 480.657827] Testing event xfer_secondary_pool: OK [ 481.099547] Testing event add_disk_randomness: OK [ 481.601595] Testing event add_input_randomness: OK [ 482.061332] Testing event debit_entropy: OK [ 482.586536] Testing event push_to_pool: OK [ 482.991742] Testing event credit_entropy_bits: OK [ 483.554324] Testing event mix_pool_bytes_nolock: OK [ 484.005508] Testing event mix_pool_bytes: OK [ 484.433974] Testing event add_device_randomness: OK [ 484.901914] Testing event io_page_fault: OK [ 485.439340] Testing event unmap: OK [ 485.822117] Testing event map: OK [ 486.255970] Testing event detach_device_from_domain: OK [ 486.717921] Testing event attach_device_to_domain: OK [ 487.182723] Testing event remove_device_from_group: OK [ 487.636112] Testing event add_device_to_group: OK [ 488.085894] Testing event regcache_drop_region: OK [ 488.642603] Testing event regmap_async_complete_done: OK [ 489.035306] Testing event regmap_async_complete_start: OK [ 489.490266] Testing event regmap_async_io_complete: OK [ 489.960113] Testing event regmap_async_write_start: OK [ 490.366624] Testing event regmap_cache_bypass: OK [ 490.800277] Testing event regmap_cache_only: OK [ 491.298192] Testing event regcache_sync: OK [ 491.749543] Testing event regmap_hw_write_done: OK [ 492.205418] Testing event regmap_hw_write_start: OK [ 492.677509] Testing event regmap_hw_read_done: OK [ 493.158129] Testing event regmap_hw_read_start: OK [ 493.627268] Testing event regmap_reg_read_cache: OK [ 494.085307] Testing event regmap_reg_read: OK [ 494.447255] Testing event regmap_reg_write: OK [ 494.894974] Testing event dma_fence_wait_end: OK [ 495.425966] Testing event dma_fence_wait_start: OK [ 495.905892] Testing event dma_fence_signaled: OK [ 496.319658] Testing event dma_fence_enable_signal: OK [ 496.746223] Testing event dma_fence_destroy: OK [ 497.186199] Testing event dma_fence_init: OK [ 497.631440] Testing event dma_fence_emit: OK [ 498.065401] Testing event sync_timeline: OK [ 498.450991] Testing event spi_transfer_stop: OK [ 498.886601] Testing event spi_transfer_start: OK [ 499.361750] Testing event spi_message_done: OK [ 499.740349] Testing event spi_message_start: OK [ 500.247315] Testing event spi_message_submit: OK [ 500.683407] Testing event spi_controller_busy: OK [ 501.087527] Testing event spi_controller_idle: OK [ 501.565493] Testing event mdio_access: OK [ 501.973692] Testing event rtc_timer_fired: OK [ 502.449139] Testing event rtc_timer_dequeue: OK [ 502.923265] Testing event rtc_timer_enqueue: OK [ 503.271426] Testing event rtc_read_offset: OK [ 503.742528] Testing event rtc_set_offset: OK [ 504.160583] Testing event rtc_alarm_irq_enable: OK [ 504.657032] Testing event rtc_irq_set_state: OK [ 505.074162] Testing event rtc_irq_set_freq: OK [ 505.670888] Testing event rtc_read_alarm: OK [ 506.527893] Testing event rtc_set_alarm: OK [ 506.789507] Testing event rtc_read_time: OK [ 507.225890] Testing event rtc_set_time: OK [ 507.648443] Testing event memory_failure_event: OK [ 508.156076] Testing event aer_event: OK [ 508.577218] Testing event non_standard_event: OK [ 509.008930] Testing event arm_event: OK [ 509.382473] Testing event mc_event: OK [ 509.838640] Testing event binder_return: OK [ 510.332138] Testing event binder_command: OK [ 510.732877] Testing event binder_unmap_kernel_end: OK [ 511.693012] Testing event binder_unmap_kernel_start: OK [ 512.113499] Testing event binder_unmap_user_end: OK [ 512.571597] Testing event binder_unmap_user_start: OK [ 513.032403] Testing event binder_alloc_page_end: OK [ 513.419244] Testing event binder_alloc_page_start: OK [ 513.846549] Testing event binder_free_lru_end: OK [ 514.198207] Testing event binder_free_lru_start: OK [ 514.713266] Testing event binder_alloc_lru_end: OK [ 515.219888] Testing event binder_alloc_lru_start: OK [ 515.554881] Testing event binder_update_page_range: OK [ 516.081285] Testing event binder_transaction_failed_buffer_release: OK [ 516.530637] Testing event binder_transaction_buffer_release: OK [ 516.977367] Testing event binder_transaction_alloc_buf: OK [ 517.449485] Testing event binder_transaction_fd_recv: OK [ 517.886460] Testing event binder_transaction_fd_send: OK [ 518.336346] Testing event binder_transaction_ref_to_ref: OK [ 518.786760] Testing event binder_transaction_ref_to_node: OK [ 519.211574] Testing event binder_transaction_node_to_ref: OK [ 519.630658] Testing event binder_transaction_received: OK [ 520.230965] Testing event binder_transaction: OK [ 520.696602] Testing event binder_wait_for_work: OK [ 521.135626] Testing event binder_read_done: OK [ 521.429006] Testing event binder_write_done: OK [ 522.021689] Testing event binder_ioctl_done: OK [ 523.225851] Testing event binder_unlock: OK [ 523.653261] Testing event binder_locked: OK [ 524.097723] Testing event binder_lock: OK [ 524.517483] Testing event binder_ioctl: OK [ 524.961540] Testing event br_fdb_update: OK [ 525.415420] Testing event fdb_delete: OK [ 525.908385] Testing event br_fdb_external_learn_add: OK [ 526.400061] Testing event br_fdb_add: OK [ 526.771848] Testing event qdisc_dequeue: OK [ 527.321407] Testing event fib_table_lookup: OK [ 527.826868] Testing event tcp_probe: OK [ 528.274187] Testing event tcp_retransmit_synack: OK [ 528.744801] Testing event tcp_rcv_space_adjust: OK [ 529.146941] Testing event tcp_destroy_sock: OK [ 529.623361] Testing event tcp_receive_reset: OK [ 530.117395] Testing event tcp_send_reset: OK [ 530.592388] Testing event tcp_retransmit_skb: OK [ 530.989083] Testing event udp_fail_queue_rcv_skb: OK [ 531.480473] Testing event inet_sock_set_state: OK [ 531.870469] Testing event sock_exceed_buf_limit: OK [ 532.317737] Testing event sock_rcvqueue_full: OK [ 532.634115] Testing event napi_poll: OK [ 533.106768] Testing event netif_rx_ni_entry: OK [ 533.554351] Testing event netif_rx_entry: OK [ 534.020913] Testing event netif_receive_skb_list_entry: OK [ 534.427207] Testing event netif_receive_skb_entry: OK [ 534.892948] Testing event napi_gro_receive_entry: OK [ 535.332900] Testing event napi_gro_frags_entry: OK [ 535.865354] Testing event netif_rx: OK [ 537.710358] Testing event netif_receive_skb: OK [ 538.196613] Testing event net_dev_queue: OK [ 538.659092] Testing event net_dev_xmit: OK [ 539.151223] Testing event net_dev_start_xmit: OK [ 539.563154] Testing event skb_copy_datagram_iovec: OK [ 540.003313] Testing event consume_skb: OK [ 540.469268] Testing event kfree_skb: OK [ 540.889859] Testing event 9p_protocol_dump: OK [ 541.370403] Testing event 9p_client_res: OK [ 542.735552] Testing event 9p_client_req: OK [ 543.737929] Running tests on trace event systems: [ 543.767229] Testing event system 9p: OK [ 544.295664] Testing event system skb: OK [ 544.703156] Testing event system net: OK [ 545.289607] Testing event system napi: OK [ 545.863280] Testing event system sock: OK [ 546.339167] Testing event system udp: OK [ 547.210518] Testing event system tcp: OK [ 547.770155] Testing event system fib: OK [ 548.294471] Testing event system qdisc: OK [ 548.774792] Testing event system bridge: OK [ 549.248008] Testing event system binder: OK [ 549.939916] Testing event system ras: OK [ 550.699219] Testing event system rtc: OK [ 551.354062] Testing event system mdio: OK [ 551.855752] Testing event system spi: OK [ 552.318297] Testing event system sync_trace: OK [ 552.879836] Testing event system dma_fence: OK [ 553.450595] Testing event system regmap: OK [ 554.846488] Testing event system iommu: OK [ 555.431176] Testing event system random: OK [ 556.014475] Testing event system regulator: OK [ 556.676652] Testing event system rpmh: OK [ 557.164555] Testing event system clk: OK [ 557.891902] Testing event system gpio: OK [ 558.475445] Testing event system wbt: OK [ 559.003800] Testing event system block: OK [ 559.853305] Testing event system filelock: OK [ 560.554160] Testing event system fs_dax: OK [ 561.161076] Testing event system writeback: OK [ 562.033635] Testing event system page_ref: OK [ 563.826599] Testing event system cma: OK [ 564.358138] Testing event system page_isolation: OK [ 564.865954] Testing event system huge_memory: OK [ 565.383013] Testing event system migrate: OK [ 565.918835] Testing event system compaction: OK [ 566.584356] Testing event system kmem: OK [ 567.093231] Testing event system percpu: OK [ 567.865756] Testing event system vmscan: OK [ 568.501937] Testing event system pagemap: OK [ 569.067499] Testing event system oom: OK [ 569.681222] Testing event system filemap: OK [ 570.186798] Testing event system rseq: OK [ 570.599434] Testing event system xdp: OK [ 571.202536] Testing event system benchmark: OK [ 572.010266] Testing event system rpm: OK [ 572.584243] Testing event system power: OK [ 573.416294] Testing event system test: OK [ 573.829284] Testing event system preemptirq: OK [ 574.901723] Testing event system cgroup: OK [ 575.593241] Testing event system module: OK [ 576.088164] Testing event system alarmtimer: OK [ 576.599718] Testing event system timer: OK [ 577.191756] Testing event system swiotlb: OK [ 577.683380] Testing event system rcu: OK [ 578.874488] Testing event system printk: OK [ 579.462511] Testing event system lock: OK [ 580.072545] Testing event system sched: OK [ 580.820324] Testing event system workqueue: OK [ 581.275229] Testing event system signal: OK [ 581.842254] Testing event system irq: OK [ 582.425324] Testing event system cpuhp: OK [ 582.960168] Testing event system task: OK [ 583.471802] Testing event system kvm: OK [ 584.284016] Testing event system emulation: OK [ 584.613251] Testing event system ipi: OK [ 585.137662] Testing event system raw_syscalls: OK [ 585.503185] Testing event system initcall: OK [ 585.982587] Running tests on all trace events: [ 585.992612] Testing all events: OK [ 601.047370] ftrace_replace_code: 1 [ 611.629687] ../kernel/trace/ftrace.c:2446, loop_counter: 41494 [ 611.645886] Testing ftrace filter: OK [ 611.800864] trace_kprobe: Testing kprobe tracing: OK [ 612.441427] Loading compiled-in X.509 certificates [ 612.587879] _warn_unseeded_randomness: 1 callbacks suppressed [ 612.588143] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 612.591443] Loaded X.509 cert 'Build time autogenerated kernel key: ac2c3039a2695eb0013480c54749bb09e0d7349f' [ 612.601215] page_owner is disabled [ 612.768200] Key type big_key registered [ 612.842726] Key type encrypted registered [ 612.847995] ima: No TPM chip found, activating TPM-bypass! [ 612.849504] ima: Allocated hash algorithm: sha1 [ 612.853779] No architecture policies found [ 612.861552] random: get_random_bytes called from prandom_seed_full_state+0xc0/0x198 with crng_init=0 [ 612.902091] hctosys: unable to open rtc device (rtc0) [ 612.922417] psci_checker: PSCI checker started using 1 CPUs [ 612.923048] psci_checker: Starting hotplug tests [ 612.924019] psci_checker: Trying to turn off and on again all CPUs [ 612.925623] psci_checker: Trying to turn off and on again group 0 (CPUs 0) [ 612.926600] psci_checker: Hotplug tests passed OK [ 612.927211] psci_checker: Starting suspend tests (10 cycles per state) [ 612.928160] psci_checker: cpuidle not available on CPU 0, ignoring [ 612.929638] psci_checker: Could not start suspend tests on any CPU [ 612.930242] psci_checker: PSCI checker completed [ 612.998092] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 613.247698] ### dt-test ### start of unittest - you will see error messages [ 613.283644] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 613.285292] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 613.286367] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 613.287329] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 613.288266] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 613.289811] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 613.313434] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 613.315722] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 613.318501] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 613.888912] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status [ 613.957862] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status [ 613.988252] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status [ 614.054746] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status [ 614.158440] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status [ 614.249436] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status [ 614.310175] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status [ 614.417574] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status [ 614.477649] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo [ 614.479864] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 614.481395] OF: overlay: overlay #6 is not topmost [ 614.853947] OF: overlay: remove: Could not find overlay #7 [ 614.854695] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #7 [ 615.163813] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/status [ 615.170812] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/status [ 615.173975] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@30/incline-up [ 615.175688] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@40/incline-up [ 615.191595] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/status [ 615.193593] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/color [ 615.195025] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/rate [ 615.197674] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/hvac_2 [ 615.199347] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200 [ 615.201744] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_left [ 615.203402] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_right [ 615.339367] OF: overlay: ERROR: multiple fragments add and/or delete node /testcase-data-2/substation@100/motor-1/controller [ 615.344940] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/controller/name [ 615.360943] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 615.362560] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 615.364247] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/rpm_avail [ 615.388147] ### dt-test ### end of unittest - 217 passed, 0 failed [ 615.419949] uart-pl011 9000000.pl011: no DMA platform data [ 615.885803] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 615.887066] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 615.937727] VFS: Mounted root (9p filesystem) readonly on device 0:18. [ 615.963547] devtmpfs: mounted [ 616.856970] Freeing unused kernel memory: 4096K [ 617.254528] Checked W+X mappings: passed, no W+X pages found [ 617.255432] rodata_test: all tests were successful [ 617.257093] Run /sbin/init as init process [ 617.390716] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=0 [ 617.393414] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=0 [ 617.394456] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=0 [ 618.519891] random: fast init done [ 622.397677] systemd[1]: System time before build time, advancing clock. [ 622.991080] systemd[1]: Failed to insert module 'autofs4': No such file or directory [ 627.267073] random: systemd: uninitialized urandom read (16 bytes read) [ 627.691705] random: systemd: uninitialized urandom read (16 bytes read) [ 627.698896] random: systemd: uninitialized urandom read (16 bytes read) [ 634.688070] 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) [ 634.790888] systemd[1]: Detected virtualization qemu. [ 634.799407] systemd[1]: Detected architecture arm64. Welcome to Debian GNU/Linux 9 (stretch)! [ 634.968199] systemd[1]: Set hostname to . [ 635.188301] _warn_unseeded_randomness: 3 callbacks suppressed [ 635.189855] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=1 [ 635.265789] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 636.065960] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 636.733827] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 636.735048] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 636.736046] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 637.739930] _warn_unseeded_randomness: 11 callbacks suppressed [ 637.740182] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 637.742615] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 637.743642] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 639.008304] _warn_unseeded_randomness: 12 callbacks suppressed [ 639.009756] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 639.202358] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 639.203648] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 639.880264] random: systemd: uninitialized urandom read (16 bytes read) [ 640.075836] _warn_unseeded_randomness: 6 callbacks suppressed [ 640.076081] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 640.078520] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 640.079561] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 641.155613] _warn_unseeded_randomness: 5 callbacks suppressed [ 641.155859] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 641.158554] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 641.159611] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 642.309804] _warn_unseeded_randomness: 4 callbacks suppressed [ 642.310101] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 642.311727] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 642.359037] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 643.529484] _warn_unseeded_randomness: 3 callbacks suppressed [ 643.529743] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 643.531433] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 643.566900] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 645.038764] systemd-hiberna (1257) used greatest stack depth: 10576 bytes left [ 647.379238] random: systemd-cryptse: uninitialized urandom read (16 bytes read) [ 648.192078] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 649.636250] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 652.123131] random: systemd: uninitialized urandom read (16 bytes read) [ 652.439571] random: systemd: uninitialized urandom read (16 bytes read) [ 652.447646] random: systemd: uninitialized urandom read (16 bytes read) [ 653.585411] _warn_unseeded_randomness: 1 callbacks suppressed [ 653.585682] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [ 662.462897] systemd[1]: networking.service: Cannot add dependency job, ignoring: Unit networking.service is masked. [ 662.553657] random: get_random_bytes called from generate_random_uuid+0x20/0x50 with crng_init=1 [ 662.649567] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 662.739009] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 662.785557] systemd[1]: Listening on Journal Audit Socket. [ OK ] Listening on Journal Audit Socket. [ 662.822434] systemd[1]: Listening on Syslog Socket. [ OK ] Listening on Syslog Socket. [ 662.839834] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 662.876318] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 662.909662] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 662.949734] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ OK ] Listening on /dev/initctl Compatibility Named Pipe. [ 663.069191] systemd[1]: Created slice System Slice. [ OK ] Created slice System Slice. [ OK ] Reached target Slices. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-getty.slice. [ 663.539221] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 Mounting Debug File System... Mounting POSIX Message Queue File System... [ 664.587868] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 664.589637] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 664.590751] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ OK ] Reached target Swap. [UNSUPP] Starting of Arbitrary Executable Fi…tem Automount Point not supported. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Encrypted Volumes. [ OK ] Listening on Journal Socket. [ 665.717011] _warn_unseeded_randomness: 5 callbacks suppressed [ 665.717285] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 665.718804] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 665.734628] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 Starting Load Kernel Modules... [ 667.775986] _warn_unseeded_randomness: 4 callbacks suppressed [ 667.776245] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 667.778455] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 667.779427] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 669.087513] systemd[1]: Starting Journal Service... Starting Journal Service... [ 669.309920] systemd[1]: Reached target Sockets. [ OK ] Reached target Sockets. [ 669.993760] _warn_unseeded_randomness: 8 callbacks suppressed [ 669.994058] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 670.217255] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 670.218561] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 671.468135] _warn_unseeded_randomness: 3 callbacks suppressed [ 671.468403] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 672.165900] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 672.167096] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 672.387211] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 672.489710] _warn_unseeded_randomness: 2 callbacks suppressed [ 672.489957] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 672.503935] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 672.621517] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 674.042611] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 674.790039] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 674.791195] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 674.945444] systemd[1]: Starting Create Static Device Nodes in /dev... Starting Create Static Device Nodes in /dev... [ 675.107629] _warn_unseeded_randomness: 1 callbacks suppressed [ 675.107878] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 675.118604] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 675.174907] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 676.877105] _warn_unseeded_randomness: 8 callbacks suppressed [ 676.877366] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 677.475811] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 677.477601] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 677.779112] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 678.065417] _warn_unseeded_randomness: 4 callbacks suppressed [ 678.065687] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 680.215609] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 681.229551] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 681.230807] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 681.231916] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 685.266519] systemd[1]: Mounted Debug File System. [ OK ] Mounted Debug File System. [ 685.429608] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 687.488208] systemd[1]: Started Load Kernel Modules. [ OK ] Started Load Kernel Modules. [ 687.653033] _warn_unseeded_randomness: 6 callbacks suppressed [ 687.653298] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 687.906977] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 687.908274] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 688.880257] systemd[1]: Started Remount Root and Kernel File Systems. [ OK ] Started Remount Root and Kernel File Systems. [ 689.269215] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 689.858056] _warn_unseeded_randomness: 4 callbacks suppressed [ 689.858333] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 690.049759] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 690.051008] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 690.725761] systemd[1]: Started Create Static Device Nodes in /dev. [ OK ] Started Create Static Device Nodes in /dev. [ 692.995082] _warn_unseeded_randomness: 1 callbacks suppressed [ 692.995347] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 693.153891] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 693.839553] systemd[1]: Starting udev Kernel Device Manager... Starting udev Kernel Device Manager... [ 695.667674] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 695.811767] systemd[1]: Reached target Local File Systems (Pre). [ OK ] Reached target Local File Systems (Pre). [ 695.990865] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 695.992329] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 695.993849] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 697.345836] _warn_unseeded_randomness: 4 callbacks suppressed [ 697.346112] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 697.478485] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 697.479634] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 698.059806] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 698.282832] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 700.294233] _warn_unseeded_randomness: 3 callbacks suppressed [ 700.294498] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 700.401777] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 700.402914] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 701.107263] systemd[1]: Mounting Configuration File System... Mounting Configuration File System... [ 703.679526] _warn_unseeded_randomness: 3 callbacks suppressed [ 703.679790] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 704.087491] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 704.089339] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 705.403479] _warn_unseeded_randomness: 5 callbacks suppressed [ 705.403743] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 705.405846] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 705.838771] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 706.013880] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 706.618986] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 706.633045] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 706.689583] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 708.667983] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 708.669594] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 708.873821] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 715.084092] mount (1283) used greatest stack depth: 10496 bytes left [ 715.087203] _warn_unseeded_randomness: 3 callbacks suppressed [ 715.087454] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 715.116285] systemd[1]: Started Load/Save Random Seed. [ OK ] Started Load/Save Random Seed. [ 715.353552] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 715.354792] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 715.618582] systemd[1]: Mounted Configuration File System. [ OK ] Mounted Configuration File System. [ 716.981881] _warn_unseeded_randomness: 3 callbacks suppressed [ 716.982184] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 717.224429] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 717.226190] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 718.456169] systemd[1]: Started Apply Kernel Variables. [ OK ] Started Apply Kernel Variables. [ 720.083820] systemd[1]: Started udev Kernel Device Manager. [ OK ] Started udev Kernel Device Manager. [* ] (1 of 3) A start job is running for…g all Devices (1min 2s / no limit) [** ] (1 of 3) A start job is running for…g all Devices (1min 3s / no limit) [*** ] (1 of 3) A start job is running for…g all Devices (1min 3s / no limit) [ *** ] (2 of 3) A start job is running for…v-ttyAMA0.device (1min 4s / 30min) [ *** ] (2 of 3) A start job is running for…v-ttyAMA0.device (1min 4s / 30min)[ 727.312870] _warn_unseeded_randomness: 3 callbacks suppressed [ 727.313135] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [ ***] (2 of 3) A start job is running for…v-ttyAMA0.device (1min 5s / 30min) [ **] (3 of 3) A start job is running for…urnal Service (1min 5s / 30min 5s) [ *] (3 of 3) A start job is running for…urnal Service (1min 6s / 30min 5s) [ **] (3 of 3) A start job is running for…urnal Service (1min 6s / 30min 5s) [ ***] (1 of 3) A start job is running for…g all Devices (1min 7s / no limit) [ *** ] (1 of 3) A start job is running for…g all Devices (1min 7s / no limit) [ *** ] (1 of 3) A start job is running for…g all Devices (1min 8s / no limit) [*** ] (2 of 3) A start job is running for…v-ttyAMA0.device (1min 8s / 30min) [** ] (2 of 3) A start job is running for…v-ttyAMA0.device (1min 9s / 30min) [* ] (2 of 3) A start job is running for…v-ttyAMA0.device (1min 9s / 30min) [** ] (3 of 3) A start job is running for…rnal Service (1min 10s / 30min 5s) [*** ] (3 of 3) A start job is running for…rnal Service (1min 10s / 30min 5s) [ *** ] (3 of 3) A start job is running for…rnal Service (1min 11s / 30min 5s) [ *** ] (1 of 3) A start job is running for… all Devices (1min 11s / no limit) [ ***] (1 of 3) A start job is running for… all Devices (1min 12s / no limit) [ **] (1 of 3) A start job is running for… all Devices (1min 12s / no limit) [ *] (2 of 3) A start job is running for…-ttyAMA0.device (1min 13s / 30min) [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 13s / 30min) [ ***] (2 of 3) A start job is running for…-ttyAMA0.device (1min 14s / 30min) [ *** ] (3 of 3) A start job is running for…rnal Service (1min 14s / 30min 5s) [ *** ] (3 of 3) A start job is running for…rnal Service (1min 15s / 30min 5s) [*** ] (3 of 3) A start job is running for…rnal Service (1min 15s / 30min 5s) [** ] (1 of 3) A start job is running for… all Devices (1min 16s / no limit) [* ] (1 of 3) A start job is running for… all Devices (1min 16s / no limit) [** ] (1 of 3) A start job is running for… all Devices (1min 17s / no limit)[ 740.119632] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [*** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 17s / 30min) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 18s / 30min) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 18s / 30min) [ ***] (3 of 3) A start job is running for…rnal Service (1min 19s / 30min 5s) [ **] (3 of 3) A start job is running for…rnal Service (1min 19s / 30min 5s) [ *] (3 of 3) A start job is running for…rnal Service (1min 20s / 30min 5s) [ **] (1 of 3) A start job is running for… all Devices (1min 20s / no limit) [ ***] (1 of 3) A start job is running for… all Devices (1min 21s / no limit) [ *** ] (1 of 3) A start job is running for… all Devices (1min 21s / no limit) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 22s / 30min) [*** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 22s / 30min) [** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 23s / 30min) [* ] (3 of 3) A start job is running for…rnal Service (1min 23s / 30min 5s) [** ] (3 of 3) A start job is running for…rnal Service (1min 24s / 30min 5s) [*** ] (3 of 3) A start job is running for…rnal Service (1min 24s / 30min 5s) [ *** ] (1 of 3) A start job is running for… all Devices (1min 25s / no limit) [ *** ] (1 of 3) A start job is running for… all Devices (1min 25s / no limit) [ ***] (1 of 3) A start job is running for… all Devices (1min 26s / no limit) [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 26s / 30min) [ *] (2 of 3) A start job is running for…-ttyAMA0.device (1min 27s / 30min)[ 750.081719] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 750.083132] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 750.085043] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 753.205517] _warn_unseeded_randomness: 5 callbacks suppressed [ 753.205778] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 753.207636] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 753.578362] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 31s / 30min) [ ***] (3 of 3) A start job is running for…rnal Service (1min 31s / 30min 5s) [ *** ] (3 of 3) A start job is running for…rnal Service (1min 31s / 30min 5s) [ *** ] (3 of 3) A start job is running for…rnal Service (1min 32s / 30min 5s) [*** ] (1 of 3) A start job is running for… all Devices (1min 32s / no limit) [** ] (1 of 3) A start job is running for… all Devices (1min 33s / no limit) [* ] (1 of 3) A start job is running for… all Devices (1min 33s / no limit) [** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 34s / 30min) [*** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 35s / 30min) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 35s / 30min) [ *** ] (3 of 3) A start job is running for…rnal Service (1min 35s / 30min 5s) [ ***] (3 of 3) A start job is running for…rnal Service (1min 36s / 30min 5s) [ **] (3 of 3) A start job is running for…rnal Service (1min 36s / 30min 5s) [ *] (1 of 3) A start job is running for… all Devices (1min 37s / no limit) [ **] (1 of 3) A start job is running for… all Devices (1min 37s / no limit) [ ***] (1 of 3) A start job is running for… all Devices (1min 38s / no limit) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 38s / 30min)[ 761.705639] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [ 762.783744] _warn_unseeded_randomness: 1 callbacks suppressed [ 762.784008] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 762.863969] random: crng init done Starting Flush Journal to Persistent Storage... [ *** ] (2 of 3) A start job is running for… all Devices (1min 44s / no limit) [*** ] (2 of 3) A start job is running for… all Devices (1min 44s / no limit) [** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 45s / 30min) [* ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 46s / 30min) [** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 46s / 30min) [*** ] (1 of 3) A start job is running for…tent Storage (1min 46s / 3min 10s) [ *** ] (1 of 3) A start job is running for…tent Storage (1min 47s / 3min 10s)[ 770.381855] systemd-journald[1270]: Received request to flush runtime journal from PID 1 [ *** ] (1 of 3) A start job is running for…tent Storage (1min 48s / 3min 10s) [ OK ] Started Flush Journal to Persistent Storage. Starting Create Volatile Files and Directories... [ ***] (2 of 3) A start job is running for… all Devices (1min 54s / no limit) [ **] (2 of 3) A start job is running for… all Devices (1min 55s / no limit) [ *] (2 of 3) A start job is running for… all Devices (1min 56s / no limit) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (1min 56s / 30min) [ ***] (3 of 3) A start job is running for…-ttyAMA0.device (1min 57s / 30min) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 57s / 30min) [ *** ] (1 of 3) A start job is running for… Directories (1min 58s / no limit) [*** ] (1 of 3) A start job is running for… Directories (1min 58s / no limit) [** ] (1 of 3) A start job is running for… Directories (1min 59s / no limit) [* ] (2 of 3) A start job is running for… all Devices (1min 59s / no limit) [** ] (2 of 3) A start job is running for…plug all Devices (2min / no limit) [*** ] (2 of 3) A start job is running for…plug all Devices (2min / no limit) [ *** ] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 1s / 30min) [ *** ] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 1s / 30min) [ ***] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 2s / 30min) [ **] (1 of 3) A start job is running for…d Directories (2min 2s / no limit) [ *] (1 of 3) A start job is running for…d Directories (2min 3s / no limit) [ **] (1 of 3) A start job is running for…d Directories (2min 3s / no limit) [ ***] (2 of 3) A start job is running for…g all Devices (2min 4s / no limit) [ *** ] (2 of 3) A start job is running for…g all Devices (2min 4s / no limit) [ *** ] (2 of 3) A start job is running for…g all Devices (2min 5s / no limit) [*** ] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 5s / 30min) [** ] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 6s / 30min) [* ] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 6s / 30min) [** ] (1 of 3) A start job is running for…d Directories (2min 7s / no limit) [*** ] (1 of 3) A start job is running for…d Directories (2min 7s / no limit) [ *** ] (1 of 3) A start job is running for…d Directories (2min 8s / no limit) [ *** ] (2 of 3) A start job is running for…g all Devices (2min 8s / no limit) [ ***] (2 of 3) A start job is running for…g all Devices (2min 9s / no limit) [ **] (2 of 3) A start job is running for…g all Devices (2min 9s / no limit) [ *] (3 of 3) A start job is running for…-ttyAMA0.device (2min 10s / 30min) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (2min 10s / 30min) [ ***] (3 of 3) A start job is running for…-ttyAMA0.device (2min 11s / 30min) [ *** ] (1 of 3) A start job is running for… Directories (2min 11s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (2min 12s / no limit) [*** ] (1 of 3) A start job is running for… Directories (2min 12s / no limit) [** ] (2 of 3) A start job is running for… all Devices (2min 13s / no limit) [* ] (2 of 3) A start job is running for… all Devices (2min 13s / no limit)