0s autopkgtest [18:04:40]: starting date and time: 2025-06-18 18:04:40+0000 0s autopkgtest [18:04:40]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [18:04:40]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.efidkfoe/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:requests --apt-upgrade django-cas-server --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=requests/2.32.3+dfsg-5ubuntu2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-15.secgroup --name adt-questing-s390x-django-cas-server-20250618-180440-juju-7f2275-prod-proposed-migration-environment-15-76f6903d-ab10-4a99-af27-134a831380a9 --image adt/ubuntu-questing-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 1069s nova [W] Using flock in prodstack6-s390x 1069s flock: timeout while waiting to get lock 1069s Creating nova instance adt-questing-s390x-django-cas-server-20250618-180440-juju-7f2275-prod-proposed-migration-environment-15-76f6903d-ab10-4a99-af27-134a831380a9 from image adt/ubuntu-questing-s390x-server-20250618.img (UUID 3bd366b8-73fb-4dfa-8068-6fbfafb23f9b)... 1069s nova [E] nova boot failed (attempt #0): 1069s nova [E] DEBUG (extension:189) found extension EntryPoint.parse('v1password = swiftclient.authv1:PasswordLoader') 1069s DEBUG (extension:189) found extension EntryPoint.parse('noauth = cinderclient.contrib.noauth:CinderNoAuthLoader') 1069s DEBUG (extension:189) found extension EntryPoint.parse('admin_token = keystoneauth1.loading._plugins.admin_token:AdminToken') 1069s DEBUG (extension:189) found extension EntryPoint.parse('none = keystoneauth1.loading._plugins.noauth:NoAuth') 1069s DEBUG (extension:189) found extension EntryPoint.parse('password = keystoneauth1.loading._plugins.identity.generic:Password') 1069s DEBUG (extension:189) found extension EntryPoint.parse('token = keystoneauth1.loading._plugins.identity.generic:Token') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v2password = keystoneauth1.loading._plugins.identity.v2:Password') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v2token = keystoneauth1.loading._plugins.identity.v2:Token') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3adfspassword = keystoneauth1.extras._saml2._loading:ADFSPassword') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3applicationcredential = keystoneauth1.loading._plugins.identity.v3:ApplicationCredential') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3fedkerb = keystoneauth1.extras.kerberos._loading:MappedKerberos') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3kerberos = keystoneauth1.extras.kerberos._loading:Kerberos') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3multifactor = keystoneauth1.loading._plugins.identity.v3:MultiFactor') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3oauth1 = keystoneauth1.extras.oauth1._loading:V3OAuth1') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcaccesstoken = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAccessToken') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcauthcode = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAuthorizationCode') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcclientcredentials = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectClientCredentials') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcpassword = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectPassword') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3password = keystoneauth1.loading._plugins.identity.v3:Password') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3samlpassword = keystoneauth1.extras._saml2._loading:Saml2Password') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3token = keystoneauth1.loading._plugins.identity.v3:Token') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3tokenlessauth = keystoneauth1.loading._plugins.identity.v3:TokenlessAuth') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3totp = keystoneauth1.loading._plugins.identity.v3:TOTP') 1069s DEBUG (session:517) REQ: curl -g -i -X GET https://keystone.ps6.canonical.com:5000/v3 -H "Accept: application/json" -H "User-Agent: nova keystoneauth1/4.0.0 python-requests/2.22.0 CPython/3.8.10" 1069s DEBUG (connectionpool:962) Starting new HTTPS connection (1): keystone.ps6.canonical.com:5000 1069s DEBUG (connectionpool:429) https://keystone.ps6.canonical.com:5000 "GET /v3 HTTP/1.1" 200 267 1069s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 267 Content-Type: application/json Date: Wed, 18 Jun 2025 18:06:27 GMT Keep-Alive: timeout=75, max=1000 Server: Apache/2.4.52 (Ubuntu) Vary: X-Auth-Token x-openstack-request-id: req-29448ceb-4842-47be-80a4-c16857538833 1069s DEBUG (session:580) RESP BODY: {"version": {"id": "v3.14", "status": "stable", "updated": "2020-04-07T00:00:00Z", "links": [{"rel": "self", "href": "https://keystone.ps6.canonical.com:5000/v3/"}], "media-types": [{"base": "application/json", "type": "application/vnd.openstack.identity-v3+json"}]}} 1069s DEBUG (session:946) GET call to https://keystone.ps6.canonical.com:5000/v3 used request id req-29448ceb-4842-47be-80a4-c16857538833 1069s DEBUG (base:182) Making authentication request to https://keystone.ps6.canonical.com:5000/v3/auth/tokens 1069s DEBUG (connectionpool:429) https://keystone.ps6.canonical.com:5000 "POST /v3/auth/tokens HTTP/1.1" 201 12521 1069s DEBUG (base:187) {"token": {"methods": ["password"], "user": {"domain": {"id": "default", "name": "Default"}, "id": "fda033b8c7ea463db1c3747f74a59d10", "name": "prod-proposed-migration-s390x", "password_expires_at": null}, "audit_ids": ["ApRXHxSwTkG-CWx0iRZ2Ew"], "expires_at": "2025-06-19T18:06:28.000000Z", "issued_at": "2025-06-18T18:06:28.000000Z", "project": {"domain": {"id": "default", "name": "Default"}, "id": "ad292334f14047cc96e9c50587dfe285", "name": "prod-proposed-migration-s390x_project"}, "is_domain": false, "roles": [{"id": "80674fb26dab46e9a6bd57e7f8193360", "name": "load-balancer_member"}, {"id": "86bf3043974746c2bf52c2a9f362f986", "name": "reader"}, {"id": "15cf997650e345b7b21c02b5cdb7d84a", "name": "member"}], "is_admin_project": false, "catalog": [{"endpoints": [{"id": "213cdf38198c4581bd5b7d6d0836923b", "interface": "internal", "region_id": "prodstack6", "url": "https://radosgw-internal.ps6.canonical.com:443/", "region": "prodstack6"}, {"id": "67061eee26944a1a8009f9d07be19738", "interface": "public", "region_id": "prodstack6", "url": "https://radosgw.ps6.canonical.com:443/", "region": "prodstack6"}, {"id": "fc88c52c749a47d08a24a2c7efbde01b", "interface": "admin", "region_id": "prodstack6", "url": "https://radosgw-admin.ps6.canonical.com:443/", "region": "prodstack6"}], "id": "06e4714942634054bf9aa11715312b4e", "type": "s3", "name": "s3"}, {"endpoints": [{"id": "8aa44c94b63746bd9b37f1008ad55fa8", "interface": "admin", "region_id": "prodstack6", "url": "https://aodh-admin.ps6.canonical.com:8042", "region": "prodstack6"}, {"id": "92cf08a80a0242ebbca8ec893cf332b1", "interface": "public", "region_id": "prodstack6", "url": "https://aodh.ps6.canonical.com:8042", "region": "prodstack6"}, {"id": "d8139c6e11ae4edcb78981c12bd53212", "interface": "internal", "region_id": "prodstack6", "url": "https://aodh-internal.ps6.canonical.com:8042", "region": "prodstack6"}], "id": "2fb939b0b969435c8f0c5fa4edb4de94", "type": "alarming", "name": "aodh"}, {"endpoints": [{"id": "a49fcc80e658411885f88cdd8432a571", "interface": "internal", "region_id": "prodstack6", "url": "https://heat-internal.ps6.canonical.com:8000/v1", "region": "prodstack6"}, {"id": "b23866fddba8415885b06c064c34b94b", "interface": "public", "region_id": "prodstack6", "url": "https://heat.ps6.canonical.com:8000/v1", "region": "prodstack6"}, {"id": "f84ae55197f94b8392a678f35c82ca26", "interface": "admin", "region_id": "prodstack6", "url": "https://heat-admin.ps6.canonical.com:8000/v1", "region": "prodstack6"}], "id": "3d4b59f7dab644b2b527e8e96b697545", "type": "cloudformation", "name": "heat-cfn"}, {"endpoints": [{"id": "2e0143334c6241169f45f1c11970c6bc", "interface": "public", "region_id": "prodstack6", "url": "https://heat.ps6.canonical.com:8004/v1/ad292334f14047cc96e9c50587dfe285", "region": "prodstack6"}, {"id": "5e19c32243c04a3faf1e5f9ab900c260", "interface": "internal", "region_id": "prodstack6", "url": "https://heat-internal.ps6.canonical.com:8004/v1/ad292334f14047cc96e9c50587dfe285", "region": "prodstack6"}, {"id": "b3fc5c88ec8543a691fec75726b86967", "interface": "admin", "region_id": "prodstack6", "url": "https://heat-admin.ps6.canonical.com:8004/v1/ad292334f14047cc96e9c50587dfe285", "region": "prodstack6"}], "id": "43b926205044476b8093b18d8ce0a9e4", "type": "orchestration", "name": "heat"}, {"endpoints": [{"id": "a47569b96e5b4146a312e4673504557c", "interface": "public", "region_id": "prodstack6", "url": "https://glance.ps6.canonical.com:9292", "region": "prodstack6"}, {"id": "bba35c4ab04f4114a2a34e65568537bd", "interface": "internal", "region_id": "prodstack6", "url": "https://glance-internal.ps6.canonical.com:9292", "region": "prodstack6"}, {"id": "ef769f1117c74a62a279131486c89628", "interface": "admin", "region_id": "prodstack6", "url": "https://glance-admin.ps6.canonical.com:9292", "region": "prodstack6"}], "id": "49de724549524bd78e9cd46a4477226f", "type": "image", "name": "glance"}, {"endpoints": [{"id": "a1a41e9a1e2d42e5b6cd5380cd1e21bf", "interface": "admin", "region_id": "prodstack6", "url": "https://cinder-admin.ps6.canonical.com:8776/v3/ad292334f14047cc96e9c50587dfe285", "region": "prodstack6"}, {"id": "d2309a99724b4111827482c68a832c0c", "interface": "public", "region_id": "prodstack6", "url": "https://cinder.ps6.canonical.com:8776/v3/ad292334f14047cc96e9c50587dfe285", "region": "prodstack6"}, {"id": "f8a02e3949ff42afa772eb5e74dbef9f", "interface": "internal", "region_id": "prodstack6", "url": "https://cinder-internal.ps6.canonical.com:8776/v3/ad292334f14047cc96e9c50587dfe285", "region": "prodstack6"}], "id": "5bf6578a631349e3af4bf36d7adb18e1", "type": "volumev3", "name": "cinderv3"}, {"endpoints": [{"id": "01d84128f36444e9b01549b86bd69116", "interface": "admin", "region_id": "prodstack6", "url": "https://barbican-admin.ps6.canonical.com:9312", "region": "prodstack6"}, {"id": "0ce4a8777e0b40a88f7fc0f77e34a605", "interface": "public", "region_id": "prodstack6", "url": "https://barbican.ps6.canonical.com:9311", "region": "prodstack6"}, {"id": "f507ba49e4a7435cb330234217c92449", "interface": "internal", "region_id": "prodstack6", "url": "https://barbican-internal.ps6.canonical.com:9311", "region": "prodstack6"}], "id": "62cb1c3d9f6c45979c13516a6b3ff114", "type": "key-manager", "name": "barbican"}, {"endpoints": [{"id": "3cb6121bd8f14da8aa2fba7be823a2b4", "interface": "internal", "region_id": "prodstack6", "url": "https://neutron-internal.ps6.canonical.com:9696", "region": "prodstack6"}, {"id": "46ba11d133fe4e8f9e8f9097ba737682", "interface": "admin", "region_id": "prodstack6", "url": "https://neutron-admin.ps6.canonical.com:9696", "region": "prodstack6"}, {"id": "d872eb6aa23d40009165aa3dcdce3948", "interface": "public", "region_id": "prodstack6", "url": "https://neutron.ps6.canonical.com:9696", "region": "prodstack6"}], "id": "759cb6fdfbc146dd8b9d1ba444c4b9db", "type": "network", "name": "neutron"}, {"endpoints": [{"id": "4349d565f72446da9699269f85df5e8b", "interface": "internal", "region_id": "prodstack6", "url": "https://manila-internal.ps6.canonical.com:8786/v2/ad292334f14047cc96e9c50587dfe285", "region": "prodstack6"}, {"id": "a483dcc58f5e415e9ec739c94474e609", "interface": "public", "region_id": "prodstack6", "url": "https://manila.ps6.canonical.com:8786/v2/ad292334f14047cc96e9c50587dfe285", "region": "prodstack6"}, {"id": "c1f14236723e42878562d8dd63d5a6a0", "interface": "admin", "region_id": "prodstack6", "url": "https://manila-admin.ps6.canonical.com:8786/v2/ad292334f14047cc96e9c50587dfe285", "region": "prodstack6"}], "id": "93fc4008035946deb1f433c2dd905a5d", "type": "sharev2", "name": "manilav2"}, {"endpoints": [{"id": "0fa148793c7249c8b187b6b4ff425731", "interface": "internal", "region_id": "prodstack6", "url": "https://radosgw-internal.ps6.canonical.com:443/swift/v1/AUTH_e4b04ca58d734ed0aa29e306adad4f79/simplestreams/data", "region": "prodstack6"}, {"id": "555ade266a6b4bcc81b6e5abf607aec5", "interface": "public", "region_id": "prodstack6", "url": "https://radosgw.ps6.canonical.com:443/swift/v1/AUTH_e4b04ca58d734ed0aa29e306adad4f79/simplestreams/data", "region": "prodstack6"}, {"id": "7dad1a7b1552465686424fc014463173", "interface": "admin", "region_id": "prodstack6", "url": "https://radosgw-admin.ps6.canonical.com:443/swift/simplestreams/data", "region": "prodstack6"}], "id": "977dd359af1a4fcc91a9daf4dc33b0f3", "type": "product-streams", "name": "image-stream"}, {"endpoints": [{"id": "12d30eabcdec4831bd15273d55b1cbab", "interface": "internal", "region_id": "prodstack6", "url": "https://manila-internal.ps6.canonical.com:8786/v1/ad292334f14047cc96e9c50587dfe285", "region": "prodstack6"}, {"id": "29d35b2b483346479f1ff1a13604d50e", "interface": "admin", "region_id": "prodstack6", "url": "https://manila-admin.ps6.canonical.com:8786/v1/ad292334f14047cc96e9c50587dfe285", "region": "prodstack6"}, {"id": "4aa1422b8f5945d8aed9ba6a8d670e5c", "interface": "public", "region_id": "prodstack6", "url": "https://manila.ps6.canonical.com:8786/v1/ad292334f14047cc96e9c50587dfe285", "region": "prodstack6"}], "id": "ac5e4f28fa794cde99f1d7ffc1b664e5", "type": "share", "name": "manila"}, {"endpoints": [{"id": "c7db93d54a684d8caeae1d9e18a59118", "interface": "admin", "region_id": "prodstack6", "url": "https://nova-admin.ps6.canonical.com:8774/v2.1", "region": "prodstack6"}, {"id": "dd7a796f401b477f9f1b5bbc05747d8d", "interface": "public", "region_id": "prodstack6", "url": "https://nova.ps6.canonical.com:8774/v2.1", "region": "prodstack6"}, {"id": "e7ecb038d830462ea67b0b463af5e013", "interface": "internal", "region_id": "prodstack6", "url": "https://nova-internal.ps6.canonical.com:8774/v2.1", "region": "prodstack6"}], "id": "b4519af08e174d4a8333a2d14ac3ba3b", "type": "compute", "name": "nova"}, {"endpoints": [{"id": "02ef5b6154934ffd97d8b5b8e4d70d2a", "interface": "internal", "region_id": "prodstack6", "url": "https://radosgw-internal.ps6.canonical.com:443/swift/v1/AUTH_ad292334f14047cc96e9c50587dfe285", "region": "prodstack6"}, {"id": "2741abe526e94842a4fa801423ba1ef0", "interface": "public", "region_id": "prodstack6", "url": "https://radosgw.ps6.canonical.com:443/swift/v1/AUTH_ad292334f14047cc96e9c50587dfe285", "region": "prodstack6"}, {"id": "3742f9406c604516933d6fd8c29e0880", "interface": "admin", "region_id": "prodstack6", "url": "https://radosgw-admin.ps6.canonical.com:443/swift", "region": "prodstack6"}], "id": "c7f07a7b3b3c408c96200dfd1d1a0f6f", "type": "object-store", "name": "swift"}, {"endpoints": [{"id": "06d4ed6c160a43f2b7fa7f80ad1dc709", "interface": "internal", "region_id": "prodstack6", "url": "https://designate-internal.ps6.canonical.com:9001", "region": "prodstack6"}, {"id": "1e240624a50c4ca0aaa6ef63cbc94f1b", "interface": "admin", "region_id": "prodstack6", "url": "https://designate-admin.ps6.canonical.com:9001", "region": "prodstack6"}, {"id": "f94d7d17997a43fd9c63b34c43301c6e", "interface": "public", "region_id": "prodstack6", "url": "https://designate.ps6.canonical.com:9001", "region": "prodstack6"}], "id": "cf82013d63844fedbe96bcacb6bd1fef", "type": "dns", "name": "designate"}, {"endpoints": [{"id": "4a688340be464710bfa60bf3f88f41c4", "interface": "admin", "region_id": "prodstack6", "url": "https://gnocchi-admin.ps6.canonical.com:8041", "region": "prodstack6"}, {"id": "a5a95506c96d4716adb4efc3ac9051be", "interface": "public", "region_id": "prodstack6", "url": "https://gnocchi.ps6.canonical.com:8041", "region": "prodstack6"}, {"id": "eebc17a086834f8fa8bb6b35c8061a8c", "interface": "internal", "region_id": "prodstack6", "url": "https://gnocchi-internal.ps6.canonical.com:8041", "region": "prodstack6"}], "id": "d10589e854a446a5b852593f33f170a7", "type": "metric", "name": "gnocchi"}, {"endpoints": [{"id": "08f66df28d0f47f3bec12f29e06d85ac", "interface": "admin", "region_id": "prodstack6", "url": "https://keystone-admin.ps6.canonical.com:35357/v3", "region": "prodstack6"}, {"id": "46d270b45ceb42db902f4d241afa6a70", "interface": "public", "region_id": "prodstack6", "url": "https://keystone.ps6.canonical.com:5000/v3", "region": "prodstack6"}, {"id": "a4addee11d9349cfa46ec6660dbad947", "interface": "internal", "region_id": "prodstack6", "url": "https://keystone-internal.ps6.canonical.com:5000/v3", "region": "prodstack6"}], "id": "deb38a775a9b4e87a8de9e3eed779882", "type": "identity", "name": "keystone"}, {"endpoints": [{"id": "18b4af5f40664babb94e884c6a53ccf3", "interface": "admin", "region_id": "prodstack6", "url": "https://octavia-admin.ps6.canonical.com:9876", "region": "prodstack6"}, {"id": "2b6f7ba7f9944ee1aebd6dccc5223b2d", "interface": "internal", "region_id": "prodstack6", "url": "https://octavia-internal.ps6.canonical.com:9876", "region": "prodstack6"}, {"id": "5e3012e9c79e4890be4d964b6208f5c6", "interface": "public", "region_id": "prodstack6", "url": "https://octavia.ps6.canonical.com:9876", "region": "prodstack6"}], "id": "e00337be20d54948b66a1c3109b885c6", "type": "load-balancer", "name": "octavia"}, {"endpoints": [{"id": "2d2942b35f3041389ad810f679d02f22", "interface": "public", "region_id": "prodstack6", "url": "https://placement.ps6.canonical.com:8778", "region": "prodstack6"}, {"id": "2e3beaac7ac14284b72e2592820ad725", "interface": "admin", "region_id": "prodstack6", "url": "https://placement-admin.ps6.canonical.com:8778", "region": "prodstack6"}, {"id": "91060f6df9ae444f81808adf40540d94", "interface": "internal", "region_id": "prodstack6", "url": "https://placement-internal.ps6.canonical.com:8778", "region": "prodstack6"}], "id": "edc6e37f154f4c0ab3dd4d52b82bf873", "type": "placement", "name": "placement"}]}} 1069s REQ: curl -g -i -X GET https://nova.ps6.canonical.com:8774/v2.1 -H "Accept: application/json" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}3ebd0f8c8a29b6a214cc14eab4906148c681045957f71043482ab5e4f9d6798c" 1069s DEBUG (session:517) REQ: curl -g -i -X GET https://nova.ps6.canonical.com:8774/v2.1 -H "Accept: application/json" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}3ebd0f8c8a29b6a214cc14eab4906148c681045957f71043482ab5e4f9d6798c" 1069s DEBUG (connectionpool:962) Starting new HTTPS connection (1): nova.ps6.canonical.com:8774 1069s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1 HTTP/1.1" 302 0 1069s RESP: [302] Connection: Keep-Alive Content-Length: 0 Content-Type: text/plain; charset=utf8 Date: Wed, 18 Jun 2025 18:06:28 GMT Keep-Alive: timeout=75, max=1000 Location: https://nova.ps6.canonical.com:8774/v2.1/ Server: Apache/2.4.52 (Ubuntu) x-compute-request-id: req-62edb209-c5b9-4875-8042-f877d6bab3f4 x-openstack-request-id: req-62edb209-c5b9-4875-8042-f877d6bab3f4 1069s DEBUG (session:548) RESP: [302] Connection: Keep-Alive Content-Length: 0 Content-Type: text/plain; charset=utf8 Date: Wed, 18 Jun 2025 18:06:28 GMT Keep-Alive: timeout=75, max=1000 Location: https://nova.ps6.canonical.com:8774/v2.1/ Server: Apache/2.4.52 (Ubuntu) x-compute-request-id: req-62edb209-c5b9-4875-8042-f877d6bab3f4 x-openstack-request-id: req-62edb209-c5b9-4875-8042-f877d6bab3f4 1069s RESP BODY: Omitted, Content-Type is set to text/plain; charset=utf8. Only application/json responses have their bodies logged. 1069s DEBUG (session:580) RESP BODY: Omitted, Content-Type is set to text/plain; charset=utf8. Only application/json responses have their bodies logged. 1069s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/ HTTP/1.1" 200 397 1069s RESP: [200] Connection: Keep-Alive Content-Length: 397 Content-Type: application/json Date: Wed, 18 Jun 2025 18:06:28 GMT Keep-Alive: timeout=75, max=999 OpenStack-API-Version: compute 2.1 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.1 x-compute-request-id: req-1310b11c-62ac-48f4-add3-59fa62f2f387 x-openstack-request-id: req-1310b11c-62ac-48f4-add3-59fa62f2f387 1069s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 397 Content-Type: application/json Date: Wed, 18 Jun 2025 18:06:28 GMT Keep-Alive: timeout=75, max=999 OpenStack-API-Version: compute 2.1 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.1 x-compute-request-id: req-1310b11c-62ac-48f4-add3-59fa62f2f387 x-openstack-request-id: req-1310b11c-62ac-48f4-add3-59fa62f2f387 1069s RESP BODY: {"version": {"id": "v2.1", "status": "CURRENT", "version": "2.90", "min_version": "2.1", "updated": "2013-07-23T11:33:21Z", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/"}, {"rel": "describedby", "type": "text/html", "href": "http://docs.openstack.org/"}], "media-types": [{"base": "application/json", "type": "application/vnd.openstack.compute+json;version=2.1"}]}} 1069s DEBUG (session:580) RESP BODY: {"version": {"id": "v2.1", "status": "CURRENT", "version": "2.90", "min_version": "2.1", "updated": "2013-07-23T11:33:21Z", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/"}, {"rel": "describedby", "type": "text/html", "href": "http://docs.openstack.org/"}], "media-types": [{"base": "application/json", "type": "application/vnd.openstack.compute+json;version=2.1"}]}} 1069s GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/ used request id req-1310b11c-62ac-48f4-add3-59fa62f2f387 1069s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/ used request id req-1310b11c-62ac-48f4-add3-59fa62f2f387 1069s DEBUG (extension:189) found extension EntryPoint.parse('v1password = swiftclient.authv1:PasswordLoader') 1069s DEBUG (extension:189) found extension EntryPoint.parse('noauth = cinderclient.contrib.noauth:CinderNoAuthLoader') 1069s DEBUG (extension:189) found extension EntryPoint.parse('admin_token = keystoneauth1.loading._plugins.admin_token:AdminToken') 1069s DEBUG (extension:189) found extension EntryPoint.parse('none = keystoneauth1.loading._plugins.noauth:NoAuth') 1069s DEBUG (extension:189) found extension EntryPoint.parse('password = keystoneauth1.loading._plugins.identity.generic:Password') 1069s DEBUG (extension:189) found extension EntryPoint.parse('token = keystoneauth1.loading._plugins.identity.generic:Token') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v2password = keystoneauth1.loading._plugins.identity.v2:Password') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v2token = keystoneauth1.loading._plugins.identity.v2:Token') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3adfspassword = keystoneauth1.extras._saml2._loading:ADFSPassword') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3applicationcredential = keystoneauth1.loading._plugins.identity.v3:ApplicationCredential') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3fedkerb = keystoneauth1.extras.kerberos._loading:MappedKerberos') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3kerberos = keystoneauth1.extras.kerberos._loading:Kerberos') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3multifactor = keystoneauth1.loading._plugins.identity.v3:MultiFactor') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3oauth1 = keystoneauth1.extras.oauth1._loading:V3OAuth1') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcaccesstoken = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAccessToken') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcauthcode = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectAuthorizationCode') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcclientcredentials = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectClientCredentials') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3oidcpassword = keystoneauth1.loading._plugins.identity.v3:OpenIDConnectPassword') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3password = keystoneauth1.loading._plugins.identity.v3:Password') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3samlpassword = keystoneauth1.extras._saml2._loading:Saml2Password') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3token = keystoneauth1.loading._plugins.identity.v3:Token') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3tokenlessauth = keystoneauth1.loading._plugins.identity.v3:TokenlessAuth') 1069s DEBUG (extension:189) found extension EntryPoint.parse('v3totp = keystoneauth1.loading._plugins.identity.v3:TOTP') 1069s DEBUG (session:517) REQ: curl -g -i -X GET https://glance.ps6.canonical.com:9292/v2/images/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}3ebd0f8c8a29b6a214cc14eab4906148c681045957f71043482ab5e4f9d6798c" -H "X-OpenStack-Nova-API-Version: 2.87" 1069s DEBUG (connectionpool:962) Starting new HTTPS connection (1): glance.ps6.canonical.com:9292 1069s DEBUG (connectionpool:429) https://glance.ps6.canonical.com:9292 "GET /v2/images/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b HTTP/1.1" 200 2261 1069s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 2261 Content-Type: application/json Date: Wed, 18 Jun 2025 18:06:29 GMT Keep-Alive: timeout=75, max=1000 Server: Apache/2.4.52 (Ubuntu) X-Openstack-Request-Id: req-3a143cdf-59d9-4023-baef-0c9221d3a8a8 1069s DEBUG (session:580) RESP BODY: {"architecture": "s390x", "base_image_ref": "37828cef-4e21-48ba-b7d5-63f9eea03051", "boot_roles": "load-balancer_member,member,reader", "content_id": "auto.sync", "hw_cdrom_bus": "scsi", "hw_disk_bus": "virtio", "hw_input_bus": "usb", "hw_machine_type": "s390-ccw-virtio", "hw_pointer_model": "usbtablet", "hw_vif_model": "virtio", "image_location": "snapshot", "image_state": "available", "image_type": "snapshot", "instance_uuid": "2d52e14f-9827-4b3b-bb92-b6c38616fd7d", "item_name": "disk1.img", "os_distro": "ubuntu", "os_version": "25.04", "owner_id": "ad292334f14047cc96e9c50587dfe285", "owner_project_name": "prod-proposed-migration-s390x_project", "owner_user_name": "prod-proposed-migration-s390x", "product_name": "com.ubuntu.cloud.daily:server:25.04:s390x", "simplestreams_metadata": "{\"aliases\": \"25.04,p,plucky,devel\", \"arch\": \"s390x\", \"ftype\": \"disk1.img\", \"label\": \"daily\", \"md5\": \"e18cf68b0057508b4f373becf7e386ce\", \"os\": \"ubuntu\", \"pubname\": \"ubuntu-plucky-daily-s390x-server-20250113\", \"release\": \"plucky\", \"release_codename\": \"Pluck", "source_content_id": "com.ubuntu.cloud:daily:download", "user_id": "fda033b8c7ea463db1c3747f74a59d10", "version_name": "20250113", "name": "adt/ubuntu-questing-s390x-server-20250618.img", "disk_format": "qcow2", "container_format": "bare", "visibility": "private", "size": 2229469184, "virtual_size": 21474836480, "status": "active", "checksum": "ecda1cfdf4487cfc71eba92a53b62329", "protected": false, "min_ram": 0, "min_disk": 20, "owner": "ad292334f14047cc96e9c50587dfe285", "os_hidden": false, "os_hash_algo": "sha256", "os_hash_value": "f204599aae983ea086086e3a4ce1baeeab9d437471a6a70dfea3a12167facab2", "id": "3bd366b8-73fb-4dfa-8068-6fbfafb23f9b", "created_at": "2025-06-18T06:19:54Z", "updated_at": "2025-06-18T06:20:19Z", "locations": [{"url": "rbd://eea9d068-c18c-11ed-8dc0-013aacb71b80/glance/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b/snap", "metadata": {"store": "ceph"}}], "direct_url": "rbd://eea9d068-c18c-11ed-8dc0-013aacb71b80/glance/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b/snap", "tags": [], "self": "/v2/images/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b", "file": "/v2/images/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b/file", "schema": "/v2/schemas/image", "stores": "ceph"} 1069s DEBUG (session:936) GET call to image for https://glance.ps6.canonical.com:9292/v2/images/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b used request id req-3a143cdf-59d9-4023-baef-0c9221d3a8a8 1069s DEBUG (session:517) REQ: curl -g -i -X GET https://nova.ps6.canonical.com:8774/v2.1/flavors/autopkgtest-s390x -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}3ebd0f8c8a29b6a214cc14eab4906148c681045957f71043482ab5e4f9d6798c" -H "X-OpenStack-Nova-API-Version: 2.87" 1069s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors/autopkgtest-s390x HTTP/1.1" 404 90 1069s DEBUG (session:548) RESP: [404] Connection: Keep-Alive Content-Length: 90 Content-Type: application/json; charset=UTF-8 Date: Wed, 18 Jun 2025 18:06:29 GMT Keep-Alive: timeout=75, max=998 OpenStack-API-Version: compute 2.87 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.87 x-compute-request-id: req-821a3114-ce59-4c98-938a-22eaed8ec180 x-openstack-request-id: req-821a3114-ce59-4c98-938a-22eaed8ec180 1069s DEBUG (session:580) RESP BODY: {"itemNotFound": {"code": 404, "message": "Flavor autopkgtest-s390x could not be found."}} 1069s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/flavors/autopkgtest-s390x used request id req-821a3114-ce59-4c98-938a-22eaed8ec180 1069s DEBUG (session:517) REQ: curl -g -i -X GET https://nova.ps6.canonical.com:8774/v2.1/flavors?is_public=None -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}3ebd0f8c8a29b6a214cc14eab4906148c681045957f71043482ab5e4f9d6798c" -H "X-OpenStack-Nova-API-Version: 2.87" 1069s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors?is_public=None HTTP/1.1" 200 15749 1069s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 15749 Content-Type: application/json Date: Wed, 18 Jun 2025 18:06:29 GMT Keep-Alive: timeout=75, max=997 OpenStack-API-Version: compute 2.87 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.87 x-compute-request-id: req-238542de-f56e-4131-b9e1-25a746538b65 x-openstack-request-id: req-238542de-f56e-4131-b9e1-25a746538b65 1069s DEBUG (session:580) RESP BODY: {"flavors": [{"id": "03162251-b47f-403b-a928-104b4bc66898", "name": "builder-s390x-cpu2-ram4-disk20", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/03162251-b47f-403b-a928-104b4bc66898"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/03162251-b47f-403b-a928-104b4bc66898"}], "description": null}, {"id": "03d6b00d-4875-44aa-b60c-e1162d975180", "name": "builder-s390x-cpu16-ram64-disk50", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/03d6b00d-4875-44aa-b60c-e1162d975180"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/03d6b00d-4875-44aa-b60c-e1162d975180"}], "description": null}, {"id": "05196979-e04b-475f-8ec6-ee66f924a5bb", "name": "builder-s390x-cpu4-ram8-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/05196979-e04b-475f-8ec6-ee66f924a5bb"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/05196979-e04b-475f-8ec6-ee66f924a5bb"}], "description": null}, {"id": "0a154e63-cfad-4957-96a6-5a4123266f79", "name": "autopkgtest-big-s390x", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/0a154e63-cfad-4957-96a6-5a4123266f79"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/0a154e63-cfad-4957-96a6-5a4123266f79"}], "description": null}, {"id": "16f0eee6-3271-4d64-84cc-0ed22a1ba47d", "name": "builder-s390x-cpu16-ram32-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/16f0eee6-3271-4d64-84cc-0ed22a1ba47d"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/16f0eee6-3271-4d64-84cc-0ed22a1ba47d"}], "description": null}, {"id": "19be4598-edae-4564-ba9a-6f31af8d2aca", "name": "builder-s390x-cpu8-ram16-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/19be4598-edae-4564-ba9a-6f31af8d2aca"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/19be4598-edae-4564-ba9a-6f31af8d2aca"}], "description": null}, {"id": "1f41ada3-49a9-4c7a-bfe0-afb334e3fb81", "name": "builder-s390x-cpu8-ram32-disk20", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/1f41ada3-49a9-4c7a-bfe0-afb334e3fb81"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/1f41ada3-49a9-4c7a-bfe0-afb334e3fb81"}], "description": null}, {"id": "290ff9bf-b2d5-4d4f-8fd7-bac6ed1d98d1", "name": "builder-s390x-cpu48-ram64-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/290ff9bf-b2d5-4d4f-8fd7-bac6ed1d98d1"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/290ff9bf-b2d5-4d4f-8fd7-bac6ed1d98d1"}], "description": null}, {"id": "29d166cc-5f51-49db-bb7c-2cf8273b5c70", "name": "builder-s390x-cpu2-ram4-disk50", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/29d166cc-5f51-49db-bb7c-2cf8273b5c70"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/29d166cc-5f51-49db-bb7c-2cf8273b5c70"}], "description": null}, {"id": "30c160ed-fc27-4695-8ed9-1225cfc8c77b", "name": "builder-s390x-cpu4-ram32-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/30c160ed-fc27-4695-8ed9-1225cfc8c77b"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/30c160ed-fc27-4695-8ed9-1225cfc8c77b"}], "description": null}, {"id": "38de1015-2d47-44dc-8fb4-59fea0843596", "name": "builder-s390x-cpu8-ram8-disk20", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/38de1015-2d47-44dc-8fb4-59fea0843596"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/38de1015-2d47-44dc-8fb4-59fea0843596"}], "description": null}, {"id": "40fb6661-187a-454b-a0f6-21e2917928cd", "name": "builder-s390x-cpu4-ram16-disk40", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/40fb6661-187a-454b-a0f6-21e2917928cd"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/40fb6661-187a-454b-a0f6-21e2917928cd"}], "description": null}, {"id": "43b26512-c2bd-43c3-9dc3-6654864d78bf", "name": "builder-s390x-cpu8-ram32-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/43b26512-c2bd-43c3-9dc3-6654864d78bf"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/43b26512-c2bd-43c3-9dc3-6654864d78bf"}], "description": null}, {"id": "4be34a38-2e2b-4c28-8f50-093d912a8239", "name": "builder-s390x-cpu2-ram8-disk50", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/4be34a38-2e2b-4c28-8f50-093d912a8239"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/4be34a38-2e2b-4c28-8f50-093d912a8239"}], "description": null}, {"id": "4c066e77-5b00-4187-bce1-29164b6a0e7b", "name": "builder-s390x-cpu4-ram8-disk20", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/4c066e77-5b00-4187-bce1-29164b6a0e7b"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/4c066e77-5b00-4187-bce1-29164b6a0e7b"}], "description": null}, {"id": "4e198565-d86f-4daa-8506-c0923a5ca6e7", "name": "builder-s390x-cpu48-ram128-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/4e198565-d86f-4daa-8506-c0923a5ca6e7"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/4e198565-d86f-4daa-8506-c0923a5ca6e7"}], "description": null}, {"id": "511b3962-e5fd-4f34-a342-adf51c388d4a", "name": "builder-s390x-cpu2-ram64-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/511b3962-e5fd-4f34-a342-adf51c388d4a"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/511b3962-e5fd-4f34-a342-adf51c388d4a"}], "description": null}, {"id": "5654e68d-cd71-4a6f-9857-4244d17cee07", "name": "builder-s390x-cpu4-ram16-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/5654e68d-cd71-4a6f-9857-4244d17cee07"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/5654e68d-cd71-4a6f-9857-4244d17cee07"}], "description": null}, {"id": "5cac32a0-85d2-4049-8cd6-ce7164372b01", "name": "autopkgtest-s390x", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/5cac32a0-85d2-4049-8cd6-ce7164372b01"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/5cac32a0-85d2-4049-8cd6-ce7164372b01"}], "description": null}, {"id": "5e5016a1-a6c6-4758-969b-374ce4c0c482", "name": "builder-s390x-cpu2-ram4-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/5e5016a1-a6c6-4758-969b-374ce4c0c482"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/5e5016a1-a6c6-4758-969b-374ce4c0c482"}], "description": null}, {"id": "69b8f599-c030-42be-9ac9-002b471bfef6", "name": "builder-s390x-cpu8-ram16-disk200", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/69b8f599-c030-42be-9ac9-002b471bfef6"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/69b8f599-c030-42be-9ac9-002b471bfef6"}], "description": null}, {"id": "741488e4-c2ce-4d25-a145-8edef429ae72", "name": "builder-s390x-cpu8-ram16-disk50", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/741488e4-c2ce-4d25-a145-8edef429ae72"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/741488e4-c2ce-4d25-a145-8edef429ae72"}], "description": null}, {"id": "82f5392f-3939-47f6-9cf6-2085a02a2982", "name": "builder-s390x-cpu64-ram256-disk50", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/82f5392f-3939-47f6-9cf6-2085a02a2982"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/82f5392f-3939-47f6-9cf6-2085a02a2982"}], "description": null}, {"id": "84be8f32-dfc2-4aff-825e-c287fec72a97", "name": "builder-s390x-cpu4-ram16-disk20", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/84be8f32-dfc2-4aff-825e-c287fec72a97"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/84be8f32-dfc2-4aff-825e-c287fec72a97"}], "description": null}, {"id": "877737da-fee3-4951-89fe-595070c8735c", "name": "builder-s390x-cpu4-ram8-disk200", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/877737da-fee3-4951-89fe-595070c8735c"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/877737da-fee3-4951-89fe-595070c8735c"}], "description": null}, {"id": "8abf51c6-e4df-4faf-85b3-ef009d473e72", "name": "builder-s390x-cpu4-ram8-disk50", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/8abf51c6-e4df-4faf-85b3-ef009d473e72"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/8abf51c6-e4df-4faf-85b3-ef009d473e72"}], "description": null}, {"id": "90005c77-450f-49e8-b53f-9424ed6b2612", "name": "builder-s390x-cpu8-ram32-disk50", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/90005c77-450f-49e8-b53f-9424ed6b2612"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/90005c77-450f-49e8-b53f-9424ed6b2612"}], "description": null}, {"id": "9486d770-ca82-44b6-bbf0-322a7a903a8e", "name": "builder-s390x-cpu4-ram4-disk50", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/9486d770-ca82-44b6-bbf0-322a7a903a8e"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/9486d770-ca82-44b6-bbf0-322a7a903a8e"}], "description": null}, {"id": "9af6e698-fd0d-4687-aa47-d53ebf80efc5", "name": "builder-s390x-cpu2-ram8-disk28", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/9af6e698-fd0d-4687-aa47-d53ebf80efc5"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/9af6e698-fd0d-4687-aa47-d53ebf80efc5"}], "description": null}, {"id": "a7d41d5b-9869-4ff3-b282-57a9f8142d37", "name": "builder-s390x-cpu2-ram16-disk20", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/a7d41d5b-9869-4ff3-b282-57a9f8142d37"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/a7d41d5b-9869-4ff3-b282-57a9f8142d37"}], "description": null}, {"id": "a954eb03-9810-464c-8378-9d472c2e354b", "name": "builder-s390x-cpu4-ram4-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/a954eb03-9810-464c-8378-9d472c2e354b"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/a954eb03-9810-464c-8378-9d472c2e354b"}], "description": null}, {"id": "add6a04e-9a35-4f57-8a40-b756e9bbc563", "name": "builder-s390x-cpu8-ram16-disk500", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/add6a04e-9a35-4f57-8a40-b756e9bbc563"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/add6a04e-9a35-4f57-8a40-b756e9bbc563"}], "description": null}, {"id": "ba873832-532c-4be6-b399-3caafd0b1f85", "name": "builder-s390x-cpu4-ram4-disk20", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/ba873832-532c-4be6-b399-3caafd0b1f85"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/ba873832-532c-4be6-b399-3caafd0b1f85"}], "description": null}, {"id": "bbaf6f9d-86da-4c7b-b41e-5dba42bc8be2", "name": "builder-s390x-cpu4-ram32-disk50", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/bbaf6f9d-86da-4c7b-b41e-5dba42bc8be2"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/bbaf6f9d-86da-4c7b-b41e-5dba42bc8be2"}], "description": null}, {"id": "bc8fdcd7-2f4f-4032-8a65-aec604da32a3", "name": "builder-s390x-cpu2-ram16-disk50", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/bc8fdcd7-2f4f-4032-8a65-aec604da32a3"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/bc8fdcd7-2f4f-4032-8a65-aec604da32a3"}], "description": null}, {"id": "bde27b36-130b-4ea0-9a30-6df70525d72c", "name": "builder-s390x-cpu2-ram64-disk50", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/bde27b36-130b-4ea0-9a30-6df70525d72c"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/bde27b36-130b-4ea0-9a30-6df70525d72c"}], "description": null}, {"id": "c2cc32b1-e874-440d-b38f-b179dda7b964", "name": "builder-s390x-cpu8-ram16-disk20", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/c2cc32b1-e874-440d-b38f-b179dda7b964"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/c2cc32b1-e874-440d-b38f-b179dda7b964"}], "description": null}, {"id": "c3d6f05c-fce6-4f16-8a11-8a441e680443", "name": "builder-s390x-cpu2-ram16-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/c3d6f05c-fce6-4f16-8a11-8a441e680443"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/c3d6f05c-fce6-4f16-8a11-8a441e680443"}], "description": null}, {"id": "cf8eb717-8fb8-4f39-9f9a-041ad8de0d57", "name": "builder-s390x-cpu16-ram64-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/cf8eb717-8fb8-4f39-9f9a-041ad8de0d57"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/cf8eb717-8fb8-4f39-9f9a-041ad8de0d57"}], "description": null}, {"id": "d54672cc-318b-4196-b0e0-d2bca56a221c", "name": "builder-s390x-cpu8-ram8-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/d54672cc-318b-4196-b0e0-d2bca56a221c"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/d54672cc-318b-4196-b0e0-d2bca56a221c"}], "description": null}, {"id": "d888376b-4a42-456e-ad43-f53ddf7fdff6", "name": "builder-s390x-cpu4-ram32-disk20", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/d888376b-4a42-456e-ad43-f53ddf7fdff6"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/d888376b-4a42-456e-ad43-f53ddf7fdff6"}], "description": null}, {"id": "d99596f5-7a6c-43d9-87a2-e70196e44165", "name": "builder-s390x-cpu2-ram8-disk100", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/d99596f5-7a6c-43d9-87a2-e70196e44165"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/d99596f5-7a6c-43d9-87a2-e70196e44165"}], "description": null}, {"id": "e9cc765b-4f31-4512-8c37-135c93482f04", "name": "builder-s390x-cpu4-ram16-disk50", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/e9cc765b-4f31-4512-8c37-135c93482f04"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/e9cc765b-4f31-4512-8c37-135c93482f04"}], "description": null}, {"id": "f9f1f492-217b-44b0-8ae5-c600b7a2d2c5", "name": "builder-s390x-cpu8-ram12-disk20", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/f9f1f492-217b-44b0-8ae5-c600b7a2d2c5"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/f9f1f492-217b-44b0-8ae5-c600b7a2d2c5"}], "description": null}, {"id": "fe92de60-14a4-4d76-a8d0-aa915da2be82", "name": "builder-s390x-cpu2-ram8-disk20", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/fe92de60-14a4-4d76-a8d0-aa915da2be82"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/fe92de60-14a4-4d76-a8d0-aa915da2be82"}], "description": null}]} 1069s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/flavors?is_public=None used request id req-238542de-f56e-4131-b9e1-25a746538b65 1069s DEBUG (session:517) REQ: curl -g -i -X GET https://nova.ps6.canonical.com:8774/v2.1/flavors/5cac32a0-85d2-4049-8cd6-ce7164372b01 -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}3ebd0f8c8a29b6a214cc14eab4906148c681045957f71043482ab5e4f9d6798c" -H "X-OpenStack-Nova-API-Version: 2.87" 1069s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/flavors/5cac32a0-85d2-4049-8cd6-ce7164372b01 HTTP/1.1" 200 619 1069s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 619 Content-Type: application/json Date: Wed, 18 Jun 2025 18:06:29 GMT Keep-Alive: timeout=75, max=996 OpenStack-API-Version: compute 2.87 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.87 x-compute-request-id: req-f3a22bea-feab-4b62-a876-b95dd3de888c x-openstack-request-id: req-f3a22bea-feab-4b62-a876-b95dd3de888c 1069s DEBUG (session:580) RESP BODY: {"flavor": {"id": "5cac32a0-85d2-4049-8cd6-ce7164372b01", "name": "autopkgtest-s390x", "ram": 4096, "disk": 20, "swap": 0, "OS-FLV-EXT-DATA:ephemeral": 0, "OS-FLV-DISABLED:disabled": false, "vcpus": 2, "os-flavor-access:is_public": false, "rxtx_factor": 1.0, "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/flavors/5cac32a0-85d2-4049-8cd6-ce7164372b01"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/flavors/5cac32a0-85d2-4049-8cd6-ce7164372b01"}], "description": null, "extra_specs": {"aggregate_instance_extra_specs:commit": "builder-s390x", "hw_rng:allowed": "True"}}} 1069s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/flavors/5cac32a0-85d2-4049-8cd6-ce7164372b01 used request id req-f3a22bea-feab-4b62-a876-b95dd3de888c 1069s DEBUG (session:517) REQ: curl -g -i -X POST https://nova.ps6.canonical.com:8774/v2.1/servers -H "Accept: application/json" -H "Content-Type: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}3ebd0f8c8a29b6a214cc14eab4906148c681045957f71043482ab5e4f9d6798c" -H "X-OpenStack-Nova-API-Version: 2.87" -d '{"server": {"name": "adt-questing-s390x-django-cas-server-20250618-180440-juju-7f2275-prod-proposed-migration-environment-15-76f6903d-ab10-4a99-af27-134a831380a9", "imageRef": "3bd366b8-73fb-4dfa-8068-6fbfafb23f9b", "flavorRef": "5cac32a0-85d2-4049-8cd6-ce7164372b01", "user_data": "I2Nsb3VkLWNvbmZpZwpob3N0bmFtZTogYXV0b3BrZ3Rlc3QKZnFkbjogYXV0b3BrZ3Rlc3QubG9jYWwKbWFuYWdlX2V0Y19ob3N0czogdHJ1ZQphcHRfdXBkYXRlOiB0cnVlCmFwdF91cGdyYWRlOiBmYWxzZQphcHRfbWlycm9yOiBodHRwOi8vZnRwbWFzdGVyLmludGVybmFsL3VidW50dS8KCnJ1bmNtZDoKIC0gZWNobyAnQWNxdWlyZTo6TGFuZ3VhZ2VzICJub25lIjsnID4gL2V0Yy9hcHQvYXB0LmNvbmYuZC85MG5vbGFuZ3VhZ2VzCiAtIGVjaG8gJ2ZvcmNlLXVuc2FmZS1pbycgPiAvZXRjL2Rwa2cvZHBrZy5jZmcuZC9hdXRvcGtndGVzdAogLSBwcmludGYgJ1xuVEVSTT1saW51eFxuJyA+PiAvZXRjL2Vudmlyb25tZW50CiAtIHNlZCAtaSAtciAnL14xMjcuMC4xLjEvIHMvYXV0b3BrZ3Rlc3QtW14gXStcLi9hdXRvcGtndGVzdFwuLycgL2V0Yy9ob3N0cwo=", "key_name": "testbed-juju-7f2275-prod-proposed-migration-environment-15", "min_count": 1, "max_count": 1, "security_groups": [{"name": "autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-15.secgroup"}], "networks": [{"uuid": "e5b7295f-25e9-4d55-83b6-ed1971e76f4e"}]}}' 1069s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "POST /v2.1/servers HTTP/1.1" 202 477 1069s DEBUG (session:548) RESP: [202] Connection: Keep-Alive Content-Length: 477 Content-Type: application/json Date: Wed, 18 Jun 2025 18:06:29 GMT Keep-Alive: timeout=75, max=995 OpenStack-API-Version: compute 2.87 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.87 location: https://nova.ps6.canonical.com:8774/v2.1/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d x-compute-request-id: req-cdcee532-4124-4157-b1e1-5330c4d0828c x-openstack-request-id: req-cdcee532-4124-4157-b1e1-5330c4d0828c 1069s DEBUG (session:580) RESP BODY: {"server": {"id": "ecb1e7a3-d663-4ca5-bb62-97710dcec31d", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d"}], "OS-DCF:diskConfig": "MANUAL", "security_groups": [{"name": "autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-15.secgroup"}], "adminPass": "6wgsdBN6WpF6"}} 1069s DEBUG (session:936) POST call to compute for https://nova.ps6.canonical.com:8774/v2.1/servers used request id req-cdcee532-4124-4157-b1e1-5330c4d0828c 1069s DEBUG (session:517) REQ: curl -g -i -X GET https://nova.ps6.canonical.com:8774/v2.1/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}3ebd0f8c8a29b6a214cc14eab4906148c681045957f71043482ab5e4f9d6798c" -H "X-OpenStack-Nova-API-Version: 2.87" 1069s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d HTTP/1.1" 200 2637 1069s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 2637 Content-Type: application/json Date: Wed, 18 Jun 2025 18:06:30 GMT Keep-Alive: timeout=75, max=994 OpenStack-API-Version: compute 2.87 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.87 x-compute-request-id: req-2b96d33a-6506-4c20-9387-ce758d21dcac x-openstack-request-id: req-2b96d33a-6506-4c20-9387-ce758d21dcac 1069s DEBUG (session:580) RESP BODY: {"server": {"id": "ecb1e7a3-d663-4ca5-bb62-97710dcec31d", "name": "adt-questing-s390x-django-cas-server-20250618-180440-juju-7f2275-prod-proposed-migration-environment-15-76f6903d-ab10-4a99-af27-134a831380a9", "status": "BUILD", "tenant_id": "ad292334f14047cc96e9c50587dfe285", "user_id": "fda033b8c7ea463db1c3747f74a59d10", "metadata": {}, "hostId": "", "image": {"id": "3bd366b8-73fb-4dfa-8068-6fbfafb23f9b", "links": [{"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/images/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b"}]}, "flavor": {"vcpus": 2, "ram": 4096, "disk": 20, "ephemeral": 0, "swap": 0, "original_name": "autopkgtest-s390x", "extra_specs": {"aggregate_instance_extra_specs:commit": "builder-s390x", "hw_rng:allowed": "True"}}, "created": "2025-06-18T18:06:30Z", "updated": "2025-06-18T18:06:30Z", "addresses": {}, "accessIPv4": "", "accessIPv6": "", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d"}], "OS-DCF:diskConfig": "MANUAL", "progress": 0, "OS-EXT-AZ:availability_zone": "", "config_drive": "", "key_name": "testbed-juju-7f2275-prod-proposed-migration-environment-15", "OS-SRV-USG:launched_at": null, "OS-SRV-USG:terminated_at": null, "OS-EXT-SRV-ATTR:host": null, "OS-EXT-SRV-ATTR:instance_name": "", "OS-EXT-SRV-ATTR:hypervisor_hostname": null, "OS-EXT-SRV-ATTR:reservation_id": "r-zp1xe6b3", "OS-EXT-SRV-ATTR:launch_index": 0, "OS-EXT-SRV-ATTR:hostname": "adt-questing-s390x-django-cas-server-20250618-180440-juju-7f227", "OS-EXT-SRV-ATTR:kernel_id": "", "OS-EXT-SRV-ATTR:ramdisk_id": "", "OS-EXT-SRV-ATTR:root_device_name": null, "OS-EXT-SRV-ATTR:user_data": "I2Nsb3VkLWNvbmZpZwpob3N0bmFtZTogYXV0b3BrZ3Rlc3QKZnFkbjogYXV0b3BrZ3Rlc3QubG9jYWwKbWFuYWdlX2V0Y19ob3N0czogdHJ1ZQphcHRfdXBkYXRlOiB0cnVlCmFwdF91cGdyYWRlOiBmYWxzZQphcHRfbWlycm9yOiBodHRwOi8vZnRwbWFzdGVyLmludGVybmFsL3VidW50dS8KCnJ1bmNtZDoKIC0gZWNobyAnQWNxdWlyZTo6TGFuZ3VhZ2VzICJub25lIjsnID4gL2V0Yy9hcHQvYXB0LmNvbmYuZC85MG5vbGFuZ3VhZ2VzCiAtIGVjaG8gJ2ZvcmNlLXVuc2FmZS1pbycgPiAvZXRjL2Rwa2cvZHBrZy5jZmcuZC9hdXRvcGtndGVzdAogLSBwcmludGYgJ1xuVEVSTT1saW51eFxuJyA+PiAvZXRjL2Vudmlyb25tZW50CiAtIHNlZCAtaSAtciAnL14xMjcuMC4xLjEvIHMvYXV0b3BrZ3Rlc3QtW14gXStcLi9hdXRvcGtndGVzdFwuLycgL2V0Yy9ob3N0cwo=", "OS-EXT-STS:task_state": "scheduling", "OS-EXT-STS:vm_state": "building", "OS-EXT-STS:power_state": 0, "os-extended-volumes:volumes_attached": [], "locked": false, "locked_reason": null, "description": null, "tags": [], "trusted_image_certificates": null, "server_groups": []}} 1069s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d used request id req-2b96d33a-6506-4c20-9387-ce758d21dcac 1069s DEBUG (session:517) REQ: curl -g -i -X GET https://glance.ps6.canonical.com:9292/v2/images/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}3ebd0f8c8a29b6a214cc14eab4906148c681045957f71043482ab5e4f9d6798c" -H "X-OpenStack-Nova-API-Version: 2.87" 1069s DEBUG (connectionpool:429) https://glance.ps6.canonical.com:9292 "GET /v2/images/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b HTTP/1.1" 200 2261 1069s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 2261 Content-Type: application/json Date: Wed, 18 Jun 2025 18:06:30 GMT Keep-Alive: timeout=75, max=999 Server: Apache/2.4.52 (Ubuntu) X-Openstack-Request-Id: req-986e34c8-04ec-4f6c-ad08-451498aef46c 1069s DEBUG (session:580) RESP BODY: {"architecture": "s390x", "base_image_ref": "37828cef-4e21-48ba-b7d5-63f9eea03051", "boot_roles": "load-balancer_member,member,reader", "content_id": "auto.sync", "hw_cdrom_bus": "scsi", "hw_disk_bus": "virtio", "hw_input_bus": "usb", "hw_machine_type": "s390-ccw-virtio", "hw_pointer_model": "usbtablet", "hw_vif_model": "virtio", "image_location": "snapshot", "image_state": "available", "image_type": "snapshot", "instance_uuid": "2d52e14f-9827-4b3b-bb92-b6c38616fd7d", "item_name": "disk1.img", "os_distro": "ubuntu", "os_version": "25.04", "owner_id": "ad292334f14047cc96e9c50587dfe285", "owner_project_name": "prod-proposed-migration-s390x_project", "owner_user_name": "prod-proposed-migration-s390x", "product_name": "com.ubuntu.cloud.daily:server:25.04:s390x", "simplestreams_metadata": "{\"aliases\": \"25.04,p,plucky,devel\", \"arch\": \"s390x\", \"ftype\": \"disk1.img\", \"label\": \"daily\", \"md5\": \"e18cf68b0057508b4f373becf7e386ce\", \"os\": \"ubuntu\", \"pubname\": \"ubuntu-plucky-daily-s390x-server-20250113\", \"release\": \"plucky\", \"release_codename\": \"Pluck", "source_content_id": "com.ubuntu.cloud:daily:download", "user_id": "fda033b8c7ea463db1c3747f74a59d10", "version_name": "20250113", "name": "adt/ubuntu-questing-s390x-server-20250618.img", "disk_format": "qcow2", "container_format": "bare", "visibility": "private", "size": 2229469184, "virtual_size": 21474836480, "status": "active", "checksum": "ecda1cfdf4487cfc71eba92a53b62329", "protected": false, "min_ram": 0, "min_disk": 20, "owner": "ad292334f14047cc96e9c50587dfe285", "os_hidden": false, "os_hash_algo": "sha256", "os_hash_value": "f204599aae983ea086086e3a4ce1baeeab9d437471a6a70dfea3a12167facab2", "id": "3bd366b8-73fb-4dfa-8068-6fbfafb23f9b", "created_at": "2025-06-18T06:19:54Z", "updated_at": "2025-06-18T06:20:19Z", "locations": [{"url": "rbd://eea9d068-c18c-11ed-8dc0-013aacb71b80/glance/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b/snap", "metadata": {"store": "ceph"}}], "direct_url": "rbd://eea9d068-c18c-11ed-8dc0-013aacb71b80/glance/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b/snap", "tags": [], "self": "/v2/images/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b", "file": "/v2/images/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b/file", "schema": "/v2/schemas/image", "stores": "ceph"} 1069s DEBUG (session:936) GET call to image for https://glance.ps6.canonical.com:9292/v2/images/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b used request id req-986e34c8-04ec-4f6c-ad08-451498aef46c 1069s +--------------------------------------+----------------------------------------------------------------------------------------------------------------------------------------------+ 1069s | Property | Value | 1069s +--------------------------------------+----------------------------------------------------------------------------------------------------------------------------------------------+ 1069s | OS-DCF:diskConfig | MANUAL | 1069s | OS-EXT-AZ:availability_zone | | 1069s | OS-EXT-SRV-ATTR:host | - | 1069s | OS-EXT-SRV-ATTR:hostname | adt-questing-s390x-django-cas-server-20250618-180440-juju-7f227 | 1069s | OS-EXT-SRV-ATTR:hypervisor_hostname | - | 1069s | OS-EXT-SRV-ATTR:instance_name | | 1069s | OS-EXT-SRV-ATTR:kernel_id | | 1069s | OS-EXT-SRV-ATTR:launch_index | 0 | 1069s | OS-EXT-SRV-ATTR:ramdisk_id | | 1069s | OS-EXT-SRV-ATTR:reservation_id | r-zp1xe6b3 | 1069s | OS-EXT-SRV-ATTR:root_device_name | - | 1069s | OS-EXT-STS:power_state | 0 | 1069s | OS-EXT-STS:task_state | scheduling | 1069s | OS-EXT-STS:vm_state | building | 1069s | OS-SRV-USG:launched_at | - | 1069s | OS-SRV-USG:terminated_at | - | 1069s | accessIPv4 | | 1069s | accessIPv6 | | 1069s | adminPass | 6wgsdBN6WpF6 | 1069s | config_drive | | 1069s | created | 2025-06-18T18:06:30Z | 1069s | description | - | 1069s | flavor:disk | 20 | 1069s | flavor:ephemeral | 0 | 1069s | flavor:extra_specs | {"aggregate_instance_extra_specs:commit": "builder-s390x", "hw_rng:allowed": "True"} | 1069s | flavor:original_name | autopkgtest-s390x | 1069s | flavor:ram | 4096 | 1069s | flavor:swap | 0 | 1069s | flavor:vcpus | 2 | 1069s | hostId | | 1069s | id | ecb1e7a3-d663-4ca5-bb62-97710dcec31d | 1069s | image | adt/ubuntu-questing-s390x-server-20250618.img (3bd366b8-73fb-4dfa-8068-6fbfafb23f9b) | 1069s | key_name | testbed-juju-7f2275-prod-proposed-migration-environment-15 | 1069s | locked | False | 1069s | locked_reason | - | 1069s | metadata | {} | 1069s | name | adt-questing-s390x-django-cas-server-20250618-180440-juju-7f2275-prod-proposed-migration-environment-15-76f6903d-ab10-4a99-af27-134a831380a9 | 1069s | os-extended-volumes:volumes_attached | [] | 1069s | progress | 0 | 1069s | security_groups | autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-15.secgroup | 1069s | server_groups | [] | 1069s | status | BUILD | 1069s | tags | [] | 1069s | tenant_id | ad292334f14047cc96e9c50587dfe285 | 1069s | trusted_image_certificates | - | 1069s | updated | 2025-06-18T18:06:30Z | 1069s | user_id | fda033b8c7ea463db1c3747f74a59d10 | 1069s +--------------------------------------+----------------------------------------------------------------------------------------------------------------------------------------------+DEBUG (session:517) REQ: curl -g -i -X GET https://nova.ps6.canonical.com:8774/v2.1/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}3ebd0f8c8a29b6a214cc14eab4906148c681045957f71043482ab5e4f9d6798c" -H "X-OpenStack-Nova-API-Version: 2.87" 1069s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d HTTP/1.1" 200 2637 1069s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 2637 Content-Type: application/json Date: Wed, 18 Jun 2025 18:06:30 GMT Keep-Alive: timeout=75, max=993 OpenStack-API-Version: compute 2.87 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.87 x-compute-request-id: req-c1622dd2-87ea-4e25-a04e-a0fe3dfcab65 x-openstack-request-id: req-c1622dd2-87ea-4e25-a04e-a0fe3dfcab65 1069s DEBUG (session:580) RESP BODY: {"server": {"id": "ecb1e7a3-d663-4ca5-bb62-97710dcec31d", "name": "adt-questing-s390x-django-cas-server-20250618-180440-juju-7f2275-prod-proposed-migration-environment-15-76f6903d-ab10-4a99-af27-134a831380a9", "status": "BUILD", "tenant_id": "ad292334f14047cc96e9c50587dfe285", "user_id": "fda033b8c7ea463db1c3747f74a59d10", "metadata": {}, "hostId": "", "image": {"id": "3bd366b8-73fb-4dfa-8068-6fbfafb23f9b", "links": [{"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/images/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b"}]}, "flavor": {"vcpus": 2, "ram": 4096, "disk": 20, "ephemeral": 0, "swap": 0, "original_name": "autopkgtest-s390x", "extra_specs": {"aggregate_instance_extra_specs:commit": "builder-s390x", "hw_rng:allowed": "True"}}, "created": "2025-06-18T18:06:30Z", "updated": "2025-06-18T18:06:31Z", "addresses": {}, "accessIPv4": "", "accessIPv6": "", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d"}], "OS-DCF:diskConfig": "MANUAL", "progress": 0, "OS-EXT-AZ:availability_zone": "", "config_drive": "", "key_name": "testbed-juju-7f2275-prod-proposed-migration-environment-15", "OS-SRV-USG:launched_at": null, "OS-SRV-USG:terminated_at": null, "OS-EXT-SRV-ATTR:host": null, "OS-EXT-SRV-ATTR:instance_name": "", "OS-EXT-SRV-ATTR:hypervisor_hostname": null, "OS-EXT-SRV-ATTR:reservation_id": "r-zp1xe6b3", "OS-EXT-SRV-ATTR:launch_index": 0, "OS-EXT-SRV-ATTR:hostname": "adt-questing-s390x-django-cas-server-20250618-180440-juju-7f227", "OS-EXT-SRV-ATTR:kernel_id": "", "OS-EXT-SRV-ATTR:ramdisk_id": "", "OS-EXT-SRV-ATTR:root_device_name": null, "OS-EXT-SRV-ATTR:user_data": "I2Nsb3VkLWNvbmZpZwpob3N0bmFtZTogYXV0b3BrZ3Rlc3QKZnFkbjogYXV0b3BrZ3Rlc3QubG9jYWwKbWFuYWdlX2V0Y19ob3N0czogdHJ1ZQphcHRfdXBkYXRlOiB0cnVlCmFwdF91cGdyYWRlOiBmYWxzZQphcHRfbWlycm9yOiBodHRwOi8vZnRwbWFzdGVyLmludGVybmFsL3VidW50dS8KCnJ1bmNtZDoKIC0gZWNobyAnQWNxdWlyZTo6TGFuZ3VhZ2VzICJub25lIjsnID4gL2V0Yy9hcHQvYXB0LmNvbmYuZC85MG5vbGFuZ3VhZ2VzCiAtIGVjaG8gJ2ZvcmNlLXVuc2FmZS1pbycgPiAvZXRjL2Rwa2cvZHBrZy5jZmcuZC9hdXRvcGtndGVzdAogLSBwcmludGYgJ1xuVEVSTT1saW51eFxuJyA+PiAvZXRjL2Vudmlyb25tZW50CiAtIHNlZCAtaSAtciAnL14xMjcuMC4xLjEvIHMvYXV0b3BrZ3Rlc3QtW14gXStcLi9hdXRvcGtndGVzdFwuLycgL2V0Yy9ob3N0cwo=", "OS-EXT-STS:task_state": "scheduling", "OS-EXT-STS:vm_state": "building", "OS-EXT-STS:power_state": 0, "os-extended-volumes:volumes_attached": [], "locked": false, "locked_reason": null, "description": null, "tags": [], "trusted_image_certificates": null, "server_groups": []}} 1069s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d used request id req-c1622dd2-87ea-4e25-a04e-a0fe3dfcab65 1069s 1069s 1069s Server building... 0% completeDEBUG (session:517) REQ: curl -g -i -X GET https://nova.ps6.canonical.com:8774/v2.1/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d -H "Accept: application/json" -H "OpenStack-API-Version: compute 2.87" -H "User-Agent: python-novaclient" -H "X-Auth-Token: {SHA256}3ebd0f8c8a29b6a214cc14eab4906148c681045957f71043482ab5e4f9d6798c" -H "X-OpenStack-Nova-API-Version: 2.87" 1069s DEBUG (connectionpool:429) https://nova.ps6.canonical.com:8774 "GET /v2.1/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d HTTP/1.1" 200 2764 1069s DEBUG (session:548) RESP: [200] Connection: Keep-Alive Content-Length: 2764 Content-Type: application/json Date: Wed, 18 Jun 2025 18:06:36 GMT Keep-Alive: timeout=75, max=992 OpenStack-API-Version: compute 2.87 Server: Apache/2.4.52 (Ubuntu) Vary: OpenStack-API-Version,X-OpenStack-Nova-API-Version X-OpenStack-Nova-API-Version: 2.87 x-compute-request-id: req-fad0c679-0a69-499a-b6e6-25889a0b24ad x-openstack-request-id: req-fad0c679-0a69-499a-b6e6-25889a0b24ad 1069s DEBUG (session:580) RESP BODY: {"server": {"id": "ecb1e7a3-d663-4ca5-bb62-97710dcec31d", "name": "adt-questing-s390x-django-cas-server-20250618-180440-juju-7f2275-prod-proposed-migration-environment-15-76f6903d-ab10-4a99-af27-134a831380a9", "status": "ERROR", "tenant_id": "ad292334f14047cc96e9c50587dfe285", "user_id": "fda033b8c7ea463db1c3747f74a59d10", "metadata": {}, "hostId": "", "image": {"id": "3bd366b8-73fb-4dfa-8068-6fbfafb23f9b", "links": [{"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/images/3bd366b8-73fb-4dfa-8068-6fbfafb23f9b"}]}, "flavor": {"vcpus": 2, "ram": 4096, "disk": 20, "ephemeral": 0, "swap": 0, "original_name": "autopkgtest-s390x", "extra_specs": {"aggregate_instance_extra_specs:commit": "builder-s390x", "hw_rng:allowed": "True"}}, "created": "2025-06-18T18:06:30Z", "updated": "2025-06-18T18:06:31Z", "addresses": {}, "accessIPv4": "", "accessIPv6": "", "links": [{"rel": "self", "href": "https://nova.ps6.canonical.com:8774/v2.1/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d"}, {"rel": "bookmark", "href": "https://nova.ps6.canonical.com:8774/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d"}], "OS-DCF:diskConfig": "MANUAL", "fault": {"code": 500, "created": "2025-06-18T18:06:31Z", "message": "No valid host was found. There are not enough hosts available."}, "OS-EXT-AZ:availability_zone": "", "config_drive": "", "key_name": "testbed-juju-7f2275-prod-proposed-migration-environment-15", "OS-SRV-USG:launched_at": null, "OS-SRV-USG:terminated_at": null, "OS-EXT-SRV-ATTR:host": null, "OS-EXT-SRV-ATTR:instance_name": "instance-0011b5d2", "OS-EXT-SRV-ATTR:hypervisor_hostname": null, "OS-EXT-SRV-ATTR:reservation_id": "r-zp1xe6b3", "OS-EXT-SRV-ATTR:launch_index": 0, "OS-EXT-SRV-ATTR:hostname": "adt-questing-s390x-django-cas-server-20250618-180440-juju-7f227", "OS-EXT-SRV-ATTR:kernel_id": "", "OS-EXT-SRV-ATTR:ramdisk_id": "", "OS-EXT-SRV-ATTR:root_device_name": null, "OS-EXT-SRV-ATTR:user_data": "I2Nsb3VkLWNvbmZpZwpob3N0bmFtZTogYXV0b3BrZ3Rlc3QKZnFkbjogYXV0b3BrZ3Rlc3QubG9jYWwKbWFuYWdlX2V0Y19ob3N0czogdHJ1ZQphcHRfdXBkYXRlOiB0cnVlCmFwdF91cGdyYWRlOiBmYWxzZQphcHRfbWlycm9yOiBodHRwOi8vZnRwbWFzdGVyLmludGVybmFsL3VidW50dS8KCnJ1bmNtZDoKIC0gZWNobyAnQWNxdWlyZTo6TGFuZ3VhZ2VzICJub25lIjsnID4gL2V0Yy9hcHQvYXB0LmNvbmYuZC85MG5vbGFuZ3VhZ2VzCiAtIGVjaG8gJ2ZvcmNlLXVuc2FmZS1pbycgPiAvZXRjL2Rwa2cvZHBrZy5jZmcuZC9hdXRvcGtndGVzdAogLSBwcmludGYgJ1xuVEVSTT1saW51eFxuJyA+PiAvZXRjL2Vudmlyb25tZW50CiAtIHNlZCAtaSAtciAnL14xMjcuMC4xLjEvIHMvYXV0b3BrZ3Rlc3QtW14gXStcLi9hdXRvcGtndGVzdFwuLycgL2V0Yy9ob3N0cwo=", "OS-EXT-STS:task_state": null, "OS-EXT-STS:vm_state": "error", "OS-EXT-STS:power_state": 0, "os-extended-volumes:volumes_attached": [], "locked": false, "locked_reason": null, "description": null, "tags": [], "trusted_image_certificates": null, "server_groups": []}} 1069s DEBUG (session:936) GET call to compute for https://nova.ps6.canonical.com:8774/v2.1/servers/ecb1e7a3-d663-4ca5-bb62-97710dcec31d used request id req-fad0c679-0a69-499a-b6e6-25889a0b24ad 1069s DEBUG (shell:822) 1069s Traceback (most recent call last): 1069s File "/usr/lib/python3/dist-packages/novaclient/shell.py", line 820, in main 1069s OpenStackComputeShell().main(argv) 1069s File "/usr/lib/python3/dist-packages/novaclient/shell.py", line 742, in main 1069s args.func(self.cs, args) 1069s File "/usr/lib/python3/dist-packages/novaclient/v2/shell.py", line 980, in do_boot 1069s _poll_for_status(cs.servers.get, server.id, 'building', ['active']) 1069s File "/usr/lib/python3/dist-packages/novaclient/v2/shell.py", line 1019, in _poll_for_status 1069s raise exceptions.ResourceInErrorState(obj) 1069s novaclient.exceptions.ResourceInErrorState: 1069s ERROR (ResourceInErrorState): 1069s 1069s Error building server 1072s autopkgtest [18:22:32]: testbed dpkg architecture: s390x 1072s autopkgtest [18:22:32]: testbed apt version: 3.1.2 1072s autopkgtest [18:22:32]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1072s autopkgtest [18:22:32]: testbed release detected to be: None 1073s autopkgtest [18:22:33]: updating testbed package index (apt update) 1073s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 1074s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 1074s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 1074s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 1074s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [406 kB] 1074s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [16.8 kB] 1074s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/restricted Sources [4716 B] 1074s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [34.3 kB] 1075s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [55.1 kB] 1075s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/restricted s390x Packages [716 B] 1075s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [337 kB] 1075s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [5892 B] 1075s Fetched 1109 kB in 2s (652 kB/s) 1076s Reading package lists... 1076s autopkgtest [18:22:36]: upgrading testbed (apt dist-upgrade and autopurge) 1076s Reading package lists... 1076s Building dependency tree... 1076s Reading state information... 1076s Calculating upgrade... 1076s The following packages will be upgraded: 1076s libblockdev-crypto3 libblockdev-fs3 libblockdev-loop3 libblockdev-mdraid3 1076s libblockdev-nvme3 libblockdev-part3 libblockdev-swap3 libblockdev-utils3 1076s libblockdev3 libpng16-16t64 libpython3.12-minimal libpython3.12-stdlib 1076s libpython3.12t64 libssl3t64 openssl openssl-provider-legacy python3-requests 1077s 17 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1077s Need to get 9141 kB of archives. 1077s After this operation, 458 kB of additional disk space will be used. 1077s Get:1 http://ftpmaster.internal/ubuntu questing/main s390x openssl-provider-legacy s390x 3.5.0-2ubuntu1 [38.6 kB] 1077s Get:2 http://ftpmaster.internal/ubuntu questing/main s390x libssl3t64 s390x 3.5.0-2ubuntu1 [2017 kB] 1078s Get:3 http://ftpmaster.internal/ubuntu questing/main s390x openssl s390x 3.5.0-2ubuntu1 [1244 kB] 1079s Get:4 http://ftpmaster.internal/ubuntu questing/main s390x libpng16-16t64 s390x 1.6.49-1~exp1 [199 kB] 1079s Get:5 http://ftpmaster.internal/ubuntu questing/main s390x libblockdev-utils3 s390x 3.3.0-2.1 [19.5 kB] 1079s Get:6 http://ftpmaster.internal/ubuntu questing/main s390x libblockdev-crypto3 s390x 3.3.0-2.1 [23.8 kB] 1079s Get:7 http://ftpmaster.internal/ubuntu questing/main s390x libblockdev-fs3 s390x 3.3.0-2.1 [36.2 kB] 1079s Get:8 http://ftpmaster.internal/ubuntu questing/main s390x libblockdev-loop3 s390x 3.3.0-2.1 [7116 B] 1079s Get:9 http://ftpmaster.internal/ubuntu questing/main s390x libblockdev-mdraid3 s390x 3.3.0-2.1 [12.8 kB] 1079s Get:10 http://ftpmaster.internal/ubuntu questing/main s390x libblockdev-nvme3 s390x 3.3.0-2.1 [18.2 kB] 1079s Get:11 http://ftpmaster.internal/ubuntu questing/main s390x libblockdev-part3 s390x 3.3.0-2.1 [15.3 kB] 1079s Get:12 http://ftpmaster.internal/ubuntu questing/main s390x libblockdev-swap3 s390x 3.3.0-2.1 [7758 B] 1079s Get:13 http://ftpmaster.internal/ubuntu questing/main s390x libblockdev3 s390x 3.3.0-2.1 [54.0 kB] 1079s Get:14 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12t64 s390x 3.12.10-1 [2509 kB] 1080s Get:15 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12-stdlib s390x 3.12.10-1 [2049 kB] 1080s Get:16 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x libpython3.12-minimal s390x 3.12.10-1 [836 kB] 1081s Get:17 http://ftpmaster.internal/ubuntu questing-proposed/main s390x python3-requests all 2.32.3+dfsg-5ubuntu2 [53.1 kB] 1081s Fetched 9141 kB in 4s (2260 kB/s) 1081s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 82210 files and directories currently installed.) 1081s Preparing to unpack .../openssl-provider-legacy_3.5.0-2ubuntu1_s390x.deb ... 1081s Unpacking openssl-provider-legacy (3.5.0-2ubuntu1) over (3.4.1-1ubuntu3) ... 1081s Setting up openssl-provider-legacy (3.5.0-2ubuntu1) ... 1081s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 82210 files and directories currently installed.) 1081s Preparing to unpack .../libssl3t64_3.5.0-2ubuntu1_s390x.deb ... 1081s Unpacking libssl3t64:s390x (3.5.0-2ubuntu1) over (3.4.1-1ubuntu3) ... 1081s Setting up libssl3t64:s390x (3.5.0-2ubuntu1) ... 1081s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 82209 files and directories currently installed.) 1081s Preparing to unpack .../00-openssl_3.5.0-2ubuntu1_s390x.deb ... 1081s Unpacking openssl (3.5.0-2ubuntu1) over (3.4.1-1ubuntu3) ... 1081s Preparing to unpack .../01-libpng16-16t64_1.6.49-1~exp1_s390x.deb ... 1081s Unpacking libpng16-16t64:s390x (1.6.49-1~exp1) over (1.6.48-1) ... 1081s Preparing to unpack .../02-libblockdev-utils3_3.3.0-2.1_s390x.deb ... 1081s Unpacking libblockdev-utils3:s390x (3.3.0-2.1) over (3.3.0-2) ... 1081s Preparing to unpack .../03-libblockdev-crypto3_3.3.0-2.1_s390x.deb ... 1081s Unpacking libblockdev-crypto3:s390x (3.3.0-2.1) over (3.3.0-2) ... 1081s Preparing to unpack .../04-libblockdev-fs3_3.3.0-2.1_s390x.deb ... 1081s Unpacking libblockdev-fs3:s390x (3.3.0-2.1) over (3.3.0-2) ... 1081s Preparing to unpack .../05-libblockdev-loop3_3.3.0-2.1_s390x.deb ... 1081s Unpacking libblockdev-loop3:s390x (3.3.0-2.1) over (3.3.0-2) ... 1081s Preparing to unpack .../06-libblockdev-mdraid3_3.3.0-2.1_s390x.deb ... 1081s Unpacking libblockdev-mdraid3:s390x (3.3.0-2.1) over (3.3.0-2) ... 1081s Preparing to unpack .../07-libblockdev-nvme3_3.3.0-2.1_s390x.deb ... 1081s Unpacking libblockdev-nvme3:s390x (3.3.0-2.1) over (3.3.0-2) ... 1081s Preparing to unpack .../08-libblockdev-part3_3.3.0-2.1_s390x.deb ... 1081s Unpacking libblockdev-part3:s390x (3.3.0-2.1) over (3.3.0-2) ... 1081s Preparing to unpack .../09-libblockdev-swap3_3.3.0-2.1_s390x.deb ... 1081s Unpacking libblockdev-swap3:s390x (3.3.0-2.1) over (3.3.0-2) ... 1081s Preparing to unpack .../10-libblockdev3_3.3.0-2.1_s390x.deb ... 1081s Unpacking libblockdev3:s390x (3.3.0-2.1) over (3.3.0-2) ... 1081s Preparing to unpack .../11-libpython3.12t64_3.12.10-1_s390x.deb ... 1081s Unpacking libpython3.12t64:s390x (3.12.10-1) over (3.12.8-3) ... 1081s Preparing to unpack .../12-libpython3.12-stdlib_3.12.10-1_s390x.deb ... 1081s Unpacking libpython3.12-stdlib:s390x (3.12.10-1) over (3.12.8-3) ... 1081s Preparing to unpack .../13-libpython3.12-minimal_3.12.10-1_s390x.deb ... 1081s Unpacking libpython3.12-minimal:s390x (3.12.10-1) over (3.12.8-3) ... 1081s Preparing to unpack .../14-python3-requests_2.32.3+dfsg-5ubuntu2_all.deb ... 1082s Unpacking python3-requests (2.32.3+dfsg-5ubuntu2) over (2.32.3+dfsg-5ubuntu1) ... 1082s Setting up libpython3.12-minimal:s390x (3.12.10-1) ... 1082s Setting up python3-requests (2.32.3+dfsg-5ubuntu2) ... 1082s Setting up libblockdev-utils3:s390x (3.3.0-2.1) ... 1082s Setting up libpng16-16t64:s390x (1.6.49-1~exp1) ... 1082s Setting up libblockdev-nvme3:s390x (3.3.0-2.1) ... 1082s Setting up libblockdev-fs3:s390x (3.3.0-2.1) ... 1082s Setting up openssl (3.5.0-2ubuntu1) ... 1082s Setting up libpython3.12-stdlib:s390x (3.12.10-1) ... 1082s Setting up libblockdev-mdraid3:s390x (3.3.0-2.1) ... 1082s Setting up libblockdev-crypto3:s390x (3.3.0-2.1) ... 1082s Setting up libblockdev-swap3:s390x (3.3.0-2.1) ... 1082s Setting up libblockdev-loop3:s390x (3.3.0-2.1) ... 1082s Setting up libblockdev3:s390x (3.3.0-2.1) ... 1082s Setting up libblockdev-part3:s390x (3.3.0-2.1) ... 1082s Setting up libpython3.12t64:s390x (3.12.10-1) ... 1082s Processing triggers for man-db (2.13.1-1) ... 1082s Processing triggers for libc-bin (2.41-6ubuntu2) ... 1083s Reading package lists... 1083s Building dependency tree... 1083s Reading state information... 1083s Solving dependencies... 1083s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1085s autopkgtest [18:22:45]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr 6 13:39:00 UTC 2025 1085s autopkgtest [18:22:45]: @@@@@@@@@@@@@@@@@@@@ apt-source django-cas-server 1087s Get:1 http://ftpmaster.internal/ubuntu questing/universe django-cas-server 2.1.0-3 (dsc) [2597 B] 1087s Get:2 http://ftpmaster.internal/ubuntu questing/universe django-cas-server 2.1.0-3 (tar) [138 kB] 1087s Get:3 http://ftpmaster.internal/ubuntu questing/universe django-cas-server 2.1.0-3 (diff) [10.2 kB] 1087s gpgv: Signature made Sat Dec 14 20:43:18 2024 UTC 1087s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 1087s gpgv: issuer "tchet@debian.org" 1087s gpgv: Can't check signature: No public key 1087s dpkg-source: warning: cannot verify inline signature for ./django-cas-server_2.1.0-3.dsc: no acceptable signature found 1087s autopkgtest [18:22:47]: testing package django-cas-server version 2.1.0-3 1087s autopkgtest [18:22:47]: build not needed 1088s autopkgtest [18:22:48]: test run-test-suite.sh: preparing testbed 1088s Reading package lists... 1088s Building dependency tree... 1088s Reading state information... 1088s Solving dependencies... 1088s The following NEW packages will be installed: 1088s fonts-font-awesome fonts-lato libjs-jquery libjs-sphinxdoc libjs-underscore 1088s libxslt1.1 python3-all python3-asgiref python3-django 1088s python3-django-cas-server python3-django-cas-server-doc python3-iniconfig 1088s python3-legacy-cgi python3-lxml python3-packaging python3-pluggy 1088s python3-pytest python3-pytest-django python3-requests-futures 1088s python3-sqlparse sphinx-rtd-theme-common 1088s 0 upgraded, 21 newly installed, 0 to remove and 0 not upgraded. 1088s Need to get 10000 kB of archives. 1088s After this operation, 52.0 MB of additional disk space will be used. 1088s Get:1 http://ftpmaster.internal/ubuntu questing/main s390x fonts-lato all 2.015-1 [2781 kB] 1090s Get:2 http://ftpmaster.internal/ubuntu questing/main s390x fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 1090s Get:3 http://ftpmaster.internal/ubuntu questing/main s390x libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 1090s Get:4 http://ftpmaster.internal/ubuntu questing/main s390x libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 1090s Get:5 http://ftpmaster.internal/ubuntu questing/main s390x libjs-sphinxdoc all 8.2.3-1ubuntu2 [28.0 kB] 1090s Get:6 http://ftpmaster.internal/ubuntu questing/main s390x libxslt1.1 s390x 1.1.43-0exp1 [163 kB] 1090s Get:7 http://ftpmaster.internal/ubuntu questing/main s390x python3-all s390x 3.13.4-1 [880 B] 1090s Get:8 http://ftpmaster.internal/ubuntu questing/main s390x python3-asgiref all 3.8.1-1 [24.8 kB] 1090s Get:9 http://ftpmaster.internal/ubuntu questing/main s390x python3-sqlparse all 0.5.3-1 [32.9 kB] 1090s Get:10 http://ftpmaster.internal/ubuntu questing/main s390x python3-django all 3:4.2.18-1ubuntu1.1 [2918 kB] 1090s Get:11 http://ftpmaster.internal/ubuntu questing/main s390x python3-legacy-cgi all 2.6.3-1 [16.8 kB] 1090s Get:12 http://ftpmaster.internal/ubuntu questing/main s390x python3-lxml s390x 5.4.0-1build1 [1409 kB] 1090s Get:13 http://ftpmaster.internal/ubuntu questing/universe s390x python3-requests-futures all 1.0.2-1 [12.0 kB] 1090s Get:14 http://ftpmaster.internal/ubuntu questing/universe s390x python3-django-cas-server all 2.1.0-3 [111 kB] 1090s Get:15 http://ftpmaster.internal/ubuntu questing/main s390x sphinx-rtd-theme-common all 3.0.2+dfsg-3 [1013 kB] 1091s Get:16 http://ftpmaster.internal/ubuntu questing/universe s390x python3-django-cas-server-doc all 2.1.0-3 [178 kB] 1091s Get:17 http://ftpmaster.internal/ubuntu questing/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 1091s Get:18 http://ftpmaster.internal/ubuntu questing/main s390x python3-packaging all 25.0-1 [52.8 kB] 1091s Get:19 http://ftpmaster.internal/ubuntu questing/universe s390x python3-pluggy all 1.5.0-1 [21.0 kB] 1091s Get:20 http://ftpmaster.internal/ubuntu questing/universe s390x python3-pytest all 8.3.5-2 [252 kB] 1091s Get:21 http://ftpmaster.internal/ubuntu questing/universe s390x python3-pytest-django all 4.5.2-3 [18.5 kB] 1091s Fetched 10000 kB in 2s (4331 kB/s) 1091s Selecting previously unselected package fonts-lato. 1091s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 82262 files and directories currently installed.) 1091s Preparing to unpack .../00-fonts-lato_2.015-1_all.deb ... 1091s Unpacking fonts-lato (2.015-1) ... 1091s Selecting previously unselected package fonts-font-awesome. 1091s Preparing to unpack .../01-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 1091s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 1091s Selecting previously unselected package libjs-jquery. 1091s Preparing to unpack .../02-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 1091s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 1091s Selecting previously unselected package libjs-underscore. 1091s Preparing to unpack .../03-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 1091s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 1091s Selecting previously unselected package libjs-sphinxdoc. 1091s Preparing to unpack .../04-libjs-sphinxdoc_8.2.3-1ubuntu2_all.deb ... 1091s Unpacking libjs-sphinxdoc (8.2.3-1ubuntu2) ... 1091s Selecting previously unselected package libxslt1.1:s390x. 1091s Preparing to unpack .../05-libxslt1.1_1.1.43-0exp1_s390x.deb ... 1091s Unpacking libxslt1.1:s390x (1.1.43-0exp1) ... 1091s Selecting previously unselected package python3-all. 1091s Preparing to unpack .../06-python3-all_3.13.4-1_s390x.deb ... 1091s Unpacking python3-all (3.13.4-1) ... 1091s Selecting previously unselected package python3-asgiref. 1091s Preparing to unpack .../07-python3-asgiref_3.8.1-1_all.deb ... 1091s Unpacking python3-asgiref (3.8.1-1) ... 1091s Selecting previously unselected package python3-sqlparse. 1091s Preparing to unpack .../08-python3-sqlparse_0.5.3-1_all.deb ... 1091s Unpacking python3-sqlparse (0.5.3-1) ... 1091s Selecting previously unselected package python3-django. 1091s Preparing to unpack .../09-python3-django_3%3a4.2.18-1ubuntu1.1_all.deb ... 1091s Unpacking python3-django (3:4.2.18-1ubuntu1.1) ... 1091s Selecting previously unselected package python3-legacy-cgi. 1091s Preparing to unpack .../10-python3-legacy-cgi_2.6.3-1_all.deb ... 1091s Unpacking python3-legacy-cgi (2.6.3-1) ... 1091s Selecting previously unselected package python3-lxml:s390x. 1091s Preparing to unpack .../11-python3-lxml_5.4.0-1build1_s390x.deb ... 1091s Unpacking python3-lxml:s390x (5.4.0-1build1) ... 1091s Selecting previously unselected package python3-requests-futures. 1091s Preparing to unpack .../12-python3-requests-futures_1.0.2-1_all.deb ... 1091s Unpacking python3-requests-futures (1.0.2-1) ... 1091s Selecting previously unselected package python3-django-cas-server. 1091s Preparing to unpack .../13-python3-django-cas-server_2.1.0-3_all.deb ... 1091s Unpacking python3-django-cas-server (2.1.0-3) ... 1092s Selecting previously unselected package sphinx-rtd-theme-common. 1092s Preparing to unpack .../14-sphinx-rtd-theme-common_3.0.2+dfsg-3_all.deb ... 1092s Unpacking sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 1092s Selecting previously unselected package python3-django-cas-server-doc. 1092s Preparing to unpack .../15-python3-django-cas-server-doc_2.1.0-3_all.deb ... 1092s Unpacking python3-django-cas-server-doc (2.1.0-3) ... 1092s Selecting previously unselected package python3-iniconfig. 1092s Preparing to unpack .../16-python3-iniconfig_1.1.1-2_all.deb ... 1092s Unpacking python3-iniconfig (1.1.1-2) ... 1092s Selecting previously unselected package python3-packaging. 1092s Preparing to unpack .../17-python3-packaging_25.0-1_all.deb ... 1092s Unpacking python3-packaging (25.0-1) ... 1092s Selecting previously unselected package python3-pluggy. 1092s Preparing to unpack .../18-python3-pluggy_1.5.0-1_all.deb ... 1092s Unpacking python3-pluggy (1.5.0-1) ... 1092s Selecting previously unselected package python3-pytest. 1092s Preparing to unpack .../19-python3-pytest_8.3.5-2_all.deb ... 1092s Unpacking python3-pytest (8.3.5-2) ... 1092s Selecting previously unselected package python3-pytest-django. 1092s Preparing to unpack .../20-python3-pytest-django_4.5.2-3_all.deb ... 1092s Unpacking python3-pytest-django (4.5.2-3) ... 1092s Setting up python3-iniconfig (1.1.1-2) ... 1092s Setting up fonts-lato (2.015-1) ... 1092s Setting up python3-all (3.13.4-1) ... 1092s Setting up python3-packaging (25.0-1) ... 1092s Setting up python3-sqlparse (0.5.3-1) ... 1092s Setting up python3-pluggy (1.5.0-1) ... 1092s Setting up python3-legacy-cgi (2.6.3-1) ... 1092s Setting up libxslt1.1:s390x (1.1.43-0exp1) ... 1092s Setting up python3-requests-futures (1.0.2-1) ... 1092s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 1092s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 1092s Setting up sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 1092s Setting up python3-asgiref (3.8.1-1) ... 1092s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 1092s Setting up python3-django (3:4.2.18-1ubuntu1.1) ... 1093s Setting up python3-pytest (8.3.5-2) ... 1093s Setting up python3-pytest-django (4.5.2-3) ... 1093s Setting up libjs-sphinxdoc (8.2.3-1ubuntu2) ... 1093s Setting up python3-lxml:s390x (5.4.0-1build1) ... 1093s Setting up python3-django-cas-server (2.1.0-3) ... 1094s Setting up python3-django-cas-server-doc (2.1.0-3) ... 1094s Processing triggers for man-db (2.13.1-1) ... 1094s Processing triggers for libc-bin (2.41-6ubuntu2) ... 1095s autopkgtest [18:22:55]: test run-test-suite.sh: [----------------------- 1095s ************************************************************************** 1095s # A new feature in cloud-init identified possible datasources for # 1095s # this system as: # 1095s # [] # 1095s # However, the datasource used was: OpenStack # 1095s # # 1095s # In the future, cloud-init will only attempt to use datasources that # 1095s # are identified or specifically configured. # 1095s # For more information see # 1095s # https://bugs.launchpad.net/bugs/1669675 # 1095s # # 1095s # If you are seeing this message, please file a bug against # 1095s # cloud-init at # 1095s # https://github.com/canonical/cloud-init/issues # 1095s # Make sure to include the cloud provider your instance is # 1095s # running on. # 1095s # # 1095s # After you have filed a bug, you can disable this warning by launching # 1095s # your instance with the cloud-config below, or putting that content # 1095s # into /etc/cloud/cloud.cfg.d/99-warnings.cfg # 1095s # # 1095s # #cloud-config # 1095s # warnings: # 1095s # dsid_missing_source: off # 1095s ************************************************************************** 1095s 1095s Disable the warnings above by: 1095s touch /home/ubuntu/.cloud-warnings.skip 1095s or 1095s touch /var/lib/cloud/instance/warnings/.skip 1095s 1095s *************************** 1095s *** Testing with python3.13 1095s *************************** 1095s 1095s Content of current working folder: 1095s 1095s total 20 1095s drwxr-xr-x 3 ubuntu ubuntu 4096 Jun 18 18:22 . 1095s drwxrwxrwt 5 root root 4096 Jun 18 18:22 .. 1095s drwxrwxr-x 9 ubuntu ubuntu 4096 Jun 18 18:22 cas_server 1095s -rw-rw-r-- 1 ubuntu ubuntu 140 Aug 18 2024 pytest.ini 1095s -rw-rw-r-- 1 ubuntu ubuntu 152 Aug 18 2024 setup.cfg 1095s Running tests... 1095s 1095s ============================= test session starts ============================== 1095s platform linux -- Python 3.13.5, pytest-8.3.5, pluggy-1.5.0 -- /usr/bin/python3.13 1095s cachedir: .pytest_cache 1095s django: settings: cas_server.tests.settings (from ini) 1095s rootdir: /tmp/autopkgtest.ZBmTbp/autopkgtest_tmp 1095s configfile: pytest.ini 1095s testpaths: cas_server/tests/ 1095s plugins: django-4.5.2, typeguard-4.4.2 1096s collecting ... collected 128 items 1096s 1096s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 1096s 2025-06-18 18:22:56,349 WARNING Identity provider suffix exemple.fr not found 1096s 2025-06-18 18:22:56,361 INFO Got an invalid ticket ST-aVstY6FPixwQyTHDGM4I3jdy6pBB8GZSmR6D9laIHxVrjHmIBnbalxm8oh8xy from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 1096s 2025-06-18 18:22:56,364 INFO Got an invalid ticket ST-T4XxAd5fXaiVg5QmVWv5YodOp3qswSS5JQEjFSRexY7ZSCVSJtFEDJX4t8vC8 from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 1096s PASSED 1096s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2025-06-18 18:22:56,382 WARNING CAS_FEDERATE is False, set it to True to use federation 1096s 2025-06-18 18:22:56,383 WARNING CAS_FEDERATE is False, set it to True to use federation 1096s PASSED 1096s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2025-06-18 18:22:56,392 INFO Trying to authenticate http://127.0.0.1:8080 again 1096s 2025-06-18 18:22:56,395 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 1096s 2025-06-18 18:22:56,403 INFO User dédé@example.com successfully authenticated 1096s 2025-06-18 18:22:56,410 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 1096s 2025-06-18 18:22:56,417 INFO Trying to authenticate http://127.0.0.1:8081 again 1096s 2025-06-18 18:22:56,420 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 1096s 2025-06-18 18:22:56,426 INFO User dédé@example.org successfully authenticated 1096s 2025-06-18 18:22:56,432 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 1096s 2025-06-18 18:22:56,438 INFO Trying to authenticate http://127.0.0.1:8082 again 1096s 2025-06-18 18:22:56,441 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 1096s 2025-06-18 18:22:56,447 INFO User dédé@example.net successfully authenticated 1096s 2025-06-18 18:22:56,453 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 1096s 2025-06-18 18:22:56,459 INFO Trying to authenticate http://127.0.0.1:8083 again 1096s 2025-06-18 18:22:56,463 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 1096s 2025-06-18 18:22:56,469 INFO User dédé@example.test successfully authenticated 1096s 2025-06-18 18:22:56,475 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 1096s 2025-06-18 18:22:56,497 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-oAjobUN8DcqZ7Viq5MA8WNF8dhtVUGnbBiFIWSLxLcvl6vAt1uWWpKt4Av9PK 1096s 2025-06-18 18:22:56,513 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-YaFcvkmgddCJ7ILFbo6hC5sF4oCmkTA8tO00xD03v2zduJBbTDCGVvHjpQsLI 1096s PASSED 1096s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 1096s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2025-06-18 18:22:56,532 INFO Trying to authenticate http://127.0.0.1:8080 again 1096s 2025-06-18 18:22:56,535 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 1096s 2025-06-18 18:22:56,541 INFO User dédé@example.com successfully authenticated 1096s 2025-06-18 18:22:56,547 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 1096s 2025-06-18 18:22:56,553 INFO Trying to authenticate http://127.0.0.1:8081 again 1096s 2025-06-18 18:22:56,556 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 1096s 2025-06-18 18:22:56,561 INFO User dédé@example.org successfully authenticated 1096s 2025-06-18 18:22:56,567 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 1096s 2025-06-18 18:22:56,573 INFO Trying to authenticate http://127.0.0.1:8082 again 1096s 2025-06-18 18:22:56,577 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 1096s 2025-06-18 18:22:56,582 INFO User dédé@example.net successfully authenticated 1096s 2025-06-18 18:22:56,588 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 1096s 2025-06-18 18:22:56,594 INFO Trying to authenticate http://127.0.0.1:8083 again 1096s 2025-06-18 18:22:56,597 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 1096s 2025-06-18 18:22:56,603 INFO User dédé@example.test successfully authenticated 1096s 2025-06-18 18:22:56,609 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 1096s 2025-06-18 18:22:56,609 INFO logout requested 1096s 2025-06-18 18:22:56,611 INFO Logging out user dédé@example.com. 1096s 2025-06-18 18:22:56,613 INFO User dédé@example.com logged out 1096s 2025-06-18 18:22:56,619 INFO logout requested 1096s 2025-06-18 18:22:56,620 INFO logout requested 1096s 2025-06-18 18:22:56,621 INFO Logging out user dédé@example.org. 1096s 2025-06-18 18:22:56,624 INFO User dédé@example.org logged out 1096s 2025-06-18 18:22:56,630 INFO logout requested 1096s 2025-06-18 18:22:56,631 INFO logout requested 1096s 2025-06-18 18:22:56,632 INFO Logging out user dédé@example.net. 1096s 2025-06-18 18:22:56,635 INFO User dédé@example.net logged out 1096s 2025-06-18 18:22:56,640 INFO logout requested 1096s 2025-06-18 18:22:56,642 INFO logout requested 1096s 2025-06-18 18:22:56,643 INFO Logging out user dédé@example.test. 1096s 2025-06-18 18:22:56,646 INFO User dédé@example.test logged out 1096s 2025-06-18 18:22:56,651 INFO logout requested 1096s 2025-06-18 18:22:56,658 INFO Trying to authenticate http://127.0.0.1:8080 again 1096s 2025-06-18 18:22:56,661 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 1096s 2025-06-18 18:22:56,667 INFO User dédé@example.com successfully authenticated 1096s 2025-06-18 18:22:56,673 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 1096s 2025-06-18 18:22:56,679 INFO Trying to authenticate http://127.0.0.1:8081 again 1096s 2025-06-18 18:22:56,682 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 1096s 2025-06-18 18:22:56,688 INFO User dédé@example.org successfully authenticated 1096s 2025-06-18 18:22:56,693 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 1096s 2025-06-18 18:22:56,699 INFO Trying to authenticate http://127.0.0.1:8082 again 1096s 2025-06-18 18:22:56,702 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 1096s 2025-06-18 18:22:56,708 INFO User dédé@example.net successfully authenticated 1096s 2025-06-18 18:22:56,714 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 1096s 2025-06-18 18:22:56,720 INFO Trying to authenticate http://127.0.0.1:8083 again 1096s 2025-06-18 18:22:56,723 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 1096s 2025-06-18 18:22:56,729 INFO User dédé@example.test successfully authenticated 1096s 2025-06-18 18:22:56,735 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 1096s 2025-06-18 18:22:56,736 INFO logout requested 1096s 2025-06-18 18:22:56,737 INFO Logging out user dédé. 1096s 2025-06-18 18:22:56,737 INFO User dédé logged out 1096s 2025-06-18 18:22:56,743 INFO logout requested 1096s 2025-06-18 18:22:56,744 INFO Logging out user dédé. 1096s 2025-06-18 18:22:56,744 INFO User dédé logged out 1096s 2025-06-18 18:22:56,750 INFO logout requested 1096s 2025-06-18 18:22:56,750 INFO Logging out user dédé. 1096s 2025-06-18 18:22:56,751 INFO User dédé logged out 1096s 2025-06-18 18:22:56,757 INFO logout requested 1096s 2025-06-18 18:22:56,757 INFO Logging out user dédé. 1096s 2025-06-18 18:22:56,758 INFO User dédé logged out 1096s PASSED 1096s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2025-06-18 18:22:56,773 INFO Trying to authenticate http://127.0.0.1:8080 again 1096s 2025-06-18 18:22:56,776 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 1096s 2025-06-18 18:22:56,782 INFO User dédé@example.com successfully authenticated 1096s 2025-06-18 18:22:56,787 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 1096s 2025-06-18 18:22:56,816 INFO Trying to authenticate http://127.0.0.1:8081 again 1096s 2025-06-18 18:22:56,819 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 1096s 2025-06-18 18:22:56,824 INFO User dédé@example.org successfully authenticated 1096s 2025-06-18 18:22:56,830 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 1096s 2025-06-18 18:22:56,836 INFO Trying to authenticate http://127.0.0.1:8082 again 1096s 2025-06-18 18:22:56,839 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 1096s 2025-06-18 18:22:56,844 INFO User dédé@example.net successfully authenticated 1096s 2025-06-18 18:22:56,850 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 1096s 2025-06-18 18:22:56,857 INFO Trying to authenticate http://127.0.0.1:8083 again 1096s 2025-06-18 18:22:56,859 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 1096s 2025-06-18 18:22:56,865 INFO User dédé@example.test successfully authenticated 1096s 2025-06-18 18:22:56,870 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 1096s 2025-06-18 18:22:56,871 INFO logout requested 1096s 2025-06-18 18:22:56,872 INFO Logging out user dédé@example.com. 1096s 2025-06-18 18:22:56,875 INFO User dédé@example.com logged out 1096s 2025-06-18 18:22:56,876 INFO logout requested 1096s 2025-06-18 18:22:56,877 INFO Logging out user dédé@example.org. 1096s 2025-06-18 18:22:56,880 INFO User dédé@example.org logged out 1096s 2025-06-18 18:22:56,880 INFO logout requested 1096s 2025-06-18 18:22:56,881 INFO Logging out user dédé@example.net. 1096s 2025-06-18 18:22:56,884 INFO User dédé@example.net logged out 1096s 2025-06-18 18:22:56,884 INFO logout requested 1096s 2025-06-18 18:22:56,886 INFO Logging out user dédé@example.test. 1096s 2025-06-18 18:22:56,888 INFO User dédé@example.test logged out 1096s PASSED 1096s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2025-06-18 18:22:56,897 WARNING A login attempt failed 1096s PASSED 1096s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 1096s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2025-06-18 18:22:56,917 INFO Trying to authenticate http://127.0.0.1:8080 again 1096s 2025-06-18 18:22:56,919 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 1096s 2025-06-18 18:22:56,925 INFO User dédé@example.com successfully authenticated 1096s 2025-06-18 18:22:56,931 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 1096s 2025-06-18 18:22:56,937 INFO Trying to authenticate http://127.0.0.1:8081 again 1096s 2025-06-18 18:22:56,940 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 1096s 2025-06-18 18:22:56,946 INFO User dédé@example.org successfully authenticated 1096s 2025-06-18 18:22:56,951 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 1096s 2025-06-18 18:22:56,958 INFO Trying to authenticate http://127.0.0.1:8082 again 1096s 2025-06-18 18:22:56,960 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 1096s 2025-06-18 18:22:56,966 INFO User dédé@example.net successfully authenticated 1097s 2025-06-18 18:22:56,972 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 1097s 2025-06-18 18:22:56,978 INFO Trying to authenticate http://127.0.0.1:8083 again 1097s 2025-06-18 18:22:56,981 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 1097s 2025-06-18 18:22:56,987 INFO User dédé@example.test successfully authenticated 1097s 2025-06-18 18:22:56,993 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 1097s PASSED 1097s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2025-06-18 18:22:57,002 INFO Trying to authenticate http://127.0.0.1:8080 again 1097s 2025-06-18 18:22:57,005 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 1097s 2025-06-18 18:22:57,011 INFO User dédé@example.com successfully authenticated 1097s 2025-06-18 18:22:57,017 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 1097s 2025-06-18 18:22:57,023 INFO Trying to authenticate http://127.0.0.1:8081 again 1097s 2025-06-18 18:22:57,025 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 1097s 2025-06-18 18:22:57,031 INFO User dédé@example.org successfully authenticated 1097s 2025-06-18 18:22:57,037 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 1097s 2025-06-18 18:22:57,043 INFO Trying to authenticate http://127.0.0.1:8082 again 1097s 2025-06-18 18:22:57,046 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 1097s 2025-06-18 18:22:57,051 INFO User dédé@example.net successfully authenticated 1097s 2025-06-18 18:22:57,058 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 1097s 2025-06-18 18:22:57,064 INFO Trying to authenticate http://127.0.0.1:8083 again 1097s 2025-06-18 18:22:57,066 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 1097s 2025-06-18 18:22:57,073 INFO User dédé@example.test successfully authenticated 1097s 2025-06-18 18:22:57,079 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 1097s 2025-06-18 18:22:57,086 INFO Trying to authenticate http://127.0.0.1:8080 again 1097s 2025-06-18 18:22:57,090 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 1097s 2025-06-18 18:22:57,096 INFO User dédé@example.com successfully authenticated 1097s 2025-06-18 18:22:57,102 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 1097s 2025-06-18 18:22:57,109 INFO Trying to authenticate http://127.0.0.1:8081 again 1097s 2025-06-18 18:22:57,112 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 1097s 2025-06-18 18:22:57,117 INFO User dédé@example.org successfully authenticated 1097s 2025-06-18 18:22:57,123 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 1097s 2025-06-18 18:22:57,129 INFO Trying to authenticate http://127.0.0.1:8082 again 1097s 2025-06-18 18:22:57,132 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 1097s 2025-06-18 18:22:57,138 INFO User dédé@example.net successfully authenticated 1097s 2025-06-18 18:22:57,143 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 1097s 2025-06-18 18:22:57,150 INFO Trying to authenticate http://127.0.0.1:8083 again 1097s 2025-06-18 18:22:57,153 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 1097s 2025-06-18 18:22:57,158 INFO User dédé@example.test successfully authenticated 1097s 2025-06-18 18:22:57,164 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 1097s 2025-06-18 18:22:57,165 WARNING User already authenticated, dropping federated authentication request 1097s 2025-06-18 18:22:57,166 WARNING User already authenticated, dropping federated authentication request 1097s 2025-06-18 18:22:57,166 WARNING User already authenticated, dropping federated authentication request 1097s 2025-06-18 18:22:57,167 WARNING User already authenticated, dropping federated authentication request 1097s PASSED 1097s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2025-06-18 18:22:57,176 INFO Trying to authenticate http://127.0.0.1:8080 again 1097s 2025-06-18 18:22:57,179 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 1097s 2025-06-18 18:22:57,184 INFO User dédé@example.com successfully authenticated 1097s 2025-06-18 18:22:57,190 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 1097s 2025-06-18 18:22:57,196 INFO Trying to authenticate http://127.0.0.1:8081 again 1097s 2025-06-18 18:22:57,199 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 1097s 2025-06-18 18:22:57,204 INFO User dédé@example.org successfully authenticated 1097s 2025-06-18 18:22:57,210 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 1097s 2025-06-18 18:22:57,217 INFO Trying to authenticate http://127.0.0.1:8082 again 1097s 2025-06-18 18:22:57,219 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 1097s 2025-06-18 18:22:57,224 INFO User dédé@example.net successfully authenticated 1097s 2025-06-18 18:22:57,230 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 1097s 2025-06-18 18:22:57,237 INFO Trying to authenticate http://127.0.0.1:8083 again 1097s 2025-06-18 18:22:57,239 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 1097s 2025-06-18 18:22:57,245 INFO User dédé@example.test successfully authenticated 1097s 2025-06-18 18:22:57,251 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 1097s 2025-06-18 18:22:57,251 INFO logout requested 1097s 2025-06-18 18:22:57,252 INFO Logging out user dédé@example.com. 1097s 2025-06-18 18:22:57,256 INFO User dédé@example.com logged out 1097s 2025-06-18 18:22:57,257 INFO logout requested 1097s 2025-06-18 18:22:57,258 INFO Logging out user dédé@example.org. 1097s 2025-06-18 18:22:57,262 INFO User dédé@example.org logged out 1097s 2025-06-18 18:22:57,263 INFO logout requested 1097s 2025-06-18 18:22:57,265 INFO Logging out user dédé@example.net. 1097s 2025-06-18 18:22:57,267 INFO User dédé@example.net logged out 1097s 2025-06-18 18:22:57,269 INFO logout requested 1097s 2025-06-18 18:22:57,270 INFO Logging out user dédé@example.test. 1097s 2025-06-18 18:22:57,273 INFO User dédé@example.test logged out 1097s PASSED 1097s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2025-06-18 18:22:57,284 INFO Trying to authenticate http://127.0.0.1:8080 again 1097s 2025-06-18 18:22:57,286 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 1097s 2025-06-18 18:22:57,292 INFO User dédé@example.com successfully authenticated 1097s 2025-06-18 18:22:57,298 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 1097s 2025-06-18 18:22:57,305 INFO Trying to authenticate http://127.0.0.1:8081 again 1097s 2025-06-18 18:22:57,307 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 1097s 2025-06-18 18:22:57,313 INFO User dédé@example.org successfully authenticated 1097s 2025-06-18 18:22:57,319 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 1097s 2025-06-18 18:22:57,325 INFO Trying to authenticate http://127.0.0.1:8082 again 1097s 2025-06-18 18:22:57,327 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 1097s 2025-06-18 18:22:57,333 INFO User dédé@example.net successfully authenticated 1097s 2025-06-18 18:22:57,339 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 1097s 2025-06-18 18:22:57,345 INFO Trying to authenticate http://127.0.0.1:8083 again 1097s 2025-06-18 18:22:57,349 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 1097s 2025-06-18 18:22:57,356 INFO User dédé@example.test successfully authenticated 1097s 2025-06-18 18:22:57,362 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 1097s 2025-06-18 18:22:57,365 INFO Trying to authenticate http://127.0.0.1:8080 again 1097s 2025-06-18 18:22:57,367 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 1097s 2025-06-18 18:22:57,373 INFO User dédé@example.com successfully authenticated 1097s 2025-06-18 18:22:57,376 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 1097s 2025-06-18 18:22:57,379 INFO Trying to authenticate http://127.0.0.1:8081 again 1097s 2025-06-18 18:22:57,382 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 1097s 2025-06-18 18:22:57,389 INFO User dédé@example.org successfully authenticated 1097s 2025-06-18 18:22:57,392 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 1097s 2025-06-18 18:22:57,395 INFO Trying to authenticate http://127.0.0.1:8082 again 1097s 2025-06-18 18:22:57,398 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 1097s 2025-06-18 18:22:57,404 INFO User dédé@example.net successfully authenticated 1097s 2025-06-18 18:22:57,407 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 1097s 2025-06-18 18:22:57,411 INFO Trying to authenticate http://127.0.0.1:8083 again 1097s 2025-06-18 18:22:57,414 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 1097s 2025-06-18 18:22:57,420 INFO User dédé@example.test successfully authenticated 1097s 2025-06-18 18:22:57,423 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 1097s PASSED 1097s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 1097s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 1097s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 1097s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries PASSED 1097s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2025-06-18 18:22:57,448 INFO User test successfully authenticated 1097s 2025-06-18 18:22:57,459 INFO User test successfully authenticated 1097s PASSED 1097s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2025-06-18 18:22:57,467 INFO User test successfully authenticated 1097s 2025-06-18 18:22:57,473 INFO User test successfully authenticated 1097s 2025-06-18 18:22:57,479 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 1097s PASSED 1097s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2025-06-18 18:22:57,481 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=45678): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 1097s 2025-06-18 18:22:57,489 INFO User test successfully authenticated 1097s PASSED 1097s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2025-06-18 18:22:57,499 INFO User test successfully authenticated 1097s PASSED 1097s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2025-06-18 18:22:57,511 INFO User test successfully authenticated 1097s 2025-06-18 18:22:57,518 INFO Sending SLO requests to service http://127.0.0.1:39345 for user test 1097s 2025-06-18 18:22:57,519 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 1097s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 1097s PASSED 1097s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2025-06-18 18:22:57,526 INFO User test successfully authenticated 1097s PASSED 1097s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 1097s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 1097s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 1097s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 1097s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 1097s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 1097s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 1097s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 1097s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 1097s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 1097s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 1097s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 1097s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 1097s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 1097s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 1097s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 1097s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 1097s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 1097s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 1098s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 1098s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 1098s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 1098s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2025-06-18 18:22:58,470 INFO User test successfully authenticated 1098s PASSED 1098s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success 2025-06-18 18:22:58,474 WARNING Service https://www.example.org not allowed. 1098s 2025-06-18 18:22:58,481 INFO User test successfully authenticated 1098s 2025-06-18 18:22:58,485 INFO Service ticket created for service https://www.example.com by user test. 1098s PASSED 1098s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt PASSED 1098s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn PASSED 1098s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged PASSED 1098s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2025-06-18 18:22:58,493 INFO User test successfully authenticated 1098s 2025-06-18 18:22:58,497 INFO Service ticket created for service https://www.example.com by user test. 1098s 2025-06-18 18:22:58,504 INFO User test successfully authenticated 1098s 2025-06-18 18:22:58,514 INFO User test successfully authenticated 1098s 2025-06-18 18:22:58,524 INFO User test successfully authenticated 1098s PASSED 1098s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required 2025-06-18 18:22:58,528 WARNING User test seems authenticated but is not found in the database. 1098s 2025-06-18 18:22:58,528 INFO Logging out user test. 1098s 2025-06-18 18:22:58,528 INFO User test logged out 1098s PASSED 1098s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2025-06-18 18:22:58,538 INFO User test successfully authenticated 1098s 2025-06-18 18:22:58,542 WARNING Username test not allowed on service restrict_user_fail 1098s PASSED 1098s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2025-06-18 18:22:58,550 WARNING A login attempt failed 1098s 2025-06-18 18:22:58,558 WARNING A login attempt failed 1098s 2025-06-18 18:22:58,568 WARNING A login attempt failed 1098s PASSED 1098s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2025-06-18 18:22:58,578 WARNING Received an invalid login ticket 1098s PASSED 1098s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2025-06-18 18:22:58,588 WARNING A login attempt failed 1098s PASSED 1098s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2025-06-18 18:22:58,599 INFO User test successfully authenticated 1098s PASSED 1098s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2025-06-18 18:22:58,609 INFO User test successfully authenticated 1098s PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_renew 2025-06-18 18:22:59,176 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,183 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,185 INFO Service ticket created for service https://www.example.com by user test. 1099s PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2025-06-18 18:22:59,192 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,199 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,201 INFO Service ticket created for service https://www.example.com by user test. 1099s PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2025-06-18 18:22:59,209 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,212 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 1099s 2025-06-18 18:22:59,214 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 1099s 2025-06-18 18:22:59,217 INFO Service ticket created for service https://filter_success.example.com by user test. 1099s PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2025-06-18 18:22:59,224 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,227 WARNING Username test not allowed on service restrict_user_fail 1099s 2025-06-18 18:22:59,231 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 1099s PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2025-06-18 18:22:59,238 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,242 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 1099s 2025-06-18 18:22:59,245 INFO Service ticket created for service https://field_needed_success.example.com by user test. 1099s PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2025-06-18 18:22:59,253 INFO User test successfully authenticated 1099s PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2025-06-18 18:22:59,256 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 1099s 2025-06-18 18:22:59,264 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,268 WARNING User test seems authenticated but is not found in the database. 1099s 2025-06-18 18:22:59,268 INFO Logging out user test. 1099s 2025-06-18 18:22:59,268 INFO User test logged out 1099s PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2025-06-18 18:22:59,287 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,291 INFO Service ticket created for service https://www.example.com/é by user test. 1099s PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2025-06-18 18:22:59,299 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,304 INFO Service ticket created for service https://www.example.com by user test. 1099s PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2025-06-18 18:22:59,312 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,318 WARNING Received a login attempt for an already-active user 1099s 2025-06-18 18:22:59,320 INFO Service ticket created for service https://www.example.com by user test. 1099s PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2025-06-18 18:22:59,328 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,331 WARNING Service https://www.example.org not allowed. 1099s PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2025-06-18 18:22:59,334 WARNING Service https://www.example.net not allowed. 1099s PASSED 1099s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2025-06-18 18:22:59,340 WARNING Service https://www.example.net not allowed. 1099s PASSED 1099s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2025-06-18 18:22:59,349 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,351 INFO logout requested 1099s 2025-06-18 18:22:59,352 INFO Logging out user test. 1099s 2025-06-18 18:22:59,354 INFO User test logged out 1099s PASSED 1099s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2025-06-18 18:22:59,359 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,366 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,372 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,377 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,384 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,386 INFO logout requested 1099s 2025-06-18 18:22:59,386 INFO Logging out user test from all sessions. 1099s 2025-06-18 18:22:59,397 INFO User test logged out 1099s PASSED 1099s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2025-06-18 18:22:59,403 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,405 INFO logout requested 1099s 2025-06-18 18:22:59,405 INFO Logging out user test. 1099s PASSED 1099s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2025-06-18 18:22:59,408 INFO User test logged out 1099s 2025-06-18 18:22:59,409 INFO logout requested 1099s PASSED 1099s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2025-06-18 18:22:59,416 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,422 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,424 INFO logout requested 1099s 2025-06-18 18:22:59,424 INFO Logging out user test from all sessions. 1099s 2025-06-18 18:22:59,429 INFO User test logged out 1099s PASSED 1099s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2025-06-18 18:22:59,436 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,441 INFO Service ticket created for service http://127.0.0.1:36451 by user test. 1099s 2025-06-18 18:22:59,443 INFO Validate: Service ticket ST-VtJ0Mhc5IknShFYCXnVrbo0ZgY4YpzdLytfshITLKHR50mZSJVN1W5mUw236d validated, user test authenticated on service http://127.0.0.1:36451 1099s 2025-06-18 18:22:59,444 INFO logout requested 1099s 2025-06-18 18:22:59,444 INFO Logging out user test. 1099s 2025-06-18 18:22:59,446 INFO Sending SLO requests to service http://127.0.0.1:36451 for user test 1099s 2025-06-18 18:22:59,448 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 1099s 2025-06-18 18:22:59,448 INFO User test logged out 1099s 2025-06-18 18:22:59,453 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,457 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 1099s 2025-06-18 18:22:59,460 INFO Validate: Service ticket ST-bJeJtLoixymoqNN0SCOzcDZXS3MYMhSSEV6WuTmrXZfA30tXyRhaWONYjONNa validated, user test authenticated on service http://127.0.0.1:45678 1099s 2025-06-18 18:22:59,461 INFO logout requested 1099s 2025-06-18 18:22:59,461 INFO Logging out user test. 1099s 2025-06-18 18:22:59,463 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 1099s 2025-06-18 18:22:59,465 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 1099s 2025-06-18 18:22:59,466 INFO User test logged out 1099s 2025-06-18 18:22:59,470 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,475 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 1099s 2025-06-18 18:22:59,477 INFO Validate: Service ticket ST-nBxGObG1cAgwZRos3I2qDrkhuiIDIJMTxTIyhG3ATj9tkPnb34hn0eFjxYkxz validated, user test authenticated on service http://127.0.0.1:45678 1099s 2025-06-18 18:22:59,477 INFO logout requested 1099s 2025-06-18 18:22:59,478 INFO Logging out user test. 1099s 2025-06-18 18:22:59,480 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 1099s 2025-06-18 18:22:59,481 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=45643): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 1099s 2025-06-18 18:22:59,482 INFO User test logged out 1099s PASSED 1099s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2025-06-18 18:22:59,488 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,494 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,498 INFO logout requested 1099s 2025-06-18 18:22:59,498 INFO Logging out user test. 1099s 2025-06-18 18:22:59,501 INFO User test logged out 1099s PASSED 1099s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2025-06-18 18:22:59,511 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,513 INFO logout requested 1099s 2025-06-18 18:22:59,513 INFO Logging out user test. 1099s 2025-06-18 18:22:59,516 INFO User test logged out 1099s PASSED 1099s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2025-06-18 18:22:59,524 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,526 INFO logout requested 1099s 2025-06-18 18:22:59,527 INFO Logging out user test. 1099s 2025-06-18 18:22:59,529 INFO User test logged out 1099s PASSED 1099s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2025-06-18 18:22:59,538 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,541 INFO logout requested 1099s 2025-06-18 18:22:59,541 INFO Logging out user test. 1099s 2025-06-18 18:22:59,543 INFO User test logged out 1099s PASSED 1099s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2025-06-18 18:22:59,549 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,551 INFO logout requested 1099s 2025-06-18 18:22:59,552 INFO Logging out user test. 1099s 2025-06-18 18:22:59,554 INFO User test logged out 1099s 2025-06-18 18:22:59,558 INFO logout requested 1099s PASSED 1099s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 1099s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 1099s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice 2025-06-18 18:22:59,568 WARNING Service https://www.example.org not allowed. 1099s PASSED 1099s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings PASSED 1099s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 1099s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2025-06-18 18:22:59,578 INFO User test successfully authenticated 1099s PASSED 1099s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 1099s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2025-06-18 18:22:59,590 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,594 INFO Service ticket created for service https://www.example.com by user test. 1099s 2025-06-18 18:22:59,595 WARNING Validate: service or ticket missing 1099s 2025-06-18 18:22:59,596 WARNING Validate: service or ticket missing 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2025-06-18 18:22:59,602 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,604 INFO Service ticket created for service https://www.example.com by user test. 1099s 2025-06-18 18:22:59,606 INFO Validate: Service ticket ST-26Bq8Q0pBVgRN4vpTZLU3V1tESIw9CyR16iPzZ0yMV8ctwIUchYJ2r4MS07Jl validated, user test authenticated on service https://www.example.com 1099s 2025-06-18 18:22:59,610 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,613 INFO Service ticket created for service https://www.example.com by user test. 1099s 2025-06-18 18:22:59,615 INFO Validate: Service ticket ST-C5F1CxHKgxrdhcKMoAT9yGsxLe9VWvjFLSsDnUQqvcBVEYO3f7Oltl2T8rlIv validated, user test authenticated on service https://www.example.com 1099s 2025-06-18 18:22:59,617 INFO Service ticket created for service https://www.example.com by user test. 1099s 2025-06-18 18:22:59,618 WARNING Validate: Service ticket ST-hz9muvPE4Qiwg7NclTGE7I2rtwL8rddsywoBiMLdF72Vs8syxIwMJJqqSO8Qc not found or already validated, auth to https://www.example.com failed 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2025-06-18 18:22:59,624 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,629 INFO Service ticket created for service https://user_field.example.com by user test. 1099s 2025-06-18 18:22:59,631 INFO Validate: Service ticket ST-PNN2IFmCRqzWfj74vcZyWTDEsYBX2zFcEIVUTbuxtWoxZJJqBLKd54g8GSDPZ validated, user test authenticated on service https://user_field.example.com 1099s 2025-06-18 18:22:59,636 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,640 INFO Service ticket created for service https://user_field_alt.example.com by user test. 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2025-06-18 18:22:59,642 INFO Validate: Service ticket ST-oDtBUAwFt0ALXkE3aMvjoPJEvOpcHvxAaWzgBBJaY66c0oYwj51qTAXZ9cMer validated, user test authenticated on service https://user_field_alt.example.com 1099s 2025-06-18 18:22:59,649 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,653 INFO Service ticket created for service https://www.example.com by user test. 1099s 2025-06-18 18:22:59,655 WARNING Validate: Service ticket ST-2SXq218fPPMUcztpydJcpRDZXa6snTCkx3skn3fnyHb7YNgYBtKjknzy1Zgn1 not found or already validated, auth to https://www.example.org failed 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2025-06-18 18:22:59,660 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,664 INFO Service ticket created for service https://www.example.com by user test. 1099s 2025-06-18 18:22:59,666 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2025-06-18 18:22:59,671 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,676 INFO Service ticket created for service https://www.example.com by user test. 1099s 2025-06-18 18:22:59,678 INFO Validate: Service ticket ST-yeGMKmvNheQrHlu7vQ30ftysEHZVkx3eNWpJnaTViZfr8gEfulHhICPKc1Fs2 validated, user test authenticated on service https://www.example.com 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2025-06-18 18:22:59,685 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,689 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 1099s 2025-06-18 18:22:59,690 WARNING ValidateService: missing ticket or service 1099s 2025-06-18 18:22:59,691 WARNING ValidateService: missing ticket or service 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2025-06-18 18:22:59,698 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,703 INFO Service ticket created for service http://127.0.0.1:41255 by user test. 1099s 2025-06-18 18:22:59,707 INFO ValidateService: ticket ST-JzRz6F6omvRMIx4XqUVkeyXBlvjSI7L9uW1IlTsrE5LXhYSGKPisQNFPrZtGR validated for user test on service http://127.0.0.1:41255. Proxy Granting Ticket transmited to http://127.0.0.1:41255. 1099s 2025-06-18 18:22:59,710 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 1099s 2025-06-18 18:22:59,713 INFO ValidateService: ticket PT-cRV9oBoKojmy9TWugEfEkNJYVbeEpxd5llOou9HVb3oEIlqGpzZIKPPOI5454 validated for user test on service http://127.0.0.1:45678. 1099s 2025-06-18 18:22:59,718 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,722 INFO Service ticket created for service http://127.0.0.1:40731 by user test. 1099s 2025-06-18 18:22:59,726 INFO ValidateService: ticket ST-ikM8XYYmfXVPL71QUCVKwUChB7yWIxVPEJ0KYxemgZ1CpPAhmIg2AxWDEtwEj validated for user test on service http://127.0.0.1:40731. Proxy Granting Ticket transmited to http://127.0.0.1:40731. 1099s 2025-06-18 18:22:59,729 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 1099s 2025-06-18 18:22:59,730 WARNING ValidateService: validation error: INVALID_TICKET PT-qY3pTRFBBA8F5msdYUsnAiCTBvXSrOnKKV0jB9HzHbejWWy78f1wUxd79rQbf 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2025-06-18 18:22:59,737 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,742 INFO Service ticket created for service https://replace_attribute.example.com by user test. 1099s 2025-06-18 18:22:59,744 INFO ValidateService: ticket ST-2x0phlWcW0liLVjiB3wRjdXgTM3uy3fPgsr1guOvmMpP2LGIuLfOIGwuuZ3Dv validated for user test on service https://replace_attribute.example.com. 1099s 2025-06-18 18:22:59,749 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,753 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 1099s 2025-06-18 18:22:59,756 INFO ValidateService: ticket ST-6g6eZ734RSNmVf0Ix3aPlLDJ17N3HgGC5XycjGgh7LylpFBcXX1wLSkwulRYk validated for user test on service https://replace_attribute_list.example.com. 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2025-06-18 18:22:59,763 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,767 INFO Service ticket created for service http://127.0.0.1:46471 by user test. 1099s 2025-06-18 18:22:59,772 INFO ValidateService: ticket ST-bkoKKK69wzI84WbINjD8Huu9AIDF7C2IrLcSnNVSyqte6eC9tfxhkGzqneWoA validated for user test on service http://127.0.0.1:46471. Proxy Granting Ticket transmited to http://127.0.0.1:46471. 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2025-06-18 18:22:59,778 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,782 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 1099s 2025-06-18 18:22:59,785 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 1099s 2025-06-18 18:22:59,789 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,794 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 1099s 2025-06-18 18:22:59,796 WARNING Service https://www.example.org not allowed. 1099s 2025-06-18 18:22:59,796 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2025-06-18 18:22:59,803 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,808 INFO Service ticket created for service https://127.0.0.1:41515 by user test. 1099s 2025-06-18 18:22:59,812 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=41515): Max retries exceeded with url: /?pgtId=PGT-B1LccyNf59z4MJPUWamtLnBj6WHngmHOWjgCLQ0hthpJJUtceUzfQJI3YPNe&pgtIou=PGTIOU-kjFd6bw2vIcpU3zzGgLVgBmbD2K8sd1BKmGxso8sCfqdFH550xDoX5Uxi (Caused by SSLError(SSLError(1, '[SSL: RECORD_LAYER_FAILURE] record layer failure (_ssl.c:1029)'))) 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2025-06-18 18:22:59,820 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,822 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 1099s 2025-06-18 18:22:59,824 INFO ValidateService: ticket ST-3CGLrip9QJnSp9PgnoUOsPr2cmCSHdyoePaiPHtsevwsVuhlXZkbZmMWbRkRR validated for user test on service http://127.0.0.1:45678. 1099s 2025-06-18 18:22:59,829 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,831 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 1099s 2025-06-18 18:22:59,833 INFO ValidateService: ticket ST-yb35Voj282GunauKzstqTPIIyiNKJwefpBr2az8HPrcDTTryIz1NxRsOyBgqZ validated for user test on service http://127.0.0.1:45678. 1099s 2025-06-18 18:22:59,836 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 1099s 2025-06-18 18:22:59,837 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2025-06-18 18:22:59,844 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,848 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 1099s 2025-06-18 18:22:59,850 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2025-06-18 18:22:59,856 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,860 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 1099s 2025-06-18 18:22:59,861 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2025-06-18 18:22:59,868 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,872 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2025-06-18 18:22:59,874 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 1099s 2025-06-18 18:22:59,880 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,884 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 1099s 2025-06-18 18:22:59,886 INFO ValidateService: ticket ST-audbV1l2H1aTIpQwf5KMk7c45rSQSFA4M4aG5j3lgHRfheWOObAya3OswY9au validated for user test on service http://127.0.0.1:45678. 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2025-06-18 18:22:59,893 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,897 INFO Service ticket created for service https://one_attribute.example.com by user test. 1099s 2025-06-18 18:22:59,899 INFO ValidateService: ticket ST-qOSEIpRHOqiPgOZZ9Igft8NZPWhSoCNw94aD3qw1rw7dW0ipCeOIZpTrwXYhv validated for user test on service https://one_attribute.example.com. 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2025-06-18 18:22:59,906 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,910 INFO Service ticket created for service http://127.0.0.1:40519 by user test. 1099s PASSED 1099s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2025-06-18 18:22:59,914 INFO ValidateService: ticket ST-N4dg1HM6MJVN8DGIUOlOB80hmzwHXajbyq9Ly5jJSQl6PdpQBd6JmP3gRuvr5 validated for user test on service http://127.0.0.1:40519. Proxy Granting Ticket transmited to http://127.0.0.1:40519. 1099s 2025-06-18 18:22:59,921 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,925 INFO Service ticket created for service https://user_field.example.com by user test. 1099s 2025-06-18 18:22:59,927 INFO ValidateService: ticket ST-x2DakwOjRUCRrz7YSaZnCHStTBNPUjtHsS3UvNBxefLhVWl8KeJ1ARfnEywYQ validated for user test on service https://user_field.example.com. 1099s 2025-06-18 18:22:59,931 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,936 INFO Service ticket created for service https://user_field_alt.example.com by user test. 1099s 2025-06-18 18:22:59,938 INFO ValidateService: ticket ST-wTF6uGBT7u5DYeQEgUfvB9xri4fecCCwvgrREeobkW1a2vSZFdibAEtCOS9wH validated for user test on service https://user_field_alt.example.com. 1099s PASSED 1099s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2025-06-18 18:22:59,946 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,950 INFO Service ticket created for service http://127.0.0.1:34039 by user test. 1099s 2025-06-18 18:22:59,954 INFO ValidateService: ticket ST-FaVO69M1RnTIFiYuwnDgmK0hPZ9740pDJ5yrIZNheaSw6so24TJ8hw6nIOpFh validated for user test on service http://127.0.0.1:34039. Proxy Granting Ticket transmited to http://127.0.0.1:34039. 1099s 2025-06-18 18:22:59,955 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 1099s 2025-06-18 18:22:59,955 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 1099s PASSED 1099s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2025-06-18 18:22:59,962 INFO User test successfully authenticated 1099s 2025-06-18 18:22:59,967 INFO Service ticket created for service http://127.0.0.1:40241 by user test. 1100s 2025-06-18 18:22:59,970 INFO ValidateService: ticket ST-8NHj475tKegxZKKEKNRopfkjdOQpM8X7WhjEiGi27tz9dXEVvjsSmygxWUZC2 validated for user test on service http://127.0.0.1:40241. Proxy Granting Ticket transmited to http://127.0.0.1:40241. 1100s 2025-06-18 18:22:59,972 WARNING Username test not allowed on service restrict_user_fail 1100s 2025-06-18 18:22:59,972 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 1100s 2025-06-18 18:22:59,974 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 1100s 2025-06-18 18:22:59,974 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 1100s PASSED 1100s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2025-06-18 18:22:59,976 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 1100s 2025-06-18 18:22:59,976 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 1100s 2025-06-18 18:22:59,983 INFO User test successfully authenticated 1100s 2025-06-18 18:22:59,987 INFO Service ticket created for service http://127.0.0.1:34613 by user test. 1100s 2025-06-18 18:22:59,991 INFO ValidateService: ticket ST-Og5VUbYipj9JOuuZUvSSOFl8ysQYDDrT0wBlYTg1nk8tshDqComo3kE3K5Mmr validated for user test on service http://127.0.0.1:34613. Proxy Granting Ticket transmited to http://127.0.0.1:34613. 1100s PASSED 1100s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2025-06-18 18:22:59,992 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 1100s 2025-06-18 18:22:59,999 INFO User test successfully authenticated 1100s 2025-06-18 18:23:00,003 INFO Service ticket created for service http://127.0.0.1:35939 by user test. 1100s 2025-06-18 18:23:00,007 INFO ValidateService: ticket ST-ebx5hc5NolPMKF6nPBsC88gpXdLwBDCTi4D4LTKDZAkCFps0Ur2WGXn8epxZ5 validated for user test on service http://127.0.0.1:35939. Proxy Granting Ticket transmited to http://127.0.0.1:35939. 1100s 2025-06-18 18:23:00,008 WARNING Service https://www.example.org not allowed. 1100s 2025-06-18 18:23:00,008 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 1100s PASSED 1100s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2025-06-18 18:23:00,009 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:35939 does not allow proxy tickets 1100s 2025-06-18 18:23:00,017 INFO User test successfully authenticated 1100s 2025-06-18 18:23:00,021 INFO Service ticket created for service http://127.0.0.1:44875 by user test. 1100s 2025-06-18 18:23:00,024 INFO ValidateService: ticket ST-QQNquoouD7bBO96OgGSkwnqkwFYUeCexvxou0NONjakgSk9KLV7xibRcjD8Rp validated for user test on service http://127.0.0.1:44875. Proxy Granting Ticket transmited to http://127.0.0.1:44875. 1100s 2025-06-18 18:23:00,027 INFO Proxy ticket created for user test on service https://www.example.com. 1100s PASSED2025-06-18 18:23:00,029 INFO ValidateService: ticket PT-b5tUuFf6nicULMcqb6rQ5R3hzQpIGYHns86BaIoZq1VxzG9HE8EyEcpJ08lhR validated for user test on service https://www.example.com. 1100s 1100s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2025-06-18 18:23:00,037 INFO User test successfully authenticated 1100s 2025-06-18 18:23:00,041 INFO Service ticket created for service https://www.example.com by user test. 1100s 2025-06-18 18:23:00,043 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 1100s PASSED 1100s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket PASSED 1100s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix 2025-06-18 18:23:00,047 WARNING SamlValidate: validation error: AuthnFailed ticket ST-Idbug7KA7DncZvCYDXlUHKVKCCZUYTbrFiKpdpbzTcrbhiibB5SsDEpQmbexO not found 1100s 2025-06-18 18:23:00,048 WARNING SamlValidate: validation error: AuthnFailed ticket PT-qEvt2W9ifUkvC0doPOLcf9RBKYdIUM24TeawwJoT8m3aV9Lxd8Y3FIOMW0dae not found 1100s PASSED 1100s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml 2025-06-18 18:23:00,051 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 1100s 2025-06-18 18:23:00,055 WARNING SamlValidate: validation error: VersionMismatch 1100s PASSED 1100s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2025-06-18 18:23:00,063 INFO User test successfully authenticated 1100s 2025-06-18 18:23:00,068 INFO Service ticket created for service https://www.example.com by user test. 1100s 2025-06-18 18:23:00,073 INFO User test successfully authenticated 1100s PASSED 1100s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2025-06-18 18:23:00,078 INFO Service ticket created for service http://127.0.0.1:39119 by user test. 1100s 2025-06-18 18:23:00,082 INFO ValidateService: ticket ST-lYeGk0Pjl1zCyA1zv1jcqGkL8UOgP0L9TF0baAYkbOUkv0nrc731hhejxOFXN validated for user test on service http://127.0.0.1:39119. Proxy Granting Ticket transmited to http://127.0.0.1:39119. 1100s 2025-06-18 18:23:00,084 INFO Proxy ticket created for user test on service https://www.example.com. 1100s 2025-06-18 18:23:00,086 INFO SamlValidate: ticket ST-cRQGUi2SxuBqQqZNoy97sbMvznSVhDytKLASKA2IlLpTO4Ojjn7BTAEMRCeYz validated for user test on service https://www.example.com. 1100s 2025-06-18 18:23:00,088 INFO SamlValidate: ticket PT-XINX7iJ11OLvgJ0AKV5dbPXm21SM0IS4Ns13LV9jqTGUoyquCW2L32zybpofo validated for user test on service https://www.example.com. 1100s 2025-06-18 18:23:00,096 INFO User test successfully authenticated 1100s 2025-06-18 18:23:00,100 INFO Service ticket created for service https://field_needed_success.example.com by user test. 1100s 2025-06-18 18:23:00,103 INFO SamlValidate: ticket ST-yBngkd40VEGRFVd2PdTgb7RFlJ7rbCBiRt5g2HYVtDw0RPG5qL1GEubeyQTg0 validated for user test on service https://field_needed_success.example.com. 1100s 2025-06-18 18:23:00,107 INFO User test successfully authenticated 1100s 2025-06-18 18:23:00,111 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 1100s 2025-06-18 18:23:00,113 INFO SamlValidate: ticket ST-dX7IwtMqg5xWGbW9cFQBQiSxF5FFSDWAs5fSW3RoGvOP3iv8HUeH0FrRYcL4H validated for user test on service https://field_needed_success_alt.example.com. 1100s PASSED 1100s 1100s =============================== warnings summary =============================== 1100s cas_server/tests/test_federate.py: 8 warnings 1100s cas_server/tests/test_view.py: 3 warnings 1100s /tmp/autopkgtest.ZBmTbp/autopkgtest_tmp/cas_server/utils.py:211: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC). 1100s datetime.utcnow() + timedelta(seconds=max_age), 1100s 1100s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 1100s /usr/lib/python3.13/unittest/case.py:707: DeprecationWarning: It is deprecated to return a value that is not None from a test case (>) 1100s return self.run(*args, **kwds) 1100s 1100s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 1100s ======================= 128 passed, 12 warnings in 4.24s ======================= 1100s Destroying test database for alias 'default'... 1100s 1100s autopkgtest [18:23:00]: test run-test-suite.sh: -----------------------] 1101s autopkgtest [18:23:01]: test run-test-suite.sh: - - - - - - - - - - results - - - - - - - - - - 1101s run-test-suite.sh PASS 1101s autopkgtest [18:23:01]: @@@@@@@@@@@@@@@@@@@@ summary 1101s run-test-suite.sh PASS 1119s nova [W] Timed out waiting for a1f16700-e699-4efc-a7f8-c4729f452fdc to get deleted.