0s autopkgtest [21:25:57]: starting date and time: 2024-03-22 21:25:57+0000 0s autopkgtest [21:25:57]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [21:25:57]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.zo1s1wb9/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --setup-commands /home/ubuntu/autopkgtest/setup-commands/setup-testbed --apt-pocket=proposed=src:sphinx --apt-upgrade django-cas-server --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=sphinx/7.2.6-6 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-arm64-14.secgroup --name adt-noble-arm64-django-cas-server-20240322-212557-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --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,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 222s autopkgtest [21:29:39]: testbed dpkg architecture: arm64 222s autopkgtest [21:29:39]: testbed apt version: 2.7.12 222s autopkgtest [21:29:39]: @@@@@@@@@@@@@@@@@@@@ test bed setup 224s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 225s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [498 kB] 226s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [4041 kB] 228s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [58.8 kB] 228s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 228s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 Packages [680 kB] 228s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main arm64 c-n-f Metadata [3144 B] 228s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 Packages [33.7 kB] 228s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted arm64 c-n-f Metadata [116 B] 228s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 Packages [4365 kB] 229s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe arm64 c-n-f Metadata [8528 B] 229s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 Packages [71.0 kB] 229s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse arm64 c-n-f Metadata [116 B] 244s Fetched 9884 kB in 9s (1136 kB/s) 245s Reading package lists... 254s Reading package lists... 255s Building dependency tree... 255s Reading state information... 257s Calculating upgrade... 259s The following packages will be upgraded: 259s fonts-ubuntu-console 259s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 259s Need to get 18.7 kB of archives. 259s After this operation, 0 B of additional disk space will be used. 259s Get:1 http://ftpmaster.internal/ubuntu noble/main arm64 fonts-ubuntu-console all 0.869+git20240321-0ubuntu1 [18.7 kB] 261s Fetched 18.7 kB in 0s (51.6 kB/s) 263s (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 ... 75911 files and directories currently installed.) 263s Preparing to unpack .../fonts-ubuntu-console_0.869+git20240321-0ubuntu1_all.deb ... 263s Unpacking fonts-ubuntu-console (0.869+git20240321-0ubuntu1) over (0.869-0ubuntu1) ... 263s Setting up fonts-ubuntu-console (0.869+git20240321-0ubuntu1) ... 264s Reading package lists... 265s Building dependency tree... 265s Reading state information... 268s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 272s sh: Attempting to set up Debian/Ubuntu apt sources automatically 272s sh: Distribution appears to be Ubuntu 276s Reading package lists... 277s Building dependency tree... 277s Reading state information... 279s eatmydata is already the newest version (131-1). 279s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 279s Reading package lists... 280s Building dependency tree... 280s Reading state information... 283s dbus is already the newest version (1.14.10-4ubuntu1). 283s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 283s Reading package lists... 284s Building dependency tree... 284s Reading state information... 286s rng-tools-debian is already the newest version (2.4). 286s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 286s Reading package lists... 287s Building dependency tree... 287s Reading state information... 289s The following packages will be REMOVED: 289s cloud-init* python3-configobj* python3-debconf* 291s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 291s After this operation, 3256 kB disk space will be freed. 291s (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 ... 75911 files and directories currently installed.) 291s Removing cloud-init (24.1.2-0ubuntu1) ... 295s Removing python3-configobj (5.0.8-3) ... 295s Removing python3-debconf (1.5.86) ... 296s Processing triggers for man-db (2.12.0-3) ... 298s (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 ... 75522 files and directories currently installed.) 298s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 302s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 302s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 302s invoke-rc.d: policy-rc.d denied execution of try-restart. 302s Reading package lists... 303s Building dependency tree... 303s Reading state information... 306s linux-generic is already the newest version (6.8.0-11.11+1). 306s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 307s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 307s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 307s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 319s Reading package lists... 319s Reading package lists... 320s Building dependency tree... 320s Reading state information... 322s Calculating upgrade... 324s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 324s Reading package lists... 325s Building dependency tree... 325s Reading state information... 327s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 327s autopkgtest [21:31:24]: rebooting testbed after setup commands that affected boot 493s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 503s autopkgtest [21:34:20]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP PREEMPT_DYNAMIC Wed Feb 14 02:53:31 UTC 2024 508s autopkgtest [21:34:25]: @@@@@@@@@@@@@@@@@@@@ apt-source django-cas-server 513s Get:1 http://ftpmaster.internal/ubuntu noble/universe django-cas-server 2.0.0-2 (dsc) [2588 B] 513s Get:2 http://ftpmaster.internal/ubuntu noble/universe django-cas-server 2.0.0-2 (tar) [169 kB] 513s Get:3 http://ftpmaster.internal/ubuntu noble/universe django-cas-server 2.0.0-2 (diff) [6480 B] 515s gpgv: Signature made Sat Dec 3 08:42:02 2022 UTC 515s gpgv: using RSA key B70DFC6F134FECFC011E62AA83016014251D1DB0 515s gpgv: Can't check signature: No public key 515s dpkg-source: warning: cannot verify inline signature for ./django-cas-server_2.0.0-2.dsc: no acceptable signature found 515s autopkgtest [21:34:32]: testing package django-cas-server version 2.0.0-2 515s autopkgtest [21:34:32]: build not needed 516s autopkgtest [21:34:33]: test run-test-suite.sh: preparing testbed 520s Reading package lists... 521s Building dependency tree... 521s Reading state information... 522s Starting pkgProblemResolver with broken count: 0 522s Starting 2 pkgProblemResolver with broken count: 0 522s Done 524s The following additional packages will be installed: 524s fonts-font-awesome fonts-lato libjs-jquery libjs-sphinxdoc libjs-underscore 524s libxslt1.1 python3-all python3-asgiref python3-django 524s python3-django-cas-server python3-django-cas-server-doc python3-iniconfig 524s python3-lxml python3-mock python3-packaging python3-pluggy python3-pytest 524s python3-pytest-django python3-requests-futures python3-sqlparse 524s sphinx-rtd-theme-common 524s Suggested packages: 524s bpython3 geoip-database-contrib gettext ipython3 libgdal20 524s libsqlite3-mod-spatialite python-django-doc python3-bcrypt python3-flup 524s python3-memcache python3-mysqldb python3-pil python3-psycopg2 524s python3-selenium python3-sqlite python-lxml-doc python-mock-doc 524s python-sqlparse-doc 524s Recommended packages: 524s javascript-common python3-bs4 python3-cssselect python3-html5lib 524s The following NEW packages will be installed: 524s autopkgtest-satdep fonts-font-awesome fonts-lato libjs-jquery 524s libjs-sphinxdoc libjs-underscore libxslt1.1 python3-all python3-asgiref 524s python3-django python3-django-cas-server python3-django-cas-server-doc 524s python3-iniconfig python3-lxml python3-mock python3-packaging python3-pluggy 524s python3-pytest python3-pytest-django python3-requests-futures 524s python3-sqlparse sphinx-rtd-theme-common 525s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 525s Need to get 12.0 MB/12.0 MB of archives. 525s After this operation, 55.5 MB of additional disk space will be used. 525s Get:1 /tmp/autopkgtest.yJGS4D/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [748 B] 525s Get:2 http://ftpmaster.internal/ubuntu noble/main arm64 fonts-lato all 2.015-1 [2781 kB] 527s Get:3 http://ftpmaster.internal/ubuntu noble/main arm64 fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 527s Get:4 http://ftpmaster.internal/ubuntu noble/main arm64 libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 528s Get:5 http://ftpmaster.internal/ubuntu noble/main arm64 libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 528s Get:6 http://ftpmaster.internal/ubuntu noble/main arm64 libjs-sphinxdoc all 7.2.6-4 [149 kB] 528s Get:7 http://ftpmaster.internal/ubuntu noble/main arm64 libxslt1.1 arm64 1.1.35-1 [161 kB] 528s Get:8 http://ftpmaster.internal/ubuntu noble/main arm64 python3-all arm64 3.12.1-0ubuntu2 [906 B] 528s Get:9 http://ftpmaster.internal/ubuntu noble/main arm64 python3-asgiref all 3.7.2-1 [24.8 kB] 528s Get:10 http://ftpmaster.internal/ubuntu noble/main arm64 python3-sqlparse all 0.4.4-1 [31.5 kB] 528s Get:11 http://ftpmaster.internal/ubuntu noble/main arm64 python3-django all 3:4.2.9-1 [4102 kB] 529s Get:12 http://ftpmaster.internal/ubuntu noble/main arm64 python3-lxml arm64 5.1.0-1 [2042 kB] 529s Get:13 http://ftpmaster.internal/ubuntu noble/universe arm64 python3-requests-futures all 1.0.1-1 [11.7 kB] 529s Get:14 http://ftpmaster.internal/ubuntu noble/universe arm64 python3-django-cas-server all 2.0.0-2 [111 kB] 529s Get:15 http://ftpmaster.internal/ubuntu noble/main arm64 sphinx-rtd-theme-common all 2.0.0+dfsg-1 [1012 kB] 529s Get:16 http://ftpmaster.internal/ubuntu noble/universe arm64 python3-django-cas-server-doc all 2.0.0-2 [159 kB] 529s Get:17 http://ftpmaster.internal/ubuntu noble/universe arm64 python3-iniconfig all 1.1.1-2 [6024 B] 529s Get:18 http://ftpmaster.internal/ubuntu noble/main arm64 python3-packaging all 23.2-1 [40.6 kB] 529s Get:19 http://ftpmaster.internal/ubuntu noble/universe arm64 python3-pluggy all 1.4.0-1 [20.4 kB] 529s Get:20 http://ftpmaster.internal/ubuntu noble/universe arm64 python3-pytest all 7.4.4-1 [305 kB] 529s Get:21 http://ftpmaster.internal/ubuntu noble/universe arm64 python3-pytest-django all 4.5.2-3 [18.5 kB] 529s Get:22 http://ftpmaster.internal/ubuntu noble/universe arm64 python3-mock all 5.1.0-1 [64.1 kB] 531s Fetched 12.0 MB in 5s (2340 kB/s) 531s Selecting previously unselected package fonts-lato. 533s (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 ... 75467 files and directories currently installed.) 533s Preparing to unpack .../00-fonts-lato_2.015-1_all.deb ... 533s Unpacking fonts-lato (2.015-1) ... 534s Selecting previously unselected package fonts-font-awesome. 534s Preparing to unpack .../01-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 534s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 534s Selecting previously unselected package libjs-jquery. 534s Preparing to unpack .../02-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 534s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 534s Selecting previously unselected package libjs-underscore. 534s Preparing to unpack .../03-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 534s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 534s Selecting previously unselected package libjs-sphinxdoc. 535s Preparing to unpack .../04-libjs-sphinxdoc_7.2.6-4_all.deb ... 535s Unpacking libjs-sphinxdoc (7.2.6-4) ... 535s Selecting previously unselected package libxslt1.1:arm64. 535s Preparing to unpack .../05-libxslt1.1_1.1.35-1_arm64.deb ... 535s Unpacking libxslt1.1:arm64 (1.1.35-1) ... 535s Selecting previously unselected package python3-all. 535s Preparing to unpack .../06-python3-all_3.12.1-0ubuntu2_arm64.deb ... 535s Unpacking python3-all (3.12.1-0ubuntu2) ... 535s Selecting previously unselected package python3-asgiref. 535s Preparing to unpack .../07-python3-asgiref_3.7.2-1_all.deb ... 535s Unpacking python3-asgiref (3.7.2-1) ... 535s Selecting previously unselected package python3-sqlparse. 535s Preparing to unpack .../08-python3-sqlparse_0.4.4-1_all.deb ... 535s Unpacking python3-sqlparse (0.4.4-1) ... 535s Selecting previously unselected package python3-django. 535s Preparing to unpack .../09-python3-django_3%3a4.2.9-1_all.deb ... 535s Unpacking python3-django (3:4.2.9-1) ... 540s Selecting previously unselected package python3-lxml:arm64. 540s Preparing to unpack .../10-python3-lxml_5.1.0-1_arm64.deb ... 540s Unpacking python3-lxml:arm64 (5.1.0-1) ... 541s Selecting previously unselected package python3-requests-futures. 541s Preparing to unpack .../11-python3-requests-futures_1.0.1-1_all.deb ... 541s Unpacking python3-requests-futures (1.0.1-1) ... 541s Selecting previously unselected package python3-django-cas-server. 541s Preparing to unpack .../12-python3-django-cas-server_2.0.0-2_all.deb ... 541s Unpacking python3-django-cas-server (2.0.0-2) ... 541s Selecting previously unselected package sphinx-rtd-theme-common. 541s Preparing to unpack .../13-sphinx-rtd-theme-common_2.0.0+dfsg-1_all.deb ... 541s Unpacking sphinx-rtd-theme-common (2.0.0+dfsg-1) ... 541s Selecting previously unselected package python3-django-cas-server-doc. 541s Preparing to unpack .../14-python3-django-cas-server-doc_2.0.0-2_all.deb ... 541s Unpacking python3-django-cas-server-doc (2.0.0-2) ... 541s Selecting previously unselected package python3-iniconfig. 541s Preparing to unpack .../15-python3-iniconfig_1.1.1-2_all.deb ... 541s Unpacking python3-iniconfig (1.1.1-2) ... 542s Selecting previously unselected package python3-packaging. 542s Preparing to unpack .../16-python3-packaging_23.2-1_all.deb ... 542s Unpacking python3-packaging (23.2-1) ... 542s Selecting previously unselected package python3-pluggy. 542s Preparing to unpack .../17-python3-pluggy_1.4.0-1_all.deb ... 542s Unpacking python3-pluggy (1.4.0-1) ... 542s Selecting previously unselected package python3-pytest. 542s Preparing to unpack .../18-python3-pytest_7.4.4-1_all.deb ... 542s Unpacking python3-pytest (7.4.4-1) ... 542s Selecting previously unselected package python3-pytest-django. 542s Preparing to unpack .../19-python3-pytest-django_4.5.2-3_all.deb ... 542s Unpacking python3-pytest-django (4.5.2-3) ... 542s Selecting previously unselected package python3-mock. 542s Preparing to unpack .../20-python3-mock_5.1.0-1_all.deb ... 542s Unpacking python3-mock (5.1.0-1) ... 542s Selecting previously unselected package autopkgtest-satdep. 542s Preparing to unpack .../21-1-autopkgtest-satdep.deb ... 542s Unpacking autopkgtest-satdep (0) ... 542s Setting up python3-iniconfig (1.1.1-2) ... 543s Setting up fonts-lato (2.015-1) ... 543s Setting up python3-all (3.12.1-0ubuntu2) ... 543s Setting up python3-mock (5.1.0-1) ... 545s Setting up python3-packaging (23.2-1) ... 545s Setting up python3-sqlparse (0.4.4-1) ... 546s Setting up python3-pluggy (1.4.0-1) ... 547s Setting up libxslt1.1:arm64 (1.1.35-1) ... 547s Setting up python3-requests-futures (1.0.1-1) ... 548s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 548s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 548s Setting up sphinx-rtd-theme-common (2.0.0+dfsg-1) ... 548s Setting up python3-asgiref (3.7.2-1) ... 549s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 549s Setting up python3-django (3:4.2.9-1) ... 556s Setting up python3-pytest (7.4.4-1) ... 557s Setting up python3-pytest-django (4.5.2-3) ... 558s Setting up libjs-sphinxdoc (7.2.6-4) ... 558s Setting up python3-lxml:arm64 (5.1.0-1) ... 559s Setting up python3-django-cas-server (2.0.0-2) ... 560s Setting up python3-django-cas-server-doc (2.0.0-2) ... 560s Setting up autopkgtest-satdep (0) ... 560s Processing triggers for man-db (2.12.0-3) ... 562s Processing triggers for libc-bin (2.39-0ubuntu2) ... 575s (Reading database ... 82285 files and directories currently installed.) 575s Removing autopkgtest-satdep (0) ... 576s autopkgtest [21:35:33]: test run-test-suite.sh: [----------------------- 577s 577s *************************** 577s *** Testing with python3.12 577s *************************** 577s 577s Content of current working folder: 577s 577s total 20 577s drwxr-xr-x 3 ubuntu ubuntu 4096 Mar 22 21:35 . 577s drwxrwxrwt 5 root root 4096 Mar 22 21:35 .. 577s drwxrwxr-x 9 ubuntu ubuntu 4096 Oct 17 2022 cas_server 577s -rw-rw-r-- 1 ubuntu ubuntu 140 Oct 17 2022 pytest.ini 577s -rw-rw-r-- 1 ubuntu ubuntu 152 Oct 17 2022 setup.cfg 577s Running tests... 577s 581s ============================= test session starts ============================== 581s platform linux -- Python 3.12.2, pytest-7.4.4, pluggy-1.4.0 -- /usr/bin/python3.12 581s cachedir: .pytest_cache 581s django: settings: cas_server.tests.settings (from ini) 581s rootdir: /tmp/autopkgtest.yJGS4D/autopkgtest_tmp 581s configfile: pytest.ini 581s testpaths: cas_server/tests/ 581s plugins: django-4.5.2 581s collecting ... collected 128 items 581s 581s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 585s 2024-03-22 21:35:42,251 WARNING Identity provider suffix exemple.fr not found 585s 2024-03-22 21:35:42,459 INFO Got an invalid ticket ST-hm4qGu6MntEBiGvocM5dNNKylzU62f53BCJgjYS9qrZ9rEMGkAf2QrlrHQXDV from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 585s 2024-03-22 21:35:42,478 INFO Got an invalid ticket ST-XdgitQFaaEYxqPTMa6rXHYLF9cJ6t9yHP5857rVdnkM17q7m3JGxUfE3zM5HT from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 585s PASSED 585s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2024-03-22 21:35:42,687 WARNING CAS_FEDERATE is False, set it to True to use federation 585s 2024-03-22 21:35:42,692 WARNING CAS_FEDERATE is False, set it to True to use federation 585s PASSED 585s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2024-03-22 21:35:42,781 INFO Trying to authenticate http://127.0.0.1:8080 again 585s 2024-03-22 21:35:42,808 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 585s 2024-03-22 21:35:42,884 INFO User dédé@example.com successfully authenticated 585s 2024-03-22 21:35:42,945 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 586s 2024-03-22 21:35:43,009 INFO Trying to authenticate http://127.0.0.1:8081 again 586s 2024-03-22 21:35:43,038 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 586s 2024-03-22 21:35:43,094 INFO User dédé@example.org successfully authenticated 586s 2024-03-22 21:35:43,146 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 586s 2024-03-22 21:35:43,207 INFO Trying to authenticate http://127.0.0.1:8082 again 586s 2024-03-22 21:35:43,232 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 586s 2024-03-22 21:35:43,296 INFO User dédé@example.net successfully authenticated 586s 2024-03-22 21:35:43,367 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 586s 2024-03-22 21:35:43,441 INFO Trying to authenticate http://127.0.0.1:8083 again 586s 2024-03-22 21:35:43,474 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 586s 2024-03-22 21:35:43,537 INFO User dédé@example.test successfully authenticated 586s 2024-03-22 21:35:43,592 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 586s 2024-03-22 21:35:43,876 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-DufdDuSOQf9qLI8t2Um1zvkka3qHRFVvTpTB9Osj1XJzTxyK0lLPQ9td3GuIh 587s 2024-03-22 21:35:44,060 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-1Ez56XyUBV2aKO0XHl2nWANySPjHHiljVRTzjOZzV2vLPlmRWCOAu4QQQoUMT 587s PASSED 587s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 587s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2024-03-22 21:35:44,262 INFO Trying to authenticate http://127.0.0.1:8080 again 587s 2024-03-22 21:35:44,287 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 587s 2024-03-22 21:35:44,353 INFO User dédé@example.com successfully authenticated 587s 2024-03-22 21:35:44,407 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 587s 2024-03-22 21:35:44,481 INFO Trying to authenticate http://127.0.0.1:8081 again 587s 2024-03-22 21:35:44,506 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 587s 2024-03-22 21:35:44,565 INFO User dédé@example.org successfully authenticated 587s 2024-03-22 21:35:44,616 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 587s 2024-03-22 21:35:44,681 INFO Trying to authenticate http://127.0.0.1:8082 again 587s 2024-03-22 21:35:44,706 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 587s 2024-03-22 21:35:44,766 INFO User dédé@example.net successfully authenticated 587s 2024-03-22 21:35:44,816 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 587s 2024-03-22 21:35:44,904 INFO Trying to authenticate http://127.0.0.1:8083 again 588s 2024-03-22 21:35:44,990 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 588s 2024-03-22 21:35:45,131 INFO User dédé@example.test successfully authenticated 588s 2024-03-22 21:35:45,258 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 588s 2024-03-22 21:35:45,271 INFO logout requested 588s 2024-03-22 21:35:45,289 INFO Logging out user dédé@example.com. 588s 2024-03-22 21:35:45,384 INFO User dédé@example.com logged out 588s 2024-03-22 21:35:45,507 INFO logout requested 588s 2024-03-22 21:35:45,525 INFO logout requested 588s 2024-03-22 21:35:45,536 INFO Logging out user dédé@example.org. 588s 2024-03-22 21:35:45,579 INFO User dédé@example.org logged out 588s 2024-03-22 21:35:45,635 INFO logout requested 588s 2024-03-22 21:35:45,649 INFO logout requested 588s 2024-03-22 21:35:45,660 INFO Logging out user dédé@example.net. 588s 2024-03-22 21:35:45,687 INFO User dédé@example.net logged out 588s 2024-03-22 21:35:45,758 INFO logout requested 588s 2024-03-22 21:35:45,776 INFO logout requested 588s 2024-03-22 21:35:45,787 INFO Logging out user dédé@example.test. 588s 2024-03-22 21:35:45,833 INFO User dédé@example.test logged out 588s 2024-03-22 21:35:45,934 INFO logout requested 589s 2024-03-22 21:35:46,031 INFO Trying to authenticate http://127.0.0.1:8080 again 589s 2024-03-22 21:35:46,077 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 589s 2024-03-22 21:35:46,140 INFO User dédé@example.com successfully authenticated 589s 2024-03-22 21:35:46,194 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 589s 2024-03-22 21:35:46,263 INFO Trying to authenticate http://127.0.0.1:8081 again 589s 2024-03-22 21:35:46,294 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 589s 2024-03-22 21:35:46,391 INFO User dédé@example.org successfully authenticated 589s 2024-03-22 21:35:46,446 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 589s 2024-03-22 21:35:46,515 INFO Trying to authenticate http://127.0.0.1:8082 again 589s 2024-03-22 21:35:46,542 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 589s 2024-03-22 21:35:46,627 INFO User dédé@example.net successfully authenticated 589s 2024-03-22 21:35:46,682 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 589s 2024-03-22 21:35:46,749 INFO Trying to authenticate http://127.0.0.1:8083 again 589s 2024-03-22 21:35:46,777 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 589s 2024-03-22 21:35:46,857 INFO User dédé@example.test successfully authenticated 589s 2024-03-22 21:35:46,912 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 589s 2024-03-22 21:35:46,925 INFO logout requested 589s 2024-03-22 21:35:46,932 INFO Logging out user dédé. 589s 2024-03-22 21:35:46,938 INFO User dédé logged out 590s 2024-03-22 21:35:47,004 INFO logout requested 590s 2024-03-22 21:35:47,011 INFO Logging out user dédé. 590s 2024-03-22 21:35:47,019 INFO User dédé logged out 590s 2024-03-22 21:35:47,080 INFO logout requested 590s 2024-03-22 21:35:47,086 INFO Logging out user dédé. 590s 2024-03-22 21:35:47,093 INFO User dédé logged out 590s 2024-03-22 21:35:47,154 INFO logout requested 590s 2024-03-22 21:35:47,161 INFO Logging out user dédé. 590s 2024-03-22 21:35:47,168 INFO User dédé logged out 590s PASSED 590s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2024-03-22 21:35:47,315 INFO Trying to authenticate http://127.0.0.1:8080 again 590s 2024-03-22 21:35:47,338 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 590s 2024-03-22 21:35:47,395 INFO User dédé@example.com successfully authenticated 590s 2024-03-22 21:35:47,448 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 590s 2024-03-22 21:35:47,512 INFO Trying to authenticate http://127.0.0.1:8081 again 590s 2024-03-22 21:35:47,538 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 590s 2024-03-22 21:35:47,610 INFO User dédé@example.org successfully authenticated 590s 2024-03-22 21:35:47,672 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 590s 2024-03-22 21:35:47,735 INFO Trying to authenticate http://127.0.0.1:8082 again 590s 2024-03-22 21:35:47,773 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 590s 2024-03-22 21:35:47,861 INFO User dédé@example.net successfully authenticated 590s 2024-03-22 21:35:47,919 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 591s 2024-03-22 21:35:47,998 INFO Trying to authenticate http://127.0.0.1:8083 again 591s 2024-03-22 21:35:48,024 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 591s 2024-03-22 21:35:48,105 INFO User dédé@example.test successfully authenticated 591s 2024-03-22 21:35:48,165 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 591s 2024-03-22 21:35:48,173 INFO logout requested 591s 2024-03-22 21:35:48,184 INFO Logging out user dédé@example.com. 591s 2024-03-22 21:35:48,218 INFO User dédé@example.com logged out 591s 2024-03-22 21:35:48,223 INFO logout requested 591s 2024-03-22 21:35:48,234 INFO Logging out user dédé@example.org. 591s 2024-03-22 21:35:48,266 INFO User dédé@example.org logged out 591s 2024-03-22 21:35:48,271 INFO logout requested 591s 2024-03-22 21:35:48,281 INFO Logging out user dédé@example.net. 591s 2024-03-22 21:35:48,309 INFO User dédé@example.net logged out 591s 2024-03-22 21:35:48,314 INFO logout requested 591s 2024-03-22 21:35:48,323 INFO Logging out user dédé@example.test. 591s 2024-03-22 21:35:48,350 INFO User dédé@example.test logged out 591s PASSED 591s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2024-03-22 21:35:48,449 WARNING A login attempt failed 591s PASSED 591s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 591s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2024-03-22 21:35:48,676 INFO Trying to authenticate http://127.0.0.1:8080 again 591s 2024-03-22 21:35:48,699 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 591s 2024-03-22 21:35:48,769 INFO User dédé@example.com successfully authenticated 591s 2024-03-22 21:35:48,826 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 591s 2024-03-22 21:35:48,904 INFO Trying to authenticate http://127.0.0.1:8081 again 591s 2024-03-22 21:35:48,934 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 592s 2024-03-22 21:35:49,000 INFO User dédé@example.org successfully authenticated 592s 2024-03-22 21:35:49,059 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 592s 2024-03-22 21:35:49,132 INFO Trying to authenticate http://127.0.0.1:8082 again 592s 2024-03-22 21:35:49,171 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 592s 2024-03-22 21:35:49,234 INFO User dédé@example.net successfully authenticated 592s 2024-03-22 21:35:49,292 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 592s 2024-03-22 21:35:49,366 INFO Trying to authenticate http://127.0.0.1:8083 again 592s 2024-03-22 21:35:49,391 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 592s 2024-03-22 21:35:49,451 INFO User dédé@example.test successfully authenticated 592s 2024-03-22 21:35:49,511 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 592s PASSED 592s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2024-03-22 21:35:49,613 INFO Trying to authenticate http://127.0.0.1:8080 again 592s 2024-03-22 21:35:49,637 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 592s 2024-03-22 21:35:49,695 INFO User dédé@example.com successfully authenticated 592s 2024-03-22 21:35:49,767 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 592s 2024-03-22 21:35:49,865 INFO Trying to authenticate http://127.0.0.1:8081 again 592s 2024-03-22 21:35:49,891 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 593s 2024-03-22 21:35:50,008 INFO User dédé@example.org successfully authenticated 593s 2024-03-22 21:35:50,087 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 593s 2024-03-22 21:35:50,190 INFO Trying to authenticate http://127.0.0.1:8082 again 593s 2024-03-22 21:35:50,236 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 593s 2024-03-22 21:35:50,294 INFO User dédé@example.net successfully authenticated 593s 2024-03-22 21:35:50,455 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 593s 2024-03-22 21:35:50,579 INFO Trying to authenticate http://127.0.0.1:8083 again 593s 2024-03-22 21:35:50,633 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 593s 2024-03-22 21:35:50,706 INFO User dédé@example.test successfully authenticated 593s 2024-03-22 21:35:50,781 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 593s 2024-03-22 21:35:50,877 INFO Trying to authenticate http://127.0.0.1:8080 again 593s 2024-03-22 21:35:50,901 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 594s 2024-03-22 21:35:50,965 INFO User dédé@example.com successfully authenticated 594s 2024-03-22 21:35:51,020 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 594s 2024-03-22 21:35:51,101 INFO Trying to authenticate http://127.0.0.1:8081 again 594s 2024-03-22 21:35:51,178 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 594s 2024-03-22 21:35:51,247 INFO User dédé@example.org successfully authenticated 594s 2024-03-22 21:35:51,299 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 594s 2024-03-22 21:35:51,412 INFO Trying to authenticate http://127.0.0.1:8082 again 594s 2024-03-22 21:35:51,462 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 594s 2024-03-22 21:35:51,520 INFO User dédé@example.net successfully authenticated 594s 2024-03-22 21:35:51,584 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 594s 2024-03-22 21:35:51,846 INFO Trying to authenticate http://127.0.0.1:8083 again 594s 2024-03-22 21:35:51,877 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 594s 2024-03-22 21:35:51,948 INFO User dédé@example.test successfully authenticated 595s 2024-03-22 21:35:52,068 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 595s 2024-03-22 21:35:52,078 WARNING User already authenticated, dropping federated authentication request 595s 2024-03-22 21:35:52,086 WARNING User already authenticated, dropping federated authentication request 595s 2024-03-22 21:35:52,094 WARNING User already authenticated, dropping federated authentication request 595s 2024-03-22 21:35:52,101 WARNING User already authenticated, dropping federated authentication request 595s PASSED 595s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2024-03-22 21:35:52,215 INFO Trying to authenticate http://127.0.0.1:8080 again 595s 2024-03-22 21:35:52,263 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 595s 2024-03-22 21:35:52,321 INFO User dédé@example.com successfully authenticated 595s 2024-03-22 21:35:52,398 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 595s 2024-03-22 21:35:52,506 INFO Trying to authenticate http://127.0.0.1:8081 again 595s 2024-03-22 21:35:52,533 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 595s 2024-03-22 21:35:52,597 INFO User dédé@example.org successfully authenticated 595s 2024-03-22 21:35:52,671 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 595s 2024-03-22 21:35:52,742 INFO Trying to authenticate http://127.0.0.1:8082 again 595s 2024-03-22 21:35:52,772 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 595s 2024-03-22 21:35:52,855 INFO User dédé@example.net successfully authenticated 595s 2024-03-22 21:35:52,931 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 596s 2024-03-22 21:35:53,013 INFO Trying to authenticate http://127.0.0.1:8083 again 596s 2024-03-22 21:35:53,040 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 596s 2024-03-22 21:35:53,114 INFO User dédé@example.test successfully authenticated 596s 2024-03-22 21:35:53,210 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 596s 2024-03-22 21:35:53,228 INFO logout requested 596s 2024-03-22 21:35:53,346 INFO Logging out user dédé@example.com. 596s 2024-03-22 21:35:53,388 INFO User dédé@example.com logged out 596s 2024-03-22 21:35:53,409 INFO logout requested 596s 2024-03-22 21:35:53,426 INFO Logging out user dédé@example.org. 596s 2024-03-22 21:35:53,479 INFO User dédé@example.org logged out 596s 2024-03-22 21:35:53,498 INFO logout requested 596s 2024-03-22 21:35:53,511 INFO Logging out user dédé@example.net. 596s 2024-03-22 21:35:53,542 INFO User dédé@example.net logged out 596s 2024-03-22 21:35:53,561 INFO logout requested 596s 2024-03-22 21:35:53,571 INFO Logging out user dédé@example.test. 596s 2024-03-22 21:35:53,605 INFO User dédé@example.test logged out 596s PASSED 596s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2024-03-22 21:35:53,726 INFO Trying to authenticate http://127.0.0.1:8080 again 596s 2024-03-22 21:35:53,764 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 596s 2024-03-22 21:35:53,838 INFO User dédé@example.com successfully authenticated 596s 2024-03-22 21:35:53,914 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 597s 2024-03-22 21:35:53,981 INFO Trying to authenticate http://127.0.0.1:8081 again 597s 2024-03-22 21:35:54,007 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 597s 2024-03-22 21:35:54,089 INFO User dédé@example.org successfully authenticated 597s 2024-03-22 21:35:54,176 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 597s 2024-03-22 21:35:54,297 INFO Trying to authenticate http://127.0.0.1:8082 again 597s 2024-03-22 21:35:54,368 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 597s 2024-03-22 21:35:54,442 INFO User dédé@example.net successfully authenticated 597s 2024-03-22 21:35:54,498 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 597s 2024-03-22 21:35:54,601 INFO Trying to authenticate http://127.0.0.1:8083 again 597s 2024-03-22 21:35:54,633 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 597s 2024-03-22 21:35:54,733 INFO User dédé@example.test successfully authenticated 597s 2024-03-22 21:35:54,786 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 597s 2024-03-22 21:35:54,813 INFO Trying to authenticate http://127.0.0.1:8080 again 597s 2024-03-22 21:35:54,839 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 597s 2024-03-22 21:35:54,924 INFO User dédé@example.com successfully authenticated 598s 2024-03-22 21:35:54,954 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 598s 2024-03-22 21:35:54,990 INFO Trying to authenticate http://127.0.0.1:8081 again 598s 2024-03-22 21:35:55,025 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 598s 2024-03-22 21:35:55,147 INFO User dédé@example.org successfully authenticated 598s 2024-03-22 21:35:55,224 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 598s 2024-03-22 21:35:55,268 INFO Trying to authenticate http://127.0.0.1:8082 again 598s 2024-03-22 21:35:55,312 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 598s 2024-03-22 21:35:55,394 INFO User dédé@example.net successfully authenticated 598s 2024-03-22 21:35:55,432 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 598s 2024-03-22 21:35:55,474 INFO Trying to authenticate http://127.0.0.1:8083 again 598s 2024-03-22 21:35:55,509 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 598s 2024-03-22 21:35:55,589 INFO User dédé@example.test successfully authenticated 598s 2024-03-22 21:35:55,616 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 598s PASSED 598s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 598s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 598s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 599s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2024-03-22 21:35:55,988 INFO User test successfully authenticated 599s PASSED 599s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2024-03-22 21:35:56,149 INFO User test successfully authenticated 599s PASSED 599s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2024-03-22 21:35:56,242 INFO User test successfully authenticated 599s 2024-03-22 21:35:56,303 INFO User test successfully authenticated 599s 2024-03-22 21:35:56,361 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 599s 2024-03-22 21:35:56,381 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')) 599s PASSED 599s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2024-03-22 21:35:56,452 INFO User test successfully authenticated 599s PASSED 599s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2024-03-22 21:35:56,578 INFO User test successfully authenticated 599s PASSED 599s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2024-03-22 21:35:56,780 INFO User test successfully authenticated 599s 2024-03-22 21:35:56,881 INFO Sending SLO requests to service http://127.0.0.1:46613 for user test 599s 2024-03-22 21:35:56,897 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 599s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 599s PASSED 600s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2024-03-22 21:35:57,008 INFO User test successfully authenticated 600s PASSED 600s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 600s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 600s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 600s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 600s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 600s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 600s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 600s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 600s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 600s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 600s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 600s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 600s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 600s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 600s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 600s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 600s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 600s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 600s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 602s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 602s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 602s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 602s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2024-03-22 21:35:59,442 INFO User test successfully authenticated 602s 2024-03-22 21:35:59,480 WARNING Service https://www.example.org not allowed. 602s PASSED 602s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success 2024-03-22 21:35:59,566 INFO User test successfully authenticated 602s 2024-03-22 21:35:59,614 INFO Service ticket created for service https://www.example.com by user test. 602s PASSED 602s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt 2024-03-22 21:35:59,697 INFO User test successfully authenticated 602s 2024-03-22 21:35:59,746 INFO Service ticket created for service https://www.example.com by user test. 602s PASSED 602s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn 2024-03-22 21:35:59,836 INFO User test successfully authenticated 602s PASSED 603s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged 2024-03-22 21:35:59,973 INFO User test successfully authenticated 603s PASSED 603s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2024-03-22 21:36:00,097 INFO User test successfully authenticated 603s 2024-03-22 21:36:00,143 WARNING User test seems authenticated but is not found in the database. 603s 2024-03-22 21:36:00,145 INFO Logging out user test. 603s 2024-03-22 21:36:00,164 INFO User test logged out 603s PASSED 603s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 603s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2024-03-22 21:36:00,308 INFO User test successfully authenticated 603s 2024-03-22 21:36:00,347 WARNING Username test not allowed on service restrict_user_fail 603s PASSED 603s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2024-03-22 21:36:00,460 WARNING A login attempt failed 603s 2024-03-22 21:36:00,588 WARNING A login attempt failed 603s 2024-03-22 21:36:00,723 WARNING A login attempt failed 603s PASSED 603s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2024-03-22 21:36:00,888 WARNING Received an invalid login ticket 603s PASSED 604s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2024-03-22 21:36:01,018 WARNING A login attempt failed 604s PASSED 604s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2024-03-22 21:36:01,174 INFO User test successfully authenticated 604s PASSED 604s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2024-03-22 21:36:01,315 INFO User test successfully authenticated 604s PASSED 610s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 610s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 610s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 610s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 610s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 610s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 610s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 610s cas_server/tests/test_view.py::LoginTestCase::test_renew 2024-03-22 21:36:07,781 INFO User test successfully authenticated 610s 2024-03-22 21:36:07,854 INFO User test successfully authenticated 610s 2024-03-22 21:36:07,894 INFO Service ticket created for service https://www.example.com by user test. 610s PASSED 611s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2024-03-22 21:36:07,973 INFO User test successfully authenticated 611s 2024-03-22 21:36:08,052 INFO User test successfully authenticated 611s 2024-03-22 21:36:08,078 INFO Service ticket created for service https://www.example.com by user test. 611s PASSED 611s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2024-03-22 21:36:08,195 INFO User test successfully authenticated 611s 2024-03-22 21:36:08,230 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 611s 2024-03-22 21:36:08,266 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 611s 2024-03-22 21:36:08,298 INFO Service ticket created for service https://filter_success.example.com by user test. 611s PASSED 611s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2024-03-22 21:36:08,366 INFO User test successfully authenticated 611s 2024-03-22 21:36:08,395 WARNING Username test not allowed on service restrict_user_fail 611s 2024-03-22 21:36:08,425 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 611s PASSED 611s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2024-03-22 21:36:08,505 INFO User test successfully authenticated 611s 2024-03-22 21:36:08,557 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 611s 2024-03-22 21:36:08,589 INFO Service ticket created for service https://field_needed_success.example.com by user test. 611s PASSED 611s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2024-03-22 21:36:08,664 INFO User test successfully authenticated 611s 2024-03-22 21:36:08,696 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 611s PASSED 611s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2024-03-22 21:36:08,784 INFO User test successfully authenticated 611s 2024-03-22 21:36:08,821 WARNING User test seems authenticated but is not found in the database. 611s 2024-03-22 21:36:08,822 INFO Logging out user test. 611s 2024-03-22 21:36:08,828 INFO User test logged out 611s PASSED 611s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 612s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 612s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2024-03-22 21:36:09,071 INFO User test successfully authenticated 612s 2024-03-22 21:36:09,145 INFO Service ticket created for service https://www.example.com/é by user test. 612s PASSED 612s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2024-03-22 21:36:09,277 INFO User test successfully authenticated 612s 2024-03-22 21:36:09,330 INFO Service ticket created for service https://www.example.com by user test. 612s PASSED 612s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2024-03-22 21:36:09,453 INFO User test successfully authenticated 612s 2024-03-22 21:36:09,516 WARNING Received a login attempt for an already-active user 612s 2024-03-22 21:36:09,535 INFO Service ticket created for service https://www.example.com by user test. 612s PASSED 612s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2024-03-22 21:36:09,673 INFO User test successfully authenticated 612s 2024-03-22 21:36:09,702 WARNING Service https://www.example.org not allowed. 612s PASSED 612s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2024-03-22 21:36:09,763 WARNING Service https://www.example.net not allowed. 612s PASSED 612s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2024-03-22 21:36:09,843 WARNING Service https://www.example.net not allowed. 612s PASSED 613s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2024-03-22 21:36:09,959 INFO User test successfully authenticated 613s 2024-03-22 21:36:09,982 INFO logout requested 613s 2024-03-22 21:36:09,986 INFO Logging out user test. 613s 2024-03-22 21:36:10,067 INFO User test logged out 613s PASSED 613s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2024-03-22 21:36:10,143 INFO User test successfully authenticated 613s 2024-03-22 21:36:10,234 INFO User test successfully authenticated 613s 2024-03-22 21:36:10,290 INFO User test successfully authenticated 613s 2024-03-22 21:36:10,358 INFO User test successfully authenticated 613s 2024-03-22 21:36:10,413 INFO User test successfully authenticated 613s 2024-03-22 21:36:10,498 INFO User test successfully authenticated 613s 2024-03-22 21:36:10,552 INFO User test successfully authenticated 613s 2024-03-22 21:36:10,606 INFO User test successfully authenticated 613s 2024-03-22 21:36:10,662 INFO User test successfully authenticated 613s 2024-03-22 21:36:10,682 INFO logout requested 613s 2024-03-22 21:36:10,687 INFO Logging out user test from all sessions. 614s 2024-03-22 21:36:10,878 INFO User test logged out 614s PASSED 614s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2024-03-22 21:36:10,933 INFO User test successfully authenticated 614s 2024-03-22 21:36:10,954 INFO logout requested 614s 2024-03-22 21:36:10,958 INFO Logging out user test. 614s 2024-03-22 21:36:10,980 INFO User test logged out 614s PASSED 614s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2024-03-22 21:36:10,998 INFO logout requested 614s PASSED 614s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2024-03-22 21:36:11,077 INFO User test successfully authenticated 614s 2024-03-22 21:36:11,148 INFO User test successfully authenticated 614s 2024-03-22 21:36:11,169 INFO logout requested 614s 2024-03-22 21:36:11,174 INFO Logging out user test from all sessions. 614s 2024-03-22 21:36:11,225 INFO User test logged out 614s PASSED 614s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2024-03-22 21:36:11,317 INFO User test successfully authenticated 614s 2024-03-22 21:36:11,357 INFO Service ticket created for service http://127.0.0.1:41641 by user test. 614s 2024-03-22 21:36:11,378 INFO Validate: Service ticket ST-qrbr2wo54TdGvMXvgimMtUczsUYQ4zNAAirksQDNV3ihKwCR7AkwTV4jcEB8T validated, user test authenticated on service http://127.0.0.1:41641 614s 2024-03-22 21:36:11,387 INFO logout requested 614s 2024-03-22 21:36:11,391 INFO Logging out user test. 614s 2024-03-22 21:36:11,408 INFO Sending SLO requests to service http://127.0.0.1:41641 for user test 614s 2024-03-22 21:36:11,423 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 614s 2024-03-22 21:36:11,433 INFO User test logged out 614s 2024-03-22 21:36:11,485 INFO User test successfully authenticated 614s 2024-03-22 21:36:11,522 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 614s 2024-03-22 21:36:11,541 INFO Validate: Service ticket ST-svIsAljXm5UFgYhwLUc4jXOUxidbX6WW7PSlQIcdQD2PrbtKMqwtT7U4famsQ validated, user test authenticated on service http://127.0.0.1:45678 614s 2024-03-22 21:36:11,556 INFO logout requested 614s 2024-03-22 21:36:11,561 INFO Logging out user test. 614s 2024-03-22 21:36:11,576 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 614s 2024-03-22 21:36:11,592 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 614s 2024-03-22 21:36:11,600 INFO User test logged out 614s 2024-03-22 21:36:11,649 INFO User test successfully authenticated 614s 2024-03-22 21:36:11,688 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 614s 2024-03-22 21:36:11,709 INFO Validate: Service ticket ST-M0iwgw1uECSmekXcd7UOgabyFp1B8ee9v8hQPrFRdijDt7cazAXMjURhNgoi6 validated, user test authenticated on service http://127.0.0.1:45678 614s 2024-03-22 21:36:11,718 INFO logout requested 614s 2024-03-22 21:36:11,723 INFO Logging out user test. 614s 2024-03-22 21:36:11,737 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 614s 2024-03-22 21:36:11,750 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=46139): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 614s 2024-03-22 21:36:11,770 INFO User test logged out 614s PASSED 614s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2024-03-22 21:36:11,833 INFO User test successfully authenticated 614s 2024-03-22 21:36:11,895 INFO User test successfully authenticated 614s 2024-03-22 21:36:11,932 INFO logout requested 614s 2024-03-22 21:36:11,937 INFO Logging out user test. 615s 2024-03-22 21:36:11,961 INFO User test logged out 615s PASSED 615s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2024-03-22 21:36:12,064 INFO User test successfully authenticated 615s 2024-03-22 21:36:12,084 INFO logout requested 615s 2024-03-22 21:36:12,088 INFO Logging out user test. 615s 2024-03-22 21:36:12,111 INFO User test logged out 615s PASSED 615s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2024-03-22 21:36:12,202 INFO User test successfully authenticated 615s 2024-03-22 21:36:12,223 INFO logout requested 615s 2024-03-22 21:36:12,227 INFO Logging out user test. 615s 2024-03-22 21:36:12,250 INFO User test logged out 615s PASSED 615s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2024-03-22 21:36:12,342 INFO User test successfully authenticated 615s 2024-03-22 21:36:12,363 INFO logout requested 615s 2024-03-22 21:36:12,367 INFO Logging out user test. 615s 2024-03-22 21:36:12,404 INFO User test logged out 615s PASSED 615s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2024-03-22 21:36:12,476 INFO User test successfully authenticated 615s 2024-03-22 21:36:12,498 INFO logout requested 615s 2024-03-22 21:36:12,503 INFO Logging out user test. 615s 2024-03-22 21:36:12,528 INFO User test logged out 615s 2024-03-22 21:36:12,569 INFO logout requested 615s PASSED 615s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 615s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 615s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice 2024-03-22 21:36:12,692 WARNING Service https://www.example.org not allowed. 615s PASSED 615s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings PASSED 615s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 615s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2024-03-22 21:36:12,921 INFO User test successfully authenticated 616s PASSED 616s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 616s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2024-03-22 21:36:13,106 INFO User test successfully authenticated 616s 2024-03-22 21:36:13,148 INFO Service ticket created for service https://www.example.com by user test. 616s 2024-03-22 21:36:13,161 WARNING Validate: service or ticket missing 616s 2024-03-22 21:36:13,166 WARNING Validate: service or ticket missing 616s PASSED 616s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2024-03-22 21:36:13,227 INFO User test successfully authenticated 616s 2024-03-22 21:36:13,270 INFO Service ticket created for service https://www.example.com by user test. 616s 2024-03-22 21:36:13,290 INFO Validate: Service ticket ST-uylWiv0mdUELsUMLTC7uDvOBnqw9PXHAnVYj4E7kO58KS3PSFGNkGskjmBway validated, user test authenticated on service https://www.example.com 616s 2024-03-22 21:36:13,350 INFO User test successfully authenticated 616s 2024-03-22 21:36:13,373 INFO Service ticket created for service https://www.example.com by user test. 616s 2024-03-22 21:36:13,392 INFO Validate: Service ticket ST-n8hzm7Xu4m8kZj8454ZNrJCOTAi8PNh6XiucD6fr5eE7MEugMC73XsjeKejG4 validated, user test authenticated on service https://www.example.com 616s 2024-03-22 21:36:13,416 INFO Service ticket created for service https://www.example.com by user test. 616s 2024-03-22 21:36:13,430 WARNING Validate: Service ticket ST-yRub0QktiIe3UuFYzZ7lPJDvpudXVoamEjt1Tb6IrOn6CXvJfbMmbUPE1yyFP not found or already validated, auth to https://www.example.com failed 616s PASSED 616s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2024-03-22 21:36:13,488 INFO User test successfully authenticated 616s 2024-03-22 21:36:13,528 INFO Service ticket created for service https://user_field.example.com by user test. 616s 2024-03-22 21:36:13,548 INFO Validate: Service ticket ST-Zx8x0Ofpi4oi7qn91IKrZTfVBpJ4Ofs2mTjP0WKtUTO7g4dHuPWRcBLfMBKqG validated, user test authenticated on service https://user_field.example.com 616s 2024-03-22 21:36:13,597 INFO User test successfully authenticated 616s 2024-03-22 21:36:13,881 INFO Service ticket created for service https://user_field_alt.example.com by user test. 616s 2024-03-22 21:36:13,905 INFO Validate: Service ticket ST-4C5VPoKiFmQxFbLpaJ1whk3KTRjYOMuashuBLctIPRn2lenAtT0nMaT50xU3g validated, user test authenticated on service https://user_field_alt.example.com 616s PASSED 617s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2024-03-22 21:36:14,024 INFO User test successfully authenticated 617s 2024-03-22 21:36:14,089 INFO Service ticket created for service https://www.example.com by user test. 617s 2024-03-22 21:36:14,107 WARNING Validate: Service ticket ST-hLc4eYmoFq0DoFT1NKHaF4O1E8rU6ydlwh9nfLHXP4bbGCv0RF6K9PudYlU4G not found or already validated, auth to https://www.example.org failed 617s PASSED 617s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2024-03-22 21:36:14,166 INFO User test successfully authenticated 617s 2024-03-22 21:36:14,207 INFO Service ticket created for service https://www.example.com by user test. 617s 2024-03-22 21:36:14,223 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 617s PASSED 617s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2024-03-22 21:36:14,283 INFO User test successfully authenticated 617s 2024-03-22 21:36:14,323 INFO Service ticket created for service https://www.example.com by user test. 617s 2024-03-22 21:36:14,341 INFO Validate: Service ticket ST-AtWR7yGq9MzSdWah1kA4V80P3qGEY5m07dfU0LdFZTTilkEEUWOzc8mc1gA8R validated, user test authenticated on service https://www.example.com 617s PASSED 617s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2024-03-22 21:36:14,410 INFO User test successfully authenticated 617s 2024-03-22 21:36:14,446 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 617s 2024-03-22 21:36:14,462 WARNING ValidateService: missing ticket or service 617s PASSED 617s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2024-03-22 21:36:14,468 WARNING ValidateService: missing ticket or service 617s 2024-03-22 21:36:14,539 INFO User test successfully authenticated 617s 2024-03-22 21:36:14,578 INFO Service ticket created for service http://127.0.0.1:41909 by user test. 617s 2024-03-22 21:36:14,614 INFO ValidateService: ticket ST-uoWCST4jDJjQJYTFO9OU5KsK8TrpKhVmhPKTbdWnmzEuMpCnUxhz9ioVYe0hW validated for user test on service http://127.0.0.1:41909. Proxy Granting Ticket transmited to http://127.0.0.1:41909. 617s 2024-03-22 21:36:14,643 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 617s 2024-03-22 21:36:14,670 INFO ValidateService: ticket PT-kPCWv8qpzUrUXRanR9exqS0MGAAfhuAnDp0tdtON2ycIOYINnuKtmNh0eNknN validated for user test on service http://127.0.0.1:45678. 617s 2024-03-22 21:36:14,724 INFO User test successfully authenticated 617s 2024-03-22 21:36:14,765 INFO Service ticket created for service http://127.0.0.1:45227 by user test. 617s 2024-03-22 21:36:14,798 INFO ValidateService: ticket ST-uvhsJECTHf2R5FhILxqxyH1jvC6H5TmSDh7uzujFU7GnmJk2q9VIRltMHvLK6 validated for user test on service http://127.0.0.1:45227. Proxy Granting Ticket transmited to http://127.0.0.1:45227. 617s 2024-03-22 21:36:14,823 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 617s 2024-03-22 21:36:14,832 WARNING ValidateService: validation error: INVALID_TICKET PT-KckPBTRp5YTESKrrL32MAGbRfbIMRDd15GKbWD7jMzhRUraXJdzGyhB748dZ9 617s PASSED 617s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2024-03-22 21:36:14,899 INFO User test successfully authenticated 617s 2024-03-22 21:36:14,945 INFO Service ticket created for service https://replace_attribute.example.com by user test. 618s 2024-03-22 21:36:14,966 INFO ValidateService: ticket ST-IPZZLSld5y0jPMSogx7DACCYB5dTtRx104H8pBwxq6dkMTh3dobDETO33Wlj7 validated for user test on service https://replace_attribute.example.com. 618s 2024-03-22 21:36:15,024 INFO User test successfully authenticated 618s 2024-03-22 21:36:15,066 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 618s 2024-03-22 21:36:15,088 INFO ValidateService: ticket ST-IrtNjO1NFC9xQkw7OhyEhy80wAoFX0opsbPWGPrLlnYZaXeAiypXPoivoaiZk validated for user test on service https://replace_attribute_list.example.com. 618s PASSED 618s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2024-03-22 21:36:15,164 INFO User test successfully authenticated 618s 2024-03-22 21:36:15,208 INFO Service ticket created for service http://127.0.0.1:42271 by user test. 618s 2024-03-22 21:36:15,248 INFO ValidateService: ticket ST-qmhPUAENqFXNzk8YvPln9lqhVnOUTNMZXhXQcHEwsG2UD0tiaw6GA3a3eZMiY validated for user test on service http://127.0.0.1:42271. Proxy Granting Ticket transmited to http://127.0.0.1:42271. 618s PASSED 618s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2024-03-22 21:36:15,323 INFO User test successfully authenticated 618s 2024-03-22 21:36:15,365 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 618s 2024-03-22 21:36:15,390 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 618s 2024-03-22 21:36:15,437 INFO User test successfully authenticated 618s 2024-03-22 21:36:15,480 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 618s 2024-03-22 21:36:15,508 WARNING Service https://www.example.org not allowed. 618s 2024-03-22 21:36:15,508 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 618s PASSED 618s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2024-03-22 21:36:15,591 INFO User test successfully authenticated 618s 2024-03-22 21:36:15,632 INFO Service ticket created for service https://127.0.0.1:41429 by user test. 618s 2024-03-22 21:36:15,803 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=41429): Max retries exceeded with url: /?pgtId=PGT-cuwq9R76pt3rnGKBSBKobpjx0Q2hNRLCLIXNf8PAjkrFGamDZNFC6nLPOV1x&pgtIou=PGTIOU-SvFpmb2JTgKnI3OzeiiqHTYeKA8Mh5cVIqMyslvUmoxC19QE6wyKoyDE9 (Caused by SSLError(SSLError(1, '[SSL: WRONG_VERSION_NUMBER] wrong version number (_ssl.c:1000)'))) 618s PASSED 618s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2024-03-22 21:36:15,889 INFO User test successfully authenticated 618s 2024-03-22 21:36:15,912 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 618s 2024-03-22 21:36:15,933 INFO ValidateService: ticket ST-BwpihTfAsGVAMHD0UjtRZHkOnFI6K5kNOfrTbQA95PJePRoeajFhFLCOb8Ykw validated for user test on service http://127.0.0.1:45678. 619s 2024-03-22 21:36:15,980 INFO User test successfully authenticated 619s 2024-03-22 21:36:16,002 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 619s 2024-03-22 21:36:16,022 INFO ValidateService: ticket ST-DTqTQtlnFbAjl05eiTCKxOYbdIGi7wl3d9NY3Tntq8x5rD2L1DS33NXnU1pC2 validated for user test on service http://127.0.0.1:45678. 619s 2024-03-22 21:36:16,048 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 619s 2024-03-22 21:36:16,061 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 619s PASSED 619s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2024-03-22 21:36:16,132 INFO User test successfully authenticated 619s 2024-03-22 21:36:16,172 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 619s 2024-03-22 21:36:16,191 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 619s PASSED 619s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2024-03-22 21:36:16,258 INFO User test successfully authenticated 619s 2024-03-22 21:36:16,300 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 619s 2024-03-22 21:36:16,312 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 619s PASSED 619s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2024-03-22 21:36:16,380 INFO User test successfully authenticated 619s 2024-03-22 21:36:16,433 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 619s 2024-03-22 21:36:16,449 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 619s PASSED 619s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2024-03-22 21:36:16,517 INFO User test successfully authenticated 619s 2024-03-22 21:36:16,557 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 619s 2024-03-22 21:36:16,580 INFO ValidateService: ticket ST-yVJeHvrVf0qYBYtm0hgUWsIJBzIotZaWjksnftGDteXbZUO78hPlT9UybSqcf validated for user test on service http://127.0.0.1:45678. 619s PASSED 619s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2024-03-22 21:36:16,652 INFO User test successfully authenticated 619s 2024-03-22 21:36:16,691 INFO Service ticket created for service https://one_attribute.example.com by user test. 619s 2024-03-22 21:36:16,712 INFO ValidateService: ticket ST-cC71av3FeS4MQiGdi0crXKtlGTZ38Y3RS8iu4r4fhqxxXy2Ct7rqCxmlnlNJV validated for user test on service https://one_attribute.example.com. 619s PASSED 619s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2024-03-22 21:36:16,786 INFO User test successfully authenticated 619s 2024-03-22 21:36:16,825 INFO Service ticket created for service http://127.0.0.1:36069 by user test. 619s 2024-03-22 21:36:16,859 INFO ValidateService: ticket ST-OFPkgn7nyUboYfSQ9bag9YYatqRVIV4hLK4ghZ7WtjRKHQT0uydPNGHNfAN1r validated for user test on service http://127.0.0.1:36069. Proxy Granting Ticket transmited to http://127.0.0.1:36069. 619s PASSED 619s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2024-03-22 21:36:16,931 INFO User test successfully authenticated 620s 2024-03-22 21:36:16,972 INFO Service ticket created for service https://user_field.example.com by user test. 620s 2024-03-22 21:36:16,993 INFO ValidateService: ticket ST-Gg0q9wRfstifHRuxrFE6To36XoFgnSEwMKkQRGapCkIvrf6Ttqta21OvSyOc2 validated for user test on service https://user_field.example.com. 620s 2024-03-22 21:36:17,041 INFO User test successfully authenticated 620s 2024-03-22 21:36:17,088 INFO Service ticket created for service https://user_field_alt.example.com by user test. 620s 2024-03-22 21:36:17,124 INFO ValidateService: ticket ST-DC7dsViXxg9G5Rz23QypTFBebsmy0sJUA0o2xPOhy1Z8JoEiS4dZQPdLSn0mF validated for user test on service https://user_field_alt.example.com. 620s PASSED 620s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2024-03-22 21:36:17,215 INFO User test successfully authenticated 620s 2024-03-22 21:36:17,255 INFO Service ticket created for service http://127.0.0.1:32811 by user test. 620s 2024-03-22 21:36:17,289 INFO ValidateService: ticket ST-nSxGTTgc3PGbfnsmraxeG4KKTICuPeBZLhKtnNWezaKwqOEHorbTbXvn1v0AZ validated for user test on service http://127.0.0.1:32811. Proxy Granting Ticket transmited to http://127.0.0.1:32811. 620s 2024-03-22 21:36:17,296 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 620s 2024-03-22 21:36:17,302 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 620s PASSED 620s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2024-03-22 21:36:17,378 INFO User test successfully authenticated 620s 2024-03-22 21:36:17,417 INFO Service ticket created for service http://127.0.0.1:36959 by user test. 620s 2024-03-22 21:36:17,450 INFO ValidateService: ticket ST-8PszD3Qqei91ulFvujQdeowCi3PxxuPN2lMrxWXaXqunYnGYiyM432kelIm4A validated for user test on service http://127.0.0.1:36959. Proxy Granting Ticket transmited to http://127.0.0.1:36959. 620s 2024-03-22 21:36:17,468 WARNING Username test not allowed on service restrict_user_fail 620s 2024-03-22 21:36:17,469 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 620s 2024-03-22 21:36:17,483 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 620s 2024-03-22 21:36:17,485 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 620s 2024-03-22 21:36:17,499 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 620s 2024-03-22 21:36:17,500 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 620s PASSED 620s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2024-03-22 21:36:17,576 INFO User test successfully authenticated 620s 2024-03-22 21:36:17,617 INFO Service ticket created for service http://127.0.0.1:33893 by user test. 620s 2024-03-22 21:36:17,653 INFO ValidateService: ticket ST-5BL8Z6x1xTznQAH8fs8Z9yIr357z0YjBI3ZCcYzbfG536FQWUBIf71xc7seID validated for user test on service http://127.0.0.1:33893. Proxy Granting Ticket transmited to http://127.0.0.1:33893. 620s 2024-03-22 21:36:17,666 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 620s PASSED 620s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2024-03-22 21:36:17,743 INFO User test successfully authenticated 620s 2024-03-22 21:36:17,786 INFO Service ticket created for service http://127.0.0.1:38879 by user test. 620s 2024-03-22 21:36:17,823 INFO ValidateService: ticket ST-hOJGKXIZ6Ck6t1f3km5GfcyXWJ0FWBZmb7bDAKyjeoNVEcljVfCh5n6jIXY6k validated for user test on service http://127.0.0.1:38879. Proxy Granting Ticket transmited to http://127.0.0.1:38879. 620s 2024-03-22 21:36:17,833 WARNING Service https://www.example.org not allowed. 620s 2024-03-22 21:36:17,835 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 620s 2024-03-22 21:36:17,845 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:38879 does not allow proxy tickets 620s PASSED 620s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2024-03-22 21:36:17,935 INFO User test successfully authenticated 621s 2024-03-22 21:36:17,977 INFO Service ticket created for service http://127.0.0.1:40775 by user test. 621s 2024-03-22 21:36:18,010 INFO ValidateService: ticket ST-v7XRPljTSsJf1DjkHpRDMNOOqadQlHyyegaLCxRnVJKwAYkjHzMPiCWpQhRWm validated for user test on service http://127.0.0.1:40775. Proxy Granting Ticket transmited to http://127.0.0.1:40775. 621s 2024-03-22 21:36:18,036 INFO Proxy ticket created for user test on service https://www.example.com. 621s 2024-03-22 21:36:18,058 INFO ValidateService: ticket PT-6ktci3PE8iepcKFzWQR16MYM9V9QiZfrBhjdkuLqmyntAYxySilkFeggoIwaL validated for user test on service https://www.example.com. 621s PASSED 621s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2024-03-22 21:36:18,128 INFO User test successfully authenticated 621s 2024-03-22 21:36:18,165 INFO Service ticket created for service https://www.example.com by user test. 621s 2024-03-22 21:36:18,183 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 621s PASSED 621s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2024-03-22 21:36:18,219 WARNING SamlValidate: validation error: AuthnFailed ticket ST-m1xNW8zBzMuRPPxT5mzPJsXAHZrGC3gEeR9uU8FtLfQzwrQBDOproT8ZeTll4 not found 621s 2024-03-22 21:36:18,228 WARNING SamlValidate: validation error: AuthnFailed ticket PT-5u8dGK4IIpRTDhAe8SsJL2Ov9N2hx9pM1TafIeR2ADzhbVFBBGZ13ElD4LSL1 not found 621s PASSED 621s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix PASSED2024-03-22 21:36:18,258 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 621s 621s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml 2024-03-22 21:36:18,292 WARNING SamlValidate: validation error: VersionMismatch 621s PASSED 621s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2024-03-22 21:36:18,358 INFO User test successfully authenticated 621s 2024-03-22 21:36:18,396 INFO Service ticket created for service https://www.example.com by user test. 621s 2024-03-22 21:36:18,453 INFO User test successfully authenticated 621s 2024-03-22 21:36:18,490 INFO Service ticket created for service http://127.0.0.1:33729 by user test. 621s 2024-03-22 21:36:18,529 INFO ValidateService: ticket ST-ZGfvaSsWvRyKtdAozLNpVCtgwDDVhl0AclyyD2nAAIL1PV9lHDgUpgUlkN5q7 validated for user test on service http://127.0.0.1:33729. Proxy Granting Ticket transmited to http://127.0.0.1:33729. 621s 2024-03-22 21:36:18,556 INFO Proxy ticket created for user test on service https://www.example.com. 621s 2024-03-22 21:36:18,574 INFO SamlValidate: ticket ST-AOr8kAhQCGwoOIGH8jvI3RThjkICQ2VgfCZ90JMoX9KKwHxfcpV5wQOTaWOca validated for user test on service https://www.example.com. 621s 2024-03-22 21:36:18,591 INFO SamlValidate: ticket PT-kNwgsupmFzeq3LKu5zSohoTtZXIB575Rj2poNeg6pa1cslaHgcYdyv3xyHgZH validated for user test on service https://www.example.com. 621s PASSED 621s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2024-03-22 21:36:18,662 INFO User test successfully authenticated 621s 2024-03-22 21:36:18,700 INFO Service ticket created for service https://field_needed_success.example.com by user test. 621s 2024-03-22 21:36:18,722 INFO SamlValidate: ticket ST-SchPvexv37oROSNfLWPcNi5uBVnUxRqgL6kcW831wS47GYnLDBTV971etVSmT validated for user test on service https://field_needed_success.example.com. 621s 2024-03-22 21:36:18,763 INFO User test successfully authenticated 621s 2024-03-22 21:36:18,800 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 621s 2024-03-22 21:36:18,825 INFO SamlValidate: ticket ST-hCDt4FsFB9o9D4sMkrmTcQ1eEugMR3faWs0U6FupCsJRXeIT06L7vLL6MBPNE validated for user test on service https://field_needed_success_alt.example.com. 621s PASSEDDestroying test database for alias 'default'... 621s 621s 621s =============================== warnings summary =============================== 621s cas_server/utils.py:33 621s /tmp/autopkgtest.yJGS4D/autopkgtest_tmp/cas_server/utils.py:33: DeprecationWarning: 'crypt' is deprecated and slated for removal in Python 3.13 621s import crypt 621s 621s cas_server/tests/utils.py:20 621s /tmp/autopkgtest.yJGS4D/autopkgtest_tmp/cas_server/tests/utils.py:20: DeprecationWarning: 'cgi' is deprecated and slated for removal in Python 3.13 621s import cgi 621s 621s cas_server/tests/test_federate.py: 8 warnings 621s cas_server/tests/test_view.py: 3 warnings 621s /tmp/autopkgtest.yJGS4D/autopkgtest_tmp/cas_server/utils.py:208: 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). 621s datetime.utcnow() + timedelta(seconds=max_age), 621s 621s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 621s /usr/lib/python3.12/unittest/case.py:690: DeprecationWarning: It is deprecated to return a value that is not None from a test case (>) 621s return self.run(*args, **kwds) 621s 621s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 621s ====================== 128 passed, 14 warnings in 40.86s ======================= 622s 623s autopkgtest [21:36:20]: test run-test-suite.sh: -----------------------] 624s autopkgtest [21:36:21]: test run-test-suite.sh: - - - - - - - - - - results - - - - - - - - - - 624s run-test-suite.sh PASS 624s autopkgtest [21:36:21]: @@@@@@@@@@@@@@@@@@@@ summary 624s run-test-suite.sh PASS 644s Creating nova instance adt-noble-arm64-django-cas-server-20240322-212557-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-arm64-server-20240322.img (UUID 30e11504-94c3-4021-bd9a-a4301970c682)...