0s autopkgtest [12:24:14]: starting date and time: 2025-10-23 12:24:14+0000 0s autopkgtest [12:24:14]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [12:24:14]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.i0lp1aom/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:pytest,src:python-pluggy --apt-upgrade django-cas-server --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=pytest/8.4.2-1 python-pluggy/1.6.0-1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-amd64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-1.secgroup --name adt-resolute-amd64-django-cas-server-20251023-122414-juju-7f2275-prod-proposed-migration-environment-15-13ba0104-5b72-4e13-bb1e-4dad8ca695e6 --image adt/ubuntu-resolute-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-amd64-django-cas-server-20251023-122414-juju-7f2275-prod-proposed-migration-environment-15-13ba0104-5b72-4e13-bb1e-4dad8ca695e6 from image adt/ubuntu-resolute-amd64-server-20251022.img (UUID e0b5cd61-c9ee-4e30-97a7-7c67c05c2ee7)... 26s autopkgtest [12:24:40]: testbed dpkg architecture: amd64 27s autopkgtest [12:24:41]: testbed apt version: 3.1.8ubuntu1 27s autopkgtest [12:24:41]: @@@@@@@@@@@@@@@@@@@@ test bed setup 27s autopkgtest [12:24:41]: testbed release detected to be: None 28s autopkgtest [12:24:42]: updating testbed package index (apt update) 28s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [83.3 kB] 28s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 28s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 28s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 28s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [535 kB] 28s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [15.0 kB] 28s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [59.9 kB] 29s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5028 B] 29s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [140 kB] 29s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [89.5 kB] 29s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [28.0 kB] 29s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [3208 B] 29s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [350 kB] 29s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [128 kB] 29s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [6744 B] 29s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [3156 B] 29s Fetched 1447 kB in 1s (1804 kB/s) 29s Reading package lists... 30s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 30s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 30s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 30s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 30s Reading package lists... 30s Reading package lists... 31s Building dependency tree... 31s Reading state information... 31s Calculating upgrade... 31s The following packages will be upgraded: 31s python3-pyparsing python3-yaml 31s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 31s Need to get 275 kB of archives. 31s After this operation, 272 kB of additional disk space will be used. 31s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-yaml amd64 6.0.2-2 [188 kB] 31s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-pyparsing all 3.1.3-1 [87.0 kB] 31s dpkg-preconfigure: unable to re-open stdin: No such file or directory 31s Fetched 275 kB in 0s (16.4 MB/s) 31s (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 ... 78458 files and directories currently installed.) 31s Preparing to unpack .../python3-yaml_6.0.2-2_amd64.deb ... 31s Unpacking python3-yaml (6.0.2-2) over (6.0.2-1build2) ... 31s Preparing to unpack .../python3-pyparsing_3.1.3-1_all.deb ... 31s Unpacking python3-pyparsing (3.1.3-1) over (3.1.2-1) ... 31s Setting up python3-yaml (6.0.2-2) ... 31s Setting up python3-pyparsing (3.1.3-1) ... 32s autopkgtest [12:24:46]: upgrading testbed (apt dist-upgrade and autopurge) 32s Reading package lists... 32s Building dependency tree... 32s Reading state information... 32s Calculating upgrade... 32s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 32s Reading package lists... 32s Building dependency tree... 32s Reading state information... 32s Solving dependencies... 33s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 35s autopkgtest [12:24:49]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 10:00:33 UTC 2025 35s autopkgtest [12:24:49]: @@@@@@@@@@@@@@@@@@@@ apt-source django-cas-server 36s Get:1 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (dsc) [2597 B] 36s Get:2 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (tar) [138 kB] 36s Get:3 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (diff) [10.2 kB] 36s gpgv: Signature made Sat Dec 14 20:43:18 2024 UTC 36s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 36s gpgv: issuer "tchet@debian.org" 36s gpgv: Can't check signature: No public key 36s dpkg-source: warning: cannot verify inline signature for ./django-cas-server_2.1.0-3.dsc: no acceptable signature found 36s autopkgtest [12:24:50]: testing package django-cas-server version 2.1.0-3 37s autopkgtest [12:24:51]: build not needed 37s autopkgtest [12:24:51]: test run-test-suite.sh: preparing testbed 37s Reading package lists... 37s Building dependency tree... 37s Reading state information... 38s Solving dependencies... 38s The following NEW packages will be installed: 38s fonts-font-awesome fonts-lato libjs-jquery libjs-sphinxdoc libjs-underscore 38s libxslt1.1 python3-all python3-asgiref python3-django 38s python3-django-cas-server python3-django-cas-server-doc python3-iniconfig 38s python3-legacy-cgi python3-lxml python3-packaging python3-pluggy 38s python3-pytest python3-pytest-django python3-requests-futures 38s python3-sqlparse sphinx-rtd-theme-common 38s 0 upgraded, 21 newly installed, 0 to remove and 0 not upgraded. 38s Need to get 11.1 MB of archives. 38s After this operation, 58.1 MB of additional disk space will be used. 38s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 fonts-lato all 2.015-1 [2781 kB] 38s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 38s Get:3 http://ftpmaster.internal/ubuntu resolute/main amd64 libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 38s Get:4 http://ftpmaster.internal/ubuntu resolute/main amd64 libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 38s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 libjs-sphinxdoc all 8.2.3-1ubuntu2 [28.0 kB] 38s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 libxslt1.1 amd64 1.1.43-0.1 [172 kB] 38s Get:7 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-all amd64 3.13.7-1 [884 B] 38s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-asgiref all 3.9.1-2 [24.9 kB] 38s Get:9 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-sqlparse all 0.5.3-1 [32.9 kB] 38s Get:10 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-django all 3:5.2.4-1ubuntu2 [3036 kB] 38s Get:11 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-legacy-cgi all 2.6.3-1 [16.8 kB] 38s Get:12 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-lxml amd64 6.0.1-1build1 [2336 kB] 38s Get:13 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-requests-futures all 1.0.2-1 [12.0 kB] 38s Get:14 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-django-cas-server all 2.1.0-3 [111 kB] 38s Get:15 http://ftpmaster.internal/ubuntu resolute/main amd64 sphinx-rtd-theme-common all 3.0.2+dfsg-3 [1013 kB] 38s Get:16 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-django-cas-server-doc all 2.1.0-3 [178 kB] 38s Get:17 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-iniconfig all 1.1.1-2 [6024 B] 38s Get:18 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-packaging all 25.0-1 [52.8 kB] 38s Get:19 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pluggy all 1.6.0-1 [21.0 kB] 38s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 python3-pytest all 8.4.2-1 [268 kB] 38s Get:21 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest-django all 4.5.2-3 [18.5 kB] 39s Fetched 11.1 MB in 1s (15.0 MB/s) 39s Selecting previously unselected package fonts-lato. 39s (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 ... 78459 files and directories currently installed.) 39s Preparing to unpack .../00-fonts-lato_2.015-1_all.deb ... 39s Unpacking fonts-lato (2.015-1) ... 39s Selecting previously unselected package fonts-font-awesome. 39s Preparing to unpack .../01-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 39s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 39s Selecting previously unselected package libjs-jquery. 39s Preparing to unpack .../02-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 39s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 39s Selecting previously unselected package libjs-underscore. 39s Preparing to unpack .../03-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 39s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 39s Selecting previously unselected package libjs-sphinxdoc. 39s Preparing to unpack .../04-libjs-sphinxdoc_8.2.3-1ubuntu2_all.deb ... 39s Unpacking libjs-sphinxdoc (8.2.3-1ubuntu2) ... 39s Selecting previously unselected package libxslt1.1:amd64. 39s Preparing to unpack .../05-libxslt1.1_1.1.43-0.1_amd64.deb ... 39s Unpacking libxslt1.1:amd64 (1.1.43-0.1) ... 39s Selecting previously unselected package python3-all. 39s Preparing to unpack .../06-python3-all_3.13.7-1_amd64.deb ... 39s Unpacking python3-all (3.13.7-1) ... 39s Selecting previously unselected package python3-asgiref. 39s Preparing to unpack .../07-python3-asgiref_3.9.1-2_all.deb ... 39s Unpacking python3-asgiref (3.9.1-2) ... 39s Selecting previously unselected package python3-sqlparse. 39s Preparing to unpack .../08-python3-sqlparse_0.5.3-1_all.deb ... 39s Unpacking python3-sqlparse (0.5.3-1) ... 39s Selecting previously unselected package python3-django. 39s Preparing to unpack .../09-python3-django_3%3a5.2.4-1ubuntu2_all.deb ... 39s Unpacking python3-django (3:5.2.4-1ubuntu2) ... 39s Selecting previously unselected package python3-legacy-cgi. 39s Preparing to unpack .../10-python3-legacy-cgi_2.6.3-1_all.deb ... 39s Unpacking python3-legacy-cgi (2.6.3-1) ... 39s Selecting previously unselected package python3-lxml:amd64. 39s Preparing to unpack .../11-python3-lxml_6.0.1-1build1_amd64.deb ... 39s Unpacking python3-lxml:amd64 (6.0.1-1build1) ... 39s Selecting previously unselected package python3-requests-futures. 39s Preparing to unpack .../12-python3-requests-futures_1.0.2-1_all.deb ... 39s Unpacking python3-requests-futures (1.0.2-1) ... 39s Selecting previously unselected package python3-django-cas-server. 39s Preparing to unpack .../13-python3-django-cas-server_2.1.0-3_all.deb ... 39s Unpacking python3-django-cas-server (2.1.0-3) ... 39s Selecting previously unselected package sphinx-rtd-theme-common. 39s Preparing to unpack .../14-sphinx-rtd-theme-common_3.0.2+dfsg-3_all.deb ... 39s Unpacking sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 39s Selecting previously unselected package python3-django-cas-server-doc. 39s Preparing to unpack .../15-python3-django-cas-server-doc_2.1.0-3_all.deb ... 39s Unpacking python3-django-cas-server-doc (2.1.0-3) ... 39s Selecting previously unselected package python3-iniconfig. 39s Preparing to unpack .../16-python3-iniconfig_1.1.1-2_all.deb ... 39s Unpacking python3-iniconfig (1.1.1-2) ... 39s Selecting previously unselected package python3-packaging. 39s Preparing to unpack .../17-python3-packaging_25.0-1_all.deb ... 39s Unpacking python3-packaging (25.0-1) ... 39s Selecting previously unselected package python3-pluggy. 39s Preparing to unpack .../18-python3-pluggy_1.6.0-1_all.deb ... 39s Unpacking python3-pluggy (1.6.0-1) ... 39s Selecting previously unselected package python3-pytest. 39s Preparing to unpack .../19-python3-pytest_8.4.2-1_all.deb ... 39s Unpacking python3-pytest (8.4.2-1) ... 39s Selecting previously unselected package python3-pytest-django. 39s Preparing to unpack .../20-python3-pytest-django_4.5.2-3_all.deb ... 39s Unpacking python3-pytest-django (4.5.2-3) ... 39s Setting up python3-iniconfig (1.1.1-2) ... 39s Setting up fonts-lato (2.015-1) ... 39s Setting up python3-all (3.13.7-1) ... 39s Setting up python3-packaging (25.0-1) ... 39s Setting up python3-sqlparse (0.5.3-1) ... 39s Setting up python3-pluggy (1.6.0-1) ... 39s Setting up python3-legacy-cgi (2.6.3-1) ... 39s Setting up libxslt1.1:amd64 (1.1.43-0.1) ... 39s Setting up python3-requests-futures (1.0.2-1) ... 40s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 40s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 40s Setting up sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 40s Setting up python3-asgiref (3.9.1-2) ... 40s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 40s Setting up python3-django (3:5.2.4-1ubuntu2) ... 40s Setting up python3-pytest (8.4.2-1) ... 40s Setting up python3-pytest-django (4.5.2-3) ... 41s Setting up libjs-sphinxdoc (8.2.3-1ubuntu2) ... 41s Setting up python3-lxml:amd64 (6.0.1-1build1) ... 41s Setting up python3-django-cas-server (2.1.0-3) ... 41s Setting up python3-django-cas-server-doc (2.1.0-3) ... 41s Processing triggers for man-db (2.13.1-1) ... 41s Processing triggers for libc-bin (2.42-0ubuntu3) ... 42s autopkgtest [12:24:56]: test run-test-suite.sh: [----------------------- 42s 42s *************************** 42s *** Testing with python3.13 42s *************************** 42s 42s Content of current working folder: 42s 42s total 20 42s drwxr-xr-x 3 ubuntu ubuntu 4096 Oct 23 12:24 . 42s drwxrwxrwx 5 root root 4096 Oct 23 12:24 .. 42s drwxrwxr-x 9 ubuntu ubuntu 4096 Oct 23 12:24 cas_server 42s -rw-rw-r-- 1 ubuntu ubuntu 140 Aug 18 2024 pytest.ini 42s -rw-rw-r-- 1 ubuntu ubuntu 152 Aug 18 2024 setup.cfg 42s Running tests... 42s 42s ============================= test session starts ============================== 42s platform linux -- Python 3.13.9, pytest-8.4.2, pluggy-1.6.0 -- /usr/bin/python3.13 42s cachedir: .pytest_cache 42s django: settings: cas_server.tests.settings (from ini) 42s rootdir: /tmp/autopkgtest.TKigrW/autopkgtest_tmp 42s configfile: pytest.ini 42s testpaths: cas_server/tests/ 42s plugins: django-4.5.2, typeguard-4.4.2 42s collecting ... collected 128 items 42s 42s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 43s 2025-10-23 12:24:56,109 WARNING Identity provider suffix exemple.fr not found 43s 2025-10-23 12:24:56,117 INFO Got an invalid ticket ST-rygZXOzoKEunyrPNWSWgrPm0Hpc5GVconH8N7mBfX8b8fU4QE6HGV0HXfjXjM from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 43s 2025-10-23 12:24:56,119 INFO Got an invalid ticket ST-2G31ECIE6D1IBymm1gYBDqEWnABoQA9BROn0bFbDpRhcJv08LsUBIdX1LG2Ro from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 43s PASSED 43s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2025-10-23 12:24:56,137 WARNING CAS_FEDERATE is False, set it to True to use federation 43s 2025-10-23 12:24:56,137 WARNING CAS_FEDERATE is False, set it to True to use federation 43s PASSED 43s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2025-10-23 12:24:56,146 INFO Trying to authenticate http://127.0.0.1:8080 again 43s 2025-10-23 12:24:56,149 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 43s 2025-10-23 12:24:56,159 INFO User dédé@example.com successfully authenticated 43s 2025-10-23 12:24:56,165 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 43s 2025-10-23 12:24:56,171 INFO Trying to authenticate http://127.0.0.1:8081 again 43s 2025-10-23 12:24:56,174 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 43s 2025-10-23 12:24:56,180 INFO User dédé@example.org successfully authenticated 43s 2025-10-23 12:24:56,185 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 43s 2025-10-23 12:24:56,191 INFO Trying to authenticate http://127.0.0.1:8082 again 43s 2025-10-23 12:24:56,194 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 43s 2025-10-23 12:24:56,199 INFO User dédé@example.net successfully authenticated 43s 2025-10-23 12:24:56,205 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 43s 2025-10-23 12:24:56,210 INFO Trying to authenticate http://127.0.0.1:8083 again 43s 2025-10-23 12:24:56,214 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 43s 2025-10-23 12:24:56,219 INFO User dédé@example.test successfully authenticated 43s 2025-10-23 12:24:56,224 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 43s 2025-10-23 12:24:56,244 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-y8VdMITP3KTE5as8UcIkIOQXA4vxbOWraJTq2SihWkctIQcsLmigMv3Zklqwm 43s 2025-10-23 12:24:56,257 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-L4pX4FpiE5UYgA6X1Xm51N9zunDgmOiQuO1lt3mnWaMkOg977R5s7dxClkwQa 43s PASSED 43s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 43s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2025-10-23 12:24:56,275 INFO Trying to authenticate http://127.0.0.1:8080 again 43s 2025-10-23 12:24:56,278 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 43s 2025-10-23 12:24:56,283 INFO User dédé@example.com successfully authenticated 43s 2025-10-23 12:24:56,288 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 43s 2025-10-23 12:24:56,294 INFO Trying to authenticate http://127.0.0.1:8081 again 43s 2025-10-23 12:24:56,297 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 43s 2025-10-23 12:24:56,303 INFO User dédé@example.org successfully authenticated 43s 2025-10-23 12:24:56,308 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 43s 2025-10-23 12:24:56,313 INFO Trying to authenticate http://127.0.0.1:8082 again 43s 2025-10-23 12:24:56,316 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 43s 2025-10-23 12:24:56,321 INFO User dédé@example.net successfully authenticated 43s 2025-10-23 12:24:56,327 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 43s 2025-10-23 12:24:56,332 INFO Trying to authenticate http://127.0.0.1:8083 again 43s 2025-10-23 12:24:56,335 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 43s 2025-10-23 12:24:56,341 INFO User dédé@example.test successfully authenticated 43s 2025-10-23 12:24:56,346 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 43s 2025-10-23 12:24:56,346 INFO logout requested 43s 2025-10-23 12:24:56,347 INFO Logging out user dédé@example.com. 43s 2025-10-23 12:24:56,350 INFO User dédé@example.com logged out 43s 2025-10-23 12:24:56,354 INFO logout requested 43s 2025-10-23 12:24:56,356 INFO logout requested 43s 2025-10-23 12:24:56,357 INFO Logging out user dédé@example.org. 43s 2025-10-23 12:24:56,360 INFO User dédé@example.org logged out 43s 2025-10-23 12:24:56,364 INFO logout requested 43s 2025-10-23 12:24:56,366 INFO logout requested 43s 2025-10-23 12:24:56,367 INFO Logging out user dédé@example.net. 43s 2025-10-23 12:24:56,369 INFO User dédé@example.net logged out 43s 2025-10-23 12:24:56,374 INFO logout requested 43s 2025-10-23 12:24:56,375 INFO logout requested 43s 2025-10-23 12:24:56,376 INFO Logging out user dédé@example.test. 43s 2025-10-23 12:24:56,379 INFO User dédé@example.test logged out 43s 2025-10-23 12:24:56,383 INFO logout requested 43s 2025-10-23 12:24:56,390 INFO Trying to authenticate http://127.0.0.1:8080 again 43s 2025-10-23 12:24:56,393 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 43s 2025-10-23 12:24:56,398 INFO User dédé@example.com successfully authenticated 43s 2025-10-23 12:24:56,404 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 43s 2025-10-23 12:24:56,411 INFO Trying to authenticate http://127.0.0.1:8081 again 43s 2025-10-23 12:24:56,414 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 43s 2025-10-23 12:24:56,419 INFO User dédé@example.org successfully authenticated 43s 2025-10-23 12:24:56,424 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 43s 2025-10-23 12:24:56,455 INFO Trying to authenticate http://127.0.0.1:8082 again 43s 2025-10-23 12:24:56,458 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 43s 2025-10-23 12:24:56,463 INFO User dédé@example.net successfully authenticated 43s 2025-10-23 12:24:56,468 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 43s 2025-10-23 12:24:56,474 INFO Trying to authenticate http://127.0.0.1:8083 again 43s 2025-10-23 12:24:56,477 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 43s 2025-10-23 12:24:56,482 INFO User dédé@example.test successfully authenticated 43s 2025-10-23 12:24:56,488 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 43s 2025-10-23 12:24:56,489 INFO logout requested 43s 2025-10-23 12:24:56,489 INFO Logging out user dédé. 43s 2025-10-23 12:24:56,490 INFO User dédé logged out 43s 2025-10-23 12:24:56,495 INFO logout requested 43s 2025-10-23 12:24:56,496 INFO Logging out user dédé. 43s 2025-10-23 12:24:56,496 INFO User dédé logged out 43s 2025-10-23 12:24:56,501 INFO logout requested 43s 2025-10-23 12:24:56,502 INFO Logging out user dédé. 43s 2025-10-23 12:24:56,502 INFO User dédé logged out 43s 2025-10-23 12:24:56,507 INFO logout requested 43s 2025-10-23 12:24:56,508 INFO Logging out user dédé. 43s 2025-10-23 12:24:56,508 INFO User dédé logged out 43s PASSED 43s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2025-10-23 12:24:56,521 INFO Trying to authenticate http://127.0.0.1:8080 again 43s 2025-10-23 12:24:56,524 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 43s 2025-10-23 12:24:56,529 INFO User dédé@example.com successfully authenticated 43s 2025-10-23 12:24:56,534 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 43s 2025-10-23 12:24:56,540 INFO Trying to authenticate http://127.0.0.1:8081 again 43s 2025-10-23 12:24:56,543 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 43s 2025-10-23 12:24:56,548 INFO User dédé@example.org successfully authenticated 43s 2025-10-23 12:24:56,553 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 43s 2025-10-23 12:24:56,559 INFO Trying to authenticate http://127.0.0.1:8082 again 43s 2025-10-23 12:24:56,562 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 43s 2025-10-23 12:24:56,567 INFO User dédé@example.net successfully authenticated 43s 2025-10-23 12:24:56,572 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 43s 2025-10-23 12:24:56,578 INFO Trying to authenticate http://127.0.0.1:8083 again 43s 2025-10-23 12:24:56,581 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 43s 2025-10-23 12:24:56,586 INFO User dédé@example.test successfully authenticated 43s 2025-10-23 12:24:56,592 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 43s 2025-10-23 12:24:56,592 INFO logout requested 43s 2025-10-23 12:24:56,593 INFO Logging out user dédé@example.com. 43s 2025-10-23 12:24:56,596 INFO User dédé@example.com logged out 43s 2025-10-23 12:24:56,596 INFO logout requested 43s 2025-10-23 12:24:56,597 INFO Logging out user dédé@example.org. 43s 2025-10-23 12:24:56,599 INFO User dédé@example.org logged out 43s 2025-10-23 12:24:56,600 INFO logout requested 43s 2025-10-23 12:24:56,601 INFO Logging out user dédé@example.net. 43s 2025-10-23 12:24:56,603 INFO User dédé@example.net logged out 43s 2025-10-23 12:24:56,603 INFO logout requested 43s 2025-10-23 12:24:56,604 INFO Logging out user dédé@example.test. 43s 2025-10-23 12:24:56,607 INFO User dédé@example.test logged out 43s PASSED 43s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2025-10-23 12:24:56,615 WARNING A login attempt failed 43s PASSED 43s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 43s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2025-10-23 12:24:56,632 INFO Trying to authenticate http://127.0.0.1:8080 again 43s 2025-10-23 12:24:56,635 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 43s 2025-10-23 12:24:56,640 INFO User dédé@example.com successfully authenticated 43s 2025-10-23 12:24:56,645 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 43s 2025-10-23 12:24:56,651 INFO Trying to authenticate http://127.0.0.1:8081 again 43s 2025-10-23 12:24:56,654 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 43s 2025-10-23 12:24:56,659 INFO User dédé@example.org successfully authenticated 43s 2025-10-23 12:24:56,664 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 43s 2025-10-23 12:24:56,670 INFO Trying to authenticate http://127.0.0.1:8082 again 43s 2025-10-23 12:24:56,673 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 43s 2025-10-23 12:24:56,678 INFO User dédé@example.net successfully authenticated 43s 2025-10-23 12:24:56,683 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 43s 2025-10-23 12:24:56,690 INFO Trying to authenticate http://127.0.0.1:8083 again 43s 2025-10-23 12:24:56,693 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 43s 2025-10-23 12:24:56,698 INFO User dédé@example.test successfully authenticated 43s 2025-10-23 12:24:56,703 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 43s PASSED 43s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2025-10-23 12:24:56,713 INFO Trying to authenticate http://127.0.0.1:8080 again 43s 2025-10-23 12:24:56,716 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 43s 2025-10-23 12:24:56,721 INFO User dédé@example.com successfully authenticated 43s 2025-10-23 12:24:56,726 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 43s 2025-10-23 12:24:56,732 INFO Trying to authenticate http://127.0.0.1:8081 again 43s 2025-10-23 12:24:56,735 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 43s 2025-10-23 12:24:56,740 INFO User dédé@example.org successfully authenticated 43s 2025-10-23 12:24:56,745 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 43s 2025-10-23 12:24:56,751 INFO Trying to authenticate http://127.0.0.1:8082 again 43s 2025-10-23 12:24:56,753 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 43s 2025-10-23 12:24:56,759 INFO User dédé@example.net successfully authenticated 43s 2025-10-23 12:24:56,764 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 43s 2025-10-23 12:24:56,770 INFO Trying to authenticate http://127.0.0.1:8083 again 43s 2025-10-23 12:24:56,773 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 43s 2025-10-23 12:24:56,778 INFO User dédé@example.test successfully authenticated 43s 2025-10-23 12:24:56,783 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 43s 2025-10-23 12:24:56,789 INFO Trying to authenticate http://127.0.0.1:8080 again 43s 2025-10-23 12:24:56,791 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 43s 2025-10-23 12:24:56,797 INFO User dédé@example.com successfully authenticated 43s 2025-10-23 12:24:56,802 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 43s 2025-10-23 12:24:56,808 INFO Trying to authenticate http://127.0.0.1:8081 again 43s 2025-10-23 12:24:56,810 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 44s 2025-10-23 12:24:56,816 INFO User dédé@example.org successfully authenticated 44s 2025-10-23 12:24:56,821 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 44s 2025-10-23 12:24:56,826 INFO Trying to authenticate http://127.0.0.1:8082 again 44s 2025-10-23 12:24:56,830 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 44s 2025-10-23 12:24:56,835 INFO User dédé@example.net successfully authenticated 44s 2025-10-23 12:24:56,840 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 44s 2025-10-23 12:24:56,846 INFO Trying to authenticate http://127.0.0.1:8083 again 44s 2025-10-23 12:24:56,849 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 44s 2025-10-23 12:24:56,854 INFO User dédé@example.test successfully authenticated 44s 2025-10-23 12:24:56,859 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 44s 2025-10-23 12:24:56,860 WARNING User already authenticated, dropping federated authentication request 44s 2025-10-23 12:24:56,861 WARNING User already authenticated, dropping federated authentication request 44s 2025-10-23 12:24:56,862 WARNING User already authenticated, dropping federated authentication request 44s 2025-10-23 12:24:56,862 WARNING User already authenticated, dropping federated authentication request 44s PASSED 44s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2025-10-23 12:24:56,871 INFO Trying to authenticate http://127.0.0.1:8080 again 44s 2025-10-23 12:24:56,874 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 44s 2025-10-23 12:24:56,879 INFO User dédé@example.com successfully authenticated 44s 2025-10-23 12:24:56,884 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 44s 2025-10-23 12:24:56,890 INFO Trying to authenticate http://127.0.0.1:8081 again 44s 2025-10-23 12:24:56,893 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 44s 2025-10-23 12:24:56,898 INFO User dédé@example.org successfully authenticated 44s 2025-10-23 12:24:56,903 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 44s 2025-10-23 12:24:56,909 INFO Trying to authenticate http://127.0.0.1:8082 again 44s 2025-10-23 12:24:56,911 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 44s 2025-10-23 12:24:56,917 INFO User dédé@example.net successfully authenticated 44s 2025-10-23 12:24:56,922 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 44s 2025-10-23 12:24:56,930 INFO Trying to authenticate http://127.0.0.1:8083 again 44s 2025-10-23 12:24:56,933 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 44s 2025-10-23 12:24:56,938 INFO User dédé@example.test successfully authenticated 44s 2025-10-23 12:24:56,943 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 44s 2025-10-23 12:24:56,943 INFO logout requested 44s 2025-10-23 12:24:56,944 INFO Logging out user dédé@example.com. 44s 2025-10-23 12:24:56,947 INFO User dédé@example.com logged out 44s 2025-10-23 12:24:56,949 INFO logout requested 44s 2025-10-23 12:24:56,950 INFO Logging out user dédé@example.org. 44s 2025-10-23 12:24:56,952 INFO User dédé@example.org logged out 44s 2025-10-23 12:24:56,954 INFO logout requested 44s 2025-10-23 12:24:56,955 INFO Logging out user dédé@example.net. 44s 2025-10-23 12:24:56,957 INFO User dédé@example.net logged out 44s 2025-10-23 12:24:56,958 INFO logout requested 44s 2025-10-23 12:24:56,959 INFO Logging out user dédé@example.test. 44s 2025-10-23 12:24:56,962 INFO User dédé@example.test logged out 44s PASSED 44s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2025-10-23 12:24:56,971 INFO Trying to authenticate http://127.0.0.1:8080 again 44s 2025-10-23 12:24:56,974 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 44s 2025-10-23 12:24:56,979 INFO User dédé@example.com successfully authenticated 44s 2025-10-23 12:24:56,984 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 44s 2025-10-23 12:24:56,990 INFO Trying to authenticate http://127.0.0.1:8081 again 44s 2025-10-23 12:24:56,993 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 44s 2025-10-23 12:24:56,998 INFO User dédé@example.org successfully authenticated 44s 2025-10-23 12:24:57,003 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 44s 2025-10-23 12:24:57,009 INFO Trying to authenticate http://127.0.0.1:8082 again 44s 2025-10-23 12:24:57,012 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 44s 2025-10-23 12:24:57,017 INFO User dédé@example.net successfully authenticated 44s 2025-10-23 12:24:57,023 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 44s 2025-10-23 12:24:57,028 INFO Trying to authenticate http://127.0.0.1:8083 again 44s 2025-10-23 12:24:57,031 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 44s 2025-10-23 12:24:57,037 INFO User dédé@example.test successfully authenticated 44s 2025-10-23 12:24:57,042 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 44s 2025-10-23 12:24:57,045 INFO Trying to authenticate http://127.0.0.1:8080 again 44s 2025-10-23 12:24:57,047 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 44s 2025-10-23 12:24:57,053 INFO User dédé@example.com successfully authenticated 44s 2025-10-23 12:24:57,055 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 44s 2025-10-23 12:24:57,058 INFO Trying to authenticate http://127.0.0.1:8081 again 44s 2025-10-23 12:24:57,061 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 44s 2025-10-23 12:24:57,067 INFO User dédé@example.org successfully authenticated 44s 2025-10-23 12:24:57,070 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 44s 2025-10-23 12:24:57,073 INFO Trying to authenticate http://127.0.0.1:8082 again 44s 2025-10-23 12:24:57,075 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 44s 2025-10-23 12:24:57,081 INFO User dédé@example.net successfully authenticated 44s 2025-10-23 12:24:57,084 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 44s 2025-10-23 12:24:57,087 INFO Trying to authenticate http://127.0.0.1:8083 again 44s 2025-10-23 12:24:57,090 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 44s 2025-10-23 12:24:57,096 INFO User dédé@example.test successfully authenticated 44s 2025-10-23 12:24:57,098 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 44s PASSED 44s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 44s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 44s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 44s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2025-10-23 12:24:57,122 INFO User test successfully authenticated 44s PASSED 44s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2025-10-23 12:24:57,131 INFO User test successfully authenticated 44s PASSED 44s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2025-10-23 12:24:57,139 INFO User test successfully authenticated 44s 2025-10-23 12:24:57,145 INFO User test successfully authenticated 44s 2025-10-23 12:24:57,150 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 44s 2025-10-23 12:24:57,153 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')) 44s PASSED 44s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2025-10-23 12:24:57,160 INFO User test successfully authenticated 44s PASSED 44s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2025-10-23 12:24:57,169 INFO User test successfully authenticated 44s PASSED 44s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2025-10-23 12:24:57,180 INFO User test successfully authenticated 44s 2025-10-23 12:24:57,187 INFO Sending SLO requests to service http://127.0.0.1:33455 for user test 44s 2025-10-23 12:24:57,188 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 44s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 44s PASSED 44s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2025-10-23 12:24:57,195 INFO User test successfully authenticated 44s PASSED 44s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 44s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 44s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 44s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 44s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 44s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 44s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 44s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 44s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 44s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 44s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 44s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 44s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 44s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 44s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 44s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 44s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 44s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 44s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 44s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 44s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 44s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 44s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2025-10-23 12:24:57,526 INFO User test successfully authenticated 44s 2025-10-23 12:24:57,529 WARNING Service https://www.example.org not allowed. 44s PASSED 44s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success 2025-10-23 12:24:57,536 INFO User test successfully authenticated 44s 2025-10-23 12:24:57,540 INFO Service ticket created for service https://www.example.com by user test. 44s PASSED 44s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt 2025-10-23 12:24:57,546 INFO User test successfully authenticated 44s 2025-10-23 12:24:57,550 INFO Service ticket created for service https://www.example.com by user test. 44s PASSED 44s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn 2025-10-23 12:24:57,557 INFO User test successfully authenticated 44s PASSED 44s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged 2025-10-23 12:24:57,566 INFO User test successfully authenticated 44s PASSED 44s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2025-10-23 12:24:57,575 INFO User test successfully authenticated 44s 2025-10-23 12:24:57,578 WARNING User test seems authenticated but is not found in the database. 44s 2025-10-23 12:24:57,578 INFO Logging out user test. 44s 2025-10-23 12:24:57,579 INFO User test logged out 44s PASSED 44s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 44s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2025-10-23 12:24:57,588 INFO User test successfully authenticated 44s 2025-10-23 12:24:57,591 WARNING Username test not allowed on service restrict_user_fail 44s PASSED 44s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2025-10-23 12:24:57,599 WARNING A login attempt failed 44s 2025-10-23 12:24:57,606 WARNING A login attempt failed 44s 2025-10-23 12:24:57,613 WARNING A login attempt failed 44s PASSED 44s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2025-10-23 12:24:57,623 WARNING Received an invalid login ticket 44s PASSED 44s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2025-10-23 12:24:57,632 WARNING A login attempt failed 44s PASSED 44s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2025-10-23 12:24:57,641 INFO User test successfully authenticated 44s PASSED 44s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2025-10-23 12:24:57,650 INFO User test successfully authenticated 44s PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_renew 2025-10-23 12:24:58,126 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,132 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,134 INFO Service ticket created for service https://www.example.com by user test. 45s PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2025-10-23 12:24:58,141 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,147 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,149 INFO Service ticket created for service https://www.example.com by user test. 45s PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2025-10-23 12:24:58,156 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,159 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 45s 2025-10-23 12:24:58,161 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 45s 2025-10-23 12:24:58,163 INFO Service ticket created for service https://filter_success.example.com by user test. 45s PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2025-10-23 12:24:58,170 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,173 WARNING Username test not allowed on service restrict_user_fail 45s 2025-10-23 12:24:58,176 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 45s PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2025-10-23 12:24:58,182 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,185 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 45s 2025-10-23 12:24:58,188 INFO Service ticket created for service https://field_needed_success.example.com by user test. 45s PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2025-10-23 12:24:58,195 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,197 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 45s PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2025-10-23 12:24:58,204 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,208 WARNING User test seems authenticated but is not found in the database. 45s 2025-10-23 12:24:58,208 INFO Logging out user test. 45s 2025-10-23 12:24:58,208 INFO User test logged out 45s PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2025-10-23 12:24:58,225 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,229 INFO Service ticket created for service https://www.example.com/é by user test. 45s PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2025-10-23 12:24:58,236 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,240 INFO Service ticket created for service https://www.example.com by user test. 45s PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2025-10-23 12:24:58,247 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,252 WARNING Received a login attempt for an already-active user 45s 2025-10-23 12:24:58,255 INFO Service ticket created for service https://www.example.com by user test. 45s PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2025-10-23 12:24:58,261 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,264 WARNING Service https://www.example.org not allowed. 45s PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2025-10-23 12:24:58,268 WARNING Service https://www.example.net not allowed. 45s PASSED 45s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2025-10-23 12:24:58,273 WARNING Service https://www.example.net not allowed. 45s PASSED 45s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2025-10-23 12:24:58,281 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,283 INFO logout requested 45s 2025-10-23 12:24:58,283 INFO Logging out user test. 45s 2025-10-23 12:24:58,285 INFO User test logged out 45s PASSED 45s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2025-10-23 12:24:58,290 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,296 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,301 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,307 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,312 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,318 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,323 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,329 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,335 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,337 INFO logout requested 45s 2025-10-23 12:24:58,337 INFO Logging out user test from all sessions. 45s 2025-10-23 12:24:58,352 INFO User test logged out 45s PASSED 45s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2025-10-23 12:24:58,358 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,360 INFO logout requested 45s 2025-10-23 12:24:58,360 INFO Logging out user test. 45s 2025-10-23 12:24:58,362 INFO User test logged out 45s PASSED 45s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2025-10-23 12:24:58,364 INFO logout requested 45s PASSED 45s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2025-10-23 12:24:58,370 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,375 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,377 INFO logout requested 45s 2025-10-23 12:24:58,378 INFO Logging out user test from all sessions. 45s 2025-10-23 12:24:58,381 INFO User test logged out 45s PASSED 45s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2025-10-23 12:24:58,388 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,392 INFO Service ticket created for service http://127.0.0.1:33379 by user test. 45s 2025-10-23 12:24:58,394 INFO Validate: Service ticket ST-rV3vHsAXVIcfGyf7uHJQmQKtlrWysaRwKtPJoZ3GgULAsh56oDI3ebGUXlgia validated, user test authenticated on service http://127.0.0.1:33379 45s 2025-10-23 12:24:58,395 INFO logout requested 45s 2025-10-23 12:24:58,395 INFO Logging out user test. 45s 2025-10-23 12:24:58,397 INFO Sending SLO requests to service http://127.0.0.1:33379 for user test 45s 2025-10-23 12:24:58,399 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 45s 2025-10-23 12:24:58,400 INFO User test logged out 45s 2025-10-23 12:24:58,404 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,408 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 45s 2025-10-23 12:24:58,410 INFO Validate: Service ticket ST-GhjmniZzLDj9ss9d8Auk682d8bsOJMJohCBZGbXhocVlLfQejtVMc1Px4Me3T validated, user test authenticated on service http://127.0.0.1:45678 45s 2025-10-23 12:24:58,411 INFO logout requested 45s 2025-10-23 12:24:58,411 INFO Logging out user test. 45s 2025-10-23 12:24:58,412 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 45s 2025-10-23 12:24:58,415 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 45s 2025-10-23 12:24:58,415 INFO User test logged out 45s 2025-10-23 12:24:58,419 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,423 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 45s 2025-10-23 12:24:58,425 INFO Validate: Service ticket ST-N5JQCEitpBi59M8fbKrHoFkOvFuZUEC3wfuiaUFVHnyOGD97pV8TVkM8n1dab validated, user test authenticated on service http://127.0.0.1:45678 45s 2025-10-23 12:24:58,426 INFO logout requested 45s 2025-10-23 12:24:58,426 INFO Logging out user test. 45s 2025-10-23 12:24:58,428 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 45s 2025-10-23 12:24:58,429 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=39253): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 45s 2025-10-23 12:24:58,430 INFO User test logged out 45s PASSED 45s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2025-10-23 12:24:58,436 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,441 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,445 INFO logout requested 45s 2025-10-23 12:24:58,445 INFO Logging out user test. 45s 2025-10-23 12:24:58,447 INFO User test logged out 45s PASSED 45s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2025-10-23 12:24:58,456 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,458 INFO logout requested 45s 2025-10-23 12:24:58,459 INFO Logging out user test. 45s 2025-10-23 12:24:58,461 INFO User test logged out 45s PASSED 45s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2025-10-23 12:24:58,469 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,471 INFO logout requested 45s 2025-10-23 12:24:58,471 INFO Logging out user test. 45s 2025-10-23 12:24:58,473 INFO User test logged out 45s PASSED 45s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2025-10-23 12:24:58,481 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,483 INFO logout requested 45s 2025-10-23 12:24:58,483 INFO Logging out user test. 45s 2025-10-23 12:24:58,485 INFO User test logged out 45s PASSED 45s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2025-10-23 12:24:58,491 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,493 INFO logout requested 45s 2025-10-23 12:24:58,493 INFO Logging out user test. 45s 2025-10-23 12:24:58,495 INFO User test logged out 45s 2025-10-23 12:24:58,498 INFO logout requested 45s PASSED 45s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 45s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 45s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice PASSED 45s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings 2025-10-23 12:24:58,508 WARNING Service https://www.example.org not allowed. 45s PASSED 45s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 45s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2025-10-23 12:24:58,518 INFO User test successfully authenticated 45s PASSED 45s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 45s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2025-10-23 12:24:58,529 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,533 INFO Service ticket created for service https://www.example.com by user test. 45s 2025-10-23 12:24:58,534 WARNING Validate: service or ticket missing 45s 2025-10-23 12:24:58,535 WARNING Validate: service or ticket missing 45s PASSED 45s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2025-10-23 12:24:58,540 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,542 INFO Service ticket created for service https://www.example.com by user test. 45s 2025-10-23 12:24:58,544 INFO Validate: Service ticket ST-cGWop4owL9kStN7ToLQVBxvp5xkLeVeXHviIxa7eGVwcH6OdOqbqU4DwndVi7 validated, user test authenticated on service https://www.example.com 45s 2025-10-23 12:24:58,548 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,550 INFO Service ticket created for service https://www.example.com by user test. 45s 2025-10-23 12:24:58,552 INFO Validate: Service ticket ST-xdp2Pl3VNDHy2UE5ujEzxf81yZ2hcHxkkFl0lc89kvKepQxXCVn8fRwOIWNDS validated, user test authenticated on service https://www.example.com 45s 2025-10-23 12:24:58,554 INFO Service ticket created for service https://www.example.com by user test. 45s 2025-10-23 12:24:58,555 WARNING Validate: Service ticket ST-AkOSDrbRCWNSPYJSNSh5BLMQKwtgQvl52JIJKw7t81NDNdGMAp6Aqdr9Z2GO1 not found or already validated, auth to https://www.example.com failed 45s PASSED 45s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2025-10-23 12:24:58,561 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,565 INFO Service ticket created for service https://user_field.example.com by user test. 45s 2025-10-23 12:24:58,567 INFO Validate: Service ticket ST-UeMyQ7e2hal0HVwY9hdafu2mRGJTyeFzn6dCBSC1Q4LOpnYWAYEz4O0pm7ghb validated, user test authenticated on service https://user_field.example.com 45s 2025-10-23 12:24:58,571 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,575 INFO Service ticket created for service https://user_field_alt.example.com by user test. 45s 2025-10-23 12:24:58,577 INFO Validate: Service ticket ST-ROklFKsF1CMg9aRboOq20D5lREMUADm60Vq7NbgqYKUlAFqzPJ3ZfHcFczyAN validated, user test authenticated on service https://user_field_alt.example.com 45s PASSED 45s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2025-10-23 12:24:58,583 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,587 INFO Service ticket created for service https://www.example.com by user test. 45s PASSED2025-10-23 12:24:58,589 WARNING Validate: Service ticket ST-tYXPXqNjzkMwrK9h0vLAuQClI3xzmQQUCiJUzptFx4d3BonKzoNIMP9pOu1WS not found or already validated, auth to https://www.example.org failed 45s 45s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2025-10-23 12:24:58,593 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,597 INFO Service ticket created for service https://www.example.com by user test. 45s 2025-10-23 12:24:58,599 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 45s PASSED 45s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2025-10-23 12:24:58,604 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,608 INFO Service ticket created for service https://www.example.com by user test. 45s 2025-10-23 12:24:58,610 INFO Validate: Service ticket ST-W7JapStJgqAwJSaEnKewgmxqdKNNI3ZKot3luZSbsYAbjdQs8reDVDB7mAwC8 validated, user test authenticated on service https://www.example.com 45s PASSED 45s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2025-10-23 12:24:58,616 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,620 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 45s 2025-10-23 12:24:58,622 WARNING ValidateService: missing ticket or service 45s 2025-10-23 12:24:58,622 WARNING ValidateService: missing ticket or service 45s PASSED 45s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2025-10-23 12:24:58,629 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,633 INFO Service ticket created for service http://127.0.0.1:44961 by user test. 45s 2025-10-23 12:24:58,637 INFO ValidateService: ticket ST-ETTC7Bbw4XEpgCzlTDjbuMfK8acf7OCpkf8cAL0BMjS2Fi7PpyCuQzFhvqZus validated for user test on service http://127.0.0.1:44961. Proxy Granting Ticket transmited to http://127.0.0.1:44961. 45s 2025-10-23 12:24:58,640 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 45s 2025-10-23 12:24:58,642 INFO ValidateService: ticket PT-ZSovaRP0XRTlmmj0VMFfYPtG0emGDm1Z6XmKaCgV2CFkuLUF56dmarNc2yXof validated for user test on service http://127.0.0.1:45678. 45s 2025-10-23 12:24:58,647 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,651 INFO Service ticket created for service http://127.0.0.1:43747 by user test. 45s 2025-10-23 12:24:58,654 INFO ValidateService: ticket ST-eCYBDbT7khh4vdvoFzt0b9Vi5Qub03mcYbKbGk322sMgvTrgG268kekwT79oh validated for user test on service http://127.0.0.1:43747. Proxy Granting Ticket transmited to http://127.0.0.1:43747. 45s 2025-10-23 12:24:58,657 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 45s 2025-10-23 12:24:58,658 WARNING ValidateService: validation error: INVALID_TICKET PT-9W1U6OreUafg4tUF99MQmM9sBPpFUTRXqq2xM4r3ieFWVm02JQCneCkKI3BW1 45s PASSED 45s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2025-10-23 12:24:58,664 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,668 INFO Service ticket created for service https://replace_attribute.example.com by user test. 45s 2025-10-23 12:24:58,670 INFO ValidateService: ticket ST-ypJ8C95Rf2ifipXkqldBBPPQdRavmFJpJWDmmRJ2QVvVs5xfHhrUF6Cu5jhJy validated for user test on service https://replace_attribute.example.com. 45s 2025-10-23 12:24:58,674 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,678 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 45s 2025-10-23 12:24:58,680 INFO ValidateService: ticket ST-eBvFE3CkS7dpgLcq6PIMofFDzFTEw83jmVvKJpBkH9QGOo8fOorzAL5oVS1o7 validated for user test on service https://replace_attribute_list.example.com. 45s PASSED 45s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2025-10-23 12:24:58,687 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,691 INFO Service ticket created for service http://127.0.0.1:37207 by user test. 45s 2025-10-23 12:24:58,695 INFO ValidateService: ticket ST-0427hAZ77thOmlGrScTRA4hJBjn6fwJaTtz18cp09qs4AEY4e8rt9FnafENc2 validated for user test on service http://127.0.0.1:37207. Proxy Granting Ticket transmited to http://127.0.0.1:37207. 45s PASSED 45s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2025-10-23 12:24:58,701 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,705 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 45s 2025-10-23 12:24:58,707 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 45s 2025-10-23 12:24:58,711 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,715 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 45s PASSED 45s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2025-10-23 12:24:58,718 WARNING Service https://www.example.org not allowed. 45s 2025-10-23 12:24:58,718 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 45s 2025-10-23 12:24:58,724 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,728 INFO Service ticket created for service https://127.0.0.1:36665 by user test. 45s 2025-10-23 12:24:58,732 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=36665): Max retries exceeded with url: /?pgtId=PGT-1VMu6Qky4XLkiWvpFqAWyvF5waT512PFTekFWGGbTDvHoeIu8rcIhZ8JVIyA&pgtIou=PGTIOU-G1XFnVLpWIrwwbd1Ix6DroyrFm9RU0Nrhc2cAwEnwsyBZP2cLonbXFAVh (Caused by SSLError(SSLError(1, '[SSL: RECORD_LAYER_FAILURE] record layer failure (_ssl.c:1033)'))) 45s PASSED 45s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2025-10-23 12:24:58,738 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,740 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 45s 2025-10-23 12:24:58,742 INFO ValidateService: ticket ST-Q0r4Jiy4q3CxQ6L1wMKhINoKHMxD3M6raCg5SWGEsLW00xA5iNSu0ZmQPiDby validated for user test on service http://127.0.0.1:45678. 45s 2025-10-23 12:24:58,746 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,748 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 45s 2025-10-23 12:24:58,750 INFO ValidateService: ticket ST-ZBqmxABmP4G9q0nFfvDJrp91xAYQylNz92ui79gT32jUjPHfUiPkhZmqAOxRD validated for user test on service http://127.0.0.1:45678. 45s 2025-10-23 12:24:58,753 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 45s PASSED 45s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2025-10-23 12:24:58,754 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 45s 2025-10-23 12:24:58,760 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,764 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 45s 2025-10-23 12:24:58,766 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 45s PASSED 45s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2025-10-23 12:24:58,772 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,775 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 45s 2025-10-23 12:24:58,777 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 45s PASSED 45s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2025-10-23 12:24:58,783 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,787 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 45s 2025-10-23 12:24:58,788 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 45s PASSED 45s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2025-10-23 12:24:58,794 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,798 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 45s 2025-10-23 12:24:58,800 INFO ValidateService: ticket ST-rr1U79WI3R2wkevoZk2kDkuXJLFFgaOM8WXoXtYf7fRpr68rN1usuSL3ri3Ec validated for user test on service http://127.0.0.1:45678. 45s PASSED 45s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2025-10-23 12:24:58,806 INFO User test successfully authenticated 45s 2025-10-23 12:24:58,810 INFO Service ticket created for service https://one_attribute.example.com by user test. 45s 2025-10-23 12:24:58,812 INFO ValidateService: ticket ST-oKTgK4WqgmcXgSBM1lH4ECvHfSzopb5mq08KaTLFHn8uzV4P6qc8rG6cPztyT validated for user test on service https://one_attribute.example.com. 45s PASSED 46s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2025-10-23 12:24:58,818 INFO User test successfully authenticated 46s 2025-10-23 12:24:58,822 INFO Service ticket created for service http://127.0.0.1:39145 by user test. 46s 2025-10-23 12:24:58,826 INFO ValidateService: ticket ST-LuKO1A53A3b82xGj9qRWcLytIs5xaRNTQ0kW4feKYtRH0GhVITbN0LdwgS6nt validated for user test on service http://127.0.0.1:39145. Proxy Granting Ticket transmited to http://127.0.0.1:39145. 46s PASSED 46s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2025-10-23 12:24:58,832 INFO User test successfully authenticated 46s 2025-10-23 12:24:58,836 INFO Service ticket created for service https://user_field.example.com by user test. 46s 2025-10-23 12:24:58,838 INFO ValidateService: ticket ST-5oxgnyzg3DowwtgtaNdelBuSZXxmPzvPNaSDa2VlJDUTINh4LFz9NtoNh89uI validated for user test on service https://user_field.example.com. 46s 2025-10-23 12:24:58,842 INFO User test successfully authenticated 46s 2025-10-23 12:24:58,847 INFO Service ticket created for service https://user_field_alt.example.com by user test. 46s 2025-10-23 12:24:58,849 INFO ValidateService: ticket ST-IbeAsospXCsbNRAdAwV7tNv1sOoEXWi1BZ8HuSTs11GfxdJYGgOkFHGoeZWI7 validated for user test on service https://user_field_alt.example.com. 46s PASSED 46s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2025-10-23 12:24:58,856 INFO User test successfully authenticated 46s 2025-10-23 12:24:58,860 INFO Service ticket created for service http://127.0.0.1:41705 by user test. 46s 2025-10-23 12:24:58,864 INFO ValidateService: ticket ST-jLEsAtq0FiZIifecQH4LIsYQt4IoWJwJsnNDUMzy8oHaXqgIPEIwE7e45UjJW validated for user test on service http://127.0.0.1:41705. Proxy Granting Ticket transmited to http://127.0.0.1:41705. 46s 2025-10-23 12:24:58,864 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 46s 2025-10-23 12:24:58,865 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 46s PASSED 46s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2025-10-23 12:24:58,872 INFO User test successfully authenticated 46s 2025-10-23 12:24:58,876 INFO Service ticket created for service http://127.0.0.1:45717 by user test. 46s 2025-10-23 12:24:58,879 INFO ValidateService: ticket ST-jQBlMjrgDlGQj8xYhyYLM7QcCOOOixajaAtj2ZbYHpeeGSFJYycHfcTRx9JWj validated for user test on service http://127.0.0.1:45717. Proxy Granting Ticket transmited to http://127.0.0.1:45717. 46s 2025-10-23 12:24:58,881 WARNING Username test not allowed on service restrict_user_fail 46s 2025-10-23 12:24:58,881 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 46s 2025-10-23 12:24:58,882 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 46s 2025-10-23 12:24:58,883 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 46s 2025-10-23 12:24:58,884 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 46s 2025-10-23 12:24:58,884 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 46s PASSED 46s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2025-10-23 12:24:58,891 INFO User test successfully authenticated 46s 2025-10-23 12:24:58,895 INFO Service ticket created for service http://127.0.0.1:37549 by user test. 46s 2025-10-23 12:24:58,898 INFO ValidateService: ticket ST-N9rj3LlKdRwWYr6bE1qELge3aZzTeuBju1K59KD45Uk6PCy6lCFrzTqyfcmj9 validated for user test on service http://127.0.0.1:37549. Proxy Granting Ticket transmited to http://127.0.0.1:37549. 46s 2025-10-23 12:24:58,900 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 46s PASSED 46s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2025-10-23 12:24:58,906 INFO User test successfully authenticated 46s 2025-10-23 12:24:58,910 INFO Service ticket created for service http://127.0.0.1:36243 by user test. 46s 2025-10-23 12:24:58,913 INFO ValidateService: ticket ST-hujQeR0j7pLvQlK1BgqKBiUAwQgvQM7OtnmvhxtgZZAtvIpROCrLIccW7A1ml validated for user test on service http://127.0.0.1:36243. Proxy Granting Ticket transmited to http://127.0.0.1:36243. 46s 2025-10-23 12:24:58,914 WARNING Service https://www.example.org not allowed. 46s 2025-10-23 12:24:58,914 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 46s 2025-10-23 12:24:58,915 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:36243 does not allow proxy tickets 46s PASSED 46s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2025-10-23 12:24:58,922 INFO User test successfully authenticated 46s 2025-10-23 12:24:58,926 INFO Service ticket created for service http://127.0.0.1:35683 by user test. 46s 2025-10-23 12:24:58,930 INFO ValidateService: ticket ST-CU14XqRjgSadsOoCe6PCt9yAzx0i98F9a1wBT8xtNrHPf6D6CEh330ntk9aot validated for user test on service http://127.0.0.1:35683. Proxy Granting Ticket transmited to http://127.0.0.1:35683. 46s 2025-10-23 12:24:58,933 INFO Proxy ticket created for user test on service https://www.example.com. 46s 2025-10-23 12:24:58,934 INFO ValidateService: ticket PT-cEY3aoGMFx2K1rfUxdzB680d0FfSPLVEddTXclMElNjKFOpjJ4eSKoT21CybD validated for user test on service https://www.example.com. 46s PASSED 46s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2025-10-23 12:24:58,941 INFO User test successfully authenticated 46s 2025-10-23 12:24:58,946 INFO Service ticket created for service https://www.example.com by user test. 46s 2025-10-23 12:24:58,947 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 46s PASSED 46s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2025-10-23 12:24:58,951 WARNING SamlValidate: validation error: AuthnFailed ticket ST-I0EfHFA0hMzLeCQC7MSOFLDoPNJ6eNlwz0x8b1m6pSHydnRC09C6ISTo9aKqM not found 46s 2025-10-23 12:24:58,952 WARNING SamlValidate: validation error: AuthnFailed ticket PT-LDhsPhVUmZwWZRDn8BPJ4J8yEtmTFYQTXv3aZ67eJF7X3DDvXf4HuPAmLgBH9 not found 46s PASSED 46s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix 2025-10-23 12:24:58,955 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 46s PASSED 46s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml 2025-10-23 12:24:58,957 WARNING SamlValidate: validation error: VersionMismatch 46s PASSED 46s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2025-10-23 12:24:58,963 INFO User test successfully authenticated 46s 2025-10-23 12:24:58,968 INFO Service ticket created for service https://www.example.com by user test. 46s 2025-10-23 12:24:58,972 INFO User test successfully authenticated 46s 2025-10-23 12:24:58,976 INFO Service ticket created for service http://127.0.0.1:38873 by user test. 46s 2025-10-23 12:24:58,980 INFO ValidateService: ticket ST-YmACT163qq5JLqBqeT7fEZDCXC4Ga7YQmvdsVxnXzoVNspeZ3pcznSBh8WLgK validated for user test on service http://127.0.0.1:38873. Proxy Granting Ticket transmited to http://127.0.0.1:38873. 46s 2025-10-23 12:24:58,983 INFO Proxy ticket created for user test on service https://www.example.com. 46s 2025-10-23 12:24:58,985 INFO SamlValidate: ticket ST-MlV5CmyQCaayjZOTd2jxXQv8I5HXtsQPkSZ7c5WIa5Mu9njAG28LLMcxawY3Y validated for user test on service https://www.example.com. 46s 2025-10-23 12:24:58,987 INFO SamlValidate: ticket PT-PqtPE85VctFkv1T2uyCAOd1tlhN082rwiI5jod1efYqJuoudOk598SpUzL7yk validated for user test on service https://www.example.com. 46s PASSED 46s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2025-10-23 12:24:58,993 INFO User test successfully authenticated 46s 2025-10-23 12:24:58,997 INFO Service ticket created for service https://field_needed_success.example.com by user test. 46s 2025-10-23 12:24:58,999 INFO SamlValidate: ticket ST-XperyOpJnEjgBxz1fDLY6Wq5ligrcgnvHuEy2Aikq03SLvSuRKEmlwSXUdzwV validated for user test on service https://field_needed_success.example.com. 46s 2025-10-23 12:24:59,003 INFO User test successfully authenticated 46s 2025-10-23 12:24:59,007 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 46s 2025-10-23 12:24:59,009 INFO SamlValidate: ticket ST-0xNX917kKHYW2fuilxMun2EK1pnHyUrmVNlxnU3AvUjPzNb6yAK29IxjdWMP3 validated for user test on service https://field_needed_success_alt.example.com. 46s PASSEDDestroying test database for alias 'default'... 46s 46s 46s =============================== warnings summary =============================== 46s cas_server/tests/test_federate.py: 8 warnings 46s cas_server/tests/test_view.py: 3 warnings 46s /tmp/autopkgtest.TKigrW/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). 46s datetime.utcnow() + timedelta(seconds=max_age), 46s 46s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 46s /usr/lib/python3.13/unittest/case.py:707: DeprecationWarning: It is deprecated to return a value that is not None from a test case (>) 46s return self.run(*args, **kwds) 46s 46s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 46s ======================= 128 passed, 12 warnings in 3.28s ======================= 46s 46s autopkgtest [12:25:00]: test run-test-suite.sh: -----------------------] 47s run-test-suite.sh PASS 47s autopkgtest [12:25:01]: test run-test-suite.sh: - - - - - - - - - - results - - - - - - - - - - 47s autopkgtest [12:25:01]: @@@@@@@@@@@@@@@@@@@@ summary 47s run-test-suite.sh PASS