0s autopkgtest [01:12:22]: starting date and time: 2024-06-08 01:12:22+0000 0s autopkgtest [01:12:22]: git checkout: 433ed4cb Merge branch 'skia/nova_flock' into 'ubuntu/5.34+prod' 0s autopkgtest [01:12:22]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.pts7evv6/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:pytest,src:astropy --apt-upgrade django-cas-server --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=pytest/8.2.2-1ubuntu1 astropy/6.0.1-4' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos01-s390x-7.secgroup --name adt-oracular-s390x-django-cas-server-20240608-005958-juju-7f2275-prod-proposed-migration-environment-3-789e86cb-ed19-4906-a937-ae04f79d9c85 --image adt/ubuntu-oracular-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://us.ports.ubuntu.com/ubuntu-ports/ 110s autopkgtest [01:14:12]: testbed dpkg architecture: s390x 110s autopkgtest [01:14:12]: testbed apt version: 2.9.3 110s autopkgtest [01:14:12]: @@@@@@@@@@@@@@@@@@@@ test bed setup 111s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [110 kB] 111s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [7972 B] 111s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [2864 B] 111s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [44.3 kB] 111s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [339 kB] 112s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x Packages [47.8 kB] 112s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/restricted s390x Packages [1860 B] 112s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/universe s390x Packages [264 kB] 112s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse s390x Packages [1768 B] 112s Fetched 820 kB in 1s (847 kB/s) 112s Reading package lists... 114s Reading package lists... 115s Building dependency tree... 115s Reading state information... 115s Calculating upgrade... 115s The following packages will be upgraded: 115s debianutils libheif-plugin-aomdec libheif-plugin-aomenc 115s libheif-plugin-libde265 libheif1 libnetplan1 liburcu8t64 netplan-generator 115s netplan.io python3-netplan 116s 10 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 116s Need to get 758 kB of archives. 116s After this operation, 48.1 kB disk space will be freed. 116s Get:1 http://ftpmaster.internal/ubuntu oracular/main s390x debianutils s390x 5.18 [90.2 kB] 116s Get:2 http://ftpmaster.internal/ubuntu oracular/main s390x netplan-generator s390x 1.0-3ubuntu1 [59.2 kB] 116s Get:3 http://ftpmaster.internal/ubuntu oracular/main s390x python3-netplan s390x 1.0-3ubuntu1 [22.9 kB] 116s Get:4 http://ftpmaster.internal/ubuntu oracular/main s390x netplan.io s390x 1.0-3ubuntu1 [65.8 kB] 116s Get:5 http://ftpmaster.internal/ubuntu oracular/main s390x libnetplan1 s390x 1.0-3ubuntu1 [125 kB] 116s Get:6 http://ftpmaster.internal/ubuntu oracular/main s390x libheif-plugin-aomdec s390x 1.17.6-3 [10.1 kB] 116s Get:7 http://ftpmaster.internal/ubuntu oracular/main s390x libheif-plugin-aomenc s390x 1.17.6-3 [15.1 kB] 116s Get:8 http://ftpmaster.internal/ubuntu oracular/main s390x libheif1 s390x 1.17.6-3 [295 kB] 116s Get:9 http://ftpmaster.internal/ubuntu oracular/main s390x libheif-plugin-libde265 s390x 1.17.6-3 [8172 B] 116s Get:10 http://ftpmaster.internal/ubuntu oracular/main s390x liburcu8t64 s390x 0.14.0-4 [66.5 kB] 117s Fetched 758 kB in 1s (1093 kB/s) 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 ... 54607 files and directories currently installed.) 117s Preparing to unpack .../debianutils_5.18_s390x.deb ... 117s Unpacking debianutils (5.18) over (5.17build1) ... 117s Setting up debianutils (5.18) ... 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 ... 54607 files and directories currently installed.) 117s Preparing to unpack .../0-netplan-generator_1.0-3ubuntu1_s390x.deb ... 117s Adding 'diversion of /lib/systemd/system-generators/netplan to /lib/systemd/system-generators/netplan.usr-is-merged by netplan-generator' 117s Unpacking netplan-generator (1.0-3ubuntu1) over (1.0-2ubuntu1) ... 117s Preparing to unpack .../1-python3-netplan_1.0-3ubuntu1_s390x.deb ... 117s Unpacking python3-netplan (1.0-3ubuntu1) over (1.0-2ubuntu1) ... 117s Preparing to unpack .../2-netplan.io_1.0-3ubuntu1_s390x.deb ... 117s Unpacking netplan.io (1.0-3ubuntu1) over (1.0-2ubuntu1) ... 117s Preparing to unpack .../3-libnetplan1_1.0-3ubuntu1_s390x.deb ... 117s Unpacking libnetplan1:s390x (1.0-3ubuntu1) over (1.0-2ubuntu1) ... 117s Preparing to unpack .../4-libheif-plugin-aomdec_1.17.6-3_s390x.deb ... 117s Unpacking libheif-plugin-aomdec:s390x (1.17.6-3) over (1.17.6-1ubuntu4) ... 117s Preparing to unpack .../5-libheif-plugin-aomenc_1.17.6-3_s390x.deb ... 117s Unpacking libheif-plugin-aomenc:s390x (1.17.6-3) over (1.17.6-1ubuntu4) ... 117s Preparing to unpack .../6-libheif1_1.17.6-3_s390x.deb ... 117s Unpacking libheif1:s390x (1.17.6-3) over (1.17.6-1ubuntu4) ... 117s Preparing to unpack .../7-libheif-plugin-libde265_1.17.6-3_s390x.deb ... 117s Unpacking libheif-plugin-libde265:s390x (1.17.6-3) over (1.17.6-1ubuntu4) ... 117s Preparing to unpack .../8-liburcu8t64_0.14.0-4_s390x.deb ... 117s Unpacking liburcu8t64:s390x (0.14.0-4) over (0.14.0-3.1build1) ... 117s Setting up liburcu8t64:s390x (0.14.0-4) ... 117s Setting up libnetplan1:s390x (1.0-3ubuntu1) ... 117s Setting up python3-netplan (1.0-3ubuntu1) ... 117s Setting up netplan-generator (1.0-3ubuntu1) ... 117s Removing 'diversion of /lib/systemd/system-generators/netplan to /lib/systemd/system-generators/netplan.usr-is-merged by netplan-generator' 117s Setting up netplan.io (1.0-3ubuntu1) ... 117s Setting up libheif-plugin-aomdec:s390x (1.17.6-3) ... 117s Setting up libheif-plugin-libde265:s390x (1.17.6-3) ... 117s Setting up libheif1:s390x (1.17.6-3) ... 117s Setting up libheif-plugin-aomenc:s390x (1.17.6-3) ... 117s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 117s Processing triggers for man-db (2.12.1-1) ... 119s Processing triggers for dbus (1.14.10-4ubuntu4) ... 119s Reading package lists... 119s Building dependency tree... 119s Reading state information... 120s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 120s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 120s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 120s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 120s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 121s Reading package lists... 121s Reading package lists... 122s Building dependency tree... 122s Reading state information... 122s Calculating upgrade... 122s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 123s Reading package lists... 123s Building dependency tree... 123s Reading state information... 123s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 126s autopkgtest [01:14:28]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:14:26 UTC 2024 126s autopkgtest [01:14:28]: @@@@@@@@@@@@@@@@@@@@ apt-source django-cas-server 128s Get:1 http://ftpmaster.internal/ubuntu oracular/universe django-cas-server 2.0.0-2 (dsc) [2588 B] 128s Get:2 http://ftpmaster.internal/ubuntu oracular/universe django-cas-server 2.0.0-2 (tar) [169 kB] 128s Get:3 http://ftpmaster.internal/ubuntu oracular/universe django-cas-server 2.0.0-2 (diff) [6480 B] 129s gpgv: Signature made Sat Dec 3 08:42:02 2022 UTC 129s gpgv: using RSA key B70DFC6F134FECFC011E62AA83016014251D1DB0 129s gpgv: Can't check signature: No public key 129s dpkg-source: warning: cannot verify inline signature for ./django-cas-server_2.0.0-2.dsc: no acceptable signature found 129s autopkgtest [01:14:31]: testing package django-cas-server version 2.0.0-2 129s autopkgtest [01:14:31]: build not needed 131s autopkgtest [01:14:33]: test run-test-suite.sh: preparing testbed 132s Reading package lists... 133s Building dependency tree... 133s Reading state information... 133s Starting pkgProblemResolver with broken count: 0 133s Starting 2 pkgProblemResolver with broken count: 0 133s Done 133s The following additional packages will be installed: 133s fonts-font-awesome fonts-lato libjs-jquery libjs-sphinxdoc libjs-underscore 133s libxslt1.1 python3-all python3-asgiref python3-django 133s python3-django-cas-server python3-django-cas-server-doc python3-iniconfig 133s python3-lxml python3-mock python3-packaging python3-pluggy python3-pytest 133s python3-pytest-django python3-requests-futures python3-sqlparse 133s sphinx-rtd-theme-common 133s Suggested packages: 133s bpython3 geoip-database-contrib gettext ipython3 libgdal20 133s libsqlite3-mod-spatialite python-django-doc python3-bcrypt python3-flup 133s python3-memcache python3-mysqldb python3-pil python3-psycopg2 133s python3-selenium python3-sqlite python-lxml-doc python-mock-doc 133s python-sqlparse-doc 133s Recommended packages: 133s javascript-common python3-tz python3-bs4 python3-cssselect python3-html5lib 133s The following NEW packages will be installed: 133s autopkgtest-satdep fonts-font-awesome fonts-lato libjs-jquery 133s libjs-sphinxdoc libjs-underscore libxslt1.1 python3-all python3-asgiref 133s python3-django python3-django-cas-server python3-django-cas-server-doc 133s python3-iniconfig python3-lxml python3-mock python3-packaging python3-pluggy 133s python3-pytest python3-pytest-django python3-requests-futures 133s python3-sqlparse sphinx-rtd-theme-common 134s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 134s Need to get 10.0 MB/10.0 MB of archives. 134s After this operation, 51.9 MB of additional disk space will be used. 134s Get:1 /tmp/autopkgtest.IJYb6q/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [752 B] 134s Get:2 http://ftpmaster.internal/ubuntu oracular/main s390x fonts-lato all 2.015-1 [2781 kB] 135s Get:3 http://ftpmaster.internal/ubuntu oracular/main s390x fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 135s Get:4 http://ftpmaster.internal/ubuntu oracular/main s390x libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 135s Get:5 http://ftpmaster.internal/ubuntu oracular/main s390x libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 135s Get:6 http://ftpmaster.internal/ubuntu oracular/main s390x libjs-sphinxdoc all 7.2.6-8 [150 kB] 135s Get:7 http://ftpmaster.internal/ubuntu oracular/main s390x libxslt1.1 s390x 1.1.39-0exp1build1 [170 kB] 135s Get:8 http://ftpmaster.internal/ubuntu oracular/main s390x python3-all s390x 3.12.3-0ubuntu1 [890 B] 135s Get:9 http://ftpmaster.internal/ubuntu oracular/main s390x python3-asgiref all 3.8.1-1 [24.8 kB] 135s Get:10 http://ftpmaster.internal/ubuntu oracular/main s390x python3-sqlparse all 0.5.0-1 [32.4 kB] 135s Get:11 http://ftpmaster.internal/ubuntu oracular/main s390x python3-django all 3:4.2.13-1 [2919 kB] 136s Get:12 http://ftpmaster.internal/ubuntu oracular/main s390x python3-lxml s390x 5.2.1-1 [1300 kB] 136s Get:13 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-requests-futures all 1.0.1-1 [11.7 kB] 136s Get:14 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-django-cas-server all 2.0.0-2 [111 kB] 136s Get:15 http://ftpmaster.internal/ubuntu oracular/main s390x sphinx-rtd-theme-common all 2.0.0+dfsg-1 [1012 kB] 136s Get:16 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-django-cas-server-doc all 2.0.0-2 [159 kB] 136s Get:17 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 136s Get:18 http://ftpmaster.internal/ubuntu oracular/main s390x python3-packaging all 24.0-1 [41.1 kB] 136s Get:19 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-pluggy all 1.5.0-1 [21.0 kB] 136s Get:20 http://ftpmaster.internal/ubuntu oracular-proposed/universe s390x python3-pytest all 8.2.2-1ubuntu1 [250 kB] 136s Get:21 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-pytest-django all 4.5.2-3 [18.5 kB] 136s Get:22 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-mock all 5.1.0-1 [64.1 kB] 136s Fetched 10.0 MB in 3s (3834 kB/s) 136s Selecting previously unselected package fonts-lato. 137s (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 ... 54607 files and directories currently installed.) 137s Preparing to unpack .../00-fonts-lato_2.015-1_all.deb ... 137s Unpacking fonts-lato (2.015-1) ... 137s Selecting previously unselected package fonts-font-awesome. 137s Preparing to unpack .../01-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 137s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 137s Selecting previously unselected package libjs-jquery. 137s Preparing to unpack .../02-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 137s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 137s Selecting previously unselected package libjs-underscore. 137s Preparing to unpack .../03-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 137s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 137s Selecting previously unselected package libjs-sphinxdoc. 137s Preparing to unpack .../04-libjs-sphinxdoc_7.2.6-8_all.deb ... 137s Unpacking libjs-sphinxdoc (7.2.6-8) ... 137s Selecting previously unselected package libxslt1.1:s390x. 137s Preparing to unpack .../05-libxslt1.1_1.1.39-0exp1build1_s390x.deb ... 137s Unpacking libxslt1.1:s390x (1.1.39-0exp1build1) ... 137s Selecting previously unselected package python3-all. 137s Preparing to unpack .../06-python3-all_3.12.3-0ubuntu1_s390x.deb ... 137s Unpacking python3-all (3.12.3-0ubuntu1) ... 137s Selecting previously unselected package python3-asgiref. 137s Preparing to unpack .../07-python3-asgiref_3.8.1-1_all.deb ... 137s Unpacking python3-asgiref (3.8.1-1) ... 137s Selecting previously unselected package python3-sqlparse. 137s Preparing to unpack .../08-python3-sqlparse_0.5.0-1_all.deb ... 137s Unpacking python3-sqlparse (0.5.0-1) ... 138s Selecting previously unselected package python3-django. 138s Preparing to unpack .../09-python3-django_3%3a4.2.13-1_all.deb ... 138s Unpacking python3-django (3:4.2.13-1) ... 139s Selecting previously unselected package python3-lxml:s390x. 139s Preparing to unpack .../10-python3-lxml_5.2.1-1_s390x.deb ... 139s Unpacking python3-lxml:s390x (5.2.1-1) ... 139s Selecting previously unselected package python3-requests-futures. 139s Preparing to unpack .../11-python3-requests-futures_1.0.1-1_all.deb ... 139s Unpacking python3-requests-futures (1.0.1-1) ... 139s Selecting previously unselected package python3-django-cas-server. 139s Preparing to unpack .../12-python3-django-cas-server_2.0.0-2_all.deb ... 139s Unpacking python3-django-cas-server (2.0.0-2) ... 139s Selecting previously unselected package sphinx-rtd-theme-common. 139s Preparing to unpack .../13-sphinx-rtd-theme-common_2.0.0+dfsg-1_all.deb ... 139s Unpacking sphinx-rtd-theme-common (2.0.0+dfsg-1) ... 139s Selecting previously unselected package python3-django-cas-server-doc. 139s Preparing to unpack .../14-python3-django-cas-server-doc_2.0.0-2_all.deb ... 139s Unpacking python3-django-cas-server-doc (2.0.0-2) ... 139s Selecting previously unselected package python3-iniconfig. 139s Preparing to unpack .../15-python3-iniconfig_1.1.1-2_all.deb ... 139s Unpacking python3-iniconfig (1.1.1-2) ... 139s Selecting previously unselected package python3-packaging. 139s Preparing to unpack .../16-python3-packaging_24.0-1_all.deb ... 139s Unpacking python3-packaging (24.0-1) ... 139s Selecting previously unselected package python3-pluggy. 139s Preparing to unpack .../17-python3-pluggy_1.5.0-1_all.deb ... 139s Unpacking python3-pluggy (1.5.0-1) ... 139s Selecting previously unselected package python3-pytest. 139s Preparing to unpack .../18-python3-pytest_8.2.2-1ubuntu1_all.deb ... 139s Unpacking python3-pytest (8.2.2-1ubuntu1) ... 139s Selecting previously unselected package python3-pytest-django. 139s Preparing to unpack .../19-python3-pytest-django_4.5.2-3_all.deb ... 139s Unpacking python3-pytest-django (4.5.2-3) ... 139s Selecting previously unselected package python3-mock. 139s Preparing to unpack .../20-python3-mock_5.1.0-1_all.deb ... 139s Unpacking python3-mock (5.1.0-1) ... 139s Selecting previously unselected package autopkgtest-satdep. 139s Preparing to unpack .../21-1-autopkgtest-satdep.deb ... 139s Unpacking autopkgtest-satdep (0) ... 139s Setting up python3-iniconfig (1.1.1-2) ... 139s Setting up fonts-lato (2.015-1) ... 139s Setting up python3-all (3.12.3-0ubuntu1) ... 139s Setting up python3-mock (5.1.0-1) ... 140s Setting up python3-packaging (24.0-1) ... 140s Setting up python3-sqlparse (0.5.0-1) ... 141s Setting up python3-pluggy (1.5.0-1) ... 141s Setting up libxslt1.1:s390x (1.1.39-0exp1build1) ... 141s Setting up python3-requests-futures (1.0.1-1) ... 141s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 141s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 141s Setting up sphinx-rtd-theme-common (2.0.0+dfsg-1) ... 141s Setting up python3-asgiref (3.8.1-1) ... 141s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 141s Setting up python3-django (3:4.2.13-1) ... 144s Setting up python3-pytest (8.2.2-1ubuntu1) ... 144s Setting up python3-pytest-django (4.5.2-3) ... 144s Setting up libjs-sphinxdoc (7.2.6-8) ... 144s Setting up python3-lxml:s390x (5.2.1-1) ... 145s Setting up python3-django-cas-server (2.0.0-2) ... 145s Setting up python3-django-cas-server-doc (2.0.0-2) ... 145s Setting up autopkgtest-satdep (0) ... 145s Processing triggers for man-db (2.12.1-1) ... 146s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 149s (Reading database ... 61414 files and directories currently installed.) 149s Removing autopkgtest-satdep (0) ... 150s autopkgtest [01:14:52]: test run-test-suite.sh: [----------------------- 150s 150s *************************** 150s *** Testing with python3.12 150s *************************** 150s 150s Content of current working folder: 150s 150s total 20 150s drwxr-xr-x 3 ubuntu ubuntu 4096 Jun 8 01:14 . 150s drwxrwxrwt 5 root root 4096 Jun 8 01:14 .. 150s drwxrwxr-x 9 ubuntu ubuntu 4096 Oct 17 2022 cas_server 150s -rw-rw-r-- 1 ubuntu ubuntu 140 Oct 17 2022 pytest.ini 150s -rw-rw-r-- 1 ubuntu ubuntu 152 Oct 17 2022 setup.cfg 150s Running tests... 150s 151s ============================= test session starts ============================== 151s platform linux -- Python 3.12.3, pytest-8.2.2, pluggy-1.5.0 -- /usr/bin/python3.12 151s cachedir: .pytest_cache 151s django: settings: cas_server.tests.settings (from ini) 151s rootdir: /tmp/autopkgtest.IJYb6q/autopkgtest_tmp 151s configfile: pytest.ini 151s testpaths: cas_server/tests/ 151s plugins: django-4.5.2 151s collecting ... collected 128 items 151s 151s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 152s 2024-06-08 01:14:54,769 WARNING Identity provider suffix exemple.fr not found 152s 2024-06-08 01:14:54,820 INFO Got an invalid ticket ST-g7A7RhDpZsZX9ACwx0oHjLvqdfkaIFpUxX3GkXd5Yz6Go5c8O2u7catmsq30y from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 152s 2024-06-08 01:14:54,823 INFO Got an invalid ticket ST-Y3nPbvCm95waX7WjWrbH6bVIQWuMZk1Yra87VUoV97OYRW3iX7Cw6JOoOmbd9 from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 152s PASSED 152s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2024-06-08 01:14:54,865 WARNING CAS_FEDERATE is False, set it to True to use federation 152s 2024-06-08 01:14:54,867 WARNING CAS_FEDERATE is False, set it to True to use federation 152s PASSED 152s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2024-06-08 01:14:54,888 INFO Trying to authenticate http://127.0.0.1:8080 again 152s 2024-06-08 01:14:54,894 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 152s 2024-06-08 01:14:54,928 INFO User dédé@example.com successfully authenticated 152s 2024-06-08 01:14:54,957 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 153s 2024-06-08 01:14:54,975 INFO Trying to authenticate http://127.0.0.1:8081 again 153s 2024-06-08 01:14:54,984 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 153s 2024-06-08 01:14:55,000 INFO User dédé@example.org successfully authenticated 153s 2024-06-08 01:14:55,018 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 153s 2024-06-08 01:14:55,046 INFO Trying to authenticate http://127.0.0.1:8082 again 153s 2024-06-08 01:14:55,061 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 153s 2024-06-08 01:14:55,079 INFO User dédé@example.net successfully authenticated 153s 2024-06-08 01:14:55,092 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 153s 2024-06-08 01:14:55,104 INFO Trying to authenticate http://127.0.0.1:8083 again 153s 2024-06-08 01:14:55,109 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 153s 2024-06-08 01:14:55,120 INFO User dédé@example.test successfully authenticated 153s 2024-06-08 01:14:55,129 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 153s 2024-06-08 01:14:55,171 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-Nd3xtGZegIYIKjkljs3R6plZDEskq4TIIydXwtn7kaP6wWE1xj77K0JsBQ5Q6 153s 2024-06-08 01:14:55,197 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-i2eb1jcw1Jh7C3T11PEkqwT4JafWgVSIEu8ajkY4mpjtnaPDX4WCCDPcGurn9 153s PASSED 153s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 153s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2024-06-08 01:14:55,231 INFO Trying to authenticate http://127.0.0.1:8080 again 153s 2024-06-08 01:14:55,235 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 153s 2024-06-08 01:14:55,246 INFO User dédé@example.com successfully authenticated 153s 2024-06-08 01:14:55,258 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 153s 2024-06-08 01:14:55,271 INFO Trying to authenticate http://127.0.0.1:8081 again 153s 2024-06-08 01:14:55,276 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 153s 2024-06-08 01:14:55,296 INFO User dédé@example.org successfully authenticated 153s 2024-06-08 01:14:55,315 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 153s 2024-06-08 01:14:55,340 INFO Trying to authenticate http://127.0.0.1:8082 again 153s 2024-06-08 01:14:55,349 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 153s 2024-06-08 01:14:55,370 INFO User dédé@example.net successfully authenticated 153s 2024-06-08 01:14:55,394 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 153s 2024-06-08 01:14:55,417 INFO Trying to authenticate http://127.0.0.1:8083 again 153s 2024-06-08 01:14:55,429 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 153s 2024-06-08 01:14:55,449 INFO User dédé@example.test successfully authenticated 153s 2024-06-08 01:14:55,460 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 153s 2024-06-08 01:14:55,462 INFO logout requested 153s 2024-06-08 01:14:55,464 INFO Logging out user dédé@example.com. 153s 2024-06-08 01:14:55,478 INFO User dédé@example.com logged out 153s 2024-06-08 01:14:55,498 INFO logout requested 153s 2024-06-08 01:14:55,502 INFO logout requested 153s 2024-06-08 01:14:55,506 INFO Logging out user dédé@example.org. 153s 2024-06-08 01:14:55,518 INFO User dédé@example.org logged out 153s 2024-06-08 01:14:55,536 INFO logout requested 153s 2024-06-08 01:14:55,540 INFO logout requested 153s 2024-06-08 01:14:55,543 INFO Logging out user dédé@example.net. 153s 2024-06-08 01:14:55,548 INFO User dédé@example.net logged out 153s 2024-06-08 01:14:55,559 INFO logout requested 153s 2024-06-08 01:14:55,562 INFO logout requested 153s 2024-06-08 01:14:55,564 INFO Logging out user dédé@example.test. 153s 2024-06-08 01:14:55,571 INFO User dédé@example.test logged out 153s 2024-06-08 01:14:55,589 INFO logout requested 153s 2024-06-08 01:14:55,615 INFO Trying to authenticate http://127.0.0.1:8080 again 153s 2024-06-08 01:14:55,619 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 153s 2024-06-08 01:14:55,640 INFO User dédé@example.com successfully authenticated 153s 2024-06-08 01:14:55,655 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 153s 2024-06-08 01:14:55,668 INFO Trying to authenticate http://127.0.0.1:8081 again 153s 2024-06-08 01:14:55,674 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 153s 2024-06-08 01:14:55,685 INFO User dédé@example.org successfully authenticated 153s 2024-06-08 01:14:55,698 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 153s 2024-06-08 01:14:55,738 INFO Trying to authenticate http://127.0.0.1:8082 again 153s 2024-06-08 01:14:55,765 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 153s 2024-06-08 01:14:55,782 INFO User dédé@example.net successfully authenticated 153s 2024-06-08 01:14:55,805 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 153s 2024-06-08 01:14:55,825 INFO Trying to authenticate http://127.0.0.1:8083 again 153s 2024-06-08 01:14:55,836 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 153s 2024-06-08 01:14:55,890 INFO User dédé@example.test successfully authenticated 153s 2024-06-08 01:14:55,924 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 153s 2024-06-08 01:14:55,931 INFO logout requested 153s 2024-06-08 01:14:55,936 INFO Logging out user dédé. 153s 2024-06-08 01:14:55,941 INFO User dédé logged out 153s 2024-06-08 01:14:55,966 INFO logout requested 153s 2024-06-08 01:14:55,967 INFO Logging out user dédé. 153s 2024-06-08 01:14:55,967 INFO User dédé logged out 154s 2024-06-08 01:14:55,985 INFO logout requested 154s 2024-06-08 01:14:55,989 INFO Logging out user dédé. 154s 2024-06-08 01:14:55,991 INFO User dédé logged out 154s 2024-06-08 01:14:56,011 INFO logout requested 154s 2024-06-08 01:14:56,013 INFO Logging out user dédé. 154s 2024-06-08 01:14:56,015 INFO User dédé logged out 154s PASSED 154s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2024-06-08 01:14:56,085 INFO Trying to authenticate http://127.0.0.1:8080 again 154s 2024-06-08 01:14:56,093 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 154s 2024-06-08 01:14:56,119 INFO User dédé@example.com successfully authenticated 154s 2024-06-08 01:14:56,134 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 154s 2024-06-08 01:14:56,163 INFO Trying to authenticate http://127.0.0.1:8081 again 154s 2024-06-08 01:14:56,173 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 154s 2024-06-08 01:14:56,188 INFO User dédé@example.org successfully authenticated 154s 2024-06-08 01:14:56,201 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 154s 2024-06-08 01:14:56,214 INFO Trying to authenticate http://127.0.0.1:8082 again 154s 2024-06-08 01:14:56,219 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 154s 2024-06-08 01:14:56,243 INFO User dédé@example.net successfully authenticated 154s 2024-06-08 01:14:56,254 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 154s 2024-06-08 01:14:56,274 INFO Trying to authenticate http://127.0.0.1:8083 again 154s 2024-06-08 01:14:56,283 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 154s 2024-06-08 01:14:56,301 INFO User dédé@example.test successfully authenticated 154s 2024-06-08 01:14:56,312 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 154s 2024-06-08 01:14:56,313 INFO logout requested 154s 2024-06-08 01:14:56,316 INFO Logging out user dédé@example.com. 154s 2024-06-08 01:14:56,322 INFO User dédé@example.com logged out 154s 2024-06-08 01:14:56,323 INFO logout requested 154s 2024-06-08 01:14:56,325 INFO Logging out user dédé@example.org. 154s 2024-06-08 01:14:56,331 INFO User dédé@example.org logged out 154s 2024-06-08 01:14:56,332 INFO logout requested 154s 2024-06-08 01:14:56,333 INFO Logging out user dédé@example.net. 154s 2024-06-08 01:14:56,339 INFO User dédé@example.net logged out 154s 2024-06-08 01:14:56,340 INFO logout requested 154s 2024-06-08 01:14:56,343 INFO Logging out user dédé@example.test. 154s 2024-06-08 01:14:56,347 INFO User dédé@example.test logged out 154s PASSED 154s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2024-06-08 01:14:56,365 WARNING A login attempt failed 154s PASSED 154s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 154s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2024-06-08 01:14:56,421 INFO Trying to authenticate http://127.0.0.1:8080 again 154s 2024-06-08 01:14:56,425 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 154s 2024-06-08 01:14:56,446 INFO User dédé@example.com successfully authenticated 154s 2024-06-08 01:14:56,464 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 154s 2024-06-08 01:14:56,494 INFO Trying to authenticate http://127.0.0.1:8081 again 154s 2024-06-08 01:14:56,501 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 154s 2024-06-08 01:14:56,532 INFO User dédé@example.org successfully authenticated 154s 2024-06-08 01:14:56,563 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 154s 2024-06-08 01:14:56,589 INFO Trying to authenticate http://127.0.0.1:8082 again 154s 2024-06-08 01:14:56,598 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 154s 2024-06-08 01:14:56,611 INFO User dédé@example.net successfully authenticated 154s 2024-06-08 01:14:56,631 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 154s 2024-06-08 01:14:56,655 INFO Trying to authenticate http://127.0.0.1:8083 again 154s 2024-06-08 01:14:56,661 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 154s 2024-06-08 01:14:56,688 INFO User dédé@example.test successfully authenticated 154s 2024-06-08 01:14:56,703 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 154s PASSED 154s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2024-06-08 01:14:56,731 INFO Trying to authenticate http://127.0.0.1:8080 again 154s 2024-06-08 01:14:56,740 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 154s 2024-06-08 01:14:56,755 INFO User dédé@example.com successfully authenticated 154s 2024-06-08 01:14:56,768 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 154s 2024-06-08 01:14:56,794 INFO Trying to authenticate http://127.0.0.1:8081 again 154s 2024-06-08 01:14:56,800 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 154s 2024-06-08 01:14:56,817 INFO User dédé@example.org successfully authenticated 154s 2024-06-08 01:14:56,837 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 154s 2024-06-08 01:14:56,874 INFO Trying to authenticate http://127.0.0.1:8082 again 154s 2024-06-08 01:14:56,892 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 154s 2024-06-08 01:14:56,926 INFO User dédé@example.net successfully authenticated 154s 2024-06-08 01:14:56,961 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 155s 2024-06-08 01:14:56,984 INFO Trying to authenticate http://127.0.0.1:8083 again 155s 2024-06-08 01:14:56,997 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 155s 2024-06-08 01:14:57,026 INFO User dédé@example.test successfully authenticated 155s 2024-06-08 01:14:57,038 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 155s 2024-06-08 01:14:57,058 INFO Trying to authenticate http://127.0.0.1:8080 again 155s 2024-06-08 01:14:57,072 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 155s 2024-06-08 01:14:57,087 INFO User dédé@example.com successfully authenticated 155s 2024-06-08 01:14:57,109 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 155s 2024-06-08 01:14:57,126 INFO Trying to authenticate http://127.0.0.1:8081 again 155s 2024-06-08 01:14:57,132 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 155s 2024-06-08 01:14:57,146 INFO User dédé@example.org successfully authenticated 155s 2024-06-08 01:14:57,154 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 155s 2024-06-08 01:14:57,165 INFO Trying to authenticate http://127.0.0.1:8082 again 155s 2024-06-08 01:14:57,169 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 155s 2024-06-08 01:14:57,177 INFO User dédé@example.net successfully authenticated 155s 2024-06-08 01:14:57,186 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 155s 2024-06-08 01:14:57,239 INFO Trying to authenticate http://127.0.0.1:8083 again 155s 2024-06-08 01:14:57,245 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 155s 2024-06-08 01:14:57,253 INFO User dédé@example.test successfully authenticated 155s 2024-06-08 01:14:57,262 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 155s 2024-06-08 01:14:57,264 WARNING User already authenticated, dropping federated authentication request 155s 2024-06-08 01:14:57,265 WARNING User already authenticated, dropping federated authentication request 155s 2024-06-08 01:14:57,266 WARNING User already authenticated, dropping federated authentication request 155s 2024-06-08 01:14:57,267 WARNING User already authenticated, dropping federated authentication request 155s PASSED 155s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2024-06-08 01:14:57,281 INFO Trying to authenticate http://127.0.0.1:8080 again 155s 2024-06-08 01:14:57,287 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 155s 2024-06-08 01:14:57,296 INFO User dédé@example.com successfully authenticated 155s 2024-06-08 01:14:57,306 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 155s 2024-06-08 01:14:57,316 INFO Trying to authenticate http://127.0.0.1:8081 again 155s 2024-06-08 01:14:57,320 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 155s 2024-06-08 01:14:57,331 INFO User dédé@example.org successfully authenticated 155s 2024-06-08 01:14:57,341 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 155s 2024-06-08 01:14:57,351 INFO Trying to authenticate http://127.0.0.1:8082 again 155s 2024-06-08 01:14:57,356 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 155s 2024-06-08 01:14:57,365 INFO User dédé@example.net successfully authenticated 155s 2024-06-08 01:14:57,374 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 155s 2024-06-08 01:14:57,389 INFO Trying to authenticate http://127.0.0.1:8083 again 155s 2024-06-08 01:14:57,395 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 155s 2024-06-08 01:14:57,406 INFO User dédé@example.test successfully authenticated 155s 2024-06-08 01:14:57,415 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 155s 2024-06-08 01:14:57,416 INFO logout requested 155s 2024-06-08 01:14:57,418 INFO Logging out user dédé@example.com. 155s 2024-06-08 01:14:57,423 INFO User dédé@example.com logged out 155s 2024-06-08 01:14:57,425 INFO logout requested 155s 2024-06-08 01:14:57,427 INFO Logging out user dédé@example.org. 155s 2024-06-08 01:14:57,432 INFO User dédé@example.org logged out 155s 2024-06-08 01:14:57,435 INFO logout requested 155s 2024-06-08 01:14:57,436 INFO Logging out user dédé@example.net. 155s 2024-06-08 01:14:57,442 INFO User dédé@example.net logged out 155s 2024-06-08 01:14:57,445 INFO logout requested 155s 2024-06-08 01:14:57,447 INFO Logging out user dédé@example.test. 155s 2024-06-08 01:14:57,451 INFO User dédé@example.test logged out 155s PASSED 155s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2024-06-08 01:14:57,471 INFO Trying to authenticate http://127.0.0.1:8080 again 155s 2024-06-08 01:14:57,475 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 155s 2024-06-08 01:14:57,485 INFO User dédé@example.com successfully authenticated 155s 2024-06-08 01:14:57,495 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 155s 2024-06-08 01:14:57,509 INFO Trying to authenticate http://127.0.0.1:8081 again 155s 2024-06-08 01:14:57,515 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 155s 2024-06-08 01:14:57,527 INFO User dédé@example.org successfully authenticated 155s 2024-06-08 01:14:57,537 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 155s 2024-06-08 01:14:57,548 INFO Trying to authenticate http://127.0.0.1:8082 again 155s 2024-06-08 01:14:57,552 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 155s 2024-06-08 01:14:57,562 INFO User dédé@example.net successfully authenticated 155s 2024-06-08 01:14:57,571 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 155s 2024-06-08 01:14:57,583 INFO Trying to authenticate http://127.0.0.1:8083 again 155s 2024-06-08 01:14:57,587 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 155s 2024-06-08 01:14:57,599 INFO User dédé@example.test successfully authenticated 155s 2024-06-08 01:14:57,611 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 155s 2024-06-08 01:14:57,617 INFO Trying to authenticate http://127.0.0.1:8080 again 155s 2024-06-08 01:14:57,623 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 155s 2024-06-08 01:14:57,634 INFO User dédé@example.com successfully authenticated 155s 2024-06-08 01:14:57,640 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 155s 2024-06-08 01:14:57,647 INFO Trying to authenticate http://127.0.0.1:8081 again 155s 2024-06-08 01:14:57,652 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 155s 2024-06-08 01:14:57,663 INFO User dédé@example.org successfully authenticated 155s 2024-06-08 01:14:57,668 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 155s 2024-06-08 01:14:57,675 INFO Trying to authenticate http://127.0.0.1:8082 again 155s 2024-06-08 01:14:57,680 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 155s 2024-06-08 01:14:57,693 INFO User dédé@example.net successfully authenticated 155s 2024-06-08 01:14:57,697 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 155s 2024-06-08 01:14:57,704 INFO Trying to authenticate http://127.0.0.1:8083 again 155s 2024-06-08 01:14:57,709 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 155s 2024-06-08 01:14:57,721 INFO User dédé@example.test successfully authenticated 155s 2024-06-08 01:14:57,725 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 155s PASSED 155s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 155s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 155s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 155s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2024-06-08 01:14:57,770 INFO User test successfully authenticated 155s PASSED 155s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2024-06-08 01:14:57,789 INFO User test successfully authenticated 155s PASSED 155s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2024-06-08 01:14:57,809 INFO User test successfully authenticated 155s 2024-06-08 01:14:57,825 INFO User test successfully authenticated 155s 2024-06-08 01:14:57,839 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 155s 2024-06-08 01:14:57,842 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')) 155s PASSED 155s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2024-06-08 01:14:57,857 INFO User test successfully authenticated 155s PASSED 155s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2024-06-08 01:14:57,876 INFO User test successfully authenticated 155s PASSED 155s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2024-06-08 01:14:57,907 INFO User test successfully authenticated 155s 2024-06-08 01:14:57,927 INFO Sending SLO requests to service http://127.0.0.1:43687 for user test 155s 2024-06-08 01:14:57,931 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 155s PASSEDConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 155s 155s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2024-06-08 01:14:57,964 INFO User test successfully authenticated 156s PASSED 156s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 156s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 156s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 156s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 156s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 156s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 156s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 156s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 156s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 156s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 156s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 156s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 156s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 156s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 156s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 156s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 156s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 156s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 156s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 157s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 157s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 157s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 157s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2024-06-08 01:14:59,557 INFO User test successfully authenticated 157s 2024-06-08 01:14:59,564 WARNING Service https://www.example.org not allowed. 157s PASSED 157s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success 2024-06-08 01:14:59,579 INFO User test successfully authenticated 157s 2024-06-08 01:14:59,586 INFO Service ticket created for service https://www.example.com by user test. 157s 2024-06-08 01:14:59,599 INFO User test successfully authenticated 157s 2024-06-08 01:14:59,605 INFO Service ticket created for service https://www.example.com by user test. 157s PASSED 157s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt PASSED 157s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn 2024-06-08 01:14:59,621 INFO User test successfully authenticated 157s PASSED 157s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged 2024-06-08 01:14:59,641 INFO User test successfully authenticated 157s PASSED 157s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2024-06-08 01:14:59,664 INFO User test successfully authenticated 157s 2024-06-08 01:14:59,672 WARNING User test seems authenticated but is not found in the database. 157s 2024-06-08 01:14:59,672 INFO Logging out user test. 157s 2024-06-08 01:14:59,673 INFO User test logged out 157s PASSED 157s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 157s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2024-06-08 01:14:59,692 INFO User test successfully authenticated 157s 2024-06-08 01:14:59,697 WARNING Username test not allowed on service restrict_user_fail 157s PASSED 157s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2024-06-08 01:14:59,711 WARNING A login attempt failed 157s 2024-06-08 01:14:59,728 WARNING A login attempt failed 157s 2024-06-08 01:14:59,744 WARNING A login attempt failed 157s PASSED 157s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2024-06-08 01:14:59,763 WARNING Received an invalid login ticket 157s PASSED 157s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2024-06-08 01:14:59,784 WARNING A login attempt failed 157s PASSED 157s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2024-06-08 01:14:59,809 INFO User test successfully authenticated 157s PASSED 157s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2024-06-08 01:14:59,829 INFO User test successfully authenticated 157s PASSED 159s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 159s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 159s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 159s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 159s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 159s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 159s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 159s cas_server/tests/test_view.py::LoginTestCase::test_renew 2024-06-08 01:15:01,411 INFO User test successfully authenticated 159s 2024-06-08 01:15:01,424 INFO User test successfully authenticated 159s 2024-06-08 01:15:01,429 INFO Service ticket created for service https://www.example.com by user test. 159s PASSED 159s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2024-06-08 01:15:01,444 INFO User test successfully authenticated 159s 2024-06-08 01:15:01,459 INFO User test successfully authenticated 159s 2024-06-08 01:15:01,464 INFO Service ticket created for service https://www.example.com by user test. 159s PASSED 159s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2024-06-08 01:15:01,480 INFO User test successfully authenticated 159s 2024-06-08 01:15:01,493 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 159s 2024-06-08 01:15:01,516 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 159s 2024-06-08 01:15:01,534 INFO Service ticket created for service https://filter_success.example.com by user test. 159s PASSED 159s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2024-06-08 01:15:01,663 INFO User test successfully authenticated 159s 2024-06-08 01:15:01,693 WARNING Username test not allowed on service restrict_user_fail 159s 2024-06-08 01:15:01,784 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 159s PASSED 159s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2024-06-08 01:15:01,834 INFO User test successfully authenticated 159s 2024-06-08 01:15:01,844 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 159s 2024-06-08 01:15:01,848 INFO Service ticket created for service https://field_needed_success.example.com by user test. 159s PASSED 159s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2024-06-08 01:15:01,886 INFO User test successfully authenticated 159s 2024-06-08 01:15:01,921 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 159s PASSED 159s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2024-06-08 01:15:01,970 INFO User test successfully authenticated 160s 2024-06-08 01:15:01,995 WARNING User test seems authenticated but is not found in the database. 160s 2024-06-08 01:15:01,996 INFO Logging out user test. 160s 2024-06-08 01:15:02,013 INFO User test logged out 160s PASSED 160s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 160s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 160s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2024-06-08 01:15:02,087 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,135 INFO Service ticket created for service https://www.example.com/é by user test. 160s PASSED 160s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2024-06-08 01:15:02,158 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,168 INFO Service ticket created for service https://www.example.com by user test. 160s PASSED 160s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2024-06-08 01:15:02,201 INFO User test successfully authenticated 160s PASSED 160s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2024-06-08 01:15:02,227 WARNING Received a login attempt for an already-active user 160s 2024-06-08 01:15:02,235 INFO Service ticket created for service https://www.example.com by user test. 160s 2024-06-08 01:15:02,269 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,284 WARNING Service https://www.example.org not allowed. 160s PASSED 160s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2024-06-08 01:15:02,298 WARNING Service https://www.example.net not allowed. 160s PASSED 160s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2024-06-08 01:15:02,309 WARNING Service https://www.example.net not allowed. 160s PASSED 160s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2024-06-08 01:15:02,341 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,348 INFO logout requested 160s 2024-06-08 01:15:02,350 INFO Logging out user test. 160s PASSED 160s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2024-06-08 01:15:02,356 INFO User test logged out 160s 2024-06-08 01:15:02,371 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,394 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,406 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,419 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,431 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,437 INFO logout requested 160s 2024-06-08 01:15:02,437 INFO Logging out user test from all sessions. 160s 2024-06-08 01:15:02,460 INFO User test logged out 160s PASSED 160s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2024-06-08 01:15:02,472 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,477 INFO logout requested 160s 2024-06-08 01:15:02,478 INFO Logging out user test. 160s 2024-06-08 01:15:02,483 INFO User test logged out 160s PASSED 160s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2024-06-08 01:15:02,487 INFO logout requested 160s PASSED 160s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2024-06-08 01:15:02,504 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,516 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,521 INFO logout requested 160s 2024-06-08 01:15:02,522 INFO Logging out user test from all sessions. 160s 2024-06-08 01:15:02,535 INFO User test logged out 160s PASSED 160s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2024-06-08 01:15:02,549 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,559 INFO Service ticket created for service http://127.0.0.1:45809 by user test. 160s 2024-06-08 01:15:02,564 INFO Validate: Service ticket ST-YkpdB3QMyHiwibsPtcHCrtHkFWWqj1accxmUUkIsf5opYr7GWXixe8EEioU9I validated, user test authenticated on service http://127.0.0.1:45809 160s 2024-06-08 01:15:02,566 INFO logout requested 160s 2024-06-08 01:15:02,566 INFO Logging out user test. 160s 2024-06-08 01:15:02,570 INFO Sending SLO requests to service http://127.0.0.1:45809 for user test 160s 2024-06-08 01:15:02,573 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 160s 2024-06-08 01:15:02,575 INFO User test logged out 160s 2024-06-08 01:15:02,591 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,600 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 160s 2024-06-08 01:15:02,607 INFO Validate: Service ticket ST-ig6KClBdccGnsIEkkdtu1KjUv4mtcEen5gFkOk7UkGE1otGm2YCAFPe9bVllt validated, user test authenticated on service http://127.0.0.1:45678 160s 2024-06-08 01:15:02,610 INFO logout requested 160s 2024-06-08 01:15:02,611 INFO Logging out user test. 160s 2024-06-08 01:15:02,614 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 160s 2024-06-08 01:15:02,617 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 160s 2024-06-08 01:15:02,619 INFO User test logged out 160s 2024-06-08 01:15:02,628 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,636 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 160s 2024-06-08 01:15:02,640 INFO Validate: Service ticket ST-62iTyr1ROt4WiNgJ4pSlDJb5Cz8a4TBvGYOOERcBcwp0FMSGxjnMceDsJPLnf validated, user test authenticated on service http://127.0.0.1:45678 160s 2024-06-08 01:15:02,641 INFO logout requested 160s 2024-06-08 01:15:02,642 INFO Logging out user test. 160s 2024-06-08 01:15:02,646 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 160s 2024-06-08 01:15:02,648 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=40961): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 160s 2024-06-08 01:15:02,650 INFO User test logged out 160s PASSED 160s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2024-06-08 01:15:02,661 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,673 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,678 INFO logout requested 160s 2024-06-08 01:15:02,679 INFO Logging out user test. 160s 2024-06-08 01:15:02,684 INFO User test logged out 160s PASSED 160s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2024-06-08 01:15:02,709 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,717 INFO logout requested 160s 2024-06-08 01:15:02,718 INFO Logging out user test. 160s 2024-06-08 01:15:02,728 INFO User test logged out 160s PASSED 160s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2024-06-08 01:15:02,770 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,776 INFO logout requested 160s 2024-06-08 01:15:02,777 INFO Logging out user test. 160s 2024-06-08 01:15:02,785 INFO User test logged out 160s PASSED 160s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2024-06-08 01:15:02,826 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,834 INFO logout requested 160s 2024-06-08 01:15:02,835 INFO Logging out user test. 160s 2024-06-08 01:15:02,842 INFO User test logged out 160s PASSED 160s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2024-06-08 01:15:02,861 INFO User test successfully authenticated 160s 2024-06-08 01:15:02,867 INFO logout requested 160s 2024-06-08 01:15:02,867 INFO Logging out user test. 160s 2024-06-08 01:15:02,875 INFO User test logged out 160s 2024-06-08 01:15:02,890 INFO logout requested 160s PASSED 160s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 160s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 160s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice 2024-06-08 01:15:02,948 WARNING Service https://www.example.org not allowed. 160s PASSED 160s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings PASSED 160s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 161s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2024-06-08 01:15:02,980 INFO User test successfully authenticated 161s PASSED 161s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 161s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2024-06-08 01:15:03,018 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,033 INFO Service ticket created for service https://www.example.com by user test. 161s 2024-06-08 01:15:03,050 WARNING Validate: service or ticket missing 161s 2024-06-08 01:15:03,051 WARNING Validate: service or ticket missing 161s PASSED 161s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2024-06-08 01:15:03,103 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,108 INFO Service ticket created for service https://www.example.com by user test. 161s 2024-06-08 01:15:03,114 INFO Validate: Service ticket ST-Mnq5zHdxR79tbcggJUQmAAYpbPpHQqsDfYsLupFNJAfkMWjmklRBuVOee7ngX validated, user test authenticated on service https://www.example.com 161s 2024-06-08 01:15:03,121 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,125 INFO Service ticket created for service https://www.example.com by user test. 161s 2024-06-08 01:15:03,127 INFO Validate: Service ticket ST-WcBquJR5omj6TRwXsxTMhIoEgpsBk18dUId49LUiIXvm64biEdjyJ4rVVsIxH validated, user test authenticated on service https://www.example.com 161s 2024-06-08 01:15:03,131 INFO Service ticket created for service https://www.example.com by user test. 161s 2024-06-08 01:15:03,133 WARNING Validate: Service ticket ST-BjVOLm1Y3BVbP1yp7EHBGgY8nYZ41PTqcqmFsa69YtGIANRTMStOIx8tuuNFr not found or already validated, auth to https://www.example.com failed 161s PASSED 161s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2024-06-08 01:15:03,146 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,152 INFO Service ticket created for service https://user_field.example.com by user test. 161s 2024-06-08 01:15:03,155 INFO Validate: Service ticket ST-dxeIFb0kKhOrd4z8mxXlwj5A0n7Sk8btvnz4SBheRSeZcHbb6OBlmq25BqM3B validated, user test authenticated on service https://user_field.example.com 161s 2024-06-08 01:15:03,164 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,172 INFO Service ticket created for service https://user_field_alt.example.com by user test. 161s 2024-06-08 01:15:03,175 INFO Validate: Service ticket ST-suaMt8HjZZC9TleanSIXiw6AQ4wvImnfVruyXRBQ6K58QDkL1WJ0ntUi9csMx validated, user test authenticated on service https://user_field_alt.example.com 161s PASSED 161s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2024-06-08 01:15:03,187 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,193 INFO Service ticket created for service https://www.example.com by user test. 161s 2024-06-08 01:15:03,196 WARNING Validate: Service ticket ST-3dsGcytfAkhVrcZtLAJbZVs7NEmANIgTkxvqAkuc1BXj4vFRDr2e02xYvFCin not found or already validated, auth to https://www.example.org failed 161s PASSED 161s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2024-06-08 01:15:03,207 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,215 INFO Service ticket created for service https://www.example.com by user test. 161s PASSED 161s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2024-06-08 01:15:03,218 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 161s 2024-06-08 01:15:03,229 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,236 INFO Service ticket created for service https://www.example.com by user test. 161s 2024-06-08 01:15:03,239 INFO Validate: Service ticket ST-7npYZJKfRb4nSxi1mbQ25nIg8kGiqwjrmwko3VJNrf6fDgn3EpO7FiWH32aqC validated, user test authenticated on service https://www.example.com 161s PASSED 161s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2024-06-08 01:15:03,254 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,262 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 161s 2024-06-08 01:15:03,264 WARNING ValidateService: missing ticket or service 161s 2024-06-08 01:15:03,265 WARNING ValidateService: missing ticket or service 161s PASSED 161s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2024-06-08 01:15:03,280 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,288 INFO Service ticket created for service http://127.0.0.1:42513 by user test. 161s 2024-06-08 01:15:03,294 INFO ValidateService: ticket ST-3V6xKw1UtfnjKLgLjwSf90nQPp0PeAZWEgVFqbSOm70BUljYoo3bzG4kDa2n6 validated for user test on service http://127.0.0.1:42513. Proxy Granting Ticket transmited to http://127.0.0.1:42513. 161s 2024-06-08 01:15:03,300 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 161s 2024-06-08 01:15:03,305 INFO ValidateService: ticket PT-4yd3tyuIGKQxblzKQ0dhPkmG7rDjM2m8NWSpvZMNxHG80pVtozjlhgUKOBblW validated for user test on service http://127.0.0.1:45678. 161s 2024-06-08 01:15:03,315 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,323 INFO Service ticket created for service http://127.0.0.1:44433 by user test. 161s 2024-06-08 01:15:03,329 INFO ValidateService: ticket ST-id7pbQJZaSRFD47wlDLtK8KmbfWDfUJ5JNxDhKueQmBktCK5Kz6jCWuAwccsl validated for user test on service http://127.0.0.1:44433. Proxy Granting Ticket transmited to http://127.0.0.1:44433. 161s 2024-06-08 01:15:03,335 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 161s 2024-06-08 01:15:03,336 WARNING ValidateService: validation error: INVALID_TICKET PT-WKJtu4RJWIHLS9b6wCtL6wKNZGTbERvOybHM4lrViTU1MTuz2oQwBBIFaeatU 161s PASSED 161s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2024-06-08 01:15:03,347 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,355 INFO Service ticket created for service https://replace_attribute.example.com by user test. 161s 2024-06-08 01:15:03,358 INFO ValidateService: ticket ST-AxxwAam5ED2OdWCoEHtrnWvKm4VtsWSzuooc4fAZUVaVlsKw9Kq80z0kZDVOE validated for user test on service https://replace_attribute.example.com. 161s 2024-06-08 01:15:03,367 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,373 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 161s 2024-06-08 01:15:03,376 INFO ValidateService: ticket ST-xwXu9pNQARKzayXtHtcF3lqtsZpgyaCgHVoEL7QRyPeygOcwy6tUVD9cevB6e validated for user test on service https://replace_attribute_list.example.com. 161s PASSED 161s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2024-06-08 01:15:03,387 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,394 INFO Service ticket created for service http://127.0.0.1:35829 by user test. 161s 2024-06-08 01:15:03,401 INFO ValidateService: ticket ST-JMZDzJDWUZvrD0FcJhGFSTVtirqcFsazrhFW0PEZ3jDIfF98A1tD9ZmFQSqHB validated for user test on service http://127.0.0.1:35829. Proxy Granting Ticket transmited to http://127.0.0.1:35829. 161s PASSED 161s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2024-06-08 01:15:03,417 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,426 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 161s 2024-06-08 01:15:03,431 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 161s 2024-06-08 01:15:03,446 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,468 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 161s 2024-06-08 01:15:03,475 WARNING Service https://www.example.org not allowed. 161s 2024-06-08 01:15:03,475 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 161s PASSED 161s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2024-06-08 01:15:03,495 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,514 INFO Service ticket created for service https://127.0.0.1:37249 by user test. 161s 2024-06-08 01:15:03,557 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=37249): Max retries exceeded with url: /?pgtId=PGT-2Yr2wDdVQafdU9d0D2n0Z3cwpgbtY9Jf7gpu43FVA6lYGhLHfTWTQUIWEcGX&pgtIou=PGTIOU-4OxPvdADwp6Bb5PW3OH6Rfzg0fDzsLGWpmvEmM7STW44kkA4PNSYvAtqb (Caused by SSLError(SSLError(1, '[SSL: WRONG_VERSION_NUMBER] wrong version number (_ssl.c:1000)'))) 161s PASSED 161s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2024-06-08 01:15:03,571 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,575 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 161s 2024-06-08 01:15:03,579 INFO ValidateService: ticket ST-HUGV5V4eYYedwMHKF3eXlxAmM9aTtQM79YxFWm9M1FMSq2KEnORYSP5AsxGmz validated for user test on service http://127.0.0.1:45678. 161s 2024-06-08 01:15:03,587 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,591 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 161s 2024-06-08 01:15:03,595 INFO ValidateService: ticket ST-XUTfQarUGGjOf6wQ9CXfeOn9xRbGuwO5DhnA5RrSjLg9dtIR8Dw1iBk1Mh6iM validated for user test on service http://127.0.0.1:45678. 161s 2024-06-08 01:15:03,600 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 161s 2024-06-08 01:15:03,602 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 161s PASSED 161s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2024-06-08 01:15:03,613 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,619 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 161s PASSED 161s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2024-06-08 01:15:03,621 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 161s 2024-06-08 01:15:03,633 INFO User test successfully authenticated 161s PASSED 161s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2024-06-08 01:15:03,639 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 161s 2024-06-08 01:15:03,641 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 161s 2024-06-08 01:15:03,652 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,658 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 161s 2024-06-08 01:15:03,660 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 161s PASSED 161s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2024-06-08 01:15:03,670 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,676 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 161s 2024-06-08 01:15:03,681 INFO ValidateService: ticket ST-KtPHFQIG9Sr0h2VdOIl412IqRT6dmSnjtCUEUM6O6TdEtXaXCIzFj44yWXBLf validated for user test on service http://127.0.0.1:45678. 161s PASSED 161s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2024-06-08 01:15:03,691 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,698 INFO Service ticket created for service https://one_attribute.example.com by user test. 161s 2024-06-08 01:15:03,701 INFO ValidateService: ticket ST-7ePNv7eKTD4HT3qzdOyysXoVkplJHCKoVqDaXXm7PbOJaVgpsuTXmnJZvnKSp validated for user test on service https://one_attribute.example.com. 161s PASSED 161s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2024-06-08 01:15:03,712 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,720 INFO Service ticket created for service http://127.0.0.1:35187 by user test. 161s 2024-06-08 01:15:03,725 INFO ValidateService: ticket ST-3jf8ZwnxCV1gje7XjkmqPgZ7JiW4kqbYhpHvhKTHwjLfdYivYhrbSXUbBIjFF validated for user test on service http://127.0.0.1:35187. Proxy Granting Ticket transmited to http://127.0.0.1:35187. 161s PASSED 161s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2024-06-08 01:15:03,736 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,742 INFO Service ticket created for service https://user_field.example.com by user test. 161s 2024-06-08 01:15:03,745 INFO ValidateService: ticket ST-vznohcFBelIMKwANLd9y4eBV5EkmrxXMrTlPHDyuPm67dfnCEUs4iRJzi0b4D validated for user test on service https://user_field.example.com. 161s 2024-06-08 01:15:03,753 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,759 INFO Service ticket created for service https://user_field_alt.example.com by user test. 161s 2024-06-08 01:15:03,762 INFO ValidateService: ticket ST-6sOZkhxzcsYH8Z1QPCx98HR2vAOWzcbYdz9FWzhakBhqZHEhqiUk0xdAQqe1S validated for user test on service https://user_field_alt.example.com. 161s PASSED 161s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2024-06-08 01:15:03,776 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,783 INFO Service ticket created for service http://127.0.0.1:45839 by user test. 161s 2024-06-08 01:15:03,789 INFO ValidateService: ticket ST-ZtT4hKJfZik57KTR1eo9iyqwji0qtbIHY5eVaJztWoUE0c0E9CbnerlP6eGtj validated for user test on service http://127.0.0.1:45839. Proxy Granting Ticket transmited to http://127.0.0.1:45839. 161s 2024-06-08 01:15:03,790 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 161s 2024-06-08 01:15:03,791 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 161s PASSED 161s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2024-06-08 01:15:03,803 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,810 INFO Service ticket created for service http://127.0.0.1:33057 by user test. 161s 2024-06-08 01:15:03,816 INFO ValidateService: ticket ST-6AligXbF9ZLqOfy82R1G7XIgRI3NIFvLs0K6lxDlT2QZvo2j22onQzb5NYgdk validated for user test on service http://127.0.0.1:33057. Proxy Granting Ticket transmited to http://127.0.0.1:33057. 161s PASSED2024-06-08 01:15:03,819 WARNING Username test not allowed on service restrict_user_fail 161s 2024-06-08 01:15:03,819 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 161s 2024-06-08 01:15:03,821 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 161s 2024-06-08 01:15:03,821 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 161s 2024-06-08 01:15:03,824 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 161s 2024-06-08 01:15:03,824 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 161s 161s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2024-06-08 01:15:03,836 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,843 INFO Service ticket created for service http://127.0.0.1:33287 by user test. 161s 2024-06-08 01:15:03,849 INFO ValidateService: ticket ST-vLVkxrMvBXg0JZu0V8UWhNhKyqkXIZtMnecJ0cBZ40aVZdSNZUpYcUC8V74ht validated for user test on service http://127.0.0.1:33287. Proxy Granting Ticket transmited to http://127.0.0.1:33287. 161s 2024-06-08 01:15:03,851 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 161s PASSED 161s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2024-06-08 01:15:03,866 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,875 INFO Service ticket created for service http://127.0.0.1:41241 by user test. 161s 2024-06-08 01:15:03,882 INFO ValidateService: ticket ST-kofZCLd2bbP478YrjOqPWWxN3g28FmBiouhIiNgpeg9nwDjRrXQYP3mV3g5WE validated for user test on service http://127.0.0.1:41241. Proxy Granting Ticket transmited to http://127.0.0.1:41241. 161s 2024-06-08 01:15:03,883 WARNING Service https://www.example.org not allowed. 161s 2024-06-08 01:15:03,884 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 161s 2024-06-08 01:15:03,886 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:41241 does not allow proxy tickets 161s PASSED 161s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2024-06-08 01:15:03,898 INFO User test successfully authenticated 161s 2024-06-08 01:15:03,907 INFO Service ticket created for service http://127.0.0.1:45383 by user test. 161s 2024-06-08 01:15:03,915 INFO ValidateService: ticket ST-E4dmNkqSCaavZqSZdtZtCxfxvfHfTvzChEqpI1dhGXFXg4IaiKIXvKHsOX6DM validated for user test on service http://127.0.0.1:45383. Proxy Granting Ticket transmited to http://127.0.0.1:45383. 161s 2024-06-08 01:15:03,922 INFO Proxy ticket created for user test on service https://www.example.com. 162s 2024-06-08 01:15:03,970 INFO ValidateService: ticket PT-4ABpdIUKz3cuMRRrPKgC6Sk3J2vpKWU2ciX5laqJUvvYIlZnAOgR1NU24qx7n validated for user test on service https://www.example.com. 162s PASSED 162s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2024-06-08 01:15:03,987 INFO User test successfully authenticated 162s 2024-06-08 01:15:03,997 INFO Service ticket created for service https://www.example.com by user test. 162s 2024-06-08 01:15:04,001 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 162s PASSED 162s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2024-06-08 01:15:04,009 WARNING SamlValidate: validation error: AuthnFailed ticket ST-urvuclX5Z0EtgBbxZOC4ecp3aGMg847tSi1i7aUD400xJb4ZANRUhwjM02Tqi not found 162s 2024-06-08 01:15:04,011 WARNING SamlValidate: validation error: AuthnFailed ticket PT-WUXtyOsLdEgbaogFRJBzD195Bczt90PoMtPLsvz7CMhD0AVjjECwkaYcSp0zm not found 162s PASSED 162s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix 2024-06-08 01:15:04,017 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 162s PASSED 162s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml PASSED2024-06-08 01:15:04,023 WARNING SamlValidate: validation error: VersionMismatch 162s 162s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2024-06-08 01:15:04,036 INFO User test successfully authenticated 162s 2024-06-08 01:15:04,044 INFO Service ticket created for service https://www.example.com by user test. 162s 2024-06-08 01:15:04,055 INFO User test successfully authenticated 162s 2024-06-08 01:15:04,063 INFO Service ticket created for service http://127.0.0.1:44199 by user test. 162s 2024-06-08 01:15:04,069 INFO ValidateService: ticket ST-c9PcMuC9WvgmPKt3w77pc6PPIM7siC14F9xtHfw2Y9bWBuaxG6dJweTIJ5lvv validated for user test on service http://127.0.0.1:44199. Proxy Granting Ticket transmited to http://127.0.0.1:44199. 162s 2024-06-08 01:15:04,073 INFO Proxy ticket created for user test on service https://www.example.com. 162s 2024-06-08 01:15:04,076 INFO SamlValidate: ticket ST-Q2JQAQrmZunCyfVst26igd60rTInObY9n6xwHcvuyRVxeeLOevglP4hyH8d6S validated for user test on service https://www.example.com. 162s 2024-06-08 01:15:04,080 INFO SamlValidate: ticket PT-l2Gvz3K8jtZiXgx7a93pJYVGzFi4zl8iwj3tfr6uIleD5C65uB9pW1lkCxK7q validated for user test on service https://www.example.com. 162s PASSED 162s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2024-06-08 01:15:04,093 INFO User test successfully authenticated 162s 2024-06-08 01:15:04,099 INFO Service ticket created for service https://field_needed_success.example.com by user test. 162s 2024-06-08 01:15:04,103 INFO SamlValidate: ticket ST-wlFLelxi9T2i66H0W2uRnIHpuunCiommIoaS3oKWhq9mQVuiGZVtg3Fhg98dN validated for user test on service https://field_needed_success.example.com. 162s 2024-06-08 01:15:04,112 INFO User test successfully authenticated 162s 2024-06-08 01:15:04,119 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 162s 2024-06-08 01:15:04,123 INFO SamlValidate: ticket ST-75BrqjzDey4F0zIqdI1ILOEUhUNh6iqADWGrciG4inB4WtO8hXhTgmB3tJwgF validated for user test on service https://field_needed_success_alt.example.com. 162s PASSEDDestroying test database for alias 'default'... 162s 162s 162s =============================== warnings summary =============================== 162s cas_server/utils.py:33 162s /tmp/autopkgtest.IJYb6q/autopkgtest_tmp/cas_server/utils.py:33: DeprecationWarning: 'crypt' is deprecated and slated for removal in Python 3.13 162s import crypt 162s 162s cas_server/tests/utils.py:20 162s /tmp/autopkgtest.IJYb6q/autopkgtest_tmp/cas_server/tests/utils.py:20: DeprecationWarning: 'cgi' is deprecated and slated for removal in Python 3.13 162s import cgi 162s 162s cas_server/tests/test_federate.py: 8 warnings 162s cas_server/tests/test_view.py: 3 warnings 162s /tmp/autopkgtest.IJYb6q/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). 162s datetime.utcnow() + timedelta(seconds=max_age), 162s 162s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 162s /usr/lib/python3.12/unittest/case.py:690: DeprecationWarning: It is deprecated to return a value that is not None from a test case (>) 162s return self.run(*args, **kwds) 162s 162s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 162s ====================== 128 passed, 14 warnings in 10.76s ======================= 162s 162s autopkgtest [01:15:04]: test run-test-suite.sh: -----------------------] 163s run-test-suite.sh PASS 163s autopkgtest [01:15:05]: test run-test-suite.sh: - - - - - - - - - - results - - - - - - - - - - 163s autopkgtest [01:15:05]: @@@@@@@@@@@@@@@@@@@@ summary 163s run-test-suite.sh PASS 194s nova [W] Using flock in scalingstack-bos01-s390x 194s Creating nova instance adt-oracular-s390x-django-cas-server-20240608-005958-juju-7f2275-prod-proposed-migration-environment-3-789e86cb-ed19-4906-a937-ae04f79d9c85 from image adt/ubuntu-oracular-s390x-server-20240607.img (UUID 7eeb2415-a1eb-49f2-b205-8690074843ed)...