$ adt-run -l adt-boot.log -d /tmp/tmp.lXjW2N5fd3/lava-server_2016.4-1.dsc -U --- qemu --debug --show-boot adt-sid-2.raw
$ adt-run -l adt-orig-image.log -d /tmp/tmp.lXjW2N5fd3/lava-server_2016.4-1.dsc -U --- qemu --debug --show-boot adt-sid.img So adt-orig-image.log uses the same image as I have been using for some time - it's just that as it's using unstable, I update/upgrade it manually between tests (otherwise adt reboots during the test and slows things down). It looks now like a change in what adt is expecting at the boot prompt. -- Neil Williams ============= http://www.linux.codehelp.co.uk/
adt-run: DBG: testbed init
adt-run [16:57:33]: version 3.20.4
adt-run [16:57:33]: host sylvester; command line: /usr/bin/adt-run -l adt-boot.log -d /tmp/tmp.lXjW2N5fd3/lava-server_2016.4-1.dsc -U --- qemu --debug --show-boot adt-sid-2.raw
adt-run: DBG: got reply from testbed: ok
adt-run: DBG: testbed open, scratch=None
adt-run: DBG: sending command to testbed: open
adt-virt-qemu: DBG: executing open
adt-virt-qemu: DBG: Creating temporary overlay image in /tmp/adt-virt-qemu.rj4nwrzx/overlay.img
adt-virt-qemu: DBG: execute-timeout: qemu-img create -f qcow2 -b /home/neil/code/lava/packaging/alioth/adt-run-qemu/adt-sid-2.raw /tmp/adt-virt-qemu.rj4nwrzx/overlay.img
adt-virt-qemu: DBG: Detected KVM capable Intel host CPU, enabling nested KVM
adt-virt-qemu: DBG: find_free_port: trying 10022
adt-virt-qemu: DBG: find_free_port: 10022 is free
adt-virt-qemu: DBG: Forwarding local port 10022 to VM ssh port 22
adt-virt-qemu: DBG: expect: " login: "
warning: host doesn't support requested feature: CPUID.01H:ECX.vmx [bit 5]
[H[J[1;1H[?25l[m[H[J[1;1H[2;25HGNU GRUB version 2.02~beta2-36
[m[4;2H+----------------------------------------------------------------------------+[5;2H|[5;79H|[6;2H|[6;79H|[7;2H|[7;79H|[8;2H|[8;79H|[9;2H|[9;79H|[10;2H|[10;79H|[11;2H|[11;79H|[12;2H|[12;79H|[13;2H|[13;79H|[14;2H|[14;79H|[15;2H|[15;79H|[16;2H|[16;79H|[17;2H+----------------------------------------------------------------------------+[m[18;2H[19;2H[m Use the ^ and v keys to select which entry is highlighted.
Press enter to boot the selected OS, `e' to edit the commands
before booting or `c' for a command-line. [5;80H [7m[5;3H*Debian GNU/Linux [m[5;78H[m[m[6;3H Advanced options for Debian GNU/Linux [m[6;78H[m[m[7;3H [m[7;78H[m[m[8;3H [m[8;78H[m[m[9;3H [m[9;78H[m[m[10;3H [m[10;78H[m[m[11;3H [m[11;78H[m[m[12;3H [m[12;78H[m[m[13;3H [m[13;78H[m[m[14;3H [m[14;78H[m[m[15;3H [m[15;78H[m[m[16;3H [m[16;78H[m[16;80H [5;78H[22;1H The highlighted entry will be executed automatically in 1s. [5;78H[22;1H The highlighted entry will be executed automatically in 0s. [5;78H[?25h[H[J[1;1H[H[J[1;1HLoading Linux 4.5.0-1-amd64 ...
Loading initial ramdisk ...
[ 0.000000] Linux version 4.5.0-1-amd64 ([email protected]) (gcc version 5.3.1 20160409 (Debian 5.3.1-14) ) #1 SMP Debian 4.5.1-1 (2016-04-14)
[ 0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-4.5.0-1-amd64 root=UUID=c5e14d62-3c44-4aca-8122-c802d3ed187a ro console=tty0 console=tty1 console=ttyS0,38400n8
[ 0.000000] x86/fpu: Legacy x87 FPU detected.
[ 0.000000] x86/fpu: Using 'lazy' FPU context switches.
[ 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-0x000000003ffdffff] usable
[ 0.000000] BIOS-e820: [mem 0x000000003ffe0000-0x000000003fffffff] 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.8 present.
[ 0.000000] Hypervisor detected: KVM
[ 0.000000] AGP: No AGP bridge found
[ 0.000000] e820: last_pfn = 0x3ffe0 max_arch_pfn = 0x400000000
[ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WC UC- UC
[ 0.000000] found SMP MP-table at [mem 0x000f6630-0x000f663f] mapped at [ffff8800000f6630]
[ 0.000000] RAMDISK: [mem 0x3618e000-0x370befff]
[ 0.000000] ACPI: Early table checksum verification disabled
[ 0.000000] ACPI: RSDP 0x00000000000F6460 000014 (v00 BOCHS )
[ 0.000000] ACPI: RSDT 0x000000003FFE1789 000034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: FACP 0x000000003FFE0CAF 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001)
[ 0.000000] ACPI: DSDT 0x000000003FFE0040 000C6F (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: FACS 0x000000003FFE0000 000040
[ 0.000000] ACPI: SSDT 0x000000003FFE0D23 0009B6 (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: APIC 0x000000003FFE16D9 000078 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001)
[ 0.000000] ACPI: HPET 0x000000003FFE1751 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001)
[ 0.000000] No NUMA configuration found
[ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000003ffdffff]
[ 0.000000] NODE_DATA(0) allocated [mem 0x3ffdb000-0x3ffdffff]
[ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[ 0.000000] kvm-clock: cpu 0, msr 0:3ffd3001, primary cpu clock
[ 0.000000] kvm-clock: using sched offset of 1961394879 cycles
[ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns
[ 0.000000] Zone ranges:
[ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff]
[ 0.000000] DMA32 [mem 0x0000000001000000-0x000000003ffdffff]
[ 0.000000] Normal empty
[ 0.000000] Device 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-0x000000003ffdffff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdffff]
[ 0.000000] ACPI: PM-Timer IO Port: 0x608
[ 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] 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 1 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 0x40000000-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: 7645519600211568 ns
[ 0.000000] setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:1 nr_node_ids:1
[ 0.000000] PERCPU: Embedded 32 pages/cpu @ffff88003fc00000 s93144 r8192 d29736 u2097152
[ 0.000000] KVM setup async PF for cpu 0
[ 0.000000] kvm-stealtime: cpu 0, msr 3fc0d980
[ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 257897
[ 0.000000] Policy zone: DMA32
[ 0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-4.5.0-1-amd64 root=UUID=c5e14d62-3c44-4aca-8122-c802d3ed187a ro console=tty0 console=tty1 console=ttyS0,38400n8
[ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[ 0.000000] AGP: Checking aperture...
[ 0.000000] AGP: No AGP bridge found
[ 0.000000] Memory: 1002060K/1048056K available (5869K kernel code, 1096K rwdata, 2928K rodata, 1316K init, 824K bss, 45996K reserved, 0K cma-reserved)
[ 0.000000] Hierarchical RCU implementation.
[ 0.000000] Build-time adjustment of leaf fanout to 64.
[ 0.000000] RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1.
[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=1
[ 0.000000] NR_IRQS:33024 nr_irqs:256 16
[ 0.000000] Console: colour VGA+ 80x25
[ 0.000000] console [tty0] enabled
[ 0.000000] console [ttyS0] enabled
[ 0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns
[ 0.000000] tsc: Detected 2659.808 MHz processor
[ 0.138777] Calibrating delay loop (skipped) preset value.. 5319.61 BogoMIPS (lpj=10639232)
[ 0.140457] pid_max: default: 32768 minimum: 301
[ 0.141371] ACPI: Core revision 20160108
[ 0.143586] ACPI: 2 ACPI AML tables successfully acquired and loaded
[ 0.144853]
[ 0.145300] Security Framework initialized
[ 0.146135] Yama: disabled by default; enable with sysctl kernel.yama.*
[ 0.147346] AppArmor: AppArmor disabled by boot time parameter
[ 0.148537] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[ 0.150100] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[ 0.151881] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[ 0.153168] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[ 0.154882] Disabling memory control group subsystem
[ 0.155927] mce: CPU supports 10 MCE banks
[ 0.156788] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 0.157822] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 0.169236] Freeing SMP alternatives memory: 24K (ffffffff81c5d000 - ffffffff81c63000)
[ 0.175437] ftrace: allocating 24051 entries in 94 pages
[ 0.198318] x2apic enabled
[ 0.199135] Switched APIC routing to physical x2apic.
[ 0.201050] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 0.309861] smpboot: CPU0: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1)
[ 0.311579] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only.
[ 0.313680] x86: Booted up 1 node, 1 CPUs
[ 0.314494] smpboot: Total of 1 processors activated (5319.61 BogoMIPS)
[ 0.315932] devtmpfs: initialized
[ 0.318286] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[ 0.319964] pinctrl core: initialized pinctrl subsystem
[ 0.320955] NET: Registered protocol family 16
[ 0.321843] cpuidle: using governor ladder
[ 0.322573] cpuidle: using governor menu
[ 0.323313] ACPI: bus type PCI registered
[ 0.324029] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[ 0.325136] PCI: Using configuration type 1 for base access
[ 0.326641] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[ 0.327873] ACPI: Added _OSI(Module Device)
[ 0.328606] ACPI: Added _OSI(Processor Device)
[ 0.329352] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.330145] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.331469] ACPI: Executed 1 blocks of module-level executable AML code
[ 0.332946] ACPI: Interpreter enabled
[ 0.333604] ACPI: (supports S0 S3 S4 S5)
[ 0.334291] ACPI: Using IOAPIC for interrupt routing
[ 0.335122] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.338904] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.339905] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[ 0.340976] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[ 0.342030] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[ 0.344089] acpiphp: Slot [3] registered
[ 0.344796] acpiphp: Slot [4] registered
[ 0.345496] acpiphp: Slot [5] registered
[ 0.346197] acpiphp: Slot [6] registered
[ 0.346898] acpiphp: Slot [7] registered
[ 0.347593] acpiphp: Slot [8] registered
[ 0.348301] acpiphp: Slot [9] registered
[ 0.348996] acpiphp: Slot [10] registered
[ 0.349705] acpiphp: Slot [11] registered
[ 0.350422] acpiphp: Slot [12] registered
[ 0.351135] acpiphp: Slot [13] registered
[ 0.351843] acpiphp: Slot [14] registered
[ 0.352571] acpiphp: Slot [15] registered
[ 0.353273] acpiphp: Slot [16] registered
[ 0.354012] acpiphp: Slot [17] registered
[ 0.354719] acpiphp: Slot [18] registered
[ 0.355421] acpiphp: Slot [19] registered
[ 0.356140] acpiphp: Slot [20] registered
[ 0.356853] acpiphp: Slot [21] registered
[ 0.357608] acpiphp: Slot [22] registered
[ 0.358333] acpiphp: Slot [23] registered
[ 0.359045] acpiphp: Slot [24] registered
[ 0.359757] acpiphp: Slot [25] registered
[ 0.360475] acpiphp: Slot [26] registered
[ 0.361180] acpiphp: Slot [27] registered
[ 0.361897] acpiphp: Slot [28] registered
[ 0.362612] acpiphp: Slot [29] registered
[ 0.363319] acpiphp: Slot [30] registered
[ 0.364042] acpiphp: Slot [31] registered
[ 0.364743] PCI host bridge to bus 0000:00
[ 0.365443] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
[ 0.366510] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
[ 0.367588] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[ 0.368955] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
[ 0.370220] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.378254] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
[ 0.379399] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
[ 0.380417] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
[ 0.381499] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
[ 0.384517] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
[ 0.385487] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
[ 0.450342] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[ 0.462254] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[ 0.463275] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[ 0.464289] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[ 0.465255] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[ 0.466279] ACPI: Enabled 16 GPEs in block 00 to 0F
[ 0.467135] vgaarb: setting as boot device: PCI:0000:00:02.0
[ 0.467795] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[ 0.468798] vgaarb: loaded
[ 0.469195] vgaarb: bridge control possible 0000:00:02.0
[ 0.469950] PCI: Using ACPI for IRQ routing
[ 0.470734] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[ 0.471541] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[ 0.472367] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[ 0.476169] clocksource: Switched to clocksource kvm-clock
[ 0.483604] VFS: Disk quotas dquot_6.6.0
[ 0.484379] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 0.485444] pnp: PnP ACPI init
[ 0.486576] pnp: PnP ACPI: found 7 devices
[ 0.493319] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[ 0.494598] NET: Registered protocol family 2
[ 0.495402] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.496308] TCP bind hash table entries: 8192 (order: 5, 131072 bytes)
[ 0.497080] TCP: Hash tables configured (established 8192 bind 8192)
[ 0.497844] UDP hash table entries: 512 (order: 2, 16384 bytes)
[ 0.498534] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[ 0.499306] NET: Registered protocol family 1
[ 0.499875] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[ 0.500579] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[ 0.501275] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[ 0.502064] Unpacking initramfs...
[ 0.743897] Freeing initrd memory: 15556K (ffff88003618e000 - ffff8800370bf000)
[ 0.745405] futex hash table entries: 256 (order: 2, 16384 bytes)
[ 0.746149] audit: initializing netlink subsys (disabled)
[ 0.746818] audit: type=2000 audit(1461081455.949:1): initialized
[ 0.747725] Initialise system trusted keyring
[ 0.748431] zbud: loaded
[ 0.749352] Key type asymmetric registered
[ 0.749886] Asymmetric key parser 'x509' registered
[ 0.750490] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[ 0.751431] io scheduler noop registered
[ 0.751951] io scheduler deadline registered
[ 0.752514] io scheduler cfq registered (default)
[ 0.753145] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[ 0.753950] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[ 0.754745] GHES: HEST is not enabled!
[ 0.755311] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 0.778937] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[ 0.803391] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[ 0.804686] Linux agpgart interface v0.103
[ 0.805299] AMD IOMMUv2 driver by Joerg Roedel <[email protected]>
[ 0.806008] AMD IOMMUv2 functionality not available on this system
[ 0.806825] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[ 0.808384] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 0.809010] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 0.809733] mousedev: PS/2 mouse device common for all mice
[ 0.810612] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0
[ 0.811889] rtc_cmos 00:00: RTC can wake from S4
[ 0.812657] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[ 0.813463] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs
[ 0.814278] ledtrig-cpu: registered to indicate activity on CPUs
[ 0.815156] NET: Registered protocol family 10
[ 0.815919] mip6: Mobile IPv6
[ 0.816384] NET: Registered protocol family 17
[ 0.816955] mpls_gso: MPLS GSO support
[ 0.817580] microcode: CPU0 sig=0xf61, pf=0x1, revision=0x1
[ 0.818289] microcode: Microcode Update Driver: v2.01 <[email protected]>, Peter Oruba
[ 0.819476] registered taskstats version 1
[ 0.820044] Loading compiled-in X.509 certificates
[ 0.820905] Loaded X.509 cert 'Debian Project: Ben Hutchings: 008a018dca80932630'
[ 0.821906] zswap: loaded using pool lzo/zbud
[ 0.822750] rtc_cmos 00:00: setting system clock to 2016-04-19 15:57:35 UTC (1461081455)
[ 0.825049] Freeing unused kernel memory: 1316K (ffffffff81b14000 - ffffffff81c5d000)
[ 0.826052] Write protecting the kernel read-only data: 10240k
[ 0.826888] Freeing unused kernel memory: 264K (ffff8800015be000 - ffff880001600000)
[ 0.830126] Freeing unused kernel memory: 1168K (ffff8800018dc000 - ffff880001a00000)
[ 0.837359] x86/mm: Checked W+X mappings: passed, no W+X pages found.
Loading, please wait...
starting version 229
[ 0.844855] random: udevadm urandom read with 2 bits of entropy available
[ 0.865158] scsi_mod: module verification failed: signature and/or required key missing - tainting kernel
[ 0.867553] SCSI subsystem initialized
[ 0.892549] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3
[ 0.893828] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input2
[ 0.916208] Floppy drive(s): fd0 is 1.44M
[ 0.925659] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[ 0.926402] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver
[ 0.929147] scsi host0: ata_piix
[ 0.929797] scsi host1: ata_piix
[ 0.930310] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0a0 irq 14
[ 0.931084] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0a8 irq 15
[ 0.940588] FDC 0 is a S82078B
[ 0.952484] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10
[ 0.953222] virtio-pci 0000:00:04.0: virtio_pci: leaving for legacy driver
[ 0.973297] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10
[ 0.974050] virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy driver
[ 0.979523] vda: vda1
[ 1.000261] blk_update_request: I/O error, dev fd0, sector 0
[ 1.001038] floppy: error -5 while reading block 0
[ 1.097083] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[ 1.099011] ata2.00: configured for MWDMA2
[ 1.100804] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5
[ 1.112736] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[ 1.113542] cdrom: Uniform CD-ROM driver Revision: 3.20
Begin: Loading essential drivers ... done.
Begin: Running /scripts/init-premount ... done.
Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done.
Begin: Running /scripts/local-premount ... done.
Begin: Will now check root file system ... fsck from util-linux 2.28
fsck: error 2 (No such file or directory) while executing fsck.ext4 for /dev/vda1
fsck exited with status code 8
done.
Warning: File system check failed but did not detect errors
[ 1.744279] tsc: Refined TSC clocksource calibration: 2659.810 MHz
[ 1.745074] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2656f0320a1, max_idle_ns: 440795237495 ns
[ 6.203538] EXT4-fs (vda1): INFO: recovery required on readonly filesystem
[ 6.205651] EXT4-fs (vda1): write access will be enabled during recovery
[ 6.221545] EXT4-fs (vda1): recovery complete
[ 6.222649] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null)
done.
Begin: Running /scripts/local-bottom ... done.
Begin: Running /scripts/init-bottom ... done.
[ 6.256095] systemd[1]: systemd 229 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN)
[ 6.258289] systemd[1]: Detected virtualization qemu.
[ 6.258921] systemd[1]: Detected architecture x86-64.
Welcome to [1mDebian GNU/Linux stretch/sid[0m!
[ 6.260551] systemd[1]: Set hostname to <debian>.
[ 6.295509] systemd[1]: Listening on Journal Socket.
[[0;32m OK [0m] Listening on Journal Socket.
[ 6.297154] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[[0;32m OK [0m] Started Dispatch Password Requests to Console Directory Watch.
[ 6.299061] systemd[1]: Reached target Remote File Systems (Pre).
[[0;32m OK [0m] Reached target Remote File Systems (Pre).
[ 6.300637] systemd[1]: Reached target Encrypted Volumes.
[[0;32m OK [0m] Reached target Encrypted Volumes.
[ 6.302063] systemd[1]: Listening on fsck to fsckd communication Socket.
[[0;32m OK [0m] Listening on fsck to fsckd communication Socket.
[ 6.303801] systemd[1]: Created slice System Slice.
[[0;32m OK [0m] Created slice System Slice.
[ 6.305674] systemd[1]: Mounting POSIX Message Queue File System...
Mounting POSIX Message Queue File System...
[ 6.307529] systemd[1]: Starting File System Check on Root Device...
Starting File System Check on Root Device...
[ 6.309553] systemd[1]: Listening on udev Kernel Socket.
[[0;32m OK [0m] Listening on udev Kernel Socket.
[ 6.317242] systemd[1]: Starting Load Kernel Modules...
Starting Load Kernel Modules...
[ 6.322302] systemd[1]: Starting Create list of required static device nodes for the current kernel...
Starting Create list of required st... nodes for the current kernel...
[ 6.332250] systemd[1]: Listening on Syslog Socket.
[[0;32m OK [0m] Listening on Syslog Socket.
[ 6.334968] systemd[1]: Listening on Journal Audit Socket.
[[0;32m OK [0m] Listening on Journal Audit Socket.
[ 6.337682] systemd[1]: Reached target Remote File Systems.
[[0;32m OK [0m] Reached target Remote File Systems.
[ 6.341614] systemd[1]: Mounting Huge Pages File System...
Mounting Huge Pages File System...
[ 6.343884] systemd[1]: Created slice User and Session Slice.
[[0;32m OK [0m] Created slice User and Session Slice.
[ 6.346757] systemd[1]: Reached target Slices.
[[0;32m OK [0m] Reached target Slices.
[ 6.348876] systemd[1]: Listening on udev Control Socket.
[[0;32m OK [0m] Listening on udev Control Socket.
[ 6.351038] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[[0;32m OK [0m] Listening on /dev/initctl Compatibility Named Pipe.
[ 6.352964] systemd[1]: Created slice system-getty.slice.
[[0;32m OK [0m] Created slice system-getty.slice.
[ 6.354526] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
[[0;32m OK [0m] Set up automount Arbitrary Executab...ats File System Automount Point.
[ 6.357071] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[[0;32m OK [0m] Started Forward Password Requests to Wall Directory Watch.
[ 6.358949] systemd[1]: Reached target Swap.
[[0;32m OK [0m] Reached target Swap.
[ 6.360774] systemd[1]: Mounting Debug File System...
Mounting Debug File System...
[ 6.362175] systemd[1]: Created slice system-serial\x2dgetty.slice.
[[0;32m OK [0m] Created slice system-serial\x2dgetty.slice.
[ 6.364271] systemd[1]: Listening on Journal Socket (/dev/log).
[[0;32m OK [0m] Listening on Journal Socket (/dev/log).
[ 6.368809] systemd[1]: Mounted Huge Pages File System.
[[0;32m OK [0m] Mounted Huge Pages File System.
[ 6.371772] systemd[1]: Mounted POSIX Message Queue File System.
[[0;32m OK [0m] Mounted POSIX Message Queue File System.
[ 6.374141] systemd[1]: Mounted Debug File System.
[[0;32m OK [0m] Mounted Debug File System.
[ 6.375833] systemd[1]: Started File System Check on Root Device.
[[0;32m OK [0m] Started File System Check on Root Device.
[ 6.377758] systemd[1]: Started Load Kernel Modules.
[[0;32m OK [0m] Started Load Kernel Modules.
[ 6.379304] systemd[1]: Started Create list of required static device nodes for the current kernel.
[[0;32m OK [0m] Started Create list of required sta...ce nodes for the current kernel.
[ 6.403790] systemd[1]: Started File System Check Daemon to report status.
[[0;32m OK [0m] Started File System Check Daemon to report status.
[ 6.406280] systemd[1]: Starting Create Static Device Nodes in /dev...
Starting Create Static Device Nodes in /dev...
[ 6.408880] systemd[1]: Starting Apply Kernel Variables...
Starting Apply Kernel Variables...
[ 6.415945] systemd[1]: Starting Remount Root and Kernel File Systems...
Starting Remount Root and Kernel File Systems...
[ 6.426779] systemd[1]: Started Create Static Device Nodes in /dev.
[[0;32m OK [0m] Started Create Static Device Nodes in /dev.[ 6.431943] EXT4-fs (vda1): re-mounted. Opts: errors=remount-ro
[ 6.433521] systemd[1]: Started Apply Kernel Variables.
[[0;32m OK [0m] Started Apply Kernel Variables.
[ 6.435141] systemd[1]: Started Remount Root and Kernel File Systems.
[[0;32m OK [0m] Started Remount Root and Kernel File Systems.
[ 6.454077] systemd[1]: Starting Load/Save Random Seed...
Starting Load/Save Random Seed...
[ 6.456589] systemd[1]: Starting udev Coldplug all Devices...
Starting udev Coldplug all Devices...
[ 6.477300] systemd[1]: Starting udev Kernel Device Manager...
Starting udev Kernel Device Manager...
[ 6.479478] systemd[1]: Reached target Local File Systems (Pre).
[[0;32m OK [0m] Reached target Local File Systems (Pre).
[ 6.481999] systemd[1]: Reached target Local File Systems.
[[0;32m OK [0m] Reached target Local File Systems.
[ 6.488778] systemd[1]: Starting Raise network interfaces...
Starting Raise network interfaces...
[ 6.496983] systemd[1]: Started udev Kernel Device Manager.
[[0;32m OK [0m] Started udev Kernel Device Manager.
[ 6.504725] systemd[1]: Started Load/Save Random Seed.
[[0;32m OK [0m] Started Load/Save Random Seed.
[ 6.584255] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input4
[ 6.585337] ACPI: Power Button [PWRF]
[ 6.596147] systemd[1]: Started udev Coldplug all Devices.
[ 6.598543] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[[0;32m OK [0m] Started udev Coldplug all Devices.[ 6.610880] parport_pc 00:04: reported by Plug and Play ACPI
[ 6.611738] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[ 6.627640] [drm] Initialized drm 1.1.0 20060810
[ 6.633410] 9pnet: Installing 9P2000 support
[ 6.634430] sr 1:0:0:0: Attached scsi generic sg0 type 5
[ 6.642878] input: PC Speaker as /devices/platform/pcspkr/input/input5
[ 6.666408] [drm] Found bochs VGA, ID 0xb0c0.
[ 6.667021] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000.
[ 6.680260] blk_update_request: I/O error, dev fd0, sector 0
[ 6.680999] floppy: error -5 while reading block 0
[ 6.683813] [TTM] Zone kernel: Available graphics memory: 510194 kiB
[ 6.684652] [TTM] Initializing pool allocator
[ 6.685248] [TTM] Initializing DMA pool allocator
[ 6.700074] fbcon: bochsdrmfb (fb0) is primary device
[ 6.706576] systemd[1]: Found device /dev/ttyS0.
[ 6.727254] ppdev: user-space parallel port driver
[ 6.733445] Console: switching to colour frame buffer device 128x48
[ 6.742759] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device
[[0;32m OK [0m] Found device /dev/ttyS0.
[ 6.746244] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
[ 6.755010] systemd[1]: Started Entropy daemon using the HAVEGE algorithm.
[[0;32m OK [0m] Started Entropy daemon using the HAVEGE algorithm.
[ 6.757430] systemd[1]: Starting Journal Service...
Starting Journal Service...
[ 6.794888] systemd[1]: Started Journal Service.
[[0;32m OK [0m] Started Journal Service.
Starting Flush Journal to Persistent Storage...
[ 6.810769] systemd-journald[266]: Received request to flush runtime journal from PID 1
[[0;32m OK [0m] Started Flush Journal to Persistent Storage.
Starting Create Volatile Files and Directories...
[[0;32m OK [0m] Started Create Volatile Files and Directories.
Starting Update UTMP about System Boot/Shutdown...
Starting Network Time Synchronization...
[[0;32m OK [0m] Started Update UTMP about System Boot/Shutdown.
[[0;32m OK [0m] Started Network Time Synchronization.
[[0;32m OK [0m] Reached target System Time Synchronized.
[[0;32m OK [0m] Reached target System Initialization.
[[0;32m OK [0m] Listening on ACPID Listen Socket.
[[0;32m OK [0m] Started Daily apt activities.
[[0;32m OK [0m] Started Daily Cleanup of Temporary Directories.
[[0;32m OK [0m] Reached target Timers.
[[0;32m OK [0m] Listening on D-Bus System Message Bus Socket.
[[0;32m OK [0m] Reached target Sockets.
[[0;32m OK [0m] Started ACPI Events Check.
[[0;32m OK [0m] Reached target Paths.
[[0;32m OK [0m] Reached target Basic System.
[[0;32m OK [0m] Started D-Bus System Message Bus.
Starting System Logging Service...
Starting Login Service...
[[0;32m OK [0m] Started Regular background program processing daemon.
Starting autopkgtest.service...
Starting Permit User Sessions...
[[0;32m OK [0m] Started autopkgtest.service.
[[0;32m OK [0m] Started Permit User Sessions.
[[0;32m OK [0m] Started System Logging Service.
[[0;32m OK [0m] Started Login Service.
[[0;32m OK [0m] Started Raise network interfaces.
[[0;32m OK [0m] Reached target Network.
Starting /etc/rc.local Compatibility...
[[0;32m OK [0m] Started /etc/rc.local Compatibility.
[[0;32m OK [0m] Started Serial Getty on ttyS0.
[[0;32m OK [0m] Started Getty on tty1.
[[0;32m OK [0m] Reached target Login Prompts.
[[0;32m OK [0m] Reached target Multi-User System.
[[0;32m OK [0m] Reached target Graphical Interface.
Starting Update UTMP about System Runlevel Changes...
[[0;32m OK [0m] Started Update UTMP about System Runlevel Changes.
Debian GNU/Linux stretch/sid debian ttyS0
debian login: adt-virt-qemu: DBG: expect: found ""login prompt on ttyS0""
adt-virt-qemu: DBG: expect: "ok"
adt-virt-qemu: DBG: expect: found ""b'ok'""
adt-virt-qemu: DBG: setup_shell(): there already is a shell on ttyS1
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: expect: "(qemu)"
adt-virt-qemu: DBG: expect: found ""b'(qemu)'""
adt-virt-qemu: DBG: expect: "(qemu)"
adt-virt-qemu: DBG: expect: found ""b'(qemu)'""
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: Copying host timezone Europe/London to VM
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: expect: "/python"
adt-virt-qemu: DBG: expect: found ""b'/python'""
adt-virt-qemu: DBG: expect: "# "
adt-virt-qemu: DBG: expect: found ""b'# '""
adt-virt-qemu: DBG: execute-timeout: /tmp/adt-virt-qemu.rj4nwrzx/runcmd true
qemu-system-x86_64: terminating on signal 15 from pid 572
adt-virt-qemu: DBG: cleanup...
Unexpected error:
Traceback (most recent call last):
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 705, in mainloop
command()
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 643, in command
r = f(c, ce)
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 258, in cmd_open
caller.hook_open()
File "/usr/bin/adt-virt-qemu", line 567, in hook_open
make_auxverb(shareddir)
File "/usr/bin/adt-virt-qemu", line 413, in make_auxverb
status = VirtSubproc.execute_timeout(None, 5, VirtSubproc.auxverb + ['true'])[0]
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 144, in execute_timeout
(out, err) = sp.communicate(instr)
File "/usr/lib/python3.5/subprocess.py", line 1057, in communicate
self.wait()
File "/usr/lib/python3.5/subprocess.py", line 1648, in wait
(pid, sts) = self._try_wait(0)
File "/usr/lib/python3.5/subprocess.py", line 1598, in _try_wait
(pid, sts) = os.waitpid(self.pid, wait_flags)
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 64, in alarm_handler
raise Timeout()
VirtSubproc.Timeout
adt-run: DBG: TestbedFailure unexpected eof from the testbed
adt-run: DBG: testbed stop
adt-run: DBG: testbed close, scratch=None
adt-run: DBG: sending command to testbed: quit
adt-run: DBG: TestbedFailure cannot send to testbed: ['BrokenPipeError: [Errno 32] Broken pipe\n']
adt-run: DBG: testbed stop
adt-run [16:57:52]: ERROR: testbed failure: cannot send to testbed: ['BrokenPipeError: [Errno 32] Broken pipe\n']
adt-run: DBG: testbed stop
adt-run: DBG: testbed init adt-run [16:58:43]: version 3.20.4 adt-run [16:58:43]: host sylvester; command line: /usr/bin/adt-run -l adt-orig-image.log -d /tmp/tmp.lXjW2N5fd3/lava-server_2016.4-1.dsc -U --- qemu --debug --show-boot adt-sid.img adt-run: DBG: got reply from testbed: ok adt-run: DBG: testbed open, scratch=None adt-run: DBG: sending command to testbed: open adt-virt-qemu: DBG: executing open adt-virt-qemu: DBG: Creating temporary overlay image in /tmp/adt-virt-qemu.lljt9jr6/overlay.img adt-virt-qemu: DBG: execute-timeout: qemu-img create -f qcow2 -b /home/neil/code/lava/packaging/alioth/adt-run-qemu/adt-sid.img /tmp/adt-virt-qemu.lljt9jr6/overlay.img adt-virt-qemu: DBG: Detected KVM capable Intel host CPU, enabling nested KVM adt-virt-qemu: DBG: find_free_port: trying 10022 adt-virt-qemu: DBG: find_free_port: 10022 is free adt-virt-qemu: DBG: Forwarding local port 10022 to VM ssh port 22 adt-virt-qemu: DBG: expect: " login: " warning: host doesn't support requested feature: CPUID.01H:ECX.vmx [bit 5] SYSLINUX 6.03 20151108 Copyright (C) 1994-2014 H. Peter Anvin et al Loading boot/vmlinuz-4.3.0-1-amd64... ok Loading boot/initrd.img-4.3.0-1-amd64...ok [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Initializing cgroup subsys cpuacct [ 0.000000] Linux version 4.3.0-1-amd64 ([email protected]) (gcc version 5.3.1 20160121 (Debian 5.3.1-7) ) #1 SMP Debian 4.3.5-1 (2016-02-06) [ 0.000000] Command line: BOOT_IMAGE=boot/vmlinuz-4.3.0-1-amd64 initrd=boot/initrd.img-4.3.0-1-amd64 root=UUID=3c705aee-4780-4bf6-abd1-98977a2faa1d ro console=ttyS0,115200 [ 0.000000] x86/fpu: Legacy x87 FPU detected. [ 0.000000] x86/fpu: Using 'lazy' FPU context switches. [ 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-0x000000003ffdffff] usable [ 0.000000] BIOS-e820: [mem 0x000000003ffe0000-0x000000003fffffff] 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.8 present. [ 0.000000] Hypervisor detected: KVM [ 0.000000] AGP: No AGP bridge found [ 0.000000] e820: last_pfn = 0x3ffe0 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WC UC- UC [ 0.000000] found SMP MP-table at [mem 0x000f6630-0x000f663f] mapped at [ffff8800000f6630] [ 0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff] [ 0.000000] init_memory_mapping: [mem 0x3ee00000-0x3effffff] [ 0.000000] init_memory_mapping: [mem 0x20000000-0x3edfffff] [ 0.000000] init_memory_mapping: [mem 0x00100000-0x1fffffff] [ 0.000000] init_memory_mapping: [mem 0x3f000000-0x3ffdffff] [ 0.000000] RAMDISK: [mem 0x3f057000-0x3ffdffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F6460 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x000000003FFE1789 000034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x000000003FFE0CAF 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x000000003FFE0040 000C6F (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x000000003FFE0000 000040 [ 0.000000] ACPI: SSDT 0x000000003FFE0D23 0009B6 (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: APIC 0x000000003FFE16D9 000078 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x000000003FFE1751 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000003ffdffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x3f053000-0x3f056fff] [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:3f04b001, primary cpu clock [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x000000003ffdffff] [ 0.000000] Normal 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-0x000000003ffdffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000003ffdffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 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] 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 1 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 0x40000000-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: 7645519600211568 ns [ 0.000000] setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:1 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 32 pages/cpu @ffff88003ee00000 s93016 r8192 d29864 u2097152 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 3ee0d9c0 [ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 257897 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: BOOT_IMAGE=boot/vmlinuz-4.3.0-1-amd64 initrd=boot/initrd.img-4.3.0-1-amd64 root=UUID=3c705aee-4780-4bf6-abd1-98977a2faa1d ro console=ttyS0,115200 [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes) [ 0.000000] AGP: Checking aperture... [ 0.000000] AGP: No AGP bridge found [ 0.000000] Memory: 1001772K/1048056K available (5676K kernel code, 1050K rwdata, 2796K rodata, 1296K init, 836K bss, 46284K reserved, 0K cma-reserved) [ 0.000000] Hierarchical RCU implementation. [ 0.000000] Build-time adjustment of leaf fanout to 64. [ 0.000000] RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=1 [ 0.000000] NR_IRQS:33024 nr_irqs:256 16 [ 0.000000] Console: colour VGA+ 80x25 [ 0.000000] console [ttyS0] enabled [ 0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.000000] tsc: Detected 2659.808 MHz processor [ 0.008000] Calibrating delay loop (skipped) preset value.. 5319.61 BogoMIPS (lpj=10639232) [ 0.008000] pid_max: default: 32768 minimum: 301 [ 0.008000] ACPI: Core revision 20150818 [ 0.008000] ACPI: 2 ACPI AML tables successfully acquired and loaded [ 0.008000] Security Framework initialized [ 0.008004] Yama: disabled by default; enable with sysctl kernel.yama.* [ 0.008553] AppArmor: AppArmor disabled by boot time parameter [ 0.009109] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.009924] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.010968] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes) [ 0.011621] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes) [ 0.012000] Initializing cgroup subsys io [ 0.012015] Initializing cgroup subsys memory [ 0.012390] Initializing cgroup subsys devices [ 0.012764] Initializing cgroup subsys freezer [ 0.013135] Initializing cgroup subsys net_cls [ 0.013508] Initializing cgroup subsys perf_event [ 0.013900] Initializing cgroup subsys net_prio [ 0.014343] mce: CPU supports 10 MCE banks [ 0.014729] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.016003] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.025001] Freeing SMP alternatives memory: 20K (ffffffff81c4c000 - ffffffff81c51000) [ 0.029559] ftrace: allocating 23625 entries in 93 pages [ 0.036240] x2apic enabled [ 0.036679] Switched APIC routing to physical x2apic. [ 0.040378] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.044000] smpboot: CPU0: Intel Common KVM processor (family: 0xf, model: 0x6, stepping: 0x1) [ 0.044000] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only. [ 0.044000] x86: Booted up 1 node, 1 CPUs [ 0.044000] smpboot: Total of 1 processors activated (5319.61 BogoMIPS) [ 0.044000] devtmpfs: initialized [ 0.045255] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.046075] pinctrl core: initialized pinctrl subsystem [ 0.046615] NET: Registered protocol family 16 [ 0.047114] cpuidle: using governor ladder [ 0.047440] cpuidle: using governor menu [ 0.047821] ACPI: bus type PCI registered [ 0.048006] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.048602] PCI: Using configuration type 1 for base access [ 0.049834] ACPI: Added _OSI(Module Device) [ 0.050172] ACPI: Added _OSI(Processor Device) [ 0.050515] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.050871] ACPI: Added _OSI(Processor Aggregator Device) [ 0.052118] ACPI: Executed 1 blocks of module-level executable AML code [ 0.052948] ACPI: Interpreter enabled [ 0.053248] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S1_] (20150818/hwxface-580) [ 0.053968] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20150818/hwxface-580) [ 0.054697] ACPI: (supports S0 S3 S4 S5) [ 0.055014] ACPI: Using IOAPIC for interrupt routing [ 0.055408] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.058555] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.059038] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI] [ 0.059570] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.060019] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.061195] acpiphp: Slot [3] registered [ 0.061523] acpiphp: Slot [4] registered [ 0.061847] acpiphp: Slot [5] registered [ 0.062172] acpiphp: Slot [6] registered [ 0.062497] acpiphp: Slot [7] registered [ 0.062821] acpiphp: Slot [8] registered [ 0.063144] acpiphp: Slot [9] registered [ 0.063474] acpiphp: Slot [10] registered [ 0.064009] acpiphp: Slot [11] registered [ 0.064340] acpiphp: Slot [12] registered [ 0.064670] acpiphp: Slot [13] registered [ 0.064999] acpiphp: Slot [14] registered [ 0.065329] acpiphp: Slot [15] registered [ 0.065658] acpiphp: Slot [16] registered [ 0.065988] acpiphp: Slot [17] registered [ 0.066316] acpiphp: Slot [18] registered [ 0.066646] acpiphp: Slot [19] registered [ 0.066975] acpiphp: Slot [20] registered [ 0.067304] acpiphp: Slot [21] registered [ 0.067646] acpiphp: Slot [22] registered [ 0.067981] acpiphp: Slot [23] registered [ 0.068022] acpiphp: Slot [24] registered [ 0.068353] acpiphp: Slot [25] registered [ 0.068684] acpiphp: Slot [26] registered [ 0.069016] acpiphp: Slot [27] registered [ 0.069349] acpiphp: Slot [28] registered [ 0.069680] acpiphp: Slot [29] registered [ 0.070011] acpiphp: Slot [30] registered [ 0.070342] acpiphp: Slot [31] registered [ 0.070666] PCI host bridge to bus 0000:00 [ 0.070987] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.071416] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.072003] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.072524] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.073086] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window] [ 0.080946] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.081535] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.082030] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.082558] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.083510] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.084012] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.140391] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.141132] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.141815] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.142483] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.143106] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 0.143899] ACPI: Enabled 16 GPEs in block 00 to 0F [ 0.144184] vgaarb: setting as boot device: PCI:0000:00:02.0 [ 0.144700] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none [ 0.145418] vgaarb: loaded [ 0.145670] vgaarb: bridge control possible 0000:00:02.0 [ 0.146309] PCI: Using ACPI for IRQ routing [ 0.147007] HPET: 3 timers in total, 0 timers will be used for per-cpu timer [ 0.148019] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.148510] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.154074] clocksource: Switched to clocksource kvm-clock [ 0.159311] pnp: PnP ACPI init [ 0.160358] pnp: PnP ACPI: found 7 devices [ 0.166847] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.167586] NET: Registered protocol family 2 [ 0.168096] TCP established hash table entries: 8192 (order: 4, 65536 bytes) [ 0.168659] TCP bind hash table entries: 8192 (order: 5, 131072 bytes) [ 0.169188] TCP: Hash tables configured (established 8192 bind 8192) [ 0.169708] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 0.170170] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 0.170689] NET: Registered protocol family 1 [ 0.171044] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.171501] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.171947] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.172526] Unpacking initramfs... [ 0.421234] Freeing initrd memory: 15908K (ffff88003f057000 - ffff88003ffe0000) [ 0.422081] microcode: CPU0 sig=0xf61, pf=0x1, revision=0x1 [ 0.422587] microcode: Microcode Update Driver: v2.00 <[email protected]>, Peter Oruba [ 0.423507] futex hash table entries: 256 (order: 2, 16384 bytes) [ 0.424042] audit: initializing netlink subsys (disabled) [ 0.424485] audit: type=2000 audit(1461081525.565:1): initialized [ 0.425274] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 0.425789] zbud: loaded [ 0.426113] VFS: Disk quotas dquot_6.6.0 [ 0.426438] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.427298] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) [ 0.427900] io scheduler noop registered [ 0.428231] io scheduler deadline registered [ 0.428573] io scheduler cfq registered (default) [ 0.428998] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 0.429450] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 0.430002] GHES: HEST is not enabled! [ 0.430363] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 0.453853] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 0.477426] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 0.478276] Linux agpgart interface v0.103 [ 0.478673] AMD IOMMUv2 driver by Joerg Roedel <[email protected]> [ 0.479133] AMD IOMMUv2 functionality not available on this system [ 0.479790] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 0.480949] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 0.481347] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 0.481849] mousedev: PS/2 mouse device common for all mice [ 0.482479] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 [ 0.483347] rtc_cmos 00:00: RTC can wake from S4 [ 0.483889] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0 [ 0.484508] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs [ 0.485076] ledtrig-cpu: registered to indicate activity on CPUs [ 0.485742] NET: Registered protocol family 10 [ 0.486291] mip6: Mobile IPv6 [ 0.486539] NET: Registered protocol family 17 [ 0.486882] mpls_gso: MPLS GSO support [ 0.487398] registered taskstats version 1 [ 0.487756] zswap: loaded using pool lzo/zbud [ 0.488753] rtc_cmos 00:00: setting system clock to 2016-04-19 15:58:45 UTC (1461081525) [ 0.490053] Freeing unused kernel memory: 1296K (ffffffff81b08000 - ffffffff81c4c000) [ 0.490677] Write protecting the kernel read-only data: 10240k [ 0.491315] Freeing unused kernel memory: 456K (ffff88000158e000 - ffff880001600000) [ 0.492276] Freeing unused kernel memory: 1300K (ffff8800018bb000 - ffff880001a00000) Loading, please wait... starting version 229 [ 0.501475] random: udevadm urandom read with 1 bits of entropy available [ 0.526337] SCSI subsystem initialized [ 0.554173] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 0.555055] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input2 [ 0.576601] Floppy drive(s): fd0 is 1.44M [ 0.582962] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11 [ 0.583467] virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver [ 0.585783] scsi host0: ata_piix [ 0.586229] scsi host1: ata_piix [ 0.586538] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0a0 irq 14 [ 0.587057] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0a8 irq 15 [ 0.596412] FDC 0 is a S82078B [ 0.607995] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10 [ 0.608512] virtio-pci 0000:00:04.0: virtio_pci: leaving for legacy driver [ 0.628138] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10 [ 0.628639] virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy driver [ 0.657033] vda: vda1 [ 0.752947] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 [ 0.754449] ata2.00: configured for MWDMA2 [ 0.755889] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 [ 0.768768] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray [ 0.769622] cdrom: Uniform CD-ROM driver Revision: 3.20 Begin: Loading essential drivers ... done. Begin: Running /scripts/init-premount ... done. Begin: Mounting root file system ... Begin: Running /scripts/local-top ... done. Begin: Running /scripts/local-premount ... done. Begin: Will now check root file system ... fsck from util-linux 2.27.1 [/sbin/fsck.ext4 (1) -- /dev/vda1] fsck.ext4 -a -C0 /dev/vda1 /dev/vda1: clean, 27599/610800 files, 327021/2440960 blocks done. [ 0.868521] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null) done. Begin: Running /scripts/local-bottom ... done. Begin: Running /scripts/init-bottom ... done. [ 0.983666] systemd[1]: systemd 229 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ -LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN) [ 0.987657] systemd[1]: Detected virtualization qemu. [ 0.988784] systemd[1]: Detected architecture x86-64. Welcome to [1mDebian GNU/Linux stretch/sid[0m! [ 0.992395] systemd[1]: Set hostname to <debian>. [ 1.068950] systemd[1]: Listening on udev Control Socket. [[0;32m OK [0m] Listening on udev Control Socket. [ 1.070951] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [[0;32m OK [0m] Listening on /dev/initctl Compatibility Named Pipe. [ 1.073196] systemd[1]: Created slice System Slice. [[0;32m OK [0m] Created slice System Slice. [ 1.075147] systemd[1]: Reached target Swap. [[0;32m OK [0m] Reached target Swap. [ 1.076873] systemd[1]: Reached target Slices. [[0;32m OK [0m] Reached target Slices. [ 1.078684] systemd[1]: Listening on Journal Socket. [[0;32m OK [0m] Listening on Journal Socket. [ 1.082052] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 1.084270] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 1.086410] systemd[1]: Reached target Remote File Systems (Pre). [[0;32m OK [0m] Reached target Remote File Systems (Pre). [ 1.089997] systemd[1]: Reached target Remote File Systems. [[0;32m OK [0m] Reached target Remote File Systems. [ 1.094294] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. [[0;32m OK [0m] Set up automount Arbitrary Executab...ats File System Automount Point. [ 1.096516] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [[0;32m OK [0m] Started Dispatch Password Requests to Console Directory Watch. [ 1.100139] systemd[1]: Listening on Journal Audit Socket. [[0;32m OK [0m] Listening on Journal Audit Socket. [ 1.101956] systemd[1]: Listening on Journal Socket (/dev/log). [[0;32m OK [0m] Listening on Journal Socket (/dev/log). [ 1.103244] systemd[1]: Listening on udev Kernel Socket. [[0;32m OK [0m] Listening on udev Kernel Socket. [ 1.104896] systemd[1]: Mounting Debug File System... Mounting Debug File System... [ 1.106162] systemd[1]: Reached target Encrypted Volumes. [[0;32m OK [0m] Reached target Encrypted Volumes. [ 1.107404] systemd[1]: Listening on fsck to fsckd communication Socket. [[0;32m OK [0m] Listening on fsck to fsckd communication Socket. [ 1.110599] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [ 1.113085] systemd[1]: Created slice system-getty.slice. [[0;32m OK [0m] Created slice system-getty.slice. [ 1.114844] systemd[1]: Created slice system-serial\x2dgetty.slice. [[0;32m OK [0m] Created slice system-serial\x2dgetty.slice. [ 1.118217] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 1.123586] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 1.124310] EXT4-fs (vda1): re-mounted. Opts: errors=remount-ro [[0;32m OK [0m] Started Forward Password Requests to Wall Directory Watch. [ 1.126733] systemd[1]: Listening on Syslog Socket. [[0;32m OK [0m] Listening on Syslog Socket. [ 1.129174] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st... nodes for the current kernel... [ 1.131780] systemd[1]: Mounted Debug File System. [[0;32m OK [0m] Mounted Debug File System. [ 1.133768] systemd[1]: Mounted Huge Pages File System. [[0;32m OK [0m] Mounted Huge Pages File System. [ 1.135779] systemd[1]: Mounted POSIX Message Queue File System. [[0;32m OK [0m] Mounted POSIX Message Queue File System. [ 1.137389] systemd[1]: Started Load Kernel Modules. [[0;32m OK [0m] Started Load Kernel Modules. [ 1.139547] systemd[1]: Started Remount Root and Kernel File Systems. [[0;32m OK [0m] Started Remount Root and Kernel File Systems. [ 1.141168] systemd[1]: Started Create list of required static device nodes for the current kernel. [[0;32m OK [0m] Started Create list of required sta...ce nodes for the current kernel. [ 1.151413] systemd[1]: Starting Create Static Device Nodes in /dev... Starting Create Static Device Nodes in /dev... [ 1.153220] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 1.156160] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 1.159215] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 1.165492] systemd[1]: Started Load/Save Random Seed. [[0;32m OK [0m] Started Load/Save Random Seed. [ 1.170897] systemd[1]: Started Apply Kernel Variables. [[0;32m OK [0m] Started Apply Kernel Variables. [ 1.177152] systemd[1]: Started Create Static Device Nodes in /dev. [[0;32m OK [0m] Started Create Static Device Nodes in /dev. [ 1.192854] systemd[1]: Starting udev Kernel Device Manager... Starting udev Kernel Device Manager... [ 1.196081] systemd[1]: Reached target Local File Systems (Pre). [[0;32m OK [0m] Reached target Local File Systems (Pre). [ 1.200574] systemd[1]: Reached target Local File Systems. [[0;32m OK [0m] Reached target Local File Systems. [ 1.206040] systemd[1]: Starting Raise network interfaces... Starting Raise network interfaces... [ 1.214159] systemd[1]: Started Entropy daemon using the HAVEGE algorithm. [[0;32m OK [0m] Started Entropy daemon using the HAVEGE algorithm. [ 1.224270] systemd[1]: Starting Journal Service... Starting Journal Service... [ 1.238932] systemd[1]: Started udev Kernel Device Manager. [[0;32m OK [0m] Started udev Kernel Device Manager. [ 1.246031] systemd[1]: Started udev Coldplug all Devices. [[0;32m OK [0m] Started udev Coldplug all Devices. [ 1.273186] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input4 [ 1.273830] ACPI: Power Button [PWRF] [ 1.279158] parport_pc 00:04: reported by Plug and Play ACPI [ 1.279827] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE] [ 1.299657] systemd[1]: Started Journal Service. [[0;32m OK [0m] Started Journal Service. Starting Flush Journal to Persistent Storage... [ 1.314223] [drm] Initialized drm 1.1.0 20060810 [ 1.330939] sr 1:0:0:0: Attached scsi generic sg0 type 5 [ 1.338264] 9pnet: Installing 9P2000 support [ 1.349195] input: PC Speaker as /devices/platform/pcspkr/input/input5 [[0;32m OK [0m] Found device /dev/ttyS0. [ 1.384543] systemd-journald[166]: Received request to flush runtime journal from PID 1 [[0;32m OK [0m] Started Flush Journal to Persistent Storage. Starting Create Volatile Files and Directories... [ 1.420083] tsc: Refined TSC clocksource calibration: 2659.801 MHz [ 1.420610] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x2656e7f1076, max_idle_ns: 440795281352 ns [ 1.422944] ppdev: user-space parallel port driver [[0;32m OK [0m] Started Create Volatile Files and Directories. Starting Network Time Synchronization... Starting Update UTMP about System Boot/Shutdown... [ 1.468971] ACPI: No IRQ available for PCI Interrupt Link [LNKS]. Try pci=noacpi or acpi=off [ 1.469723] piix4_smbus 0000:00:01.3: PCI INT A: no GSI [ 1.470162] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [[0;32m OK [0m] Started Update UTMP about System Boot/Shutdown. [ 1.476672] [drm] Found bochs VGA, ID 0xb0c0. [ 1.477133] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. [ 1.484112] [TTM] Zone kernel: Available graphics memory: 510376 kiB [ 1.484665] [TTM] Initializing pool allocator [ 1.485081] [TTM] Initializing DMA pool allocator [ 1.490565] fbcon: bochsdrmfb (fb0) is primary device [ 1.514742] Console: switching to colour frame buffer device 128x48 [ 1.517063] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device [[0;32m OK [0m] Started Network Time Synchronization. [[0;32m OK [0m] Reached target System Initialization. [ 1.528157] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0 [[0;32m OK [0m] Started ACPI Events Check. [[0;32m OK [0m] Reached target Paths. [[0;32m OK [0m] Started Daily Cleanup of Temporary Directories. [[0;32m OK [0m] Listening on ACPID Listen Socket. [[0;32m OK [0m] Reached target Sockets. [[0;32m OK [0m] Reached target Basic System. Starting Permit User Sessions... Starting autopkgtest.service... Starting LSB: daemon to balance interrupts for SMP systems... Starting getty on tty2-tty6 if dbus and logind are not available... Starting System Logging Service... [[0;32m OK [0m] Started Regular background program processing daemon. [[0;32m OK [0m] Reached target System Time Synchronized. [[0;32m OK [0m] Started Daily apt activities. [[0;32m OK [0m] Reached target Timers. [[0;32m OK [0m] Started System Logging Service. [[0;32m OK [0m] Started Permit User Sessions. [[0;32m OK [0m] Started autopkgtest.service. [[0;32m OK [0m] Started getty on tty2-tty6 if dbus and logind are not available. [[0;32m OK [0m] Started LSB: daemon to balance interrupts for SMP systems. [[0;32m OK [0m] Started Raise network interfaces. [[0;32m OK [0m] Reached target Network. Starting /etc/rc.local Compatibility... [[0;32m OK [0m] Started /etc/rc.local Compatibility. [[0;32m OK [0m] Started Getty on tty5. [[0;32m OK [0m] Started Getty on tty3. [[0;32m OK [0m] Started Getty on tty6. [[0;32m OK [0m] Started Serial Getty on ttyS0. [[0;32m OK [0m] Started Getty on tty4. [[0;32m OK [0m] Started Getty on tty1. [[0;32m OK [0m] Started Getty on tty2. [[0;32m OK [0m] Reached target Login Prompts. [[0;32m OK [0m] Reached target Multi-User System. [[0;32m OK [0m] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [[0;32m OK [0m] Started Update UTMP about System Runlevel Changes. Debian GNU/Linux stretch/sid debian ttyS0 debian login: adt-virt-qemu: DBG: expect: found ""login prompt on ttyS0"" adt-virt-qemu: DBG: expect: "ok" adt-virt-qemu: DBG: expect: found ""b'ok'"" adt-virt-qemu: DBG: setup_shell(): there already is a shell on ttyS1 adt-virt-qemu: DBG: expect: "#" adt-virt-qemu: DBG: expect: found ""b'#'"" adt-virt-qemu: DBG: expect: "(qemu)" adt-virt-qemu: DBG: expect: found ""b'(qemu)'"" adt-virt-qemu: DBG: expect: "(qemu)" adt-virt-qemu: DBG: expect: found ""b'(qemu)'"" adt-virt-qemu: DBG: expect: "#" adt-virt-qemu: DBG: expect: found ""b'#'"" adt-virt-qemu: DBG: expect: "#" adt-virt-qemu: DBG: expect: found ""b'#'"" adt-virt-qemu: DBG: expect: "#" adt-virt-qemu: DBG: expect: found ""b'#'"" adt-virt-qemu: DBG: Copying host timezone Europe/London to VM adt-virt-qemu: DBG: expect: "#" adt-virt-qemu: DBG: expect: found ""b'#'"" adt-virt-qemu: DBG: expect: "/python" adt-virt-qemu: DBG: expect: found ""b'/python'"" adt-virt-qemu: DBG: expect: "# " adt-virt-qemu: DBG: expect: found ""b'# '"" adt-virt-qemu: DBG: execute-timeout: /tmp/adt-virt-qemu.lljt9jr6/runcmd true qemu-system-x86_64: terminating on signal 15 from pid 737 adt-virt-qemu: DBG: cleanup... Unexpected error: Traceback (most recent call last): File "/usr/share/autopkgtest/python/VirtSubproc.py", line 705, in mainloop command() File "/usr/share/autopkgtest/python/VirtSubproc.py", line 643, in command r = f(c, ce) File "/usr/share/autopkgtest/python/VirtSubproc.py", line 258, in cmd_open caller.hook_open() File "/usr/bin/adt-virt-qemu", line 567, in hook_open make_auxverb(shareddir) File "/usr/bin/adt-virt-qemu", line 413, in make_auxverb status = VirtSubproc.execute_timeout(None, 5, VirtSubproc.auxverb + ['true'])[0] File "/usr/share/autopkgtest/python/VirtSubproc.py", line 144, in execute_timeout (out, err) = sp.communicate(instr) File "/usr/lib/python3.5/subprocess.py", line 1057, in communicate self.wait() File "/usr/lib/python3.5/subprocess.py", line 1648, in wait (pid, sts) = self._try_wait(0) File "/usr/lib/python3.5/subprocess.py", line 1598, in _try_wait (pid, sts) = os.waitpid(self.pid, wait_flags) File "/usr/share/autopkgtest/python/VirtSubproc.py", line 64, in alarm_handler raise Timeout() VirtSubproc.Timeout adt-run: DBG: TestbedFailure unexpected eof from the testbed adt-run: DBG: testbed stop adt-run: DBG: testbed close, scratch=None adt-run: DBG: sending command to testbed: quit adt-run: DBG: TestbedFailure cannot send to testbed: ['BrokenPipeError: [Errno 32] Broken pipe\n'] adt-run: DBG: testbed stop adt-run [16:58:56]: ERROR: testbed failure: cannot send to testbed: ['BrokenPipeError: [Errno 32] Broken pipe\n'] adt-run: DBG: testbed stop
pgpCRdEIwOcUu.pgp
Description: OpenPGP digital signature
