[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 4.20.0-rc5-next-20181205-00011-g6db3431f9b32-dirty (anders@compilator) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05)) #1 SMP Wed Dec 5 08:57:46 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 0xbebf6600-0xbebf7fff] [ 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+0xe4/0x7d8 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: 970276K/2097152K available (25592K kernel code, 16876K rwdata, 4888K rodata, 6144K init, 23474K bss, 117252K reserved, 16384K cma-reserved) [ 0.000000] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 0.000000] random: get_random_u64 called from cache_random_seq_create+0xd0/0x1a8 with crng_init=0 [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.000000] ODEBUG: selftest passed [ 0.000000] ftrace: allocating 42047 entries in 165 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.000236] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.022584] Console: colour dummy device 80x25 [ 0.028952] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.030172] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.030775] ... MAX_LOCK_DEPTH: 48 [ 0.031366] ... MAX_LOCKDEP_KEYS: 8191 [ 0.031969] ... CLASSHASH_SIZE: 4096 [ 0.032528] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.033095] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.033670] ... CHAINHASH_SIZE: 32768 [ 0.034822] memory used by lock dependency info: 7775 kB [ 0.035448] per task-struct memory footprint: 2688 bytes [ 0.036083] ------------------------ [ 0.036604] | Locking API testsuite: [ 0.037140] ---------------------------------------------------------------------------- [ 0.037866] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.039031] -------------------------------------------------------------------------- [ 0.040220] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.054281] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.068299] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.083597] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.099053] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.116170] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.133460] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.150236] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.162562] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.173751] -------------------------------------------------------------------------- [ 0.174943] recursive read-lock: | ok | | ok | [ 0.179242] recursive read-lock #2: | ok | | ok | [ 0.183523] mixed read-write-lock: | ok | | ok | [ 0.187827] mixed write-read-lock: | ok | | ok | [ 0.192136] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.195762] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.200841] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.205431] -------------------------------------------------------------------------- [ 0.206419] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.212453] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.218486] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.224842] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.230774] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.237390] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.243372] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.249233] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.255850] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.261858] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.268403] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.274770] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.281745] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.287909] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.294699] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.300965] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.307976] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.314044] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.320951] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.326794] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.332909] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.339741] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.345939] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.352848] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.359265] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.366156] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.372548] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.379463] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.385788] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.392531] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.398840] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.405728] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.413467] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.420131] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.426431] hard-irq lock-inversion/123: ok | ok | ok | [ 0.433232] soft-irq lock-inversion/123: ok | ok | ok | [ 0.439862] hard-irq lock-inversion/132: ok | ok | ok | [ 0.446861] soft-irq lock-inversion/132: ok | ok | ok | [ 0.453175] hard-irq lock-inversion/213: ok | ok | ok | [ 0.459981] soft-irq lock-inversion/213: ok | ok | ok | [ 0.466496] hard-irq lock-inversion/231: ok | ok | ok | [ 0.473309] soft-irq lock-inversion/231: ok | ok | ok | [ 0.479553] hard-irq lock-inversion/312: ok | ok | ok | [ 0.485727] soft-irq lock-inversion/312: ok | ok | ok | [ 0.492536] hard-irq lock-inversion/321: ok | ok | ok | [ 0.498676] soft-irq lock-inversion/321: ok | ok | ok | [ 0.505443] hard-irq read-recursion/123: ok | [ 0.508075] soft-irq read-recursion/123: ok | [ 0.511074] hard-irq read-recursion/132: ok | [ 0.513585] soft-irq read-recursion/132: ok | [ 0.516568] hard-irq read-recursion/213: ok | [ 0.519554] soft-irq read-recursion/213: ok | [ 0.522047] hard-irq read-recursion/231: ok | [ 0.525086] soft-irq read-recursion/231: ok | [ 0.527651] hard-irq read-recursion/312: ok | [ 0.530570] soft-irq read-recursion/312: ok | [ 0.533574] hard-irq read-recursion/321: ok | [ 0.536043] soft-irq read-recursion/321: ok | [ 0.539030] -------------------------------------------------------------------------- [ 0.539713] | Wound/wait tests | [ 0.540293] --------------------- [ 0.540785] ww api failures: ok | ok | ok | [ 0.549525] ww contexts mixing: ok | ok | [ 0.553680] finishing ww context: ok | ok | ok | ok | [ 0.561671] locking mismatches: ok | ok | ok | [ 0.567488] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.587997] spinlock nest unlocked: ok | [ 0.590599] ----------------------------------------------------- [ 0.591124] |block | try |context| [ 0.591637] ----------------------------------------------------- [ 0.592143] context: ok | ok | ok | [ 0.598192] try: ok | ok | ok | [ 0.604232] block: ok | ok | ok | [ 0.609764] spinlock: ok | ok | ok | [ 0.616631] ------------------------------------------------------- [ 0.617339] Good, all 261 testcases passed! | [ 0.617898] --------------------------------- [ 0.625711] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.626730] pid_max: default: 32768 minimum: 301 [ 0.643407] LSM: Security Framework initializing [ 0.644385] Yama: becoming mindful. [ 0.648390] LoadPin: ready to pin (currently enforcing) [ 0.649596] SELinux: Initializing. [ 0.662290] *** VALIDATE SELinux *** [ 0.663488] AppArmor: AppArmor disabled by boot time parameter [ 0.721110] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.742349] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.747049] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.749264] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.807768] *** VALIDATE proc *** [ 0.840788] *** VALIDATE cgroup1 *** [ 0.841417] *** VALIDATE cgroup2 *** [ 0.955401] ASID allocator initialised with 32768 entries [ 0.972027] rcu: Hierarchical SRCU implementation. [ 1.105092] EFI services will not be available. [ 1.149970] smp: Bringing up secondary CPUs ... [ 1.150709] smp: Brought up 1 node, 1 CPU [ 1.151287] SMP: Total of 1 processors activated. [ 1.152592] CPU features: detected: 32-bit EL0 Support [ 1.153305] CPU features: detected: CRC32 instructions [ 1.526114] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.527231] CPU: All CPU(s) started at EL1 [ 1.528564] alternatives: patching kernel code [ 1.530476] _warn_unseeded_randomness: 98 callbacks suppressed [ 1.530967] random: get_random_u64 called from compute_layout+0x138/0x1b8 with crng_init=0 [ 1.591179] random: get_random_u64 called from dup_task_struct+0x164/0x7f0 with crng_init=0 [ 5.826191] node 0 initialised, 248310 pages in 4ms [ 5.839219] ================================================================================ [ 5.839945] UBSAN: Undefined behaviour in ../kernel/exit.c:735:2 [ 5.840376] member access within misaligned address ffffff80080bad90 for type 'struct task_struct' [ 5.840796] which requires 64 byte alignment [ 5.841541] CPU: 0 PID: 15 Comm: pgdatinit0 Tainted: G T 4.20.0-rc5-next-20181205-00011-g6db3431f9b32-dirty #1 [ 5.842028] Hardware name: linux,dummy-virt (DT) [ 5.842515] Call trace: [ 5.842917] dump_backtrace+0x0/0x308 [ 5.843335] show_stack+0x28/0x38 [ 5.843719] __dump_stack+0x24/0x2c [ 5.844102] dump_stack+0xf8/0x16c [ 5.844479] ubsan_epilogue+0x18/0x50 [ 5.844871] handle_misaligned_access+0x94/0xc8 [ 5.845281] ubsan_type_mismatch_common+0x60/0x90 [ 5.845699] __ubsan_handle_type_mismatch+0x50/0x74 [ 5.846113] exit_notify+0x558/0x5c0 [ 5.846496] do_exit+0xabc/0xf60 [ 5.846877] kthread+0x274/0x278 [ 5.847259] ret_from_fork+0x10/0x18 [ 5.847653] ================================================================================ [ 5.849462] pgdatinit0 (15) used greatest stack depth: 12720 bytes left [ 5.863931] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 5.974109] devtmpfs: initialized [ 6.297614] workqueue: round-robin CPU selection forced, expect performance impact [ 6.318876] gcov: version magic: 0x4137332a [ 6.356465] Registered cp15_barrier emulation handler [ 6.357497] Registered setend emulation handler [ 17.641032] DMA-API: preallocated 65536 debug entries [ 17.641659] DMA-API: debugging enabled by kernel config [ 17.643488] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 17.647332] futex hash table entries: 256 (order: 3, 32768 bytes) [ 17.655571] Running postponed tracer tests: [ 17.658605] Testing tracer function: [ 17.659736] ================================================================================ [ 17.660623] UBSAN: Undefined behaviour in ../kernel/sched/fair.c:7431:2 [ 17.661065] member access within misaligned address ffffffc07ebe4f68 for type 'struct cfs_rq' [ 17.661499] which requires 64 byte alignment [ 17.661984] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G T 4.20.0-rc5-next-20181205-00011-g6db3431f9b32-dirty #1 [ 17.662633] Hardware name: linux,dummy-virt (DT) [ 17.663056] Call trace: [ 17.663466] dump_backtrace+0x0/0x308 [ 17.663958] show_stack+0x28/0x38 [ 17.664417] __dump_stack+0x24/0x2c [ 17.664900] dump_stack+0xf8/0x16c [ 17.665340] ubsan_epilogue+0x18/0x50 [ 17.665844] handle_misaligned_access+0x94/0xc8 [ 17.666345] ubsan_type_mismatch_common+0x60/0x90 [ 17.666876] __ubsan_handle_type_mismatch+0x50/0x74 [ 17.667378] update_blocked_averages+0x24a8/0x2928 [ 17.667827] idle_balance+0x5c8/0xd68 [ 17.668287] pick_next_task_fair+0x780/0x7d8 [ 17.668758] __schedule+0x74c/0x15c0 [ 17.669177] schedule+0x154/0x210 [ 17.669610] schedule_timeout+0xe88/0xef8 [ 17.670070] schedule_timeout_uninterruptible+0x7c/0x90 [ 17.670527] msleep+0x3c/0x58 [ 17.670990] trace_selftest_startup_function+0x140/0x4d4 [ 17.671450] run_tracer_selftest+0x300/0x4a4 [ 17.671902] init_trace_selftests+0x29c/0x8b4 [ 17.672384] do_one_initcall+0x4c8/0xa50 [ 17.672806] do_initcall_level+0xbcc/0xca4 [ 17.673217] do_initcalls+0x64/0xa8 [ 17.673633] do_basic_setup+0x44/0x70 [ 17.674066] kernel_init_freeable+0x2e0/0x428 [ 17.674519] kernel_init+0x28/0x140 [ 17.674992] ret_from_fork+0x10/0x18 [ 17.675368] ================================================================================ [ 17.712638] ftrace_replace_code: 1 [ 28.933238] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 29.154048] ftrace_replace_code: 1 [ 39.067996] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 39.073022] PASSED [ 39.073527] Testing dynamic ftrace: [ 41.070854] ftrace_replace_code: 1 [ 41.188016] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 41.437696] PASSED [ 41.438656] Testing dynamic ftrace ops #1: [ 49.395745] ftrace_replace_code: 1 [ 49.519707] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 49.524747] (1 0 1 0 0) [ 49.524968] (1 1 2 0 0) [ 49.540452] ftrace_replace_code: 1 [ 64.180000] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 64.182251] (2 1 3 0 1008498) [ 64.182538] (2 2 4 0 1008513) [ 64.200203] ftrace_replace_code: 1 [ 75.243709] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 75.261289] ftrace_replace_code: 1 [ 75.377828] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 75.382872] ftrace_replace_code: 1 [ 75.501902] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 75.509105] PASSED [ 75.509622] Testing dynamic ftrace ops #2: [ 83.458235] ftrace_replace_code: 1 [ 83.588134] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 83.592623] ftrace_replace_code: 1 [ 83.708917] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 83.732821] ftrace_replace_code: 1 [ 97.562518] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 97.565969] (1 0 1 997946 0) [ 97.566213] (1 1 2 997961 0) [ 97.586353] (2 1 3 1 939) [ 97.587421] (2 2 4 117 1055) [ 97.635738] ftrace_replace_code: 1 [ 107.538280] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 107.541023] PASSED [ 107.542210] Testing ftrace recursion: [ 109.529396] ftrace_replace_code: 1 [ 109.647831] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 109.652678] ftrace_replace_code: 1 [ 109.768921] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 109.772030] PASSED [ 109.772559] Testing ftrace recursion safe: [ 111.759204] ftrace_replace_code: 1 [ 111.884887] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 111.889881] ftrace_replace_code: 1 [ 112.007022] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 112.010011] PASSED [ 112.010540] Testing ftrace regs(no arch support): [ 114.004979] ftrace_replace_code: 1 [ 114.122856] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 114.127885] ftrace_replace_code: 1 [ 114.243648] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 114.246857] PASSED [ 114.247954] Testing tracer nop: PASSED [ 114.251210] Testing tracer irqsoff: [ 114.271947] ftrace_replace_code: 1 [ 125.646256] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 125.677316] ftrace_replace_code: 1 [ 135.610688] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 135.614189] PASSED [ 135.616580] Testing tracer wakeup: [ 135.655407] ftrace_replace_code: 1 [ 147.113220] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 147.147819] ftrace_replace_code: 1 [ 157.250707] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 157.262637] PASSED [ 157.263683] Testing tracer wakeup_rt: [ 157.300842] ftrace_replace_code: 1 [ 168.745175] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 168.772453] ftrace_replace_code: 1 [ 178.710212] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 178.722281] PASSED [ 178.723406] Testing tracer wakeup_dl: [ 178.760305] ftrace_replace_code: 1 [ 190.247471] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 190.283796] ftrace_replace_code: 1 [ 190.565780] sched: DL replenish lagged too much [ 200.241174] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 200.250904] PASSED [ 200.253951] Testing tracer function_graph: [ 200.288536] ftrace_replace_code: 1 [ 211.509466] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 211.937133] ftrace_replace_code: 1 [ 224.175138] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 224.179754] PASSED [ 224.399559] _warn_unseeded_randomness: 7 callbacks suppressed [ 224.399837] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 224.402041] random: get_random_u64 called from cache_random_seq_create+0xd0/0x1a8 with crng_init=0 [ 224.404736] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 224.433076] prandom: seed boundary self test passed [ 224.476617] prandom: 100 self tests passed [ 224.485739] pinctrl core: initialized pinctrl subsystem [ 224.613945] ================================================================================ [ 224.614693] UBSAN: Undefined behaviour in ../drivers/base/devres.c:505:2 [ 224.615226] member access within misaligned address ffffff800804b8d8 for type 'struct devres' [ 224.615701] which requires 128 byte alignment [ 224.616273] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G T 4.20.0-rc5-next-20181205-00011-g6db3431f9b32-dirty #1 [ 224.616922] Hardware name: linux,dummy-virt (DT) [ 224.617360] Call trace: [ 224.617795] dump_backtrace+0x0/0x308 [ 224.618241] show_stack+0x28/0x38 [ 224.618735] __dump_stack+0x24/0x2c [ 224.619190] dump_stack+0xf8/0x16c [ 224.619638] ubsan_epilogue+0x18/0x50 [ 224.620101] handle_misaligned_access+0x94/0xc8 [ 224.620566] ubsan_type_mismatch_common+0x60/0x90 [ 224.621072] __ubsan_handle_type_mismatch+0x50/0x74 [ 224.621549] release_nodes+0x94/0x180 [ 224.622003] devres_release_all+0xa0/0xc0 [ 224.622435] really_probe+0x69c/0xd48 [ 224.622866] really_probe_debug+0x3c/0x128 [ 224.623324] driver_probe_device+0x1f8/0x2a0 [ 224.623764] __driver_attach+0x250/0x340 [ 224.624275] bus_for_each_dev+0x110/0x180 [ 224.624711] driver_attach+0x50/0x60 [ 224.625140] bus_add_driver+0x308/0x4f0 [ 224.625575] driver_register+0x2a0/0x330 [ 224.626065] __platform_driver_register+0xcc/0xe0 [ 224.626548] regulator_dummy_init+0x100/0x174 [ 224.627019] regulator_init+0xf0/0x128 [ 224.627487] do_one_initcall+0x4c8/0xa50 [ 224.627974] do_initcall_level+0xbcc/0xca4 [ 224.628425] do_initcalls+0x64/0xa8 [ 224.628843] do_basic_setup+0x44/0x70 [ 224.629292] kernel_init_freeable+0x2e0/0x428 [ 224.629788] kernel_init+0x28/0x140 [ 224.630221] ret_from_fork+0x10/0x18 [ 224.630582] ================================================================================ [ 224.651436] regulator-dummy: Failed to create debugfs directory [ 224.664825] [ 224.665344] ************************************************************* [ 224.666036] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 224.667270] ** ** [ 224.667864] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 224.668520] ** ** [ 224.669093] ** This means that this kernel is built to expose internal ** [ 224.669696] ** IOMMU data structures, which may compromise security on ** [ 224.670287] ** your system. ** [ 224.671510] ** ** [ 224.672128] ** If you see this message and you are not debugging the ** [ 224.672709] ** kernel, report this immediately to your vendor! ** [ 224.673321] ** ** [ 224.673955] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 224.674582] ************************************************************* [ 224.716733] DMI not present or invalid. [ 224.791363] NET: Registered protocol family 16 [ 224.830550] audit: initializing netlink subsys (disabled) [ 225.035988] cpuidle: using governor ladder [ 225.038088] cpuidle: using governor menu [ 225.068151] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 225.070265] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 225.078408] audit: type=2000 audit(191.652:1): state=initialized audit_enabled=0 res=1 [ 225.295645] DMA: preallocated 256 KiB pool for atomic allocations [ 225.420848] Serial: AMBA PL011 UART driver [ 226.542587] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 226.546901] printk: console [ttyAMA0] enabled [ 226.546901] printk: console [ttyAMA0] enabled [ 226.548373] printk: bootconsole [pl11] disabled [ 226.548373] printk: bootconsole [pl11] disabled [ 226.701157] _warn_unseeded_randomness: 42 callbacks suppressed [ 226.701421] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 226.704112] random: get_random_u64 called from cache_random_seq_create+0xd0/0x1a8 with crng_init=0 [ 227.454354] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 227.538433] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 227.737537] kobject: 'renesas,r9a06g032-sysctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 227.752895] kobject: 'renesas-cpg-mssr' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 227.806136] _warn_unseeded_randomness: 33 callbacks suppressed [ 227.806408] random: get_random_u64 called from dup_task_struct+0x164/0x7f0 with crng_init=0 [ 227.815280] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 227.816745] random: get_random_u64 called from cache_random_seq_create+0xd0/0x1a8 with crng_init=0 [ 227.835226] arm-v7s io-pgtable: self test ok [ 227.837887] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 227.848213] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 227.855386] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 227.863401] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 227.873415] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 227.881523] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 227.890259] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 227.902419] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 227.915703] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 227.929486] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 227.943210] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 227.956940] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 227.973949] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 228.008953] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 228.043822] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 228.080569] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 228.122310] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 228.164423] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 228.206880] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 228.251562] vgaarb: loaded [ 228.313438] pps_core: LinuxPPS API ver. 1 registered [ 228.314249] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 228.319933] PTP clock support registered [ 228.332366] kobject: 'brcmstb-reboot' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 228.561902] NetLabel: Initializing [ 228.563410] NetLabel: domain hash size = 128 [ 228.564090] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 228.577061] NetLabel: unlabeled traffic allowed by default [ 228.604476] clocksource: Switched to clocksource arch_sys_counter [ 237.807964] _warn_unseeded_randomness: 7 callbacks suppressed [ 237.808229] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 237.810113] random: get_random_u64 called from cache_random_seq_create+0xd0/0x1a8 with crng_init=0 [ 237.814050] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 237.834288] VFS: Disk quotas dquot_6.6.0 [ 237.840619] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 237.876939] *** VALIDATE hugetlbfs *** [ 239.083491] _warn_unseeded_randomness: 7 callbacks suppressed [ 239.083771] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 239.085632] random: get_random_u64 called from cache_random_seq_create+0xd0/0x1a8 with crng_init=0 [ 239.088508] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 239.096256] NET: Registered protocol family 2 [ 239.157526] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 239.164569] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 239.196466] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 239.209150] TCP: Hash tables configured (established 16384 bind 16384) [ 239.222221] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 239.229922] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 239.256579] NET: Registered protocol family 1 [ 239.262791] NET: Registered protocol family 44 [ 239.381684] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 239.397255] kvm [1]: HYP mode not available [ 239.810613] Kprobe smoke test: started [ 241.084739] Kprobe smoke test: passed successfully [ 241.180712] Initialise system trusted keyrings [ 241.183736] _warn_unseeded_randomness: 50 callbacks suppressed [ 241.183988] random: get_random_bytes called from key_alloc+0x500/0xa48 with crng_init=0 [ 241.193389] Key type blacklist registered [ 241.201559] random: get_random_u64 called from dup_task_struct+0x164/0x7f0 with crng_init=0 [ 241.220343] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 244.032270] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 244.033627] random: get_random_u64 called from cache_random_seq_create+0xd0/0x1a8 with crng_init=0 [ 244.065510] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 244.347915] 9p: Installing v9fs 9p2000 file system support [ 244.507823] Allocating IMA blacklist keyring. [ 244.525824] ================================================================================ [ 244.526668] UBSAN: Undefined behaviour in ../crypto/algapi.c:363:2 [ 244.527328] member access within misaligned address ffffff800d2fbe38 for type 'struct crypto_alg' [ 244.528103] which requires 128 byte alignment [ 244.528745] CPU: 0 PID: 37 Comm: cryptomgr_test Tainted: G T 4.20.0-rc5-next-20181205-00011-g6db3431f9b32-dirty #1 [ 244.529514] Hardware name: linux,dummy-virt (DT) [ 244.530065] Call trace: [ 244.530613] dump_backtrace+0x0/0x308 [ 244.531203] show_stack+0x28/0x38 [ 244.531739] __dump_stack+0x24/0x2c [ 244.532295] dump_stack+0xf8/0x16c [ 244.532847] ubsan_epilogue+0x18/0x50 [ 244.533418] handle_misaligned_access+0x94/0xc8 [ 244.534018] ubsan_type_mismatch_common+0x60/0x90 [ 244.534611] __ubsan_handle_type_mismatch+0x50/0x74 [ 244.535261] crypto_remove_final+0x78/0x238 [ 244.535892] crypto_alg_tested+0x450/0x488 [ 244.536518] cryptomgr_test+0x44/0x58 [ 244.537121] kthread+0x25c/0x278 [ 244.537712] ret_from_fork+0x10/0x18 [ 244.538234] ================================================================================ [ 244.544247] cryptomgr_test (37) used greatest stack depth: 11712 bytes left [ 244.578699] ================================================================================ [ 244.579643] UBSAN: Undefined behaviour in ../kernel/exit.c:735:2 [ 244.580315] member access within misaligned address ffffff800d2fad90 for type 'struct task_struct' [ 244.581066] which requires 64 byte alignment [ 244.581753] CPU: 0 PID: 38 Comm: kworker/u2:0 Tainted: G T 4.20.0-rc5-next-20181205-00011-g6db3431f9b32-dirty #1 [ 244.582621] Hardware name: linux,dummy-virt (DT) [ 244.583169] Call trace: [ 244.583715] dump_backtrace+0x0/0x308 [ 244.584290] show_stack+0x28/0x38 [ 244.584873] __dump_stack+0x24/0x2c [ 244.585466] dump_stack+0xf8/0x16c [ 244.586063] ubsan_epilogue+0x18/0x50 [ 244.586677] handle_misaligned_access+0x94/0xc8 [ 244.587322] ubsan_type_mismatch_common+0x60/0x90 [ 244.587989] __ubsan_handle_type_mismatch+0x50/0x74 [ 244.588652] exit_notify+0x440/0x5c0 [ 244.589233] do_exit+0xabc/0xf60 [ 244.589829] call_usermodehelper_exec_async+0x348/0x3b0 [ 244.590507] ret_from_fork+0x10/0x18 [ 244.591044] ================================================================================ [ 244.594268] kworker/u2:0 (38) used greatest stack depth: 11408 bytes left [ 246.413498] Key type asymmetric registered [ 246.416448] Asymmetric key parser 'x509' registered [ 246.425649] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 246.445339] _warn_unseeded_randomness: 28 callbacks suppressed [ 246.445611] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 246.448253] random: get_random_u64 called from cache_random_seq_create+0xd0/0x1a8 with crng_init=0 [ 246.497554] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 246.732849] kobject: 'brcm-gisb-arb' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 246.874941] kobject: 'armada-37xx-pinctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 247.152418] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 247.156105] ================================================================================ [ 247.157109] UBSAN: Undefined behaviour in ../drivers/base/devres.c:505:2 [ 247.157861] member access within misaligned address ffffff800804b928 for type 'struct devres' [ 247.158592] which requires 128 byte alignment [ 247.159282] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G T 4.20.0-rc5-next-20181205-00011-g6db3431f9b32-dirty #1 [ 247.160117] Hardware name: linux,dummy-virt (DT) [ 247.160622] Call trace: [ 247.161172] dump_backtrace+0x0/0x308 [ 247.161717] show_stack+0x28/0x38 [ 247.162295] __dump_stack+0x24/0x2c [ 247.162901] dump_stack+0xf8/0x16c [ 247.163513] ubsan_epilogue+0x18/0x50 [ 247.164133] handle_misaligned_access+0x94/0xc8 [ 247.164785] ubsan_type_mismatch_common+0x60/0x90 [ 247.165453] __ubsan_handle_type_mismatch+0x50/0x74 [ 247.166132] release_nodes+0x11c/0x180 [ 247.166762] devres_release_all+0xa0/0xc0 [ 247.167372] really_probe+0x69c/0xd48 [ 247.167983] really_probe_debug+0x3c/0x128 [ 247.168600] driver_probe_device+0x1f8/0x2a0 [ 247.169225] __driver_attach+0x250/0x340 [ 247.169869] bus_for_each_dev+0x110/0x180 [ 247.170476] driver_attach+0x50/0x60 [ 247.171072] bus_add_driver+0x308/0x4f0 [ 247.171682] driver_register+0x2a0/0x330 [ 247.172317] amba_driver_register+0x120/0x130 [ 247.172965] pl061_gpio_init+0x2c/0x5c [ 247.173574] do_one_initcall+0x4c8/0xa50 [ 247.174180] do_initcall_level+0xbcc/0xca4 [ 247.174789] do_initcalls+0x64/0xa8 [ 247.175381] do_basic_setup+0x44/0x70 [ 247.175982] kernel_init_freeable+0x2e0/0x428 [ 247.176618] kernel_init+0x28/0x140 [ 247.177227] ret_from_fork+0x10/0x18 [ 247.177757] ================================================================================ [ 247.186021] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 247.221708] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 247.261671] kobject: 'stmpe-pwm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 247.301932] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 247.416320] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 247.420431] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 247.424653] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 247.426585] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 247.433462] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 247.460022] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 247.461546] pci_bus 0000:00: root bus resource [bus 00-0f] [ 247.462744] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 247.464669] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 247.465802] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 247.472865] pci 0000:00:00.0: calling quirk_mmio_always_on+0x0/0x50 @ 1 [ 247.474225] pci 0000:00:00.0: quirk_mmio_always_on+0x0/0x50 took 167 usecs [ 247.608233] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 247.609740] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 247.612260] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 247.613985] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 247.616207] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 247.617352] pci 0000:00:03.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 247.618531] pci 0000:00:03.0: BAR 0: assigned [io 0x1000-0x103f] [ 247.620421] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 247.621584] pci 0000:00:02.0: BAR 0: assigned [io 0x1060-0x107f] [ 247.626049] pci 0000:00:00.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 247.628058] pci 0000:00:00.0: tegra_pcie_relax_enable+0x0/0x40 took 202 usecs [ 247.632219] pci 0000:00:01.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 247.633443] pci 0000:00:01.0: tegra_pcie_relax_enable+0x0/0x40 took 90 usecs [ 247.636127] pci 0000:00:02.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 247.637416] pci 0000:00:02.0: tegra_pcie_relax_enable+0x0/0x40 took 89 usecs [ 247.640144] pci 0000:00:03.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 247.641454] pci 0000:00:03.0: tegra_pcie_relax_enable+0x0/0x40 took 88 usecs [ 247.716422] _warn_unseeded_randomness: 15 callbacks suppressed [ 247.716662] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 247.718341] kobject: 'layerscape-pcie' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 247.720649] random: get_random_u32 called from shuffle_freelist+0xb4/0x398 with crng_init=0 [ 248.518630] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 248.568841] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 248.570070] kobject: 'virtio0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 248.621769] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 248.659803] kobject: 'virtio1' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 248.712099] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 248.747868] _warn_unseeded_randomness: 3 callbacks suppressed [ 248.748137] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 248.749949] kobject: 'virtio2' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 248.769274] random: get_random_u32 called from shuffle_freelist+0xb4/0x398 with crng_init=0 [ 256.321067] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 256.546464] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 256.548616] kobject: 'ttyS0' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 256.550374] random: get_random_u32 called from shuffle_freelist+0xb4/0x398 with crng_init=0 [ 256.553263] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 256.554314] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 256.655932] kobject: 'ttyS1' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 256.658000] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 256.757485] kobject: 'ttyS2' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 256.760571] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 256.858830] kobject: 'ttyS3' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 256.861771] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 256.998115] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 257.017215] KGDB: Registered I/O driver kgdbts [ 257.018514] kgdbts:RUN plant and detach test Entering kdb (current=0xffffffc03f5c8040, pid 1) on processor 0 due to Keyboard Entry [0]kdb> [ 257.030758] kgdbts:RUN sw breakpoint test [ 257.048901] kgdbts:RUN bad memory access test [ 257.052931] kgdbts:RUN singlestep test 1000 iterations [ 257.087867] kgdbts:RUN singlestep [0/1000] [ 260.290793] kgdbts:RUN singlestep [100/1000] [ 263.495987] kgdbts:RUN singlestep [200/1000] [ 266.694884] kgdbts:RUN singlestep [300/1000] [ 269.898692] kgdbts:RUN singlestep [400/1000] [ 273.098802] kgdbts:RUN singlestep [500/1000] [ 276.298870] kgdbts:RUN singlestep [600/1000] [ 279.498649] kgdbts:RUN singlestep [700/1000] [ 282.698888] kgdbts:RUN singlestep [800/1000] [ 285.898787] kgdbts:RUN singlestep [900/1000] [ 289.066622] kgdbts:RUN do_fork for 100 breakpoints [ 289.074083] _warn_unseeded_randomness: 7 callbacks suppressed [ 289.074382] random: get_random_u64 called from dup_task_struct+0x164/0x7f0 with crng_init=0 [ 289.265437] ================================================================================ [ 289.266675] UBSAN: Undefined behaviour in ../net/core/dev.c:8006:2 [ 289.267480] member access within misaligned address ffffff800804b820 for type 'struct net_device' [ 289.268300] which requires 64 byte alignment [ 289.268971] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G T 4.20.0-rc5-next-20181205-00011-g6db3431f9b32-dirty #1 [ 289.269927] Hardware name: linux,dummy-virt (DT) [ 289.270481] Call trace: [ 289.271084] dump_backtrace+0x0/0x308 [ 289.271783] show_stack+0x28/0x38 [ 289.272437] __dump_stack+0x24/0x2c [ 289.273064] dump_stack+0xf8/0x16c [ 289.273697] ubsan_epilogue+0x18/0x50 [ 289.274365] handle_misaligned_access+0x94/0xc8 [ 289.275059] ubsan_type_mismatch_common+0x60/0x90 [ 289.275870] __ubsan_handle_type_mismatch+0x50/0x74 [ 289.276540] rollback_registered_many+0x304/0x900 [ 289.277235] rollback_registered+0xbc/0x158 [ 289.277931] unregister_netdevice_queue+0x234/0x280 [ 289.278597] unregister_netdev+0x2c/0x40 [ 289.279275] virtnet_remove+0x70/0xc8 [ 289.279972] virtio_dev_remove+0x74/0xf0 [ 289.280627] really_probe+0x640/0xd48 [ 289.281265] really_probe_debug+0x3c/0x128 [ 289.281930] driver_probe_device+0x1f8/0x2a0 [ 289.282549] __driver_attach+0x250/0x340 [ 289.283217] bus_for_each_dev+0x110/0x180 [ 289.283811] driver_attach+0x50/0x60 [ 289.284416] bus_add_driver+0x308/0x4f0 [ 289.285006] driver_register+0x2a0/0x330 [ 289.285600] register_virtio_driver+0xa4/0xb8 [ 289.286254] virtio_net_driver_init+0x100/0x17c [ 289.286885] do_one_initcall+0x4c8/0xa50 [ 289.287472] do_initcall_level+0xbcc/0xca4 [ 289.288108] do_initcalls+0x64/0xa8 [ 289.288701] do_basic_setup+0x44/0x70 [ 289.289323] kernel_init_freeable+0x2e0/0x428 [ 289.289944] kernel_init+0x28/0x140 [ 289.290563] ret_from_fork+0x10/0x18 [ 289.291139] ================================================================================ [ 289.293042] ================================================================================ [ 289.293936] UBSAN: Undefined behaviour in ../net/core/dev.c:1495:2 [ 289.294609] member access within misaligned address ffffff800804b7b0 for type 'struct net_device' [ 289.295430] which requires 64 byte alignment [ 289.296138] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G T 4.20.0-rc5-next-20181205-00011-g6db3431f9b32-dirty #1 [ 289.297115] Hardware name: linux,dummy-virt (DT) [ 289.297656] Call trace: [ 289.298179] dump_backtrace+0x0/0x308 [ 289.298816] show_stack+0x28/0x38 [ 289.299477] __dump_stack+0x24/0x2c [ 289.300188] dump_stack+0xf8/0x16c [ 289.300836] ubsan_epilogue+0x18/0x50 [ 289.301500] handle_misaligned_access+0x94/0xc8 [ 289.302229] ubsan_type_mismatch_common+0x60/0x90 [ 289.302889] __ubsan_handle_type_mismatch+0x50/0x74 [ 289.303686] dev_close_many+0x1a4/0x388 [ 289.304323] rollback_registered_many+0x428/0x900 [ 289.305036] rollback_registered+0xbc/0x158 [ 289.305684] unregister_netdevice_queue+0x234/0x280 [ 289.306403] unregister_netdev+0x2c/0x40 [ 289.307019] virtnet_remove+0x70/0xc8 [ 289.307682] virtio_dev_remove+0x74/0xf0 [ 289.308334] really_probe+0x640/0xd48 [ 289.308963] really_probe_debug+0x3c/0x128 [ 289.309574] driver_probe_device+0x1f8/0x2a0 [ 289.310235] __driver_attach+0x250/0x340 [ 289.310841] bus_for_each_dev+0x110/0x180 [ 289.311550] driver_attach+0x50/0x60 [ 289.312211] bus_add_driver+0x308/0x4f0 [ 289.312852] driver_register+0x2a0/0x330 [ 289.313503] register_virtio_driver+0xa4/0xb8 [ 289.314175] virtio_net_driver_init+0x100/0x17c [ 289.314856] do_one_initcall+0x4c8/0xa50 [ 289.315588] do_initcall_level+0xbcc/0xca4 [ 289.316200] do_initcalls+0x64/0xa8 [ 289.316789] do_basic_setup+0x44/0x70 [ 289.317430] kernel_init_freeable+0x2e0/0x428 [ 289.318074] kernel_init+0x28/0x140 [ 289.318747] ret_from_fork+0x10/0x18 [ 289.319348] ================================================================================ [ 289.321157] ================================================================================ [ 289.322025] UBSAN: Undefined behaviour in ../net/core/dev.c:1501:2 [ 289.322716] member access within misaligned address ffffff800804b7b0 for type 'struct net_device' [ 289.323542] which requires 64 byte alignment [ 289.324256] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G T 4.20.0-rc5-next-20181205-00011-g6db3431f9b32-dirty #1 [ 289.325068] Hardware name: linux,dummy-virt (DT) [ 289.325555] Call trace: [ 289.326140] dump_backtrace+0x0/0x308 [ 289.326802] show_stack+0x28/0x38 [ 289.327516] __dump_stack+0x24/0x2c [ 289.328171] dump_stack+0xf8/0x16c [ 289.328810] ubsan_epilogue+0x18/0x50 [ 289.329443] handle_misaligned_access+0x94/0xc8 [ 289.330124] ubsan_type_mismatch_common+0x60/0x90 [ 289.330791] __ubsan_handle_type_mismatch+0x50/0x74 [ 289.331629] dev_close_many+0x218/0x388 [ 289.332366] rollback_registered_many+0x428/0x900 [ 289.333033] rollback_registered+0xbc/0x158 [ 289.333781] unregister_netdevice_queue+0x234/0x280 [ 289.334490] unregister_netdev+0x2c/0x40 [ 289.335126] virtnet_remove+0x70/0xc8 [ 289.335759] virtio_dev_remove+0x74/0xf0 [ 289.336460] really_probe+0x640/0xd48 [ 289.337109] really_probe_debug+0x3c/0x128 [ 289.337757] driver_probe_device+0x1f8/0x2a0 [ 289.338396] __driver_attach+0x250/0x340 [ 289.339023] bus_for_each_dev+0x110/0x180 [ 289.339677] driver_attach+0x50/0x60 [ 289.340281] bus_add_driver+0x308/0x4f0 [ 289.340924] driver_register+0x2a0/0x330 [ 289.341541] register_virtio_driver+0xa4/0xb8 [ 289.342183] virtio_net_driver_init+0x100/0x17c [ 289.342817] do_one_initcall+0x4c8/0xa50 [ 289.343499] do_initcall_level+0xbcc/0xca4 [ 289.344129] do_initcalls+0x64/0xa8 [ 289.344702] do_basic_setup+0x44/0x70 [ 289.345327] kernel_init_freeable+0x2e0/0x428 [ 289.346039] kernel_init+0x28/0x140 [ 289.346673] ret_from_fork+0x10/0x18 [ 289.347323] ================================================================================ [ 291.213486] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 291.214796] kobject: 'rx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 291.222771] kobject: 'tx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 291.224860] random: get_random_u32 called from shuffle_freelist+0xb4/0x398 with crng_init=0 [ 291.309295] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 291.310887] kobject: 'net' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 292.217698] KGDB: Unregistered I/O driver kgdbts, debugger disabled [ 293.250971] kobject: 'queues' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 293.398262] kobject: '44' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 293.420662] kobject: 'eth0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 293.429518] random: get_random_u32 called from shuffle_freelist+0xb4/0x398 with crng_init=0 [ 293.697978] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 293.699605] kobject: '45' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 293.707641] kobject: '46' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 293.710359] ledtrig-cpu: registered to indicate activity on CPUs [ 293.713436] random: get_random_u32 called from shuffle_freelist+0xb4/0x398 with crng_init=0 [ 293.737371] kobject: 'meson-sm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 293.845240] ashmem: initialized [ 294.157631] 9pnet: Installing 9P2000 support [ 294.717132] _warn_unseeded_randomness: 9 callbacks suppressed [ 294.717356] random: get_random_bytes called from init_oops_id+0x38/0x60 with crng_init=0 [ 294.740868] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 294.742086] kobject: '47' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 294.744134] random: get_random_u32 called from shuffle_freelist+0xb4/0x398 with crng_init=0 [ 294.756159] kobject: '48' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 294.848055] registered taskstats version 1 [ 294.849901] Running ring buffer tests... [ 305.118026] finished [ 305.119920] CPU 0: [ 305.120591] events: 5485 [ 305.121366] dropped bytes: 0 [ 305.122139] alloced bytes: 454404 [ 305.123628] written bytes: 418913 [ 305.124366] biggest event: 31 [ 305.125050] smallest event: 0 [ 305.431548] read events: 5485 [ 305.432334] lost events: 0 [ 305.433128] total events: 5485 [ 305.433881] recorded len bytes: 454404 [ 305.434638] recorded size bytes: 418913 [ 305.436144] Ring buffer PASSED! [ 305.548842] Running tests on trace events: [ 305.549719] Testing event initcall_finish: OK [ 305.596356] Testing event initcall_start: OK [ 305.636484] Testing event initcall_level: OK [ 305.676134] Testing event sys_exit: OK [ 305.714891] Testing event sys_enter: OK [ 305.754666] Testing event ipi_exit: OK [ 305.796111] Testing event ipi_entry: OK [ 305.841367] Testing event ipi_raise: OK [ 305.875653] Testing event instruction_emulation: OK [ 305.922127] Testing event kvm_halt_poll_ns: OK [ 305.961234] Testing event kvm_age_page: OK [ 306.000890] Testing event kvm_fpu: OK [ 306.045333] Testing event kvm_mmio: OK [ 306.086465] Testing event kvm_ack_irq: OK [ 306.126713] Testing event kvm_set_irq: OK [ 306.166218] Testing event kvm_vcpu_wakeup: OK [ 306.205183] Testing event kvm_userspace_exit: OK [ 306.245249] Testing event kvm_timer_update_irq: OK [ 306.286233] Testing event kvm_toggle_cache: OK [ 306.325707] Testing event kvm_set_way_flush: OK [ 306.362757] Testing event kvm_test_age_hva: OK [ 306.405097] Testing event kvm_age_hva: OK [ 306.442312] Testing event kvm_set_spte_hva: OK [ 306.485365] Testing event kvm_unmap_hva_range: OK [ 306.526158] Testing event kvm_mmio_emulate: OK [ 306.566395] Testing event kvm_irq_line: OK [ 306.608260] Testing event kvm_access_fault: OK [ 306.653583] Testing event kvm_guest_fault: OK [ 306.689874] Testing event kvm_exit: OK [ 306.726022] Testing event kvm_entry: OK [ 306.773444] Testing event kvm_set_guest_debug: OK [ 306.817882] Testing event kvm_handle_sys_reg: OK [ 306.860786] Testing event trap_reg: OK [ 306.906636] Testing event kvm_arm_set_regset: OK [ 306.946669] Testing event kvm_arm_set_dreg32: OK [ 306.988376] Testing event kvm_arm_clear_debug: OK [ 307.028177] Testing event kvm_arm_setup_debug: OK [ 307.066184] Testing event kvm_hvc_arm64: OK [ 307.108912] Testing event kvm_wfx_arm64: OK [ 307.150330] Testing event vgic_update_irq_pending: OK [ 307.189179] Testing event task_rename: OK [ 307.230230] Testing event task_newtask: OK [ 307.270597] Testing event cpuhp_exit: OK [ 307.312642] Testing event cpuhp_multi_enter: OK [ 307.357227] Testing event cpuhp_enter: OK [ 307.402292] Testing event softirq_raise: OK [ 307.445013] Testing event softirq_exit: OK [ 307.486215] Testing event softirq_entry: OK [ 307.530029] Testing event irq_handler_exit: OK [ 307.570420] Testing event irq_handler_entry: OK [ 307.612112] Testing event signal_deliver: OK [ 307.652822] Testing event signal_generate: OK [ 307.693167] Testing event workqueue_execute_end: OK [ 307.737227] Testing event workqueue_execute_start: OK [ 307.778265] Testing event workqueue_activate_work: OK [ 307.818464] Testing event workqueue_queue_work: OK [ 307.858775] Testing event sched_wake_idle_without_ipi: OK [ 307.900622] Testing event sched_swap_numa: OK [ 307.941199] Testing event sched_stick_numa: OK [ 307.982343] Testing event sched_move_numa: OK [ 308.018753] Testing event sched_process_hang: OK [ 308.061036] Testing event sched_pi_setprio: OK [ 308.101007] Testing event sched_stat_runtime: [ 308.104629] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 308.144990] OK [ 308.145629] Testing event sched_stat_blocked: OK [ 308.186330] Testing event sched_stat_iowait: OK [ 308.226785] Testing event sched_stat_sleep: OK [ 308.266783] Testing event sched_stat_wait: OK [ 308.308441] Testing event sched_process_exec: OK [ 308.348453] Testing event sched_process_fork: OK [ 308.389737] Testing event sched_process_wait: OK [ 308.425925] Testing event sched_wait_task: OK [ 308.462053] Testing event sched_process_exit: OK [ 308.506597] Testing event sched_process_free: OK [ 308.546669] Testing event sched_migrate_task: OK [ 308.588265] Testing event sched_switch: OK [ 308.629680] Testing event sched_wakeup_new: OK [ 308.666794] Testing event sched_wakeup: OK [ 308.712638] Testing event sched_waking: OK [ 308.750602] Testing event sched_kthread_stop_ret: OK [ 308.792234] Testing event sched_kthread_stop: OK [ 308.832359] Testing event lock_acquired: OK [ 308.882897] Testing event lock_contended: OK [ 308.924791] Testing event lock_release: OK [ 308.980194] Testing event lock_acquire: OK [ 309.032421] Testing event console: OK [ 309.070213] Testing event rcu_barrier: OK [ 309.113498] Testing event rcu_torture_read: OK [ 309.154176] Testing event rcu_batch_end: OK [ 309.194878] Testing event rcu_invoke_kfree_callback: OK [ 309.236489] Testing event rcu_invoke_callback: OK [ 309.278521] Testing event rcu_batch_start: OK [ 309.322750] Testing event rcu_kfree_callback: OK [ 309.362343] Testing event rcu_callback: OK [ 309.412854] Testing event rcu_dyntick: OK [ 309.457032] Testing event rcu_fqs: OK [ 309.498355] Testing event rcu_quiescent_state_report: OK [ 309.538716] Testing event rcu_unlock_preempted_task: OK [ 309.578538] Testing event rcu_preempt_task: OK [ 309.620516] Testing event rcu_nocb_wake: OK [ 309.669298] Testing event rcu_exp_funnel_lock: OK [ 309.709381] Testing event rcu_exp_grace_period: OK [ 309.752993] Testing event rcu_grace_period_init: OK [ 309.794207] Testing event rcu_future_grace_period: OK [ 309.834304] Testing event rcu_grace_period: OK [ 309.882097] Testing event rcu_utilization: OK [ 309.921206] Testing event swiotlb_bounced: OK [ 309.962693] Testing event tick_stop: OK [ 310.004242] Testing event itimer_expire: OK [ 310.037901] Testing event itimer_state: OK [ 310.085188] Testing event hrtimer_cancel: OK [ 310.127974] Testing event hrtimer_expire_exit: OK [ 310.170447] Testing event hrtimer_expire_entry: OK [ 310.210381] Testing event hrtimer_start: OK [ 310.250718] Testing event hrtimer_init: OK [ 310.292278] Testing event timer_cancel: OK [ 310.334688] Testing event timer_expire_exit: OK [ 310.378685] Testing event timer_expire_entry: OK [ 310.418355] Testing event timer_start: OK [ 310.464136] Testing event timer_init: OK [ 310.502241] Testing event alarmtimer_cancel: OK [ 310.541739] Testing event alarmtimer_start: OK [ 310.581119] Testing event alarmtimer_fired: OK [ 310.620484] Testing event alarmtimer_suspend: OK [ 310.662358] Testing event module_request: OK [ 310.702731] Testing event module_put: OK [ 310.744333] Testing event module_get: OK [ 310.778058] Testing event module_free: OK [ 310.818777] Testing event module_load: OK [ 310.858759] Testing event cgroup_transfer_tasks: OK [ 310.900356] Testing event cgroup_attach_task: OK [ 310.938368] Testing event cgroup_rename: OK [ 310.978660] Testing event cgroup_release: OK [ 311.020488] Testing event cgroup_rmdir: OK [ 311.064006] Testing event cgroup_mkdir: OK [ 311.105007] Testing event cgroup_remount: OK [ 311.149920] Testing event cgroup_destroy_root: OK [ 311.190207] Testing event cgroup_setup_root: OK [ 311.230585] Testing event irq_enable: OK [ 311.284444] Testing event irq_disable: OK [ 311.334642] Testing event ftrace_test_filter: OK [ 311.377931] Testing event dev_pm_qos_remove_request: OK [ 311.418257] Testing event dev_pm_qos_update_request: OK [ 311.458128] Testing event dev_pm_qos_add_request: OK [ 311.496876] Testing event pm_qos_update_flags: OK [ 311.538233] Testing event pm_qos_update_target: OK [ 311.575957] Testing event pm_qos_update_request_timeout: OK [ 311.621083] Testing event pm_qos_remove_request: OK [ 311.662188] Testing event pm_qos_update_request: OK [ 311.705330] Testing event pm_qos_add_request: OK [ 311.746326] Testing event power_domain_target: OK [ 311.786494] Testing event clock_set_rate: OK [ 311.828160] Testing event clock_disable: OK [ 311.864383] Testing event clock_enable: OK [ 311.901851] Testing event wakeup_source_deactivate: OK [ 311.946458] Testing event wakeup_source_activate: OK [ 311.986672] Testing event suspend_resume: OK [ 312.044183] Testing event device_pm_callback_end: OK [ 312.084319] Testing event device_pm_callback_start: OK [ 312.125277] Testing event cpu_frequency_limits: OK [ 312.159749] Testing event cpu_frequency: OK [ 312.202377] Testing event pstate_sample: OK [ 312.242566] Testing event powernv_throttle: OK [ 312.284517] Testing event cpu_idle: OK [ 312.327980] Testing event rpm_return_int: OK [ 312.372546] Testing event rpm_idle: OK [ 312.414016] Testing event rpm_resume: OK [ 312.457052] Testing event rpm_suspend: OK [ 312.498182] Testing event benchmark_event: OK [ 312.634879] Testing event xdp_devmap_xmit: OK [ 312.674007] Testing event xdp_cpumap_enqueue: OK [ 312.714148] Testing event xdp_cpumap_kthread: OK [ 312.753706] Testing event xdp_redirect_map_err: OK [ 312.790052] Testing event xdp_redirect_map: OK [ 312.830773] Testing event xdp_redirect_err: OK [ 312.870741] Testing event xdp_redirect: OK [ 312.912242] Testing event xdp_exception: OK [ 312.953122] Testing event rseq_ip_fixup: OK [ 312.990585] Testing event rseq_update: OK [ 313.032292] Testing event file_check_and_advance_wb_err: OK [ 313.074453] Testing event filemap_set_wb_err: OK [ 313.119915] Testing event mm_filemap_add_to_page_cache: OK [ 313.161331] Testing event mm_filemap_delete_from_page_cache: OK [ 313.202338] Testing event compact_retry: OK [ 313.240996] Testing event skip_task_reaping: OK [ 313.282300] Testing event finish_task_reaping: OK [ 313.321668] Testing event start_task_reaping: OK [ 313.357856] Testing event wake_reaper: OK [ 313.401127] Testing event mark_victim: OK [ 313.438322] Testing event reclaim_retry_zone: OK [ 313.481182] Testing event oom_score_adj_update: OK [ 313.522136] Testing event mm_lru_activate: OK [ 313.560813] Testing event mm_lru_insertion: OK [ 313.601160] Testing event mm_vmscan_inactive_list_is_low: OK [ 313.638621] Testing event mm_vmscan_lru_shrink_active: OK [ 313.682238] Testing event mm_vmscan_lru_shrink_inactive: OK [ 313.721097] Testing event mm_vmscan_writepage: OK [ 313.758276] Testing event mm_vmscan_lru_isolate: OK [ 313.801167] Testing event mm_shrink_slab_end: OK [ 313.842115] Testing event mm_shrink_slab_start: OK [ 313.881045] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 313.921028] Testing event mm_vmscan_memcg_reclaim_end: OK [ 313.960866] Testing event mm_vmscan_direct_reclaim_end: OK [ 314.002265] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 314.042759] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 314.084225] Testing event mm_vmscan_direct_reclaim_begin: OK [ 314.130764] Testing event mm_vmscan_wakeup_kswapd: OK [ 314.170540] Testing event mm_vmscan_kswapd_wake: OK [ 314.212361] Testing event mm_vmscan_kswapd_sleep: OK [ 314.245753] Testing event percpu_destroy_chunk: OK [ 314.290372] Testing event percpu_create_chunk: OK [ 314.330720] Testing event percpu_alloc_percpu_fail: OK [ 314.372509] Testing event percpu_free_percpu: OK [ 314.406248] Testing event percpu_alloc_percpu: OK [ 314.453794] Testing event mm_fragmentation_stall: OK [ 314.497359] Testing event mm_page_alloc_extfrag: OK [ 314.541312] Testing event mm_page_pcpu_drain: OK [ 314.584824] Testing event mm_page_alloc_zone_locked: OK [ 314.630316] Testing event mm_page_alloc: OK [ 314.672979] Testing event mm_page_free_batched: OK [ 314.710457] Testing event mm_page_free: OK [ 314.757709] Testing event kmem_cache_free: OK [ 314.800947] Testing event kfree: OK [ 314.841042] Testing event kmem_cache_alloc_node: OK [ 314.882264] Testing event kmalloc_node: OK [ 314.922792] Testing event kmem_cache_alloc: OK [ 314.962255] Testing event kmalloc: OK [ 315.005610] Testing event mm_compaction_kcompactd_wake: OK [ 315.046352] Testing event mm_compaction_wakeup_kcompactd: OK [ 315.083920] Testing event mm_compaction_kcompactd_sleep: OK [ 315.125372] Testing event mm_compaction_defer_reset: OK [ 315.170122] Testing event mm_compaction_defer_compaction: OK [ 315.213110] Testing event mm_compaction_deferred: OK [ 315.254222] Testing event mm_compaction_suitable: OK [ 315.293014] Testing event mm_compaction_finished: OK [ 315.333159] Testing event mm_compaction_try_to_compact_pages: OK [ 315.374372] Testing event mm_compaction_end: OK [ 315.414607] Testing event mm_compaction_begin: OK [ 315.456350] Testing event mm_compaction_migratepages: OK [ 315.494180] Testing event mm_compaction_isolate_freepages: OK [ 315.537351] Testing event mm_compaction_isolate_migratepages: OK [ 315.578346] Testing event mm_migrate_pages: OK [ 315.618701] Testing event mm_collapse_huge_page_swapin: OK [ 315.666117] Testing event mm_collapse_huge_page_isolate: OK [ 315.706852] Testing event mm_collapse_huge_page: OK [ 315.748260] Testing event mm_khugepaged_scan_pmd: OK [ 315.793849] Testing event test_pages_isolated: OK [ 315.830872] Testing event cma_release: OK [ 315.867571] Testing event cma_alloc: OK [ 315.910636] Testing event page_ref_unfreeze: OK [ 315.956468] Testing event page_ref_freeze: OK [ 316.000034] Testing event page_ref_mod_unless: OK [ 316.046288] Testing event page_ref_mod_and_return: OK [ 316.085287] Testing event page_ref_mod_and_test: OK [ 316.285531] Testing event page_ref_mod: OK [ 316.370721] Testing event page_ref_set: OK [ 316.420716] Testing event sb_clear_inode_writeback: OK [ 316.461085] Testing event sb_mark_inode_writeback: OK [ 316.501043] Testing event writeback_dirty_inode_enqueue: OK [ 316.541288] Testing event writeback_lazytime_iput: OK [ 316.582461] Testing event writeback_lazytime: OK [ 316.622217] Testing event writeback_single_inode: OK [ 316.662635] Testing event writeback_single_inode_start: OK [ 316.704362] Testing event writeback_wait_iff_congested: OK [ 316.737779] Testing event writeback_congestion_wait: OK [ 316.782669] Testing event writeback_sb_inodes_requeue: OK [ 316.822309] Testing event balance_dirty_pages: OK [ 316.862757] Testing event bdi_dirty_ratelimit: OK [ 316.904236] Testing event global_dirty_state: OK [ 316.937867] Testing event writeback_queue_io: OK [ 316.982850] Testing event wbc_writepage: OK [ 317.024409] Testing event writeback_bdi_register: OK [ 317.066368] Testing event writeback_wake_background: OK [ 317.106559] Testing event writeback_pages_written: OK [ 317.148571] Testing event writeback_wait: OK [ 317.182320] Testing event writeback_written: OK [ 317.226583] Testing event writeback_start: OK [ 317.266142] Testing event writeback_exec: OK [ 317.305211] Testing event writeback_queue: OK [ 317.350452] Testing event writeback_write_inode: OK [ 317.396000] Testing event writeback_write_inode_start: OK [ 317.434194] Testing event writeback_dirty_inode: OK [ 317.476187] Testing event writeback_dirty_inode_start: OK [ 317.521724] Testing event writeback_mark_inode_dirty: OK [ 317.562215] Testing event writeback_dirty_page: OK [ 317.605164] Testing event dax_writeback_one: OK [ 317.648236] Testing event dax_writeback_range_done: OK [ 317.694162] Testing event dax_writeback_range: OK [ 317.730899] Testing event dax_insert_mapping: OK [ 317.767712] Testing event dax_insert_pfn_mkwrite: OK [ 317.810470] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 317.850169] Testing event dax_load_hole: OK [ 317.893282] Testing event dax_pte_fault_done: OK [ 317.934255] Testing event dax_pte_fault: OK [ 317.970486] Testing event dax_pmd_insert_mapping: OK [ 318.012709] Testing event dax_pmd_load_hole_fallback: OK [ 318.052300] Testing event dax_pmd_load_hole: OK [ 318.090010] Testing event dax_pmd_fault_done: OK [ 318.133651] Testing event dax_pmd_fault: OK [ 318.174094] Testing event generic_add_lease: OK [ 318.213672] Testing event time_out_leases: OK [ 318.249833] Testing event generic_delete_lease: OK [ 318.286052] Testing event break_lease_unblock: OK [ 318.326515] Testing event break_lease_block: OK [ 318.368237] Testing event break_lease_noblock: OK [ 318.414628] Testing event flock_lock_inode: OK [ 318.454210] Testing event locks_remove_posix: OK [ 318.493452] Testing event fcntl_setlk: OK [ 318.529849] Testing event posix_lock_inode: OK [ 318.572611] Testing event locks_get_lock_context: OK [ 318.610242] Testing event block_rq_remap: OK [ 318.649946] Testing event block_bio_remap: OK [ 318.693952] Testing event block_split: OK [ 318.730814] Testing event block_unplug: OK [ 318.767665] Testing event block_plug: OK [ 318.806904] Testing event block_sleeprq: OK [ 318.848579] Testing event block_getrq: OK [ 318.890800] Testing event block_bio_queue: OK [ 318.933119] Testing event block_bio_frontmerge: OK [ 318.970492] Testing event block_bio_backmerge: OK [ 319.012896] Testing event block_bio_complete: OK [ 319.053207] Testing event block_bio_bounce: OK [ 319.092644] Testing event block_rq_issue: OK [ 319.137422] Testing event block_rq_insert: OK [ 319.178479] Testing event block_rq_complete: OK [ 319.221133] Testing event block_rq_requeue: OK [ 319.262121] Testing event block_dirty_buffer: OK [ 319.300992] Testing event block_touch_buffer: OK [ 319.340406] Testing event wbt_timer: OK [ 319.382324] Testing event wbt_step: OK [ 319.422152] Testing event wbt_lat: OK [ 319.461293] Testing event wbt_stat: OK [ 319.505288] Testing event gpio_value: OK [ 319.545562] Testing event gpio_direction: OK [ 319.586496] Testing event clk_set_duty_cycle_complete: OK [ 319.626169] Testing event clk_set_duty_cycle: OK [ 319.665422] Testing event clk_set_phase_complete: OK [ 319.708820] Testing event clk_set_phase: OK [ 319.750194] Testing event clk_set_parent_complete: OK [ 319.789167] Testing event clk_set_parent: OK [ 319.830266] Testing event clk_set_rate_complete: OK [ 319.869195] Testing event clk_set_rate: OK [ 319.908936] Testing event clk_unprepare_complete: OK [ 319.949121] Testing event clk_unprepare: OK [ 319.990288] Testing event clk_prepare_complete: OK [ 320.026706] Testing event clk_prepare: OK [ 320.066562] Testing event clk_disable_complete: OK [ 320.106870] Testing event clk_disable: OK [ 320.149258] Testing event clk_enable_complete: OK [ 320.190211] Testing event clk_enable: OK [ 320.232966] Testing event rpmh_send_msg: OK [ 320.274862] Testing event rpmh_tx_done: OK [ 320.317977] Testing event regulator_set_voltage_complete: OK [ 320.361889] Testing event regulator_set_voltage: OK [ 320.404678] Testing event regulator_disable_complete: OK [ 320.449063] Testing event regulator_disable: OK [ 320.490467] Testing event regulator_enable_complete: OK [ 320.530614] Testing event regulator_enable_delay: OK [ 320.572160] Testing event regulator_enable: OK [ 320.617386] Testing event urandom_read: OK [ 320.661767] Testing event random_read: OK [ 320.702135] Testing event extract_entropy_user: OK [ 320.744633] Testing event extract_entropy: OK [ 320.789424] Testing event get_random_bytes_arch: OK [ 320.833927] Testing event get_random_bytes: OK [ 320.876579] Testing event xfer_secondary_pool: OK [ 320.921311] Testing event add_disk_randomness: OK [ 320.966088] Testing event add_input_randomness: OK [ 321.009085] Testing event debit_entropy: OK [ 321.048602] Testing event push_to_pool: OK [ 321.090420] Testing event credit_entropy_bits: OK [ 321.130149] Testing event mix_pool_bytes_nolock: OK [ 321.173882] Testing event mix_pool_bytes: OK [ 321.209845] Testing event add_device_randomness: OK [ 321.252779] Testing event io_page_fault: OK [ 321.290726] Testing event unmap: OK [ 321.332595] Testing event map: OK [ 321.376104] Testing event detach_device_from_domain: OK [ 321.416781] Testing event attach_device_to_domain: OK [ 321.456439] Testing event remove_device_from_group: OK [ 321.498675] Testing event add_device_to_group: OK [ 321.538241] Testing event regcache_drop_region: OK [ 321.577256] Testing event regmap_async_complete_done: OK [ 321.618191] Testing event regmap_async_complete_start: OK [ 321.660619] Testing event regmap_async_io_complete: OK [ 321.705409] Testing event regmap_async_write_start: OK [ 321.750019] Testing event regmap_cache_bypass: OK [ 321.792600] Testing event regmap_cache_only: OK [ 321.837260] Testing event regcache_sync: OK [ 321.878569] Testing event regmap_hw_write_done: OK [ 321.918403] Testing event regmap_hw_write_start: OK [ 321.958414] Testing event regmap_hw_read_done: OK [ 321.997675] Testing event regmap_hw_read_start: OK [ 322.041503] Testing event regmap_reg_read_cache: OK [ 322.086028] Testing event regmap_reg_read: OK [ 322.128583] Testing event regmap_reg_write: OK [ 322.173744] Testing event dma_fence_wait_end: OK [ 322.217277] Testing event dma_fence_wait_start: OK [ 322.258067] Testing event dma_fence_signaled: OK [ 322.297172] Testing event dma_fence_enable_signal: OK [ 322.342647] Testing event dma_fence_destroy: OK [ 322.385855] Testing event dma_fence_init: OK [ 322.426128] Testing event dma_fence_emit: OK [ 322.468950] Testing event sync_timeline: OK [ 322.506245] Testing event spi_transfer_stop: OK [ 322.549161] Testing event spi_transfer_start: OK [ 322.588816] Testing event spi_message_done: OK [ 322.629190] Testing event spi_message_start: OK [ 322.670161] Testing event spi_message_submit: OK [ 322.709279] Testing event spi_controller_busy: OK [ 322.750124] Testing event spi_controller_idle: OK [ 322.788862] Testing event rtc_timer_fired: OK [ 322.830796] Testing event rtc_timer_dequeue: OK [ 322.874817] Testing event rtc_timer_enqueue: OK [ 322.918553] Testing event rtc_read_offset: OK [ 322.958021] Testing event rtc_set_offset: OK [ 322.997045] Testing event rtc_alarm_irq_enable: OK [ 323.042878] Testing event rtc_irq_set_state: OK [ 323.082602] Testing event rtc_irq_set_freq: OK [ 323.124366] Testing event rtc_read_alarm: OK [ 323.158303] Testing event rtc_set_alarm: OK [ 323.198564] Testing event rtc_read_time: OK [ 323.240606] Testing event rtc_set_time: OK [ 323.282782] Testing event memory_failure_event: OK [ 323.322052] Testing event aer_event: OK [ 323.360825] Testing event non_standard_event: OK [ 323.402088] Testing event arm_event: OK [ 323.440865] Testing event mc_event: OK [ 323.484702] Testing event binder_return: OK [ 323.522551] Testing event binder_command: OK [ 323.564257] Testing event binder_unmap_kernel_end: OK [ 323.605577] Testing event binder_unmap_kernel_start: OK [ 323.650796] Testing event binder_unmap_user_end: OK [ 323.693888] Testing event binder_unmap_user_start: OK [ 323.734478] Testing event binder_alloc_page_end: OK [ 323.774047] Testing event binder_alloc_page_start: OK [ 323.812996] Testing event binder_free_lru_end: OK [ 323.852532] Testing event binder_free_lru_start: OK [ 323.896420] Testing event binder_alloc_lru_end: OK [ 323.934134] Testing event binder_alloc_lru_start: OK [ 323.977137] Testing event binder_update_page_range: OK [ 324.014560] Testing event binder_transaction_failed_buffer_release: OK [ 324.057026] Testing event binder_transaction_buffer_release: OK [ 324.096992] Testing event binder_transaction_alloc_buf: OK [ 324.134598] Testing event binder_transaction_fd_recv: OK [ 324.177006] Testing event binder_transaction_fd_send: OK [ 324.216889] Testing event binder_transaction_ref_to_ref: OK [ 324.258854] Testing event binder_transaction_ref_to_node: OK [ 324.301910] Testing event binder_transaction_node_to_ref: OK [ 324.345818] Testing event binder_transaction_received: OK [ 324.389172] Testing event binder_transaction: OK [ 324.430056] Testing event binder_wait_for_work: OK [ 324.469208] Testing event binder_read_done: OK [ 324.514688] Testing event binder_write_done: OK [ 324.554580] Testing event binder_ioctl_done: OK [ 324.596336] Testing event binder_unlock: OK [ 324.633926] Testing event binder_locked: OK [ 324.672790] Testing event binder_lock: OK [ 324.716333] Testing event binder_ioctl: OK [ 324.749993] Testing event br_fdb_update: OK [ 324.790488] Testing event fdb_delete: OK [ 324.826605] Testing event br_fdb_external_learn_add: OK [ 324.866619] Testing event br_fdb_add: OK [ 324.902590] Testing event qdisc_dequeue: OK [ 324.942532] Testing event fib_table_lookup: OK [ 324.984298] Testing event tcp_probe: OK [ 325.024851] Testing event tcp_retransmit_synack: OK [ 325.064916] Testing event tcp_rcv_space_adjust: OK [ 325.104882] Testing event tcp_destroy_sock: OK [ 325.150286] Testing event tcp_receive_reset: OK [ 325.192709] Testing event tcp_send_reset: OK [ 325.234344] Testing event tcp_retransmit_skb: OK [ 325.273035] Testing event udp_fail_queue_rcv_skb: OK [ 325.312899] Testing event inet_sock_set_state: OK [ 325.354219] Testing event sock_exceed_buf_limit: OK [ 325.397586] Testing event sock_rcvqueue_full: OK [ 325.438209] Testing event napi_poll: OK [ 325.478526] Testing event netif_receive_skb_list_exit: OK [ 325.514777] Testing event netif_rx_ni_exit: OK [ 325.562185] Testing event netif_rx_exit: OK [ 325.604584] Testing event netif_receive_skb_exit: OK [ 325.646824] Testing event napi_gro_receive_exit: OK [ 325.689961] Testing event napi_gro_frags_exit: OK [ 325.730365] Testing event netif_rx_ni_entry: OK [ 325.770098] Testing event netif_rx_entry: OK [ 325.809098] Testing event netif_receive_skb_list_entry: OK [ 325.850225] Testing event netif_receive_skb_entry: OK [ 325.889354] Testing event napi_gro_receive_entry: OK [ 325.925878] Testing event napi_gro_frags_entry: OK [ 325.969152] Testing event netif_rx: OK [ 326.009359] Testing event netif_receive_skb: OK [ 326.045829] Testing event net_dev_queue: OK [ 326.089137] Testing event net_dev_xmit: OK [ 326.128851] Testing event net_dev_start_xmit: OK [ 326.170395] Testing event skb_copy_datagram_iovec: OK [ 326.210153] Testing event consume_skb: OK [ 326.250442] Testing event kfree_skb: OK [ 326.290215] Testing event 9p_protocol_dump: OK [ 326.338188] Testing event 9p_client_res: OK [ 326.377232] Testing event 9p_client_req: OK [ 326.416306] Running tests on trace event systems: [ 326.417113] Testing event system 9p: OK [ 326.469439] Testing event system skb: OK [ 326.520397] Testing event system net: OK [ 326.585360] Testing event system napi: OK [ 326.638308] Testing event system sock: OK [ 326.684272] Testing event system udp: OK [ 326.719921] Testing event system tcp: OK [ 326.770593] Testing event system fib: OK [ 326.818190] Testing event system qdisc: OK [ 326.858605] Testing event system bridge: OK [ 326.900274] Testing event system binder: OK [ 326.977873] Testing event system ras: OK [ 327.052196] Testing event system rtc: OK [ 327.109451] Testing event system spi: OK [ 327.165751] Testing event system sync_trace: OK [ 327.209816] Testing event system dma_fence: OK [ 327.258752] Testing event system regmap: OK [ 327.318777] Testing event system iommu: OK [ 327.376955] Testing event system random: OK [ 327.440284] Testing event system regulator: OK [ 327.501580] Testing event system rpmh: OK [ 327.545952] Testing event system clk: OK [ 327.608585] Testing event system gpio: OK [ 327.661142] Testing event system wbt: OK [ 327.708404] Testing event system block: OK [ 327.777608] Testing event system filelock: OK [ 327.840386] Testing event system fs_dax: OK [ 327.900792] Testing event system writeback: OK [ 327.983761] Testing event system page_ref: OK [ 328.288505] Testing event system cma: OK [ 328.344790] Testing event system page_isolation: OK [ 328.385315] Testing event system huge_memory: OK [ 328.432561] Testing event system migrate: OK [ 328.473768] Testing event system compaction: OK [ 328.530898] Testing event system kmem: OK [ 328.598616] Testing event system percpu: OK [ 328.653237] Testing event system vmscan: OK [ 328.714549] Testing event system pagemap: OK [ 328.753422] Testing event system oom: OK [ 328.814407] Testing event system filemap: OK [ 328.869091] Testing event system rseq: OK [ 328.913941] Testing event system xdp: OK [ 328.967871] Testing event system benchmark: OK [ 329.105176] Testing event system rpm: OK [ 329.152895] Testing event system power: OK [ 329.233551] Testing event system test: OK [ 329.269773] Testing event system preemptirq: OK [ 329.361008] Testing event system cgroup: OK [ 329.416561] Testing event system module: OK [ 329.469693] Testing event system alarmtimer: OK [ 329.515886] Testing event system timer: OK [ 329.579840] Testing event system swiotlb: OK [ 329.626292] Testing event system rcu: OK [ 329.721368] Testing event system printk: OK [ 329.775934] Testing event system lock: OK [ 329.868680] Testing event system sched: OK [ 329.944569] Testing event system workqueue: OK [ 330.002901] Testing event system signal: OK [ 330.045553] Testing event system irq: OK [ 330.093067] Testing event system cpuhp: OK [ 330.145993] Testing event system task: OK [ 330.190077] Testing event system kvm: OK [ 330.272813] Testing event system emulation: OK [ 330.342305] Testing event system ipi: OK [ 330.390840] Testing event system syscalls: OK [ 330.472066] Testing event system raw_syscalls: OK [ 330.514137] Testing event system initcall: OK [ 330.568306] Running tests on all trace events: [ 330.568967] Testing all events: [ 332.645136] event_benchmark (694) used greatest stack depth: 10992 bytes left [ 333.058503] OK [ 333.074494] ftrace_replace_code: 1 [ 391.569390] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 391.590269] Running tests again, along with the function tracer [ 391.598445] Running tests on trace events: [ 391.610167] Testing event initcall_finish: OK [ 391.957877] Testing event initcall_start: OK [ 392.266816] Testing event initcall_level: OK [ 392.578227] Testing event sys_exit: OK [ 392.953891] Testing event sys_enter: OK [ 393.297801] Testing event ipi_exit: OK [ 393.653789] Testing event ipi_entry: OK [ 393.986158] Testing event ipi_raise: OK [ 394.330988] Testing event instruction_emulation: OK [ 394.666370] Testing event kvm_halt_poll_ns: OK [ 394.993927] Testing event kvm_age_page: OK [ 395.373859] Testing event kvm_fpu: OK [ 395.702340] Testing event kvm_mmio: OK [ 396.086092] Testing event kvm_ack_irq: OK [ 396.386109] Testing event kvm_set_irq: OK [ 396.726471] Testing event kvm_vcpu_wakeup: OK [ 397.049803] Testing event kvm_userspace_exit: OK [ 397.350822] Testing event kvm_timer_update_irq: OK [ 397.689914] Testing event kvm_toggle_cache: OK [ 398.041776] Testing event kvm_set_way_flush: OK [ 398.398065] Testing event kvm_test_age_hva: OK [ 398.718207] Testing event kvm_age_hva: OK [ 399.053952] Testing event kvm_set_spte_hva: OK [ 399.410135] Testing event kvm_unmap_hva_range: OK [ 399.769811] Testing event kvm_mmio_emulate: OK [ 400.090124] Testing event kvm_irq_line: OK [ 400.414458] Testing event kvm_access_fault: OK [ 400.789665] Testing event kvm_guest_fault: OK [ 401.114970] Testing event kvm_exit: OK [ 401.437538] Testing event kvm_entry: OK [ 401.767788] Testing event kvm_set_guest_debug: OK [ 402.122343] Testing event kvm_handle_sys_reg: OK [ 402.486097] Testing event trap_reg: OK [ 402.814009] Testing event kvm_arm_set_regset: OK [ 403.154300] Testing event kvm_arm_set_dreg32: OK [ 403.602166] Testing event kvm_arm_clear_debug: OK [ 403.933855] Testing event kvm_arm_setup_debug: OK [ 404.265921] Testing event kvm_hvc_arm64: OK [ 404.606148] Testing event kvm_wfx_arm64: OK [ 404.994086] Testing event vgic_update_irq_pending: OK [ 405.326130] Testing event task_rename: OK [ 405.645744] Testing event task_newtask: OK [ 405.993992] Testing event cpuhp_exit: OK [ 406.314407] Testing event cpuhp_multi_enter: OK [ 406.681980] Testing event cpuhp_enter: OK [ 407.010182] Testing event softirq_raise: OK [ 407.310034] Testing event softirq_exit: OK [ 407.654685] Testing event softirq_entry: OK [ 408.002308] Testing event irq_handler_exit: OK [ 408.338042] Testing event irq_handler_entry: OK [ 408.685921] Testing event signal_deliver: OK [ 409.002043] Testing event signal_generate: OK [ 409.318966] Testing event workqueue_execute_end: OK [ 409.681632] Testing event workqueue_execute_start: OK [ 410.054187] Testing event workqueue_activate_work: OK [ 410.390004] Testing event workqueue_queue_work: OK [ 410.738313] Testing event sched_wake_idle_without_ipi: OK [ 411.105859] Testing event sched_swap_numa: OK [ 411.466126] Testing event sched_stick_numa: OK [ 411.818402] Testing event sched_move_numa: OK [ 412.130326] Testing event sched_process_hang: OK [ 412.450024] Testing event sched_pi_setprio: OK [ 412.833925] Testing event sched_stat_runtime: OK [ 413.225785] Testing event sched_stat_blocked: OK [ 413.546158] Testing event sched_stat_iowait: OK [ 413.909917] Testing event sched_stat_sleep: OK [ 414.238263] Testing event sched_stat_wait: OK [ 414.630212] Testing event sched_process_exec: OK [ 414.998092] Testing event sched_process_fork: OK [ 415.422547] Testing event sched_process_wait: OK [ 415.761992] Testing event sched_wait_task: OK [ 416.049873] Testing event sched_process_exit: OK [ 416.401717] Testing event sched_process_free: OK [ 416.766092] Testing event sched_migrate_task: OK [ 417.102459] Testing event sched_switch: OK [ 417.402627] Testing event sched_wakeup_new: OK [ 417.762054] Testing event sched_wakeup: OK [ 418.061909] Testing event sched_waking: OK [ 418.371988] Testing event sched_kthread_stop_ret: OK [ 418.705942] Testing event sched_kthread_stop: OK [ 419.038109] Testing event lock_acquired: OK [ 419.638239] Testing event lock_contended: OK [ 419.954331] Testing event lock_release: OK [ 420.377848] Testing event lock_acquire: OK [ 420.809892] Testing event console: OK [ 421.114252] Testing event rcu_barrier: OK [ 421.422536] Testing event rcu_torture_read: OK [ 421.738353] Testing event rcu_batch_end: OK [ 422.066264] Testing event rcu_invoke_kfree_callback: OK [ 422.378223] Testing event rcu_invoke_callback: OK [ 422.750357] Testing event rcu_batch_start: OK [ 423.073912] Testing event rcu_kfree_callback: OK [ 423.434772] Testing event rcu_callback: OK [ 423.757938] Testing event rcu_dyntick: OK [ 424.146223] Testing event rcu_fqs: OK [ 424.498276] Testing event rcu_quiescent_state_report: OK [ 424.849722] Testing event rcu_unlock_preempted_task: OK [ 425.162765] Testing event rcu_preempt_task: OK [ 425.518584] Testing event rcu_nocb_wake: OK [ 425.866378] Testing event rcu_exp_funnel_lock: OK [ 426.246285] Testing event rcu_exp_grace_period: OK [ 426.654802] Testing event rcu_grace_period_init: OK [ 427.002880] Testing event rcu_future_grace_period: OK [ 427.354791] Testing event rcu_grace_period: OK [ 427.730174] Testing event rcu_utilization: OK [ 428.118356] Testing event swiotlb_bounced: OK [ 428.446219] Testing event tick_stop: OK [ 428.745799] Testing event itimer_expire: OK [ 429.058309] Testing event itimer_state: OK [ 429.425822] Testing event hrtimer_cancel: OK [ 429.770415] Testing event hrtimer_expire_exit: OK [ 430.098238] Testing event hrtimer_expire_entry: OK [ 430.446159] Testing event hrtimer_start: OK [ 430.821757] Testing event hrtimer_init: OK [ 431.134336] Testing event timer_cancel: OK [ 431.478659] Testing event timer_expire_exit: OK [ 431.818008] Testing event timer_expire_entry: OK [ 432.314486] Testing event timer_start: OK [ 432.717775] Testing event timer_init: OK [ 433.081625] Testing event alarmtimer_cancel: OK [ 433.401817] Testing event alarmtimer_start: OK [ 433.750413] Testing event alarmtimer_fired: OK [ 434.105963] Testing event alarmtimer_suspend: OK [ 434.394595] Testing event module_request: OK [ 434.726307] Testing event module_put: OK [ 435.069765] Testing event module_get: OK [ 435.418067] Testing event module_free: OK [ 435.762226] Testing event module_load: OK [ 436.166279] Testing event cgroup_transfer_tasks: OK [ 436.453810] Testing event cgroup_attach_task: OK [ 436.765770] Testing event cgroup_rename: OK [ 437.090110] Testing event cgroup_release: OK [ 437.450039] Testing event cgroup_rmdir: OK [ 437.793970] Testing event cgroup_mkdir: OK [ 438.062420] Testing event cgroup_remount: OK [ 438.382093] Testing event cgroup_destroy_root: OK [ 438.790463] Testing event cgroup_setup_root: OK [ 439.121774] Testing event irq_enable: OK [ 439.706464] Testing event irq_disable: OK [ 440.385556] Testing event ftrace_test_filter: OK [ 440.733797] Testing event dev_pm_qos_remove_request: OK [ 441.045853] Testing event dev_pm_qos_update_request: OK [ 441.418272] Testing event dev_pm_qos_add_request: OK [ 441.746048] Testing event pm_qos_update_flags: OK [ 442.109708] Testing event pm_qos_update_target: OK [ 442.454230] Testing event pm_qos_update_request_timeout: OK [ 442.822210] Testing event pm_qos_remove_request: OK [ 443.221778] Testing event pm_qos_update_request: OK [ 443.522406] Testing event pm_qos_add_request: OK [ 443.829868] Testing event power_domain_target: OK [ 444.206358] Testing event clock_set_rate: OK [ 444.514387] Testing event clock_disable: OK [ 444.830888] Testing event clock_enable: OK [ 445.190085] Testing event wakeup_source_deactivate: OK [ 445.562091] Testing event wakeup_source_activate: OK [ 445.905927] Testing event suspend_resume: OK [ 446.421866] Testing event device_pm_callback_end: OK [ 446.802483] Testing event device_pm_callback_start: OK [ 447.158254] Testing event cpu_frequency_limits: OK [ 447.510064] Testing event cpu_frequency: OK [ 447.834150] Testing event pstate_sample: OK [ 448.213846] Testing event powernv_throttle: OK [ 448.589894] Testing event cpu_idle: OK [ 448.893619] Testing event rpm_return_int: OK [ 449.205860] Testing event rpm_idle: OK [ 449.566964] Testing event rpm_resume: OK [ 449.857796] Testing event rpm_suspend: OK [ 450.174272] Testing event benchmark_event: OK [ 450.681921] Testing event xdp_devmap_xmit: OK [ 451.045837] Testing event xdp_cpumap_enqueue: OK [ 451.374156] Testing event xdp_cpumap_kthread: OK [ 451.707862] Testing event xdp_redirect_map_err: OK [ 452.042055] Testing event xdp_redirect_map: OK [ 452.398066] Testing event xdp_redirect_err: OK [ 452.806186] Testing event xdp_redirect: OK [ 453.162484] Testing event xdp_exception: OK [ 453.586366] Testing event rseq_ip_fixup: OK [ 453.957608] Testing event rseq_update: OK [ 454.350862] Testing event file_check_and_advance_wb_err: OK [ 454.674433] Testing event filemap_set_wb_err: OK [ 454.957902] Testing event mm_filemap_add_to_page_cache: OK [ 455.278216] Testing event mm_filemap_delete_from_page_cache: OK [ 455.642184] Testing event compact_retry: OK [ 455.998259] Testing event skip_task_reaping: OK [ 456.273985] Testing event finish_task_reaping: OK [ 456.642048] Testing event start_task_reaping: OK [ 456.941813] Testing event wake_reaper: OK [ 457.246075] Testing event mark_victim: OK [ 457.605783] Testing event reclaim_retry_zone: OK [ 457.933829] Testing event oom_score_adj_update: OK [ 458.278089] Testing event mm_lru_activate: OK [ 458.605892] Testing event mm_lru_insertion: OK [ 458.969507] Testing event mm_vmscan_inactive_list_is_low: OK [ 459.314458] Testing event mm_vmscan_lru_shrink_active: OK [ 459.690079] Testing event mm_vmscan_lru_shrink_inactive: OK [ 460.042464] Testing event mm_vmscan_writepage: OK [ 460.346277] Testing event mm_vmscan_lru_isolate: OK [ 460.677621] Testing event mm_shrink_slab_end: OK [ 461.022253] Testing event mm_shrink_slab_start: OK [ 461.390746] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 461.730148] Testing event mm_vmscan_memcg_reclaim_end: OK [ 462.098777] Testing event mm_vmscan_direct_reclaim_end: OK [ 462.450272] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 462.839888] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 463.130381] Testing event mm_vmscan_direct_reclaim_begin: OK [ 463.494061] Testing event mm_vmscan_wakeup_kswapd: OK [ 463.841953] Testing event mm_vmscan_kswapd_wake: OK [ 464.149813] Testing event mm_vmscan_kswapd_sleep: OK [ 464.573903] Testing event percpu_destroy_chunk: OK [ 464.918097] Testing event percpu_create_chunk: OK [ 465.186367] Testing event percpu_alloc_percpu_fail: OK [ 465.533949] Testing event percpu_free_percpu: OK [ 465.910903] Testing event percpu_alloc_percpu: OK [ 466.230393] Testing event mm_fragmentation_stall: OK [ 466.538199] Testing event mm_page_alloc_extfrag: OK [ 466.870799] Testing event mm_page_pcpu_drain: OK [ 467.209886] Testing event mm_page_alloc_zone_locked: OK [ 467.558274] Testing event mm_page_alloc: OK [ 467.885773] Testing event mm_page_free_batched: OK [ 468.254120] Testing event mm_page_free: OK [ 468.609924] Testing event kmem_cache_free: OK [ 468.962217] Testing event kfree: OK [ 469.297604] Testing event kmem_cache_alloc_node: OK [ 469.673994] Testing event kmalloc_node: OK [ 469.997992] Testing event kmem_cache_alloc: OK [ 470.322604] Testing event kmalloc: OK [ 470.726206] Testing event mm_compaction_kcompactd_wake: OK [ 471.065611] Testing event mm_compaction_wakeup_kcompactd: OK [ 471.358159] Testing event mm_compaction_kcompactd_sleep: OK [ 471.682220] Testing event mm_compaction_defer_reset: OK [ 472.034395] Testing event mm_compaction_defer_compaction: OK [ 472.398091] Testing event mm_compaction_deferred: OK [ 472.737948] Testing event mm_compaction_suitable: OK [ 473.094439] Testing event mm_compaction_finished: OK [ 473.470013] Testing event mm_compaction_try_to_compact_pages: OK [ 473.822545] Testing event mm_compaction_end: OK [ 474.157984] Testing event mm_compaction_begin: OK [ 474.469574] Testing event mm_compaction_migratepages: OK [ 474.806254] Testing event mm_compaction_isolate_freepages: OK [ 475.170454] Testing event mm_compaction_isolate_migratepages: OK [ 475.558240] Testing event mm_migrate_pages: OK [ 475.866083] Testing event mm_collapse_huge_page_swapin: OK [ 476.197865] Testing event mm_collapse_huge_page_isolate: OK [ 476.518424] Testing event mm_collapse_huge_page: OK [ 476.882879] Testing event mm_khugepaged_scan_pmd: OK [ 477.217989] Testing event test_pages_isolated: OK [ 477.549850] Testing event cma_release: OK [ 477.869861] Testing event cma_alloc: OK [ 478.170461] Testing event page_ref_unfreeze: OK [ 478.594668] Testing event page_ref_freeze: OK [ 478.930539] Testing event page_ref_mod_unless: OK [ 479.394023] Testing event page_ref_mod_and_return: OK [ 479.738341] Testing event page_ref_mod_and_test: OK [ 481.225773] Testing event page_ref_mod: OK [ 481.838116] Testing event page_ref_set: OK [ 482.262224] Testing event sb_clear_inode_writeback: OK [ 482.585945] Testing event sb_mark_inode_writeback: OK [ 482.934892] Testing event writeback_dirty_inode_enqueue: OK [ 483.350228] Testing event writeback_lazytime_iput: OK [ 483.686387] Testing event writeback_lazytime: OK [ 484.006717] Testing event writeback_single_inode: OK [ 484.314375] Testing event writeback_single_inode_start: OK [ 484.662291] Testing event writeback_wait_iff_congested: OK [ 485.045795] Testing event writeback_congestion_wait: OK [ 485.362239] Testing event writeback_sb_inodes_requeue: OK [ 485.706284] Testing event balance_dirty_pages: OK [ 486.090045] Testing event bdi_dirty_ratelimit: OK [ 486.389943] Testing event global_dirty_state: OK [ 486.730290] Testing event writeback_queue_io: OK [ 487.062378] Testing event wbc_writepage: OK [ 487.362152] Testing event writeback_bdi_register: OK [ 487.718380] Testing event writeback_wake_background: OK [ 488.078408] Testing event writeback_pages_written: OK [ 488.406238] Testing event writeback_wait: OK [ 488.750418] Testing event writeback_written: OK [ 489.066184] Testing event writeback_start: OK [ 489.434333] Testing event writeback_exec: OK [ 489.738896] Testing event writeback_queue: OK [ 490.058094] Testing event writeback_write_inode: OK [ 490.537779] Testing event writeback_write_inode_start: OK [ 490.977968] Testing event writeback_dirty_inode: OK [ 491.329828] Testing event writeback_dirty_inode_start: OK [ 491.650010] Testing event writeback_mark_inode_dirty: OK [ 491.978179] Testing event writeback_dirty_page: OK [ 492.354109] Testing event dax_writeback_one: OK [ 492.669915] Testing event dax_writeback_range_done: OK [ 493.010061] Testing event dax_writeback_range: OK [ 493.350727] Testing event dax_insert_mapping: OK [ 493.701650] Testing event dax_insert_pfn_mkwrite: OK [ 494.050213] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 494.417678] Testing event dax_load_hole: OK [ 494.773664] Testing event dax_pte_fault_done: OK [ 495.122294] Testing event dax_pte_fault: OK [ 495.405729] Testing event dax_pmd_insert_mapping: OK [ 495.738011] Testing event dax_pmd_load_hole_fallback: OK [ 496.106301] Testing event dax_pmd_load_hole: OK [ 496.430845] Testing event dax_pmd_fault_done: OK [ 496.725957] Testing event dax_pmd_fault: OK [ 497.085938] Testing event generic_add_lease: OK [ 497.426123] Testing event time_out_leases: OK [ 497.754933] Testing event generic_delete_lease: OK [ 498.086210] Testing event break_lease_unblock: OK [ 498.450300] Testing event break_lease_block: OK [ 498.757723] Testing event break_lease_noblock: OK [ 499.105968] Testing event flock_lock_inode: OK [ 499.422297] Testing event locks_remove_posix: OK [ 499.725542] Testing event fcntl_setlk: OK [ 500.050595] Testing event posix_lock_inode: OK [ 500.386325] Testing event locks_get_lock_context: OK [ 500.797837] Testing event block_rq_remap: OK [ 501.074006] Testing event block_bio_remap: OK [ 501.410152] Testing event block_split: OK [ 501.749682] Testing event block_unplug: OK [ 502.070336] Testing event block_plug: OK [ 502.418120] Testing event block_sleeprq: OK [ 502.746743] Testing event block_getrq: OK [ 503.081804] Testing event block_bio_queue: OK [ 503.494381] Testing event block_bio_frontmerge: OK [ 503.801870] Testing event block_bio_backmerge: OK [ 504.141868] Testing event block_bio_complete: OK [ 504.525884] Testing event block_bio_bounce: OK [ 504.814729] Testing event block_rq_issue: OK [ 505.141931] Testing event block_rq_insert: OK [ 505.518270] Testing event block_rq_complete: OK [ 505.870192] Testing event block_rq_requeue: OK [ 506.185972] Testing event block_dirty_buffer: OK [ 506.486844] Testing event block_touch_buffer: OK [ 506.841834] Testing event wbt_timer: OK [ 507.141772] Testing event wbt_step: OK [ 507.518227] Testing event wbt_lat: OK [ 507.882537] Testing event wbt_stat: OK [ 508.245756] Testing event gpio_value: OK [ 508.597986] Testing event gpio_direction: OK [ 508.893975] Testing event clk_set_duty_cycle_complete: OK [ 509.218105] Testing event clk_set_duty_cycle: OK [ 509.594303] Testing event clk_set_phase_complete: OK [ 509.922181] Testing event clk_set_phase: OK [ 510.262405] Testing event clk_set_parent_complete: OK [ 510.602448] Testing event clk_set_parent: OK [ 510.970366] Testing event clk_set_rate_complete: OK [ 511.266314] Testing event clk_set_rate: OK [ 511.606555] Testing event clk_unprepare_complete: OK [ 511.977986] Testing event clk_unprepare: OK [ 512.314105] Testing event clk_prepare_complete: OK [ 512.642246] Testing event clk_prepare: OK [ 513.086120] Testing event clk_disable_complete: OK [ 513.465936] Testing event clk_disable: OK [ 513.769953] Testing event clk_enable_complete: OK [ 514.109842] Testing event clk_enable: OK [ 514.418608] Testing event rpmh_send_msg: OK [ 514.745944] Testing event rpmh_tx_done: OK [ 514.994119] Testing event regulator_set_voltage_complete: OK [ 515.350193] Testing event regulator_set_voltage: OK [ 515.714172] Testing event regulator_disable_complete: OK [ 516.069957] Testing event regulator_disable: OK [ 516.422386] Testing event regulator_enable_complete: OK [ 516.790171] Testing event regulator_enable_delay: OK [ 517.138438] Testing event regulator_enable: OK [ 517.510290] Testing event urandom_read: OK [ 517.869601] Testing event random_read: OK [ 518.202307] Testing event extract_entropy_user: OK [ 518.510311] Testing event extract_entropy: OK [ 518.854393] Testing event get_random_bytes_arch: OK [ 519.194258] Testing event get_random_bytes: OK [ 519.593865] Testing event xfer_secondary_pool: OK [ 519.910010] Testing event add_disk_randomness: OK [ 520.233819] Testing event add_input_randomness: OK [ 520.589732] Testing event debit_entropy: OK [ 520.921690] Testing event push_to_pool: OK [ 521.247013] Testing event credit_entropy_bits: OK [ 521.614451] Testing event mix_pool_bytes_nolock: OK [ 521.973741] Testing event mix_pool_bytes: OK [ 522.306649] Testing event add_device_randomness: OK [ 522.661995] Testing event io_page_fault: OK [ 523.025970] Testing event unmap: OK [ 523.349740] Testing event map: OK [ 523.698771] Testing event detach_device_from_domain: OK [ 524.010568] Testing event attach_device_to_domain: OK [ 524.369928] Testing event remove_device_from_group: OK [ 524.682010] Testing event add_device_to_group: OK [ 525.050334] Testing event regcache_drop_region: OK [ 525.346232] Testing event regmap_async_complete_done: OK [ 525.682112] Testing event regmap_async_complete_start: OK [ 526.045825] Testing event regmap_async_io_complete: OK [ 526.390457] Testing event regmap_async_write_start: OK [ 526.742865] Testing event regmap_cache_bypass: OK [ 527.130343] Testing event regmap_cache_only: OK [ 527.454318] Testing event regcache_sync: OK [ 527.810314] Testing event regmap_hw_write_done: OK [ 528.193826] Testing event regmap_hw_write_start: OK [ 528.530342] Testing event regmap_hw_read_done: OK [ 528.890021] Testing event regmap_hw_read_start: OK [ 529.162025] Testing event regmap_reg_read_cache: OK [ 529.530444] Testing event regmap_reg_read: OK [ 529.875808] Testing event regmap_reg_write: OK [ 530.202111] Testing event dma_fence_wait_end: OK [ 530.542098] Testing event dma_fence_wait_start: OK [ 530.869807] Testing event dma_fence_signaled: OK [ 531.234313] Testing event dma_fence_enable_signal: OK [ 531.570249] Testing event dma_fence_destroy: OK [ 531.918262] Testing event dma_fence_init: OK [ 532.254094] Testing event dma_fence_emit: OK [ 532.622274] Testing event sync_timeline: OK [ 532.982385] Testing event spi_transfer_stop: OK [ 533.298148] Testing event spi_transfer_start: OK [ 533.614052] Testing event spi_message_done: OK [ 533.978685] Testing event spi_message_start: OK [ 534.366052] Testing event spi_message_submit: OK [ 534.714276] Testing event spi_controller_busy: OK [ 535.054138] Testing event spi_controller_idle: OK [ 535.318156] Testing event rtc_timer_fired: OK [ 535.674143] Testing event rtc_timer_dequeue: OK [ 536.089893] Testing event rtc_timer_enqueue: OK [ 536.458730] Testing event rtc_read_offset: OK [ 536.778065] Testing event rtc_set_offset: OK [ 537.114308] Testing event rtc_alarm_irq_enable: OK [ 537.478358] Testing event rtc_irq_set_state: OK [ 537.862272] Testing event rtc_irq_set_freq: OK [ 538.205681] Testing event rtc_read_alarm: OK [ 538.602275] Testing event rtc_set_alarm: OK [ 538.925625] Testing event rtc_read_time: OK [ 539.242428] Testing event rtc_set_time: OK [ 539.590819] Testing event memory_failure_event: OK [ 539.974119] Testing event aer_event: OK [ 540.333627] Testing event non_standard_event: OK [ 540.706618] Testing event arm_event: OK [ 541.142732] Testing event mc_event: OK [ 541.414558] Testing event binder_return: OK [ 541.766585] Testing event binder_command: OK [ 542.129942] Testing event binder_unmap_kernel_end: OK [ 542.445862] Testing event binder_unmap_kernel_start: OK [ 542.810388] Testing event binder_unmap_user_end: OK [ 543.154024] Testing event binder_unmap_user_start: OK [ 543.458924] Testing event binder_alloc_page_end: OK [ 543.789967] Testing event binder_alloc_page_start: OK [ 544.149917] Testing event binder_free_lru_end: OK [ 544.502088] Testing event binder_free_lru_start: OK [ 544.853842] Testing event binder_alloc_lru_end: OK [ 545.185855] Testing event binder_alloc_lru_start: OK [ 545.522119] Testing event binder_update_page_range: OK [ 545.898175] Testing event binder_transaction_failed_buffer_release: OK [ 546.230435] Testing event binder_transaction_buffer_release: OK [ 546.626255] Testing event binder_transaction_alloc_buf: OK [ 546.986846] Testing event binder_transaction_fd_recv: OK [ 547.294225] Testing event binder_transaction_fd_send: OK [ 547.666847] Testing event binder_transaction_ref_to_ref: OK [ 548.122484] Testing event binder_transaction_ref_to_node: OK [ 548.418508] Testing event binder_transaction_node_to_ref: OK [ 548.774328] Testing event binder_transaction_received: OK [ 549.110098] Testing event binder_transaction: OK [ 549.458308] Testing event binder_wait_for_work: OK [ 549.850338] Testing event binder_read_done: OK [ 550.266453] Testing event binder_write_done: OK [ 550.597852] Testing event binder_ioctl_done: OK [ 550.897967] Testing event binder_unlock: OK [ 551.237881] Testing event binder_locked: OK [ 551.683962] Testing event binder_lock: OK [ 552.038291] Testing event binder_ioctl: OK [ 552.401678] Testing event br_fdb_update: OK [ 552.753595] Testing event fdb_delete: OK [ 553.082560] Testing event br_fdb_external_learn_add: OK [ 553.426143] Testing event br_fdb_add: OK [ 553.758022] Testing event qdisc_dequeue: OK [ 554.118102] Testing event fib_table_lookup: OK [ 554.525847] Testing event tcp_probe: OK [ 554.913685] Testing event tcp_retransmit_synack: OK [ 555.274485] Testing event tcp_rcv_space_adjust: OK [ 555.633959] Testing event tcp_destroy_sock: OK [ 556.006041] Testing event tcp_receive_reset: OK [ 556.329873] Testing event tcp_send_reset: OK [ 556.730323] Testing event tcp_retransmit_skb: OK [ 557.078386] Testing event udp_fail_queue_rcv_skb: OK [ 557.390290] Testing event inet_sock_set_state: OK [ 557.697797] Testing event sock_exceed_buf_limit: OK [ 558.062119] Testing event sock_rcvqueue_full: OK [ 558.466194] Testing event napi_poll: OK [ 558.829844] Testing event netif_receive_skb_list_exit: OK [ 559.158497] Testing event netif_rx_ni_exit: OK [ 559.514116] Testing event netif_rx_exit: OK [ 559.854371] Testing event netif_receive_skb_exit: OK [ 560.230111] Testing event napi_gro_receive_exit: OK [ 560.602394] Testing event napi_gro_frags_exit: OK [ 560.945895] Testing event netif_rx_ni_entry: OK [ 561.306094] Testing event netif_rx_entry: OK [ 561.618438] Testing event netif_receive_skb_list_entry: OK [ 561.942366] Testing event netif_receive_skb_entry: OK [ 562.306158] Testing event napi_gro_receive_entry: OK [ 562.634326] Testing event napi_gro_frags_entry: OK [ 562.990226] Testing event netif_rx: OK [ 563.306343] Testing event netif_receive_skb: OK [ 563.642974] Testing event net_dev_queue: OK [ 564.042259] Testing event net_dev_xmit: OK [ 564.386157] Testing event net_dev_start_xmit: OK [ 564.730362] Testing event skb_copy_datagram_iovec: OK [ 565.042417] Testing event consume_skb: OK [ 565.398325] Testing event kfree_skb: OK [ 565.789845] Testing event 9p_protocol_dump: OK [ 566.211901] Testing event 9p_client_res: OK [ 566.574292] Testing event 9p_client_req: OK [ 566.897951] Running tests on trace event systems: [ 566.910514] Testing event system 9p: OK [ 567.382665] Testing event system skb: OK [ 567.845990] Testing event system net: OK [ 568.365977] Testing event system napi: OK [ 568.878556] Testing event system sock: OK [ 569.197842] Testing event system udp: OK [ 569.585851] Testing event system tcp: OK [ 570.010174] Testing event system fib: OK [ 570.361717] Testing event system qdisc: OK [ 570.702433] Testing event system bridge: OK [ 571.070398] Testing event system binder: OK [ 571.838016] Testing event system ras: OK [ 572.422543] Testing event system rtc: OK [ 572.958647] Testing event system spi: OK [ 573.362363] Testing event system sync_trace: OK [ 573.762038] Testing event system dma_fence: OK [ 574.193831] Testing event system regmap: OK [ 574.765808] Testing event system iommu: OK [ 575.306566] Testing event system random: OK [ 575.857587] Testing event system regulator: OK [ 576.334090] Testing event system rpmh: OK [ 576.774377] Testing event system clk: OK [ 577.362158] Testing event system gpio: OK [ 577.810700] Testing event system wbt: OK [ 578.150104] Testing event system block: OK [ 578.702242] Testing event system filelock: OK [ 579.346177] Testing event system fs_dax: OK [ 579.914330] Testing event system writeback: OK [ 580.746238] Testing event system page_ref: OK [ 582.498243] Testing event system cma: OK [ 582.958627] Testing event system page_isolation: OK [ 583.281778] Testing event system huge_memory: OK [ 583.709976] Testing event system migrate: OK [ 584.070375] Testing event system compaction: OK [ 584.562316] Testing event system kmem: OK [ 585.153985] Testing event system percpu: OK [ 585.686284] Testing event system vmscan: OK [ 586.189793] Testing event system pagemap: OK [ 586.634417] Testing event system oom: OK [ 587.014404] Testing event system filemap: OK [ 587.450035] Testing event system rseq: OK [ 587.810501] Testing event system xdp: OK [ 588.342544] Testing event system benchmark: OK [ 588.910344] Testing event system rpm: OK [ 589.334524] Testing event system power: OK [ 590.010283] Testing event system test: OK [ 590.435845] Testing event system preemptirq: OK [ 591.797970] Testing event system cgroup: OK [ 592.333801] Testing event system module: OK [ 592.906545] Testing event system alarmtimer: OK [ 593.342356] Testing event system timer: OK [ 593.966945] Testing event system swiotlb: OK [ 594.386296] Testing event system rcu: OK [ 595.150401] Testing event system printk: OK [ 595.645721] Testing event system lock: OK [ 596.342542] Testing event system sched: OK [ 597.074206] Testing event system workqueue: OK [ 597.601812] Testing event system signal: OK [ 597.938121] Testing event system irq: OK [ 598.366380] Testing event system cpuhp: OK [ 598.814061] Testing event system task: OK [ 599.246678] Testing event system kvm: OK [ 599.997972] Testing event system emulation: OK [ 600.646128] Testing event system ipi: OK [ 601.009532] Testing event system syscalls: OK [ 601.686166] Testing event system raw_syscalls: OK [ 602.118010] Testing event system initcall: OK [ 602.590512] Running tests on all trace events: [ 602.602541] Testing all events: OK [ 644.038303] ftrace_replace_code: 1 [ 655.002986] ../kernel/trace/ftrace.c:2454, loop_counter: 42047 [ 655.013490] Testing ftrace filter: OK [ 655.245054] trace_kprobe: Testing kprobe tracing: OK [ 656.121190] Loading compiled-in X.509 certificates [ 656.278350] _warn_unseeded_randomness: 3 callbacks suppressed [ 656.278664] random: get_random_bytes called from key_alloc+0x500/0xa48 with crng_init=0 [ 656.284728] Loaded X.509 cert 'Build time autogenerated kernel key: 795061f1cca26c7f015c6d8e65f9ff7bd66db670' [ 656.313856] random: get_random_u64 called from kmem_cache_open+0x78/0x3c8 with crng_init=0 [ 656.316595] random: get_random_u64 called from cache_random_seq_create+0xd0/0x1a8 with crng_init=0 [ 656.372575] zswap: default zpool zbud not available [ 656.374208] zswap: pool creation failed [ 656.420083] page_owner is disabled [ 656.705283] Key type big_key registered [ 656.844304] Key type encrypted registered [ 656.852470] ima: No TPM chip found, activating TPM-bypass! [ 656.855936] ima: Allocated hash algorithm: sha1 [ 656.861168] No architecture policies found [ 656.874598] evm: Initialising EVM extended attributes: [ 656.876704] evm: security.selinux [ 656.877826] evm: security.SMACK64 [ 656.878891] evm: security.SMACK64EXEC [ 656.880738] evm: security.SMACK64TRANSMUTE [ 656.881893] evm: security.SMACK64MMAP [ 656.882965] evm: security.apparmor [ 656.884771] evm: security.ima [ 656.885808] evm: security.capability [ 656.886915] evm: HMAC attrs: 0x1 [ 656.987729] hctosys: unable to open rtc device (rtc0) [ 657.023682] psci_checker: PSCI checker started using 1 CPUs [ 657.024776] psci_checker: Starting hotplug tests [ 657.028135] psci_checker: Trying to turn off and on again all CPUs [ 657.030009] psci_checker: Trying to turn off and on again group 0 (CPUs 0) [ 657.033037] psci_checker: Hotplug tests passed OK [ 657.034232] psci_checker: Starting suspend tests (10 cycles per state) [ 657.036651] psci_checker: cpuidle not available on CPU 0, ignoring [ 657.038557] psci_checker: Could not start suspend tests on any CPU [ 657.040632] psci_checker: PSCI checker completed [ 657.128516] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 657.458102] ### dt-test ### start of unittest - you will see error messages [ 657.507978] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 657.510314] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 657.513367] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 657.515892] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 657.517730] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 657.520054] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 657.550706] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 657.554335] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 657.557839] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 657.978473] _warn_unseeded_randomness: 2 callbacks suppressed [ 657.978736] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 657.981023] kobject: 'testcase-data:platform-tests:test-device@1' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 658.008592] kobject: 'testcase-data:platform-tests:test-device@0' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 658.028830] kobject: 'unittest-bus.0' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 658.098145] random: get_random_u32 called from shuffle_freelist+0xb4/0x398 with crng_init=0 [ 658.290917] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status [ 658.378220] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status [ 658.405820] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 658.407855] kobject: 'testcase-data:overlay-node:test-bus:test-unittest1' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 658.423959] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status [ 658.508987] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status [ 658.533411] kobject: 'testcase-data:overlay-node:test-bus:test-unittest3' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 658.646203] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status [ 658.745004] kobject: 'testcase-data:overlay-node:test-bus:test-unittest5' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 658.766072] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status [ 658.844206] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status [ 658.945093] kobject: 'testcase-data:overlay-node:test-bus:test-unittest7' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 658.970975] kobject: 'testcase-data:overlay-node:test-bus:test-unittest6' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 658.988098] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status [ 659.064974] _warn_unseeded_randomness: 6 callbacks suppressed [ 659.065254] random: get_random_u32 called from shuffle_freelist+0xb4/0x398 with crng_init=0 [ 659.068056] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo [ 659.070858] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 659.072888] OF: overlay: overlay #6 is not topmost [ 659.099985] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 659.100995] kobject: 'testcase-data:overlay-node:test-bus:test-unittest8' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 659.116807] random: get_random_u32 called from shuffle_freelist+0xb4/0x398 with crng_init=0 [ 659.528963] kobject: 'testcase-data:overlay-node:test-bus:test-unittest11:test-unittest111' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 659.552712] kobject: 'testcase-data:overlay-node:test-bus:test-unittest11' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 659.556008] 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 [ 659.559784] 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 [ 659.562863] OF: overlay: remove: Could not find overlay #7 [ 659.564377] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #7 [ 659.598085] kobject: 'testcase-data:overlay-node:test-bus:test-unittest10:test-unittest101' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 659.621366] kobject: 'testcase-data:overlay-node:test-bus:test-unittest10' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 659.624584] 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 [ 659.628325] 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 [ 659.657085] kobject: 'testcase-data:overlay-node:test-bus:test-unittest4' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 659.660183] 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 [ 659.754899] kobject: 'testcase-data:overlay-node:test-bus:test-unittest2' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 659.849710] kobject: 'testcase-data:overlay-node:test-bus:test-unittest0' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 659.973134] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/status [ 659.981683] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/status [ 659.985558] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@30/incline-up [ 659.988294] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@40/incline-up [ 660.009163] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/status [ 660.010852] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/color [ 660.013545] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/rate [ 660.016621] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/hvac_2 [ 660.018565] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200 [ 660.021626] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_left [ 660.024280] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_right [ 660.210894] OF: overlay: ERROR: multiple fragments add and/or delete node /testcase-data-2/substation@100/motor-1/controller [ 660.218209] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/controller/name [ 660.221512] _warn_unseeded_randomness: 2 callbacks suppressed [ 660.221781] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 660.224063] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 660.226464] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 660.240642] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 660.242550] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 660.245419] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/rpm_avail [ 660.266558] random: get_random_u32 called from shuffle_freelist+0xb4/0x398 with crng_init=0 [ 660.273465] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 660.274433] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 660.277649] ### dt-test ### end of unittest - 217 passed, 0 failed [ 660.342712] uart-pl011 9000000.pl011: no DMA platform data [ 660.936785] VFS: Mounted root (9p filesystem) readonly on device 0:19. [ 660.966648] devtmpfs: mounted [ 660.996042] kobject: 'test-unittest4' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 661.017370] integrity: Unable to open file: /etc/keys/x509_ima.der (-2) [ 661.046020] integrity: Unable to open file: /etc/keys/x509_evm.der (-2) [ 662.526616] Freeing unused kernel memory: 6144K [ 662.538954] _warn_unseeded_randomness: 5 callbacks suppressed [ 662.540749] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 662.541696] kobject: 'test-unittest101' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 662.548554] kobject: 'test-unittest10' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 663.190494] Checked W+X mappings: passed, no W+X pages found [ 663.192220] rodata_test: all tests were successful [ 663.193464] Run /sbin/init as init process [ 663.218586] kobject: 'test-unittest111' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 663.256281] random: get_random_u32 called from shuffle_freelist+0xb4/0x398 with crng_init=0 [ 663.410030] random: get_random_u64 called from arch_mmap_rnd+0xb8/0xd0 with crng_init=0 [ 663.573489] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest10 [ 663.766448] _warn_unseeded_randomness: 4 callbacks suppressed [ 663.766710] random: get_random_u32 called from kobject_release+0x44/0x1e8 with crng_init=0 [ 663.769894] kobject: 'test-unittest11' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 663.984630] random: get_random_u32 called from shuffle_freelist+0xb4/0x398 with crng_init=0 [ 664.021124] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest4 [ 665.119281] random: fast init done [ 666.588329] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest10/test-unittest101 [ 667.349807] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11/test-unittest111 [ 667.862279] 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