Re: 9f4835fb96 ("x86/fpu: Tighten validation of user-supplied .."): Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b

From: Fengguang Wu
Date: Mon Sep 25 2017 - 09:10:51 EST


On Mon, Sep 25, 2017 at 10:20:13AM +0800, kernel test robot wrote:
Hi Ingo,

On your request I'm resending the report here, with attached dmesg,
kconfig and reproduce script.

I'll go on to test your split up commits, too.

https://git.kernel.org/pub/scm/linux/kernel/git/tip/tip.git WIP.x86/fpu

It looks 6fc19a42 ("x86/fpu: Use validate_xstate_header() to validate
the xstate_header in sanitize_restored_xstate()") is the first bad commit.

Attached are the dmesg files for 6fc19a42 and its parent commit.

The panic message is,

[ 28.093528] Freeing unused kernel memory: 1656K
[ 28.094182] Write protecting the kernel text: 33872k
[ 28.094922] Write protecting the kernel read-only data: 12160k
[ 28.095441] NX-protecting the kernel data: 23472k
[ 28.130213] init[1] bad frame in sigreturn frame:7fdddcec ip:77f5a1b2 sp:7fdde254 orax:ffffffff in ld-2.15.so[77f59000+20000]
[ 28.131803] init[1] bad frame in sigreturn frame:7fddd62c ip:77f5a1b0 sp:7fdddb94 orax:ffffffff in ld-2.15.so[77f59000+20000]
[ 28.133169] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b
[ 28.133169]
[ 28.133979] CPU: 0 PID: 1 Comm: init Not tainted 4.14.0-rc1-00228-g6fc19a4 #1
[ 28.134601] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 28.135659] Call Trace:
[ 28.136070] dump_stack+0x40/0x5e
[ 28.136594] panic+0x1c5/0x58c
[ 28.137072] forget_original_parent+0x1ee/0x843
[ 28.137514] do_exit+0x1087/0x17c6
[ 28.137813] do_group_exit+0x1d1/0x1d1
[ 28.138220] get_signal+0x1294/0x12ca
[ 28.138546] do_signal+0x2c/0x55b
[ 28.138842] ? force_sig_info+0x1bd/0x1d5
[ 28.139219] ? force_sig+0x22/0x32
[ 28.139519] ? signal_fault+0x14b/0x161
[ 28.139853] ? exit_to_usermode_loop+0x2f/0x2ae
[ 28.140255] ? trace_hardirqs_on_caller+0x2d/0x384
[ 28.140671] exit_to_usermode_loop+0xf7/0x2ae
[ 28.141058] do_int80_syscall_32+0x4e8/0x4fe
[ 28.141432] entry_INT80_32+0x2f/0x2f
[ 28.141829] EIP: 0x77f5a1b0
[ 28.142093] EFLAGS: 00000246 CPU: 0
[ 28.142401] EAX: 00000000 EBX: 0000009c ECX: 00000000 EDX: 00000000
[ 28.142943] ESI: 0000000b EDI: 004ae4c8 EBP: 7fdddce8 ESP: 7fdddb94
[ 28.143482] DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 007b
[ 28.143959] Kernel Offset: disabled

