[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 4.20.0-rc4-next-20181128-00012-g4fd2b21510f8 (anders@compilator) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05)) #42 SMP Fri Nov 30 12:21:32 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: 993196K/2097152K available (15872K kernel code, 3774K rwdata, 4852K rodata, 6144K init, 23374K bss, 94372K 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 41985 entries in 165 pages [ 0.000000] Running RCU self tests [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU dyntick-idle grace-period acceleration is enabled. [ 0.000000] rcu: RCU lockdep checking is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=1. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] rcu: RCU debug extended QS entry/exit. [ 0.000000] Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] GICv2m: range[mem 0x08020000-0x08020fff], SPI[80:143] [ 0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns [ 0.000235] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.017290] Console: colour dummy device 80x25 [ 0.023621] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.024341] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.024886] ... MAX_LOCK_DEPTH: 48 [ 0.025434] ... MAX_LOCKDEP_KEYS: 8191 [ 0.026009] ... CLASSHASH_SIZE: 4096 [ 0.026562] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.027123] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.028088] ... CHAINHASH_SIZE: 32768 [ 0.028664] memory used by lock dependency info: 7775 kB [ 0.029268] per task-struct memory footprint: 2688 bytes [ 0.029919] ------------------------ [ 0.030429] | Locking API testsuite: [ 0.030954] ---------------------------------------------------------------------------- [ 0.031661] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.032771] -------------------------------------------------------------------------- [ 0.033841] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.047044] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.061268] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.076584] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.092801] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.109529] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.126521] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.143980] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.156626] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.167999] -------------------------------------------------------------------------- [ 0.169235] recursive read-lock: | ok | | ok | [ 0.173557] recursive read-lock #2: | ok | | ok | [ 0.177982] mixed read-write-lock: | ok | | ok | [ 0.182425] mixed write-read-lock: | ok | | ok | [ 0.186938] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.191111] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.195830] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.200579] -------------------------------------------------------------------------- [ 0.201903] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.207886] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.214502] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.220569] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.226773] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.233418] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.239677] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.245739] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.252387] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.258494] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.265152] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.271761] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.278821] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.285267] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.292313] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.298752] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.305893] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.312172] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.319160] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.325187] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.331444] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.338209] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.344738] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.351763] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.358351] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.365164] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.371783] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.378694] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.385244] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.392147] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.398593] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.405351] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.411732] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.418387] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.424793] hard-irq lock-inversion/123: ok | ok | ok | [ 0.431528] soft-irq lock-inversion/123: ok | ok | ok | [ 0.438023] hard-irq lock-inversion/132: ok | ok | ok | [ 0.444822] soft-irq lock-inversion/132: ok | ok | ok | [ 0.451276] hard-irq lock-inversion/213: ok | ok | ok | [ 0.458044] soft-irq lock-inversion/213: ok | ok | ok | [ 0.464585] hard-irq lock-inversion/231: ok | ok | ok | [ 0.471462] soft-irq lock-inversion/231: ok | ok | ok | [ 0.478044] hard-irq lock-inversion/312: ok | ok | ok | [ 0.484876] soft-irq lock-inversion/312: ok | ok | ok | [ 0.491584] hard-irq lock-inversion/321: ok | ok | ok | [ 0.498428] soft-irq lock-inversion/321: ok | ok | ok | [ 0.504825] hard-irq read-recursion/123: ok | [ 0.507953] soft-irq read-recursion/123: ok | [ 0.510545] hard-irq read-recursion/132: ok | [ 0.513518] soft-irq read-recursion/132: ok | [ 0.516538] hard-irq read-recursion/213: ok | [ 0.519102] soft-irq read-recursion/213: ok | [ 0.522149] hard-irq read-recursion/231: ok | [ 0.525093] soft-irq read-recursion/231: ok | [ 0.527724] hard-irq read-recursion/312: ok | [ 0.530690] soft-irq read-recursion/312: ok | [ 0.533705] hard-irq read-recursion/321: ok | [ 0.536226] soft-irq read-recursion/321: ok | [ 0.539261] -------------------------------------------------------------------------- [ 0.539988] | Wound/wait tests | [ 0.540496] --------------------- [ 0.541026] ww api failures: ok | ok | ok | [ 0.549086] ww contexts mixing: ok | ok | [ 0.553408] finishing ww context: ok | ok | ok | ok | [ 0.561325] locking mismatches: ok | ok | ok | [ 0.567324] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.588180] spinlock nest unlocked: ok | [ 0.590414] ----------------------------------------------------- [ 0.591051] |block | try |context| [ 0.591720] ----------------------------------------------------- [ 0.592863] context: ok | ok | ok | [ 0.599034] try: ok | ok | ok | [ 0.604710] block: ok | ok | ok | [ 0.610441] spinlock: ok | ok | ok | [ 0.617156] ------------------------------------------------------- [ 0.617900] Good, all 261 testcases passed! | [ 0.618527] --------------------------------- [ 0.733910] ODEBUG: selftest passed [ 0.740095] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.741293] pid_max: default: 32768 minimum: 301 [ 0.757080] LSM: Security Framework initializing [ 0.758178] Yama: becoming mindful. [ 0.761679] LoadPin: ready to pin (currently enforcing) [ 0.762917] SELinux: Initializing. [ 0.774927] *** VALIDATE SELinux *** [ 0.775959] AppArmor: AppArmor disabled by boot time parameter [ 0.833405] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.856609] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.861174] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.862962] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.916355] *** VALIDATE proc *** [ 0.944508] *** VALIDATE cgroup1 *** [ 0.945211] *** VALIDATE cgroup2 *** [ 1.040378] ASID allocator initialised with 32768 entries [ 1.055899] rcu: Hierarchical SRCU implementation. [ 1.180069] EFI services will not be available. [ 1.218424] smp: Bringing up secondary CPUs ... [ 1.219871] smp: Brought up 1 node, 1 CPU [ 1.220504] SMP: Total of 1 processors activated. [ 1.221270] CPU features: detected: 32-bit EL0 Support [ 1.222040] CPU features: detected: CRC32 instructions [ 1.582455] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.584177] CPU: All CPU(s) started at EL1 [ 1.585505] alternatives: patching kernel code [ 1.587437] _warn_unseeded_randomness: 98 callbacks suppressed [ 1.587856] random: get_random_u64 called from compute_layout+0x138/0x1b8 with crng_init=0 [ 1.642806] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 5.765348] node 0 initialised, 248300 pages in 4ms [ 5.777081] pgdatinit0 (15) used greatest stack depth: 13280 bytes left [ 5.791706] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 5.898962] devtmpfs: initialized [ 6.151384] workqueue: round-robin CPU selection forced, expect performance impact [ 6.218311] gcov: version magic: 0x4137332a [ 6.254380] Registered cp15_barrier emulation handler [ 6.255981] Registered setend emulation handler [ 16.581195] DMA-API: preallocated 65536 debug entries [ 16.581983] DMA-API: debugging enabled by kernel config [ 16.583813] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 16.587675] futex hash table entries: 256 (order: 3, 32768 bytes) [ 16.595767] Running postponed tracer tests: [ 16.601192] Testing tracer function: [ 16.847760] ftrace_replace_code: 1 [ 33.671208] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 34.197373] ftrace_replace_code: 1 [ 45.340512] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 45.358097] PASSED [ 45.358664] Testing dynamic ftrace: [ 47.526502] ftrace_replace_code: 1 [ 47.681141] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 47.927898] PASSED [ 47.928707] Testing dynamic ftrace ops #1: [ 56.510526] ftrace_replace_code: 1 [ 56.667330] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 56.698547] (1 0 1 0 0) [ 56.698792] (1 1 2 0 0) [ 56.866856] ftrace_replace_code: 1 [ 100.484611] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 100.500554] (2 1 3 0 1590582) [ 100.501256] (2 2 4 0 1590597) [ 100.768619] ftrace_replace_code: 1 [ 118.264907] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 118.354950] ftrace_replace_code: 1 [ 118.507241] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 118.540360] ftrace_replace_code: 1 [ 118.693610] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 118.711611] PASSED [ 118.712145] Testing dynamic ftrace ops #2: [ 127.260806] ftrace_replace_code: 1 [ 127.418772] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 127.447082] ftrace_replace_code: 1 [ 127.601378] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 127.849674] ftrace_replace_code: 1 [ 166.628120] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 166.642722] (1 0 1 1528765 0) [ 166.643315] (1 1 2 1528780 0) [ 166.943820] (2 1 3 1 8304) [ 166.951936] (2 2 4 336 8639) [ 167.595994] ftrace_replace_code: 1 [ 178.740451] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 178.755736] PASSED [ 178.756250] Testing ftrace recursion: [ 180.918110] ftrace_replace_code: 1 [ 181.072182] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 181.100689] ftrace_replace_code: 1 [ 181.252743] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 181.268255] PASSED [ 181.268746] Testing ftrace recursion safe: [ 183.428163] ftrace_replace_code: 1 [ 183.582742] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 183.611317] ftrace_replace_code: 1 [ 183.763864] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 183.779049] PASSED [ 183.779605] Testing ftrace regs(no arch support): [ 185.939847] ftrace_replace_code: 1 [ 186.094780] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 186.123420] ftrace_replace_code: 1 [ 186.276577] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 186.292502] PASSED [ 186.293504] Testing tracer nop: PASSED [ 186.296694] Testing tracer irqsoff: [ 186.526289] ftrace_replace_code: 1 [ 204.463689] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 204.716426] ftrace_replace_code: 1 [ 216.008393] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 216.023987] PASSED [ 216.026955] Testing tracer wakeup: [ 216.272818] ftrace_replace_code: 1 [ 234.057172] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 234.299047] ftrace_replace_code: 1 [ 245.554920] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 245.577878] PASSED [ 245.579926] Testing tracer wakeup_rt: [ 245.824237] ftrace_replace_code: 1 [ 263.612781] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 263.864232] ftrace_replace_code: 1 [ 264.236829] sched: DL replenish lagged too much [ 275.117220] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 275.140312] PASSED [ 275.141388] Testing tracer wakeup_dl: [ 275.384920] ftrace_replace_code: 1 [ 293.172469] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 293.423268] ftrace_replace_code: 1 [ 304.711912] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 304.735377] PASSED [ 304.738345] Testing tracer function_graph: [ 304.981117] ftrace_replace_code: 1 [ 321.522119] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 716.236822] watchdog: BUG: soft lockup - CPU#0 stuck for 21s! [ksoftirqd/0:9] [ 716.252169] Modules linked in: [ 716.265062] irq event stamp: 73078 [ 716.278709] hardirqs last enabled at (73077): [] _raw_spin_unlock_irq+0x38/0x78 [ 716.298721] hardirqs last disabled at (73078): [] el1_irq+0xa8/0x1c0 [ 716.317737] softirqs last enabled at (182): [] __do_softirq+0x9a8/0x9f0 [ 716.336636] softirqs last disabled at (119): [] run_ksoftirqd+0x30/0x68 [ 716.354085] CPU: 0 PID: 9 Comm: ksoftirqd/0 Tainted: G T 4.20.0-rc4-next-20181128-00012-g4fd2b21510f8 #42 [ 716.373959] Hardware name: linux,dummy-virt (DT) [ 716.386764] pstate: 20400005 (nzCv daif +PAN -UAO) [ 716.401966] pc : _raw_spin_unlock_irq+0x3c/0x78 [ 716.416667] lr : _raw_spin_unlock_irq+0x38/0x78 [ 716.429248] sp : ffffff800b99bc90 [ 716.439883] x29: ffffff800b99bc90 x28: 0000000000000003 [ 716.456018] x27: ffffffc03f5dc040 x26: ffffff8009c15df8 [ 716.471851] x25: ffffff8009d12fb0 x24: 0000000000000000 [ 716.487659] x23: ffffff8008ee5f58 x22: ffffffc03f5dc040 [ 716.503496] x21: ffffffc03f624040 x20: ffffff800817dc78 [ 716.519385] x19: ffffffc07ebdf6c0 x18: 0000000034f4d91d [ 716.535312] x17: 0000000000000000 x16: 0000000000000000 [ 716.551330] x15: ffffff8009c6a580 x14: ffffff800ac4e690 [ 716.567216] x13: ffffff800ac4fe90 x12: ffffff800ac4fe90 [ 716.583317] x11: ffffff800ac4ff98 x10: ffffff800ac4ff98 [ 716.599374] x9 : 00000000000008e8 x8 : 0000000000904c96 [ 716.615385] x7 : 00000000000008b8 x6 : 0000004075191000 [ 716.631260] x5 : ffffff8009cb82b0 x4 : ffffff8009c15bb0 [ 716.647209] x3 : 00000000000fe65e x2 : 00000000000fe65e [ 716.663085] x1 : 0000000000000001 x0 : 00000000000fe65e [ 716.679544] Call trace: [ 716.691974] _raw_spin_unlock_irq+0x3c/0x78 [ 716.706418] finish_task_switch+0x160/0x220 [ 716.721249] __schedule+0xa78/0xaf8 [ 716.734807] preempt_schedule_common+0x4c/0x90 [ 716.749530] _cond_resched+0x40/0x50 [ 716.763167] run_ksoftirqd+0x50/0x68 [ 716.777031] smpboot_thread_fn+0x400/0x428 [ 716.791087] kthread+0x154/0x170 [ 716.804064] ret_from_fork+0x10/0x18 [ 717.391227] ftrace_replace_code: 1 [ 732.552779] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 732.569909] PASSED [ 732.792548] _warn_unseeded_randomness: 7 callbacks suppressed [ 732.792825] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 732.795288] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 732.798183] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 732.824496] prandom: seed boundary self test passed [ 732.866764] prandom: 100 self tests passed [ 732.875612] pinctrl core: initialized pinctrl subsystem [ 733.016752] regulator-dummy: Failed to create debugfs directory [ 733.028427] [ 733.028969] ************************************************************* [ 733.030352] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 733.031104] ** ** [ 733.031805] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 733.032503] ** ** [ 733.033201] ** This means that this kernel is built to expose internal ** [ 733.033909] ** IOMMU data structures, which may compromise security on ** [ 733.035211] ** your system. ** [ 733.035957] ** ** [ 733.036660] ** If you see this message and you are not debugging the ** [ 733.037359] ** kernel, report this immediately to your vendor! ** [ 733.038067] ** ** [ 733.039282] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 733.039993] ************************************************************* [ 733.081019] DMI not present or invalid. [ 733.156020] NET: Registered protocol family 16 [ 733.193183] audit: initializing netlink subsys (disabled) [ 733.395753] cpuidle: using governor ladder [ 733.398437] cpuidle: using governor menu [ 733.426627] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 733.429599] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 733.435997] audit: type=2000 audit(322.516:1): state=initialized audit_enabled=0 res=1 [ 733.648900] DMA: preallocated 256 KiB pool for atomic allocations [ 733.772379] Serial: AMBA PL011 UART driver [ 734.858450] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 734.862416] printk: console [ttyAMA0] enabled [ 734.862416] printk: console [ttyAMA0] enabled [ 734.863941] printk: bootconsole [pl11] disabled [ 734.863941] printk: bootconsole [pl11] disabled [ 735.010059] _warn_unseeded_randomness: 42 callbacks suppressed [ 735.010321] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 735.013188] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 735.732079] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 735.813314] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 736.003326] kobject: 'renesas,r9a06g032-sysctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 736.017653] kobject: 'renesas-cpg-mssr' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 736.090262] arm-v7s io-pgtable: self test ok [ 736.092997] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 736.103169] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 736.110948] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 736.119539] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 736.129443] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 736.138088] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 736.147031] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 736.159400] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 736.173171] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 736.187936] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 736.202020] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 736.216286] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 736.233695] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 736.269194] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 736.305460] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 736.342343] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 736.384893] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 736.428019] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 736.470978] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 736.514768] vgaarb: loaded [ 736.539421] _warn_unseeded_randomness: 34 callbacks suppressed [ 736.539690] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 736.542098] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 736.579816] pps_core: LinuxPPS API ver. 1 registered [ 736.580877] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 736.586704] PTP clock support registered [ 736.599231] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 736.601204] kobject: 'brcmstb-reboot' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 736.820731] NetLabel: Initializing [ 736.821563] NetLabel: domain hash size = 128 [ 736.823106] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 736.835045] NetLabel: unlabeled traffic allowed by default [ 736.863218] clocksource: Switched to clocksource arch_sys_counter [ 743.159936] _warn_unseeded_randomness: 4 callbacks suppressed [ 743.160200] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 743.163234] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 743.167529] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 743.188090] VFS: Disk quotas dquot_6.6.0 [ 743.195060] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 743.232186] *** VALIDATE hugetlbfs *** [ 744.372133] _warn_unseeded_randomness: 7 callbacks suppressed [ 744.372396] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 744.374857] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 744.377338] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 744.384479] NET: Registered protocol family 2 [ 744.440508] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 744.447811] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 744.481262] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 744.494871] TCP: Hash tables configured (established 16384 bind 16384) [ 744.507226] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 744.515194] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 744.540004] NET: Registered protocol family 1 [ 744.546343] NET: Registered protocol family 44 [ 744.652610] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 744.667612] kvm [1]: HYP mode not available [ 745.064083] Kprobe smoke test: started [ 746.366569] Kprobe smoke test: passed successfully [ 746.456921] Initialise system trusted keyrings [ 746.459710] _warn_unseeded_randomness: 50 callbacks suppressed [ 746.459972] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 746.472038] Key type blacklist registered [ 746.479889] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 746.497106] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 749.120316] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 749.122418] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 749.152369] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 749.416014] 9p: Installing v9fs 9p2000 file system support [ 749.564105] Allocating IMA blacklist keyring. [ 749.585254] cryptomgr_test (37) used greatest stack depth: 12400 bytes left [ 749.616674] kworker/u2:1 (38) used greatest stack depth: 11936 bytes left [ 751.215108] Key type asymmetric registered [ 751.217372] Asymmetric key parser 'x509' registered [ 751.226904] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 751.244866] _warn_unseeded_randomness: 26 callbacks suppressed [ 751.245135] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 751.247767] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 751.295735] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 751.531473] kobject: 'brcm-gisb-arb' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 751.679845] kobject: 'armada-37xx-pinctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 751.961230] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 751.971449] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 752.002913] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 752.040655] kobject: 'stmpe-pwm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 752.078436] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 752.188892] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 752.192601] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 752.196333] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 752.198751] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 752.204271] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 752.226968] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 752.228245] pci_bus 0000:00: root bus resource [bus 00-0f] [ 752.229339] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 752.231027] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 752.232053] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 752.237289] pci 0000:00:00.0: calling quirk_mmio_always_on+0x0/0x38 @ 1 [ 752.239158] pci 0000:00:00.0: quirk_mmio_always_on+0x0/0x38 took 133 usecs [ 752.362748] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 752.364063] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 752.366337] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 752.367875] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 752.369372] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 752.371167] pci 0000:00:03.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 752.372303] pci 0000:00:03.0: BAR 0: assigned [io 0x1000-0x103f] [ 752.373389] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 752.375096] pci 0000:00:02.0: BAR 0: assigned [io 0x1060-0x107f] [ 752.379420] pci 0000:00:00.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 752.380736] pci 0000:00:00.0: tegra_pcie_relax_enable+0x0/0x40 took 210 usecs [ 752.384403] pci 0000:00:01.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 752.386427] pci 0000:00:01.0: tegra_pcie_relax_enable+0x0/0x40 took 93 usecs [ 752.388305] pci 0000:00:02.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 752.390300] pci 0000:00:02.0: tegra_pcie_relax_enable+0x0/0x40 took 829 usecs [ 752.392145] pci 0000:00:03.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 752.393353] pci 0000:00:03.0: tegra_pcie_relax_enable+0x0/0x40 took 96 usecs [ 752.467593] _warn_unseeded_randomness: 15 callbacks suppressed [ 752.467855] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 752.469398] kobject: 'layerscape-pcie' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 752.505331] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 753.250611] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 753.295792] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 753.296924] kobject: 'virtio0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 753.344152] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 753.378445] kobject: 'virtio1' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 753.427323] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 753.460546] kobject: 'virtio2' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 753.478890] _warn_unseeded_randomness: 4 callbacks suppressed [ 753.479168] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 760.588280] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 760.799046] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 760.800175] kobject: 'ttyS0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 760.801349] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 760.804207] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 760.805121] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 760.899628] kobject: 'ttyS1' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 760.902171] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 760.992827] kobject: 'ttyS2' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 760.995431] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 761.088613] kobject: 'ttyS3' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 761.091200] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 761.220739] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 761.239588] KGDB: Registered I/O driver kgdbts [ 761.240791] kgdbts:RUN plant and detach test Entering kdb (current=0xffffffc03f558040, pid 1) on processor 0 due to Keyboard Entry [0]kdb> [ 761.252470] kgdbts:RUN sw breakpoint test [ 761.270836] kgdbts:RUN bad memory access test [ 761.274595] kgdbts:RUN singlestep test 1000 iterations [ 761.309248] kgdbts:RUN singlestep [0/1000] [ 764.557719] kgdbts:RUN singlestep [100/1000] [ 767.806457] kgdbts:RUN singlestep [200/1000] [ 771.051863] kgdbts:RUN singlestep [300/1000] [ 774.299976] kgdbts:RUN singlestep [400/1000] [ 777.544680] kgdbts:RUN singlestep [500/1000] [ 780.790515] kgdbts:RUN singlestep [600/1000] [ 784.038376] kgdbts:RUN singlestep [700/1000] [ 787.293775] kgdbts:RUN singlestep [800/1000] [ 790.541277] kgdbts:RUN singlestep [900/1000] [ 793.758221] kgdbts:RUN do_fork for 100 breakpoints [ 793.766490] _warn_unseeded_randomness: 7 callbacks suppressed [ 793.766787] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 795.842458] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 795.843716] kobject: 'rx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 795.851095] kobject: 'tx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 795.880307] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 795.935240] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 795.936473] kobject: 'net' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 796.844872] KGDB: Unregistered I/O driver kgdbts, debugger disabled [ 797.886968] kobject: 'queues' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 797.991889] kobject: '44' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 798.015253] kobject: 'eth0' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 798.039519] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 798.296137] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 798.297368] kobject: '45' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 798.306795] kobject: '46' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 798.310421] ledtrig-cpu: registered to indicate activity on CPUs [ 798.322088] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 798.337217] kobject: 'meson-sm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 798.440133] ashmem: initialized [ 798.737228] 9pnet: Installing 9P2000 support [ 799.255844] _warn_unseeded_randomness: 9 callbacks suppressed [ 799.256074] random: get_random_bytes called from init_oops_id+0x38/0x60 with crng_init=0 [ 799.279821] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 799.280950] kobject: '47' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 799.284254] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 799.291692] kobject: '48' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 799.372691] registered taskstats version 1 [ 799.375246] Running ring buffer tests... [ 809.557092] finished [ 809.559308] CPU 0: [ 809.559991] events: 5398 [ 809.560727] dropped bytes: 0 [ 809.562226] alloced bytes: 420540 [ 809.562980] written bytes: 411961 [ 809.563685] biggest event: 86 [ 809.564383] smallest event: 0 [ 810.010090] read events: 5398 [ 810.010799] lost events: 0 [ 810.011535] total events: 5398 [ 810.012237] recorded len bytes: 420540 [ 810.012920] recorded size bytes: 411961 [ 810.014281] Ring buffer PASSED! [ 810.129991] Running tests on trace events: [ 810.130891] Testing event initcall_finish: OK [ 810.176402] Testing event initcall_start: OK [ 810.214974] Testing event initcall_level: OK [ 810.256957] Testing event sys_exit: OK [ 810.296301] Testing event sys_enter: OK [ 810.334933] Testing event ipi_exit: OK [ 810.368270] Testing event ipi_entry: OK [ 810.408164] Testing event ipi_raise: OK [ 810.446914] Testing event instruction_emulation: OK [ 810.487836] Testing event kvm_halt_poll_ns: OK [ 810.528534] Testing event kvm_age_page: OK [ 810.566845] Testing event kvm_fpu: OK [ 810.602408] Testing event kvm_mmio: OK [ 810.642743] Testing event kvm_ack_irq: OK [ 810.676566] Testing event kvm_set_irq: OK [ 810.716207] Testing event kvm_vcpu_wakeup: OK [ 810.754998] Testing event kvm_userspace_exit: OK [ 810.792243] Testing event kvm_timer_update_irq: OK [ 810.831072] Testing event kvm_toggle_cache: OK [ 810.874286] Testing event kvm_set_way_flush: OK [ 810.912276] Testing event kvm_test_age_hva: OK [ 810.951650] Testing event kvm_age_hva: OK [ 810.995267] Testing event kvm_set_spte_hva: OK [ 811.032933] Testing event kvm_unmap_hva_range: OK [ 811.068848] Testing event kvm_mmio_emulate: OK [ 811.113176] Testing event kvm_irq_line: OK [ 811.149312] Testing event kvm_access_fault: OK [ 811.185103] Testing event kvm_guest_fault: OK [ 811.227056] Testing event kvm_exit: OK [ 811.270401] Testing event kvm_entry: OK [ 811.308505] Testing event kvm_set_guest_debug: OK [ 811.347098] Testing event kvm_handle_sys_reg: OK [ 811.389147] Testing event trap_reg: OK [ 811.428902] Testing event kvm_arm_set_regset: OK [ 811.468655] Testing event kvm_arm_set_dreg32: OK [ 811.508173] Testing event kvm_arm_clear_debug: OK [ 811.551972] Testing event kvm_arm_setup_debug: OK [ 811.590954] Testing event kvm_hvc_arm64: OK [ 811.629163] Testing event kvm_wfx_arm64: OK [ 811.669111] Testing event vgic_update_irq_pending: OK [ 811.708132] Testing event task_rename: OK [ 811.751116] Testing event task_newtask: OK [ 811.790373] Testing event cpuhp_exit: OK [ 811.836868] Testing event cpuhp_multi_enter: OK [ 811.879884] Testing event cpuhp_enter: OK [ 811.917057] Testing event softirq_raise: OK [ 811.952919] Testing event softirq_exit: OK [ 811.993130] Testing event softirq_entry: OK [ 812.029086] Testing event irq_handler_exit: OK [ 812.069122] Testing event irq_handler_entry: OK [ 812.110831] Testing event signal_deliver: OK [ 812.151035] Testing event signal_generate: OK [ 812.194865] Testing event workqueue_execute_end: OK [ 812.234513] Testing event workqueue_execute_start: OK [ 812.273047] Testing event workqueue_activate_work: OK [ 812.314468] Testing event workqueue_queue_work: OK [ 812.352272] Testing event sched_wake_idle_without_ipi: OK [ 812.396185] Testing event sched_swap_numa: OK [ 812.440018] Testing event sched_stick_numa: OK [ 812.479742] Testing event sched_move_numa: OK [ 812.516948] Testing event sched_process_hang: OK [ 812.554931] Testing event sched_pi_setprio: OK [ 812.590597] Testing event sched_stat_runtime: [ 812.593208] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 812.635219] OK [ 812.635830] Testing event sched_stat_blocked: OK [ 812.675190] Testing event sched_stat_iowait: OK [ 812.715396] Testing event sched_stat_sleep: OK [ 812.757164] Testing event sched_stat_wait: OK [ 812.796745] Testing event sched_process_exec: OK [ 812.839444] Testing event sched_process_fork: OK [ 812.879819] Testing event sched_process_wait: OK [ 812.918920] Testing event sched_wait_task: OK [ 812.954626] Testing event sched_process_exit: OK [ 812.995416] Testing event sched_process_free: OK [ 813.035066] Testing event sched_migrate_task: OK [ 813.072307] Testing event sched_switch: OK [ 813.115834] Testing event sched_wakeup_new: OK [ 813.156563] Testing event sched_wakeup: OK [ 813.198690] Testing event sched_waking: OK [ 813.240027] Testing event sched_kthread_stop_ret: OK [ 813.280703] Testing event sched_kthread_stop: OK [ 813.320204] Testing event lock_acquired: OK [ 813.397237] Testing event lock_contended: OK [ 813.436074] Testing event lock_release: OK [ 813.514709] Testing event lock_acquire: OK [ 813.602650] Testing event console: OK [ 813.640147] Testing event rcu_barrier: OK [ 813.680365] Testing event rcu_torture_read: OK [ 813.720192] Testing event rcu_batch_end: OK [ 813.759362] Testing event rcu_invoke_kfree_callback: OK [ 813.801192] Testing event rcu_invoke_callback: OK [ 813.842920] Testing event rcu_batch_start: OK [ 813.887239] Testing event rcu_kfree_callback: OK [ 813.919878] Testing event rcu_callback: OK [ 813.959172] Testing event rcu_dyntick: OK [ 814.004091] Testing event rcu_fqs: OK [ 814.044518] Testing event rcu_quiescent_state_report: OK [ 814.084054] Testing event rcu_unlock_preempted_task: OK [ 814.120697] Testing event rcu_preempt_task: OK [ 814.155631] Testing event rcu_nocb_wake: OK [ 814.200659] Testing event rcu_exp_funnel_lock: OK [ 814.245171] Testing event rcu_exp_grace_period: OK [ 814.288546] Testing event rcu_grace_period_init: OK [ 814.328420] Testing event rcu_future_grace_period: OK [ 814.366953] Testing event rcu_grace_period: OK [ 814.415306] Testing event rcu_utilization: OK [ 814.456657] Testing event swiotlb_bounced: OK [ 814.497102] Testing event tick_stop: OK [ 814.536238] Testing event itimer_expire: OK [ 814.575688] Testing event itimer_state: OK [ 814.615855] Testing event hrtimer_cancel: OK [ 814.656773] Testing event hrtimer_expire_exit: OK [ 814.694989] Testing event hrtimer_expire_entry: OK [ 814.736946] Testing event hrtimer_start: OK [ 814.776386] Testing event hrtimer_init: OK [ 814.815319] Testing event timer_cancel: OK [ 814.852532] Testing event timer_expire_exit: OK [ 814.888489] Testing event timer_expire_entry: OK [ 814.933291] Testing event timer_start: OK [ 814.972838] Testing event timer_init: OK [ 815.012984] Testing event alarmtimer_cancel: OK [ 815.052254] Testing event alarmtimer_start: OK [ 815.090914] Testing event alarmtimer_fired: OK [ 815.128235] Testing event alarmtimer_suspend: OK [ 815.166873] Testing event module_request: OK [ 815.204132] Testing event module_put: OK [ 815.243708] Testing event module_get: OK [ 815.284655] Testing event module_free: OK [ 815.323552] Testing event module_load: OK [ 815.364729] Testing event cgroup_transfer_tasks: OK [ 815.404513] Testing event cgroup_attach_task: OK [ 815.446609] Testing event cgroup_rename: OK [ 815.480486] Testing event cgroup_release: OK [ 815.520323] Testing event cgroup_rmdir: OK [ 815.559759] Testing event cgroup_mkdir: OK [ 815.603642] Testing event cgroup_remount: OK [ 815.646409] Testing event cgroup_destroy_root: OK [ 815.684273] Testing event cgroup_setup_root: OK [ 815.723590] Testing event irq_enable: OK [ 815.782879] Testing event irq_disable: OK [ 815.843331] Testing event ftrace_test_filter: OK [ 815.883380] Testing event dev_pm_qos_remove_request: OK [ 815.916134] Testing event dev_pm_qos_update_request: OK [ 815.951880] Testing event dev_pm_qos_add_request: OK [ 815.994726] Testing event pm_qos_update_flags: OK [ 816.032296] Testing event pm_qos_update_target: OK [ 816.070639] Testing event pm_qos_update_request_timeout: OK [ 816.103902] Testing event pm_qos_remove_request: OK [ 816.150589] Testing event pm_qos_update_request: OK [ 816.188986] Testing event pm_qos_add_request: OK [ 816.228317] Testing event power_domain_target: OK [ 816.266788] Testing event clock_set_rate: OK [ 816.304292] Testing event clock_disable: OK [ 816.342886] Testing event clock_enable: OK [ 816.376538] Testing event wakeup_source_deactivate: OK [ 816.416254] Testing event wakeup_source_activate: OK [ 816.455812] Testing event suspend_resume: OK [ 816.509279] Testing event device_pm_callback_end: OK [ 816.548801] Testing event device_pm_callback_start: OK [ 816.587410] Testing event cpu_frequency_limits: OK [ 816.627268] Testing event cpu_frequency: OK [ 816.667008] Testing event pstate_sample: OK [ 816.704191] Testing event powernv_throttle: OK [ 816.743395] Testing event cpu_idle: OK [ 816.787875] Testing event rpm_return_int: OK [ 816.832434] Testing event rpm_idle: OK [ 816.872216] Testing event rpm_resume: OK [ 816.911007] Testing event rpm_suspend: OK [ 816.948218] Testing event benchmark_event: OK [ 817.086856] Testing event xdp_devmap_xmit: OK [ 817.124922] Testing event xdp_cpumap_enqueue: OK [ 817.160872] Testing event xdp_cpumap_kthread: OK [ 817.206223] Testing event xdp_redirect_map_err: OK [ 817.238789] Testing event xdp_redirect_map: OK [ 817.283920] Testing event xdp_redirect_err: OK [ 817.324396] Testing event xdp_redirect: OK [ 817.365049] Testing event xdp_exception: OK [ 817.404946] Testing event rseq_ip_fixup: OK [ 817.443013] Testing event rseq_update: OK [ 817.484813] Testing event file_check_and_advance_wb_err: OK [ 817.524337] Testing event filemap_set_wb_err: OK [ 817.567218] Testing event mm_filemap_add_to_page_cache: OK [ 817.609020] Testing event mm_filemap_delete_from_page_cache: OK [ 817.648709] Testing event compact_retry: OK [ 817.688238] Testing event skip_task_reaping: OK [ 817.723033] Testing event finish_task_reaping: OK [ 817.759970] Testing event start_task_reaping: OK [ 817.800504] Testing event wake_reaper: OK [ 817.840041] Testing event mark_victim: OK [ 817.883881] Testing event reclaim_retry_zone: OK [ 817.927930] Testing event oom_score_adj_update: OK [ 817.967737] Testing event mm_lru_activate: OK [ 818.005077] Testing event mm_lru_insertion: OK [ 818.043071] Testing event mm_vmscan_inactive_list_is_low: OK [ 818.086344] Testing event mm_vmscan_lru_shrink_active: OK [ 818.124324] Testing event mm_vmscan_lru_shrink_inactive: OK [ 818.163000] Testing event mm_vmscan_writepage: OK [ 818.200225] Testing event mm_vmscan_lru_isolate: OK [ 818.239099] Testing event mm_shrink_slab_end: OK [ 818.280310] Testing event mm_shrink_slab_start: OK [ 818.319000] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 818.360759] Testing event mm_vmscan_memcg_reclaim_end: OK [ 818.399087] Testing event mm_vmscan_direct_reclaim_end: OK [ 818.440442] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 818.483304] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 818.524943] Testing event mm_vmscan_direct_reclaim_begin: OK [ 818.564310] Testing event mm_vmscan_wakeup_kswapd: OK [ 818.603116] Testing event mm_vmscan_kswapd_wake: OK [ 818.644845] Testing event mm_vmscan_kswapd_sleep: OK [ 818.684258] Testing event percpu_destroy_chunk: OK [ 818.723070] Testing event percpu_create_chunk: OK [ 818.765020] Testing event percpu_alloc_percpu_fail: OK [ 818.804346] Testing event percpu_free_percpu: OK [ 818.843423] Testing event percpu_alloc_percpu: OK [ 818.883005] Testing event mm_page_alloc_extfrag: OK [ 818.926357] Testing event mm_page_pcpu_drain: OK [ 818.964266] Testing event mm_page_alloc_zone_locked: OK [ 819.005325] Testing event mm_page_alloc: OK [ 819.048148] Testing event mm_page_free_batched: OK [ 819.088836] Testing event mm_page_free: OK [ 819.128377] Testing event kmem_cache_free: OK [ 819.168312] Testing event kfree: OK [ 819.211132] Testing event kmem_cache_alloc_node: OK [ 819.252305] Testing event kmalloc_node: OK [ 819.291862] Testing event kmem_cache_alloc: OK [ 819.336042] Testing event kmalloc: OK [ 819.376655] Testing event mm_compaction_kcompactd_wake: OK [ 819.417300] Testing event mm_compaction_wakeup_kcompactd: OK [ 819.452405] Testing event mm_compaction_kcompactd_sleep: OK [ 819.492249] Testing event mm_compaction_defer_reset: OK [ 819.531143] Testing event mm_compaction_defer_compaction: OK [ 819.573294] Testing event mm_compaction_deferred: OK [ 819.612227] Testing event mm_compaction_suitable: OK [ 819.651023] Testing event mm_compaction_finished: OK [ 819.692929] Testing event mm_compaction_try_to_compact_pages: OK [ 819.728764] Testing event mm_compaction_end: OK [ 819.771257] Testing event mm_compaction_begin: OK [ 819.811359] Testing event mm_compaction_migratepages: OK [ 819.852271] Testing event mm_compaction_isolate_freepages: OK [ 819.891187] Testing event mm_compaction_isolate_migratepages: OK [ 819.934579] Testing event mm_migrate_pages: OK [ 819.972597] Testing event mm_collapse_huge_page_swapin: OK [ 820.009187] Testing event mm_collapse_huge_page_isolate: OK [ 820.051122] Testing event mm_collapse_huge_page: OK [ 820.089314] Testing event mm_khugepaged_scan_pmd: OK [ 820.127371] Testing event test_pages_isolated: OK [ 820.164951] Testing event cma_release: OK [ 820.207606] Testing event cma_alloc: OK [ 820.244948] Testing event page_ref_unfreeze: OK [ 820.287543] Testing event page_ref_freeze: OK [ 820.326789] Testing event page_ref_mod_unless: OK [ 820.369050] Testing event page_ref_mod_and_return: OK [ 820.407904] Testing event page_ref_mod_and_test: OK [ 820.603485] Testing event page_ref_mod: OK [ 820.690625] Testing event page_ref_set: OK [ 820.740217] Testing event sb_clear_inode_writeback: OK [ 820.779056] Testing event sb_mark_inode_writeback: OK [ 820.823901] Testing event writeback_dirty_inode_enqueue: OK [ 820.867956] Testing event writeback_lazytime_iput: OK [ 820.912038] Testing event writeback_lazytime: OK [ 820.952238] Testing event writeback_single_inode: OK [ 820.990892] Testing event writeback_single_inode_start: OK [ 821.032100] Testing event writeback_wait_iff_congested: OK [ 821.072293] Testing event writeback_congestion_wait: OK [ 821.105865] Testing event writeback_sb_inodes_requeue: OK [ 821.144765] Testing event balance_dirty_pages: OK [ 821.190110] Testing event bdi_dirty_ratelimit: OK [ 821.222883] Testing event global_dirty_state: OK [ 821.267730] Testing event writeback_queue_io: OK [ 821.311914] Testing event wbc_writepage: OK [ 821.351001] Testing event writeback_bdi_register: OK [ 821.388233] Testing event writeback_wake_background: OK [ 821.424763] Testing event writeback_pages_written: OK [ 821.463656] Testing event writeback_wait: OK [ 821.500909] Testing event writeback_written: OK [ 821.540271] Testing event writeback_start: OK [ 821.579097] Testing event writeback_exec: OK [ 821.622245] Testing event writeback_queue: OK [ 821.660244] Testing event writeback_write_inode: OK [ 821.703643] Testing event writeback_write_inode_start: OK [ 821.744249] Testing event writeback_dirty_inode: OK [ 821.783099] Testing event writeback_dirty_inode_start: OK [ 821.826329] Testing event writeback_mark_inode_dirty: OK [ 821.864315] Testing event writeback_dirty_page: OK [ 821.903131] Testing event dax_writeback_one: OK [ 821.946681] Testing event dax_writeback_range_done: OK [ 821.984283] Testing event dax_writeback_range: OK [ 822.027789] Testing event dax_insert_mapping: OK [ 822.068263] Testing event dax_insert_pfn_mkwrite: OK [ 822.107554] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 822.151402] Testing event dax_load_hole: OK [ 822.185017] Testing event dax_pte_fault_done: OK [ 822.229321] Testing event dax_pte_fault: OK [ 822.268284] Testing event dax_pmd_insert_mapping: OK [ 822.310363] Testing event dax_pmd_load_hole_fallback: OK [ 822.348139] Testing event dax_pmd_load_hole: OK [ 822.387012] Testing event dax_pmd_fault_done: OK [ 822.422290] Testing event dax_pmd_fault: OK [ 822.462430] Testing event generic_add_lease: OK [ 822.496591] Testing event time_out_leases: OK [ 822.532763] Testing event generic_delete_lease: OK [ 822.568161] Testing event break_lease_unblock: OK [ 822.603648] Testing event break_lease_block: OK [ 822.644995] Testing event break_lease_noblock: OK [ 822.680832] Testing event flock_lock_inode: OK [ 822.725284] Testing event locks_remove_posix: OK [ 822.761267] Testing event fcntl_setlk: OK [ 822.800723] Testing event posix_lock_inode: OK [ 822.842432] Testing event locks_get_lock_context: OK [ 822.880354] Testing event block_rq_remap: OK [ 822.917286] Testing event block_bio_remap: OK [ 822.956238] Testing event block_split: OK [ 822.995012] Testing event block_unplug: OK [ 823.039869] Testing event block_plug: OK [ 823.076991] Testing event block_sleeprq: OK [ 823.111682] Testing event block_getrq: OK [ 823.152169] Testing event block_bio_queue: OK [ 823.191656] Testing event block_bio_frontmerge: OK [ 823.235479] Testing event block_bio_backmerge: OK [ 823.280028] Testing event block_bio_complete: OK [ 823.324774] Testing event block_bio_bounce: OK [ 823.359639] Testing event block_rq_issue: OK [ 823.399193] Testing event block_rq_insert: OK [ 823.438243] Testing event block_rq_complete: OK [ 823.479958] Testing event block_rq_requeue: OK [ 823.515937] Testing event block_dirty_buffer: OK [ 823.556202] Testing event block_touch_buffer: OK [ 823.595785] Testing event wbt_timer: OK [ 823.632867] Testing event wbt_step: OK [ 823.671107] Testing event wbt_lat: OK [ 823.711019] Testing event wbt_stat: OK [ 823.754763] Testing event gpio_value: OK [ 823.795876] Testing event gpio_direction: OK [ 823.837018] Testing event clk_set_duty_cycle_complete: OK [ 823.879285] Testing event clk_set_duty_cycle: OK [ 823.920204] Testing event clk_set_phase_complete: OK [ 823.958952] Testing event clk_set_phase: OK [ 823.994573] Testing event clk_set_parent_complete: OK [ 824.035012] Testing event clk_set_parent: OK [ 824.072704] Testing event clk_set_rate_complete: OK [ 824.116563] Testing event clk_set_rate: OK [ 824.156245] Testing event clk_unprepare_complete: OK [ 824.195636] Testing event clk_unprepare: OK [ 824.238512] Testing event clk_prepare_complete: OK [ 824.276272] Testing event clk_prepare: OK [ 824.315526] Testing event clk_disable_complete: OK [ 824.356031] Testing event clk_disable: OK [ 824.392111] Testing event clk_enable_complete: OK [ 824.439766] Testing event clk_enable: OK [ 824.477051] Testing event rpmh_send_msg: OK [ 824.516256] Testing event rpmh_tx_done: OK [ 824.554393] Testing event regulator_set_voltage_complete: OK [ 824.588631] Testing event regulator_set_voltage: OK [ 824.623833] Testing event regulator_disable_complete: OK [ 824.660680] Testing event regulator_disable: OK [ 824.700182] Testing event regulator_enable_complete: OK [ 824.739720] Testing event regulator_enable_delay: OK [ 824.779635] Testing event regulator_enable: OK [ 824.818344] Testing event urandom_read: OK [ 824.852748] Testing event random_read: OK [ 824.894951] Testing event extract_entropy_user: OK [ 824.933017] Testing event extract_entropy: OK [ 824.972197] Testing event get_random_bytes_arch: OK [ 825.008645] Testing event get_random_bytes: OK [ 825.047236] Testing event xfer_secondary_pool: OK [ 825.085016] Testing event add_disk_randomness: OK [ 825.124511] Testing event add_input_randomness: OK [ 825.165094] Testing event debit_entropy: OK [ 825.204262] Testing event push_to_pool: OK [ 825.243570] Testing event credit_entropy_bits: OK [ 825.282953] Testing event mix_pool_bytes_nolock: OK [ 825.316535] Testing event mix_pool_bytes: OK [ 825.360079] Testing event add_device_randomness: OK [ 825.402576] Testing event io_page_fault: OK [ 825.440218] Testing event unmap: OK [ 825.479529] Testing event map: OK [ 825.519544] Testing event detach_device_from_domain: OK [ 825.559031] Testing event attach_device_to_domain: OK [ 825.600825] Testing event remove_device_from_group: OK [ 825.640318] Testing event add_device_to_group: OK [ 825.680112] Testing event regcache_drop_region: OK [ 825.720267] Testing event regmap_async_complete_done: OK [ 825.759704] Testing event regmap_async_complete_start: OK [ 825.797182] Testing event regmap_async_io_complete: OK [ 825.836148] Testing event regmap_async_write_start: OK [ 825.876257] Testing event regmap_cache_bypass: OK [ 825.912634] Testing event regmap_cache_only: OK [ 825.951564] Testing event regcache_sync: OK [ 825.992616] Testing event regmap_hw_write_done: OK [ 826.031304] Testing event regmap_hw_write_start: OK [ 826.074882] Testing event regmap_hw_read_done: OK [ 826.112393] Testing event regmap_hw_read_start: OK [ 826.151010] Testing event regmap_reg_read_cache: OK [ 826.194787] Testing event regmap_reg_read: OK [ 826.232214] Testing event regmap_reg_write: OK [ 826.271014] Testing event dma_fence_wait_end: OK [ 826.308356] Testing event dma_fence_wait_start: OK [ 826.346610] Testing event dma_fence_signaled: OK [ 826.382772] Testing event dma_fence_enable_signal: OK [ 826.422731] Testing event dma_fence_destroy: OK [ 826.458530] Testing event dma_fence_init: OK [ 826.493098] Testing event dma_fence_emit: OK [ 826.531694] Testing event sync_timeline: OK [ 826.572255] Testing event spi_transfer_stop: OK [ 826.611057] Testing event spi_transfer_start: OK [ 826.653022] Testing event spi_message_done: OK [ 826.691328] Testing event spi_message_start: OK [ 826.732503] Testing event spi_message_submit: OK [ 826.771075] Testing event spi_controller_busy: OK [ 826.808382] Testing event spi_controller_idle: OK [ 826.847074] Testing event rtc_timer_fired: OK [ 826.888798] Testing event rtc_timer_dequeue: OK [ 826.928698] Testing event rtc_timer_enqueue: OK [ 826.971531] Testing event rtc_read_offset: OK [ 827.008418] Testing event rtc_set_offset: OK [ 827.048469] Testing event rtc_alarm_irq_enable: OK [ 827.090470] Testing event rtc_irq_set_state: OK [ 827.128965] Testing event rtc_irq_set_freq: OK [ 827.163775] Testing event rtc_read_alarm: OK [ 827.204498] Testing event rtc_set_alarm: OK [ 827.246997] Testing event rtc_read_time: OK [ 827.284370] Testing event rtc_set_time: OK [ 827.318018] Testing event memory_failure_event: OK [ 827.355803] Testing event aer_event: OK [ 827.396308] Testing event non_standard_event: OK [ 827.434985] Testing event arm_event: OK [ 827.472208] Testing event mc_event: OK [ 827.511373] Testing event binder_return: OK [ 827.551101] Testing event binder_command: OK [ 827.595884] Testing event binder_unmap_kernel_end: OK [ 827.636519] Testing event binder_unmap_kernel_start: OK [ 827.678918] Testing event binder_unmap_user_end: OK [ 827.716205] Testing event binder_unmap_user_start: OK [ 827.754521] Testing event binder_alloc_page_end: OK [ 827.788632] Testing event binder_alloc_page_start: OK [ 827.831457] Testing event binder_free_lru_end: OK [ 827.871615] Testing event binder_free_lru_start: OK [ 827.915982] Testing event binder_alloc_lru_end: OK [ 827.954684] Testing event binder_alloc_lru_start: OK [ 827.988446] Testing event binder_update_page_range: OK [ 828.028283] Testing event binder_transaction_failed_buffer_release: OK [ 828.067757] Testing event binder_transaction_buffer_release: OK [ 828.108328] Testing event binder_transaction_alloc_buf: OK [ 828.147706] Testing event binder_transaction_fd_recv: OK [ 828.191781] Testing event binder_transaction_fd_send: OK [ 828.234511] Testing event binder_transaction_ref_to_ref: OK [ 828.272284] Testing event binder_transaction_ref_to_node: OK [ 828.311363] Testing event binder_transaction_node_to_ref: OK [ 828.354553] Testing event binder_transaction_received: OK [ 828.392314] Testing event binder_transaction: OK [ 828.430805] Testing event binder_wait_for_work: OK [ 828.468348] Testing event binder_read_done: OK [ 828.506876] Testing event binder_write_done: OK [ 828.544268] Testing event binder_ioctl_done: OK [ 828.583033] Testing event binder_unlock: OK [ 828.620869] Testing event binder_locked: OK [ 828.663747] Testing event binder_lock: OK [ 828.704162] Testing event binder_ioctl: OK [ 828.742751] Testing event br_fdb_update: OK [ 828.780127] Testing event fdb_delete: OK [ 828.819270] Testing event br_fdb_external_learn_add: OK [ 828.856058] Testing event br_fdb_add: OK [ 828.894868] Testing event qdisc_dequeue: OK [ 828.930667] Testing event fib_table_lookup: OK [ 828.975892] Testing event tcp_probe: OK [ 829.015731] Testing event tcp_retransmit_synack: OK [ 829.052936] Testing event tcp_rcv_space_adjust: OK [ 829.090921] Testing event tcp_destroy_sock: OK [ 829.128238] Testing event tcp_receive_reset: OK [ 829.166665] Testing event tcp_send_reset: OK [ 829.202735] Testing event tcp_retransmit_skb: OK [ 829.242796] Testing event udp_fail_queue_rcv_skb: OK [ 829.280289] Testing event inet_sock_set_state: OK [ 829.319195] Testing event sock_exceed_buf_limit: OK [ 829.360856] Testing event sock_rcvqueue_full: OK [ 829.400284] Testing event napi_poll: OK [ 829.442834] Testing event netif_rx_ni_entry: OK [ 829.478562] Testing event netif_rx_entry: OK [ 829.519049] Testing event netif_receive_skb_list_entry: OK [ 829.556313] Testing event netif_receive_skb_entry: OK [ 829.595577] Testing event napi_gro_receive_entry: OK [ 829.639030] Testing event napi_gro_frags_entry: OK [ 829.677270] Testing event netif_rx: OK [ 829.720114] Testing event netif_receive_skb: OK [ 829.760326] Testing event net_dev_queue: OK [ 829.800019] Testing event net_dev_xmit: OK [ 829.842888] Testing event net_dev_start_xmit: OK [ 829.878693] Testing event skb_copy_datagram_iovec: OK [ 829.918235] Testing event consume_skb: OK [ 829.956635] Testing event kfree_skb: OK [ 829.998592] Testing event 9p_protocol_dump: OK [ 830.050663] Testing event 9p_client_res: OK [ 830.088869] Testing event 9p_client_req: OK [ 830.128318] Running tests on trace event systems: [ 830.129115] Testing event system 9p: OK [ 830.182462] Testing event system skb: OK [ 830.228577] Testing event system net: OK [ 830.283376] Testing event system napi: OK [ 830.332931] Testing event system sock: OK [ 830.377109] Testing event system udp: OK [ 830.419817] Testing event system tcp: OK [ 830.474897] Testing event system fib: OK [ 830.522252] Testing event system qdisc: OK [ 830.564214] Testing event system bridge: OK [ 830.610863] Testing event system binder: OK [ 830.688737] Testing event system ras: OK [ 830.739094] Testing event system rtc: OK [ 830.814387] Testing event system spi: OK [ 830.870488] Testing event system sync_trace: OK [ 830.914227] Testing event system dma_fence: OK [ 830.962226] Testing event system regmap: OK [ 831.023840] Testing event system iommu: OK [ 831.079962] Testing event system random: OK [ 831.152152] Testing event system regulator: OK [ 831.211484] Testing event system rpmh: OK [ 831.250245] Testing event system clk: OK [ 831.315806] Testing event system gpio: OK [ 831.356337] Testing event system wbt: OK [ 831.412248] Testing event system block: OK [ 831.475150] Testing event system filelock: OK [ 831.539025] Testing event system fs_dax: OK [ 831.597272] Testing event system writeback: OK [ 831.670227] Testing event system page_ref: OK [ 831.961114] Testing event system cma: OK [ 832.012973] Testing event system page_isolation: OK [ 832.054523] Testing event system huge_memory: OK [ 832.099734] Testing event system migrate: OK [ 832.145195] Testing event system compaction: OK [ 832.200319] Testing event system kmem: OK [ 832.270449] Testing event system percpu: OK [ 832.324624] Testing event system vmscan: OK [ 832.387391] Testing event system pagemap: OK [ 832.452680] Testing event system oom: OK [ 832.503086] Testing event system filemap: OK [ 832.552206] Testing event system rseq: OK [ 832.596772] Testing event system xdp: OK [ 832.653052] Testing event system benchmark: OK [ 832.790545] Testing event system rpm: OK [ 832.835202] Testing event system power: OK [ 832.916929] Testing event system test: OK [ 832.960250] Testing event system preemptirq: OK [ 833.078659] Testing event system cgroup: OK [ 833.134679] Testing event system module: OK [ 833.190437] Testing event system alarmtimer: OK [ 833.238736] Testing event system timer: OK [ 833.304129] Testing event system swiotlb: OK [ 833.355020] Testing event system rcu: OK [ 833.450939] Testing event system printk: OK [ 833.502647] Testing event system lock: OK [ 833.675918] Testing event system sched: OK [ 833.760852] Testing event system workqueue: OK [ 833.822663] Testing event system signal: OK [ 833.871001] Testing event system irq: OK [ 833.922444] Testing event system cpuhp: OK [ 833.971105] Testing event system task: OK [ 834.015377] Testing event system kvm: OK [ 834.095199] Testing event system emulation: OK [ 834.164941] Testing event system ipi: OK [ 834.210672] Testing event system raw_syscalls: OK [ 834.256761] Testing event system initcall: OK [ 834.306549] Running tests on all trace events: [ 834.307269] Testing all events: [ 846.232400] event_benchmark (682) used greatest stack depth: 11520 bytes left [ 846.737143] OK [ 846.911509] ftrace_replace_code: 1 [ 851.834111] hrtimer: interrupt took 9397360 ns [ 923.394942] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 923.441973] Running tests again, along with the function tracer [ 923.488547] Running tests on trace events: [ 923.503774] Testing event initcall_finish: OK [ 924.031384] Testing event initcall_start: OK [ 924.866380] Testing event initcall_level: OK [ 925.707014] Testing event sys_exit: OK [ 926.457053] Testing event sys_enter: OK [ 927.336620] Testing event ipi_exit: OK [ 928.197649] Testing event ipi_entry: OK [ 929.138747] Testing event ipi_raise: OK [ 929.898710] Testing event instruction_emulation: OK [ 930.427706] Testing event kvm_halt_poll_ns: OK [ 931.295775] Testing event kvm_age_page: OK [ 931.939732] Testing event kvm_fpu: OK [ 932.874691] Testing event kvm_mmio: OK [ 933.658309] Testing event kvm_ack_irq: OK [ 934.289680] Testing event kvm_set_irq: OK [ 935.200841] Testing event kvm_vcpu_wakeup: OK [ 935.926620] Testing event kvm_userspace_exit: OK [ 936.704171] Testing event kvm_timer_update_irq: OK [ 937.570694] Testing event kvm_toggle_cache: OK [ 938.370006] Testing event kvm_set_way_flush: OK [ 938.977204] Testing event kvm_test_age_hva: OK [ 939.779618] Testing event kvm_age_hva: OK [ 940.624965] Testing event kvm_set_spte_hva: OK [ 941.300311] Testing event kvm_unmap_hva_range: OK [ 942.102762] Testing event kvm_mmio_emulate: OK [ 942.760383] Testing event kvm_irq_line: OK [ 943.570719] Testing event kvm_access_fault: OK [ 944.411388] Testing event kvm_guest_fault: OK [ 945.346252] Testing event kvm_exit: OK [ 945.932575] Testing event kvm_entry: OK [ 946.856979] Testing event kvm_set_guest_debug: OK [ 947.523295] Testing event kvm_handle_sys_reg: OK [ 948.271980] Testing event trap_reg: OK [ 949.206061] Testing event kvm_arm_set_regset: OK [ 949.831450] Testing event kvm_arm_set_dreg32: OK [ 950.420383] Testing event kvm_arm_clear_debug: OK [ 951.222302] Testing event kvm_arm_setup_debug: OK [ 951.911318] Testing event kvm_hvc_arm64: OK [ 952.813948] Testing event kvm_wfx_arm64: OK [ 953.720321] Testing event vgic_update_irq_pending: OK [ 954.438631] Testing event task_rename: OK [ 955.142208] Testing event task_newtask: OK [ 956.039018] Testing event cpuhp_exit: OK [ 956.742464] Testing event cpuhp_multi_enter: OK [ 957.390724] Testing event cpuhp_enter: OK [ 958.259943] Testing event softirq_raise: OK [ 958.906513] Testing event softirq_exit: OK [ 959.714261] Testing event softirq_entry: OK [ 960.120682] Testing event irq_handler_exit: OK [ 961.112943] Testing event irq_handler_entry: OK [ 961.756623] Testing event signal_deliver: OK [ 962.683778] Testing event signal_generate: OK [ 963.424033] Testing event workqueue_execute_end: OK [ 964.170807] Testing event workqueue_execute_start: OK [ 965.039906] Testing event workqueue_activate_work: OK [ 965.886136] Testing event workqueue_queue_work: OK [ 966.543034] Testing event sched_wake_idle_without_ipi: OK [ 967.267966] Testing event sched_swap_numa: OK [ 968.120890] Testing event sched_stick_numa: OK [ 968.959206] Testing event sched_move_numa: OK [ 969.710287] Testing event sched_process_hang: OK [ 970.602774] Testing event sched_pi_setprio: OK [ 971.378747] Testing event sched_stat_runtime: OK [ 972.162605] Testing event sched_stat_blocked: OK [ 972.887084] Testing event sched_stat_iowait: OK [ 973.696915] Testing event sched_stat_sleep: OK [ 974.417296] Testing event sched_stat_wait: OK [ 975.115415] Testing event sched_process_exec: OK [ 975.829632] Testing event sched_process_fork: OK [ 976.795793] Testing event sched_process_wait: OK [ 977.654053] Testing event sched_wait_task: OK [ 978.526351] Testing event sched_process_exit: OK [ 979.191413] Testing event sched_process_free: OK [ 980.026680] Testing event sched_migrate_task: OK [ 980.844804] Testing event sched_switch: OK [ 981.664351] Testing event sched_wakeup_new: OK [ 982.342834] Testing event sched_wakeup: OK [ 983.128478] Testing event sched_waking: OK [ 983.960170] Testing event sched_kthread_stop_ret: OK [ 984.768305] Testing event sched_kthread_stop: OK [ 985.685579] Testing event lock_acquired: OK [ 986.344278] Testing event lock_contended: OK [ 987.010739] Testing event lock_release: OK [ 988.044449] Testing event lock_acquire: OK [ 988.791591] Testing event console: OK [ 989.592368] Testing event rcu_barrier: OK [ 990.133531] Testing event rcu_torture_read: OK [ 990.808599] Testing event rcu_batch_end: OK [ 991.513599] Testing event rcu_invoke_kfree_callback: OK [ 992.336980] Testing event rcu_invoke_callback: OK [ 993.034327] Testing event rcu_batch_start: OK [ 993.994104] Testing event rcu_kfree_callback: OK [ 994.703074] Testing event rcu_callback: OK [ 995.503583] Testing event rcu_dyntick: OK [ 996.344406] Testing event rcu_fqs: OK [ 997.081439] Testing event rcu_quiescent_state_report: OK [ 997.744128] Testing event rcu_unlock_preempted_task: OK [ 998.621388] Testing event rcu_preempt_task: OK [ 999.332422] Testing event rcu_nocb_wake: OK [ 1000.279960] Testing event rcu_exp_funnel_lock: OK [ 1000.927206] Testing event rcu_exp_grace_period: OK [ 1001.784257] Testing event rcu_grace_period_init: OK [ 1002.568703] Testing event rcu_future_grace_period: OK [ 1003.285889] Testing event rcu_grace_period: OK [ 1003.978365] Testing event rcu_utilization: OK [ 1004.780656] Testing event swiotlb_bounced: OK [ 1005.623554] Testing event tick_stop: OK [ 1006.470895] Testing event itimer_expire: OK [ 1007.277255] Testing event itimer_state: OK [ 1008.059245] Testing event hrtimer_cancel: OK [ 1008.981657] Testing event hrtimer_expire_exit: OK [ 1009.744726] Testing event hrtimer_expire_entry: OK [ 1010.583969] Testing event hrtimer_start: OK [ 1011.385238] Testing event hrtimer_init: OK [ 1012.242912] Testing event timer_cancel: OK [ 1012.937782] Testing event timer_expire_exit: OK [ 1013.723791] Testing event timer_expire_entry: OK [ 1014.244930] Testing event timer_start: OK [ 1015.212346] Testing event timer_init: OK [ 1015.959364] Testing event alarmtimer_cancel: OK [ 1016.849655] Testing event alarmtimer_start: OK [ 1017.744308] Testing event alarmtimer_fired: OK [ 1018.393903] Testing event alarmtimer_suspend: OK [ 1019.250735] Testing event module_request: OK [ 1019.910954] Testing event module_put: OK [ 1020.471912] Testing event module_get: OK [ 1021.310213] Testing event module_free: OK [ 1021.955361] Testing event module_load: OK [ 1022.876054] Testing event cgroup_transfer_tasks: OK [ 1023.600606] Testing event cgroup_attach_task: OK [ 1024.484004] Testing event cgroup_rename: OK [ 1025.166123] Testing event cgroup_release: OK [ 1025.992832] Testing event cgroup_rmdir: OK [ 1026.714886] Testing event cgroup_mkdir: OK [ 1027.507303] Testing event cgroup_remount: OK [ 1028.297244] Testing event cgroup_destroy_root: OK [ 1028.964742] Testing event cgroup_setup_root: OK [ 1029.771395] Testing event irq_enable: OK [ 1030.779218] Testing event irq_disable: OK [ 1031.895884] Testing event ftrace_test_filter: OK [ 1032.784613] Testing event dev_pm_qos_remove_request: OK [ 1033.702082] Testing event dev_pm_qos_update_request: OK [ 1034.385431] Testing event dev_pm_qos_add_request: OK [ 1035.263156] Testing event pm_qos_update_flags: OK [ 1036.045815] Testing event pm_qos_update_target: OK [ 1036.813948] Testing event pm_qos_update_request_timeout: OK [ 1037.588973] Testing event pm_qos_remove_request: OK [ 1038.639257] Testing event pm_qos_update_request: OK [ 1039.285652] Testing event pm_qos_add_request: OK [ 1040.164004] Testing event power_domain_target: OK [ 1041.023793] Testing event clock_set_rate: OK [ 1041.761710] Testing event clock_disable: OK [ 1042.518941] Testing event clock_enable: OK [ 1043.170443] Testing event wakeup_source_deactivate: OK [ 1043.941747] Testing event wakeup_source_activate: OK [ 1044.804606] Testing event suspend_resume: OK [ 1045.749161] Testing event device_pm_callback_end: OK [ 1046.557495] Testing event device_pm_callback_start: OK [ 1047.418161] Testing event cpu_frequency_limits: OK [ 1048.281250] Testing event cpu_frequency: OK [ 1048.960541] Testing event pstate_sample: OK [ 1049.658966] Testing event powernv_throttle: OK [ 1050.320969] Testing event cpu_idle: OK [ 1050.988969] Testing event rpm_return_int: OK [ 1051.920103] Testing event rpm_idle: OK [ 1052.774091] Testing event rpm_resume: OK [ 1053.559624] Testing event rpm_suspend: OK [ 1054.416668] Testing event benchmark_event: OK [ 1055.738125] Testing event xdp_devmap_xmit: OK [ 1056.487910] Testing event xdp_cpumap_enqueue: OK [ 1057.314278] Testing event xdp_cpumap_kthread: OK [ 1058.152300] Testing event xdp_redirect_map_err: OK [ 1058.915429] Testing event xdp_redirect_map: OK [ 1059.564409] Testing event xdp_redirect_err: OK [ 1060.472111] Testing event xdp_redirect: OK [ 1061.268246] Testing event xdp_exception: OK [ 1061.975996] Testing event rseq_ip_fixup: OK [ 1062.715456] Testing event rseq_update: OK [ 1063.589240] Testing event file_check_and_advance_wb_err: OK [ 1064.401291] Testing event filemap_set_wb_err: OK [ 1064.939350] Testing event mm_filemap_add_to_page_cache: OK [ 1065.743064] Testing event mm_filemap_delete_from_page_cache: OK [ 1066.436499] Testing event compact_retry: OK [ 1067.162717] Testing event skip_task_reaping: OK [ 1068.079850] Testing event finish_task_reaping: OK [ 1068.875612] Testing event start_task_reaping: OK [ 1069.668952] Testing event wake_reaper: OK [ 1070.463653] Testing event mark_victim: OK [ 1071.341160] Testing event reclaim_retry_zone: OK [ 1072.299507] Testing event oom_score_adj_update: OK [ 1073.044228] Testing event mm_lru_activate: OK [ 1073.787635] Testing event mm_lru_insertion: OK [ 1074.517595] Testing event mm_vmscan_inactive_list_is_low: OK [ 1075.337664] Testing event mm_vmscan_lru_shrink_active: OK [ 1076.090037] Testing event mm_vmscan_lru_shrink_inactive: OK [ 1076.902480] Testing event mm_vmscan_writepage: OK [ 1077.721474] Testing event mm_vmscan_lru_isolate: OK [ 1078.502000] Testing event mm_shrink_slab_end: OK [ 1079.322113] Testing event mm_shrink_slab_start: OK [ 1080.136304] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 1080.965551] Testing event mm_vmscan_memcg_reclaim_end: OK [ 1081.751403] Testing event mm_vmscan_direct_reclaim_end: OK [ 1082.595907] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 1083.449444] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 1084.109824] Testing event mm_vmscan_direct_reclaim_begin: OK [ 1084.904718] Testing event mm_vmscan_wakeup_kswapd: OK [ 1085.791502] Testing event mm_vmscan_kswapd_wake: OK [ 1086.567774] Testing event mm_vmscan_kswapd_sleep: OK [ 1087.436166] Testing event percpu_destroy_chunk: OK [ 1088.027782] Testing event percpu_create_chunk: OK [ 1088.809968] Testing event percpu_alloc_percpu_fail: OK [ 1089.585786] Testing event percpu_free_percpu: OK [ 1090.470730] Testing event percpu_alloc_percpu: OK [ 1091.276392] Testing event mm_page_alloc_extfrag: OK [ 1092.225196] Testing event mm_page_pcpu_drain: OK [ 1093.022105] Testing event mm_page_alloc_zone_locked: OK [ 1093.638207] Testing event mm_page_alloc: OK [ 1094.328871] Testing event mm_page_free_batched: OK [ 1094.997636] Testing event mm_page_free: OK [ 1095.877278] Testing event kmem_cache_free: OK [ 1096.770638] Testing event kfree: OK [ 1097.669769] Testing event kmem_cache_alloc_node: OK [ 1098.381986] Testing event kmalloc_node: OK [ 1099.181402] Testing event kmem_cache_alloc: OK [ 1099.923892] Testing event kmalloc: OK [ 1100.771059] Testing event mm_compaction_kcompactd_wake: OK [ 1101.585002] Testing event mm_compaction_wakeup_kcompactd: OK [ 1102.389466] Testing event mm_compaction_kcompactd_sleep: OK [ 1103.086082] Testing event mm_compaction_defer_reset: OK [ 1103.744051] Testing event mm_compaction_defer_compaction: OK [ 1104.514290] Testing event mm_compaction_deferred: OK [ 1105.166020] Testing event mm_compaction_suitable: OK [ 1105.928282] Testing event mm_compaction_finished: OK [ 1106.745036] Testing event mm_compaction_try_to_compact_pages: OK [ 1107.620537] Testing event mm_compaction_end: OK [ 1108.560334] Testing event mm_compaction_begin: OK [ 1109.333309] Testing event mm_compaction_migratepages: OK [ 1110.213590] Testing event mm_compaction_isolate_freepages: OK [ 1110.941993] Testing event mm_compaction_isolate_migratepages: OK [ 1111.822211] Testing event mm_migrate_pages: OK [ 1112.573681] Testing event mm_collapse_huge_page_swapin: OK [ 1113.244844] Testing event mm_collapse_huge_page_isolate: OK [ 1113.824493] Testing event mm_collapse_huge_page: OK [ 1114.794919] Testing event mm_khugepaged_scan_pmd: OK [ 1115.543616] Testing event test_pages_isolated: OK [ 1116.251790] Testing event cma_release: OK [ 1117.278739] Testing event cma_alloc: OK [ 1117.933474] Testing event page_ref_unfreeze: OK [ 1118.701073] Testing event page_ref_freeze: OK [ 1119.375226] Testing event page_ref_mod_unless: OK [ 1120.498274] Testing event page_ref_mod_and_return: OK [ 1121.090672] Testing event page_ref_mod_and_test: OK [ 1123.391672] Testing event page_ref_mod: OK [ 1124.576416] Testing event page_ref_set: OK [ 1125.491742] Testing event sb_clear_inode_writeback: OK [ 1126.228811] Testing event sb_mark_inode_writeback: OK [ 1127.034352] Testing event writeback_dirty_inode_enqueue: OK [ 1127.971023] Testing event writeback_lazytime_iput: OK [ 1128.664026] Testing event writeback_lazytime: OK [ 1129.706910] Testing event writeback_single_inode: OK [ 1130.413265] Testing event writeback_single_inode_start: OK [ 1131.275849] Testing event writeback_wait_iff_congested: OK [ 1131.874821] Testing event writeback_congestion_wait: OK [ 1132.701186] Testing event writeback_sb_inodes_requeue: OK [ 1133.478355] Testing event balance_dirty_pages: OK [ 1134.306728] Testing event bdi_dirty_ratelimit: OK [ 1135.132068] Testing event global_dirty_state: OK [ 1135.902615] Testing event writeback_queue_io: OK [ 1136.745801] Testing event wbc_writepage: OK [ 1137.447005] Testing event writeback_bdi_register: OK [ 1137.982587] Testing event writeback_wake_background: OK [ 1138.790453] Testing event writeback_pages_written: OK [ 1139.693150] Testing event writeback_wait: OK [ 1140.541452] Testing event writeback_written: OK [ 1141.380751] Testing event writeback_start: OK [ 1142.254840] Testing event writeback_exec: OK [ 1142.938423] Testing event writeback_queue: OK [ 1143.669030] Testing event writeback_write_inode: OK [ 1144.224003] Testing event writeback_write_inode_start: OK [ 1145.072140] Testing event writeback_dirty_inode: OK [ 1145.909098] Testing event writeback_dirty_inode_start: OK [ 1146.715147] Testing event writeback_mark_inode_dirty: OK [ 1147.544757] Testing event writeback_dirty_page: OK [ 1148.449507] Testing event dax_writeback_one: OK [ 1149.129399] Testing event dax_writeback_range_done: OK [ 1149.662322] Testing event dax_writeback_range: OK [ 1150.361270] Testing event dax_insert_mapping: OK [ 1151.206189] Testing event dax_insert_pfn_mkwrite: OK [ 1151.979459] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 1152.796852] Testing event dax_load_hole: OK [ 1153.442144] Testing event dax_pte_fault_done: OK [ 1154.576577] Testing event dax_pte_fault: OK [ 1155.256199] Testing event dax_pmd_insert_mapping: OK [ 1156.069753] Testing event dax_pmd_load_hole_fallback: OK [ 1156.897145] Testing event dax_pmd_load_hole: OK [ 1157.651334] Testing event dax_pmd_fault_done: OK [ 1158.566584] Testing event dax_pmd_fault: OK [ 1159.256492] Testing event generic_add_lease: OK [ 1160.127393] Testing event time_out_leases: OK [ 1160.955724] Testing event generic_delete_lease: OK [ 1161.642915] Testing event break_lease_unblock: OK [ 1162.531801] Testing event break_lease_block: OK [ 1163.264118] Testing event break_lease_noblock: OK [ 1164.164134] Testing event flock_lock_inode: OK [ 1164.961574] Testing event locks_remove_posix: OK [ 1165.836765] Testing event fcntl_setlk: OK [ 1166.645965] Testing event posix_lock_inode: OK [ 1167.374720] Testing event locks_get_lock_context: OK [ 1168.314979] Testing event block_rq_remap: OK [ 1169.097696] Testing event block_bio_remap: OK [ 1169.899547] Testing event block_split: OK [ 1170.544709] Testing event block_unplug: OK [ 1171.196259] Testing event block_plug: OK [ 1171.969790] Testing event block_sleeprq: OK [ 1172.623686] Testing event block_getrq: OK [ 1173.304032] Testing event block_bio_queue: OK [ 1173.992076] Testing event block_bio_frontmerge: OK [ 1174.877728] Testing event block_bio_backmerge: OK [ 1175.589640] Testing event block_bio_complete: OK [ 1176.431532] Testing event block_bio_bounce: OK [ 1177.086017] Testing event block_rq_issue: OK [ 1177.867565] Testing event block_rq_insert: OK [ 1178.681444] Testing event block_rq_complete: OK [ 1179.284636] Testing event block_rq_requeue: OK [ 1180.083305] Testing event block_dirty_buffer: OK [ 1180.978793] Testing event block_touch_buffer: OK [ 1181.734294] Testing event wbt_timer: OK [ 1182.498143] Testing event wbt_step: OK [ 1183.086292] Testing event wbt_lat: OK [ 1183.953038] Testing event wbt_stat: OK [ 1184.642801] Testing event gpio_value: OK [ 1185.377855] Testing event gpio_direction: OK [ 1186.106598] Testing event clk_set_duty_cycle_complete: OK [ 1186.820209] Testing event clk_set_duty_cycle: OK [ 1187.623847] Testing event clk_set_phase_complete: OK [ 1188.556285] Testing event clk_set_phase: OK [ 1189.258890] Testing event clk_set_parent_complete: OK [ 1190.040055] Testing event clk_set_parent: OK [ 1190.875166] Testing event clk_set_rate_complete: OK [ 1191.607050] Testing event clk_set_rate: OK [ 1192.538066] Testing event clk_unprepare_complete: OK [ 1193.329627] Testing event clk_unprepare: OK [ 1194.164006] Testing event clk_prepare_complete: OK [ 1194.922295] Testing event clk_prepare: OK [ 1195.707544] Testing event clk_disable_complete: OK [ 1196.592671] Testing event clk_disable: OK [ 1197.304519] Testing event clk_enable_complete: OK [ 1198.187241] Testing event clk_enable: OK [ 1198.870144] Testing event rpmh_send_msg: OK [ 1199.507889] Testing event rpmh_tx_done: OK [ 1200.311597] Testing event regulator_set_voltage_complete: OK [ 1200.759285] Testing event regulator_set_voltage: OK [ 1201.628475] Testing event regulator_disable_complete: OK [ 1202.326670] Testing event regulator_disable: OK [ 1203.026964] Testing event regulator_enable_complete: OK [ 1203.980764] Testing event regulator_enable_delay: OK [ 1204.810415] Testing event regulator_enable: OK [ 1205.668231] Testing event urandom_read: OK [ 1206.324578] Testing event random_read: OK [ 1207.080455] Testing event extract_entropy_user: OK [ 1207.934114] Testing event extract_entropy: OK [ 1208.625739] Testing event get_random_bytes_arch: OK [ 1209.446954] Testing event get_random_bytes: OK [ 1210.295458] Testing event xfer_secondary_pool: OK [ 1211.066062] Testing event add_disk_randomness: OK [ 1211.915433] Testing event add_input_randomness: OK [ 1212.728387] Testing event debit_entropy: OK [ 1213.645958] Testing event push_to_pool: OK [ 1214.422288] Testing event credit_entropy_bits: OK [ 1215.205393] Testing event mix_pool_bytes_nolock: OK [ 1215.965429] Testing event mix_pool_bytes: OK [ 1216.632294] Testing event add_device_randomness: OK [ 1217.435379] Testing event io_page_fault: OK [ 1218.219928] Testing event unmap: OK [ 1219.111004] Testing event map: OK [ 1219.805684] Testing event detach_device_from_domain: OK [ 1220.698268] Testing event attach_device_to_domain: OK [ 1221.321730] Testing event remove_device_from_group: OK [ 1222.134114] Testing event add_device_to_group: OK [ 1222.941418] Testing event regcache_drop_region: OK [ 1223.710806] Testing event regmap_async_complete_done: OK [ 1224.496526] Testing event regmap_async_complete_start: OK [ 1225.324051] Testing event regmap_async_io_complete: OK [ 1226.121858] Testing event regmap_async_write_start: OK [ 1226.812385] Testing event regmap_cache_bypass: OK [ 1227.755072] Testing event regmap_cache_only: OK [ 1228.570654] Testing event regcache_sync: OK [ 1229.243353] Testing event regmap_hw_write_done: OK [ 1230.255330] Testing event regmap_hw_write_start: OK [ 1230.960723] Testing event regmap_hw_read_done: OK [ 1231.726288] Testing event regmap_hw_read_start: OK [ 1232.603093] Testing event regmap_reg_read_cache: OK [ 1233.438701] Testing event regmap_reg_read: OK [ 1234.195034] Testing event regmap_reg_write: OK [ 1235.183087] Testing event dma_fence_wait_end: OK [ 1235.757931] Testing event dma_fence_wait_start: OK [ 1236.419637] Testing event dma_fence_signaled: OK [ 1237.217416] Testing event dma_fence_enable_signal: OK [ 1237.886775] Testing event dma_fence_destroy: OK [ 1238.654923] Testing event dma_fence_init: OK [ 1239.479191] Testing event dma_fence_emit: OK [ 1240.264864] Testing event sync_timeline: OK [ 1240.995223] Testing event spi_transfer_stop: OK [ 1241.743520] Testing event spi_transfer_start: OK [ 1242.548667] Testing event spi_message_done: OK [ 1243.555419] Testing event spi_message_start: OK [ 1244.276526] Testing event spi_message_submit: OK [ 1244.914855] Testing event spi_controller_busy: OK [ 1245.618988] Testing event spi_controller_idle: OK [ 1246.352906] Testing event rtc_timer_fired: OK [ 1247.109028] Testing event rtc_timer_dequeue: OK [ 1248.050427] Testing event rtc_timer_enqueue: OK [ 1248.828056] Testing event rtc_read_offset: OK [ 1249.635692] Testing event rtc_set_offset: OK [ 1250.480532] Testing event rtc_alarm_irq_enable: OK [ 1251.256301] Testing event rtc_irq_set_state: OK [ 1252.016906] Testing event rtc_irq_set_freq: OK [ 1252.843070] Testing event rtc_read_alarm: OK [ 1253.749256] Testing event rtc_set_alarm: OK [ 1254.415966] Testing event rtc_read_time: OK [ 1255.246778] Testing event rtc_set_time: OK [ 1255.910824] Testing event memory_failure_event: OK [ 1256.617409] Testing event aer_event: OK [ 1257.276743] Testing event non_standard_event: OK [ 1258.114966] Testing event arm_event: OK [ 1258.860141] Testing event mc_event: OK [ 1259.620642] Testing event binder_return: OK [ 1260.393615] Testing event binder_command: OK [ 1261.240354] Testing event binder_unmap_kernel_end: OK [ 1262.030981] Testing event binder_unmap_kernel_start: OK [ 1262.960952] Testing event binder_unmap_user_end: OK [ 1263.796186] Testing event binder_unmap_user_start: OK [ 1264.402804] Testing event binder_alloc_page_end: OK [ 1265.281453] Testing event binder_alloc_page_start: OK [ 1266.130998] Testing event binder_free_lru_end: OK [ 1266.845398] Testing event binder_free_lru_start: OK [ 1267.805207] Testing event binder_alloc_lru_end: OK [ 1268.464529] Testing event binder_alloc_lru_start: OK [ 1269.366116] Testing event binder_update_page_range: OK [ 1270.152756] Testing event binder_transaction_failed_buffer_release: OK [ 1270.989334] Testing event binder_transaction_buffer_release: OK [ 1271.737372] Testing event binder_transaction_alloc_buf: OK [ 1272.498198] Testing event binder_transaction_fd_recv: OK [ 1273.352749] Testing event binder_transaction_fd_send: OK [ 1274.153353] Testing event binder_transaction_ref_to_ref: OK [ 1274.897635] Testing event binder_transaction_ref_to_node: OK [ 1275.682330] Testing event binder_transaction_node_to_ref: OK [ 1276.535970] Testing event binder_transaction_received: OK [ 1277.513220] Testing event binder_transaction: OK [ 1278.209133] Testing event binder_wait_for_work: OK [ 1278.934744] Testing event binder_read_done: OK [ 1279.772570] Testing event binder_write_done: OK [ 1280.492742] Testing event binder_ioctl_done: OK [ 1281.057849] Testing event binder_unlock: OK [ 1281.657765] Testing event binder_locked: OK [ 1282.365216] Testing event binder_lock: OK [ 1283.123533] Testing event binder_ioctl: OK [ 1283.901481] Testing event br_fdb_update: OK [ 1284.670575] Testing event fdb_delete: OK [ 1285.545597] Testing event br_fdb_external_learn_add: OK [ 1286.312314] Testing event br_fdb_add: OK [ 1287.008369] Testing event qdisc_dequeue: OK [ 1287.712929] Testing event fib_table_lookup: OK [ 1288.565011] Testing event tcp_probe: OK [ 1289.506867] Testing event tcp_retransmit_synack: OK [ 1290.040927] Testing event tcp_rcv_space_adjust: OK [ 1290.833666] Testing event tcp_destroy_sock: OK [ 1291.674602] Testing event tcp_receive_reset: OK [ 1292.450848] Testing event tcp_send_reset: OK [ 1293.258817] Testing event tcp_retransmit_skb: OK [ 1293.931308] Testing event udp_fail_queue_rcv_skb: OK [ 1294.764853] Testing event inet_sock_set_state: OK [ 1295.587785] Testing event sock_exceed_buf_limit: OK [ 1296.392320] Testing event sock_rcvqueue_full: OK [ 1297.174297] Testing event napi_poll: OK [ 1297.942949] Testing event netif_rx_ni_entry: OK [ 1298.831441] Testing event netif_rx_entry: OK [ 1299.567124] Testing event netif_receive_skb_list_entry: OK [ 1300.116208] Testing event netif_receive_skb_entry: OK [ 1301.096982] Testing event napi_gro_receive_entry: OK [ 1301.738371] Testing event napi_gro_frags_entry: OK [ 1302.528076] Testing event netif_rx: OK [ 1303.363029] Testing event netif_receive_skb: OK [ 1304.121977] Testing event net_dev_queue: OK [ 1304.963157] Testing event net_dev_xmit: OK [ 1305.723715] Testing event net_dev_start_xmit: OK [ 1306.591239] Testing event skb_copy_datagram_iovec: OK [ 1307.374612] Testing event consume_skb: OK [ 1308.222782] Testing event kfree_skb: OK [ 1309.060824] Testing event 9p_protocol_dump: OK [ 1310.012194] Testing event 9p_client_res: OK [ 1310.800104] Testing event 9p_client_req: OK [ 1311.728518] Running tests on trace event systems: [ 1311.746388] Testing event system 9p: OK [ 1312.620747] Testing event system skb: OK [ 1313.227337] Testing event system net: OK [ 1314.262249] Testing event system napi: OK [ 1315.198566] Testing event system sock: OK [ 1316.162255] Testing event system udp: OK [ 1317.041192] Testing event system tcp: OK [ 1317.952072] Testing event system fib: OK [ 1318.698784] Testing event system qdisc: OK [ 1319.707247] Testing event system bridge: OK [ 1320.562428] Testing event system binder: OK [ 1322.149634] Testing event system ras: OK [ 1323.404240] Testing event system rtc: OK [ 1324.549986] Testing event system spi: OK [ 1325.654554] Testing event system sync_trace: OK [ 1326.609058] Testing event system dma_fence: OK [ 1327.556989] Testing event system regmap: OK [ 1328.803166] Testing event system iommu: OK [ 1329.753102] Testing event system random: OK [ 1331.010637] Testing event system regulator: OK [ 1331.933894] Testing event system rpmh: OK [ 1332.947746] Testing event system clk: OK [ 1334.113300] Testing event system gpio: OK [ 1335.130549] Testing event system wbt: OK [ 1336.008999] Testing event system block: OK [ 1337.226605] Testing event system filelock: OK [ 1338.572249] Testing event system fs_dax: OK [ 1339.771309] Testing event system writeback: OK [ 1341.166173] Testing event system page_ref: OK [ 1344.328564] Testing event system cma: OK [ 1345.279840] Testing event system page_isolation: OK [ 1346.165482] Testing event system huge_memory: OK [ 1347.056011] Testing event system migrate: OK [ 1347.933204] Testing event system compaction: OK [ 1349.071200] Testing event system kmem: OK [ 1350.362355] Testing event system percpu: OK [ 1351.420479] Testing event system vmscan: OK [ 1352.534967] Testing event system pagemap: OK [ 1353.513438] Testing event system oom: OK [ 1354.354563] Testing event system filemap: OK [ 1355.380480] Testing event system rseq: OK [ 1356.252678] Testing event system xdp: OK [ 1357.072349] Testing event system benchmark: OK [ 1358.376870] Testing event system rpm: OK [ 1359.270927] Testing event system power: OK [ 1360.672107] Testing event system test: OK [ 1361.849247] Testing event system preemptirq: OK [ 1363.889234] Testing event system cgroup: OK [ 1364.839259] Testing event system module: OK [ 1365.831028] Testing event system alarmtimer: OK [ 1366.627704] Testing event system timer: OK [ 1368.000096] Testing event system swiotlb: OK [ 1368.927363] Testing event system rcu: OK [ 1370.480886] Testing event system printk: OK [ 1371.447316] Testing event system lock: OK [ 1372.401523] Testing event system sched: OK [ 1373.854105] Testing event system workqueue: OK [ 1374.768858] Testing event system signal: OK [ 1375.791487] Testing event system irq: OK [ 1376.704426] Testing event system cpuhp: OK [ 1377.581467] Testing event system task: OK [ 1378.448560] Testing event system kvm: OK [ 1380.140399] Testing event system emulation: OK [ 1381.272072] Testing event system ipi: OK [ 1381.976429] Testing event system raw_syscalls: OK [ 1382.778641] Testing event system initcall: OK [ 1383.697270] Running tests on all trace events: [ 1383.712855] Testing all events: OK [ 1425.907699] ftrace_replace_code: 1 [ 1439.142258] ../kernel/trace/ftrace.c:2442, loop_counter: 41985 [ 1439.164948] Testing ftrace filter: OK [ 1439.384946] trace_kprobe: Testing kprobe tracing: OK [ 1440.270271] Loading compiled-in X.509 certificates [ 1440.417036] _warn_unseeded_randomness: 3 callbacks suppressed [ 1440.417357] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 1440.422645] Loaded X.509 cert 'Build time autogenerated kernel key: ef4425a12675de91b49c005bf61fbcc152bd6e38' [ 1440.449480] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 1440.452040] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 1440.504941] zswap: default zpool zbud not available [ 1440.508218] zswap: pool creation failed [ 1440.551416] page_owner is disabled [ 1440.818363] Key type big_key registered [ 1440.934313] Key type encrypted registered [ 1440.942320] ima: No TPM chip found, activating TPM-bypass! [ 1440.944979] ima: Allocated hash algorithm: sha1 [ 1440.950273] No architecture policies found [ 1440.963175] evm: Initialising EVM extended attributes: [ 1440.964300] evm: security.selinux [ 1440.965374] evm: security.SMACK64 [ 1440.967002] evm: security.SMACK64EXEC [ 1440.968043] evm: security.SMACK64TRANSMUTE [ 1440.969094] evm: security.SMACK64MMAP [ 1440.970774] evm: security.apparmor [ 1440.971787] evm: security.ima [ 1440.972784] evm: security.capability [ 1440.974561] evm: HMAC attrs: 0x1 [ 1441.070168] hctosys: unable to open rtc device (rtc0) [ 1441.104635] psci_checker: PSCI checker started using 1 CPUs [ 1441.106459] psci_checker: Starting hotplug tests [ 1441.108829] psci_checker: Trying to turn off and on again all CPUs [ 1441.111256] psci_checker: Trying to turn off and on again group 0 (CPUs 0) [ 1441.114247] psci_checker: Hotplug tests passed OK [ 1441.115326] psci_checker: Starting suspend tests (10 cycles per state) [ 1441.117001] psci_checker: cpuidle not available on CPU 0, ignoring [ 1441.119344] psci_checker: Could not start suspend tests on any CPU [ 1441.120482] psci_checker: PSCI checker completed [ 1441.207282] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 1441.521243] ### dt-test ### start of unittest - you will see error messages [ 1441.569158] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 1441.572012] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 1441.574680] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 1441.576598] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 1441.579057] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 1441.581097] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 1441.613171] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 1441.618132] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 1441.622416] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 1442.035805] _warn_unseeded_randomness: 2 callbacks suppressed [ 1442.036089] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 1442.039229] kobject: 'testcase-data:platform-tests:test-device@1' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 1442.066253] kobject: 'testcase-data:platform-tests:test-device@0' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 1442.086537] kobject: 'unittest-bus.0' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 1442.105451] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 1442.347203] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status [ 1442.434183] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status [ 1442.461000] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 1442.463515] kobject: 'testcase-data:overlay-node:test-bus:test-unittest1' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 1442.480254] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status [ 1442.563560] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status [ 1442.587820] kobject: 'testcase-data:overlay-node:test-bus:test-unittest3' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 1442.698557] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status [ 1442.795919] kobject: 'testcase-data:overlay-node:test-bus:test-unittest5' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 1442.819301] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status [ 1442.895717] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status [ 1442.994769] kobject: 'testcase-data:overlay-node:test-bus:test-unittest7' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 1443.020367] kobject: 'testcase-data:overlay-node:test-bus:test-unittest6' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 1443.038944] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status [ 1443.114925] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo [ 1443.119422] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 1443.121234] OF: overlay: overlay #6 is not topmost [ 1443.148761] kobject: 'testcase-data:overlay-node:test-bus:test-unittest8' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 1443.164450] _warn_unseeded_randomness: 6 callbacks suppressed [ 1443.164731] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 1443.571696] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 1443.573343] kobject: 'testcase-data:overlay-node:test-bus:test-unittest11:test-unittest111' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 1443.597221] kobject: 'testcase-data:overlay-node:test-bus:test-unittest11' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 1443.602339] 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 [ 1443.607256] 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 [ 1443.612100] OF: overlay: remove: Could not find overlay #7 [ 1443.613370] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #7 [ 1443.647481] kobject: 'testcase-data:overlay-node:test-bus:test-unittest10:test-unittest101' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 1443.671435] kobject: 'testcase-data:overlay-node:test-bus:test-unittest10' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 1443.675821] 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 [ 1443.680624] 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 [ 1443.710254] kobject: 'testcase-data:overlay-node:test-bus:test-unittest4' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 1443.714531] 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 [ 1443.808589] kobject: 'testcase-data:overlay-node:test-bus:test-unittest2' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 1443.819751] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 1443.906055] kobject: 'testcase-data:overlay-node:test-bus:test-unittest0' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 1444.026096] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/status [ 1444.035869] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/status [ 1444.040841] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@30/incline-up [ 1444.044789] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@40/incline-up [ 1444.066551] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/status [ 1444.069194] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/color [ 1444.072302] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/rate [ 1444.076560] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/hvac_2 [ 1444.080283] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200 [ 1444.083902] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_left [ 1444.087773] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_right [ 1444.272654] OF: overlay: ERROR: multiple fragments add and/or delete node /testcase-data-2/substation@100/motor-1/controller [ 1444.280865] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/controller/name [ 1444.285211] _warn_unseeded_randomness: 2 callbacks suppressed [ 1444.287297] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 1444.288806] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 1444.292640] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 1444.307994] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 1444.311518] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 1444.315270] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/rpm_avail [ 1444.339064] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 1444.344677] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 1444.346963] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 1444.351152] ### dt-test ### end of unittest - 217 passed, 0 failed [ 1444.402682] uart-pl011 9000000.pl011: no DMA platform data [ 1445.060421] VFS: Mounted root (9p filesystem) readonly on device 0:19. [ 1445.075930] kobject: 'test-unittest101' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 1445.107769] devtmpfs: mounted [ 1445.143896] integrity: Unable to open file: /etc/keys/x509_ima.der (-2) [ 1445.175269] integrity: Unable to open file: /etc/keys/x509_evm.der (-2) [ 1446.610173] Freeing unused kernel memory: 6144K [ 1446.631082] _warn_unseeded_randomness: 5 callbacks suppressed [ 1446.631369] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 1446.634769] kobject: 'test-unittest4' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 1446.641139] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest10/test-unittest101 [ 1447.142947] Checked W+X mappings: passed, no W+X pages found [ 1447.144444] rodata_test: all tests were successful [ 1447.146901] Run /sbin/init as init process [ 1447.158639] kobject: 'test-unittest11' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 1447.219422] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 1447.372027] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=0 [ 1447.659720] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest4 [ 1447.760199] _warn_unseeded_randomness: 4 callbacks suppressed [ 1447.760483] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 1447.763797] kobject: 'test-unittest10' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 1447.780433] kobject: 'test-unittest111' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 1448.004148] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 1449.108759] random: fast init done [ 1449.163706] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11 [ 1451.853041] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11/test-unittest111 [ 1451.858734] 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 [ 1456.595915] systemd[1]: System time before build time, advancing clock. [ 1457.548734] systemd[1]: Failed to insert module 'autofs4': No such file or directory [ 1463.149388] random: systemd: uninitialized urandom read (16 bytes read) [ 1463.763913] random: systemd: uninitialized urandom read (16 bytes read) [ 1463.773218] random: systemd: uninitialized urandom read (16 bytes read) [ 1472.931971] 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) [ 1473.081153] systemd[1]: Detected virtualization qemu. [ 1473.092870] systemd[1]: Detected architecture arm64. Welcome to Debian GNU/Linux 9 (stretch)! [ 1473.397028] systemd[1]: Set hostname to . [ 1473.721062] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=1 [ 1473.839712] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 1475.049066] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 1476.060354] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1476.063423] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1476.064784] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1477.182958] _warn_unseeded_randomness: 12 callbacks suppressed [ 1477.183266] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 1477.447160] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1477.448720] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1478.291923] _warn_unseeded_randomness: 6 callbacks suppressed [ 1478.292215] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1478.296770] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1478.298974] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1479.458549] _warn_unseeded_randomness: 5 callbacks suppressed [ 1479.458843] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1479.461110] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1479.463176] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1480.338636] random: systemd: uninitialized urandom read (16 bytes read) [ 1480.552543] _warn_unseeded_randomness: 4 callbacks suppressed [ 1480.552853] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 1480.850593] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1480.851980] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1482.069365] _warn_unseeded_randomness: 4 callbacks suppressed [ 1482.072413] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 1482.241142] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1482.243164] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1484.175561] _warn_unseeded_randomness: 6 callbacks suppressed [ 1484.175870] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1484.178309] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1484.224174] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1485.655273] _warn_unseeded_randomness: 3 callbacks suppressed [ 1485.655578] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1485.657115] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1485.720405] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1487.662337] systemd-hiberna (1268) used greatest stack depth: 8 bytes left [ 1490.724861] random: systemd-cryptse: uninitialized urandom read (16 bytes read) [ 1492.019465] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 1494.220097] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 1498.103491] random: systemd: uninitialized urandom read (16 bytes read) [ 1498.506143] random: systemd: uninitialized urandom read (16 bytes read) [ 1498.512397] random: systemd: uninitialized urandom read (16 bytes read) [ 1498.658973] _warn_unseeded_randomness: 1 callbacks suppressed [ 1498.659298] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 1498.895633] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 1513.760382] systemd[1]: networking.service: Cannot add dependency job, ignoring: Unit networking.service is masked. [ 1513.893009] random: get_random_bytes called from generate_random_uuid+0x20/0x50 with crng_init=1 [ 1514.025115] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 1514.099435] 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. [ 1514.134450] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 1514.192919] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ OK ] Listening on /dev/initctl Compatibility Named Pipe. [ 1514.260809] systemd[1]: Listening on Journal Audit Socket. [ OK ] Listening on Journal Audit Socket. [ 1514.346673] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 1514.511902] systemd[1]: Created slice System Slice. [ OK ] Created slice System Slice. [ 1514.647737] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ 1514.779268] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ OK ] Reached target Slices. [ OK ] Listening on Syslog Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Encrypted Volumes. [ OK ] Reached target Paths. [ 1515.360789] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 Mounting Debug File System... [ 1516.728967] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1516.730955] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1516.732118] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 Mounting POSIX Message Queue File System... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket. [ 1518.180587] _warn_unseeded_randomness: 5 callbacks suppressed [ 1518.180893] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 1518.291389] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1518.292754] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1518.781365] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 1520.180761] _warn_unseeded_randomness: 5 callbacks suppressed [ 1520.181069] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1520.183588] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1520.219868] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 1521.925242] _warn_unseeded_randomness: 3 callbacks suppressed [ 1521.928756] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 1523.005063] systemd[1]: Starting Create Static Device Nodes in /dev... Starting Create Static Device Nodes in /dev... [ 1524.246975] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1524.248327] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1524.250495] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1525.516889] systemd[1]: Starting Journal Service... Starting Journal Service... [ 1528.404952] _warn_unseeded_randomness: 8 callbacks suppressed [ 1528.405276] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 1528.539933] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 1528.548618] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 1529.300024] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 1529.456969] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 1529.544860] _warn_unseeded_randomness: 14 callbacks suppressed [ 1529.545146] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1529.590949] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1529.645321] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 1531.356094] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 1531.642744] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1531.644160] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1532.366318] _warn_unseeded_randomness: 4 callbacks suppressed [ 1532.366615] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 1532.473226] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 1532.771906] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 1533.048070] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 1533.459102] systemd[1]: Reached target Sockets. [ OK ] Reached target Sockets. [ 1537.051241] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1537.052667] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1537.054590] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1538.107614] _warn_unseeded_randomness: 1 callbacks suppressed [ 1538.107987] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1538.208591] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1538.820471] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 1540.892767] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 1542.441069] systemd[1]: Mounted Debug File System. [ OK ] Mounted Debug File System. [ 1542.588002] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 1544.532742] systemd[1]: Started Remount Root and Kernel File Systems. [ OK ] Started Remount Root and Kernel File Systems. [ 1545.530316] systemd[1]: Started Create Static Device Nodes in /dev. [ OK ] Started Create Static Device Nodes in /dev. [ 1546.456583] systemd[1]: Started Load Kernel Modules. [ OK ] Started Load Kernel Modules. [ 1546.570313] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [ 1546.855268] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 1548.536992] systemd[1]: Mounting Configuration File System... Mounting Configuration File System... [ 1551.039717] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1551.041143] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1551.043120] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1551.280200] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 1552.865543] _warn_unseeded_randomness: 4 callbacks suppressed [ 1552.865867] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 1553.145195] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1553.147179] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1554.482633] systemd[1]: Starting udev Kernel Device Manager... [ 1554.582658] _warn_unseeded_randomness: 2 callbacks suppressed [ 1554.582952] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 Starting udev Kernel Device Manager... [ 1554.854553] systemd[1]: Reached target Local File Systems (Pre). [ OK ] Reached target Local File Systems (Pre). [ 1555.168938] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 1557.656833] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 1557.679959] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 1557.804142] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 1558.736939] _warn_unseeded_randomness: 5 callbacks suppressed [ 1558.737220] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 1558.965340] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1558.983790] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1560.848870] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 1563.580362] _warn_unseeded_randomness: 2 callbacks suppressed [ 1563.580657] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1563.583417] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1563.584579] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1564.239054] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 1567.494786] _warn_unseeded_randomness: 2 callbacks suppressed [ 1567.495093] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1567.496776] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1567.537579] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 1570.057319] systemd[1]: Mounted Configuration File System. [ OK ] Mounted Configuration File System. [ 1572.047459] systemd[1]: Started Apply Kernel Variables. [ OK ] Started Apply Kernel Variables. [ 1573.078276] _warn_unseeded_randomness: 3 callbacks suppressed [ 1573.078586] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 1574.195295] systemd[1]: Started Load/Save Random Seed. [ OK ] Started Load/Save Random Seed. [* ] (1 of 4) A start job is running for…v-ttyAMA0.device (1min 5s / 30min) [** ] (1 of 4) A start job is running for…v-ttyAMA0.device (1min 6s / 30min) [*** ] (1 of 4) A start job is running for…v-ttyAMA0.device (1min 6s / 30min) [ *** ] (2 of 4) A start job is running for…g all Devices (1min 7s / no limit) [ *** ] (2 of 4) A start job is running for…g all Devices (1min 7s / no limit) [ ***] (2 of 4) A start job is running for…g all Devices (1min 8s / no limit) [ **] (3 of 4) A start job is running for…vice Manager (1min 8s / 30min 39s) [ *] (3 of 4) A start job is running for…vice Manager (1min 9s / 30min 39s) [ **] (3 of 4) A start job is running for…vice Manager (1min 9s / 30min 39s) [ ***] (4 of 4) A start job is running for…nal Service (1min 10s / 30min 10s) [ *** ] (4 of 4) A start job is running for…nal Service (1min 10s / 30min 10s) [ *** ] (4 of 4) A start job is running for…nal Service (1min 11s / 30min 10s) [*** ] (1 of 4) A start job is running for…-ttyAMA0.device (1min 11s / 30min) [** ] (1 of 4) A start job is running for…-ttyAMA0.device (1min 12s / 30min) [* ] (1 of 4) A start job is running for…-ttyAMA0.device (1min 12s / 30min)[ 1586.598757] systemd[1]: Started udev Kernel Device Manager. [ OK ] Started udev Kernel Device Manager. [** ] (3 of 3) A start job is running for…nal Service (1min 18s / 30min 10s) [*** ] (3 of 3) A start job is running for…nal Service (1min 18s / 30min 10s) [ *** ] (3 of 3) A start job is running for…nal Service (1min 19s / 30min 10s) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 19s / 30min) [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (1min 20s / 30min) [ **] (1 of 3) A start job is running for…-ttyAMA0.device (1min 20s / 30min) [ *] (2 of 3) A start job is running for… all Devices (1min 21s / no limit) [ **] (2 of 3) A start job is running for… all Devices (1min 21s / no limit) [ ***] (2 of 3) A start job is running for… all Devices (1min 22s / no limit) [ *** ] (3 of 3) A start job is running for…nal Service (1min 22s / 30min 10s) [ *** ] (3 of 3) A start job is running for…nal Service (1min 23s / 30min 10s) [*** ] (3 of 3) A start job is running for…nal Service (1min 23s / 30min 10s) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 24s / 30min) [* ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 24s / 30min) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 25s / 30min)[ 1599.044742] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. Starting Flush Journal to Persistent Storage... [ 1602.969204] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1602.971078] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1602.972079] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [*** ] (2 of 3) A start job is running for… all Devices (1min 30s / no limit) [ *** ] (2 of 3) A start job is running for… all Devices (1min 31s / no limit) [ *** ] (2 of 3) A start job is running for… all Devices (1min 31s / no limit) [ ***] (3 of 3) A start job is running for…tent Storage (1min 32s / 2min 56s) [ **] (3 of 3) A start job is running for…tent Storage (1min 32s / 2min 56s) [ *] (3 of 3) A start job is running for…tent Storage (1min 33s / 2min 56s) [ **] (1 of 3) A start job is running for…-ttyAMA0.device (1min 33s / 30min)[ 1607.929157] systemd-journald[1284]: Received request to flush runtime journal from PID 1 [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (1min 34s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 34s / 30min)[ 1609.015079] _warn_unseeded_randomness: 4 callbacks suppressed [ 1609.015385] 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... [ 1613.963285] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 1613.964613] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 1613.966526] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 1615.047245] _warn_unseeded_randomness: 5 callbacks suppressed [ 1615.047599] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ *** ] (2 of 3) A start job is running for… Directories (1min 41s / no limit) [*** ] (2 of 3) A start job is running for… Directories (1min 41s / no limit)[ 1615.993024] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [** ] (2 of 3) A start job is running for… Directories (1min 42s / no limit) [* ] (3 of 3) A start job is running for… all Devices (1min 42s / no limit) [** ] (3 of 3) A start job is running for… all Devices (1min 43s / no limit) [*** ] (3 of 3) A start job is running for… all Devices (1min 43s / no limit) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 44s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 44s / 30min) [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (1min 45s / 30min) [ **] (2 of 3) A start job is running for… Directories (1min 45s / no limit) [ *] (2 of 3) A start job is running for… Directories (1min 46s / no limit) [ **] (2 of 3) A start job is running for… Directories (1min 46s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (1min 47s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 47s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 48s / no limit) [*** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 48s / 30min) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 49s / 30min) [* ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 49s / 30min) [** ] (2 of 3) A start job is running for… Directories (1min 50s / no limit) [*** ] (2 of 3) A start job is running for… Directories (1min 50s / no limit) [ *** ] (2 of 3) A start job is running for… Directories (1min 51s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 51s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (1min 52s / no limit) [ **] (3 of 3) A start job is running for… all Devices (1min 52s / no limit) [ *] (1 of 3) A start job is running for…-ttyAMA0.device (1min 53s / 30min) [ **] (1 of 3) A start job is running for…-ttyAMA0.device (1min 53s / 30min) [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (1min 54s / 30min)[ 1628.231981] random: crng init done [ *** ] (2 of 3) A start job is running for… Directories (1min 54s / no limit) [ *** ] (2 of 3) A start job is running for… Directories (1min 55s / no limit) [*** ] (2 of 3) A start job is running for… Directories (1min 55s / no limit) [** ] (3 of 3) A start job is running for… all Devices (1min 56s / no limit) [* ] (3 of 3) A start job is running for… all Devices (1min 56s / no limit) [** ] (3 of 3) A start job is running for… all Devices (1min 57s / no limit) [*** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 57s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 58s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 58s / 30min) [ ***] (2 of 3) A start job is running for… Directories (1min 59s / no limit) [ **] (2 of 3) A start job is running for… Directories (1min 59s / no limit) [ *] (2 of 3) A start job is running for… and Directories (2min / no limit) [ **] (3 of 3) A start job is running for…plug all Devices (2min / no limit) [ ***] (3 of 3) A start job is running for…g all Devices (2min 1s / no limit) [ *** ] (3 of 3) A start job is running for…g all Devices (2min 1s / no limit) [ *** ] (1 of 3) A start job is running for…v-ttyAMA0.device (2min 4s / 30min) [*** ] (1 of 3) A start job is running for…v-ttyAMA0.device (2min 4s / 30min) [** ] (1 of 3) A start job is running for…v-ttyAMA0.device (2min 5s / 30min) [* ] (2 of 3) A start job is running for…d Directories (2min 5s / no limit) [** ] (2 of 3) A start job is running for…d Directories (2min 6s / no limit) [*** ] (2 of 3) A start job is running for…d Directories (2min 6s / no limit) [ *** ] (3 of 3) A start job is running for…g all Devices (2min 7s / no limit) [ *** ] (3 of 3) A start job is running for…g all Devices (2min 7s / no limit) [ ***] (3 of 3) A start job is running for…g all Devices (2min 8s / no limit) [ **] (1 of 3) A start job is running for…v-ttyAMA0.device (2min 8s / 30min) [ *] (1 of 3) A start job is running for…v-ttyAMA0.device (2min 9s / 30min) [ **] (1 of 3) A start job is running for…v-ttyAMA0.device (2min 9s / 30min) [ ***] (2 of 3) A start job is running for… Directories (2min 10s / no limit) [ *** ] (2 of 3) A start job is running for… Directories (2min 10s / no limit) [ *** ] (2 of 3) A start job is running for… Directories (2min 11s / no limit) [*** ] (3 of 3) A start job is running for… all Devices (2min 11s / no limit) [** ] (3 of 3) A start job is running for… all Devices (2min 12s / no limit) [* ] (3 of 3) A start job is running for… all Devices (2min 12s / no limit) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (2min 13s / 30min) [*** ] (1 of 3) A start job is running for…-ttyAMA0.device (2min 13s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (2min 14s / 30min) [ *** ] (2 of 3) A start job is running for… Directories (2min 14s / no limit) [ ***] (2 of 3) A start job is running for… Directories (2min 15s / no limit) [ **] (2 of 3) A start job is running for… Directories (2min 15s / no limit) [ *] (3 of 3) A start job is running for… all Devices (2min 16s / no limit) [ **] (3 of 3) A start job is running for… all Devices (2min 16s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (2min 17s / no limit) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (2min 17s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (2min 18s / 30min) [ OK ] Started Create Volatile Files and Directories. Starting Network Time Synchronization... Starting Update UTMP about System Boot/Shutdown... [*** ] (1 of 4) A start job is running for…-ttyAMA0.device (2min 30s / 30min) [** ] (2 of 4) A start job is running for… all Devices (2min 31s / no limit) [* ] (2 of 4) A start job is running for… all Devices (2min 32s / no limit) [** ] (2 of 4) A start job is running for… all Devices (2min 32s / no limit) [*** ] (3 of 4) A start job is running for…hronization (2min 33s / 32min 23s) [ *** ] (3 of 4) A start job is running for…hronization (2min 33s / 32min 23s) [ *** ] (3 of 4) A start job is running for…hronization (2min 34s / 32min 23s) [ ***] (4 of 4) A start job is running for…oot/Shutdown (2min 34s / no limit) [ **] (4 of 4) A start job is running for…oot/Shutdown (2min 35s / no limit) [ *] (4 of 4) A start job is running for…oot/Shutdown (2min 35s / no limit) [ **] (1 of 4) A start job is running for…-ttyAMA0.device (2min 36s / 30min) [ ***] (1 of 4) A start job is running for…-ttyAMA0.device (2min 36s / 30min) [ *** ] (1 of 4) A start job is running for…-ttyAMA0.device (2min 37s / 30min) [ *** ] (2 of 4) A start job is running for… all Devices (2min 37s / no limit) [*** ] (2 of 4) A start job is running for… all Devices (2min 38s / no limit) [** ] (2 of 4) A start job is running for… all Devices (2min 38s / no limit) [* ] (3 of 4) A start job is running for…hronization (2min 39s / 32min 23s) [** ] (3 of 4) A start job is running for…hronization (2min 39s / 32min 23s) [*** ] (3 of 4) A start job is running for…hronization (2min 40s / 32min 23s) [ *** ] (4 of 4) A start job is running for…oot/Shutdown (2min 41s / no limit) [ *** ] (4 of 4) A start job is running for…oot/Shutdown (2min 41s / no limit) [ ***] (4 of 4) A start job is running for…oot/Shutdown (2min 42s / no limit) [ **] (1 of 4) A start job is running for…-ttyAMA0.device (2min 42s / 30min) [ *] (1 of 4) A start job is running for…-ttyAMA0.device (2min 43s / 30min) [ **] (1 of 4) A start job is running for…-ttyAMA0.device (2min 43s / 30min) [ OK ] Started Update UTMP about System Boot/Shutdown. [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (2min 50s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (2min 50s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (2min 51s / 30min) [*** ] (2 of 3) A start job is running for… all Devices (2min 51s / no limit) [** ] (2 of 3) A start job is running for… all Devices (2min 52s / no limit) [* ] (2 of 3) A start job is running for… all Devices (2min 52s / no limit) [** ] (3 of 3) A start job is running for…hronization (2min 53s / 32min 23s) [*** ] (3 of 3) A start job is running for…hronization (2min 53s / 32min 23s) [ *** ] (3 of 3) A start job is running for…hronization (2min 54s / 32min 23s) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (2min 54s / 30min) [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (2min 55s / 30min) [ **] (1 of 3) A start job is running for…-ttyAMA0.device (2min 55s / 30min) [ *] (2 of 3) A start job is running for… all Devices (2min 56s / no limit) [ **] (2 of 3) A start job is running for… all Devices (2min 56s / no limit) [ ***] (2 of 3) A start job is running for… all Devices (2min 57s / no limit) [ *** ] (3 of 3) A start job is running for…hronization (2min 57s / 32min 23s) [ *** ] (3 of 3) A start job is running for…hronization (2min 58s / 32min 23s) [*** ] (3 of 3) A start job is running for…hronization (2min 58s / 32min 23s) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (2min 59s / 30min) [* ] (1 of 3) A start job is running for…-ttyAMA0.device (2min 59s / 30min) [** ] (1 of 3) A start job is running for dev-ttyAMA0.device (3min / 30min) [*** ] (2 of 3) A start job is running for…plug all Devices (3min / no limit) [ *** ] (2 of 3) A start job is running for…g all Devices (3min 1s / no limit) [ *** ] (2 of 3) A start job is running for…g all Devices (3min 1s / no limit) [ ***] (3 of 3) A start job is running for…chronization (3min 2s / 32min 23s) [ **] (3 of 3) A start job is running for…chronization (3min 2s / 32min 23s) [ *] (3 of 3) A start job is running for…chronization (3min 3s / 32min 23s) [ **] (1 of 3) A start job is running for…v-ttyAMA0.device (3min 3s / 30min) [ ***] (1 of 3) A start job is running for…v-ttyAMA0.device (3min 4s / 30min) [ *** ] (1 of 3) A start job is running for…v-ttyAMA0.device (3min 4s / 30min) [ *** ] (2 of 3) A start job is running for…g all Devices (3min 5s / no limit) [*** ] (2 of 3) A start job is running for…g all Devices (3min 5s / no limit) [** ] (2 of 3) A start job is running for…g all Devices (3min 6s / no limit) [* ] (3 of 3) A start job is running for…chronization (3min 6s / 32min 23s) [** ] (3 of 3) A start job is running for…chronization (3min 7s / 32min 23s) [*** ] (3 of 3) A start job is running for…chronization (3min 7s / 32min 23s) [ *** ] (1 of 3) A start job is running for…v-ttyAMA0.device (3min 8s / 30min) [ *** ] (1 of 3) A start job is running for…v-ttyAMA0.device (3min 8s / 30min) [ ***] (1 of 3) A start job is running for…v-ttyAMA0.device (3min 9s / 30min) [ OK ] Started Network Time Synchronization. [ OK ] Reached target System Time Synchronized. [ **] (1 of 2) A start job is running for…-ttyAMA0.device (3min 15s / 30min) [ *] (1 of 2) A start job is running for…-ttyAMA0.device (3min 16s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (3min 16s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (3min 17s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (3min 17s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (3min 18s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 18s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 19s / 30min) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 19s / 30min) [** ] (2 of 2) A start job is running for… all Devices (3min 20s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (3min 20s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (3min 21s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 21s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (3min 22s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (3min 22s / 30min) [ *] (2 of 2) A start job is running for… all Devices (3min 23s / no limit) [ **] (2 of 2) A start job is running for… all Devices (3min 23s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (3min 24s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 24s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 25s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 25s / 30min) [** ] (2 of 2) A start job is running for… all Devices (3min 26s / no limit) [* ] (2 of 2) A start job is running for… all Devices (3min 26s / no limit) [** ] (2 of 2) A start job is running for… all Devices (3min 27s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 28s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 28s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 29s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (3min 29s / no limit) [ **] (2 of 2) A start job is running for… all Devices (3min 30s / no limit) [ *] (2 of 2) A start job is running for… all Devices (3min 30s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (3min 31s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (3min 31s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 32s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (3min 32s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (3min 33s / no limit) [** ] (2 of 2) A start job is running for… all Devices (3min 33s / no limit) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 34s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 34s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 35s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (3min 35s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (3min 36s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (3min 36s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (3min 37s / 30min) [ *] (1 of 2) A start job is running for…-ttyAMA0.device (3min 37s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (3min 38s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (3min 38s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (3min 39s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (3min 39s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 40s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 40s / 30min) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 41s / 30min) [** ] (2 of 2) A start job is running for… all Devices (3min 41s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (3min 42s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (3min 42s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 43s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (3min 43s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (3min 44s / 30min) [ *] (2 of 2) A start job is running for… all Devices (3min 44s / no limit) [ **] (2 of 2) A start job is running for… all Devices (3min 45s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (3min 45s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 46s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 46s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 47s / 30min) [** ] (2 of 2) A start job is running for… all Devices (3min 47s / no limit) [* ] (2 of 2) A start job is running for… all Devices (3min 48s / no limit) [** ] (2 of 2) A start job is running for… all Devices (3min 48s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 49s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 49s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 50s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (3min 50s / no limit) [ **] (2 of 2) A start job is running for… all Devices (3min 51s / no limit) [ *] (2 of 2) A start job is running for… all Devices (3min 51s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (3min 52s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (3min 52s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 53s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (3min 53s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (3min 54s / no limit) [** ] (2 of 2) A start job is running for… all Devices (3min 54s / no limit) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 55s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 55s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (3min 56s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (3min 56s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (3min 57s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (3min 57s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (3min 58s / 30min) [ *] (1 of 2) A start job is running for…-ttyAMA0.device (3min 58s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (3min 59s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (3min 59s / no limit) [ *** ] (2 of 2) A start job is running for…plug all Devices (4min / no limit) [ *** ] (2 of 2) A start job is running for…plug all Devices (4min / no limit) [*** ] (1 of 2) A start job is running for…v-ttyAMA0.device (4min 1s / 30min) [** ] (1 of 2) A start job is running for…v-ttyAMA0.device (4min 1s / 30min) [* ] (1 of 2) A start job is running for…v-ttyAMA0.device (4min 2s / 30min) [** ] (2 of 2) A start job is running for…g all Devices (4min 2s / no limit) [*** ] (2 of 2) A start job is running for…g all Devices (4min 3s / no limit) [ *** ] (2 of 2) A start job is running for…g all Devices (4min 3s / no limit) [ *** ] (1 of 2) A start job is running for…v-ttyAMA0.device (4min 4s / 30min) [ ***] (1 of 2) A start job is running for…v-ttyAMA0.device (4min 4s / 30min) [ **] (1 of 2) A start job is running for…v-ttyAMA0.device (4min 5s / 30min) [ *] (2 of 2) A start job is running for…g all Devices (4min 5s / no limit) [ **] (2 of 2) A start job is running for…g all Devices (4min 6s / no limit) [ ***] (2 of 2) A start job is running for…g all Devices (4min 6s / no limit) [ *** ] (1 of 2) A start job is running for…v-ttyAMA0.device (4min 7s / 30min) [ *** ] (1 of 2) A start job is running for…v-ttyAMA0.device (4min 7s / 30min) [*** ] (1 of 2) A start job is running for…v-ttyAMA0.device (4min 8s / 30min) [** ] (2 of 2) A start job is running for…g all Devices (4min 8s / no limit) [* ] (2 of 2) A start job is running for…g all Devices (4min 9s / no limit) [** ] (2 of 2) A start job is running for… all Devices (4min 10s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (4min 10s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (4min 11s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (4min 11s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (4min 12s / no limit) [ **] (2 of 2) A start job is running for… all Devices (4min 12s / no limit) [ *] (2 of 2) A start job is running for… all Devices (4min 13s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (4min 13s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (4min 14s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (4min 14s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (4min 15s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (4min 15s / no limit) [** ] (2 of 2) A start job is running for… all Devices (4min 16s / no limit) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (4min 16s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (4min 17s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (4min 17s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (4min 18s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (4min 18s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (4min 19s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (4min 19s / 30min) [ *] (1 of 2) A start job is running for…-ttyAMA0.device (4min 20s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (4min 20s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (4min 21s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (4min 21s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (4min 22s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (4min 22s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (4min 23s / 30min) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (4min 23s / 30min) [** ] (2 of 2) A start job is running for… all Devices (4min 24s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (4min 24s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (4min 25s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (4min 26s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (4min 26s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (4min 27s / 30min)[ 1781.225068] systemd[1]: systemd-journald.service: Main process exited, code=killed, status=6/ABRT [ 1782.714259] systemd[1]: systemd-journald.service: Unit entered failed state. [ 1782.821301] systemd[1]: systemd-journald.service: Failed with result 'watchdog'. [ *] (2 of 2) A start job is running for… all Devices (4min 29s / no limit)[ 1783.808915] systemd[1]: systemd-journald.service: Service has no hold-off time, scheduling restart. [ 1786.180637] systemd[1]: Stopped Flush Journal to Persistent Storage. [ OK ] Stopped Flush Journal to Persistent Storage. [ 1786.320869] systemd[1]: Stopping Flush Journal to Persistent Storage... Stopping Flush Journal to Persistent Storage... [ 1786.424619] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 1788.747360] systemd[1]: Starting Journal Service... Starting Journal Service... [ **] (1 of 3) A start job is running for…-ttyAMA0.device (4min 38s / 30min) [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (4min 38s / 30min) [ *** ] (2 of 3) A start job is running for Journal Service (9s / 30min 3s) [ *** ] (2 of 3) A start job is running for Journal Service (9s / 30min 3s) [*** ] (2 of 3) A start job is running for Journal Service (10s / 30min 3s) [** ] (3 of 3) A start job is running for… all Devices (4min 40s / no limit) [* ] (3 of 3) A start job is running for… all Devices (4min 41s / no limit) [** ] (3 of 3) A start job is running for… all Devices (4min 41s / no limit) [*** ] (1 of 3) A start job is running for…-ttyAMA0.device (4min 42s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (4min 42s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (4min 43s / 30min) [ ***] (2 of 3) A start job is running for Journal Service (13s / 30min 3s) [ **] (2 of 3) A start job is running for Journal Service (14s / 30min 3s) [ *] (2 of 3) A start job is running for Journal Service (14s / 30min 3s) [ **] (3 of 3) A start job is running for… all Devices (4min 45s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (4min 45s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (4min 46s / no limit) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (4min 46s / 30min) [*** ] (1 of 3) A start job is running for…-ttyAMA0.device (4min 47s / 30min) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (4min 47s / 30min) [* ] (2 of 3) A start job is running for Journal Service (18s / 30min 3s) [** ] (2 of 3) A start job is running for Journal Service (18s / 30min 3s) [*** ] (2 of 3) A start job is running for Journal Service (19s / 30min 3s) [ *** ] (3 of 3) A start job is running for… all Devices (4min 49s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (4min 50s / no limit)[ 1804.251789] systemd-journald[1317]: File /run/log/journal/ee22564d41614722a231f845c5bb3c72/system.journal corrupted or uncleanly shut down, renaming and replacing. [ ***] (3 of 3) A start job is running for… all Devices (4min 50s / no limit) [ **] (1 of 3) A start job is running for…-ttyAMA0.device (4min 51s / 30min) [ *] (1 of 3) A start job is running for…-ttyAMA0.device (4min 51s / 30min) [ **] (1 of 3) A start job is running for…-ttyAMA0.device (4min 52s / 30min) [ ***] (2 of 3) A start job is running for Journal Service (22s / 30min 3s) [ *** ] (2 of 3) A start job is running for Journal Service (23s / 30min 3s) [ *** ] (2 of 3) A start job is running for Journal Service (23s / 30min 3s) [*** ] (3 of 3) A start job is running for… all Devices (4min 54s / no limit) [** ] (3 of 3) A start job is running for… all Devices (4min 54s / no limit) [* ] (3 of 3) A start job is running for… all Devices (4min 55s / no limit) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (4min 55s / 30min) [*** ] (1 of 3) A start job is running for…-ttyAMA0.device (4min 56s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (4min 56s / 30min) [ *** ] (2 of 3) A start job is running for Journal Service (27s / 30min 3s) [ ***] (2 of 3) A start job is running for Journal Service (27s / 30min 3s) [ **] (2 of 3) A start job is running for Journal Service (28s / 30min 3s) [ *] (3 of 3) A start job is running for… all Devices (4min 58s / no limit) [ **] (3 of 3) A start job is running for… all Devices (4min 59s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (4min 59s / no limit) [ *** ] (1 of 3) A start job is running for dev-ttyAMA0.device (5min / 30min) [ *** ] (1 of 3) A start job is running for dev-ttyAMA0.device (5min / 30min) [*** ] (1 of 3) A start job is running for…v-ttyAMA0.device (5min 1s / 30min) [** ] (2 of 3) A start job is running for Journal Service (31s / 30min 3s) [* ] (2 of 3) A start job is running for Journal Service (32s / 30min 3s) [** ] (2 of 3) A start job is running for Journal Service (32s / 30min 3s) [*** ] (3 of 3) A start job is running for…g all Devices (5min 3s / no limit) [ *** ] (3 of 3) A start job is running for…g all Devices (5min 3s / no limit) [ *** ] (3 of 3) A start job is running for…g all Devices (5min 4s / no limit) [ ***] (1 of 3) A start job is running for…v-ttyAMA0.device (5min 4s / 30min) [ **] (1 of 3) A start job is running for…v-ttyAMA0.device (5min 5s / 30min) [ *] (1 of 3) A start job is running for…v-ttyAMA0.device (5min 5s / 30min) [ **] (2 of 3) A start job is running for Journal Service (36s / 30min 3s) [ ***] (2 of 3) A start job is running for Journal Service (36s / 30min 3s) [ *** ] (2 of 3) A start job is running for Journal Service (37s / 30min 3s) [ *** ] (3 of 3) A start job is running for…g all Devices (5min 7s / no limit) [*** ] (3 of 3) A start job is running for… all Devices (5min 11s / no limit) [** ] (3 of 3) A start job is running for… all Devices (5min 11s / no limit) [* ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 12s / 30min) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 12s / 30min) [*** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 13s / 30min) [ *** ] (2 of 3) A start job is running for Journal Service (43s / 30min 3s) [ *** ] (2 of 3) A start job is running for Journal Service (44s / 30min 3s) [ ***] (2 of 3) A start job is running for Journal Service (44s / 30min 3s) [ **] (3 of 3) A start job is running for… all Devices (5min 15s / no limit) [ *] (3 of 3) A start job is running for… all Devices (5min 15s / no limit) [ **] (3 of 3) A start job is running for… all Devices (5min 16s / no limit) [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (5min 16s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 17s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 17s / 30min) [*** ] (2 of 3) A start job is running for Journal Service (48s / 30min 3s) [** ] (2 of 3) A start job is running for Journal Service (48s / 30min 3s) [* ] (2 of 3) A start job is running for Journal Service (49s / 30min 3s) [** ] (3 of 3) A start job is running for… all Devices (5min 19s / no limit) [*** ] (3 of 3) A start job is running for… all Devices (5min 20s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (5min 20s / no limit) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 21s / 30min) [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (5min 21s / 30min) [ **] (1 of 3) A start job is running for…-ttyAMA0.device (5min 22s / 30min) [ *] (2 of 3) A start job is running for Journal Service (52s / 30min 3s) [ **] (2 of 3) A start job is running for Journal Service (53s / 30min 3s) [ ***] (2 of 3) A start job is running for Journal Service (53s / 30min 3s) [ *** ] (3 of 3) A start job is running for… all Devices (5min 24s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (5min 24s / no limit) [*** ] (3 of 3) A start job is running for… all Devices (5min 25s / no limit) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 25s / 30min) [* ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 26s / 30min) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 26s / 30min) [*** ] (2 of 3) A start job is running for Journal Service (57s / 30min 3s) [ *** ] (2 of 3) A start job is running for Journal Service (57s / 30min 3s) [ *** ] (2 of 3) A start job is running for Journal Service (58s / 30min 3s) [ ***] (3 of 3) A start job is running for… all Devices (5min 29s / no limit) [ **] (3 of 3) A start job is running for… all Devices (5min 29s / no limit) [ *] (3 of 3) A start job is running for… all Devices (5min 30s / no limit) [ **] (1 of 3) A start job is running for…-ttyAMA0.device (5min 30s / 30min) [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (5min 31s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 31s / 30min) [ *** ] (2 of 3) A start job is running for…urnal Service (1min 2s / 30min 3s) [*** ] (2 of 3) A start job is running for…urnal Service (1min 2s / 30min 3s) [** ] (2 of 3) A start job is running for…urnal Service (1min 3s / 30min 3s) [* ] (3 of 3) A start job is running for… all Devices (5min 33s / no limit) [** ] (3 of 3) A start job is running for… all Devices (5min 34s / no limit) [*** ] (3 of 3) A start job is running for… all Devices (5min 34s / no limit) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 35s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 35s / 30min) [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (5min 36s / 30min) [ **] (2 of 3) A start job is running for…urnal Service (1min 6s / 30min 3s) [ *] (2 of 3) A start job is running for…urnal Service (1min 7s / 30min 3s) [ **] (2 of 3) A start job is running for…urnal Service (1min 7s / 30min 3s) [ ***] (3 of 3) A start job is running for… all Devices (5min 38s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (5min 38s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (5min 39s / no limit) [*** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 39s / 30min) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 40s / 30min) [* ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 40s / 30min) [** ] (2 of 3) A start job is running for…rnal Service (1min 11s / 30min 3s) [*** ] (2 of 3) A start job is running for…rnal Service (1min 11s / 30min 3s) [ *** ] (2 of 3) A start job is running for…rnal Service (1min 12s / 30min 3s) [ *** ] (3 of 3) A start job is running for… all Devices (5min 42s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (5min 43s / no limit) [ **] (3 of 3) A start job is running for… all Devices (5min 43s / no limit) [ *] (1 of 3) A start job is running for…-ttyAMA0.device (5min 44s / 30min) [ **] (1 of 3) A start job is running for…-ttyAMA0.device (5min 44s / 30min) [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (5min 45s / 30min) [ *** ] (2 of 3) A start job is running for…rnal Service (1min 15s / 30min 3s) [ *** ] (2 of 3) A start job is running for…rnal Service (1min 16s / 30min 3s) [*** ] (2 of 3) A start job is running for…rnal Service (1min 16s / 30min 3s) [** ] (3 of 3) A start job is running for… all Devices (5min 47s / no limit) [* ] (3 of 3) A start job is running for… all Devices (5min 47s / no limit) [** ] (3 of 3) A start job is running for… all Devices (5min 48s / no limit) [*** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 48s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 49s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 49s / 30min) [ ***] (2 of 3) A start job is running for…rnal Service (1min 20s / 30min 3s) [ **] (2 of 3) A start job is running for…rnal Service (1min 20s / 30min 3s) [ *] (2 of 3) A start job is running for…rnal Service (1min 21s / 30min 3s) [ **] (3 of 3) A start job is running for… all Devices (5min 52s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (5min 52s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (5min 53s / no limit) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 53s / 30min) [*** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 54s / 30min) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (5min 54s / 30min) [* ] (2 of 3) A start job is running for…rnal Service (1min 25s / 30min 3s) [** ] (2 of 3) A start job is running for…rnal Service (1min 25s / 30min 3s) [*** ] (2 of 3) A start job is running for…rnal Service (1min 26s / 30min 3s) [ *** ] (3 of 3) A start job is running for… all Devices (5min 56s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (5min 57s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (5min 57s / no limit) [ **] (1 of 3) A start job is running for…-ttyAMA0.device (5min 58s / 30min) [ *] (1 of 3) A start job is running for…-ttyAMA0.device (5min 58s / 30min) [ **] (1 of 3) A start job is running for…-ttyAMA0.device (5min 59s / 30min)[ 1875.987706] systemd[1]: Found device /dev/ttyAMA0. [ OK ] Found device /dev/ttyAMA0. [ 1879.396330] virtio_net virtio0 enp0s1: renamed from eth0 [ ***] (2 of 2) A start job is running for…g all Devices (6min 7s / no limit) [ *** ] (2 of 2) A start job is running for…g all Devices (6min 8s / no limit) [ *** ] (2 of 2) A start job is running for…g all Devices (6min 8s / no limit) [*** ] (1 of 2) A start job is running for…rnal Service (1min 39s / 30min 3s) [** ] (1 of 2) A start job is running for…rnal Service (1min 40s / 30min 3s) [* ] (1 of 2) A start job is running for…rnal Service (1min 40s / 30min 3s) [** ] (2 of 2) A start job is running for… all Devices (6min 11s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (6min 11s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (6min 12s / no limit)[ 1886.386266] systemd-journald[1317]: /dev/kmsg buffer overrun, some messages lost. [ *** ] (1 of 2) A start job is running for…rnal Service (1min 42s / 30min 3s) [ ***] (1 of 2) A start job is running for…rnal Service (1min 43s / 30min 3s) [ **] (1 of 2) A start job is running for…rnal Service (1min 43s / 30min 3s)[ 1887.702191] systemd-journald[1317]: /dev/kmsg buffer overrun, some messages lost. [ *] (2 of 2) A start job is running for… all Devices (6min 14s / no limit) [ **] (2 of 2) A start job is running for… all Devices (6min 14s / no limit)[ 1888.980673] systemd-journald[1317]: /dev/kmsg buffer overrun, some messages lost. [ ***] (2 of 2) A start job is running for… all Devices (6min 15s / no limit) [ *** ] (1 of 2) A start job is running for…rnal Service (1min 45s / 30min 3s) [ *** ] (1 of 2) A start job is running for…rnal Service (1min 46s / 30min 3s)[ 1890.384203] systemd-journald[1317]: /dev/kmsg buffer overrun, some messages lost. [*** ] (1 of 2) A start job is running for…rnal Service (1min 46s / 30min 3s) [** ] (2 of 2) A start job is running for… all Devices (6min 17s / no limit)[ 1892.369068] systemd[1]: Started udev Coldplug all Devices. [ OK ] Started udev Coldplug all Devices. [* ] A start job is running for Journal Service (1min 53s / 30min 3s) [** ] A start job is running for Journal Service (1min 54s / 30min 3s) [*** ] A start job is running for Journal Service (1min 54s / 30min 3s) [ *** ] A start job is running for Journal Service (1min 55s / 30min 3s) [ *** ] A start job is running for Journal Service (1min 55s / 30min 3s) [ ***] A start job is running for Journal Service (1min 56s / 30min 3s) [ **] A start job is running for Journal Service (1min 56s / 30min 3s) [ *] A start job is running for Journal Service (1min 57s / 30min 3s) [ **] A start job is running for Journal Service (1min 57s / 30min 3s) [ ***] A start job is running for Journal Service (1min 58s / 30min 3s) [ *** ] A start job is running for Journal Service (1min 58s / 30min 3s) [ *** ] A start job is running for Journal Service (1min 59s / 30min 3s) [*** ] A start job is running for Journal Service (1min 59s / 30min 3s) [** ] A start job is running for Journal Service (2min / 30min 3s) [* ] A start job is running for Journal Service (2min / 30min 3s) [** ] A start job is running for Journal Service (2min 1s / 30min 3s) [*** ] A start job is running for Journal Service (2min 1s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 2s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 2s / 30min 3s) [ ***] A start job is running for Journal Service (2min 3s / 30min 3s) [ **] A start job is running for Journal Service (2min 3s / 30min 3s) [ *] A start job is running for Journal Service (2min 4s / 30min 3s) [ **] A start job is running for Journal Service (2min 8s / 30min 3s) [ ***] A start job is running for Journal Service (2min 8s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 9s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 9s / 30min 3s) [*** ] A start job is running for Journal Service (2min 10s / 30min 3s) [** ] A start job is running for Journal Service (2min 10s / 30min 3s) [* ] A start job is running for Journal Service (2min 11s / 30min 3s) [** ] A start job is running for Journal Service (2min 11s / 30min 3s) [*** ] A start job is running for Journal Service (2min 12s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 12s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 13s / 30min 3s) [ ***] A start job is running for Journal Service (2min 13s / 30min 3s) [ **] A start job is running for Journal Service (2min 14s / 30min 3s) [ *] A start job is running for Journal Service (2min 14s / 30min 3s) [ **] A start job is running for Journal Service (2min 15s / 30min 3s) [ ***] A start job is running for Journal Service (2min 15s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 16s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 16s / 30min 3s) [*** ] A start job is running for Journal Service (2min 17s / 30min 3s) [** ] A start job is running for Journal Service (2min 17s / 30min 3s) [* ] A start job is running for Journal Service (2min 18s / 30min 3s) [** ] A start job is running for Journal Service (2min 18s / 30min 3s) [*** ] A start job is running for Journal Service (2min 25s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 32s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 33s / 30min 3s) [ ***] A start job is running for Journal Service (2min 33s / 30min 3s) [ **] A start job is running for Journal Service (2min 34s / 30min 3s) [ *] A start job is running for Journal Service (2min 34s / 30min 3s) [ **] A start job is running for Journal Service (2min 35s / 30min 3s) [ ***] A start job is running for Journal Service (2min 35s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 36s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 36s / 30min 3s) [*** ] A start job is running for Journal Service (2min 37s / 30min 3s) [** ] A start job is running for Journal Service (2min 37s / 30min 3s) [* ] A start job is running for Journal Service (2min 38s / 30min 3s) [** ] A start job is running for Journal Service (2min 38s / 30min 3s) [*** ] A start job is running for Journal Service (2min 39s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 39s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 40s / 30min 3s) [ ***] A start job is running for Journal Service (2min 40s / 30min 3s) [ **] A start job is running for Journal Service (2min 41s / 30min 3s) [ *] A start job is running for Journal Service (2min 41s / 30min 3s) [ **] A start job is running for Journal Service (2min 42s / 30min 3s) [ ***] A start job is running for Journal Service (2min 42s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 43s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 43s / 30min 3s) [*** ] A start job is running for Journal Service (2min 44s / 30min 3s) [** ] A start job is running for Journal Service (2min 44s / 30min 3s) [* ] A start job is running for Journal Service (2min 45s / 30min 3s) [** ] A start job is running for Journal Service (2min 45s / 30min 3s) [*** ] A start job is running for Journal Service (2min 46s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 46s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 47s / 30min 3s) [ ***] A start job is running for Journal Service (2min 47s / 30min 3s) [ **] A start job is running for Journal Service (2min 48s / 30min 3s) [ *] A start job is running for Journal Service (2min 48s / 30min 3s) [ **] A start job is running for Journal Service (2min 49s / 30min 3s) [ ***] A start job is running for Journal Service (2min 49s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 50s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 50s / 30min 3s) [*** ] A start job is running for Journal Service (2min 51s / 30min 3s) [** ] A start job is running for Journal Service (2min 51s / 30min 3s) [* ] A start job is running for Journal Service (2min 52s / 30min 3s) [** ] A start job is running for Journal Service (2min 52s / 30min 3s) [*** ] A start job is running for Journal Service (2min 53s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 53s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 54s / 30min 3s) [ ***] A start job is running for Journal Service (2min 54s / 30min 3s) [ **] A start job is running for Journal Service (2min 55s / 30min 3s) [ *] A start job is running for Journal Service (2min 55s / 30min 3s) [ **] A start job is running for Journal Service (2min 56s / 30min 3s) [ ***] A start job is running for Journal Service (2min 56s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 57s / 30min 3s) [ *** ] A start job is running for Journal Service (2min 57s / 30min 3s) [*** ] A start job is running for Journal Service (2min 58s / 30min 3s) [** ] A start job is running for Journal Service (2min 58s / 30min 3s) [* ] A start job is running for Journal Service (2min 59s / 30min 3s) [** ] A start job is running for Journal Service (2min 59s / 30min 3s) [*** ] A start job is running for Journal Service (3min / 30min 3s) [ *** ] A start job is running for Journal Service (3min / 30min 3s) [ *** ] A start job is running for Journal Service (3min 1s / 30min 3s) [ ***] A start job is running for Journal Service (3min 1s / 30min 3s) [ **] A start job is running for Journal Service (3min 2s / 30min 3s) [ *] A start job is running for Journal Service (3min 2s / 30min 3s) [ **] A start job is running for Journal Service (3min 3s / 30min 3s) [ ***] A start job is running for Journal Service (3min 3s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 4s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 4s / 30min 3s) [*** ] A start job is running for Journal Service (3min 5s / 30min 3s) [** ] A start job is running for Journal Service (3min 5s / 30min 3s) [* ] A start job is running for Journal Service (3min 6s / 30min 3s) [** ] A start job is running for Journal Service (3min 6s / 30min 3s) [*** ] A start job is running for Journal Service (3min 7s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 7s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 8s / 30min 3s) [ ***] A start job is running for Journal Service (3min 8s / 30min 3s) [ **] A start job is running for Journal Service (3min 9s / 30min 3s) [ *] A start job is running for Journal Service (3min 9s / 30min 3s) [ **] A start job is running for Journal Service (3min 10s / 30min 3s) [ ***] A start job is running for Journal Service (3min 10s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 11s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 11s / 30min 3s) [*** ] A start job is running for Journal Service (3min 12s / 30min 3s) [** ] A start job is running for Journal Service (3min 12s / 30min 3s) [* ] A start job is running for Journal Service (3min 13s / 30min 3s) [** ] A start job is running for Journal Service (3min 13s / 30min 3s) [*** ] A start job is running for Journal Service (3min 14s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 14s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 15s / 30min 3s) [ ***] A start job is running for Journal Service (3min 15s / 30min 3s) [ **] A start job is running for Journal Service (3min 16s / 30min 3s) [ *] A start job is running for Journal Service (3min 16s / 30min 3s) [ **] A start job is running for Journal Service (3min 17s / 30min 3s) [ ***] A start job is running for Journal Service (3min 17s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 18s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 18s / 30min 3s) [*** ] A start job is running for Journal Service (3min 19s / 30min 3s) [** ] A start job is running for Journal Service (3min 19s / 30min 3s) [* ] A start job is running for Journal Service (3min 20s / 30min 3s) [** ] A start job is running for Journal Service (3min 20s / 30min 3s) [*** ] A start job is running for Journal Service (3min 21s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 21s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 22s / 30min 3s) [ ***] A start job is running for Journal Service (3min 22s / 30min 3s) [ **] A start job is running for Journal Service (3min 23s / 30min 3s) [ *] A start job is running for Journal Service (3min 23s / 30min 3s) [ **] A start job is running for Journal Service (3min 24s / 30min 3s) [ ***] A start job is running for Journal Service (3min 24s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 25s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 25s / 30min 3s) [*** ] A start job is running for Journal Service (3min 26s / 30min 3s) [** ] A start job is running for Journal Service (3min 26s / 30min 3s) [* ] A start job is running for Journal Service (3min 27s / 30min 3s) [** ] A start job is running for Journal Service (3min 27s / 30min 3s) [*** ] A start job is running for Journal Service (3min 28s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 28s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 29s / 30min 3s) [ ***] A start job is running for Journal Service (3min 29s / 30min 3s) [ **] A start job is running for Journal Service (3min 30s / 30min 3s) [ *] A start job is running for Journal Service (3min 30s / 30min 3s) [ **] A start job is running for Journal Service (3min 31s / 30min 3s) [ ***] A start job is running for Journal Service (3min 31s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 32s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 32s / 30min 3s) [*** ] A start job is running for Journal Service (3min 33s / 30min 3s) [** ] A start job is running for Journal Service (3min 33s / 30min 3s) [* ] A start job is running for Journal Service (3min 34s / 30min 3s) [** ] A start job is running for Journal Service (3min 34s / 30min 3s) [*** ] A start job is running for Journal Service (3min 35s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 35s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 36s / 30min 3s) [ ***] A start job is running for Journal Service (3min 36s / 30min 3s) [ **] A start job is running for Journal Service (3min 37s / 30min 3s) [ *] A start job is running for Journal Service (3min 37s / 30min 3s) [ **] A start job is running for Journal Service (3min 38s / 30min 3s) [ ***] A start job is running for Journal Service (3min 38s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 39s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 39s / 30min 3s) [*** ] A start job is running for Journal Service (3min 40s / 30min 3s) [** ] A start job is running for Journal Service (3min 40s / 30min 3s) [* ] A start job is running for Journal Service (3min 41s / 30min 3s) [** ] A start job is running for Journal Service (3min 41s / 30min 3s) [*** ] A start job is running for Journal Service (3min 42s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 42s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 43s / 30min 3s) [ ***] A start job is running for Journal Service (3min 43s / 30min 3s) [ **] A start job is running for Journal Service (3min 44s / 30min 3s) [ *] A start job is running for Journal Service (3min 44s / 30min 3s) [ **] A start job is running for Journal Service (3min 45s / 30min 3s) [ ***] A start job is running for Journal Service (3min 45s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 46s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 46s / 30min 3s) [*** ] A start job is running for Journal Service (3min 47s / 30min 3s) [** ] A start job is running for Journal Service (3min 47s / 30min 3s) [* ] A start job is running for Journal Service (3min 48s / 30min 3s) [** ] A start job is running for Journal Service (3min 48s / 30min 3s) [*** ] A start job is running for Journal Service (3min 49s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 49s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 50s / 30min 3s) [ ***] A start job is running for Journal Service (3min 50s / 30min 3s) [ **] A start job is running for Journal Service (3min 51s / 30min 3s) [ *] A start job is running for Journal Service (3min 51s / 30min 3s) [ **] A start job is running for Journal Service (3min 52s / 30min 3s) [ ***] A start job is running for Journal Service (3min 52s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 53s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 53s / 30min 3s) [*** ] A start job is running for Journal Service (3min 54s / 30min 3s) [** ] A start job is running for Journal Service (3min 54s / 30min 3s) [* ] A start job is running for Journal Service (3min 55s / 30min 3s) [** ] A start job is running for Journal Service (3min 55s / 30min 3s) [*** ] A start job is running for Journal Service (3min 56s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 56s / 30min 3s) [ *** ] A start job is running for Journal Service (3min 57s / 30min 3s) [ ***] A start job is running for Journal Service (3min 57s / 30min 3s) [ **] A start job is running for Journal Service (3min 58s / 30min 3s) [ *] A start job is running for Journal Service (3min 58s / 30min 3s) [ **] A start job is running for Journal Service (3min 59s / 30min 3s) [ ***] A start job is running for Journal Service (3min 59s / 30min 3s) [ *** ] A start job is running for Journal Service (4min / 30min 3s) [ *** ] A start job is running for Journal Service (4min 1s / 30min 3s) [*** ] A start job is running for Journal Service (4min 1s / 30min 3s) [** ] A start job is running for Journal Service (4min 2s / 30min 3s) [* ] A start job is running for Journal Service (4min 2s / 30min 3s) [** ] A start job is running for Journal Service (4min 3s / 30min 3s) [*** ] A start job is running for Journal Service (4min 3s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 4s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 4s / 30min 3s) [ ***] A start job is running for Journal Service (4min 5s / 30min 3s) [ **] A start job is running for Journal Service (4min 5s / 30min 3s) [ *] A start job is running for Journal Service (4min 6s / 30min 3s) [ **] A start job is running for Journal Service (4min 6s / 30min 3s) [ ***] A start job is running for Journal Service (4min 7s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 7s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 8s / 30min 3s) [*** ] A start job is running for Journal Service (4min 8s / 30min 3s) [** ] A start job is running for Journal Service (4min 9s / 30min 3s) [* ] A start job is running for Journal Service (4min 9s / 30min 3s) [** ] A start job is running for Journal Service (4min 10s / 30min 3s) [*** ] A start job is running for Journal Service (4min 10s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 11s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 11s / 30min 3s) [ ***] A start job is running for Journal Service (4min 12s / 30min 3s) [ **] A start job is running for Journal Service (4min 12s / 30min 3s) [ *] A start job is running for Journal Service (4min 13s / 30min 3s) [ **] A start job is running for Journal Service (4min 13s / 30min 3s) [ ***] A start job is running for Journal Service (4min 14s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 14s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 15s / 30min 3s) [*** ] A start job is running for Journal Service (4min 15s / 30min 3s) [** ] A start job is running for Journal Service (4min 16s / 30min 3s) [* ] A start job is running for Journal Service (4min 16s / 30min 3s) [** ] A start job is running for Journal Service (4min 17s / 30min 3s) [*** ] A start job is running for Journal Service (4min 17s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 18s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 18s / 30min 3s) [ ***] A start job is running for Journal Service (4min 19s / 30min 3s) [ **] A start job is running for Journal Service (4min 19s / 30min 3s) [ *] A start job is running for Journal Service (4min 20s / 30min 3s) [ **] A start job is running for Journal Service (4min 20s / 30min 3s) [ ***] A start job is running for Journal Service (4min 21s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 21s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 22s / 30min 3s) [*** ] A start job is running for Journal Service (4min 22s / 30min 3s) [** ] A start job is running for Journal Service (4min 23s / 30min 3s) [* ] A start job is running for Journal Service (4min 23s / 30min 3s) [** ] A start job is running for Journal Service (4min 24s / 30min 3s) [*** ] A start job is running for Journal Service (4min 24s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 25s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 25s / 30min 3s) [ ***] A start job is running for Journal Service (4min 26s / 30min 3s) [ **] A start job is running for Journal Service (4min 26s / 30min 3s) [ *] A start job is running for Journal Service (4min 27s / 30min 3s) [ **] A start job is running for Journal Service (4min 27s / 30min 3s) [ ***] A start job is running for Journal Service (4min 28s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 28s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 29s / 30min 3s) [*** ] A start job is running for Journal Service (4min 29s / 30min 3s) [** ] A start job is running for Journal Service (4min 30s / 30min 3s) [* ] A start job is running for Journal Service (4min 30s / 30min 3s) [** ] A start job is running for Journal Service (4min 31s / 30min 3s) [*** ] A start job is running for Journal Service (4min 31s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 32s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 32s / 30min 3s) [ ***] A start job is running for Journal Service (4min 33s / 30min 3s) [ **] A start job is running for Journal Service (4min 33s / 30min 3s) [ *] A start job is running for Journal Service (4min 34s / 30min 3s) [ **] A start job is running for Journal Service (4min 34s / 30min 3s) [ ***] A start job is running for Journal Service (4min 35s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 35s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 36s / 30min 3s) [*** ] A start job is running for Journal Service (4min 36s / 30min 3s) [** ] A start job is running for Journal Service (4min 37s / 30min 3s) [* ] A start job is running for Journal Service (4min 37s / 30min 3s) [** ] A start job is running for Journal Service (4min 38s / 30min 3s) [*** ] A start job is running for Journal Service (4min 38s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 39s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 39s / 30min 3s) [ ***] A start job is running for Journal Service (4min 40s / 30min 3s) [ **] A start job is running for Journal Service (4min 40s / 30min 3s) [ *] A start job is running for Journal Service (4min 41s / 30min 3s) [ **] A start job is running for Journal Service (4min 41s / 30min 3s) [ ***] A start job is running for Journal Service (4min 42s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 42s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 43s / 30min 3s) [*** ] A start job is running for Journal Service (4min 43s / 30min 3s) [** ] A start job is running for Journal Service (4min 44s / 30min 3s) [* ] A start job is running for Journal Service (4min 44s / 30min 3s) [** ] A start job is running for Journal Service (4min 45s / 30min 3s) [*** ] A start job is running for Journal Service (4min 45s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 46s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 46s / 30min 3s) [ ***] A start job is running for Journal Service (4min 47s / 30min 3s) [ **] A start job is running for Journal Service (4min 47s / 30min 3s) [ *] A start job is running for Journal Service (4min 48s / 30min 3s) [ **] A start job is running for Journal Service (4min 48s / 30min 3s) [ ***] A start job is running for Journal Service (4min 49s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 49s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 50s / 30min 3s) [*** ] A start job is running for Journal Service (4min 50s / 30min 3s) [** ] A start job is running for Journal Service (4min 51s / 30min 3s) [* ] A start job is running for Journal Service (4min 51s / 30min 3s) [** ] A start job is running for Journal Service (4min 52s / 30min 3s) [*** ] A start job is running for Journal Service (4min 52s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 53s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 53s / 30min 3s) [ ***] A start job is running for Journal Service (4min 54s / 30min 3s) [ **] A start job is running for Journal Service (4min 54s / 30min 3s) [ *] A start job is running for Journal Service (4min 55s / 30min 3s) [ **] A start job is running for Journal Service (4min 55s / 30min 3s) [ ***] A start job is running for Journal Service (4min 56s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 56s / 30min 3s) [ *** ] A start job is running for Journal Service (4min 57s / 30min 3s) [*** ] A start job is running for Journal Service (4min 57s / 30min 3s) [** ] A start job is running for Journal Service (4min 58s / 30min 3s) [* ] A start job is running for Journal Service (4min 58s / 30min 3s) [** ] A start job is running for Journal Service (4min 59s / 30min 3s) [*** ] A start job is running for Journal Service (4min 59s / 30min 3s) [ *** ] A start job is running for Journal Service (5min / 30min 3s) [ *** ] A start job is running for Journal Service (5min / 30min 3s) [ ***] A start job is running for Journal Service (5min 1s / 30min 3s) [ **] A start job is running for Journal Service (5min 1s / 30min 3s) [ *] A start job is running for Journal Service (5min 2s / 30min 3s) [ **] A start job is running for Journal Service (5min 2s / 30min 3s) [ ***] A start job is running for Journal Service (5min 3s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 3s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 4s / 30min 3s) [*** ] A start job is running for Journal Service (5min 4s / 30min 3s) [** ] A start job is running for Journal Service (5min 5s / 30min 3s) [* ] A start job is running for Journal Service (5min 5s / 30min 3s) [** ] A start job is running for Journal Service (5min 6s / 30min 3s) [*** ] A start job is running for Journal Service (5min 6s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 7s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 7s / 30min 3s) [ ***] A start job is running for Journal Service (5min 8s / 30min 3s) [ **] A start job is running for Journal Service (5min 8s / 30min 3s) [ *] A start job is running for Journal Service (5min 9s / 30min 3s) [ **] A start job is running for Journal Service (5min 9s / 30min 3s) [ ***] A start job is running for Journal Service (5min 10s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 10s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 11s / 30min 3s) [*** ] A start job is running for Journal Service (5min 11s / 30min 3s) [** ] A start job is running for Journal Service (5min 12s / 30min 3s) [* ] A start job is running for Journal Service (5min 12s / 30min 3s) [** ] A start job is running for Journal Service (5min 13s / 30min 3s) [*** ] A start job is running for Journal Service (5min 13s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 14s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 14s / 30min 3s) [ ***] A start job is running for Journal Service (5min 15s / 30min 3s) [ **] A start job is running for Journal Service (5min 15s / 30min 3s) [ *] A start job is running for Journal Service (5min 16s / 30min 3s) [ **] A start job is running for Journal Service (5min 16s / 30min 3s) [ ***] A start job is running for Journal Service (5min 17s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 17s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 18s / 30min 3s) [*** ] A start job is running for Journal Service (5min 18s / 30min 3s) [** ] A start job is running for Journal Service (5min 19s / 30min 3s) [* ] A start job is running for Journal Service (5min 19s / 30min 3s) [** ] A start job is running for Journal Service (5min 20s / 30min 3s) [*** ] A start job is running for Journal Service (5min 20s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 21s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 21s / 30min 3s) [ ***] A start job is running for Journal Service (5min 22s / 30min 3s) [ **] A start job is running for Journal Service (5min 22s / 30min 3s) [ *] A start job is running for Journal Service (5min 23s / 30min 3s) [ **] A start job is running for Journal Service (5min 23s / 30min 3s) [ ***] A start job is running for Journal Service (5min 24s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 24s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 25s / 30min 3s) [*** ] A start job is running for Journal Service (5min 25s / 30min 3s) [** ] A start job is running for Journal Service (5min 26s / 30min 3s) [* ] A start job is running for Journal Service (5min 26s / 30min 3s) [** ] A start job is running for Journal Service (5min 27s / 30min 3s) [*** ] A start job is running for Journal Service (5min 34s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 41s / 30min 3s) [ *** ] A start job is running for Journal Service (5min 48s / 30min 3s) [ ***] A start job is running for Journal Service (5min 52s / 30min 3s) [ **] A start job is running for Journal Service (6min 3s / 30min 3s) [ *] A start job is running for Journal Service (6min 8s / 30min 3s) [ **] A start job is running for Journal Service (6min 19s / 30min 3s) [ ***] A start job is running for Journal Service (6min 30s / 30min 3s) [ *** ] A start job is running for Journal Service (6min 41s / 30min 3s) [ *** ] A start job is running for Journal Service (6min 51s / 30min 3s) [*** ] A start job is running for Journal Service (7min 2s / 30min 3s) [** ] A start job is running for Journal Service (7min 13s / 30min 3s) [* ] A start job is running for Journal Service (7min 24s / 30min 3s) [** ] A start job is running for Journal Service (7min 35s / 30min 3s) [*** ] A start job is running for Journal Service (7min 46s / 30min 3s) [ *** ] A start job is running for Journal Service (7min 57s / 30min 3s) [ *** ] A start job is running for Journal Service (8min 8s / 30min 3s) [ ***] A start job is running for Journal Service (8min 13s / 30min 3s) [ **] A start job is running for Journal Service (8min 24s / 30min 3s) [ *] A start job is running for Journal Service (8min 35s / 30min 3s) [ **] A start job is running for Journal Service (8min 46s / 30min 3s) [ ***] A start job is running for Journal Service (8min 57s / 30min 3s) [ *** ] A start job is running for Journal Service (9min 7s / 30min 3s) [ *** ] A start job is running for Journal Service (9min 18s / 30min 3s) [*** ] A start job is running for Journal Service (9min 29s / 30min 3s) [** ] A start job is running for Journal Service (9min 40s / 30min 3s) [* ] A start job is running for Journal Service (9min 51s / 30min 3s) [** ] A start job is running for Journal Service (10min 1s / 30min 3s) [*** ] A start job is running for Journal Service (10min 9s / 30min 3s) [ *** ] A start job is running for Journal Service (10min 14s / 30min 3s) [ *** ] A start job is running for Journal Service (10min 19s / 30min 3s) [ ***] A start job is running for Journal Service (10min 24s / 30min 3s) [ **] A start job is running for Journal Service (10min 30s / 30min 3s)[ 2416.458176] systemd[1]: Started Journal Service. [ OK ] Started Journal Service.