0s autopkgtest [11:11:36]: starting date and time: 2024-03-22 11:11:36+0000 0s autopkgtest [11:11:36]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [11:11:36]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.u3hcnot7/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-s390x-9.secgroup --name adt-noble-s390x-django-cas-server-20240322-111136-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-s390x-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/ 102s autopkgtest [11:13:18]: testbed dpkg architecture: s390x 102s autopkgtest [11:13:18]: testbed apt version: 2.7.12 102s autopkgtest [11:13:18]: @@@@@@@@@@@@@@@@@@@@ test bed setup 103s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 103s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [54.0 kB] 103s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [496 kB] 103s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 103s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3760 kB] 103s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [664 kB] 103s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 103s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 103s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 103s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [3992 kB] 104s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 104s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [45.1 kB] 104s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 106s Fetched 9147 kB in 2s (3662 kB/s) 106s Reading package lists... 108s Reading package lists... 109s Building dependency tree... 109s Reading state information... 109s Calculating upgrade... 109s The following packages will be upgraded: 109s cloud-init debianutils fonts-ubuntu-console python3-markupsafe 109s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 109s Need to get 719 kB of archives. 109s After this operation, 11.3 kB disk space will be freed. 109s Get:1 http://ftpmaster.internal/ubuntu noble/main s390x debianutils s390x 5.17 [90.1 kB] 109s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x fonts-ubuntu-console all 0.869+git20240321-0ubuntu1 [18.7 kB] 109s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x python3-markupsafe s390x 2.1.5-1build1 [12.8 kB] 109s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x cloud-init all 24.1.2-0ubuntu1 [597 kB] 110s Preconfiguring packages ... 110s Fetched 719 kB in 1s (1252 kB/s) 110s (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 ... 52171 files and directories currently installed.) 110s Preparing to unpack .../debianutils_5.17_s390x.deb ... 110s Unpacking debianutils (5.17) over (5.16) ... 110s Setting up debianutils (5.17) ... 110s (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 ... 52171 files and directories currently installed.) 110s Preparing to unpack .../fonts-ubuntu-console_0.869+git20240321-0ubuntu1_all.deb ... 110s Unpacking fonts-ubuntu-console (0.869+git20240321-0ubuntu1) over (0.869-0ubuntu1) ... 110s Preparing to unpack .../python3-markupsafe_2.1.5-1build1_s390x.deb ... 110s Unpacking python3-markupsafe (2.1.5-1build1) over (2.1.5-1) ... 110s Preparing to unpack .../cloud-init_24.1.2-0ubuntu1_all.deb ... 110s Unpacking cloud-init (24.1.2-0ubuntu1) over (24.1.1-0ubuntu1) ... 110s Setting up fonts-ubuntu-console (0.869+git20240321-0ubuntu1) ... 110s Setting up cloud-init (24.1.2-0ubuntu1) ... 112s Setting up python3-markupsafe (2.1.5-1build1) ... 112s Processing triggers for man-db (2.12.0-3) ... 112s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 113s Reading package lists... 113s Building dependency tree... 113s Reading state information... 113s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 113s Unknown architecture, assuming PC-style ttyS0 113s sh: Attempting to set up Debian/Ubuntu apt sources automatically 113s sh: Distribution appears to be Ubuntu 114s Reading package lists... 114s Building dependency tree... 114s Reading state information... 115s eatmydata is already the newest version (131-1). 115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s Reading package lists... 115s Building dependency tree... 115s Reading state information... 115s dbus is already the newest version (1.14.10-4ubuntu1). 115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s Reading package lists... 115s Building dependency tree... 115s Reading state information... 115s rng-tools-debian is already the newest version (2.4). 115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s Reading package lists... 115s Building dependency tree... 115s Reading state information... 116s The following packages will be REMOVED: 116s cloud-init* python3-configobj* python3-debconf* 116s 0 upgraded, 0 newly installed, 3 to remove and 0 not upgraded. 116s After this operation, 3256 kB disk space will be freed. 116s (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 ... 52170 files and directories currently installed.) 116s Removing cloud-init (24.1.2-0ubuntu1) ... 116s Removing python3-configobj (5.0.8-3) ... 116s Removing python3-debconf (1.5.86) ... 116s Processing triggers for man-db (2.12.0-3) ... 117s (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 ... 51781 files and directories currently installed.) 117s Purging configuration files for cloud-init (24.1.2-0ubuntu1) ... 117s dpkg: warning: while removing cloud-init, directory '/etc/cloud/cloud.cfg.d' not empty so not removed 117s Processing triggers for rsyslog (8.2312.0-3ubuntu3) ... 117s invoke-rc.d: policy-rc.d denied execution of try-restart. 117s Reading package lists... 117s Building dependency tree... 117s Reading state information... 118s linux-generic is already the newest version (6.8.0-11.11+1). 118s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 118s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 118s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 118s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 120s Reading package lists... 120s Reading package lists... 120s Building dependency tree... 120s Reading state information... 120s Calculating upgrade... 120s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 120s Reading package lists... 121s Building dependency tree... 121s Reading state information... 121s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 121s autopkgtest [11:13:37]: rebooting testbed after setup commands that affected boot 141s autopkgtest [11:13:57]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 144s autopkgtest [11:14:00]: @@@@@@@@@@@@@@@@@@@@ apt-source django-cas-server 146s Get:1 http://ftpmaster.internal/ubuntu noble/universe django-cas-server 2.0.0-2 (dsc) [2588 B] 146s Get:2 http://ftpmaster.internal/ubuntu noble/universe django-cas-server 2.0.0-2 (tar) [169 kB] 146s Get:3 http://ftpmaster.internal/ubuntu noble/universe django-cas-server 2.0.0-2 (diff) [6480 B] 146s gpgv: Signature made Sat Dec 3 08:42:02 2022 UTC 146s gpgv: using RSA key B70DFC6F134FECFC011E62AA83016014251D1DB0 146s gpgv: Can't check signature: No public key 146s dpkg-source: warning: cannot verify inline signature for ./django-cas-server_2.0.0-2.dsc: no acceptable signature found 146s autopkgtest [11:14:02]: testing package django-cas-server version 2.0.0-2 147s autopkgtest [11:14:03]: build not needed 148s autopkgtest [11:14:04]: test run-test-suite.sh: preparing testbed 149s Reading package lists... 149s Building dependency tree... 149s Reading state information... 149s Starting pkgProblemResolver with broken count: 0 149s Starting 2 pkgProblemResolver with broken count: 0 149s Done 150s The following additional packages will be installed: 150s fonts-font-awesome fonts-lato libjs-jquery libjs-sphinxdoc libjs-underscore 150s libxslt1.1 python3-all python3-asgiref python3-django 150s python3-django-cas-server python3-django-cas-server-doc python3-iniconfig 150s python3-lxml python3-mock python3-packaging python3-pluggy python3-pytest 150s python3-pytest-django python3-requests-futures python3-sqlparse 150s sphinx-rtd-theme-common 150s Suggested packages: 150s bpython3 geoip-database-contrib gettext ipython3 libgdal20 150s libsqlite3-mod-spatialite python-django-doc python3-bcrypt python3-flup 150s python3-memcache python3-mysqldb python3-pil python3-psycopg2 150s python3-selenium python3-sqlite python-lxml-doc python-mock-doc 150s python-sqlparse-doc 150s Recommended packages: 150s javascript-common python3-bs4 python3-cssselect python3-html5lib 150s The following NEW packages will be installed: 150s autopkgtest-satdep fonts-font-awesome fonts-lato libjs-jquery 150s libjs-sphinxdoc libjs-underscore libxslt1.1 python3-all python3-asgiref 150s python3-django python3-django-cas-server python3-django-cas-server-doc 150s python3-iniconfig python3-lxml python3-mock python3-packaging python3-pluggy 150s python3-pytest python3-pytest-django python3-requests-futures 150s python3-sqlparse sphinx-rtd-theme-common 150s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 150s Need to get 12.2 MB/12.2 MB of archives. 150s After this operation, 55.7 MB of additional disk space will be used. 150s Get:1 /tmp/autopkgtest.hu2Ugn/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [748 B] 150s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x fonts-lato all 2.015-1 [2781 kB] 151s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 151s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 151s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 151s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x libjs-sphinxdoc all 7.2.6-4 [149 kB] 151s Get:7 http://ftpmaster.internal/ubuntu noble/main s390x libxslt1.1 s390x 1.1.35-1 [157 kB] 151s Get:8 http://ftpmaster.internal/ubuntu noble/main s390x python3-all s390x 3.12.1-0ubuntu2 [908 B] 151s Get:9 http://ftpmaster.internal/ubuntu noble/main s390x python3-asgiref all 3.7.2-1 [24.8 kB] 151s Get:10 http://ftpmaster.internal/ubuntu noble/main s390x python3-sqlparse all 0.4.4-1 [31.5 kB] 151s Get:11 http://ftpmaster.internal/ubuntu noble/main s390x python3-django all 3:4.2.9-1 [4102 kB] 152s Get:12 http://ftpmaster.internal/ubuntu noble/main s390x python3-lxml s390x 5.1.0-1 [2268 kB] 152s Get:13 http://ftpmaster.internal/ubuntu noble/universe s390x python3-requests-futures all 1.0.1-1 [11.7 kB] 152s Get:14 http://ftpmaster.internal/ubuntu noble/universe s390x python3-django-cas-server all 2.0.0-2 [111 kB] 152s Get:15 http://ftpmaster.internal/ubuntu noble/main s390x sphinx-rtd-theme-common all 2.0.0+dfsg-1 [1012 kB] 152s Get:16 http://ftpmaster.internal/ubuntu noble/universe s390x python3-django-cas-server-doc all 2.0.0-2 [159 kB] 152s Get:17 http://ftpmaster.internal/ubuntu noble/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 152s Get:18 http://ftpmaster.internal/ubuntu noble/main s390x python3-packaging all 23.2-1 [40.6 kB] 152s Get:19 http://ftpmaster.internal/ubuntu noble/universe s390x python3-pluggy all 1.4.0-1 [20.4 kB] 152s Get:20 http://ftpmaster.internal/ubuntu noble/universe s390x python3-pytest all 7.4.4-1 [305 kB] 152s Get:21 http://ftpmaster.internal/ubuntu noble/universe s390x python3-pytest-django all 4.5.2-3 [18.5 kB] 152s Get:22 http://ftpmaster.internal/ubuntu noble/universe s390x python3-mock all 5.1.0-1 [64.1 kB] 153s Fetched 12.2 MB in 3s (4175 kB/s) 153s Selecting previously unselected package fonts-lato. 153s (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 ... 51726 files and directories currently installed.) 153s Preparing to unpack .../00-fonts-lato_2.015-1_all.deb ... 153s Unpacking fonts-lato (2.015-1) ... 153s Selecting previously unselected package fonts-font-awesome. 153s Preparing to unpack .../01-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 153s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 153s Selecting previously unselected package libjs-jquery. 153s Preparing to unpack .../02-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 153s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 153s Selecting previously unselected package libjs-underscore. 153s Preparing to unpack .../03-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 153s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 153s Selecting previously unselected package libjs-sphinxdoc. 153s Preparing to unpack .../04-libjs-sphinxdoc_7.2.6-4_all.deb ... 153s Unpacking libjs-sphinxdoc (7.2.6-4) ... 153s Selecting previously unselected package libxslt1.1:s390x. 153s Preparing to unpack .../05-libxslt1.1_1.1.35-1_s390x.deb ... 153s Unpacking libxslt1.1:s390x (1.1.35-1) ... 153s Selecting previously unselected package python3-all. 153s Preparing to unpack .../06-python3-all_3.12.1-0ubuntu2_s390x.deb ... 153s Unpacking python3-all (3.12.1-0ubuntu2) ... 153s Selecting previously unselected package python3-asgiref. 153s Preparing to unpack .../07-python3-asgiref_3.7.2-1_all.deb ... 153s Unpacking python3-asgiref (3.7.2-1) ... 153s Selecting previously unselected package python3-sqlparse. 153s Preparing to unpack .../08-python3-sqlparse_0.4.4-1_all.deb ... 153s Unpacking python3-sqlparse (0.4.4-1) ... 153s Selecting previously unselected package python3-django. 153s Preparing to unpack .../09-python3-django_3%3a4.2.9-1_all.deb ... 153s Unpacking python3-django (3:4.2.9-1) ... 154s Selecting previously unselected package python3-lxml:s390x. 154s Preparing to unpack .../10-python3-lxml_5.1.0-1_s390x.deb ... 154s Unpacking python3-lxml:s390x (5.1.0-1) ... 154s Selecting previously unselected package python3-requests-futures. 154s Preparing to unpack .../11-python3-requests-futures_1.0.1-1_all.deb ... 154s Unpacking python3-requests-futures (1.0.1-1) ... 154s Selecting previously unselected package python3-django-cas-server. 154s Preparing to unpack .../12-python3-django-cas-server_2.0.0-2_all.deb ... 154s Unpacking python3-django-cas-server (2.0.0-2) ... 154s Selecting previously unselected package sphinx-rtd-theme-common. 154s Preparing to unpack .../13-sphinx-rtd-theme-common_2.0.0+dfsg-1_all.deb ... 154s Unpacking sphinx-rtd-theme-common (2.0.0+dfsg-1) ... 154s Selecting previously unselected package python3-django-cas-server-doc. 154s Preparing to unpack .../14-python3-django-cas-server-doc_2.0.0-2_all.deb ... 154s Unpacking python3-django-cas-server-doc (2.0.0-2) ... 154s Selecting previously unselected package python3-iniconfig. 154s Preparing to unpack .../15-python3-iniconfig_1.1.1-2_all.deb ... 154s Unpacking python3-iniconfig (1.1.1-2) ... 154s Selecting previously unselected package python3-packaging. 154s Preparing to unpack .../16-python3-packaging_23.2-1_all.deb ... 154s Unpacking python3-packaging (23.2-1) ... 154s Selecting previously unselected package python3-pluggy. 154s Preparing to unpack .../17-python3-pluggy_1.4.0-1_all.deb ... 154s Unpacking python3-pluggy (1.4.0-1) ... 154s Selecting previously unselected package python3-pytest. 154s Preparing to unpack .../18-python3-pytest_7.4.4-1_all.deb ... 154s Unpacking python3-pytest (7.4.4-1) ... 154s Selecting previously unselected package python3-pytest-django. 154s Preparing to unpack .../19-python3-pytest-django_4.5.2-3_all.deb ... 154s Unpacking python3-pytest-django (4.5.2-3) ... 154s Selecting previously unselected package python3-mock. 154s Preparing to unpack .../20-python3-mock_5.1.0-1_all.deb ... 154s Unpacking python3-mock (5.1.0-1) ... 154s Selecting previously unselected package autopkgtest-satdep. 154s Preparing to unpack .../21-1-autopkgtest-satdep.deb ... 154s Unpacking autopkgtest-satdep (0) ... 154s Setting up python3-iniconfig (1.1.1-2) ... 154s Setting up fonts-lato (2.015-1) ... 154s Setting up python3-all (3.12.1-0ubuntu2) ... 154s Setting up python3-mock (5.1.0-1) ... 155s Setting up python3-packaging (23.2-1) ... 155s Setting up python3-sqlparse (0.4.4-1) ... 155s Setting up python3-pluggy (1.4.0-1) ... 155s Setting up libxslt1.1:s390x (1.1.35-1) ... 155s Setting up python3-requests-futures (1.0.1-1) ... 155s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 155s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 155s Setting up sphinx-rtd-theme-common (2.0.0+dfsg-1) ... 155s Setting up python3-asgiref (3.7.2-1) ... 155s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 155s Setting up python3-django (3:4.2.9-1) ... 156s Setting up python3-pytest (7.4.4-1) ... 156s Setting up python3-pytest-django (4.5.2-3) ... 156s Setting up libjs-sphinxdoc (7.2.6-4) ... 156s Setting up python3-lxml:s390x (5.1.0-1) ... 157s Setting up python3-django-cas-server (2.0.0-2) ... 157s Setting up python3-django-cas-server-doc (2.0.0-2) ... 157s Setting up autopkgtest-satdep (0) ... 157s Processing triggers for man-db (2.12.0-3) ... 157s Processing triggers for libc-bin (2.39-0ubuntu2) ... 160s (Reading database ... 58544 files and directories currently installed.) 160s Removing autopkgtest-satdep (0) ... 160s autopkgtest [11:14:16]: test run-test-suite.sh: [----------------------- 161s 161s *************************** 161s *** Testing with python3.12 161s *************************** 161s 161s Content of current working folder: 161s 161s total 20 161s drwxr-xr-x 3 ubuntu ubuntu 4096 Mar 22 11:14 . 161s drwxrwxrwt 5 root root 4096 Mar 22 11:14 .. 161s drwxrwxr-x 9 ubuntu ubuntu 4096 Oct 17 2022 cas_server 161s -rw-rw-r-- 1 ubuntu ubuntu 140 Oct 17 2022 pytest.ini 161s -rw-rw-r-- 1 ubuntu ubuntu 152 Oct 17 2022 setup.cfg 161s Running tests... 161s 161s ============================= test session starts ============================== 161s platform linux -- Python 3.12.2, pytest-7.4.4, pluggy-1.4.0 -- /usr/bin/python3.12 161s cachedir: .pytest_cache 161s django: settings: cas_server.tests.settings (from ini) 161s rootdir: /tmp/autopkgtest.hu2Ugn/autopkgtest_tmp 161s configfile: pytest.ini 161s testpaths: cas_server/tests/ 161s plugins: django-4.5.2 161s collecting ... collected 128 items 161s 161s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 162s 2024-03-22 11:14:16,608 WARNING Identity provider suffix exemple.fr not found 162s 2024-03-22 11:14:16,654 INFO Got an invalid ticket ST-enN3Y3TEG3DAwFZIUNb8nM2MncnP2wpH3tLanvxRHTDCc3iXZWwEIcxR3n5s2 from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 162s 2024-03-22 11:14:16,657 INFO Got an invalid ticket ST-oW3luuUZoAAZv75DbmT2JzcqxdlaU3RLb9kl1130ZNubaeoYwxpXVD4d5YJ3k from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 162s PASSED 162s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2024-03-22 11:14:16,689 WARNING CAS_FEDERATE is False, set it to True to use federation 162s 2024-03-22 11:14:16,689 WARNING CAS_FEDERATE is False, set it to True to use federation 162s PASSED 162s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2024-03-22 11:14:16,702 INFO Trying to authenticate http://127.0.0.1:8080 again 162s 2024-03-22 11:14:16,705 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 162s 2024-03-22 11:14:16,718 INFO User dédé@example.com successfully authenticated 162s 2024-03-22 11:14:16,727 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 162s 2024-03-22 11:14:16,736 INFO Trying to authenticate http://127.0.0.1:8081 again 162s 2024-03-22 11:14:16,740 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 162s 2024-03-22 11:14:16,749 INFO User dédé@example.org successfully authenticated 162s 2024-03-22 11:14:16,756 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 162s 2024-03-22 11:14:16,764 INFO Trying to authenticate http://127.0.0.1:8082 again 162s 2024-03-22 11:14:16,767 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 162s 2024-03-22 11:14:16,775 INFO User dédé@example.net successfully authenticated 162s 2024-03-22 11:14:16,782 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 162s 2024-03-22 11:14:16,791 INFO Trying to authenticate http://127.0.0.1:8083 again 162s 2024-03-22 11:14:16,795 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 162s 2024-03-22 11:14:16,803 INFO User dédé@example.test successfully authenticated 162s 2024-03-22 11:14:16,809 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 162s 2024-03-22 11:14:16,840 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-5892AsF2Pk6IZCMzgWBme8VUHpFlOoT0V5m2RNkvih3gxRkZcuMZ41LGP8SbZ 162s 2024-03-22 11:14:16,858 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-GXLyWK5LqgMfuVy8yApBD8KlPy0YLFpbkXps0osdDIz2Ex4zM9auTsgrWFshH 162s PASSED 162s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 162s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2024-03-22 11:14:16,885 INFO Trying to authenticate http://127.0.0.1:8080 again 162s 2024-03-22 11:14:16,888 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 162s 2024-03-22 11:14:16,899 INFO User dédé@example.com successfully authenticated 162s 2024-03-22 11:14:16,908 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 162s 2024-03-22 11:14:16,917 INFO Trying to authenticate http://127.0.0.1:8081 again 162s 2024-03-22 11:14:16,919 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 162s 2024-03-22 11:14:16,928 INFO User dédé@example.org successfully authenticated 162s 2024-03-22 11:14:16,943 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 162s 2024-03-22 11:14:16,956 INFO Trying to authenticate http://127.0.0.1:8082 again 162s 2024-03-22 11:14:16,959 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 162s 2024-03-22 11:14:16,967 INFO User dédé@example.net successfully authenticated 162s 2024-03-22 11:14:16,974 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 162s 2024-03-22 11:14:16,982 INFO Trying to authenticate http://127.0.0.1:8083 again 162s 2024-03-22 11:14:16,985 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 162s 2024-03-22 11:14:16,995 INFO User dédé@example.test successfully authenticated 162s 2024-03-22 11:14:17,002 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 162s 2024-03-22 11:14:17,003 INFO logout requested 162s 2024-03-22 11:14:17,004 INFO Logging out user dédé@example.com. 162s 2024-03-22 11:14:17,008 INFO User dédé@example.com logged out 162s 2024-03-22 11:14:17,014 INFO logout requested 162s 2024-03-22 11:14:17,016 INFO logout requested 162s 2024-03-22 11:14:17,018 INFO Logging out user dédé@example.org. 162s 2024-03-22 11:14:17,021 INFO User dédé@example.org logged out 162s 2024-03-22 11:14:17,032 INFO logout requested 162s 2024-03-22 11:14:17,034 INFO logout requested 162s 2024-03-22 11:14:17,036 INFO Logging out user dédé@example.net. 162s 2024-03-22 11:14:17,039 INFO User dédé@example.net logged out 162s 2024-03-22 11:14:17,047 INFO logout requested 162s 2024-03-22 11:14:17,049 INFO logout requested 162s 2024-03-22 11:14:17,050 INFO Logging out user dédé@example.test. 162s 2024-03-22 11:14:17,054 INFO User dédé@example.test logged out 162s 2024-03-22 11:14:17,061 INFO logout requested 162s 2024-03-22 11:14:17,075 INFO Trying to authenticate http://127.0.0.1:8080 again 162s 2024-03-22 11:14:17,081 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 162s 2024-03-22 11:14:17,091 INFO User dédé@example.com successfully authenticated 162s 2024-03-22 11:14:17,103 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 162s 2024-03-22 11:14:17,118 INFO Trying to authenticate http://127.0.0.1:8081 again 162s 2024-03-22 11:14:17,121 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 162s 2024-03-22 11:14:17,130 INFO User dédé@example.org successfully authenticated 162s 2024-03-22 11:14:17,136 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 162s 2024-03-22 11:14:17,145 INFO Trying to authenticate http://127.0.0.1:8082 again 162s 2024-03-22 11:14:17,152 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 162s 2024-03-22 11:14:17,160 INFO User dédé@example.net successfully authenticated 162s 2024-03-22 11:14:17,172 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 162s 2024-03-22 11:14:17,181 INFO Trying to authenticate http://127.0.0.1:8083 again 162s 2024-03-22 11:14:17,185 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 162s 2024-03-22 11:14:17,192 INFO User dédé@example.test successfully authenticated 162s 2024-03-22 11:14:17,199 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 162s 2024-03-22 11:14:17,200 INFO logout requested 162s 2024-03-22 11:14:17,201 INFO Logging out user dédé. 162s 2024-03-22 11:14:17,202 INFO User dédé logged out 162s 2024-03-22 11:14:17,211 INFO logout requested 162s 2024-03-22 11:14:17,212 INFO Logging out user dédé. 162s 2024-03-22 11:14:17,212 INFO User dédé logged out 162s 2024-03-22 11:14:17,220 INFO logout requested 162s 2024-03-22 11:14:17,220 INFO Logging out user dédé. 162s 2024-03-22 11:14:17,221 INFO User dédé logged out 162s 2024-03-22 11:14:17,228 INFO logout requested 162s 2024-03-22 11:14:17,229 INFO Logging out user dédé. 162s 2024-03-22 11:14:17,230 INFO User dédé logged out 162s PASSED 162s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2024-03-22 11:14:17,248 INFO Trying to authenticate http://127.0.0.1:8080 again 162s 2024-03-22 11:14:17,250 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 162s 2024-03-22 11:14:17,258 INFO User dédé@example.com successfully authenticated 162s 2024-03-22 11:14:17,264 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 162s 2024-03-22 11:14:17,272 INFO Trying to authenticate http://127.0.0.1:8081 again 162s 2024-03-22 11:14:17,275 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 162s 2024-03-22 11:14:17,284 INFO User dédé@example.org successfully authenticated 162s 2024-03-22 11:14:17,291 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 162s 2024-03-22 11:14:17,299 INFO Trying to authenticate http://127.0.0.1:8082 again 162s 2024-03-22 11:14:17,302 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 162s 2024-03-22 11:14:17,309 INFO User dédé@example.net successfully authenticated 162s 2024-03-22 11:14:17,316 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 162s 2024-03-22 11:14:17,330 INFO Trying to authenticate http://127.0.0.1:8083 again 162s 2024-03-22 11:14:17,333 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 162s 2024-03-22 11:14:17,342 INFO User dédé@example.test successfully authenticated 162s 2024-03-22 11:14:17,348 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 162s 2024-03-22 11:14:17,349 INFO logout requested 162s 2024-03-22 11:14:17,350 INFO Logging out user dédé@example.com. 162s 2024-03-22 11:14:17,354 INFO User dédé@example.com logged out 162s 2024-03-22 11:14:17,354 INFO logout requested 162s 2024-03-22 11:14:17,356 INFO Logging out user dédé@example.org. 162s 2024-03-22 11:14:17,359 INFO User dédé@example.org logged out 162s 2024-03-22 11:14:17,359 INFO logout requested 162s 2024-03-22 11:14:17,361 INFO Logging out user dédé@example.net. 162s 2024-03-22 11:14:17,364 INFO User dédé@example.net logged out 162s 2024-03-22 11:14:17,364 INFO logout requested 162s 2024-03-22 11:14:17,376 INFO Logging out user dédé@example.test. 162s 2024-03-22 11:14:17,383 INFO User dédé@example.test logged out 162s PASSED 162s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2024-03-22 11:14:17,393 WARNING A login attempt failed 162s PASSED 162s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 163s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2024-03-22 11:14:17,444 INFO Trying to authenticate http://127.0.0.1:8080 again 163s 2024-03-22 11:14:17,455 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 163s 2024-03-22 11:14:17,464 INFO User dédé@example.com successfully authenticated 163s 2024-03-22 11:14:17,473 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 163s 2024-03-22 11:14:17,481 INFO Trying to authenticate http://127.0.0.1:8081 again 163s 2024-03-22 11:14:17,484 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 163s 2024-03-22 11:14:17,492 INFO User dédé@example.org successfully authenticated 163s 2024-03-22 11:14:17,498 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 163s 2024-03-22 11:14:17,507 INFO Trying to authenticate http://127.0.0.1:8082 again 163s 2024-03-22 11:14:17,510 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 163s 2024-03-22 11:14:17,517 INFO User dédé@example.net successfully authenticated 163s 2024-03-22 11:14:17,523 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 163s 2024-03-22 11:14:17,532 INFO Trying to authenticate http://127.0.0.1:8083 again 163s 2024-03-22 11:14:17,535 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 163s 2024-03-22 11:14:17,542 INFO User dédé@example.test successfully authenticated 163s 2024-03-22 11:14:17,549 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 163s PASSED 163s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2024-03-22 11:14:17,561 INFO Trying to authenticate http://127.0.0.1:8080 again 163s 2024-03-22 11:14:17,563 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 163s 2024-03-22 11:14:17,570 INFO User dédé@example.com successfully authenticated 163s 2024-03-22 11:14:17,577 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 163s 2024-03-22 11:14:17,585 INFO Trying to authenticate http://127.0.0.1:8081 again 163s 2024-03-22 11:14:17,588 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 163s 2024-03-22 11:14:17,598 INFO User dédé@example.org successfully authenticated 163s 2024-03-22 11:14:17,606 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 163s 2024-03-22 11:14:17,615 INFO Trying to authenticate http://127.0.0.1:8082 again 163s 2024-03-22 11:14:17,619 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 163s 2024-03-22 11:14:17,626 INFO User dédé@example.net successfully authenticated 163s 2024-03-22 11:14:17,633 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 163s 2024-03-22 11:14:17,641 INFO Trying to authenticate http://127.0.0.1:8083 again 163s 2024-03-22 11:14:17,644 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 163s 2024-03-22 11:14:17,652 INFO User dédé@example.test successfully authenticated 163s 2024-03-22 11:14:17,658 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 163s 2024-03-22 11:14:17,666 INFO Trying to authenticate http://127.0.0.1:8080 again 163s 2024-03-22 11:14:17,669 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 163s 2024-03-22 11:14:17,676 INFO User dédé@example.com successfully authenticated 163s 2024-03-22 11:14:17,683 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 163s 2024-03-22 11:14:17,691 INFO Trying to authenticate http://127.0.0.1:8081 again 163s 2024-03-22 11:14:17,695 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 163s 2024-03-22 11:14:17,702 INFO User dédé@example.org successfully authenticated 163s 2024-03-22 11:14:17,709 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 163s 2024-03-22 11:14:17,717 INFO Trying to authenticate http://127.0.0.1:8082 again 163s 2024-03-22 11:14:17,720 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 163s 2024-03-22 11:14:17,727 INFO User dédé@example.net successfully authenticated 163s 2024-03-22 11:14:17,735 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 163s 2024-03-22 11:14:17,765 INFO Trying to authenticate http://127.0.0.1:8083 again 163s 2024-03-22 11:14:17,769 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 163s 2024-03-22 11:14:17,776 INFO User dédé@example.test successfully authenticated 163s 2024-03-22 11:14:17,782 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 163s 2024-03-22 11:14:17,784 WARNING User already authenticated, dropping federated authentication request 163s 2024-03-22 11:14:17,784 WARNING User already authenticated, dropping federated authentication request 163s 2024-03-22 11:14:17,785 WARNING User already authenticated, dropping federated authentication request 163s 2024-03-22 11:14:17,786 WARNING User already authenticated, dropping federated authentication request 163s PASSED 163s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2024-03-22 11:14:17,797 INFO Trying to authenticate http://127.0.0.1:8080 again 163s 2024-03-22 11:14:17,800 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 163s 2024-03-22 11:14:17,807 INFO User dédé@example.com successfully authenticated 163s 2024-03-22 11:14:17,814 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 163s 2024-03-22 11:14:17,822 INFO Trying to authenticate http://127.0.0.1:8081 again 163s 2024-03-22 11:14:17,825 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 163s 2024-03-22 11:14:17,832 INFO User dédé@example.org successfully authenticated 163s 2024-03-22 11:14:17,839 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 163s 2024-03-22 11:14:17,847 INFO Trying to authenticate http://127.0.0.1:8082 again 163s 2024-03-22 11:14:17,849 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 163s 2024-03-22 11:14:17,857 INFO User dédé@example.net successfully authenticated 163s 2024-03-22 11:14:17,863 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 163s 2024-03-22 11:14:17,872 INFO Trying to authenticate http://127.0.0.1:8083 again 163s 2024-03-22 11:14:17,875 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 163s 2024-03-22 11:14:17,883 INFO User dédé@example.test successfully authenticated 163s 2024-03-22 11:14:17,889 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 163s 2024-03-22 11:14:17,890 INFO logout requested 163s 2024-03-22 11:14:17,891 INFO Logging out user dédé@example.com. 163s 2024-03-22 11:14:17,895 INFO User dédé@example.com logged out 163s 2024-03-22 11:14:17,897 INFO logout requested 163s 2024-03-22 11:14:17,898 INFO Logging out user dédé@example.org. 163s 2024-03-22 11:14:17,902 INFO User dédé@example.org logged out 163s 2024-03-22 11:14:17,904 INFO logout requested 163s 2024-03-22 11:14:17,905 INFO Logging out user dédé@example.net. 163s 2024-03-22 11:14:17,908 INFO User dédé@example.net logged out 163s 2024-03-22 11:14:17,910 INFO logout requested 163s 2024-03-22 11:14:17,912 INFO Logging out user dédé@example.test. 163s 2024-03-22 11:14:17,915 INFO User dédé@example.test logged out 163s PASSED 163s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2024-03-22 11:14:17,927 INFO Trying to authenticate http://127.0.0.1:8080 again 163s 2024-03-22 11:14:17,930 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 163s 2024-03-22 11:14:17,937 INFO User dédé@example.com successfully authenticated 163s 2024-03-22 11:14:17,943 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 163s 2024-03-22 11:14:17,951 INFO Trying to authenticate http://127.0.0.1:8081 again 163s 2024-03-22 11:14:17,954 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 163s 2024-03-22 11:14:17,961 INFO User dédé@example.org successfully authenticated 163s 2024-03-22 11:14:17,967 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 163s 2024-03-22 11:14:17,975 INFO Trying to authenticate http://127.0.0.1:8082 again 163s 2024-03-22 11:14:17,978 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 163s 2024-03-22 11:14:17,985 INFO User dédé@example.net successfully authenticated 163s 2024-03-22 11:14:17,991 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 163s 2024-03-22 11:14:18,000 INFO Trying to authenticate http://127.0.0.1:8083 again 163s 2024-03-22 11:14:18,003 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 163s 2024-03-22 11:14:18,010 INFO User dédé@example.test successfully authenticated 163s 2024-03-22 11:14:18,016 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 163s 2024-03-22 11:14:18,020 INFO Trying to authenticate http://127.0.0.1:8080 again 163s 2024-03-22 11:14:18,022 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 163s 2024-03-22 11:14:18,030 INFO User dédé@example.com successfully authenticated 163s 2024-03-22 11:14:18,034 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 163s 2024-03-22 11:14:18,038 INFO Trying to authenticate http://127.0.0.1:8081 again 163s 2024-03-22 11:14:18,041 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 163s 2024-03-22 11:14:18,048 INFO User dédé@example.org successfully authenticated 163s 2024-03-22 11:14:18,052 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 163s 2024-03-22 11:14:18,056 INFO Trying to authenticate http://127.0.0.1:8082 again 163s 2024-03-22 11:14:18,059 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 163s 2024-03-22 11:14:18,067 INFO User dédé@example.net successfully authenticated 163s 2024-03-22 11:14:18,070 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 163s 2024-03-22 11:14:18,074 INFO Trying to authenticate http://127.0.0.1:8083 again 163s 2024-03-22 11:14:18,077 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 163s 2024-03-22 11:14:18,085 INFO User dédé@example.test successfully authenticated 163s 2024-03-22 11:14:18,088 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 163s PASSED 163s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 163s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 163s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 163s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2024-03-22 11:14:18,121 INFO User test successfully authenticated 163s PASSED 163s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2024-03-22 11:14:18,134 INFO User test successfully authenticated 163s PASSED 163s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2024-03-22 11:14:18,144 INFO User test successfully authenticated 163s 2024-03-22 11:14:18,151 INFO User test successfully authenticated 163s 2024-03-22 11:14:18,159 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 163s 2024-03-22 11:14:18,161 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')) 163s PASSED 163s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2024-03-22 11:14:18,170 INFO User test successfully authenticated 163s PASSED 163s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2024-03-22 11:14:18,183 INFO User test successfully authenticated 163s PASSED 163s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2024-03-22 11:14:18,197 INFO User test successfully authenticated 163s 2024-03-22 11:14:18,206 INFO Sending SLO requests to service http://127.0.0.1:34031 for user test 163s 2024-03-22 11:14:18,207 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 163s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 163s PASSED 163s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2024-03-22 11:14:18,218 INFO User test successfully authenticated 163s PASSED 163s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 163s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 163s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 163s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 163s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 163s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 163s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 163s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 163s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 163s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 163s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 163s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 163s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 163s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 163s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 163s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 163s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 163s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 163s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 164s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 164s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 164s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 164s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2024-03-22 11:14:19,304 INFO User test successfully authenticated 164s 2024-03-22 11:14:19,308 WARNING Service https://www.example.org not allowed. 164s PASSED 164s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success 2024-03-22 11:14:19,319 INFO User test successfully authenticated 164s PASSED 164s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt PASSED 164s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn PASSED 164s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged 2024-03-22 11:14:19,325 INFO Service ticket created for service https://www.example.com by user test. 164s 2024-03-22 11:14:19,341 INFO User test successfully authenticated 164s 2024-03-22 11:14:19,345 INFO Service ticket created for service https://www.example.com by user test. 164s 2024-03-22 11:14:19,355 INFO User test successfully authenticated 164s 2024-03-22 11:14:19,368 INFO User test successfully authenticated 164s PASSED 164s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2024-03-22 11:14:19,383 INFO User test successfully authenticated 164s 2024-03-22 11:14:19,387 WARNING User test seems authenticated but is not found in the database. 164s 2024-03-22 11:14:19,388 INFO Logging out user test. 164s 2024-03-22 11:14:19,388 INFO User test logged out 164s PASSED 164s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 164s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2024-03-22 11:14:19,401 INFO User test successfully authenticated 164s 2024-03-22 11:14:19,405 WARNING Username test not allowed on service restrict_user_fail 164s PASSED 164s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2024-03-22 11:14:19,419 WARNING A login attempt failed 164s 2024-03-22 11:14:19,435 WARNING A login attempt failed 165s 2024-03-22 11:14:19,446 WARNING A login attempt failed 165s PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2024-03-22 11:14:19,462 WARNING Received an invalid login ticket 165s PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2024-03-22 11:14:19,475 WARNING A login attempt failed 165s PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2024-03-22 11:14:19,490 INFO User test successfully authenticated 165s PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2024-03-22 11:14:19,506 INFO User test successfully authenticated 165s PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled 2024-03-22 11:14:20,282 INFO User test successfully authenticated 165s 2024-03-22 11:14:20,290 INFO User test successfully authenticated 165s 2024-03-22 11:14:20,293 INFO Service ticket created for service https://www.example.com by user test. 165s 2024-03-22 11:14:20,302 INFO User test successfully authenticated 165s 2024-03-22 11:14:20,312 INFO User test successfully authenticated 165s PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_renew PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2024-03-22 11:14:20,314 INFO Service ticket created for service https://www.example.com by user test. 165s PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2024-03-22 11:14:20,325 INFO User test successfully authenticated 165s 2024-03-22 11:14:20,329 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 165s 2024-03-22 11:14:20,331 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 165s 2024-03-22 11:14:20,335 INFO Service ticket created for service https://filter_success.example.com by user test. 165s 2024-03-22 11:14:20,344 INFO User test successfully authenticated 165s 2024-03-22 11:14:20,348 WARNING Username test not allowed on service restrict_user_fail 165s 2024-03-22 11:14:20,352 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 165s 2024-03-22 11:14:20,361 INFO User test successfully authenticated 165s 2024-03-22 11:14:20,365 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 165s 2024-03-22 11:14:20,369 INFO Service ticket created for service https://field_needed_success.example.com by user test. 165s 2024-03-22 11:14:20,380 INFO User test successfully authenticated 165s 2024-03-22 11:14:20,386 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 165s 2024-03-22 11:14:20,396 INFO User test successfully authenticated 165s 2024-03-22 11:14:20,400 WARNING User test seems authenticated but is not found in the database. 165s 2024-03-22 11:14:20,400 INFO Logging out user test. 165s 2024-03-22 11:14:20,401 INFO User test logged out 165s 2024-03-22 11:14:20,425 INFO User test successfully authenticated 165s PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 165s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2024-03-22 11:14:20,430 INFO Service ticket created for service https://www.example.com/é by user test. 165s PASSED 166s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2024-03-22 11:14:20,441 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,446 INFO Service ticket created for service https://www.example.com by user test. 166s PASSED 166s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2024-03-22 11:14:20,458 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,469 WARNING Received a login attempt for an already-active user 166s 2024-03-22 11:14:20,471 INFO Service ticket created for service https://www.example.com by user test. 166s PASSED 166s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2024-03-22 11:14:20,481 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,485 WARNING Service https://www.example.org not allowed. 166s PASSED 166s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2024-03-22 11:14:20,489 WARNING Service https://www.example.net not allowed. 166s PASSED 166s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2024-03-22 11:14:20,498 WARNING Service https://www.example.net not allowed. 166s PASSED 166s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2024-03-22 11:14:20,510 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,512 INFO logout requested 166s PASSED 166s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2024-03-22 11:14:20,513 INFO Logging out user test. 166s 2024-03-22 11:14:20,516 INFO User test logged out 166s 2024-03-22 11:14:20,523 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,531 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,538 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,547 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,554 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,562 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,570 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,577 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,585 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,592 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,596 INFO logout requested 166s 2024-03-22 11:14:20,598 INFO Logging out user test from all sessions. 166s PASSED2024-03-22 11:14:20,625 INFO User test logged out 166s 166s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2024-03-22 11:14:20,634 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,637 INFO logout requested 166s 2024-03-22 11:14:20,638 INFO Logging out user test. 166s 2024-03-22 11:14:20,641 INFO User test logged out 166s PASSED 166s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2024-03-22 11:14:20,643 INFO logout requested 166s PASSED 166s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2024-03-22 11:14:20,652 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,662 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,664 INFO logout requested 166s 2024-03-22 11:14:20,666 INFO Logging out user test from all sessions. 166s PASSED 166s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2024-03-22 11:14:20,671 INFO User test logged out 166s 2024-03-22 11:14:20,682 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,687 INFO Service ticket created for service http://127.0.0.1:38175 by user test. 166s 2024-03-22 11:14:20,690 INFO Validate: Service ticket ST-7iZG4RgCf00BClLQG5kk5IocB9VEItfnyhMtGKjFf236IojfV2QSUnS8499oL validated, user test authenticated on service http://127.0.0.1:38175 166s 2024-03-22 11:14:20,691 INFO logout requested 166s 2024-03-22 11:14:20,692 INFO Logging out user test. 166s 2024-03-22 11:14:20,694 INFO Sending SLO requests to service http://127.0.0.1:38175 for user test 166s 2024-03-22 11:14:20,696 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 166s 2024-03-22 11:14:20,697 INFO User test logged out 166s 2024-03-22 11:14:20,704 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,709 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 166s 2024-03-22 11:14:20,711 INFO Validate: Service ticket ST-KqBX8FrYVBKcZwTZKMEJW1iTIsEicjSHDmq1nHNZSFvdcGQXh1EAYVUo5Pbfg validated, user test authenticated on service http://127.0.0.1:45678 166s 2024-03-22 11:14:20,712 INFO logout requested 166s 2024-03-22 11:14:20,713 INFO Logging out user test. 166s 2024-03-22 11:14:20,715 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 166s 2024-03-22 11:14:20,717 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 166s 2024-03-22 11:14:20,718 INFO User test logged out 166s 2024-03-22 11:14:20,725 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,731 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 166s 2024-03-22 11:14:20,735 INFO Validate: Service ticket ST-nhSFYQts9t9Xx2RYpdudtX6Q3j15Ih72l1hLDDh34bumnhrVHVySyKGVIvq0f validated, user test authenticated on service http://127.0.0.1:45678 166s 2024-03-22 11:14:20,736 INFO logout requested 166s 2024-03-22 11:14:20,737 INFO Logging out user test. 166s 2024-03-22 11:14:20,742 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 166s 2024-03-22 11:14:20,743 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=38367): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 166s 2024-03-22 11:14:20,744 INFO User test logged out 166s PASSED 166s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2024-03-22 11:14:20,752 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,760 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,765 INFO logout requested 166s 2024-03-22 11:14:20,766 INFO Logging out user test. 166s 2024-03-22 11:14:20,769 INFO User test logged out 166s PASSED 166s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service PASSED 166s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2024-03-22 11:14:20,782 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,784 INFO logout requested 166s 2024-03-22 11:14:20,785 INFO Logging out user test. 166s 2024-03-22 11:14:20,788 INFO User test logged out 166s 2024-03-22 11:14:20,799 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,802 INFO logout requested 166s 2024-03-22 11:14:20,802 INFO Logging out user test. 166s 2024-03-22 11:14:20,805 INFO User test logged out 166s PASSED 166s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2024-03-22 11:14:20,823 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,826 INFO logout requested 166s 2024-03-22 11:14:20,826 INFO Logging out user test. 166s 2024-03-22 11:14:20,829 INFO User test logged out 166s PASSED 166s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2024-03-22 11:14:20,838 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,840 INFO logout requested 166s 2024-03-22 11:14:20,841 INFO Logging out user test. 166s 2024-03-22 11:14:20,844 INFO User test logged out 166s 2024-03-22 11:14:20,849 INFO logout requested 166s PASSED 166s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 166s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 166s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice 2024-03-22 11:14:20,862 WARNING Service https://www.example.org not allowed. 166s PASSED 166s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings PASSED 166s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 166s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2024-03-22 11:14:20,875 INFO User test successfully authenticated 166s PASSED 166s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 166s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2024-03-22 11:14:20,889 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,898 INFO Service ticket created for service https://www.example.com by user test. 166s 2024-03-22 11:14:20,902 WARNING Validate: service or ticket missing 166s PASSED 166s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2024-03-22 11:14:20,902 WARNING Validate: service or ticket missing 166s 2024-03-22 11:14:20,909 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,912 INFO Service ticket created for service https://www.example.com by user test. 166s 2024-03-22 11:14:20,915 INFO Validate: Service ticket ST-syZ5ELVuwxHfxQZ0eUR47GiGxvapWFLBUuY3IARfeyXT31OpcYoQ2gNOIZ26P validated, user test authenticated on service https://www.example.com 166s 2024-03-22 11:14:20,920 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,923 INFO Service ticket created for service https://www.example.com by user test. 166s 2024-03-22 11:14:20,925 INFO Validate: Service ticket ST-LNLG46CN8sJwPmZvXL7uTFBcThDFXuhigmbxeDZTid3f4UtZA2ZSRmE0bVdPl validated, user test authenticated on service https://www.example.com 166s 2024-03-22 11:14:20,929 INFO Service ticket created for service https://www.example.com by user test. 166s 2024-03-22 11:14:20,930 WARNING Validate: Service ticket ST-BYzaRiHDQKk72nsB0209ghs3xtOQZM4znmIlpjER6ZIdf65TLKgHNULNALd3e not found or already validated, auth to https://www.example.com failed 166s PASSED 166s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2024-03-22 11:14:20,937 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,943 INFO Service ticket created for service https://user_field.example.com by user test. 166s 2024-03-22 11:14:20,945 INFO Validate: Service ticket ST-z734oJGSaFy66ICT8xrp0s7XdB91kACR007iGpFgzdkzv98DxdGBKaUlTKB0b validated, user test authenticated on service https://user_field.example.com 166s 2024-03-22 11:14:20,978 INFO User test successfully authenticated 166s 2024-03-22 11:14:20,986 INFO Service ticket created for service https://user_field_alt.example.com by user test. 166s 2024-03-22 11:14:20,989 INFO Validate: Service ticket ST-WNDzQmDH7Jb9LgDuQdvTxalAQxMUFhqtWIaRXJEMHYNikt9c5VuiA5cFJzUQb validated, user test authenticated on service https://user_field_alt.example.com 166s PASSED 166s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2024-03-22 11:14:20,996 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,001 INFO Service ticket created for service https://www.example.com by user test. 166s 2024-03-22 11:14:21,003 WARNING Validate: Service ticket ST-8m8dmca0KxHrfARfBJren88nEc2tzfEEfPDGl3flzOYGjsDLbYuxYq5N4qBhD not found or already validated, auth to https://www.example.org failed 166s PASSED 166s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2024-03-22 11:14:21,010 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,015 INFO Service ticket created for service https://www.example.com by user test. 166s 2024-03-22 11:14:21,017 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 166s PASSED 166s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2024-03-22 11:14:21,024 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,028 INFO Service ticket created for service https://www.example.com by user test. 166s 2024-03-22 11:14:21,031 INFO Validate: Service ticket ST-maaB8bHGVnw9cw44WGWCle1OAPkMi5TYkvVCm7E7LiFY6YVhsKlux1SK8dL31 validated, user test authenticated on service https://www.example.com 166s PASSED 166s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2024-03-22 11:14:21,040 INFO User test successfully authenticated 166s PASSED 166s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2024-03-22 11:14:21,045 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 166s 2024-03-22 11:14:21,046 WARNING ValidateService: missing ticket or service 166s 2024-03-22 11:14:21,047 WARNING ValidateService: missing ticket or service 166s 2024-03-22 11:14:21,056 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,060 INFO Service ticket created for service http://127.0.0.1:44475 by user test. 166s 2024-03-22 11:14:21,065 INFO ValidateService: ticket ST-Y5Q5TdDKXfKn0TTHnxJmBYGjcUEOF03b33vVCnbKJx28nFq2yYH7jTIPcEpdw validated for user test on service http://127.0.0.1:44475. Proxy Granting Ticket transmited to http://127.0.0.1:44475. 166s 2024-03-22 11:14:21,069 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 166s 2024-03-22 11:14:21,072 INFO ValidateService: ticket PT-uGFstBpqaCAc22SVJsY1cGbTEyiyMDlrGH9ljbOWk2URWGtW0kUQkw5ENz54x validated for user test on service http://127.0.0.1:45678. 166s 2024-03-22 11:14:21,078 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,083 INFO Service ticket created for service http://127.0.0.1:38121 by user test. 166s 2024-03-22 11:14:21,088 INFO ValidateService: ticket ST-GpwFeOIVlcQ9h86uBFLyiegwbjNvVRqJicxmCrTwUr9wbiuRZXVdBb2TRqP2K validated for user test on service http://127.0.0.1:38121. Proxy Granting Ticket transmited to http://127.0.0.1:38121. 166s 2024-03-22 11:14:21,091 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 166s 2024-03-22 11:14:21,092 WARNING ValidateService: validation error: INVALID_TICKET PT-cLrAm4YUhQtBOwufh0FjJOEfDCBHqaPdOswRhEqX9zQkCz8l4olCmGKDNxZhP 166s PASSED 166s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2024-03-22 11:14:21,100 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,106 INFO Service ticket created for service https://replace_attribute.example.com by user test. 166s 2024-03-22 11:14:21,109 INFO ValidateService: ticket ST-5kCCgsVdu8L79kFnMfDkCZcLRxig4ZfOusN61FPWosv2kwPEe4lreADmUzAsW validated for user test on service https://replace_attribute.example.com. 166s 2024-03-22 11:14:21,115 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,120 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 166s 2024-03-22 11:14:21,122 INFO ValidateService: ticket ST-WS9ExZmbXnyPd7WbljmsKcxxQYM6NAASOSSgsWZCGgDZdzPVQiwFGKGme2Kjo validated for user test on service https://replace_attribute_list.example.com. 166s PASSED 166s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2024-03-22 11:14:21,131 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,136 INFO Service ticket created for service http://127.0.0.1:34765 by user test. 166s 2024-03-22 11:14:21,141 INFO ValidateService: ticket ST-8czitWW7JQPr5BUR3jAyE9YWM0xVqQmEmBk6i7GAV0FDR6QDzZ8kYW0FnbsOh validated for user test on service http://127.0.0.1:34765. Proxy Granting Ticket transmited to http://127.0.0.1:34765. 166s PASSED 166s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2024-03-22 11:14:21,149 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,154 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 166s 2024-03-22 11:14:21,157 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 166s 2024-03-22 11:14:21,163 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,168 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 166s 2024-03-22 11:14:21,171 WARNING Service https://www.example.org not allowed. 166s 2024-03-22 11:14:21,171 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 166s PASSED 166s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2024-03-22 11:14:21,180 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,185 INFO Service ticket created for service https://127.0.0.1:45421 by user test. 166s PASSED2024-03-22 11:14:21,225 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=45421): Max retries exceeded with url: /?pgtId=PGT-VbXgNA7XRcvOQkbirfav0Rca6GMLI96LK2xPsBgCLJwNHWhsTaSfWVtpD4Rq&pgtIou=PGTIOU-RrYlSFWa7dLYVPHlPbT5mqgI0Y2OLOkAqCGMwGxjSFSEo3BJz8T3m4IIn (Caused by SSLError(SSLError(1, '[SSL: WRONG_VERSION_NUMBER] wrong version number (_ssl.c:1000)'))) 166s 166s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2024-03-22 11:14:21,234 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,237 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 166s 2024-03-22 11:14:21,239 INFO ValidateService: ticket ST-jmWnlJZjYeCrp24guR4IUtpSxF1wuX148mtyh2HfUJ6naXv0xuhf4YGl40kmx validated for user test on service http://127.0.0.1:45678. 166s 2024-03-22 11:14:21,245 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,248 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 166s 2024-03-22 11:14:21,252 INFO ValidateService: ticket ST-ENAIAVQslHGZcbhFxIPYIkGXXEuuonLHrmkIrz1PtVcL2vAkG1D0N8VLV3EKD validated for user test on service http://127.0.0.1:45678. 166s 2024-03-22 11:14:21,255 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 166s 2024-03-22 11:14:21,257 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 166s PASSED 166s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2024-03-22 11:14:21,270 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,275 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 166s PASSED 166s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2024-03-22 11:14:21,278 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 166s 2024-03-22 11:14:21,286 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,291 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 166s 2024-03-22 11:14:21,293 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 166s PASSED 166s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2024-03-22 11:14:21,302 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,307 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 166s 2024-03-22 11:14:21,309 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 166s PASSED 166s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2024-03-22 11:14:21,318 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,323 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 166s 2024-03-22 11:14:21,326 INFO ValidateService: ticket ST-daKeRcsQJib9qDJHhKLJHaxGt30n9zrzB3EOOb3I8i6VxETac5IvDJ5xxBzGV validated for user test on service http://127.0.0.1:45678. 166s PASSED 166s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2024-03-22 11:14:21,334 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,340 INFO Service ticket created for service https://one_attribute.example.com by user test. 166s 2024-03-22 11:14:21,342 INFO ValidateService: ticket ST-t4K03TM5Dkxt5QQ46hRBIoOc2uGbboeB7ysK9UMY4dMIPHGA0P11Mfmi3CqTT validated for user test on service https://one_attribute.example.com. 166s PASSED 166s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2024-03-22 11:14:21,352 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,358 INFO Service ticket created for service http://127.0.0.1:43095 by user test. 166s 2024-03-22 11:14:21,362 INFO ValidateService: ticket ST-A13GCTJdmQZ36vEf8bXc5wu5mnngepxxAHP5cXDZNknCfMPRorqdSuJLHbpQZ validated for user test on service http://127.0.0.1:43095. Proxy Granting Ticket transmited to http://127.0.0.1:43095. 166s PASSED 166s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2024-03-22 11:14:21,371 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,375 INFO Service ticket created for service https://user_field.example.com by user test. 166s 2024-03-22 11:14:21,378 INFO ValidateService: ticket ST-0cDRxm7XNk96ZDK64iqG8OUwBnAmJOQbL3Ce9sr2GUDk9HtCON2KRpsUDvG4k validated for user test on service https://user_field.example.com. 166s 2024-03-22 11:14:21,384 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,390 INFO Service ticket created for service https://user_field_alt.example.com by user test. 166s 2024-03-22 11:14:21,393 INFO ValidateService: ticket ST-gX1NYIzf4sLQzLdm9SjGMw1xt07ewYXRxVs9Ti3SMPkKFAh9G5Hs2Jm6fM5KV validated for user test on service https://user_field_alt.example.com. 166s PASSED 166s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2024-03-22 11:14:21,404 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,409 INFO Service ticket created for service http://127.0.0.1:41255 by user test. 166s 2024-03-22 11:14:21,413 INFO ValidateService: ticket ST-4XB2WCplPA9vgDl4PVawrWMInNGXrq1mzbkc0tyonzD0m26qctEQMp3s4r4MR validated for user test on service http://127.0.0.1:41255. Proxy Granting Ticket transmited to http://127.0.0.1:41255. 166s 2024-03-22 11:14:21,414 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 166s 2024-03-22 11:14:21,415 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 166s PASSED 166s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2024-03-22 11:14:21,424 INFO User test successfully authenticated 166s 2024-03-22 11:14:21,429 INFO Service ticket created for service http://127.0.0.1:40857 by user test. 166s 2024-03-22 11:14:21,433 INFO ValidateService: ticket ST-nKNQA1KJpf54VgjDuyBBR7Qk1ZsY8QT7EG9ylwyV5XZrLYSypIHOAhxcUpE47 validated for user test on service http://127.0.0.1:40857. Proxy Granting Ticket transmited to http://127.0.0.1:40857. 166s 2024-03-22 11:14:21,435 WARNING Username test not allowed on service restrict_user_fail 166s 2024-03-22 11:14:21,435 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 166s 2024-03-22 11:14:21,437 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 166s 2024-03-22 11:14:21,438 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 166s 2024-03-22 11:14:21,439 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 166s 2024-03-22 11:14:21,439 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 166s PASSED 167s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2024-03-22 11:14:21,449 INFO User test successfully authenticated 167s 2024-03-22 11:14:21,454 INFO Service ticket created for service http://127.0.0.1:40871 by user test. 167s 2024-03-22 11:14:21,459 INFO ValidateService: ticket ST-RyZhfSHdbE3n1RY8XVih9uNY2SVvTlimDzgW2Gkd75PFXrDMA7SEyJWO6yXg5 validated for user test on service http://127.0.0.1:40871. Proxy Granting Ticket transmited to http://127.0.0.1:40871. 167s 2024-03-22 11:14:21,460 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 167s PASSED 167s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2024-03-22 11:14:21,469 INFO User test successfully authenticated 167s 2024-03-22 11:14:21,474 INFO Service ticket created for service http://127.0.0.1:43981 by user test. 167s 2024-03-22 11:14:21,479 INFO ValidateService: ticket ST-GKkZtpaFRf5ICAxULigHouPbiOi5pv3Gt7hTafz5gEkTe9AOC69vdCOhzqgfW validated for user test on service http://127.0.0.1:43981. Proxy Granting Ticket transmited to http://127.0.0.1:43981. 167s 2024-03-22 11:14:21,480 WARNING Service https://www.example.org not allowed. 167s 2024-03-22 11:14:21,480 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 167s PASSED 167s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2024-03-22 11:14:21,481 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:43981 does not allow proxy tickets 167s 2024-03-22 11:14:21,491 INFO User test successfully authenticated 167s 2024-03-22 11:14:21,496 INFO Service ticket created for service http://127.0.0.1:33165 by user test. 167s 2024-03-22 11:14:21,501 INFO ValidateService: ticket ST-jq2pgO90XqI13W5Z1cvMh9Nd9HqCtTDdprkURvWK1wbvQEI4zmy50HV6wfVaw validated for user test on service http://127.0.0.1:33165. Proxy Granting Ticket transmited to http://127.0.0.1:33165. 167s 2024-03-22 11:14:21,504 INFO Proxy ticket created for user test on service https://www.example.com. 167s 2024-03-22 11:14:21,507 INFO ValidateService: ticket PT-pAGXzrEjlgS5yNkLBS5IjkqyTeqCuWvB24tq5v1SFcWFm7XDfej01ZaadVMcy validated for user test on service https://www.example.com. 167s PASSED 167s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2024-03-22 11:14:21,517 INFO User test successfully authenticated 167s 2024-03-22 11:14:21,523 INFO Service ticket created for service https://www.example.com by user test. 167s 2024-03-22 11:14:21,525 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 167s PASSED 167s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2024-03-22 11:14:21,531 WARNING SamlValidate: validation error: AuthnFailed ticket ST-49EvrOVXKhy0mpPeOiPZ4TgpWS25IMRhCjQb6PnCXxUj7iNcqi3pAg4g0GYGu not found 167s 2024-03-22 11:14:21,532 WARNING SamlValidate: validation error: AuthnFailed ticket PT-jhtiYudVYLE8AZoURnfz3WK4Lq4WX1zrJt9Iu1TP0NdIBBhAqAHnJ15QosJ6J not found 167s PASSED 167s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix PASSED2024-03-22 11:14:21,536 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 167s 167s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml PASSED 167s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2024-03-22 11:14:21,540 WARNING SamlValidate: validation error: VersionMismatch 167s 2024-03-22 11:14:21,548 INFO User test successfully authenticated 167s 2024-03-22 11:14:21,553 INFO Service ticket created for service https://www.example.com by user test. 167s 2024-03-22 11:14:21,560 INFO User test successfully authenticated 167s 2024-03-22 11:14:21,565 INFO Service ticket created for service http://127.0.0.1:34453 by user test. 167s 2024-03-22 11:14:21,569 INFO ValidateService: ticket ST-Z9zQoyCP7yrxXaj8BnBamAtFhE21jGbj2hvDeY97hHPUI4Go5d4jAwxX4qhxX validated for user test on service http://127.0.0.1:34453. Proxy Granting Ticket transmited to http://127.0.0.1:34453. 167s 2024-03-22 11:14:21,573 INFO Proxy ticket created for user test on service https://www.example.com. 167s 2024-03-22 11:14:21,575 INFO SamlValidate: ticket ST-Xur1vF5vpzUYRqBDPHrZks3BNOkdpuvC8X4uymZD9cpz6NgQIgAGbxuHPOwUV validated for user test on service https://www.example.com. 167s 2024-03-22 11:14:21,577 INFO SamlValidate: ticket PT-tOW3ugmKt4LckWuBmQ8pOAKePENeDSCv8Oh3MEwK6M0gubTcfjBdgXydR9Sj7 validated for user test on service https://www.example.com. 167s PASSED 167s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2024-03-22 11:14:21,586 INFO User test successfully authenticated 167s 2024-03-22 11:14:21,591 INFO Service ticket created for service https://field_needed_success.example.com by user test. 167s 2024-03-22 11:14:21,594 INFO SamlValidate: ticket ST-IFMy0pLXrb8bBAkEHYwuO09uIHXaStfoeIFjyDpkJENk2c99Z443HBl39un0M validated for user test on service https://field_needed_success.example.com. 167s 2024-03-22 11:14:21,600 INFO User test successfully authenticated 167s 2024-03-22 11:14:21,605 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 167s 2024-03-22 11:14:21,607 INFO SamlValidate: ticket ST-mQ34vcqZuAUUBqsq0IBwe1nyzIkO2UOxpcLqb1vLlUYlEVLbBGeNBE4gvr9P4 validated for user test on service https://field_needed_success_alt.example.com. 167s PASSEDDestroying test database for alias 'default'... 167s 167s 167s =============================== warnings summary =============================== 167s cas_server/utils.py:33 167s /tmp/autopkgtest.hu2Ugn/autopkgtest_tmp/cas_server/utils.py:33: DeprecationWarning: 'crypt' is deprecated and slated for removal in Python 3.13 167s import crypt 167s 167s cas_server/tests/utils.py:20 167s /tmp/autopkgtest.hu2Ugn/autopkgtest_tmp/cas_server/tests/utils.py:20: DeprecationWarning: 'cgi' is deprecated and slated for removal in Python 3.13 167s import cgi 167s 167s cas_server/tests/test_federate.py: 8 warnings 167s cas_server/tests/test_view.py: 3 warnings 167s /tmp/autopkgtest.hu2Ugn/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). 167s datetime.utcnow() + timedelta(seconds=max_age), 167s 167s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 167s /usr/lib/python3.12/unittest/case.py:690: DeprecationWarning: It is deprecated to return a value that is not None from a test case (>) 167s return self.run(*args, **kwds) 167s 167s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 167s ======================= 128 passed, 14 warnings in 5.67s ======================= 167s 167s autopkgtest [11:14:23]: test run-test-suite.sh: -----------------------] 168s autopkgtest [11:14:24]: test run-test-suite.sh: - - - - - - - - - - results - - - - - - - - - - 168s run-test-suite.sh PASS 168s autopkgtest [11:14:24]: @@@@@@@@@@@@@@@@@@@@ summary 168s run-test-suite.sh PASS 179s Creating nova instance adt-noble-s390x-django-cas-server-20240322-111136-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-s390x-server-20240321.img (UUID f7ee8f0f-480f-4014-94f0-3be2a19e259d)...