On Mon, Oct 09, 2017 at 08:26:05AM -0500, Josh Poimboeuf wrote:
On Mon, Oct 09, 2017 at 08:55:04PM +0800, Fengguang Wu wrote:
On Mon, Oct 09, 2017 at 08:21:13PM +0800, Fengguang Wu wrote:
> On Mon, Oct 09, 2017 at 12:50:55PM +0200, Peter Zijlstra wrote:
> > > Fengguang, if you're still listening, could you please rerun the tests
> > > on top of ce07a9415f26, with the attached patches also applied?
> >
> > Ping!? it would be very good to get feedback on this asap.
>
> Sorry for the delay!
>
> > > From e7840ad76515f0b5061fcdd098b57b7c01b61482 Mon Sep 17 00:00:00 2001
> > > Message-Id: 
<e7840ad76515f0b5061fcdd098b57b7c01b61482.1507215196.git.jpoim...@redhat.com>
> > > From: Josh Poimboeuf <jpoim...@redhat.com>
> > > Date: Thu, 5 Oct 2017 09:43:59 -0500
> > > Subject: [PATCH 1/2] unwinder fixes
> > >
> > > ---
> > >  arch/x86/kernel/unwind_frame.c | 33 ++++++++++++++++++++++++++++++---
>
> I just test 316 boots and see 7 WARNINGs:
>
> [  404.948035] WARNING: kernel stack frame pointer at c6ea3ecd in init:212 
has bad value   (null)
> [  298.118383] WARNING: kernel stack frame pointer at cde07dad in init:1 has 
bad value bc000000
> [  112.848677] WARNING: kernel stack frame pointer at cde07dbd in swapper/0:1 
has bad value c2000000
> [  127.942417] WARNING: kernel stack frame pointer at cf95de71 in 
rb_producer:50 has bad value 03cf95de
> [    4.736938] WARNING: kernel stack frame pointer at bf643d59 in 
kworker/0:1:15 has bad value b5000000
> [  308.260066] WARNING: kernel stack frame pointer at bde07da5 in udevd:155 
has bad value b5bfa17b
>
> [  277.473596] WARNING: CPU: 0 PID: 520 at kernel/locking/lockdep.c:3841 
check_flags+0x119/0x1b0

The unwinder patch I sent had a few bugs: it broke frame pointer
encoding (causing the '?' entries on the lockdep stack trace) and it
didn't disable the frame pointer warnings.  Here's the fixed version.

Fengguang, can you do a round of tests with this patch and the lockdep
patch I sent before?  Thanks!

It works! I tried 500 boots and only find 1 occurrence of this error,
which looks irrelevant to the current issue.

[  187.855027] init: plymouth-splash main process (418) terminated with status 1
[  187.953296] init: networking main process (419) terminated with status 1
[  191.697721] ------------[ cut here ]------------
[  191.699318] WARNING: CPU: 0 PID: 424 at kernel/locking/lockdep.c:3928 
check_flags+0x119/0x1b0
[  191.700967] CPU: 0 PID: 424 Comm: trinity-main Not tainted 
4.14.0-rc3-00002-gc394639 #1
[  191.702200] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 
1.10.2-1 04/01/2014
[  191.703476] task: c82fec80 task.stack: c8bbe000
[  191.704194] EIP: check_flags+0x119/0x1b0
[  191.704809] EFLAGS: 00010086 CPU: 0
[  191.705380] EAX: 0000002e EBX: c82fec80 ECX: 00000107 EDX: b8afe274
[  191.716483] ESI: c8003400 EDI: 00000000 EBP: c6de5c5c ESP: c6de5c54
[  191.717457]  DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068
[  191.718350] CR0: 80050033 CR2: 0858f014 CR3: 18035000 CR4: 00000690
[  191.719405] Call Trace:
[  191.719823]  <SOFTIRQ>
[  191.720241]  lock_acquire+0x3d/0x230
[  191.720819]  ? perf_event_output_forward+0x14/0x180
[  191.721601]  ? __rcu_read_lock+0x3/0x20
[  191.722218]  perf_event_output_forward+0x41/0x180
[  191.722958]  ? perf_prepare_sample+0x830/0x830
[  191.723657]  ? __perf_event_account_interrupt+0x215/0x240
[  191.724508]  ? perf_prepare_sample+0x830/0x830
[  191.725213]  __perf_event_overflow+0x98/0x150
[  191.725898]  perf_swevent_overflow+0x9e/0xe0
[  191.736679]  perf_swevent_event+0x153/0x1a0
[  191.737345]  perf_tp_event+0x110/0x440
[  191.737943]  ? check_preemption_disabled+0x3d/0x1a0
[  191.738759]  ? check_preemption_disabled+0x3d/0x1a0
[  191.739568]  ? debug_smp_processor_id+0x12/0x20
[  191.740332]  ? perf_trace_buf_alloc+0xf9/0x1c0
[  191.741107]  perf_ftrace_function_call+0xe0/0xf0
[  191.741866]  ? __local_bh_enable+0x99/0xa0
[  191.742586]  ? ftrace_ops_no_ops+0x334/0x380
[  191.743263]  ftrace_ops_no_ops+0x334/0x380
[  191.743924]  ? check_preemption_disabled+0x3d/0x1a0
[  191.744695]  ? __local_bh_enable+0x99/0xa0
[  191.745350]  ? preempt_count_sub+0x8/0x2e0
[  191.746000]  ftrace_stub+0x14/0x1c
[  191.756703]  ? preempt_count_sub+0xd/0x2e0
[  191.757404]  ? trace_softirqs_on+0xf2/0x150
[  191.758079]  __local_bh_enable+0x99/0xa0
[  191.758786]  __do_softirq+0x6a5/0x9c0
[  191.759440]  ? __irqentry_text_end+0x6/0x6
[  191.760096]  do_softirq_own_stack+0x30/0x40
[  191.760788]  </SOFTIRQ>
[  191.761230]  irq_exit+0x56/0xd0
[  191.761796]  smp_apic_timer_interrupt+0x48d/0x6f0
[  191.762571]  apic_timer_interrupt+0x3a/0x40
[  191.763298] EIP: lock_acquire+0x1d6/0x230
[  191.763933] EFLAGS: 00000246 CPU: 0
[  191.764548] EAX: 00000246 EBX: c82fec80 ECX: 6b96419b EDX: 00000000
[  191.765567] ESI: 00000246 EDI: 00000000 EBP: c8bbfe64 ESP: c8bbfe30
[  191.776469]  DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068
[  191.777395]  ? cgroup1_procs_write+0xb/0x20
[  191.778068]  ? handle_pte_fault+0x55f/0x1cc0
[  191.778837]  _raw_spin_lock+0x42/0x50
[  191.779447]  ? handle_pte_fault+0x55f/0x1cc0
[  191.780119]  handle_pte_fault+0x55f/0x1cc0
[  191.780849]  handle_mm_fault+0x531/0x700
[  191.781504]  ? handle_mm_fault+0x72/0x700
[  191.782194]  __do_page_fault+0xa8a/0xbd0
[  191.782855]  do_page_fault+0x2cc/0x422
[  191.783471]  ? kvm_read_and_reset_pf_reason+0x70/0x70
[  191.784293]  do_async_page_fault+0x26/0x60
[  191.796340]  common_exception+0x3d/0x42
[  191.796973] EIP: 0xa7de393e
[  191.797401] EFLAGS: 00010206 CPU: 0
[  191.797924] EAX: 00000000 EBX: a7f0eff4 ECX: 00001ff1 EDX: 0858f010
[  191.798902] ESI: 0858d008 EDI: 00002009 EBP: 00000004 ESP: afe7c120
[  191.799828]  DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 007b
[  191.800679]  ? kvm_read_and_reset_pf_reason+0x70/0x70
[  191.801453] Code: 00 85 c0 74 75 e8 88 8d 2f 00 85 c0 74 6c 83 3d cc c1 57 ba 00 
75 63 c7 44 24 04 34 da 92 b9 c7 04 24 0a 93 91 b9 e8 ec 3b 01 00 <0f> ff eb 4b 
8d 76 00 8b 0d 88 2a 54 ba 85 c9 75 3e 64 a1 4c 68
[  191.804438] ---[ end trace 70000c51373576aa ]---
[  191.805188] irq event stamp: 362178
[  191.805708] hardirqs last  enabled at (362176): [<b9490faa>] 
restore_all+0xf/0x25
[  191.816989] hardirqs last disabled at (362177): [<b9496420>] 
__do_softirq+0xf0/0x9c0
[  191.818122] softirqs last  enabled at (362178): [<b94969d5>] 
__do_softirq+0x6a5/0x9c0
[  191.819324] softirqs last disabled at (362171): [<b8a10ce0>] 
do_softirq_own_stack+0x30/0x40
[  196.174500] init: tty4 main process ended, respawning
[  196.249472] init: tty5 main process (375) terminated with status 1
[  196.251170] init: tty5 main process ended, respawning
[  196.270393] init: tty2 main process (377) terminated with status 1
[  196.272059] init: tty2 main process ended, respawning
[  196.428413] init: tty3 main process ended, respawning
[  196.430660] init: tty6 main process (379) terminated with status 1
[  196.437890] init: tty6 main process ended, respawning
[init] Using pid_max = 32768
[init] Kernel was tainted on startup. Will ignore flags that are already set.
[init] Started watchdog process, PID is 430
[main] Main thread is alive.
[main] Setsockopt(1 a 80d3000 7e) on fd 8 [1:1:1]
[main] Setsockopt(1 a 80d3000 13) on fd 11 [1:5:1]

Thanks,
Fengguang
early console in setup code
[    0.000000] Linux version 4.14.0-rc3-00002-gc394639 (kbuild@lkp-snb01) (gcc 
version 4.8.4 (Debian 4.8.4-1)) #1 SMP PREEMPT Mon Oct 9 21:50:49 CST 2017
[    0.000000] KERNEL supported cpus:
[    0.000000]   AMD AuthenticAMD
[    0.000000] CPU: vendor_id 'GenuineIntel' unknown, using generic init.
[    0.000000] CPU: Your system may be unstable.
[    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] Notice: NX (Execute Disable) protection cannot be enabled: 
non-PAE kernel!
[    0.000000] random: fast init done
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 
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 = 0x100000
[    0.000000] MTRR default type: write-back
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-BFFFF uncachable
[    0.000000]   C0000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 0080000000 mask FF80000000 uncachable
[    0.000000]   1 disabled
[    0.000000]   2 disabled
[    0.000000]   3 disabled
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
[    0.000000] Scan for SMP in [mem 0x00000000-0x000003ff]
[    0.000000] Scan for SMP in [mem 0x0009fc00-0x0009ffff]
[    0.000000] Scan for SMP in [mem 0x000f0000-0x000fffff]
[    0.000000] found SMP MP-table at [mem 0x000f6a50-0x000f6a5f] mapped at 
[ffc67a50]
[    0.000000]   mpc: f6a60-f6b7c
[    0.000000] initial memory mapped: [mem 0x00000000-0x0b7fffff]
[    0.000000] Base memory trampoline at [b009b000] 9b000 size 16384
[    0.000000] BRK [0x0b534000, 0x0b534fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x1768b000-0x18ecffff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F6840 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x0000000018EE1628 000030 (v01 BOCHS  BXPCRSDT 
00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x0000000018EE147C 000074 (v01 BOCHS  BXPCFACP 
00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x0000000018EE0040 00143C (v01 BOCHS  BXPCDSDT 
00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x0000000018EE0000 000040
[    0.000000] ACPI: APIC 0x0000000018EE1570 000080 (v01 BOCHS  BXPCAPIC 
00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x0000000018EE15F0 000038 (v01 BOCHS  BXPCHPET 
00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to         ffffc000 (        fee00000)
[    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 898792862 cycles
[    0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 
0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[    0.000000] BRK [0x0b535000, 0x0b535fff] PGTABLE
[    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 b9adda00, node_mem_map 
c72a3028
[    0.000000]   DMA zone: 40 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 3998 pages, LIFO batch:0
[    0.000000]   Normal zone: 958 pages used for memmap
[    0.000000]   Normal zone: 98002 pages, LIFO batch:31
[    0.000000] Using APIC driver default
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 
02
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 
05
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 
09
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 
0a
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 
0b
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 
01
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 
03
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 
04
[    0.000000] ACPI: IRQ5 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 
06
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 
07
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 
08
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] ACPI: IRQ10 used by override.
[    0.000000] ACPI: IRQ11 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 
0c
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 
0d
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 
0e
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 
0f
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    0.000000] mapped IOAPIC to ffffb000 (fec00000)
[    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: 19112604462750000 ns
[    0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:2 nr_cpu_ids:2 
nr_node_ids:1
[    0.000000] percpu: Embedded 19 pages/cpu @c727b000 s54528 r0 d23296 u77824
[    0.000000] pcpu-alloc: s54528 r0 d23296 u77824 alloc=19*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 1727dac0
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 101002
[    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-i0-201739/wfg:unwind_frame-josh:c394639389261e631779eb1ac94aaf1d16f566a5/.vmlinuz-c394639389261e631779eb1ac94aaf1d16f566a5-20171009220139-265:quantal-vp-31
 branch=wfg/unwind_frame-josh 
BOOT_IMAGE=/pkg/linux/i386-randconfig-i0-201739/gcc-4.8/c394639389261e631779eb1ac94aaf1d16f566a5/vmlinuz-4.14.0-rc3-00002-gc394639
 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: 333848K/408000K available (10845K kernel code, 3471K 
rwdata, 5544K rodata, 916K init, 23160K bss, 74152K reserved, 0K cma-reserved, 
0K highmem)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xffe66000 - 0xfffff000   (1636 kB)
[    0.000000]     pkmap   : 0xff800000 - 0xffc00000   (4096 kB)
[    0.000000]     vmalloc : 0xc96d2000 - 0xff7fe000   ( 865 MB)
[    0.000000]     lowmem  : 0xb0000000 - 0xc8ed2000   ( 398 MB)
[    0.000000]       .init : 0xb9d7d000 - 0xb9e62000   ( 916 kB)
[    0.000000]       .data : 0xb949764c - 0xb9d66e40   (9021 kB)
[    0.000000]       .text : 0xb8a00000 - 0xb949764c   (10845 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor 
mode...Ok.
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    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.010000] Running RCU self tests
[    0.010000] Preemptible hierarchical RCU implementation.
[    0.010000]  RCU lockdep checking is enabled.
[    0.010000]  RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=2.
[    0.010000]  RCU debug extended QS entry/exit.
[    0.010000]  RCU CPU stall warnings timeout set to 100 
(rcu_cpu_stall_timeout).
[    0.010000]  Tasks RCU enabled.
[    0.010000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.010000] NR_IRQS: 2304, nr_irqs: 440, preallocated irqs: 16
[    0.010000] CPU 0 irqstacks, hard=c6de2000 soft=c6de4000
[    0.010000] console [ttyS0] enabled
[    0.010000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., 
Ingo Molnar
[    0.010000] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.010000] ... MAX_LOCK_DEPTH:          48
[    0.010000] ... MAX_LOCKDEP_KEYS:        8191
[    0.010000] ... CLASSHASH_SIZE:          4096
[    0.010000] ... MAX_LOCKDEP_ENTRIES:     32768
[    0.010000] ... MAX_LOCKDEP_CHAINS:      65536
[    0.010000] ... CHAINHASH_SIZE:          32768
[    0.010000]  memory used by lock dependency info: 4399 kB
[    0.010000]  per task-struct memory footprint: 1536 bytes
[    0.010000] ------------------------
[    0.010000] | Locking API testsuite:
[    0.010000] 
----------------------------------------------------------------------------
[    0.010000]                                  | spin |wlock |rlock |mutex | 
wsem | rsem |
[    0.010000]   
--------------------------------------------------------------------------
[    0.010000]                      A-A deadlock:  ok  |  ok  |  ok  |  ok  |  
ok  |  ok  |  ok  |
[    0.010000]                  A-B-B-A deadlock:  ok  |  ok  |  ok  |  ok  |  
ok  |  ok  |  ok  |
[    0.010000]              A-B-B-C-C-A deadlock:  ok  |  ok  |  ok  |  ok  |  
ok  |  ok  |  ok  |
[    0.010000]              A-B-C-A-B-C deadlock:  ok  |  ok  |  ok  |  ok  |  
ok  |  ok  |  ok  |
[    0.010000]          A-B-B-C-C-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  
ok  |  ok  |  ok  |
[    0.010000]          A-B-C-D-B-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  
ok  |  ok  |  ok  |
[    0.010000]          A-B-C-D-B-C-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  
ok  |  ok  |  ok  |
[    0.010000]                     double unlock:  ok  |  ok  |  ok  |  ok  |  
ok  |  ok  |  ok  |
[    0.010000]                   initialize held:  ok  |  ok  |  ok  |  ok  |  
ok  |  ok  |  ok  |
[    0.010000]   
--------------------------------------------------------------------------
[    0.010000]               recursive read-lock:             |  ok  |          
   |  ok  |
[    0.010000]            recursive read-lock #2:             |  ok  |          
   |  ok  |
[    0.010000]             mixed read-write-lock:             |  ok  |          
   |  ok  |
[    0.010000]             mixed write-read-lock:             |  ok  |          
   |  ok  |
[    0.010000]   mixed read-lock/lock-write ABBA:             |FAILED|          
   |  ok  |
[    0.010000]    mixed read-lock/lock-read ABBA:             |  ok  |          
   |  ok  |
[    0.010000]  mixed write-lock/lock-write ABBA:             |  ok  |          
   |  ok  |
[    0.010000]   
--------------------------------------------------------------------------
[    0.010000]      hard-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.010000]      soft-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.010000]      hard-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.010000]      soft-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.010000]        sirq-safe-A => hirqs-on/12:  ok  |  ok  |  ok  |
[    0.010000]        sirq-safe-A => hirqs-on/21:  ok  |  ok  |  ok  |
[    0.010000]          hard-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.010000]          soft-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.010000]          hard-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.010000]          soft-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.010000]     hard-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    0.010000]     soft-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    0.010000]       soft-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    0.010000]       hard-irq read-recursion/123:  ok  |
[    0.010000]       soft-irq read-recursion/123:  ok  |
[    0.010000]       hard-irq read-recursion/132:  ok  |
[    0.010000]       soft-irq read-recursion/132:  ok  |
[    0.010000]       hard-irq read-recursion/213:  ok  |
[    0.010000]       soft-irq read-recursion/213:  ok  |
[    0.010000]       hard-irq read-recursion/231:  ok  |
[    0.010000]       soft-irq read-recursion/231:  ok  |
[    0.010000]       hard-irq read-recursion/312:  ok  |
[    0.010000]       soft-irq read-recursion/312:  ok  |
[    0.010000]       hard-irq read-recursion/321:  ok  |
[    0.010000]       soft-irq read-recursion/321:  ok  |
[    0.010000]   
--------------------------------------------------------------------------
[    0.010000]   | Wound/wait tests |
[    0.010000]   ---------------------
[    0.010000]                   ww api failures:  ok  |  ok  |  ok  |
[    0.010000]                ww contexts mixing:  ok  |  ok  |
[    0.010000]              finishing ww context:  ok  |  ok  |  ok  |  ok  |
[    0.010000]                locking mismatches:  ok  |  ok  |  ok  |
[    0.010000]                  EDEADLK handling:  ok  |  ok  |  ok  |  ok  |  
ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.010000]            spinlock nest unlocked:  ok  |
[    0.010000]   -----------------------------------------------------
[    0.010000]                                  |block | try  |context|
[    0.010000]   -----------------------------------------------------
[    0.010000]                           context:  ok  |  ok  |  ok  |
[    0.010000]                               try:  ok  |  ok  |  ok  |
[    0.010000]                             block:  ok  |  ok  |  ok  |
[    0.010000]                          spinlock:  ok  |  ok  |  ok  |
[    0.010000] -------------------------------------------------------
[    0.010000] Good, all 261 testcases passed! |
[    0.010000] ---------------------------------
[    0.010000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, 
max_idle_ns: 19112604467 ns
[    0.010000] hpet clockevent registered
[    0.010031] tsc: Detected 2693.508 MHz processor
[    0.010718] tsc: Marking TSC unstable due to TSCs unsynchronized
[    0.010877] Calibrating delay loop (skipped) preset value.. 5387.01 BogoMIPS 
(lpj=26935080)
[    0.012096] pid_max: default: 32768 minimum: 301
[    0.012844] ACPI: Core revision 20170728
[    0.021645] ACPI: 1 ACPI AML tables successfully acquired and loaded
[    0.023295] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.024294] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.030486] mce: CPU supports 10 MCE banks
[    0.031131] mce: unknown CPU type - not enabling MCE support
[    0.031972] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.032774] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.033947] Freeing SMP alternatives memory: 20K
[    0.036401] smpboot: Max logical packages: 2
[    0.037086] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    0.037952] enabled ExtINT on CPU#0
[    0.050475] ENABLING IO-APIC IRQs
[    0.051107] init IO_APIC IRQs
[    0.051623]  apic 0 pin 0 not connected
[    0.052309] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 
Active:0 Dest:1)
[    0.053583] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 
Active:0 Dest:1)
[    0.054827] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 
Active:0 Dest:1)
[    0.056073] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 
Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 
Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 
Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 
Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 
Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 
Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 
Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 
Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 
Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 
Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 
Active:0 Dest:1)
[    0.060000] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 
Active:0 Dest:1)
[    0.060000]  apic 0 pin 16 not connected
[    0.060000]  apic 0 pin 17 not connected
[    0.060000]  apic 0 pin 18 not connected
[    0.060000]  apic 0 pin 19 not connected
[    0.060000]  apic 0 pin 20 not connected
[    0.060000]  apic 0 pin 21 not connected
[    0.060000]  apic 0 pin 22 not connected
[    0.060000]  apic 0 pin 23 not connected
[    0.060000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.060000] Using local APIC timer interrupts.
[    0.060000] calibrating APIC timer ...
[    0.070000] ... lapic delta = 8750957
[    0.070000] ... PM-Timer delta = 501212
[    0.070000] APIC calibration not consistent with PM-Timer: 140ms instead of 
100ms
[    0.070000] APIC delta adjusted to PM-Timer: 6249730 (8750957)
[    0.070000] TSC delta adjusted to PM-Timer: 269350330 (377147952)
[    0.070000] ..... delta 6249730
[    0.070000] ..... mult: 268423859
[    0.070000] ..... calibration result: 9999568
[    0.070000] ..... CPU clock speed is 2693.5033 MHz.
[    0.070000] ..... host bus clock speed is 999.9568 MHz.
[    0.070096] smpboot: CPU0: GenuineIntel Common KVM processor (family: 0xf, 
model: 0x6, stepping: 0x1)
[    0.110092] Performance Events: no PMU driver, software events only.
[    0.130052] Hierarchical SRCU implementation.
[    0.190048] smp: Bringing up secondary CPUs ...
[    0.272220] CPU 1 irqstacks, hard=c6ff4000 soft=c6ff6000
[    0.273039] x86: Booting SMP configuration:
[    0.273659] .... node  #0, CPUs:      #1
[    0.000000] Initializing CPU#1
[    0.010000] kvm-clock: cpu 1, msr 0:18ed1041, secondary cpu clock
[    0.010000] masked ExtINT on CPU#1
[    0.310122] KVM setup async PF for cpu 1
[    0.310122] kvm-stealtime: cpu 1, msr 17290ac0
[    0.312433] smp: Brought up 1 node, 2 CPUs
[    0.320023] smpboot: Total of 2 processors activated (10774.03 BogoMIPS)
[    0.350033] devtmpfs: initialized
[    0.357934] workqueue: round-robin CPU selection forced, expect performance 
impact
[    0.380667] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, 
max_idle_ns: 19112604462750000 ns
[    0.382330] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.385324] prandom: seed boundary self test passed
[    0.390522] prandom: 100 self tests passed
[    0.391872] regulator-dummy: no parameters
[    0.395709] NET: Registered protocol family 16
[    0.410037] cpuidle: using governor ladder
[    0.411844] ACPI: bus type PCI registered
[    0.413200] PCI: PCI BIOS revision 2.10 entry at 0xfd501, last bus=0
[    0.420035] PCI: Using configuration type 1 for base access
[    0.510380] HugeTLB registered 4.00 MiB page size, pre-allocated 0 pages
[    0.520395] gpio-f7188x: Not a Fintek device at 0x0000002e
[    0.521291] gpio-f7188x: Not a Fintek device at 0x0000004e
[    0.522849] ACPI: Added _OSI(Module Device)
[    0.523504] ACPI: Added _OSI(Processor Device)
[    0.530089] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.530950] ACPI: Added _OSI(Processor Aggregator Device)
[    0.588050] ACPI: Interpreter enabled
[    0.588669] ACPI: (supports S0 S5)
[    0.589198] ACPI: Using IOAPIC for interrupt routing
[    0.590185] PCI: Using host bridge windows from ACPI; if necessary, use 
"pci=nocrs" and report a bug
[    0.593514] ACPI: Enabled 2 GPEs in block 00 to 0F
[    0.754281] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.760045] acpi PNP0A03:00: _OSC: OS supports [Segments]
[    0.770142] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.773668] acpi PNP0A03:00: fail to add MMCONFIG information, can't access 
extended PCI configuration space under this bridge.
[    0.775893] PCI host bridge to bus 0000:00
[    0.780042] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    0.781170] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    0.782282] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff 
window]
[    0.783506] pci_bus 0000:00: root bus resource [mem 0x18f00000-0xfebfffff 
window]
[    0.784732] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.785780] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.791358] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.795209] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    0.830027] pci 0000:00:01.1: reg 0x20: [io  0xc200-0xc20f]
[    0.843432] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    0.844452] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    0.845398] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    0.846421] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    0.860385] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    0.862094] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 
ACPI
[    0.863145] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    0.866132] pci 0000:00:02.0: [1234:1111] type 00 class 0x030000
[    0.873566] pci 0000:00:02.0: reg 0x10: [mem 0xfd000000-0xfdffffff pref]
[    0.893739] pci 0000:00:02.0: reg 0x18: [mem 0xfebf0000-0xfebf0fff]
[    0.940033] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    0.942803] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    0.950026] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    0.957807] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    0.996422] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    1.010712] pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000
[    1.020026] pci 0000:00:04.0: reg 0x10: [io  0xc040-0xc07f]
[    1.030027] pci 0000:00:04.0: reg 0x14: [mem 0xfebf1000-0xfebf1fff]
[    1.064341] pci 0000:00:04.0: reg 0x20: [mem 0xfe000000-0xfe003fff 64bit 
pref]
[    1.076833] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000
[    1.100025] pci 0000:00:05.0: reg 0x10: [io  0xc080-0xc0bf]
[    1.110027] pci 0000:00:05.0: reg 0x14: [mem 0xfebf2000-0xfebf2fff]
[    1.164755] pci 0000:00:05.0: reg 0x20: [mem 0xfe004000-0xfe007fff 64bit 
pref]
[    1.182575] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000
[    1.194711] pci 0000:00:06.0: reg 0x10: [io  0xc0c0-0xc0ff]
[    1.204773] pci 0000:00:06.0: reg 0x14: [mem 0xfebf3000-0xfebf3fff]
[    1.240030] pci 0000:00:06.0: reg 0x20: [mem 0xfe008000-0xfe00bfff 64bit 
pref]
[    1.262557] pci 0000:00:07.0: [1af4:1001] type 00 class 0x010000
[    1.290023] pci 0000:00:07.0: reg 0x10: [io  0xc100-0xc13f]
[    1.304797] pci 0000:00:07.0: reg 0x14: [mem 0xfebf4000-0xfebf4fff]
[    1.334681] pci 0000:00:07.0: reg 0x20: [mem 0xfe00c000-0xfe00ffff 64bit 
pref]
[    1.352467] pci 0000:00:08.0: [1af4:1001] type 00 class 0x010000
[    1.364707] pci 0000:00:08.0: reg 0x10: [io  0xc140-0xc17f]
[    1.390030] pci 0000:00:08.0: reg 0x14: [mem 0xfebf5000-0xfebf5fff]
[    1.450026] pci 0000:00:08.0: reg 0x20: [mem 0xfe010000-0xfe013fff 64bit 
pref]
[    1.481479] pci 0000:00:09.0: [1af4:1001] type 00 class 0x010000
[    1.494616] pci 0000:00:09.0: reg 0x10: [io  0xc180-0xc1bf]
[    1.504472] pci 0000:00:09.0: reg 0x14: [mem 0xfebf6000-0xfebf6fff]
[    1.540028] pci 0000:00:09.0: reg 0x20: [mem 0xfe014000-0xfe017fff 64bit 
pref]
[    1.552538] pci 0000:00:0a.0: [1af4:1001] type 00 class 0x010000
[    1.570044] pci 0000:00:0a.0: reg 0x10: [io  0xc1c0-0xc1ff]
[    1.590033] pci 0000:00:0a.0: reg 0x14: [mem 0xfebf7000-0xfebf7fff]
[    1.620026] pci 0000:00:0a.0: reg 0x20: [mem 0xfe018000-0xfe01bfff 64bit 
pref]
[    1.640551] pci 0000:00:0b.0: [8086:25ab] type 00 class 0x088000
[    1.653542] pci 0000:00:0b.0: reg 0x10: [mem 0xfebf8000-0xfebf800f]
[    1.687035] pci_bus 0000:00: on NUMA node 0
[    1.696958] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    1.698708] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    1.700458] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    1.710964] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    1.712290] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    1.730189] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[    1.731085] pci 0000:00:02.0: vgaarb: VGA device added: 
decodes=io+mem,owns=io+mem,locks=none
[    1.732340] pci 0000:00:02.0: vgaarb: bridge control possible
[    1.733178] vgaarb: loaded
[    1.735566] ACPI: bus type USB registered
[    1.736337] usbcore: registered new interface driver usbfs
[    1.737256] usbcore: registered new interface driver hub
[    1.740183] usbcore: registered new device driver usb
[    1.741485] pps_core: LinuxPPS API ver. 1 registered
[    1.742215] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo 
Giometti <giome...@linux.it>
[    1.750105] EDAC MC: Ver: 3.0.0
[    1.751317] EDAC DEBUG: edac_mc_sysfs_init: device mc created
[    1.760406] Unable to find dmi tokens
[    1.770424] FPGA manager framework
[    1.771085] PCI: Using ACPI for IRQ routing
[    1.771701] PCI: pci_cache_line_size set to 64 bytes
[    1.772835] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    1.773718] e820: reserve RAM buffer [mem 0x18ed2000-0x1bffffff]
[    1.806737] clocksource: Switched to clocksource kvm-clock
[    1.807092] Warning: could not register all branches stats
[    1.807951] Warning: could not register annotated branches stats
[    2.345637] VFS: Disk quotas dquot_6.6.0
[    2.346357] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    2.347704] FS-Cache: Loaded
[    2.348454] pnp: PnP ACPI init
[    2.349460] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    2.350751] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    2.352053] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    2.373269] pnp 00:03: [dma 2]
[    2.374007] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    2.375596] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    2.377054] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    2.378441] pnp 00:06: Plug and Play ACPI device, IDs PNP0501 (active)
[    2.381941] pnp: PnP ACPI: found 7 devices
[    2.440399] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, 
max_idle_ns: 2085701024 ns
[    2.442025] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[    2.442892] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[    2.461872] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[    2.462867] pci_bus 0000:00: resource 7 [mem 0x18f00000-0xfebfffff window]
[    2.475098] NET: Registered protocol family 1
[    2.475808] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    2.476690] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    2.477581] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    2.478575] pci 0000:00:02.0: Video device with shadowed ROM at [mem 
0x000c0000-0x000dffff]
[    2.479942] PCI: CLS 0 bytes, default 64
[    2.481151] Unpacking initramfs...
[    9.135198] Freeing initrd memory: 24852K
[    9.138641] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 
0x26d349e8249, max_idle_ns: 440795288087 ns
[    9.140394] Machine check injector initialized
[    9.166989] The force parameter has not been set to 1. The Iris poweroff 
handler will not be installed.
[    9.168303] NatSemi SCx200 Driver
[    9.169058] spin_lock-torture:--- Start of test [debug]: nwriters_stress=4 
nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 
shutdown_secs=0 onoff_interval=0 onoff_holdoff=0
[    9.171555] spin_lock-torture: Creating torture_shuffle task
[    9.184266] spin_lock-torture: Creating torture_stutter task
[    9.185131] spin_lock-torture: torture_shuffle task started
[    9.186151] spin_lock-torture: Creating lock_torture_writer task
[    9.187157] spin_lock-torture: torture_stutter task started
[    9.188051] spin_lock-torture: Creating lock_torture_writer task
[    9.189072] spin_lock-torture: lock_torture_writer task started
[    9.190163] spin_lock-torture: Creating lock_torture_writer task
[    9.191231] spin_lock-torture: lock_torture_writer task started
[    9.193124] spin_lock-torture: Creating lock_torture_writer task
[    9.194066] spin_lock-torture: lock_torture_writer task started
[    9.198148] spin_lock-torture: lock_torture_writer task started
[    9.199036] spin_lock-torture: Creating lock_torture_stats task
[    9.213132] spin_lock-torture: lock_torture_stats task started
[    9.226288] Key type blacklist registered
[    9.236130] workingset: timestamp_bits=14 max_order=17 bucket_order=3
[    9.371983] zbud: loaded
[    9.400049] orangefs_debugfs_init: called with debug mask: :none: :0:
[    9.401476] orangefs_init: module version upstream loaded
[   10.604214] Key type asymmetric registered
[   10.604948] test_string_helpers: Running tests...
[   10.610704] test_hexdump: all 1184 tests passed
[   10.611955] test_firmware: interface ready
[   10.717089] test_hash: __hash_32() has no arch implementation to test.
[   10.718069] test_hash: hash_32() has no arch implementation to test.
[   10.719108] test_hash: hash_64() has no arch implementation to test.
[   10.720151] test_hash: 33152 tests passed.
[   10.720878] test_siphash: self-tests: pass
[   10.743955] Running rhashtable test nelem=8, max_size=0, shrinking=0
[   10.745058] Test 00:
[   10.753520]   Adding 50000 keys
[   12.195791]   Traversal complete: counted=50000, nelems=50000, 
entries=50000, table-jumps=0
[   12.761685]   Traversal complete: counted=50000, nelems=50000, 
entries=50000, table-jumps=0
[   12.762834]   Deleting 50000 keys
[   13.468828]   Duration of test: 2708039140 ns
[   13.471546] Test 01:
[   13.491538]   Adding 50000 keys
[   15.061599]   Traversal complete: counted=50000, nelems=50000, 
entries=50000, table-jumps=0
[   15.560729]   Traversal complete: counted=50000, nelems=50000, 
entries=50000, table-jumps=0
[   15.561960]   Deleting 50000 keys
[   16.195359]   Duration of test: 2703326222 ns
[   16.196016] Test 02:
[   16.197879]   Adding 50000 keys
[   17.790661]   Traversal complete: counted=50000, nelems=50000, 
entries=50000, table-jumps=0
[   18.280039]   Traversal complete: counted=50000, nelems=50000, 
entries=50000, table-jumps=0
[   18.298329]   Deleting 50000 keys
[   19.011160]   Duration of test: 2812837736 ns
[   19.011875] Test 03:
[   19.020854]   Adding 50000 keys
[   20.678665]   Traversal complete: counted=50000, nelems=50000, 
entries=50000, table-jumps=0
[   21.187815]   Traversal complete: counted=50000, nelems=50000, 
entries=50000, table-jumps=0
[   21.200699]   Deleting 50000 keys
[   21.896142]   Duration of test: 2874733313 ns
[   21.898792] Average test time: 2774734102
[   21.921558] Testing concurrent rhashtable access from 10 threads
[   56.195918] Started 10 threads, 0 failed
[   56.200578] test passed
[   56.211937] test_printf: all 260 tests passed
[   56.806002] test_bitmap: test 13: input is '0-2047:128/256' OK, Time: 2055
[   56.862805] test_bitmap: all 460506 tests passed
[   56.868877] crc32: CRC_LE_BITS = 1, CRC_BE BITS = 1
[   56.869707] crc32: self tests passed, processed 225944 bytes in 2648766 nsec
[   56.903856] crc32c: CRC_LE_BITS = 1
[   56.904429] crc32c: self tests passed, processed 225944 bytes in 1393934 nsec
[   57.411090] crc32_combine: 8373 self tests passed
[   57.983198] crc32c_combine: 8373 self tests passed
[   57.991522] atomic64_test: passed for i386+ platform with CX8 and with SSE
[   57.992749] glob: 64 self-tests passed, 0 failed
[   57.993509] interval tree insert/remove
[   63.415540]  -> 146042 cycles
[   63.416472] interval tree search
[   65.900057]  -> 668947 cycles (2692 results)
[   65.905003] no IO addresses supplied
[   65.906106] usbcore: registered new interface driver udlfb
[   65.907128] ipmi message handler version 39.2
[   65.907865] IPMI System Interface driver.
[   65.937114] ipmi_si: Unable to find any System Interface(s)
[   65.938041] IPMI SSIF Interface driver
[   65.938850] IPMI Watchdog: driver initialized
[   65.939536] Copyright (C) 2004 MontaVista Software - IPMI Powerdown via 
sys_reboot.
[   65.951940] input: Power Button as 
/devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[   65.953093] ACPI: Power Button [PWRF]
[   66.667157] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[   66.780577] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 
16550A
[   66.860567] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 
16550A
[   66.921164] lp: driver loaded but no devices found
[   66.922355] Non-volatile memory driver v1.3
[   66.940539] scx200_gpio: no SCx200 gpio present
[   66.941646] platform pc8736x_gpio.0: NatSemi pc8736x GPIO Driver Initializing
[   66.942696] platform pc8736x_gpio.0: no device found
[   66.943725] nsc_gpio initializing
[   66.944302] telclk_interrupt = 0xf non-mcpbl0010 hw.
[   66.945102] Hangcheck: starting hangcheck timer 0.9.1 (tick is 180 seconds, 
margin is 60 seconds).
[   66.954099] drm_mm: Testing DRM range manger (struct drm_mm), with 
random_seed=0xd2fa055a max_iterations=8192 max_prime=128
[   66.955702] drm_mm: igt_sanitycheck - ok!
[   81.530075] Writes:  Total: 4  Max/Min: 0/0   Fail: 0 
[  146.140042] Writes:  Total: 4  Max/Min: 0/0   Fail: 0 
[  178.999319] drm_mm: lookup failed for node 0 + 1
[  179.005908] parport_pc 00:04: reported by Plug and Play ACPI
[  179.030388] parport0: PC-style at 0x378, irq 7 [PCSPP(,...)]
[  179.032414] lp0: using parport0 (interrupt-driven).
[  179.053014] dummy-irq: no IRQ given.  Use irq=N
[  179.055301] usbcore: registered new interface driver rtsx_usb
[  179.058285] usbcore: registered new interface driver viperboard
[  179.060787] parport0: cannot grant exclusive access for device spi-lm70llp
[  179.080190] spi_lm70llp: spi_lm70llp probe fail, status -12
[  179.082180] parport0: cannot grant exclusive access for device ks0108
[  179.083287] ks0108: ERROR: parport didn't register new device
[  179.084279] cfag12864b: ERROR: ks0108 is not initialized
[  179.085199] cfag12864bfb: ERROR: cfag12864b is not initialized
[  179.313224] panel: panel driver registered on parport0 (io=0x378).
[  179.314445] usbcore: registered new interface driver hwa-rc
[  179.315856] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[  179.316876] ehci-pci: EHCI PCI platform driver
[  179.317705] ehci-platform: EHCI generic platform driver
[  179.318758] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[  179.328839] ohci-pci: OHCI PCI platform driver
[  179.329635] ohci-platform: OHCI generic platform driver
[  179.330671] fotg210_hcd: FOTG210 Host Controller (EHCI) Driver
[  179.331535] Warning! fotg210_hcd should always be loaded before uhci_hcd and 
ohci_hcd, not after
[  179.340896] usbcore: registered new interface driver wusb-cbaf
[  179.341832] usbcore: registered new interface driver cdc_wdm
[  179.342748] usbcore: registered new interface driver mdc800
[  179.343565] mdc800: v0.7.5 (30/10/2000):USB Driver for Mustek MDC800 Digital 
Camera
[  179.344823] usbcore: registered new interface driver adutux
[  179.345759] usbcore: registered new interface driver appledisplay
[  179.346800] usbcore: registered new interface driver cytherm
[  179.347754] usbcore: registered new interface driver emi62 - firmware loader
[  179.348819] ftdi_elan: driver ftdi-elan
[  179.367661] usbcore: registered new interface driver ftdi-elan
[  179.368591] usbcore: registered new interface driver iowarrior
[  179.369512] usbcore: registered new interface driver isight_firmware
[  179.370507] usbcore: registered new interface driver usblcd
[  179.371403] usbcore: registered new interface driver ldusb
[  179.372278] usbcore: registered new interface driver legousbtower
[  179.373255] usbcore: registered new interface driver rio500
[  179.374164] usbcore: registered new interface driver usbtest
[  179.375136] usbcore: registered new interface driver usb_ehset_test
[  179.394321] usbcore: registered new interface driver uss720
[  179.395142] uss720: USB Parport Cable driver for Cables using the Lucent 
Technologies USS720 Chip
[  179.396401] uss720: NOTE: this is a special purpose driver to allow 
nonstandard
[  179.397469] uss720: protocols (eg. bitbang) over USS720 usb to parallel 
cables
[  179.398506] uss720: If you just want to connect to a printer, use usblp 
instead
[  179.399627] usbcore: registered new interface driver usbsevseg
[  179.400568] usbcore: registered new interface driver yurex
[  179.401723] usbcore: registered new interface driver sisusb
[  179.402610] usbcore: registered new interface driver lvs
[  179.415704] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 
0x60,0x64 irq 1,12
[  179.451570] serio: i8042 KBD port at 0x60,0x64 irq 1
[  179.452374] serio: i8042 AUX port at 0x60,0x64 irq 12
[  179.453337] parport0: cannot grant exclusive access for device parkbd
[  179.675063] mousedev: PS/2 mouse device common for all mice
[  179.675934] evbug: Connected device: input0 (Power Button at 
LNXPWRBN/button/input0)
[  179.677361] usbcore: registered new interface driver appletouch
[  179.678308] usbcore: registered new interface driver bcm5974
[  179.690019] usbcore: registered new interface driver xpad
[  179.691039] usbcore: registered new interface driver gtco
[  179.709735] usbcore: registered new interface driver hanwang
[  179.710662] usbcore: registered new interface driver kbtab
[  179.711560] usbcore: registered new interface driver pegasus_notetaker
[  179.715700] i2c /dev entries driver
[  179.716631] usbcore: registered new interface driver i2c-tiny-usb
[  179.738712] pps pps0: new PPS source ktimer
[  179.739388] pps pps0: ktimer PPS source registered
[  179.740234] Driver for 1-wire Dallas network protocol.
[  179.741333] usbcore: registered new interface driver DS9490R
[  179.742208] DS1WM w1 busmaster driver - (c) 2004 Szabolcs Gyurko
[  179.743351] w1_f0d_init()
[  179.765080] applesmc: supported laptop not found!
[  179.765781] applesmc: driver init failed (ret=-19)!
[  179.770094] pc87360: PC8736x not detected, module not inserted
[  179.772369] sdhci: Secure Digital Host Controller Interface driver
[  179.773323] sdhci: Copyright(c) Pierre Ossman
[  179.792281] wbsd: Winbond W83L51xD SD/MMC card interface driver
[  179.793151] wbsd: Copyright(c) Pierre Ossman
[  179.794057] VUB300 Driver rom wait states = 1C irqpoll timeout = 0400
[  179.864608] input: AT Translated Set 2 keyboard as 
/devices/platform/i8042/serio0/input/input1
[  179.884130] usbcore: registered new interface driver vub300
[  179.894866] evbug: Connected device: input1 (AT Translated Set 2 keyboard at 
isa0060/serio0/input0)
[  179.910298] dcdbas dcdbas: Dell Systems Management Base Driver (version 
5.6.0-3.2)
[  179.922465] hidraw: raw HID events driver (C) Jiri Kosina
[  179.926430] usbcore: registered new interface driver usbkbd
[  179.927353] usbcore: registered new interface driver usbmouse
[  179.948232]  fake-fmc-carrier: mezzanine 0
[  179.948891]       Manufacturer: fake-vendor
[  179.949545]       Product name: fake-design-for-testing
[  179.950632] fmc fake-design-for-testing-f001: Driver has no ID: matches all
[  179.951770] fmc_trivial: probe of fake-design-for-testing-f001 failed with 
error -95
[  179.952977] fmc fake-design-for-testing-f001: Driver has no ID: matches all
[  179.954021] fmc_write_eeprom fake-design-for-testing-f001: fmc_write_eeprom: 
no busid passed, refusing all cards
[  179.983823] start plist test
[  179.991564] end plist test
[  179.992019] mce: Unable to init MCE device (rc: -5)
[  179.993714] ... APIC ID:      00000000 (0)
[  179.994327] ... APIC VERSION: 01050014
[  179.994900] 0000000000000000000000000000000000000000000000000000000000000000
[  179.996029] 0000000000000000000000000000000000000000000000000000000000000000
[  179.997144] 0000000000000000000000000000000000000000000000000000000000008000
[  180.007016] number of MP IRQ sources: 15.
[  180.007609] number of IO-APIC #0 registers: 24.
[  180.008305] testing the IO APIC.......................
[  180.009120] IO APIC #0......
[  180.009580] .... register #00: 00000000
[  180.010182] .......    : physical APIC id: 00
[  180.010849] .......    : Delivery Type: 0
[  180.011475] .......    : LTS          : 0
[  180.012099] .... register #01: 00170011
[  180.012695] .......     : max redirection entries: 17
[  180.013469] .......     : PRQ implemented: 0
[  180.014143] .......     : IO APIC version: 11
[  180.014825] .... register #02: 00000000
[  180.015428] .......     : arbitration: 00
[  180.016051] .... IRQ redirection table:
[  180.016659] IOAPIC 0:
[  180.017077]  pin00, disabled, edge , high, V(00), IRR(0), S(0), physical, 
D(00), M(0)
[  180.018277]  pin01, enabled , edge , high, V(31), IRR(0), S(0), logical , 
D(03), M(1)
[  180.019476]  pin02, enabled , edge , high, V(30), IRR(0), S(0), logical , 
D(03), M(1)
[  180.033775]  pin03, enabled , edge , high, V(33), IRR(0), S(0), logical , 
D(03), M(1)
[  180.034991]  pin04, enabled , edge , high, V(34), IRR(0), S(0), logical , 
D(03), M(1)
[  180.036219]  pin05, disabled, level, high, V(35), IRR(0), S(0), logical , 
D(03), M(1)
[  180.037468]  pin06, enabled , edge , high, V(36), IRR(0), S(0), logical , 
D(03), M(1)
[  180.038671]  pin07, enabled , edge , high, V(37), IRR(0), S(0), logical , 
D(03), M(1)
[  180.039873]  pin08, enabled , edge , high, V(38), IRR(0), S(0), logical , 
D(03), M(1)
[  180.041075]  pin09, enabled , level, high, V(39), IRR(0), S(0), logical , 
D(03), M(1)
[  180.042341]  pin0a, disabled, level, high, V(3A), IRR(0), S(0), logical , 
D(03), M(1)
[  180.043536]  pin0b, disabled, level, high, V(3B), IRR(0), S(0), logical , 
D(03), M(1)
[  180.044726]  pin0c, enabled , edge , high, V(3C), IRR(0), S(0), logical , 
D(03), M(1)
[  180.058970]  pin0d, enabled , edge , high, V(3D), IRR(0), S(0), logical , 
D(03), M(1)
[  180.060198]  pin0e, enabled , edge , high, V(3E), IRR(0), S(0), logical , 
D(03), M(1)
[  180.061333]  pin0f, enabled , edge , high, V(3F), IRR(0), S(0), logical , 
D(03), M(1)
[  180.062477]  pin10, disabled, edge , high, V(00), IRR(0), S(0), physical, 
D(00), M(0)
[  180.063610]  pin11, disabled, edge , high, V(00), IRR(0), S(0), physical, 
D(00), M(0)
[  180.064742]  pin12, disabled, edge , high, V(00), IRR(0), S(0), physical, 
D(00), M(0)
[  180.065874]  pin13, disabled, edge , high, V(00), IRR(0), S(0), physical, 
D(00), M(0)
[  180.067035]  pin14, disabled, edge , high, V(00), IRR(0), S(0), physical, 
D(00), M(0)
[  180.068186]  pin15, disabled, edge , high, V(00), IRR(0), S(0), physical, 
D(00), M(0)
[  180.069358]  pin16, disabled, edge , high, V(00), IRR(0), S(0), physical, 
D(00), M(0)
[  180.070571]  pin17, disabled, edge , high, V(00), IRR(0), S(0), physical, 
D(00), M(0)
[  180.071753] IRQ to pin mappings:
[  180.085523] IRQ0 -> 0:2
[  180.085955] IRQ1 -> 0:1
[  180.086376] IRQ3 -> 0:3
[  180.086812] IRQ4 -> 0:4
[  180.087238] IRQ5 -> 0:5
[  180.087631] IRQ6 -> 0:6
[  180.088029] IRQ7 -> 0:7
[  180.088422] IRQ8 -> 0:8
[  180.088818] IRQ9 -> 0:9
[  180.089218] IRQ10 -> 0:10
[  180.089634] IRQ11 -> 0:11
[  180.090057] IRQ12 -> 0:12
[  180.090476] IRQ13 -> 0:13
[  180.090892] IRQ14 -> 0:14
[  180.091319] IRQ15 -> 0:15
[  180.091751] .................................... done.
[  180.092501] Using IPI Shortcut mode
[  180.093111] sched_clock: Marking stable (180086795799, 0)->(182833820934, 
-2747025135)
[  180.095580] page_owner is disabled
[  180.174990] Key type big_key registered
[  180.176740] Key type trusted registered
[  180.177960] Key type encrypted registered
[  180.184491] OF: Duplicate name in testcase-data, renamed to 
"duplicate-name#1"
[  180.212989] ### dt-test ### start of unittest - you will see error messages
[  180.241091] OF: /testcase-data/phandle-tests/consumer-a: could not get 
#phandle-cells-missing for /testcase-data/phandle-tests/provider1
[  180.242967] OF: /testcase-data/phandle-tests/consumer-a: could not get 
#phandle-cells-missing for /testcase-data/phandle-tests/provider1
[  180.244822] OF: /testcase-data/phandle-tests/consumer-a: could not find 
phandle
[  180.245949] OF: /testcase-data/phandle-tests/consumer-a: could not find 
phandle
[  180.247147] OF: /testcase-data/phandle-tests/consumer-a: arguments longer 
than property
[  180.266573] OF: /testcase-data/phandle-tests/consumer-a: arguments longer 
than property
[  180.326584] OF: overlay: overlay_is_topmost: #5 clashes #6 
@/testcase-data/overlay-node/test-bus/test-unittest8
[  180.328173] OF: overlay: overlay #5 is not topmost
[  180.371282] i2c i2c-0: Added multiplexed i2c bus 1
[  180.380712] i2c i2c-0: Added multiplexed i2c bus 2
[  180.407617] ### dt-test ### FAIL of_unittest_overlay_high_level():2201 
overlay_base_root not initialized
[  180.409091] ### dt-test ### end of unittest - 188 passed, 1 failed
[  180.451958] Freeing unused kernel memory: 916K
[  180.515111] Write protecting the kernel text: 10848k
[  180.546355] Write protecting the kernel read-only data: 5548k
[  181.526091] init: Failed to create pty - disabling logging for job
[  181.527300] init: Temporary process spawn error: No such file or directory
[  181.710516] init: Failed to create pty - disabling logging for job
[  181.711738] init: Temporary process spawn error: No such file or directory
[  182.029617] init: Failed to create pty - disabling logging for job
[  182.030848] init: Temporary process spawn error: No such file or directory
[  182.035952] init: Failed to create pty - disabling logging for job
[  182.037281] init: Temporary process spawn error: No such file or directory
[  182.526324] init: Failed to create pty - disabling logging for job
[  182.527483] init: Temporary process spawn error: No such file or directory
[  182.550539] init: Failed to create pty - disabling logging for job
[  182.551699] init: Temporary process spawn error: No such file or directory
[  182.616316] init: Failed to create pty - disabling logging for job
[  182.617543] init: Temporary process spawn error: No such file or directory
[  182.641534] init: Failed to create pty - disabling logging for job
[  182.643929] init: Temporary process spawn error: No such file or directory
[  182.663095] init: Failed to create pty - disabling logging for job
[  182.664261] init: Temporary process spawn error: No such file or directory
[  182.705015] init: plymouth-log main process (215) terminated with status 1
[  182.708633] udevd[217]: starting version 175
[  182.726986] init: Failed to create pty - disabling logging for job
[  182.728132] init: Temporary process spawn error: No such file or directory
[  182.780630] init: Failed to create pty - disabling logging for job
[  182.781806] init: Temporary process spawn error: No such file or directory
[  182.814577] init: Failed to create pty - disabling logging for job
[  182.815741] init: Temporary process spawn error: No such file or directory
udevd[226]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:LNXSYSTM:': No such file or directory
udevd[232]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:LNXSYBUS:': No such file or directory
udevd[235]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
pci:v00008086d00001237sv00001AF4sd00001100bc06sc00i00': No such file or 
directory
udevd[242]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv fmc': No 
such file or directory
udevd[243]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:LNXSYBUS:': No such file or directory
udevd[241]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
pci:v00008086d00007010sv00001AF4sd00001100bc01sc01i80': No such file or 
directory
udevd[249]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
pci:v00008086d0000100Esv00001AF4sd00001100bc02sc00i00': No such file or 
directory
udevd[245]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
pci:v00001AF4d00001001sv00001AF4sd00000002bc01sc00i00': No such file or 
directory
udevd[246]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
pci:v00001AF4d00001001sv00001AF4sd00000002bc01sc00i00': No such file or 
directory
udevd[248]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
pci:v00001AF4d00001001sv00001AF4sd00000002bc01sc00i00': No such file or 
directory
udevd[252]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0A03:': No such file or directory
udevd[258]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0103:': No such file or directory
udevd[261]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
pci:v00008086d00007000sv00001AF4sd00001100bc06sc01i00': No such file or 
directory
udevd[257]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0C0F:': No such file or directory
udevd[260]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0C0F:': No such file or directory
udevd[262]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0C0F:': No such file or directory
udevd[251]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:ACPI0010:PNP0A05:': No such file or directory
udevd[250]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
pci:v00001234d00001111sv00001AF4sd00001100bc03sc00i00': No such file or 
directory
udevd[244]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
pci:v00008086d00007113sv00001AF4sd00001100bc06sc80i00': No such file or 
directory
udevd[269]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0A06:': No such file or directory
udevd[265]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0A06:': No such file or directory
udevd[266]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
pci:v00001AF4d00001001sv00001AF4sd00000002bc01sc00i00': No such file or 
directory
udevd[270]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
pci:v00001AF4d00001001sv00001AF4sd00000002bc01sc00i00': No such file or 
directory
udevd[276]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:QEMU0002:': No such file or directory
udevd[259]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
input:b0019v0000p0001e0000-e0,1,k74,ramlsfw': No such file or directory
udevd[268]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0C0F:': No such file or directory
udevd[281]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0A06:': No such file or directory
udevd[271]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
pci:v00001AF4d00001001sv00001AF4sd00000002bc01sc00i00': No such file or 
directory
udevd[267]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0C0F:': No such file or directory
udevd[308]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0501:': No such file or directory
udevd[304]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
pci:v00001AF4d00001001sv00001AF4sd00000002bc01sc00i00': No such file or 
directory
udevd[309]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:LNXCPU:': No such file or directory
udevd[310]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0303:': No such file or directory
udevd[314]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:LNXCPU:': No such file or directory
udevd[319]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
of:Ntestcase-device2T<NULL>Ctestcase-device': No such file or directory
udevd[320]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
platform:vpd': No such file or directory
udevd[315]: 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 
acpi:PNP0501:': No such file or directory
udevd[311]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0400:': No such file or directory
udevd[306]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0700:': No such file or directory
udevd[305]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0B00:': No such file or directory
udevd[318]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
platform:dell_rbu': No such file or directory
udevd[316]: 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 
pci:v00008086d000025ABsv00001AF4sd00001100bc08sc80i00': No such file or 
directory
udevd[317]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:QEMU0002:': No such file or directory
udevd[322]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
of:Ni2c-test-busT<NULL>Cunittest-i2c-bus': No such file or directory
udevd[321]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
of:Ntestcase-device1T<NULL>Ctestcase-device': No such file or directory
udevd[313]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
acpi:PNP0F13:': No such file or directory
udevd[323]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
platform:memconsole': No such file or directory
udevd[324]: 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[325]: failed to execute '/sbin/modprobe' '/sbin/modprobe -bv 
serio:ty01pr00id00ex00': No such file or directory
[  184.649665] init: Failed to create pty - disabling logging for job
[  184.650814] init: Temporary process spawn error: No such file or directory
[  184.763086] init: Failed to create pty - disabling logging for job
[  184.764240] init: Temporary process spawn error: No such file or directory
[  184.845260] init: Failed to create pty - disabling logging for job
[  184.869159] init: Temporary process spawn error: No such file or directory
[  184.899790] init: Failed to create pty - disabling logging for job
[  184.901144] init: Temporary process spawn error: No such file or directory
[  185.453651] init: failsafe main process (332) killed by TERM signal
[  186.123517] init: Failed to create pty - disabling logging for job
[  186.124653] init: Temporary process spawn error: No such file or directory
[  186.151715] init: Failed to create pty - disabling logging for job
[  186.152858] init: Temporary process spawn error: No such file or directory
[  186.231842] init: Failed to create pty - disabling logging for job
[  186.233003] init: Temporary process spawn error: No such file or directory
[  186.242013] init: Failed to create pty - disabling logging for job
[  186.285872] init: Temporary process spawn error: No such file or directory
[  186.292344] init: Failed to create pty - disabling logging for job
[  186.293681] init: Temporary process spawn error: No such file or directory
Kernel tests: Boot OK!
Trinity v1.4pre  Dave Jones <da...@redhat.com>
[init] Marking syscall get_robust_list (312) as to be disabled.
Done parsing arguments.
Marking all syscalls as enabled.
[init] Disabling syscalls marked as disabled by command line options
[init] Marked syscall get_robust_list (312) as deactivated.
[init] Enabled 350 syscalls. Disabled 1 syscalls.
DANGER: RUNNING AS ROOT.
Unless you are running in a virtual machine, this could cause serious problems 
such as overwriting CMOS
or similar which could potentially make this machine unbootable without a 
firmware reset.

ctrl-c now unless you really know what you are doing.
[  187.661124] init: Temporary process spawn error: No such file or directory
[  187.710721] init: Failed to create pty - disabling logging for job
[  187.711949] init: Temporary process spawn error: No such file or directory
[  187.755448] init: udev-fallback-graphics main process (412) terminated with 
status 127
[  187.761885] init: Failed to create pty - disabling logging for job
[  187.779467] init: Temporary process spawn error: No such file or directory
[  187.803947] init: Failed to create pty - disabling logging for job
[  187.805816] init: Temporary process spawn error: No such file or directory
[  187.855027] init: plymouth-splash main process (418) terminated with status 1
[  187.953296] init: networking main process (419) terminated with status 1
[  191.697721] ------------[ cut here ]------------
[  191.699318] WARNING: CPU: 0 PID: 424 at kernel/locking/lockdep.c:3928 
check_flags+0x119/0x1b0
[  191.700967] CPU: 0 PID: 424 Comm: trinity-main Not tainted 
4.14.0-rc3-00002-gc394639 #1
[  191.702200] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 
1.10.2-1 04/01/2014
[  191.703476] task: c82fec80 task.stack: c8bbe000
[  191.704194] EIP: check_flags+0x119/0x1b0
[  191.704809] EFLAGS: 00010086 CPU: 0
[  191.705380] EAX: 0000002e EBX: c82fec80 ECX: 00000107 EDX: b8afe274
[  191.716483] ESI: c8003400 EDI: 00000000 EBP: c6de5c5c ESP: c6de5c54
[  191.717457]  DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068
[  191.718350] CR0: 80050033 CR2: 0858f014 CR3: 18035000 CR4: 00000690
[  191.719405] Call Trace:
[  191.719823]  <SOFTIRQ>
[  191.720241]  lock_acquire+0x3d/0x230
[  191.720819]  ? perf_event_output_forward+0x14/0x180
[  191.721601]  ? __rcu_read_lock+0x3/0x20
[  191.722218]  perf_event_output_forward+0x41/0x180
[  191.722958]  ? perf_prepare_sample+0x830/0x830
[  191.723657]  ? __perf_event_account_interrupt+0x215/0x240
[  191.724508]  ? perf_prepare_sample+0x830/0x830
[  191.725213]  __perf_event_overflow+0x98/0x150
[  191.725898]  perf_swevent_overflow+0x9e/0xe0
[  191.736679]  perf_swevent_event+0x153/0x1a0
[  191.737345]  perf_tp_event+0x110/0x440
[  191.737943]  ? check_preemption_disabled+0x3d/0x1a0
[  191.738759]  ? check_preemption_disabled+0x3d/0x1a0
[  191.739568]  ? debug_smp_processor_id+0x12/0x20
[  191.740332]  ? perf_trace_buf_alloc+0xf9/0x1c0
[  191.741107]  perf_ftrace_function_call+0xe0/0xf0
[  191.741866]  ? __local_bh_enable+0x99/0xa0
[  191.742586]  ? ftrace_ops_no_ops+0x334/0x380
[  191.743263]  ftrace_ops_no_ops+0x334/0x380
[  191.743924]  ? check_preemption_disabled+0x3d/0x1a0
[  191.744695]  ? __local_bh_enable+0x99/0xa0
[  191.745350]  ? preempt_count_sub+0x8/0x2e0
[  191.746000]  ftrace_stub+0x14/0x1c
[  191.756703]  ? preempt_count_sub+0xd/0x2e0
[  191.757404]  ? trace_softirqs_on+0xf2/0x150
[  191.758079]  __local_bh_enable+0x99/0xa0
[  191.758786]  __do_softirq+0x6a5/0x9c0
[  191.759440]  ? __irqentry_text_end+0x6/0x6
[  191.760096]  do_softirq_own_stack+0x30/0x40
[  191.760788]  </SOFTIRQ>
[  191.761230]  irq_exit+0x56/0xd0
[  191.761796]  smp_apic_timer_interrupt+0x48d/0x6f0
[  191.762571]  apic_timer_interrupt+0x3a/0x40
[  191.763298] EIP: lock_acquire+0x1d6/0x230
[  191.763933] EFLAGS: 00000246 CPU: 0
[  191.764548] EAX: 00000246 EBX: c82fec80 ECX: 6b96419b EDX: 00000000
[  191.765567] ESI: 00000246 EDI: 00000000 EBP: c8bbfe64 ESP: c8bbfe30
[  191.776469]  DS: 007b ES: 007b FS: 00d8 GS: 0033 SS: 0068
[  191.777395]  ? cgroup1_procs_write+0xb/0x20
[  191.778068]  ? handle_pte_fault+0x55f/0x1cc0
[  191.778837]  _raw_spin_lock+0x42/0x50
[  191.779447]  ? handle_pte_fault+0x55f/0x1cc0
[  191.780119]  handle_pte_fault+0x55f/0x1cc0
[  191.780849]  handle_mm_fault+0x531/0x700
[  191.781504]  ? handle_mm_fault+0x72/0x700
[  191.782194]  __do_page_fault+0xa8a/0xbd0
[  191.782855]  do_page_fault+0x2cc/0x422
[  191.783471]  ? kvm_read_and_reset_pf_reason+0x70/0x70
[  191.784293]  do_async_page_fault+0x26/0x60
[  191.796340]  common_exception+0x3d/0x42
[  191.796973] EIP: 0xa7de393e
[  191.797401] EFLAGS: 00010206 CPU: 0
[  191.797924] EAX: 00000000 EBX: a7f0eff4 ECX: 00001ff1 EDX: 0858f010
[  191.798902] ESI: 0858d008 EDI: 00002009 EBP: 00000004 ESP: afe7c120
[  191.799828]  DS: 007b ES: 007b FS: 0000 GS: 0033 SS: 007b
[  191.800679]  ? kvm_read_and_reset_pf_reason+0x70/0x70
[  191.801453] Code: 00 85 c0 74 75 e8 88 8d 2f 00 85 c0 74 6c 83 3d cc c1 57 
ba 00 75 63 c7 44 24 04 34 da 92 b9 c7 04 24 0a 93 91 b9 e8 ec 3b 01 00 <0f> ff 
eb 4b 8d 76 00 8b 0d 88 2a 54 ba 85 c9 75 3e 64 a1 4c 68
[  191.804438] ---[ end trace 70000c51373576aa ]---
[  191.805188] irq event stamp: 362178
[  191.805708] hardirqs last  enabled at (362176): [<b9490faa>] 
restore_all+0xf/0x25
[  191.816989] hardirqs last disabled at (362177): [<b9496420>] 
__do_softirq+0xf0/0x9c0
[  191.818122] softirqs last  enabled at (362178): [<b94969d5>] 
__do_softirq+0x6a5/0x9c0
[  191.819324] softirqs last disabled at (362171): [<b8a10ce0>] 
do_softirq_own_stack+0x30/0x40
[  196.174500] init: tty4 main process ended, respawning
[  196.249472] init: tty5 main process (375) terminated with status 1
[  196.251170] init: tty5 main process ended, respawning
[  196.270393] init: tty2 main process (377) terminated with status 1
[  196.272059] init: tty2 main process ended, respawning
[  196.428413] init: tty3 main process ended, respawning
[  196.430660] init: tty6 main process (379) terminated with status 1
[  196.437890] init: tty6 main process ended, respawning
[init] Using pid_max = 32768
[init] Kernel was tainted on startup. Will ignore flags that are already set.
[init] Started watchdog process, PID is 430
[main] Main thread is alive.
[main] Setsockopt(1 a 80d3000 7e) on fd 8 [1:1:1]
[main] Setsockopt(1 a 80d3000 13) on fd 11 [1:5:1]
[main] Setsockopt(1 9 80d3000 4) on fd 12 [1:1:1]
[main] Setsockopt(10e 3 80d3000 ce) on fd 13 [16:2:2]
[main] Setsockopt(1 2c 80d3000 4) on fd 15 [16:3:16]
[main] Setsockopt(1 2d 80d3000 4) on fd 18 [1:1:1]
[main] Setsockopt(1 2e 80d3000 4) on fd 22 [1:5:1]
[main] Setsockopt(1 1d 80d3000 9b) on fd 23 [1:2:1]
[main] Setsockopt(1 8 80d3000 4) on fd 24 [1:2:1]
[main] Setsockopt(1 5 80d3000 4) on fd 26 [1:1:1]
[main] Setsockopt(1 5 80d3000 17) on fd 27 [1:1:1]
[main] Setsockopt(1 6 80d3000 4) on fd 28 [1:2:1]
[main] Setsockopt(1 2d 80d3000 bc) on fd 29 [1:2:1]
[main] Setsockopt(10e 4 80d3000 5d) on fd 30 [16:3:0]
[main] Setsockopt(1 8 80d3000 4) on fd 31 [1:1:1]
[main] Setsockopt(10e 2 80d3000 24) on fd 32 [16:3:15]
[main] Setsockopt(1 c 80d3000 4) on fd 33 [1:5:1]
[main] Setsockopt(1 12 80d3000 4) on fd 34 [1:5:1]
[main] Setsockopt(1 e 80d3000 4) on fd 35 [16:2:4]
[main] Setsockopt(1 f 80d3000 4) on fd 36 [1:2:1]
[main] Setsockopt(1 2b 80d3000 4) on fd 37 [1:2:1]
[main] Setsockopt(1 c 80d3000 4) on fd 38 [1:1:1]
[main] Setsockopt(1 2d 80d3000 4) on fd 39 [16:2:15]
[main] Setsockopt(1 2d 80d3000 e2) on fd 40 [1:2:1]
[main] Setsockopt(1 20 80d3000 ae) on fd 41 [1:2:1]
[main] Setsockopt(1 1 80d3000 4) on fd 43 [1:2:1]
[main] Setsockopt(1 9 80d3000 34) on fd 44 [1:2:1]
[main] Setsockopt(10e 3 80d3000 4) on fd 46 [16:2:15]
[main] Setsockopt(10e 4 80d3000 24) on fd 49 [16:3:0]
[main] Setsockopt(1 2c 80d3000 4) on fd 52 [1:5:1]
[main] Setsockopt(1 5 80d3000 3e) on fd 54 [1:5:1]
[main] Setsockopt(1 2f 80d3000 ba) on fd 55 [1:5:1]
[main] Setsockopt(1 10 80d3000 4) on fd 57 [1:2:1]
[main] Setsockopt(1 28 80d3000 4) on fd 59 [1:1:1]
[main] Setsockopt(1 1d 80d3000 4) on fd 60 [1:2:1]
[main] Setsockopt(1 2d 80d3000 de) on fd 61 [1:1:1]
[main] Setsockopt(1 10 80d3000 4) on fd 62 [1:1:1]
[main] Setsockopt(1 23 80d3000 b3) on fd 63 [16:3:4]
[main] Setsockopt(1 24 80d3000 52) on fd 64 [1:1:1]
[main] Setsockopt(10e 2 80d3000 a1) on fd 65 [16:2:16]
[main] Setsockopt(1 2e 80d3000 4) on fd 66 [1:5:1]
[main] Setsockopt(1 9 80d3000 4) on fd 67 [1:5:1]
[main] Setsockopt(1 b 80d3000 4) on fd 73 [1:2:1]
[main] Setsockopt(1 2 80d3000 4) on fd 75 [1:1:1]
[main] Setsockopt(1 e 80d3000 ab) on fd 77 [1:2:1]
[main] Setsockopt(1 21 80d3000 4) on fd 79 [1:1:1]
[main] Setsockopt(1 29 80d3000 3b) on fd 80 [16:3:0]
[main] Setsockopt(1 f 80d3000 4) on fd 81 [1:1:1]
[main] Setsockopt(1 12 80d3000 8b) on fd 82 [1:2:1]
[main] Setsockopt(1 1d 80d3000 4) on fd 83 [1:5:1]
[main] Setsockopt(1 2c 80d3000 b5) on fd 84 [1:5:1]
[main] Setsockopt(1 a 80d3000 4) on fd 86 [1:2:1]
[main] Setsockopt(1 5 80d3000 5a) on fd 87 [1:2:1]
[main] Setsockopt(1 2d 80d3000 7e) on fd 88 [1:5:1]
[main] Setsockopt(1 20 80d3000 4) on fd 90 [16:3:16]
[main] Setsockopt(1 b 80d3000 9b) on fd 92 [1:5:1]
[main] Setsockopt(1 c 80d3000 56) on fd 93 [1:1:1]
[main] Setsockopt(1 7 80d3000 4) on fd 95 [1:2:1]
[main] Setsockopt(1 21 80d3000 7) on fd 96 [1:5:1]
[main] Setsockopt(10e 5 80d3000 4) on fd 97 [16:2:15]
[main] Setsockopt(1 29 80d3000 4) on fd 98 [1:1:1]
[main] Setsockopt(1 21 80d3000 a9) on fd 100 [1:2:1]
[main] Setsockopt(1 2c 80d3000 4) on fd 101 [1:5:1]
[main] Setsockopt(10e 5 80d3000 4) on fd 102 [16:3:0]
[main] Setsockopt(10e 2 80d3000 a8) on fd 104 [16:3:16]
[main] Setsockopt(1 20 80d3000 b8) on fd 107 [1:1:1]
[main] Setsockopt(1 29 80d3000 25) on fd 109 [1:2:1]
[main] Setsockopt(1 b 80d3000 4) on fd 112 [1:5:1]
[main] Setsockopt(1 a 80d3000 4) on fd 113 [1:1:1]
[main] Setsockopt(1 21 80d3000 7) on fd 114 [1:5:1]
[main] Setsockopt(1 6 80d3000 4) on fd 116 [1:1:1]
[main] Setsockopt(1 12 80d3000 d8) on fd 117 [1:2:1]
[main] Setsockopt(1 15 80d3000 8) on fd 119 [1:5:1]
[main] Setsockopt(1 2f 80d3000 94) on fd 121 [16:3:16]
[main] Setsockopt(1 2d 80d3000 4) on fd 123 [1:2:1]
[main] Setsockopt(1 2c 80d3000 4) on fd 124 [16:2:4]
[main] Setsockopt(1 2d 80d3000 4) on fd 125 [1:1:1]
[main] Setsockopt(10e 3 80d3000 bf) on fd 126 [16:2:16]
[main] Setsockopt(1 8 80d3000 4) on fd 127 [1:5:1]
[main] Setsockopt(1 c 80d3000 86) on fd 128 [1:5:1]
[main] Setsockopt(1 21 80d3000 4) on fd 129 [1:2:1]
[main] Setsockopt(1 15 80d3000 8) on fd 136 [1:1:1]
[main] Setsockopt(10e 1 80d3000 4) on fd 137 [16:3:0]
[main] Setsockopt(1 22 80d3000 90) on fd 139 [1:1:1]
[main] Setsockopt(1 c 80d3000 4) on fd 143 [1:1:1]
[main] Setsockopt(1 23 80d3000 10) on fd 144 [1:5:1]
[main] Setsockopt(1 12 80d3000 4) on fd 147 [1:1:1]
[main] Setsockopt(1 25 80d3000 4) on fd 148 [16:2:15]
[main] Setsockopt(1 d 80d3000 8) on fd 149 [1:1:1]
[main] Setsockopt(1 2f 80d3000 4) on fd 150 [1:1:1]
[main] Setsockopt(1 e 80d3000 4) on fd 151 [1:2:1]
[main] Setsockopt(1 23 80d3000 4) on fd 152 [1:2:1]
[main] Setsockopt(1 29 80d3000 48) on fd 154 [1:5:1]
[main] Setsockopt(1 5 80d3000 4) on fd 155 [1:1:1]
[main] Setsockopt(10e 3 80d3000 4) on fd 156 [16:2:2]
[main] Setsockopt(1 1d 80d3000 6b) on fd 161 [1:1:1]
[main] Setsockopt(1 c 80d3000 4) on fd 162 [1:2:1]
[main] Setsockopt(1 2c 80d3000 28) on fd 163 [16:2:15]
[main] Setsockopt(1 24 80d3000 4) on fd 165 [16:3:15]
[main] Setsockopt(10e 5 80d3000 4) on fd 169 [16:2:4]
[main] Setsockopt(1 20 80d3000 4) on fd 171 [1:1:1]
[main] Setsockopt(1 9 80d3000 4) on fd 173 [1:1:1]
[main] Setsockopt(1 9 80d3000 58) on fd 175 [1:5:1]
[main] Setsockopt(1 8 80d3000 ac) on fd 177 [1:5:1]
[main] Setsockopt(1 8 80d3000 4) on fd 178 [1:1:1]
[main] Setsockopt(1 23 80d3000 3f) on fd 180 [1:2:1]
[main] Setsockopt(1 2c 80d3000 b2) on fd 181 [1:1:1]
[main] Setsockopt(1 b 80d3000 4) on fd 183 [1:5:1]
[main] Setsockopt(1 5 80d3000 d5) on fd 184 [1:2:1]
[main] Setsockopt(10e 4 80d3000 98) on fd 188 [16:3:16]
[main] Setsockopt(1 9 80d3000 8e) on fd 189 [1:2:1]
[main] Setsockopt(1 f 80d3000 4) on fd 191 [1:2:1]
[main] Setsockopt(1 f 80d3000 4) on fd 192 [1:1:1]
[main] Setsockopt(1 2c 80d3000 4) on fd 193 [1:5:1]
[main] Setsockopt(1 1d 80d3000 4) on fd 195 [1:1:1]
[main] Setsockopt(1 10 80d3000 79) on fd 196 [1:5:1]
[main] Setsockopt(1 1d 80d3000 3c) on fd 197 [16:3:4]
[main] Setsockopt(1 c 80d3000 4) on fd 199 [1:1:1]
[main] Setsockopt(10e 4 80d3000 8d) on fd 200 [16:3:15]
[main] Setsockopt(1 b 80d3000 4) on fd 203 [1:1:1]
[main] Setsockopt(1 2 80d3000 4) on fd 205 [1:1:1]
[main] Setsockopt(1 8 80d3000 4) on fd 206 [16:3:4]
[main] Setsockopt(1 b 80d3000 cb) on fd 208 [1:2:1]
[main] Setsockopt(1 2 80d3000 4) on fd 211 [1:5:1]
[main] Setsockopt(1 8 80d3000 4) on fd 213 [1:1:1]
[main] Setsockopt(1 2b 80d3000 40) on fd 215 [16:3:16]
[main] Setsockopt(1 23 80d3000 4) on fd 217 [1:2:1]
[main] Setsockopt(1 9 80d3000 4) on fd 219 [1:1:1]
[main] Setsockopt(10e 4 80d3000 3d) on fd 221 [16:3:4]
[main] Setsockopt(1 20 80d3000 e2) on fd 223 [1:2:1]
[main] Setsockopt(1 9 80d3000 4) on fd 224 [1:1:1]
[main] Setsockopt(1 e 80d3000 f8) on fd 225 [1:2:1]
[main] Setsockopt(10e 5 80d3000 4) on fd 226 [16:2:4]
[main] Setsockopt(1 d 80d3000 8) on fd 227 [1:5:1]
[main] Setsockopt(1 5 80d3000 4) on fd 228 [1:1:1]
[main] Setsockopt(1 10 80d3000 4) on fd 229 [1:2:1]
[main] Setsockopt(1 12 80d3000 4) on fd 230 [16:2:16]
[main] Setsockopt(1 2 80d3000 4) on fd 231 [1:1:1]
[main] Setsockopt(10e 3 80d3000 4) on fd 232 [16:2:0]
[main] Setsockopt(1 25 80d3000 8a) on fd 233 [1:2:1]
[main] Setsockopt(1 2e 80d3000 e) on fd 234 [1:2:1]
[main] Setsockopt(1 1d 80d3000 4) on fd 236 [1:2:1]
[main] Setsockopt(1 2f 80d3000 4) on fd 237 [16:3:4]
[main] Setsockopt(1 8 80d3000 f5) on fd 238 [1:5:1]
[main] Setsockopt(1 2b 80d3000 4) on fd 239 [1:2:1]
[main] Setsockopt(10e 5 80d3000 4) on fd 242 [16:2:2]
[main] Setsockopt(1 29 80d3000 94) on fd 243 [1:1:1]
[main] Setsockopt(1 12 80d3000 4) on fd 244 [1:5:1]
[main] Setsockopt(1 b 80d3000 45) on fd 246 [1:1:1]
[main] Setsockopt(1 8 80d3000 5) on fd 247 [1:2:1]
[main] Setsockopt(1 6 80d3000 4) on fd 252 [1:5:1]
[main] Setsockopt(1 9 80d3000 4) on fd 253 [16:2:0]
[main] Setsockopt(1 e 80d3000 3b) on fd 254 [1:5:1]
[main] Setsockopt(1 23 80d3000 94) on fd 256 [16:2:0]
[main] Setsockopt(1 7 80d3000 4) on fd 257 [1:1:1]
[main] Setsockopt(1 25 80d3000 2a) on fd 258 [1:2:1]
[main] Setsockopt(10e 4 80d3000 4) on fd 259 [16:2:16]
[main] Setsockopt(10e 3 80d3000 4) on fd 262 [16:3:2]
[main] Setsockopt(1 a 80d3000 4) on fd 263 [1:2:1]
[main] Setsockopt(1 2f 80d3000 b2) on fd 265 [1:1:1]
[main] Setsockopt(1 6 80d3000 4) on fd 267 [1:5:1]
[main] Setsockopt(1 9 80d3000 4) on fd 268 [1:1:1]
[main] Setsockopt(1 a 80d3000 13) on fd 270 [1:2:1]
[main] Setsockopt(1 a 80d3000 4) on fd 271 [1:2:1]
[main] Setsockopt(1 23 80d3000 4) on fd 272 [1:2:1]
[main] Setsockopt(1 23 80d3000 fd) on fd 273 [1:1:1]
[main] Setsockopt(10e 3 80d3000 67) on fd 275 [16:3:0]
[main] Setsockopt(1 2b 80d3000 4) on fd 277 [16:2:4]
[main] Setsockopt(1 23 80d3000 f2) on fd 278 [1:1:1]
[main] Setsockopt(1 2c 80d3000 4) on fd 280 [1:1:1]
[main] Setsockopt(1 c 80d3000 4) on fd 281 [1:1:1]
[main] Setsockopt(1 5 80d3000 4) on fd 282 [1:5:1]
[main] Setsockopt(1 2b 80d3000 48) on fd 283 [1:1:1]
[main] Setsockopt(1 9 80d3000 4) on fd 285 [1:2:1]
[main] Setsockopt(1 12 80d3000 16) on fd 287 [16:2:0]
[main] Setsockopt(10e 3 80d3000 4) on fd 290 [16:3:4]
[main] Setsockopt(1 2c 80d3000 4) on fd 291 [1:5:1]
[main] Setsockopt(1 23 80d3000 69) on fd 292 [16:3:4]
[main] Setsockopt(1 24 80d3000 10) on fd 293 [1:5:1]
[main] Setsockopt(10e 3 80d3000 4) on fd 294 [16:2:2]
[main] Setsockopt(1 2e 80d3000 71) on fd 295 [1:5:1]
[main] Setsockopt(10e 5 80d3000 4) on fd 297 [16:3:2]
[main] Setsockopt(1 7 80d3000 5) on fd 298 [1:5:1]
[main] Setsockopt(1 2c 80d3000 82) on fd 299 [1:2:1]
[main] Setsockopt(1 8 80d3000 a0) on fd 300 [1:5:1]
[main] Setsockopt(1 b 80d3000 4) on fd 302 [1:5:1]
[main] Setsockopt(1 f 80d3000 4) on fd 305 [1:5:1]
[main] Setsockopt(1 23 80d3000 4) on fd 306 [1:1:1]
[main] Setsockopt(10e 5 80d3000 4) on fd 307 [16:3:2]
[main] Setsockopt(1 2e 80d3000 85) on fd 308 [1:2:1]
[main] Setsockopt(1 2f 80d3000 4) on fd 315 [1:2:1]
[main] Setsockopt(1 e 80d3000 b2) on fd 318 [1:1:1]
[main] Setsockopt(1 f 80d3000 ee) on fd 319 [1:2:1]
[main] Setsockopt(10e 5 80d3000 27) on fd 327 [16:3:0]
[main] Setsockopt(1 a 80d3000 4) on fd 329 [1:1:1]
[main] Setsockopt(1 9 80d3000 4) on fd 330 [1:2:1]
[main] Setsockopt(1 6 80d3000 4) on fd 332 [1:1:1]
[main] Setsockopt(1 25 80d3000 4) on fd 334 [16:2:0]
[main] Setsockopt(1 2f 80d3000 4) on fd 335 [1:2:1]
[main] Setsockopt(1 24 80d3000 a4) on fd 339 [1:2:1]
[main] Setsockopt(1 29 80d3000 4) on fd 341 [1:1:1]
[main] Setsockopt(1 7 80d3000 74) on fd 342 [1:5:1]
[main] Setsockopt(1 25 80d3000 4) on fd 344 [1:2:1]
[main] Setsockopt(1 2d 80d3000 9e) on fd 346 [1:5:1]
[main] Setsockopt(1 2a 80d3000 81) on fd 348 [1:2:1]
[main] Setsockopt(1 2d 80d3000 4) on fd 350 [1:2:1]
[main] Setsockopt(1 b 80d3000 4) on fd 351 [1:2:1]
[main] Setsockopt(1 8 80d3000 4) on fd 352 [1:1:1]
[main] Setsockopt(1 28 80d3000 73) on fd 353 [16:2:0]
[main] Setsockopt(1 b 80d3000 78) on fd 356 [1:1:1]
[main] Setsockopt(1 2b 80d3000 5) on fd 358 [16:2:16]
[main] Setsockopt(1 2c 80d3000 4) on fd 359 [1:1:1]
[main] Setsockopt(1 7 80d3000 2e) on fd 360 [1:2:1]
[main] Setsockopt(1 21 80d3000 f7) on fd 363 [1:2:1]
[main] Setsockopt(1 e 80d3000 4) on fd 364 [1:1:1]
[main] Setsockopt(1 21 80d3000 2b) on fd 365 [1:2:1]
[main] Setsockopt(1 23 80d3000 23) on fd 368 [1:2:1]
[main] Setsockopt(1 29 80d3000 4) on fd 369 [1:2:1]
[main] Setsockopt(1 23 80d3000 4) on fd 371 [1:2:1]
[main] Setsockopt(1 f 80d3000 4) on fd 373 [1:1:1]
[main] Setsockopt(1 6 80d3000 e3) on fd 375 [1:1:1]
[main] Setsockopt(1 23 80d3000 4) on fd 376 [16:3:16]
[main] Setsockopt(1 d 80d3000 8) on fd 379 [1:1:1]
[main] 375 sockets created based on info from socket cachefile.
[main] Generating file descriptors
[main] Added 297 filenames from /dev
[main] Added 7683 filenames from /proc
[main] Added 16137 filenames from /sys
[child0:432] setuid16 (23) returned ENOSYS, marking as inactive.
[child0:432] kexec_load (283) returned ENOSYS, marking as inactive.
[child0:432] set_mempolicy (276) returned ENOSYS, marking as inactive.
[child0:432] mq_timedreceive (280) returned ENOSYS, marking as inactive.
[child0:432] getresuid16 (165) returned ENOSYS, marking as inactive.
[child0:432] getuid16 (24) returned ENOSYS, marking as inactive.
[child0:432] mq_timedsend (279) returned ENOSYS, marking as inactive.
[child0:432] ioprio_set (289) returned ENOSYS, marking as inactive.
[child0:432] setgroups16 (81) returned ENOSYS, marking as inactive.
[child0:432] uid changed! Was: 0, now 2147483129
[watchdog] [430] Watchdog exiting
[child1:433] child exiting.
Bailing main loop. Exit reason: UID changed.
[init] 
Ran 64 syscalls. Successes: 18  Failures: 45
[  206.249191] init: tty4 main process (425) terminated with status 1
[  206.250342] init: tty4 main process ended, respawning
[  206.369538] init: tty5 main process (426) terminated with status 1
[  206.370845] init: tty5 main process ended, respawning
[  206.448921] init: tty2 main process (427) terminated with status 1
[  206.463355] init: tty2 main process ended, respawning
[  206.479870] init: tty3 main process (428) terminated with status 1
[  206.481178] init: tty3 main process ended, respawning
[  206.503949] init: tty6 main process (429) terminated with status 1
[  206.505164] init: tty6 main process ended, respawning
[  211.929517] Writes:  Total: 4  Max/Min: 0/0   Fail: 0 
[  216.293653] init: tty4 main process (434) terminated with status 1
[  216.294561] init: tty4 main process ended, respawning
[  216.402146] init: tty5 main process (435) terminated with status 1
[  216.403053] init: tty5 main process ended, respawning
[  216.512841] init: tty2 main process (436) terminated with status 1
[  216.513766] init: tty2 main process ended, respawning
[  216.541597] init: tty3 main process (437) terminated with status 1
[  216.542503] init: tty3 main process ended, respawning
[  216.554569] init: tty6 main process (438) terminated with status 1
[  216.555492] init: tty6 main process ended, respawning
[  226.314452] init: tty4 main process (441) terminated with status 1
[  226.315720] init: tty4 main process ended, respawning
[  226.424440] init: tty5 main process (442) terminated with status 1
[  226.425825] init: tty5 main process ended, respawning
[  226.545567] init: tty2 main process (443) terminated with status 1
[  226.546773] init: tty2 main process ended, respawning
[  226.556096] init: tty3 main process (444) terminated with status 1
[  226.557337] init: tty3 main process ended, respawning
[  226.586220] init: tty6 main process (445) terminated with status 1
[  226.587215] init: tty6 main process ended, respawning
[  236.325061] init: tty4 main process (446) terminated with status 1
[  236.325734] init: tty4 main process ended, respawning
[  236.435326] init: tty5 main process (447) terminated with status 1
[  236.437069] init: tty5 main process ended, respawning
[  236.564516] init: tty2 main process (448) terminated with status 1
[  236.565213] init: tty2 main process ended, respawning
[  236.575253] init: tty3 main process (449) terminated with status 1
[  236.575958] init: tty3 main process ended, respawning
[  236.607209] init: tty6 main process (450) terminated with status 1
[  236.607951] init: tty6 main process ended, respawning
error: 'rc.local' exited outside the expected code flow.
[  240.877210] init: Failed to create pty - disabling logging for job
[  240.877968] init: Temporary process spawn error: No such file or directory
[  240.898302] init: rc main process (373) killed by TERM signal
[  240.904572] init: tty4 main process (451) killed by TERM signal
[  240.906783] init: tty5 main process (452) killed by TERM signal
[  240.908855] init: tty2 main process (453) killed by TERM signal
[  240.911019] init: tty3 main process (454) killed by TERM signal
[  240.913337] init: tty6 main process (455) killed by TERM signal
[  240.919689] init: hwclock-save main process (458) terminated with status 70
[  240.925065] init: plymouth-upstart-bridge main process (459) terminated with 
status 1
umount: /run/lock: not mounted
 * Will now restart
