[ 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)) #7 SMP Wed Nov 28 12:58:45 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: 993496K/2097152K available (15572K kernel code, 3774K rwdata, 4852K rodata, 6144K init, 23374K bss, 94072K 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.000237] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.017978] Console: colour dummy device 80x25 [ 0.024506] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.025139] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.025731] ... MAX_LOCK_DEPTH: 48 [ 0.026312] ... MAX_LOCKDEP_KEYS: 8191 [ 0.026877] ... CLASSHASH_SIZE: 4096 [ 0.027444] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.028460] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.029027] ... CHAINHASH_SIZE: 32768 [ 0.029676] memory used by lock dependency info: 7775 kB [ 0.030342] per task-struct memory footprint: 2688 bytes [ 0.030999] ------------------------ [ 0.031536] | Locking API testsuite: [ 0.032054] ---------------------------------------------------------------------------- [ 0.033089] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.033675] -------------------------------------------------------------------------- [ 0.034620] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.047895] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.061822] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.076807] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.091846] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.108830] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.125726] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.145049] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.156375] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.167886] -------------------------------------------------------------------------- [ 0.168547] recursive read-lock: | ok | | ok | [ 0.172721] recursive read-lock #2: | ok | | ok | [ 0.176849] mixed read-write-lock: | ok | | ok | [ 0.180911] mixed write-read-lock: | ok | | ok | [ 0.185153] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.189347] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.193821] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.198392] -------------------------------------------------------------------------- [ 0.199795] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.205706] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.211694] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.217975] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.223937] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.230558] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.236555] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.242434] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.248975] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.255009] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.261121] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.268033] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.274462] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.281287] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.287563] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.294335] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.300650] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.307369] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.313848] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.320235] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.326148] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.332375] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.339216] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.345403] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.352306] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.358530] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.365479] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.371653] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.378457] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.384618] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.391336] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.397478] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.404372] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.410409] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.417298] hard-irq lock-inversion/123: ok | ok | ok | [ 0.423508] soft-irq lock-inversion/123: ok | ok | ok | [ 0.430462] hard-irq lock-inversion/132: ok | ok | ok | [ 0.436651] soft-irq lock-inversion/132: ok | ok | ok | [ 0.443508] hard-irq lock-inversion/213: ok | ok | ok | [ 0.449799] soft-irq lock-inversion/213: ok | ok | ok | [ 0.456689] hard-irq lock-inversion/231: ok | ok | ok | [ 0.462832] soft-irq lock-inversion/231: ok | ok | ok | [ 0.469852] hard-irq lock-inversion/312: ok | ok | ok | [ 0.476130] soft-irq lock-inversion/312: ok | ok | ok | [ 0.483056] hard-irq lock-inversion/321: ok | ok | ok | [ 0.489297] soft-irq lock-inversion/321: ok | ok | ok | [ 0.496322] hard-irq read-recursion/123: ok | [ 0.498755] soft-irq read-recursion/123: ok | [ 0.501714] hard-irq read-recursion/132: ok | [ 0.504116] soft-irq read-recursion/132: ok | [ 0.506993] hard-irq read-recursion/213: ok | [ 0.509405] soft-irq read-recursion/213: ok | [ 0.512470] hard-irq read-recursion/231: ok | [ 0.514872] soft-irq read-recursion/231: ok | [ 0.517890] hard-irq read-recursion/312: ok | [ 0.520379] soft-irq read-recursion/312: ok | [ 0.523357] hard-irq read-recursion/321: ok | [ 0.526338] soft-irq read-recursion/321: ok | [ 0.528954] -------------------------------------------------------------------------- [ 0.530095] | Wound/wait tests | [ 0.530632] --------------------- [ 0.531150] ww api failures: ok | ok | ok | [ 0.539394] ww contexts mixing: ok | ok | [ 0.543599] finishing ww context: ok | ok | ok | ok | [ 0.552859] locking mismatches: ok | ok | ok | [ 0.558628] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.582868] spinlock nest unlocked: ok | [ 0.585791] ----------------------------------------------------- [ 0.586338] |block | try |context| [ 0.586898] ----------------------------------------------------- [ 0.587414] context: ok | ok | ok | [ 0.594123] try: ok | ok | ok | [ 0.599639] block: ok | ok | ok | [ 0.605343] spinlock: ok | ok | ok | [ 0.612350] ------------------------------------------------------- [ 0.613000] Good, all 261 testcases passed! | [ 0.613644] --------------------------------- [ 0.732460] ODEBUG: selftest passed [ 0.738769] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 0.739802] pid_max: default: 32768 minimum: 301 [ 0.758325] LSM: Security Framework initializing [ 0.759834] Yama: becoming mindful. [ 0.763082] LoadPin: ready to pin (currently enforcing) [ 0.764725] SELinux: Initializing. [ 0.777453] *** VALIDATE SELinux *** [ 0.778500] AppArmor: AppArmor disabled by boot time parameter [ 0.834027] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.858936] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.863580] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.865366] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 0.922425] *** VALIDATE proc *** [ 0.951666] *** VALIDATE cgroup1 *** [ 0.952289] *** VALIDATE cgroup2 *** [ 1.055731] ASID allocator initialised with 32768 entries [ 1.071725] rcu: Hierarchical SRCU implementation. [ 1.201394] EFI services will not be available. [ 1.243493] smp: Bringing up secondary CPUs ... [ 1.244264] smp: Brought up 1 node, 1 CPU [ 1.244886] SMP: Total of 1 processors activated. [ 1.245636] CPU features: detected: 32-bit EL0 Support [ 1.246378] CPU features: detected: CRC32 instructions [ 1.602599] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 1.603636] CPU: All CPU(s) started at EL1 [ 1.605008] alternatives: patching kernel code [ 1.606899] _warn_unseeded_randomness: 98 callbacks suppressed [ 1.607319] random: get_random_u64 called from compute_layout+0x138/0x1b8 with crng_init=0 [ 1.663451] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 5.808426] node 0 initialised, 248300 pages in 4ms [ 5.820354] pgdatinit0 (15) used greatest stack depth: 13280 bytes left [ 5.843167] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 5.952328] devtmpfs: initialized [ 6.191524] workqueue: round-robin CPU selection forced, expect performance impact [ 6.282076] gcov: version magic: 0x4137332a [ 6.320122] Registered cp15_barrier emulation handler [ 6.321173] Registered setend emulation handler [ 17.035610] DMA-API: preallocated 65536 debug entries [ 17.036716] DMA-API: debugging enabled by kernel config [ 17.038472] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 17.042129] futex hash table entries: 256 (order: 3, 32768 bytes) [ 17.050399] Running postponed tracer tests: [ 17.052009] Testing tracer function: [ 17.082928] ftrace_replace_code: 1 [ 28.261104] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 28.539331] ftrace_replace_code: 1 [ 38.022964] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 38.027390] PASSED [ 38.027911] Testing dynamic ftrace: [ 40.137020] ftrace_replace_code: 1 [ 40.228064] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 40.471719] PASSED [ 40.472507] Testing dynamic ftrace ops #1: [ 48.876269] ftrace_replace_code: 1 [ 48.973160] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 48.977649] (1 0 1 0 0) [ 48.977871] (1 1 2 0 0) [ 48.988963] ftrace_replace_code: 1 [ 63.279888] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 63.282128] (2 1 3 0 1079054) [ 63.282449] (2 2 4 0 1079075) [ 63.295125] ftrace_replace_code: 1 [ 73.996274] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 74.011933] ftrace_replace_code: 1 [ 74.102385] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 74.107428] ftrace_replace_code: 1 [ 74.196432] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 74.201211] PASSED [ 74.202894] Testing dynamic ftrace ops #2: [ 82.599854] ftrace_replace_code: 1 [ 82.701551] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 82.705059] ftrace_replace_code: 1 [ 82.794323] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 82.811947] ftrace_replace_code: 1 [ 96.255133] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 96.258478] (1 0 1 1055445 0) [ 96.258750] (1 1 2 1055466 0) [ 96.273893] (2 1 3 1 654) [ 96.275075] (2 2 4 136 789) [ 96.312543] ftrace_replace_code: 1 [ 105.779005] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 105.781137] PASSED [ 105.782201] Testing ftrace recursion: [ 107.883836] ftrace_replace_code: 1 [ 107.975009] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 107.979274] ftrace_replace_code: 1 [ 108.068582] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 108.070412] PASSED [ 108.071650] Testing ftrace recursion safe: [ 110.171944] ftrace_replace_code: 1 [ 110.264041] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 110.269047] ftrace_replace_code: 1 [ 110.358318] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 110.361435] PASSED [ 110.362168] Testing ftrace regs(no arch support): [ 112.462529] ftrace_replace_code: 1 [ 112.554555] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 112.559421] ftrace_replace_code: 1 [ 112.648506] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 112.651670] PASSED [ 112.653520] Testing tracer nop: PASSED [ 112.656467] Testing tracer irqsoff: [ 112.671758] ftrace_replace_code: 1 [ 123.405811] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 123.425586] ftrace_replace_code: 1 [ 132.998029] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 133.001149] PASSED [ 133.004449] Testing tracer wakeup: [ 133.035650] ftrace_replace_code: 1 [ 143.690723] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 143.725293] ftrace_replace_code: 1 [ 153.213898] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 153.225078] PASSED [ 153.226079] Testing tracer wakeup_rt: [ 153.255982] ftrace_replace_code: 1 [ 164.067911] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 164.090838] ftrace_replace_code: 1 [ 173.559097] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 173.569550] PASSED [ 173.570563] Testing tracer wakeup_dl: [ 173.599050] ftrace_replace_code: 1 [ 184.263976] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 184.295319] ftrace_replace_code: 1 [ 193.878977] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 193.889394] PASSED [ 193.891417] Testing tracer function_graph: [ 193.892580] ../kernel/trace/trace_selftest.c:766 [ 193.893574] ../kernel/trace/trace_selftest.c:768 [ 193.894161] ../kernel/trace/trace_selftest.c:770 [ 193.922957] ftrace_replace_code: 1 [ 204.441962] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 204.550715] ../kernel/trace/trace_selftest.c:773 [ 204.669974] ../kernel/trace/trace_selftest.c:778 [ 205.487869] ../kernel/trace/trace_selftest.c:780 [ 246.783123] watchdog: BUG: soft lockup - CPU#0 stuck for 28s! [kworker/0:1:13] [ 246.812419] Modules linked in: [ 246.830320] irq event stamp: 7462 [ 246.849828] hardirqs last enabled at (7461): [] _raw_spin_unlock_irq+0x38/0x78 [ 246.877574] hardirqs last disabled at (7462): [] el1_irq+0xa8/0x1c0 [ 246.904127] softirqs last enabled at (958): [] __do_softirq+0x9a8/0x9f0 [ 246.930487] softirqs last disabled at (951): [] irq_exit+0x128/0x1b8 [ 246.954096] CPU: 0 PID: 13 Comm: kworker/0:1 Tainted: G T 4.20.0-rc4-next-20181128-00009-g44c557177b8d-dirty #7 [ 246.981708] Hardware name: linux,dummy-virt (DT) [ 247.006309] Workqueue: (null) (events) [ 247.028956] pstate: 20400005 (nzCv daif +PAN -UAO) [ 247.050536] pc : _raw_spin_unlock_irq+0x3c/0x78 [ 247.071421] lr : _raw_spin_unlock_irq+0x38/0x78 [ 247.089414] sp : ffffff80080abcd0 [ 247.104987] x29: ffffff80080abcd0 x28: 0000000000000003 [ 247.128167] x27: ffffffc03f624040 x26: ffffff8009c15df8 [ 247.151019] x25: ffffff8009d12fb0 x24: 0000000000000000 [ 247.174890] x23: ffffff8008f28918 x22: ffffffc03f624040 [ 247.200161] x21: ffffffc03f5dc040 x20: ffffff80081c9c40 [ 247.225735] x19: ffffffc07ebdf6c0 x18: 0000000034f4d91d [ 247.251269] x17: 0000000000000000 x16: 0000000000000000 [ 247.276790] x15: ffffff8009c6a580 x14: ffffff800ac4e6a0 [ 247.302253] x13: ffffff800ac4fea0 x12: ffffff800ac4fea0 [ 247.327584] x11: ffffff800ac4ffa8 x10: ffffff800ac4ffa8 [ 247.352936] x9 : 0000000000000660 x8 : 000000000016f70a [ 247.378502] x7 : 0000000000000630 x6 : 0000004075191000 [ 247.404043] x5 : ffffff8009cb82b0 x4 : ffffff8009c15bb0 [ 247.429229] x3 : 00000000000df0e6 x2 : 00000000000df0e6 [ 247.454685] x1 : 0000000000000001 x0 : 00000000000df0e6 [ 247.480810] Call trace: [ 247.500074] _raw_spin_unlock_irq+0x3c/0x78 [ 247.522696] finish_task_switch+0x160/0x220 [ 247.545393] __schedule+0xa78/0xaf8 [ 247.566679] schedule+0x9c/0xe0 [ 247.587172] worker_thread+0x240/0x2a0 [ 247.608800] kthread+0x154/0x170 [ 247.629490] ret_from_fork+0x10/0x18 [ 248.004976] ../kernel/trace/trace_selftest.c:792 [ 248.105417] ../kernel/trace/trace_selftest.c:794 [ 248.115347] ../kernel/trace/trace_selftest.c:797 [ 248.143324] ../kernel/trace/trace_selftest.c:799 [ 248.186222] ftrace_replace_code: 1 [ 260.251652] ../kernel/trace/ftrace.c:2446, loop_counter: 41986 [ 260.255610] ../kernel/trace/trace_selftest.c:802 [ 260.256626] ../kernel/trace/trace_selftest.c:804 [ 260.258006] PASSED [ 260.474173] _warn_unseeded_randomness: 7 callbacks suppressed [ 260.474453] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 260.477428] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 260.481610] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 260.508929] prandom: seed boundary self test passed [ 260.553315] prandom: 100 self tests passed [ 260.562491] pinctrl core: initialized pinctrl subsystem [ 260.706456] regulator-dummy: Failed to create debugfs directory [ 260.718997] [ 260.719654] ************************************************************* [ 260.721515] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 260.722434] ** ** [ 260.723501] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 260.724652] ** ** [ 260.726418] ** This means that this kernel is built to expose internal ** [ 260.727320] ** IOMMU data structures, which may compromise security on ** [ 260.728464] ** your system. ** [ 260.729544] ** ** [ 260.731298] ** If you see this message and you are not debugging the ** [ 260.732290] ** kernel, report this immediately to your vendor! ** [ 260.733462] ** ** [ 260.734543] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 260.736318] ************************************************************* [ 260.778674] DMI not present or invalid. [ 260.855432] NET: Registered protocol family 16 [ 260.893061] audit: initializing netlink subsys (disabled) [ 261.099286] cpuidle: using governor ladder [ 261.102349] cpuidle: using governor menu [ 261.132013] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____)) [ 261.134642] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 261.139577] audit: type=2000 audit(192.212:1): state=initialized audit_enabled=0 res=1 [ 261.340752] DMA: preallocated 256 KiB pool for atomic allocations [ 261.464764] Serial: AMBA PL011 UART driver [ 262.558436] 9000000.pl011: ttyAMA0 at MMIO 0x9000000 (irq = 39, base_baud = 0) is a PL011 rev1 [ 262.562781] printk: console [ttyAMA0] enabled [ 262.562781] printk: console [ttyAMA0] enabled [ 262.564555] printk: bootconsole [pl11] disabled [ 262.564555] printk: bootconsole [pl11] disabled [ 262.723167] _warn_unseeded_randomness: 42 callbacks suppressed [ 262.723439] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 262.726326] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 263.455212] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 263.537799] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 263.731320] kobject: 'renesas,r9a06g032-sysctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 263.746806] kobject: 'renesas-cpg-mssr' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 263.821021] arm-v7s io-pgtable: self test ok [ 263.823461] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 32 [ 263.834262] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 36 [ 263.841608] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 40 [ 263.850288] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 42 [ 263.860157] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 44 [ 263.868745] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x40201000, IAS 48 [ 263.877655] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 32 [ 263.890053] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 36 [ 263.903978] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 40 [ 263.918350] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 42 [ 263.933306] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 44 [ 263.947811] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x02004000, IAS 48 [ 263.965221] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 32 [ 264.000898] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 36 [ 264.036399] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 40 [ 264.074034] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 42 [ 264.116650] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 44 [ 264.159885] arm-lpae io-pgtable: selftest: pgsize_bitmap 0x20010000, IAS 48 [ 264.203989] arm-lpae io-pgtable: selftest: completed with 18 PASS 0 FAIL [ 264.247944] vgaarb: loaded [ 264.273325] _warn_unseeded_randomness: 34 callbacks suppressed [ 264.273596] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 264.277014] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 264.316838] pps_core: LinuxPPS API ver. 1 registered [ 264.317952] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 264.325334] PTP clock support registered [ 264.337510] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 264.339659] kobject: 'brcmstb-reboot' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 264.559717] NetLabel: Initializing [ 264.560658] NetLabel: domain hash size = 128 [ 264.562663] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 264.576731] NetLabel: unlabeled traffic allowed by default [ 264.604152] clocksource: Switched to clocksource arch_sys_counter [ 270.680554] _warn_unseeded_randomness: 4 callbacks suppressed [ 270.680822] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 270.684204] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 270.689188] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 270.709844] VFS: Disk quotas dquot_6.6.0 [ 270.717084] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 270.753471] *** VALIDATE hugetlbfs *** [ 271.911954] _warn_unseeded_randomness: 7 callbacks suppressed [ 271.912221] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 271.915649] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 271.919756] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 271.927867] NET: Registered protocol family 2 [ 271.987912] tcp_listen_portaddr_hash hash table entries: 1024 (order: 4, 90112 bytes) [ 271.996783] TCP established hash table entries: 16384 (order: 5, 131072 bytes) [ 272.030044] TCP bind hash table entries: 16384 (order: 8, 1310720 bytes) [ 272.044511] TCP: Hash tables configured (established 16384 bind 16384) [ 272.057520] UDP hash table entries: 1024 (order: 5, 196608 bytes) [ 272.066014] UDP-Lite hash table entries: 1024 (order: 5, 196608 bytes) [ 272.093533] NET: Registered protocol family 1 [ 272.100601] NET: Registered protocol family 44 [ 272.211639] hw perfevents: enabled with armv8_pmuv3 PMU driver, 1 counters available [ 272.227649] kvm [1]: HYP mode not available [ 272.628232] Kprobe smoke test: started [ 273.933604] Kprobe smoke test: passed successfully [ 274.028244] Initialise system trusted keyrings [ 274.031488] _warn_unseeded_randomness: 50 callbacks suppressed [ 274.031752] random: get_random_bytes called from key_alloc+0x380/0x698 with crng_init=0 [ 274.041947] Key type blacklist registered [ 274.050152] random: get_random_u64 called from dup_task_struct+0xdc/0x3d0 with crng_init=0 [ 274.069157] workingset: timestamp_bits=44 max_order=19 bucket_order=0 [ 276.784704] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 276.786032] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 276.816563] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 277.096319] 9p: Installing v9fs 9p2000 file system support [ 277.256453] Allocating IMA blacklist keyring. [ 277.277653] cryptomgr_test (37) used greatest stack depth: 12400 bytes left [ 277.313010] kworker/u2:0 (38) used greatest stack depth: 11936 bytes left [ 279.022159] Key type asymmetric registered [ 279.025565] Asymmetric key parser 'x509' registered [ 279.035495] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) [ 279.053844] _warn_unseeded_randomness: 26 callbacks suppressed [ 279.054112] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 279.057912] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 279.105676] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 279.334711] kobject: 'brcm-gisb-arb' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 1000) [ 279.478219] kobject: 'armada-37xx-pinctrl' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 750) [ 279.749611] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 279.760291] pl061_gpio 9030000.pl061: Unbalanced pm_runtime_enable! [ 279.794008] pl061_gpio 9030000.pl061: PL061 GPIO chip @0x0000000009030000 registered [ 279.833253] kobject: 'stmpe-pwm' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 279.873047] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 279.985673] pci-host-generic 3f000000.pcie: host bridge /pcie@10000000 ranges: [ 279.989984] pci-host-generic 3f000000.pcie: IO 0x3eff0000..0x3effffff -> 0x00000000 [ 279.994837] pci-host-generic 3f000000.pcie: MEM 0x10000000..0x3efeffff -> 0x10000000 [ 279.998000] pci-host-generic 3f000000.pcie: MEM 0x8000000000..0xffffffffff -> 0x8000000000 [ 280.005011] pci-host-generic 3f000000.pcie: ECAM at [mem 0x3f000000-0x3fffffff] for [bus 00-0f] [ 280.029518] pci-host-generic 3f000000.pcie: PCI host bridge to bus 0000:00 [ 280.032182] pci_bus 0000:00: root bus resource [bus 00-0f] [ 280.033854] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 280.036396] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff] [ 280.038099] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff] [ 280.044786] pci 0000:00:00.0: calling quirk_mmio_always_on+0x0/0x38 @ 1 [ 280.046747] pci 0000:00:00.0: quirk_mmio_always_on+0x0/0x38 took 248 usecs [ 280.176164] pci 0000:00:01.0: BAR 6: assigned [mem 0x10000000-0x1003ffff pref] [ 280.178126] pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] [ 280.181088] pci 0000:00:02.0: BAR 4: assigned [mem 0x8000004000-0x8000007fff 64bit pref] [ 280.184002] pci 0000:00:03.0: BAR 4: assigned [mem 0x8000008000-0x800000bfff 64bit pref] [ 280.186225] pci 0000:00:01.0: BAR 1: assigned [mem 0x10040000-0x10040fff] [ 280.188606] pci 0000:00:03.0: BAR 1: assigned [mem 0x10041000-0x10041fff] [ 280.190329] pci 0000:00:03.0: BAR 0: assigned [io 0x1000-0x103f] [ 280.192656] pci 0000:00:01.0: BAR 0: assigned [io 0x1040-0x105f] [ 280.193851] pci 0000:00:02.0: BAR 0: assigned [io 0x1060-0x107f] [ 280.198054] pci 0000:00:00.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 280.200040] pci 0000:00:00.0: tegra_pcie_relax_enable+0x0/0x40 took 201 usecs [ 280.203784] pci 0000:00:01.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 280.205027] pci 0000:00:01.0: tegra_pcie_relax_enable+0x0/0x40 took 93 usecs [ 280.206887] pci 0000:00:02.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 280.208766] pci 0000:00:02.0: tegra_pcie_relax_enable+0x0/0x40 took 93 usecs [ 280.210589] pci 0000:00:03.0: calling tegra_pcie_relax_enable+0x0/0x40 @ 1 [ 280.212491] pci 0000:00:03.0: tegra_pcie_relax_enable+0x0/0x40 took 92 usecs [ 280.287488] _warn_unseeded_randomness: 15 callbacks suppressed [ 280.287759] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 280.289151] kobject: 'layerscape-pcie' ((____ptrval____)): kobject_release, parent (____ptrval____) (delayed 250) [ 280.324270] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 281.062816] virtio-pci 0000:00:01.0: enabling device (0000 -> 0003) [ 281.109418] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 281.110435] kobject: 'virtio0' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 281.157729] virtio-pci 0000:00:02.0: enabling device (0000 -> 0003) [ 281.192337] kobject: 'virtio1' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 281.241743] virtio-pci 0000:00:03.0: enabling device (0000 -> 0003) [ 281.275582] kobject: 'virtio2' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 281.312743] _warn_unseeded_randomness: 4 callbacks suppressed [ 281.313022] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 288.693306] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 288.919817] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 288.921021] kobject: 'ttyS0' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 288.924302] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 288.928662] random: get_random_u32 called from shuffle_freelist+0x64/0x218 with crng_init=0 [ 289.025176] random: get_random_u32 called from kobject_release+0x24/0xf8 with crng_init=0 [ 289.026271] kobject: 'ttyS1' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 289.029043] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 500) [ 289.124907] kobject: 'ttyS2' ((____ptrval____)): kobject_release, parent (null) (delayed 1000) [ 289.127700] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 750) [ 289.227970] kobject: 'ttyS3' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 289.229948] kobject: '(null)' ((____ptrval____)): kobject_release, parent (null) (delayed 250) [ 289.373188] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 289.392939] KGDB: Registered I/O driver kgdbts [ 289.394334] kgdbts:RUN plant and detach test Entering kdb (current=0xffffffc03f558040, pid 1) on processor 0 due to Keyboard Entry [0]kdb> [ 289.407786] kgdbts:RUN sw breakpoint test [ 289.426571] kgdbts:RUN bad memory access test [ 289.430647] kgdbts:RUN singlestep test 1000 iterations [ 289.467463] kgdbts:RUN singlestep [0/1000]