0s autopkgtest [12:41:51]: starting date and time: 2025-10-26 12:41:51+0000 0s autopkgtest [12:41:51]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [12:41:51]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.na3j8qyk/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults --apt-upgrade django-cas-server --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=python3-defaults/3.13.7-2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-14.secgroup --name adt-resolute-s390x-django-cas-server-20251026-124151-juju-7f2275-prod-proposed-migration-environment-15-b9d9cbc7-90bd-488d-a7ee-e150284e6201 --image adt/ubuntu-resolute-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-s390x-django-cas-server-20251026-124151-juju-7f2275-prod-proposed-migration-environment-15-b9d9cbc7-90bd-488d-a7ee-e150284e6201 from image adt/ubuntu-resolute-s390x-server-20251026.img (UUID be60aa04-f66a-4616-9409-44144e7aad62)... 52s autopkgtest [12:42:43]: testbed dpkg architecture: s390x 52s autopkgtest [12:42:43]: testbed apt version: 3.1.8ubuntu1 52s autopkgtest [12:42:43]: @@@@@@@@@@@@@@@@@@@@ test bed setup 52s autopkgtest [12:42:43]: testbed release detected to be: None 53s autopkgtest [12:42:44]: updating testbed package index (apt update) 53s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [87.8 kB] 53s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 54s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 54s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 54s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [16.5 kB] 54s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [413 kB] 54s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [66.4 kB] 54s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5028 B] 54s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x Packages [149 kB] 54s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/restricted s390x Packages [940 B] 54s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x Packages [275 kB] 54s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse s390x Packages [4204 B] 54s Fetched 1018 kB in 1s (966 kB/s) 55s Reading package lists... 55s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 56s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 56s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 56s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 56s Reading package lists... 56s Reading package lists... 56s Building dependency tree... 56s Reading state information... 57s Calculating upgrade... 57s The following packages will be upgraded: 57s binutils binutils-common binutils-s390x-linux-gnu gir1.2-glib-2.0 57s libbinutils libctf-nobfd0 libctf0 libglib2.0-0t64 libglib2.0-data 57s libpython3-stdlib libsframe2 python3 python3-minimal 57s 13 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 57s Need to get 3771 kB of archives. 57s After this operation, 3072 B of additional disk space will be used. 57s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x python3-minimal s390x 3.13.7-2 [27.8 kB] 57s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x python3 s390x 3.13.7-2 [23.9 kB] 57s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x libpython3-stdlib s390x 3.13.7-2 [10.6 kB] 57s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x gir1.2-glib-2.0 s390x 2.86.1-1 [180 kB] 57s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x libglib2.0-0t64 s390x 2.86.1-1 [1601 kB] 58s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x libglib2.0-data all 2.86.1-1 [56.7 kB] 58s Get:7 http://ftpmaster.internal/ubuntu resolute/main s390x libctf0 s390x 2.45-8ubuntu1 [103 kB] 58s Get:8 http://ftpmaster.internal/ubuntu resolute/main s390x libctf-nobfd0 s390x 2.45-8ubuntu1 [103 kB] 58s Get:9 http://ftpmaster.internal/ubuntu resolute/main s390x binutils-s390x-linux-gnu s390x 2.45-8ubuntu1 [939 kB] 58s Get:10 http://ftpmaster.internal/ubuntu resolute/main s390x libbinutils s390x 2.45-8ubuntu1 [487 kB] 58s Get:11 http://ftpmaster.internal/ubuntu resolute/main s390x binutils s390x 2.45-8ubuntu1 [3206 B] 58s Get:12 http://ftpmaster.internal/ubuntu resolute/main s390x binutils-common s390x 2.45-8ubuntu1 [221 kB] 58s Get:13 http://ftpmaster.internal/ubuntu resolute/main s390x libsframe2 s390x 2.45-8ubuntu1 [15.3 kB] 58s dpkg-preconfigure: unable to re-open stdin: No such file or directory 58s Fetched 3771 kB in 1s (3809 kB/s) 58s (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 ... 56873 files and directories currently installed.) 58s Preparing to unpack .../python3-minimal_3.13.7-2_s390x.deb ... 58s Unpacking python3-minimal (3.13.7-2) over (3.13.7-1) ... 58s Setting up python3-minimal (3.13.7-2) ... 58s (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 ... 56873 files and directories currently installed.) 58s Preparing to unpack .../00-python3_3.13.7-2_s390x.deb ... 58s running python pre-rtupdate hooks for python3.13... 58s Unpacking python3 (3.13.7-2) over (3.13.7-1) ... 58s Preparing to unpack .../01-libpython3-stdlib_3.13.7-2_s390x.deb ... 58s Unpacking libpython3-stdlib:s390x (3.13.7-2) over (3.13.7-1) ... 58s Preparing to unpack .../02-gir1.2-glib-2.0_2.86.1-1_s390x.deb ... 58s Unpacking gir1.2-glib-2.0:s390x (2.86.1-1) over (2.86.0-2) ... 58s Preparing to unpack .../03-libglib2.0-0t64_2.86.1-1_s390x.deb ... 58s Unpacking libglib2.0-0t64:s390x (2.86.1-1) over (2.86.0-2) ... 58s Preparing to unpack .../04-libglib2.0-data_2.86.1-1_all.deb ... 58s Unpacking libglib2.0-data (2.86.1-1) over (2.86.0-2) ... 59s Preparing to unpack .../05-libctf0_2.45-8ubuntu1_s390x.deb ... 59s Unpacking libctf0:s390x (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 59s Preparing to unpack .../06-libctf-nobfd0_2.45-8ubuntu1_s390x.deb ... 59s Unpacking libctf-nobfd0:s390x (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 59s Preparing to unpack .../07-binutils-s390x-linux-gnu_2.45-8ubuntu1_s390x.deb ... 59s Unpacking binutils-s390x-linux-gnu (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 59s Preparing to unpack .../08-libbinutils_2.45-8ubuntu1_s390x.deb ... 59s Unpacking libbinutils:s390x (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 59s Preparing to unpack .../09-binutils_2.45-8ubuntu1_s390x.deb ... 59s Unpacking binutils (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 59s Preparing to unpack .../10-binutils-common_2.45-8ubuntu1_s390x.deb ... 59s Unpacking binutils-common:s390x (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 59s Preparing to unpack .../11-libsframe2_2.45-8ubuntu1_s390x.deb ... 59s Unpacking libsframe2:s390x (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 59s Setting up libsframe2:s390x (2.45-8ubuntu1) ... 59s Setting up binutils-common:s390x (2.45-8ubuntu1) ... 59s Setting up libctf-nobfd0:s390x (2.45-8ubuntu1) ... 59s Setting up libglib2.0-0t64:s390x (2.86.1-1) ... 59s No schema files found: doing nothing. 59s Setting up libglib2.0-data (2.86.1-1) ... 59s Setting up gir1.2-glib-2.0:s390x (2.86.1-1) ... 59s Setting up libbinutils:s390x (2.45-8ubuntu1) ... 59s Setting up libpython3-stdlib:s390x (3.13.7-2) ... 59s Setting up libctf0:s390x (2.45-8ubuntu1) ... 59s Setting up binutils-s390x-linux-gnu (2.45-8ubuntu1) ... 59s Setting up python3 (3.13.7-2) ... 59s running python rtupdate hooks for python3.13... 59s running python post-rtupdate hooks for python3.13... 59s Setting up binutils (2.45-8ubuntu1) ... 59s Processing triggers for libc-bin (2.42-0ubuntu3) ... 59s Processing triggers for man-db (2.13.1-1) ... 60s autopkgtest [12:42:51]: upgrading testbed (apt dist-upgrade and autopurge) 60s Reading package lists... 60s Building dependency tree... 60s Reading state information... 60s Calculating upgrade... 61s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 61s Reading package lists... 61s Building dependency tree... 61s Reading state information... 61s Solving dependencies... 61s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 63s autopkgtest [12:42:54]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP Mon Sep 22 08:56:47 UTC 2025 64s autopkgtest [12:42:55]: @@@@@@@@@@@@@@@@@@@@ apt-source django-cas-server 66s Get:1 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (dsc) [2597 B] 66s Get:2 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (tar) [138 kB] 66s Get:3 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (diff) [10.2 kB] 66s gpgv: Signature made Sat Dec 14 20:43:18 2024 UTC 66s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 66s gpgv: issuer "tchet@debian.org" 66s gpgv: Can't check signature: No public key 66s dpkg-source: warning: cannot verify inline signature for ./django-cas-server_2.1.0-3.dsc: no acceptable signature found 66s autopkgtest [12:42:57]: testing package django-cas-server version 2.1.0-3 66s autopkgtest [12:42:57]: build not needed 67s autopkgtest [12:42:58]: test run-test-suite.sh: preparing testbed 67s Reading package lists... 67s Building dependency tree... 67s Reading state information... 67s Solving dependencies... 67s The following NEW packages will be installed: 67s fonts-font-awesome fonts-lato libjs-jquery libjs-sphinxdoc libjs-underscore 67s libpython3.14-minimal libpython3.14-stdlib libxslt1.1 python3-all 67s python3-asgiref python3-django python3-django-cas-server 67s python3-django-cas-server-doc python3-iniconfig python3-legacy-cgi 67s python3-lxml python3-packaging python3-pluggy python3-pytest 67s python3-pytest-django python3-requests-futures python3-sqlparse python3.14 67s python3.14-minimal sphinx-rtd-theme-common 67s 0 upgraded, 25 newly installed, 0 to remove and 0 not upgraded. 67s Need to get 17.8 MB of archives. 67s After this operation, 82.4 MB of additional disk space will be used. 67s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x fonts-lato all 2.015-1 [2781 kB] 68s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x libpython3.14-minimal s390x 3.14.0-4 [904 kB] 68s Get:3 http://ftpmaster.internal/ubuntu resolute/main s390x python3.14-minimal s390x 3.14.0-4 [2509 kB] 68s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 68s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 68s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 68s Get:7 http://ftpmaster.internal/ubuntu resolute/main s390x libjs-sphinxdoc all 8.2.3-1ubuntu2 [28.0 kB] 68s Get:8 http://ftpmaster.internal/ubuntu resolute/main s390x libpython3.14-stdlib s390x 3.14.0-4 [2373 kB] 68s Get:9 http://ftpmaster.internal/ubuntu resolute/main s390x libxslt1.1 s390x 1.1.43-0.1 [163 kB] 68s Get:10 http://ftpmaster.internal/ubuntu resolute/main s390x python3.14 s390x 3.14.0-4 [805 kB] 68s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x python3-all s390x 3.13.7-2 [892 B] 68s Get:12 http://ftpmaster.internal/ubuntu resolute/main s390x python3-asgiref all 3.9.1-2 [24.9 kB] 68s Get:13 http://ftpmaster.internal/ubuntu resolute/main s390x python3-sqlparse all 0.5.3-1 [32.9 kB] 68s Get:14 http://ftpmaster.internal/ubuntu resolute/main s390x python3-django all 3:5.2.4-1ubuntu2 [3036 kB] 68s Get:15 http://ftpmaster.internal/ubuntu resolute/main s390x python3-legacy-cgi all 2.6.3-1 [16.8 kB] 68s Get:16 http://ftpmaster.internal/ubuntu resolute/main s390x python3-lxml s390x 6.0.1-1build1 [2480 kB] 69s Get:17 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-requests-futures all 1.0.2-1 [12.0 kB] 69s Get:18 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-django-cas-server all 2.1.0-3 [111 kB] 69s Get:19 http://ftpmaster.internal/ubuntu resolute/main s390x sphinx-rtd-theme-common all 3.0.2+dfsg-3 [1013 kB] 69s Get:20 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-django-cas-server-doc all 2.1.0-3 [178 kB] 69s Get:21 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 69s Get:22 http://ftpmaster.internal/ubuntu resolute/main s390x python3-packaging all 25.0-1 [52.8 kB] 69s Get:23 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pluggy all 1.6.0-1 [21.0 kB] 69s Get:24 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pytest all 8.3.5-2 [252 kB] 69s Get:25 http://ftpmaster.internal/ubuntu resolute/universe s390x python3-pytest-django all 4.11.1-2 [21.8 kB] 69s Fetched 17.8 MB in 2s (10.9 MB/s) 69s Selecting previously unselected package fonts-lato. 69s (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 ... 56873 files and directories currently installed.) 69s Preparing to unpack .../00-fonts-lato_2.015-1_all.deb ... 69s Unpacking fonts-lato (2.015-1) ... 69s Selecting previously unselected package libpython3.14-minimal:s390x. 69s Preparing to unpack .../01-libpython3.14-minimal_3.14.0-4_s390x.deb ... 69s Unpacking libpython3.14-minimal:s390x (3.14.0-4) ... 69s Selecting previously unselected package python3.14-minimal. 69s Preparing to unpack .../02-python3.14-minimal_3.14.0-4_s390x.deb ... 69s Unpacking python3.14-minimal (3.14.0-4) ... 69s Selecting previously unselected package fonts-font-awesome. 69s Preparing to unpack .../03-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 69s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 69s Selecting previously unselected package libjs-jquery. 69s Preparing to unpack .../04-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 69s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 69s Selecting previously unselected package libjs-underscore. 69s Preparing to unpack .../05-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 69s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 69s Selecting previously unselected package libjs-sphinxdoc. 69s Preparing to unpack .../06-libjs-sphinxdoc_8.2.3-1ubuntu2_all.deb ... 69s Unpacking libjs-sphinxdoc (8.2.3-1ubuntu2) ... 69s Selecting previously unselected package libpython3.14-stdlib:s390x. 69s Preparing to unpack .../07-libpython3.14-stdlib_3.14.0-4_s390x.deb ... 69s Unpacking libpython3.14-stdlib:s390x (3.14.0-4) ... 69s Selecting previously unselected package libxslt1.1:s390x. 69s Preparing to unpack .../08-libxslt1.1_1.1.43-0.1_s390x.deb ... 69s Unpacking libxslt1.1:s390x (1.1.43-0.1) ... 69s Selecting previously unselected package python3.14. 69s Preparing to unpack .../09-python3.14_3.14.0-4_s390x.deb ... 69s Unpacking python3.14 (3.14.0-4) ... 69s Selecting previously unselected package python3-all. 69s Preparing to unpack .../10-python3-all_3.13.7-2_s390x.deb ... 69s Unpacking python3-all (3.13.7-2) ... 69s Selecting previously unselected package python3-asgiref. 69s Preparing to unpack .../11-python3-asgiref_3.9.1-2_all.deb ... 69s Unpacking python3-asgiref (3.9.1-2) ... 69s Selecting previously unselected package python3-sqlparse. 69s Preparing to unpack .../12-python3-sqlparse_0.5.3-1_all.deb ... 69s Unpacking python3-sqlparse (0.5.3-1) ... 69s Selecting previously unselected package python3-django. 69s Preparing to unpack .../13-python3-django_3%3a5.2.4-1ubuntu2_all.deb ... 69s Unpacking python3-django (3:5.2.4-1ubuntu2) ... 70s Selecting previously unselected package python3-legacy-cgi. 70s Preparing to unpack .../14-python3-legacy-cgi_2.6.3-1_all.deb ... 70s Unpacking python3-legacy-cgi (2.6.3-1) ... 70s Selecting previously unselected package python3-lxml:s390x. 70s Preparing to unpack .../15-python3-lxml_6.0.1-1build1_s390x.deb ... 70s Unpacking python3-lxml:s390x (6.0.1-1build1) ... 70s Selecting previously unselected package python3-requests-futures. 70s Preparing to unpack .../16-python3-requests-futures_1.0.2-1_all.deb ... 70s Unpacking python3-requests-futures (1.0.2-1) ... 70s Selecting previously unselected package python3-django-cas-server. 70s Preparing to unpack .../17-python3-django-cas-server_2.1.0-3_all.deb ... 70s Unpacking python3-django-cas-server (2.1.0-3) ... 70s Selecting previously unselected package sphinx-rtd-theme-common. 70s Preparing to unpack .../18-sphinx-rtd-theme-common_3.0.2+dfsg-3_all.deb ... 70s Unpacking sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 70s Selecting previously unselected package python3-django-cas-server-doc. 70s Preparing to unpack .../19-python3-django-cas-server-doc_2.1.0-3_all.deb ... 70s Unpacking python3-django-cas-server-doc (2.1.0-3) ... 70s Selecting previously unselected package python3-iniconfig. 70s Preparing to unpack .../20-python3-iniconfig_1.1.1-2_all.deb ... 70s Unpacking python3-iniconfig (1.1.1-2) ... 70s Selecting previously unselected package python3-packaging. 70s Preparing to unpack .../21-python3-packaging_25.0-1_all.deb ... 70s Unpacking python3-packaging (25.0-1) ... 70s Selecting previously unselected package python3-pluggy. 70s Preparing to unpack .../22-python3-pluggy_1.6.0-1_all.deb ... 70s Unpacking python3-pluggy (1.6.0-1) ... 70s Selecting previously unselected package python3-pytest. 70s Preparing to unpack .../23-python3-pytest_8.3.5-2_all.deb ... 70s Unpacking python3-pytest (8.3.5-2) ... 70s Selecting previously unselected package python3-pytest-django. 70s Preparing to unpack .../24-python3-pytest-django_4.11.1-2_all.deb ... 70s Unpacking python3-pytest-django (4.11.1-2) ... 70s Setting up python3-iniconfig (1.1.1-2) ... 70s Setting up fonts-lato (2.015-1) ... 70s Setting up libpython3.14-minimal:s390x (3.14.0-4) ... 70s Setting up python3-packaging (25.0-1) ... 71s Setting up python3-sqlparse (0.5.3-1) ... 71s Setting up python3-pluggy (1.6.0-1) ... 71s Setting up python3-legacy-cgi (2.6.3-1) ... 71s Setting up libxslt1.1:s390x (1.1.43-0.1) ... 71s Setting up python3-requests-futures (1.0.2-1) ... 71s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 71s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 71s Setting up sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 71s Setting up python3.14-minimal (3.14.0-4) ... 72s Setting up python3-asgiref (3.9.1-2) ... 72s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 72s Setting up python3-django (3:5.2.4-1ubuntu2) ... 74s Setting up libpython3.14-stdlib:s390x (3.14.0-4) ... 74s Setting up python3-pytest (8.3.5-2) ... 74s Setting up python3-pytest-django (4.11.1-2) ... 74s Setting up libjs-sphinxdoc (8.2.3-1ubuntu2) ... 74s Setting up python3-lxml:s390x (6.0.1-1build1) ... 75s Setting up python3.14 (3.14.0-4) ... 75s Setting up python3-django-cas-server (2.1.0-3) ... 76s Setting up python3-all (3.13.7-2) ... 76s Setting up python3-django-cas-server-doc (2.1.0-3) ... 76s Processing triggers for systemd (257.9-0ubuntu2) ... 76s Processing triggers for man-db (2.13.1-1) ... 76s Processing triggers for libc-bin (2.42-0ubuntu3) ... 77s autopkgtest [12:43:08]: test run-test-suite.sh: [----------------------- 77s 77s *************************** 77s *** Testing with python3.14 77s *************************** 77s 77s Content of current working folder: 77s 77s total 20 77s drwxr-xr-x 3 ubuntu ubuntu 4096 Oct 26 12:43 . 77s drwxrwxrwx 5 root root 4096 Oct 26 12:43 .. 77s drwxrwxr-x 9 ubuntu ubuntu 4096 Oct 26 12:42 cas_server 77s -rw-rw-r-- 1 ubuntu ubuntu 140 Aug 18 2024 pytest.ini 77s -rw-rw-r-- 1 ubuntu ubuntu 152 Aug 18 2024 setup.cfg 77s Running tests... 77s 78s ============================= test session starts ============================== 78s platform linux -- Python 3.14.0, pytest-8.3.5, pluggy-1.6.0 -- /usr/bin/python3.14 78s cachedir: .pytest_cache 78s django: version: 5.2.4, settings: cas_server.tests.settings (from ini) 78s rootdir: /tmp/autopkgtest.uNEuAX/autopkgtest_tmp 78s configfile: pytest.ini 78s testpaths: cas_server/tests/ 78s plugins: typeguard-4.4.2, django-4.11.1 78s collecting ... collected 128 items 78s 78s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 79s 2025-10-26 12:43:10,026 WARNING Identity provider suffix exemple.fr not found 79s 2025-10-26 12:43:10,041 INFO Got an invalid ticket ST-WhLxt89QPOHLRAtUIkagY0BJy2RjZeUOsiBIR5bevoFf77FXNYcXN9MuBcTqG from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 79s 2025-10-26 12:43:10,045 INFO Got an invalid ticket ST-PlBDqxUPyWRWdCxqxlqtcoHW0EOGMdnwFU8tdUSyvtrOyH716LwdWpKJJUNve from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 79s PASSED 79s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2025-10-26 12:43:10,071 WARNING CAS_FEDERATE is False, set it to True to use federation 79s 2025-10-26 12:43:10,071 WARNING CAS_FEDERATE is False, set it to True to use federation 79s PASSED 79s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2025-10-26 12:43:10,083 INFO Trying to authenticate http://127.0.0.1:8080 again 79s 2025-10-26 12:43:10,091 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 79s 2025-10-26 12:43:10,102 INFO User dédé@example.com successfully authenticated 79s 2025-10-26 12:43:10,110 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 79s 2025-10-26 12:43:10,117 INFO Trying to authenticate http://127.0.0.1:8081 again 79s 2025-10-26 12:43:10,122 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 79s 2025-10-26 12:43:10,129 INFO User dédé@example.org successfully authenticated 79s 2025-10-26 12:43:10,136 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 79s 2025-10-26 12:43:10,144 INFO Trying to authenticate http://127.0.0.1:8082 again 79s 2025-10-26 12:43:10,148 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 79s 2025-10-26 12:43:10,154 INFO User dédé@example.net successfully authenticated 79s 2025-10-26 12:43:10,161 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 79s 2025-10-26 12:43:10,168 INFO Trying to authenticate http://127.0.0.1:8083 again 79s 2025-10-26 12:43:10,173 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 79s 2025-10-26 12:43:10,179 INFO User dédé@example.test successfully authenticated 79s 2025-10-26 12:43:10,187 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 79s 2025-10-26 12:43:10,210 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-caytCjQ9c7wbwBKMhnn1UuvluLda4a4KjsIihb7HqGPhhike5t19f94AQsTka 79s 2025-10-26 12:43:10,230 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-GkzUUjOO5g5zev9mtn2Gj8AlEQhkMQhYKuYpsXI2Q5ZYUZe6UAiG9PuAXUrKz 79s PASSED 79s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 79s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2025-10-26 12:43:10,256 INFO Trying to authenticate http://127.0.0.1:8080 again 79s 2025-10-26 12:43:10,264 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 79s 2025-10-26 12:43:10,270 INFO User dédé@example.com successfully authenticated 79s 2025-10-26 12:43:10,276 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 79s 2025-10-26 12:43:10,283 INFO Trying to authenticate http://127.0.0.1:8081 again 79s 2025-10-26 12:43:10,289 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 79s 2025-10-26 12:43:10,295 INFO User dédé@example.org successfully authenticated 79s 2025-10-26 12:43:10,302 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 79s 2025-10-26 12:43:10,309 INFO Trying to authenticate http://127.0.0.1:8082 again 79s 2025-10-26 12:43:10,313 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 79s 2025-10-26 12:43:10,319 INFO User dédé@example.net successfully authenticated 79s 2025-10-26 12:43:10,326 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 79s 2025-10-26 12:43:10,332 INFO Trying to authenticate http://127.0.0.1:8083 again 79s 2025-10-26 12:43:10,339 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 79s 2025-10-26 12:43:10,345 INFO User dédé@example.test successfully authenticated 79s 2025-10-26 12:43:10,361 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 79s 2025-10-26 12:43:10,362 INFO logout requested 79s 2025-10-26 12:43:10,363 INFO Logging out user dédé@example.com. 79s 2025-10-26 12:43:10,366 INFO User dédé@example.com logged out 79s 2025-10-26 12:43:10,372 INFO logout requested 79s 2025-10-26 12:43:10,374 INFO logout requested 79s 2025-10-26 12:43:10,375 INFO Logging out user dédé@example.org. 79s 2025-10-26 12:43:10,378 INFO User dédé@example.org logged out 79s 2025-10-26 12:43:10,384 INFO logout requested 79s 2025-10-26 12:43:10,386 INFO logout requested 79s 2025-10-26 12:43:10,387 INFO Logging out user dédé@example.net. 79s 2025-10-26 12:43:10,390 INFO User dédé@example.net logged out 79s 2025-10-26 12:43:10,397 INFO logout requested 79s 2025-10-26 12:43:10,399 INFO logout requested 79s 2025-10-26 12:43:10,400 INFO Logging out user dédé@example.test. 79s 2025-10-26 12:43:10,403 INFO User dédé@example.test logged out 79s 2025-10-26 12:43:10,408 INFO logout requested 79s 2025-10-26 12:43:10,420 INFO Trying to authenticate http://127.0.0.1:8080 again 79s 2025-10-26 12:43:10,426 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 79s 2025-10-26 12:43:10,432 INFO User dédé@example.com successfully authenticated 79s 2025-10-26 12:43:10,441 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 79s 2025-10-26 12:43:10,450 INFO Trying to authenticate http://127.0.0.1:8081 again 79s 2025-10-26 12:43:10,455 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 79s 2025-10-26 12:43:10,461 INFO User dédé@example.org successfully authenticated 79s 2025-10-26 12:43:10,467 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 79s 2025-10-26 12:43:10,474 INFO Trying to authenticate http://127.0.0.1:8082 again 79s 2025-10-26 12:43:10,478 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 79s 2025-10-26 12:43:10,484 INFO User dédé@example.net successfully authenticated 79s 2025-10-26 12:43:10,490 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 79s 2025-10-26 12:43:10,497 INFO Trying to authenticate http://127.0.0.1:8083 again 79s 2025-10-26 12:43:10,500 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 79s 2025-10-26 12:43:10,506 INFO User dédé@example.test successfully authenticated 79s 2025-10-26 12:43:10,512 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 79s 2025-10-26 12:43:10,513 INFO logout requested 79s 2025-10-26 12:43:10,514 INFO Logging out user dédé. 79s 2025-10-26 12:43:10,514 INFO User dédé logged out 79s 2025-10-26 12:43:10,520 INFO logout requested 79s 2025-10-26 12:43:10,521 INFO Logging out user dédé. 79s 2025-10-26 12:43:10,522 INFO User dédé logged out 79s 2025-10-26 12:43:10,527 INFO logout requested 79s 2025-10-26 12:43:10,528 INFO Logging out user dédé. 79s 2025-10-26 12:43:10,528 INFO User dédé logged out 79s 2025-10-26 12:43:10,534 INFO logout requested 79s 2025-10-26 12:43:10,535 INFO Logging out user dédé. 79s 2025-10-26 12:43:10,535 INFO User dédé logged out 79s PASSED 79s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2025-10-26 12:43:10,551 INFO Trying to authenticate http://127.0.0.1:8080 again 79s 2025-10-26 12:43:10,554 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 79s 2025-10-26 12:43:10,559 INFO User dédé@example.com successfully authenticated 79s 2025-10-26 12:43:10,565 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 79s 2025-10-26 12:43:10,573 INFO Trying to authenticate http://127.0.0.1:8081 again 79s 2025-10-26 12:43:10,576 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 79s 2025-10-26 12:43:10,581 INFO User dédé@example.org successfully authenticated 79s 2025-10-26 12:43:10,587 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 79s 2025-10-26 12:43:10,594 INFO Trying to authenticate http://127.0.0.1:8082 again 79s 2025-10-26 12:43:10,597 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 79s 2025-10-26 12:43:10,603 INFO User dédé@example.net successfully authenticated 79s 2025-10-26 12:43:10,608 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 79s 2025-10-26 12:43:10,615 INFO Trying to authenticate http://127.0.0.1:8083 again 79s 2025-10-26 12:43:10,619 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 79s 2025-10-26 12:43:10,624 INFO User dédé@example.test successfully authenticated 79s 2025-10-26 12:43:10,630 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 79s 2025-10-26 12:43:10,631 INFO logout requested 79s 2025-10-26 12:43:10,632 INFO Logging out user dédé@example.com. 79s 2025-10-26 12:43:10,635 INFO User dédé@example.com logged out 79s 2025-10-26 12:43:10,636 INFO logout requested 79s 2025-10-26 12:43:10,637 INFO Logging out user dédé@example.org. 79s 2025-10-26 12:43:10,640 INFO User dédé@example.org logged out 79s 2025-10-26 12:43:10,640 INFO logout requested 79s 2025-10-26 12:43:10,641 INFO Logging out user dédé@example.net. 79s 2025-10-26 12:43:10,644 INFO User dédé@example.net logged out 79s 2025-10-26 12:43:10,644 INFO logout requested 79s 2025-10-26 12:43:10,646 INFO Logging out user dédé@example.test. 79s 2025-10-26 12:43:10,648 INFO User dédé@example.test logged out 79s PASSED 79s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2025-10-26 12:43:10,658 WARNING A login attempt failed 79s PASSED 79s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 79s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2025-10-26 12:43:10,680 INFO Trying to authenticate http://127.0.0.1:8080 again 79s 2025-10-26 12:43:10,683 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 79s 2025-10-26 12:43:10,689 INFO User dédé@example.com successfully authenticated 79s 2025-10-26 12:43:10,695 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 79s 2025-10-26 12:43:10,702 INFO Trying to authenticate http://127.0.0.1:8081 again 79s 2025-10-26 12:43:10,705 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 79s 2025-10-26 12:43:10,711 INFO User dédé@example.org successfully authenticated 79s 2025-10-26 12:43:10,717 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 79s 2025-10-26 12:43:10,723 INFO Trying to authenticate http://127.0.0.1:8082 again 79s 2025-10-26 12:43:10,727 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 79s 2025-10-26 12:43:10,735 INFO User dédé@example.net successfully authenticated 79s 2025-10-26 12:43:10,742 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 79s 2025-10-26 12:43:10,753 INFO Trying to authenticate http://127.0.0.1:8083 again 79s 2025-10-26 12:43:10,758 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 79s 2025-10-26 12:43:10,765 INFO User dédé@example.test successfully authenticated 79s 2025-10-26 12:43:10,771 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 79s PASSED 79s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2025-10-26 12:43:10,781 INFO Trying to authenticate http://127.0.0.1:8080 again 79s 2025-10-26 12:43:10,784 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 79s 2025-10-26 12:43:10,790 INFO User dédé@example.com successfully authenticated 79s 2025-10-26 12:43:10,796 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 79s 2025-10-26 12:43:10,803 INFO Trying to authenticate http://127.0.0.1:8081 again 79s 2025-10-26 12:43:10,806 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 79s 2025-10-26 12:43:10,812 INFO User dédé@example.org successfully authenticated 79s 2025-10-26 12:43:10,819 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 79s 2025-10-26 12:43:10,825 INFO Trying to authenticate http://127.0.0.1:8082 again 79s 2025-10-26 12:43:10,828 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 79s 2025-10-26 12:43:10,834 INFO User dédé@example.net successfully authenticated 79s 2025-10-26 12:43:10,840 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 79s 2025-10-26 12:43:10,847 INFO Trying to authenticate http://127.0.0.1:8083 again 79s 2025-10-26 12:43:10,850 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 79s 2025-10-26 12:43:10,856 INFO User dédé@example.test successfully authenticated 79s 2025-10-26 12:43:10,862 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 79s 2025-10-26 12:43:10,872 INFO Trying to authenticate http://127.0.0.1:8080 again 79s 2025-10-26 12:43:10,875 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 79s 2025-10-26 12:43:10,880 INFO User dédé@example.com successfully authenticated 79s 2025-10-26 12:43:10,886 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 79s 2025-10-26 12:43:10,894 INFO Trying to authenticate http://127.0.0.1:8081 again 79s 2025-10-26 12:43:10,900 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 79s 2025-10-26 12:43:10,905 INFO User dédé@example.org successfully authenticated 79s 2025-10-26 12:43:10,911 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 79s 2025-10-26 12:43:10,920 INFO Trying to authenticate http://127.0.0.1:8082 again 79s 2025-10-26 12:43:10,924 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 79s 2025-10-26 12:43:10,933 INFO User dédé@example.net successfully authenticated 79s 2025-10-26 12:43:10,939 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 79s 2025-10-26 12:43:10,946 INFO Trying to authenticate http://127.0.0.1:8083 again 79s 2025-10-26 12:43:10,950 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 79s 2025-10-26 12:43:10,958 INFO User dédé@example.test successfully authenticated 79s 2025-10-26 12:43:10,964 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 79s 2025-10-26 12:43:10,965 WARNING User already authenticated, dropping federated authentication request 79s 2025-10-26 12:43:10,966 WARNING User already authenticated, dropping federated authentication request 79s 2025-10-26 12:43:10,966 WARNING User already authenticated, dropping federated authentication request 79s 2025-10-26 12:43:10,967 WARNING User already authenticated, dropping federated authentication request 79s PASSED 80s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2025-10-26 12:43:10,977 INFO Trying to authenticate http://127.0.0.1:8080 again 80s 2025-10-26 12:43:10,980 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 80s 2025-10-26 12:43:10,987 INFO User dédé@example.com successfully authenticated 80s 2025-10-26 12:43:10,993 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 80s 2025-10-26 12:43:10,999 INFO Trying to authenticate http://127.0.0.1:8081 again 80s 2025-10-26 12:43:11,003 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 80s 2025-10-26 12:43:11,013 INFO User dédé@example.org successfully authenticated 80s 2025-10-26 12:43:11,019 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 80s 2025-10-26 12:43:11,026 INFO Trying to authenticate http://127.0.0.1:8082 again 80s 2025-10-26 12:43:11,031 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 80s 2025-10-26 12:43:11,037 INFO User dédé@example.net successfully authenticated 80s 2025-10-26 12:43:11,073 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 80s 2025-10-26 12:43:11,080 INFO Trying to authenticate http://127.0.0.1:8083 again 80s 2025-10-26 12:43:11,085 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 80s 2025-10-26 12:43:11,092 INFO User dédé@example.test successfully authenticated 80s 2025-10-26 12:43:11,099 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 80s 2025-10-26 12:43:11,100 INFO logout requested 80s 2025-10-26 12:43:11,101 INFO Logging out user dédé@example.com. 80s 2025-10-26 12:43:11,104 INFO User dédé@example.com logged out 80s 2025-10-26 12:43:11,106 INFO logout requested 80s 2025-10-26 12:43:11,107 INFO Logging out user dédé@example.org. 80s 2025-10-26 12:43:11,110 INFO User dédé@example.org logged out 80s 2025-10-26 12:43:11,112 INFO logout requested 80s 2025-10-26 12:43:11,113 INFO Logging out user dédé@example.net. 80s 2025-10-26 12:43:11,116 INFO User dédé@example.net logged out 80s 2025-10-26 12:43:11,118 INFO logout requested 80s 2025-10-26 12:43:11,119 INFO Logging out user dédé@example.test. 80s 2025-10-26 12:43:11,122 INFO User dédé@example.test logged out 80s PASSED 80s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2025-10-26 12:43:11,135 INFO Trying to authenticate http://127.0.0.1:8080 again 80s 2025-10-26 12:43:11,139 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 80s 2025-10-26 12:43:11,145 INFO User dédé@example.com successfully authenticated 80s 2025-10-26 12:43:11,151 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 80s 2025-10-26 12:43:11,164 INFO Trying to authenticate http://127.0.0.1:8081 again 80s 2025-10-26 12:43:11,171 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 80s 2025-10-26 12:43:11,179 INFO User dédé@example.org successfully authenticated 80s 2025-10-26 12:43:11,185 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 80s 2025-10-26 12:43:11,192 INFO Trying to authenticate http://127.0.0.1:8082 again 80s 2025-10-26 12:43:11,196 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 80s 2025-10-26 12:43:11,203 INFO User dédé@example.net successfully authenticated 80s 2025-10-26 12:43:11,210 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 80s 2025-10-26 12:43:11,217 INFO Trying to authenticate http://127.0.0.1:8083 again 80s 2025-10-26 12:43:11,220 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 80s 2025-10-26 12:43:11,227 INFO User dédé@example.test successfully authenticated 80s 2025-10-26 12:43:11,235 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 80s 2025-10-26 12:43:11,238 INFO Trying to authenticate http://127.0.0.1:8080 again 80s 2025-10-26 12:43:11,241 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 80s 2025-10-26 12:43:11,248 INFO User dédé@example.com successfully authenticated 80s 2025-10-26 12:43:11,251 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 80s 2025-10-26 12:43:11,256 INFO Trying to authenticate http://127.0.0.1:8081 again 80s 2025-10-26 12:43:11,260 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 80s 2025-10-26 12:43:11,268 INFO User dédé@example.org successfully authenticated 80s 2025-10-26 12:43:11,272 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 80s 2025-10-26 12:43:11,277 INFO Trying to authenticate http://127.0.0.1:8082 again 80s 2025-10-26 12:43:11,281 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 80s 2025-10-26 12:43:11,296 INFO User dédé@example.net successfully authenticated 80s 2025-10-26 12:43:11,302 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 80s 2025-10-26 12:43:11,309 INFO Trying to authenticate http://127.0.0.1:8083 again 80s 2025-10-26 12:43:11,314 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 80s 2025-10-26 12:43:11,340 INFO User dédé@example.test successfully authenticated 80s 2025-10-26 12:43:11,345 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 80s PASSED 80s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 80s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 80s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 80s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2025-10-26 12:43:11,442 INFO User test successfully authenticated 80s PASSED 80s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2025-10-26 12:43:11,479 INFO User test successfully authenticated 80s PASSED 80s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2025-10-26 12:43:11,500 INFO User test successfully authenticated 80s 2025-10-26 12:43:11,522 INFO User test successfully authenticated 80s 2025-10-26 12:43:11,541 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 80s 2025-10-26 12:43:11,546 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')) 80s PASSED 80s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2025-10-26 12:43:11,570 INFO User test successfully authenticated 80s PASSED 80s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2025-10-26 12:43:11,594 INFO User test successfully authenticated 80s PASSED 80s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2025-10-26 12:43:11,615 INFO User test successfully authenticated 80s 2025-10-26 12:43:11,632 INFO Sending SLO requests to service http://127.0.0.1:45805 for user test 80s 2025-10-26 12:43:11,634 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 80s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 80s PASSED 80s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2025-10-26 12:43:11,646 INFO User test successfully authenticated 80s PASSED 80s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 80s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 80s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 80s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 80s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 80s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 80s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 80s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 80s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 80s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 80s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 80s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 80s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 80s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 80s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 80s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 80s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 80s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 80s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 81s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 81s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 81s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 81s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2025-10-26 12:43:12,474 INFO User test successfully authenticated 81s 2025-10-26 12:43:12,478 WARNING Service https://www.example.org not allowed. 81s PASSED 81s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success 2025-10-26 12:43:12,486 INFO User test successfully authenticated 81s 2025-10-26 12:43:12,491 INFO Service ticket created for service https://www.example.com by user test. 81s PASSED 81s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt 2025-10-26 12:43:12,498 INFO User test successfully authenticated 81s 2025-10-26 12:43:12,503 INFO Service ticket created for service https://www.example.com by user test. 81s PASSED 81s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn 2025-10-26 12:43:12,510 INFO User test successfully authenticated 81s PASSED 81s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged 2025-10-26 12:43:12,521 INFO User test successfully authenticated 81s PASSED 81s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2025-10-26 12:43:12,532 INFO User test successfully authenticated 81s 2025-10-26 12:43:12,536 WARNING User test seems authenticated but is not found in the database. 81s 2025-10-26 12:43:12,536 INFO Logging out user test. 81s 2025-10-26 12:43:12,536 INFO User test logged out 81s PASSED 81s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 81s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2025-10-26 12:43:12,548 INFO User test successfully authenticated 81s 2025-10-26 12:43:12,551 WARNING Username test not allowed on service restrict_user_fail 81s PASSED 81s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2025-10-26 12:43:12,560 WARNING A login attempt failed 81s 2025-10-26 12:43:12,569 WARNING A login attempt failed 81s 2025-10-26 12:43:12,578 WARNING A login attempt failed 81s PASSED 81s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2025-10-26 12:43:12,592 WARNING Received an invalid login ticket 81s PASSED 81s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2025-10-26 12:43:12,603 WARNING A login attempt failed 81s PASSED 81s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2025-10-26 12:43:12,616 INFO User test successfully authenticated 81s PASSED 81s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2025-10-26 12:43:12,626 INFO User test successfully authenticated 81s PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_renew 2025-10-26 12:43:13,225 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,233 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,236 INFO Service ticket created for service https://www.example.com by user test. 82s PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2025-10-26 12:43:13,244 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,250 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,254 INFO Service ticket created for service https://www.example.com by user test. 82s PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2025-10-26 12:43:13,262 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,265 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 82s 2025-10-26 12:43:13,267 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 82s 2025-10-26 12:43:13,271 INFO Service ticket created for service https://filter_success.example.com by user test. 82s PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2025-10-26 12:43:13,278 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,283 WARNING Username test not allowed on service restrict_user_fail 82s 2025-10-26 12:43:13,287 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 82s PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2025-10-26 12:43:13,294 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,297 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 82s 2025-10-26 12:43:13,300 INFO Service ticket created for service https://field_needed_success.example.com by user test. 82s PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2025-10-26 12:43:13,308 INFO User test successfully authenticated 82s PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2025-10-26 12:43:13,311 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 82s 2025-10-26 12:43:13,322 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,326 WARNING User test seems authenticated but is not found in the database. 82s 2025-10-26 12:43:13,326 INFO Logging out user test. 82s 2025-10-26 12:43:13,326 INFO User test logged out 82s PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2025-10-26 12:43:13,348 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,353 INFO Service ticket created for service https://www.example.com/é by user test. 82s PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2025-10-26 12:43:13,362 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,366 INFO Service ticket created for service https://www.example.com by user test. 82s PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2025-10-26 12:43:13,375 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,383 WARNING Received a login attempt for an already-active user 82s 2025-10-26 12:43:13,385 INFO Service ticket created for service https://www.example.com by user test. 82s PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2025-10-26 12:43:13,393 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,396 WARNING Service https://www.example.org not allowed. 82s PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2025-10-26 12:43:13,400 WARNING Service https://www.example.net not allowed. 82s PASSED 82s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2025-10-26 12:43:13,411 WARNING Service https://www.example.net not allowed. 82s PASSED 82s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2025-10-26 12:43:13,420 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,422 INFO logout requested 82s 2025-10-26 12:43:13,423 INFO Logging out user test. 82s 2025-10-26 12:43:13,426 INFO User test logged out 82s PASSED 82s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2025-10-26 12:43:13,451 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,457 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,463 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,470 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,476 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,482 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,488 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,494 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,500 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,503 INFO logout requested 82s 2025-10-26 12:43:13,503 INFO Logging out user test from all sessions. 82s 2025-10-26 12:43:13,523 INFO User test logged out 82s PASSED 82s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2025-10-26 12:43:13,530 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,532 INFO logout requested 82s 2025-10-26 12:43:13,533 INFO Logging out user test. 82s 2025-10-26 12:43:13,535 INFO User test logged out 82s PASSED 82s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2025-10-26 12:43:13,537 INFO logout requested 82s PASSED 82s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2025-10-26 12:43:13,544 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,551 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,553 INFO logout requested 82s 2025-10-26 12:43:13,553 INFO Logging out user test from all sessions. 82s 2025-10-26 12:43:13,558 INFO User test logged out 82s PASSED 82s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2025-10-26 12:43:13,565 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,570 INFO Service ticket created for service http://127.0.0.1:35149 by user test. 82s 2025-10-26 12:43:13,572 INFO Validate: Service ticket ST-Hm8Vw5LenZuML7L3tvdGFxDxMCz3zY7zldYwgrlDDi4oyfASy6jlCrKIISvpu validated, user test authenticated on service http://127.0.0.1:35149 82s 2025-10-26 12:43:13,573 INFO logout requested 82s 2025-10-26 12:43:13,573 INFO Logging out user test. 82s 2025-10-26 12:43:13,576 INFO Sending SLO requests to service http://127.0.0.1:35149 for user test 82s 2025-10-26 12:43:13,578 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 82s 2025-10-26 12:43:13,579 INFO User test logged out 82s 2025-10-26 12:43:13,584 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,588 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 82s 2025-10-26 12:43:13,590 INFO Validate: Service ticket ST-ZYg8Z4ddbK5gAdySgWga58IPc4RVRPTLSA0lLGKwLXPN979Z5hwckJbTCNJAx validated, user test authenticated on service http://127.0.0.1:45678 82s 2025-10-26 12:43:13,591 INFO logout requested 82s 2025-10-26 12:43:13,591 INFO Logging out user test. 82s 2025-10-26 12:43:13,593 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 82s 2025-10-26 12:43:13,596 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 82s 2025-10-26 12:43:13,597 INFO User test logged out 82s 2025-10-26 12:43:13,603 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,607 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 82s 2025-10-26 12:43:13,610 INFO Validate: Service ticket ST-6ywTeuPTK4JixDsVkJgIut9toziZRUf7wYzoWPwvzcaGp5zJ5rnC89GALrzo8 validated, user test authenticated on service http://127.0.0.1:45678 82s 2025-10-26 12:43:13,611 INFO logout requested 82s 2025-10-26 12:43:13,611 INFO Logging out user test. 82s 2025-10-26 12:43:13,613 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 82s 2025-10-26 12:43:13,615 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=46339): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 82s 2025-10-26 12:43:13,616 INFO User test logged out 82s PASSED 82s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2025-10-26 12:43:13,622 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,629 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,634 INFO logout requested 82s 2025-10-26 12:43:13,634 INFO Logging out user test. 82s 2025-10-26 12:43:13,636 INFO User test logged out 82s PASSED 82s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2025-10-26 12:43:13,647 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,649 INFO logout requested 82s 2025-10-26 12:43:13,650 INFO Logging out user test. 82s 2025-10-26 12:43:13,653 INFO User test logged out 82s PASSED 82s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2025-10-26 12:43:13,661 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,663 INFO logout requested 82s 2025-10-26 12:43:13,664 INFO Logging out user test. 82s 2025-10-26 12:43:13,668 INFO User test logged out 82s PASSED 82s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2025-10-26 12:43:13,680 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,682 INFO logout requested 82s 2025-10-26 12:43:13,683 INFO Logging out user test. 82s 2025-10-26 12:43:13,685 INFO User test logged out 82s PASSED 82s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2025-10-26 12:43:13,693 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,695 INFO logout requested 82s 2025-10-26 12:43:13,695 INFO Logging out user test. 82s 2025-10-26 12:43:13,698 INFO User test logged out 82s 2025-10-26 12:43:13,702 INFO logout requested 82s PASSED 82s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 82s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 82s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice 2025-10-26 12:43:13,713 WARNING Service https://www.example.org not allowed. 82s PASSED 82s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings PASSED 82s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 82s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2025-10-26 12:43:13,724 INFO User test successfully authenticated 82s PASSED 82s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 82s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2025-10-26 12:43:13,737 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,743 INFO Service ticket created for service https://www.example.com by user test. 82s 2025-10-26 12:43:13,744 WARNING Validate: service or ticket missing 82s 2025-10-26 12:43:13,744 WARNING Validate: service or ticket missing 82s PASSED 82s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2025-10-26 12:43:13,751 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,754 INFO Service ticket created for service https://www.example.com by user test. 82s 2025-10-26 12:43:13,756 INFO Validate: Service ticket ST-pxArwzmvpbuUhuG9gi1Lvy29L6E2wiyQLMgcSllxFgTys6lUgZ7lHIpkCkAf3 validated, user test authenticated on service https://www.example.com 82s 2025-10-26 12:43:13,761 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,764 INFO Service ticket created for service https://www.example.com by user test. 82s 2025-10-26 12:43:13,766 INFO Validate: Service ticket ST-V2UMlu7iDMEbwBcCJeUr0111rcsmenTjaaLONkhOpSFVowFE9VDizRtQjtERI validated, user test authenticated on service https://www.example.com 82s 2025-10-26 12:43:13,769 INFO Service ticket created for service https://www.example.com by user test. 82s 2025-10-26 12:43:13,770 WARNING Validate: Service ticket ST-MfI1UdIGFUBw3iSB2czhH0gi84hvASLQjacA4PyVJ24EiV6lDAhOkMVDM8KNw not found or already validated, auth to https://www.example.com failed 82s PASSED 82s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2025-10-26 12:43:13,777 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,782 INFO Service ticket created for service https://user_field.example.com by user test. 82s 2025-10-26 12:43:13,784 INFO Validate: Service ticket ST-pqXDSUMQfy62pPrCAoZYmjeGm3Fg5FGxcmUJzV57Y8Y7VEDWrK1jIppP9YCpu validated, user test authenticated on service https://user_field.example.com 82s 2025-10-26 12:43:13,796 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,805 INFO Service ticket created for service https://user_field_alt.example.com by user test. 82s PASSED 82s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2025-10-26 12:43:13,808 INFO Validate: Service ticket ST-i9fF2H5js9rV6uF32t8iO6QEyvdwIN7UNAsFaG26sB5WyQTCcQxpQowb9OrSS validated, user test authenticated on service https://user_field_alt.example.com 82s 2025-10-26 12:43:13,815 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,821 INFO Service ticket created for service https://www.example.com by user test. 82s 2025-10-26 12:43:13,823 WARNING Validate: Service ticket ST-2BBabP5Co65NY0hPxVLG6mUpwfFnzWIzUj1312W2qxmKjFIwpjzBvXEq4gA0O not found or already validated, auth to https://www.example.org failed 82s PASSED 82s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2025-10-26 12:43:13,830 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,839 INFO Service ticket created for service https://www.example.com by user test. 82s 2025-10-26 12:43:13,841 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 82s PASSED 82s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2025-10-26 12:43:13,848 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,859 INFO Service ticket created for service https://www.example.com by user test. 82s 2025-10-26 12:43:13,861 INFO Validate: Service ticket ST-BifSGZ68Bt30k7cGz5KQXanuOiGVfibRsT5L8ALvfQh4VJPhhQ0CCF7OdL9uv validated, user test authenticated on service https://www.example.com 82s PASSED 82s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2025-10-26 12:43:13,871 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,875 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 82s PASSED 82s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2025-10-26 12:43:13,876 WARNING ValidateService: missing ticket or service 82s 2025-10-26 12:43:13,877 WARNING ValidateService: missing ticket or service 82s 2025-10-26 12:43:13,889 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,893 INFO Service ticket created for service http://127.0.0.1:36937 by user test. 82s 2025-10-26 12:43:13,900 INFO ValidateService: ticket ST-CNI7srXAEGymCvxd2HujIKzE43Kla7t8Vp4sI2jt7D2KRxAZkLowFDPpy9fFe validated for user test on service http://127.0.0.1:36937. Proxy Granting Ticket transmited to http://127.0.0.1:36937. 82s 2025-10-26 12:43:13,904 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 82s 2025-10-26 12:43:13,907 INFO ValidateService: ticket PT-Jo6KRebhhxZxlbx9BdUTe2znhK001WQms1ZbXK4EMLH7PmddRS75wyoTEHtgS validated for user test on service http://127.0.0.1:45678. 82s 2025-10-26 12:43:13,913 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,918 INFO Service ticket created for service http://127.0.0.1:44849 by user test. 82s 2025-10-26 12:43:13,927 INFO ValidateService: ticket ST-f70Ubwk4qdxIXYj6RcnegDEWE06YGsIMuMvU8KHHsuub0dtLs4wBSMwkrok7R validated for user test on service http://127.0.0.1:44849. Proxy Granting Ticket transmited to http://127.0.0.1:44849. 82s 2025-10-26 12:43:13,930 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 82s 2025-10-26 12:43:13,931 WARNING ValidateService: validation error: INVALID_TICKET PT-XuCIjeiwvWxwgKh6L8ggzczXAEmAzFGeERSVZwaS46NzsbHwiH0VFYvn42jTt 82s PASSED 82s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2025-10-26 12:43:13,939 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,944 INFO Service ticket created for service https://replace_attribute.example.com by user test. 82s 2025-10-26 12:43:13,947 INFO ValidateService: ticket ST-KopovEeAxOui6yfgNYhEnay0iWyf4meeuX0nmnoE7u8jtnF0Sfh1VCgglWK6h validated for user test on service https://replace_attribute.example.com. 82s 2025-10-26 12:43:13,951 INFO User test successfully authenticated 82s 2025-10-26 12:43:13,956 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 82s 2025-10-26 12:43:13,959 INFO ValidateService: ticket ST-mJ5ubuPDwIeIp7Lwn1lFXffna6HRObgkjkrRqAJna5BkHAx2ajqGLPPbomDII validated for user test on service https://replace_attribute_list.example.com. 82s PASSED 82s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2025-10-26 12:43:13,967 INFO User test successfully authenticated 83s 2025-10-26 12:43:13,972 INFO Service ticket created for service http://127.0.0.1:44839 by user test. 83s 2025-10-26 12:43:13,977 INFO ValidateService: ticket ST-biop6XgcDXtP6sn7jMeGWfzUFZYDNMwYSBwGisEN3WqcoLDszMtaQ15DxFrRO validated for user test on service http://127.0.0.1:44839. Proxy Granting Ticket transmited to http://127.0.0.1:44839. 83s PASSED 83s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2025-10-26 12:43:13,985 INFO User test successfully authenticated 83s 2025-10-26 12:43:13,991 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 83s 2025-10-26 12:43:13,994 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 83s 2025-10-26 12:43:13,999 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,004 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 83s 2025-10-26 12:43:14,006 WARNING Service https://www.example.org not allowed. 83s 2025-10-26 12:43:14,006 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 83s PASSED 83s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2025-10-26 12:43:14,015 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,020 INFO Service ticket created for service https://127.0.0.1:45093 by user test. 83s 2025-10-26 12:43:14,026 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=45093): Max retries exceeded with url: /?pgtId=PGT-BCuXyQQzlz2wV5TW99Zx4aNQTfHOQzfc99g3CtKT2sMU9rqcrNxqNwvHWhUF&pgtIou=PGTIOU-zvZOnLk4dNl8cbGwQSxv3kXywKKNvvS7ZPBQwOiZPf5QhiwLVXDeyesky (Caused by SSLError(SSLError(1, '[SSL: RECORD_LAYER_FAILURE] record layer failure (_ssl.c:1077)'))) 83s PASSED 83s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2025-10-26 12:43:14,033 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,036 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 83s 2025-10-26 12:43:14,038 INFO ValidateService: ticket ST-4HYNvDnYx994ZkGwrrc53ClAUf54hSNEJKQ5oqwmBrM8suYNyLVSRAdxkKSr9 validated for user test on service http://127.0.0.1:45678. 83s 2025-10-26 12:43:14,044 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,046 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 83s 2025-10-26 12:43:14,049 INFO ValidateService: ticket ST-qAnMU5wVd2MRwIMO2jakHzM7VQPIPRCAiqEbR1H7BtN7PiSYTpxHAX4tYu6bP validated for user test on service http://127.0.0.1:45678. 83s 2025-10-26 12:43:14,052 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 83s 2025-10-26 12:43:14,054 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 83s PASSED 83s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2025-10-26 12:43:14,061 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,065 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 83s 2025-10-26 12:43:14,068 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 83s PASSED 83s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2025-10-26 12:43:14,075 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,079 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 83s 2025-10-26 12:43:14,082 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 83s PASSED 83s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2025-10-26 12:43:14,088 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,093 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 83s 2025-10-26 12:43:14,097 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 83s PASSED 83s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2025-10-26 12:43:14,104 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,110 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 83s 2025-10-26 12:43:14,112 INFO ValidateService: ticket ST-ek2n07ED9dP3LamRfv87y7aC1FDj4IoWyIdRadcjmPx2v90gYtBD8eOlVI96v validated for user test on service http://127.0.0.1:45678. 83s PASSED 83s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2025-10-26 12:43:14,119 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,125 INFO Service ticket created for service https://one_attribute.example.com by user test. 83s 2025-10-26 12:43:14,127 INFO ValidateService: ticket ST-QXsT5iosoj4Ihl0raIdJy3xRYeHBhM37TBwGJ95F0gnWaG4LhlODzQkOlEuRl validated for user test on service https://one_attribute.example.com. 83s PASSED 83s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2025-10-26 12:43:14,135 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,140 INFO Service ticket created for service http://127.0.0.1:42193 by user test. 83s 2025-10-26 12:43:14,145 INFO ValidateService: ticket ST-kvTFZeFzs7VywVEFOC09qHn6px8rSPFk8PMngHTCRbPpwC6SpIWjt1sw5nkFz validated for user test on service http://127.0.0.1:42193. Proxy Granting Ticket transmited to http://127.0.0.1:42193. 83s PASSED 83s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2025-10-26 12:43:14,153 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,157 INFO Service ticket created for service https://user_field.example.com by user test. 83s 2025-10-26 12:43:14,161 INFO ValidateService: ticket ST-fw7Z9CBT2WU9Mi0Nf8dzksXLQ5YrbQcoqDKnFYVTd4pliQu7uH3HatKn34y0L validated for user test on service https://user_field.example.com. 83s 2025-10-26 12:43:14,165 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,170 INFO Service ticket created for service https://user_field_alt.example.com by user test. 83s 2025-10-26 12:43:14,173 INFO ValidateService: ticket ST-m6k8II5kTh4zkegKCXfO06ikKFiP7LA0cwbVKmGcHnFE1wsuq1ePW4WwjYZzx validated for user test on service https://user_field_alt.example.com. 83s PASSED 83s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2025-10-26 12:43:14,183 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,187 INFO Service ticket created for service http://127.0.0.1:36857 by user test. 83s 2025-10-26 12:43:14,192 INFO ValidateService: ticket ST-aim55rK0nYHjPluKlnHU9JLg2W4wBFwoHaVDOupj5pGu2V6dTE1BFoGD5zDbV validated for user test on service http://127.0.0.1:36857. Proxy Granting Ticket transmited to http://127.0.0.1:36857. 83s PASSED 83s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2025-10-26 12:43:14,193 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 83s 2025-10-26 12:43:14,193 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 83s 2025-10-26 12:43:14,203 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,208 INFO Service ticket created for service http://127.0.0.1:44761 by user test. 83s 2025-10-26 12:43:14,214 INFO ValidateService: ticket ST-Rfy23qvM99j8xV6MWFcAPStCMelEipLns02ghVskZC6N7UdMKZ0jUHW5JJPtN validated for user test on service http://127.0.0.1:44761. Proxy Granting Ticket transmited to http://127.0.0.1:44761. 83s 2025-10-26 12:43:14,217 WARNING Username test not allowed on service restrict_user_fail 83s 2025-10-26 12:43:14,217 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 83s 2025-10-26 12:43:14,218 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 83s 2025-10-26 12:43:14,218 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 83s 2025-10-26 12:43:14,220 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 83s 2025-10-26 12:43:14,220 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 83s PASSED 83s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2025-10-26 12:43:14,229 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,233 INFO Service ticket created for service http://127.0.0.1:33951 by user test. 83s 2025-10-26 12:43:14,241 INFO ValidateService: ticket ST-gndpb8FBociZDKnAPUFKBDoT7nQ2hUjEn08NweFsWxSd7OgfJqTFmcoPR3itg validated for user test on service http://127.0.0.1:33951. Proxy Granting Ticket transmited to http://127.0.0.1:33951. 83s 2025-10-26 12:43:14,243 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 83s PASSED 83s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2025-10-26 12:43:14,252 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,256 INFO Service ticket created for service http://127.0.0.1:34689 by user test. 83s PASSED2025-10-26 12:43:14,261 INFO ValidateService: ticket ST-IR3ioI5XWXK8VUGb5SxGSKt3mj2W37xyccNO19vgCfcCE3bMX9WB1CrTM0LmJ validated for user test on service http://127.0.0.1:34689. Proxy Granting Ticket transmited to http://127.0.0.1:34689. 83s 2025-10-26 12:43:14,262 WARNING Service https://www.example.org not allowed. 83s 2025-10-26 12:43:14,262 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 83s 2025-10-26 12:43:14,264 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:34689 does not allow proxy tickets 83s 83s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2025-10-26 12:43:14,275 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,280 INFO Service ticket created for service http://127.0.0.1:41715 by user test. 83s 2025-10-26 12:43:14,287 INFO ValidateService: ticket ST-ueav0gvfIKd7Jcxb7P0S7RgsMwziJSwMy9xgWXcU0bwwWVFNmn8sAhMa77fqR validated for user test on service http://127.0.0.1:41715. Proxy Granting Ticket transmited to http://127.0.0.1:41715. 83s 2025-10-26 12:43:14,290 INFO Proxy ticket created for user test on service https://www.example.com. 83s PASSED 83s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2025-10-26 12:43:14,293 INFO ValidateService: ticket PT-3rHvHHW7wvLOpSB78hXH4y8kg05LrPJ0Hs6A5RJ9AvMeWd9w2Z8WCKZ97Gws0 validated for user test on service https://www.example.com. 83s 2025-10-26 12:43:14,302 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,306 INFO Service ticket created for service https://www.example.com by user test. 83s 2025-10-26 12:43:14,309 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 83s PASSED 83s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2025-10-26 12:43:14,315 WARNING SamlValidate: validation error: AuthnFailed ticket ST-JfKKzxdShFpZeM3ZxmjDItHbx29K4HSkQ2su6O0QvH9AooAL3mh6KwRresEC1 not found 83s 2025-10-26 12:43:14,316 WARNING SamlValidate: validation error: AuthnFailed ticket PT-Ajwx3YR4DFHyE6JsevsAU5WCLKnywldcRcxexBBpoHFGBR2Ay2ZuQyGbm08NH not found 83s PASSED 83s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix PASSED 83s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml 2025-10-26 12:43:14,320 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 83s 2025-10-26 12:43:14,323 WARNING SamlValidate: validation error: VersionMismatch 83s PASSED 83s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2025-10-26 12:43:14,333 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,337 INFO Service ticket created for service https://www.example.com by user test. 83s 2025-10-26 12:43:14,344 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,349 INFO Service ticket created for service http://127.0.0.1:35861 by user test. 83s 2025-10-26 12:43:14,355 INFO ValidateService: ticket ST-s4OhhCpcL5pmAgt8niD8AJOywWlfnO8cY2DZEO8eXINT6IlweYr3sbILvjokK validated for user test on service http://127.0.0.1:35861. Proxy Granting Ticket transmited to http://127.0.0.1:35861. 83s 2025-10-26 12:43:14,358 INFO Proxy ticket created for user test on service https://www.example.com. 83s 2025-10-26 12:43:14,360 INFO SamlValidate: ticket ST-WtSiIT4hdPhwTAC96QYtESz3gJgU1i5im2Ei2GteBOBJE0UoY5XmZz55W234b validated for user test on service https://www.example.com. 83s 2025-10-26 12:43:14,362 INFO SamlValidate: ticket PT-oqtM8zSCN0kmHfJRXjA6xtR5V7YWIi9SSYbp7isBZsBsTvQy2251W8eLncSZw validated for user test on service https://www.example.com. 83s PASSED 83s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2025-10-26 12:43:14,371 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,376 INFO Service ticket created for service https://field_needed_success.example.com by user test. 83s 2025-10-26 12:43:14,378 INFO SamlValidate: ticket ST-SkPxStJTdUZM3IwI5k1oJU4r7asNgLu8U4JtdB8bzlsKMB0qWoXGLUjIvQwiH validated for user test on service https://field_needed_success.example.com. 83s 2025-10-26 12:43:14,384 INFO User test successfully authenticated 83s 2025-10-26 12:43:14,389 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 83s 2025-10-26 12:43:14,391 INFO SamlValidate: ticket ST-bJghho9YihyEtGxdH7T6QLWttFMRlA2mMpILq6MkTcgwiTtfPUaLDjzVecFNw validated for user test on service https://field_needed_success_alt.example.com. 83s PASSEDDestroying test database for alias 'default'... 83s 83s 83s =============================== warnings summary =============================== 83s cas_server/tests/test_federate.py: 8 warnings 83s cas_server/tests/test_view.py: 3 warnings 83s /tmp/autopkgtest.uNEuAX/autopkgtest_tmp/cas_server/utils.py:211: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC). 83s datetime.utcnow() + timedelta(seconds=max_age), 83s 83s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 83s /usr/lib/python3.14/unittest/case.py:725: DeprecationWarning: It is deprecated to return a value that is not None from a test case (> returned 'list') 83s return self.run(*args, **kwds) 83s 83s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 83s ======================= 128 passed, 12 warnings in 4.98s ======================= 83s 83s *************************** 83s *** Testing with python3.13 83s *************************** 83s 83s Content of current working folder: 83s 83s total 20 83s drwxr-xr-x 3 ubuntu ubuntu 4096 Oct 26 12:43 . 83s drwxrwxrwx 5 root root 4096 Oct 26 12:43 .. 83s drwxrwxr-x 10 ubuntu ubuntu 4096 Oct 26 12:43 cas_server 83s -rw-rw-r-- 1 ubuntu ubuntu 140 Aug 18 2024 pytest.ini 83s -rw-rw-r-- 1 ubuntu ubuntu 152 Aug 18 2024 setup.cfg 83s Running tests... 83s 83s ============================= test session starts ============================== 83s platform linux -- Python 3.13.9, pytest-8.3.5, pluggy-1.6.0 -- /usr/bin/python3.13 83s cachedir: .pytest_cache 83s django: version: 5.2.4, settings: cas_server.tests.settings (from ini) 83s rootdir: /tmp/autopkgtest.uNEuAX/autopkgtest_tmp 83s configfile: pytest.ini 83s testpaths: cas_server/tests/ 83s plugins: typeguard-4.4.2, django-4.11.1 84s collecting ... collected 128 items 84s 84s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 84s 2025-10-26 12:43:15,538 WARNING Identity provider suffix exemple.fr not found 84s 2025-10-26 12:43:15,553 INFO Got an invalid ticket ST-egDG4RiMc67wip3TRA2YpUUamhMhdcZOfxcaQrOYHEx7eHSFLySRffSrN29Zx from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 84s 2025-10-26 12:43:15,557 INFO Got an invalid ticket ST-FIs9dg9qizATRlYr3RTlOoFQb0t5xFUqe8ounKnm6Hn2rVWcVIeAJpZ35Tew8 from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 84s PASSED 84s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2025-10-26 12:43:15,585 WARNING CAS_FEDERATE is False, set it to True to use federation 84s 2025-10-26 12:43:15,586 WARNING CAS_FEDERATE is False, set it to True to use federation 84s PASSED 84s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2025-10-26 12:43:15,596 INFO Trying to authenticate http://127.0.0.1:8080 again 84s 2025-10-26 12:43:15,599 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 84s 2025-10-26 12:43:15,609 INFO User dédé@example.com successfully authenticated 84s 2025-10-26 12:43:15,616 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 84s 2025-10-26 12:43:15,624 INFO Trying to authenticate http://127.0.0.1:8081 again 84s 2025-10-26 12:43:15,627 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 84s 2025-10-26 12:43:15,633 INFO User dédé@example.org successfully authenticated 84s 2025-10-26 12:43:15,640 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 84s 2025-10-26 12:43:15,647 INFO Trying to authenticate http://127.0.0.1:8082 again 84s 2025-10-26 12:43:15,651 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 84s 2025-10-26 12:43:15,658 INFO User dédé@example.net successfully authenticated 84s 2025-10-26 12:43:15,666 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 84s 2025-10-26 12:43:15,680 INFO Trying to authenticate http://127.0.0.1:8083 again 84s 2025-10-26 12:43:15,685 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 84s 2025-10-26 12:43:15,698 INFO User dédé@example.test successfully authenticated 84s 2025-10-26 12:43:15,709 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 84s 2025-10-26 12:43:15,737 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-d8RUKdOT7ky0DCu0zXcemYDQMh21skdubXAz1uM8ftQWGpwea4135B4zryWEN 84s 2025-10-26 12:43:15,753 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-qYh7ZKBIvJ6Qa9TgLIkrMoWz7tx1tomxGwEMl5tni8MEY2uKxvzp6TmzhrQjY 84s PASSED 84s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 84s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2025-10-26 12:43:15,779 INFO Trying to authenticate http://127.0.0.1:8080 again 84s 2025-10-26 12:43:15,782 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 84s 2025-10-26 12:43:15,788 INFO User dédé@example.com successfully authenticated 84s 2025-10-26 12:43:15,794 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 84s 2025-10-26 12:43:15,801 INFO Trying to authenticate http://127.0.0.1:8081 again 84s 2025-10-26 12:43:15,803 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 84s 2025-10-26 12:43:15,809 INFO User dédé@example.org successfully authenticated 84s 2025-10-26 12:43:15,815 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 84s 2025-10-26 12:43:15,822 INFO Trying to authenticate http://127.0.0.1:8082 again 84s 2025-10-26 12:43:15,825 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 84s 2025-10-26 12:43:15,831 INFO User dédé@example.net successfully authenticated 84s 2025-10-26 12:43:15,836 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 84s 2025-10-26 12:43:15,843 INFO Trying to authenticate http://127.0.0.1:8083 again 84s 2025-10-26 12:43:15,846 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 84s 2025-10-26 12:43:15,852 INFO User dédé@example.test successfully authenticated 84s 2025-10-26 12:43:15,858 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 84s 2025-10-26 12:43:15,859 INFO logout requested 84s 2025-10-26 12:43:15,860 INFO Logging out user dédé@example.com. 84s 2025-10-26 12:43:15,863 INFO User dédé@example.com logged out 84s 2025-10-26 12:43:15,868 INFO logout requested 84s 2025-10-26 12:43:15,870 INFO logout requested 84s 2025-10-26 12:43:15,871 INFO Logging out user dédé@example.org. 84s 2025-10-26 12:43:15,874 INFO User dédé@example.org logged out 84s 2025-10-26 12:43:15,879 INFO logout requested 84s 2025-10-26 12:43:15,881 INFO logout requested 84s 2025-10-26 12:43:15,882 INFO Logging out user dédé@example.net. 84s 2025-10-26 12:43:15,885 INFO User dédé@example.net logged out 84s 2025-10-26 12:43:15,890 INFO logout requested 84s 2025-10-26 12:43:15,892 INFO logout requested 84s 2025-10-26 12:43:15,893 INFO Logging out user dédé@example.test. 84s 2025-10-26 12:43:15,896 INFO User dédé@example.test logged out 84s 2025-10-26 12:43:15,901 INFO logout requested 84s 2025-10-26 12:43:15,909 INFO Trying to authenticate http://127.0.0.1:8080 again 84s 2025-10-26 12:43:15,912 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 84s 2025-10-26 12:43:15,918 INFO User dédé@example.com successfully authenticated 84s 2025-10-26 12:43:15,924 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 84s 2025-10-26 12:43:15,931 INFO Trying to authenticate http://127.0.0.1:8081 again 84s 2025-10-26 12:43:15,934 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 84s 2025-10-26 12:43:15,940 INFO User dédé@example.org successfully authenticated 84s 2025-10-26 12:43:15,946 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 84s 2025-10-26 12:43:15,952 INFO Trying to authenticate http://127.0.0.1:8082 again 84s 2025-10-26 12:43:15,955 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 84s 2025-10-26 12:43:15,962 INFO User dédé@example.net successfully authenticated 84s 2025-10-26 12:43:15,968 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 85s 2025-10-26 12:43:15,974 INFO Trying to authenticate http://127.0.0.1:8083 again 85s 2025-10-26 12:43:15,977 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 85s 2025-10-26 12:43:15,984 INFO User dédé@example.test successfully authenticated 85s 2025-10-26 12:43:15,990 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 85s 2025-10-26 12:43:15,991 INFO logout requested 85s 2025-10-26 12:43:15,992 INFO Logging out user dédé. 85s 2025-10-26 12:43:15,992 INFO User dédé logged out 85s 2025-10-26 12:43:15,998 INFO logout requested 85s 2025-10-26 12:43:15,999 INFO Logging out user dédé. 85s 2025-10-26 12:43:15,999 INFO User dédé logged out 85s 2025-10-26 12:43:16,005 INFO logout requested 85s 2025-10-26 12:43:16,006 INFO Logging out user dédé. 85s 2025-10-26 12:43:16,006 INFO User dédé logged out 85s 2025-10-26 12:43:16,012 INFO logout requested 85s 2025-10-26 12:43:16,013 INFO Logging out user dédé. 85s 2025-10-26 12:43:16,013 INFO User dédé logged out 85s PASSED 85s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2025-10-26 12:43:16,029 INFO Trying to authenticate http://127.0.0.1:8080 again 85s 2025-10-26 12:43:16,032 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 85s 2025-10-26 12:43:16,037 INFO User dédé@example.com successfully authenticated 85s 2025-10-26 12:43:16,043 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 85s 2025-10-26 12:43:16,051 INFO Trying to authenticate http://127.0.0.1:8081 again 85s 2025-10-26 12:43:16,054 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 85s 2025-10-26 12:43:16,060 INFO User dédé@example.org successfully authenticated 85s 2025-10-26 12:43:16,066 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 85s 2025-10-26 12:43:16,096 INFO Trying to authenticate http://127.0.0.1:8082 again 85s 2025-10-26 12:43:16,099 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 85s 2025-10-26 12:43:16,105 INFO User dédé@example.net successfully authenticated 85s 2025-10-26 12:43:16,111 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 85s 2025-10-26 12:43:16,119 INFO Trying to authenticate http://127.0.0.1:8083 again 85s 2025-10-26 12:43:16,122 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 85s 2025-10-26 12:43:16,128 INFO User dédé@example.test successfully authenticated 85s 2025-10-26 12:43:16,134 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 85s 2025-10-26 12:43:16,134 INFO logout requested 85s 2025-10-26 12:43:16,136 INFO Logging out user dédé@example.com. 85s 2025-10-26 12:43:16,139 INFO User dédé@example.com logged out 85s 2025-10-26 12:43:16,139 INFO logout requested 85s 2025-10-26 12:43:16,140 INFO Logging out user dédé@example.org. 85s 2025-10-26 12:43:16,143 INFO User dédé@example.org logged out 85s 2025-10-26 12:43:16,143 INFO logout requested 85s 2025-10-26 12:43:16,145 INFO Logging out user dédé@example.net. 85s 2025-10-26 12:43:16,148 INFO User dédé@example.net logged out 85s 2025-10-26 12:43:16,148 INFO logout requested 85s 2025-10-26 12:43:16,149 INFO Logging out user dédé@example.test. 85s 2025-10-26 12:43:16,153 INFO User dédé@example.test logged out 85s PASSED 85s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2025-10-26 12:43:16,162 WARNING A login attempt failed 85s PASSED 85s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 85s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2025-10-26 12:43:16,183 INFO Trying to authenticate http://127.0.0.1:8080 again 85s 2025-10-26 12:43:16,185 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 85s 2025-10-26 12:43:16,192 INFO User dédé@example.com successfully authenticated 85s 2025-10-26 12:43:16,197 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 85s 2025-10-26 12:43:16,204 INFO Trying to authenticate http://127.0.0.1:8081 again 85s 2025-10-26 12:43:16,207 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 85s 2025-10-26 12:43:16,213 INFO User dédé@example.org successfully authenticated 85s 2025-10-26 12:43:16,219 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 85s 2025-10-26 12:43:16,226 INFO Trying to authenticate http://127.0.0.1:8082 again 85s 2025-10-26 12:43:16,228 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 85s 2025-10-26 12:43:16,235 INFO User dédé@example.net successfully authenticated 85s 2025-10-26 12:43:16,241 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 85s 2025-10-26 12:43:16,247 INFO Trying to authenticate http://127.0.0.1:8083 again 85s 2025-10-26 12:43:16,250 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 85s 2025-10-26 12:43:16,257 INFO User dédé@example.test successfully authenticated 85s 2025-10-26 12:43:16,262 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 85s PASSED 85s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2025-10-26 12:43:16,273 INFO Trying to authenticate http://127.0.0.1:8080 again 85s 2025-10-26 12:43:16,276 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 85s 2025-10-26 12:43:16,282 INFO User dédé@example.com successfully authenticated 85s 2025-10-26 12:43:16,288 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 85s 2025-10-26 12:43:16,294 INFO Trying to authenticate http://127.0.0.1:8081 again 85s 2025-10-26 12:43:16,297 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 85s 2025-10-26 12:43:16,304 INFO User dédé@example.org successfully authenticated 85s 2025-10-26 12:43:16,313 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 85s 2025-10-26 12:43:16,320 INFO Trying to authenticate http://127.0.0.1:8082 again 85s 2025-10-26 12:43:16,323 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 85s 2025-10-26 12:43:16,332 INFO User dédé@example.net successfully authenticated 85s 2025-10-26 12:43:16,338 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 85s 2025-10-26 12:43:16,346 INFO Trying to authenticate http://127.0.0.1:8083 again 85s 2025-10-26 12:43:16,349 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 85s 2025-10-26 12:43:16,356 INFO User dédé@example.test successfully authenticated 85s 2025-10-26 12:43:16,362 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 85s 2025-10-26 12:43:16,368 INFO Trying to authenticate http://127.0.0.1:8080 again 85s 2025-10-26 12:43:16,371 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 85s 2025-10-26 12:43:16,379 INFO User dédé@example.com successfully authenticated 85s 2025-10-26 12:43:16,385 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 85s 2025-10-26 12:43:16,392 INFO Trying to authenticate http://127.0.0.1:8081 again 85s 2025-10-26 12:43:16,395 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 85s 2025-10-26 12:43:16,402 INFO User dédé@example.org successfully authenticated 85s 2025-10-26 12:43:16,407 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 85s 2025-10-26 12:43:16,414 INFO Trying to authenticate http://127.0.0.1:8082 again 85s 2025-10-26 12:43:16,418 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 85s 2025-10-26 12:43:16,424 INFO User dédé@example.net successfully authenticated 85s 2025-10-26 12:43:16,431 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 85s 2025-10-26 12:43:16,437 INFO Trying to authenticate http://127.0.0.1:8083 again 85s 2025-10-26 12:43:16,441 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 85s 2025-10-26 12:43:16,448 INFO User dédé@example.test successfully authenticated 85s 2025-10-26 12:43:16,454 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 85s 2025-10-26 12:43:16,455 WARNING User already authenticated, dropping federated authentication request 85s 2025-10-26 12:43:16,456 WARNING User already authenticated, dropping federated authentication request 85s 2025-10-26 12:43:16,459 WARNING User already authenticated, dropping federated authentication request 85s 2025-10-26 12:43:16,459 WARNING User already authenticated, dropping federated authentication request 85s PASSED 85s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2025-10-26 12:43:16,471 INFO Trying to authenticate http://127.0.0.1:8080 again 85s 2025-10-26 12:43:16,475 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 85s 2025-10-26 12:43:16,482 INFO User dédé@example.com successfully authenticated 85s 2025-10-26 12:43:16,488 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 85s 2025-10-26 12:43:16,495 INFO Trying to authenticate http://127.0.0.1:8081 again 85s 2025-10-26 12:43:16,498 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 85s 2025-10-26 12:43:16,505 INFO User dédé@example.org successfully authenticated 85s 2025-10-26 12:43:16,511 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 85s 2025-10-26 12:43:16,517 INFO Trying to authenticate http://127.0.0.1:8082 again 85s 2025-10-26 12:43:16,520 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 85s 2025-10-26 12:43:16,527 INFO User dédé@example.net successfully authenticated 85s 2025-10-26 12:43:16,533 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 85s 2025-10-26 12:43:16,540 INFO Trying to authenticate http://127.0.0.1:8083 again 85s 2025-10-26 12:43:16,543 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 85s 2025-10-26 12:43:16,550 INFO User dédé@example.test successfully authenticated 85s 2025-10-26 12:43:16,556 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 85s 2025-10-26 12:43:16,557 INFO logout requested 85s 2025-10-26 12:43:16,558 INFO Logging out user dédé@example.com. 85s 2025-10-26 12:43:16,561 INFO User dédé@example.com logged out 85s 2025-10-26 12:43:16,563 INFO logout requested 85s 2025-10-26 12:43:16,564 INFO Logging out user dédé@example.org. 85s 2025-10-26 12:43:16,567 INFO User dédé@example.org logged out 85s 2025-10-26 12:43:16,569 INFO logout requested 85s 2025-10-26 12:43:16,570 INFO Logging out user dédé@example.net. 85s 2025-10-26 12:43:16,573 INFO User dédé@example.net logged out 85s 2025-10-26 12:43:16,575 INFO logout requested 85s 2025-10-26 12:43:16,576 INFO Logging out user dédé@example.test. 85s 2025-10-26 12:43:16,579 INFO User dédé@example.test logged out 85s PASSED 85s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2025-10-26 12:43:16,591 INFO Trying to authenticate http://127.0.0.1:8080 again 85s 2025-10-26 12:43:16,594 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 85s 2025-10-26 12:43:16,600 INFO User dédé@example.com successfully authenticated 85s 2025-10-26 12:43:16,606 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 85s 2025-10-26 12:43:16,613 INFO Trying to authenticate http://127.0.0.1:8081 again 85s 2025-10-26 12:43:16,616 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 85s 2025-10-26 12:43:16,622 INFO User dédé@example.org successfully authenticated 85s 2025-10-26 12:43:16,628 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 85s 2025-10-26 12:43:16,635 INFO Trying to authenticate http://127.0.0.1:8082 again 85s 2025-10-26 12:43:16,638 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 85s 2025-10-26 12:43:16,644 INFO User dédé@example.net successfully authenticated 85s 2025-10-26 12:43:16,650 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 85s 2025-10-26 12:43:16,657 INFO Trying to authenticate http://127.0.0.1:8083 again 85s 2025-10-26 12:43:16,660 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 85s 2025-10-26 12:43:16,666 INFO User dédé@example.test successfully authenticated 85s 2025-10-26 12:43:16,672 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 85s 2025-10-26 12:43:16,675 INFO Trying to authenticate http://127.0.0.1:8080 again 85s 2025-10-26 12:43:16,679 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 85s 2025-10-26 12:43:16,688 INFO User dédé@example.com successfully authenticated 85s 2025-10-26 12:43:16,692 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 85s 2025-10-26 12:43:16,695 INFO Trying to authenticate http://127.0.0.1:8081 again 85s 2025-10-26 12:43:16,698 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 85s 2025-10-26 12:43:16,705 INFO User dédé@example.org successfully authenticated 85s 2025-10-26 12:43:16,708 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 85s 2025-10-26 12:43:16,711 INFO Trying to authenticate http://127.0.0.1:8082 again 85s 2025-10-26 12:43:16,714 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 85s 2025-10-26 12:43:16,720 INFO User dédé@example.net successfully authenticated 85s 2025-10-26 12:43:16,723 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 85s 2025-10-26 12:43:16,727 INFO Trying to authenticate http://127.0.0.1:8083 again 85s 2025-10-26 12:43:16,730 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 85s 2025-10-26 12:43:16,736 INFO User dédé@example.test successfully authenticated 85s 2025-10-26 12:43:16,739 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 85s PASSED 85s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 85s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 85s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 85s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2025-10-26 12:43:16,766 INFO User test successfully authenticated 85s PASSED 85s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2025-10-26 12:43:16,778 INFO User test successfully authenticated 85s PASSED 85s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2025-10-26 12:43:16,787 INFO User test successfully authenticated 85s 2025-10-26 12:43:16,793 INFO User test successfully authenticated 85s 2025-10-26 12:43:16,800 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 85s 2025-10-26 12:43:16,803 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')) 85s PASSED 85s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2025-10-26 12:43:16,812 INFO User test successfully authenticated 85s PASSED 85s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2025-10-26 12:43:16,823 INFO User test successfully authenticated 85s PASSED 85s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2025-10-26 12:43:16,836 INFO User test successfully authenticated 85s 2025-10-26 12:43:16,844 INFO Sending SLO requests to service http://127.0.0.1:35059 for user test 85s 2025-10-26 12:43:16,846 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 85s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 85s PASSED 85s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2025-10-26 12:43:16,854 INFO User test successfully authenticated 85s PASSED 85s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 85s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 85s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 85s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 85s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 85s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 85s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 85s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 85s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 85s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 85s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 85s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 85s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 85s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 85s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 85s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 85s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 85s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 85s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 86s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 86s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 86s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 86s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2025-10-26 12:43:17,686 INFO User test successfully authenticated 86s 2025-10-26 12:43:17,689 WARNING Service https://www.example.org not allowed. 86s PASSED 86s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success 2025-10-26 12:43:17,697 INFO User test successfully authenticated 86s 2025-10-26 12:43:17,701 INFO Service ticket created for service https://www.example.com by user test. 86s PASSED 86s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt 2025-10-26 12:43:17,709 INFO User test successfully authenticated 86s 2025-10-26 12:43:17,713 INFO Service ticket created for service https://www.example.com by user test. 86s PASSED 86s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn 2025-10-26 12:43:17,721 INFO User test successfully authenticated 86s PASSED 86s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged 2025-10-26 12:43:17,732 INFO User test successfully authenticated 86s PASSED 86s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2025-10-26 12:43:17,742 INFO User test successfully authenticated 86s PASSED2025-10-26 12:43:17,746 WARNING User test seems authenticated but is not found in the database. 86s 2025-10-26 12:43:17,746 INFO Logging out user test. 86s 2025-10-26 12:43:17,747 INFO User test logged out 86s 86s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 86s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2025-10-26 12:43:17,758 INFO User test successfully authenticated 86s PASSED 86s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2025-10-26 12:43:17,761 WARNING Username test not allowed on service restrict_user_fail 86s 2025-10-26 12:43:17,770 WARNING A login attempt failed 86s 2025-10-26 12:43:17,779 WARNING A login attempt failed 86s 2025-10-26 12:43:17,787 WARNING A login attempt failed 86s PASSED 86s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2025-10-26 12:43:17,799 WARNING Received an invalid login ticket 86s PASSED 86s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2025-10-26 12:43:17,810 WARNING A login attempt failed 86s PASSED 86s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2025-10-26 12:43:17,821 INFO User test successfully authenticated 86s PASSED 86s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2025-10-26 12:43:17,832 INFO User test successfully authenticated 86s PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_renew 2025-10-26 12:43:18,424 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,431 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,434 INFO Service ticket created for service https://www.example.com by user test. 87s PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2025-10-26 12:43:18,443 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,450 INFO User test successfully authenticated 87s PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2025-10-26 12:43:18,453 INFO Service ticket created for service https://www.example.com by user test. 87s 2025-10-26 12:43:18,464 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,469 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 87s 2025-10-26 12:43:18,471 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 87s 2025-10-26 12:43:18,475 INFO Service ticket created for service https://filter_success.example.com by user test. 87s PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2025-10-26 12:43:18,485 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,488 WARNING Username test not allowed on service restrict_user_fail 87s 2025-10-26 12:43:18,492 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 87s PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2025-10-26 12:43:18,502 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,505 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 87s PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2025-10-26 12:43:18,508 INFO Service ticket created for service https://field_needed_success.example.com by user test. 87s 2025-10-26 12:43:18,516 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,519 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 87s PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2025-10-26 12:43:18,527 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,531 WARNING User test seems authenticated but is not found in the database. 87s 2025-10-26 12:43:18,531 INFO Logging out user test. 87s 2025-10-26 12:43:18,532 INFO User test logged out 87s PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2025-10-26 12:43:18,552 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,556 INFO Service ticket created for service https://www.example.com/é by user test. 87s PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2025-10-26 12:43:18,564 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,569 INFO Service ticket created for service https://www.example.com by user test. 87s PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2025-10-26 12:43:18,577 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,584 WARNING Received a login attempt for an already-active user 87s 2025-10-26 12:43:18,585 INFO Service ticket created for service https://www.example.com by user test. 87s PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2025-10-26 12:43:18,594 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,597 WARNING Service https://www.example.org not allowed. 87s PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2025-10-26 12:43:18,601 WARNING Service https://www.example.net not allowed. 87s PASSED 87s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2025-10-26 12:43:18,607 WARNING Service https://www.example.net not allowed. 87s PASSED 87s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2025-10-26 12:43:18,616 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,619 INFO logout requested 87s 2025-10-26 12:43:18,619 INFO Logging out user test. 87s 2025-10-26 12:43:18,622 INFO User test logged out 87s PASSED 87s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2025-10-26 12:43:18,628 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,634 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,641 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,647 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,653 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,660 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,667 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,669 INFO logout requested 87s 2025-10-26 12:43:18,670 INFO Logging out user test from all sessions. 87s 2025-10-26 12:43:18,686 INFO User test logged out 87s PASSED 87s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2025-10-26 12:43:18,693 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,695 INFO logout requested 87s 2025-10-26 12:43:18,696 INFO Logging out user test. 87s 2025-10-26 12:43:18,698 INFO User test logged out 87s PASSED 87s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2025-10-26 12:43:18,700 INFO logout requested 87s PASSED 87s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2025-10-26 12:43:18,707 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,713 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,715 INFO logout requested 87s 2025-10-26 12:43:18,716 INFO Logging out user test from all sessions. 87s 2025-10-26 12:43:18,721 INFO User test logged out 87s PASSED 87s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2025-10-26 12:43:18,728 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,733 INFO Service ticket created for service http://127.0.0.1:36465 by user test. 87s 2025-10-26 12:43:18,736 INFO Validate: Service ticket ST-UnigDeGcafJDjw4kQL4iKI7KCEAu2n6DXHHgPDibTYDOIPjX1ywNyfOVAGEPw validated, user test authenticated on service http://127.0.0.1:36465 87s 2025-10-26 12:43:18,737 INFO logout requested 87s 2025-10-26 12:43:18,737 INFO Logging out user test. 87s 2025-10-26 12:43:18,740 INFO Sending SLO requests to service http://127.0.0.1:36465 for user test 87s 2025-10-26 12:43:18,742 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 87s 2025-10-26 12:43:18,743 INFO User test logged out 87s 2025-10-26 12:43:18,748 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,752 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 87s 2025-10-26 12:43:18,754 INFO Validate: Service ticket ST-E12XewyGgoXdhd5eXPDmsWaT2zcwgE2t9Mq73QrHHEU12EK6mIDkyVfv8WCZ4 validated, user test authenticated on service http://127.0.0.1:45678 87s 2025-10-26 12:43:18,755 INFO logout requested 87s 2025-10-26 12:43:18,756 INFO Logging out user test. 87s 2025-10-26 12:43:18,757 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 87s 2025-10-26 12:43:18,759 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 87s 2025-10-26 12:43:18,761 INFO User test logged out 87s 2025-10-26 12:43:18,767 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,772 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 87s 2025-10-26 12:43:18,774 INFO Validate: Service ticket ST-oPKoTi0hV9WGAIpIYZWMCKwUvbo7aP2TzdzTtLwRi9VmuXQkzUHRpWugzDc5z validated, user test authenticated on service http://127.0.0.1:45678 87s 2025-10-26 12:43:18,775 INFO logout requested 87s 2025-10-26 12:43:18,776 INFO Logging out user test. 87s 2025-10-26 12:43:18,777 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 87s 2025-10-26 12:43:18,779 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=41773): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 87s 2025-10-26 12:43:18,780 INFO User test logged out 87s PASSED 87s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2025-10-26 12:43:18,788 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,794 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,798 INFO logout requested 87s 2025-10-26 12:43:18,798 INFO Logging out user test. 87s 2025-10-26 12:43:18,801 INFO User test logged out 87s PASSED 87s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2025-10-26 12:43:18,811 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,813 INFO logout requested 87s 2025-10-26 12:43:18,814 INFO Logging out user test. 87s 2025-10-26 12:43:18,816 INFO User test logged out 87s PASSED 87s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2025-10-26 12:43:18,826 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,828 INFO logout requested 87s 2025-10-26 12:43:18,828 INFO Logging out user test. 87s 2025-10-26 12:43:18,831 INFO User test logged out 87s PASSED 87s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2025-10-26 12:43:18,839 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,841 INFO logout requested 87s 2025-10-26 12:43:18,842 INFO Logging out user test. 87s 2025-10-26 12:43:18,844 INFO User test logged out 87s PASSED 87s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2025-10-26 12:43:18,851 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,853 INFO logout requested 87s 2025-10-26 12:43:18,853 INFO Logging out user test. 87s 2025-10-26 12:43:18,856 INFO User test logged out 87s 2025-10-26 12:43:18,862 INFO logout requested 87s PASSED 87s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 87s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 87s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice 2025-10-26 12:43:18,880 WARNING Service https://www.example.org not allowed. 87s PASSED 87s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings PASSED 87s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 87s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2025-10-26 12:43:18,909 INFO User test successfully authenticated 87s PASSED 87s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 87s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2025-10-26 12:43:18,923 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,937 INFO Service ticket created for service https://www.example.com by user test. 87s 2025-10-26 12:43:18,938 WARNING Validate: service or ticket missing 87s 2025-10-26 12:43:18,939 WARNING Validate: service or ticket missing 87s PASSED 87s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2025-10-26 12:43:18,945 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,953 INFO Service ticket created for service https://www.example.com by user test. 87s 2025-10-26 12:43:18,956 INFO Validate: Service ticket ST-tcTgNJXVdayLoJNHe4wVsgwcsf7JVrkAKLXC8Igz7VXSjGhFqZggBu8n5XLSX validated, user test authenticated on service https://www.example.com 87s 2025-10-26 12:43:18,961 INFO User test successfully authenticated 87s 2025-10-26 12:43:18,963 INFO Service ticket created for service https://www.example.com by user test. 87s 2025-10-26 12:43:18,967 INFO Validate: Service ticket ST-hiTZGPzNwfM5nTjsYUTFtXVU5lQ7PvT3q8nxC1JuQzfyUNe1OuLBkgrfPNSlh validated, user test authenticated on service https://www.example.com 88s 2025-10-26 12:43:18,977 INFO Service ticket created for service https://www.example.com by user test. 88s 2025-10-26 12:43:18,978 WARNING Validate: Service ticket ST-JSsA5q25FILCXeF7FGB3gaXbV6S2BmH9LvVPMwKhNG8PF7PWqWZcPFPen7wuB not found or already validated, auth to https://www.example.com failed 88s PASSED 88s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2025-10-26 12:43:18,984 INFO User test successfully authenticated 88s 2025-10-26 12:43:18,990 INFO Service ticket created for service https://user_field.example.com by user test. 88s 2025-10-26 12:43:18,992 INFO Validate: Service ticket ST-MDXDABWJcxjtX47nLg57zxHxuiz42CFRhUEGC7ZEaoH1HjcLlrcLwv9llgSt8 validated, user test authenticated on service https://user_field.example.com 88s 2025-10-26 12:43:18,997 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,006 INFO Service ticket created for service https://user_field_alt.example.com by user test. 88s 2025-10-26 12:43:19,008 INFO Validate: Service ticket ST-ZlI2oiUqmk11FcqGjBO8p5CNJhQOitKnQ1Tus0BGcBEFT6bs5kvW5horiiJUN validated, user test authenticated on service https://user_field_alt.example.com 88s PASSED 88s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2025-10-26 12:43:19,015 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,020 INFO Service ticket created for service https://www.example.com by user test. 88s 2025-10-26 12:43:19,022 WARNING Validate: Service ticket ST-mT2qkg4SVYjIhyQluHtCq76CV14ZxfHmsfATd5NTMWN2vutkznR7pRh8ZIgkf not found or already validated, auth to https://www.example.org failed 88s PASSED 88s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2025-10-26 12:43:19,027 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,033 INFO Service ticket created for service https://www.example.com by user test. 88s 2025-10-26 12:43:19,034 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 88s PASSED 88s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2025-10-26 12:43:19,040 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,047 INFO Service ticket created for service https://www.example.com by user test. 88s 2025-10-26 12:43:19,052 INFO Validate: Service ticket ST-qo8kKyioQ1fvgQJ2tTyObwJuusauxF3E8fCgSwQqojukCUfFM6DpgXHomlzsE validated, user test authenticated on service https://www.example.com 88s PASSED 88s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2025-10-26 12:43:19,060 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,065 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 88s 2025-10-26 12:43:19,066 WARNING ValidateService: missing ticket or service 88s 2025-10-26 12:43:19,067 WARNING ValidateService: missing ticket or service 88s PASSED 88s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2025-10-26 12:43:19,079 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,087 INFO Service ticket created for service http://127.0.0.1:39893 by user test. 88s 2025-10-26 12:43:19,092 INFO ValidateService: ticket ST-LVhhBUxwZ6kam3eDSilqm4TFCOvzn20yQqAb0e7GacBTONNCa2VwXIhXaOplR validated for user test on service http://127.0.0.1:39893. Proxy Granting Ticket transmited to http://127.0.0.1:39893. 88s 2025-10-26 12:43:19,099 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 88s 2025-10-26 12:43:19,102 INFO ValidateService: ticket PT-LcyiaSzqVq6rYoQNr9uk2m6ksgjmumWidt83UxWAHFg3Blc3G8sLFX7BiWRel validated for user test on service http://127.0.0.1:45678. 88s 2025-10-26 12:43:19,108 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,113 INFO Service ticket created for service http://127.0.0.1:45743 by user test. 88s 2025-10-26 12:43:19,119 INFO ValidateService: ticket ST-SOh4saLRQGNNAU1OEfhDpCuPQI1LyYjgq420uSky0ZM8duEKKqTga4V2rtgFU validated for user test on service http://127.0.0.1:45743. Proxy Granting Ticket transmited to http://127.0.0.1:45743. 88s 2025-10-26 12:43:19,122 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 88s 2025-10-26 12:43:19,123 WARNING ValidateService: validation error: INVALID_TICKET PT-DGYBDML1iCvkjSFSVAl6vNYlLyhibLDWN8OZVys5LXoKnkBVRJbVlgiXhkPs8 88s PASSED 88s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2025-10-26 12:43:19,129 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,135 INFO Service ticket created for service https://replace_attribute.example.com by user test. 88s 2025-10-26 12:43:19,137 INFO ValidateService: ticket ST-QQUy0AJlWrTXrIokem1XKl8ypa3K8g5026aOW1d5WJ1tlvM27BZPFROLAlaRM validated for user test on service https://replace_attribute.example.com. 88s 2025-10-26 12:43:19,142 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,147 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 88s 2025-10-26 12:43:19,150 INFO ValidateService: ticket ST-g02XFjtBdRjlK5EOfNGk2x8Pg77daZNNGx6GlbAidbYhGbeVYnG0VKrw53VeD validated for user test on service https://replace_attribute_list.example.com. 88s PASSED 88s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2025-10-26 12:43:19,158 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,162 INFO Service ticket created for service http://127.0.0.1:39655 by user test. 88s 2025-10-26 12:43:19,167 INFO ValidateService: ticket ST-g720d4Tv3n7U3aakJY2bISYh0nq2LE9XP9lSBWGpjnxhSohoUWSUEWCEGgULb validated for user test on service http://127.0.0.1:39655. Proxy Granting Ticket transmited to http://127.0.0.1:39655. 88s PASSED 88s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2025-10-26 12:43:19,175 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,179 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 88s 2025-10-26 12:43:19,183 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 88s 2025-10-26 12:43:19,187 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,191 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 88s 2025-10-26 12:43:19,196 WARNING Service https://www.example.org not allowed. 88s 2025-10-26 12:43:19,196 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 88s PASSED 88s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2025-10-26 12:43:19,206 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,210 INFO Service ticket created for service https://127.0.0.1:44571 by user test. 88s 2025-10-26 12:43:19,217 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=44571): Max retries exceeded with url: /?pgtId=PGT-xHR1F3bPoZyMPSXyjTEUnFYKphoJs66hPdXavbeZo7aL0HPrWKFbMfDLl5Iv&pgtIou=PGTIOU-KRUDO8nQdiJB2U6KNyJhpOXKa4AJUGi26dLundH7Ykw5P60LPXroe0tn4 (Caused by SSLError(SSLError(1, '[SSL: RECORD_LAYER_FAILURE] record layer failure (_ssl.c:1033)'))) 88s PASSED 88s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2025-10-26 12:43:19,223 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,226 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 88s 2025-10-26 12:43:19,228 INFO ValidateService: ticket ST-jNCTaUESh35fLMKeEdXlZ09IaFOEuLfTeYZTunLzT8SCalABS9vmonF0Xr6Qa validated for user test on service http://127.0.0.1:45678. 88s 2025-10-26 12:43:19,234 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,236 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 88s PASSED2025-10-26 12:43:19,239 INFO ValidateService: ticket ST-5sPzlctTyC9ZgB3PQ1cFjLgNUn894DwZLiFyJmR06LVG1nKIFAVulk4TvYF4Y validated for user test on service http://127.0.0.1:45678. 88s 2025-10-26 12:43:19,241 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 88s 2025-10-26 12:43:19,244 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 88s 88s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2025-10-26 12:43:19,251 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,255 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 88s 2025-10-26 12:43:19,260 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 88s PASSED 88s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2025-10-26 12:43:19,267 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,271 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 88s 2025-10-26 12:43:19,273 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 88s PASSED 88s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2025-10-26 12:43:19,280 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,285 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 88s 2025-10-26 12:43:19,291 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 88s PASSED 88s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2025-10-26 12:43:19,298 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,302 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 88s 2025-10-26 12:43:19,306 INFO ValidateService: ticket ST-X1OuJEfq4461q6hrOisxxx17hdN9wW7fkI8UjTuRMVtzaMsBeEvu1xPtWykcy validated for user test on service http://127.0.0.1:45678. 88s PASSED 88s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2025-10-26 12:43:19,313 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,319 INFO Service ticket created for service https://one_attribute.example.com by user test. 88s 2025-10-26 12:43:19,321 INFO ValidateService: ticket ST-7ZjREKIhZ5TUQYxvxq1UtoNg0jy0TT9yPn6OMKMnWa39E5vKexSZ4jU0qteKY validated for user test on service https://one_attribute.example.com. 88s PASSED 88s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2025-10-26 12:43:19,331 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,336 INFO Service ticket created for service http://127.0.0.1:45941 by user test. 88s 2025-10-26 12:43:19,342 INFO ValidateService: ticket ST-JWQCAYKrZP5meZxB6hhdGJkXpRzqH5PzorgyyaxMbUOr1jTbsP8nR8fDHfaLj validated for user test on service http://127.0.0.1:45941. Proxy Granting Ticket transmited to http://127.0.0.1:45941. 88s PASSED 88s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2025-10-26 12:43:19,350 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,354 INFO Service ticket created for service https://user_field.example.com by user test. 88s 2025-10-26 12:43:19,358 INFO ValidateService: ticket ST-xVu02OQOFmebRFbJBWCrXzwA1mafluI6HgZX9VtSTUWO1OZOgViw9oYMm16cZ validated for user test on service https://user_field.example.com. 88s 2025-10-26 12:43:19,362 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,366 INFO Service ticket created for service https://user_field_alt.example.com by user test. 88s 2025-10-26 12:43:19,370 INFO ValidateService: ticket ST-o3vHdI4jGDxFWWu4UuipFEfGid9I3uqEj93mrJofBqtfkFPN0WGQtrW2MwzTY validated for user test on service https://user_field_alt.example.com. 88s PASSED 88s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2025-10-26 12:43:19,380 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,385 INFO Service ticket created for service http://127.0.0.1:44711 by user test. 88s 2025-10-26 12:43:19,391 INFO ValidateService: ticket ST-fOSGmtAfM5luprXndSiGVHYfJnH6uPQjxqYSnia2zttj2hmOj0i15taf1an3j validated for user test on service http://127.0.0.1:44711. Proxy Granting Ticket transmited to http://127.0.0.1:44711. 88s 2025-10-26 12:43:19,392 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 88s 2025-10-26 12:43:19,392 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 88s PASSED 88s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2025-10-26 12:43:19,400 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,405 INFO Service ticket created for service http://127.0.0.1:34373 by user test. 88s 2025-10-26 12:43:19,410 INFO ValidateService: ticket ST-xunl0WhmB8jrQ43q1MREgALE8h0V76RSjoAfaJRASd6SWucC9aiHQEcfJlvOk validated for user test on service http://127.0.0.1:34373. Proxy Granting Ticket transmited to http://127.0.0.1:34373. 88s 2025-10-26 12:43:19,412 WARNING Username test not allowed on service restrict_user_fail 88s 2025-10-26 12:43:19,412 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 88s 2025-10-26 12:43:19,414 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 88s 2025-10-26 12:43:19,414 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 88s 2025-10-26 12:43:19,415 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 88s 2025-10-26 12:43:19,416 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 88s PASSED 88s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2025-10-26 12:43:19,424 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,428 INFO Service ticket created for service http://127.0.0.1:43327 by user test. 88s 2025-10-26 12:43:19,435 INFO ValidateService: ticket ST-5GbgcVFxrrlTrOgGiaih9PLvBe8MtrFPMvYGvvNBGXvfJ3vFiwVGRhcUnCs1Q validated for user test on service http://127.0.0.1:43327. Proxy Granting Ticket transmited to http://127.0.0.1:43327. 88s 2025-10-26 12:43:19,437 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 88s PASSED 88s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2025-10-26 12:43:19,447 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,451 INFO Service ticket created for service http://127.0.0.1:45285 by user test. 88s 2025-10-26 12:43:19,456 INFO ValidateService: ticket ST-7ePP0z58KkSmDuGZNZhDUYan3XleugpS7aJ9E3KLhFtJeaE4PahuMkOvs054C validated for user test on service http://127.0.0.1:45285. Proxy Granting Ticket transmited to http://127.0.0.1:45285. 88s 2025-10-26 12:43:19,460 WARNING Service https://www.example.org not allowed. 88s 2025-10-26 12:43:19,460 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 88s 2025-10-26 12:43:19,461 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:45285 does not allow proxy tickets 88s PASSED 88s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2025-10-26 12:43:19,470 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,475 INFO Service ticket created for service http://127.0.0.1:46669 by user test. 88s 2025-10-26 12:43:19,481 INFO ValidateService: ticket ST-1dwnU05mQb6Y04WsrhP8KbgsiF7U9GdcFqrkbfd53FSGkh0dlpQTRecj4jwD2 validated for user test on service http://127.0.0.1:46669. Proxy Granting Ticket transmited to http://127.0.0.1:46669. 88s 2025-10-26 12:43:19,484 INFO Proxy ticket created for user test on service https://www.example.com. 88s 2025-10-26 12:43:19,486 INFO ValidateService: ticket PT-2zUkXero75vDsswP9t03J9Fur77eRiyKQLpuybenpSWnmBfGVaFKxGsM4F1UT validated for user test on service https://www.example.com. 88s PASSED 88s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2025-10-26 12:43:19,496 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,501 INFO Service ticket created for service https://www.example.com by user test. 88s 2025-10-26 12:43:19,503 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 88s PASSED 88s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2025-10-26 12:43:19,509 WARNING SamlValidate: validation error: AuthnFailed ticket ST-wETqVLODjwaZvFcKGsiyxUSKZ6zqzix0jLzJYeLNpxoOnrhj8QLc2VlPYRajj not found 88s 2025-10-26 12:43:19,510 WARNING SamlValidate: validation error: AuthnFailed ticket PT-34Gw1iCJHZnp4jZ5LqJVcRW6DK30xnAVJhZpLFSpA8eHDj6g45T0XaR0zCI1v not found 88s PASSED 88s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix 2025-10-26 12:43:19,513 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 88s PASSED 88s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml 2025-10-26 12:43:19,516 WARNING SamlValidate: validation error: VersionMismatch 88s PASSED 88s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2025-10-26 12:43:19,526 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,531 INFO Service ticket created for service https://www.example.com by user test. 88s 2025-10-26 12:43:19,541 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,545 INFO Service ticket created for service http://127.0.0.1:39297 by user test. 88s 2025-10-26 12:43:19,552 INFO ValidateService: ticket ST-MnDyUv9i3QxtL7YVVNhfAidh2GCl8QIUArSSfM0RdWqdAOtKXG6QxsyNWmvxY validated for user test on service http://127.0.0.1:39297. Proxy Granting Ticket transmited to http://127.0.0.1:39297. 88s 2025-10-26 12:43:19,555 INFO Proxy ticket created for user test on service https://www.example.com. 88s 2025-10-26 12:43:19,557 INFO SamlValidate: ticket ST-FxRrORBiptM6qT6IPlOKuWbK3ZRAwJz4CH3zK19uu56uXqgq8dw8PBlqDH9EK validated for user test on service https://www.example.com. 88s 2025-10-26 12:43:19,559 INFO SamlValidate: ticket PT-0wiPUKNWERsBFEBDJLcuUrFfNmKS73UgVz1N4j0NbMMz9fYYSswJsS0LoQ7MV validated for user test on service https://www.example.com. 88s PASSED 88s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2025-10-26 12:43:19,568 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,572 INFO Service ticket created for service https://field_needed_success.example.com by user test. 88s 2025-10-26 12:43:19,575 INFO SamlValidate: ticket ST-wdPRoIX9xIbappxouDs5Dyso2oSWFVbtZvJ07qkkFcDr1iiJEyxmb1JcJkZiY validated for user test on service https://field_needed_success.example.com. 88s 2025-10-26 12:43:19,580 INFO User test successfully authenticated 88s 2025-10-26 12:43:19,585 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 88s 2025-10-26 12:43:19,587 INFO SamlValidate: ticket ST-sRdIFxu287fHkKLERqDpUxr7gTrWlBYItbnucgL2lPfk3hj1zcPQwmy9vZtTw validated for user test on service https://field_needed_success_alt.example.com. 88s PASSEDDestroying test database for alias 'default'... 88s 88s 88s =============================== warnings summary =============================== 88s cas_server/tests/test_federate.py: 8 warnings 88s cas_server/tests/test_view.py: 3 warnings 88s /tmp/autopkgtest.uNEuAX/autopkgtest_tmp/cas_server/utils.py:211: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC). 88s datetime.utcnow() + timedelta(seconds=max_age), 88s 88s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 88s /usr/lib/python3.13/unittest/case.py:707: DeprecationWarning: It is deprecated to return a value that is not None from a test case (>) 88s return self.run(*args, **kwds) 88s 88s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 88s ======================= 128 passed, 12 warnings in 4.63s ======================= 88s 89s autopkgtest [12:43:20]: test run-test-suite.sh: -----------------------] 89s autopkgtest [12:43:20]: test run-test-suite.sh: - - - - - - - - - - results - - - - - - - - - - 89s run-test-suite.sh PASS 89s autopkgtest [12:43:20]: @@@@@@@@@@@@@@@@@@@@ summary 89s run-test-suite.sh PASS