[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 4.20.0-rc4-next-20181128-00009-g44c557177b8d (anders@compilator) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05)) #2 SMP Wed Nov 28 10:00:20 CET 2018 [ 0.000000] Machine model: linux,dummy-virt [ 0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '') [ 0.000000] printk: bootconsole [pl11] enabled [ 0.000000] efi: Getting EFI parameters from FDT: [ 0.000000] efi: UEFI not found. [ 0.000000] cma: Reserved 16 MiB at 0x00000000bf000000 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] NUMA: No NUMA configuration found [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] NUMA: NODE_DATA [mem 0xbebf05c0-0xbebf1fff] [ 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+0xdc/0x660 with crng_init=0 [ 0.000000] percpu: Embedded 490 pages/cpu @(____ptrval____) s1969240 r8192 d29608 u2007040 [ 0.000000] Detected VIPT I-cache on CPU0 [ 0.000000] CPU features: enabling workaround for ARM erratum 843419 [ 0.000000] CPU features: enabling workaround for ARM erratum 845719 [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 516096 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: root=/dev/root rootfstype=9p rootflags=trans=virtio console=ttyAMA0,38400n8 earlycon=pl011,0x9000000 initcall_debug [ 0.000000] Memory: 995776K/2097152K available (13292K kernel code, 3774K rwdata, 4852K rodata, 6144K init, 23374K bss, 91800K 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 41975 entries in 164 pages [ 0.000000] Running RCU self tests [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU dyntick-idle grace-period acceleration is enabled. [ 0.000000] rcu: RCU lockdep checking is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=1. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] rcu: RCU debug extended QS entry/exit. [ 0.000000] Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] GICv2m: range[mem 0x08020000-0x08020fff], SPI[80:143] [ 0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns [ 0.000147] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.011088] Console: colour dummy device 80x25 [ 0.013349] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.013616] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.013813] ... MAX_LOCK_DEPTH: 48 [ 0.013968] ... MAX_LOCKDEP_KEYS: 8191 [ 0.014144] ... CLASSHASH_SIZE: 4096 [ 0.014374] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.014532] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.014690] ... CHAINHASH_SIZE: 32768 [ 0.014870] memory used by lock dependency info: 7775 kB [ 0.015056] per task-struct memory footprint: 2688 bytes [ 0.015257] ------------------------ [ 0.015450] | Locking API testsuite: [ 0.015608] ---------------------------------------------------------------------------- [ 0.016026] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.016221] -------------------------------------------------------------------------- [ 0.016733] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.027051] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.036981] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.047171] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.057589] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.068322] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.079098] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.090436] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.099746] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.108796] -------------------------------------------------------------------------- [ 0.109014] recursive read-lock: | ok | | ok | [ 0.111871] recursive read-lock #2: | ok | | ok | [ 0.114944] mixed read-write-lock: | ok | | ok | [ 0.117920] mixed write-read-lock: | ok | | ok | [ 0.120772] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.123722] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.126886] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.129855] -------------------------------------------------------------------------- [ 0.130261] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.134812] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.139288] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.143706] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.148042] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.152480] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.156854] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.161308] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.165680] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.170109] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.174510] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.179064] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.183563] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.188100] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.192592] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.197192] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.201708] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.206225] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.210807] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.215254] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.219654] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.224163] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.228737] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.233376] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.237917] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.242562] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.247212] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.251809] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.256373] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.260979] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.265549] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.270132] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.274783] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.279366] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.283992] hard-irq lock-inversion/123: ok | ok | ok | [ 0.288738] soft-irq lock-inversion/123: ok | ok | ok | [ 0.293313] hard-irq lock-inversion/132: ok | ok | ok | [ 0.297985] soft-irq lock-inversion/132: ok | ok | ok | [ 0.302628] hard-irq lock-inversion/213: ok | ok | ok | [ 0.307243] soft-irq lock-inversion/213: ok | ok | ok | [ 0.311803] hard-irq lock-inversion/231: ok | ok | ok | [ 0.316419] soft-irq lock-inversion/231: ok | ok | ok | [ 0.321034] hard-irq lock-inversion/312: ok | ok | ok | [ 0.325650] soft-irq lock-inversion/312: ok | ok | ok | [ 0.330337] hard-irq lock-inversion/321: ok | ok | ok | [ 0.334964] soft-irq lock-inversion/321: ok | ok | ok | [ 0.339575] hard-irq read-recursion/123: ok | [ 0.341495] soft-irq read-recursion/123: ok | [ 0.343214] hard-irq read-recursion/132: ok | [ 0.344890] soft-irq read-recursion/132: ok | [ 0.346790] hard-irq read-recursion/213: ok | [ 0.348465] soft-irq read-recursion/213: ok | [ 0.350134] hard-irq read-recursion/231: ok | [ 0.352093] soft-irq read-recursion/231: ok | [ 0.353801] hard-irq read-recursion/312: ok | [ 0.355509] soft-irq read-recursion/312: ok | [ 0.357403] hard-irq read-recursion/321: ok | [ 0.359112] soft-irq read-recursion/321: ok | [ 0.360982] -------------------------------------------------------------------------- [ 0.361177] | Wound/wait tests | [ 0.361333] --------------------- [ 0.361498] ww api failures: ok | ok | ok | [ 0.367356] ww contexts mixing: ok | ok | [ 0.370397] finishing ww context: ok | ok | ok | ok | [ 0.376238] locking mismatches: ok | ok | ok | [ 0.380616] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.395694] spinlock nest unlocked: ok | [ 0.397411] ----------------------------------------------------- [ 0.397596] |block | try |context| [ 0.397768] ----------------------------------------------------- [ 0.397962] context: ok | ok | ok | [ 0.402400] try: ok | ok | ok | [ 0.406688] block: ok | ok | ok | [ 0.410923] spinlock: ok | ok | ok | [ 0.415530] ------------------------------------------------------- [ 0.415723] Good, all 261 testcases passed! | [ 0.415883] --------------------------------- [ 0.462538] ODEBUG: selftest passed [ 0.465794] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.466198] pid_max: default: 32768 minimum: 301 [ 0.473190] LSM: Security Framework initializing [ 0.473651] Yama: becoming mindful. [ 0.475482] LoadPin: ready to pin (currently enforcing) [ 0.475938] SELinux: Initializing. [ 0.480452] *** VALIDATE SELinux *** [ 0.481016] AppArmor: AppArmor disabled by boot time parameter [ 0.494397] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.498958] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.500724] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.501245] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.526052] *** VALIDATE proc *** [ 0.539196] *** VALIDATE cgroup1 *** [ 0.539447] *** VALIDATE cgroup2 *** [ 0.588786] ASID allocator initialised with 32768 entries [ 0.594906] rcu: Hierarchical SRCU implementation. [ 0.642317] EFI services will not be available. [ 0.660744] smp: Bringing up secondary CPUs ... [ 0.661069] smp: Brought up 1 node, 1 CPU [ 0.661260] SMP: Total of 1 processors activated. [ 0.661540] CPU features: detected: 32-bit EL0 Support [ 0.661795] CPU features: detected: CRC32 instructions [ 0.869253] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 0.869777] CPU: All CPU(s) started at EL1 [ 0.870668] alternatives: patching kernel code [ 3.086998] node 0 initialised, 248298 pages in 4ms [ 3.093162] pgdatinit0 (15) used greatest stack depth: 13312 bytes left [ 3.140538] devtmpfs: initialized [ 3.264357] gcov: version magic: 0x4137332a [ 3.276648] Registered cp15_barrier emulation handler [ 3.277051] Registered setend emulation handler [ 4.163919] workqueue: round-robin CPU selection forced, expect performance impact [ 7.362969] DMA-API: preallocated 65536 debug entries [ 7.363230] DMA-API: debugging enabled by kernel config [ 7.363766] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 7.365255] futex hash table entries: 256 (order: 3, 32768 bytes) [ 7.367719] Running postponed tracer tests: [ 7.368996] Testing tracer function: [ 7.403323] ftrace_replace_code: 1 [ 14.309489] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 14.482523] ftrace_replace_code: 1 [ 20.465730] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 20.468541] PASSED [ 20.468766] Testing dynamic ftrace: [ 20.588364] ftrace_replace_code: 1 [ 20.684996] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 20.916727] PASSED [ 20.916999] Testing dynamic ftrace ops #1: [ 21.374801] ftrace_replace_code: 1 [ 21.472289] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 21.476282] (1 0 1 0 0) [ 21.476387] (1 1 2 0 0) [ 21.487709] ftrace_replace_code: 1 [ 31.384835] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 31.386474] (2 1 3 0 907307) [ 31.388024] (2 2 4 0 907578) [ 31.399863] ftrace_replace_code: 1 [ 38.368638] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 38.382677] ftrace_replace_code: 1 [ 38.478874] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 38.483249] ftrace_replace_code: 1 [ 38.579349] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 38.582530] PASSED [ 38.582712] Testing dynamic ftrace ops #2: [ 39.032284] ftrace_replace_code: 1 [ 39.132556] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 39.136400] ftrace_replace_code: 1 [ 39.232309] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 39.250258] ftrace_replace_code: 1 [ 48.311582] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 48.314105] (1 0 1 881511 0) [ 48.314242] (1 1 2 881532 0) [ 48.327631] (2 1 3 1 503) [ 48.329455] (2 2 4 385 887) [ 48.366896] ftrace_replace_code: 1 [ 54.356457] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 54.358391] PASSED [ 54.358590] Testing ftrace recursion: [ 54.473834] ftrace_replace_code: 1 [ 54.570433] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 54.574272] ftrace_replace_code: 1 [ 54.671317] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 54.672681] PASSED [ 54.672886] Testing ftrace recursion safe: [ 54.787297] ftrace_replace_code: 1 [ 54.883919] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 54.887827] ftrace_replace_code: 1 [ 54.984232] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 54.985861] PASSED [ 54.986067] Testing ftrace regs(no arch support): [ 55.100534] ftrace_replace_code: 1 [ 55.197340] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 55.201191] ftrace_replace_code: 1 [ 55.297554] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 55.299051] PASSED [ 55.299854] Testing tracer nop: PASSED [ 55.300935] Testing tracer irqsoff: [ 55.315724] ftrace_replace_code: 1 [ 62.308572] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 62.326153] ftrace_replace_code: 1 [ 68.339631] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 68.341655] PASSED [ 68.342845] Testing tracer wakeup: [ 68.368733] ftrace_replace_code: 1 [ 75.372913] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 75.407201] ftrace_replace_code: 1 [ 75.768200] sched: DL replenish lagged too much [ 81.418399] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 81.424994] PASSED [ 81.426127] Testing tracer wakeup_rt: [ 81.449195] ftrace_replace_code: 1 [ 88.532507] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 88.554171] ftrace_replace_code: 1 [ 94.568127] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 94.573926] PASSED [ 94.574489] Testing tracer wakeup_dl: [ 94.597964] ftrace_replace_code: 1 [ 101.583059] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 101.602269] ftrace_replace_code: 1 [ 107.609698] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 107.615589] PASSED [ 107.616408] Testing tracer function_graph: [ 107.637302] ftrace_replace_code: 1 [ 114.445615] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 114.758824] ftrace_replace_code: 1 [ 121.806236] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 121.808500] PASSED [ 121.860297] _warn_unseeded_randomness: 108 callbacks suppressed [ 121.860405] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 121.861201] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 121.862159] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 121.872479] prandom: seed boundary self test passed [ 121.878555] prandom: 100 self tests passed [ 121.881861] pinctrl core: initialized pinctrl subsystem [ 121.930334] regulator-dummy: Failed to create debugfs directory [ 121.934181] [ 121.934363] ************************************************************* [ 121.934598] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 121.934829] ** ** [ 121.935060] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 121.935289] ** ** [ 121.935520] ** This means that this kernel is built to expose internal ** [ 121.935742] ** IOMMU data structures, which may compromise security on ** [ 121.935961] ** your system. ** [ 121.936405] ** ** [ 121.936631] ** If you see this message and you are not debugging the ** [ 121.936819] ** kernel, report this immediately to your vendor! ** [ 121.937010] ** ** [ 121.937209] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 121.937408] ************************************************************* [ 121.949962] DMI not present or invalid. [ 121.976809] NET: Registered protocol family 16 [ 121.991477] audit: initializing netlink subsys (disabled) [ 122.056335] cpuidle: using governor ladder [ 122.057047] cpuidle: using governor menu [ 122.065787] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 122.068168] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 122.070375] audit: type=2000 audit(107.084:1): state=initialized audit_enabled=0 res=1 [ 122.127972] DMA: preallocated 256 KiB pool for atomic allocations [ 122.165906] Serial: AMBA PL011 UART driver [ 122.480603] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 122.482547] printk: console [ttyAMA0] enabled [ 122.482547] printk: console [ttyAMA0] enabled [ 122.483137] printk: bootconsole [pl11] disabled [ 122.483137] printk: bootconsole [pl11] disabled [ 122.799486] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 122.865973] kobject: 'renesas,r9a06g032-sysctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 122.869346] kobject: 'renesas-cpg-mssr' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 122.893395] arm-v7s io-pgtable: self test ok [ 122.894068] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 122.897383] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 122.898553] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 122.900103] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 122.901449] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 122.902699] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 122.904236] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 122.905948] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 122.907742] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 122.909904] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 122.911813] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 122.913972] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 122.916148] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 122.920271] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 122.924391] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 122.928664] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 122.933593] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 122.938410] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 122.943222] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 122.956407] vgaarb: loaded [ 122.963836] _warn_unseeded_randomness: 79 callbacks suppressed [ 122.963892] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 122.964522] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 122.977219] pps_core: LinuxPPS API ver. 1 registered [ 122.977501] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 122.979168] PTP clock support registered [ 122.982355] random: get_random_u32 called from kobject_release+0x20/0xf0 with crng_init=0 [ 122.982679] kobject: 'brcmstb-reboot' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 123.055630] NetLabel: Initializing [ 123.056153] NetLabel: domain hash size = 128 [ 123.056394] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 123.060834] NetLabel: unlabeled traffic allowed by default [ 123.071209] clocksource: Switched to clocksource arch_sys_counter [ 124.775894] _warn_unseeded_randomness: 4 callbacks suppressed [ 124.775950] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 124.776644] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 124.777873] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 124.785767] VFS: Disk quotas dquot_6.6.0 [ 124.788192] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 124.799522] *** VALIDATE hugetlbfs *** [ 125.197817] NET: Registered protocol family 2 [ 125.221337] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 125.223459] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 125.228255] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 125.234601] TCP: Hash tables configured (established 16384 bind 16384) [ 125.238888] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 125.241072] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 125.250992] NET: Registered protocol family 1 [ 125.252819] NET: Registered protocol family 44 [ 125.292133] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 125.296413] kvm [1]: HYP mode not available [ 125.427166] Kprobe smoke test: started [ 125.478195] Kprobe smoke test: passed successfully [ 125.508567] Initialise system trusted keyrings [ 125.512616] Key type blacklist registered [ 125.520042] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 126.443805] _warn_unseeded_randomness: 62 callbacks suppressed [ 126.443862] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 126.444527] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 126.455522] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 126.548420] 9p: Installing v9fs 9p2000 file system support [ 126.606016] Allocating IMA blacklist keyring. [ 126.628572] kworker/u2:1 (38) used greatest stack depth: 12016 bytes left [ 127.144209] Key type asymmetric registered [ 127.144959] Asymmetric key parser 'x509' registered [ 127.148029] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 127.241405] kobject: 'brcm-gisb-arb' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 127.279444] kobject: 'armada-37xx-pinctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 127.356080] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 127.359177] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 127.371059] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 127.381041] kobject: 'stmpe-pwm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 127.392738] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 127.423923] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 127.425070] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 127.426200] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 127.427108] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 127.428906] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 127.436495] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 127.437038] pci_bus 0000:00: root bus resource [bus 00-0f] [ 127.437439] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 127.437776] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 127.438117] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 127.440624] pci 0000:00:00.0: calling quirk_mmio_always_on+0x0/0x30 @ 1 [ 127.441033] pci 0000:00:00.0: quirk_mmio_always_on+0x0/0x30 took 50 usecs [ 127.478803] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 127.479354] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 127.479895] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 127.480354] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 127.480775] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 127.481123] pci 0000:00:03.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 127.481469] pci 0000:00:03.0: BAR 0: assigned [io 0x1000-0x103f] [ 127.481814] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 127.482134] pci 0000:00:02.0: BAR 0: assigned [io 0x1060-0x107f] [ 127.483441] pci 0000:00:00.0: calling tegra_pcie_relax_enable+0x0/0x38 @ 1 [ 127.483865] pci 0000:00:00.0: tegra_pcie_relax_enable+0x0/0x38 took 94 usecs [ 127.484950] pci 0000:00:01.0: calling tegra_pcie_relax_enable+0x0/0x38 @ 1 [ 127.485287] pci 0000:00:01.0: tegra_pcie_relax_enable+0x0/0x38 took 18 usecs [ 127.485825] pci 0000:00:02.0: calling tegra_pcie_relax_enable+0x0/0x38 @ 1 [ 127.486151] pci 0000:00:02.0: tegra_pcie_relax_enable+0x0/0x38 took 17 usecs [ 127.487001] pci 0000:00:03.0: calling tegra_pcie_relax_enable+0x0/0x38 @ 1 [ 127.487332] pci 0000:00:03.0: tegra_pcie_relax_enable+0x0/0x38 took 17 usecs [ 127.505593] _warn_unseeded_randomness: 44 callbacks suppressed [ 127.505647] random: get_random_u32 called from kobject_release+0x20/0xf0 with crng_init=0 [ 127.506141] kobject: 'layerscape-pcie' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 127.515223] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 127.705289] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 127.721475] random: get_random_u32 called from kobject_release+0x20/0xf0 with crng_init=0 [ 127.721866] kobject: 'virtio0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 127.736294] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 127.747338] kobject: 'virtio1' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 127.761954] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 127.772692] kobject: 'virtio2' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 130.240987] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 130.308908] _warn_unseeded_randomness: 5 callbacks suppressed [ 130.308971] random: get_random_u32 called from kobject_release+0x20/0xf0 with crng_init=0 [ 130.309635] kobject: 'ttyS0' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 130.311042] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 130.312336] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 130.339040] random: get_random_u32 called from kobject_release+0x20/0xf0 with crng_init=0 [ 130.339384] kobject: 'ttyS1' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 130.340006] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 130.365527] kobject: 'ttyS2' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 130.366761] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 130.392577] kobject: 'ttyS3' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 130.393181] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 130.435560] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 130.441492] KGDB: Registered I/O driver kgdbts [ 130.442074] kgdbts:RUN plant and detach test Entering kdb (current=0xffffffc03f558040, pid 1) on processor 0 due to Keyboard Entry [0]kdb> [ 130.448073] kgdbts:RUN sw breakpoint test [ 130.451913] kgdbts:RUN bad memory access test [ 130.452866] kgdbts:RUN singlestep test 1000 iterations [ 130.458645] kgdbts:RUN singlestep [0/1000] [ 130.929624] kgdbts:RUN singlestep [100/1000] [ 131.399764] kgdbts:RUN singlestep [200/1000] [ 131.869808] kgdbts:RUN singlestep [300/1000] [ 132.339823] kgdbts:RUN singlestep [400/1000] [ 132.809713] kgdbts:RUN singlestep [500/1000] [ 133.279953] kgdbts:RUN singlestep [600/1000] [ 133.749946] kgdbts:RUN singlestep [700/1000] [ 134.219785] kgdbts:RUN singlestep [800/1000] [ 134.690004] kgdbts:RUN singlestep [900/1000] [ 135.156433] kgdbts:RUN do_fork for 100 breakpoints [ 135.163560] _warn_unseeded_randomness: 7 callbacks suppressed [ 135.163622] random: get_random_u64 called from dup_task_struct+0xbc/0x350 with crng_init=0 [ 135.244349] random: get_random_u32 called from kobject_release+0x20/0xf0 with crng_init=0 [ 135.244902] kobject: 'rx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 135.247155] kobject: 'tx-0' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 135.262879] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 135.272162] kobject: 'net' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 137.281688] kobject: 'queues' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 137.590992] kobject: '44' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 137.596955] kobject: 'eth0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 137.607674] _warn_unseeded_randomness: 1 callbacks suppressed [ 137.607738] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 137.680998] ledtrig-cpu: registered to indicate activity on CPUs [ 137.682942] random: get_random_u32 called from kobject_release+0x20/0xf0 with crng_init=0 [ 137.683296] kobject: '45' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 137.686023] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 137.690686] kobject: 'meson-sm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 137.699233] kobject: '46' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 137.731780] ashmem: initialized [ 137.837358] 9pnet: Installing 9P2000 support [ 137.907124] kobject: '47' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 137.915109] kobject: '48' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 137.936478] registered taskstats version 1 [ 137.937023] Running ring buffer tests... [ 148.061566] finished [ 148.061983] CPU 0: [ 148.062189] events: 7561 [ 148.062954] dropped bytes: 0 [ 148.063194] alloced bytes: 590040 [ 148.063428] written bytes: 578034 [ 148.063660] biggest event: 132 [ 148.063889] smallest event: 0 [ 148.173331] read events: 7561 [ 148.173575] lost events: 0 [ 148.173799] total events: 7561 [ 148.174026] recorded len bytes: 590040 [ 148.174277] recorded size bytes: 578034 [ 148.174840] Ring buffer PASSED! [ 148.201574] Running tests on trace events: [ 148.201913] Testing event initcall_finish: OK [ 148.227097] Testing event initcall_start: OK [ 148.256609] Testing event initcall_level: OK [ 148.288475] Testing event sys_exit: OK [ 148.320008] Testing event sys_enter: OK [ 148.351208] Testing event ipi_exit: OK [ 148.383748] Testing event ipi_entry: OK [ 148.415218] Testing event ipi_raise: OK [ 148.447702] Testing event instruction_emulation: OK [ 148.483709] Testing event kvm_halt_poll_ns: OK [ 148.511602] Testing event kvm_age_page: OK [ 148.539660] Testing event kvm_fpu: OK [ 148.574191] Testing event kvm_mmio: OK [ 148.607967] Testing event kvm_ack_irq: OK [ 148.639098] Testing event kvm_set_irq: OK [ 148.671893] Testing event kvm_vcpu_wakeup: OK [ 148.703651] Testing event kvm_userspace_exit: OK [ 148.731802] Testing event kvm_timer_update_irq: OK [ 148.763485] Testing event kvm_toggle_cache: OK [ 148.791746] Testing event kvm_set_way_flush: OK [ 148.819086] Testing event kvm_test_age_hva: OK [ 148.851827] Testing event kvm_age_hva: OK [ 148.884272] Testing event kvm_set_spte_hva: OK [ 148.907929] Testing event kvm_unmap_hva_range: OK [ 148.939712] Testing event kvm_mmio_emulate: OK [ 148.970169] Testing event kvm_irq_line: OK [ 148.999930] Testing event kvm_access_fault: OK [ 149.031717] Testing event kvm_guest_fault: OK [ 149.062287] Testing event kvm_exit: OK [ 149.095734] Testing event kvm_entry: OK [ 149.116549] Testing event kvm_set_guest_debug: OK [ 149.138055] Testing event kvm_handle_sys_reg: OK [ 149.159708] Testing event trap_reg: OK [ 149.184181] Testing event kvm_arm_set_regset: OK [ 149.207824] Testing event kvm_arm_set_dreg32: OK [ 149.228435] Testing event kvm_arm_clear_debug: OK [ 149.250025] Testing event kvm_arm_setup_debug: OK [ 149.271703] Testing event kvm_hvc_arm64: OK [ 149.293068] Testing event kvm_wfx_arm64: OK [ 149.315143] Testing event vgic_update_irq_pending: OK [ 149.335721] Testing event task_rename: OK [ 149.356294] Testing event task_newtask: OK [ 149.379411] Testing event cpuhp_exit: OK [ 149.401838] Testing event cpuhp_multi_enter: OK [ 149.424673] Testing event cpuhp_enter: OK [ 149.445568] Testing event softirq_raise: OK [ 149.466969] Testing event softirq_exit: OK [ 149.487265] Testing event softirq_entry: OK [ 149.509559] Testing event irq_handler_exit: OK [ 149.533166] Testing event irq_handler_entry: OK [ 149.553776] Testing event signal_deliver: OK [ 149.575406] Testing event signal_generate: OK [ 149.596520] Testing event workqueue_execute_end: OK [ 149.620271] Testing event workqueue_execute_start: OK [ 149.642095] Testing event workqueue_activate_work: OK [ 149.665623] Testing event workqueue_queue_work: OK [ 149.687071] Testing event sched_wake_idle_without_ipi: OK [ 149.708813] Testing event sched_swap_numa: OK [ 149.731221] Testing event sched_stick_numa: OK [ 149.754119] Testing event sched_move_numa: OK [ 149.775690] Testing event sched_process_hang: OK [ 149.796559] Testing event sched_pi_setprio: OK [ 149.817408] Testing event sched_stat_runtime: [ 149.819918] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 149.839612] OK [ 149.839845] Testing event sched_stat_blocked: OK [ 149.860948] Testing event sched_stat_iowait: OK [ 149.884173] Testing event sched_stat_sleep: OK [ 149.905587] Testing event sched_stat_wait: OK [ 149.927128] Testing event sched_process_exec: OK [ 149.948562] Testing event sched_process_fork: OK [ 149.972102] Testing event sched_process_wait: OK [ 149.995427] Testing event sched_wait_task: OK [ 150.018020] Testing event sched_process_exit: OK [ 150.039864] Testing event sched_process_free: OK [ 150.060596] Testing event sched_migrate_task: OK [ 150.081494] Testing event sched_switch: OK [ 150.101765] Testing event sched_wakeup_new: OK [ 150.123298] Testing event sched_wakeup: OK [ 150.145355] Testing event sched_waking: OK [ 150.167712] Testing event sched_kthread_stop_ret: OK [ 150.188171] Testing event sched_kthread_stop: OK [ 150.207749] Testing event lock_acquired: OK [ 150.236396] Testing event lock_contended: OK [ 150.257509] Testing event lock_release: OK [ 150.289269] Testing event lock_acquire: OK [ 150.317618] Testing event console: OK [ 150.340231] Testing event rcu_barrier: OK [ 150.359863] Testing event rcu_torture_read: OK [ 150.379729] Testing event rcu_batch_end: OK [ 150.400873] Testing event rcu_invoke_kfree_callback: OK [ 150.423421] Testing event rcu_invoke_callback: OK [ 150.445634] Testing event rcu_batch_start: OK [ 150.467176] Testing event rcu_kfree_callback: OK [ 150.488259] Testing event rcu_callback: OK [ 150.507960] Testing event rcu_dyntick: OK [ 150.532626] Testing event rcu_fqs: OK [ 150.555663] Testing event rcu_quiescent_state_report: OK [ 150.576581] Testing event rcu_unlock_preempted_task: OK [ 150.600224] Testing event rcu_preempt_task: OK [ 150.623299] Testing event rcu_nocb_wake: OK [ 150.648548] Testing event rcu_exp_funnel_lock: OK [ 150.670185] Testing event rcu_exp_grace_period: OK [ 150.692495] Testing event rcu_grace_period_init: OK [ 150.715336] Testing event rcu_future_grace_period: OK [ 150.737394] Testing event rcu_grace_period: OK [ 150.764740] Testing event rcu_utilization: OK [ 150.788195] Testing event swiotlb_bounced: OK [ 150.807714] Testing event tick_stop: OK [ 150.828451] Testing event itimer_expire: OK [ 150.849607] Testing event itimer_state: OK [ 150.871031] Testing event hrtimer_cancel: OK [ 150.892883] Testing event hrtimer_expire_exit: OK [ 150.916375] Testing event hrtimer_expire_entry: OK [ 150.939320] Testing event hrtimer_start: OK [ 150.962120] Testing event hrtimer_init: OK [ 150.986112] Testing event timer_cancel: OK [ 151.010291] Testing event timer_expire_exit: OK [ 151.031878] Testing event timer_expire_entry: OK [ 151.055759] Testing event timer_start: OK [ 151.079975] Testing event timer_init: OK [ 151.100606] Testing event alarmtimer_cancel: OK [ 151.124352] Testing event alarmtimer_start: OK [ 151.146099] Testing event alarmtimer_fired: OK [ 151.167713] Testing event alarmtimer_suspend: OK [ 151.188724] Testing event module_request: OK [ 151.210290] Testing event module_put: OK [ 151.231761] Testing event module_get: OK [ 151.252678] Testing event module_free: OK [ 151.274054] Testing event module_load: OK [ 151.295709] Testing event cgroup_transfer_tasks: OK [ 151.316688] Testing event cgroup_attach_task: OK [ 151.339276] Testing event cgroup_rename: OK [ 151.361161] Testing event cgroup_release: OK [ 151.381892] Testing event cgroup_rmdir: OK [ 151.403334] Testing event cgroup_mkdir: OK [ 151.425540] Testing event cgroup_remount: OK [ 151.445833] Testing event cgroup_destroy_root: OK [ 151.465949] Testing event cgroup_setup_root: OK [ 151.489169] Testing event irq_enable: OK [ 151.515517] Testing event irq_disable: OK [ 151.541837] Testing event ftrace_test_filter: [ 151.547952] KGDB: Unregistered I/O driver kgdbts, debugger disabled [ 151.565861] OK [ 151.566096] Testing event dev_pm_qos_remove_request: OK [ 151.589164] Testing event dev_pm_qos_update_request: OK [ 151.611055] Testing event dev_pm_qos_add_request: OK [ 151.631307] Testing event pm_qos_update_flags: OK [ 151.653481] Testing event pm_qos_update_target: OK [ 151.673758] Testing event pm_qos_update_request_timeout: OK [ 151.695520] Testing event pm_qos_remove_request: OK [ 151.716408] Testing event pm_qos_update_request: OK [ 151.740200] Testing event pm_qos_add_request: OK [ 151.763446] Testing event power_domain_target: OK [ 151.785884] Testing event clock_set_rate: OK [ 151.807834] Testing event clock_disable: OK [ 151.828674] Testing event clock_enable: OK [ 151.851967] Testing event wakeup_source_deactivate: OK [ 151.875452] Testing event wakeup_source_activate: OK [ 151.898098] Testing event suspend_resume: OK [ 151.932254] Testing event device_pm_callback_end: OK [ 151.952589] Testing event device_pm_callback_start: OK [ 151.975555] Testing event cpu_frequency_limits: OK [ 151.995570] Testing event cpu_frequency: OK [ 152.016199] Testing event pstate_sample: OK [ 152.036865] Testing event powernv_throttle: OK [ 152.057392] Testing event cpu_idle: OK [ 152.080204] Testing event rpm_return_int: OK [ 152.104027] Testing event rpm_idle: OK [ 152.124051] Testing event rpm_resume: OK [ 152.146098] Testing event rpm_suspend: OK [ 152.167690] Testing event benchmark_event: OK [ 152.287498] Testing event xdp_devmap_xmit: OK [ 152.311050] Testing event xdp_cpumap_enqueue: OK [ 152.331725] Testing event xdp_cpumap_kthread: OK [ 152.352594] Testing event xdp_redirect_map_err: OK [ 152.374119] Testing event xdp_redirect_map: OK [ 152.395829] Testing event xdp_redirect_err: OK [ 152.416702] Testing event xdp_redirect: OK [ 152.439268] Testing event xdp_exception: OK [ 152.460135] Testing event rseq_ip_fixup: OK [ 152.480474] Testing event rseq_update: OK [ 152.501996] Testing event file_check_and_advance_wb_err: OK [ 152.523733] Testing event filemap_set_wb_err: OK [ 152.544590] Testing event mm_filemap_add_to_page_cache: OK [ 152.565518] Testing event mm_filemap_delete_from_page_cache: OK [ 152.587121] Testing event compact_retry: OK [ 152.608459] Testing event skip_task_reaping: OK [ 152.632189] Testing event finish_task_reaping: OK [ 152.655368] Testing event start_task_reaping: OK [ 152.678114] Testing event wake_reaper: OK [ 152.699790] Testing event mark_victim: OK [ 152.720578] Testing event reclaim_retry_zone: OK [ 152.741497] Testing event oom_score_adj_update: OK [ 152.763138] Testing event mm_lru_activate: OK [ 152.784476] Testing event mm_lru_insertion: OK [ 152.808112] Testing event mm_vmscan_inactive_list_is_low: OK [ 152.831393] Testing event mm_vmscan_lru_shrink_active: OK [ 152.854063] Testing event mm_vmscan_lru_shrink_inactive: OK [ 152.875765] Testing event mm_vmscan_writepage: OK [ 152.897623] Testing event mm_vmscan_lru_isolate: OK [ 152.921358] Testing event mm_shrink_slab_end: OK [ 152.945750] Testing event mm_shrink_slab_start: OK [ 152.989834] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 153.012001] Testing event mm_vmscan_memcg_reclaim_end: OK [ 153.031863] Testing event mm_vmscan_direct_reclaim_end: OK [ 153.051783] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 153.071799] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 153.091941] Testing event mm_vmscan_direct_reclaim_begin: OK [ 153.111830] Testing event mm_vmscan_wakeup_kswapd: OK [ 153.131464] Testing event mm_vmscan_kswapd_wake: OK [ 153.149854] Testing event mm_vmscan_kswapd_sleep: OK [ 153.171389] Testing event percpu_destroy_chunk: OK [ 153.193651] Testing event percpu_create_chunk: OK [ 153.213945] Testing event percpu_alloc_percpu_fail: OK [ 153.235394] Testing event percpu_free_percpu: OK [ 153.257712] Testing event percpu_alloc_percpu: OK [ 153.279035] Testing event mm_page_alloc_extfrag: OK [ 153.300798] Testing event mm_page_pcpu_drain: OK [ 153.323133] Testing event mm_page_alloc_zone_locked: OK [ 153.343966] Testing event mm_page_alloc: OK [ 153.364554] Testing event mm_page_free_batched: OK [ 153.385567] Testing event mm_page_free: OK [ 153.407203] Testing event kmem_cache_free: OK [ 153.428794] Testing event kfree: OK [ 153.452214] Testing event kmem_cache_alloc_node: OK [ 153.475492] Testing event kmalloc_node: OK [ 153.496135] Testing event kmem_cache_alloc: OK [ 153.519568] Testing event kmalloc: OK [ 153.540361] Testing event mm_compaction_kcompactd_wake: OK [ 153.562183] Testing event mm_compaction_wakeup_kcompactd: OK [ 153.583765] Testing event mm_compaction_kcompactd_sleep: OK [ 153.604643] Testing event mm_compaction_defer_reset: OK [ 153.625534] Testing event mm_compaction_defer_compaction: OK [ 153.647095] Testing event mm_compaction_deferred: OK [ 153.668547] Testing event mm_compaction_suitable: OK [ 153.692617] Testing event mm_compaction_finished: OK [ 153.715272] Testing event mm_compaction_try_to_compact_pages: OK [ 153.735818] Testing event mm_compaction_end: OK [ 153.756537] Testing event mm_compaction_begin: OK [ 153.777669] Testing event mm_compaction_migratepages: OK [ 153.800509] Testing event mm_compaction_isolate_freepages: OK [ 153.823142] Testing event mm_compaction_isolate_migratepages: OK [ 153.845229] Testing event mm_migrate_pages: OK [ 153.865807] Testing event mm_collapse_huge_page_swapin: OK [ 153.887778] Testing event mm_collapse_huge_page_isolate: OK [ 153.909327] Testing event mm_collapse_huge_page: OK [ 153.929954] Testing event mm_khugepaged_scan_pmd: OK [ 153.951518] Testing event test_pages_isolated: OK [ 153.973428] Testing event cma_release: OK [ 153.993872] Testing event cma_alloc: OK [ 154.015530] Testing event page_ref_unfreeze: OK [ 154.040142] Testing event page_ref_freeze: OK [ 154.063811] Testing event page_ref_mod_unless: OK [ 154.091715] Testing event page_ref_mod_and_return: OK [ 154.116141] Testing event page_ref_mod_and_test: OK [ 154.223988] Testing event page_ref_mod: OK [ 154.268706] Testing event page_ref_set: OK [ 154.293463] Testing event sb_clear_inode_writeback: OK [ 154.316515] Testing event sb_mark_inode_writeback: OK [ 154.339257] Testing event writeback_dirty_inode_enqueue: OK [ 154.359833] Testing event writeback_lazytime_iput: OK [ 154.381778] Testing event writeback_lazytime: OK [ 154.403056] Testing event writeback_single_inode: OK [ 154.424399] Testing event writeback_single_inode_start: OK [ 154.446184] Testing event writeback_wait_iff_congested: OK [ 154.469639] Testing event writeback_congestion_wait: OK [ 154.491507] Testing event writeback_sb_inodes_requeue: OK [ 154.511832] Testing event balance_dirty_pages: OK [ 154.533064] Testing event bdi_dirty_ratelimit: OK [ 154.553839] Testing event global_dirty_state: OK [ 154.575475] Testing event writeback_queue_io: OK [ 154.597442] Testing event wbc_writepage: OK [ 154.619299] Testing event writeback_bdi_register: OK [ 154.640734] Testing event writeback_wake_background: OK [ 154.663689] Testing event writeback_pages_written: OK [ 154.684314] Testing event writeback_wait: OK [ 154.705631] Testing event writeback_written: OK [ 154.727408] Testing event writeback_start: OK [ 154.749274] Testing event writeback_exec: OK [ 154.769860] Testing event writeback_queue: OK [ 154.791559] Testing event writeback_write_inode: OK [ 154.813830] Testing event writeback_write_inode_start: OK [ 154.835634] Testing event writeback_dirty_inode: OK [ 154.856199] Testing event writeback_dirty_inode_start: OK [ 154.878184] Testing event writeback_mark_inode_dirty: OK [ 154.899787] Testing event writeback_dirty_page: OK [ 154.920554] Testing event dax_writeback_one: OK [ 154.941508] Testing event dax_writeback_range_done: OK [ 154.963020] Testing event dax_writeback_range: OK [ 154.985276] Testing event dax_insert_mapping: OK [ 155.011427] Testing event dax_insert_pfn_mkwrite: OK [ 155.032046] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 155.054165] Testing event dax_load_hole: OK [ 155.075659] Testing event dax_pte_fault_done: OK [ 155.096501] Testing event dax_pte_fault: OK [ 155.117576] Testing event dax_pmd_insert_mapping: OK [ 155.138984] Testing event dax_pmd_load_hole_fallback: OK [ 155.161138] Testing event dax_pmd_load_hole: OK [ 155.185452] Testing event dax_pmd_fault_done: OK [ 155.207680] Testing event dax_pmd_fault: OK [ 155.229012] Testing event generic_add_lease: OK [ 155.249809] Testing event time_out_leases: OK [ 155.271076] Testing event generic_delete_lease: OK [ 155.293442] Testing event break_lease_unblock: OK [ 155.317841] Testing event break_lease_block: OK [ 155.339722] Testing event break_lease_noblock: OK [ 155.360555] Testing event flock_lock_inode: OK [ 155.382106] Testing event locks_remove_posix: OK [ 155.405662] Testing event fcntl_setlk: OK [ 155.428036] Testing event posix_lock_inode: OK [ 155.449555] Testing event locks_get_lock_context: OK [ 155.471770] Testing event block_rq_remap: OK [ 155.492542] Testing event block_bio_remap: OK [ 155.515121] Testing event block_split: OK [ 155.535761] Testing event block_unplug: OK [ 155.556752] Testing event block_plug: OK [ 155.579291] Testing event block_sleeprq: OK [ 155.599678] Testing event block_getrq: OK [ 155.621184] Testing event block_bio_queue: OK [ 155.643085] Testing event block_bio_frontmerge: OK [ 155.663759] Testing event block_bio_backmerge: OK [ 155.684854] Testing event block_bio_complete: OK [ 155.708147] Testing event block_bio_bounce: OK [ 155.731323] Testing event block_rq_issue: OK [ 155.754075] Testing event block_rq_insert: OK [ 155.775899] Testing event block_rq_complete: OK [ 155.796567] Testing event block_rq_requeue: OK [ 155.818074] Testing event block_dirty_buffer: OK [ 155.839747] Testing event block_touch_buffer: OK [ 155.860633] Testing event wbt_timer: OK [ 155.881483] Testing event wbt_step: OK [ 155.903068] Testing event wbt_lat: OK [ 155.924545] Testing event wbt_stat: OK [ 155.948216] Testing event gpio_value: OK [ 155.971742] Testing event gpio_direction: OK [ 155.992024] Testing event clk_set_duty_cycle_complete: OK [ 156.012468] Testing event clk_set_duty_cycle: OK [ 156.034057] Testing event clk_set_phase_complete: OK [ 156.055766] Testing event clk_set_phase: OK [ 156.076554] Testing event clk_set_parent_complete: OK [ 156.097591] Testing event clk_set_parent: OK [ 156.119149] Testing event clk_set_rate_complete: OK [ 156.140474] Testing event clk_set_rate: OK [ 156.163045] Testing event clk_unprepare_complete: OK [ 156.183840] Testing event clk_unprepare: OK [ 156.207791] Testing event clk_prepare_complete: OK [ 156.228361] Testing event clk_prepare: OK [ 156.251265] Testing event clk_disable_complete: OK [ 156.271861] Testing event clk_disable: OK [ 156.295762] Testing event clk_enable_complete: OK [ 156.316475] Testing event clk_enable: OK [ 156.340232] Testing event rpmh_send_msg: OK [ 156.363322] Testing event rpmh_tx_done: OK [ 156.383714] Testing event regulator_set_voltage_complete: OK [ 156.404580] Testing event regulator_set_voltage: OK [ 156.425465] Testing event regulator_disable_complete: OK [ 156.447100] Testing event regulator_disable: OK [ 156.468448] Testing event regulator_enable_complete: OK [ 156.491078] Testing event regulator_enable_delay: OK [ 156.511740] Testing event regulator_enable: OK [ 156.533042] Testing event urandom_read: OK [ 156.553745] Testing event random_read: OK [ 156.575669] Testing event extract_entropy_user: OK [ 156.596365] Testing event extract_entropy: OK [ 156.618066] Testing event get_random_bytes_arch: OK [ 156.641395] Testing event get_random_bytes: OK [ 156.661793] Testing event xfer_secondary_pool: OK [ 156.683222] Testing event add_disk_randomness: OK [ 156.705716] Testing event add_input_randomness: OK [ 156.727958] Testing event debit_entropy: OK [ 156.749550] Testing event push_to_pool: OK [ 156.770276] Testing event credit_entropy_bits: OK [ 156.791235] Testing event mix_pool_bytes_nolock: OK [ 156.813191] Testing event mix_pool_bytes: OK [ 156.833728] Testing event add_device_randomness: OK [ 156.855393] Testing event io_page_fault: OK [ 156.877400] Testing event unmap: OK [ 156.897733] Testing event map: OK [ 156.919268] Testing event detach_device_from_domain: OK [ 156.941392] Testing event attach_device_to_domain: OK [ 156.961798] Testing event remove_device_from_group: OK [ 156.983295] Testing event add_device_to_group: OK [ 157.005416] Testing event regcache_drop_region: OK [ 157.025767] Testing event regmap_async_complete_done: OK [ 157.047251] Testing event regmap_async_complete_start: OK [ 157.069403] Testing event regmap_async_io_complete: OK [ 157.089774] Testing event regmap_async_write_start: OK [ 157.111275] Testing event regmap_cache_bypass: OK [ 157.133439] Testing event regmap_cache_only: OK [ 157.153798] Testing event regcache_sync: OK [ 157.175911] Testing event regmap_hw_write_done: OK [ 157.197508] Testing event regmap_hw_write_start: OK [ 157.218015] Testing event regmap_hw_read_done: OK [ 157.239594] Testing event regmap_hw_read_start: OK [ 157.261190] Testing event regmap_reg_read_cache: OK [ 157.281808] Testing event regmap_reg_read: OK [ 157.303326] Testing event regmap_reg_write: OK [ 157.325390] Testing event dma_fence_wait_end: OK [ 157.345843] Testing event dma_fence_wait_start: OK [ 157.365863] Testing event dma_fence_signaled: OK [ 157.389219] Testing event dma_fence_enable_signal: OK [ 157.411415] Testing event dma_fence_destroy: OK [ 157.429807] Testing event dma_fence_init: OK [ 157.451165] Testing event dma_fence_emit: OK [ 157.472281] Testing event sync_timeline: OK [ 157.495119] Testing event spi_transfer_stop: OK [ 157.515718] Testing event spi_transfer_start: OK [ 157.536551] Testing event spi_message_done: OK [ 157.558125] Testing event spi_message_start: OK [ 157.579680] Testing event spi_message_submit: OK [ 157.600667] Testing event spi_controller_busy: OK [ 157.622086] Testing event spi_controller_idle: OK [ 157.643681] Testing event rtc_timer_fired: OK [ 157.664510] Testing event rtc_timer_dequeue: OK [ 157.687570] Testing event rtc_timer_enqueue: OK [ 157.707930] Testing event rtc_read_offset: OK [ 157.728409] Testing event rtc_set_offset: OK [ 157.750155] Testing event rtc_alarm_irq_enable: OK [ 157.771744] Testing event rtc_irq_set_state: OK [ 157.792551] Testing event rtc_irq_set_freq: OK [ 157.814128] Testing event rtc_read_alarm: OK [ 157.837098] Testing event rtc_set_alarm: OK [ 157.857502] Testing event rtc_read_time: OK [ 157.879681] Testing event rtc_set_time: OK [ 157.900526] Testing event memory_failure_event: OK [ 157.924146] Testing event aer_event: OK [ 157.947429] Testing event non_standard_event: OK [ 157.970024] Testing event arm_event: OK [ 157.991693] Testing event mc_event: OK [ 158.012962] Testing event binder_return: OK [ 158.033791] Testing event binder_command: OK [ 158.055055] Testing event binder_unmap_kernel_end: OK [ 158.076521] Testing event binder_unmap_kernel_start: OK [ 158.100143] Testing event binder_unmap_user_end: OK [ 158.123417] Testing event binder_unmap_user_start: OK [ 158.146124] Testing event binder_alloc_page_end: OK [ 158.167722] Testing event binder_alloc_page_start: OK [ 158.188547] Testing event binder_free_lru_end: OK [ 158.210117] Testing event binder_free_lru_start: OK [ 158.233951] Testing event binder_alloc_lru_end: OK [ 158.255491] Testing event binder_alloc_lru_start: OK [ 158.276263] Testing event binder_update_page_range: OK [ 158.295748] Testing event binder_transaction_failed_buffer_release: OK [ 158.316482] Testing event binder_transaction_buffer_release: OK [ 158.338164] Testing event binder_transaction_alloc_buf: OK [ 158.359799] Testing event binder_transaction_fd_recv: OK [ 158.381180] Testing event binder_transaction_fd_send: OK [ 158.403157] Testing event binder_transaction_ref_to_ref: OK [ 158.424374] Testing event binder_transaction_ref_to_node: OK [ 158.447170] Testing event binder_transaction_node_to_ref: OK [ 158.470095] Testing event binder_transaction_received: OK [ 158.491775] Testing event binder_transaction: OK [ 158.512550] Testing event binder_wait_for_work: OK [ 158.533545] Testing event binder_read_done: OK [ 158.555202] Testing event binder_write_done: OK [ 158.576489] Testing event binder_ioctl_done: OK [ 158.600181] Testing event binder_unlock: OK [ 158.622276] Testing event binder_locked: OK [ 158.643625] Testing event binder_lock: OK [ 158.664363] Testing event binder_ioctl: OK [ 158.686062] Testing event br_fdb_update: OK [ 158.707689] Testing event fdb_delete: OK [ 158.728444] Testing event br_fdb_external_learn_add: OK [ 158.750010] Testing event br_fdb_add: OK [ 158.773154] Testing event qdisc_dequeue: OK [ 158.793542] Testing event fib_table_lookup: OK [ 158.815813] Testing event tcp_probe: OK [ 158.838108] Testing event tcp_retransmit_synack: OK [ 158.861465] Testing event tcp_rcv_space_adjust: OK [ 158.881825] Testing event tcp_destroy_sock: OK [ 158.903504] Testing event tcp_receive_reset: OK [ 158.925270] Testing event tcp_send_reset: OK [ 158.945802] Testing event tcp_retransmit_skb: OK [ 158.967551] Testing event udp_fail_queue_rcv_skb: OK [ 158.989253] Testing event inet_sock_set_state: OK [ 159.009877] Testing event sock_exceed_buf_limit: OK [ 159.031144] Testing event sock_rcvqueue_full: OK [ 159.052456] Testing event napi_poll: OK [ 159.076570] Testing event netif_rx_ni_entry: OK [ 159.098130] Testing event netif_rx_entry: OK [ 159.119745] Testing event netif_receive_skb_list_entry: OK [ 159.140608] Testing event netif_receive_skb_entry: OK [ 159.161574] Testing event napi_gro_receive_entry: OK [ 159.183056] Testing event napi_gro_frags_entry: OK [ 159.208521] Testing event netif_rx: OK [ 159.226992] Testing event netif_receive_skb: OK [ 159.247211] Testing event net_dev_queue: OK [ 159.268407] Testing event net_dev_xmit: OK [ 159.291286] Testing event net_dev_start_xmit: OK [ 159.311727] Testing event skb_copy_datagram_iovec: OK [ 159.332578] Testing event consume_skb: OK [ 159.355712] Testing event kfree_skb: OK [ 159.377382] Testing event 9p_protocol_dump: OK [ 159.401419] Testing event 9p_client_res: OK [ 159.423504] Testing event 9p_client_req: OK [ 159.444418] Running tests on trace event systems: [ 159.444777] Testing event system 9p: OK [ 159.475232] Testing event system skb: OK [ 159.499492] Testing event system net: OK [ 159.527468] Testing event system napi: OK [ 159.551363] Testing event system sock: OK [ 159.575460] Testing event system udp: OK [ 159.595765] Testing event system tcp: OK [ 159.621152] Testing event system fib: OK [ 159.643491] Testing event system qdisc: OK [ 159.664717] Testing event system bridge: OK [ 159.688655] Testing event system binder: OK [ 159.728805] Testing event system ras: OK [ 159.752346] Testing event system rtc: OK [ 159.784833] Testing event system spi: OK [ 159.810926] Testing event system sync_trace: OK [ 159.833817] Testing event system dma_fence: OK [ 159.861571] Testing event system regmap: OK [ 159.896440] Testing event system iommu: OK [ 159.920226] Testing event system random: OK [ 159.952473] Testing event system regulator: OK [ 159.983374] Testing event system rpmh: OK [ 160.003491] Testing event system clk: OK [ 160.033319] Testing event system gpio: OK [ 160.057348] Testing event system wbt: OK [ 160.086221] Testing event system block: OK [ 160.119671] Testing event system filelock: OK [ 160.145426] Testing event system fs_dax: OK [ 160.174264] Testing event system writeback: OK [ 160.213940] Testing event system page_ref: OK [ 160.359753] Testing event system cma: OK [ 160.383138] Testing event system page_isolation: OK [ 160.403940] Testing event system huge_memory: OK [ 160.428634] Testing event system migrate: OK [ 160.449320] Testing event system compaction: OK [ 160.479469] Testing event system kmem: OK [ 160.512192] Testing event system percpu: OK [ 160.535998] Testing event system vmscan: OK [ 160.568432] Testing event system pagemap: OK [ 160.591098] Testing event system oom: OK [ 160.617914] Testing event system filemap: OK [ 160.644367] Testing event system rseq: OK [ 160.667029] Testing event system xdp: OK [ 160.695224] Testing event system benchmark: OK [ 160.815888] Testing event system rpm: OK [ 160.841377] Testing event system power: OK [ 160.883905] Testing event system test: OK [ 160.903555] Testing event system preemptirq: OK [ 160.937672] Testing event system cgroup: OK [ 160.964675] Testing event system module: OK [ 160.988120] Testing event system alarmtimer: OK [ 161.012453] Testing event system timer: OK [ 161.046145] Testing event system swiotlb: OK [ 161.068461] Testing event system rcu: OK [ 161.117148] Testing event system printk: OK [ 161.139268] Testing event system lock: OK [ 161.179982] Testing event system sched: OK [ 161.219798] Testing event system workqueue: OK [ 161.244332] Testing event system signal: OK [ 161.267566] Testing event system irq: OK [ 161.293071] Testing event system cpuhp: OK [ 161.320393] Testing event system task: OK [ 161.342287] Testing event system kvm: OK [ 161.387693] Testing event system emulation: OK [ 161.408503] Testing event system ipi: OK [ 161.436203] Testing event system raw_syscalls: OK [ 161.459561] Testing event system initcall: OK [ 161.483641] Running tests on all trace events: [ 161.483912] Testing all events: OK [ 162.510260] ftrace_replace_code: 1 [ 180.221463] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 180.233724] Running tests again, along with the function tracer [ 180.240320] Running tests on trace events: [ 180.244652] Testing event initcall_finish: OK [ 180.364473] Testing event initcall_start: OK [ 180.467838] Testing event initcall_level: OK [ 180.584992] Testing event sys_exit: OK [ 180.712929] Testing event sys_enter: OK [ 180.829429] Testing event ipi_exit: OK [ 180.941199] Testing event ipi_entry: OK [ 181.064406] Testing event ipi_raise: OK [ 181.185737] Testing event instruction_emulation: OK [ 181.306283] Testing event kvm_halt_poll_ns: OK [ 181.417953] Testing event kvm_age_page: OK [ 181.528103] Testing event kvm_fpu: OK [ 181.640600] Testing event kvm_mmio: OK [ 181.748892] Testing event kvm_ack_irq: OK [ 181.869783] Testing event kvm_set_irq: OK [ 181.982201] Testing event kvm_vcpu_wakeup: OK [ 182.091793] Testing event kvm_userspace_exit: OK [ 182.190080] Testing event kvm_timer_update_irq: OK [ 182.304272] Testing event kvm_toggle_cache: OK [ 182.418038] Testing event kvm_set_way_flush: OK [ 182.524906] Testing event kvm_test_age_hva: OK [ 182.624654] Testing event kvm_age_hva: OK [ 182.744393] Testing event kvm_set_spte_hva: OK [ 182.865560] Testing event kvm_unmap_hva_range: OK [ 183.021608] Testing event kvm_mmio_emulate: OK [ 183.120496] Testing event kvm_irq_line: OK [ 183.236396] Testing event kvm_access_fault: OK [ 183.352727] Testing event kvm_guest_fault: OK [ 183.462258] Testing event kvm_exit: OK [ 183.573117] Testing event kvm_entry: OK [ 183.677840] Testing event kvm_set_guest_debug: OK [ 183.788867] Testing event kvm_handle_sys_reg: OK [ 183.905848] Testing event trap_reg: OK [ 184.013678] Testing event kvm_arm_set_regset: OK [ 184.125805] Testing event kvm_arm_set_dreg32: OK [ 184.248400] Testing event kvm_arm_clear_debug: OK [ 184.364491] Testing event kvm_arm_setup_debug: OK [ 184.493779] Testing event kvm_hvc_arm64: OK [ 184.606217] Testing event kvm_wfx_arm64: OK [ 184.729704] Testing event vgic_update_irq_pending: OK [ 184.853527] Testing event task_rename: OK [ 184.958229] Testing event task_newtask: OK [ 185.084369] Testing event cpuhp_exit: OK [ 185.201979] Testing event cpuhp_multi_enter: OK [ 185.324932] Testing event cpuhp_enter: OK [ 185.445835] Testing event softirq_raise: OK [ 185.560618] Testing event softirq_exit: OK [ 185.669589] Testing event softirq_entry: OK [ 185.785880] Testing event irq_handler_exit: OK [ 185.900800] Testing event irq_handler_entry: OK [ 186.012310] Testing event signal_deliver: OK [ 186.136401] Testing event signal_generate: OK [ 186.257921] Testing event workqueue_execute_end: OK [ 186.377375] Testing event workqueue_execute_start: OK [ 186.501649] Testing event workqueue_activate_work: OK [ 186.620706] Testing event workqueue_queue_work: OK [ 186.738143] Testing event sched_wake_idle_without_ipi: OK [ 186.880389] Testing event sched_swap_numa: OK [ 186.994205] Testing event sched_stick_numa: OK [ 187.112479] Testing event sched_move_numa: OK [ 187.218230] Testing event sched_process_hang: OK [ 187.357297] Testing event sched_pi_setprio: OK [ 187.461631] Testing event sched_stat_runtime: OK [ 187.572447] Testing event sched_stat_blocked: OK [ 187.689570] Testing event sched_stat_iowait: OK [ 187.800765] Testing event sched_stat_sleep: OK [ 187.925759] Testing event sched_stat_wait: OK [ 188.041615] Testing event sched_process_exec: OK [ 188.169006] Testing event sched_process_fork: OK [ 188.281843] Testing event sched_process_wait: OK [ 188.409474] Testing event sched_wait_task: OK [ 188.520677] Testing event sched_process_exit: OK [ 188.633822] Testing event sched_process_free: OK [ 188.769801] Testing event sched_migrate_task: OK [ 188.881318] Testing event sched_switch: OK [ 189.003018] Testing event sched_wakeup_new: OK [ 189.120852] Testing event sched_wakeup: OK [ 189.232509] Testing event sched_waking: OK [ 189.346250] Testing event sched_kthread_stop_ret: OK [ 189.453042] Testing event sched_kthread_stop: OK [ 189.562270] Testing event lock_acquired: OK [ 189.701350] Testing event lock_contended: OK [ 189.806046] Testing event lock_release: OK [ 189.960559] Testing event lock_acquire: OK [ 190.104594] Testing event console: OK [ 190.196439] Testing event rcu_barrier: OK [ 190.325107] Testing event rcu_torture_read: OK [ 190.440478] Testing event rcu_batch_end: OK [ 190.578071] Testing event rcu_invoke_kfree_callback: OK [ 190.681500] Testing event rcu_invoke_callback: OK [ 190.797082] Testing event rcu_batch_start: OK [ 190.919793] Testing event rcu_kfree_callback: OK [ 191.017999] Testing event rcu_callback: OK [ 191.136941] Testing event rcu_dyntick: OK [ 191.273527] Testing event rcu_fqs: OK [ 191.389814] Testing event rcu_quiescent_state_report: OK [ 191.516658] Testing event rcu_unlock_preempted_task: OK [ 191.648261] Testing event rcu_preempt_task: OK [ 191.765784] Testing event rcu_nocb_wake: OK [ 191.901084] Testing event rcu_exp_funnel_lock: OK [ 192.021009] Testing event rcu_exp_grace_period: OK [ 192.141894] Testing event rcu_grace_period_init: OK [ 192.266989] Testing event rcu_future_grace_period: OK [ 192.393540] Testing event rcu_grace_period: OK [ 192.520304] Testing event rcu_utilization: OK [ 192.633533] Testing event swiotlb_bounced: OK [ 192.749724] Testing event tick_stop: OK [ 192.861874] Testing event itimer_expire: OK [ 192.965534] Testing event itimer_state: OK [ 193.068877] Testing event hrtimer_cancel: OK [ 193.184414] Testing event hrtimer_expire_exit: OK [ 193.298246] Testing event hrtimer_expire_entry: OK [ 193.422008] Testing event hrtimer_start: OK [ 193.550146] Testing event hrtimer_init: OK [ 193.660873] Testing event timer_cancel: OK [ 193.785240] Testing event timer_expire_exit: OK [ 193.908855] Testing event timer_expire_entry: OK [ 194.024591] Testing event timer_start: OK [ 194.148680] Testing event timer_init: OK [ 194.262083] Testing event alarmtimer_cancel: OK [ 194.363974] Testing event alarmtimer_start: OK [ 194.485819] Testing event alarmtimer_fired: OK [ 194.592920] Testing event alarmtimer_suspend: OK [ 194.704899] Testing event module_request: OK [ 194.812100] Testing event module_put: OK [ 194.932133] Testing event module_get: OK [ 195.048699] Testing event module_free: OK [ 195.168518] Testing event module_load: OK [ 195.269419] Testing event cgroup_transfer_tasks: OK [ 195.392820] Testing event cgroup_attach_task: OK [ 195.513370] Testing event cgroup_rename: OK [ 195.626165] Testing event cgroup_release: OK [ 195.749282] Testing event cgroup_rmdir: OK [ 195.861698] Testing event cgroup_mkdir: OK [ 195.998224] Testing event cgroup_remount: OK [ 196.117249] Testing event cgroup_destroy_root: OK [ 196.235052] Testing event cgroup_setup_root: OK [ 196.341627] Testing event irq_enable: OK [ 196.548485] Testing event irq_disable: OK [ 196.714227] Testing event ftrace_test_filter: OK [ 196.834060] Testing event dev_pm_qos_remove_request: OK [ 196.937510] Testing event dev_pm_qos_update_request: OK [ 197.053763] Testing event dev_pm_qos_add_request: OK [ 197.169813] Testing event pm_qos_update_flags: OK [ 197.290141] Testing event pm_qos_update_target: OK [ 197.408418] Testing event pm_qos_update_request_timeout: OK [ 197.533229] Testing event pm_qos_remove_request: OK [ 197.649099] Testing event pm_qos_update_request: OK [ 197.772887] Testing event pm_qos_add_request: OK [ 197.888619] Testing event power_domain_target: OK [ 198.001371] Testing event clock_set_rate: OK [ 198.105217] Testing event clock_disable: OK [ 198.236711] Testing event clock_enable: OK [ 198.343998] Testing event wakeup_source_deactivate: OK [ 198.461917] Testing event wakeup_source_activate: OK [ 198.576929] Testing event suspend_resume: OK [ 198.728670] Testing event device_pm_callback_end: OK [ 198.853252] Testing event device_pm_callback_start: OK [ 198.978171] Testing event cpu_frequency_limits: OK [ 199.110182] Testing event cpu_frequency: OK [ 199.214042] Testing event pstate_sample: OK [ 199.329573] Testing event powernv_throttle: OK [ 199.432828] Testing event cpu_idle: OK [ 199.553372] Testing event rpm_return_int: OK [ 199.665871] Testing event rpm_idle: OK [ 199.781245] Testing event rpm_resume: OK [ 199.928476] Testing event rpm_suspend: OK [ 200.029796] Testing event benchmark_event: OK [ 200.233340] Testing event xdp_devmap_xmit: OK [ 200.346258] Testing event xdp_cpumap_enqueue: OK [ 200.454159] Testing event xdp_cpumap_kthread: OK [ 200.574227] Testing event xdp_redirect_map_err: OK [ 200.684731] Testing event xdp_redirect_map: OK [ 200.806156] Testing event xdp_redirect_err: OK [ 200.925975] Testing event xdp_redirect: OK [ 201.036615] Testing event xdp_exception: OK [ 201.164575] Testing event rseq_ip_fixup: OK [ 201.290252] Testing event rseq_update: OK [ 201.401121] Testing event file_check_and_advance_wb_err: OK [ 201.499917] Testing event filemap_set_wb_err: OK [ 201.620727] Testing event mm_filemap_add_to_page_cache: OK [ 201.733947] Testing event mm_filemap_delete_from_page_cache: OK [ 201.860466] Testing event compact_retry: OK [ 201.965449] Testing event skip_task_reaping: OK [ 202.104261] Testing event finish_task_reaping: OK [ 202.224816] Testing event start_task_reaping: OK [ 202.330291] Testing event wake_reaper: OK [ 202.452418] Testing event mark_victim: OK [ 202.564094] Testing event reclaim_retry_zone: OK [ 202.684441] Testing event oom_score_adj_update: OK [ 202.800486] Testing event mm_lru_activate: OK [ 202.898165] Testing event mm_lru_insertion: OK [ 203.028830] Testing event mm_vmscan_inactive_list_is_low: OK [ 203.150282] Testing event mm_vmscan_lru_shrink_active: OK [ 203.280412] Testing event mm_vmscan_lru_shrink_inactive: OK [ 203.386298] Testing event mm_vmscan_writepage: OK [ 203.501794] Testing event mm_vmscan_lru_isolate: OK [ 203.612918] Testing event mm_shrink_slab_end: OK [ 203.725056] Testing event mm_shrink_slab_start: OK [ 203.822199] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 203.940259] Testing event mm_vmscan_memcg_reclaim_end: OK [ 204.056413] Testing event mm_vmscan_direct_reclaim_end: OK [ 204.176805] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 204.300691] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 204.406010] Testing event mm_vmscan_direct_reclaim_begin: OK [ 204.505661] Testing event mm_vmscan_wakeup_kswapd: OK [ 204.628545] Testing event mm_vmscan_kswapd_wake: OK [ 204.750078] Testing event mm_vmscan_kswapd_sleep: OK [ 204.876525] Testing event percpu_destroy_chunk: OK [ 205.001400] Testing event percpu_create_chunk: OK [ 205.124438] Testing event percpu_alloc_percpu_fail: OK [ 205.240390] Testing event percpu_free_percpu: OK [ 205.353996] Testing event percpu_alloc_percpu: OK [ 205.464854] Testing event mm_page_alloc_extfrag: OK [ 205.573085] Testing event mm_page_pcpu_drain: OK [ 205.672687] Testing event mm_page_alloc_zone_locked: OK [ 205.784288] Testing event mm_page_alloc: OK [ 205.905136] Testing event mm_page_free_batched: OK [ 206.012706] Testing event mm_page_free: OK [ 206.136263] Testing event kmem_cache_free: OK [ 206.232427] Testing event kfree: OK [ 206.352776] Testing event kmem_cache_alloc_node: OK [ 206.457850] Testing event kmalloc_node: OK [ 206.577893] Testing event kmem_cache_alloc: OK [ 206.697817] Testing event kmalloc: OK [ 206.821498] Testing event mm_compaction_kcompactd_wake: OK [ 206.934172] Testing event mm_compaction_wakeup_kcompactd: OK [ 207.049952] Testing event mm_compaction_kcompactd_sleep: OK [ 207.153943] Testing event mm_compaction_defer_reset: OK [ 207.261921] Testing event mm_compaction_defer_compaction: OK [ 207.372809] Testing event mm_compaction_deferred: OK [ 207.484552] Testing event mm_compaction_suitable: OK [ 207.617480] Testing event mm_compaction_finished: OK [ 207.722251] Testing event mm_compaction_try_to_compact_pages: OK [ 207.841323] Testing event mm_compaction_end: OK [ 207.958131] Testing event mm_compaction_begin: OK [ 208.070026] Testing event mm_compaction_migratepages: OK [ 208.202019] Testing event mm_compaction_isolate_freepages: OK [ 208.320676] Testing event mm_compaction_isolate_migratepages: OK [ 208.432587] Testing event mm_migrate_pages: OK [ 208.540424] Testing event mm_collapse_huge_page_swapin: OK [ 208.640282] Testing event mm_collapse_huge_page_isolate: OK [ 208.760715] Testing event mm_collapse_huge_page: OK [ 208.912313] Testing event mm_khugepaged_scan_pmd: OK [ 209.017560] Testing event test_pages_isolated: OK [ 209.132716] Testing event cma_release: OK [ 209.251902] Testing event cma_alloc: OK [ 209.346322] Testing event page_ref_unfreeze: OK [ 209.466174] Testing event page_ref_freeze: OK [ 209.586096] Testing event page_ref_mod_unless: OK [ 209.716740] Testing event page_ref_mod_and_return: OK [ 209.837645] Testing event page_ref_mod_and_test: OK [ 210.329368] Testing event page_ref_mod: OK [ 210.548466] Testing event page_ref_set: OK [ 210.670316] Testing event sb_clear_inode_writeback: OK [ 210.786018] Testing event sb_mark_inode_writeback: OK [ 210.904305] Testing event writeback_dirty_inode_enqueue: OK [ 211.012220] Testing event writeback_lazytime_iput: OK [ 211.124932] Testing event writeback_lazytime: OK [ 211.238295] Testing event writeback_single_inode: OK [ 211.360715] Testing event writeback_single_inode_start: OK [ 211.484456] Testing event writeback_wait_iff_congested: OK [ 211.602109] Testing event writeback_congestion_wait: OK [ 211.729882] Testing event writeback_sb_inodes_requeue: OK [ 211.841701] Testing event balance_dirty_pages: OK [ 211.969800] Testing event bdi_dirty_ratelimit: OK [ 212.080757] Testing event global_dirty_state: OK [ 212.209494] Testing event writeback_queue_io: OK [ 212.313503] Testing event wbc_writepage: OK [ 212.420375] Testing event writeback_bdi_register: OK [ 212.540293] Testing event writeback_wake_background: OK [ 212.656665] Testing event writeback_pages_written: OK [ 212.770000] Testing event writeback_wait: OK [ 212.898290] Testing event writeback_written: OK [ 213.005570] Testing event writeback_start: OK [ 213.117737] Testing event writeback_exec: OK [ 213.234177] Testing event writeback_queue: OK [ 213.353916] Testing event writeback_write_inode: OK [ 213.473614] Testing event writeback_write_inode_start: OK [ 213.593088] Testing event writeback_dirty_inode: OK [ 213.708355] Testing event writeback_dirty_inode_start: OK [ 213.825722] Testing event writeback_mark_inode_dirty: OK [ 213.953724] Testing event writeback_dirty_page: OK [ 214.064366] Testing event dax_writeback_one: OK [ 214.172756] Testing event dax_writeback_range_done: OK [ 214.294219] Testing event dax_writeback_range: OK [ 214.417091] Testing event dax_insert_mapping: OK [ 214.536681] Testing event dax_insert_pfn_mkwrite: OK [ 214.640647] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 214.752277] Testing event dax_load_hole: OK [ 214.860522] Testing event dax_pte_fault_done: OK [ 214.985225] Testing event dax_pte_fault: OK [ 215.109482] Testing event dax_pmd_insert_mapping: OK [ 215.221616] Testing event dax_pmd_load_hole_fallback: OK [ 215.344821] Testing event dax_pmd_load_hole: OK [ 215.450172] Testing event dax_pmd_fault_done: OK [ 215.576501] Testing event dax_pmd_fault: OK [ 215.698969] Testing event generic_add_lease: OK [ 215.840723] Testing event time_out_leases: OK [ 215.936458] Testing event generic_delete_lease: OK [ 216.056641] Testing event break_lease_unblock: OK [ 216.165854] Testing event break_lease_block: OK [ 216.289726] Testing event break_lease_noblock: OK [ 216.405575] Testing event flock_lock_inode: OK [ 216.512265] Testing event locks_remove_posix: OK [ 216.637339] Testing event fcntl_setlk: OK [ 216.773374] Testing event posix_lock_inode: OK [ 216.882276] Testing event locks_get_lock_context: OK [ 216.980642] Testing event block_rq_remap: OK [ 217.105180] Testing event block_bio_remap: OK [ 217.209861] Testing event block_split: OK [ 217.317114] Testing event block_unplug: OK [ 217.442215] Testing event block_plug: OK [ 217.546271] Testing event block_sleeprq: OK [ 217.662163] Testing event block_getrq: OK [ 217.789522] Testing event block_bio_queue: OK [ 217.910222] Testing event block_bio_frontmerge: OK [ 218.032711] Testing event block_bio_backmerge: OK [ 218.148984] Testing event block_bio_complete: OK [ 218.272666] Testing event block_bio_bounce: OK [ 218.389506] Testing event block_rq_issue: OK [ 218.509266] Testing event block_rq_insert: OK [ 218.618048] Testing event block_rq_complete: OK [ 218.742193] Testing event block_rq_requeue: OK [ 218.861313] Testing event block_dirty_buffer: OK [ 218.972634] Testing event block_touch_buffer: OK [ 219.072991] Testing event wbt_timer: OK [ 219.196270] Testing event wbt_step: OK [ 219.301471] Testing event wbt_lat: OK [ 219.424797] Testing event wbt_stat: OK [ 219.541898] Testing event gpio_value: OK [ 219.665048] Testing event gpio_direction: OK [ 219.788361] Testing event clk_set_duty_cycle_complete: OK [ 219.909216] Testing event clk_set_duty_cycle: OK [ 220.029055] Testing event clk_set_phase_complete: OK [ 220.138033] Testing event clk_set_phase: OK [ 220.261030] Testing event clk_set_parent_complete: OK [ 220.386048] Testing event clk_set_parent: OK [ 220.508685] Testing event clk_set_rate_complete: OK [ 220.606114] Testing event clk_set_rate: OK [ 220.727018] Testing event clk_unprepare_complete: OK [ 220.846316] Testing event clk_unprepare: OK [ 220.969592] Testing event clk_prepare_complete: OK [ 221.092629] Testing event clk_prepare: OK [ 221.198307] Testing event clk_disable_complete: OK [ 221.324815] Testing event clk_disable: OK [ 221.424311] Testing event clk_enable_complete: OK [ 221.529005] Testing event clk_enable: OK [ 221.637583] Testing event rpmh_send_msg: OK [ 221.748846] Testing event rpmh_tx_done: OK [ 221.877309] Testing event regulator_set_voltage_complete: OK [ 221.993799] Testing event regulator_set_voltage: OK [ 222.109474] Testing event regulator_disable_complete: OK [ 222.221089] Testing event regulator_disable: OK [ 222.332650] Testing event regulator_enable_complete: OK [ 222.449486] Testing event regulator_enable_delay: OK [ 222.557467] Testing event regulator_enable: OK [ 222.675768] Testing event urandom_read: OK [ 222.784422] Testing event random_read: OK [ 222.898187] Testing event extract_entropy_user: OK [ 223.016470] Testing event extract_entropy: OK [ 223.130133] Testing event get_random_bytes_arch: OK [ 223.253096] Testing event get_random_bytes: OK [ 223.369389] Testing event xfer_secondary_pool: OK [ 223.484332] Testing event add_disk_randomness: OK [ 223.595032] Testing event add_input_randomness: OK [ 223.692993] Testing event debit_entropy: OK [ 223.800262] Testing event push_to_pool: OK [ 223.913627] Testing event credit_entropy_bits: OK [ 224.021619] Testing event mix_pool_bytes_nolock: OK [ 224.128287] Testing event mix_pool_bytes: OK [ 224.234172] Testing event add_device_randomness: OK [ 224.345262] Testing event io_page_fault: OK [ 224.460658] Testing event unmap: OK [ 224.562329] Testing event map: OK [ 224.677316] Testing event detach_device_from_domain: OK [ 224.797624] Testing event attach_device_to_domain: OK [ 224.929988] Testing event remove_device_from_group: OK [ 225.050204] Testing event add_device_to_group: OK [ 225.177192] Testing event regcache_drop_region: OK [ 225.276845] Testing event regmap_async_complete_done: OK [ 225.394230] Testing event regmap_async_complete_start: OK [ 225.508326] Testing event regmap_async_io_complete: OK [ 225.602135] Testing event regmap_async_write_start: OK [ 225.711837] Testing event regmap_cache_bypass: OK [ 225.841805] Testing event regmap_cache_only: OK [ 225.952842] Testing event regcache_sync: OK [ 226.067911] Testing event regmap_hw_write_done: OK [ 226.164477] Testing event regmap_hw_write_start: OK [ 226.288780] Testing event regmap_hw_read_done: OK [ 226.402279] Testing event regmap_hw_read_start: OK [ 226.517751] Testing event regmap_reg_read_cache: OK [ 226.628544] Testing event regmap_reg_read: OK [ 226.741828] Testing event regmap_reg_write: OK [ 226.849856] Testing event dma_fence_wait_end: OK [ 226.974219] Testing event dma_fence_wait_start: OK [ 227.092434] Testing event dma_fence_signaled: OK [ 227.200320] Testing event dma_fence_enable_signal: OK [ 227.312217] Testing event dma_fence_destroy: OK [ 227.414123] Testing event dma_fence_init: OK [ 227.533298] Testing event dma_fence_emit: OK [ 227.658298] Testing event sync_timeline: OK [ 227.775085] Testing event spi_transfer_stop: OK [ 227.896101] Testing event spi_transfer_start: OK [ 227.989871] Testing event spi_message_done: OK [ 228.112010] Testing event spi_message_start: OK [ 228.204266] Testing event spi_message_submit: OK [ 228.316996] Testing event spi_controller_busy: OK [ 228.433174] Testing event spi_controller_idle: OK [ 228.537550] Testing event rtc_timer_fired: OK [ 228.653803] Testing event rtc_timer_dequeue: OK [ 228.801126] Testing event rtc_timer_enqueue: OK [ 228.920694] Testing event rtc_read_offset: OK [ 229.028402] Testing event rtc_set_offset: OK [ 229.147616] Testing event rtc_alarm_irq_enable: OK [ 229.277705] Testing event rtc_irq_set_state: OK [ 229.386091] Testing event rtc_irq_set_freq: OK [ 229.504630] Testing event rtc_read_alarm: OK [ 229.617081] Testing event rtc_set_alarm: OK [ 229.736474] Testing event rtc_read_time: OK [ 229.845226] Testing event rtc_set_time: OK [ 229.968797] Testing event memory_failure_event: OK [ 230.077951] Testing event aer_event: OK [ 230.188968] Testing event non_standard_event: OK [ 230.308335] Testing event arm_event: OK [ 230.432942] Testing event mc_event: OK [ 230.536666] Testing event binder_return: OK [ 230.664466] Testing event binder_command: OK [ 230.769995] Testing event binder_unmap_kernel_end: OK [ 230.880366] Testing event binder_unmap_kernel_start: OK [ 231.001784] Testing event binder_unmap_user_end: OK [ 231.125025] Testing event binder_unmap_user_start: OK [ 231.233118] Testing event binder_alloc_page_end: OK [ 231.336462] Testing event binder_alloc_page_start: OK [ 231.449696] Testing event binder_free_lru_end: OK [ 231.564727] Testing event binder_free_lru_start: OK [ 231.684714] Testing event binder_alloc_lru_end: OK [ 231.809640] Testing event binder_alloc_lru_start: OK [ 231.921375] Testing event binder_update_page_range: OK [ 232.036789] Testing event binder_transaction_failed_buffer_release: OK [ 232.156886] Testing event binder_transaction_buffer_release: OK [ 232.269185] Testing event binder_transaction_alloc_buf: OK [ 232.392689] Testing event binder_transaction_fd_recv: OK [ 232.505711] Testing event binder_transaction_fd_send: OK [ 232.614193] Testing event binder_transaction_ref_to_ref: OK [ 232.736356] Testing event binder_transaction_ref_to_node: OK [ 232.876908] Testing event binder_transaction_node_to_ref: OK [ 232.980587] Testing event binder_transaction_received: OK [ 233.077001] Testing event binder_transaction: OK [ 233.221055] Testing event binder_wait_for_work: OK [ 233.357432] Testing event binder_read_done: OK [ 233.460873] Testing event binder_write_done: OK [ 233.580252] Testing event binder_ioctl_done: OK [ 233.701186] Testing event binder_unlock: OK [ 233.813428] Testing event binder_locked: OK [ 233.932890] Testing event binder_lock: OK [ 234.053459] Testing event binder_ioctl: OK [ 234.173252] Testing event br_fdb_update: OK [ 234.285071] Testing event fdb_delete: OK [ 234.390236] Testing event br_fdb_external_learn_add: OK [ 234.524453] Testing event br_fdb_add: OK [ 234.629181] Testing event qdisc_dequeue: OK [ 234.742005] Testing event fib_table_lookup: OK [ 234.861627] Testing event tcp_probe: OK [ 234.973128] Testing event tcp_retransmit_synack: OK [ 235.089937] Testing event tcp_rcv_space_adjust: OK [ 235.202101] Testing event tcp_destroy_sock: OK [ 235.316602] Testing event tcp_receive_reset: OK [ 235.425027] Testing event tcp_send_reset: OK [ 235.532580] Testing event tcp_retransmit_skb: OK [ 235.661335] Testing event udp_fail_queue_rcv_skb: OK [ 235.797640] Testing event inet_sock_set_state: OK [ 235.905320] Testing event sock_exceed_buf_limit: OK [ 236.029904] Testing event sock_rcvqueue_full: OK [ 236.144394] Testing event napi_poll: OK [ 236.269961] Testing event netif_rx_ni_entry: OK [ 236.386181] Testing event netif_rx_entry: OK [ 236.505576] Testing event netif_receive_skb_list_entry: OK [ 236.604550] Testing event netif_receive_skb_entry: OK [ 236.724467] Testing event napi_gro_receive_entry: OK [ 236.846226] Testing event napi_gro_frags_entry: OK [ 236.968282] Testing event netif_rx: OK [ 237.093280] Testing event netif_receive_skb: OK [ 237.199774] Testing event net_dev_queue: OK [ 237.309765] Testing event net_dev_xmit: OK [ 237.436384] Testing event net_dev_start_xmit: OK [ 237.554072] Testing event skb_copy_datagram_iovec: OK [ 237.657752] Testing event consume_skb: OK [ 237.789347] Testing event kfree_skb: OK [ 237.905773] Testing event 9p_protocol_dump: OK [ 238.048450] Testing event 9p_client_res: OK [ 238.160784] Testing event 9p_client_req: OK [ 238.270087] Running tests on trace event systems: [ 238.276243] Testing event system 9p: OK [ 238.421373] Testing event system skb: OK [ 238.543675] Testing event system net: OK [ 238.684863] Testing event system napi: OK [ 238.808920] Testing event system sock: OK [ 238.929009] Testing event system udp: OK [ 239.057779] Testing event system tcp: OK [ 239.178954] Testing event system fib: OK [ 239.304918] Testing event system qdisc: OK [ 239.413662] Testing event system bridge: OK [ 239.508980] Testing event system binder: OK [ 239.740528] Testing event system ras: OK [ 239.928319] Testing event system rtc: OK [ 240.085745] Testing event system spi: OK [ 240.240322] Testing event system sync_trace: OK [ 240.349442] Testing event system dma_fence: OK [ 240.485042] Testing event system regmap: OK [ 240.660887] Testing event system iommu: OK [ 240.821847] Testing event system random: OK [ 240.985605] Testing event system regulator: OK [ 241.150279] Testing event system rpmh: OK [ 241.284540] Testing event system clk: OK [ 241.461720] Testing event system gpio: OK [ 241.597179] Testing event system wbt: OK [ 241.737871] Testing event system block: OK [ 241.932437] Testing event system filelock: OK [ 242.129177] Testing event system fs_dax: OK [ 242.308540] Testing event system writeback: OK [ 242.541129] Testing event system page_ref: OK [ 243.293753] Testing event system cma: OK [ 243.413601] Testing event system page_isolation: OK [ 243.512817] Testing event system huge_memory: OK [ 243.649612] Testing event system migrate: OK [ 243.773791] Testing event system compaction: OK [ 243.936516] Testing event system kmem: OK [ 244.117422] Testing event system percpu: OK [ 244.257378] Testing event system vmscan: OK [ 244.428355] Testing event system pagemap: OK [ 244.546310] Testing event system oom: OK [ 244.692532] Testing event system filemap: OK [ 244.834261] Testing event system rseq: OK [ 244.965563] Testing event system xdp: OK [ 245.116357] Testing event system benchmark: OK [ 245.321948] Testing event system rpm: OK [ 245.441438] Testing event system power: OK [ 245.661976] Testing event system test: OK [ 245.790160] Testing event system preemptirq: OK [ 246.053137] Testing event system cgroup: OK [ 246.225879] Testing event system module: OK [ 246.362222] Testing event system alarmtimer: OK [ 246.505316] Testing event system timer: OK [ 246.685353] Testing event system swiotlb: OK [ 246.830053] Testing event system rcu: OK [ 247.064372] Testing event system printk: OK [ 247.185629] Testing event system lock: OK [ 247.377456] Testing event system sched: OK [ 247.580478] Testing event system workqueue: OK [ 247.764545] Testing event system signal: OK [ 247.881287] Testing event system irq: OK [ 248.016865] Testing event system cpuhp: OK [ 248.154144] Testing event system task: OK [ 248.277215] Testing event system kvm: OK [ 248.501482] Testing event system emulation: OK [ 248.644368] Testing event system ipi: OK [ 248.784424] Testing event system raw_syscalls: OK [ 248.913146] Testing event system initcall: OK [ 249.037875] Running tests on all trace events: [ 249.044880] Testing all events: OK [ 256.696583] ftrace_replace_code: 1 [ 262.947092] ../kernel/trace/ftrace.c:2446, loop_counter: 41975 [ 262.951959] Testing ftrace filter: OK [ 263.013889] trace_kprobe: Testing kprobe tracing: OK [ 263.070085] Loading compiled-in X.509 certificates [ 263.120136] _warn_unseeded_randomness: 15 callbacks suppressed [ 263.120235] random: get_random_bytes called from key_alloc+0x2ec/0x590 with crng_init=0 [ 263.121778] Loaded X.509 cert 'Build time autogenerated kernel key: ac2c3039a2695eb0013480c54749bb09e0d7349f' [ 263.130852] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 263.131385] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 263.148265] kworker/u2:1 (1249) used greatest stack depth: 11360 bytes left [ 263.149904] zswap: default zpool zbud not available [ 263.151035] zswap: pool creation failed [ 263.165762] page_owner is disabled [ 263.250730] Key type big_key registered [ 263.288934] Key type encrypted registered [ 263.292232] ima: No TPM chip found, activating TPM-bypass! [ 263.293245] ima: Allocated hash algorithm: sha1 [ 263.295821] No architecture policies found [ 263.300140] evm: Initialising EVM extended attributes: [ 263.300473] evm: security.selinux [ 263.300762] evm: security.SMACK64 [ 263.301028] evm: security.SMACK64EXEC [ 263.301244] evm: security.SMACK64TRANSMUTE [ 263.301512] evm: security.SMACK64MMAP [ 263.301768] evm: security.apparmor [ 263.302018] evm: security.ima [ 263.302664] evm: security.capability [ 263.302954] evm: HMAC attrs: 0x1 [ 263.337848] hctosys: unable to open rtc device (rtc0) [ 263.348242] psci_checker: PSCI checker started using 1 CPUs [ 263.348543] psci_checker: Starting hotplug tests [ 263.349394] psci_checker: Trying to turn off and on again all CPUs [ 263.350061] psci_checker: Trying to turn off and on again group 0 (CPUs 0) [ 263.351115] psci_checker: Hotplug tests passed OK [ 263.351379] psci_checker: Starting suspend tests (10 cycles per state) [ 263.351872] psci_checker: cpuidle not available on CPU 0, ignoring [ 263.352364] psci_checker: Could not start suspend tests on any CPU [ 263.352664] psci_checker: PSCI checker completed [ 263.368977] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 263.476448] ### dt-test ### start of unittest - you will see error messages [ 263.489471] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 263.489989] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 263.490910] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 263.491332] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 263.491728] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 263.492121] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 263.500079] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 263.500977] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 263.501861] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 263.608592] kobject: 'testcase-data:platform-tests:test-device@1' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 263.616142] kobject: 'testcase-data:platform-tests:test-device@0' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 263.621402] kobject: 'unittest-bus.0' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 263.685370] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status [ 263.708302] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status [ 263.716518] kobject: 'testcase-data:overlay-node:test-bus:test-unittest1' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 263.720768] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status [ 263.742164] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status [ 263.749077] kobject: 'testcase-data:overlay-node:test-bus:test-unittest3' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 263.777987] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status [ 263.804380] kobject: 'testcase-data:overlay-node:test-bus:test-unittest5' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 263.809408] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status [ 263.828399] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status [ 263.853997] kobject: 'testcase-data:overlay-node:test-bus:test-unittest7' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 263.861031] kobject: 'testcase-data:overlay-node:test-bus:test-unittest6' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 263.864919] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status [ 263.883959] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo [ 263.885002] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 263.885541] OF: overlay: overlay #6 is not topmost [ 263.892861] kobject: 'testcase-data:overlay-node:test-bus:test-unittest8' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 264.000556] kobject: 'testcase-data:overlay-node:test-bus:test-unittest11:test-unittest111' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 264.007198] kobject: 'testcase-data:overlay-node:test-bus:test-unittest11' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 264.007974] OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /testcase-data/overlay-node/test-bus/test-unittest11/test-unittest111 [ 264.008851] OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /testcase-data/overlay-node/test-bus/test-unittest11 [ 264.009785] OF: overlay: remove: Could not find overlay #7 [ 264.010144] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #7 [ 264.019071] kobject: 'testcase-data:overlay-node:test-bus:test-unittest10:test-unittest101' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 264.025153] kobject: 'testcase-data:overlay-node:test-bus:test-unittest10' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 264.025895] OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /testcase-data/overlay-node/test-bus/test-unittest10/test-unittest101 [ 264.027180] OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /testcase-data/overlay-node/test-bus/test-unittest10 [ 264.034863] kobject: 'testcase-data:overlay-node:test-bus:test-unittest4' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 264.035538] OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /testcase-data/overlay-node/test-bus/test-unittest4 [ 264.059742] kobject: 'testcase-data:overlay-node:test-bus:test-unittest2' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 264.083801] kobject: 'testcase-data:overlay-node:test-bus:test-unittest0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 264.118125] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/status [ 264.121357] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/status [ 264.122821] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@30/incline-up [ 264.123821] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@40/incline-up [ 264.131046] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/status [ 264.131731] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/color [ 264.132352] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/rate [ 264.133315] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/hvac_2 [ 264.134136] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200 [ 264.135239] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_left [ 264.136042] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_right [ 264.184881] OF: overlay: ERROR: multiple fragments add and/or delete node /testcase-data-2/substation@100/motor-1/controller [ 264.187007] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/controller/name [ 264.187907] _warn_unseeded_randomness: 18 callbacks suppressed [ 264.187974] random: get_random_u32 called from kobject_release+0x20/0xf0 with crng_init=0 [ 264.188543] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 264.189525] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 264.192929] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 264.193633] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 264.194821] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/rpm_avail [ 264.200167] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 264.201539] random: get_random_u32 called from kobject_release+0x20/0xf0 with crng_init=0 [ 264.201938] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 264.203152] ### dt-test ### end of unittest - 217 passed, 0 failed [ 264.219877] uart-pl011 9000000.pl011: no DMA platform data [ 264.440222] VFS: Mounted root (9p filesystem) readonly on device 0:19. [ 264.453948] devtmpfs: mounted [ 264.468057] integrity: Unable to open file: /etc/keys/x509_ima.der (-2) [ 264.477333] integrity: Unable to open file: /etc/keys/x509_evm.der (-2) [ 265.007291] Freeing unused kernel memory: 6144K [ 265.065160] Checked W+X mappings: passed, no W+X pages found [ 265.065633] rodata_test: all tests were successful [ 265.066189] Run /sbin/init as init process [ 265.342366] _warn_unseeded_randomness: 8 callbacks suppressed [ 265.342448] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 265.716421] random: fast init done [ 267.099888] random: get_random_u32 called from kobject_release+0x20/0xf0 with crng_init=1 [ 267.100302] kobject: 'test-unittest11' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 267.449397] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 268.124559] random: get_random_u32 called from kobject_release+0x20/0xf0 with crng_init=1 [ 268.124954] kobject: 'test-unittest4' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 268.126064] kobject: 'test-unittest10' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 268.127990] kobject: 'test-unittest101' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 268.128906] kobject: 'test-unittest111' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) SELinux: Could not open policy file <= /etc/selinux/targeted/policy/policy.31: No such file or directory [ 268.207801] systemd[1]: System time before build time, advancing clock. [ 268.238048] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 268.516291] systemd[1]: Failed to insert module 'autofs4': No such file or directory [ 269.148471] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest4 [ 270.303186] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest10/test-unittest101 [ 270.548124] random: systemd: uninitialized urandom read (16 bytes read) [ 270.756972] random: systemd: uninitialized urandom read (16 bytes read) [ 270.760535] random: systemd: uninitialized urandom read (16 bytes read) [ 271.314979] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest10 [ 271.315774] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11 [ 272.398954] OF: ERROR: memory leak before free overlay changeset, /testcase-data/overlay-node/test-bus/test-unittest11/test-unittest111 [ 274.270669] 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) [ 274.321299] systemd[1]: Detected virtualization qemu. [ 274.325462] systemd[1]: Detected architecture arm64. Welcome to Debian GNU/Linux 9 (stretch)! [ 274.419324] systemd[1]: Set hostname to . [ 274.540378] random: get_random_u32 called from bucket_table_alloc+0x108/0x178 with crng_init=1 [ 274.582077] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 274.997907] random: get_random_u64 called from dup_task_struct+0xbc/0x350 with crng_init=1 [ 275.704702] _warn_unseeded_randomness: 15 callbacks suppressed [ 275.704779] random: get_random_u64 called from dup_task_struct+0xbc/0x350 with crng_init=1 [ 275.732302] random: get_random_u64 called from arch_mmap_rnd+0x78/0x98 with crng_init=1 [ 275.733113] random: get_random_u64 called from randomize_stack_top+0x28/0x68 with crng_init=1 [ 276.688933] random: systemd: uninitialized urandom read (16 bytes read) [ 276.767608] _warn_unseeded_randomness: 17 callbacks suppressed [ 276.767681] random: get_random_u64 called from randomize_stack_top+0x28/0x68 with crng_init=1 [ 276.768249] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 276.769173] random: get_random_u64 called from arch_mmap_rnd+0x78/0x98 with crng_init=1 [ 277.841803] _warn_unseeded_randomness: 14 callbacks suppressed [ 277.841878] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 278.320200] random: get_random_u64 called from randomize_stack_top+0x28/0x68 with crng_init=1 [ 278.320610] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 279.721781] systemd-hiberna (1267) used greatest stack depth: 8 bytes left [ 280.716292] random: systemd-cryptse: uninitialized urandom read (16 bytes read) [ 281.037445] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 281.756059] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 282.885455] random: systemd: uninitialized urandom read (16 bytes read) [ 283.037824] random: systemd: uninitialized urandom read (16 bytes read) [ 283.040962] random: systemd: uninitialized urandom read (16 bytes read) [ 283.089870] _warn_unseeded_randomness: 2 callbacks suppressed [ 283.089964] random: get_random_u32 called from bpf_jit_binary_alloc+0x8c/0xf0 with crng_init=1 [ 283.170150] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 288.003397] systemd[1]: networking.service: Cannot add dependency job, ignoring: Unit networking.service is masked. [ 288.050038] random: get_random_bytes called from generate_random_uuid+0x18/0x48 with crng_init=1 [ 288.100951] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 288.144594] systemd[1]: Listening on Syslog Socket. [ OK ] Listening on Syslog Socket. [ 288.156072] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 288.177903] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ OK ] Listening on /dev/initctl Compatibility Named Pipe. [ 288.192297] 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. [ 288.212050] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 288.239975] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ 288.252946] systemd[1]: Reached target Encrypted Volumes. [ OK ] Reached target Encrypted Volumes. [ 288.264817] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ OK ] Created slice System Slice. [ OK ] Created slice system-getty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ 288.551846] random: get_random_u64 called from dup_task_struct+0xbc/0x350 with crng_init=1 Mounting POSIX Message Queue File System... [ OK ] Listening on Journal Audit Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. [ 289.090845] random: get_random_u64 called from arch_mmap_rnd+0x78/0x98 with crng_init=1 [ 289.091323] random: get_random_u64 called from randomize_stack_top+0x28/0x68 with crng_init=1 [ 289.091643] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 Starting Remount Root and Kernel File Systems... Starting Load Kernel Modules... [ 291.051096] _warn_unseeded_randomness: 11 callbacks suppressed [ 291.051177] random: get_random_u64 called from arch_mmap_rnd+0x78/0x98 with crng_init=1 [ 291.051791] random: get_random_u64 called from randomize_stack_top+0x28/0x68 with crng_init=1 [ 291.052121] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 Starting Create Static Device Nodes in /dev... [ 292.263759] _warn_unseeded_randomness: 12 callbacks suppressed [ 292.263835] random: get_random_u64 called from arch_mmap_rnd+0x78/0x98 with crng_init=1 [ 292.264497] random: get_random_u64 called from randomize_stack_top+0x28/0x68 with crng_init=1 [ 292.264929] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 Starting Journal Service... [ 293.314172] _warn_unseeded_randomness: 4 callbacks suppressed [ 293.314263] random: get_random_u64 called from dup_task_struct+0xbc/0x350 with crng_init=1 [ 293.625592] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 293.660654] random: get_random_u32 called from bpf_jit_binary_alloc+0x8c/0xf0 with crng_init=1 [ 293.708777] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 294.740428] _warn_unseeded_randomness: 15 callbacks suppressed [ 294.740506] random: get_random_u64 called from dup_task_struct+0xbc/0x350 with crng_init=1 [ 295.257735] systemd[1]: Mounting Debug File System... [ 295.344091] random: get_random_u64 called from arch_mmap_rnd+0x78/0x98 with crng_init=1 [ 295.344621] random: get_random_u64 called from randomize_stack_top+0x28/0x68 with crng_init=1 Mounting Debug File System... [ 295.457451] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 295.601315] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 295.716260] systemd[1]: Reached target Sockets. [ OK ] Reached target Sockets. [ 295.849190] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 297.137063] _warn_unseeded_randomness: 5 callbacks suppressed [ 297.137212] random: get_random_u64 called from randomize_stack_top+0x28/0x68 with crng_init=1 [ 297.137838] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 297.409642] random: get_random_u64 called from arch_mmap_rnd+0x78/0x98 with crng_init=1 [ 298.820971] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 298.883854] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 299.433564] systemd[1]: Started Remount Root and Kernel File Systems. [ OK ] Started Remount Root and Kernel File Systems. [ 299.813370] systemd[1]: Started Load Kernel Modules. [ OK ] Started Load Kernel Modules. [ 300.077154] systemd[1]: Started Create Static Device Nodes in /dev. [ OK ] Started Create Static Device Nodes in /dev. [ 300.256474] systemd[1]: Mounted Debug File System. [ OK ] Mounted Debug File System. [ 300.767162] systemd[1]: Starting udev Kernel Device Manager... Starting udev Kernel Device Manager... [ 301.387032] systemd[1]: Mounting Configuration File System... [ 301.401685] _warn_unseeded_randomness: 2 callbacks suppressed [ 301.401760] random: get_random_u32 called from bpf_jit_binary_alloc+0x8c/0xf0 with crng_init=1 Mounting Configuration File System... [ 301.532457] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 301.609277] random: get_random_u64 called from arch_mmap_rnd+0x78/0x98 with crng_init=1 [ 302.557894] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 302.897136] _warn_unseeded_randomness: 7 callbacks suppressed [ 302.897212] random: get_random_u64 called from arch_mmap_rnd+0x78/0x98 with crng_init=1 [ 302.897886] random: get_random_u64 called from randomize_stack_top+0x28/0x68 with crng_init=1 [ 302.898834] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 304.090256] _warn_unseeded_randomness: 8 callbacks suppressed [ 304.091158] random: get_random_u64 called from dup_task_struct+0xbc/0x350 with crng_init=1 [ 304.723237] systemd[1]: Starting udev Coldplug all Devices... [ 304.731021] random: get_random_bytes called from __prandom_timer+0x2c/0x98 with crng_init=1 Starting udev Coldplug all Devices... [ 304.871229] systemd[1]: Reached target Local File Systems (Pre). [ OK ] Reached target Local File Systems (Pre). [ 305.005619] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 306.080007] random: get_random_u64 called from arch_mmap_rnd+0x78/0x98 with crng_init=1 [ 306.080532] random: get_random_u64 called from randomize_stack_top+0x28/0x68 with crng_init=1 [ 306.080942] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 307.317845] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 308.554661] _warn_unseeded_randomness: 6 callbacks suppressed [ 308.554737] random: get_random_u64 called from arch_mmap_rnd+0x78/0x98 with crng_init=1 [ 308.555393] random: get_random_u64 called from randomize_stack_top+0x28/0x68 with crng_init=1 [ 308.555783] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 309.277587] systemd[1]: Mounted Configuration File System. [ OK ] Mounted Configuration File System. [ 309.936617] systemd[1]: Started Apply Kernel Variables. [ OK ] Started Apply Kernel Variables. [ 311.815201] _warn_unseeded_randomness: 4 callbacks suppressed [ 311.815295] random: get_random_u64 called from dup_task_struct+0xbc/0x350 with crng_init=1 [ 312.124151] systemd[1]: Started Load/Save Random Seed. [ OK ] Started Load/Save Random Seed. [ 312.297683] systemd[1]: Started udev Kernel Device Manager. [ OK ] Started udev Kernel Device Manager. [* ] (1 of 3) A start job is running for Journal Service (29s / 30min 3s) [** ] (1 of 3) A start job is running for Journal Service (30s / 30min 3s) [*** ] (1 of 3) A start job is running for Journal Service (30s / 30min 3s) [ *** ] (2 of 3) A start job is running for dev-ttyAMA0.device (31s / 30min)[ 319.127296] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. Starting Flush Journal to Persistent Storage... [ 320.525521] random: get_random_u64 called from arch_mmap_rnd+0x78/0x98 with crng_init=1 [ 320.526039] random: get_random_u64 called from randomize_stack_top+0x28/0x68 with crng_init=1 [ 320.527488] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 322.316492] systemd-journald[1282]: Received request to flush runtime journal from PID 1 [ 322.451146] _warn_unseeded_randomness: 4 callbacks suppressed [ 322.451219] random: get_random_u64 called from dup_task_struct+0xbc/0x350 with crng_init=1 [ OK ] Started Flush Journal to Persistent Storage. Starting Create Volatile Files and Directories... [ 325.138875] random: get_random_u64 called from arch_mmap_rnd+0x78/0x98 with crng_init=1 [ 325.139341] random: get_random_u64 called from randomize_stack_top+0x28/0x68 with crng_init=1 [ 325.139661] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ *** ] (2 of 3) A start job is running for dev-ttyAMA0.device (40s / 30min) [ ***] (2 of 3) A start job is running for dev-ttyAMA0.device (40s / 30min) [ **] (3 of 3) A start job is running for…dplug all Devices (41s / no limit) [ *] (3 of 3) A start job is running for…dplug all Devices (42s / no limit)[ 330.528759] _warn_unseeded_randomness: 5 callbacks suppressed [ 330.528836] random: get_random_u64 called from arch_mmap_rnd+0x78/0x98 with crng_init=1 [ 330.529463] random: get_random_u64 called from randomize_stack_top+0x28/0x68 with crng_init=1 [ 330.529816] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ **] (3 of 3) A start job is running for…dplug all Devices (42s / no limit) [ ***] (1 of 3) A start job is running for…s and Directories (43s / no limit)[ 331.741123] _warn_unseeded_randomness: 1 callbacks suppressed [ 331.741199] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ *** ] (1 of 3) A start job is running for…s and Directories (43s / no limit) [ *** ] (1 of 3) A start job is running for…s and Directories (44s / no limit) [*** ] (2 of 3) A start job is running for dev-ttyAMA0.device (44s / 30min) [** ] (2 of 3) A start job is running for dev-ttyAMA0.device (45s / 30min) [* ] (2 of 3) A start job is running for dev-ttyAMA0.device (45s / 30min)[ 333.931044] random: crng init done [** ] (3 of 3) A start job is running for…dplug all Devices (46s / no limit) [*** ] (3 of 3) A start job is running for…dplug all Devices (46s / no limit) [ *** ] (3 of 3) A start job is running for…dplug all Devices (47s / no limit) [ *** ] (1 of 3) A start job is running for…s and Directories (47s / no limit) [ ***] (1 of 3) A start job is running for…s and Directories (48s / no limit) [ **] (1 of 3) A start job is running for…s and Directories (48s / no limit) [ *] (2 of 3) A start job is running for dev-ttyAMA0.device (49s / 30min) [ **] (2 of 3) A start job is running for dev-ttyAMA0.device (49s / 30min) [ ***] (2 of 3) A start job is running for dev-ttyAMA0.device (50s / 30min) [ OK ] Started Create Volatile Files and Directories. Starting Network Time Synchronization... Starting Update UTMP about System Boot/Shutdown... [ *** ] (1 of 4) A start job is running for… Synchronization (56s / 30min 52s) [ *** ] (1 of 4) A start job is running for… Synchronization (56s / 30min 52s) [*** ] (1 of 4) A start job is running for… Synchronization (57s / 30min 52s) [** ] (2 of 4) A start job is running for dev-ttyAMA0.device (57s / 30min) [* ] (2 of 4) A start job is running for dev-ttyAMA0.device (58s / 30min) [** ] (2 of 4) A start job is running for dev-ttyAMA0.device (58s / 30min) [*** ] (3 of 4) A start job is running for…dplug all Devices (59s / no limit) [ *** ] (3 of 4) A start job is running for…dplug all Devices (59s / no limit) [ OK ] Started Update UTMP about System Boot/Shutdown. [ *** ] (2 of 3) A start job is running for…v-ttyAMA0.device (1min 5s / 30min) [ ***] (3 of 3) A start job is running for…g all Devices (1min 5s / no limit) [ **] (3 of 3) A start job is running for…g all Devices (1min 6s / no limit) [ *] (3 of 3) A start job is running for…g all Devices (1min 6s / no limit) [ **] (1 of 3) A start job is running for…chronization (1min 7s / 30min 52s) [ ***] (1 of 3) A start job is running for…chronization (1min 7s / 30min 52s) [ OK ] Started Network Time Synchronization. [ OK ] Reached target System Time Synchronized. [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 13s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (1min 14s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (1min 14s / no limit) [** ] (2 of 2) A start job is running for… all Devices (1min 15s / no limit) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 15s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 16s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 16s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (1min 17s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (1min 17s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (1min 18s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (1min 18s / 30min) [ *] (1 of 2) A start job is running for…-ttyAMA0.device (1min 19s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (1min 19s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (1min 20s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (1min 20s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (1min 21s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 21s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 22s / 30min) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 22s / 30min) [** ] (2 of 2) A start job is running for… all Devices (1min 23s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (1min 23s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (1min 24s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 24s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (1min 25s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (1min 25s / 30min) [ *] (2 of 2) A start job is running for… all Devices (1min 26s / no limit) [ **] (2 of 2) A start job is running for… all Devices (1min 26s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (1min 27s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 27s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 28s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 29s / 30min) [** ] (2 of 2) A start job is running for… all Devices (1min 29s / no limit) [* ] (2 of 2) A start job is running for… all Devices (1min 30s / no limit) [** ] (2 of 2) A start job is running for… all Devices (1min 30s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 31s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 31s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 32s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (1min 32s / no limit) [ **] (2 of 2) A start job is running for… all Devices (1min 33s / no limit) [ *] (2 of 2) A start job is running for… all Devices (1min 33s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (1min 34s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (1min 34s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 35s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (1min 35s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (1min 36s / no limit) [** ] (2 of 2) A start job is running for… all Devices (1min 36s / no limit) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 37s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 37s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 38s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (1min 38s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (1min 39s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (1min 39s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (1min 40s / 30min) [ *] (1 of 2) A start job is running for…-ttyAMA0.device (1min 40s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (1min 41s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (1min 41s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (1min 42s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (1min 42s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 43s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 43s / 30min) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 44s / 30min) [** ] (2 of 2) A start job is running for… all Devices (1min 44s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (1min 45s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (1min 45s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 46s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (1min 46s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (1min 46s / 30min) [ *] (2 of 2) A start job is running for… all Devices (1min 47s / no limit) [ **] (2 of 2) A start job is running for… all Devices (1min 47s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (1min 48s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 48s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 49s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 49s / 30min) [** ] (2 of 2) A start job is running for… all Devices (1min 50s / no limit) [* ] (2 of 2) A start job is running for… all Devices (1min 50s / no limit) [ OK ] Found device /dev/ttyAMA0. [ 400.893886] systemd-journald[1282]: /dev/kmsg buffer overrun, some messages lost. [ 401.023270] virtio_net virtio0 enp0s1: renamed from eth0 [ 401.210986] systemd-journald[1282]: /dev/kmsg buffer overrun, some messages lost. [ 401.541004] systemd-journald[1282]: /dev/kmsg buffer overrun, some messages lost. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Started Daily apt upgrade and clean activities. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started Regular background program processing daemon. Starting System Logging Service... Starting Permit User Sessions... Starting getty on tty2-tty6 if dbus and logind are not available... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyAMA0. [ OK ] Started System Logging Service. Debian GNU/Linux 9 compilator ttyAMA0 compilator login: [ 441.849418] kobject: 'vcs2' (0000000013245af6): kobject_release, parent (null) (delayed 1000) [ 441.900288] kobject: 'vcsu2' (000000008a65d91c): kobject_release, parent (null) (delayed 250) [ 441.940210] kobject: 'vcsa2' (000000003ac5b324): kobject_release, parent (null) (delayed 500) [ 441.967684] kobject: 'vcs5' (000000001b98a8a6): kobject_release, parent (null) (delayed 1000) [ 442.007502] kobject: 'vcsu5' (00000000802ce730): kobject_release, parent (null) (delayed 750) [ 442.043644] kobject: 'vcsa5' (0000000048dbfa0f): kobject_release, parent (null) (delayed 750) [ 442.076172] kobject: 'vcs6' (000000001a361553): kobject_release, parent (null) (delayed 1000) [ 442.116315] kobject: 'vcsu6' (00000000a2af5c87): kobject_release, parent (null) (delayed 750) [ 442.157902] kobject: 'vcsa6' (000000006119e1e3): kobject_release, parent (null) (delayed 750) [ 442.199682] kobject: 'vcs3' (00000000233be785): kobject_release, parent (null) (delayed 500) [ 442.237062] kobject: 'vcsu3' (00000000ef762583): kobject_release, parent (null) (delayed 1000) [ 442.283056] kobject: 'vcsa3' (00000000d4ae10e0): kobject_release, parent (null) (delayed 750) [ 442.349725] kobject: 'vcs4' (00000000091728be): kobject_release, parent (null) (delayed 250) [ 442.408225] kobject: 'vcsu4' (0000000091c1b4ea): kobject_release, parent (null) (delayed 750) [ 442.464458] kobject: 'vcsa4' (000000007b766b16): kobject_release, parent (null) (delayed 1000)