search

cockpit-project / bots

*beep* *boop* *beep*
GNU Lesser General Public License v2.1
25 stars 33 forks source link

TestSubscriptions.testSubAndInAndFail broken on subscription-manager-1.28 branch #6466

Closed martinpitt closed 1 week ago

martinpitt commented 3 months ago

This failure has existed for months, and we keep ignoring it in rhel-8-* refreshes. Let's naughty this, so that there's less guesswork and more automation again.

@ptoscano I can't file an upstream bug as https://github.com/candlepin/subscription-manager-cockpit/ doesn't have issues enabled.

This was fixed in the main branch in https://github.com/candlepin/subscription-manager-cockpit/pull/66 , but needs backporting.

cockpituous commented 3 months ago

rhel-8-9 Ooops, it happened again

# ----------------------------------------------------------------------
# testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail)
Waiting 5 seconds for https://10.111.112.100:8443/candlepin
Waiting for https://10.111.112.100:8443/candlepin
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[0705/230946.060311:WARNING:sandbox_linux.cc(430)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9786/devtools/browser/418d14d6-5a8b-413d-91b7-4035886a6269
> warning: failed to poll tuned 
> debug: Trying to get current organization...
> debug: Reading configuration file
> debug: Current organization: {}
> debug: loaded client config 
> debug: Other state: unknown
> debug: connection_options: 
> debug: Opening private bus interface at unix:abstract=/run/dbus-ikFKmOj25s,guid=ea6f5d440acea7c2b12a83ce66887d46
> debug: registering using username and password
> log: registration_options: 
> debug: Result of registration:  
> debug: stopping registration server
> debug: saving: server.hostname 
> debug: saving: server.port 
> debug: saving: server.prefix 
> debug: requesting update of subscription status
> debug: requesting update of syspurpose status
> debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
> log: 
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1720220988038.969, 'url': 'http://127.0.0.2:9391/cockpit/login', 'networkRequestId': '1455.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-ikFKmOj25s,guid=ea6f5d440acea7c2b12a83ce66887d46
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

Wrote screenshot to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2501-FAIL.png
Wrote HTML dump to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2501-FAIL.html
Wrote JS log to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2501-FAIL.js.log
Journal extracted to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2501-FAIL.log.gz
Journal extracted to TestSubscriptions-testSubAndInAndFail-services-127.0.0.2-2502-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1720220988038.969, 'url': 'http://127.0.0.2:9391/cockpit/login', 'networkRequestId': '1455.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-ikFKmOj25s,guid=ea6f5d440acea7c2b12a83ce66887d46
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

# Result testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail) failed
# 1 TEST FAILED [206s on 661ff2788af7]
not ok 5 integration-tests/check-subscriptions TestSubscriptions.testSubAndInAndFail $2

First occurrence: 2024-07-05T23:11:53.821005+00:00 | revision d07b2d07b642373d280cf9f4e9c1130679391ee4 Times recorded: 1 Latest occurrences:

# ----------------------------------------------------------------------
# testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail)
Waiting 5 seconds for https://10.111.112.100:8443/candlepin
Waiting for https://10.111.112.100:8443/candlepin
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[0713/230810.970882:WARNING:sandbox_linux.cc(430)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9834/devtools/browser/002ac5cb-575b-47d8-817b-e0ecda09dc84
> warning: failed to poll tuned 
> debug: Reading configuration file
> debug: Trying to get current organization...
> debug: loaded client config 
> debug: Current organization: {}
> debug: Other state: unknown
> debug: connection_options: 
> debug: Opening private bus interface at unix:abstract=/run/dbus-hdwBvmrOtV,guid=5f9b19f01536d233d291d486669308e3
> debug: registering using username and password
> log: registration_options: 
> debug: Result of registration:  
> debug: stopping registration server
> debug: saving: server.hostname 
> debug: saving: server.port 
> debug: saving: server.prefix 
> debug: requesting update of subscription status
> debug: requesting update of syspurpose status
> debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
> log: 
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1720912092939.08, 'url': 'http://127.0.0.2:9191/cockpit/login', 'networkRequestId': '1420.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Reading configuration file
debug: Trying to get current organization...
debug: loaded client config 
debug: Current organization: {}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-hdwBvmrOtV,guid=5f9b19f01536d233d291d486669308e3
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

Wrote screenshot to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2301-FAIL.png
Wrote HTML dump to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2301-FAIL.html
Wrote JS log to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2301-FAIL.js.log
Journal extracted to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2301-FAIL.log.gz
Journal extracted to TestSubscriptions-testSubAndInAndFail-services-127.0.0.2-2302-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1720912092939.08, 'url': 'http://127.0.0.2:9191/cockpit/login', 'networkRequestId': '1420.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Reading configuration file
debug: Trying to get current organization...
debug: loaded client config 
debug: Current organization: {}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-hdwBvmrOtV,guid=5f9b19f01536d233d291d486669308e3
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

# Result testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail) failed
# 1 TEST FAILED [306s on 0fdf8705234c]
not ok 5 integration-tests/check-subscriptions TestSubscriptions.testSubAndInAndFail $2

First occurrence: 2024-07-13T23:12:14.643647+00:00 | revision 413b33f0adc8a7457a06a6d6eac2193b31c5d515 Times recorded: 1 Latest occurrences:

# ----------------------------------------------------------------------
# testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail)
Waiting 5 seconds for https://10.111.112.100:8443/candlepin
Waiting for https://10.111.112.100:8443/candlepin
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[0721/230629.433899:WARNING:sandbox_linux.cc(430)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9650/devtools/browser/ad894dcd-38b6-4a1d-be34-125c8a299e60
> warning: failed to poll tuned 
> debug: Reading configuration file
> debug: Trying to get current organization...
> debug: loaded client config 
> debug: Current organization: {}
> debug: Other state: unknown
> debug: connection_options: 
> debug: Opening private bus interface at unix:abstract=/run/dbus-VfChw2ABnM,guid=bd0c87d210ed8f60620ef675669d947d
> debug: registering using username and password
> log: registration_options: 
> debug: Result of registration:  
> debug: stopping registration server
> debug: saving: server.hostname 
> debug: saving: server.port 
> debug: saving: server.prefix 
> debug: requesting update of subscription status
> debug: requesting update of syspurpose status
> debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
> log: 
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1721603190741.677, 'url': 'http://127.0.0.2:9391/cockpit/login', 'networkRequestId': '1375.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Reading configuration file
debug: Trying to get current organization...
debug: loaded client config 
debug: Current organization: {}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-VfChw2ABnM,guid=bd0c87d210ed8f60620ef675669d947d
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

Wrote screenshot to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2501-FAIL.png
Wrote HTML dump to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2501-FAIL.html
Wrote JS log to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2501-FAIL.js.log
Journal extracted to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2501-FAIL.log.gz
Journal extracted to TestSubscriptions-testSubAndInAndFail-services-127.0.0.2-2502-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1721603190741.677, 'url': 'http://127.0.0.2:9391/cockpit/login', 'networkRequestId': '1375.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Reading configuration file
debug: Trying to get current organization...
debug: loaded client config 
debug: Current organization: {}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-VfChw2ABnM,guid=bd0c87d210ed8f60620ef675669d947d
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

# Result testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail) failed
# 1 TEST FAILED [303s on e1a156d64a12]
not ok 5 integration-tests/check-subscriptions TestSubscriptions.testSubAndInAndFail $2

First occurrence: 2024-07-21T23:10:30.743563+00:00 | revision 5385f4b51e4cdf25aee884fb024c92996c068cc9 Times recorded: 1 Latest occurrences:

# ----------------------------------------------------------------------
# testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail)
Waiting 5 seconds for https://10.111.112.100:8443/candlepin
Waiting for https://10.111.112.100:8443/candlepin
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[0729/231004.311369:WARNING:sandbox_linux.cc(430)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:10107/devtools/browser/6e220bd8-bc66-4a2a-b56b-d29f9519ca0d
> warning: failed to poll tuned 
> debug: Reading configuration file
> debug: Trying to get current organization...
> debug: loaded client config 
> debug: Current organization: {}
> debug: Other state: unknown
> debug: connection_options: 
> debug: Opening private bus interface at unix:abstract=/run/dbus-w2lgwpud2s,guid=0f3ec76f2edfc80f1252e82966a82155
> debug: registering using username and password
> log: registration_options: 
> debug: Result of registration:  
> debug: stopping registration server
> debug: saving: server.hostname 
> debug: saving: server.port 
> debug: saving: server.prefix 
> debug: requesting update of subscription status
> debug: requesting update of syspurpose status
> debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
> log: 
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1722294607059.4, 'url': 'http://127.0.0.2:9091/cockpit/login', 'networkRequestId': '1459.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Reading configuration file
debug: Trying to get current organization...
debug: loaded client config 
debug: Current organization: {}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-w2lgwpud2s,guid=0f3ec76f2edfc80f1252e82966a82155
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

Wrote screenshot to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2201-FAIL.png
Wrote HTML dump to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2201-FAIL.html
Wrote JS log to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2201-FAIL.js.log
Journal extracted to TestSubscriptions-testSubAndInAndFail-rhel-8-9-127.0.0.2-2201-FAIL.log.gz
Journal extracted to TestSubscriptions-testSubAndInAndFail-services-127.0.0.2-2202-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1722294607059.4, 'url': 'http://127.0.0.2:9091/cockpit/login', 'networkRequestId': '1459.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Reading configuration file
debug: Trying to get current organization...
debug: loaded client config 
debug: Current organization: {}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-w2lgwpud2s,guid=0f3ec76f2edfc80f1252e82966a82155
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

# Result testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail) failed
# 1 TEST FAILED [307s on a2eeca6eb5d4]
not ok 5 integration-tests/check-subscriptions TestSubscriptions.testSubAndInAndFail $2

First occurrence: 2024-07-29T23:14:07.581222+00:00 | revision 170ddb2d698282a997e5dce29dcf243510a6b2d6 Times recorded: 1 Latest occurrences:

cockpituous commented 3 months ago

rhel-8-4 Ooops, it happened again

# ----------------------------------------------------------------------
# testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail)
Waiting 5 seconds for https://10.111.112.100:8443/candlepin
Waiting for https://10.111.112.100:8443/candlepin
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:847: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:847: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[0629/230343.239345:WARNING:sandbox_linux.cc(436)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9678/devtools/browser/217a06cf-6533-4f79-8872-9a75c164ea74
CDP: {"source":"security","level":"error","text":"Refused to apply inline style because it violates the following Content Security Policy directive: \"default-src 'self' http://127.0.0.2:9391\". Either the 'unsafe-inline' keyword, a hash ('sha256-BMIkowAqVEI88ibUJ1NtYB8ByZOuS4MTi73eGoFo2+Q='), or a nonce ('nonce-...') is required to enable inline execution. Note that hashes do not apply to event handlers, style attributes and javascript: navigations unless the 'unsafe-hashes' keyword is present. Note also that 'style-src' was not explicitly set, so 'default-src' is used as a fallback.\n","timestamp":1719702226298.014,"url":"http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":24,"stackTrace":{"callFrames":[{"functionName":"Ee","scriptId":"23","url":"http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":24,"columnNumber":35353},{"functionName":"$e","scriptId":"23","url":"http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":24,"columnNumber":44390},{"functionName":"append","scriptId":"23","url":"http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":24,"columnNumber":46071},{"functionName":"P","scriptId":"23","url":"http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":63,"columnNumber":66643},{"functionName":"","scriptId":"23","url":"http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":63,"columnNumber":75839},{"functionName":"n","scriptId":"23","url":"http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":0,"columnNumber":109},{"functionName":"","scriptId":"23","url":"http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":33,"columnNumber":18487},{"functionName":"n","scriptId":"23","url":"http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":0,"columnNumber":109},{"functionName":"","scriptId":"23","url":"http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":0,"columnNumber":901},{"functionName":"","scriptId":"23","url":"http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":0,"columnNumber":912}]}}
> warning: An accessible aria-label is required when using the progress component without a title.
> warning: grep: /sys/class/dmi/id/power/autosuspend_delay_ms: Input/output error

> warning: stat: cannot stat '/etc/insights-client/.last-upload.results': No such file or directory

> debug: Reading configuration file
> debug: Trying to get current organization...
> debug: loaded client config 
> debug: Current organization: {}
> debug: Other state: unknown
> debug: connection_options: 
> debug: Opening private bus interface at unix:abstract=/run/dbus-bqtgI4lAEg,guid=029567167a202e4a891ae5f6668092d6
> debug: registering using username and password
> log: registration_options: 
> debug: Result of registration:  
> debug: stopping registration server
> debug: saving: server.hostname 
> debug: saving: server.port 
> debug: saving: server.prefix 
> debug: requesting update of subscription status
> debug: requesting update of syspurpose status
> debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
> log: 
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1719702224715.862, 'url': 'http://127.0.0.2:9391/cockpit/login', 'networkRequestId': '1449.12'}
cdp: {'source': 'security', 'level': 'error', 'text': 'Refused to apply inline style because it violates the following Content Security Policy directive: "default-src \'self\' http://127.0.0.2:9391". Either the \'unsafe-inline\' keyword, a hash (\'sha256-BMIkowAqVEI88ibUJ1NtYB8ByZOuS4MTi73eGoFo2+Q=\'), or a nonce (\'nonce-...\') is required to enable inline execution. Note that hashes do not apply to event handlers, style attributes and javascript: navigations unless the \'unsafe-hashes\' keyword is present. Note also that \'style-src\' was not explicitly set, so \'default-src\' is used as a fallback.\n', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'stackTrace': {'callFrames': [{'functionName': 'Ee', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'columnNumber': 35353}, {'functionName': '$e', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'columnNumber': 44390}, {'functionName': 'append', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'columnNumber': 46071}, {'functionName': 'P', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 63, 'columnNumber': 66643}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 63, 'columnNumber': 75839}, {'functionName': 'n', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 109}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 33, 'columnNumber': 18487}, {'functionName': 'n', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 109}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 901}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 912}]}}
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: grep: /sys/class/dmi/id/power/autosuspend_delay_ms: Input/output error

