[ 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)) #42 SMP Mon Nov 26 13:15:11 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: 999116K/2097152K available (14024K kernel code, 3358K rwdata, 4656K rodata, 4096K init, 21766K bss, 84412K 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.000293] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.014242] Console: colour dummy device 80x25 [ 0.015959] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.016571] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.017130] ... MAX_LOCK_DEPTH: 48 [ 0.017648] ... MAX_LOCKDEP_KEYS: 8191 [ 0.018378] ... CLASSHASH_SIZE: 4096 [ 0.018913] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.019509] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.020052] ... CHAINHASH_SIZE: 32768 [ 0.020609] memory used by lock dependency info: 7775 kB [ 0.021139] per task-struct memory footprint: 2688 bytes [ 0.021727] ------------------------ [ 0.022822] | Locking API testsuite: [ 0.023344] ---------------------------------------------------------------------------- [ 0.023946] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.024572] -------------------------------------------------------------------------- [ 0.025812] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.038090] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.049970] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.062918] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.076022] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.090117] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.104062] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.117835] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.128330] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.138973] -------------------------------------------------------------------------- [ 0.139628] recursive read-lock: | ok | | ok | [ 0.144723] recursive read-lock #2: | ok | | ok | [ 0.148386] mixed read-write-lock: | ok | | ok | [ 0.152534] mixed write-read-lock: | ok | | ok | [ 0.156660] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.160565] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.164926] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.169310] -------------------------------------------------------------------------- [ 0.170857] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.176866] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.182691] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.188578] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.194336] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.200158] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.206037] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.211668] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.216502] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.221305] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.226100] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.231123] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.236290] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.241295] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.246310] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.251965] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.256997] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.261930] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.266907] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.271771] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.276659] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.281675] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.286738] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.291896] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.299103] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.305250] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.312238] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.317915] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.324011] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.329819] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.335160] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.340451] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.345841] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.351158] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.356509] hard-irq lock-inversion/123: ok | ok | ok | [ 0.361840] soft-irq lock-inversion/123: ok | ok | ok | [ 0.367219] hard-irq lock-inversion/132: ok | ok | ok | [ 0.372565] soft-irq lock-inversion/132: ok | ok | ok | [ 0.377951] hard-irq lock-inversion/213: ok | ok | ok | [ 0.383121] soft-irq lock-inversion/213: ok | ok | ok | [ 0.388421] hard-irq lock-inversion/231: ok | ok | ok | [ 0.393793] soft-irq lock-inversion/231: ok | ok | ok | [ 0.399088] hard-irq lock-inversion/312: ok | ok | ok | [ 0.404304] soft-irq lock-inversion/312: ok | ok | ok | [ 0.409630] hard-irq lock-inversion/321: ok | ok | ok | [ 0.414793] soft-irq lock-inversion/321: ok | ok | ok | [ 0.420328] hard-irq read-recursion/123: ok | [ 0.422595] soft-irq read-recursion/123: ok | [ 0.424553] hard-irq read-recursion/132: ok | [ 0.426863] soft-irq read-recursion/132: ok | [ 0.428820] hard-irq read-recursion/213: ok | [ 0.431088] soft-irq read-recursion/213: ok | [ 0.433102] hard-irq read-recursion/231: ok | [ 0.435368] soft-irq read-recursion/231: ok | [ 0.437358] hard-irq read-recursion/312: ok | [ 0.439582] soft-irq read-recursion/312: ok | [ 0.441553] hard-irq read-recursion/321: ok | [ 0.443819] soft-irq read-recursion/321: ok | [ 0.445847] -------------------------------------------------------------------------- [ 0.446552] | Wound/wait tests | [ 0.446863] --------------------- [ 0.447150] ww api failures: ok | ok | ok | [ 0.453713] ww contexts mixing: ok | ok | [ 0.457264] finishing ww context: ok | ok | ok | ok | [ 0.463757] locking mismatches: ok | ok | ok | [ 0.468677] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.486094] spinlock nest unlocked: ok | [ 0.487831] ----------------------------------------------------- [ 0.488161] |block | try |context| [ 0.488506] ----------------------------------------------------- [ 0.488915] context: ok | ok | ok | [ 0.494086] try: ok | ok | ok | [ 0.498973] block: ok | ok | ok | [ 0.503770] spinlock: ok | ok | ok | [ 0.509036] ------------------------------------------------------- [ 0.509393] Good, all 261 testcases passed! | [ 0.510074] --------------------------------- [ 0.514748] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.515397] pid_max: default: 32768 minimum: 301 [ 0.525510] LSM: Security Framework initializing [ 0.526119] Yama: becoming mindful. [ 0.528721] LoadPin: ready to pin (currently enforcing) [ 0.529485] AppArmor: AppArmor disabled by boot time parameter [ 0.554255] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.563405] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.566139] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.567061] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.601139] *** VALIDATE proc *** [ 0.619019] *** VALIDATE cgroup1 *** [ 0.619424] *** VALIDATE cgroup2 *** [ 0.680048] ASID allocator initialised with 32768 entries [ 0.686359] rcu: Hierarchical SRCU implementation. [ 0.748980] EFI services will not be available. [ 0.772222] smp: Bringing up secondary CPUs ... [ 0.772719] smp: Brought up 1 node, 1 CPU [ 0.773086] SMP: Total of 1 processors activated. [ 0.773528] CPU features: detected: 32-bit EL0 Support [ 0.773954] CPU features: detected: CRC32 instructions [ 1.046350] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.047088] CPU: All CPU(s) started at EL1 [ 1.048010] alternatives: patching kernel code [ 1.532903] node 0 initialised, 249310 pages in 4ms [ 1.540205] pgdatinit0 (15) used greatest stack depth: 13408 bytes left [ 1.613579] devtmpfs: initialized [ 1.813131] _warn_unseeded_randomness: 81 callbacks suppressed [ 1.813536] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=0 [ 1.814699] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 1.815449] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 1.827791] Registered cp15_barrier emulation handler [ 2.476424] workqueue: round-robin CPU selection forced, expect performance impact [ 7.833549] DMA-API: preallocated 65536 debug entries [ 7.834370] DMA-API: debugging enabled by kernel config [ 7.834911] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 7.836246] futex hash table entries: 256 (order: 3, 32768 bytes) [ 7.838834] Running postponed tracer tests: [ 7.843739] Testing tracer function: PASSED [ 21.853041] Testing dynamic ftrace: PASSED [ 22.965920] Testing dynamic ftrace ops #1: [ 26.446700] (1 0 1 0 0) [ 26.447194] (1 1 2 0 0) [ 35.216146] (2 1 3 0 736847) [ 35.216968] (2 2 4 0 736958) PASSED [ 42.312993] Testing dynamic ftrace ops #2: [ 54.333573] (1 0 1 735036 0) [ 54.334360] (1 1 2 735166 0) [ 54.342083] (2 1 3 1 244) [ 54.342721] (2 2 4 117 360) PASSED [ 60.916556] Testing ftrace recursion: PASSED [ 61.804097] Testing ftrace recursion safe: PASSED [ 62.693324] Testing ftrace regs(no arch support): PASSED [ 63.583542] Testing tracer nop: PASSED [ 63.584380] Testing tracer irqsoff: PASSED [ 77.634235] Testing tracer function_graph: PASSED [ 92.048822] _warn_unseeded_randomness: 2 callbacks suppressed [ 92.049092] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 92.050167] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 92.051702] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 92.061532] prandom: seed boundary self test passed [ 92.083967] prandom: 100 self tests passed [ 92.088161] pinctrl core: initialized pinctrl subsystem [ 92.156696] regulator-dummy: Failed to create debugfs directory [ 92.163026] [ 92.163333] ************************************************************* [ 92.163691] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 92.164104] ** ** [ 92.164465] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 92.164867] ** ** [ 92.165215] ** This means that this kernel is built to expose internal ** [ 92.165620] ** IOMMU data structures, which may compromise security on ** [ 92.166363] ** your system. ** [ 92.166774] ** ** [ 92.167134] ** If you see this message and you are not debugging the ** [ 92.167498] ** kernel, report this immediately to your vendor! ** [ 92.167883] ** ** [ 92.168325] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 92.168758] ************************************************************* [ 92.185494] DMI not present or invalid. [ 92.216849] NET: Registered protocol family 16 [ 92.237129] audit: initializing netlink subsys (disabled) [ 92.330565] cpuidle: using governor ladder [ 92.331293] cpuidle: using governor menu [ 92.342859] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 92.343587] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 92.347849] audit: type=2000 audit(82.928:1): state=initialized audit_enabled=0 res=1 [ 92.405742] DMA: preallocated 256 KiB pool for atomic allocations [ 92.455287] Serial: AMBA PL011 UART driver [ 93.005749] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 93.008714] printk: console [ttyAMA0] enabled [ 93.008714] printk: console [ttyAMA0] enabled [ 93.009631] printk: bootconsole [pl11] disabled [ 93.009631] printk: bootconsole [pl11] disabled [ 93.484451] _warn_unseeded_randomness: 41 callbacks suppressed [ 93.484628] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 93.486230] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 93.489657] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 93.520887] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 93.653848] arm-v7s io-pgtable: self test ok [ 93.654570] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 93.660562] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 93.663971] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 93.667759] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 93.671885] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 93.675622] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 93.679527] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 93.685444] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 93.692123] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 93.698522] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 93.705456] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 93.711933] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 93.719553] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 93.736329] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 93.753364] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 93.770946] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 93.790417] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 93.809734] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 93.829388] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 93.851369] vgaarb: loaded [ 93.890672] pps_core: LinuxPPS API ver. 1 registered [ 93.891171] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 93.893836] PTP clock support registered [ 94.006279] NetLabel: Initializing [ 94.006812] NetLabel: domain hash size = 128 [ 94.007687] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 94.014734] NetLabel: unlabeled traffic allowed by default [ 94.027974] clocksource: Switched to clocksource arch_sys_counter [ 95.925465] _warn_unseeded_randomness: 37 callbacks suppressed [ 95.925657] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 95.927625] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 95.929134] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 95.938229] VFS: Disk quotas dquot_6.6.0 [ 95.941591] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 95.950820] *** VALIDATE hugetlbfs *** [ 96.546135] NET: Registered protocol family 2 [ 96.577479] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 96.581035] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 96.593673] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 96.602310] TCP: Hash tables configured (established 16384 bind 16384) [ 96.608685] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 96.612573] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 96.622434] NET: Registered protocol family 1 [ 96.686501] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 96.692969] kvm [1]: HYP mode not available [ 96.718508] kworker/u2:1 (34) used greatest stack depth: 11920 bytes left [ 96.885062] Initialise system trusted keyrings [ 96.894787] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 98.242833] _warn_unseeded_randomness: 58 callbacks suppressed [ 98.244044] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 98.244977] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 98.260343] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 98.404025] 9p: Installing v9fs 9p2000 file system support [ 99.030192] Key type asymmetric registered [ 99.032063] Asymmetric key parser 'x509' registered [ 99.035783] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 99.402518] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 99.408127] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 99.426367] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 99.469665] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 99.514613] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 99.517015] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 99.519541] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 99.520663] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 99.524296] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 99.536538] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 99.537486] pci_bus 0000:00: root bus resource [bus 00-0f] [ 99.538275] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 99.539475] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 99.540228] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 99.598636] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 99.600203] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 99.601391] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 99.602346] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 99.603583] pci 0000:00:02.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 99.604327] pci 0000:00:02.0: BAR 0: assigned [io 0x1000-0x103f] [ 99.605018] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 100.003619] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 100.052192] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 103.677058] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 103.956788] SuperH (H)SCI(F) driver initialized [ 104.026440] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 104.084573] libphy: Fixed MDIO Bus: probed [ 104.202047] _warn_unseeded_randomness: 38 callbacks suppressed [ 104.202230] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 104.482414] ledtrig-cpu: registered to indicate activity on CPUs [ 104.508326] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 104.509211] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 104.545777] ashmem: initialized [ 104.693310] 9pnet: Installing 9P2000 support [ 104.942768] registered taskstats version 1 [ 104.943763] Running tests on trace events: [ 104.944359] Testing event initcall_finish: OK [ 104.969366] Testing event initcall_start: OK [ 104.993520] Testing event initcall_level: OK [ 105.018121] Testing event sys_exit: OK [ 105.045066] Testing event sys_enter: OK [ 105.069836] Testing event ipi_exit: OK [ 105.093371] Testing event ipi_entry: OK [ 105.117752] Testing event ipi_raise: OK [ 105.144537] Testing event instruction_emulation: OK [ 105.170116] Testing event kvm_halt_poll_ns: OK [ 105.193603] Testing event kvm_age_page: OK [ 105.217258] Testing event kvm_fpu: OK [ 105.241502] Testing event kvm_mmio: OK [ 105.265630] Testing event kvm_ack_irq: OK [ 105.292372] Testing event kvm_set_irq: OK [ 105.317529] Testing event kvm_vcpu_wakeup: OK [ 105.341508] Testing event kvm_userspace_exit: OK [ 105.365348] Testing event kvm_timer_update_irq: OK [ 105.389306] Testing event kvm_toggle_cache: OK [ 105.413423] Testing event kvm_set_way_flush: OK [ 105.437399] Testing event kvm_test_age_hva: OK [ 105.461601] Testing event kvm_age_hva: OK [ 105.485634] Testing event kvm_set_spte_hva: OK [ 105.512214] Testing event kvm_unmap_hva_range: OK [ 105.537511] Testing event kvm_mmio_emulate: OK [ 105.560919] Testing event kvm_irq_line: OK [ 105.585517] Testing event kvm_access_fault: OK [ 105.608824] Testing event kvm_guest_fault: OK [ 105.632916] Testing event kvm_exit: OK [ 105.657109] Testing event kvm_entry: OK [ 105.681826] Testing event kvm_set_guest_debug: OK [ 105.708627] Testing event kvm_handle_sys_reg: OK [ 105.733795] Testing event trap_reg: OK [ 105.761362] Testing event kvm_arm_set_regset: OK [ 105.786188] Testing event kvm_arm_set_dreg32: OK [ 105.812753] Testing event kvm_arm_clear_debug: OK [ 105.837349] Testing event kvm_arm_setup_debug: OK [ 105.861620] Testing event kvm_hvc_arm64: OK [ 105.888238] Testing event kvm_wfx_arm64: OK [ 105.913841] Testing event vgic_update_irq_pending: OK [ 105.937558] Testing event task_rename: OK [ 105.964407] Testing event task_newtask: OK [ 105.989788] Testing event cpuhp_exit: OK [ 106.017213] Testing event cpuhp_multi_enter: OK [ 106.044292] Testing event cpuhp_enter: OK [ 106.070122] Testing event softirq_raise: OK [ 106.098135] Testing event softirq_exit: OK [ 106.124421] Testing event softirq_entry: OK [ 106.150462] Testing event irq_handler_exit: OK [ 106.177710] Testing event irq_handler_entry: OK [ 106.201612] Testing event signal_deliver: OK [ 106.228267] Testing event signal_generate: OK [ 106.253821] Testing event workqueue_execute_end: OK [ 106.279882] Testing event workqueue_execute_start: OK [ 106.304654] Testing event workqueue_activate_work: OK [ 106.329845] Testing event workqueue_queue_work: OK [ 106.356353] Testing event sched_wake_idle_without_ipi: OK [ 106.382142] Testing event sched_swap_numa: OK [ 106.408098] Testing event sched_stick_numa: OK [ 106.433934] Testing event sched_move_numa: OK [ 106.461061] Testing event sched_process_hang: OK [ 106.485758] Testing event sched_pi_setprio: OK [ 106.509447] Testing event sched_stat_runtime: [ 106.511912] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 106.536066] OK [ 106.536555] Testing event sched_stat_blocked: OK [ 106.562326] Testing event sched_stat_iowait: OK [ 106.588264] Testing event sched_stat_sleep: OK [ 106.613464] Testing event sched_stat_wait: OK [ 106.637878] Testing event sched_process_exec: OK [ 106.664020] Testing event sched_process_fork: OK [ 106.688911] Testing event sched_process_wait: OK [ 106.713898] Testing event sched_wait_task: OK [ 106.740381] Testing event sched_process_exit: OK [ 106.765588] Testing event sched_process_free: OK [ 106.789520] Testing event sched_migrate_task: OK [ 106.814153] Testing event sched_switch: OK [ 106.840855] Testing event sched_wakeup_new: OK [ 106.865561] Testing event sched_wakeup: OK [ 106.889444] Testing event sched_waking: OK [ 106.916864] Testing event sched_kthread_stop_ret: OK [ 106.941797] Testing event sched_kthread_stop: OK [ 106.965506] Testing event lock_acquired: OK [ 106.993184] Testing event lock_contended: OK [ 107.020257] Testing event lock_release: OK [ 107.046463] Testing event lock_acquire: OK [ 107.076226] Testing event console: OK [ 107.101326] Testing event rcu_barrier: OK [ 107.126265] Testing event rcu_torture_read: OK [ 107.152875] Testing event rcu_batch_end: OK [ 107.178301] Testing event rcu_invoke_kfree_callback: OK [ 107.204115] Testing event rcu_invoke_callback: OK [ 107.229391] Testing event rcu_batch_start: OK [ 107.253560] Testing event rcu_kfree_callback: OK [ 107.277539] Testing event rcu_callback: OK [ 107.304243] Testing event rcu_dyntick: OK [ 107.330235] Testing event rcu_fqs: OK [ 107.356789] Testing event rcu_quiescent_state_report: OK [ 107.381681] Testing event rcu_unlock_preempted_task: OK [ 107.406073] Testing event rcu_preempt_task: OK [ 107.429556] Testing event rcu_exp_funnel_lock: OK [ 107.454106] Testing event rcu_exp_grace_period: OK [ 107.478504] Testing event rcu_grace_period_init: OK [ 107.502860] Testing event rcu_future_grace_period: OK [ 107.528264] Testing event rcu_grace_period: OK [ 107.556308] Testing event rcu_utilization: OK [ 107.584560] Testing event swiotlb_bounced: OK [ 107.610313] Testing event tick_stop: OK [ 107.637632] Testing event itimer_expire: OK [ 107.661934] Testing event itimer_state: OK [ 107.685820] Testing event hrtimer_cancel: OK [ 107.710237] Testing event hrtimer_expire_exit: OK [ 107.733395] Testing event hrtimer_expire_entry: OK [ 107.757584] Testing event hrtimer_start: OK [ 107.781750] Testing event hrtimer_init: OK [ 107.808027] Testing event timer_cancel: OK [ 107.833769] Testing event timer_expire_exit: OK [ 107.861038] Testing event timer_expire_entry: OK [ 107.888327] Testing event timer_start: OK [ 107.914309] Testing event timer_init: OK [ 107.941372] Testing event alarmtimer_cancel: OK [ 107.965806] Testing event alarmtimer_start: OK [ 107.989981] Testing event alarmtimer_fired: OK [ 108.013771] Testing event alarmtimer_suspend: OK [ 108.037820] Testing event module_request: OK [ 108.061760] Testing event module_put: OK [ 108.085629] Testing event module_get: OK [ 108.109568] Testing event module_free: OK [ 108.133278] Testing event module_load: OK [ 108.157495] Testing event cgroup_transfer_tasks: OK [ 108.181745] Testing event cgroup_attach_task: OK [ 108.208401] Testing event cgroup_rename: OK [ 108.233676] Testing event cgroup_release: OK [ 108.260238] Testing event cgroup_rmdir: OK [ 108.288250] Testing event cgroup_mkdir: OK [ 108.313279] Testing event cgroup_remount: OK [ 108.337698] Testing event cgroup_destroy_root: OK [ 108.364032] Testing event cgroup_setup_root: OK [ 108.388545] Testing event irq_enable: OK [ 108.416255] Testing event irq_disable: OK [ 108.444323] Testing event ftrace_test_filter: OK [ 108.465310] Testing event dev_pm_qos_remove_request: OK [ 108.489445] Testing event dev_pm_qos_update_request: OK [ 108.513534] Testing event dev_pm_qos_add_request: OK [ 108.537878] Testing event pm_qos_update_flags: OK [ 108.564396] Testing event pm_qos_update_target: OK [ 108.590168] Testing event pm_qos_update_request_timeout: OK [ 108.616871] Testing event pm_qos_remove_request: OK [ 108.644231] Testing event pm_qos_update_request: OK [ 108.669778] Testing event pm_qos_add_request: OK [ 108.696193] Testing event power_domain_target: OK [ 108.721750] Testing event clock_set_rate: OK [ 108.748326] Testing event clock_disable: OK [ 108.774123] Testing event clock_enable: OK [ 108.800263] Testing event wakeup_source_deactivate: OK [ 108.825567] Testing event wakeup_source_activate: OK [ 108.851954] Testing event suspend_resume: OK [ 108.889105] Testing event device_pm_callback_end: OK [ 108.916369] Testing event device_pm_callback_start: OK [ 108.941978] Testing event cpu_frequency_limits: OK [ 108.965540] Testing event cpu_frequency: OK [ 108.992590] Testing event pstate_sample: OK [ 109.018493] Testing event powernv_throttle: OK [ 109.044046] Testing event cpu_idle: OK [ 109.070048] Testing event rpm_return_int: OK [ 109.096116] Testing event rpm_idle: OK [ 109.121516] Testing event rpm_resume: OK [ 109.145693] Testing event rpm_suspend: OK [ 109.169383] Testing event benchmark_event: OK [ 109.293965] Testing event xdp_devmap_xmit: OK [ 109.319724] Testing event xdp_cpumap_enqueue: OK [ 109.344629] Testing event xdp_cpumap_kthread: OK [ 109.369093] Testing event xdp_redirect_map_err: OK [ 109.394028] Testing event xdp_redirect_map: OK [ 109.420480] Testing event xdp_redirect_err: OK [ 109.445912] Testing event xdp_redirect: OK [ 109.469476] Testing event xdp_exception: OK [ 109.493965] Testing event rseq_ip_fixup: OK [ 109.517397] Testing event rseq_update: OK [ 109.541455] Testing event file_check_and_advance_wb_err: OK [ 109.565786] Testing event filemap_set_wb_err: OK [ 109.592371] Testing event mm_filemap_add_to_page_cache: OK [ 109.617549] Testing event mm_filemap_delete_from_page_cache: OK [ 109.641492] Testing event compact_retry: OK [ 109.665390] Testing event skip_task_reaping: OK [ 109.689658] Testing event finish_task_reaping: OK [ 109.716187] Testing event start_task_reaping: OK [ 109.741570] Testing event wake_reaper: OK [ 109.768067] Testing event mark_victim: OK [ 109.793896] Testing event reclaim_retry_zone: OK [ 109.820325] Testing event oom_score_adj_update: OK [ 109.845293] Testing event mm_lru_activate: OK [ 109.869456] Testing event mm_lru_insertion: OK [ 109.896191] Testing event mm_vmscan_inactive_list_is_low: OK [ 109.921713] Testing event mm_vmscan_lru_shrink_active: OK [ 109.948074] Testing event mm_vmscan_lru_shrink_inactive: OK [ 109.973693] Testing event mm_vmscan_writepage: OK [ 110.000595] Testing event mm_vmscan_lru_isolate: OK [ 110.026032] Testing event mm_shrink_slab_end: OK [ 110.049305] Testing event mm_shrink_slab_start: OK [ 110.073384] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 110.097469] Testing event mm_vmscan_memcg_reclaim_end: OK [ 110.121530] Testing event mm_vmscan_direct_reclaim_end: OK [ 110.145618] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 110.169767] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 110.196599] Testing event mm_vmscan_direct_reclaim_begin: OK [ 110.221795] Testing event mm_vmscan_wakeup_kswapd: OK [ 110.245425] Testing event mm_vmscan_kswapd_wake: OK [ 110.270075] Testing event mm_vmscan_kswapd_sleep: OK [ 110.296364] Testing event percpu_destroy_chunk: OK [ 110.321534] Testing event percpu_create_chunk: OK [ 110.348018] Testing event percpu_alloc_percpu_fail: OK [ 110.373261] Testing event percpu_free_percpu: OK [ 110.397839] Testing event percpu_alloc_percpu: OK [ 110.421354] Testing event mm_page_alloc_extfrag: OK [ 110.445593] Testing event mm_page_pcpu_drain: OK [ 110.469595] Testing event mm_page_alloc_zone_locked: OK [ 110.496872] Testing event mm_page_alloc: OK [ 110.521616] Testing event mm_page_free_batched: OK [ 110.545339] Testing event mm_page_free: OK [ 110.569828] Testing event kmem_cache_free: OK [ 110.593618] Testing event kfree: OK [ 110.620545] Testing event kmem_cache_alloc_node: OK [ 110.645734] Testing event kmalloc_node: OK [ 110.673241] Testing event kmem_cache_alloc: OK [ 110.700121] Testing event kmalloc: OK [ 110.725287] Testing event mm_compaction_kcompactd_wake: OK [ 110.750060] Testing event mm_compaction_wakeup_kcompactd: OK [ 110.776364] Testing event mm_compaction_kcompactd_sleep: OK [ 110.801956] Testing event mm_compaction_defer_reset: OK [ 110.825341] Testing event mm_compaction_defer_compaction: OK [ 110.849300] Testing event mm_compaction_deferred: OK [ 110.873372] Testing event mm_compaction_suitable: OK [ 110.897436] Testing event mm_compaction_finished: OK [ 110.921491] Testing event mm_compaction_try_to_compact_pages: OK [ 110.945664] Testing event mm_compaction_end: OK [ 110.969823] Testing event mm_compaction_begin: OK [ 110.996428] Testing event mm_compaction_migratepages: OK [ 111.021683] Testing event mm_compaction_isolate_freepages: OK [ 111.048101] Testing event mm_compaction_isolate_migratepages: OK [ 111.073806] Testing event mm_migrate_pages: OK [ 111.097701] Testing event mm_collapse_huge_page_swapin: OK [ 111.122190] Testing event mm_collapse_huge_page_isolate: OK [ 111.145813] Testing event mm_collapse_huge_page: OK [ 111.169630] Testing event mm_khugepaged_scan_pmd: OK [ 111.193424] Testing event test_pages_isolated: OK [ 111.217368] Testing event cma_release: OK [ 111.241412] Testing event cma_alloc: OK [ 111.265477] Testing event page_ref_unfreeze: OK [ 111.296714] Testing event page_ref_freeze: OK [ 111.322564] Testing event page_ref_mod_unless: OK [ 111.353651] Testing event page_ref_mod_and_return: OK [ 111.377906] Testing event page_ref_mod_and_test: OK [ 111.503923] Testing event page_ref_mod: OK [ 111.557786] Testing event page_ref_set: OK [ 111.589597] Testing event sb_clear_inode_writeback: OK [ 111.613561] Testing event sb_mark_inode_writeback: OK [ 111.640572] Testing event writeback_dirty_inode_enqueue: OK [ 111.665794] Testing event writeback_lazytime_iput: OK [ 111.689511] Testing event writeback_lazytime: OK [ 111.714235] Testing event writeback_single_inode: OK [ 111.740859] Testing event writeback_single_inode_start: OK [ 111.765709] Testing event writeback_wait_iff_congested: OK [ 111.789468] Testing event writeback_congestion_wait: OK [ 111.814267] Testing event writeback_sb_inodes_requeue: OK [ 111.840742] Testing event balance_dirty_pages: OK [ 111.866108] Testing event bdi_dirty_ratelimit: OK [ 111.889290] Testing event global_dirty_state: OK [ 111.913394] Testing event writeback_queue_io: OK [ 111.937885] Testing event wbc_writepage: OK [ 111.964504] Testing event writeback_bdi_register: OK [ 111.990173] Testing event writeback_wake_background: OK [ 112.016178] Testing event writeback_pages_written: OK [ 112.042564] Testing event writeback_wait: OK [ 112.065386] Testing event writeback_written: OK [ 112.089872] Testing event writeback_start: OK [ 112.116612] Testing event writeback_exec: OK [ 112.141790] Testing event writeback_queue: OK [ 112.165330] Testing event writeback_write_inode: OK [ 112.189887] Testing event writeback_write_inode_start: OK [ 112.216571] Testing event writeback_dirty_inode: OK [ 112.241704] Testing event writeback_dirty_inode_start: OK [ 112.268682] Testing event writeback_mark_inode_dirty: OK [ 112.293630] Testing event writeback_dirty_page: OK [ 112.317317] Testing event dax_writeback_one: OK [ 112.341385] Testing event dax_writeback_range_done: OK [ 112.365488] Testing event dax_writeback_range: OK [ 112.389878] Testing event dax_insert_mapping: OK [ 112.413400] Testing event dax_insert_pfn_mkwrite: OK [ 112.440213] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 112.465690] Testing event dax_load_hole: OK [ 112.492198] Testing event dax_pte_fault_done: OK [ 112.518650] Testing event dax_pte_fault: OK [ 112.545340] Testing event dax_pmd_insert_mapping: OK [ 112.569338] Testing event dax_pmd_load_hole_fallback: OK [ 112.596090] Testing event dax_pmd_load_hole: OK [ 112.620451] Testing event dax_pmd_fault_done: OK [ 112.645439] Testing event dax_pmd_fault: OK [ 112.669727] Testing event generic_add_lease: OK [ 112.696380] Testing event time_out_leases: OK [ 112.721566] Testing event generic_delete_lease: OK [ 112.745700] Testing event break_lease_unblock: OK [ 112.772217] Testing event break_lease_block: OK [ 112.797583] Testing event break_lease_noblock: OK [ 112.824903] Testing event flock_lock_inode: OK [ 112.849629] Testing event locks_remove_posix: OK [ 112.873395] Testing event fcntl_setlk: OK [ 112.897315] Testing event posix_lock_inode: OK [ 112.921852] Testing event locks_get_lock_context: OK [ 112.948513] Testing event block_rq_remap: OK [ 112.973457] Testing event block_bio_remap: OK [ 112.996828] Testing event block_split: OK [ 113.021148] Testing event block_unplug: OK [ 113.048488] Testing event block_plug: OK [ 113.073668] Testing event block_sleeprq: OK [ 113.099976] Testing event block_getrq: OK [ 113.125794] Testing event block_bio_queue: OK [ 113.152428] Testing event block_bio_frontmerge: OK [ 113.177986] Testing event block_bio_backmerge: OK [ 113.201497] Testing event block_bio_complete: OK [ 113.225821] Testing event block_bio_bounce: OK [ 113.252304] Testing event block_rq_issue: OK [ 113.277613] Testing event block_rq_insert: OK [ 113.301682] Testing event block_rq_complete: OK [ 113.328537] Testing event block_rq_requeue: OK [ 113.353967] Testing event block_dirty_buffer: OK [ 113.377283] Testing event block_touch_buffer: OK [ 113.401459] Testing event wbt_timer: OK [ 113.425432] Testing event wbt_step: OK [ 113.449543] Testing event wbt_lat: OK [ 113.476145] Testing event wbt_stat: OK [ 113.501664] Testing event gpio_value: OK [ 113.530206] Testing event gpio_direction: OK [ 113.564565] Testing event clk_set_duty_cycle_complete: OK [ 113.590063] Testing event clk_set_duty_cycle: OK [ 113.613330] Testing event clk_set_phase_complete: OK [ 113.637695] Testing event clk_set_phase: OK [ 113.664564] Testing event clk_set_parent_complete: OK [ 113.689893] Testing event clk_set_parent: OK [ 113.713492] Testing event clk_set_rate_complete: OK [ 113.737545] Testing event clk_set_rate: OK [ 113.761592] Testing event clk_unprepare_complete: OK [ 113.785807] Testing event clk_unprepare: OK [ 113.812700] Testing event clk_prepare_complete: OK [ 113.837763] Testing event clk_prepare: OK [ 113.861867] Testing event clk_disable_complete: OK [ 113.885502] Testing event clk_disable: OK [ 113.912481] Testing event clk_enable_complete: OK [ 113.937428] Testing event clk_enable: OK [ 113.964169] Testing event rpmh_send_msg: OK [ 113.989958] Testing event rpmh_tx_done: OK [ 114.016222] Testing event regulator_set_voltage_complete: OK [ 114.041770] Testing event regulator_set_voltage: OK [ 114.069494] Testing event regulator_disable_complete: OK [ 114.094446] Testing event regulator_disable: OK [ 114.120247] Testing event regulator_enable_complete: OK [ 114.145323] Testing event regulator_enable_delay: OK [ 114.169824] Testing event regulator_enable: OK [ 114.196362] Testing event urandom_read: OK [ 114.221564] Testing event random_read: OK [ 114.245446] Testing event extract_entropy_user: OK [ 114.269601] Testing event extract_entropy: OK [ 114.296112] Testing event get_random_bytes_arch: OK [ 114.320304] Testing event get_random_bytes: OK [ 114.345789] Testing event xfer_secondary_pool: OK [ 114.373045] Testing event add_disk_randomness: OK [ 114.397815] Testing event add_input_randomness: OK [ 114.421843] Testing event debit_entropy: OK [ 114.445452] Testing event push_to_pool: OK [ 114.469417] Testing event credit_entropy_bits: OK [ 114.493297] Testing event mix_pool_bytes_nolock: OK [ 114.517752] Testing event mix_pool_bytes: OK [ 114.544396] Testing event add_device_randomness: OK [ 114.569869] Testing event io_page_fault: OK [ 114.593325] Testing event unmap: OK [ 114.617809] Testing event map: OK [ 114.644460] Testing event detach_device_from_domain: OK [ 114.669667] Testing event attach_device_to_domain: OK [ 114.696809] Testing event remove_device_from_group: OK [ 114.721913] Testing event add_device_to_group: OK [ 114.745678] Testing event regcache_drop_region: OK [ 114.769577] Testing event regmap_async_complete_done: OK [ 114.796543] Testing event regmap_async_complete_start: OK [ 114.822300] Testing event regmap_async_io_complete: OK [ 114.849559] Testing event regmap_async_write_start: OK [ 114.874521] Testing event regmap_cache_bypass: OK [ 114.902115] Testing event regmap_cache_only: OK [ 114.925717] Testing event regcache_sync: OK [ 114.953357] Testing event regmap_hw_write_done: OK [ 114.977820] Testing event regmap_hw_write_start: OK [ 115.002239] Testing event regmap_hw_read_done: OK [ 115.029967] Testing event regmap_hw_read_start: OK [ 115.056494] Testing event regmap_reg_read_cache: OK [ 115.081739] Testing event regmap_reg_read: OK [ 115.108469] Testing event regmap_reg_write: OK [ 115.134291] Testing event dma_fence_wait_end: OK [ 115.157581] Testing event dma_fence_wait_start: OK [ 115.184117] Testing event dma_fence_signaled: OK [ 115.209513] Testing event dma_fence_enable_signal: OK [ 115.236305] Testing event dma_fence_destroy: OK [ 115.261855] Testing event dma_fence_init: OK [ 115.288714] Testing event dma_fence_emit: OK [ 115.313491] Testing event sync_timeline: OK [ 115.340574] Testing event spi_transfer_stop: OK [ 115.366544] Testing event spi_transfer_start: OK [ 115.393513] Testing event spi_message_done: OK [ 115.417851] Testing event spi_message_start: OK [ 115.441558] Testing event spi_message_submit: OK [ 115.466016] Testing event spi_controller_busy: OK [ 115.492719] Testing event spi_controller_idle: OK [ 115.517941] Testing event mdio_access: OK [ 115.545069] Testing event rtc_timer_fired: OK [ 115.569810] Testing event rtc_timer_dequeue: OK [ 115.593779] Testing event rtc_timer_enqueue: OK [ 115.621049] Testing event rtc_read_offset: OK [ 115.648511] Testing event rtc_set_offset: OK [ 115.673675] Testing event rtc_alarm_irq_enable: OK [ 115.701193] Testing event rtc_irq_set_state: OK [ 115.726095] Testing event rtc_irq_set_freq: OK [ 115.749427] Testing event rtc_read_alarm: OK [ 115.773489] Testing event rtc_set_alarm: OK [ 115.797428] Testing event rtc_read_time: OK [ 115.821606] Testing event rtc_set_time: OK [ 115.845598] Testing event memory_failure_event: OK [ 115.872618] Testing event aer_event: OK [ 115.897678] Testing event non_standard_event: OK [ 115.925033] Testing event arm_event: OK [ 115.952526] Testing event mc_event: OK [ 115.977246] Testing event binder_return: OK [ 116.001712] Testing event binder_command: OK [ 116.025627] Testing event binder_unmap_kernel_end: OK [ 116.052707] Testing event binder_unmap_kernel_start: OK [ 116.077146] Testing event binder_unmap_user_end: OK [ 116.101716] Testing event binder_unmap_user_start: OK [ 116.128552] Testing event binder_alloc_page_end: OK [ 116.153923] Testing event binder_alloc_page_start: OK [ 116.180813] Testing event binder_free_lru_end: OK [ 116.208442] Testing event binder_free_lru_start: OK [ 116.234226] Testing event binder_alloc_lru_end: OK [ 116.261291] Testing event binder_alloc_lru_start: OK [ 116.286220] Testing event binder_update_page_range: OK [ 116.309532] Testing event binder_transaction_failed_buffer_release: OK [ 116.333575] Testing event binder_transaction_buffer_release: OK [ 116.357676] Testing event binder_transaction_alloc_buf: OK [ 116.381614] Testing event binder_transaction_fd_recv: OK [ 116.408576] Testing event binder_transaction_fd_send: OK [ 116.433812] Testing event binder_transaction_ref_to_ref: OK [ 116.457990] Testing event binder_transaction_ref_to_node: OK [ 116.484800] Testing event binder_transaction_node_to_ref: OK [ 116.509811] Testing event binder_transaction_received: OK [ 116.533466] Testing event binder_transaction: OK [ 116.560586] Testing event binder_wait_for_work: OK [ 116.586419] Testing event binder_read_done: OK [ 116.609666] Testing event binder_write_done: OK [ 116.636549] Testing event binder_ioctl_done: OK [ 116.661065] Testing event binder_unlock: OK [ 116.686036] Testing event binder_locked: OK [ 116.712586] Testing event binder_lock: OK [ 116.737527] Testing event binder_ioctl: OK [ 116.761559] Testing event br_fdb_update: OK [ 116.786821] Testing event fdb_delete: OK [ 116.813064] Testing event br_fdb_external_learn_add: OK [ 116.838125] Testing event br_fdb_add: OK [ 116.864214] Testing event qdisc_dequeue: OK [ 116.889694] Testing event fib_table_lookup: OK [ 116.914091] Testing event tcp_probe: OK [ 116.940634] Testing event tcp_retransmit_synack: OK [ 116.965536] Testing event tcp_rcv_space_adjust: OK [ 116.989539] Testing event tcp_destroy_sock: OK [ 117.016366] Testing event tcp_receive_reset: OK [ 117.041649] Testing event tcp_send_reset: OK [ 117.069065] Testing event tcp_retransmit_skb: OK [ 117.094177] Testing event udp_fail_queue_rcv_skb: OK [ 117.120637] Testing event inet_sock_set_state: OK [ 117.145698] Testing event sock_exceed_buf_limit: OK [ 117.169943] Testing event sock_rcvqueue_full: OK [ 117.198154] Testing event napi_poll: OK [ 117.225986] Testing event netif_rx_ni_entry: OK [ 117.249702] Testing event netif_rx_entry: OK [ 117.276887] Testing event netif_receive_skb_list_entry: OK [ 117.301540] Testing event netif_receive_skb_entry: OK [ 117.328653] Testing event napi_gro_receive_entry: OK [ 117.354767] Testing event napi_gro_frags_entry: OK [ 117.381900] Testing event netif_rx: OK [ 117.408887] Testing event netif_receive_skb: OK [ 117.436355] Testing event net_dev_queue: OK [ 117.461635] Testing event net_dev_xmit: OK [ 117.489045] Testing event net_dev_start_xmit: OK [ 117.513626] Testing event skb_copy_datagram_iovec: OK [ 117.537670] Testing event consume_skb: OK [ 117.561923] Testing event kfree_skb: OK [ 117.585650] Testing event 9p_protocol_dump: OK [ 117.618572] Testing event 9p_client_res: OK [ 117.645638] Testing event 9p_client_req: OK [ 117.669758] Running tests on trace event systems: [ 117.670557] Testing event system 9p: OK [ 117.707937] Testing event system skb: OK [ 117.736452] Testing event system net: OK [ 117.768991] Testing event system napi: OK [ 117.797068] Testing event system sock: OK [ 117.826718] Testing event system udp: OK [ 117.852711] Testing event system tcp: OK [ 117.884522] Testing event system fib: OK [ 117.910275] Testing event system qdisc: OK [ 117.937397] Testing event system bridge: OK [ 117.964691] Testing event system binder: OK [ 118.006179] Testing event system ras: OK [ 118.036108] Testing event system rtc: OK [ 118.074838] Testing event system mdio: OK [ 118.101134] Testing event system spi: OK [ 118.133201] Testing event system sync_trace: OK [ 118.157594] Testing event system dma_fence: OK [ 118.188249] Testing event system regmap: OK [ 118.225318] Testing event system iommu: OK [ 118.256993] Testing event system random: OK [ 118.295791] Testing event system regulator: OK [ 118.324609] Testing event system rpmh: OK [ 118.349176] Testing event system clk: OK [ 118.386155] Testing event system gpio: OK [ 118.415987] Testing event system wbt: OK [ 118.444970] Testing event system block: OK [ 118.480585] Testing event system filelock: OK [ 118.513554] Testing event system fs_dax: OK [ 118.549120] Testing event system writeback: OK [ 118.602131] Testing event system page_ref: OK [ 118.776451] Testing event system cma: OK [ 118.806258] Testing event system page_isolation: OK [ 118.833608] Testing event system huge_memory: OK [ 118.864699] Testing event system migrate: OK [ 118.889530] Testing event system compaction: OK [ 118.922911] Testing event system kmem: OK [ 118.962919] Testing event system percpu: OK [ 118.992344] Testing event system vmscan: OK [ 119.029117] Testing event system pagemap: OK [ 119.053983] Testing event system oom: OK [ 119.086641] Testing event system filemap: OK [ 119.114729] Testing event system rseq: OK [ 119.142698] Testing event system xdp: OK [ 119.177873] Testing event system benchmark: OK [ 119.306487] Testing event system rpm: OK [ 119.334056] Testing event system power: OK [ 119.386548] Testing event system test: OK [ 119.412487] Testing event system preemptirq: OK [ 119.450252] Testing event system cgroup: OK [ 119.483566] Testing event system module: OK [ 119.511847] Testing event system alarmtimer: OK [ 119.541298] Testing event system timer: OK [ 119.580086] Testing event system swiotlb: OK [ 119.605968] Testing event system rcu: OK [ 119.657538] Testing event system printk: OK [ 119.682138] Testing event system lock: OK [ 119.722659] Testing event system sched: OK [ 119.766599] Testing event system workqueue: OK [ 119.796093] Testing event system signal: OK [ 119.825273] Testing event system irq: OK [ 119.856121] Testing event system cpuhp: OK [ 119.885111] Testing event system task: OK [ 119.909980] Testing event system kvm: OK [ 119.961237] Testing event system emulation: OK [ 119.986241] Testing event system ipi: OK [ 120.017329] Testing event system raw_syscalls: OK [ 120.042147] Testing event system initcall: OK [ 120.072046] Running tests on all trace events: [ 120.072502] Testing all events: OK [ 133.930126] Running tests again, along with the function tracer [ 133.934169] Running tests on trace events: [ 133.937329] Testing event initcall_finish: OK [ 134.376887] Testing event initcall_start: OK [ 134.442592] Testing event initcall_level: OK [ 134.518145] Testing event sys_exit: OK [ 134.597910] Testing event sys_enter: OK [ 134.682530] Testing event ipi_exit: OK [ 134.774315] Testing event ipi_entry: OK [ 134.844658] Testing event ipi_raise: OK [ 134.929466] Testing event instruction_emulation: OK [ 135.001183] Testing event kvm_halt_poll_ns: OK [ 135.074613] Testing event kvm_age_page: OK [ 135.164486] Testing event kvm_fpu: OK [ 135.226478] Testing event kvm_mmio: OK [ 135.309272] Testing event kvm_ack_irq: OK [ 135.384563] Testing event kvm_set_irq: OK [ 135.456913] Testing event kvm_vcpu_wakeup: OK [ 135.522720] Testing event kvm_userspace_exit: OK [ 135.608634] Testing event kvm_timer_update_irq: OK [ 135.677864] Testing event kvm_toggle_cache: OK [ 135.754155] Testing event kvm_set_way_flush: OK [ 135.822609] Testing event kvm_test_age_hva: OK [ 135.897945] Testing event kvm_age_hva: OK [ 135.984568] Testing event kvm_set_spte_hva: OK [ 136.058553] Testing event kvm_unmap_hva_range: OK [ 136.121193] Testing event kvm_mmio_emulate: OK [ 136.196568] Testing event kvm_irq_line: OK [ 136.257179] Testing event kvm_access_fault: OK [ 136.349318] Testing event kvm_guest_fault: OK [ 136.419712] Testing event kvm_exit: OK [ 136.506218] Testing event kvm_entry: OK [ 136.570498] Testing event kvm_set_guest_debug: OK [ 136.658230] Testing event kvm_handle_sys_reg: OK [ 136.721905] Testing event trap_reg: OK [ 136.797991] Testing event kvm_arm_set_regset: OK [ 136.866344] Testing event kvm_arm_set_dreg32: OK [ 136.928540] Testing event kvm_arm_clear_debug: OK [ 137.006833] Testing event kvm_arm_setup_debug: OK [ 137.104553] Testing event kvm_hvc_arm64: OK [ 137.182008] Testing event kvm_wfx_arm64: OK [ 137.250464] Testing event vgic_update_irq_pending: OK [ 137.330010] Testing event task_rename: OK [ 137.421592] Testing event task_newtask: OK [ 137.486873] Testing event cpuhp_exit: OK [ 137.570361] Testing event cpuhp_multi_enter: OK [ 137.654512] Testing event cpuhp_enter: OK [ 137.730724] Testing event softirq_raise: OK [ 137.805807] Testing event softirq_exit: OK [ 137.878868] Testing event softirq_entry: OK [ 137.953420] Testing event irq_handler_exit: OK [ 138.025880] Testing event irq_handler_entry: OK [ 138.106543] Testing event signal_deliver: OK [ 138.186855] Testing event signal_generate: OK [ 138.268477] Testing event workqueue_execute_end: OK [ 138.353198] Testing event workqueue_execute_start: OK [ 138.433652] Testing event workqueue_activate_work: OK [ 138.501459] Testing event workqueue_queue_work: OK [ 138.590675] Testing event sched_wake_idle_without_ipi: OK [ 138.669098] Testing event sched_swap_numa: OK [ 138.745388] Testing event sched_stick_numa: OK [ 138.820996] Testing event sched_move_numa: OK [ 138.898399] Testing event sched_process_hang: OK [ 138.962667] Testing event sched_pi_setprio: OK [ 139.037050] Testing event sched_stat_runtime: OK [ 139.113863] Testing event sched_stat_blocked: OK [ 139.198105] Testing event sched_stat_iowait: OK [ 139.282542] Testing event sched_stat_sleep: OK [ 139.366608] Testing event sched_stat_wait: OK [ 139.433397] Testing event sched_process_exec: OK [ 139.517941] Testing event sched_process_fork: OK [ 139.600781] Testing event sched_process_wait: OK [ 139.678188] Testing event sched_wait_task: OK [ 139.754378] Testing event sched_process_exit: OK [ 139.833194] Testing event sched_process_free: OK [ 139.900413] Testing event sched_migrate_task: OK [ 139.966866] Testing event sched_switch: OK [ 140.045292] Testing event sched_wakeup_new: OK [ 140.134176] Testing event sched_wakeup: OK [ 140.216736] Testing event sched_waking: OK [ 140.289528] Testing event sched_kthread_stop_ret: OK [ 140.390404] Testing event sched_kthread_stop: OK [ 140.470404] Testing event lock_acquired: OK [ 140.541397] Testing event lock_contended: OK [ 140.605794] Testing event lock_release: OK [ 140.697374] Testing event lock_acquire: OK [ 140.778335] Testing event console: OK [ 140.846902] Testing event rcu_barrier: OK [ 140.929969] Testing event rcu_torture_read: OK [ 141.016852] Testing event rcu_batch_end: OK [ 141.106415] Testing event rcu_invoke_kfree_callback: OK [ 141.182615] Testing event rcu_invoke_callback: OK [ 141.248805] Testing event rcu_batch_start: OK [ 141.330071] Testing event rcu_kfree_callback: OK [ 141.398651] Testing event rcu_callback: OK [ 141.490082] Testing event rcu_dyntick: OK [ 141.581032] Testing event rcu_fqs: OK [ 141.656996] Testing event rcu_quiescent_state_report: OK [ 141.732999] Testing event rcu_unlock_preempted_task: OK [ 141.802108] Testing event rcu_preempt_task: OK [ 141.882822] Testing event rcu_exp_funnel_lock: OK [ 141.965251] Testing event rcu_exp_grace_period: OK [ 142.042309] Testing event rcu_grace_period_init: OK [ 142.120566] Testing event rcu_future_grace_period: OK [ 142.201951] Testing event rcu_grace_period: OK [ 142.293883] Testing event rcu_utilization: OK [ 142.374209] Testing event swiotlb_bounced: OK [ 142.454858] Testing event tick_stop: OK [ 142.530320] Testing event itimer_expire: OK [ 142.597920] Testing event itimer_state: OK [ 142.657721] Testing event hrtimer_cancel: OK [ 142.738136] Testing event hrtimer_expire_exit: OK [ 142.821195] Testing event hrtimer_expire_entry: OK [ 142.888637] Testing event hrtimer_start: OK [ 142.981373] Testing event hrtimer_init: OK [ 143.058367] Testing event timer_cancel: OK [ 143.119797] Testing event timer_expire_exit: OK [ 143.200996] Testing event timer_expire_entry: OK [ 143.281289] Testing event timer_start: OK [ 143.358444] Testing event timer_init: OK [ 143.429529] Testing event alarmtimer_cancel: OK [ 143.506645] Testing event alarmtimer_start: OK [ 143.576836] Testing event alarmtimer_fired: OK [ 143.674401] Testing event alarmtimer_suspend: OK [ 143.753457] Testing event module_request: OK [ 143.833219] Testing event module_put: OK [ 143.898919] Testing event module_get: OK [ 143.971935] Testing event module_free: OK [ 144.045964] Testing event module_load: OK [ 144.117017] Testing event cgroup_transfer_tasks: OK [ 144.205067] Testing event cgroup_attach_task: OK [ 144.273121] Testing event cgroup_rename: OK [ 144.362503] Testing event cgroup_release: OK [ 144.430242] Testing event cgroup_rmdir: OK [ 144.501519] Testing event cgroup_mkdir: OK [ 144.589760] Testing event cgroup_remount: OK [ 144.670564] Testing event cgroup_destroy_root: OK [ 144.741885] Testing event cgroup_setup_root: OK [ 144.818621] Testing event irq_enable: OK [ 144.930610] Testing event irq_disable: OK [ 145.030337] Testing event ftrace_test_filter: OK [ 145.102553] Testing event dev_pm_qos_remove_request: OK [ 145.181867] Testing event dev_pm_qos_update_request: OK [ 145.255636] Testing event dev_pm_qos_add_request: OK [ 145.334040] Testing event pm_qos_update_flags: OK [ 145.413184] Testing event pm_qos_update_target: OK [ 145.494862] Testing event pm_qos_update_request_timeout: OK [ 145.574819] Testing event pm_qos_remove_request: OK [ 145.645170] Testing event pm_qos_update_request: OK [ 145.729254] Testing event pm_qos_add_request: OK [ 145.801118] Testing event power_domain_target: OK [ 145.881738] Testing event clock_set_rate: OK [ 145.955837] Testing event clock_disable: OK [ 146.032534] Testing event clock_enable: OK [ 146.101514] Testing event wakeup_source_deactivate: OK [ 146.181108] Testing event wakeup_source_activate: OK [ 146.265884] Testing event suspend_resume: OK [ 146.374179] Testing event device_pm_callback_end: OK [ 146.448888] Testing event device_pm_callback_start: OK [ 146.522466] Testing event cpu_frequency_limits: OK [ 146.596735] Testing event cpu_frequency: OK [ 146.673275] Testing event pstate_sample: OK [ 146.761953] Testing event powernv_throttle: OK [ 146.828493] Testing event cpu_idle: OK [ 146.905464] Testing event rpm_return_int: OK [ 146.989145] Testing event rpm_idle: OK [ 147.058535] Testing event rpm_resume: OK [ 147.129855] Testing event rpm_suspend: OK [ 147.201050] Testing event benchmark_event: OK [ 147.374320] Testing event xdp_devmap_xmit: OK [ 147.440899] Testing event xdp_cpumap_enqueue: OK [ 147.506677] Testing event xdp_cpumap_kthread: OK [ 147.574594] Testing event xdp_redirect_map_err: OK [ 147.664540] Testing event xdp_redirect_map: OK [ 147.732960] Testing event xdp_redirect_err: OK [ 147.801599] Testing event xdp_redirect: OK [ 147.869114] Testing event xdp_exception: OK [ 147.941993] Testing event rseq_ip_fixup: OK [ 148.014697] Testing event rseq_update: OK [ 148.097050] Testing event file_check_and_advance_wb_err: OK [ 148.178081] Testing event filemap_set_wb_err: OK [ 148.262775] Testing event mm_filemap_add_to_page_cache: OK [ 148.358141] Testing event mm_filemap_delete_from_page_cache: OK [ 148.442108] Testing event compact_retry: OK [ 148.513288] Testing event skip_task_reaping: OK [ 148.582231] Testing event finish_task_reaping: OK [ 148.657366] Testing event start_task_reaping: OK [ 148.741447] Testing event wake_reaper: OK [ 148.805996] Testing event mark_victim: OK [ 148.897155] Testing event reclaim_retry_zone: OK [ 148.968753] Testing event oom_score_adj_update: OK [ 149.053211] Testing event mm_lru_activate: OK [ 149.133448] Testing event mm_lru_insertion: OK [ 149.220451] Testing event mm_vmscan_inactive_list_is_low: OK [ 149.300561] Testing event mm_vmscan_lru_shrink_active: OK [ 149.385322] Testing event mm_vmscan_lru_shrink_inactive: OK [ 149.469384] Testing event mm_vmscan_writepage: OK [ 149.542469] Testing event mm_vmscan_lru_isolate: OK [ 149.614900] Testing event mm_shrink_slab_end: OK [ 149.697387] Testing event mm_shrink_slab_start: OK [ 149.781041] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 149.860989] Testing event mm_vmscan_memcg_reclaim_end: OK [ 149.938128] Testing event mm_vmscan_direct_reclaim_end: OK [ 150.014656] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 150.093844] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 150.165267] Testing event mm_vmscan_direct_reclaim_begin: OK [ 150.245505] Testing event mm_vmscan_wakeup_kswapd: OK [ 150.314279] Testing event mm_vmscan_kswapd_wake: OK [ 150.386044] Testing event mm_vmscan_kswapd_sleep: OK [ 150.462500] Testing event percpu_destroy_chunk: OK [ 150.541243] Testing event percpu_create_chunk: OK [ 150.626661] Testing event percpu_alloc_percpu_fail: OK [ 150.713550] Testing event percpu_free_percpu: OK [ 150.781200] Testing event percpu_alloc_percpu: OK [ 150.846661] Testing event mm_page_alloc_extfrag: OK [ 150.926775] Testing event mm_page_pcpu_drain: OK [ 151.008611] Testing event mm_page_alloc_zone_locked: OK [ 151.077648] Testing event mm_page_alloc: OK [ 151.156717] Testing event mm_page_free_batched: OK [ 151.230028] Testing event mm_page_free: OK [ 151.326689] Testing event kmem_cache_free: OK [ 151.396986] Testing event kfree: OK [ 151.470776] Testing event kmem_cache_alloc_node: OK [ 151.542687] Testing event kmalloc_node: OK [ 151.622018] Testing event kmem_cache_alloc: OK [ 151.706357] Testing event kmalloc: OK [ 151.777009] Testing event mm_compaction_kcompactd_wake: OK [ 151.853517] Testing event mm_compaction_wakeup_kcompactd: OK [ 151.938403] Testing event mm_compaction_kcompactd_sleep: OK [ 152.018116] Testing event mm_compaction_defer_reset: OK [ 152.090884] Testing event mm_compaction_defer_compaction: OK [ 152.174312] Testing event mm_compaction_deferred: OK [ 152.238214] Testing event mm_compaction_suitable: OK [ 152.325834] Testing event mm_compaction_finished: OK [ 152.396902] Testing event mm_compaction_try_to_compact_pages: OK [ 152.477258] Testing event mm_compaction_end: OK [ 152.552810] Testing event mm_compaction_begin: OK [ 152.622054] Testing event mm_compaction_migratepages: OK [ 152.701013] Testing event mm_compaction_isolate_freepages: OK [ 152.785940] Testing event mm_compaction_isolate_migratepages: OK [ 152.857264] Testing event mm_migrate_pages: OK [ 152.936901] Testing event mm_collapse_huge_page_swapin: OK [ 153.025265] Testing event mm_collapse_huge_page_isolate: OK [ 153.098857] Testing event mm_collapse_huge_page: OK [ 153.166052] Testing event mm_khugepaged_scan_pmd: OK [ 153.242365] Testing event test_pages_isolated: OK [ 153.325709] Testing event cma_release: OK [ 153.401917] Testing event cma_alloc: OK [ 153.478636] Testing event page_ref_unfreeze: OK [ 153.570836] Testing event page_ref_freeze: OK [ 153.646619] Testing event page_ref_mod_unless: OK [ 153.738793] Testing event page_ref_mod_and_return: OK [ 153.825235] Testing event page_ref_mod_and_test: OK [ 154.148851] Testing event page_ref_mod: OK [ 154.306785] Testing event page_ref_set: OK [ 154.406287] Testing event sb_clear_inode_writeback: OK [ 154.488606] Testing event sb_mark_inode_writeback: OK [ 154.570114] Testing event writeback_dirty_inode_enqueue: OK [ 154.642140] Testing event writeback_lazytime_iput: OK [ 154.720806] Testing event writeback_lazytime: OK [ 154.802661] Testing event writeback_single_inode: OK [ 154.876903] Testing event writeback_single_inode_start: OK [ 154.948455] Testing event writeback_wait_iff_congested: OK [ 155.029185] Testing event writeback_congestion_wait: OK [ 155.105259] Testing event writeback_sb_inodes_requeue: OK [ 155.193087] Testing event balance_dirty_pages: OK [ 155.265044] Testing event bdi_dirty_ratelimit: OK [ 155.354440] Testing event global_dirty_state: OK [ 155.425884] Testing event writeback_queue_io: OK [ 155.493914] Testing event wbc_writepage: OK [ 155.565893] Testing event writeback_bdi_register: OK [ 155.633195] Testing event writeback_wake_background: OK [ 155.702903] Testing event writeback_pages_written: OK [ 155.777915] Testing event writeback_wait: OK [ 155.862348] Testing event writeback_written: OK [ 155.922884] Testing event writeback_start: OK [ 156.002135] Testing event writeback_exec: OK [ 156.077046] Testing event writeback_queue: OK [ 156.166844] Testing event writeback_write_inode: OK [ 156.233104] Testing event writeback_write_inode_start: OK [ 156.318119] Testing event writeback_dirty_inode: OK [ 156.394454] Testing event writeback_dirty_inode_start: OK [ 156.486113] Testing event writeback_mark_inode_dirty: OK [ 156.556982] Testing event writeback_dirty_page: OK [ 156.629089] Testing event dax_writeback_one: OK [ 156.706641] Testing event dax_writeback_range_done: OK [ 156.789733] Testing event dax_writeback_range: OK [ 156.872539] Testing event dax_insert_mapping: OK [ 156.937461] Testing event dax_insert_pfn_mkwrite: OK [ 157.018206] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 157.104910] Testing event dax_load_hole: OK [ 157.166914] Testing event dax_pte_fault_done: OK [ 157.244784] Testing event dax_pte_fault: OK [ 157.322911] Testing event dax_pmd_insert_mapping: OK [ 157.394813] Testing event dax_pmd_load_hole_fallback: OK [ 157.470701] Testing event dax_pmd_load_hole: OK [ 157.558034] Testing event dax_pmd_fault_done: OK [ 157.629618] Testing event dax_pmd_fault: OK [ 157.697579] Testing event generic_add_lease: OK [ 157.778923] Testing event time_out_leases: OK [ 157.854419] Testing event generic_delete_lease: OK [ 157.925460] Testing event break_lease_unblock: OK [ 158.000704] Testing event break_lease_block: OK [ 158.074903] Testing event break_lease_noblock: OK [ 158.156992] Testing event flock_lock_inode: OK [ 158.233480] Testing event locks_remove_posix: OK [ 158.322219] Testing event fcntl_setlk: OK [ 158.393783] Testing event posix_lock_inode: OK [ 158.469055] Testing event locks_get_lock_context: OK [ 158.553806] Testing event block_rq_remap: OK [ 158.624916] Testing event block_bio_remap: OK [ 158.693700] Testing event block_split: OK [ 158.770711] Testing event block_unplug: OK [ 158.842701] Testing event block_plug: OK [ 158.925218] Testing event block_sleeprq: OK [ 159.006508] Testing event block_getrq: OK [ 159.086919] Testing event block_bio_queue: OK [ 159.169061] Testing event block_bio_frontmerge: OK [ 159.254150] Testing event block_bio_backmerge: OK [ 159.337434] Testing event block_bio_complete: OK [ 159.410208] Testing event block_bio_bounce: OK [ 159.488820] Testing event block_rq_issue: OK [ 159.578162] Testing event block_rq_insert: OK [ 159.653660] Testing event block_rq_complete: OK [ 159.738454] Testing event block_rq_requeue: OK [ 159.810243] Testing event block_dirty_buffer: OK [ 159.893821] Testing event block_touch_buffer: OK [ 159.980471] Testing event wbt_timer: OK [ 160.060829] Testing event wbt_step: OK [ 160.128157] Testing event wbt_lat: OK [ 160.204455] Testing event wbt_stat: OK [ 160.288504] Testing event gpio_value: OK [ 160.370494] Testing event gpio_direction: OK [ 160.457156] Testing event clk_set_duty_cycle_complete: OK [ 160.534593] Testing event clk_set_duty_cycle: OK [ 160.625227] Testing event clk_set_phase_complete: OK [ 160.709452] Testing event clk_set_phase: OK [ 160.776651] Testing event clk_set_parent_complete: OK [ 160.862015] Testing event clk_set_parent: OK [ 160.958842] Testing event clk_set_rate_complete: OK [ 161.026687] Testing event clk_set_rate: OK [ 161.094444] Testing event clk_unprepare_complete: OK [ 161.177873] Testing event clk_unprepare: OK [ 161.260865] Testing event clk_prepare_complete: OK [ 161.350875] Testing event clk_prepare: OK [ 161.446382] Testing event clk_disable_complete: OK [ 161.512958] Testing event clk_disable: OK [ 161.594052] Testing event clk_enable_complete: OK [ 161.676739] Testing event clk_enable: OK [ 161.765179] Testing event rpmh_send_msg: OK [ 161.829046] Testing event rpmh_tx_done: OK [ 161.902869] Testing event regulator_set_voltage_complete: OK [ 161.984908] Testing event regulator_set_voltage: OK [ 162.057906] Testing event regulator_disable_complete: OK [ 162.129546] Testing event regulator_disable: OK [ 162.209058] Testing event regulator_enable_complete: OK [ 162.282283] Testing event regulator_enable_delay: OK [ 162.358783] Testing event regulator_enable: OK [ 162.438124] Testing event urandom_read: OK [ 162.509902] Testing event random_read: OK [ 162.581894] Testing event extract_entropy_user: OK [ 162.658065] Testing event extract_entropy: OK [ 162.737775] Testing event get_random_bytes_arch: OK [ 162.812608] Testing event get_random_bytes: OK [ 162.900726] Testing event xfer_secondary_pool: OK [ 162.978726] Testing event add_disk_randomness: OK [ 163.042345] Testing event add_input_randomness: OK [ 163.122193] Testing event debit_entropy: OK [ 163.201249] Testing event push_to_pool: OK [ 163.285386] Testing event credit_entropy_bits: OK [ 163.366452] Testing event mix_pool_bytes_nolock: OK [ 163.437739] Testing event mix_pool_bytes: OK [ 163.526921] Testing event add_device_randomness: OK [ 163.601332] Testing event io_page_fault: OK [ 163.685116] Testing event unmap: OK [ 163.750506] Testing event map: OK [ 163.844315] Testing event detach_device_from_domain: OK [ 163.922544] Testing event attach_device_to_domain: OK [ 163.986629] Testing event remove_device_from_group: OK [ 164.065321] Testing event add_device_to_group: OK [ 164.137246] Testing event regcache_drop_region: OK [ 164.198084] Testing event regmap_async_complete_done: OK [ 164.278799] Testing event regmap_async_complete_start: OK [ 164.358543] Testing event regmap_async_io_complete: OK [ 164.442429] Testing event regmap_async_write_start: OK [ 164.522617] Testing event regmap_cache_bypass: OK [ 164.593931] Testing event regmap_cache_only: OK [ 164.673208] Testing event regcache_sync: OK [ 164.742233] Testing event regmap_hw_write_done: OK [ 164.812888] Testing event regmap_hw_write_start: OK [ 164.905569] Testing event regmap_hw_read_done: OK [ 164.981713] Testing event regmap_hw_read_start: OK [ 165.044692] Testing event regmap_reg_read_cache: OK [ 165.113928] Testing event regmap_reg_read: OK [ 165.201008] Testing event regmap_reg_write: OK [ 165.281041] Testing event dma_fence_wait_end: OK [ 165.354634] Testing event dma_fence_wait_start: OK [ 165.434869] Testing event dma_fence_signaled: OK [ 165.526799] Testing event dma_fence_enable_signal: OK [ 165.589958] Testing event dma_fence_destroy: OK [ 165.669917] Testing event dma_fence_init: OK [ 165.733737] Testing event dma_fence_emit: OK [ 165.824630] Testing event sync_timeline: OK [ 165.885806] Testing event spi_transfer_stop: OK [ 165.962741] Testing event spi_transfer_start: OK [ 166.036836] Testing event spi_message_done: OK [ 166.105926] Testing event spi_message_start: OK [ 166.177662] Testing event spi_message_submit: OK [ 166.254187] Testing event spi_controller_busy: OK [ 166.342462] Testing event spi_controller_idle: OK [ 166.416784] Testing event mdio_access: OK [ 166.498391] Testing event rtc_timer_fired: OK [ 166.570165] Testing event rtc_timer_dequeue: OK [ 166.642446] Testing event rtc_timer_enqueue: OK [ 166.725365] Testing event rtc_read_offset: OK [ 166.805857] Testing event rtc_set_offset: OK [ 166.878255] Testing event rtc_alarm_irq_enable: OK [ 166.969826] Testing event rtc_irq_set_state: OK [ 167.036667] Testing event rtc_irq_set_freq: OK [ 167.105360] Testing event rtc_read_alarm: OK [ 167.177862] Testing event rtc_set_alarm: OK [ 167.253022] Testing event rtc_read_time: OK [ 167.334102] Testing event rtc_set_time: OK [ 167.430293] Testing event memory_failure_event: OK [ 167.510161] Testing event aer_event: OK [ 167.582025] Testing event non_standard_event: OK [ 167.656771] Testing event arm_event: OK [ 167.729889] Testing event mc_event: OK [ 167.809844] Testing event binder_return: OK [ 167.880907] Testing event binder_command: OK [ 167.961638] Testing event binder_unmap_kernel_end: OK [ 168.028828] Testing event binder_unmap_kernel_start: OK [ 168.114522] Testing event binder_unmap_user_end: OK [ 168.186111] Testing event binder_unmap_user_start: OK [ 168.265107] Testing event binder_alloc_page_end: OK [ 168.358231] Testing event binder_alloc_page_start: OK [ 168.437774] Testing event binder_free_lru_end: OK [ 168.525208] Testing event binder_free_lru_start: OK [ 168.602482] Testing event binder_alloc_lru_end: OK [ 168.673133] Testing event binder_alloc_lru_start: OK [ 168.748466] Testing event binder_update_page_range: OK [ 168.825124] Testing event binder_transaction_failed_buffer_release: OK [ 168.922727] Testing event binder_transaction_buffer_release: OK [ 169.001577] Testing event binder_transaction_alloc_buf: OK [ 169.074593] Testing event binder_transaction_fd_recv: OK [ 169.142589] Testing event binder_transaction_fd_send: OK [ 169.225470] Testing event binder_transaction_ref_to_ref: OK [ 169.313738] Testing event binder_transaction_ref_to_node: OK [ 169.394803] Testing event binder_transaction_node_to_ref: OK [ 169.464770] Testing event binder_transaction_received: OK [ 169.546634] Testing event binder_transaction: OK [ 169.622354] Testing event binder_wait_for_work: OK [ 169.688784] Testing event binder_read_done: OK [ 169.774301] Testing event binder_write_done: OK [ 169.834144] Testing event binder_ioctl_done: OK [ 169.918767] Testing event binder_unlock: OK [ 169.996932] Testing event binder_locked: OK [ 170.085169] Testing event binder_lock: OK [ 170.156549] Testing event binder_ioctl: OK [ 170.232795] Testing event br_fdb_update: OK [ 170.309594] Testing event fdb_delete: OK [ 170.394468] Testing event br_fdb_external_learn_add: OK [ 170.473814] Testing event br_fdb_add: OK [ 170.533613] Testing event qdisc_dequeue: OK [ 170.626068] Testing event fib_table_lookup: OK [ 170.698703] Testing event tcp_probe: OK [ 170.766788] Testing event tcp_retransmit_synack: OK [ 170.850915] Testing event tcp_rcv_space_adjust: OK [ 170.926210] Testing event tcp_destroy_sock: OK [ 170.990181] Testing event tcp_receive_reset: OK [ 171.062881] Testing event tcp_send_reset: OK [ 171.138476] Testing event tcp_retransmit_skb: OK [ 171.212591] Testing event udp_fail_queue_rcv_skb: OK [ 171.297151] Testing event inet_sock_set_state: OK [ 171.377055] Testing event sock_exceed_buf_limit: OK [ 171.456773] Testing event sock_rcvqueue_full: OK [ 171.537368] Testing event napi_poll: OK [ 171.614890] Testing event netif_rx_ni_entry: OK [ 171.704944] Testing event netif_rx_entry: OK [ 171.782002] Testing event netif_receive_skb_list_entry: OK [ 171.854148] Testing event netif_receive_skb_entry: OK [ 171.937351] Testing event napi_gro_receive_entry: OK [ 172.012524] Testing event napi_gro_frags_entry: OK [ 172.089069] Testing event netif_rx: OK [ 172.161127] Testing event netif_receive_skb: OK [ 172.238368] Testing event net_dev_queue: OK [ 172.329109] Testing event net_dev_xmit: OK [ 172.402271] Testing event net_dev_start_xmit: OK [ 172.486694] Testing event skb_copy_datagram_iovec: OK [ 172.562685] Testing event consume_skb: OK [ 172.636793] Testing event kfree_skb: OK [ 172.717341] Testing event 9p_protocol_dump: OK [ 172.814882] Testing event 9p_client_res: OK [ 172.902627] Testing event 9p_client_req: OK [ 172.993033] Running tests on trace event systems: [ 172.996527] Testing event system 9p: OK [ 173.093302] Testing event system skb: OK [ 173.169846] Testing event system net: OK [ 173.266765] Testing event system napi: OK [ 173.357592] Testing event system sock: OK [ 173.458386] Testing event system udp: OK [ 173.546370] Testing event system tcp: OK [ 173.638455] Testing event system fib: OK [ 173.706895] Testing event system qdisc: OK [ 173.785158] Testing event system bridge: OK [ 173.874501] Testing event system binder: OK [ 174.008690] Testing event system ras: OK [ 174.113606] Testing event system rtc: OK [ 174.229163] Testing event system mdio: OK [ 174.325480] Testing event system spi: OK [ 174.421341] Testing event system sync_trace: OK [ 174.503646] Testing event system dma_fence: OK [ 174.590788] Testing event system regmap: OK [ 174.714133] Testing event system iommu: OK [ 174.805022] Testing event system random: OK [ 174.920762] Testing event system regulator: OK [ 175.025412] Testing event system rpmh: OK [ 175.114106] Testing event system clk: OK [ 175.225373] Testing event system gpio: OK [ 175.318534] Testing event system wbt: OK [ 175.426371] Testing event system block: OK [ 175.530114] Testing event system filelock: OK [ 175.642278] Testing event system fs_dax: OK [ 175.746522] Testing event system writeback: OK [ 175.894313] Testing event system page_ref: OK [ 176.373512] Testing event system cma: OK [ 176.473687] Testing event system page_isolation: OK [ 176.546214] Testing event system huge_memory: OK [ 176.642685] Testing event system migrate: OK [ 176.709640] Testing event system compaction: OK [ 176.813878] Testing event system kmem: OK [ 176.922769] Testing event system percpu: OK [ 177.021978] Testing event system vmscan: OK [ 177.146014] Testing event system pagemap: OK [ 177.249603] Testing event system oom: OK [ 177.344938] Testing event system filemap: OK [ 177.428717] Testing event system rseq: OK [ 177.520759] Testing event system xdp: OK [ 177.616813] Testing event system benchmark: OK [ 177.790247] Testing event system rpm: OK [ 177.874106] Testing event system power: OK [ 178.037497] Testing event system test: OK [ 178.130345] Testing event system preemptirq: OK [ 178.290472] Testing event system cgroup: OK [ 178.390809] Testing event system module: OK [ 178.481496] Testing event system alarmtimer: OK [ 178.572957] Testing event system timer: OK [ 178.686607] Testing event system swiotlb: OK [ 178.766821] Testing event system rcu: OK [ 178.897927] Testing event system printk: OK [ 178.989168] Testing event system lock: OK [ 179.078176] Testing event system sched: OK [ 179.212497] Testing event system workqueue: OK [ 179.322355] Testing event system signal: OK [ 179.398489] Testing event system irq: OK [ 179.486353] Testing event system cpuhp: OK [ 179.590073] Testing event system task: OK [ 179.665510] Testing event system kvm: OK [ 179.822258] Testing event system emulation: OK [ 179.917696] Testing event system ipi: OK [ 179.997120] Testing event system raw_syscalls: OK [ 180.081724] Testing event system initcall: OK [ 180.174067] Running tests on all trace events: [ 180.177533] Testing all events: OK [ 189.926542] Testing ftrace filter: OK [ 190.027552] trace_kprobe: Testing kprobe tracing: OK [ 190.408315] Loading compiled-in X.509 certificates [ 190.512690] _warn_unseeded_randomness: 7 callbacks suppressed [ 190.512934] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 190.515798] Loaded X.509 cert 'Build time autogenerated kernel key: 4054958e39d9099bc90ea5e944013505afd1c71c' [ 190.518804] page_owner is disabled [ 190.636801] Key type big_key registered [ 190.684333] Key type encrypted registered [ 190.686380] ima: No TPM chip found, activating TPM-bypass! [ 190.687644] ima: Allocated hash algorithm: sha1 [ 190.690720] No architecture policies found [ 190.696436] random: get_random_bytes called from prandom_seed_full_state+0xc0/0x198 with crng_init=0 [ 190.718037] hctosys: unable to open rtc device (rtc0) [ 190.733202] psci_checker: PSCI checker started using 1 CPUs [ 190.733683] psci_checker: Starting hotplug tests [ 190.734433] psci_checker: Trying to turn off and on again all CPUs [ 190.735757] psci_checker: Trying to turn off and on again group 0 (CPUs 0) [ 190.736492] psci_checker: Hotplug tests passed OK [ 190.736952] psci_checker: Starting suspend tests (10 cycles per state) [ 190.737670] psci_checker: cpuidle not available on CPU 0, ignoring [ 190.738443] psci_checker: Could not start suspend tests on any CPU [ 190.739365] psci_checker: PSCI checker completed [ 190.780701] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 190.961190] ### dt-test ### start of unittest - you will see error messages [ 190.986032] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 190.986868] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 190.988119] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 190.988799] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 190.989502] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 190.990192] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 191.005938] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 191.008006] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 191.009600] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 191.383786] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status [ 191.429285] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status [ 191.450599] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status [ 191.495477] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status [ 191.564334] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status [ 191.624324] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status [ 191.664219] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status [ 191.735420] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status [ 191.774209] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo [ 191.776416] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 191.777255] OF: overlay: overlay #6 is not topmost [ 192.021783] OF: overlay: remove: Could not find overlay #7 [ 192.022404] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #7 [ 192.231459] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/status [ 192.236213] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/status [ 192.238264] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@30/incline-up [ 192.240062] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@40/incline-up [ 192.251637] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/status [ 192.252811] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/color [ 192.254090] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/rate [ 192.256059] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/hvac_2 [ 192.257353] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200 [ 192.258788] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_left [ 192.260503] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_right [ 192.356208] OF: overlay: ERROR: multiple fragments add and/or delete node /testcase-data-2/substation@100/motor-1/controller [ 192.359876] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/controller/name [ 192.370411] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 192.372152] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 192.373375] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/rpm_avail [ 192.389686] ### dt-test ### end of unittest - 217 passed, 0 failed [ 192.408681] uart-pl011 9000000.pl011: no DMA platform data [ 192.723817] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 192.724737] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 192.763890] VFS: Mounted root (9p filesystem) readonly on device 0:18. [ 192.784561] devtmpfs: mounted [ 193.426033] Freeing unused kernel memory: 4096K [ 193.445456] Checked W+X mappings: passed, no W+X pages found [ 193.446267] rodata_test: all tests were successful [ 193.447713] Run /sbin/init as init process [ 193.554638] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=0 [ 193.866155] _warn_unseeded_randomness: 4 callbacks suppressed [ 193.866357] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 194.457292] random: fast init done [ 197.464572] systemd[1]: System time before build time, advancing clock. [ 197.933557] systemd[1]: Failed to insert module 'autofs4': No such file or directory [ 200.990745] random: systemd: uninitialized urandom read (16 bytes read) [ 201.324594] random: systemd: uninitialized urandom read (16 bytes read) [ 201.329386] random: systemd: uninitialized urandom read (16 bytes read) [ 206.267764] 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) [ 206.340057] systemd[1]: Detected virtualization qemu. [ 206.346650] systemd[1]: Detected architecture arm64. Welcome to Debian GNU/Linux 9 (stretch)! [ 206.489090] systemd[1]: Set hostname to . [ 206.666571] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=1 [ 206.725743] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 207.313456] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 207.808490] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 207.809378] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 207.810025] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 208.868016] _warn_unseeded_randomness: 20 callbacks suppressed [ 208.868204] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 208.896007] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 208.896884] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 209.980047] random: systemd: uninitialized urandom read (16 bytes read) [ 210.105074] _warn_unseeded_randomness: 11 callbacks suppressed [ 210.105262] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 210.130890] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 210.132346] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 211.664018] _warn_unseeded_randomness: 12 callbacks suppressed [ 211.664200] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 211.665390] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 211.666045] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 213.914481] systemd-hiberna (1256) used greatest stack depth: 10576 bytes left [ 215.761908] random: systemd-cryptse: uninitialized urandom read (16 bytes read) [ 216.149192] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 217.218685] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 218.888826] random: systemd: uninitialized urandom read (16 bytes read) [ 219.120818] random: systemd: uninitialized urandom read (16 bytes read) [ 219.125149] random: systemd: uninitialized urandom read (16 bytes read) [ 226.389504] systemd[1]: networking.service: Cannot add dependency job, ignoring: Unit networking.service is masked. [ 226.458230] _warn_unseeded_randomness: 10 callbacks suppressed [ 226.458422] random: get_random_bytes called from generate_random_uuid+0x20/0x50 with crng_init=1 [ 226.533091] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 226.596733] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 226.625386] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 226.654269] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ OK ] Listening on /dev/initctl Compatibility Named Pipe. [ 226.667779] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 226.690155] systemd[1]: Starting of Arbitrary Executable File Formats File System Automount Point not supported. [UNSUPP] Starting of Arbitrary Executable Fi…tem Automount Point not supported. [ 226.780508] systemd[1]: Created slice System Slice. [ OK ] Created slice System Slice. [ 227.078597] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 227.194772] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 227.798643] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 227.799967] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 227.800638] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 227.826861] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ OK ] Created slice system-getty.slice. [ 228.801231] _warn_unseeded_randomness: 9 callbacks suppressed [ 228.801440] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 228.877050] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 228.906549] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 Mounting Debug File System... [ 230.141172] _warn_unseeded_randomness: 1 callbacks suppressed [ 230.141366] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 230.823418] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 230.824261] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 Starting Create Static Device Nodes in /dev... [ OK ] Reached target Slices. [ 231.230691] _warn_unseeded_randomness: 2 callbacks suppressed [ 231.230885] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 231.274304] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 231.321439] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 232.266073] systemd-modules (1265) used greatest stack depth: 9664 bytes left [ 232.270737] _warn_unseeded_randomness: 9 callbacks suppressed [ 232.270942] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 232.381815] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 232.410419] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 233.349362] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 234.595771] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ 234.722500] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 235.790189] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ 235.945689] systemd[1]: Reached target Encrypted Volumes. [ OK ] Reached target Encrypted Volumes. [ 236.051818] systemd[1]: Reached target Paths. [ 236.166179] _warn_unseeded_randomness: 3 callbacks suppressed [ 236.166361] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 236.167977] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 236.168661] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ OK ] Reached target Paths. [ 237.408366] _warn_unseeded_randomness: 12 callbacks suppressed [ 237.408550] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 237.734535] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 238.619922] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 238.937988] systemd[1]: Listening on Syslog Socket. [ OK ] Listening on Syslog Socket. [ 239.274737] systemd[1]: Listening on Journal Audit Socket. [ OK ] Listening on Journal Audit Socket. [ 240.082287] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 240.084059] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 240.084846] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 241.258174] _warn_unseeded_randomness: 8 callbacks suppressed [ 241.258363] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 241.298978] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 241.304039] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 241.337952] systemd[1]: Starting Journal Service... Starting Journal Service... [ 241.597819] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 241.804297] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 241.989437] systemd[1]: Reached target Sockets. [ OK ] Reached target Sockets. [ 243.058605] _warn_unseeded_randomness: 4 callbacks suppressed [ 243.058792] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 243.060340] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 243.089534] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 244.389048] _warn_unseeded_randomness: 2 callbacks suppressed [ 244.389254] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 244.660092] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 244.792508] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 244.897564] systemd[1]: Mounted Debug File System. [ OK ] Mounted Debug File System. [ 244.943862] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 245.007890] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 245.474217] systemd[1]: Started Load Kernel Modules. [ OK ] Started Load Kernel Modules. [ 246.065271] systemd[1]: Started Remount Root and Kernel File Systems. [ OK ] Started Remount Root and Kernel File Systems. [ 246.672153] systemd[1]: Started Create Static Device Nodes in /dev. [ OK ] Started Create Static Device Nodes in /dev. [ 247.941037] _warn_unseeded_randomness: 6 callbacks suppressed [ 247.941269] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 248.143710] systemd[1]: Starting udev Kernel Device Manager... Starting udev Kernel Device Manager... [ 249.269666] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 249.270530] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 249.272022] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 249.375701] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 249.521558] systemd-journald[1276]: /dev/kmsg buffer overrun, some messages lost. [ 250.707546] _warn_unseeded_randomness: 5 callbacks suppressed [ 250.707729] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 250.708846] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 250.709496] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 250.776823] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 250.885655] systemd[1]: Reached target Local File Systems (Pre). [ OK ] Reached target Local File Systems (Pre). [ 251.042849] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 252.322047] _warn_unseeded_randomness: 4 callbacks suppressed [ 252.322244] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 252.397487] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 252.398331] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 252.977044] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 254.426689] _warn_unseeded_randomness: 3 callbacks suppressed [ 254.426894] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 254.999730] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 255.000670] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 255.104097] systemd[1]: Mounting Configuration File System... Mounting Configuration File System... [ 255.450677] _warn_unseeded_randomness: 4 callbacks suppressed [ 255.450899] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 258.068622] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 258.296971] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 258.297869] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 259.144926] _warn_unseeded_randomness: 4 callbacks suppressed [ 259.145109] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 259.242793] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 259.314332] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 261.573711] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 261.773534] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 261.774417] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 262.110302] systemd[1]: Started Load/Save Random Seed. [ OK ] Started Load/Save Random Seed. [ 263.815834] systemd[1]: Started Apply Kernel Variables. [ OK ] Started Apply Kernel Variables. [ 265.257857] systemd[1]: Mounted Configuration File System. [ OK ] Mounted Configuration File System. [ 265.447745] _warn_unseeded_randomness: 3 callbacks suppressed [ 265.447936] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 265.672820] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 265.673830] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 266.760280] _warn_unseeded_randomness: 2 callbacks suppressed [ 266.760543] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 267.530911] systemd[1]: Started udev Kernel Device Manager. [ OK ] Started udev Kernel Device Manager. [* ] (1 of 3) A start job is running for dev-ttyAMA0.device (46s / 30min) [** ] (1 of 3) A start job is running for dev-ttyAMA0.device (46s / 30min) [*** ] (1 of 3) A start job is running for dev-ttyAMA0.device (47s / 30min) [ *** ] (2 of 3) A start job is running for…dplug all Devices (47s / no limit) [ *** ] (2 of 3) A start job is running for…dplug all Devices (48s / no limit) [ ***] (2 of 3) A start job is running for…dplug all Devices (48s / no limit) [ **] (3 of 3) A start job is running for Journal Service (49s / 30min 14s) [ *] (3 of 3) A start job is running for Journal Service (49s / 30min 14s) [ **] (3 of 3) A start job is running for Journal Service (50s / 30min 14s) [ ***] (1 of 3) A start job is running for dev-ttyAMA0.device (50s / 30min) [ *** ] (1 of 3) A start job is running for dev-ttyAMA0.device (51s / 30min) [ *** ] (1 of 3) A start job is running for dev-ttyAMA0.device (51s / 30min) [*** ] (2 of 3) A start job is running for…dplug all Devices (52s / no limit) [** ] (2 of 3) A start job is running for…dplug all Devices (52s / no limit) [* ] (2 of 3) A start job is running for…dplug all Devices (53s / no limit) [** ] (3 of 3) A start job is running for Journal Service (53s / 30min 14s)[ 280.332133] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 280.759577] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [*** ] (3 of 3) A start job is running for Journal Service (54s / 30min 14s) [ *** ] (3 of 3) A start job is running for Journal Service (54s / 30min 14s) [ *** ] (1 of 3) A start job is running for dev-ttyAMA0.device (55s / 30min) [ ***] (1 of 3) A start job is running for dev-ttyAMA0.device (55s / 30min) [ **] (1 of 3) A start job is running for dev-ttyAMA0.device (56s / 30min) [ *] (2 of 3) A start job is running for…dplug all Devices (56s / no limit) [ **] (2 of 3) A start job is running for…dplug all Devices (57s / no limit) [ ***] (2 of 3) A start job is running for…dplug all Devices (57s / no limit) [ *** ] (3 of 3) A start job is running for Journal Service (58s / 30min 14s) [ *** ] (3 of 3) A start job is running for Journal Service (58s / 30min 14s) [*** ] (3 of 3) A start job is running for Journal Service (59s / 30min 14s) [** ] (1 of 3) A start job is running for dev-ttyAMA0.device (59s / 30min) [* ] (1 of 3) A start job is running for dev-ttyAMA0.device (1min / 30min) [** ] (1 of 3) A start job is running for dev-ttyAMA0.device (1min / 30min)[ 287.492578] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 287.493455] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 287.494099] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 289.576733] _warn_unseeded_randomness: 5 callbacks suppressed [ 289.576933] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 289.577959] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 289.789770] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [*** ] (2 of 3) A start job is running for…g all Devices (1min 3s / no limit) [ *** ] (2 of 3) A start job is running for…g all Devices (1min 3s / no limit) [ *** ] (2 of 3) A start job is running for…g all Devices (1min 4s / no limit) [ ***] (3 of 3) A start job is running for…rnal Service (1min 4s / 30min 14s) [ **] (3 of 3) A start job is running for…rnal Service (1min 5s / 30min 14s) [ *] (3 of 3) A start job is running for…rnal Service (1min 5s / 30min 14s) [ **] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 6s / 30min) [ ***] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 6s / 30min) [ *** ] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 7s / 30min) [ *** ] (2 of 3) A start job is running for…g all Devices (1min 7s / no limit) [*** ] (2 of 3) A start job is running for…g all Devices (1min 8s / no limit) [** ] (2 of 3) A start job is running for…g all Devices (1min 8s / no limit) [* ] (3 of 3) A start job is running for…rnal Service (1min 9s / 30min 14s) [** ] (3 of 3) A start job is running for…rnal Service (1min 9s / 30min 14s) [*** ] (3 of 3) A start job is running for…nal Service (1min 10s / 30min 14s) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 10s / 30min) [ *** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 11s / 30min) [ ***] (1 of 3) A start job is running for…-ttyAMA0.device (1min 11s / 30min) [ **] (2 of 3) A start job is running for… all Devices (1min 12s / no limit) [ *] (2 of 3) A start job is running for… all Devices (1min 12s / no limit) [ **] (2 of 3) A start job is running for… all Devices (1min 13s / no limit) [ ***] (3 of 3) A start job is running for…nal Service (1min 13s / 30min 14s) [ *** ] (3 of 3) A start job is running for…nal Service (1min 14s / 30min 14s) [ *** ] (3 of 3) A start job is running for…nal Service (1min 14s / 30min 14s) [*** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 15s / 30min) [** ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 15s / 30min) [* ] (1 of 3) A start job is running for…-ttyAMA0.device (1min 16s / 30min)[ 302.837427] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [ 303.556662] _warn_unseeded_randomness: 3 callbacks suppressed [ 303.556861] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 Starting Flush Journal to Persistent Storage... [ 305.768187] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 305.769067] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 305.769777] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 306.289619] random: crng init done [ 306.290103] random: 1 get_random_xx warning(s) missed due to ratelimiting [** ] (2 of 3) A start job is running for… all Devices (1min 21s / no limit) [*** ] (2 of 3) A start job is running for… all Devices (1min 22s / no limit)[ 308.993683] systemd-journald[1276]: Received request to flush runtime journal from PID 1 [ *** ] (2 of 3) A start job is running for… all Devices (1min 22s / 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 28s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (1min 29s / no limit) [ **] (3 of 3) A start job is running for… all Devices (1min 29s / no limit) [ *] (1 of 3) A start job is running for… Directories (1min 30s / no limit) [ **] (1 of 3) A start job is running for… Directories (1min 30s / no limit) [ ***] (1 of 3) A start job is running for… Directories (1min 31s / no limit) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 31s / 30min) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 32s / 30min) [*** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 32s / 30min) [** ] (3 of 3) A start job is running for… all Devices (1min 33s / no limit) [* ] (3 of 3) A start job is running for… all Devices (1min 33s / no limit) [** ] (3 of 3) A start job is running for… all Devices (1min 34s / no limit) [*** ] (1 of 3) A start job is running for… Directories (1min 34s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (1min 35s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (1min 35s / no limit) [ ***] (2 of 3) A start job is running for…-ttyAMA0.device (1min 36s / 30min) [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 36s / 30min) [ *] (2 of 3) A start job is running for…-ttyAMA0.device (1min 37s / 30min) [ **] (3 of 3) A start job is running for… all Devices (1min 37s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (1min 38s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 38s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (1min 39s / no limit) [*** ] (1 of 3) A start job is running for… Directories (1min 39s / no limit) [** ] (1 of 3) A start job is running for… Directories (1min 40s / no limit) [* ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 40s / 30min) [** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 41s / 30min) [*** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 41s / 30min) [ *** ] (3 of 3) A start job is running for… all Devices (1min 42s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 42s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (1min 43s / no limit) [ OK ] Started Create Volatile Files and Directories. Starting Network Time Synchronization... Starting Update UTMP about System Boot/Shutdown... [ **] (3 of 4) A start job is running for… all Devices (1min 49s / no limit) [ *] (3 of 4) A start job is running for… all Devices (1min 50s / no limit) [ **] (3 of 4) A start job is running for… all Devices (1min 50s / no limit) [ ***] (4 of 4) A start job is running for…oot/Shutdown (1min 51s / no limit) [ *** ] (4 of 4) A start job is running for…oot/Shutdown (1min 51s / no limit) [ *** ] (4 of 4) A start job is running for…oot/Shutdown (1min 52s / no limit) [*** ] (1 of 4) A start job is running for…hronization (1min 52s / 31min 46s) [** ] (1 of 4) A start job is running for…hronization (1min 53s / 31min 46s) [* ] (1 of 4) A start job is running for…hronization (1min 54s / 31min 46s) [** ] (2 of 4) A start job is running for…-ttyAMA0.device (1min 54s / 30min) [*** ] (2 of 4) A start job is running for…-ttyAMA0.device (1min 55s / 30min) [ OK ] Started Update UTMP about System Boot/Shutdown. [ *** ] (1 of 3) A start job is running for…chronization (2min 1s / 31min 46s) [ *** ] (2 of 3) A start job is running for…v-ttyAMA0.device (2min 1s / 30min) [ ***] (2 of 3) A start job is running for…v-ttyAMA0.device (2min 2s / 30min) [ **] (2 of 3) A start job is running for…v-ttyAMA0.device (2min 2s / 30min) [ *] (3 of 3) A start job is running for…g all Devices (2min 3s / no limit) [ **] (3 of 3) A start job is running for…g all Devices (2min 3s / no limit) [ ***] (3 of 3) A start job is running for…g all Devices (2min 4s / no limit) [ *** ] (1 of 3) A start job is running for…chronization (2min 4s / 31min 46s) [ *** ] (1 of 3) A start job is running for…chronization (2min 5s / 31min 46s) [ OK ] Started Network Time Synchronization. [ OK ] Reached target System Time Synchronized. [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 10s / 30min) [** ] (2 of 2) A start job is running for… all Devices (2min 11s / no limit) [* ] (2 of 2) A start job is running for… all Devices (2min 11s / no limit) [** ] (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 17s / 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 18s / 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 19s / 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 20s / 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 21s / 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 22s / 30min) [ OK ] Found device /dev/ttyAMA0. [ ***] A start job is running for udev Col… all Devices (2min 29s / no limit) [ *** ] A start job is running for udev Col… all Devices (2min 29s / no limit) [ *** ] A start job is running for udev Col… all Devices (2min 30s / no limit) [*** ] A start job is running for udev Col… all Devices (2min 30s / no limit) [** ] A start job is running for udev Col… all Devices (2min 31s / no limit) [* ] A start job is running for udev Col… all Devices (2min 31s / no limit)[ 378.518807] virtio_net virtio0 enp0s1: renamed from eth0 [** ] A start job is running for udev Col… all Devices (2min 32s / no limit) [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. Starting System Logging Service... [ OK ] Started Regular background program processing daemon. Starting getty on tty2-tty6 if dbus and logind are not available... [ OK ] Started Daily apt upgrade and clean activities. Starting Permit User Sessions... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started System Logging Service. [ OK ] Started Permit User Sessions. [*** ] A start job is running for getty on…ot available (2min 59s / no limit) [ *** ] A start job is running for getty on…not available (3min 2s / no limit) [ *** ] A start job is running for getty on…not available (3min 5s / no limit) [ ***] A start job is running for getty on…not available (3min 7s / no limit) [ **] A start job is running for getty on…ot available (3min 10s / no limit) [ *] A start job is running for getty on…ot available (3min 12s / no limit) [ **] A start job is running for getty on…ot available (3min 15s / no limit) [ ***] A start job is running for getty on…ot available (3min 16s / no limit) [ OK ] Started Getty on tty6. [ OK ] Started Getty on tty5. [ OK ] Started Getty on tty4. [ OK ] Started Getty on tty3. [ OK ] Started Serial Getty on ttyAMA0. [ OK ] Started Getty on tty1. [ OK ] Started Getty on tty2. [ OK ] Started getty on tty2-tty6 if dbus and logind are not available. Debian GNU/Linux 9 compilator ttyAMA0 compilator login: