[ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034] [ 0.000000] Linux version 4.20.0-rc4-next-20181130-00011-gaa3ac9a7b87a-dirty (anders@compilator) (gcc version 7.3.1 20180425 [linaro-7.3-2018.05 revision d29120a424ecfbc167ef90065c0eeb7f91977701] (Linaro GCC 7.3-2018.05)) #53 SMP Sat Dec 1 12:24:31 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 0xbebf5600-0xbebf6fff] [ 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] kasan: KernelAddressSanitizer initialized [ 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+0x15c/0x7e8 with crng_init=0 [ 0.000000] percpu: Embedded 497 pages/cpu @(____ptrval____) s1998424 r8192 d29096 u2035712 [ 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: 970260K/2097152K available (19928K kernel code, 18250K rwdata, 9836K rodata, 6144K init, 23683K bss, 389772K 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] ODEBUG: selftest passed [ 0.000000] ftrace: allocating 42130 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.000307] sched_clock: 56 bits at 62MHz, resolution 16ns, wraps every 4398046511096ns [ 0.030340] Console: colour dummy device 80x25 [ 0.042971] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.043993] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.044915] ... MAX_LOCK_DEPTH: 48 [ 0.046754] ... MAX_LOCKDEP_KEYS: 8191 [ 0.047680] ... CLASSHASH_SIZE: 4096 [ 0.048652] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.049560] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.051349] ... CHAINHASH_SIZE: 32768 [ 0.052297] memory used by lock dependency info: 7775 kB [ 0.053270] per task-struct memory footprint: 2688 bytes [ 0.054224] ------------------------ [ 0.055968] | Locking API testsuite: [ 0.056846] ---------------------------------------------------------------------------- [ 0.057835] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.058801] -------------------------------------------------------------------------- [ 0.061231] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.184166] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.310615] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.440165] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.570202] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.703863] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.836885] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.970148] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 1.093088] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 1.214899] -------------------------------------------------------------------------- [ 1.215900] recursive read-lock: | ok | | ok | [ 1.251948] recursive read-lock #2: | ok | | ok | [ 1.288297] mixed read-write-lock: | ok | | ok | [ 1.324491] mixed write-read-lock: | ok | | ok | [ 1.360809] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 1.397189] mixed read-lock/lock-read ABBA: | ok | | ok | [ 1.434157] mixed write-lock/lock-write ABBA: | ok | | ok | [ 1.471023] -------------------------------------------------------------------------- [ 1.472752] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 1.526993] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 1.581014] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 1.634465] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 1.688186] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 1.742130] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 1.795927] hard-safe-A + irqs-on/12: ok | ok | ok | [ 1.850058] soft-safe-A + irqs-on/12: ok | ok | ok | [ 1.903913] hard-safe-A + irqs-on/21: ok | ok | ok | [ 1.957758] soft-safe-A + irqs-on/21: ok | ok | ok | [ 2.012259] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 2.067160] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 2.122031] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 2.177033] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 2.232994] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 2.288155] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 2.343801] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 2.398917] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 2.453887] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 2.508557] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 2.563531] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 2.618329] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 2.673357] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 2.728308] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 2.783445] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 2.838349] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 2.894083] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 2.949334] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 3.005215] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 3.060287] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 3.115844] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 3.170770] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 3.226225] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 3.281069] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 3.336477] hard-irq lock-inversion/123: ok | ok | ok | [ 3.391502] soft-irq lock-inversion/123: ok | ok | ok | [ 3.447172] hard-irq lock-inversion/132: ok | ok | ok | [ 3.502244] soft-irq lock-inversion/132: ok | ok | ok | [ 3.557671] hard-irq lock-inversion/213: ok | ok | ok | [ 3.612740] soft-irq lock-inversion/213: ok | ok | ok | [ 3.667814] hard-irq lock-inversion/231: ok | ok | ok | [ 3.722769] soft-irq lock-inversion/231: ok | ok | ok | [ 3.778039] hard-irq lock-inversion/312: ok | ok | ok | [ 3.833660] soft-irq lock-inversion/312: ok | ok | ok | [ 3.889916] hard-irq lock-inversion/321: ok | ok | ok | [ 3.944952] soft-irq lock-inversion/321: ok | ok | ok | [ 4.000542] hard-irq read-recursion/123: ok | [ 4.019961] soft-irq read-recursion/123: ok | [ 4.039461] hard-irq read-recursion/132: ok | [ 4.059121] soft-irq read-recursion/132: ok | [ 4.078558] hard-irq read-recursion/213: ok | [ 4.097586] soft-irq read-recursion/213: ok | [ 4.117142] hard-irq read-recursion/231: ok | [ 4.136614] soft-irq read-recursion/231: ok | [ 4.156019] hard-irq read-recursion/312: ok | [ 4.175248] soft-irq read-recursion/312: ok | [ 4.194387] hard-irq read-recursion/321: ok | [ 4.213455] soft-irq read-recursion/321: ok | [ 4.232875] -------------------------------------------------------------------------- [ 4.233972] | Wound/wait tests | [ 4.234948] --------------------- [ 4.236895] ww api failures: ok | ok | ok | [ 4.293780] ww contexts mixing: ok | ok | [ 4.330063] finishing ww context: ok | ok | ok | ok | [ 4.401810] locking mismatches: ok | ok | ok | [ 4.456075] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 4.640064] spinlock nest unlocked: ok | [ 4.658649] ----------------------------------------------------- [ 4.659648] |block | try |context| [ 4.660743] ----------------------------------------------------- [ 4.662711] context: ok | ok | ok | [ 4.717842] try: ok | ok | ok | [ 4.772499] block: ok | ok | ok | [ 4.826455] spinlock: ok | ok | ok | [ 4.881779] ------------------------------------------------------- [ 4.882843] Good, all 261 testcases passed! | [ 4.883831] --------------------------------- [ 4.888830] _warn_unseeded_randomness: 15 callbacks suppressed [ 4.892128] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 4.894597] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 4.898574] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 4.907383] Calibrating delay loop (skipped), value calculated using timer frequency.. 125.00 BogoMIPS (lpj=250000) [ 4.908978] pid_max: default: 32768 minimum: 301 [ 4.955037] LSM: Security Framework initializing [ 4.956742] Yama: becoming mindful. [ 4.965082] LoadPin: ready to pin (currently enforcing) [ 4.968053] SELinux: Initializing. [ 5.005480] *** VALIDATE SELinux *** [ 5.006970] AppArmor: AppArmor disabled by boot time parameter [ 5.119449] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 5.161336] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 5.173658] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 5.178413] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) [ 5.326321] *** VALIDATE proc *** [ 5.405733] *** VALIDATE cgroup1 *** [ 5.406891] *** VALIDATE cgroup2 *** [ 5.634547] ASID allocator initialised with 32768 entries [ 5.671332] rcu: Hierarchical SRCU implementation. [ 6.031084] EFI services will not be available. [ 6.125500] smp: Bringing up secondary CPUs ... [ 6.127918] smp: Brought up 1 node, 1 CPU [ 6.128902] SMP: Total of 1 processors activated. [ 6.130079] CPU features: detected: 32-bit EL0 Support [ 6.132390] CPU features: detected: CRC32 instructions [ 6.841954] CPU features: emulated: Privileged Access Never (PAN) using TTBR0_EL1 switching [ 6.843561] CPU: All CPU(s) started at EL1 [ 6.847315] alternatives: patching kernel code [ 6.849674] _warn_unseeded_randomness: 80 callbacks suppressed [ 6.849998] random: get_random_u64 called from compute_layout+0x148/0x1c8 with crng_init=0 [ 7.004588] random: get_random_u64 called from dup_task_struct+0x120/0x248 with crng_init=0 [ 15.286704] node 0 initialised, 180184 pages in 4ms [ 15.305053] workqueue: round-robin CPU selection forced, expect performance impact [ 15.314472] pgdatinit0 (15) used greatest stack depth: 28800 bytes left [ 15.342145] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 15.601736] devtmpfs: initialized [ 16.554678] gcov: version magic: 0x4137332a [ 16.662370] _warn_unseeded_randomness: 2 callbacks suppressed [ 16.662743] random: get_random_u32 called from bucket_table_alloc+0x154/0x1f0 with crng_init=0 [ 16.670611] random: get_random_u64 called from kmem_cache_open+0x38/0x1d8 with crng_init=0 [ 16.674081] random: get_random_u64 called from cache_random_seq_create+0x64/0x110 with crng_init=0 [ 16.736560] Registered cp15_barrier emulation handler [ 16.738590] Registered setend emulation handler [ 43.556257] watchdog: BUG: soft lockup - CPU#0 stuck for 23s! [swapper/0:1] [ 43.557102] Modules linked in: [ 43.557944] irq event stamp: 195586 [ 43.558962] hardirqs last enabled at (195585): [] __slab_alloc+0x6c/0x88 [ 43.559889] hardirqs last disabled at (195586): [] el1_irq+0xa8/0x1c0 [ 43.560846] softirqs last enabled at (195562): [] __do_softirq+0xc50/0xcbc [ 43.561821] softirqs last disabled at (195555): [] irq_exit+0x188/0x240 [ 43.563131] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G T 4.20.0-rc4-next-20181130-00011-gaa3ac9a7b87a-dirty #53 [ 43.563992] Hardware name: linux,dummy-virt (DT) [ 43.564978] pstate: 20400005 (nzCv daif +PAN -UAO) [ 43.565867] pc : lock_acquire+0x4b4/0x4d8 [ 43.566681] lr : lock_acquire+0x4ac/0x4d8 [ 43.567433] sp : ffffffc03f607a10 [ 43.568177] x29: ffffffc03f607a20 x28: ffffff900853a858 [ 43.569137] x27: 0000000000000000 x26: 0000000000000001 [ 43.570058] x25: 0000000000000000 x24: 0000000000000000 [ 43.570953] x23: 0000000000000000 x22: ffffff900a815780 [ 43.571913] x21: 0000000000000000 x20: 0000000000000000 [ 43.572868] x19: ffffffc03f5f8040 x18: 00000000000025a0 [ 43.573810] x17: 0000000000000000 x16: 0000000000000000 [ 43.574757] x15: 00000000000023d8 x14: e1cd51a357de404b [ 43.575729] x13: ffffff900a8f4790 x12: 0000000000000002 [ 43.576727] x11: 0000000000000002 x10: ffffff900a41d000 [ 43.577705] x9 : 1ffffff807ebf2fa x8 : ffffff8807ebf2fa [ 43.578618] x7 : ffffff900853a858 x6 : 0000000000000000 [ 43.579516] x5 : 0000000000000000 x4 : 0000000000a26543 [ 43.580427] x3 : 000000006262c5a2 x2 : dfffff9000000000 [ 43.581351] x1 : 0000000000000000 x0 : 0000000000000003 [ 43.582376] Call trace: [ 43.583282] lock_acquire+0x4b4/0x4d8 [ 43.584114] __fs_reclaim_acquire+0x3c/0x48 [ 43.584908] fs_reclaim_acquire+0x2c/0x38 [ 43.585725] kmem_cache_alloc_trace+0x5c/0x740 [ 43.586555] prealloc_memory+0x7c/0x230 [ 43.587304] dma_debug_init+0x154/0x1c0 [ 43.588082] do_one_initcall+0x434/0x8e0 [ 43.588944] do_initcall_level+0x95c/0xa38 [ 43.589754] do_initcalls+0x64/0xa8 [ 43.590553] do_basic_setup+0x44/0x70 [ 43.591390] kernel_init_freeable+0x1cc/0x308 [ 43.592218] kernel_init+0x28/0x148 [ 43.592988] ret_from_fork+0x10/0x18 [ 43.593943] Kernel panic - not syncing: softlockup: hung tasks [ 43.594888] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G L T 4.20.0-rc4-next-20181130-00011-gaa3ac9a7b87a-dirty #53 [ 43.595711] Hardware name: linux,dummy-virt (DT) [ 43.596396] Call trace: [ 43.597120] dump_backtrace+0x0/0x2f0 [ 43.597870] show_stack+0x28/0x38 [ 43.598646] __dump_stack+0x28/0x30 [ 43.599404] dump_stack+0xd0/0x130 [ 43.600126] panic+0x2b0/0x574 [ 43.600904] watchdog_timer_fn+0x3a0/0x428 [ 43.601642] __run_hrtimer+0x8fc/0xd78 [ 43.602375] __hrtimer_run_queues+0x17c/0x1f0 [ 43.603162] hrtimer_run_queues+0x16c/0x198 [ 43.603939] run_local_timers+0x34/0xa0 [ 43.604802] update_process_times+0x30/0x70 [ 43.605565] tick_periodic+0x134/0x148 [ 43.606367] tick_handle_periodic+0x4c/0x110 [ 43.607191] arch_timer_handler_virt+0x4c/0x68 [ 43.608014] handle_percpu_devid_irq+0x480/0x958 [ 43.608842] generic_handle_irq+0x48/0x68 [ 43.609643] __handle_domain_irq+0xcc/0x108 [ 43.610387] gic_handle_irq+0x8c/0xe8 [ 43.611110] el1_irq+0xec/0x1c0 [ 43.611862] lock_acquire+0x4b4/0x4d8 [ 43.612681] __fs_reclaim_acquire+0x3c/0x48 [ 43.613466] fs_reclaim_acquire+0x2c/0x38 [ 43.614240] kmem_cache_alloc_trace+0x5c/0x740 [ 43.615023] prealloc_memory+0x7c/0x230 [ 43.615756] dma_debug_init+0x154/0x1c0 [ 43.616507] do_one_initcall+0x434/0x8e0 [ 43.617304] do_initcall_level+0x95c/0xa38 [ 43.618066] do_initcalls+0x64/0xa8 [ 43.618816] do_basic_setup+0x44/0x70 [ 43.619614] kernel_init_freeable+0x1cc/0x308 [ 43.620401] kernel_init+0x28/0x148 [ 43.621155] ret_from_fork+0x10/0x18 [ 43.623876] ---[ end Kernel panic - not syncing: softlockup: hung tasks ]---