I've also attached a similar log after downgrading qemu to the version in jessie. No change in the bug.
-- Neil Williams ============= http://www.linux.codehelp.co.uk/
adt-run: DBG: testbed init
adt-run [16:44:44]: version 3.20.4
adt-run [16:44:44]: host sylvester; command line: /usr/bin/adt-run -o logs2 -l adt2.log -d /tmp/tmp.lXjW2N5fd3/lava-server_2016.4-1.dsc -U --- qemu --debug adt-sid-2.raw
adt-run: DBG: got reply from testbed: ok
adt-run: DBG: testbed open, scratch=None
adt-run: DBG: sending command to testbed: open
adt-virt-qemu: DBG: executing open
adt-virt-qemu: DBG: Creating temporary overlay image in /tmp/adt-virt-qemu.j5tuxl4q/overlay.img
adt-virt-qemu: DBG: execute-timeout: qemu-img create -f qcow2 -b /home/neil/code/lava/packaging/alioth/adt-run-qemu/adt-sid-2.raw /tmp/adt-virt-qemu.j5tuxl4q/overlay.img
adt-virt-qemu: DBG: Detected KVM capable Intel host CPU, enabling nested KVM
adt-virt-qemu: DBG: find_free_port: trying 10022
adt-virt-qemu: DBG: find_free_port: 10022 is free
adt-virt-qemu: DBG: Forwarding local port 10022 to VM ssh port 22
adt-virt-qemu: DBG: expect: " login: "
warning: host doesn't support requested feature: CPUID.01H:ECX.vmx [bit 5]
adt-virt-qemu: DBG: expect: found ""login prompt on ttyS0""
adt-virt-qemu: DBG: expect: "ok"
adt-virt-qemu: DBG: expect: found ""b'ok'""
adt-virt-qemu: DBG: setup_shell(): there already is a shell on ttyS1
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: expect: "(qemu)"
adt-virt-qemu: DBG: expect: found ""b'(qemu)'""
adt-virt-qemu: DBG: expect: "(qemu)"
adt-virt-qemu: DBG: expect: found ""b'(qemu)'""
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: Copying host timezone Europe/London to VM
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: expect: "/python"
adt-virt-qemu: DBG: expect: found ""b'/python'""
adt-virt-qemu: DBG: expect: "# "
adt-virt-qemu: DBG: expect: found ""b'# '""
adt-virt-qemu: DBG: execute-timeout: /tmp/adt-virt-qemu.j5tuxl4q/runcmd true
qemu-system-x86_64: terminating on signal 15 from pid 30360
adt-virt-qemu: DBG: cleanup...
Unexpected error:
Traceback (most recent call last):
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 705, in mainloop
command()
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 643, in command
r = f(c, ce)
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 258, in cmd_open
caller.hook_open()
File "/usr/bin/adt-virt-qemu", line 567, in hook_open
make_auxverb(shareddir)
File "/usr/bin/adt-virt-qemu", line 413, in make_auxverb
status = VirtSubproc.execute_timeout(None, 5, VirtSubproc.auxverb + ['true'])[0]
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 144, in execute_timeout
(out, err) = sp.communicate(instr)
File "/usr/lib/python3.5/subprocess.py", line 1057, in communicate
self.wait()
File "/usr/lib/python3.5/subprocess.py", line 1648, in wait
(pid, sts) = self._try_wait(0)
File "/usr/lib/python3.5/subprocess.py", line 1598, in _try_wait
(pid, sts) = os.waitpid(self.pid, wait_flags)
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 64, in alarm_handler
raise Timeout()
VirtSubproc.Timeout
adt-run: DBG: TestbedFailure unexpected eof from the testbed
adt-run: DBG: testbed stop
adt-run: DBG: testbed close, scratch=None
adt-run: DBG: sending command to testbed: quit
adt-run: DBG: TestbedFailure cannot send to testbed: ['BrokenPipeError: [Errno 32] Broken pipe\n']
adt-run: DBG: testbed stop
adt-run [16:45:04]: ERROR: testbed failure: cannot send to testbed: ['BrokenPipeError: [Errno 32] Broken pipe\n']
adt-run: DBG: testbed stop
adt-run: DBG: testbed init
adt-run [16:49:54]: version 3.20.4
adt-run [16:49:54]: host sylvester; command line: /usr/bin/adt-run -o logs3 -l adt-downgrade.log -d /tmp/tmp.lXjW2N5fd3/lava-server_2016.4-1.dsc -U --- qemu --debug adt-sid-2.raw
adt-run: DBG: got reply from testbed: ok
adt-run: DBG: testbed open, scratch=None
adt-run: DBG: sending command to testbed: open
adt-virt-qemu: DBG: executing open
adt-virt-qemu: DBG: Creating temporary overlay image in /tmp/adt-virt-qemu.n409k_y1/overlay.img
adt-virt-qemu: DBG: execute-timeout: qemu-img create -f qcow2 -b /home/neil/code/lava/packaging/alioth/adt-run-qemu/adt-sid-2.raw /tmp/adt-virt-qemu.n409k_y1/overlay.img
adt-virt-qemu: DBG: Detected KVM capable Intel host CPU, enabling nested KVM
adt-virt-qemu: DBG: find_free_port: trying 10022
adt-virt-qemu: DBG: find_free_port: 10022 is free
adt-virt-qemu: DBG: Forwarding local port 10022 to VM ssh port 22
(process:31629): GLib-WARNING **: /build/glib2.0-2CrUwg/glib2.0-2.48.0/./glib/gmem.c:483: custom memory allocation vtable not supported
adt-virt-qemu: DBG: expect: " login: "
adt-virt-qemu: DBG: expect: found ""login prompt on ttyS0""
adt-virt-qemu: DBG: expect: "ok"
adt-virt-qemu: DBG: expect: found ""b'ok'""
adt-virt-qemu: DBG: setup_shell(): there already is a shell on ttyS1
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: expect: "(qemu)"
adt-virt-qemu: DBG: expect: found ""b'(qemu)'""
adt-virt-qemu: DBG: expect: "(qemu)"
adt-virt-qemu: DBG: expect: found ""b'(qemu)'""
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: Copying host timezone Europe/London to VM
adt-virt-qemu: DBG: expect: "#"
adt-virt-qemu: DBG: expect: found ""b'#'""
adt-virt-qemu: DBG: expect: "/python"
adt-virt-qemu: DBG: expect: found ""b'/python'""
adt-virt-qemu: DBG: expect: "# "
adt-virt-qemu: DBG: expect: found ""b'# '""
adt-virt-qemu: DBG: expect: "# "
adt-virt-qemu: DBG: expect: found ""b'# '""
adt-virt-qemu: DBG: execute-timeout: /tmp/adt-virt-qemu.n409k_y1/runcmd true
qemu: terminating on signal 15 from pid 31626
adt-virt-qemu: DBG: cleanup...
Unexpected error:
Traceback (most recent call last):
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 705, in mainloop
command()
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 643, in command
r = f(c, ce)
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 258, in cmd_open
caller.hook_open()
File "/usr/bin/adt-virt-qemu", line 567, in hook_open
make_auxverb(shareddir)
File "/usr/bin/adt-virt-qemu", line 413, in make_auxverb
status = VirtSubproc.execute_timeout(None, 5, VirtSubproc.auxverb + ['true'])[0]
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 144, in execute_timeout
(out, err) = sp.communicate(instr)
File "/usr/lib/python3.5/subprocess.py", line 1057, in communicate
self.wait()
File "/usr/lib/python3.5/subprocess.py", line 1648, in wait
(pid, sts) = self._try_wait(0)
File "/usr/lib/python3.5/subprocess.py", line 1598, in _try_wait
(pid, sts) = os.waitpid(self.pid, wait_flags)
File "/usr/share/autopkgtest/python/VirtSubproc.py", line 64, in alarm_handler
raise Timeout()
VirtSubproc.Timeout
adt-run: DBG: TestbedFailure unexpected eof from the testbed
adt-run: DBG: testbed stop
adt-run: DBG: testbed close, scratch=None
adt-run: DBG: sending command to testbed: quit
adt-run: DBG: TestbedFailure cannot send to testbed: ['BrokenPipeError: [Errno 32] Broken pipe\n']
adt-run: DBG: testbed stop
adt-run [16:50:13]: ERROR: testbed failure: cannot send to testbed: ['BrokenPipeError: [Errno 32] Broken pipe\n']
adt-run: DBG: testbed stop
pgphUvSbhWGOL.pgp
Description: OpenPGP digital signature
