SELinux denies swtpm access to /var/log/swtpm/libvirt/qemu/*.log #6792

mvollmer · 2024-08-26T10:18:50Z

Upstream: https://bugzilla.redhat.com/show_bug.cgi?id=2307853

martinpitt · 2024-08-26T10:38:12Z

Is that a duplicate of #5944 aka. https://bugzilla.redhat.com/show_bug.cgi?id=2265346 ? Our naughty was closed just two weeks ago, but perhaps circumstances changed (like testing on RHEL 10?)

mvollmer · 2024-08-26T10:43:51Z

Is that a duplicate of #5944 aka. https://bugzilla.redhat.com/show_bug.cgi?id=2265346 ?

I checked that and it doesn't mention /var/log/swtpm/libvirt/qemu, but maybe I was too quick...

mvollmer · 2024-08-26T10:45:13Z

Is that a duplicate of #5944 aka. https://bugzilla.redhat.com/show_bug.cgi?id=2265346 ?

I checked that and it doesn't mention /var/log/swtpm/libvirt/qemu, but maybe I was too quick...

No, I think this here is new, and maybe a result of the fixes for 2265346.

cockpituous · 2024-08-26T10:59:18Z

fedora-41
Ooops, it happened again

# ----------------------------------------------------------------------
# testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 43583
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 43583.
.....+....+...+...+++++++++++++++++++++++++++++++++++++++++++++*...+...+......+............+...+++++++++++++++++++++++++++++++++++++++++++++*....+......+...+...+....+......+...............+...............+...+..+++++
.......+.....+............+++++++++++++++++++++++++++++++++++++++++++++*...+.......+...+..+...+.......+.....+....+...+............+........+.+..+...+....+..+.........+......+...+.+..+..........+..+.......+......+...+..+.......+......+.........+.....+......+.+......+.....+..........+..+.+.....+.+........+.+..+...+.+++++++++++++++++++++++++++++++++++++++++++++*...+...........+.............+......+...+...+...+............+............+...+.........+....................+.+..+...............+...............+...+..........+..+............+................+.....+...+..........+..+.............+...............+........................+.....+......+.+.........+.................+...+.+...+++++
-----
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> info: osinfo-detect command failed:  (process:55986): GLib-GIO-WARNING **: 16:42:44.269: Can't find module 'gvfs' specified in GIO_USE_VFS
Traceback (most recent call last):
  File "<string>", line 22, in <module>
    media = Libosinfo.Media().create_from_location(sys.argv[1])
gi.repository.GLib.GError: osinfo-media-error: No volume descriptors (0)
error: failed to get domain 'VmNotInstalled'
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/VmNotInstalled-swtpm.log' for details.\nDomain installation does not appear to have been successful.\nIf it was, you can restart your domain by running:\n  virsh --connect qemu:///system start VmNotInstalled\notherwise, please restart your installation.\nCommand '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 297, in install_vm\n    subprocess.check_output(params)\n    ~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 472, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n               **kwargs).stdout\n               ^^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 577, in run\n    raise CalledProcessError(retcode, process.args,\n                             output=stdout, stderr=stderr)\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 375, in <module>\n    install_vm(args)\n    ~~~~~~~~~~^^^^^^\n  File \"<string>\", line 308, in install_vm\n    raise e\n  File \"<string>\", line 297, in install_vm\n    subprocess.check_output(params)\n    ~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 472, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n               **kwargs).stdout\n               ^^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 577, in run\n    raise CalledProcessError(retcode, process.args,\n                             output=stdout, stderr=stderr)\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1."}
Wrote VmNotInstalled XML to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-3001-VmNotInstalled.xml
Wrote VmNotInstalled log to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-3001-VmNotInstalled.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2248, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2832, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-3001-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-3001-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-3001-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-3001-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged-ro.socket, virtstoraged.socket, virtstoraged-admin.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd-ro.socket, virtnetworkd.socket, virtnetworkd-admin.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-admin.socket, virtqemud.socket, virtqemud-ro.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2248, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2832, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

# Result testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall) failed
# 1 TEST FAILED [220s on e7785daf5512]
not ok 75 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstall [ND@7]

First occurrence: 2024-11-29T16:46:09.705083+00:00 | revision 5dc6ec3eab2f1606ff486e506ad6bd62ead263fb
Times recorded: 1
Latest occurrences:

2024-11-29T16:46:09.705083+00:00 | revision 5dc6ec3eab2f1606ff486e506ad6bd62ead263fb

# ----------------------------------------------------------------------
# testMultipleSettings (__main__.TestMachinesSettings.testMultipleSettings)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 44185
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 44185.
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> warn: Failed to start VM subVmTest1 : internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/subVmTest1-swtpm.log' for details.
Wrote subVmTest1 XML to TestMachinesSettings-testMultipleSettings-fedora-41-127.0.0.2-2501-subVmTest1.xml
Wrote subVmTest1 log to TestMachinesSettings-testMultipleSettings-fedora-41-127.0.0.2-2501-subVmTest1.log
Wrote subVmTest1 console log to TestMachinesSettings-testMultipleSettings-fedora-41-127.0.0.2-2501-subVmTest1-console.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-settings", line 537, in testMultipleSettings
    b.wait_in_text("#vm-subVmTest1-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

Wrote screenshot to TestMachinesSettings-testMultipleSettings-fedora-41-127.0.0.2-2501-FAIL.png
Wrote HTML dump to TestMachinesSettings-testMultipleSettings-fedora-41-127.0.0.2-2501-FAIL.html
Wrote JS log to TestMachinesSettings-testMultipleSettings-fedora-41-127.0.0.2-2501-FAIL.js.log
Journal extracted to TestMachinesSettings-testMultipleSettings-fedora-41-127.0.0.2-2501-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged-ro.socket, virtstoraged-admin.socket, virtstoraged.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd-ro.socket, virtnetworkd-admin.socket, virtnetworkd.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-ro.socket, virtqemud-admin.socket, virtqemud.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-settings", line 537, in testMultipleSettings
    b.wait_in_text("#vm-subVmTest1-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

# Result testMultipleSettings (__main__.TestMachinesSettings.testMultipleSettings) failed
# 1 TEST FAILED [85s on baf1e67aeb8d]
not ok 77 /work/make-checkout-workdir/test/check-machines-settings TestMachinesSettings.testMultipleSettings [ND@3]

First occurrence: 2024-11-30T02:25:57.309326+00:00 | revision 978131f4110b70fac5d2dc17c12100c3aa13e561
Times recorded: 1
Latest occurrences:

2024-11-30T02:25:57.309326+00:00 | revision 978131f4110b70fac5d2dc17c12100c3aa13e561

# ----------------------------------------------------------------------
# testConfigureBeforeInstallBiosTPM (__main__.TestMachinesCreate.testConfigureBeforeInstallBiosTPM)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 38859
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 38859.
....+...+...........+......+++++++++++++++++++++++++++++++++++++++++++++*..+...+....+..+.+..+...............+.......+...........+....+........+............+.+.....+.......+..+...+......+++++++++++++++++++++++++++++++++++++++++++++*.....................................+.+.......................+.......+..+............+.........+......+....+......+........+..................+...+......+..........+...+.....+.+.....................+...................................+.+++++
.........+............+......+..........+...+..+............+.+..+...+.+.........+.....+.+......+...+...........+...+++++++++++++++++++++++++++++++++++++++++++++*.......+++++++++++++++++++++++++++++++++++++++++++++*.+..+.......+......+..+...+...........................+.+.................+...+....+...+..+.+.........+...+...+.....+.......+...+.........+........+...+......+..................+...+.........+.............+............+...+......+......+...........+...+............+....+..................+......+...........+....+..+...+..............................+.......+++++
-----
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> info: osinfo-detect command failed:  (process:33051): GLib-GIO-WARNING **: 02:31:27.653: Can't find module 'gvfs' specified in GIO_USE_VFS
Traceback (most recent call last):
  File "<string>", line 22, in <module>
    media = Libosinfo.Media().create_from_location(sys.argv[1])
gi.repository.GLib.GError: osinfo-media-error: No volume descriptors (0)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/subVmTest1-swtpm.log' for details.\nDomain installation does not appear to have been successful.\nIf it was, you can restart your domain by running:\n  virsh --connect qemu:///system start subVmTest1\notherwise, please restart your installation.\nCommand '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 297, in install_vm\n    subprocess.check_output(params)\n    ~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 472, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n               **kwargs).stdout\n               ^^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 577, in run\n    raise CalledProcessError(retcode, process.args,\n                             output=stdout, stderr=stderr)\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 375, in <module>\n    install_vm(args)\n    ~~~~~~~~~~^^^^^^\n  File \"<string>\", line 308, in install_vm\n    raise e\n  File \"<string>\", line 297, in install_vm\n    subprocess.check_output(params)\n    ~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 472, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n               **kwargs).stdout\n               ^^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 577, in run\n    raise CalledProcessError(retcode, process.args,\n                             output=stdout, stderr=stderr)\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1."}
Wrote subVmTest1 XML to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-3001-subVmTest1.xml
Wrote subVmTest1 log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-3001-subVmTest1.log
Wrote subVmTest1 console log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-3001-subVmTest1-console.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2404, in testConfigureBeforeInstallBiosTPM
    b.wait_in_text(f"#vm-{vmName}-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-3001-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-3001-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-3001-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-3001-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged-ro.socket, virtstoraged-admin.socket, virtstoraged.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd-admin.socket, virtnetworkd-ro.socket, virtnetworkd.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud.socket, virtqemud-ro.socket, virtqemud-admin.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2404, in testConfigureBeforeInstallBiosTPM
    b.wait_in_text(f"#vm-{vmName}-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

# Result testConfigureBeforeInstallBiosTPM (__main__.TestMachinesCreate.testConfigureBeforeInstallBiosTPM) failed
# 1 TEST FAILED [45s on baf1e67aeb8d]
not ok 8 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstallBiosTPM [ND@7]

First occurrence: 2024-11-30T02:31:57.701059+00:00 | revision 978131f4110b70fac5d2dc17c12100c3aa13e561
Times recorded: 1
Latest occurrences:

2024-11-30T02:31:57.701059+00:00 | revision 978131f4110b70fac5d2dc17c12100c3aa13e561

# ----------------------------------------------------------------------
# testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 45919
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 45919.
......+...+.....+...+...+.........+...+...+.+.....+..........+...............+......+..+...+...+..........+.....+....+......+..+....+.....+...+.+......+......+..+.+.....+.+.........+.....+.......+...+.....+.+......+.........+...........+.......+.....................+...............+..+...+++++++++++++++++++++++++++++++++++++++++++++*.......+...+..+....+++++++++++++++++++++++++++++++++++++++++++++*......+...+............+.+......+...+...........+.+...+...+........+...................+...........+......+.+.....+...........................+.+.....+.........+.......+.....+.....................+.......+...+..+...+........................+......+.......+..+....+.....+.+........+.+.....+.+.........+......+.........+.....+.+...+..+......+....+...+...+........+.........+...+............+..........+.....+...+....+++++
.+.......+...+............+...+...+.....+....+..+...+.+++++++++++++++++++++++++++++++++++++++++++++*......+.....+....+++++++++++++++++++++++++++++++++++++++++++++*......+...............+.....+.+....................+.+.........+...+.....+...+.............+..+...+..........+..+..............................+.........+...+.+.............................+..........+......+.....+......+.+..+...+.............+.........+...+..+......+................+........+.+.....+.+..............+..........+.........+...+.....+....+...........+.......+.....+...+...+......+..........+.....+....+.................+.........+....+..+.......+..+...+.+...............+.....+......................+........+.+......+.......................+....+..+...............+..........+.....+..........+.........+..+++++
-----
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> info: osinfo-detect command failed:  (process:44251): GLib-GIO-WARNING **: 02:31:51.330: Can't find module 'gvfs' specified in GIO_USE_VFS
Traceback (most recent call last):
  File "<string>", line 22, in <module>
    media = Libosinfo.Media().create_from_location(sys.argv[1])
gi.repository.GLib.GError: osinfo-media-error: No volume descriptors (0)
error: failed to get domain 'VmNotInstalled'
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/VmNotInstalled-swtpm.log' for details.\nDomain installation does not appear to have been successful.\nIf it was, you can restart your domain by running:\n  virsh --connect qemu:///system start VmNotInstalled\notherwise, please restart your installation.\nCommand '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 297, in install_vm\n    subprocess.check_output(params)\n    ~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 472, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n               **kwargs).stdout\n               ^^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 577, in run\n    raise CalledProcessError(retcode, process.args,\n                             output=stdout, stderr=stderr)\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 375, in <module>\n    install_vm(args)\n    ~~~~~~~~~~^^^^^^\n  File \"<string>\", line 308, in install_vm\n    raise e\n  File \"<string>\", line 297, in install_vm\n    subprocess.check_output(params)\n    ~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 472, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n               **kwargs).stdout\n               ^^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 577, in run\n    raise CalledProcessError(retcode, process.args,\n                             output=stdout, stderr=stderr)\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1."}
Wrote VmNotInstalled XML to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2301-VmNotInstalled.xml
Wrote VmNotInstalled log to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2301-VmNotInstalled.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2247, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2832, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2301-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2301-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2301-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2301-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged-ro.socket, virtstoraged.socket, virtstoraged-admin.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd.socket, virtnetworkd-admin.socket, virtnetworkd-ro.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-ro.socket, virtqemud.socket, virtqemud-admin.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2247, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2832, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

# Result testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall) failed
# 1 TEST FAILED [221s on baf1e67aeb8d]
not ok 6 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstall [ND@1]

First occurrence: 2024-11-30T02:35:16.759286+00:00 | revision 978131f4110b70fac5d2dc17c12100c3aa13e561
Times recorded: 1
Latest occurrences:

2024-11-30T02:35:16.759286+00:00 | revision 978131f4110b70fac5d2dc17c12100c3aa13e561

# ----------------------------------------------------------------------
# testMultipleSettings (__main__.TestMachinesSettings.testMultipleSettings)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 36423
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 36423.
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> warn: Failed to start VM subVmTest1 : internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/subVmTest1-swtpm.log' for details.
Wrote subVmTest1 XML to TestMachinesSettings-testMultipleSettings-fedora-41-127.0.0.2-2201-subVmTest1.xml
Wrote subVmTest1 log to TestMachinesSettings-testMultipleSettings-fedora-41-127.0.0.2-2201-subVmTest1.log
Wrote subVmTest1 console log to TestMachinesSettings-testMultipleSettings-fedora-41-127.0.0.2-2201-subVmTest1-console.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-settings", line 537, in testMultipleSettings
    b.wait_in_text("#vm-subVmTest1-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

Wrote screenshot to TestMachinesSettings-testMultipleSettings-fedora-41-127.0.0.2-2201-FAIL.png
Wrote HTML dump to TestMachinesSettings-testMultipleSettings-fedora-41-127.0.0.2-2201-FAIL.html
Wrote JS log to TestMachinesSettings-testMultipleSettings-fedora-41-127.0.0.2-2201-FAIL.js.log
Journal extracted to TestMachinesSettings-testMultipleSettings-fedora-41-127.0.0.2-2201-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged.socket, virtstoraged-ro.socket, virtstoraged-admin.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd-admin.socket, virtnetworkd-ro.socket, virtnetworkd.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-ro.socket, virtqemud-admin.socket, virtqemud.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-settings", line 537, in testMultipleSettings
    b.wait_in_text("#vm-subVmTest1-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

# Result testMultipleSettings (__main__.TestMachinesSettings.testMultipleSettings) failed
# 1 TEST FAILED [88s on c20a095cee43]
not ok 77 /work/make-checkout-workdir/test/check-machines-settings TestMachinesSettings.testMultipleSettings [ND@0]

First occurrence: 2024-12-01T02:51:55.049462+00:00 | revision e6e460829e0c4d14186e60a2c55f358ba0cf548e
Times recorded: 1
Latest occurrences:

2024-12-01T02:51:55.049462+00:00 | revision e6e460829e0c4d14186e60a2c55f358ba0cf548e

# ----------------------------------------------------------------------
# testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 50555
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 50555.
..+...+..+...+............+...+...............+.........+.+...+++++++++++++++++++++++++++++++++++++++++++++*..+++++++++++++++++++++++++++++++++++++++++++++*..+..........+...+..+...+.............+.....+....+........+................+..+...+.......+.....+....+...........+...+............+.......+......+......+...+.....+.+..+...+......+.+..+.+......+.........+..............+...+....+...+.................+.+..+................+..............+......+.+....................+.+..+.+.........+...+...............+...+..+...+......+....+..+.........+...................+......+...+.....+.+............+........+.+...+...+........+.+...+..................+............+++++
..........+...+..+.+...........+++++++++++++++++++++++++++++++++++++++++++++*.+......+......+...+...+...+.+++++++++++++++++++++++++++++++++++++++++++++*..........+..+++++
-----
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> info: osinfo-detect command failed:  (process:48119): GLib-GIO-WARNING **: 02:57:57.838: Can't find module 'gvfs' specified in GIO_USE_VFS
Traceback (most recent call last):
  File "<string>", line 22, in <module>
    media = Libosinfo.Media().create_from_location(sys.argv[1])
gi.repository.GLib.GError: osinfo-media-error: No volume descriptors (0)
error: failed to get domain 'VmNotInstalled'
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/VmNotInstalled-swtpm.log' for details.\nDomain installation does not appear to have been successful.\nIf it was, you can restart your domain by running:\n  virsh --connect qemu:///system start VmNotInstalled\notherwise, please restart your installation.\nCommand '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 297, in install_vm\n    subprocess.check_output(params)\n    ~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 472, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n               **kwargs).stdout\n               ^^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 577, in run\n    raise CalledProcessError(retcode, process.args,\n                             output=stdout, stderr=stderr)\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 375, in <module>\n    install_vm(args)\n    ~~~~~~~~~~^^^^^^\n  File \"<string>\", line 308, in install_vm\n    raise e\n  File \"<string>\", line 297, in install_vm\n    subprocess.check_output(params)\n    ~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 472, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n               **kwargs).stdout\n               ^^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 577, in run\n    raise CalledProcessError(retcode, process.args,\n                             output=stdout, stderr=stderr)\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1."}
Wrote VmNotInstalled XML to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2501-VmNotInstalled.xml
Wrote VmNotInstalled log to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2501-VmNotInstalled.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2247, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2832, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2501-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2501-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2501-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2501-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged.socket, virtstoraged-ro.socket, virtstoraged-admin.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd.socket, virtnetworkd-ro.socket, virtnetworkd-admin.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-admin.socket, virtqemud.socket, virtqemud-ro.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2247, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2832, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

# Result testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall) failed
# 1 TEST FAILED [222s on c20a095cee43]
not ok 6 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstall [ND@3]

First occurrence: 2024-12-01T03:01:23.059851+00:00 | revision e6e460829e0c4d14186e60a2c55f358ba0cf548e
Times recorded: 1
Latest occurrences:

2024-12-01T03:01:23.059851+00:00 | revision e6e460829e0c4d14186e60a2c55f358ba0cf548e

# ----------------------------------------------------------------------
# testConfigureBeforeInstallBiosTPM (__main__.TestMachinesCreate.testConfigureBeforeInstallBiosTPM)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 54979
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 54979.
..+.....+.............+.....+.......+...+..+....+..+....+..............+.........+.+......+......+...+++++++++++++++++++++++++++++++++++++++++++++*......+...+....+..+++++++++++++++++++++++++++++++++++++++++++++*.+...+......+......+........+....................................+...+...+.............+..+............+.......+........+.+......+.........+.....+.+...........+.......+.....................+.........+..+...+...+....+....................+.........+......+......+.......+........+....+......+.....+.......+......+.........+...........+....+++++
...+......+.+......+........+.+...+.....+...............+.......+.....+.+..+................+............+..............+....+..+...+....+.................+...+......+.+......+++++++++++++++++++++++++++++++++++++++++++++*......+.....+++++++++++++++++++++++++++++++++++++++++++++*...+...+.........+....+..+.......+...+....................+...+.+...........+............+.+.....+......+.........+.......+.....+.........+.+............+...+.........+.........+......+...........+...+.+........+..................+..........+.....+......................+..+.........................+...+...........+......+.+..+.......+.....+...+.+......+.....+...+++++
-----
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> info: osinfo-detect command failed:  (process:48342): GLib-GIO-WARNING **: 02:39:27.229: Can't find module 'gvfs' specified in GIO_USE_VFS
Traceback (most recent call last):
  File "<string>", line 22, in <module>
    media = Libosinfo.Media().create_from_location(sys.argv[1])
gi.repository.GLib.GError: osinfo-media-error: No volume descriptors (0)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/subVmTest1-swtpm.log' for details.\nDomain installation does not appear to have been successful.\nIf it was, you can restart your domain by running:\n  virsh --connect qemu:///system start subVmTest1\notherwise, please restart your installation.\nCommand '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 297, in install_vm\n    subprocess.check_output(params)\n    ~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 472, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n               **kwargs).stdout\n               ^^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 577, in run\n    raise CalledProcessError(retcode, process.args,\n                             output=stdout, stderr=stderr)\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 375, in <module>\n    install_vm(args)\n    ~~~~~~~~~~^^^^^^\n  File \"<string>\", line 308, in install_vm\n    raise e\n  File \"<string>\", line 297, in install_vm\n    subprocess.check_output(params)\n    ~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 472, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n               **kwargs).stdout\n               ^^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 577, in run\n    raise CalledProcessError(retcode, process.args,\n                             output=stdout, stderr=stderr)\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1."}
Wrote subVmTest1 XML to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-2401-subVmTest1.xml
Wrote subVmTest1 log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-2401-subVmTest1.log
Wrote subVmTest1 console log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-2401-subVmTest1-console.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2404, in testConfigureBeforeInstallBiosTPM
    b.wait_in_text(f"#vm-{vmName}-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-2401-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-2401-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-2401-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-2401-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged.socket, virtstoraged-admin.socket, virtstoraged-ro.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd-ro.socket, virtnetworkd-admin.socket, virtnetworkd.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud.socket, virtqemud-ro.socket, virtqemud-admin.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2404, in testConfigureBeforeInstallBiosTPM
    b.wait_in_text(f"#vm-{vmName}-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

# Result testConfigureBeforeInstallBiosTPM (__main__.TestMachinesCreate.testConfigureBeforeInstallBiosTPM) failed
# 1 TEST FAILED [46s on a710aaa763ca]
not ok 8 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstallBiosTPM [ND@2]

First occurrence: 2024-12-02T02:39:58.268817+00:00 | revision e6e460829e0c4d14186e60a2c55f358ba0cf548e
Times recorded: 1
Latest occurrences:

2024-12-02T02:39:58.268817+00:00 | revision e6e460829e0c4d14186e60a2c55f358ba0cf548e

# ----------------------------------------------------------------------
# testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 36853
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 36853.
...+.+...+.....+.........+.+......+...+..+......+.........+++++++++++++++++++++++++++++++++++++++++++++*....+.....+.+...........+++++++++++++++++++++++++++++++++++++++++++++*.+..+............+...............+....+.....+.........+.+......+.....+......+..........+..+.......+.....+...+.+...........+.+.........+....................................+...+...............+............+...+..+....+...+...........+............+...+......+......+++++
..+.........+....+...........+....+..+.......+......+..+...+.+++++++++++++++++++++++++++++++++++++++++++++*....+.+...+.....+......+...+++++++++++++++++++++++++++++++++++++++++++++*.+....+...+..............+.+++++
-----
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> info: osinfo-detect command failed:  (process:45163): GLib-GIO-WARNING **: 02:39:51.980: Can't find module 'gvfs' specified in GIO_USE_VFS
Traceback (most recent call last):
  File "<string>", line 22, in <module>
    media = Libosinfo.Media().create_from_location(sys.argv[1])
gi.repository.GLib.GError: osinfo-media-error: No volume descriptors (0)
error: failed to get domain 'VmNotInstalled'
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/VmNotInstalled-swtpm.log' for details.\nDomain installation does not appear to have been successful.\nIf it was, you can restart your domain by running:\n  virsh --connect qemu:///system start VmNotInstalled\notherwise, please restart your installation.\nCommand '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 297, in install_vm\n    subprocess.check_output(params)\n    ~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 472, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n               **kwargs).stdout\n               ^^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 577, in run\n    raise CalledProcessError(retcode, process.args,\n                             output=stdout, stderr=stderr)\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 375, in <module>\n    install_vm(args)\n    ~~~~~~~~~~^^^^^^\n  File \"<string>\", line 308, in install_vm\n    raise e\n  File \"<string>\", line 297, in install_vm\n    subprocess.check_output(params)\n    ~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 472, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n               **kwargs).stdout\n               ^^^^^^^^^\n  File \"/usr/lib64/python3.13/subprocess.py\", line 577, in run\n    raise CalledProcessError(retcode, process.args,\n                             output=stdout, stderr=stderr)\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1."}
Wrote VmNotInstalled XML to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2301-VmNotInstalled.xml
Wrote VmNotInstalled log to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2301-VmNotInstalled.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2247, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2832, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2301-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2301-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2301-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2301-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged-ro.socket, virtstoraged.socket, virtstoraged-admin.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd-ro.socket, virtnetworkd.socket, virtnetworkd-admin.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-admin.socket, virtqemud.socket, virtqemud-ro.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2247, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2832, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

# Result testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall) failed
# 1 TEST FAILED [220s on a710aaa763ca]
not ok 6 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstall [ND@1]

First occurrence: 2024-12-02T02:43:16.411474+00:00 | revision e6e460829e0c4d14186e60a2c55f358ba0cf548e
Times recorded: 1
Latest occurrences:

2024-12-02T02:43:16.411474+00:00 | revision e6e460829e0c4d14186e60a2c55f358ba0cf548e

wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-3001-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-3001-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-3001-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-fedora-41-127.0.0.2-3001-FAIL.log.gz
SKIP http://127.0.0.2:9891/cockpit/static/login.js -> /work/make-checkout-workdir/dist/static/login.js
SKIP http://127.0.0.2:9891/cockpit/@localhost/base1/cockpit.js -> /work/make-checkout-workdir/dist/base1/cockpit.js
SKIP http://127.0.0.2:9891/cockpit/@localhost/shell/shell.js -> /work/make-checkout-workdir/dist/shell/shell.js
Wrote coverage data to /work/make-checkout-workdir/lcov/TestMachinesCreate-testConfigureBeforeInstallBiosTPM.info.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged.socket, virtstoraged-ro.socket, virtstoraged-admin.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd.socket, virtnetworkd-admin.socket, virtnetworkd-ro.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-admin.socket, virtqemud.socket, virtqemud-ro.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2404, in testConfigureBeforeInstallBiosTPM
    b.wait_in_text(f"#vm-{vmName}-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

# Result testConfigureBeforeInstallBiosTPM (__main__.TestMachinesCreate.testConfigureBeforeInstallBiosTPM) failed
# 1 TEST FAILED [50s on 53dd78068ce6]
not ok 8 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstallBiosTPM [ND@7]

First occurrence: 2024-12-04T08:30:42.855924+00:00 | revision d16231f66ff6c06cebd2f1c8a0f1367fd1514fec
Times recorded: 1
Latest occurrences:

2024-12-04T08:30:42.855924+00:00 | revision d16231f66ff6c06cebd2f1c8a0f1367fd1514fec

Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2248, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
    ~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2862, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2501-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2501-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2501-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstall-fedora-41-127.0.0.2-2501-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged.socket, virtstoraged-ro.socket, virtstoraged-admin.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd-admin.socket, virtnetworkd-ro.socket, virtnetworkd.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-ro.socket, virtqemud.socket, virtqemud-admin.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2248, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
    ~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2862, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

# Result testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall) failed
# 1 TEST FAILED [226s on c3d9b6ea14c1]
not ok 6 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstall [ND@3]

First occurrence: 2025-01-10T02:35:31.056695+00:00 | revision 6c1f318dc3fc2504a64989780e17691666ff02e2
Times recorded: 1
Latest occurrences:

2025-01-10T02:35:31.056695+00:00 | revision 6c1f318dc3fc2504a64989780e17691666ff02e2

cockpituous · 2024-09-26T12:18:45Z

rhel-10-0
Ooops, it happened again

# ----------------------------------------------------------------------
# testMultipleSettings (__main__.TestMachinesSettings.testMultipleSettings)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 39191
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 39191.
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> warn: Failed to start VM subVmTest1 : internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/subVmTest1-swtpm.log' for details.
Wrote subVmTest1 XML to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2201-subVmTest1.xml
Wrote subVmTest1 log to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2201-subVmTest1.log
Wrote subVmTest1 console log to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2201-subVmTest1-console.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-settings", line 537, in testMultipleSettings
    b.wait_in_text("#vm-subVmTest1-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 853, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 803, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

Wrote screenshot to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2201-FAIL.png
Wrote HTML dump to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2201-FAIL.html
Wrote JS log to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2201-FAIL.js.log
Journal extracted to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2201-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged-ro.socket, virtstoraged-admin.socket, virtstoraged.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd-ro.socket, virtnetworkd.socket, virtnetworkd-admin.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-admin.socket, virtqemud-ro.socket, virtqemud.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-settings", line 537, in testMultipleSettings
    b.wait_in_text("#vm-subVmTest1-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 853, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 803, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

# Result testMultipleSettings (__main__.TestMachinesSettings.testMultipleSettings) failed
# 1 TEST FAILED [72s on 10c3124c8827]
not ok 60 /work/make-checkout-workdir/test/check-machines-settings TestMachinesSettings.testMultipleSettings [ND@0]

First occurrence: 2024-11-27T13:58:39.466833+00:00 | revision 5dc6ec3eab2f1606ff486e506ad6bd62ead263fb
Times recorded: 1
Latest occurrences:

2024-11-27T13:58:39.466833+00:00 | revision 5dc6ec3eab2f1606ff486e506ad6bd62ead263fb

# ----------------------------------------------------------------------
# testConfigureBeforeInstallBiosTPM (__main__.TestMachinesCreate.testConfigureBeforeInstallBiosTPM)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 37383
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 37383.
.+.+.....+.+.....................+.....+.......+.....+++++++++++++++++++++++++++++++++++++++++++++*.......+...+..+.............+......+.....+......+....+....................+.+..+......+.........+.+..+.........+.+++++++++++++++++++++++++++++++++++++++++++++*..+......+..+.............+.....+....+...........+.+.....+.........+.......+..............+....+..+...........................+...+.......+...+..+..........+.....+.............+..+.......+......+.....+.........+......+............+.........................+.....+.+..+...+......+.+..+.+.....+.......+........+.+...+..+.........+....+...+...+.....+.........+...+.........+..........+...+......+..+...+....+..+....+......+.....+....+..+....+........+.......+...+.................+.............+......+.....+.+...+...+...+.........+..+...+............+.........+.......+..+...+...............+.............+......+...............+......+.....+..................+.+..............+....+..+.+.....+....+...+......+...........+.+..............................+..............+.+..+.+.....+.........+.+.....+......+.....................+..........+...+......+........+...+...............+...+......................+...+.........+...+.....+........................+....+......+..............+.+...+...+..+.+..+.................................+.+.....+..........+.....+....+.....+...................+++++
....+...+..........+..+....+..+.............+..+....+.....+.........+.+........+...+....+...+......+.....+......+.+.........+...+...+.....+.......+..+......+...+....+..+....+++++++++++++++++++++++++++++++++++++++++++++*.......+.....+...+....+...+++++++++++++++++++++++++++++++++++++++++++++*...+..+.......+......+..+.........+....+..+..........+...+.........+..........................+....+..+...+...+...+.......+.....+............+..........+.....+.+.........+.....+.+..+...................+......+...+.....+.+...+........+....+..................+...+.................+......+.......+........+....+....................+.+..+..........+........+..........+...............+..+............+...+...+.+........+.+..+..........+...+......+........+......+................+..................+............+.....+........................+....+..+...+..........................................+.............+..+....+..+.......+........+.......+...........+.+..............+.+.....+..........+...+...........+....+.................+....+.............................+..........+.....+......+.+.....+.+..+..........+.....................+.....+.........+.+..+....+..............+....+...............+..+.+...............+...+..............+..........+..+.+...+.....+....+..+...+..........+........+...+....+.....+...+.............+......+.....+...+.....................+.......+...+..+...+....+...+.......................+................+........+.+...............+.....+.........+.......+...+..+.............+............+........+.............+........+....+...+.......................+.+...+..+.+..............+......+.......+......+..+...............+...+....+........+...+....+..+.+........+.+........+......+.+...............+.....................+..+.+..+.............+.........+.....+.+......+........+...............+.+.........+............+...+......+..+...+.......+..+...+.......+.....+.+..+..........+...+.....+.....................+.+..+.............+.........+.........+...........+..........+.....+...+..........+............+.....+.........+................+...........+...+....+......+..+.........+....+..+......................+......+......+..............+.......+..+...+..........+..+......+...............+.......+...+......+..............+....+.....+............+.+........+..........+.....+.......+.....+.......+..+.+...+.........+.....+...+............+.+......+...........+....+.....+...+.+...............+......+.....+.+..+......+...................+......+........................+...+..............+.........+.+.........+............+............+...+.....+...+....+..+..................+......+......................+........+.+.....+.+...+......+.....+.+.....+....+.....+.........+..........+......+...+..+....+......+........+.......+.....+...+.......+++++
-----
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> info: osinfo-detect command failed:  (process:5111): GLib-GIO-WARNING **: 21:59:17.514: Can't find module 'gvfs' specified in GIO_USE_VFS
Traceback (most recent call last):
  File "<string>", line 22, in <module>
gi.repository.GLib.GError: osinfo-media-error: No volume descriptors (0)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
WARNING: Waiting for ph_is_present("#vm-subVmTest1-firmware") took 8.1 seconds, which is 54% of the timeout.
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/subVmTest1-swtpm.log' for details.\nDomain installation does not appear to have been successful.\nIf it was, you can restart your domain by running:\n  virsh --connect qemu:///system start subVmTest1\notherwise, please restart your installation.\nCommand '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 297, in install_vm\n  File \"/usr/lib64/python3.12/subprocess.py\", line 466, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/lib64/python3.12/subprocess.py\", line 571, in run\n    raise CalledProcessError(retcode, process.args,\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 375, in <module>\n  File \"<string>\", line 308, in install_vm\n  File \"<string>\", line 297, in install_vm\n  File \"/usr/lib64/python3.12/subprocess.py\", line 466, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/lib64/python3.12/subprocess.py\", line 571, in run\n    raise CalledProcessError(retcode, process.args,\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1."}
Wrote subVmTest1 XML to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-subVmTest1.xml
Wrote subVmTest1 log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-subVmTest1.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2404, in testConfigureBeforeInstallBiosTPM
    b.wait_in_text(f"#vm-{vmName}-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 853, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 803, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged.socket, virtstoraged-admin.socket, virtstoraged-ro.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd-admin.socket, virtnetworkd.socket, virtnetworkd-ro.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-admin.socket, virtqemud-ro.socket, virtqemud.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2404, in testConfigureBeforeInstallBiosTPM
    b.wait_in_text(f"#vm-{vmName}-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 853, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 803, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

# Result testConfigureBeforeInstallBiosTPM (__main__.TestMachinesCreate.testConfigureBeforeInstallBiosTPM) failed
# 1 TEST FAILED [79s on 3c2c311b7c54]
not ok 8 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstallBiosTPM [ND@7]

First occurrence: 2024-11-28T02:59:55.953268+00:00 | revision 0c2206169440534b11ae0d0d7fb3276c137167fc
Times recorded: 1
Latest occurrences:

2024-11-28T02:59:55.953268+00:00 | revision 0c2206169440534b11ae0d0d7fb3276c137167fc

# ----------------------------------------------------------------------
# testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 44973
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 44973.
.+...+....+.........+++++++++++++++++++++++++++++++++++++++++++++*....+................+..+..........+..+.+......+...+......+.....+.+........+.+.........+..+..........+.....+....+......+.....+.+..+...+....+.....+....+..+.......+..+++++++++++++++++++++++++++++++++++++++++++++*...+....+...+...+.........+......+......+..................+............+...+...........+.+.....+.+..+.+......+.....+.........+....+......+..+..........+...+....................+.......+.....+..........+...+.....+.+.....+.+++++
..+..+...+......+......+.........+.........+......+....+...........+....+..+.......+++++++++++++++++++++++++++++++++++++++++++++*..+.+...............+...+..+...+....+........+...+....+..+.+.....+.................................+.+...+...+..+.+.....+......+.+........+.+.....+.+..+.+..+............+............+.........+..........+......+...+..+++++++++++++++++++++++++++++++++++++++++++++*......+.+..............+....+....................+.+......+...+......+..+............+.+......+++++
-----
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> info: osinfo-detect command failed:  (process:5064): GLib-GIO-WARNING **: 21:59:13.942: Can't find module 'gvfs' specified in GIO_USE_VFS
Traceback (most recent call last):
  File "<string>", line 22, in <module>
gi.repository.GLib.GError: osinfo-media-error: No volume descriptors (0)
error: failed to get domain 'VmNotInstalled'
error: failed to get domain 'VmNotInstalled'
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/VmNotInstalled-swtpm.log' for details.\nDomain installation does not appear to have been successful.\nIf it was, you can restart your domain by running:\n  virsh --connect qemu:///system start VmNotInstalled\notherwise, please restart your installation.\nCommand '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 297, in install_vm\n  File \"/usr/lib64/python3.12/subprocess.py\", line 466, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/lib64/python3.12/subprocess.py\", line 571, in run\n    raise CalledProcessError(retcode, process.args,\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 375, in <module>\n  File \"<string>\", line 308, in install_vm\n  File \"<string>\", line 297, in install_vm\n  File \"/usr/lib64/python3.12/subprocess.py\", line 466, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/lib64/python3.12/subprocess.py\", line 571, in run\n    raise CalledProcessError(retcode, process.args,\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1."}
Wrote VmNotInstalled XML to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-VmNotInstalled.xml
Wrote VmNotInstalled log to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-VmNotInstalled.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2247, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2815, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged.socket, virtstoraged-ro.socket, virtstoraged-admin.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd-ro.socket, virtnetworkd.socket, virtnetworkd-admin.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-ro.socket, virtqemud-admin.socket, virtqemud.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2247, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2815, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

# Result testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall) failed
# 1 TEST FAILED [251s on 3c2c311b7c54]
not ok 6 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstall [ND@5]

First occurrence: 2024-11-28T03:02:47.221012+00:00 | revision 0c2206169440534b11ae0d0d7fb3276c137167fc
Times recorded: 1
Latest occurrences:

2024-11-28T03:02:47.221012+00:00 | revision 0c2206169440534b11ae0d0d7fb3276c137167fc

# ----------------------------------------------------------------------
# testMultipleSettings (__main__.TestMachinesSettings.testMultipleSettings)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 56443
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 56443.
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> warn: Failed to start VM subVmTest1 : internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/subVmTest1-swtpm.log' for details.
Wrote subVmTest1 XML to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2701-subVmTest1.xml
Wrote subVmTest1 log to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2701-subVmTest1.log
Wrote subVmTest1 console log to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2701-subVmTest1-console.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-settings", line 537, in testMultipleSettings
    b.wait_in_text("#vm-subVmTest1-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 853, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 803, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

Wrote screenshot to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2701-FAIL.png
Wrote HTML dump to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2701-FAIL.html
Wrote JS log to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2701-FAIL.js.log
Journal extracted to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2701-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged.socket, virtstoraged-ro.socket, virtstoraged-admin.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd-ro.socket, virtnetworkd-admin.socket, virtnetworkd.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud.socket, virtqemud-admin.socket, virtqemud-ro.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-settings", line 537, in testMultipleSettings
    b.wait_in_text("#vm-subVmTest1-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 853, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 803, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

# Result testMultipleSettings (__main__.TestMachinesSettings.testMultipleSettings) failed
# 1 TEST FAILED [89s on 3c2c311b7c54]
not ok 77 /work/make-checkout-workdir/test/check-machines-settings TestMachinesSettings.testMultipleSettings [ND@4]

First occurrence: 2024-11-28T03:08:38.804848+00:00 | revision 0c2206169440534b11ae0d0d7fb3276c137167fc
Times recorded: 1
Latest occurrences:

2024-11-28T03:08:38.804848+00:00 | revision 0c2206169440534b11ae0d0d7fb3276c137167fc

# ----------------------------------------------------------------------
# testConfigureBeforeInstallBiosTPM (__main__.TestMachinesCreate.testConfigureBeforeInstallBiosTPM)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 40799
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 40799.
..+.....................+++++++++++++++++++++++++++++++++++++++++++++*..+++++++++++++++++++++++++++++++++++++++++++++*....+.........+.+...+.....+.+...........+....+.....+......+......+...+....+.....+.......+...+...........+..........+.........+.....+.+..+............+......+.+.....+...+.+..............+.......+......+..+...+....+...+........+...+......+...............+...+...+...............+............+.............+........+.+...+..+......+.......+..+.............+............+...............+........+.+......+.....+...+............+...............+......+...............+..........+............+..+....+............+..+............+.+..+.+.....................+.....+......+.......+...+.....+............+..................+....+...+.....+...+...+.......+...+.....+......+......+.........+.........+++++
...+..+............+...+......+.+........+...+.........+.........+++++++++++++++++++++++++++++++++++++++++++++*.+..+.........+....+........+...+......+......+.......+......+......+++++++++++++++++++++++++++++++++++++++++++++*.......+...........+...+............+...+.............+...+..+.........+.+...+................................+...+..............................+............+.......+......+...........+....+......+...+.....+......+.......+.........+.....+....+.....+.+......+..+.+..+............+..........+..+......+.........+.+............+.....+.............+..................+............+.....+............................+...........+...+...+.........+......+.......+......+.....+.+...+..+..........+++++
-----
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> info: osinfo-detect command failed:  (process:5091): GLib-GIO-WARNING **: 07:55:04.568: Can't find module 'gvfs' specified in GIO_USE_VFS
Traceback (most recent call last):
  File "<string>", line 22, in <module>
gi.repository.GLib.GError: osinfo-media-error: No volume descriptors (0)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/subVmTest1-swtpm.log' for details.\nDomain installation does not appear to have been successful.\nIf it was, you can restart your domain by running:\n  virsh --connect qemu:///system start subVmTest1\notherwise, please restart your installation.\nCommand '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 297, in install_vm\n  File \"/usr/lib64/python3.12/subprocess.py\", line 466, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/lib64/python3.12/subprocess.py\", line 571, in run\n    raise CalledProcessError(retcode, process.args,\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 375, in <module>\n  File \"<string>\", line 308, in install_vm\n  File \"<string>\", line 297, in install_vm\n  File \"/usr/lib64/python3.12/subprocess.py\", line 466, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/lib64/python3.12/subprocess.py\", line 571, in run\n    raise CalledProcessError(retcode, process.args,\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1."}
Wrote subVmTest1 XML to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-subVmTest1.xml
Wrote subVmTest1 log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-subVmTest1.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2404, in testConfigureBeforeInstallBiosTPM
    b.wait_in_text(f"#vm-{vmName}-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged.socket, virtstoraged-admin.socket, virtstoraged-ro.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd.socket, virtnetworkd-admin.socket, virtnetworkd-ro.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-ro.socket, virtqemud.socket, virtqemud-admin.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2404, in testConfigureBeforeInstallBiosTPM
    b.wait_in_text(f"#vm-{vmName}-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

# Result testConfigureBeforeInstallBiosTPM (__main__.TestMachinesCreate.testConfigureBeforeInstallBiosTPM) failed
# 1 TEST FAILED [77s on 8930797f473e]
not ok 8 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstallBiosTPM [ND@7]

First occurrence: 2024-11-28T12:55:36.041314+00:00 | revision ebaa19d80796c021d6a3d1d101b8b035d79e1bfc
Times recorded: 1
Latest occurrences:

2024-11-28T12:55:36.041314+00:00 | revision ebaa19d80796c021d6a3d1d101b8b035d79e1bfc

# ----------------------------------------------------------------------
# testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 39901
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 39901.
.......+.+......+.....+.......+.....+..........+...+.....+...+.......+...+......+...+.....+...+...+.+........+.+.....+.......+..............+...+......+.+...+........+....+...+........+...+....+..+....+..+....+...+...............+.....+.......+........+............+.............+...+..+.......+...+..+....+............+++++++++++++++++++++++++++++++++++++++++++++*.........+.+..+.+...............+............+..+.+............+..+.......+......+..+......+.+...+++++++++++++++++++++++++++++++++++++++++++++*....+.............+..+.......+...+............+...........+.+..............+......+.............+..+.........+......+...+.+......+...+........+....+........+.+..+...+.+......+...+..............+...+...............+......................+..+......+......................+.....+......+......+...+.............+...+...........+.+........+......+....+.....+......+..........+.....+...................+..+....+......+.........+..................+...+..+...+..........+........+......+..........+........+.+.........+......+..+.........+...............+....+............+........+.+...+...+............+..+.+..+.+...............+............+...+...........................+..............+.+..+.......+.........+..+...+....+...............+.....+......+.+...........+.............+..............+......+...............+.+.....+.+.....+.............+....................+.+...........+............+.+...........+.............+.......................+.......+...+.................................+.....+.+.........+...........+.+.........+...........+..........+...............+...+...........+......+...................+........+.......+...........+.........+...................+++++
...+........+.+..+...+....+......+..............+...+..........+............+..+.+...+.....+....+..+...............+....+.....+.+......+.....+....+......+...+...........+.+...+....................+.+...+..+.+..............+....+.........+......+............+.....+.......+...+..+.+++++++++++++++++++++++++++++++++++++++++++++*.+...+..+.+............+...+..+.+......+...+...........+.+++++++++++++++++++++++++++++++++++++++++++++*...+.........+..+.......+........+..................+..........+............+..+.+.....+.......+..+............+......+....+...+..+.+........+.........+++++
-----
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> info: osinfo-detect command failed:  (process:5067): GLib-GIO-WARNING **: 07:55:04.835: Can't find module 'gvfs' specified in GIO_USE_VFS
Traceback (most recent call last):
  File "<string>", line 22, in <module>
gi.repository.GLib.GError: osinfo-media-error: No volume descriptors (0)
error: failed to get domain 'VmNotInstalled'
error: failed to get domain 'VmNotInstalled'
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/VmNotInstalled-swtpm.log' for details.\nDomain installation does not appear to have been successful.\nIf it was, you can restart your domain by running:\n  virsh --connect qemu:///system start VmNotInstalled\notherwise, please restart your installation.\nCommand '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 297, in install_vm\n  File \"/usr/lib64/python3.12/subprocess.py\", line 466, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/lib64/python3.12/subprocess.py\", line 571, in run\n    raise CalledProcessError(retcode, process.args,\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 375, in <module>\n  File \"<string>\", line 308, in install_vm\n  File \"<string>\", line 297, in install_vm\n  File \"/usr/lib64/python3.12/subprocess.py\", line 466, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/lib64/python3.12/subprocess.py\", line 571, in run\n    raise CalledProcessError(retcode, process.args,\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1."}
Wrote VmNotInstalled XML to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-VmNotInstalled.xml
Wrote VmNotInstalled log to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-VmNotInstalled.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2247, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2832, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged-ro.socket, virtstoraged.socket, virtstoraged-admin.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd.socket, virtnetworkd-ro.socket, virtnetworkd-admin.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-admin.socket, virtqemud-ro.socket, virtqemud.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2247, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2832, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

# Result testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall) failed
# 1 TEST FAILED [257s on 8930797f473e]
not ok 6 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstall [ND@5]

First occurrence: 2024-11-28T12:58:36.424749+00:00 | revision ebaa19d80796c021d6a3d1d101b8b035d79e1bfc
Times recorded: 1
Latest occurrences:

2024-11-28T12:58:36.424749+00:00 | revision ebaa19d80796c021d6a3d1d101b8b035d79e1bfc

# ----------------------------------------------------------------------
# testConfigureBeforeInstallBiosTPM (__main__.TestMachinesCreate.testConfigureBeforeInstallBiosTPM)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 37155
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 37155.
..+.+...............+..+..........+...+.....+....+++++++++++++++++++++++++++++++++++++++++++++*.+...+.......+..+.+.....+.+......+...+...........+......+.......+..+++++++++++++++++++++++++++++++++++++++++++++*..+.........+.....................+...+.+...+..+................+...+...........+......+.......+...+...+.....+......+.+...+..+..................+.+...+...........+....+.....+............+...+.+..............+.........................+..+......+....+.....+....+......+..................+......+...........+....+.....+.+.....+......+....+.....+...............+.+++++
..+.+.....+.+...+++++++++++++++++++++++++++++++++++++++++++++*.....+........+.+........+......+............+...+.......+..+.............+..............+.+...+......+.....+.............+...+...+.....+......+....+.....+.+.....+...+.+.....+......+......+++++++++++++++++++++++++++++++++++++++++++++*.+...+...........+.+...+...+.....+...+.......+..+.......+........................+.........+..+.+..+.......+.....+...+.+......+......+..+......+......+.+.....+.............+.........+.....+.......+.........+.....+......+..............................+.+........+......+......+.......+...+...+.................+....+...+...+...+......+.....+.........+.......+..+.+..+....+......+........+............+...+......+...+............+++++
-----
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> info: osinfo-detect command failed:  (process:5054): GLib-GIO-WARNING **: 08:32:31.298: Can't find module 'gvfs' specified in GIO_USE_VFS
Traceback (most recent call last):
  File "<string>", line 22, in <module>
gi.repository.GLib.GError: osinfo-media-error: No volume descriptors (0)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/subVmTest1-swtpm.log' for details.\nDomain installation does not appear to have been successful.\nIf it was, you can restart your domain by running:\n  virsh --connect qemu:///system start subVmTest1\notherwise, please restart your installation.\nCommand '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 297, in install_vm\n  File \"/usr/lib64/python3.12/subprocess.py\", line 466, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/lib64/python3.12/subprocess.py\", line 571, in run\n    raise CalledProcessError(retcode, process.args,\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 375, in <module>\n  File \"<string>\", line 308, in install_vm\n  File \"<string>\", line 297, in install_vm\n  File \"/usr/lib64/python3.12/subprocess.py\", line 466, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/lib64/python3.12/subprocess.py\", line 571, in run\n    raise CalledProcessError(retcode, process.args,\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'subVmTest1', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1."}
Wrote subVmTest1 XML to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-subVmTest1.xml
Wrote subVmTest1 log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-subVmTest1.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2404, in testConfigureBeforeInstallBiosTPM
    b.wait_in_text(f"#vm-{vmName}-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-rhel-10-0-127.0.0.2-3001-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged.socket, virtstoraged-admin.socket, virtstoraged-ro.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd-ro.socket, virtnetworkd.socket, virtnetworkd-admin.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-admin.socket, virtqemud-ro.socket, virtqemud.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2404, in testConfigureBeforeInstallBiosTPM
    b.wait_in_text(f"#vm-{vmName}-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

# Result testConfigureBeforeInstallBiosTPM (__main__.TestMachinesCreate.testConfigureBeforeInstallBiosTPM) failed
# 1 TEST FAILED [73s on f4d3e696d55e]
not ok 8 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstallBiosTPM [ND@7]

First occurrence: 2024-11-29T13:32:59.788177+00:00 | revision b276f3f831af628f21b069c17fd716dc3992bec7
Times recorded: 1
Latest occurrences:

2024-11-29T13:32:59.788177+00:00 | revision b276f3f831af628f21b069c17fd716dc3992bec7

# ----------------------------------------------------------------------
# testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 55895
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 55895.
......+...+.........+...+++++++++++++++++++++++++++++++++++++++++++++*.+.+......+.....+.......+........+.........+....+..+....+...+...............+.....+......+.+.........+..............+............+.+.....+.........+....+..+..........+..+..........+..+...+.+......+......+++++++++++++++++++++++++++++++++++++++++++++*..+......+.........+......+...............+.+.....+.........+....+..+......................+............+......+..+................+.....+......+................+...+...................................+.......+.........+...............+.....+....+..+.........+......+..........+...+..+..........+............+.....+...+....+......+........+.....................+....+..+..........+............+......+..+...+.......+...+......+.....+......+....+....................+...+.+......+..............+.........+......+.+...+..+.........+....+..+......+.......+.....+...+.......+......+......+.....+.........+......+...+.......+...+........+.......+........+.......+..+......+.......+..+................+..+..........+.....+............+....+.........+.................................+++++
..........+........+++++++++++++++++++++++++++++++++++++++++++++*.....+...+........+...+++++++++++++++++++++++++++++++++++++++++++++*...+........+...+.......+.................+.+........+.........................+........................+.....+.+......+........+...+.........+.+..+...+.........+.+...............+.....+....+...+...........+....+......+...............+.....+.........+.............+.....+...+.+......+..............+.+...+.........+......+..+...+................+...+...+..................+......+.............................+....+..+...+...+.......+...........+..........+............+.........+.....+...+...+.......+.....+...+....+...+..+....+.....+...................+.....+.......+......+...+...........+.........+......+...+.............+........+..........+..+...+..........+......+........+......+....+......+..............+...............+...+.......+........+.......+..+................+.....+....+...+..+...................+.....+..........+.....+.+...............+...+..+......+........................+.+++++
-----
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> info: osinfo-detect command failed:  (process:5122): GLib-GIO-WARNING **: 08:32:27.198: Can't find module 'gvfs' specified in GIO_USE_VFS
Traceback (most recent call last):
  File "<string>", line 22, in <module>
gi.repository.GLib.GError: osinfo-media-error: No volume descriptors (0)
error: failed to get domain 'VmNotInstalled'
error: failed to get domain 'VmNotInstalled'
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/VmNotInstalled-swtpm.log' for details.\nDomain installation does not appear to have been successful.\nIf it was, you can restart your domain by running:\n  virsh --connect qemu:///system start VmNotInstalled\notherwise, please restart your installation.\nCommand '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 297, in install_vm\n  File \"/usr/lib64/python3.12/subprocess.py\", line 466, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/lib64/python3.12/subprocess.py\", line 571, in run\n    raise CalledProcessError(retcode, process.args,\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1.\nTraceback (most recent call last):\n  File \"<string>\", line 375, in <module>\n  File \"<string>\", line 308, in install_vm\n  File \"<string>\", line 297, in install_vm\n  File \"/usr/lib64/python3.12/subprocess.py\", line 466, in check_output\n    return run(*popenargs, stdout=PIPE, timeout=timeout, check=True,\n           ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^\n  File \"/usr/lib64/python3.12/subprocess.py\", line 571, in run\n    raise CalledProcessError(retcode, process.args,\nsubprocess.CalledProcessError: Command '['virt-install', '--connect', 'qemu:///system', '--quiet', '--os-variant', 'fedora28', '--reinstall', 'VmNotInstalled', '--wait', '-1', '--noautoconsole', '--cdrom', '/var/lib/libvirt/novell.iso']' returned non-zero exit status 1."}
Wrote VmNotInstalled XML to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-VmNotInstalled.xml
Wrote VmNotInstalled log to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-VmNotInstalled.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2247, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2832, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstall-rhel-10-0-127.0.0.2-2801-FAIL.log.gz
> warn: transport closed: disconnected
umount: /run/user/1000: target is busy.
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged.socket, virtstoraged-admin.socket, virtstoraged-ro.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd-admin.socket, virtnetworkd.socket, virtnetworkd-ro.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud-admin.socket, virtqemud-ro.socket, virtqemud.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2247, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2832, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

# Result testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall) failed
# 1 TEST FAILED [252s on f4d3e696d55e]
not ok 6 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstall [ND@5]

First occurrence: 2024-11-29T13:35:59.084021+00:00 | revision b276f3f831af628f21b069c17fd716dc3992bec7
Times recorded: 1
Latest occurrences:

2024-11-29T13:35:59.084021+00:00 | revision b276f3f831af628f21b069c17fd716dc3992bec7

# ----------------------------------------------------------------------
# testMultipleSettings (__main__.TestMachinesSettings.testMultipleSettings)
Starting ChromeDriver 129.0.6668.89 (951c0b97221f8d4ba37cf97d324505c832251cf9-refs/branch-heads/6668@{#1503}) on port 48051
Only local connections are allowed.
Please see https://chromedriver.chromium.org/security-considerations for suggestions on keeping ChromeDriver safe.
ChromeDriver was started successfully on port 48051.
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> info: failed to fetch lastlog2: Object(5)
> warn: cockpit.format_{bytes,bits}[_per_sec](..., MiB, [object Object]) is deprecated.
> warn: Failed to start VM subVmTest1 : internal error: Could not run '/usr/bin/swtpm_setup'. exitstatus: 1; Check error log '/var/log/swtpm/libvirt/qemu/subVmTest1-swtpm.log' for details.
Wrote subVmTest1 XML to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2201-subVmTest1.xml
Wrote subVmTest1 log to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2201-subVmTest1.log
Wrote subVmTest1 console log to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2201-subVmTest1-console.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-settings", line 537, in testMultipleSettings
    b.wait_in_text("#vm-subVmTest1-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

Wrote screenshot to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2201-FAIL.png
Wrote HTML dump to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2201-FAIL.html
Wrote JS log to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2201-FAIL.js.log
Journal extracted to TestMachinesSettings-testMultipleSettings-rhel-10-0-127.0.0.2-2201-FAIL.log.gz
> warn: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged-ro.socket, virtstoraged-admin.socket, virtstoraged.socket
Stopping 'virtnetworkd.service', but its triggering units are still active:
virtnetworkd-admin.socket, virtnetworkd-ro.socket, virtnetworkd.socket
Stopping 'virtqemud.service', but its triggering units are still active:
virtqemud.socket, virtqemud-ro.socket, virtqemud-admin.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-settings", line 537, in testMultipleSettings
    b.wait_in_text("#vm-subVmTest1-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 869, in wait_in_text
    self.wait_js_cond("ph_in_text(%s,%s)" % (jsquote(selector), jsquote(text)),
  File "/work/make-checkout-workdir/test/common/testlib.py", line 819, in wait_js_cond
    raise Error(f"timeout\nwait_js_cond({cond}): {last_error.msg}") from None
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Error: actual text: Shut off

# Result testMultipleSettings (__main__.TestMachinesSettings.testMultipleSettings) failed
# 1 TEST FAILED [72s on f4d3e696d55e]
not ok 77 /work/make-checkout-workdir/test/check-machines-settings TestMachinesSettings.testMultipleSettings [ND@0]

First occurrence: 2024-11-29T13:40:30.138067+00:00 | revision b276f3f831af628f21b069c17fd716dc3992bec7
Times recorded: 1
Latest occurrences:

2024-11-29T13:40:30.138067+00:00 | revision b276f3f831af628f21b069c17fd716dc3992bec7

We want to write a precise naughty for some startup failures like cockpit-project/bots#6792 . For that we need the message on the console, not just on the UI. It also makes human debugging easier.

cockpit-project/cockpit-machines#1796 adds TPM testing to TestMachinesSettings.testMultipleSettings, which also fails due to cockpit-project#6792. Add a corresponding pattern.

cockpit-project/cockpit-machines#1796 adds TPM testing to TestMachinesSettings.testMultipleSettings, which also fails due to #6792. Add a corresponding pattern.

We want to write a precise naughty for some startup failures like cockpit-project/bots#6792 . For that we need the message on the console, not just on the UI. It also makes human debugging easier.

mvollmer added a commit to mvollmer/bots that referenced this issue Aug 26, 2024

naughty: New cockpit-project#6792 for fedora-41

c10d27d

martinpitt pushed a commit that referenced this issue Aug 26, 2024

naughty: New #6792 for fedora-41

503d1d7

martinpitt mentioned this issue Sep 26, 2024

testmap: Enable rhel-10-0 for c-machines #6920

Merged

martinpitt added a commit to martinpitt/bots that referenced this issue Sep 26, 2024

naughty: cockpit-project#6792 applies to rhel-10-0 as well

3579345

jelly pushed a commit that referenced this issue Sep 26, 2024

naughty: #6792 applies to rhel-10-0 as well

f9e8fe6

mvollmer added the knownissue label Oct 8, 2024

martinpitt mentioned this issue Nov 4, 2024

Add "Add TPM" VM action cockpit-project/cockpit-machines#1796

Merged

1 task

martinpitt mentioned this issue Nov 4, 2024

naughty: Add variant of #6792 for new test #7064

Merged

jelly pushed a commit that referenced this issue Nov 4, 2024

naughty: Add variant of #6792 for new test

80ff2a8

cockpit-project/cockpit-machines#1796 adds TPM testing to TestMachinesSettings.testMultipleSettings, which also fails due to #6792. Add a corresponding pattern.

Provide feedback

Saved searches

Use saved searches to filter your results more quickly

SELinux denies swtpm access to /var/log/swtpm/libvirt/qemu/*.log #6792

SELinux denies swtpm access to /var/log/swtpm/libvirt/qemu/*.log #6792

mvollmer commented Aug 26, 2024

martinpitt commented Aug 26, 2024 •

edited

Loading

mvollmer commented Aug 26, 2024

mvollmer commented Aug 26, 2024

cockpituous commented Aug 26, 2024 •

edited

Loading

cockpituous commented Sep 26, 2024 •

edited

Loading

SELinux denies swtpm access to /var/log/swtpm/libvirt/qemu/*.log #6792

SELinux denies swtpm access to /var/log/swtpm/libvirt/qemu/*.log #6792

Comments

mvollmer commented Aug 26, 2024

martinpitt commented Aug 26, 2024 • edited Loading

mvollmer commented Aug 26, 2024

mvollmer commented Aug 26, 2024

cockpituous commented Aug 26, 2024 • edited Loading

cockpituous commented Sep 26, 2024 • edited Loading

martinpitt commented Aug 26, 2024 •

edited

Loading

cockpituous commented Aug 26, 2024 •

edited

Loading

cockpituous commented Sep 26, 2024 •

edited

Loading