[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 4.20.0-rc4-next-20181128-00009-g44c557177b8d-dirty (anders@compilator) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05)) #19 SMP Thu Nov 29 11:28:52 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 0xbebf25c0-0xbebf3fff] [ 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+0xe0/0x6ec with crng_init=0 [ 0.000000] percpu: Embedded 490 pages/cpu @(____ptrval____) s1969240 r8192 d29608 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: 992500K/2097152K available (16568K kernel code, 3774K rwdata, 4852K rodata, 6144K init, 23374K bss, 95060K 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 41939 entries in 164 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 dyntick-idle grace-period acceleration 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 callback double-/use-after-free debug enabled. [ 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.000217] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.014858] Console: colour dummy device 80x25 [ 0.019111] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.019628] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.020013] ... MAX_LOCK_DEPTH: 48 [ 0.020355] ... MAX_LOCKDEP_KEYS: 8191 [ 0.020720] ... CLASSHASH_SIZE: 4096 [ 0.021066] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.021451] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.021825] ... CHAINHASH_SIZE: 32768 [ 0.022486] memory used by lock dependency info: 7775 kB [ 0.022919] per task-struct memory footprint: 2688 bytes [ 0.023382] ------------------------ [ 0.023741] | Locking API testsuite: [ 0.024112] ---------------------------------------------------------------------------- [ 0.024587] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.025015] -------------------------------------------------------------------------- [ 0.025863] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.036952] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.047637] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.059254] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.070376] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.082574] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.094426] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.106442] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.116636] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.126576] -------------------------------------------------------------------------- [ 0.127143] recursive read-lock: | ok | | ok | [ 0.130669] recursive read-lock #2: | ok | | ok | [ 0.134222] mixed read-write-lock: | ok | | ok | [ 0.137415] mixed write-read-lock: | ok | | ok | [ 0.140792] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.144064] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.147734] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.151077] -------------------------------------------------------------------------- [ 0.152183] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.157112] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.162808] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.167724] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.172510] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.177348] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.182227] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.187146] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.192078] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.196948] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.201852] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.206850] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.211920] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.217154] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.222122] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.227549] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.232539] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.237501] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.242544] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.247460] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.252393] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.257504] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.262500] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.267875] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.272995] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.278040] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.283131] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.288202] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.293245] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.298297] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.303326] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.308473] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.313580] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.318606] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.323686] hard-irq lock-inversion/123: ok | ok | ok | [ 0.328736] soft-irq lock-inversion/123: ok | ok | ok | [ 0.333782] hard-irq lock-inversion/132: ok | ok | ok | [ 0.338823] soft-irq lock-inversion/132: ok | ok | ok | [ 0.343956] hard-irq lock-inversion/213: ok | ok | ok | [ 0.349014] soft-irq lock-inversion/213: ok | ok | ok | [ 0.354125] hard-irq lock-inversion/231: ok | ok | ok | [ 0.359396] soft-irq lock-inversion/231: ok | ok | ok | [ 0.364522] hard-irq lock-inversion/312: ok | ok | ok | [ 0.369633] soft-irq lock-inversion/312: ok | ok | ok | [ 0.374729] hard-irq lock-inversion/321: ok | ok | ok | [ 0.379832] soft-irq lock-inversion/321: ok | ok | ok | [ 0.384904] hard-irq read-recursion/123: ok | [ 0.386834] soft-irq read-recursion/123: ok | [ 0.389175] hard-irq read-recursion/132: ok | [ 0.391089] soft-irq read-recursion/132: ok | [ 0.393336] hard-irq read-recursion/213: ok | [ 0.395234] soft-irq read-recursion/213: ok | [ 0.397467] hard-irq read-recursion/231: ok | [ 0.399379] soft-irq read-recursion/231: ok | [ 0.401602] hard-irq read-recursion/312: ok | [ 0.403548] soft-irq read-recursion/312: ok | [ 0.405784] hard-irq read-recursion/321: ok | [ 0.407787] soft-irq read-recursion/321: ok | [ 0.410065] -------------------------------------------------------------------------- [ 0.410515] | Wound/wait tests | [ 0.410843] --------------------- [ 0.411213] ww api failures: ok | ok | ok | [ 0.417690] ww contexts mixing: ok | ok | [ 0.421142] finishing ww context: ok | ok | ok | ok | [ 0.427540] locking mismatches: ok | ok | ok | [ 0.432301] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.448761] spinlock nest unlocked: ok | [ 0.450547] ----------------------------------------------------- [ 0.450937] |block | try |context| [ 0.451300] ----------------------------------------------------- [ 0.451702] context: ok | ok | ok | [ 0.456718] try: ok | ok | ok | [ 0.461411] block: ok | ok | ok | [ 0.466118] spinlock: ok | ok | ok | [ 0.471197] ------------------------------------------------------- [ 0.471609] Good, all 261 testcases passed! | [ 0.472280] --------------------------------- [ 0.525386] ODEBUG: selftest passed [ 0.529646] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.530425] pid_max: default: 32768 minimum: 301 [ 0.540056] LSM: Security Framework initializing [ 0.541245] Yama: becoming mindful. [ 0.543456] LoadPin: ready to pin (currently enforcing) [ 0.544245] SELinux: Initializing. [ 0.551427] *** VALIDATE SELinux *** [ 0.552318] AppArmor: AppArmor disabled by boot time parameter [ 0.582727] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.595005] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.597870] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.598993] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.633776] *** VALIDATE proc *** [ 0.652441] *** VALIDATE cgroup1 *** [ 0.652866] *** VALIDATE cgroup2 *** [ 0.716565] ASID allocator initialised with 32768 entries [ 0.726069] rcu: Hierarchical SRCU implementation. [ 0.801747] EFI services will not be available. [ 0.827078] smp: Bringing up secondary CPUs ... [ 0.828022] smp: Brought up 1 node, 1 CPU [ 0.828425] SMP: Total of 1 processors activated. [ 0.828918] CPU features: detected: 32-bit EL0 Support [ 0.829367] CPU features: detected: CRC32 instructions [ 1.079527] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.080317] CPU: All CPU(s) started at EL1 [ 1.081620] alternatives: patching kernel code [ 4.877920] node 0 initialised, 248302 pages in 8ms [ 4.885457] pgdatinit0 (15) used greatest stack depth: 13280 bytes left [ 4.958819] devtmpfs: initialized [ 5.143137] gcov: version magic: 0x4137332a [ 5.148428] _warn_unseeded_randomness: 103 callbacks suppressed [ 5.148831] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=0 [ 5.151920] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 5.152753] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 5.166320] Registered cp15_barrier emulation handler [ 5.166961] Registered setend emulation handler [ 5.800572] workqueue: round-robin CPU selection forced, expect performance impact [ 9.767682] DMA-API: preallocated 65536 debug entries [ 9.768471] DMA-API: debugging enabled by kernel config [ 9.769689] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 9.771724] futex hash table entries: 256 (order: 3, 32768 bytes) [ 9.776761] Running postponed tracer tests: [ 9.782086] Testing tracer function: [ 9.818633] ftrace_replace_code: 1 [ 18.108361] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 18.363700] ftrace_replace_code: 1 [ 25.194922] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 25.198703] PASSED [ 25.199064] Testing dynamic ftrace: [ 26.939844] ftrace_replace_code: 1 [ 26.954764] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 27.197603] PASSED [ 27.198256] Testing dynamic ftrace ops #1: [ 34.147092] ftrace_replace_code: 1 [ 34.163448] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 34.167950] (1 0 1 0 0) [ 34.168133] (1 1 2 0 0) [ 34.181584] ftrace_replace_code: 1 [ 44.556524] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 44.558559] (2 1 3 0 921626) [ 44.558832] (2 2 4 0 921647) [ 44.572295] ftrace_replace_code: 1 [ 52.369037] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 52.383442] ftrace_replace_code: 1 [ 52.396737] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 52.401104] ftrace_replace_code: 1 [ 52.414384] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 52.418221] PASSED [ 52.418607] Testing dynamic ftrace ops #2: [ 59.356655] ftrace_replace_code: 1 [ 59.373027] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 59.377058] ftrace_replace_code: 1 [ 59.392896] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 59.412846] ftrace_replace_code: 1 [ 69.287861] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 69.290866] (1 0 1 911996 0) [ 69.291080] (1 1 2 912017 0) [ 69.307362] (2 1 3 1 753) [ 69.309565] (2 2 4 385 1137) [ 69.347240] ftrace_replace_code: 1 [ 76.133442] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 76.135678] PASSED [ 76.136049] Testing ftrace recursion: [ 77.878701] ftrace_replace_code: 1 [ 77.894307] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 77.898586] ftrace_replace_code: 1 [ 77.912737] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 77.914974] PASSED [ 77.915350] Testing ftrace recursion safe: [ 79.725737] ftrace_replace_code: 1 [ 79.741183] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 79.745498] ftrace_replace_code: 1 [ 79.759283] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 79.761128] PASSED [ 79.761492] Testing ftrace regs(no arch support): [ 81.534829] ftrace_replace_code: 1 [ 81.549591] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 81.553682] ftrace_replace_code: 1 [ 81.567001] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 81.569228] PASSED [ 81.569872] Testing tracer nop: PASSED [ 81.571610] Testing tracer irqsoff: [ 81.588509] ftrace_replace_code: 1 [ 89.499856] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 89.532076] ftrace_replace_code: 1 [ 96.389203] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 96.391743] PASSED [ 96.393590] Testing tracer wakeup: [ 96.423651] ftrace_replace_code: 1 [ 104.246034] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 104.271134] ftrace_replace_code: 1 [ 111.174486] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 111.183401] PASSED [ 111.184131] Testing tracer wakeup_rt: [ 111.213374] ftrace_replace_code: 1 [ 119.157155] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 119.193868] ftrace_replace_code: 1 [ 126.043995] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 126.052546] PASSED [ 126.053284] Testing tracer wakeup_dl: [ 126.078327] ftrace_replace_code: 1 [ 133.902129] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 133.932190] ftrace_replace_code: 1 [ 140.782519] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 140.790712] PASSED [ 140.792164] Testing tracer function_graph: [ 140.792295] ../kernel/trace/trace_selftest.c:766 [ 140.793620] ../kernel/trace/trace_selftest.c:768 [ 140.794037] ../kernel/trace/trace_selftest.c:770 [ 140.817476] ftrace_replace_code: 1 [ 148.564880] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 148.677445] ../kernel/trace/trace_selftest.c:773 [ 148.757908] ../kernel/trace/trace_selftest.c:778 [ 149.313644] ../kernel/trace/trace_selftest.c:780 [ 174.633833] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [kworker/0:1:13] [ 174.657800] Modules linked in: [ 174.672705] irq event stamp: 5714 [ 174.688893] hardirqs last enabled at (5713): [] _raw_spin_unlock_irq+0x38/0x78 [ 174.711863] hardirqs last disabled at (5714): [] el1_irq+0xa8/0x1c0 [ 174.733782] softirqs last enabled at (0): [] copy_process.isra.0+0x954/0x2830 [ 174.753793] softirqs last disabled at (0): [<0000000000000000>] (null) [ 174.772579] CPU: 0 PID: 13 Comm: kworker/0:1 Tainted: G T 4.20.0-rc4-next-20181128-00009-g44c557177b8d-dirty #19 [ 174.795608] Hardware name: linux,dummy-virt (DT) [ 174.815713] Workqueue: (null) (events) [ 174.834245] pstate: 20400005 (nzCv daif +PAN -UAO) [ 174.851640] pc : _raw_spin_unlock_irq+0x3c/0x78 [ 174.868794] lr : _raw_spin_unlock_irq+0x38/0x78 [ 174.883230] sp : ffffff80081abcd0 [ 174.895930] x29: ffffff80081abcd0 x28: 0000000000000003 [ 174.914677] x27: ffffffc03f624040 x26: ffffff8009e15df8 [ 174.933327] x25: ffffff8009f12fb0 x24: 0000000000000000 [ 174.953545] x23: ffffff800902b258 x22: ffffffc03f624040 [ 174.974285] x21: ffffffc03f5dc040 x20: ffffff80082c8670 [ 174.994672] x19: ffffffc07ebe06c0 x18: 0000000034f4d91d [ 175.014476] x17: 0000000000000000 x16: 0000000000000000 [ 175.034870] x15: ffffff8009e6a580 x14: ffffff800ae4e690 [ 175.055477] x13: ffffff800ae4fe90 x12: ffffff800ae4fe90 [ 175.075488] x11: ffffff800ae4ff98 x10: ffffff800ae4ff98 [ 175.095738] x9 : 0000000000000d80 x8 : 0000000000113ac6 [ 175.115723] x7 : 0000000000000d50 x6 : 0000004074f92000 [ 175.136191] x5 : ffffff8009eb82b0 x4 : ffffff8009e15bb0 [ 175.156271] x3 : 00000000000d3174 x2 : 00000000000d3174 [ 175.176523] x1 : 0000000000000001 x0 : 00000000000d3174 [ 175.197427] Call trace: [ 175.212947] _raw_spin_unlock_irq+0x3c/0x78 [ 175.231193] finish_task_switch+0x160/0x220 [ 175.249101] __schedule+0xa78/0xaf8 [ 175.265973] schedule+0x9c/0xe0 [ 175.282488] worker_thread+0x240/0x2a0 [ 175.299902] kthread+0x154/0x170 [ 175.316548] ret_from_fork+0x10/0x18 [ 175.516907] ../kernel/trace/trace_selftest.c:792 [ 175.583189] ../kernel/trace/trace_selftest.c:794 [ 175.587821] ../kernel/trace/trace_selftest.c:797 [ 175.610570] ../kernel/trace/trace_selftest.c:799 [ 175.637178] ftrace_replace_code: 1 [ 184.235278] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 184.238397] ../kernel/trace/trace_selftest.c:802 [ 184.238958] ../kernel/trace/trace_selftest.c:804 [ 184.239467] PASSED [ 184.316188] _warn_unseeded_randomness: 2 callbacks suppressed [ 184.316417] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 184.317604] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 184.319863] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 184.335323] prandom: seed boundary self test passed [ 184.371469] prandom: 100 self tests passed [ 184.376884] pinctrl core: initialized pinctrl subsystem [ 184.461434] regulator-dummy: Failed to create debugfs directory [ 184.468201] [ 184.468527] ************************************************************* [ 184.469094] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 184.470087] ** ** [ 184.470576] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 184.471024] ** ** [ 184.471419] ** This means that this kernel is built to expose internal ** [ 184.471819] ** IOMMU data structures, which may compromise security on ** [ 184.472215] ** your system. ** [ 184.472613] ** ** [ 184.473009] ** If you see this message and you are not debugging the ** [ 184.473406] ** kernel, report this immediately to your vendor! ** [ 184.473804] ** ** [ 184.474568] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 184.475068] ************************************************************* [ 184.500171] DMI not present or invalid. [ 184.541937] NET: Registered protocol family 16 [ 184.565858] audit: initializing netlink subsys (disabled) [ 184.684932] cpuidle: using governor ladder [ 184.686287] cpuidle: using governor menu [ 184.702594] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 184.704487] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 184.707966] audit: type=2000 audit(140.636:1): state=initialized audit_enabled=0 res=1 [ 184.808375] DMA: preallocated 256 KiB pool for atomic allocations [ 184.882201] Serial: AMBA PL011 UART driver [ 185.452306] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 185.456414] printk: console [ttyAMA0] enabled [ 185.456414] printk: console [ttyAMA0] enabled [ 185.457610] printk: bootconsole [pl11] disabled [ 185.457610] printk: bootconsole [pl11] disabled [ 185.543611] _warn_unseeded_randomness: 42 callbacks suppressed [ 185.543825] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 185.545500] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 185.936001] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 185.981018] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 186.092000] kobject: 'renesas,r9a06g032-sysctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 186.100686] kobject: 'renesas-cpg-mssr' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 186.144368] arm-v7s io-pgtable: self test ok [ 186.146297] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 186.153615] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 186.158512] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 186.164434] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 186.170743] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 186.176520] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 186.182142] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 186.191218] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 186.201271] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 186.212210] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 186.222820] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 186.233925] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 186.246668] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 186.275059] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 186.304315] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 186.334414] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 186.368301] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 186.402365] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 186.436320] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 186.460809] vgaarb: loaded [ 186.496481] pps_core: LinuxPPS API ver. 1 registered [ 186.497166] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 186.500743] PTP clock support registered [ 186.506981] kobject: 'brcmstb-reboot' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 186.627043] _warn_unseeded_randomness: 39 callbacks suppressed [ 186.627267] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=0 [ 186.631041] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 186.637664] NetLabel: Initializing [ 186.638294] NetLabel: domain hash size = 128 [ 186.639241] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 186.647260] NetLabel: unlabeled traffic allowed by default [ 186.664897] clocksource: Switched to clocksource arch_sys_counter [ 190.546270] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 190.547171] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 190.549695] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 190.561551] VFS: Disk quotas dquot_6.6.0 [ 190.565100] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 190.586202] *** VALIDATE hugetlbfs *** [ 191.267604] NET: Registered protocol family 2 [ 191.305163] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 191.310572] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 191.330188] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 191.342088] TCP: Hash tables configured (established 16384 bind 16384) [ 191.349727] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 191.354981] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 191.371577] NET: Registered protocol family 1 [ 191.375661] NET: Registered protocol family 44 [ 191.437132] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 191.445905] kvm [1]: HYP mode not available [ 191.555210] _warn_unseeded_randomness: 56 callbacks suppressed [ 191.555423] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 191.557417] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 191.562321] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 191.661226] Kprobe smoke test: started [ 192.728901] Kprobe smoke test: passed successfully [ 192.782604] Initialise system trusted keyrings [ 192.784706] _warn_unseeded_randomness: 1 callbacks suppressed [ 192.784921] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 192.790658] Key type blacklist registered [ 192.795394] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 192.806802] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 194.415282] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 194.416843] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 194.435356] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 194.596432] 9p: Installing v9fs 9p2000 file system support [ 194.679663] Allocating IMA blacklist keyring. [ 194.693213] cryptomgr_test (37) used greatest stack depth: 12400 bytes left [ 194.714989] kworker/u2:1 (38) used greatest stack depth: 11936 bytes left [ 195.495288] Key type asymmetric registered [ 195.498190] Asymmetric key parser 'x509' registered [ 195.503328] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 195.514399] _warn_unseeded_randomness: 26 callbacks suppressed [ 195.514629] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 195.515920] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 195.542119] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 195.675476] kobject: 'brcm-gisb-arb' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 195.750248] kobject: 'armada-37xx-pinctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 195.895130] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 195.899932] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 195.920632] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 195.940835] kobject: 'stmpe-pwm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 195.962264] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 196.020683] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 196.022768] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 196.025199] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 196.026291] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 196.029807] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 196.043197] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 196.044715] pci_bus 0000:00: root bus resource [bus 00-0f] [ 196.045504] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 196.046204] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 196.046944] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 196.050766] pci 0000:00:00.0: calling quirk_mmio_always_on+0x0/0x38 @ 1 [ 196.051635] pci 0000:00:00.0: quirk_mmio_always_on+0x0/0x38 took 70 usecs [ 196.125300] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 196.126402] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 196.127538] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 196.129055] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 196.129969] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 196.130749] pci 0000:00:03.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 196.131523] pci 0000:00:03.0: BAR 0: assigned [io 0x1000-0x103f] [ 196.132759] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 196.133488] pci 0000:00:02.0: BAR 0: assigned [io 0x1060-0x107f] [ 196.135303] pci 0000:00:00.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 196.136736] pci 0000:00:00.0: tegra_pcie_relax_enable+0x0/0x40 took 133 usecs [ 196.140871] pci 0000:00:01.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 196.141708] pci 0000:00:01.0: tegra_pcie_relax_enable+0x0/0x40 took 33 usecs [ 196.143060] pci 0000:00:02.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 196.143851] pci 0000:00:02.0: tegra_pcie_relax_enable+0x0/0x40 took 30 usecs [ 196.145581] pci 0000:00:03.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 196.146352] pci 0000:00:03.0: tegra_pcie_relax_enable+0x0/0x40 took 31 usecs [ 196.184680] kobject: 'layerscape-pcie' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 196.585371] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 196.609914] _warn_unseeded_randomness: 17 callbacks suppressed [ 196.610154] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 196.611312] kobject: 'virtio0' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 196.623062] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 196.637971] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 196.657023] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 196.657881] kobject: 'virtio1' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 196.684536] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 196.702790] kobject: 'virtio2' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 200.904956] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 201.012969] _warn_unseeded_randomness: 3 callbacks suppressed [ 201.013208] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 201.014467] kobject: 'ttyS0' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 201.015628] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 201.017593] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 201.018266] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 201.062124] kobject: 'ttyS1' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 201.063201] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 201.105237] kobject: 'ttyS2' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 201.106290] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 201.149930] kobject: 'ttyS3' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 201.151059] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 201.222461] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 201.233973] KGDB: Registered I/O driver kgdbts [ 201.234998] kgdbts:RUN plant and detach test Entering kdb (current=0xffffffc03f558040, pid 1) on processor 0 due to Keyboard Entry [0]kdb> [ 201.245065] kgdbts:RUN sw breakpoint test [ 201.261811] kgdbts:RUN bad memory access test [ 201.265109] kgdbts:RUN singlestep test 1000 iterations [ 201.295203] kgdbts:RUN singlestep [0/1000] [ 204.117659] kgdbts:RUN singlestep [100/1000] [ 206.933236] kgdbts:RUN singlestep [200/1000] [ 209.778079] kgdbts:RUN singlestep [300/1000] [ 212.725302] kgdbts:RUN singlestep [400/1000] [ 215.555255] kgdbts:RUN singlestep [500/1000] [ 218.376430] kgdbts:RUN singlestep [600/1000] [ 221.196325] kgdbts:RUN singlestep [700/1000] [ 224.012560] kgdbts:RUN singlestep [800/1000] [ 226.829903] kgdbts:RUN singlestep [900/1000] [ 229.624619] kgdbts:RUN do_fork for 100 breakpoints [ 229.630513] _warn_unseeded_randomness: 7 callbacks suppressed [ 229.630798] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 231.384745] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 231.385717] kobject: 'rx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 231.389269] kobject: 'tx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 231.402154] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 231.423999] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 231.425544] kobject: 'net' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 232.382389] KGDB: Unregistered I/O driver kgdbts, debugger disabled [ 234.537179] kobject: 'queues' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 234.617146] kobject: '44' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 234.625816] kobject: 'eth0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 234.639151] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 234.774991] ledtrig-cpu: registered to indicate activity on CPUs [ 234.777234] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 234.777438] kobject: '45' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 234.784930] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 234.793130] kobject: 'meson-sm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 234.804851] kobject: '46' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 234.858884] ashmem: initialized [ 235.027089] 9pnet: Installing 9P2000 support [ 235.548628] kobject: '47' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 235.550316] kobject: '48' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 235.589207] registered taskstats version 1 [ 235.590488] Running ring buffer tests... [ 245.793894] finished [ 245.794500] CPU 0: [ 245.795007] events: 7597 [ 245.795540] dropped bytes: 0 [ 245.796685] alloced bytes: 592216 [ 245.797214] written bytes: 580145 [ 245.797727] biggest event: 81 [ 245.798218] smallest event: 0 [ 246.081990] read events: 7597 [ 246.082563] lost events: 0 [ 246.083142] total events: 7597 [ 246.083633] recorded len bytes: 592216 [ 246.084595] recorded size bytes: 580145 [ 246.085146] Ring buffer PASSED! [ 246.125130] Running tests on trace events: [ 246.125819] Testing event initcall_finish: OK [ 246.154200] Testing event initcall_start: OK [ 246.178338] Testing event initcall_level: OK [ 246.207152] Testing event sys_exit: OK [ 246.235658] Testing event sys_enter: OK [ 246.261672] Testing event ipi_exit: OK [ 246.289732] Testing event ipi_entry: OK [ 246.318539] Testing event ipi_raise: OK [ 246.342791] Testing event instruction_emulation: OK [ 246.370893] Testing event kvm_halt_poll_ns: OK [ 246.398264] Testing event kvm_age_page: OK [ 246.425341] Testing event kvm_fpu: OK [ 246.450232] Testing event kvm_mmio: OK [ 246.478321] Testing event kvm_ack_irq: OK [ 246.503639] Testing event kvm_set_irq: OK [ 246.532969] Testing event kvm_vcpu_wakeup: OK [ 246.558525] Testing event kvm_userspace_exit: OK [ 246.585784] Testing event kvm_timer_update_irq: OK [ 246.611353] Testing event kvm_toggle_cache: OK [ 246.638577] Testing event kvm_set_way_flush: OK [ 246.666994] Testing event kvm_test_age_hva: OK [ 246.693651] Testing event kvm_age_hva: OK [ 246.718045] Testing event kvm_set_spte_hva: OK [ 246.743158] Testing event kvm_unmap_hva_range: OK [ 246.771100] Testing event kvm_mmio_emulate: OK [ 246.797537] Testing event kvm_irq_line: OK [ 246.821894] Testing event kvm_access_fault: OK [ 246.847630] Testing event kvm_guest_fault: OK [ 246.874020] Testing event kvm_exit: OK [ 246.898556] Testing event kvm_entry: OK [ 246.927677] Testing event kvm_set_guest_debug: OK [ 246.953472] Testing event kvm_handle_sys_reg: OK [ 246.978339] Testing event trap_reg: OK [ 247.008845] Testing event kvm_arm_set_regset: OK [ 247.034888] Testing event kvm_arm_set_dreg32: OK [ 247.063848] Testing event kvm_arm_clear_debug: OK [ 247.089534] Testing event kvm_arm_setup_debug: OK [ 247.114402] Testing event kvm_hvc_arm64: OK [ 247.146719] Testing event kvm_wfx_arm64: OK [ 247.174702] Testing event vgic_update_irq_pending: OK [ 247.205015] Testing event task_rename: OK [ 247.230345] Testing event task_newtask: OK [ 247.261041] Testing event cpuhp_exit: OK [ 247.286242] Testing event cpuhp_multi_enter: OK [ 247.314287] Testing event cpuhp_enter: OK [ 247.340931] Testing event softirq_raise: OK [ 247.368880] Testing event softirq_exit: OK [ 247.394207] Testing event softirq_entry: OK [ 247.425179] Testing event irq_handler_exit: OK [ 247.450921] Testing event irq_handler_entry: OK [ 247.480978] Testing event signal_deliver: OK [ 247.506366] Testing event signal_generate: OK [ 247.535473] Testing event workqueue_execute_end: OK [ 247.562643] Testing event workqueue_execute_start: OK [ 247.591562] Testing event workqueue_activate_work: OK [ 247.618702] Testing event workqueue_queue_work: OK [ 247.647615] Testing event sched_wake_idle_without_ipi: OK [ 247.674889] Testing event sched_swap_numa: OK [ 247.703809] Testing event sched_stick_numa: OK [ 247.730593] Testing event sched_move_numa: OK [ 247.759805] Testing event sched_process_hang: OK [ 247.786197] Testing event sched_pi_setprio: OK [ 247.813348] Testing event sched_stat_runtime: [ 247.815696] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 247.841472] OK [ 247.841976] Testing event sched_stat_blocked: OK [ 247.869428] Testing event sched_stat_iowait: OK [ 247.898210] Testing event sched_stat_sleep: OK [ 247.922879] Testing event sched_stat_wait: OK [ 247.950723] Testing event sched_process_exec: OK [ 247.978061] Testing event sched_process_fork: OK [ 248.003768] Testing event sched_process_wait: OK [ 248.030507] Testing event sched_wait_task: OK [ 248.057052] Testing event sched_process_exit: OK [ 248.085612] Testing event sched_process_free: OK [ 248.110476] Testing event sched_migrate_task: OK [ 248.134872] Testing event sched_switch: OK [ 248.161779] Testing event sched_wakeup_new: OK [ 248.187308] Testing event sched_wakeup: OK [ 248.214563] Testing event sched_waking: OK [ 248.242744] Testing event sched_kthread_stop_ret: OK [ 248.270074] Testing event sched_kthread_stop: OK [ 248.297888] Testing event lock_acquired: OK [ 248.345308] Testing event lock_contended: OK [ 248.370733] Testing event lock_release: OK [ 248.423166] Testing event lock_acquire: OK [ 248.467834] Testing event console: OK [ 248.494378] Testing event rcu_barrier: OK [ 248.518657] Testing event rcu_torture_read: OK [ 248.542888] Testing event rcu_batch_end: OK [ 248.571482] Testing event rcu_invoke_kfree_callback: OK [ 248.598714] Testing event rcu_invoke_callback: OK [ 248.631051] Testing event rcu_batch_start: OK [ 248.658620] Testing event rcu_kfree_callback: OK [ 248.689330] Testing event rcu_callback: OK [ 248.717194] Testing event rcu_dyntick: OK [ 248.742772] Testing event rcu_fqs: OK [ 248.769169] Testing event rcu_quiescent_state_report: OK [ 248.794436] Testing event rcu_unlock_preempted_task: OK [ 248.822639] Testing event rcu_preempt_task: OK [ 248.847829] Testing event rcu_nocb_wake: OK [ 248.879625] Testing event rcu_exp_funnel_lock: OK [ 248.905505] Testing event rcu_exp_grace_period: OK [ 248.933260] Testing event rcu_grace_period_init: OK [ 248.958401] Testing event rcu_future_grace_period: OK [ 248.986777] Testing event rcu_grace_period: OK [ 249.017723] Testing event rcu_utilization: OK [ 249.045471] Testing event swiotlb_bounced: OK [ 249.073611] Testing event tick_stop: OK [ 249.102280] Testing event itimer_expire: OK [ 249.127003] Testing event itimer_state: OK [ 249.156873] Testing event hrtimer_cancel: OK [ 249.183389] Testing event hrtimer_expire_exit: OK [ 249.211229] Testing event hrtimer_expire_entry: OK [ 249.238880] Testing event hrtimer_start: OK [ 249.265118] Testing event hrtimer_init: OK [ 249.290376] Testing event timer_cancel: OK [ 249.322433] Testing event timer_expire_exit: OK [ 249.350583] Testing event timer_expire_entry: OK [ 249.381151] Testing event timer_start: OK [ 249.406629] Testing event timer_init: OK [ 249.434232] Testing event alarmtimer_cancel: OK [ 249.459773] Testing event alarmtimer_start: OK [ 249.486572] Testing event alarmtimer_fired: OK [ 249.514904] Testing event alarmtimer_suspend: OK [ 249.539317] Testing event module_request: OK [ 249.563849] Testing event module_put: OK [ 249.588964] Testing event module_get: OK [ 249.613725] Testing event module_free: OK [ 249.638218] Testing event module_load: OK [ 249.664978] Testing event cgroup_transfer_tasks: OK [ 249.690317] Testing event cgroup_attach_task: OK [ 249.715618] Testing event cgroup_rename: OK [ 249.742535] Testing event cgroup_release: OK [ 249.769888] Testing event cgroup_rmdir: OK [ 249.795369] Testing event cgroup_mkdir: OK [ 249.822000] Testing event cgroup_remount: OK [ 249.849017] Testing event cgroup_destroy_root: OK [ 249.875472] Testing event cgroup_setup_root: OK [ 249.905343] Testing event irq_enable: OK [ 249.949140] Testing event irq_disable: OK [ 249.986623] Testing event ftrace_test_filter: OK [ 250.013925] Testing event dev_pm_qos_remove_request: OK [ 250.038104] Testing event dev_pm_qos_update_request: OK [ 250.062466] Testing event dev_pm_qos_add_request: OK [ 250.087686] Testing event pm_qos_update_flags: OK [ 250.114153] Testing event pm_qos_update_target: OK [ 250.138204] Testing event pm_qos_update_request_timeout: OK [ 250.165192] Testing event pm_qos_remove_request: OK [ 250.193315] Testing event pm_qos_update_request: OK [ 250.219121] Testing event pm_qos_add_request: OK [ 250.246691] Testing event power_domain_target: OK [ 250.275273] Testing event clock_set_rate: OK [ 250.301415] Testing event clock_disable: OK [ 250.325522] Testing event clock_enable: OK [ 250.354300] Testing event wakeup_source_deactivate: OK [ 250.379836] Testing event wakeup_source_activate: OK [ 250.406600] Testing event suspend_resume: OK [ 250.447790] Testing event device_pm_callback_end: OK [ 250.474707] Testing event device_pm_callback_start: OK [ 250.501745] Testing event cpu_frequency_limits: OK [ 250.530782] Testing event cpu_frequency: OK [ 250.557526] Testing event pstate_sample: OK [ 250.581323] Testing event powernv_throttle: OK [ 250.606565] Testing event cpu_idle: OK [ 250.634960] Testing event rpm_return_int: OK [ 250.664697] Testing event rpm_idle: OK [ 250.690596] Testing event rpm_resume: OK [ 250.719655] Testing event rpm_suspend: OK [ 250.746310] Testing event benchmark_event: OK [ 250.870740] Testing event xdp_devmap_xmit: OK [ 250.898564] Testing event xdp_cpumap_enqueue: OK [ 250.923720] Testing event xdp_cpumap_kthread: OK [ 250.950345] Testing event xdp_redirect_map_err: OK [ 250.979461] Testing event xdp_redirect_map: OK [ 251.006413] Testing event xdp_redirect_err: OK [ 251.034149] Testing event xdp_redirect: OK [ 251.061631] Testing event xdp_exception: OK [ 251.089125] Testing event rseq_ip_fixup: OK [ 251.114538] Testing event rseq_update: OK [ 251.142357] Testing event file_check_and_advance_wb_err: OK [ 251.169203] Testing event filemap_set_wb_err: OK [ 251.197357] Testing event mm_filemap_add_to_page_cache: OK [ 251.222448] Testing event mm_filemap_delete_from_page_cache: OK [ 251.253230] Testing event compact_retry: OK [ 251.278834] Testing event skip_task_reaping: OK [ 251.309188] Testing event finish_task_reaping: OK [ 251.334321] Testing event start_task_reaping: OK [ 251.365357] Testing event wake_reaper: OK [ 251.390185] Testing event mark_victim: OK [ 251.418009] Testing event reclaim_retry_zone: OK [ 251.445860] Testing event oom_score_adj_update: OK [ 251.470956] Testing event mm_lru_activate: OK [ 251.497690] Testing event mm_lru_insertion: OK [ 251.522453] Testing event mm_vmscan_inactive_list_is_low: OK [ 251.554524] Testing event mm_vmscan_lru_shrink_active: OK [ 251.579117] Testing event mm_vmscan_lru_shrink_inactive: OK [ 251.605735] Testing event mm_vmscan_writepage: OK [ 251.630450] Testing event mm_vmscan_lru_isolate: OK [ 251.658257] Testing event mm_shrink_slab_end: OK [ 251.684969] Testing event mm_shrink_slab_start: OK [ 251.710724] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 251.737174] Testing event mm_vmscan_memcg_reclaim_end: OK [ 251.765212] Testing event mm_vmscan_direct_reclaim_end: OK [ 251.790607] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 251.819182] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 251.843415] Testing event mm_vmscan_direct_reclaim_begin: OK [ 251.868782] Testing event mm_vmscan_wakeup_kswapd: OK [ 251.893108] Testing event mm_vmscan_kswapd_wake: OK [ 251.917607] Testing event mm_vmscan_kswapd_sleep: OK [ 251.941459] Testing event percpu_destroy_chunk: OK [ 251.966501] Testing event percpu_create_chunk: OK [ 251.995790] Testing event percpu_alloc_percpu_fail: OK [ 252.019736] Testing event percpu_free_percpu: OK [ 252.046832] Testing event percpu_alloc_percpu: OK [ 252.077017] Testing event mm_page_alloc_extfrag: OK [ 252.102559] Testing event mm_page_pcpu_drain: OK [ 252.133051] Testing event mm_page_alloc_zone_locked: OK [ 252.159243] Testing event mm_page_alloc: OK [ 252.189165] Testing event mm_page_free_batched: OK [ 252.214603] Testing event mm_page_free: OK [ 252.241677] Testing event kmem_cache_free: OK [ 252.267336] Testing event kfree: OK [ 252.294459] Testing event kmem_cache_alloc_node: OK [ 252.323497] Testing event kmalloc_node: OK [ 252.350232] Testing event kmem_cache_alloc: OK [ 252.377970] Testing event kmalloc: OK [ 252.404984] Testing event mm_compaction_kcompactd_wake: OK [ 252.430771] Testing event mm_compaction_wakeup_kcompactd: OK [ 252.457596] Testing event mm_compaction_kcompactd_sleep: OK [ 252.481894] Testing event mm_compaction_defer_reset: OK [ 252.506171] Testing event mm_compaction_defer_compaction: OK [ 252.533476] Testing event mm_compaction_deferred: OK [ 252.562259] Testing event mm_compaction_suitable: OK [ 252.587604] Testing event mm_compaction_finished: OK [ 252.610932] Testing event mm_compaction_try_to_compact_pages: OK [ 252.638181] Testing event mm_compaction_end: OK [ 252.661941] Testing event mm_compaction_begin: OK [ 252.689115] Testing event mm_compaction_migratepages: OK [ 252.714994] Testing event mm_compaction_isolate_freepages: OK [ 252.743387] Testing event mm_compaction_isolate_migratepages: OK [ 252.770392] Testing event mm_migrate_pages: OK [ 252.797498] Testing event mm_collapse_huge_page_swapin: OK [ 252.822750] Testing event mm_collapse_huge_page_isolate: OK [ 252.853308] Testing event mm_collapse_huge_page: OK [ 252.877952] Testing event mm_khugepaged_scan_pmd: OK [ 252.902150] Testing event test_pages_isolated: OK [ 252.929746] Testing event cma_release: OK [ 252.958155] Testing event cma_alloc: OK [ 252.982455] Testing event page_ref_unfreeze: OK [ 253.014130] Testing event page_ref_freeze: OK [ 253.039812] Testing event page_ref_mod_unless: OK [ 253.076619] Testing event page_ref_mod_and_return: OK [ 253.101380] Testing event page_ref_mod_and_test: OK [ 253.231727] Testing event page_ref_mod: OK [ 253.288559] Testing event page_ref_set: OK [ 253.316892] Testing event sb_clear_inode_writeback: OK [ 253.342528] Testing event sb_mark_inode_writeback: OK [ 253.370658] Testing event writeback_dirty_inode_enqueue: OK [ 253.398728] Testing event writeback_lazytime_iput: OK [ 253.423394] Testing event writeback_lazytime: OK [ 253.448768] Testing event writeback_single_inode: OK [ 253.474070] Testing event writeback_single_inode_start: OK [ 253.499376] Testing event writeback_wait_iff_congested: OK [ 253.529020] Testing event writeback_congestion_wait: OK [ 253.554106] Testing event writeback_sb_inodes_requeue: OK [ 253.579548] Testing event balance_dirty_pages: OK [ 253.606348] Testing event bdi_dirty_ratelimit: OK [ 253.635611] Testing event global_dirty_state: OK [ 253.659757] Testing event writeback_queue_io: OK [ 253.685896] Testing event wbc_writepage: OK [ 253.714242] Testing event writeback_bdi_register: OK [ 253.739747] Testing event writeback_wake_background: OK [ 253.766621] Testing event writeback_pages_written: OK [ 253.795248] Testing event writeback_wait: OK [ 253.819508] Testing event writeback_written: OK [ 253.846559] Testing event writeback_start: OK [ 253.873785] Testing event writeback_exec: OK [ 253.899079] Testing event writeback_queue: OK [ 253.926137] Testing event writeback_write_inode: OK [ 253.953724] Testing event writeback_write_inode_start: OK [ 253.979225] Testing event writeback_dirty_inode: OK [ 254.006129] Testing event writeback_dirty_inode_start: OK [ 254.033475] Testing event writeback_mark_inode_dirty: OK [ 254.062252] Testing event writeback_dirty_page: OK [ 254.086647] Testing event dax_writeback_one: OK [ 254.114466] Testing event dax_writeback_range_done: OK [ 254.144667] Testing event dax_writeback_range: OK [ 254.170570] Testing event dax_insert_mapping: OK [ 254.202381] Testing event dax_insert_pfn_mkwrite: OK [ 254.226819] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 254.254429] Testing event dax_load_hole: OK [ 254.282408] Testing event dax_pte_fault_done: OK [ 254.306919] Testing event dax_pte_fault: OK [ 254.337308] Testing event dax_pmd_insert_mapping: OK [ 254.361936] Testing event dax_pmd_load_hole_fallback: OK [ 254.386341] Testing event dax_pmd_load_hole: OK [ 254.411499] Testing event dax_pmd_fault_done: OK [ 254.434739] Testing event dax_pmd_fault: OK [ 254.461408] Testing event generic_add_lease: OK [ 254.485382] Testing event time_out_leases: OK [ 254.508974] Testing event generic_delete_lease: OK [ 254.534557] Testing event break_lease_unblock: OK [ 254.561852] Testing event break_lease_block: OK [ 254.587167] Testing event break_lease_noblock: OK [ 254.613588] Testing event flock_lock_inode: OK [ 254.638477] Testing event locks_remove_posix: OK [ 254.670521] Testing event fcntl_setlk: OK [ 254.694681] Testing event posix_lock_inode: OK [ 254.722355] Testing event locks_get_lock_context: OK [ 254.749886] Testing event block_rq_remap: OK [ 254.775077] Testing event block_bio_remap: OK [ 254.801592] Testing event block_split: OK [ 254.826472] Testing event block_unplug: OK [ 254.855791] Testing event block_plug: OK [ 254.882550] Testing event block_sleeprq: OK [ 254.911592] Testing event block_getrq: OK [ 254.935700] Testing event block_bio_queue: OK [ 254.965307] Testing event block_bio_frontmerge: OK [ 254.989848] Testing event block_bio_backmerge: OK [ 255.014135] Testing event block_bio_complete: OK [ 255.038337] Testing event block_bio_bounce: OK [ 255.063657] Testing event block_rq_issue: OK [ 255.090275] Testing event block_rq_insert: OK [ 255.117970] Testing event block_rq_complete: OK [ 255.143280] Testing event block_rq_requeue: OK [ 255.170597] Testing event block_dirty_buffer: OK [ 255.194855] Testing event block_touch_buffer: OK [ 255.223594] Testing event wbt_timer: OK [ 255.247675] Testing event wbt_step: OK [ 255.274381] Testing event wbt_lat: OK [ 255.302029] Testing event wbt_stat: OK [ 255.329688] Testing event gpio_value: OK [ 255.356916] Testing event gpio_direction: OK [ 255.382860] Testing event clk_set_duty_cycle_complete: OK [ 255.410898] Testing event clk_set_duty_cycle: OK [ 255.442401] Testing event clk_set_phase_complete: OK [ 255.466894] Testing event clk_set_phase: OK [ 255.494477] Testing event clk_set_parent_complete: OK [ 255.522053] Testing event clk_set_parent: OK [ 255.549646] Testing event clk_set_rate_complete: OK [ 255.574287] Testing event clk_set_rate: OK [ 255.602291] Testing event clk_unprepare_complete: OK [ 255.628866] Testing event clk_unprepare: OK [ 255.654726] Testing event clk_prepare_complete: OK [ 255.683031] Testing event clk_prepare: OK [ 255.707302] Testing event clk_disable_complete: OK [ 255.732837] Testing event clk_disable: OK [ 255.757329] Testing event clk_enable_complete: OK [ 255.781674] Testing event clk_enable: OK [ 255.806408] Testing event rpmh_send_msg: OK [ 255.831637] Testing event rpmh_tx_done: OK [ 255.857815] Testing event regulator_set_voltage_complete: OK [ 255.882367] Testing event regulator_set_voltage: OK [ 255.907745] Testing event regulator_disable_complete: OK [ 255.934494] Testing event regulator_disable: OK [ 255.964745] Testing event regulator_enable_complete: OK [ 255.989694] Testing event regulator_enable_delay: OK [ 256.014509] Testing event regulator_enable: OK [ 256.046735] Testing event urandom_read: OK [ 256.070723] Testing event random_read: OK [ 256.098406] Testing event extract_entropy_user: OK [ 256.125906] Testing event extract_entropy: OK [ 256.151246] Testing event get_random_bytes_arch: OK [ 256.180650] Testing event get_random_bytes: OK [ 256.206188] Testing event xfer_secondary_pool: OK [ 256.233918] Testing event add_disk_randomness: OK [ 256.259467] Testing event add_input_randomness: OK [ 256.286710] Testing event debit_entropy: OK [ 256.313424] Testing event push_to_pool: OK [ 256.342236] Testing event credit_entropy_bits: OK [ 256.367760] Testing event mix_pool_bytes_nolock: OK [ 256.394246] Testing event mix_pool_bytes: OK [ 256.423436] Testing event add_device_randomness: OK [ 256.449624] Testing event io_page_fault: OK [ 256.474598] Testing event unmap: OK [ 256.503604] Testing event map: OK [ 256.527779] Testing event detach_device_from_domain: OK [ 256.553887] Testing event attach_device_to_domain: OK [ 256.582534] Testing event remove_device_from_group: OK [ 256.607741] Testing event add_device_to_group: OK [ 256.634691] Testing event regcache_drop_region: OK [ 256.662839] Testing event regmap_async_complete_done: OK [ 256.687572] Testing event regmap_async_complete_start: OK [ 256.714753] Testing event regmap_async_io_complete: OK [ 256.742977] Testing event regmap_async_write_start: OK [ 256.771661] Testing event regmap_cache_bypass: OK [ 256.797869] Testing event regmap_cache_only: OK [ 256.826077] Testing event regcache_sync: OK [ 256.853983] Testing event regmap_hw_write_done: OK [ 256.878255] Testing event regmap_hw_write_start: OK [ 256.905690] Testing event regmap_hw_read_done: OK [ 256.931887] Testing event regmap_hw_read_start: OK [ 256.958549] Testing event regmap_reg_read_cache: OK [ 256.985852] Testing event regmap_reg_read: OK [ 257.011520] Testing event regmap_reg_write: OK [ 257.038702] Testing event dma_fence_wait_end: OK [ 257.065186] Testing event dma_fence_wait_start: OK [ 257.093237] Testing event dma_fence_signaled: OK [ 257.118136] Testing event dma_fence_enable_signal: OK [ 257.146318] Testing event dma_fence_destroy: OK [ 257.171130] Testing event dma_fence_init: OK [ 257.198144] Testing event dma_fence_emit: OK [ 257.226234] Testing event sync_timeline: OK [ 257.251804] Testing event spi_transfer_stop: OK [ 257.278632] Testing event spi_transfer_start: OK [ 257.306796] Testing event spi_message_done: OK [ 257.331516] Testing event spi_message_start: OK [ 257.356933] Testing event spi_message_submit: OK [ 257.385302] Testing event spi_controller_busy: OK [ 257.410583] Testing event spi_controller_idle: OK [ 257.439076] Testing event rtc_timer_fired: OK [ 257.465503] Testing event rtc_timer_dequeue: OK [ 257.494163] Testing event rtc_timer_enqueue: OK [ 257.521504] Testing event rtc_read_offset: OK [ 257.550398] Testing event rtc_set_offset: OK [ 257.578292] Testing event rtc_alarm_irq_enable: OK [ 257.603052] Testing event rtc_irq_set_state: OK [ 257.631821] Testing event rtc_irq_set_freq: OK [ 257.657371] Testing event rtc_read_alarm: OK [ 257.684856] Testing event rtc_set_alarm: OK [ 257.709764] Testing event rtc_read_time: OK [ 257.734209] Testing event rtc_set_time: OK [ 257.758282] Testing event memory_failure_event: OK [ 257.783768] Testing event aer_event: OK [ 257.810466] Testing event non_standard_event: OK [ 257.840942] Testing event arm_event: OK [ 257.866054] Testing event mc_event: OK [ 257.889993] Testing event binder_return: OK [ 257.914405] Testing event binder_command: OK [ 257.942848] Testing event binder_unmap_kernel_end: OK [ 257.971614] Testing event binder_unmap_kernel_start: OK [ 257.995709] Testing event binder_unmap_user_end: OK [ 258.022364] Testing event binder_unmap_user_start: OK [ 258.049948] Testing event binder_alloc_page_end: OK [ 258.075198] Testing event binder_alloc_page_start: OK [ 258.101764] Testing event binder_free_lru_end: OK [ 258.126336] Testing event binder_free_lru_start: OK [ 258.151820] Testing event binder_alloc_lru_end: OK [ 258.178410] Testing event binder_alloc_lru_start: OK [ 258.207380] Testing event binder_update_page_range: OK [ 258.234412] Testing event binder_transaction_failed_buffer_release: OK [ 258.259759] Testing event binder_transaction_buffer_release: OK [ 258.286454] Testing event binder_transaction_alloc_buf: OK [ 258.317236] Testing event binder_transaction_fd_recv: OK [ 258.342810] Testing event binder_transaction_fd_send: OK [ 258.372996] Testing event binder_transaction_ref_to_ref: OK [ 258.398056] Testing event binder_transaction_ref_to_node: OK [ 258.425019] Testing event binder_transaction_node_to_ref: OK [ 258.451596] Testing event binder_transaction_received: OK [ 258.478190] Testing event binder_transaction: OK [ 258.502175] Testing event binder_wait_for_work: OK [ 258.529571] Testing event binder_read_done: OK [ 258.553980] Testing event binder_write_done: OK [ 258.579663] Testing event binder_ioctl_done: OK [ 258.602706] Testing event binder_unlock: OK [ 258.630270] Testing event binder_locked: OK [ 258.657765] Testing event binder_lock: OK [ 258.683163] Testing event binder_ioctl: OK [ 258.710749] Testing event br_fdb_update: OK [ 258.734815] Testing event fdb_delete: OK [ 258.763283] Testing event br_fdb_external_learn_add: OK [ 258.790305] Testing event br_fdb_add: OK [ 258.814080] Testing event qdisc_dequeue: OK [ 258.840729] Testing event fib_table_lookup: OK [ 258.866667] Testing event tcp_probe: OK [ 258.897154] Testing event tcp_retransmit_synack: OK [ 258.922009] Testing event tcp_rcv_space_adjust: OK [ 258.947421] Testing event tcp_destroy_sock: OK [ 258.973509] Testing event tcp_receive_reset: OK [ 258.997911] Testing event tcp_send_reset: OK [ 259.022180] Testing event tcp_retransmit_skb: OK [ 259.049283] Testing event udp_fail_queue_rcv_skb: OK [ 259.074117] Testing event inet_sock_set_state: OK [ 259.098505] Testing event sock_exceed_buf_limit: OK [ 259.125370] Testing event sock_rcvqueue_full: OK [ 259.151710] Testing event napi_poll: OK [ 259.178651] Testing event netif_rx_ni_entry: OK [ 259.206044] Testing event netif_rx_entry: OK [ 259.231417] Testing event netif_receive_skb_list_entry: OK [ 259.258608] Testing event netif_receive_skb_entry: OK [ 259.286633] Testing event napi_gro_receive_entry: OK [ 259.311536] Testing event napi_gro_frags_entry: OK [ 259.337563] Testing event netif_rx: OK [ 259.361871] Testing event netif_receive_skb: OK [ 259.386206] Testing event net_dev_queue: OK [ 259.410406] Testing event net_dev_xmit: OK [ 259.438411] Testing event net_dev_start_xmit: OK [ 259.463000] Testing event skb_copy_datagram_iovec: OK [ 259.491342] Testing event consume_skb: OK [ 259.518657] Testing event kfree_skb: OK [ 259.546898] Testing event 9p_protocol_dump: OK [ 259.579729] Testing event 9p_client_res: OK [ 259.606300] Testing event 9p_client_req: OK [ 259.630442] Running tests on trace event systems: [ 259.631061] Testing event system 9p: OK [ 259.667347] Testing event system skb: OK [ 259.696604] Testing event system net: OK [ 259.732927] Testing event system napi: OK [ 259.761370] Testing event system sock: OK [ 259.791789] Testing event system udp: OK [ 259.823329] Testing event system tcp: OK [ 259.854820] Testing event system fib: OK [ 259.884921] Testing event system qdisc: OK [ 259.913902] Testing event system bridge: OK [ 259.943326] Testing event system binder: OK [ 259.994037] Testing event system ras: OK [ 260.035586] Testing event system rtc: OK [ 260.073477] Testing event system spi: OK [ 260.106147] Testing event system sync_trace: OK [ 260.133071] Testing event system dma_fence: OK [ 260.166240] Testing event system regmap: OK [ 260.206940] Testing event system iommu: OK [ 260.238537] Testing event system random: OK [ 260.278463] Testing event system regulator: OK [ 260.313286] Testing event system rpmh: OK [ 260.343446] Testing event system clk: OK [ 260.385044] Testing event system gpio: OK [ 260.413739] Testing event system wbt: OK [ 260.446860] Testing event system block: OK [ 260.485848] Testing event system filelock: OK [ 260.523679] Testing event system fs_dax: OK [ 260.555288] Testing event system writeback: OK [ 260.602862] Testing event system page_ref: OK [ 260.780955] Testing event system cma: OK [ 260.810741] Testing event system page_isolation: OK [ 260.839561] Testing event system huge_memory: OK [ 260.874221] Testing event system migrate: OK [ 260.901430] Testing event system compaction: OK [ 260.940629] Testing event system kmem: OK [ 260.985204] Testing event system percpu: OK [ 261.024856] Testing event system vmscan: OK [ 261.064906] Testing event system pagemap: OK [ 261.092709] Testing event system oom: OK [ 261.129976] Testing event system filemap: OK [ 261.159558] Testing event system rseq: OK [ 261.192877] Testing event system xdp: OK [ 261.226850] Testing event system benchmark: OK [ 261.353577] Testing event system rpm: OK [ 261.386323] Testing event system power: OK [ 261.440740] Testing event system test: OK [ 261.467281] Testing event system preemptirq: OK [ 261.537839] Testing event system cgroup: OK [ 261.573187] Testing event system module: OK [ 261.602446] Testing event system alarmtimer: OK [ 261.633226] Testing event system timer: OK [ 261.676918] Testing event system swiotlb: OK [ 261.702903] Testing event system rcu: OK [ 261.769622] Testing event system printk: OK [ 261.795321] Testing event system lock: OK [ 261.911426] Testing event system sched: OK [ 261.961465] Testing event system workqueue: OK [ 262.002910] Testing event system signal: OK [ 262.031722] Testing event system irq: OK [ 262.062685] Testing event system cpuhp: OK [ 262.091883] Testing event system task: OK [ 262.123183] Testing event system kvm: OK [ 262.176540] Testing event system emulation: OK [ 262.203803] Testing event system ipi: OK [ 262.235486] Testing event system raw_syscalls: OK [ 262.266422] Testing event system initcall: OK [ 262.296623] Running tests on all trace events: [ 262.297119] Testing all events: OK [ 265.222815] ftrace_replace_code: 1 [ 268.557248] hrtimer: interrupt took 9621440 ns [ 315.649436] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 315.679927] Running tests again, along with the function tracer [ 315.708876] Running tests on trace events: [ 315.717795] Testing event initcall_finish: OK [ 316.141316] Testing event initcall_start: OK [ 316.628694] Testing event initcall_level: OK [ 317.224660] Testing event sys_exit: OK [ 317.631616] Testing event sys_enter: OK [ 318.133395] Testing event ipi_exit: OK [ 318.617716] Testing event ipi_entry: OK [ 319.102526] Testing event ipi_raise: OK [ 319.514872] Testing event instruction_emulation: OK [ 320.034778] Testing event kvm_halt_poll_ns: OK [ 320.538439] Testing event kvm_age_page: OK [ 320.999384] Testing event kvm_fpu: OK [ 321.496794] Testing event kvm_mmio: OK [ 321.920867] Testing event kvm_ack_irq: OK [ 322.542549] Testing event kvm_set_irq: OK [ 322.883885] Testing event kvm_vcpu_wakeup: OK [ 323.327778] Testing event kvm_userspace_exit: OK [ 323.743582] Testing event kvm_timer_update_irq: OK [ 324.247028] Testing event kvm_toggle_cache: OK [ 324.709938] Testing event kvm_set_way_flush: OK [ 325.184222] Testing event kvm_test_age_hva: OK [ 325.593562] Testing event kvm_age_hva: OK [ 326.037144] Testing event kvm_set_spte_hva: OK [ 326.528492] Testing event kvm_unmap_hva_range: OK [ 327.083968] Testing event kvm_mmio_emulate: OK [ 327.578566] Testing event kvm_irq_line: OK [ 328.000138] Testing event kvm_access_fault: OK [ 328.475261] Testing event kvm_guest_fault: OK [ 329.074273] Testing event kvm_exit: OK [ 329.429976] Testing event kvm_entry: OK [ 329.880337] Testing event kvm_set_guest_debug: OK [ 330.365729] Testing event kvm_handle_sys_reg: OK [ 330.821173] Testing event trap_reg: OK [ 331.153809] Testing event kvm_arm_set_regset: OK [ 331.584762] Testing event kvm_arm_set_dreg32: OK [ 332.161532] Testing event kvm_arm_clear_debug: OK [ 332.626602] Testing event kvm_arm_setup_debug: OK [ 333.020430] Testing event kvm_hvc_arm64: OK [ 333.493795] Testing event kvm_wfx_arm64: OK [ 334.054719] Testing event vgic_update_irq_pending: OK [ 334.415878] Testing event task_rename: OK [ 334.888880] Testing event task_newtask: OK [ 335.556805] Testing event cpuhp_exit: OK [ 336.045243] Testing event cpuhp_multi_enter: OK [ 336.633585] Testing event cpuhp_enter: OK [ 337.080640] Testing event softirq_raise: OK [ 337.532543] Testing event softirq_exit: OK [ 337.964498] Testing event softirq_entry: OK [ 338.434285] Testing event irq_handler_exit: OK [ 338.916497] Testing event irq_handler_entry: OK [ 339.347554] Testing event signal_deliver: OK [ 339.882720] Testing event signal_generate: OK [ 340.397960] Testing event workqueue_execute_end: OK [ 340.966491] Testing event workqueue_execute_start: OK [ 341.391424] Testing event workqueue_activate_work: OK [ 341.895418] Testing event workqueue_queue_work: OK [ 342.355497] Testing event sched_wake_idle_without_ipi: OK [ 342.917050] Testing event sched_swap_numa: OK [ 343.397576] Testing event sched_stick_numa: OK [ 343.842477] Testing event sched_move_numa: OK [ 344.324195] Testing event sched_process_hang: OK [ 344.681163] Testing event sched_pi_setprio: OK [ 345.165919] Testing event sched_stat_runtime: OK [ 345.653250] Testing event sched_stat_blocked: OK [ 346.192318] Testing event sched_stat_iowait: OK [ 346.609028] Testing event sched_stat_sleep: OK [ 347.160852] Testing event sched_stat_wait: OK [ 347.662366] Testing event sched_process_exec: OK [ 348.201689] Testing event sched_process_fork: OK [ 348.630852] Testing event sched_process_wait: OK [ 349.136951] Testing event sched_wait_task: OK [ 349.592417] Testing event sched_process_exit: OK [ 350.059431] Testing event sched_process_free: OK [ 350.469610] Testing event sched_migrate_task: OK [ 350.909421] Testing event sched_switch: OK [ 351.417509] Testing event sched_wakeup_new: OK [ 351.876722] Testing event sched_wakeup: OK [ 352.310790] Testing event sched_waking: OK [ 352.739240] Testing event sched_kthread_stop_ret: OK [ 353.238173] Testing event sched_kthread_stop: OK [ 353.719527] Testing event lock_acquired: OK [ 354.229189] Testing event lock_contended: OK [ 354.746193] Testing event lock_release: OK [ 355.159666] Testing event lock_acquire: OK [ 355.658271] Testing event console: OK [ 355.988285] Testing event rcu_barrier: OK [ 356.487315] Testing event rcu_torture_read: OK [ 356.997085] Testing event rcu_batch_end: OK [ 357.523539] Testing event rcu_invoke_kfree_callback: OK [ 357.972488] Testing event rcu_invoke_callback: OK [ 358.451422] Testing event rcu_batch_start: OK [ 358.908022] Testing event rcu_kfree_callback: OK [ 359.498629] Testing event rcu_callback: OK [ 359.972442] Testing event rcu_dyntick: OK [ 360.398362] Testing event rcu_fqs: OK [ 360.939557] Testing event rcu_quiescent_state_report: OK [ 361.380457] Testing event rcu_unlock_preempted_task: OK [ 361.933557] Testing event rcu_preempt_task: OK [ 362.437223] Testing event rcu_nocb_wake: OK [ 362.908923] Testing event rcu_exp_funnel_lock: OK [ 363.457774] Testing event rcu_exp_grace_period: OK [ 363.908917] Testing event rcu_grace_period_init: OK [ 364.402662] Testing event rcu_future_grace_period: OK [ 364.911364] Testing event rcu_grace_period: OK [ 365.323644] Testing event rcu_utilization: OK [ 365.787965] Testing event swiotlb_bounced: OK [ 366.344701] Testing event tick_stop: OK [ 366.771784] Testing event itimer_expire: OK [ 367.302296] Testing event itimer_state: OK [ 367.704448] Testing event hrtimer_cancel: OK [ 368.237912] Testing event hrtimer_expire_exit: OK [ 368.671604] Testing event hrtimer_expire_entry: OK [ 369.228664] Testing event hrtimer_start: OK [ 369.611781] Testing event hrtimer_init: OK [ 370.093611] Testing event timer_cancel: OK [ 370.530593] Testing event timer_expire_exit: OK [ 371.055403] Testing event timer_expire_entry: OK [ 371.535558] Testing event timer_start: OK [ 371.969628] Testing event timer_init: OK [ 372.520336] Testing event alarmtimer_cancel: OK [ 373.057572] Testing event alarmtimer_start: OK [ 373.613448] Testing event alarmtimer_fired: OK [ 374.099379] Testing event alarmtimer_suspend: OK [ 374.641647] Testing event module_request: OK [ 375.184476] Testing event module_put: OK [ 375.670710] Testing event module_get: OK [ 376.195501] Testing event module_free: OK [ 376.672797] Testing event module_load: OK [ 377.129634] Testing event cgroup_transfer_tasks: OK [ 377.578248] Testing event cgroup_attach_task: OK [ 378.020378] Testing event cgroup_rename: OK [ 378.492432] Testing event cgroup_release: OK [ 378.896166] Testing event cgroup_rmdir: OK [ 379.536915] Testing event cgroup_mkdir: OK [ 379.927470] Testing event cgroup_remount: OK [ 380.396010] Testing event cgroup_destroy_root: OK [ 380.835112] Testing event cgroup_setup_root: OK [ 381.213111] Testing event irq_enable: OK [ 382.186705] Testing event irq_disable: OK [ 383.048069] Testing event ftrace_test_filter: OK [ 383.416345] Testing event dev_pm_qos_remove_request: OK [ 383.856568] Testing event dev_pm_qos_update_request: OK [ 384.373836] Testing event dev_pm_qos_add_request: OK [ 384.772589] Testing event pm_qos_update_flags: OK [ 385.335536] Testing event pm_qos_update_target: OK [ 385.715982] Testing event pm_qos_update_request_timeout: OK [ 386.196207] Testing event pm_qos_remove_request: OK [ 386.597023] Testing event pm_qos_update_request: OK [ 387.006842] Testing event pm_qos_add_request: OK [ 387.595792] Testing event power_domain_target: OK [ 388.040820] Testing event clock_set_rate: OK [ 388.369287] Testing event clock_disable: OK [ 388.774920] Testing event clock_enable: OK [ 389.171536] Testing event wakeup_source_deactivate: OK [ 389.680664] Testing event wakeup_source_activate: OK [ 390.316019] Testing event suspend_resume: OK [ 390.864049] Testing event device_pm_callback_end: OK [ 391.405095] Testing event device_pm_callback_start: OK [ 391.866689] Testing event cpu_frequency_limits: OK [ 392.389772] Testing event cpu_frequency: OK [ 392.662317] Testing event pstate_sample: OK [ 393.229304] Testing event powernv_throttle: OK [ 393.651067] Testing event cpu_idle: OK [ 394.125345] Testing event rpm_return_int: OK [ 394.493758] Testing event rpm_idle: OK [ 394.891939] Testing event rpm_resume: OK [ 395.397632] Testing event rpm_suspend: OK [ 395.877770] Testing event benchmark_event: OK [ 396.690414] Testing event xdp_devmap_xmit: OK [ 397.111450] Testing event xdp_cpumap_enqueue: OK [ 397.523462] Testing event xdp_cpumap_kthread: OK [ 397.950101] Testing event xdp_redirect_map_err: OK [ 398.531913] Testing event xdp_redirect_map: OK [ 399.061709] Testing event xdp_redirect_err: OK [ 399.559465] Testing event xdp_redirect: OK [ 399.967202] Testing event xdp_exception: OK [ 400.519397] Testing event rseq_ip_fixup: OK [ 401.134349] Testing event rseq_update: OK [ 401.509064] Testing event file_check_and_advance_wb_err: OK [ 402.059382] Testing event filemap_set_wb_err: OK [ 402.448409] Testing event mm_filemap_add_to_page_cache: OK [ 402.981941] Testing event mm_filemap_delete_from_page_cache: OK [ 403.528021] Testing event compact_retry: OK [ 404.043537] Testing event skip_task_reaping: OK [ 404.445629] Testing event finish_task_reaping: OK [ 404.860310] Testing event start_task_reaping: OK [ 405.352023] Testing event wake_reaper: OK [ 405.884647] Testing event mark_victim: OK [ 406.308892] Testing event reclaim_retry_zone: OK [ 406.829081] Testing event oom_score_adj_update: OK [ 407.359795] Testing event mm_lru_activate: OK [ 407.847024] Testing event mm_lru_insertion: OK [ 408.377512] Testing event mm_vmscan_inactive_list_is_low: OK [ 408.829907] Testing event mm_vmscan_lru_shrink_active: OK [ 409.343906] Testing event mm_vmscan_lru_shrink_inactive: OK [ 409.776628] Testing event mm_vmscan_writepage: OK [ 410.160087] Testing event mm_vmscan_lru_isolate: OK [ 410.596724] Testing event mm_shrink_slab_end: OK [ 411.088297] Testing event mm_shrink_slab_start: OK [ 411.663032] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 412.080789] Testing event mm_vmscan_memcg_reclaim_end: OK [ 412.681583] Testing event mm_vmscan_direct_reclaim_end: OK [ 413.096547] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 413.509749] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 413.902978] Testing event mm_vmscan_direct_reclaim_begin: OK [ 414.458876] Testing event mm_vmscan_wakeup_kswapd: OK [ 414.895263] Testing event mm_vmscan_kswapd_wake: OK [ 415.334700] Testing event mm_vmscan_kswapd_sleep: OK [ 415.811728] Testing event percpu_destroy_chunk: OK [ 416.313249] Testing event percpu_create_chunk: OK [ 416.796529] Testing event percpu_alloc_percpu_fail: OK [ 417.325087] Testing event percpu_free_percpu: OK [ 417.796342] Testing event percpu_alloc_percpu: OK [ 418.261712] Testing event mm_page_alloc_extfrag: OK [ 418.679219] Testing event mm_page_pcpu_drain: OK [ 419.221292] Testing event mm_page_alloc_zone_locked: OK [ 419.705629] Testing event mm_page_alloc: OK [ 420.189488] Testing event mm_page_free_batched: OK [ 420.611032] Testing event mm_page_free: OK [ 421.070749] Testing event kmem_cache_free: OK [ 421.349294] Testing event kfree: OK [ 421.802799] Testing event kmem_cache_alloc_node: OK [ 422.333670] Testing event kmalloc_node: OK [ 422.749646] Testing event kmem_cache_alloc: OK [ 423.311907] Testing event kmalloc: OK [ 423.823070] Testing event mm_compaction_kcompactd_wake: OK [ 424.332762] Testing event mm_compaction_wakeup_kcompactd: OK [ 424.831110] Testing event mm_compaction_kcompactd_sleep: OK [ 425.345438] Testing event mm_compaction_defer_reset: OK [ 425.870134] Testing event mm_compaction_defer_compaction: OK [ 426.352576] Testing event mm_compaction_deferred: OK [ 426.859640] Testing event mm_compaction_suitable: OK [ 427.349093] Testing event mm_compaction_finished: OK [ 427.799280] Testing event mm_compaction_try_to_compact_pages: OK [ 428.300611] Testing event mm_compaction_end: OK [ 428.753505] Testing event mm_compaction_begin: OK [ 429.237034] Testing event mm_compaction_migratepages: OK [ 429.727121] Testing event mm_compaction_isolate_freepages: OK [ 430.248218] Testing event mm_compaction_isolate_migratepages: OK [ 430.757970] Testing event mm_migrate_pages: OK [ 431.267469] Testing event mm_collapse_huge_page_swapin: OK [ 431.715795] Testing event mm_collapse_huge_page_isolate: OK [ 432.232005] Testing event mm_collapse_huge_page: OK [ 432.691707] Testing event mm_khugepaged_scan_pmd: OK [ 433.171614] Testing event test_pages_isolated: OK [ 433.691425] Testing event cma_release: OK [ 434.201626] Testing event cma_alloc: OK [ 434.716718] Testing event page_ref_unfreeze: OK [ 435.121121] Testing event page_ref_freeze: OK [ 435.598679] Testing event page_ref_mod_unless: OK [ 436.196625] Testing event page_ref_mod_and_return: OK [ 436.735919] Testing event page_ref_mod_and_test: OK [ 438.204901] Testing event page_ref_mod: OK [ 438.951037] Testing event page_ref_set: OK [ 439.537857] Testing event sb_clear_inode_writeback: OK [ 440.072476] Testing event sb_mark_inode_writeback: OK [ 440.461827] Testing event writeback_dirty_inode_enqueue: OK [ 441.026697] Testing event writeback_lazytime_iput: OK [ 441.593723] Testing event writeback_lazytime: OK [ 442.108956] Testing event writeback_single_inode: OK [ 442.604576] Testing event writeback_single_inode_start: OK [ 443.126284] Testing event writeback_wait_iff_congested: OK [ 443.656000] Testing event writeback_congestion_wait: OK [ 444.111139] Testing event writeback_sb_inodes_requeue: OK [ 444.634817] Testing event balance_dirty_pages: OK [ 445.117247] Testing event bdi_dirty_ratelimit: OK [ 445.621820] Testing event global_dirty_state: OK [ 446.103661] Testing event writeback_queue_io: OK [ 446.489585] Testing event wbc_writepage: OK [ 447.009088] Testing event writeback_bdi_register: OK [ 447.333731] Testing event writeback_wake_background: OK [ 447.880381] Testing event writeback_pages_written: OK [ 448.310472] Testing event writeback_wait: OK [ 448.731146] Testing event writeback_written: OK [ 449.024312] Testing event writeback_start: OK [ 449.560794] Testing event writeback_exec: OK [ 450.029915] Testing event writeback_queue: OK [ 450.496882] Testing event writeback_write_inode: OK [ 451.026533] Testing event writeback_write_inode_start: OK [ 451.447505] Testing event writeback_dirty_inode: OK [ 451.828700] Testing event writeback_dirty_inode_start: OK [ 452.249576] Testing event writeback_mark_inode_dirty: OK [ 452.734974] Testing event writeback_dirty_page: OK [ 453.199699] Testing event dax_writeback_one: OK [ 453.667794] Testing event dax_writeback_range_done: OK [ 454.135700] Testing event dax_writeback_range: OK [ 454.562230] Testing event dax_insert_mapping: OK [ 455.001807] Testing event dax_insert_pfn_mkwrite: OK [ 455.591389] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 455.977949] Testing event dax_load_hole: OK [ 456.423045] Testing event dax_pte_fault_done: OK [ 456.891825] Testing event dax_pte_fault: OK [ 457.377210] Testing event dax_pmd_insert_mapping: OK [ 457.733858] Testing event dax_pmd_load_hole_fallback: OK [ 458.164213] Testing event dax_pmd_load_hole: OK [ 458.592110] Testing event dax_pmd_fault_done: OK [ 459.135506] Testing event dax_pmd_fault: OK [ 459.642039] Testing event generic_add_lease: OK [ 460.129051] Testing event time_out_leases: OK [ 460.582563] Testing event generic_delete_lease: OK [ 461.010099] Testing event break_lease_unblock: OK [ 461.566995] Testing event break_lease_block: OK [ 462.027656] Testing event break_lease_noblock: OK [ 462.479885] Testing event flock_lock_inode: OK [ 462.905682] Testing event locks_remove_posix: OK [ 463.428156] Testing event fcntl_setlk: OK [ 463.909838] Testing event posix_lock_inode: OK [ 464.374447] Testing event locks_get_lock_context: OK [ 464.838160] Testing event block_rq_remap: OK [ 465.247511] Testing event block_bio_remap: OK [ 465.798289] Testing event block_split: OK [ 466.225898] Testing event block_unplug: OK [ 466.776033] Testing event block_plug: OK [ 467.243106] Testing event block_sleeprq: OK [ 467.591074] Testing event block_getrq: OK [ 468.058105] Testing event block_bio_queue: OK [ 468.609780] Testing event block_bio_frontmerge: OK [ 469.077526] Testing event block_bio_backmerge: OK [ 469.597566] Testing event block_bio_complete: OK [ 470.073589] Testing event block_bio_bounce: OK [ 470.497229] Testing event block_rq_issue: OK [ 470.868901] Testing event block_rq_insert: OK [ 471.357575] Testing event block_rq_complete: OK [ 471.893672] Testing event block_rq_requeue: OK [ 472.468947] Testing event block_dirty_buffer: OK [ 472.999205] Testing event block_touch_buffer: OK [ 473.544023] Testing event wbt_timer: OK [ 474.082330] Testing event wbt_step: OK [ 474.536738] Testing event wbt_lat: OK [ 474.895177] Testing event wbt_stat: OK [ 475.297248] Testing event gpio_value: OK [ 475.819945] Testing event gpio_direction: OK [ 476.309330] Testing event clk_set_duty_cycle_complete: OK [ 476.793842] Testing event clk_set_duty_cycle: OK [ 477.358134] Testing event clk_set_phase_complete: OK [ 477.806887] Testing event clk_set_phase: OK [ 478.290962] Testing event clk_set_parent_complete: OK [ 478.796996] Testing event clk_set_parent: OK [ 479.243743] Testing event clk_set_rate_complete: OK [ 479.680911] Testing event clk_set_rate: OK [ 480.126147] Testing event clk_unprepare_complete: OK [ 480.617142] Testing event clk_unprepare: OK [ 481.019604] Testing event clk_prepare_complete: OK [ 481.484898] Testing event clk_prepare: OK [ 481.937451] Testing event clk_disable_complete: OK [ 482.475203] Testing event clk_disable: OK [ 482.986163] Testing event clk_enable_complete: OK [ 483.457237] Testing event clk_enable: OK [ 483.948096] Testing event rpmh_send_msg: OK [ 484.529353] Testing event rpmh_tx_done: OK [ 484.945859] Testing event regulator_set_voltage_complete: OK [ 485.337869] Testing event regulator_set_voltage: OK [ 485.808894] Testing event regulator_disable_complete: OK [ 486.280635] Testing event regulator_disable: OK [ 486.752582] Testing event regulator_enable_complete: OK [ 487.209789] Testing event regulator_enable_delay: OK [ 487.755481] Testing event regulator_enable: OK [ 488.253816] Testing event urandom_read: OK [ 488.658714] Testing event random_read: OK [ 489.081355] Testing event extract_entropy_user: OK [ 489.574672] Testing event extract_entropy: OK [ 490.074084] Testing event get_random_bytes_arch: OK [ 490.601866] Testing event get_random_bytes: OK [ 491.110885] Testing event xfer_secondary_pool: OK [ 491.638988] Testing event add_disk_randomness: OK [ 492.173021] Testing event add_input_randomness: OK [ 492.647280] Testing event debit_entropy: OK [ 493.175343] Testing event push_to_pool: OK [ 493.667773] Testing event credit_entropy_bits: OK [ 494.093580] Testing event mix_pool_bytes_nolock: OK [ 494.578993] Testing event mix_pool_bytes: OK [ 495.048755] Testing event add_device_randomness: OK [ 495.535824] Testing event io_page_fault: OK [ 495.969256] Testing event unmap: OK [ 496.482262] Testing event map: OK [ 496.937598] Testing event detach_device_from_domain: OK [ 497.434334] Testing event attach_device_to_domain: OK [ 497.920913] Testing event remove_device_from_group: OK [ 498.435457] Testing event add_device_to_group: OK [ 498.915374] Testing event regcache_drop_region: OK [ 499.363334] Testing event regmap_async_complete_done: OK [ 499.875613] Testing event regmap_async_complete_start: OK [ 500.418579] Testing event regmap_async_io_complete: OK [ 500.921084] Testing event regmap_async_write_start: OK [ 501.453821] Testing event regmap_cache_bypass: OK [ 501.916121] Testing event regmap_cache_only: OK [ 502.447322] Testing event regcache_sync: OK [ 502.909689] Testing event regmap_hw_write_done: OK [ 503.401763] Testing event regmap_hw_write_start: OK [ 503.908845] Testing event regmap_hw_read_done: OK [ 504.393209] Testing event regmap_hw_read_start: OK [ 504.851813] Testing event regmap_reg_read_cache: OK [ 505.420406] Testing event regmap_reg_read: OK [ 505.856350] Testing event regmap_reg_write: OK [ 506.379225] Testing event dma_fence_wait_end: OK [ 506.910854] Testing event dma_fence_wait_start: OK [ 507.374738] Testing event dma_fence_signaled: OK [ 507.892781] Testing event dma_fence_enable_signal: OK [ 508.362084] Testing event dma_fence_destroy: OK [ 508.797703] Testing event dma_fence_init: OK [ 509.653398] Testing event dma_fence_emit: OK [ 510.014892] Testing event sync_timeline: OK [ 510.492281] Testing event spi_transfer_stop: OK [ 510.851477] Testing event spi_transfer_start: OK [ 511.348755] Testing event spi_message_done: OK [ 511.800987] Testing event spi_message_start: OK [ 512.175555] Testing event spi_message_submit: OK [ 512.646202] Testing event spi_controller_busy: OK [ 513.112240] Testing event spi_controller_idle: OK [ 513.614719] Testing event rtc_timer_fired: OK [ 514.069980] Testing event rtc_timer_dequeue: OK [ 514.430973] Testing event rtc_timer_enqueue: OK [ 514.888835] Testing event rtc_read_offset: OK [ 515.393583] Testing event rtc_set_offset: OK [ 515.889844] Testing event rtc_alarm_irq_enable: OK [ 516.352075] Testing event rtc_irq_set_state: OK [ 516.815451] Testing event rtc_irq_set_freq: OK [ 517.344685] Testing event rtc_read_alarm: OK [ 517.865199] Testing event rtc_set_alarm: OK [ 518.375227] Testing event rtc_read_time: OK [ 518.913030] Testing event rtc_set_time: OK [ 519.324737] Testing event memory_failure_event: OK [ 519.812957] Testing event aer_event: OK [ 520.332935] Testing event non_standard_event: OK [ 520.746217] Testing event arm_event: OK [ 521.343814] Testing event mc_event: OK [ 521.794706] Testing event binder_return: OK [ 522.258454] Testing event binder_command: OK [ 522.767618] Testing event binder_unmap_kernel_end: OK [ 523.204097] Testing event binder_unmap_kernel_start: OK [ 523.692644] Testing event binder_unmap_user_end: OK [ 524.086119] Testing event binder_unmap_user_start: OK [ 524.496501] Testing event binder_alloc_page_end: OK [ 524.968080] Testing event binder_alloc_page_start: OK [ 525.424346] Testing event binder_free_lru_end: OK [ 525.871491] Testing event binder_free_lru_start: OK [ 526.209012] Testing event binder_alloc_lru_end: OK [ 526.769375] Testing event binder_alloc_lru_start: OK [ 527.294632] Testing event binder_update_page_range: OK [ 527.788931] Testing event binder_transaction_failed_buffer_release: OK [ 528.273006] Testing event binder_transaction_buffer_release: OK [ 528.739353] Testing event binder_transaction_alloc_buf: OK [ 529.203810] Testing event binder_transaction_fd_recv: OK [ 529.618321] Testing event binder_transaction_fd_send: OK [ 530.147917] Testing event binder_transaction_ref_to_ref: OK [ 530.614779] Testing event binder_transaction_ref_to_node: OK [ 531.089026] Testing event binder_transaction_node_to_ref: OK [ 531.629425] Testing event binder_transaction_received: OK [ 532.099682] Testing event binder_transaction: OK [ 532.598602] Testing event binder_wait_for_work: OK [ 533.124151] Testing event binder_read_done: OK [ 533.609575] Testing event binder_write_done: OK [ 533.997901] Testing event binder_ioctl_done: OK [ 534.432158] Testing event binder_unlock: OK [ 534.823747] Testing event binder_locked: OK [ 535.332003] Testing event binder_lock: OK [ 535.750235] Testing event binder_ioctl: OK [ 536.276103] Testing event br_fdb_update: OK [ 536.764776] Testing event fdb_delete: OK [ 537.298255] Testing event br_fdb_external_learn_add: OK [ 537.794440] Testing event br_fdb_add: OK [ 538.220567] Testing event qdisc_dequeue: OK [ 538.757602] Testing event fib_table_lookup: OK [ 539.186316] Testing event tcp_probe: OK [ 539.648449] Testing event tcp_retransmit_synack: OK [ 540.189522] Testing event tcp_rcv_space_adjust: OK [ 540.658543] Testing event tcp_destroy_sock: [ 540.795569] kworker/dying (13) used greatest stack depth: 11840 bytes left [ 541.171245] OK [ 541.176839] Testing event tcp_receive_reset: OK [ 541.674632] Testing event tcp_send_reset: OK [ 542.267386] Testing event tcp_retransmit_skb: OK [ 542.794590] Testing event udp_fail_queue_rcv_skb: OK [ 543.227584] Testing event inet_sock_set_state: OK [ 543.714196] Testing event sock_exceed_buf_limit: OK [ 544.240243] Testing event sock_rcvqueue_full: OK [ 544.693214] Testing event napi_poll: OK [ 545.087246] Testing event netif_rx_ni_entry: OK [ 545.722716] Testing event netif_rx_entry: OK [ 546.095280] Testing event netif_receive_skb_list_entry: OK [ 546.596046] Testing event netif_receive_skb_entry: OK [ 547.056695] Testing event napi_gro_receive_entry: OK [ 547.548143] Testing event napi_gro_frags_entry: OK [ 548.057337] Testing event netif_rx: OK [ 548.482116] Testing event netif_receive_skb: OK [ 548.991225] Testing event net_dev_queue: OK [ 549.477673] Testing event net_dev_xmit: OK [ 549.951214] Testing event net_dev_start_xmit: OK [ 550.277839] Testing event skb_copy_datagram_iovec: OK [ 550.831158] Testing event consume_skb: OK [ 551.246096] Testing event kfree_skb: OK [ 551.717703] Testing event 9p_protocol_dump: OK [ 552.189929] Testing event 9p_client_res: OK [ 552.601499] Testing event 9p_client_req: OK [ 553.132323] Running tests on trace event systems: [ 553.141702] Testing event system 9p: OK [ 553.739327] Testing event system skb: OK [ 554.283201] Testing event system net: OK [ 554.864901] Testing event system napi: OK [ 555.456998] Testing event system sock: OK [ 555.911560] Testing event system udp: OK [ 556.399635] Testing event system tcp: OK [ 556.993302] Testing event system fib: OK [ 557.412980] Testing event system qdisc: OK [ 557.965703] Testing event system bridge: OK [ 558.481355] Testing event system binder: OK [ 559.310654] Testing event system ras: OK [ 560.151446] Testing event system rtc: OK [ 560.837193] Testing event system spi: OK [ 561.657132] Testing event system sync_trace: OK [ 562.184788] Testing event system dma_fence: OK [ 562.753791] Testing event system regmap: OK [ 563.477539] Testing event system iommu: OK [ 564.107775] Testing event system random: OK [ 564.765933] Testing event system regulator: OK [ 565.450233] Testing event system rpmh: OK [ 566.060470] Testing event system clk: OK [ 566.582909] Testing event system gpio: OK [ 567.092691] Testing event system wbt: OK [ 567.616419] Testing event system block: OK [ 568.343639] Testing event system filelock: OK [ 569.118603] Testing event system fs_dax: OK [ 569.866636] Testing event system writeback: OK [ 570.871159] Testing event system page_ref: OK [ 573.101580] Testing event system cma: OK [ 573.640733] Testing event system page_isolation: OK [ 574.018043] Testing event system huge_memory: OK [ 574.677071] Testing event system migrate: OK [ 575.261889] Testing event system compaction: OK [ 575.917176] Testing event system kmem: OK [ 576.688717] Testing event system percpu: OK [ 577.313611] Testing event system vmscan: OK [ 578.086398] Testing event system pagemap: OK [ 578.716976] Testing event system oom: OK [ 579.318127] Testing event system filemap: OK [ 579.937549] Testing event system rseq: OK [ 580.437955] Testing event system xdp: OK [ 581.117886] Testing event system benchmark: OK [ 581.879927] Testing event system rpm: OK [ 582.356680] Testing event system power: OK [ 583.376877] Testing event system test: OK [ 583.892015] Testing event system preemptirq: OK [ 585.064296] Testing event system cgroup: OK [ 585.764773] Testing event system module: OK [ 586.195863] Testing event system alarmtimer: OK [ 586.770877] Testing event system timer: OK [ 587.515128] Testing event system swiotlb: OK [ 588.068250] Testing event system rcu: OK [ 588.970856] Testing event system printk: OK [ 589.566793] Testing event system lock: OK [ 590.237650] Testing event system sched: OK [ 591.014821] Testing event system workqueue: OK [ 591.840417] Testing event system signal: OK [ 592.381126] Testing event system irq: OK [ 593.028866] Testing event system cpuhp: OK [ 593.521955] Testing event system task: OK [ 593.993598] Testing event system kvm: OK [ 594.933662] Testing event system emulation: OK [ 595.542153] Testing event system ipi: OK [ 596.044411] Testing event system raw_syscalls: OK [ 596.569120] Testing event system initcall: OK [ 597.147313] Running tests on all trace events: [ 597.156236] Testing all events: OK [ 615.365653] ftrace_replace_code: 1 [ 622.677615] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 622.683639] Testing ftrace filter: OK [ 622.768450] trace_kprobe: Testing kprobe tracing: OK [ 623.453745] Loading compiled-in X.509 certificates [ 623.551973] _warn_unseeded_randomness: 13 callbacks suppressed [ 623.553198] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 623.554955] Loaded X.509 cert 'Build time autogenerated kernel key: ef4425a12675de91b49c005bf61fbcc152bd6e38' [ 623.569766] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 623.570715] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 623.598047] zswap: default zpool zbud not available [ 623.598908] zswap: pool creation failed [ 623.623152] page_owner is disabled [ 623.753275] Key type big_key registered [ 623.810041] Key type encrypted registered [ 623.814781] ima: No TPM chip found, activating TPM-bypass! [ 623.816687] ima: Allocated hash algorithm: sha1 [ 623.819394] No architecture policies found [ 623.826303] evm: Initialising EVM extended attributes: [ 623.826855] evm: security.selinux [ 623.827319] evm: security.SMACK64 [ 623.827739] evm: security.SMACK64EXEC [ 623.828637] evm: security.SMACK64TRANSMUTE [ 623.829091] evm: security.SMACK64MMAP [ 623.829517] evm: security.apparmor [ 623.829927] evm: security.ima [ 623.830328] evm: security.capability [ 623.830773] evm: HMAC attrs: 0x1 [ 623.886086] hctosys: unable to open rtc device (rtc0) [ 623.901284] psci_checker: PSCI checker started using 1 CPUs [ 623.901813] psci_checker: Starting hotplug tests [ 623.902972] psci_checker: Trying to turn off and on again all CPUs [ 623.904429] psci_checker: Trying to turn off and on again group 0 (CPUs 0) [ 623.905410] psci_checker: Hotplug tests passed OK [ 623.905918] psci_checker: Starting suspend tests (10 cycles per state) [ 623.906677] psci_checker: cpuidle not available on CPU 0, ignoring [ 623.907456] psci_checker: Could not start suspend tests on any CPU [ 623.908437] psci_checker: PSCI checker completed [ 623.962882] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 624.129019] ### dt-test ### start of unittest - you will see error messages [ 624.151197] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 624.152767] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 624.153863] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 624.154710] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 624.155522] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 624.156819] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 624.170969] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 624.173174] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 624.174680] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 624.369490] kobject: 'testcase-data:platform-tests:test-device@1' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 624.382546] kobject: 'testcase-data:platform-tests:test-device@0' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 624.391550] kobject: 'unittest-bus.0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 624.512933] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status [ 624.553142] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status [ 624.566134] _warn_unseeded_randomness: 4 callbacks suppressed [ 624.566372] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 624.567613] kobject: 'testcase-data:overlay-node:test-bus:test-unittest1' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 624.575466] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status [ 624.579212] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 624.615169] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status [ 624.626847] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 624.627562] kobject: 'testcase-data:overlay-node:test-bus:test-unittest3' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 624.679873] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status [ 624.726040] kobject: 'testcase-data:overlay-node:test-bus:test-unittest5' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 624.735523] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status [ 624.770887] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status [ 624.817328] kobject: 'testcase-data:overlay-node:test-bus:test-unittest7' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 624.829411] kobject: 'testcase-data:overlay-node:test-bus:test-unittest6' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 624.837128] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status [ 624.871845] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo [ 624.874127] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 624.875037] OF: overlay: overlay #6 is not topmost [ 624.887414] kobject: 'testcase-data:overlay-node:test-bus:test-unittest8' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 625.083333] kobject: 'testcase-data:overlay-node:test-bus:test-unittest11:test-unittest111' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 625.094368] kobject: 'testcase-data:overlay-node:test-bus:test-unittest11' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 625.095777] OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /testcase-data/overlay-node/test-bus/test-unittest11/test-unittest111 [ 625.097819] OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /testcase-data/overlay-node/test-bus/test-unittest11 [ 625.099653] OF: overlay: remove: Could not find overlay #7 [ 625.100676] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #7 [ 625.114897] kobject: 'testcase-data:overlay-node:test-bus:test-unittest10:test-unittest101' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 625.125756] kobject: 'testcase-data:overlay-node:test-bus:test-unittest10' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 625.127099] OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /testcase-data/overlay-node/test-bus/test-unittest10/test-unittest101 [ 625.129117] OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /testcase-data/overlay-node/test-bus/test-unittest10 [ 625.142275] kobject: 'testcase-data:overlay-node:test-bus:test-unittest4' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 625.143599] OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /testcase-data/overlay-node/test-bus/test-unittest4 [ 625.186954] kobject: 'testcase-data:overlay-node:test-bus:test-unittest2' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 625.230020] kobject: 'testcase-data:overlay-node:test-bus:test-unittest0' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 625.291633] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/status [ 625.295641] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/status [ 625.297837] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@30/incline-up [ 625.299087] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@40/incline-up [ 625.307805] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/status [ 625.309435] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/color [ 625.310480] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/rate [ 625.312534] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/hvac_2 [ 625.314038] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200 [ 625.315286] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_left [ 625.317092] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_right [ 625.400740] OF: overlay: ERROR: multiple fragments add and/or delete node /testcase-data-2/substation@100/motor-1/controller [ 625.403770] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/controller/name [ 625.405919] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 625.407288] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 625.414660] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 625.415817] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 625.417545] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/rpm_avail [ 625.430503] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 625.431746] ### dt-test ### end of unittest - 217 passed, 0 failed [ 625.457822] uart-pl011 9000000.pl011: no DMA platform data [ 625.711018] _warn_unseeded_randomness: 11 callbacks suppressed [ 625.711269] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 625.713066] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 625.754425] VFS: Mounted root (9p filesystem) readonly on device 0:19. [ 625.775160] devtmpfs: mounted [ 625.794898] integrity: Unable to open file: /etc/keys/x509_ima.der (-2) [ 625.807499] integrity: Unable to open file: /etc/keys/x509_evm.der (-2) [ 626.486965] Freeing unused kernel memory: 6144K [ 626.889765] Checked W+X mappings: passed, no W+X pages found [ 626.890560] rodata_test: all tests were successful [ 626.891381] Run /sbin/init as init process [ 627.001386] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=0 [ 627.003297] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=0 [ 627.004701] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=0 [ 627.102742] kobject: 'test-unittest11' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 627.840982] random: fast init done [ 628.255329] _warn_unseeded_randomness: 4 callbacks suppressed [ 628.255573] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=1 [ 628.257323] kobject: 'test-unittest4' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 628.264896] kobject: 'test-unittest10' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 628.267405] kobject: 'test-unittest111' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 628.352091] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 629.278012] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=1 [ 629.278782] kobject: 'test-unittest101' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 629.281802] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11/test-unittest111 [ 629.811815] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 630.270990] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest10 [ 631.326479] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11 SELinux: Could not open policy file <= /etc/selinux/targeted/policy/policy.31: No such file or directory [ 631.491403] systemd[1]: System time before build time, advancing clock. [ 631.923680] systemd[1]: Failed to insert module 'autofs4': No such file or directory [ 632.405920] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest4 [ 633.524956] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest10/test-unittest101 [ 634.662871] random: systemd: uninitialized urandom read (16 bytes read) [ 634.967743] random: systemd: uninitialized urandom read (16 bytes read) [ 634.973766] random: systemd: uninitialized urandom read (16 bytes read) [ 639.696575] 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) [ 639.768919] systemd[1]: Detected virtualization qemu. [ 639.774854] systemd[1]: Detected architecture arm64. Welcome to Debian GNU/Linux 9 (stretch)! [ 639.910058] systemd[1]: Set hostname to . [ 640.085952] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=1 [ 640.148066] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 640.765327] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 641.267831] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 641.269468] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 641.270277] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 642.365954] _warn_unseeded_randomness: 19 callbacks suppressed [ 642.366200] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 642.367647] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 642.369034] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 643.396520] random: systemd: uninitialized urandom read (16 bytes read) [ 643.497473] _warn_unseeded_randomness: 12 callbacks suppressed [ 643.497733] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 643.499036] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 643.501938] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 645.066962] _warn_unseeded_randomness: 12 callbacks suppressed [ 645.067204] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 645.069336] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 645.070182] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 648.010188] systemd-hiberna (1269) used greatest stack depth: 8 bytes left [ 649.347944] random: systemd-cryptse: uninitialized urandom read (16 bytes read) [ 649.943386] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 651.003508] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 652.662186] random: systemd: uninitialized urandom read (16 bytes read) [ 652.863412] random: systemd: uninitialized urandom read (16 bytes read) [ 652.867645] random: systemd: uninitialized urandom read (16 bytes read) [ 652.942814] _warn_unseeded_randomness: 8 callbacks suppressed [ 652.943088] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 653.061701] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 659.898432] systemd[1]: networking.service: Cannot add dependency job, ignoring: Unit networking.service is masked. [ 659.970791] random: get_random_bytes called from generate_random_uuid+0x20/0x50 with crng_init=1 [ 660.086378] systemd[1]: Created slice System Slice. [ OK ] Created slice System Slice. [ 660.181948] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ 660.407875] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 660.510630] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 660.606266] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 660.667853] systemd[1]: Listening on Journal Audit Socket. [ OK ] Listening on Journal Audit Socket. [ 661.197195] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 661.198247] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 661.199130] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 661.259759] systemd[1]: Mounting Debug File System... Mounting Debug File System... [ 661.329763] systemd[1]: Starting of Arbitrary Executable File Formats File System Automount Point not supported. [UNSUPP] Starting of Arbitrary Executable Fi…tem Automount Point not supported. [ 661.428692] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 662.230054] _warn_unseeded_randomness: 9 callbacks suppressed [ 662.230333] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 662.242220] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 662.250172] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 662.366199] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ OK ] Listening on Journal Socket. [ 663.254101] _warn_unseeded_randomness: 1 callbacks suppressed [ 663.254356] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 663.554602] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 663.555640] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 Starting Create Static Device Nodes in /dev... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Remote File Systems. [ 664.530707] _warn_unseeded_randomness: 6 callbacks suppressed [ 664.530948] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 664.532868] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 664.533667] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 665.628756] _warn_unseeded_randomness: 3 callbacks suppressed [ 665.628986] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [ 665.921850] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 666.021777] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 666.706496] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 666.985672] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ 667.087617] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 667.089106] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 667.089915] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 667.154573] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 667.192912] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ 667.239813] systemd[1]: Reached target Encrypted Volumes. [ OK ] Reached target Encrypted Volumes. [ 667.303153] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 667.379772] systemd[1]: Listening on Syslog Socket. [ OK ] Listening on Syslog Socket. [ 668.307122] systemd[1]: Starting Journal Service... Starting Journal Service... [ OK ] Listening on /dev/initctl Compatibility Named Pipe. [ OK ] Reached target Sockets. [ 670.133615] _warn_unseeded_randomness: 6 callbacks suppressed [ 670.133879] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 670.170301] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 670.215755] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 Starting Remount Root and Kernel File Systems... [ 672.028976] _warn_unseeded_randomness: 13 callbacks suppressed [ 672.029214] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 672.030579] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 672.031393] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 673.159058] systemd[1]: Mounted Debug File System. [ OK ] Mounted Debug File System. [ 673.214851] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 673.276633] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 674.197008] systemd[1]: Started Create Static Device Nodes in /dev. [ OK ] Started Create Static Device Nodes in /dev. [ 674.835170] systemd[1]: Started Load Kernel Modules. [ OK ] Started Load Kernel Modules. [ 675.740698] _warn_unseeded_randomness: 3 callbacks suppressed [ 675.741018] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 675.742417] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 675.743239] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 675.925959] systemd[1]: Mounting Configuration File System... Mounting Configuration File System... [ 676.919779] _warn_unseeded_randomness: 4 callbacks suppressed [ 676.921393] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 677.885824] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 678.022906] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 678.023953] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 678.025271] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 679.030763] _warn_unseeded_randomness: 5 callbacks suppressed [ 679.031005] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 679.032981] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 679.033810] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 679.716924] systemd[1]: Starting udev Kernel Device Manager... Starting udev Kernel Device Manager... [ 681.539777] _warn_unseeded_randomness: 3 callbacks suppressed [ 681.540045] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 681.595744] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 681.618713] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 682.761092] systemd[1]: Mounted Configuration File System. [ OK ] Mounted Configuration File System. [ 682.881024] _warn_unseeded_randomness: 8 callbacks suppressed [ 682.881303] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 683.418449] systemd[1]: Started Remount Root and Kernel File Systems. [ OK ] Started Remount Root and Kernel File Systems. [ 684.099917] systemd[1]: Started Apply Kernel Variables. [ OK ] Started Apply Kernel Variables. [ 685.537052] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 686.991099] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 687.068931] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 687.069920] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 687.070727] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 687.130548] systemd[1]: Reached target Local File Systems (Pre). [ OK ] Reached target Local File Systems (Pre). [ 687.272901] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 688.850644] _warn_unseeded_randomness: 2 callbacks suppressed [ 688.850916] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 688.852698] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 688.939467] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [* ] (1 of 5) A start job is running for…dplug all Devices (33s / no limit)[ 693.346536] _warn_unseeded_randomness: 3 callbacks suppressed [ 693.346833] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 693.891441] systemd[1]: Started Load/Save Random Seed. [ OK ] Started Load/Save Random Seed. [ 696.043300] systemd[1]: Started udev Kernel Device Manager. [ OK ] Started udev Kernel Device Manager. [** ] (1 of 3) A start job is running for…dplug all Devices (41s / no limit) [*** ] (1 of 3) A start job is running for…dplug all Devices (41s / no limit) [ *** ] (2 of 3) A start job is running for Journal Service (42s / 30min 7s) [ *** ] (2 of 3) A start job is running for Journal Service (42s / 30min 7s) [ ***] (2 of 3) A start job is running for Journal Service (43s / 30min 7s) [ **] (3 of 3) A start job is running for dev-ttyAMA0.device (43s / 30min) [ *] (3 of 3) A start job is running for dev-ttyAMA0.device (44s / 30min) [ **] (3 of 3) A start job is running for dev-ttyAMA0.device (44s / 30min) [ ***] (1 of 3) A start job is running for…dplug all Devices (45s / no limit) [ *** ] (1 of 3) A start job is running for…dplug all Devices (45s / no limit) [ *** ] (1 of 3) A start job is running for…dplug all Devices (46s / no limit) [*** ] (2 of 3) A start job is running for Journal Service (46s / 30min 7s) [** ] (2 of 3) A start job is running for Journal Service (47s / 30min 7s) [* ] (2 of 3) A start job is running for Journal Service (47s / 30min 7s) [** ] (3 of 3) A start job is running for dev-ttyAMA0.device (48s / 30min) [*** ] (3 of 3) A start job is running for dev-ttyAMA0.device (48s / 30min) [ *** ] (3 of 3) A start job is running for dev-ttyAMA0.device (49s / 30min)[ 709.639809] systemd[1]: Started Journal Service. [ OK ] Started Journal Service.