[ 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)) #41 SMP Mon Nov 26 12:44:04 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: 998884K/2097152K available (14256K kernel code, 3356K rwdata, 4652K rodata, 4096K init, 21768K bss, 84644K 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 41387 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.000160] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.012137] Console: colour dummy device 80x25 [ 0.013061] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.013358] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.013594] ... MAX_LOCK_DEPTH: 48 [ 0.013831] ... MAX_LOCKDEP_KEYS: 8191 [ 0.014070] ... CLASSHASH_SIZE: 4096 [ 0.014308] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.014555] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.014795] ... CHAINHASH_SIZE: 32768 [ 0.015036] memory used by lock dependency info: 7775 kB [ 0.015291] per task-struct memory footprint: 2688 bytes [ 0.015568] ------------------------ [ 0.015789] | Locking API testsuite: [ 0.016013] ---------------------------------------------------------------------------- [ 0.016512] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.016799] -------------------------------------------------------------------------- [ 0.017407] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.027554] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.037378] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.047547] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.057969] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.068736] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.079468] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.090394] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.099548] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.108256] -------------------------------------------------------------------------- [ 0.108820] recursive read-lock: | ok | | ok | [ 0.111617] recursive read-lock #2: | ok | | ok | [ 0.114633] mixed read-write-lock: | ok | | ok | [ 0.117650] mixed write-read-lock: | ok | | ok | [ 0.120435] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.123359] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.126500] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.129407] -------------------------------------------------------------------------- [ 0.129926] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.134308] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.138702] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.143062] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.147465] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.151851] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.156202] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.160570] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.164928] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.169304] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.173660] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.178179] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.182677] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.187189] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.191700] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.196267] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.200777] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.205288] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.209792] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.214201] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.219209] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.223707] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.228185] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.232781] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.237335] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.241900] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.246480] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.251032] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.255577] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.260175] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.264725] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.269302] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.274116] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.278652] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.283484] hard-irq lock-inversion/123: ok | ok | ok | [ 0.288053] soft-irq lock-inversion/123: ok | ok | ok | [ 0.292639] hard-irq lock-inversion/132: ok | ok | ok | [ 0.297222] soft-irq lock-inversion/132: ok | ok | ok | [ 0.301814] hard-irq lock-inversion/213: ok | ok | ok | [ 0.306390] soft-irq lock-inversion/213: ok | ok | ok | [ 0.310990] hard-irq lock-inversion/231: ok | ok | ok | [ 0.315740] soft-irq lock-inversion/231: ok | ok | ok | [ 0.320431] hard-irq lock-inversion/312: ok | ok | ok | [ 0.325063] soft-irq lock-inversion/312: ok | ok | ok | [ 0.329640] hard-irq lock-inversion/321: ok | ok | ok | [ 0.334188] soft-irq lock-inversion/321: ok | ok | ok | [ 0.338724] hard-irq read-recursion/123: ok | [ 0.340631] soft-irq read-recursion/123: ok | [ 0.342298] hard-irq read-recursion/132: ok | [ 0.343971] soft-irq read-recursion/132: ok | [ 0.345871] hard-irq read-recursion/213: ok | [ 0.347537] soft-irq read-recursion/213: ok | [ 0.349203] hard-irq read-recursion/231: ok | [ 0.351101] soft-irq read-recursion/231: ok | [ 0.352773] hard-irq read-recursion/312: ok | [ 0.354425] soft-irq read-recursion/312: ok | [ 0.356324] hard-irq read-recursion/321: ok | [ 0.357982] soft-irq read-recursion/321: ok | [ 0.359651] -------------------------------------------------------------------------- [ 0.359934] | Wound/wait tests | [ 0.360401] --------------------- [ 0.360628] ww api failures: ok | ok | ok | [ 0.366379] ww contexts mixing: ok | ok | [ 0.369408] finishing ww context: ok | ok | ok | ok | [ 0.375189] locking mismatches: ok | ok | ok | [ 0.379480] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.395074] spinlock nest unlocked: ok | [ 0.396894] ----------------------------------------------------- [ 0.397166] |block | try |context| [ 0.397435] ----------------------------------------------------- [ 0.397704] context: ok | ok | ok | [ 0.402333] try: ok | ok | ok | [ 0.406561] block: ok | ok | ok | [ 0.411186] spinlock: ok | ok | ok | [ 0.416030] ------------------------------------------------------- [ 0.416394] Good, all 261 testcases passed! | [ 0.416688] --------------------------------- [ 0.420816] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.421539] pid_max: default: 32768 minimum: 301 [ 0.429805] LSM: Security Framework initializing [ 0.430376] Yama: becoming mindful. [ 0.432668] LoadPin: ready to pin (currently enforcing) [ 0.433336] AppArmor: AppArmor disabled by boot time parameter [ 0.448747] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.454051] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.455981] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.456963] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.484027] *** VALIDATE proc *** [ 0.498202] *** VALIDATE cgroup1 *** [ 0.498638] *** VALIDATE cgroup2 *** [ 0.548129] ASID allocator initialised with 32768 entries [ 0.552970] rcu: Hierarchical SRCU implementation. [ 0.599543] EFI services will not be available. [ 0.617731] smp: Bringing up secondary CPUs ... [ 0.618229] smp: Brought up 1 node, 1 CPU [ 0.618591] SMP: Total of 1 processors activated. [ 0.618994] CPU features: detected: 32-bit EL0 Support [ 0.619399] CPU features: detected: CRC32 instructions [ 0.840852] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 0.841872] CPU: All CPU(s) started at EL1 [ 0.842606] alternatives: patching kernel code [ 1.312558] node 0 initialised, 249310 pages in 4ms [ 1.319722] pgdatinit0 (15) used greatest stack depth: 13408 bytes left [ 1.373290] devtmpfs: initialized [ 1.525362] Registered cp15_barrier emulation handler [ 2.422614] workqueue: round-robin CPU selection forced, expect performance impact [ 4.950484] DMA-API: preallocated 65536 debug entries [ 4.950933] DMA-API: debugging enabled by kernel config [ 4.951434] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 4.952573] futex hash table entries: 256 (order: 3, 32768 bytes) [ 4.954712] Running postponed tracer tests: [ 4.958849] Testing tracer function: PASSED [ 17.287832] Testing dynamic ftrace: PASSED [ 18.385633] Testing dynamic ftrace ops #1: [ 21.784012] (1 0 1 0 0) [ 21.784475] (1 1 2 0 0) [ 29.749352] (2 1 3 0 701557) [ 29.750280] (2 2 4 0 701668) PASSED [ 35.947985] Testing dynamic ftrace ops #2: [ 47.044220] (1 0 1 691423 0) [ 47.045176] (1 1 2 691553 0) [ 47.053153] (2 1 3 1 485) [ 47.053850] (2 2 4 117 601) PASSED [ 52.724160] Testing ftrace recursion: PASSED [ 53.597925] Testing ftrace recursion safe: PASSED [ 54.470034] Testing ftrace regs(no arch support): PASSED [ 55.343832] Testing tracer nop: PASSED [ 55.344509] Testing tracer irqsoff: PASSED [ 67.688719] Testing tracer function_graph: PASSED [ 80.077247] _warn_unseeded_randomness: 86 callbacks suppressed [ 80.078496] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 80.079307] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 80.080275] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 80.087879] prandom: seed boundary self test passed [ 80.110526] prandom: 100 self tests passed [ 80.114033] pinctrl core: initialized pinctrl subsystem [ 80.166070] regulator-dummy: Failed to create debugfs directory [ 80.170862] [ 80.171110] ************************************************************* [ 80.171504] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 80.172224] ** ** [ 80.172591] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 80.172958] ** ** [ 80.173320] ** This means that this kernel is built to expose internal ** [ 80.173677] ** IOMMU data structures, which may compromise security on ** [ 80.174033] ** your system. ** [ 80.174389] ** ** [ 80.174779] ** If you see this message and you are not debugging the ** [ 80.175164] ** kernel, report this immediately to your vendor! ** [ 80.175530] ** ** [ 80.176219] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 80.176597] ************************************************************* [ 80.189312] DMI not present or invalid. [ 80.212287] NET: Registered protocol family 16 [ 80.228094] audit: initializing netlink subsys (disabled) [ 80.297112] cpuidle: using governor ladder [ 80.297723] cpuidle: using governor menu [ 80.305891] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 80.306629] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 80.310168] audit: type=2000 audit(72.092:1): state=initialized audit_enabled=0 res=1 [ 80.345873] DMA: preallocated 256 KiB pool for atomic allocations [ 80.382759] Serial: AMBA PL011 UART driver [ 80.753976] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 80.756223] printk: console [ttyAMA0] enabled [ 80.756223] printk: console [ttyAMA0] enabled [ 80.757469] printk: bootconsole [pl11] disabled [ 80.757469] printk: bootconsole [pl11] disabled [ 81.108443] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 81.144991] _warn_unseeded_randomness: 68 callbacks suppressed [ 81.145158] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 81.149145] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 81.149960] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 81.209705] arm-v7s io-pgtable: self test ok [ 81.210343] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 81.215615] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 81.218438] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 81.221616] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 81.225114] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 81.227822] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 81.231077] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 81.236343] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 81.242386] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 81.248247] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 81.254089] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 81.260316] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 81.267072] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 81.283758] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 81.300360] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 81.317604] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 81.336616] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 81.355578] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 81.374669] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 81.390912] vgaarb: loaded [ 81.418852] pps_core: LinuxPPS API ver. 1 registered [ 81.419379] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 81.421584] PTP clock support registered [ 81.504667] NetLabel: Initializing [ 81.505127] NetLabel: domain hash size = 128 [ 81.505556] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 81.510971] NetLabel: unlabeled traffic allowed by default [ 81.521554] clocksource: Switched to clocksource arch_sys_counter [ 82.842724] _warn_unseeded_randomness: 8 callbacks suppressed [ 82.842898] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 82.844100] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 82.845810] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 82.853011] VFS: Disk quotas dquot_6.6.0 [ 82.855460] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 82.862527] *** VALIDATE hugetlbfs *** [ 83.290497] NET: Registered protocol family 2 [ 83.314799] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 83.317569] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 83.326057] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 83.334709] TCP: Hash tables configured (established 16384 bind 16384) [ 83.339307] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 83.342370] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 83.350682] NET: Registered protocol family 1 [ 83.399595] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 83.404193] kvm [1]: HYP mode not available [ 83.424380] kworker/u2:1 (34) used greatest stack depth: 11920 bytes left [ 83.542937] Initialise system trusted keyrings [ 83.550153] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 84.559389] _warn_unseeded_randomness: 58 callbacks suppressed [ 84.559554] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 84.561262] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 84.573454] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 84.682879] 9p: Installing v9fs 9p2000 file system support [ 85.104543] Key type asymmetric registered [ 85.106537] Asymmetric key parser 'x509' registered [ 85.109872] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 85.361355] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 85.365155] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 85.378819] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 85.409002] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 85.439119] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 85.441185] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 85.442799] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 85.443806] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 85.446842] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 85.456538] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 85.457884] pci_bus 0000:00: root bus resource [bus 00-0f] [ 85.458618] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 85.459347] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 85.460008] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 85.505181] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 85.506232] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 85.507322] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 85.508134] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 85.509254] pci 0000:00:02.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 85.509959] pci 0000:00:02.0: BAR 0: assigned [io 0x1000-0x103f] [ 85.510708] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 85.772385] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 85.807624] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 88.449698] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 88.639443] SuperH (H)SCI(F) driver initialized [ 88.680552] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 88.722697] libphy: Fixed MDIO Bus: probed [ 88.813879] _warn_unseeded_randomness: 38 callbacks suppressed [ 88.814058] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=0 [ 89.006790] ledtrig-cpu: registered to indicate activity on CPUs [ 89.024216] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 89.025418] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 89.053515] ashmem: initialized [ 89.159646] 9pnet: Installing 9P2000 support [ 89.398759] registered taskstats version 1 [ 89.399311] Running tests on trace events: [ 89.399824] Testing event initcall_finish: OK [ 89.423845] Testing event initcall_start: OK [ 89.446937] Testing event initcall_level: OK [ 89.471700] Testing event sys_exit: OK [ 89.494373] Testing event sys_enter: OK [ 89.518261] Testing event ipi_exit: OK [ 89.541714] Testing event ipi_entry: OK [ 89.562538] Testing event ipi_raise: OK [ 89.584377] Testing event instruction_emulation: OK [ 89.606396] Testing event kvm_halt_poll_ns: OK [ 89.628093] Testing event kvm_age_page: OK [ 89.649901] Testing event kvm_fpu: OK [ 89.670414] Testing event kvm_mmio: OK [ 89.694276] Testing event kvm_ack_irq: OK [ 89.718521] Testing event kvm_set_irq: OK [ 89.742743] Testing event kvm_vcpu_wakeup: OK [ 89.767452] Testing event kvm_userspace_exit: OK [ 89.790242] Testing event kvm_timer_update_irq: OK [ 89.812479] Testing event kvm_toggle_cache: OK [ 89.834123] Testing event kvm_set_way_flush: OK [ 89.856080] Testing event kvm_test_age_hva: OK [ 89.878133] Testing event kvm_age_hva: OK [ 89.897778] Testing event kvm_set_spte_hva: OK [ 89.919961] Testing event kvm_unmap_hva_range: OK [ 89.941981] Testing event kvm_mmio_emulate: OK [ 89.961854] Testing event kvm_irq_line: OK [ 89.981965] Testing event kvm_access_fault: OK [ 90.003961] Testing event kvm_guest_fault: OK [ 90.025942] Testing event kvm_exit: OK [ 90.046878] Testing event kvm_entry: OK [ 90.070543] Testing event kvm_set_guest_debug: OK [ 90.094492] Testing event kvm_handle_sys_reg: OK [ 90.117818] Testing event trap_reg: OK [ 90.144577] Testing event kvm_arm_set_regset: OK [ 90.166812] Testing event kvm_arm_set_dreg32: OK [ 90.191327] Testing event kvm_arm_clear_debug: OK [ 90.215264] Testing event kvm_arm_setup_debug: OK [ 90.238318] Testing event kvm_hvc_arm64: OK [ 90.261708] Testing event kvm_wfx_arm64: OK [ 90.285548] Testing event vgic_update_irq_pending: OK [ 90.308448] Testing event task_rename: OK [ 90.332407] Testing event task_newtask: OK [ 90.354137] Testing event cpuhp_exit: OK [ 90.378849] Testing event cpuhp_multi_enter: OK [ 90.403057] Testing event cpuhp_enter: OK [ 90.427669] Testing event softirq_raise: OK [ 90.450260] Testing event softirq_exit: OK [ 90.472423] Testing event softirq_entry: OK [ 90.494021] Testing event irq_handler_exit: OK [ 90.517798] Testing event irq_handler_entry: OK [ 90.542991] Testing event signal_deliver: OK [ 90.567280] Testing event signal_generate: OK [ 90.590323] Testing event workqueue_execute_end: OK [ 90.613865] Testing event workqueue_execute_start: OK [ 90.633764] Testing event workqueue_activate_work: OK [ 90.653686] Testing event workqueue_queue_work: OK [ 90.674340] Testing event sched_wake_idle_without_ipi: OK [ 90.698812] Testing event sched_swap_numa: OK [ 90.722571] Testing event sched_stick_numa: OK [ 90.746646] Testing event sched_move_numa: OK [ 90.770442] Testing event sched_process_hang: OK [ 90.793874] Testing event sched_pi_setprio: OK [ 90.816395] Testing event sched_stat_runtime: [ 90.819610] Scheduler tracepoints stat_sleep, stat_iowait, stat_blocked and stat_runtime require the kernel parameter schedstats=enable or kernel.sched_schedstats=1 [ 90.842402] OK [ 90.842889] Testing event sched_stat_blocked: OK [ 90.867552] Testing event sched_stat_iowait: OK [ 90.890494] Testing event sched_stat_sleep: OK [ 90.914598] Testing event sched_stat_wait: OK [ 90.939677] Testing event sched_process_exec: OK [ 90.962268] Testing event sched_process_fork: OK [ 90.985650] Testing event sched_process_wait: OK [ 91.006029] Testing event sched_wait_task: OK [ 91.027941] Testing event sched_process_exit: OK [ 91.050234] Testing event sched_process_free: OK [ 91.070683] Testing event sched_migrate_task: OK [ 91.093575] Testing event sched_switch: OK [ 91.117731] Testing event sched_wakeup_new: OK [ 91.138533] Testing event sched_wakeup: OK [ 91.161826] Testing event sched_waking: OK [ 91.185582] Testing event sched_kthread_stop_ret: OK [ 91.206327] Testing event sched_kthread_stop: OK [ 91.228363] Testing event lock_acquired: OK [ 91.254476] Testing event lock_contended: OK [ 91.273632] Testing event lock_release: OK [ 91.300008] Testing event lock_acquire: OK [ 91.325988] Testing event console: OK [ 91.346694] Testing event rcu_barrier: OK [ 91.369943] Testing event rcu_torture_read: OK [ 91.394458] Testing event rcu_batch_end: OK [ 91.418822] Testing event rcu_invoke_kfree_callback: OK [ 91.443495] Testing event rcu_invoke_callback: OK [ 91.466053] Testing event rcu_batch_start: OK [ 91.488502] Testing event rcu_kfree_callback: OK [ 91.509996] Testing event rcu_callback: OK [ 91.533678] Testing event rcu_dyntick: OK [ 91.559691] Testing event rcu_fqs: OK [ 91.583256] Testing event rcu_quiescent_state_report: OK [ 91.606365] Testing event rcu_unlock_preempted_task: OK [ 91.628266] Testing event rcu_preempt_task: OK [ 91.649946] Testing event rcu_exp_funnel_lock: OK [ 91.673599] Testing event rcu_exp_grace_period: OK [ 91.701616] Testing event rcu_grace_period_init: OK [ 91.722488] Testing event rcu_future_grace_period: OK [ 91.746480] Testing event rcu_grace_period: OK [ 91.772526] Testing event rcu_utilization: OK [ 91.795669] Testing event swiotlb_bounced: OK [ 91.818514] Testing event tick_stop: OK [ 91.842562] Testing event itimer_expire: OK [ 91.866772] Testing event itimer_state: OK [ 91.891685] Testing event hrtimer_cancel: OK [ 91.914444] Testing event hrtimer_expire_exit: OK [ 91.937595] Testing event hrtimer_expire_entry: OK [ 91.959256] Testing event hrtimer_start: OK [ 91.982715] Testing event hrtimer_init: OK [ 92.006774] Testing event timer_cancel: OK [ 92.031696] Testing event timer_expire_exit: OK [ 92.054333] Testing event timer_expire_entry: OK [ 92.077669] Testing event timer_start: OK [ 92.098108] Testing event timer_init: OK [ 92.117458] Testing event alarmtimer_cancel: OK [ 92.138386] Testing event alarmtimer_start: OK [ 92.162472] Testing event alarmtimer_fired: OK [ 92.185831] Testing event alarmtimer_suspend: OK [ 92.208323] Testing event module_request: OK [ 92.230101] Testing event module_put: OK [ 92.252402] Testing event module_get: OK [ 92.276466] Testing event module_free: OK [ 92.298017] Testing event module_load: OK [ 92.319903] Testing event cgroup_transfer_tasks: OK [ 92.342070] Testing event cgroup_attach_task: OK [ 92.362552] Testing event cgroup_rename: OK [ 92.384441] Testing event cgroup_release: OK [ 92.406167] Testing event cgroup_rmdir: OK [ 92.429747] Testing event cgroup_mkdir: OK [ 92.454686] Testing event cgroup_remount: OK [ 92.479565] Testing event cgroup_destroy_root: OK [ 92.502760] Testing event cgroup_setup_root: OK [ 92.526488] Testing event irq_enable: OK [ 92.552021] Testing event irq_disable: OK [ 92.575574] Testing event ftrace_test_filter: OK [ 92.598337] Testing event dev_pm_qos_remove_request: OK [ 92.621700] Testing event dev_pm_qos_update_request: OK [ 92.642183] Testing event dev_pm_qos_add_request: OK [ 92.663934] Testing event pm_qos_update_flags: OK [ 92.685972] Testing event pm_qos_update_target: OK [ 92.706548] Testing event pm_qos_update_request_timeout: OK [ 92.729995] Testing event pm_qos_remove_request: OK [ 92.753646] Testing event pm_qos_update_request: OK [ 92.774615] Testing event pm_qos_add_request: OK [ 92.798721] Testing event power_domain_target: OK [ 92.823386] Testing event clock_set_rate: OK [ 92.846057] Testing event clock_disable: OK [ 92.868166] Testing event clock_enable: OK [ 92.889704] Testing event wakeup_source_deactivate: OK [ 92.912548] Testing event wakeup_source_activate: OK [ 92.936385] Testing event suspend_resume: OK [ 92.967469] Testing event device_pm_callback_end: OK [ 92.990426] Testing event device_pm_callback_start: OK [ 93.013732] Testing event cpu_frequency_limits: OK [ 93.034598] Testing event cpu_frequency: OK [ 93.059072] Testing event pstate_sample: OK [ 93.083386] Testing event powernv_throttle: OK [ 93.106552] Testing event cpu_idle: OK [ 93.131355] Testing event rpm_return_int: OK [ 93.155483] Testing event rpm_idle: OK [ 93.178225] Testing event rpm_resume: OK [ 93.200234] Testing event rpm_suspend: OK [ 93.222019] Testing event benchmark_event: OK [ 93.346199] Testing event xdp_devmap_xmit: OK [ 93.370788] Testing event xdp_cpumap_enqueue: OK [ 93.395439] Testing event xdp_cpumap_kthread: OK [ 93.418120] Testing event xdp_redirect_map_err: OK [ 93.440511] Testing event xdp_redirect_map: OK [ 93.462089] Testing event xdp_redirect_err: OK [ 93.484081] Testing event xdp_redirect: OK [ 93.506099] Testing event xdp_exception: OK [ 93.529902] Testing event rseq_ip_fixup: OK [ 93.550480] Testing event rseq_update: OK [ 93.573601] Testing event file_check_and_advance_wb_err: OK [ 93.594503] Testing event filemap_set_wb_err: OK [ 93.617450] Testing event mm_filemap_add_to_page_cache: OK [ 93.638732] Testing event mm_filemap_delete_from_page_cache: OK [ 93.662587] Testing event compact_retry: OK [ 93.686537] Testing event skip_task_reaping: OK [ 93.710701] Testing event finish_task_reaping: OK [ 93.734575] Testing event start_task_reaping: OK [ 93.758500] Testing event wake_reaper: OK [ 93.782692] Testing event mark_victim: OK [ 93.807426] Testing event reclaim_retry_zone: OK [ 93.830112] Testing event oom_score_adj_update: OK [ 93.852477] Testing event mm_lru_activate: OK [ 93.873968] Testing event mm_lru_insertion: OK [ 93.896019] Testing event mm_vmscan_inactive_list_is_low: OK [ 93.917933] Testing event mm_vmscan_lru_shrink_active: OK [ 93.938623] Testing event mm_vmscan_lru_shrink_inactive: OK [ 93.962413] Testing event mm_vmscan_writepage: OK [ 93.986383] Testing event mm_vmscan_lru_isolate: OK [ 94.010589] Testing event mm_shrink_slab_end: OK [ 94.034537] Testing event mm_shrink_slab_start: OK [ 94.058310] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 94.080397] Testing event mm_vmscan_memcg_reclaim_end: OK [ 94.102054] Testing event mm_vmscan_direct_reclaim_end: OK [ 94.125581] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 94.148389] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 94.170157] Testing event mm_vmscan_direct_reclaim_begin: OK [ 94.193705] Testing event mm_vmscan_wakeup_kswapd: OK [ 94.216303] Testing event mm_vmscan_kswapd_wake: OK [ 94.238099] Testing event mm_vmscan_kswapd_sleep: OK [ 94.261556] Testing event percpu_destroy_chunk: OK [ 94.284260] Testing event percpu_create_chunk: OK [ 94.306224] Testing event percpu_alloc_percpu_fail: OK [ 94.327980] Testing event percpu_free_percpu: OK [ 94.350229] Testing event percpu_alloc_percpu: OK [ 94.370475] Testing event mm_page_alloc_extfrag: OK [ 94.393712] Testing event mm_page_pcpu_drain: OK [ 94.414743] Testing event mm_page_alloc_zone_locked: OK [ 94.439723] Testing event mm_page_alloc: OK [ 94.462251] Testing event mm_page_free_batched: OK [ 94.484322] Testing event mm_page_free: OK [ 94.506203] Testing event kmem_cache_free: OK [ 94.527999] Testing event kfree: OK [ 94.549747] Testing event kmem_cache_alloc_node: OK [ 94.569832] Testing event kmalloc_node: OK [ 94.591109] Testing event kmem_cache_alloc: OK [ 94.614832] Testing event kmalloc: OK [ 94.639631] Testing event mm_compaction_kcompactd_wake: OK [ 94.662329] Testing event mm_compaction_wakeup_kcompactd: OK [ 94.684338] Testing event mm_compaction_kcompactd_sleep: OK [ 94.720563] Testing event mm_compaction_defer_reset: OK [ 94.751433] Testing event mm_compaction_defer_compaction: OK [ 94.774620] Testing event mm_compaction_deferred: OK [ 94.797856] Testing event mm_compaction_suitable: OK [ 94.818644] Testing event mm_compaction_finished: OK [ 94.842157] Testing event mm_compaction_try_to_compact_pages: OK [ 94.865981] Testing event mm_compaction_end: OK [ 94.888471] Testing event mm_compaction_begin: OK [ 94.910070] Testing event mm_compaction_migratepages: OK [ 94.931970] Testing event mm_compaction_isolate_freepages: OK [ 94.953993] Testing event mm_compaction_isolate_migratepages: OK [ 94.974777] Testing event mm_migrate_pages: OK [ 94.998524] Testing event mm_collapse_huge_page_swapin: OK [ 95.024214] Testing event mm_collapse_huge_page_isolate: OK [ 95.047357] Testing event mm_collapse_huge_page: OK [ 95.070618] Testing event mm_khugepaged_scan_pmd: OK [ 95.094559] Testing event test_pages_isolated: OK [ 95.118653] Testing event cma_release: OK [ 95.138470] Testing event cma_alloc: OK [ 95.160074] Testing event page_ref_unfreeze: OK [ 95.183019] Testing event page_ref_freeze: OK [ 95.206397] Testing event page_ref_mod_unless: OK [ 95.232147] Testing event page_ref_mod_and_return: OK [ 95.254060] Testing event page_ref_mod_and_test: OK [ 95.354114] Testing event page_ref_mod: OK [ 95.401280] Testing event page_ref_set: OK [ 95.424545] Testing event sb_clear_inode_writeback: OK [ 95.447102] Testing event sb_mark_inode_writeback: OK [ 95.470404] Testing event writeback_dirty_inode_enqueue: OK [ 95.493962] Testing event writeback_lazytime_iput: OK [ 95.517764] Testing event writeback_lazytime: OK [ 95.538647] Testing event writeback_single_inode: OK [ 95.562174] Testing event writeback_single_inode_start: OK [ 95.586586] Testing event writeback_wait_iff_congested: OK [ 95.610634] Testing event writeback_congestion_wait: OK [ 95.634674] Testing event writeback_sb_inodes_requeue: OK [ 95.658700] Testing event balance_dirty_pages: OK [ 95.683519] Testing event bdi_dirty_ratelimit: OK [ 95.706297] Testing event global_dirty_state: OK [ 95.728426] Testing event writeback_queue_io: OK [ 95.750079] Testing event wbc_writepage: OK [ 95.772025] Testing event writeback_bdi_register: OK [ 95.794021] Testing event writeback_wake_background: OK [ 95.814729] Testing event writeback_pages_written: OK [ 95.838765] Testing event writeback_wait: OK [ 95.862612] Testing event writeback_written: OK [ 95.886726] Testing event writeback_start: OK [ 95.910581] Testing event writeback_exec: OK [ 95.934623] Testing event writeback_queue: OK [ 95.958606] Testing event writeback_write_inode: OK [ 95.982568] Testing event writeback_write_inode_start: OK [ 96.006719] Testing event writeback_dirty_inode: OK [ 96.030669] Testing event writeback_dirty_inode_start: OK [ 96.054708] Testing event writeback_mark_inode_dirty: OK [ 96.078413] Testing event writeback_dirty_page: OK [ 96.101879] Testing event dax_writeback_one: OK [ 96.125878] Testing event dax_writeback_range_done: OK [ 96.146575] Testing event dax_writeback_range: OK [ 96.169902] Testing event dax_insert_mapping: OK [ 96.192563] Testing event dax_insert_pfn_mkwrite: OK [ 96.216435] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 96.240418] Testing event dax_load_hole: OK [ 96.262131] Testing event dax_pte_fault_done: OK [ 96.285571] Testing event dax_pte_fault: OK [ 96.308373] Testing event dax_pmd_insert_mapping: OK [ 96.330048] Testing event dax_pmd_load_hole_fallback: OK [ 96.352433] Testing event dax_pmd_load_hole: OK [ 96.376355] Testing event dax_pmd_fault_done: OK [ 96.397760] Testing event dax_pmd_fault: OK [ 96.417928] Testing event generic_add_lease: OK [ 96.438593] Testing event time_out_leases: OK [ 96.462565] Testing event generic_delete_lease: OK [ 96.487606] Testing event break_lease_unblock: OK [ 96.510143] Testing event break_lease_block: OK [ 96.533700] Testing event break_lease_noblock: OK [ 96.554705] Testing event flock_lock_inode: OK [ 96.578734] Testing event locks_remove_posix: OK [ 96.602786] Testing event fcntl_setlk: OK [ 96.626633] Testing event posix_lock_inode: OK [ 96.650557] Testing event locks_get_lock_context: OK [ 96.674621] Testing event block_rq_remap: OK [ 96.697760] Testing event block_bio_remap: OK [ 96.720456] Testing event block_split: OK [ 96.744460] Testing event block_unplug: OK [ 96.778503] Testing event block_plug: OK [ 96.802148] Testing event block_sleeprq: OK [ 96.824112] Testing event block_getrq: OK [ 96.846631] Testing event block_bio_queue: OK [ 96.871930] Testing event block_bio_frontmerge: OK [ 96.898000] Testing event block_bio_backmerge: OK [ 96.918812] Testing event block_bio_complete: OK [ 96.942767] Testing event block_bio_bounce: OK [ 96.967401] Testing event block_rq_issue: OK [ 96.990424] Testing event block_rq_insert: OK [ 97.015891] Testing event block_rq_complete: OK [ 97.038193] Testing event block_rq_requeue: OK [ 97.060496] Testing event block_dirty_buffer: OK [ 97.082218] Testing event block_touch_buffer: OK [ 97.106128] Testing event wbt_timer: OK [ 97.128441] Testing event wbt_step: OK [ 97.152426] Testing event wbt_lat: OK [ 97.177497] Testing event wbt_stat: OK [ 97.201899] Testing event gpio_value: OK [ 97.223270] Testing event gpio_direction: OK [ 97.246930] Testing event clk_set_duty_cycle_complete: OK [ 97.270754] Testing event clk_set_duty_cycle: OK [ 97.294608] Testing event clk_set_phase_complete: OK [ 97.318547] Testing event clk_set_phase: OK [ 97.342562] Testing event clk_set_parent_complete: OK [ 97.366730] Testing event clk_set_parent: OK [ 97.391530] Testing event clk_set_rate_complete: OK [ 97.414259] Testing event clk_set_rate: OK [ 97.437500] Testing event clk_unprepare_complete: OK [ 97.458842] Testing event clk_unprepare: OK [ 97.483535] Testing event clk_prepare_complete: OK [ 97.506214] Testing event clk_prepare: OK [ 97.528373] Testing event clk_disable_complete: OK [ 97.550189] Testing event clk_disable: OK [ 97.572005] Testing event clk_enable_complete: OK [ 97.593705] Testing event clk_enable: OK [ 97.613861] Testing event rpmh_send_msg: OK [ 97.635052] Testing event rpmh_tx_done: OK [ 97.658707] Testing event regulator_set_voltage_complete: OK [ 97.682608] Testing event regulator_set_voltage: OK [ 97.706541] Testing event regulator_disable_complete: OK [ 97.730584] Testing event regulator_disable: OK [ 97.754617] Testing event regulator_enable_complete: OK [ 97.778544] Testing event regulator_enable_delay: OK [ 97.802599] Testing event regulator_enable: OK [ 97.826500] Testing event urandom_read: OK [ 97.850329] Testing event random_read: OK [ 97.875785] Testing event extract_entropy_user: OK [ 97.898624] Testing event extract_entropy: OK [ 97.921936] Testing event get_random_bytes_arch: OK [ 97.946707] Testing event get_random_bytes: OK [ 97.971353] Testing event xfer_secondary_pool: OK [ 97.994226] Testing event add_disk_randomness: OK [ 98.016430] Testing event add_input_randomness: OK [ 98.038003] Testing event debit_entropy: OK [ 98.060504] Testing event push_to_pool: OK [ 98.085790] Testing event credit_entropy_bits: OK [ 98.106518] Testing event mix_pool_bytes_nolock: OK [ 98.130794] Testing event mix_pool_bytes: OK [ 98.155583] Testing event add_device_randomness: OK [ 98.179204] Testing event io_page_fault: OK [ 98.202344] Testing event unmap: OK [ 98.225719] Testing event map: OK [ 98.248361] Testing event detach_device_from_domain: OK [ 98.270092] Testing event attach_device_to_domain: OK [ 98.293579] Testing event remove_device_from_group: OK [ 98.316422] Testing event add_device_to_group: OK [ 98.338104] Testing event regcache_drop_region: OK [ 98.360554] Testing event regmap_async_complete_done: OK [ 98.384369] Testing event regmap_async_complete_start: OK [ 98.406134] Testing event regmap_async_io_complete: OK [ 98.428062] Testing event regmap_async_write_start: OK [ 98.449945] Testing event regmap_cache_bypass: OK [ 98.470647] Testing event regmap_cache_only: OK [ 98.494081] Testing event regcache_sync: OK [ 98.518701] Testing event regmap_hw_write_done: OK [ 98.542827] Testing event regmap_hw_write_start: OK [ 98.567044] Testing event regmap_hw_read_done: OK [ 98.591359] Testing event regmap_hw_read_start: OK [ 98.614244] Testing event regmap_reg_read_cache: OK [ 98.636346] Testing event regmap_reg_read: OK [ 98.658055] Testing event regmap_reg_write: OK [ 98.681529] Testing event dma_fence_wait_end: OK [ 98.704387] Testing event dma_fence_wait_start: OK [ 98.726034] Testing event dma_fence_signaled: OK [ 98.749701] Testing event dma_fence_enable_signal: OK [ 98.774924] Testing event dma_fence_destroy: OK [ 98.799301] Testing event dma_fence_init: OK [ 98.822236] Testing event dma_fence_emit: OK [ 98.844195] Testing event sync_timeline: OK [ 98.866099] Testing event spi_transfer_stop: OK [ 98.888080] Testing event spi_transfer_start: OK [ 98.909887] Testing event spi_message_done: OK [ 98.930653] Testing event spi_message_start: OK [ 98.954071] Testing event spi_message_submit: OK [ 98.978600] Testing event spi_controller_busy: OK [ 99.002618] Testing event spi_controller_idle: OK [ 99.026446] Testing event mdio_access: OK [ 99.050702] Testing event rtc_timer_fired: OK [ 99.075518] Testing event rtc_timer_dequeue: OK [ 99.098199] Testing event rtc_timer_enqueue: OK [ 99.121804] Testing event rtc_read_offset: OK [ 99.142401] Testing event rtc_set_offset: OK [ 99.165715] Testing event rtc_alarm_irq_enable: OK [ 99.187544] Testing event rtc_irq_set_state: OK [ 99.210308] Testing event rtc_irq_set_freq: OK [ 99.232355] Testing event rtc_read_alarm: OK [ 99.254206] Testing event rtc_set_alarm: OK [ 99.275923] Testing event rtc_read_time: OK [ 99.297993] Testing event rtc_set_time: OK [ 99.318609] Testing event memory_failure_event: OK [ 99.342548] Testing event aer_event: OK [ 99.366426] Testing event non_standard_event: OK [ 99.389960] Testing event arm_event: OK [ 99.412409] Testing event mc_event: OK [ 99.433894] Testing event binder_return: OK [ 99.456452] Testing event binder_command: OK [ 99.480451] Testing event binder_unmap_kernel_end: OK [ 99.502036] Testing event binder_unmap_kernel_start: OK [ 99.525712] Testing event binder_unmap_user_end: OK [ 99.548339] Testing event binder_unmap_user_start: OK [ 99.570112] Testing event binder_alloc_page_end: OK [ 99.592523] Testing event binder_alloc_page_start: OK [ 99.617892] Testing event binder_free_lru_end: OK [ 99.638598] Testing event binder_free_lru_start: OK [ 99.662764] Testing event binder_alloc_lru_end: OK [ 99.687237] Testing event binder_alloc_lru_start: OK [ 99.710948] Testing event binder_update_page_range: OK [ 99.734763] Testing event binder_transaction_failed_buffer_release: OK [ 99.758712] Testing event binder_transaction_buffer_release: OK [ 99.782518] Testing event binder_transaction_alloc_buf: OK [ 99.806566] Testing event binder_transaction_fd_recv: OK [ 99.830728] Testing event binder_transaction_fd_send: OK [ 99.855368] Testing event binder_transaction_ref_to_ref: OK [ 99.878890] Testing event binder_transaction_ref_to_node: OK [ 99.903981] Testing event binder_transaction_node_to_ref: OK [ 99.927345] Testing event binder_transaction_received: OK [ 99.950308] Testing event binder_transaction: OK [ 99.973783] Testing event binder_wait_for_work: OK [ 99.996368] Testing event binder_read_done: OK [ 100.018124] Testing event binder_write_done: OK [ 100.040468] Testing event binder_ioctl_done: OK [ 100.065711] Testing event binder_unlock: OK [ 100.088193] Testing event binder_locked: OK [ 100.109995] Testing event binder_lock: OK [ 100.131886] Testing event binder_ioctl: OK [ 100.154065] Testing event br_fdb_update: OK [ 100.173677] Testing event fdb_delete: OK [ 100.193615] Testing event br_fdb_external_learn_add: OK [ 100.213827] Testing event br_fdb_add: OK [ 100.234408] Testing event qdisc_dequeue: OK [ 100.258673] Testing event fib_table_lookup: OK [ 100.283638] Testing event tcp_probe: OK [ 100.306174] Testing event tcp_retransmit_synack: OK [ 100.329690] Testing event tcp_rcv_space_adjust: OK [ 100.350542] Testing event tcp_destroy_sock: OK [ 100.374109] Testing event tcp_receive_reset: OK [ 100.397641] Testing event tcp_send_reset: OK [ 100.418861] Testing event tcp_retransmit_skb: OK [ 100.442579] Testing event udp_fail_queue_rcv_skb: OK [ 100.466531] Testing event inet_sock_set_state: OK [ 100.490771] Testing event sock_exceed_buf_limit: OK [ 100.514390] Testing event sock_rcvqueue_full: OK [ 100.537857] Testing event napi_poll: OK [ 100.558050] Testing event netif_rx_ni_entry: OK [ 100.577503] Testing event netif_rx_entry: OK [ 100.598344] Testing event netif_receive_skb_list_entry: OK [ 100.622509] Testing event netif_receive_skb_entry: OK [ 100.646735] Testing event napi_gro_receive_entry: OK [ 100.671524] Testing event napi_gro_frags_entry: OK [ 100.694247] Testing event netif_rx: OK [ 100.716366] Testing event netif_receive_skb: OK [ 100.738078] Testing event net_dev_queue: OK [ 100.759949] Testing event net_dev_xmit: OK [ 100.781866] Testing event net_dev_start_xmit: OK [ 100.802604] Testing event skb_copy_datagram_iovec: OK [ 100.826563] Testing event consume_skb: OK [ 100.851636] Testing event kfree_skb: OK [ 100.874383] Testing event 9p_protocol_dump: OK [ 100.901886] Testing event 9p_client_res: OK [ 100.922789] Testing event 9p_client_req: OK [ 100.945986] Running tests on trace event systems: [ 100.946624] Testing event system 9p: OK [ 100.978031] Testing event system skb: OK [ 101.002875] Testing event system net: OK [ 101.035133] Testing event system napi: OK [ 101.058499] Testing event system sock: OK [ 101.083697] Testing event system udp: OK [ 101.106114] Testing event system tcp: OK [ 101.132561] Testing event system fib: OK [ 101.153941] Testing event system qdisc: OK [ 101.174566] Testing event system bridge: OK [ 101.198709] Testing event system binder: OK [ 101.238965] Testing event system ras: OK [ 101.263252] Testing event system rtc: OK [ 101.295432] Testing event system mdio: OK [ 101.318125] Testing event system spi: OK [ 101.343827] Testing event system sync_trace: OK [ 101.365972] Testing event system dma_fence: OK [ 101.391902] Testing event system regmap: OK [ 101.423772] Testing event system iommu: OK [ 101.447004] Testing event system random: OK [ 101.478502] Testing event system regulator: OK [ 101.502979] Testing event system rpmh: OK [ 101.526248] Testing event system clk: OK [ 101.559777] Testing event system gpio: OK [ 101.582726] Testing event system wbt: OK [ 101.607520] Testing event system block: OK [ 101.641658] Testing event system filelock: OK [ 101.670993] Testing event system fs_dax: OK [ 101.700533] Testing event system writeback: OK [ 101.739148] Testing event system page_ref: OK [ 101.885399] Testing event system cma: OK [ 101.909802] Testing event system page_isolation: OK [ 101.930870] Testing event system huge_memory: OK [ 101.956496] Testing event system migrate: OK [ 101.978638] Testing event system compaction: OK [ 102.010136] Testing event system kmem: OK [ 102.045745] Testing event system percpu: OK [ 102.074106] Testing event system vmscan: OK [ 102.105841] Testing event system pagemap: OK [ 102.126288] Testing event system oom: OK [ 102.151843] Testing event system filemap: OK [ 102.175306] Testing event system rseq: OK [ 102.198667] Testing event system xdp: OK [ 102.225481] Testing event system benchmark: OK [ 102.344017] Testing event system rpm: OK [ 102.367708] Testing event system power: OK [ 102.415147] Testing event system test: OK [ 102.433679] Testing event system preemptirq: OK [ 102.461999] Testing event system cgroup: OK [ 102.490172] Testing event system module: OK [ 102.514747] Testing event system alarmtimer: OK [ 102.538661] Testing event system timer: OK [ 102.573217] Testing event system swiotlb: OK [ 102.593945] Testing event system rcu: OK [ 102.637316] Testing event system printk: OK [ 102.659071] Testing event system lock: OK [ 102.692024] Testing event system sched: OK [ 102.730453] Testing event system workqueue: OK [ 102.755239] Testing event system signal: OK [ 102.778779] Testing event system irq: OK [ 102.803833] Testing event system cpuhp: OK [ 102.831123] Testing event system task: OK [ 102.855596] Testing event system kvm: OK [ 102.896039] Testing event system emulation: OK [ 102.917905] Testing event system ipi: OK [ 102.943104] Testing event system raw_syscalls: OK [ 102.968015] Testing event system initcall: OK [ 102.990930] Running tests on all trace events: [ 102.991297] Testing all events: OK [ 117.615630] Running tests again, along with the function tracer [ 117.620248] Running tests on trace events: [ 117.623865] Testing event initcall_finish: OK [ 118.020426] Testing event initcall_start: OK [ 118.107026] Testing event initcall_level: OK [ 118.184381] Testing event sys_exit: OK [ 118.288043] Testing event sys_enter: OK [ 118.356540] Testing event ipi_exit: OK [ 118.444021] Testing event ipi_entry: OK [ 118.523424] Testing event ipi_raise: OK [ 118.616382] Testing event instruction_emulation: OK [ 118.696082] Testing event kvm_halt_poll_ns: OK [ 118.784489] Testing event kvm_age_page: OK [ 118.871135] Testing event kvm_fpu: OK [ 118.944442] Testing event kvm_mmio: OK [ 119.020055] Testing event kvm_ack_irq: OK [ 119.115136] Testing event kvm_set_irq: OK [ 119.184538] Testing event kvm_vcpu_wakeup: OK [ 119.271756] Testing event kvm_userspace_exit: OK [ 119.350922] Testing event kvm_timer_update_irq: OK [ 119.434567] Testing event kvm_toggle_cache: OK [ 119.519475] Testing event kvm_set_way_flush: OK [ 119.599907] Testing event kvm_test_age_hva: OK [ 119.675674] Testing event kvm_age_hva: OK [ 119.764487] Testing event kvm_set_spte_hva: OK [ 119.863560] Testing event kvm_unmap_hva_range: OK [ 119.928239] Testing event kvm_mmio_emulate: OK [ 120.011856] Testing event kvm_irq_line: OK [ 120.098339] Testing event kvm_access_fault: OK [ 120.171570] Testing event kvm_guest_fault: OK [ 120.276402] Testing event kvm_exit: OK [ 120.336437] Testing event kvm_entry: OK [ 120.424579] Testing event kvm_set_guest_debug: OK [ 120.515291] Testing event kvm_handle_sys_reg: OK [ 120.590566] Testing event trap_reg: OK [ 120.674751] Testing event kvm_arm_set_regset: OK [ 120.762961] Testing event kvm_arm_set_dreg32: OK [ 120.856530] Testing event kvm_arm_clear_debug: OK [ 120.939640] Testing event kvm_arm_setup_debug: OK [ 121.024003] Testing event kvm_hvc_arm64: OK [ 121.112121] Testing event kvm_wfx_arm64: OK [ 121.194413] Testing event vgic_update_irq_pending: OK [ 121.263892] Testing event task_rename: OK [ 121.347265] Testing event task_newtask: OK [ 121.436344] Testing event cpuhp_exit: OK [ 121.515220] Testing event cpuhp_multi_enter: OK [ 121.599277] Testing event cpuhp_enter: OK [ 121.683225] Testing event softirq_raise: OK [ 121.751860] Testing event softirq_exit: OK [ 121.850390] Testing event softirq_entry: OK [ 121.928577] Testing event irq_handler_exit: OK [ 122.004280] Testing event irq_handler_entry: OK [ 122.099477] Testing event signal_deliver: OK [ 122.171231] Testing event signal_generate: OK [ 122.248047] Testing event workqueue_execute_end: OK [ 122.327695] Testing event workqueue_execute_start: OK [ 122.416540] Testing event workqueue_activate_work: OK [ 122.510946] Testing event workqueue_queue_work: OK [ 122.579948] Testing event sched_wake_idle_without_ipi: OK [ 122.659301] Testing event sched_swap_numa: OK [ 122.739729] Testing event sched_stick_numa: OK [ 122.819580] Testing event sched_move_numa: OK [ 122.900152] Testing event sched_process_hang: OK [ 122.983897] Testing event sched_pi_setprio: OK [ 123.059235] Testing event sched_stat_runtime: OK [ 123.147145] Testing event sched_stat_blocked: OK [ 123.223477] Testing event sched_stat_iowait: OK [ 123.299574] Testing event sched_stat_sleep: OK [ 123.372114] Testing event sched_stat_wait: OK [ 123.456308] Testing event sched_process_exec: OK [ 123.539244] Testing event sched_process_fork: OK [ 123.616002] Testing event sched_process_wait: OK [ 123.703207] Testing event sched_wait_task: OK [ 123.786327] Testing event sched_process_exit: OK [ 123.870431] Testing event sched_process_free: OK [ 123.955435] Testing event sched_migrate_task: OK [ 124.039668] Testing event sched_switch: OK [ 124.119679] Testing event sched_wakeup_new: OK [ 124.204081] Testing event sched_wakeup: OK [ 124.307980] Testing event sched_waking: OK [ 124.383473] Testing event sched_kthread_stop_ret: OK [ 124.460158] Testing event sched_kthread_stop: OK [ 124.555733] Testing event lock_acquired: OK [ 124.624226] Testing event lock_contended: OK [ 124.722333] Testing event lock_release: OK [ 124.806295] Testing event lock_acquire: OK [ 124.903277] Testing event console: OK [ 124.979175] Testing event rcu_barrier: OK [ 125.071008] Testing event rcu_torture_read: OK [ 125.143479] Testing event rcu_batch_end: OK [ 125.226095] Testing event rcu_invoke_kfree_callback: OK [ 125.308259] Testing event rcu_invoke_callback: OK [ 125.391514] Testing event rcu_batch_start: OK [ 125.468451] Testing event rcu_kfree_callback: OK [ 125.550815] Testing event rcu_callback: OK [ 125.640434] Testing event rcu_dyntick: OK [ 125.728573] Testing event rcu_fqs: OK [ 125.814120] Testing event rcu_quiescent_state_report: OK [ 125.892328] Testing event rcu_unlock_preempted_task: OK [ 125.964444] Testing event rcu_preempt_task: OK [ 126.058169] Testing event rcu_exp_funnel_lock: OK [ 126.148311] Testing event rcu_exp_grace_period: OK [ 126.252390] Testing event rcu_grace_period_init: OK [ 126.327149] Testing event rcu_future_grace_period: OK [ 126.412070] Testing event rcu_grace_period: OK [ 126.504290] Testing event rcu_utilization: OK [ 126.594852] Testing event swiotlb_bounced: OK [ 126.694960] Testing event tick_stop: OK [ 126.765686] Testing event itimer_expire: OK [ 126.842231] Testing event itimer_state: OK [ 126.923733] Testing event hrtimer_cancel: OK [ 127.008482] Testing event hrtimer_expire_exit: OK [ 127.103507] Testing event hrtimer_expire_entry: OK [ 127.175125] Testing event hrtimer_start: OK [ 127.264249] Testing event hrtimer_init: OK [ 127.346634] Testing event timer_cancel: OK [ 127.419788] Testing event timer_expire_exit: OK [ 127.501441] Testing event timer_expire_entry: OK [ 127.588127] Testing event timer_start: OK [ 127.665100] Testing event timer_init: OK [ 127.747804] Testing event alarmtimer_cancel: OK [ 127.819454] Testing event alarmtimer_start: OK [ 127.906582] Testing event alarmtimer_fired: OK [ 127.995841] Testing event alarmtimer_suspend: OK [ 128.060182] Testing event module_request: OK [ 128.159527] Testing event module_put: OK [ 128.231785] Testing event module_get: OK [ 128.323850] Testing event module_free: OK [ 128.406469] Testing event module_load: OK [ 128.495873] Testing event cgroup_transfer_tasks: OK [ 128.583327] Testing event cgroup_attach_task: OK [ 128.674374] Testing event cgroup_rename: OK [ 128.756312] Testing event cgroup_release: OK [ 128.839800] Testing event cgroup_rmdir: OK [ 128.931602] Testing event cgroup_mkdir: OK [ 129.007340] Testing event cgroup_remount: OK [ 129.096026] Testing event cgroup_destroy_root: OK [ 129.179589] Testing event cgroup_setup_root: OK [ 129.260001] Testing event irq_enable: OK [ 129.383390] Testing event irq_disable: OK [ 129.512197] Testing event ftrace_test_filter: OK [ 129.587726] Testing event dev_pm_qos_remove_request: OK [ 129.660253] Testing event dev_pm_qos_update_request: OK [ 129.754937] Testing event dev_pm_qos_add_request: OK [ 129.839335] Testing event pm_qos_update_flags: OK [ 129.924239] Testing event pm_qos_update_target: OK [ 130.010611] Testing event pm_qos_update_request_timeout: OK [ 130.096437] Testing event pm_qos_remove_request: OK [ 130.179685] Testing event pm_qos_update_request: OK [ 130.263848] Testing event pm_qos_add_request: OK [ 130.343314] Testing event power_domain_target: OK [ 130.416081] Testing event clock_set_rate: OK [ 130.506585] Testing event clock_disable: OK [ 130.583683] Testing event clock_enable: OK [ 130.667167] Testing event wakeup_source_deactivate: OK [ 130.747789] Testing event wakeup_source_activate: OK [ 130.839695] Testing event suspend_resume: OK [ 130.951719] Testing event device_pm_callback_end: OK [ 131.032588] Testing event device_pm_callback_start: OK [ 131.114089] Testing event cpu_frequency_limits: OK [ 131.199715] Testing event cpu_frequency: OK [ 131.295591] Testing event pstate_sample: OK [ 131.359829] Testing event powernv_throttle: OK [ 131.443899] Testing event cpu_idle: OK [ 131.534621] Testing event rpm_return_int: OK [ 131.611741] Testing event rpm_idle: OK [ 131.688526] Testing event rpm_resume: OK [ 131.775302] Testing event rpm_suspend: OK [ 131.863624] Testing event benchmark_event: OK [ 132.023058] Testing event xdp_devmap_xmit: OK [ 132.108167] Testing event xdp_cpumap_enqueue: OK [ 132.191782] Testing event xdp_cpumap_kthread: OK [ 132.274696] Testing event xdp_redirect_map_err: OK [ 132.359976] Testing event xdp_redirect_map: OK [ 132.439607] Testing event xdp_redirect_err: OK [ 132.523268] Testing event xdp_redirect: OK [ 132.604210] Testing event xdp_exception: OK [ 132.688184] Testing event rseq_ip_fixup: OK [ 132.772072] Testing event rseq_update: OK [ 132.860049] Testing event file_check_and_advance_wb_err: OK [ 132.951587] Testing event filemap_set_wb_err: OK [ 133.031806] Testing event mm_filemap_add_to_page_cache: OK [ 133.139375] Testing event mm_filemap_delete_from_page_cache: OK [ 133.206278] Testing event compact_retry: OK [ 133.283705] Testing event skip_task_reaping: OK [ 133.390176] Testing event finish_task_reaping: OK [ 133.458555] Testing event start_task_reaping: OK [ 133.535651] Testing event wake_reaper: OK [ 133.618659] Testing event mark_victim: OK [ 133.708589] Testing event reclaim_retry_zone: OK [ 133.780318] Testing event oom_score_adj_update: OK [ 133.864146] Testing event mm_lru_activate: OK [ 133.951927] Testing event mm_lru_insertion: OK [ 134.028218] Testing event mm_vmscan_inactive_list_is_low: OK [ 134.120455] Testing event mm_vmscan_lru_shrink_active: OK [ 134.192009] Testing event mm_vmscan_lru_shrink_inactive: OK [ 134.275992] Testing event mm_vmscan_writepage: OK [ 134.358640] Testing event mm_vmscan_lru_isolate: OK [ 134.427666] Testing event mm_shrink_slab_end: OK [ 134.511221] Testing event mm_shrink_slab_start: OK [ 134.602819] Testing event mm_vmscan_memcg_softlimit_reclaim_end: OK [ 134.686270] Testing event mm_vmscan_memcg_reclaim_end: OK [ 134.775214] Testing event mm_vmscan_direct_reclaim_end: OK [ 134.860069] Testing event mm_vmscan_memcg_softlimit_reclaim_begin: OK [ 134.951659] Testing event mm_vmscan_memcg_reclaim_begin: OK [ 135.020249] Testing event mm_vmscan_direct_reclaim_begin: OK [ 135.106251] Testing event mm_vmscan_wakeup_kswapd: OK [ 135.183884] Testing event mm_vmscan_kswapd_wake: OK [ 135.268154] Testing event mm_vmscan_kswapd_sleep: OK [ 135.340264] Testing event percpu_destroy_chunk: OK [ 135.434443] Testing event percpu_create_chunk: OK [ 135.517401] Testing event percpu_alloc_percpu_fail: OK [ 135.600493] Testing event percpu_free_percpu: OK [ 135.678857] Testing event percpu_alloc_percpu: OK [ 135.763681] Testing event mm_page_alloc_extfrag: OK [ 135.832352] Testing event mm_page_pcpu_drain: OK [ 135.916026] Testing event mm_page_alloc_zone_locked: OK [ 136.003825] Testing event mm_page_alloc: OK [ 136.098484] Testing event mm_page_free_batched: OK [ 136.163197] Testing event mm_page_free: OK [ 136.236095] Testing event kmem_cache_free: OK [ 136.327084] Testing event kfree: OK [ 136.415391] Testing event kmem_cache_alloc_node: OK [ 136.486845] Testing event kmalloc_node: OK [ 136.575485] Testing event kmem_cache_alloc: OK [ 136.662162] Testing event kmalloc: OK [ 136.748439] Testing event mm_compaction_kcompactd_wake: OK [ 136.830160] Testing event mm_compaction_wakeup_kcompactd: OK [ 136.922847] Testing event mm_compaction_kcompactd_sleep: OK [ 136.999349] Testing event mm_compaction_defer_reset: OK [ 137.092472] Testing event mm_compaction_defer_compaction: OK [ 137.178290] Testing event mm_compaction_deferred: OK [ 137.263515] Testing event mm_compaction_suitable: OK [ 137.355920] Testing event mm_compaction_finished: OK [ 137.427995] Testing event mm_compaction_try_to_compact_pages: OK [ 137.503386] Testing event mm_compaction_end: OK [ 137.587533] Testing event mm_compaction_begin: OK [ 137.672300] Testing event mm_compaction_migratepages: OK [ 137.748127] Testing event mm_compaction_isolate_freepages: OK [ 137.831012] Testing event mm_compaction_isolate_migratepages: OK [ 137.938293] Testing event mm_migrate_pages: OK [ 138.015786] Testing event mm_collapse_huge_page_swapin: OK [ 138.096288] Testing event mm_collapse_huge_page_isolate: OK [ 138.183291] Testing event mm_collapse_huge_page: OK [ 138.252490] Testing event mm_khugepaged_scan_pmd: OK [ 138.355806] Testing event test_pages_isolated: OK [ 138.434743] Testing event cma_release: OK [ 138.504234] Testing event cma_alloc: OK [ 138.608096] Testing event page_ref_unfreeze: OK [ 138.711563] Testing event page_ref_freeze: OK [ 138.787296] Testing event page_ref_mod_unless: OK [ 138.878272] Testing event page_ref_mod_and_return: OK [ 138.959842] Testing event page_ref_mod_and_test: OK [ 139.316014] Testing event page_ref_mod: OK [ 139.468519] Testing event page_ref_set: OK [ 139.552402] Testing event sb_clear_inode_writeback: OK [ 139.626796] Testing event sb_mark_inode_writeback: OK [ 139.726694] Testing event writeback_dirty_inode_enqueue: OK [ 139.792010] Testing event writeback_lazytime_iput: OK [ 139.876303] Testing event writeback_lazytime: OK [ 139.960558] Testing event writeback_single_inode: OK [ 140.048247] Testing event writeback_single_inode_start: OK [ 140.130781] Testing event writeback_wait_iff_congested: OK [ 140.219478] Testing event writeback_congestion_wait: OK [ 140.297339] Testing event writeback_sb_inodes_requeue: OK [ 140.376109] Testing event balance_dirty_pages: OK [ 140.472242] Testing event bdi_dirty_ratelimit: OK [ 140.551796] Testing event global_dirty_state: OK [ 140.628157] Testing event writeback_queue_io: OK [ 140.706406] Testing event wbc_writepage: OK [ 140.814690] Testing event writeback_bdi_register: OK [ 140.896117] Testing event writeback_wake_background: OK [ 140.975916] Testing event writeback_pages_written: OK [ 141.059375] Testing event writeback_wait: OK [ 141.138831] Testing event writeback_written: OK [ 141.218511] Testing event writeback_start: OK [ 141.311811] Testing event writeback_exec: OK [ 141.380066] Testing event writeback_queue: OK [ 141.452211] Testing event writeback_write_inode: OK [ 141.536069] Testing event writeback_write_inode_start: OK [ 141.612537] Testing event writeback_dirty_inode: OK [ 141.694881] Testing event writeback_dirty_inode_start: OK [ 141.779650] Testing event writeback_mark_inode_dirty: OK [ 141.844094] Testing event writeback_dirty_page: OK [ 141.935278] Testing event dax_writeback_one: OK [ 142.011886] Testing event dax_writeback_range_done: OK [ 142.080427] Testing event dax_writeback_range: OK [ 142.176152] Testing event dax_insert_mapping: OK [ 142.250136] Testing event dax_insert_pfn_mkwrite: OK [ 142.335459] Testing event dax_insert_pfn_mkwrite_no_entry: OK [ 142.423259] Testing event dax_load_hole: OK [ 142.502591] Testing event dax_pte_fault_done: OK [ 142.595761] Testing event dax_pte_fault: OK [ 142.667994] Testing event dax_pmd_insert_mapping: OK [ 142.750950] Testing event dax_pmd_load_hole_fallback: OK [ 142.839565] Testing event dax_pmd_load_hole: OK [ 142.916214] Testing event dax_pmd_fault_done: OK [ 143.000400] Testing event dax_pmd_fault: OK [ 143.101960] Testing event generic_add_lease: OK [ 143.187484] Testing event time_out_leases: OK [ 143.255857] Testing event generic_delete_lease: OK [ 143.335999] Testing event break_lease_unblock: OK [ 143.424249] Testing event break_lease_block: OK [ 143.496311] Testing event break_lease_noblock: OK [ 143.599752] Testing event flock_lock_inode: OK [ 143.674907] Testing event locks_remove_posix: OK [ 143.763222] Testing event fcntl_setlk: OK [ 143.824065] Testing event posix_lock_inode: OK [ 143.906655] Testing event locks_get_lock_context: OK [ 143.989791] Testing event block_rq_remap: OK [ 144.067504] Testing event block_bio_remap: OK [ 144.152495] Testing event block_split: OK [ 144.228377] Testing event block_unplug: OK [ 144.303697] Testing event block_plug: OK [ 144.389416] Testing event block_sleeprq: OK [ 144.464322] Testing event block_getrq: OK [ 144.548525] Testing event block_bio_queue: OK [ 144.627515] Testing event block_bio_frontmerge: OK [ 144.704199] Testing event block_bio_backmerge: OK [ 144.792161] Testing event block_bio_complete: OK [ 144.860339] Testing event block_bio_bounce: OK [ 144.950021] Testing event block_rq_issue: OK [ 145.035907] Testing event block_rq_insert: OK [ 145.108173] Testing event block_rq_complete: OK [ 145.196247] Testing event block_rq_requeue: OK [ 145.267707] Testing event block_dirty_buffer: OK [ 145.344165] Testing event block_touch_buffer: OK [ 145.431997] Testing event wbt_timer: OK [ 145.503221] Testing event wbt_step: OK [ 145.583593] Testing event wbt_lat: OK [ 145.671317] Testing event wbt_stat: OK [ 145.764256] Testing event gpio_value: OK [ 145.838683] Testing event gpio_direction: OK [ 145.912338] Testing event clk_set_duty_cycle_complete: OK [ 145.999412] Testing event clk_set_duty_cycle: OK [ 146.079943] Testing event clk_set_phase_complete: OK [ 146.159305] Testing event clk_set_phase: OK [ 146.232009] Testing event clk_set_parent_complete: OK [ 146.300100] Testing event clk_set_parent: OK [ 146.399788] Testing event clk_set_rate_complete: OK [ 146.482815] Testing event clk_set_rate: OK [ 146.579427] Testing event clk_unprepare_complete: OK [ 146.647871] Testing event clk_unprepare: OK [ 146.727547] Testing event clk_prepare_complete: OK [ 146.807629] Testing event clk_prepare: OK [ 146.894956] Testing event clk_disable_complete: OK [ 146.983573] Testing event clk_disable: OK [ 147.068080] Testing event clk_enable_complete: OK [ 147.151805] Testing event clk_enable: OK [ 147.227913] Testing event rpmh_send_msg: OK [ 147.303731] Testing event rpmh_tx_done: OK [ 147.399113] Testing event regulator_set_voltage_complete: OK [ 147.467685] Testing event regulator_set_voltage: OK [ 147.551464] Testing event regulator_disable_complete: OK [ 147.630440] Testing event regulator_disable: OK [ 147.708497] Testing event regulator_enable_complete: OK [ 147.794971] Testing event regulator_enable_delay: OK [ 147.859645] Testing event regulator_enable: OK [ 147.939630] Testing event urandom_read: OK [ 148.019212] Testing event random_read: OK [ 148.123206] Testing event extract_entropy_user: OK [ 148.192536] Testing event extract_entropy: OK [ 148.283030] Testing event get_random_bytes_arch: OK [ 148.372267] Testing event get_random_bytes: OK [ 148.447610] Testing event xfer_secondary_pool: OK [ 148.519800] Testing event add_disk_randomness: OK [ 148.602534] Testing event add_input_randomness: OK [ 148.691735] Testing event debit_entropy: OK [ 148.765490] Testing event push_to_pool: OK [ 148.855072] Testing event credit_entropy_bits: OK [ 148.944111] Testing event mix_pool_bytes_nolock: OK [ 149.015310] Testing event mix_pool_bytes: OK [ 149.107382] Testing event add_device_randomness: OK [ 149.199123] Testing event io_page_fault: OK [ 149.268099] Testing event unmap: OK [ 149.368083] Testing event map: OK [ 149.435622] Testing event detach_device_from_domain: OK [ 149.527777] Testing event attach_device_to_domain: OK [ 149.603492] Testing event remove_device_from_group: OK [ 149.680558] Testing event add_device_to_group: OK [ 149.763845] Testing event regcache_drop_region: OK [ 149.852444] Testing event regmap_async_complete_done: OK [ 149.923941] Testing event regmap_async_complete_start: OK [ 150.011891] Testing event regmap_async_io_complete: OK [ 150.083936] Testing event regmap_async_write_start: OK [ 150.162544] Testing event regmap_cache_bypass: OK [ 150.243879] Testing event regmap_cache_only: OK [ 150.330801] Testing event regcache_sync: OK [ 150.415470] Testing event regmap_hw_write_done: OK [ 150.499360] Testing event regmap_hw_write_start: OK [ 150.590483] Testing event regmap_hw_read_done: OK [ 150.671668] Testing event regmap_hw_read_start: OK [ 150.736120] Testing event regmap_reg_read_cache: OK [ 150.832060] Testing event regmap_reg_read: OK [ 150.899287] Testing event regmap_reg_write: OK [ 150.995659] Testing event dma_fence_wait_end: OK [ 151.076129] Testing event dma_fence_wait_start: OK [ 151.171317] Testing event dma_fence_signaled: OK [ 151.239940] Testing event dma_fence_enable_signal: OK [ 151.315699] Testing event dma_fence_destroy: OK [ 151.395547] Testing event dma_fence_init: OK [ 151.475977] Testing event dma_fence_emit: OK [ 151.547569] Testing event sync_timeline: OK [ 151.619219] Testing event spi_transfer_stop: OK [ 151.695101] Testing event spi_transfer_start: OK [ 151.776588] Testing event spi_message_done: OK [ 151.847053] Testing event spi_message_start: OK [ 151.931466] Testing event spi_message_submit: OK [ 152.000250] Testing event spi_controller_busy: OK [ 152.095667] Testing event spi_controller_idle: OK [ 152.160400] Testing event mdio_access: OK [ 152.248403] Testing event rtc_timer_fired: OK [ 152.343925] Testing event rtc_timer_dequeue: OK [ 152.431179] Testing event rtc_timer_enqueue: OK [ 152.504383] Testing event rtc_read_offset: OK [ 152.607916] Testing event rtc_set_offset: OK [ 152.690333] Testing event rtc_alarm_irq_enable: OK [ 152.755737] Testing event rtc_irq_set_state: OK [ 152.824057] Testing event rtc_irq_set_freq: OK [ 152.903980] Testing event rtc_read_alarm: OK [ 152.983332] Testing event rtc_set_alarm: OK [ 153.071399] Testing event rtc_read_time: OK [ 153.142313] Testing event rtc_set_time: OK [ 153.223668] Testing event memory_failure_event: OK [ 153.296327] Testing event aer_event: OK [ 153.383776] Testing event non_standard_event: OK [ 153.454532] Testing event arm_event: OK [ 153.543147] Testing event mc_event: OK [ 153.611261] Testing event binder_return: OK [ 153.694794] Testing event binder_command: OK [ 153.776009] Testing event binder_unmap_kernel_end: OK [ 153.858737] Testing event binder_unmap_kernel_start: OK [ 153.963361] Testing event binder_unmap_user_end: OK [ 154.035672] Testing event binder_unmap_user_start: OK [ 154.131550] Testing event binder_alloc_page_end: OK [ 154.203630] Testing event binder_alloc_page_start: OK [ 154.284622] Testing event binder_free_lru_end: OK [ 154.371532] Testing event binder_free_lru_start: OK [ 154.460461] Testing event binder_alloc_lru_end: OK [ 154.535516] Testing event binder_alloc_lru_start: OK [ 154.626971] Testing event binder_update_page_range: OK [ 154.711982] Testing event binder_transaction_failed_buffer_release: OK [ 154.807919] Testing event binder_transaction_buffer_release: OK [ 154.887706] Testing event binder_transaction_alloc_buf: OK [ 154.955806] Testing event binder_transaction_fd_recv: OK [ 155.044530] Testing event binder_transaction_fd_send: OK [ 155.139742] Testing event binder_transaction_ref_to_ref: OK [ 155.215445] Testing event binder_transaction_ref_to_node: OK [ 155.295657] Testing event binder_transaction_node_to_ref: OK [ 155.372044] Testing event binder_transaction_received: OK [ 155.456379] Testing event binder_transaction: OK [ 155.540107] Testing event binder_wait_for_work: OK [ 155.627145] Testing event binder_read_done: OK [ 155.720049] Testing event binder_write_done: OK [ 155.791775] Testing event binder_ioctl_done: OK [ 155.875477] Testing event binder_unlock: OK [ 155.955069] Testing event binder_locked: OK [ 156.028400] Testing event binder_lock: OK [ 156.108586] Testing event binder_ioctl: OK [ 156.202294] Testing event br_fdb_update: OK [ 156.267179] Testing event fdb_delete: OK [ 156.358775] Testing event br_fdb_external_learn_add: OK [ 156.435759] Testing event br_fdb_add: OK [ 156.530872] Testing event qdisc_dequeue: OK [ 156.620236] Testing event fib_table_lookup: OK [ 156.703485] Testing event tcp_probe: OK [ 156.768478] Testing event tcp_retransmit_synack: OK [ 156.850345] Testing event tcp_rcv_space_adjust: OK [ 156.947591] Testing event tcp_destroy_sock: OK [ 157.012109] Testing event tcp_receive_reset: OK [ 157.091667] Testing event tcp_send_reset: OK [ 157.180412] Testing event tcp_retransmit_skb: OK [ 157.267143] Testing event udp_fail_queue_rcv_skb: OK [ 157.348076] Testing event inet_sock_set_state: OK [ 157.440004] Testing event sock_exceed_buf_limit: OK [ 157.539382] Testing event sock_rcvqueue_full: OK [ 157.613184] Testing event napi_poll: OK [ 157.706734] Testing event netif_rx_ni_entry: OK [ 157.768591] Testing event netif_rx_entry: OK [ 157.860237] Testing event netif_receive_skb_list_entry: OK [ 157.947570] Testing event netif_receive_skb_entry: OK [ 158.023902] Testing event napi_gro_receive_entry: OK [ 158.111131] Testing event napi_gro_frags_entry: OK [ 158.173259] Testing event netif_rx: OK [ 158.247984] Testing event netif_receive_skb: OK [ 158.359152] Testing event net_dev_queue: OK [ 158.419513] Testing event net_dev_xmit: OK [ 158.502741] Testing event net_dev_start_xmit: OK [ 158.584245] Testing event skb_copy_datagram_iovec: OK [ 158.647685] Testing event consume_skb: OK [ 158.728446] Testing event kfree_skb: OK [ 158.800380] Testing event 9p_protocol_dump: OK [ 158.895898] Testing event 9p_client_res: OK [ 158.972124] Testing event 9p_client_req: OK [ 159.055256] Running tests on trace event systems: [ 159.059005] Testing event system 9p: OK [ 159.148346] Testing event system skb: OK [ 159.235531] Testing event system net: OK [ 159.338694] Testing event system napi: OK [ 159.428539] Testing event system sock: OK [ 159.507507] Testing event system udp: OK [ 159.580147] Testing event system tcp: OK [ 159.679111] Testing event system fib: OK [ 159.759132] Testing event system qdisc: OK [ 159.844004] Testing event system bridge: OK [ 159.932104] Testing event system binder: OK [ 160.086806] Testing event system ras: OK [ 160.216338] Testing event system rtc: OK [ 160.311168] Testing event system mdio: OK [ 160.396223] Testing event system spi: OK [ 160.503838] Testing event system sync_trace: OK [ 160.599382] Testing event system dma_fence: OK [ 160.707052] Testing event system regmap: OK [ 160.828433] Testing event system iommu: OK [ 160.944172] Testing event system random: OK [ 161.064349] Testing event system regulator: OK [ 161.171491] Testing event system rpmh: OK [ 161.260394] Testing event system clk: OK [ 161.388537] Testing event system gpio: OK [ 161.488354] Testing event system wbt: OK [ 161.583225] Testing event system block: OK [ 161.707457] Testing event system filelock: OK [ 161.856294] Testing event system fs_dax: OK [ 161.972587] Testing event system writeback: OK [ 162.143717] Testing event system page_ref: OK [ 162.651963] Testing event system cma: OK [ 162.744249] Testing event system page_isolation: OK [ 162.838251] Testing event system huge_memory: OK [ 162.920353] Testing event system migrate: OK [ 163.019493] Testing event system compaction: OK [ 163.130951] Testing event system kmem: OK [ 163.263533] Testing event system percpu: OK [ 163.392489] Testing event system vmscan: OK [ 163.495318] Testing event system pagemap: OK [ 163.595506] Testing event system oom: OK [ 163.688482] Testing event system filemap: OK [ 163.783603] Testing event system rseq: OK [ 163.875701] Testing event system xdp: OK [ 163.986587] Testing event system benchmark: OK [ 164.171885] Testing event system rpm: OK [ 164.255811] Testing event system power: OK [ 164.420256] Testing event system test: OK [ 164.511610] Testing event system preemptirq: OK [ 164.703300] Testing event system cgroup: OK [ 164.811773] Testing event system module: OK [ 164.919523] Testing event system alarmtimer: OK [ 165.002709] Testing event system timer: OK [ 165.136316] Testing event system swiotlb: OK [ 165.236038] Testing event system rcu: OK [ 165.383649] Testing event system printk: OK [ 165.490703] Testing event system lock: OK [ 165.600159] Testing event system sched: OK [ 165.746684] Testing event system workqueue: OK [ 165.826586] Testing event system signal: OK [ 165.943651] Testing event system irq: OK [ 166.023600] Testing event system cpuhp: OK [ 166.123209] Testing event system task: OK [ 166.192196] Testing event system kvm: OK [ 166.347957] Testing event system emulation: OK [ 166.445254] Testing event system ipi: OK [ 166.532435] Testing event system raw_syscalls: OK [ 166.614471] Testing event system initcall: OK [ 166.723494] Running tests on all trace events: [ 166.727451] Testing all events: OK [ 176.002079] Testing ftrace filter: OK [ 176.052172] trace_kprobe: Testing kprobe tracing: OK [ 176.403553] Loading compiled-in X.509 certificates [ 176.486309] _warn_unseeded_randomness: 7 callbacks suppressed [ 176.486537] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 176.489157] Loaded X.509 cert 'Build time autogenerated kernel key: 4054958e39d9099bc90ea5e944013505afd1c71c' [ 176.491565] page_owner is disabled [ 176.565399] Key type big_key registered [ 176.602895] Key type encrypted registered [ 176.605619] ima: No TPM chip found, activating TPM-bypass! [ 176.606295] ima: Allocated hash algorithm: sha1 [ 176.609465] No architecture policies found [ 176.613616] random: get_random_bytes called from prandom_seed_full_state+0xc0/0x198 with crng_init=0 [ 176.630329] hctosys: unable to open rtc device (rtc0) [ 176.639717] psci_checker: PSCI checker started using 1 CPUs [ 176.640130] psci_checker: Starting hotplug tests [ 176.641264] psci_checker: Trying to turn off and on again all CPUs [ 176.642113] psci_checker: Trying to turn off and on again group 0 (CPUs 0) [ 176.642755] psci_checker: Hotplug tests passed OK [ 176.643136] psci_checker: Starting suspend tests (10 cycles per state) [ 176.643750] psci_checker: cpuidle not available on CPU 0, ignoring [ 176.644379] psci_checker: Could not start suspend tests on any CPU [ 176.645202] psci_checker: PSCI checker completed [ 176.677343] Duplicate name in testcase-data, renamed to "duplicate-name#1" [ 176.803193] ### dt-test ### start of unittest - you will see error messages [ 176.818337] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 176.819157] OF: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 [ 176.819856] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 176.820412] OF: /testcase-data/phandle-tests/consumer-a: could not find phandle [ 176.821350] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 176.821951] OF: /testcase-data/phandle-tests/consumer-a: arguments longer than property [ 176.831294] OF: /testcase-data/phandle-tests/consumer-b: could not get #phandle-missing-cells for /testcase-data/phandle-tests/provider1 [ 176.832421] OF: /testcase-data/phandle-tests/consumer-b: could not find phandle [ 176.833798] OF: /testcase-data/phandle-tests/consumer-b: arguments longer than property [ 177.066400] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest0/status [ 177.095366] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest1/status [ 177.109934] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest2/status [ 177.137502] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest3/status [ 177.181784] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest5/status [ 177.220059] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest6/status [ 177.245588] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest7/status [ 177.290370] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/status [ 177.315923] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data/overlay-node/test-bus/test-unittest8/property-foo [ 177.317861] OF: overlay: node_overlaps_later_cs: #6 overlaps with #7 @/testcase-data/overlay-node/test-bus/test-unittest8 [ 177.318748] OF: overlay: overlay #6 is not topmost [ 177.472421] OF: overlay: remove: Could not find overlay #7 [ 177.473378] ### dt-test ### of_unittest_destroy_tracked_overlays: no overlay to destroy for #7 [ 177.605560] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/status [ 177.609063] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/status [ 177.610653] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@30/incline-up [ 177.611784] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/fairway-1/ride@100/track@40/incline-up [ 177.619294] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/status [ 177.620255] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/color [ 177.621517] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/lights@40000/rate [ 177.622834] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/hvac_2 [ 177.623976] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200 [ 177.625315] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_left [ 177.626425] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /__symbols__/ride_200_right [ 177.686299] OF: overlay: ERROR: multiple fragments add and/or delete node /testcase-data-2/substation@100/motor-1/controller [ 177.688926] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/controller/name [ 177.695556] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 177.696957] OF: overlay: WARNING: memory leak will occur if overlay removed, property: /testcase-data-2/substation@100/motor-1/rpm_avail [ 177.697985] OF: overlay: ERROR: multiple fragments add, update, and/or delete property /testcase-data-2/substation@100/motor-1/rpm_avail [ 177.707999] ### dt-test ### end of unittest - 217 passed, 0 failed [ 177.723549] uart-pl011 9000000.pl011: no DMA platform data [ 177.903723] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 177.905049] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 177.935225] VFS: Mounted root (9p filesystem) readonly on device 0:18. [ 177.949643] devtmpfs: mounted [ 178.360156] Freeing unused kernel memory: 4096K [ 178.379257] Checked W+X mappings: passed, no W+X pages found [ 178.379902] rodata_test: all tests were successful [ 178.380991] Run /sbin/init as init process [ 178.457684] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=0 [ 179.060567] random: fast init done [ 181.075372] systemd[1]: System time before build time, advancing clock. [ 181.378634] systemd[1]: Failed to insert module 'autofs4': No such file or directory [ 183.422354] random: systemd: uninitialized urandom read (16 bytes read) [ 183.644394] random: systemd: uninitialized urandom read (16 bytes read) [ 183.648120] random: systemd: uninitialized urandom read (16 bytes read) [ 186.946513] 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) [ 186.998427] systemd[1]: Detected virtualization qemu. [ 187.002910] systemd[1]: Detected architecture arm64. Welcome to Debian GNU/Linux 9 (stretch)! [ 187.092370] systemd[1]: Set hostname to . [ 187.214777] _warn_unseeded_randomness: 5 callbacks suppressed [ 187.214963] random: get_random_u32 called from bucket_table_alloc+0x130/0x1a8 with crng_init=1 [ 187.254722] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 187.652297] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 188.397896] _warn_unseeded_randomness: 13 callbacks suppressed [ 188.398070] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 188.398979] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 188.400203] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 189.463691] random: systemd: uninitialized urandom read (16 bytes read) [ 189.490574] _warn_unseeded_randomness: 18 callbacks suppressed [ 189.490742] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 189.491786] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 189.492387] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 190.575935] _warn_unseeded_randomness: 11 callbacks suppressed [ 190.576102] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 190.577565] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 190.578128] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 192.431994] systemd-hiberna (1256) used greatest stack depth: 10576 bytes left [ 193.592124] random: systemd-cryptse: uninitialized urandom read (16 bytes read) [ 193.962781] systemd-fstab-g (1258) used greatest stack depth: 9664 bytes left [ 194.016358] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 194.688041] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) [ 195.758291] random: systemd: uninitialized urandom read (16 bytes read) [ 195.912564] random: systemd: uninitialized urandom read (16 bytes read) [ 195.916407] random: systemd: uninitialized urandom read (16 bytes read) [ 200.547425] systemd[1]: networking.service: Cannot add dependency job, ignoring: Unit networking.service is masked. [ 200.598398] _warn_unseeded_randomness: 8 callbacks suppressed [ 200.598636] random: get_random_bytes called from generate_random_uuid+0x20/0x50 with crng_init=1 [ 200.634781] systemd[1]: Starting of Arbitrary Executable File Formats File System Automount Point not supported. [UNSUPP] Starting of Arbitrary Executable Fi…tem Automount Point not supported. [ 200.685465] systemd[1]: Listening on Journal Audit Socket. [ OK ] Listening on Journal Audit Socket. [ 200.710413] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ OK ] Listening on /dev/initctl Compatibility Named Pipe. [ 200.732247] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 200.748393] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 200.766671] systemd[1]: Listening on Syslog Socket. [ OK ] Listening on Syslog Socket. [ 200.832000] systemd[1]: Created slice System Slice. [ OK ] Created slice System Slice. [ 200.882207] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ 201.021340] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 201.098344] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... Mounting Debug File System... [ 201.564244] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ OK ] Listening on Journal Socket. [ 201.611943] _warn_unseeded_randomness: 3 callbacks suppressed [ 201.612110] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 201.631087] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 201.631787] random: get_random_bytes called from create_elf_tables+0x10c/0xd18 with crng_init=1 [ OK ] Reached target Swap. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Create Static Device Nodes in /dev... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Remote File Systems. [ OK ] Reached target Slices. [ 203.047384] _warn_unseeded_randomness: 8 callbacks suppressed [ 203.047550] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 203.048598] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 203.049688] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 204.308322] _warn_unseeded_randomness: 11 callbacks suppressed [ 204.308490] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 204.309868] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 204.310497] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 Starting Load Kernel Modules... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ 205.399932] _warn_unseeded_randomness: 4 callbacks suppressed [ 205.400107] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 205.589907] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 205.590676] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 205.787257] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 206.850479] _warn_unseeded_randomness: 5 callbacks suppressed [ 206.850664] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 207.463355] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 207.553313] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 207.554072] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 208.266658] _warn_unseeded_randomness: 5 callbacks suppressed [ 208.266855] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 208.386640] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 208.387400] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 209.225321] systemd[1]: Starting Journal Service... Starting Journal Service... [ 209.379422] _warn_unseeded_randomness: 11 callbacks suppressed [ 209.379598] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 209.482637] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 209.483506] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 209.950194] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ 211.029981] _warn_unseeded_randomness: 3 callbacks suppressed [ 211.030161] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 211.031086] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 211.063835] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 212.254618] _warn_unseeded_randomness: 12 callbacks suppressed [ 212.254813] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 212.748302] systemd[1]: Mounted Debug File System. [ OK ] Mounted Debug File System. [ 212.808568] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ 212.862785] systemd[1]: Mounted POSIX Message Queue File System. [ OK ] Mounted POSIX Message Queue File System. [ 213.704002] systemd[1]: Started Create Static Device Nodes in /dev. [ OK ] Started Create Static Device Nodes in /dev. [ 214.096412] systemd[1]: Started Load Kernel Modules. [ OK ] Started Load Kernel Modules. [ 214.981463] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 215.078056] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 215.079024] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 215.240306] systemd[1]: Mounting Configuration File System... Mounting Configuration File System... [ 216.405671] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 216.648222] _warn_unseeded_randomness: 4 callbacks suppressed [ 216.648394] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 216.649942] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 216.650635] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 217.801566] systemd[1]: Starting udev Kernel Device Manager... Starting udev Kernel Device Manager... [ 217.879698] _warn_unseeded_randomness: 7 callbacks suppressed [ 217.879873] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 218.109340] random: get_random_bytes called from __prandom_timer+0x30/0x98 with crng_init=1 [ 219.283109] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 219.283833] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 219.354737] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 220.196310] systemd[1]: Started Remount Root and Kernel File Systems. [ OK ] Started Remount Root and Kernel File Systems. [ 220.346000] _warn_unseeded_randomness: 2 callbacks suppressed [ 220.346177] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 220.485457] systemd[1]: Mounted Configuration File System. [ OK ] Mounted Configuration File System. [ 220.518305] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 220.519486] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 220.989681] systemd[1]: Started Apply Kernel Variables. [ OK ] Started Apply Kernel Variables. [ 222.172225] _warn_unseeded_randomness: 5 callbacks suppressed [ 222.172400] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 222.292091] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=1 [ 222.621433] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 222.799674] systemd[1]: Reached target Local File Systems (Pre). [ OK ] Reached target Local File Systems (Pre). [ 222.897600] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 224.103866] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 224.105119] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 224.105790] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 224.369390] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 225.638221] _warn_unseeded_randomness: 2 callbacks suppressed [ 225.638398] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 225.639531] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 225.721320] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [* ] (1 of 5) A start job is running for…l Device Manager (27s / 30min 16s)[ 228.577634] _warn_unseeded_randomness: 1 callbacks suppressed [ 228.577813] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 228.717991] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 228.718803] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 228.921209] systemd[1]: Started Load/Save Random Seed. [ OK ] Started Load/Save Random Seed. [ 230.472059] systemd[1]: Started udev Kernel Device Manager. [ OK ] Started udev Kernel Device Manager. [** ] (1 of 3) A start job is running for Journal Service (35s / 30min 7s) [*** ] (1 of 3) A start job is running for Journal Service (35s / 30min 7s) [ *** ] (2 of 3) A start job is running for dev-ttyAMA0.device (36s / 30min) [ *** ] (2 of 3) A start job is running for dev-ttyAMA0.device (36s / 30min) [ ***] (2 of 3) A start job is running for dev-ttyAMA0.device (37s / 30min) [ **] (3 of 3) A start job is running for…dplug all Devices (37s / no limit) [ *] (3 of 3) A start job is running for…dplug all Devices (38s / no limit) [ **] (3 of 3) A start job is running for…dplug all Devices (38s / no limit) [ ***] (1 of 3) A start job is running for Journal Service (39s / 30min 7s) [ *** ] (1 of 3) A start job is running for Journal Service (39s / 30min 7s) [ *** ] (1 of 3) A start job is running for Journal Service (40s / 30min 7s)[ 240.878444] _warn_unseeded_randomness: 3 callbacks suppressed [ 240.878615] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [*** ] (2 of 3) A start job is running for dev-ttyAMA0.device (40s / 30min) [** ] (2 of 3) A start job is running for dev-ttyAMA0.device (41s / 30min) [* ] (2 of 3) A start job is running for dev-ttyAMA0.device (41s / 30min) [** ] (3 of 3) A start job is running for…dplug all Devices (42s / no limit) [*** ] (3 of 3) A start job is running for…dplug all Devices (42s / no limit) [ *** ] (3 of 3) A start job is running for…dplug all Devices (43s / no limit) [ *** ] (1 of 3) A start job is running for Journal Service (43s / 30min 7s) [ ***] (1 of 3) A start job is running for Journal Service (44s / 30min 7s) [ **] (1 of 3) A start job is running for Journal Service (44s / 30min 7s)[ 245.454536] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 245.455269] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 245.455846] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ *] (2 of 3) A start job is running for dev-ttyAMA0.device (45s / 30min)[ 246.873014] _warn_unseeded_randomness: 4 callbacks suppressed [ 246.873190] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 246.874103] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 246.952078] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ **] (2 of 3) A start job is running for dev-ttyAMA0.device (46s / 30min) [ ***] (2 of 3) A start job is running for dev-ttyAMA0.device (46s / 30min) [ *** ] (3 of 3) A start job is running for…dplug all Devices (47s / no limit) [ *** ] (3 of 3) A start job is running for…dplug all Devices (47s / no limit) [*** ] (3 of 3) A start job is running for…dplug all Devices (48s / no limit) [** ] (1 of 3) A start job is running for Journal Service (48s / 30min 7s) [* ] (1 of 3) A start job is running for Journal Service (49s / 30min 7s) [** ] (1 of 3) A start job is running for Journal Service (49s / 30min 7s) [*** ] (2 of 3) A start job is running for dev-ttyAMA0.device (50s / 30min) [ *** ] (2 of 3) A start job is running for dev-ttyAMA0.device (50s / 30min) [ *** ] (2 of 3) A start job is running for dev-ttyAMA0.device (51s / 30min) [ ***] (3 of 3) A start job is running for…dplug all Devices (51s / no limit) [ **] (3 of 3) A start job is running for…dplug all Devices (52s / no limit) [ *] (3 of 3) A start job is running for…dplug all Devices (52s / no limit)[ 253.602971] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [ 254.142004] _warn_unseeded_randomness: 1 callbacks suppressed [ 254.142183] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 Starting Flush Journal to Persistent Storage... [ 255.524653] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 255.525778] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ 255.526393] random: get_random_u32 called from arch_align_stack+0x34/0x50 with crng_init=1 [ 258.355156] systemd-journald[1274]: Received request to flush runtime journal from PID 1 [ 258.685857] _warn_unseeded_randomness: 4 callbacks suppressed [ 258.686041] random: get_random_u64 called from dup_task_struct+0xdc/0x3c8 with crng_init=1 [ 258.812409] random: get_random_u64 called from arch_mmap_rnd+0x98/0xb0 with crng_init=1 [ 258.814052] random: get_random_u64 called from randomize_stack_top+0x38/0x80 with crng_init=1 [ OK ] Started Flush Journal to Persistent Storage. [ 259.129285] random: crng init done [ 259.129732] random: 2 get_random_xx warning(s) missed due to ratelimiting Starting Create Volatile Files and Directories... [ **] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 3s / 30min) [ ***] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 4s / 30min) [ *** ] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 4s / 30min) [ *** ] (2 of 3) A start job is running for…d Directories (1min 5s / no limit) [*** ] (2 of 3) A start job is running for…d Directories (1min 5s / no limit) [** ] (2 of 3) A start job is running for…d Directories (1min 6s / no limit) [* ] (3 of 3) A start job is running for…g all Devices (1min 6s / no limit) [** ] (3 of 3) A start job is running for…g all Devices (1min 7s / no limit) [*** ] (3 of 3) A start job is running for…g all Devices (1min 7s / no limit) [ *** ] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 8s / 30min) [ *** ] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 8s / 30min) [ ***] (1 of 3) A start job is running for…v-ttyAMA0.device (1min 9s / 30min) [ **] (2 of 3) A start job is running for…d Directories (1min 9s / no limit) [ *] (2 of 3) A start job is running for… Directories (1min 10s / no limit) [ **] (2 of 3) A start job is running for… Directories (1min 10s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (1min 11s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 11s / no limit) [ *** ] (3 of 3) A start job is running for… all Devices (1min 12s / no limit) [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting Network Time Synchronization... [*** ] (3 of 4) A start job is running for…hronization (1min 18s / 31min 15s) [** ] (3 of 4) A start job is running for…hronization (1min 18s / 31min 15s) [* ] (3 of 4) A start job is running for…hronization (1min 19s / 31min 15s) [** ] (4 of 4) A start job is running for… all Devices (1min 19s / no limit) [ OK ] Started Update UTMP about System Boot/Shutdown. [*** ] (2 of 3) A start job is running for…hronization (1min 25s / 31min 15s) [ *** ] (2 of 3) A start job is running for…hronization (1min 25s / 31min 15s) [ *** ] (3 of 3) A start job is running for… all Devices (1min 26s / no limit) [ ***] (3 of 3) A start job is running for… all Devices (1min 26s / no limit) [ **] (3 of 3) A start job is running for… all Devices (1min 27s / no limit) [ *] (1 of 3) A start job is running for…-ttyAMA0.device (1min 27s / 30min) [ **] (1 of 3) A start job is running for…-ttyAMA0.device (1min 28s / 30min) [ OK ] Started Network Time Synchronization. [ OK ] Reached target System Time Synchronized. [ ***] (2 of 2) A start job is running for… all Devices (1min 33s / no limit) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 34s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 34s / 30min) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 35s / 30min) [** ] (2 of 2) A start job is running for… all Devices (1min 35s / no limit) [* ] (2 of 2) A start job is running for… all Devices (1min 36s / no limit) [** ] (2 of 2) A start job is running for… all Devices (1min 36s / no limit) [*** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 37s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 37s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 38s / 30min) [ ***] (2 of 2) A start job is running for… all Devices (1min 38s / no limit) [ **] (2 of 2) A start job is running for… all Devices (1min 39s / no limit) [ *] (2 of 2) A start job is running for… all Devices (1min 39s / no limit) [ **] (1 of 2) A start job is running for…-ttyAMA0.device (1min 40s / 30min) [ ***] (1 of 2) A start job is running for…-ttyAMA0.device (1min 40s / 30min) [ *** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 41s / 30min) [ *** ] (2 of 2) A start job is running for… all Devices (1min 41s / no limit) [*** ] (2 of 2) A start job is running for… all Devices (1min 42s / no limit) [** ] (2 of 2) A start job is running for… all Devices (1min 42s / no limit) [* ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 43s / 30min) [** ] (1 of 2) A start job is running for…-ttyAMA0.device (1min 43s / 30min) [ OK ] Found device /dev/ttyAMA0. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Basic System. [ 309.838349] virtio_net virtio0 enp0s1: renamed from eth0 [ OK ] Started Regular background program processing daemon. Starting System Logging Service... [ OK ] Started Daily apt upgrade and clean activities. [ OK ] Reached target Timers. Starting Permit User Sessions... Starting getty on tty2-tty6 if dbus and logind are not available... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyAMA0. [ OK ] Started Getty on tty1. [ OK ] Started System Logging Service. Debian GNU/Linux 9 compilator ttyAMA0 compilator login: