0s autopkgtest [13:19:06]: starting date and time: 2024-11-14 13:19:06+0000 0s autopkgtest [13:19:06]: git checkout: 6f3be7a8 Fix armhf LXD image generation for plucky 0s autopkgtest [13:19:06]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.xsp2d8m1/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:lxml --apt-upgrade django-cas-server --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=lxml/5.3.0-1build1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-arm64-19.secgroup --name adt-plucky-arm64-django-cas-server-20241114-131906-juju-7f2275-prod-proposed-migration-environment-20-b6177cb5-eb48-400b-8a04-cf3cdbed7d07 --image adt/ubuntu-plucky-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 79s autopkgtest [13:20:25]: testbed dpkg architecture: arm64 79s autopkgtest [13:20:25]: testbed apt version: 2.9.8 79s autopkgtest [13:20:25]: @@@@@@@@@@@@@@@@@@@@ test bed setup 80s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 80s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.3 kB] 81s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [105 kB] 81s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 81s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [983 kB] 81s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [132 kB] 81s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 Packages [50.3 kB] 81s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [715 kB] 81s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [6012 B] 81s Fetched 2088 kB in 1s (2226 kB/s) 81s Reading package lists... 84s Reading package lists... 84s Building dependency tree... 84s Reading state information... 86s Calculating upgrade... 86s The following packages will be upgraded: 86s pastebinit 87s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 87s Need to get 14.9 kB of archives. 87s After this operation, 0 B of additional disk space will be used. 87s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 pastebinit all 1.7.1-1 [14.9 kB] 87s Fetched 14.9 kB in 0s (65.2 kB/s) 87s (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 ... 79925 files and directories currently installed.) 87s Preparing to unpack .../pastebinit_1.7.1-1_all.deb ... 87s Unpacking pastebinit (1.7.1-1) over (1.7.0-1) ... 87s Setting up pastebinit (1.7.1-1) ... 88s Processing triggers for man-db (2.12.1-3) ... 88s Reading package lists... 89s Building dependency tree... 89s Reading state information... 91s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 91s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 91s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 91s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 91s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 93s Reading package lists... 93s Reading package lists... 93s Building dependency tree... 93s Reading state information... 94s Calculating upgrade... 95s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 95s Reading package lists... 95s Building dependency tree... 95s Reading state information... 96s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 99s autopkgtest [13:20:45]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 14:19:41 UTC 2024 100s autopkgtest [13:20:46]: @@@@@@@@@@@@@@@@@@@@ apt-source django-cas-server 102s Get:1 http://ftpmaster.internal/ubuntu plucky/universe django-cas-server 2.1.0-1 (dsc) [2613 B] 102s Get:2 http://ftpmaster.internal/ubuntu plucky/universe django-cas-server 2.1.0-1 (tar) [138 kB] 102s Get:3 http://ftpmaster.internal/ubuntu plucky/universe django-cas-server 2.1.0-1 (diff) [9752 B] 102s gpgv: Signature made Tue Aug 20 09:50:18 2024 UTC 102s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 102s gpgv: issuer "tchet@debian.org" 102s gpgv: Can't check signature: No public key 102s dpkg-source: warning: cannot verify inline signature for ./django-cas-server_2.1.0-1.dsc: no acceptable signature found 102s autopkgtest [13:20:48]: testing package django-cas-server version 2.1.0-1 103s autopkgtest [13:20:49]: build not needed 103s autopkgtest [13:20:49]: test run-test-suite.sh: preparing testbed 107s Reading package lists... 107s Building dependency tree... 107s Reading state information... 108s Starting pkgProblemResolver with broken count: 0 108s Starting 2 pkgProblemResolver with broken count: 0 108s Done 110s The following additional packages will be installed: 110s fonts-font-awesome fonts-lato libjs-jquery libjs-sphinxdoc libjs-underscore 110s libxslt1.1 python3-all python3-asgiref python3-django 110s python3-django-cas-server python3-django-cas-server-doc python3-iniconfig 110s python3-lxml python3-mock python3-packaging python3-pluggy python3-pytest 110s python3-pytest-django python3-requests-futures python3-sqlparse 110s sphinx-rtd-theme-common 110s Suggested packages: 110s bpython3 geoip-database-contrib gettext ipython3 libgdal20 110s libsqlite3-mod-spatialite python-django-doc python3-bcrypt python3-flup 110s python3-memcache python3-mysqldb python3-pil python3-psycopg2 110s python3-selenium python3-sqlite python-lxml-doc python-mock-doc 110s python-sqlparse-doc 110s Recommended packages: 110s javascript-common python3-tz python3-bs4 python3-cssselect python3-html5lib 110s The following NEW packages will be installed: 110s autopkgtest-satdep fonts-font-awesome fonts-lato libjs-jquery 110s libjs-sphinxdoc libjs-underscore libxslt1.1 python3-all python3-asgiref 110s python3-django python3-django-cas-server python3-django-cas-server-doc 110s python3-iniconfig python3-lxml python3-mock python3-packaging python3-pluggy 110s python3-pytest python3-pytest-django python3-requests-futures 110s python3-sqlparse sphinx-rtd-theme-common 110s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 110s Need to get 10.3 MB/10.3 MB of archives. 110s After this operation, 55.5 MB of additional disk space will be used. 110s Get:1 /tmp/autopkgtest.bmd9gY/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [744 B] 110s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 fonts-lato all 2.015-1 [2781 kB] 111s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 111s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 111s Get:5 http://ftpmaster.internal/ubuntu plucky/main arm64 libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 111s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 libjs-sphinxdoc all 7.4.7-4 [158 kB] 111s Get:7 http://ftpmaster.internal/ubuntu plucky/main arm64 libxslt1.1 arm64 1.1.39-0exp1ubuntu1 [167 kB] 111s Get:8 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-all arm64 3.12.6-0ubuntu1 [886 B] 111s Get:9 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-asgiref all 3.8.1-1 [24.8 kB] 111s Get:10 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-sqlparse all 0.5.1-2 [32.7 kB] 111s Get:11 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-django all 3:4.2.15-1ubuntu1 [2916 kB] 112s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 python3-lxml arm64 5.3.0-1build1 [1591 kB] 112s Get:13 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-requests-futures all 1.0.1-1 [11.7 kB] 112s Get:14 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-django-cas-server all 2.1.0-1 [111 kB] 112s Get:15 http://ftpmaster.internal/ubuntu plucky/main arm64 sphinx-rtd-theme-common all 3.0.1+dfsg-1 [1012 kB] 112s Get:16 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-django-cas-server-doc all 2.1.0-1 [176 kB] 112s Get:17 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-iniconfig all 1.1.1-2 [6024 B] 112s Get:18 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-packaging all 24.1-1 [41.4 kB] 112s Get:19 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-pluggy all 1.5.0-1 [21.0 kB] 112s Get:20 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-pytest all 8.3.3-1 [251 kB] 112s Get:21 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-pytest-django all 4.5.2-3 [18.5 kB] 112s Get:22 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-mock all 5.1.0-1 [64.1 kB] 113s Fetched 10.3 MB in 2s (4153 kB/s) 113s Selecting previously unselected package fonts-lato. 113s (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 ... 79925 files and directories currently installed.) 113s Preparing to unpack .../00-fonts-lato_2.015-1_all.deb ... 113s Unpacking fonts-lato (2.015-1) ... 113s Selecting previously unselected package fonts-font-awesome. 113s Preparing to unpack .../01-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 113s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 113s Selecting previously unselected package libjs-jquery. 113s Preparing to unpack .../02-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 113s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 113s Selecting previously unselected package libjs-underscore. 113s Preparing to unpack .../03-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 113s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 114s Selecting previously unselected package libjs-sphinxdoc. 114s Preparing to unpack .../04-libjs-sphinxdoc_7.4.7-4_all.deb ... 114s Unpacking libjs-sphinxdoc (7.4.7-4) ... 114s Selecting previously unselected package libxslt1.1:arm64. 114s Preparing to unpack .../05-libxslt1.1_1.1.39-0exp1ubuntu1_arm64.deb ... 114s Unpacking libxslt1.1:arm64 (1.1.39-0exp1ubuntu1) ... 114s Selecting previously unselected package python3-all. 114s Preparing to unpack .../06-python3-all_3.12.6-0ubuntu1_arm64.deb ... 114s Unpacking python3-all (3.12.6-0ubuntu1) ... 114s Selecting previously unselected package python3-asgiref. 114s Preparing to unpack .../07-python3-asgiref_3.8.1-1_all.deb ... 114s Unpacking python3-asgiref (3.8.1-1) ... 114s Selecting previously unselected package python3-sqlparse. 114s Preparing to unpack .../08-python3-sqlparse_0.5.1-2_all.deb ... 114s Unpacking python3-sqlparse (0.5.1-2) ... 114s Selecting previously unselected package python3-django. 114s Preparing to unpack .../09-python3-django_3%3a4.2.15-1ubuntu1_all.deb ... 114s Unpacking python3-django (3:4.2.15-1ubuntu1) ... 115s Selecting previously unselected package python3-lxml:arm64. 115s Preparing to unpack .../10-python3-lxml_5.3.0-1build1_arm64.deb ... 115s Unpacking python3-lxml:arm64 (5.3.0-1build1) ... 115s Selecting previously unselected package python3-requests-futures. 115s Preparing to unpack .../11-python3-requests-futures_1.0.1-1_all.deb ... 115s Unpacking python3-requests-futures (1.0.1-1) ... 115s Selecting previously unselected package python3-django-cas-server. 115s Preparing to unpack .../12-python3-django-cas-server_2.1.0-1_all.deb ... 115s Unpacking python3-django-cas-server (2.1.0-1) ... 115s Selecting previously unselected package sphinx-rtd-theme-common. 115s Preparing to unpack .../13-sphinx-rtd-theme-common_3.0.1+dfsg-1_all.deb ... 115s Unpacking sphinx-rtd-theme-common (3.0.1+dfsg-1) ... 115s Selecting previously unselected package python3-django-cas-server-doc. 115s Preparing to unpack .../14-python3-django-cas-server-doc_2.1.0-1_all.deb ... 115s Unpacking python3-django-cas-server-doc (2.1.0-1) ... 115s Selecting previously unselected package python3-iniconfig. 115s Preparing to unpack .../15-python3-iniconfig_1.1.1-2_all.deb ... 115s Unpacking python3-iniconfig (1.1.1-2) ... 115s Selecting previously unselected package python3-packaging. 115s Preparing to unpack .../16-python3-packaging_24.1-1_all.deb ... 115s Unpacking python3-packaging (24.1-1) ... 115s Selecting previously unselected package python3-pluggy. 115s Preparing to unpack .../17-python3-pluggy_1.5.0-1_all.deb ... 115s Unpacking python3-pluggy (1.5.0-1) ... 115s Selecting previously unselected package python3-pytest. 115s Preparing to unpack .../18-python3-pytest_8.3.3-1_all.deb ... 115s Unpacking python3-pytest (8.3.3-1) ... 115s Selecting previously unselected package python3-pytest-django. 115s Preparing to unpack .../19-python3-pytest-django_4.5.2-3_all.deb ... 115s Unpacking python3-pytest-django (4.5.2-3) ... 115s Selecting previously unselected package python3-mock. 115s Preparing to unpack .../20-python3-mock_5.1.0-1_all.deb ... 115s Unpacking python3-mock (5.1.0-1) ... 115s Selecting previously unselected package autopkgtest-satdep. 115s Preparing to unpack .../21-1-autopkgtest-satdep.deb ... 115s Unpacking autopkgtest-satdep (0) ... 115s Setting up python3-iniconfig (1.1.1-2) ... 116s Setting up fonts-lato (2.015-1) ... 116s Setting up python3-all (3.12.6-0ubuntu1) ... 116s Setting up python3-mock (5.1.0-1) ... 116s Setting up python3-packaging (24.1-1) ... 116s Setting up python3-sqlparse (0.5.1-2) ... 116s Setting up python3-pluggy (1.5.0-1) ... 117s Setting up libxslt1.1:arm64 (1.1.39-0exp1ubuntu1) ... 117s Setting up python3-requests-futures (1.0.1-1) ... 117s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 117s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 117s Setting up sphinx-rtd-theme-common (3.0.1+dfsg-1) ... 117s Setting up python3-asgiref (3.8.1-1) ... 117s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 117s Setting up python3-django (3:4.2.15-1ubuntu1) ... 119s Setting up python3-pytest (8.3.3-1) ... 119s Setting up python3-pytest-django (4.5.2-3) ... 120s Setting up libjs-sphinxdoc (7.4.7-4) ... 120s Setting up python3-lxml:arm64 (5.3.0-1build1) ... 120s Setting up python3-django-cas-server (2.1.0-1) ... 120s Setting up python3-django-cas-server-doc (2.1.0-1) ... 120s Setting up autopkgtest-satdep (0) ... 120s Processing triggers for man-db (2.12.1-3) ... 120s Processing triggers for libc-bin (2.40-1ubuntu3) ... 128s (Reading database ... 86741 files and directories currently installed.) 128s Removing autopkgtest-satdep (0) ... 129s autopkgtest [13:21:15]: test run-test-suite.sh: [----------------------- 129s 129s *************************** 129s *** Testing with python3.12 129s *************************** 129s 129s Content of current working folder: 129s 129s total 20 129s drwxr-xr-x 3 ubuntu ubuntu 4096 Nov 14 13:21 . 129s drwxrwxrwt 5 root root 4096 Nov 14 13:21 .. 129s drwxrwxr-x 9 ubuntu ubuntu 4096 Nov 14 13:20 cas_server 129s -rw-rw-r-- 1 ubuntu ubuntu 140 Aug 18 11:21 pytest.ini 129s -rw-rw-r-- 1 ubuntu ubuntu 152 Aug 18 11:21 setup.cfg 129s Running tests... 129s 130s ============================= test session starts ============================== 130s platform linux -- Python 3.12.7, pytest-8.3.3, pluggy-1.5.0 -- /usr/bin/python3.12 130s cachedir: .pytest_cache 130s django: settings: cas_server.tests.settings (from ini) 130s rootdir: /tmp/autopkgtest.bmd9gY/autopkgtest_tmp 130s configfile: pytest.ini 130s testpaths: cas_server/tests/ 130s plugins: typeguard-4.4.1, django-4.5.2 131s collecting ... collected 128 items 131s 131s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 132s 2024-11-14 13:21:18,460 WARNING Identity provider suffix exemple.fr not found 132s 2024-11-14 13:21:18,483 INFO Got an invalid ticket ST-3BsoUizUcW7HeHe1DKteqxY383nJOf2Fa2jK7WvBz69Iyx9atTnDOhfP51aWC from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 132s 2024-11-14 13:21:18,493 INFO Got an invalid ticket ST-ipnD734L0AalKrUNYVxDBZOYzv4rBjtAmCy40SJCYDDpShbafW6oUmAQEBxvm from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 132s PASSED 132s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2024-11-14 13:21:18,567 WARNING CAS_FEDERATE is False, set it to True to use federation 132s 2024-11-14 13:21:18,569 WARNING CAS_FEDERATE is False, set it to True to use federation 132s PASSED 132s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2024-11-14 13:21:18,605 INFO Trying to authenticate http://127.0.0.1:8080 again 132s 2024-11-14 13:21:18,621 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 132s 2024-11-14 13:21:18,653 INFO User dédé@example.com successfully authenticated 132s 2024-11-14 13:21:18,683 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 132s 2024-11-14 13:21:18,716 INFO Trying to authenticate http://127.0.0.1:8081 again 132s 2024-11-14 13:21:18,730 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 132s 2024-11-14 13:21:18,754 INFO User dédé@example.org successfully authenticated 132s 2024-11-14 13:21:18,778 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 132s 2024-11-14 13:21:18,806 INFO Trying to authenticate http://127.0.0.1:8082 again 132s 2024-11-14 13:21:18,820 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 132s 2024-11-14 13:21:18,844 INFO User dédé@example.net successfully authenticated 132s 2024-11-14 13:21:18,869 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 132s 2024-11-14 13:21:18,896 INFO Trying to authenticate http://127.0.0.1:8083 again 132s 2024-11-14 13:21:18,914 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 132s 2024-11-14 13:21:18,939 INFO User dédé@example.test successfully authenticated 133s 2024-11-14 13:21:18,963 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 133s 2024-11-14 13:21:19,064 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-4UrYr14z0twZKpo9SQiF5AOqBZxvf2wlQs7kH2iDl3zAszLFbJHsd97Cbs4Ls 133s 2024-11-14 13:21:19,127 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-lRe7ObU1HfEaFdJEQkn7oznyRxElsOxBaXrWhPQxbj43sNa8EmAJvNX3Z0mVG 133s PASSED 133s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 133s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2024-11-14 13:21:19,212 INFO Trying to authenticate http://127.0.0.1:8080 again 133s 2024-11-14 13:21:19,224 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 133s 2024-11-14 13:21:19,250 INFO User dédé@example.com successfully authenticated 133s 2024-11-14 13:21:19,275 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 133s 2024-11-14 13:21:19,302 INFO Trying to authenticate http://127.0.0.1:8081 again 133s 2024-11-14 13:21:19,315 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 133s 2024-11-14 13:21:19,340 INFO User dédé@example.org successfully authenticated 133s 2024-11-14 13:21:19,364 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 133s 2024-11-14 13:21:19,391 INFO Trying to authenticate http://127.0.0.1:8082 again 133s 2024-11-14 13:21:19,406 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 133s 2024-11-14 13:21:19,431 INFO User dédé@example.net successfully authenticated 133s 2024-11-14 13:21:19,455 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 133s 2024-11-14 13:21:19,482 INFO Trying to authenticate http://127.0.0.1:8083 again 133s 2024-11-14 13:21:19,496 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 133s 2024-11-14 13:21:19,521 INFO User dédé@example.test successfully authenticated 133s 2024-11-14 13:21:19,545 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 133s 2024-11-14 13:21:19,549 INFO logout requested 133s 2024-11-14 13:21:19,554 INFO Logging out user dédé@example.com. 133s 2024-11-14 13:21:19,565 INFO User dédé@example.com logged out 133s 2024-11-14 13:21:19,590 INFO logout requested 133s 2024-11-14 13:21:19,598 INFO logout requested 133s 2024-11-14 13:21:19,603 INFO Logging out user dédé@example.org. 133s 2024-11-14 13:21:19,615 INFO User dédé@example.org logged out 133s 2024-11-14 13:21:19,637 INFO logout requested 133s 2024-11-14 13:21:19,643 INFO logout requested 133s 2024-11-14 13:21:19,648 INFO Logging out user dédé@example.net. 133s 2024-11-14 13:21:19,659 INFO User dédé@example.net logged out 133s 2024-11-14 13:21:19,681 INFO logout requested 133s 2024-11-14 13:21:19,688 INFO logout requested 133s 2024-11-14 13:21:19,693 INFO Logging out user dédé@example.test. 133s 2024-11-14 13:21:19,704 INFO User dédé@example.test logged out 133s 2024-11-14 13:21:19,726 INFO logout requested 133s 2024-11-14 13:21:19,757 INFO Trying to authenticate http://127.0.0.1:8080 again 133s 2024-11-14 13:21:19,771 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 133s 2024-11-14 13:21:19,795 INFO User dédé@example.com successfully authenticated 133s 2024-11-14 13:21:19,819 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 133s 2024-11-14 13:21:19,846 INFO Trying to authenticate http://127.0.0.1:8081 again 133s 2024-11-14 13:21:19,861 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 133s 2024-11-14 13:21:19,886 INFO User dédé@example.org successfully authenticated 133s 2024-11-14 13:21:19,911 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 133s 2024-11-14 13:21:19,937 INFO Trying to authenticate http://127.0.0.1:8082 again 133s 2024-11-14 13:21:19,952 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 134s 2024-11-14 13:21:19,976 INFO User dédé@example.net successfully authenticated 134s 2024-11-14 13:21:20,005 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 134s 2024-11-14 13:21:20,035 INFO Trying to authenticate http://127.0.0.1:8083 again 134s 2024-11-14 13:21:20,050 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 134s 2024-11-14 13:21:20,074 INFO User dédé@example.test successfully authenticated 134s 2024-11-14 13:21:20,098 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 134s 2024-11-14 13:21:20,104 INFO logout requested 134s 2024-11-14 13:21:20,107 INFO Logging out user dédé. 134s 2024-11-14 13:21:20,109 INFO User dédé logged out 134s 2024-11-14 13:21:20,133 INFO logout requested 134s 2024-11-14 13:21:20,136 INFO Logging out user dédé. 134s 2024-11-14 13:21:20,138 INFO User dédé logged out 134s 2024-11-14 13:21:20,161 INFO logout requested 134s 2024-11-14 13:21:20,164 INFO Logging out user dédé. 134s 2024-11-14 13:21:20,168 INFO User dédé logged out 134s 2024-11-14 13:21:20,191 INFO logout requested 134s 2024-11-14 13:21:20,194 INFO Logging out user dédé. 134s 2024-11-14 13:21:20,196 INFO User dédé logged out 134s PASSED 134s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2024-11-14 13:21:20,255 INFO Trying to authenticate http://127.0.0.1:8080 again 134s 2024-11-14 13:21:20,267 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 134s 2024-11-14 13:21:20,293 INFO User dédé@example.com successfully authenticated 134s 2024-11-14 13:21:20,316 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 134s 2024-11-14 13:21:20,343 INFO Trying to authenticate http://127.0.0.1:8081 again 134s 2024-11-14 13:21:20,356 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 134s 2024-11-14 13:21:20,386 INFO User dédé@example.org successfully authenticated 134s 2024-11-14 13:21:20,410 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 134s 2024-11-14 13:21:20,437 INFO Trying to authenticate http://127.0.0.1:8082 again 134s 2024-11-14 13:21:20,452 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 134s 2024-11-14 13:21:20,476 INFO User dédé@example.net successfully authenticated 134s 2024-11-14 13:21:20,500 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 134s 2024-11-14 13:21:20,527 INFO Trying to authenticate http://127.0.0.1:8083 again 134s 2024-11-14 13:21:20,541 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 134s 2024-11-14 13:21:20,566 INFO User dédé@example.test successfully authenticated 134s 2024-11-14 13:21:20,590 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 134s 2024-11-14 13:21:20,594 INFO logout requested 134s 2024-11-14 13:21:20,599 INFO Logging out user dédé@example.com. 134s 2024-11-14 13:21:20,613 INFO User dédé@example.com logged out 134s 2024-11-14 13:21:20,615 INFO logout requested 134s 2024-11-14 13:21:20,620 INFO Logging out user dédé@example.org. 134s 2024-11-14 13:21:20,632 INFO User dédé@example.org logged out 134s 2024-11-14 13:21:20,634 INFO logout requested 134s 2024-11-14 13:21:20,638 INFO Logging out user dédé@example.net. 134s 2024-11-14 13:21:20,649 INFO User dédé@example.net logged out 134s 2024-11-14 13:21:20,651 INFO logout requested 134s 2024-11-14 13:21:20,657 INFO Logging out user dédé@example.test. 134s 2024-11-14 13:21:20,668 INFO User dédé@example.test logged out 134s PASSED 134s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2024-11-14 13:21:20,702 WARNING A login attempt failed 135s PASSED2024-11-14 13:21:20,782 INFO Trying to authenticate http://127.0.0.1:8080 again 135s 135s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 135s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2024-11-14 13:21:20,794 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 135s 2024-11-14 13:21:20,818 INFO User dédé@example.com successfully authenticated 135s 2024-11-14 13:21:20,849 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 135s 2024-11-14 13:21:20,876 INFO Trying to authenticate http://127.0.0.1:8081 again 135s 2024-11-14 13:21:20,890 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 135s 2024-11-14 13:21:20,915 INFO User dédé@example.org successfully authenticated 135s 2024-11-14 13:21:20,938 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 135s 2024-11-14 13:21:20,966 INFO Trying to authenticate http://127.0.0.1:8082 again 135s 2024-11-14 13:21:20,979 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 135s 2024-11-14 13:21:21,004 INFO User dédé@example.net successfully authenticated 135s 2024-11-14 13:21:21,027 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 135s 2024-11-14 13:21:21,055 INFO Trying to authenticate http://127.0.0.1:8083 again 135s 2024-11-14 13:21:21,069 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 135s 2024-11-14 13:21:21,095 INFO User dédé@example.test successfully authenticated 135s 2024-11-14 13:21:21,121 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 135s PASSED 135s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2024-11-14 13:21:21,160 INFO Trying to authenticate http://127.0.0.1:8080 again 135s 2024-11-14 13:21:21,173 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 135s 2024-11-14 13:21:21,197 INFO User dédé@example.com successfully authenticated 135s 2024-11-14 13:21:21,222 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 135s 2024-11-14 13:21:21,253 INFO Trying to authenticate http://127.0.0.1:8081 again 135s 2024-11-14 13:21:21,266 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 135s 2024-11-14 13:21:21,292 INFO User dédé@example.org successfully authenticated 135s 2024-11-14 13:21:21,316 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 135s 2024-11-14 13:21:21,345 INFO Trying to authenticate http://127.0.0.1:8082 again 135s 2024-11-14 13:21:21,359 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 135s 2024-11-14 13:21:21,385 INFO User dédé@example.net successfully authenticated 135s 2024-11-14 13:21:21,409 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 135s 2024-11-14 13:21:21,435 INFO Trying to authenticate http://127.0.0.1:8083 again 135s 2024-11-14 13:21:21,448 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 135s 2024-11-14 13:21:21,472 INFO User dédé@example.test successfully authenticated 135s 2024-11-14 13:21:21,498 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 135s 2024-11-14 13:21:21,524 INFO Trying to authenticate http://127.0.0.1:8080 again 135s 2024-11-14 13:21:21,537 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 135s 2024-11-14 13:21:21,561 INFO User dédé@example.com successfully authenticated 135s 2024-11-14 13:21:21,585 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 135s 2024-11-14 13:21:21,612 INFO Trying to authenticate http://127.0.0.1:8081 again 135s 2024-11-14 13:21:21,627 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 135s 2024-11-14 13:21:21,655 INFO User dédé@example.org successfully authenticated 135s 2024-11-14 13:21:21,680 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 135s 2024-11-14 13:21:21,856 INFO Trying to authenticate http://127.0.0.1:8082 again 135s 2024-11-14 13:21:21,871 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 135s 2024-11-14 13:21:21,896 INFO User dédé@example.net successfully authenticated 135s 2024-11-14 13:21:21,920 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 135s 2024-11-14 13:21:21,947 INFO Trying to authenticate http://127.0.0.1:8083 again 135s 2024-11-14 13:21:21,963 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 136s 2024-11-14 13:21:21,987 INFO User dédé@example.test successfully authenticated 136s 2024-11-14 13:21:22,011 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 136s 2024-11-14 13:21:22,016 WARNING User already authenticated, dropping federated authentication request 136s 2024-11-14 13:21:22,019 WARNING User already authenticated, dropping federated authentication request 136s 2024-11-14 13:21:22,023 WARNING User already authenticated, dropping federated authentication request 136s 2024-11-14 13:21:22,027 WARNING User already authenticated, dropping federated authentication request 136s PASSED 136s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2024-11-14 13:21:22,065 INFO Trying to authenticate http://127.0.0.1:8080 again 136s 2024-11-14 13:21:22,078 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 136s 2024-11-14 13:21:22,104 INFO User dédé@example.com successfully authenticated 136s 2024-11-14 13:21:22,128 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 136s 2024-11-14 13:21:22,155 INFO Trying to authenticate http://127.0.0.1:8081 again 136s 2024-11-14 13:21:22,170 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 136s 2024-11-14 13:21:22,195 INFO User dédé@example.org successfully authenticated 136s 2024-11-14 13:21:22,219 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 136s 2024-11-14 13:21:22,252 INFO Trying to authenticate http://127.0.0.1:8082 again 136s 2024-11-14 13:21:22,265 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 136s 2024-11-14 13:21:22,290 INFO User dédé@example.net successfully authenticated 136s 2024-11-14 13:21:22,314 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 136s 2024-11-14 13:21:22,342 INFO Trying to authenticate http://127.0.0.1:8083 again 136s 2024-11-14 13:21:22,357 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 136s 2024-11-14 13:21:22,381 INFO User dédé@example.test successfully authenticated 136s 2024-11-14 13:21:22,404 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 136s 2024-11-14 13:21:22,408 INFO logout requested 136s 2024-11-14 13:21:22,413 INFO Logging out user dédé@example.com. 136s 2024-11-14 13:21:22,426 INFO User dédé@example.com logged out 136s 2024-11-14 13:21:22,435 INFO logout requested 136s 2024-11-14 13:21:22,439 INFO Logging out user dédé@example.org. 136s 2024-11-14 13:21:22,451 INFO User dédé@example.org logged out 136s 2024-11-14 13:21:22,459 INFO logout requested 136s 2024-11-14 13:21:22,463 INFO Logging out user dédé@example.net. 136s 2024-11-14 13:21:22,474 INFO User dédé@example.net logged out 136s 2024-11-14 13:21:22,483 INFO logout requested 136s 2024-11-14 13:21:22,487 INFO Logging out user dédé@example.test. 136s 2024-11-14 13:21:22,500 INFO User dédé@example.test logged out 136s PASSED 136s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2024-11-14 13:21:22,542 INFO Trying to authenticate http://127.0.0.1:8080 again 136s 2024-11-14 13:21:22,554 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 136s 2024-11-14 13:21:22,579 INFO User dédé@example.com successfully authenticated 136s 2024-11-14 13:21:22,603 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 136s 2024-11-14 13:21:22,629 INFO Trying to authenticate http://127.0.0.1:8081 again 136s 2024-11-14 13:21:22,643 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 136s 2024-11-14 13:21:22,666 INFO User dédé@example.org successfully authenticated 136s 2024-11-14 13:21:22,690 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 136s 2024-11-14 13:21:22,720 INFO Trying to authenticate http://127.0.0.1:8082 again 136s 2024-11-14 13:21:22,733 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 136s 2024-11-14 13:21:22,757 INFO User dédé@example.net successfully authenticated 136s 2024-11-14 13:21:22,781 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 136s 2024-11-14 13:21:22,807 INFO Trying to authenticate http://127.0.0.1:8083 again 136s 2024-11-14 13:21:22,821 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 136s 2024-11-14 13:21:22,846 INFO User dédé@example.test successfully authenticated 136s 2024-11-14 13:21:22,869 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 136s 2024-11-14 13:21:22,883 INFO Trying to authenticate http://127.0.0.1:8080 again 136s 2024-11-14 13:21:22,897 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 136s 2024-11-14 13:21:22,923 INFO User dédé@example.com successfully authenticated 136s 2024-11-14 13:21:22,934 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 136s 2024-11-14 13:21:22,951 INFO Trying to authenticate http://127.0.0.1:8081 again 136s 2024-11-14 13:21:22,964 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 137s 2024-11-14 13:21:22,991 INFO User dédé@example.org successfully authenticated 137s 2024-11-14 13:21:23,002 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 137s 2024-11-14 13:21:23,017 INFO Trying to authenticate http://127.0.0.1:8082 again 137s 2024-11-14 13:21:23,032 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 137s 2024-11-14 13:21:23,058 INFO User dédé@example.net successfully authenticated 137s 2024-11-14 13:21:23,069 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 137s 2024-11-14 13:21:23,085 INFO Trying to authenticate http://127.0.0.1:8083 again 137s 2024-11-14 13:21:23,099 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 137s 2024-11-14 13:21:23,125 INFO User dédé@example.test successfully authenticated 137s 2024-11-14 13:21:23,137 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 137s PASSED 137s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 137s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 137s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 137s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2024-11-14 13:21:23,246 INFO User test successfully authenticated 137s PASSED 137s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2024-11-14 13:21:23,292 INFO User test successfully authenticated 137s PASSED 137s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2024-11-14 13:21:23,327 INFO User test successfully authenticated 137s 2024-11-14 13:21:23,352 INFO User test successfully authenticated 137s 2024-11-14 13:21:23,380 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 137s 2024-11-14 13:21:23,390 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')) 137s PASSED 137s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2024-11-14 13:21:23,422 INFO User test successfully authenticated 137s PASSED 137s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2024-11-14 13:21:23,463 INFO User test successfully authenticated 137s PASSED 137s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2024-11-14 13:21:23,512 INFO User test successfully authenticated 137s 2024-11-14 13:21:23,544 INFO Sending SLO requests to service http://127.0.0.1:39893 for user test 137s 2024-11-14 13:21:23,552 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 137s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 137s PASSED 137s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2024-11-14 13:21:23,584 INFO User test successfully authenticated 137s PASSED 137s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 137s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 137s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 137s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 137s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 137s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 137s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 137s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 137s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 137s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 137s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 137s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 137s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 137s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 137s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 137s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 137s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 137s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 137s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 138s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 138s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 138s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 138s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2024-11-14 13:21:24,699 INFO User test successfully authenticated 138s 2024-11-14 13:21:24,713 WARNING Service https://www.example.org not allowed. 138s PASSED 138s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success 2024-11-14 13:21:24,741 INFO User test successfully authenticated 138s 2024-11-14 13:21:24,760 INFO Service ticket created for service https://www.example.com by user test. 138s PASSED 138s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt 2024-11-14 13:21:24,786 INFO User test successfully authenticated 138s 2024-11-14 13:21:24,804 INFO Service ticket created for service https://www.example.com by user test. 138s PASSED 138s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn 2024-11-14 13:21:24,831 INFO User test successfully authenticated 138s PASSED 138s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged 2024-11-14 13:21:24,871 INFO User test successfully authenticated 138s PASSED 138s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2024-11-14 13:21:24,910 INFO User test successfully authenticated 138s 2024-11-14 13:21:24,927 WARNING User test seems authenticated but is not found in the database. 138s 2024-11-14 13:21:24,927 INFO Logging out user test. 138s 2024-11-14 13:21:24,930 INFO User test logged out 138s PASSED 138s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 139s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2024-11-14 13:21:24,971 INFO User test successfully authenticated 139s 2024-11-14 13:21:24,986 WARNING Username test not allowed on service restrict_user_fail 139s PASSED 139s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2024-11-14 13:21:25,019 WARNING A login attempt failed 139s 2024-11-14 13:21:25,055 WARNING A login attempt failed 139s 2024-11-14 13:21:25,086 WARNING A login attempt failed 139s PASSED 139s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2024-11-14 13:21:25,127 WARNING Received an invalid login ticket 139s PASSED 139s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2024-11-14 13:21:25,164 WARNING A login attempt failed 139s PASSED 139s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2024-11-14 13:21:25,207 INFO User test successfully authenticated 139s PASSED 139s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2024-11-14 13:21:25,247 INFO User test successfully authenticated 139s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_renew 2024-11-14 13:21:27,431 INFO User test successfully authenticated 141s 2024-11-14 13:21:27,459 INFO User test successfully authenticated 141s 2024-11-14 13:21:27,469 INFO Service ticket created for service https://www.example.com by user test. 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2024-11-14 13:21:27,499 INFO User test successfully authenticated 141s 2024-11-14 13:21:27,527 INFO User test successfully authenticated 141s 2024-11-14 13:21:27,536 INFO Service ticket created for service https://www.example.com by user test. 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2024-11-14 13:21:27,570 INFO User test successfully authenticated 141s 2024-11-14 13:21:27,585 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 141s 2024-11-14 13:21:27,594 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 141s 2024-11-14 13:21:27,610 INFO Service ticket created for service https://filter_success.example.com by user test. 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2024-11-14 13:21:27,636 INFO User test successfully authenticated 141s 2024-11-14 13:21:27,651 WARNING Username test not allowed on service restrict_user_fail 141s 2024-11-14 13:21:27,666 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2024-11-14 13:21:27,698 INFO User test successfully authenticated 141s 2024-11-14 13:21:27,712 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 141s 2024-11-14 13:21:27,728 INFO Service ticket created for service https://field_needed_success.example.com by user test. 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2024-11-14 13:21:27,757 INFO User test successfully authenticated 141s 2024-11-14 13:21:27,771 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2024-11-14 13:21:27,800 INFO User test successfully authenticated 141s 2024-11-14 13:21:27,818 WARNING User test seems authenticated but is not found in the database. 141s 2024-11-14 13:21:27,818 INFO Logging out user test. 141s 2024-11-14 13:21:27,821 INFO User test logged out 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2024-11-14 13:21:27,895 INFO User test successfully authenticated 141s 2024-11-14 13:21:27,913 INFO Service ticket created for service https://www.example.com/é by user test. 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2024-11-14 13:21:27,945 INFO User test successfully authenticated 142s 2024-11-14 13:21:27,964 INFO Service ticket created for service https://www.example.com by user test. 142s PASSED 142s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2024-11-14 13:21:27,997 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,024 WARNING Received a login attempt for an already-active user 142s 2024-11-14 13:21:28,032 INFO Service ticket created for service https://www.example.com by user test. 142s PASSED 142s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2024-11-14 13:21:28,063 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,078 WARNING Service https://www.example.org not allowed. 142s PASSED 142s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2024-11-14 13:21:28,094 WARNING Service https://www.example.net not allowed. 142s PASSED 142s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2024-11-14 13:21:28,117 WARNING Service https://www.example.net not allowed. 142s PASSED 142s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2024-11-14 13:21:28,152 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,162 INFO logout requested 142s 2024-11-14 13:21:28,164 INFO Logging out user test. 142s 2024-11-14 13:21:28,175 INFO User test logged out 142s PASSED 142s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2024-11-14 13:21:28,198 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,224 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,249 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,274 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,299 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,329 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,353 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,380 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,406 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,433 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,443 INFO logout requested 142s 2024-11-14 13:21:28,445 INFO Logging out user test from all sessions. 142s PASSED 142s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2024-11-14 13:21:28,526 INFO User test logged out 142s 2024-11-14 13:21:28,550 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,560 INFO logout requested 142s 2024-11-14 13:21:28,562 INFO Logging out user test. 142s 2024-11-14 13:21:28,572 INFO User test logged out 142s PASSED 142s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2024-11-14 13:21:28,579 INFO logout requested 142s PASSED 142s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2024-11-14 13:21:28,608 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,634 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,644 INFO logout requested 142s 2024-11-14 13:21:28,646 INFO Logging out user test from all sessions. 142s 2024-11-14 13:21:28,665 INFO User test logged out 142s PASSED 142s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2024-11-14 13:21:28,694 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,715 INFO Service ticket created for service http://127.0.0.1:46487 by user test. 142s 2024-11-14 13:21:28,725 INFO Validate: Service ticket ST-cjmFx1YE22TEmG5hLrthyYJO4BfvJrXoXgaJaMhAvGhZRRyVBYqsugrC8OZJi validated, user test authenticated on service http://127.0.0.1:46487 142s 2024-11-14 13:21:28,729 INFO logout requested 142s 2024-11-14 13:21:28,731 INFO Logging out user test. 142s 2024-11-14 13:21:28,740 INFO Sending SLO requests to service http://127.0.0.1:46487 for user test 142s 2024-11-14 13:21:28,748 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 142s 2024-11-14 13:21:28,752 INFO User test logged out 142s 2024-11-14 13:21:28,778 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,798 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 142s 2024-11-14 13:21:28,807 INFO Validate: Service ticket ST-YMChUOfLmvA4YyBJU9CB8ykVP0IP0WQ1ag4q1nq7w7lLMGSkMkPIRZGzEbk0X validated, user test authenticated on service http://127.0.0.1:45678 142s 2024-11-14 13:21:28,811 INFO logout requested 142s 2024-11-14 13:21:28,813 INFO Logging out user test. 142s 2024-11-14 13:21:28,822 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 142s 2024-11-14 13:21:28,830 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 142s 2024-11-14 13:21:28,834 INFO User test logged out 142s 2024-11-14 13:21:28,853 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,872 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 142s 2024-11-14 13:21:28,881 INFO Validate: Service ticket ST-ObUzSjVQwP8Zq5SVCtGDrLqcaMXQYdMI8JeKZg1eI5a3gKjXtNGa6qMbav8G4 validated, user test authenticated on service http://127.0.0.1:45678 142s 2024-11-14 13:21:28,884 INFO logout requested 142s 2024-11-14 13:21:28,887 INFO Logging out user test. 142s 2024-11-14 13:21:28,895 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 142s 2024-11-14 13:21:28,902 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=38629): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 142s 2024-11-14 13:21:28,907 INFO User test logged out 142s PASSED 142s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2024-11-14 13:21:28,932 INFO User test successfully authenticated 142s 2024-11-14 13:21:28,958 INFO User test successfully authenticated 143s 2024-11-14 13:21:28,978 INFO logout requested 143s 2024-11-14 13:21:28,980 INFO Logging out user test. 143s 2024-11-14 13:21:28,990 INFO User test logged out 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2024-11-14 13:21:29,031 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,041 INFO logout requested 143s 2024-11-14 13:21:29,043 INFO Logging out user test. 143s 2024-11-14 13:21:29,053 INFO User test logged out 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2024-11-14 13:21:29,089 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,099 INFO logout requested 143s 2024-11-14 13:21:29,101 INFO Logging out user test. 143s 2024-11-14 13:21:29,110 INFO User test logged out 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2024-11-14 13:21:29,147 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,158 INFO logout requested 143s 2024-11-14 13:21:29,160 INFO Logging out user test. 143s 2024-11-14 13:21:29,172 INFO User test logged out 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2024-11-14 13:21:29,204 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,214 INFO logout requested 143s 2024-11-14 13:21:29,216 INFO Logging out user test. 143s 2024-11-14 13:21:29,225 INFO User test logged out 143s 2024-11-14 13:21:29,241 INFO logout requested 143s PASSED 143s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 143s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 143s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice 2024-11-14 13:21:29,285 WARNING Service https://www.example.org not allowed. 143s PASSED 143s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings PASSED 143s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 143s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2024-11-14 13:21:29,329 INFO User test successfully authenticated 143s PASSED 143s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 143s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2024-11-14 13:21:29,379 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,400 INFO Service ticket created for service https://www.example.com by user test. 143s 2024-11-14 13:21:29,406 WARNING Validate: service or ticket missing 143s 2024-11-14 13:21:29,408 WARNING Validate: service or ticket missing 143s PASSED 143s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2024-11-14 13:21:29,431 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,441 INFO Service ticket created for service https://www.example.com by user test. 143s 2024-11-14 13:21:29,451 INFO Validate: Service ticket ST-UMFgQk7AwuuLAxudQAJSPV7274rYuTUNDAFmaTX45gblfg05kS0wqsYal45M2 validated, user test authenticated on service https://www.example.com 143s 2024-11-14 13:21:29,469 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,480 INFO Service ticket created for service https://www.example.com by user test. 143s 2024-11-14 13:21:29,489 INFO Validate: Service ticket ST-oAZYCT6d7enZYm1ETlftu7T1eDLNW9G5QDruXolH1xZjZv0nTzQcg7gAaHoch validated, user test authenticated on service https://www.example.com 143s 2024-11-14 13:21:29,501 INFO Service ticket created for service https://www.example.com by user test. 143s 2024-11-14 13:21:29,506 WARNING Validate: Service ticket ST-iq9EEJoHp7REK3lIagvUyZAw1FCZvZPMqrbuLjyYODONgepQUwewmq44JG5pg not found or already validated, auth to https://www.example.com failed 143s PASSED 143s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2024-11-14 13:21:29,529 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,548 INFO Service ticket created for service https://user_field.example.com by user test. 143s 2024-11-14 13:21:29,558 INFO Validate: Service ticket ST-65NjBqhEKVjdtcXxbOTgjt7xJhrFagekuc6RWH0k5gF77YE2luyJGopUgpMzt validated, user test authenticated on service https://user_field.example.com 143s 2024-11-14 13:21:29,576 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,596 INFO Service ticket created for service https://user_field_alt.example.com by user test. 143s 2024-11-14 13:21:29,605 INFO Validate: Service ticket ST-YK9WAU7IiS3XtvQOtRCEky8r0Fo1S9WVfMYOKgrluQtbzPsCL89B7rBlcrX46 validated, user test authenticated on service https://user_field_alt.example.com 143s PASSED 143s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2024-11-14 13:21:29,632 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,651 INFO Service ticket created for service https://www.example.com by user test. 143s 2024-11-14 13:21:29,658 WARNING Validate: Service ticket ST-Te0bgLWpfW5xghK7rMtOcygPhCGP6sdudL5oGeGPAwbAyBcHOikAKduTWQYeh not found or already validated, auth to https://www.example.org failed 143s PASSED 143s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2024-11-14 13:21:29,682 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,701 INFO Service ticket created for service https://www.example.com by user test. 143s PASSED2024-11-14 13:21:29,708 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 143s 143s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2024-11-14 13:21:29,731 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,750 INFO Service ticket created for service https://www.example.com by user test. 143s 2024-11-14 13:21:29,759 INFO Validate: Service ticket ST-mmckOTzQKyGV6AtgjwCZmIj4oSGm3LEabl3oxpfeFd0Bc0QCLMEvBDhszC4QZ validated, user test authenticated on service https://www.example.com 143s PASSED 143s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2024-11-14 13:21:29,788 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,807 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 143s 2024-11-14 13:21:29,813 WARNING ValidateService: missing ticket or service 143s 2024-11-14 13:21:29,816 WARNING ValidateService: missing ticket or service 143s PASSED 143s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2024-11-14 13:21:29,846 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,865 INFO Service ticket created for service http://127.0.0.1:34119 by user test. 143s 2024-11-14 13:21:29,882 INFO ValidateService: ticket ST-z72ZkaN17VFKBDsLuxlHevZLakDesB9Xg94aqizNcvvX7xawFPHbe89CKJpdN validated for user test on service http://127.0.0.1:34119. Proxy Granting Ticket transmited to http://127.0.0.1:34119. 143s 2024-11-14 13:21:29,897 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 143s 2024-11-14 13:21:29,909 INFO ValidateService: ticket PT-Fmoev69AufVLnrxJgcHvJoyL78KarWBxCAzdRrK2TJxkY1oUFhIWT2QJvhakG validated for user test on service http://127.0.0.1:45678. 143s 2024-11-14 13:21:29,930 INFO User test successfully authenticated 143s 2024-11-14 13:21:29,949 INFO Service ticket created for service http://127.0.0.1:42859 by user test. 143s 2024-11-14 13:21:29,965 INFO ValidateService: ticket ST-JRfS8bK2BMnuhFNDE727eilEwdyd0XVCTm2mkCOAUiQsJGjut9k9qPgHjDiCe validated for user test on service http://127.0.0.1:42859. Proxy Granting Ticket transmited to http://127.0.0.1:42859. 144s 2024-11-14 13:21:29,977 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 144s 2024-11-14 13:21:29,981 WARNING ValidateService: validation error: INVALID_TICKET PT-zKEfC848tTbkdvh1BPcu2vp2Ttvh8JRoB9SmGTNYv65Vd4CJaqewf1Dw7mbJ6 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2024-11-14 13:21:30,008 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,029 INFO Service ticket created for service https://replace_attribute.example.com by user test. 144s 2024-11-14 13:21:30,039 INFO ValidateService: ticket ST-GNJQKSeAeGOJIZc1Le88AqDgw69OdUWAtjETP0INO21RrKyjJVHM54rQDE7EU validated for user test on service https://replace_attribute.example.com. 144s 2024-11-14 13:21:30,060 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,079 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2024-11-14 13:21:30,089 INFO ValidateService: ticket ST-vhCdxIgyKJgO5doZKWjtV3Gi8HMccneHDnzRRwxVSCvzhptXlMqsraezcmQzk validated for user test on service https://replace_attribute_list.example.com. 144s 2024-11-14 13:21:30,258 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,276 INFO Service ticket created for service http://127.0.0.1:44625 by user test. 144s 2024-11-14 13:21:30,293 INFO ValidateService: ticket ST-plczV31AgHSrnOCdLnG8nrnuHgS3P41IvV86XTElfXynlLvN3bbfQpuDtLj5b validated for user test on service http://127.0.0.1:44625. Proxy Granting Ticket transmited to http://127.0.0.1:44625. 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2024-11-14 13:21:30,320 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,338 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 13:21:30,350 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 144s 2024-11-14 13:21:30,367 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,386 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 13:21:30,397 WARNING Service https://www.example.org not allowed. 144s 2024-11-14 13:21:30,397 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2024-11-14 13:21:30,426 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,445 INFO Service ticket created for service https://127.0.0.1:46765 by user test. 144s 2024-11-14 13:21:30,461 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=46765): Max retries exceeded with url: /?pgtId=PGT-yipvxKqcd2IwK7kHjxAo74u3HmO6joQulykOK78eYLFmQMKnbvndMphfW0A1&pgtIou=PGTIOU-FBWXvBRMmqBzuksNV4flsxVVCeG6e7B6coBDXm9M6j706yHZ2zf67NXqG (Caused by SSLError(SSLError(1, '[SSL] record layer failure (_ssl.c:1000)'))) 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2024-11-14 13:21:30,487 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,497 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 13:21:30,507 INFO ValidateService: ticket ST-WrFtZAhdgv5nfssaRX7cyDRw5s3K7fmfmaBuHV1R5rpLonoFkE3ZMVhXk1Udo validated for user test on service http://127.0.0.1:45678. 144s 2024-11-14 13:21:30,525 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,535 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 13:21:30,546 INFO ValidateService: ticket ST-aLDTU3j1xUN1wkJUTamhuiL8ZW28drxbkWN7uXmB76ctC4bdVUThatuAfAfVc validated for user test on service http://127.0.0.1:45678. 144s 2024-11-14 13:21:30,557 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 13:21:30,563 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2024-11-14 13:21:30,589 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,610 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 13:21:30,620 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2024-11-14 13:21:30,649 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,672 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 13:21:30,678 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2024-11-14 13:21:30,706 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,724 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 13:21:30,732 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2024-11-14 13:21:30,758 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,777 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 13:21:30,787 INFO ValidateService: ticket ST-6z3BBHjBnaAjdcvrZsoHgpRoXzBaWtnQ2PQQ1Kq8i341tAlWfVjfzIxDVrDiM validated for user test on service http://127.0.0.1:45678. 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2024-11-14 13:21:30,816 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,835 INFO Service ticket created for service https://one_attribute.example.com by user test. 144s 2024-11-14 13:21:30,846 INFO ValidateService: ticket ST-MrbQYcUNfdMd0WibgrfsnYM4YUqoaiqISOw85HLAk6Iwja7Br80TEm9fAPDYW validated for user test on service https://one_attribute.example.com. 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2024-11-14 13:21:30,873 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,893 INFO Service ticket created for service http://127.0.0.1:45585 by user test. 144s 2024-11-14 13:21:30,914 INFO ValidateService: ticket ST-KNm5a02iim1CWdqPfvqxttBIteeXy19K5sicpz6YzNVI6lo3Ilt46Pe419eNq validated for user test on service http://127.0.0.1:45585. Proxy Granting Ticket transmited to http://127.0.0.1:45585. 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2024-11-14 13:21:30,941 INFO User test successfully authenticated 144s 2024-11-14 13:21:30,960 INFO Service ticket created for service https://user_field.example.com by user test. 145s 2024-11-14 13:21:30,969 INFO ValidateService: ticket ST-Qnt7lIrkV4IuxDdHZ7IvDUxOLDPnkwVR7BzqIPO9NZjRrWvwxmI69Zy0tZyou validated for user test on service https://user_field.example.com. 145s 2024-11-14 13:21:30,988 INFO User test successfully authenticated 145s 2024-11-14 13:21:31,007 INFO Service ticket created for service https://user_field_alt.example.com by user test. 145s PASSED 145s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2024-11-14 13:21:31,017 INFO ValidateService: ticket ST-N2xLzIUhWJOuDzojKcoMlmVWAJnN8Fj6rJjVmNeCKH1EjfHFjP9mQWFYEHoal validated for user test on service https://user_field_alt.example.com. 145s 2024-11-14 13:21:31,049 INFO User test successfully authenticated 145s 2024-11-14 13:21:31,069 INFO Service ticket created for service http://127.0.0.1:45429 by user test. 145s 2024-11-14 13:21:31,086 INFO ValidateService: ticket ST-mJMxzTofJgPhFvQeBI63zy8VukiAq9O53I4g8ia2LKxadyYqcw9cy7g6wt4uH validated for user test on service http://127.0.0.1:45429. Proxy Granting Ticket transmited to http://127.0.0.1:45429. 145s 2024-11-14 13:21:31,089 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 145s 2024-11-14 13:21:31,092 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 145s PASSED 145s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2024-11-14 13:21:31,123 INFO User test successfully authenticated 145s 2024-11-14 13:21:31,142 INFO Service ticket created for service http://127.0.0.1:33843 by user test. 145s 2024-11-14 13:21:31,160 INFO ValidateService: ticket ST-v0fCHXV0WM9blsTW5QNptYo1DyfDndrhdQtphvWqP3HqUgyEHDMIzh8AGwxit validated for user test on service http://127.0.0.1:33843. Proxy Granting Ticket transmited to http://127.0.0.1:33843. 145s 2024-11-14 13:21:31,168 WARNING Username test not allowed on service restrict_user_fail 145s 2024-11-14 13:21:31,168 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 145s 2024-11-14 13:21:31,175 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 145s 2024-11-14 13:21:31,176 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 145s 2024-11-14 13:21:31,183 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 145s 2024-11-14 13:21:31,183 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 145s PASSED 145s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2024-11-14 13:21:31,213 INFO User test successfully authenticated 145s 2024-11-14 13:21:31,232 INFO Service ticket created for service http://127.0.0.1:42159 by user test. 145s 2024-11-14 13:21:31,249 INFO ValidateService: ticket ST-lCq6TrLCfXnOW7WegadMVoEeRvtgYDfEEu5EXzwyxbwJHoEzffJs7NnEdKISe validated for user test on service http://127.0.0.1:42159. Proxy Granting Ticket transmited to http://127.0.0.1:42159. 145s 2024-11-14 13:21:31,254 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 145s PASSED 145s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2024-11-14 13:21:31,284 INFO User test successfully authenticated 145s 2024-11-14 13:21:31,303 INFO Service ticket created for service http://127.0.0.1:37953 by user test. 145s 2024-11-14 13:21:31,319 INFO ValidateService: ticket ST-Vminiung4XnIf99tBkI1L4NcmcpoLxO4eyjTmgkHlEh4cKAN7TXH14TV7jQ3q validated for user test on service http://127.0.0.1:37953. Proxy Granting Ticket transmited to http://127.0.0.1:37953. 145s 2024-11-14 13:21:31,325 WARNING Service https://www.example.org not allowed. 145s 2024-11-14 13:21:31,325 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 145s 2024-11-14 13:21:31,330 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:37953 does not allow proxy tickets 145s PASSED 145s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2024-11-14 13:21:31,361 INFO User test successfully authenticated 145s 2024-11-14 13:21:31,379 INFO Service ticket created for service http://127.0.0.1:38963 by user test. 145s 2024-11-14 13:21:31,394 INFO ValidateService: ticket ST-xSL3i0CkRixXDF8H6CwLNdpFgMiQIDs3OTtAJGIxNZHKRRipR3IlAWLfoLWnS validated for user test on service http://127.0.0.1:38963. Proxy Granting Ticket transmited to http://127.0.0.1:38963. 145s 2024-11-14 13:21:31,409 INFO Proxy ticket created for user test on service https://www.example.com. 145s 2024-11-14 13:21:31,418 INFO ValidateService: ticket PT-qD5LSKDV2hRJL5pebSdd7sPPoGHk4gmW8f6JesVF88CLKIf4iRaJjSIuOlJmL validated for user test on service https://www.example.com. 145s PASSED 145s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2024-11-14 13:21:31,448 INFO User test successfully authenticated 145s 2024-11-14 13:21:31,466 INFO Service ticket created for service https://www.example.com by user test. 145s 2024-11-14 13:21:31,475 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 145s PASSED 145s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2024-11-14 13:21:31,490 WARNING SamlValidate: validation error: AuthnFailed ticket ST-mWdAOL70sTWQq67120wYB5orvgCqRgwSnwYfV17qpL5r8jKdp6cdGaMlDG31I not found 145s 2024-11-14 13:21:31,496 WARNING SamlValidate: validation error: AuthnFailed ticket PT-uVd1KalrXW4X15yrz2l05EnXYznmD0L1LetXLDixdcMiJNHYJ7Npv6SNnY1uZ not found 145s PASSED 146s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix 2024-11-14 13:21:31,509 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 146s PASSED2024-11-14 13:21:31,521 WARNING SamlValidate: validation error: VersionMismatch 146s 2024-11-14 13:21:31,548 INFO User test successfully authenticated 146s 2024-11-14 13:21:31,568 INFO Service ticket created for service https://www.example.com by user test. 146s 2024-11-14 13:21:31,589 INFO User test successfully authenticated 146s 2024-11-14 13:21:31,612 INFO Service ticket created for service http://127.0.0.1:37305 by user test. 146s 2024-11-14 13:21:31,629 INFO ValidateService: ticket ST-CEG79sXyPZafnUJBeDIxfx5RRDTZvttUHavdDM8XzE4zyFCEEumczNqXQn5wv validated for user test on service http://127.0.0.1:37305. Proxy Granting Ticket transmited to http://127.0.0.1:37305. 146s 2024-11-14 13:21:31,641 INFO Proxy ticket created for user test on service https://www.example.com. 146s 2024-11-14 13:21:31,649 INFO SamlValidate: ticket ST-SzHBpwWWEKuq3XRMXJQtPw6QLfPConojBQmqfAfKB10Q1PxbYVqHqpCelzgWb validated for user test on service https://www.example.com. 146s 2024-11-14 13:21:31,658 INFO SamlValidate: ticket PT-VVXpyZXpviIjPpBpT1ieylXLLxyOnZETLqHjvc81vwl8Ki4htvi60NgsVmO3L validated for user test on service https://www.example.com. 146s 2024-11-14 13:21:31,687 INFO User test successfully authenticated 146s 2024-11-14 13:21:31,705 INFO Service ticket created for service https://field_needed_success.example.com by user test. 146s 2024-11-14 13:21:31,716 INFO SamlValidate: ticket ST-E94RccclFsphiUGl6W9HlyAU7KwNbzIG7zWWC7EtDVMmgVc9WbdAcnKA4dSMD validated for user test on service https://field_needed_success.example.com. 146s 2024-11-14 13:21:31,734 INFO User test successfully authenticated 146s 2024-11-14 13:21:31,754 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 146s 2024-11-14 13:21:31,764 INFO SamlValidate: ticket ST-QuOhBk24C6higdivRwKlMgwOQ7Un5wad1yft6jf9DllTXbhbEJBd3VYqgE1ZT validated for user test on service https://field_needed_success_alt.example.com. 146s Destroying test database for alias 'default'... 146s 146s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml PASSED 146s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok PASSED 146s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field PASSED 146s 146s =============================== warnings summary =============================== 146s cas_server/utils.py:40 146s /tmp/autopkgtest.bmd9gY/autopkgtest_tmp/cas_server/utils.py:40: DeprecationWarning: 'crypt' is deprecated and slated for removal in Python 3.13 146s import crypt 146s 146s cas_server/tests/utils.py:20 146s /tmp/autopkgtest.bmd9gY/autopkgtest_tmp/cas_server/tests/utils.py:20: DeprecationWarning: 'cgi' is deprecated and slated for removal in Python 3.13 146s import cgi 146s 146s cas_server/tests/test_federate.py: 8 warnings 146s cas_server/tests/test_view.py: 3 warnings 146s /tmp/autopkgtest.bmd9gY/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). 146s datetime.utcnow() + timedelta(seconds=max_age), 146s 146s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 146s /usr/lib/python3.12/unittest/case.py:690: DeprecationWarning: It is deprecated to return a value that is not None from a test case (>) 146s return self.run(*args, **kwds) 146s 146s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 146s ====================== 128 passed, 14 warnings in 14.86s ======================= 146s 146s autopkgtest [13:21:32]: test run-test-suite.sh: -----------------------] 147s autopkgtest [13:21:33]: test run-test-suite.sh: - - - - - - - - - - results - - - - - - - - - - 147s run-test-suite.sh PASS 147s autopkgtest [13:21:33]: @@@@@@@@@@@@@@@@@@@@ summary 147s run-test-suite.sh PASS 159s nova [W] Skipping flock in bos03-arm64 159s Creating nova instance adt-plucky-arm64-django-cas-server-20241114-131906-juju-7f2275-prod-proposed-migration-environment-20-b6177cb5-eb48-400b-8a04-cf3cdbed7d07 from image adt/ubuntu-plucky-arm64-server-20241114.img (UUID 4472f5f7-859f-4441-9e8e-9550fb35f210)...