Re: [PATCH 2/3 v3] x86: entry_64.S: always allocate complete "struct pt_regs"

From: Andrey Wagin
Date: Wed Feb 25 2015 - 07:37:31 EST

2015-02-13 0:54 GMT+03:00 Denys Vlasenko <dvlasenk@xxxxxxxxxx>:
> 64-bit code was using six stack slots less by not saving/restoring
> registers which are callee-preserved according to C ABI,
> and not allocating space for them.
> Only when syscall needed a complete "struct pt_regs",
> the complete area was allocated and filled in.
> As an additional twist, on interrupt entry a "slightly less truncated pt_regs"
> trick is used, to make nested interrupt stacks easier to unwind.
>
> This proved to be a source of significant obfuscation and subtle bugs.
> For example, stub_fork had to pop the return address,
> extend the struct, save registers, and push return address back. Ugly.
> ia32_ptregs_common pops return address and "returns" via jmp insn,
> throwing a wrench into CPU return stack cache.
>
> This patch changes code to always allocate a complete "struct pt_regs".
> The saving of registers is still done lazily.
>
> "Partial pt_regs" trick on interrupt stack is retained.
>
> Macros which manipulate "struct pt_regs" on stack are reworked:
> ALLOC_PT_GPREGS_ON_STACK allocates the structure.
> SAVE_C_REGS saves to it those registers which are clobbered by C code.
> SAVE_EXTRA_REGS saves to it all other registers.
> Corresponding RESTORE_* and REMOVE_PT_GPREGS_FROM_STACK macros reverse it.
>
> ia32_ptregs_common, stub_fork and friends lost their ugly dance with
> return pointer.
>
> LOAD_ARGS32 in ia32entry.S now uses symbolic stack offsets
> instead of magic numbers.
>
> error_entry and save_paranoid now use SAVE_C_REGS + SAVE_EXTRA_REGS
> instead of having it open-coded yet again.
>
> Patch was run-tested: 64-bit executables, 32-bit executables,
> strace works.
> Timing tests did not show measurable difference in 32-bit
> and 64-bit syscalls.

Hello Denys,

My test vm doesn't boot with this patch. Could you help to investigate
this issue?

I have attached a kernel config and console log.