warning: stat: cannot stat '/etc/insights-client/.last-upload.results': No such file or directory

debug: Reading configuration file
debug: Trying to get current organization...
debug: loaded client config 
debug: Current organization: {}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-bqtgI4lAEg,guid=029567167a202e4a891ae5f6668092d6
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
log: 
log: 
log: 
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 

Wrote screenshot to TestSubscriptions-testSubAndInAndFail-rhel-8-4-127.0.0.2-2501-FAIL.png
Wrote HTML dump to TestSubscriptions-testSubAndInAndFail-rhel-8-4-127.0.0.2-2501-FAIL.html
Wrote JS log to TestSubscriptions-testSubAndInAndFail-rhel-8-4-127.0.0.2-2501-FAIL.js.log
Journal extracted to TestSubscriptions-testSubAndInAndFail-rhel-8-4-127.0.0.2-2501-FAIL.log.gz
Journal extracted to TestSubscriptions-testSubAndInAndFail-services-127.0.0.2-2502-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1719702224715.862, 'url': 'http://127.0.0.2:9391/cockpit/login', 'networkRequestId': '1449.12'}
cdp: {'source': 'security', 'level': 'error', 'text': 'Refused to apply inline style because it violates the following Content Security Policy directive: "default-src \'self\' http://127.0.0.2:9391". Either the \'unsafe-inline\' keyword, a hash (\'sha256-BMIkowAqVEI88ibUJ1NtYB8ByZOuS4MTi73eGoFo2+Q=\'), or a nonce (\'nonce-...\') is required to enable inline execution. Note that hashes do not apply to event handlers, style attributes and javascript: navigations unless the \'unsafe-hashes\' keyword is present. Note also that \'style-src\' was not explicitly set, so \'default-src\' is used as a fallback.\n', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'stackTrace': {'callFrames': [{'functionName': 'Ee', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'columnNumber': 35353}, {'functionName': '$e', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'columnNumber': 44390}, {'functionName': 'append', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'columnNumber': 46071}, {'functionName': 'P', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 63, 'columnNumber': 66643}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 63, 'columnNumber': 75839}, {'functionName': 'n', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 109}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 33, 'columnNumber': 18487}, {'functionName': 'n', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 109}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 901}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9391/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 912}]}}
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: grep: /sys/class/dmi/id/power/autosuspend_delay_ms: Input/output error

warning: stat: cannot stat '/etc/insights-client/.last-upload.results': No such file or directory

debug: Reading configuration file
debug: Trying to get current organization...
debug: loaded client config 
debug: Current organization: {}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-bqtgI4lAEg,guid=029567167a202e4a891ae5f6668092d6
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
log: 
log: 
log: 
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 

# Result testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail) failed
# 1 TEST FAILED [171s on ee38391349cc]
not ok 5 integration-tests/check-subscriptions TestSubscriptions.testSubAndInAndFail $2

First occurrence: 2024-06-29T23:05:32.926652+00:00 | revision b10cccf9671d4f6cffad2977d4786f2ed0e84335 Times recorded: 1 Latest occurrences:

# ----------------------------------------------------------------------
# testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail)
Waiting 5 seconds for https://10.111.112.100:8443/candlepin
Waiting for https://10.111.112.100:8443/candlepin
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:847: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:847: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[0730/230548.126355:WARNING:sandbox_linux.cc(430)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9530/devtools/browser/c9f6fe53-589d-4021-b77e-5ccd9ae8ab47
CDP: {"source":"security","level":"error","text":"Refused to apply inline style because it violates the following Content Security Policy directive: \"default-src 'self' http://127.0.0.2:9091\". Either the 'unsafe-inline' keyword, a hash ('sha256-BMIkowAqVEI88ibUJ1NtYB8ByZOuS4MTi73eGoFo2+Q='), or a nonce ('nonce-...') is required to enable inline execution. Note that hashes do not apply to event handlers, style attributes and javascript: navigations unless the 'unsafe-hashes' keyword is present. Note also that 'style-src' was not explicitly set, so 'default-src' is used as a fallback.\n","timestamp":1722380751158.798,"url":"http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":24,"stackTrace":{"callFrames":[{"functionName":"Ee","scriptId":"23","url":"http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":24,"columnNumber":35353},{"functionName":"$e","scriptId":"23","url":"http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":24,"columnNumber":44390},{"functionName":"append","scriptId":"23","url":"http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":24,"columnNumber":46071},{"functionName":"P","scriptId":"23","url":"http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":63,"columnNumber":66643},{"functionName":"","scriptId":"23","url":"http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":63,"columnNumber":75839},{"functionName":"n","scriptId":"23","url":"http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":0,"columnNumber":109},{"functionName":"","scriptId":"23","url":"http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":33,"columnNumber":18487},{"functionName":"n","scriptId":"23","url":"http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":0,"columnNumber":109},{"functionName":"","scriptId":"23","url":"http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":0,"columnNumber":901},{"functionName":"","scriptId":"23","url":"http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js","lineNumber":0,"columnNumber":912}]}}
> warning: An accessible aria-label is required when using the progress component without a title.
> warning: grep: /sys/class/dmi/id/power/autosuspend_delay_ms: Input/output error

> warning: stat: cannot stat '/etc/insights-client/.last-upload.results': No such file or directory

> debug: Reading configuration file
> debug: Trying to get current organization...
> debug: loaded client config 
> debug: Current organization: {}
> debug: Other state: unknown
> debug: connection_options: 
> debug: Opening private bus interface at unix:abstract=/run/dbus-3KDDGEEbXy,guid=4c93ae1ad7133dff435a6ad766a971d0
> debug: registering using username and password
> log: registration_options: 
> debug: Result of registration:  
> debug: stopping registration server
> debug: saving: server.hostname 
> debug: saving: server.port 
> debug: saving: server.prefix 
> debug: requesting update of subscription status
> debug: requesting update of syspurpose status
> debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
> log: 
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1722380749623.277, 'url': 'http://127.0.0.2:9091/cockpit/login', 'networkRequestId': '1460.12'}
cdp: {'source': 'security', 'level': 'error', 'text': 'Refused to apply inline style because it violates the following Content Security Policy directive: "default-src \'self\' http://127.0.0.2:9091". Either the \'unsafe-inline\' keyword, a hash (\'sha256-BMIkowAqVEI88ibUJ1NtYB8ByZOuS4MTi73eGoFo2+Q=\'), or a nonce (\'nonce-...\') is required to enable inline execution. Note that hashes do not apply to event handlers, style attributes and javascript: navigations unless the \'unsafe-hashes\' keyword is present. Note also that \'style-src\' was not explicitly set, so \'default-src\' is used as a fallback.\n', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'stackTrace': {'callFrames': [{'functionName': 'Ee', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'columnNumber': 35353}, {'functionName': '$e', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'columnNumber': 44390}, {'functionName': 'append', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'columnNumber': 46071}, {'functionName': 'P', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 63, 'columnNumber': 66643}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 63, 'columnNumber': 75839}, {'functionName': 'n', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 109}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 33, 'columnNumber': 18487}, {'functionName': 'n', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 109}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 901}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 912}]}}
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: grep: /sys/class/dmi/id/power/autosuspend_delay_ms: Input/output error

warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: stat: cannot stat '/etc/insights-client/.last-upload.results': No such file or directory

debug: Reading configuration file
debug: Trying to get current organization...
debug: loaded client config 
debug: Current organization: {}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-3KDDGEEbXy,guid=4c93ae1ad7133dff435a6ad766a971d0
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

Wrote screenshot to TestSubscriptions-testSubAndInAndFail-rhel-8-4-127.0.0.2-2201-FAIL.png
Wrote HTML dump to TestSubscriptions-testSubAndInAndFail-rhel-8-4-127.0.0.2-2201-FAIL.html
Wrote JS log to TestSubscriptions-testSubAndInAndFail-rhel-8-4-127.0.0.2-2201-FAIL.js.log
Journal extracted to TestSubscriptions-testSubAndInAndFail-rhel-8-4-127.0.0.2-2201-FAIL.log.gz
Journal extracted to TestSubscriptions-testSubAndInAndFail-services-127.0.0.2-2202-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1722380749623.277, 'url': 'http://127.0.0.2:9091/cockpit/login', 'networkRequestId': '1460.12'}
cdp: {'source': 'security', 'level': 'error', 'text': 'Refused to apply inline style because it violates the following Content Security Policy directive: "default-src \'self\' http://127.0.0.2:9091". Either the \'unsafe-inline\' keyword, a hash (\'sha256-BMIkowAqVEI88ibUJ1NtYB8ByZOuS4MTi73eGoFo2+Q=\'), or a nonce (\'nonce-...\') is required to enable inline execution. Note that hashes do not apply to event handlers, style attributes and javascript: navigations unless the \'unsafe-hashes\' keyword is present. Note also that \'style-src\' was not explicitly set, so \'default-src\' is used as a fallback.\n', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'stackTrace': {'callFrames': [{'functionName': 'Ee', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'columnNumber': 35353}, {'functionName': '$e', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'columnNumber': 44390}, {'functionName': 'append', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 24, 'columnNumber': 46071}, {'functionName': 'P', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 63, 'columnNumber': 66643}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 63, 'columnNumber': 75839}, {'functionName': 'n', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 109}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 33, 'columnNumber': 18487}, {'functionName': 'n', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 109}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 901}, {'functionName': '', 'scriptId': '23', 'url': 'http://127.0.0.2:9091/cockpit/$d155fa2bd2c636ea162f65711e24fde4e167bebf377de3f0e3c9bef2b0d255f0/shell/index.js', 'lineNumber': 0, 'columnNumber': 912}]}}
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: grep: /sys/class/dmi/id/power/autosuspend_delay_ms: Input/output error

warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: An accessible aria-label is required when using the progress component without a title.
warning: stat: cannot stat '/etc/insights-client/.last-upload.results': No such file or directory

debug: Reading configuration file
debug: Trying to get current organization...
debug: loaded client config 
debug: Current organization: {}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-3KDDGEEbXy,guid=4c93ae1ad7133dff435a6ad766a971d0
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

# Result testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail) failed
# 1 TEST FAILED [168s on cd6d99cf3f9c]
not ok 5 integration-tests/check-subscriptions TestSubscriptions.testSubAndInAndFail $2

First occurrence: 2024-07-30T23:07:35.061935+00:00 | revision c99b0355391a0ceec678500369999edd04da3265 Times recorded: 1 Latest occurrences:

cockpituous commented 3 months ago

rhel-8-8 Ooops, it happened again

# ----------------------------------------------------------------------
# testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail)
Waiting 5 seconds for https://10.111.112.100:8443/candlepin
Waiting for https://10.111.112.100:8443/candlepin
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[0720/230508.842183:WARNING:sandbox_linux.cc(430)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9823/devtools/browser/4b43e2f9-0729-492b-b7d9-d4817046fda9
> warning: failed to poll tuned 
> debug: Trying to get current organization...
> debug: Reading configuration file
> debug: Current organization: {}
> debug: loaded client config 
> debug: Other state: unknown
> debug: connection_options: 
> debug: Opening private bus interface at unix:abstract=/run/dbus-sP79Z099Ug,guid=1535f013b17e7a9717510fff669c42ac
> debug: registering using username and password
> log: registration_options: 
> debug: Result of registration:  
> debug: stopping registration server
> debug: saving: server.hostname 
> debug: saving: server.port 
> debug: saving: server.prefix 
> debug: requesting update of subscription status
> debug: requesting update of syspurpose status
> debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
> log: 
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1721516711216.103, 'url': 'http://127.0.0.2:9091/cockpit/login', 'networkRequestId': '1436.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-sP79Z099Ug,guid=1535f013b17e7a9717510fff669c42ac
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
log: 
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
log: 
log: 
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 

Wrote screenshot to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.png
Wrote HTML dump to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.html
Wrote JS log to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.js.log
Journal extracted to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.log.gz
Journal extracted to TestSubscriptions-testSubAndInAndFail-services-127.0.0.2-2202-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1721516711216.103, 'url': 'http://127.0.0.2:9091/cockpit/login', 'networkRequestId': '1436.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-sP79Z099Ug,guid=1535f013b17e7a9717510fff669c42ac
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
log: 
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
log: 
log: 
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 

# Result testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail) failed
# 1 TEST FAILED [176s on 60093a63b17d]
not ok 5 integration-tests/check-subscriptions TestSubscriptions.testSubAndInAndFail $2

First occurrence: 2024-07-20T23:07:03.631036+00:00 | revision a5246c842f4eceb88730bb5195677969eb628c80 Times recorded: 1 Latest occurrences:

# ----------------------------------------------------------------------
# testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail)
Waiting 5 seconds for https://10.111.112.100:8443/candlepin
Waiting for https://10.111.112.100:8443/candlepin
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[0722/065758.737319:WARNING:sandbox_linux.cc(430)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9530/devtools/browser/85390e72-b197-4899-96a7-7739eeb4ec1b
> warning: failed to poll tuned 
> debug: Trying to get current organization...
> debug: Reading configuration file
> debug: Current organization: {}
> debug: loaded client config 
> debug: Other state: unknown
> debug: connection_options: 
> debug: Opening private bus interface at unix:abstract=/run/dbus-3yfplcxGwb,guid=bb8fbe73a7f0ac0b90b82982669e02fe
> debug: registering using username and password
> log: registration_options: 
> debug: Result of registration:  
> debug: stopping registration server
> debug: saving: server.hostname 
> debug: saving: server.port 
> debug: saving: server.prefix 
> debug: requesting update of subscription status
> debug: requesting update of syspurpose status
> debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
> log: 
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1721631480045.001, 'url': 'http://127.0.0.2:9091/cockpit/login', 'networkRequestId': '1498.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-3yfplcxGwb,guid=bb8fbe73a7f0ac0b90b82982669e02fe
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
log: 
log: 
log: 
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 

Wrote screenshot to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.png
Wrote HTML dump to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.html
Wrote JS log to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.js.log
Journal extracted to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.log.gz
Journal extracted to TestSubscriptions-testSubAndInAndFail-services-127.0.0.2-2202-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1721631480045.001, 'url': 'http://127.0.0.2:9091/cockpit/login', 'networkRequestId': '1498.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-3yfplcxGwb,guid=bb8fbe73a7f0ac0b90b82982669e02fe
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
log: 
log: 
log: 
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 

# Result testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail) failed
# 1 TEST FAILED [191s on 88efea3ce855]
not ok 5 integration-tests/check-subscriptions TestSubscriptions.testSubAndInAndFail $2

First occurrence: 2024-07-22T07:00:01.926599+00:00 | revision b93cec064d6e94a0db0156af4606b5d5ee911a02 Times recorded: 1 Latest occurrences:

# ----------------------------------------------------------------------
# testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail)
Waiting 5 seconds for https://10.111.112.100:8443/candlepin
Waiting for https://10.111.112.100:8443/candlepin
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[0722/093725.830000:WARNING:sandbox_linux.cc(430)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9906/devtools/browser/6102b6a5-ff8c-43a3-b9a3-f48949ceae06
> warning: failed to poll tuned 
> debug: Trying to get current organization...
> debug: Reading configuration file
> debug: Current organization: {}
> debug: loaded client config 
> debug: Other state: unknown
> debug: connection_options: 
> debug: Opening private bus interface at unix:abstract=/run/dbus-WvlfA8D9fL,guid=1c3612cc3e6558c23074519e669e285e
> debug: registering using username and password
> log: registration_options: 
> debug: Result of registration:  
> debug: stopping registration server
> debug: saving: server.hostname 
> debug: saving: server.port 
> debug: saving: server.prefix 
> debug: requesting update of subscription status
> debug: requesting update of syspurpose status
> debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
> log: 
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1721641048748.477, 'url': 'http://127.0.0.2:9091/cockpit/login', 'networkRequestId': '1451.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-WvlfA8D9fL,guid=1c3612cc3e6558c23074519e669e285e
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
log: 
log: 
log: 
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 

Wrote screenshot to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.png
Wrote HTML dump to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.html
Wrote JS log to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.js.log
Journal extracted to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.log.gz
Journal extracted to TestSubscriptions-testSubAndInAndFail-services-127.0.0.2-2202-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1721641048748.477, 'url': 'http://127.0.0.2:9091/cockpit/login', 'networkRequestId': '1451.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-WvlfA8D9fL,guid=1c3612cc3e6558c23074519e669e285e
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
log: 
log: 
log: 
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 

# Result testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail) failed
# 1 TEST FAILED [185s on d1c456556504]
not ok 5 integration-tests/check-subscriptions TestSubscriptions.testSubAndInAndFail $2

First occurrence: 2024-07-22T09:39:26.015015+00:00 | revision e8f2245e9a295823e8c7b46ba1e898567e3d362e Times recorded: 1 Latest occurrences:

# ----------------------------------------------------------------------
# testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail)
Waiting 5 seconds for https://10.111.112.100:8443/candlepin
Waiting for https://10.111.112.100:8443/candlepin
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:852: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[0722/094048.775879:WARNING:sandbox_linux.cc(430)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9791/devtools/browser/e74c3cba-ef6e-4e44-945f-2504f5f2ee02
> warning: failed to poll tuned 
> debug: Trying to get current organization...
> debug: Reading configuration file
> debug: Current organization: {}
> debug: loaded client config 
> debug: Other state: unknown
> debug: connection_options: 
> debug: Opening private bus interface at unix:abstract=/run/dbus-vRQNAIvuXK,guid=db04b3a20ad4fb0334737875669e2928
> debug: registering using username and password
> log: registration_options: 
> debug: Result of registration:  
> debug: stopping registration server
> debug: saving: server.hostname 
> debug: saving: server.port 
> debug: saving: server.prefix 
> debug: requesting update of subscription status
> debug: requesting update of syspurpose status
> debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
> log: 
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1721641250568.043, 'url': 'http://127.0.0.2:9091/cockpit/login', 'networkRequestId': '1569.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-vRQNAIvuXK,guid=db04b3a20ad4fb0334737875669e2928
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
log: 
debug: loaded client config 
debug: loaded client config 
log: 
debug: loaded client config 
log: 

Wrote screenshot to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.png
Wrote HTML dump to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.html
Wrote JS log to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.js.log
Journal extracted to TestSubscriptions-testSubAndInAndFail-rhel-8-8-127.0.0.2-2201-FAIL.log.gz
Journal extracted to TestSubscriptions-testSubAndInAndFail-services-127.0.0.2-2202-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1721641250568.043, 'url': 'http://127.0.0.2:9091/cockpit/login', 'networkRequestId': '1569.12'}
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-vRQNAIvuXK,guid=db04b3a20ad4fb0334737875669e2928
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
log: 
debug: loaded client config 
debug: loaded client config 
log: 
debug: loaded client config 
log: 

# Result testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail) failed
# 1 TEST FAILED [189s on 8c8a7115198d]
not ok 5 integration-tests/check-subscriptions TestSubscriptions.testSubAndInAndFail $2

First occurrence: 2024-07-22T09:42:47.445679+00:00 | revision e8f2245e9a295823e8c7b46ba1e898567e3d362e Times recorded: 1 Latest occurrences:

cockpituous commented 3 months ago

rhel-8-6 Ooops, it happened again

# ----------------------------------------------------------------------
# testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail)
Waiting 5 seconds for https://10.111.112.100:8443/candlepin
Waiting for https://10.111.112.100:8443/candlepin
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:847: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:847: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[0606/075527.323015:WARNING:sandbox_linux.cc(436)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9310/devtools/browser/b1a4fd44-ed48-4a8e-88e4-193ce2e1a950
> warning: Parse error /var/lib/insights/host-details.json Not permitted to perform this action.
> warning: Parse error /var/lib/insights/insights-details.json Not permitted to perform this action.
> warning: failed to poll tuned 
> debug: Trying to get current organization...
> debug: Reading configuration file
> debug: Current organization: {}
> debug: loaded client config 
> debug: Other state: unknown
> debug: connection_options: 
> debug: Opening private bus interface at unix:abstract=/run/dbus-SGghPsUdtZ,guid=7e01e16bd7f009e3ca8f923f66616b76
> debug: registering using username and password
> log: registration_options: 
> debug: Result of registration:  
> debug: stopping registration server
> debug: saving: server.hostname 
> debug: saving: server.port 
> debug: saving: server.prefix 
> debug: requesting update of subscription status
> debug: requesting update of syspurpose status
> debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
> log: 
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1717660529427.309, 'url': 'http://127.0.0.2:9191/cockpit/login', 'networkRequestId': '1521.12'}
warning: Parse error /var/lib/insights/host-details.json Not permitted to perform this action.
warning: Parse error /var/lib/insights/insights-details.json Not permitted to perform this action.
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-SGghPsUdtZ,guid=7e01e16bd7f009e3ca8f923f66616b76
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

Wrote screenshot to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2301-FAIL.png
Wrote HTML dump to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2301-FAIL.html
Wrote JS log to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2301-FAIL.js.log
Journal extracted to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2301-FAIL.log.gz
Journal extracted to TestSubscriptions-testSubAndInAndFail-services-127.0.0.2-2302-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1717660529427.309, 'url': 'http://127.0.0.2:9191/cockpit/login', 'networkRequestId': '1521.12'}
warning: Parse error /var/lib/insights/host-details.json Not permitted to perform this action.
warning: Parse error /var/lib/insights/insights-details.json Not permitted to perform this action.
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-SGghPsUdtZ,guid=7e01e16bd7f009e3ca8f923f66616b76
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

# Result testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail) failed
# 1 TEST FAILED [177s on da8bc842eee6]
not ok 5 integration-tests/check-subscriptions TestSubscriptions.testSubAndInAndFail $2

First occurrence: 2024-06-06T07:57:20.557021+00:00 | revision 3fadb47f2e259db74c168d3fcea9771a7fce806d Times recorded: 1 Latest occurrences:

# ----------------------------------------------------------------------
# testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail)
Waiting 5 seconds for https://10.111.112.100:8443/candlepin
Waiting for https://10.111.112.100:8443/candlepin
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:847: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:847: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[0610/114843.546414:WARNING:sandbox_linux.cc(436)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9854/devtools/browser/d6579e4f-e1d5-4ddd-ba84-6e0d282d85f5
> warning: Parse error /var/lib/insights/host-details.json Not permitted to perform this action.
> warning: Parse error /var/lib/insights/insights-details.json Not permitted to perform this action.
> warning: failed to poll tuned 
> debug: Trying to get current organization...
> debug: Reading configuration file
> debug: Current organization: {}
> debug: loaded client config 
> debug: Other state: unknown
> debug: connection_options: 
> debug: Opening private bus interface at unix:abstract=/run/dbus-HqjrcRn9zs,guid=0b351ca477dd37a0740034ef6666e822
> debug: registering using username and password
> log: registration_options: 
> debug: Result of registration:  
> debug: stopping registration server
> debug: saving: server.hostname 
> debug: saving: server.port 
> debug: saving: server.prefix 
> debug: requesting update of subscription status
> debug: requesting update of syspurpose status
> debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
> log: 
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1718020125236.217, 'url': 'http://127.0.0.2:9091/cockpit/login', 'networkRequestId': '1420.12'}
warning: Parse error /var/lib/insights/host-details.json Not permitted to perform this action.
warning: Parse error /var/lib/insights/insights-details.json Not permitted to perform this action.
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-HqjrcRn9zs,guid=0b351ca477dd37a0740034ef6666e822
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

Wrote screenshot to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2201-FAIL.png
Wrote HTML dump to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2201-FAIL.html
Wrote JS log to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2201-FAIL.js.log
Journal extracted to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2201-FAIL.log.gz
Journal extracted to TestSubscriptions-testSubAndInAndFail-services-127.0.0.2-2202-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1718020125236.217, 'url': 'http://127.0.0.2:9091/cockpit/login', 'networkRequestId': '1420.12'}
warning: Parse error /var/lib/insights/host-details.json Not permitted to perform this action.
warning: Parse error /var/lib/insights/insights-details.json Not permitted to perform this action.
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-HqjrcRn9zs,guid=0b351ca477dd37a0740034ef6666e822
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-04-26T13:23:02+0000", "updated": "2024-04-26T13:23:07+0000", "id": "8a8082e48f1a92c7018f1a92d9d40002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-04-26T13:23:07+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

# Result testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail) failed
# 1 TEST FAILED [172s on bfd4819b76fb]
not ok 5 integration-tests/check-subscriptions TestSubscriptions.testSubAndInAndFail $2

First occurrence: 2024-06-10T11:50:34.949154+00:00 | revision 18a337716eef1eced1fa65bce3eba991842626ea Times recorded: 1 Latest occurrences:

# ----------------------------------------------------------------------
# testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail)
Waiting 5 seconds for https://10.111.112.100:8443/candlepin
Waiting for https://10.111.112.100:8443/candlepin
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:847: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:847: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[0629/230630.327695:WARNING:sandbox_linux.cc(436)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9683/devtools/browser/749f0d58-f541-494d-8e3d-b9c23d38fe25
> warning: Parse error /var/lib/insights/host-details.json Not permitted to perform this action.
> warning: Parse error /var/lib/insights/insights-details.json Not permitted to perform this action.
> warning: failed to poll tuned 
> debug: Trying to get current organization...
> debug: Reading configuration file
> debug: Current organization: {}
> debug: loaded client config 
> debug: Other state: unknown
> debug: connection_options: 
> debug: Opening private bus interface at unix:abstract=/run/dbus-W5BFiAkbrE,guid=b9aa3e161af3f6cd5a42d2886680937c
> debug: registering using username and password
> log: registration_options: 
> debug: Result of registration:  
> debug: stopping registration server
> debug: saving: server.hostname 
> debug: saving: server.port 
> debug: saving: server.prefix 
> debug: requesting update of subscription status
> debug: requesting update of syspurpose status
> debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
> log: 
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1719702391637.244, 'url': 'http://127.0.0.2:9191/cockpit/login', 'networkRequestId': '1401.12'}
warning: Parse error /var/lib/insights/host-details.json Not permitted to perform this action.
warning: Parse error /var/lib/insights/insights-details.json Not permitted to perform this action.
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-W5BFiAkbrE,guid=b9aa3e161af3f6cd5a42d2886680937c
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

Wrote screenshot to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2301-FAIL.png
Wrote HTML dump to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2301-FAIL.html
Wrote JS log to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2301-FAIL.js.log
Journal extracted to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2301-FAIL.log.gz
Journal extracted to TestSubscriptions-testSubAndInAndFail-services-127.0.0.2-2302-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1719702391637.244, 'url': 'http://127.0.0.2:9191/cockpit/login', 'networkRequestId': '1401.12'}
warning: Parse error /var/lib/insights/host-details.json Not permitted to perform this action.
warning: Parse error /var/lib/insights/insights-details.json Not permitted to perform this action.
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-W5BFiAkbrE,guid=b9aa3e161af3f6cd5a42d2886680937c
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-06-05T11:29:40+0000", "updated": "2024-06-05T11:29:45+0000", "id": "8a8082e48fe8295d018fe829701d0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-06-05T11:29:45+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

