[ 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)) #52 SMP Tue Nov 27 10:03:57 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: 998280K/2097152K available (14860K kernel code, 3358K rwdata, 4656K rodata, 4096K init, 21766K bss, 85248K 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.000204] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.013359] Console: colour dummy device 80x25 [ 0.014472] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.014943] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.015273] ... MAX_LOCK_DEPTH: 48 [ 0.015596] ... MAX_LOCKDEP_KEYS: 8191 [ 0.015922] ... CLASSHASH_SIZE: 4096 [ 0.016266] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.016591] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.017277] ... CHAINHASH_SIZE: 32768 [ 0.017660] memory used by lock dependency info: 7775 kB [ 0.018069] per task-struct memory footprint: 2688 bytes [ 0.018513] ------------------------ [ 0.018830] | Locking API testsuite: [ 0.019192] ---------------------------------------------------------------------------- [ 0.019600] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.019985] -------------------------------------------------------------------------- [ 0.020776] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.032457] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.043943] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.056802] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.069030] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.082198] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.095101] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.108448] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.118399] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.128158] -------------------------------------------------------------------------- [ 0.128554] recursive read-lock: | ok | | ok | [ 0.132184] recursive read-lock #2: | ok | | ok | [ 0.135696] mixed read-write-lock: | ok | | ok | [ 0.138850] mixed write-read-lock: | ok | | ok | [ 0.142225] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.145700] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.149443] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.152824] -------------------------------------------------------------------------- [ 0.153869] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.158933] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.163937] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.168824] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.173985] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.179270] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.184385] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.189453] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.194568] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.199582] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.204632] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.209980] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.215923] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.221229] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.226463] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.231746] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.236986] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.242215] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.247523] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.252507] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.257772] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.263010] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.268247] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.273843] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.279458] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.285176] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.290618] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.295907] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.301212] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.306447] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.311822] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.317003] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.322351] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.327602] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.332834] hard-irq lock-inversion/123: ok | ok | ok | [ 0.338093] soft-irq lock-inversion/123: ok | ok | ok | [ 0.343513] hard-irq lock-inversion/132: ok | ok | ok | [ 0.348928] soft-irq lock-inversion/132: ok | ok | ok | [ 0.354300] hard-irq lock-inversion/213: ok | ok | ok | [ 0.359638] soft-irq lock-inversion/213: ok | ok | ok | [ 0.364842] hard-irq lock-inversion/231: ok | ok | ok | [ 0.370085] soft-irq lock-inversion/231: ok | ok | ok | [ 0.375431] hard-irq lock-inversion/312: ok | ok | ok | [ 0.380593] soft-irq lock-inversion/312: ok | ok | ok | [ 0.385992] hard-irq lock-inversion/321: ok | ok | ok | [ 0.391201] soft-irq lock-inversion/321: ok | ok | ok | [ 0.396412] hard-irq read-recursion/123: ok | [ 0.398657] soft-irq read-recursion/123: ok | [ 0.400660] hard-irq read-recursion/132: ok | [ 0.402968] soft-irq read-recursion/132: ok | [ 0.405013] hard-irq read-recursion/213: ok | [ 0.407333] soft-irq read-recursion/213: ok | [ 0.409451] hard-irq read-recursion/231: ok | [ 0.411835] soft-irq read-recursion/231: ok | [ 0.413855] hard-irq read-recursion/312: ok | [ 0.416274] soft-irq read-recursion/312: ok | [ 0.418378] hard-irq read-recursion/321: ok | [ 0.420700] soft-irq read-recursion/321: ok | [ 0.422773] -------------------------------------------------------------------------- [ 0.423135] | Wound/wait tests | [ 0.423551] --------------------- [ 0.423852] ww api failures: ok | ok | ok | [ 0.430885] ww contexts mixing: ok | ok | [ 0.434360] finishing ww context: ok | ok | ok | ok | [ 0.441109] locking mismatches: ok | ok | ok | [ 0.446044] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.463129] spinlock nest unlocked: ok | [ 0.464954] ----------------------------------------------------- [ 0.465307] |block | try |context| [ 0.465666] ----------------------------------------------------- [ 0.466021] context: ok | ok | ok | [ 0.471129] try: ok | ok | ok | [ 0.475958] block: ok | ok | ok | [ 0.480766] spinlock: ok | ok | ok | [ 0.485993] ------------------------------------------------------- [ 0.486373] Good, all 261 testcases passed! | [ 0.487081] --------------------------------- [ 0.491953] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.492656] pid_max: default: 32768 minimum: 301 [ 0.503583] LSM: Security Framework initializing [ 0.504614] Yama: becoming mindful. [ 0.507022] LoadPin: ready to pin (currently enforcing) [ 0.507801] AppArmor: AppArmor disabled by boot time parameter [ 0.534805] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.544623] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.547549] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.548558] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.582107] *** VALIDATE proc *** [ 0.600194] *** VALIDATE cgroup1 *** [ 0.600595] *** VALIDATE cgroup2 *** [ 0.662356] ASID allocator initialised with 32768 entries [ 0.668822] rcu: Hierarchical SRCU implementation. [ 0.732925] EFI services will not be available. [ 0.756044] smp: Bringing up secondary CPUs ... [ 0.756507] smp: Brought up 1 node, 1 CPU [ 0.756859] SMP: Total of 1 processors activated. [ 0.757802] CPU features: detected: 32-bit EL0 Support [ 0.758293] CPU features: detected: CRC32 instructions [ 1.037457] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.038258] CPU: All CPU(s) started at EL1 [ 1.039289] alternatives: patching kernel code [ 1.516990] node 0 initialised, 249310 pages in 4ms [ 1.524415] pgdatinit0 (15) used greatest stack depth: 13408 bytes left [ 1.604655] devtmpfs: initialized [ 1.816665] _warn_unseeded_randomness: 81 callbacks suppressed [ 1.817079] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=0 [ 1.818192] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 1.819747] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 1.830582] Registered cp15_barrier emulation handler [ 2.472122] workqueue: round-robin CPU selection forced, expect performance impact [ 8.241133] DMA-API: preallocated 65536 debug entries [ 8.241544] DMA-API: debugging enabled by kernel config [ 8.242003] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 8.243994] futex hash table entries: 256 (order: 3, 32768 bytes) [ 8.246009] Running postponed tracer tests: [ 8.251204] Testing tracer function: PASSED [ 27.126400] Testing dynamic ftrace: PASSED [ 28.410811] Testing dynamic ftrace ops #1: [ 32.160973] (1 0 1 0 0) [ 32.161512] (1 1 2 0 0) [ 49.618071] (2 1 3 0 1063303) [ 49.621835] (2 2 4 0 1063647) PASSED [ 60.174436] Testing dynamic ftrace ops #2: [ 80.078221] (1 0 1 1023404 0) [ 80.079762] (1 1 2 1023534 0) [ 80.170793] (2 1 3 1 3498) [ 80.174222] (2 2 4 330 3827) PASSED [ 88.522409] Testing ftrace recursion: PASSED [ 89.721254] Testing ftrace recursion safe: PASSED [ 90.928429] Testing ftrace regs(no arch support): PASSED [ 92.133111] Testing tracer nop: PASSED [ 92.135072] Testing tracer irqsoff: PASSED [ 111.436953] Testing tracer function_graph: PASSED [ 130.931746] _warn_unseeded_randomness: 2 callbacks suppressed [ 130.931997] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 130.932971] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 130.934221] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 130.944921] prandom: seed boundary self test passed [ 130.966988] prandom: 100 self tests passed [ 130.971531] pinctrl core: initialized pinctrl subsystem [ 131.045003] regulator-dummy: Failed to create debugfs directory [ 131.051796] [ 131.052142] ************************************************************* [ 131.052553] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 131.052956] ** ** [ 131.053348] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 131.053741] ** ** [ 131.054143] ** This means that this kernel is built to expose internal ** [ 131.054574] ** IOMMU data structures, which may compromise security on ** [ 131.055471] ** your system. ** [ 131.055894] ** ** [ 131.056292] ** If you see this message and you are not debugging the ** [ 131.056693] ** kernel, report this immediately to your vendor! ** [ 131.057093] ** ** [ 131.057505] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 131.057897] ************************************************************* [ 131.075750] DMI not present or invalid. [ 131.108042] NET: Registered protocol family 16 [ 131.128985] audit: initializing netlink subsys (disabled) [ 131.228872] cpuidle: using governor ladder [ 131.229597] cpuidle: using governor menu [ 131.241616] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 131.242380] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 131.245977] audit: type=2000 audit(109.640:1): state=initialized audit_enabled=0 res=1 [ 131.304949] DMA: preallocated 256 KiB pool for atomic allocations [ 131.358016] Serial: AMBA PL011 UART driver [ 131.958054] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 131.961015] printk: console [ttyAMA0] enabled [ 131.961015] printk: console [ttyAMA0] enabled [ 131.961936] printk: bootconsole [pl11] disabled [ 131.961936] printk: bootconsole [pl11] disabled [ 132.048654] _warn_unseeded_randomness: 39 callbacks suppressed [ 132.048837] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 132.050564] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 132.477168] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 132.514615] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 132.657170] arm-v7s io-pgtable: self test ok [ 132.657837] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 132.664162] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 132.667579] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 132.671470] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 132.675623] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 132.679021] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 132.682924] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 132.688947] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 132.695010] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 132.701639] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 132.707904] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 132.714520] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 132.722181] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 132.739037] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 132.755379] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 132.772653] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 132.791662] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 132.810383] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 132.829545] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 132.852404] vgaarb: loaded [ 132.893734] pps_core: LinuxPPS API ver. 1 registered [ 132.894297] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 132.896909] PTP clock support registered [ 133.017121] NetLabel: Initializing [ 133.018039] NetLabel: domain hash size = 128 [ 133.018568] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 133.026171] NetLabel: unlabeled traffic allowed by default [ 133.040301] clocksource: Switched to clocksource arch_sys_counter [ 135.799889] _warn_unseeded_randomness: 39 callbacks suppressed [ 135.800086] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 135.801457] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 135.802905] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 135.812798] VFS: Disk quotas dquot_6.6.0 [ 135.816381] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 135.825949] *** VALIDATE hugetlbfs *** [ 136.460135] NET: Registered protocol family 2 [ 136.493611] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 136.497469] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 136.512427] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 136.521191] TCP: Hash tables configured (established 16384 bind 16384) [ 136.527967] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 136.532400] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 136.542817] NET: Registered protocol family 1 [ 136.611981] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 136.617972] kvm [1]: HYP mode not available [ 136.642202] kworker/u2:1 (34) used greatest stack depth: 11920 bytes left [ 136.816284] Initialise system trusted keyrings [ 136.817686] _warn_unseeded_randomness: 56 callbacks suppressed [ 136.817858] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 136.821863] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 136.831848] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 138.265791] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 138.266621] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 138.282740] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 138.434177] 9p: Installing v9fs 9p2000 file system support [ 139.121691] Key type asymmetric registered [ 139.122791] Asymmetric key parser 'x509' registered [ 139.127579] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 139.525994] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 139.532176] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 139.551348] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 139.597197] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 139.646410] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 139.648895] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 139.651520] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 139.652690] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 139.656536] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 139.669259] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 139.670285] pci_bus 0000:00: root bus resource [bus 00-0f] [ 139.671616] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 139.672399] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 139.673110] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 139.751668] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 139.752620] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 139.753719] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 139.754716] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 139.756128] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 139.756847] pci 0000:00:03.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 139.757533] pci 0000:00:03.0: BAR 0: assigned [io 0x1000-0x103f] [ 139.758213] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 139.759396] pci 0000:00:02.0: BAR 0: assigned [io 0x1060-0x107f] [ 140.195810] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 140.248230] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 140.294353] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 144.200128] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 144.508789] SuperH (H)SCI(F) driver initialized [ 144.556703] _warn_unseeded_randomness: 38 callbacks suppressed [ 144.556898] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 144.618449] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 144.681494] libphy: Fixed MDIO Bus: probed [ 145.094643] ledtrig-cpu: registered to indicate activity on CPUs [ 145.120062] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 145.120865] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 145.162706] ashmem: initialized [ 145.321094] 9pnet: Installing 9P2000 support [ 145.560720] registered taskstats version 1 [ 145.561295] Running tests on trace events: [ 145.561783] Testing event initcall_finish: [ 145.565989] _warn_unseeded_randomness: 6 callbacks suppressed [ 145.566180] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 145.588827] OK [ 145.589322] Testing event initcall_start: OK [ 145.614053] Testing event initcall_level: OK [ 145.642101] Testing event sys_exit: OK [ 145.670274] Testing event sys_enter: OK [ 145.696589] Testing event ipi_exit: OK [ 145.722325] Testing event ipi_entry: OK [ 145.748390] Testing event ipi_raise: OK [ 145.773860] Testing event instruction_emulation: OK [ 145.800685] Testing event kvm_halt_poll_ns: OK [ 145.828218] Testing event kvm_age_page: OK [ 145.853725] Testing event kvm_fpu: OK [ 145.877511] Testing event kvm_mmio: OK [ 145.904598] Testing event kvm_ack_irq: OK [ 145.930373] Testing event kvm_set_irq: OK [ 145.957879] Testing event kvm_vcpu_wakeup: OK [ 145.982676] Testing event kvm_userspace_exit: OK [ 146.008008] Testing event kvm_timer_update_irq: OK [ 146.033056] Testing event kvm_toggle_cache: OK [ 146.057923] Testing event kvm_set_way_flush: OK [ 146.084231] Testing event kvm_test_age_hva: OK [ 146.109816] Testing event kvm_age_hva: OK [ 146.136438] Testing event kvm_set_spte_hva: OK [ 146.164189] Testing event kvm_unmap_hva_range: OK [ 146.189419] Testing event kvm_mmio_emulate: OK [ 146.214683] Testing event kvm_irq_line: OK [ 146.239692] Testing event kvm_access_fault: OK [ 146.264844] Testing event kvm_guest_fault: OK [ 146.290110] Testing event kvm_exit: OK [ 146.316329] Testing event kvm_entry: OK [ 146.341860] Testing event kvm_set_guest_debug: OK [ 146.368283] Testing event kvm_handle_sys_reg: OK [ 146.393865] Testing event trap_reg: OK [ 146.420994] Testing event kvm_arm_set_regset: OK [ 146.446528] Testing event kvm_arm_set_dreg32: OK [ 146.474087] Testing event kvm_arm_clear_debug: OK [ 146.500281] Testing event kvm_arm_setup_debug: OK [ 146.525898] Testing event kvm_hvc_arm64: OK [ 146.549794] Testing event kvm_wfx_arm64: OK [ 146.576366] Testing event vgic_update_irq_pending: OK [ 146.602434] Testing event task_rename: OK [ 146.632057] Testing event task_newtask: OK [ 146.657449] Testing event cpuhp_exit: OK [ 146.684805] Testing event cpuhp_multi_enter: OK [ 146.713001] Testing event cpuhp_enter: OK [ 146.740569] Testing event softirq_raise: OK [ 146.766249] Testing event softirq_exit: OK [ 146.792862] Testing event softirq_entry: OK [ 146.818222] Testing event irq_handler_exit: OK [ 146.844975] Testing event irq_handler_entry: OK [ 146.873201] Testing event signal_deliver: OK [ 146.900552] Testing event signal_generate: OK [ 146.928692] Testing event workqueue_execute_end: OK [ 146.954311] Testing event workqueue_execute_start: OK [ 146.982118] Testing event workqueue_activate_work: OK [ 147.007532] Testing event workqueue_queue_work: OK [ 147.033566] Testing event sched_wake_idle_without_ipi: OK [ 147.060810] Testing event sched_swap_numa: OK [ 147.086469] Testing event sched_stick_numa: OK [ 147.114034] Testing event sched_move_numa: OK [ 147.140443] Testing event sched_process_hang: OK [ 147.165442] Testing event sched_pi_setprio: OK [ 147.189827] Testing event sched_stat_runtime: [ 147.193219] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 147.221518] OK [ 147.222008] Testing event sched_stat_blocked: OK [ 147.249123] Testing event sched_stat_iowait: OK [ 147.273915] Testing event sched_stat_sleep: OK [ 147.300425] Testing event sched_stat_wait: OK [ 147.326501] Testing event sched_process_exec: OK [ 147.353920] Testing event sched_process_fork: OK [ 147.378572] Testing event sched_process_wait: OK [ 147.404028] Testing event sched_wait_task: OK [ 147.429857] Testing event sched_process_exit: OK [ 147.461900] Testing event sched_process_free: OK [ 147.488739] Testing event sched_migrate_task: OK [ 147.514280] Testing event sched_switch: OK [ 147.542078] Testing event sched_wakeup_new: OK [ 147.568266] Testing event sched_wakeup: OK [ 147.593518] Testing event sched_waking: OK [ 147.620609] Testing event sched_kthread_stop_ret: OK [ 147.646461] Testing event sched_kthread_stop: OK [ 147.673944] Testing event lock_acquired: OK [ 147.705813] Testing event lock_contended: OK [ 147.732722] Testing event lock_release: OK [ 147.772937] Testing event lock_acquire: OK [ 147.808861] Testing event console: OK [ 147.833509] Testing event rcu_barrier: OK [ 147.857668] Testing event rcu_torture_read: OK [ 147.883799] Testing event rcu_batch_end: OK [ 147.909764] Testing event rcu_invoke_kfree_callback: OK [ 147.936823] Testing event rcu_invoke_callback: OK [ 147.964479] Testing event rcu_batch_start: OK [ 147.992560] Testing event rcu_kfree_callback: OK [ 148.018026] Testing event rcu_callback: OK [ 148.044691] Testing event rcu_dyntick: OK [ 148.073223] Testing event rcu_fqs: OK [ 148.100678] Testing event rcu_quiescent_state_report: OK [ 148.128558] Testing event rcu_unlock_preempted_task: OK [ 148.153943] Testing event rcu_preempt_task: OK [ 148.180334] Testing event rcu_exp_funnel_lock: OK [ 148.206165] Testing event rcu_exp_grace_period: OK [ 148.237328] Testing event rcu_grace_period_init: OK [ 148.261768] Testing event rcu_future_grace_period: OK [ 148.288275] Testing event rcu_grace_period: OK [ 148.321730] Testing event rcu_utilization: OK [ 148.352075] Testing event swiotlb_bounced: OK [ 148.377633] Testing event tick_stop: OK [ 148.404542] Testing event itimer_expire: OK [ 148.432026] Testing event itimer_state: OK [ 148.457504] Testing event hrtimer_cancel: OK [ 148.484732] Testing event hrtimer_expire_exit: OK [ 148.510368] Testing event hrtimer_expire_entry: OK [ 148.536818] Testing event hrtimer_start: OK [ 148.564066] Testing event hrtimer_init: OK [ 148.589417] Testing event timer_cancel: OK [ 148.616573] Testing event timer_expire_exit: OK [ 148.642523] Testing event timer_expire_entry: OK [ 148.669959] Testing event timer_start: OK [ 148.698126] Testing event timer_init: OK [ 148.724302] Testing event alarmtimer_cancel: OK [ 148.750280] Testing event alarmtimer_start: OK [ 148.776713] Testing event alarmtimer_fired: OK [ 148.802116] Testing event alarmtimer_suspend: OK [ 148.828424] Testing event module_request: OK [ 148.856213] Testing event module_put: OK [ 148.881328] Testing event module_get: OK [ 148.905832] Testing event module_free: OK [ 148.932343] Testing event module_load: OK [ 148.958251] Testing event cgroup_transfer_tasks: OK [ 148.986003] Testing event cgroup_attach_task: OK [ 149.012053] Testing event cgroup_rename: OK [ 149.037900] Testing event cgroup_release: OK [ 149.069458] Testing event cgroup_rmdir: OK [ 149.094292] Testing event cgroup_mkdir: OK [ 149.120847] Testing event cgroup_remount: OK [ 149.146266] Testing event cgroup_destroy_root: OK [ 149.172274] Testing event cgroup_setup_root: OK [ 149.197873] Testing event irq_enable: OK [ 149.231597] Testing event irq_disable: OK [ 149.262137] Testing event ftrace_test_filter: OK [ 149.288828] Testing event dev_pm_qos_remove_request: OK [ 149.316553] Testing event dev_pm_qos_update_request: OK [ 149.342171] Testing event dev_pm_qos_add_request: OK [ 149.368309] Testing event pm_qos_update_flags: OK [ 149.393891] Testing event pm_qos_update_target: OK [ 149.420254] Testing event pm_qos_update_request_timeout: OK [ 149.445936] Testing event pm_qos_remove_request: OK [ 149.472540] Testing event pm_qos_update_request: OK [ 149.498417] Testing event pm_qos_add_request: OK [ 149.525895] Testing event power_domain_target: OK [ 149.549784] Testing event clock_set_rate: OK [ 149.573998] Testing event clock_disable: OK [ 149.600041] Testing event clock_enable: OK [ 149.625971] Testing event wakeup_source_deactivate: OK [ 149.652225] Testing event wakeup_source_activate: OK [ 149.677892] Testing event suspend_resume: OK [ 149.716854] Testing event device_pm_callback_end: OK [ 149.742358] Testing event device_pm_callback_start: OK [ 149.770141] Testing event cpu_frequency_limits: OK [ 149.796839] Testing event cpu_frequency: OK [ 149.824044] Testing event pstate_sample: OK [ 149.849334] Testing event powernv_throttle: OK [ 149.875593] Testing event cpu_idle: OK [ 149.902561] Testing event rpm_return_int: OK [ 149.930663] Testing event rpm_idle: OK [ 149.957858] Testing event rpm_resume: OK [ 149.984360] Testing event rpm_suspend: OK [ 150.009373] Testing event benchmark_event: OK [ 150.132819] Testing event xdp_devmap_xmit: OK [ 150.162602] Testing event xdp_cpumap_enqueue: OK [ 150.188243] Testing event xdp_cpumap_kthread: OK [ 150.213878] Testing event xdp_redirect_map_err: OK [ 150.240407] Testing event xdp_redirect_map: OK [ 150.268537] Testing event xdp_redirect_err: OK [ 150.294069] Testing event xdp_redirect: OK [ 150.320344] Testing event xdp_exception: OK [ 150.347984] Testing event rseq_ip_fixup: OK [ 150.373869] Testing event rseq_update: OK [ 150.400406] Testing event file_check_and_advance_wb_err: OK [ 150.426178] Testing event filemap_set_wb_err: OK [ 150.452817] Testing event mm_filemap_add_to_page_cache: OK [ 150.478023] Testing event mm_filemap_delete_from_page_cache: OK [ 150.504669] Testing event compact_retry: OK [ 150.530119] Testing event skip_task_reaping: OK [ 150.556485] Testing event finish_task_reaping: OK [ 150.582185] Testing event start_task_reaping: OK [ 150.608810] Testing event wake_reaper: OK [ 150.634068] Testing event mark_victim: OK [ 150.660401] Testing event reclaim_retry_zone: OK [ 150.686197] Testing event oom_score_adj_update: OK [ 150.712814] Testing event mm_lru_activate: OK [ 150.738134] Testing event mm_lru_insertion: OK [ 150.764393] Testing event mm_vmscan_inactive_list_is_low: OK [ 150.792242] Testing event mm_vmscan_lru_shrink_active: OK [ 150.817353] Testing event mm_vmscan_lru_shrink_inactive: OK [ 150.841764] Testing event mm_vmscan_writepage: OK [ 150.868247] Testing event mm_vmscan_lru_isolate: OK [ 150.894279] Testing event mm_shrink_slab_end: OK [ 150.920741] Testing event mm_shrink_slab_start: OK [ 150.947889] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 150.973505] Testing event mm_vmscan_memcg_reclaim_end: OK [ 150.997793] Testing event mm_vmscan_direct_reclaim_end: OK [ 151.024245] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 151.050456] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 151.077764] Testing event mm_vmscan_direct_reclaim_begin: OK [ 151.101876] Testing event mm_vmscan_wakeup_kswapd: OK [ 151.128533] Testing event mm_vmscan_kswapd_wake: OK [ 151.155935] Testing event mm_vmscan_kswapd_sleep: OK [ 151.181508] Testing event percpu_destroy_chunk: OK [ 151.205715] Testing event percpu_create_chunk: OK [ 151.231816] Testing event percpu_alloc_percpu_fail: OK [ 151.256857] Testing event percpu_free_percpu: OK [ 151.282010] Testing event percpu_alloc_percpu: OK [ 151.308261] Testing event mm_page_alloc_extfrag: OK [ 151.334541] Testing event mm_page_pcpu_drain: OK [ 151.361740] Testing event mm_page_alloc_zone_locked: OK [ 151.388697] Testing event mm_page_alloc: OK [ 151.413940] Testing event mm_page_free_batched: OK [ 151.440213] Testing event mm_page_free: OK [ 151.466397] Testing event kmem_cache_free: OK [ 151.497021] Testing event kfree: OK [ 151.521847] Testing event kmem_cache_alloc_node: OK [ 151.548258] Testing event kmalloc_node: OK [ 151.576016] Testing event kmem_cache_alloc: OK [ 151.602096] Testing event kmalloc: OK [ 151.630299] Testing event mm_compaction_kcompactd_wake: OK [ 151.656183] Testing event mm_compaction_wakeup_kcompactd: OK [ 151.681915] Testing event mm_compaction_kcompactd_sleep: OK [ 151.708408] Testing event mm_compaction_defer_reset: OK [ 151.733411] Testing event mm_compaction_defer_compaction: OK [ 151.757752] Testing event mm_compaction_deferred: OK [ 151.781359] Testing event mm_compaction_suitable: OK [ 151.808294] Testing event mm_compaction_finished: OK [ 151.833805] Testing event mm_compaction_try_to_compact_pages: OK [ 151.860831] Testing event mm_compaction_end: OK [ 151.886469] Testing event mm_compaction_begin: OK [ 151.913865] Testing event mm_compaction_migratepages: OK [ 151.938648] Testing event mm_compaction_isolate_freepages: OK [ 151.963926] Testing event mm_compaction_isolate_migratepages: OK [ 151.989614] Testing event mm_migrate_pages: OK [ 152.016920] Testing event mm_collapse_huge_page_swapin: OK [ 152.045144] Testing event mm_collapse_huge_page_isolate: OK [ 152.070815] Testing event mm_collapse_huge_page: OK [ 152.097548] Testing event mm_khugepaged_scan_pmd: OK [ 152.121728] Testing event test_pages_isolated: OK [ 152.148217] Testing event cma_release: OK [ 152.174382] Testing event cma_alloc: OK [ 152.201868] Testing event page_ref_unfreeze: OK [ 152.230793] Testing event page_ref_freeze: OK [ 152.260388] Testing event page_ref_mod_unless: OK [ 152.293109] Testing event page_ref_mod_and_return: OK [ 152.318819] Testing event page_ref_mod_and_test: OK [ 152.452134] Testing event page_ref_mod: OK [ 152.512307] Testing event page_ref_set: OK [ 152.542681] Testing event sb_clear_inode_writeback: OK [ 152.568289] Testing event sb_mark_inode_writeback: OK [ 152.593889] Testing event writeback_dirty_inode_enqueue: OK [ 152.620444] Testing event writeback_lazytime_iput: OK [ 152.645463] Testing event writeback_lazytime: OK [ 152.669721] Testing event writeback_single_inode: OK [ 152.696324] Testing event writeback_single_inode_start: OK [ 152.722289] Testing event writeback_wait_iff_congested: OK [ 152.748732] Testing event writeback_congestion_wait: OK [ 152.775894] Testing event writeback_sb_inodes_requeue: OK [ 152.801361] Testing event balance_dirty_pages: OK [ 152.825885] Testing event bdi_dirty_ratelimit: OK [ 152.851790] Testing event global_dirty_state: OK [ 152.876824] Testing event writeback_queue_io: OK [ 152.901815] Testing event wbc_writepage: OK [ 152.928207] Testing event writeback_bdi_register: OK [ 152.953789] Testing event writeback_wake_background: OK [ 152.980437] Testing event writeback_pages_written: OK [ 153.008356] Testing event writeback_wait: OK [ 153.033415] Testing event writeback_written: OK [ 153.057825] Testing event writeback_start: OK [ 153.084210] Testing event writeback_exec: OK [ 153.109821] Testing event writeback_queue: OK [ 153.136420] Testing event writeback_write_inode: OK [ 153.162276] Testing event writeback_write_inode_start: OK [ 153.188723] Testing event writeback_dirty_inode: OK [ 153.215913] Testing event writeback_dirty_inode_start: OK [ 153.241543] Testing event writeback_mark_inode_dirty: OK [ 153.265743] Testing event writeback_dirty_page: OK [ 153.292297] Testing event dax_writeback_one: OK [ 153.318367] Testing event dax_writeback_range_done: OK [ 153.345904] Testing event dax_writeback_range: OK [ 153.370666] Testing event dax_insert_mapping: OK [ 153.395965] Testing event dax_insert_pfn_mkwrite: OK [ 153.421646] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 153.448942] Testing event dax_load_hole: OK [ 153.474392] Testing event dax_pte_fault_done: OK [ 153.500721] Testing event dax_pte_fault: OK [ 153.528136] Testing event dax_pmd_insert_mapping: OK [ 153.553406] Testing event dax_pmd_load_hole_fallback: OK [ 153.578722] Testing event dax_pmd_load_hole: OK [ 153.601217] Testing event dax_pmd_fault_done: OK [ 153.625733] Testing event dax_pmd_fault: OK [ 153.650062] Testing event generic_add_lease: OK [ 153.678105] Testing event time_out_leases: OK [ 153.704136] Testing event generic_delete_lease: OK [ 153.730038] Testing event break_lease_unblock: OK [ 153.756244] Testing event break_lease_block: OK [ 153.781901] Testing event break_lease_noblock: OK [ 153.808254] Testing event flock_lock_inode: OK [ 153.833890] Testing event locks_remove_posix: OK [ 153.860277] Testing event fcntl_setlk: OK [ 153.885871] Testing event posix_lock_inode: OK [ 153.912246] Testing event locks_get_lock_context: OK [ 153.937894] Testing event block_rq_remap: OK [ 153.961690] Testing event block_bio_remap: OK [ 153.986068] Testing event block_split: OK [ 154.012357] Testing event block_unplug: OK [ 154.038210] Testing event block_plug: OK [ 154.064849] Testing event block_sleeprq: OK [ 154.089876] Testing event block_getrq: OK [ 154.116495] Testing event block_bio_queue: OK [ 154.143902] Testing event block_bio_frontmerge: OK [ 154.169496] Testing event block_bio_backmerge: OK [ 154.193794] Testing event block_bio_complete: OK [ 154.220210] Testing event block_bio_bounce: OK [ 154.245584] Testing event block_rq_issue: OK [ 154.272638] Testing event block_rq_insert: OK [ 154.300934] Testing event block_rq_complete: OK [ 154.325830] Testing event block_rq_requeue: OK [ 154.358050] Testing event block_dirty_buffer: OK [ 154.382230] Testing event block_touch_buffer: OK [ 154.408435] Testing event wbt_timer: OK [ 154.433777] Testing event wbt_step: OK [ 154.457924] Testing event wbt_lat: OK [ 154.484319] Testing event wbt_stat: OK [ 154.510087] Testing event gpio_value: OK [ 154.538560] Testing event gpio_direction: OK [ 154.569290] Testing event clk_set_duty_cycle_complete: OK [ 154.594106] Testing event clk_set_duty_cycle: OK [ 154.620260] Testing event clk_set_phase_complete: OK [ 154.646403] Testing event clk_set_phase: OK [ 154.673938] Testing event clk_set_parent_complete: OK [ 154.700469] Testing event clk_set_parent: OK [ 154.726301] Testing event clk_set_rate_complete: OK [ 154.752294] Testing event clk_set_rate: OK [ 154.777930] Testing event clk_unprepare_complete: OK [ 154.804641] Testing event clk_unprepare: OK [ 154.830103] Testing event clk_prepare_complete: OK [ 154.856296] Testing event clk_prepare: OK [ 154.882441] Testing event clk_disable_complete: OK [ 154.909921] Testing event clk_disable: OK [ 154.936481] Testing event clk_enable_complete: OK [ 154.962470] Testing event clk_enable: OK [ 154.989929] Testing event rpmh_send_msg: OK [ 155.014712] Testing event rpmh_tx_done: OK [ 155.041666] Testing event regulator_set_voltage_complete: OK [ 155.065699] Testing event regulator_set_voltage: OK [ 155.091758] Testing event regulator_disable_complete: OK [ 155.116744] Testing event regulator_disable: OK [ 155.142202] Testing event regulator_enable_complete: OK [ 155.168321] Testing event regulator_enable_delay: OK [ 155.193779] Testing event regulator_enable: OK [ 155.217861] Testing event urandom_read: OK [ 155.244540] Testing event random_read: OK [ 155.270184] Testing event extract_entropy_user: OK [ 155.296296] Testing event extract_entropy: OK [ 155.321928] Testing event get_random_bytes_arch: OK [ 155.348444] Testing event get_random_bytes: OK [ 155.373474] Testing event xfer_secondary_pool: OK [ 155.397861] Testing event add_disk_randomness: OK [ 155.423771] Testing event add_input_randomness: OK [ 155.448874] Testing event debit_entropy: OK [ 155.473757] Testing event push_to_pool: OK [ 155.500286] Testing event credit_entropy_bits: OK [ 155.526421] Testing event mix_pool_bytes_nolock: OK [ 155.553986] Testing event mix_pool_bytes: OK [ 155.578567] Testing event add_device_randomness: OK [ 155.604217] Testing event io_page_fault: OK [ 155.630621] Testing event unmap: OK [ 155.658143] Testing event map: OK [ 155.684277] Testing event detach_device_from_domain: OK [ 155.709944] Testing event attach_device_to_domain: OK [ 155.736315] Testing event remove_device_from_group: OK [ 155.761958] Testing event add_device_to_group: OK [ 155.788351] Testing event regcache_drop_region: OK [ 155.813800] Testing event regmap_async_complete_done: OK [ 155.837962] Testing event regmap_async_complete_start: OK [ 155.864518] Testing event regmap_async_io_complete: OK [ 155.890163] Testing event regmap_async_write_start: OK [ 155.916764] Testing event regmap_cache_bypass: OK [ 155.942118] Testing event regmap_cache_only: OK [ 155.968441] Testing event regcache_sync: OK [ 155.994583] Testing event regmap_hw_write_done: OK [ 156.022203] Testing event regmap_hw_write_start: OK [ 156.048761] Testing event regmap_hw_read_done: OK [ 156.076000] Testing event regmap_hw_read_start: OK [ 156.101426] Testing event regmap_reg_read_cache: OK [ 156.125854] Testing event regmap_reg_read: OK [ 156.151762] Testing event regmap_reg_write: OK [ 156.176731] Testing event dma_fence_wait_end: OK [ 156.202210] Testing event dma_fence_wait_start: OK [ 156.228299] Testing event dma_fence_signaled: OK [ 156.253936] Testing event dma_fence_enable_signal: OK [ 156.280576] Testing event dma_fence_destroy: OK [ 156.308243] Testing event dma_fence_init: OK [ 156.333448] Testing event dma_fence_emit: OK [ 156.357620] Testing event sync_timeline: OK [ 156.384309] Testing event spi_transfer_stop: OK [ 156.410317] Testing event spi_transfer_start: OK [ 156.437937] Testing event spi_message_done: OK [ 156.462745] Testing event spi_message_start: OK [ 156.489528] Testing event spi_message_submit: OK [ 156.513963] Testing event spi_controller_busy: OK [ 156.540261] Testing event spi_controller_idle: OK [ 156.566467] Testing event mdio_access: OK [ 156.593891] Testing event rtc_timer_fired: OK [ 156.620319] Testing event rtc_timer_dequeue: OK [ 156.648348] Testing event rtc_timer_enqueue: OK [ 156.674058] Testing event rtc_read_offset: OK [ 156.700329] Testing event rtc_set_offset: OK [ 156.726355] Testing event rtc_alarm_irq_enable: OK [ 156.754068] Testing event rtc_irq_set_state: OK [ 156.780461] Testing event rtc_irq_set_freq: OK [ 156.805437] Testing event rtc_read_alarm: OK [ 156.829913] Testing event rtc_set_alarm: OK [ 156.856254] Testing event rtc_read_time: OK [ 156.882386] Testing event rtc_set_time: OK [ 156.909901] Testing event memory_failure_event: OK [ 156.934747] Testing event aer_event: OK [ 156.961653] Testing event non_standard_event: OK [ 156.985741] Testing event arm_event: OK [ 157.012304] Testing event mc_event: OK [ 157.037679] Testing event binder_return: OK [ 157.064472] Testing event binder_command: OK [ 157.090164] Testing event binder_unmap_kernel_end: OK [ 157.116479] Testing event binder_unmap_kernel_start: OK [ 157.143986] Testing event binder_unmap_user_end: OK [ 157.169498] Testing event binder_unmap_user_start: OK [ 157.193814] Testing event binder_alloc_page_end: OK [ 157.220328] Testing event binder_alloc_page_start: OK [ 157.246363] Testing event binder_free_lru_end: OK [ 157.273934] Testing event binder_free_lru_start: OK [ 157.298775] Testing event binder_alloc_lru_end: OK [ 157.325678] Testing event binder_alloc_lru_start: OK [ 157.349883] Testing event binder_update_page_range: OK [ 157.375780] Testing event binder_transaction_failed_buffer_release: OK [ 157.400924] Testing event binder_transaction_buffer_release: OK [ 157.425929] Testing event binder_transaction_alloc_buf: OK [ 157.452285] Testing event binder_transaction_fd_recv: OK [ 157.478323] Testing event binder_transaction_fd_send: OK [ 157.504760] Testing event binder_transaction_ref_to_ref: OK [ 157.530160] Testing event binder_transaction_ref_to_node: OK [ 157.556362] Testing event binder_transaction_node_to_ref: OK [ 157.581801] Testing event binder_transaction_received: OK [ 157.605927] Testing event binder_transaction: OK [ 157.632478] Testing event binder_wait_for_work: OK [ 157.658138] Testing event binder_read_done: OK [ 157.684839] Testing event binder_write_done: OK [ 157.710294] Testing event binder_ioctl_done: OK [ 157.736450] Testing event binder_unlock: OK [ 157.761607] Testing event binder_locked: OK [ 157.786130] Testing event binder_lock: OK [ 157.810518] Testing event binder_ioctl: OK [ 157.835984] Testing event br_fdb_update: OK [ 157.860702] Testing event fdb_delete: OK [ 157.885292] Testing event br_fdb_external_learn_add: OK [ 157.911721] Testing event br_fdb_add: OK [ 157.934729] Testing event qdisc_dequeue: OK [ 157.960656] Testing event fib_table_lookup: OK [ 157.986099] Testing event tcp_probe: OK [ 158.012273] Testing event tcp_retransmit_synack: OK [ 158.038414] Testing event tcp_rcv_space_adjust: OK [ 158.065814] Testing event tcp_destroy_sock: OK [ 158.090545] Testing event tcp_receive_reset: OK [ 158.115983] Testing event tcp_send_reset: OK [ 158.141737] Testing event tcp_retransmit_skb: OK [ 158.169002] Testing event udp_fail_queue_rcv_skb: OK [ 158.196570] Testing event inet_sock_set_state: OK [ 158.222240] Testing event sock_exceed_buf_limit: OK [ 158.248435] Testing event sock_rcvqueue_full: OK [ 158.274783] Testing event napi_poll: OK [ 158.302664] Testing event netif_rx_ni_entry: OK [ 158.329909] Testing event netif_rx_entry: OK [ 158.356315] Testing event netif_receive_skb_list_entry: OK [ 158.382405] Testing event netif_receive_skb_entry: OK [ 158.409936] Testing event napi_gro_receive_entry: OK [ 158.434711] Testing event napi_gro_frags_entry: OK [ 158.460086] Testing event netif_rx: OK [ 158.485612] Testing event netif_receive_skb: OK [ 158.512988] Testing event net_dev_queue: OK [ 158.538042] Testing event net_dev_xmit: OK [ 158.564467] Testing event net_dev_start_xmit: OK [ 158.590135] Testing event skb_copy_datagram_iovec: OK [ 158.616438] Testing event consume_skb: OK [ 158.642548] Testing event kfree_skb: OK [ 158.670259] Testing event 9p_protocol_dump: OK [ 158.701797] Testing event 9p_client_res: OK [ 158.728686] Testing event 9p_client_req: OK [ 158.754120] Running tests on trace event systems: [ 158.754616] Testing event system 9p: OK [ 158.791813] Testing event system skb: OK [ 158.820618] Testing event system net: OK [ 158.854140] Testing event system napi: OK [ 158.882521] Testing event system sock: OK [ 158.911909] Testing event system udp: OK [ 158.939952] Testing event system tcp: OK [ 158.972666] Testing event system fib: OK [ 158.999815] Testing event system qdisc: OK [ 159.026663] Testing event system bridge: OK [ 159.056868] Testing event system binder: OK [ 159.104372] Testing event system ras: OK [ 159.134519] Testing event system rtc: OK [ 159.176324] Testing event system mdio: OK [ 159.202498] Testing event system spi: OK [ 159.237065] Testing event system sync_trace: OK [ 159.264204] Testing event system dma_fence: OK [ 159.297365] Testing event system regmap: OK [ 159.336390] Testing event system iommu: OK [ 159.368274] Testing event system random: OK [ 159.406391] Testing event system regulator: OK [ 159.437108] Testing event system rpmh: OK [ 159.466474] Testing event system clk: OK [ 159.509193] Testing event system gpio: OK [ 159.549191] Testing event system wbt: OK [ 159.578742] Testing event system block: OK [ 159.617266] Testing event system filelock: OK [ 159.650141] Testing event system fs_dax: OK [ 159.686734] Testing event system writeback: OK [ 159.734614] Testing event system page_ref: OK [ 159.922295] Testing event system cma: OK [ 159.953138] Testing event system page_isolation: OK [ 159.978608] Testing event system huge_memory: OK [ 160.010630] Testing event system migrate: OK [ 160.038059] Testing event system compaction: OK [ 160.076845] Testing event system kmem: OK [ 160.124005] Testing event system percpu: OK [ 160.153032] Testing event system vmscan: OK [ 160.191860] Testing event system pagemap: OK [ 160.218777] Testing event system oom: OK [ 160.254558] Testing event system filemap: OK [ 160.285058] Testing event system rseq: OK [ 160.314457] Testing event system xdp: OK [ 160.350673] Testing event system benchmark: OK [ 160.473814] Testing event system rpm: OK [ 160.507829] Testing event system power: OK [ 160.562606] Testing event system test: OK [ 160.589886] Testing event system preemptirq: OK [ 160.642784] Testing event system cgroup: OK [ 160.675941] Testing event system module: OK [ 160.706621] Testing event system alarmtimer: OK [ 160.735997] Testing event system timer: OK [ 160.777064] Testing event system swiotlb: OK [ 160.804992] Testing event system rcu: OK [ 160.859556] Testing event system printk: OK [ 160.885744] Testing event system lock: OK [ 160.966136] Testing event system sched: OK [ 161.012476] Testing event system workqueue: OK [ 161.046330] Testing event system signal: OK [ 161.078380] Testing event system irq: OK [ 161.110158] Testing event system cpuhp: OK [ 161.140804] Testing event system task: OK [ 161.169403] Testing event system kvm: OK [ 161.221838] Testing event system emulation: OK [ 161.261145] Testing event system ipi: OK [ 161.287946] Testing event system raw_syscalls: OK [ 161.316237] Testing event system initcall: OK [ 161.345911] Running tests on all trace events: [ 161.346320] Testing all events: OK [ 168.407720] hrtimer: interrupt took 9602448 ns [ 370.140760] Running tests again, along with the function tracer [ 370.182454] Running tests on trace events: [ 370.203804] Testing event initcall_finish: OK [ 370.671757] Testing event initcall_start: OK [ 371.228168] Testing event initcall_level: OK [ 372.326731] Testing event sys_exit: OK [ 373.162872] Testing event sys_enter: OK [ 373.970921] Testing event ipi_exit: OK [ 374.456507] Testing event ipi_entry: OK [ 375.332270] Testing event ipi_raise: OK [ 375.911763] Testing event instruction_emulation: OK [ 376.635774] Testing event kvm_halt_poll_ns: OK [ 377.210323] Testing event kvm_age_page: OK [ 377.950553] Testing event kvm_fpu: OK [ 378.754804] Testing event kvm_mmio: OK [ 379.615878] Testing event kvm_ack_irq: OK [ 380.186215] Testing event kvm_set_irq: OK [ 380.912191] Testing event kvm_vcpu_wakeup: OK [ 381.444153] Testing event kvm_userspace_exit: OK [ 381.982931] Testing event kvm_timer_update_irq: OK [ 382.767843] Testing event kvm_toggle_cache: OK [ 383.514838] Testing event kvm_set_way_flush: OK [ 384.151763] Testing event kvm_test_age_hva: OK [ 384.768508] Testing event kvm_age_hva: OK [ 385.550882] Testing event kvm_set_spte_hva: OK [ 386.170934] Testing event kvm_unmap_hva_range: OK [ 386.723183] Testing event kvm_mmio_emulate: OK [ 387.630903] Testing event kvm_irq_line: OK [ 388.198871] Testing event kvm_access_fault: OK [ 388.682872] Testing event kvm_guest_fault: OK [ 389.378952] Testing event kvm_exit: OK [ 390.024255] Testing event kvm_entry: OK [ 390.369471] Testing event kvm_set_guest_debug: OK [ 391.019769] Testing event kvm_handle_sys_reg: OK [ 391.725896] Testing event trap_reg: OK [ 392.273070] Testing event kvm_arm_set_regset: OK [ 393.114843] Testing event kvm_arm_set_dreg32: OK [ 393.913537] Testing event kvm_arm_clear_debug: OK [ 394.493797] Testing event kvm_arm_setup_debug: OK [ 395.149963] Testing event kvm_hvc_arm64: OK [ 395.659825] Testing event kvm_wfx_arm64: OK [ 396.237331] Testing event vgic_update_irq_pending: OK [ 396.957264] Testing event task_rename: OK [ 397.648759] Testing event task_newtask: OK [ 398.256760] Testing event cpuhp_exit: OK [ 398.881527] Testing event cpuhp_multi_enter: OK [ 399.733616] Testing event cpuhp_enter: OK [ 400.421675] Testing event softirq_raise: OK [ 401.117075] Testing event softirq_exit: OK [ 401.668486] Testing event softirq_entry: OK [ 402.349488] Testing event irq_handler_exit: OK [ 402.885483] Testing event irq_handler_entry: OK [ 403.690703] Testing event signal_deliver: OK [ 404.405553] Testing event signal_generate: OK [ 405.406892] Testing event workqueue_execute_end: OK [ 405.841695] Testing event workqueue_execute_start: OK [ 406.280427] Testing event workqueue_activate_work: OK [ 406.849123] Testing event workqueue_queue_work: OK [ 407.496911] Testing event sched_wake_idle_without_ipi: OK [ 408.058705] Testing event sched_swap_numa: OK [ 409.122916] Testing event sched_stick_numa: OK [ 410.286629] Testing event sched_move_numa: OK [ 410.872527] Testing event sched_process_hang: OK [ 411.514869] Testing event sched_pi_setprio: OK [ 412.390413] Testing event sched_stat_runtime: OK [ 413.203856] Testing event sched_stat_blocked: OK [ 414.094772] Testing event sched_stat_iowait: OK [ 414.744241] Testing event sched_stat_sleep: OK [ 415.437105] Testing event sched_stat_wait: OK [ 416.199755] Testing event sched_process_exec: OK [ 416.808224] Testing event sched_process_fork: OK [ 417.542930] Testing event sched_process_wait: OK [ 418.379974] Testing event sched_wait_task: OK [ 418.982744] Testing event sched_process_exit: OK [ 419.684045] Testing event sched_process_free: OK [ 420.228134] Testing event sched_migrate_task: OK [ 420.621708] Testing event sched_switch: OK [ 421.078890] Testing event sched_wakeup_new: OK [ 421.840203] Testing event sched_wakeup: OK [ 422.636448] Testing event sched_waking: OK [ 423.299731] Testing event sched_kthread_stop_ret: OK [ 423.962066] Testing event sched_kthread_stop: OK [ 424.619890] Testing event lock_acquired: OK [ 425.485531] Testing event lock_contended: OK [ 426.156193] Testing event lock_release: OK [ 426.526889] Testing event lock_acquire: OK [ 427.091989] Testing event console: OK [ 427.716265] Testing event rcu_barrier: OK [ 428.228124] Testing event rcu_torture_read: OK [ 428.838677] Testing event rcu_batch_end: OK [ 429.377326] Testing event rcu_invoke_kfree_callback: OK [ 430.018735] Testing event rcu_invoke_callback: OK [ 430.715791] Testing event rcu_batch_start: OK [ 431.186521] Testing event rcu_kfree_callback: OK [ 431.983749] Testing event rcu_callback: OK [ 432.424237] Testing event rcu_dyntick: OK [ 432.965892] Testing event rcu_fqs: OK [ 434.024132] Testing event rcu_quiescent_state_report: OK [ 434.807791] Testing event rcu_unlock_preempted_task: OK [ 435.414684] Testing event rcu_preempt_task: OK [ 435.956819] Testing event rcu_exp_funnel_lock: OK [ 436.780830] Testing event rcu_exp_grace_period: OK [ 437.499761] Testing event rcu_grace_period_init: OK [ 438.144307] Testing event rcu_future_grace_period: OK [ 438.890692] Testing event rcu_grace_period: OK [ 439.686734] Testing event rcu_utilization: OK [ 440.253863] Testing event swiotlb_bounced: OK [ 440.842886] Testing event tick_stop: OK [ 441.457064] Testing event itimer_expire: OK [ 441.948039] Testing event itimer_state: OK [ 442.429258] Testing event hrtimer_cancel: OK [ 443.078804] Testing event hrtimer_expire_exit: OK [ 443.678719] Testing event hrtimer_expire_entry: OK [ 444.240888] Testing event hrtimer_start: OK [ 444.925901] Testing event hrtimer_init: OK [ 445.491761] Testing event timer_cancel: OK [ 446.185046] Testing event timer_expire_exit: OK [ 446.693832] Testing event timer_expire_entry: OK [ 447.218000] Testing event timer_start: OK [ 447.953961] Testing event timer_init: OK [ 448.320963] Testing event alarmtimer_cancel: OK [ 448.922084] Testing event alarmtimer_start: OK [ 449.662829] Testing event alarmtimer_fired: OK [ 450.395867] Testing event alarmtimer_suspend: OK [ 450.990922] Testing event module_request: OK [ 451.653777] Testing event module_put: OK [ 452.590768] Testing event module_get: OK [ 453.426644] Testing event module_free: OK [ 454.068759] Testing event module_load: OK [ 454.777546] Testing event cgroup_transfer_tasks: OK [ 455.405700] Testing event cgroup_attach_task: OK [ 456.127752] Testing event cgroup_rename: OK [ 456.878934] Testing event cgroup_release: OK [ 457.453297] Testing event cgroup_rmdir: OK [ 458.206883] Testing event cgroup_mkdir: OK [ 458.929325] Testing event cgroup_remount: OK [ 459.902119] Testing event cgroup_destroy_root: OK [ 460.589389] Testing event cgroup_setup_root: OK [ 461.331783] Testing event irq_enable: OK [ 462.770878] Testing event irq_disable: OK [ 464.057830] Testing event ftrace_test_filter: OK [ 464.837183] Testing event dev_pm_qos_remove_request: OK [ 465.386221] Testing event dev_pm_qos_update_request: OK [ 465.930922] Testing event dev_pm_qos_add_request: OK [ 466.687762] Testing event pm_qos_update_flags: OK [ 467.530923] Testing event pm_qos_update_target: OK [ 468.145592] Testing event pm_qos_update_request_timeout: OK [ 468.694870] Testing event pm_qos_remove_request: OK [ 469.451529] Testing event pm_qos_update_request: OK [ 470.293188] Testing event pm_qos_add_request: OK [ 470.931798] Testing event power_domain_target: OK [ 471.717956] Testing event clock_set_rate: OK [ 472.077894] Testing event clock_disable: OK [ 472.961289] Testing event clock_enable: OK [ 473.765880] Testing event wakeup_source_deactivate: OK [ 474.510861] Testing event wakeup_source_activate: OK [ 475.199722] Testing event suspend_resume: OK [ 476.193955] Testing event device_pm_callback_end: OK [ 476.880476] Testing event device_pm_callback_start: OK [ 477.588347] Testing event cpu_frequency_limits: OK [ 478.580713] Testing event cpu_frequency: OK [ 479.028244] Testing event pstate_sample: OK [ 479.924231] Testing event powernv_throttle: OK [ 480.398733] Testing event cpu_idle: OK [ 480.965593] Testing event rpm_return_int: OK [ 481.558957] Testing event rpm_idle: OK [ 482.208846] Testing event rpm_resume: OK [ 482.591788] Testing event rpm_suspend: OK [ 483.507805] Testing event benchmark_event: OK [ 484.888549] Testing event xdp_devmap_xmit: OK [ 485.826899] Testing event xdp_cpumap_enqueue: OK [ 486.592832] Testing event xdp_cpumap_kthread: OK [ 487.194929] Testing event xdp_redirect_map_err: OK [ 487.971530] Testing event xdp_redirect_map: OK [ 488.568408] Testing event xdp_redirect_err: OK [ 489.195761] Testing event xdp_redirect: OK [ 489.903851] Testing event xdp_exception: OK [ 490.485137] Testing event rseq_ip_fixup: OK [ 491.032045] Testing event rseq_update: OK [ 491.642842] Testing event file_check_and_advance_wb_err: OK [ 492.752916] Testing event filemap_set_wb_err: OK [ 493.326946] Testing event mm_filemap_add_to_page_cache: OK [ 494.052447] Testing event mm_filemap_delete_from_page_cache: OK [ 494.613415] Testing event compact_retry: OK [ 495.382829] Testing event skip_task_reaping: OK [ 496.262694] Testing event finish_task_reaping: OK [ 496.840763] Testing event start_task_reaping: OK [ 497.610594] Testing event wake_reaper: OK [ 498.214861] Testing event mark_victim: OK [ 498.630877] Testing event reclaim_retry_zone: OK [ 499.528367] Testing event oom_score_adj_update: OK [ 500.294768] Testing event mm_lru_activate: OK [ 501.110048] Testing event mm_lru_insertion: OK [ 501.715760] Testing event mm_vmscan_inactive_list_is_low: OK [ 502.137486] Testing event mm_vmscan_lru_shrink_active: OK [ 502.786941] Testing event mm_vmscan_lru_shrink_inactive: OK [ 503.318935] Testing event mm_vmscan_writepage: OK [ 504.355761] Testing event mm_vmscan_lru_isolate: OK [ 505.085109] Testing event mm_shrink_slab_end: OK [ 505.757050] Testing event mm_shrink_slab_start: OK [ 506.446784] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 507.272058] Testing event mm_vmscan_memcg_reclaim_end: OK [ 508.171981] Testing event mm_vmscan_direct_reclaim_end: OK [ 508.893111] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 509.661081] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 510.288069] Testing event mm_vmscan_direct_reclaim_begin: OK [ 511.148709] Testing event mm_vmscan_wakeup_kswapd: OK [ 511.888567] Testing event mm_vmscan_kswapd_wake: OK [ 512.636289] Testing event mm_vmscan_kswapd_sleep: OK [ 513.182899] Testing event percpu_destroy_chunk: OK [ 513.976320] Testing event percpu_create_chunk: OK [ 514.348942] Testing event percpu_alloc_percpu_fail: OK [ 515.123944] Testing event percpu_free_percpu: OK [ 515.756332] Testing event percpu_alloc_percpu: OK [ 516.545048] Testing event mm_page_alloc_extfrag: OK [ 517.136316] Testing event mm_page_pcpu_drain: OK [ 517.908487] Testing event mm_page_alloc_zone_locked: OK [ 518.446671] Testing event mm_page_alloc: OK [ 519.218873] Testing event mm_page_free_batched: OK [ 520.060217] Testing event mm_page_free: OK [ 520.814839] Testing event kmem_cache_free: OK [ 521.586687] Testing event kfree: OK [ 522.245829] Testing event kmem_cache_alloc_node: OK [ 523.078890] Testing event kmalloc_node: OK [ 523.744082] Testing event kmem_cache_alloc: OK [ 524.554442] Testing event kmalloc: OK [ 525.655430] Testing event mm_compaction_kcompactd_wake: OK [ 526.558066] Testing event mm_compaction_wakeup_kcompactd: OK [ 527.178881] Testing event mm_compaction_kcompactd_sleep: OK [ 527.917989] Testing event mm_compaction_defer_reset: OK [ 528.616845] Testing event mm_compaction_defer_compaction: OK [ 529.221412] Testing event mm_compaction_deferred: OK [ 529.885658] Testing event mm_compaction_suitable: OK [ 530.439780] Testing event mm_compaction_finished: OK [ 530.976309] Testing event mm_compaction_try_to_compact_pages: OK [ 531.677969] Testing event mm_compaction_end: OK [ 532.382761] Testing event mm_compaction_begin: OK [ 533.288953] Testing event mm_compaction_migratepages: OK [ 533.861254] Testing event mm_compaction_isolate_freepages: OK [ 534.361667] Testing event mm_compaction_isolate_migratepages: OK [ 535.121626] Testing event mm_migrate_pages: OK [ 535.775913] Testing event mm_collapse_huge_page_swapin: OK [ 536.349089] Testing event mm_collapse_huge_page_isolate: OK [ 537.161446] Testing event mm_collapse_huge_page: OK [ 538.020384] Testing event mm_khugepaged_scan_pmd: OK [ 538.571931] Testing event test_pages_isolated: OK [ 539.360223] Testing event cma_release: OK [ 539.954869] Testing event cma_alloc: OK [ 540.738909] Testing event page_ref_unfreeze: OK [ 541.508806] Testing event page_ref_freeze: OK [ 542.172505] Testing event page_ref_mod_unless: OK [ 543.226812] Testing event page_ref_mod_and_return: OK [ 544.111722] Testing event page_ref_mod_and_test: OK [ 546.681307] Testing event page_ref_mod: OK [ 548.298894] Testing event page_ref_set: OK [ 548.788518] Testing event sb_clear_inode_writeback: OK [ 549.646871] Testing event sb_mark_inode_writeback: OK [ 550.324259] Testing event writeback_dirty_inode_enqueue: OK [ 551.188389] Testing event writeback_lazytime_iput: OK [ 552.205061] Testing event writeback_lazytime: OK [ 553.020714] Testing event writeback_single_inode: OK [ 553.453615] Testing event writeback_single_inode_start: OK [ 554.229217] Testing event writeback_wait_iff_congested: OK [ 555.003824] Testing event writeback_congestion_wait: OK [ 555.768387] Testing event writeback_sb_inodes_requeue: OK [ 556.474963] Testing event balance_dirty_pages: OK [ 557.016557] Testing event bdi_dirty_ratelimit: OK [ 557.601781] Testing event global_dirty_state: OK [ 558.150896] Testing event writeback_queue_io: OK [ 558.754900] Testing event wbc_writepage: OK [ 559.612237] Testing event writeback_bdi_register: OK [ 560.272740] Testing event writeback_wake_background: OK [ 560.928457] Testing event writeback_pages_written: OK [ 561.533465] Testing event writeback_wait: OK [ 562.190562] Testing event writeback_written: OK [ 562.987804] Testing event writeback_start: OK [ 563.698922] Testing event writeback_exec: OK [ 564.293490] Testing event writeback_queue: OK [ 565.093407] Testing event writeback_write_inode: OK [ 565.722745] Testing event writeback_write_inode_start: OK [ 566.638708] Testing event writeback_dirty_inode: OK [ 567.192270] Testing event writeback_dirty_inode_start: OK [ 567.821235] Testing event writeback_mark_inode_dirty: OK [ 568.426847] Testing event writeback_dirty_page: OK [ 569.212440] Testing event dax_writeback_one: OK [ 570.113568] Testing event dax_writeback_range_done: OK [ 570.887840] Testing event dax_writeback_range: OK [ 571.735801] Testing event dax_insert_mapping: OK [ 572.232333] Testing event dax_insert_pfn_mkwrite: OK [ 572.879806] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 573.445746] Testing event dax_load_hole: OK [ 574.116355] Testing event dax_pte_fault_done: OK [ 574.818751] Testing event dax_pte_fault: OK [ 575.562758] Testing event dax_pmd_insert_mapping: OK [ 576.164946] Testing event dax_pmd_load_hole_fallback: OK [ 576.821000] Testing event dax_pmd_load_hole: OK [ 577.472322] Testing event dax_pmd_fault_done: OK [ 577.908634] Testing event dax_pmd_fault: OK [ 578.411797] Testing event generic_add_lease: OK [ 579.480536] Testing event time_out_leases: OK [ 580.090922] Testing event generic_delete_lease: OK [ 580.665565] Testing event break_lease_unblock: OK [ 581.288656] Testing event break_lease_block: OK [ 581.930931] Testing event break_lease_noblock: OK [ 582.509930] Testing event flock_lock_inode: OK [ 582.969257] Testing event locks_remove_posix: OK [ 583.720615] Testing event fcntl_setlk: OK [ 584.338961] Testing event posix_lock_inode: OK [ 584.969489] Testing event locks_get_lock_context: OK [ 585.732855] Testing event block_rq_remap: OK [ 586.294778] Testing event block_bio_remap: OK [ 586.938682] Testing event block_split: OK [ 587.713088] Testing event block_unplug: OK [ 588.159773] Testing event block_plug: OK [ 588.797366] Testing event block_sleeprq: OK [ 589.201279] Testing event block_getrq: OK [ 590.120039] Testing event block_bio_queue: OK [ 590.767857] Testing event block_bio_frontmerge: OK [ 591.250629] Testing event block_bio_backmerge: OK [ 591.958708] Testing event block_bio_complete: OK [ 592.587977] Testing event block_bio_bounce: OK [ 593.391989] Testing event block_rq_issue: OK [ 593.816616] Testing event block_rq_insert: OK [ 594.883765] Testing event block_rq_complete: OK [ 595.497922] Testing event block_rq_requeue: OK [ 596.175803] Testing event block_dirty_buffer: OK [ 596.802753] Testing event block_touch_buffer: OK [ 597.457519] Testing event wbt_timer: OK [ 598.284794] Testing event wbt_step: OK [ 598.738820] Testing event wbt_lat: OK [ 599.582757] Testing event wbt_stat: OK [ 600.094897] Testing event gpio_value: OK [ 600.754713] Testing event gpio_direction: OK [ 601.186869] Testing event clk_set_duty_cycle_complete: OK [ 602.448216] Testing event clk_set_duty_cycle: OK [ 603.096237] Testing event clk_set_phase_complete: OK [ 603.748142] Testing event clk_set_phase: OK [ 604.310674] Testing event clk_set_parent_complete: OK [ 604.916441] Testing event clk_set_parent: OK [ 605.225479] Testing event clk_set_rate_complete: OK [ 605.755773] Testing event clk_set_rate: OK [ 606.555717] Testing event clk_unprepare_complete: OK [ 607.152450] Testing event clk_unprepare: OK [ 607.522820] Testing event clk_prepare_complete: OK [ 608.178821] Testing event clk_prepare: OK [ 608.856592] Testing event clk_disable_complete: OK [ 609.406926] Testing event clk_disable: OK [ 609.822481] Testing event clk_enable_complete: OK [ 610.436517] Testing event clk_enable: OK [ 611.002850] Testing event rpmh_send_msg: OK [ 611.776997] Testing event rpmh_tx_done: OK [ 612.197701] Testing event regulator_set_voltage_complete: OK [ 612.958948] Testing event regulator_set_voltage: OK [ 613.500800] Testing event regulator_disable_complete: OK [ 614.266900] Testing event regulator_disable: OK [ 615.017658] Testing event regulator_enable_complete: OK [ 615.654834] Testing event regulator_enable_delay: OK [ 616.282857] Testing event regulator_enable: OK [ 617.054723] Testing event urandom_read: OK [ 617.937433] Testing event random_read: OK [ 618.552610] Testing event extract_entropy_user: OK [ 619.082888] Testing event extract_entropy: OK [ 619.860041] Testing event get_random_bytes_arch: OK [ 620.368280] Testing event get_random_bytes: OK [ 620.882764] Testing event xfer_secondary_pool: OK [ 621.813272] Testing event add_disk_randomness: OK [ 622.529805] Testing event add_input_randomness: OK [ 623.002826] Testing event debit_entropy: OK [ 623.670897] Testing event push_to_pool: OK [ 624.366890] Testing event credit_entropy_bits: OK [ 625.278865] Testing event mix_pool_bytes_nolock: OK [ 625.675798] Testing event mix_pool_bytes: OK [ 626.167833] Testing event add_device_randomness: OK [ 626.841066] Testing event io_page_fault: OK [ 627.530917] Testing event unmap: OK [ 628.279709] Testing event map: OK [ 628.908814] Testing event detach_device_from_domain: OK [ 629.505878] Testing event attach_device_to_domain: OK [ 630.018895] Testing event remove_device_from_group: OK [ 630.590892] Testing event add_device_to_group: OK [ 631.580492] Testing event regcache_drop_region: OK [ 632.074932] Testing event regmap_async_complete_done: OK [ 632.713222] Testing event regmap_async_complete_start: OK [ 633.622902] Testing event regmap_async_io_complete: OK [ 634.544354] Testing event regmap_async_write_start: OK [ 635.033559] Testing event regmap_cache_bypass: OK [ 635.533689] Testing event regmap_cache_only: OK [ 636.090856] Testing event regcache_sync: OK [ 636.673892] Testing event regmap_hw_write_done: OK [ 637.565960] Testing event regmap_hw_write_start: OK [ 638.241817] Testing event regmap_hw_read_done: OK [ 638.722764] Testing event regmap_hw_read_start: OK [ 639.382894] Testing event regmap_reg_read_cache: OK [ 640.103778] Testing event regmap_reg_read: OK [ 640.578922] Testing event regmap_reg_write: OK [ 641.509472] Testing event dma_fence_wait_end: OK [ 642.687856] Testing event dma_fence_wait_start: OK [ 643.311761] Testing event dma_fence_signaled: OK [ 644.144634] Testing event dma_fence_enable_signal: OK [ 644.782358] Testing event dma_fence_destroy: OK [ 645.610003] Testing event dma_fence_init: OK [ 646.214912] Testing event dma_fence_emit: OK [ 646.774510] Testing event sync_timeline: OK [ 647.226487] Testing event spi_transfer_stop: OK [ 647.961066] Testing event spi_transfer_start: OK [ 648.698875] Testing event spi_message_done: OK [ 649.426051] Testing event spi_message_start: OK [ 649.820524] Testing event spi_message_submit: OK [ 650.613598] Testing event spi_controller_busy: OK [ 651.371828] Testing event spi_controller_idle: OK [ 651.876358] Testing event mdio_access: OK [ 652.476657] Testing event rtc_timer_fired: OK [ 653.337342] Testing event rtc_timer_dequeue: OK [ 653.969976] Testing event rtc_timer_enqueue: OK [ 654.482940] Testing event rtc_read_offset: OK [ 655.091839] Testing event rtc_set_offset: OK [ 655.918277] Testing event rtc_alarm_irq_enable: OK [ 656.509774] Testing event rtc_irq_set_state: OK [ 657.093293] Testing event rtc_irq_set_freq: OK [ 657.781098] Testing event rtc_read_alarm: OK [ 658.583786] Testing event rtc_set_alarm: OK [ 659.205560] Testing event rtc_read_time: OK [ 659.782843] Testing event rtc_set_time: OK [ 660.730833] Testing event memory_failure_event: OK [ 661.624690] Testing event aer_event: OK [ 662.260231] Testing event non_standard_event: OK [ 662.968145] Testing event arm_event: OK [ 663.812094] Testing event mc_event: OK [ 664.620454] Testing event binder_return: OK [ 665.155922] Testing event binder_command: OK [ 665.928231] Testing event binder_unmap_kernel_end: OK [ 666.601308] Testing event binder_unmap_kernel_start: OK [ 667.300869] Testing event binder_unmap_user_end: OK [ 667.976786] Testing event binder_unmap_user_start: OK [ 668.405100] Testing event binder_alloc_page_end: OK [ 669.258785] Testing event binder_alloc_page_start: OK [ 669.868973] Testing event binder_free_lru_end: OK [ 670.665151] Testing event binder_free_lru_start: OK [ 671.146393] Testing event binder_alloc_lru_end: OK [ 671.917368] Testing event binder_alloc_lru_start: OK [ 672.540308] Testing event binder_update_page_range: OK [ 673.373387] Testing event binder_transaction_failed_buffer_release: OK [ 673.966789] Testing event binder_transaction_buffer_release: OK [ 674.750848] Testing event binder_transaction_alloc_buf: OK [ 675.363816] Testing event binder_transaction_fd_recv: OK [ 675.929433] Testing event binder_transaction_fd_send: OK [ 676.400445] Testing event binder_transaction_ref_to_ref: OK [ 676.835957] Testing event binder_transaction_ref_to_node: OK [ 677.608536] Testing event binder_transaction_node_to_ref: OK [ 678.413812] Testing event binder_transaction_received: OK [ 679.000216] Testing event binder_transaction: OK [ 679.728448] Testing event binder_wait_for_work: OK [ 680.712811] Testing event binder_read_done: OK [ 681.616057] Testing event binder_write_done: OK [ 682.210435] Testing event binder_ioctl_done: OK [ 683.122381] Testing event binder_unlock: OK [ 683.680517] Testing event binder_locked: OK [ 684.153031] Testing event binder_lock: OK [ 684.676762] Testing event binder_ioctl: OK [ 685.592507] Testing event br_fdb_update: OK [ 686.170773] Testing event fdb_delete: OK [ 686.688035] Testing event br_fdb_external_learn_add: OK [ 687.234949] Testing event br_fdb_add: OK [ 688.172211] Testing event qdisc_dequeue: OK [ 688.774546] Testing event fib_table_lookup: OK [ 689.338978] Testing event tcp_probe: OK [ 690.073763] Testing event tcp_retransmit_synack: OK [ 690.770859] Testing event tcp_rcv_space_adjust: OK [ 691.301886] Testing event tcp_destroy_sock: OK [ 691.909977] Testing event tcp_receive_reset: OK [ 692.440049] Testing event tcp_send_reset: OK [ 693.077739] Testing event tcp_retransmit_skb: OK [ 693.739807] Testing event udp_fail_queue_rcv_skb: OK [ 694.246914] Testing event inet_sock_set_state: OK [ 694.967755] Testing event sock_exceed_buf_limit: OK [ 695.610792] Testing event sock_rcvqueue_full: OK [ 696.370626] Testing event napi_poll: OK [ 697.239773] Testing event netif_rx_ni_entry: OK [ 698.000217] Testing event netif_rx_entry: OK [ 698.898935] Testing event netif_receive_skb_list_entry: OK [ 699.825621] Testing event netif_receive_skb_entry: OK [ 700.305727] Testing event napi_gro_receive_entry: OK [ 700.852822] Testing event napi_gro_frags_entry: OK [ 701.762861] Testing event netif_rx: OK [ 702.650796] Testing event netif_receive_skb: OK [ 703.438036] Testing event net_dev_queue: OK [ 703.908337] Testing event net_dev_xmit: OK [ 704.711810] Testing event net_dev_start_xmit: OK [ 705.207749] Testing event skb_copy_datagram_iovec: OK [ 705.934499] Testing event consume_skb: OK [ 706.708049] Testing event kfree_skb: OK [ 707.382841] Testing event 9p_protocol_dump: OK [ 708.396225] Testing event 9p_client_res: OK [ 709.183987] Testing event 9p_client_req: OK [ 710.006937] Running tests on trace event systems: [ 710.032827] Testing event system 9p: OK [ 711.002858] Testing event system skb: OK [ 711.966073] Testing event system net: OK [ 712.678866] Testing event system napi: OK [ 713.476151] Testing event system sock: OK [ 714.231989] Testing event system udp: OK [ 714.898602] Testing event system tcp: OK [ 715.960089] Testing event system fib: OK [ 716.706120] Testing event system qdisc: OK [ 717.338613] Testing event system bridge: OK [ 718.184144] Testing event system binder: OK [ 719.442947] Testing event system ras: OK [ 720.510735] Testing event system rtc: OK [ 721.708265] Testing event system mdio: OK [ 722.218642] Testing event system spi: OK [ 723.008763] Testing event system sync_trace: OK [ 723.955824] Testing event system dma_fence: OK [ 724.722889] Testing event system regmap: OK [ 726.094914] Testing event system iommu: OK [ 726.969937] Testing event system random: OK [ 728.124379] Testing event system regulator: OK [ 729.230878] Testing event system rpmh: OK [ 729.977343] Testing event system clk: OK [ 731.367970] Testing event system gpio: OK [ 732.534873] Testing event system wbt: OK [ 733.428478] Testing event system block: OK [ 734.458891] Testing event system filelock: OK [ 735.714892] Testing event system fs_dax: OK [ 736.712077] Testing event system writeback: OK [ 738.068038] Testing event system page_ref: OK [ 742.074738] Testing event system cma: OK [ 742.999798] Testing event system page_isolation: OK [ 743.532660] Testing event system huge_memory: OK [ 744.251775] Testing event system migrate: OK [ 744.943725] Testing event system compaction: OK [ 746.026814] Testing event system kmem: OK [ 747.348573] Testing event system percpu: OK [ 748.157183] Testing event system vmscan: OK [ 749.338765] Testing event system pagemap: OK [ 750.148067] Testing event system oom: OK [ 750.918780] Testing event system filemap: OK [ 751.928060] Testing event system rseq: OK [ 752.674920] Testing event system xdp: OK [ 753.634838] Testing event system benchmark: OK [ 754.706919] Testing event system rpm: OK [ 755.450830] Testing event system power: OK [ 756.762736] Testing event system test: OK [ 757.765399] Testing event system preemptirq: OK [ 759.768737] Testing event system cgroup: OK [ 760.778495] Testing event system module: OK [ 761.557378] Testing event system alarmtimer: OK [ 762.424173] Testing event system timer: OK [ 763.563800] Testing event system swiotlb: OK [ 764.369504] Testing event system rcu: OK [ 765.540146] Testing event system printk: OK [ 766.323789] Testing event system lock: OK [ 766.714830] Testing event system sched: OK [ 768.096118] Testing event system workqueue: OK [ 768.984526] Testing event system signal: OK [ 769.702525] Testing event system irq: OK [ 770.517721] Testing event system cpuhp: OK [ 771.087968] Testing event system task: OK [ 771.535316] Testing event system kvm: OK [ 772.865362] Testing event system emulation: OK [ 774.060508] Testing event system ipi: OK [ 774.718799] Testing event system raw_syscalls: OK [ 775.259767] Testing event system initcall: OK [ 776.132570] Running tests on all trace events: [ 776.139026] Testing all events: OK [ 798.253592] Testing ftrace filter: OK [ 798.369222] trace_kprobe: Testing kprobe tracing: OK [ 798.739938] Loading compiled-in X.509 certificates [ 798.846864] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 798.849092] Loaded X.509 cert 'Build time autogenerated kernel key: 4054958e39d9099bc90ea5e944013505afd1c71c' [ 798.852972] page_owner is disabled [ 798.977511] Key type big_key registered [ 799.032155] Key type encrypted registered [ 799.034300] ima: No TPM chip found, activating TPM-bypass! [ 799.035730] ima: Allocated hash algorithm: sha1 [ 799.039416] No architecture policies found [ 799.044816] random: get_random_bytes called from prandom_seed_full_state+0xc0/0x198 with crng_init=0 [ 799.069760] hctosys: unable to open rtc device (rtc0) [ 799.085844] psci_checker: PSCI checker started using 1 CPUs [ 799.086347] psci_checker: Starting hotplug tests [ 799.087604] psci_checker: Trying to turn off and on again all CPUs [ 799.088552] psci_checker: Trying to turn off and on again group 0 (CPUs 0) [ 799.089422] psci_checker: Hotplug tests passed OK [ 799.089922] psci_checker: Starting suspend tests (10 cycles per state) [ 799.090670] psci_checker: cpuidle not available on CPU 0, ignoring [ 799.092043] psci_checker: Could not start suspend tests on any CPU [ 799.092719] psci_checker: PSCI checker completed [ 799.138332] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 799.338436] ### dt-test ### start of unittest - you will see error messages [ 799.366208] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 799.367689] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 799.368628] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 799.369404] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 799.370283] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 799.371654] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 799.389290] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 799.391685] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 799.393502] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 799.805586] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status [ 799.854563] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status [ 799.877717] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status [ 799.925146] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status [ 800.000149] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status [ 800.066208] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status [ 800.109632] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status [ 800.186309] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status [ 800.229293] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo [ 800.231615] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 800.232409] OF: overlay: overlay #6 is not topmost [ 800.500704] OF: overlay: remove: Could not find overlay #7 [ 800.501269] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #7 [ 800.730327] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/status [ 800.735850] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/status [ 800.737922] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@30/incline-up [ 800.739759] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@40/incline-up [ 800.752208] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/status [ 800.753291] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/color [ 800.754626] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/rate [ 800.756734] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/hvac_2 [ 800.758029] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200 [ 800.759944] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_left [ 800.761255] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_right [ 800.865007] OF: overlay: ERROR: multiple fragments add and/or delete node /testcase-data-2/substation@100/motor-1/controller [ 800.868894] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/controller/name [ 800.880431] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 800.881709] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 800.883519] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/rpm_avail [ 800.900970] ### dt-test ### end of unittest - 217 passed, 0 failed [ 800.920338] uart-pl011 9000000.pl011: no DMA platform data [ 801.279606] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 801.280550] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 801.323610] VFS: Mounted root (9p filesystem) readonly on device 0:18. [ 801.343612] devtmpfs: mounted [ 802.048432] Freeing unused kernel memory: 4096K [ 802.070403] Checked W+X mappings: passed, no W+X pages found [ 802.071700] rodata_test: all tests were successful [ 802.072584] Run /sbin/init as init process [ 802.193456] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=0 [ 802.301831] _warn_unseeded_randomness: 4 callbacks suppressed [ 802.302025] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 803.176142] random: fast init done [ 806.452627] systemd[1]: System time before build time, advancing clock. [ 806.945745] systemd[1]: Failed to insert module 'autofs4': No such file or directory [ 810.333210] random: systemd: uninitialized urandom read (16 bytes read) [ 810.708698] random: systemd: uninitialized urandom read (16 bytes read) [ 810.713332] random: systemd: uninitialized urandom read (16 bytes read) [ 816.217654] 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) [ 816.297454] systemd[1]: Detected virtualization qemu. [ 816.305486] systemd[1]: Detected architecture arm64. Welcome to Debian GNU/Linux 9 (stretch)! [ 816.458812] systemd[1]: Set hostname to . [ 816.654117] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=1 [ 816.717195] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 817.376168] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 817.929407] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 817.930325] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 817.931954] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 819.042252] _warn_unseeded_randomness: 20 callbacks suppressed [ 819.042448] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 819.044458] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 819.046249] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 820.352627] random: systemd: uninitialized urandom read (16 bytes read) [ 820.444426] _warn_unseeded_randomness: 9 callbacks suppressed [ 820.444625] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 820.445679] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 820.447946] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 822.245456] _warn_unseeded_randomness: 10 callbacks suppressed [ 822.245652] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 822.246898] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 822.248193] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 824.522510] systemd-hiberna (1259) used greatest stack depth: 10576 bytes left [ 826.229029] random: systemd-cryptse: uninitialized urandom read (16 bytes read) [ 826.309779] systemd-getty-g (1262) used greatest stack depth: 10400 bytes left [ 826.904083] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 828.002545] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 829.514239] systemd-sysv-ge (1264) used greatest stack depth: 9664 bytes left [ 829.821280] random: systemd: uninitialized urandom read (16 bytes read) [ 830.069820] random: systemd: uninitialized urandom read (16 bytes read) [ 830.074255] random: systemd: uninitialized urandom read (16 bytes read) [ 837.765049] systemd[1]: networking.service: Cannot add dependency job, ignoring: Unit networking.service is masked. [ 837.838491] _warn_unseeded_randomness: 6 callbacks suppressed [ 837.838694] random: get_random_bytes called from generate_random_uuid+0x20/0x50 with crng_init=1 [ 837.902473] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ OK ] Listening on /dev/initctl Compatibility Named Pipe. [ 837.970619] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 838.001149] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 838.024152] systemd[1]: Listening on Syslog Socket. [ OK ] Listening on Syslog Socket. [ 838.068994] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ 838.094675] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 838.114324] systemd[1]: Reached target Remote File Systems. [ OK ] Reached target Remote File Systems. [ 838.209528] systemd[1]: Created slice System Slice. [ OK ] Created slice System Slice. [ 838.226305] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 838.430275] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 Mounting Debug File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-getty.slice. [ OK ] Reached target Swap. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [UNSUPP] Starting of Arbitrary Executable Fi…tem Automount Point not supported. [ OK ] Listening on Journal Audit Socket. [ 839.208430] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 839.209300] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 839.210100] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 Mounting Huge Pages File System... [ OK ] Reached target Encrypted Volumes. [ 840.750477] _warn_unseeded_randomness: 7 callbacks suppressed [ 840.750679] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 840.752477] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 840.753266] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 Mounting POSIX Message Queue File System... [ OK ] Listening on Journal Socket. [ OK ] Reached target Sockets. [ 842.337050] _warn_unseeded_randomness: 11 callbacks suppressed [ 842.337246] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 842.338256] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 842.410077] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 843.181819] systemd[1]: Starting Journal Service... Starting Journal Service... [ 844.307692] _warn_unseeded_randomness: 4 callbacks suppressed [ 844.307891] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 844.450288] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 844.451739] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 845.082151] systemd[1]: Starting Create Static Device Nodes in /dev... Starting Create Static Device Nodes in /dev... [ 846.650877] _warn_unseeded_randomness: 7 callbacks suppressed [ 846.652305] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 846.844429] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 846.845329] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 847.336230] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 849.100978] _warn_unseeded_randomness: 5 callbacks suppressed [ 849.101246] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 849.233575] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 849.234467] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 849.837326] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 851.773839] _warn_unseeded_randomness: 3 callbacks suppressed [ 851.774037] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 851.775662] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 851.842152] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 852.913193] _warn_unseeded_randomness: 2 callbacks suppressed [ 852.913457] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 853.358790] systemd[1]: Mounted Debug File System. [ OK ] Mounted Debug File System. [ 853.452955] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 853.557553] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 853.817464] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 854.721536] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 854.722456] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 854.724080] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 857.848749] _warn_unseeded_randomness: 2 callbacks suppressed [ 857.848947] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 858.040293] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 858.041272] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 858.438618] systemd[1]: Started Create Static Device Nodes in /dev. [ OK ] Started Create Static Device Nodes in /dev. [ 859.296002] systemd[1]: Started Load Kernel Modules. [ OK ] Started Load Kernel Modules. [ 860.298515] _warn_unseeded_randomness: 6 callbacks suppressed [ 860.298739] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 860.552648] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 860.891853] systemd[1]: Mounting Configuration File System... Mounting Configuration File System... [ 861.174895] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 861.496200] _warn_unseeded_randomness: 3 callbacks suppressed [ 861.496422] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 862.694366] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 863.513830] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 863.718341] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 863.719744] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 863.720530] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 864.935887] _warn_unseeded_randomness: 3 callbacks suppressed [ 864.936086] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 865.056562] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 865.057511] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 865.702703] systemd[1]: Starting udev Kernel Device Manager... Starting udev Kernel Device Manager... [ 868.083707] _warn_unseeded_randomness: 2 callbacks suppressed [ 868.083907] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 868.084970] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 868.154459] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 869.224347] systemd[1]: Started Remount Root and Kernel File Systems. [ OK ] Started Remount Root and Kernel File Systems. [ 869.376613] _warn_unseeded_randomness: 3 callbacks suppressed [ 869.376811] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 869.589241] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 869.590089] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 869.656377] systemd[1]: Mounted Configuration File System. [ OK ] Mounted Configuration File System. [ 870.513155] systemd[1]: Started Apply Kernel Variables. [ OK ] Started Apply Kernel Variables. [ 872.357755] _warn_unseeded_randomness: 4 callbacks suppressed [ 872.357954] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 872.498365] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 873.149678] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 873.370090] systemd[1]: Reached target Local File Systems (Pre). [ OK ] Reached target Local File Systems (Pre). [ 873.542052] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 874.861071] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 874.861944] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 874.862611] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 875.771867] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 876.084342] _warn_unseeded_randomness: 2 callbacks suppressed [ 876.084536] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 877.578649] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 877.580028] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 877.580703] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 878.928815] _warn_unseeded_randomness: 3 callbacks suppressed [ 878.929005] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [* ] (1 of 5) A start job is running for…d/Save Random Seed (41s / 1min 4s)[ 879.713591] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 879.940265] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 879.941139] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 879.941802] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 880.327655] systemd[1]: Started Load/Save Random Seed. [ OK ] Started Load/Save Random Seed. [ 885.232065] 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 (52s / 30min) [*** ] (1 of 3) A start job is running for dev-ttyAMA0.device (53s / 30min) [ *** ] (2 of 3) A start job is running for Journal Service (53s / 30min 4s) [ *** ] (2 of 3) A start job is running for Journal Service (54s / 30min 4s) [ ***] (2 of 3) A start job is running for Journal Service (54s / 30min 4s) [ **] (3 of 3) A start job is running for…dplug all Devices (55s / no limit) [ *] (3 of 3) A start job is running for…dplug all Devices (55s / no limit) [ **] (3 of 3) A start job is running for…dplug all Devices (56s / no limit) [ ***] (1 of 3) A start job is running for dev-ttyAMA0.device (56s / 30min) [ *** ] (1 of 3) A start job is running for dev-ttyAMA0.device (57s / 30min)[ 895.067431] _warn_unseeded_randomness: 2 callbacks suppressed [ 895.067635] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [ *** ] (1 of 3) A start job is running for dev-ttyAMA0.device (57s / 30min) [*** ] (2 of 3) A start job is running for Journal Service (58s / 30min 4s) [** ] (2 of 3) A start job is running for Journal Service (58s / 30min 4s)[ 896.581855] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [ 897.173555] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 Starting Flush Journal to Persistent Storage... [ 898.794239] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 898.795726] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 898.796609] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [ 901.426221] systemd-journald[1272]: Received request to flush runtime journal from PID 1 [* ] (2 of 3) A start job is running for…v-ttyAMA0.device (1min 4s / 30min)[ 901.968319] _warn_unseeded_randomness: 3 callbacks suppressed [ 901.968518] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 902.136806] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 902.137878] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ OK ] Started Flush Journal to Persistent Storage. [ 903.585236] _warn_unseeded_randomness: 3 callbacks suppressed [ 903.585425] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 Starting Create Volatile Files and Directories... [ 906.405263] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 906.406153] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 906.407503] random: get_random_u32 called from arch_align_stack+0x40/0x60 with crng_init=1 [** ] (3 of 3) A start job is running for…g all Devices (1min 9s / no limit) [*** ] (3 of 3) A start job is running for… all Devices (1min 10s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 10s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (1min 11s / no limit)[ 909.278178] random: crng init done [ 909.278642] random: 6 get_random_xx warning(s) missed due to ratelimiting [ ***] (1 of 3) A start job is running for… Directories (1min 11s / no limit) [ **] (1 of 3) A start job is running for… Directories (1min 12s / no limit) [ *] (2 of 3) A start job is running for…-ttyAMA0.device (1min 12s / 30min) [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 13s / 30min) [ ***] (2 of 3) A start job is running for…-ttyAMA0.device (1min 13s / 30min) [ *** ] (3 of 3) A start job is running for… all Devices (1min 14s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 14s / no limit) [*** ] (3 of 3) A start job is running for… all Devices (1min 15s / no limit) [** ] (1 of 3) A start job is running for… Directories (1min 15s / no limit) [* ] (1 of 3) A start job is running for… Directories (1min 16s / no limit) [** ] (1 of 3) A start job is running for… Directories (1min 16s / no limit) [*** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 18s / 30min) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 19s / 30min) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 19s / 30min) [ ***] (3 of 3) A start job is running for… all Devices (1min 20s / no limit) [ **] (3 of 3) A start job is running for… all Devices (1min 20s / no limit) [ *] (3 of 3) A start job is running for… all Devices (1min 21s / no limit) [ **] (1 of 3) A start job is running for… Directories (1min 21s / no limit) [ ***] (1 of 3) A start job is running for… Directories (1min 22s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (1min 22s / no limit) [ *** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 23s / 30min) [*** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 23s / 30min) [** ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 24s / 30min) [* ] (3 of 3) A start job is running for… all Devices (1min 24s / no limit) [** ] (3 of 3) A start job is running for… all Devices (1min 25s / no limit) [*** ] (3 of 3) A start job is running for… all Devices (1min 25s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (1min 26s / no limit) [ *** ] (1 of 3) A start job is running for… Directories (1min 26s / no limit) [ ***] (1 of 3) A start job is running for… Directories (1min 27s / no limit) [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 27s / 30min) [ *] (2 of 3) A start job is running for…-ttyAMA0.device (1min 28s / 30min) [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 28s / 30min) [ ***] (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) [ *** ] (3 of 3) A start job is running for… all Devices (1min 30s / 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 37s / no limit) [** ] (3 of 4) A start job is running for… all Devices (1min 38s / no limit) [* ] (3 of 4) A start job is running for… all Devices (1min 39s / no limit) [** ] (4 of 4) A start job is running for…oot/Shutdown (1min 39s / no limit) [*** ] (4 of 4) A start job is running for…oot/Shutdown (1min 40s / no limit) [ *** ] (4 of 4) A start job is running for…oot/Shutdown (1min 40s / no limit) [ *** ] (1 of 4) A start job is running for…hronization (1min 41s / 31min 33s) [ ***] (1 of 4) A start job is running for…hronization (1min 41s / 31min 33s) [ **] (1 of 4) A start job is running for…hronization (1min 42s / 31min 33s) [ *] (2 of 4) A start job is running for…-ttyAMA0.device (1min 42s / 30min) [ **] (2 of 4) A start job is running for…-ttyAMA0.device (1min 43s / 30min) [ ***] (2 of 4) A start job is running for…-ttyAMA0.device (1min 43s / 30min) [ *** ] (3 of 4) A start job is running for… all Devices (1min 44s / no limit) [ *** ] (3 of 4) A start job is running for… all Devices (1min 45s / no limit) [*** ] (3 of 4) A start job is running for… all Devices (1min 45s / no limit) [ OK ] Started Update UTMP about System Boot/Shutdown. [** ] (3 of 3) A start job is running for… all Devices (1min 51s / no limit) [* ] (3 of 3) A start job is running for… all Devices (1min 52s / no limit) [** ] (3 of 3) A start job is running for… all Devices (1min 52s / no limit) [*** ] (1 of 3) A start job is running for…hronization (1min 53s / 31min 33s) [ *** ] (1 of 3) A start job is running for…hronization (1min 53s / 31min 33s) [ *** ] (1 of 3) A start job is running for…hronization (1min 54s / 31min 33s) [ ***] (2 of 3) A start job is running for…-ttyAMA0.device (1min 54s / 30min) [ **] (2 of 3) A start job is running for…-ttyAMA0.device (1min 55s / 30min) [ *] (2 of 3) A start job is running for…-ttyAMA0.device (1min 55s / 30min) [ **] (3 of 3) A start job is running for… all Devices (1min 56s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (1min 56s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 57s / no limit) [ *** ] (1 of 3) A start job is running for…hronization (1min 57s / 31min 33s) [*** ] (1 of 3) A start job is running for…hronization (1min 58s / 31min 33s) [** ] (1 of 3) A start job is running for…hronization (1min 58s / 31min 33s) [* ] (2 of 3) A start job is running for…-ttyAMA0.device (1min 59s / 30min) [ OK ] Started Network Time Synchronization. [ OK ] Reached target System Time Synchronized. [** ] (1 of 2) A start job is running for…v-ttyAMA0.device (2min 5s / 30min) [*** ] (1 of 2) A start job is running for…v-ttyAMA0.device (2min 5s / 30min) [ *** ] (2 of 2) A start job is running for…g all Devices (2min 6s / no limit) [ *** ] (2 of 2) A start job is running for…g all Devices (2min 6s / no limit) [ ***] (2 of 2) A start job is running for…g all Devices (2min 7s / no limit) [ **] (1 of 2) A start job is running for…v-ttyAMA0.device (2min 7s / 30min) [ *] (1 of 2) A start job is running for…v-ttyAMA0.device (2min 8s / 30min) [ **] (1 of 2) A start job is running for…v-ttyAMA0.device (2min 8s / 30min) [ ***] (2 of 2) A start job is running for…g all Devices (2min 9s / no limit) [ *** ] (2 of 2) A start job is running for…g all Devices (2min 9s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (2min 10s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 10s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 11s / 30min) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 11s / 30min) [** ] (2 of 2) A start job is running for… all Devices (2min 12s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (2min 12s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (2min 13s / no limit) [ *** ] (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 14s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (2min 14s / 30min) [ *] (2 of 2) A start job is running for… all Devices (2min 15s / no limit) [ **] (2 of 2) A start job is running for… all Devices (2min 15s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (2min 16s / no limit) [ *** ] (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 17s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 17s / 30min) [** ] (2 of 2) A start job is running for… all Devices (2min 18s / no limit) [* ] (2 of 2) A start job is running for… all Devices (2min 18s / no limit) [** ] (2 of 2) A start job is running for… all Devices (2min 19s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 19s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 20s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 20s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (2min 21s / no limit) [ **] (2 of 2) A start job is running for… all Devices (2min 21s / no limit) [ *] (2 of 2) A start job is running for… all Devices (2min 22s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (2min 22s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (2min 23s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 23s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (2min 24s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (2min 24s / no limit) [** ] (2 of 2) A start job is running for… all Devices (2min 25s / no limit) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 25s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 26s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 26s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (2min 27s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (2min 27s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (2min 28s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (2min 28s / 30min) [ *] (1 of 2) A start job is running for…-ttyAMA0.device (2min 29s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (2min 29s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (2min 30s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (2min 30s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (2min 31s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 31s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 32s / 30min) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 32s / 30min) [** ] (2 of 2) A start job is running for… all Devices (2min 33s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (2min 33s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (2min 34s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 34s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (2min 35s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (2min 35s / 30min) [ *] (2 of 2) A start job is running for… all Devices (2min 36s / no limit) [ **] (2 of 2) A start job is running for… all Devices (2min 36s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (2min 37s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 37s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 38s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 38s / 30min) [** ] (2 of 2) A start job is running for… all Devices (2min 39s / no limit) [* ] (2 of 2) A start job is running for… all Devices (2min 40s / no limit) [** ] (2 of 2) A start job is running for… all Devices (2min 41s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 41s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 42s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 42s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (2min 43s / no limit) [ **] (2 of 2) A start job is running for… all Devices (2min 43s / no limit) [ *] (2 of 2) A start job is running for… all Devices (2min 44s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (2min 44s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (2min 45s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 45s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (2min 46s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (2min 46s / no limit) [** ] (2 of 2) A start job is running for… all Devices (2min 47s / no limit) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 47s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 48s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 48s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (2min 49s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (2min 49s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (2min 50s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (2min 50s / 30min) [ *] (1 of 2) A start job is running for…-ttyAMA0.device (2min 51s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (2min 51s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (2min 52s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (2min 52s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (2min 53s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 53s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 54s / 30min) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 54s / 30min) [** ] (2 of 2) A start job is running for… all Devices (2min 55s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (2min 55s / no limit) [ *** ] (2 of 2) A start job is running for… all Devices (2min 56s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 56s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (2min 57s / 30min) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (2min 57s / 30min) [ *] (2 of 2) A start job is running for… all Devices (2min 58s / no limit) [ **] (2 of 2) A start job is running for… all Devices (2min 58s / no limit) [ ***] (2 of 2) A start job is running for… all Devices (2min 59s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (2min 59s / 30min) [ *** ] (1 of 2) A start job is running for dev-ttyAMA0.device (3min / 30min) [*** ] (1 of 2) A start job is running for dev-ttyAMA0.device (3min / 30min) [** ] (2 of 2) A start job is running for…g all Devices (3min 1s / no limit) [* ] (2 of 2) A start job is running for…g all Devices (3min 1s / no limit) [** ] (2 of 2) A start job is running for…g all Devices (3min 2s / no limit) [*** ] (1 of 2) A start job is running for…v-ttyAMA0.device (3min 2s / 30min) [ *** ] (1 of 2) A start job is running for…v-ttyAMA0.device (3min 3s / 30min) [ *** ] (1 of 2) A start job is running for…v-ttyAMA0.device (3min 3s / 30min) [ ***] (2 of 2) A start job is running for…g all Devices (3min 4s / no limit) [ **] (2 of 2) A start job is running for…g all Devices (3min 4s / no limit) [ *] (2 of 2) A start job is running for…g all Devices (3min 5s / no limit)[ 1023.377450] virtio_net virtio0 enp0s1: renamed from eth0 [ **] (1 of 2) A start job is running for…v-ttyAMA0.device (3min 5s / 30min) [ ***] (1 of 2) A start job is running for…v-ttyAMA0.device (3min 6s / 30min) [ *** ] (1 of 2) A start job is running for…v-ttyAMA0.device (3min 6s / 30min) [ *** ] (2 of 2) A start job is running for…g all Devices (3min 7s / no limit) [*** ] (2 of 2) A start job is running for…g all Devices (3min 7s / no limit) [ OK ] Found device /dev/ttyAMA0. [ 1028.865508] systemd-journald[1272]: /dev/kmsg buffer overrun, some messages lost. [ 1029.357657] systemd-journald[1272]: /dev/kmsg buffer overrun, some messages lost. [ 1029.372742] systemd-journald[1272]: /dev/kmsg buffer overrun, some messages lost. [ 1029.875910] systemd-journald[1272]: /dev/kmsg buffer overrun, some messages lost. [ 1030.370012] systemd-journald[1272]: /dev/kmsg buffer overrun, some messages lost. [ 1030.862257] systemd-journald[1272]: /dev/kmsg buffer overrun, some messages lost. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Basic System. Starting Permit User Sessions... Starting getty on tty2-tty6 if dbus and logind are not available... [ OK ] Started Daily apt upgrade and clean activities. [ OK ] Reached target Timers. [ OK ] Started Regular background program processing daemon. Starting System Logging Service... [ OK ] Started Permit User Sessions. [** ] (2 of 2) A start job is running for…ot available (3min 37s / no limit) [ OK ] Started System Logging Service. [* ] A start job is running for getty on…ot available (3min 48s / no limit) [** ] A start job is running for getty on…ot available (3min 53s / no limit) [*** ] A start job is running for getty on…ot available (3min 55s / no limit) [ *** ] A start job is running for getty on…ot available (3min 58s / no limit) [ *** ] A start job is running for getty on…not available (4min 1s / no limit) [ ***] A start job is running for getty on…not available (4min 2s / no limit) [ OK ] Started Getty on tty6. [ OK ] Started Getty on tty5. [ OK ] Started Getty on tty4. [ OK ] Started Getty on tty3. [ OK ] Started Getty on tty2. Starting Cleanup of Temporary Directories... [ OK ] Started Serial Getty on ttyAMA0. [ OK ] Started Getty on tty1. [ OK ] Started getty on tty2-tty6 if dbus and logind are not available. [ 1105.992952] systemd[1]: systemd-journald.service: Main process exited, code=killed, status=6/ABRT Debian GNU/Linux 9 compilator ttyAMA0 compilator login: [ 1107.366729] systemd[1]: systemd-journald.service: Unit entered failed state. [ 1107.496323] systemd[1]: systemd-journald.service: Failed with result 'watchdog'. [ 1108.128604] systemd[1]: systemd-journald.service: Service has no hold-off time, scheduling restart. [ 1110.090630] systemd[1]: Stopped Flush Journal to Persistent Storage. [ 1110.137144] systemd[1]: Stopping Flush Journal to Persistent Storage... [ 1110.138828] systemd[1]: Stopped Journal Service. [ 1111.885986] systemd[1]: Starting Journal Service... [ 1115.717249] systemd[1]: Started Cleanup of Temporary Directories. [ 1117.409837] systemd[1]: Started Update UTMP about System Runlevel Changes.