early console in setup code Probing EDD (edd=off to disable)... ok [ 0.000000] Linux version 4.14.0-rc1-00227-gcc4be4e (kbuild@intel11) (gcc version 5.4.1 20160904 (Debian 5.4.1-2)) #1 PREEMPT Mon Sep 25 20:22:27 CST 2017 [ 0.000000] x86/fpu: x87 FPU will use FXSAVE [ 0.000000] e820: BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000018ed1fff] usable [ 0.000000] BIOS-e820: [mem 0x0000000018ed2000-0x0000000018efffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] debug: ignoring loglevel setting. [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] random: fast init done [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] tsc: Fast TSC calibration using PIT [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] e820: last_pfn = 0x18ed2 max_arch_pfn = 0x1000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC [ 0.000000] initial memory mapped: [mem 0x00000000-0x061fffff] [ 0.000000] Base memory trampoline at [8009b000] 9b000 size 16384 [ 0.000000] BRK [0x05988000, 0x05988fff] PGTABLE [ 0.000000] BRK [0x05989000, 0x05989fff] PGTABLE [ 0.000000] RAMDISK: [mem 0x1768b000-0x18ecffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F6930 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x0000000018EE1936 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x0000000018EE180A 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x0000000018EE0040 0017CA (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x0000000018EE0000 000040 [ 0.000000] ACPI: APIC 0x0000000018EE187E 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x0000000018EE18FE 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] 0MB HIGHMEM available. [ 0.000000] 398MB LOWMEM available. [ 0.000000] mapped low ram: 0 - 18ed2000 [ 0.000000] low ram: 0 - 18ed2000 [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:18ed1001, primary cpu clock [ 0.000000] kvm-clock: using sched offset of 2911937517 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] Normal [mem 0x0000000001000000-0x0000000018ed1fff] [ 0.000000] HighMem empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x0000000018ed1fff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000018ed1fff] [ 0.000000] On node 0 totalpages: 102000 [ 0.000000] free_area_init_node: node 0, pgdat 84080900, node_mem_map 9736b020 [ 0.000000] DMA zone: 32 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 3998 pages, LIFO batch:0 [ 0.000000] Normal zone: 766 pages used for memmap [ 0.000000] Normal zone: 98002 pages, LIFO batch:31 [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 3d28140 [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] e820: [mem 0x18f00000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370452778343963 ns [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768 [ 0.000000] pcpu-alloc: [0] 0 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 101202 [ 0.000000] Kernel command line: root=/dev/ram0 hung_task_panic=1 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw link=/kbuild-tests/run-queue/kvm/i386-randconfig-b0-09232213/linux-devel:devel-spot-201709232001:cc4be4e6ab033b35318a3a8f8e089331855ba871/.vmlinuz-cc4be4e6ab033b35318a3a8f8e089331855ba871-20170925202938-6:quantal-vp-62 branch=linux-devel/devel-spot-201709232001 BOOT_IMAGE=/pkg/linux/i386-randconfig-b0-09232213/gcc-5/cc4be4e6ab033b35318a3a8f8e089331855ba871/vmlinuz-4.14.0-rc1-00227-gcc4be4e drbd.minor_count=8 [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes) [ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes) [ 0.000000] Initializing CPU#0 [ 0.000000] allocated 412484 bytes of page_ext [ 0.000000] Initializing HighMem for node 0 (00000000:00000000) [ 0.000000] Memory: 303708K/408000K available (33871K kernel code, 8664K rwdata, 12152K rodata, 1656K init, 18588K bss, 104292K reserved, 0K cma-reserved, 0K highmem) [ 0.000000] virtual kernel memory layout: [ 0.000000] fixmap : 0xfffe2000 - 0xfffff000 ( 116 kB) [ 0.000000] pkmap : 0xffc00000 - 0xffe00000 (2048 kB) [ 0.000000] vmalloc : 0x996d2000 - 0xffbfe000 (1637 MB) [ 0.000000] lowmem : 0x80000000 - 0x98ed2000 ( 398 MB) [ 0.000000] .init : 0x84591000 - 0x8472f000 (1656 kB) [ 0.000000] .data : 0x83113f2a - 0x8456a280 (20824 kB) [ 0.000000] .text : 0x81000000 - 0x83113f2a (33871 kB) [ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok. [ 0.000000] [ 0.000000] ********************************************************** [ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.000000] ** ** [ 0.000000] ** trace_printk() being used. Allocating extra memory. ** [ 0.000000] ** ** [ 0.000000] ** This means that this is a DEBUG kernel and it is ** [ 0.000000] ** unsafe for production use. ** [ 0.000000] ** ** [ 0.000000] ** If you see this message and you are not debugging ** [ 0.000000] ** the kernel, report this immediately to your vendor! ** [ 0.000000] ** ** [ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.000000] ********************************************************** [ 0.003333] Running RCU self tests [ 0.003333] Preemptible hierarchical RCU implementation. [ 0.003333] RCU event tracing is enabled. [ 0.003333] RCU lockdep checking is enabled. [ 0.003333] RCU callback double-/use-after-free debug enabled. [ 0.003333] RCU debug extended QS entry/exit. [ 0.003333] RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout). [ 0.003333] Tasks RCU enabled. [ 0.003333] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.003333] CPU 0 irqstacks, hard=96d94000 soft=96d96000 [ 0.003333] Offload RCU callbacks from CPUs: . [ 0.003333] console [ttyS0] enabled [ 0.003333] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.003333] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.003333] ... MAX_LOCK_DEPTH: 48 [ 0.003333] ... MAX_LOCKDEP_KEYS: 8191 [ 0.003333] ... CLASSHASH_SIZE: 4096 [ 0.003333] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.003333] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.003333] ... CHAINHASH_SIZE: 32768 [ 0.003333] memory used by lock dependency info: 4399 kB [ 0.003333] per task-struct memory footprint: 1536 bytes [ 0.003333] ------------------------ [ 0.003333] | Locking API testsuite: [ 0.003333] ---------------------------------------------------------------------------- [ 0.003333] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] recursive read-lock: | ok | | ok | [ 0.003333] recursive read-lock #2: | ok | | ok | [ 0.003333] mixed read-write-lock: | ok | | ok | [ 0.003333] mixed write-read-lock: | ok | | ok | [ 0.003333] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.003333] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.003333] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.003333] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.003333] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.003333] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.003333] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.003333] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.003333] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.003333] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.003333] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.003333] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/123: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/123: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/132: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/132: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/213: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/213: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/231: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/231: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/312: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/312: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/321: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/321: ok | ok | ok | [ 0.003333] hard-irq read-recursion/123: ok | [ 0.003333] soft-irq read-recursion/123: ok | [ 0.003333] hard-irq read-recursion/132: ok | [ 0.003333] soft-irq read-recursion/132: ok | [ 0.003333] hard-irq read-recursion/213: ok | [ 0.003333] soft-irq read-recursion/213: ok | [ 0.003333] hard-irq read-recursion/231: ok | [ 0.003333] soft-irq read-recursion/231: ok | [ 0.003333] hard-irq read-recursion/312: ok | [ 0.003333] soft-irq read-recursion/312: ok | [ 0.003333] hard-irq read-recursion/321: ok | [ 0.003333] soft-irq read-recursion/321: ok | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] | Wound/wait tests | [ 0.003333] --------------------- [ 0.003333] ww api failures: ok | ok | ok | [ 0.003333] ww contexts mixing: ok | ok | [ 0.003333] finishing ww context: ok | ok | ok | ok | [ 0.003333] locking mismatches: ok | ok | ok | [ 0.003333] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.003333] spinlock nest unlocked: ok | [ 0.003333] ----------------------------------------------------- [ 0.003333] |block | try |context| [ 0.003333] ----------------------------------------------------- [ 0.003333] context: ok | ok | ok | [ 0.003333] try: ok | ok | ok | [ 0.003333] block: ok | ok | ok | [ 0.003333] spinlock: ok | ok | ok | [ 0.003333] ------------------------------------------------------- [ 0.003333] Good, all 261 testcases passed! | [ 0.003333] --------------------------------- [ 0.003333] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.003333] hpet clockevent registered [ 0.003374] tsc: Detected 2693.508 MHz processor [ 0.004074] Calibrating delay loop (skipped) preset value.. 5389.47 BogoMIPS (lpj=8978360) [ 0.004591] pid_max: default: 4096 minimum: 301 [ 0.006702] ACPI: Core revision 20170728 [ 0.030471] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.031458] ACPI: setting ELCR to 0200 (from 0c00) [ 0.032294] Security Framework initialized [ 0.032939] LoadPin: ready to pin (currently enabled) [ 0.032986] Smack: Initializing. [ 0.033889] AppArmor: AppArmor disabled by boot time parameter [ 0.034853] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.035844] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.039178] mce: CPU supports 10 MCE banks [ 0.040124] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040940] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041830] CPU: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1) [ 0.047638] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only. [ 0.049620] Hierarchical SRCU implementation. [ 0.053362] devtmpfs: initialized [ 0.057186] gcov: version magic: 0x3530342a [ 0.076420] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns [ 0.076721] futex hash table entries: 16 (order: -3, 768 bytes) [ 0.082043] NET: Registered protocol family 16 [ 0.089964] cpuidle: using governor ladder [ 0.093075] ACPI: bus type PCI registered [ 0.094685] PCI: PCI BIOS area is rw and x. Use pci=nobios if you want it NX. [ 0.095763] PCI: PCI BIOS revision 2.10 entry at 0xfd3e3, last bus=0 [ 0.096701] PCI: Using configuration type 1 for base access [ 0.265599] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.270622] ACPI: Added _OSI(Module Device) [ 0.271230] ACPI: Added _OSI(Processor Device) [ 0.271865] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.272534] ACPI: Added _OSI(Processor Aggregator Device) [ 0.317917] ACPI: Interpreter enabled [ 0.318838] ACPI: (supports S0 S3 S4 S5) [ 0.319433] ACPI: Using PIC for interrupt routing [ 0.320319] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.326384] ACPI: Enabled 3 GPEs in block 00 to 0F [ 0.472923] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.473408] acpi PNP0A03:00: _OSC: OS supports [Segments] [ 0.474421] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.475467] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.478492] PCI host bridge to bus 0000:00 [ 0.480049] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.481032] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.482015] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.483382] pci_bus 0000:00: root bus resource [mem 0x18f00000-0xfebfffff window] [ 0.484461] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.485401] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.491563] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.497481] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.523373] pci 0000:00:01.1: reg 0x20: [io 0xc200-0xc20f] [ 0.536748] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.537791] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.538740] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.540039] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.545625] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.547420] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.548488] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.553973] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 [ 0.560038] pci 0000:00:02.0: reg 0x10: [mem 0xfa000000-0xfaffffff pref] [ 0.580044] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff] [ 0.613383] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 0.620855] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 0.626709] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 0.636712] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 0.670042] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.680831] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 [ 0.686714] pci 0000:00:04.0: reg 0x10: [io 0xc040-0xc07f] [ 0.693383] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff] [ 0.716714] pci 0000:00:04.0: reg 0x20: [mem 0xfb000000-0xfb7fffff 64bit pref] [ 0.733821] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.740048] pci 0000:00:05.0: reg 0x10: [io 0xc080-0xc0bf] [ 0.750042] pci 0000:00:05.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff] [ 0.770050] pci 0000:00:05.0: reg 0x20: [mem 0xfb800000-0xfbffffff 64bit pref] [ 0.782632] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.790047] pci 0000:00:06.0: reg 0x10: [io 0xc0c0-0xc0ff] [ 0.800050] pci 0000:00:06.0: reg 0x14: [mem 0xfebf3000-0xfebf3fff] [ 0.820051] pci 0000:00:06.0: reg 0x20: [mem 0xfc000000-0xfc7fffff 64bit pref] [ 0.834521] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 [ 0.843380] pci 0000:00:07.0: reg 0x10: [io 0xc100-0xc13f] [ 0.850048] pci 0000:00:07.0: reg 0x14: [mem 0xfebf4000-0xfebf4fff] [ 0.873382] pci 0000:00:07.0: reg 0x20: [mem 0xfc800000-0xfcffffff 64bit pref] [ 0.886983] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 [ 0.896761] pci 0000:00:08.0: reg 0x10: [io 0xc140-0xc17f] [ 0.903384] pci 0000:00:08.0: reg 0x14: [mem 0xfebf5000-0xfebf5fff] [ 0.926723] pci 0000:00:08.0: reg 0x20: [mem 0xfd000000-0xfd7fffff 64bit pref] [ 0.936666] pci 0000:00:09.0: [1af4:1001] type 00 class 0x010000 [ 0.939999] pci 0000:00:09.0: reg 0x10: [io 0xc180-0xc1bf] [ 0.946715] pci 0000:00:09.0: reg 0x14: [mem 0xfebf6000-0xfebf6fff] [ 0.970087] pci 0000:00:09.0: reg 0x20: [mem 0xfd800000-0xfdffffff 64bit pref] [ 0.985255] pci 0000:00:0a.0: [1af4:1001] type 00 class 0x010000 [ 0.993382] pci 0000:00:0a.0: reg 0x10: [io 0xc1c0-0xc1ff] [ 1.003382] pci 0000:00:0a.0: reg 0x14: [mem 0xfebf7000-0xfebf7fff] [ 1.023383] pci 0000:00:0a.0: reg 0x20: [mem 0xfe000000-0xfe7fffff 64bit pref] [ 1.043773] pci 0000:00:0b.0: [8086:25ab] type 00 class 0x088000 [ 1.049060] pci 0000:00:0b.0: reg 0x10: [mem 0xfebf8000-0xfebf800f] [ 1.091756] pci_bus 0000:00: on NUMA node 0 [ 1.108349] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 1.115791] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 1.123000] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 1.129217] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 1.131643] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 1.143999] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 1.144903] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 1.146184] pci 0000:00:02.0: vgaarb: bridge control possible [ 1.146715] vgaarb: loaded [ 1.154189] SCSI subsystem initialized [ 1.155772] media: Linux media interface: v0.10 [ 1.156780] Linux video capture interface: v2.00 [ 1.157811] pps_core: LinuxPPS API ver. 1 registered [ 1.160041] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 1.161441] PTP clock support registered [ 1.165473] PCI: Using ACPI for IRQ routing [ 1.166111] PCI: pci_cache_line_size set to 64 bytes [ 1.167208] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 1.168115] e820: reserve RAM buffer [mem 0x18ed2000-0x1bffffff] [ 1.174435] Bluetooth: Core ver 2.22 [ 1.175191] NET: Registered protocol family 31 [ 1.175912] Bluetooth: HCI device and connection manager initialized [ 1.176778] Bluetooth: HCI socket layer initialized [ 1.177609] Bluetooth: L2CAP socket layer initialized [ 1.178677] NET: Registered protocol family 8 [ 1.179398] NET: Registered protocol family 20 [ 1.185027] NetLabel: Initializing [ 1.185596] NetLabel: domain hash size = 128 [ 1.186259] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 1.187026] NetLabel: unlabeled traffic allowed by default [ 1.193592] clocksource: Switched to clocksource kvm-clock [ 1.261362] Warning: could not register all branches stats [ 1.262296] Warning: could not register annotated branches stats [ 2.142705] VFS: Disk quotas dquot_6.6.0 [ 2.144055] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 2.146679] pnp: PnP ACPI init [ 2.148346] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 2.150720] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 2.152976] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 2.154495] pnp 00:03: [dma 2] [ 2.155662] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 2.160730] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 2.163960] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 2.166297] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 2.175439] pnp: PnP ACPI: found 7 devices [ 2.217480] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 2.219828] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 2.220873] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 2.221892] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 2.223118] pci_bus 0000:00: resource 7 [mem 0x18f00000-0xfebfffff window] [ 2.224583] NET: Registered protocol family 2 [ 2.227932] TCP established hash table entries: 4096 (order: 2, 16384 bytes) [ 2.229163] TCP bind hash table entries: 4096 (order: 5, 163840 bytes) [ 2.232144] TCP: Hash tables configured (established 4096 bind 4096) [ 2.233518] UDP hash table entries: 256 (order: 2, 24576 bytes) [ 2.234544] UDP-Lite hash table entries: 256 (order: 2, 24576 bytes) [ 2.236644] NET: Registered protocol family 1 [ 2.238981] RPC: Registered named UNIX socket transport module. [ 2.240077] RPC: Registered udp transport module. [ 2.240809] RPC: Registered tcp transport module. [ 2.241535] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 2.242522] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 2.243584] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 2.244542] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 2.245672] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 2.247456] PCI: CLS 0 bytes, default 64 [ 2.249876] Unpacking initramfs... [ 7.704340] Freeing initrd memory: 24852K [ 7.709955] apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac) [ 7.710903] apm: overridden by ACPI. [ 7.722364] PCLMULQDQ-NI instructions are not detected. [ 7.726704] The force parameter has not been set to 1. The Iris poweroff handler will not be installed. [ 7.728261] spin_lock-torture:--- Start of test [debug]: nwriters_stress=2 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0 [ 7.731444] spin_lock-torture: Creating torture_shuffle task [ 7.732719] spin_lock-torture: Creating torture_stutter task [ 7.734709] spin_lock-torture: torture_shuffle task started [ 7.735962] spin_lock-torture: Creating lock_torture_writer task [ 7.737582] spin_lock-torture: torture_stutter task started [ 7.738766] spin_lock-torture: Creating lock_torture_writer task [ 7.740909] spin_lock-torture: lock_torture_writer task started [ 7.741875] spin_lock-torture: Creating lock_torture_stats task [ 7.745174] spin_lock-torture: lock_torture_writer task started [ 7.747439] spin_lock-torture: lock_torture_stats task started [ 8.246673] [ 8.246941] ====================================================== [ 8.247816] WARNING: possible circular locking dependency detected [ 8.248691] 4.14.0-rc1-00227-gcc4be4e #1 Not tainted [ 8.249391] ------------------------------------------------------ [ 8.249707] kworker/0:1/13 is trying to acquire lock: [ 8.249707] (ww_class_mutex){+.+.}, at: [<81150775>] test_abba_work+0xea/0x571 [ 8.249707] [ 8.249707] but now in release context of a crosslock acquired at the following: [ 8.249707] ((complete)&abba.b_ready){+.+.}, at: [<83103dfc>] wait_for_completion+0x25/0x35 [ 8.249707] [ 8.249707] which lock already depends on the new lock. [ 8.249707] [ 8.249707] the existing dependency chain (in reverse order) is: [ 8.249707] [ 8.249707] -> #1 ((complete)&abba.b_ready){+.+.}: [ 8.249707] validate_chain+0xf47/0x1171 [ 8.249707] __lock_acquire+0xcad/0x112e [ 8.249707] lock_acquire+0x26c/0x3a4 [ 8.249707] wait_for_common+0x69/0x419 [ 8.249707] wait_for_completion+0x25/0x35 [ 8.249707] test_abba+0x2ee/0x8cd [ 8.249707] test_ww_mutex_init+0x19d/0x40d [ 8.249707] do_one_initcall+0x12f/0x308 [ 8.249707] kernel_init_freeable+0x23a/0x3e8 [ 8.249707] kernel_init+0x1b/0x309 [ 8.249707] ret_from_fork+0x19/0x24 [ 8.249707] [ 8.249707] -> #0 (ww_class_mutex){+.+.}: [ 8.249707] __ww_mutex_lock+0x11a/0x2b19 [ 8.249707] ww_mutex_lock+0x71/0x1af [ 8.249707] test_abba_work+0xea/0x571 [ 8.249707] process_one_work+0x88f/0x106e [ 8.249707] worker_thread+0x7a4/0xaaa [ 8.249707] [ 8.249707] other info that might help us debug this: [ 8.249707] [ 8.249707] Possible unsafe locking scenario by crosslock: [ 8.249707] [ 8.249707] CPU0 CPU1 [ 8.249707] ---- ---- [ 8.249707] lock(ww_class_mutex); [ 8.249707] lock((complete)&abba.b_ready); [ 8.249707] lock(ww_class_mutex); [ 8.249707] unlock((complete)&abba.b_ready); [ 8.249707] [ 8.249707] *** DEADLOCK *** [ 8.249707] [ 8.249707] 5 locks held by kworker/0:1/13: [ 8.249707] #0: ("events"){+.+.}, at: [<810eeb1c>] process_one_work+0x447/0x106e [ 8.249707] #1: ((&abba.work)){+.+.}, at: [<810eeb1c>] process_one_work+0x447/0x106e [ 8.249707] #2: (ww_class_acquire){+.+.}, at: [<810eef64>] process_one_work+0x88f/0x106e [ 8.249707] #3: (ww_class_mutex){+.+.}, at: [<81150775>] test_abba_work+0xea/0x571 [ 8.249707] #4: (&x->wait#5){....}, at: [<81133912>] complete+0x25/0xc3 [ 8.249707] [ 8.249707] stack backtrace: [ 8.249707] CPU: 0 PID: 13 Comm: kworker/0:1 Not tainted 4.14.0-rc1-00227-gcc4be4e #1 [ 8.249707] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014 [ 8.249707] Workqueue: events test_abba_work [ 8.249707] Call Trace: [ 8.249707] dump_stack+0x40/0x5e [ 8.249707] print_circular_bug+0x1c0/0x1da [ 8.249707] check_prev_add+0x1b5/0xbe5 [ 8.249707] ? save_stack_trace+0x25/0x36 [ 8.249707] commit_xhlock+0x420/0x554 [ 8.249707] ? check_noncircular+0x2e/0x2e [ 8.249707] lock_commit_crosslock+0x222/0x36b [ 8.249707] complete+0x3d/0xc3 [ 8.249707] test_abba_work+0x103/0x571 [ 8.249707] ? process_one_work+0x88f/0x106e [ 8.249707] process_one_work+0x88f/0x106e [ 8.249707] worker_thread+0x7a4/0xaaa [ 8.249707] ? rescuer_thread+0x5f2/0x5f2 [ 8.249707] kthread+0x25e/0x271 [ 8.249707] ? rescuer_thread+0x5f2/0x5f2 [ 8.249707] ? __kthread_create_on_node+0x316/0x316 [ 8.249707] ret_from_fork+0x19/0x24 [ 8.763118] tsc: Refined TSC clocksource calibration: 2693.509 MHz [ 8.764075] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d34aa491a, max_idle_ns: 440795256568 ns [ 14.340158] torture_init_begin: Refusing rcu init: spin_lock running. [ 14.343354] torture_init_begin: One torture test at a time! [ 14.347257] audit: initializing netlink subsys (disabled) [ 14.353244] audit: type=2000 audit(1506342815.297:1): state=initialized audit_enabled=0 res=1 [ 16.183097] workingset: timestamp_bits=30 max_order=17 bucket_order=0 [ 16.188868] zbud: loaded [ 16.195722] DLM installed [ 16.206036] NFS: Registering the id_resolver key type [ 16.206987] Key type id_resolver registered [ 16.207684] Key type id_legacy registered [ 16.211504] ntfs: driver 2.1.32 [Flags: R/O DEBUG]. [ 16.212361] efs: 1.0a - http://aeschi.ch.eu.org/efs/ [ 16.214402] romfs: ROMFS MTD (C) 2007 Red Hat, Inc. [ 16.215237] QNX4 filesystem 0.2.3 registered. [ 16.215971] qnx6: QNX6 filesystem 1.0.0 registered. [ 16.216915] orangefs_debugfs_init: called with debug mask: :none: :0: [ 16.221253] orangefs_init: module version upstream loaded [ 16.224773] JFS: nTxBlock = 2566, nTxLock = 20535 [ 16.227856] befs: version: 0.9.3 [ 16.230355] ocfs2: Registered cluster interface user [ 16.231719] OCFS2 User DLM kernel interface loaded [ 16.242533] ceph: loaded (mds proto 32) [ 16.274666] NET: Registered protocol family 38 [ 16.275588] Key type asymmetric registered [ 16.276337] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 16.278008] io scheduler noop registered (default) [ 16.278836] io scheduler cfq registered [ 16.279461] io scheduler mq-deadline registered [ 16.280316] io scheduler bfq registered [ 16.280953] test_string_helpers: Running tests... [ 16.287591] test_hexdump: all 1184 tests passed [ 16.288620] test_firmware: interface ready [ 16.327516] test_hash: __hash_32() has no arch implementation to test. [ 16.328457] test_hash: hash_32() has no arch implementation to test. [ 16.329356] test_hash: hash_64() has no arch implementation to test. [ 16.330328] test_hash: 33152 tests passed. [ 16.331034] test_siphash: self-tests: pass [ 16.334895] test_printf: all 260 tests passed [ 16.559163] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 2385 [ 16.569835] test_bitmap: all 460506 tests passed [ 16.570271] test_uuid: all 18 tests passed [ 16.572183] crc32: CRC_LE_BITS = 32, CRC_BE BITS = 32 [ 16.572654] crc32: self tests passed, processed 225944 bytes in 240398 nsec [ 16.573565] crc32c: CRC_LE_BITS = 32 [ 16.573924] crc32c: self tests passed, processed 225944 bytes in 123962 nsec [ 16.605660] crc32_combine: 8373 self tests passed [ 16.640111] crc32c_combine: 8373 self tests passed [ 16.641233] xz_dec_test: module loaded [ 16.641786] xz_dec_test: Create a device node with 'mknod xz_dec_test c 246 0' and write .xz files to it. [ 16.649564] glob: 64 self-tests passed, 0 failed [ 16.652495] rbtree testing [ 18.087297] -> test 1 (latency of nnodes insert+delete): 38646 cycles [ 19.786745] -> test 2 (latency of nnodes cached insert+delete): 45732 cycles [ 19.992961] -> test 3 (latency of inorder traversal): 5522 cycles [ 19.996485] -> test 4 (latency to fetch first node) [ 19.997353] non-cached: 67 cycles [ 19.998287] cached: 6 cycles [ 20.191698] augmented rbtree testing [ 22.212934] -> test 1 (latency of nnodes insert+delete): 54441 cycles [ 24.429416] -> test 2 (latency of nnodes cached insert+delete): 59598 cycles [ 24.604547] interval tree insert/remove [ 26.645975] -> 54985 cycles [ 26.647690] interval tree search [ 28.210205] -> 420858 cycles (2692 results) [ 28.212480] gpio_it87: no device [ 28.214200] gpio-mockup: probe of gpio-mockup failed with error -22 [ 28.221448] uvesafb: failed to execute /sbin/v86d [ 28.221898] uvesafb: make sure that the v86d helper is installed and executable [ 28.222561] uvesafb: Getting VBE info block failed (eax=0x4f00, err=-2) [ 28.223227] uvesafb: vbe_init() failed with -22 [ 28.223675] uvesafb: probe of uvesafb.0 failed with error -22 [ 28.224238] ipmi message handler version 39.2 [ 28.224657] ipmi device interface [ 28.225014] IPMI System Interface driver. [ 28.225696] ipmi_si: Unable to find any System Interface(s) [ 28.226203] IPMI SSIF Interface driver [ 28.233976] Copyright (C) 2004 MontaVista Software - IPMI Powerdown via sys_reboot. [ 28.235853] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 28.237076] ACPI: Power Button [PWRF] [ 28.238576] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1 [ 28.239289] ACPI: Power Button [PWRF] [ 28.244887] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 28.269362] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 28.271785] console [ttyS0] disabled [ 0.000000] Linux version 4.14.0-rc1-00227-gcc4be4e (kbuild@intel11) (gcc version 5.4.1 20160904 (Debian 5.4.1-2)) #1 PREEMPT Mon Sep 25 20:22:27 CST 2017 [ 0.000000] x86/fpu: x87 FPU will use FXSAVE [ 0.000000] e820: BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000018ed1fff] usable [ 0.000000] BIOS-e820: [mem 0x0000000018ed2000-0x0000000018efffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] debug: ignoring loglevel setting. [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] random: fast init done [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] tsc: Fast TSC calibration using PIT [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable [ 0.000000] e820: last_pfn = 0x18ed2 max_arch_pfn = 0x1000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WT UC- UC WB WT UC- UC [ 0.000000] initial memory mapped: [mem 0x00000000-0x061fffff] [ 0.000000] Base memory trampoline at [8009b000] 9b000 size 16384 [ 0.000000] BRK [0x05988000, 0x05988fff] PGTABLE [ 0.000000] BRK [0x05989000, 0x05989fff] PGTABLE [ 0.000000] RAMDISK: [mem 0x1768b000-0x18ecffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F6930 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x0000000018EE1936 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x0000000018EE180A 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x0000000018EE0040 0017CA (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x0000000018EE0000 000040 [ 0.000000] ACPI: APIC 0x0000000018EE187E 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x0000000018EE18FE 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] 0MB HIGHMEM available. [ 0.000000] 398MB LOWMEM available. [ 0.000000] mapped low ram: 0 - 18ed2000 [ 0.000000] low ram: 0 - 18ed2000 [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:18ed1001, primary cpu clock [ 0.000000] kvm-clock: using sched offset of 2911937517 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] Normal [mem 0x0000000001000000-0x0000000018ed1fff] [ 0.000000] HighMem empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x0000000018ed1fff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000018ed1fff] [ 0.000000] On node 0 totalpages: 102000 [ 0.000000] free_area_init_node: node 0, pgdat 84080900, node_mem_map 9736b020 [ 0.000000] DMA zone: 32 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 3998 pages, LIFO batch:0 [ 0.000000] Normal zone: 766 pages used for memmap [ 0.000000] Normal zone: 98002 pages, LIFO batch:31 [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 3d28140 [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] e820: [mem 0x18f00000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370452778343963 ns [ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768 [ 0.000000] pcpu-alloc: [0] 0 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 101202 [ 0.000000] Kernel command line: root=/dev/ram0 hung_task_panic=1 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw link=/kbuild-tests/run-queue/kvm/i386-randconfig-b0-09232213/linux-devel:devel-spot-201709232001:cc4be4e6ab033b35318a3a8f8e089331855ba871/.vmlinuz-cc4be4e6ab033b35318a3a8f8e089331855ba871-20170925202938-6:quantal-vp-62 branch=linux-devel/devel-spot-201709232001 BOOT_IMAGE=/pkg/linux/i386-randconfig-b0-09232213/gcc-5/cc4be4e6ab033b35318a3a8f8e089331855ba871/vmlinuz-4.14.0-rc1-00227-gcc4be4e drbd.minor_count=8 [ 0.000000] sysrq: sysrq always enabled. [ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes) [ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes) [ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes) [ 0.000000] Initializing CPU#0 [ 0.000000] allocated 412484 bytes of page_ext [ 0.000000] Initializing HighMem for node 0 (00000000:00000000) [ 0.000000] Memory: 303708K/408000K available (33871K kernel code, 8664K rwdata, 12152K rodata, 1656K init, 18588K bss, 104292K reserved, 0K cma-reserved, 0K highmem) [ 0.000000] virtual kernel memory layout: [ 0.000000] fixmap : 0xfffe2000 - 0xfffff000 ( 116 kB) [ 0.000000] pkmap : 0xffc00000 - 0xffe00000 (2048 kB) [ 0.000000] vmalloc : 0x996d2000 - 0xffbfe000 (1637 MB) [ 0.000000] lowmem : 0x80000000 - 0x98ed2000 ( 398 MB) [ 0.000000] .init : 0x84591000 - 0x8472f000 (1656 kB) [ 0.000000] .data : 0x83113f2a - 0x8456a280 (20824 kB) [ 0.000000] .text : 0x81000000 - 0x83113f2a (33871 kB) [ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok. [ 0.000000] [ 0.000000] ********************************************************** [ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.000000] ** ** [ 0.000000] ** trace_printk() being used. Allocating extra memory. ** [ 0.000000] ** ** [ 0.000000] ** This means that this is a DEBUG kernel and it is ** [ 0.000000] ** unsafe for production use. ** [ 0.000000] ** ** [ 0.000000] ** If you see this message and you are not debugging ** [ 0.000000] ** the kernel, report this immediately to your vendor! ** [ 0.000000] ** ** [ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.000000] ********************************************************** [ 0.003333] Running RCU self tests [ 0.003333] Preemptible hierarchical RCU implementation. [ 0.003333] RCU event tracing is enabled. [ 0.003333] RCU lockdep checking is enabled. [ 0.003333] RCU callback double-/use-after-free debug enabled. [ 0.003333] RCU debug extended QS entry/exit. [ 0.003333] RCU CPU stall warnings timeout set to 100 (rcu_cpu_stall_timeout). [ 0.003333] Tasks RCU enabled. [ 0.003333] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 [ 0.003333] CPU 0 irqstacks, hard=96d94000 soft=96d96000 [ 0.003333] Offload RCU callbacks from CPUs: . [ 0.003333] console [ttyS0] enabled [ 0.003333] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar [ 0.003333] ... MAX_LOCKDEP_SUBCLASSES: 8 [ 0.003333] ... MAX_LOCK_DEPTH: 48 [ 0.003333] ... MAX_LOCKDEP_KEYS: 8191 [ 0.003333] ... CLASSHASH_SIZE: 4096 [ 0.003333] ... MAX_LOCKDEP_ENTRIES: 32768 [ 0.003333] ... MAX_LOCKDEP_CHAINS: 65536 [ 0.003333] ... CHAINHASH_SIZE: 32768 [ 0.003333] memory used by lock dependency info: 4399 kB [ 0.003333] per task-struct memory footprint: 1536 bytes [ 0.003333] ------------------------ [ 0.003333] | Locking API testsuite: [ 0.003333] ---------------------------------------------------------------------------- [ 0.003333] | spin |wlock |rlock |mutex | wsem | rsem | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] A-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-B-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-B-C-C-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-C-A-B-C deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-B-C-C-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-C-D-B-D-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] A-B-C-D-B-C-D-A deadlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] double unlock: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] initialize held: ok | ok | ok | ok | ok | ok | ok | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] recursive read-lock: | ok | | ok | [ 0.003333] recursive read-lock #2: | ok | | ok | [ 0.003333] mixed read-write-lock: | ok | | ok | [ 0.003333] mixed write-read-lock: | ok | | ok | [ 0.003333] mixed read-lock/lock-write ABBA: |FAILED| | ok | [ 0.003333] mixed read-lock/lock-read ABBA: | ok | | ok | [ 0.003333] mixed write-lock/lock-write ABBA: | ok | | ok | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] hard-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.003333] soft-irqs-on + irq-safe-A/12: ok | ok | ok | [ 0.003333] hard-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.003333] soft-irqs-on + irq-safe-A/21: ok | ok | ok | [ 0.003333] sirq-safe-A => hirqs-on/12: ok | ok | ok | [ 0.003333] sirq-safe-A => hirqs-on/21: ok | ok | ok | [ 0.003333] hard-safe-A + irqs-on/12: ok | ok | ok | [ 0.003333] soft-safe-A + irqs-on/12: ok | ok | ok | [ 0.003333] hard-safe-A + irqs-on/21: ok | ok | ok | [ 0.003333] soft-safe-A + irqs-on/21: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/123: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/132: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/213: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/231: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/312: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #1/321: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/123: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/132: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/213: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/231: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/312: ok | ok | ok | [ 0.003333] hard-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.003333] soft-safe-A + unsafe-B #2/321: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/123: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/123: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/132: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/132: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/213: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/213: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/231: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/231: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/312: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/312: ok | ok | ok | [ 0.003333] hard-irq lock-inversion/321: ok | ok | ok | [ 0.003333] soft-irq lock-inversion/321: ok | ok | ok | [ 0.003333] hard-irq read-recursion/123: ok | [ 0.003333] soft-irq read-recursion/123: ok | [ 0.003333] hard-irq read-recursion/132: ok | [ 0.003333] soft-irq read-recursion/132: ok | [ 0.003333] hard-irq read-recursion/213: ok | [ 0.003333] soft-irq read-recursion/213: ok | [ 0.003333] hard-irq read-recursion/231: ok | [ 0.003333] soft-irq read-recursion/231: ok | [ 0.003333] hard-irq read-recursion/312: ok | [ 0.003333] soft-irq read-recursion/312: ok | [ 0.003333] hard-irq read-recursion/321: ok | [ 0.003333] soft-irq read-recursion/321: ok | [ 0.003333] -------------------------------------------------------------------------- [ 0.003333] | Wound/wait tests | [ 0.003333] --------------------- [ 0.003333] ww api failures: ok | ok | ok | [ 0.003333] ww contexts mixing: ok | ok | [ 0.003333] finishing ww context: ok | ok | ok | ok | [ 0.003333] locking mismatches: ok | ok | ok | [ 0.003333] EDEADLK handling: ok | ok | ok | ok | ok | ok | ok | ok | ok | ok | [ 0.003333] spinlock nest unlocked: ok | [ 0.003333] ----------------------------------------------------- [ 0.003333] |block | try |context| [ 0.003333] ----------------------------------------------------- [ 0.003333] context: ok | ok | ok | [ 0.003333] try: ok | ok | ok | [ 0.003333] block: ok | ok | ok | [ 0.003333] spinlock: ok | ok | ok | [ 0.003333] ------------------------------------------------------- [ 0.003333] Good, all 261 testcases passed! | [ 0.003333] --------------------------------- [ 0.003333] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.003333] hpet clockevent registered [ 0.003374] tsc: Detected 2693.508 MHz processor [ 0.004074] Calibrating delay loop (skipped) preset value.. 5389.47 BogoMIPS (lpj=8978360) [ 0.004591] pid_max: default: 4096 minimum: 301 [ 0.006702] ACPI: Core revision 20170728 [ 0.030471] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.031458] ACPI: setting ELCR to 0200 (from 0c00) [ 0.032294] Security Framework initialized [ 0.032939] LoadPin: ready to pin (currently enabled) [ 0.032986] Smack: Initializing. [ 0.033889] AppArmor: AppArmor disabled by boot time parameter [ 0.034853] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.035844] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) [ 0.039178] mce: CPU supports 10 MCE banks [ 0.040124] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040940] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041830] CPU: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1) [ 0.047638] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only. [ 0.049620] Hierarchical SRCU implementation. [ 0.053362] devtmpfs: initialized [ 0.057186] gcov: version magic: 0x3530342a [ 0.076420] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns [ 0.076721] futex hash table entries: 16 (order: -3, 768 bytes) [ 0.082043] NET: Registered protocol family 16 [ 0.089964] cpuidle: using governor ladder [ 0.093075] ACPI: bus type PCI registered [ 0.094685] PCI: PCI BIOS area is rw and x. Use pci=nobios if you want it NX. [ 0.095763] PCI: PCI BIOS revision 2.10 entry at 0xfd3e3, last bus=0 [ 0.096701] PCI: Using configuration type 1 for base access [ 0.265599] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.270622] ACPI: Added _OSI(Module Device) [ 0.271230] ACPI: Added _OSI(Processor Device) [ 0.271865] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.272534] ACPI: Added _OSI(Processor Aggregator Device) [ 0.317917] ACPI: Interpreter enabled [ 0.318838] ACPI: (supports S0 S3 S4 S5) [ 0.319433] ACPI: Using PIC for interrupt routing [ 0.320319] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.326384] ACPI: Enabled 3 GPEs in block 00 to 0F [ 0.472923] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.473408] acpi PNP0A03:00: _OSC: OS supports [Segments] [ 0.474421] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.475467] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.478492] PCI host bridge to bus 0000:00 [ 0.480049] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.481032] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.482015] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.483382] pci_bus 0000:00: root bus resource [mem 0x18f00000-0xfebfffff window] [ 0.484461] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.485401] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.491563] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.497481] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.523373] pci 0000:00:01.1: reg 0x20: [io 0xc200-0xc20f] [ 0.536748] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.537791] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.538740] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.540039] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.545625] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.547420] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.548488] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.553973] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000 [ 0.560038] pci 0000:00:02.0: reg 0x10: [mem 0xfa000000-0xfaffffff pref] [ 0.580044] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff] [ 0.613383] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref] [ 0.620855] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000 [ 0.626709] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff] [ 0.636712] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f] [ 0.670042] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.680831] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 [ 0.686714] pci 0000:00:04.0: reg 0x10: [io 0xc040-0xc07f] [ 0.693383] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff] [ 0.716714] pci 0000:00:04.0: reg 0x20: [mem 0xfb000000-0xfb7fffff 64bit pref] [ 0.733821] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.740048] pci 0000:00:05.0: reg 0x10: [io 0xc080-0xc0bf] [ 0.750042] pci 0000:00:05.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff] [ 0.770050] pci 0000:00:05.0: reg 0x20: [mem 0xfb800000-0xfbffffff 64bit pref] [ 0.782632] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.790047] pci 0000:00:06.0: reg 0x10: [io 0xc0c0-0xc0ff] [ 0.800050] pci 0000:00:06.0: reg 0x14: [mem 0xfebf3000-0xfebf3fff] [ 0.820051] pci 0000:00:06.0: reg 0x20: [mem 0xfc000000-0xfc7fffff 64bit pref] [ 0.834521] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000 [ 0.843380] pci 0000:00:07.0: reg 0x10: [io 0xc100-0xc13f] [ 0.850048] pci 0000:00:07.0: reg 0x14: [mem 0xfebf4000-0xfebf4fff] [ 0.873382] pci 0000:00:07.0: reg 0x20: [mem 0xfc800000-0xfcffffff 64bit pref] [ 0.886983] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000 [ 0.896761] pci 0000:00:08.0: reg 0x10: [io 0xc140-0xc17f] [ 0.903384] pci 0000:00:08.0: reg 0x14: [mem 0xfebf5000-0xfebf5fff] [ 0.926723] pci 0000:00:08.0: reg 0x20: [mem 0xfd000000-0xfd7fffff 64bit pref] [ 0.936666] pci 0000:00:09.0: [1af4:1001] type 00 class 0x010000 [ 0.939999] pci 0000:00:09.0: reg 0x10: [io 0xc180-0xc1bf] [ 0.946715] pci 0000:00:09.0: reg 0x14: [mem 0xfebf6000-0xfebf6fff] [ 0.970087] pci 0000:00:09.0: reg 0x20: [mem 0xfd800000-0xfdffffff 64bit pref] [ 0.985255] pci 0000:00:0a.0: [1af4:1001] type 00 class 0x010000 [ 0.993382] pci 0000:00:0a.0: reg 0x10: [io 0xc1c0-0xc1ff] [ 1.003382] pci 0000:00:0a.0: reg 0x14: [mem 0xfebf7000-0xfebf7fff] [ 1.023383] pci 0000:00:0a.0: reg 0x20: [mem 0xfe000000-0xfe7fffff 64bit pref] [ 1.043773] pci 0000:00:0b.0: [8086:25ab] type 00 class 0x088000 [ 1.049060] pci 0000:00:0b.0: reg 0x10: [mem 0xfebf8000-0xfebf800f] [ 1.091756] pci_bus 0000:00: on NUMA node 0 [ 1.108349] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 1.115791] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 1.123000] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 1.129217] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 1.131643] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 1.143999] pci 0000:00:02.0: vgaarb: setting as boot VGA device [ 1.144903] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 1.146184] pci 0000:00:02.0: vgaarb: bridge control possible [ 1.146715] vgaarb: loaded [ 1.154189] SCSI subsystem initialized [ 1.155772] media: Linux media interface: v0.10 [ 1.156780] Linux video capture interface: v2.00 [ 1.157811] pps_core: LinuxPPS API ver. 1 registered [ 1.160041] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 1.161441] PTP clock support registered [ 1.165473] PCI: Using ACPI for IRQ routing [ 1.166111] PCI: pci_cache_line_size set to 64 bytes [ 1.167208] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] [ 1.168115] e820: reserve RAM buffer [mem 0x18ed2000-0x1bffffff] [ 1.174435] Bluetooth: Core ver 2.22 [ 1.175191] NET: Registered protocol family 31 [ 1.175912] Bluetooth: HCI device and connection manager initialized [ 1.176778] Bluetooth: HCI socket layer initialized [ 1.177609] Bluetooth: L2CAP socket layer initialized [ 1.178677] NET: Registered protocol family 8 [ 1.179398] NET: Registered protocol family 20 [ 1.185027] NetLabel: Initializing [ 1.185596] NetLabel: domain hash size = 128 [ 1.186259] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 1.187026] NetLabel: unlabeled traffic allowed by default [ 1.193592] clocksource: Switched to clocksource kvm-clock [ 1.261362] Warning: could not register all branches stats [ 1.262296] Warning: could not register annotated branches stats [ 2.142705] VFS: Disk quotas dquot_6.6.0 [ 2.144055] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) [ 2.146679] pnp: PnP ACPI init [ 2.148346] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) [ 2.150720] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) [ 2.152976] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) [ 2.154495] pnp 00:03: [dma 2] [ 2.155662] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) [ 2.160730] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active) [ 2.163960] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active) [ 2.166297] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active) [ 2.175439] pnp: PnP ACPI: found 7 devices [ 2.217480] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 2.219828] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 2.220873] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 2.221892] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 2.223118] pci_bus 0000:00: resource 7 [mem 0x18f00000-0xfebfffff window] [ 2.224583] NET: Registered protocol family 2 [ 2.227932] TCP established hash table entries: 4096 (order: 2, 16384 bytes) [ 2.229163] TCP bind hash table entries: 4096 (order: 5, 163840 bytes) [ 2.232144] TCP: Hash tables configured (established 4096 bind 4096) [ 2.233518] UDP hash table entries: 256 (order: 2, 24576 bytes) [ 2.234544] UDP-Lite hash table entries: 256 (order: 2, 24576 bytes) [ 2.236644] NET: Registered protocol family 1 [ 2.238981] RPC: Registered named UNIX socket transport module. [ 2.240077] RPC: Registered udp transport module. [ 2.240809] RPC: Registered tcp transport module. [ 2.241535] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 2.242522] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 2.243584] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 2.244542] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 2.245672] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 2.247456] PCI: CLS 0 bytes, default 64 [ 2.249876] Unpacking initramfs... [ 7.704340] Freeing initrd memory: 24852K [ 7.709955] apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac) [ 7.710903] apm: overridden by ACPI. [ 7.722364] PCLMULQDQ-NI instructions are not detected. [ 7.726704] The force parameter has not been set to 1. The Iris poweroff handler will not be installed. [ 7.728261] spin_lock-torture:--- Start of test [debug]: nwriters_stress=2 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0 [ 7.731444] spin_lock-torture: Creating torture_shuffle task [ 7.732719] spin_lock-torture: Creating torture_stutter task [ 7.734709] spin_lock-torture: torture_shuffle task started [ 7.735962] spin_lock-torture: Creating lock_torture_writer task [ 7.737582] spin_lock-torture: torture_stutter task started [ 7.738766] spin_lock-torture: Creating lock_torture_writer task [ 7.740909] spin_lock-torture: lock_torture_writer task started [ 7.741875] spin_lock-torture: Creating lock_torture_stats task [ 7.745174] spin_lock-torture: lock_torture_writer task started [ 7.747439] spin_lock-torture: lock_torture_stats task started [ 8.246673] [ 8.246941] ====================================================== [ 8.247816] WARNING: possible circular locking dependency detected [ 8.248691] 4.14.0-rc1-00227-gcc4be4e #1 Not tainted [ 8.249391] ------------------------------------------------------ [ 8.249707] kworker/0:1/13 is trying to acquire lock: [ 8.249707] (ww_class_mutex){+.+.}, at: [<81150775>] test_abba_work+0xea/0x571 [ 8.249707] [ 8.249707] but now in release context of a crosslock acquired at the following: [ 8.249707] ((complete)&abba.b_ready){+.+.}, at: [<83103dfc>] wait_for_completion+0x25/0x35 [ 8.249707] [ 8.249707] which lock already depends on the new lock. [ 8.249707] [ 8.249707] the existing dependency chain (in reverse order) is: [ 8.249707] [ 8.249707] -> #1 ((complete)&abba.b_ready){+.+.}: [ 8.249707] validate_chain+0xf47/0x1171 [ 8.249707] __lock_acquire+0xcad/0x112e [ 8.249707] lock_acquire+0x26c/0x3a4 [ 8.249707] wait_for_common+0x69/0x419 [ 8.249707] wait_for_completion+0x25/0x35 [ 8.249707] test_abba+0x2ee/0x8cd [ 8.249707] test_ww_mutex_init+0x19d/0x40d [ 8.249707] do_one_initcall+0x12f/0x308 [ 8.249707] kernel_init_freeable+0x23a/0x3e8 [ 8.249707] kernel_init+0x1b/0x309 [ 8.249707] ret_from_fork+0x19/0x24 [ 8.249707] [ 8.249707] -> #0 (ww_class_mutex){+.+.}: [ 8.249707] __ww_mutex_lock+0x11a/0x2b19 [ 8.249707] ww_mutex_lock+0x71/0x1af [ 8.249707] test_abba_work+0xea/0x571 [ 8.249707] process_one_work+0x88f/0x106e [ 8.249707] worker_thread+0x7a4/0xaaa [ 8.249707] [ 8.249707] other info that might help us debug this: [ 8.249707] [ 8.249707] Possible unsafe locking scenario by crosslock: [ 8.249707] [ 8.249707] CPU0 CPU1 [ 8.249707] ---- ---- [ 8.249707] lock(ww_class_mutex); [ 8.249707] lock((complete)&abba.b_ready); [ 8.249707] lock(ww_class_mutex); [ 8.249707] unlock((complete)&abba.b_ready); [ 8.249707] [ 8.249707] *** DEADLOCK *** [ 8.249707] [ 8.249707] 5 locks held by kworker/0:1/13: [ 8.249707] #0: ("events"){+.+.}, at: [<810eeb1c>] process_one_work+0x447/0x106e [ 8.249707] #1: ((&abba.work)){+.+.}, at: [<810eeb1c>] process_one_work+0x447/0x106e [ 8.249707] #2: (ww_class_acquire){+.+.}, at: [<810eef64>] process_one_work+0x88f/0x106e [ 8.249707] #3: (ww_class_mutex){+.+.}, at: [<81150775>] test_abba_work+0xea/0x571 [ 8.249707] #4: (&x->wait#5){....}, at: [<81133912>] complete+0x25/0xc3 [ 8.249707] [ 8.249707] stack backtrace: [ 8.249707] CPU: 0 PID: 13 Comm: kworker/0:1 Not tainted 4.14.0-rc1-00227-gcc4be4e #1 [ 8.249707] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014 [ 8.249707] Workqueue: events test_abba_work [ 8.249707] Call Trace: [ 8.249707] dump_stack+0x40/0x5e [ 8.249707] print_circular_bug+0x1c0/0x1da [ 8.249707] check_prev_add+0x1b5/0xbe5 [ 8.249707] ? save_stack_trace+0x25/0x36 [ 8.249707] commit_xhlock+0x420/0x554 [ 8.249707] ? check_noncircular+0x2e/0x2e [ 8.249707] lock_commit_crosslock+0x222/0x36b [ 8.249707] complete+0x3d/0xc3 [ 8.249707] test_abba_work+0x103/0x571 [ 8.249707] ? process_one_work+0x88f/0x106e [ 8.249707] process_one_work+0x88f/0x106e [ 8.249707] worker_thread+0x7a4/0xaaa [ 8.249707] ? rescuer_thread+0x5f2/0x5f2 [ 8.249707] kthread+0x25e/0x271 [ 8.249707] ? rescuer_thread+0x5f2/0x5f2 [ 8.249707] ? __kthread_create_on_node+0x316/0x316 [ 8.249707] ret_from_fork+0x19/0x24 [ 8.763118] tsc: Refined TSC clocksource calibration: 2693.509 MHz [ 8.764075] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d34aa491a, max_idle_ns: 440795256568 ns [ 14.340158] torture_init_begin: Refusing rcu init: spin_lock running. [ 14.343354] torture_init_begin: One torture test at a time! [ 14.347257] audit: initializing netlink subsys (disabled) [ 14.353244] audit: type=2000 audit(1506342815.297:1): state=initialized audit_enabled=0 res=1 [ 16.183097] workingset: timestamp_bits=30 max_order=17 bucket_order=0 [ 16.188868] zbud: loaded [ 16.195722] DLM installed [ 16.206036] NFS: Registering the id_resolver key type [ 16.206987] Key type id_resolver registered [ 16.207684] Key type id_legacy registered [ 16.211504] ntfs: driver 2.1.32 [Flags: R/O DEBUG]. [ 16.212361] efs: 1.0a - http://aeschi.ch.eu.org/efs/ [ 16.214402] romfs: ROMFS MTD (C) 2007 Red Hat, Inc. [ 16.215237] QNX4 filesystem 0.2.3 registered. [ 16.215971] qnx6: QNX6 filesystem 1.0.0 registered. [ 16.216915] orangefs_debugfs_init: called with debug mask: :none: :0: [ 16.221253] orangefs_init: module version upstream loaded [ 16.224773] JFS: nTxBlock = 2566, nTxLock = 20535 [ 16.227856] befs: version: 0.9.3 [ 16.230355] ocfs2: Registered cluster interface user [ 16.231719] OCFS2 User DLM kernel interface loaded [ 16.242533] ceph: loaded (mds proto 32) [ 16.274666] NET: Registered protocol family 38 [ 16.275588] Key type asymmetric registered [ 16.276337] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 16.278008] io scheduler noop registered (default) [ 16.278836] io scheduler cfq registered [ 16.279461] io scheduler mq-deadline registered [ 16.280316] io scheduler bfq registered [ 16.280953] test_string_helpers: Running tests... [ 16.287591] test_hexdump: all 1184 tests passed [ 16.288620] test_firmware: interface ready [ 16.327516] test_hash: __hash_32() has no arch implementation to test. [ 16.328457] test_hash: hash_32() has no arch implementation to test. [ 16.329356] test_hash: hash_64() has no arch implementation to test. [ 16.330328] test_hash: 33152 tests passed. [ 16.331034] test_siphash: self-tests: pass [ 16.334895] test_printf: all 260 tests passed [ 16.559163] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 2385 [ 16.569835] test_bitmap: all 460506 tests passed [ 16.570271] test_uuid: all 18 tests passed [ 16.572183] crc32: CRC_LE_BITS = 32, CRC_BE BITS = 32 [ 16.572654] crc32: self tests passed, processed 225944 bytes in 240398 nsec [ 16.573565] crc32c: CRC_LE_BITS = 32 [ 16.573924] crc32c: self tests passed, processed 225944 bytes in 123962 nsec [ 16.605660] crc32_combine: 8373 self tests passed [ 16.640111] crc32c_combine: 8373 self tests passed [ 16.641233] xz_dec_test: module loaded [ 16.641786] xz_dec_test: Create a device node with 'mknod xz_dec_test c 246 0' and write .xz files to it. [ 16.649564] glob: 64 self-tests passed, 0 failed [ 16.652495] rbtree testing [ 18.087297] -> test 1 (latency of nnodes insert+delete): 38646 cycles [ 19.786745] -> test 2 (latency of nnodes cached insert+delete): 45732 cycles [ 19.992961] -> test 3 (latency of inorder traversal): 5522 cycles [ 19.996485] -> test 4 (latency to fetch first node) [ 19.997353] non-cached: 67 cycles [ 19.998287] cached: 6 cycles [ 20.191698] augmented rbtree testing [ 22.212934] -> test 1 (latency of nnodes insert+delete): 54441 cycles [ 24.429416] -> test 2 (latency of nnodes cached insert+delete): 59598 cycles [ 24.604547] interval tree insert/remove [ 26.645975] -> 54985 cycles [ 26.647690] interval tree search [ 28.210205] -> 420858 cycles (2692 results) [ 28.212480] gpio_it87: no device [ 28.214200] gpio-mockup: probe of gpio-mockup failed with error -22 [ 28.221448] uvesafb: failed to execute /sbin/v86d [ 28.221898] uvesafb: make sure that the v86d helper is installed and executable [ 28.222561] uvesafb: Getting VBE info block failed (eax=0x4f00, err=-2) [ 28.223227] uvesafb: vbe_init() failed with -22 [ 28.223675] uvesafb: probe of uvesafb.0 failed with error -22 [ 28.224238] ipmi message handler version 39.2 [ 28.224657] ipmi device interface [ 28.225014] IPMI System Interface driver. [ 28.225696] ipmi_si: Unable to find any System Interface(s) [ 28.226203] IPMI SSIF Interface driver [ 28.233976] Copyright (C) 2004 MontaVista Software - IPMI Powerdown via sys_reboot. [ 28.235853] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 28.237076] ACPI: Power Button [PWRF] [ 28.238576] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1 [ 28.239289] ACPI: Power Button [PWRF] [ 28.244887] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled [ 28.269362] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 28.271785] console [ttyS0] disabled [ 28.298567] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 28.680212] console [ttyS0] enabled [ 28.707506] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 28.736797] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 28.768092] lp: driver loaded but no devices found [ 28.770339] Non-volatile memory driver v1.3 [ 28.770979] nsc_gpio initializing [ 28.771473] telclk_interrupt = 0xf non-mcpbl0010 hw. [ 28.772249] smapi::smapi_init, ERROR invalid usSmapiID [ 28.772967] mwave: tp3780i::tp3780I_InitializeBoardData: Error: SMAPI is not available on this machine [ 28.774393] mwave: mwavedd::mwave_init: Error: Failed to initialize board data [ 28.775421] mwave: mwavedd::mwave_init: Error: Failed to initialize [ 28.776425] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds). [ 28.785538] dummy-irq: no IRQ given. Use irq=N [ 28.786304] lkdtm: No crash points registered, enable through debugfs [ 28.790192] Silicon Labs C2 port support v. 0.51.0 - (C) 2007 Rodolfo Giometti [ 28.797765] Uniform Multi-Platform E-IDE driver [ 28.800757] ide_generic: please use "probe_mask=0x3f" module parameter for probing all legacy ISA IDE ports [ 28.802166] ide-gd driver 1.18 [ 28.803718] Loading iSCSI transport class v2.0-870. [ 28.807320] rdac: device handler registered [ 28.808396] hp_sw: device handler registered [ 28.809016] emc: device handler registered [ 28.809897] alua: device handler registered [ 28.810578] osst :I: Tape driver with OnStream support version 0.99.4 [ 28.810578] osst :I: $Id: osst.c,v 1.73 2005/01/01 21:13:34 wriede Exp $ [ 28.814340] SCSI Media Changer driver v0.25 [ 28.820945] Rounding down aligned max_sectors from 4294967295 to 4294967288 [ 28.826665] mtdoops: mtd device (mtddev=name/number) must be supplied [ 28.827603] L440GX flash mapping: failed to find PIIX4 ISA bridge, cannot continue [ 28.828739] SBC-GXx flash: IO:0x258-0x259 MEM:0xdc000-0xdffff [ 28.864141] No valid DiskOnChip devices found [ 28.864917] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 28.866223] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 28.867593] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 28.869044] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 28.870542] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 28.871983] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0 [ 28.873510] nand: device found, Manufacturer ID: 0x98, Chip ID: 0x39 [ 28.874491] nand: Toshiba NAND 128MiB 1,8V 8-bit [ 28.875202] nand: 128 MiB, SLC, erase size: 16 KiB, page size: 512, OOB size: 16 [ 28.876426] flash size: 128 MiB [ 28.876936] page size: 512 bytes [ 28.877435] OOB area size: 16 bytes [ 28.877980] sector size: 16 KiB [ 28.878472] pages number: 262144 [ 28.878973] pages per sector: 32 [ 28.879481] bus width: 8 [ 28.879921] bits in sector size: 14 [ 28.880472] bits in page size: 9 [ 28.880975] bits in OOB size: 4 [ 28.881468] flash size with OOB: 135168 KiB [ 28.882112] page address bytes: 4 [ 28.882634] sector address bytes: 3 [ 28.883214] options: 0x42 [ 28.884909] Scanning device for bad blocks [ 28.934900] ftl_cs: FTL header not found. [ 28.938503] Creating 1 MTD partitions on "NAND 128MiB 1,8V 8-bit": [ 28.939079] 0x000000000000-0x000008000000 : "NAND simulator partition 0" [ 28.948894] ftl_cs: FTL header not found. [ 28.954223] ipddp.c:v0.01 8/28/97 Bradford W. Johnson [ 28.957908] ipddp0: Appletalk-IP Decap. mode by Jay Schulist [ 28.958593] vcan: Virtual CAN interface driver [ 28.959000] vxcan: Virtual CAN Tunnel driver [ 28.959390] slcan: serial line CAN interface driver [ 28.959890] slcan: 10 dynamic interface channels. [ 28.960402] panel: panel driver not yet registered [ 28.962118] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 28.964094] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 28.967058] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 28.972385] evbug: Connected device: input1 (Power Button at LNXPWRBN/button/input0) [ 28.977833] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2 [ 28.990258] evbug: Connected device: input2 (AT Translated Set 2 keyboard at isa0060/serio0/input0) [ 28.998039] evbug: Disconnected device: input2 [ 29.002923] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3 [ 29.004500] apanel: Fujitsu BIOS signature 'FJKEYINF' not found... [ 29.008621] wistron_btns: System unknown [ 29.016230] lirc_dev: IR Remote Control driver registered, major 240 [ 29.016937] evbug: Connected device: input3 (AT Translated Set 2 keyboard at isa0060/serio0/input0) [ 29.018115] IR NEC protocol handler initialized [ 29.018536] IR RC6 protocol handler initialized [ 29.018951] IR JVC protocol handler initialized [ 29.019365] IR SANYO protocol handler initialized [ 29.019891] IR MCE Keyboard/mouse protocol handler initialized [ 29.020424] IR XMP protocol handler initialized [ 29.021517] pps pps0: new PPS source ktimer [ 29.021913] pps pps0: ktimer PPS source registered [ 29.022348] pps_ldisc: PPS line discipline registered [ 29.022810] pps_parport: parallel port PPS client [ 29.024537] Driver for 1-wire Dallas network protocol. [ 29.025868] w1_f0d_init() [ 29.026238] __power_supply_register: Expected proper parent device for 'test_ac' [ 29.028751] __power_supply_register: Expected proper parent device for 'test_battery' [ 29.031661] (NULL device *): hwmon_device_register() is deprecated. Please convert the driver to use hwmon_device_register_with_info(). [ 29.032973] __power_supply_register: Expected proper parent device for 'test_usb' [ 29.045252] applesmc: supported laptop not found! [ 29.046055] applesmc: driver init failed (ret=-19)! [ 29.048668] f71882fg: Not a Fintek device [ 29.049336] f71882fg: Not a Fintek device [ 29.062370] intel_powerclamp: CPU does not support MWAIT [ 29.062634] Bluetooth: HCI UART driver ver 2.3 [ 29.064257] Bluetooth: HCI UART protocol H4 registered [ 29.065089] Bluetooth: HCI UART protocol BCSP registered [ 29.066085] Bluetooth: HCI UART protocol LL registered [ 29.067086] Bluetooth: HCI UART protocol Intel registered [ 29.067913] Bluetooth: HCI UART protocol Marvell registered [ 29.070771] wbsd: Winbond W83L51xD SD/MMC card interface driver [ 29.071724] wbsd: Copyright(c) Pierre Ossman [ 29.079888] ledtrig-cpu: registered to indicate activity on CPUs [ 29.087562] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2) [ 29.088894] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2) [ 29.090303] hidraw: raw HID events driver (C) Jiri Kosina [ 29.107344] hdaps: supported laptop not found! [ 29.108058] hdaps: driver init failed (ret=-19)! [ 29.143593] fake-fmc-carrier: mezzanine 0 [ 29.144307] Manufacturer: fake-vendor [ 29.145004] Product name: fake-design-for-testing [ 29.147549] fmc fake-design-for-testing-f001: Driver has no ID: matches all [ 29.148738] fmc_trivial: probe of fake-design-for-testing-f001 failed with error -95 [ 29.150040] fmc fake-design-for-testing-f001: Driver has no ID: matches all [ 29.151117] fmc_write_eeprom fake-design-for-testing-f001: fmc_write_eeprom: no busid passed, refusing all cards [ 29.152680] fmc fake-design-for-testing-f001: Driver has no ID: matches all [ 29.155946] fmc_chardev fake-design-for-testing-f001: Created misc device "fake-design-for-testing-f001" [ 29.158493] fmc_chardev fake-design-for-testing-f001: Created misc device "fake-design-for-testing-f001" [ 29.166091] NET: Registered protocol family 26 [ 29.170023] u32 classifier [ 29.170297] Performance counters on [ 29.171126] ipip: IPv4 and MPLS over IPv4 tunneling driver [ 29.174808] Initializing XFRM netlink socket [ 29.175562] NET: Registered protocol family 17 [ 29.175993] NET: Registered protocol family 15 [ 29.176654] NET: Registered protocol family 5 [ 29.177394] NET: Registered protocol family 9 [ 29.178116] X25: Linux Version 0.2 [ 29.178451] can: controller area network core (rev 20170425 abi 9) [ 29.180054] NET: Registered protocol family 29 [ 29.180481] can: broadcast manager protocol (rev 20170425 t) [ 29.182645] NET: Registered protocol family 33 [ 29.183121] Key type rxrpc registered [ 29.183475] Key type rxrpc_s registered [ 29.184048] l2tp_core: L2TP core driver, V2.0 [ 29.184459] l2tp_ip: L2TP IP encapsulation support (L2TPv3) [ 29.184981] l2tp_netlink: L2TP netlink interface [ 29.185740] l2tp_eth: L2TP ethernet pseudowire support (L2TPv3) [ 29.186531] l2tp_debugfs: L2TP debugfs support [ 29.186946] NET4: DECnet for Linux: V.2.5.68s (C) 1995-2003 Linux DECnet Project Team [ 29.188637] DECnet: Routing cache hash table of 512 buckets, 20Kbytes [ 29.189245] NET: Registered protocol family 12 [ 29.190245] DCCP: Activated CCID 2 (TCP-like) [ 29.190798] sctp: Hash tables configured (bind 64/102) [ 29.192490] tipc: Activated (version 2.0.0) [ 29.192936] NET: Registered protocol family 30 [ 29.193636] tipc: Started in single node mode [ 29.194113] 9pnet: Installing 9P2000 support [ 29.195805] Key type dns_resolver registered [ 29.196225] Key type ceph registered [ 29.197047] libceph: loaded (mon/osd proto 15/24) [ 29.197719] batman_adv: B.A.T.M.A.N. advanced 2017.3 (compatibility version 15) loaded [ 29.198473] openvswitch: Open vSwitch switching datapath [ 29.199075] mpls_gso: MPLS GSO support [ 29.205194] sched_clock: Marking stable (29203585729, 0)->(30230528921, -1026943192) [ 29.210371] kAFS: Red Hat AFS client v0.1 registering. [ 29.213914] Key type trusted registered [ 29.215035] Key type encrypted registered [ 29.221641] BIOS EDD facility v0.16 2004-Jun-25, 6 devices found [ 29.229730] Freeing unused kernel memory: 1656K [ 29.230501] Write protecting the kernel text: 33872k [ 29.231183] Write protecting the kernel read-only data: 12160k [ 29.231721] NX-protecting the kernel data: 23472k mountall: Event failed [ 29.570688] init: Failed to create pty - disabling logging for job [ 29.571368] init: Temporary process spawn error: No such file or directory [ 29.603665] init: Failed to create pty - disabling logging for job [ 29.604354] init: Temporary process spawn error: No such file or directory [ 29.695461] init: Failed to create pty - disabling logging for job [ 29.696844] init: Temporary process spawn error: No such file or directory [ 29.702122] init: Failed to create pty - disabling logging for job [ 29.703473] init: Temporary process spawn error: No such file or directory [ 29.734182] sh[193] bad frame in sigreturn frame:7f98c3ac ip:77ef41b2 sp:7f98c92c orax:ffffffff in ld-2.15.so[77ef3000+20000] [ 29.737091] init: mounted-run main process (193) killed by SEGV signal mountall: Event failed [ 29.828407] init: Failed to create pty - disabling logging for job [ 29.829478] init: Temporary process spawn error: No such file or directory [ 29.835216] init: Failed to create pty - disabling logging for job [ 29.836276] init: Temporary process spawn error: No such file or directory [ 29.853233] init: Failed to create pty - disabling logging for job [ 29.854314] init: Temporary process spawn error: No such file or directory [ 29.874535] init: Failed to create pty - disabling logging for job [ 29.875601] init: Temporary process spawn error: No such file or directory [ 29.896544] init: plymouth-log main process (209) terminated with status 1 [ 29.924895] init: Failed to create pty - disabling logging for job [ 29.925961] init: Temporary process spawn error: No such file or directory [ 29.954680] init: Failed to create pty - disabling logging for job [ 29.955739] init: Temporary process spawn error: No such file or directory [ 29.957421] udevd[216]: starting version 175 [ 29.982295] init: Failed to create pty - disabling logging for job [ 29.983780] init: Temporary process spawn error: No such file or directory [ 29.993629] init: Failed to create pty - disabling logging for job [ 29.994700] init: Temporary process spawn error: No such file or directory udevd[223]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:LNXSYSTM:': No such file or directory udevd[226]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:LNXSYBUS:': No such file or directory udevd[229]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00008086d00001237sv00001AF4sd00001100bc06sc00i00': No such file or directory udevd[230]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0103:': No such file or directory udevd[231]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:ACPI0010:PNP0A05:': No such file or directory udevd[232]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:LNXSYBUS:': No such file or directory udevd[234]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv input:b0019v0000p0001e0000-e0,1,k74,ramlsfw': No such file or directory udevd[239]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0A03:': No such file or directory udevd[242]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0C0F:': No such file or directory udevd[244]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0C0F:': No such file or directory udevd[241]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0C0F:': No such file or directory udevd[246]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0C0F:': No such file or directory udevd[247]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:LNXCPU:': No such file or directory udevd[248]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:LNXCPU:': No such file or directory udevd[249]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0A06:': No such file or directory udevd[254]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0A06:': No such file or directory udevd[251]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:QEMU0002:': No such file or directory udevd[252]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0A06:': No such file or directory udevd[253]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00008086d00007000sv00001AF4sd00001100bc06sc01i00': No such file or directory udevd[250]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0A06:': No such file or directory udevd[256]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0C0F:': No such file or directory udevd[291]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0400:': No such file or directory udevd[295]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00001234d00001111sv00001AF4sd00001100bc03sc00i00': No such file or directory udevd[297]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0F13:': No such file or directory udevd[296]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0B00:': No such file or directory udevd[292]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0501:': No such file or directory udevd[290]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0303:': No such file or directory udevd[293]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0700:': No such file or directory udevd[298]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00008086d0000100Esv00001AF4sd00001100bc02sc00i00': No such file or directory udevd[294]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0501:': No such file or directory udevd[301]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00001AF4d00001001sv00001AF4sd00000002bc01sc00i00': No such file or directory udevd[299]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00008086d00007010sv00001AF4sd00001100bc01sc01i80': No such file or directory udevd[300]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00001AF4d00001001sv00001AF4sd00000002bc01sc00i00': No such file or directory udevd[302]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00008086d00007113sv00001AF4sd00001100bc06sc80i00': No such file or directory udevd[303]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00001AF4d00001001sv00001AF4sd00000002bc01sc00i00': No such file or directory udevd[304]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00001AF4d00001001sv00001AF4sd00000002bc01sc00i00': No such file or directory udevd[306]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:PNP0103:': No such file or directory udevd[307]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00001AF4d00001001sv00001AF4sd00000002bc01sc00i00': No such file or directory udevd[305]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00001AF4d00001001sv00001AF4sd00000002bc01sc00i00': No such file or directory udevd[308]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv acpi:QEMU0002:': No such file or directory udevd[309]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv platform:gpio-mockup': No such file or directory udevd[310]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv serio:ty01pr00id00ex00': No such file or directory udevd[311]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv platform:platform-framebuffer': No such file or directory udevd[312]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv platform:regulatory': No such file or directory udevd[314]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00008086d000025ABsv00001AF4sd00001100bc08sc80i00': No such file or directory udevd[313]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv input:b0011v0001p0001eAB41-e0,1,4,11,14,k71,72,73,74,75,76,77,79,7A,7B,7C,7D,7E,7F,80,8C,8E,8F,9B,9C,9D,9E,9F,A3,A4,A5,A6,AC,AD,B7,B8,B9,D9,E2,ram4,l0,1,2,sfw': No such file or directory udevd[315]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv platform:uvesafb': No such file or directory udevd[316]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv pci:v00001AF4d00001001sv00001AF4sd00000002bc01sc00i00': No such file or directory [ 31.154807] init: Failed to create pty - disabling logging for job [ 31.155518] init: Temporary process spawn error: No such file or directory udevd[323]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv platform:regulatory': No such file or directory [ 31.231779] init: Failed to create pty - disabling logging for job [ 31.232485] init: Temporary process spawn error: No such file or directory [ 31.275577] init: Failed to create pty - disabling logging for job [ 31.276812] init: Temporary process spawn error: No such file or directory [ 31.322377] sh[325] bad frame in sigreturn frame:7fbd766c ip:77fcf1b2 sp:7fbd7bec orax:ffffffff in ld-2.15.so[77fce000+20000] [ 31.338468] init: Failed to create pty - disabling logging for job [ 31.339272] init: Temporary process spawn error: No such file or directory [ 31.344818] init: network-interface (lo) pre-start process (325) killed by SEGV signal [ 31.346202] init: udev-fallback-graphics main process (328) terminated with status 127 [ 31.354087] init: Failed to create pty - disabling logging for job [ 31.354832] init: Temporary process spawn error: No such file or directory [ 31.388835] sh[334] bad frame in sigreturn frame:7f965b2c ip:77f401b2 sp:7f9660bc orax:ffffffff in ld-2.15.so[77f3f000+20000] [ 31.390950] init: udev-finish main process (334) killed by SEGV signal [ 31.490014] init: networking main process (339) terminated with status 1 [ 31.573601] init: Failed to create pty - disabling logging for job [ 31.574704] init: Temporary process spawn error: No such file or directory [ 31.584373] init: Failed to create pty - disabling logging for job [ 31.585433] init: Temporary process spawn error: No such file or directory [ 31.599087] init: Failed to create pty - disabling logging for job [ 31.600468] init: Temporary process spawn error: No such file or directory [ 31.618559] rc[367] bad frame in sigreturn frame:7fb5aeec ip:77fa91b2 sp:7fb5b45c orax:ffffffff in ld-2.15.so[77fa8000+20000] [ 31.620711] rc[367] bad frame in sigreturn frame:7fb5aeec ip:77fa91b2 sp:7fb5b45c orax:ffffffff in ld-2.15.so[77fa8000+20000] error: '/etc/init.d/rc' exited outside the expected code flow. [ 31.635772] rc[367] bad frame in sigreturn frame:7fb5aeec ip:77fa91b2 sp:7fb5b45c orax:ffffffff in ld-2.15.so[77fa8000+20000] [ 31.643212] init: Failed to create pty - disabling logging for job [ 31.644395] init: Temporary process spawn error: No such file or directory [ 31.664315] init: rc main process (367) killed by SEGV signal [ 31.672071] init: Failed to create pty - disabling logging for job [ 31.673482] init: Temporary process spawn error: No such file or directory [ 31.680469] init: Failed to create pty - disabling logging for job [ 31.681552] init: Temporary process spawn error: No such file or directory [ 31.717188] init: plymouth-stop pre-start process (374) terminated with status 1 udevd[376]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv platform:regulatory': No such file or directory [ 39.496819] sock: process `trinity-main' is using obsolete setsockopt SO_BSDCOMPAT [ 39.622970] x25_bind: socket is bound [ 41.614328] init: tty4 main process (368) terminated with status 1 [ 41.620358] init: tty4 main process ended, respawning [ 41.626719] init: tty5 main process (369) terminated with status 1 [ 41.627819] init: tty5 main process ended, respawning [ 41.658780] init: tty2 main process (371) terminated with status 1 [ 41.660937] init: tty2 main process ended, respawning [ 41.666738] init: tty3 main process (372) terminated with status 1 [ 41.667724] init: tty3 main process ended, respawning [ 41.714032] init: tty6 main process (373) terminated with status 1 [ 41.715139] init: tty6 main process ended, respawning [ 41.717434] init: tty1 main process (375) terminated with status 1 [ 41.718521] init: tty1 main process ended, respawning [ 42.656352] random: crng init done [ 45.259480] VFS: Warning: trinity-c0 using old stat() call. Recompile your binary. [ 45.261762] VFS: Warning: trinity-c0 using old stat() call. Recompile your binary. [ 45.263683] VFS: Warning: trinity-c0 using old stat() call. Recompile your binary. [ 45.725210] VFS: Warning: trinity-c0 using old stat() call. Recompile your binary. [ 45.726089] VFS: Warning: trinity-c0 using old stat() call. Recompile your binary. [ 51.680296] init: tty4 main process (379) terminated with status 1 [ 51.680984] init: tty4 main process ended, respawning [ 51.690259] init: tty5 main process (380) terminated with status 1 [ 51.690953] init: tty5 main process ended, respawning [ 51.723451] init: tty2 main process (381) terminated with status 1 [ 51.724095] init: tty2 main process ended, respawning [ 51.736779] init: tty3 main process (382) terminated with status 1 [ 51.737444] init: tty3 main process ended, respawning [ 51.763588] init: tty6 main process (383) terminated with status 1 [ 51.764248] init: tty6 main process ended, respawning [ 51.770108] init: tty1 main process (384) terminated with status 1 [ 51.770756] init: tty1 main process ended, respawning [ 61.690664] init: tty4 main process (387) terminated with status 1 [ 61.691935] init: tty4 main process ended, respawning [ 61.697252] init: tty5 main process (388) terminated with status 1 [ 61.698331] init: tty5 main process ended, respawning [ 61.733640] init: tty2 main process (389) terminated with status 1 [ 61.734336] init: tty2 main process ended, respawning [ 61.743561] init: tty3 main process (390) terminated with status 1 [ 61.744198] init: tty3 main process ended, respawning [ 61.773994] init: tty6 main process (391) terminated with status 1 [ 61.775055] init: tty6 main process ended, respawning [ 61.779836] init: tty1 main process (392) terminated with status 1 [ 61.780878] init: tty1 main process ended, respawning [ 68.772994] Writes: Total: 2 Max/Min: 0/0 Fail: 0 [ 71.713951] init: tty4 main process (393) terminated with status 1 [ 71.714657] init: tty4 main process ended, respawning [ 71.716219] init: tty5 main process (394) terminated with status 1 [ 71.716920] init: tty5 main process ended, respawning [ 71.750329] init: tty2 main process (395) terminated with status 1 [ 71.751004] init: tty2 main process ended, respawning [ 71.754861] init: tty3 main process (396) terminated with status 1 [ 71.755533] init: tty3 main process ended, respawning [ 71.797304] init: tty6 main process (397) terminated with status 1 [ 71.798053] init: tty6 main process ended, respawning [ 71.799723] init: tty1 main process (398) terminated with status 1 [ 71.800407] init: tty1 main process ended, respawning [ 81.727492] init: tty4 main process (399) terminated with status 1 [ 81.728568] init: tty4 main process ended, respawning [ 81.732007] init: tty5 main process (400) terminated with status 1 [ 81.733167] init: tty5 main process ended, respawning [ 81.764064] init: tty2 main process (401) terminated with status 1 [ 81.765159] init: tty2 main process ended, respawning [ 81.768493] init: tty3 main process (402) terminated with status 1 [ 81.769523] init: tty3 main process ended, respawning [ 81.811390] init: tty6 main process (403) terminated with status 1 [ 81.812494] init: tty6 main process ended, respawning [ 81.815944] init: tty1 main process (404) terminated with status 1 [ 81.817097] init: tty1 main process ended, respawning [ 89.470662] init: Failed to create pty - disabling logging for job [ 89.479496] init: Temporary process spawn error: No such file or directory [ 89.543551] init: tty4 main process (405) killed by TERM signal [ 89.550095] init: tty5 main process (406) killed by TERM signal [ 89.556794] init: tty2 main process (407) killed by TERM signal [ 89.562268] init: tty3 main process (408) killed by TERM signal [ 89.568695] init: tty6 main process (409) killed by TERM signal [ 89.571835] init: tty1 main process (410) killed by TERM signal [ 89.577779] init: hwclock-save main process (414) terminated with status 70 [ 89.580079] init: plymouth-upstart-bridge main process (415) terminated with status 1 [ 89.636434] S20sendsigs[419] bad frame in sigreturn frame:7fd7a96c ip:77f3c1b2 sp:7fd7aefc orax:ffffffff in ld-2.15.so[77f3b000+20000] [ 89.639062] S20sendsigs[418] bad frame in sigreturn frame:7fd7ad2c ip:77f3c1b2 sp:7fd7b2bc orax:ffffffff in ld-2.15.so[77f3b000+20000] /etc/init.d/rc: line 149: 418 Segmentation fault $debug "$script" $action [ 89.673386] S31umountnfs.sh[428] bad frame in sigreturn frame:7fae55ec ip:77ee11b2 sp:7fae5b6c orax:ffffffff in ld-2.15.so[77ee0000+20000] /etc/init.d/rc: line 149: 428 Segmentation fault $debug "$script" $action umount: /run/lock: not mounted * Will now restart [ 89.847223] Unregister pv shared memory for cpu 0 [ 89.847706] spin_lock-torture: Unscheduled system shutdown detected [ 89.848292] no ifx modem active; [ 89.849022] reboot: Restarting system [ 89.849356] reboot: machine restart Elapsed time: 100 kvm=( qemu-system-x86_64 -enable-kvm -cpu kvm64 -kernel /pkg/linux/i386-randconfig-b0-09232213/gcc-5/cc4be4e6ab033b35318a3a8f8e089331855ba871/vmlinuz-4.14.0-rc1-00227-gcc4be4e -initrd /osimage/quantal/quantal-core-i386.cgz -m 399 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -watchdog-action debug -rtc base=localtime -drive file=/fs/sdf1/disk0-quantal-vp-62,media=disk,if=virtio -drive file=/fs/sdf1/disk1-quantal-vp-62,media=disk,if=virtio -drive file=/fs/sdf1/disk2-quantal-vp-62,media=disk,if=virtio -drive file=/fs/sdf1/disk3-quantal-vp-62,media=disk,if=virtio -drive file=/fs/sdf1/disk4-quantal-vp-62,media=disk,if=virtio -drive file=/fs/sdf1/disk5-quantal-vp-62,media=disk,if=virtio -drive file=/fs/sdf1/disk6-quantal-vp-62,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-quantal-vp-62 -serial file:/dev/shm/kboot/quantal-vp-62/serial -serial file:/dev/shm/kboot/quantal-vp-62/kmsg -daemonize -display none -monitor null ) append=( root=/dev/ram0 hung_task_panic=1 debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 net.ifnames=0 printk.devkmsg=on panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 drbd.minor_count=8 systemd.log_level=err ignore_loglevel console=tty0 earlyprintk=ttyS0,115200 console=ttyS0,115200 vga=normal rw link=/kbuild-tests/run-queue/kvm/i386-randconfig-b0-09232213/linux-devel:devel-spot-201709232001:cc4be4e6ab033b35318a3a8f8e089331855ba871/.vmlinuz-cc4be4e6ab033b35318a3a8f8e089331855ba871-20170925202938-6:quantal-vp-62 branch=linux-devel/devel-spot-201709232001 BOOT_IMAGE=/pkg/linux/i386-randconfig-b0-09232213/gcc-5/cc4be4e6ab033b35318a3a8f8e089331855ba871/vmlinuz-4.14.0-rc1-00227-gcc4be4e drbd.minor_count=8 ) "${kvm[@]}" -append "${append[*]}"