# Result testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail) failed
# 1 TEST FAILED [174s on 81cc62607014]
not ok 5 integration-tests/check-subscriptions TestSubscriptions.testSubAndInAndFail $2

First occurrence: 2024-06-29T23:08:23.579280+00:00 | revision 7ad7e2cdec39e71a242cac93001f783b726a2c7e Times recorded: 1 Latest occurrences:

# ----------------------------------------------------------------------
# testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail)
Waiting 5 seconds for https://10.111.112.100:8443/candlepin
Waiting for https://10.111.112.100:8443/candlepin
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:847: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
/usr/lib/python3.6/site-packages/urllib3/connectionpool.py:847: InsecureRequestWarning: Unverified HTTPS request is being made. Adding certificate verification is strongly advised. See: https://urllib3.readthedocs.io/en/latest/advanced-usage.html#ssl-warnings
  InsecureRequestWarning)
[0731/230823.690191:WARNING:sandbox_linux.cc(430)] InitializeSandbox() called with multiple threads in process gpu-process.

DevTools listening on ws://127.0.0.1:9560/devtools/browser/ebbfdaea-267e-419d-8af8-91eda0da94ad
> warning: Parse error /var/lib/insights/host-details.json Not permitted to perform this action.
> warning: Parse error /var/lib/insights/insights-details.json Not permitted to perform this action.
> warning: failed to poll tuned 
> debug: Trying to get current organization...
> debug: Reading configuration file
> debug: Current organization: {}
> debug: loaded client config 
> debug: Other state: unknown
> debug: connection_options: 
> debug: Opening private bus interface at unix:abstract=/run/dbus-954V0GSGMC,guid=4a4e878f7f556ccec419810966aac3ee
> debug: registering using username and password
> log: registration_options: 
> debug: Result of registration:  
> debug: stopping registration server
> debug: saving: server.hostname 
> debug: saving: server.port 
> debug: saving: server.prefix 
> debug: requesting update of subscription status
> debug: requesting update of syspurpose status
> debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
> log: 
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1722467305294.476, 'url': 'http://127.0.0.2:9291/cockpit/login', 'networkRequestId': '1538.12'}
warning: Parse error /var/lib/insights/host-details.json Not permitted to perform this action.
warning: Parse error /var/lib/insights/insights-details.json Not permitted to perform this action.
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-954V0GSGMC,guid=4a4e878f7f556ccec419810966aac3ee
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

