Re: iotest 040, 041, intermittent failure in netbsd VM

[Peter Maydell]

On Mon, 17 Jan 2022 at 20:35, John Snow <jsnow@redhat.com> wrote:
> I do expect this to print more information on failure than it
> currently is, though (bug somewhere in machine.py, I think).
> Can you please try applying this temporary patch and running `./check
> -qcow2 040 041` until you see a breakage and show me the output from
> that?

Having fixed my setup to not use an ancient host QEMU, here's
the relevant bit of the log:

  TEST   iotest-qcow2: 037
  TEST   iotest-qcow2: 038 [not run]
  TEST   iotest-qcow2: 039 [not run]
  TEST   iotest-qcow2: 040 [fail]
QEMU          --
"/home/qemu/qemu-test.vdrI02/build/tests/qemu-iotests/../../qemu-system-aarch64"
-nodefaults -display none -accel qtest -machine virt
QEMU_IMG      --
"/home/qemu/qemu-test.vdrI02/build/tests/qemu-iotests/../../qemu-img"
QEMU_IO       --
"/home/qemu/qemu-test.vdrI02/build/tests/qemu-iotests/../../qemu-io"
--cache writeback --aio threads -f qcow2
QEMU_NBD      --
"/home/qemu/qemu-test.vdrI02/build/tests/qemu-iotests/../../qemu-nbd"
IMGFMT        -- qcow2
IMGPROTO      -- file
PLATFORM      -- NetBSD/amd64 localhost 9.2
TEST_DIR      -- /home/qemu/qemu-test.vdrI02/build/tests/qemu-iotests/scratch
SOCK_DIR      -- /tmp/tmp1h12r7ev
GDB_OPTIONS   --
VALGRIND_QEMU --
PRINT_QEMU_OUTPUT --