[ 2.428124] systemd-journald[284]: Received request to flush
runtime journal from PID 1
[ 2.508252] traps: systemd-cgroups[380] general protection
ip:7f68ad096028 sp:7fffba298af8 error:0 in
ld-2.18.so[7f68ad07e000+20000][ OK
[ 2.600179] traps: systemd-cgroups[384] general protection
ip:7f11b9a9c028 sp:7fff4420f978 error:0 in
ld-2.18.so[7f11b9a84000+20000]
[ 2.743790] traps: systemd-cgroups[392] general protection
ip:7f7f40a44028 sp:7fffe1c1b8b8 error:0 in
ld-2.18.so[7f7f40a2c000+20000]
[ 2.754576] traps: systemd-cgroups[393] general protection
ip:7fd1314bd028 sp:7ffff76ecc88 error:0 in
ld-2.18.so[7fd1314a5000+20000]
[ 2.765343] traps: systemd-cgroups[396] general protection
ip:7ff4537b7028 sp:7fff05902378 error:0 in
ld-2.18.so[7ff45379f000+20000]
[ 2.798782] traps: systemd-cgroups[399] general protection
ip:7f4d5bc9c028 sp:7fff35cb3a48 error:0 in
ld-2.18.so[7f4d5bc84000+20000]
[ 3.376298] Kernel panic - not syncing: Attempted to kill init!
exitcode=0x0000000b
[ 3.376298]
[ 3.377199] CPU: 2 PID: 1 Comm: systemd Not tainted 3.19.0+ #169
[ 3.377199] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011
[ 3.377199] 0000000000000000 00000000302f3f16 ffff88007c88bc48
ffffffff817b2f1a
[ 3.377199] 0000000000000000 ffffffff81a348f8 ffff88007c88bcc8
ffffffff817b14d8
[ 3.377199] ffff880000000010 ffff88007c88bcd8 ffff88007c88bc78
00000000302f3f16
[ 3.377199] Call Trace:
[ 3.377199] [<ffffffff817b2f1a>] dump_stack+0x45/0x57
[ 3.377199] [<ffffffff817b14d8>] panic+0xd5/0x20e
[ 3.377199] [<ffffffff8109c855>] do_exit+0xb15/0xb20
[ 3.377199] [<ffffffff8109c8fe>] do_group_exit+0x4e/0xc0
[ 3.377199] [<ffffffff810aa751>] get_signal+0x271/0x860
[ 3.377199] [<ffffffff81015547>] do_signal+0x37/0x760
[ 3.377199] [<ffffffff810cc850>] ? wake_up_state+0x20/0x20
[ 3.377199] [<ffffffff817bbb2c>] ? int_very_careful+0x5/0xd
[ 3.377199] [<ffffffff810ee08d>] ? trace_hardirqs_on_caller+0x13d/0x1e0
[ 3.377199] [<ffffffff81015cd0>] do_notify_resume+0x60/0x70
[ 3.377199] [<ffffffff817bbb7f>] int_signal+0x12/0x17
[ 3.377199] Kernel Offset: 0x0 from 0xffffffff81000000 (relocation
range: 0xffffffff80000000-0xffffffff9fffffff)
[ 3.377199] ---[ end Kernel panic - not syncing: Attempted to kill
init! exitcode=0x0000000b
[ 3.377199]


[avagin@localhost linux-2.6]$ git bisect log
# bad: [549c45cea13c1b1d4557dec2e5e3f256615682f6] Add linux-next
specific files for 20150224
# good: [c517d838eb7d07bbe9507871fab3931deccff539] Linux 4.0-rc1
git bisect start 'next-20150224' 'v4.0-rc1'
# good: [bb37267e803b5d88eca99db1f501cb0410de60ff] Merge
remote-tracking branch 'integrity/next'
git bisect good bb37267e803b5d88eca99db1f501cb0410de60ff
# good: [e6dad2c669e2029ee71bd05e858f69e12906dbfc] ia64: use %*pb[l]
to print bitmaps including cpumasks and nodemasks
git bisect good e6dad2c669e2029ee71bd05e858f69e12906dbfc
# good: [e1a0636f6f12948e8e64afe107bda7cb189ef938] Merge
remote-tracking branch 'kselftest/next'
git bisect good e1a0636f6f12948e8e64afe107bda7cb189ef938
# good: [e39b37bdf3aeda8fed17aa7dff42a6fecfc4f262] fs/ufs/super.c: fix
potential race condition
git bisect good e39b37bdf3aeda8fed17aa7dff42a6fecfc4f262
# good: [97dc3f62c8f37795cdee3001e86c346dd7f7a879] scripts/gdb: add
internal helper and convenience function for per-cpu lookup
git bisect good 97dc3f62c8f37795cdee3001e86c346dd7f7a879
# bad: [a2dc0f333a3dd8eba791afc848623c0a708ea2e4] Merge
remote-tracking branch 'livepatching/for-next'
git bisect bad a2dc0f333a3dd8eba791afc848623c0a708ea2e4
# bad: [82bbadb13ef4b3e2217a2fe297be768caf473314] x86, entry: Remove
int_check_syscall_exit_work
git bisect bad 82bbadb13ef4b3e2217a2fe297be768caf473314
# bad: [0bc5dd63915de8bac63ef63f6e75c3fecd0838d2] x86: entry_64.S:
always allocate complete "struct pt_regs"
git bisect bad 0bc5dd63915de8bac63ef63f6e75c3fecd0838d2
# good: [2202eb90f175cf45d1b2d1c64dbb5676a8ad07ad] x86: introduce
push/pop macros which generate CFI_REL_OFFSET and CFI_RESTORE
git bisect good 2202eb90f175cf45d1b2d1c64dbb5676a8ad07ad
# good: [f5e1c4084319a42e5f14d41e2d638949ce66bc08] x86: entry_64.S:
fix wrong symbolic constant usage: R11->ARGOFFSET
git bisect good f5e1c4084319a42e5f14d41e2d638949ce66bc08
# first bad commit: [0bc5dd63915de8bac63ef63f6e75c3fecd0838d2] x86:
entry_64.S: always allocate complete "struct pt_regs"

Attachment: .config
Description: Binary data

[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Initializing cgroup subsys cpuacct
[ 0.000000] Linux version 3.19.0+ (avagin@xxxxxxxxxxxxxxxxxxxxx) (gcc version 4.9.2 20150212 (Red Hat 4.9.2-6) (GCC) ) #169 SMP Wed Feb 25 15:20:44 MSK 2015
[ 0.000000] Command line: BOOT_IMAGE=/vmlinuz-3.19.0+ root=UUID=92dcf04a-50ae-458c-851c-6aa51a139db7 ro rd.md=0 rd.lvm=0 rd.dm=0 rd.luks=0 vconsole.keymap=us console=ttyS0,115200 LANG=en_US.UTF-8
[ 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-0x000000007fffdfff] usable
[ 0.000000] BIOS-e820: [mem 0x000000007fffe000-0x000000007fffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[ 0.000000] NX (Execute Disable) protection: active
[ 0.000000] SMBIOS 2.4 present.
[ 0.000000] Hypervisor detected: KVM
[ 0.000000] e820: last_pfn = 0x7fffe max_arch_pfn = 0x400000000
[ 0.000000] PAT not supported by CPU.
[ 0.000000] found SMP MP-table at [mem 0x000f1e20-0x000f1e2f] mapped at [ffff8800000f1e20]
[ 0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff]
[ 0.000000] init_memory_mapping: [mem 0x7fc00000-0x7fdfffff]
[ 0.000000] init_memory_mapping: [mem 0x60000000-0x7fbfffff]
[ 0.000000] init_memory_mapping: [mem 0x40000000-0x5fffffff]
[ 0.000000] init_memory_mapping: [mem 0x00100000-0x3fffffff]
[ 0.000000] init_memory_mapping: [mem 0x7fe00000-0x7fffdfff]
[ 0.000000] RAMDISK: [mem 0x36d62000-0x376a8fff]
[ 0.000000] ACPI: Early table checksum verification disabled
[ 0.000000] ACPI: RSDP 0x00000000000F1C50 000014 (v00 BOCHS )
[ 0.000000] ACPI: RSDT 0x000000007FFFE340 000034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: FACP 0x000000007FFFFF80 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001)
[ 0.000000] ACPI: DSDT 0x000000007FFFE380 001135 (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: FACS 0x000000007FFFFF40 000040
[ 0.000000] ACPI: SSDT 0x000000007FFFF600 00093C (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: APIC 0x000000007FFFF500 000090 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001)
[ 0.000000] ACPI: HPET 0x000000007FFFF4C0 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001)
[ 0.000000] No NUMA configuration found
[ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000007fffdfff]
[ 0.000000] NODE_DATA(0) allocated [mem 0x7ffe9000-0x7fffdfff]
[ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[ 0.000000] kvm-clock: cpu 0, msr 0:7ffe8001, primary cpu clock
[ 0.000000] Zone ranges:
[ 0.000000] DMA [mem 0x00001000-0x00ffffff]
[ 0.000000] DMA32 [mem 0x01000000-0x7fffdfff]
[ 0.000000] Normal empty
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x00001000-0x0009efff]
[ 0.000000] node 0: [mem 0x00100000-0x7fffdfff]
[ 0.000000] Initmem setup node 0 [mem 0x00001000-0x7fffdfff]
[ 0.000000] ACPI: PM-Timer IO Port: 0xb008
[ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x02] enabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x03] enabled)
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[ 0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0])
[ 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] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[ 0.000000] Using ACPI (MADT) for SMP configuration information
[ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs
[ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[ 0.000000] e820: [mem 0x80000000-0xfeffbfff] available for PCI devices
[ 0.000000] Booting paravirtualized kernel on KVM
[ 0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:4 nr_node_ids:1
[ 0.000000] PERCPU: Embedded 32 pages/cpu @ffff88007fc00000 s91840 r8192 d31040 u524288
[ 0.000000] KVM setup async PF for cpu 0
[ 0.000000] kvm-stealtime: cpu 0, msr 7fc0e440
[ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 515975
[ 0.000000] Policy zone: DMA32
[ 0.000000] Kernel command line: BOOT_IMAGE=/vmlinuz-3.19.0+ root=UUID=92dcf04a-50ae-458c-851c-6aa51a139db7 ro rd.md=0 rd.lvm=0 rd.dm=0 rd.luks=0 vconsole.keymap=us console=ttyS0,115200 LANG=en_US.UTF-8
[ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[ 0.000000] Memory: 2023852K/2096752K available (7939K kernel code, 1305K rwdata, 3240K rodata, 1468K init, 14188K bss, 72900K reserved, 0K cma-reserved)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] Hierarchical RCU implementation.
[ 0.000000] RCU lockdep checking is enabled.
[ 0.000000] RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4.
[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[ 0.000000] Running RCU self tests
[ 0.000000] NR_IRQS:4352 nr_irqs:456 16
[ 0.000000] Offload RCU callbacks from all CPUs
[ 0.000000] Offload RCU callbacks from CPUs: 0-3.
[ 0.000000] Console: colour VGA+ 80x25
[ 0.000000] console [ttyS0] enabled
[ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 0.000000] ... MAX_LOCK_DEPTH: 48
[ 0.000000] ... MAX_LOCKDEP_KEYS: 8191
[ 0.000000] ... CLASSHASH_SIZE: 4096
[ 0.000000] ... MAX_LOCKDEP_ENTRIES: 32768
[ 0.000000] ... MAX_LOCKDEP_CHAINS: 65536
[ 0.000000] ... CHAINHASH_SIZE: 32768
[ 0.000000] memory used by lock dependency info: 8159 kB
[ 0.000000] per task-struct memory footprint: 1920 bytes
[ 0.000000] tsc: Detected 2491.904 MHz processor
[ 0.002000] Calibrating delay loop (skipped) preset value.. 4983.80 BogoMIPS (lpj=2491904)
[ 0.002271] pid_max: default: 32768 minimum: 301
[ 0.002706] ACPI: Core revision 20141107
[ 0.005049] ACPI: All ACPI Tables successfully acquired
[ 0.005662] Security Framework initialized
[ 0.006009] SELinux: Initializing.
[ 0.007012] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes)
[ 0.008332] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes)
[ 0.009164] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes)
[ 0.009787] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes)
[ 0.011831] Initializing cgroup subsys memory
[ 0.012014] Initializing cgroup subsys devices
[ 0.012445] Initializing cgroup subsys freezer
[ 0.013024] Initializing cgroup subsys net_cls
[ 0.013440] Initializing cgroup subsys blkio
[ 0.014007] Initializing cgroup subsys perf_event
[ 0.014561] Initializing cgroup subsys net_prio
[ 0.015008] Initializing cgroup subsys hugetlb
[ 0.015534] mce: CPU supports 10 MCE banks
[ 0.016040] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 0.016040] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 0.017348] Freeing SMP alternatives memory: 24K (ffffffff81eb7000 - ffffffff81ebd000)
[ 0.021651] ftrace: allocating 26766 entries in 105 pages
[ 0.029668] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 0.031000] smpboot: CPU0: Intel QEMU Virtual CPU version 2.1.3 (fam: 06, model: 02, stepping: 03)
[ 0.031234] Performance Events: unsupported p6 CPU model 2 no PMU driver, software events only.
[ 0.033701] NMI watchdog: disabled (cpu0): hardware events not enabled
[ 0.034374] x86: Booting SMP configuration:
[ 0.035003] .... node #0, CPUs: #1
[ 0.002000] kvm-clock: cpu 1, msr 0:7ffe8041, secondary cpu clock
[ 0.049090] KVM setup async PF for cpu 1
[ 0.049820] #2
[ 0.049820] kvm-stealtime: cpu 1, msr 7fc8e440
[ 0.002000] kvm-clock: cpu 2, msr 0:7ffe8081, secondary cpu clock
[ 0.062051] KVM setup async PF for cpu 2
[ 0.062789] #3
[ 0.062789] kvm-stealtime: cpu 2, msr 7fd0e440
[ 0.002000] kvm-clock: cpu 3, msr 0:7ffe80c1, secondary cpu clock
[ 0.076069] x86: Booted up 1 node, 4 CPUs
[ 0.076047] KVM setup async PF for cpu 3
[ 0.076051] kvm-stealtime: cpu 3, msr 7fd8e440
[ 0.077004] smpboot: Total of 4 processors activated (19935.23 BogoMIPS)
[ 0.079568] devtmpfs: initialized
[ 0.083153] atomic64_test: passed for x86-64 platform with CX8 and with SSE
[ 0.083761] pinctrl core: initialized pinctrl subsystem
[ 0.084267] RTC time: 12:24:47, date: 02/25/15
[ 0.085317] NET: Registered protocol family 16
[ 0.089113] cpuidle: using governor menu
[ 0.089686] ACPI: bus type PCI registered
[ 0.090003] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[ 0.091062] PCI: Using configuration type 1 for base access
[ 0.099193] ACPI: Added _OSI(Module Device)
[ 0.099642] ACPI: Added _OSI(Processor Device)
[ 0.100004] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.100469] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.105832] ACPI: Interpreter enabled
[ 0.106011] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S1_] (20141107/hwxface-580)
[ 0.107224] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20141107/hwxface-580)
[ 0.108260] ACPI: (supports S0 S3 S4 S5)
[ 0.108650] ACPI: Using IOAPIC for interrupt routing
[ 0.109034] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.116197] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.116797] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[ 0.117051] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[ 0.118170] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[ 0.120664] acpiphp: Slot [3] registered
[ 0.121051] acpiphp: Slot [4] registered
[ 0.121459] acpiphp: Slot [5] registered
[ 0.121885] acpiphp: Slot [6] registered
[ 0.122043] acpiphp: Slot [7] registered
[ 0.122475] acpiphp: Slot [8] registered
[ 0.123036] acpiphp: Slot [9] registered
[ 0.123450] acpiphp: Slot [10] registered
[ 0.124041] acpiphp: Slot [11] registered
[ 0.124464] acpiphp: Slot [12] registered
[ 0.124897] acpiphp: Slot [13] registered
[ 0.125035] acpiphp: Slot [14] registered
[ 0.125467] acpiphp: Slot [15] registered
[ 0.126035] acpiphp: Slot [16] registered
[ 0.126462] acpiphp: Slot [17] registered
[ 0.127061] acpiphp: Slot [18] registered
[ 0.127482] acpiphp: Slot [19] registered
[ 0.128017] acpiphp: Slot [20] registered
[ 0.128442] acpiphp: Slot [21] registered
[ 0.128878] acpiphp: Slot [22] registered
[ 0.129036] acpiphp: Slot [23] registered
[ 0.129467] acpiphp: Slot [24] registered
[ 0.130035] acpiphp: Slot [25] registered
[ 0.130446] acpiphp: Slot [26] registered
[ 0.131024] acpiphp: Slot [27] registered
[ 0.131450] acpiphp: Slot [28] registered
[ 0.131884] acpiphp: Slot [29] registered
[ 0.132056] acpiphp: Slot [30] registered
[ 0.132490] acpiphp: Slot [31] registered
[ 0.133012] PCI host bridge to bus 0000:00
[ 0.133407] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.134004] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7]
[ 0.134601] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff]
[ 0.135003] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff]
[ 0.135668] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff]
[ 0.144027] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
[ 0.145003] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
[ 0.145626] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
[ 0.146003] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
[ 0.154250] pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI
[ 0.154936] pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by PIIX4 SMB
[ 0.215311] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[ 0.216079] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[ 0.216817] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[ 0.217281] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[ 0.218235] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[ 0.219513] ACPI: Enabled 16 GPEs in block 00 to 0F
[ 0.220297] vgaarb: setting as boot device: PCI:0000:00:02.0
[ 0.220632] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[ 0.221006] vgaarb: loaded
[ 0.222003] vgaarb: bridge control possible 0000:00:02.0
[ 0.222641] SCSI subsystem initialized
[ 0.223124] ACPI: bus type USB registered
[ 0.223573] usbcore: registered new interface driver usbfs
[ 0.224024] usbcore: registered new interface driver hub
[ 0.225046] usbcore: registered new device driver usb
[ 0.225673] PCI: Using ACPI for IRQ routing
[ 0.226658] NetLabel: Initializing
[ 0.227003] NetLabel: domain hash size = 128
[ 0.227417] NetLabel: protocols = UNLABELED CIPSOv4
[ 0.228050] NetLabel: unlabeled traffic allowed by default
[ 0.228686] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[ 0.229016] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[ 0.230080] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[ 0.234079] Switched to clocksource kvm-clock
[ 0.265138] pnp: PnP ACPI init
[ 0.266332] pnp: PnP ACPI: found 5 devices
[ 0.278469] NET: Registered protocol family 2
[ 0.279371] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
[ 0.280566] TCP bind hash table entries: 16384 (order: 8, 1048576 bytes)
[ 0.282091] TCP: Hash tables configured (established 16384 bind 16384)
[ 0.282771] TCP: reno registered
[ 0.283128] UDP hash table entries: 1024 (order: 5, 163840 bytes)
[ 0.283840] UDP-Lite hash table entries: 1024 (order: 5, 163840 bytes)
[ 0.284719] NET: Registered protocol family 1
[ 0.285175] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[ 0.285764] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[ 0.286338] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[ 0.287459] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
[ 0.289171] Unpacking initramfs...
[ 0.448445] Freeing initrd memory: 9500K (ffff880036d62000 - ffff8800376a9000)
[ 0.451876] futex hash table entries: 1024 (order: 5, 131072 bytes)
[ 0.452560] Initialise system trusted keyring
[ 0.453095] audit: initializing netlink subsys (disabled)
[ 0.453665] audit: type=2000 audit(1424867087.915:1): initialized
[ 0.454804] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[ 0.460277] zpool: loaded
[ 0.460562] zbud: loaded
[ 0.461694] VFS: Disk quotas dquot_6.5.2
[ 0.462218] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 0.464532] Key type big_key registered
[ 0.467597] alg: No test for stdrng (krng)
[ 0.468064] NET: Registered protocol family 38
[ 0.468538] Key type asymmetric registered
[ 0.469071] Asymmetric key parser 'x509' registered
[ 0.469900] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[ 0.471073] io scheduler noop registered
[ 0.471674] io scheduler deadline registered
[ 0.472483] io scheduler cfq registered (default)
[ 0.473344] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[ 0.473994] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[ 0.474883] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[ 0.475635] ACPI: Power Button [PWRF]
[ 0.476706] GHES: HEST is not enabled!
[ 0.477762] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 10
[ 0.480284] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10
[ 0.482318] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11
[ 0.484051] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 0.507927] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[ 0.514033] Non-volatile memory driver v1.3
[ 0.516820] vda: vda1
[ 0.519861] scsi host0: ata_piix
[ 0.520703] scsi host1: ata_piix
[ 0.521145] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0c0 irq 14
[ 0.522065] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0c8 irq 15
[ 0.522971] libphy: Fixed MDIO Bus: probed
[ 0.523884] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 0.524528] ehci-pci: EHCI PCI platform driver
[ 0.524986] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 0.525592] ohci-pci: OHCI PCI platform driver
[ 0.526058] uhci_hcd: USB Universal Host Controller Interface driver
[ 0.528212] uhci_hcd 0000:00:01.2: UHCI Host Controller
[ 0.529181] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1
[ 0.529969] uhci_hcd 0000:00:01.2: detected 2 ports
[ 0.530568] uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c040
[ 0.531556] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001
[ 0.532245] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 0.532959] usb usb1: Product: UHCI Host Controller
[ 0.533472] usb usb1: Manufacturer: Linux 3.19.0+ uhci_hcd
[ 0.533994] usb usb1: SerialNumber: 0000:00:01.2
[ 0.535167] hub 1-0:1.0: USB hub found
[ 0.535615] hub 1-0:1.0: 2 ports detected
[ 0.536752] usbcore: registered new interface driver usbserial
[ 0.537341] usbcore: registered new interface driver usbserial_generic
[ 0.538084] usbserial: USB Serial support registered for generic
[ 0.538758] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[ 0.540327] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 0.540978] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 0.542002] mousedev: PS/2 mouse device common for all mice
[ 0.543550] rtc_cmos 00:00: RTC can wake from S4
[ 0.544435] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[ 0.544803] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[ 0.545089] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs
[ 0.545376] device-mapper: uevent: version 1.0.3
[ 0.545686] device-mapper: ioctl: 4.29.0-ioctl (2014-10-28) initialised: dm-devel@xxxxxxxxxx
[ 0.548525] hidraw: raw HID events driver (C) Jiri Kosina
[ 0.548919] usbcore: registered new interface driver usbhid
[ 0.548920] usbhid: USB HID core driver
[ 0.549024] drop_monitor: Initializing network drop monitor service
[ 0.549257] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 0.551065] TCP: cubic registered
[ 0.551075] Initializing XFRM netlink socket
[ 0.551611] NET: Registered protocol family 10
[ 0.552453] mip6: Mobile IPv6
[ 0.552476] NET: Registered protocol family 17
[ 0.553735] Loading compiled-in X.509 certificates
[ 0.555047] Loaded X.509 cert 'Magrathea: Glacier signing key: 186426f37a9cad9c2f80b301b81beedeabae47f5'
[ 0.555097] registered taskstats version 1
[ 0.556205] Magic number: 3:500:431
[ 0.556206] hash matches drivers/base/power/main.c:480
[ 0.556953] rtc_cmos 00:00: setting system clock to 2015-02-25 12:24:47 UTC (1424867087)
[ 0.675840] ata1.00: ATA-7: QEMU HARDDISK, 2.1.3, max UDMA/100
[ 0.677452] ata1.00: 41943040 sectors, multi 16: LBA48
[ 0.679849] ata1.00: configured for MWDMA2
[ 0.682567] scsi 0:0:0:0: Direct-Access ATA QEMU HARDDISK 3 PQ: 0 ANSI: 5
[ 0.688494] sd 0:0:0:0: [sda] 41943040 512-byte logical blocks: (21.4 GB/20.0 GiB)
[ 0.688554] sd 0:0:0:0: Attached scsi generic sg0 type 0
[ 0.691699] sd 0:0:0:0: [sda] Write Protect is off
[ 0.692939] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 0.698177] sda: sda1 sda2 sda3
[ 0.702710] sd 0:0:0:0: [sda] Attached SCSI disk
[ 0.705634] Freeing unused kernel memory: 1468K (ffffffff81d48000 - ffffffff81eb7000)
[ 0.707104] Write protecting the kernel read-only data: 12288k
[ 0.709092] Freeing unused kernel memory: 240K (ffff8800017c4000 - ffff880001800000)
[ 0.711252] Freeing unused kernel memory: 856K (ffff880001b2a000 - ffff880001c00000)
[ 0.724818] systemd[1]: [/etc/systemd/system.conf:28] Unknown lvalue 'DefaultCPUAccounting' in section 'Manager'
[ 0.732475] systemd[1]: systemd 208 running in system mode. (+PAM +LIBWRAP +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ)
[ 0.735573] systemd[1]: Detected virtualization 'kvm'.
[ 0.736854] systemd[1]: Running in initial RAM disk.
[ 0.741564] systemd[1]: Set hostname to <avagin-fc19-cr>.
[ 0.753272] random: systemd urandom read with 15 bits of entropy available
[ 0.813534] systemd[1]: Expecting device dev-disk-by\x2duuid-92dcf04a\x2d50ae\x2d458c\x2d851c\x2d6aa51a139db7.device...
[ 0.815907] systemd[1]: Starting -.slice.
[ 0.818175] systemd[1]: Created slice -.slice.
[ 0.818670] systemd[1]: Starting System Slice.
[ 0.820111] systemd[1]: Created slice System Slice.
[ 0.820602] systemd[1]: Starting Slices.
[ 0.821682] systemd[1]: Reached target Slices.
[ 0.822223] systemd[1]: Starting Timers.
[ 0.823261] systemd[1]: Reached target Timers.
[ 0.823714] systemd[1]: Starting Journal Socket.
[ 0.825068] systemd[1]: Listening on Journal Socket.
[ 0.825895] systemd[1]: Starting dracut cmdline hook...
[ 0.828338] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[ 0.831483] systemd[1]: Starting Setup Virtual Console...
[ 0.833838] systemd[1]: Starting Journal Service...
[ 0.839129] systemd[1]: Started Journal Service.
[ 0.923438] systemd-udevd[151]: starting version 208
[ 1.185038] PM: Starting manual resume from disk
[ 1.213086] EXT4-fs (sda3): INFO: recovery required on readonly filesystem
[ 1.213766] EXT4-fs (sda3): write access will be enabled during recovery
[ 1.241453] EXT4-fs (sda3): recovery complete
[ 1.242972] EXT4-fs (sda3): mounted filesystem with ordered data mode. Opts: (null)
[ 1.416228] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[ 1.451294] tsc: Refined TSC clocksource calibration: 2491.847 MHz
[ 1.473032] systemd-journald[108]: Received SIGTERM
[ 1.557945] SELinux: Disabled at runtime.
[ 1.582139] audit: type=1404 audit(1424867088.525:2): selinux=0 auid=4294967295 ses=4294967295
[ 1.595957] systemd[1]: [/etc/systemd/system.conf:28] Unknown lvalue 'DefaultCPUAccounting' in section 'Manager'
[ 1.892650] random: nonblocking pool is initialized
[ 1.939923] systemd-fsck[283]: /dev/sda3: clean, 280790/1022000 files, 3712499/4082432 blocks

