[ 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)) #16 SMP Thu Nov 29 10:54:00 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 0xbebf15c0-0xbebf2fff] [ 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: 994084K/2097152K available (14984K kernel code, 3774K rwdata, 4852K rodata, 6144K init, 23374K bss, 93484K 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.000235] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.015325] Console: colour dummy device 80x25 [ 0.019561] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.020073] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.020469] ... MAX_LOCK_DEPTH: 48 [ 0.020806] ... MAX_LOCKDEP_KEYS: 8191 [ 0.021155] ... CLASSHASH_SIZE: 4096 [ 0.021510] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.021886] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.022562] ... CHAINHASH_SIZE: 32768 [ 0.022934] memory used by lock dependency info: 7775 kB [ 0.023325] per task-struct memory footprint: 2688 bytes [ 0.023752] ------------------------ [ 0.024100] | Locking API testsuite: [ 0.024463] ---------------------------------------------------------------------------- [ 0.024895] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.025344] -------------------------------------------------------------------------- [ 0.026137] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.037418] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.048436] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.060449] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.071794] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.084242] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.096431] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.108828] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.118985] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.129186] -------------------------------------------------------------------------- [ 0.129675] recursive read-lock: | ok | | ok | [ 0.133460] recursive read-lock #2: | ok | | ok | [ 0.137168] mixed read-write-lock: | ok | | ok | [ 0.140832] mixed write-read-lock: | ok | | ok | [ 0.144141] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.147484] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.151275] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.155797] -------------------------------------------------------------------------- [ 0.156670] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.161700] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.166808] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.171828] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.176843] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.181876] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.186856] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.191772] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.196723] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.201756] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.206959] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.212288] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.217913] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.223170] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.228411] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.233558] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.238697] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.243918] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.249034] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.254066] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.259100] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.264163] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.269206] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.274521] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.279739] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.285032] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.290387] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.295726] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.301028] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.306331] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.311757] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.317026] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.322246] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.327441] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.332616] hard-irq lock-inversion/123: ok | ok | ok | [ 0.337772] soft-irq lock-inversion/123: ok | ok | ok | [ 0.343055] hard-irq lock-inversion/132: ok | ok | ok | [ 0.348184] soft-irq lock-inversion/132: ok | ok | ok | [ 0.353363] hard-irq lock-inversion/213: ok | ok | ok | [ 0.358504] soft-irq lock-inversion/213: ok | ok | ok | [ 0.363671] hard-irq lock-inversion/231: ok | ok | ok | [ 0.368852] soft-irq lock-inversion/231: ok | ok | ok | [ 0.374082] hard-irq lock-inversion/312: ok | ok | ok | [ 0.379299] soft-irq lock-inversion/312: ok | ok | ok | [ 0.384509] hard-irq lock-inversion/321: ok | ok | ok | [ 0.389759] soft-irq lock-inversion/321: ok | ok | ok | [ 0.395089] hard-irq read-recursion/123: ok | [ 0.397449] soft-irq read-recursion/123: ok | [ 0.399505] hard-irq read-recursion/132: ok | [ 0.401843] soft-irq read-recursion/132: ok | [ 0.403890] hard-irq read-recursion/213: ok | [ 0.406191] soft-irq read-recursion/213: ok | [ 0.408246] hard-irq read-recursion/231: ok | [ 0.410558] soft-irq read-recursion/231: ok | [ 0.412584] hard-irq read-recursion/312: ok | [ 0.414925] soft-irq read-recursion/312: ok | [ 0.416955] hard-irq read-recursion/321: ok | [ 0.419260] soft-irq read-recursion/321: ok | [ 0.421295] -------------------------------------------------------------------------- [ 0.421773] | Wound/wait tests | [ 0.422081] --------------------- [ 0.422416] ww api failures: ok | ok | ok | [ 0.429192] ww contexts mixing: ok | ok | [ 0.432589] finishing ww context: ok | ok | ok | ok | [ 0.439010] locking mismatches: ok | ok | ok | [ 0.443868] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.460257] spinlock nest unlocked: ok | [ 0.462358] ----------------------------------------------------- [ 0.462768] |block | try |context| [ 0.463190] ----------------------------------------------------- [ 0.463598] context: ok | ok | ok | [ 0.468562] try: ok | ok | ok | [ 0.473330] block: ok | ok | ok | [ 0.478265] spinlock: ok | ok | ok | [ 0.483670] ------------------------------------------------------- [ 0.484116] Good, all 261 testcases passed! | [ 0.484493] --------------------------------- [ 0.538806] ODEBUG: selftest passed [ 0.543188] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.543996] pid_max: default: 32768 minimum: 301 [ 0.554316] LSM: Security Framework initializing [ 0.555106] Yama: becoming mindful. [ 0.557329] LoadPin: ready to pin (currently enforcing) [ 0.558115] SELinux: Initializing. [ 0.565408] *** VALIDATE SELinux *** [ 0.566260] AppArmor: AppArmor disabled by boot time parameter [ 0.597544] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.609881] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.612547] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.613964] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.648175] *** VALIDATE proc *** [ 0.667023] *** VALIDATE cgroup1 *** [ 0.667508] *** VALIDATE cgroup2 *** [ 0.731853] ASID allocator initialised with 32768 entries [ 0.741369] rcu: Hierarchical SRCU implementation. [ 0.815077] EFI services will not be available. [ 0.839949] smp: Bringing up secondary CPUs ... [ 0.841010] smp: Brought up 1 node, 1 CPU [ 0.841420] SMP: Total of 1 processors activated. [ 0.841958] CPU features: detected: 32-bit EL0 Support [ 0.842455] CPU features: detected: CRC32 instructions [ 1.091000] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.091832] CPU: All CPU(s) started at EL1 [ 1.092688] alternatives: patching kernel code [ 4.899554] node 0 initialised, 248300 pages in 4ms [ 4.907123] pgdatinit0 (15) used greatest stack depth: 13280 bytes left [ 4.979721] devtmpfs: initialized [ 5.156403] gcov: version magic: 0x4137332a [ 5.162252] _warn_unseeded_randomness: 103 callbacks suppressed [ 5.162679] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=0 [ 5.165962] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 5.166769] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 5.181218] Registered cp15_barrier emulation handler [ 5.181842] Registered setend emulation handler [ 5.816439] workqueue: round-robin CPU selection forced, expect performance impact [ 9.866759] DMA-API: preallocated 65536 debug entries [ 9.867277] DMA-API: debugging enabled by kernel config [ 9.869025] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 9.871202] futex hash table entries: 256 (order: 3, 32768 bytes) [ 9.876748] Running postponed tracer tests: [ 9.881756] Testing tracer function: [ 9.918772] ftrace_replace_code: 1 [ 18.084971] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 18.335850] ftrace_replace_code: 1 [ 25.232459] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 25.236244] PASSED [ 25.236604] Testing dynamic ftrace: [ 26.971963] ftrace_replace_code: 1 [ 26.986902] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 27.224683] PASSED [ 27.225201] Testing dynamic ftrace ops #1: [ 34.252422] ftrace_replace_code: 1 [ 34.268029] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 34.272268] (1 0 1 0 0) [ 34.272796] (1 1 2 0 0) [ 34.285939] ftrace_replace_code: 1 [ 45.164334] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 45.167748] (2 1 3 0 1020180) [ 45.168014] (2 2 4 0 1020201) [ 45.181369] ftrace_replace_code: 1 [ 53.066665] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 53.080708] ftrace_replace_code: 1 [ 53.093968] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 53.098182] ftrace_replace_code: 1 [ 53.111434] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 53.115036] PASSED [ 53.115402] Testing dynamic ftrace ops #2: [ 60.033864] ftrace_replace_code: 1 [ 60.052694] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 60.056558] ftrace_replace_code: 1 [ 60.070089] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 60.089776] ftrace_replace_code: 1 [ 70.336014] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 70.339142] (1 0 1 1004628 0) [ 70.339387] (1 1 2 1004649 0) [ 70.355595] (2 1 3 1 753) [ 70.356653] (2 2 4 136 888) [ 70.394815] ftrace_replace_code: 1 [ 77.288976] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 77.290838] PASSED [ 77.291245] Testing ftrace recursion: [ 79.020312] ftrace_replace_code: 1 [ 79.034772] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 79.038762] ftrace_replace_code: 1 [ 79.052037] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 79.053613] PASSED [ 79.054354] Testing ftrace recursion safe: [ 80.784405] ftrace_replace_code: 1 [ 80.799241] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 80.803254] ftrace_replace_code: 1 [ 80.816438] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 80.818064] PASSED [ 80.818457] Testing ftrace regs(no arch support): [ 82.548778] ftrace_replace_code: 1 [ 82.563900] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 82.567896] ftrace_replace_code: 1 [ 82.581503] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 82.583280] PASSED [ 82.583919] Testing tracer nop: PASSED [ 82.586004] Testing tracer irqsoff: [ 82.602788] ftrace_replace_code: 1 [ 90.614293] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 90.645678] ftrace_replace_code: 1 [ 97.629405] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 97.632061] PASSED [ 97.634349] Testing tracer wakeup: [ 97.664515] ftrace_replace_code: 1 [ 105.624167] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 105.662674] ftrace_replace_code: 1 [ 106.206003] sched: DL replenish lagged too much [ 112.580310] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 112.589423] PASSED [ 112.590232] Testing tracer wakeup_rt: [ 112.616152] ftrace_replace_code: 1 [ 120.574127] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 120.603460] ftrace_replace_code: 1 [ 127.520974] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 127.529203] PASSED [ 127.529929] Testing tracer wakeup_dl: [ 127.555020] ftrace_replace_code: 1 [ 135.642133] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 135.678886] ftrace_replace_code: 1 [ 142.587398] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 142.595685] PASSED [ 142.597098] Testing tracer function_graph: [ 142.597230] ../kernel/trace/trace_selftest.c:766 [ 142.597918] ../kernel/trace/trace_selftest.c:768 [ 142.598907] ../kernel/trace/trace_selftest.c:770 [ 142.622322] ftrace_replace_code: 1 [ 150.374241] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 150.443624] ../kernel/trace/trace_selftest.c:773 [ 150.522027] ../kernel/trace/trace_selftest.c:778 [ 151.070571] ../kernel/trace/trace_selftest.c:780 [ 188.920692] watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [kworker/0:1:13] [ 188.937923] Modules linked in: [ 188.953139] irq event stamp: 7794 [ 188.969264] hardirqs last enabled at (7793): [] _raw_spin_unlock_irq+0x38/0x78 [ 188.991561] hardirqs last disabled at (7794): [] el1_irq+0xa8/0x1c0 [ 189.013734] softirqs last enabled at (258): [] __do_softirq+0x9a8/0x9f0 [ 189.035940] softirqs last disabled at (251): [] irq_exit+0x128/0x1b8 [ 189.055290] CPU: 0 PID: 13 Comm: kworker/0:1 Tainted: G T 4.20.0-rc4-next-20181128-00009-g44c557177b8d-dirty #16 [ 189.078276] Hardware name: linux,dummy-virt (DT) [ 189.098455] Workqueue: (null) (events) [ 189.117540] pstate: 20400005 (nzCv daif +PAN -UAO) [ 189.135800] pc : _raw_spin_unlock_irq+0x3c/0x78 [ 189.153502] lr : _raw_spin_unlock_irq+0x38/0x78 [ 189.168065] sp : ffffff800b8d3cd0 [ 189.180726] x29: ffffff800b8d3cd0 x28: 0000000000000003 [ 189.200379] x27: ffffffc03f624040 x26: ffffff8009c15df8 [ 189.219776] x25: ffffff8009d12fb0 x24: 0000000000000000 [ 189.239081] x23: ffffff8008fb5318 x22: ffffffc03f624040 [ 189.258232] x21: ffffffc03f5dc040 x20: ffffff8008254668 [ 189.277132] x19: ffffffc07ebdf6c0 x18: 0000000034f4d91d [ 189.296274] x17: 0000000000000000 x16: 0000000000000000 [ 189.315897] x15: ffffff8009c6a580 x14: ffffff800ac4e690 [ 189.335076] x13: ffffff800ac4fe90 x12: ffffff800ac4fe90 [ 189.354206] x11: ffffff800ac4ff98 x10: ffffff800ac4ff98 [ 189.373180] x9 : 0000000000000f18 x8 : 000000000019fd6e [ 189.392635] x7 : 0000000000000ee8 x6 : 0000004075191000 [ 189.411992] x5 : ffffff8009cb82b0 x4 : ffffff8009c15bb0 [ 189.431195] x3 : 00000000000d4258 x2 : 00000000000d4258 [ 189.450109] x1 : 0000000000000001 x0 : 00000000000d4258 [ 189.470091] Call trace: [ 189.485486] _raw_spin_unlock_irq+0x3c/0x78 [ 189.502839] finish_task_switch+0x160/0x220 [ 189.520397] __schedule+0xa78/0xaf8 [ 189.536347] schedule+0x9c/0xe0 [ 189.552092] worker_thread+0x240/0x2a0 [ 189.568778] kthread+0x154/0x170 [ 189.584967] ret_from_fork+0x10/0x18 [ 189.779580] ../kernel/trace/trace_selftest.c:792 [ 189.844323] ../kernel/trace/trace_selftest.c:794 [ 189.849176] ../kernel/trace/trace_selftest.c:797 [ 189.865856] ../kernel/trace/trace_selftest.c:799 [ 189.892851] ftrace_replace_code: 1 [ 198.730272] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 198.732779] ../kernel/trace/trace_selftest.c:802 [ 198.733239] ../kernel/trace/trace_selftest.c:804 [ 198.734292] PASSED [ 198.809179] _warn_unseeded_randomness: 2 callbacks suppressed [ 198.809416] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 198.810529] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 198.812841] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 198.828196] prandom: seed boundary self test passed [ 198.863914] prandom: 100 self tests passed [ 198.869235] pinctrl core: initialized pinctrl subsystem [ 198.951343] regulator-dummy: Failed to create debugfs directory [ 198.958375] [ 198.958706] ************************************************************* [ 198.959158] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 198.959591] ** ** [ 198.960010] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 198.960408] ** ** [ 198.960822] ** This means that this kernel is built to expose internal ** [ 198.961239] ** IOMMU data structures, which may compromise security on ** [ 198.961659] ** your system. ** [ 198.962458] ** ** [ 198.962888] ** If you see this message and you are not debugging the ** [ 198.963305] ** kernel, report this immediately to your vendor! ** [ 198.963726] ** ** [ 198.964126] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 198.964536] ************************************************************* [ 198.988886] DMI not present or invalid. [ 199.030495] NET: Registered protocol family 16 [ 199.054940] audit: initializing netlink subsys (disabled) [ 199.168703] cpuidle: using governor ladder [ 199.170447] cpuidle: using governor menu [ 199.186133] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 199.188042] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 199.190958] audit: type=2000 audit(144.852:1): state=initialized audit_enabled=0 res=1 [ 199.287058] DMA: preallocated 256 KiB pool for atomic allocations [ 199.358955] Serial: AMBA PL011 UART driver [ 199.902950] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 199.905736] printk: console [ttyAMA0] enabled [ 199.905736] printk: console [ttyAMA0] enabled [ 199.906711] printk: bootconsole [pl11] disabled [ 199.906711] printk: bootconsole [pl11] disabled [ 199.988039] _warn_unseeded_randomness: 42 callbacks suppressed [ 199.988260] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 199.989603] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 200.363179] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 200.409109] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 200.515076] kobject: 'renesas,r9a06g032-sysctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 200.523227] kobject: 'renesas-cpg-mssr' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 200.566031] arm-v7s io-pgtable: self test ok [ 200.567491] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 200.575147] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 200.580006] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 200.585428] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 200.591949] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 200.597283] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 200.602737] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 200.611542] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 200.621680] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 200.631855] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 200.641929] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 200.652772] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 200.665498] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 200.692730] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 200.720511] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 200.748828] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 200.781558] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 200.815048] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 200.847624] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 200.872629] vgaarb: loaded [ 200.907008] pps_core: LinuxPPS API ver. 1 registered [ 200.907617] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 200.911129] PTP clock support registered [ 200.917229] kobject: 'brcmstb-reboot' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 201.041158] NetLabel: Initializing [ 201.041634] NetLabel: domain hash size = 128 [ 201.042120] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 201.048909] NetLabel: unlabeled traffic allowed by default [ 201.066036] clocksource: Switched to clocksource arch_sys_counter [ 204.940402] _warn_unseeded_randomness: 41 callbacks suppressed [ 204.940626] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 204.942656] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 204.944911] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 204.957611] VFS: Disk quotas dquot_6.6.0 [ 204.960779] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 204.982429] *** VALIDATE hugetlbfs *** [ 205.638898] NET: Registered protocol family 2 [ 205.673966] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 205.678474] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 205.698083] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 205.709703] TCP: Hash tables configured (established 16384 bind 16384) [ 205.716719] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 205.722183] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 205.738875] NET: Registered protocol family 1 [ 205.742861] NET: Registered protocol family 44 [ 205.805813] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 205.814403] kvm [1]: HYP mode not available [ 206.020103] Kprobe smoke test: started [ 207.088846] Kprobe smoke test: passed successfully [ 207.141962] Initialise system trusted keyrings [ 207.143384] _warn_unseeded_randomness: 60 callbacks suppressed [ 207.143592] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 207.148865] Key type blacklist registered [ 207.153852] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 207.163448] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 208.734033] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 208.734941] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 208.753822] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 208.912075] 9p: Installing v9fs 9p2000 file system support [ 208.995248] Allocating IMA blacklist keyring. [ 209.009931] cryptomgr_test (37) used greatest stack depth: 12400 bytes left [ 209.032619] kworker/u2:1 (38) used greatest stack depth: 11936 bytes left [ 209.800097] Key type asymmetric registered [ 209.802092] Asymmetric key parser 'x509' registered [ 209.807214] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 209.819077] _warn_unseeded_randomness: 26 callbacks suppressed [ 209.819308] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 209.820582] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 209.846818] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 209.975947] kobject: 'brcm-gisb-arb' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 210.049587] kobject: 'armada-37xx-pinctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 210.188940] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 210.193988] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 210.212934] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 210.232520] kobject: 'stmpe-pwm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 210.253951] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 210.311699] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 210.314123] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 210.315959] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 210.317034] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 210.320247] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 210.334833] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 210.335839] pci_bus 0000:00: root bus resource [bus 00-0f] [ 210.336596] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 210.337811] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 210.338526] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 210.342304] pci 0000:00:00.0: calling quirk_mmio_always_on+0x0/0x38 @ 1 [ 210.343143] pci 0000:00:00.0: quirk_mmio_always_on+0x0/0x38 took 66 usecs [ 210.414455] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 210.415553] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 210.416661] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 210.418172] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 210.419074] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 210.419852] pci 0000:00:03.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 210.420609] pci 0000:00:03.0: BAR 0: assigned [io 0x1000-0x103f] [ 210.421846] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 210.422572] pci 0000:00:02.0: BAR 0: assigned [io 0x1060-0x107f] [ 210.424323] pci 0000:00:00.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 210.425683] pci 0000:00:00.0: tegra_pcie_relax_enable+0x0/0x40 took 595 usecs [ 210.427644] pci 0000:00:01.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 210.428420] pci 0000:00:01.0: tegra_pcie_relax_enable+0x0/0x40 took 31 usecs [ 210.430120] pci 0000:00:02.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 210.430898] pci 0000:00:02.0: tegra_pcie_relax_enable+0x0/0x40 took 30 usecs [ 210.432118] pci 0000:00:03.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 210.432889] pci 0000:00:03.0: tegra_pcie_relax_enable+0x0/0x40 took 29 usecs [ 210.470720] kobject: 'layerscape-pcie' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 210.860260] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 210.884924] _warn_unseeded_randomness: 17 callbacks suppressed [ 210.886217] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 210.886890] kobject: 'virtio0' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 210.900624] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 210.912192] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 210.930541] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 210.931317] kobject: 'virtio1' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 210.956234] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 210.974218] kobject: 'virtio2' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 215.068323] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 215.175462] _warn_unseeded_randomness: 3 callbacks suppressed [ 215.175701] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 215.176927] kobject: 'ttyS0' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 215.178344] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 215.179912] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 215.180574] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 215.224726] kobject: 'ttyS1' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 215.226367] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 215.267095] kobject: 'ttyS2' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 215.268142] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 215.311107] kobject: 'ttyS3' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 215.312262] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 215.382570] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 215.392662] KGDB: Registered I/O driver kgdbts [ 215.394171] kgdbts:RUN plant and detach test Entering kdb (current=0xffffffc03f558040, pid 1) on processor 0 due to Keyboard Entry [0]kdb> [ 215.403381] kgdbts:RUN sw breakpoint test [ 215.419210] kgdbts:RUN bad memory access test [ 215.422460] kgdbts:RUN singlestep test 1000 iterations [ 215.453525] kgdbts:RUN singlestep [0/1000] [ 218.330420] kgdbts:RUN singlestep [100/1000] [ 221.204374] kgdbts:RUN singlestep [200/1000] [ 224.081473] kgdbts:RUN singlestep [300/1000] [ 226.990442] kgdbts:RUN singlestep [400/1000] [ 229.978415] kgdbts:RUN singlestep [500/1000] [ 232.853888] kgdbts:RUN singlestep [600/1000] [ 235.733646] kgdbts:RUN singlestep [700/1000] [ 238.634888] kgdbts:RUN singlestep [800/1000] [ 241.510484] kgdbts:RUN singlestep [900/1000] [ 244.359631] kgdbts:RUN do_fork for 100 breakpoints [ 244.365848] _warn_unseeded_randomness: 7 callbacks suppressed [ 244.366108] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 246.146349] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 246.147255] kobject: 'rx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 246.150723] kobject: 'tx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 246.163284] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 246.184696] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 246.186198] kobject: 'net' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 247.159600] KGDB: Unregistered I/O driver kgdbts, debugger disabled [ 248.187905] kobject: 'queues' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 248.249915] kobject: 'eth0' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 248.262812] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 248.396572] ledtrig-cpu: registered to indicate activity on CPUs [ 248.398211] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 248.398430] kobject: '44' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 248.399551] kobject: '45' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 248.407264] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 248.415062] kobject: 'meson-sm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 248.426416] kobject: '46' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 248.478638] ashmem: initialized [ 248.577999] kworker/u2:1 (118) used greatest stack depth: 11824 bytes left [ 248.644043] 9pnet: Installing 9P2000 support [ 248.965759] kobject: '47' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 248.967609] kobject: '48' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 249.006919] registered taskstats version 1 [ 249.008169] Running ring buffer tests... [ 259.130856] finished [ 259.131525] CPU 0: [ 259.132018] events: 7546 [ 259.132512] dropped bytes: 0 [ 259.133009] alloced bytes: 588240 [ 259.134137] written bytes: 576252 [ 259.134668] biggest event: 89 [ 259.135184] smallest event: 0 [ 259.387359] read events: 7546 [ 259.387948] lost events: 0 [ 259.388446] total events: 7546 [ 259.388913] recorded len bytes: 588240 [ 259.389912] recorded size bytes: 576252 [ 259.390457] Ring buffer PASSED! [ 259.428689] Running tests on trace events: [ 259.430005] Testing event initcall_finish: OK [ 259.459631] Testing event initcall_start: OK [ 259.486953] Testing event initcall_level: OK [ 259.514961] Testing event sys_exit: OK [ 259.540616] Testing event sys_enter: OK [ 259.567817] Testing event ipi_exit: OK [ 259.595146] Testing event ipi_entry: OK [ 259.622017] Testing event ipi_raise: OK [ 259.647802] Testing event instruction_emulation: OK [ 259.675707] Testing event kvm_halt_poll_ns: OK [ 259.702688] Testing event kvm_age_page: OK [ 259.726803] Testing event kvm_fpu: OK [ 259.751628] Testing event kvm_mmio: OK [ 259.779630] Testing event kvm_ack_irq: OK [ 259.806579] Testing event kvm_set_irq: OK [ 259.831019] Testing event kvm_vcpu_wakeup: OK [ 259.858763] Testing event kvm_userspace_exit: OK [ 259.884587] Testing event kvm_timer_update_irq: OK [ 259.911828] Testing event kvm_toggle_cache: OK [ 259.938770] Testing event kvm_set_way_flush: OK [ 259.964419] Testing event kvm_test_age_hva: OK [ 259.991722] Testing event kvm_age_hva: OK [ 260.018541] Testing event kvm_set_spte_hva: OK [ 260.042978] Testing event kvm_unmap_hva_range: OK [ 260.070602] Testing event kvm_mmio_emulate: OK [ 260.094863] Testing event kvm_irq_line: OK [ 260.120791] Testing event kvm_access_fault: OK [ 260.147069] Testing event kvm_guest_fault: OK [ 260.175072] Testing event kvm_exit: OK [ 260.202635] Testing event kvm_entry: OK [ 260.227519] Testing event kvm_set_guest_debug: OK [ 260.254760] Testing event kvm_handle_sys_reg: OK [ 260.280398] Testing event trap_reg: OK [ 260.310448] Testing event kvm_arm_set_regset: OK [ 260.338388] Testing event kvm_arm_set_dreg32: OK [ 260.366254] Testing event kvm_arm_clear_debug: OK [ 260.391378] Testing event kvm_arm_setup_debug: OK [ 260.418614] Testing event kvm_hvc_arm64: OK [ 260.443570] Testing event kvm_wfx_arm64: OK [ 260.471011] Testing event vgic_update_irq_pending: OK [ 260.496718] Testing event task_rename: OK [ 260.525965] Testing event task_newtask: OK [ 260.551416] Testing event cpuhp_exit: OK [ 260.579057] Testing event cpuhp_multi_enter: OK [ 260.604837] Testing event cpuhp_enter: OK [ 260.631829] Testing event softirq_raise: OK [ 260.659371] Testing event softirq_exit: OK [ 260.687083] Testing event softirq_entry: OK [ 260.712537] Testing event irq_handler_exit: OK [ 260.742264] Testing event irq_handler_entry: OK [ 260.770136] Testing event signal_deliver: OK [ 260.795485] Testing event signal_generate: OK [ 260.822628] Testing event workqueue_execute_end: OK [ 260.850191] Testing event workqueue_execute_start: OK [ 260.875760] Testing event workqueue_activate_work: OK [ 260.902876] Testing event workqueue_queue_work: OK [ 260.931057] Testing event sched_wake_idle_without_ipi: OK [ 260.958400] Testing event sched_swap_numa: OK [ 260.983671] Testing event sched_stick_numa: OK [ 261.011338] Testing event sched_move_numa: OK [ 261.038626] Testing event sched_process_hang: OK [ 261.064551] Testing event sched_pi_setprio: OK [ 261.091769] Testing event sched_stat_runtime: [ 261.094364] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 261.123769] OK [ 261.124248] Testing event sched_stat_blocked: OK [ 261.151101] Testing event sched_stat_iowait: OK [ 261.178148] Testing event sched_stat_sleep: OK [ 261.203762] Testing event sched_stat_wait: OK [ 261.231258] Testing event sched_process_exec: OK [ 261.258073] Testing event sched_process_fork: OK [ 261.283804] Testing event sched_process_wait: OK [ 261.310956] Testing event sched_wait_task: OK [ 261.339202] Testing event sched_process_exit: OK [ 261.366825] Testing event sched_process_free: OK [ 261.392586] Testing event sched_migrate_task: OK [ 261.419731] Testing event sched_switch: OK [ 261.447323] Testing event sched_wakeup_new: OK [ 261.472813] Testing event sched_wakeup: OK [ 261.499742] Testing event sched_waking: OK [ 261.527491] Testing event sched_kthread_stop_ret: OK [ 261.554753] Testing event sched_kthread_stop: OK [ 261.579618] Testing event lock_acquired: OK [ 261.624442] Testing event lock_contended: OK [ 261.650931] Testing event lock_release: OK [ 261.706030] Testing event lock_acquire: OK [ 261.755822] Testing event console: OK [ 261.781014] Testing event rcu_barrier: OK [ 261.807825] Testing event rcu_torture_read: OK [ 261.835286] Testing event rcu_batch_end: OK [ 261.860444] Testing event rcu_invoke_kfree_callback: OK [ 261.887735] Testing event rcu_invoke_callback: OK [ 261.915171] Testing event rcu_batch_start: OK [ 261.943010] Testing event rcu_kfree_callback: OK [ 261.971149] Testing event rcu_callback: OK [ 261.999214] Testing event rcu_dyntick: OK [ 262.027481] Testing event rcu_fqs: OK [ 262.054561] Testing event rcu_quiescent_state_report: OK [ 262.083106] Testing event rcu_unlock_preempted_task: OK [ 262.110557] Testing event rcu_preempt_task: OK [ 262.134857] Testing event rcu_nocb_wake: OK [ 262.163993] Testing event rcu_exp_funnel_lock: OK [ 262.190142] Testing event rcu_exp_grace_period: OK [ 262.218651] Testing event rcu_grace_period_init: OK [ 262.246147] Testing event rcu_future_grace_period: OK [ 262.271944] Testing event rcu_grace_period: OK [ 262.306066] Testing event rcu_utilization: OK [ 262.336004] Testing event swiotlb_bounced: OK [ 262.362344] Testing event tick_stop: OK [ 262.386647] Testing event itimer_expire: OK [ 262.410740] Testing event itimer_state: OK [ 262.434628] Testing event hrtimer_cancel: OK [ 262.459678] Testing event hrtimer_expire_exit: OK [ 262.486252] Testing event hrtimer_expire_entry: OK [ 262.511559] Testing event hrtimer_start: OK [ 262.538238] Testing event hrtimer_init: OK [ 262.562886] Testing event timer_cancel: OK [ 262.587573] Testing event timer_expire_exit: OK [ 262.612764] Testing event timer_expire_entry: OK [ 262.635823] Testing event timer_start: OK [ 262.662498] Testing event timer_init: OK [ 262.686610] Testing event alarmtimer_cancel: OK [ 262.713861] Testing event alarmtimer_start: OK [ 262.739532] Testing event alarmtimer_fired: OK [ 262.767630] Testing event alarmtimer_suspend: OK [ 262.792137] Testing event module_request: OK [ 262.821943] Testing event module_put: OK [ 262.846556] Testing event module_get: OK [ 262.873966] Testing event module_free: OK [ 262.899700] Testing event module_load: OK [ 262.930197] Testing event cgroup_transfer_tasks: OK [ 262.955592] Testing event cgroup_attach_task: OK [ 262.983737] Testing event cgroup_rename: OK [ 263.010474] Testing event cgroup_release: OK [ 263.036361] Testing event cgroup_rmdir: OK [ 263.063304] Testing event cgroup_mkdir: OK [ 263.091202] Testing event cgroup_remount: OK [ 263.115002] Testing event cgroup_destroy_root: OK [ 263.142319] Testing event cgroup_setup_root: OK [ 263.170345] Testing event irq_enable: OK [ 263.215245] Testing event irq_disable: OK [ 263.255048] Testing event ftrace_test_filter: OK [ 263.278660] Testing event dev_pm_qos_remove_request: OK [ 263.308282] Testing event dev_pm_qos_update_request: OK [ 263.332513] Testing event dev_pm_qos_add_request: OK [ 263.359986] Testing event pm_qos_update_flags: OK [ 263.387640] Testing event pm_qos_update_target: OK [ 263.412132] Testing event pm_qos_update_request_timeout: OK [ 263.441939] Testing event pm_qos_remove_request: OK [ 263.466538] Testing event pm_qos_update_request: OK [ 263.491815] Testing event pm_qos_add_request: OK [ 263.520489] Testing event power_domain_target: OK [ 263.547525] Testing event clock_set_rate: OK [ 263.574589] Testing event clock_disable: OK [ 263.598396] Testing event clock_enable: OK [ 263.623052] Testing event wakeup_source_deactivate: OK [ 263.650411] Testing event wakeup_source_activate: OK [ 263.676667] Testing event suspend_resume: OK [ 263.715214] Testing event device_pm_callback_end: OK [ 263.739039] Testing event device_pm_callback_start: OK [ 263.765890] Testing event cpu_frequency_limits: OK [ 263.791178] Testing event cpu_frequency: OK [ 263.815558] Testing event pstate_sample: OK [ 263.840808] Testing event powernv_throttle: OK [ 263.866851] Testing event cpu_idle: OK [ 263.893931] Testing event rpm_return_int: OK [ 263.918973] Testing event rpm_idle: OK [ 263.946580] Testing event rpm_resume: OK [ 263.975537] Testing event rpm_suspend: OK [ 263.999441] Testing event benchmark_event: OK [ 264.124211] Testing event xdp_devmap_xmit: OK [ 264.150705] Testing event xdp_cpumap_enqueue: OK [ 264.174646] Testing event xdp_cpumap_kthread: OK [ 264.198538] Testing event xdp_redirect_map_err: OK [ 264.225896] Testing event xdp_redirect_map: OK [ 264.251644] Testing event xdp_redirect_err: OK [ 264.282430] Testing event xdp_redirect: OK [ 264.308036] Testing event xdp_exception: OK [ 264.338463] Testing event rseq_ip_fixup: OK [ 264.363793] Testing event rseq_update: OK [ 264.391080] Testing event file_check_and_advance_wb_err: OK [ 264.416150] Testing event filemap_set_wb_err: OK [ 264.443946] Testing event mm_filemap_add_to_page_cache: OK [ 264.471837] Testing event mm_filemap_delete_from_page_cache: OK [ 264.499862] Testing event compact_retry: OK [ 264.526760] Testing event skip_task_reaping: OK [ 264.551263] Testing event finish_task_reaping: OK [ 264.578640] Testing event start_task_reaping: OK [ 264.604209] Testing event wake_reaper: OK [ 264.632745] Testing event mark_victim: OK [ 264.659413] Testing event reclaim_retry_zone: OK [ 264.683457] Testing event oom_score_adj_update: OK [ 264.707427] Testing event mm_lru_activate: OK [ 264.732825] Testing event mm_lru_insertion: OK [ 264.755853] Testing event mm_vmscan_inactive_list_is_low: OK [ 264.782724] Testing event mm_vmscan_lru_shrink_active: OK [ 264.806585] Testing event mm_vmscan_lru_shrink_inactive: OK [ 264.830150] Testing event mm_vmscan_writepage: OK [ 264.855670] Testing event mm_vmscan_lru_isolate: OK [ 264.880667] Testing event mm_shrink_slab_end: OK [ 264.903969] Testing event mm_shrink_slab_start: OK [ 264.931107] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 264.956267] Testing event mm_vmscan_memcg_reclaim_end: OK [ 264.983895] Testing event mm_vmscan_direct_reclaim_end: OK [ 265.011886] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 265.040097] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 265.066806] Testing event mm_vmscan_direct_reclaim_begin: OK [ 265.090564] Testing event mm_vmscan_wakeup_kswapd: OK [ 265.115692] Testing event mm_vmscan_kswapd_wake: OK [ 265.140760] Testing event mm_vmscan_kswapd_sleep: OK [ 265.163694] Testing event percpu_destroy_chunk: OK [ 265.190936] Testing event percpu_create_chunk: OK [ 265.216663] Testing event percpu_alloc_percpu_fail: OK [ 265.247015] Testing event percpu_free_percpu: OK [ 265.270787] Testing event percpu_alloc_percpu: OK [ 265.294525] Testing event mm_page_alloc_extfrag: OK [ 265.322046] Testing event mm_page_pcpu_drain: OK [ 265.347685] Testing event mm_page_alloc_zone_locked: OK [ 265.375207] Testing event mm_page_alloc: OK [ 265.400173] Testing event mm_page_free_batched: OK [ 265.426779] Testing event mm_page_free: OK [ 265.456409] Testing event kmem_cache_free: OK [ 265.480426] Testing event kfree: OK [ 265.507720] Testing event kmem_cache_alloc_node: OK [ 265.535358] Testing event kmalloc_node: OK [ 265.562605] Testing event kmem_cache_alloc: OK [ 265.588284] Testing event kmalloc: OK [ 265.616890] Testing event mm_compaction_kcompactd_wake: OK [ 265.639755] Testing event mm_compaction_wakeup_kcompactd: OK [ 265.664227] Testing event mm_compaction_kcompactd_sleep: OK [ 265.690097] Testing event mm_compaction_defer_reset: OK [ 265.715716] Testing event mm_compaction_defer_compaction: OK [ 265.740826] Testing event mm_compaction_deferred: OK [ 265.763494] Testing event mm_compaction_suitable: OK [ 265.790213] Testing event mm_compaction_finished: OK [ 265.815642] Testing event mm_compaction_try_to_compact_pages: OK [ 265.843238] Testing event mm_compaction_end: OK [ 265.868238] Testing event mm_compaction_begin: OK [ 265.895358] Testing event mm_compaction_migratepages: OK [ 265.923393] Testing event mm_compaction_isolate_freepages: OK [ 265.950588] Testing event mm_compaction_isolate_migratepages: OK [ 265.975609] Testing event mm_migrate_pages: OK [ 266.003600] Testing event mm_collapse_huge_page_swapin: OK [ 266.028836] Testing event mm_collapse_huge_page_isolate: OK [ 266.054382] Testing event mm_collapse_huge_page: OK [ 266.078557] Testing event mm_khugepaged_scan_pmd: OK [ 266.103423] Testing event test_pages_isolated: OK [ 266.136039] Testing event cma_release: OK [ 266.160122] Testing event cma_alloc: OK [ 266.187490] Testing event page_ref_unfreeze: OK [ 266.217809] Testing event page_ref_freeze: OK [ 266.247051] Testing event page_ref_mod_unless: OK [ 266.279787] Testing event page_ref_mod_and_return: OK [ 266.306577] Testing event page_ref_mod_and_test: OK [ 266.434895] Testing event page_ref_mod: OK [ 266.490194] Testing event page_ref_set: OK [ 266.519343] Testing event sb_clear_inode_writeback: OK [ 266.547317] Testing event sb_mark_inode_writeback: OK [ 266.574918] Testing event writeback_dirty_inode_enqueue: OK [ 266.598991] Testing event writeback_lazytime_iput: OK [ 266.623312] Testing event writeback_lazytime: OK [ 266.652319] Testing event writeback_single_inode: OK [ 266.678318] Testing event writeback_single_inode_start: OK [ 266.702627] Testing event writeback_wait_iff_congested: OK [ 266.727639] Testing event writeback_congestion_wait: OK [ 266.759250] Testing event writeback_sb_inodes_requeue: OK [ 266.787273] Testing event balance_dirty_pages: OK [ 266.814050] Testing event bdi_dirty_ratelimit: OK [ 266.836137] Testing event global_dirty_state: OK [ 266.866358] Testing event writeback_queue_io: OK [ 266.894714] Testing event wbc_writepage: OK [ 266.919471] Testing event writeback_bdi_register: OK [ 266.946711] Testing event writeback_wake_background: OK [ 266.975391] Testing event writeback_pages_written: OK [ 267.003518] Testing event writeback_wait: OK [ 267.028479] Testing event writeback_written: OK [ 267.056195] Testing event writeback_start: OK [ 267.082770] Testing event writeback_exec: OK [ 267.106531] Testing event writeback_queue: OK [ 267.132634] Testing event writeback_write_inode: OK [ 267.162141] Testing event writeback_write_inode_start: OK [ 267.187874] Testing event writeback_dirty_inode: OK [ 267.215740] Testing event writeback_dirty_inode_start: OK [ 267.243164] Testing event writeback_mark_inode_dirty: OK [ 267.268243] Testing event writeback_dirty_page: OK [ 267.292830] Testing event dax_writeback_one: OK [ 267.318773] Testing event dax_writeback_range_done: OK [ 267.343694] Testing event dax_writeback_range: OK [ 267.371405] Testing event dax_insert_mapping: OK [ 267.398574] Testing event dax_insert_pfn_mkwrite: OK [ 267.424044] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 267.451577] Testing event dax_load_hole: OK [ 267.478679] Testing event dax_pte_fault_done: OK [ 267.503011] Testing event dax_pte_fault: OK [ 267.527410] Testing event dax_pmd_insert_mapping: OK [ 267.555455] Testing event dax_pmd_load_hole_fallback: OK [ 267.580944] Testing event dax_pmd_load_hole: OK [ 267.606601] Testing event dax_pmd_fault_done: OK [ 267.630840] Testing event dax_pmd_fault: OK [ 267.655614] Testing event generic_add_lease: OK [ 267.683371] Testing event time_out_leases: OK [ 267.708627] Testing event generic_delete_lease: OK [ 267.731666] Testing event break_lease_unblock: OK [ 267.759958] Testing event break_lease_block: OK [ 267.784337] Testing event break_lease_noblock: OK [ 267.808752] Testing event flock_lock_inode: OK [ 267.835042] Testing event locks_remove_posix: OK [ 267.860197] Testing event fcntl_setlk: OK [ 267.887289] Testing event posix_lock_inode: OK [ 267.911454] Testing event locks_get_lock_context: OK [ 267.935419] Testing event block_rq_remap: OK [ 267.959411] Testing event block_bio_remap: OK [ 267.984767] Testing event block_split: OK [ 268.011916] Testing event block_unplug: OK [ 268.042138] Testing event block_plug: OK [ 268.067622] Testing event block_sleeprq: OK [ 268.094708] Testing event block_getrq: OK [ 268.118871] Testing event block_bio_queue: OK [ 268.143660] Testing event block_bio_frontmerge: OK [ 268.168806] Testing event block_bio_backmerge: OK [ 268.191927] Testing event block_bio_complete: OK [ 268.218621] Testing event block_bio_bounce: OK [ 268.243923] Testing event block_rq_issue: OK [ 268.271076] Testing event block_rq_insert: OK [ 268.296924] Testing event block_rq_complete: OK [ 268.322748] Testing event block_rq_requeue: OK [ 268.350813] Testing event block_dirty_buffer: OK [ 268.379532] Testing event block_touch_buffer: OK [ 268.404797] Testing event wbt_timer: OK [ 268.431632] Testing event wbt_step: OK [ 268.460397] Testing event wbt_lat: OK [ 268.486223] Testing event wbt_stat: OK [ 268.510883] Testing event gpio_value: OK [ 268.540471] Testing event gpio_direction: OK [ 268.566912] Testing event clk_set_duty_cycle_complete: OK [ 268.592733] Testing event clk_set_duty_cycle: OK [ 268.622016] Testing event clk_set_phase_complete: OK [ 268.647079] Testing event clk_set_phase: OK [ 268.672440] Testing event clk_set_parent_complete: OK [ 268.699287] Testing event clk_set_parent: OK [ 268.723590] Testing event clk_set_rate_complete: OK [ 268.748809] Testing event clk_set_rate: OK [ 268.771885] Testing event clk_unprepare_complete: OK [ 268.799051] Testing event clk_unprepare: OK [ 268.822907] Testing event clk_prepare_complete: OK [ 268.847664] Testing event clk_prepare: OK [ 268.874901] Testing event clk_disable_complete: OK [ 268.898951] Testing event clk_disable: OK [ 268.923498] Testing event clk_enable_complete: OK [ 268.948785] Testing event clk_enable: OK [ 268.975906] Testing event rpmh_send_msg: OK [ 269.004215] Testing event rpmh_tx_done: OK [ 269.031843] Testing event regulator_set_voltage_complete: OK [ 269.058725] Testing event regulator_set_voltage: OK [ 269.083032] Testing event regulator_disable_complete: OK [ 269.107340] Testing event regulator_disable: OK [ 269.132874] Testing event regulator_enable_complete: OK [ 269.158760] Testing event regulator_enable_delay: OK [ 269.187145] Testing event regulator_enable: OK [ 269.214877] Testing event urandom_read: OK [ 269.240751] Testing event random_read: OK [ 269.266595] Testing event extract_entropy_user: OK [ 269.291843] Testing event extract_entropy: OK [ 269.319917] Testing event get_random_bytes_arch: OK [ 269.351347] Testing event get_random_bytes: OK [ 269.378280] Testing event xfer_secondary_pool: OK [ 269.403416] Testing event add_disk_randomness: OK [ 269.428848] Testing event add_input_randomness: OK [ 269.455992] Testing event debit_entropy: OK [ 269.483767] Testing event push_to_pool: OK [ 269.512195] Testing event credit_entropy_bits: OK [ 269.538268] Testing event mix_pool_bytes_nolock: OK [ 269.564257] Testing event mix_pool_bytes: OK [ 269.592560] Testing event add_device_randomness: OK [ 269.616916] Testing event io_page_fault: OK [ 269.643598] Testing event unmap: OK [ 269.671075] Testing event map: OK [ 269.696104] Testing event detach_device_from_domain: OK [ 269.722878] Testing event attach_device_to_domain: OK [ 269.751487] Testing event remove_device_from_group: OK [ 269.778522] Testing event add_device_to_group: OK [ 269.802849] Testing event regcache_drop_region: OK [ 269.830976] Testing event regmap_async_complete_done: OK [ 269.859249] Testing event regmap_async_complete_start: OK [ 269.884177] Testing event regmap_async_io_complete: OK [ 269.911796] Testing event regmap_async_write_start: OK [ 269.942262] Testing event regmap_cache_bypass: OK [ 269.966803] Testing event regmap_cache_only: OK [ 269.990525] Testing event regcache_sync: OK [ 270.016024] Testing event regmap_hw_write_done: OK [ 270.044194] Testing event regmap_hw_write_start: OK [ 270.072784] Testing event regmap_hw_read_done: OK [ 270.099419] Testing event regmap_hw_read_start: OK [ 270.126209] Testing event regmap_reg_read_cache: OK [ 270.148184] Testing event regmap_reg_read: OK [ 270.178354] Testing event regmap_reg_write: OK [ 270.203336] Testing event dma_fence_wait_end: OK [ 270.231006] Testing event dma_fence_wait_start: OK [ 270.256740] Testing event dma_fence_signaled: OK [ 270.283953] Testing event dma_fence_enable_signal: OK [ 270.310767] Testing event dma_fence_destroy: OK [ 270.339187] Testing event dma_fence_init: OK [ 270.363384] Testing event dma_fence_emit: OK [ 270.387311] Testing event sync_timeline: OK [ 270.413842] Testing event spi_transfer_stop: OK [ 270.439704] Testing event spi_transfer_start: OK [ 270.470098] Testing event spi_message_done: OK [ 270.495845] Testing event spi_message_start: OK [ 270.523233] Testing event spi_message_submit: OK [ 270.548155] Testing event spi_controller_busy: OK [ 270.575942] Testing event spi_controller_idle: OK [ 270.607287] Testing event rtc_timer_fired: OK [ 270.631938] Testing event rtc_timer_dequeue: OK [ 270.662417] Testing event rtc_timer_enqueue: OK [ 270.688627] Testing event rtc_read_offset: OK [ 270.718371] Testing event rtc_set_offset: OK [ 270.744119] Testing event rtc_alarm_irq_enable: OK [ 270.774198] Testing event rtc_irq_set_state: OK [ 270.798703] Testing event rtc_irq_set_freq: OK [ 270.823594] Testing event rtc_read_alarm: OK [ 270.855658] Testing event rtc_set_alarm: OK [ 270.880770] Testing event rtc_read_time: OK [ 270.908912] Testing event rtc_set_time: OK [ 270.934948] Testing event memory_failure_event: OK [ 270.959488] Testing event aer_event: OK [ 270.984832] Testing event non_standard_event: OK [ 271.012004] Testing event arm_event: OK [ 271.041896] Testing event mc_event: OK [ 271.066904] Testing event binder_return: OK [ 271.095793] Testing event binder_command: OK [ 271.120857] Testing event binder_unmap_kernel_end: OK [ 271.147750] Testing event binder_unmap_kernel_start: OK [ 271.179827] Testing event binder_unmap_user_end: OK [ 271.206407] Testing event binder_unmap_user_start: OK [ 271.231574] Testing event binder_alloc_page_end: OK [ 271.256916] Testing event binder_alloc_page_start: OK [ 271.283944] Testing event binder_free_lru_end: OK [ 271.313862] Testing event binder_free_lru_start: OK [ 271.338687] Testing event binder_alloc_lru_end: OK [ 271.364708] Testing event binder_alloc_lru_start: OK [ 271.394093] Testing event binder_update_page_range: OK [ 271.419907] Testing event binder_transaction_failed_buffer_release: OK [ 271.447924] Testing event binder_transaction_buffer_release: OK [ 271.476003] Testing event binder_transaction_alloc_buf: OK [ 271.503084] Testing event binder_transaction_fd_recv: OK [ 271.526946] Testing event binder_transaction_fd_send: OK [ 271.551471] Testing event binder_transaction_ref_to_ref: OK [ 271.576927] Testing event binder_transaction_ref_to_node: OK [ 271.602980] Testing event binder_transaction_node_to_ref: OK [ 271.631252] Testing event binder_transaction_received: OK [ 271.658687] Testing event binder_transaction: OK [ 271.682854] Testing event binder_wait_for_work: OK [ 271.711742] Testing event binder_read_done: OK [ 271.736674] Testing event binder_write_done: OK [ 271.760796] Testing event binder_ioctl_done: OK [ 271.787080] Testing event binder_unlock: OK [ 271.811469] Testing event binder_locked: OK [ 271.839408] Testing event binder_lock: OK [ 271.866913] Testing event binder_ioctl: OK [ 271.891750] Testing event br_fdb_update: OK [ 271.919504] Testing event fdb_delete: OK [ 271.946420] Testing event br_fdb_external_learn_add: OK [ 271.972347] Testing event br_fdb_add: OK [ 271.999973] Testing event qdisc_dequeue: OK [ 272.024457] Testing event fib_table_lookup: OK [ 272.055135] Testing event tcp_probe: OK [ 272.078680] Testing event tcp_retransmit_synack: OK [ 272.103794] Testing event tcp_rcv_space_adjust: OK [ 272.131329] Testing event tcp_destroy_sock: OK [ 272.156476] Testing event tcp_receive_reset: OK [ 272.183698] Testing event tcp_send_reset: OK [ 272.211195] Testing event tcp_retransmit_skb: OK [ 272.239440] Testing event udp_fail_queue_rcv_skb: OK [ 272.267498] Testing event inet_sock_set_state: OK [ 272.292313] Testing event sock_exceed_buf_limit: OK [ 272.319520] Testing event sock_rcvqueue_full: OK [ 272.347206] Testing event napi_poll: OK [ 272.374548] Testing event netif_rx_ni_entry: OK [ 272.398675] Testing event netif_rx_entry: OK [ 272.423600] Testing event netif_receive_skb_list_entry: OK [ 272.456371] Testing event netif_receive_skb_entry: OK [ 272.484187] Testing event napi_gro_receive_entry: OK [ 272.510718] Testing event napi_gro_frags_entry: OK [ 272.535059] Testing event netif_rx: OK [ 272.559474] Testing event netif_receive_skb: OK [ 272.587955] Testing event net_dev_queue: OK [ 272.612698] Testing event net_dev_xmit: OK [ 272.638328] Testing event net_dev_start_xmit: OK [ 272.662929] Testing event skb_copy_datagram_iovec: OK [ 272.691128] Testing event consume_skb: OK [ 272.719225] Testing event kfree_skb: OK [ 272.744331] Testing event 9p_protocol_dump: OK [ 272.775808] Testing event 9p_client_res: OK [ 272.803745] Testing event 9p_client_req: OK [ 272.831044] Running tests on trace event systems: [ 272.831781] Testing event system 9p: OK [ 272.871461] Testing event system skb: OK [ 272.903187] Testing event system net: OK [ 272.938791] Testing event system napi: OK [ 272.966216] Testing event system sock: OK [ 272.997953] Testing event system udp: OK [ 273.028482] Testing event system tcp: OK [ 273.061938] Testing event system fib: OK [ 273.092757] Testing event system qdisc: OK [ 273.119117] Testing event system bridge: OK [ 273.150096] Testing event system binder: OK [ 273.200371] Testing event system ras: OK [ 273.238826] Testing event system rtc: OK [ 273.283393] Testing event system spi: OK [ 273.315178] Testing event system sync_trace: OK [ 273.342491] Testing event system dma_fence: OK [ 273.378816] Testing event system regmap: OK [ 273.415598] Testing event system iommu: OK [ 273.451676] Testing event system random: OK [ 273.520667] Testing event system regulator: OK [ 273.588822] Testing event system rpmh: OK [ 273.652564] Testing event system clk: OK [ 273.701822] Testing event system gpio: OK [ 273.728719] Testing event system wbt: OK [ 273.764709] Testing event system block: OK [ 273.801881] Testing event system filelock: OK [ 273.835960] Testing event system fs_dax: OK [ 273.871715] Testing event system writeback: OK [ 273.923405] Testing event system page_ref: OK [ 274.100324] Testing event system cma: OK [ 274.134105] Testing event system page_isolation: OK [ 274.164251] Testing event system huge_memory: OK [ 274.194939] Testing event system migrate: OK [ 274.224825] Testing event system compaction: OK [ 274.263307] Testing event system kmem: OK [ 274.304174] Testing event system percpu: OK [ 274.338620] Testing event system vmscan: OK [ 274.379011] Testing event system pagemap: OK [ 274.407912] Testing event system oom: OK [ 274.443496] Testing event system filemap: OK [ 274.474855] Testing event system rseq: OK [ 274.504025] Testing event system xdp: OK [ 274.536775] Testing event system benchmark: OK [ 274.666636] Testing event system rpm: OK [ 274.699948] Testing event system power: OK [ 274.754023] Testing event system test: OK [ 274.780287] Testing event system preemptirq: OK [ 274.850487] Testing event system cgroup: OK [ 274.886862] Testing event system module: OK [ 274.918018] Testing event system alarmtimer: OK [ 274.950038] Testing event system timer: OK [ 274.990403] Testing event system swiotlb: OK [ 275.020364] Testing event system rcu: OK [ 275.075875] Testing event system printk: OK [ 275.107397] Testing event system lock: OK [ 275.220341] Testing event system sched: OK [ 275.267880] Testing event system workqueue: OK [ 275.298124] Testing event system signal: OK [ 275.329754] Testing event system irq: OK [ 275.362356] Testing event system cpuhp: OK [ 275.391516] Testing event system task: OK [ 275.422149] Testing event system kvm: OK [ 275.476393] Testing event system emulation: OK [ 275.504205] Testing event system ipi: OK [ 275.538096] Testing event system raw_syscalls: OK [ 275.568336] Testing event system initcall: OK [ 275.598548] Running tests on all trace events: [ 275.599060] Testing all events: OK [ 278.442867] ftrace_replace_code: 1 [ 282.602166] hrtimer: interrupt took 9434256 ns