Wrote screenshot to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2401-FAIL.png
Wrote HTML dump to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2401-FAIL.html
Wrote JS log to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2401-FAIL.js.log
Journal extracted to TestSubscriptions-testSubAndInAndFail-rhel-8-6-127.0.0.2-2401-FAIL.log.gz
Journal extracted to TestSubscriptions-testSubAndInAndFail-services-127.0.0.2-2402-FAIL.log.gz
Traceback (most recent call last):
  File "/work/make-checkout-workdir/integration-tests/check-subscriptions", line 416, in testSubAndInAndFail
    b.wait_visible("button .pf-c-button__icon svg[fill='orange']")
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 591, in wait_visible
    self._wait_present(selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 582, in _wait_present
    self.wait_js_func('ph_is_present', selector)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 576, in wait_js_func
    self.wait_js_cond("%s(%s)" % (func, ','.join(map(jsquote, args))))
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 566, in wait_js_cond
    self.raise_cdp_exception("timeout\nwait_js_cond", cond, result["exceptionDetails"], trailer)
  File "/work/make-checkout-workdir/integration-tests/common/testlib.py", line 272, in raise_cdp_exception
    raise Error("%s(%s): %s" % (func, arg, msg))
testlib.Error: timeout
wait_js_cond(ph_is_present("button .pf-c-button__icon svg[fill='orange']")): Uncaught (in promise) Error: condition did not become true
cdp: {'source': 'network', 'level': 'error', 'text': 'Failed to load resource: the server responded with a status of 401 (Authentication failed)', 'timestamp': 1722467305294.476, 'url': 'http://127.0.0.2:9291/cockpit/login', 'networkRequestId': '1538.12'}
warning: Parse error /var/lib/insights/host-details.json Not permitted to perform this action.
warning: Parse error /var/lib/insights/insights-details.json Not permitted to perform this action.
warning: failed to poll tuned 
warning: failed to poll tuned 
debug: Trying to get current organization...
debug: Reading configuration file
debug: Current organization: {}
debug: loaded client config 
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Reading configuration file
debug: loaded client config 
debug: connection_options: 
debug: Opening private bus interface at unix:abstract=/run/dbus-954V0GSGMC,guid=4a4e878f7f556ccec419810966aac3ee
debug: registering using username and password
log: registration_options: 
debug: Result of registration:  
debug: stopping registration server
debug: saving: server.hostname 
debug: saving: server.port 
debug: saving: server.prefix 
debug: requesting update of subscription status
debug: requesting update of syspurpose status
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Other state: unknown
debug: Other state: unknown
debug: Other state: unknown
debug: Trying to get current organization...
debug: Trying to get current organization...
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Trying to get current organization...
debug: Reading configuration file
debug: Reading configuration file
debug: Reading configuration file
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: Current organization: {"created": "2024-07-02T12:43:04+0000", "updated": "2024-07-02T12:43:09+0000", "id": "8a8082e4907378450190737856ca0002", "displayName": "Admin Owner", "key": "admin", "contentPrefix": null, "defaultServiceLevel": null, "logLevel": null, "contentAccessMode": "entitlement", "contentAccessModeList": "entitlement", "autobindHypervisorDisabled": false, "autobindDisabled": false, "lastRefreshed": "2024-07-02T12:43:09+0000", "parentOwner": null, "upstreamConsumer": null, "anonymous": null, "claimed": null, "claimantOwner": null}
debug: loaded client config 
debug: loaded client config 
debug: loaded client config 
log: 
log: 
log: 

# Result testSubAndInAndFail (__main__.TestSubscriptions.testSubAndInAndFail) failed
# 1 TEST FAILED [294s on b275672afd9f]
not ok 5 integration-tests/check-subscriptions TestSubscriptions.testSubAndInAndFail $2

First occurrence: 2024-07-31T23:12:14.949031+00:00 | revision 3d441a279c38bc18cecd11577f56ff1e3bc7c973 Times recorded: 1 Latest occurrences:

ptoscano commented 1 month ago

This should not be a problem anymore: branches of subscription-manager that ship cockpit and are still tested with the cockpit CI were fixed (backporting commits from subscription-manager-cockpit).

martinpitt commented 1 week ago

Right, see https://github.com/cockpit-project/bots/pull/6815 . Thanks!