[ 1.955153] systemd-udevd[298]: starting version 208
[ 1.978584] EXT4-fs (sda3): re-mounted. Opts: (null)
[ 2.219244] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 0
[ 2.245492] microcode: CPU0 sig=0x623, pf=0x0, revision=0x1
[ 2.248544] microcode: CPU1 sig=0x623, pf=0x0, revision=0x1
[ 2.249514] microcode: CPU2 sig=0x623, pf=0x0, revision=0x1
[ 2.250631] microcode: CPU3 sig=0x623, pf=0x0, revision=0x1
[ 2.252063] microcode: Microcode Update Driver: v2.00 <tigran@xxxxxxxxxxxxxxxxxxxx>, Peter Oruba
[ 2.344453] systemd-fsck[348]: /dev/sda1: recovering journal

[ 2.384577] Adding 4128764k swap on /dev/sda2. Priority:-1 extents:1 across:4128764k FS
[ 2.393586] systemd-fsck[348]: /dev/sda1: clean, 384/128016 files, 399662/512000 blocks

[ 2.415224] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
[ 2.428124] systemd-journald[284]: Received request to flush runtime journal from PID 1
[ 2.508252] traps: systemd-cgroups[380] general protection ip:7f68ad096028 sp:7fffba298af8 error:0 in ld-2.18.so[7f68ad07e000+20000][ OK 
[ 2.600179] traps: systemd-cgroups[384] general protection ip:7f11b9a9c028 sp:7fff4420f978 error:0 in ld-2.18.so[7f11b9a84000+20000]
[ 2.743790] traps: systemd-cgroups[392] general protection ip:7f7f40a44028 sp:7fffe1c1b8b8 error:0 in ld-2.18.so[7f7f40a2c000+20000]
[ 2.754576] traps: systemd-cgroups[393] general protection ip:7fd1314bd028 sp:7ffff76ecc88 error:0 in ld-2.18.so[7fd1314a5000+20000]
[ 2.765343] traps: systemd-cgroups[396] general protection ip:7ff4537b7028 sp:7fff05902378 error:0 in ld-2.18.so[7ff45379f000+20000]
[ 2.798782] traps: systemd-cgroups[399] general protection ip:7f4d5bc9c028 sp:7fff35cb3a48 error:0 in ld-2.18.so[7f4d5bc84000+20000]
[ 3.376298] Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b
[ 3.376298]
[ 3.377199] CPU: 2 PID: 1 Comm: systemd Not tainted 3.19.0+ #169
[ 3.377199] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011
[ 3.377199] 0000000000000000 00000000302f3f16 ffff88007c88bc48 ffffffff817b2f1a
[ 3.377199] 0000000000000000 ffffffff81a348f8 ffff88007c88bcc8 ffffffff817b14d8
[ 3.377199] ffff880000000010 ffff88007c88bcd8 ffff88007c88bc78 00000000302f3f16
[ 3.377199] Call Trace:
[ 3.377199] [<ffffffff817b2f1a>] dump_stack+0x45/0x57
[ 3.377199] [<ffffffff817b14d8>] panic+0xd5/0x20e
[ 3.377199] [<ffffffff8109c855>] do_exit+0xb15/0xb20
[ 3.377199] [<ffffffff8109c8fe>] do_group_exit+0x4e/0xc0
[ 3.377199] [<ffffffff810aa751>] get_signal+0x271/0x860
[ 3.377199] [<ffffffff81015547>] do_signal+0x37/0x760
[ 3.377199] [<ffffffff810cc850>] ? wake_up_state+0x20/0x20
[ 3.377199] [<ffffffff817bbb2c>] ? int_very_careful+0x5/0xd
[ 3.377199] [<ffffffff810ee08d>] ? trace_hardirqs_on_caller+0x13d/0x1e0
[ 3.377199] [<ffffffff81015cd0>] do_notify_resume+0x60/0x70
[ 3.377199] [<ffffffff817bbb7f>] int_signal+0x12/0x17
[ 3.377199] Kernel Offset: 0x0 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffff9fffffff)
[ 3.377199] ---[ end Kernel panic - not syncing: Attempted to kill init! exitcode=0x0000000b
[ 3.377199]