Thanks,
Fengguang
early console in setup code
Probing EDD (edd=off to disable)... ok
[ 0.000000] Linux version 4.14.0-rc1-00228-g6fc19a4 (kbuild@lkp-ivb-ep02) (gcc version 5.4.1 20160904 (Debian 5.4.1-2)) #1 PREEMPT Mon Sep 25 20:14:06 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 failed
[ 0.000000] tsc: Unable to calibrate against PIT
[ 0.000000] tsc: No reference (HPET/PMTIMER) available
[ 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 2994679870 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:6fc19a4258c555dd10e160f1fad06a0d7201844a/.vmlinuz-6fc19a4258c555dd10e160f1fad06a0d7201844a-20170925202015-17:quantal-vp-10 branch=linux-devel/devel-spot-201709232001 BOOT_IMAGE=/pkg/linux/i386-randconfig-b0-09232213/gcc-5/6fc19a4258c555dd10e160f1fad06a0d7201844a/vmlinuz-4.14.0-rc1-00228-g6fc19a4 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 : 0x83113e6a - 0x8456a280 (20825 kB)
[ 0.000000] .text : 0x81000000 - 0x83113e6a (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.003378] tsc: Detected 2693.508 MHz processor
[ 0.004093] Calibrating delay loop (skipped) preset value.. 5389.47 BogoMIPS (lpj=8978360)
[ 0.004678] pid_max: default: 4096 minimum: 301
[ 0.006699] ACPI: Core revision 20170728
[ 0.032382] ACPI: 1 ACPI AML tables successfully acquired and loaded
[ 0.033360] ACPI: setting ELCR to 0200 (from 0c00)
[ 0.034300] Security Framework initialized
[ 0.034982] LoadPin: ready to pin (currently enabled)
[ 0.035035] Smack: Initializing.
[ 0.036731] AppArmor: AppArmor disabled by boot time parameter
[ 0.037799] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.038870] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.042670] mce: CPU supports 10 MCE banks
[ 0.043457] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 0.044333] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 0.045230] CPU: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1)
[ 0.051058] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only.
[ 0.053092] Hierarchical SRCU implementation.
[ 0.056987] devtmpfs: initialized
[ 0.059840] gcov: version magic: 0x3530342a
[ 0.078931] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns
[ 0.080059] futex hash table entries: 16 (order: -3, 768 bytes)
[ 0.085129] NET: Registered protocol family 16
[ 0.093621] cpuidle: using governor ladder
[ 0.096978] ACPI: bus type PCI registered
[ 0.099035] PCI: PCI BIOS area is rw and x. Use pci=nobios if you want it NX.
[ 0.100060] PCI: PCI BIOS revision 2.10 entry at 0xfd3e3, last bus=0
[ 0.101170] PCI: Using configuration type 1 for base access
[ 0.284115] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 0.289470] ACPI: Added _OSI(Module Device)
[ 0.290162] ACPI: Added _OSI(Processor Device)
[ 0.290856] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.291593] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.344129] ACPI: Interpreter enabled
[ 0.345132] ACPI: (supports S0 S3 S4 S5)
[ 0.345869] ACPI: Using PIC for interrupt routing
[ 0.347005] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.353703] ACPI: Enabled 3 GPEs in block 00 to 0F
[ 0.504784] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.505860] acpi PNP0A03:00: _OSC: OS supports [Segments]
[ 0.506921] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[ 0.508108] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[ 0.511829] PCI host bridge to bus 0000:00
[ 0.512531] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
[ 0.513385] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
[ 0.514543] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[ 0.515705] pci_bus 0000:00: root bus resource [mem 0x18f00000-0xfebfffff window]
[ 0.516723] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.517784] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[ 0.525119] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[ 0.531512] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[ 0.560037] pci 0000:00:01.1: reg 0x20: [io 0xc200-0xc20f]
[ 0.573406] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
[ 0.574575] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
[ 0.575722] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
[ 0.576706] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
[ 0.582611] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[ 0.584305] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
[ 0.585587] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
[ 0.594108] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[ 0.600051] pci 0000:00:02.0: reg 0x10: [mem 0xfa000000-0xfaffffff pref]
[ 0.613382] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[ 0.640053] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[ 0.644288] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[ 0.650042] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[ 0.656706] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f]
[ 0.690051] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[ 0.693402] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[ 0.700031] pci 0000:00:04.0: reg 0x10: [io 0xc040-0xc07f]
[ 0.706712] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[ 0.733367] pci 0000:00:04.0: reg 0x20: [mem 0xfb000000-0xfb7fffff 64bit pref]
[ 0.750843] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000
[ 0.756700] pci 0000:00:05.0: reg 0x10: [io 0xc080-0xc0bf]
[ 0.766702] pci 0000:00:05.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff]
[ 0.789999] pci 0000:00:05.0: reg 0x20: [mem 0xfb800000-0xfbffffff 64bit pref]
[ 0.802524] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000
[ 0.809999] pci 0000:00:06.0: reg 0x10: [io 0xc0c0-0xc0ff]
[ 0.820044] pci 0000:00:06.0: reg 0x14: [mem 0xfebf3000-0xfebf3fff]
[ 0.846718] pci 0000:00:06.0: reg 0x20: [mem 0xfc000000-0xfc7fffff 64bit pref]
[ 0.857856] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000
[ 0.863391] pci 0000:00:07.0: reg 0x10: [io 0xc100-0xc13f]
[ 0.873381] pci 0000:00:07.0: reg 0x14: [mem 0xfebf4000-0xfebf4fff]
[ 0.893370] pci 0000:00:07.0: reg 0x20: [mem 0xfc800000-0xfcffffff 64bit pref]
[ 0.905468] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000
[ 0.913333] pci 0000:00:08.0: reg 0x10: [io 0xc140-0xc17f]
[ 0.923390] pci 0000:00:08.0: reg 0x14: [mem 0xfebf5000-0xfebf5fff]
[ 0.943383] pci 0000:00:08.0: reg 0x20: [mem 0xfd000000-0xfd7fffff 64bit pref]
[ 0.960052] pci 0000:00:09.0: [1af4:1001] type 00 class 0x010000
[ 0.966714] pci 0000:00:09.0: reg 0x10: [io 0xc180-0xc1bf]
[ 0.976709] pci 0000:00:09.0: reg 0x14: [mem 0xfebf6000-0xfebf6fff]
[ 0.996711] pci 0000:00:09.0: reg 0x20: [mem 0xfd800000-0xfdffffff 64bit pref]
[ 1.012194] pci 0000:00:0a.0: [1af4:1001] type 00 class 0x010000
[ 1.020054] pci 0000:00:0a.0: reg 0x10: [io 0xc1c0-0xc1ff]
[ 1.026716] pci 0000:00:0a.0: reg 0x14: [mem 0xfebf7000-0xfebf7fff]
[ 1.053379] pci 0000:00:0a.0: reg 0x20: [mem 0xfe000000-0xfe7fffff 64bit pref]
[ 1.072054] pci 0000:00:0b.0: [8086:25ab] type 00 class 0x088000
[ 1.075785] pci 0000:00:0b.0: reg 0x10: [mem 0xfebf8000-0xfebf800f]
[ 1.112359] pci_bus 0000:00: on NUMA node 0
[ 1.125475] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[ 1.129531] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[ 1.133633] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[ 1.137655] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[ 1.140072] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[ 1.147351] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[ 1.148290] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[ 1.149626] pci 0000:00:02.0: vgaarb: bridge control possible
[ 1.150035] vgaarb: loaded
[ 1.157383] SCSI subsystem initialized
[ 1.158677] media: Linux media interface: v0.10
[ 1.159824] Linux video capture interface: v2.00
[ 1.160361] pps_core: LinuxPPS API ver. 1 registered
[ 1.161122] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@xxxxxxxx>
[ 1.162559] PTP clock support registered
[ 1.166818] PCI: Using ACPI for IRQ routing
[ 1.167466] PCI: pci_cache_line_size set to 64 bytes
[ 1.168735] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[ 1.169675] e820: reserve RAM buffer [mem 0x18ed2000-0x1bffffff]
[ 1.173988] Bluetooth: Core ver 2.22
[ 1.174683] NET: Registered protocol family 31
[ 1.175371] Bluetooth: HCI device and connection manager initialized
[ 1.176365] Bluetooth: HCI socket layer initialized
[ 1.176723] Bluetooth: L2CAP socket layer initialized
[ 1.177716] NET: Registered protocol family 8
[ 1.178394] NET: Registered protocol family 20
[ 1.182004] NetLabel: Initializing
[ 1.182540] NetLabel: domain hash size = 128
[ 1.183208] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
[ 1.183639] NetLabel: unlabeled traffic allowed by default
[ 1.188482] clocksource: Switched to clocksource kvm-clock
[ 1.217140] Warning: could not register all branches stats
[ 1.218055] Warning: could not register annotated branches stats
[ 1.871154] VFS: Disk quotas dquot_6.6.0
[ 1.872470] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 1.874981] pnp: PnP ACPI init
[ 1.876644] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[ 1.878949] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[ 1.881149] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[ 1.882665] pnp 00:03: [dma 2]
[ 1.883947] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[ 1.886404] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[ 1.889252] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[ 1.891488] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[ 1.900782] pnp: PnP ACPI: found 7 devices
[ 1.943908] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[ 1.946267] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
[ 1.947281] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
[ 1.948358] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[ 1.949507] pci_bus 0000:00: resource 7 [mem 0x18f00000-0xfebfffff window]
[ 1.951067] NET: Registered protocol family 2
[ 1.954466] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[ 1.955785] TCP bind hash table entries: 4096 (order: 5, 163840 bytes)
[ 1.957775] TCP: Hash tables configured (established 4096 bind 4096)
[ 1.959167] UDP hash table entries: 256 (order: 2, 24576 bytes)
[ 1.960263] UDP-Lite hash table entries: 256 (order: 2, 24576 bytes)
[ 1.962518] NET: Registered protocol family 1
[ 1.965313] RPC: Registered named UNIX socket transport module.
[ 1.966317] RPC: Registered udp transport module.
[ 1.967048] RPC: Registered tcp transport module.
[ 1.967787] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 1.969014] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[ 1.969883] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[ 1.970838] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[ 1.972053] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[ 1.973620] PCI: CLS 0 bytes, default 64
[ 1.975937] Unpacking initramfs...
[ 5.735130] Freeing initrd memory: 24852K
[ 5.743022] apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac)
[ 5.743972] apm: overridden by ACPI.
[ 5.754211] PCLMULQDQ-NI instructions are not detected.
[ 5.755804] The force parameter has not been set to 1. The Iris poweroff handler will not be installed.
[ 5.757910] 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
[ 5.762657] spin_lock-torture: Creating torture_shuffle task
[ 5.766388] spin_lock-torture: Creating torture_stutter task
[ 5.767856] spin_lock-torture: torture_shuffle task started
[ 5.769415] spin_lock-torture: Creating lock_torture_writer task
[ 5.771064] spin_lock-torture: torture_stutter task started
[ 5.772552] spin_lock-torture: Creating lock_torture_writer task
[ 5.773800] spin_lock-torture: lock_torture_writer task started
[ 5.775500] spin_lock-torture: Creating lock_torture_stats task
[ 5.776810] spin_lock-torture: lock_torture_writer task started
[ 5.779167] spin_lock-torture: lock_torture_stats task started
[ 6.281859]
[ 6.282127] ======================================================
[ 6.283041] WARNING: possible circular locking dependency detected
[ 6.283926] 4.14.0-rc1-00228-g6fc19a4 #1 Not tainted
[ 6.284644] ------------------------------------------------------
[ 6.284853] kworker/0:1/13 is trying to acquire lock:
[ 6.284853] (ww_class_mutex){+.+.}, at: [<811506d5>] test_abba_work+0xea/0x571
[ 6.284853]
[ 6.284853] but now in release context of a crosslock acquired at the following:
[ 6.284853] ((complete)&abba.b_ready){+.+.}, at: [<83103d5c>] wait_for_completion+0x25/0x35
[ 6.284853]
[ 6.284853] which lock already depends on the new lock.
[ 6.284853]
[ 6.284853] the existing dependency chain (in reverse order) is:
[ 6.284853]
[ 6.284853] -> #1 ((complete)&abba.b_ready){+.+.}:
[ 6.284853] validate_chain+0xf47/0x1171
[ 6.284853] __lock_acquire+0xcad/0x112e
[ 6.284853] lock_acquire+0x26c/0x3a4
[ 6.284853] wait_for_common+0x69/0x419
[ 6.284853] wait_for_completion+0x25/0x35
[ 6.284853] test_abba+0x2ee/0x8cd
[ 6.284853] test_ww_mutex_init+0x19d/0x40d
[ 6.284853] do_one_initcall+0x12f/0x308
[ 6.284853] kernel_init_freeable+0x23a/0x3e8
[ 6.284853] kernel_init+0x1b/0x309
[ 6.284853] ret_from_fork+0x19/0x24
[ 6.284853]
[ 6.284853] -> #0 (ww_class_mutex){+.+.}:
[ 6.284853] __ww_mutex_lock+0x11a/0x2b19
[ 6.284853] ww_mutex_lock+0x71/0x1af
[ 6.284853] test_abba_work+0xea/0x571
[ 6.284853] process_one_work+0x88f/0x106e
[ 6.284853] worker_thread+0x7a4/0xaaa
[ 6.284853]
[ 6.284853] other info that might help us debug this:
[ 6.284853]
[ 6.284853] Possible unsafe locking scenario by crosslock:
[ 6.284853]
[ 6.284853] CPU0 CPU1
[ 6.284853] ---- ----
[ 6.284853] lock(ww_class_mutex);
[ 6.284853] lock((complete)&abba.b_ready);
[ 6.284853] lock(ww_class_mutex);
[ 6.284853] unlock((complete)&abba.b_ready);
[ 6.284853]
[ 6.284853] *** DEADLOCK ***
[ 6.284853]
[ 6.284853] 5 locks held by kworker/0:1/13:
[ 6.284853] #0: ("events"){+.+.}, at: [<810eea7c>] process_one_work+0x447/0x106e
[ 6.284853] #1: ((&abba.work)){+.+.}, at: [<810eea7c>] process_one_work+0x447/0x106e
[ 6.284853] #2: (ww_class_acquire){+.+.}, at: [<810eeec4>] process_one_work+0x88f/0x106e
[ 6.284853] #3: (ww_class_mutex){+.+.}, at: [<811506d5>] test_abba_work+0xea/0x571
[ 6.284853] #4: (&x->wait#5){....}, at: [<81133872>] complete+0x25/0xc3
[ 6.284853]
[ 6.284853] stack backtrace:
[ 6.284853] CPU: 0 PID: 13 Comm: kworker/0:1 Not tainted 4.14.0-rc1-00228-g6fc19a4 #1
[ 6.284853] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 6.284853] Workqueue: events test_abba_work
[ 6.284853] Call Trace:
[ 6.284853] dump_stack+0x40/0x5e
[ 6.284853] print_circular_bug+0x1c0/0x1da
[ 6.284853] check_prev_add+0x1b5/0xbe5
[ 6.284853] ? save_stack_trace+0x25/0x36
[ 6.284853] commit_xhlock+0x420/0x554
[ 6.284853] ? check_noncircular+0x2e/0x2e
[ 6.284853] lock_commit_crosslock+0x222/0x36b
[ 6.284853] complete+0x3d/0xc3
[ 6.284853] test_abba_work+0x103/0x571
[ 6.284853] ? process_one_work+0x88f/0x106e
[ 6.284853] process_one_work+0x88f/0x106e
[ 6.284853] worker_thread+0x7a4/0xaaa
[ 6.284853] ? rescuer_thread+0x5f2/0x5f2
[ 6.284853] kthread+0x25e/0x271
[ 6.284853] ? rescuer_thread+0x5f2/0x5f2
[ 6.284853] ? __kthread_create_on_node+0x316/0x316
[ 6.284853] ret_from_fork+0x19/0x24
[ 6.801564] tsc: Refined TSC clocksource calibration: 2693.507 MHz
[ 6.802267] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d348cd811, max_idle_ns: 440795335366 ns
[ 12.393488] torture_init_begin: Refusing rcu init: spin_lock running.
[ 12.399376] torture_init_begin: One torture test at a time!
[ 12.403790] audit: initializing netlink subsys (disabled)
[ 12.408235] audit: type=2000 audit(1506342036.537:1): state=initialized audit_enabled=0 res=1
[ 14.418230] workingset: timestamp_bits=30 max_order=17 bucket_order=0
[ 14.419991] zbud: loaded
[ 14.426222] DLM installed
[ 14.436202] NFS: Registering the id_resolver key type
[ 14.437015] Key type id_resolver registered
[ 14.437635] Key type id_legacy registered
[ 14.441976] ntfs: driver 2.1.32 [Flags: R/O DEBUG].
[ 14.442577] efs: 1.0a - http://aeschi.ch.eu.org/efs/
[ 14.443895] romfs: ROMFS MTD (C) 2007 Red Hat, Inc.
[ 14.444368] QNX4 filesystem 0.2.3 registered.
[ 14.444931] qnx6: QNX6 filesystem 1.0.0 registered.
[ 14.445523] orangefs_debugfs_init: called with debug mask: :none: :0:
[ 14.449469] orangefs_init: module version upstream loaded
[ 14.452556] JFS: nTxBlock = 2566, nTxLock = 20535
[ 14.455287] befs: version: 0.9.3
[ 14.457568] ocfs2: Registered cluster interface user
[ 14.458218] OCFS2 User DLM kernel interface loaded
[ 14.468742] ceph: loaded (mds proto 32)
[ 14.491346] NET: Registered protocol family 38
[ 14.492094] Key type asymmetric registered
[ 14.492619] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[ 14.493659] io scheduler noop registered (default)
[ 14.494158] io scheduler cfq registered
[ 14.494589] io scheduler mq-deadline registered
[ 14.495479] io scheduler bfq registered
[ 14.496027] test_string_helpers: Running tests...
[ 14.500363] test_hexdump: all 1184 tests passed
[ 14.501134] test_firmware: interface ready
[ 14.519360] test_hash: __hash_32() has no arch implementation to test.
[ 14.519993] test_hash: hash_32() has no arch implementation to test.
[ 14.520596] test_hash: hash_64() has no arch implementation to test.
[ 14.521216] test_hash: 33152 tests passed.
[ 14.521693] test_siphash: self-tests: pass
[ 14.523708] test_printf: all 260 tests passed
[ 14.775301] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 4206
[ 14.800512] test_bitmap: all 460506 tests passed
[ 14.801223] test_uuid: all 18 tests passed
[ 14.803896] crc32: CRC_LE_BITS = 32, CRC_BE BITS = 32
[ 14.804667] crc32: self tests passed, processed 225944 bytes in 295662 nsec
[ 14.806125] crc32c: CRC_LE_BITS = 32
[ 14.806650] crc32c: self tests passed, processed 225944 bytes in 142959 nsec
[ 14.841185] crc32_combine: 8373 self tests passed
[ 14.875817] crc32c_combine: 8373 self tests passed
[ 14.877025] xz_dec_test: module loaded
[ 14.877590] xz_dec_test: Create a device node with 'mknod xz_dec_test c 246 0' and write .xz files to it.
[ 14.881078] glob: 64 self-tests passed, 0 failed
[ 14.882313] rbtree testing
[ 16.311625] -> test 1 (latency of nnodes insert+delete): 38498 cycles
[ 17.634392] -> test 2 (latency of nnodes cached insert+delete): 35522 cycles
[ 17.790558] -> test 3 (latency of inorder traversal): 4176 cycles
[ 17.793961] -> test 4 (latency to fetch first node)
[ 17.794717] non-cached: 64 cycles
[ 17.797179] cached: 6 cycles
[ 17.961108] augmented rbtree testing
[ 20.091959] -> test 1 (latency of nnodes insert+delete): 57394 cycles
[ 22.536385] -> test 2 (latency of nnodes cached insert+delete): 65809 cycles
[ 22.860687] interval tree insert/remove
[ 25.460533] -> 70026 cycles
[ 25.463145] interval tree search
[ 26.966274] -> 404860 cycles (2692 results)
[ 26.968275] gpio_it87: no device
[ 26.972935] gpio-mockup: probe of gpio-mockup failed with error -22
[ 26.982601] uvesafb: failed to execute /sbin/v86d
[ 26.983337] uvesafb: make sure that the v86d helper is installed and executable
[ 26.984400] uvesafb: Getting VBE info block failed (eax=0x4f00, err=-2)
[ 26.985488] uvesafb: vbe_init() failed with -22
[ 26.986204] uvesafb: probe of uvesafb.0 failed with error -22
[ 26.987113] ipmi message handler version 39.2
[ 26.987767] ipmi device interface
[ 26.988488] IPMI System Interface driver.
[ 26.990687] ipmi_si: Unable to find any System Interface(s)
[ 26.991655] IPMI SSIF Interface driver
[ 26.994553] Copyright (C) 2004 MontaVista Software - IPMI Powerdown via sys_reboot.
[ 26.997423] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[ 26.998769] ACPI: Power Button [PWRF]
[ 27.002628] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
[ 27.003766] ACPI: Power Button [PWRF]
[ 27.011857] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[ 27.041710] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[ 27.047320] console [ttyS0] disabled
[ 0.000000] Linux version 4.14.0-rc1-00228-g6fc19a4 (kbuild@lkp-ivb-ep02) (gcc version 5.4.1 20160904 (Debian 5.4.1-2)) #1 PREEMPT Mon Sep 25 20:14:06 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 failed
[ 0.000000] tsc: Unable to calibrate against PIT
[ 0.000000] tsc: No reference (HPET/PMTIMER) available
[ 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 2994679870 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:6fc19a4258c555dd10e160f1fad06a0d7201844a/.vmlinuz-6fc19a4258c555dd10e160f1fad06a0d7201844a-20170925202015-17:quantal-vp-10 branch=linux-devel/devel-spot-201709232001 BOOT_IMAGE=/pkg/linux/i386-randconfig-b0-09232213/gcc-5/6fc19a4258c555dd10e160f1fad06a0d7201844a/vmlinuz-4.14.0-rc1-00228-g6fc19a4 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 : 0x83113e6a - 0x8456a280 (20825 kB)
[ 0.000000] .text : 0x81000000 - 0x83113e6a (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.003378] tsc: Detected 2693.508 MHz processor
[ 0.004093] Calibrating delay loop (skipped) preset value.. 5389.47 BogoMIPS (lpj=8978360)
[ 0.004678] pid_max: default: 4096 minimum: 301
[ 0.006699] ACPI: Core revision 20170728
[ 0.032382] ACPI: 1 ACPI AML tables successfully acquired and loaded
[ 0.033360] ACPI: setting ELCR to 0200 (from 0c00)
[ 0.034300] Security Framework initialized
[ 0.034982] LoadPin: ready to pin (currently enabled)
[ 0.035035] Smack: Initializing.
[ 0.036731] AppArmor: AppArmor disabled by boot time parameter
[ 0.037799] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.038870] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.042670] mce: CPU supports 10 MCE banks
[ 0.043457] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 0.044333] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 0.045230] CPU: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1)
[ 0.051058] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only.
[ 0.053092] Hierarchical SRCU implementation.
[ 0.056987] devtmpfs: initialized
[ 0.059840] gcov: version magic: 0x3530342a
[ 0.078931] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns
[ 0.080059] futex hash table entries: 16 (order: -3, 768 bytes)
[ 0.085129] NET: Registered protocol family 16
[ 0.093621] cpuidle: using governor ladder
[ 0.096978] ACPI: bus type PCI registered
[ 0.099035] PCI: PCI BIOS area is rw and x. Use pci=nobios if you want it NX.
[ 0.100060] PCI: PCI BIOS revision 2.10 entry at 0xfd3e3, last bus=0
[ 0.101170] PCI: Using configuration type 1 for base access
[ 0.284115] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 0.289470] ACPI: Added _OSI(Module Device)
[ 0.290162] ACPI: Added _OSI(Processor Device)
[ 0.290856] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.291593] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.344129] ACPI: Interpreter enabled
[ 0.345132] ACPI: (supports S0 S3 S4 S5)
[ 0.345869] ACPI: Using PIC for interrupt routing
[ 0.347005] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.353703] ACPI: Enabled 3 GPEs in block 00 to 0F
[ 0.504784] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.505860] acpi PNP0A03:00: _OSC: OS supports [Segments]
[ 0.506921] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[ 0.508108] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[ 0.511829] PCI host bridge to bus 0000:00
[ 0.512531] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
[ 0.513385] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
[ 0.514543] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[ 0.515705] pci_bus 0000:00: root bus resource [mem 0x18f00000-0xfebfffff window]
[ 0.516723] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.517784] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[ 0.525119] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[ 0.531512] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[ 0.560037] pci 0000:00:01.1: reg 0x20: [io 0xc200-0xc20f]
[ 0.573406] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
[ 0.574575] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
[ 0.575722] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
[ 0.576706] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
[ 0.582611] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[ 0.584305] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
[ 0.585587] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
[ 0.594108] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[ 0.600051] pci 0000:00:02.0: reg 0x10: [mem 0xfa000000-0xfaffffff pref]
[ 0.613382] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[ 0.640053] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[ 0.644288] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[ 0.650042] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[ 0.656706] pci 0000:00:03.0: reg 0x14: [io 0xc000-0xc03f]
[ 0.690051] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[ 0.693402] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[ 0.700031] pci 0000:00:04.0: reg 0x10: [io 0xc040-0xc07f]
[ 0.706712] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[ 0.733367] pci 0000:00:04.0: reg 0x20: [mem 0xfb000000-0xfb7fffff 64bit pref]
[ 0.750843] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000
[ 0.756700] pci 0000:00:05.0: reg 0x10: [io 0xc080-0xc0bf]
[ 0.766702] pci 0000:00:05.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff]
[ 0.789999] pci 0000:00:05.0: reg 0x20: [mem 0xfb800000-0xfbffffff 64bit pref]
[ 0.802524] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000
[ 0.809999] pci 0000:00:06.0: reg 0x10: [io 0xc0c0-0xc0ff]
[ 0.820044] pci 0000:00:06.0: reg 0x14: [mem 0xfebf3000-0xfebf3fff]
[ 0.846718] pci 0000:00:06.0: reg 0x20: [mem 0xfc000000-0xfc7fffff 64bit pref]
[ 0.857856] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000
[ 0.863391] pci 0000:00:07.0: reg 0x10: [io 0xc100-0xc13f]
[ 0.873381] pci 0000:00:07.0: reg 0x14: [mem 0xfebf4000-0xfebf4fff]
[ 0.893370] pci 0000:00:07.0: reg 0x20: [mem 0xfc800000-0xfcffffff 64bit pref]
[ 0.905468] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000
[ 0.913333] pci 0000:00:08.0: reg 0x10: [io 0xc140-0xc17f]
[ 0.923390] pci 0000:00:08.0: reg 0x14: [mem 0xfebf5000-0xfebf5fff]
[ 0.943383] pci 0000:00:08.0: reg 0x20: [mem 0xfd000000-0xfd7fffff 64bit pref]
[ 0.960052] pci 0000:00:09.0: [1af4:1001] type 00 class 0x010000
[ 0.966714] pci 0000:00:09.0: reg 0x10: [io 0xc180-0xc1bf]
[ 0.976709] pci 0000:00:09.0: reg 0x14: [mem 0xfebf6000-0xfebf6fff]
[ 0.996711] pci 0000:00:09.0: reg 0x20: [mem 0xfd800000-0xfdffffff 64bit pref]
[ 1.012194] pci 0000:00:0a.0: [1af4:1001] type 00 class 0x010000
[ 1.020054] pci 0000:00:0a.0: reg 0x10: [io 0xc1c0-0xc1ff]
[ 1.026716] pci 0000:00:0a.0: reg 0x14: [mem 0xfebf7000-0xfebf7fff]
[ 1.053379] pci 0000:00:0a.0: reg 0x20: [mem 0xfe000000-0xfe7fffff 64bit pref]
[ 1.072054] pci 0000:00:0b.0: [8086:25ab] type 00 class 0x088000
[ 1.075785] pci 0000:00:0b.0: reg 0x10: [mem 0xfebf8000-0xfebf800f]
[ 1.112359] pci_bus 0000:00: on NUMA node 0
[ 1.125475] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[ 1.129531] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[ 1.133633] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[ 1.137655] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[ 1.140072] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[ 1.147351] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[ 1.148290] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[ 1.149626] pci 0000:00:02.0: vgaarb: bridge control possible
[ 1.150035] vgaarb: loaded
[ 1.157383] SCSI subsystem initialized
[ 1.158677] media: Linux media interface: v0.10
[ 1.159824] Linux video capture interface: v2.00
[ 1.160361] pps_core: LinuxPPS API ver. 1 registered
[ 1.161122] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@xxxxxxxx>
[ 1.162559] PTP clock support registered
[ 1.166818] PCI: Using ACPI for IRQ routing
[ 1.167466] PCI: pci_cache_line_size set to 64 bytes
[ 1.168735] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[ 1.169675] e820: reserve RAM buffer [mem 0x18ed2000-0x1bffffff]
[ 1.173988] Bluetooth: Core ver 2.22
[ 1.174683] NET: Registered protocol family 31
[ 1.175371] Bluetooth: HCI device and connection manager initialized
[ 1.176365] Bluetooth: HCI socket layer initialized
[ 1.176723] Bluetooth: L2CAP socket layer initialized
[ 1.177716] NET: Registered protocol family 8
[ 1.178394] NET: Registered protocol family 20
[ 1.182004] NetLabel: Initializing
[ 1.182540] NetLabel: domain hash size = 128
[ 1.183208] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
[ 1.183639] NetLabel: unlabeled traffic allowed by default
[ 1.188482] clocksource: Switched to clocksource kvm-clock
[ 1.217140] Warning: could not register all branches stats
[ 1.218055] Warning: could not register annotated branches stats
[ 1.871154] VFS: Disk quotas dquot_6.6.0
[ 1.872470] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 1.874981] pnp: PnP ACPI init
[ 1.876644] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[ 1.878949] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[ 1.881149] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[ 1.882665] pnp 00:03: [dma 2]
[ 1.883947] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[ 1.886404] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[ 1.889252] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[ 1.891488] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[ 1.900782] pnp: PnP ACPI: found 7 devices
[ 1.943908] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[ 1.946267] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window]
[ 1.947281] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window]
[ 1.948358] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[ 1.949507] pci_bus 0000:00: resource 7 [mem 0x18f00000-0xfebfffff window]
[ 1.951067] NET: Registered protocol family 2
[ 1.954466] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[ 1.955785] TCP bind hash table entries: 4096 (order: 5, 163840 bytes)
[ 1.957775] TCP: Hash tables configured (established 4096 bind 4096)
[ 1.959167] UDP hash table entries: 256 (order: 2, 24576 bytes)
[ 1.960263] UDP-Lite hash table entries: 256 (order: 2, 24576 bytes)
[ 1.962518] NET: Registered protocol family 1
[ 1.965313] RPC: Registered named UNIX socket transport module.
[ 1.966317] RPC: Registered udp transport module.
[ 1.967048] RPC: Registered tcp transport module.
[ 1.967787] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 1.969014] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[ 1.969883] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[ 1.970838] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[ 1.972053] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[ 1.973620] PCI: CLS 0 bytes, default 64
[ 1.975937] Unpacking initramfs...
[ 5.735130] Freeing initrd memory: 24852K
[ 5.743022] apm: BIOS version 1.2 Flags 0x03 (Driver version 1.16ac)
[ 5.743972] apm: overridden by ACPI.
[ 5.754211] PCLMULQDQ-NI instructions are not detected.
[ 5.755804] The force parameter has not been set to 1. The Iris poweroff handler will not be installed.
[ 5.757910] 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
[ 5.762657] spin_lock-torture: Creating torture_shuffle task
[ 5.766388] spin_lock-torture: Creating torture_stutter task
[ 5.767856] spin_lock-torture: torture_shuffle task started
[ 5.769415] spin_lock-torture: Creating lock_torture_writer task
[ 5.771064] spin_lock-torture: torture_stutter task started
[ 5.772552] spin_lock-torture: Creating lock_torture_writer task
[ 5.773800] spin_lock-torture: lock_torture_writer task started
[ 5.775500] spin_lock-torture: Creating lock_torture_stats task
[ 5.776810] spin_lock-torture: lock_torture_writer task started
[ 5.779167] spin_lock-torture: lock_torture_stats task started
[ 6.281859]
[ 6.282127] ======================================================
[ 6.283041] WARNING: possible circular locking dependency detected
[ 6.283926] 4.14.0-rc1-00228-g6fc19a4 #1 Not tainted
[ 6.284644] ------------------------------------------------------
[ 6.284853] kworker/0:1/13 is trying to acquire lock:
[ 6.284853] (ww_class_mutex){+.+.}, at: [<811506d5>] test_abba_work+0xea/0x571
[ 6.284853]
[ 6.284853] but now in release context of a crosslock acquired at the following:
[ 6.284853] ((complete)&abba.b_ready){+.+.}, at: [<83103d5c>] wait_for_completion+0x25/0x35
[ 6.284853]
[ 6.284853] which lock already depends on the new lock.
[ 6.284853]
[ 6.284853] the existing dependency chain (in reverse order) is:
[ 6.284853]
[ 6.284853] -> #1 ((complete)&abba.b_ready){+.+.}:
[ 6.284853] validate_chain+0xf47/0x1171
[ 6.284853] __lock_acquire+0xcad/0x112e
[ 6.284853] lock_acquire+0x26c/0x3a4
[ 6.284853] wait_for_common+0x69/0x419
[ 6.284853] wait_for_completion+0x25/0x35
[ 6.284853] test_abba+0x2ee/0x8cd
[ 6.284853] test_ww_mutex_init+0x19d/0x40d
[ 6.284853] do_one_initcall+0x12f/0x308
[ 6.284853] kernel_init_freeable+0x23a/0x3e8
[ 6.284853] kernel_init+0x1b/0x309
[ 6.284853] ret_from_fork+0x19/0x24
[ 6.284853]
[ 6.284853] -> #0 (ww_class_mutex){+.+.}:
[ 6.284853] __ww_mutex_lock+0x11a/0x2b19
[ 6.284853] ww_mutex_lock+0x71/0x1af
[ 6.284853] test_abba_work+0xea/0x571
[ 6.284853] process_one_work+0x88f/0x106e
[ 6.284853] worker_thread+0x7a4/0xaaa
[ 6.284853]
[ 6.284853] other info that might help us debug this:
[ 6.284853]
[ 6.284853] Possible unsafe locking scenario by crosslock:
[ 6.284853]
[ 6.284853] CPU0 CPU1
[ 6.284853] ---- ----
[ 6.284853] lock(ww_class_mutex);
[ 6.284853] lock((complete)&abba.b_ready);
[ 6.284853] lock(ww_class_mutex);
[ 6.284853] unlock((complete)&abba.b_ready);
[ 6.284853]
[ 6.284853] *** DEADLOCK ***
[ 6.284853]
[ 6.284853] 5 locks held by kworker/0:1/13:
[ 6.284853] #0: ("events"){+.+.}, at: [<810eea7c>] process_one_work+0x447/0x106e
[ 6.284853] #1: ((&abba.work)){+.+.}, at: [<810eea7c>] process_one_work+0x447/0x106e
[ 6.284853] #2: (ww_class_acquire){+.+.}, at: [<810eeec4>] process_one_work+0x88f/0x106e
[ 6.284853] #3: (ww_class_mutex){+.+.}, at: [<811506d5>] test_abba_work+0xea/0x571
[ 6.284853] #4: (&x->wait#5){....}, at: [<81133872>] complete+0x25/0xc3
[ 6.284853]
[ 6.284853] stack backtrace:
[ 6.284853] CPU: 0 PID: 13 Comm: kworker/0:1 Not tainted 4.14.0-rc1-00228-g6fc19a4 #1
[ 6.284853] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 6.284853] Workqueue: events test_abba_work
[ 6.284853] Call Trace:
[ 6.284853] dump_stack+0x40/0x5e
[ 6.284853] print_circular_bug+0x1c0/0x1da
[ 6.284853] check_prev_add+0x1b5/0xbe5
[ 6.284853] ? save_stack_trace+0x25/0x36
[ 6.284853] commit_xhlock+0x420/0x554
[ 6.284853] ? check_noncircular+0x2e/0x2e
[ 6.284853] lock_commit_crosslock+0x222/0x36b
[ 6.284853] complete+0x3d/0xc3
[ 6.284853] test_abba_work+0x103/0x571
[ 6.284853] ? process_one_work+0x88f/0x106e
[ 6.284853] process_one_work+0x88f/0x106e
[ 6.284853] worker_thread+0x7a4/0xaaa
[ 6.284853] ? rescuer_thread+0x5f2/0x5f2
[ 6.284853] kthread+0x25e/0x271
[ 6.284853] ? rescuer_thread+0x5f2/0x5f2
[ 6.284853] ? __kthread_create_on_node+0x316/0x316
[ 6.284853] ret_from_fork+0x19/0x24
[ 6.801564] tsc: Refined TSC clocksource calibration: 2693.507 MHz
[ 6.802267] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x26d348cd811, max_idle_ns: 440795335366 ns
[ 12.393488] torture_init_begin: Refusing rcu init: spin_lock running.
[ 12.399376] torture_init_begin: One torture test at a time!
[ 12.403790] audit: initializing netlink subsys (disabled)
[ 12.408235] audit: type=2000 audit(1506342036.537:1): state=initialized audit_enabled=0 res=1
[ 14.418230] workingset: timestamp_bits=30 max_order=17 bucket_order=0
[ 14.419991] zbud: loaded
[ 14.426222] DLM installed
[ 14.436202] NFS: Registering the id_resolver key type
[ 14.437015] Key type id_resolver registered
[ 14.437635] Key type id_legacy registered
[ 14.441976] ntfs: driver 2.1.32 [Flags: R/O DEBUG].
[ 14.442577] efs: 1.0a - http://aeschi.ch.eu.org/efs/
[ 14.443895] romfs: ROMFS MTD (C) 2007 Red Hat, Inc.
[ 14.444368] QNX4 filesystem 0.2.3 registered.
[ 14.444931] qnx6: QNX6 filesystem 1.0.0 registered.
[ 14.445523] orangefs_debugfs_init: called with debug mask: :none: :0:
[ 14.449469] orangefs_init: module version upstream loaded
[ 14.452556] JFS: nTxBlock = 2566, nTxLock = 20535
[ 14.455287] befs: version: 0.9.3
[ 14.457568] ocfs2: Registered cluster interface user
[ 14.458218] OCFS2 User DLM kernel interface loaded
[ 14.468742] ceph: loaded (mds proto 32)
[ 14.491346] NET: Registered protocol family 38
[ 14.492094] Key type asymmetric registered
[ 14.492619] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247)
[ 14.493659] io scheduler noop registered (default)
[ 14.494158] io scheduler cfq registered
[ 14.494589] io scheduler mq-deadline registered
[ 14.495479] io scheduler bfq registered
[ 14.496027] test_string_helpers: Running tests...
[ 14.500363] test_hexdump: all 1184 tests passed
[ 14.501134] test_firmware: interface ready
[ 14.519360] test_hash: __hash_32() has no arch implementation to test.
[ 14.519993] test_hash: hash_32() has no arch implementation to test.
[ 14.520596] test_hash: hash_64() has no arch implementation to test.
[ 14.521216] test_hash: 33152 tests passed.
[ 14.521693] test_siphash: self-tests: pass
[ 14.523708] test_printf: all 260 tests passed
[ 14.775301] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 4206
[ 14.800512] test_bitmap: all 460506 tests passed
[ 14.801223] test_uuid: all 18 tests passed
[ 14.803896] crc32: CRC_LE_BITS = 32, CRC_BE BITS = 32
[ 14.804667] crc32: self tests passed, processed 225944 bytes in 295662 nsec
[ 14.806125] crc32c: CRC_LE_BITS = 32
[ 14.806650] crc32c: self tests passed, processed 225944 bytes in 142959 nsec
[ 14.841185] crc32_combine: 8373 self tests passed
[ 14.875817] crc32c_combine: 8373 self tests passed
[ 14.877025] xz_dec_test: module loaded
[ 14.877590] xz_dec_test: Create a device node with 'mknod xz_dec_test c 246 0' and write .xz files to it.
[ 14.881078] glob: 64 self-tests passed, 0 failed
[ 14.882313] rbtree testing
[ 16.311625] -> test 1 (latency of nnodes insert+delete): 38498 cycles
[ 17.634392] -> test 2 (latency of nnodes cached insert+delete): 35522 cycles
[ 17.790558] -> test 3 (latency of inorder traversal): 4176 cycles
[ 17.793961] -> test 4 (latency to fetch first node)
[ 17.794717] non-cached: 64 cycles
[ 17.797179] cached: 6 cycles
[ 17.961108] augmented rbtree testing
[ 20.091959] -> test 1 (latency of nnodes insert+delete): 57394 cycles
[ 22.536385] -> test 2 (latency of nnodes cached insert+delete): 65809 cycles
[ 22.860687] interval tree insert/remove
[ 25.460533] -> 70026 cycles
[ 25.463145] interval tree search
[ 26.966274] -> 404860 cycles (2692 results)
[ 26.968275] gpio_it87: no device
[ 26.972935] gpio-mockup: probe of gpio-mockup failed with error -22
[ 26.982601] uvesafb: failed to execute /sbin/v86d
[ 26.983337] uvesafb: make sure that the v86d helper is installed and executable
[ 26.984400] uvesafb: Getting VBE info block failed (eax=0x4f00, err=-2)
[ 26.985488] uvesafb: vbe_init() failed with -22
[ 26.986204] uvesafb: probe of uvesafb.0 failed with error -22
[ 26.987113] ipmi message handler version 39.2
[ 26.987767] ipmi device interface
[ 26.988488] IPMI System Interface driver.
[ 26.990687] ipmi_si: Unable to find any System Interface(s)
[ 26.991655] IPMI SSIF Interface driver
[ 26.994553] Copyright (C) 2004 MontaVista Software - IPMI Powerdown via sys_reboot.
[ 26.997423] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[ 26.998769] ACPI: Power Button [PWRF]
[ 27.002628] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
[ 27.003766] ACPI: Power Button [PWRF]
[ 27.011857] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[ 27.041710] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[ 27.047320] console [ttyS0] disabled
[ 27.076003] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[ 27.541499] console [ttyS0] enabled
[ 27.567602] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[ 27.593924] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[ 27.610152] lp: driver loaded but no devices found
[ 27.611384] Non-volatile memory driver v1.3
[ 27.611889] nsc_gpio initializing
[ 27.612214] telclk_interrupt = 0xf non-mcpbl0010 hw.
[ 27.612717] smapi::smapi_init, ERROR invalid usSmapiID
[ 27.613207] mwave: tp3780i::tp3780I_InitializeBoardData: Error: SMAPI is not available on this machine
[ 27.614070] mwave: mwavedd::mwave_init: Error: Failed to initialize board data
[ 27.614754] mwave: mwavedd::mwave_init: Error: Failed to initialize
[ 27.615377] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, margin is 60 seconds).
[ 27.622540] dummy-irq: no IRQ given. Use irq=N
[ 27.623104] lkdtm: No crash points registered, enable through debugfs
[ 27.624570] Silicon Labs C2 port support v. 0.51.0 - (C) 2007 Rodolfo Giometti
[ 27.629230] Uniform Multi-Platform E-IDE driver
[ 27.631012] ide_generic: please use "probe_mask=0x3f" module parameter for probing all legacy ISA IDE ports
[ 27.631947] ide-gd driver 1.18
[ 27.632761] Loading iSCSI transport class v2.0-870.
[ 27.634756] rdac: device handler registered
[ 27.635504] hp_sw: device handler registered
[ 27.636029] emc: device handler registered
[ 27.636499] alua: device handler registered
[ 27.636950] osst :I: Tape driver with OnStream support version 0.99.4
[ 27.636950] osst :I: $Id: osst.c,v 1.73 2005/01/01 21:13:34 wriede Exp $
[ 27.639144] SCSI Media Changer driver v0.25
[ 27.643456] Rounding down aligned max_sectors from 4294967295 to 4294967288
[ 27.646879] mtdoops: mtd device (mtddev=name/number) must be supplied
[ 27.647499] L440GX flash mapping: failed to find PIIX4 ISA bridge, cannot continue
[ 27.648301] SBC-GXx flash: IO:0x258-0x259 MEM:0xdc000-0xdffff
[ 27.682380] No valid DiskOnChip devices found
[ 27.683139] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0
[ 27.684439] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0
[ 27.685830] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0
[ 27.687143] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0
[ 27.688511] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0
[ 27.689833] [nandsim] warning: read_byte: unexpected data output cycle, state is STATE_READY return 0x0
[ 27.691140] nand: device found, Manufacturer ID: 0x98, Chip ID: 0x39
[ 27.692111] nand: Toshiba NAND 128MiB 1,8V 8-bit
[ 27.692773] nand: 128 MiB, SLC, erase size: 16 KiB, page size: 512, OOB size: 16
[ 27.693833] flash size: 128 MiB
[ 27.694295] page size: 512 bytes
[ 27.694752] OOB area size: 16 bytes
[ 27.695297] sector size: 16 KiB
[ 27.695764] pages number: 262144
[ 27.696230] pages per sector: 32
[ 27.696689] bus width: 8
[ 27.697062] bits in sector size: 14
[ 27.697554] bits in page size: 9
[ 27.698019] bits in OOB size: 4
[ 27.698507] flash size with OOB: 135168 KiB
[ 27.699119] page address bytes: 4
[ 27.699587] sector address bytes: 3
[ 27.700086] options: 0x42
[ 27.701819] Scanning device for bad blocks
[ 27.768913] ftl_cs: FTL header not found.
[ 27.774747] Creating 1 MTD partitions on "NAND 128MiB 1,8V 8-bit":
[ 27.775681] 0x000000000000-0x000008000000 : "NAND simulator partition 0"
[ 27.793198] ftl_cs: FTL header not found.
[ 27.802564] ipddp.c:v0.01 8/28/97 Bradford W. Johnson <johns393@xxxxxxxxxxxxxxxxx>
[ 27.808625] ipddp0: Appletalk-IP Decap. mode by Jay Schulist <jschlst@xxxxxxxxx>
[ 27.809679] vcan: Virtual CAN interface driver
[ 27.810314] vxcan: Virtual CAN Tunnel driver
[ 27.810923] slcan: serial line CAN interface driver
[ 27.811678] slcan: 10 dynamic interface channels.
[ 27.812474] panel: panel driver not yet registered
[ 27.814859] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[ 27.817005] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 27.819364] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 27.823783] evbug: Connected device: input1 (Power Button at LNXPWRBN/button/input0)
[ 27.828539] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2
[ 27.838221] evbug: Connected device: input2 (AT Translated Set 2 keyboard at isa0060/serio0/input0)
[ 27.845877] evbug: Disconnected device: input2
[ 27.850657] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3
[ 27.853616] apanel: Fujitsu BIOS signature 'FJKEYINF' not found...
[ 27.860234] wistron_btns: System unknown
[ 27.862405] evbug: Connected device: input3 (AT Translated Set 2 keyboard at isa0060/serio0/input0)
[ 27.869183] lirc_dev: IR Remote Control driver registered, major 240
[ 27.870086] IR NEC protocol handler initialized
[ 27.870724] IR RC6 protocol handler initialized
[ 27.871369] IR JVC protocol handler initialized
[ 27.872078] IR SANYO protocol handler initialized
[ 27.872752] IR MCE Keyboard/mouse protocol handler initialized
[ 27.873570] IR XMP protocol handler initialized
[ 27.874692] pps pps0: new PPS source ktimer
[ 27.875393] pps pps0: ktimer PPS source registered
[ 27.876089] pps_ldisc: PPS line discipline registered
[ 27.876792] pps_parport: parallel port PPS client
[ 27.877589] Driver for 1-wire Dallas network protocol.
[ 27.880038] w1_f0d_init()
[ 27.882077] __power_supply_register: Expected proper parent device for 'test_ac'
[ 27.884707] __power_supply_register: Expected proper parent device for 'test_battery'
[ 27.889324] (NULL device *): hwmon_device_register() is deprecated. Please convert the driver to use hwmon_device_register_with_info().
[ 27.891183] __power_supply_register: Expected proper parent device for 'test_usb'
[ 27.904448] applesmc: supported laptop not found!
[ 27.905247] applesmc: driver init failed (ret=-19)!
[ 27.907686] f71882fg: Not a Fintek device
[ 27.908349] f71882fg: Not a Fintek device
[ 27.920363] intel_powerclamp: CPU does not support MWAIT
[ 27.922081] Bluetooth: HCI UART driver ver 2.3
[ 27.923477] Bluetooth: HCI UART protocol H4 registered
[ 27.924203] Bluetooth: HCI UART protocol BCSP registered
[ 27.925124] Bluetooth: HCI UART protocol LL registered
[ 27.925927] Bluetooth: HCI UART protocol Intel registered
[ 27.926681] Bluetooth: HCI UART protocol Marvell registered
[ 27.929056] wbsd: Winbond W83L51xD SD/MMC card interface driver
[ 27.929900] wbsd: Copyright(c) Pierre Ossman
[ 27.936479] ledtrig-cpu: registered to indicate activity on CPUs
[ 27.942595] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2)
[ 27.945335] dcdbas dcdbas: Dell Systems Management Base Driver (version 5.6.0-3.2)
[ 27.946504] hidraw: raw HID events driver (C) Jiri Kosina
[ 27.962854] hdaps: supported laptop not found!
[ 27.963492] hdaps: driver init failed (ret=-19)!
[ 28.001021] fake-fmc-carrier: mezzanine 0
[ 28.001662] Manufacturer: fake-vendor
[ 28.002282] Product name: fake-design-for-testing
[ 28.003881] fmc fake-design-for-testing-f001: Driver has no ID: matches all
[ 28.004965] fmc_trivial: probe of fake-design-for-testing-f001 failed with error -95
[ 28.006141] fmc fake-design-for-testing-f001: Driver has no ID: matches all
[ 28.007137] fmc_write_eeprom fake-design-for-testing-f001: fmc_write_eeprom: no busid passed, refusing all cards
[ 28.008676] fmc fake-design-for-testing-f001: Driver has no ID: matches all
[ 28.011354] fmc_chardev fake-design-for-testing-f001: Created misc device "fake-design-for-testing-f001"
[ 28.013436] fmc_chardev fake-design-for-testing-f001: Created misc device "fake-design-for-testing-f001"
[ 28.023786] NET: Registered protocol family 26
[ 28.029514] u32 classifier
[ 28.029950] Performance counters on
[ 28.030974] ipip: IPv4 and MPLS over IPv4 tunneling driver
[ 28.036225] Initializing XFRM netlink socket
[ 28.037121] NET: Registered protocol family 17
[ 28.037818] NET: Registered protocol family 15
[ 28.038609] NET: Registered protocol family 5
[ 28.039336] NET: Registered protocol family 9
[ 28.041094] X25: Linux Version 0.2
[ 28.041671] can: controller area network core (rev 20170425 abi 9)
[ 28.042665] NET: Registered protocol family 29
[ 28.043355] can: broadcast manager protocol (rev 20170425 t)
[ 28.046284] NET: Registered protocol family 33
[ 28.046977] Key type rxrpc registered
[ 28.047523] Key type rxrpc_s registered
[ 28.048452] l2tp_core: L2TP core driver, V2.0
[ 28.049143] l2tp_ip: L2TP IP encapsulation support (L2TPv3)
[ 28.050004] l2tp_netlink: L2TP netlink interface
[ 28.050983] l2tp_eth: L2TP ethernet pseudowire support (L2TPv3)
[ 28.052941] l2tp_debugfs: L2TP debugfs support
[ 28.053604] NET4: DECnet for Linux: V.2.5.68s (C) 1995-2003 Linux DECnet Project Team
[ 28.055032] DECnet: Routing cache hash table of 512 buckets, 20Kbytes
[ 28.056021] NET: Registered protocol family 12
[ 28.058119] DCCP: Activated CCID 2 (TCP-like)
[ 28.059051] sctp: Hash tables configured (bind 64/102)
[ 28.060034] tipc: Activated (version 2.0.0)
[ 28.060752] NET: Registered protocol family 30
[ 28.062686] tipc: Started in single node mode
[ 28.064177] 9pnet: Installing 9P2000 support
[ 28.064580] Key type dns_resolver registered
[ 28.065220] Key type ceph registered
[ 28.065660] libceph: loaded (mon/osd proto 15/24)
[ 28.066322] batman_adv: B.A.T.M.A.N. advanced 2017.3 (compatibility version 15) loaded
[ 28.067059] openvswitch: Open vSwitch switching datapath
[ 28.067647] mpls_gso: MPLS GSO support
[ 28.073171] sched_clock: Marking stable (28071481801, 0)->(28917159796, -845677995)
[ 28.076445] kAFS: Red Hat AFS client v0.1 registering.
[ 28.080210] Key type trusted registered
[ 28.080928] Key type encrypted registered
[ 28.086808] BIOS EDD facility v0.16 2004-Jun-25, 6 devices found
[ 28.093528] Freeing unused kernel memory: 1656K
[ 28.094182] Write protecting the kernel text: 33872k
[ 28.094922] Write protecting the kernel read-only data: 12160k
[ 28.095441] NX-protecting the kernel data: 23472k
[ 28.130213] init[1] bad frame in sigreturn frame:7fdddcec ip:77f5a1b2 sp:7fdde254 orax:ffffffff in ld-2.15.so[77f59000+20000]
[ 28.131803] init[1] bad frame in sigreturn frame:7fddd62c ip:77f5a1b0 sp:7fdddb94 orax:ffffffff in ld-2.15.so[77f59000+20000]
[ 28.133169] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b
[ 28.133169]
[ 28.133979] CPU: 0 PID: 1 Comm: init Not tainted 4.14.0-rc1-00228-g6fc19a4 #1
[ 28.134601] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.9.3-20161025_171302-gandalf 04/01/2014
[ 28.135659] Call Trace:
[ 28.136070] dump_stack+0x40/0x5e
[ 28.136594] panic+0x1c5/0x58c
[ 28.137072] forget_original_parent+0x1ee/0x843
[ 28.137514] do_exit+0x1087/0x17c6
[ 28.137813] do_group_exit+0x1d1/0x1d1
[ 28.138220] get_signal+0x1294/0x12ca
[ 28.138546] do_signal+0x2c/0x55b
[ 28.138842] ? force_sig_info+0x1bd/0x1d5
[ 28.139219] ? force_sig+0x22/0x32
[ 28.139519] ? signal_fault+0x14b/0x161
[ 28.139853] ? exit_to_usermode_loop+0x2f/0x2ae
[ 28.140255] ? trace_hardirqs_on_caller+0x2d/0x384
[ 28.140671] exit_to_usermode_loop+0xf7/0x2ae
[ 28.141058] do_int80_syscall_32+0x4e8/0x4fe
[ 28.141432] entry_INT80_32+0x2f/0x2f
[ 28.141829] EIP: 0x77f5a1b0
[ 28.142093] EFLAGS: 00000246 CPU: 0
[ 28.142401] EAX: 00000000 EBX: 0000009c ECX: 00000000 EDX: 00000000
[ 28.142943] ESI: 0000000b EDI: 004ae4c8 EBP: 7fdddce8 ESP: 7fdddb94
[ 28.143482] DS: 007b ES: 007b FS: 0000 GS: 0000 SS: 007b
[ 28.143959] Kernel Offset: disabled

