0s autopkgtest [12:10:16]: starting date and time: 2025-10-26 12:10:16+0000 0s autopkgtest [12:10:16]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [12:10:16]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.4hdwwkfz/out --timeout-copy=6000 -a i386 --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 builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-3.secgroup --name adt-resolute-i386-django-cas-server-20251026-121015-juju-7f2275-prod-proposed-migration-environment-2-8dccb3e0-ec9e-4da0-ba8c-ba8af51bbfea --image adt/ubuntu-resolute-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-i386-django-cas-server-20251026-121015-juju-7f2275-prod-proposed-migration-environment-2-8dccb3e0-ec9e-4da0-ba8c-ba8af51bbfea from image adt/ubuntu-resolute-amd64-server-20251026.img (UUID 0295c187-b5ab-4a71-8f81-9453d0ae5b0a)... 65s autopkgtest [12:11:21]: testbed dpkg architecture: amd64 66s autopkgtest [12:11:22]: testbed apt version: 3.1.8ubuntu1 66s autopkgtest [12:11:22]: @@@@@@@@@@@@@@@@@@@@ test bed setup 66s autopkgtest [12:11:22]: testbed release detected to be: None 67s autopkgtest [12:11:23]: updating testbed package index (apt update) 68s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [87.8 kB] 68s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 68s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 68s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 68s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [61.7 kB] 68s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [422 kB] 68s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [16.5 kB] 68s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5028 B] 68s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [111 kB] 68s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [162 kB] 68s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [3356 B] 68s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [3744 B] 68s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [28.6 kB] 68s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [336 B] 68s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [312 kB] 68s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [137 kB] 68s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [11.1 kB] 68s Get:18 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [6132 B] 68s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [2476 B] 68s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [492 B] 70s Fetched 1373 kB in 1s (1117 kB/s) 71s Reading package lists... 71s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 71s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 72s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 72s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 73s Reading package lists... 73s Reading package lists... 73s Building dependency tree... 73s Reading state information... 73s Calculating upgrade... 73s The following packages will be upgraded: 73s binutils binutils-common binutils-x86-64-linux-gnu gir1.2-glib-2.0 73s libbinutils libctf-nobfd0 libctf0 libglib2.0-0t64 libglib2.0-data 73s libgprofng0 libpython3-stdlib libsframe2 python3 python3-minimal 73s 14 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 73s Need to get 5384 kB of archives. 73s After this operation, 728 kB of additional disk space will be used. 73s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-minimal amd64 3.13.7-2 [27.8 kB] 74s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3 amd64 3.13.7-2 [23.9 kB] 74s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 libpython3-stdlib amd64 3.13.7-2 [10.6 kB] 74s Get:4 http://ftpmaster.internal/ubuntu resolute/main amd64 gir1.2-glib-2.0 amd64 2.86.1-1 [182 kB] 74s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 libglib2.0-0t64 amd64 2.86.1-1 [1692 kB] 75s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 libglib2.0-data all 2.86.1-1 [56.7 kB] 75s Get:7 http://ftpmaster.internal/ubuntu resolute/main amd64 libgprofng0 amd64 2.45-8ubuntu1 [930 kB] 75s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 libctf0 amd64 2.45-8ubuntu1 [104 kB] 75s Get:9 http://ftpmaster.internal/ubuntu resolute/main amd64 libctf-nobfd0 amd64 2.45-8ubuntu1 [106 kB] 75s Get:10 http://ftpmaster.internal/ubuntu resolute/main amd64 binutils-x86-64-linux-gnu amd64 2.45-8ubuntu1 [1177 kB] 75s Get:11 http://ftpmaster.internal/ubuntu resolute/main amd64 libbinutils amd64 2.45-8ubuntu1 [624 kB] 76s Get:12 http://ftpmaster.internal/ubuntu resolute/main amd64 binutils amd64 2.45-8ubuntu1 [211 kB] 76s Get:13 http://ftpmaster.internal/ubuntu resolute/main amd64 binutils-common amd64 2.45-8ubuntu1 [221 kB] 76s Get:14 http://ftpmaster.internal/ubuntu resolute/main amd64 libsframe2 amd64 2.45-8ubuntu1 [17.0 kB] 76s dpkg-preconfigure: unable to re-open stdin: No such file or directory 76s Fetched 5384 kB in 2s (2249 kB/s) 76s (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 ... 78454 files and directories currently installed.) 76s Preparing to unpack .../python3-minimal_3.13.7-2_amd64.deb ... 76s Unpacking python3-minimal (3.13.7-2) over (3.13.7-1) ... 76s Setting up python3-minimal (3.13.7-2) ... 77s (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 ... 78454 files and directories currently installed.) 77s Preparing to unpack .../00-python3_3.13.7-2_amd64.deb ... 77s running python pre-rtupdate hooks for python3.13... 77s Unpacking python3 (3.13.7-2) over (3.13.7-1) ... 77s Preparing to unpack .../01-libpython3-stdlib_3.13.7-2_amd64.deb ... 77s Unpacking libpython3-stdlib:amd64 (3.13.7-2) over (3.13.7-1) ... 77s Preparing to unpack .../02-gir1.2-glib-2.0_2.86.1-1_amd64.deb ... 77s Unpacking gir1.2-glib-2.0:amd64 (2.86.1-1) over (2.86.0-2) ... 77s Preparing to unpack .../03-libglib2.0-0t64_2.86.1-1_amd64.deb ... 77s Unpacking libglib2.0-0t64:amd64 (2.86.1-1) over (2.86.0-2) ... 77s Preparing to unpack .../04-libglib2.0-data_2.86.1-1_all.deb ... 77s Unpacking libglib2.0-data (2.86.1-1) over (2.86.0-2) ... 77s Preparing to unpack .../05-libgprofng0_2.45-8ubuntu1_amd64.deb ... 77s Unpacking libgprofng0:amd64 (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 77s Preparing to unpack .../06-libctf0_2.45-8ubuntu1_amd64.deb ... 77s Unpacking libctf0:amd64 (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 77s Preparing to unpack .../07-libctf-nobfd0_2.45-8ubuntu1_amd64.deb ... 77s Unpacking libctf-nobfd0:amd64 (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 77s Preparing to unpack .../08-binutils-x86-64-linux-gnu_2.45-8ubuntu1_amd64.deb ... 77s Unpacking binutils-x86-64-linux-gnu (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 77s Preparing to unpack .../09-libbinutils_2.45-8ubuntu1_amd64.deb ... 77s Unpacking libbinutils:amd64 (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 78s Preparing to unpack .../10-binutils_2.45-8ubuntu1_amd64.deb ... 78s Unpacking binutils (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 78s Preparing to unpack .../11-binutils-common_2.45-8ubuntu1_amd64.deb ... 78s Unpacking binutils-common:amd64 (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 78s Preparing to unpack .../12-libsframe2_2.45-8ubuntu1_amd64.deb ... 78s Unpacking libsframe2:amd64 (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 78s Setting up libsframe2:amd64 (2.45-8ubuntu1) ... 78s Setting up binutils-common:amd64 (2.45-8ubuntu1) ... 78s Setting up libctf-nobfd0:amd64 (2.45-8ubuntu1) ... 78s Setting up libglib2.0-0t64:amd64 (2.86.1-1) ... 78s No schema files found: doing nothing. 78s Setting up libglib2.0-data (2.86.1-1) ... 78s Setting up gir1.2-glib-2.0:amd64 (2.86.1-1) ... 78s Setting up libbinutils:amd64 (2.45-8ubuntu1) ... 78s Setting up libpython3-stdlib:amd64 (3.13.7-2) ... 78s Setting up libctf0:amd64 (2.45-8ubuntu1) ... 78s Setting up python3 (3.13.7-2) ... 78s running python rtupdate hooks for python3.13... 78s running python post-rtupdate hooks for python3.13... 78s Setting up libgprofng0:amd64 (2.45-8ubuntu1) ... 78s Setting up binutils-x86-64-linux-gnu (2.45-8ubuntu1) ... 78s Setting up binutils (2.45-8ubuntu1) ... 78s Processing triggers for libc-bin (2.42-0ubuntu3) ... 78s Processing triggers for man-db (2.13.1-1) ... 79s autopkgtest [12:11:35]: upgrading testbed (apt dist-upgrade and autopurge) 79s Reading package lists... 79s Building dependency tree... 79s Reading state information... 79s Calculating upgrade... 80s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 80s Reading package lists... 80s Building dependency tree... 80s Reading state information... 80s Solving dependencies... 80s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 83s autopkgtest [12:11:39]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 10:00:33 UTC 2025 83s autopkgtest [12:11:39]: @@@@@@@@@@@@@@@@@@@@ apt-source django-cas-server 86s Get:1 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (dsc) [2597 B] 86s Get:2 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (tar) [138 kB] 86s Get:3 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (diff) [10.2 kB] 86s gpgv: Signature made Sat Dec 14 20:43:18 2024 UTC 86s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 86s gpgv: issuer "tchet@debian.org" 86s gpgv: Can't check signature: No public key 86s dpkg-source: warning: cannot verify inline signature for ./django-cas-server_2.1.0-3.dsc: no acceptable signature found 86s autopkgtest [12:11:42]: testing package django-cas-server version 2.1.0-3 87s autopkgtest [12:11:43]: build not needed 88s autopkgtest [12:11:44]: test run-test-suite.sh: preparing testbed 88s Reading package lists... 88s Building dependency tree... 88s Reading state information... 88s Solving dependencies... 89s The following NEW packages will be installed: 89s fonts-font-awesome fonts-lato libjs-jquery libjs-sphinxdoc libjs-underscore 89s libpython3.14-minimal libpython3.14-stdlib libxslt1.1 python3-all 89s python3-asgiref python3-django python3-django-cas-server 89s python3-django-cas-server-doc python3-iniconfig python3-legacy-cgi 89s python3-lxml python3-packaging python3-pluggy python3-pytest 89s python3-pytest-django python3-requests-futures python3-sqlparse python3.14 89s python3.14-minimal sphinx-rtd-theme-common 89s 0 upgraded, 25 newly installed, 0 to remove and 0 not upgraded. 89s Need to get 17.7 MB of archives. 89s After this operation, 82.5 MB of additional disk space will be used. 89s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 fonts-lato all 2.015-1 [2781 kB] 90s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-minimal amd64 3.14.0-4 [906 kB] 91s Get:3 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14-minimal amd64 3.14.0-4 [2559 kB] 91s Get:4 http://ftpmaster.internal/ubuntu resolute/main amd64 fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 91s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 91s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 91s Get:7 http://ftpmaster.internal/ubuntu resolute/main amd64 libjs-sphinxdoc all 8.2.3-1ubuntu2 [28.0 kB] 91s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-stdlib amd64 3.14.0-4 [2397 kB] 92s Get:9 http://ftpmaster.internal/ubuntu resolute/main amd64 libxslt1.1 amd64 1.1.43-0.1 [172 kB] 92s Get:10 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14 amd64 3.14.0-4 [805 kB] 92s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-all amd64 3.13.7-2 [890 B] 92s Get:12 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-asgiref all 3.9.1-2 [24.9 kB] 92s Get:13 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-sqlparse all 0.5.3-1 [32.9 kB] 92s Get:14 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-django all 3:5.2.4-1ubuntu2 [3036 kB] 92s Get:15 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-legacy-cgi all 2.6.3-1 [16.8 kB] 92s Get:16 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-lxml amd64 6.0.1-1build1 [2336 kB] 93s Get:17 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-requests-futures all 1.0.2-1 [12.0 kB] 93s Get:18 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-django-cas-server all 2.1.0-3 [111 kB] 93s Get:19 http://ftpmaster.internal/ubuntu resolute/main amd64 sphinx-rtd-theme-common all 3.0.2+dfsg-3 [1013 kB] 93s Get:20 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-django-cas-server-doc all 2.1.0-3 [178 kB] 93s Get:21 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-iniconfig all 1.1.1-2 [6024 B] 93s Get:22 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-packaging all 25.0-1 [52.8 kB] 93s Get:23 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pluggy all 1.6.0-1 [21.0 kB] 93s Get:24 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest all 8.3.5-2 [252 kB] 93s Get:25 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest-django all 4.11.1-2 [21.8 kB] 93s Fetched 17.7 MB in 4s (4372 kB/s) 93s Selecting previously unselected package fonts-lato. 93s (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 ... 78454 files and directories currently installed.) 93s Preparing to unpack .../00-fonts-lato_2.015-1_all.deb ... 93s Unpacking fonts-lato (2.015-1) ... 93s Selecting previously unselected package libpython3.14-minimal:amd64. 93s Preparing to unpack .../01-libpython3.14-minimal_3.14.0-4_amd64.deb ... 93s Unpacking libpython3.14-minimal:amd64 (3.14.0-4) ... 93s Selecting previously unselected package python3.14-minimal. 93s Preparing to unpack .../02-python3.14-minimal_3.14.0-4_amd64.deb ... 93s Unpacking python3.14-minimal (3.14.0-4) ... 93s Selecting previously unselected package fonts-font-awesome. 93s Preparing to unpack .../03-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 93s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 93s Selecting previously unselected package libjs-jquery. 93s Preparing to unpack .../04-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 93s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 93s Selecting previously unselected package libjs-underscore. 94s Preparing to unpack .../05-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 94s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 94s Selecting previously unselected package libjs-sphinxdoc. 94s Preparing to unpack .../06-libjs-sphinxdoc_8.2.3-1ubuntu2_all.deb ... 94s Unpacking libjs-sphinxdoc (8.2.3-1ubuntu2) ... 94s Selecting previously unselected package libpython3.14-stdlib:amd64. 94s Preparing to unpack .../07-libpython3.14-stdlib_3.14.0-4_amd64.deb ... 94s Unpacking libpython3.14-stdlib:amd64 (3.14.0-4) ... 94s Selecting previously unselected package libxslt1.1:amd64. 94s Preparing to unpack .../08-libxslt1.1_1.1.43-0.1_amd64.deb ... 94s Unpacking libxslt1.1:amd64 (1.1.43-0.1) ... 94s Selecting previously unselected package python3.14. 94s Preparing to unpack .../09-python3.14_3.14.0-4_amd64.deb ... 94s Unpacking python3.14 (3.14.0-4) ... 94s Selecting previously unselected package python3-all. 94s Preparing to unpack .../10-python3-all_3.13.7-2_amd64.deb ... 94s Unpacking python3-all (3.13.7-2) ... 94s Selecting previously unselected package python3-asgiref. 94s Preparing to unpack .../11-python3-asgiref_3.9.1-2_all.deb ... 94s Unpacking python3-asgiref (3.9.1-2) ... 94s Selecting previously unselected package python3-sqlparse. 94s Preparing to unpack .../12-python3-sqlparse_0.5.3-1_all.deb ... 94s Unpacking python3-sqlparse (0.5.3-1) ... 94s Selecting previously unselected package python3-django. 94s Preparing to unpack .../13-python3-django_3%3a5.2.4-1ubuntu2_all.deb ... 94s Unpacking python3-django (3:5.2.4-1ubuntu2) ... 94s Selecting previously unselected package python3-legacy-cgi. 94s Preparing to unpack .../14-python3-legacy-cgi_2.6.3-1_all.deb ... 94s Unpacking python3-legacy-cgi (2.6.3-1) ... 94s Selecting previously unselected package python3-lxml:amd64. 94s Preparing to unpack .../15-python3-lxml_6.0.1-1build1_amd64.deb ... 94s Unpacking python3-lxml:amd64 (6.0.1-1build1) ... 94s Selecting previously unselected package python3-requests-futures. 94s Preparing to unpack .../16-python3-requests-futures_1.0.2-1_all.deb ... 94s Unpacking python3-requests-futures (1.0.2-1) ... 94s Selecting previously unselected package python3-django-cas-server. 94s Preparing to unpack .../17-python3-django-cas-server_2.1.0-3_all.deb ... 94s Unpacking python3-django-cas-server (2.1.0-3) ... 94s Selecting previously unselected package sphinx-rtd-theme-common. 94s Preparing to unpack .../18-sphinx-rtd-theme-common_3.0.2+dfsg-3_all.deb ... 94s Unpacking sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 95s Selecting previously unselected package python3-django-cas-server-doc. 95s Preparing to unpack .../19-python3-django-cas-server-doc_2.1.0-3_all.deb ... 95s Unpacking python3-django-cas-server-doc (2.1.0-3) ... 95s Selecting previously unselected package python3-iniconfig. 95s Preparing to unpack .../20-python3-iniconfig_1.1.1-2_all.deb ... 95s Unpacking python3-iniconfig (1.1.1-2) ... 95s Selecting previously unselected package python3-packaging. 95s Preparing to unpack .../21-python3-packaging_25.0-1_all.deb ... 95s Unpacking python3-packaging (25.0-1) ... 95s Selecting previously unselected package python3-pluggy. 95s Preparing to unpack .../22-python3-pluggy_1.6.0-1_all.deb ... 95s Unpacking python3-pluggy (1.6.0-1) ... 95s Selecting previously unselected package python3-pytest. 95s Preparing to unpack .../23-python3-pytest_8.3.5-2_all.deb ... 95s Unpacking python3-pytest (8.3.5-2) ... 95s Selecting previously unselected package python3-pytest-django. 95s Preparing to unpack .../24-python3-pytest-django_4.11.1-2_all.deb ... 95s Unpacking python3-pytest-django (4.11.1-2) ... 95s Setting up python3-iniconfig (1.1.1-2) ... 95s Setting up fonts-lato (2.015-1) ... 95s Setting up libpython3.14-minimal:amd64 (3.14.0-4) ... 95s Setting up python3-packaging (25.0-1) ... 96s Setting up python3-sqlparse (0.5.3-1) ... 96s Setting up python3-pluggy (1.6.0-1) ... 96s Setting up python3-legacy-cgi (2.6.3-1) ... 96s Setting up libxslt1.1:amd64 (1.1.43-0.1) ... 96s Setting up python3-requests-futures (1.0.2-1) ... 96s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 96s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 97s Setting up sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 97s Setting up python3.14-minimal (3.14.0-4) ... 98s Setting up python3-asgiref (3.9.1-2) ... 98s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 98s Setting up python3-django (3:5.2.4-1ubuntu2) ... 101s Setting up libpython3.14-stdlib:amd64 (3.14.0-4) ... 101s Setting up python3-pytest (8.3.5-2) ... 101s Setting up python3-pytest-django (4.11.1-2) ... 102s Setting up libjs-sphinxdoc (8.2.3-1ubuntu2) ... 102s Setting up python3-lxml:amd64 (6.0.1-1build1) ... 102s Setting up python3.14 (3.14.0-4) ... 103s Setting up python3-django-cas-server (2.1.0-3) ... 103s Setting up python3-all (3.13.7-2) ... 103s Setting up python3-django-cas-server-doc (2.1.0-3) ... 103s Processing triggers for systemd (257.9-0ubuntu2) ... 104s Processing triggers for man-db (2.13.1-1) ... 104s Processing triggers for libc-bin (2.42-0ubuntu3) ... 105s autopkgtest [12:12:01]: test environment configured for cross building 105s autopkgtest [12:12:01]: test run-test-suite.sh: [----------------------- 106s 106s *************************** 106s *** Testing with python3.14 106s *************************** 106s 106s Content of current working folder: 106s 106s total 20 106s drwxr-xr-x 3 ubuntu ubuntu 4096 Oct 26 12:12 . 106s drwxrwxrwx 5 root root 4096 Oct 26 12:12 .. 106s drwxrwxr-x 9 ubuntu ubuntu 4096 Oct 26 12:11 cas_server 106s -rw-rw-r-- 1 ubuntu ubuntu 140 Aug 18 2024 pytest.ini 106s -rw-rw-r-- 1 ubuntu ubuntu 152 Aug 18 2024 setup.cfg 106s Running tests... 106s 107s ============================= test session starts ============================== 107s platform linux -- Python 3.14.0, pytest-8.3.5, pluggy-1.6.0 -- /usr/bin/python3.14 107s cachedir: .pytest_cache 107s django: version: 5.2.4, settings: cas_server.tests.settings (from ini) 107s rootdir: /tmp/autopkgtest.NLiDjT/autopkgtest_tmp 107s configfile: pytest.ini 107s testpaths: cas_server/tests/ 107s plugins: django-4.11.1, typeguard-4.4.2 107s collecting ... collected 128 items 107s 107s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 108s 2025-10-26 12:12:04,121 WARNING Identity provider suffix exemple.fr not found 108s 2025-10-26 12:12:04,136 INFO Got an invalid ticket ST-x7Q15LiMcILBHP4n7acHUUeFO6pssrfP2aIRuOXhwd6jQ6vV8CW5B0u1pcUa6 from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 108s 2025-10-26 12:12:04,141 INFO Got an invalid ticket ST-9bsIXzTAbnNksghuOK2GPNDZdzzqjx10YnJ1TdHSozVWDDgvLZEf9WEZQLd6q from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 108s PASSED 108s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false PASSED 108s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2025-10-26 12:12:04,180 WARNING CAS_FEDERATE is False, set it to True to use federation 108s 2025-10-26 12:12:04,180 WARNING CAS_FEDERATE is False, set it to True to use federation 108s 2025-10-26 12:12:04,199 INFO Trying to authenticate http://127.0.0.1:8080 again 108s 2025-10-26 12:12:04,205 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 108s 2025-10-26 12:12:04,220 INFO User dédé@example.com successfully authenticated 108s 2025-10-26 12:12:04,234 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 108s 2025-10-26 12:12:04,248 INFO Trying to authenticate http://127.0.0.1:8081 again 108s 2025-10-26 12:12:04,255 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 108s 2025-10-26 12:12:04,269 INFO User dédé@example.org successfully authenticated 108s 2025-10-26 12:12:04,281 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 108s 2025-10-26 12:12:04,299 INFO Trying to authenticate http://127.0.0.1:8082 again 108s 2025-10-26 12:12:04,307 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 108s 2025-10-26 12:12:04,322 INFO User dédé@example.net successfully authenticated 108s 2025-10-26 12:12:04,334 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 108s 2025-10-26 12:12:04,348 INFO Trying to authenticate http://127.0.0.1:8083 again 108s 2025-10-26 12:12:04,356 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 108s 2025-10-26 12:12:04,370 INFO User dédé@example.test successfully authenticated 108s 2025-10-26 12:12:04,381 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 108s 2025-10-26 12:12:04,422 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-wrjEPBkfyKvzyfMwxs5D128MDN95UlCJsf5sU4jehfpb9bFMqxEW6pbc5bvmf 108s 2025-10-26 12:12:04,451 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-pvu1ZLiWLNkgUhYsliVnONWa0RyLXxwBpz9auKk9F1S15pa6hq1d3ga7iFQ3o 108s PASSED 108s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 108s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2025-10-26 12:12:04,493 INFO Trying to authenticate http://127.0.0.1:8080 again 108s 2025-10-26 12:12:04,499 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 108s 2025-10-26 12:12:04,510 INFO User dédé@example.com successfully authenticated 108s 2025-10-26 12:12:04,521 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 108s 2025-10-26 12:12:04,535 INFO Trying to authenticate http://127.0.0.1:8081 again 108s 2025-10-26 12:12:04,542 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 108s 2025-10-26 12:12:04,554 INFO User dédé@example.org successfully authenticated 108s 2025-10-26 12:12:04,565 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 108s 2025-10-26 12:12:04,579 INFO Trying to authenticate http://127.0.0.1:8082 again 108s 2025-10-26 12:12:04,583 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 108s 2025-10-26 12:12:04,594 INFO User dédé@example.net successfully authenticated 108s 2025-10-26 12:12:04,604 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 108s 2025-10-26 12:12:04,619 INFO Trying to authenticate http://127.0.0.1:8083 again 108s 2025-10-26 12:12:04,628 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 108s 2025-10-26 12:12:04,639 INFO User dédé@example.test successfully authenticated 108s 2025-10-26 12:12:04,650 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 108s 2025-10-26 12:12:04,651 INFO logout requested 108s 2025-10-26 12:12:04,653 INFO Logging out user dédé@example.com. 108s 2025-10-26 12:12:04,658 INFO User dédé@example.com logged out 108s 2025-10-26 12:12:04,669 INFO logout requested 108s 2025-10-26 12:12:04,674 INFO logout requested 108s 2025-10-26 12:12:04,677 INFO Logging out user dédé@example.org. 108s 2025-10-26 12:12:04,682 INFO User dédé@example.org logged out 108s 2025-10-26 12:12:04,695 INFO logout requested 108s 2025-10-26 12:12:04,698 INFO logout requested 108s 2025-10-26 12:12:04,701 INFO Logging out user dédé@example.net. 108s 2025-10-26 12:12:04,707 INFO User dédé@example.net logged out 108s 2025-10-26 12:12:04,717 INFO logout requested 108s 2025-10-26 12:12:04,720 INFO logout requested 108s 2025-10-26 12:12:04,722 INFO Logging out user dédé@example.test. 108s 2025-10-26 12:12:04,727 INFO User dédé@example.test logged out 108s 2025-10-26 12:12:04,737 INFO logout requested 108s 2025-10-26 12:12:04,760 INFO Trying to authenticate http://127.0.0.1:8080 again 108s 2025-10-26 12:12:04,768 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 108s 2025-10-26 12:12:04,784 INFO User dédé@example.com successfully authenticated 108s 2025-10-26 12:12:04,799 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 108s 2025-10-26 12:12:04,817 INFO Trying to authenticate http://127.0.0.1:8081 again 108s 2025-10-26 12:12:04,825 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 108s 2025-10-26 12:12:04,840 INFO User dédé@example.org successfully authenticated 108s 2025-10-26 12:12:04,853 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 108s 2025-10-26 12:12:04,868 INFO Trying to authenticate http://127.0.0.1:8082 again 108s 2025-10-26 12:12:04,874 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 108s 2025-10-26 12:12:04,886 INFO User dédé@example.net successfully authenticated 108s 2025-10-26 12:12:04,898 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 108s 2025-10-26 12:12:04,912 INFO Trying to authenticate http://127.0.0.1:8083 again 108s 2025-10-26 12:12:04,921 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 108s 2025-10-26 12:12:04,937 INFO User dédé@example.test successfully authenticated 108s 2025-10-26 12:12:04,951 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 108s 2025-10-26 12:12:04,955 INFO logout requested 108s 2025-10-26 12:12:04,956 INFO Logging out user dédé. 108s 2025-10-26 12:12:04,958 INFO User dédé logged out 108s 2025-10-26 12:12:04,974 INFO logout requested 109s 2025-10-26 12:12:04,976 INFO Logging out user dédé. 109s 2025-10-26 12:12:04,977 INFO User dédé logged out 109s 2025-10-26 12:12:04,993 INFO logout requested 109s 2025-10-26 12:12:04,994 INFO Logging out user dédé. 109s 2025-10-26 12:12:04,996 INFO User dédé logged out 109s 2025-10-26 12:12:05,012 INFO logout requested 109s 2025-10-26 12:12:05,014 INFO Logging out user dédé. 109s 2025-10-26 12:12:05,015 INFO User dédé logged out 109s PASSED 109s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2025-10-26 12:12:05,058 INFO Trying to authenticate http://127.0.0.1:8080 again 109s 2025-10-26 12:12:05,065 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 109s 2025-10-26 12:12:05,082 INFO User dédé@example.com successfully authenticated 109s 2025-10-26 12:12:05,098 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 109s 2025-10-26 12:12:05,119 INFO Trying to authenticate http://127.0.0.1:8081 again 109s 2025-10-26 12:12:05,126 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 109s 2025-10-26 12:12:05,140 INFO User dédé@example.org successfully authenticated 109s 2025-10-26 12:12:05,150 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 109s 2025-10-26 12:12:05,162 INFO Trying to authenticate http://127.0.0.1:8082 again 109s 2025-10-26 12:12:05,169 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 109s 2025-10-26 12:12:05,180 INFO User dédé@example.net successfully authenticated 109s 2025-10-26 12:12:05,190 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 109s 2025-10-26 12:12:05,202 INFO Trying to authenticate http://127.0.0.1:8083 again 109s 2025-10-26 12:12:05,209 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 109s 2025-10-26 12:12:05,220 INFO User dédé@example.test successfully authenticated 109s 2025-10-26 12:12:05,230 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 109s 2025-10-26 12:12:05,231 INFO logout requested 109s 2025-10-26 12:12:05,233 INFO Logging out user dédé@example.com. 109s 2025-10-26 12:12:05,238 INFO User dédé@example.com logged out 109s 2025-10-26 12:12:05,239 INFO logout requested 109s 2025-10-26 12:12:05,241 INFO Logging out user dédé@example.org. 109s 2025-10-26 12:12:05,246 INFO User dédé@example.org logged out 109s 2025-10-26 12:12:05,247 INFO logout requested 109s 2025-10-26 12:12:05,249 INFO Logging out user dédé@example.net. 109s 2025-10-26 12:12:05,253 INFO User dédé@example.net logged out 109s 2025-10-26 12:12:05,254 INFO logout requested 109s 2025-10-26 12:12:05,256 INFO Logging out user dédé@example.test. 109s 2025-10-26 12:12:05,260 INFO User dédé@example.test logged out 109s PASSED 109s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2025-10-26 12:12:05,278 WARNING A login attempt failed 109s PASSED 109s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 109s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2025-10-26 12:12:05,314 INFO Trying to authenticate http://127.0.0.1:8080 again 109s 2025-10-26 12:12:05,320 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 109s 2025-10-26 12:12:05,329 INFO User dédé@example.com successfully authenticated 109s 2025-10-26 12:12:05,339 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 109s 2025-10-26 12:12:05,351 INFO Trying to authenticate http://127.0.0.1:8081 again 109s 2025-10-26 12:12:05,355 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 109s 2025-10-26 12:12:05,364 INFO User dédé@example.org successfully authenticated 109s 2025-10-26 12:12:05,374 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 109s 2025-10-26 12:12:05,385 INFO Trying to authenticate http://127.0.0.1:8082 again 109s 2025-10-26 12:12:05,390 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 109s 2025-10-26 12:12:05,400 INFO User dédé@example.net successfully authenticated 109s 2025-10-26 12:12:05,409 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 109s 2025-10-26 12:12:05,421 INFO Trying to authenticate http://127.0.0.1:8083 again 109s 2025-10-26 12:12:05,426 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 109s 2025-10-26 12:12:05,439 INFO User dédé@example.test successfully authenticated 109s 2025-10-26 12:12:05,450 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 109s PASSED 109s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2025-10-26 12:12:05,467 INFO Trying to authenticate http://127.0.0.1:8080 again 109s 2025-10-26 12:12:05,472 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 109s 2025-10-26 12:12:05,483 INFO User dédé@example.com successfully authenticated 109s 2025-10-26 12:12:05,495 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 109s 2025-10-26 12:12:05,506 INFO Trying to authenticate http://127.0.0.1:8081 again 109s 2025-10-26 12:12:05,513 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 109s 2025-10-26 12:12:05,523 INFO User dédé@example.org successfully authenticated 109s 2025-10-26 12:12:05,534 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 109s 2025-10-26 12:12:05,545 INFO Trying to authenticate http://127.0.0.1:8082 again 109s 2025-10-26 12:12:05,551 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 109s 2025-10-26 12:12:05,560 INFO User dédé@example.net successfully authenticated 109s 2025-10-26 12:12:05,569 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 109s 2025-10-26 12:12:05,581 INFO Trying to authenticate http://127.0.0.1:8083 again 109s 2025-10-26 12:12:05,586 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 109s 2025-10-26 12:12:05,595 INFO User dédé@example.test successfully authenticated 109s 2025-10-26 12:12:05,604 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 109s 2025-10-26 12:12:05,617 INFO Trying to authenticate http://127.0.0.1:8080 again 109s 2025-10-26 12:12:05,622 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 109s 2025-10-26 12:12:05,631 INFO User dédé@example.com successfully authenticated 109s 2025-10-26 12:12:05,640 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 109s 2025-10-26 12:12:05,653 INFO Trying to authenticate http://127.0.0.1:8081 again 109s 2025-10-26 12:12:05,659 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 109s 2025-10-26 12:12:05,669 INFO User dédé@example.org successfully authenticated 109s 2025-10-26 12:12:05,678 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 109s 2025-10-26 12:12:05,691 INFO Trying to authenticate http://127.0.0.1:8082 again 109s 2025-10-26 12:12:05,699 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 109s 2025-10-26 12:12:05,716 INFO User dédé@example.net successfully authenticated 109s 2025-10-26 12:12:05,728 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 109s 2025-10-26 12:12:05,743 INFO Trying to authenticate http://127.0.0.1:8083 again 109s 2025-10-26 12:12:05,751 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 109s 2025-10-26 12:12:05,769 INFO User dédé@example.test successfully authenticated 109s 2025-10-26 12:12:05,779 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 109s 2025-10-26 12:12:05,781 WARNING User already authenticated, dropping federated authentication request 109s 2025-10-26 12:12:05,782 WARNING User already authenticated, dropping federated authentication request 109s 2025-10-26 12:12:05,783 WARNING User already authenticated, dropping federated authentication request 109s PASSED 109s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2025-10-26 12:12:05,784 WARNING User already authenticated, dropping federated authentication request 109s 2025-10-26 12:12:05,801 INFO Trying to authenticate http://127.0.0.1:8080 again 109s 2025-10-26 12:12:05,806 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 109s 2025-10-26 12:12:05,817 INFO User dédé@example.com successfully authenticated 109s 2025-10-26 12:12:05,827 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 109s 2025-10-26 12:12:05,837 INFO Trying to authenticate http://127.0.0.1:8081 again 109s 2025-10-26 12:12:05,843 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 109s 2025-10-26 12:12:05,858 INFO User dédé@example.org successfully authenticated 109s 2025-10-26 12:12:05,867 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 109s 2025-10-26 12:12:05,878 INFO Trying to authenticate http://127.0.0.1:8082 again 109s 2025-10-26 12:12:05,884 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 109s 2025-10-26 12:12:05,893 INFO User dédé@example.net successfully authenticated 109s 2025-10-26 12:12:05,935 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 109s 2025-10-26 12:12:05,945 INFO Trying to authenticate http://127.0.0.1:8083 again 109s 2025-10-26 12:12:05,952 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 109s 2025-10-26 12:12:05,962 INFO User dédé@example.test successfully authenticated 109s 2025-10-26 12:12:05,973 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 109s 2025-10-26 12:12:05,974 INFO logout requested 110s 2025-10-26 12:12:05,976 INFO Logging out user dédé@example.com. 110s 2025-10-26 12:12:05,981 INFO User dédé@example.com logged out 110s 2025-10-26 12:12:05,984 INFO logout requested 110s 2025-10-26 12:12:05,986 INFO Logging out user dédé@example.org. 110s 2025-10-26 12:12:05,990 INFO User dédé@example.org logged out 110s 2025-10-26 12:12:05,993 INFO logout requested 110s 2025-10-26 12:12:05,995 INFO Logging out user dédé@example.net. 110s 2025-10-26 12:12:05,999 INFO User dédé@example.net logged out 110s 2025-10-26 12:12:06,002 INFO logout requested 110s 2025-10-26 12:12:06,004 INFO Logging out user dédé@example.test. 110s 2025-10-26 12:12:06,008 INFO User dédé@example.test logged out 110s PASSED 110s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2025-10-26 12:12:06,026 INFO Trying to authenticate http://127.0.0.1:8080 again 110s 2025-10-26 12:12:06,032 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 110s 2025-10-26 12:12:06,041 INFO User dédé@example.com successfully authenticated 110s 2025-10-26 12:12:06,052 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 110s 2025-10-26 12:12:06,063 INFO Trying to authenticate http://127.0.0.1:8081 again 110s 2025-10-26 12:12:06,069 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 110s 2025-10-26 12:12:06,080 INFO User dédé@example.org successfully authenticated 110s 2025-10-26 12:12:06,089 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 110s 2025-10-26 12:12:06,100 INFO Trying to authenticate http://127.0.0.1:8082 again 110s 2025-10-26 12:12:06,106 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 110s 2025-10-26 12:12:06,117 INFO User dédé@example.net successfully authenticated 110s 2025-10-26 12:12:06,127 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 110s 2025-10-26 12:12:06,139 INFO Trying to authenticate http://127.0.0.1:8083 again 110s 2025-10-26 12:12:06,146 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 110s 2025-10-26 12:12:06,156 INFO User dédé@example.test successfully authenticated 110s 2025-10-26 12:12:06,181 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 110s 2025-10-26 12:12:06,188 INFO Trying to authenticate http://127.0.0.1:8080 again 110s 2025-10-26 12:12:06,193 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 110s 2025-10-26 12:12:06,205 INFO User dédé@example.com successfully authenticated 110s 2025-10-26 12:12:06,209 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 110s 2025-10-26 12:12:06,215 INFO Trying to authenticate http://127.0.0.1:8081 again 110s 2025-10-26 12:12:06,221 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 110s 2025-10-26 12:12:06,231 INFO User dédé@example.org successfully authenticated 110s 2025-10-26 12:12:06,236 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 110s 2025-10-26 12:12:06,243 INFO Trying to authenticate http://127.0.0.1:8082 again 110s 2025-10-26 12:12:06,249 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 110s 2025-10-26 12:12:06,261 INFO User dédé@example.net successfully authenticated 110s 2025-10-26 12:12:06,267 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 110s 2025-10-26 12:12:06,275 INFO Trying to authenticate http://127.0.0.1:8083 again 110s 2025-10-26 12:12:06,283 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 110s 2025-10-26 12:12:06,299 INFO User dédé@example.test successfully authenticated 110s 2025-10-26 12:12:06,306 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 110s PASSED 110s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 110s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 110s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 110s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2025-10-26 12:12:06,375 INFO User test successfully authenticated 110s PASSED 110s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2025-10-26 12:12:06,404 INFO User test successfully authenticated 110s PASSED 110s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2025-10-26 12:12:06,431 INFO User test successfully authenticated 110s 2025-10-26 12:12:06,447 INFO User test successfully authenticated 110s 2025-10-26 12:12:06,462 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 110s 2025-10-26 12:12:06,467 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')) 110s PASSED 110s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2025-10-26 12:12:06,485 INFO User test successfully authenticated 110s PASSED 110s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2025-10-26 12:12:06,513 INFO User test successfully authenticated 110s PASSED 110s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2025-10-26 12:12:06,547 INFO User test successfully authenticated 110s 2025-10-26 12:12:06,567 INFO Sending SLO requests to service http://127.0.0.1:35457 for user test 110s PASSED 110s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2025-10-26 12:12:06,571 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 110s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 110s 2025-10-26 12:12:06,590 INFO User test successfully authenticated 110s PASSED 110s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 110s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 110s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 110s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 110s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 110s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 110s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 110s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 110s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 110s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 110s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 110s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 110s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 110s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 110s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 110s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 110s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 110s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 110s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 111s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 111s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 111s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 111s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2025-10-26 12:12:07,541 INFO User test successfully authenticated 111s 2025-10-26 12:12:07,548 WARNING Service https://www.example.org not allowed. 111s PASSED 111s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success PASSED 111s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt 2025-10-26 12:12:07,564 INFO User test successfully authenticated 111s 2025-10-26 12:12:07,571 INFO Service ticket created for service https://www.example.com by user test. 111s 2025-10-26 12:12:07,585 INFO User test successfully authenticated 111s 2025-10-26 12:12:07,593 INFO Service ticket created for service https://www.example.com by user test. 111s PASSED 111s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn 2025-10-26 12:12:07,607 INFO User test successfully authenticated 111s PASSED 111s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged PASSED 111s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2025-10-26 12:12:07,627 INFO User test successfully authenticated 111s 2025-10-26 12:12:07,645 INFO User test successfully authenticated 111s 2025-10-26 12:12:07,652 WARNING User test seems authenticated but is not found in the database. 111s 2025-10-26 12:12:07,652 INFO Logging out user test. 111s 2025-10-26 12:12:07,653 INFO User test logged out 111s PASSED 111s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 111s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2025-10-26 12:12:07,677 INFO User test successfully authenticated 111s 2025-10-26 12:12:07,684 WARNING Username test not allowed on service restrict_user_fail 111s PASSED 111s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2025-10-26 12:12:07,700 WARNING A login attempt failed 111s 2025-10-26 12:12:07,716 WARNING A login attempt failed 111s 2025-10-26 12:12:07,730 WARNING A login attempt failed 111s PASSED 111s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2025-10-26 12:12:07,753 WARNING Received an invalid login ticket 111s PASSED 111s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2025-10-26 12:12:07,774 WARNING A login attempt failed 111s PASSED 111s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2025-10-26 12:12:07,796 INFO User test successfully authenticated 111s PASSED 111s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2025-10-26 12:12:07,813 INFO User test successfully authenticated 111s PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_renew 2025-10-26 12:12:08,822 INFO User test successfully authenticated 112s 2025-10-26 12:12:08,836 INFO User test successfully authenticated 112s 2025-10-26 12:12:08,841 INFO Service ticket created for service https://www.example.com by user test. 112s PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2025-10-26 12:12:08,854 INFO User test successfully authenticated 112s 2025-10-26 12:12:08,865 INFO User test successfully authenticated 112s PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2025-10-26 12:12:08,872 INFO Service ticket created for service https://www.example.com by user test. 112s 2025-10-26 12:12:08,888 INFO User test successfully authenticated 112s 2025-10-26 12:12:08,895 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 112s 2025-10-26 12:12:08,899 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 112s 2025-10-26 12:12:08,905 INFO Service ticket created for service https://filter_success.example.com by user test. 112s PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2025-10-26 12:12:08,918 INFO User test successfully authenticated 112s 2025-10-26 12:12:08,926 WARNING Username test not allowed on service restrict_user_fail 112s 2025-10-26 12:12:08,932 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 112s PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2025-10-26 12:12:08,945 INFO User test successfully authenticated 112s 2025-10-26 12:12:08,951 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 112s 2025-10-26 12:12:08,956 INFO Service ticket created for service https://field_needed_success.example.com by user test. 112s PASSED 112s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2025-10-26 12:12:08,969 INFO User test successfully authenticated 113s 2025-10-26 12:12:08,975 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2025-10-26 12:12:08,994 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,001 WARNING User test seems authenticated but is not found in the database. 113s 2025-10-26 12:12:09,001 INFO Logging out user test. 113s 2025-10-26 12:12:09,002 INFO User test logged out 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2025-10-26 12:12:09,041 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,048 INFO Service ticket created for service https://www.example.com/é by user test. 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2025-10-26 12:12:09,063 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,071 INFO Service ticket created for service https://www.example.com by user test. 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2025-10-26 12:12:09,090 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,108 WARNING Received a login attempt for an already-active user 113s 2025-10-26 12:12:09,113 INFO Service ticket created for service https://www.example.com by user test. 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2025-10-26 12:12:09,132 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,138 WARNING Service https://www.example.org not allowed. 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2025-10-26 12:12:09,146 WARNING Service https://www.example.net not allowed. 113s PASSED 113s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2025-10-26 12:12:09,163 WARNING Service https://www.example.net not allowed. 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2025-10-26 12:12:09,177 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,181 INFO logout requested 113s 2025-10-26 12:12:09,181 INFO Logging out user test. 113s 2025-10-26 12:12:09,185 INFO User test logged out 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2025-10-26 12:12:09,214 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,224 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,234 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,238 INFO logout requested 113s 2025-10-26 12:12:09,238 INFO Logging out user test from all sessions. 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2025-10-26 12:12:09,249 INFO User test logged out 113s 2025-10-26 12:12:09,261 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,265 INFO logout requested 113s 2025-10-26 12:12:09,265 INFO Logging out user test. 113s PASSED2025-10-26 12:12:09,269 INFO User test logged out 113s 113s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2025-10-26 12:12:09,273 INFO logout requested 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2025-10-26 12:12:09,284 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,296 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,300 INFO logout requested 113s 2025-10-26 12:12:09,300 INFO Logging out user test from all sessions. 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2025-10-26 12:12:09,308 INFO User test logged out 113s 2025-10-26 12:12:09,321 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,329 INFO Service ticket created for service http://127.0.0.1:42097 by user test. 113s 2025-10-26 12:12:09,333 INFO Validate: Service ticket ST-XBba0GgpFzHEGMdlCk5CsVr3eTlpmo7ZffelMpXuWe0lBfuZpxaBH6ZPhVwxi validated, user test authenticated on service http://127.0.0.1:42097 113s 2025-10-26 12:12:09,334 INFO logout requested 113s 2025-10-26 12:12:09,335 INFO Logging out user test. 113s 2025-10-26 12:12:09,338 INFO Sending SLO requests to service http://127.0.0.1:42097 for user test 113s 2025-10-26 12:12:09,342 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 113s 2025-10-26 12:12:09,344 INFO User test logged out 113s 2025-10-26 12:12:09,355 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,362 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 113s 2025-10-26 12:12:09,366 INFO Validate: Service ticket ST-X0syFfSKr5s6jUsCDHA0eUjLGjeLrHNkbgn8LZnWxrqJdjg7C6aaQ82zZhvzC validated, user test authenticated on service http://127.0.0.1:45678 113s 2025-10-26 12:12:09,367 INFO logout requested 113s 2025-10-26 12:12:09,368 INFO Logging out user test. 113s 2025-10-26 12:12:09,370 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 113s 2025-10-26 12:12:09,374 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 113s 2025-10-26 12:12:09,376 INFO User test logged out 113s 2025-10-26 12:12:09,385 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,391 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 113s 2025-10-26 12:12:09,396 INFO Validate: Service ticket ST-381rp9QaoUJzAihv1k6g68MD6Zz8Hmkj4X5QvfAfHTOvqp9LEhD0ekySBNvfa validated, user test authenticated on service http://127.0.0.1:45678 113s 2025-10-26 12:12:09,397 INFO logout requested 113s 2025-10-26 12:12:09,398 INFO Logging out user test. 113s 2025-10-26 12:12:09,401 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2025-10-26 12:12:09,404 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=33357): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 113s 2025-10-26 12:12:09,405 INFO User test logged out 113s 2025-10-26 12:12:09,415 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,425 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,433 INFO logout requested 113s 2025-10-26 12:12:09,433 INFO Logging out user test. 113s 2025-10-26 12:12:09,437 INFO User test logged out 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2025-10-26 12:12:09,453 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,457 INFO logout requested 113s 2025-10-26 12:12:09,458 INFO Logging out user test. 113s 2025-10-26 12:12:09,462 INFO User test logged out 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2025-10-26 12:12:09,478 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,482 INFO logout requested 113s 2025-10-26 12:12:09,482 INFO Logging out user test. 113s 2025-10-26 12:12:09,487 INFO User test logged out 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2025-10-26 12:12:09,503 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,507 INFO logout requested 113s 2025-10-26 12:12:09,507 INFO Logging out user test. 113s PASSED 113s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2025-10-26 12:12:09,512 INFO User test logged out 113s 2025-10-26 12:12:09,523 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,527 INFO logout requested 113s 2025-10-26 12:12:09,528 INFO Logging out user test. 113s 2025-10-26 12:12:09,532 INFO User test logged out 113s 2025-10-26 12:12:09,539 INFO logout requested 113s PASSED 113s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 113s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 113s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice PASSED 113s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings 2025-10-26 12:12:09,559 WARNING Service https://www.example.org not allowed. 113s PASSED 113s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 113s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2025-10-26 12:12:09,577 INFO User test successfully authenticated 113s PASSED 113s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 113s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2025-10-26 12:12:09,606 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,615 INFO Service ticket created for service https://www.example.com by user test. 113s 2025-10-26 12:12:09,618 WARNING Validate: service or ticket missing 113s 2025-10-26 12:12:09,618 WARNING Validate: service or ticket missing 113s PASSED 113s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2025-10-26 12:12:09,631 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,636 INFO Service ticket created for service https://www.example.com by user test. 113s 2025-10-26 12:12:09,640 INFO Validate: Service ticket ST-M1GHZtsP9kBYUd6GNBt1cllMY8BY3kfIfFvQGqQfFdIPbQ8E24pYuEyrG4lVW validated, user test authenticated on service https://www.example.com 113s 2025-10-26 12:12:09,651 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,656 INFO Service ticket created for service https://www.example.com by user test. 113s PASSED 113s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2025-10-26 12:12:09,659 INFO Validate: Service ticket ST-KrOAlrV4X6WN41QPG6wpk2UCOdKfCMEvl69cLC7F6XyMMjt5kDQMNIKpaduKa validated, user test authenticated on service https://www.example.com 113s 2025-10-26 12:12:09,664 INFO Service ticket created for service https://www.example.com by user test. 113s 2025-10-26 12:12:09,666 WARNING Validate: Service ticket ST-68KU5gU0G40Wb8mDSpom757P9Jm4ys42vkRRRegUTLskS25CVp6tmOAoffbQW not found or already validated, auth to https://www.example.com failed 113s 2025-10-26 12:12:09,677 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,684 INFO Service ticket created for service https://user_field.example.com by user test. 113s 2025-10-26 12:12:09,690 INFO Validate: Service ticket ST-Br0oi7PuxTlAtM6QkK31RO9EFpmfrk0UYy4UTXDsSuw8RWB34PkzLaCaJre0g validated, user test authenticated on service https://user_field.example.com 113s 2025-10-26 12:12:09,704 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,715 INFO Service ticket created for service https://user_field_alt.example.com by user test. 113s 2025-10-26 12:12:09,721 INFO Validate: Service ticket ST-YWvsmamG1mN8KPxjxgnvXLJhr2hw1WVRwHfeJhsZGBlAIgxIymxIHyXLRSJhG validated, user test authenticated on service https://user_field_alt.example.com 113s PASSED 113s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2025-10-26 12:12:09,739 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,752 INFO Service ticket created for service https://www.example.com by user test. 113s 2025-10-26 12:12:09,756 WARNING Validate: Service ticket ST-TvC3K0F2NvTycZ1N4xOdkjm1debG0wEFVqFuxiqgNQHbiyeDluh8NwJ8JVizH not found or already validated, auth to https://www.example.org failed 113s PASSED 113s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2025-10-26 12:12:09,772 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,783 INFO Service ticket created for service https://www.example.com by user test. 113s 2025-10-26 12:12:09,787 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 113s PASSED 113s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2025-10-26 12:12:09,803 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,816 INFO Service ticket created for service https://www.example.com by user test. 113s 2025-10-26 12:12:09,821 INFO Validate: Service ticket ST-Zr7wmIkAKDOO79CT8jGTvEmVcYKvp2c5dCMnFLduaW71Py0Jr5bqiolGT3nkn validated, user test authenticated on service https://www.example.com 113s PASSED 113s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2025-10-26 12:12:09,842 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,853 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 113s 2025-10-26 12:12:09,857 WARNING ValidateService: missing ticket or service 113s 2025-10-26 12:12:09,858 WARNING ValidateService: missing ticket or service 113s PASSED 113s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2025-10-26 12:12:09,877 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,890 INFO Service ticket created for service http://127.0.0.1:41877 by user test. 113s 2025-10-26 12:12:09,900 INFO ValidateService: ticket ST-jtEfsi0ww143WzkWazxuBoPEsC0OhwY6QlwsH3vEMHUelYJjoK0v83iX9fZ0Y validated for user test on service http://127.0.0.1:41877. Proxy Granting Ticket transmited to http://127.0.0.1:41877. 113s 2025-10-26 12:12:09,909 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 113s 2025-10-26 12:12:09,915 INFO ValidateService: ticket PT-RiQFs6wxGV8iHwQYfo8LlfIplqR8K2EZxWFfhgVFsa3J7Kw94XCbgbXx87DmM validated for user test on service http://127.0.0.1:45678. 113s 2025-10-26 12:12:09,930 INFO User test successfully authenticated 113s 2025-10-26 12:12:09,942 INFO Service ticket created for service http://127.0.0.1:39795 by user test. 113s 2025-10-26 12:12:09,956 INFO ValidateService: ticket ST-vRM6jQJR7IJpc9GuHSikUNedcgEzNk1PV4wIG0vv1hYoR21zm7FRFhFM6zZ8F validated for user test on service http://127.0.0.1:39795. Proxy Granting Ticket transmited to http://127.0.0.1:39795. 113s 2025-10-26 12:12:09,965 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 113s 2025-10-26 12:12:09,968 WARNING ValidateService: validation error: INVALID_TICKET PT-DWJAE6gSALRBtd0Ds6rf7p4uK5I6eU4Di8VA2WsCL7gk8b1Hm5tc69AA2a9s6 113s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2025-10-26 12:12:09,988 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,000 INFO Service ticket created for service https://replace_attribute.example.com by user test. 114s 2025-10-26 12:12:10,007 INFO ValidateService: ticket ST-sl2y2pSEhcIIAJCCdrqt1eYbGWBtX60wwrXgtoITn0tnGuOp597qvtJnxnjqk validated for user test on service https://replace_attribute.example.com. 114s 2025-10-26 12:12:10,019 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,029 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2025-10-26 12:12:10,034 INFO ValidateService: ticket ST-qBscvd6ld7u9vV3VkT6wBdaPGs3CVAUYI527ajegPeo6EM1j5BxFxFwKsjXgZ validated for user test on service https://replace_attribute_list.example.com. 114s 2025-10-26 12:12:10,054 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,064 INFO Service ticket created for service http://127.0.0.1:34961 by user test. 114s 2025-10-26 12:12:10,075 INFO ValidateService: ticket ST-KJQxLZbTANfbkan6LkJ7V1vmqHcil7jR9B9jKPoBofSZqB7CGABwXRgEkIRBY validated for user test on service http://127.0.0.1:34961. Proxy Granting Ticket transmited to http://127.0.0.1:34961. 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2025-10-26 12:12:10,095 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,107 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-26 12:12:10,114 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 114s 2025-10-26 12:12:10,127 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,137 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-26 12:12:10,142 WARNING Service https://www.example.org not allowed. 114s 2025-10-26 12:12:10,143 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2025-10-26 12:12:10,158 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,166 INFO Service ticket created for service https://127.0.0.1:45651 by user test. 114s 2025-10-26 12:12:10,174 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=45651): Max retries exceeded with url: /?pgtId=PGT-QgvDiIiQtlTsLF9ELzg9ix56Mtcv6MCFRmlbHqa8brLbS77i9uake7ADo4Ph&pgtIou=PGTIOU-9lovR3DbdzIGRmy1iB3BDqI3nptBfXF0mC72BTQVwiFWvGdGQ6zgeOtAu (Caused by SSLError(SSLError(1, '[SSL: RECORD_LAYER_FAILURE] record layer failure (_ssl.c:1077)'))) 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2025-10-26 12:12:10,190 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,194 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-26 12:12:10,198 INFO ValidateService: ticket ST-49ihg25gbjoF3GpHTJTPrjMW4uyhm5tPIYnHuEd8q7cetlz7YoSy7rpjF8mfA validated for user test on service http://127.0.0.1:45678. 114s 2025-10-26 12:12:10,207 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,213 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-26 12:12:10,218 INFO ValidateService: ticket ST-1WNB4wE1cKEs52Zb3OutpN635seQe1rCFKFz219lz42HYK3a6lpylQDAPUSn3 validated for user test on service http://127.0.0.1:45678. 114s 2025-10-26 12:12:10,223 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-26 12:12:10,225 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2025-10-26 12:12:10,239 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,247 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-26 12:12:10,250 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2025-10-26 12:12:10,263 INFO User test successfully authenticated 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2025-10-26 12:12:10,271 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-26 12:12:10,273 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 114s 2025-10-26 12:12:10,287 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,295 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-26 12:12:10,298 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2025-10-26 12:12:10,311 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,319 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 114s 2025-10-26 12:12:10,323 INFO ValidateService: ticket ST-dTsY11Z7MDLnPF2Px7eIzQWYBjOKXFHymtHAnL0u6fCbQabYckU6a5DPCHcK5 validated for user test on service http://127.0.0.1:45678. 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2025-10-26 12:12:10,339 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,348 INFO Service ticket created for service https://one_attribute.example.com by user test. 114s 2025-10-26 12:12:10,353 INFO ValidateService: ticket ST-RwLEfaM091kTlaxzbwhDIn9OOigLtQkl5wijj1h0sugDCwQdMuTYGUXiVjTj3 validated for user test on service https://one_attribute.example.com. 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2025-10-26 12:12:10,369 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,377 INFO Service ticket created for service http://127.0.0.1:40649 by user test. 114s PASSED 114s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2025-10-26 12:12:10,385 INFO ValidateService: ticket ST-MTNZ8vFnzz10TFE3gZC3jVemVE3i2i48RTw2au0WxWSfFxdXojfd7K0pXGKR8 validated for user test on service http://127.0.0.1:40649. Proxy Granting Ticket transmited to http://127.0.0.1:40649. 114s 2025-10-26 12:12:10,401 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,410 INFO Service ticket created for service https://user_field.example.com by user test. 114s 2025-10-26 12:12:10,414 INFO ValidateService: ticket ST-ftR7CdXrqVdl5CSeBANLpG4DUX1R1REGt5mhMnS41PFbCWklqb7Ji6iCPn0r2 validated for user test on service https://user_field.example.com. 114s 2025-10-26 12:12:10,424 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,440 INFO Service ticket created for service https://user_field_alt.example.com by user test. 114s 2025-10-26 12:12:10,446 INFO ValidateService: ticket ST-HqmHOXpweVxP0m9Sv5yQD8NMtvDHCGH4z8fHRdENN2h0mtfbkJaA0zT4hfYG5 validated for user test on service https://user_field_alt.example.com. 114s PASSED 114s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2025-10-26 12:12:10,467 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,479 INFO Service ticket created for service http://127.0.0.1:34675 by user test. 114s PASSED 114s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2025-10-26 12:12:10,488 INFO ValidateService: ticket ST-I1Ogf3B7ioVCuaB1HFYITkAyzxt58V2WM3NrkEDZlsnLvhQeJQSUsSp59AOjj validated for user test on service http://127.0.0.1:34675. Proxy Granting Ticket transmited to http://127.0.0.1:34675. 114s 2025-10-26 12:12:10,490 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 114s 2025-10-26 12:12:10,491 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 114s 2025-10-26 12:12:10,509 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,519 INFO Service ticket created for service http://127.0.0.1:41087 by user test. 114s 2025-10-26 12:12:10,527 INFO ValidateService: ticket ST-eaCxOnWQ8ZjreCCnbGcMBhP3gIpBxywWaEIdLA21YSnVn9Toi6Qioi8yiny8r validated for user test on service http://127.0.0.1:41087. Proxy Granting Ticket transmited to http://127.0.0.1:41087. 114s 2025-10-26 12:12:10,532 WARNING Username test not allowed on service restrict_user_fail 114s 2025-10-26 12:12:10,533 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 114s 2025-10-26 12:12:10,536 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 114s 2025-10-26 12:12:10,537 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 114s 2025-10-26 12:12:10,542 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 114s 2025-10-26 12:12:10,542 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 114s PASSED 114s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2025-10-26 12:12:10,560 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,571 INFO Service ticket created for service http://127.0.0.1:46293 by user test. 114s 2025-10-26 12:12:10,580 INFO ValidateService: ticket ST-V16DggFuru2udk6kyMpCbjXPwouZaoLY6bBMaAyrB8MePcgD1qeuK2t7KdYaX validated for user test on service http://127.0.0.1:46293. Proxy Granting Ticket transmited to http://127.0.0.1:46293. 114s 2025-10-26 12:12:10,583 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 114s PASSED 114s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2025-10-26 12:12:10,601 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,612 INFO Service ticket created for service http://127.0.0.1:40391 by user test. 114s 2025-10-26 12:12:10,619 INFO ValidateService: ticket ST-zabzOmizP5DtCslM5gqS6i37KmI5aAc8GkkmWobJ2QjgO3tQYcBTJjV8KR8GP validated for user test on service http://127.0.0.1:40391. Proxy Granting Ticket transmited to http://127.0.0.1:40391. 114s 2025-10-26 12:12:10,622 WARNING Service https://www.example.org not allowed. 114s 2025-10-26 12:12:10,622 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 114s 2025-10-26 12:12:10,625 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:40391 does not allow proxy tickets 114s PASSED 114s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2025-10-26 12:12:10,641 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,650 INFO Service ticket created for service http://127.0.0.1:45753 by user test. 114s 2025-10-26 12:12:10,658 INFO ValidateService: ticket ST-LXQMcPIqhMTkNykhF3krs7bH5w9TbgrCZaScVJ7QDfe0RqinqT9TRIdL1JU8Q validated for user test on service http://127.0.0.1:45753. Proxy Granting Ticket transmited to http://127.0.0.1:45753. 114s 2025-10-26 12:12:10,664 INFO Proxy ticket created for user test on service https://www.example.com. 114s PASSED2025-10-26 12:12:10,668 INFO ValidateService: ticket PT-MR0UlI99UYLRPzojw9FRfYsReZyxDpjAqa9QoofiMXd9u4XC7lZCKfpl8xUCR validated for user test on service https://www.example.com. 114s 114s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2025-10-26 12:12:10,686 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,709 INFO Service ticket created for service https://www.example.com by user test. 114s 2025-10-26 12:12:10,714 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 114s PASSED 114s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2025-10-26 12:12:10,723 WARNING SamlValidate: validation error: AuthnFailed ticket ST-pIIp8OPZKGA8B2yLFrvOJpvrXGdCw3pGOwD0iW9dZHFb0aD6cZEQzRc3ryePp not found 114s 2025-10-26 12:12:10,725 WARNING SamlValidate: validation error: AuthnFailed ticket PT-ahgnSLrnNwGX78CHJUxvduNVeeVMk9ZkPZsfJGCNsflZncg8cu4ag1uNZ8bZS not found 114s PASSED 114s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix 2025-10-26 12:12:10,732 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 114s PASSED 114s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml 2025-10-26 12:12:10,739 WARNING SamlValidate: validation error: VersionMismatch 114s PASSED 114s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2025-10-26 12:12:10,754 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,764 INFO Service ticket created for service https://www.example.com by user test. 114s 2025-10-26 12:12:10,775 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,785 INFO Service ticket created for service http://127.0.0.1:32821 by user test. 114s 2025-10-26 12:12:10,794 INFO ValidateService: ticket ST-k7mSZaO0hMRLKR4Nu8zWhGIVgkjQmgeqtnixE9D2iEpcpmty0m3y4gQcJj39O validated for user test on service http://127.0.0.1:32821. Proxy Granting Ticket transmited to http://127.0.0.1:32821. 114s 2025-10-26 12:12:10,801 INFO Proxy ticket created for user test on service https://www.example.com. 114s 2025-10-26 12:12:10,805 INFO SamlValidate: ticket ST-P0CzWtqyfQse9uT3cy4lCUqLH8c73q4qCF4xOhDMkQ3fWQEQOr5CGMLjiyXuJ validated for user test on service https://www.example.com. 114s 2025-10-26 12:12:10,810 INFO SamlValidate: ticket PT-XWh6haBMemLJzyGI9jDcv52ZRxV5vY3qGRXioTE1DIFNThLHAZnUWgQtp4rY0 validated for user test on service https://www.example.com. 114s PASSED 114s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2025-10-26 12:12:10,825 INFO User test successfully authenticated 114s 2025-10-26 12:12:10,834 INFO Service ticket created for service https://field_needed_success.example.com by user test. 114s 2025-10-26 12:12:10,839 INFO SamlValidate: ticket ST-5B1MufgheUvstnyrxvnDTVSsYZCpyVTI3Ab2JULDZci7A2L08r1qkEsQlzlEj validated for user test on service https://field_needed_success.example.com. 114s 2025-10-26 12:12:10,847 INFO User test successfully authenticated 114s PASSED2025-10-26 12:12:10,856 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 114s 2025-10-26 12:12:10,861 INFO SamlValidate: ticket ST-R9ScMI2125TXFF9R2DK4H5vtXm4i5I4hTNOZwETB9IsagPvJ3jdzTPiWkUqYo validated for user test on service https://field_needed_success_alt.example.com. 114s Destroying test database for alias 'default'... 114s 114s 114s =============================== warnings summary =============================== 114s cas_server/tests/test_federate.py: 8 warnings 114s cas_server/tests/test_view.py: 3 warnings 114s /tmp/autopkgtest.NLiDjT/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). 114s datetime.utcnow() + timedelta(seconds=max_age), 114s 114s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 114s /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') 114s return self.run(*args, **kwds) 114s 114s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 114s ======================= 128 passed, 12 warnings in 7.65s ======================= 115s 115s *************************** 115s *** Testing with python3.13 115s *************************** 115s 115s Content of current working folder: 115s 115s total 20 115s drwxr-xr-x 3 ubuntu ubuntu 4096 Oct 26 12:12 . 115s drwxrwxrwx 5 root root 4096 Oct 26 12:12 .. 115s drwxrwxr-x 10 ubuntu ubuntu 4096 Oct 26 12:12 cas_server 115s -rw-rw-r-- 1 ubuntu ubuntu 140 Aug 18 2024 pytest.ini 115s -rw-rw-r-- 1 ubuntu ubuntu 152 Aug 18 2024 setup.cfg 115s Running tests... 115s 115s ============================= test session starts ============================== 115s platform linux -- Python 3.13.9, pytest-8.3.5, pluggy-1.6.0 -- /usr/bin/python3.13 115s cachedir: .pytest_cache 115s django: version: 5.2.4, settings: cas_server.tests.settings (from ini) 115s rootdir: /tmp/autopkgtest.NLiDjT/autopkgtest_tmp 115s configfile: pytest.ini 115s testpaths: cas_server/tests/ 115s plugins: django-4.11.1, typeguard-4.4.2 115s collecting ... collected 128 items 115s 115s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 116s 2025-10-26 12:12:12,531 WARNING Identity provider suffix exemple.fr not found 116s 2025-10-26 12:12:12,549 INFO Got an invalid ticket ST-sjaWdYWOssDHDKZhHz83pse6nEBY93H8tUg9MDIG6YfCUS6itWKPZaekRxaRp from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 116s 2025-10-26 12:12:12,553 INFO Got an invalid ticket ST-N7E1Hdt2n1hFIpkU3FJp33vZcxpZRwDrHOaD3i5YPNF8HmdXUOuBwZ1b3VAbB from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 116s PASSED 116s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2025-10-26 12:12:12,589 WARNING CAS_FEDERATE is False, set it to True to use federation 116s PASSED 116s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2025-10-26 12:12:12,590 WARNING CAS_FEDERATE is False, set it to True to use federation 116s 2025-10-26 12:12:12,610 INFO Trying to authenticate http://127.0.0.1:8080 again 116s 2025-10-26 12:12:12,615 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 116s 2025-10-26 12:12:12,629 INFO User dédé@example.com successfully authenticated 116s 2025-10-26 12:12:12,641 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 116s 2025-10-26 12:12:12,652 INFO Trying to authenticate http://127.0.0.1:8081 again 116s 2025-10-26 12:12:12,660 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 116s 2025-10-26 12:12:12,673 INFO User dédé@example.org successfully authenticated 116s 2025-10-26 12:12:12,685 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 116s 2025-10-26 12:12:12,699 INFO Trying to authenticate http://127.0.0.1:8082 again 116s 2025-10-26 12:12:12,706 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 116s 2025-10-26 12:12:12,718 INFO User dédé@example.net successfully authenticated 116s 2025-10-26 12:12:12,730 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 116s 2025-10-26 12:12:12,745 INFO Trying to authenticate http://127.0.0.1:8083 again 116s 2025-10-26 12:12:12,754 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 116s 2025-10-26 12:12:12,767 INFO User dédé@example.test successfully authenticated 116s 2025-10-26 12:12:12,779 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 116s 2025-10-26 12:12:12,826 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-2rkU1s7Hzq8ZQnWgQrxu7CiJLgTUlkiOcYzFvtwvQfBUhrvroqGfoX1DOyozb 116s 2025-10-26 12:12:12,858 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-ReEjouunJkkK6NXoTXxo2cJVImSUoUc3c1666j29pTCZv3dr3vvNKrFyWaV2c 116s PASSED 116s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 116s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2025-10-26 12:12:12,905 INFO Trying to authenticate http://127.0.0.1:8080 again 116s 2025-10-26 12:12:12,910 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 116s 2025-10-26 12:12:12,921 INFO User dédé@example.com successfully authenticated 116s 2025-10-26 12:12:12,933 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 116s 2025-10-26 12:12:12,949 INFO Trying to authenticate http://127.0.0.1:8081 again 116s 2025-10-26 12:12:12,954 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 116s 2025-10-26 12:12:12,965 INFO User dédé@example.org successfully authenticated 117s 2025-10-26 12:12:12,977 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 117s 2025-10-26 12:12:12,989 INFO Trying to authenticate http://127.0.0.1:8082 again 117s 2025-10-26 12:12:12,994 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 117s 2025-10-26 12:12:13,004 INFO User dédé@example.net successfully authenticated 117s 2025-10-26 12:12:13,014 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 117s 2025-10-26 12:12:13,025 INFO Trying to authenticate http://127.0.0.1:8083 again 117s 2025-10-26 12:12:13,032 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 117s 2025-10-26 12:12:13,043 INFO User dédé@example.test successfully authenticated 117s 2025-10-26 12:12:13,053 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 117s 2025-10-26 12:12:13,055 INFO logout requested 117s 2025-10-26 12:12:13,057 INFO Logging out user dédé@example.com. 117s 2025-10-26 12:12:13,062 INFO User dédé@example.com logged out 117s 2025-10-26 12:12:13,072 INFO logout requested 117s 2025-10-26 12:12:13,075 INFO logout requested 117s 2025-10-26 12:12:13,078 INFO Logging out user dédé@example.org. 117s 2025-10-26 12:12:13,083 INFO User dédé@example.org logged out 117s 2025-10-26 12:12:13,092 INFO logout requested 117s 2025-10-26 12:12:13,094 INFO logout requested 117s 2025-10-26 12:12:13,096 INFO Logging out user dédé@example.net. 117s 2025-10-26 12:12:13,101 INFO User dédé@example.net logged out 117s 2025-10-26 12:12:13,110 INFO logout requested 117s 2025-10-26 12:12:13,112 INFO logout requested 117s 2025-10-26 12:12:13,114 INFO Logging out user dédé@example.test. 117s 2025-10-26 12:12:13,119 INFO User dédé@example.test logged out 117s 2025-10-26 12:12:13,128 INFO logout requested 117s 2025-10-26 12:12:13,141 INFO Trying to authenticate http://127.0.0.1:8080 again 117s 2025-10-26 12:12:13,146 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 117s 2025-10-26 12:12:13,156 INFO User dédé@example.com successfully authenticated 117s 2025-10-26 12:12:13,166 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 117s 2025-10-26 12:12:13,176 INFO Trying to authenticate http://127.0.0.1:8081 again 117s 2025-10-26 12:12:13,184 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 117s 2025-10-26 12:12:13,200 INFO User dédé@example.org successfully authenticated 117s 2025-10-26 12:12:13,213 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 117s 2025-10-26 12:12:13,229 INFO Trying to authenticate http://127.0.0.1:8082 again 117s 2025-10-26 12:12:13,236 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 117s 2025-10-26 12:12:13,248 INFO User dédé@example.net successfully authenticated 117s 2025-10-26 12:12:13,262 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 117s 2025-10-26 12:12:13,279 INFO Trying to authenticate http://127.0.0.1:8083 again 117s 2025-10-26 12:12:13,286 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 117s 2025-10-26 12:12:13,301 INFO User dédé@example.test successfully authenticated 117s 2025-10-26 12:12:13,315 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 117s 2025-10-26 12:12:13,318 INFO logout requested 117s 2025-10-26 12:12:13,319 INFO Logging out user dédé. 117s 2025-10-26 12:12:13,320 INFO User dédé logged out 117s 2025-10-26 12:12:13,334 INFO logout requested 117s 2025-10-26 12:12:13,335 INFO Logging out user dédé. 117s 2025-10-26 12:12:13,336 INFO User dédé logged out 117s 2025-10-26 12:12:13,349 INFO logout requested 117s 2025-10-26 12:12:13,351 INFO Logging out user dédé. 117s 2025-10-26 12:12:13,353 INFO User dédé logged out 117s 2025-10-26 12:12:13,364 INFO logout requested 117s 2025-10-26 12:12:13,365 INFO Logging out user dédé. 117s 2025-10-26 12:12:13,366 INFO User dédé logged out 117s PASSED 117s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2025-10-26 12:12:13,395 INFO Trying to authenticate http://127.0.0.1:8080 again 117s 2025-10-26 12:12:13,400 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 117s 2025-10-26 12:12:13,411 INFO User dédé@example.com successfully authenticated 117s 2025-10-26 12:12:13,421 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 117s 2025-10-26 12:12:13,435 INFO Trying to authenticate http://127.0.0.1:8081 again 117s 2025-10-26 12:12:13,442 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 117s 2025-10-26 12:12:13,454 INFO User dédé@example.org successfully authenticated 117s 2025-10-26 12:12:13,464 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 117s 2025-10-26 12:12:13,479 INFO Trying to authenticate http://127.0.0.1:8082 again 117s 2025-10-26 12:12:13,484 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 117s 2025-10-26 12:12:13,494 INFO User dédé@example.net successfully authenticated 117s 2025-10-26 12:12:13,505 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 117s 2025-10-26 12:12:13,567 INFO Trying to authenticate http://127.0.0.1:8083 again 117s 2025-10-26 12:12:13,572 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 117s 2025-10-26 12:12:13,584 INFO User dédé@example.test successfully authenticated 117s 2025-10-26 12:12:13,595 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 117s 2025-10-26 12:12:13,596 INFO logout requested 117s 2025-10-26 12:12:13,599 INFO Logging out user dédé@example.com. 117s 2025-10-26 12:12:13,604 INFO User dédé@example.com logged out 117s 2025-10-26 12:12:13,605 INFO logout requested 117s 2025-10-26 12:12:13,607 INFO Logging out user dédé@example.org. 117s 2025-10-26 12:12:13,612 INFO User dédé@example.org logged out 117s 2025-10-26 12:12:13,613 INFO logout requested 117s 2025-10-26 12:12:13,615 INFO Logging out user dédé@example.net. 117s 2025-10-26 12:12:13,620 INFO User dédé@example.net logged out 117s 2025-10-26 12:12:13,621 INFO logout requested 117s 2025-10-26 12:12:13,623 INFO Logging out user dédé@example.test. 117s 2025-10-26 12:12:13,630 INFO User dédé@example.test logged out 117s PASSED 117s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2025-10-26 12:12:13,648 WARNING A login attempt failed 117s PASSED 117s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 117s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2025-10-26 12:12:13,686 INFO Trying to authenticate http://127.0.0.1:8080 again 117s 2025-10-26 12:12:13,691 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 117s 2025-10-26 12:12:13,703 INFO User dédé@example.com successfully authenticated 117s 2025-10-26 12:12:13,713 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 117s 2025-10-26 12:12:13,725 INFO Trying to authenticate http://127.0.0.1:8081 again 117s 2025-10-26 12:12:13,729 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 117s 2025-10-26 12:12:13,741 INFO User dédé@example.org successfully authenticated 117s 2025-10-26 12:12:13,751 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 117s 2025-10-26 12:12:13,763 INFO Trying to authenticate http://127.0.0.1:8082 again 117s 2025-10-26 12:12:13,768 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 117s 2025-10-26 12:12:13,781 INFO User dédé@example.net successfully authenticated 117s 2025-10-26 12:12:13,794 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 117s 2025-10-26 12:12:13,809 INFO Trying to authenticate http://127.0.0.1:8083 again 117s 2025-10-26 12:12:13,815 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 117s 2025-10-26 12:12:13,830 INFO User dédé@example.test successfully authenticated 117s PASSED 117s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2025-10-26 12:12:13,843 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 117s 2025-10-26 12:12:13,867 INFO Trying to authenticate http://127.0.0.1:8080 again 117s 2025-10-26 12:12:13,872 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 117s 2025-10-26 12:12:13,884 INFO User dédé@example.com successfully authenticated 117s 2025-10-26 12:12:13,895 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 117s 2025-10-26 12:12:13,906 INFO Trying to authenticate http://127.0.0.1:8081 again 117s 2025-10-26 12:12:13,911 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 117s 2025-10-26 12:12:13,926 INFO User dédé@example.org successfully authenticated 117s 2025-10-26 12:12:13,940 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 117s 2025-10-26 12:12:13,958 INFO Trying to authenticate http://127.0.0.1:8082 again 117s 2025-10-26 12:12:13,963 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 118s 2025-10-26 12:12:13,978 INFO User dédé@example.net successfully authenticated 118s 2025-10-26 12:12:13,991 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 118s 2025-10-26 12:12:14,005 INFO Trying to authenticate http://127.0.0.1:8083 again 118s 2025-10-26 12:12:14,011 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 118s 2025-10-26 12:12:14,024 INFO User dédé@example.test successfully authenticated 118s 2025-10-26 12:12:14,034 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 118s 2025-10-26 12:12:14,048 INFO Trying to authenticate http://127.0.0.1:8080 again 118s 2025-10-26 12:12:14,053 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 118s 2025-10-26 12:12:14,065 INFO User dédé@example.com successfully authenticated 118s 2025-10-26 12:12:14,077 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 118s 2025-10-26 12:12:14,090 INFO Trying to authenticate http://127.0.0.1:8081 again 118s 2025-10-26 12:12:14,096 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 118s 2025-10-26 12:12:14,113 INFO User dédé@example.org successfully authenticated 118s 2025-10-26 12:12:14,126 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 118s 2025-10-26 12:12:14,141 INFO Trying to authenticate http://127.0.0.1:8082 again 118s 2025-10-26 12:12:14,148 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 118s 2025-10-26 12:12:14,160 INFO User dédé@example.net successfully authenticated 118s 2025-10-26 12:12:14,173 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 118s 2025-10-26 12:12:14,188 INFO Trying to authenticate http://127.0.0.1:8083 again 118s 2025-10-26 12:12:14,194 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 118s 2025-10-26 12:12:14,209 INFO User dédé@example.test successfully authenticated 118s 2025-10-26 12:12:14,223 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 118s PASSED2025-10-26 12:12:14,225 WARNING User already authenticated, dropping federated authentication request 118s 2025-10-26 12:12:14,226 WARNING User already authenticated, dropping federated authentication request 118s 2025-10-26 12:12:14,227 WARNING User already authenticated, dropping federated authentication request 118s 2025-10-26 12:12:14,229 WARNING User already authenticated, dropping federated authentication request 118s 118s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2025-10-26 12:12:14,246 INFO Trying to authenticate http://127.0.0.1:8080 again 118s 2025-10-26 12:12:14,251 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 118s 2025-10-26 12:12:14,263 INFO User dédé@example.com successfully authenticated 118s 2025-10-26 12:12:14,273 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 118s 2025-10-26 12:12:14,285 INFO Trying to authenticate http://127.0.0.1:8081 again 118s 2025-10-26 12:12:14,290 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 118s 2025-10-26 12:12:14,301 INFO User dédé@example.org successfully authenticated 118s 2025-10-26 12:12:14,312 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 118s 2025-10-26 12:12:14,323 INFO Trying to authenticate http://127.0.0.1:8082 again 118s 2025-10-26 12:12:14,328 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 118s 2025-10-26 12:12:14,339 INFO User dédé@example.net successfully authenticated 118s 2025-10-26 12:12:14,349 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 118s 2025-10-26 12:12:14,360 INFO Trying to authenticate http://127.0.0.1:8083 again 118s 2025-10-26 12:12:14,365 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 118s 2025-10-26 12:12:14,376 INFO User dédé@example.test successfully authenticated 118s 2025-10-26 12:12:14,386 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 118s 2025-10-26 12:12:14,388 INFO logout requested 118s 2025-10-26 12:12:14,389 INFO Logging out user dédé@example.com. 118s 2025-10-26 12:12:14,394 INFO User dédé@example.com logged out 118s 2025-10-26 12:12:14,398 INFO logout requested 118s 2025-10-26 12:12:14,400 INFO Logging out user dédé@example.org. 118s 2025-10-26 12:12:14,405 INFO User dédé@example.org logged out 118s 2025-10-26 12:12:14,408 INFO logout requested 118s 2025-10-26 12:12:14,410 INFO Logging out user dédé@example.net. 118s 2025-10-26 12:12:14,415 INFO User dédé@example.net logged out 118s 2025-10-26 12:12:14,418 INFO logout requested 118s 2025-10-26 12:12:14,420 INFO Logging out user dédé@example.test. 118s PASSED 118s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2025-10-26 12:12:14,425 INFO User dédé@example.test logged out 118s 2025-10-26 12:12:14,445 INFO Trying to authenticate http://127.0.0.1:8080 again 118s 2025-10-26 12:12:14,450 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 118s 2025-10-26 12:12:14,460 INFO User dédé@example.com successfully authenticated 118s 2025-10-26 12:12:14,471 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 118s 2025-10-26 12:12:14,485 INFO Trying to authenticate http://127.0.0.1:8081 again 118s 2025-10-26 12:12:14,490 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 118s 2025-10-26 12:12:14,501 INFO User dédé@example.org successfully authenticated 118s 2025-10-26 12:12:14,512 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 118s 2025-10-26 12:12:14,526 INFO Trying to authenticate http://127.0.0.1:8082 again 118s 2025-10-26 12:12:14,532 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 118s 2025-10-26 12:12:14,543 INFO User dédé@example.net successfully authenticated 118s 2025-10-26 12:12:14,554 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 118s 2025-10-26 12:12:14,567 INFO Trying to authenticate http://127.0.0.1:8083 again 118s 2025-10-26 12:12:14,571 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 118s 2025-10-26 12:12:14,583 INFO User dédé@example.test successfully authenticated 118s 2025-10-26 12:12:14,596 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 118s 2025-10-26 12:12:14,603 INFO Trying to authenticate http://127.0.0.1:8080 again 118s 2025-10-26 12:12:14,609 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 118s 2025-10-26 12:12:14,625 INFO User dédé@example.com successfully authenticated 118s 2025-10-26 12:12:14,631 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 118s 2025-10-26 12:12:14,637 INFO Trying to authenticate http://127.0.0.1:8081 again 118s 2025-10-26 12:12:14,642 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 118s 2025-10-26 12:12:14,653 INFO User dédé@example.org successfully authenticated 118s 2025-10-26 12:12:14,658 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 118s 2025-10-26 12:12:14,664 INFO Trying to authenticate http://127.0.0.1:8082 again 118s 2025-10-26 12:12:14,669 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 118s 2025-10-26 12:12:14,683 INFO User dédé@example.net successfully authenticated 118s 2025-10-26 12:12:14,688 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 118s 2025-10-26 12:12:14,695 INFO Trying to authenticate http://127.0.0.1:8083 again 118s 2025-10-26 12:12:14,700 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 118s 2025-10-26 12:12:14,712 INFO User dédé@example.test successfully authenticated 118s PASSED 118s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries 2025-10-26 12:12:14,718 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 118s PASSED 118s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 118s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 118s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2025-10-26 12:12:14,769 INFO User test successfully authenticated 118s PASSED 118s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2025-10-26 12:12:14,791 INFO User test successfully authenticated 118s PASSED 118s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2025-10-26 12:12:14,810 INFO User test successfully authenticated 118s 2025-10-26 12:12:14,825 INFO User test successfully authenticated 118s 2025-10-26 12:12:14,837 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 118s 2025-10-26 12:12:14,842 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=45678): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 118s PASSED 118s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries PASSED 118s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2025-10-26 12:12:14,857 INFO User test successfully authenticated 118s 2025-10-26 12:12:14,876 INFO User test successfully authenticated 118s PASSED 118s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2025-10-26 12:12:14,900 INFO User test successfully authenticated 118s PASSED 118s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2025-10-26 12:12:14,914 INFO Sending SLO requests to service http://127.0.0.1:43127 for user test 118s 2025-10-26 12:12:14,917 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 118s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 118s 2025-10-26 12:12:14,931 INFO User test successfully authenticated 118s PASSED 118s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 118s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 118s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 118s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 118s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 118s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 118s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 118s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 118s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 118s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 118s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 118s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 118s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 118s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 118s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 118s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 118s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 119s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 119s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 119s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 119s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 119s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 119s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2025-10-26 12:12:15,793 INFO User test successfully authenticated 119s 2025-10-26 12:12:15,801 WARNING Service https://www.example.org not allowed. 119s PASSED 119s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success 2025-10-26 12:12:15,818 INFO User test successfully authenticated 119s 2025-10-26 12:12:15,826 INFO Service ticket created for service https://www.example.com by user test. 119s PASSED 119s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt 2025-10-26 12:12:15,842 INFO User test successfully authenticated 119s 2025-10-26 12:12:15,849 INFO Service ticket created for service https://www.example.com by user test. 119s PASSED 119s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn 2025-10-26 12:12:15,864 INFO User test successfully authenticated 119s PASSED 119s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged 2025-10-26 12:12:15,887 INFO User test successfully authenticated 119s PASSED 119s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2025-10-26 12:12:15,907 INFO User test successfully authenticated 119s PASSED 119s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required 2025-10-26 12:12:15,914 WARNING User test seems authenticated but is not found in the database. 119s 2025-10-26 12:12:15,914 INFO Logging out user test. 119s 2025-10-26 12:12:15,915 INFO User test logged out 119s PASSED 119s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2025-10-26 12:12:15,938 INFO User test successfully authenticated 119s 2025-10-26 12:12:15,945 WARNING Username test not allowed on service restrict_user_fail 119s PASSED 119s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2025-10-26 12:12:15,961 WARNING A login attempt failed 120s 2025-10-26 12:12:15,979 WARNING A login attempt failed 120s 2025-10-26 12:12:15,995 WARNING A login attempt failed 120s PASSED 120s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2025-10-26 12:12:16,017 WARNING Received an invalid login ticket 120s PASSED 120s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2025-10-26 12:12:16,039 WARNING A login attempt failed 120s PASSED 120s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2025-10-26 12:12:16,062 INFO User test successfully authenticated 120s PASSED 120s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2025-10-26 12:12:16,080 INFO User test successfully authenticated 120s PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_renew 2025-10-26 12:12:17,143 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,155 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,159 INFO Service ticket created for service https://www.example.com by user test. 121s PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2025-10-26 12:12:17,174 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,185 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,189 INFO Service ticket created for service https://www.example.com by user test. 121s PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2025-10-26 12:12:17,205 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,212 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 121s 2025-10-26 12:12:17,217 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 121s 2025-10-26 12:12:17,223 INFO Service ticket created for service https://filter_success.example.com by user test. 121s PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2025-10-26 12:12:17,237 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,243 WARNING Username test not allowed on service restrict_user_fail 121s PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2025-10-26 12:12:17,249 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 121s 2025-10-26 12:12:17,264 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,270 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 121s 2025-10-26 12:12:17,277 INFO Service ticket created for service https://field_needed_success.example.com by user test. 121s PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2025-10-26 12:12:17,291 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,297 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 121s PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2025-10-26 12:12:17,313 INFO User test successfully authenticated 121s PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service 2025-10-26 12:12:17,319 WARNING User test seems authenticated but is not found in the database. 121s 2025-10-26 12:12:17,319 INFO Logging out user test. 121s 2025-10-26 12:12:17,320 INFO User test logged out 121s PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2025-10-26 12:12:17,357 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,365 INFO Service ticket created for service https://www.example.com/é by user test. 121s PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2025-10-26 12:12:17,381 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,388 INFO Service ticket created for service https://www.example.com by user test. 121s PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2025-10-26 12:12:17,405 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,417 WARNING Received a login attempt for an already-active user 121s 2025-10-26 12:12:17,420 INFO Service ticket created for service https://www.example.com by user test. 121s PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2025-10-26 12:12:17,436 INFO User test successfully authenticated 121s PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service PASSED 121s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2025-10-26 12:12:17,441 WARNING Service https://www.example.org not allowed. 121s 2025-10-26 12:12:17,450 WARNING Service https://www.example.net not allowed. 121s 2025-10-26 12:12:17,462 WARNING Service https://www.example.net not allowed. 121s PASSED 121s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2025-10-26 12:12:17,479 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,484 INFO logout requested 121s 2025-10-26 12:12:17,485 INFO Logging out user test. 121s 2025-10-26 12:12:17,489 INFO User test logged out 121s PASSED 121s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2025-10-26 12:12:17,500 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,512 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,525 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,536 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,540 INFO logout requested 121s 2025-10-26 12:12:17,540 INFO Logging out user test from all sessions. 121s PASSED2025-10-26 12:12:17,555 INFO User test logged out 121s 121s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout PASSED2025-10-26 12:12:17,567 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,571 INFO logout requested 121s 2025-10-26 12:12:17,572 INFO Logging out user test. 121s 2025-10-26 12:12:17,576 INFO User test logged out 121s 121s cas_server/tests/test_view.py::LogoutTestCase::test_logout PASSED 121s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2025-10-26 12:12:17,580 INFO logout requested 121s 2025-10-26 12:12:17,593 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,604 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,608 INFO logout requested 121s 2025-10-26 12:12:17,608 INFO Logging out user test from all sessions. 121s PASSED 121s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2025-10-26 12:12:17,616 INFO User test logged out 121s 2025-10-26 12:12:17,629 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,637 INFO Service ticket created for service http://127.0.0.1:41735 by user test. 121s 2025-10-26 12:12:17,641 INFO Validate: Service ticket ST-1q7K88UGFURDBtaOI0TKrpwYvSh6hKE3eN8BcyKhHTaU1FqzLZDRkmL10Ya98 validated, user test authenticated on service http://127.0.0.1:41735 121s 2025-10-26 12:12:17,643 INFO logout requested 121s 2025-10-26 12:12:17,643 INFO Logging out user test. 121s 2025-10-26 12:12:17,646 INFO Sending SLO requests to service http://127.0.0.1:41735 for user test 121s 2025-10-26 12:12:17,649 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 121s 2025-10-26 12:12:17,651 INFO User test logged out 121s 2025-10-26 12:12:17,661 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,668 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 121s 2025-10-26 12:12:17,672 INFO Validate: Service ticket ST-XJbLgOvmVwAFoXkHtM53oS8dw4mZFWUzZRKCLVOojCo1bROdgOQcZuZvmWMZL validated, user test authenticated on service http://127.0.0.1:45678 121s 2025-10-26 12:12:17,674 INFO logout requested 121s 2025-10-26 12:12:17,674 INFO Logging out user test. 121s 2025-10-26 12:12:17,677 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 121s 2025-10-26 12:12:17,680 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 121s 2025-10-26 12:12:17,682 INFO User test logged out 121s 2025-10-26 12:12:17,691 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,700 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 121s 2025-10-26 12:12:17,704 INFO Validate: Service ticket ST-avuav4PtS8TsXyJYH77VKc2d4ntIpJzrKELN2s7D6ioKy02kygg4aWj7vhgwm validated, user test authenticated on service http://127.0.0.1:45678 121s 2025-10-26 12:12:17,706 INFO logout requested 121s 2025-10-26 12:12:17,707 INFO Logging out user test. 121s 2025-10-26 12:12:17,710 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 121s PASSED 121s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2025-10-26 12:12:17,713 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=35801): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 121s 2025-10-26 12:12:17,714 INFO User test logged out 121s 2025-10-26 12:12:17,726 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,739 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,747 INFO logout requested 121s 2025-10-26 12:12:17,747 INFO Logging out user test. 121s 2025-10-26 12:12:17,751 INFO User test logged out 121s PASSED 121s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2025-10-26 12:12:17,770 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,774 INFO logout requested 121s 2025-10-26 12:12:17,775 INFO Logging out user test. 121s 2025-10-26 12:12:17,780 INFO User test logged out 121s PASSED 121s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2025-10-26 12:12:17,799 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,803 INFO logout requested 121s 2025-10-26 12:12:17,804 INFO Logging out user test. 121s 2025-10-26 12:12:17,808 INFO User test logged out 121s PASSED 121s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout PASSED 121s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2025-10-26 12:12:17,826 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,830 INFO logout requested 121s 2025-10-26 12:12:17,830 INFO Logging out user test. 121s 2025-10-26 12:12:17,834 INFO User test logged out 121s 2025-10-26 12:12:17,847 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,851 INFO logout requested 121s 2025-10-26 12:12:17,853 INFO Logging out user test. 121s 2025-10-26 12:12:17,858 INFO User test logged out 121s 2025-10-26 12:12:17,865 INFO logout requested 121s PASSED 121s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 121s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 121s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice 2025-10-26 12:12:17,886 WARNING Service https://www.example.org not allowed. 121s PASSED 121s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings PASSED 121s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 121s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2025-10-26 12:12:17,908 INFO User test successfully authenticated 121s PASSED 121s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 121s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2025-10-26 12:12:17,932 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,940 INFO Service ticket created for service https://www.example.com by user test. 121s PASSED 121s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2025-10-26 12:12:17,942 WARNING Validate: service or ticket missing 121s 2025-10-26 12:12:17,943 WARNING Validate: service or ticket missing 121s 2025-10-26 12:12:17,953 INFO User test successfully authenticated 121s 2025-10-26 12:12:17,958 INFO Service ticket created for service https://www.example.com by user test. 121s 2025-10-26 12:12:17,961 INFO Validate: Service ticket ST-6DynSXiBSyryQgOaW9WcqX3WAjQTEe6OpvssFLCiOVAMMeaFUwYZAXsEGlR0e validated, user test authenticated on service https://www.example.com 121s 2025-10-26 12:12:17,971 INFO User test successfully authenticated 122s 2025-10-26 12:12:17,976 INFO Service ticket created for service https://www.example.com by user test. 122s 2025-10-26 12:12:17,979 INFO Validate: Service ticket ST-JZa0Cgkd9HtM2d6Fwkw914yk02egTJ5Ts35WkGsMypZJWosUgmM9MUZpuPiQV validated, user test authenticated on service https://www.example.com 122s 2025-10-26 12:12:17,984 INFO Service ticket created for service https://www.example.com by user test. 122s 2025-10-26 12:12:17,987 WARNING Validate: Service ticket ST-pv0tnUCxw5enXPJBj3TIE6axno9L3Xia5PFzuO1kCUerTEuLVbWbs2rSUprJH not found or already validated, auth to https://www.example.com failed 122s PASSED 122s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2025-10-26 12:12:17,998 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,006 INFO Service ticket created for service https://user_field.example.com by user test. 122s 2025-10-26 12:12:18,010 INFO Validate: Service ticket ST-F7a1HfrZBCR8NHaWiAly7cwWQwrK01ELwzimAHGtsDw1jGzDySHjikoP0oxje validated, user test authenticated on service https://user_field.example.com 122s 2025-10-26 12:12:18,020 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,028 INFO Service ticket created for service https://user_field_alt.example.com by user test. 122s PASSED 122s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2025-10-26 12:12:18,032 INFO Validate: Service ticket ST-Jh9Su2KRZkZ0TH1FDV1h6vk3Q6PQwNn8TrW4huZ5xwrvcxJGHV4pPyxgRcI2d validated, user test authenticated on service https://user_field_alt.example.com 122s PASSED 122s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2025-10-26 12:12:18,044 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,052 INFO Service ticket created for service https://www.example.com by user test. 122s 2025-10-26 12:12:18,055 WARNING Validate: Service ticket ST-b5xeAqG1LHW9HXvt4iHSPb5njTcRzcQkDCV3J9bffXyomJ145yCUHGKziLSTq not found or already validated, auth to https://www.example.org failed 122s 2025-10-26 12:12:18,069 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,077 INFO Service ticket created for service https://www.example.com by user test. 122s PASSED 122s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2025-10-26 12:12:18,080 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 122s 2025-10-26 12:12:18,091 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,099 INFO Service ticket created for service https://www.example.com by user test. 122s PASSED 122s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2025-10-26 12:12:18,103 INFO Validate: Service ticket ST-FxDODjqVOAoiPJxhba9viWbs8AGMrF361cQZ8mfxY7nd3zsomIIR3jVEDlE9z validated, user test authenticated on service https://www.example.com 122s 2025-10-26 12:12:18,118 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,126 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 122s 2025-10-26 12:12:18,129 WARNING ValidateService: missing ticket or service 122s PASSED2025-10-26 12:12:18,130 WARNING ValidateService: missing ticket or service 122s 122s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2025-10-26 12:12:18,143 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,151 INFO Service ticket created for service http://127.0.0.1:43107 by user test. 122s 2025-10-26 12:12:18,161 INFO ValidateService: ticket ST-ZVJlce9YYtvqyTx0TA7ILA3vULh6uAW4O5Gr8V6AemKrsgFDmJMy8hMD5bmEX validated for user test on service http://127.0.0.1:43107. Proxy Granting Ticket transmited to http://127.0.0.1:43107. 122s 2025-10-26 12:12:18,167 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 122s 2025-10-26 12:12:18,172 INFO ValidateService: ticket PT-VEusOJWeKBDvoGkGZlBUsGgu4zqydsXfqVhuMZMDpto3q7qUj6nptlNKfd1TG validated for user test on service http://127.0.0.1:45678. 122s 2025-10-26 12:12:18,182 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,190 INFO Service ticket created for service http://127.0.0.1:40629 by user test. 122s 2025-10-26 12:12:18,197 INFO ValidateService: ticket ST-LXmhV62G9njBgHFi6FetGbDkHFfE3V98bymdf1ZfFtSi50x1UMyvCnRxFW0kE validated for user test on service http://127.0.0.1:40629. Proxy Granting Ticket transmited to http://127.0.0.1:40629. 122s PASSED2025-10-26 12:12:18,203 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 122s 2025-10-26 12:12:18,205 WARNING ValidateService: validation error: INVALID_TICKET PT-q24WKlJPRNdh80rWoMKm7MuZ4y9T1wqtkRlYDRj8sXYDkoE6ahNW6SLljlgMV 122s 122s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2025-10-26 12:12:18,219 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,227 INFO Service ticket created for service https://replace_attribute.example.com by user test. 122s 2025-10-26 12:12:18,231 INFO ValidateService: ticket ST-6qxF74sOLU0JczeGILu070VMOza09iF8UvmAlmsSM8uwEfvwfE5js4Bb8K7h9 validated for user test on service https://replace_attribute.example.com. 122s 2025-10-26 12:12:18,240 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,249 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 122s 2025-10-26 12:12:18,253 INFO ValidateService: ticket ST-rPKaV2kaSijrj6APdLEF8dY7hH1oKUwRZKekoDoEuOSY1Vp09xIEXeQXGJXNE validated for user test on service https://replace_attribute_list.example.com. 122s PASSED 122s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2025-10-26 12:12:18,268 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,276 INFO Service ticket created for service http://127.0.0.1:42483 by user test. 122s 2025-10-26 12:12:18,284 INFO ValidateService: ticket ST-sfmKx4nnSy6Vc2bvOpEKYhxSxo6BZOfnxcSkeWxclh8K0TMqUMUrKLPtlM7vZ validated for user test on service http://127.0.0.1:42483. Proxy Granting Ticket transmited to http://127.0.0.1:42483. 122s PASSED 122s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2025-10-26 12:12:18,298 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,307 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 122s 2025-10-26 12:12:18,311 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 122s 2025-10-26 12:12:18,320 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,328 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 122s PASSED 122s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2025-10-26 12:12:18,333 WARNING Service https://www.example.org not allowed. 122s 2025-10-26 12:12:18,333 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 122s 2025-10-26 12:12:18,348 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,356 INFO Service ticket created for service https://127.0.0.1:36347 by user test. 122s PASSED 122s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2025-10-26 12:12:18,363 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=36347): Max retries exceeded with url: /?pgtId=PGT-FAcc891TCPmqpcdgDDCHFijfmrKR5Lm9xWXQuzPEHQLtr5SqPDjIdgh0G4RX&pgtIou=PGTIOU-pkjp9g7dNfv73QOWzpcLrbkIHp3m151iuw7FFF2xMgUhdn2g45Ldu4SEe (Caused by SSLError(SSLError(1, '[SSL: RECORD_LAYER_FAILURE] record layer failure (_ssl.c:1033)'))) 122s 2025-10-26 12:12:18,377 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,382 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 122s 2025-10-26 12:12:18,386 INFO ValidateService: ticket ST-plBhpjObIdVZ7sOqjyXSAOibbVXCs2ockv1aVAtSzklgANyaRWYy6Jh5yVJLS validated for user test on service http://127.0.0.1:45678. 122s 2025-10-26 12:12:18,397 INFO User test successfully authenticated 122s PASSED 122s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2025-10-26 12:12:18,402 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 122s 2025-10-26 12:12:18,406 INFO ValidateService: ticket ST-cJZtLnZFyRmAsH1Gob94gmPpak8S4lUMkf26w2sii2CfZqS8QwUNlrh5VPK0r validated for user test on service http://127.0.0.1:45678. 122s 2025-10-26 12:12:18,412 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 122s 2025-10-26 12:12:18,414 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 122s 2025-10-26 12:12:18,428 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,436 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 122s PASSED 122s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2025-10-26 12:12:18,440 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 122s 2025-10-26 12:12:18,456 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,464 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 122s 2025-10-26 12:12:18,467 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 122s PASSED 122s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2025-10-26 12:12:18,481 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,489 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 122s 2025-10-26 12:12:18,493 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 122s PASSED 122s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2025-10-26 12:12:18,507 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,515 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 122s 2025-10-26 12:12:18,520 INFO ValidateService: ticket ST-JEhW2CK4P8VcqMLZFVdvPxsrEoweQxT3s8P97JNBj84S8rNS4H53TWnYC4GSi validated for user test on service http://127.0.0.1:45678. 122s PASSED 122s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2025-10-26 12:12:18,534 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,542 INFO Service ticket created for service https://one_attribute.example.com by user test. 122s PASSED2025-10-26 12:12:18,548 INFO ValidateService: ticket ST-r2kO2dPtcF2gLxvtLC0zcF1Zd26x7ThNseeYWqMgVRm9gxeo6nXhKyb3Ch3WW validated for user test on service https://one_attribute.example.com. 122s 122s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2025-10-26 12:12:18,564 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,572 INFO Service ticket created for service http://127.0.0.1:42875 by user test. 122s 2025-10-26 12:12:18,580 INFO ValidateService: ticket ST-pIDNhmkMcA4rIKgk7D61BRw73ROlDiHfEuHWLUcHkcZHmbe0lggXc7GhcGyBb validated for user test on service http://127.0.0.1:42875. Proxy Granting Ticket transmited to http://127.0.0.1:42875. 122s PASSED 122s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2025-10-26 12:12:18,596 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,606 INFO Service ticket created for service https://user_field.example.com by user test. 122s 2025-10-26 12:12:18,610 INFO ValidateService: ticket ST-l0Z1B8PQlv0ti7QnslC2C0UZwOtE0jQkYiOmw8QyF9gaSwOkv6aaV2gpWU4oQ validated for user test on service https://user_field.example.com. 122s 2025-10-26 12:12:18,620 INFO User test successfully authenticated 122s PASSED 122s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2025-10-26 12:12:18,628 INFO Service ticket created for service https://user_field_alt.example.com by user test. 122s 2025-10-26 12:12:18,632 INFO ValidateService: ticket ST-oYm9hZhpbLCtbMPioHRAPys9I05TfaqKxeYfXtBewkDy6kvSiQWrxQVRm2l5P validated for user test on service https://user_field_alt.example.com. 122s 2025-10-26 12:12:18,650 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,658 INFO Service ticket created for service http://127.0.0.1:39047 by user test. 122s 2025-10-26 12:12:18,666 INFO ValidateService: ticket ST-zMcXS82i23hMmnSoBKm5q0kJRX6LzdgOQAhAyVeSxlLQPj5ArasChWT6At67T validated for user test on service http://127.0.0.1:39047. Proxy Granting Ticket transmited to http://127.0.0.1:39047. 122s 2025-10-26 12:12:18,668 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 122s 2025-10-26 12:12:18,670 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 122s PASSED 122s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2025-10-26 12:12:18,684 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,692 INFO Service ticket created for service http://127.0.0.1:36745 by user test. 122s PASSED 122s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2025-10-26 12:12:18,701 INFO ValidateService: ticket ST-qdoTIk4cG5nUNUgUUVL4sNhrTIbaVMIizmQ4U5ZxNEYqIe08BZgF4QTzbMh0f validated for user test on service http://127.0.0.1:36745. Proxy Granting Ticket transmited to http://127.0.0.1:36745. 122s 2025-10-26 12:12:18,705 WARNING Username test not allowed on service restrict_user_fail 122s 2025-10-26 12:12:18,705 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 122s 2025-10-26 12:12:18,708 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 122s 2025-10-26 12:12:18,708 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 122s 2025-10-26 12:12:18,712 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 122s 2025-10-26 12:12:18,712 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 122s 2025-10-26 12:12:18,726 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,734 INFO Service ticket created for service http://127.0.0.1:39431 by user test. 122s 2025-10-26 12:12:18,742 INFO ValidateService: ticket ST-4TewEF7ZU2Uf4fmuARV0m6accSZSGHHEPqervL9T5vWSgKmgPonpAjVPvamWM validated for user test on service http://127.0.0.1:39431. Proxy Granting Ticket transmited to http://127.0.0.1:39431. 122s 2025-10-26 12:12:18,744 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 122s PASSED 122s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2025-10-26 12:12:18,760 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,767 INFO Service ticket created for service http://127.0.0.1:33759 by user test. 122s 2025-10-26 12:12:18,775 INFO ValidateService: ticket ST-wawqw8hGuhTkd8RUZWIZmRUO07SDyEzHY89wwlQ87SAFg4mNg2jq0NblYj03d validated for user test on service http://127.0.0.1:33759. Proxy Granting Ticket transmited to http://127.0.0.1:33759. 122s 2025-10-26 12:12:18,777 WARNING Service https://www.example.org not allowed. 122s 2025-10-26 12:12:18,777 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 122s 2025-10-26 12:12:18,779 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:33759 does not allow proxy tickets 122s PASSED 122s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2025-10-26 12:12:18,794 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,804 INFO Service ticket created for service http://127.0.0.1:45273 by user test. 122s 2025-10-26 12:12:18,812 INFO ValidateService: ticket ST-USfm0g1ZMvJ7OvGTtmBT3866HdnPrfbZtFzyK9AnuteCfWUCXoWE0uCNbHUxZ validated for user test on service http://127.0.0.1:45273. Proxy Granting Ticket transmited to http://127.0.0.1:45273. 122s 2025-10-26 12:12:18,819 INFO Proxy ticket created for user test on service https://www.example.com. 122s 2025-10-26 12:12:18,823 INFO ValidateService: ticket PT-tP4YRkHIWchtTWSSFHZitD6sJwJdIZjiWQbH4nvw9RbKurJTPKPZjbNQQsuFk validated for user test on service https://www.example.com. 122s PASSED 122s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2025-10-26 12:12:18,838 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,846 INFO Service ticket created for service https://www.example.com by user test. 122s 2025-10-26 12:12:18,850 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 122s PASSED 122s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket PASSED 122s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix 2025-10-26 12:12:18,858 WARNING SamlValidate: validation error: AuthnFailed ticket ST-ohd4QEIQOByAgfry7Mwse5ZIlwoYGhAnRuOilAGvZrckjufudv7bdMV9Pfln0 not found 122s 2025-10-26 12:12:18,860 WARNING SamlValidate: validation error: AuthnFailed ticket PT-IMJAnM68dgjBhZ4zZpb8LiQGrbHDnM7L5ZP5nvALkpWCYlix0uVQEmLYcnPO9 not found 122s 2025-10-26 12:12:18,867 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 122s PASSED 122s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml PASSED 122s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2025-10-26 12:12:18,874 WARNING SamlValidate: validation error: VersionMismatch 122s 2025-10-26 12:12:18,887 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,896 INFO Service ticket created for service https://www.example.com by user test. 122s 2025-10-26 12:12:18,907 INFO User test successfully authenticated 122s 2025-10-26 12:12:18,915 INFO Service ticket created for service http://127.0.0.1:44385 by user test. 122s 2025-10-26 12:12:18,924 INFO ValidateService: ticket ST-RF6lTKBZnf9ue96bFLmABI2Sa9lPAARbzOjk91DO1BVevCJQzCoYEeciD9Jjx validated for user test on service http://127.0.0.1:44385. Proxy Granting Ticket transmited to http://127.0.0.1:44385. 122s 2025-10-26 12:12:18,932 INFO Proxy ticket created for user test on service https://www.example.com. 122s 2025-10-26 12:12:18,937 INFO SamlValidate: ticket ST-Z4WK8Zr02USxdCBLrhQHiZBLeZYtXkwk4UK8fddhn5KO71TzmyVj9GbGh3sUW validated for user test on service https://www.example.com. 122s 2025-10-26 12:12:18,942 INFO SamlValidate: ticket PT-FOlBMli853iqBQji0tjPAVQYxZG4oN59gCB96NM4oDfWPVncRQhHazYkDtiwV validated for user test on service https://www.example.com. 122s PASSED 122s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2025-10-26 12:12:18,960 INFO User test successfully authenticated 123s 2025-10-26 12:12:18,970 INFO Service ticket created for service https://field_needed_success.example.com by user test. 123s 2025-10-26 12:12:18,977 INFO SamlValidate: ticket ST-M2aEe8XZS57Z5PZRJPuXOAu9zPPVcT3h1Giq6EumwReB8XqkiO7UCEBJm4lVR validated for user test on service https://field_needed_success.example.com. 123s 2025-10-26 12:12:18,989 INFO User test successfully authenticated 123s 2025-10-26 12:12:18,999 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 123s 2025-10-26 12:12:19,005 INFO SamlValidate: ticket ST-AvHatq8nbCCmh8JhYKOmGb2k42OllbFHHqZYH7AnaWPwxTEmCkIsIMYC07dq7 validated for user test on service https://field_needed_success_alt.example.com. 123s PASSEDDestroying test database for alias 'default'... 123s 123s 123s =============================== warnings summary =============================== 123s cas_server/tests/test_federate.py: 8 warnings 123s cas_server/tests/test_view.py: 3 warnings 123s /tmp/autopkgtest.NLiDjT/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). 123s datetime.utcnow() + timedelta(seconds=max_age), 123s 123s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 123s /usr/lib/python3.13/unittest/case.py:707: DeprecationWarning: It is deprecated to return a value that is not None from a test case (>) 123s return self.run(*args, **kwds) 123s 123s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 123s ======================= 128 passed, 12 warnings in 7.21s ======================= 123s 123s autopkgtest [12:12:19]: test run-test-suite.sh: -----------------------] 124s run-test-suite.sh PASS 124s autopkgtest [12:12:20]: test run-test-suite.sh: - - - - - - - - - - results - - - - - - - - - - 124s autopkgtest [12:12:20]: @@@@@@@@@@@@@@@@@@@@ summary 124s run-test-suite.sh PASS