--- /home/qemu/qemu-test.vdrI02/src/tests/qemu-iotests/040.out
+++ 040.out.bad
@@ -1,5 +1,95 @@
-.................................................................
+.......ERROR:qemu.aqmp.qmp_client.qemu-12407:Failed to establish
connection: concurrent.futures._base.CancelledError
+ERROR:qemu.machine.machine:Error launching VM
+ERROR:qemu.machine.machine:Process was forked, waiting on it
+ERROR:qemu.machine.machine:Command:
'/home/qemu/qemu-test.vdrI02/build/tests/qemu-iotests/../../qemu-system-aarch64
-display none -vga none -chardev
socket,id=mon,path=/tmp/tmp1h12r7ev/qemu-12407-monitor.sock -mon
chardev=mon,mode=control -qtest
unix:path=/tmp/tmp1h12r7ev/qemu-12407-qtest.sock -accel qtest
-nodefaults -display none -accel qtest -machine virt -drive
if=none,id=drive0,file=/home/qemu/qemu-test.vdrI02/build/tests/qemu-iotests/scratch/test.img,format=qcow2,cache=writeback,aio=threads,node-name=top,backing.node-name=mid,backing.backing.node-name=base
-device virtio-scsi -device scsi-hd,id=scsi0,drive=drive0'
+ERROR:qemu.machine.machine:Output: "qemu-system-aarch64: -chardev
socket,id=mon,path=/tmp/tmp1h12r7ev/qemu-12407-monitor.sock: Failed to
connect to '/tmp/tmp1h12r7ev/qemu-12407-monitor.sock': No such file or
directory\n"
+ERROR:qemu.machine.machine:exitcode: 1
+E..........................................ERROR:qemu.aqmp.qmp_client.qemu-12407:Failed
to establish connection: concurrent.futures._base.CancelledError
+ERROR:qemu.machine.machine:Error launching VM
+ERROR:qemu.machine.machine:Process was forked, waiting on it
+ERROR:qemu.machine.machine:Command:
'/home/qemu/qemu-test.vdrI02/build/tests/qemu-iotests/../../qemu-system-aarch64
-display none -vga none -chardev
socket,id=mon,path=/tmp/tmp1h12r7ev/qemu-12407-monitor.sock -mon
chardev=mon,mode=control -qtest
unix:path=/tmp/tmp1h12r7ev/qemu-12407-qtest.sock -accel qtest
-nodefaults -display none -accel qtest -machine virt -drive
if=none,id=drive0,file=/home/qemu/qemu-test.vdrI02/build/tests/qemu-iotests/scratch/test.img,format=qcow2,cache=writeback,aio=threads,node-name=top,backing.node-name=mid,backing.backing.node-name=base
-device virtio-scsi -device scsi-hd,id=scsi0,drive=drive0'
+ERROR:qemu.machine.machine:Output: "qemu-system-aarch64: -chardev
socket,id=mon,path=/tmp/tmp1h12r7ev/qemu-12407-monitor.sock: Failed to
connect to '/tmp/tmp1h12r7ev/qemu-12407-monitor.sock': No such file or
directory\n"
+ERROR:qemu.machine.machine:exitcode: 1
+E....ERROR:qemu.aqmp.qmp_client.qemu-12407:Failed to establish
connection: concurrent.futures._base.CancelledError
+ERROR:qemu.machine.machine:Error launching VM
+ERROR:qemu.machine.machine:Process was forked, waiting on it
+ERROR:qemu.machine.machine:Command:
'/home/qemu/qemu-test.vdrI02/build/tests/qemu-iotests/../../qemu-system-aarch64
-display none -vga none -chardev
socket,id=mon,path=/tmp/tmp1h12r7ev/qemu-12407-monitor.sock -mon
chardev=mon,mode=control -qtest
unix:path=/tmp/tmp1h12r7ev/qemu-12407-qtest.sock -accel qtest
-nodefaults -display none -accel qtest -machine virt -drive
if=none,id=drive0,file=/home/qemu/qemu-test.vdrI02/build/tests/qemu-iotests/scratch/test.img,format=qcow2,cache=writeback,aio=threads,node-name=top,backing.node-name=mid,backing.backing.node-name=base
-device virtio-scsi -device scsi-hd,id=scsi0,drive=drive0'
+ERROR:qemu.machine.machine:Output: "qemu-system-aarch64: -chardev
socket,id=mon,path=/tmp/tmp1h12r7ev/qemu-12407-monitor.sock: Failed to
connect to '/tmp/tmp1h12r7ev/qemu-12407-monitor.sock': No such file or
directory\n"
+ERROR:qemu.machine.machine:exitcode: 1
+E.........
+======================================================================
+ERROR: test_device_not_found (__main__.TestActiveZeroLengthImage)
+----------------------------------------------------------------------
+Traceback (most recent call last):
+  File "/home/qemu/qemu-test.vdrI02/src/tests/qemu-iotests/040", line
94, in setUp
+    self.vm.launch()
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/machine/machine.py",
line 399, in launch
+    self._launch()
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/machine/machine.py",
line 437, in _launch
+    self._post_launch()
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/machine/qtest.py",
line 147, in _post_launch
+    super()._post_launch()
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/machine/machine.py",
line 340, in _post_launch
+    self._qmp.accept(self._qmp_timer)
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/aqmp/legacy.py",
line 69, in accept
+    timeout
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/aqmp/legacy.py",
line 42, in _sync
+    asyncio.wait_for(future, timeout=timeout)
+  File "/usr/pkg/lib/python3.7/asyncio/base_events.py", line 587, in
run_until_complete
+    return future.result()
+  File "/usr/pkg/lib/python3.7/asyncio/tasks.py", line 449, in wait_for
+    raise futures.TimeoutError()
+concurrent.futures._base.TimeoutError
+
+======================================================================
+ERROR: test_commit_node (__main__.TestSingleDrive)
+----------------------------------------------------------------------
+Traceback (most recent call last):
+  File "/home/qemu/qemu-test.vdrI02/src/tests/qemu-iotests/040", line
94, in setUp
+    self.vm.launch()
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/machine/machine.py",
line 399, in launch
+    self._launch()
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/machine/machine.py",
line 437, in _launch
+    self._post_launch()
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/machine/qtest.py",
line 147, in _post_launch
+    super()._post_launch()
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/machine/machine.py",
line 340, in _post_launch
+    self._qmp.accept(self._qmp_timer)
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/aqmp/legacy.py",
line 69, in accept
+    timeout
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/aqmp/legacy.py",
line 42, in _sync
+    asyncio.wait_for(future, timeout=timeout)
+  File "/usr/pkg/lib/python3.7/asyncio/base_events.py", line 587, in
run_until_complete
+    return future.result()
+  File "/usr/pkg/lib/python3.7/asyncio/tasks.py", line 449, in wait_for
+    raise futures.TimeoutError()
+concurrent.futures._base.TimeoutError
+
+======================================================================
+ERROR: test_implicit_node (__main__.TestSingleDrive)
+----------------------------------------------------------------------
+Traceback (most recent call last):
+  File "/home/qemu/qemu-test.vdrI02/src/tests/qemu-iotests/040", line
94, in setUp
+    self.vm.launch()
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/machine/machine.py",
line 399, in launch
+    self._launch()
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/machine/machine.py",
line 437, in _launch
+    self._post_launch()
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/machine/qtest.py",
line 147, in _post_launch
+    super()._post_launch()
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/machine/machine.py",
line 340, in _post_launch
+    self._qmp.accept(self._qmp_timer)
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/aqmp/legacy.py",
line 69, in accept
+    timeout
+  File "/home/qemu/qemu-test.vdrI02/src/python/qemu/aqmp/legacy.py",
line 42, in _sync
+    asyncio.wait_for(future, timeout=timeout)
+  File "/usr/pkg/lib/python3.7/asyncio/base_events.py", line 587, in
run_until_complete
+    return future.result()
+  File "/usr/pkg/lib/python3.7/asyncio/tasks.py", line 449, in wait_for
+    raise futures.TimeoutError()
+concurrent.futures._base.TimeoutError
+
 ----------------------------------------------------------------------
 Ran 65 tests

-OK
+FAILED (errors=3)
  TEST   iotest-qcow2: 041
  TEST   iotest-qcow2: 042
  TEST   iotest-qcow2: 043
(etc)


thanks
-- PMM