bots libvirt is denied access to swtpm

libvirt is denied access to swtpm

Open jelly opened this issue 1 year ago • 4 comments

Bugzilla: https://bugzilla.redhat.com/show_bug.cgi?id=2265346

Feb 21 '24 16:02 jelly

fedora-40 Ooops, it happened again

Feb 21 '24 16:02 cockpituous

centos-10 Ooops, it happened again

# ----------------------------------------------------------------------
# testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall)
..+...+.+...+......+..+.........+.+..+..........+.....+.......+........+.......+......+........+.+.....+....+..+..................+...............+++++++++++++++++++++++++++++++++++++++++++++*...+.+...........+.+...+.....+......+.+........+.+.....+.+...+.....+....+......+.........+..+......+....+...........+.......+...+...+.....+...............+....+..+.+.....+...+....+..+......+...+....+...+..+.......+.....+...+...+...+.+.........+..+..........+...+.....+......+...+++++++++++++++++++++++++++++++++++++++++++++*..+.....+..................+.+...........+.+........+..........+......+........+............+.............+...+...+......+.....+..........+...+......+..............+.+...........+....+......+.....+.+........+..........+...........+....+.........+.....+..........+.....+..........+..................+..+...+..........+.........+.........+..+............+.+.........+..................+...+..............+.............+...+..+...................+...+...+...+.........+...+.......................+.......+...+.........+...+........+.+......+............+...+..+......+.+.....................+............+...........+.........+...+....+.....+.+..............+...+...+...+...............+....+...+++++
.+.....+...................+.....+...+...+.+........+...+....+......+.................+.+++++++++++++++++++++++++++++++++++++++++++++*......+.+++++++++++++++++++++++++++++++++++++++++++++*.....+++++
-----
[0522/143206.944983:WARNING:sandbox_linux.cc(420)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9688/devtools/browser/b22b85b8-ea17-47bb-960f-b40738be5a0f
[0522/143206.967269:WARNING:runtime_features.cc(730)] AttributionReportingCrossAppWeb cannot be enabled in this configuration. Use --enable-features=ConversionMeasurement,AttributionReportingCrossAppWeb in addition.
[0522/143207.620233:WARNING:runtime_features.cc(730)] AttributionReportingCrossAppWeb cannot be enabled in this configuration. Use --enable-features=ConversionMeasurement,AttributionReportingCrossAppWeb in addition.
CDP: {"source":"network","level":"error","text":"Failed to load resource: the server responded with a status of 404 (ERROR)","timestamp":1716388330733.63,"url":"http://127.0.0.2:9691/cockpit/@localhost/*/po.js","networkRequestId":"1878.20"}
CDP: {"source":"network","level":"error","text":"Failed to load resource: the server responded with a status of 404 (ERROR)","timestamp":1716388330745.364,"url":"http://127.0.0.2:9691/cockpit/@localhost/*/po.manifest.js","networkRequestId":"1878.19"}
CDP: {"source":"security","level":"error","text":"Refused to execute script from 'http://127.0.0.2:9691/cockpit/@localhost/*/po.manifest.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.","timestamp":1716388330787.046,"url":"http://127.0.0.2:9691/machines"}
CDP: {"source":"security","level":"error","text":"Refused to execute script from 'http://127.0.0.2:9691/cockpit/@localhost/*/po.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.","timestamp":1716388330787.202,"url":"http://127.0.0.2:9691/machines"}
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
> warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
> log: osinfo-detect command failed:  (process:5772): GLib-GIO-WARNING **: 10:32:20.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'
CDP: {"source":"network","level":"error","text":"Failed to load resource: the server responded with a status of 404 (ERROR)","timestamp":1716388349210.897,"url":"http://127.0.0.2:9691/cockpit/@localhost/*/po.manifest.js","networkRequestId":"1878.170"}
CDP: {"source":"network","level":"error","text":"Failed to load resource: the server responded with a status of 404 (ERROR)","timestamp":1716388349212.187,"url":"http://127.0.0.2:9691/cockpit/@localhost/*/po.js","networkRequestId":"1878.171"}
CDP: {"source":"security","level":"error","text":"Refused to execute script from 'http://127.0.0.2:9691/cockpit/@localhost/*/po.manifest.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.","timestamp":1716388349246.443,"url":"http://127.0.0.2:9691/machines#/vm?name=VmNotInstalled&connection=system"}
CDP: {"source":"security","level":"error","text":"Refused to execute script from 'http://127.0.0.2:9691/cockpit/@localhost/*/po.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.","timestamp":1716388349246.685,"url":"http://127.0.0.2:9691/machines#/vm?name=VmNotInstalled&connection=system"}
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
> warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not get process id of swtpm\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 285, 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 363, in <module>\n  File \"<string>\", line 296, in install_vm\n  File \"<string>\", line 285, 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-centos-10-127.0.0.2-2801-VmNotInstalled.xml
Wrote VmNotInstalled log to TestMachinesCreate-testConfigureBeforeInstall-centos-10-127.0.0.2-2801-VmNotInstalled.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2169, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2629, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstall-centos-10-127.0.0.2-2801-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstall-centos-10-127.0.0.2-2801-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstall-centos-10-127.0.0.2-2801-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstall-centos-10-127.0.0.2-2801-FAIL.log.gz
> warning: 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.socket, virtqemud-ro.socket, virtqemud-admin.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2169, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2629, 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 [248s on 2988a08c7135]
not ok 63 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstall [ND@5]

First occurrence: 2024-05-22T14:35:47.761819+00:00 | revision 49763df1a545c33b7451260fa0a0714f6380625a Times recorded: 1 Latest occurrences:

2024-05-22T14:35:47.761819+00:00 | revision 49763df1a545c33b7451260fa0a0714f6380625a

# ----------------------------------------------------------------------
# testConfigureBeforeInstallBiosTPM (__main__.TestMachinesCreate.testConfigureBeforeInstallBiosTPM)
.......+............+++++++++++++++++++++++++++++++++++++++++++++*...+...+.........+.+++++++++++++++++++++++++++++++++++++++++++++*............+.....+......+...+......+.+.....+....+.......................+...+.......+......+.................+..........+...+.....+.......+...+......+........+......+..........+...........+....+.........+..+...+.+.........+............+...+....................+...+.......+...........+..........+...........+.+............+..+.........+.+......+.....+....+......+...........................+............+.....+.....................+............+.+.........+........+.......+............+......+..+.+.....+......+.............+...+..............+..........+...+..+.............+...............+.....+.+......+......+........+.......+........+....+...+........+.......+...+......+...........+...+....+..................+.........+.....+................+..+.......+.........+.........+..+...+...+.........+.....................+.........+.........+.+.....+....+............+.....+.........+.+............+..+..................+...+..........+..+...+...+......+..........+..............................+.........+.........+..+......+.............+..+.......+...+..+...+.......+....................+......................+......+........+............+...............+....+........+......+.+...............+........+.+..+.......+...........+.+...+...+............+.....+............+...+......+............+..........+...+.....+...............+..................................+.............................................+..+.........+.......+..+..........+..+.+.....+............................+..+....+..............+....+...+.....+.........+.+..+......+.+........+......+............+....+......+.....+..........+..+...............+.......+.........+......+........+.+......+......+...+..+...+.+...........+.........+.+.....+...+.........+...+.......+......+.........+...+..+.+++++
..+..+..........+.....+.........+......+.+.....+.........+....+......+...+.....+.......+.....+++++++++++++++++++++++++++++++++++++++++++++*.....+............+................+..+.+++++++++++++++++++++++++++++++++++++++++++++*.........+.+..+...+....+...........+...+.......+........+....+.................+..................+..........+...+.....+..............................+..........+........+.......+...+.....+.+........+.+.........+...............+..+.........+......+...+..........+........+....+.........+......+...+...+.........+.....+...+.+.........+...+..............+.+........+....+...+............+..+.............+........+.............+.....+.......+..+.+......+..............+.........................+..+.............+..+...+.......+..+...+.........+...+......+.......+..+.+..+..................+............+...+............+..................................+...............+.....+...+.+....................+..........+.....+...................+......+..+.......+...........+....+..+....+.....+.+......+........+....+...+..+......+......+.+.....+..........+..+.......+...........+.......+.....+.+..+..................+.........+.......+...+........+..................+.......+............+.....+.+..............+......................+...............+......+..+......+..........+..+...+..........+++++
-----
[0621/050813.829722:WARNING:sandbox_linux.cc(436)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9851/devtools/browser/fc802054-db54-4cfc-9973-48529cea634b
CDP: {"source":"network","level":"error","text":"Failed to load resource: the server responded with a status of 404 (ERROR)","timestamp":1718946499447.345,"url":"http://127.0.0.2:9891/cockpit/@localhost/*/po.js","networkRequestId":"2382.20"}
CDP: {"source":"network","level":"error","text":"Failed to load resource: the server responded with a status of 404 (ERROR)","timestamp":1718946499448.613,"url":"http://127.0.0.2:9891/cockpit/@localhost/*/po.manifest.js","networkRequestId":"2382.19"}
CDP: {"source":"security","level":"error","text":"Refused to execute script from 'http://127.0.0.2:9891/cockpit/@localhost/*/po.manifest.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.","timestamp":1718946499484.838,"url":"http://127.0.0.2:9891/machines"}
CDP: {"source":"security","level":"error","text":"Refused to execute script from 'http://127.0.0.2:9891/cockpit/@localhost/*/po.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.","timestamp":1718946499484.955,"url":"http://127.0.0.2:9891/machines"}
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
> warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
> log: osinfo-detect command failed:  (process:7879): GLib-GIO-WARNING **: 01:08:41.113: 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)
CDP: {"source":"network","level":"error","text":"Failed to load resource: the server responded with a status of 404 (ERROR)","timestamp":1718946527384.036,"url":"http://127.0.0.2:9891/cockpit/@localhost/*/po.js","networkRequestId":"2382.171"}
CDP: {"source":"network","level":"error","text":"Failed to load resource: the server responded with a status of 404 (ERROR)","timestamp":1718946527385.657,"url":"http://127.0.0.2:9891/cockpit/@localhost/*/po.manifest.js","networkRequestId":"2382.170"}
CDP: {"source":"security","level":"error","text":"Refused to execute script from 'http://127.0.0.2:9891/cockpit/@localhost/*/po.manifest.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.","timestamp":1718946527447.239,"url":"http://127.0.0.2:9891/machines#/vm?name=subVmTest1&connection=system"}
CDP: {"source":"security","level":"error","text":"Refused to execute script from 'http://127.0.0.2:9891/cockpit/@localhost/*/po.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.","timestamp":1718946527447.357,"url":"http://127.0.0.2:9891/machines#/vm?name=subVmTest1&connection=system"}
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
> warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
> log: listInactiveInterfaces action for path /org/libvirt/QEMU/interface/52_3a54_3a01_3a00_3a00_3a01 Cannot recv data: Connection reset by peer
> log: listInactiveInterfaces action for path /org/libvirt/QEMU/interface/52_3a54_3a00_3a12_3a34_3a56 Cannot recv data: Connection reset by peer
> log: listInactiveInterfaces action for path /org/libvirt/QEMU/interface/00_3a00_3a00_3a00_3a00_3a00 Cannot recv data: Connection reset by peer
> log: listInactiveInterfaces action for path /org/libvirt/QEMU/interface/52_3a54_3a00_3ad1_3a8e_3aa4 Cannot recv data: Connection reset by peer
WARNING: Waiting for ph_is_present("#vm-subVmTest1-firmware:not([disabled]):not([aria-disabled=true])") took 11.9 seconds, which is 79% of the timeout.
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not get process id of swtpm\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 285, 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 363, in <module>\n  File \"<string>\", line 296, in install_vm\n  File \"<string>\", line 285, 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-centos-10-127.0.0.2-3001-subVmTest1.xml
Wrote subVmTest1 log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-centos-10-127.0.0.2-3001-subVmTest1.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2298, in testConfigureBeforeInstallBiosTPM
    b.wait_in_text(f"#vm-{vmName}-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 690, 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 628, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 331, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Uncaught (in promise) Error: actual text: Shut off
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1718946497817.889, 'url': 'http://127.0.0.2:9891/cockpit/login', 'networkRequestId': '2382.12'}
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 404 (ERROR)', 'timestamp': 1718946499447.345, 'url': 'http://127.0.0.2:9891/cockpit/@localhost/*/po.js', 'networkRequestId': '2382.20'}
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 404 (ERROR)', 'timestamp': 1718946499448.613, 'url': 'http://127.0.0.2:9891/cockpit/@localhost/*/po.manifest.js', 'networkRequestId': '2382.19'}
cdp: {'source': 'security', 'level': 'error', 'text': "Refused to execute script from 'http://127.0.0.2:9891/cockpit/@localhost/*/po.manifest.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.", 'timestamp': 1718946499484.838, 'url': 'http://127.0.0.2:9891/machines'}
cdp: {'source': 'security', 'level': 'error', 'text': "Refused to execute script from 'http://127.0.0.2:9891/cockpit/@localhost/*/po.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.", 'timestamp': 1718946499484.955, 'url': 'http://127.0.0.2:9891/machines'}
error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
log: osinfo-detect command failed:  (process:7879): GLib-GIO-WARNING **: 01:08:41.113: 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)
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 404 (ERROR)', 'timestamp': 1718946527384.036, 'url': 'http://127.0.0.2:9891/cockpit/@localhost/*/po.js', 'networkRequestId': '2382.171'}
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 404 (ERROR)', 'timestamp': 1718946527385.657, 'url': 'http://127.0.0.2:9891/cockpit/@localhost/*/po.manifest.js', 'networkRequestId': '2382.170'}
cdp: {'source': 'security', 'level': 'error', 'text': "Refused to execute script from 'http://127.0.0.2:9891/cockpit/@localhost/*/po.manifest.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.", 'timestamp': 1718946527447.239, 'url': 'http://127.0.0.2:9891/machines#/vm?name=subVmTest1&connection=system'}
cdp: {'source': 'security', 'level': 'error', 'text': "Refused to execute script from 'http://127.0.0.2:9891/cockpit/@localhost/*/po.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.", 'timestamp': 1718946527447.357, 'url': 'http://127.0.0.2:9891/machines#/vm?name=subVmTest1&connection=system'}
error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
log: listInactiveInterfaces action for path /org/libvirt/QEMU/interface/52_3a54_3a01_3a00_3a00_3a01 Cannot recv data: Connection reset by peer
log: listInactiveInterfaces action for path /org/libvirt/QEMU/interface/52_3a54_3a00_3a12_3a34_3a56 Cannot recv data: Connection reset by peer
log: listInactiveInterfaces action for path /org/libvirt/QEMU/interface/00_3a00_3a00_3a00_3a00_3a00 Cannot recv data: Connection reset by peer
log: listInactiveInterfaces action for path /org/libvirt/QEMU/interface/52_3a54_3a00_3ad1_3a8e_3aa4 Cannot recv data: Connection reset by peer
error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not get process id of swtpm\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 285, 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 363, in <module>\n  File \"<string>\", line 296, in install_vm\n  File \"<string>\", line 285, 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 screenshot to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-centos-10-127.0.0.2-3001-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-centos-10-127.0.0.2-3001-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-centos-10-127.0.0.2-3001-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-centos-10-127.0.0.2-3001-FAIL.log.gz
Core dumps downloaded to /work/make-checkout-workdir/TestMachinesCreate-testConfigureBeforeInstallBiosTPM-centos-10-127.0.0.2-3001-FAIL.core
Process 7407 (virtinterfaced) of user 0 dumped core.
Module libnss_systemd.so.2 from rpm systemd-255.3-1.el10.x86_64
Module libcap.so.2 from rpm libcap-2.69-4.el10.x86_64
Module libudev.so.1 from rpm systemd-255.3-1.el10.x86_64
Module libvirt_driver_interface.so from rpm libvirt-10.4.0-1.el10.x86_64
Module libbrotlicommon.so.1 from rpm brotli-1.1.0-3.el10.x86_64
Module libevent-2.1.so.7 from rpm libevent-2.1.12-13.el10.x86_64
Module libgmp.so.10 from rpm gmp-6.2.1-8.el10.x86_64
Module libkeyutils.so.1 from rpm keyutils-1.6.3-3.el10.x86_64
Module libkrb5support.so.0 from rpm krb5-1.21.2-5.el10.x86_64
Module libblkid.so.1 from rpm util-linux-2.40.1-4.el10.x86_64
Module libbrotlidec.so.1 from rpm brotli-1.1.0-3.el10.x86_64
Module libssl.so.3 from rpm openssl-3.2.2-1.el10.x86_64
Module libpsl.so.5 from rpm libpsl-0.21.5-3.el10.x86_64
Module libnghttp2.so.14 from rpm nghttp2-1.61.0-1.el10.x86_64
Module libcrypt.so.2 from rpm libxcrypt-4.4.36-5.el10.x86_64
Module libcrypto.so.3 from rpm openssl-3.2.2-1.el10.x86_64
Module libhogweed.so.6 from rpm nettle-3.9.1-8.el10.x86_64
Module libnettle.so.8 from rpm nettle-3.9.1-8.el10.x86_64
Module libtasn1.so.6 from rpm libtasn1-4.19.0-6.el10.x86_64
Module libunistring.so.5 from rpm libunistring-1.1-7.el10.x86_64
Module libidn2.so.0 from rpm libidn2-2.3.7-1.el10.x86_64
Module libp11-kit.so.0 from rpm p11-kit-0.25.3-8.el10.x86_64
Module libattr.so.1 from rpm attr-2.5.2-3.el10.x86_64
Module liblzma.so.5 from rpm xz-5.4.6-1.el10.x86_64
Module libcom_err.so.2 from rpm e2fsprogs-1.47.0-5.el10.x86_64
Module libk5crypto.so.3 from rpm krb5-1.21.2-5.el10.x86_64
Module libkrb5.so.3 from rpm krb5-1.21.2-5.el10.x86_64
Module libgssapi_krb5.so.2 from rpm krb5-1.21.2-5.el10.x86_64
Module libmount.so.1 from rpm util-linux-2.40.1-4.el10.x86_64
Module libz.so.1 from rpm zlib-ng-2.1.6-2.el10.x86_64
Module libgmodule-2.0.so.0 from rpm glib2-2.80.2-1.el10.x86_64
Module libffi.so.8 from rpm libffi-3.4.4-7.el10.x86_64
Module libpcre2-8.so.0 from rpm pcre2-10.42-3.el10.1.x86_64
Module libcurl.so.4 from rpm curl-8.6.0-6.el10.x86_64
Module libsasl2.so.3 from rpm cyrus-sasl-2.1.28-19.el10.x86_64
Module libssh.so.4 from rpm libssh-0.10.6-6.el10.x86_64
Module libyajl.so.2 from rpm yajl-2.1.0-23.el10.x86_64
Module libselinux.so.1 from rpm libselinux-3.6-4.el10.x86_64
Module libnuma.so.1 from rpm numactl-2.0.16-5.el10.x86_64
Module libnl-3.so.200 from rpm libnl3-3.9.0-3.el10.x86_64
Module libgnutls.so.30 from rpm gnutls-3.8.5-4.el10.x86_64
Module libcap-ng.so.0 from rpm libcap-ng-0.8.4-4.el10.x86_64
Module libaudit.so.1 from rpm audit-4.0-8.el10.x86_64
Module libacl.so.1 from rpm acl-2.3.2-1.el10.x86_64
Module libxml2.so.2 from rpm libxml2-2.12.5-1.el10.x86_64
Module libtirpc.so.3 from rpm libtirpc-1.3.4-1.rc2.el10.2.x86_64
Module libgio-2.0.so.0 from rpm glib2-2.80.2-1.el10.x86_64
Module libgobject-2.0.so.0 from rpm glib2-2.80.2-1.el10.x86_64
Module libglib-2.0.so.0 from rpm glib2-2.80.2-1.el10.x86_64
Module libvirt-qemu.so.0 from rpm libvirt-10.4.0-1.el10.x86_64
Module libvirt-lxc.so.0 from rpm libvirt-10.4.0-1.el10.x86_64
Module libvirt.so.0 from rpm libvirt-10.4.0-1.el10.x86_64
Module virtinterfaced from rpm libvirt-10.4.0-1.el10.x86_64
Stack trace of thread 7467:
#0  0x00007ff2f683325c __pthread_kill_implementation (libc.so.6 + 0x9525c)
#1  0x00007ff2f67de3b6 raise (libc.so.6 + 0x403b6)
#2  0x00007ff2f67c68fa abort (libc.so.6 + 0x288fa)
#3  0x00007ff2f67c7956 __libc_message_impl.cold (libc.so.6 + 0x29956)
#4  0x00007ff2f683d017 malloc_printerr (libc.so.6 + 0x9f017)
#5  0x00007ff2f68405d4 _int_malloc (libc.so.6 + 0xa25d4)
#6  0x00007ff2f6842518 __libc_calloc (libc.so.6 + 0xa4518)
#7  0x00007ff2f71178aa g_malloc0 (libglib-2.0.so.0 + 0x628aa)
#8  0x00007ff2f6dfee05 virNetServerProgramDispatch (libvirt.so.0 + 0x1fee05)
#9  0x00007ff2f6dff3a2 virNetServerProcessMsg (libvirt.so.0 + 0x1ff3a2)
#10 0x00007ff2f6dff4b1 virNetServerHandleJob (libvirt.so.0 + 0x1ff4b1)
#11 0x00007ff2f6d3a565 virThreadPoolWorker (libvirt.so.0 + 0x13a565)
#12 0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#13 0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#14 0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7465:
#0  0x00007ff2f682de7e __futex_abstimed_wait_common (libc.so.6 + 0x8fe7e)
#1  0x00007ff2f6830740 pthread_cond_wait@@GLIBC_2.3.2 (libc.so.6 + 0x92740)
#2  0x00007ff2f6d3233a virCondWait (libvirt.so.0 + 0x13233a)
#3  0x00007ff2f6d3a4e3 virThreadPoolWorker (libvirt.so.0 + 0x13a4e3)
#4  0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7489:
#0  0x00007ff2f689237f __poll (libc.so.6 + 0xf437f)
#1  0x00007ff2f716f046 g_main_context_iterate_unlocked.isra.0 (libglib-2.0.so.0 + 0xba046)
#2  0x00007ff2f7111650 g_main_context_iteration (libglib-2.0.so.0 + 0x5c650)
#3  0x00007ff2f71116a1 glib_worker_main (libglib-2.0.so.0 + 0x5c6a1)
#4  0x00007ff2f713edf2 g_thread_proxy (libglib-2.0.so.0 + 0x89df2)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7468:
#0  0x00007ff2f682de7e __futex_abstimed_wait_common (libc.so.6 + 0x8fe7e)
#1  0x00007ff2f6830740 pthread_cond_wait@@GLIBC_2.3.2 (libc.so.6 + 0x92740)
#2  0x00007ff2f6d3233a virCondWait (libvirt.so.0 + 0x13233a)
#3  0x00007ff2f6d3a4e3 virThreadPoolWorker (libvirt.so.0 + 0x13a4e3)
#4  0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7487:
#0  0x00007ff2f689cf0d syscall (libc.so.6 + 0xfef0d)
#1  0x00007ff2f716c264 g_cond_wait (libglib-2.0.so.0 + 0xb7264)
#2  0x00007ff2f70dc0db g_async_queue_pop_intern_unlocked (libglib-2.0.so.0 + 0x270db)
#3  0x00007ff2f713ff92 g_thread_pool_spawn_thread (libglib-2.0.so.0 + 0x8af92)
#4  0x00007ff2f713edf2 g_thread_proxy (libglib-2.0.so.0 + 0x89df2)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7474:
#0  0x00007ff2f682de7e __futex_abstimed_wait_common (libc.so.6 + 0x8fe7e)
#1  0x00007ff2f6830740 pthread_cond_wait@@GLIBC_2.3.2 (libc.so.6 + 0x92740)
#2  0x00007ff2f6d3233a virCondWait (libvirt.so.0 + 0x13233a)
#3  0x00007ff2f6d3a5db virThreadPoolWorker (libvirt.so.0 + 0x13a5db)
#4  0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7475:
#0  0x00007ff2f682de7e __futex_abstimed_wait_common (libc.so.6 + 0x8fe7e)
#1  0x00007ff2f6830740 pthread_cond_wait@@GLIBC_2.3.2 (libc.so.6 + 0x92740)
#2  0x00007ff2f6d3233a virCondWait (libvirt.so.0 + 0x13233a)
#3  0x00007ff2f6d3a5db virThreadPoolWorker (libvirt.so.0 + 0x13a5db)
#4  0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7472:
#0  0x00007ff2f682de7e __futex_abstimed_wait_common (libc.so.6 + 0x8fe7e)
#1  0x00007ff2f6830740 pthread_cond_wait@@GLIBC_2.3.2 (libc.so.6 + 0x92740)
#2  0x00007ff2f6d3233a virCondWait (libvirt.so.0 + 0x13233a)
#3  0x00007ff2f6d3a5db virThreadPoolWorker (libvirt.so.0 + 0x13a5db)
#4  0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7484:
#0  0x00007ff2f682de7e __futex_abstimed_wait_common (libc.so.6 + 0x8fe7e)
#1  0x00007ff2f6830740 pthread_cond_wait@@GLIBC_2.3.2 (libc.so.6 + 0x92740)
#2  0x00007ff2f6d3233a virCondWait (libvirt.so.0 + 0x13233a)
#3  0x00007ff2f6d3a4e3 virThreadPoolWorker (libvirt.so.0 + 0x13a4e3)
#4  0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7469:
#0  0x00007ff2f682de7e __futex_abstimed_wait_common (libc.so.6 + 0x8fe7e)
#1  0x00007ff2f6830740 pthread_cond_wait@@GLIBC_2.3.2 (libc.so.6 + 0x92740)
#2  0x00007ff2f6d3233a virCondWait (libvirt.so.0 + 0x13233a)
#3  0x00007ff2f6d3a4e3 virThreadPoolWorker (libvirt.so.0 + 0x13a4e3)
#4  0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7483:
#0  0x00007ff2f682de7e __futex_abstimed_wait_common (libc.so.6 + 0x8fe7e)
#1  0x00007ff2f6830740 pthread_cond_wait@@GLIBC_2.3.2 (libc.so.6 + 0x92740)
#2  0x00007ff2f6d3233a virCondWait (libvirt.so.0 + 0x13233a)
#3  0x00007ff2f6d3a4e3 virThreadPoolWorker (libvirt.so.0 + 0x13a4e3)
#4  0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7482:
#0  0x00007ff2f682de7e __futex_abstimed_wait_common (libc.so.6 + 0x8fe7e)
#1  0x00007ff2f6830740 pthread_cond_wait@@GLIBC_2.3.2 (libc.so.6 + 0x92740)
#2  0x00007ff2f6d3233a virCondWait (libvirt.so.0 + 0x13233a)
#3  0x00007ff2f6d3a4e3 virThreadPoolWorker (libvirt.so.0 + 0x13a4e3)
#4  0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7471:
#0  0x00007ff2f682de7e __futex_abstimed_wait_common (libc.so.6 + 0x8fe7e)
#1  0x00007ff2f6830740 pthread_cond_wait@@GLIBC_2.3.2 (libc.so.6 + 0x92740)
#2  0x00007ff2f6d3233a virCondWait (libvirt.so.0 + 0x13233a)
#3  0x00007ff2f6d3a5db virThreadPoolWorker (libvirt.so.0 + 0x13a5db)
#4  0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7407:
#0  0x00007ff2f689237f __poll (libc.so.6 + 0xf437f)
#1  0x00007ff2f716f046 g_main_context_iterate_unlocked.isra.0 (libglib-2.0.so.0 + 0xba046)
#2  0x00007ff2f7111650 g_main_context_iteration (libglib-2.0.so.0 + 0x5c650)
#3  0x00007ff2f6ce33a4 virEventGLibRunOnce (libvirt.so.0 + 0xe33a4)
#4  0x00007ff2f6e00ef2 virNetDaemonRun (libvirt.so.0 + 0x200ef2)
#5  0x0000556cdd0b0ba2 main (virtinterfaced + 0x33ba2)
#6  0x00007ff2f67c82ee __libc_start_call_main (libc.so.6 + 0x2a2ee)
#7  0x00007ff2f67c83a9 __libc_start_main@@GLIBC_2.34 (libc.so.6 + 0x2a3a9)
#8  0x0000556cdd0b1465 _start (virtinterfaced + 0x34465)
Stack trace of thread 7481:
#0  0x00007ff2f682de7e __futex_abstimed_wait_common (libc.so.6 + 0x8fe7e)
#1  0x00007ff2f6830740 pthread_cond_wait@@GLIBC_2.3.2 (libc.so.6 + 0x92740)
#2  0x00007ff2f6d3233a virCondWait (libvirt.so.0 + 0x13233a)
#3  0x00007ff2f6d3a4e3 virThreadPoolWorker (libvirt.so.0 + 0x13a4e3)
#4  0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7470:
#0  0x00007ff2f6892e2e statx (libc.so.6 + 0xf4e2e)
#1  0x00007ff2f5270c2d statx_fallback (libudev.so.1 + 0x19c2d)
#2  0x00007ff2f5278617 path_is_root_at (libudev.so.1 + 0x21617)
#3  0x00007ff2f527901d chaseat (libudev.so.1 + 0x2201d)
#4  0x00007ff2f527cbfa chase.constprop.0 (libudev.so.1 + 0x25bfa)
#5  0x00007ff2f5267fb8 device_set_syspath (libudev.so.1 + 0x10fb8)
#6  0x00007ff2f527e6e8 device_new_from_syspath.constprop.0 (libudev.so.1 + 0x276e8)
#7  0x00007ff2f5260ff4 enumerator_scan_dir_and_add_devices (libudev.so.1 + 0x9ff4)
#8  0x00007ff2f52613df enumerator_scan_dir (libudev.so.1 + 0xa3df)
#9  0x00007ff2f526757d udev_enumerate_scan_devices (libudev.so.1 + 0x1057d)
#10 0x00007ff2f5293529 udevInterfaceLookupByMACString (libvirt_driver_interface.so + 0x3529)
#11 0x00007ff2f6f1feae virInterfaceLookupByMACString (libvirt.so.0 + 0x31feae)
#12 0x0000556cdd0e7cb7 remoteDispatchInterfaceLookupByMACStringHelper.lto_priv.0 (virtinterfaced + 0x6acb7)
#13 0x00007ff2f6dfee6a virNetServerProgramDispatch (libvirt.so.0 + 0x1fee6a)
#14 0x00007ff2f6dff3a2 virNetServerProcessMsg (libvirt.so.0 + 0x1ff3a2)
#15 0x00007ff2f6dff4b1 virNetServerHandleJob (libvirt.so.0 + 0x1ff4b1)
#16 0x00007ff2f6d3a565 virThreadPoolWorker (libvirt.so.0 + 0x13a565)
#17 0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#18 0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#19 0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7479:
#0  0x00007ff2f682de7e __futex_abstimed_wait_common (libc.so.6 + 0x8fe7e)
#1  0x00007ff2f6830740 pthread_cond_wait@@GLIBC_2.3.2 (libc.so.6 + 0x92740)
#2  0x00007ff2f6d3233a virCondWait (libvirt.so.0 + 0x13233a)
#3  0x00007ff2f6d3a4e3 virThreadPoolWorker (libvirt.so.0 + 0x13a4e3)
#4  0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7490:
#0  0x00007ff2f689237f __poll (libc.so.6 + 0xf437f)
#1  0x00007ff2f716f046 g_main_context_iterate_unlocked.isra.0 (libglib-2.0.so.0 + 0xba046)
#2  0x00007ff2f711601f g_main_loop_run (libglib-2.0.so.0 + 0x6101f)
#3  0x00007ff2f6adb1aa gdbus_shared_thread_func.lto_priv.0 (libgio-2.0.so.0 + 0x10d1aa)
#4  0x00007ff2f713edf2 g_thread_proxy (libglib-2.0.so.0 + 0x89df2)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
Stack trace of thread 7473:
#0  0x00007ff2f682de7e __futex_abstimed_wait_common (libc.so.6 + 0x8fe7e)
#1  0x00007ff2f6830740 pthread_cond_wait@@GLIBC_2.3.2 (libc.so.6 + 0x92740)
#2  0x00007ff2f6d3233a virCondWait (libvirt.so.0 + 0x13233a)
#3  0x00007ff2f6d3a5db virThreadPoolWorker (libvirt.so.0 + 0x13a5db)
#4  0x00007ff2f6d39a68 virThreadHelper (libvirt.so.0 + 0x139a68)
#5  0x00007ff2f6831502 start_thread (libc.so.6 + 0x93502)
#6  0x00007ff2f689f0dc __clone3 (libc.so.6 + 0x1010dc)
ELF object binary architecture: AMD x86-64
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-centos-10-127.0.0.2-3001-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstallBiosTPM-centos-10-127.0.0.2-3001-FAIL.log.gz
Core dumps downloaded to /work/make-checkout-workdir/TestMachinesCreate-testConfigureBeforeInstallBiosTPM-centos-10-127.0.0.2-3001-FAIL.core
> warning: transport closed: disconnected
umount: /run/user/1000: no mount point specified.
CDP: {"source":"rendering","level":"info","text":"Autofocus processing was blocked because a document already has a focused element.","timestamp":1718946559250.71,"url":"http://127.0.0.2:9891/machines#/vm?name=subVmTest1&connection=system"}
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-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-create", line 2298, in testConfigureBeforeInstallBiosTPM
    b.wait_in_text(f"#vm-{vmName}-system-state", "Running")
  File "/work/make-checkout-workdir/test/common/testlib.py", line 690, 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 628, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 331, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_in_text("#vm-subVmTest1-system-state","Running")): Uncaught (in promise) Error: actual text: Shut off
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1718946497817.889, 'url': 'http://127.0.0.2:9891/cockpit/login', 'networkRequestId': '2382.12'}
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 404 (ERROR)', 'timestamp': 1718946499447.345, 'url': 'http://127.0.0.2:9891/cockpit/@localhost/*/po.js', 'networkRequestId': '2382.20'}
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 404 (ERROR)', 'timestamp': 1718946499448.613, 'url': 'http://127.0.0.2:9891/cockpit/@localhost/*/po.manifest.js', 'networkRequestId': '2382.19'}
cdp: {'source': 'security', 'level': 'error', 'text': "Refused to execute script from 'http://127.0.0.2:9891/cockpit/@localhost/*/po.manifest.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.", 'timestamp': 1718946499484.838, 'url': 'http://127.0.0.2:9891/machines'}
cdp: {'source': 'security', 'level': 'error', 'text': "Refused to execute script from 'http://127.0.0.2:9891/cockpit/@localhost/*/po.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.", 'timestamp': 1718946499484.955, 'url': 'http://127.0.0.2:9891/machines'}
error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
log: osinfo-detect command failed:  (process:7879): GLib-GIO-WARNING **: 01:08:41.113: 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)
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 404 (ERROR)', 'timestamp': 1718946527384.036, 'url': 'http://127.0.0.2:9891/cockpit/@localhost/*/po.js', 'networkRequestId': '2382.171'}
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 404 (ERROR)', 'timestamp': 1718946527385.657, 'url': 'http://127.0.0.2:9891/cockpit/@localhost/*/po.manifest.js', 'networkRequestId': '2382.170'}
cdp: {'source': 'security', 'level': 'error', 'text': "Refused to execute script from 'http://127.0.0.2:9891/cockpit/@localhost/*/po.manifest.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.", 'timestamp': 1718946527447.239, 'url': 'http://127.0.0.2:9891/machines#/vm?name=subVmTest1&connection=system'}
cdp: {'source': 'security', 'level': 'error', 'text': "Refused to execute script from 'http://127.0.0.2:9891/cockpit/@localhost/*/po.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.", 'timestamp': 1718946527447.357, 'url': 'http://127.0.0.2:9891/machines#/vm?name=subVmTest1&connection=system'}
error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
log: listInactiveInterfaces action for path /org/libvirt/QEMU/interface/52_3a54_3a01_3a00_3a00_3a01 Cannot recv data: Connection reset by peer
log: listInactiveInterfaces action for path /org/libvirt/QEMU/interface/52_3a54_3a00_3a12_3a34_3a56 Cannot recv data: Connection reset by peer
log: listInactiveInterfaces action for path /org/libvirt/QEMU/interface/00_3a00_3a00_3a00_3a00_3a00 Cannot recv data: Connection reset by peer
log: listInactiveInterfaces action for path /org/libvirt/QEMU/interface/52_3a54_3a00_3ad1_3a8e_3aa4 Cannot recv data: Connection reset by peer
error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not get process id of swtpm\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 285, 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 363, in <module>\n  File \"<string>\", line 296, in install_vm\n  File \"<string>\", line 285, 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."}

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

