[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 4.20.0-rc3-next-20181122-00007-g64e3bffc54df-dirty (anders@compilator) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05)) #44 SMP Mon Nov 26 13:49:56 CET 2018 [ 0.000000] Machine model: linux,dummy-virt [ 0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '') [ 0.000000] printk: bootconsole [pl11] enabled [ 0.000000] efi: Getting EFI parameters from FDT: [ 0.000000] efi: UEFI not found. [ 0.000000] cma: Reserved 16 MiB at 0x00000000bf000000 [ 0.000000] NUMA: No NUMA configuration found [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] NUMA: NODE_DATA [mem 0xbefea5c0-0xbefebfff] [ 0.000000] Zone ranges: [ 0.000000] DMA32 [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] psci: probing for conduit method from DT. [ 0.000000] psci: PSCIv0.2 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: Trusted OS migration not required [ 0.000000] random: get_random_bytes called from start_kernel+0xa8/0x498 with crng_init=0 [ 0.000000] percpu: Embedded 490 pages/cpu @(____ptrval____) s1967960 r8192 d30888 u2007040 [ 0.000000] Detected VIPT I-cache on CPU0 [ 0.000000] CPU features: enabling workaround for ARM erratum 843419 [ 0.000000] CPU features: enabling workaround for ARM erratum 845719 [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 516096 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: root=/dev/root rootfstype=9p rootflags=trans=virtio console=ttyAMA0,38400n8 earlycon=pl011,0x9000000 [ 0.000000] Memory: 997376K/2097152K available (15764K kernel code, 3358K rwdata, 4656K rodata, 4096K init, 21766K bss, 86152K reserved, 16384K cma-reserved) [ 0.000000] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 0.000000] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 [ 0.000000] ftrace: allocating 41424 entries in 162 pages [ 0.000000] Running RCU self tests [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU lockdep checking is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=1. [ 0.000000] rcu: RCU debug extended QS entry/exit. [ 0.000000] Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] GICv2m: range[mem 0x08020000-0x08020fff], SPI[80:143] [ 0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns [ 0.000177] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.013810] Console: colour dummy device 80x25 [ 0.015047] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.015457] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.015788] ... MAX_LOCK_DEPTH: 48 [ 0.016139] ... MAX_LOCKDEP_KEYS: 8191 [ 0.016480] ... CLASSHASH_SIZE: 4096 [ 0.016839] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.017552] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.017946] ... CHAINHASH_SIZE: 32768 [ 0.018342] memory used by lock dependency info: 7775 kB [ 0.018741] per task-struct memory footprint: 2688 bytes [ 0.019192] ------------------------ [ 0.019550] | Locking API testsuite: [ 0.019893] ---------------------------------------------------------------------------- [ 0.020296] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.020804] -------------------------------------------------------------------------- [ 0.021950] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.033050] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.044267] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.056628] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.068813] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.082005] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.094939] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.108077] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.118172] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.127988] -------------------------------------------------------------------------- [ 0.128408] recursive read-lock: | ok | | ok | [ 0.131848] recursive read-lock #2: | ok | | ok | [ 0.135061] mixed read-write-lock: | ok | | ok | [ 0.138653] mixed write-read-lock: | ok | | ok | [ 0.142226] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.145660] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.149018] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.152772] -------------------------------------------------------------------------- [ 0.153510] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.158841] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.163914] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.168865] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.173957] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.179013] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.184091] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.189074] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.194146] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.199149] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.204190] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.209406] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.214681] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.219896] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.225127] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.230350] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.236229] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.241446] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.246652] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.251716] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.256748] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.261877] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.267078] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.272539] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.278127] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.283378] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.289022] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.294691] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.300204] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.305518] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.310847] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.316226] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.321740] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.327142] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.332474] hard-irq lock-inversion/123: ok | ok | ok | [ 0.337836] soft-irq lock-inversion/123: ok | ok | ok | [ 0.343374] hard-irq lock-inversion/132: ok | ok | ok | [ 0.348580] soft-irq lock-inversion/132: ok | ok | ok | [ 0.354092] hard-irq lock-inversion/213: ok | ok | ok | [ 0.359640] soft-irq lock-inversion/213: ok | ok | ok | [ 0.365121] hard-irq lock-inversion/231: ok | ok | ok | [ 0.370467] soft-irq lock-inversion/231: ok | ok | ok | [ 0.375992] hard-irq lock-inversion/312: ok | ok | ok | [ 0.381370] soft-irq lock-inversion/312: ok | ok | ok | [ 0.386782] hard-irq lock-inversion/321: ok | ok | ok | [ 0.392041] soft-irq lock-inversion/321: ok | ok | ok | [ 0.397435] hard-irq read-recursion/123: ok | [ 0.399784] soft-irq read-recursion/123: ok | [ 0.401900] hard-irq read-recursion/132: ok | [ 0.404316] soft-irq read-recursion/132: ok | [ 0.406427] hard-irq read-recursion/213: ok | [ 0.408826] soft-irq read-recursion/213: ok | [ 0.410917] hard-irq read-recursion/231: ok | [ 0.413371] soft-irq read-recursion/231: ok | [ 0.415495] hard-irq read-recursion/312: ok | [ 0.417929] soft-irq read-recursion/312: ok | [ 0.420027] hard-irq read-recursion/321: ok | [ 0.422400] soft-irq read-recursion/321: ok | [ 0.424550] -------------------------------------------------------------------------- [ 0.424933] | Wound/wait tests | [ 0.425340] --------------------- [ 0.425646] ww api failures: ok | ok | ok | [ 0.432527] ww contexts mixing: ok | ok | [ 0.436133] finishing ww context: ok | ok | ok | ok | [ 0.442803] locking mismatches: ok | ok | ok | [ 0.447841] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.465089] spinlock nest unlocked: ok | [ 0.466955] ----------------------------------------------------- [ 0.467306] |block | try |context| [ 0.467698] ----------------------------------------------------- [ 0.468127] context: ok | ok | ok | [ 0.473841] try: ok | ok | ok | [ 0.478746] block: ok | ok | ok | [ 0.483732] spinlock: ok | ok | ok | [ 0.489060] ------------------------------------------------------- [ 0.489443] Good, all 261 testcases passed! | [ 0.489887] --------------------------------- [ 0.494575] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.495302] pid_max: default: 32768 minimum: 301 [ 0.506103] LSM: Security Framework initializing [ 0.506736] Yama: becoming mindful. [ 0.509607] LoadPin: ready to pin (currently enforcing) [ 0.510405] AppArmor: AppArmor disabled by boot time parameter [ 0.536155] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.545630] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.548480] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.549506] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.583025] *** VALIDATE proc *** [ 0.600653] *** VALIDATE cgroup1 *** [ 0.601066] *** VALIDATE cgroup2 *** [ 0.661484] ASID allocator initialised with 32768 entries [ 0.667883] rcu: Hierarchical SRCU implementation. [ 0.730218] EFI services will not be available. [ 0.753283] smp: Bringing up secondary CPUs ... [ 0.753787] smp: Brought up 1 node, 1 CPU [ 0.754138] SMP: Total of 1 processors activated. [ 0.754734] CPU features: detected: 32-bit EL0 Support [ 0.755265] CPU features: detected: CRC32 instructions [ 1.029216] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.030000] CPU: All CPU(s) started at EL1 [ 1.031398] alternatives: patching kernel code [ 1.505990] node 0 initialised, 249310 pages in 4ms [ 1.513393] pgdatinit0 (15) used greatest stack depth: 13408 bytes left [ 1.591855] devtmpfs: initialized [ 1.794749] _warn_unseeded_randomness: 81 callbacks suppressed [ 1.795151] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=0 [ 1.796415] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 1.797243] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 1.808816] Registered cp15_barrier emulation handler [ 2.462472] workqueue: round-robin CPU selection forced, expect performance impact [ 8.031776] DMA-API: preallocated 65536 debug entries [ 8.032188] DMA-API: debugging enabled by kernel config [ 8.032655] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 8.034592] futex hash table entries: 256 (order: 3, 32768 bytes) [ 8.036742] Running postponed tracer tests: [ 8.042448] Testing tracer function: PASSED [ 22.624656] Testing dynamic ftrace: PASSED [ 23.736474] Testing dynamic ftrace ops #1: [ 27.196141] (1 0 1 0 0) [ 27.196621] (1 1 2 0 0) [ 36.239896] (2 1 3 0 751273) [ 36.241802] (2 2 4 0 751624) PASSED [ 43.555567] Testing dynamic ftrace ops #2: [ 55.776887] (1 0 1 743988 0) [ 55.777727] (1 1 2 744118 0) [ 55.785967] (2 1 3 1 244) [ 55.786651] (2 2 4 117 360) PASSED [ 62.554583] Testing ftrace recursion: PASSED [ 63.438834] Testing ftrace recursion safe: PASSED [ 64.319892] Testing ftrace regs(no arch support): PASSED [ 65.206919] Testing tracer nop: PASSED [ 65.207776] Testing tracer irqsoff: PASSED [ 80.032184] Testing tracer function_graph: PASSED [ 95.267399] _warn_unseeded_randomness: 2 callbacks suppressed [ 95.267642] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 95.269269] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 95.270443] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 95.280203] prandom: seed boundary self test passed [ 95.303646] prandom: 100 self tests passed [ 95.308187] pinctrl core: initialized pinctrl subsystem [ 95.379589] regulator-dummy: Failed to create debugfs directory [ 95.386425] [ 95.386744] ************************************************************* [ 95.387125] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 95.387582] ** ** [ 95.387952] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 95.388384] ** ** [ 95.389237] ** This means that this kernel is built to expose internal ** [ 95.389617] ** IOMMU data structures, which may compromise security on ** [ 95.389994] ** your system. ** [ 95.390411] ** ** [ 95.390780] ** If you see this message and you are not debugging the ** [ 95.391212] ** kernel, report this immediately to your vendor! ** [ 95.391590] ** ** [ 95.391973] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 95.392399] ************************************************************* [ 95.410004] DMI not present or invalid. [ 95.441659] NET: Registered protocol family 16 [ 95.462500] audit: initializing netlink subsys (disabled) [ 95.560605] cpuidle: using governor ladder [ 95.561309] cpuidle: using governor menu [ 95.572955] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 95.574109] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 95.577205] audit: type=2000 audit(85.524:1): state=initialized audit_enabled=0 res=1 [ 95.636325] DMA: preallocated 256 KiB pool for atomic allocations [ 95.688134] Serial: AMBA PL011 UART driver [ 96.274610] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 96.277629] printk: console [ttyAMA0] enabled [ 96.277629] printk: console [ttyAMA0] enabled [ 96.278512] printk: bootconsole [pl11] disabled [ 96.278512] printk: bootconsole [pl11] disabled [ 96.362746] _warn_unseeded_randomness: 39 callbacks suppressed [ 96.362924] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 96.364105] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 96.780668] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 96.817048] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 96.958728] arm-v7s io-pgtable: self test ok [ 96.959384] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 96.965530] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 96.968901] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 96.972595] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 96.976263] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 96.979873] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 96.983619] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 96.989101] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 96.995526] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 97.001617] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 97.007948] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 97.014227] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 97.021460] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 97.036950] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 97.051976] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 97.067881] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 97.085433] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 97.103180] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 97.120982] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 97.144010] vgaarb: loaded [ 97.184109] pps_core: LinuxPPS API ver. 1 registered [ 97.184626] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 97.187578] PTP clock support registered [ 97.305184] NetLabel: Initializing [ 97.306098] NetLabel: domain hash size = 128 [ 97.306606] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 97.313928] NetLabel: unlabeled traffic allowed by default [ 97.328120] clocksource: Switched to clocksource arch_sys_counter [ 99.922488] _warn_unseeded_randomness: 39 callbacks suppressed [ 99.922692] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 99.924685] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 99.926121] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 99.935720] VFS: Disk quotas dquot_6.6.0 [ 99.939201] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 99.948648] *** VALIDATE hugetlbfs *** [ 100.558402] NET: Registered protocol family 2 [ 100.591538] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 100.595406] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 100.609464] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 100.618573] TCP: Hash tables configured (established 16384 bind 16384) [ 100.625387] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 100.629657] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 100.640196] NET: Registered protocol family 1 [ 100.707346] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 100.713055] kvm [1]: HYP mode not available [ 100.739882] kworker/u2:1 (34) used greatest stack depth: 11920 bytes left [ 100.914439] Initialise system trusted keyrings [ 100.926000] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 102.294312] _warn_unseeded_randomness: 58 callbacks suppressed [ 102.294495] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 102.296259] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 102.311877] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 102.458524] 9p: Installing v9fs 9p2000 file system support [ 103.128822] Key type asymmetric registered [ 103.129851] Asymmetric key parser 'x509' registered [ 103.133891] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 103.525646] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 103.531533] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 103.549461] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 103.594259] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 103.643188] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 103.645149] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 103.647609] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 103.648733] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 103.652300] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 103.664808] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 103.665735] pci_bus 0000:00: root bus resource [bus 00-0f] [ 103.666518] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 103.667709] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 103.668411] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 103.728758] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 103.729694] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 103.731358] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 103.732244] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 103.733074] pci 0000:00:02.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 103.733796] pci 0000:00:02.0: BAR 0: assigned [io 0x1000-0x103f] [ 103.734488] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 104.165650] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 104.217348] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 107.988767] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 108.286014] SuperH (H)SCI(F) driver initialized [ 108.358380] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 108.423508] libphy: Fixed MDIO Bus: probed [ 108.542112] _warn_unseeded_randomness: 38 callbacks suppressed [ 108.542301] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 108.840661] ledtrig-cpu: registered to indicate activity on CPUs [ 108.867603] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 108.868504] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 108.905660] ashmem: initialized [ 109.063723] 9pnet: Installing 9P2000 support [ 109.311278] registered taskstats version 1 [ 109.311843] Running tests on trace events: [ 109.312384] Testing event initcall_finish: OK [ 109.338450] Testing event initcall_start: OK [ 109.361704] Testing event initcall_level: OK [ 109.390078] Testing event sys_exit: OK [ 109.417737] Testing event sys_enter: OK [ 109.444239] Testing event ipi_exit: OK [ 109.469325] Testing event ipi_entry: OK [ 109.493459] Testing event ipi_raise: OK [ 109.520182] Testing event instruction_emulation: OK [ 109.545980] Testing event kvm_halt_poll_ns: OK [ 109.572621] Testing event kvm_age_page: OK [ 109.597988] Testing event kvm_fpu: OK [ 109.623977] Testing event kvm_mmio: OK [ 109.649982] Testing event kvm_ack_irq: OK [ 109.676586] Testing event kvm_set_irq: OK [ 109.701657] Testing event kvm_vcpu_wakeup: OK [ 109.725428] Testing event kvm_userspace_exit: OK [ 109.752400] Testing event kvm_timer_update_irq: OK [ 109.777776] Testing event kvm_toggle_cache: OK [ 109.804626] Testing event kvm_set_way_flush: OK [ 109.832575] Testing event kvm_test_age_hva: OK [ 109.857980] Testing event kvm_age_hva: OK [ 109.885923] Testing event kvm_set_spte_hva: OK [ 109.912178] Testing event kvm_unmap_hva_range: OK [ 109.937235] Testing event kvm_mmio_emulate: OK [ 109.963973] Testing event kvm_irq_line: OK [ 109.990066] Testing event kvm_access_fault: OK [ 110.016393] Testing event kvm_guest_fault: OK [ 110.041251] Testing event kvm_exit: OK [ 110.067945] Testing event kvm_entry: OK [ 110.093608] Testing event kvm_set_guest_debug: OK [ 110.120166] Testing event kvm_handle_sys_reg: OK [ 110.145751] Testing event trap_reg: OK [ 110.174618] Testing event kvm_arm_set_regset: OK [ 110.201558] Testing event kvm_arm_set_dreg32: OK [ 110.228460] Testing event kvm_arm_clear_debug: OK [ 110.253537] Testing event kvm_arm_setup_debug: OK [ 110.277777] Testing event kvm_hvc_arm64: OK [ 110.301867] Testing event kvm_wfx_arm64: OK [ 110.328330] Testing event vgic_update_irq_pending: OK [ 110.353619] Testing event task_rename: OK [ 110.380583] Testing event task_newtask: OK [ 110.405695] Testing event cpuhp_exit: OK [ 110.433113] Testing event cpuhp_multi_enter: OK [ 110.460238] Testing event cpuhp_enter: OK [ 110.486107] Testing event softirq_raise: OK [ 110.513817] Testing event softirq_exit: OK [ 110.539569] Testing event softirq_entry: OK [ 110.564614] Testing event irq_handler_exit: OK [ 110.593178] Testing event irq_handler_entry: OK [ 110.621263] Testing event signal_deliver: OK [ 110.648203] Testing event signal_generate: OK [ 110.673445] Testing event workqueue_execute_end: OK [ 110.700295] Testing event workqueue_execute_start: OK [ 110.729095] Testing event workqueue_activate_work: OK [ 110.753710] Testing event workqueue_queue_work: OK [ 110.780303] Testing event sched_wake_idle_without_ipi: OK [ 110.806146] Testing event sched_swap_numa: OK [ 110.832604] Testing event sched_stick_numa: OK [ 110.858462] Testing event sched_move_numa: OK [ 110.885457] Testing event sched_process_hang: OK [ 110.910109] Testing event sched_pi_setprio: OK [ 110.936226] Testing event sched_stat_runtime: [ 110.938580] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 110.963577] OK [ 110.964041] Testing event sched_stat_blocked: OK [ 110.992031] Testing event sched_stat_iowait: OK [ 111.017551] Testing event sched_stat_sleep: OK [ 111.044384] Testing event sched_stat_wait: OK [ 111.070027] Testing event sched_process_exec: OK [ 111.096590] Testing event sched_process_fork: OK [ 111.122096] Testing event sched_process_wait: OK [ 111.148112] Testing event sched_wait_task: OK [ 111.173276] Testing event sched_process_exit: OK [ 111.200284] Testing event sched_process_free: OK [ 111.225746] Testing event sched_migrate_task: OK [ 111.252107] Testing event sched_switch: OK [ 111.277809] Testing event sched_wakeup_new: OK [ 111.304160] Testing event sched_wakeup: OK [ 111.329224] Testing event sched_waking: OK [ 111.353868] Testing event sched_kthread_stop_ret: OK [ 111.380418] Testing event sched_kthread_stop: OK [ 111.406272] Testing event lock_acquired: OK [ 111.436678] Testing event lock_contended: OK [ 111.462616] Testing event lock_release: OK [ 111.500124] Testing event lock_acquire: OK [ 111.535902] Testing event console: OK [ 111.560048] Testing event rcu_barrier: OK [ 111.588009] Testing event rcu_torture_read: OK [ 111.613248] Testing event rcu_batch_end: OK [ 111.638124] Testing event rcu_invoke_kfree_callback: OK [ 111.664195] Testing event rcu_invoke_callback: OK [ 111.689324] Testing event rcu_batch_start: OK [ 111.713682] Testing event rcu_kfree_callback: OK [ 111.740261] Testing event rcu_callback: OK [ 111.765567] Testing event rcu_dyntick: OK [ 111.793600] Testing event rcu_fqs: OK [ 111.819985] Testing event rcu_quiescent_state_report: OK [ 111.846187] Testing event rcu_unlock_preempted_task: OK [ 111.874437] Testing event rcu_preempt_task: OK [ 111.902314] Testing event rcu_exp_funnel_lock: OK [ 111.928659] Testing event rcu_exp_grace_period: OK [ 111.957252] Testing event rcu_grace_period_init: OK [ 111.981545] Testing event rcu_future_grace_period: OK [ 112.008292] Testing event rcu_grace_period: OK [ 112.037690] Testing event rcu_utilization: OK [ 112.066394] Testing event swiotlb_bounced: OK [ 112.093988] Testing event tick_stop: OK [ 112.121780] Testing event itimer_expire: OK [ 112.147768] Testing event itimer_state: OK [ 112.172738] Testing event hrtimer_cancel: OK [ 112.200706] Testing event hrtimer_expire_exit: OK [ 112.225561] Testing event hrtimer_expire_entry: OK [ 112.253119] Testing event hrtimer_start: OK [ 112.279858] Testing event hrtimer_init: OK [ 112.306048] Testing event timer_cancel: OK [ 112.333761] Testing event timer_expire_exit: OK [ 112.359949] Testing event timer_expire_entry: OK [ 112.386175] Testing event timer_start: OK [ 112.414644] Testing event timer_init: OK [ 112.441235] Testing event alarmtimer_cancel: OK [ 112.465613] Testing event alarmtimer_start: OK [ 112.489347] Testing event alarmtimer_fired: OK [ 112.516192] Testing event alarmtimer_suspend: OK [ 112.541444] Testing event module_request: OK [ 112.568994] Testing event module_put: OK [ 112.593682] Testing event module_get: OK [ 112.620311] Testing event module_free: OK [ 112.645656] Testing event module_load: OK [ 112.672386] Testing event cgroup_transfer_tasks: OK [ 112.697974] Testing event cgroup_attach_task: OK [ 112.724672] Testing event cgroup_rename: OK [ 112.750267] Testing event cgroup_release: OK [ 112.776202] Testing event cgroup_rmdir: OK [ 112.801343] Testing event cgroup_mkdir: OK [ 112.828264] Testing event cgroup_remount: OK [ 112.853880] Testing event cgroup_destroy_root: OK [ 112.880054] Testing event cgroup_setup_root: OK [ 112.905340] Testing event irq_enable: OK [ 112.937908] Testing event irq_disable: OK [ 112.969505] Testing event ftrace_test_filter: OK [ 112.994418] Testing event dev_pm_qos_remove_request: OK [ 113.019577] Testing event dev_pm_qos_update_request: OK [ 113.044574] Testing event dev_pm_qos_add_request: OK [ 113.069986] Testing event pm_qos_update_flags: OK [ 113.096196] Testing event pm_qos_update_target: OK [ 113.121389] Testing event pm_qos_update_request_timeout: OK [ 113.145464] Testing event pm_qos_remove_request: OK [ 113.172418] Testing event pm_qos_update_request: OK [ 113.197590] Testing event pm_qos_add_request: OK [ 113.221550] Testing event power_domain_target: OK [ 113.248213] Testing event clock_set_rate: OK [ 113.273207] Testing event clock_disable: OK [ 113.299780] Testing event clock_enable: OK [ 113.325340] Testing event wakeup_source_deactivate: OK [ 113.352116] Testing event wakeup_source_activate: OK [ 113.377432] Testing event suspend_resume: OK [ 113.416216] Testing event device_pm_callback_end: OK [ 113.441964] Testing event device_pm_callback_start: OK [ 113.468868] Testing event cpu_frequency_limits: OK [ 113.493823] Testing event cpu_frequency: OK [ 113.520373] Testing event pstate_sample: OK [ 113.545217] Testing event powernv_throttle: OK [ 113.569313] Testing event cpu_idle: OK [ 113.596732] Testing event rpm_return_int: OK [ 113.622182] Testing event rpm_idle: OK [ 113.648769] Testing event rpm_resume: OK [ 113.673878] Testing event rpm_suspend: OK [ 113.700229] Testing event benchmark_event: OK [ 113.825196] Testing event xdp_devmap_xmit: OK [ 113.852262] Testing event xdp_cpumap_enqueue: OK [ 113.877441] Testing event xdp_cpumap_kthread: OK [ 113.904235] Testing event xdp_redirect_map_err: OK [ 113.932959] Testing event xdp_redirect_map: OK [ 113.957644] Testing event xdp_redirect_err: OK [ 113.984316] Testing event xdp_redirect: OK [ 114.010109] Testing event xdp_exception: OK [ 114.036644] Testing event rseq_ip_fixup: OK [ 114.062578] Testing event rseq_update: OK [ 114.089384] Testing event file_check_and_advance_wb_err: OK [ 114.113468] Testing event filemap_set_wb_err: OK [ 114.139629] Testing event mm_filemap_add_to_page_cache: OK [ 114.165691] Testing event mm_filemap_delete_from_page_cache: OK [ 114.192274] Testing event compact_retry: OK [ 114.217215] Testing event skip_task_reaping: OK [ 114.243976] Testing event finish_task_reaping: OK [ 114.269579] Testing event start_task_reaping: OK [ 114.296160] Testing event wake_reaper: OK [ 114.321816] Testing event mark_victim: OK [ 114.348159] Testing event reclaim_retry_zone: OK [ 114.373321] Testing event oom_score_adj_update: OK [ 114.397978] Testing event mm_lru_activate: OK [ 114.424555] Testing event mm_lru_insertion: OK [ 114.449764] Testing event mm_vmscan_inactive_list_is_low: OK [ 114.476610] Testing event mm_vmscan_lru_shrink_active: OK [ 114.504375] Testing event mm_vmscan_lru_shrink_inactive: OK [ 114.530039] Testing event mm_vmscan_writepage: OK [ 114.557668] Testing event mm_vmscan_lru_isolate: OK [ 114.584183] Testing event mm_shrink_slab_end: OK [ 114.609319] Testing event mm_shrink_slab_start: OK [ 114.636134] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 114.662110] Testing event mm_vmscan_memcg_reclaim_end: OK [ 114.689512] Testing event mm_vmscan_direct_reclaim_end: OK [ 114.713529] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 114.739730] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 114.765964] Testing event mm_vmscan_direct_reclaim_begin: OK [ 114.792136] Testing event mm_vmscan_wakeup_kswapd: OK [ 114.817785] Testing event mm_vmscan_kswapd_wake: OK [ 114.844201] Testing event mm_vmscan_kswapd_sleep: OK [ 114.869313] Testing event percpu_destroy_chunk: OK [ 114.893545] Testing event percpu_create_chunk: OK [ 114.920240] Testing event percpu_alloc_percpu_fail: OK [ 114.945266] Testing event percpu_free_percpu: OK [ 114.969755] Testing event percpu_alloc_percpu: OK [ 114.996211] Testing event mm_page_alloc_extfrag: OK [ 115.022152] Testing event mm_page_pcpu_drain: OK [ 115.048623] Testing event mm_page_alloc_zone_locked: OK [ 115.076865] Testing event mm_page_alloc: OK [ 115.102025] Testing event mm_page_free_batched: OK [ 115.130357] Testing event mm_page_free: OK [ 115.157588] Testing event kmem_cache_free: OK [ 115.184112] Testing event kfree: OK [ 115.210049] Testing event kmem_cache_alloc_node: OK [ 115.236420] Testing event kmalloc_node: OK [ 115.262138] Testing event kmem_cache_alloc: OK [ 115.288549] Testing event kmalloc: OK [ 115.313799] Testing event mm_compaction_kcompactd_wake: OK [ 115.337556] Testing event mm_compaction_wakeup_kcompactd: OK [ 115.361172] Testing event mm_compaction_kcompactd_sleep: OK [ 115.387812] Testing event mm_compaction_defer_reset: OK [ 115.414142] Testing event mm_compaction_defer_compaction: OK [ 115.441344] Testing event mm_compaction_deferred: OK [ 115.468416] Testing event mm_compaction_suitable: OK [ 115.493795] Testing event mm_compaction_finished: OK [ 115.520761] Testing event mm_compaction_try_to_compact_pages: OK [ 115.548320] Testing event mm_compaction_end: OK [ 115.574096] Testing event mm_compaction_begin: OK [ 115.601802] Testing event mm_compaction_migratepages: OK [ 115.627666] Testing event mm_compaction_isolate_freepages: OK [ 115.652632] Testing event mm_compaction_isolate_migratepages: OK [ 115.680950] Testing event mm_migrate_pages: OK [ 115.708079] Testing event mm_collapse_huge_page_swapin: OK [ 115.734352] Testing event mm_collapse_huge_page_isolate: OK [ 115.761923] Testing event mm_collapse_huge_page: OK [ 115.788087] Testing event mm_khugepaged_scan_pmd: OK [ 115.813345] Testing event test_pages_isolated: OK [ 115.840047] Testing event cma_release: OK [ 115.865637] Testing event cma_alloc: OK [ 115.892104] Testing event page_ref_unfreeze: OK [ 115.920958] Testing event page_ref_freeze: OK [ 115.949666] Testing event page_ref_mod_unless: OK [ 115.982165] Testing event page_ref_mod_and_return: OK [ 116.008112] Testing event page_ref_mod_and_test: OK [ 116.140256] Testing event page_ref_mod: OK [ 116.198636] Testing event page_ref_set: OK [ 116.229516] Testing event sb_clear_inode_writeback: OK [ 116.254044] Testing event sb_mark_inode_writeback: OK [ 116.280650] Testing event writeback_dirty_inode_enqueue: OK [ 116.305582] Testing event writeback_lazytime_iput: OK [ 116.329454] Testing event writeback_lazytime: OK [ 116.356235] Testing event writeback_single_inode: OK [ 116.381753] Testing event writeback_single_inode_start: OK [ 116.408860] Testing event writeback_wait_iff_congested: OK [ 116.436361] Testing event writeback_congestion_wait: OK [ 116.461958] Testing event writeback_sb_inodes_requeue: OK [ 116.489828] Testing event balance_dirty_pages: OK [ 116.515780] Testing event bdi_dirty_ratelimit: OK [ 116.540519] Testing event global_dirty_state: OK [ 116.566649] Testing event writeback_queue_io: OK [ 116.593361] Testing event wbc_writepage: OK [ 116.617413] Testing event writeback_bdi_register: OK [ 116.643700] Testing event writeback_wake_background: OK [ 116.670273] Testing event writeback_pages_written: OK [ 116.697544] Testing event writeback_wait: OK [ 116.721422] Testing event writeback_written: OK [ 116.747687] Testing event writeback_start: OK [ 116.774233] Testing event writeback_exec: OK [ 116.801305] Testing event writeback_queue: OK [ 116.825452] Testing event writeback_write_inode: OK [ 116.851602] Testing event writeback_write_inode_start: OK [ 116.877924] Testing event writeback_dirty_inode: OK [ 116.904135] Testing event writeback_dirty_inode_start: OK [ 116.929743] Testing event writeback_mark_inode_dirty: OK [ 116.956408] Testing event writeback_dirty_page: OK [ 116.981641] Testing event dax_writeback_one: OK [ 117.005475] Testing event dax_writeback_range_done: OK [ 117.032412] Testing event dax_writeback_range: OK [ 117.057852] Testing event dax_insert_mapping: OK [ 117.084767] Testing event dax_insert_pfn_mkwrite: OK [ 117.112260] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 117.138199] Testing event dax_load_hole: OK [ 117.166402] Testing event dax_pte_fault_done: OK [ 117.193434] Testing event dax_pte_fault: OK [ 117.217449] Testing event dax_pmd_insert_mapping: OK [ 117.242642] Testing event dax_pmd_load_hole_fallback: OK [ 117.268698] Testing event dax_pmd_load_hole: OK [ 117.293502] Testing event dax_pmd_fault_done: OK [ 117.317263] Testing event dax_pmd_fault: OK [ 117.341574] Testing event generic_add_lease: OK [ 117.368242] Testing event time_out_leases: OK [ 117.393759] Testing event generic_delete_lease: OK [ 117.420979] Testing event break_lease_unblock: OK [ 117.448272] Testing event break_lease_block: OK [ 117.474025] Testing event break_lease_noblock: OK [ 117.501673] Testing event flock_lock_inode: OK [ 117.528129] Testing event locks_remove_posix: OK [ 117.553781] Testing event fcntl_setlk: OK [ 117.580164] Testing event posix_lock_inode: OK [ 117.605376] Testing event locks_get_lock_context: OK [ 117.629473] Testing event block_rq_remap: OK [ 117.653360] Testing event block_bio_remap: OK [ 117.677715] Testing event block_split: OK [ 117.704170] Testing event block_unplug: OK [ 117.729875] Testing event block_plug: OK [ 117.756926] Testing event block_sleeprq: OK [ 117.784079] Testing event block_getrq: OK [ 117.810037] Testing event block_bio_queue: OK [ 117.837615] Testing event block_bio_frontmerge: OK [ 117.864071] Testing event block_bio_backmerge: OK [ 117.889298] Testing event block_bio_complete: OK [ 117.914047] Testing event block_bio_bounce: OK [ 117.941767] Testing event block_rq_issue: OK [ 117.968079] Testing event block_rq_insert: OK [ 117.993910] Testing event block_rq_complete: OK [ 118.020610] Testing event block_rq_requeue: OK [ 118.045609] Testing event block_dirty_buffer: OK [ 118.069477] Testing event block_touch_buffer: OK [ 118.096334] Testing event wbt_timer: OK [ 118.121553] Testing event wbt_step: OK [ 118.145491] Testing event wbt_lat: OK [ 118.172510] Testing event wbt_stat: OK [ 118.197675] Testing event gpio_value: OK [ 118.225348] Testing event gpio_direction: OK [ 118.252502] Testing event clk_set_duty_cycle_complete: OK [ 118.277509] Testing event clk_set_duty_cycle: OK [ 118.304289] Testing event clk_set_phase_complete: OK [ 118.329368] Testing event clk_set_phase: OK [ 118.353476] Testing event clk_set_parent_complete: OK [ 118.380379] Testing event clk_set_parent: OK [ 118.405882] Testing event clk_set_rate_complete: OK [ 118.432780] Testing event clk_set_rate: OK [ 118.460273] Testing event clk_unprepare_complete: OK [ 118.486096] Testing event clk_unprepare: OK [ 118.513728] Testing event clk_prepare_complete: OK [ 118.539979] Testing event clk_prepare: OK [ 118.566094] Testing event clk_disable_complete: OK [ 118.593758] Testing event clk_disable: OK [ 118.619908] Testing event clk_enable_complete: OK [ 118.646124] Testing event clk_enable: OK [ 118.674544] Testing event rpmh_send_msg: OK [ 118.701321] Testing event rpmh_tx_done: OK [ 118.725543] Testing event regulator_set_voltage_complete: OK [ 118.751888] Testing event regulator_set_voltage: OK [ 118.777835] Testing event regulator_disable_complete: OK [ 118.804203] Testing event regulator_disable: OK [ 118.829295] Testing event regulator_enable_complete: OK [ 118.853619] Testing event regulator_enable_delay: OK [ 118.880401] Testing event regulator_enable: OK [ 118.906436] Testing event urandom_read: OK [ 118.931906] Testing event random_read: OK [ 118.958123] Testing event extract_entropy_user: OK [ 118.983451] Testing event extract_entropy: OK [ 119.008591] Testing event get_random_bytes_arch: OK [ 119.033748] Testing event get_random_bytes: OK [ 119.060202] Testing event xfer_secondary_pool: OK [ 119.085422] Testing event add_disk_randomness: OK [ 119.109498] Testing event add_input_randomness: OK [ 119.136406] Testing event debit_entropy: OK [ 119.161827] Testing event push_to_pool: OK [ 119.188740] Testing event credit_entropy_bits: OK [ 119.216262] Testing event mix_pool_bytes_nolock: OK [ 119.242128] Testing event mix_pool_bytes: OK [ 119.270219] Testing event add_device_randomness: OK [ 119.296431] Testing event io_page_fault: OK [ 119.321733] Testing event unmap: OK [ 119.348158] Testing event map: OK [ 119.373313] Testing event detach_device_from_domain: OK [ 119.397582] Testing event attach_device_to_domain: OK [ 119.424222] Testing event remove_device_from_group: OK [ 119.449368] Testing event add_device_to_group: OK [ 119.473527] Testing event regcache_drop_region: OK [ 119.500180] Testing event regmap_async_complete_done: OK [ 119.525354] Testing event regmap_async_complete_start: OK [ 119.549542] Testing event regmap_async_io_complete: OK [ 119.576235] Testing event regmap_async_write_start: OK [ 119.601499] Testing event regmap_cache_bypass: OK [ 119.625515] Testing event regmap_cache_only: OK [ 119.652378] Testing event regcache_sync: OK [ 119.677836] Testing event regmap_hw_write_done: OK [ 119.704923] Testing event regmap_hw_write_start: OK [ 119.732522] Testing event regmap_hw_read_done: OK [ 119.757931] Testing event regmap_hw_read_start: OK [ 119.785651] Testing event regmap_reg_read_cache: OK [ 119.812072] Testing event regmap_reg_read: OK [ 119.837256] Testing event regmap_reg_write: OK [ 119.861801] Testing event dma_fence_wait_end: OK [ 119.888209] Testing event dma_fence_wait_start: OK [ 119.913694] Testing event dma_fence_signaled: OK [ 119.940917] Testing event dma_fence_enable_signal: OK [ 119.968205] Testing event dma_fence_destroy: OK [ 119.994020] Testing event dma_fence_init: OK [ 120.022408] Testing event dma_fence_emit: OK [ 120.046528] Testing event sync_timeline: OK [ 120.072553] Testing event spi_transfer_stop: OK [ 120.097763] Testing event spi_transfer_start: OK [ 120.124180] Testing event spi_message_done: OK [ 120.149338] Testing event spi_message_start: OK [ 120.173518] Testing event spi_message_submit: OK [ 120.200324] Testing event spi_controller_busy: OK [ 120.225359] Testing event spi_controller_idle: OK [ 120.249704] Testing event mdio_access: OK [ 120.278076] Testing event rtc_timer_fired: OK [ 120.303944] Testing event rtc_timer_dequeue: OK [ 120.329800] Testing event rtc_timer_enqueue: OK [ 120.356187] Testing event rtc_read_offset: OK [ 120.382080] Testing event rtc_set_offset: OK [ 120.408018] Testing event rtc_alarm_irq_enable: OK [ 120.433458] Testing event rtc_irq_set_state: OK [ 120.460160] Testing event rtc_irq_set_freq: OK [ 120.485537] Testing event rtc_read_alarm: OK [ 120.512332] Testing event rtc_set_alarm: OK [ 120.537612] Testing event rtc_read_time: OK [ 120.564312] Testing event rtc_set_time: OK [ 120.589287] Testing event memory_failure_event: OK [ 120.613457] Testing event aer_event: OK [ 120.640223] Testing event non_standard_event: OK [ 120.665302] Testing event arm_event: OK [ 120.689440] Testing event mc_event: OK [ 120.713570] Testing event binder_return: OK [ 120.740360] Testing event binder_command: OK [ 120.765796] Testing event binder_unmap_kernel_end: OK [ 120.789486] Testing event binder_unmap_kernel_start: OK [ 120.816400] Testing event binder_unmap_user_end: OK [ 120.841763] Testing event binder_unmap_user_start: OK [ 120.868569] Testing event binder_alloc_page_end: OK [ 120.896333] Testing event binder_alloc_page_start: OK [ 120.923870] Testing event binder_free_lru_end: OK [ 120.950155] Testing event binder_free_lru_start: OK [ 120.977325] Testing event binder_alloc_lru_end: OK [ 121.004439] Testing event binder_alloc_lru_start: OK [ 121.029824] Testing event binder_update_page_range: OK [ 121.056750] Testing event binder_transaction_failed_buffer_release: OK [ 121.084354] Testing event binder_transaction_buffer_release: OK [ 121.110196] Testing event binder_transaction_alloc_buf: OK [ 121.138241] Testing event binder_transaction_fd_recv: OK [ 121.164019] Testing event binder_transaction_fd_send: OK [ 121.189458] Testing event binder_transaction_ref_to_ref: OK [ 121.213521] Testing event binder_transaction_ref_to_node: OK [ 121.239650] Testing event binder_transaction_node_to_ref: OK [ 121.266335] Testing event binder_transaction_received: OK [ 121.293350] Testing event binder_transaction: OK [ 121.317337] Testing event binder_wait_for_work: OK [ 121.343669] Testing event binder_read_done: OK [ 121.370220] Testing event binder_write_done: OK [ 121.397421] Testing event binder_ioctl_done: OK [ 121.421381] Testing event binder_unlock: OK [ 121.448268] Testing event binder_locked: OK [ 121.473234] Testing event binder_lock: OK [ 121.496707] Testing event binder_ioctl: OK [ 121.521761] Testing event br_fdb_update: OK [ 121.545402] Testing event fdb_delete: OK [ 121.569989] Testing event br_fdb_external_learn_add: OK [ 121.592935] Testing event br_fdb_add: OK [ 121.617329] Testing event qdisc_dequeue: OK [ 121.641494] Testing event fib_table_lookup: OK [ 121.668586] Testing event tcp_probe: OK [ 121.693749] Testing event tcp_retransmit_synack: OK [ 121.720769] Testing event tcp_rcv_space_adjust: OK [ 121.748260] Testing event tcp_destroy_sock: OK [ 121.774075] Testing event tcp_receive_reset: OK [ 121.801624] Testing event tcp_send_reset: OK [ 121.828219] Testing event tcp_retransmit_skb: OK [ 121.853758] Testing event udp_fail_queue_rcv_skb: OK [ 121.880252] Testing event inet_sock_set_state: OK [ 121.906063] Testing event sock_exceed_buf_limit: OK [ 121.932508] Testing event sock_rcvqueue_full: OK [ 121.958431] Testing event napi_poll: OK [ 121.985709] Testing event netif_rx_ni_entry: OK [ 122.012098] Testing event netif_rx_entry: OK [ 122.037563] Testing event netif_receive_skb_list_entry: OK [ 122.061914] Testing event netif_receive_skb_entry: OK [ 122.088207] Testing event napi_gro_receive_entry: OK [ 122.113355] Testing event napi_gro_frags_entry: OK [ 122.139968] Testing event netif_rx: OK [ 122.165921] Testing event netif_receive_skb: OK [ 122.192156] Testing event net_dev_queue: OK [ 122.217392] Testing event net_dev_xmit: OK [ 122.244057] Testing event net_dev_start_xmit: OK [ 122.269972] Testing event skb_copy_datagram_iovec: OK [ 122.296068] Testing event consume_skb: OK [ 122.321591] Testing event kfree_skb: OK [ 122.348374] Testing event 9p_protocol_dump: OK [ 122.381176] Testing event 9p_client_res: OK [ 122.406130] Testing event 9p_client_req: OK [ 122.432731] Running tests on trace event systems: [ 122.433232] Testing event system 9p: OK [ 122.469851] Testing event system skb: OK [ 122.500359] Testing event system net: OK [ 122.533453] Testing event system napi: OK [ 122.561015] Testing event system sock: OK [ 122.592002] Testing event system udp: OK [ 122.619950] Testing event system tcp: OK [ 122.651970] Testing event system fib: OK [ 122.678189] Testing event system qdisc: OK [ 122.706550] Testing event system bridge: OK [ 122.734034] Testing event system binder: OK [ 122.782394] Testing event system ras: OK [ 122.812279] Testing event system rtc: OK [ 122.851374] Testing event system mdio: OK [ 122.880770] Testing event system spi: OK [ 122.915660] Testing event system sync_trace: OK [ 122.942305] Testing event system dma_fence: OK [ 122.975408] Testing event system regmap: OK [ 123.012335] Testing event system iommu: OK [ 123.044792] Testing event system random: OK [ 123.084335] Testing event system regulator: OK [ 123.117285] Testing event system rpmh: OK [ 123.147931] Testing event system clk: OK [ 123.189380] Testing event system gpio: OK [ 123.217256] Testing event system wbt: OK [ 123.249768] Testing event system block: OK [ 123.288639] Testing event system filelock: OK [ 123.323473] Testing event system fs_dax: OK [ 123.357761] Testing event system writeback: OK [ 123.406474] Testing event system page_ref: OK [ 123.595592] Testing event system cma: OK [ 123.625313] Testing event system page_isolation: OK [ 123.653366] Testing event system huge_memory: OK [ 123.684833] Testing event system migrate: OK [ 123.710583] Testing event system compaction: OK [ 123.749108] Testing event system kmem: OK [ 123.787802] Testing event system percpu: OK [ 123.819850] Testing event system vmscan: OK [ 123.856304] Testing event system pagemap: OK [ 123.885082] Testing event system oom: OK [ 123.919389] Testing event system filemap: OK [ 123.947961] Testing event system rseq: OK [ 123.976982] Testing event system xdp: OK [ 124.012379] Testing event system benchmark: OK [ 124.133407] Testing event system rpm: OK [ 124.164202] Testing event system power: OK [ 124.216862] Testing event system test: OK [ 124.241801] Testing event system preemptirq: OK [ 124.292181] Testing event system cgroup: OK [ 124.325780] Testing event system module: OK [ 124.356451] Testing event system alarmtimer: OK [ 124.385412] Testing event system timer: OK [ 124.427808] Testing event system swiotlb: OK [ 124.454476] Testing event system rcu: OK [ 124.508884] Testing event system printk: OK [ 124.536208] Testing event system lock: OK [ 124.593551] Testing event system sched: OK [ 124.640656] Testing event system workqueue: OK [ 124.671635] Testing event system signal: OK [ 124.703658] Testing event system irq: OK [ 124.733986] Testing event system cpuhp: OK [ 124.765238] Testing event system task: OK [ 124.793582] Testing event system kvm: OK [ 124.845599] Testing event system emulation: OK [ 124.872799] Testing event system ipi: OK [ 124.904305] Testing event system raw_syscalls: OK [ 124.930373] Testing event system initcall: OK [ 124.960516] Running tests on all trace events: [ 124.960959] Testing all events: OK [ 150.711867] watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [swapper/0:1] [ 150.713466] Modules linked in: [ 150.715298] irq event stamp: 1663716 [ 150.717182] hardirqs last enabled at (1663715): [] _raw_spin_unlock_irqrestore+0x50/0x98 [ 150.719007] hardirqs last disabled at (1663716): [] el1_irq+0xa8/0x1c0 [ 150.720850] softirqs last enabled at (1627316): [] __do_softirq+0x988/0x9d0 [ 150.722798] softirqs last disabled at (1627307): [] irq_exit+0x128/0x1b8 [ 150.724718] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.20.0-rc3-next-20181122-00007-g64e3bffc54df-dirty #44 [ 150.726243] Hardware name: linux,dummy-virt (DT) [ 150.727834] pstate: 20400005 (nzCv daif +PAN -UAO) [ 150.729693] pc : _raw_spin_unlock_irqrestore+0x54/0x98 [ 150.731479] lr : _raw_spin_unlock_irqrestore+0x50/0x98 [ 150.732906] sp : ffffff800804ba20 [ 150.734265] x29: ffffff800804ba20 x28: 0000000000000000 [ 150.736109] x27: ffffff8009489000 x26: ffffff8009a15df8 [ 150.738057] x25: ffffff8009a9a608 x24: 0000000000000001 [ 150.739899] x23: 00000000d503201f x22: ffffff80087fd1bc [ 150.741763] x21: ffffff8008e37db8 x20: ffffff8009a251f0 [ 150.743609] x19: 0000000000000000 x18: 0000000034f4d91d [ 150.745468] x17: 0000000000000000 x16: 0000000000000000 [ 150.747247] x15: 00000000000027c1 x14: ffffff8009a61628 [ 150.749139] x13: 00000000000027bf x12: ffffff800a444ab0 [ 150.751015] x11: ffffff8009e16b88 x10: 0000000000008008 [ 150.752887] x9 : ffffff8009a25208 x8 : 0000000000000001 [ 150.754701] x7 : 0000004073245000 x6 : ffffff8009a9d200 [ 150.756605] x5 : 0000000000000000 x4 : 0000000000000002 [ 150.758491] x3 : fffffffffffffe80 x2 : ffffff800a4423b0 [ 150.760406] x1 : 000000000000001a x0 : 00000000000ecdbb [ 150.762480] Call trace: [ 150.764225] _raw_spin_unlock_irqrestore+0x54/0x98 [ 150.766182] __aarch64_insn_write+0x60/0x78 [ 150.767932] aarch64_insn_write+0x24/0x30 [ 150.769679] aarch64_insn_patch_text_nosync+0x30/0x68 [ 150.771571] ftrace_modify_code+0x50/0xc8 [ 150.773443] ftrace_make_call+0x16c/0x248 [ 150.775319] __ftrace_replace_code+0x80/0xc0 [ 150.777090] ftrace_replace_code+0x60/0xf0 [ 150.778871] ftrace_modify_all_code+0x40/0xe8 [ 150.780654] arch_ftrace_update_code+0x1c/0x28 [ 150.782460] ftrace_run_update_code+0x38/0x50 [ 150.784198] ftrace_startup_enable+0x48/0x58 [ 150.786028] ftrace_startup+0x104/0x140 [ 150.787784] register_ftrace_function+0x6c/0x88 [ 150.789571] event_trace_self_test_with_function+0xa0/0xc4 [ 150.791346] event_trace_self_tests_init+0x4c/0x60 [ 150.793175] do_one_initcall+0x31c/0x6c8 [ 150.794963] do_initcall_level+0x4ac/0x50c [ 150.796742] do_initcalls+0x28/0x40 [ 150.798515] do_basic_setup+0x34/0x3c [ 150.800338] kernel_init_freeable+0x12c/0x1d4 [ 150.802150] kernel_init+0x28/0x140 [ 150.803895] ret_from_fork+0x10/0x18 [ 152.399025] rcu: INFO: rcu_sched detected stalls on CPUs/tasks: [ 152.399923] rcu: (detected by 0, t=6502 jiffies, g=8649, q=499) [ 152.400840] rcu: All QSes seen, last rcu_sched kthread activity 6502 (4294927878-4294921376), jiffies_till_next_fqs=1, root ->qsmask 0x0 [ 152.401544] swapper/0 R running task 9616 1 0 0x0000002a [ 152.402361] Call trace: [ 152.402828] dump_backtrace+0x0/0x230 [ 152.403313] show_stack+0x28/0x38 [ 152.403762] sched_show_task+0x394/0x3e0 [ 152.404249] print_other_cpu_stall+0x3b8/0x440 [ 152.404771] check_cpu_stall+0x1ac/0x1f0 [ 152.405251] rcu_pending+0x38/0x128 [ 152.405699] rcu_check_callbacks+0x31c/0x598 [ 152.406213] update_process_times+0x38/0x68 [ 152.406711] tick_sched_handle+0x68/0x80 [ 152.407199] tick_sched_timer+0x58/0xb0 [ 152.407688] __run_hrtimer+0x620/0x950 [ 152.408188] __hrtimer_run_queues+0xf4/0x150 [ 152.408695] hrtimer_interrupt+0x11c/0x338 [ 152.409156] arch_timer_handler_virt+0x44/0x60 [ 152.409656] handle_percpu_devid_irq+0x360/0x758 [ 152.410175] generic_handle_irq+0x40/0x60 [ 152.410639] __handle_domain_irq+0xa8/0xd8 [ 152.411130] gic_handle_irq+0x8c/0xe8 [ 152.411576] el1_irq+0xec/0x1c0 [ 152.412061] trace_event_buffer_lock_reserve+0x264/0x290 [ 152.412595] function_test_events_call+0x144/0x408 [ 152.413075] ftrace_ops_no_ops+0x20c/0x220 [ 152.413567] ftrace_graph_call+0x0/0xc [ 152.414053] __check_object_size+0x24/0x680 [ 152.414546] __probe_kernel_read+0x9c/0x158 [ 152.415039] aarch64_insn_read+0x40/0x88 [ 152.415528] ftrace_modify_code+0x6c/0xc8 [ 152.416016] ftrace_make_call+0x16c/0x248 [ 152.416522] __ftrace_replace_code+0x80/0xc0 [ 152.417027] ftrace_replace_code+0x60/0xf0 [ 152.417505] ftrace_modify_all_code+0x40/0xe8 [ 152.418052] arch_ftrace_update_code+0x1c/0x28 [ 152.418578] ftrace_run_update_code+0x38/0x50 [ 152.419100] ftrace_startup_enable+0x48/0x58 [ 152.419616] ftrace_startup+0x104/0x140 [ 152.420084] register_ftrace_function+0x6c/0x88 [ 152.420613] event_trace_self_test_with_function+0xa0/0xc4 [ 152.421112] event_trace_self_tests_init+0x4c/0x60 [ 152.421628] do_one_initcall+0x31c/0x6c8 [ 152.422139] do_initcall_level+0x4ac/0x50c [ 152.422645] do_initcalls+0x28/0x40 [ 152.423101] do_basic_setup+0x34/0x3c [ 152.423608] kernel_init_freeable+0x12c/0x1d4 [ 152.424086] kernel_init+0x28/0x140 [ 152.424624] ret_from_fork+0x10/0x18 [ 152.425257] rcu: rcu_sched kthread starved for 6502 jiffies! g8649 f0x2 RCU_GP_WAIT_FQS(5) ->state=0x0 ->cpu=0 [ 152.425797] rcu: RCU grace-period kthread stack dump: [ 152.426255] rcu_sched R running task 13824 10 2 0x00000028 [ 152.426944] Call trace: [ 152.427416] __switch_to+0x5c/0x68 [ 152.427911] __schedule+0xa3c/0xac0 [ 152.428467] schedule+0x9c/0xe0 [ 152.428936] schedule_timeout+0x950/0x9b8 [ 152.429439] rcu_gp_fqs_loop+0x39c/0xc10 [ 152.429942] rcu_gp_kthread+0x5fc/0x618 [ 152.430416] kthread+0x150/0x168 [ 152.430910] ret_from_fork+0x10/0x18 [ 183.936835] Running tests again, along with the function tracer [ 183.950435] Running tests on trace events: [ 183.962477] Testing event initcall_finish: [ 184.024728] BUG: workqueue lockup - pool cpus=0 node=0 flags=0x0 nice=0 stuck for 56s! [ 184.071933] Showing busy workqueues and worker pools: [ 184.087454] workqueue events: flags=0x0 [ 184.098941] pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=2/256 [ 184.107607] pending: vmstat_shepherd, psi_update_work [ 184.119661] workqueue mm_percpu_wq: flags=0x8 [ 184.133807] pwq 0: cpus=0 node=0 flags=0x0 nice=0 active=1/256 [ 184.140980] pending: vmstat_update [ 186.492915] OK [ 186.510321] Testing event initcall_start: OK [ 186.870568] Testing event initcall_level: OK [ 187.286648] Testing event sys_exit: OK [ 187.826725] Testing event sys_enter: OK [ 188.254544] Testing event ipi_exit: OK [ 188.653926] Testing event ipi_entry: OK [ 189.038436] Testing event ipi_raise: OK [ 189.443272] Testing event instruction_emulation: OK [ 189.822438] Testing event kvm_halt_poll_ns: OK [ 190.214714] Testing event kvm_age_page: OK [ 190.674087] Testing event kvm_fpu: OK [ 191.146631] Testing event kvm_mmio: OK [ 191.510362] Testing event kvm_ack_irq: OK [ 191.994203] Testing event kvm_set_irq: OK [ 192.402100] Testing event kvm_vcpu_wakeup: OK [ 192.754536] Testing event kvm_userspace_exit: OK [ 193.234269] Testing event kvm_timer_update_irq: OK [ 193.687265] Testing event kvm_toggle_cache: OK [ 194.094467] Testing event kvm_set_way_flush: OK [ 194.510645] Testing event kvm_test_age_hva: OK [ 194.899188] Testing event kvm_age_hva: OK [ 195.234533] Testing event kvm_set_spte_hva: OK [ 195.858507] Testing event kvm_unmap_hva_range: OK [ 196.250622] Testing event kvm_mmio_emulate: OK [ 196.786265] Testing event kvm_irq_line: OK [ 197.118441] Testing event kvm_access_fault: OK [ 197.498520] Testing event kvm_guest_fault: OK [ 197.850271] Testing event kvm_exit: OK [ 198.226655] Testing event kvm_entry: OK [ 198.618197] Testing event kvm_set_guest_debug: OK [ 199.114669] Testing event kvm_handle_sys_reg: OK [ 199.558308] Testing event trap_reg: OK [ 200.026246] Testing event kvm_arm_set_regset: OK [ 200.381857] Testing event kvm_arm_set_dreg32: OK [ 200.790309] Testing event kvm_arm_clear_debug: OK [ 201.153944] Testing event kvm_arm_setup_debug: OK [ 201.549773] Testing event kvm_hvc_arm64: OK [ 201.965725] Testing event kvm_wfx_arm64: OK [ 202.485650] Testing event vgic_update_irq_pending: OK [ 202.834396] Testing event task_rename: OK [ 203.257834] Testing event task_newtask: OK [ 203.725754] Testing event cpuhp_exit: OK [ 204.285925] Testing event cpuhp_multi_enter: OK [ 204.713764] Testing event cpuhp_enter: OK [ 205.130470] Testing event softirq_raise: OK [ 205.546508] Testing event softirq_exit: OK [ 205.930086] Testing event softirq_entry: OK [ 206.410129] Testing event irq_handler_exit: OK [ 206.893977] Testing event irq_handler_entry: OK [ 207.342443] Testing event signal_deliver: OK [ 207.802031] Testing event signal_generate: OK [ 208.173769] Testing event workqueue_execute_end: OK [ 208.538573] Testing event workqueue_execute_start: OK [ 208.895218] Testing event workqueue_activate_work: OK [ 209.302404] Testing event workqueue_queue_work: OK [ 209.781769] Testing event sched_wake_idle_without_ipi: OK [ 210.110458] Testing event sched_swap_numa: OK [ 210.666234] Testing event sched_stick_numa: OK [ 211.102261] Testing event sched_move_numa: OK [ 211.474374] Testing event sched_process_hang: OK [ 211.926194] Testing event sched_pi_setprio: OK [ 212.250392] Testing event sched_stat_runtime: OK [ 212.757619] Testing event sched_stat_blocked: OK [ 213.181925] Testing event sched_stat_iowait: OK [ 213.666302] Testing event sched_stat_sleep: OK [ 214.067269] Testing event sched_stat_wait: OK [ 214.445996] Testing event sched_process_exec: OK [ 214.858331] Testing event sched_process_fork: OK [ 215.279292] Testing event sched_process_wait: OK [ 215.621604] Testing event sched_wait_task: OK [ 216.142482] Testing event sched_process_exit: OK [ 216.518472] Testing event sched_process_free: OK [ 216.897888] Testing event sched_migrate_task: OK [ 217.298672] Testing event sched_switch: OK [ 217.642404] Testing event sched_wakeup_new: OK [ 218.010038] Testing event sched_wakeup: OK [ 218.550541] Testing event sched_waking: OK [ 218.982723] Testing event sched_kthread_stop_ret: OK [ 219.378171] Testing event sched_kthread_stop: OK [ 219.798375] Testing event lock_acquired: OK [ 220.202415] Testing event lock_contended: OK [ 220.702087] Testing event lock_release: OK [ 221.106586] Testing event lock_acquire: OK [ 221.570304] Testing event console: OK [ 222.046580] Testing event rcu_barrier: OK [ 222.446025] Testing event rcu_torture_read: OK [ 222.854679] Testing event rcu_batch_end: OK [ 223.301874] Testing event rcu_invoke_kfree_callback: OK [ 223.810322] Testing event rcu_invoke_callback: OK [ 224.209958] Testing event rcu_batch_start: OK [ 224.667241] Testing event rcu_kfree_callback: OK [ 225.042547] Testing event rcu_callback: OK [ 225.450073] Testing event rcu_dyntick: OK [ 225.982339] Testing event rcu_fqs: OK [ 226.610066] Testing event rcu_quiescent_state_report: OK [ 226.930474] Testing event rcu_unlock_preempted_task: OK [ 227.258370] Testing event rcu_preempt_task: OK [ 227.617705] Testing event rcu_exp_funnel_lock: OK [ 227.918685] Testing event rcu_exp_grace_period: OK [ 228.326228] Testing event rcu_grace_period_init: OK [ 228.729723] Testing event rcu_future_grace_period: OK [ 229.178575] Testing event rcu_grace_period: OK [ 229.746709] Testing event rcu_utilization: OK [ 230.227204] Testing event swiotlb_bounced: OK [ 230.650619] Testing event tick_stop: OK [ 231.102291] Testing event itimer_expire: OK [ 231.482527] Testing event itimer_state: OK [ 231.882271] Testing event hrtimer_cancel: OK [ 232.342109] Testing event hrtimer_expire_exit: OK [ 232.774549] Testing event hrtimer_expire_entry: OK [ 233.242269] Testing event hrtimer_start: OK [ 233.498267] Testing event hrtimer_init: OK [ 233.917847] Testing event timer_cancel: OK [ 234.434550] Testing event timer_expire_exit: OK [ 234.802414] Testing event timer_expire_entry: OK [ 235.151255] Testing event timer_start: OK [ 235.617877] Testing event timer_init: OK [ 235.970308] Testing event alarmtimer_cancel: OK [ 236.309769] Testing event alarmtimer_start: OK [ 236.774147] Testing event alarmtimer_fired: OK [ 237.198684] Testing event alarmtimer_suspend: OK [ 237.562666] Testing event module_request: OK [ 237.950242] Testing event module_put: OK [ 238.270503] Testing event module_get: OK [ 238.594101] Testing event module_free: OK [ 238.946466] Testing event module_load: OK [ 239.442544] Testing event cgroup_transfer_tasks: OK [ 240.127310] Testing event cgroup_attach_task: OK [ 240.593687] Testing event cgroup_rename: OK [ 241.053950] Testing event cgroup_release: OK [ 241.409987] Testing event cgroup_rmdir: OK [ 241.938323] Testing event cgroup_mkdir: OK [ 242.365791] Testing event cgroup_remount: OK [ 242.838572] Testing event cgroup_destroy_root: OK [ 243.194238] Testing event cgroup_setup_root: OK [ 243.609903] Testing event irq_enable: OK [ 245.238229] Testing event irq_disable: OK [ 246.186362] Testing event ftrace_test_filter: OK [ 246.626730] Testing event dev_pm_qos_remove_request: OK [ 247.091319] Testing event dev_pm_qos_update_request: OK [ 247.506263] Testing event dev_pm_qos_add_request: OK [ 247.959278] Testing event pm_qos_update_flags: OK [ 248.358333] Testing event pm_qos_update_target: OK [ 248.782529] Testing event pm_qos_update_request_timeout: OK [ 249.370145] Testing event pm_qos_remove_request: OK [ 249.854260] Testing event pm_qos_update_request: OK [ 250.259254] Testing event pm_qos_add_request: OK [ 250.606225] Testing event power_domain_target: OK [ 250.934357] Testing event clock_set_rate: OK [ 251.373109] Testing event clock_disable: OK [ 251.777981] Testing event clock_enable: OK [ 252.170355] Testing event wakeup_source_deactivate: OK [ 252.506302] Testing event wakeup_source_activate: OK [ 252.893511] Testing event suspend_resume: OK [ 253.462692] Testing event device_pm_callback_end: OK [ 253.890736] Testing event device_pm_callback_start: OK [ 254.274234] Testing event cpu_frequency_limits: OK [ 254.705786] Testing event cpu_frequency: OK [ 255.110313] Testing event pstate_sample: OK [ 255.485727] Testing event powernv_throttle: OK [ 255.930298] Testing event cpu_idle: OK [ 256.326302] Testing event rpm_return_int: OK [ 256.789894] Testing event rpm_idle: OK [ 257.293938] Testing event rpm_resume: OK [ 257.650399] Testing event rpm_suspend: OK [ 258.130000] Testing event benchmark_event: OK [ 258.658377] Testing event xdp_devmap_xmit: OK [ 259.058128] Testing event xdp_cpumap_enqueue: OK [ 259.454298] Testing event xdp_cpumap_kthread: OK [ 259.945607] Testing event xdp_redirect_map_err: OK [ 260.353882] Testing event xdp_redirect_map: OK [ 260.818154] Testing event xdp_redirect_err: OK [ 261.218691] Testing event xdp_redirect: OK [ 261.694464] Testing event xdp_exception: OK [ 262.074326] Testing event rseq_ip_fixup: OK [ 262.521848] Testing event rseq_update: OK [ 262.889871] Testing event file_check_and_advance_wb_err: OK [ 263.290307] Testing event filemap_set_wb_err: OK [ 263.746603] Testing event mm_filemap_add_to_page_cache: OK [ 264.157866] Testing event mm_filemap_delete_from_page_cache: OK [ 264.598111] Testing event compact_retry: OK [ 265.058042] Testing event skip_task_reaping: OK [ 265.405613] Testing event finish_task_reaping: OK [ 265.818160] Testing event start_task_reaping: OK [ 266.182199] Testing event wake_reaper: OK [ 266.717781] Testing event mark_victim: OK [ 267.089936] Testing event reclaim_retry_zone: OK [ 267.458702] Testing event oom_score_adj_update: OK [ 267.874496] Testing event mm_lru_activate: OK [ 268.218543] Testing event mm_lru_insertion: OK [ 268.754554] Testing event mm_vmscan_inactive_list_is_low: OK [ 269.294648] Testing event mm_vmscan_lru_shrink_active: OK [ 269.689824] Testing event mm_vmscan_lru_shrink_inactive: OK [ 270.014658] Testing event mm_vmscan_writepage: OK [ 270.390239] Testing event mm_vmscan_lru_isolate: OK [ 270.826182] Testing event mm_shrink_slab_end: OK [ 271.254734] Testing event mm_shrink_slab_start: OK [ 271.733982] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 272.178204] Testing event mm_vmscan_memcg_reclaim_end: OK [ 272.582220] Testing event mm_vmscan_direct_reclaim_end: OK [ 273.082406] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 273.583189] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 274.022191] Testing event mm_vmscan_direct_reclaim_begin: OK [ 274.381539] Testing event mm_vmscan_wakeup_kswapd: OK [ 274.862702] Testing event mm_vmscan_kswapd_wake: OK [ 275.405979] Testing event mm_vmscan_kswapd_sleep: OK [ 275.770116] Testing event percpu_destroy_chunk: OK [ 276.278496] Testing event percpu_create_chunk: OK [ 276.726200] Testing event percpu_alloc_percpu_fail: OK [ 277.193879] Testing event percpu_free_percpu: OK [ 277.734285] Testing event percpu_alloc_percpu: OK [ 278.114420] Testing event mm_page_alloc_extfrag: OK [ 278.498722] Testing event mm_page_pcpu_drain: OK [ 278.950101] Testing event mm_page_alloc_zone_locked: OK [ 279.326687] Testing event mm_page_alloc: OK [ 279.758062] Testing event mm_page_free_batched: OK [ 280.241905] Testing event mm_page_free: OK [ 280.702207] Testing event kmem_cache_free: OK [ 281.114491] Testing event kfree: OK [ 281.546195] Testing event kmem_cache_alloc_node: OK [ 281.906571] Testing event kmalloc_node: OK [ 282.326313] Testing event kmem_cache_alloc: OK [ 282.750288] Testing event kmalloc: OK [ 283.105977] Testing event mm_compaction_kcompactd_wake: OK [ 283.617992] Testing event mm_compaction_wakeup_kcompactd: OK [ 284.022291] Testing event mm_compaction_kcompactd_sleep: OK [ 284.414170] Testing event mm_compaction_defer_reset: OK [ 284.805558] Testing event mm_compaction_defer_compaction: OK [ 285.275269] Testing event mm_compaction_deferred: OK [ 285.666631] Testing event mm_compaction_suitable: OK [ 286.142171] Testing event mm_compaction_finished: OK [ 286.505985] Testing event mm_compaction_try_to_compact_pages: OK [ 287.094620] Testing event mm_compaction_end: OK [ 287.470673] Testing event mm_compaction_begin: OK [ 287.910459] Testing event mm_compaction_migratepages: OK [ 288.305980] Testing event mm_compaction_isolate_freepages: OK [ 288.846414] Testing event mm_compaction_isolate_migratepages: OK [ 289.250404] Testing event mm_migrate_pages: OK [ 289.678120] Testing event mm_collapse_huge_page_swapin: OK [ 290.082681] Testing event mm_collapse_huge_page_isolate: OK [ 290.745882] Testing event mm_collapse_huge_page: OK [ 291.094346] Testing event mm_khugepaged_scan_pmd: OK [ 291.542433] Testing event test_pages_isolated: OK [ 292.042490] Testing event cma_release: OK [ 292.365656] Testing event cma_alloc: OK [ 292.694243] Testing event page_ref_unfreeze: OK [ 293.146132] Testing event page_ref_freeze: OK [ 293.554035] Testing event page_ref_mod_unless: OK [ 293.981879] Testing event page_ref_mod_and_return: OK [ 294.554665] Testing event page_ref_mod_and_test: OK [ 295.810032] Testing event page_ref_mod: OK [ 296.570164] Testing event page_ref_set: OK [ 297.156733] Testing event sb_clear_inode_writeback: OK [ 297.573677] Testing event sb_mark_inode_writeback: OK [ 298.050206] Testing event writeback_dirty_inode_enqueue: OK [ 298.386100] Testing event writeback_lazytime_iput: OK [ 298.746737] Testing event writeback_lazytime: OK [ 299.086334] Testing event writeback_single_inode: OK [ 299.414226] Testing event writeback_single_inode_start: OK [ 299.822120] Testing event writeback_wait_iff_congested: OK [ 300.282458] Testing event writeback_congestion_wait: OK [ 300.806580] Testing event writeback_sb_inodes_requeue: OK [ 301.251251] Testing event balance_dirty_pages: OK [ 301.610754] Testing event bdi_dirty_ratelimit: OK [ 301.986752] Testing event global_dirty_state: OK [ 302.379281] Testing event writeback_queue_io: OK [ 302.782247] Testing event wbc_writepage: OK [ 303.221986] Testing event writeback_bdi_register: OK [ 303.657924] Testing event writeback_wake_background: OK [ 304.078321] Testing event writeback_pages_written: OK [ 304.418612] Testing event writeback_wait: OK [ 304.866498] Testing event writeback_written: OK [ 305.194562] Testing event writeback_start: OK [ 305.630512] Testing event writeback_exec: OK [ 306.142634] Testing event writeback_queue: OK [ 306.578693] Testing event writeback_write_inode: OK [ 307.038616] Testing event writeback_write_inode_start: OK [ 307.547240] Testing event writeback_dirty_inode: OK [ 307.974696] Testing event writeback_dirty_inode_start: OK [ 308.342443] Testing event writeback_mark_inode_dirty: OK [ 308.806219] Testing event writeback_dirty_page: OK [ 309.210142] Testing event dax_writeback_one: OK [ 309.610605] Testing event dax_writeback_range_done: OK [ 310.066332] Testing event dax_writeback_range: OK [ 310.582029] Testing event dax_insert_mapping: OK [ 310.982532] Testing event dax_insert_pfn_mkwrite: OK [ 311.466663] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 311.906460] Testing event dax_load_hole: OK [ 312.394336] Testing event dax_pte_fault_done: OK [ 312.746140] Testing event dax_pte_fault: OK [ 313.178337] Testing event dax_pmd_insert_mapping: OK [ 313.474528] Testing event dax_pmd_load_hole_fallback: OK [ 313.858505] Testing event dax_pmd_load_hole: OK [ 314.182194] Testing event dax_pmd_fault_done: OK [ 314.543269] Testing event dax_pmd_fault: OK [ 314.970693] Testing event generic_add_lease: OK [ 315.434285] Testing event time_out_leases: OK [ 315.854015] Testing event generic_delete_lease: OK [ 316.254408] Testing event break_lease_unblock: OK [ 316.642070] Testing event break_lease_block: OK [ 317.158423] Testing event break_lease_noblock: OK [ 317.478065] Testing event flock_lock_inode: OK [ 318.026373] Testing event locks_remove_posix: OK [ 318.438680] Testing event fcntl_setlk: OK [ 318.886040] Testing event posix_lock_inode: OK [ 319.254553] Testing event locks_get_lock_context: OK [ 319.722093] Testing event block_rq_remap: OK [ 320.106071] Testing event block_bio_remap: OK [ 320.494490] Testing event block_split: OK [ 320.918541] Testing event block_unplug: OK [ 321.310411] Testing event block_plug: OK [ 321.722088] Testing event block_sleeprq: OK [ 322.106601] Testing event block_getrq: OK [ 322.397978] Testing event block_bio_queue: OK [ 322.894190] Testing event block_bio_frontmerge: OK [ 323.294505] Testing event block_bio_backmerge: OK [ 323.645596] Testing event block_bio_complete: OK [ 324.034196] Testing event block_bio_bounce: OK [ 324.381608] Testing event block_rq_issue: OK [ 324.950389] Testing event block_rq_insert: OK [ 325.414475] Testing event block_rq_complete: OK [ 325.834159] Testing event block_rq_requeue: OK [ 326.165944] Testing event block_dirty_buffer: OK [ 326.737898] Testing event block_touch_buffer: OK [ 327.086095] Testing event wbt_timer: OK [ 327.561886] Testing event wbt_step: OK [ 327.877580] Testing event wbt_lat: OK [ 328.389778] Testing event wbt_stat: OK [ 328.946307] Testing event gpio_value: OK [ 329.293886] Testing event gpio_direction: OK [ 329.774154] Testing event clk_set_duty_cycle_complete: OK [ 330.225805] Testing event clk_set_duty_cycle: OK [ 330.562240] Testing event clk_set_phase_complete: OK [ 330.958159] Testing event clk_set_phase: OK [ 331.534289] Testing event clk_set_parent_complete: OK [ 331.990113] Testing event clk_set_parent: OK [ 332.401935] Testing event clk_set_rate_complete: OK [ 332.886209] Testing event clk_set_rate: OK [ 333.242241] Testing event clk_unprepare_complete: OK [ 333.658746] Testing event clk_unprepare: OK [ 334.062663] Testing event clk_prepare_complete: OK [ 334.486355] Testing event clk_prepare: OK [ 334.869898] Testing event clk_disable_complete: OK [ 335.233794] Testing event clk_disable: OK [ 335.710173] Testing event clk_enable_complete: OK [ 336.090468] Testing event clk_enable: OK [ 336.518014] Testing event rpmh_send_msg: OK [ 336.914317] Testing event rpmh_tx_done: OK [ 337.301879] Testing event regulator_set_voltage_complete: OK [ 337.641831] Testing event regulator_set_voltage: OK [ 338.130108] Testing event regulator_disable_complete: OK [ 338.642684] Testing event regulator_disable: OK [ 339.002462] Testing event regulator_enable_complete: OK [ 339.406704] Testing event regulator_enable_delay: OK [ 340.034483] Testing event regulator_enable: OK [ 340.358069] Testing event urandom_read: OK [ 340.833879] Testing event random_read: OK [ 341.182302] Testing event extract_entropy_user: OK [ 341.594298] Testing event extract_entropy: OK [ 341.890441] Testing event get_random_bytes_arch: OK [ 342.346729] Testing event get_random_bytes: OK [ 342.770072] Testing event xfer_secondary_pool: OK [ 343.198492] Testing event add_disk_randomness: OK [ 343.526288] Testing event add_input_randomness: OK [ 343.939311] Testing event debit_entropy: OK [ 344.494709] Testing event push_to_pool: OK [ 344.901817] Testing event credit_entropy_bits: OK [ 345.222447] Testing event mix_pool_bytes_nolock: OK [ 345.589581] Testing event mix_pool_bytes: OK [ 346.102282] Testing event add_device_randomness: OK [ 346.510289] Testing event io_page_fault: OK [ 346.877878] Testing event unmap: OK [ 347.386439] Testing event map: OK [ 347.845925] Testing event detach_device_from_domain: OK [ 348.173885] Testing event attach_device_to_domain: OK [ 348.534722] Testing event remove_device_from_group: OK [ 348.958688] Testing event add_device_to_group: OK [ 349.358322] Testing event regcache_drop_region: OK [ 349.749933] Testing event regmap_async_complete_done: OK [ 350.183292] Testing event regmap_async_complete_start: OK [ 350.558169] Testing event regmap_async_io_complete: OK [ 351.066144] Testing event regmap_async_write_start: OK [ 351.522124] Testing event regmap_cache_bypass: OK [ 351.898650] Testing event regmap_cache_only: OK [ 352.246736] Testing event regcache_sync: OK [ 352.749719] Testing event regmap_hw_write_done: OK [ 353.238371] Testing event regmap_hw_write_start: OK [ 353.457896] Testing event regmap_hw_read_done: OK [ 354.014134] Testing event regmap_hw_read_start: OK [ 354.406704] Testing event regmap_reg_read_cache: OK [ 354.871323] Testing event regmap_reg_read: OK [ 355.302495] Testing event regmap_reg_write: OK [ 355.702552] Testing event dma_fence_wait_end: OK [ 356.241912] Testing event dma_fence_wait_start: OK [ 356.715268] Testing event dma_fence_signaled: OK [ 357.147284] Testing event dma_fence_enable_signal: OK [ 357.591188] hrtimer: interrupt took 8562464 ns [ 357.596291] Testing event dma_fence_destroy: OK [ 357.970744] Testing event dma_fence_init: OK [ 358.386425] Testing event dma_fence_emit: OK [ 358.818043] Testing event sync_timeline: OK [ 359.202662] Testing event spi_transfer_stop: OK [ 359.622324] Testing event spi_transfer_start: OK [ 359.938138] Testing event spi_message_done: OK [ 360.298052] Testing event spi_message_start: OK [ 360.686168] Testing event spi_message_submit: OK [ 361.046737] Testing event spi_controller_busy: OK [ 361.542310] Testing event spi_controller_idle: OK [ 361.901872] Testing event mdio_access: OK [ 362.418099] Testing event rtc_timer_fired: OK [ 362.834156] Testing event rtc_timer_dequeue: OK [ 363.250457] Testing event rtc_timer_enqueue: OK [ 363.662347] Testing event rtc_read_offset: OK [ 364.051197] Testing event rtc_set_offset: OK [ 364.373938] Testing event rtc_alarm_irq_enable: OK [ 364.690299] Testing event rtc_irq_set_state: OK [ 365.122712] Testing event rtc_irq_set_freq: OK [ 365.489953] Testing event rtc_read_alarm: OK [ 365.950005] Testing event rtc_set_alarm: OK [ 366.454406] Testing event rtc_read_time: OK [ 366.814363] Testing event rtc_set_time: OK [ 367.309924] Testing event memory_failure_event: OK [ 367.722146] Testing event aer_event: OK [ 368.210079] Testing event non_standard_event: OK [ 368.546616] Testing event arm_event: OK [ 368.885766] Testing event mc_event: OK [ 369.327184] Testing event binder_return: OK [ 369.853873] Testing event binder_command: OK [ 370.282282] Testing event binder_unmap_kernel_end: OK [ 370.658261] Testing event binder_unmap_kernel_start: OK [ 370.986484] Testing event binder_unmap_user_end: OK [ 371.390099] Testing event binder_unmap_user_start: OK [ 371.762453] Testing event binder_alloc_page_end: OK [ 372.166371] Testing event binder_alloc_page_start: OK [ 372.622516] Testing event binder_free_lru_end: OK [ 373.087173] Testing event binder_free_lru_start: OK [ 373.482708] Testing event binder_alloc_lru_end: OK [ 373.918497] Testing event binder_alloc_lru_start: OK [ 374.318521] Testing event binder_update_page_range: OK [ 374.750352] Testing event binder_transaction_failed_buffer_release: OK [ 375.091223] Testing event binder_transaction_buffer_release: OK [ 375.510233] Testing event binder_transaction_alloc_buf: OK [ 375.886263] Testing event binder_transaction_fd_recv: OK [ 376.246717] Testing event binder_transaction_fd_send: OK [ 376.738094] Testing event binder_transaction_ref_to_ref: OK [ 377.199220] Testing event binder_transaction_ref_to_node: OK [ 377.453703] Testing event binder_transaction_node_to_ref: OK [ 377.882491] Testing event binder_transaction_received: OK [ 378.266565] Testing event binder_transaction: OK [ 378.750423] Testing event binder_wait_for_work: OK [ 379.150381] Testing event binder_read_done: OK [ 379.618310] Testing event binder_write_done: OK [ 380.078028] Testing event binder_ioctl_done: OK [ 380.614344] Testing event binder_unlock: OK [ 380.994693] Testing event binder_locked: OK [ 381.405861] Testing event binder_lock: OK [ 381.914094] Testing event binder_ioctl: OK [ 382.342359] Testing event br_fdb_update: OK [ 382.606448] Testing event fdb_delete: OK [ 382.906299] Testing event br_fdb_external_learn_add: OK [ 383.342372] Testing event br_fdb_add: OK [ 383.689540] Testing event qdisc_dequeue: OK [ 384.165992] Testing event fib_table_lookup: OK [ 384.582266] Testing event tcp_probe: OK [ 385.085909] Testing event tcp_retransmit_synack: OK [ 385.594425] Testing event tcp_rcv_space_adjust: OK [ 385.997893] Testing event tcp_destroy_sock: OK [ 386.334069] Testing event tcp_receive_reset: OK [ 386.707373] Testing event tcp_send_reset: OK [ 387.118546] Testing event tcp_retransmit_skb: OK [ 387.482317] Testing event udp_fail_queue_rcv_skb: OK [ 387.938249] Testing event inet_sock_set_state: OK [ 388.438365] Testing event sock_exceed_buf_limit: OK [ 388.765814] Testing event sock_rcvqueue_full: OK [ 389.218145] Testing event napi_poll: OK [ 389.622044] Testing event netif_rx_ni_entry: OK [ 390.011272] Testing event netif_rx_entry: OK [ 390.401952] Testing event netif_receive_skb_list_entry: OK [ 390.906244] Testing event netif_receive_skb_entry: OK [ 391.362207] Testing event napi_gro_receive_entry: OK [ 391.892666] Testing event napi_gro_frags_entry: OK [ 392.329881] Testing event netif_rx: OK [ 392.826044] Testing event netif_receive_skb: OK [ 393.226336] Testing event net_dev_queue: OK [ 393.753900] Testing event net_dev_xmit: OK [ 394.130250] Testing event net_dev_start_xmit: OK [ 394.594710] Testing event skb_copy_datagram_iovec: OK [ 395.066326] Testing event consume_skb: OK [ 395.521772] Testing event kfree_skb: OK [ 395.885962] Testing event 9p_protocol_dump: OK [ 396.373994] Testing event 9p_client_res: OK [ 396.882001] Testing event 9p_client_req: OK [ 397.174227] Running tests on trace event systems: [ 397.182006] Testing event system 9p: OK [ 397.738303] Testing event system skb: OK [ 398.210139] Testing event system net: OK [ 398.749923] Testing event system napi: OK [ 399.241757] Testing event system sock: OK [ 399.753717] Testing event system udp: OK [ 400.046240] Testing event system tcp: OK [ 400.598130] Testing event system fib: OK [ 400.993924] Testing event system qdisc: OK [ 401.362077] Testing event system bridge: OK [ 401.814191] Testing event system binder: OK [ 402.526372] Testing event system ras: OK [ 403.074578] Testing event system rtc: OK [ 403.662406] Testing event system mdio: OK [ 404.178271] Testing event system spi: OK [ 404.714474] Testing event system sync_trace: OK [ 405.146066] Testing event system dma_fence: OK [ 405.681946] Testing event system regmap: OK [ 406.166201] Testing event system iommu: OK [ 406.618283] Testing event system random: OK [ 407.218275] Testing event system regulator: OK [ 407.718493] Testing event system rpmh: OK [ 408.213789] Testing event system clk: OK [ 408.717901] Testing event system gpio: OK [ 409.238674] Testing event system wbt: OK [ 409.681845] Testing event system block: OK [ 410.282652] Testing event system filelock: OK [ 410.877900] Testing event system fs_dax: OK [ 411.489828] Testing event system writeback: OK [ 412.261718] Testing event system page_ref: OK [ 414.253371] Testing event system cma: OK [ 414.785999] Testing event system page_isolation: OK [ 415.086392] Testing event system huge_memory: OK [ 415.698455] Testing event system migrate: OK [ 416.178178] Testing event system compaction: OK [ 416.562587] Testing event system kmem: OK [ 417.106013] Testing event system percpu: OK [ 417.610314] Testing event system vmscan: OK [ 418.170090] Testing event system pagemap: OK [ 418.574537] Testing event system oom: OK [ 419.086157] Testing event system filemap: OK [ 419.670486] Testing event system rseq: OK [ 420.109976] Testing event system xdp: OK [ 420.602122] Testing event system benchmark: OK [ 421.286279] Testing event system rpm: OK [ 421.714248] Testing event system power: OK [ 422.441810] Testing event system test: OK [ 423.037989] Testing event system preemptirq: OK [ 424.001672] Testing event system cgroup: OK [ 424.534262] Testing event system module: OK [ 425.098202] Testing event system alarmtimer: OK [ 425.550533] Testing event system timer: OK [ 426.242070] Testing event system swiotlb: OK [ 426.818389] Testing event system rcu: OK [ 427.667578] Testing event system printk: OK [ 428.090451] Testing event system lock: OK [ 428.686238] Testing event system sched: OK [ 429.389878] Testing event system workqueue: OK [ 430.102720] Testing event system signal: OK [ 430.538481] Testing event system irq: OK [ 430.962094] Testing event system cpuhp: OK [ 431.477915] Testing event system task: OK [ 431.982136] Testing event system kvm: OK [ 432.697798] Testing event system emulation: OK [ 433.334265] Testing event system ipi: OK [ 433.754152] Testing event system raw_syscalls: OK [ 434.358671] Testing event system initcall: OK [ 434.858513] Running tests on all trace events: [ 434.870621] Testing all events: OK [ 487.923867] Testing ftrace filter: OK [ 488.038186] trace_kprobe: Testing kprobe tracing: OK [ 488.469210] Loading compiled-in X.509 certificates [ 488.576336] _warn_unseeded_randomness: 7 callbacks suppressed [ 488.576576] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 488.578633] Loaded X.509 cert 'Build time autogenerated kernel key: 4054958e39d9099bc90ea5e944013505afd1c71c' [ 488.582214] page_owner is disabled [ 488.700195] Key type big_key registered [ 488.753009] Key type encrypted registered [ 488.755516] ima: No TPM chip found, activating TPM-bypass! [ 488.756247] ima: Allocated hash algorithm: sha1 [ 488.759723] No architecture policies found [ 488.765166] random: get_random_bytes called from prandom_seed_full_state+0xc0/0x198 with crng_init=0 [ 488.790480] hctosys: unable to open rtc device (rtc0) [ 488.804943] psci_checker: PSCI checker started using 1 CPUs [ 488.805412] psci_checker: Starting hotplug tests [ 488.806117] psci_checker: Trying to turn off and on again all CPUs [ 488.807333] psci_checker: Trying to turn off and on again group 0 (CPUs 0) [ 488.808085] psci_checker: Hotplug tests passed OK [ 488.808566] psci_checker: Starting suspend tests (10 cycles per state) [ 488.809264] psci_checker: cpuidle not available on CPU 0, ignoring [ 488.809992] psci_checker: Could not start suspend tests on any CPU [ 488.810444] psci_checker: PSCI checker completed [ 488.853803] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 489.045732] ### dt-test ### start of unittest - you will see error messages [ 489.071999] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 489.072835] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 489.073624] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 489.074275] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 489.075436] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 489.076153] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 489.092910] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 489.094546] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 489.096567] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 489.504170] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status [ 489.552764] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status [ 489.574538] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status [ 489.621028] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status [ 489.694189] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status [ 489.759137] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status [ 489.801249] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status [ 489.876480] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status [ 489.918241] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo [ 489.920334] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 489.921095] OF: overlay: overlay #6 is not topmost [ 490.184967] OF: overlay: remove: Could not find overlay #7 [ 490.185501] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #7 [ 490.409374] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/status [ 490.414340] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/status [ 490.416755] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@30/incline-up [ 490.417972] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@40/incline-up [ 490.429909] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/status [ 490.431367] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/color [ 490.432431] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/rate [ 490.434051] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/hvac_2 [ 490.435682] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200 [ 490.436982] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_left [ 490.438160] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_right [ 490.537150] OF: overlay: ERROR: multiple fragments add and/or delete node /testcase-data-2/substation@100/motor-1/controller [ 490.540815] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/controller/name [ 490.551974] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 490.553175] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 490.554373] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/rpm_avail [ 490.571606] ### dt-test ### end of unittest - 217 passed, 0 failed [ 490.591578] uart-pl011 9000000.pl011: no DMA platform data [ 490.934115] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 490.935482] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 490.977043] VFS: Mounted root (9p filesystem) readonly on device 0:18. [ 490.997831] devtmpfs: mounted [ 491.692641] Freeing unused kernel memory: 4096K [ 491.713856] Checked W+X mappings: passed, no W+X pages found [ 491.714661] rodata_test: all tests were successful [ 491.716107] Run /sbin/init as init process [ 491.833556] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=0 [ 492.790186] random: fast init done [ 495.996622] systemd[1]: System time before build time, advancing clock. [ 496.497950] systemd[1]: Failed to insert module 'autofs4': No such file or directory [ 499.754142] random: systemd: uninitialized urandom read (16 bytes read) [ 500.110625] random: systemd: uninitialized urandom read (16 bytes read) [ 500.116828] random: systemd: uninitialized urandom read (16 bytes read) [ 505.360063] 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) [ 505.437009] systemd[1]: Detected virtualization qemu. [ 505.443367] systemd[1]: Detected architecture arm64. Welcome to Debian GNU/Linux 9 (stretch)! [ 505.594079] systemd[1]: Set hostname to . [ 505.780426] _warn_unseeded_randomness: 5 callbacks suppressed [ 505.780634] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=1 [ 505.840513] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 506.461530] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 506.988199] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 506.989045] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 506.989700] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 508.109717] _warn_unseeded_randomness: 20 callbacks suppressed [ 508.109914] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 508.160053] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 508.160955] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 509.308856] random: systemd: uninitialized urandom read (16 bytes read) [ 509.437175] _warn_unseeded_randomness: 11 callbacks suppressed [ 509.437371] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 509.452226] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 509.453102] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 511.068446] _warn_unseeded_randomness: 11 callbacks suppressed [ 511.068642] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 511.069701] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 511.071530] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 513.543362] systemd-hiberna (1256) used greatest stack depth: 11040 bytes left [ 514.044056] systemd-rc-loca (1257) used greatest stack depth: 10576 bytes left [ 515.168256] random: systemd-cryptse: uninitialized urandom read (16 bytes read) [ 515.776002] systemd-fstab-g (1258) used greatest stack depth: 10000 bytes left [ 515.902624] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 516.965924] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 518.696978] random: systemd: uninitialized urandom read (16 bytes read) [ 518.940573] random: systemd: uninitialized urandom read (16 bytes read) [ 518.944834] random: systemd: uninitialized urandom read (16 bytes read) [ 526.789212] systemd[1]: networking.service: Cannot add dependency job, ignoring: Unit networking.service is masked. [ 526.858633] _warn_unseeded_randomness: 5 callbacks suppressed [ 526.860115] random: get_random_bytes called from generate_random_uuid+0x20/0x50 with crng_init=1 [ 526.925860] systemd[1]: Listening on Syslog Socket. [ OK ] Listening on Syslog Socket. [ 526.964543] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 527.006346] systemd[1]: Listening on Journal Audit Socket. [ OK ] Listening on Journal Audit Socket. [ 527.051512] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ 527.083504] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 527.173357] systemd[1]: Created slice System Slice. [ OK ] Created slice System Slice. [ 527.373898] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 527.475456] systemd[1]: Mounting Debug File System... Mounting Debug File System... [ 528.074500] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 528.164218] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 528.165081] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 528.165779] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 529.020081] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ OK ] Listening on /dev/initctl Compatibility Named Pipe. [ 529.189265] _warn_unseeded_randomness: 10 callbacks suppressed [ 529.189453] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 529.196636] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 529.206446] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on Journal Socket (/dev/log). [UNSUPP] Starting of Arbitrary Executable Fi…tem Automount Point not supported. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. [ 530.204066] _warn_unseeded_randomness: 1 callbacks suppressed [ 530.204265] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 530.205510] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 530.254142] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 Starting Load Kernel Modules... [ 531.933658] _warn_unseeded_randomness: 10 callbacks suppressed [ 531.933878] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 532.049550] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 532.050381] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 533.277342] _warn_unseeded_randomness: 3 callbacks suppressed [ 533.277538] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 533.394350] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 533.395627] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 534.153833] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 534.317491] systemd[1]: Reached target Sockets. [ OK ] Reached target Sockets. [ 534.609237] _warn_unseeded_randomness: 5 callbacks suppressed [ 534.609435] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 534.757324] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 534.758154] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 535.774425] _warn_unseeded_randomness: 3 callbacks suppressed [ 535.774633] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 535.820404] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 535.821203] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 536.343719] systemd[1]: Starting Journal Service... Starting Journal Service... [ 537.007980] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ 537.104213] systemd[1]: Reached target Encrypted Volumes. [ OK ] Reached target Encrypted Volumes. [ 537.941892] _warn_unseeded_randomness: 3 callbacks suppressed [ 537.942089] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 538.583690] systemd[1]: Starting Create Static Device Nodes in /dev... Starting Create Static Device Nodes in /dev... [ 538.769033] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 538.769870] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 538.841500] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 538.961724] systemd[1]: Reached target Paths. [ 538.966430] _warn_unseeded_randomness: 2 callbacks suppressed [ 538.966619] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 538.994286] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ OK ] Reached target Paths. [ 539.060658] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 539.466006] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ 539.558429] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 540.538155] _warn_unseeded_randomness: 9 callbacks suppressed [ 540.538351] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 540.540147] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 540.540976] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 542.501184] systemd[1]: Mounted Debug File System. [ OK ] Mounted Debug File System. [ 542.578357] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 542.681304] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 544.230215] systemd[1]: Started Load Kernel Modules. [ OK ] Started Load Kernel Modules. [ 545.581809] _warn_unseeded_randomness: 8 callbacks suppressed [ 545.582069] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 546.105823] systemd[1]: Mounting Configuration File System... Mounting Configuration File System... [ 546.403609] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 546.404481] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 547.544795] _warn_unseeded_randomness: 3 callbacks suppressed [ 547.544994] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 548.495876] systemd[1]: Starting Apply Kernel Variables... [ 548.583273] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 548.584120] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 548.584793] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 Starting Apply Kernel Variables... [ 550.210002] _warn_unseeded_randomness: 4 callbacks suppressed [ 550.210199] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 550.249182] systemd-journald[1273]: /dev/kmsg buffer overrun, some messages lost. [ 550.361185] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 550.362012] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 550.489327] systemd-journald[1273]: /dev/kmsg buffer overrun, some messages lost. [ 551.304403] systemd[1]: Started Remount Root and Kernel File Systems. [ OK ] Started Remount Root and Kernel File Systems. [ 552.693247] systemd[1]: Started Create Static Device Nodes in /dev. [ OK ] Started Create Static Device Nodes in /dev. [ 553.589575] _warn_unseeded_randomness: 6 callbacks suppressed [ 553.589797] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 554.494313] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 554.712831] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 554.713668] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 554.714342] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 555.076261] systemd[1]: Starting udev Kernel Device Manager... Starting udev Kernel Device Manager... [ 556.212755] _warn_unseeded_randomness: 10 callbacks suppressed [ 556.212953] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 556.968163] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 557.153060] systemd[1]: Reached target Local File Systems (Pre). [ OK ] Reached target Local File Systems (Pre). [ 557.286547] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 557.287814] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 557.288506] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 557.349459] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 559.115576] _warn_unseeded_randomness: 4 callbacks suppressed [ 559.115775] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 559.117021] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 559.278227] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 559.935688] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 561.851482] _warn_unseeded_randomness: 4 callbacks suppressed [ 561.851676] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 561.852723] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 562.099712] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 562.142160] systemd[1]: Mounted Configuration File System. [ OK ] Mounted Configuration File System. [ 563.005379] systemd[1]: Started Apply Kernel Variables. [ OK ] Started Apply Kernel Variables. [ 564.392359] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 564.613165] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 564.614005] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 565.071484] systemd[1]: Started Load/Save Random Seed. [ OK ] Started Load/Save Random Seed. [ 566.904349] _warn_unseeded_randomness: 1 callbacks suppressed [ 566.904609] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [* ] (1 of 4) A start job is running for…l Device Manager (43s / 30min 27s) [** ] (1 of 4) A start job is running for…l Device Manager (43s / 30min 27s) [*** ] (1 of 4) A start job is running for…l Device Manager (44s / 30min 27s) [ *** ] (2 of 4) A start job is running for dev-ttyAMA0.device (44s / 30min) [ *** ] (2 of 4) A start job is running for dev-ttyAMA0.device (45s / 30min)[ 572.678066] systemd[1]: Started udev Kernel Device Manager. [ OK ] Started udev Kernel Device Manager. [ ***] (2 of 3) A start job is running for Journal Service (51s / 30min 8s) [ **] (3 of 3) A start job is running for…dplug all Devices (51s / no limit) [ *] (3 of 3) A start job is running for…dplug all Devices (52s / no limit) [ **] (3 of 3) A start job is running for…dplug all Devices (52s / no limit) [ ***] (1 of 3) A start job is running for dev-ttyAMA0.device (53s / 30min) [ *** ] (1 of 3) A start job is running for dev-ttyAMA0.device (53s / 30min) [ *** ] (1 of 3) A start job is running for dev-ttyAMA0.device (54s / 30min) [*** ] (2 of 3) A start job is running for Journal Service (54s / 30min 8s) [** ] (2 of 3) A start job is running for Journal Service (55s / 30min 8s) [* ] (2 of 3) A start job is running for Journal Service (55s / 30min 8s) [** ] (3 of 3) A start job is running for…dplug all Devices (56s / no limit) [*** ] (3 of 3) A start job is running for…dplug all Devices (56s / no limit) [ *** ] (3 of 3) A start job is running for…dplug all Devices (57s / no limit) [ *** ] (1 of 3) A start job is running for dev-ttyAMA0.device (57s / 30min) [ ***] (1 of 3) A start job is running for dev-ttyAMA0.device (58s / 30min) [ **] (1 of 3) A start job is running for dev-ttyAMA0.device (58s / 30min) [ *] (2 of 3) A start job is running for Journal Service (59s / 30min 8s)[ 585.994170] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [ 586.733503] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 Starting Flush Journal to Persistent Storage... [ 588.941871] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 588.943167] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 588.943847] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ **] (2 of 3) A start job is running for…v-ttyAMA0.device (1min 4s / 30min) [ ***] (2 of 3) A start job is running for…v-ttyAMA0.device (1min 4s / 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 5s / 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…stent Storage (1min 7s / 2min 29s) [* ] (1 of 3) A start job is running for…stent Storage (1min 7s / 2min 29s) [** ] (1 of 3) A start job is running for…stent Storage (1min 8s / 2min 29s) [*** ] (2 of 3) A start job is running for…v-ttyAMA0.device (1min 8s / 30min) [ *** ] (2 of 3) A start job is running for…v-ttyAMA0.device (1min 9s / 30min)[ 596.795849] _warn_unseeded_randomness: 5 callbacks suppressed [ 596.796040] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 596.797185] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 596.797841] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 11s / 30min) [ ***] (3 of 3) A start job is running for… all Devices (1min 11s / no limit)[ 598.885402] _warn_unseeded_randomness: 4 callbacks suppressed [ 598.885597] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 598.886683] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ **] (3 of 3) A start job is running for… all Devices (1min 12s / no limit)[ 599.109466] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ *] (3 of 3) A start job is running for… all Devices (1min 12s / no limit) [ **] (1 of 3) A start job is running for…tent Storage (1min 13s / 2min 29s) [ ***] (1 of 3) A start job is running for…tent Storage (1min 13s / 2min 29s)[ 600.887670] _warn_unseeded_randomness: 1 callbacks suppressed [ 600.887873] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [ *** ] (1 of 3) A start job is running for…tent Storage (1min 14s / 2min 29s) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 14s / 30min) [*** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 15s / 30min) [** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 15s / 30min) [* ] (3 of 3) A start job is running for… all Devices (1min 16s / no limit) [** ] (3 of 3) A start job is running for… all Devices (1min 16s / no limit) [*** ] (3 of 3) A start job is running for… all Devices (1min 17s / no limit) [ *** ] (1 of 3) A start job is running for…tent Storage (1min 17s / 2min 29s) [ *** ] (1 of 3) A start job is running for…tent Storage (1min 18s / 2min 29s) [ ***] (1 of 3) A start job is running for…tent Storage (1min 18s / 2min 29s) [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 19s / 30min) [ *] (2 of 3) A start job is running for…-ttyAMA0.device (1min 19s / 30min)[ 606.611762] random: crng init done [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 20s / 30min)[ 607.160761] systemd-journald[1273]: Received request to flush runtime journal from PID 1 [ ***] (3 of 3) A start job is running for… all Devices (1min 20s / no limit) [ OK ] Started Flush Journal to Persistent Storage. Starting Create Volatile Files and Directories... [ *** ] (3 of 3) A start job is running for… all Devices (1min 26s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 27s / no limit) [*** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 27s / 30min) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 28s / 30min) [* ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 28s / 30min) [** ] (2 of 3) A start job is running for… Directories (1min 29s / no limit) [*** ] (2 of 3) A start job is running for… Directories (1min 29s / no limit) [ *** ] (2 of 3) A start job is running for… Directories (1min 30s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 31s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (1min 31s / no limit) [ **] (3 of 3) A start job is running for… all Devices (1min 32s / no limit) [ *] (1 of 3) A start job is running for…-ttyAMA0.device (1min 32s / 30min) [ **] (1 of 3) A start job is running for…-ttyAMA0.device (1min 33s / 30min) [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (1min 33s / 30min) [ *** ] (2 of 3) A start job is running for… Directories (1min 34s / no limit) [ *** ] (2 of 3) A start job is running for… Directories (1min 34s / no limit) [*** ] (2 of 3) A start job is running for… Directories (1min 35s / no limit) [** ] (3 of 3) A start job is running for… all Devices (1min 35s / no limit) [* ] (3 of 3) A start job is running for… all Devices (1min 36s / no limit) [** ] (3 of 3) A start job is running for… all Devices (1min 36s / no limit) [*** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 37s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 37s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 38s / 30min) [ ***] (2 of 3) A start job is running for… Directories (1min 38s / no limit) [ **] (2 of 3) A start job is running for… Directories (1min 39s / no limit) [ *] (2 of 3) A start job is running for… Directories (1min 39s / no limit) [ **] (3 of 3) A start job is running for… all Devices (1min 40s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (1min 40s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 41s / no limit) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 41s / 30min) [*** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 42s / 30min) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 42s / 30min) [* ] (2 of 3) A start job is running for… Directories (1min 42s / no limit) [ OK ] Started Create Volatile Files and Directories. Starting Network Time Synchronization... Starting Update UTMP about System Boot/Shutdown... [** ] (1 of 4) A start job is running for…-ttyAMA0.device (1min 49s / 30min) [*** ] (1 of 4) A start job is running for…-ttyAMA0.device (1min 50s / 30min) [ *** ] (2 of 4) A start job is running for…hronization (1min 51s / 31min 45s) [ *** ] (2 of 4) A start job is running for…hronization (1min 51s / 31min 45s) [ ***] (2 of 4) A start job is running for…hronization (1min 52s / 31min 45s) [ **] (3 of 4) A start job is running for… all Devices (1min 52s / no limit) [ *] (3 of 4) A start job is running for… all Devices (1min 53s / no limit) [ **] (3 of 4) A start job is running for… all Devices (1min 53s / no limit) [ ***] (4 of 4) A start job is running for…oot/Shutdown (1min 54s / no limit) [ *** ] (4 of 4) A start job is running for…oot/Shutdown (1min 55s / no limit) [ *** ] (4 of 4) A start job is running for…oot/Shutdown (1min 55s / no limit) [ OK ] Started Update UTMP about System Boot/Shutdown. [*** ] (3 of 3) A start job is running for…g all Devices (2min 1s / no limit) [** ] (3 of 3) A start job is running for…g all Devices (2min 2s / no limit) [* ] (3 of 3) A start job is running for…g all Devices (2min 2s / no limit) [** ] (1 of 3) A start job is running for…v-ttyAMA0.device (2min 3s / 30min) [*** ] (1 of 3) A start job is running for…v-ttyAMA0.device (2min 3s / 30min) [ *** ] (1 of 3) A start job is running for…v-ttyAMA0.device (2min 4s / 30min) [ *** ] (2 of 3) A start job is running for…chronization (2min 4s / 31min 45s) [ ***] (2 of 3) A start job is running for…chronization (2min 5s / 31min 45s) [ **] (2 of 3) A start job is running for…chronization (2min 5s / 31min 45s) [ *] (3 of 3) A start job is running for…g all Devices (2min 6s / no limit) [ **] (3 of 3) A start job is running for…g all Devices (2min 6s / no limit) [ OK ] Started Network Time Synchronization. [ OK ] Reached target System Time Synchronized. [ ***] (2 of 2) A start job is running for… all Devices (2min 12s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 12s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 13s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 13s / 30min) [** ] (2 of 2) A start job is running for… all Devices (2min 14s / no limit) [* ] (2 of 2) A start job is running for… all Devices (2min 14s / no limit) [** ] (2 of 2) A start job is running for… all Devices (2min 15s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 15s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 16s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 16s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (2min 17s / no limit) [ **] (2 of 2) A start job is running for… all Devices (2min 18s / no limit) [ *] (2 of 2) A start job is running for… all Devices (2min 18s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (2min 19s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (2min 19s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 20s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (2min 20s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (2min 21s / no limit) [** ] (2 of 2) A start job is running for… all Devices (2min 21s / no limit) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 22s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 22s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 23s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (2min 23s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (2min 24s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (2min 24s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (2min 25s / 30min) [ *] (1 of 2) A start job is running for…-ttyAMA0.device (2min 25s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (2min 26s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (2min 26s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (2min 27s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (2min 27s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 28s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 28s / 30min) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 29s / 30min) [** ] (2 of 2) A start job is running for… all Devices (2min 29s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (2min 30s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (2min 30s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 31s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (2min 31s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (2min 32s / 30min) [ *] (2 of 2) A start job is running for… all Devices (2min 32s / no limit) [ **] (2 of 2) A start job is running for… all Devices (2min 33s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (2min 33s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 34s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 34s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 35s / 30min) [** ] (2 of 2) A start job is running for… all Devices (2min 35s / no limit) [ OK ] Found device /dev/ttyAMA0. [ 685.373395] virtio_net virtio0 enp0s1: renamed from eth0 [* ] A start job is running for udev Col… all Devices (2min 42s / no limit) [** ] A start job is running for udev Col… all Devices (2min 42s / no limit) [*** ] A start job is running for udev Col… all Devices (2min 43s / no limit) [ *** ] A start job is running for udev Col… all Devices (2min 43s / no limit) [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Basic System. Starting getty on tty2-tty6 if dbus and logind are not available... [ OK ] Started Regular background program processing daemon. Starting Permit User Sessions... Starting System Logging Service... [ OK ] Started Daily apt upgrade and clean activities. [ OK ] Reached target Timers. [ OK ] Started Permit User Sessions. [ OK ] Started System Logging Service. [ *** ] A start job is running for getty on…ot available (3min 14s / no limit) [ ***] A start job is running for getty on…ot available (3min 18s / no limit) [ **] A start job is running for getty on…ot available (3min 20s / no limit) [ *] A start job is running for getty on…ot available (3min 24s / no limit) [ **] A start job is running for getty on…ot available (3min 26s / no limit) [ ***] A start job is running for getty on…ot available (3min 29s / no limit) [ *** ] A start job is running for getty on…ot available (3min 31s / no limit) [ OK ] Started Getty on tty6. [ OK ] Started Getty on tty5. [ OK ] Started Getty on tty4. [ OK ] Started Getty on tty3. [ OK ] Started Getty on tty2. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyAMA0. [ OK ] Started getty on tty2-tty6 if dbus and logind are not available. Debian GNU/Linux 9 compilator ttyAMA0 compilator login: [ 817.102311] systemd[1]: systemd-journald.service: Main process exited, code=killed, status=6/ABRT [ 817.661410] systemd[1]: systemd-journald.service: Unit entered failed state. [ 817.699873] systemd[1]: systemd-journald.service: Failed with result 'watchdog'. [ 817.841909] systemd[1]: systemd-journald.service: Service has no hold-off time, scheduling restart. [ 818.679344] systemd[1]: Stopped Flush Journal to Persistent Storage. [ 818.725157] systemd[1]: Stopping Flush Journal to Persistent Storage... [ 818.726653] systemd[1]: Stopped Journal Service. [ 819.664786] systemd[1]: Starting Journal Service... [ 823.741965] systemd-journald[1338]: File /run/log/journal/ee22564d41614722a231f845c5bb3c72/system.journal corrupted or uncleanly shut down, renaming and replacing. [ 867.597041] systemd[1]: Started Journal Service. Debian GNU/Linux 9 compilator ttyAMA0 compilator login: Debian GNU/Linux 9 compilator ttyAMA0 compilator login: Debian GNU/Linux 9 compilator ttyAMA0 compilator login: Debian GNU/Linux 9 compilator ttyAMA0 compilator login: Debian GNU/Linux 9 compilator ttyAMA0 compilator login: Debian GNU/Linux 9 compilator ttyAMA0 compilator login: Debian GNU/Linux 9 compilator ttyAMA0 compilator login: qql