[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 4.20.0-rc4-next-20181130-00011-gaa3ac9a7b87a-dirty (anders@compilator) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05)) #57 SMP Sat Dec 1 14:59:19 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 0xbebf1600-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/0x6e8 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: 993948K/2097152K available (15108K kernel code, 3784K rwdata, 4860K rodata, 6144K init, 23377K bss, 93620K 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] ODEBUG: selftest passed [ 0.000000] ftrace: allocating 42031 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.000249] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.017098] Console: colour dummy device 80x25 [ 0.022898] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.024037] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.024512] ... MAX_LOCK_DEPTH: 48 [ 0.025037] ... MAX_LOCKDEP_KEYS: 8191 [ 0.025502] ... CLASSHASH_SIZE: 4096 [ 0.025968] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.026435] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.026903] ... CHAINHASH_SIZE: 32768 [ 0.027374] memory used by lock dependency info: 7775 kB [ 0.028281] per task-struct memory footprint: 2688 bytes [ 0.028807] ------------------------ [ 0.029262] | Locking API testsuite: [ 0.029716] ---------------------------------------------------------------------------- [ 0.030281] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.030826] -------------------------------------------------------------------------- [ 0.031689] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.044516] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.058081] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.072872] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.087664] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.104014] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.120687] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.137168] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.149004] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.160156] -------------------------------------------------------------------------- [ 0.160787] recursive read-lock: | ok | | ok | [ 0.164986] recursive read-lock #2: | ok | | ok | [ 0.169139] mixed read-write-lock: | ok | | ok | [ 0.173548] mixed write-read-lock: | ok | | ok | [ 0.177751] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.181828] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.186252] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.190717] -------------------------------------------------------------------------- [ 0.191671] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.197317] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.203612] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.209339] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.214904] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.221184] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.227010] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.232704] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.238799] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.244610] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.250477] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.257195] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.263436] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.270046] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.276214] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.282492] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.289153] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.295203] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.301717] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.307470] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.313355] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.319900] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.326077] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.332789] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.339075] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.345690] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.351893] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.358149] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.364814] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.370930] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.377642] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.383707] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.390637] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.396675] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.402955] hard-irq lock-inversion/123: ok | ok | ok | [ 0.409646] soft-irq lock-inversion/123: ok | ok | ok | [ 0.416414] hard-irq lock-inversion/132: ok | ok | ok | [ 0.422519] soft-irq lock-inversion/132: ok | ok | ok | [ 0.429278] hard-irq lock-inversion/213: ok | ok | ok | [ 0.435499] soft-irq lock-inversion/213: ok | ok | ok | [ 0.442330] hard-irq lock-inversion/231: ok | ok | ok | [ 0.448608] soft-irq lock-inversion/231: ok | ok | ok | [ 0.455569] hard-irq lock-inversion/312: ok | ok | ok | [ 0.461886] soft-irq lock-inversion/312: ok | ok | ok | [ 0.468864] hard-irq lock-inversion/321: ok | ok | ok | [ 0.474946] soft-irq lock-inversion/321: ok | ok | ok | [ 0.481786] hard-irq read-recursion/123: ok | [ 0.484111] soft-irq read-recursion/123: ok | [ 0.487076] hard-irq read-recursion/132: ok | [ 0.489504] soft-irq read-recursion/132: ok | [ 0.492370] hard-irq read-recursion/213: ok | [ 0.494821] soft-irq read-recursion/213: ok | [ 0.497728] hard-irq read-recursion/231: ok | [ 0.500134] soft-irq read-recursion/231: ok | [ 0.503020] hard-irq read-recursion/312: ok | [ 0.505476] soft-irq read-recursion/312: ok | [ 0.508350] hard-irq read-recursion/321: ok | [ 0.510780] soft-irq read-recursion/321: ok | [ 0.513724] -------------------------------------------------------------------------- [ 0.514407] | Wound/wait tests | [ 0.514894] --------------------- [ 0.515399] ww api failures: ok | ok | ok | [ 0.523304] ww contexts mixing: ok | ok | [ 0.527465] finishing ww context: ok | ok | ok | ok | [ 0.534966] locking mismatches: ok | ok | ok | [ 0.541219] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.561677] spinlock nest unlocked: ok | [ 0.564211] ----------------------------------------------------- [ 0.564771] |block | try |context| [ 0.565322] ----------------------------------------------------- [ 0.565871] context: ok | ok | ok | [ 0.572567] try: ok | ok | ok | [ 0.578246] block: ok | ok | ok | [ 0.583925] spinlock: ok | ok | ok | [ 0.590847] ------------------------------------------------------- [ 0.591436] Good, all 261 testcases passed! | [ 0.591948] --------------------------------- [ 0.598499] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.600002] pid_max: default: 32768 minimum: 301 [ 0.615187] LSM: Security Framework initializing [ 0.616162] Yama: becoming mindful. [ 0.619689] LoadPin: ready to pin (currently enforcing) [ 0.621297] SELinux: Initializing. [ 0.632741] *** VALIDATE SELinux *** [ 0.633774] AppArmor: AppArmor disabled by boot time parameter [ 0.687747] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.708286] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.712632] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.714310] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.766660] *** VALIDATE proc *** [ 0.796831] *** VALIDATE cgroup1 *** [ 0.797942] *** VALIDATE cgroup2 *** [ 0.892250] ASID allocator initialised with 32768 entries [ 0.907147] rcu: Hierarchical SRCU implementation. [ 1.027752] EFI services will not be available. [ 1.064482] smp: Bringing up secondary CPUs ... [ 1.065684] smp: Brought up 1 node, 1 CPU [ 1.066311] SMP: Total of 1 processors activated. [ 1.067104] CPU features: detected: 32-bit EL0 Support [ 1.067792] CPU features: detected: CRC32 instructions [ 1.405967] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.407553] CPU: All CPU(s) started at EL1 [ 1.408785] alternatives: patching kernel code [ 1.410639] _warn_unseeded_randomness: 98 callbacks suppressed [ 1.411078] random: get_random_u64 called from compute_layout+0x138/0x1b8 with crng_init=0 [ 1.464832] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 5.543936] node 0 initialised, 248300 pages in 4ms [ 5.555042] pgdatinit0 (15) used greatest stack depth: 13280 bytes left [ 5.569407] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 5.673061] devtmpfs: initialized [ 5.993295] gcov: version magic: 0x4137332a [ 6.026051] Registered cp15_barrier emulation handler [ 6.027064] Registered setend emulation handler [ 6.113503] workqueue: round-robin CPU selection forced, expect performance impact [ 16.294239] DMA-API: preallocated 65536 debug entries [ 16.294852] DMA-API: debugging enabled by kernel config [ 16.296737] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 16.300390] futex hash table entries: 256 (order: 3, 32768 bytes) [ 16.308110] Running postponed tracer tests: [ 16.313561] Testing tracer function: [ 16.355406] ftrace_replace_code: 1 [ 26.242608] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 26.435040] ftrace_replace_code: 1 [ 35.458594] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 35.466287] PASSED [ 35.466810] Testing dynamic ftrace: [ 37.545195] ftrace_replace_code: 1 [ 37.574913] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 37.819374] PASSED [ 37.820148] Testing dynamic ftrace ops #1: [ 46.098819] ftrace_replace_code: 1 [ 46.129369] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 46.136158] (1 0 1 0 0) [ 46.136367] (1 1 2 0 0) [ 46.154050] ftrace_replace_code: 1 [ 59.133955] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 59.136119] (2 1 3 0 964776) [ 59.136385] (2 2 4 0 964791) [ 59.156083] ftrace_replace_code: 1 [ 69.446686] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 69.468330] ftrace_replace_code: 1 [ 69.495290] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 69.501994] ftrace_replace_code: 1 [ 69.529139] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 69.536201] PASSED [ 69.536685] Testing dynamic ftrace ops #2: [ 77.811029] ftrace_replace_code: 1 [ 77.841777] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 77.848430] ftrace_replace_code: 1 [ 77.881497] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 77.909263] ftrace_replace_code: 1 [ 90.200875] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 90.204044] (1 0 1 946354 0) [ 90.204272] (1 1 2 946369 0) [ 90.225705] (2 1 3 1 939) [ 90.227946] (2 2 4 351 1289) [ 90.287193] ftrace_replace_code: 1 [ 99.308172] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 99.312355] PASSED [ 99.312835] Testing ftrace recursion: [ 101.384013] ftrace_replace_code: 1 [ 101.413240] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 101.419924] ftrace_replace_code: 1 [ 101.447173] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 101.451045] PASSED [ 101.451498] Testing ftrace recursion safe: [ 103.520494] ftrace_replace_code: 1 [ 103.549527] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 103.555810] ftrace_replace_code: 1 [ 103.583464] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 103.586804] PASSED [ 103.587820] Testing ftrace regs(no arch support): [ 105.655726] ftrace_replace_code: 1 [ 105.684310] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 105.690780] ftrace_replace_code: 1 [ 105.717885] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 105.721693] PASSED [ 105.722492] Testing tracer nop: PASSED [ 105.725201] Testing tracer irqsoff: [ 105.748306] ftrace_replace_code: 1 [ 115.756201] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 115.783676] ftrace_replace_code: 1 [ 124.820888] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 124.825327] PASSED [ 124.827707] Testing tracer wakeup: [ 124.866933] ftrace_replace_code: 1 [ 134.903675] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 134.934953] ftrace_replace_code: 1 [ 143.979752] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 143.991066] PASSED [ 143.992059] Testing tracer wakeup_rt: [ 144.029958] ftrace_replace_code: 1 [ 154.074224] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 154.103310] ftrace_replace_code: 1 [ 163.149904] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 163.160704] PASSED [ 163.161677] Testing tracer wakeup_dl: [ 163.197064] ftrace_replace_code: 1 [ 173.247433] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 173.276102] ftrace_replace_code: 1 [ 182.325569] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 182.337722] PASSED [ 182.340410] Testing tracer function_graph: [ 182.376549] ftrace_replace_code: 1 [ 192.323160] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 192.689384] ftrace_replace_code: 1 [ 203.481221] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 203.486435] PASSED [ 203.689015] _warn_unseeded_randomness: 7 callbacks suppressed [ 203.689299] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 203.690999] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 203.694348] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 203.721414] prandom: seed boundary self test passed [ 203.763215] prandom: 100 self tests passed [ 203.771640] pinctrl core: initialized pinctrl subsystem [ 203.908065] regulator-dummy: Failed to create debugfs directory [ 203.920049] [ 203.920604] ************************************************************* [ 203.921301] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 203.921986] ** ** [ 203.923196] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 203.923858] ** ** [ 203.924513] ** This means that this kernel is built to expose internal ** [ 203.925203] ** IOMMU data structures, which may compromise security on ** [ 203.925861] ** your system. ** [ 203.926515] ** ** [ 203.927706] ** If you see this message and you are not debugging the ** [ 203.928365] ** kernel, report this immediately to your vendor! ** [ 203.929050] ** ** [ 203.929706] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 203.930363] ************************************************************* [ 203.969930] DMI not present or invalid. [ 204.044295] NET: Registered protocol family 16 [ 204.080637] audit: initializing netlink subsys (disabled) [ 204.276811] cpuidle: using governor ladder [ 204.279364] cpuidle: using governor menu [ 204.306796] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 204.309494] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 204.322459] audit: type=2000 audit(176.724:1): state=initialized audit_enabled=0 res=1 [ 204.516477] DMA: preallocated 256 KiB pool for atomic allocations [ 204.635399] Serial: AMBA PL011 UART driver [ 205.678977] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 205.682893] printk: console [ttyAMA0] enabled [ 205.682893] printk: console [ttyAMA0] enabled [ 205.684390] printk: bootconsole [pl11] disabled [ 205.684390] printk: bootconsole [pl11] disabled [ 205.825672] _warn_unseeded_randomness: 42 callbacks suppressed [ 205.825935] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 205.828759] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 206.541967] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 206.617914] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 206.801371] kobject: 'renesas,r9a06g032-sysctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 206.815304] kobject: 'renesas-cpg-mssr' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 206.886080] arm-v7s io-pgtable: self test ok [ 206.888805] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 206.898650] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 206.906123] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 206.914315] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 206.923512] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 206.931510] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 206.939394] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 206.951613] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 206.965188] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 206.979740] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 206.993683] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 207.007657] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 207.024456] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 207.060367] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 207.096722] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 207.133908] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 207.176589] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 207.220242] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 207.263456] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 207.305934] vgaarb: loaded [ 207.330403] _warn_unseeded_randomness: 36 callbacks suppressed [ 207.330677] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 207.332823] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 207.368730] pps_core: LinuxPPS API ver. 1 registered [ 207.370151] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 207.375628] PTP clock support registered [ 207.386770] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 207.388519] kobject: 'brcmstb-reboot' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 207.602779] NetLabel: Initializing [ 207.603598] NetLabel: domain hash size = 128 [ 207.604476] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 207.616454] NetLabel: unlabeled traffic allowed by default [ 207.642601] clocksource: Switched to clocksource arch_sys_counter [ 216.411527] _warn_unseeded_randomness: 4 callbacks suppressed [ 216.411793] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 216.414625] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 216.418855] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 216.439401] VFS: Disk quotas dquot_6.6.0 [ 216.446695] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 216.480778] *** VALIDATE hugetlbfs *** [ 217.599327] _warn_unseeded_randomness: 7 callbacks suppressed [ 217.599589] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 217.602462] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 217.604900] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 217.612357] NET: Registered protocol family 2 [ 217.666456] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 217.673554] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 217.704474] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 217.718298] TCP: Hash tables configured (established 16384 bind 16384) [ 217.730684] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 217.738198] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 217.762792] NET: Registered protocol family 1 [ 217.768520] NET: Registered protocol family 44 [ 217.871303] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 217.885928] kvm [1]: HYP mode not available [ 218.270187] Kprobe smoke test: started [ 219.557594] Kprobe smoke test: passed successfully [ 219.647453] Initialise system trusted keyrings [ 219.650280] _warn_unseeded_randomness: 50 callbacks suppressed [ 219.650543] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 219.659660] Key type blacklist registered [ 219.667216] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 219.684628] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 222.371243] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 222.372479] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 222.402729] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 222.667964] 9p: Installing v9fs 9p2000 file system support [ 222.815725] Allocating IMA blacklist keyring. [ 222.835444] cryptomgr_test (37) used greatest stack depth: 12400 bytes left [ 222.864658] kworker/u2:0 (38) used greatest stack depth: 11936 bytes left [ 222.947785] cryptomgr_test (42) used greatest stack depth: 11696 bytes left [ 224.484376] Key type asymmetric registered [ 224.487205] Asymmetric key parser 'x509' registered [ 224.496057] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 224.512205] _warn_unseeded_randomness: 26 callbacks suppressed [ 224.512471] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 224.515144] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 224.561848] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 224.793761] kobject: 'brcm-gisb-arb' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 224.933717] kobject: 'armada-37xx-pinctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 225.198349] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 225.207708] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 225.239842] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 225.275644] kobject: 'stmpe-pwm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 225.311668] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 225.416858] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 225.420358] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 225.425654] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 225.427404] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 225.432436] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 225.456549] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 225.458530] pci_bus 0000:00: root bus resource [bus 00-0f] [ 225.459654] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 225.460735] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 225.462436] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 225.467864] pci 0000:00:00.0: calling quirk_mmio_always_on+0x0/0x38 @ 1 [ 225.469819] pci 0000:00:00.0: quirk_mmio_always_on+0x0/0x38 took 732 usecs [ 225.592251] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 225.594245] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 225.595978] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 225.598188] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 225.599592] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 225.600739] pci 0000:00:03.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 225.602451] pci 0000:00:03.0: BAR 0: assigned [io 0x1000-0x103f] [ 225.603518] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 225.604572] pci 0000:00:02.0: BAR 0: assigned [io 0x1060-0x107f] [ 225.608699] pci 0000:00:00.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 225.610616] pci 0000:00:00.0: tegra_pcie_relax_enable+0x0/0x40 took 191 usecs [ 225.614210] pci 0000:00:01.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 225.615320] pci 0000:00:01.0: tegra_pcie_relax_enable+0x0/0x40 took 88 usecs [ 225.617707] pci 0000:00:02.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 225.618819] pci 0000:00:02.0: tegra_pcie_relax_enable+0x0/0x40 took 88 usecs [ 225.620586] pci 0000:00:03.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 225.622327] pci 0000:00:03.0: tegra_pcie_relax_enable+0x0/0x40 took 85 usecs [ 225.693765] _warn_unseeded_randomness: 15 callbacks suppressed [ 225.693999] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 225.695496] kobject: 'layerscape-pcie' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 225.711092] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=0 [ 226.442327] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 226.485935] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 226.487051] kobject: 'virtio0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 226.532285] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 226.566086] kobject: 'virtio1' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 226.612581] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 226.645743] kobject: 'virtio2' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 233.667886] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 233.875458] _warn_unseeded_randomness: 5 callbacks suppressed [ 233.875714] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 233.877978] kobject: 'ttyS0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 233.880340] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 233.884307] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=0 [ 233.971714] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 233.972662] kobject: 'ttyS1' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 233.975219] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 234.064670] kobject: 'ttyS2' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 234.067458] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 234.155787] kobject: 'ttyS3' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 234.158302] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 234.284384] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 234.302101] KGDB: Registered I/O driver kgdbts [ 234.303279] kgdbts:RUN plant and detach test Entering kdb (current=0xffffffc03f5c8040, pid 1) on processor 0 due to Keyboard Entry [0]kdb> [ 234.314597] kgdbts:RUN sw breakpoint test [ 234.332307] kgdbts:RUN bad memory access test [ 234.335992] kgdbts:RUN singlestep test 1000 iterations [ 234.370155] kgdbts:RUN singlestep [0/1000] [ 237.573267] kgdbts:RUN singlestep [100/1000] [ 240.774081] kgdbts:RUN singlestep [200/1000] [ 243.977385] kgdbts:RUN singlestep [300/1000] [ 247.181274] kgdbts:RUN singlestep [400/1000] [ 250.381478] kgdbts:RUN singlestep [500/1000] [ 253.581470] kgdbts:RUN singlestep [600/1000] [ 256.786223] kgdbts:RUN singlestep [700/1000] [ 259.989265] kgdbts:RUN singlestep [800/1000] [ 263.189415] kgdbts:RUN singlestep [900/1000] [ 266.357783] kgdbts:RUN do_fork for 100 breakpoints [ 266.365645] _warn_unseeded_randomness: 7 callbacks suppressed [ 266.365934] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 268.402391] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 268.403637] kobject: 'rx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 268.411189] kobject: 'tx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 268.492930] kobject: 'net' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 269.391428] KGDB: Unregistered I/O driver kgdbts, debugger disabled [ 272.636619] kobject: 'queues' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 272.803289] kobject: '44' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 272.823160] kobject: 'eth0' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 272.831184] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=0 [ 273.079804] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 273.080961] kobject: '45' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 273.089596] kobject: '46' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 273.092658] ledtrig-cpu: registered to indicate activity on CPUs [ 273.108083] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=0 [ 273.118938] kobject: 'meson-sm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 273.221608] ashmem: initialized [ 273.511451] 9pnet: Installing 9P2000 support [ 273.900431] _warn_unseeded_randomness: 9 callbacks suppressed [ 273.900684] random: get_random_bytes called from init_oops_id+0x38/0x60 with crng_init=0 [ 273.922848] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 273.923951] kobject: '47' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 273.930821] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=0 [ 273.938114] kobject: '48' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 274.015550] registered taskstats version 1 [ 274.017980] Running ring buffer tests... [ 284.151654] finished [ 284.152374] CPU 0: [ 284.155915] events: 6004 [ 284.156659] dropped bytes: 0 [ 284.158142] alloced bytes: 468592 [ 284.158843] written bytes: 459064 [ 284.159621] biggest event: 131 [ 284.160358] smallest event: 0 [ 284.456809] read events: 6004 [ 284.458012] lost events: 0 [ 284.458714] total events: 6004 [ 284.459455] recorded len bytes: 468592 [ 284.460181] recorded size bytes: 459064 [ 284.461640] Ring buffer PASSED! [ 284.566456] Running tests on trace events: [ 284.567290] Testing event initcall_finish: OK [ 284.608335] Testing event initcall_start: OK [ 284.646105] Testing event initcall_level: OK [ 284.679799] Testing event sys_exit: OK [ 284.719157] Testing event sys_enter: OK [ 284.755469] Testing event ipi_exit: OK [ 284.794209] Testing event ipi_entry: OK [ 284.831294] Testing event ipi_raise: OK [ 284.867824] Testing event instruction_emulation: OK [ 284.906968] Testing event kvm_halt_poll_ns: OK [ 284.944286] Testing event kvm_age_page: OK [ 284.983298] Testing event kvm_fpu: OK [ 285.019014] Testing event kvm_mmio: OK [ 285.055308] Testing event kvm_ack_irq: OK [ 285.091123] Testing event kvm_set_irq: OK [ 285.127117] Testing event kvm_vcpu_wakeup: OK [ 285.163314] Testing event kvm_userspace_exit: OK [ 285.199043] Testing event kvm_timer_update_irq: OK [ 285.235152] Testing event kvm_toggle_cache: OK [ 285.271336] Testing event kvm_set_way_flush: OK [ 285.307089] Testing event kvm_test_age_hva: OK [ 285.343074] Testing event kvm_age_hva: OK [ 285.379175] Testing event kvm_set_spte_hva: OK [ 285.415179] Testing event kvm_unmap_hva_range: OK [ 285.451155] Testing event kvm_mmio_emulate: OK [ 285.487307] Testing event kvm_irq_line: OK [ 285.526081] Testing event kvm_access_fault: OK [ 285.567503] Testing event kvm_guest_fault: OK [ 285.607610] Testing event kvm_exit: OK [ 285.646160] Testing event kvm_entry: OK [ 285.683630] Testing event kvm_set_guest_debug: OK [ 285.719878] Testing event kvm_handle_sys_reg: OK [ 285.756199] Testing event trap_reg: OK [ 285.794326] Testing event kvm_arm_set_regset: OK [ 285.832385] Testing event kvm_arm_set_dreg32: OK [ 285.871805] Testing event kvm_arm_clear_debug: OK [ 285.910256] Testing event kvm_arm_setup_debug: OK [ 285.948343] Testing event kvm_hvc_arm64: OK [ 285.990476] Testing event kvm_wfx_arm64: OK [ 286.023753] Testing event vgic_update_irq_pending: OK [ 286.062208] Testing event task_rename: OK [ 286.099913] Testing event task_newtask: OK [ 286.135920] Testing event cpuhp_exit: OK [ 286.177929] Testing event cpuhp_multi_enter: OK [ 286.215410] Testing event cpuhp_enter: OK [ 286.255948] Testing event softirq_raise: OK [ 286.294168] Testing event softirq_exit: OK [ 286.336004] Testing event softirq_entry: OK [ 286.375332] Testing event irq_handler_exit: OK [ 286.414356] Testing event irq_handler_entry: OK [ 286.447721] Testing event signal_deliver: OK [ 286.484288] Testing event signal_generate: OK [ 286.522329] Testing event workqueue_execute_end: OK [ 286.559558] Testing event workqueue_execute_start: OK [ 286.598235] Testing event workqueue_activate_work: OK [ 286.631751] Testing event workqueue_queue_work: OK [ 286.671200] Testing event sched_wake_idle_without_ipi: OK [ 286.708288] Testing event sched_swap_numa: OK [ 286.746355] Testing event sched_stick_numa: OK [ 286.779612] Testing event sched_move_numa: OK [ 286.816752] Testing event sched_process_hang: OK [ 286.859113] Testing event sched_pi_setprio: OK [ 286.893719] Testing event sched_stat_runtime: [ 286.896385] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 286.935583] OK [ 286.936171] Testing event sched_stat_blocked: OK [ 286.975484] Testing event sched_stat_iowait: OK [ 287.014350] Testing event sched_stat_sleep: OK [ 287.051772] Testing event sched_stat_wait: OK [ 287.090390] Testing event sched_process_exec: OK [ 287.127026] Testing event sched_process_fork: OK [ 287.163177] Testing event sched_process_wait: OK [ 287.199092] Testing event sched_wait_task: OK [ 287.235214] Testing event sched_process_exit: OK [ 287.271461] Testing event sched_process_free: OK [ 287.307094] Testing event sched_migrate_task: OK [ 287.343012] Testing event sched_switch: OK [ 287.379465] Testing event sched_wakeup_new: OK [ 287.415430] Testing event sched_wakeup: OK [ 287.451528] Testing event sched_waking: OK [ 287.488216] Testing event sched_kthread_stop_ret: OK [ 287.526272] Testing event sched_kthread_stop: OK [ 287.564345] Testing event lock_acquired: OK [ 287.611120] Testing event lock_contended: OK [ 287.647962] Testing event lock_release: OK [ 287.695769] Testing event lock_acquire: OK [ 287.739529] Testing event console: OK [ 287.783443] Testing event rcu_barrier: OK [ 287.819814] Testing event rcu_torture_read: OK [ 287.855805] Testing event rcu_batch_end: OK [ 287.894333] Testing event rcu_invoke_kfree_callback: OK [ 287.931441] Testing event rcu_invoke_callback: OK [ 287.970236] Testing event rcu_batch_start: OK [ 288.003655] Testing event rcu_kfree_callback: OK [ 288.043304] Testing event rcu_callback: OK [ 288.079350] Testing event rcu_dyntick: OK [ 288.123199] Testing event rcu_fqs: OK [ 288.159973] Testing event rcu_quiescent_state_report: OK [ 288.199505] Testing event rcu_unlock_preempted_task: OK [ 288.234998] Testing event rcu_preempt_task: OK [ 288.270923] Testing event rcu_nocb_wake: OK [ 288.314992] Testing event rcu_exp_funnel_lock: OK [ 288.351272] Testing event rcu_exp_grace_period: OK [ 288.391135] Testing event rcu_grace_period_init: OK [ 288.427932] Testing event rcu_future_grace_period: OK [ 288.468324] Testing event rcu_grace_period: OK [ 288.511509] Testing event rcu_utilization: OK [ 288.546556] Testing event swiotlb_bounced: OK [ 288.584103] Testing event tick_stop: OK [ 288.619791] Testing event itimer_expire: OK [ 288.656025] Testing event itimer_state: OK [ 288.691976] Testing event hrtimer_cancel: OK [ 288.728353] Testing event hrtimer_expire_exit: OK [ 288.764300] Testing event hrtimer_expire_entry: OK [ 288.799996] Testing event hrtimer_start: OK [ 288.835961] Testing event hrtimer_init: OK [ 288.871869] Testing event timer_cancel: OK [ 288.908156] Testing event timer_expire_exit: OK [ 288.943878] Testing event timer_expire_entry: OK [ 288.979898] Testing event timer_start: OK [ 289.015442] Testing event timer_init: OK [ 289.052524] Testing event alarmtimer_cancel: OK [ 289.091959] Testing event alarmtimer_start: OK [ 289.130148] Testing event alarmtimer_fired: OK [ 289.167132] Testing event alarmtimer_suspend: OK [ 289.203141] Testing event module_request: OK [ 289.239127] Testing event module_put: OK [ 289.275215] Testing event module_get: OK [ 289.311150] Testing event module_free: OK [ 289.347148] Testing event module_load: OK [ 289.383102] Testing event cgroup_transfer_tasks: OK [ 289.419296] Testing event cgroup_attach_task: OK [ 289.455170] Testing event cgroup_rename: OK [ 289.492159] Testing event cgroup_release: OK [ 289.530187] Testing event cgroup_rmdir: OK [ 289.567642] Testing event cgroup_mkdir: OK [ 289.606241] Testing event cgroup_remount: OK [ 289.643678] Testing event cgroup_destroy_root: OK [ 289.679986] Testing event cgroup_setup_root: OK [ 289.718170] Testing event irq_enable: OK [ 289.763407] Testing event irq_disable: OK [ 289.808645] Testing event ftrace_test_filter: OK [ 289.843758] Testing event dev_pm_qos_remove_request: OK [ 289.880034] Testing event dev_pm_qos_update_request: OK [ 289.915977] Testing event dev_pm_qos_add_request: OK [ 289.951944] Testing event pm_qos_update_flags: OK [ 289.988006] Testing event pm_qos_update_target: OK [ 290.023963] Testing event pm_qos_update_request_timeout: OK [ 290.060002] Testing event pm_qos_remove_request: OK [ 290.095931] Testing event pm_qos_update_request: OK [ 290.132000] Testing event pm_qos_add_request: OK [ 290.167962] Testing event power_domain_target: OK [ 290.203822] Testing event clock_set_rate: OK [ 290.234985] Testing event clock_disable: OK [ 290.271891] Testing event clock_enable: OK [ 290.302788] Testing event wakeup_source_deactivate: OK [ 290.340282] Testing event wakeup_source_activate: OK [ 290.375922] Testing event suspend_resume: OK [ 290.422226] Testing event device_pm_callback_end: OK [ 290.462499] Testing event device_pm_callback_start: OK [ 290.500319] Testing event cpu_frequency_limits: OK [ 290.539638] Testing event cpu_frequency: OK [ 290.578249] Testing event pstate_sample: OK [ 290.614870] Testing event powernv_throttle: OK [ 290.650997] Testing event cpu_idle: OK [ 290.688856] Testing event rpm_return_int: OK [ 290.726371] Testing event rpm_idle: OK [ 290.763453] Testing event rpm_resume: OK [ 290.799865] Testing event rpm_suspend: OK [ 290.835818] Testing event benchmark_event: OK [ 290.975560] Testing event xdp_devmap_xmit: OK [ 291.007854] Testing event xdp_cpumap_enqueue: OK [ 291.044234] Testing event xdp_cpumap_kthread: OK [ 291.079845] Testing event xdp_redirect_map_err: OK [ 291.115998] Testing event xdp_redirect_map: OK [ 291.152435] Testing event xdp_redirect_err: OK [ 291.191304] Testing event xdp_redirect: OK [ 291.227226] Testing event xdp_exception: OK [ 291.264644] Testing event rseq_ip_fixup: OK [ 291.302133] Testing event rseq_update: OK [ 291.339098] Testing event file_check_and_advance_wb_err: OK [ 291.375154] Testing event filemap_set_wb_err: OK [ 291.411200] Testing event mm_filemap_add_to_page_cache: OK [ 291.447201] Testing event mm_filemap_delete_from_page_cache: OK [ 291.483363] Testing event compact_retry: OK [ 291.519191] Testing event skip_task_reaping: OK [ 291.555250] Testing event finish_task_reaping: OK [ 291.591079] Testing event start_task_reaping: OK [ 291.627197] Testing event wake_reaper: OK [ 291.663083] Testing event mark_victim: OK [ 291.699184] Testing event reclaim_retry_zone: OK [ 291.735107] Testing event oom_score_adj_update: OK [ 291.771236] Testing event mm_lru_activate: OK [ 291.807177] Testing event mm_lru_insertion: OK [ 291.843214] Testing event mm_vmscan_inactive_list_is_low: OK [ 291.879231] Testing event mm_vmscan_lru_shrink_active: OK [ 291.915203] Testing event mm_vmscan_lru_shrink_inactive: OK [ 291.951220] Testing event mm_vmscan_writepage: OK [ 291.986993] Testing event mm_vmscan_lru_isolate: OK [ 292.023144] Testing event mm_shrink_slab_end: OK [ 292.059137] Testing event mm_shrink_slab_start: OK [ 292.095178] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 292.131177] Testing event mm_vmscan_memcg_reclaim_end: OK [ 292.167301] Testing event mm_vmscan_direct_reclaim_end: OK [ 292.203108] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 292.239130] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 292.275321] Testing event mm_vmscan_direct_reclaim_begin: OK [ 292.311137] Testing event mm_vmscan_wakeup_kswapd: OK [ 292.347220] Testing event mm_vmscan_kswapd_wake: OK [ 292.383178] Testing event mm_vmscan_kswapd_sleep: OK [ 292.419227] Testing event percpu_destroy_chunk: OK [ 292.455182] Testing event percpu_create_chunk: OK [ 292.491308] Testing event percpu_alloc_percpu_fail: OK [ 292.530230] Testing event percpu_free_percpu: OK [ 292.572221] Testing event percpu_alloc_percpu: OK [ 292.611266] Testing event mm_fragmentation_stall: OK [ 292.647132] Testing event mm_page_alloc_extfrag: OK [ 292.683353] Testing event mm_page_pcpu_drain: OK [ 292.719519] Testing event mm_page_alloc_zone_locked: OK [ 292.758815] Testing event mm_page_alloc: OK [ 292.795972] Testing event mm_page_free_batched: OK [ 292.836278] Testing event mm_page_free: OK [ 292.878797] Testing event kmem_cache_free: OK [ 292.915956] Testing event kfree: OK [ 292.955685] Testing event kmem_cache_alloc_node: OK [ 292.991879] Testing event kmalloc_node: OK [ 293.030626] Testing event kmem_cache_alloc: OK [ 293.068111] Testing event kmalloc: OK [ 293.107656] Testing event mm_compaction_kcompactd_wake: OK [ 293.143848] Testing event mm_compaction_wakeup_kcompactd: OK [ 293.179968] Testing event mm_compaction_kcompactd_sleep: OK [ 293.218195] Testing event mm_compaction_defer_reset: OK [ 293.260177] Testing event mm_compaction_defer_compaction: OK [ 293.296620] Testing event mm_compaction_deferred: OK [ 293.335403] Testing event mm_compaction_suitable: OK [ 293.374165] Testing event mm_compaction_finished: OK [ 293.411408] Testing event mm_compaction_try_to_compact_pages: OK [ 293.447283] Testing event mm_compaction_end: OK [ 293.483152] Testing event mm_compaction_begin: OK [ 293.519960] Testing event mm_compaction_migratepages: OK [ 293.555881] Testing event mm_compaction_isolate_freepages: OK [ 293.594244] Testing event mm_compaction_isolate_migratepages: OK [ 293.631142] Testing event mm_migrate_pages: OK [ 293.667281] Testing event mm_collapse_huge_page_swapin: OK [ 293.704691] Testing event mm_collapse_huge_page_isolate: OK [ 293.744877] Testing event mm_collapse_huge_page: OK [ 293.783606] Testing event mm_khugepaged_scan_pmd: OK [ 293.819977] Testing event test_pages_isolated: OK [ 293.858190] Testing event cma_release: OK [ 293.895187] Testing event cma_alloc: OK [ 293.935296] Testing event page_ref_unfreeze: OK [ 293.975532] Testing event page_ref_freeze: OK [ 294.016409] Testing event page_ref_mod_unless: OK [ 294.061971] Testing event page_ref_mod_and_return: OK [ 294.099253] Testing event page_ref_mod_and_test: OK [ 294.281556] Testing event page_ref_mod: OK [ 294.359974] Testing event page_ref_set: OK [ 294.402675] Testing event sb_clear_inode_writeback: OK [ 294.439174] Testing event sb_mark_inode_writeback: OK [ 294.475274] Testing event writeback_dirty_inode_enqueue: OK [ 294.511483] Testing event writeback_lazytime_iput: OK [ 294.550240] Testing event writeback_lazytime: OK [ 294.587218] Testing event writeback_single_inode: OK [ 294.623278] Testing event writeback_single_inode_start: OK [ 294.659225] Testing event writeback_wait_iff_congested: OK [ 294.695283] Testing event writeback_congestion_wait: OK [ 294.731079] Testing event writeback_sb_inodes_requeue: OK [ 294.767184] Testing event balance_dirty_pages: OK [ 294.803369] Testing event bdi_dirty_ratelimit: OK [ 294.839184] Testing event global_dirty_state: OK [ 294.875224] Testing event writeback_queue_io: OK [ 294.911100] Testing event wbc_writepage: OK [ 294.947153] Testing event writeback_bdi_register: OK [ 294.983269] Testing event writeback_wake_background: OK [ 295.019211] Testing event writeback_pages_written: OK [ 295.055305] Testing event writeback_wait: OK [ 295.091118] Testing event writeback_written: OK [ 295.127144] Testing event writeback_start: OK [ 295.163117] Testing event writeback_exec: OK [ 295.199205] Testing event writeback_queue: OK [ 295.235060] Testing event writeback_write_inode: OK [ 295.271408] Testing event writeback_write_inode_start: OK [ 295.307220] Testing event writeback_dirty_inode: OK [ 295.343303] Testing event writeback_dirty_inode_start: OK [ 295.379199] Testing event writeback_mark_inode_dirty: OK [ 295.415201] Testing event writeback_dirty_page: OK [ 295.451157] Testing event dax_writeback_one: OK [ 295.487398] Testing event dax_writeback_range_done: OK [ 295.526149] Testing event dax_writeback_range: OK [ 295.567756] Testing event dax_insert_mapping: OK [ 295.607479] Testing event dax_insert_pfn_mkwrite: OK [ 295.646230] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 295.687970] Testing event dax_load_hole: OK [ 295.727507] Testing event dax_pte_fault_done: OK [ 295.766470] Testing event dax_pte_fault: OK [ 295.803354] Testing event dax_pmd_insert_mapping: OK [ 295.838986] Testing event dax_pmd_load_hole_fallback: OK [ 295.875042] Testing event dax_pmd_load_hole: OK [ 295.911105] Testing event dax_pmd_fault_done: OK [ 295.947110] Testing event dax_pmd_fault: OK [ 295.982930] Testing event generic_add_lease: OK [ 296.019181] Testing event time_out_leases: OK [ 296.055174] Testing event generic_delete_lease: OK [ 296.091280] Testing event break_lease_unblock: OK [ 296.127053] Testing event break_lease_block: OK [ 296.163220] Testing event break_lease_noblock: OK [ 296.199266] Testing event flock_lock_inode: OK [ 296.235114] Testing event locks_remove_posix: OK [ 296.271372] Testing event fcntl_setlk: OK [ 296.307031] Testing event posix_lock_inode: OK [ 296.343147] Testing event locks_get_lock_context: OK [ 296.379225] Testing event block_rq_remap: OK [ 296.415000] Testing event block_bio_remap: OK [ 296.451109] Testing event block_split: OK [ 296.487227] Testing event block_unplug: OK [ 296.526281] Testing event block_plug: OK [ 296.563263] Testing event block_sleeprq: OK [ 296.599146] Testing event block_getrq: OK [ 296.635066] Testing event block_bio_queue: OK [ 296.671158] Testing event block_bio_frontmerge: OK [ 296.707178] Testing event block_bio_backmerge: OK [ 296.743177] Testing event block_bio_complete: OK [ 296.779394] Testing event block_bio_bounce: OK [ 296.815061] Testing event block_rq_issue: OK [ 296.851175] Testing event block_rq_insert: OK [ 296.887541] Testing event block_rq_complete: OK [ 296.923980] Testing event block_rq_requeue: OK [ 296.962123] Testing event block_dirty_buffer: OK [ 296.999289] Testing event block_touch_buffer: OK [ 297.036040] Testing event wbt_timer: OK [ 297.074044] Testing event wbt_step: OK [ 297.111326] Testing event wbt_lat: OK [ 297.148136] Testing event wbt_stat: OK [ 297.186196] Testing event gpio_value: OK [ 297.224592] Testing event gpio_direction: OK [ 297.264173] Testing event clk_set_duty_cycle_complete: OK [ 297.304771] Testing event clk_set_duty_cycle: OK [ 297.342305] Testing event clk_set_phase_complete: OK [ 297.382565] Testing event clk_set_phase: OK [ 297.419942] Testing event clk_set_parent_complete: OK [ 297.456511] Testing event clk_set_parent: OK [ 297.496131] Testing event clk_set_rate_complete: OK [ 297.534164] Testing event clk_set_rate: OK [ 297.574899] Testing event clk_unprepare_complete: OK [ 297.612175] Testing event clk_unprepare: OK [ 297.648055] Testing event clk_prepare_complete: OK [ 297.683948] Testing event clk_prepare: OK [ 297.723387] Testing event clk_disable_complete: OK [ 297.762732] Testing event clk_disable: OK [ 297.804064] Testing event clk_enable_complete: OK [ 297.842158] Testing event clk_enable: OK [ 297.880719] Testing event rpmh_send_msg: OK [ 297.919108] Testing event rpmh_tx_done: OK [ 297.955266] Testing event regulator_set_voltage_complete: OK [ 297.991150] Testing event regulator_set_voltage: OK [ 298.027161] Testing event regulator_disable_complete: OK [ 298.063270] Testing event regulator_disable: OK [ 298.099080] Testing event regulator_enable_complete: OK [ 298.135200] Testing event regulator_enable_delay: OK [ 298.171149] Testing event regulator_enable: OK [ 298.207355] Testing event urandom_read: OK [ 298.242966] Testing event random_read: OK [ 298.279288] Testing event extract_entropy_user: OK [ 298.315158] Testing event extract_entropy: OK [ 298.351092] Testing event get_random_bytes_arch: OK [ 298.387270] Testing event get_random_bytes: OK [ 298.423130] Testing event xfer_secondary_pool: OK [ 298.459284] Testing event add_disk_randomness: OK [ 298.495161] Testing event add_input_randomness: OK [ 298.531055] Testing event debit_entropy: OK [ 298.567221] Testing event push_to_pool: OK [ 298.603114] Testing event credit_entropy_bits: OK [ 298.639247] Testing event mix_pool_bytes_nolock: OK [ 298.675129] Testing event mix_pool_bytes: OK [ 298.711366] Testing event add_device_randomness: OK [ 298.747998] Testing event io_page_fault: OK [ 298.786150] Testing event unmap: OK [ 298.823777] Testing event map: OK [ 298.859871] Testing event detach_device_from_domain: OK [ 298.898274] Testing event attach_device_to_domain: OK [ 298.935674] Testing event remove_device_from_group: OK [ 298.974216] Testing event add_device_to_group: OK [ 299.015016] Testing event regcache_drop_region: OK [ 299.051965] Testing event regmap_async_complete_done: OK [ 299.088069] Testing event regmap_async_complete_start: OK [ 299.124020] Testing event regmap_async_io_complete: OK [ 299.160087] Testing event regmap_async_write_start: OK [ 299.196044] Testing event regmap_cache_bypass: OK [ 299.235485] Testing event regmap_cache_only: OK [ 299.274213] Testing event regcache_sync: OK [ 299.307705] Testing event regmap_hw_write_done: OK [ 299.347441] Testing event regmap_hw_write_start: OK [ 299.384208] Testing event regmap_hw_read_done: OK [ 299.422153] Testing event regmap_hw_read_start: OK [ 299.459164] Testing event regmap_reg_read_cache: OK [ 299.495355] Testing event regmap_reg_read: OK [ 299.534199] Testing event regmap_reg_write: OK [ 299.575787] Testing event dma_fence_wait_end: OK [ 299.615481] Testing event dma_fence_wait_start: OK [ 299.654164] Testing event dma_fence_signaled: OK [ 299.695971] Testing event dma_fence_enable_signal: OK [ 299.735778] Testing event dma_fence_destroy: OK [ 299.774156] Testing event dma_fence_init: OK [ 299.815777] Testing event dma_fence_emit: OK [ 299.855430] Testing event sync_timeline: OK [ 299.894226] Testing event spi_transfer_stop: OK [ 299.935906] Testing event spi_transfer_start: OK [ 299.975431] Testing event spi_message_done: OK [ 300.014069] Testing event spi_message_start: OK [ 300.051604] Testing event spi_message_submit: OK [ 300.090338] Testing event spi_controller_busy: OK [ 300.127184] Testing event spi_controller_idle: OK [ 300.163242] Testing event rtc_timer_fired: OK [ 300.199283] Testing event rtc_timer_dequeue: OK [ 300.236311] Testing event rtc_timer_enqueue: OK [ 300.274490] Testing event rtc_read_offset: OK [ 300.309723] Testing event rtc_set_offset: OK [ 300.343142] Testing event rtc_alarm_irq_enable: OK [ 300.381859] Testing event rtc_irq_set_state: OK [ 300.423544] Testing event rtc_irq_set_freq: OK [ 300.463703] Testing event rtc_read_alarm: OK [ 300.506810] Testing event rtc_set_alarm: OK [ 300.543941] Testing event rtc_read_time: OK [ 300.579926] Testing event rtc_set_time: OK [ 300.615897] Testing event memory_failure_event: OK [ 300.652026] Testing event aer_event: OK [ 300.687870] Testing event non_standard_event: OK [ 300.723969] Testing event arm_event: OK [ 300.760211] Testing event mc_event: OK [ 300.795737] Testing event binder_return: OK [ 300.835650] Testing event binder_command: OK [ 300.876432] Testing event binder_unmap_kernel_end: OK [ 300.919264] Testing event binder_unmap_kernel_start: OK [ 300.951783] Testing event binder_unmap_user_end: OK [ 300.990072] Testing event binder_unmap_user_start: OK [ 301.027219] Testing event binder_alloc_page_end: OK [ 301.063896] Testing event binder_alloc_page_start: OK [ 301.100038] Testing event binder_free_lru_end: OK [ 301.138099] Testing event binder_free_lru_start: OK [ 301.175620] Testing event binder_alloc_lru_end: OK [ 301.211999] Testing event binder_alloc_lru_start: OK [ 301.250145] Testing event binder_update_page_range: OK [ 301.287242] Testing event binder_transaction_failed_buffer_release: OK [ 301.323319] Testing event binder_transaction_buffer_release: OK [ 301.359271] Testing event binder_transaction_alloc_buf: OK [ 301.395167] Testing event binder_transaction_fd_recv: OK [ 301.431233] Testing event binder_transaction_fd_send: OK [ 301.467208] Testing event binder_transaction_ref_to_ref: OK [ 301.503237] Testing event binder_transaction_ref_to_node: OK [ 301.539311] Testing event binder_transaction_node_to_ref: OK [ 301.575041] Testing event binder_transaction_received: OK [ 301.611301] Testing event binder_transaction: OK [ 301.647203] Testing event binder_wait_for_work: OK [ 301.683227] Testing event binder_read_done: OK [ 301.719183] Testing event binder_write_done: OK [ 301.755178] Testing event binder_ioctl_done: OK [ 301.791144] Testing event binder_unlock: OK [ 301.826971] Testing event binder_locked: OK [ 301.862937] Testing event binder_lock: OK [ 301.898930] Testing event binder_ioctl: OK [ 301.935243] Testing event br_fdb_update: OK [ 301.970932] Testing event fdb_delete: OK [ 302.006981] Testing event br_fdb_external_learn_add: OK [ 302.042978] Testing event br_fdb_add: OK [ 302.078969] Testing event qdisc_dequeue: OK [ 302.115126] Testing event fib_table_lookup: OK [ 302.152267] Testing event tcp_probe: OK [ 302.190078] Testing event tcp_retransmit_synack: OK [ 302.227246] Testing event tcp_rcv_space_adjust: OK [ 302.267740] Testing event tcp_destroy_sock: OK [ 302.306111] Testing event tcp_receive_reset: OK [ 302.343091] Testing event tcp_send_reset: OK [ 302.379318] Testing event tcp_retransmit_skb: OK [ 302.415314] Testing event udp_fail_queue_rcv_skb: OK [ 302.451151] Testing event inet_sock_set_state: OK [ 302.487258] Testing event sock_exceed_buf_limit: OK [ 302.523237] Testing event sock_rcvqueue_full: OK [ 302.559037] Testing event napi_poll: OK [ 302.595564] Testing event netif_rx_ni_entry: OK [ 302.631998] Testing event netif_rx_entry: OK [ 302.670196] Testing event netif_receive_skb_list_entry: OK [ 302.707335] Testing event netif_receive_skb_entry: OK [ 302.743842] Testing event napi_gro_receive_entry: OK [ 302.782231] Testing event napi_gro_frags_entry: OK [ 302.823822] Testing event netif_rx: OK [ 302.863522] Testing event netif_receive_skb: OK [ 302.902151] Testing event net_dev_queue: OK [ 302.943756] Testing event net_dev_xmit: OK [ 302.983474] Testing event net_dev_start_xmit: OK [ 303.022121] Testing event skb_copy_datagram_iovec: OK [ 303.059653] Testing event consume_skb: OK [ 303.098509] Testing event kfree_skb: OK [ 303.136021] Testing event 9p_protocol_dump: OK [ 303.179845] Testing event 9p_client_res: OK [ 303.216222] Testing event 9p_client_req: OK [ 303.252064] Running tests on trace event systems: [ 303.252854] Testing event system 9p: OK [ 303.302334] Testing event system skb: OK [ 303.343169] Testing event system net: OK [ 303.394500] Testing event system napi: OK [ 303.436637] Testing event system sock: OK [ 303.483147] Testing event system udp: OK [ 303.525982] Testing event system tcp: OK [ 303.569909] Testing event system fib: OK [ 303.604428] Testing event system qdisc: OK [ 303.646147] Testing event system bridge: OK [ 303.685799] Testing event system binder: OK [ 303.763616] Testing event system ras: OK [ 303.818167] Testing event system rtc: OK [ 303.874854] Testing event system spi: OK [ 303.927524] Testing event system sync_trace: OK [ 303.962852] Testing event system dma_fence: OK [ 304.003486] Testing event system regmap: OK [ 304.063595] Testing event system iommu: OK [ 304.104674] Testing event system random: OK [ 304.166748] Testing event system regulator: OK [ 304.218624] Testing event system rpmh: OK [ 304.263158] Testing event system clk: OK [ 304.322867] Testing event system gpio: OK [ 304.371873] Testing event system wbt: OK [ 304.417901] Testing event system block: OK [ 304.471362] Testing event system filelock: OK [ 304.519623] Testing event system fs_dax: OK [ 304.584416] Testing event system writeback: OK [ 304.662844] Testing event system page_ref: OK [ 304.926697] Testing event system cma: OK [ 304.970955] Testing event system page_isolation: OK [ 305.008835] Testing event system huge_memory: OK [ 305.055081] Testing event system migrate: OK [ 305.094459] Testing event system compaction: OK [ 305.148638] Testing event system kmem: OK [ 305.208858] Testing event system percpu: OK [ 305.249594] Testing event system vmscan: OK [ 305.310534] Testing event system pagemap: OK [ 305.359842] Testing event system oom: OK [ 305.411008] Testing event system filemap: OK [ 305.460678] Testing event system rseq: OK [ 305.504872] Testing event system xdp: OK [ 305.553933] Testing event system benchmark: OK [ 305.678296] Testing event system rpm: OK [ 305.719390] Testing event system power: OK [ 305.792150] Testing event system test: OK [ 305.842556] Testing event system preemptirq: OK [ 305.883748] Testing event system cgroup: OK [ 305.934682] Testing event system module: OK [ 305.980574] Testing event system alarmtimer: OK [ 306.022776] Testing event system timer: OK [ 306.080016] Testing event system swiotlb: OK [ 306.128568] Testing event system rcu: OK [ 306.202624] Testing event system printk: OK [ 306.251461] Testing event system lock: OK [ 306.316745] Testing event system sched: OK [ 306.386312] Testing event system workqueue: OK [ 306.438867] Testing event system signal: OK [ 306.474739] Testing event system irq: OK [ 306.520457] Testing event system cpuhp: OK [ 306.563865] Testing event system task: OK [ 306.608180] Testing event system kvm: OK [ 306.680426] Testing event system emulation: OK [ 306.735650] Testing event system ipi: OK [ 306.775791] Testing event system syscalls: OK [ 306.851002] Testing event system raw_syscalls: OK [ 306.895704] Testing event system initcall: OK [ 306.938671] Running tests on all trace events: [ 306.939286] Testing all events: OK [ 308.799989] ftrace_replace_code: 1 [ 335.526574] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 335.540047] Running tests again, along with the function tracer [ 335.547766] Running tests on trace events: [ 335.552784] Testing event initcall_finish: OK [ 335.727155] Testing event initcall_start: OK [ 335.891308] Testing event initcall_level: OK [ 336.072136] Testing event sys_exit: OK [ 336.247618] Testing event sys_enter: OK [ 336.419861] Testing event ipi_exit: OK [ 336.616774] Testing event ipi_entry: OK [ 336.784318] Testing event ipi_raise: OK [ 336.963153] Testing event instruction_emulation: OK [ 337.151779] Testing event kvm_halt_poll_ns: OK [ 337.332256] Testing event kvm_age_page: OK [ 337.496725] Testing event kvm_fpu: OK [ 337.656851] Testing event kvm_mmio: OK [ 337.835145] Testing event kvm_ack_irq: OK [ 337.992067] Testing event kvm_set_irq: OK [ 338.183158] Testing event kvm_vcpu_wakeup: OK [ 338.360698] Testing event kvm_userspace_exit: OK [ 338.560638] Testing event kvm_timer_update_irq: OK [ 338.746508] Testing event kvm_toggle_cache: OK [ 338.912585] Testing event kvm_set_way_flush: OK [ 339.104433] Testing event kvm_test_age_hva: OK [ 339.323464] Testing event kvm_age_hva: OK [ 339.536140] Testing event kvm_set_spte_hva: OK [ 339.726981] Testing event kvm_unmap_hva_range: OK [ 339.912452] Testing event kvm_mmio_emulate: OK [ 340.112415] Testing event kvm_irq_line: OK [ 340.292637] Testing event kvm_access_fault: OK [ 340.468750] Testing event kvm_guest_fault: OK [ 340.636184] Testing event kvm_exit: OK [ 340.831853] Testing event kvm_entry: OK [ 340.999141] Testing event kvm_set_guest_debug: OK [ 341.171824] Testing event kvm_handle_sys_reg: OK [ 341.348898] Testing event trap_reg: OK [ 341.535847] Testing event kvm_arm_set_regset: OK [ 341.707612] Testing event kvm_arm_set_dreg32: OK [ 341.883136] Testing event kvm_arm_clear_debug: OK [ 342.063269] Testing event kvm_arm_setup_debug: OK [ 342.228404] Testing event kvm_hvc_arm64: OK [ 342.404713] Testing event kvm_wfx_arm64: OK [ 342.567156] Testing event vgic_update_irq_pending: OK [ 342.748807] Testing event task_rename: OK [ 342.916099] Testing event task_newtask: OK [ 343.095497] Testing event cpuhp_exit: OK [ 343.272885] Testing event cpuhp_multi_enter: OK [ 343.444692] Testing event cpuhp_enter: OK [ 343.643761] Testing event softirq_raise: OK [ 343.839907] Testing event softirq_exit: OK [ 344.052868] Testing event softirq_entry: OK [ 344.235708] Testing event irq_handler_exit: OK [ 344.432509] Testing event irq_handler_entry: OK [ 344.675976] Testing event signal_deliver: OK [ 344.839396] Testing event signal_generate: OK [ 345.019371] Testing event workqueue_execute_end: OK [ 345.204374] Testing event workqueue_execute_start: OK [ 345.399953] Testing event workqueue_activate_work: OK [ 345.611592] Testing event workqueue_queue_work: OK [ 345.787854] Testing event sched_wake_idle_without_ipi: OK [ 345.979186] Testing event sched_swap_numa: OK [ 346.147212] Testing event sched_stick_numa: OK [ 346.331683] Testing event sched_move_numa: OK [ 346.519219] Testing event sched_process_hang: OK [ 346.715987] Testing event sched_pi_setprio: OK [ 346.884392] Testing event sched_stat_runtime: OK [ 347.060273] Testing event sched_stat_blocked: OK [ 347.231572] Testing event sched_stat_iowait: OK [ 347.415235] Testing event sched_stat_sleep: OK [ 347.584310] Testing event sched_stat_wait: OK [ 347.748516] Testing event sched_process_exec: OK [ 347.923487] Testing event sched_process_fork: OK [ 348.092189] Testing event sched_process_wait: OK [ 348.256865] Testing event sched_wait_task: OK [ 348.436310] Testing event sched_process_exit: OK [ 348.624475] Testing event sched_process_free: OK [ 348.795992] Testing event sched_migrate_task: OK [ 348.977723] Testing event sched_switch: OK [ 349.163052] Testing event sched_wakeup_new: OK [ 349.316440] Testing event sched_wakeup: OK [ 349.495179] Testing event sched_waking: OK [ 349.660324] Testing event sched_kthread_stop_ret: OK [ 349.831217] Testing event sched_kthread_stop: OK [ 349.995316] Testing event lock_acquired: OK [ 350.192028] Testing event lock_contended: OK [ 350.415955] Testing event lock_release: OK [ 350.635149] Testing event lock_acquire: OK [ 350.839476] Testing event console: OK [ 351.020226] Testing event rcu_barrier: OK [ 351.200698] Testing event rcu_torture_read: OK [ 351.391106] Testing event rcu_batch_end: OK [ 351.603650] Testing event rcu_invoke_kfree_callback: OK [ 351.807880] Testing event rcu_invoke_callback: OK [ 351.999139] Testing event rcu_batch_start: OK [ 352.212279] Testing event rcu_kfree_callback: OK [ 352.404347] Testing event rcu_callback: OK [ 352.611032] Testing event rcu_dyntick: OK [ 352.824762] Testing event rcu_fqs: OK [ 353.004700] Testing event rcu_quiescent_state_report: OK [ 353.188840] Testing event rcu_unlock_preempted_task: OK [ 353.388733] Testing event rcu_preempt_task: OK [ 353.588390] Testing event rcu_nocb_wake: OK [ 353.803982] Testing event rcu_exp_funnel_lock: OK [ 353.995995] Testing event rcu_exp_grace_period: OK [ 354.168586] Testing event rcu_grace_period_init: OK [ 354.344852] Testing event rcu_future_grace_period: OK [ 354.519125] Testing event rcu_grace_period: OK [ 354.724203] Testing event rcu_utilization: OK [ 354.909694] Testing event swiotlb_bounced: OK [ 355.123792] Testing event tick_stop: OK [ 355.275023] Testing event itimer_expire: OK [ 355.447671] Testing event itimer_state: OK [ 355.625744] Testing event hrtimer_cancel: OK [ 355.796688] Testing event hrtimer_expire_exit: OK [ 355.983333] Testing event hrtimer_expire_entry: OK [ 356.148448] Testing event hrtimer_start: OK [ 356.323613] Testing event hrtimer_init: OK [ 356.495618] Testing event timer_cancel: OK [ 356.699491] Testing event timer_expire_exit: OK [ 356.860905] Testing event timer_expire_entry: OK [ 357.035075] Testing event timer_start: OK [ 357.220077] Testing event timer_init: OK [ 357.396706] Testing event alarmtimer_cancel: OK [ 357.571920] Testing event alarmtimer_start: OK [ 357.743722] Testing event alarmtimer_fired: OK [ 357.916692] Testing event alarmtimer_suspend: OK [ 358.087273] Testing event module_request: OK [ 358.259105] Testing event module_put: OK [ 358.447718] Testing event module_get: OK [ 358.591290] Testing event module_free: OK [ 358.772827] Testing event module_load: OK [ 358.948898] Testing event cgroup_transfer_tasks: OK [ 359.128355] Testing event cgroup_attach_task: OK [ 359.328585] Testing event cgroup_rename: OK [ 359.516585] Testing event cgroup_release: OK [ 359.691272] Testing event cgroup_rmdir: OK [ 359.907546] Testing event cgroup_mkdir: OK [ 360.092577] Testing event cgroup_remount: OK [ 360.272871] Testing event cgroup_destroy_root: OK [ 360.439764] Testing event cgroup_setup_root: OK [ 360.619345] Testing event irq_enable: OK [ 360.880186] Testing event irq_disable: OK [ 361.143074] Testing event ftrace_test_filter: OK [ 361.327886] Testing event dev_pm_qos_remove_request: OK [ 361.504577] Testing event dev_pm_qos_update_request: OK [ 361.680347] Testing event dev_pm_qos_add_request: OK [ 361.875201] Testing event pm_qos_update_flags: OK [ 362.043588] Testing event pm_qos_update_target: OK [ 362.236116] Testing event pm_qos_update_request_timeout: OK [ 362.432280] Testing event pm_qos_remove_request: OK [ 362.603158] Testing event pm_qos_update_request: OK [ 362.779382] Testing event pm_qos_add_request: OK [ 362.972538] Testing event power_domain_target: OK [ 363.123830] Testing event clock_set_rate: OK [ 363.300566] Testing event clock_disable: OK [ 363.468771] Testing event clock_enable: OK [ 363.632569] Testing event wakeup_source_deactivate: OK [ 363.807890] Testing event wakeup_source_activate: OK [ 363.987175] Testing event suspend_resume: OK [ 364.236310] Testing event device_pm_callback_end: OK [ 364.427055] Testing event device_pm_callback_start: OK [ 364.664093] Testing event cpu_frequency_limits: OK [ 364.827975] Testing event cpu_frequency: OK [ 365.026893] Testing event pstate_sample: OK [ 365.199306] Testing event powernv_throttle: OK [ 365.351237] Testing event cpu_idle: OK [ 365.519602] Testing event rpm_return_int: OK [ 365.704882] Testing event rpm_idle: OK [ 365.883639] Testing event rpm_resume: OK [ 366.048913] Testing event rpm_suspend: OK [ 366.211168] Testing event benchmark_event: OK [ 366.440821] Testing event xdp_devmap_xmit: OK [ 366.624259] Testing event xdp_cpumap_enqueue: OK [ 366.812482] Testing event xdp_cpumap_kthread: OK [ 366.988150] Testing event xdp_redirect_map_err: OK [ 367.156285] Testing event xdp_redirect_map: OK [ 367.323269] Testing event xdp_redirect_err: OK [ 367.495876] Testing event xdp_redirect: OK [ 367.671801] Testing event xdp_exception: OK [ 367.847327] Testing event rseq_ip_fixup: OK [ 368.023758] Testing event rseq_update: OK [ 368.191284] Testing event file_check_and_advance_wb_err: OK [ 368.387100] Testing event filemap_set_wb_err: OK [ 368.581747] Testing event mm_filemap_add_to_page_cache: OK [ 368.767792] Testing event mm_filemap_delete_from_page_cache: OK [ 368.952373] Testing event compact_retry: OK [ 369.168479] Testing event skip_task_reaping: OK [ 369.355878] Testing event finish_task_reaping: OK [ 369.520195] Testing event start_task_reaping: OK [ 369.707274] Testing event wake_reaper: OK [ 369.884271] Testing event mark_victim: OK [ 370.055192] Testing event reclaim_retry_zone: OK [ 370.232114] Testing event oom_score_adj_update: OK [ 370.402974] Testing event mm_lru_activate: OK [ 370.575641] Testing event mm_lru_insertion: OK [ 370.736291] Testing event mm_vmscan_inactive_list_is_low: OK [ 370.928532] Testing event mm_vmscan_lru_shrink_active: OK [ 371.103288] Testing event mm_vmscan_lru_shrink_inactive: OK [ 371.283725] Testing event mm_vmscan_writepage: OK [ 371.479117] Testing event mm_vmscan_lru_isolate: OK [ 371.648058] Testing event mm_shrink_slab_end: OK [ 371.815863] Testing event mm_shrink_slab_start: OK [ 372.023527] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 372.227793] Testing event mm_vmscan_memcg_reclaim_end: OK [ 372.419327] Testing event mm_vmscan_direct_reclaim_end: OK [ 372.624533] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 372.859432] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 373.043438] Testing event mm_vmscan_direct_reclaim_begin: OK [ 373.247463] Testing event mm_vmscan_wakeup_kswapd: OK [ 373.443700] Testing event mm_vmscan_kswapd_wake: OK [ 373.599690] Testing event mm_vmscan_kswapd_sleep: OK [ 373.814789] Testing event percpu_destroy_chunk: OK [ 373.988118] Testing event percpu_create_chunk: OK [ 374.135545] Testing event percpu_alloc_percpu_fail: OK [ 374.303468] Testing event percpu_free_percpu: OK [ 374.503480] Testing event percpu_alloc_percpu: OK [ 374.684648] Testing event mm_fragmentation_stall: OK [ 374.844032] Testing event mm_page_alloc_extfrag: OK [ 375.022981] Testing event mm_page_pcpu_drain: OK [ 375.195243] Testing event mm_page_alloc_zone_locked: OK [ 375.396699] Testing event mm_page_alloc: OK [ 375.556066] Testing event mm_page_free_batched: OK [ 375.759136] Testing event mm_page_free: OK [ 375.963563] Testing event kmem_cache_free: OK [ 376.184926] Testing event kfree: OK [ 376.356260] Testing event kmem_cache_alloc_node: OK [ 376.535493] Testing event kmalloc_node: OK [ 376.727249] Testing event kmem_cache_alloc: OK [ 376.916057] Testing event kmalloc: OK [ 377.095380] Testing event mm_compaction_kcompactd_wake: OK [ 377.267478] Testing event mm_compaction_wakeup_kcompactd: OK [ 377.452406] Testing event mm_compaction_kcompactd_sleep: OK [ 377.635361] Testing event mm_compaction_defer_reset: OK [ 377.816874] Testing event mm_compaction_defer_compaction: OK [ 378.007049] Testing event mm_compaction_deferred: OK [ 378.191302] Testing event mm_compaction_suitable: OK [ 378.359829] Testing event mm_compaction_finished: OK [ 378.536705] Testing event mm_compaction_try_to_compact_pages: OK [ 378.722966] Testing event mm_compaction_end: OK [ 378.884194] Testing event mm_compaction_begin: OK [ 379.075372] Testing event mm_compaction_migratepages: OK [ 379.251246] Testing event mm_compaction_isolate_freepages: OK [ 379.437707] Testing event mm_compaction_isolate_migratepages: OK [ 379.607205] Testing event mm_migrate_pages: OK [ 379.807191] Testing event mm_collapse_huge_page_swapin: OK [ 379.987544] Testing event mm_collapse_huge_page_isolate: OK [ 380.168609] Testing event mm_collapse_huge_page: OK [ 380.327552] Testing event mm_khugepaged_scan_pmd: OK [ 380.507209] Testing event test_pages_isolated: OK [ 380.664397] Testing event cma_release: OK [ 380.840047] Testing event cma_alloc: OK [ 381.004331] Testing event page_ref_unfreeze: OK [ 381.268089] Testing event page_ref_freeze: OK [ 381.464116] Testing event page_ref_mod_unless: OK [ 381.702844] Testing event page_ref_mod_and_return: OK [ 381.907294] Testing event page_ref_mod_and_test: OK [ 382.610957] Testing event page_ref_mod: OK [ 382.991723] Testing event page_ref_set: OK [ 383.171467] Testing event sb_clear_inode_writeback: OK [ 383.360510] Testing event sb_mark_inode_writeback: OK [ 383.544452] Testing event writeback_dirty_inode_enqueue: OK [ 383.716753] Testing event writeback_lazytime_iput: OK [ 383.892615] Testing event writeback_lazytime: OK [ 384.071419] Testing event writeback_single_inode: OK [ 384.235345] Testing event writeback_single_inode_start: OK [ 384.407758] Testing event writeback_wait_iff_congested: OK [ 384.611174] Testing event writeback_congestion_wait: OK [ 384.783927] Testing event writeback_sb_inodes_requeue: OK [ 384.960822] Testing event balance_dirty_pages: OK [ 385.139338] Testing event bdi_dirty_ratelimit: OK [ 385.328348] Testing event global_dirty_state: OK [ 385.516849] Testing event writeback_queue_io: OK [ 385.700729] Testing event wbc_writepage: OK [ 385.879494] Testing event writeback_bdi_register: OK [ 386.059181] Testing event writeback_wake_background: OK [ 386.231438] Testing event writeback_pages_written: OK [ 386.404604] Testing event writeback_wait: OK [ 386.575098] Testing event writeback_written: OK [ 386.768458] Testing event writeback_start: OK [ 386.964489] Testing event writeback_exec: OK [ 387.116679] Testing event writeback_queue: OK [ 387.288292] Testing event writeback_write_inode: OK [ 387.475185] Testing event writeback_write_inode_start: OK [ 387.651618] Testing event writeback_dirty_inode: OK [ 387.799953] Testing event writeback_dirty_inode_start: OK [ 388.031556] Testing event writeback_mark_inode_dirty: OK [ 388.199895] Testing event writeback_dirty_page: OK [ 388.372623] Testing event dax_writeback_one: OK [ 388.551999] Testing event dax_writeback_range_done: OK [ 388.731631] Testing event dax_writeback_range: OK [ 388.948154] Testing event dax_insert_mapping: OK [ 389.140365] Testing event dax_insert_pfn_mkwrite: OK [ 389.320872] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 389.520245] Testing event dax_load_hole: OK [ 389.716331] Testing event dax_pte_fault_done: OK [ 389.892220] Testing event dax_pte_fault: OK [ 390.055267] Testing event dax_pmd_insert_mapping: OK [ 390.256443] Testing event dax_pmd_load_hole_fallback: OK [ 390.456120] Testing event dax_pmd_load_hole: OK [ 390.648925] Testing event dax_pmd_fault_done: OK [ 390.851580] Testing event dax_pmd_fault: OK [ 391.023943] Testing event generic_add_lease: OK [ 391.204543] Testing event time_out_leases: OK [ 391.408378] Testing event generic_delete_lease: OK [ 391.607894] Testing event break_lease_unblock: OK [ 391.795029] Testing event break_lease_block: OK [ 391.975927] Testing event break_lease_noblock: OK [ 392.143867] Testing event flock_lock_inode: OK [ 392.319504] Testing event locks_remove_posix: OK [ 392.496795] Testing event fcntl_setlk: OK [ 392.647137] Testing event posix_lock_inode: OK [ 392.835912] Testing event locks_get_lock_context: OK [ 393.023264] Testing event block_rq_remap: OK [ 393.204461] Testing event block_bio_remap: OK [ 393.384120] Testing event block_split: OK [ 393.567567] Testing event block_unplug: OK [ 393.740219] Testing event block_plug: OK [ 393.923250] Testing event block_sleeprq: OK [ 394.104825] Testing event block_getrq: OK [ 394.272005] Testing event block_bio_queue: OK [ 394.443251] Testing event block_bio_frontmerge: OK [ 394.639370] Testing event block_bio_backmerge: OK [ 394.803473] Testing event block_bio_complete: OK [ 394.983597] Testing event block_bio_bounce: OK [ 395.156786] Testing event block_rq_issue: OK [ 395.332154] Testing event block_rq_insert: OK [ 395.535395] Testing event block_rq_complete: OK [ 395.708620] Testing event block_rq_requeue: OK [ 395.879770] Testing event block_dirty_buffer: OK [ 396.059259] Testing event block_touch_buffer: OK [ 396.236852] Testing event wbt_timer: OK [ 396.420544] Testing event wbt_step: OK [ 396.611510] Testing event wbt_lat: OK [ 396.788266] Testing event wbt_stat: OK [ 396.983566] Testing event gpio_value: OK [ 397.187851] Testing event gpio_direction: OK [ 397.396729] Testing event clk_set_duty_cycle_complete: OK [ 397.568647] Testing event clk_set_duty_cycle: OK [ 397.743126] Testing event clk_set_phase_complete: OK [ 397.923756] Testing event clk_set_phase: OK [ 398.084081] Testing event clk_set_parent_complete: OK [ 398.276895] Testing event clk_set_parent: OK [ 398.479191] Testing event clk_set_rate_complete: OK [ 398.672470] Testing event clk_set_rate: OK [ 398.863310] Testing event clk_unprepare_complete: OK [ 399.011123] Testing event clk_unprepare: OK [ 399.188697] Testing event clk_prepare_complete: OK [ 399.348591] Testing event clk_prepare: OK [ 399.527664] Testing event clk_disable_complete: OK [ 399.718927] Testing event clk_disable: OK [ 399.903266] Testing event clk_enable_complete: OK [ 400.071142] Testing event clk_enable: OK [ 400.243611] Testing event rpmh_send_msg: OK [ 400.412728] Testing event rpmh_tx_done: OK [ 400.579557] Testing event regulator_set_voltage_complete: OK [ 400.755911] Testing event regulator_set_voltage: OK [ 400.931889] Testing event regulator_disable_complete: OK [ 401.119382] Testing event regulator_disable: OK [ 401.295251] Testing event regulator_enable_complete: OK [ 401.463297] Testing event regulator_enable_delay: OK [ 401.651249] Testing event regulator_enable: OK [ 401.832638] Testing event urandom_read: OK [ 402.012004] Testing event random_read: OK [ 402.211472] Testing event extract_entropy_user: OK [ 402.391393] Testing event extract_entropy: OK [ 402.567388] Testing event get_random_bytes_arch: OK [ 402.747180] Testing event get_random_bytes: OK [ 402.912853] Testing event xfer_secondary_pool: OK [ 403.107347] Testing event add_disk_randomness: OK [ 403.259867] Testing event add_input_randomness: OK [ 403.444002] Testing event debit_entropy: OK [ 403.616666] Testing event push_to_pool: OK [ 403.800166] Testing event credit_entropy_bits: OK [ 404.008306] Testing event mix_pool_bytes_nolock: OK [ 404.200458] Testing event mix_pool_bytes: OK [ 404.376335] Testing event add_device_randomness: OK [ 404.579854] Testing event io_page_fault: OK [ 404.751587] Testing event unmap: OK [ 404.960903] Testing event map: OK [ 405.136260] Testing event detach_device_from_domain: OK [ 405.328415] Testing event attach_device_to_domain: OK [ 405.523506] Testing event remove_device_from_group: OK [ 405.732368] Testing event add_device_to_group: OK [ 405.915805] Testing event regcache_drop_region: OK [ 406.091506] Testing event regmap_async_complete_done: OK [ 406.280213] Testing event regmap_async_complete_start: OK [ 406.483557] Testing event regmap_async_io_complete: OK [ 406.652514] Testing event regmap_async_write_start: OK [ 406.816954] Testing event regmap_cache_bypass: OK [ 406.991910] Testing event regmap_cache_only: OK [ 407.155043] Testing event regcache_sync: OK [ 407.336290] Testing event regmap_hw_write_done: OK [ 407.511884] Testing event regmap_hw_write_start: OK [ 407.707895] Testing event regmap_hw_read_done: OK [ 407.887478] Testing event regmap_hw_read_start: OK [ 408.048456] Testing event regmap_reg_read_cache: OK [ 408.220578] Testing event regmap_reg_read: OK [ 408.404842] Testing event regmap_reg_write: OK [ 408.579444] Testing event dma_fence_wait_end: OK [ 408.747085] Testing event dma_fence_wait_start: OK [ 408.940669] Testing event dma_fence_signaled: OK [ 409.120351] Testing event dma_fence_enable_signal: OK [ 409.299187] Testing event dma_fence_destroy: OK [ 409.473711] Testing event dma_fence_init: OK [ 409.647105] Testing event dma_fence_emit: OK [ 409.832543] Testing event sync_timeline: OK [ 409.999440] Testing event spi_transfer_stop: OK [ 410.172399] Testing event spi_transfer_start: OK [ 410.356885] Testing event spi_message_done: OK [ 410.515455] Testing event spi_message_start: OK [ 410.695820] Testing event spi_message_submit: OK [ 410.863176] Testing event spi_controller_busy: OK [ 411.043741] Testing event spi_controller_idle: OK [ 411.215688] Testing event rtc_timer_fired: OK [ 411.392723] Testing event rtc_timer_dequeue: OK [ 411.576400] Testing event rtc_timer_enqueue: OK [ 411.787901] Testing event rtc_read_offset: OK [ 411.964197] Testing event rtc_set_offset: OK [ 412.156252] Testing event rtc_alarm_irq_enable: OK [ 412.327079] Testing event rtc_irq_set_state: OK [ 412.528446] Testing event rtc_irq_set_freq: OK [ 412.683045] Testing event rtc_read_alarm: OK [ 412.907876] Testing event rtc_set_alarm: OK [ 413.059532] Testing event rtc_read_time: OK [ 413.276120] Testing event rtc_set_time: OK [ 413.456804] Testing event memory_failure_event: OK [ 413.607425] Testing event aer_event: OK [ 413.787150] Testing event non_standard_event: OK [ 413.955800] Testing event arm_event: OK [ 414.143271] Testing event mc_event: OK [ 414.303560] Testing event binder_return: OK [ 414.480126] Testing event binder_command: OK [ 414.652563] Testing event binder_unmap_kernel_end: OK [ 414.824074] Testing event binder_unmap_kernel_start: OK [ 415.008921] Testing event binder_unmap_user_end: OK [ 415.168807] Testing event binder_unmap_user_start: OK [ 415.343169] Testing event binder_alloc_page_end: OK [ 415.520285] Testing event binder_alloc_page_start: OK [ 415.704126] Testing event binder_free_lru_end: OK [ 415.875842] Testing event binder_free_lru_start: OK [ 416.040567] Testing event binder_alloc_lru_end: OK [ 416.224585] Testing event binder_alloc_lru_start: OK [ 416.408480] Testing event binder_update_page_range: OK [ 416.611766] Testing event binder_transaction_failed_buffer_release: OK [ 416.816285] Testing event binder_transaction_buffer_release: OK [ 417.052704] Testing event binder_transaction_alloc_buf: OK [ 417.200724] Testing event binder_transaction_fd_recv: OK [ 417.383203] Testing event binder_transaction_fd_send: OK [ 417.563992] Testing event binder_transaction_ref_to_ref: OK [ 417.743217] Testing event binder_transaction_ref_to_node: OK [ 417.919001] Testing event binder_transaction_node_to_ref: OK [ 418.091298] Testing event binder_transaction_received: OK [ 418.264898] Testing event binder_transaction: OK [ 418.472196] Testing event binder_wait_for_work: OK [ 418.671527] Testing event binder_read_done: OK [ 418.902670] Testing event binder_write_done: OK [ 419.068414] Testing event binder_ioctl_done: OK [ 419.268288] Testing event binder_unlock: OK [ 419.476118] Testing event binder_locked: OK [ 419.642981] Testing event binder_lock: OK [ 419.816372] Testing event binder_ioctl: OK [ 419.984096] Testing event br_fdb_update: OK [ 420.156569] Testing event fdb_delete: OK [ 420.335256] Testing event br_fdb_external_learn_add: OK [ 420.511556] Testing event br_fdb_add: OK [ 420.700919] Testing event qdisc_dequeue: OK [ 420.883560] Testing event fib_table_lookup: OK [ 421.048194] Testing event tcp_probe: OK [ 421.228543] Testing event tcp_retransmit_synack: OK [ 421.387733] Testing event tcp_rcv_space_adjust: OK [ 421.568534] Testing event tcp_destroy_sock: OK [ 421.760537] Testing event tcp_receive_reset: OK [ 421.947638] Testing event tcp_send_reset: OK [ 422.095757] Testing event tcp_retransmit_skb: OK [ 422.268387] Testing event udp_fail_queue_rcv_skb: OK [ 422.447236] Testing event inet_sock_set_state: OK [ 422.648362] Testing event sock_exceed_buf_limit: OK [ 422.824424] Testing event sock_rcvqueue_full: OK [ 423.015579] Testing event napi_poll: OK [ 423.198436] Testing event netif_rx_ni_entry: OK [ 423.396563] Testing event netif_rx_entry: OK [ 423.563101] Testing event netif_receive_skb_list_entry: OK [ 423.735336] Testing event netif_receive_skb_entry: OK [ 423.908446] Testing event napi_gro_receive_entry: OK [ 424.067004] Testing event napi_gro_frags_entry: OK [ 424.275938] Testing event netif_rx: OK [ 424.467950] Testing event netif_receive_skb: OK [ 424.667698] Testing event net_dev_queue: OK [ 424.864450] Testing event net_dev_xmit: OK [ 425.048060] Testing event net_dev_start_xmit: OK [ 425.243492] Testing event skb_copy_datagram_iovec: OK [ 425.415583] Testing event consume_skb: OK [ 425.608134] Testing event kfree_skb: OK [ 425.796447] Testing event 9p_protocol_dump: OK [ 426.011640] Testing event 9p_client_res: OK [ 426.211764] Testing event 9p_client_req: OK [ 426.396867] Running tests on trace event systems: [ 426.404068] Testing event system 9p: OK [ 426.607004] Testing event system skb: OK [ 426.828142] Testing event system net: OK [ 427.039500] Testing event system napi: OK [ 427.263567] Testing event system sock: OK [ 427.524777] Testing event system udp: OK [ 427.728036] Testing event system tcp: OK [ 427.960127] Testing event system fib: OK [ 428.151816] Testing event system qdisc: OK [ 428.371486] Testing event system bridge: OK [ 428.579229] Testing event system binder: OK [ 428.895984] Testing event system ras: OK [ 429.172902] Testing event system rtc: OK [ 429.403480] Testing event system spi: OK [ 429.631197] Testing event system sync_trace: OK [ 429.852216] Testing event system dma_fence: OK [ 430.080739] Testing event system regmap: OK [ 430.355050] Testing event system iommu: OK [ 430.640873] Testing event system random: OK [ 430.936124] Testing event system regulator: OK [ 431.227621] Testing event system rpmh: OK [ 431.396230] Testing event system clk: OK [ 431.688064] Testing event system gpio: OK [ 431.919098] Testing event system wbt: OK [ 432.135948] Testing event system block: OK [ 432.423165] Testing event system filelock: OK [ 432.740929] Testing event system fs_dax: OK [ 433.024105] Testing event system writeback: OK [ 433.391752] Testing event system page_ref: OK [ 434.427460] Testing event system cma: OK [ 434.652725] Testing event system page_isolation: OK [ 434.832758] Testing event system huge_memory: OK [ 435.023272] Testing event system migrate: OK [ 435.199341] Testing event system compaction: OK [ 435.460147] Testing event system kmem: OK [ 435.776859] Testing event system percpu: OK [ 436.012513] Testing event system vmscan: OK [ 436.303489] Testing event system pagemap: OK [ 436.507818] Testing event system oom: OK [ 436.751891] Testing event system filemap: OK [ 436.948788] Testing event system rseq: OK [ 437.143814] Testing event system xdp: OK [ 437.364531] Testing event system benchmark: OK [ 437.659740] Testing event system rpm: OK [ 437.860012] Testing event system power: OK [ 438.171085] Testing event system test: OK [ 438.447499] Testing event system preemptirq: OK [ 438.788807] Testing event system cgroup: OK [ 439.055944] Testing event system module: OK [ 439.265618] Testing event system alarmtimer: OK [ 439.491767] Testing event system timer: OK [ 439.736565] Testing event system swiotlb: OK [ 439.959706] Testing event system rcu: OK [ 440.328558] Testing event system printk: OK [ 440.584261] Testing event system lock: OK [ 440.891993] Testing event system sched: OK [ 441.226608] Testing event system workqueue: OK [ 441.511532] Testing event system signal: OK [ 441.696524] Testing event system irq: OK [ 441.935586] Testing event system cpuhp: OK [ 442.164895] Testing event system task: OK [ 442.386497] Testing event system kvm: OK [ 442.744776] Testing event system emulation: OK [ 442.987698] Testing event system ipi: OK [ 443.187917] Testing event system syscalls: OK [ 443.636410] Testing event system raw_syscalls: OK [ 443.791516] Testing event system initcall: OK [ 443.991077] Running tests on all trace events: [ 443.996111] Testing all events: OK [ 459.377786] ftrace_replace_code: 1 [ 469.375265] ../kernel/trace/ftrace.c:2442, loop_counter: 42031 [ 469.384446] Testing ftrace filter: OK [ 469.585810] trace_kprobe: Testing kprobe tracing: OK [ 470.456328] Loading compiled-in X.509 certificates [ 470.598046] _warn_unseeded_randomness: 3 callbacks suppressed [ 470.598350] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 470.602435] Loaded X.509 cert 'Build time autogenerated kernel key: ef4425a12675de91b49c005bf61fbcc152bd6e38' [ 470.627091] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 470.628371] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 470.679538] zswap: default zpool zbud not available [ 470.680710] zswap: pool creation failed [ 470.722511] page_owner is disabled [ 470.768488] kworker/u2:0 (1259) used greatest stack depth: 11232 bytes left [ 470.968351] Key type big_key registered [ 471.075543] Key type encrypted registered [ 471.082883] ima: No TPM chip found, activating TPM-bypass! [ 471.085626] ima: Allocated hash algorithm: sha1 [ 471.090642] No architecture policies found [ 471.102731] evm: Initialising EVM extended attributes: [ 471.103465] evm: security.selinux [ 471.104121] evm: security.SMACK64 [ 471.104755] evm: security.SMACK64EXEC [ 471.106036] evm: security.SMACK64TRANSMUTE [ 471.106682] evm: security.SMACK64MMAP [ 471.107326] evm: security.apparmor [ 471.107961] evm: security.ima [ 471.108568] evm: security.capability [ 471.109884] evm: HMAC attrs: 0x1 [ 471.200326] hctosys: unable to open rtc device (rtc0) [ 471.237543] psci_checker: PSCI checker started using 1 CPUs [ 471.238250] psci_checker: Starting hotplug tests [ 471.240128] psci_checker: Trying to turn off and on again all CPUs [ 471.241947] psci_checker: Trying to turn off and on again group 0 (CPUs 0) [ 471.243610] psci_checker: Hotplug tests passed OK [ 471.244320] psci_checker: Starting suspend tests (10 cycles per state) [ 471.246050] psci_checker: cpuidle not available on CPU 0, ignoring [ 471.247182] psci_checker: Could not start suspend tests on any CPU [ 471.247893] psci_checker: PSCI checker completed [ 471.331269] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 471.643434] ### dt-test ### start of unittest - you will see error messages [ 471.687097] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 471.688493] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 471.690372] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 471.691463] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 471.692656] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 471.694479] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 471.723733] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 471.727132] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 471.730564] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 472.116067] _warn_unseeded_randomness: 2 callbacks suppressed [ 472.116321] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 472.118558] kobject: 'testcase-data:platform-tests:test-device@1' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 472.143589] kobject: 'testcase-data:platform-tests:test-device@0' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 472.161701] kobject: 'unittest-bus.0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 472.258325] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=0 [ 472.403518] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status [ 472.483954] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status [ 472.509935] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 472.511156] kobject: 'testcase-data:overlay-node:test-bus:test-unittest1' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 472.526143] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status [ 472.603206] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status [ 472.626040] kobject: 'testcase-data:overlay-node:test-bus:test-unittest3' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 472.731469] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status [ 472.822524] kobject: 'testcase-data:overlay-node:test-bus:test-unittest5' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 472.841886] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status [ 472.912734] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status [ 473.004389] kobject: 'testcase-data:overlay-node:test-bus:test-unittest7' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 473.029724] kobject: 'testcase-data:overlay-node:test-bus:test-unittest6' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 473.044531] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status [ 473.115200] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo [ 473.118425] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 473.119658] OF: overlay: overlay #6 is not topmost [ 473.145655] kobject: 'testcase-data:overlay-node:test-bus:test-unittest8' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 473.159308] _warn_unseeded_randomness: 6 callbacks suppressed [ 473.159587] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=0 [ 473.544132] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 473.545874] kobject: 'testcase-data:overlay-node:test-bus:test-unittest11:test-unittest111' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 473.567390] kobject: 'testcase-data:overlay-node:test-bus:test-unittest11' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 473.570632] 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 [ 473.574343] 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 [ 473.578013] OF: overlay: remove: Could not find overlay #7 [ 473.578915] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #7 [ 473.610531] kobject: 'testcase-data:overlay-node:test-bus:test-unittest10:test-unittest101' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 473.632348] kobject: 'testcase-data:overlay-node:test-bus:test-unittest10' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 473.635510] 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 [ 473.639542] 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 [ 473.666683] kobject: 'testcase-data:overlay-node:test-bus:test-unittest4' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 473.669911] 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 [ 473.757735] kobject: 'testcase-data:overlay-node:test-bus:test-unittest2' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 473.766454] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=0 [ 473.846476] kobject: 'testcase-data:overlay-node:test-bus:test-unittest0' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 473.961783] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/status [ 473.969918] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/status [ 473.973770] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@30/incline-up [ 473.975764] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@40/incline-up [ 473.995518] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/status [ 473.998095] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/color [ 473.999720] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/rate [ 474.002876] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/hvac_2 [ 474.005580] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200 [ 474.007547] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_left [ 474.010330] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_right [ 474.187195] OF: overlay: ERROR: multiple fragments add and/or delete node /testcase-data-2/substation@100/motor-1/controller [ 474.194282] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/controller/name [ 474.196902] _warn_unseeded_randomness: 2 callbacks suppressed [ 474.198355] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 474.199311] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 474.202266] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 474.215401] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 474.217963] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 474.220086] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/rpm_avail [ 474.241907] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=0 [ 474.246772] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 474.247768] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 474.250788] ### dt-test ### end of unittest - 217 passed, 0 failed [ 474.307519] uart-pl011 9000000.pl011: no DMA platform data [ 474.855251] kobject: 'test-unittest11' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 474.860229] kobject: 'test-unittest111' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 474.867414] kobject: 'test-unittest10' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 474.910937] VFS: Mounted root (9p filesystem) readonly on device 0:19. [ 474.943272] devtmpfs: mounted [ 474.976144] integrity: Unable to open file: /etc/keys/x509_ima.der (-2) [ 475.007036] integrity: Unable to open file: /etc/keys/x509_evm.der (-2) [ 476.352727] Freeing unused kernel memory: 6144K [ 476.379634] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11/test-unittest111 [ 476.382656] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11 [ 476.870559] Checked W+X mappings: passed, no W+X pages found [ 476.871561] rodata_test: all tests were successful [ 476.872691] Run /sbin/init as init process [ 476.884022] _warn_unseeded_randomness: 5 callbacks suppressed [ 476.884276] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 476.886555] kobject: 'test-unittest101' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 476.890833] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest10 [ 476.910694] kobject: 'test-unittest4' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 476.971629] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=0 [ 477.091675] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=0 [ 478.677571] random: fast init done [ 478.928654] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest4 [ 479.984754] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest10/test-unittest101 SELinux: Could not open policy file <= /etc/selinux/targeted/policy/policy.31: No such file or directory [ 485.692198] systemd[1]: System time before build time, advancing clock. [ 486.484797] systemd[1]: Failed to insert module 'autofs4': No such file or directory [ 491.387509] random: systemd: uninitialized urandom read (16 bytes read) [ 491.935682] random: systemd: uninitialized urandom read (16 bytes read) [ 491.944014] random: systemd: uninitialized urandom read (16 bytes read) [ 500.222076] 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) [ 500.348463] systemd[1]: Detected virtualization qemu. [ 500.359926] systemd[1]: Detected architecture arm64. Welcome to Debian GNU/Linux 9 (stretch)! [ 500.632735] systemd[1]: Set hostname to . [ 500.943551] _warn_unseeded_randomness: 5 callbacks suppressed [ 500.943855] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=1 [ 501.045894] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=1 [ 502.170046] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 503.168339] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 503.171620] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 503.178956] _warn_unseeded_randomness: 1 callbacks suppressed [ 503.179237] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=1 [ 503.183058] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 503.200857] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 504.394917] _warn_unseeded_randomness: 10 callbacks suppressed [ 504.395204] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 504.396990] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 504.398903] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 505.923257] _warn_unseeded_randomness: 12 callbacks suppressed [ 505.923553] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 506.312516] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 506.314459] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 506.975965] random: systemd: uninitialized urandom read (16 bytes read) [ 507.204052] _warn_unseeded_randomness: 5 callbacks suppressed [ 507.204351] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 507.485048] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 507.487586] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 508.564925] _warn_unseeded_randomness: 5 callbacks suppressed [ 508.566485] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 508.870209] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 508.871491] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 510.479828] _warn_unseeded_randomness: 5 callbacks suppressed [ 510.480100] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 510.482433] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 510.535567] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 511.982097] _warn_unseeded_randomness: 3 callbacks suppressed [ 511.982378] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [ 512.027743] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 512.028939] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 514.451920] systemd-hiberna (1276) used greatest stack depth: 8 bytes left [ 517.200912] random: systemd-cryptse: uninitialized urandom read (16 bytes read) [ 518.552218] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 520.612890] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 524.140559] random: systemd: uninitialized urandom read (16 bytes read) [ 524.528760] random: systemd: uninitialized urandom read (16 bytes read) [ 524.535163] random: systemd: uninitialized urandom read (16 bytes read) [ 524.672018] _warn_unseeded_randomness: 3 callbacks suppressed [ 524.672339] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 524.887107] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=1 [ 538.891444] systemd[1]: networking.service: Cannot add dependency job, ignoring: Unit networking.service is masked. [ 539.015302] random: get_random_bytes called from generate_random_uuid+0x20/0x50 with crng_init=1 [ 539.136379] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 539.197942] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 539.254034] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 539.302781] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 539.376900] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 539.436580] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 539.496209] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ OK ] Listening on /dev/initctl Compatibility Named Pipe. [ 539.567328] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ 539.596628] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ OK ] Listening on Journal Audit Socket. [ OK ] Listening on Syslog Socket. [ OK ] Reached target Sockets. [UNSUPP] Starting of Arbitrary Executable Fi…tem Automount Point not supported. [ OK ] Created slice System Slice. [ 540.691824] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 Starting Load Kernel Modules... [ OK ] Created slice system-getty.slice. [ 541.962354] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 541.963602] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 541.964708] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 Mounting Debug File System... [ 543.860392] _warn_unseeded_randomness: 5 callbacks suppressed [ 543.860722] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 544.464773] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 544.466808] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 544.692216] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 544.958093] _warn_unseeded_randomness: 2 callbacks suppressed [ 544.958377] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 544.976080] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 544.992364] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=1 [ 546.824660] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 547.582580] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 547.583885] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 547.736429] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 547.852116] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 547.986007] _warn_unseeded_randomness: 2 callbacks suppressed [ 547.986289] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 547.997074] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 548.030428] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=1 [ 549.359361] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 549.748618] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 549.750682] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 550.186502] systemd[1]: Starting Create Static Device Nodes in /dev... Starting Create Static Device Nodes in /dev... [ 550.546327] _warn_unseeded_randomness: 5 callbacks suppressed [ 550.546647] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 551.626309] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 551.627807] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 551.628959] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 553.243804] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 554.166783] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ 555.210568] _warn_unseeded_randomness: 5 callbacks suppressed [ 555.210850] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 555.212936] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 555.214739] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 556.843577] systemd[1]: Starting Journal Service... Starting Journal Service... [ 557.052163] systemd[1]: Reached target Encrypted Volumes. [ OK ] Reached target Encrypted Volumes. [ 557.284420] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 559.335592] _warn_unseeded_randomness: 3 callbacks suppressed [ 559.335894] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 559.388550] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=1 [ 559.547346] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 560.352700] _warn_unseeded_randomness: 8 callbacks suppressed [ 560.353000] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=1 [ 560.678895] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 560.695975] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 561.642883] systemd[1]: Mounted Debug File System. [ OK ] Mounted Debug File System. [ 561.855514] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 561.957992] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 562.098483] _warn_unseeded_randomness: 2 callbacks suppressed [ 562.098766] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 562.100472] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 562.102238] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 563.204939] systemd[1]: Started Load Kernel Modules. [ OK ] Started Load Kernel Modules. [ 564.262763] _warn_unseeded_randomness: 2 callbacks suppressed [ 564.263066] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 565.819411] systemd[1]: Started Create Static Device Nodes in /dev. [ OK ] Started Create Static Device Nodes in /dev. [ 567.277754] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [ 568.124063] systemd[1]: Starting udev Kernel Device Manager... Starting udev Kernel Device Manager... [ 570.046471] random: get_random_u32 called from bpf_jit_binary_alloc+0xa0/0x110 with crng_init=1 [ 570.182606] systemd[1]: Mounting Configuration File System... Mounting Configuration File System... [ 570.462277] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=1 [ 570.575075] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 572.043874] _warn_unseeded_randomness: 7 callbacks suppressed [ 572.044203] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 573.378904] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 573.380366] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 573.382276] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 573.712902] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 574.463409] _warn_unseeded_randomness: 1 callbacks suppressed [ 574.463695] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 574.482209] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 574.605982] systemd-journald[1295]: /dev/kmsg buffer overrun, some messages lost. [ 575.268079] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=1 [ 576.036504] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 576.038277] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 576.259735] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=1 [ 580.520742] systemd[1]: Started Remount Root and Kernel File Systems. [ OK ] Started Remount Root and Kernel File Systems. [ 583.487368] _warn_unseeded_randomness: 3 callbacks suppressed [ 583.487675] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 585.220258] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 587.572702] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 587.574618] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 587.575726] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 587.826526] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 588.059769] systemd[1]: Reached target Local File Systems (Pre). [ OK ] Reached target Local File Systems (Pre). [ 588.344402] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 590.819518] _warn_unseeded_randomness: 4 callbacks suppressed [ 590.819792] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 590.822327] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 591.154014] systemd[1]: Mounted Configuration File System. [ 591.191660] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=1 [ OK ] Mounted Configuration File System. [ 592.164224] _warn_unseeded_randomness: 2 callbacks suppressed [ 592.164654] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=1 [ 593.173941] systemd[1]: Started Apply Kernel Variables. [ OK ] Started Apply Kernel Variables. [ 597.128140] systemd[1]: Started udev Kernel Device Manager. [ OK ] Started udev Kernel Device Manager. [ 599.078058] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ 599.838928] systemd[1]: Started Load/Save Random Seed. [ OK ] Started Load/Save Random Seed. [* ] (1 of 3) A start job is running for…rnal Service (1min 6s / 30min 17s) [** ] (1 of 3) A start job is running for…rnal Service (1min 6s / 30min 17s) [*** ] (1 of 3) A start job is running for…rnal Service (1min 7s / 30min 17s) [ *** ] (2 of 3) A start job is running for…g all Devices (1min 7s / no limit) [ *** ] (2 of 3) A start job is running for…g all Devices (1min 8s / no limit) [ ***] (2 of 3) A start job is running for…g all Devices (1min 8s / no limit) [ **] (3 of 3) A start job is running for…v-ttyAMA0.device (1min 9s / 30min) [ *] (3 of 3) A start job is running for…v-ttyAMA0.device (1min 9s / 30min) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (1min 10s / 30min) [ ***] (1 of 3) A start job is running for…nal Service (1min 10s / 30min 17s) [ *** ] (1 of 3) A start job is running for…nal Service (1min 11s / 30min 17s) [ *** ] (1 of 3) A start job is running for…nal Service (1min 11s / 30min 17s) [*** ] (2 of 3) A start job is running for… all Devices (1min 12s / no limit) [** ] (2 of 3) A start job is running for… all Devices (1min 12s / no limit) [* ] (2 of 3) A start job is running for… all Devices (1min 13s / no limit) [** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 13s / 30min) [*** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 14s / 30min) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 14s / 30min) [ *** ] (1 of 3) A start job is running for…nal Service (1min 15s / 30min 17s) [ ***] (1 of 3) A start job is running for…nal Service (1min 15s / 30min 17s) [ **] (1 of 3) A start job is running for…nal Service (1min 16s / 30min 17s) [ *] (2 of 3) A start job is running for… all Devices (1min 16s / no limit) [ **] (2 of 3) A start job is running for… all Devices (1min 17s / no limit) [ ***] (2 of 3) A start job is running for… all Devices (1min 17s / no limit) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 18s / 30min) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 18s / 30min) [*** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 19s / 30min) [** ] (1 of 3) A start job is running for…nal Service (1min 19s / 30min 17s) [* ] (1 of 3) A start job is running for…nal Service (1min 20s / 30min 17s) [** ] (1 of 3) A start job is running for…nal Service (1min 20s / 30min 17s) [*** ] (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…-ttyAMA0.device (1min 22s / 30min) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (1min 23s / 30min) [ *] (3 of 3) A start job is running for…-ttyAMA0.device (1min 23s / 30min) [ **] (1 of 3) A start job is running for…nal Service (1min 24s / 30min 17s)[ 623.354298] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. Starting Flush Journal to Persistent Storage... [ 627.096096] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 627.098074] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 627.099136] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ ***] (1 of 3) A start job is running for…tent Storage (1min 29s / 2min 55s)[ 628.717594] _warn_unseeded_randomness: 4 callbacks suppressed [ 628.717868] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [ *** ] (1 of 3) A start job is running for…tent Storage (1min 30s / 2min 55s)[ 629.285090] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 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…-ttyAMA0.device (1min 32s / 30min) [** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 32s / 30min) [*** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 33s / 30min) [ *** ] (1 of 3) A start job is running for…tent Storage (1min 33s / 2min 55s) [ *** ] (1 of 3) A start job is running for…tent Storage (1min 34s / 2min 55s) [ ***] (1 of 3) A start job is running for…tent Storage (1min 34s / 2min 55s) [ **] (2 of 3) A start job is running for… all Devices (1min 35s / no limit) [ *] (2 of 3) A start job is running for… all Devices (1min 36s / no limit) [ **] (2 of 3) A start job is running for… all Devices (1min 37s / no limit) [ ***] (3 of 3) A start job is running for…-ttyAMA0.device (1min 37s / 30min) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 38s / 30min) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 38s / 30min) [*** ] (1 of 3) A start job is running for…tent Storage (1min 39s / 2min 55s) [** ] (1 of 3) A start job is running for…tent Storage (1min 39s / 2min 55s) [* ] (1 of 3) A start job is running for…tent Storage (1min 40s / 2min 55s) [** ] (2 of 3) A start job is running for… all Devices (1min 40s / no limit) [*** ] (2 of 3) A start job is running for… all Devices (1min 41s / no limit) [ *** ] (2 of 3) A start job is running for… all Devices (1min 41s / no limit) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 42s / 30min) [ ***] (3 of 3) A start job is running for…-ttyAMA0.device (1min 42s / 30min) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (1min 43s / 30min) [ *] (1 of 3) A start job is running for…tent Storage (1min 43s / 2min 55s) [ **] (1 of 3) A start job is running for…tent Storage (1min 44s / 2min 55s) [ ***] (1 of 3) A start job is running for…tent Storage (1min 44s / 2min 55s) [ *** ] (2 of 3) A start job is running for… all Devices (1min 45s / no limit) [ *** ] (2 of 3) A start job is running for… all Devices (1min 45s / no limit) [*** ] (2 of 3) A start job is running for… all Devices (1min 47s / no limit) [** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 48s / 30min) [* ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 48s / 30min)[ 647.958265] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 647.959537] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 647.960651] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 49s / 30min) [*** ] (1 of 3) A start job is running for…tent Storage (1min 49s / 2min 55s) [ *** ] (1 of 3) A start job is running for…tent Storage (1min 50s / 2min 55s)[ 649.740836] _warn_unseeded_randomness: 2 callbacks suppressed [ 649.741125] random: get_random_u32 called from shuffle_freelist+0x60/0x200 with crng_init=1 [ *** ] (1 of 3) A start job is running for…tent Storage (1min 50s / 2min 55s) [ ***] (2 of 3) A start job is running for… all Devices (1min 51s / no limit) [ **] (2 of 3) A start job is running for… all Devices (1min 51s / no limit) [ *] (2 of 3) A start job is running for… all Devices (1min 52s / no limit) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (1min 52s / 30min)[ 651.829935] systemd-journald[1295]: Received request to flush runtime journal from PID 1 [ 652.142897] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=1 [ ***] (3 of 3) A start job is running for…-ttyAMA0.device (1min 53s / 30min) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 53s / 30min) [ OK ] Started Flush Journal to Persistent Storage. Starting Create Volatile Files and Directories... [ 659.375333] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 659.376902] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 659.379034] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ *** ] (1 of 3) A start job is running for… and Directories (2min / no limit) [*** ] (1 of 3) A start job is running for…d Directories (2min 1s / no limit) [** ] (1 of 3) A start job is running for…d Directories (2min 1s / no limit) [* ] (2 of 3) A start job is running for…g all Devices (2min 2s / no limit) [** ] (2 of 3) A start job is running for…g all Devices (2min 2s / no limit) [*** ] (2 of 3) A start job is running for…g all Devices (2min 3s / no limit) [ *** ] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 3s / 30min) [ *** ] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 4s / 30min) [ ***] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 4s / 30min)[ 663.821277] random: crng init done [ 663.821970] random: 4 get_random_xx warning(s) missed due to ratelimiting [ **] (1 of 3) A start job is running for…d Directories (2min 5s / no limit) [ *] (1 of 3) A start job is running for…d Directories (2min 5s / no limit) [ **] (1 of 3) A start job is running for…d Directories (2min 6s / no limit) [ ***] (2 of 3) A start job is running for…g all Devices (2min 6s / no limit) [ *** ] (2 of 3) A start job is running for…g all Devices (2min 7s / no limit) [ *** ] (2 of 3) A start job is running for…g all Devices (2min 7s / no limit) [*** ] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 8s / 30min) [** ] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 8s / 30min) [* ] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 9s / 30min) [** ] (1 of 3) A start job is running for…d Directories (2min 9s / no limit) [*** ] (1 of 3) A start job is running for… Directories (2min 10s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (2min 10s / no limit) [ *** ] (2 of 3) A start job is running for… all Devices (2min 11s / no limit) [ ***] (2 of 3) A start job is running for… all Devices (2min 11s / no limit) [ **] (2 of 3) A start job is running for… all Devices (2min 12s / no limit) [ *] (3 of 3) A start job is running for…-ttyAMA0.device (2min 12s / 30min) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (2min 13s / 30min) [ ***] (3 of 3) A start job is running for…-ttyAMA0.device (2min 13s / 30min) [ *** ] (1 of 3) A start job is running for… Directories (2min 14s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (2min 14s / no limit) [*** ] (1 of 3) A start job is running for… Directories (2min 15s / no limit) [** ] (2 of 3) A start job is running for… all Devices (2min 15s / no limit) [* ] (2 of 3) A start job is running for… all Devices (2min 16s / no limit) [** ] (2 of 3) A start job is running for… all Devices (2min 16s / no limit) [*** ] (3 of 3) A start job is running for…-ttyAMA0.device (2min 17s / 30min) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (2min 17s / 30min) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (2min 18s / 30min) [ ***] (1 of 3) A start job is running for… Directories (2min 18s / no limit) [ **] (1 of 3) A start job is running for… Directories (2min 19s / no limit) [ *] (1 of 3) A start job is running for… Directories (2min 19s / no limit) [ **] (2 of 3) A start job is running for… all Devices (2min 20s / no limit) [ ***] (2 of 3) A start job is running for… all Devices (2min 21s / no limit) [ *** ] (2 of 3) A start job is running for… all Devices (2min 21s / no limit) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (2min 22s / 30min) [*** ] (3 of 3) A start job is running for…-ttyAMA0.device (2min 22s / 30min) [** ] (3 of 3) A start job is running for…-ttyAMA0.device (2min 23s / 30min) [* ] (1 of 3) A start job is running for… Directories (2min 23s / no limit) [** ] (1 of 3) A start job is running for… Directories (2min 24s / no limit) [*** ] (1 of 3) A start job is running for… Directories (2min 24s / no limit) [ *** ] (2 of 3) A start job is running for… all Devices (2min 25s / no limit) [ *** ] (2 of 3) A start job is running for… all Devices (2min 25s / no limit) [ ***] (2 of 3) A start job is running for… all Devices (2min 26s / no limit) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (2min 26s / 30min) [ *] (3 of 3) A start job is running for…-ttyAMA0.device (2min 27s / 30min) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (2min 27s / 30min) [ ***] (1 of 3) A start job is running for… Directories (2min 28s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (2min 28s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (2min 29s / no limit) [*** ] (2 of 3) A start job is running for… all Devices (2min 29s / no limit) [** ] (2 of 3) A start job is running for… all Devices (2min 30s / no limit) [* ] (2 of 3) A start job is running for… all Devices (2min 30s / no limit) [** ] (3 of 3) A start job is running for…-ttyAMA0.device (2min 31s / 30min) [*** ] (3 of 3) A start job is running for…-ttyAMA0.device (2min 31s / 30min) [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting Network Time Synchronization... [ *** ] (4 of 4) A start job is running for…-ttyAMA0.device (2min 42s / 30min) [ *** ] (1 of 4) A start job is running for…oot/Shutdown (2min 43s / no limit) [ ***] (1 of 4) A start job is running for…oot/Shutdown (2min 43s / no limit) [ **] (1 of 4) A start job is running for…oot/Shutdown (2min 44s / no limit) [ *] (2 of 4) A start job is running for…hronization (2min 44s / 32min 39s) [ **] (2 of 4) A start job is running for…hronization (2min 45s / 32min 39s) [ ***] (2 of 4) A start job is running for…hronization (2min 45s / 32min 39s) [ *** ] (3 of 4) A start job is running for… all Devices (2min 46s / no limit) [ *** ] (3 of 4) A start job is running for… all Devices (2min 47s / no limit) [*** ] (3 of 4) A start job is running for… all Devices (2min 47s / no limit) [** ] (4 of 4) A start job is running for…-ttyAMA0.device (2min 48s / 30min) [ OK ] Started Update UTMP about System Boot/Shutdown. [* ] (1 of 3) A start job is running for…hronization (2min 54s / 32min 39s) [** ] (1 of 3) A start job is running for…hronization (2min 55s / 32min 39s) [*** ] (2 of 3) A start job is running for… all Devices (2min 55s / 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 56s / no limit) [ ***] (3 of 3) A start job is running for…-ttyAMA0.device (2min 57s / 30min) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (2min 57s / 30min) [ *] (3 of 3) A start job is running for…-ttyAMA0.device (2min 58s / 30min) [ **] (1 of 3) A start job is running for…hronization (2min 58s / 32min 39s) [ ***] (1 of 3) A start job is running for…hronization (2min 59s / 32min 39s) [ *** ] (1 of 3) A start job is running for…hronization (2min 59s / 32min 39s) [ *** ] (2 of 3) A start job is running for…plug all Devices (3min / no limit) [*** ] (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) [* ] (3 of 3) A start job is running for…v-ttyAMA0.device (3min 1s / 30min) [** ] (3 of 3) A start job is running for…v-ttyAMA0.device (3min 2s / 30min) [*** ] (3 of 3) A start job is running for…v-ttyAMA0.device (3min 2s / 30min) [ *** ] (1 of 3) A start job is running for…chronization (3min 3s / 32min 39s) [ *** ] (1 of 3) A start job is running for…chronization (3min 3s / 32min 39s) [ ***] (1 of 3) A start job is running for…chronization (3min 4s / 32min 39s) [ **] (2 of 3) A start job is running for…g all Devices (3min 4s / 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 5s / no limit) [ ***] (3 of 3) A start job is running for…v-ttyAMA0.device (3min 6s / 30min) [ *** ] (3 of 3) A start job is running for…v-ttyAMA0.device (3min 6s / 30min) [ *** ] (3 of 3) A start job is running for…v-ttyAMA0.device (3min 7s / 30min) [*** ] (1 of 3) A start job is running for…chronization (3min 7s / 32min 39s) [** ] (1 of 3) A start job is running for…chronization (3min 8s / 32min 39s) [* ] (1 of 3) A start job is running for…chronization (3min 8s / 32min 39s) [** ] (2 of 3) A start job is running for…g all Devices (3min 9s / no limit) [*** ] (2 of 3) A start job is running for…g all Devices (3min 9s / no limit) [ *** ] (2 of 3) A start job is running for… all Devices (3min 10s / no limit) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (3min 10s / 30min) [ ***] (3 of 3) A start job is running for…-ttyAMA0.device (3min 11s / 30min) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (3min 11s / 30min) [ *] (1 of 3) A start job is running for…hronization (3min 12s / 32min 39s) [ **] (1 of 3) A start job is running for…hronization (3min 12s / 32min 39s) [ OK ] Started Network Time Synchronization. [ OK ] Reached target System Time Synchronized. [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (3min 18s / 30min) [ *** ] (1 of 2) A start job is running for… all Devices (3min 19s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (3min 19s / no limit) [*** ] (1 of 2) A start job is running for… all Devices (3min 20s / no limit) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 20s / 30min) [* ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 21s / 30min) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 21s / 30min) [*** ] (1 of 2) A start job is running for… all Devices (3min 22s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (3min 22s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (3min 23s / no limit) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (3min 23s / 30min) [ **] (2 of 2) A start job is running for…-ttyAMA0.device (3min 24s / 30min) [ *] (2 of 2) A start job is running for…-ttyAMA0.device (3min 24s / 30min) [ **] (1 of 2) A start job is running for… all Devices (3min 25s / no limit) [ ***] (1 of 2) A start job is running for… all Devices (3min 25s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (3min 26s / no limit) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 26s / 30min) [*** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 27s / 30min) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 27s / 30min) [* ] (1 of 2) A start job is running for… all Devices (3min 28s / no limit) [** ] (1 of 2) A start job is running for… all Devices (3min 28s / no limit) [*** ] (1 of 2) A start job is running for… all Devices (3min 29s / no limit) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 29s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 30s / 30min) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (3min 30s / 30min) [ **] (1 of 2) A start job is running for… all Devices (3min 31s / no limit) [ *] (1 of 2) A start job is running for… all Devices (3min 31s / no limit) [ **] (1 of 2) A start job is running for… all Devices (3min 32s / no limit) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (3min 32s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 33s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 33s / 30min) [*** ] (1 of 2) A start job is running for… all Devices (3min 34s / no limit) [** ] (1 of 2) A start job is running for… all Devices (3min 34s / no limit) [* ] (1 of 2) A start job is running for… all Devices (3min 35s / no limit) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 35s / 30min) [*** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 36s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 36s / 30min) [ *** ] (1 of 2) A start job is running for… all Devices (3min 37s / no limit) [ ***] (1 of 2) A start job is running for… all Devices (3min 37s / no limit) [ **] (1 of 2) A start job is running for… all Devices (3min 38s / no limit) [ *] (2 of 2) A start job is running for…-ttyAMA0.device (3min 38s / 30min) [ **] (2 of 2) A start job is running for…-ttyAMA0.device (3min 39s / 30min) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (3min 39s / 30min) [ *** ] (1 of 2) A start job is running for… all Devices (3min 40s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (3min 40s / no limit) [*** ] (1 of 2) A start job is running for… all Devices (3min 41s / no limit) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 41s / 30min) [* ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 42s / 30min) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 42s / 30min) [*** ] (1 of 2) A start job is running for… all Devices (3min 43s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (3min 44s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (3min 45s / no limit) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (3min 46s / 30min) [ **] (2 of 2) A start job is running for…-ttyAMA0.device (3min 46s / 30min) [ *] (2 of 2) A start job is running for…-ttyAMA0.device (3min 47s / 30min) [ **] (1 of 2) A start job is running for… all Devices (3min 47s / no limit) [ ***] (1 of 2) A start job is running for… all Devices (3min 48s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (3min 48s / no limit) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 49s / 30min) [*** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 49s / 30min) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 50s / 30min) [* ] (1 of 2) A start job is running for… all Devices (3min 50s / no limit) [** ] (1 of 2) A start job is running for… all Devices (3min 51s / no limit) [*** ] (1 of 2) A start job is running for… all Devices (3min 51s / no limit) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 52s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 52s / 30min) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (3min 53s / 30min) [ **] (1 of 2) A start job is running for… all Devices (3min 53s / no limit) [ *] (1 of 2) A start job is running for… all Devices (3min 54s / no limit) [ **] (1 of 2) A start job is running for… all Devices (3min 54s / no limit) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (3min 55s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 55s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 56s / 30min) [*** ] (1 of 2) A start job is running for… all Devices (3min 56s / no limit) [** ] (1 of 2) A start job is running for… all Devices (3min 57s / no limit) [* ] (1 of 2) A start job is running for… all Devices (3min 57s / no limit) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 58s / 30min) [*** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 58s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (3min 59s / 30min) [ *** ] (1 of 2) A start job is running for… all Devices (3min 59s / no limit) [ ***] (1 of 2) A start job is running for…plug all Devices (4min / no limit) [ **] (1 of 2) A start job is running for…plug all Devices (4min / no limit) [ *] (2 of 2) A start job is running for…v-ttyAMA0.device (4min 1s / 30min) [ **] (2 of 2) A start job is running for…v-ttyAMA0.device (4min 1s / 30min) [ ***] (2 of 2) A start job is running for…v-ttyAMA0.device (4min 2s / 30min) [ *** ] (1 of 2) A start job is running for…g all Devices (4min 2s / no limit) [ *** ] (1 of 2) A start job is running for…g all Devices (4min 3s / no limit) [*** ] (1 of 2) A start job is running for…g all Devices (4min 3s / no limit) [** ] (2 of 2) A start job is running for…v-ttyAMA0.device (4min 4s / 30min) [* ] (2 of 2) A start job is running for…v-ttyAMA0.device (4min 4s / 30min) [** ] (2 of 2) A start job is running for…v-ttyAMA0.device (4min 5s / 30min) [*** ] (1 of 2) A start job is running for…g all Devices (4min 5s / no limit) [ *** ] (1 of 2) A start job is running for…g all Devices (4min 6s / no limit) [ *** ] (1 of 2) A start job is running for…g all Devices (4min 6s / no limit) [ ***] (2 of 2) A start job is running for…v-ttyAMA0.device (4min 7s / 30min) [ **] (2 of 2) A start job is running for…v-ttyAMA0.device (4min 7s / 30min) [ *] (2 of 2) A start job is running for…v-ttyAMA0.device (4min 8s / 30min) [ **] (1 of 2) A start job is running for…g all Devices (4min 8s / no limit) [ ***] (1 of 2) A start job is running for…g all Devices (4min 9s / no limit) [ *** ] (1 of 2) A start job is running for…g all Devices (4min 9s / no limit) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (4min 10s / 30min) [*** ] (2 of 2) A start job is running for…-ttyAMA0.device (4min 10s / 30min) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (4min 11s / 30min) [* ] (1 of 2) A start job is running for… all Devices (4min 12s / no limit) [** ] (1 of 2) A start job is running for… all Devices (4min 12s / no limit) [*** ] (1 of 2) A start job is running for… all Devices (4min 13s / no limit) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (4min 13s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (4min 14s / 30min) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (4min 14s / 30min) [ **] (1 of 2) A start job is running for… all Devices (4min 15s / no limit) [ *] (1 of 2) A start job is running for… all Devices (4min 15s / no limit) [ **] (1 of 2) A start job is running for… all Devices (4min 16s / no limit) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (4min 16s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (4min 17s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (4min 17s / 30min) [*** ] (1 of 2) A start job is running for… all Devices (4min 18s / no limit) [** ] (1 of 2) A start job is running for… all Devices (4min 18s / no limit) [* ] (1 of 2) A start job is running for… all Devices (4min 19s / no limit) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (4min 19s / 30min) [*** ] (2 of 2) A start job is running for…-ttyAMA0.device (4min 20s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (4min 20s / 30min) [ *** ] (1 of 2) A start job is running for… all Devices (4min 21s / no limit) [ ***] (1 of 2) A start job is running for… all Devices (4min 21s / no limit) [ **] (1 of 2) A start job is running for… all Devices (4min 22s / no limit) [ *] (2 of 2) A start job is running for…-ttyAMA0.device (4min 22s / 30min) [ OK ] Found device /dev/ttyAMA0. [ 807.383490] virtio_net virtio0 enp0s1: renamed from eth0 [ **] A start job is running for udev Col… all Devices (4min 31s / no limit) [ ***] A start job is running for udev Col… all Devices (4min 31s / no limit) [ *** ] A start job is running for udev Col… all Devices (4min 32s / no limit) [ *** ] A start job is running for udev Col… all Devices (4min 32s / no limit) [*** ] A start job is running for udev Col… all Devices (4min 33s / no limit) [** ] A start job is running for udev Col… all Devices (4min 33s / no limit) [* ] A start job is running for udev Col… all Devices (4min 34s / no limit) [** ] A start job is running for udev Col… all Devices (4min 34s / no limit) [*** ] A start job is running for udev Col… all Devices (4min 35s / no limit) [ *** ] A start job is running for udev Col… all Devices (4min 35s / no limit) [ *** ] A start job is running for udev Col… all Devices (4min 36s / no limit) [ ***] A start job is running for udev Col… all Devices (4min 36s / no limit) [ **] A start job is running for udev Col… all Devices (4min 37s / no limit) [ *] A start job is running for udev Col… all Devices (4min 37s / no limit) [ **] A start job is running for udev Col… all Devices (4min 38s / no limit) [ ***] A start job is running for udev Col… all Devices (4min 38s / no limit) [ *** ] A start job is running for udev Col… all Devices (4min 39s / no limit) [ *** ] A start job is running for udev Col… all Devices (4min 39s / no limit) [*** ] A start job is running for udev Col… all Devices (4min 40s / no limit) [** ] A start job is running for udev Col… all Devices (4min 40s / no limit) [* ] A start job is running for udev Col… all Devices (4min 41s / no limit) [** ] A start job is running for udev Col… all Devices (4min 41s / no limit) [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Started Daily apt upgrade and clean activities. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. Starting Permit User Sessions... [ OK ] Started Regular background program processing daemon. Starting getty on tty2-tty6 if dbus and logind are not available... Starting System Logging Service... [ OK ] Started Permit User Sessions. [*** ] (1 of 2) A start job is running for…ging Service (5min 22s / 35min 1s) [ OK ] Started System Logging Service. [ *** ] A start job is running for getty on…ot available (5min 39s / no limit) [ *** ] A start job is running for getty on…ot available (5min 48s / no limit) [ ***] A start job is running for getty on…not available (6min 1s / no limit) [ **] A start job is running for getty on…ot available (6min 12s / no limit) [ *] A start job is running for getty on…ot available (6min 20s / no limit) [ **] A start job is running for getty on…ot available (6min 25s / no limit) [ ***] A start job is running for getty on…ot available (6min 28s / no limit)  Starting Cleanup of Temporary Directories... [ OK ] Started Getty on tty6. [ OK ] Started Getty on tty5. [ OK ] Started Getty on tty4. [ 942.085658] kobject: 'vcs6' (000000006478066b): kobject_release, parent (null) (delayed 500) [ 942.231101] kobject: 'vcsu6' (0000000092d8e31a): kobject_release, parent (null) (delayed 750) [ 942.377782] kobject: 'vcsa6' (000000002ce7d65f): kobject_release, parent (null) (delayed 750) [ OK ] Started Getty on tty3. [ 945.376218] kobject: 'vcs5' (000000001e086c35): kobject_release, parent (null) (delayed 500) [ 945.527422] kobject: 'vcsu5' (00000000bb746717): kobject_release, parent (null) (delayed 1000) [ 945.674222] kobject: 'vcsa5' (0000000063e5fbe4): kobject_release, parent (null) (delayed 1000) [ 948.242780] kobject: 'vcs4' (0000000044db26d2): kobject_release, parent (null) (delayed 500) [ 948.390337] kobject: 'vcsu4' (0000000091516648): kobject_release, parent (null) (delayed 1000) [ 948.551819] kobject: 'vcsa4' (000000003bf84714): kobject_release, parent (null) (delayed 1000) [ OK ] Started Getty on tty2. [ 953.868542] kobject: 'vcs3' (000000009be3cff3): kobject_release, parent (null) (delayed 750) [ 954.011239] kobject: 'vcsu3' (00000000165016a8): kobject_release, parent (null) (delayed 750) [ 954.172372] kobject: 'vcsa3' (00000000be21778e): kobject_release, parent (null) (delayed 1000) [ OK ] Started Serial Getty on ttyAMA0. [ 960.344612] kobject: 'vcs2' (00000000fe3f4c83): kobject_release, parent (null) (delayed 500) [ 960.504490] kobject: 'vcsu2' (000000004b88eb71): kobject_release, parent (null) (delayed 1000) [ 960.658094] kobject: 'vcsa2' (000000001ba04581): kobject_release, parent (null) (delayed 250) [ OK ] Started Getty on tty1. [ OK ] Started getty on tty2-tty6 if dbus and logind are not available. Debian GNU/Linux 9 compilator ttyAMA0 compilator login: [ OK ] Started Cleanup of Temporary Directories. [ 990.580217] systemd[1]: systemd-journald.service: Main process exited, code=killed, status=6/ABRT [ 992.088106] systemd[1]: systemd-journald.service: Unit entered failed state. [ 992.188801] systemd[1]: systemd-journald.service: Failed with result 'watchdog'. [ 993.206510] systemd[1]: systemd-journald.service: Service has no hold-off time, scheduling restart. [ 995.354544] systemd[1]: Stopped Flush Journal to Persistent Storage. [ 995.404285] systemd[1]: Stopping Flush Journal to Persistent Storage... [ 995.421877] systemd[1]: Stopped Journal Service. [ 997.420543] systemd[1]: Starting Journal Service... [ 1003.450564] systemd[1]: Started Update UTMP about System Runlevel Changes. [ 1009.524696] systemd-journald[1356]: File /run/log/journal/ee22564d41614722a231f845c5bb3c72/system.journal corrupted or uncleanly shut down, renaming and replacing. [ 1164.676155] systemd[1]: Started Journal Service. Debian GNU/Linux 9 compilator ttyAMA0 compilator login: Debian GNU/Linux 9 compilator ttyAMA0 compilator login: