0s autopkgtest [19:55:04]: starting date and time: 2025-10-20 19:55:04+0000 0s autopkgtest [19:55:04]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [19:55:04]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.ezkqaznp/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 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-arm64-15.secgroup --name adt-resolute-arm64-django-cas-server-20251020-195504-juju-7f2275-prod-proposed-migration-environment-20-2eeecd2d-156c-43e3-8a1f-7a6652bec639 --image adt/ubuntu-resolute-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-arm64-django-cas-server-20251020-195504-juju-7f2275-prod-proposed-migration-environment-20-2eeecd2d-156c-43e3-8a1f-7a6652bec639 from image adt/ubuntu-resolute-arm64-server-20251020.img (UUID 53f7e888-0b33-40b7-be7f-09b2db152d64)... 58s autopkgtest [19:56:02]: testbed dpkg architecture: arm64 58s autopkgtest [19:56:02]: testbed apt version: 3.1.8ubuntu1 59s autopkgtest [19:56:03]: @@@@@@@@@@@@@@@@@@@@ test bed setup 59s autopkgtest [19:56:03]: testbed release detected to be: None 60s autopkgtest [19:56:04]: updating testbed package index (apt update) 60s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [83.3 kB] 60s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 61s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 61s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 61s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [14.3 kB] 61s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [59.3 kB] 61s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5028 B] 61s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [646 kB] 61s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 Packages [115 kB] 61s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 Packages [43.8 kB] 62s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 Packages [409 kB] 62s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 Packages [5672 B] 62s Fetched 1382 kB in 2s (728 kB/s) 63s Reading package lists... 64s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 64s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 64s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 64s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 66s Reading package lists... 66s Reading package lists... 66s Building dependency tree... 66s Reading state information... 67s Calculating upgrade... 68s The following packages will be upgraded: 68s libbrotli1 libjson-c5 python3-inflect python3-oauthlib python3-zipp 68s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 68s Need to get 512 kB of archives. 68s After this operation, 31.7 kB of additional disk space will be used. 68s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 libjson-c5 arm64 0.18+ds-1.1 [37.5 kB] 68s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 libbrotli1 arm64 1.1.0-2build6 [336 kB] 69s Get:3 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-inflect all 7.5.0-1 [33.9 kB] 69s Get:4 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-oauthlib all 3.3.1-1 [93.5 kB] 69s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-zipp all 3.23.0-1 [10.4 kB] 69s dpkg-preconfigure: unable to re-open stdin: No such file or directory 70s Fetched 512 kB in 1s (846 kB/s) 70s (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 ... 83364 files and directories currently installed.) 70s Preparing to unpack .../libjson-c5_0.18+ds-1.1_arm64.deb ... 70s Unpacking libjson-c5:arm64 (0.18+ds-1.1) over (0.18+ds-1) ... 70s Preparing to unpack .../libbrotli1_1.1.0-2build6_arm64.deb ... 70s Unpacking libbrotli1:arm64 (1.1.0-2build6) over (1.1.0-2build5) ... 70s Preparing to unpack .../python3-inflect_7.5.0-1_all.deb ... 71s Unpacking python3-inflect (7.5.0-1) over (7.3.1-2) ... 71s Preparing to unpack .../python3-oauthlib_3.3.1-1_all.deb ... 71s Unpacking python3-oauthlib (3.3.1-1) over (3.2.2-3) ... 71s Preparing to unpack .../python3-zipp_3.23.0-1_all.deb ... 71s Unpacking python3-zipp (3.23.0-1) over (3.21.0-1) ... 71s Setting up libbrotli1:arm64 (1.1.0-2build6) ... 71s Setting up python3-zipp (3.23.0-1) ... 72s Setting up python3-inflect (7.5.0-1) ... 72s Setting up python3-oauthlib (3.3.1-1) ... 72s Setting up libjson-c5:arm64 (0.18+ds-1.1) ... 72s Processing triggers for libc-bin (2.42-0ubuntu3) ... 73s autopkgtest [19:56:17]: upgrading testbed (apt dist-upgrade and autopurge) 73s Reading package lists... 74s Building dependency tree... 74s Reading state information... 74s Calculating upgrade... 75s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 76s Reading package lists... 76s Building dependency tree... 76s Reading state information... 76s Solving dependencies... 77s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 80s autopkgtest [19:56:24]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 09:50:31 UTC 2025 80s autopkgtest [19:56:24]: @@@@@@@@@@@@@@@@@@@@ apt-source django-cas-server 82s Get:1 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (dsc) [2597 B] 82s Get:2 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (tar) [138 kB] 82s Get:3 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (diff) [10.2 kB] 83s gpgv: Signature made Sat Dec 14 20:43:18 2024 UTC 83s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 83s gpgv: issuer "tchet@debian.org" 83s gpgv: Can't check signature: No public key 83s dpkg-source: warning: cannot verify inline signature for ./django-cas-server_2.1.0-3.dsc: no acceptable signature found 83s autopkgtest [19:56:27]: testing package django-cas-server version 2.1.0-3 83s autopkgtest [19:56:27]: build not needed 84s autopkgtest [19:56:28]: test run-test-suite.sh: preparing testbed 84s Reading package lists... 85s Building dependency tree... 85s Reading state information... 85s Solving dependencies... 86s The following NEW packages will be installed: 86s fonts-font-awesome fonts-lato libjs-jquery libjs-sphinxdoc libjs-underscore 86s libxslt1.1 python3-all python3-asgiref python3-django 86s python3-django-cas-server python3-django-cas-server-doc python3-iniconfig 86s python3-legacy-cgi python3-lxml python3-packaging python3-pluggy 86s python3-pytest python3-pytest-django python3-requests-futures 86s python3-sqlparse sphinx-rtd-theme-common 86s 0 upgraded, 21 newly installed, 0 to remove and 0 not upgraded. 86s Need to get 10.2 MB of archives. 86s After this operation, 54.0 MB of additional disk space will be used. 86s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 fonts-lato all 2.015-1 [2781 kB] 87s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 88s Get:3 http://ftpmaster.internal/ubuntu resolute/main arm64 libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 88s Get:4 http://ftpmaster.internal/ubuntu resolute/main arm64 libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 88s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 libjs-sphinxdoc all 8.2.3-1ubuntu2 [28.0 kB] 88s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 libxslt1.1 arm64 1.1.43-0.1 [171 kB] 88s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-all arm64 3.13.7-1 [884 B] 88s Get:8 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-asgiref all 3.8.1-1 [24.8 kB] 88s Get:9 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-sqlparse all 0.5.3-1 [32.9 kB] 88s Get:10 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-django all 3:5.2.4-1ubuntu2 [3036 kB] 89s Get:11 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-legacy-cgi all 2.6.3-1 [16.8 kB] 89s Get:12 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-lxml arm64 6.0.1-1 [1428 kB] 89s Get:13 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-requests-futures all 1.0.2-1 [12.0 kB] 89s Get:14 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-django-cas-server all 2.1.0-3 [111 kB] 89s Get:15 http://ftpmaster.internal/ubuntu resolute/main arm64 sphinx-rtd-theme-common all 3.0.2+dfsg-3 [1013 kB] 89s Get:16 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-django-cas-server-doc all 2.1.0-3 [178 kB] 89s Get:17 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-iniconfig all 1.1.1-2 [6024 B] 89s Get:18 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-packaging all 25.0-1 [52.8 kB] 89s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 python3-pluggy all 1.6.0-1 [21.0 kB] 89s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 python3-pytest all 8.4.2-1 [268 kB] 89s Get:21 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-pytest-django all 4.5.2-3 [18.5 kB] 90s Fetched 10.2 MB in 4s (2631 kB/s) 90s Selecting previously unselected package fonts-lato. 90s (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 ... 83374 files and directories currently installed.) 90s Preparing to unpack .../00-fonts-lato_2.015-1_all.deb ... 90s Unpacking fonts-lato (2.015-1) ... 90s Selecting previously unselected package fonts-font-awesome. 90s Preparing to unpack .../01-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 90s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 90s Selecting previously unselected package libjs-jquery. 90s Preparing to unpack .../02-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 90s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 91s Selecting previously unselected package libjs-underscore. 91s Preparing to unpack .../03-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 91s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 91s Selecting previously unselected package libjs-sphinxdoc. 91s Preparing to unpack .../04-libjs-sphinxdoc_8.2.3-1ubuntu2_all.deb ... 91s Unpacking libjs-sphinxdoc (8.2.3-1ubuntu2) ... 91s Selecting previously unselected package libxslt1.1:arm64. 91s Preparing to unpack .../05-libxslt1.1_1.1.43-0.1_arm64.deb ... 91s Unpacking libxslt1.1:arm64 (1.1.43-0.1) ... 91s Selecting previously unselected package python3-all. 91s Preparing to unpack .../06-python3-all_3.13.7-1_arm64.deb ... 91s Unpacking python3-all (3.13.7-1) ... 91s Selecting previously unselected package python3-asgiref. 91s Preparing to unpack .../07-python3-asgiref_3.8.1-1_all.deb ... 91s Unpacking python3-asgiref (3.8.1-1) ... 91s Selecting previously unselected package python3-sqlparse. 91s Preparing to unpack .../08-python3-sqlparse_0.5.3-1_all.deb ... 91s Unpacking python3-sqlparse (0.5.3-1) ... 91s Selecting previously unselected package python3-django. 91s Preparing to unpack .../09-python3-django_3%3a5.2.4-1ubuntu2_all.deb ... 91s Unpacking python3-django (3:5.2.4-1ubuntu2) ... 92s Selecting previously unselected package python3-legacy-cgi. 92s Preparing to unpack .../10-python3-legacy-cgi_2.6.3-1_all.deb ... 92s Unpacking python3-legacy-cgi (2.6.3-1) ... 92s Selecting previously unselected package python3-lxml:arm64. 92s Preparing to unpack .../11-python3-lxml_6.0.1-1_arm64.deb ... 92s Unpacking python3-lxml:arm64 (6.0.1-1) ... 92s Selecting previously unselected package python3-requests-futures. 92s Preparing to unpack .../12-python3-requests-futures_1.0.2-1_all.deb ... 92s Unpacking python3-requests-futures (1.0.2-1) ... 92s Selecting previously unselected package python3-django-cas-server. 92s Preparing to unpack .../13-python3-django-cas-server_2.1.0-3_all.deb ... 92s Unpacking python3-django-cas-server (2.1.0-3) ... 92s Selecting previously unselected package sphinx-rtd-theme-common. 92s Preparing to unpack .../14-sphinx-rtd-theme-common_3.0.2+dfsg-3_all.deb ... 92s Unpacking sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 92s Selecting previously unselected package python3-django-cas-server-doc. 92s Preparing to unpack .../15-python3-django-cas-server-doc_2.1.0-3_all.deb ... 92s Unpacking python3-django-cas-server-doc (2.1.0-3) ... 92s Selecting previously unselected package python3-iniconfig. 92s Preparing to unpack .../16-python3-iniconfig_1.1.1-2_all.deb ... 92s Unpacking python3-iniconfig (1.1.1-2) ... 92s Selecting previously unselected package python3-packaging. 92s Preparing to unpack .../17-python3-packaging_25.0-1_all.deb ... 92s Unpacking python3-packaging (25.0-1) ... 92s Selecting previously unselected package python3-pluggy. 92s Preparing to unpack .../18-python3-pluggy_1.6.0-1_all.deb ... 92s Unpacking python3-pluggy (1.6.0-1) ... 92s Selecting previously unselected package python3-pytest. 92s Preparing to unpack .../19-python3-pytest_8.4.2-1_all.deb ... 92s Unpacking python3-pytest (8.4.2-1) ... 92s Selecting previously unselected package python3-pytest-django. 92s Preparing to unpack .../20-python3-pytest-django_4.5.2-3_all.deb ... 92s Unpacking python3-pytest-django (4.5.2-3) ... 93s Setting up python3-iniconfig (1.1.1-2) ... 93s Setting up fonts-lato (2.015-1) ... 93s Setting up python3-all (3.13.7-1) ... 93s Setting up python3-packaging (25.0-1) ... 93s Setting up python3-sqlparse (0.5.3-1) ... 93s Setting up python3-pluggy (1.6.0-1) ... 94s Setting up python3-legacy-cgi (2.6.3-1) ... 94s Setting up libxslt1.1:arm64 (1.1.43-0.1) ... 94s Setting up python3-requests-futures (1.0.2-1) ... 94s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 94s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 94s Setting up sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 94s Setting up python3-asgiref (3.8.1-1) ... 95s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 95s Setting up python3-django (3:5.2.4-1ubuntu2) ... 97s Setting up python3-pytest (8.4.2-1) ... 97s Setting up python3-pytest-django (4.5.2-3) ... 98s Setting up libjs-sphinxdoc (8.2.3-1ubuntu2) ... 98s Setting up python3-lxml:arm64 (6.0.1-1) ... 98s Setting up python3-django-cas-server (2.1.0-3) ... 98s Setting up python3-django-cas-server-doc (2.1.0-3) ... 98s Processing triggers for man-db (2.13.1-1) ... 99s Processing triggers for libc-bin (2.42-0ubuntu3) ... 101s autopkgtest [19:56:45]: test run-test-suite.sh: [----------------------- 101s 101s *************************** 101s *** Testing with python3.13 101s *************************** 101s 101s Content of current working folder: 101s 101s total 20 101s drwxr-xr-x 3 ubuntu ubuntu 4096 Oct 20 19:56 . 101s drwxrwxrwx 5 root root 4096 Oct 20 19:56 .. 101s drwxrwxr-x 9 ubuntu ubuntu 4096 Oct 20 19:56 cas_server 101s -rw-rw-r-- 1 ubuntu ubuntu 140 Aug 18 2024 pytest.ini 101s -rw-rw-r-- 1 ubuntu ubuntu 152 Aug 18 2024 setup.cfg 101s Running tests... 101s 102s ============================= test session starts ============================== 102s platform linux -- Python 3.13.7, pytest-8.4.2, pluggy-1.6.0 -- /usr/bin/python3.13 102s cachedir: .pytest_cache 102s django: settings: cas_server.tests.settings (from ini) 102s rootdir: /tmp/autopkgtest.Bu4hIP/autopkgtest_tmp 102s configfile: pytest.ini 102s testpaths: cas_server/tests/ 102s plugins: typeguard-4.4.2, django-4.5.2 103s collecting ... collected 128 items 103s 103s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 104s 2025-10-20 19:56:48,444 WARNING Identity provider suffix exemple.fr not found 104s 2025-10-20 19:56:48,466 INFO Got an invalid ticket ST-A3koxFmdACBuKfM5hzojEvZXpoeUzg2GKhLVDKjWnS6YPsFrPdMNumcd0SldT from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 104s 2025-10-20 19:56:48,476 INFO Got an invalid ticket ST-MevB8LGWGdeibUV5JY3cF15Vc8kPEBqQZADKp3c0zBXShcX1zLrpiDoETKRiC from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 104s PASSED 104s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2025-10-20 19:56:48,578 WARNING CAS_FEDERATE is False, set it to True to use federation 104s 2025-10-20 19:56:48,580 WARNING CAS_FEDERATE is False, set it to True to use federation 104s PASSED 104s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2025-10-20 19:56:48,620 INFO Trying to authenticate http://127.0.0.1:8080 again 104s 2025-10-20 19:56:48,634 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 104s 2025-10-20 19:56:48,670 INFO User dédé@example.com successfully authenticated 104s 2025-10-20 19:56:48,696 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 104s 2025-10-20 19:56:48,719 INFO Trying to authenticate http://127.0.0.1:8081 again 104s 2025-10-20 19:56:48,735 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 104s 2025-10-20 19:56:48,758 INFO User dédé@example.org successfully authenticated 104s 2025-10-20 19:56:48,779 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 104s 2025-10-20 19:56:48,802 INFO Trying to authenticate http://127.0.0.1:8082 again 104s 2025-10-20 19:56:48,816 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 104s 2025-10-20 19:56:48,839 INFO User dédé@example.net successfully authenticated 104s 2025-10-20 19:56:48,861 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 104s 2025-10-20 19:56:48,886 INFO Trying to authenticate http://127.0.0.1:8083 again 104s 2025-10-20 19:56:48,903 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 104s 2025-10-20 19:56:48,925 INFO User dédé@example.test successfully authenticated 104s 2025-10-20 19:56:48,946 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 105s 2025-10-20 19:56:49,034 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-9NAhsNJz94PvNQhGTBjYMIntM0paX2Oy7ikiMwHTMKTT1uhcTzIzWOazXyA8s 105s 2025-10-20 19:56:49,090 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-RwqJLGgH3Ewc4JHxGASJfCeTSRWZK1xJjT72sNNJ0qVQJeq1M6GX7r4ikJi3w 105s PASSED 105s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 105s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2025-10-20 19:56:49,159 INFO Trying to authenticate http://127.0.0.1:8080 again 105s 2025-10-20 19:56:49,172 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 105s 2025-10-20 19:56:49,193 INFO User dédé@example.com successfully authenticated 105s 2025-10-20 19:56:49,215 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 105s 2025-10-20 19:56:49,237 INFO Trying to authenticate http://127.0.0.1:8081 again 105s 2025-10-20 19:56:49,251 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 105s 2025-10-20 19:56:49,272 INFO User dédé@example.org successfully authenticated 105s 2025-10-20 19:56:49,293 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 105s 2025-10-20 19:56:49,316 INFO Trying to authenticate http://127.0.0.1:8082 again 105s 2025-10-20 19:56:49,328 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 105s 2025-10-20 19:56:49,351 INFO User dédé@example.net successfully authenticated 105s 2025-10-20 19:56:49,372 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 105s 2025-10-20 19:56:49,394 INFO Trying to authenticate http://127.0.0.1:8083 again 105s 2025-10-20 19:56:49,407 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 105s 2025-10-20 19:56:49,431 INFO User dédé@example.test successfully authenticated 105s 2025-10-20 19:56:49,453 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 105s 2025-10-20 19:56:49,455 INFO logout requested 105s 2025-10-20 19:56:49,460 INFO Logging out user dédé@example.com. 105s 2025-10-20 19:56:49,470 INFO User dédé@example.com logged out 105s 2025-10-20 19:56:49,488 INFO logout requested 105s 2025-10-20 19:56:49,494 INFO logout requested 105s 2025-10-20 19:56:49,499 INFO Logging out user dédé@example.org. 105s 2025-10-20 19:56:49,508 INFO User dédé@example.org logged out 105s 2025-10-20 19:56:49,528 INFO logout requested 105s 2025-10-20 19:56:49,534 INFO logout requested 105s 2025-10-20 19:56:49,539 INFO Logging out user dédé@example.net. 105s 2025-10-20 19:56:49,548 INFO User dédé@example.net logged out 105s 2025-10-20 19:56:49,567 INFO logout requested 105s 2025-10-20 19:56:49,573 INFO logout requested 105s 2025-10-20 19:56:49,578 INFO Logging out user dédé@example.test. 105s 2025-10-20 19:56:49,587 INFO User dédé@example.test logged out 105s 2025-10-20 19:56:49,611 INFO logout requested 105s 2025-10-20 19:56:49,636 INFO Trying to authenticate http://127.0.0.1:8080 again 105s 2025-10-20 19:56:49,648 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 105s 2025-10-20 19:56:49,788 INFO User dédé@example.com successfully authenticated 105s 2025-10-20 19:56:49,810 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 105s 2025-10-20 19:56:49,833 INFO Trying to authenticate http://127.0.0.1:8081 again 105s 2025-10-20 19:56:49,846 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 105s 2025-10-20 19:56:49,869 INFO User dédé@example.org successfully authenticated 105s 2025-10-20 19:56:49,891 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 105s 2025-10-20 19:56:49,913 INFO Trying to authenticate http://127.0.0.1:8082 again 105s 2025-10-20 19:56:49,927 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 105s 2025-10-20 19:56:49,950 INFO User dédé@example.net successfully authenticated 106s 2025-10-20 19:56:49,972 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 106s 2025-10-20 19:56:49,996 INFO Trying to authenticate http://127.0.0.1:8083 again 106s 2025-10-20 19:56:50,010 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 106s 2025-10-20 19:56:50,033 INFO User dédé@example.test successfully authenticated 106s 2025-10-20 19:56:50,055 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 106s 2025-10-20 19:56:50,060 INFO logout requested 106s 2025-10-20 19:56:50,063 INFO Logging out user dédé. 106s 2025-10-20 19:56:50,065 INFO User dédé logged out 106s 2025-10-20 19:56:50,089 INFO logout requested 106s 2025-10-20 19:56:50,091 INFO Logging out user dédé. 106s 2025-10-20 19:56:50,094 INFO User dédé logged out 106s 2025-10-20 19:56:50,115 INFO logout requested 106s 2025-10-20 19:56:50,117 INFO Logging out user dédé. 106s 2025-10-20 19:56:50,120 INFO User dédé logged out 106s 2025-10-20 19:56:50,142 INFO logout requested 106s 2025-10-20 19:56:50,145 INFO Logging out user dédé. 106s 2025-10-20 19:56:50,147 INFO User dédé logged out 106s PASSED 106s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2025-10-20 19:56:50,199 INFO Trying to authenticate http://127.0.0.1:8080 again 106s 2025-10-20 19:56:50,212 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 106s 2025-10-20 19:56:50,234 INFO User dédé@example.com successfully authenticated 106s 2025-10-20 19:56:50,256 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 106s 2025-10-20 19:56:50,281 INFO Trying to authenticate http://127.0.0.1:8081 again 106s 2025-10-20 19:56:50,293 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 106s 2025-10-20 19:56:50,316 INFO User dédé@example.org successfully authenticated 106s 2025-10-20 19:56:50,338 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 106s 2025-10-20 19:56:50,370 INFO Trying to authenticate http://127.0.0.1:8082 again 106s 2025-10-20 19:56:50,384 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 106s 2025-10-20 19:56:50,406 INFO User dédé@example.net successfully authenticated 106s 2025-10-20 19:56:50,428 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 106s 2025-10-20 19:56:50,455 INFO Trying to authenticate http://127.0.0.1:8083 again 106s 2025-10-20 19:56:50,468 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 106s 2025-10-20 19:56:50,491 INFO User dédé@example.test successfully authenticated 106s 2025-10-20 19:56:50,514 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 106s 2025-10-20 19:56:50,517 INFO logout requested 106s 2025-10-20 19:56:50,521 INFO Logging out user dédé@example.com. 106s 2025-10-20 19:56:50,533 INFO User dédé@example.com logged out 106s 2025-10-20 19:56:50,534 INFO logout requested 106s 2025-10-20 19:56:50,539 INFO Logging out user dédé@example.org. 106s 2025-10-20 19:56:50,548 INFO User dédé@example.org logged out 106s 2025-10-20 19:56:50,549 INFO logout requested 106s 2025-10-20 19:56:50,554 INFO Logging out user dédé@example.net. 106s 2025-10-20 19:56:50,564 INFO User dédé@example.net logged out 106s 2025-10-20 19:56:50,565 INFO logout requested 106s 2025-10-20 19:56:50,570 INFO Logging out user dédé@example.test. 106s PASSED2025-10-20 19:56:50,579 INFO User dédé@example.test logged out 106s 106s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2025-10-20 19:56:50,611 WARNING A login attempt failed 106s PASSED 106s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 106s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2025-10-20 19:56:50,685 INFO Trying to authenticate http://127.0.0.1:8080 again 106s 2025-10-20 19:56:50,697 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 106s 2025-10-20 19:56:50,719 INFO User dédé@example.com successfully authenticated 106s 2025-10-20 19:56:50,740 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 106s 2025-10-20 19:56:50,768 INFO Trying to authenticate http://127.0.0.1:8081 again 106s 2025-10-20 19:56:50,780 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 106s 2025-10-20 19:56:50,801 INFO User dédé@example.org successfully authenticated 106s 2025-10-20 19:56:50,823 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 106s 2025-10-20 19:56:50,856 INFO Trying to authenticate http://127.0.0.1:8082 again 106s 2025-10-20 19:56:50,870 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 106s 2025-10-20 19:56:50,892 INFO User dédé@example.net successfully authenticated 106s 2025-10-20 19:56:50,917 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 106s 2025-10-20 19:56:50,945 INFO Trying to authenticate http://127.0.0.1:8083 again 106s 2025-10-20 19:56:50,960 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 107s 2025-10-20 19:56:50,981 INFO User dédé@example.test successfully authenticated 107s 2025-10-20 19:56:51,004 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 107s PASSED 107s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2025-10-20 19:56:51,047 INFO Trying to authenticate http://127.0.0.1:8080 again 107s 2025-10-20 19:56:51,060 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 107s 2025-10-20 19:56:51,081 INFO User dédé@example.com successfully authenticated 107s 2025-10-20 19:56:51,102 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 107s 2025-10-20 19:56:51,128 INFO Trying to authenticate http://127.0.0.1:8081 again 107s 2025-10-20 19:56:51,141 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 107s 2025-10-20 19:56:51,165 INFO User dédé@example.org successfully authenticated 107s 2025-10-20 19:56:51,187 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 107s 2025-10-20 19:56:51,214 INFO Trying to authenticate http://127.0.0.1:8082 again 107s 2025-10-20 19:56:51,229 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 107s 2025-10-20 19:56:51,250 INFO User dédé@example.net successfully authenticated 107s 2025-10-20 19:56:51,272 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 107s 2025-10-20 19:56:51,296 INFO Trying to authenticate http://127.0.0.1:8083 again 107s 2025-10-20 19:56:51,310 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 107s 2025-10-20 19:56:51,332 INFO User dédé@example.test successfully authenticated 107s 2025-10-20 19:56:51,355 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 107s 2025-10-20 19:56:51,379 INFO Trying to authenticate http://127.0.0.1:8080 again 107s 2025-10-20 19:56:51,393 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 108s 2025-10-20 19:56:51,416 INFO User dédé@example.com successfully authenticated 108s PASSED 108s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider PASSED 108s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2025-10-20 19:56:51,438 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 108s 2025-10-20 19:56:51,463 INFO Trying to authenticate http://127.0.0.1:8081 again 108s 2025-10-20 19:56:51,476 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 108s 2025-10-20 19:56:51,499 INFO User dédé@example.org successfully authenticated 108s 2025-10-20 19:56:51,520 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 108s 2025-10-20 19:56:51,543 INFO Trying to authenticate http://127.0.0.1:8082 again 108s 2025-10-20 19:56:51,560 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 108s 2025-10-20 19:56:51,582 INFO User dédé@example.net successfully authenticated 108s 2025-10-20 19:56:51,604 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 108s 2025-10-20 19:56:51,626 INFO Trying to authenticate http://127.0.0.1:8083 again 108s 2025-10-20 19:56:51,642 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 108s 2025-10-20 19:56:51,663 INFO User dédé@example.test successfully authenticated 108s 2025-10-20 19:56:51,684 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 108s 2025-10-20 19:56:51,688 WARNING User already authenticated, dropping federated authentication request 108s 2025-10-20 19:56:51,691 WARNING User already authenticated, dropping federated authentication request 108s 2025-10-20 19:56:51,694 WARNING User already authenticated, dropping federated authentication request 108s 2025-10-20 19:56:51,697 WARNING User already authenticated, dropping federated authentication request 108s 2025-10-20 19:56:51,731 INFO Trying to authenticate http://127.0.0.1:8080 again 108s 2025-10-20 19:56:51,744 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 108s 2025-10-20 19:56:51,766 INFO User dédé@example.com successfully authenticated 108s 2025-10-20 19:56:51,787 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 108s 2025-10-20 19:56:51,811 INFO Trying to authenticate http://127.0.0.1:8081 again 108s 2025-10-20 19:56:51,824 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 108s 2025-10-20 19:56:51,844 INFO User dédé@example.org successfully authenticated 108s 2025-10-20 19:56:51,865 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 108s 2025-10-20 19:56:51,898 INFO Trying to authenticate http://127.0.0.1:8082 again 108s 2025-10-20 19:56:51,909 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 108s 2025-10-20 19:56:51,929 INFO User dédé@example.net successfully authenticated 108s 2025-10-20 19:56:51,949 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 108s 2025-10-20 19:56:51,972 INFO Trying to authenticate http://127.0.0.1:8083 again 108s 2025-10-20 19:56:51,985 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 108s 2025-10-20 19:56:52,007 INFO User dédé@example.test successfully authenticated 108s 2025-10-20 19:56:52,029 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 108s 2025-10-20 19:56:52,032 INFO logout requested 108s 2025-10-20 19:56:52,036 INFO Logging out user dédé@example.com. 108s 2025-10-20 19:56:52,048 INFO User dédé@example.com logged out 108s 2025-10-20 19:56:52,055 INFO logout requested 108s 2025-10-20 19:56:52,060 INFO Logging out user dédé@example.org. 108s 2025-10-20 19:56:52,069 INFO User dédé@example.org logged out 108s 2025-10-20 19:56:52,075 INFO logout requested 108s 2025-10-20 19:56:52,080 INFO Logging out user dédé@example.net. 108s 2025-10-20 19:56:52,090 INFO User dédé@example.net logged out 108s 2025-10-20 19:56:52,097 INFO logout requested 108s 2025-10-20 19:56:52,101 INFO Logging out user dédé@example.test. 108s 2025-10-20 19:56:52,112 INFO User dédé@example.test logged out 108s 2025-10-20 19:56:52,148 INFO Trying to authenticate http://127.0.0.1:8080 again 108s 2025-10-20 19:56:52,160 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 108s 2025-10-20 19:56:52,184 INFO User dédé@example.com successfully authenticated 108s 2025-10-20 19:56:52,205 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 108s 2025-10-20 19:56:52,228 INFO Trying to authenticate http://127.0.0.1:8081 again 108s 2025-10-20 19:56:52,241 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 108s 2025-10-20 19:56:52,263 INFO User dédé@example.org successfully authenticated 108s 2025-10-20 19:56:52,286 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 108s 2025-10-20 19:56:52,310 INFO Trying to authenticate http://127.0.0.1:8082 again 108s 2025-10-20 19:56:52,323 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 108s 2025-10-20 19:56:52,347 INFO User dédé@example.net successfully authenticated 108s 2025-10-20 19:56:52,368 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 108s 2025-10-20 19:56:52,391 INFO Trying to authenticate http://127.0.0.1:8083 again 108s 2025-10-20 19:56:52,404 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 108s 2025-10-20 19:56:52,428 INFO User dédé@example.test successfully authenticated 108s 2025-10-20 19:56:52,451 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 108s 2025-10-20 19:56:52,463 INFO Trying to authenticate http://127.0.0.1:8080 again 108s 2025-10-20 19:56:52,476 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 108s 2025-10-20 19:56:52,498 INFO User dédé@example.com successfully authenticated 108s 2025-10-20 19:56:52,508 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 108s 2025-10-20 19:56:52,522 INFO Trying to authenticate http://127.0.0.1:8081 again 108s 2025-10-20 19:56:52,535 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 108s 2025-10-20 19:56:52,561 INFO User dédé@example.org successfully authenticated 108s 2025-10-20 19:56:52,572 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 108s 2025-10-20 19:56:52,586 INFO Trying to authenticate http://127.0.0.1:8082 again 108s 2025-10-20 19:56:52,600 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 108s 2025-10-20 19:56:52,626 INFO User dédé@example.net successfully authenticated 108s 2025-10-20 19:56:52,637 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 108s 2025-10-20 19:56:52,651 INFO Trying to authenticate http://127.0.0.1:8083 again 108s 2025-10-20 19:56:52,665 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 108s 2025-10-20 19:56:52,692 INFO User dédé@example.test successfully authenticated 108s 2025-10-20 19:56:52,703 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 108s PASSED 108s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 108s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 108s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 108s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2025-10-20 19:56:52,822 INFO User test successfully authenticated 108s PASSED 108s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2025-10-20 19:56:52,876 INFO User test successfully authenticated 108s PASSED 108s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2025-10-20 19:56:52,926 INFO User test successfully authenticated 108s 2025-10-20 19:56:52,952 INFO User test successfully authenticated 109s 2025-10-20 19:56:52,975 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 109s 2025-10-20 19:56:52,987 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')) 109s PASSED 109s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2025-10-20 19:56:53,020 INFO User test successfully authenticated 109s PASSED 109s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2025-10-20 19:56:53,059 INFO User test successfully authenticated 109s PASSED 109s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2025-10-20 19:56:53,154 INFO User test successfully authenticated 109s 2025-10-20 19:56:53,187 INFO Sending SLO requests to service http://127.0.0.1:46663 for user test 109s 2025-10-20 19:56:53,195 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 109s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 109s PASSED 109s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2025-10-20 19:56:53,228 INFO User test successfully authenticated 109s PASSED 109s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 109s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 109s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 109s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 109s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 109s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 109s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 109s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 109s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 109s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 109s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 109s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 109s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 109s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 109s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 109s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 109s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 109s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 109s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 110s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 110s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 110s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 110s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2025-10-20 19:56:54,354 INFO User test successfully authenticated 110s 2025-10-20 19:56:54,367 WARNING Service https://www.example.org not allowed. 110s PASSED 110s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success 2025-10-20 19:56:54,395 INFO User test successfully authenticated 110s 2025-10-20 19:56:54,412 INFO Service ticket created for service https://www.example.com by user test. 110s PASSED 110s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt 2025-10-20 19:56:54,438 INFO User test successfully authenticated 110s 2025-10-20 19:56:54,455 INFO Service ticket created for service https://www.example.com by user test. 110s PASSED 110s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn 2025-10-20 19:56:54,481 INFO User test successfully authenticated 110s PASSED 110s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged 2025-10-20 19:56:54,520 INFO User test successfully authenticated 110s PASSED 110s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2025-10-20 19:56:54,557 INFO User test successfully authenticated 110s 2025-10-20 19:56:54,572 WARNING User test seems authenticated but is not found in the database. 110s 2025-10-20 19:56:54,572 INFO Logging out user test. 110s 2025-10-20 19:56:54,574 INFO User test logged out 110s PASSED 110s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 110s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2025-10-20 19:56:54,610 INFO User test successfully authenticated 110s 2025-10-20 19:56:54,623 WARNING Username test not allowed on service restrict_user_fail 110s PASSED 110s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2025-10-20 19:56:54,653 WARNING A login attempt failed 110s 2025-10-20 19:56:54,684 WARNING A login attempt failed 110s 2025-10-20 19:56:54,715 WARNING A login attempt failed 110s PASSED 110s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2025-10-20 19:56:54,752 WARNING Received an invalid login ticket 110s PASSED 110s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2025-10-20 19:56:54,787 WARNING A login attempt failed 110s PASSED 110s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2025-10-20 19:56:54,826 INFO User test successfully authenticated 110s PASSED 110s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2025-10-20 19:56:54,859 INFO User test successfully authenticated 110s PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_renew 2025-10-20 19:56:56,838 INFO User test successfully authenticated 112s 2025-10-20 19:56:56,859 INFO User test successfully authenticated 112s 2025-10-20 19:56:56,867 INFO Service ticket created for service https://www.example.com by user test. 112s PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2025-10-20 19:56:56,889 INFO User test successfully authenticated 112s 2025-10-20 19:56:56,907 INFO User test successfully authenticated 112s 2025-10-20 19:56:56,916 INFO Service ticket created for service https://www.example.com by user test. 112s PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2025-10-20 19:56:56,940 INFO User test successfully authenticated 112s 2025-10-20 19:56:56,951 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 112s 2025-10-20 19:56:56,958 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 112s 2025-10-20 19:56:56,967 INFO Service ticket created for service https://filter_success.example.com by user test. 112s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2025-10-20 19:56:56,988 INFO User test successfully authenticated 113s 2025-10-20 19:56:56,998 WARNING Username test not allowed on service restrict_user_fail 113s 2025-10-20 19:56:57,008 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2025-10-20 19:56:57,030 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,040 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 113s 2025-10-20 19:56:57,050 INFO Service ticket created for service https://field_needed_success.example.com by user test. 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2025-10-20 19:56:57,073 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,082 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2025-10-20 19:56:57,103 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,114 WARNING User test seems authenticated but is not found in the database. 113s 2025-10-20 19:56:57,115 INFO Logging out user test. 113s 2025-10-20 19:56:57,117 INFO User test logged out 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2025-10-20 19:56:57,180 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,193 INFO Service ticket created for service https://www.example.com/é by user test. 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2025-10-20 19:56:57,228 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,242 INFO Service ticket created for service https://www.example.com by user test. 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2025-10-20 19:56:57,266 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,284 WARNING Received a login attempt for an already-active user 113s 2025-10-20 19:56:57,292 INFO Service ticket created for service https://www.example.com by user test. 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2025-10-20 19:56:57,314 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,324 WARNING Service https://www.example.org not allowed. 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2025-10-20 19:56:57,336 WARNING Service https://www.example.net not allowed. 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2025-10-20 19:56:57,358 WARNING Service https://www.example.net not allowed. 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2025-10-20 19:56:57,387 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,395 INFO logout requested 113s 2025-10-20 19:56:57,396 INFO Logging out user test. 113s 2025-10-20 19:56:57,405 INFO User test logged out 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2025-10-20 19:56:57,422 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,443 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,451 INFO logout requested 113s 2025-10-20 19:56:57,452 INFO Logging out user test from all sessions. 113s 2025-10-20 19:56:57,466 INFO User test logged out 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2025-10-20 19:56:57,484 INFO User test successfully authenticated 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_logout PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2025-10-20 19:56:57,492 INFO logout requested 113s 2025-10-20 19:56:57,493 INFO Logging out user test. 113s 2025-10-20 19:56:57,502 INFO User test logged out 113s 2025-10-20 19:56:57,508 INFO logout requested 113s 2025-10-20 19:56:57,529 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,548 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,561 INFO logout requested 113s 2025-10-20 19:56:57,563 INFO Logging out user test from all sessions. 113s 2025-10-20 19:56:57,576 INFO User test logged out 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2025-10-20 19:56:57,598 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,612 INFO Service ticket created for service http://127.0.0.1:41051 by user test. 113s 2025-10-20 19:56:57,619 INFO Validate: Service ticket ST-NA6PZIRWWZLay76m6Lp3qHV35QW1E2pQVyX1S3xRTLQGaRwSR6SrqVcfoE2Rd validated, user test authenticated on service http://127.0.0.1:41051 113s 2025-10-20 19:56:57,622 INFO logout requested 113s 2025-10-20 19:56:57,624 INFO Logging out user test. 113s 2025-10-20 19:56:57,629 INFO Sending SLO requests to service http://127.0.0.1:41051 for user test 113s 2025-10-20 19:56:57,636 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 113s 2025-10-20 19:56:57,639 INFO User test logged out 113s 2025-10-20 19:56:57,658 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,672 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 113s 2025-10-20 19:56:57,679 INFO Validate: Service ticket ST-FKmTgNTEtvP4wQEJy42NRXctNe8dyGm0XMybjUo7DzapaTvhJXBVCN2elU4QU validated, user test authenticated on service http://127.0.0.1:45678 113s 2025-10-20 19:56:57,682 INFO logout requested 113s 2025-10-20 19:56:57,683 INFO Logging out user test. 113s 2025-10-20 19:56:57,688 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 113s 2025-10-20 19:56:57,695 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 113s 2025-10-20 19:56:57,698 INFO User test logged out 113s 2025-10-20 19:56:57,718 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,731 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 113s 2025-10-20 19:56:57,740 INFO Validate: Service ticket ST-fDpSKtJPY5UskGOa5JIS0L98Ca22OoxCpETOInD4EZMTTVD3L5NlBiMrYv70f validated, user test authenticated on service http://127.0.0.1:45678 113s 2025-10-20 19:56:57,743 INFO logout requested 113s 2025-10-20 19:56:57,745 INFO Logging out user test. 113s 2025-10-20 19:56:57,750 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 113s 2025-10-20 19:56:57,756 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=43489): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 113s 2025-10-20 19:56:57,758 INFO User test logged out 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2025-10-20 19:56:57,776 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,797 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,811 INFO logout requested 113s 2025-10-20 19:56:57,812 INFO Logging out user test. 113s 2025-10-20 19:56:57,818 INFO User test logged out 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2025-10-20 19:56:57,849 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,857 INFO logout requested 113s 2025-10-20 19:56:57,858 INFO Logging out user test. 113s 2025-10-20 19:56:57,865 INFO User test logged out 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2025-10-20 19:56:57,893 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,901 INFO logout requested 113s 2025-10-20 19:56:57,902 INFO Logging out user test. 113s 2025-10-20 19:56:57,909 INFO User test logged out 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2025-10-20 19:56:57,940 INFO User test successfully authenticated 113s 2025-10-20 19:56:57,948 INFO logout requested 113s 2025-10-20 19:56:57,949 INFO Logging out user test. 113s 2025-10-20 19:56:57,956 INFO User test logged out 113s PASSED 114s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2025-10-20 19:56:57,974 INFO User test successfully authenticated 114s 2025-10-20 19:56:57,981 INFO logout requested 114s 2025-10-20 19:56:57,983 INFO Logging out user test. 114s 2025-10-20 19:56:57,990 INFO User test logged out 114s 2025-10-20 19:56:58,003 INFO logout requested 114s PASSED 114s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 114s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 114s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice 2025-10-20 19:56:58,038 WARNING Service https://www.example.org not allowed. 114s PASSED 114s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings PASSED 114s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 114s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2025-10-20 19:56:58,074 INFO User test successfully authenticated 114s PASSED 114s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 114s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2025-10-20 19:56:58,111 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,124 INFO Service ticket created for service https://www.example.com by user test. 114s 2025-10-20 19:56:58,128 WARNING Validate: service or ticket missing 114s 2025-10-20 19:56:58,131 WARNING Validate: service or ticket missing 114s PASSED 114s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2025-10-20 19:56:58,148 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,155 INFO Service ticket created for service https://www.example.com by user test. 114s 2025-10-20 19:56:58,162 INFO Validate: Service ticket ST-jTnnA4xhyJgewDSGVj0cbgtFydW0X4s5xcljEVPbyFVttUgtDl7CxYkkpIFYD validated, user test authenticated on service https://www.example.com 114s 2025-10-20 19:56:58,175 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,183 INFO Service ticket created for service https://www.example.com by user test. 114s 2025-10-20 19:56:58,191 INFO Validate: Service ticket ST-btRTx5pypPj7iGtKttKcE9lZRNZKwarvGJLlYqX3flUc7fupJe14HD0clu0Pa validated, user test authenticated on service https://www.example.com 114s 2025-10-20 19:56:58,200 INFO Service ticket created for service https://www.example.com by user test. 114s 2025-10-20 19:56:58,204 WARNING Validate: Service ticket ST-aDpQGUME1dvB7rL3M6rX2y4lNb6LCbdL6WzszSStl5NpQ262Y1apdIO99ajNV not found or already validated, auth to https://www.example.com failed 114s PASSED 114s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2025-10-20 19:56:58,222 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,235 INFO Service ticket created for service https://user_field.example.com by user test. 114s 2025-10-20 19:56:58,242 INFO Validate: Service ticket ST-m5goDLKisLSX8NQv5E7UH0qp7TPW4enco5dOnebgbUocdwxz7FITa8uD5WIuO validated, user test authenticated on service https://user_field.example.com 114s 2025-10-20 19:56:58,257 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,273 INFO Service ticket created for service https://user_field_alt.example.com by user test. 114s 2025-10-20 19:56:58,279 INFO Validate: Service ticket ST-257Ov303yQqIhWcziqcGT2I7xtg8uCAmdeNiZDi3k4ak3U6Mvm7KwKEMjhCBp validated, user test authenticated on service https://user_field_alt.example.com 114s PASSED 114s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2025-10-20 19:56:58,297 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,311 INFO Service ticket created for service https://www.example.com by user test. 114s 2025-10-20 19:56:58,316 WARNING Validate: Service ticket ST-UQMaEdTc428n8Rk4g9kMLZyllLbBWNza70VSpzsRGhhRH3jR1LrqGzTXKD7Qe not found or already validated, auth to https://www.example.org failed 114s PASSED 114s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2025-10-20 19:56:58,335 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,350 INFO Service ticket created for service https://www.example.com by user test. 114s 2025-10-20 19:56:58,355 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 114s PASSED 114s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2025-10-20 19:56:58,373 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,387 INFO Service ticket created for service https://www.example.com by user test. 114s 2025-10-20 19:56:58,393 INFO Validate: Service ticket ST-5ZnsF7byTTOc11IpQhfxj10dAMkNiHnodlOl3YHLDwFQv0z5iNwxL6FyVr28n validated, user test authenticated on service https://www.example.com 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2025-10-20 19:56:58,422 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,436 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-20 19:56:58,440 WARNING ValidateService: missing ticket or service 114s 2025-10-20 19:56:58,443 WARNING ValidateService: missing ticket or service 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2025-10-20 19:56:58,465 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,478 INFO Service ticket created for service http://127.0.0.1:34713 by user test. 114s 2025-10-20 19:56:58,491 INFO ValidateService: ticket ST-aHFZFJNmlhxzWame7388rXU0cGCVN2L3jsaVTtFdUeItYN56tDpLuD4gOq9bS validated for user test on service http://127.0.0.1:34713. Proxy Granting Ticket transmited to http://127.0.0.1:34713. 114s 2025-10-20 19:56:58,502 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 114s 2025-10-20 19:56:58,513 INFO ValidateService: ticket PT-s5e4jcXHWVukYuT9hoPvtU27RfSohwHjw3SbPdrJmjOT422pR0r8irekNXNEZ validated for user test on service http://127.0.0.1:45678. 114s 2025-10-20 19:56:58,531 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,545 INFO Service ticket created for service http://127.0.0.1:46097 by user test. 114s 2025-10-20 19:56:58,557 INFO ValidateService: ticket ST-WTQ0XRzoJbllczcW8xnQlo7LoMjA7YgYkfKsm8qDj4yqzVNJEz7nafEYujwBl validated for user test on service http://127.0.0.1:46097. Proxy Granting Ticket transmited to http://127.0.0.1:46097. 114s 2025-10-20 19:56:58,567 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 114s 2025-10-20 19:56:58,570 WARNING ValidateService: validation error: INVALID_TICKET PT-hjLVPxiCISpe45vwZvUOiY6ZXBwNCp0heBB38s1alH9kPOYgGGP5NT56gHu0d 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2025-10-20 19:56:58,592 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,606 INFO Service ticket created for service https://replace_attribute.example.com by user test. 114s 2025-10-20 19:56:58,613 INFO ValidateService: ticket ST-hRdQ5qzbG4cNBEmWzH1En63Au47YkbEWy3nAXLIiTizfvuRQ6SAQ5VjEmnln7 validated for user test on service https://replace_attribute.example.com. 114s 2025-10-20 19:56:58,627 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,641 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 114s 2025-10-20 19:56:58,648 INFO ValidateService: ticket ST-zADto5qwKzA0bdgvhWP18ZhHQw8e6td8DyTq38JA9mUL0ylLhamdsGlvjFTZh validated for user test on service https://replace_attribute_list.example.com. 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2025-10-20 19:56:58,672 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,685 INFO Service ticket created for service http://127.0.0.1:35313 by user test. 114s 2025-10-20 19:56:58,698 INFO ValidateService: ticket ST-tNW4ZrsnpUWygl0Ecd1IN7OP9ZT98hNIvjBnXVl6xruWWFaaf04vO6oOlZapE validated for user test on service http://127.0.0.1:35313. Proxy Granting Ticket transmited to http://127.0.0.1:35313. 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2025-10-20 19:56:58,719 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,733 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-20 19:56:58,743 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 114s 2025-10-20 19:56:58,759 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,771 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-20 19:56:58,778 WARNING Service https://www.example.org not allowed. 114s 2025-10-20 19:56:58,779 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2025-10-20 19:56:58,799 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,811 INFO Service ticket created for service https://127.0.0.1:45923 by user test. 114s 2025-10-20 19:56:58,824 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=45923): Max retries exceeded with url: /?pgtId=PGT-Godl2st4QDQF3TfkQJF5lsYAfK0npDOYliZ7XHloMABBc18cboAWNkRMZhhz&pgtIou=PGTIOU-UqKHgyQZy2oLZ8CG9UAko7UaY0IkU66Hkp89hLp78aH430mnCv3upaewT (Caused by SSLError(SSLError(1, '[SSL: RECORD_LAYER_FAILURE] record layer failure (_ssl.c:1033)'))) 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2025-10-20 19:56:58,845 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,852 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-20 19:56:58,858 INFO ValidateService: ticket ST-F13xBXWSL3E1rREjyI5H5MPOJDXybExaLVmzWH4bj9YqdqacM96UpV0DLUitm validated for user test on service http://127.0.0.1:45678. 114s 2025-10-20 19:56:58,873 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,880 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-20 19:56:58,886 INFO ValidateService: ticket ST-eSH6WJ5OED0lkLthulMSe7O0GDl5uEjUwIgpQIhM5FQlF7xMMosT7jxNREw69 validated for user test on service http://127.0.0.1:45678. 114s 2025-10-20 19:56:58,896 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-20 19:56:58,900 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2025-10-20 19:56:58,918 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,930 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-20 19:56:58,936 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2025-10-20 19:56:58,956 INFO User test successfully authenticated 114s 2025-10-20 19:56:58,969 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 115s 2025-10-20 19:56:58,973 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 115s PASSED 115s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2025-10-20 19:56:58,992 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,004 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 115s 2025-10-20 19:56:59,009 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 115s PASSED 115s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2025-10-20 19:56:59,030 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,042 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 115s 2025-10-20 19:56:59,049 INFO ValidateService: ticket ST-GrkiBShlMaWgVZI0aNypiadTRqxWjMELNhN6KeDSXnXGfKLXFuhCe7Ukv18tk validated for user test on service http://127.0.0.1:45678. 115s PASSED 115s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2025-10-20 19:56:59,069 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,081 INFO Service ticket created for service https://one_attribute.example.com by user test. 115s 2025-10-20 19:56:59,088 INFO ValidateService: ticket ST-Ew5RANFYoMWgjUm2jB199BcmBIvxc3FC7qnVeCt1nc9JDJBY5SYowxEiOPTiU validated for user test on service https://one_attribute.example.com. 115s PASSED 115s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2025-10-20 19:56:59,110 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,123 INFO Service ticket created for service http://127.0.0.1:41065 by user test. 115s 2025-10-20 19:56:59,135 INFO ValidateService: ticket ST-35gORCnqTLQwaiGLTDbEno62RDzo9GwdPaVbfYX1bvZYwSNY3mGmcLIEhDP9L validated for user test on service http://127.0.0.1:41065. Proxy Granting Ticket transmited to http://127.0.0.1:41065. 115s PASSED 115s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2025-10-20 19:56:59,155 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,168 INFO Service ticket created for service https://user_field.example.com by user test. 115s 2025-10-20 19:56:59,174 INFO ValidateService: ticket ST-r39UL2mc1BLh6KaOym9Gy0dczFPN5nUiMCcxiYSHlqIuDSfXAHmlkBbH41uhK validated for user test on service https://user_field.example.com. 115s 2025-10-20 19:56:59,188 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,201 INFO Service ticket created for service https://user_field_alt.example.com by user test. 115s 2025-10-20 19:56:59,207 INFO ValidateService: ticket ST-pEh5trRRJ3aus1DZjbnXDLafpQAxRMCZCytD8klODJqf5ARqO5p3ceedeQPH6 validated for user test on service https://user_field_alt.example.com. 115s PASSED 115s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2025-10-20 19:56:59,230 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,244 INFO Service ticket created for service http://127.0.0.1:38759 by user test. 115s 2025-10-20 19:56:59,257 INFO ValidateService: ticket ST-6S2Pelv7Z25bmlMLzZc7l9RZTIZTjeDlxXLf6cZiKwpTsf4HaYzDx7nlhuQ1T validated for user test on service http://127.0.0.1:38759. Proxy Granting Ticket transmited to http://127.0.0.1:38759. 115s 2025-10-20 19:56:59,259 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 115s 2025-10-20 19:56:59,262 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 115s PASSED 115s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2025-10-20 19:56:59,284 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,297 INFO Service ticket created for service http://127.0.0.1:41903 by user test. 115s 2025-10-20 19:56:59,308 INFO ValidateService: ticket ST-uGLXU6tTJf9GyORT3GuH3av2B6EoYNN3jSgJE0JdxHJA7hDAfrU9KQ5zFYFCJ validated for user test on service http://127.0.0.1:41903. Proxy Granting Ticket transmited to http://127.0.0.1:41903. 115s 2025-10-20 19:56:59,315 WARNING Username test not allowed on service restrict_user_fail 115s 2025-10-20 19:56:59,315 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 115s 2025-10-20 19:56:59,320 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 115s 2025-10-20 19:56:59,321 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 115s 2025-10-20 19:56:59,325 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 115s 2025-10-20 19:56:59,326 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 115s PASSED 115s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2025-10-20 19:56:59,350 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,363 INFO Service ticket created for service http://127.0.0.1:38929 by user test. 115s 2025-10-20 19:56:59,375 INFO ValidateService: ticket ST-OmkdRxFhKROUxTOlDVmF0IXKiyameXfhxYxJWpOCbU7UPdC7lXEhwIMRdLw4n validated for user test on service http://127.0.0.1:38929. Proxy Granting Ticket transmited to http://127.0.0.1:38929. 115s 2025-10-20 19:56:59,379 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 115s PASSED 115s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2025-10-20 19:56:59,400 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,413 INFO Service ticket created for service http://127.0.0.1:35307 by user test. 115s 2025-10-20 19:56:59,426 INFO ValidateService: ticket ST-5wwccnkEllftHGTaE0n1wrWNx6gt6kslvbSQI3xPN7IoLMlG1zuULmsejeqUY validated for user test on service http://127.0.0.1:35307. Proxy Granting Ticket transmited to http://127.0.0.1:35307. 115s 2025-10-20 19:56:59,429 WARNING Service https://www.example.org not allowed. 115s 2025-10-20 19:56:59,429 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 115s 2025-10-20 19:56:59,433 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:35307 does not allow proxy tickets 115s PASSED 115s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2025-10-20 19:56:59,455 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,467 INFO Service ticket created for service http://127.0.0.1:39507 by user test. 115s 2025-10-20 19:56:59,478 INFO ValidateService: ticket ST-ZIv2IO4bDryrkwFfNxYPQ1jq5wpAtpv2IrN6psbHSUywtqzWgtQzM7a1IvjUw validated for user test on service http://127.0.0.1:39507. Proxy Granting Ticket transmited to http://127.0.0.1:39507. 115s 2025-10-20 19:56:59,488 INFO Proxy ticket created for user test on service https://www.example.com. 115s 2025-10-20 19:56:59,494 INFO ValidateService: ticket PT-RIHkVtV8x5Knn8xYBSerNfyWwbDHbFPIqT6VtBvV2PXM6q4OZdVMBsuHqC7OR validated for user test on service https://www.example.com. 115s PASSED 115s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2025-10-20 19:56:59,518 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,531 INFO Service ticket created for service https://www.example.com by user test. 115s 2025-10-20 19:56:59,537 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 115s PASSED 115s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2025-10-20 19:56:59,548 WARNING SamlValidate: validation error: AuthnFailed ticket ST-3JZoedjCB6pE6HMZ9ltvDt7emTeiH5HgYLhv81kgaZMWT24hrZz2XMMweryCy not found 115s 2025-10-20 19:56:59,552 WARNING SamlValidate: validation error: AuthnFailed ticket PT-1iTBiOzAnN0aLTpuAYBWT4mt0qGwGGOAGzfFSABxVzzrq0rgeLwYCRZovOtGI not found 115s PASSED 115s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix 2025-10-20 19:56:59,561 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 115s PASSED 115s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml 2025-10-20 19:56:59,570 WARNING SamlValidate: validation error: VersionMismatch 115s PASSED 115s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2025-10-20 19:56:59,590 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,603 INFO Service ticket created for service https://www.example.com by user test. 115s 2025-10-20 19:56:59,619 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,632 INFO Service ticket created for service http://127.0.0.1:45065 by user test. 115s 2025-10-20 19:56:59,645 INFO ValidateService: ticket ST-1UNbSgpHNFM2wE8rZTPaGxZLZVVGBmjyh2LpMQUjOpkvL4XuEcjjP1mO5w9IC validated for user test on service http://127.0.0.1:45065. Proxy Granting Ticket transmited to http://127.0.0.1:45065. 115s 2025-10-20 19:56:59,654 INFO Proxy ticket created for user test on service https://www.example.com. 115s 2025-10-20 19:56:59,662 INFO SamlValidate: ticket ST-uoncyU1H18ec0L6aAInhULzI3z75OMtr7zbZK2NL6KTFIt54B4IYcjvNOTyp5 validated for user test on service https://www.example.com. 115s 2025-10-20 19:56:59,668 INFO SamlValidate: ticket PT-8YEd2WP7TtRs3djUaDSCrQeWihq2OkhmODnbrBsjgK1lkJhuND0qy0PmMvrIw validated for user test on service https://www.example.com. 115s PASSED 115s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2025-10-20 19:56:59,688 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,700 INFO Service ticket created for service https://field_needed_success.example.com by user test. 115s 2025-10-20 19:56:59,707 INFO SamlValidate: ticket ST-0XB0guqJzlTG8AWeQEHG5zGqbbw9pJUbzuX2KaA4B9eE3TgjBueLgBXnmNd2P validated for user test on service https://field_needed_success.example.com. 115s 2025-10-20 19:56:59,719 INFO User test successfully authenticated 115s 2025-10-20 19:56:59,733 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 115s 2025-10-20 19:56:59,739 INFO SamlValidate: ticket ST-bdUNx1JUiaYxDvZbwHmgGpf1GuWlpLMuA7Eq4PaSfKjWdNcCA0A11FuVyJ73s validated for user test on service https://field_needed_success_alt.example.com. 115s PASSEDDestroying test database for alias 'default'... 116s 116s 116s =============================== warnings summary =============================== 116s cas_server/tests/test_federate.py: 8 warnings 116s cas_server/tests/test_view.py: 3 warnings 116s /tmp/autopkgtest.Bu4hIP/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). 116s datetime.utcnow() + timedelta(seconds=max_age), 116s 116s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 116s /usr/lib/python3.13/unittest/case.py:707: DeprecationWarning: It is deprecated to return a value that is not None from a test case (>) 116s return self.run(*args, **kwds) 116s 116s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 116s ====================== 128 passed, 12 warnings in 12.82s ======================= 116s 116s autopkgtest [19:57:00]: test run-test-suite.sh: -----------------------] 117s autopkgtest [19:57:01]: test run-test-suite.sh: - - - - - - - - - - results - - - - - - - - - - 117s run-test-suite.sh PASS 118s autopkgtest [19:57:02]: @@@@@@@@@@@@@@@@@@@@ summary 118s run-test-suite.sh PASS