[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 4.20.0-rc3-next-20181122-00007-g64e3bffc54df-dirty (anders@compilator) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05)) #53 SMP Tue Nov 27 10:47:17 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] NUMA: No NUMA configuration found [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] NUMA: NODE_DATA [mem 0xbefea5c0-0xbefebfff] [ 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+0xa8/0x498 with crng_init=0 [ 0.000000] percpu: Embedded 490 pages/cpu @(____ptrval____) s1967960 r8192 d30888 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 [ 0.000000] Memory: 999060K/2097152K available (14080K kernel code, 3358K rwdata, 4656K rodata, 4096K init, 21766K bss, 84468K reserved, 16384K cma-reserved) [ 0.000000] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 0.000000] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.000000] ftrace: allocating 41424 entries in 162 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 lockdep checking is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=1. [ 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] kmemleak: Kernel memory leak detector disabled [ 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.000202] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.013292] Console: colour dummy device 80x25 [ 0.014429] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.014888] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.015215] ... MAX_LOCK_DEPTH: 48 [ 0.015550] ... MAX_LOCKDEP_KEYS: 8191 [ 0.015889] ... CLASSHASH_SIZE: 4096 [ 0.016312] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.016649] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.017299] ... CHAINHASH_SIZE: 32768 [ 0.017633] memory used by lock dependency info: 7775 kB [ 0.017979] per task-struct memory footprint: 2688 bytes [ 0.018348] ------------------------ [ 0.018657] | Locking API testsuite: [ 0.018970] ---------------------------------------------------------------------------- [ 0.019356] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.019764] -------------------------------------------------------------------------- [ 0.020642] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.032432] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.043700] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.055971] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.068432] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.081578] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.094942] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.107930] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.118294] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.128169] -------------------------------------------------------------------------- [ 0.128556] recursive read-lock: | ok | | ok | [ 0.132133] recursive read-lock #2: | ok | | ok | [ 0.135487] mixed read-write-lock: | ok | | ok | [ 0.139153] mixed write-read-lock: | ok | | ok | [ 0.142865] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.146106] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.149942] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.153620] -------------------------------------------------------------------------- [ 0.154317] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.159428] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.164548] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.169519] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.174464] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.179533] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.184573] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.189581] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.194664] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.199692] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.204759] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.210046] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.216337] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.221581] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.226856] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.232122] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.237322] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.242483] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.247711] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.252768] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.258003] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.263240] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.268723] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.274051] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.279800] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.285061] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.290255] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.295552] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.300904] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.306169] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.311553] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.317021] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.322381] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.327574] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.332821] hard-irq lock-inversion/123: ok | ok | ok | [ 0.338105] soft-irq lock-inversion/123: ok | ok | ok | [ 0.343349] hard-irq lock-inversion/132: ok | ok | ok | [ 0.348639] soft-irq lock-inversion/132: ok | ok | ok | [ 0.353958] hard-irq lock-inversion/213: ok | ok | ok | [ 0.359282] soft-irq lock-inversion/213: ok | ok | ok | [ 0.364612] hard-irq lock-inversion/231: ok | ok | ok | [ 0.369831] soft-irq lock-inversion/231: ok | ok | ok | [ 0.375098] hard-irq lock-inversion/312: ok | ok | ok | [ 0.380321] soft-irq lock-inversion/312: ok | ok | ok | [ 0.385725] hard-irq lock-inversion/321: ok | ok | ok | [ 0.390946] soft-irq lock-inversion/321: ok | ok | ok | [ 0.396300] hard-irq read-recursion/123: ok | [ 0.398657] soft-irq read-recursion/123: ok | [ 0.400751] hard-irq read-recursion/132: ok | [ 0.403197] soft-irq read-recursion/132: ok | [ 0.405233] hard-irq read-recursion/213: ok | [ 0.407661] soft-irq read-recursion/213: ok | [ 0.410065] hard-irq read-recursion/231: ok | [ 0.412159] soft-irq read-recursion/231: ok | [ 0.414624] hard-irq read-recursion/312: ok | [ 0.416711] soft-irq read-recursion/312: ok | [ 0.419059] hard-irq read-recursion/321: ok | [ 0.421086] soft-irq read-recursion/321: ok | [ 0.423446] -------------------------------------------------------------------------- [ 0.423838] | Wound/wait tests | [ 0.424204] --------------------- [ 0.424505] ww api failures: ok | ok | ok | [ 0.431067] ww contexts mixing: ok | ok | [ 0.434662] finishing ww context: ok | ok | ok | ok | [ 0.441265] locking mismatches: ok | ok | ok | [ 0.446253] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.463540] spinlock nest unlocked: ok | [ 0.465339] ----------------------------------------------------- [ 0.466184] |block | try |context| [ 0.466540] ----------------------------------------------------- [ 0.466893] context: ok | ok | ok | [ 0.472134] try: ok | ok | ok | [ 0.476923] block: ok | ok | ok | [ 0.481731] spinlock: ok | ok | ok | [ 0.487030] ------------------------------------------------------- [ 0.487426] Good, all 261 testcases passed! | [ 0.487775] --------------------------------- [ 0.492459] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.493162] pid_max: default: 32768 minimum: 301 [ 0.503982] LSM: Security Framework initializing [ 0.504669] Yama: becoming mindful. [ 0.507368] LoadPin: ready to pin (currently enforcing) [ 0.508199] AppArmor: AppArmor disabled by boot time parameter [ 0.534044] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.543554] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.546315] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.547311] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.581423] *** VALIDATE proc *** [ 0.599735] *** VALIDATE cgroup1 *** [ 0.600162] *** VALIDATE cgroup2 *** [ 0.662420] ASID allocator initialised with 32768 entries [ 0.668786] rcu: Hierarchical SRCU implementation. [ 0.736509] EFI services will not be available. [ 0.759806] smp: Bringing up secondary CPUs ... [ 0.760711] smp: Brought up 1 node, 1 CPU [ 0.761138] SMP: Total of 1 processors activated. [ 0.761634] CPU features: detected: 32-bit EL0 Support [ 0.762108] CPU features: detected: CRC32 instructions [ 1.044035] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.044806] CPU: All CPU(s) started at EL1 [ 1.045730] alternatives: patching kernel code [ 1.526220] node 0 initialised, 249310 pages in 4ms [ 1.533869] pgdatinit0 (15) used greatest stack depth: 13408 bytes left [ 1.614528] devtmpfs: initialized [ 1.824443] _warn_unseeded_randomness: 81 callbacks suppressed [ 1.824867] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=0 [ 1.825987] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 1.826725] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 1.838097] Registered cp15_barrier emulation handler [ 2.473098] workqueue: round-robin CPU selection forced, expect performance impact [ 8.345762] DMA-API: preallocated 65536 debug entries [ 8.346200] DMA-API: debugging enabled by kernel config [ 8.346674] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 8.348552] futex hash table entries: 256 (order: 3, 32768 bytes) [ 8.350607] Running postponed tracer tests: [ 8.356045] Testing tracer function: [ 18.932077] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 27.454205] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 27.462594] PASSED [ 27.462954] Testing dynamic ftrace: [ 28.510903] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 28.746934] PASSED [ 28.747469] Testing dynamic ftrace ops #1: [ 32.488427] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 32.501864] (1 0 1 0 0) [ 32.502041] (1 1 2 0 0) [ 50.213914] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 50.219736] (2 1 3 0 1066085) [ 50.220077] (2 2 4 0 1066100) [ 60.580678] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 60.758019] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 60.910501] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 60.918354] PASSED [ 60.919672] Testing dynamic ftrace ops #2: [ 64.680222] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 64.843430] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 81.247068] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 81.250895] (1 0 1 1033119 0) [ 81.251186] (1 1 2 1033134 0) [ 81.343168] (2 1 3 1 3732) [ 81.344492] (2 2 4 118 3849) [ 89.837665] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 89.844371] PASSED [ 89.844719] Testing ftrace recursion: [ 90.890373] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 91.042146] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 91.048475] PASSED [ 91.048806] Testing ftrace recursion safe: [ 92.091174] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 92.242403] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 92.249119] PASSED [ 92.249470] Testing ftrace regs(no arch support): [ 93.293605] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 93.444942] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 93.451738] PASSED [ 93.452300] Testing tracer nop: PASSED [ 93.453288] Testing tracer irqsoff: [ 104.486368] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 112.918828] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 112.925809] PASSED [ 112.926435] Testing tracer function_graph: [ 123.303248] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 132.599763] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 132.607614] PASSED [ 132.618341] _warn_unseeded_randomness: 2 callbacks suppressed [ 132.618597] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 132.619761] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 132.621028] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 132.631676] prandom: seed boundary self test passed [ 132.652394] prandom: 100 self tests passed [ 132.656814] pinctrl core: initialized pinctrl subsystem [ 132.730519] regulator-dummy: Failed to create debugfs directory [ 132.736897] [ 132.737219] ************************************************************* [ 132.737618] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 132.738641] ** ** [ 132.739109] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 132.739568] ** ** [ 132.739951] ** This means that this kernel is built to expose internal ** [ 132.740333] ** IOMMU data structures, which may compromise security on ** [ 132.740814] ** your system. ** [ 132.741244] ** ** [ 132.741650] ** If you see this message and you are not debugging the ** [ 132.742472] ** kernel, report this immediately to your vendor! ** [ 132.742927] ** ** [ 132.743327] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 132.743825] ************************************************************* [ 132.761864] DMI not present or invalid. [ 132.794828] NET: Registered protocol family 16 [ 132.815836] audit: initializing netlink subsys (disabled) [ 132.915934] cpuidle: using governor ladder [ 132.916634] cpuidle: using governor menu [ 132.929087] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 132.929900] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 132.934558] audit: type=2000 audit(110.992:1): state=initialized audit_enabled=0 res=1 [ 132.996281] DMA: preallocated 256 KiB pool for atomic allocations [ 133.049694] Serial: AMBA PL011 UART driver [ 133.662288] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 133.665276] printk: console [ttyAMA0] enabled [ 133.665276] printk: console [ttyAMA0] enabled [ 133.666175] printk: bootconsole [pl11] disabled [ 133.666175] printk: bootconsole [pl11] disabled [ 133.753274] _warn_unseeded_randomness: 39 callbacks suppressed [ 133.753461] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 133.755383] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 134.182577] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 134.219424] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 134.362637] arm-v7s io-pgtable: self test ok [ 134.363364] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 134.369790] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 134.373517] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 134.377711] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 134.382087] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 134.385974] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 134.390058] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 134.395830] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 134.402143] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 134.409080] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 134.415966] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 134.422666] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 134.430616] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 134.447128] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 134.463596] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 134.480870] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 134.500437] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 134.519804] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 134.539558] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 134.563298] vgaarb: loaded [ 134.605039] pps_core: LinuxPPS API ver. 1 registered [ 134.605559] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 134.608447] PTP clock support registered [ 134.729753] NetLabel: Initializing [ 134.730259] NetLabel: domain hash size = 128 [ 134.730694] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 134.738303] NetLabel: unlabeled traffic allowed by default [ 134.751871] clocksource: Switched to clocksource arch_sys_counter [ 137.483996] _warn_unseeded_randomness: 39 callbacks suppressed [ 137.484200] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 137.485397] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 137.487267] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 137.496195] VFS: Disk quotas dquot_6.6.0 [ 137.499624] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 137.509032] *** VALIDATE hugetlbfs *** [ 138.153693] NET: Registered protocol family 2 [ 138.186172] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 138.189821] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 138.203719] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 138.212466] TCP: Hash tables configured (established 16384 bind 16384) [ 138.219090] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 138.223187] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 138.233266] NET: Registered protocol family 1 [ 138.302707] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 138.309082] kvm [1]: HYP mode not available [ 138.336435] kworker/u2:1 (34) used greatest stack depth: 11920 bytes left [ 138.513448] Initialise system trusted keyrings [ 138.515337] _warn_unseeded_randomness: 56 callbacks suppressed [ 138.515519] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 138.520053] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 138.526376] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 139.985546] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 139.986405] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 140.002531] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 140.154060] 9p: Installing v9fs 9p2000 file system support [ 140.844624] Key type asymmetric registered [ 140.845785] Asymmetric key parser 'x509' registered [ 140.849798] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 141.255959] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 141.261412] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 141.280373] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 141.326384] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 141.376878] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 141.379285] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 141.381361] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 141.382412] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 141.385988] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 141.399208] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 141.400279] pci_bus 0000:00: root bus resource [bus 00-0f] [ 141.401024] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 141.401687] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 141.402430] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 141.481476] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 141.482454] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 141.484050] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 141.485056] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 141.485983] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 141.486712] pci 0000:00:03.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 141.487976] pci 0000:00:03.0: BAR 0: assigned [io 0x1000-0x103f] [ 141.488769] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 141.489524] pci 0000:00:02.0: BAR 0: assigned [io 0x1060-0x107f] [ 141.949273] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 142.005576] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 142.056005] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 145.992596] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 146.310061] SuperH (H)SCI(F) driver initialized [ 186.377261] _warn_unseeded_randomness: 38 callbacks suppressed [ 186.377465] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 186.442420] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 186.505302] libphy: Fixed MDIO Bus: probed [ 186.921996] ledtrig-cpu: registered to indicate activity on CPUs [ 186.949625] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 186.950524] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 186.988493] ashmem: initialized [ 187.149296] 9pnet: Installing 9P2000 support [ 187.409744] _warn_unseeded_randomness: 5 callbacks suppressed [ 187.409920] random: get_random_bytes called from init_oops_id+0x38/0x60 with crng_init=0 [ 187.453328] registered taskstats version 1 [ 187.453909] Running tests on trace events: [ 187.454419] Testing event initcall_finish: [ 187.458672] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 187.480431] OK [ 187.481023] Testing event initcall_start: OK [ 187.510089] Testing event initcall_level: OK [ 187.543999] Testing event sys_exit: OK [ 187.572002] Testing event sys_enter: OK [ 187.597454] Testing event ipi_exit: OK [ 187.624567] Testing event ipi_entry: OK [ 187.652441] Testing event ipi_raise: OK [ 187.677980] Testing event instruction_emulation: OK [ 187.704827] Testing event kvm_halt_poll_ns: OK [ 187.730197] Testing event kvm_age_page: OK [ 187.756140] Testing event kvm_fpu: OK [ 187.781807] Testing event kvm_mmio: OK [ 187.808429] Testing event kvm_ack_irq: OK [ 187.834217] Testing event kvm_set_irq: OK [ 187.860607] Testing event kvm_vcpu_wakeup: OK [ 187.886148] Testing event kvm_userspace_exit: OK [ 187.912169] Testing event kvm_timer_update_irq: OK [ 187.938116] Testing event kvm_toggle_cache: OK [ 187.964713] Testing event kvm_set_way_flush: OK [ 187.991937] Testing event kvm_test_age_hva: OK [ 188.017411] Testing event kvm_age_hva: OK [ 188.044399] Testing event kvm_set_spte_hva: OK [ 188.070673] Testing event kvm_unmap_hva_range: OK [ 188.097367] Testing event kvm_mmio_emulate: OK [ 188.121554] Testing event kvm_irq_line: OK [ 188.148090] Testing event kvm_access_fault: OK [ 188.176559] Testing event kvm_guest_fault: OK [ 188.201748] Testing event kvm_exit: OK [ 188.228100] Testing event kvm_entry: OK [ 188.254130] Testing event kvm_set_guest_debug: OK [ 188.280626] Testing event kvm_handle_sys_reg: OK [ 188.305874] Testing event trap_reg: OK [ 188.336379] Testing event kvm_arm_set_regset: OK [ 188.361995] Testing event kvm_arm_set_dreg32: OK [ 188.396687] Testing event kvm_arm_clear_debug: OK [ 188.421873] Testing event kvm_arm_setup_debug: OK [ 188.448182] Testing event kvm_hvc_arm64: OK [ 188.474076] Testing event kvm_wfx_arm64: OK [ 188.501854] Testing event vgic_update_irq_pending: OK [ 188.526508] Testing event task_rename: OK [ 188.553501] Testing event task_newtask: OK [ 188.580396] Testing event cpuhp_exit: OK [ 188.608196] Testing event cpuhp_multi_enter: OK [ 188.633879] Testing event cpuhp_enter: OK [ 188.660336] Testing event softirq_raise: OK [ 188.700744] Testing event softirq_exit: OK [ 188.724987] Testing event softirq_entry: OK [ 188.752264] Testing event irq_handler_exit: OK [ 188.778376] Testing event irq_handler_entry: OK [ 188.805844] Testing event signal_deliver: OK [ 188.832164] Testing event signal_generate: OK [ 188.858296] Testing event workqueue_execute_end: OK [ 188.885922] Testing event workqueue_execute_start: OK [ 188.912262] Testing event workqueue_activate_work: OK [ 188.938455] Testing event workqueue_queue_work: OK [ 188.965761] Testing event sched_wake_idle_without_ipi: OK [ 188.992658] Testing event sched_swap_numa: OK [ 189.018205] Testing event sched_stick_numa: OK [ 189.044750] Testing event sched_move_numa: OK [ 189.071963] Testing event sched_process_hang: OK [ 189.097224] Testing event sched_pi_setprio: OK [ 189.121560] Testing event sched_stat_runtime: [ 189.124862] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 189.149823] OK [ 189.150307] Testing event sched_stat_blocked: OK [ 189.177981] Testing event sched_stat_iowait: OK [ 189.203986] Testing event sched_stat_sleep: OK [ 189.230438] Testing event sched_stat_wait: OK [ 189.259965] Testing event sched_process_exec: OK [ 189.285217] Testing event sched_process_fork: OK [ 189.309589] Testing event sched_process_wait: OK [ 189.336164] Testing event sched_wait_task: OK [ 189.364437] Testing event sched_process_exit: OK [ 189.389836] Testing event sched_process_free: OK [ 189.416113] Testing event sched_migrate_task: OK [ 189.441592] Testing event sched_switch: OK [ 189.468338] Testing event sched_wakeup_new: OK [ 189.494564] Testing event sched_wakeup: OK [ 189.521334] Testing event sched_waking: OK [ 189.548503] Testing event sched_kthread_stop_ret: OK [ 189.574225] Testing event sched_kthread_stop: OK [ 189.600975] Testing event lock_acquired: OK [ 189.634258] Testing event lock_contended: OK [ 189.657588] Testing event lock_release: OK [ 189.696731] Testing event lock_acquire: OK [ 189.732145] Testing event console: OK [ 189.756032] Testing event rcu_barrier: OK [ 189.782560] Testing event rcu_torture_read: OK [ 189.809537] Testing event rcu_batch_end: OK [ 189.833709] Testing event rcu_invoke_kfree_callback: OK [ 189.860178] Testing event rcu_invoke_callback: OK [ 189.885987] Testing event rcu_batch_start: OK [ 189.912722] Testing event rcu_kfree_callback: OK [ 189.937808] Testing event rcu_callback: OK [ 189.964603] Testing event rcu_dyntick: OK [ 189.993314] Testing event rcu_fqs: OK [ 190.021538] Testing event rcu_quiescent_state_report: OK [ 190.048080] Testing event rcu_unlock_preempted_task: OK [ 190.073789] Testing event rcu_preempt_task: OK [ 190.097385] Testing event rcu_exp_funnel_lock: OK [ 190.121803] Testing event rcu_exp_grace_period: OK [ 190.152264] Testing event rcu_grace_period_init: OK [ 190.177896] Testing event rcu_future_grace_period: OK [ 190.204194] Testing event rcu_grace_period: OK [ 190.235565] Testing event rcu_utilization: OK [ 190.264686] Testing event swiotlb_bounced: OK [ 190.290568] Testing event tick_stop: OK [ 190.317460] Testing event itimer_expire: OK [ 190.342002] Testing event itimer_state: OK [ 190.368823] Testing event hrtimer_cancel: OK [ 190.396728] Testing event hrtimer_expire_exit: OK [ 190.421763] Testing event hrtimer_expire_entry: OK [ 190.448287] Testing event hrtimer_start: OK [ 190.474109] Testing event hrtimer_init: OK [ 190.500607] Testing event timer_cancel: OK [ 190.528035] Testing event timer_expire_exit: OK [ 190.553314] Testing event timer_expire_entry: OK [ 190.577766] Testing event timer_start: OK [ 190.606322] Testing event timer_init: OK [ 190.634663] Testing event alarmtimer_cancel: OK [ 190.661367] Testing event alarmtimer_start: OK [ 190.685599] Testing event alarmtimer_fired: OK [ 190.711815] Testing event alarmtimer_suspend: OK [ 190.738249] Testing event module_request: OK [ 190.765776] Testing event module_put: OK [ 190.792302] Testing event module_get: OK [ 190.817705] Testing event module_free: OK [ 190.844098] Testing event module_load: OK [ 190.870174] Testing event cgroup_transfer_tasks: OK [ 190.897899] Testing event cgroup_attach_task: OK [ 190.922642] Testing event cgroup_rename: OK [ 190.947899] Testing event cgroup_release: OK [ 190.974193] Testing event cgroup_rmdir: OK [ 191.002148] Testing event cgroup_mkdir: OK [ 191.029567] Testing event cgroup_remount: OK [ 191.053598] Testing event cgroup_destroy_root: OK [ 191.080164] Testing event cgroup_setup_root: OK [ 191.106091] Testing event irq_enable: OK [ 191.144027] Testing event irq_disable: OK [ 191.175743] Testing event ftrace_test_filter: OK [ 191.200123] Testing event dev_pm_qos_remove_request: OK [ 191.225388] Testing event dev_pm_qos_update_request: OK [ 191.252159] Testing event dev_pm_qos_add_request: OK [ 191.278445] Testing event pm_qos_update_flags: OK [ 191.306715] Testing event pm_qos_update_target: OK [ 191.333329] Testing event pm_qos_update_request_timeout: OK [ 191.357615] Testing event pm_qos_remove_request: OK [ 191.384005] Testing event pm_qos_update_request: OK [ 191.410012] Testing event pm_qos_add_request: OK [ 191.436646] Testing event power_domain_target: OK [ 191.461611] Testing event clock_set_rate: OK [ 191.488140] Testing event clock_disable: OK [ 191.513912] Testing event clock_enable: OK [ 191.540124] Testing event wakeup_source_deactivate: OK [ 191.565797] Testing event wakeup_source_activate: OK [ 191.589562] Testing event suspend_resume: OK [ 191.628605] Testing event device_pm_callback_end: OK [ 191.654237] Testing event device_pm_callback_start: OK [ 191.681917] Testing event cpu_frequency_limits: OK [ 191.708181] Testing event cpu_frequency: OK [ 191.734206] Testing event pstate_sample: OK [ 191.760441] Testing event powernv_throttle: OK [ 191.785059] Testing event cpu_idle: OK [ 191.812905] Testing event rpm_return_int: OK [ 191.840705] Testing event rpm_idle: OK [ 191.868557] Testing event rpm_resume: OK [ 191.893594] Testing event rpm_suspend: OK [ 191.920142] Testing event benchmark_event: OK [ 192.046563] Testing event xdp_devmap_xmit: OK [ 192.072733] Testing event xdp_cpumap_enqueue: OK [ 192.097453] Testing event xdp_cpumap_kthread: OK [ 192.124418] Testing event xdp_redirect_map_err: OK [ 192.151853] Testing event xdp_redirect_map: OK [ 192.177398] Testing event xdp_redirect_err: OK [ 192.204309] Testing event xdp_redirect: OK [ 192.230269] Testing event xdp_exception: OK [ 192.260422] Testing event rseq_ip_fixup: OK [ 192.285747] Testing event rseq_update: OK [ 192.312207] Testing event file_check_and_advance_wb_err: OK [ 192.338143] Testing event filemap_set_wb_err: OK [ 192.364711] Testing event mm_filemap_add_to_page_cache: OK [ 192.389836] Testing event mm_filemap_delete_from_page_cache: OK [ 192.416876] Testing event compact_retry: OK [ 192.444503] Testing event skip_task_reaping: OK [ 192.472006] Testing event finish_task_reaping: OK [ 192.496545] Testing event start_task_reaping: OK [ 192.521585] Testing event wake_reaper: OK [ 192.548127] Testing event mark_victim: OK [ 192.574274] Testing event reclaim_retry_zone: OK [ 192.601762] Testing event oom_score_adj_update: OK [ 192.626520] Testing event mm_lru_activate: OK [ 192.651898] Testing event mm_lru_insertion: OK [ 192.678068] Testing event mm_vmscan_inactive_list_is_low: OK [ 192.705903] Testing event mm_vmscan_lru_shrink_active: OK [ 192.732356] Testing event mm_vmscan_lru_shrink_inactive: OK [ 192.757297] Testing event mm_vmscan_writepage: OK [ 192.781631] Testing event mm_vmscan_lru_isolate: OK [ 192.807755] Testing event mm_shrink_slab_end: OK [ 192.833617] Testing event mm_shrink_slab_start: OK [ 192.862042] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 192.888226] Testing event mm_vmscan_memcg_reclaim_end: OK [ 192.914209] Testing event mm_vmscan_direct_reclaim_end: OK [ 192.940635] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 192.965926] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 192.992219] Testing event mm_vmscan_direct_reclaim_begin: OK [ 193.018217] Testing event mm_vmscan_wakeup_kswapd: OK [ 193.045802] Testing event mm_vmscan_kswapd_wake: OK [ 193.070563] Testing event mm_vmscan_kswapd_sleep: OK [ 193.095682] Testing event percpu_destroy_chunk: OK [ 193.120737] Testing event percpu_create_chunk: OK [ 193.145786] Testing event percpu_alloc_percpu_fail: OK [ 193.172183] Testing event percpu_free_percpu: OK [ 193.198507] Testing event percpu_alloc_percpu: OK [ 193.225726] Testing event mm_page_alloc_extfrag: OK [ 193.252455] Testing event mm_page_pcpu_drain: OK [ 193.278103] Testing event mm_page_alloc_zone_locked: OK [ 193.306161] Testing event mm_page_alloc: OK [ 193.331737] Testing event mm_page_free_batched: OK [ 193.357957] Testing event mm_page_free: OK [ 193.386012] Testing event kmem_cache_free: OK [ 193.412277] Testing event kfree: OK [ 193.437705] Testing event kmem_cache_alloc_node: OK [ 193.464216] Testing event kmalloc_node: OK [ 193.491912] Testing event kmem_cache_alloc: OK [ 193.518719] Testing event kmalloc: OK [ 193.545959] Testing event mm_compaction_kcompactd_wake: OK [ 193.572169] Testing event mm_compaction_wakeup_kcompactd: OK [ 193.600587] Testing event mm_compaction_kcompactd_sleep: OK [ 193.625676] Testing event mm_compaction_defer_reset: OK [ 193.652261] Testing event mm_compaction_defer_compaction: OK [ 193.678065] Testing event mm_compaction_deferred: OK [ 193.704664] Testing event mm_compaction_suitable: OK [ 193.731975] Testing event mm_compaction_finished: OK [ 193.757223] Testing event mm_compaction_try_to_compact_pages: OK [ 193.781880] Testing event mm_compaction_end: OK [ 193.808660] Testing event mm_compaction_begin: OK [ 193.834062] Testing event mm_compaction_migratepages: OK [ 193.860755] Testing event mm_compaction_isolate_freepages: OK [ 193.886706] Testing event mm_compaction_isolate_migratepages: OK [ 193.913375] Testing event mm_migrate_pages: OK [ 193.937644] Testing event mm_collapse_huge_page_swapin: OK [ 193.964685] Testing event mm_collapse_huge_page_isolate: OK [ 193.990474] Testing event mm_collapse_huge_page: OK [ 194.017538] Testing event mm_khugepaged_scan_pmd: OK [ 194.044427] Testing event test_pages_isolated: OK [ 194.070686] Testing event cma_release: OK [ 194.097328] Testing event cma_alloc: OK [ 194.121501] Testing event page_ref_unfreeze: OK [ 194.152218] Testing event page_ref_freeze: OK [ 194.180197] Testing event page_ref_mod_unless: OK [ 194.215681] Testing event page_ref_mod_and_return: OK [ 194.240194] Testing event page_ref_mod_and_test: OK [ 194.376334] Testing event page_ref_mod: OK [ 194.434618] Testing event page_ref_set: OK [ 194.466226] Testing event sb_clear_inode_writeback: OK [ 194.489789] Testing event sb_mark_inode_writeback: OK [ 194.516038] Testing event writeback_dirty_inode_enqueue: OK [ 194.542643] Testing event writeback_lazytime_iput: OK [ 194.569286] Testing event writeback_lazytime: OK [ 194.593544] Testing event writeback_single_inode: OK [ 194.618044] Testing event writeback_single_inode_start: OK [ 194.643620] Testing event writeback_wait_iff_congested: OK [ 194.668625] Testing event writeback_congestion_wait: OK [ 194.696616] Testing event writeback_sb_inodes_requeue: OK [ 194.723965] Testing event balance_dirty_pages: OK [ 194.753320] Testing event bdi_dirty_ratelimit: OK [ 194.778139] Testing event global_dirty_state: OK [ 194.804724] Testing event writeback_queue_io: OK [ 194.829637] Testing event wbc_writepage: OK [ 194.856090] Testing event writeback_bdi_register: OK [ 194.882128] Testing event writeback_wake_background: OK [ 194.908697] Testing event writeback_pages_written: OK [ 194.933963] Testing event writeback_wait: OK [ 194.960132] Testing event writeback_written: OK [ 194.988436] Testing event writeback_start: OK [ 195.013577] Testing event writeback_exec: OK [ 195.040207] Testing event writeback_queue: OK [ 195.066076] Testing event writeback_write_inode: OK [ 195.092607] Testing event writeback_write_inode_start: OK [ 195.117928] Testing event writeback_dirty_inode: OK [ 195.144413] Testing event writeback_dirty_inode_start: OK [ 195.170672] Testing event writeback_mark_inode_dirty: OK [ 195.197324] Testing event writeback_dirty_page: OK [ 195.221594] Testing event dax_writeback_one: OK [ 195.248283] Testing event dax_writeback_range_done: OK [ 195.274064] Testing event dax_writeback_range: OK [ 195.300670] Testing event dax_insert_mapping: OK [ 195.325866] Testing event dax_insert_pfn_mkwrite: OK [ 195.352147] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 195.378270] Testing event dax_load_hole: OK [ 195.405778] Testing event dax_pte_fault_done: OK [ 195.430562] Testing event dax_pte_fault: OK [ 195.455896] Testing event dax_pmd_insert_mapping: OK [ 195.480560] Testing event dax_pmd_load_hole_fallback: OK [ 195.506001] Testing event dax_pmd_load_hole: OK [ 195.532156] Testing event dax_pmd_fault_done: OK [ 195.557699] Testing event dax_pmd_fault: OK [ 195.581478] Testing event generic_add_lease: OK [ 195.605585] Testing event time_out_leases: OK [ 195.629611] Testing event generic_delete_lease: OK [ 195.655747] Testing event break_lease_unblock: OK [ 195.681598] Testing event break_lease_block: OK [ 195.710035] Testing event break_lease_noblock: OK [ 195.736307] Testing event flock_lock_inode: OK [ 195.762084] Testing event locks_remove_posix: OK [ 195.788823] Testing event fcntl_setlk: OK [ 195.814490] Testing event posix_lock_inode: OK [ 195.841543] Testing event locks_get_lock_context: OK [ 195.865726] Testing event block_rq_remap: OK [ 195.891900] Testing event block_bio_remap: OK [ 195.918193] Testing event block_split: OK [ 195.946059] Testing event block_unplug: OK [ 195.973653] Testing event block_plug: OK [ 196.000310] Testing event block_sleeprq: OK [ 196.025901] Testing event block_getrq: OK [ 196.052334] Testing event block_bio_queue: OK [ 196.080060] Testing event block_bio_frontmerge: OK [ 196.105254] Testing event block_bio_backmerge: OK [ 196.129734] Testing event block_bio_complete: OK [ 196.155994] Testing event block_bio_bounce: OK [ 196.182049] Testing event block_rq_issue: OK [ 196.210518] Testing event block_rq_insert: OK [ 196.237991] Testing event block_rq_complete: OK [ 196.264139] Testing event block_rq_requeue: OK [ 196.292803] Testing event block_dirty_buffer: OK [ 196.317601] Testing event block_touch_buffer: OK [ 196.344276] Testing event wbt_timer: OK [ 196.370024] Testing event wbt_step: OK [ 196.396656] Testing event wbt_lat: OK [ 196.421714] Testing event wbt_stat: OK [ 196.448208] Testing event gpio_value: OK [ 196.474596] Testing event gpio_direction: OK [ 196.502504] Testing event clk_set_duty_cycle_complete: OK [ 196.529758] Testing event clk_set_duty_cycle: OK [ 196.553621] Testing event clk_set_phase_complete: OK [ 196.577617] Testing event clk_set_phase: OK [ 196.604080] Testing event clk_set_parent_complete: OK [ 196.630331] Testing event clk_set_parent: OK [ 196.657919] Testing event clk_set_rate_complete: OK [ 196.684345] Testing event clk_set_rate: OK [ 196.709941] Testing event clk_unprepare_complete: OK [ 196.736871] Testing event clk_unprepare: OK [ 196.762305] Testing event clk_prepare_complete: OK [ 196.788268] Testing event clk_prepare: OK [ 196.816708] Testing event clk_disable_complete: OK [ 196.841950] Testing event clk_disable: OK [ 196.868221] Testing event clk_enable_complete: OK [ 196.894109] Testing event clk_enable: OK [ 196.920633] Testing event rpmh_send_msg: OK [ 196.945815] Testing event rpmh_tx_done: OK [ 196.972132] Testing event regulator_set_voltage_complete: OK [ 196.998146] Testing event regulator_set_voltage: OK [ 197.024655] Testing event regulator_disable_complete: OK [ 197.049872] Testing event regulator_disable: OK [ 197.076095] Testing event regulator_enable_complete: OK [ 197.101449] Testing event regulator_enable_delay: OK [ 197.125909] Testing event regulator_enable: OK [ 197.152399] Testing event urandom_read: OK [ 197.179936] Testing event random_read: OK [ 197.205460] Testing event extract_entropy_user: OK [ 197.229631] Testing event extract_entropy: OK [ 197.256086] Testing event get_random_bytes_arch: OK [ 197.282633] Testing event get_random_bytes: OK [ 197.309273] Testing event xfer_secondary_pool: OK [ 197.333551] Testing event add_disk_randomness: OK [ 197.357580] Testing event add_input_randomness: OK [ 197.383595] Testing event debit_entropy: OK [ 197.408659] Testing event push_to_pool: OK [ 197.436454] Testing event credit_entropy_bits: OK [ 197.463826] Testing event mix_pool_bytes_nolock: OK [ 197.493186] Testing event mix_pool_bytes: OK [ 197.518285] Testing event add_device_randomness: OK [ 197.545010] Testing event io_page_fault: OK [ 197.569673] Testing event unmap: OK [ 197.596091] Testing event map: OK [ 197.621577] Testing event detach_device_from_domain: OK [ 197.648415] Testing event attach_device_to_domain: OK [ 197.676015] Testing event remove_device_from_group: OK [ 197.701357] Testing event add_device_to_group: OK [ 197.725622] Testing event regcache_drop_region: OK [ 197.752108] Testing event regmap_async_complete_done: OK [ 197.777661] Testing event regmap_async_complete_start: OK [ 197.804360] Testing event regmap_async_io_complete: OK [ 197.830699] Testing event regmap_async_write_start: OK [ 197.857366] Testing event regmap_cache_bypass: OK [ 197.881567] Testing event regmap_cache_only: OK [ 197.907717] Testing event regcache_sync: OK [ 197.934241] Testing event regmap_hw_write_done: OK [ 197.960903] Testing event regmap_hw_write_start: OK [ 197.986359] Testing event regmap_hw_read_done: OK [ 198.013723] Testing event regmap_hw_read_start: OK [ 198.037946] Testing event regmap_reg_read_cache: OK [ 198.064712] Testing event regmap_reg_read: OK [ 198.089784] Testing event regmap_reg_write: OK [ 198.116167] Testing event dma_fence_wait_end: OK [ 198.142119] Testing event dma_fence_wait_start: OK [ 198.168683] Testing event dma_fence_signaled: OK [ 198.194009] Testing event dma_fence_enable_signal: OK [ 198.225429] Testing event dma_fence_destroy: OK [ 198.250259] Testing event dma_fence_init: OK [ 198.276703] Testing event dma_fence_emit: OK [ 198.301373] Testing event sync_timeline: OK [ 198.325539] Testing event spi_transfer_stop: OK [ 198.351907] Testing event spi_transfer_start: OK [ 198.378040] Testing event spi_message_done: OK [ 198.405982] Testing event spi_message_start: OK [ 198.433391] Testing event spi_message_submit: OK [ 198.460350] Testing event spi_controller_busy: OK [ 198.486373] Testing event spi_controller_idle: OK [ 198.514643] Testing event mdio_access: OK [ 198.541451] Testing event rtc_timer_fired: OK [ 198.565503] Testing event rtc_timer_dequeue: OK [ 198.591997] Testing event rtc_timer_enqueue: OK [ 198.618450] Testing event rtc_read_offset: OK [ 198.647948] Testing event rtc_set_offset: OK [ 198.673222] Testing event rtc_alarm_irq_enable: OK [ 198.697799] Testing event rtc_irq_set_state: OK [ 198.723820] Testing event rtc_irq_set_freq: OK [ 198.750254] Testing event rtc_read_alarm: OK [ 198.777857] Testing event rtc_set_alarm: OK [ 198.804090] Testing event rtc_read_time: OK [ 198.829297] Testing event rtc_set_time: OK [ 198.853584] Testing event memory_failure_event: OK [ 198.879597] Testing event aer_event: OK [ 198.904684] Testing event non_standard_event: OK [ 198.929853] Testing event arm_event: OK [ 198.956071] Testing event mc_event: OK [ 198.981411] Testing event binder_return: OK [ 199.008389] Testing event binder_command: OK [ 199.036000] Testing event binder_unmap_kernel_end: OK [ 199.061307] Testing event binder_unmap_kernel_start: OK [ 199.085712] Testing event binder_unmap_user_end: OK [ 199.110746] Testing event binder_unmap_user_start: OK [ 199.136735] Testing event binder_alloc_page_end: OK [ 199.164368] Testing event binder_alloc_page_start: OK [ 199.189985] Testing event binder_free_lru_end: OK [ 199.216242] Testing event binder_free_lru_start: OK [ 199.241306] Testing event binder_alloc_lru_end: OK [ 199.265623] Testing event binder_alloc_lru_start: OK [ 199.291636] Testing event binder_update_page_range: OK [ 199.316779] Testing event binder_transaction_failed_buffer_release: OK [ 199.341800] Testing event binder_transaction_buffer_release: OK [ 199.368165] Testing event binder_transaction_alloc_buf: OK [ 199.394276] Testing event binder_transaction_fd_recv: OK [ 199.421876] Testing event binder_transaction_fd_send: OK [ 199.446591] Testing event binder_transaction_ref_to_ref: OK [ 199.471984] Testing event binder_transaction_ref_to_node: OK [ 199.498117] Testing event binder_transaction_node_to_ref: OK [ 199.525881] Testing event binder_transaction_received: OK [ 199.552362] Testing event binder_transaction: OK [ 199.577864] Testing event binder_wait_for_work: OK [ 199.604197] Testing event binder_read_done: OK [ 199.630234] Testing event binder_write_done: OK [ 199.656649] Testing event binder_ioctl_done: OK [ 199.681860] Testing event binder_unlock: OK [ 199.707999] Testing event binder_locked: OK [ 199.734472] Testing event binder_lock: OK [ 199.761322] Testing event binder_ioctl: OK [ 199.785609] Testing event br_fdb_update: OK [ 199.812079] Testing event fdb_delete: OK [ 199.838436] Testing event br_fdb_external_learn_add: OK [ 199.865342] Testing event br_fdb_add: OK [ 199.890541] Testing event qdisc_dequeue: OK [ 199.915690] Testing event fib_table_lookup: OK [ 199.942578] Testing event tcp_probe: OK [ 199.970639] Testing event tcp_retransmit_synack: OK [ 199.997346] Testing event tcp_rcv_space_adjust: OK [ 200.021597] Testing event tcp_destroy_sock: OK [ 200.048105] Testing event tcp_receive_reset: OK [ 200.074648] Testing event tcp_send_reset: OK [ 200.101332] Testing event tcp_retransmit_skb: OK [ 200.125558] Testing event udp_fail_queue_rcv_skb: OK [ 200.151942] Testing event inet_sock_set_state: OK [ 200.178191] Testing event sock_exceed_buf_limit: OK [ 200.206558] Testing event sock_rcvqueue_full: OK [ 200.233294] Testing event napi_poll: OK [ 200.260574] Testing event netif_rx_ni_entry: OK [ 200.286057] Testing event netif_rx_entry: OK [ 200.312698] Testing event netif_receive_skb_list_entry: OK [ 200.338695] Testing event netif_receive_skb_entry: OK [ 200.365406] Testing event napi_gro_receive_entry: OK [ 200.389564] Testing event napi_gro_frags_entry: OK [ 200.416119] Testing event netif_rx: OK [ 200.442036] Testing event netif_receive_skb: OK [ 200.468693] Testing event net_dev_queue: OK [ 200.493956] Testing event net_dev_xmit: OK [ 200.520260] Testing event net_dev_start_xmit: OK [ 200.547981] Testing event skb_copy_datagram_iovec: OK [ 200.573278] Testing event consume_skb: OK [ 200.600455] Testing event kfree_skb: OK [ 200.626176] Testing event 9p_protocol_dump: OK [ 200.659818] Testing event 9p_client_res: OK [ 200.685562] Testing event 9p_client_req: OK [ 200.712399] Running tests on trace event systems: [ 200.712902] Testing event system 9p: OK [ 200.748584] Testing event system skb: OK [ 200.778386] Testing event system net: OK [ 200.815560] Testing event system napi: OK [ 200.844142] Testing event system sock: OK [ 200.874720] Testing event system udp: OK [ 200.902435] Testing event system tcp: OK [ 200.934504] Testing event system fib: OK [ 200.962307] Testing event system qdisc: OK [ 200.990510] Testing event system bridge: OK [ 201.020388] Testing event system binder: OK [ 201.066464] Testing event system ras: OK [ 201.095928] Testing event system rtc: OK [ 201.137378] Testing event system mdio: OK [ 201.164278] Testing event system spi: OK [ 201.197824] Testing event system sync_trace: OK [ 201.225798] Testing event system dma_fence: OK [ 201.259436] Testing event system regmap: OK [ 201.296200] Testing event system iommu: OK [ 201.329708] Testing event system random: OK [ 201.367670] Testing event system regulator: OK [ 201.398165] Testing event system rpmh: OK [ 201.426434] Testing event system clk: OK [ 201.468275] Testing event system gpio: OK [ 201.506191] Testing event system wbt: OK [ 201.535825] Testing event system block: OK [ 201.576310] Testing event system filelock: OK [ 201.609652] Testing event system fs_dax: OK [ 201.645444] Testing event system writeback: OK [ 201.693560] Testing event system page_ref: OK [ 201.883840] Testing event system cma: OK [ 201.916502] Testing event system page_isolation: OK [ 201.942166] Testing event system huge_memory: OK [ 201.973177] Testing event system migrate: OK [ 201.999688] Testing event system compaction: OK [ 202.036078] Testing event system kmem: OK [ 202.073509] Testing event system percpu: OK [ 202.104437] Testing event system vmscan: OK [ 202.145357] Testing event system pagemap: OK [ 202.180872] Testing event system oom: OK [ 202.217642] Testing event system filemap: OK [ 202.249221] Testing event system rseq: OK [ 202.280093] Testing event system xdp: OK [ 202.314000] Testing event system benchmark: OK [ 202.441928] Testing event system rpm: OK [ 202.472596] Testing event system power: OK [ 202.527607] Testing event system test: OK [ 202.553254] Testing event system preemptirq: OK [ 202.608377] Testing event system cgroup: OK [ 202.644794] Testing event system module: OK [ 202.673184] Testing event system alarmtimer: OK [ 202.704698] Testing event system timer: OK [ 202.745890] Testing event system swiotlb: OK [ 202.772729] Testing event system rcu: OK [ 202.828137] Testing event system printk: OK [ 202.854327] Testing event system lock: OK [ 202.920451] Testing event system sched: OK [ 202.965501] Testing event system workqueue: OK [ 202.999668] Testing event system signal: OK [ 203.029091] Testing event system irq: OK [ 203.060721] Testing event system cpuhp: OK [ 203.092486] Testing event system task: OK [ 203.119858] Testing event system kvm: OK [ 203.169817] Testing event system emulation: OK [ 203.197720] Testing event system ipi: OK [ 203.233284] Testing event system raw_syscalls: OK [ 203.260995] Testing event system initcall: OK [ 203.292384] Running tests on all trace events: [ 203.292827] Testing all events: OK [ 271.144211] hrtimer: interrupt took 16129888 ns [ 369.508377] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 369.530814] Running tests again, along with the function tracer [ 369.552932] Running tests on trace events: [ 369.558836] Testing event initcall_finish: OK [ 370.165723] Testing event initcall_start: OK [ 370.726802] Testing event initcall_level: OK [ 371.340039] Testing event sys_exit: OK [ 371.986681] Testing event sys_enter: OK [ 372.798840] Testing event ipi_exit: OK [ 373.414696] Testing event ipi_entry: OK [ 374.417723] Testing event ipi_raise: OK [ 374.790049] Testing event instruction_emulation: OK [ 375.350372] Testing event kvm_halt_poll_ns: OK [ 375.922618] Testing event kvm_age_page: OK [ 376.657344] Testing event kvm_fpu: OK [ 377.328796] Testing event kvm_mmio: OK [ 377.769238] Testing event kvm_ack_irq: OK [ 378.278639] Testing event kvm_set_irq: OK [ 378.821251] Testing event kvm_vcpu_wakeup: OK [ 379.490600] Testing event kvm_userspace_exit: OK [ 380.178335] Testing event kvm_timer_update_irq: OK [ 380.743722] Testing event kvm_toggle_cache: OK [ 381.374835] Testing event kvm_set_way_flush: OK [ 382.178819] Testing event kvm_test_age_hva: OK [ 383.002776] Testing event kvm_age_hva: OK [ 383.582794] Testing event kvm_set_spte_hva: OK [ 384.121390] Testing event kvm_unmap_hva_range: OK [ 384.615861] Testing event kvm_mmio_emulate: OK [ 385.402456] Testing event kvm_irq_line: OK [ 386.010769] Testing event kvm_access_fault: OK [ 386.556316] Testing event kvm_guest_fault: OK [ 387.151835] Testing event kvm_exit: OK [ 387.721566] Testing event kvm_entry: OK [ 388.551749] Testing event kvm_set_guest_debug: OK [ 389.338657] Testing event kvm_handle_sys_reg: OK [ 389.948975] Testing event trap_reg: OK [ 390.518738] Testing event kvm_arm_set_regset: OK [ 391.200428] Testing event kvm_arm_set_dreg32: OK [ 391.855795] Testing event kvm_arm_clear_debug: OK [ 392.470780] Testing event kvm_arm_setup_debug: OK [ 393.147888] Testing event kvm_hvc_arm64: OK [ 393.798389] Testing event kvm_wfx_arm64: OK [ 394.396132] Testing event vgic_update_irq_pending: OK [ 394.847772] Testing event task_rename: OK [ 395.477600] Testing event task_newtask: OK [ 396.146698] Testing event cpuhp_exit: OK [ 397.124604] Testing event cpuhp_multi_enter: OK [ 397.735905] Testing event cpuhp_enter: OK [ 398.414773] Testing event softirq_raise: OK [ 399.034629] Testing event softirq_exit: OK [ 399.730786] Testing event softirq_entry: OK [ 400.502591] Testing event irq_handler_exit: OK [ 401.234807] Testing event irq_handler_entry: OK [ 401.777276] Testing event signal_deliver: OK [ 402.401542] Testing event signal_generate: OK [ 403.018318] Testing event workqueue_execute_end: OK [ 403.782683] Testing event workqueue_execute_start: OK [ 404.624055] Testing event workqueue_activate_work: OK [ 405.090816] Testing event workqueue_queue_work: OK [ 405.643938] Testing event sched_wake_idle_without_ipi: OK [ 406.280943] Testing event sched_swap_numa: OK [ 406.764878] Testing event sched_stick_numa: OK [ 407.240180] Testing event sched_move_numa: OK [ 407.831876] Testing event sched_process_hang: OK [ 408.408112] Testing event sched_pi_setprio: OK [ 409.043969] Testing event sched_stat_runtime: OK [ 409.622489] Testing event sched_stat_blocked: OK [ 410.652385] Testing event sched_stat_iowait: OK [ 411.539771] Testing event sched_stat_sleep: OK [ 412.165650] Testing event sched_stat_wait: OK [ 412.858827] Testing event sched_process_exec: OK [ 413.390715] Testing event sched_process_fork: OK [ 414.254533] Testing event sched_process_wait: OK [ 414.997337] Testing event sched_wait_task: OK [ 415.667746] Testing event sched_process_exit: OK [ 416.470752] Testing event sched_process_free: OK [ 417.110626] Testing event sched_migrate_task: OK [ 417.774760] Testing event sched_switch: OK [ 418.354611] Testing event sched_wakeup_new: OK [ 418.942441] Testing event sched_wakeup: OK [ 419.620052] Testing event sched_waking: OK [ 420.178502] Testing event sched_kthread_stop_ret: OK [ 420.952433] Testing event sched_kthread_stop: OK [ 421.452702] Testing event lock_acquired: OK [ 422.032094] Testing event lock_contended: OK [ 422.914828] Testing event lock_release: OK [ 423.594760] Testing event lock_acquire: OK [ 424.204152] Testing event console: OK [ 424.914761] Testing event rcu_barrier: OK [ 425.374640] Testing event rcu_torture_read: OK [ 425.954811] Testing event rcu_batch_end: OK [ 426.625419] Testing event rcu_invoke_kfree_callback: OK [ 427.155763] Testing event rcu_invoke_callback: OK [ 427.560008] Testing event rcu_batch_start: OK [ 428.069509] Testing event rcu_kfree_callback: OK [ 428.779698] Testing event rcu_callback: OK [ 429.336901] Testing event rcu_dyntick: OK [ 429.950235] Testing event rcu_fqs: OK [ 430.446355] Testing event rcu_quiescent_state_report: OK [ 431.080516] Testing event rcu_unlock_preempted_task: OK [ 431.640991] Testing event rcu_preempt_task: OK [ 432.442221] Testing event rcu_exp_funnel_lock: OK [ 433.260198] Testing event rcu_exp_grace_period: OK [ 434.130478] Testing event rcu_grace_period_init: OK [ 434.864373] Testing event rcu_future_grace_period: OK [ 435.662775] Testing event rcu_grace_period: OK [ 436.200307] Testing event rcu_utilization: OK [ 436.874667] Testing event swiotlb_bounced: OK [ 437.816096] Testing event tick_stop: OK [ 438.670621] Testing event itimer_expire: OK [ 439.366500] Testing event itimer_state: OK [ 439.986839] Testing event hrtimer_cancel: OK [ 440.786788] Testing event hrtimer_expire_exit: OK [ 441.367817] Testing event hrtimer_expire_entry: OK [ 442.006585] Testing event hrtimer_start: OK [ 442.534616] Testing event hrtimer_init: OK [ 443.115749] Testing event timer_cancel: OK [ 443.882425] Testing event timer_expire_exit: OK [ 444.850742] Testing event timer_expire_entry: OK [ 445.458786] Testing event timer_start: OK [ 446.235954] Testing event timer_init: OK [ 446.766185] Testing event alarmtimer_cancel: OK [ 447.475727] Testing event alarmtimer_start: OK [ 448.107964] Testing event alarmtimer_fired: OK [ 448.803808] Testing event alarmtimer_suspend: OK [ 449.430564] Testing event module_request: OK [ 449.829546] Testing event module_put: OK [ 450.519922] Testing event module_get: OK [ 451.063720] Testing event module_free: OK [ 451.560295] Testing event module_load: OK [ 452.184253] Testing event cgroup_transfer_tasks: OK [ 452.774686] Testing event cgroup_attach_task: OK [ 453.177365] Testing event cgroup_rename: OK [ 453.770744] Testing event cgroup_release: OK [ 454.416915] Testing event cgroup_rmdir: OK [ 455.194341] Testing event cgroup_mkdir: OK [ 455.630286] Testing event cgroup_remount: OK [ 456.162706] Testing event cgroup_destroy_root: OK [ 456.922310] Testing event cgroup_setup_root: OK [ 457.618837] Testing event irq_enable: OK [ 458.594557] Testing event irq_disable: OK [ 459.710468] Testing event ftrace_test_filter: OK [ 460.271727] Testing event dev_pm_qos_remove_request: OK [ 460.814558] Testing event dev_pm_qos_update_request: OK [ 461.798747] Testing event dev_pm_qos_add_request: OK [ 462.427889] Testing event pm_qos_update_flags: OK [ 463.216327] Testing event pm_qos_update_target: OK [ 463.866426] Testing event pm_qos_update_request_timeout: OK [ 464.551937] Testing event pm_qos_remove_request: OK [ 464.912643] Testing event pm_qos_update_request: OK [ 465.727899] Testing event pm_qos_add_request: OK [ 466.326375] Testing event power_domain_target: OK [ 467.046504] Testing event clock_set_rate: OK [ 467.818351] Testing event clock_disable: OK [ 468.442358] Testing event clock_enable: OK [ 469.178473] Testing event wakeup_source_deactivate: OK [ 469.812231] Testing event wakeup_source_activate: OK [ 470.678685] Testing event suspend_resume: OK [ 471.718801] Testing event device_pm_callback_end: OK [ 472.486831] Testing event device_pm_callback_start: OK [ 473.116381] Testing event cpu_frequency_limits: OK [ 473.798787] Testing event cpu_frequency: OK [ 474.458430] Testing event pstate_sample: OK [ 474.981271] Testing event powernv_throttle: OK [ 475.742533] Testing event cpu_idle: OK [ 476.626829] Testing event rpm_return_int: OK [ 477.138820] Testing event rpm_idle: OK [ 477.896397] Testing event rpm_resume: OK [ 478.543749] Testing event rpm_suspend: OK [ 479.262531] Testing event benchmark_event: OK [ 480.556496] Testing event xdp_devmap_xmit: OK [ 481.266743] Testing event xdp_cpumap_enqueue: OK [ 481.920922] Testing event xdp_cpumap_kthread: OK [ 482.498279] Testing event xdp_redirect_map_err: OK [ 482.843715] Testing event xdp_redirect_map: OK [ 483.393388] Testing event xdp_redirect_err: OK [ 484.074823] Testing event xdp_redirect: OK [ 484.625394] Testing event xdp_exception: OK [ 485.306204] Testing event rseq_ip_fixup: OK [ 485.971815] Testing event rseq_update: OK [ 486.530616] Testing event file_check_and_advance_wb_err: OK [ 486.979961] Testing event filemap_set_wb_err: OK [ 487.850726] Testing event mm_filemap_add_to_page_cache: OK [ 488.815875] Testing event mm_filemap_delete_from_page_cache: OK [ 489.449150] Testing event compact_retry: OK [ 490.019960] Testing event skip_task_reaping: OK [ 490.795894] Testing event finish_task_reaping: OK [ 491.384321] Testing event start_task_reaping: OK [ 492.408094] Testing event wake_reaper: OK [ 493.211791] Testing event mark_victim: OK [ 493.666782] Testing event reclaim_retry_zone: OK [ 494.262612] Testing event oom_score_adj_update: OK [ 494.862679] Testing event mm_lru_activate: OK [ 495.499814] Testing event mm_lru_insertion: OK [ 496.343965] Testing event mm_vmscan_inactive_list_is_low: OK [ 497.106568] Testing event mm_vmscan_lru_shrink_active: OK [ 497.578621] Testing event mm_vmscan_lru_shrink_inactive: OK [ 498.189078] Testing event mm_vmscan_writepage: OK [ 498.762634] Testing event mm_vmscan_lru_isolate: OK [ 499.446790] Testing event mm_shrink_slab_end: OK [ 500.163771] Testing event mm_shrink_slab_start: OK [ 500.710644] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 501.241551] Testing event mm_vmscan_memcg_reclaim_end: OK [ 502.512761] Testing event mm_vmscan_direct_reclaim_end: OK [ 503.118503] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 503.789540] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 504.735732] Testing event mm_vmscan_direct_reclaim_begin: OK [ 505.498773] Testing event mm_vmscan_wakeup_kswapd: OK [ 506.032998] Testing event mm_vmscan_kswapd_wake: OK [ 506.888180] Testing event mm_vmscan_kswapd_sleep: OK [ 507.535835] Testing event percpu_destroy_chunk: OK [ 508.258513] Testing event percpu_create_chunk: OK [ 508.838568] Testing event percpu_alloc_percpu_fail: OK [ 509.535744] Testing event percpu_free_percpu: OK [ 510.262790] Testing event percpu_alloc_percpu: OK [ 510.758417] Testing event mm_page_alloc_extfrag: OK [ 511.548017] Testing event mm_page_pcpu_drain: OK [ 512.068232] Testing event mm_page_alloc_zone_locked: OK [ 512.656046] Testing event mm_page_alloc: OK [ 513.378527] Testing event mm_page_free_batched: OK [ 513.876613] Testing event mm_page_free: OK [ 514.523678] Testing event kmem_cache_free: OK [ 515.169071] Testing event kfree: OK [ 515.633396] Testing event kmem_cache_alloc_node: OK [ 516.264102] Testing event kmalloc_node: OK [ 517.014688] Testing event kmem_cache_alloc: OK [ 517.654461] Testing event kmalloc: OK [ 518.166548] Testing event mm_compaction_kcompactd_wake: OK [ 518.550330] Testing event mm_compaction_wakeup_kcompactd: OK [ 519.007898] Testing event mm_compaction_kcompactd_sleep: OK [ 519.573296] Testing event mm_compaction_defer_reset: OK [ 520.124890] Testing event mm_compaction_defer_compaction: OK [ 520.796115] Testing event mm_compaction_deferred: OK [ 521.606553] Testing event mm_compaction_suitable: OK [ 522.062643] Testing event mm_compaction_finished: OK [ 522.500190] Testing event mm_compaction_try_to_compact_pages: OK [ 523.509385] Testing event mm_compaction_end: OK [ 524.251748] Testing event mm_compaction_begin: OK [ 524.689792] Testing event mm_compaction_migratepages: OK [ 525.204773] Testing event mm_compaction_isolate_freepages: OK [ 525.970099] Testing event mm_compaction_isolate_migratepages: OK [ 526.408118] Testing event mm_migrate_pages: OK [ 527.118640] Testing event mm_collapse_huge_page_swapin: OK [ 527.816930] Testing event mm_collapse_huge_page_isolate: OK [ 528.436752] Testing event mm_collapse_huge_page: OK [ 529.031768] Testing event mm_khugepaged_scan_pmd: OK [ 529.698448] Testing event test_pages_isolated: OK [ 530.199816] Testing event cma_release: OK [ 530.978777] Testing event cma_alloc: OK [ 531.486560] Testing event page_ref_unfreeze: OK [ 532.242611] Testing event page_ref_freeze: OK [ 533.085754] Testing event page_ref_mod_unless: OK [ 533.891808] Testing event page_ref_mod_and_return: OK [ 534.818741] Testing event page_ref_mod_and_test: OK [ 536.983782] Testing event page_ref_mod: OK [ 538.186767] Testing event page_ref_set: OK [ 539.118803] Testing event sb_clear_inode_writeback: OK [ 539.816734] Testing event sb_mark_inode_writeback: OK [ 540.504362] Testing event writeback_dirty_inode_enqueue: OK [ 541.208301] Testing event writeback_lazytime_iput: OK [ 541.754346] Testing event writeback_lazytime: OK [ 542.410827] Testing event writeback_single_inode: OK [ 542.984890] Testing event writeback_single_inode_start: OK [ 543.597020] Testing event writeback_wait_iff_congested: OK [ 544.165402] Testing event writeback_congestion_wait: OK [ 544.625147] Testing event writeback_sb_inodes_requeue: OK [ 544.994608] Testing event balance_dirty_pages: OK [ 545.523981] Testing event bdi_dirty_ratelimit: OK [ 546.347718] Testing event global_dirty_state: OK [ 546.990744] Testing event writeback_queue_io: OK [ 547.742504] Testing event wbc_writepage: OK [ 548.427898] Testing event writeback_bdi_register: OK [ 548.980231] Testing event writeback_wake_background: OK [ 549.582618] Testing event writeback_pages_written: OK [ 550.307803] Testing event writeback_wait: OK [ 550.993410] Testing event writeback_written: OK [ 551.570644] Testing event writeback_start: OK [ 552.178698] Testing event writeback_exec: OK [ 552.926593] Testing event writeback_queue: OK [ 553.574737] Testing event writeback_write_inode: OK [ 554.226776] Testing event writeback_write_inode_start: OK [ 554.702816] Testing event writeback_dirty_inode: OK [ 555.254587] Testing event writeback_dirty_inode_start: OK [ 555.932217] Testing event writeback_mark_inode_dirty: OK [ 556.599780] Testing event writeback_dirty_page: OK [ 557.078802] Testing event dax_writeback_one: OK [ 557.818845] Testing event dax_writeback_range_done: OK [ 558.664905] Testing event dax_writeback_range: OK [ 559.204308] Testing event dax_insert_mapping: OK [ 559.742583] Testing event dax_insert_pfn_mkwrite: OK [ 560.532354] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 561.450385] Testing event dax_load_hole: OK [ 562.102874] Testing event dax_pte_fault_done: OK [ 562.756351] Testing event dax_pte_fault: OK [ 563.378777] Testing event dax_pmd_insert_mapping: OK [ 564.382631] Testing event dax_pmd_load_hole_fallback: OK [ 564.855830] Testing event dax_pmd_load_hole: OK [ 565.644047] Testing event dax_pmd_fault_done: OK [ 566.180483] Testing event dax_pmd_fault: OK [ 566.748595] Testing event generic_add_lease: OK [ 567.400381] Testing event time_out_leases: OK [ 567.993563] Testing event generic_delete_lease: OK [ 568.710698] Testing event break_lease_unblock: OK [ 569.402684] Testing event break_lease_block: OK [ 570.272319] Testing event break_lease_noblock: OK [ 570.937108] Testing event flock_lock_inode: OK [ 571.494522] Testing event locks_remove_posix: OK [ 572.192465] Testing event fcntl_setlk: OK [ 572.540747] Testing event posix_lock_inode: OK [ 573.346612] Testing event locks_get_lock_context: OK [ 574.192684] Testing event block_rq_remap: OK [ 574.874722] Testing event block_bio_remap: OK [ 575.378466] Testing event block_split: OK [ 576.099986] Testing event block_unplug: OK [ 576.848080] Testing event block_plug: OK [ 577.529486] Testing event block_sleeprq: OK [ 578.093575] Testing event block_getrq: OK [ 578.793059] Testing event block_bio_queue: OK [ 579.458513] Testing event block_bio_frontmerge: OK [ 580.372752] Testing event block_bio_backmerge: OK [ 580.775781] Testing event block_bio_complete: OK [ 581.286813] Testing event block_bio_bounce: OK [ 582.006509] Testing event block_rq_issue: OK [ 582.651958] Testing event block_rq_insert: OK [ 583.426640] Testing event block_rq_complete: OK [ 584.076886] Testing event block_rq_requeue: OK [ 584.652317] Testing event block_dirty_buffer: OK [ 585.256386] Testing event block_touch_buffer: OK [ 585.854630] Testing event wbt_timer: OK [ 586.426757] Testing event wbt_step: OK [ 586.946390] Testing event wbt_lat: OK [ 587.461443] Testing event wbt_stat: OK [ 588.240433] Testing event gpio_value: OK [ 588.737349] Testing event gpio_direction: OK [ 589.367994] Testing event clk_set_duty_cycle_complete: OK [ 589.994250] Testing event clk_set_duty_cycle: OK [ 590.760153] Testing event clk_set_phase_complete: OK [ 591.298640] Testing event clk_set_phase: OK [ 591.945217] Testing event clk_set_parent_complete: OK [ 592.625380] Testing event clk_set_parent: OK [ 593.262382] Testing event clk_set_rate_complete: OK [ 593.868270] Testing event clk_set_rate: OK [ 594.511937] Testing event clk_unprepare_complete: OK [ 595.107714] Testing event clk_unprepare: OK [ 595.729338] Testing event clk_prepare_complete: OK [ 596.340811] Testing event clk_prepare: OK [ 596.927745] Testing event clk_disable_complete: OK [ 597.550603] Testing event clk_disable: OK [ 598.298724] Testing event clk_enable_complete: OK [ 598.862815] Testing event clk_enable: OK [ 599.678796] Testing event rpmh_send_msg: OK [ 600.466735] Testing event rpmh_tx_done: OK [ 601.310656] Testing event regulator_set_voltage_complete: OK [ 601.810818] Testing event regulator_set_voltage: OK [ 602.486837] Testing event regulator_disable_complete: OK [ 603.030357] Testing event regulator_disable: OK [ 603.661223] Testing event regulator_enable_complete: OK [ 604.212422] Testing event regulator_enable_delay: OK [ 604.884503] Testing event regulator_enable: OK [ 605.307723] Testing event urandom_read: OK [ 605.887721] Testing event random_read: OK [ 606.454724] Testing event extract_entropy_user: OK [ 607.336114] Testing event extract_entropy: OK [ 607.974676] Testing event get_random_bytes_arch: OK [ 608.784340] Testing event get_random_bytes: OK [ 609.401453] Testing event xfer_secondary_pool: OK [ 610.094821] Testing event add_disk_randomness: OK [ 610.711908] Testing event add_input_randomness: OK [ 611.282741] Testing event debit_entropy: OK [ 611.786784] Testing event push_to_pool: OK [ 612.770319] Testing event credit_entropy_bits: OK [ 613.570319] Testing event mix_pool_bytes_nolock: OK [ 614.257000] Testing event mix_pool_bytes: OK [ 615.226343] Testing event add_device_randomness: OK [ 616.023985] Testing event io_page_fault: OK [ 616.773297] Testing event unmap: OK [ 617.463723] Testing event map: OK [ 618.154840] Testing event detach_device_from_domain: OK [ 618.796898] Testing event attach_device_to_domain: OK [ 619.520506] Testing event remove_device_from_group: OK [ 620.374546] Testing event add_device_to_group: OK [ 620.920430] Testing event regcache_drop_region: OK [ 621.536417] Testing event regmap_async_complete_done: OK [ 622.196973] Testing event regmap_async_complete_start: OK [ 622.966593] Testing event regmap_async_io_complete: OK [ 623.470536] Testing event regmap_async_write_start: OK [ 624.346658] Testing event regmap_cache_bypass: OK [ 624.978572] Testing event regmap_cache_only: OK [ 625.686544] Testing event regcache_sync: OK [ 626.600651] Testing event regmap_hw_write_done: OK [ 627.284478] Testing event regmap_hw_write_start: OK [ 627.985345] Testing event regmap_hw_read_done: OK [ 628.862492] Testing event regmap_hw_read_start: OK [ 629.385662] Testing event regmap_reg_read_cache: OK [ 630.066153] Testing event regmap_reg_read: OK [ 630.766564] Testing event regmap_reg_write: OK [ 631.463656] Testing event dma_fence_wait_end: OK [ 632.438797] Testing event dma_fence_wait_start: OK [ 633.267746] Testing event dma_fence_signaled: OK [ 634.024155] Testing event dma_fence_enable_signal: OK [ 634.698836] Testing event dma_fence_destroy: OK [ 635.290649] Testing event dma_fence_init: OK [ 635.840059] Testing event dma_fence_emit: OK [ 636.426711] Testing event sync_timeline: OK [ 637.126506] Testing event spi_transfer_stop: OK [ 637.678804] Testing event spi_transfer_start: OK [ 638.426651] Testing event spi_message_done: OK [ 639.267969] Testing event spi_message_start: OK [ 639.940315] Testing event spi_message_submit: OK [ 640.405421] Testing event spi_controller_busy: OK [ 641.170557] Testing event spi_controller_idle: OK [ 641.810617] Testing event mdio_access: OK [ 642.768640] Testing event rtc_timer_fired: OK [ 643.174816] Testing event rtc_timer_dequeue: OK [ 643.535721] Testing event rtc_timer_enqueue: OK [ 644.229370] Testing event rtc_read_offset: OK [ 644.803948] Testing event rtc_set_offset: OK [ 645.242637] Testing event rtc_alarm_irq_enable: OK [ 645.822619] Testing event rtc_irq_set_state: OK [ 646.330516] Testing event rtc_irq_set_freq: OK [ 646.926475] Testing event rtc_read_alarm: OK [ 647.712803] Testing event rtc_set_alarm: OK [ 648.460512] Testing event rtc_read_time: OK [ 649.153148] Testing event rtc_set_time: OK [ 649.838760] Testing event memory_failure_event: OK [ 650.584799] Testing event aer_event: OK [ 651.182734] Testing event non_standard_event: OK [ 651.583740] Testing event arm_event: OK [ 652.144238] Testing event mc_event: OK [ 652.606555] Testing event binder_return: OK [ 653.246743] Testing event binder_command: OK [ 653.666358] Testing event binder_unmap_kernel_end: OK [ 654.180766] Testing event binder_unmap_kernel_start: OK [ 654.718329] Testing event binder_unmap_user_end: OK [ 655.579741] Testing event binder_unmap_user_start: OK [ 656.144767] Testing event binder_alloc_page_end: OK [ 656.818591] Testing event binder_alloc_page_start: OK [ 657.525360] Testing event binder_free_lru_end: OK [ 658.212740] Testing event binder_free_lru_start: OK [ 658.937000] Testing event binder_alloc_lru_end: OK [ 659.610750] Testing event binder_alloc_lru_start: OK [ 660.156579] Testing event binder_update_page_range: OK [ 661.109107] Testing event binder_transaction_failed_buffer_release: OK [ 661.539723] Testing event binder_transaction_buffer_release: OK [ 662.358695] Testing event binder_transaction_alloc_buf: OK [ 663.335701] Testing event binder_transaction_fd_recv: OK [ 663.938583] Testing event binder_transaction_fd_send: OK [ 664.421111] Testing event binder_transaction_ref_to_ref: OK [ 664.954780] Testing event binder_transaction_ref_to_node: OK [ 665.529038] Testing event binder_transaction_node_to_ref: OK [ 666.034857] Testing event binder_transaction_received: OK [ 666.856333] Testing event binder_transaction: OK [ 667.478743] Testing event binder_wait_for_work: OK [ 668.247747] Testing event binder_read_done: OK [ 668.677363] Testing event binder_write_done: OK [ 669.302785] Testing event binder_ioctl_done: OK [ 669.902767] Testing event binder_unlock: OK [ 670.494827] Testing event binder_locked: OK [ 670.818754] Testing event binder_lock: OK [ 671.295756] Testing event binder_ioctl: OK [ 672.174569] Testing event br_fdb_update: OK [ 673.011205] Testing event fdb_delete: OK [ 673.891888] Testing event br_fdb_external_learn_add: OK [ 674.411926] Testing event br_fdb_add: OK [ 674.954698] Testing event qdisc_dequeue: OK [ 675.555600] Testing event fib_table_lookup: OK [ 676.090461] Testing event tcp_probe: OK [ 676.731723] Testing event tcp_retransmit_synack: OK [ 677.365171] Testing event tcp_rcv_space_adjust: OK [ 677.874681] Testing event tcp_destroy_sock: OK [ 678.415852] Testing event tcp_receive_reset: OK [ 679.040565] Testing event tcp_send_reset: OK [ 679.890470] Testing event tcp_retransmit_skb: OK [ 680.630556] Testing event udp_fail_queue_rcv_skb: OK [ 681.090639] Testing event inet_sock_set_state: OK [ 681.932580] Testing event sock_exceed_buf_limit: OK [ 682.504341] Testing event sock_rcvqueue_full: OK [ 683.260426] Testing event napi_poll: OK [ 683.798821] Testing event netif_rx_ni_entry: OK [ 684.370667] Testing event netif_rx_entry: OK [ 685.278424] Testing event netif_receive_skb_list_entry: OK [ 685.776907] Testing event netif_receive_skb_entry: OK [ 686.355888] Testing event napi_gro_receive_entry: OK [ 687.022336] Testing event napi_gro_frags_entry: OK [ 687.550658] Testing event netif_rx: OK [ 688.245535] Testing event netif_receive_skb: OK [ 689.102572] Testing event net_dev_queue: OK [ 689.803814] Testing event net_dev_xmit: OK [ 690.722846] Testing event net_dev_start_xmit: OK [ 691.271816] Testing event skb_copy_datagram_iovec: OK [ 691.890452] Testing event consume_skb: OK [ 692.439760] Testing event kfree_skb: OK [ 693.214493] Testing event 9p_protocol_dump: OK [ 694.011728] Testing event 9p_client_res: OK [ 694.620794] Testing event 9p_client_req: OK [ 695.301426] Running tests on trace event systems: [ 695.307994] Testing event system 9p: OK [ 696.102346] Testing event system skb: OK [ 696.977133] Testing event system net: OK [ 698.039761] Testing event system napi: OK [ 698.820996] Testing event system sock: OK [ 699.486511] Testing event system udp: OK [ 700.070624] Testing event system tcp: OK [ 700.944956] Testing event system fib: OK [ 701.843807] Testing event system qdisc: OK [ 702.391904] Testing event system bridge: OK [ 702.871893] Testing event system binder: OK [ 703.967771] Testing event system ras: OK [ 705.142771] Testing event system rtc: OK [ 705.890448] Testing event system mdio: OK [ 706.750506] Testing event system spi: OK [ 707.553440] Testing event system sync_trace: OK [ 708.355700] Testing event system dma_fence: OK [ 709.182807] Testing event system regmap: OK [ 710.312611] Testing event system iommu: OK [ 710.996403] Testing event system random: OK [ 712.109243] Testing event system regulator: OK [ 713.186510] Testing event system rpmh: OK [ 713.830794] Testing event system clk: OK [ 714.698760] Testing event system gpio: OK [ 715.670598] Testing event system wbt: OK [ 716.262700] Testing event system block: OK [ 717.386620] Testing event system filelock: OK [ 718.565560] Testing event system fs_dax: OK [ 719.514580] Testing event system writeback: OK [ 720.705168] Testing event system page_ref: OK [ 724.191866] Testing event system cma: OK [ 725.016136] Testing event system page_isolation: OK [ 725.858771] Testing event system huge_memory: OK [ 726.774606] Testing event system migrate: OK [ 727.518585] Testing event system compaction: OK [ 728.675735] Testing event system kmem: OK [ 729.601455] Testing event system percpu: OK [ 730.247994] Testing event system vmscan: OK [ 731.147676] Testing event system pagemap: OK [ 731.977424] Testing event system oom: OK [ 733.034654] Testing event system filemap: OK [ 733.730830] Testing event system rseq: OK [ 734.414522] Testing event system xdp: OK [ 735.045162] Testing event system benchmark: OK [ 736.106617] Testing event system rpm: OK [ 736.890548] Testing event system power: OK [ 738.286670] Testing event system test: OK [ 739.334663] Testing event system preemptirq: OK [ 740.988207] Testing event system cgroup: OK [ 742.074731] Testing event system module: OK [ 742.666311] Testing event system alarmtimer: OK [ 743.554746] Testing event system timer: OK [ 744.686734] Testing event system swiotlb: OK [ 745.669303] Testing event system rcu: OK [ 746.654097] Testing event system printk: OK [ 747.306817] Testing event system lock: OK [ 747.987741] Testing event system sched: OK [ 748.786703] Testing event system workqueue: OK [ 749.651826] Testing event system signal: OK [ 750.197393] Testing event system irq: OK [ 751.030577] Testing event system cpuhp: OK [ 751.710474] Testing event system task: OK [ 752.246802] Testing event system kvm: OK [ 753.520937] Testing event system emulation: OK [ 754.536109] Testing event system ipi: OK [ 755.351774] Testing event system raw_syscalls: OK [ 756.291890] Testing event system initcall: OK [ 756.915839] Running tests on all trace events: [ 756.930523] Testing all events: OK [ 781.823634] ../kernel/trace/ftrace.c:2441, loop_counter: 41424 [ 781.832936] Testing ftrace filter: OK [ 781.947871] trace_kprobe: Testing kprobe tracing: OK [ 782.321944] Loading compiled-in X.509 certificates [ 782.433749] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 782.436273] Loaded X.509 cert 'Build time autogenerated kernel key: 4054958e39d9099bc90ea5e944013505afd1c71c' [ 782.440174] page_owner is disabled [ 782.569347] Key type big_key registered [ 782.623490] Key type encrypted registered [ 782.625694] ima: No TPM chip found, activating TPM-bypass! [ 782.626557] ima: Allocated hash algorithm: sha1 [ 782.630356] No architecture policies found [ 782.636214] random: get_random_bytes called from prandom_seed_full_state+0xc0/0x198 with crng_init=0 [ 782.661242] hctosys: unable to open rtc device (rtc0) [ 782.677404] psci_checker: PSCI checker started using 1 CPUs [ 782.677949] psci_checker: Starting hotplug tests [ 782.679295] psci_checker: Trying to turn off and on again all CPUs [ 782.680299] psci_checker: Trying to turn off and on again group 0 (CPUs 0) [ 782.681133] psci_checker: Hotplug tests passed OK [ 782.681616] psci_checker: Starting suspend tests (10 cycles per state) [ 782.682446] psci_checker: cpuidle not available on CPU 0, ignoring [ 782.683729] psci_checker: Could not start suspend tests on any CPU [ 782.684284] psci_checker: PSCI checker completed [ 782.729744] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 782.924905] ### dt-test ### start of unittest - you will see error messages [ 782.952937] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 782.953890] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 782.955268] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 782.956044] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 782.956883] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 782.957713] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 782.975594] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 782.977358] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 782.979571] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 783.401086] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status [ 783.451763] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status [ 783.474396] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status [ 783.523440] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status [ 783.599780] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status [ 783.667596] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status [ 783.712072] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status [ 783.790286] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status [ 783.834359] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo [ 783.836718] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 783.837543] OF: overlay: overlay #6 is not topmost [ 784.110628] OF: overlay: remove: Could not find overlay #7 [ 784.111686] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #7 [ 784.341879] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/status [ 784.347474] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/status [ 784.349648] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@30/incline-up [ 784.351429] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@40/incline-up [ 784.363700] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/status [ 784.364782] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/color [ 784.366016] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/rate [ 784.368149] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/hvac_2 [ 784.369458] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200 [ 784.371351] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_left [ 784.372681] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_right [ 784.473556] OF: overlay: ERROR: multiple fragments add and/or delete node /testcase-data-2/substation@100/motor-1/controller [ 784.477526] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/controller/name [ 784.489359] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 784.490681] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 784.492518] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/rpm_avail [ 784.510421] ### dt-test ### end of unittest - 217 passed, 0 failed [ 784.530023] uart-pl011 9000000.pl011: no DMA platform data [ 784.887457] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 784.888568] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 784.932127] VFS: Mounted root (9p filesystem) readonly on device 0:18. [ 784.953343] devtmpfs: mounted [ 785.655788] Freeing unused kernel memory: 4096K [ 785.673472] Checked W+X mappings: passed, no W+X pages found [ 785.674189] rodata_test: all tests were successful [ 785.675638] Run /sbin/init as init process [ 785.795964] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=0 [ 786.785328] random: fast init done [ 790.082175] systemd[1]: System time before build time, advancing clock. [ 790.606616] systemd[1]: Failed to insert module 'autofs4': No such file or directory [ 793.949329] random: systemd: uninitialized urandom read (16 bytes read) [ 794.331774] random: systemd: uninitialized urandom read (16 bytes read) [ 794.337301] random: systemd: uninitialized urandom read (16 bytes read) [ 799.793469] 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) [ 799.873396] systemd[1]: Detected virtualization qemu. [ 799.880954] systemd[1]: Detected architecture arm64. Welcome to Debian GNU/Linux 9 (stretch)! [ 800.037529] systemd[1]: Set hostname to . [ 800.224939] _warn_unseeded_randomness: 5 callbacks suppressed [ 800.225163] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=1 [ 800.288654] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 800.937866] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 801.490329] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 801.492105] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 801.492878] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 802.625146] _warn_unseeded_randomness: 20 callbacks suppressed [ 802.625345] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 802.687932] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 802.689102] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 803.813605] random: systemd: uninitialized urandom read (16 bytes read) [ 803.939940] _warn_unseeded_randomness: 13 callbacks suppressed [ 803.940141] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 803.996887] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 803.997887] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 805.667836] _warn_unseeded_randomness: 11 callbacks suppressed [ 805.668039] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 805.669164] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 805.671737] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 806.730078] _warn_unseeded_randomness: 4 callbacks suppressed [ 806.730272] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 808.326022] systemd-hiberna (1258) used greatest stack depth: 11056 bytes left [ 808.963970] systemd-rc-loca (1259) used greatest stack depth: 10576 bytes left [ 809.817676] random: systemd-cryptse: uninitialized urandom read (16 bytes read) [ 810.481520] systemd-fstab-g (1260) used greatest stack depth: 10000 bytes left [ 810.638664] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 811.745835] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 813.541908] random: systemd: uninitialized urandom read (16 bytes read) [ 813.793995] random: systemd: uninitialized urandom read (16 bytes read) [ 813.798566] random: systemd: uninitialized urandom read (16 bytes read) [ 821.813539] systemd[1]: networking.service: Cannot add dependency job, ignoring: Unit networking.service is masked. [ 821.886219] random: get_random_bytes called from generate_random_uuid+0x20/0x50 with crng_init=1 [ 821.952092] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ OK ] Listening on /dev/initctl Compatibility Named Pipe. [ 822.069592] systemd[1]: Created slice System Slice. [ OK ] Created slice System Slice. [ 822.109394] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 822.124867] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 822.146371] systemd[1]: Starting of Arbitrary Executable File Formats File System Automount Point not supported. [UNSUPP] Starting of Arbitrary Executable Fi…tem Automount Point not supported. [ 822.163552] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 822.198770] systemd[1]: Listening on Journal Audit Socket. [ OK ] Listening on Journal Audit Socket. [ 822.234236] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 822.263929] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. [ 822.489926] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 Starting Create Static Device Nodes in /dev... [ 823.306567] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 823.308006] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 823.308789] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 Starting Remount Root and Kernel File Systems... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 824.322703] _warn_unseeded_randomness: 7 callbacks suppressed [ 824.323882] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 824.324868] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 824.325637] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 Mounting POSIX Message Queue File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on Syslog Socket. [ 826.667863] _warn_unseeded_randomness: 5 callbacks suppressed [ 826.668059] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 826.669169] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 826.942013] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 827.651959] systemd[1]: Starting Journal Service... Starting Journal Service... [ 827.933043] _warn_unseeded_randomness: 5 callbacks suppressed [ 827.933233] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 827.934543] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 827.935890] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 829.773780] _warn_unseeded_randomness: 2 callbacks suppressed [ 829.773977] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 829.775678] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 829.873505] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 831.047984] _warn_unseeded_randomness: 3 callbacks suppressed [ 831.048174] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 831.049397] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 831.051730] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 831.343910] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 832.602465] _warn_unseeded_randomness: 1 callbacks suppressed [ 832.602690] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 832.844402] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 832.845371] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 833.628084] systemd[1]: Mounting Debug File System... [ 833.656726] _warn_unseeded_randomness: 9 callbacks suppressed [ 833.656925] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 Mounting Debug File System... [ 833.892059] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 833.893036] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 834.641978] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ 834.866485] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 835.069172] systemd[1]: Reached target Sockets. [ OK ] Reached target Sockets. [ 837.181586] _warn_unseeded_randomness: 3 callbacks suppressed [ 837.181850] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 837.183800] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 837.388538] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 838.148767] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 838.932410] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ 839.484390] _warn_unseeded_randomness: 2 callbacks suppressed [ 839.484591] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 839.624875] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 839.626296] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 840.905139] _warn_unseeded_randomness: 3 callbacks suppressed [ 840.905336] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 840.907472] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 841.457190] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 843.080790] _warn_unseeded_randomness: 1 callbacks suppressed [ 843.080990] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 843.225104] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 843.226464] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 843.987829] systemd[1]: Mounted Debug File System. [ OK ] Mounted Debug File System. [ 844.106309] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 845.224941] systemd[1]: Started Create Static Device Nodes in /dev. [ OK ] Started Create Static Device Nodes in /dev. [ 845.476008] _warn_unseeded_randomness: 3 callbacks suppressed [ 845.476207] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 845.653087] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 845.654139] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 846.057863] systemd[1]: Started Remount Root and Kernel File Systems. [ OK ] Started Remount Root and Kernel File Systems. [ 846.784100] systemd[1]: Started Load Kernel Modules. [ OK ] Started Load Kernel Modules. [ 847.083719] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 848.745746] _warn_unseeded_randomness: 3 callbacks suppressed [ 848.745949] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 849.098483] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 850.130188] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 850.131764] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 850.132618] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 850.173616] systemd[1]: Mounting Configuration File System... Mounting Configuration File System... [ 851.776722] _warn_unseeded_randomness: 4 callbacks suppressed [ 851.776936] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 852.077135] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 852.078062] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 852.586133] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 854.085077] _warn_unseeded_randomness: 3 callbacks suppressed [ 854.085277] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 854.258611] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 854.260033] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 855.154155] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 857.118717] _warn_unseeded_randomness: 5 callbacks suppressed [ 857.119929] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 857.355351] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 857.356281] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 858.375632] systemd[1]: Starting udev Kernel Device Manager... Starting udev Kernel Device Manager... [ 858.538312] systemd[1]: Reached target Local File Systems (Pre). [ OK ] Reached target Local File Systems (Pre). [ 858.769886] systemd[1]: Reached target Local File Systems. [ 858.847815] _warn_unseeded_randomness: 3 callbacks suppressed [ 858.848015] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ OK ] Reached target Local File Systems. [ 859.089725] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 859.090680] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 861.343580] _warn_unseeded_randomness: 5 callbacks suppressed [ 861.343780] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 861.344985] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 861.366795] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 862.449304] systemd[1]: Mounted Configuration File System. [ OK ] Mounted Configuration File System. [ 863.346381] _warn_unseeded_randomness: 3 callbacks suppressed [ 863.346583] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 863.370412] systemd[1]: Started Apply Kernel Variables. [ OK ] Started Apply Kernel Variables. [ 863.596886] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 863.597927] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 864.579474] systemd[1]: Started Load/Save Random Seed. [ OK ] Started Load/Save Random Seed. [ 866.731330] _warn_unseeded_randomness: 3 callbacks suppressed [ 866.731586] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [* ] (1 of 4) A start job is running for…l Device Manager (48s / 30min 35s) [** ] (1 of 4) A start job is running for…l Device Manager (48s / 30min 35s) [*** ] (1 of 4) A start job is running for…l Device Manager (49s / 30min 35s) [ *** ] (2 of 4) A start job is running for…dplug all Devices (49s / no limit) [ *** ] (2 of 4) A start job is running for…dplug all Devices (49s / no limit) [ ***] (2 of 4) A start job is running for…dplug all Devices (50s / no limit) [ **] (3 of 4) A start job is running for Journal Service (50s / 30min 5s) [ *] (3 of 4) A start job is running for Journal Service (51s / 30min 5s) [ **] (3 of 4) A start job is running for Journal Service (51s / 30min 5s) [ ***] (4 of 4) A start job is running for dev-ttyAMA0.device (52s / 30min) [ *** ] (4 of 4) A start job is running for dev-ttyAMA0.device (53s / 30min) [ *** ] (4 of 4) A start job is running for dev-ttyAMA0.device (53s / 30min) [*** ] (1 of 4) A start job is running for…l Device Manager (54s / 30min 35s) [** ] (1 of 4) A start job is running for…l Device Manager (54s / 30min 35s)[ 876.545289] systemd[1]: Started udev Kernel Device Manager. [ OK ] Started udev Kernel Device Manager. [* ] (2 of 3) A start job is running for Journal Service (59s / 30min 5s)[ 881.981828] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [ 882.537989] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 Starting Flush Journal to Persistent Storage... [ 884.184882] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 884.185860] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 884.186685] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [** ] (3 of 3) A start job is running for…v-ttyAMA0.device (1min 5s / 30min) [*** ] (3 of 3) A start job is running for…v-ttyAMA0.device (1min 5s / 30min)[ 887.773015] _warn_unseeded_randomness: 5 callbacks suppressed [ 887.773208] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ *** ] (3 of 3) A start job is running for…v-ttyAMA0.device (1min 6s / 30min) [ *** ] (1 of 3) A start job is running for…stent Storage (1min 6s / 2min 30s) [ ***] (1 of 3) A start job is running for…stent Storage (1min 7s / 2min 30s) [ **] (1 of 3) A start job is running for…stent Storage (1min 7s / 2min 30s) [ *] (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) [ ***] (2 of 3) A start job is running for…g all Devices (1min 9s / 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…-ttyAMA0.device (1min 10s / 30min) [*** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 10s / 30min) [** ] (1 of 3) A start job is running for…tent Storage (1min 11s / 2min 30s) [* ] (1 of 3) A start job is running for…tent Storage (1min 11s / 2min 30s) [** ] (1 of 3) A start job is running for…tent Storage (1min 12s / 2min 30s) [*** ] (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) [ *** ] (2 of 3) A start job is running for… all Devices (1min 13s / no limit)[ 896.169655] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 896.170683] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 896.172559] 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 15s / 30min) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (1min 16s / 30min)[ 898.345679] _warn_unseeded_randomness: 4 callbacks suppressed [ 898.345877] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 898.347570] 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 16s / 30min)[ 898.565097] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ **] (1 of 3) A start job is running for…tent Storage (1min 17s / 2min 30s) [ ***] (1 of 3) A start job is running for…tent Storage (1min 17s / 2min 30s) [ *** ] (1 of 3) A start job is running for…tent Storage (1min 18s / 2min 30s) [ *** ] (2 of 3) A start job is running for… all Devices (1min 18s / no limit) [*** ] (2 of 3) A start job is running for… all Devices (1min 18s / no limit) [** ] (2 of 3) A start job is running for… all Devices (1min 19s / no limit) [* ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 20s / 30min) [** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 20s / 30min) [*** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 21s / 30min) [ *** ] (1 of 3) A start job is running for…tent Storage (1min 21s / 2min 30s)[ 903.391087] random: crng init done [ 903.391583] random: 1 get_random_xx warning(s) missed due to ratelimiting [ 903.518581] systemd-journald[1270]: Received request to flush runtime journal from PID 1 [ *** ] (1 of 3) A start job is running for…tent Storage (1min 21s / 2min 30s) [ OK ] Started Flush Journal to Persistent Storage. Starting Create Volatile Files and Directories... [ ***] (1 of 3) A start job is running for… all Devices (1min 28s / no limit) [ **] (2 of 3) A start job is running for… Directories (1min 28s / no limit) [ *] (2 of 3) A start job is running for… Directories (1min 29s / no limit) [ **] (2 of 3) A start job is running for… Directories (1min 29s / no limit) [ ***] (3 of 3) A start job is running for…-ttyAMA0.device (1min 30s / 30min) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 30s / 30min) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 31s / 30min) [*** ] (1 of 3) A start job is running for… all Devices (1min 31s / no limit) [** ] (1 of 3) A start job is running for… all Devices (1min 32s / no limit) [* ] (1 of 3) A start job is running for… all Devices (1min 32s / no limit) [** ] (2 of 3) A start job is running for… Directories (1min 33s / no limit) [*** ] (2 of 3) A start job is running for… Directories (1min 33s / no limit) [ *** ] (2 of 3) A start job is running for… Directories (1min 34s / no limit) [ *** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 35s / 30min) [ ***] (3 of 3) A start job is running for…-ttyAMA0.device (1min 35s / 30min) [ **] (3 of 3) A start job is running for…-ttyAMA0.device (1min 36s / 30min) [ *] (1 of 3) A start job is running for… all Devices (1min 36s / no limit) [ **] (1 of 3) A start job is running for… all Devices (1min 37s / no limit) [ ***] (1 of 3) A start job is running for… all Devices (1min 37s / no limit) [ *** ] (2 of 3) A start job is running for… Directories (1min 38s / no limit) [ *** ] (2 of 3) A start job is running for… Directories (1min 38s / no limit) [*** ] (2 of 3) A start job is running for… Directories (1min 39s / no limit) [** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 39s / 30min) [* ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 40s / 30min) [** ] (3 of 3) A start job is running for…-ttyAMA0.device (1min 40s / 30min) [*** ] (1 of 3) A start job is running for… all Devices (1min 41s / no limit) [ *** ] (1 of 3) A start job is running for… all Devices (1min 41s / no limit) [ *** ] (1 of 3) A start job is running for… all Devices (1min 42s / no limit) [ ***] (2 of 3) A start job is running for… Directories (1min 42s / no limit) [ **] (2 of 3) A start job is running for… Directories (1min 43s / no limit) [ *] (2 of 3) A start job is running for… Directories (1min 43s / no limit) [ OK ] Started Create Volatile Files and Directories. Starting Network Time Synchronization... Starting Update UTMP about System Boot/Shutdown... [ **] (3 of 4) A start job is running for…hronization (1min 51s / 31min 46s) [ ***] (3 of 4) A start job is running for…hronization (1min 51s / 31min 46s) [ *** ] (3 of 4) A start job is running for…hronization (1min 52s / 31min 46s) [ *** ] (4 of 4) A start job is running for…-ttyAMA0.device (1min 52s / 30min) [*** ] (4 of 4) A start job is running for…-ttyAMA0.device (1min 53s / 30min) [** ] (4 of 4) A start job is running for…-ttyAMA0.device (1min 53s / 30min) [* ] (1 of 4) A start job is running for…oot/Shutdown (1min 54s / no limit) [** ] (1 of 4) A start job is running for…oot/Shutdown (1min 54s / no limit) [*** ] (1 of 4) A start job is running for…oot/Shutdown (1min 55s / no limit) [ *** ] (2 of 4) A start job is running for… all Devices (1min 55s / no limit) [ *** ] (2 of 4) A start job is running for… all Devices (1min 56s / no limit) [ OK ] Started Update UTMP about System Boot/Shutdown. [ ***] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 2s / 30min) [ **] (1 of 3) A start job is running for…g all Devices (2min 2s / no limit) [ *] (1 of 3) A start job is running for…g all Devices (2min 3s / no limit) [ **] (1 of 3) A start job is running for…g all Devices (2min 3s / no limit) [ ***] (2 of 3) A start job is running for…chronization (2min 4s / 31min 46s) [ *** ] (2 of 3) A start job is running for…chronization (2min 4s / 31min 46s) [ *** ] (2 of 3) A start job is running for…chronization (2min 5s / 31min 46s) [*** ] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 6s / 30min) [** ] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 6s / 30min) [* ] (3 of 3) A start job is running for…v-ttyAMA0.device (2min 7s / 30min) [** ] (1 of 3) A start job is running for…g all Devices (2min 7s / no limit) [ OK ] Started Network Time Synchronization. [ OK ] Reached target System Time Synchronized. [*** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 13s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 13s / 30min) [ *** ] (1 of 2) A start job is running for… all Devices (2min 14s / no limit) [ ***] (1 of 2) A start job is running for… all Devices (2min 14s / no limit) [ **] (1 of 2) A start job is running for… all Devices (2min 15s / no limit) [ *] (2 of 2) A start job is running for…-ttyAMA0.device (2min 15s / 30min) [ **] (2 of 2) A start job is running for…-ttyAMA0.device (2min 16s / 30min) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (2min 16s / 30min) [ *** ] (1 of 2) A start job is running for… all Devices (2min 17s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (2min 17s / no limit) [*** ] (1 of 2) A start job is running for… all Devices (2min 18s / no limit) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 18s / 30min) [* ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 19s / 30min) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 19s / 30min) [*** ] (1 of 2) A start job is running for… all Devices (2min 20s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (2min 20s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (2min 21s / no limit) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (2min 22s / 30min) [ **] (2 of 2) A start job is running for…-ttyAMA0.device (2min 22s / 30min) [ *] (2 of 2) A start job is running for…-ttyAMA0.device (2min 23s / 30min) [ **] (1 of 2) A start job is running for… all Devices (2min 23s / no limit) [ ***] (1 of 2) A start job is running for… all Devices (2min 24s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (2min 24s / no limit) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 25s / 30min) [*** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 25s / 30min) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 26s / 30min) [* ] (1 of 2) A start job is running for… all Devices (2min 26s / no limit) [** ] (1 of 2) A start job is running for… all Devices (2min 27s / no limit) [*** ] (1 of 2) A start job is running for… all Devices (2min 27s / no limit) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 28s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 28s / 30min) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (2min 29s / 30min) [ **] (1 of 2) A start job is running for… all Devices (2min 29s / no limit) [ *] (1 of 2) A start job is running for… all Devices (2min 30s / no limit) [ **] (1 of 2) A start job is running for… all Devices (2min 30s / no limit) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (2min 31s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 31s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 32s / 30min) [*** ] (1 of 2) A start job is running for… all Devices (2min 32s / no limit) [** ] (1 of 2) A start job is running for… all Devices (2min 33s / no limit) [* ] (1 of 2) A start job is running for… all Devices (2min 33s / no limit) [** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 34s / 30min) [*** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 34s / 30min) [ *** ] (2 of 2) A start job is running for…-ttyAMA0.device (2min 35s / 30min) [ *** ] (1 of 2) A start job is running for… all Devices (2min 35s / no limit) [ ***] (1 of 2) A start job is running for… all Devices (2min 36s / no limit) [ **] (1 of 2) A start job is running for… all Devices (2min 36s / no limit) [ *] (2 of 2) A start job is running for…-ttyAMA0.device (2min 37s / 30min) [ **] (2 of 2) A start job is running for…-ttyAMA0.device (2min 37s / 30min) [ ***] (2 of 2) A start job is running for…-ttyAMA0.device (2min 38s / 30min) [ *** ] (1 of 2) A start job is running for… all Devices (2min 38s / no limit) [ *** ] (1 of 2) A start job is running for… all Devices (2min 39s / no limit) [*** ] (1 of 2) A start job is running for… all Devices (2min 39s / no limit) [ OK ] Found device /dev/ttyAMA0. [ 984.637709] virtio_net virtio0 enp0s1: renamed from eth0 [** ] A start job is running for udev Col… all Devices (2min 46s / no limit) [* ] A start job is running for udev Col… all Devices (2min 46s / no limit) [** ] A start job is running for udev Col… all Devices (2min 47s / no limit) [*** ] A start job is running for udev Col… all Devices (2min 47s / no limit) [ *** ] A start job is running for udev Col… all Devices (2min 48s / no limit) [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started Daily apt upgrade and clean activities. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. Starting System Logging Service... [ OK ] Started Regular background program processing daemon. Starting getty on tty2-tty6 if dbus and logind are not available... Starting Permit User Sessions... Starting Cleanup of Temporary Directories... [ *** ] (1 of 4) A start job is running for…porary Directories (2s / no limit) [ ***] (2 of 4) A start job is running for…User Sessions (3min 6s / no limit) [ **] (2 of 4) A start job is running for…User Sessions (3min 6s / no limit) [ OK ] Started System Logging Service. [ OK ] Started Permit User Sessions. [ *] (2 of 2) A start job is running for…ot available (3min 20s / no limit) [ **] (1 of 2) A start job is running for…orary Directories (20s / no limit) [ OK ] Started Cleanup of Temporary Directories. [ ***] A start job is running for getty on…ot available (3min 36s / no limit) [ *** ] A start job is running for getty on…ot available (3min 39s / no limit) [ *** ] A start job is running for getty on…ot available (3min 42s / no limit) [*** ] A start job is running for getty on…ot available (3min 43s / no limit) [ OK ] Started Getty on tty6. [ OK ] Started Getty on tty5. [ OK ] Started Getty on tty4. [ OK ] Started Getty on tty3. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyAMA0. [ OK ] Started Getty on tty2. [ OK ] Started getty on tty2-tty6 if dbus and logind are not available. Debian GNU/Linux 9 compilator ttyAMA0 compilator login: [ 1113.076638] systemd[1]: systemd-journald.service: Main process exited, code=killed, status=6/ABRT [ 1113.650432] systemd[1]: systemd-journald.service: Unit entered failed state. [ 1113.679376] systemd[1]: systemd-journald.service: Failed with result 'watchdog'. [ 1113.827416] systemd[1]: systemd-journald.service: Service has no hold-off time, scheduling restart. [ 1114.716299] systemd[1]: Stopped Flush Journal to Persistent Storage. [ 1114.741786] systemd[1]: Stopping Flush Journal to Persistent Storage... [ 1114.755669] systemd[1]: Stopped Journal Service. [ 1115.685410] systemd[1]: Starting Journal Service... [ 1119.693806] systemd-journald[1339]: File /run/log/journal/ee22564d41614722a231f845c5bb3c72/system.journal corrupted or uncleanly shut down, renaming and replacing.