Elapsed time: 40

kvm=(
qemu-system-x86_64
-enable-kvm
-cpu kvm64
-kernel /pkg/linux/i386-randconfig-b0-09232213/gcc-5/6fc19a4258c555dd10e160f1fad06a0d7201844a/vmlinuz-4.14.0-rc1-00228-g6fc19a4
-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/sdc1/disk0-quantal-vp-10,media=disk,if=virtio
-drive file=/fs/sdc1/disk1-quantal-vp-10,media=disk,if=virtio
-drive file=/fs/sdc1/disk2-quantal-vp-10,media=disk,if=virtio
-drive file=/fs/sdc1/disk3-quantal-vp-10,media=disk,if=virtio
-drive file=/fs/sdc1/disk4-quantal-vp-10,media=disk,if=virtio
-drive file=/fs/sdc1/disk5-quantal-vp-10,media=disk,if=virtio
-drive file=/fs/sdc1/disk6-quantal-vp-10,media=disk,if=virtio
-pidfile /dev/shm/kboot/pid-quantal-vp-10
-serial file:/dev/shm/kboot/quantal-vp-10/serial
-serial file:/dev/shm/kboot/quantal-vp-10/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:6fc19a4258c555dd10e160f1fad06a0d7201844a/.vmlinuz-6fc19a4258c555dd10e160f1fad06a0d7201844a-20170925202015-17:quantal-vp-10
branch=linux-devel/devel-spot-201709232001
BOOT_IMAGE=/pkg/linux/i386-randconfig-b0-09232213/gcc-5/6fc19a4258c555dd10e160f1fad06a0d7201844a/vmlinuz-4.14.0-rc1-00228-g6fc19a4
drbd.minor_count=8
)

"${kvm[@]}" -append "${append[*]}"
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 <giometti@xxxxxxxx>
[ 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 <giometti@xxxxxxxx>
[ 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 <johns393@xxxxxxxxxxxxxxxxx>
[ 28.957908] ipddp0: Appletalk-IP Decap. mode by Jay Schulist <jschlst@xxxxxxxxx>
[ 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[*]}"