Help
Invalid job definition:

extra keys not allowed @ data['actions[0]']['deploy']['tmpfs']

Description
[lavafed 2019.04.0032.g55afae484] qemu - health-check
Device-type
qemu
Submitter
Rémi Duraffort
Created
4 months ago
Priority
Medium
Visibility
Public
Required Tags
staging.validation.linaro.org
#461
Complete
Device
staging-qemu01@staging.validation.linaro.org
Started
4 months ago
Duration
2 minutes
Results
  
lava-dispatcher, installed at version: 2019.04.0032.g55afae484+stretch start: 0 validate Start time: 2019-05-15 09:07:37.054397+00:00 (UTC) Validating that http://images.validation.linaro.org/kvm/standard/large-stable-6.img.gz exists qemu-system-x86, installed at version: 1:2.8+dfsg-6+deb9u5, host architecture: amd64 validate duration: 0.08 case: validate
case_id: 10064
definition: lava
result: pass
start: 1 deployimages (timeout 00:06:00) [common] start: 1.1 download-retry (timeout 00:06:00) [common] start: 1.1.1 http-download (timeout 00:06:00) [common] downloading http://images.validation.linaro.org/kvm/standard/large-stable-6.img.gz saving as /var/lib/lava/dispatcher/tmp/lavafed-461/deployimages-6myyumlz/rootfs/large-stable-6.img total size: 172764747 (164MB) Using gunzip to decompress gz progress 0% (0MB) progress 5% (8MB) progress 10% (16MB) progress 15% (24MB) progress 20% (32MB) progress 25% (41MB) progress 30% (49MB) progress 35% (57MB) progress 40% (65MB) progress 45% (74MB) progress 50% (82MB) progress 55% (90MB) progress 60% (98MB) progress 65% (107MB) progress 70% (115MB) progress 75% (123MB) progress 80% (131MB) progress 85% (140MB) progress 90% (148MB) progress 95% (156MB) progress 100% (164MB) 164MB downloaded in 44.48s (3.70MB/s) end: 1.1.1 http-download (duration 00:00:45) [common] case: http-download
case_id: 10104
definition: lava
duration: 44.68
extra: ...
level: 1.1.1
namespace: common
result: pass
end: 1.1 download-retry (duration 00:00:45) [common] start: 1.2 lava-overlay (timeout 00:05:15) [common] [common] Preparing overlay tarball in /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0 makedir: /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin makedir: /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/tests makedir: /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/results Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-add-keys Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-add-sources Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-background-process-start Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-background-process-stop Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-common-functions Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-echo-ipv4 Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-install-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-installed-packages Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-os-build Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-probe-channel Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-probe-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-target-ip Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-target-mac Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-target-storage Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-test-case Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-test-event Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-test-feedback Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-test-raise Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-test-reference Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-test-runner Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-test-set Creating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-test-shell Updating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-add-keys (debian) Updating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-add-sources (debian) Updating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-install-packages (debian) Updating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-installed-packages (debian) Updating /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/bin/lava-os-build (debian) start: 1.2.1 lava-vland-overlay (timeout 00:05:15) [common] skipped lava-vland-overlay end: 1.2.1 lava-vland-overlay (duration 00:00:00) [common] start: 1.2.2 lava-multinode-overlay (timeout 00:05:15) [common] skipped lava-multinode-overlay end: 1.2.2 lava-multinode-overlay (duration 00:00:00) [common] start: 1.2.3 test-definition (timeout 00:05:15) [common] Loading test definitions start: 1.2.3.1 inline-repo-action (timeout 00:05:15) [common] Using /lava-461 at stage 0 uuid=461_1.2.3.1 testdef=None end: 1.2.3.1 inline-repo-action (duration 00:00:00) [common] start: 1.2.3.2 test-overlay (timeout 00:05:14) [common] end: 1.2.3.2 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 10105
definition: lava
duration: 0.40
extra: ...
level: 1.2.3.2
namespace: common
result: pass
start: 1.2.3.3 test-install-overlay (timeout 00:05:14) [common] end: 1.2.3.3 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 10106
definition: lava
duration: 0.05
extra: ...
level: 1.2.3.3
namespace: common
result: pass
start: 1.2.3.4 test-runscript-overlay (timeout 00:05:14) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/0/tests/0_env-dut-inline test_uuid 461_1.2.3.1 end: 1.2.3.4 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 10107
definition: lava
duration: 0.04
extra: ...
level: 1.2.3.4
namespace: common
result: pass
start: 1.2.3.5 git-repo-action (timeout 00:05:14) [common] Using /lava-461 at stage 0 Fetching tests from http://git.linaro.org/lava-team/lava-functional-tests.git Running '/usr/bin/git clone http://git.linaro.org/lava-team/lava-functional-tests.git /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/0/tests/1_smoke-tests --depth=1' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/0/tests/1_smoke-tests/lava-test-shell/smoke-tests-basic.yaml uuid=461_1.2.3.5 testdef=None end: 1.2.3.5 git-repo-action (duration 00:00:02) [common] case: git-repo-action
case_id: 10109
definition: lava
duration: 2.30
extra: ...
level: 1.2.3.5
namespace: common
result: pass
start: 1.2.3.6 test-overlay (timeout 00:05:11) [common] end: 1.2.3.6 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 10105
definition: lava
duration: 0.01
extra: ...
level: 1.2.3.6
namespace: common
result: pass
start: 1.2.3.7 test-install-overlay (timeout 00:05:11) [common] end: 1.2.3.7 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 10106
definition: lava
duration: 0.06
extra: ...
level: 1.2.3.7
namespace: common
result: pass
start: 1.2.3.8 test-runscript-overlay (timeout 00:05:11) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/0/tests/1_smoke-tests test_uuid 461_1.2.3.5 end: 1.2.3.8 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 10107
definition: lava
duration: 0.12
extra: ...
level: 1.2.3.8
namespace: common
result: pass
start: 1.2.3.9 git-repo-action (timeout 00:05:11) [common] Using /lava-461 at stage 0 Fetching tests from http://git.linaro.org/lava-team/lava-functional-tests.git Running '/usr/bin/git clone http://git.linaro.org/lava-team/lava-functional-tests.git /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/0/tests/2_singlenode-advanced --depth=1' Tests stored (tmp) in /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/0/tests/2_singlenode-advanced/lava-test-shell/single-node/singlenode03.yaml uuid=461_1.2.3.9 testdef=None end: 1.2.3.9 git-repo-action (duration 00:00:02) [common] case: git-repo-action
case_id: 10109
definition: lava
duration: 2.20
extra: ...
level: 1.2.3.9
namespace: common
result: pass
start: 1.2.3.10 test-overlay (timeout 00:05:09) [common] end: 1.2.3.10 test-overlay (duration 00:00:00) [common] case: test-overlay
case_id: 10105
definition: lava
duration: 0.01
extra: ...
level: 1.2.3.10
namespace: common
result: pass
start: 1.2.3.11 test-install-overlay (timeout 00:05:09) [common] end: 1.2.3.11 test-install-overlay (duration 00:00:00) [common] case: test-install-overlay
case_id: 10106
definition: lava
duration: 0.04
extra: ...
level: 1.2.3.11
namespace: common
result: pass
start: 1.2.3.12 test-runscript-overlay (timeout 00:05:09) [common] runner path: /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/0/tests/2_singlenode-advanced test_uuid 461_1.2.3.9 end: 1.2.3.12 test-runscript-overlay (duration 00:00:00) [common] case: test-runscript-overlay
case_id: 10107
definition: lava
duration: 0.04
extra: ...
level: 1.2.3.12
namespace: common
result: pass
Creating lava-test-runner.conf files Using lava-test-runner path: /var/lib/lava/dispatcher/tmp/lavafed-461/lava-overlay-6p8m5y_0/lava-461/0 for stage 0 - 0_env-dut-inline - 1_smoke-tests - 2_singlenode-advanced end: 1.2.3 test-definition (duration 00:00:06) [common] start: 1.2.4 compress-overlay (timeout 00:05:09) [common] end: 1.2.4 compress-overlay (duration 00:00:02) [common] start: 1.2.5 persistent-nfs-overlay (timeout 00:05:07) [common] end: 1.2.5 persistent-nfs-overlay (duration 00:00:00) [common] end: 1.2 lava-overlay (duration 00:00:08) [common] start: 1.3 apply-overlay-guest (timeout 00:05:07) [common] Overlay: /var/lib/lava/dispatcher/tmp/lavafed-461/compress-overlay-hcm2qd_n/overlay-1.2.4.tar.gz end: 1.3 apply-overlay-guest (duration 00:00:43) [common] case: apply-overlay-guest
case_id: 10138
definition: lava
duration: 43.14
extra: ...
level: 1.3
namespace: common
result: pass
start: 1.4 deploy-device-env (timeout 00:04:24) [common] end: 1.4 deploy-device-env (duration 00:00:00) [common] end: 1 deployimages (duration 00:01:36) [common] start: 2 boot-image-retry (timeout 00:02:00) [common] start: 2.1 boot-qemu-image (timeout 00:02:00) [common] start: 2.1.1 execute-qemu (timeout 00:02:00) [common] Extending command line for qcow2 test overlay Boot command: /usr/bin/qemu-system-x86_64 -cpu host -enable-kvm -nographic -net nic,model=virtio,macaddr=DE:AD:BE:EF:32:01 -net user -m 1024 -monitor none -drive format=raw,file=/var/lib/lava/dispatcher/tmp/lavafed-461/deployimages-6myyumlz/rootfs/large-stable-6.img -drive format=qcow2,file=/var/lib/lava/dispatcher/tmp/lavafed-461/apply-overlay-guest-dn791e6p/lava-guest.qcow2,media=disk,if=ide,id=lavatest started a shell command end: 2.1.1 execute-qemu (duration 00:00:01) [common] case: execute-qemu
case_id: 10139
definition: lava
duration: 1.10
extra: ...
level: 2.1.1
namespace: common
result: pass
end: 2.1 boot-qemu-image (duration 00:00:01) [common] start: 2.2 auto-login-action (timeout 00:01:59) [common] auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:02:00) [H[J[1;1H[?25l[m[H[J[1;1H[2;22HGNU GRUB version 2.02~beta2-22+deb8u1 [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 5s. [5;78H[22;1H The highlighted entry will be executed automatically in 4s. [5;78H[22;1H The highlighted entry will be executed automatically in 3s. [5;78H[22;1H The highlighted entry will be executed automatically in 2s. [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 3.16.0-4-amd64 ... Loading initial ramdisk ... [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Initializing cgroup subsys cpuacct Using line separator: #'\n'# Waiting for the login prompt Parsing kernel messages ['-+\\[ cut here \\]-+\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', '(Unhandled fault.*)\\r\\n', 'Kernel panic - (.*) end Kernel panic', 'Stack:\\s+(.*\\s+-+\\[ end trace (\\w*) \\]-+)', 'root@debian:', 'login:', 'Login incorrect'] [auto-login-action] Waiting for messages, (timeout 00:01:46) [ 0.000000] Linux version 3.16.0-4-amd64 (debian-kernel@lists.debian.org) (gcc version 4.8.4 (Debian 4.8.4-1) ) #1 SMP Debian 3.16.7-ckt25-1 (2016-03-06) [ 0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-3.16.0-4-amd64 root=UUID=a8940005-764e-413c-a2cf-560b6a951e99 ro console=tty0 console=tty1 console=ttyS0,38400n8 [ 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 0.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 enabled: cpu 0, old 0x7040600070406, new 0x7010600070106 [ 0.000000] found SMP MP-table at [mem 0x000f6aa0-0x000f6aaf] mapped at [ffff8800000f6aa0] [ 0.000000] Using GB pages for direct mapping [ 0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff] [ 0.000000] init_memory_mapping: [mem 0x3fc00000-0x3fdfffff] [ 0.000000] init_memory_mapping: [mem 0x3c000000-0x3fbfffff] [ 0.000000] init_memory_mapping: [mem 0x00100000-0x3bffffff] [ 0.000000] init_memory_mapping: [mem 0x3fe00000-0x3ffdffff] [ 0.000000] RAMDISK: [mem 0x36488000-0x3723bfff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F68D0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x000000003FFE18DC 000030 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x000000003FFE17B8 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x000000003FFE0040 001778 (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x000000003FFE0000 000040 [ 0.000000] ACPI: APIC 0x000000003FFE182C 000078 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x000000003FFE18A4 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000003ffdffff] [ 0.000000] Initmem setup node 0 [mem 0x00000000-0x3ffdffff] [ 0.000000] NODE_DATA [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] Zone ranges: [ 0.000000] DMA [mem 0x00001000-0x00ffffff] [ 0.000000] DMA32 [mem 0x01000000-0xffffffff] [ 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-0x3ffdffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] 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 1 CPUs, 0 hotplug CPUs [ 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] setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:1 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 27 pages/cpu @ffff88003fc00000 s80896 r8192 d21504 u2097152 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 3fc0d080 [ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 258409 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-3.16.0-4-amd64 root=UUID=a8940005-764e-413c-a2cf-560b6a951e99 ro console=tty0 console=tty1 console=ttyS0,38400n8 [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes) [ 0.000000] xsave: enabled xstate_bv 0x7, cntxt size 0x340 [ 0.000000] AGP: Checking aperture... [ 0.000000] AGP: No AGP bridge found [ 0.000000] Memory: 1007952K/1048056K available (5222K kernel code, 947K rwdata, 1836K rodata, 1204K init, 840K bss, 40104K reserved) [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU dyntick-idle grace-period acceleration is enabled. [ 0.000000] RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, 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] tsc: Detected 1598.148 MHz processor [ 0.008000] Calibrating delay loop (skipped) preset value.. 3196.29 BogoMIPS (lpj=6392592) [ 0.009951] pid_max: default: 32768 minimum: 301 [ 0.012014] ACPI: Core revision 20140424 [ 0.015414] ACPI: All ACPI Tables successfully acquired [ 0.017955] Security Framework initialized [ 0.020013] AppArmor: AppArmor disabled by boot time parameter [ 0.022547] Yama: disabled by default; enable with sysctl kernel.yama.* [ 0.024099] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes) [ 0.028270] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.032125] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes) [ 0.034949] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes) [ 0.036236] Initializing cgroup subsys memory [ 0.040012] Initializing cgroup subsys devices [ 0.042099] Initializing cgroup subsys freezer [ 0.044008] Initializing cgroup subsys net_cls [ 0.046090] Initializing cgroup subsys blkio [ 0.048010] Initializing cgroup subsys perf_event [ 0.050168] Initializing cgroup subsys net_prio [ 0.052089] CPU: Physical Processor ID: 0 [ 0.056881] mce: CPU supports 10 MCE banks [ 0.058897] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.058897] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.058897] tlb_flushall_shift: 6 [ 0.072382] Freeing SMP alternatives memory: 20K (ffffffff81a1b000 - ffffffff81a20000) [ 0.080375] ftrace: allocating 21683 entries in 85 pages [ 0.092551] Enabling x2apic [ 0.094099] Enabled x2apic [ 0.096006] Switched APIC routing to physical x2apic. [ 0.102421] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.104003] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2603 v3 @ 1.60GHz (fam: 06, model: 3f, stepping: 02) [ 0.109172] Performance Events: 16-deep LBR, Haswell events, Intel PMU driver. [ 0.112008] ... version: 2 [ 0.113942] ... bit width: 48 [ 0.115897] ... generic registers: 8 [ 0.116004] ... value mask: 0000ffffffffffff [ 0.118363] ... max period: 000000007fffffff [ 0.120004] ... fixed-purpose events: 3 [ 0.121931] ... event mask: 00000007000000ff [ 0.125715] x86: Booted up 1 node, 1 CPUs [ 0.127686] smpboot: Total of 1 processors activated (3196.29 BogoMIPS) [ 0.128593] NMI watchdog: enabled on all CPUs, permanently consumes one hw-PMU counter. [ 0.136109] devtmpfs: initialized [ 0.144828] pinctrl core: initialized pinctrl subsystem [ 0.147817] NET: Registered protocol family 16 [ 0.148155] cpuidle: using governor ladder [ 0.150169] cpuidle: using governor menu [ 0.152082] ACPI: bus type PCI registered [ 0.154056] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.156209] PCI: Using configuration type 1 for base access [ 0.159697] ACPI: Added _OSI(Module Device) [ 0.160006] ACPI: Added _OSI(Processor Device) [ 0.162112] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.164005] ACPI: Added _OSI(Processor Aggregator Device) [ 0.168135] ACPI: Interpreter enabled [ 0.170007] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S1_] (20140424/hwxface-580) [ 0.173366] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20140424/hwxface-580) [ 0.177371] ACPI: (supports S0 S3 S4 S5) [ 0.179295] ACPI: Using IOAPIC for interrupt routing [ 0.180024] PCI: Using host bridge windows from ACPI; if necessary, use \"pci=nocrs\" and report a bug [ 0.187274] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.188011] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI] [ 0.190977] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.192026] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.196385] acpiphp: Slot [3] registered [ 0.198344] acpiphp: Slot [4] registered [ 0.200046] acpiphp: Slot [5] registered [ 0.202284] acpiphp: Slot [6] registered [ 0.204042] acpiphp: Slot [7] registered [ 0.206270] acpiphp: Slot [8] registered [ 0.208041] acpiphp: Slot [9] registered [ 0.210267] acpiphp: Slot [10] registered [ 0.212041] acpiphp: Slot [11] registered [ 0.214307] acpiphp: Slot [12] registered [ 0.216043] acpiphp: Slot [13] registered [ 0.218288] acpiphp: Slot [14] registered [ 0.220042] acpiphp: Slot [15] registered [ 0.222293] acpiphp: Slot [16] registered [ 0.224042] acpiphp: Slot [17] registered [ 0.226296] acpiphp: Slot [18] registered [ 0.228042] acpiphp: Slot [19] registered [ 0.230309] acpiphp: Slot [20] registered [ 0.232041] acpiphp: Slot [21] registered [ 0.234313] acpiphp: Slot [22] registered [ 0.236041] acpiphp: Slot [23] registered [ 0.238309] acpiphp: Slot [24] registered [ 0.240043] acpiphp: Slot [25] registered [ 0.242304] acpiphp: Slot [26] registered [ 0.244041] acpiphp: Slot [27] registered [ 0.246304] acpiphp: Slot [28] registered [ 0.248043] acpiphp: Slot [29] registered [ 0.250288] acpiphp: Slot [30] registered [ 0.252042] acpiphp: Slot [31] registered [ 0.256026] PCI host bridge to bus 0000:00 [ 0.258295] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.260007] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7] [ 0.263065] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff] [ 0.264007] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff] [ 0.267323] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff] [ 0.278369] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.280010] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.283202] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.284006] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.287959] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.288025] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.322263] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.325983] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.329354] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.332838] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.336102] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 0.339274] ACPI: Enabled 3 GPEs in block 00 to 0F [ 0.340477] vgaarb: setting as boot device: PCI:0000:00:02.0 [ 0.342991] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none [ 0.344006] vgaarb: loaded [ 0.345525] vgaarb: bridge control possible 0000:00:02.0 [ 0.347996] PCI: Using ACPI for IRQ routing [ 0.348431] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.351483] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.356026] Switched to clocksource kvm-clock [ 0.363854] pnp: PnP ACPI init [ 0.365540] ACPI: bus type PNP registered [ 0.368327] pnp: PnP ACPI: found 6 devices [ 0.370306] ACPI: bus type PNP unregistered [ 0.378502] NET: Registered protocol family 2 [ 0.380804] TCP established hash table entries: 8192 (order: 4, 65536 bytes) [ 0.383788] TCP bind hash table entries: 8192 (order: 5, 131072 bytes) [ 0.386589] TCP: Hash tables configured (established 8192 bind 8192) [ 0.389344] TCP: reno registered [ 0.391228] UDP hash table entries: 512 (order: 2, 16384 bytes) [ 0.393820] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) [ 0.396606] NET: Registered protocol family 1 [ 0.398685] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.401286] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.403838] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.406638] Unpacking initramfs... [ 0.762183] Freeing initrd memory: 14032K (ffff880036488000 - ffff88003723c000) [ 0.766142] microcode: CPU0 sig=0x306f2, pf=0x1, revision=0x1 [ 0.768770] microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba [ 0.773063] futex hash table entries: 256 (order: 2, 16384 bytes) [ 0.775754] audit: initializing netlink subsys (disabled) [ 0.778198] audit: type=2000 audit(1557911367.498:1): initialized [ 0.781256] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 0.784061] zbud: loaded [ 0.785712] VFS: Disk quotas dquot_6.5.2 [ 0.787646] Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.790484] msgmni has been set to 1996 [ 0.792691] alg: No test for stdrng (krng) [ 0.794716] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) [ 0.798277] io scheduler noop registered [ 0.800245] io scheduler deadline registered [ 0.802320] io scheduler cfq registered (default) [ 0.804618] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 0.807119] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 0.810009] GHES: HEST is not enabled! [ 0.811952] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 0.840296] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 0.844274] Linux agpgart interface v0.103 [ 0.846426] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 0.851385] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 0.853680] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 0.856131] mousedev: PS/2 mouse device common for all mice [ 0.859070] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 [ 0.863080] rtc_cmos 00:00: RTC can wake from S4 [ 0.866004] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0 [ 0.868986] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs [ 0.872566] ledtrig-cpu: registered to indicate activity on CPUs [ 0.875272] AMD IOMMUv2 driver by Joerg Roedel <joerg.roedel@amd.com> [ 0.878032] AMD IOMMUv2 functionality not available on this system [ 0.880819] TCP: cubic registered [ 0.882561] NET: Registered protocol family 10 [ 0.884998] mip6: Mobile IPv6 [ 0.886627] NET: Registered protocol family 17 [ 0.888760] mpls_gso: MPLS GSO support [ 0.891044] registered taskstats version 1 [ 0.893654] rtc_cmos 00:00: setting system clock to 2019-05-15 09:09:27 UTC (1557911367) [ 0.898892] Freeing unused kernel memory: 1204K (ffffffff818ee000 - ffffffff81a1b000) [ 0.902613] Write protecting the kernel read-only data: 8192k [ 0.905480] Freeing unused kernel memory: 912K (ffff88000151c000 - ffff880001600000) [ 0.909685] Freeing unused kernel memory: 212K (ffff8800017cb000 - ffff880001800000) Loading, please wait... [ 0.924162] systemd-udevd[54]: starting version 215 [ 0.926733] random: systemd-udevd urandom read with 1 bits of entropy available [ 0.938825] SCSI subsystem initialized [ 0.951776] Floppy drive(s): fd0 is 2.88M AMI BIOS [ 0.968706] FDC 0 is a S82078B [ 1.011772] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11 [ 1.019099] scsi0 : ata_piix [ 1.020887] scsi1 : ata_piix [ 1.022576] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc020 irq 14 [ 1.025472] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc028 irq 15 [ 1.186956] ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100 [ 1.190392] ata1.00: 5859375 sectors, multi 16: LBA48 [ 1.193521] ata1.01: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100 [ 1.196825] ata1.01: 1048576 sectors, multi 16: LBA48 [ 1.199706] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100 [ 1.202983] ata2.00: configured for MWDMA2 [ 1.205715] ata1.00: configured for MWDMA2 [ 1.208441] ata1.01: configured for MWDMA2 [ 1.210521] scsi 0:0:0:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 [ 1.214618] scsi 0:0:1:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 [ 1.219128] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 [ 1.228346] sd 0:0:1:0: [sdb] 1048576 512-byte logical blocks: (536 MB/512 MiB) [ 1.231912] sd 0:0:0:0: [sda] 5859375 512-byte logical blocks: (3.00 GB/2.79 GiB) [ 1.236366] sr0: scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray [ 1.238860] cdrom: Uniform CD-ROM driver Revision: 3.20 [ 1.241286] sd 0:0:1:0: [sdb] Write Protect is off [ 1.244932] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 1.247456] sd 0:0:1:0: Attached scsi generic sg1 type 0 [ 1.250020] sd 0:0:0:0: [sda] Write Protect is off [ 1.252624] sd 0:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 1.256840] sr 1:0:0:0: Attached scsi generic sg2 type 5 [ 1.260689] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 1.267721] sdb: unknown partition table [ 1.275038] sda: sda1 [ 1.279239] sd 0:0:0:0: [sda] Attached SCSI disk [ 1.282219] sd 0:0:1:0: [sdb] Attached SCSI disk 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.25.2 [/sbin/fsck.ext4 (1) -- /dev/sda1] fsck.ext4 -a -C0 /dev/sda1 ext2fs_check_if_mount: Can't check if filesystem is mounted due to missing mtab file while determining whether /dev/sda1 is mounted. /dev/sda1: clean, 15999/183264 files, 144421/732160 blocks done. [ 1.480080] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null) done. Begin: Running /scripts/local-bottom ... done. Begin: Running /scripts/init-bottom ... done. [ 1.591885] systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) [ 1.598761] systemd[1]: Detected virtualization 'kvm'. [ 1.601889] systemd[1]: Detected architecture 'x86-64'. Welcome to [1mDebian GNU/Linux 8 (jessie)[0m! [ 1.631702] systemd[1]: Inserted module 'autofs4' [ 1.635326] systemd[1]: Set hostname to <debian>. [ 1.764147] tsc: Refined TSC clocksource calibration: 1598.165 MHz [ 1.802437] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 [ 1.829278] systemd[1]: Cannot add dependency job for unit dbus.socket, ignoring: Unit dbus.socket failed to load: No such file or directory. [ 1.835426] systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. [ 1.842403] systemd[1]: Expecting device dev-ttyS0.device... Expecting device dev-ttyS0.device... [ 1.847016] systemd[1]: Starting Forward Password Requests to Wall Directory Watch. [ 1.850814] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 1.854426] systemd[1]: Starting Remote File Systems (Pre). [[32m OK [0m] Reached target Remote File Systems (Pre). [ 1.859382] systemd[1]: Reached target Remote File Systems (Pre). [ 1.862132] systemd[1]: Starting Arbitrary Executable File Formats File System Automount Point. [[32m OK [0m] Set up automount Arbitrary Executable File Formats F...utomount Point. [ 1.869148] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. [ 1.873420] systemd[1]: Starting Dispatch Password Requests to Console Directory Watch. [ 1.877240] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 1.881028] systemd[1]: Starting Encrypted Volumes. [[32m OK [0m] Reached target Encrypted Volumes. [ 1.885626] systemd[1]: Reached target Encrypted Volumes. [ 1.888138] systemd[1]: Starting Swap. [[32m OK [0m] Reached target Swap. [ 1.892533] systemd[1]: Reached target Swap. [ 1.894928] systemd[1]: Starting Root Slice. [[32m OK [0m] Created slice Root Slice. [ 1.899688] systemd[1]: Created slice Root Slice. [ 1.925900] systemd[1]: Starting User and Session Slice. [[32m OK [0m] Created slice User and Session Slice. [ 1.931364] systemd[1]: Created slice User and Session Slice. [ 1.934322] systemd[1]: Starting Delayed Shutdown Socket. [[32m OK [0m] Listening on Delayed Shutdown Socket. [ 1.939771] systemd[1]: Listening on Delayed Shutdown Socket. [ 1.942726] systemd[1]: Starting /dev/initctl Compatibility Named Pipe. [[32m OK [0m] Listening on /dev/initctl Compatibility Named Pipe. [ 1.948958] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe. [ 1.952401] systemd[1]: Starting Journal Socket (/dev/log). [[32m OK [0m] Listening on Journal Socket (/dev/log). [ 1.957974] systemd[1]: Listening on Journal Socket (/dev/log). [ 1.961023] systemd[1]: Starting udev Control Socket. [[32m OK [0m] Listening on udev Control Socket. [ 1.966344] systemd[1]: Listening on udev Control Socket. [ 1.969164] systemd[1]: Starting udev Kernel Socket. [[32m OK [0m] Listening on udev Kernel Socket. [ 1.974390] systemd[1]: Listening on udev Kernel Socket. [ 1.977186] systemd[1]: Starting Journal Socket. [[32m OK [0m] Listening on Journal Socket. [ 1.982231] systemd[1]: Listening on Journal Socket. [ 1.984895] systemd[1]: Starting System Slice. [[32m OK [0m] Created slice System Slice. [ 1.989851] systemd[1]: Created slice System Slice. [ 1.992491] systemd[1]: Started File System Check on Root Device. [ 1.995644] systemd[1]: Starting Network Service... Starting Network Service... [ 2.000694] systemd[1]: Starting system-getty.slice. [[32m OK [0m] Created slice system-getty.slice. [ 2.009562] systemd[1]: Created slice system-getty.slice. [ 2.012878] systemd[1]: Starting system-serial\x2dgetty.slice. [[32m OK [0m] Created slice system-serial\x2dgetty.slice. [ 2.022058] systemd[1]: Created slice system-serial\x2dgetty.slice. [ 2.025380] systemd[1]: Starting Increase datagram queue length... Starting Increase datagram queue length... [ 2.032566] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required static device nodes...rrent kernel... [ 2.044748] systemd[1]: Started Set Up Additional Binary Formats. [ 2.048306] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... [ 2.055650] systemd[1]: Starting udev Coldplug all Devices... Starting udev Coldplug all Devices... [ 2.065081] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 2.073308] systemd[1]: Mounting POSIX Message Queue File System... Mounting POSIX Message Queue File System... [ 2.084567] systemd[1]: Mounting Debug File System... Mounting Debug File System... [ 2.096873] systemd[1]: Starting Slices. [[32m OK [0m] Reached target Slices. [ 2.102866] systemd[1]: Reached target Slices. [ 2.105630] systemd[1]: Starting Remount Root and Kernel File Systems... Starting Remount Root and Kernel File Systems... [[32m OK [0m] Mounted Debug File System. [ 2.113725] systemd[1]: Mounted Debug File System. [[32m OK [0m] Mounted POSIX Message Queue File System. [ 2.120396] systemd[1]: Mounted POSIX Message Queue File System. [[32m OK [0m[ 2.126912] EXT4-fs (sda1): re-mounted. Opts: errors=remount-ro ] Mounted Huge Pages File System. [ 2.131091] systemd[1]: Mounted Huge Pages File System. [[32m OK [0m] Started Network Service. [ 2.136765] systemd[1]: Started Network Service. [[32m OK [0m] Started Increase datagram queue length. [ 2.141979] systemd[1]: Started Increase datagram queue length. [[32m OK [0m] Started Create list of required static device nodes ...current kernel. [ 2.147856] systemd[1]: Started Create list of required static device nodes for the current kernel. [[32m OK [0m] Started Load Kernel Modules. [ 2.154745] systemd[1]: Started Load Kernel Modules. [[32m OK [0m] Started Remount Root and Kernel File Systems. [ 2.165496] systemd[1]: Started Remount Root and Kernel File Systems. [[32m OK [0m] Started udev Coldplug all Devices. [ 2.171403] systemd[1]: Started udev Coldplug all Devices. [ 2.187849] systemd[1]: Starting Various fixups to make systemd work better on Debian... Starting Various fixups to make systemd work better on Debian... [ 2.194645] systemd[1]: Starting Load/Save Random Seed... Starting Load/Save Random Seed... [ 2.200687] systemd[1]: Starting Apply Kernel Variables... Starting Apply Kernel Variables... [ 2.209401] systemd[1]: Mounted FUSE Control File System. [ 2.212505] systemd[1]: Mounted Configuration File System. [ 2.215089] systemd[1]: Starting Create Static Device Nodes in /dev... Starting Create Static Device Nodes in /dev... [ 2.228988] systemd[1]: Starting Syslog Socket. [[32m OK [0m] Listening on Syslog Socket. [ 2.235195] systemd[1]: Listening on Syslog Socket. [ 2.238631] systemd[1]: Starting Journal Service... Starting Journal Service... [[32m OK [0m] Started Journal Service. [ 2.250292] systemd[1]: Started Journal Service. [[32m OK [0m] Started Various fixups to make systemd work better on Debian. [[32m OK [0m] Started Load/Save Random Seed. [[32m OK [0m] Started Apply Kernel Variables. [[32m OK [0m] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [[32m OK [0m] Reached target Local File Systems (Pre). [[32m OK [0m] Reached target Local File Systems. Starting Create Volatile Files and Directories...[ 2.308496] systemd-udevd[158]: starting version 215 [[32m OK [0m] Reached target Remote File Systems. Starting Trigger Flushing of Journal to Persistent Storage... Starting LSB: Raise network interfaces.... [[32m OK [0m] Started udev Kernel Device Manager. [[32m OK [0m] Started Create Volatile Files and Directories. [ 2.371387] systemd-journald[156]: Received request to flush runtime journal from PID 1 Starting Update UTMP about System Boot/Shutdown... Starting Copy rules generated while the root was ro... [[32m OK [0m] Started Trigger Flushing of Journal to Persistent Storage. [[32m OK [0m[ 2.432190] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3 [ 2.435749] ACPI: Power Button [PWRF] ] Started Update UTMP about System Boot/Shutdown. [[32m OK [0m] Started Copy rules generated while the root was ro.[ 2.462934] parport_pc 00:04: reported by Plug and Play ACPI [ 2.466349] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE] [ 2.487468] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 2.503218] input: PC Speaker as /devices/platform/pcspkr/input/input4 [ 2.508626] [drm] Initialized drm 1.1.0 20060810 [[32m OK [0m] Found device /dev/ttyS0. [ 2.580073] AVX2 version of gcm_enc/dec engaged. [ 2.609553] ppdev: user-space parallel port driver [ 2.612654] [drm] Found bochs VGA, ID 0xb0c0. [ 2.614771] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebd0000. [ 2.626229] [TTM] Zone kernel: Available graphics memory: 512166 kiB [ 2.629055] [TTM] Initializing pool allocator [ 2.631346] [TTM] Initializing DMA pool allocator [ 2.636709] fbcon: bochsdrmfb (fb0) is primary device [ 2.661244] Console: switching to colour frame buffer device 128x48 [ 2.665563] alg: No test for __gcm-aes-aesni (__driver-gcm-aes-aesni) [ 2.669385] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device [ 2.671308] bochs-drm 0000:00:02.0: registered panic notifier [ 2.673426] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0 [ 2.678784] alg: No test for crc32 (crc32-pclmul) [ 2.681824] intel_rapl: no valid rapl domains found in package 0 [ 2.785632] random: nonblocking pool is initialized [[32m OK [0m] Started LSB: Raise network interfaces.. [[32m OK [0m] Reached target Network. [[32m OK [0m] Reached target System Initialization. [[32m OK [0m] Reached target Timers. [[32m OK [0m] Listening on ACPID Listen Socket. [[32m OK [0m] Reached target Sockets. [[32m OK [0m] Reached target Paths. [[32m OK [0m] Reached target Basic System. Starting Regular background program processing daemon... [[32m OK [0m] Started Regular background program processing daemon. Starting /etc/rc.local Compatibility... Starting getty on tty2-tty6 if dbus and logind are not available... Starting System Logging Service... Starting Permit User Sessions... [[32m OK [0m] Started /etc/rc.local Compatibility. [[32m OK [0m] Started Permit User Sessions. [[32m OK [0m] Started System Logging Service. [[32m OK [0m] Started getty on tty2-tty6 if dbus and logind are not available. Starting Getty on tty6... [[32m OK [0m] Started Getty on tty6. Starting Getty on tty5... [[32m OK [0m] Started Getty on tty5. Starting Getty on tty4... [[32m OK [0m] Started Getty on tty4. Starting Getty on tty3... [[32m OK [0m] Started Getty on tty3. Starting Getty on tty2... [[32m OK [0m] Started Getty on tty2. Starting Getty on tty1... [[32m OK [0m] Started Getty on tty1. Starting Serial Getty on ttyS0... [[32m OK [0m] Started Serial Getty on ttyS0. [[32m OK [0m] Reached target Login Prompts. [[32m OK [0m] Reached target Multi-User System. [[32m OK [0m] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [[32m OK [0m] Started Update UTMP about System Runlevel Changes. Debian GNU/Linux 8 debian ttyS0 Matched prompt #5: login: case: kernel-messages
case_id: 10141
definition: lava
duration: 4.29
extra: ...
level: 2.2
namespace: common
result: pass
Sending username root root debian login: root auto-login-action: Wait for prompt ['root@debian:', 'Login incorrect', 'Login timed out'] (timeout 00:01:42) root Linux debian 3.16.0-4-amd64 #1 SMP Debian 3.16.7-ckt25-1 (2016-03-06) x86_64 The programs included with the Debian GNU/Linux system are free software; the exact distribution terms for each program are described in the individual files in /usr/share/doc/*/copyright. Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent permitted by applicable law. end: 2.2 auto-login-action (duration 00:00:17) [common] case: auto-login-action
case_id: 10142
definition: lava
duration: 16.98
extra: ...
level: 2.2
namespace: common
result: pass
start: 2.3 expect-shell-connection (timeout 00:01:42) [common] Forcing a shell prompt, looking for ['root@debian:'] root@debian:~# expect-shell-connection: Wait for prompt ['root@debian:'] (timeout 00:02:00) Waiting using forced prompt support. 59.97417986392975s timeout end: 2.3 expect-shell-connection (duration 00:00:00) [common] start: 2.4 export-device-env (timeout 00:01:42) [common] end: 2.4 export-device-env (duration 00:00:00) [common] end: 2 boot-image-retry (duration 00:00:18) [common] start: 3 lava-test-retry (timeout 00:04:06) [common] start: 3.1 lava-test-shell (timeout 00:04:06) [common] Using namespace: common # root@debian:~# # lava-test-shell: Wait for prompt ['root@debian:'] (timeout 00:05:00) # mkdir /lava-461 root@debian:~# mkdir /lava-461 mkdir /lava-461 mount /dev/disk/by-uuid/d6c94d8b-94eb-4190-ae9c-fe9f301715cd -t ext2 /lava-461 root@debian:~# mount /dev/disk/by-uuid/d6c94d8b-94eb-4190-ae9c-fe9f301715cd -t ext2 /lava-461 mount /dev/disk/by-uuid/d6c94d8b-94eb-4190-ae9c-fe9f301715cd -t e xt2 /lava-461 [ 4.646213] EXT4-fs (sdb): mounting ext2 file system using the ext4 subsystem [ 4.652632] EXT4-fs (sdb): mounted filesystem without journal. Opts: (null) ls -la /lava-461/bin/lava-test-runner root@debian:~# ls -la /lava-461/bin/lava-test-runner ls -la /lava-461/bin/lava-test-runner -rwxr-xr-x 1 root root 1041 May 15 09:08 /lava-461/bin/lava-test-runner Using /lava-461 export SHELL=/bin/bash root@debian:~export SHELL=/bin/bash # export SHELL=/bin/bash /lava-461/bin/lava-test-runner /lava-461/0 root@debian:~# /lava-461/bin/lava-test-runner /lava-461/0 Test shell timeout: 10s (minimum of the action and connection timeout) /lava-461/bin/lava-test-runner /lava-461/0 + export TESTRUN_ID=0_env-dut-inline + TESTRUN_ID=0_env-dut-inline + cd /lava-461/0/tests/0_env-dut-inline ++ cat uuid + UUID=461_1.2.3.1 + set +x <LAVA_SIGNAL_STARTRUN 0_env-dut-inline 461_1.2.3.1> Received signal: <STARTRUN> 0_env-dut-inline 461_1.2.3.1 Starting test lava.0_env-dut-inline (461_1.2.3.1) Skipping test definition patterns. + printenv TERM=vt102 SHELL=/bin/bash LAVA_RESULT_DIR=/lava-461/0/results/0_env-dut-inline-1557911371 HUSHLOGIN=FALSE OLDPWD=/root TESTRUN_ID=0_env-dut-inline USER=root PATH=/lava-461/0/../bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin MAIL=/var/mail/root PWD=/lava-461/0/tests/0_env-dut-inline HOME=/root SHLVL=4 LOGNAME=root _=/usr/bin/printenv + set +x <LAVA_SIGNAL_ENDRUN 0_env-dut-inline 461_1.2.3.1> Received signal: <ENDRUN> 0_env-dut-inline 461_1.2.3.1 Ending use of test pattern. Ending test lava.0_env-dut-inline (461_1.2.3.1), duration 0.01 case: 0_env-dut-inline
case_id: 10144
definition: lava
duration: 0.01
namespace: common
path: inline/env-dut.yaml
run: {'steps': ['printenv']}
metadata: {'format': 'Lava-Test Test Definition 1.0', 'name': 'smoke-tests-basic', 'description': 'Basic system test command for Linaro Ubuntu images'}
result: pass
revision: unspecified
uuid: 461_1.2.3.1
+ export TESTRUN_ID=1_smoke-tests + TESTRUN_ID=1_smoke-tests + cd /lava-461/0/tests/1_smoke-tests ++ cat uuid + UUID=461_1.2.3.5 + set +x Received signal: <STARTRUN> 1_smoke-tests 461_1.2.3.5 Starting test lava.1_smoke-tests (461_1.2.3.5) Skipping test definition patterns. <LAVA_SIGNAL_STARTRUN 1_smoke-tests 461_1.2.3.5> + lava-test-case linux-posix-pwd --shell pwd Received signal: <STARTTC> linux-posix-pwd <LAVA_SIGNAL_STARTTC linux-posix-pwd> /lava-461/0/tests/1_smoke-tests <LAVA_SIGNAL_ENDTC linux-posix-pwd> Received signal: <ENDTC> linux-posix-pwd Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-pwd RESULT=pass case: linux-posix-pwd
case_id: 10145
definition: 1_smoke-tests
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-pwd RESULT=pass> + lava-test-case linux-posix-uname --shell uname -a <LAVA_SIGNAL_STARTTC linux-posix-uname> Linux debian 3.16.0-4-amd64 #1 SMP Debian 3.16.7-ckt25-1 (2016-03-06) x86_64 GNU/Linux <LAVA_SIGNAL_ENDTC linux-posix-uname> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-uname RESULT=pass> + lava-test-case linux-posix-vmstat --shell vmstat Received signal: <STARTTC> linux-posix-uname Received signal: <ENDTC> linux-posix-uname Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-uname RESULT=pass case: linux-posix-uname
case_id: 10146
definition: 1_smoke-tests
result: pass
<LAVA_SIGNAL_STARTTC linux-posix-vmstat> procs -----------memory---------- ---swap-- -----io---- -system-- ------cpu----- r b swpd free buff cache si so bi bo in cs us sy id wa st 0 0 0 951832 5760 25964 0 0 6182 106 1040 2069 6 50 39 5 0 <LAVA_SIGNAL_ENDTC linux-posix-vmstat> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-vmstat RESULT=pass> + lava-test-case linux-posix-ifconfig --shell ifconfig -a <LAVA_SIGNAL_STARTTC linux-posix-ifconfig> eth0 Link encap:Ethernet HWaddr de:ad:be:ef:32:01 inet addr:10.0.2.15 Bcast:10.0.2.255 Mask:255.255.255.0 inet6 addr: fe80::dcad:beff:feef:3201/64 Scope:Link inet6 addr: fec0::dcad:beff:feef:3201/64 Scope:Site UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:3 errors:0 dropped:0 overruns:0 frame:0 TX packets:5 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:1290 (1.2 KiB) TX bytes:942 (942.0 B) Received signal: <STARTTC> linux-posix-vmstat Received signal: <ENDTC> linux-posix-vmstat Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-vmstat RESULT=pass case: linux-posix-vmstat
case_id: 10147
definition: 1_smoke-tests
result: pass
Received signal: <STARTTC> linux-posix-ifconfig lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 inet6 addr: ::1/128 Scope:Host UP LOOPBACK RUNNING MTU:65536 Metric:1 RX packets:0 errors:0 dropped:0 overruns:0 frame:0 TX packets:0 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:0 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B) <LAVA_SIGNAL_ENDTC linux-posix-ifconfig> Received signal: <ENDTC> linux-posix-ifconfig <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-ifconfig RESULT=pass> + lava-test-case linux-posix-lscpu --shell lscpu Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-ifconfig RESULT=pass case: linux-posix-ifconfig
case_id: 10148
definition: 1_smoke-tests
result: pass
<LAVA_SIGNAL_STARTTC linux-posix-lscpu> Received signal: <STARTTC> linux-posix-lscpu Architecture: x86_64 CPU op-mode(s): 32-bit, 64-bit Byte Order: Little Endian CPU(s): 1 On-line CPU(s) list: 0 Thread(s) per core: 1 Core(s) per socket: 1 Socket(s): 1 NUMA node(s): 1 Vendor ID: GenuineIntel CPU family: 6 Model: 63 Model name: Intel(R) Xeon(R) CPU E5-2603 v3 @ 1.60GHz Stepping: 2 CPU MHz: 1598.148 BogoMIPS: 3196.29 Hypervisor vendor: KVM Virtualization type: full L1d cache: 32K L1i cache: 32K L2 cache: 4096K L3 cache: 16384K NUMA node0 CPU(s): 0 <LAVA_SIGNAL_ENDTC linux-posix-lscpu> Received signal: <ENDTC> linux-posix-lscpu Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lscpu RESULT=pass case: linux-posix-lscpu
case_id: 10149
definition: 1_smoke-tests
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-lscpu RESULT=pass> + lava-test-case linux-posix-lsb_release --shell lsb_release -a <LAVA_SIGNAL_STARTTC linux-posix-lsb_release> Received signal: <STARTTC> linux-posix-lsb_release /lava-461/0/../bin/lava-test-case: line 27: lsb_release: command not found <LAVA_SIGNAL_ENDTC linux-posix-lsb_release> Received signal: <ENDTC> linux-posix-lsb_release Received signal: <TESTCASE> TEST_CASE_ID=linux-posix-lsb_release RESULT=fail case: linux-posix-lsb_release
case_id: 10150
definition: 1_smoke-tests
result: fail
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-posix-lsb_release RESULT=fail> + set +x <LAVA_SIGNAL_ENDRUN 1_smoke-tests 461_1.2.3.5> Received signal: <ENDRUN> 1_smoke-tests 461_1.2.3.5 Ending use of test pattern. Ending test lava.1_smoke-tests (461_1.2.3.5), duration 0.11 case: 1_smoke-tests
case_id: 10151
commit_id: 0f8952f18e992371f8686c59b36f44e9c6a94a1a
definition: lava
duration: 0.11
namespace: common
path: lava-test-shell/smoke-tests-basic.yaml
repository: http://git.linaro.org/lava-team/lava-functional-tests.git
result: pass
revision: unspecified
uuid: 461_1.2.3.5
+ export TESTRUN_ID=2_singlenode-advanced + TESTRUN_ID=2_singlenode-advanced + cd /lava-461/0/tests/2_singlenode-advanced ++ cat uuid + UUID=461_1.2.3.9 + set +x Received signal: <STARTRUN> 2_singlenode-advanced 461_1.2.3.9 Starting test lava.2_singlenode-advanced (461_1.2.3.9) Skipping test definition patterns. <LAVA_SIGNAL_STARTRUN 2_singlenode-advanced 461_1.2.3.9> + apt-get update -q Ign http://mirror.bytemark.co.uk stable InRelease Get:1 http://mirror.bytemark.co.uk stable Release.gpg [2434 B] Get:2 http://mirror.bytemark.co.uk stable Release [118 kB] Get:3 http://mirror.bytemark.co.uk stable/main amd64 Packages [7082 kB] Get:4 http://mirror.bytemark.co.uk stable/main Translation-en [5384 kB] Fetched 12.6 MB in 5s (2183 kB/s) Reading package lists... W: There is no public key available for the following key IDs: EF0F382A1A7B6500 + DEBIAN_FRONTEND=noninteractive + lava-test-case install-base --shell apt-get -q -y install -o Dpkg::Options::=--force-confold curl lsb-release usbutils net-tools iputils-ping <LAVA_SIGNAL_STARTTC install-base> Received signal: <STARTTC> install-base Reading package lists... Building dependency tree... The following extra packages will be installed: bzip2 ca-certificates dh-python distro-info-data file krb5-locales libbz2-1.0 libc-bin libc6 libcurl3 libexpat1 libgcrypt20 libgnutls30 libgssapi-krb5-2 libhogweed4 libidn2-0 libk5crypto3 libkeyutils1 libkrb5-3 libkrb5support0 libldap-2.4-2 libldap-common liblzma5 libmagic-mgc libmagic1 libmpdec2 libncurses5 libncursesw5 libnettle6 libnghttp2-14 libp11-kit0 libpsl5 libpython3-stdlib libpython3.5-minimal libpython3.5-stdlib libreadline7 librtmp1 libsasl2-2 libsasl2-modules libsasl2-modules-db libsqlite3-0 libssh2-1 libssl1.0.2 libssl1.1 libtasn1-6 libtinfo5 libunistring0 libusb-1.0-0 mime-support openssl publicsuffix python3 python3-minimal python3.5 python3.5-minimal xz-utils Suggested packages: bzip2-doc libdpkg-perl glibc-doc libc-l10n locales rng-tools gnutls-bin krb5-doc krb5-user libsasl2-modules-gssapi-mit libsasl2-modules-gssapi-heimdal libsasl2-modules-ldap libsasl2-modules-otp libsasl2-modules-sql lsb python3-doc python3-tk python3-venv python3.5-venv python3.5-doc binutils binfmt-support Recommended packages: libgpm2 The following NEW packages will be installed: bzip2 ca-certificates curl dh-python distro-info-data file krb5-locales libcurl3 libexpat1 libgnutls30 libgssapi-krb5-2 libhogweed4 libidn2-0 libk5crypto3 libkeyutils1 libkrb5-3 libkrb5support0 libldap-2.4-2 libldap-common libmagic-mgc libmagic1 libmpdec2 libnettle6 libnghttp2-14 libpsl5 libpython3-stdlib libpython3.5-minimal libpython3.5-stdlib libreadline7 librtmp1 libsasl2-2 libsasl2-modules libsasl2-modules-db libsqlite3-0 libssh2-1 libssl1.0.2 libssl1.1 libunistring0 libusb-1.0-0 lsb-release mime-support openssl publicsuffix python3 python3-minimal python3.5 python3.5-minimal usbutils xz-utils The following packages will be upgraded: iputils-ping libbz2-1.0 libc-bin libc6 libgcrypt20 liblzma5 libncurses5 libncursesw5 libp11-kit0 libtasn1-6 libtinfo5 net-tools 12 upgraded, 49 newly installed, 0 to remove and 143 not upgraded. Need to get 19.4 MB of archives. After this operation, 54.0 MB of additional disk space will be used. Get:1 http://mirror.bytemark.co.uk/debian/ stable/main libc6 amd64 2.24-11+deb9u4 [2694 kB] Get:2 http://mirror.bytemark.co.uk/debian/ stable/main libc-bin amd64 2.24-11+deb9u4 [782 kB] Get:3 http://mirror.bytemark.co.uk/debian/ stable/main libssl1.1 amd64 1.1.0j-1~deb9u1 [1354 kB] Get:4 http://mirror.bytemark.co.uk/debian/ stable/main libpython3.5-minimal amd64 3.5.3-1+deb9u1 [573 kB] Get:5 http://mirror.bytemark.co.uk/debian/ stable/main libexpat1 amd64 2.2.0-2+deb9u1 [83.4 kB] Get:6 http://mirror.bytemark.co.uk/debian/ stable/main python3.5-minimal amd64 3.5.3-1+deb9u1 [1691 kB] Get:7 http://mirror.bytemark.co.uk/debian/ stable/main python3-minimal amd64 3.5.3-1 [35.3 kB] Get:8 http://mirror.bytemark.co.uk/debian/ stable/main mime-support all 3.60 [36.7 kB] Get:9 http://mirror.bytemark.co.uk/debian/ stable/main libbz2-1.0 amd64 1.0.6-8.1 [43.5 kB] Get:10 http://mirror.bytemark.co.uk/debian/ stable/main liblzma5 amd64 5.2.2-1.2+b1 [234 kB] Get:11 http://mirror.bytemark.co.uk/debian/ stable/main libmpdec2 amd64 2.4.2-1 [85.2 kB] Get:12 http://mirror.bytemark.co.uk/debian/ stable/main libtinfo5 amd64 6.0+20161126-1+deb9u2 [300 kB] Get:13 http://mirror.bytemark.co.uk/debian/ stable/main libncursesw5 amd64 6.0+20161126-1+deb9u2 [115 kB] Get:14 http://mirror.bytemark.co.uk/debian/ stable/main libncurses5 amd64 6.0+20161126-1+deb9u2 [93.4 kB] Get:15 http://mirror.bytemark.co.uk/debian/ stable/main libreadline7 amd64 7.0-3 [151 kB] Get:16 http://mirror.bytemark.co.uk/debian/ stable/main libsqlite3-0 amd64 3.16.2-5+deb9u1 [572 kB] Get:17 http://mirror.bytemark.co.uk/debian/ stable/main libpython3.5-stdlib amd64 3.5.3-1+deb9u1 [2167 kB] Get:18 http://mirror.bytemark.co.uk/debian/ stable/main python3.5 amd64 3.5.3-1+deb9u1 [229 kB] Get:19 http://mirror.bytemark.co.uk/debian/ stable/main libpython3-stdlib amd64 3.5.3-1 [18.6 kB] Get:20 http://mirror.bytemark.co.uk/debian/ stable/main dh-python all 2.20170125 [86.8 kB] Get:21 http://mirror.bytemark.co.uk/debian/ stable/main python3 amd64 3.5.3-1 [21.6 kB] Get:22 http://mirror.bytemark.co.uk/debian/ stable/main libgcrypt20 amd64 1.7.6-2+deb9u3 [524 kB] Get:23 http://mirror.bytemark.co.uk/debian/ stable/main libnettle6 amd64 3.3-1+b2 [192 kB] Get:24 http://mirror.bytemark.co.uk/debian/ stable/main iputils-ping amd64 3:20161105-1 [57.5 kB] Get:25 http://mirror.bytemark.co.uk/debian/ stable/main libssl1.0.2 amd64 1.0.2r-1~deb9u1 [1302 kB] Get:26 http://mirror.bytemark.co.uk/debian/ stable/main bzip2 amd64 1.0.6-8.1 [47.5 kB] Get:27 http://mirror.bytemark.co.uk/debian/ stable/main libmagic-mgc amd64 1:5.30-1+deb9u2 [222 kB] Get:28 http://mirror.bytemark.co.uk/debian/ stable/main libmagic1 amd64 1:5.30-1+deb9u2 [111 kB] Get:29 http://mirror.bytemark.co.uk/debian/ stable/main file amd64 1:5.30-1+deb9u2 [64.1 kB] Get:30 http://mirror.bytemark.co.uk/debian/ stable/main krb5-locales all 1.15-1+deb9u1 [93.8 kB] Get:31 http://mirror.bytemark.co.uk/debian/ stable/main libhogweed4 amd64 3.3-1+b2 [136 kB] Get:32 http://mirror.bytemark.co.uk/debian/ stable/main libp11-kit0 amd64 0.23.3-2 [111 kB] Get:33 http://mirror.bytemark.co.uk/debian/ stable/main libtasn1-6 amd64 4.10-1.1+deb9u1 [50.6 kB] Get:34 http://mirror.bytemark.co.uk/debian/ stable/main libgnutls30 amd64 3.5.8-5+deb9u4 [896 kB] Get:35 http://mirror.bytemark.co.uk/debian/ stable/main libkeyutils1 amd64 1.5.9-9 [12.4 kB] Get:36 http://mirror.bytemark.co.uk/debian/ stable/main libkrb5support0 amd64 1.15-1+deb9u1 [61.9 kB] Get:37 http://mirror.bytemark.co.uk/debian/ stable/main libk5crypto3 amd64 1.15-1+deb9u1 [119 kB] Get:38 http://mirror.bytemark.co.uk/debian/ stable/main libkrb5-3 amd64 1.15-1+deb9u1 [311 kB] Get:39 http://mirror.bytemark.co.uk/debian/ stable/main libgssapi-krb5-2 amd64 1.15-1+deb9u1 [155 kB] Get:40 http://mirror.bytemark.co.uk/debian/ stable/main libsasl2-modules-db amd64 2.1.27~101-g0780600+dfsg-3 [68.2 kB] Get:41 http://mirror.bytemark.co.uk/debian/ stable/main libsasl2-2 amd64 2.1.27~101-g0780600+dfsg-3 [105 kB] Get:42 http://mirror.bytemark.co.uk/debian/ stable/main libldap-common all 2.4.44+dfsg-5+deb9u2 [85.5 kB] Get:43 http://mirror.bytemark.co.uk/debian/ stable/main libldap-2.4-2 amd64 2.4.44+dfsg-5+deb9u2 [219 kB] Get:44 http://mirror.bytemark.co.uk/debian/ stable/main xz-utils amd64 5.2.2-1.2+b1 [266 kB] Get:45 http://mirror.bytemark.co.uk/debian/ stable/main openssl amd64 1.1.0j-1~deb9u1 [746 kB] Get:46 http://mirror.bytemark.co.uk/debian/ stable/main ca-certificates all 20161130+nmu1+deb9u1 [182 kB] Get:47 http://mirror.bytemark.co.uk/debian/ stable/main libunistring0 amd64 0.9.6+really0.9.3-0.1 [279 kB] Get:48 http://mirror.bytemark.co.uk/debian/ stable/main libidn2-0 amd64 0.16-1+deb9u1 [60.7 kB] Get:49 http://mirror.bytemark.co.uk/debian/ stable/main libnghttp2-14 amd64 1.18.1-1 [79.1 kB] Get:50 http://mirror.bytemark.co.uk/debian/ stable/main libpsl5 amd64 0.17.0-3 [41.8 kB] Get:51 http://mirror.bytemark.co.uk/debian/ stable/main librtmp1 amd64 2.4+20151223.gitfa8646d.1-1+b1 [60.4 kB] Get:52 http://mirror.bytemark.co.uk/debian/ stable/main libssh2-1 amd64 1.7.0-1+deb9u1 [139 kB] Get:53 http://mirror.bytemark.co.uk/debian/ stable/main libcurl3 amd64 7.52.1-5+deb9u9 [292 kB] Get:54 http://mirror.bytemark.co.uk/debian/ stable/main curl amd64 7.52.1-5+deb9u9 [227 kB] Get:55 http://mirror.bytemark.co.uk/debian/ stable/main distro-info-data all 0.36 [5810 B] Get:56 http://mirror.bytemark.co.uk/debian/ stable/main libsasl2-modules amd64 2.1.27~101-g0780600+dfsg-3 [102 kB] Get:57 http://mirror.bytemark.co.uk/debian/ stable/main libusb-1.0-0 amd64 2:1.0.21-1 [53.9 kB] Get:58 http://mirror.bytemark.co.uk/debian/ stable/main lsb-release all 9.20161125 [27.1 kB] Get:59 http://mirror.bytemark.co.uk/debian/ stable/main net-tools amd64 1.60+git20161116.90da8a0-1 [248 kB] Get:60 http://mirror.bytemark.co.uk/debian/ stable/main usbutils amd64 1:007-4+b1 [216 kB] Get:61 http://mirror.bytemark.co.uk/debian/ stable/main publicsuffix all 20190415.1030-0+deb9u1 [108 kB] Fetched 19.4 MB in 2s (7324 kB/s) Extracting templates from packages: 49%Extracting templates from packages: 98%Extracting templates from packages: 100% Preconfiguring packages ... (Reading database ... (Reading database ... 5%(Reading database ... 10%(Reading database ... 15%(Reading database ... 20%(Reading database ... 25%(Reading database ... 30%(Reading database ... 35%(Reading database ... 40%(Reading database ... 45%(Reading database ... 50%(Reading database ... 55%(Reading database ... 60%(Reading database ... 65%(Reading database ... 70%(Reading database ... 75%(Reading database ... 80%(Reading database ... 85%(Reading database ... 90%(Reading database ... 95%(Reading database ... 100%(Reading database ... 14263 files and directories currently installed.) Preparing to unpack .../libc6_2.24-11+deb9u4_amd64.deb ... Checking for services that may need to be restarted... Checking init scripts... Unpacking libc6:amd64 (2.24-11+deb9u4) over (2.19-18+deb8u4) ... Processing triggers for libc-bin (2.19-18+deb8u4) ... Setting up libc6:amd64 (2.24-11+deb9u4) ... Checking for services that may need to be restarted... Checking init scripts... Restarting services possibly affected by the upgrade: cron: restarting...done. Services restarted successfully. Processing triggers for libc-bin (2.19-18+deb8u4) ... (Reading database ... (Reading database ... 5%(Reading database ... 10%(Reading database ... 15%(Reading database ... 20%(Reading database ... 25%(Reading database ... 30%(Reading database ... 35%(Reading database ... 40%(Reading database ... 45%(Reading database ... 50%(Reading database ... 55%(Reading database ... 60%(Reading database ... 65%(Reading database ... 70%(Reading database ... 75%(Reading database ... 80%(Reading database ... 85%(Reading database ... 90%(Reading database ... 95%(Reading database ... 100%(Reading database ... 14264 files and directories currently installed.) Preparing to unpack .../libc-bin_2.24-11+deb9u4_amd64.deb ... Unpacking libc-bin (2.24-11+deb9u4) over (2.19-18+deb8u4) ... Processing triggers for man-db (2.7.0.2-5) ... Setting up libc-bin (2.24-11+deb9u4) ... Selecting previously unselected package libssl1.1:amd64. (Reading database ... (Reading database ... 5%(Reading database ... 10%(Reading database ... 15%(Reading database ... 20%(Reading database ... 25%(Reading database ... 30%(Reading database ... 35%(Reading database ... 40%(Reading database ... 45%(Reading database ... 50%(Reading database ... 55%(Reading database ... 60%(Reading database ... 65%(Reading database ... 70%(Reading database ... 75%(Reading database ... 80%(Reading database ... 85%(Reading database ... 90%(Reading database ... 95%(Reading database ... 100%(Reading database ... 14262 files and directories currently installed.) Preparing to unpack .../libssl1.1_1.1.0j-1~deb9u1_amd64.deb ... Unpacking libssl1.1:amd64 (1.1.0j-1~deb9u1) ... Selecting previously unselected package libpython3.5-minimal:amd64. Preparing to unpack .../libpython3.5-minimal_3.5.3-1+deb9u1_amd64.deb ... Unpacking libpython3.5-minimal:amd64 (3.5.3-1+deb9u1) ... Selecting previously unselected package libexpat1:amd64. Preparing to unpack .../libexpat1_2.2.0-2+deb9u1_amd64.deb ... Unpacking libexpat1:amd64 (2.2.0-2+deb9u1) ... Selecting previously unselected package python3.5-minimal. Preparing to unpack .../python3.5-minimal_3.5.3-1+deb9u1_amd64.deb ... Unpacking python3.5-minimal (3.5.3-1+deb9u1) ... Selecting previously unselected package python3-minimal. Preparing to unpack .../python3-minimal_3.5.3-1_amd64.deb ... Unpacking python3-minimal (3.5.3-1) ... Selecting previously unselected package mime-support. Preparing to unpack .../mime-support_3.60_all.deb ... Unpacking mime-support (3.60) ... Preparing to unpack .../libbz2-1.0_1.0.6-8.1_amd64.deb ... Unpacking libbz2-1.0:amd64 (1.0.6-8.1) over (1.0.6-7+b3) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... Processing triggers for man-db (2.7.0.2-5) ... Setting up libbz2-1.0:amd64 (1.0.6-8.1) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... (Reading database ... (Reading database ... 5%(Reading database ... 10%(Reading database ... 15%(Reading database ... 20%(Reading database ... 25%(Reading database ... 30%(Reading database ... 35%(Reading database ... 40%(Reading database ... 45%(Reading database ... 50%(Reading database ... 55%(Reading database ... 60%(Reading database ... 65%(Reading database ... 70%(Reading database ... 75%(Reading database ... 80%(Reading database ... 85%(Reading database ... 90%(Reading database ... 95%(Reading database ... 100%(Reading database ... 14564 files and directories currently installed.) Preparing to unpack .../liblzma5_5.2.2-1.2+b1_amd64.deb ... Unpacking liblzma5:amd64 (5.2.2-1.2+b1) over (5.1.1alpha+20120614-2+b3) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... Setting up liblzma5:amd64 (5.2.2-1.2+b1) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... Selecting previously unselected package libmpdec2:amd64. (Reading database ... (Reading database ... 5%(Reading database ... 10%(Reading database ... 15%(Reading database ... 20%(Reading database ... 25%(Reading database ... 30%(Reading database ... 35%(Reading database ... 40%(Reading database ... 45%(Reading database ... 50%(Reading database ... 55%(Reading database ... 60%(Reading database ... 65%(Reading database ... 70%(Reading database ... 75%(Reading database ... 80%(Reading database ... 85%(Reading database ... 90%(Reading database ... 95%(Reading database ... 100%(Reading database ... 14564 files and directories currently installed.) Preparing to unpack .../libmpdec2_2.4.2-1_amd64.deb ... Unpacking libmpdec2:amd64 (2.4.2-1) ... Preparing to unpack .../libtinfo5_6.0+20161126-1+deb9u2_amd64.deb ... Unpacking libtinfo5:amd64 (6.0+20161126-1+deb9u2) over (5.9+20140913-1+b1) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... Setting up libtinfo5:amd64 (6.0+20161126-1+deb9u2) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... (Reading database ... (Reading database ... 5%(Reading database ... 10%(Reading database ... 15%(Reading database ... 20%(Reading database ... 25%(Reading database ... 30%(Reading database ... 35%(Reading database ... 40%(Reading database ... 45%(Reading database ... 50%(Reading database ... 55%(Reading database ... 60%(Reading database ... 65%(Reading database ... 70%(Reading database ... 75%(Reading database ... 80%(Reading database ... 85%(Reading database ... 90%(Reading database ... 95%(Reading database ... 100%(Reading database ... 14570 files and directories currently installed.) Preparing to unpack .../libncursesw5_6.0+20161126-1+deb9u2_amd64.deb ... Unpacking libncursesw5:amd64 (6.0+20161126-1+deb9u2) over (5.9+20140913-1+b1) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... Setting up libncursesw5:amd64 (6.0+20161126-1+deb9u2) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... (Reading database ... (Reading database ... 5%(Reading database ... 10%(Reading database ... 15%(Reading database ... 20%(Reading database ... 25%(Reading database ... 30%(Reading database ... 35%(Reading database ... 40%(Reading database ... 45%(Reading database ... 50%(Reading database ... 55%(Reading database ... 60%(Reading database ... 65%(Reading database ... 70%(Reading database ... 75%(Reading database ... 80%(Reading database ... 85%(Reading database ... 90%(Reading database ... 95%(Reading database ... 100%(Reading database ... 14570 files and directories currently installed.) Preparing to unpack .../libncurses5_6.0+20161126-1+deb9u2_amd64.deb ... Unpacking libncurses5:amd64 (6.0+20161126-1+deb9u2) over (5.9+20140913-1+b1) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... Setting up libncurses5:amd64 (6.0+20161126-1+deb9u2) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... Selecting previously unselected package libreadline7:amd64. (Reading database ... (Reading database ... 5%(Reading database ... 10%(Reading database ... 15%(Reading database ... 20%(Reading database ... 25%(Reading database ... 30%(Reading database ... 35%(Reading database ... 40%(Reading database ... 45%(Reading database ... 50%(Reading database ... 55%(Reading database ... 60%(Reading database ... 65%(Reading database ... 70%(Reading database ... 75%(Reading database ... 80%(Reading database ... 85%(Reading database ... 90%(Reading database ... 95%(Reading database ... 100%(Reading database ... 14570 files and directories currently installed.) Preparing to unpack .../libreadline7_7.0-3_amd64.deb ... Unpacking libreadline7:amd64 (7.0-3) ... Selecting previously unselected package libsqlite3-0:amd64. Preparing to unpack .../libsqlite3-0_3.16.2-5+deb9u1_amd64.deb ... Unpacking libsqlite3-0:amd64 (3.16.2-5+deb9u1) ... Selecting previously unselected package libpython3.5-stdlib:amd64. Preparing to unpack .../libpython3.5-stdlib_3.5.3-1+deb9u1_amd64.deb ... Unpacking libpython3.5-stdlib:amd64 (3.5.3-1+deb9u1) ... Selecting previously unselected package python3.5. Preparing to unpack .../python3.5_3.5.3-1+deb9u1_amd64.deb ... Unpacking python3.5 (3.5.3-1+deb9u1) ... Selecting previously unselected package libpython3-stdlib:amd64. Preparing to unpack .../libpython3-stdlib_3.5.3-1_amd64.deb ... Unpacking libpython3-stdlib:amd64 (3.5.3-1) ... Selecting previously unselected package dh-python. Preparing to unpack .../dh-python_2.20170125_all.deb ... Unpacking dh-python (2.20170125) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... Processing triggers for man-db (2.7.0.2-5) ... Setting up libssl1.1:amd64 (1.1.0j-1~deb9u1) ... Setting up libpython3.5-minimal:amd64 (3.5.3-1+deb9u1) ... Setting up libexpat1:amd64 (2.2.0-2+deb9u1) ... Setting up python3.5-minimal (3.5.3-1+deb9u1) ... [ 29.784228] perf interrupt took too long (2687 > 2500), lowering kernel.perf_event_max_sample_rate to 50000 Setting up python3-minimal (3.5.3-1) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... Selecting previously unselected package python3. (Reading database ... (Reading database ... 5%(Reading database ... 10%(Reading database ... 15%(Reading database ... 20%(Reading database ... 25%(Reading database ... 30%(Reading database ... 35%(Reading database ... 40%(Reading database ... 45%(Reading database ... 50%(Reading database ... 55%(Reading database ... 60%(Reading database ... 65%(Reading database ... 70%(Reading database ... 75%(Reading database ... 80%(Reading database ... 85%(Reading database ... 90%(Reading database ... 95%(Reading database ... 100%(Reading database ... 15242 files and directories currently installed.) Preparing to unpack .../python3_3.5.3-1_amd64.deb ... Unpacking python3 (3.5.3-1) ... Preparing to unpack .../libgcrypt20_1.7.6-2+deb9u3_amd64.deb ... Unpacking libgcrypt20:amd64 (1.7.6-2+deb9u3) over (1.6.3-2+deb8u1) ... Processing triggers for man-db (2.7.0.2-5) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... Setting up libgcrypt20:amd64 (1.7.6-2+deb9u3) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... Selecting previously unselected package libnettle6:amd64. (Reading database ... (Reading database ... 5%(Reading database ... 10%(Reading database ... 15%(Reading database ... 20%(Reading database ... 25%(Reading database ... 30%(Reading database ... 35%(Reading database ... 40%(Reading database ... 45%(Reading database ... 50%(Reading database ... 55%(Reading database ... 60%(Reading database ... 65%(Reading database ... 70%(Reading database ... 75%(Reading database ... 80%(Reading database ... 85%(Reading database ... 90%(Reading database ... 95%(Reading database ... 100%(Reading database ... 15259 files and directories currently installed.) Preparing to unpack .../libnettle6_3.3-1+b2_amd64.deb ... Unpacking libnettle6:amd64 (3.3-1+b2) ... Preparing to unpack .../iputils-ping_3%3a20161105-1_amd64.deb ... Unpacking iputils-ping (3:20161105-1) over (3:20121221-5+b2) ... Selecting previously unselected package libssl1.0.2:amd64. Preparing to unpack .../libssl1.0.2_1.0.2r-1~deb9u1_amd64.deb ... Unpacking libssl1.0.2:amd64 (1.0.2r-1~deb9u1) ... Selecting previously unselected package bzip2. Preparing to unpack .../bzip2_1.0.6-8.1_amd64.deb ... Unpacking bzip2 (1.0.6-8.1) ... Selecting previously unselected package libmagic-mgc. Preparing to unpack .../libmagic-mgc_1%3a5.30-1+deb9u2_amd64.deb ... Unpacking libmagic-mgc (1:5.30-1+deb9u2) ... Selecting previously unselected package libmagic1:amd64. Preparing to unpack .../libmagic1_1%3a5.30-1+deb9u2_amd64.deb ... Unpacking libmagic1:amd64 (1:5.30-1+deb9u2) ... Selecting previously unselected package file. Preparing to unpack .../file_1%3a5.30-1+deb9u2_amd64.deb ... Unpacking file (1:5.30-1+deb9u2) ... Selecting previously unselected package krb5-locales. Preparing to unpack .../krb5-locales_1.15-1+deb9u1_all.deb ... Unpacking krb5-locales (1.15-1+deb9u1) ... Selecting previously unselected package libhogweed4:amd64. Preparing to unpack .../libhogweed4_3.3-1+b2_amd64.deb ... Unpacking libhogweed4:amd64 (3.3-1+b2) ... Preparing to unpack .../libp11-kit0_0.23.3-2_amd64.deb ... Unpacking libp11-kit0:amd64 (0.23.3-2) over (0.20.7-1) ... Preparing to unpack .../libtasn1-6_4.10-1.1+deb9u1_amd64.deb ... Unpacking libtasn1-6:amd64 (4.10-1.1+deb9u1) over (4.2-3+deb8u1) ... Selecting previously unselected package libgnutls30:amd64. Preparing to unpack .../libgnutls30_3.5.8-5+deb9u4_amd64.deb ... Unpacking libgnutls30:amd64 (3.5.8-5+deb9u4) ... Selecting previously unselected package libkeyutils1:amd64. Preparing to unpack .../libkeyutils1_1.5.9-9_amd64.deb ... Unpacking libkeyutils1:amd64 (1.5.9-9) ... Selecting previously unselected package libkrb5support0:amd64. Preparing to unpack .../libkrb5support0_1.15-1+deb9u1_amd64.deb ... Unpacking libkrb5support0:amd64 (1.15-1+deb9u1) ... Selecting previously unselected package libk5crypto3:amd64. Preparing to unpack .../libk5crypto3_1.15-1+deb9u1_amd64.deb ... Unpacking libk5crypto3:amd64 (1.15-1+deb9u1) ... Selecting previously unselected package libkrb5-3:amd64. Preparing to unpack .../libkrb5-3_1.15-1+deb9u1_amd64.deb ... Unpacking libkrb5-3:amd64 (1.15-1+deb9u1) ... Selecting previously unselected package libgssapi-krb5-2:amd64. Preparing to unpack .../libgssapi-krb5-2_1.15-1+deb9u1_amd64.deb ... Unpacking libgssapi-krb5-2:amd64 (1.15-1+deb9u1) ... Selecting previously unselected package libsasl2-modules-db:amd64. Preparing to unpack .../libsasl2-modules-db_2.1.27~101-g0780600+dfsg-3_amd64.deb ... Unpacking libsasl2-modules-db:amd64 (2.1.27~101-g0780600+dfsg-3) ... Selecting previously unselected package libsasl2-2:amd64. Preparing to unpack .../libsasl2-2_2.1.27~101-g0780600+dfsg-3_amd64.deb ... Unpacking libsasl2-2:amd64 (2.1.27~101-g0780600+dfsg-3) ... Selecting previously unselected package libldap-common. Preparing to unpack .../libldap-common_2.4.44+dfsg-5+deb9u2_all.deb ... Unpacking libldap-common (2.4.44+dfsg-5+deb9u2) ... Selecting previously unselected package libldap-2.4-2:amd64. Preparing to unpack .../libldap-2.4-2_2.4.44+dfsg-5+deb9u2_amd64.deb ... Unpacking libldap-2.4-2:amd64 (2.4.44+dfsg-5+deb9u2) ... Selecting previously unselected package xz-utils. Preparing to unpack .../xz-utils_5.2.2-1.2+b1_amd64.deb ... Unpacking xz-utils (5.2.2-1.2+b1) ... Selecting previously unselected package openssl. Preparing to unpack .../openssl_1.1.0j-1~deb9u1_amd64.deb ... Unpacking openssl (1.1.0j-1~deb9u1) ... Selecting previously unselected package ca-certificates. Preparing to unpack .../ca-certificates_20161130+nmu1+deb9u1_all.deb ... Unpacking ca-certificates (20161130+nmu1+deb9u1) ... Selecting previously unselected package libunistring0:amd64. Preparing to unpack .../libunistring0_0.9.6+really0.9.3-0.1_amd64.deb ... Unpacking libunistring0:amd64 (0.9.6+really0.9.3-0.1) ... Selecting previously unselected package libidn2-0:amd64. Preparing to unpack .../libidn2-0_0.16-1+deb9u1_amd64.deb ... Unpacking libidn2-0:amd64 (0.16-1+deb9u1) ... Selecting previously unselected package libnghttp2-14:amd64. Preparing to unpack .../libnghttp2-14_1.18.1-1_amd64.deb ... Unpacking libnghttp2-14:amd64 (1.18.1-1) ... Selecting previously unselected package libpsl5:amd64. Preparing to unpack .../libpsl5_0.17.0-3_amd64.deb ... Unpacking libpsl5:amd64 (0.17.0-3) ... Selecting previously unselected package librtmp1:amd64. Preparing to unpack .../librtmp1_2.4+20151223.gitfa8646d.1-1+b1_amd64.deb ... Unpacking librtmp1:amd64 (2.4+20151223.gitfa8646d.1-1+b1) ... Selecting previously unselected package libssh2-1:amd64. Preparing to unpack .../libssh2-1_1.7.0-1+deb9u1_amd64.deb ... Unpacking libssh2-1:amd64 (1.7.0-1+deb9u1) ... Selecting previously unselected package libcurl3:amd64. Preparing to unpack .../libcurl3_7.52.1-5+deb9u9_amd64.deb ... Unpacking libcurl3:amd64 (7.52.1-5+deb9u9) ... Selecting previously unselected package curl. Preparing to unpack .../curl_7.52.1-5+deb9u9_amd64.deb ... Unpacking curl (7.52.1-5+deb9u9) ... Selecting previously unselected package distro-info-data. Preparing to unpack .../distro-info-data_0.36_all.deb ... Unpacking distro-info-data (0.36) ... Selecting previously unselected package libsasl2-modules:amd64. Preparing to unpack .../libsasl2-modules_2.1.27~101-g0780600+dfsg-3_amd64.deb ... Unpacking libsasl2-modules:amd64 (2.1.27~101-g0780600+dfsg-3) ... Selecting previously unselected package libusb-1.0-0:amd64. Preparing to unpack .../libusb-1.0-0_2%3a1.0.21-1_amd64.deb ... Unpacking libusb-1.0-0:amd64 (2:1.0.21-1) ... Selecting previously unselected package lsb-release. Preparing to unpack .../lsb-release_9.20161125_all.deb ... Unpacking lsb-release (9.20161125) ... Preparing to unpack .../net-tools_1.60+git20161116.90da8a0-1_amd64.deb ... Unpacking net-tools (1.60+git20161116.90da8a0-1) over (1.60-26+b1) ... Selecting previously unselected package usbutils. Preparing to unpack .../usbutils_1%3a007-4+b1_amd64.deb ... Unpacking usbutils (1:007-4+b1) ... Selecting previously unselected package publicsuffix. Preparing to unpack .../publicsuffix_20190415.1030-0+deb9u1_all.deb ... Unpacking publicsuffix (20190415.1030-0+deb9u1) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... Processing triggers for man-db (2.7.0.2-5) ... Setting up mime-support (3.60) ... Setting up libmpdec2:amd64 (2.4.2-1) ... Setting up libreadline7:amd64 (7.0-3) ... Setting up libsqlite3-0:amd64 (3.16.2-5+deb9u1) ... Setting up libpython3.5-stdlib:amd64 (3.5.3-1+deb9u1) ... Setting up python3.5 (3.5.3-1+deb9u1) ... Setting up libpython3-stdlib:amd64 (3.5.3-1) ... Setting up libnettle6:amd64 (3.3-1+b2) ... Setting up iputils-ping (3:20161105-1) ... Setting up libssl1.0.2:amd64 (1.0.2r-1~deb9u1) ... Setting up bzip2 (1.0.6-8.1) ... Setting up libmagic-mgc (1:5.30-1+deb9u2) ... Setting up libmagic1:amd64 (1:5.30-1+deb9u2) ... Setting up file (1:5.30-1+deb9u2) ... Setting up krb5-locales (1.15-1+deb9u1) ... Setting up libhogweed4:amd64 (3.3-1+b2) ... Setting up libp11-kit0:amd64 (0.23.3-2) ... Setting up libtasn1-6:amd64 (4.10-1.1+deb9u1) ... Setting up libgnutls30:amd64 (3.5.8-5+deb9u4) ... Setting up libkeyutils1:amd64 (1.5.9-9) ... Setting up libkrb5support0:amd64 (1.15-1+deb9u1) ... Setting up libk5crypto3:amd64 (1.15-1+deb9u1) ... Setting up libkrb5-3:amd64 (1.15-1+deb9u1) ... Setting up libgssapi-krb5-2:amd64 (1.15-1+deb9u1) ... Setting up libsasl2-modules-db:amd64 (2.1.27~101-g0780600+dfsg-3) ... Setting up libsasl2-2:amd64 (2.1.27~101-g0780600+dfsg-3) ... Setting up libldap-common (2.4.44+dfsg-5+deb9u2) ... Setting up libldap-2.4-2:amd64 (2.4.44+dfsg-5+deb9u2) ... Setting up xz-utils (5.2.2-1.2+b1) ... update-alternatives: using /usr/bin/xz to provide /usr/bin/lzma (lzma) in auto mode Setting up openssl (1.1.0j-1~deb9u1) ... Setting up ca-certificates (20161130+nmu1+deb9u1) ... Updating certificates in /etc/ssl/certs... 151 added, 0 removed; done. Setting up libunistring0:amd64 (0.9.6+really0.9.3-0.1) ... Setting up libidn2-0:amd64 (0.16-1+deb9u1) ... Setting up libnghttp2-14:amd64 (1.18.1-1) ... Setting up libpsl5:amd64 (0.17.0-3) ... Setting up librtmp1:amd64 (2.4+20151223.gitfa8646d.1-1+b1) ... Setting up libssh2-1:amd64 (1.7.0-1+deb9u1) ... Setting up libcurl3:amd64 (7.52.1-5+deb9u9) ... Setting up curl (7.52.1-5+deb9u9) ... Setting up distro-info-data (0.36) ... Setting up libsasl2-modules:amd64 (2.1.27~101-g0780600+dfsg-3) ... Setting up libusb-1.0-0:amd64 (2:1.0.21-1) ... Setting up net-tools (1.60+git20161116.90da8a0-1) ... Setting up usbutils (1:007-4+b1) ... Setting up publicsuffix (20190415.1030-0+deb9u1) ... Setting up python3 (3.5.3-1) ... Setting up lsb-release (9.20161125) ... Setting up dh-python (2.20170125) ... Processing triggers for libc-bin (2.24-11+deb9u4) ... Processing triggers for ca-certificates (20161130+nmu1+deb9u1) ... Updating certificates in /etc/ssl/certs... 0 added, 0 removed; done. Running hooks in /etc/ca-certificates/update.d... done. <LAVA_SIGNAL_ENDTC install-base> Received signal: <ENDTC> install-base Received signal: <TESTCASE> TEST_CASE_ID=install-base RESULT=pass case: install-base
case_id: 10155
definition: 2_singlenode-advanced
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=install-base RESULT=pass> + lava-test-case linux-linaro-ubuntu-netstat --shell netstat -an Received signal: <STARTTC> linux-linaro-ubuntu-netstat <LAVA_SIGNAL_STARTTC linux-linaro-ubuntu-netstat> Active Internet connections (servers and established) Proto Recv-Q Send-Q Local Address Foreign Address State tcp 0 0 10.0.2.15:60165 80.68.83.150:80 TIME_WAIT tcp 0 0 10.0.2.15:60164 80.68.83.150:80 TIME_WAIT udp 0 0 0.0.0.0:7109 0.0.0.0:* udp 0 0 0.0.0.0:68 0.0.0.0:* udp6 0 0 :::29702 :::* Active UNIX domain sockets (servers and established) Proto RefCnt Flags Type State I-Node Path unix 2 [ ] DGRAM 8248 /run/systemd/journal/syslog unix 2 [ ACC ] STREAM LISTENING 9149 /run/acpid.socket unix 2 [ ] DGRAM 7646 /run/systemd/notify unix 2 [ ACC ] STREAM LISTENING 7648 /run/systemd/private unix 2 [ ] DGRAM 7661 /run/systemd/shutdownd unix 4 [ ] DGRAM 7666 /run/systemd/journal/dev-log unix 2 [ ACC ] SEQPACKET LISTENING 7670 /run/udev/control unix 2 [ ACC ] STREAM LISTENING 7673 /run/systemd/journal/stdout unix 5 [ ] DGRAM 7675 /run/systemd/journal/socket unix 2 [ ] DGRAM 7714 unix 3 [ ] STREAM CONNECTED 7712 unix 2 [ ] DGRAM 9795 unix 3 [ ] STREAM CONNECTED 8393 /run/systemd/journal/stdout unix 2 [ ] DGRAM 9083 unix 3 [ ] DGRAM 8455 unix 3 [ ] STREAM CONNECTED 10520 /run/systemd/journal/stdout unix 2 [ ] DGRAM 8446 unix 2 [ ] DGRAM 10472 unix 3 [ ] DGRAM 8456 unix 3 [ ] STREAM CONNECTED 10519 <LAVA_SIGNAL_ENDTC linux-linaro-ubuntu-netstat> Received signal: <ENDTC> linux-linaro-ubuntu-netstat <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-linaro-ubuntu-netstat RESULT=pass> Received signal: <TESTCASE> TEST_CASE_ID=linux-linaro-ubuntu-netstat RESULT=pass case: linux-linaro-ubuntu-netstat
case_id: 10156
definition: 2_singlenode-advanced
result: pass
+ lava-test-case linux-linaro-ubuntu-ifconfig-dump --shell ifconfig -a <LAVA_SIGNAL_STARTTC linux-linaro-ubuntu-ifconfig-dump> Received signal: <STARTTC> linux-linaro-ubuntu-ifconfig-dump eth0: flags=4163<UP,BROADCAST,RUNNING,MULTICAST> mtu 1500 inet 10.0.2.15 netmask 255.255.255.0 broadcast 10.0.2.255 inet6 fe80::dcad:beff:feef:3201 prefixlen 64 scopeid 0x20<link> inet6 fec0::dcad:beff:feef:3201 prefixlen 64 scopeid 0x40<site> ether de:ad:be:ef:32:01 txqueuelen 1000 (Ethernet) RX packets 22430 bytes 33267859 (31.7 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 9038 bytes 499532 (487.8 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 lo: flags=73<UP,LOOPBACK,RUNNING> mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 inet6 ::1 prefixlen 128 scopeid 0x10<host> loop txqueuelen 0 (Local Loopback) RX packets 0 bytes 0 (0.0 B) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 0 bytes 0 (0.0 B) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 <LAVA_SIGNAL_ENDTC linux-linaro-ubuntu-ifconfig-dump> Received signal: <ENDTC> linux-linaro-ubuntu-ifconfig-dump <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-linaro-ubuntu-ifconfig-dump RESULT=pass> Received signal: <TESTCASE> TEST_CASE_ID=linux-linaro-ubuntu-ifconfig-dump RESULT=pass case: linux-linaro-ubuntu-ifconfig-dump
case_id: 10157
definition: 2_singlenode-advanced
result: pass
+ lava-test-case linux-linaro-ubuntu-route-dump-a --shell route Received signal: <STARTTC> linux-linaro-ubuntu-route-dump-a <LAVA_SIGNAL_STARTTC linux-linaro-ubuntu-route-dump-a> Kernel IP routing table Destination Gateway Genmask Flags Metric Ref Use Iface default serial2.lavalab 0.0.0.0 UG 0 0 0 eth0 10.0.2.0 0.0.0.0 255.255.255.0 U 0 0 0 eth0 Received signal: <ENDTC> linux-linaro-ubuntu-route-dump-a <LAVA_SIGNAL_ENDTC linux-linaro-ubuntu-route-dump-a> Received signal: <TESTCASE> TEST_CASE_ID=linux-linaro-ubuntu-route-dump-a RESULT=pass case: linux-linaro-ubuntu-route-dump-a
case_id: 10158
definition: 2_singlenode-advanced
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-linaro-ubuntu-route-dump-a RESULT=pass> + lava-test-case linux-linaro-ubuntu-route-ifconfig-up-lo --shell ifconfig lo up <LAVA_SIGNAL_STARTTC linux-linaro-ubuntu-route-ifconfig-up-lo> Received signal: <STARTTC> linux-linaro-ubuntu-route-ifconfig-up-lo Received signal: <ENDTC> linux-linaro-ubuntu-route-ifconfig-up-lo <LAVA_SIGNAL_ENDTC linux-linaro-ubuntu-route-ifconfig-up-lo> Received signal: <TESTCASE> TEST_CASE_ID=linux-linaro-ubuntu-route-ifconfig-up-lo RESULT=pass case: linux-linaro-ubuntu-route-ifconfig-up-lo
case_id: 10159
definition: 2_singlenode-advanced
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-linaro-ubuntu-route-ifconfig-up-lo RESULT=pass> + lava-test-case linux-linaro-ubuntu-route-dump-b --shell route <LAVA_SIGNAL_STARTTC linux-linaro-ubuntu-route-dump-b> Received signal: <STARTTC> linux-linaro-ubuntu-route-dump-b Kernel IP routing table Destination Gateway Genmask Flags Metric Ref Use Iface default serial2.lavalab 0.0.0.0 UG 0 0 0 eth0 10.0.2.0 0.0.0.0 255.255.255.0 U 0 0 0 eth0 <LAVA_SIGNAL_ENDTC linux-linaro-ubuntu-route-dump-b> Received signal: <ENDTC> linux-linaro-ubuntu-route-dump-b Received signal: <TESTCASE> TEST_CASE_ID=linux-linaro-ubuntu-route-dump-b RESULT=pass case: linux-linaro-ubuntu-route-dump-b
case_id: 10160
definition: 2_singlenode-advanced
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=linux-linaro-ubuntu-route-dump-b RESULT=pass> + lava-test-case ping-test --shell ping -W1 -c1 www.linaro.org <LAVA_SIGNAL_STARTTC ping-test> Received signal: <STARTTC> ping-test PING www.linaro.org (143.204.192.2) 56(84) bytes of data. --- www.linaro.org ping statistics --- 1 packets transmitted, 0 received, 100% packet loss, time 0ms <LAVA_SIGNAL_ENDTC ping-test> Received signal: <ENDTC> ping-test Received signal: <TESTCASE> TEST_CASE_ID=ping-test RESULT=fail case: ping-test
case_id: 10168
definition: 2_singlenode-advanced
result: fail
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=ping-test RESULT=fail> + lava-test-case curl-http --shell curl -o img.tar.gz http://images.validation.linaro.org/lava-masters/dynamic/cubietruck/cbtruck_img.tar.gz <LAVA_SIGNAL_STARTTC curl-http> Received signal: <STARTTC> curl-http % Total % Received % Xferd Average Speed Time Time Time Current Dload Upload Total Spent Left Speed 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0100 11.7M 100 11.7M 0 0 47.3M 0 --:--:-- --:--:-- --:--:-- 47.4M Received signal: <ENDTC> curl-http <LAVA_SIGNAL_ENDTC curl-http> <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=curl-http RESULT=pass> Received signal: <TESTCASE> TEST_CASE_ID=curl-http RESULT=pass case: curl-http
case_id: 10169
definition: 2_singlenode-advanced
result: pass
+ lava-test-case tar-tgz --shell tar -tzf img.tar.gz Received signal: <STARTTC> tar-tgz <LAVA_SIGNAL_STARTTC tar-tgz> linaro/ linaro/sun7i-a20-cubietruck.dtb linaro/zImage linaro/linaro-image-minimal-initramfs-genericarmv7a-20150113-315.rootfs.cpio.gz.u-boot <LAVA_SIGNAL_ENDTC tar-tgz> Received signal: <ENDTC> tar-tgz Received signal: <TESTCASE> TEST_CASE_ID=tar-tgz RESULT=pass case: tar-tgz
case_id: 10170
definition: 2_singlenode-advanced
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=tar-tgz RESULT=pass> + lava-test-case remove-tgz --shell rm img.tar.gz Received signal: <STARTTC> remove-tgz <LAVA_SIGNAL_STARTTC remove-tgz> Received signal: <ENDTC> remove-tgz <LAVA_SIGNAL_ENDTC remove-tgz> Received signal: <TESTCASE> TEST_CASE_ID=remove-tgz RESULT=pass case: remove-tgz
case_id: 10171
definition: 2_singlenode-advanced
result: pass
<LAVA_SIGNAL_TESTCASE TEST_CASE_ID=remove-tgz RESULT=pass> + set +x <LAVA_SIGNAL_ENDRUN 2_singlenode-advanced 461_1.2.3.9> Received signal: <ENDRUN> 2_singlenode-advanced 461_1.2.3.9 Ending use of test pattern. Ending test lava.2_singlenode-advanced (461_1.2.3.9), duration 42.40 case: 2_singlenode-advanced
case_id: 10172
commit_id: 0f8952f18e992371f8686c59b36f44e9c6a94a1a
definition: lava
duration: 42.40
namespace: common
path: lava-test-shell/single-node/singlenode03.yaml
repository: http://git.linaro.org/lava-team/lava-functional-tests.git
result: pass
revision: unspecified
uuid: 461_1.2.3.9
<LAVA_TEST_RUNNER EXIT> ok: lava_test_shell seems to have completed curl-http: pass install-base: pass linux-linaro-ubuntu-ifconfig-dump: pass linux-linaro-ubuntu-netstat: pass linux-linaro-ubuntu-route-dump-a: pass linux-linaro-ubuntu-route-dump-b: pass linux-linaro-ubuntu-route-ifconfig-up-lo: pass linux-posix-ifconfig: pass linux-posix-lsb_release: fail linux-posix-lscpu: pass linux-posix-pwd: pass linux-posix-uname: pass linux-posix-vmstat: pass ping-test: fail remove-tgz: pass tar-tgz: pass end: 3.1 lava-test-shell (duration 00:00:43) [common] end: 3 lava-test-retry (duration 00:00:43) [common] start: 4 finalize (timeout 00:00:30) [common] start: 4.1 power-off (timeout 00:00:10) [common] end: 4.1 power-off (duration 00:00:00) [common] start: 4.2 read-feedback (timeout 00:00:30) [common] Listened to connection for namespace 'common' for 1s Finalising connection for namespace 'common' poweroff root@debian:~# poweroff Disconnecting QemuSession Disconnecting from qemu: end: 4.2 read-feedback (duration 00:00:03) [common] Disconnecting QemuSession Disconnecting from qemu: end: 4 finalize (duration 00:00:03) [common] Cleaning after the job Cleaning up download directory: /var/lib/lava/dispatcher/tmp/lavafed-461/deployimages-6myyumlz/rootfs Root tmp directory removed at /var/lib/lava/dispatcher/tmp/lavafed-461 Job finished correctly case: job
case_id: 10174
definition: lava
result: pass

Top of page

Please read the triage guidelines for help on debugging failures in the test job, test definitions or in individual test cases.

Job Description YAML

Pipeline jobs use the job definition to generate a descriptive YAML file when the pipeline is validated. The file represents the data sent to the dispatcher at the start of the job, including details of the device as well as the job parameters and the details of the parameters sent to each of the deploy, boot and test actions in the job pipeline.

Click on the Metadata, Device or Job Actions headings below to see some of the information from the description file for this job or download the complete file as YAML using the link above.

features.1.type
device
features.1.action
boot
features.3.type
device
device.type
qemu
features.3.description
Inline test definition
job.type
test
job.name
qemu - health-check
features.1.name
boot.qemu
features.2.name
test.definition.git
features.3.action
test
features.2.type
device
features.3.name
test.definition.inline
features.2.description
Test definition from git
lab.name
staging.validation.linaro.org
slave.version
2019.04.0032.g55afae484
features.2.action
test
device.name
staging-qemu01
features.0.action
deploy
job.url
https://federation.lavasoftware.org/lava/scheduler/job/{id}
features.0.description
Deploy to tmpfs
slave.arch
amd64
features.0.type
device
features.1.description
Boot qemu
features.0.name
deploy.tmpfs
device type
hostname
timeouts
  • actions: {'seconds': 10}{'minutes': 3}{'seconds': 30}{'minutes': 10}{'minutes': 2}{'minutes': 20}{'minutes': 3}{'minutes': 3}{'minutes': 2}{'minutes': 3}{'minutes': 2}{'minutes': 5}{'seconds': 45}{'minutes': 3}{'minutes': 3}{'minutes': 2}{'minutes': 5}{'minutes': 10}{'seconds': 30} power-offuboot-retrybootloader-interruptdd-imageboot-qemu-imageflash-uboot-umslava-test-shelluboot-actionauto-login-actionbootloader-commandsboot-image-retryhttp-downloadumount-retrybootloader-retrybootloader-actionapply-overlay-imagedownload-retrynfs-deployu-boot-interrupt
  • connections: {'seconds': 30}{'seconds': 30}{'minutes': 2}{'seconds': 30}{'seconds': 10}{'minutes': 10}{'seconds': 30} bootloader-commandsuboot-retryauto-login-actionbootloader-interruptlava-test-shelldd-imageu-boot-interrupt
device action methods
boot:
  • qemu:
    • options: ['-cpu host', '-enable-kvm', '-nographic', '-net nic,model=virtio,macaddr=DE:AD:BE:EF:32:01 -net user', '-m 1024', '-monitor none']
    • boot_options: {'boot_order': 'c', 'root': '/dev/sda1', 'console': 'ttyS0,115200'}
    • command: qemu-system-x86_64
  • qemu-nfs:
    • options: ['-cpu host', '-enable-kvm', '-nographic', '-net nic,model=virtio,macaddr=DE:AD:BE:EF:32:01 -net user', '-m 1024', '-monitor none']
    • boot_options: {'boot_order': 'c', 'root': '/dev/sda1', 'console': 'ttyS0,115200'}
    • append: {'nfsrootargs': 'nfsroot={NFS_SERVER_IP}:{NFSROOTFS},tcp,hard,intr', 'root': '/dev/nfs', 'ipargs': 'ip=dhcp', 'console': 'ttyAMA0'}
    • command: qemu-system-x86_64
deploy:
  • nfs:
  • image:
    • guest: {'size': 512, 'interface': 'ide', 'driveid': 'lavatest'}
target
...
timeouts
  • job: {'seconds': 360}

  1. images
    {'rootfs': {'image_arg': '-drive format=raw,file={rootfs}', 'compression': 'gz', 'url': 'http://images.validation.linaro.org/kvm/standard/large-stable-6.img.gz'}}
    deployment_data
    {'TESTER_PS1': 'linaro-test [rc=$(echo \\$?)]# ', 'TESTER_PS1_INCLUDES_RC': True, 'boot_cmds': 'boot_cmds', 'lava_test_shell_file': '~/.bashrc', 'lava_test_sh_cmd': '/bin/bash', 'tar_flags': '--warning no-timestamp', 'line_separator': '\n', 'distro': 'debian', 'lava_test_results_dir': '/lava-%s', 'TESTER_PS1_PATTERN': 'linaro-test \\[rc=(\\d+)\\]# ', 'lava_test_results_part_attr': 'root_part', 'lava_test_dir': '/lava-%s'}
    Timeout
    • 6 minutes
    namespace
    common
    os
    debian
    to
    tmpfs
    root_partition
    1

  1. prompts
    ['root@debian:']
    Timeout
    • 2 minutes
    namespace
    common
    media
    tmpfs
    auto_login
    {'login_prompt': 'login:', 'username': 'root'}
    method
    qemu

  1. stage
    0
    Timeout
    • 5 minutes
    Definitions
    • from inline
    • name env-dut-inline
    • path inline/env-dut.yaml
    • repository {'run': {'steps': ['printenv']}, 'metadata': {'format': 'Lava-Test Test Definition 1.0', 'name': 'smoke-tests-basic', 'description': 'Basic system test command for Linaro Ubuntu images'}}
    • from git
    • name smoke-tests
    • path lava-test-shell/smoke-tests-basic.yaml
    • repository http://git.linaro.org/lava-team/lava-functional-tests.git
    • from git
    • name singlenode-advanced
    • path lava-test-shell/single-node/singlenode03.yaml
    • repository http://git.linaro.org/lava-team/lava-functional-tests.git
    namespace
    common

Job Timings

This section helps test writers to check for actions with a duration which is much shorter than the requested timeout. Reducing these timeouts will allow failures to be identified more quickly.

The graph only shows actions that are longer than 1 second. The full list is anyway available in the table.

Loading the data...