[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 4.20.0-rc4-next-20181128-00009-g44c557177b8d-dirty (anders@compilator) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05)) #9 SMP Wed Nov 28 13:29:17 CET 2018 [ 0.000000] Machine model: linux,dummy-virt [ 0.000000] earlycon: pl11 at MMIO 0x0000000009000000 (options '') [ 0.000000] printk: bootconsole [pl11] enabled [ 0.000000] efi: Getting EFI parameters from FDT: [ 0.000000] efi: UEFI not found. [ 0.000000] cma: Reserved 16 MiB at 0x00000000bf000000 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] NUMA: No NUMA configuration found [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x00000000bfffffff] [ 0.000000] NUMA: NODE_DATA [mem 0xbebf15c0-0xbebf2fff] [ 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+0xe0/0x6ec with crng_init=0 [ 0.000000] percpu: Embedded 490 pages/cpu @(____ptrval____) s1969240 r8192 d29608 u2007040 [ 0.000000] Detected VIPT I-cache on CPU0 [ 0.000000] CPU features: enabling workaround for ARM erratum 843419 [ 0.000000] CPU features: enabling workaround for ARM erratum 845719 [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 516096 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: root=/dev/root rootfstype=9p rootflags=trans=virtio console=ttyAMA0,38400n8 earlycon=pl011,0x9000000 initcall_debug [ 0.000000] Memory: 993360K/2097152K available (15708K kernel code, 3774K rwdata, 4852K rodata, 6144K init, 23374K bss, 94208K 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 41986 entries in 165 pages [ 0.000000] Running RCU self tests [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU dyntick-idle grace-period acceleration is enabled. [ 0.000000] rcu: RCU lockdep checking is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=64 to nr_cpu_ids=1. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] rcu: RCU debug extended QS entry/exit. [ 0.000000] Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] GICv2m: range[mem 0x08020000-0x08020fff], SPI[80:143] [ 0.000000] arch_timer: cp15 timer(s) running at 62.50MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x1cd42e208c, max_idle_ns: 881590405314 ns [ 0.000229] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.017458] Console: colour dummy device 80x25 [ 0.023863] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.024459] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.024940] ... MAX_LOCK_DEPTH: 48 [ 0.025472] ... MAX_LOCKDEP_KEYS: 8191 [ 0.025991] ... CLASSHASH_SIZE: 4096 [ 0.026478] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.026966] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.027475] ... CHAINHASH_SIZE: 32768 [ 0.028444] memory used by lock dependency info: 7775 kB [ 0.029011] per task-struct memory footprint: 2688 bytes [ 0.029548] ------------------------ [ 0.030009] | Locking API testsuite: [ 0.030501] ---------------------------------------------------------------------------- [ 0.031106] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.031667] -------------------------------------------------------------------------- [ 0.033004] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.046222] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.060127] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.074957] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.089910] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.106507] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.122846] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.140118] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.152004] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.163273] -------------------------------------------------------------------------- [ 0.163875] recursive read-lock: | ok | | ok | [ 0.168004] recursive read-lock #2: | ok | | ok | [ 0.172066] mixed read-write-lock: | ok | | ok | [ 0.176240] mixed write-read-lock: | ok | | ok | [ 0.180374] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.184577] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.188987] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.193905] -------------------------------------------------------------------------- [ 0.194876] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.200678] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.207016] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.212699] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.218410] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.224735] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.230693] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.236445] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.242697] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.248589] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.254582] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.261208] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.267473] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.274047] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.280978] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.287203] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.294144] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.300191] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.306291] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.312471] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.318373] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.324845] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.330944] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.337050] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.343927] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.350226] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.356940] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.363164] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.369889] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.376162] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.382844] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.388932] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.395737] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.401830] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.408574] hard-irq lock-inversion/123: ok | ok | ok | [ 0.414888] soft-irq lock-inversion/123: ok | ok | ok | [ 0.421944] hard-irq lock-inversion/132: ok | ok | ok | [ 0.427999] soft-irq lock-inversion/132: ok | ok | ok | [ 0.434941] hard-irq lock-inversion/213: ok | ok | ok | [ 0.441061] soft-irq lock-inversion/213: ok | ok | ok | [ 0.447945] hard-irq lock-inversion/231: ok | ok | ok | [ 0.454060] soft-irq lock-inversion/231: ok | ok | ok | [ 0.460846] hard-irq lock-inversion/312: ok | ok | ok | [ 0.467073] soft-irq lock-inversion/312: ok | ok | ok | [ 0.474013] hard-irq lock-inversion/321: ok | ok | ok | [ 0.479981] soft-irq lock-inversion/321: ok | ok | ok | [ 0.486813] hard-irq read-recursion/123: ok | [ 0.489104] soft-irq read-recursion/123: ok | [ 0.492007] hard-irq read-recursion/132: ok | [ 0.494384] soft-irq read-recursion/132: ok | [ 0.497225] hard-irq read-recursion/213: ok | [ 0.499577] soft-irq read-recursion/213: ok | [ 0.502510] hard-irq read-recursion/231: ok | [ 0.504906] soft-irq read-recursion/231: ok | [ 0.507768] hard-irq read-recursion/312: ok | [ 0.510088] soft-irq read-recursion/312: ok | [ 0.512928] hard-irq read-recursion/321: ok | [ 0.515323] soft-irq read-recursion/321: ok | [ 0.518198] -------------------------------------------------------------------------- [ 0.518775] | Wound/wait tests | [ 0.519224] --------------------- [ 0.519683] ww api failures: ok | ok | ok | [ 0.527541] ww contexts mixing: ok | ok | [ 0.531842] finishing ww context: ok | ok | ok | ok | [ 0.539539] locking mismatches: ok | ok | ok | [ 0.545744] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.566247] spinlock nest unlocked: ok | [ 0.568820] ----------------------------------------------------- [ 0.569377] |block | try |context| [ 0.569905] ----------------------------------------------------- [ 0.570442] context: ok | ok | ok | [ 0.576547] try: ok | ok | ok | [ 0.582516] block: ok | ok | ok | [ 0.588051] spinlock: ok | ok | ok | [ 0.594875] ------------------------------------------------------- [ 0.595480] Good, all 261 testcases passed! | [ 0.595961] --------------------------------- [ 0.706921] ODEBUG: selftest passed [ 0.713051] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.714012] pid_max: default: 32768 minimum: 301 [ 0.729827] LSM: Security Framework initializing [ 0.730738] Yama: becoming mindful. [ 0.734131] LoadPin: ready to pin (currently enforcing) [ 0.735248] SELinux: Initializing. [ 0.747027] *** VALIDATE SELinux *** [ 0.747975] AppArmor: AppArmor disabled by boot time parameter [ 0.801449] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.822192] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.826615] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.828697] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.880649] *** VALIDATE proc *** [ 0.908227] *** VALIDATE cgroup1 *** [ 0.908826] *** VALIDATE cgroup2 *** [ 0.952145] ../kernel/time/timer.c:1779 [ 0.958446] ../kernel/time/timer.c:1781 [ 0.961406] ../kernel/time/timer.c:1779 [ 0.967693] ../kernel/time/timer.c:1781 [ 0.970298] ../kernel/time/timer.c:1779 [ 0.975879] ../kernel/time/timer.c:1781 [ 0.978764] ../kernel/time/timer.c:1779 [ 0.984235] ../kernel/time/timer.c:1781 [ 0.986265] ../kernel/time/timer.c:1779 [ 0.992234] ../kernel/time/timer.c:1781 [ 0.996339] ../kernel/time/timer.c:1779 [ 1.002367] ../kernel/time/timer.c:1781 [ 1.011433] ASID allocator initialised with 32768 entries [ 1.015903] ../kernel/time/timer.c:1779 [ 1.022331] ../kernel/time/timer.c:1781 [ 1.023853] ../kernel/time/timer.c:1779 [ 1.024781] ../kernel/time/timer.c:1781 [ 1.029506] rcu: Hierarchical SRCU implementation. [ 1.031805] ../kernel/time/timer.c:1779 [ 1.037523] ../kernel/time/timer.c:1781 [ 1.040902] ../kernel/time/timer.c:1779 [ 1.046634] ../kernel/time/timer.c:1781 [ 1.048472] ../kernel/time/timer.c:1779 [ 1.049412] ../kernel/time/timer.c:1781 [ 1.155668] EFI services will not be available. [ 1.159064] ../kernel/time/timer.c:1779 [ 1.165989] ../kernel/time/timer.c:1781 [ 1.167311] ../kernel/time/timer.c:1779 [ 1.168777] ../kernel/time/timer.c:1781 [ 1.174049] ../kernel/time/timer.c:1779 [ 1.175647] ../kernel/time/timer.c:1779 [ 1.186021] ../kernel/time/timer.c:1781 [ 1.190975] ../kernel/time/timer.c:1781 [ 1.192301] ../kernel/time/timer.c:1779 [ 1.198264] ../kernel/time/timer.c:1781 [ 1.199604] ../kernel/time/timer.c:1779 [ 1.200555] ../kernel/time/timer.c:1781 [ 1.201610] smp: Bringing up secondary CPUs ... [ 1.202844] smp: Brought up 1 node, 1 CPU [ 1.203365] SMP: Total of 1 processors activated. [ 1.204112] CPU features: detected: 32-bit EL0 Support [ 1.204770] CPU features: detected: CRC32 instructions [ 1.239489] ../kernel/time/timer.c:1779 [ 1.251370] ../kernel/time/timer.c:1781 [ 1.252396] ../kernel/time/timer.c:1779 [ 1.253697] ../kernel/time/timer.c:1781 [ 1.563617] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.564604] CPU: All CPU(s) started at EL1 [ 1.566019] ../kernel/time/timer.c:1779 [ 1.566957] alternatives: patching kernel code [ 1.568867] _warn_unseeded_randomness: 98 callbacks suppressed [ 1.569276] random: get_random_u64 called from compute_layout+0x138/0x1b8 with crng_init=0 [ 1.580377] ../kernel/time/timer.c:1781 [ 1.622333] ../kernel/time/timer.c:1779 [ 1.625259] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 1.629563] ../kernel/time/timer.c:1781 [ 1.630901] ../kernel/time/timer.c:1779 [ 6.109720] node 0 initialised, 248300 pages in 4ms [ 6.121276] pgdatinit0 (15) used greatest stack depth: 13280 bytes left [ 6.123621] ../kernel/time/timer.c:1781 [ 6.138160] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 6.147964] ../kernel/time/timer.c:1779 [ 6.150668] ../kernel/time/timer.c:1805 [ 6.151279] ../kernel/time/timer.c:1807 [ 6.152090] ../kernel/time/timer.c:1809 [ 6.157199] ../kernel/time/timer.c:1811 [ 6.159508] ../kernel/time/timer.c:1813 [ 6.160110] ../kernel/time/timer.c:1817 [ 6.161801] ../kernel/time/timer.c:1805 [ 6.168325] ../kernel/time/timer.c:1807 [ 6.169016] ../kernel/time/timer.c:1809 [ 6.170727] ../kernel/time/timer.c:1811 [ 6.171342] ../kernel/time/timer.c:1813 [ 6.172623] ../kernel/time/timer.c:1817 [ 6.173723] ../kernel/time/timer.c:1805 [ 6.174254] ../kernel/time/timer.c:1807 [ 6.174809] ../kernel/time/timer.c:1809 [ 6.175457] ../kernel/time/timer.c:1781 [ 6.177402] ../kernel/time/timer.c:1779 [ 6.178037] ../kernel/time/timer.c:1811 [ 6.178688] ../kernel/time/timer.c:1813 [ 6.179250] ../kernel/time/timer.c:1817 [ 6.180223] ../kernel/time/timer.c:1805 [ 6.182757] ../kernel/time/timer.c:1807 [ 6.183359] ../kernel/time/timer.c:1809 [ 6.263586] ../kernel/time/timer.c:1781 [ 6.264192] devtmpfs: initialized [ 6.522380] workqueue: round-robin CPU selection forced, expect performance impact [ 6.579584] gcov: version magic: 0x4137332a [ 6.593746] ../kernel/time/timer.c:1779 [ 6.601898] ../kernel/time/timer.c:1811 [ 6.602577] ../kernel/time/timer.c:1813 [ 6.603211] ../kernel/time/timer.c:1817 [ 6.605251] ../kernel/time/timer.c:1781 [ 6.616504] Registered cp15_barrier emulation handler [ 6.618115] Registered setend emulation handler [ 6.660382] ../kernel/time/timer.c:1779 [ 6.667186] ../kernel/time/timer.c:1781 [ 16.691168] DMA-API: preallocated 65536 debug entries [ 16.691786] DMA-API: debugging enabled by kernel config [ 16.693491] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 16.697160] futex hash table entries: 256 (order: 3, 32768 bytes) [ 16.704891] Running postponed tracer tests: [ 16.706481] Testing tracer function: [ 16.706692] ../kernel/time/timer.c:1805 [ 16.708088] ../kernel/time/timer.c:1807 [ 16.708821] ../kernel/time/timer.c:1809 [ 16.717777] ../kernel/time/timer.c:1811 [ 16.718630] ../kernel/time/timer.c:1813 [ 16.719241] ../kernel/time/timer.c:1817 [ 16.738027] ftrace_replace_code: 1 [ 27.475124] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 27.486158] ../kernel/time/timer.c:1805 [ 27.496859] ../kernel/time/timer.c:1807 [ 27.507937] ../kernel/time/timer.c:1809 [ 27.720998] ../kernel/time/timer.c:1811 [ 27.737498] ../kernel/time/timer.c:1813 [ 27.748150] ../kernel/time/timer.c:1817 [ 27.782618] ftrace_replace_code: 1 [ 37.228491] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 37.233010] PASSED [ 37.233545] Testing dynamic ftrace: [ 39.368833] ftrace_replace_code: 1 [ 39.464993] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 39.465969] ../kernel/time/timer.c:1805 [ 39.466637] ../kernel/time/timer.c:1807 [ 39.467755] ../kernel/time/timer.c:1809 [ 39.585532] ../kernel/time/timer.c:1811 [ 39.586640] ../kernel/time/timer.c:1813 [ 39.587584] ../kernel/time/timer.c:1817 [ 39.589690] ../kernel/time/timer.c:1805 [ 39.590598] ../kernel/time/timer.c:1807 [ 39.591492] ../kernel/time/timer.c:1809 [ 39.712063] ../kernel/time/timer.c:1811 [ 39.713204] ../kernel/time/timer.c:1813 [ 39.714258] ../kernel/time/timer.c:1817 [ 39.716617] PASSED [ 39.717379] Testing dynamic ftrace ops #1: [ 48.083803] ftrace_replace_code: 1 [ 48.086940] ../kernel/time/timer.c:1805 [ 48.087862] ../kernel/time/timer.c:1807 [ 48.088438] ../kernel/time/timer.c:1809 [ 48.090229] ../kernel/time/timer.c:1811 [ 48.090951] ../kernel/time/timer.c:1813 [ 48.091566] ../kernel/time/timer.c:1817 [ 48.092705] ../kernel/time/timer.c:1805 [ 48.093294] ../kernel/time/timer.c:1807 [ 48.095578] ../kernel/time/timer.c:1809 [ 48.103638] ../kernel/time/timer.c:1811 [ 48.104358] ../kernel/time/timer.c:1813 [ 48.104977] ../kernel/time/timer.c:1817 [ 48.193361] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 48.197553] (1 0 1 0 0) [ 48.197799] (1 1 2 0 0) [ 48.208356] ftrace_replace_code: 1 [ 62.160987] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 62.164953] (2 1 3 0 1067788) [ 62.165254] (2 2 4 0 1067809) [ 62.176538] ftrace_replace_code: 1 [ 72.797428] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 72.801475] ../kernel/time/timer.c:1779 [ 72.803550] ../kernel/time/timer.c:1781 [ 72.814737] ftrace_replace_code: 1 [ 72.907868] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 72.912240] ftrace_replace_code: 1 [ 73.005791] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 73.010557] PASSED [ 73.012092] Testing dynamic ftrace ops #2: [ 81.379653] ftrace_replace_code: 1 [ 81.382104] ../kernel/time/timer.c:1805 [ 81.383046] ../kernel/time/timer.c:1807 [ 81.383652] ../kernel/time/timer.c:1809 [ 81.389691] ../kernel/time/timer.c:1811 [ 81.390404] ../kernel/time/timer.c:1813 [ 81.391012] ../kernel/time/timer.c:1817 [ 81.392126] ../kernel/time/timer.c:1805 [ 81.392680] ../kernel/time/timer.c:1807 [ 81.399031] ../kernel/time/timer.c:1809 [ 81.406736] ../kernel/time/timer.c:1811 [ 81.407403] ../kernel/time/timer.c:1813 [ 81.408000] ../kernel/time/timer.c:1817 [ 81.493885] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 81.497740] ftrace_replace_code: 1 [ 81.591099] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 81.608785] ftrace_replace_code: 1 [ 94.705267] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 94.708580] (1 0 1 1037839 0) [ 94.708845] (1 1 2 1037860 0) [ 94.723320] (2 1 3 1 753) [ 94.724439] (2 2 4 136 888) [ 94.761619] ftrace_replace_code: 1 [ 104.204925] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 104.207600] PASSED [ 104.208096] Testing ftrace recursion: [ 106.300158] ftrace_replace_code: 1 [ 106.395690] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 106.400057] ftrace_replace_code: 1 [ 106.494393] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 106.496894] PASSED [ 106.497411] Testing ftrace recursion safe: [ 108.590146] ftrace_replace_code: 1 [ 108.686120] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 108.690421] ftrace_replace_code: 1 [ 108.784020] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 108.786463] PASSED [ 108.786966] Testing ftrace regs(no arch support): [ 110.882389] ftrace_replace_code: 1 [ 110.978080] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 110.982540] ftrace_replace_code: 1 [ 111.076271] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 111.078353] PASSED [ 111.079977] Testing tracer nop: PASSED [ 111.082452] Testing tracer irqsoff: [ 111.097362] ftrace_replace_code: 1 [ 121.660573] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 121.693479] ftrace_replace_code: 1 [ 131.085208] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 131.088295] PASSED [ 131.091323] Testing tracer wakeup: [ 131.092080] ../kernel/time/timer.c:1779 [ 131.100732] ../kernel/time/timer.c:1781 [ 131.102482] ../kernel/time/timer.c:1779 [ 131.107325] ../kernel/time/timer.c:1781 [ 131.125399] ftrace_replace_code: 1 [ 141.730723] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 141.736367] ../kernel/time/timer.c:1779 [ 141.758653] ../kernel/time/timer.c:1781 [ 141.780272] ftrace_replace_code: 1 [ 141.920703] sched: DL replenish lagged too much [ 151.162462] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 151.166820] ../kernel/time/timer.c:1779 [ 151.172498] ../kernel/time/timer.c:1781 [ 151.173380] PASSED [ 151.174359] Testing tracer wakeup_rt: [ 151.175891] ../kernel/time/timer.c:1779 [ 151.177431] ../kernel/time/timer.c:1805 [ 151.178086] ../kernel/time/timer.c:1807 [ 151.178792] ../kernel/time/timer.c:1809 [ 151.185524] ../kernel/time/timer.c:1811 [ 151.186295] ../kernel/time/timer.c:1813 [ 151.186940] ../kernel/time/timer.c:1817 [ 151.189317] ../kernel/time/timer.c:1805 [ 151.189902] ../kernel/time/timer.c:1807 [ 151.190588] ../kernel/time/timer.c:1809 [ 151.191825] ../kernel/time/timer.c:1781 [ 151.193902] ../kernel/time/timer.c:1779 [ 151.195719] ../kernel/time/timer.c:1781 [ 151.212981] ftrace_replace_code: 1 [ 151.213368] ../kernel/time/timer.c:1811 [ 151.215003] ../kernel/time/timer.c:1813 [ 151.215600] ../kernel/time/timer.c:1817 [ 151.439503] ../kernel/time/timer.c:1805 [ 151.440157] ../kernel/time/timer.c:1807 [ 151.440808] ../kernel/time/timer.c:1809 [ 151.443595] ../kernel/time/timer.c:1811 [ 151.444363] ../kernel/time/timer.c:1813 [ 151.445052] ../kernel/time/timer.c:1817 [ 151.446189] ../kernel/time/timer.c:1805 [ 151.446777] ../kernel/time/timer.c:1807 [ 151.448884] ../kernel/time/timer.c:1809 [ 151.456566] ../kernel/time/timer.c:1811 [ 151.457285] ../kernel/time/timer.c:1813 [ 151.457934] ../kernel/time/timer.c:1817 [ 161.752945] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 161.763336] ../kernel/time/timer.c:1779 [ 161.785111] ../kernel/time/timer.c:1781 [ 161.805486] ftrace_replace_code: 1 [ 171.262918] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 171.267533] ../kernel/time/timer.c:1779 [ 171.274367] ../kernel/time/timer.c:1805 [ 171.275028] ../kernel/time/timer.c:1807 [ 171.275677] ../kernel/time/timer.c:1809 [ 171.276394] ../kernel/time/timer.c:1781 [ 171.278011] PASSED [ 171.278987] Testing tracer wakeup_dl: [ 171.279494] ../kernel/time/timer.c:1779 [ 171.284849] ../kernel/time/timer.c:1811 [ 171.285611] ../kernel/time/timer.c:1813 [ 171.286800] ../kernel/time/timer.c:1817 [ 171.287927] ../kernel/time/timer.c:1805 [ 171.288520] ../kernel/time/timer.c:1807 [ 171.289147] ../kernel/time/timer.c:1809 [ 171.290387] ../kernel/time/timer.c:1781 [ 171.293181] ../kernel/time/timer.c:1779 [ 171.295760] ../kernel/time/timer.c:1811 [ 171.296489] ../kernel/time/timer.c:1813 [ 171.297151] ../kernel/time/timer.c:1817 [ 171.298271] ../kernel/time/timer.c:1781 [ 171.316271] ftrace_replace_code: 1 [ 171.386322] ../kernel/time/timer.c:1805 [ 171.387310] ../kernel/time/timer.c:1807 [ 171.387913] ../kernel/time/timer.c:1809 [ 171.390441] ../kernel/time/timer.c:1811 [ 171.391171] ../kernel/time/timer.c:1813 [ 171.391793] ../kernel/time/timer.c:1817 [ 171.392860] ../kernel/time/timer.c:1805 [ 171.393447] ../kernel/time/timer.c:1807 [ 171.395739] ../kernel/time/timer.c:1809 [ 171.403728] ../kernel/time/timer.c:1811 [ 171.404459] ../kernel/time/timer.c:1813 [ 171.405152] ../kernel/time/timer.c:1817 [ 181.882106] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 181.892369] ../kernel/time/timer.c:1779 [ 181.906736] ../kernel/time/timer.c:1781 [ 181.928463] ftrace_replace_code: 1 [ 191.336771] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 191.341217] ../kernel/time/timer.c:1779 [ 191.346460] ../kernel/time/timer.c:1781 [ 191.347196] PASSED [ 191.350218] Testing tracer function_graph: [ 191.350386] ../kernel/trace/trace_selftest.c:766 [ 191.351414] ../kernel/trace/trace_selftest.c:768 [ 191.351997] ../kernel/trace/trace_selftest.c:770 [ 191.379962] ftrace_replace_code: 1 [ 191.380774] ../kernel/time/timer.c:1805 [ 191.381794] ../kernel/time/timer.c:1807 [ 191.382470] ../kernel/time/timer.c:1809 [ 191.388848] ../kernel/time/timer.c:1811 [ 191.389637] ../kernel/time/timer.c:1813 [ 191.390347] ../kernel/time/timer.c:1817 [ 191.391496] ../kernel/time/timer.c:1805 [ 191.393587] ../kernel/time/timer.c:1807 [ 191.394256] ../kernel/time/timer.c:1809 [ 191.401604] ../kernel/time/timer.c:1811 [ 191.402387] ../kernel/time/timer.c:1813 [ 191.403014] ../kernel/time/timer.c:1817 [ 191.584705] ../kernel/time/timer.c:1805 [ 191.585345] ../kernel/time/timer.c:1807 [ 191.586020] ../kernel/time/timer.c:1809 [ 191.588794] ../kernel/time/timer.c:1811 [ 191.589560] ../kernel/time/timer.c:1813 [ 191.590239] ../kernel/time/timer.c:1817 [ 191.591314] ../kernel/time/timer.c:1805 [ 191.591900] ../kernel/time/timer.c:1807 [ 191.594085] ../kernel/time/timer.c:1809 [ 191.602257] ../kernel/time/timer.c:1811 [ 191.603012] ../kernel/time/timer.c:1813 [ 191.603653] ../kernel/time/timer.c:1817 [ 201.871187] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 201.973706] ../kernel/trace/trace_selftest.c:773 [ 202.088561] ../kernel/trace/trace_selftest.c:778 [ 202.808251] ../kernel/trace/trace_selftest.c:780 [ 202.947525] ../kernel/time/timer.c:1805 [ 203.086184] ../kernel/time/timer.c:1807 [ 203.225644] ../kernel/time/timer.c:1809 [ 228.608240] watchdog: BUG: soft lockup - CPU#0 stuck for 25s! [swapper/0:1] [ 228.629854] Modules linked in: [ 228.648796] irq event stamp: 1882282 [ 228.669891] hardirqs last enabled at (1882281): [] _raw_spin_unlock_irq+0x38/0x78 [ 228.698146] hardirqs last disabled at (1882282): [] el1_irq+0xa8/0x1c0 [ 228.725934] softirqs last enabled at (1877252): [] __do_softirq+0x9a8/0x9f0 [ 228.753367] softirqs last disabled at (1877153): [] irq_exit+0x128/0x1b8 [ 228.778302] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G T 4.20.0-rc4-next-20181128-00009-g44c557177b8d-dirty #9 [ 228.805806] Hardware name: linux,dummy-virt (DT) [ 228.824418] pstate: 20400005 (nzCv daif +PAN -UAO) [ 228.846507] pc : _raw_spin_unlock_irq+0x3c/0x78 [ 228.868004] lr : _raw_spin_unlock_irq+0x38/0x78 [ 228.886074] sp : ffffff800804b8c0 [ 228.901905] x29: ffffff800804b8c0 x28: 0000000000000003 [ 228.925207] x27: ffffffc03f558040 x26: ffffff8009c15df8 [ 228.951559] x25: ffffff8009d12fb0 x24: 0000000000000000 [ 228.978266] x23: ffffff8008f07958 x22: ffffffc03f558040 [ 229.003986] x21: ffffffc03f5dc040 x20: ffffff80081a7c40 [ 229.029819] x19: ffffffc07ebdf6c0 x18: 0000000034f4d91d [ 229.055622] x17: 0000000000000000 x16: 0000000000000000 [ 229.080751] x15: ffffff8009c6a580 x14: ffffff800ac4e6a0 [ 229.106715] x13: ffffff800ac4fea0 x12: ffffff800ac4fea0 [ 229.131807] x11: ffffff800ac4ffa8 x10: ffffff800ac4ffa8 [ 229.157857] x9 : 0000000000000078 x8 : 00000000000ed66d [ 229.183185] x7 : 0000000000000048 x6 : 0000004075191000 [ 229.208763] x5 : ffffff8009cb82b0 x4 : ffffff8009c15bb0 [ 229.234900] x3 : fffffffffffffe80 x2 : ffffff800a6aa530 [ 229.260226] x1 : 000000000000001f x0 : 00000000000de5a6 [ 229.287040] Call trace: [ 229.306484] _raw_spin_unlock_irq+0x3c/0x78 [ 229.329676] finish_task_switch+0x160/0x220 [ 229.352572] __schedule+0xa78/0xaf8 [ 229.374364] schedule+0x9c/0xe0 [ 229.395063] schedule_timeout+0xa5c/0xb00 [ 229.417146] schedule_timeout_uninterruptible+0x3c/0x48 [ 229.442356] msleep+0x3c/0x58 [ 229.462931] trace_selftest_startup_function_graph+0x2a8/0x538 [ 229.488579] run_tracer_selftest+0x234/0x384 [ 229.512058] register_tracer+0x488/0x6a8 [ 229.534165] init_graph_trace+0x148/0x170 [ 229.556965] do_one_initcall+0x324/0x6e8 [ 229.578872] do_initcall_level+0x898/0x970 [ 229.601163] do_initcalls+0x64/0xa8 [ 229.622924] do_basic_setup+0x44/0x70 [ 229.645184] kernel_init_freeable+0x178/0x2bc [ 229.667879] kernel_init+0x28/0x140 [ 229.689792] ret_from_fork+0x10/0x18 [ 286.892355] watchdog: BUG: soft lockup - CPU#0 stuck for 28s! [swapper/0:1] [ 286.914185] Modules linked in: [ 286.933235] irq event stamp: 1888200 [ 286.953462] hardirqs last enabled at (1888199): [] _raw_spin_unlock_irq+0x38/0x78 [ 286.982407] hardirqs last disabled at (1888200): [] el1_irq+0xa8/0x1c0 [ 287.009991] softirqs last enabled at (1877252): [] __do_softirq+0x9a8/0x9f0 [ 287.038030] softirqs last disabled at (1877153): [] irq_exit+0x128/0x1b8 [ 287.062877] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G L T 4.20.0-rc4-next-20181128-00009-g44c557177b8d-dirty #9 [ 287.091462] Hardware name: linux,dummy-virt (DT) [ 287.110521] pstate: 20400005 (nzCv daif +PAN -UAO) [ 287.132961] pc : _raw_spin_unlock_irq+0x3c/0x78 [ 287.154868] lr : _raw_spin_unlock_irq+0x38/0x78 [ 287.173686] sp : ffffff800804b8c0 [ 287.190020] x29: ffffff800804b8c0 x28: 0000000000000003 [ 287.213712] x27: ffffffc03f558040 x26: ffffff8009c15df8 [ 287.237427] x25: ffffff8009d12fb0 x24: 0000000000000000 [ 287.261316] x23: ffffff8008f07958 x22: ffffffc03f558040 [ 287.285431] x21: ffffffc03f5dc040 x20: ffffff80081a7c40 [ 287.309492] x19: ffffffc07ebdf6c0 x18: 0000000034f4d91d [ 287.333568] x17: 0000000000000000 x16: 0000000000000000 [ 287.357693] x15: ffffff8009c6a580 x14: ffffff800ac4e6a0 [ 287.381491] x13: ffffff800ac4fea0 x12: ffffff800ac4fea0 [ 287.405434] x11: ffffff800ac4ffa8 x10: ffffff800ac4ffa8 [ 287.429113] x9 : 00000000000002a0 x8 : 00000000002eae3b [ 287.452878] x7 : 0000000000000270 x6 : 0000004075191000 [ 287.476692] x5 : ffffff8009cb82b0 x4 : ffffff8009c15bb0 [ 287.500535] x3 : fffffffffffffe80 x2 : ffffff800a6aa530 [ 287.523560] x1 : 000000000000001f x0 : 00000000000e164a [ 287.547488] Call trace: [ 287.565466] _raw_spin_unlock_irq+0x3c/0x78 [ 287.586006] finish_task_switch+0x160/0x220 [ 287.606941] __schedule+0xa78/0xaf8 [ 287.626565] schedule+0x9c/0xe0 [ 287.645641] schedule_timeout+0xa5c/0xb00 [ 287.666387] schedule_timeout_uninterruptible+0x3c/0x48 [ 287.688424] msleep+0x3c/0x58 [ 287.707198] trace_selftest_startup_function_graph+0x2a8/0x538 [ 287.730206] run_tracer_selftest+0x234/0x384 [ 287.750759] register_tracer+0x488/0x6a8 [ 287.771105] init_graph_trace+0x148/0x170 [ 287.791314] do_one_initcall+0x324/0x6e8 [ 287.811629] do_initcall_level+0x898/0x970 [ 287.832118] do_initcalls+0x64/0xa8 [ 287.851753] do_basic_setup+0x44/0x70 [ 287.871569] kernel_init_freeable+0x178/0x2bc [ 287.892397] kernel_init+0x28/0x140 [ 287.911802] ret_from_fork+0x10/0x18 [ 345.362407] watchdog: BUG: soft lockup - CPU#0 stuck for 28s! [ksoftirqd/0:9] [ 345.383209] Modules linked in: [ 345.400779] irq event stamp: 39696 [ 345.419737] hardirqs last enabled at (39695): [] _raw_spin_unlock_irq+0x38/0x78 [ 345.447033] hardirqs last disabled at (39696): [] el1_irq+0xa8/0x1c0 [ 345.472772] softirqs last enabled at (32056): [] __do_softirq+0x9a8/0x9f0 [ 345.499475] softirqs last disabled at (31941): [] run_ksoftirqd+0x30/0x68 [ 345.522983] CPU: 0 PID: 9 Comm: ksoftirqd/0 Tainted: G L T 4.20.0-rc4-next-20181128-00009-g44c557177b8d-dirty #9 [ 345.549820] Hardware name: linux,dummy-virt (DT) [ 345.567214] pstate: 20400005 (nzCv daif +PAN -UAO) [ 345.587484] pc : _raw_spin_unlock_irq+0x3c/0x78 [ 345.607734] lr : _raw_spin_unlock_irq+0x38/0x78 [ 345.624802] sp : ffffff800808bc90 [ 345.639678] x29: ffffff800808bc90 x28: 0000000000000003 [ 345.661902] x27: ffffffc03f5dc040 x26: ffffff8009c15df8 [ 345.684029] x25: ffffff8009d12fb0 x24: 0000000000000000 [ 345.706173] x23: ffffff8008f07958 x22: ffffffc03f5dc040 [ 345.728458] x21: ffffffc03f558040 x20: ffffff80081a7c40 [ 345.750475] x19: ffffffc07ebdf6c0 x18: 0000000034f4d91d [ 345.772515] x17: 0000000000000000 x16: 0000000000000000 [ 345.794803] x15: ffffff8009c6a580 x14: ffffff800ac4e6a0 [ 345.817118] x13: ffffff800ac4fea0 x12: ffffff800ac4fea0 [ 345.839198] x11: ffffff800ac4ffa8 x10: ffffff800ac4ffa8 [ 345.861615] x9 : 0000000000000c78 x8 : 00000000004ea607 [ 345.883543] x7 : 0000000000000c48 x6 : 0000004075191000 [ 345.905816] x5 : ffffff8009cb82b0 x4 : ffffff8009c15bb0 [ 345.928224] x3 : 00000000000e47fa x2 : 00000000000e47fa [ 345.950369] x1 : 0000000000000001 x0 : 00000000000e47fa [ 345.973312] Call trace: [ 345.990513] _raw_spin_unlock_irq+0x3c/0x78 [ 346.010481] finish_task_switch+0x160/0x220 [ 346.030743] __schedule+0xa78/0xaf8 [ 346.049687] preempt_schedule_common+0x4c/0x90 [ 346.069508] _cond_resched+0x40/0x50 [ 346.088222] run_ksoftirqd+0x50/0x68 [ 346.106636] smpboot_thread_fn+0x400/0x428 [ 346.125783] kthread+0x154/0x170 [ 346.143997] ret_from_fork+0x10/0x18 [ 346.265913] ../kernel/time/timer.c:1811 [ 346.379394] ../kernel/time/timer.c:1813 [ 346.493312] ../kernel/time/timer.c:1817 [ 346.586846] ../kernel/trace/trace_selftest.c:792 [ 346.679913] ../kernel/trace/trace_selftest.c:794 [ 346.685432] ../kernel/trace/trace_selftest.c:797 [ 346.713953] ../kernel/trace/trace_selftest.c:799 [ 346.755227] ftrace_replace_code: 1 [ 358.534183] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 358.537640] ../kernel/trace/trace_selftest.c:802 [ 358.538334] ../kernel/trace/trace_selftest.c:804 [ 358.539071] PASSED [ 358.755292] _warn_unseeded_randomness: 7 callbacks suppressed [ 358.755570] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 358.757714] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 358.760429] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 358.785749] prandom: seed boundary self test passed [ 358.827391] prandom: 100 self tests passed [ 358.835822] pinctrl core: initialized pinctrl subsystem [ 358.973053] regulator-dummy: Failed to create debugfs directory [ 358.984880] [ 358.985422] ************************************************************* [ 358.986070] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 358.986670] ** ** [ 358.987795] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 358.988366] ** ** [ 358.988946] ** This means that this kernel is built to expose internal ** [ 358.989532] ** IOMMU data structures, which may compromise security on ** [ 358.990146] ** your system. ** [ 358.990708] ** ** [ 358.991273] ** If you see this message and you are not debugging the ** [ 358.992391] ** kernel, report this immediately to your vendor! ** [ 358.992963] ** ** [ 358.993527] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 358.994120] ************************************************************* [ 359.034526] DMI not present or invalid. [ 359.110135] NET: Registered protocol family 16 [ 359.127689] ../kernel/time/timer.c:1779 [ 359.129688] ../kernel/time/timer.c:1781 [ 359.147719] audit: initializing netlink subsys (disabled) [ 359.151418] ../kernel/time/timer.c:1779 [ 359.156880] ../kernel/time/timer.c:1781 [ 359.345000] cpuidle: using governor ladder [ 359.347575] cpuidle: using governor menu [ 359.374973] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 359.377592] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 359.378555] ../kernel/time/timer.c:1779 [ 359.379766] ../kernel/time/timer.c:1805 [ 359.380372] ../kernel/time/timer.c:1807 [ 359.381629] ../kernel/time/timer.c:1809 [ 359.384194] audit: type=2000 audit(203.956:1): state=initialized audit_enabled=0 res=1 [ 359.387175] ../kernel/time/timer.c:1811 [ 359.387879] ../kernel/time/timer.c:1813 [ 359.388519] ../kernel/time/timer.c:1817 [ 359.395484] ../kernel/time/timer.c:1805 [ 359.396074] ../kernel/time/timer.c:1807 [ 359.396681] ../kernel/time/timer.c:1809 [ 359.400657] ../kernel/time/timer.c:1811 [ 359.401350] ../kernel/time/timer.c:1813 [ 359.401978] ../kernel/time/timer.c:1817 [ 359.408810] ../kernel/time/timer.c:1781 [ 359.415073] ../kernel/time/timer.c:1779 [ 359.431130] ../kernel/time/timer.c:1781 [ 359.599337] DMA: preallocated 256 KiB pool for atomic allocations [ 359.717844] Serial: AMBA PL011 UART driver [ 360.775895] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 360.779548] printk: console [ttyAMA0] enabled [ 360.779548] printk: console [ttyAMA0] enabled [ 360.781365] printk: bootconsole [pl11] disabled [ 360.781365] printk: bootconsole [pl11] disabled [ 360.796703] ../kernel/time/timer.c:1779 [ 360.808161] ../kernel/time/timer.c:1781 [ 360.925589] _warn_unseeded_randomness: 42 callbacks suppressed [ 360.925888] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 360.927772] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 361.634792] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 361.638665] ../kernel/time/timer.c:1779 [ 361.640491] ../kernel/time/timer.c:1805 [ 361.641217] ../kernel/time/timer.c:1807 [ 361.641978] ../kernel/time/timer.c:1809 [ 361.648423] ../kernel/time/timer.c:1811 [ 361.649289] ../kernel/time/timer.c:1813 [ 361.650055] ../kernel/time/timer.c:1817 [ 361.651253] ../kernel/time/timer.c:1805 [ 361.656974] ../kernel/time/timer.c:1807 [ 361.657743] ../kernel/time/timer.c:1809 [ 361.662942] ../kernel/time/timer.c:1811 [ 361.663799] ../kernel/time/timer.c:1813 [ 361.664920] ../kernel/time/timer.c:1817 [ 361.667691] ../kernel/time/timer.c:1781 [ 361.673051] ../kernel/time/timer.c:1779 [ 361.674585] ../kernel/time/timer.c:1833 [ 361.675268] ../kernel/time/timer.c:1805 [ 361.676007] ../kernel/time/timer.c:1807 [ 361.676739] ../kernel/time/timer.c:1809 [ 361.682923] ../kernel/time/timer.c:1781 [ 361.689409] ../kernel/time/timer.c:1779 [ 361.696036] ../kernel/time/timer.c:1781 [ 361.716442] ../kernel/time/timer.c:1779 [ 361.723720] ../kernel/time/timer.c:1781 [ 361.735374] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 361.750659] ../kernel/time/timer.c:1779 [ 361.770874] ../kernel/time/timer.c:1781 [ 361.785623] ../kernel/time/timer.c:1779 [ 361.793373] ../kernel/time/timer.c:1781 [ 361.805544] ../kernel/time/timer.c:1779 [ 361.812696] ../kernel/time/timer.c:1781 [ 361.819048] ../kernel/time/timer.c:1779 [ 361.825885] ../kernel/time/timer.c:1781 [ 361.848020] ../kernel/time/timer.c:1779 [ 361.854953] ../kernel/time/timer.c:1781 [ 361.932356] kobject: 'renesas,r9a06g032-sysctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 361.946420] kobject: 'renesas-cpg-mssr' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 500) [ 361.994949] ../kernel/time/timer.c:1779 [ 362.001940] ../kernel/time/timer.c:1781 [ 362.018150] arm-v7s io-pgtable: self test ok [ 362.020707] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 362.030539] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 362.037911] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 362.045959] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 362.055181] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 362.063292] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 362.071725] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 362.083225] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 362.096678] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 362.111079] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 362.124759] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 362.138688] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 362.155522] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 362.190645] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 362.225340] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 362.262457] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 362.303600] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 362.346703] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 362.388807] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 362.424869] ../kernel/time/timer.c:1779 [ 362.430722] ../kernel/time/timer.c:1781 [ 362.434190] vgaarb: loaded [ 362.458004] _warn_unseeded_randomness: 34 callbacks suppressed [ 362.458272] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 362.460082] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 362.497830] pps_core: LinuxPPS API ver. 1 registered [ 362.498582] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 362.504104] PTP clock support registered [ 362.515963] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 362.516953] kobject: 'brcmstb-reboot' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 362.581285] ../kernel/time/timer.c:1779 [ 362.585117] ../kernel/time/timer.c:1781 [ 362.603154] ../kernel/time/timer.c:1779 [ 362.609071] ../kernel/time/timer.c:1781 [ 362.736349] NetLabel: Initializing [ 362.737060] NetLabel: domain hash size = 128 [ 362.737874] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 362.749987] NetLabel: unlabeled traffic allowed by default [ 362.752535] ../kernel/time/timer.c:1779 [ 362.759650] ../kernel/time/timer.c:1781 [ 362.775807] ../kernel/time/timer.c:1779 [ 362.778461] ../kernel/time/timer.c:1781 [ 362.779294] clocksource: Switched to clocksource arch_sys_counter [ 368.876232] _warn_unseeded_randomness: 4 callbacks suppressed [ 368.876499] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 368.879063] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 368.882895] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 368.902749] VFS: Disk quotas dquot_6.6.0 [ 368.908789] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 368.943778] *** VALIDATE hugetlbfs *** [ 368.971826] ../kernel/time/timer.c:1779 [ 368.997038] ../kernel/time/timer.c:1781 [ 369.007718] ../kernel/time/timer.c:1779 [ 369.011472] ../kernel/time/timer.c:1781 [ 369.021169] ../kernel/time/timer.c:1779 [ 369.024924] ../kernel/time/timer.c:1781 [ 369.035688] ../kernel/time/timer.c:1779 [ 369.039593] ../kernel/time/timer.c:1781 [ 369.050029] ../kernel/time/timer.c:1779 [ 369.054495] ../kernel/time/timer.c:1781 [ 369.064081] ../kernel/time/timer.c:1779 [ 369.067874] ../kernel/time/timer.c:1781 [ 369.078059] ../kernel/time/timer.c:1779 [ 369.081936] ../kernel/time/timer.c:1781 [ 369.091996] ../kernel/time/timer.c:1779 [ 369.095769] ../kernel/time/timer.c:1781 [ 369.107058] ../kernel/time/timer.c:1779 [ 369.110937] ../kernel/time/timer.c:1781 [ 369.121114] ../kernel/time/timer.c:1779 [ 369.124813] ../kernel/time/timer.c:1781 [ 369.135772] ../kernel/time/timer.c:1779 [ 369.139517] ../kernel/time/timer.c:1781 [ 369.154724] ../kernel/time/timer.c:1779 [ 369.158634] ../kernel/time/timer.c:1781 [ 369.168579] ../kernel/time/timer.c:1779 [ 369.172359] ../kernel/time/timer.c:1781 [ 369.182591] ../kernel/time/timer.c:1779 [ 369.186576] ../kernel/time/timer.c:1781 [ 369.197047] ../kernel/time/timer.c:1779 [ 369.200762] ../kernel/time/timer.c:1781 [ 369.211492] ../kernel/time/timer.c:1779 [ 369.215178] ../kernel/time/timer.c:1781 [ 369.225983] ../kernel/time/timer.c:1779 [ 369.229246] ../kernel/time/timer.c:1781 [ 369.240816] ../kernel/time/timer.c:1779 [ 369.244624] ../kernel/time/timer.c:1781 [ 369.256549] ../kernel/time/timer.c:1779 [ 369.260325] ../kernel/time/timer.c:1781 [ 369.271972] ../kernel/time/timer.c:1779 [ 369.275643] ../kernel/time/timer.c:1781 [ 369.287176] ../kernel/time/timer.c:1779 [ 369.290827] ../kernel/time/timer.c:1781 [ 369.302465] ../kernel/time/timer.c:1779 [ 369.306148] ../kernel/time/timer.c:1781 [ 369.316957] ../kernel/time/timer.c:1779 [ 369.320935] ../kernel/time/timer.c:1781 [ 369.332988] ../kernel/time/timer.c:1779 [ 369.336621] ../kernel/time/timer.c:1781 [ 369.347990] ../kernel/time/timer.c:1779 [ 369.351711] ../kernel/time/timer.c:1781 [ 369.363433] ../kernel/time/timer.c:1779 [ 369.367145] ../kernel/time/timer.c:1781 [ 369.379013] ../kernel/time/timer.c:1779 [ 369.382991] ../kernel/time/timer.c:1781 [ 369.394701] ../kernel/time/timer.c:1779 [ 369.398385] ../kernel/time/timer.c:1781 [ 369.410315] ../kernel/time/timer.c:1779 [ 369.414298] ../kernel/time/timer.c:1781 [ 369.425914] ../kernel/time/timer.c:1779 [ 369.428940] ../kernel/time/timer.c:1781 [ 369.440558] ../kernel/time/timer.c:1779 [ 369.444265] ../kernel/time/timer.c:1781 [ 369.455913] ../kernel/time/timer.c:1779 [ 369.459972] ../kernel/time/timer.c:1781 [ 369.471326] ../kernel/time/timer.c:1779 [ 369.475040] ../kernel/time/timer.c:1781 [ 369.486875] ../kernel/time/timer.c:1779 [ 369.490573] ../kernel/time/timer.c:1781