[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 4.20.0-rc4-next-20181128-00009-g44c557177b8d-dirty (anders@compilator) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05)) #15 SMP Thu Nov 29 09:45:43 CET 2018 [ 0.000000] Machine model: linux,dummy-virt [ 0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '') [ 0.000000] printk: bootconsole [pl11] enabled [ 0.000000] efi: Getting EFI parameters from FDT: [ 0.000000] efi: UEFI not found. [ 0.000000] cma: Reserved 16 MiB at 0x00000000bf000000 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] NUMA: No NUMA configuration found [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] NUMA: NODE_DATA [mem 0xbebf15c0-0xbebf2fff] [ 0.000000] Zone ranges: [ 0.000000] DMA32 [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv0.2 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: Trusted OS migration not required [ 0.000000] random: get_random_bytes called from start_kernel+0xe0/0x6ec with crng_init=0 [ 0.000000] percpu: Embedded 490 pages/cpu @(____ptrval____) s1969240 r8192 d29608 u2007040 [ 0.000000] Detected VIPT I-cache on CPU0 [ 0.000000] CPU features: enabling workaround for ARM erratum 843419 [ 0.000000] CPU features: enabling workaround for ARM erratum 845719 [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 516096 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: root=/dev/root rootfstype=9p rootflags=trans=virtio console=ttyAMA0,38400n8 earlycon=pl011,0x9000000 initcall_debug [ 0.000000] Memory: 992712K/2097152K available (16356K kernel code, 3774K rwdata, 4852K rodata, 6144K init, 23374K bss, 94856K reserved, 16384K cma-reserved) [ 0.000000] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 0.000000] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.000000] ftrace: allocating 41939 entries in 164 pages [ 0.000000] Running RCU self tests [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU dyntick-idle grace-period acceleration is enabled. [ 0.000000] rcu: RCU lockdep checking is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=1. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] rcu: RCU debug extended QS entry/exit. [ 0.000000] Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] GICv2m: range[mem 0x08020000-0x08020fff], SPI[80:143] [ 0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns [ 0.000217] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.015292] Console: colour dummy device 80x25 [ 0.019459] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.019951] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.020315] ... MAX_LOCK_DEPTH: 48 [ 0.020674] ... MAX_LOCKDEP_KEYS: 8191 [ 0.021051] ... CLASSHASH_SIZE: 4096 [ 0.021422] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.021789] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.022468] ... CHAINHASH_SIZE: 32768 [ 0.022909] memory used by lock dependency info: 7775 kB [ 0.023291] per task-struct memory footprint: 2688 bytes [ 0.023704] ------------------------ [ 0.024040] | Locking API testsuite: [ 0.024390] ---------------------------------------------------------------------------- [ 0.024838] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.025274] -------------------------------------------------------------------------- [ 0.026106] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.037529] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.048426] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.060095] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.071433] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.083909] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.096204] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.108823] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.118930] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.128919] -------------------------------------------------------------------------- [ 0.129368] recursive read-lock: | ok | | ok | [ 0.132947] recursive read-lock #2: | ok | | ok | [ 0.136509] mixed read-write-lock: | ok | | ok | [ 0.139712] mixed write-read-lock: | ok | | ok | [ 0.143184] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.146491] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.150211] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.153586] -------------------------------------------------------------------------- [ 0.154684] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.159613] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.165344] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.170323] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.175218] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.180224] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.185262] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.190178] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.195203] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.200207] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.205228] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.210500] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.215676] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.221085] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.226262] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.231851] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.237087] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.242290] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.247549] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.252642] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.257721] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.262928] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.268050] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.273308] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.278550] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.283787] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.289114] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.294441] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.299646] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.304972] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.310259] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.315502] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.320760] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.326076] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.331464] hard-irq lock-inversion/123: ok | ok | ok | [ 0.336710] soft-irq lock-inversion/123: ok | ok | ok | [ 0.342148] hard-irq lock-inversion/132: ok | ok | ok | [ 0.347458] soft-irq lock-inversion/132: ok | ok | ok | [ 0.352798] hard-irq lock-inversion/213: ok | ok | ok | [ 0.358205] soft-irq lock-inversion/213: ok | ok | ok | [ 0.363463] hard-irq lock-inversion/231: ok | ok | ok | [ 0.368724] soft-irq lock-inversion/231: ok | ok | ok | [ 0.374115] hard-irq lock-inversion/312: ok | ok | ok | [ 0.379367] soft-irq lock-inversion/312: ok | ok | ok | [ 0.384642] hard-irq lock-inversion/321: ok | ok | ok | [ 0.389925] soft-irq lock-inversion/321: ok | ok | ok | [ 0.395246] hard-irq read-recursion/123: ok | [ 0.397592] soft-irq read-recursion/123: ok | [ 0.399630] hard-irq read-recursion/132: ok | [ 0.402013] soft-irq read-recursion/132: ok | [ 0.404050] hard-irq read-recursion/213: ok | [ 0.406464] soft-irq read-recursion/213: ok | [ 0.408464] hard-irq read-recursion/231: ok | [ 0.410878] soft-irq read-recursion/231: ok | [ 0.412829] hard-irq read-recursion/312: ok | [ 0.415291] soft-irq read-recursion/312: ok | [ 0.417255] hard-irq read-recursion/321: ok | [ 0.419633] soft-irq read-recursion/321: ok | [ 0.421650] -------------------------------------------------------------------------- [ 0.422127] | Wound/wait tests | [ 0.422459] --------------------- [ 0.422800] ww api failures: ok | ok | ok | [ 0.429629] ww contexts mixing: ok | ok | [ 0.432868] finishing ww context: ok | ok | ok | ok | [ 0.439923] locking mismatches: ok | ok | ok | [ 0.444893] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.462204] spinlock nest unlocked: ok | [ 0.464055] ----------------------------------------------------- [ 0.464455] |block | try |context| [ 0.464851] ----------------------------------------------------- [ 0.465243] context: ok | ok | ok | [ 0.470344] try: ok | ok | ok | [ 0.475163] block: ok | ok | ok | [ 0.480049] spinlock: ok | ok | ok | [ 0.485333] ------------------------------------------------------- [ 0.485788] Good, all 261 testcases passed! | [ 0.486478] --------------------------------- [ 0.544085] ODEBUG: selftest passed [ 0.548476] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.549269] pid_max: default: 32768 minimum: 301 [ 0.559759] LSM: Security Framework initializing [ 0.560522] Yama: becoming mindful. [ 0.563089] LoadPin: ready to pin (currently enforcing) [ 0.563876] SELinux: Initializing. [ 0.571240] *** VALIDATE SELinux *** [ 0.572390] AppArmor: AppArmor disabled by boot time parameter [ 0.603654] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.615888] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.618854] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.619889] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.655274] *** VALIDATE proc *** [ 0.674231] *** VALIDATE cgroup1 *** [ 0.674707] *** VALIDATE cgroup2 *** [ 0.740739] ASID allocator initialised with 32768 entries [ 0.751167] rcu: Hierarchical SRCU implementation. [ 0.829060] EFI services will not be available. [ 0.856018] smp: Bringing up secondary CPUs ... [ 0.856596] smp: Brought up 1 node, 1 CPU [ 0.857014] SMP: Total of 1 processors activated. [ 0.857511] CPU features: detected: 32-bit EL0 Support [ 0.858019] CPU features: detected: CRC32 instructions [ 1.116491] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.117293] CPU: All CPU(s) started at EL1 [ 1.118241] alternatives: patching kernel code [ 5.135422] node 0 initialised, 248300 pages in 4ms [ 5.143092] pgdatinit0 (15) used greatest stack depth: 13280 bytes left [ 5.158723] _warn_unseeded_randomness: 100 callbacks suppressed [ 5.159180] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 5.160240] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 5.169858] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 5.222123] devtmpfs: initialized [ 5.407123] gcov: version magic: 0x4137332a [ 5.427950] Registered cp15_barrier emulation handler [ 5.428613] Registered setend emulation handler [ 6.034970] workqueue: round-robin CPU selection forced, expect performance impact [ 10.328656] DMA-API: preallocated 65536 debug entries [ 10.329135] DMA-API: debugging enabled by kernel config [ 10.330805] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 10.332906] futex hash table entries: 256 (order: 3, 32768 bytes) [ 10.338216] Running postponed tracer tests: [ 10.343226] Testing tracer function: [ 10.382498] ftrace_replace_code: 1 [ 18.153392] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 18.414726] ftrace_replace_code: 1 [ 24.977464] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 24.980935] PASSED [ 24.981759] Testing dynamic ftrace: [ 26.730392] ftrace_replace_code: 1 [ 26.744086] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 26.984975] PASSED [ 26.985585] Testing dynamic ftrace ops #1: [ 34.079232] ftrace_replace_code: 1 [ 34.093159] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 34.097481] (1 0 1 0 0) [ 34.097673] (1 1 2 0 0) [ 34.111068] ftrace_replace_code: 1 [ 44.277188] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 44.279239] (2 1 3 0 843337) [ 44.279508] (2 2 4 0 843358) [ 44.293395] ftrace_replace_code: 1 [ 50.532869] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [swapper/0:1] [ 50.533837] Modules linked in: [ 50.534314] irq event stamp: 604428 [ 50.534911] hardirqs last enabled at (604427): [] _raw_spin_unlock_irqrestore+0x50/0x98 [ 50.535480] hardirqs last disabled at (604428): [] el1_irq+0xa8/0x1c0 [ 50.536017] softirqs last enabled at (604372): [] __do_softirq+0x9a8/0x9f0 [ 50.536578] softirqs last disabled at (604365): [] irq_exit+0x128/0x1b8 [ 50.537378] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G T 4.20.0-rc4-next-20181128-00009-g44c557177b8d-dirty #15 [ 50.537886] Hardware name: linux,dummy-virt (DT) [ 50.538426] pstate: 20400005 (nzCv daif +PAN -UAO) [ 50.538897] pc : _raw_spin_unlock_irqrestore+0x54/0x98 [ 50.539380] lr : _raw_spin_unlock_irqrestore+0x50/0x98 [ 50.539769] sp : ffffff800b9d3770 [ 50.540152] x29: ffffff800b9d3770 x28: 0000000000008743 [ 50.540688] x27: 0000000000008743 x26: ffffff800ac47de8 [ 50.541176] x25: ffffff800ac48440 x24: ffffffc03fc10080 [ 50.541657] x23: 0000000000000000 x22: ffffff8008c18fa0 [ 50.542167] x21: ffffff8008e6887c x20: ffffff8009c25c70 [ 50.542650] x19: 0000000000000000 x18: 0000000034f4d91d [ 50.543130] x17: 0000000000000000 x16: 0000000000000000 [ 50.543612] x15: ffffff8009c6ac18 x14: 0000000000001495 [ 50.544094] x13: ffffff800ac4fe90 x12: ffffff800802e234 [ 50.544577] x11: ffffff800ac465d0 x10: 0000000000028f58 [ 50.545083] x9 : ffffff8009c16000 x8 : 0000000000000000 [ 50.545570] x7 : ffffff8008e68850 x6 : 0000000000000000 [ 50.546050] x5 : ffffff8009c16718 x4 : ffffffc03f558040 [ 50.546559] x3 : fffffffffffffe80 x2 : ffffff800a6aa530 [ 50.547038] x1 : 000000000000001d x0 : 0000000000049359 [ 50.547573] Call trace: [ 50.548014] _raw_spin_unlock_irqrestore+0x54/0x98 [ 50.548483] __aarch64_insn_write+0x5c/0x70 [ 50.548916] aarch64_insn_write+0x10/0x20 [ 50.549359] aarch64_insn_patch_text_nosync+0x20/0x58 [ 50.549795] ftrace_modify_code+0x48/0xc0 [ 50.550219] ftrace_make_nop+0x1a0/0x1e8 [ 50.550641] __ftrace_replace_code+0x100/0x158 [ 50.551068] ftrace_replace_code+0x11c/0x248 [ 50.551494] ftrace_modify_all_code+0xac/0x2a0 [ 50.551926] arch_ftrace_update_code+0x10/0x20 [ 50.552353] ftrace_run_update_code+0xb4/0xf0 [ 50.552775] ftrace_shutdown+0x284/0x5b8 [ 50.553199] unregister_ftrace_function+0x5c/0x98 [ 50.553625] trace_selftest_ops+0x524/0x6a0 [ 50.554075] trace_selftest_startup_dynamic_tracing.constprop.3+0x27c/0x338 [ 50.554584] trace_selftest_startup_function+0x2d4/0x450 [ 50.555021] run_tracer_selftest+0x234/0x384 [ 50.555461] init_trace_selftests+0x160/0x448 [ 50.555881] do_one_initcall+0x324/0x6e8 [ 50.556304] do_initcall_level+0x898/0x970 [ 50.556718] do_initcalls+0x64/0xa8 [ 50.557124] do_basic_setup+0x44/0x70 [ 50.557541] kernel_init_freeable+0x178/0x2bc [ 50.557948] kernel_init+0x28/0x140 [ 50.558359] ret_from_fork+0x10/0x18 [ 51.714760] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 51.731753] ftrace_replace_code: 1 [ 51.745092] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 51.749433] ftrace_replace_code: 1 [ 51.762784] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 51.766474] PASSED [ 51.766810] Testing dynamic ftrace ops #2: [ 58.737751] ftrace_replace_code: 1 [ 58.751777] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 58.755745] ftrace_replace_code: 1 [ 58.768971] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 58.789303] ftrace_replace_code: 1 [ 68.427456] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 68.429262] (1 0 1 825881 0) [ 68.429489] (1 1 2 825902 0) [ 68.447236] (2 1 3 1 777) [ 68.448208] (2 2 4 135 911) [ 68.486736] ftrace_replace_code: 1 [ 75.054981] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 75.057281] PASSED [ 75.057645] Testing ftrace recursion: [ 76.801730] ftrace_replace_code: 1 [ 76.815552] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 76.819771] ftrace_replace_code: 1 [ 76.833173] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 76.834769] PASSED [ 76.835634] Testing ftrace recursion safe: [ 77.826818] watchdog: BUG: soft lockup - CPU#0 stuck for 24s! [swapper/0:1] [ 77.827468] Modules linked in: [ 77.827824] irq event stamp: 847306 [ 77.828307] hardirqs last enabled at (847305): [] el1_irq+0xf4/0x1c0 [ 77.828841] hardirqs last disabled at (847306): [] el1_irq+0xa8/0x1c0 [ 77.829368] softirqs last enabled at (847304): [] __do_softirq+0x9a8/0x9f0 [ 77.829893] softirqs last disabled at (847297): [] irq_exit+0x128/0x1b8 [ 77.830436] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G L T 4.20.0-rc4-next-20181128-00009-g44c557177b8d-dirty #15 [ 77.830902] Hardware name: linux,dummy-virt (DT) [ 77.831295] pstate: 40400005 (nZcv daif +PAN -UAO) [ 77.831710] pc : kallsyms_expand_symbol.constprop.0+0x9c/0xf8 [ 77.832192] lr : kallsyms_expand_symbol.constprop.0+0x9c/0xf8 [ 77.832554] sp : ffffff800b9d3760 [ 77.832872] x29: ffffff800b9d3760 x28: ffffff8009366000 [ 77.833350] x27: ffffff8009366000 x26: 000000000005b7ba [ 77.833834] x25: ffffff800923f0b1 x24: ffffff800923f0ba [ 77.834302] x23: ffffff800b9d3874 x22: 0000000000000065 [ 77.834782] x21: 0000000000000001 x20: ffffff80093418b5 [ 77.835263] x19: 0000000000000077 x18: 0000000034f4d91d [ 77.835752] x17: 0000000000000000 x16: 0fffc00000010201 [ 77.836246] x15: ffffff8009c6ac18 x14: 00000000000014fe [ 77.836687] x13: ffffff8009d1d250 x12: 0000000000000038 [ 77.837144] x11: 0101010101010101 x10: 7f7f7f7f7f7f7f7f [ 77.837584] x9 : 61ff6a626e6b6e6d x8 : 7f7f7f7f7f7f7f7f [ 77.838020] x7 : 000000000006c45e x6 : 000000000006c45e [ 77.838462] x5 : ffffff800ac45be8 x4 : ffffff800b9d3859 [ 77.838926] x3 : ffffff800b9d3850 x2 : 0000000000000077 [ 77.839364] x1 : 0000000000000000 x0 : 0000000000000000 [ 77.839792] Call trace: [ 77.840174] kallsyms_expand_symbol.constprop.0+0x9c/0xf8 [ 77.840585] kallsyms_lookup+0x84/0x138 [ 77.840972] ftrace_match_record+0x7c/0x1c8 [ 77.841356] match_records+0x204/0x338 [ 77.841759] ftrace_match_records+0x30/0x50 [ 77.842190] ftrace_set_hash+0x164/0x2a8 [ 77.842608] ftrace_set_regex+0x40/0x60 [ 77.843005] ftrace_set_filter+0xd0/0xf0 [ 77.843411] trace_selftest_function_recursion+0x1c4/0x2c0 [ 77.843873] trace_selftest_startup_function+0x320/0x450 [ 77.844286] run_tracer_selftest+0x234/0x384 [ 77.844694] init_trace_selftests+0x160/0x448 [ 77.845083] do_one_initcall+0x324/0x6e8 [ 77.845493] do_initcall_level+0x898/0x970 [ 77.845874] do_initcalls+0x64/0xa8 [ 77.846277] do_basic_setup+0x44/0x70 [ 77.846676] kernel_init_freeable+0x178/0x2bc [ 77.847059] kernel_init+0x28/0x140 [ 77.847451] ret_from_fork+0x10/0x18 [ 78.599651] ftrace_replace_code: 1 [ 78.613355] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 78.617571] ftrace_replace_code: 1 [ 78.630764] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 78.632399] PASSED [ 78.632769] Testing ftrace regs(no arch support): [ 80.376185] ftrace_replace_code: 1 [ 80.390533] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 80.395039] ftrace_replace_code: 1 [ 80.409019] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 80.410821] PASSED [ 80.415337] Testing tracer nop: PASSED [ 80.417984] Testing tracer irqsoff: [ 80.435280] ftrace_replace_code: 1 [ 88.073443] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 88.106638] ftrace_replace_code: 1 [ 94.749748] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 94.752549] PASSED [ 94.754514] Testing tracer wakeup: [ 94.792784] ftrace_replace_code: 1 [ 102.494969] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 102.536765] ftrace_replace_code: 1 [ 109.117028] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 109.127359] PASSED [ 109.128968] Testing tracer wakeup_rt: [ 109.155837] ftrace_replace_code: 1 [ 116.785470] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 116.829151] ftrace_replace_code: 1 [ 116.895575] sched: DL replenish lagged too much [ 123.410985] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 123.422310] PASSED [ 123.423078] Testing tracer wakeup_dl: [ 123.448200] ftrace_replace_code: 1 [ 131.206176] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 131.246166] ftrace_replace_code: 1 [ 137.850027] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 137.861978] PASSED [ 137.863538] Testing tracer function_graph: [ 137.863672] ../kernel/trace/trace_selftest.c:766 [ 137.865343] ../kernel/trace/trace_selftest.c:768 [ 137.865811] ../kernel/trace/trace_selftest.c:770 [ 137.889641] ftrace_replace_code: 1 [ 145.323341] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 145.442740] ../kernel/trace/trace_selftest.c:773 [ 145.518483] ../kernel/trace/trace_selftest.c:778 [ 145.999621] ../kernel/trace/trace_selftest.c:780 [ 176.525454] watchdog: BUG: soft lockup - CPU#0 stuck for 26s! [kworker/0:1:13] [ 176.546149] Modules linked in: [ 176.563697] irq event stamp: 3164 [ 176.582781] hardirqs last enabled at (3163): [] _raw_spin_unlock_irq+0x38/0x78 [ 176.609358] hardirqs last disabled at (3164): [] el1_irq+0xa8/0x1c0 [ 176.634815] softirqs last enabled at (334): [] __do_softirq+0x9a8/0x9f0 [ 176.660201] softirqs last disabled at (297): [] irq_exit+0x128/0x1b8 [ 176.682923] CPU: 0 PID: 13 Comm: kworker/0:1 Tainted: G L T 4.20.0-rc4-next-20181128-00009-g44c557177b8d-dirty #15 [ 176.709826] Hardware name: linux,dummy-virt (DT) [ 176.732965] Workqueue: (null) (events) [ 176.754552] pstate: 20400005 (nzCv daif +PAN -UAO) [ 176.774992] pc : _raw_spin_unlock_irq+0x3c/0x78 [ 176.795372] lr : _raw_spin_unlock_irq+0x38/0x78 [ 176.812314] sp : ffffff800ba33cd0 [ 176.827251] x29: ffffff800ba33cd0 x28: 0000000000000003 [ 176.849339] x27: ffffffc03f624040 x26: ffffff8009c15df8 [ 176.871555] x25: ffffff8009d12fb0 x24: 0000000000000000 [ 176.893334] x23: ffffff8008e5f298 x22: ffffffc03f624040 [ 176.915498] x21: ffffffc03f5e0040 x20: ffffff80080fd610 [ 176.937590] x19: ffffffc07ebdf6c0 x18: 0000000034f4d91d [ 176.959391] x17: 0000000000000000 x16: 0000000000000000 [ 176.981451] x15: ffffff8009c6a580 x14: ffffff800ac4e690 [ 177.003440] x13: ffffff800ac4fe90 x12: ffffff800ac4fe90 [ 177.025648] x11: ffffff800ac4ff98 x10: ffffff800ac4ff98 [ 177.048251] x9 : 0000000000000bd0 x8 : 0000000000121998 [ 177.070769] x7 : 0000000000000ba0 x6 : 0000004075191000 [ 177.092944] x5 : ffffff8009cb82b0 x4 : ffffff8009c15bb0 [ 177.114860] x3 : 00000000000d1b88 x2 : 00000000000d1b88 [ 177.137583] x1 : 0000000000000001 x0 : 00000000000d1b88 [ 177.160379] Call trace: [ 177.177495] _raw_spin_unlock_irq+0x3c/0x78 [ 177.197128] finish_task_switch+0x160/0x220 [ 177.217456] __schedule+0xa78/0xaf8 [ 177.236231] schedule+0x9c/0xe0 [ 177.254451] worker_thread+0x240/0x2a0 [ 177.273592] kthread+0x154/0x170 [ 177.291720] ret_from_fork+0x10/0x18 [ 232.826299] watchdog: BUG: soft lockup - CPU#0 stuck for 26s! [ksoftirqd/0:9] [ 232.846283] Modules linked in: [ 232.863313] irq event stamp: 22486 [ 232.882150] hardirqs last enabled at (22485): [] _raw_spin_unlock_irq+0x38/0x78 [ 232.908571] hardirqs last disabled at (22486): [] el1_irq+0xa8/0x1c0 [ 232.934448] softirqs last enabled at (17630): [] __do_softirq+0x9a8/0x9f0 [ 232.960997] softirqs last disabled at (17517): [] run_ksoftirqd+0x30/0x68 [ 232.984545] CPU: 0 PID: 9 Comm: ksoftirqd/0 Tainted: G L T 4.20.0-rc4-next-20181128-00009-g44c557177b8d-dirty #15 [ 233.011375] Hardware name: linux,dummy-virt (DT) [ 233.028948] pstate: 20400005 (nzCv daif +PAN -UAO) [ 233.050348] pc : _raw_spin_unlock_irq+0x3c/0x78 [ 233.070711] lr : _raw_spin_unlock_irq+0x38/0x78 [ 233.087415] sp : ffffff800ba13c90 [ 233.102293] x29: ffffff800ba13c90 x28: 0000000000000003 [ 233.124543] x27: ffffffc03f5dc040 x26: ffffff8009c15df8 [ 233.146845] x25: ffffff8009d12fb0 x24: 0000000000000000 [ 233.168972] x23: ffffff8008e5f298 x22: ffffffc03f5dc040 [ 233.191101] x21: ffffffc03f558040 x20: ffffff80080fd610 [ 233.214152] x19: ffffffc07ebdf6c0 x18: 0000000034f4d91d [ 233.236199] x17: 0000000000000000 x16: 0000000000000000 [ 233.258011] x15: ffffff8009c6a580 x14: ffffff800ac4e690 [ 233.280736] x13: ffffff800ac4fe90 x12: ffffff800ac4fe90 [ 233.303074] x11: ffffff800ac4ff98 x10: ffffff800ac4ff98 [ 233.325261] x9 : 0000000000000090 x8 : 0000000000325b38 [ 233.346943] x7 : 0000000000000060 x6 : 0000004075191000 [ 233.369089] x5 : ffffff8009cb82b0 x4 : ffffff8009c15bb0 [ 233.391572] x3 : 00000000000d550e x2 : 00000000000d550e [ 233.414027] x1 : 0000000000000001 x0 : 00000000000d550e [ 233.436641] Call trace: [ 233.453888] _raw_spin_unlock_irq+0x3c/0x78 [ 233.473555] finish_task_switch+0x160/0x220 [ 233.494527] __schedule+0xa78/0xaf8 [ 233.513766] preempt_schedule_common+0x4c/0x90 [ 233.534704] _cond_resched+0x40/0x50 [ 233.554328] run_ksoftirqd+0x50/0x68 [ 233.574148] smpboot_thread_fn+0x400/0x428 [ 233.594598] kthread+0x154/0x170 [ 233.613516] ret_from_fork+0x10/0x18 [ 234.276518] ../kernel/trace/trace_selftest.c:792 [ 234.352032] ../kernel/trace/trace_selftest.c:794 [ 234.357329] ../kernel/trace/trace_selftest.c:797 [ 234.381944] ../kernel/trace/trace_selftest.c:799 [ 234.409725] ftrace_replace_code: 1 [ 242.168604] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 242.171749] ../kernel/trace/trace_selftest.c:802 [ 242.172290] ../kernel/trace/trace_selftest.c:804 [ 242.172920] PASSED [ 242.254679] _warn_unseeded_randomness: 5 callbacks suppressed [ 242.254916] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 242.256136] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 242.258557] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 242.274964] prandom: seed boundary self test passed [ 242.316727] prandom: 100 self tests passed [ 242.322744] pinctrl core: initialized pinctrl subsystem [ 242.408542] regulator-dummy: Failed to create debugfs directory [ 242.415428] [ 242.415773] ************************************************************* [ 242.416798] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 242.417340] ** ** [ 242.417871] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 242.418386] ** ** [ 242.418884] ** This means that this kernel is built to expose internal ** [ 242.419364] ** IOMMU data structures, which may compromise security on ** [ 242.419836] ** your system. ** [ 242.420290] ** ** [ 242.421135] ** If you see this message and you are not debugging the ** [ 242.421590] ** kernel, report this immediately to your vendor! ** [ 242.422026] ** ** [ 242.422481] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 242.422915] ************************************************************* [ 242.448953] DMI not present or invalid. [ 242.492072] NET: Registered protocol family 16 [ 242.516266] audit: initializing netlink subsys (disabled) [ 242.636446] cpuidle: using governor ladder [ 242.637778] cpuidle: using governor menu [ 242.654467] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 242.656410] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 242.658339] audit: type=2000 audit(143.988:1): state=initialized audit_enabled=0 res=1 [ 242.760707] DMA: preallocated 256 KiB pool for atomic allocations [ 242.835063] Serial: AMBA PL011 UART driver [ 243.411292] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 243.414161] printk: console [ttyAMA0] enabled [ 243.414161] printk: console [ttyAMA0] enabled [ 243.415203] printk: bootconsole [pl11] disabled [ 243.415203] printk: bootconsole [pl11] disabled [ 243.502043] _warn_unseeded_randomness: 42 callbacks suppressed [ 243.502267] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 243.504143] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 243.895691] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 243.944519] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 244.056695] kobject: 'renesas,r9a06g032-sysctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 244.065124] kobject: 'renesas-cpg-mssr' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 244.109878] arm-v7s io-pgtable: self test ok [ 244.111367] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 244.119113] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 244.123976] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 244.129458] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 244.136250] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 244.141652] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 244.147338] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 244.156463] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 244.167007] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 244.177576] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 244.188414] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 244.199002] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 244.211818] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 244.240304] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 244.268920] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 244.298791] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 244.333089] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 244.366870] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 244.400579] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 244.426512] vgaarb: loaded [ 244.462429] pps_core: LinuxPPS API ver. 1 registered [ 244.463067] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 244.466868] PTP clock support registered [ 244.473383] kobject: 'brcmstb-reboot' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 244.596307] _warn_unseeded_randomness: 39 callbacks suppressed [ 244.596532] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=0 [ 244.600741] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 244.607569] NetLabel: Initializing [ 244.608146] NetLabel: domain hash size = 128 [ 244.609140] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 244.615837] NetLabel: unlabeled traffic allowed by default [ 244.634114] clocksource: Switched to clocksource arch_sys_counter [ 248.687373] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 248.688320] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 248.691033] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 248.703307] VFS: Disk quotas dquot_6.6.0 [ 248.706975] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 248.729068] *** VALIDATE hugetlbfs *** [ 249.419494] NET: Registered protocol family 2 [ 249.456007] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 249.460598] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 249.480713] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 249.492516] TCP: Hash tables configured (established 16384 bind 16384) [ 249.500324] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 249.506068] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 249.523843] NET: Registered protocol family 1 [ 249.528110] NET: Registered protocol family 44 [ 249.592496] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 249.602602] kvm [1]: HYP mode not available [ 249.691920] _warn_unseeded_randomness: 54 callbacks suppressed [ 249.692138] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 249.694168] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 249.719821] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 249.826284] Kprobe smoke test: started [ 250.907818] Kprobe smoke test: passed successfully [ 250.963294] Initialise system trusted keyrings [ 250.964874] _warn_unseeded_randomness: 3 callbacks suppressed [ 250.965111] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 250.971276] Key type blacklist registered [ 250.976022] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 250.987200] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 252.602981] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 252.603979] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 252.623709] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 252.787404] 9p: Installing v9fs 9p2000 file system support [ 252.874516] Allocating IMA blacklist keyring. [ 252.888150] cryptomgr_test (37) used greatest stack depth: 12400 bytes left [ 252.907932] kworker/u2:1 (38) used greatest stack depth: 11936 bytes left [ 253.723958] Key type asymmetric registered [ 253.726284] Asymmetric key parser 'x509' registered [ 253.731683] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 253.742961] _warn_unseeded_randomness: 26 callbacks suppressed [ 253.743208] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 253.744774] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 253.772147] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 253.908096] kobject: 'brcm-gisb-arb' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 253.986493] kobject: 'armada-37xx-pinctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 254.137049] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 254.142585] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 254.162533] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 254.183307] kobject: 'stmpe-pwm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 254.205982] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 254.266947] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 254.269622] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 254.271762] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 254.273072] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 254.276600] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 254.291301] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 254.292455] pci_bus 0000:00: root bus resource [bus 00-0f] [ 254.293876] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 254.294773] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 254.295654] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 254.299713] pci 0000:00:00.0: calling quirk_mmio_always_on+0x0/0x38 @ 1 [ 254.300734] pci 0000:00:00.0: quirk_mmio_always_on+0x0/0x38 took 75 usecs [ 254.375633] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 254.376889] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 254.378785] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 254.380076] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 254.381612] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 254.382554] pci 0000:00:03.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 254.383445] pci 0000:00:03.0: BAR 0: assigned [io 0x1000-0x103f] [ 254.384348] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 254.385682] pci 0000:00:02.0: BAR 0: assigned [io 0x1060-0x107f] [ 254.387615] pci 0000:00:00.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 254.388653] pci 0000:00:00.0: tegra_pcie_relax_enable+0x0/0x40 took 144 usecs [ 254.391289] pci 0000:00:01.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 254.392251] pci 0000:00:01.0: tegra_pcie_relax_enable+0x0/0x40 took 37 usecs [ 254.394140] pci 0000:00:02.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 254.395084] pci 0000:00:02.0: tegra_pcie_relax_enable+0x0/0x40 took 36 usecs [ 254.396473] pci 0000:00:03.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 254.397885] pci 0000:00:03.0: tegra_pcie_relax_enable+0x0/0x40 took 35 usecs [ 254.436933] kobject: 'layerscape-pcie' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 254.849921] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 254.875392] _warn_unseeded_randomness: 17 callbacks suppressed [ 254.875633] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 254.876875] kobject: 'virtio0' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 254.892030] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 254.904234] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 254.923092] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 254.923964] kobject: 'virtio1' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 254.950644] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 254.968892] kobject: 'virtio2' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 259.483646] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 259.605109] _warn_unseeded_randomness: 3 callbacks suppressed [ 259.606528] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 259.607637] kobject: 'ttyS0' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 259.609045] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 259.611237] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 259.612099] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 259.662282] kobject: 'ttyS1' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 259.663639] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 259.711308] kobject: 'ttyS2' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 259.712606] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 259.762453] kobject: 'ttyS3' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 259.763852] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 259.844850] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 259.858157] KGDB: Registered I/O driver kgdbts [ 259.859287] kgdbts:RUN plant and detach test Entering kdb (current=0xffffffc03f558040, pid 1) on processor 0 due to Keyboard Entry [0]kdb> [ 259.869650] kgdbts:RUN sw breakpoint test [ 259.885873] kgdbts:RUN bad memory access test [ 259.888868] kgdbts:RUN singlestep test 1000 iterations [ 259.920961] kgdbts:RUN singlestep [0/1000] [ 262.774913] kgdbts:RUN singlestep [100/1000] [ 265.601926] kgdbts:RUN singlestep [200/1000] [ 268.426689] kgdbts:RUN singlestep [300/1000] [ 271.254152] kgdbts:RUN singlestep [400/1000] [ 274.080977] kgdbts:RUN singlestep [500/1000] [ 276.906654] kgdbts:RUN singlestep [600/1000] [ 279.734025] kgdbts:RUN singlestep [700/1000] [ 282.564033] kgdbts:RUN singlestep [800/1000] [ 285.395494] kgdbts:RUN singlestep [900/1000] [ 288.197454] kgdbts:RUN do_fork for 100 breakpoints [ 288.202599] _warn_unseeded_randomness: 7 callbacks suppressed [ 288.202855] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 289.952195] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 289.953112] kobject: 'rx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 289.956691] kobject: 'tx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 289.978773] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 289.991698] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 289.992611] kobject: 'net' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 290.959078] KGDB: Unregistered I/O driver kgdbts, debugger disabled [ 294.004369] kobject: 'queues' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 294.068047] kobject: 'eth0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 294.081940] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 294.220912] ledtrig-cpu: registered to indicate activity on CPUs [ 294.222563] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 294.222782] kobject: '44' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 294.223142] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 294.224361] kobject: '45' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 294.240238] kobject: 'meson-sm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 294.252284] kobject: '46' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 294.307413] ashmem: initialized [ 294.476781] 9pnet: Installing 9P2000 support [ 295.058324] kobject: '47' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 295.066274] kobject: '48' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 295.113988] registered taskstats version 1 [ 295.115346] Running ring buffer tests... [ 295.155021] _warn_unseeded_randomness: 16 callbacks suppressed [ 295.155262] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 305.267343] finished [ 305.267963] CPU 0: [ 305.268403] events: 7539 [ 305.268967] dropped bytes: 0 [ 305.270251] alloced bytes: 587668 [ 305.270798] written bytes: 575689 [ 305.271265] biggest event: 70 [ 305.271741] smallest event: 0 [ 305.532100] read events: 7539 [ 305.532688] lost events: 0 [ 305.533753] total events: 7539 [ 305.534313] recorded len bytes: 587668 [ 305.534826] recorded size bytes: 575689 [ 305.535394] Ring buffer PASSED! [ 305.577664] Running tests on trace events: [ 305.578319] Testing event initcall_finish: OK [ 305.608545] Testing event initcall_start: OK [ 305.632099] Testing event initcall_level: OK [ 305.660313] Testing event sys_exit: OK [ 305.690345] Testing event sys_enter: OK [ 305.715780] Testing event ipi_exit: OK [ 305.746460] Testing event ipi_entry: OK [ 305.771312] Testing event ipi_raise: OK [ 305.799594] Testing event instruction_emulation: OK [ 305.827583] Testing event kvm_halt_poll_ns: OK [ 305.855896] Testing event kvm_age_page: OK [ 305.883444] Testing event kvm_fpu: OK [ 305.908558] Testing event kvm_mmio: OK [ 305.940653] Testing event kvm_ack_irq: OK [ 305.971281] Testing event kvm_set_irq: OK [ 305.998662] Testing event kvm_vcpu_wakeup: OK [ 306.028005] Testing event kvm_userspace_exit: OK [ 306.052694] Testing event kvm_timer_update_irq: OK [ 306.084939] Testing event kvm_toggle_cache: OK [ 306.113706] Testing event kvm_set_way_flush: OK [ 306.139853] Testing event kvm_test_age_hva: OK [ 306.172438] Testing event kvm_age_hva: OK [ 306.199683] Testing event kvm_set_spte_hva: OK [ 306.226742] Testing event kvm_unmap_hva_range: OK [ 306.252789] Testing event kvm_mmio_emulate: OK [ 306.279874] Testing event kvm_irq_line: OK [ 306.308056] Testing event kvm_access_fault: OK [ 306.339366] Testing event kvm_guest_fault: OK [ 306.367382] Testing event kvm_exit: OK [ 306.398526] Testing event kvm_entry: OK [ 306.423400] Testing event kvm_set_guest_debug: OK [ 306.451343] Testing event kvm_handle_sys_reg: OK [ 306.478553] Testing event trap_reg: OK [ 306.507967] Testing event kvm_arm_set_regset: OK [ 306.532946] Testing event kvm_arm_set_dreg32: OK [ 306.563985] Testing event kvm_arm_clear_debug: OK [ 306.590155] Testing event kvm_arm_setup_debug: OK [ 306.618142] Testing event kvm_hvc_arm64: OK [ 306.643776] Testing event kvm_wfx_arm64: OK [ 306.674727] Testing event vgic_update_irq_pending: OK [ 306.699418] Testing event task_rename: OK [ 306.728614] Testing event task_newtask: OK [ 306.760034] Testing event cpuhp_exit: OK [ 306.791084] Testing event cpuhp_multi_enter: OK [ 306.815357] Testing event cpuhp_enter: OK [ 306.842773] Testing event softirq_raise: OK [ 306.871862] Testing event softirq_exit: OK [ 306.898861] Testing event softirq_entry: OK [ 306.924306] Testing event irq_handler_exit: OK [ 306.952234] Testing event irq_handler_entry: OK [ 306.982482] Testing event signal_deliver: OK [ 307.007671] Testing event signal_generate: OK [ 307.039091] Testing event workqueue_execute_end: OK [ 307.064928] Testing event workqueue_execute_start: OK [ 307.091359] Testing event workqueue_activate_work: OK [ 307.120976] Testing event workqueue_queue_work: OK [ 307.148890] Testing event sched_wake_idle_without_ipi: OK [ 307.180651] Testing event sched_swap_numa: OK [ 307.204743] Testing event sched_stick_numa: OK [ 307.236796] Testing event sched_move_numa: OK [ 307.263698] Testing event sched_process_hang: OK [ 307.290653] Testing event sched_pi_setprio: OK [ 307.316403] Testing event sched_stat_runtime: [ 307.319469] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 307.347194] OK [ 307.347674] Testing event sched_stat_blocked: OK [ 307.375893] Testing event sched_stat_iowait: OK [ 307.403084] Testing event sched_stat_sleep: OK [ 307.430009] Testing event sched_stat_wait: OK [ 307.456086] Testing event sched_process_exec: OK [ 307.487783] Testing event sched_process_fork: OK [ 307.515361] Testing event sched_process_wait: OK [ 307.546684] Testing event sched_wait_task: OK [ 307.571591] Testing event sched_process_exit: OK [ 307.599850] Testing event sched_process_free: OK [ 307.627747] Testing event sched_migrate_task: OK [ 307.652952] Testing event sched_switch: OK [ 307.684381] Testing event sched_wakeup_new: OK [ 307.711639] Testing event sched_wakeup: OK [ 307.739612] Testing event sched_waking: OK [ 307.764830] Testing event sched_kthread_stop_ret: OK [ 307.792933] Testing event sched_kthread_stop: OK [ 307.816984] Testing event lock_acquired: OK [ 307.859464] Testing event lock_contended: OK [ 307.885889] Testing event lock_release: OK [ 307.933700] Testing event lock_acquire: OK [ 307.985035] Testing event console: OK [ 308.011427] Testing event rcu_barrier: OK [ 308.038419] Testing event rcu_torture_read: OK [ 308.063755] Testing event rcu_batch_end: OK [ 308.091440] Testing event rcu_invoke_kfree_callback: OK [ 308.119301] Testing event rcu_invoke_callback: OK [ 308.146553] Testing event rcu_batch_start: OK [ 308.171938] Testing event rcu_kfree_callback: OK [ 308.200121] Testing event rcu_callback: OK [ 308.231995] Testing event rcu_dyntick: OK [ 308.264035] Testing event rcu_fqs: OK [ 308.292153] Testing event rcu_quiescent_state_report: OK [ 308.318610] Testing event rcu_unlock_preempted_task: OK [ 308.344665] Testing event rcu_preempt_task: OK [ 308.371523] Testing event rcu_nocb_wake: OK [ 308.401856] Testing event rcu_exp_funnel_lock: OK [ 308.424418] Testing event rcu_exp_grace_period: OK [ 308.452700] Testing event rcu_grace_period_init: OK [ 308.482194] Testing event rcu_future_grace_period: OK [ 308.510914] Testing event rcu_grace_period: OK [ 308.544532] Testing event rcu_utilization: OK [ 308.576196] Testing event swiotlb_bounced: OK [ 308.603620] Testing event tick_stop: OK [ 308.630854] Testing event itimer_expire: OK [ 308.658300] Testing event itimer_state: OK [ 308.684897] Testing event hrtimer_cancel: OK [ 308.712217] Testing event hrtimer_expire_exit: OK [ 308.743373] Testing event hrtimer_expire_entry: OK [ 308.771626] Testing event hrtimer_start: OK [ 308.799923] Testing event hrtimer_init: OK [ 308.827745] Testing event timer_cancel: OK [ 308.854278] Testing event timer_expire_exit: OK [ 308.880514] Testing event timer_expire_entry: OK [ 308.907344] Testing event timer_start: OK [ 308.934158] Testing event timer_init: OK [ 308.959681] Testing event alarmtimer_cancel: OK [ 308.988138] Testing event alarmtimer_start: OK [ 309.014977] Testing event alarmtimer_fired: OK [ 309.039794] Testing event alarmtimer_suspend: OK [ 309.070207] Testing event module_request: OK [ 309.095686] Testing event module_put: OK [ 309.124455] Testing event module_get: OK [ 309.150347] Testing event module_free: OK [ 309.175027] Testing event module_load: OK [ 309.202721] Testing event cgroup_transfer_tasks: OK [ 309.232032] Testing event cgroup_attach_task: OK [ 309.256685] Testing event cgroup_rename: OK [ 309.288761] Testing event cgroup_release: OK [ 309.317896] Testing event cgroup_rmdir: OK [ 309.343971] Testing event cgroup_mkdir: OK [ 309.375500] Testing event cgroup_remount: OK [ 309.403369] Testing event cgroup_destroy_root: OK [ 309.434667] Testing event cgroup_setup_root: OK [ 309.459417] Testing event irq_enable: OK [ 309.503738] Testing event irq_disable: OK [ 309.547739] Testing event ftrace_test_filter: OK [ 309.575563] Testing event dev_pm_qos_remove_request: OK [ 309.602757] Testing event dev_pm_qos_update_request: OK [ 309.627747] Testing event dev_pm_qos_add_request: OK [ 309.655699] Testing event pm_qos_update_flags: OK [ 309.683570] Testing event pm_qos_update_target: OK [ 309.711391] Testing event pm_qos_update_request_timeout: OK [ 309.739048] Testing event pm_qos_remove_request: OK [ 309.763711] Testing event pm_qos_update_request: OK [ 309.791712] Testing event pm_qos_add_request: OK [ 309.819901] Testing event power_domain_target: OK [ 309.844762] Testing event clock_set_rate: OK [ 309.875540] Testing event clock_disable: OK [ 309.900062] Testing event clock_enable: OK [ 309.927372] Testing event wakeup_source_deactivate: OK [ 309.955283] Testing event wakeup_source_activate: OK [ 309.982661] Testing event suspend_resume: OK [ 310.022450] Testing event device_pm_callback_end: OK [ 310.047412] Testing event device_pm_callback_start: OK [ 310.075931] Testing event cpu_frequency_limits: OK [ 310.104122] Testing event cpu_frequency: OK [ 310.130610] Testing event pstate_sample: OK [ 310.156376] Testing event powernv_throttle: OK [ 310.183488] Testing event cpu_idle: OK [ 310.212115] Testing event rpm_return_int: OK [ 310.238967] Testing event rpm_idle: OK [ 310.266897] Testing event rpm_resume: OK [ 310.292580] Testing event rpm_suspend: OK [ 310.319521] Testing event benchmark_event: OK [ 310.448346] Testing event xdp_devmap_xmit: OK [ 310.476931] Testing event xdp_cpumap_enqueue: OK [ 310.503288] Testing event xdp_cpumap_kthread: OK [ 310.532888] Testing event xdp_redirect_map_err: OK [ 310.563780] Testing event xdp_redirect_map: OK [ 310.591676] Testing event xdp_redirect_err: OK [ 310.616267] Testing event xdp_redirect: OK [ 310.644321] Testing event xdp_exception: OK [ 310.672459] Testing event rseq_ip_fixup: OK [ 310.702274] Testing event rseq_update: OK [ 310.727767] Testing event file_check_and_advance_wb_err: OK [ 310.758554] Testing event filemap_set_wb_err: OK [ 310.786511] Testing event mm_filemap_add_to_page_cache: OK [ 310.812119] Testing event mm_filemap_delete_from_page_cache: OK [ 310.842454] Testing event compact_retry: OK [ 310.867596] Testing event skip_task_reaping: OK [ 310.896072] Testing event finish_task_reaping: OK [ 310.923068] Testing event start_task_reaping: OK [ 310.947687] Testing event wake_reaper: OK [ 310.975811] Testing event mark_victim: OK [ 311.002336] Testing event reclaim_retry_zone: OK [ 311.027711] Testing event oom_score_adj_update: OK [ 311.055676] Testing event mm_lru_activate: OK [ 311.083581] Testing event mm_lru_insertion: OK [ 311.110502] Testing event mm_vmscan_inactive_list_is_low: OK [ 311.140183] Testing event mm_vmscan_lru_shrink_active: OK [ 311.170293] Testing event mm_vmscan_lru_shrink_inactive: OK [ 311.195647] Testing event mm_vmscan_writepage: OK [ 311.226879] Testing event mm_vmscan_lru_isolate: OK [ 311.251438] Testing event mm_shrink_slab_end: OK [ 311.279432] Testing event mm_shrink_slab_start: OK [ 311.306411] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 311.331803] Testing event mm_vmscan_memcg_reclaim_end: OK [ 311.359470] Testing event mm_vmscan_direct_reclaim_end: OK [ 311.386389] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 311.411779] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 311.439386] Testing event mm_vmscan_direct_reclaim_begin: OK [ 311.466382] Testing event mm_vmscan_wakeup_kswapd: OK [ 311.491723] Testing event mm_vmscan_kswapd_wake: OK [ 311.520234] Testing event mm_vmscan_kswapd_sleep: OK [ 311.547272] Testing event percpu_destroy_chunk: OK [ 311.575131] Testing event percpu_create_chunk: OK [ 311.602641] Testing event percpu_alloc_percpu_fail: OK [ 311.632293] Testing event percpu_free_percpu: OK [ 311.656973] Testing event percpu_alloc_percpu: OK [ 311.688726] Testing event mm_page_alloc_extfrag: OK [ 311.716365] Testing event mm_page_pcpu_drain: OK [ 311.744149] Testing event mm_page_alloc_zone_locked: OK [ 311.771076] Testing event mm_page_alloc: OK [ 311.795693] Testing event mm_page_free_batched: OK [ 311.824449] Testing event mm_page_free: OK [ 311.851069] Testing event kmem_cache_free: OK [ 311.879427] Testing event kfree: OK [ 311.906504] Testing event kmem_cache_alloc_node: OK [ 311.931743] Testing event kmalloc_node: OK [ 311.960303] Testing event kmem_cache_alloc: OK [ 311.988568] Testing event kmalloc: OK [ 312.018373] Testing event mm_compaction_kcompactd_wake: OK [ 312.048452] Testing event mm_compaction_wakeup_kcompactd: OK [ 312.075021] Testing event mm_compaction_kcompactd_sleep: OK [ 312.103664] Testing event mm_compaction_defer_reset: OK [ 312.130783] Testing event mm_compaction_defer_compaction: OK [ 312.155871] Testing event mm_compaction_deferred: OK [ 312.184311] Testing event mm_compaction_suitable: OK [ 312.212637] Testing event mm_compaction_finished: OK [ 312.239794] Testing event mm_compaction_try_to_compact_pages: OK [ 312.267954] Testing event mm_compaction_end: OK [ 312.294390] Testing event mm_compaction_begin: OK [ 312.319737] Testing event mm_compaction_migratepages: OK [ 312.348011] Testing event mm_compaction_isolate_freepages: OK [ 312.374441] Testing event mm_compaction_isolate_migratepages: OK [ 312.399861] Testing event mm_migrate_pages: OK [ 312.428305] Testing event mm_collapse_huge_page_swapin: OK [ 312.456218] Testing event mm_collapse_huge_page_isolate: OK [ 312.486332] Testing event mm_collapse_huge_page: OK [ 312.511751] Testing event mm_khugepaged_scan_pmd: OK [ 312.542737] Testing event test_pages_isolated: OK [ 312.568922] Testing event cma_release: OK [ 312.595737] Testing event cma_alloc: OK [ 312.624304] Testing event page_ref_unfreeze: OK [ 312.654810] Testing event page_ref_freeze: OK [ 312.687368] Testing event page_ref_mod_unless: OK [ 312.718708] Testing event page_ref_mod_and_return: OK [ 312.744552] Testing event page_ref_mod_and_test: OK [ 312.878236] Testing event page_ref_mod: OK [ 312.932314] Testing event page_ref_set: OK [ 312.966417] Testing event sb_clear_inode_writeback: OK [ 312.991894] Testing event sb_mark_inode_writeback: OK [ 313.019607] Testing event writeback_dirty_inode_enqueue: OK [ 313.046570] Testing event writeback_lazytime_iput: OK [ 313.071748] Testing event writeback_lazytime: OK [ 313.100249] Testing event writeback_single_inode: OK [ 313.127073] Testing event writeback_single_inode_start: OK [ 313.151707] Testing event writeback_wait_iff_congested: OK [ 313.179887] Testing event writeback_congestion_wait: OK [ 313.206422] Testing event writeback_sb_inodes_requeue: OK [ 313.231826] Testing event balance_dirty_pages: OK [ 313.260115] Testing event bdi_dirty_ratelimit: OK [ 313.292416] Testing event global_dirty_state: OK [ 313.319946] Testing event writeback_queue_io: OK [ 313.350074] Testing event wbc_writepage: OK [ 313.377839] Testing event writeback_bdi_register: OK [ 313.406173] Testing event writeback_wake_background: OK [ 313.431966] Testing event writeback_pages_written: OK [ 313.460032] Testing event writeback_wait: OK [ 313.491247] Testing event writeback_written: OK [ 313.518752] Testing event writeback_start: OK [ 313.548355] Testing event writeback_exec: OK [ 313.575448] Testing event writeback_queue: OK [ 313.600989] Testing event writeback_write_inode: OK [ 313.627746] Testing event writeback_write_inode_start: OK [ 313.655887] Testing event writeback_dirty_inode: OK [ 313.682364] Testing event writeback_dirty_inode_start: OK [ 313.712120] Testing event writeback_mark_inode_dirty: OK [ 313.740025] Testing event writeback_dirty_page: OK [ 313.771962] Testing event dax_writeback_one: OK [ 313.799604] Testing event dax_writeback_range_done: OK [ 313.826658] Testing event dax_writeback_range: OK [ 313.854635] Testing event dax_insert_mapping: OK [ 313.883921] Testing event dax_insert_pfn_mkwrite: OK [ 313.908446] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 313.934964] Testing event dax_load_hole: OK [ 313.963494] Testing event dax_pte_fault_done: OK [ 313.990560] Testing event dax_pte_fault: OK [ 314.015722] Testing event dax_pmd_insert_mapping: OK [ 314.043756] Testing event dax_pmd_load_hole_fallback: OK [ 314.071735] Testing event dax_pmd_load_hole: OK [ 314.099232] Testing event dax_pmd_fault_done: OK [ 314.126860] Testing event dax_pmd_fault: OK [ 314.151534] Testing event generic_add_lease: OK [ 314.180358] Testing event time_out_leases: OK [ 314.206992] Testing event generic_delete_lease: OK [ 314.231745] Testing event break_lease_unblock: OK [ 314.259622] Testing event break_lease_block: OK [ 314.286319] Testing event break_lease_noblock: OK [ 314.311684] Testing event flock_lock_inode: OK [ 314.339291] Testing event locks_remove_posix: OK [ 314.366238] Testing event fcntl_setlk: OK [ 314.391752] Testing event posix_lock_inode: OK [ 314.419720] Testing event locks_get_lock_context: OK [ 314.447438] Testing event block_rq_remap: OK [ 314.471863] Testing event block_bio_remap: OK [ 314.499533] Testing event block_split: OK [ 314.530269] Testing event block_unplug: OK [ 314.555755] Testing event block_plug: OK [ 314.584080] Testing event block_sleeprq: OK [ 314.612462] Testing event block_getrq: OK [ 314.639916] Testing event block_bio_queue: OK [ 314.667920] Testing event block_bio_frontmerge: OK [ 314.700372] Testing event block_bio_backmerge: OK [ 314.727521] Testing event block_bio_complete: OK [ 314.755464] Testing event block_bio_bounce: OK [ 314.782488] Testing event block_rq_issue: OK [ 314.811958] Testing event block_rq_insert: OK [ 314.836761] Testing event block_rq_complete: OK [ 314.868633] Testing event block_rq_requeue: OK [ 314.896526] Testing event block_dirty_buffer: OK [ 314.922305] Testing event block_touch_buffer: OK [ 314.947066] Testing event wbt_timer: OK [ 314.975556] Testing event wbt_step: OK [ 315.002639] Testing event wbt_lat: OK [ 315.027807] Testing event wbt_stat: OK [ 315.056287] Testing event gpio_value: OK [ 315.084069] Testing event gpio_direction: OK [ 315.112925] Testing event clk_set_duty_cycle_complete: OK [ 315.140154] Testing event clk_set_duty_cycle: OK [ 315.166835] Testing event clk_set_phase_complete: OK [ 315.192332] Testing event clk_set_phase: OK [ 315.220096] Testing event clk_set_parent_complete: OK [ 315.250149] Testing event clk_set_parent: OK [ 315.275433] Testing event clk_set_rate_complete: OK [ 315.304026] Testing event clk_set_rate: OK [ 315.330928] Testing event clk_unprepare_complete: OK [ 315.355821] Testing event clk_unprepare: OK [ 315.384321] Testing event clk_prepare_complete: OK [ 315.412255] Testing event clk_prepare: OK [ 315.438900] Testing event clk_disable_complete: OK [ 315.467481] Testing event clk_disable: OK [ 315.494793] Testing event clk_enable_complete: OK [ 315.519719] Testing event clk_enable: OK [ 315.548245] Testing event rpmh_send_msg: OK [ 315.578371] Testing event rpmh_tx_done: OK [ 315.603664] Testing event regulator_set_voltage_complete: OK [ 315.634592] Testing event regulator_set_voltage: OK [ 315.659629] Testing event regulator_disable_complete: OK [ 315.688128] Testing event regulator_disable: OK [ 315.714941] Testing event regulator_enable_complete: OK [ 315.739768] Testing event regulator_enable_delay: OK [ 315.767820] Testing event regulator_enable: OK [ 315.794379] Testing event urandom_read: OK [ 315.819736] Testing event random_read: OK [ 315.847780] Testing event extract_entropy_user: OK [ 315.875588] Testing event extract_entropy: OK [ 315.902400] Testing event get_random_bytes_arch: OK [ 315.931547] Testing event get_random_bytes: OK [ 315.958705] Testing event xfer_secondary_pool: OK [ 315.986563] Testing event add_disk_randomness: OK [ 316.016290] Testing event add_input_randomness: OK [ 316.040519] Testing event debit_entropy: OK [ 316.072738] Testing event push_to_pool: OK [ 316.100398] Testing event credit_entropy_bits: OK [ 316.132853] Testing event mix_pool_bytes_nolock: OK [ 316.156787] Testing event mix_pool_bytes: OK [ 316.182787] Testing event add_device_randomness: OK [ 316.211114] Testing event io_page_fault: OK [ 316.235595] Testing event unmap: OK [ 316.262587] Testing event map: OK [ 316.286849] Testing event detach_device_from_domain: OK [ 316.311705] Testing event attach_device_to_domain: OK [ 316.338672] Testing event remove_device_from_group: OK [ 316.362927] Testing event add_device_to_group: OK [ 316.387682] Testing event regcache_drop_region: OK [ 316.414629] Testing event regmap_async_complete_done: OK [ 316.438927] Testing event regmap_async_complete_start: OK [ 316.463739] Testing event regmap_async_io_complete: OK [ 316.490637] Testing event regmap_async_write_start: OK [ 316.514915] Testing event regmap_cache_bypass: OK [ 316.539678] Testing event regmap_cache_only: OK [ 316.566783] Testing event regcache_sync: OK [ 316.592792] Testing event regmap_hw_write_done: OK [ 316.621896] Testing event regmap_hw_write_start: OK [ 316.647091] Testing event regmap_hw_read_done: OK [ 316.672476] Testing event regmap_hw_read_start: OK [ 316.699685] Testing event regmap_reg_read_cache: OK [ 316.726503] Testing event regmap_reg_read: OK [ 316.750884] Testing event regmap_reg_write: OK [ 316.775563] Testing event dma_fence_wait_end: OK [ 316.802576] Testing event dma_fence_wait_start: OK [ 316.826943] Testing event dma_fence_signaled: OK [ 316.851660] Testing event dma_fence_enable_signal: OK [ 316.878749] Testing event dma_fence_destroy: OK [ 316.902978] Testing event dma_fence_init: OK [ 316.927520] Testing event dma_fence_emit: OK [ 316.952876] Testing event sync_timeline: OK [ 316.978867] Testing event spi_transfer_stop: OK [ 317.003737] Testing event spi_transfer_start: OK [ 317.031933] Testing event spi_message_done: OK [ 317.058623] Testing event spi_message_start: OK [ 317.083741] Testing event spi_message_submit: OK [ 317.112479] Testing event spi_controller_busy: OK [ 317.142156] Testing event spi_controller_idle: OK [ 317.167304] Testing event rtc_timer_fired: OK [ 317.198890] Testing event rtc_timer_dequeue: OK [ 317.223275] Testing event rtc_timer_enqueue: OK [ 317.251775] Testing event rtc_read_offset: OK [ 317.278437] Testing event rtc_set_offset: OK [ 317.303749] Testing event rtc_alarm_irq_enable: OK [ 317.332090] Testing event rtc_irq_set_state: OK [ 317.363493] Testing event rtc_irq_set_freq: OK [ 317.391276] Testing event rtc_read_alarm: OK [ 317.422674] Testing event rtc_set_alarm: OK [ 317.447424] Testing event rtc_read_time: OK [ 317.475448] Testing event rtc_set_time: OK [ 317.502713] Testing event memory_failure_event: OK [ 317.527726] Testing event aer_event: OK [ 317.558175] Testing event non_standard_event: OK [ 317.584556] Testing event arm_event: OK [ 317.616726] Testing event mc_event: OK [ 317.643576] Testing event binder_return: OK [ 317.670721] Testing event binder_command: OK [ 317.700103] Testing event binder_unmap_kernel_end: OK [ 317.730278] Testing event binder_unmap_kernel_start: OK [ 317.755957] Testing event binder_unmap_user_end: OK [ 317.786656] Testing event binder_unmap_user_start: OK [ 317.811452] Testing event binder_alloc_page_end: OK [ 317.839873] Testing event binder_alloc_page_start: OK [ 317.866510] Testing event binder_free_lru_end: OK [ 317.891747] Testing event binder_free_lru_start: OK [ 317.919795] Testing event binder_alloc_lru_end: OK [ 317.947643] Testing event binder_alloc_lru_start: OK [ 317.975433] Testing event binder_update_page_range: OK [ 318.000237] Testing event binder_transaction_failed_buffer_release: OK [ 318.028276] Testing event binder_transaction_buffer_release: OK [ 318.058073] Testing event binder_transaction_alloc_buf: OK [ 318.083470] Testing event binder_transaction_fd_recv: OK [ 318.111877] Testing event binder_transaction_fd_send: OK [ 318.138469] Testing event binder_transaction_ref_to_ref: OK [ 318.163786] Testing event binder_transaction_ref_to_node: OK [ 318.190728] Testing event binder_transaction_node_to_ref: OK [ 318.219766] Testing event binder_transaction_received: OK [ 318.246665] Testing event binder_transaction: OK [ 318.272740] Testing event binder_wait_for_work: OK [ 318.299566] Testing event binder_read_done: OK [ 318.331849] Testing event binder_write_done: OK [ 318.359771] Testing event binder_ioctl_done: OK [ 318.386601] Testing event binder_unlock: OK [ 318.415769] Testing event binder_locked: OK [ 318.442477] Testing event binder_lock: OK [ 318.467502] Testing event binder_ioctl: OK [ 318.495408] Testing event br_fdb_update: OK [ 318.522342] Testing event fdb_delete: OK [ 318.548067] Testing event br_fdb_external_learn_add: OK [ 318.575798] Testing event br_fdb_add: OK [ 318.607249] Testing event qdisc_dequeue: OK [ 318.635410] Testing event fib_table_lookup: OK [ 318.667380] Testing event tcp_probe: OK [ 318.695859] Testing event tcp_retransmit_synack: OK [ 318.722437] Testing event tcp_rcv_space_adjust: OK [ 318.747723] Testing event tcp_destroy_sock: OK [ 318.775949] Testing event tcp_receive_reset: OK [ 318.800619] Testing event tcp_send_reset: OK [ 318.830420] Testing event tcp_retransmit_skb: OK [ 318.855696] Testing event udp_fail_queue_rcv_skb: OK [ 318.886656] Testing event inet_sock_set_state: OK [ 318.911546] Testing event sock_exceed_buf_limit: OK [ 318.939538] Testing event sock_rcvqueue_full: OK [ 318.966466] Testing event napi_poll: OK [ 318.992068] Testing event netif_rx_ni_entry: OK [ 319.019920] Testing event netif_rx_entry: OK [ 319.052580] Testing event netif_receive_skb_list_entry: OK [ 319.080226] Testing event netif_receive_skb_entry: OK [ 319.107353] Testing event napi_gro_receive_entry: OK [ 319.132504] Testing event napi_gro_frags_entry: OK [ 319.164686] Testing event netif_rx: OK [ 319.194051] Testing event netif_receive_skb: OK [ 319.219778] Testing event net_dev_queue: OK [ 319.252443] Testing event net_dev_xmit: OK [ 319.280140] Testing event net_dev_start_xmit: OK [ 319.310304] Testing event skb_copy_datagram_iovec: OK [ 319.335769] Testing event consume_skb: OK [ 319.367174] Testing event kfree_skb: OK [ 319.392071] Testing event 9p_protocol_dump: OK [ 319.430602] Testing event 9p_client_res: OK [ 319.455031] Testing event 9p_client_req: OK [ 319.479733] Running tests on trace event systems: [ 319.480349] Testing event system 9p: OK [ 319.518867] Testing event system skb: OK [ 319.550178] Testing event system net: OK [ 319.590198] Testing event system napi: OK [ 319.621875] Testing event system sock: OK [ 319.652039] Testing event system udp: OK [ 319.683557] Testing event system tcp: OK [ 319.715527] Testing event system fib: OK [ 319.747050] Testing event system qdisc: OK [ 319.775907] Testing event system bridge: OK [ 319.808155] Testing event system binder: OK [ 319.858153] Testing event system ras: OK [ 319.886473] Testing event system rtc: OK [ 319.933757] Testing event system spi: OK [ 319.975712] Testing event system sync_trace: OK [ 320.004553] Testing event system dma_fence: OK [ 320.039460] Testing event system regmap: OK [ 320.083967] Testing event system iommu: OK [ 320.116208] Testing event system random: OK [ 320.155652] Testing event system regulator: OK [ 320.190946] Testing event system rpmh: OK [ 320.220226] Testing event system clk: OK [ 320.264062] Testing event system gpio: OK [ 320.292956] Testing event system wbt: OK [ 320.328657] Testing event system block: OK [ 320.374624] Testing event system filelock: OK [ 320.411757] Testing event system fs_dax: OK [ 320.451662] Testing event system writeback: OK [ 320.508404] Testing event system page_ref: OK [ 320.695982] Testing event system cma: OK [ 320.722537] Testing event system page_isolation: OK [ 320.752506] Testing event system huge_memory: OK [ 320.784471] Testing event system migrate: OK [ 320.815287] Testing event system compaction: OK [ 320.854077] Testing event system kmem: OK [ 320.905875] Testing event system percpu: OK [ 320.938242] Testing event system vmscan: OK [ 320.978972] Testing event system pagemap: OK [ 321.012146] Testing event system oom: OK [ 321.048367] Testing event system filemap: OK [ 321.082078] Testing event system rseq: OK [ 321.112863] Testing event system xdp: OK [ 321.150799] Testing event system benchmark: OK [ 321.275099] Testing event system rpm: OK [ 321.310795] Testing event system power: OK [ 321.367775] Testing event system test: OK [ 321.408208] Testing event system preemptirq: OK [ 321.468379] Testing event system cgroup: OK [ 321.503827] Testing event system module: OK [ 321.538933] Testing event system alarmtimer: OK [ 321.568488] Testing event system timer: OK [ 321.616436] Testing event system swiotlb: OK [ 321.644072] Testing event system rcu: OK [ 321.706876] Testing event system printk: OK [ 321.740950] Testing event system lock: OK [ 321.889066] Testing event system sched: OK [ 321.939793] Testing event system workqueue: OK [ 321.971298] Testing event system signal: OK [ 322.006121] Testing event system irq: OK [ 322.042934] Testing event system cpuhp: OK [ 322.078238] Testing event system task: OK [ 322.104538] Testing event system kvm: OK [ 322.158758] Testing event system emulation: OK [ 322.186735] Testing event system ipi: OK [ 322.226836] Testing event system raw_syscalls: OK [ 322.258444] Testing event system initcall: OK [ 322.286890] Running tests on all trace events: [ 322.287413] Testing all events: OK [ 326.079300] ftrace_replace_code: 1 [ 330.114288] hrtimer: interrupt took 11389616 ns [ 347.433517] watchdog: BUG: soft lockup - CPU#0 stuck for 21s! [swapper/0:1] [ 347.436196] Modules linked in: [ 347.439355] irq event stamp: 2458886 [ 347.443101] hardirqs last enabled at (2458885): [] _raw_spin_unlock_irqrestore+0x50/0x98 [ 347.446885] hardirqs last disabled at (2458886): [] el1_irq+0xa8/0x1c0 [ 347.450700] softirqs last enabled at (2419898): [] __do_softirq+0x9a8/0x9f0 [ 347.454363] softirqs last disabled at (2419889): [] irq_exit+0x128/0x1b8 [ 347.457502] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G L T 4.20.0-rc4-next-20181128-00009-g44c557177b8d-dirty #15 [ 347.460357] Hardware name: linux,dummy-virt (DT) [ 347.463128] pstate: 20400005 (nzCv daif +PAN -UAO) [ 347.466543] pc : _raw_spin_unlock_irqrestore+0x54/0x98 [ 347.469948] lr : _raw_spin_unlock_irqrestore+0x50/0x98 [ 347.472585] sp : ffffff800b9d3910 [ 347.475113] x29: ffffff800b9d3910 x28: 0000000000005af1 [ 347.478675] x27: 0000000000005af1 x26: ffffff800ac47de8 [ 347.482249] x25: ffffff800ac48440 x24: ffffffc03fc10580 [ 347.485787] x23: 0000000000000001 x22: ffffff80088fe49c [ 347.489345] x21: ffffff8008e6887c x20: ffffff8009c25c70 [ 347.493040] x19: 0000000000000000 x18: 0000000034f4d91d [ 347.496758] x17: 0000000000000000 x16: 0000000000000000 [ 347.500422] x15: ffffff8009c6ac18 x14: ffffff800ac4e690 [ 347.504064] x13: ffffff800ac4fe90 x12: ffffff800ac4fe90 [ 347.507497] x11: ffffff800ac4ff98 x10: ffffff800ac4ff98 [ 347.511193] x9 : 0000000000000e54 x8 : 00000000003ec771 [ 347.514694] x7 : 0000000000000e54 x6 : 0000004075191000 [ 347.518486] x5 : ffffff8009cb82b0 x4 : ffffff8009c15bb0 [ 347.522128] x3 : fffffffffffffe80 x2 : ffffff800a6aa530 [ 347.525652] x1 : 000000000000001d x0 : 000000000018759c [ 347.529457] Call trace: [ 347.532859] _raw_spin_unlock_irqrestore+0x54/0x98 [ 347.536296] __aarch64_insn_write+0x5c/0x70 [ 347.539764] aarch64_insn_write+0x10/0x20 [ 347.543056] aarch64_insn_patch_text_nosync+0x20/0x58 [ 347.546616] ftrace_modify_code+0x48/0xc0 [ 347.549969] ftrace_make_call+0x18c/0x1f8 [ 347.553300] __ftrace_replace_code+0xcc/0x158 [ 347.556768] ftrace_replace_code+0x11c/0x248 [ 347.560130] ftrace_modify_all_code+0xac/0x2a0 [ 347.563518] arch_ftrace_update_code+0x10/0x20 [ 347.567187] ftrace_run_update_code+0xb4/0xf0 [ 347.570699] ftrace_startup_enable+0x94/0xb8 [ 347.574391] ftrace_startup+0x1c4/0x230 [ 347.577789] register_ftrace_function+0xd0/0x108 [ 347.581346] event_trace_self_test_with_function+0x1fc/0x270 [ 347.584920] event_trace_self_tests_init+0x104/0x14c [ 347.588410] do_one_initcall+0x324/0x6e8 [ 347.591880] do_initcall_level+0x898/0x970 [ 347.595304] do_initcalls+0x64/0xa8 [ 347.598820] do_basic_setup+0x44/0x70 [ 347.602258] kernel_init_freeable+0x178/0x2bc [ 347.605820] kernel_init+0x28/0x140 [ 347.609378] ret_from_fork+0x10/0x18 [ 366.771838] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 366.786333] Running tests again, along with the function tracer [ 366.817591] Running tests on trace events: [ 366.845673] Testing event initcall_finish: [ 366.926057] BUG: workqueue lockup - pool cpus=0 node=0 flags=0x0 nice=0 stuck for 41s! [ 366.994745] Showing busy workqueues and worker pools: [ 367.008207] workqueue events: flags=0x0 [ 367.039495] pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=2/256 [ 367.057864] pending: vmstat_shepherd, psi_update_work [ 367.092574] workqueue events_power_efficient: flags=0x82 [ 367.105807] pwq 2: cpus=0 flags=0x4 nice=0 active=1/256 [ 367.122989] pending: neigh_periodic_work [ 367.153754] workqueue rcu_gp: flags=0x8 [ 367.163942] pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/256 [ 367.179114] pending: srcu_invoke_callbacks [ 367.210515] workqueue mm_percpu_wq: flags=0x8 [ 367.221157] pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/256 [ 367.236333] pending: vmstat_update [ 370.601236] OK [ 370.607501] Testing event initcall_start: OK [ 371.034776] Testing event initcall_level: OK [ 371.723597] Testing event sys_exit: OK [ 372.177435] Testing event sys_enter: OK [ 372.793916] Testing event ipi_exit: OK [ 373.317299] Testing event ipi_entry: OK [ 373.748248] Testing event ipi_raise: OK [ 374.246827] Testing event instruction_emulation: OK [ 374.768960] Testing event kvm_halt_poll_ns: OK [ 375.358717] Testing event kvm_age_page: OK [ 375.914021] Testing event kvm_fpu: OK [ 376.336799] Testing event kvm_mmio: OK [ 376.858975] Testing event kvm_ack_irq: OK [ 377.312304] Testing event kvm_set_irq: OK [ 377.776372] Testing event kvm_vcpu_wakeup: OK [ 378.364199] Testing event kvm_userspace_exit: OK [ 378.906691] Testing event kvm_timer_update_irq: OK [ 379.308115] Testing event kvm_toggle_cache: OK [ 379.841118] Testing event kvm_set_way_flush: OK [ 380.311402] Testing event kvm_test_age_hva: OK [ 380.839445] Testing event kvm_age_hva: OK [ 381.332561] Testing event kvm_set_spte_hva: OK [ 381.767110] Testing event kvm_unmap_hva_range: OK [ 382.304446] Testing event kvm_mmio_emulate: OK [ 382.948083] Testing event kvm_irq_line: OK [ 383.375341] Testing event kvm_access_fault: OK [ 383.913403] Testing event kvm_guest_fault: OK [ 384.491845] Testing event kvm_exit: OK [ 384.949591] Testing event kvm_entry: OK [ 385.521969] Testing event kvm_set_guest_debug: OK [ 386.004959] Testing event kvm_handle_sys_reg: OK [ 386.536488] Testing event trap_reg: OK [ 386.958273] Testing event kvm_arm_set_regset: OK [ 387.480522] Testing event kvm_arm_set_dreg32: OK [ 388.037530] Testing event kvm_arm_clear_debug: OK [ 388.585434] Testing event kvm_arm_setup_debug: OK [ 389.100977] Testing event kvm_hvc_arm64: OK [ 389.603548] Testing event kvm_wfx_arm64: OK [ 390.131467] Testing event vgic_update_irq_pending: OK [ 390.575410] Testing event task_rename: OK [ 391.084963] Testing event task_newtask: OK [ 391.463951] Testing event cpuhp_exit: OK [ 391.946738] Testing event cpuhp_multi_enter: OK [ 392.420689] Testing event cpuhp_enter: OK [ 392.840468] Testing event softirq_raise: OK [ 393.329895] Testing event softirq_exit: OK [ 393.935939] Testing event softirq_entry: OK [ 394.431862] Testing event irq_handler_exit: OK [ 394.906945] Testing event irq_handler_entry: OK [ 395.355750] Testing event signal_deliver: OK [ 395.891828] Testing event signal_generate: OK [ 396.433590] Testing event workqueue_execute_end: OK [ 396.918714] Testing event workqueue_execute_start: OK [ 397.444484] Testing event workqueue_activate_work: OK [ 397.899146] Testing event workqueue_queue_work: OK [ 398.486758] Testing event sched_wake_idle_without_ipi: OK [ 399.002079] Testing event sched_swap_numa: OK [ 399.563994] Testing event sched_stick_numa: OK [ 400.005482] Testing event sched_move_numa: OK [ 400.481846] Testing event sched_process_hang: OK [ 400.976618] Testing event sched_pi_setprio: OK [ 401.445407] Testing event sched_stat_runtime: OK [ 402.037710] Testing event sched_stat_blocked: OK [ 402.436135] Testing event sched_stat_iowait: OK [ 402.889040] Testing event sched_stat_sleep: OK [ 403.420886] Testing event sched_stat_wait: OK [ 403.888195] Testing event sched_process_exec: OK [ 404.449584] Testing event sched_process_fork: OK [ 404.922587] Testing event sched_process_wait: OK [ 405.471564] Testing event sched_wait_task: OK [ 405.977100] Testing event sched_process_exit: OK [ 406.535008] Testing event sched_process_free: OK [ 407.108620] Testing event sched_migrate_task: OK [ 407.603675] Testing event sched_switch: OK [ 408.083467] Testing event sched_wakeup_new: OK [ 408.470800] Testing event sched_wakeup: OK [ 408.973391] Testing event sched_waking: OK [ 409.529063] Testing event sched_kthread_stop_ret: OK [ 409.953986] Testing event sched_kthread_stop: OK [ 410.513280] Testing event lock_acquired: OK [ 410.993430] Testing event lock_contended: OK [ 411.364676] Testing event lock_release: OK [ 411.901467] Testing event lock_acquire: OK [ 412.290956] Testing event console: OK [ 412.862811] Testing event rcu_barrier: OK [ 413.437013] Testing event rcu_torture_read: OK [ 414.000131] Testing event rcu_batch_end: OK [ 414.508765] Testing event rcu_invoke_kfree_callback: OK [ 414.953803] Testing event rcu_invoke_callback: OK [ 415.491133] Testing event rcu_batch_start: OK [ 416.055723] Testing event rcu_kfree_callback: OK [ 416.562851] Testing event rcu_callback: OK [ 417.124776] Testing event rcu_dyntick: OK [ 417.611423] Testing event rcu_fqs: OK [ 418.129826] Testing event rcu_quiescent_state_report: OK [ 418.595764] Testing event rcu_unlock_preempted_task: OK [ 419.141943] Testing event rcu_preempt_task: OK [ 419.745646] Testing event rcu_nocb_wake: OK [ 420.314914] Testing event rcu_exp_funnel_lock: OK [ 420.844303] Testing event rcu_exp_grace_period: OK [ 421.425741] Testing event rcu_grace_period_init: OK [ 421.936463] Testing event rcu_future_grace_period: OK [ 422.353037] Testing event rcu_grace_period: OK [ 422.957228] Testing event rcu_utilization: OK [ 423.493996] Testing event swiotlb_bounced: OK [ 424.068136] Testing event tick_stop: OK [ 424.528804] Testing event itimer_expire: OK [ 424.943826] Testing event itimer_state: OK [ 425.424209] Testing event hrtimer_cancel: OK [ 426.021762] Testing event hrtimer_expire_exit: OK [ 426.578844] Testing event hrtimer_expire_entry: OK [ 427.047440] Testing event hrtimer_start: OK [ 427.574444] Testing event hrtimer_init: OK [ 428.121974] Testing event timer_cancel: OK [ 428.680323] Testing event timer_expire_exit: OK [ 429.181763] Testing event timer_expire_entry: OK [ 429.614902] Testing event timer_start: OK [ 430.168884] Testing event timer_init: OK [ 430.624698] Testing event alarmtimer_cancel: OK [ 431.099541] Testing event alarmtimer_start: OK [ 431.657446] Testing event alarmtimer_fired: OK [ 432.112208] Testing event alarmtimer_suspend: OK [ 432.497205] Testing event module_request: OK [ 433.103317] Testing event module_put: OK [ 433.576232] Testing event module_get: OK [ 434.108271] Testing event module_free: OK [ 434.681320] Testing event module_load: OK [ 435.180667] Testing event cgroup_transfer_tasks: OK [ 435.715208] Testing event cgroup_attach_task: OK [ 436.238160] Testing event cgroup_rename: OK [ 436.710716] Testing event cgroup_release: OK [ 437.227403] Testing event cgroup_rmdir: OK [ 437.819838] Testing event cgroup_mkdir: OK [ 438.234795] Testing event cgroup_remount: OK [ 438.791845] Testing event cgroup_destroy_root: OK [ 439.327106] Testing event cgroup_setup_root: OK [ 439.887422] Testing event irq_enable: OK [ 440.731799] Testing event irq_disable: OK [ 441.626257] Testing event ftrace_test_filter: OK [ 442.180323] Testing event dev_pm_qos_remove_request: OK [ 442.572503] Testing event dev_pm_qos_update_request: OK [ 443.135018] Testing event dev_pm_qos_add_request: OK [ 443.784667] Testing event pm_qos_update_flags: OK [ 444.254494] Testing event pm_qos_update_target: OK [ 444.753474] Testing event pm_qos_update_request_timeout: OK [ 445.308597] Testing event pm_qos_remove_request: OK [ 445.803532] Testing event pm_qos_update_request: OK [ 446.331232] Testing event pm_qos_add_request: OK [ 446.861136] Testing event power_domain_target: OK [ 447.417407] Testing event clock_set_rate: OK [ 447.939322] Testing event clock_disable: OK [ 448.380409] Testing event clock_enable: OK [ 448.981218] Testing event wakeup_source_deactivate: OK [ 449.434997] Testing event wakeup_source_activate: OK [ 449.927588] Testing event suspend_resume: OK [ 450.471628] Testing event device_pm_callback_end: OK [ 451.037251] Testing event device_pm_callback_start: OK [ 451.530825] Testing event cpu_frequency_limits: OK [ 452.108957] Testing event cpu_frequency: OK [ 452.647868] Testing event pstate_sample: OK [ 453.141185] Testing event powernv_throttle: OK [ 453.664252] Testing event cpu_idle: OK [ 454.286473] Testing event rpm_return_int: OK [ 454.762999] Testing event rpm_idle: OK [ 455.255423] Testing event rpm_resume: OK [ 455.854118] Testing event rpm_suspend: OK [ 456.321653] Testing event benchmark_event: OK [ 456.978633] Testing event xdp_devmap_xmit: OK [ 457.423839] Testing event xdp_cpumap_enqueue: OK [ 458.053400] Testing event xdp_cpumap_kthread: OK [ 458.536676] Testing event xdp_redirect_map_err: OK [ 459.127072] Testing event xdp_redirect_map: OK [ 459.657949] Testing event xdp_redirect_err: OK [ 460.171975] Testing event xdp_redirect: OK [ 460.720080] Testing event xdp_exception: OK [ 461.224703] Testing event rseq_ip_fixup: OK [ 461.645253] Testing event rseq_update: OK [ 462.181473] Testing event file_check_and_advance_wb_err: OK [ 462.763631] Testing event filemap_set_wb_err: OK [ 463.222118] Testing event mm_filemap_add_to_page_cache: OK [ 463.764678] Testing event mm_filemap_delete_from_page_cache: OK [ 464.300444] Testing event compact_retry: OK [ 464.722606] Testing event skip_task_reaping: OK [ 465.087019] Testing event finish_task_reaping: OK [ 465.729851] Testing event start_task_reaping: OK [ 466.199360] Testing event wake_reaper: OK [ 466.732667] Testing event mark_victim: OK [ 467.284645] Testing event reclaim_retry_zone: OK [ 467.670663] Testing event oom_score_adj_update: OK [ 468.217399] Testing event mm_lru_activate: OK [ 468.655242] Testing event mm_lru_insertion: OK [ 469.112099] Testing event mm_vmscan_inactive_list_is_low: OK [ 469.703323] Testing event mm_vmscan_lru_shrink_active: OK [ 470.231333] Testing event mm_vmscan_lru_shrink_inactive: OK [ 470.761591] Testing event mm_vmscan_writepage: OK [ 471.326387] Testing event mm_vmscan_lru_isolate: OK [ 471.788618] Testing event mm_shrink_slab_end: OK [ 472.272755] Testing event mm_shrink_slab_start: OK [ 472.796599] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 473.222891] Testing event mm_vmscan_memcg_reclaim_end: OK [ 473.763781] Testing event mm_vmscan_direct_reclaim_end: OK [ 474.291591] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 474.774921] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 475.318644] Testing event mm_vmscan_direct_reclaim_begin: OK [ 475.787797] Testing event mm_vmscan_wakeup_kswapd: OK [ 476.294630] Testing event mm_vmscan_kswapd_wake: OK [ 476.883020] Testing event mm_vmscan_kswapd_sleep: OK [ 477.381076] Testing event percpu_destroy_chunk: OK [ 477.923463] Testing event percpu_create_chunk: OK [ 478.377280] Testing event percpu_alloc_percpu_fail: OK [ 478.729946] Testing event percpu_free_percpu: OK [ 479.239732] Testing event percpu_alloc_percpu: OK [ 479.667627] Testing event mm_page_alloc_extfrag: OK [ 480.254242] Testing event mm_page_pcpu_drain: OK [ 480.723323] Testing event mm_page_alloc_zone_locked: OK [ 481.250413] Testing event mm_page_alloc: OK [ 481.797215] Testing event mm_page_free_batched: OK [ 482.306952] Testing event mm_page_free: OK [ 482.905366] Testing event kmem_cache_free: OK [ 483.415806] Testing event kfree: OK [ 483.833922] Testing event kmem_cache_alloc_node: OK [ 484.168177] Testing event kmalloc_node: OK [ 484.661646] Testing event kmem_cache_alloc: OK [ 485.068891] Testing event kmalloc: OK [ 485.661420] Testing event mm_compaction_kcompactd_wake: OK [ 486.203900] Testing event mm_compaction_wakeup_kcompactd: OK [ 486.635152] Testing event mm_compaction_kcompactd_sleep: OK [ 487.141605] Testing event mm_compaction_defer_reset: OK [ 487.727942] Testing event mm_compaction_defer_compaction: OK [ 488.177210] Testing event mm_compaction_deferred: OK [ 488.601828] Testing event mm_compaction_suitable: OK [ 489.188790] Testing event mm_compaction_finished: OK [ 489.676821] Testing event mm_compaction_try_to_compact_pages: OK [ 490.072080] Testing event mm_compaction_end: OK [ 490.608767] Testing event mm_compaction_begin: OK [ 491.129157] Testing event mm_compaction_migratepages: OK [ 491.655237] Testing event mm_compaction_isolate_freepages: OK [ 492.192532] Testing event mm_compaction_isolate_migratepages: OK [ 492.715361] Testing event mm_migrate_pages: OK [ 493.186121] Testing event mm_collapse_huge_page_swapin: OK [ 493.759052] Testing event mm_collapse_huge_page_isolate: OK [ 494.243846] Testing event mm_collapse_huge_page: OK [ 494.735376] Testing event mm_khugepaged_scan_pmd: OK [ 495.231685] Testing event test_pages_isolated: OK [ 495.794629] Testing event cma_release: OK [ 496.237513] Testing event cma_alloc: OK [ 496.578326] Testing event page_ref_unfreeze: OK [ 497.121549] Testing event page_ref_freeze: OK [ 497.598047] Testing event page_ref_mod_unless: OK [ 498.208250] Testing event page_ref_mod_and_return: OK [ 498.587325] Testing event page_ref_mod_and_test: OK [ 500.070023] Testing event page_ref_mod: OK [ 500.918265] Testing event page_ref_set: OK [ 501.487900] Testing event sb_clear_inode_writeback: OK [ 502.017972] Testing event sb_mark_inode_writeback: OK [ 502.509239] Testing event writeback_dirty_inode_enqueue: OK [ 502.912419] Testing event writeback_lazytime_iput: OK [ 503.393270] Testing event writeback_lazytime: OK [ 503.958837] Testing event writeback_single_inode: OK [ 504.488437] Testing event writeback_single_inode_start: OK [ 505.029964] Testing event writeback_wait_iff_congested: OK [ 505.608348] Testing event writeback_congestion_wait: OK [ 506.141902] Testing event writeback_sb_inodes_requeue: OK [ 506.715594] Testing event balance_dirty_pages: OK [ 507.263118] Testing event bdi_dirty_ratelimit: OK [ 507.729689] Testing event global_dirty_state: OK [ 508.226871] Testing event writeback_queue_io: OK [ 508.739505] Testing event wbc_writepage: OK [ 509.109388] Testing event writeback_bdi_register: OK [ 509.553002] Testing event writeback_wake_background: OK [ 510.023928] Testing event writeback_pages_written: OK [ 510.652523] Testing event writeback_wait: OK [ 511.151283] Testing event writeback_written: OK [ 511.701764] Testing event writeback_start: OK [ 512.239481] Testing event writeback_exec: OK [ 512.835620] Testing event writeback_queue: OK [ 513.293444] Testing event writeback_write_inode: OK [ 513.760767] Testing event writeback_write_inode_start: OK [ 514.271002] Testing event writeback_dirty_inode: OK [ 514.897112] Testing event writeback_dirty_inode_start: OK [ 515.442904] Testing event writeback_mark_inode_dirty: OK [ 515.959837] Testing event writeback_dirty_page: OK [ 516.348692] Testing event dax_writeback_one: OK [ 516.849181] Testing event dax_writeback_range_done: OK [ 517.344635] Testing event dax_writeback_range: OK [ 517.801240] Testing event dax_insert_mapping: OK [ 518.257913] Testing event dax_insert_pfn_mkwrite: OK [ 518.790138] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 519.369145] Testing event dax_load_hole: OK [ 519.885562] Testing event dax_pte_fault_done: OK [ 520.472224] Testing event dax_pte_fault: OK [ 520.976083] Testing event dax_pmd_insert_mapping: OK [ 521.583236] Testing event dax_pmd_load_hole_fallback: OK [ 522.236757] Testing event dax_pmd_load_hole: OK [ 522.727270] Testing event dax_pmd_fault_done: OK [ 523.233237] Testing event dax_pmd_fault: OK [ 523.683217] Testing event generic_add_lease: OK [ 524.257006] Testing event time_out_leases: OK [ 524.775152] Testing event generic_delete_lease: OK [ 525.160766] Testing event break_lease_unblock: OK [ 525.703047] Testing event break_lease_block: OK [ 526.276112] Testing event break_lease_noblock: OK [ 526.743392] Testing event flock_lock_inode: OK [ 527.196728] Testing event locks_remove_posix: OK [ 527.587116] Testing event fcntl_setlk: OK [ 528.084477] Testing event posix_lock_inode: OK [ 528.474389] Testing event locks_get_lock_context: OK [ 529.026665] Testing event block_rq_remap: OK [ 529.623087] Testing event block_bio_remap: OK [ 530.133759] Testing event block_split: OK [ 530.560952] Testing event block_unplug: OK [ 531.178914] Testing event block_plug: OK [ 531.567479] Testing event block_sleeprq: OK [ 532.183215] Testing event block_getrq: OK [ 532.545378] Testing event block_bio_queue: OK [ 533.138750] Testing event block_bio_frontmerge: OK [ 533.614166] Testing event block_bio_backmerge: OK [ 534.109874] Testing event block_bio_complete: OK [ 534.535213] Testing event block_bio_bounce: OK [ 535.087734] Testing event block_rq_issue: OK [ 535.596414] Testing event block_rq_insert: OK [ 536.087287] Testing event block_rq_complete: OK [ 536.571572] Testing event block_rq_requeue: OK [ 536.972086] Testing event block_dirty_buffer: OK [ 537.525688] Testing event block_touch_buffer: OK [ 538.046610] Testing event wbt_timer: OK [ 538.545025] Testing event wbt_step: OK [ 538.986506] Testing event wbt_lat: OK [ 539.377211] Testing event wbt_stat: OK [ 539.932642] Testing event gpio_value: OK [ 540.396646] Testing event gpio_direction: OK [ 540.878113] Testing event clk_set_duty_cycle_complete: OK [ 541.357897] Testing event clk_set_duty_cycle: OK [ 541.874740] Testing event clk_set_phase_complete: OK [ 542.435067] Testing event clk_set_phase: OK [ 542.878493] Testing event clk_set_parent_complete: OK [ 543.419739] Testing event clk_set_parent: OK [ 543.914505] Testing event clk_set_rate_complete: OK [ 544.445096] Testing event clk_set_rate: OK [ 544.938336] Testing event clk_unprepare_complete: OK [ 545.474344] Testing event clk_unprepare: OK [ 546.064895] Testing event clk_prepare_complete: OK [ 546.535050] Testing event clk_prepare: OK [ 547.051499] Testing event clk_disable_complete: OK [ 547.543652] Testing event clk_disable: OK [ 548.084132] Testing event clk_enable_complete: OK [ 548.604021] Testing event clk_enable: OK [ 549.045419] Testing event rpmh_send_msg: OK [ 549.628116] Testing event rpmh_tx_done: OK [ 550.009244] Testing event regulator_set_voltage_complete: OK [ 550.758211] Testing event regulator_set_voltage: OK [ 551.208743] Testing event regulator_disable_complete: OK [ 551.791340] Testing event regulator_disable: OK [ 552.207369] Testing event regulator_enable_complete: OK [ 552.605641] Testing event regulator_enable_delay: OK [ 553.145382] Testing event regulator_enable: OK [ 553.514212] Testing event urandom_read: OK [ 553.992160] Testing event random_read: OK [ 554.538434] Testing event extract_entropy_user: OK [ 555.178048] Testing event extract_entropy: OK [ 555.684676] Testing event get_random_bytes_arch: OK [ 556.140082] Testing event get_random_bytes: OK [ 556.725327] Testing event xfer_secondary_pool: OK [ 557.181287] Testing event add_disk_randomness: OK [ 557.690375] Testing event add_input_randomness: OK [ 558.221966] Testing event debit_entropy: OK [ 558.737319] Testing event push_to_pool: OK [ 559.221444] Testing event credit_entropy_bits: OK [ 559.745134] Testing event mix_pool_bytes_nolock: OK [ 560.267755] Testing event mix_pool_bytes: OK [ 560.786634] Testing event add_device_randomness: OK [ 561.203830] Testing event io_page_fault: OK [ 561.679569] Testing event unmap: OK [ 562.186972] Testing event map: OK [ 562.647151] Testing event detach_device_from_domain: OK [ 563.191904] Testing event attach_device_to_domain: OK [ 563.677813] Testing event remove_device_from_group: OK [ 564.054719] Testing event add_device_to_group: OK [ 564.570813] Testing event regcache_drop_region: OK [ 565.078251] Testing event regmap_async_complete_done: OK [ 565.673681] Testing event regmap_async_complete_start: OK [ 566.199824] Testing event regmap_async_io_complete: OK [ 566.652054] Testing event regmap_async_write_start: OK [ 567.191105] Testing event regmap_cache_bypass: OK [ 567.747448] Testing event regmap_cache_only: OK [ 568.314633] Testing event regcache_sync: OK [ 568.828847] Testing event regmap_hw_write_done: OK [ 569.391454] Testing event regmap_hw_write_start: OK [ 569.945127] Testing event regmap_hw_read_done: OK [ 570.507855] Testing event regmap_hw_read_start: OK [ 570.958234] Testing event regmap_reg_read_cache: OK [ 571.346405] Testing event regmap_reg_read: OK [ 571.998642] Testing event regmap_reg_write: OK [ 572.341071] Testing event dma_fence_wait_end: OK [ 572.807900] Testing event dma_fence_wait_start: OK [ 573.288180] Testing event dma_fence_signaled: OK [ 573.696766] Testing event dma_fence_enable_signal: OK [ 574.302364] Testing event dma_fence_destroy: OK [ 574.791493] Testing event dma_fence_init: OK [ 575.182672] Testing event dma_fence_emit: OK [ 575.708229] Testing event sync_timeline: OK [ 576.162169] Testing event spi_transfer_stop: OK [ 576.675916] Testing event spi_transfer_start: OK [ 577.195559] Testing event spi_message_done: OK [ 577.596508] Testing event spi_message_start: OK [ 578.160854] Testing event spi_message_submit: OK [ 578.727360] Testing event spi_controller_busy: OK [ 579.268548] Testing event spi_controller_idle: OK [ 579.749738] Testing event rtc_timer_fired: OK [ 580.193624] Testing event rtc_timer_dequeue: OK [ 580.682216] Testing event rtc_timer_enqueue: OK [ 581.165926] Testing event rtc_read_offset: OK [ 581.578392] Testing event rtc_set_offset: OK [ 582.057338] Testing event rtc_alarm_irq_enable: OK [ 582.740387] Testing event rtc_irq_set_state: OK [ 583.207947] Testing event rtc_irq_set_freq: OK [ 583.694345] Testing event rtc_read_alarm: OK [ 584.209859] Testing event rtc_set_alarm: OK [ 584.728235] Testing event rtc_read_time: OK [ 585.257104] Testing event rtc_set_time: OK [ 585.759231] Testing event memory_failure_event: OK [ 586.214931] Testing event aer_event: OK [ 586.793256] Testing event non_standard_event: OK [ 587.320821] Testing event arm_event: OK [ 587.732056] Testing event mc_event: OK [ 588.266536] Testing event binder_return: OK [ 588.705708] Testing event binder_command: OK [ 589.268638] Testing event binder_unmap_kernel_end: OK [ 589.622621] Testing event binder_unmap_kernel_start: OK [ 590.119753] Testing event binder_unmap_user_end: OK [ 590.575486] Testing event binder_unmap_user_start: OK [ 591.040420] Testing event binder_alloc_page_end: OK [ 591.553412] Testing event binder_alloc_page_start: OK [ 592.003975] Testing event binder_free_lru_end: OK [ 592.556679] Testing event binder_free_lru_start: OK [ 593.086010] Testing event binder_alloc_lru_end: OK [ 593.579574] Testing event binder_alloc_lru_start: OK [ 594.122289] Testing event binder_update_page_range: OK [ 594.626019] Testing event binder_transaction_failed_buffer_release: OK [ 595.056938] Testing event binder_transaction_buffer_release: OK [ 595.781556] Testing event binder_transaction_alloc_buf: OK [ 596.178263] Testing event binder_transaction_fd_recv: OK [ 596.724743] Testing event binder_transaction_fd_send: OK [ 597.270785] Testing event binder_transaction_ref_to_ref: OK [ 597.621444] Testing event binder_transaction_ref_to_node: OK [ 598.072911] Testing event binder_transaction_node_to_ref: OK [ 598.606912] Testing event binder_transaction_received: OK [ 599.302834] Testing event binder_transaction: OK [ 599.636812] Testing event binder_wait_for_work: OK [ 600.201113] Testing event binder_read_done: OK [ 600.660652] Testing event binder_write_done: OK [ 601.229826] Testing event binder_ioctl_done: OK [ 601.730397] Testing event binder_unlock: OK [ 602.142170] Testing event binder_locked: OK [ 602.722752] Testing event binder_lock: OK [ 603.280393] Testing event binder_ioctl: OK [ 603.619100] Testing event br_fdb_update: OK [ 604.096052] Testing event fdb_delete: OK [ 604.677380] Testing event br_fdb_external_learn_add: OK [ 605.250209] Testing event br_fdb_add: OK [ 605.752928] Testing event qdisc_dequeue: OK [ 606.268268] Testing event fib_table_lookup: OK [ 606.863524] Testing event tcp_probe: OK [ 607.408390] Testing event tcp_retransmit_synack: OK [ 607.850047] Testing event tcp_rcv_space_adjust: OK [ 608.338795] Testing event tcp_destroy_sock: OK [ 608.770287] Testing event tcp_receive_reset: OK [ 609.329112] Testing event tcp_send_reset: OK [ 609.896112] Testing event tcp_retransmit_skb: OK [ 610.393108] Testing event udp_fail_queue_rcv_skb: OK [ 610.832687] Testing event inet_sock_set_state: OK [ 611.304652] Testing event sock_exceed_buf_limit: OK [ 611.794510] Testing event sock_rcvqueue_full: OK [ 612.225818] Testing event napi_poll: OK [ 612.829587] Testing event netif_rx_ni_entry: OK [ 613.330689] Testing event netif_rx_entry: OK [ 613.970717] Testing event netif_receive_skb_list_entry: OK [ 614.526896] Testing event netif_receive_skb_entry: OK [ 615.009958] Testing event napi_gro_receive_entry: OK [ 615.597537] Testing event napi_gro_frags_entry: OK [ 616.085060] Testing event netif_rx: OK [ 616.547302] Testing event netif_receive_skb: OK [ 617.075167] Testing event net_dev_queue: OK [ 617.468814] Testing event net_dev_xmit: OK [ 618.005803] Testing event net_dev_start_xmit: OK [ 618.490967] Testing event skb_copy_datagram_iovec: OK [ 619.062058] Testing event consume_skb: OK [ 619.709586] Testing event kfree_skb: OK [ 620.224769] Testing event 9p_protocol_dump: OK [ 620.759986] Testing event 9p_client_res: OK [ 621.230323] Testing event 9p_client_req: OK [ 621.746399] Running tests on trace event systems: [ 621.757075] Testing event system 9p: OK [ 622.336013] Testing event system skb: OK [ 622.929905] Testing event system net: OK [ 623.584764] Testing event system napi: OK [ 624.167877] Testing event system sock: OK [ 624.593346] Testing event system udp: OK [ 625.029537] Testing event system tcp: OK [ 625.652625] Testing event system fib: OK [ 626.318098] Testing event system qdisc: OK [ 626.857664] Testing event system bridge: OK [ 627.369737] Testing event system binder: OK [ 628.264225] Testing event system ras: OK [ 629.070281] Testing event system rtc: OK [ 629.868744] Testing event system spi: OK [ 630.474118] Testing event system sync_trace: OK [ 631.038447] Testing event system dma_fence: OK [ 631.676560] Testing event system regmap: OK [ 632.395057] Testing event system iommu: OK [ 633.124559] Testing event system random: OK [ 633.931482] Testing event system regulator: OK [ 634.572638] Testing event system rpmh: OK [ 635.129621] Testing event system clk: OK [ 635.948198] Testing event system gpio: OK [ 636.542332] Testing event system wbt: OK [ 637.131172] Testing event system block: OK [ 637.873215] Testing event system filelock: OK [ 638.640469] Testing event system fs_dax: OK [ 639.487149] Testing event system writeback: OK [ 640.275635] Testing event system page_ref: OK [ 642.677709] Testing event system cma: OK [ 643.263439] Testing event system page_isolation: OK [ 643.809239] Testing event system huge_memory: OK [ 644.366322] Testing event system migrate: OK [ 644.827183] Testing event system compaction: OK [ 645.498573] Testing event system kmem: OK [ 646.286248] Testing event system percpu: OK [ 646.957425] Testing event system vmscan: OK [ 647.773091] Testing event system pagemap: OK [ 648.424097] Testing event system oom: OK [ 649.016853] Testing event system filemap: OK [ 649.651257] Testing event system rseq: OK [ 650.246224] Testing event system xdp: OK [ 650.829861] Testing event system benchmark: OK [ 651.635803] Testing event system rpm: OK [ 652.192363] Testing event system power: OK [ 653.261750] Testing event system test: OK [ 654.025325] Testing event system preemptirq: OK [ 655.334156] Testing event system cgroup: OK [ 656.017508] Testing event system module: OK [ 656.659529] Testing event system alarmtimer: OK [ 657.261222] Testing event system timer: OK [ 657.912670] Testing event system swiotlb: OK [ 658.481294] Testing event system rcu: OK [ 659.420935] Testing event system printk: OK [ 660.187463] Testing event system lock: OK [ 660.747116] Testing event system sched: OK [ 661.678378] Testing event system workqueue: OK [ 662.153438] Testing event system signal: OK [ 662.947519] Testing event system irq: OK [ 663.560187] Testing event system cpuhp: OK [ 664.062887] Testing event system task: OK [ 664.579337] Testing event system kvm: OK [ 665.630974] Testing event system emulation: OK [ 666.391429] Testing event system ipi: OK [ 666.895994] Testing event system raw_syscalls: OK [ 667.382205] Testing event system initcall: OK [ 668.070489] Running tests on all trace events: [ 668.080841] Testing all events: OK [ 686.456148] ftrace_replace_code: 1 [ 693.196956] ../kernel/trace/ftrace.c:2442, loop_counter: 41939 [ 693.208420] Testing ftrace filter: OK [ 693.295533] trace_kprobe: Testing kprobe tracing: OK [ 694.063530] Loading compiled-in X.509 certificates [ 694.166637] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 694.168584] Loaded X.509 cert 'Build time autogenerated kernel key: ef4425a12675de91b49c005bf61fbcc152bd6e38' [ 694.184858] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 694.186735] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 694.215627] zswap: default zpool zbud not available [ 694.216587] zswap: pool creation failed [ 694.243153] page_owner is disabled [ 694.371401] cryptomgr_probe (1253) used greatest stack depth: 11920 bytes left [ 694.376112] Key type big_key registered [ 694.435319] Key type encrypted registered [ 694.440367] ima: No TPM chip found, activating TPM-bypass! [ 694.442637] ima: Allocated hash algorithm: sha1 [ 694.446263] No architecture policies found [ 694.453877] evm: Initialising EVM extended attributes: [ 694.454626] evm: security.selinux [ 694.455247] evm: security.SMACK64 [ 694.455852] evm: security.SMACK64EXEC [ 694.456371] evm: security.SMACK64TRANSMUTE [ 694.456897] evm: security.SMACK64MMAP [ 694.457910] evm: security.apparmor [ 694.458411] evm: security.ima [ 694.458876] evm: security.capability [ 694.459395] evm: HMAC attrs: 0x1 [ 694.519556] hctosys: unable to open rtc device (rtc0) [ 694.535768] psci_checker: PSCI checker started using 1 CPUs [ 694.536387] psci_checker: Starting hotplug tests [ 694.538245] psci_checker: Trying to turn off and on again all CPUs [ 694.539338] psci_checker: Trying to turn off and on again group 0 (CPUs 0) [ 694.540475] psci_checker: Hotplug tests passed OK [ 694.541812] psci_checker: Starting suspend tests (10 cycles per state) [ 694.542740] psci_checker: cpuidle not available on CPU 0, ignoring [ 694.543678] psci_checker: Could not start suspend tests on any CPU [ 694.544296] psci_checker: PSCI checker completed [ 694.601777] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 694.767582] ### dt-test ### start of unittest - you will see error messages [ 694.790442] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 694.791738] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 694.792960] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 694.794423] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 694.795307] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 694.796241] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 694.811191] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 694.813023] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 694.815176] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 695.017048] kobject: 'testcase-data:platform-tests:test-device@1' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 695.031327] kobject: 'testcase-data:platform-tests:test-device@0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 695.041051] kobject: 'unittest-bus.0' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 695.165887] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status [ 695.208988] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status [ 695.223651] _warn_unseeded_randomness: 4 callbacks suppressed [ 695.223905] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 695.226146] kobject: 'testcase-data:overlay-node:test-bus:test-unittest1' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 695.234659] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status [ 695.238898] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 695.276469] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status [ 695.289047] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 695.290481] kobject: 'testcase-data:overlay-node:test-bus:test-unittest3' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 695.345663] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status [ 695.394530] kobject: 'testcase-data:overlay-node:test-bus:test-unittest5' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 695.404633] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status [ 695.443651] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status [ 695.492691] kobject: 'testcase-data:overlay-node:test-bus:test-unittest7' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 695.506266] kobject: 'testcase-data:overlay-node:test-bus:test-unittest6' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 695.514651] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status [ 695.552449] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo [ 695.555110] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 695.556243] OF: overlay: overlay #6 is not topmost [ 695.570213] kobject: 'testcase-data:overlay-node:test-bus:test-unittest8' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 695.772590] kobject: 'testcase-data:overlay-node:test-bus:test-unittest11:test-unittest111' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 695.784495] kobject: 'testcase-data:overlay-node:test-bus:test-unittest11' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 695.786664] 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 [ 695.788482] 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 [ 695.791004] OF: overlay: remove: Could not find overlay #7 [ 695.791760] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #7 [ 695.807729] kobject: 'testcase-data:overlay-node:test-bus:test-unittest10:test-unittest101' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 695.820026] kobject: 'testcase-data:overlay-node:test-bus:test-unittest10' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 695.822164] 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 [ 695.823971] 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 [ 695.838397] kobject: 'testcase-data:overlay-node:test-bus:test-unittest4' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 695.839983] 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 [ 695.886503] kobject: 'testcase-data:overlay-node:test-bus:test-unittest2' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 695.932531] kobject: 'testcase-data:overlay-node:test-bus:test-unittest0' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 695.995727] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/status [ 696.000024] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/status [ 696.002564] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@30/incline-up [ 696.004023] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@40/incline-up [ 696.014266] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/status [ 696.015498] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/color [ 696.016765] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/rate [ 696.019127] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/hvac_2 [ 696.020637] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200 [ 696.022694] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_left [ 696.023996] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_right [ 696.111812] OF: overlay: ERROR: multiple fragments add and/or delete node /testcase-data-2/substation@100/motor-1/controller [ 696.115635] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/controller/name [ 696.117919] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 696.119407] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 696.127118] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 696.128526] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 696.130401] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/rpm_avail [ 696.144278] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 696.146254] ### dt-test ### end of unittest - 217 passed, 0 failed [ 696.175174] uart-pl011 9000000.pl011: no DMA platform data [ 696.435518] _warn_unseeded_randomness: 15 callbacks suppressed [ 696.435776] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 696.437211] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 696.482837] VFS: Mounted root (9p filesystem) readonly on device 0:19. [ 696.501723] devtmpfs: mounted [ 696.523215] integrity: Unable to open file: /etc/keys/x509_ima.der (-2) [ 696.538166] integrity: Unable to open file: /etc/keys/x509_evm.der (-2) [ 697.234975] Freeing unused kernel memory: 6144K [ 697.238887] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 697.239795] kobject: 'test-unittest111' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 697.653544] Checked W+X mappings: passed, no W+X pages found [ 697.654415] rodata_test: all tests were successful [ 697.655427] Run /sbin/init as init process [ 697.687017] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 697.770313] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=0 [ 697.772299] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=0 [ 697.839330] kobject: 'test-unittest101' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 697.871174] kobject: 'test-unittest4' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 698.255548] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11/test-unittest111 [ 698.652565] random: fast init done [ 698.863902] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest10/test-unittest101 [ 698.994073] _warn_unseeded_randomness: 5 callbacks suppressed [ 698.994341] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=1 [ 698.995562] kobject: 'test-unittest10' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 699.015018] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 699.888486] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest4 [ 700.016413] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=1 [ 700.017941] kobject: 'test-unittest11' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 700.249433] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 701.008911] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest10 SELinux: Could not open policy file <= /etc/selinux/targeted/policy/policy.31: No such file or directory [ 702.450525] systemd[1]: System time before build time, advancing clock. [ 702.944626] systemd[1]: Failed to insert module 'autofs4': No such file or directory [ 703.086876] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11 [ 705.765042] random: systemd: uninitialized urandom read (16 bytes read) [ 706.086097] random: systemd: uninitialized urandom read (16 bytes read) [ 706.091610] random: systemd: uninitialized urandom read (16 bytes read) [ 710.848637] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN) [ 710.924518] systemd[1]: Detected virtualization qemu. [ 710.931882] systemd[1]: Detected architecture arm64. Welcome to Debian GNU/Linux 9 (stretch)! [ 711.075741] systemd[1]: Set hostname to . [ 711.255669] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=1 [ 711.318772] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 711.960377] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 712.451461] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 712.452589] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 712.454499] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 713.618270] _warn_unseeded_randomness: 19 callbacks suppressed [ 713.618525] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 713.626959] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 713.628151] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 714.768766] random: systemd: uninitialized urandom read (16 bytes read) [ 714.871923] _warn_unseeded_randomness: 13 callbacks suppressed [ 714.872182] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 714.874233] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 714.903320] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 716.582981] _warn_unseeded_randomness: 11 callbacks suppressed [ 716.583233] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 716.584794] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 716.586901] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 717.739893] _warn_unseeded_randomness: 7 callbacks suppressed [ 717.740149] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 719.484606] systemd-hiberna (1268) used greatest stack depth: 8 bytes left [ 721.132020] random: systemd-cryptse: uninitialized urandom read (16 bytes read) [ 721.744509] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 722.866890] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 724.702737] random: systemd: uninitialized urandom read (16 bytes read) [ 724.908806] random: systemd: uninitialized urandom read (16 bytes read) [ 724.914914] random: systemd: uninitialized urandom read (16 bytes read) [ 724.992491] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 725.114483] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 732.113057] systemd[1]: networking.service: Cannot add dependency job, ignoring: Unit networking.service is masked. [ 732.184236] random: get_random_bytes called from generate_random_uuid+0x20/0x50 with crng_init=1 [ 732.268091] systemd[1]: Listening on Journal Audit Socket. [ OK ] Listening on Journal Audit Socket. [ 732.327707] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 732.342979] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 732.390908] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 732.436975] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ 732.472866] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 732.491419] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 732.513938] systemd[1]: Starting of Arbitrary Executable File Formats File System Automount Point not supported. [UNSUPP] Starting of Arbitrary Executable Fi…tem Automount Point not supported. [ 732.615436] systemd[1]: Created slice System Slice. [ OK ] Created slice System Slice. [ 732.816793] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 Mounting POSIX Message Queue File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ 733.603710] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 733.604899] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 733.606458] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 Starting Create Static Device Nodes in /dev... Starting Remount Root and Kernel File Systems... [ 736.196915] _warn_unseeded_randomness: 11 callbacks suppressed [ 736.197179] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 736.199038] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 736.216269] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 Mounting Debug File System... [ 737.283938] _warn_unseeded_randomness: 5 callbacks suppressed [ 737.284211] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 739.099436] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 739.100487] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 739.101818] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 739.660732] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 740.856203] _warn_unseeded_randomness: 8 callbacks suppressed [ 740.856458] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 741.995046] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 741.996299] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 741.997854] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 742.356165] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 742.479769] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 742.555969] systemd[1]: Reached target Encrypted Volumes. [ OK ] Reached target Encrypted Volumes. [ 742.703767] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 742.920258] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ OK ] Listening on /dev/initctl Compatibility Named Pipe. [ 743.147725] _warn_unseeded_randomness: 3 callbacks suppressed [ 743.147965] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 743.150254] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 743.151087] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 743.367703] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ 743.476797] systemd[1]: Listening on Syslog Socket. [ OK ] Listening on Syslog Socket. [ 743.560153] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ 743.690875] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. [ 744.992908] systemd[1]: Starting Journal Service... Starting Journal Service... [ 746.295836] _warn_unseeded_randomness: 5 callbacks suppressed [ 746.296095] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 746.375267] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 746.391347] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 747.056244] systemd[1]: Mounted Debug File System. [ OK ] Mounted Debug File System. [ 747.097969] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 747.146286] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 747.640456] systemd[1]: Started Create Static Device Nodes in /dev. [ OK ] Started Create Static Device Nodes in /dev. [ 748.144433] systemd[1]: Started Remount Root and Kernel File Systems. [ OK ] Started Remount Root and Kernel File Systems. [ 748.753035] systemd[1]: Started Load Kernel Modules. [ OK ] Started Load Kernel Modules. [ 749.312169] _warn_unseeded_randomness: 9 callbacks suppressed [ 749.312426] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 749.597688] systemd[1]: Mounting Configuration File System... Mounting Configuration File System... [ 750.934671] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 751.022423] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 751.023620] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 751.024620] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 752.196491] _warn_unseeded_randomness: 4 callbacks suppressed [ 752.196752] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 752.199005] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 752.304165] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 753.455384] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 753.631822] systemd-journald[1288]: /dev/kmsg buffer overrun, some messages lost. [ 755.175385] _warn_unseeded_randomness: 2 callbacks suppressed [ 755.175628] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 755.177041] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 755.178492] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 755.912547] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 756.156077] systemd[1]: Reached target Local File Systems (Pre). [ 756.196796] _warn_unseeded_randomness: 2 callbacks suppressed [ 756.197064] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ OK ] Reached target Local File Systems (Pre). [ 756.348752] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 757.634014] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 757.635261] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 757.636177] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ 758.123789] systemd[1]: Starting udev Kernel Device Manager... Starting udev Kernel Device Manager... [ 760.120478] _warn_unseeded_randomness: 4 callbacks suppressed [ 760.120740] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 760.137267] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 760.213104] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 761.120428] systemd[1]: Mounted Configuration File System. [ OK ] Mounted Configuration File System. [ 762.102297] systemd[1]: Started Apply Kernel Variables. [ OK ] Started Apply Kernel Variables. [ 762.792070] _warn_unseeded_randomness: 9 callbacks suppressed [ 762.792328] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 763.350651] systemd[1]: Started Load/Save Random Seed. [ OK ] Started Load/Save Random Seed. [* ] (1 of 4) A start job is running for…dplug all Devices (36s / no limit) [** ] (1 of 4) A start job is running for…dplug all Devices (36s / no limit) [*** ] (1 of 4) A start job is running for…dplug all Devices (37s / no limit) [ *** ] (2 of 4) A start job is running for Journal Service (37s / 30min 12s) [ *** ] (2 of 4) A start job is running for Journal Service (38s / 30min 12s) [ ***] (2 of 4) A start job is running for Journal Service (38s / 30min 12s) [ **] (3 of 4) A start job is running for…l Device Manager (39s / 30min 25s) [ *] (3 of 4) A start job is running for…l Device Manager (39s / 30min 25s) [ **] (3 of 4) A start job is running for…l Device Manager (40s / 30min 25s) [ ***] (4 of 4) A start job is running for dev-ttyAMA0.device (40s / 30min) [ *** ] (4 of 4) A start job is running for dev-ttyAMA0.device (41s / 30min)[ 774.003756] systemd[1]: Started udev Kernel Device Manager. [ OK ] Started udev Kernel Device Manager. [ 777.581884] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [ *** ] (1 of 3) A start job is running for…dplug all Devices (46s / no limit) [*** ] (2 of 3) A start job is running for Journal Service (47s / 30min 12s) [** ] (2 of 3) A start job is running for Journal Service (47s / 30min 12s) [* ] (2 of 3) A start job is running for Journal Service (48s / 30min 12s) [** ] (3 of 3) A start job is running for dev-ttyAMA0.device (48s / 30min) [*** ] (3 of 3) A start job is running for dev-ttyAMA0.device (49s / 30min) [ *** ] (3 of 3) A start job is running for dev-ttyAMA0.device (49s / 30min) [ *** ] (1 of 3) A start job is running for…dplug all Devices (50s / no limit) [ ***] (1 of 3) A start job is running for…dplug all Devices (50s / no limit) [ **] (1 of 3) A start job is running for…dplug all Devices (51s / no limit) [ *] (2 of 3) A start job is running for Journal Service (51s / 30min 12s) [ **] (2 of 3) A start job is running for Journal Service (52s / 30min 12s) [ ***] (2 of 3) A start job is running for Journal Service (52s / 30min 12s) [ *** ] (3 of 3) A start job is running for dev-ttyAMA0.device (53s / 30min) [ *** ] (3 of 3) A start job is running for dev-ttyAMA0.device (53s / 30min) [*** ] (3 of 3) A start job is running for dev-ttyAMA0.device (54s / 30min) [** ] (1 of 3) A start job is running for…dplug all Devices (54s / no limit) [* ] (1 of 3) A start job is running for…dplug all Devices (55s / no limit) [** ] (1 of 3) A start job is running for…dplug all Devices (55s / no limit) [*** ] (2 of 3) A start job is running for Journal Service (56s / 30min 12s) [ *** ] (2 of 3) A start job is running for Journal Service (56s / 30min 12s) [ *** ] (2 of 3) A start job is running for Journal Service (57s / 30min 12s) [ ***] (3 of 3) A start job is running for dev-ttyAMA0.device (57s / 30min) [ **] (3 of 3) A start job is running for dev-ttyAMA0.device (58s / 30min) [ *] (3 of 3) A start job is running for dev-ttyAMA0.device (58s / 30min)[ 793.137806] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 793.138905] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 793.139856] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ **] (1 of 3) A start job is running for…g all Devices (1min 1s / no limit) [ ***] (1 of 3) A start job is running for…g all Devices (1min 1s / no limit) [ *** ] (1 of 3) A start job is running for…g all Devices (1min 1s / no limit) [ *** ] (2 of 3) A start job is running for…rnal Service (1min 2s / 30min 12s) [*** ] (2 of 3) A start job is running for…rnal Service (1min 2s / 30min 12s) [** ] (2 of 3) A start job is running for…rnal Service (1min 3s / 30min 12s) [* ] (3 of 3) A start job is running for…v-ttyAMA0.device (1min 3s / 30min) [** ] (3 of 3) A start job is running for…v-ttyAMA0.device (1min 4s / 30min) [*** ] (3 of 3) A start job is running for…v-ttyAMA0.device (1min 4s / 30min) [ *** ] (1 of 3) A start job is running for…g all Devices (1min 5s / no limit) [ *** ] (1 of 3) A start job is running for…g all Devices (1min 5s / no limit) [ ***] (1 of 3) A start job is running for…g all Devices (1min 6s / no limit) [ **] (2 of 3) A start job is running for…rnal Service (1min 6s / 30min 12s) [ *] (2 of 3) A start job is running for…rnal Service (1min 7s / 30min 12s) [ **] (2 of 3) A start job is running for…rnal Service (1min 7s / 30min 12s) [ ***] (3 of 3) A start job is running for…v-ttyAMA0.device (1min 8s / 30min) [ *** ] (3 of 3) A start job is running for…v-ttyAMA0.device (1min 8s / 30min) [ *** ] (3 of 3) A start job is running for…v-ttyAMA0.device (1min 9s / 30min) [*** ] (1 of 3) A start job is running for…g all Devices (1min 9s / no limit) [** ] (1 of 3) A start job is running for… all Devices (1min 10s / no limit)[ 803.154040] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [ 803.995998] _warn_unseeded_randomness: 5 callbacks suppressed [ 803.996266] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 Starting Flush Journal to Persistent Storage... [ 806.139971] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 806.141105] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 806.143292] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [* ] (1 of 3) A start job is running for… all Devices (1min 16s / no limit) [** ] (2 of 3) A start job is running for…tent Storage (1min 16s / 2min 41s) [*** ] (2 of 3) A start job is running for…tent Storage (1min 17s / 2min 41s)[ 810.464599] systemd-journald[1288]: Received request to flush runtime journal from PID 1 [ *** ] (2 of 3) A start job is running for…tent Storage (1min 18s / 2min 41s)[ 810.635273] _warn_unseeded_randomness: 4 callbacks suppressed [ 810.635539] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ OK ] Started Flush Journal to Persistent Storage. Starting Create Volatile Files and Directories... [ 814.273858] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 814.275100] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 814.275940] random: get_random_u32 called from arch_align_stack+0x2c/0x48 with crng_init=1 [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 24s / 30min) [ ***] (3 of 3) A start job is running for…-ttyAMA0.device (1min 24s / 30min) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (1min 25s / 30min) [ *] (1 of 3) A start job is running for… all Devices (1min 25s / no limit) [ **] (1 of 3) A start job is running for… all Devices (1min 26s / no limit) [ ***] (1 of 3) A start job is running for… all Devices (1min 26s / no limit) [ *** ] (2 of 3) A start job is running for… Directories (1min 27s / no limit) [ *** ] (2 of 3) A start job is running for… Directories (1min 27s / no limit) [*** ] (2 of 3) A start job is running for… Directories (1min 28s / no limit) [** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 28s / 30min) [* ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 29s / 30min) [** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 29s / 30min)[ 822.179486] random: crng init done [ 822.180076] random: 4 get_random_xx warning(s) missed due to ratelimiting [*** ] (1 of 3) A start job is running for… all Devices (1min 30s / no limit) [ *** ] (1 of 3) A start job is running for… all Devices (1min 30s / no limit) [ *** ] (1 of 3) A start job is running for… all Devices (1min 31s / no limit) [ ***] (2 of 3) A start job is running for… Directories (1min 31s / no limit) [ **] (2 of 3) A start job is running for… Directories (1min 32s / no limit) [ *] (2 of 3) A start job is running for… Directories (1min 32s / no limit) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (1min 33s / 30min) [ ***] (3 of 3) A start job is running for…-ttyAMA0.device (1min 33s / 30min) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 34s / 30min) [ *** ] (1 of 3) A start job is running for… all Devices (1min 34s / no limit) [*** ] (1 of 3) A start job is running for… all Devices (1min 35s / no limit) [** ] (1 of 3) A start job is running for… all Devices (1min 35s / no limit) [* ] (2 of 3) A start job is running for… Directories (1min 36s / no limit) [** ] (2 of 3) A start job is running for… Directories (1min 36s / no limit) [*** ] (2 of 3) A start job is running for… Directories (1min 37s / no limit) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 37s / 30min) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 38s / 30min) [ ***] (3 of 3) A start job is running for…-ttyAMA0.device (1min 38s / 30min) [ **] (1 of 3) A start job is running for… all Devices (1min 39s / no limit) [ *] (1 of 3) A start job is running for… all Devices (1min 39s / no limit) [ OK ] Started Create Volatile Files and Directories. Starting Network Time Synchronization... Starting Update UTMP about System Boot/Shutdown... [ **] (3 of 4) A start job is running for…-ttyAMA0.device (1min 46s / 30min) [ ***] (4 of 4) A start job is running for…oot/Shutdown (1min 46s / no limit) [ *** ] (4 of 4) A start job is running for…oot/Shutdown (1min 47s / no limit) [ *** ] (4 of 4) A start job is running for…oot/Shutdown (1min 47s / no limit) [*** ] (1 of 4) A start job is running for… all Devices (1min 48s / no limit) [** ] (1 of 4) A start job is running for… all Devices (1min 48s / no limit) [* ] (1 of 4) A start job is running for… all Devices (1min 49s / no limit) [** ] (2 of 4) A start job is running for…hronization (1min 49s / 31min 42s) [*** ] (2 of 4) A start job is running for…hronization (1min 50s / 31min 42s) [ *** ] (2 of 4) A start job is running for…hronization (1min 50s / 31min 42s) [ *** ] (3 of 4) A start job is running for…-ttyAMA0.device (1min 51s / 30min) [ ***] (3 of 4) A start job is running for…-ttyAMA0.device (1min 51s / 30min) [ **] (3 of 4) A start job is running for…-ttyAMA0.device (1min 52s / 30min) [ OK ] Started Update UTMP about System Boot/Shutdown. [ *] (3 of 3) A start job is running for…-ttyAMA0.device (1min 58s / 30min) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (1min 58s / 30min) [ ***] (3 of 3) A start job is running for…-ttyAMA0.device (1min 59s / 30min) [ *** ] (1 of 3) A start job is running for… all Devices (1min 59s / no limit) [ *** ] (1 of 3) A start job is running for…plug all Devices (2min / no limit) [*** ] (1 of 3) A start job is running for…plug all Devices (2min / no limit) [** ] (2 of 3) A start job is running for…chronization (2min 1s / 31min 42s) [* ] (2 of 3) A start job is running for…chronization (2min 1s / 31min 42s) [** ] (2 of 3) A start job is running for…chronization (2min 2s / 31min 42s) [*** ] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 2s / 30min) [ OK ] Started Network Time Synchronization. [ OK ] Reached target System Time Synchronized. [ *** ] (1 of 2) A start job is running for…g all Devices (2min 8s / no limit) [ *** ] (1 of 2) A start job is running for…g all Devices (2min 9s / no limit) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (2min 10s / 30min) [ **] (2 of 2) A start job is running for…-ttyAMA0.device (2min 10s / 30min) [ *] (2 of 2) A start job is running for…-ttyAMA0.device (2min 11s / 30min) [ **] (1 of 2) A start job is running for… all Devices (2min 11s / no limit) [ ***] (1 of 2) A start job is running for… all Devices (2min 12s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (2min 12s / no limit) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 13s / 30min) [*** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 13s / 30min) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 14s / 30min) [* ] (1 of 2) A start job is running for… all Devices (2min 14s / no limit) [** ] (1 of 2) A start job is running for… all Devices (2min 15s / no limit) [*** ] (1 of 2) A start job is running for… all Devices (2min 15s / no limit) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 16s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 16s / 30min) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (2min 17s / 30min) [ **] (1 of 2) A start job is running for… all Devices (2min 17s / no limit) [ *] (1 of 2) A start job is running for… all Devices (2min 18s / no limit) [ **] (1 of 2) A start job is running for… all Devices (2min 18s / no limit) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (2min 19s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 19s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 20s / 30min) [*** ] (1 of 2) A start job is running for… all Devices (2min 20s / no limit) [** ] (1 of 2) A start job is running for… all Devices (2min 21s / no limit) [* ] (1 of 2) A start job is running for… all Devices (2min 21s / no limit) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 22s / 30min) [*** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 22s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 23s / 30min) [ *** ] (1 of 2) A start job is running for… all Devices (2min 23s / no limit) [ ***] (1 of 2) A start job is running for… all Devices (2min 24s / no limit) [ **] (1 of 2) A start job is running for… all Devices (2min 24s / no limit) [ *] (2 of 2) A start job is running for…-ttyAMA0.device (2min 25s / 30min) [ **] (2 of 2) A start job is running for…-ttyAMA0.device (2min 25s / 30min) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (2min 26s / 30min) [ *** ] (1 of 2) A start job is running for… all Devices (2min 26s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (2min 27s / no limit) [*** ] (1 of 2) A start job is running for… all Devices (2min 27s / no limit) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 28s / 30min) [* ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 28s / 30min) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 29s / 30min) [ OK ] Found device /dev/ttyAMA0. [ 884.587177] virtio_net virtio0 enp0s1: renamed from eth0 [*** ] A start job is running for udev Col… all Devices (2min 35s / no limit) [ *** ] A start job is running for udev Col… all Devices (2min 36s / no limit) [ *** ] A start job is running for udev Col… all Devices (2min 36s / no limit) [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started Daily apt upgrade and clean activities. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started Regular background program processing daemon. Starting getty on tty2-tty6 if dbus and logind are not available... Starting System Logging Service... Starting Permit User Sessions... [ ***] (3 of 3) A start job is running for…ser Sessions (2min 55s / no limit) [ OK ] Started Permit User Sessions. [ OK ] Started System Logging Service. [ **] A start job is running for getty on…ot available (3min 10s / no limit) [ *] A start job is running for getty on…ot available (3min 12s / no limit) [ **] A start job is running for getty on…ot available (3min 15s / no limit) [ ***] A start job is running for getty on…ot available (3min 17s / no limit) [ *** ] A start job is running for getty on…ot available (3min 19s / no limit) [ *** ] A start job is running for getty on…ot available (3min 22s / no limit) [*** ] A start job is running for getty on…ot available (3min 23s / no limit) [ OK ] Started Getty on tty6. [ OK ] Started Getty on tty5. [ OK ] Started Getty on tty4. [ OK ] Started Getty on tty3. [ OK ] Started Serial Getty on ttyAMA0. [ OK ] Started Getty on tty1. [ OK ] Started Getty on tty2. [ 943.582379] kobject: 'vcs6' (00000000996a9b3a): kobject_release, parent (null) (delayed 750) [ 943.658080] kobject: 'vcsu6' (000000000c505325): kobject_release, parent (null) (delayed 500) [ 943.710077] kobject: 'vcsa6' (0000000078a45fea): kobject_release, parent (null) (delayed 250) [ 944.751321] kobject: 'vcs5' (0000000000569847): kobject_release, parent (null) (delayed 250) [ 944.818179] kobject: 'vcsu5' (00000000df4bcea7): kobject_release, parent (null) (delayed 1000) [ 944.887043] kobject: 'vcsa5' (00000000b494a2c3): kobject_release, parent (null) (delayed 1000) [ 946.096586] kobject: 'vcs4' (00000000f4c55578): kobject_release, parent (null) (delayed 1000) [ 946.197050] kobject: 'vcsu4' (000000000c505325): kobject_release, parent (null) (delayed 250) [ 946.263501] kobject: 'vcsa4' (0000000000569847): kobject_release, parent (null) (delayed 250) [ OK ] Started getty on tty2-tty6 if dbus and logind are not available. [ 947.619366] kobject: 'vcs3' (0000000078a45fea): kobject_release, parent (null) (delayed 250) [ 947.728998] kobject: 'vcsu3' (0000000090430699): kobject_release, parent (null) (delayed 750) [ 947.795857] kobject: 'vcsa3' (000000000c505325): kobject_release, parent (null) (delayed 750) [ 950.695595] kobject: 'vcs2' (0000000078a45fea): kobject_release, parent (null) (delayed 750) [ 950.795090] kobject: 'vcsu2' (00000000f4c55578): kobject_release, parent (null) (delayed 500) [ 950.904478] kobject: 'vcsa2' (00000000e730413c): kobject_release, parent (null) (delayed 750) Debian GNU/Linux 9 compilator ttyAMA0 compilator login: [ 1025.327827] systemd[1]: systemd-journald.service: Main process exited, code=killed, status=6/ABRT [ 1025.740612] systemd[1]: systemd-journald.service: Unit entered failed state. [ 1025.775764] systemd[1]: systemd-journald.service: Failed with result 'watchdog'. [ 1025.905049] systemd[1]: systemd-journald.service: Service has no hold-off time, scheduling restart. [ 1026.527456] systemd[1]: Stopped Flush Journal to Persistent Storage. [ 1026.556938] systemd[1]: Stopping Flush Journal to Persistent Storage... [ 1026.564738] systemd[1]: Stopped Journal Service. [ 1027.176428] systemd[1]: Starting Journal Service... [ 1032.432206] systemd-journald[1351]: File /run/log/journal/ee22564d41614722a231f845c5bb3c72/system.journal corrupted or uncleanly shut down, renaming and replacing. [ 1072.357101] systemd[1]: Started Journal Service.