First occurrence: 2024-06-21T05:09:23.456875+00:00 | revision 424c5f62f5598be2619a303a1a236dc53c9ee856 Times recorded: 1 Latest occurrences:

2024-06-21T05:09:23.456875+00:00 | revision 424c5f62f5598be2619a303a1a236dc53c9ee856

# ----------------------------------------------------------------------
# testConfigureBeforeInstall (__main__.TestMachinesCreate.testConfigureBeforeInstall)
...+....+...+..+.........+.........+.......+...+..+..........+...........+.........+...+.+..+.............+......+.........+.....+.........+.......+......+.....+.........+..........+.....+.+........+......+...+...............+.+..+...+.+...+...........+.......+...+.....+......+....+.....+.......+........+....+...+...+............+.....+....+........+................+..+....+...+..+.+........+.+........+...+....+...+...........+.+...+..+...............+......+....+........+.+...............+......+...+...+..+.+.....+++++++++++++++++++++++++++++++++++++++++++++*........+..+...+...+...+.+.....+.+...+..+.......+++++++++++++++++++++++++++++++++++++++++++++*.............+.+..+.......+..........................+...............+...+.........+......+....+......+.....+...................+...................................+.+......+.....+......+....+...............+........+.+.....+......+......+...+............+.......+........+...+....+...+.........+...+..+......+...+..........+.....+....+.....+.+............+..+......+.........+.............+........................+...+.......................+....+........+.......+......+.....+...+....+...............+..+......+.............+.................+....+......+..+.......+...+..+.+..+.......+...........+............+............+.............+...............+......+..+....+...+...+...+...........+.+........+......+.........+.+...+.................+.+.................+.......+.........+.........+...............+...+..+.......+........+......+.+......+.....+...+...................+.....+............+....+.....+....+...+...+..+...+......+....+...............+++++
....+......+.....+....+.....+.............+...............+..+..................+++++++++++++++++++++++++++++++++++++++++++++*...+.........+...+...+..+++++++++++++++++++++++++++++++++++++++++++++*.......................+......+...................+......+.........+.........+.....+......+.......+...+..+...+.......+..+................+.....+....+..+..........+...+...........+.+...+...+..+.+............+..+.......+...........+....+.....+.+.........+......+......................................+.........+......+.+......+...+............+..+......+.......+.................................+......+...........+............+.+......+...+.................+...+......+.+...+.....+............+++++
-----
[0621/050812.183153:WARNING:sandbox_linux.cc(436)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9784/devtools/browser/8aef04a8-434b-4477-b9c8-aa312a02d756
CDP: {"source":"network","level":"error","text":"Failed to load resource: the server responded with a status of 404 (ERROR)","timestamp":1718946497823.13,"url":"http://127.0.0.2:9691/cockpit/@localhost/*/po.js","networkRequestId":"2231.20"}
CDP: {"source":"network","level":"error","text":"Failed to load resource: the server responded with a status of 404 (ERROR)","timestamp":1718946497833.843,"url":"http://127.0.0.2:9691/cockpit/@localhost/*/po.manifest.js","networkRequestId":"2231.19"}
CDP: {"source":"security","level":"error","text":"Refused to execute script from 'http://127.0.0.2:9691/cockpit/@localhost/*/po.manifest.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.","timestamp":1718946497869.358,"url":"http://127.0.0.2:9691/machines"}
CDP: {"source":"security","level":"error","text":"Refused to execute script from 'http://127.0.0.2:9691/cockpit/@localhost/*/po.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.","timestamp":1718946497869.516,"url":"http://127.0.0.2:9691/machines"}
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
> warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
> log: osinfo-detect command failed:  (process:7904): GLib-GIO-WARNING **: 01:08:35.513: 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'
CDP: {"source":"network","level":"error","text":"Failed to load resource: the server responded with a status of 404 (ERROR)","timestamp":1718946523994.663,"url":"http://127.0.0.2:9691/cockpit/@localhost/*/po.js","networkRequestId":"2231.171"}
CDP: {"source":"network","level":"error","text":"Failed to load resource: the server responded with a status of 404 (ERROR)","timestamp":1718946524019.011,"url":"http://127.0.0.2:9691/cockpit/@localhost/*/po.manifest.js","networkRequestId":"2231.170"}
CDP: {"source":"security","level":"error","text":"Refused to execute script from 'http://127.0.0.2:9691/cockpit/@localhost/*/po.manifest.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.","timestamp":1718946524037.628,"url":"http://127.0.0.2:9691/machines#/vm?name=VmNotInstalled&connection=system"}
CDP: {"source":"security","level":"error","text":"Refused to execute script from 'http://127.0.0.2:9691/cockpit/@localhost/*/po.js' because its MIME type ('text/html') is not executable, and strict MIME type checking is enabled.","timestamp":1718946524037.754,"url":"http://127.0.0.2:9691/machines#/vm?name=VmNotInstalled&connection=system"}
> error: Scrollbar test exception: TypeError: Cannot read properties of null (reading 'appendChild')
> warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
> warning: failed to poll tuned {"problem":"disconnected","name":"null","message":"Server has closed the connection.","toString":""}
> error: {"problem":null,"exit_status":1,"exit_signal":null,"message":"ERROR    internal error: Could not get process id of swtpm\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 285, 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 363, in <module>\n  File \"<string>\", line 296, in install_vm\n  File \"<string>\", line 285, 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-centos-10-127.0.0.2-2801-VmNotInstalled.xml
Wrote VmNotInstalled log to TestMachinesCreate-testConfigureBeforeInstall-centos-10-127.0.0.2-2801-VmNotInstalled.log
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2172, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2609, in wait
    raise Error(msg or "Condition did not become true.")
testlib.Error: Condition did not become true.

Wrote screenshot to TestMachinesCreate-testConfigureBeforeInstall-centos-10-127.0.0.2-2801-FAIL.png
Wrote HTML dump to TestMachinesCreate-testConfigureBeforeInstall-centos-10-127.0.0.2-2801-FAIL.html
Wrote JS log to TestMachinesCreate-testConfigureBeforeInstall-centos-10-127.0.0.2-2801-FAIL.js.log
Journal extracted to TestMachinesCreate-testConfigureBeforeInstall-centos-10-127.0.0.2-2801-FAIL.log.gz
> warning: transport closed: disconnected
Stopping 'virtstoraged.service', but its triggering units are still active:
virtstoraged-admin.socket, virtstoraged-ro.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.socket, virtqemud-admin.socket
Traceback (most recent call last):
  File "/work/make-checkout-workdir/test/check-machines-create", line 2172, in testConfigureBeforeInstall
    testlib.wait(lambda: "VmNotInstalled" in m.execute("virsh list --persistent"), delay=3)
  File "/work/make-checkout-workdir/test/common/testlib.py", line 2609, 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 [262s on a7682b5b5b93]
not ok 6 /work/make-checkout-workdir/test/check-machines-create TestMachinesCreate.testConfigureBeforeInstall [ND@5]

First occurrence: 2024-06-21T05:12:00.519808+00:00 | revision 424c5f62f5598be2619a303a1a236dc53c9ee856 Times recorded: 1 Latest occurrences:

2024-06-21T05:12:00.519808+00:00 | revision 424c5f62f5598be2619a303a1a236dc53c9ee856

May 22 '24 11:05 cockpituous

We don't regularly run centos-10 for c-machines, and the downstream bug is still open. Manual bump.

Jun 16 '24 07:06 martinpitt

See above, bump.

Jul 14 '24 05:07 martinpitt

bots bots copied to clipboard

libvirt is denied access to swtpm

bots
bots copied to clipboard