[  241.894718] Unregister pv shared memory for cpu 1
[  241.895400] Unregister pv shared memory for cpu 0
[  241.896170] spin_lock-torture: Unscheduled system shutdown detected
[  241.912745] torture thread torture_stutter parking due to system shutdown
[  241.914156] torture thread torture_shuffle parking due to system shutdown
[  241.940045] reboot: Restarting system
[  241.940598] reboot: machine restart

Elapsed time: 250

kvm=(
        qemu-system-x86_64
        -enable-kvm
        -cpu kvm64
        -kernel 
/pkg/linux/i386-randconfig-i0-201739/gcc-4.8/c394639389261e631779eb1ac94aaf1d16f566a5/vmlinuz-4.14.0-rc3-00002-gc394639
        -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-31,media=disk,if=virtio
        -drive file=/fs/sdc1/disk1-quantal-vp-31,media=disk,if=virtio
        -drive file=/fs/sdc1/disk2-quantal-vp-31,media=disk,if=virtio
        -drive file=/fs/sdc1/disk3-quantal-vp-31,media=disk,if=virtio
        -drive file=/fs/sdc1/disk4-quantal-vp-31,media=disk,if=virtio
        -drive file=/fs/sdc1/disk5-quantal-vp-31,media=disk,if=virtio
        -drive file=/fs/sdc1/disk6-quantal-vp-31,media=disk,if=virtio
        -pidfile /dev/shm/kboot/pid-quantal-vp-31
        -serial file:/dev/shm/kboot/quantal-vp-31/serial
        -serial file:/dev/shm/kboot/quantal-vp-31/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-i0-201739/wfg:unwind_frame-josh:c394639389261e631779eb1ac94aaf1d16f566a5/.vmlinuz-c394639389261e631779eb1ac94aaf1d16f566a5-20171009220139-265:quantal-vp-31
        branch=wfg/unwind_frame-josh
        
BOOT_IMAGE=/pkg/linux/i386-randconfig-i0-201739/gcc-4.8/c394639389261e631779eb1ac94aaf1d16f566a5/vmlinuz-4.14.0-rc3-00002-gc394639
        drbd.minor_count=8
)

"${kvm[@]}" -append "${append[*]}"

Reply via email to