0s autopkgtest [12:50:55]: starting date and time: 2025-10-26 12:50:55+0000 0s autopkgtest [12:50:55]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [12:50:55]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.b309ev2z/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults --apt-upgrade django-cas-server --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=python3-defaults/3.13.7-2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-amd64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-8.secgroup --name adt-resolute-amd64-django-cas-server-20251026-125055-juju-7f2275-prod-proposed-migration-environment-15-f7cb9f75-f8ee-4429-af7c-3c4482f3db34 --image adt/ubuntu-resolute-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-amd64-django-cas-server-20251026-125055-juju-7f2275-prod-proposed-migration-environment-15-f7cb9f75-f8ee-4429-af7c-3c4482f3db34 from image adt/ubuntu-resolute-amd64-server-20251026.img (UUID 08b4d2da-572c-4a3f-82f9-021e099dc3d7)... 31s autopkgtest [12:51:26]: testbed dpkg architecture: amd64 31s autopkgtest [12:51:26]: testbed apt version: 3.1.8ubuntu1 32s autopkgtest [12:51:27]: @@@@@@@@@@@@@@@@@@@@ test bed setup 32s autopkgtest [12:51:27]: testbed release detected to be: None 32s autopkgtest [12:51:27]: updating testbed package index (apt update) 34s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [87.8 kB] 34s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 34s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 34s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 34s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5028 B] 34s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [16.5 kB] 34s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [66.4 kB] 34s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [413 kB] 34s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [169 kB] 34s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [111 kB] 34s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [3356 B] 34s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [3744 B] 34s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [28.6 kB] 34s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [336 B] 34s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [298 kB] 34s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [129 kB] 34s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [11.0 kB] 34s Get:18 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [6132 B] 34s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [2476 B] 34s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [492 B] 35s Fetched 1352 kB in 2s (715 kB/s) 35s Reading package lists... 36s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 36s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 36s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 36s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 37s Reading package lists... 37s Reading package lists... 37s Building dependency tree... 37s Reading state information... 37s Calculating upgrade... 37s The following packages will be upgraded: 37s binutils binutils-common binutils-x86-64-linux-gnu gir1.2-glib-2.0 37s libbinutils libctf-nobfd0 libctf0 libglib2.0-0t64 libglib2.0-data 37s libgprofng0 libpython3-stdlib libsframe2 python3 python3-minimal 37s 14 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 37s Need to get 5384 kB of archives. 37s After this operation, 728 kB of additional disk space will be used. 37s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-minimal amd64 3.13.7-2 [27.8 kB] 37s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3 amd64 3.13.7-2 [23.9 kB] 37s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 libpython3-stdlib amd64 3.13.7-2 [10.6 kB] 37s Get:4 http://ftpmaster.internal/ubuntu resolute/main amd64 gir1.2-glib-2.0 amd64 2.86.1-1 [182 kB] 37s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 libglib2.0-0t64 amd64 2.86.1-1 [1692 kB] 37s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 libglib2.0-data all 2.86.1-1 [56.7 kB] 37s Get:7 http://ftpmaster.internal/ubuntu resolute/main amd64 libgprofng0 amd64 2.45-8ubuntu1 [930 kB] 37s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 libctf0 amd64 2.45-8ubuntu1 [104 kB] 37s Get:9 http://ftpmaster.internal/ubuntu resolute/main amd64 libctf-nobfd0 amd64 2.45-8ubuntu1 [106 kB] 37s Get:10 http://ftpmaster.internal/ubuntu resolute/main amd64 binutils-x86-64-linux-gnu amd64 2.45-8ubuntu1 [1177 kB] 38s Get:11 http://ftpmaster.internal/ubuntu resolute/main amd64 libbinutils amd64 2.45-8ubuntu1 [624 kB] 38s Get:12 http://ftpmaster.internal/ubuntu resolute/main amd64 binutils amd64 2.45-8ubuntu1 [211 kB] 38s Get:13 http://ftpmaster.internal/ubuntu resolute/main amd64 binutils-common amd64 2.45-8ubuntu1 [221 kB] 38s Get:14 http://ftpmaster.internal/ubuntu resolute/main amd64 libsframe2 amd64 2.45-8ubuntu1 [17.0 kB] 38s dpkg-preconfigure: unable to re-open stdin: No such file or directory 38s Fetched 5384 kB in 1s (6700 kB/s) 38s (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.) 38s Preparing to unpack .../python3-minimal_3.13.7-2_amd64.deb ... 38s Unpacking python3-minimal (3.13.7-2) over (3.13.7-1) ... 38s Setting up python3-minimal (3.13.7-2) ... 38s (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.) 38s Preparing to unpack .../00-python3_3.13.7-2_amd64.deb ... 38s running python pre-rtupdate hooks for python3.13... 38s Unpacking python3 (3.13.7-2) over (3.13.7-1) ... 38s Preparing to unpack .../01-libpython3-stdlib_3.13.7-2_amd64.deb ... 38s Unpacking libpython3-stdlib:amd64 (3.13.7-2) over (3.13.7-1) ... 38s Preparing to unpack .../02-gir1.2-glib-2.0_2.86.1-1_amd64.deb ... 38s Unpacking gir1.2-glib-2.0:amd64 (2.86.1-1) over (2.86.0-2) ... 38s Preparing to unpack .../03-libglib2.0-0t64_2.86.1-1_amd64.deb ... 38s Unpacking libglib2.0-0t64:amd64 (2.86.1-1) over (2.86.0-2) ... 38s Preparing to unpack .../04-libglib2.0-data_2.86.1-1_all.deb ... 38s Unpacking libglib2.0-data (2.86.1-1) over (2.86.0-2) ... 38s Preparing to unpack .../05-libgprofng0_2.45-8ubuntu1_amd64.deb ... 38s Unpacking libgprofng0:amd64 (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 38s Preparing to unpack .../06-libctf0_2.45-8ubuntu1_amd64.deb ... 38s Unpacking libctf0:amd64 (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 38s Preparing to unpack .../07-libctf-nobfd0_2.45-8ubuntu1_amd64.deb ... 38s Unpacking libctf-nobfd0:amd64 (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 38s Preparing to unpack .../08-binutils-x86-64-linux-gnu_2.45-8ubuntu1_amd64.deb ... 38s Unpacking binutils-x86-64-linux-gnu (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 39s Preparing to unpack .../09-libbinutils_2.45-8ubuntu1_amd64.deb ... 39s Unpacking libbinutils:amd64 (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 39s Preparing to unpack .../10-binutils_2.45-8ubuntu1_amd64.deb ... 39s Unpacking binutils (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 39s Preparing to unpack .../11-binutils-common_2.45-8ubuntu1_amd64.deb ... 39s Unpacking binutils-common:amd64 (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 39s Preparing to unpack .../12-libsframe2_2.45-8ubuntu1_amd64.deb ... 39s Unpacking libsframe2:amd64 (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 39s Setting up libsframe2:amd64 (2.45-8ubuntu1) ... 39s Setting up binutils-common:amd64 (2.45-8ubuntu1) ... 39s Setting up libctf-nobfd0:amd64 (2.45-8ubuntu1) ... 39s Setting up libglib2.0-0t64:amd64 (2.86.1-1) ... 39s No schema files found: doing nothing. 39s Setting up libglib2.0-data (2.86.1-1) ... 39s Setting up gir1.2-glib-2.0:amd64 (2.86.1-1) ... 39s Setting up libbinutils:amd64 (2.45-8ubuntu1) ... 39s Setting up libpython3-stdlib:amd64 (3.13.7-2) ... 39s Setting up libctf0:amd64 (2.45-8ubuntu1) ... 39s Setting up python3 (3.13.7-2) ... 39s running python rtupdate hooks for python3.13... 39s running python post-rtupdate hooks for python3.13... 39s Setting up libgprofng0:amd64 (2.45-8ubuntu1) ... 39s Setting up binutils-x86-64-linux-gnu (2.45-8ubuntu1) ... 39s Setting up binutils (2.45-8ubuntu1) ... 39s Processing triggers for libc-bin (2.42-0ubuntu3) ... 39s Processing triggers for man-db (2.13.1-1) ... 39s autopkgtest [12:51:34]: upgrading testbed (apt dist-upgrade and autopurge) 39s Reading package lists... 40s Building dependency tree... 40s Reading state information... 40s Calculating upgrade... 40s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 40s Reading package lists... 40s Building dependency tree... 40s Reading state information... 40s Solving dependencies... 40s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 42s autopkgtest [12:51:37]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 10:00:33 UTC 2025 42s autopkgtest [12:51:37]: @@@@@@@@@@@@@@@@@@@@ apt-source django-cas-server 44s Get:1 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (dsc) [2597 B] 44s Get:2 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (tar) [138 kB] 44s Get:3 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (diff) [10.2 kB] 44s gpgv: Signature made Sat Dec 14 20:43:18 2024 UTC 44s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 44s gpgv: issuer "tchet@debian.org" 44s gpgv: Can't check signature: No public key 44s dpkg-source: warning: cannot verify inline signature for ./django-cas-server_2.1.0-3.dsc: no acceptable signature found 44s autopkgtest [12:51:39]: testing package django-cas-server version 2.1.0-3 44s autopkgtest [12:51:39]: build not needed 45s autopkgtest [12:51:40]: test run-test-suite.sh: preparing testbed 45s Reading package lists... 45s Building dependency tree... 45s Reading state information... 45s Solving dependencies... 45s The following NEW packages will be installed: 45s fonts-font-awesome fonts-lato libjs-jquery libjs-sphinxdoc libjs-underscore 45s libpython3.14-minimal libpython3.14-stdlib libxslt1.1 python3-all 45s python3-asgiref python3-django python3-django-cas-server 45s python3-django-cas-server-doc python3-iniconfig python3-legacy-cgi 45s python3-lxml python3-packaging python3-pluggy python3-pytest 45s python3-pytest-django python3-requests-futures python3-sqlparse python3.14 45s python3.14-minimal sphinx-rtd-theme-common 45s 0 upgraded, 25 newly installed, 0 to remove and 0 not upgraded. 45s Need to get 17.7 MB of archives. 45s After this operation, 82.5 MB of additional disk space will be used. 45s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 fonts-lato all 2.015-1 [2781 kB] 46s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-minimal amd64 3.14.0-4 [906 kB] 46s Get:3 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14-minimal amd64 3.14.0-4 [2559 kB] 46s Get:4 http://ftpmaster.internal/ubuntu resolute/main amd64 fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 46s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 46s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 46s Get:7 http://ftpmaster.internal/ubuntu resolute/main amd64 libjs-sphinxdoc all 8.2.3-1ubuntu2 [28.0 kB] 46s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-stdlib amd64 3.14.0-4 [2397 kB] 46s Get:9 http://ftpmaster.internal/ubuntu resolute/main amd64 libxslt1.1 amd64 1.1.43-0.1 [172 kB] 46s Get:10 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14 amd64 3.14.0-4 [805 kB] 46s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-all amd64 3.13.7-2 [890 B] 46s Get:12 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-asgiref all 3.9.1-2 [24.9 kB] 46s Get:13 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-sqlparse all 0.5.3-1 [32.9 kB] 46s Get:14 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-django all 3:5.2.4-1ubuntu2 [3036 kB] 46s Get:15 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-legacy-cgi all 2.6.3-1 [16.8 kB] 46s Get:16 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-lxml amd64 6.0.1-1build1 [2336 kB] 46s Get:17 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-requests-futures all 1.0.2-1 [12.0 kB] 46s Get:18 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-django-cas-server all 2.1.0-3 [111 kB] 46s Get:19 http://ftpmaster.internal/ubuntu resolute/main amd64 sphinx-rtd-theme-common all 3.0.2+dfsg-3 [1013 kB] 46s Get:20 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-django-cas-server-doc all 2.1.0-3 [178 kB] 46s Get:21 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-iniconfig all 1.1.1-2 [6024 B] 46s Get:22 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-packaging all 25.0-1 [52.8 kB] 46s Get:23 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pluggy all 1.6.0-1 [21.0 kB] 46s Get:24 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest all 8.3.5-2 [252 kB] 46s Get:25 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest-django all 4.11.1-2 [21.8 kB] 46s Fetched 17.7 MB in 1s (18.9 MB/s) 46s Selecting previously unselected package fonts-lato. 46s (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.) 46s Preparing to unpack .../00-fonts-lato_2.015-1_all.deb ... 46s Unpacking fonts-lato (2.015-1) ... 46s Selecting previously unselected package libpython3.14-minimal:amd64. 46s Preparing to unpack .../01-libpython3.14-minimal_3.14.0-4_amd64.deb ... 46s Unpacking libpython3.14-minimal:amd64 (3.14.0-4) ... 47s Selecting previously unselected package python3.14-minimal. 47s Preparing to unpack .../02-python3.14-minimal_3.14.0-4_amd64.deb ... 47s Unpacking python3.14-minimal (3.14.0-4) ... 47s Selecting previously unselected package fonts-font-awesome. 47s Preparing to unpack .../03-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 47s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 47s Selecting previously unselected package libjs-jquery. 47s Preparing to unpack .../04-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 47s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 47s Selecting previously unselected package libjs-underscore. 47s Preparing to unpack .../05-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 47s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 47s Selecting previously unselected package libjs-sphinxdoc. 47s Preparing to unpack .../06-libjs-sphinxdoc_8.2.3-1ubuntu2_all.deb ... 47s Unpacking libjs-sphinxdoc (8.2.3-1ubuntu2) ... 47s Selecting previously unselected package libpython3.14-stdlib:amd64. 47s Preparing to unpack .../07-libpython3.14-stdlib_3.14.0-4_amd64.deb ... 47s Unpacking libpython3.14-stdlib:amd64 (3.14.0-4) ... 47s Selecting previously unselected package libxslt1.1:amd64. 47s Preparing to unpack .../08-libxslt1.1_1.1.43-0.1_amd64.deb ... 47s Unpacking libxslt1.1:amd64 (1.1.43-0.1) ... 47s Selecting previously unselected package python3.14. 47s Preparing to unpack .../09-python3.14_3.14.0-4_amd64.deb ... 47s Unpacking python3.14 (3.14.0-4) ... 47s Selecting previously unselected package python3-all. 47s Preparing to unpack .../10-python3-all_3.13.7-2_amd64.deb ... 47s Unpacking python3-all (3.13.7-2) ... 47s Selecting previously unselected package python3-asgiref. 47s Preparing to unpack .../11-python3-asgiref_3.9.1-2_all.deb ... 47s Unpacking python3-asgiref (3.9.1-2) ... 47s Selecting previously unselected package python3-sqlparse. 47s Preparing to unpack .../12-python3-sqlparse_0.5.3-1_all.deb ... 47s Unpacking python3-sqlparse (0.5.3-1) ... 47s Selecting previously unselected package python3-django. 47s Preparing to unpack .../13-python3-django_3%3a5.2.4-1ubuntu2_all.deb ... 47s Unpacking python3-django (3:5.2.4-1ubuntu2) ... 47s Selecting previously unselected package python3-legacy-cgi. 47s Preparing to unpack .../14-python3-legacy-cgi_2.6.3-1_all.deb ... 47s Unpacking python3-legacy-cgi (2.6.3-1) ... 47s Selecting previously unselected package python3-lxml:amd64. 47s Preparing to unpack .../15-python3-lxml_6.0.1-1build1_amd64.deb ... 47s Unpacking python3-lxml:amd64 (6.0.1-1build1) ... 47s Selecting previously unselected package python3-requests-futures. 47s Preparing to unpack .../16-python3-requests-futures_1.0.2-1_all.deb ... 47s Unpacking python3-requests-futures (1.0.2-1) ... 47s Selecting previously unselected package python3-django-cas-server. 47s Preparing to unpack .../17-python3-django-cas-server_2.1.0-3_all.deb ... 47s Unpacking python3-django-cas-server (2.1.0-3) ... 47s Selecting previously unselected package sphinx-rtd-theme-common. 47s Preparing to unpack .../18-sphinx-rtd-theme-common_3.0.2+dfsg-3_all.deb ... 47s Unpacking sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 47s Selecting previously unselected package python3-django-cas-server-doc. 47s Preparing to unpack .../19-python3-django-cas-server-doc_2.1.0-3_all.deb ... 47s Unpacking python3-django-cas-server-doc (2.1.0-3) ... 47s Selecting previously unselected package python3-iniconfig. 47s Preparing to unpack .../20-python3-iniconfig_1.1.1-2_all.deb ... 47s Unpacking python3-iniconfig (1.1.1-2) ... 47s Selecting previously unselected package python3-packaging. 47s Preparing to unpack .../21-python3-packaging_25.0-1_all.deb ... 47s Unpacking python3-packaging (25.0-1) ... 47s Selecting previously unselected package python3-pluggy. 47s Preparing to unpack .../22-python3-pluggy_1.6.0-1_all.deb ... 47s Unpacking python3-pluggy (1.6.0-1) ... 47s Selecting previously unselected package python3-pytest. 47s Preparing to unpack .../23-python3-pytest_8.3.5-2_all.deb ... 47s Unpacking python3-pytest (8.3.5-2) ... 47s Selecting previously unselected package python3-pytest-django. 47s Preparing to unpack .../24-python3-pytest-django_4.11.1-2_all.deb ... 47s Unpacking python3-pytest-django (4.11.1-2) ... 47s Setting up python3-iniconfig (1.1.1-2) ... 47s Setting up fonts-lato (2.015-1) ... 47s Setting up libpython3.14-minimal:amd64 (3.14.0-4) ... 47s Setting up python3-packaging (25.0-1) ... 47s Setting up python3-sqlparse (0.5.3-1) ... 48s Setting up python3-pluggy (1.6.0-1) ... 48s Setting up python3-legacy-cgi (2.6.3-1) ... 48s Setting up libxslt1.1:amd64 (1.1.43-0.1) ... 48s Setting up python3-requests-futures (1.0.2-1) ... 48s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 48s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 48s Setting up sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 48s Setting up python3.14-minimal (3.14.0-4) ... 48s Setting up python3-asgiref (3.9.1-2) ... 49s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 49s Setting up python3-django (3:5.2.4-1ubuntu2) ... 50s Setting up libpython3.14-stdlib:amd64 (3.14.0-4) ... 50s Setting up python3-pytest (8.3.5-2) ... 50s Setting up python3-pytest-django (4.11.1-2) ... 51s Setting up libjs-sphinxdoc (8.2.3-1ubuntu2) ... 51s Setting up python3-lxml:amd64 (6.0.1-1build1) ... 51s Setting up python3.14 (3.14.0-4) ... 51s Setting up python3-django-cas-server (2.1.0-3) ... 52s Setting up python3-all (3.13.7-2) ... 52s Setting up python3-django-cas-server-doc (2.1.0-3) ... 52s Processing triggers for systemd (257.9-0ubuntu2) ... 52s Processing triggers for man-db (2.13.1-1) ... 52s Processing triggers for libc-bin (2.42-0ubuntu3) ... 53s autopkgtest [12:51:48]: test run-test-suite.sh: [----------------------- 53s 53s *************************** 53s *** Testing with python3.14 53s *************************** 53s 53s Content of current working folder: 53s 53s total 20 53s drwxr-xr-x 3 ubuntu ubuntu 4096 Oct 26 12:51 . 53s drwxrwxrwx 5 root root 4096 Oct 26 12:51 .. 53s drwxrwxr-x 9 ubuntu ubuntu 4096 Oct 26 12:51 cas_server 53s -rw-rw-r-- 1 ubuntu ubuntu 140 Aug 18 2024 pytest.ini 53s -rw-rw-r-- 1 ubuntu ubuntu 152 Aug 18 2024 setup.cfg 53s Running tests... 53s 53s ============================= test session starts ============================== 53s platform linux -- Python 3.14.0, pytest-8.3.5, pluggy-1.6.0 -- /usr/bin/python3.14 53s cachedir: .pytest_cache 53s django: version: 5.2.4, settings: cas_server.tests.settings (from ini) 53s rootdir: /tmp/autopkgtest.ZIY8bQ/autopkgtest_tmp 53s configfile: pytest.ini 53s testpaths: cas_server/tests/ 53s plugins: django-4.11.1, typeguard-4.4.2 53s collecting ... collected 128 items 53s 53s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 54s 2025-10-26 12:51:48,916 WARNING Identity provider suffix exemple.fr not found 54s 2025-10-26 12:51:48,925 INFO Got an invalid ticket ST-yzuXvbOLqqFtTXhjECNziAZWN8ifWpN12uWIMMlGJbyNivlcxFBrGvgpNLANg from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 54s 2025-10-26 12:51:48,927 INFO Got an invalid ticket ST-C07I8sz301dDiBNUT8JfC9PlK1ZemuTnnvpNAKoxt3P2xJ1rlTaRWko9sUEZt from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 54s PASSED 54s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2025-10-26 12:51:48,946 WARNING CAS_FEDERATE is False, set it to True to use federation 54s 2025-10-26 12:51:48,946 WARNING CAS_FEDERATE is False, set it to True to use federation 54s PASSED 54s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2025-10-26 12:51:48,955 INFO Trying to authenticate http://127.0.0.1:8080 again 54s 2025-10-26 12:51:48,959 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 54s 2025-10-26 12:51:48,967 INFO User dédé@example.com successfully authenticated 54s 2025-10-26 12:51:48,974 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 54s 2025-10-26 12:51:48,980 INFO Trying to authenticate http://127.0.0.1:8081 again 54s 2025-10-26 12:51:48,984 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 54s 2025-10-26 12:51:48,989 INFO User dédé@example.org successfully authenticated 54s 2025-10-26 12:51:48,995 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 54s 2025-10-26 12:51:49,000 INFO Trying to authenticate http://127.0.0.1:8082 again 54s 2025-10-26 12:51:49,003 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 54s 2025-10-26 12:51:49,009 INFO User dédé@example.net successfully authenticated 54s 2025-10-26 12:51:49,014 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 54s 2025-10-26 12:51:49,020 INFO Trying to authenticate http://127.0.0.1:8083 again 54s 2025-10-26 12:51:49,023 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 54s 2025-10-26 12:51:49,029 INFO User dédé@example.test successfully authenticated 54s 2025-10-26 12:51:49,034 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 54s 2025-10-26 12:51:49,055 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-dqZiykb9FBRgN6dECzM6mjcqPsv5cdvqVGeXMVGpvKpTDXvkT20iUONkFZ8hR 54s 2025-10-26 12:51:49,069 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-5jDxLdBvvfpkZuiWnt6z8ZD3uYIod5V507Yk3UwstkPC0ezhxBahoYtNjJ4l8 54s PASSED 54s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 54s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2025-10-26 12:51:49,088 INFO Trying to authenticate http://127.0.0.1:8080 again 54s 2025-10-26 12:51:49,091 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 54s 2025-10-26 12:51:49,096 INFO User dédé@example.com successfully authenticated 54s 2025-10-26 12:51:49,102 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 54s 2025-10-26 12:51:49,108 INFO Trying to authenticate http://127.0.0.1:8081 again 54s 2025-10-26 12:51:49,111 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 54s 2025-10-26 12:51:49,116 INFO User dédé@example.org successfully authenticated 54s 2025-10-26 12:51:49,121 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 54s 2025-10-26 12:51:49,127 INFO Trying to authenticate http://127.0.0.1:8082 again 54s 2025-10-26 12:51:49,130 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 54s 2025-10-26 12:51:49,136 INFO User dédé@example.net successfully authenticated 54s 2025-10-26 12:51:49,141 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 54s 2025-10-26 12:51:49,147 INFO Trying to authenticate http://127.0.0.1:8083 again 54s 2025-10-26 12:51:49,151 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 54s 2025-10-26 12:51:49,156 INFO User dédé@example.test successfully authenticated 54s 2025-10-26 12:51:49,162 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 54s 2025-10-26 12:51:49,162 INFO logout requested 54s 2025-10-26 12:51:49,163 INFO Logging out user dédé@example.com. 54s 2025-10-26 12:51:49,166 INFO User dédé@example.com logged out 54s 2025-10-26 12:51:49,171 INFO logout requested 54s 2025-10-26 12:51:49,172 INFO logout requested 54s 2025-10-26 12:51:49,173 INFO Logging out user dédé@example.org. 54s 2025-10-26 12:51:49,176 INFO User dédé@example.org logged out 54s 2025-10-26 12:51:49,181 INFO logout requested 54s 2025-10-26 12:51:49,183 INFO logout requested 54s 2025-10-26 12:51:49,184 INFO Logging out user dédé@example.net. 54s 2025-10-26 12:51:49,186 INFO User dédé@example.net logged out 54s 2025-10-26 12:51:49,191 INFO logout requested 54s 2025-10-26 12:51:49,192 INFO logout requested 54s 2025-10-26 12:51:49,193 INFO Logging out user dédé@example.test. 54s 2025-10-26 12:51:49,196 INFO User dédé@example.test logged out 54s 2025-10-26 12:51:49,201 INFO logout requested 54s 2025-10-26 12:51:49,208 INFO Trying to authenticate http://127.0.0.1:8080 again 54s 2025-10-26 12:51:49,211 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 54s 2025-10-26 12:51:49,216 INFO User dédé@example.com successfully authenticated 54s 2025-10-26 12:51:49,221 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 54s 2025-10-26 12:51:49,227 INFO Trying to authenticate http://127.0.0.1:8081 again 54s 2025-10-26 12:51:49,231 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 54s 2025-10-26 12:51:49,236 INFO User dédé@example.org successfully authenticated 54s 2025-10-26 12:51:49,241 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 54s 2025-10-26 12:51:49,247 INFO Trying to authenticate http://127.0.0.1:8082 again 54s 2025-10-26 12:51:49,250 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 54s 2025-10-26 12:51:49,255 INFO User dédé@example.net successfully authenticated 54s 2025-10-26 12:51:49,261 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 54s 2025-10-26 12:51:49,267 INFO Trying to authenticate http://127.0.0.1:8083 again 54s 2025-10-26 12:51:49,270 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 54s 2025-10-26 12:51:49,276 INFO User dédé@example.test successfully authenticated 54s 2025-10-26 12:51:49,281 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 54s 2025-10-26 12:51:49,282 INFO logout requested 54s 2025-10-26 12:51:49,283 INFO Logging out user dédé. 54s 2025-10-26 12:51:49,283 INFO User dédé logged out 54s 2025-10-26 12:51:49,289 INFO logout requested 54s 2025-10-26 12:51:49,290 INFO Logging out user dédé. 54s 2025-10-26 12:51:49,290 INFO User dédé logged out 54s 2025-10-26 12:51:49,296 INFO logout requested 54s 2025-10-26 12:51:49,296 INFO Logging out user dédé. 54s 2025-10-26 12:51:49,297 INFO User dédé logged out 54s 2025-10-26 12:51:49,302 INFO logout requested 54s 2025-10-26 12:51:49,303 INFO Logging out user dédé. 54s 2025-10-26 12:51:49,303 INFO User dédé logged out 54s PASSED 54s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2025-10-26 12:51:49,317 INFO Trying to authenticate http://127.0.0.1:8080 again 54s 2025-10-26 12:51:49,320 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 54s 2025-10-26 12:51:49,326 INFO User dédé@example.com successfully authenticated 54s 2025-10-26 12:51:49,331 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 54s 2025-10-26 12:51:49,338 INFO Trying to authenticate http://127.0.0.1:8081 again 54s 2025-10-26 12:51:49,341 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 54s 2025-10-26 12:51:49,346 INFO User dédé@example.org successfully authenticated 54s 2025-10-26 12:51:49,352 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 54s 2025-10-26 12:51:49,358 INFO Trying to authenticate http://127.0.0.1:8082 again 54s 2025-10-26 12:51:49,361 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 54s 2025-10-26 12:51:49,366 INFO User dédé@example.net successfully authenticated 54s 2025-10-26 12:51:49,371 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 54s 2025-10-26 12:51:49,378 INFO Trying to authenticate http://127.0.0.1:8083 again 54s 2025-10-26 12:51:49,381 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 54s 2025-10-26 12:51:49,387 INFO User dédé@example.test successfully authenticated 54s 2025-10-26 12:51:49,392 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 54s 2025-10-26 12:51:49,393 INFO logout requested 54s 2025-10-26 12:51:49,394 INFO Logging out user dédé@example.com. 54s 2025-10-26 12:51:49,397 INFO User dédé@example.com logged out 54s 2025-10-26 12:51:49,398 INFO logout requested 54s 2025-10-26 12:51:49,399 INFO Logging out user dédé@example.org. 54s 2025-10-26 12:51:49,401 INFO User dédé@example.org logged out 54s 2025-10-26 12:51:49,402 INFO logout requested 54s 2025-10-26 12:51:49,403 INFO Logging out user dédé@example.net. 54s 2025-10-26 12:51:49,405 INFO User dédé@example.net logged out 54s 2025-10-26 12:51:49,405 INFO logout requested 54s 2025-10-26 12:51:49,406 INFO Logging out user dédé@example.test. 54s 2025-10-26 12:51:49,409 INFO User dédé@example.test logged out 54s PASSED 54s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2025-10-26 12:51:49,418 WARNING A login attempt failed 54s PASSED 54s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 54s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2025-10-26 12:51:49,436 INFO Trying to authenticate http://127.0.0.1:8080 again 54s 2025-10-26 12:51:49,439 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 54s 2025-10-26 12:51:49,444 INFO User dédé@example.com successfully authenticated 54s 2025-10-26 12:51:49,449 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 54s 2025-10-26 12:51:49,456 INFO Trying to authenticate http://127.0.0.1:8081 again 54s 2025-10-26 12:51:49,459 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 54s 2025-10-26 12:51:49,464 INFO User dédé@example.org successfully authenticated 54s 2025-10-26 12:51:49,469 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 54s 2025-10-26 12:51:49,475 INFO Trying to authenticate http://127.0.0.1:8082 again 54s 2025-10-26 12:51:49,479 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 54s 2025-10-26 12:51:49,484 INFO User dédé@example.net successfully authenticated 54s 2025-10-26 12:51:49,490 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 54s 2025-10-26 12:51:49,496 INFO Trying to authenticate http://127.0.0.1:8083 again 54s 2025-10-26 12:51:49,499 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 54s 2025-10-26 12:51:49,504 INFO User dédé@example.test successfully authenticated 54s 2025-10-26 12:51:49,509 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 54s PASSED 54s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2025-10-26 12:51:49,518 INFO Trying to authenticate http://127.0.0.1:8080 again 54s 2025-10-26 12:51:49,521 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 54s 2025-10-26 12:51:49,526 INFO User dédé@example.com successfully authenticated 54s 2025-10-26 12:51:49,532 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 54s 2025-10-26 12:51:49,537 INFO Trying to authenticate http://127.0.0.1:8081 again 54s 2025-10-26 12:51:49,540 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 54s 2025-10-26 12:51:49,545 INFO User dédé@example.org successfully authenticated 54s 2025-10-26 12:51:49,551 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 54s 2025-10-26 12:51:49,557 INFO Trying to authenticate http://127.0.0.1:8082 again 54s 2025-10-26 12:51:49,560 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 54s 2025-10-26 12:51:49,565 INFO User dédé@example.net successfully authenticated 54s 2025-10-26 12:51:49,570 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 54s 2025-10-26 12:51:49,576 INFO Trying to authenticate http://127.0.0.1:8083 again 54s 2025-10-26 12:51:49,580 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 54s 2025-10-26 12:51:49,585 INFO User dédé@example.test successfully authenticated 54s 2025-10-26 12:51:49,590 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 54s 2025-10-26 12:51:49,597 INFO Trying to authenticate http://127.0.0.1:8080 again 54s 2025-10-26 12:51:49,600 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 54s 2025-10-26 12:51:49,605 INFO User dédé@example.com successfully authenticated 54s 2025-10-26 12:51:49,610 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 54s 2025-10-26 12:51:49,618 INFO Trying to authenticate http://127.0.0.1:8081 again 54s 2025-10-26 12:51:49,621 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 55s 2025-10-26 12:51:49,626 INFO User dédé@example.org successfully authenticated 55s 2025-10-26 12:51:49,631 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 55s 2025-10-26 12:51:49,637 INFO Trying to authenticate http://127.0.0.1:8082 again 55s 2025-10-26 12:51:49,640 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 55s 2025-10-26 12:51:49,646 INFO User dédé@example.net successfully authenticated 55s 2025-10-26 12:51:49,652 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 55s 2025-10-26 12:51:49,657 INFO Trying to authenticate http://127.0.0.1:8083 again 55s 2025-10-26 12:51:49,661 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 55s 2025-10-26 12:51:49,667 INFO User dédé@example.test successfully authenticated 55s 2025-10-26 12:51:49,672 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 55s 2025-10-26 12:51:49,673 WARNING User already authenticated, dropping federated authentication request 55s 2025-10-26 12:51:49,674 WARNING User already authenticated, dropping federated authentication request 55s 2025-10-26 12:51:49,674 WARNING User already authenticated, dropping federated authentication request 55s 2025-10-26 12:51:49,675 WARNING User already authenticated, dropping federated authentication request 55s PASSED 55s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2025-10-26 12:51:49,683 INFO Trying to authenticate http://127.0.0.1:8080 again 55s 2025-10-26 12:51:49,686 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 55s 2025-10-26 12:51:49,692 INFO User dédé@example.com successfully authenticated 55s 2025-10-26 12:51:49,697 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 55s 2025-10-26 12:51:49,703 INFO Trying to authenticate http://127.0.0.1:8081 again 55s 2025-10-26 12:51:49,706 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 55s 2025-10-26 12:51:49,713 INFO User dédé@example.org successfully authenticated 55s 2025-10-26 12:51:49,719 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 55s 2025-10-26 12:51:49,724 INFO Trying to authenticate http://127.0.0.1:8082 again 55s 2025-10-26 12:51:49,727 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 55s 2025-10-26 12:51:49,732 INFO User dédé@example.net successfully authenticated 55s 2025-10-26 12:51:49,748 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 55s 2025-10-26 12:51:49,754 INFO Trying to authenticate http://127.0.0.1:8083 again 55s 2025-10-26 12:51:49,757 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 55s 2025-10-26 12:51:49,762 INFO User dédé@example.test successfully authenticated 55s 2025-10-26 12:51:49,768 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 55s 2025-10-26 12:51:49,769 INFO logout requested 55s 2025-10-26 12:51:49,770 INFO Logging out user dédé@example.com. 55s 2025-10-26 12:51:49,772 INFO User dédé@example.com logged out 55s 2025-10-26 12:51:49,774 INFO logout requested 55s 2025-10-26 12:51:49,775 INFO Logging out user dédé@example.org. 55s 2025-10-26 12:51:49,777 INFO User dédé@example.org logged out 55s 2025-10-26 12:51:49,779 INFO logout requested 55s 2025-10-26 12:51:49,780 INFO Logging out user dédé@example.net. 55s 2025-10-26 12:51:49,782 INFO User dédé@example.net logged out 55s 2025-10-26 12:51:49,784 INFO logout requested 55s 2025-10-26 12:51:49,785 INFO Logging out user dédé@example.test. 55s 2025-10-26 12:51:49,787 INFO User dédé@example.test logged out 55s PASSED 55s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2025-10-26 12:51:49,797 INFO Trying to authenticate http://127.0.0.1:8080 again 55s 2025-10-26 12:51:49,800 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 55s 2025-10-26 12:51:49,806 INFO User dédé@example.com successfully authenticated 55s 2025-10-26 12:51:49,811 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 55s 2025-10-26 12:51:49,817 INFO Trying to authenticate http://127.0.0.1:8081 again 55s 2025-10-26 12:51:49,820 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 55s 2025-10-26 12:51:49,825 INFO User dédé@example.org successfully authenticated 55s 2025-10-26 12:51:49,830 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 55s 2025-10-26 12:51:49,836 INFO Trying to authenticate http://127.0.0.1:8082 again 55s 2025-10-26 12:51:49,839 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 55s 2025-10-26 12:51:49,844 INFO User dédé@example.net successfully authenticated 55s 2025-10-26 12:51:49,850 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 55s 2025-10-26 12:51:49,855 INFO Trying to authenticate http://127.0.0.1:8083 again 55s 2025-10-26 12:51:49,858 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 55s 2025-10-26 12:51:49,863 INFO User dédé@example.test successfully authenticated 55s 2025-10-26 12:51:49,869 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 55s 2025-10-26 12:51:49,872 INFO Trying to authenticate http://127.0.0.1:8080 again 55s 2025-10-26 12:51:49,874 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 55s 2025-10-26 12:51:49,880 INFO User dédé@example.com successfully authenticated 55s 2025-10-26 12:51:49,883 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 55s 2025-10-26 12:51:49,886 INFO Trying to authenticate http://127.0.0.1:8081 again 55s 2025-10-26 12:51:49,889 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 55s 2025-10-26 12:51:49,897 INFO User dédé@example.org successfully authenticated 55s 2025-10-26 12:51:49,900 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 55s 2025-10-26 12:51:49,904 INFO Trying to authenticate http://127.0.0.1:8082 again 55s 2025-10-26 12:51:49,907 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 55s 2025-10-26 12:51:49,913 INFO User dédé@example.net successfully authenticated 55s 2025-10-26 12:51:49,916 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 55s 2025-10-26 12:51:49,920 INFO Trying to authenticate http://127.0.0.1:8083 again 55s 2025-10-26 12:51:49,923 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 55s 2025-10-26 12:51:49,929 INFO User dédé@example.test successfully authenticated 55s 2025-10-26 12:51:49,931 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 55s PASSED 55s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 55s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 55s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 55s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2025-10-26 12:51:49,957 INFO User test successfully authenticated 55s PASSED 55s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2025-10-26 12:51:49,969 INFO User test successfully authenticated 55s PASSED 55s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2025-10-26 12:51:49,977 INFO User test successfully authenticated 55s 2025-10-26 12:51:49,984 INFO User test successfully authenticated 55s 2025-10-26 12:51:49,990 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 55s 2025-10-26 12:51:49,993 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')) 55s PASSED 55s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2025-10-26 12:51:50,000 INFO User test successfully authenticated 55s PASSED 55s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2025-10-26 12:51:50,011 INFO User test successfully authenticated 55s PASSED 55s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2025-10-26 12:51:50,023 INFO User test successfully authenticated 55s 2025-10-26 12:51:50,030 INFO Sending SLO requests to service http://127.0.0.1:38669 for user test 55s 2025-10-26 12:51:50,031 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 55s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 55s PASSED 55s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2025-10-26 12:51:50,038 INFO User test successfully authenticated 55s PASSED 55s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 55s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 55s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 55s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 55s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 55s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 55s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 55s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 55s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 55s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 55s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 55s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 55s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 55s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 55s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 55s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 55s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 55s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 55s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 55s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 55s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 55s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 55s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2025-10-26 12:51:50,382 INFO User test successfully authenticated 55s 2025-10-26 12:51:50,386 WARNING Service https://www.example.org not allowed. 55s PASSED 55s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success 2025-10-26 12:51:50,393 INFO User test successfully authenticated 55s 2025-10-26 12:51:50,398 INFO Service ticket created for service https://www.example.com by user test. 55s PASSED 55s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt 2025-10-26 12:51:50,405 INFO User test successfully authenticated 55s 2025-10-26 12:51:50,409 INFO Service ticket created for service https://www.example.com by user test. 55s PASSED 55s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn 2025-10-26 12:51:50,416 INFO User test successfully authenticated 55s PASSED 55s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged 2025-10-26 12:51:50,427 INFO User test successfully authenticated 55s PASSED 55s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2025-10-26 12:51:50,437 INFO User test successfully authenticated 55s 2025-10-26 12:51:50,440 WARNING User test seems authenticated but is not found in the database. 55s 2025-10-26 12:51:50,440 INFO Logging out user test. 55s 2025-10-26 12:51:50,441 INFO User test logged out 55s PASSED 55s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 55s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2025-10-26 12:51:50,451 INFO User test successfully authenticated 55s 2025-10-26 12:51:50,456 WARNING Username test not allowed on service restrict_user_fail 55s PASSED 55s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2025-10-26 12:51:50,464 WARNING A login attempt failed 55s 2025-10-26 12:51:50,472 WARNING A login attempt failed 55s 2025-10-26 12:51:50,479 WARNING A login attempt failed 55s PASSED 55s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2025-10-26 12:51:50,489 WARNING Received an invalid login ticket 55s PASSED 55s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2025-10-26 12:51:50,498 WARNING A login attempt failed 55s PASSED 55s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2025-10-26 12:51:50,509 INFO User test successfully authenticated 55s PASSED 55s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2025-10-26 12:51:50,517 INFO User test successfully authenticated 55s PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_renew 2025-10-26 12:51:51,014 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,022 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,025 INFO Service ticket created for service https://www.example.com by user test. 56s PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2025-10-26 12:51:51,032 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,037 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,040 INFO Service ticket created for service https://www.example.com by user test. 56s PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2025-10-26 12:51:51,048 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,051 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 56s 2025-10-26 12:51:51,054 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 56s 2025-10-26 12:51:51,057 INFO Service ticket created for service https://filter_success.example.com by user test. 56s 2025-10-26 12:51:51,064 INFO User test successfully authenticated 56s PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2025-10-26 12:51:51,068 WARNING Username test not allowed on service restrict_user_fail 56s 2025-10-26 12:51:51,072 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 56s PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2025-10-26 12:51:51,078 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,081 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 56s 2025-10-26 12:51:51,084 INFO Service ticket created for service https://field_needed_success.example.com by user test. 56s PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2025-10-26 12:51:51,091 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,094 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 56s PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2025-10-26 12:51:51,102 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,106 WARNING User test seems authenticated but is not found in the database. 56s 2025-10-26 12:51:51,106 INFO Logging out user test. 56s 2025-10-26 12:51:51,107 INFO User test logged out 56s PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2025-10-26 12:51:51,126 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,130 INFO Service ticket created for service https://www.example.com/é by user test. 56s PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2025-10-26 12:51:51,138 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,142 INFO Service ticket created for service https://www.example.com by user test. 56s PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2025-10-26 12:51:51,149 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,156 WARNING Received a login attempt for an already-active user 56s 2025-10-26 12:51:51,158 INFO Service ticket created for service https://www.example.com by user test. 56s PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2025-10-26 12:51:51,165 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,168 WARNING Service https://www.example.org not allowed. 56s PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2025-10-26 12:51:51,172 WARNING Service https://www.example.net not allowed. 56s PASSED 56s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2025-10-26 12:51:51,181 WARNING Service https://www.example.net not allowed. 56s PASSED 56s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2025-10-26 12:51:51,189 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,191 INFO logout requested 56s 2025-10-26 12:51:51,191 INFO Logging out user test. 56s 2025-10-26 12:51:51,194 INFO User test logged out 56s PASSED 56s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2025-10-26 12:51:51,210 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,215 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,221 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,227 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,232 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,237 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,243 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,249 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,254 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,260 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,262 INFO logout requested 56s 2025-10-26 12:51:51,262 INFO Logging out user test from all sessions. 56s 2025-10-26 12:51:51,280 INFO User test logged out 56s PASSED 56s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2025-10-26 12:51:51,286 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,289 INFO logout requested 56s 2025-10-26 12:51:51,289 INFO Logging out user test. 56s 2025-10-26 12:51:51,291 INFO User test logged out 56s PASSED 56s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2025-10-26 12:51:51,293 INFO logout requested 56s PASSED 56s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2025-10-26 12:51:51,300 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,306 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,308 INFO logout requested 56s 2025-10-26 12:51:51,308 INFO Logging out user test from all sessions. 56s 2025-10-26 12:51:51,312 INFO User test logged out 56s PASSED 56s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2025-10-26 12:51:51,319 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,324 INFO Service ticket created for service http://127.0.0.1:46767 by user test. 56s 2025-10-26 12:51:51,326 INFO Validate: Service ticket ST-T0yJO700hkXwM5h7vDiOkwbJmonqzDg400dBaeRMigOtmTgKnLINvAioAkDlM validated, user test authenticated on service http://127.0.0.1:46767 56s 2025-10-26 12:51:51,327 INFO logout requested 56s 2025-10-26 12:51:51,327 INFO Logging out user test. 56s 2025-10-26 12:51:51,329 INFO Sending SLO requests to service http://127.0.0.1:46767 for user test 56s 2025-10-26 12:51:51,331 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 56s 2025-10-26 12:51:51,332 INFO User test logged out 56s 2025-10-26 12:51:51,337 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,341 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 56s 2025-10-26 12:51:51,343 INFO Validate: Service ticket ST-UmxM9OzI69eHV0rhsUCBfCnWdowvxABG5ka5JJ8k3DLIjsD6t7hNjM2QFypIh validated, user test authenticated on service http://127.0.0.1:45678 56s 2025-10-26 12:51:51,344 INFO logout requested 56s 2025-10-26 12:51:51,345 INFO Logging out user test. 56s 2025-10-26 12:51:51,346 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 56s 2025-10-26 12:51:51,348 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 56s 2025-10-26 12:51:51,350 INFO User test logged out 56s 2025-10-26 12:51:51,354 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,358 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 56s 2025-10-26 12:51:51,360 INFO Validate: Service ticket ST-TiKDYxVitNzS9TyoAJLCVuO39O9hoIZmkt5z427OX1pvH90MtYTEYvrKZJByn validated, user test authenticated on service http://127.0.0.1:45678 56s 2025-10-26 12:51:51,361 INFO logout requested 56s 2025-10-26 12:51:51,362 INFO Logging out user test. 56s 2025-10-26 12:51:51,363 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 56s 2025-10-26 12:51:51,365 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=34097): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 56s 2025-10-26 12:51:51,365 INFO User test logged out 56s PASSED 56s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2025-10-26 12:51:51,372 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,377 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,381 INFO logout requested 56s 2025-10-26 12:51:51,382 INFO Logging out user test. 56s 2025-10-26 12:51:51,384 INFO User test logged out 56s PASSED 56s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2025-10-26 12:51:51,393 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,396 INFO logout requested 56s 2025-10-26 12:51:51,396 INFO Logging out user test. 56s 2025-10-26 12:51:51,398 INFO User test logged out 56s PASSED 56s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2025-10-26 12:51:51,406 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,408 INFO logout requested 56s 2025-10-26 12:51:51,409 INFO Logging out user test. 56s 2025-10-26 12:51:51,411 INFO User test logged out 56s PASSED 56s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2025-10-26 12:51:51,419 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,421 INFO logout requested 56s 2025-10-26 12:51:51,422 INFO Logging out user test. 56s 2025-10-26 12:51:51,424 INFO User test logged out 56s PASSED 56s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2025-10-26 12:51:51,430 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,433 INFO logout requested 56s 2025-10-26 12:51:51,433 INFO Logging out user test. 56s 2025-10-26 12:51:51,435 INFO User test logged out 56s 2025-10-26 12:51:51,439 INFO logout requested 56s PASSED 56s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 56s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 56s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice PASSED 56s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings 2025-10-26 12:51:51,449 WARNING Service https://www.example.org not allowed. 56s PASSED 56s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 56s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2025-10-26 12:51:51,459 INFO User test successfully authenticated 56s PASSED 56s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 56s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2025-10-26 12:51:51,471 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,475 INFO Service ticket created for service https://www.example.com by user test. 56s 2025-10-26 12:51:51,476 WARNING Validate: service or ticket missing 56s 2025-10-26 12:51:51,477 WARNING Validate: service or ticket missing 56s PASSED 56s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2025-10-26 12:51:51,482 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,485 INFO Service ticket created for service https://www.example.com by user test. 56s 2025-10-26 12:51:51,487 INFO Validate: Service ticket ST-mca2MeR3s2BPSvuSw2xHCkbw8yCsXRmnrsgJCrcH1fJgDNn0nIHzhRKKJs4kG validated, user test authenticated on service https://www.example.com 56s 2025-10-26 12:51:51,491 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,493 INFO Service ticket created for service https://www.example.com by user test. 56s 2025-10-26 12:51:51,495 INFO Validate: Service ticket ST-jPW3KmRbE1v0xbzfdiOT0oyW6RjfF5mUreUgQajhluKlB8CVvu1eAZKP83sqr validated, user test authenticated on service https://www.example.com 56s 2025-10-26 12:51:51,498 INFO Service ticket created for service https://www.example.com by user test. 56s 2025-10-26 12:51:51,499 WARNING Validate: Service ticket ST-vVHZfI77NZQxXPQbCwkoyVBTETnMTCNUH8i8CzaZPSZaPIyZJlzFAdDcctQ44 not found or already validated, auth to https://www.example.com failed 56s PASSED 56s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2025-10-26 12:51:51,505 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,509 INFO Service ticket created for service https://user_field.example.com by user test. 56s 2025-10-26 12:51:51,511 INFO Validate: Service ticket ST-jdjP89SOdAoWlfJCvTi4207ehmhpJNNEn6VrgMYGlsbkomtZnw7SbZPkqgbi0 validated, user test authenticated on service https://user_field.example.com 56s 2025-10-26 12:51:51,515 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,519 INFO Service ticket created for service https://user_field_alt.example.com by user test. 56s 2025-10-26 12:51:51,521 INFO Validate: Service ticket ST-yUqUZhmCMErQS2ug1OSnfrAYW4g7MRUsspCUtizAHFLeHNOUdJg7MBqgRZsVF validated, user test authenticated on service https://user_field_alt.example.com 56s PASSED 56s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2025-10-26 12:51:51,527 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,531 INFO Service ticket created for service https://www.example.com by user test. 56s 2025-10-26 12:51:51,533 WARNING Validate: Service ticket ST-fNjtKK2tCeC7hcPkZsSM6ouX0ab6p2x69ljEl916LMXs8XN80CYye4AudgASW not found or already validated, auth to https://www.example.org failed 56s PASSED 56s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2025-10-26 12:51:51,538 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,542 INFO Service ticket created for service https://www.example.com by user test. 56s 2025-10-26 12:51:51,543 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 56s PASSED 56s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2025-10-26 12:51:51,549 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,553 INFO Service ticket created for service https://www.example.com by user test. 56s 2025-10-26 12:51:51,555 INFO Validate: Service ticket ST-jjDvfWI0deV7V9nytbYEQON9zVL7xk5NLIbbFwv3NI2OuZvUkWznJ7FvZVxc5 validated, user test authenticated on service https://www.example.com 56s PASSED 56s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2025-10-26 12:51:51,562 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,566 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 56s 2025-10-26 12:51:51,567 WARNING ValidateService: missing ticket or service 56s 2025-10-26 12:51:51,568 WARNING ValidateService: missing ticket or service 56s PASSED 56s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2025-10-26 12:51:51,575 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,579 INFO Service ticket created for service http://127.0.0.1:41989 by user test. 56s 2025-10-26 12:51:51,583 INFO ValidateService: ticket ST-9Fk3UKZDYWfzv8EjCkdh2kPH2kXcIV2ZRh72Dv0GoCToskzgQYcJraAXYvbjd validated for user test on service http://127.0.0.1:41989. Proxy Granting Ticket transmited to http://127.0.0.1:41989. 56s 2025-10-26 12:51:51,587 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 56s 2025-10-26 12:51:51,589 INFO ValidateService: ticket PT-on1cxenSSRekcFINCT0GCTtZvg0xkzuMDd9zfnIJKXrwyREOco4Z3QaaGTq8T validated for user test on service http://127.0.0.1:45678. 56s 2025-10-26 12:51:51,593 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,597 INFO Service ticket created for service http://127.0.0.1:37553 by user test. 56s 2025-10-26 12:51:51,602 INFO ValidateService: ticket ST-uzvD65hdSuEA4egjWKaR1cJ8UvhR810Tof0q0DjMFx0ovPYJMkqdUx9Cl2dmK validated for user test on service http://127.0.0.1:37553. Proxy Granting Ticket transmited to http://127.0.0.1:37553. 56s 2025-10-26 12:51:51,605 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 56s 2025-10-26 12:51:51,605 WARNING ValidateService: validation error: INVALID_TICKET PT-WG2pa2DnqayCyYuPYX9wgZvpDYkJtxOrJo1II3RbF1arWEEKvWCzw89zMcqqA 56s PASSED 56s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2025-10-26 12:51:51,612 INFO User test successfully authenticated 56s 2025-10-26 12:51:51,616 INFO Service ticket created for service https://replace_attribute.example.com by user test. 56s 2025-10-26 12:51:51,618 INFO ValidateService: ticket ST-HTBYqdP08aZa95PxI6ko2zuwpFOX8IligaDjXOBE3DX9yFu4L1ptDYdQUIWoQ validated for user test on service https://replace_attribute.example.com. 57s 2025-10-26 12:51:51,622 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,626 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 57s 2025-10-26 12:51:51,629 INFO ValidateService: ticket ST-EBxkUAj13FtGgldhY0XSpoQE8BJkHuQbVrT9pqHuDLC2L0RzWDAfBE6vQPhsl validated for user test on service https://replace_attribute_list.example.com. 57s PASSED 57s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2025-10-26 12:51:51,635 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,639 INFO Service ticket created for service http://127.0.0.1:33471 by user test. 57s 2025-10-26 12:51:51,643 INFO ValidateService: ticket ST-zqO8AHahs23eRaFm7t7UeTVpj07rdPB5Kmi1cdl1KEzjT8qSjdFgElrx0LIeo validated for user test on service http://127.0.0.1:33471. Proxy Granting Ticket transmited to http://127.0.0.1:33471. 57s PASSED 57s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2025-10-26 12:51:51,650 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,654 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 57s 2025-10-26 12:51:51,657 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 57s 2025-10-26 12:51:51,660 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,664 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 57s 2025-10-26 12:51:51,667 WARNING Service https://www.example.org not allowed. 57s 2025-10-26 12:51:51,667 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 57s PASSED 57s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2025-10-26 12:51:51,673 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,677 INFO Service ticket created for service https://127.0.0.1:46445 by user test. 57s 2025-10-26 12:51:51,681 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=46445): Max retries exceeded with url: /?pgtId=PGT-GBhXsu3xFXqzxyKBXkXuTQAIbLkJT95v8yaFs7kKF6DY8QOtwZTEhvDz8eEJ&pgtIou=PGTIOU-EKp3VfhlrGJYmGvQXjP6sW45BpxH9OQb2VMtQNSAS2vg0DUK2RZpx94Gj (Caused by SSLError(SSLError(1, '[SSL: RECORD_LAYER_FAILURE] record layer failure (_ssl.c:1077)'))) 57s PASSED 57s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2025-10-26 12:51:51,688 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,690 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 57s 2025-10-26 12:51:51,692 INFO ValidateService: ticket ST-Jn2llRcuJZEKBOelbxdHDBjUCVccoHqB0Bwqbjo9JH0O1pqMD8Iu22Yo11fTB validated for user test on service http://127.0.0.1:45678. 57s 2025-10-26 12:51:51,696 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,698 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 57s 2025-10-26 12:51:51,700 INFO ValidateService: ticket ST-9VgW2Tdb7QhI6ImpY2HSx7rsNUr0RxtITwyi5l9VnzctqeaTrjcI00S0TfmEx validated for user test on service http://127.0.0.1:45678. 57s 2025-10-26 12:51:51,703 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 57s PASSED 57s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2025-10-26 12:51:51,704 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 57s 2025-10-26 12:51:51,710 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,714 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 57s 2025-10-26 12:51:51,715 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 57s PASSED 57s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2025-10-26 12:51:51,721 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,726 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 57s 2025-10-26 12:51:51,727 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 57s PASSED 57s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2025-10-26 12:51:51,733 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,737 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 57s 2025-10-26 12:51:51,738 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 57s PASSED 57s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2025-10-26 12:51:51,744 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,749 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 57s 2025-10-26 12:51:51,751 INFO ValidateService: ticket ST-8YDUHWbKj62EnATuYWvpla58cIIqytmxDGFTA6ajXed7O1ePSay6o82Y9ac9v validated for user test on service http://127.0.0.1:45678. 57s PASSED 57s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2025-10-26 12:51:51,757 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,761 INFO Service ticket created for service https://one_attribute.example.com by user test. 57s 2025-10-26 12:51:51,763 INFO ValidateService: ticket ST-GE0TX5zsNZqqvoU0ntve5NzVHrqafcXr5GHobqGiplqIjHJal0tlkkX7EtESD validated for user test on service https://one_attribute.example.com. 57s PASSED 57s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2025-10-26 12:51:51,769 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,774 INFO Service ticket created for service http://127.0.0.1:39481 by user test. 57s 2025-10-26 12:51:51,778 INFO ValidateService: ticket ST-4Dcc7xgsEZVgMLuIeptIWNoUAl9EqD4gEWvs0nlGXz7zhStayfP7GYIh5s6sl validated for user test on service http://127.0.0.1:39481. Proxy Granting Ticket transmited to http://127.0.0.1:39481. 57s PASSED 57s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2025-10-26 12:51:51,784 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,788 INFO Service ticket created for service https://user_field.example.com by user test. 57s 2025-10-26 12:51:51,790 INFO ValidateService: ticket ST-Fy5u2Td19OrG4YVFm01lGsrDK6pjVKbnicVo6G4rxl9WcVs0JTJnmbUAsv2eE validated for user test on service https://user_field.example.com. 57s 2025-10-26 12:51:51,794 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,799 INFO Service ticket created for service https://user_field_alt.example.com by user test. 57s 2025-10-26 12:51:51,801 INFO ValidateService: ticket ST-2sxOLQIsYwD2xCsemunnmwpiOByoL1htPm0FOGHRrhYT8fOqd04NwpiDbRnbs validated for user test on service https://user_field_alt.example.com. 57s PASSED 57s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2025-10-26 12:51:51,809 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,813 INFO Service ticket created for service http://127.0.0.1:35621 by user test. 57s 2025-10-26 12:51:51,817 INFO ValidateService: ticket ST-95pZ84qicbPcyc883c6ypRke2G92W3bAgCf3mTcasb3UeDDZalIDnExe4jin4 validated for user test on service http://127.0.0.1:35621. Proxy Granting Ticket transmited to http://127.0.0.1:35621. 57s 2025-10-26 12:51:51,818 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 57s 2025-10-26 12:51:51,818 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 57s PASSED 57s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2025-10-26 12:51:51,826 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,830 INFO Service ticket created for service http://127.0.0.1:34361 by user test. 57s 2025-10-26 12:51:51,834 INFO ValidateService: ticket ST-bVG6NUTt5O2SQwFj6o2WgZxUyi2amxilP84xLtFmHh157LDkUUcU5odJWBJYG validated for user test on service http://127.0.0.1:34361. Proxy Granting Ticket transmited to http://127.0.0.1:34361. 57s 2025-10-26 12:51:51,836 WARNING Username test not allowed on service restrict_user_fail 57s 2025-10-26 12:51:51,836 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 57s 2025-10-26 12:51:51,837 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 57s 2025-10-26 12:51:51,838 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 57s 2025-10-26 12:51:51,839 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 57s 2025-10-26 12:51:51,839 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 57s PASSED 57s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2025-10-26 12:51:51,846 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,850 INFO Service ticket created for service http://127.0.0.1:36257 by user test. 57s 2025-10-26 12:51:51,855 INFO ValidateService: ticket ST-HuR4a3z9S2MsszkcXK4ugAz70zKLIm0lWO7zMR88vb6i0vt3VdjyvzlE4jn56 validated for user test on service http://127.0.0.1:36257. Proxy Granting Ticket transmited to http://127.0.0.1:36257. 57s 2025-10-26 12:51:51,856 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 57s PASSED 57s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2025-10-26 12:51:51,863 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,867 INFO Service ticket created for service http://127.0.0.1:46459 by user test. 57s 2025-10-26 12:51:51,871 INFO ValidateService: ticket ST-ncgNdX1fUwPaTEb19b9NME3n3PRctBfJ9V62EJTOCnVspnPvBu4g0upPUvtj5 validated for user test on service http://127.0.0.1:46459. Proxy Granting Ticket transmited to http://127.0.0.1:46459. 57s 2025-10-26 12:51:51,872 WARNING Service https://www.example.org not allowed. 57s 2025-10-26 12:51:51,872 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 57s 2025-10-26 12:51:51,873 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:46459 does not allow proxy tickets 57s PASSED 57s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2025-10-26 12:51:51,881 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,885 INFO Service ticket created for service http://127.0.0.1:34875 by user test. 57s 2025-10-26 12:51:51,889 INFO ValidateService: ticket ST-mjWgY0rhTXsbtwiEcy0vvBvvSlwuuglrXbvVaIX74JICcwMcWQoBeV7JD1DIs validated for user test on service http://127.0.0.1:34875. Proxy Granting Ticket transmited to http://127.0.0.1:34875. 57s 2025-10-26 12:51:51,892 INFO Proxy ticket created for user test on service https://www.example.com. 57s 2025-10-26 12:51:51,894 INFO ValidateService: ticket PT-cFeQ0jXiXh8dDPXJauPJOilZpyRSBo8VLbzhXQRmcuzxdPGxAChiYcdT7RQPX validated for user test on service https://www.example.com. 57s PASSED 57s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2025-10-26 12:51:51,901 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,906 INFO Service ticket created for service https://www.example.com by user test. 57s 2025-10-26 12:51:51,908 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 57s PASSED 57s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2025-10-26 12:51:51,912 WARNING SamlValidate: validation error: AuthnFailed ticket ST-Uz4AEmLy3WXYQ5a1cLvFnfWOVMl78aDdaxX8VrvdUB8TIUmgG9daPSfznwzAM not found 57s 2025-10-26 12:51:51,913 WARNING SamlValidate: validation error: AuthnFailed ticket PT-LOLm8rs8WZGRcy2lbdxrHfzmbVfVUgcXae7TXm8tvwk6ohKhVMzA5QQZcfO3U not found 57s PASSED 57s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix 2025-10-26 12:51:51,916 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 57s PASSED 57s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml 2025-10-26 12:51:51,919 WARNING SamlValidate: validation error: VersionMismatch 57s PASSED 57s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2025-10-26 12:51:51,926 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,930 INFO Service ticket created for service https://www.example.com by user test. 57s 2025-10-26 12:51:51,935 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,940 INFO Service ticket created for service http://127.0.0.1:39939 by user test. 57s 2025-10-26 12:51:51,944 INFO ValidateService: ticket ST-KdWHjWT5CFZ2aN1tF1BetSpI87yRApUMVqoYGbV0VObRq8bvkGPQiJwsH9Kk0 validated for user test on service http://127.0.0.1:39939. Proxy Granting Ticket transmited to http://127.0.0.1:39939. 57s 2025-10-26 12:51:51,947 INFO Proxy ticket created for user test on service https://www.example.com. 57s 2025-10-26 12:51:51,949 INFO SamlValidate: ticket ST-2KvS5eDZNDHuCz69xhtVclVAN5vaJBZwQIIgw0jO81nIqzR9Qp8nmPsIeuIOe validated for user test on service https://www.example.com. 57s 2025-10-26 12:51:51,951 INFO SamlValidate: ticket PT-C5W7UOIDNL5KyAlMsJTjXHLZUQ4b3QQe17749l4Ek2Ofe0VVsh1AWJrkAOVve validated for user test on service https://www.example.com. 57s PASSED 57s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2025-10-26 12:51:51,958 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,962 INFO Service ticket created for service https://field_needed_success.example.com by user test. 57s 2025-10-26 12:51:51,964 INFO SamlValidate: ticket ST-CQn2gipE8iEob6uq52BYZVV6kPgKKdKpBF90boYmDgk4Vk4WORRSIzRFklzyh validated for user test on service https://field_needed_success.example.com. 57s 2025-10-26 12:51:51,969 INFO User test successfully authenticated 57s 2025-10-26 12:51:51,973 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 57s 2025-10-26 12:51:51,976 INFO SamlValidate: ticket ST-6NJ6PrjKyUTOO24MPDJ93XEc754ipvjRO1QEJCY1iEEaImSuk89tWbW0zVbVM validated for user test on service https://field_needed_success_alt.example.com. 57s PASSEDDestroying test database for alias 'default'... 57s 57s 57s =============================== warnings summary =============================== 57s cas_server/tests/test_federate.py: 8 warnings 57s cas_server/tests/test_view.py: 3 warnings 57s /tmp/autopkgtest.ZIY8bQ/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). 57s datetime.utcnow() + timedelta(seconds=max_age), 57s 57s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 57s /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') 57s return self.run(*args, **kwds) 57s 57s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 57s ======================= 128 passed, 12 warnings in 3.49s ======================= 57s 57s *************************** 57s *** Testing with python3.13 57s *************************** 57s 57s Content of current working folder: 57s 57s total 20 57s drwxr-xr-x 3 ubuntu ubuntu 4096 Oct 26 12:51 . 57s drwxrwxrwx 5 root root 4096 Oct 26 12:51 .. 57s drwxrwxr-x 10 ubuntu ubuntu 4096 Oct 26 12:51 cas_server 57s -rw-rw-r-- 1 ubuntu ubuntu 140 Aug 18 2024 pytest.ini 57s -rw-rw-r-- 1 ubuntu ubuntu 152 Aug 18 2024 setup.cfg 57s Running tests... 57s 57s ============================= test session starts ============================== 57s platform linux -- Python 3.13.9, pytest-8.3.5, pluggy-1.6.0 -- /usr/bin/python3.13 57s cachedir: .pytest_cache 57s django: version: 5.2.4, settings: cas_server.tests.settings (from ini) 57s rootdir: /tmp/autopkgtest.ZIY8bQ/autopkgtest_tmp 57s configfile: pytest.ini 57s testpaths: cas_server/tests/ 57s plugins: django-4.11.1, typeguard-4.4.2 57s collecting ... collected 128 items 57s 57s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 58s 2025-10-26 12:51:52,795 WARNING Identity provider suffix exemple.fr not found 58s 2025-10-26 12:51:52,803 INFO Got an invalid ticket ST-q40Tr5GWwryqfSFjUefTwjcKUoT359nIUwteYXDOu2Mr25Z8Ldb1hzCVDlgNv from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 58s 2025-10-26 12:51:52,805 INFO Got an invalid ticket ST-CzNCB0uqd7VXOToKL6mnJC8f6JCMDyTMBzkRdDkYeHa2l5TVDfHjSYts9woYG from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 58s PASSED 58s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2025-10-26 12:51:52,823 WARNING CAS_FEDERATE is False, set it to True to use federation 58s PASSED 58s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2025-10-26 12:51:52,824 WARNING CAS_FEDERATE is False, set it to True to use federation 58s 2025-10-26 12:51:52,832 INFO Trying to authenticate http://127.0.0.1:8080 again 58s 2025-10-26 12:51:52,835 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 58s 2025-10-26 12:51:52,843 INFO User dédé@example.com successfully authenticated 58s 2025-10-26 12:51:52,850 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 58s 2025-10-26 12:51:52,856 INFO Trying to authenticate http://127.0.0.1:8081 again 58s 2025-10-26 12:51:52,859 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 58s 2025-10-26 12:51:52,865 INFO User dédé@example.org successfully authenticated 58s 2025-10-26 12:51:52,870 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 58s 2025-10-26 12:51:52,876 INFO Trying to authenticate http://127.0.0.1:8082 again 58s 2025-10-26 12:51:52,878 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 58s 2025-10-26 12:51:52,884 INFO User dédé@example.net successfully authenticated 58s 2025-10-26 12:51:52,889 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 58s 2025-10-26 12:51:52,895 INFO Trying to authenticate http://127.0.0.1:8083 again 58s 2025-10-26 12:51:52,898 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 58s 2025-10-26 12:51:52,903 INFO User dédé@example.test successfully authenticated 58s 2025-10-26 12:51:52,909 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 58s 2025-10-26 12:51:52,929 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-hQmOA8p9UfUC8rcdWMUsdlv9YNVqmKyLPsfrAofTRUysrT0LMpPHjaHo3mGaC 58s 2025-10-26 12:51:52,943 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-C0lMOGBAphtnb29YO12VrBAhwhQUIJhHgpg6vLBlXJU3jclTHo54rI8z2G1Sp 58s PASSED 58s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 58s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2025-10-26 12:51:52,961 INFO Trying to authenticate http://127.0.0.1:8080 again 58s 2025-10-26 12:51:52,964 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 58s 2025-10-26 12:51:52,969 INFO User dédé@example.com successfully authenticated 58s 2025-10-26 12:51:52,974 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 58s 2025-10-26 12:51:52,982 INFO Trying to authenticate http://127.0.0.1:8081 again 58s 2025-10-26 12:51:52,985 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 58s 2025-10-26 12:51:52,990 INFO User dédé@example.org successfully authenticated 58s 2025-10-26 12:51:52,995 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 58s 2025-10-26 12:51:53,001 INFO Trying to authenticate http://127.0.0.1:8082 again 58s 2025-10-26 12:51:53,004 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 58s 2025-10-26 12:51:53,009 INFO User dédé@example.net successfully authenticated 58s 2025-10-26 12:51:53,014 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 58s 2025-10-26 12:51:53,020 INFO Trying to authenticate http://127.0.0.1:8083 again 58s 2025-10-26 12:51:53,024 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 58s 2025-10-26 12:51:53,029 INFO User dédé@example.test successfully authenticated 58s 2025-10-26 12:51:53,034 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 58s 2025-10-26 12:51:53,034 INFO logout requested 58s 2025-10-26 12:51:53,035 INFO Logging out user dédé@example.com. 58s 2025-10-26 12:51:53,038 INFO User dédé@example.com logged out 58s 2025-10-26 12:51:53,042 INFO logout requested 58s 2025-10-26 12:51:53,044 INFO logout requested 58s 2025-10-26 12:51:53,045 INFO Logging out user dédé@example.org. 58s 2025-10-26 12:51:53,048 INFO User dédé@example.org logged out 58s 2025-10-26 12:51:53,052 INFO logout requested 58s 2025-10-26 12:51:53,054 INFO logout requested 58s 2025-10-26 12:51:53,055 INFO Logging out user dédé@example.net. 58s 2025-10-26 12:51:53,057 INFO User dédé@example.net logged out 58s 2025-10-26 12:51:53,061 INFO logout requested 58s 2025-10-26 12:51:53,063 INFO logout requested 58s 2025-10-26 12:51:53,064 INFO Logging out user dédé@example.test. 58s 2025-10-26 12:51:53,066 INFO User dédé@example.test logged out 58s 2025-10-26 12:51:53,071 INFO logout requested 58s 2025-10-26 12:51:53,077 INFO Trying to authenticate http://127.0.0.1:8080 again 58s 2025-10-26 12:51:53,080 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 58s 2025-10-26 12:51:53,086 INFO User dédé@example.com successfully authenticated 58s 2025-10-26 12:51:53,091 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 58s 2025-10-26 12:51:53,097 INFO Trying to authenticate http://127.0.0.1:8081 again 58s 2025-10-26 12:51:53,100 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 58s 2025-10-26 12:51:53,105 INFO User dédé@example.org successfully authenticated 58s 2025-10-26 12:51:53,111 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 58s 2025-10-26 12:51:53,116 INFO Trying to authenticate http://127.0.0.1:8082 again 58s 2025-10-26 12:51:53,119 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 58s 2025-10-26 12:51:53,125 INFO User dédé@example.net successfully authenticated 58s 2025-10-26 12:51:53,130 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 58s 2025-10-26 12:51:53,135 INFO Trying to authenticate http://127.0.0.1:8083 again 58s 2025-10-26 12:51:53,138 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 58s 2025-10-26 12:51:53,144 INFO User dédé@example.test successfully authenticated 58s 2025-10-26 12:51:53,149 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 58s 2025-10-26 12:51:53,151 INFO logout requested 58s 2025-10-26 12:51:53,151 INFO Logging out user dédé. 58s 2025-10-26 12:51:53,152 INFO User dédé logged out 58s 2025-10-26 12:51:53,157 INFO logout requested 58s 2025-10-26 12:51:53,157 INFO Logging out user dédé. 58s 2025-10-26 12:51:53,158 INFO User dédé logged out 58s 2025-10-26 12:51:53,163 INFO logout requested 58s 2025-10-26 12:51:53,163 INFO Logging out user dédé. 58s 2025-10-26 12:51:53,164 INFO User dédé logged out 58s 2025-10-26 12:51:53,169 INFO logout requested 58s 2025-10-26 12:51:53,170 INFO Logging out user dédé. 58s 2025-10-26 12:51:53,170 INFO User dédé logged out 58s PASSED 58s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2025-10-26 12:51:53,184 INFO Trying to authenticate http://127.0.0.1:8080 again 58s 2025-10-26 12:51:53,186 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 58s 2025-10-26 12:51:53,192 INFO User dédé@example.com successfully authenticated 58s 2025-10-26 12:51:53,197 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 58s 2025-10-26 12:51:53,203 INFO Trying to authenticate http://127.0.0.1:8081 again 58s 2025-10-26 12:51:53,206 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 58s 2025-10-26 12:51:53,211 INFO User dédé@example.org successfully authenticated 58s 2025-10-26 12:51:53,216 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 58s 2025-10-26 12:51:53,223 INFO Trying to authenticate http://127.0.0.1:8082 again 58s 2025-10-26 12:51:53,226 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 58s 2025-10-26 12:51:53,231 INFO User dédé@example.net successfully authenticated 58s 2025-10-26 12:51:53,236 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 58s 2025-10-26 12:51:53,269 INFO Trying to authenticate http://127.0.0.1:8083 again 58s 2025-10-26 12:51:53,273 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 58s 2025-10-26 12:51:53,278 INFO User dédé@example.test successfully authenticated 58s 2025-10-26 12:51:53,283 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 58s 2025-10-26 12:51:53,284 INFO logout requested 58s 2025-10-26 12:51:53,285 INFO Logging out user dédé@example.com. 58s 2025-10-26 12:51:53,287 INFO User dédé@example.com logged out 58s 2025-10-26 12:51:53,288 INFO logout requested 58s 2025-10-26 12:51:53,289 INFO Logging out user dédé@example.org. 58s 2025-10-26 12:51:53,291 INFO User dédé@example.org logged out 58s 2025-10-26 12:51:53,292 INFO logout requested 58s 2025-10-26 12:51:53,292 INFO Logging out user dédé@example.net. 58s 2025-10-26 12:51:53,295 INFO User dédé@example.net logged out 58s 2025-10-26 12:51:53,295 INFO logout requested 58s 2025-10-26 12:51:53,296 INFO Logging out user dédé@example.test. 58s 2025-10-26 12:51:53,299 INFO User dédé@example.test logged out 58s PASSED 58s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2025-10-26 12:51:53,306 WARNING A login attempt failed 58s PASSED 58s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 58s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2025-10-26 12:51:53,324 INFO Trying to authenticate http://127.0.0.1:8080 again 58s 2025-10-26 12:51:53,326 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 58s 2025-10-26 12:51:53,332 INFO User dédé@example.com successfully authenticated 58s 2025-10-26 12:51:53,337 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 58s 2025-10-26 12:51:53,343 INFO Trying to authenticate http://127.0.0.1:8081 again 58s 2025-10-26 12:51:53,346 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 58s 2025-10-26 12:51:53,351 INFO User dédé@example.org successfully authenticated 58s 2025-10-26 12:51:53,356 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 58s 2025-10-26 12:51:53,362 INFO Trying to authenticate http://127.0.0.1:8082 again 58s 2025-10-26 12:51:53,365 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 58s 2025-10-26 12:51:53,370 INFO User dédé@example.net successfully authenticated 58s 2025-10-26 12:51:53,376 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 58s 2025-10-26 12:51:53,381 INFO Trying to authenticate http://127.0.0.1:8083 again 58s 2025-10-26 12:51:53,384 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 58s 2025-10-26 12:51:53,390 INFO User dédé@example.test successfully authenticated 58s PASSED 58s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2025-10-26 12:51:53,395 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 58s 2025-10-26 12:51:53,405 INFO Trying to authenticate http://127.0.0.1:8080 again 58s 2025-10-26 12:51:53,407 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 58s 2025-10-26 12:51:53,413 INFO User dédé@example.com successfully authenticated 58s 2025-10-26 12:51:53,418 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 58s 2025-10-26 12:51:53,424 INFO Trying to authenticate http://127.0.0.1:8081 again 58s 2025-10-26 12:51:53,427 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 58s 2025-10-26 12:51:53,432 INFO User dédé@example.org successfully authenticated 58s 2025-10-26 12:51:53,438 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 58s 2025-10-26 12:51:53,443 INFO Trying to authenticate http://127.0.0.1:8082 again 58s 2025-10-26 12:51:53,446 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 58s 2025-10-26 12:51:53,452 INFO User dédé@example.net successfully authenticated 58s 2025-10-26 12:51:53,457 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 58s 2025-10-26 12:51:53,462 INFO Trying to authenticate http://127.0.0.1:8083 again 58s 2025-10-26 12:51:53,465 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 58s 2025-10-26 12:51:53,471 INFO User dédé@example.test successfully authenticated 58s 2025-10-26 12:51:53,476 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 58s 2025-10-26 12:51:53,481 INFO Trying to authenticate http://127.0.0.1:8080 again 58s 2025-10-26 12:51:53,484 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 58s 2025-10-26 12:51:53,490 INFO User dédé@example.com successfully authenticated 58s 2025-10-26 12:51:53,495 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 58s 2025-10-26 12:51:53,501 INFO Trying to authenticate http://127.0.0.1:8081 again 58s 2025-10-26 12:51:53,504 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 58s 2025-10-26 12:51:53,511 INFO User dédé@example.org successfully authenticated 58s 2025-10-26 12:51:53,516 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 58s 2025-10-26 12:51:53,521 INFO Trying to authenticate http://127.0.0.1:8082 again 58s 2025-10-26 12:51:53,524 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 58s 2025-10-26 12:51:53,530 INFO User dédé@example.net successfully authenticated 58s 2025-10-26 12:51:53,535 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 58s 2025-10-26 12:51:53,541 INFO Trying to authenticate http://127.0.0.1:8083 again 58s 2025-10-26 12:51:53,544 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 58s 2025-10-26 12:51:53,550 INFO User dédé@example.test successfully authenticated 58s 2025-10-26 12:51:53,555 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 58s 2025-10-26 12:51:53,556 WARNING User already authenticated, dropping federated authentication request 58s 2025-10-26 12:51:53,557 WARNING User already authenticated, dropping federated authentication request 58s 2025-10-26 12:51:53,557 WARNING User already authenticated, dropping federated authentication request 58s 2025-10-26 12:51:53,558 WARNING User already authenticated, dropping federated authentication request 58s PASSED 58s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2025-10-26 12:51:53,567 INFO Trying to authenticate http://127.0.0.1:8080 again 58s 2025-10-26 12:51:53,570 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 58s 2025-10-26 12:51:53,576 INFO User dédé@example.com successfully authenticated 58s 2025-10-26 12:51:53,581 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 58s 2025-10-26 12:51:53,587 INFO Trying to authenticate http://127.0.0.1:8081 again 58s 2025-10-26 12:51:53,590 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 58s 2025-10-26 12:51:53,596 INFO User dédé@example.org successfully authenticated 58s 2025-10-26 12:51:53,602 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 58s 2025-10-26 12:51:53,608 INFO Trying to authenticate http://127.0.0.1:8082 again 58s 2025-10-26 12:51:53,611 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 58s 2025-10-26 12:51:53,617 INFO User dédé@example.net successfully authenticated 58s 2025-10-26 12:51:53,622 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 59s 2025-10-26 12:51:53,628 INFO Trying to authenticate http://127.0.0.1:8083 again 59s 2025-10-26 12:51:53,631 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 59s 2025-10-26 12:51:53,637 INFO User dédé@example.test successfully authenticated 59s 2025-10-26 12:51:53,642 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 59s 2025-10-26 12:51:53,643 INFO logout requested 59s 2025-10-26 12:51:53,644 INFO Logging out user dédé@example.com. 59s 2025-10-26 12:51:53,647 INFO User dédé@example.com logged out 59s 2025-10-26 12:51:53,649 INFO logout requested 59s 2025-10-26 12:51:53,650 INFO Logging out user dédé@example.org. 59s 2025-10-26 12:51:53,652 INFO User dédé@example.org logged out 59s 2025-10-26 12:51:53,654 INFO logout requested 59s 2025-10-26 12:51:53,655 INFO Logging out user dédé@example.net. 59s 2025-10-26 12:51:53,657 INFO User dédé@example.net logged out 59s 2025-10-26 12:51:53,659 INFO logout requested 59s 2025-10-26 12:51:53,660 INFO Logging out user dédé@example.test. 59s 2025-10-26 12:51:53,663 INFO User dédé@example.test logged out 59s PASSED 59s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2025-10-26 12:51:53,673 INFO Trying to authenticate http://127.0.0.1:8080 again 59s 2025-10-26 12:51:53,676 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 59s 2025-10-26 12:51:53,681 INFO User dédé@example.com successfully authenticated 59s 2025-10-26 12:51:53,687 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 59s 2025-10-26 12:51:53,693 INFO Trying to authenticate http://127.0.0.1:8081 again 59s 2025-10-26 12:51:53,696 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 59s 2025-10-26 12:51:53,701 INFO User dédé@example.org successfully authenticated 59s 2025-10-26 12:51:53,706 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 59s 2025-10-26 12:51:53,712 INFO Trying to authenticate http://127.0.0.1:8082 again 59s 2025-10-26 12:51:53,715 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 59s 2025-10-26 12:51:53,720 INFO User dédé@example.net successfully authenticated 59s 2025-10-26 12:51:53,726 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 59s 2025-10-26 12:51:53,732 INFO Trying to authenticate http://127.0.0.1:8083 again 59s 2025-10-26 12:51:53,735 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 59s 2025-10-26 12:51:53,740 INFO User dédé@example.test successfully authenticated 59s 2025-10-26 12:51:53,745 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 59s 2025-10-26 12:51:53,748 INFO Trying to authenticate http://127.0.0.1:8080 again 59s 2025-10-26 12:51:53,751 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 59s 2025-10-26 12:51:53,757 INFO User dédé@example.com successfully authenticated 59s 2025-10-26 12:51:53,759 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 59s 2025-10-26 12:51:53,762 INFO Trying to authenticate http://127.0.0.1:8081 again 59s 2025-10-26 12:51:53,766 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 59s 2025-10-26 12:51:53,771 INFO User dédé@example.org successfully authenticated 59s 2025-10-26 12:51:53,774 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 59s 2025-10-26 12:51:53,777 INFO Trying to authenticate http://127.0.0.1:8082 again 59s 2025-10-26 12:51:53,780 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 59s 2025-10-26 12:51:53,788 INFO User dédé@example.net successfully authenticated 59s 2025-10-26 12:51:53,790 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 59s 2025-10-26 12:51:53,793 INFO Trying to authenticate http://127.0.0.1:8083 again 59s 2025-10-26 12:51:53,796 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 59s 2025-10-26 12:51:53,802 INFO User dédé@example.test successfully authenticated 59s 2025-10-26 12:51:53,804 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 59s PASSED 59s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 59s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 59s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 59s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2025-10-26 12:51:53,828 INFO User test successfully authenticated 59s PASSED 59s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2025-10-26 12:51:53,837 INFO User test successfully authenticated 59s PASSED 59s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2025-10-26 12:51:53,845 INFO User test successfully authenticated 59s 2025-10-26 12:51:53,851 INFO User test successfully authenticated 59s 2025-10-26 12:51:53,857 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 59s 2025-10-26 12:51:53,859 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')) 59s PASSED 59s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2025-10-26 12:51:53,866 INFO User test successfully authenticated 59s PASSED 59s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2025-10-26 12:51:53,876 INFO User test successfully authenticated 59s PASSED 59s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2025-10-26 12:51:53,886 INFO User test successfully authenticated 59s PASSED 59s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2025-10-26 12:51:53,893 INFO Sending SLO requests to service http://127.0.0.1:46023 for user test 59s 2025-10-26 12:51:53,895 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 59s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 59s 2025-10-26 12:51:53,901 INFO User test successfully authenticated 59s PASSED 59s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 59s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 59s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 59s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 59s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 59s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 59s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 59s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 59s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 59s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 59s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 59s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 59s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 59s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 59s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 59s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 59s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 59s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 59s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 59s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 59s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 59s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2025-10-26 12:51:54,036 INFO User test successfully authenticated 59s 2025-10-26 12:51:54,039 WARNING Service https://www.example.org not allowed. 59s PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success 2025-10-26 12:51:54,046 INFO User test successfully authenticated 59s 2025-10-26 12:51:54,049 INFO Service ticket created for service https://www.example.com by user test. 59s PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt 2025-10-26 12:51:54,056 INFO User test successfully authenticated 59s 2025-10-26 12:51:54,060 INFO Service ticket created for service https://www.example.com by user test. 59s PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn 2025-10-26 12:51:54,066 INFO User test successfully authenticated 59s PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged 2025-10-26 12:51:54,076 INFO User test successfully authenticated 59s PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2025-10-26 12:51:54,085 INFO User test successfully authenticated 59s 2025-10-26 12:51:54,088 WARNING User test seems authenticated but is not found in the database. 59s 2025-10-26 12:51:54,089 INFO Logging out user test. 59s 2025-10-26 12:51:54,089 INFO User test logged out 59s PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2025-10-26 12:51:54,099 INFO User test successfully authenticated 59s 2025-10-26 12:51:54,102 WARNING Username test not allowed on service restrict_user_fail 59s PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2025-10-26 12:51:54,110 WARNING A login attempt failed 59s 2025-10-26 12:51:54,117 WARNING A login attempt failed 59s 2025-10-26 12:51:54,124 WARNING A login attempt failed 59s PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2025-10-26 12:51:54,134 WARNING Received an invalid login ticket 59s PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2025-10-26 12:51:54,144 WARNING A login attempt failed 59s PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2025-10-26 12:51:54,154 INFO User test successfully authenticated 59s PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2025-10-26 12:51:54,163 INFO User test successfully authenticated 59s PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 59s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_renew 2025-10-26 12:51:54,632 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,638 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,640 INFO Service ticket created for service https://www.example.com by user test. 60s PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2025-10-26 12:51:54,647 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,653 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,655 INFO Service ticket created for service https://www.example.com by user test. 60s PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2025-10-26 12:51:54,662 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,665 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 60s 2025-10-26 12:51:54,667 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 60s 2025-10-26 12:51:54,670 INFO Service ticket created for service https://filter_success.example.com by user test. 60s PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2025-10-26 12:51:54,677 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,680 WARNING Username test not allowed on service restrict_user_fail 60s PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2025-10-26 12:51:54,683 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 60s 2025-10-26 12:51:54,690 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,693 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 60s PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2025-10-26 12:51:54,696 INFO Service ticket created for service https://field_needed_success.example.com by user test. 60s 2025-10-26 12:51:54,703 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,706 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 60s PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2025-10-26 12:51:54,713 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,717 WARNING User test seems authenticated but is not found in the database. 60s 2025-10-26 12:51:54,717 INFO Logging out user test. 60s 2025-10-26 12:51:54,718 INFO User test logged out 60s PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2025-10-26 12:51:54,735 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,739 INFO Service ticket created for service https://www.example.com/é by user test. 60s PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2025-10-26 12:51:54,746 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,750 INFO Service ticket created for service https://www.example.com by user test. 60s PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2025-10-26 12:51:54,758 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,764 WARNING Received a login attempt for an already-active user 60s 2025-10-26 12:51:54,766 INFO Service ticket created for service https://www.example.com by user test. 60s PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2025-10-26 12:51:54,773 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,776 WARNING Service https://www.example.org not allowed. 60s PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2025-10-26 12:51:54,780 WARNING Service https://www.example.net not allowed. 60s PASSED 60s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2025-10-26 12:51:54,785 WARNING Service https://www.example.net not allowed. 60s PASSED 60s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2025-10-26 12:51:54,793 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,795 INFO logout requested 60s 2025-10-26 12:51:54,796 INFO Logging out user test. 60s 2025-10-26 12:51:54,798 INFO User test logged out 60s PASSED 60s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2025-10-26 12:51:54,803 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,809 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,814 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,820 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,825 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,831 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,836 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,838 INFO logout requested 60s 2025-10-26 12:51:54,839 INFO Logging out user test from all sessions. 60s 2025-10-26 12:51:54,851 INFO User test logged out 60s PASSED 60s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2025-10-26 12:51:54,857 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,859 INFO logout requested 60s 2025-10-26 12:51:54,859 INFO Logging out user test. 60s 2025-10-26 12:51:54,861 INFO User test logged out 60s PASSED 60s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2025-10-26 12:51:54,863 INFO logout requested 60s PASSED 60s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2025-10-26 12:51:54,870 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,876 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,878 INFO logout requested 60s 2025-10-26 12:51:54,878 INFO Logging out user test from all sessions. 60s 2025-10-26 12:51:54,882 INFO User test logged out 60s PASSED 60s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2025-10-26 12:51:54,889 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,893 INFO Service ticket created for service http://127.0.0.1:43049 by user test. 60s 2025-10-26 12:51:54,896 INFO Validate: Service ticket ST-CLaxtfLUjO6kXDgLEp4qFRzvFUsqcQWdhwvlmLCCXKNGtQnrlozhUxl3I7yK3 validated, user test authenticated on service http://127.0.0.1:43049 60s 2025-10-26 12:51:54,896 INFO logout requested 60s 2025-10-26 12:51:54,897 INFO Logging out user test. 60s 2025-10-26 12:51:54,898 INFO Sending SLO requests to service http://127.0.0.1:43049 for user test 60s 2025-10-26 12:51:54,900 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 60s 2025-10-26 12:51:54,901 INFO User test logged out 60s 2025-10-26 12:51:54,906 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,910 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 60s 2025-10-26 12:51:54,912 INFO Validate: Service ticket ST-TllFLrojEXL2fWEw6sXgWm2N3gfzzIl3sFv8pyX8g2cRVhnaHKcqBUyZQk7E3 validated, user test authenticated on service http://127.0.0.1:45678 60s 2025-10-26 12:51:54,912 INFO logout requested 60s 2025-10-26 12:51:54,913 INFO Logging out user test. 60s 2025-10-26 12:51:54,914 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 60s 2025-10-26 12:51:54,916 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 60s 2025-10-26 12:51:54,917 INFO User test logged out 60s 2025-10-26 12:51:54,922 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,926 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 60s 2025-10-26 12:51:54,928 INFO Validate: Service ticket ST-7Oh65x3whWDMm156DTDxHURjHkLfy3VUSbwxpwQmSyR4JAtj18PjU8yCR1ZrW validated, user test authenticated on service http://127.0.0.1:45678 60s 2025-10-26 12:51:54,929 INFO logout requested 60s 2025-10-26 12:51:54,929 INFO Logging out user test. 60s 2025-10-26 12:51:54,930 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 60s 2025-10-26 12:51:54,932 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=46019): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 60s 2025-10-26 12:51:54,933 INFO User test logged out 60s PASSED 60s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2025-10-26 12:51:54,939 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,945 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,948 INFO logout requested 60s 2025-10-26 12:51:54,949 INFO Logging out user test. 60s 2025-10-26 12:51:54,951 INFO User test logged out 60s PASSED 60s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2025-10-26 12:51:54,960 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,962 INFO logout requested 60s 2025-10-26 12:51:54,963 INFO Logging out user test. 60s 2025-10-26 12:51:54,965 INFO User test logged out 60s PASSED 60s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2025-10-26 12:51:54,973 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,975 INFO logout requested 60s 2025-10-26 12:51:54,975 INFO Logging out user test. 60s 2025-10-26 12:51:54,977 INFO User test logged out 60s PASSED 60s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2025-10-26 12:51:54,985 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,987 INFO logout requested 60s 2025-10-26 12:51:54,987 INFO Logging out user test. 60s 2025-10-26 12:51:54,989 INFO User test logged out 60s PASSED 60s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2025-10-26 12:51:54,996 INFO User test successfully authenticated 60s 2025-10-26 12:51:54,998 INFO logout requested 60s 2025-10-26 12:51:54,998 INFO Logging out user test. 60s 2025-10-26 12:51:55,000 INFO User test logged out 60s 2025-10-26 12:51:55,003 INFO logout requested 60s PASSED 60s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 60s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 60s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice PASSED 60s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings 2025-10-26 12:51:55,014 WARNING Service https://www.example.org not allowed. 60s PASSED 60s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 60s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2025-10-26 12:51:55,024 INFO User test successfully authenticated 60s PASSED 60s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 60s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2025-10-26 12:51:55,036 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,040 INFO Service ticket created for service https://www.example.com by user test. 60s 2025-10-26 12:51:55,042 WARNING Validate: service or ticket missing 60s 2025-10-26 12:51:55,042 WARNING Validate: service or ticket missing 60s PASSED 60s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2025-10-26 12:51:55,047 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,050 INFO Service ticket created for service https://www.example.com by user test. 60s 2025-10-26 12:51:55,052 INFO Validate: Service ticket ST-w9CtJI4locdtlukOzMPSSNs9ECN6A6DlvYNv7Fblki0J1URYCtj5mXRIuBW9Y validated, user test authenticated on service https://www.example.com 60s 2025-10-26 12:51:55,056 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,058 INFO Service ticket created for service https://www.example.com by user test. 60s 2025-10-26 12:51:55,060 INFO Validate: Service ticket ST-b5r2u0L1Pg6nuHiXgbJienS9KfRaS8AiPXbU7UVS5J0GuwO10fis56Uq5CcxZ validated, user test authenticated on service https://www.example.com 60s 2025-10-26 12:51:55,062 INFO Service ticket created for service https://www.example.com by user test. 60s 2025-10-26 12:51:55,063 WARNING Validate: Service ticket ST-oS3QW7hPNFGTTu4Oyn3E1Uu7KqHvNKI226ndBPJ39bsluuVMB6lV4OtOO1vcD not found or already validated, auth to https://www.example.com failed 60s PASSED 60s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2025-10-26 12:51:55,069 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,073 INFO Service ticket created for service https://user_field.example.com by user test. 60s 2025-10-26 12:51:55,075 INFO Validate: Service ticket ST-UvviAh5HQznjlY7FKKbfZ1JT3cNQ8Fqp49SY21WZTsMBAejrNrTyuciOY54NA validated, user test authenticated on service https://user_field.example.com 60s 2025-10-26 12:51:55,079 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,083 INFO Service ticket created for service https://user_field_alt.example.com by user test. 60s 2025-10-26 12:51:55,085 INFO Validate: Service ticket ST-5oODSqruPx5IVgSdpapBEd4Q82PwO7BUgVgQY3tMtlKGvo7mWOZZONuFt6W3V validated, user test authenticated on service https://user_field_alt.example.com 60s PASSED 60s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2025-10-26 12:51:55,090 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,095 INFO Service ticket created for service https://www.example.com by user test. 60s 2025-10-26 12:51:55,096 WARNING Validate: Service ticket ST-ZmuW5NMJYnkz0ZM5MbawOyZz28JimjbEpzpcKvEYiUhrll1D83kQYLlvp2HMQ not found or already validated, auth to https://www.example.org failed 60s PASSED 60s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2025-10-26 12:51:55,101 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,105 INFO Service ticket created for service https://www.example.com by user test. 60s 2025-10-26 12:51:55,107 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 60s PASSED 60s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2025-10-26 12:51:55,112 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,117 INFO Service ticket created for service https://www.example.com by user test. 60s 2025-10-26 12:51:55,119 INFO Validate: Service ticket ST-iFYXQnQ2uI0pMe5RvZyeZZbycCLgDTt4k9bcpchvtPHlQI7MptacTeRz6jmxb validated, user test authenticated on service https://www.example.com 60s PASSED 60s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2025-10-26 12:51:55,125 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,129 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 60s 2025-10-26 12:51:55,130 WARNING ValidateService: missing ticket or service 60s 2025-10-26 12:51:55,131 WARNING ValidateService: missing ticket or service 60s PASSED 60s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2025-10-26 12:51:55,138 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,142 INFO Service ticket created for service http://127.0.0.1:33305 by user test. 60s 2025-10-26 12:51:55,146 INFO ValidateService: ticket ST-qppfp9aMGprIWfoivYXgIoOBLs6PftQMJ8z5uuw16vCHfTFY6M5lBQhVVVrKB validated for user test on service http://127.0.0.1:33305. Proxy Granting Ticket transmited to http://127.0.0.1:33305. 60s 2025-10-26 12:51:55,150 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 60s 2025-10-26 12:51:55,152 INFO ValidateService: ticket PT-bvCgjwUnRcpsXP8NZMxqDryIhXnLTGd3EVLjVHo4NJnYQ3YK1BkX6sIve1NDy validated for user test on service http://127.0.0.1:45678. 60s 2025-10-26 12:51:55,157 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,161 INFO Service ticket created for service http://127.0.0.1:39935 by user test. 60s 2025-10-26 12:51:55,165 INFO ValidateService: ticket ST-GBotvDtHuxTEhMgamg9xZMC4HYPpE9PiLDWqRfCM204LEJcE2vbn9ZXBdWKeV validated for user test on service http://127.0.0.1:39935. Proxy Granting Ticket transmited to http://127.0.0.1:39935. 60s 2025-10-26 12:51:55,168 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 60s 2025-10-26 12:51:55,169 WARNING ValidateService: validation error: INVALID_TICKET PT-vY1pMk9OgMIWZjE6b6gadZCoq8AIJ1jlbEtBkkkJuo2yzN2p0IT8ttKAr3lAr 60s PASSED 60s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2025-10-26 12:51:55,175 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,179 INFO Service ticket created for service https://replace_attribute.example.com by user test. 60s 2025-10-26 12:51:55,182 INFO ValidateService: ticket ST-0eRzJp4ouORMDS0KClL4MOEs4f5wyGJyMEPSuOiecU7Oey29OueZO8ntPkCpQ validated for user test on service https://replace_attribute.example.com. 60s 2025-10-26 12:51:55,186 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,190 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 60s 2025-10-26 12:51:55,192 INFO ValidateService: ticket ST-gUQmhjq4zMTo3eGxtz0Gjc5T5FEvHNAFSYuI5zgz4QeAC6jXMnAOggROEfI37 validated for user test on service https://replace_attribute_list.example.com. 60s PASSED 60s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2025-10-26 12:51:55,199 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,203 INFO Service ticket created for service http://127.0.0.1:39291 by user test. 60s 2025-10-26 12:51:55,207 INFO ValidateService: ticket ST-c5ZDnMx7mILQw6PiWYHR92omsoa4UdyjJwyGis4AqbzOhavaF9YBx3ZLCkAs5 validated for user test on service http://127.0.0.1:39291. Proxy Granting Ticket transmited to http://127.0.0.1:39291. 60s PASSED 60s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2025-10-26 12:51:55,214 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,218 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 60s 2025-10-26 12:51:55,220 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 60s 2025-10-26 12:51:55,224 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,228 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 60s 2025-10-26 12:51:55,230 WARNING Service https://www.example.org not allowed. 60s 2025-10-26 12:51:55,230 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 60s PASSED 60s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2025-10-26 12:51:55,237 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,241 INFO Service ticket created for service https://127.0.0.1:46859 by user test. 60s 2025-10-26 12:51:55,245 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=46859): Max retries exceeded with url: /?pgtId=PGT-YPmND6H669PqHCvhmHxXH0QXcNYYURnVob5rRFaXmcbNY1DJTKCrYfkM9MFD&pgtIou=PGTIOU-uZCRwtofGh09WBc1oM70YdTF80OyqmVp6pnsEqHH8KnzKa2zYXAn1Q3EJ (Caused by SSLError(SSLError(1, '[SSL: RECORD_LAYER_FAILURE] record layer failure (_ssl.c:1033)'))) 60s PASSED 60s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2025-10-26 12:51:55,252 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,255 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 60s 2025-10-26 12:51:55,259 INFO ValidateService: ticket ST-h0dIaqSeH5shEeW5znrbLYzOb5ssgZLeF9iymsofcH60tilxk6PbcLL5Gv8rQ validated for user test on service http://127.0.0.1:45678. 60s 2025-10-26 12:51:55,263 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,265 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 60s 2025-10-26 12:51:55,267 INFO ValidateService: ticket ST-T4cqfrySCS3SbvpbFETXfyQ6jAG1vPz9eGCSAwQUxRubT1hDD66gLXzjx2mrQ validated for user test on service http://127.0.0.1:45678. 60s 2025-10-26 12:51:55,270 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 60s 2025-10-26 12:51:55,271 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 60s PASSED 60s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2025-10-26 12:51:55,278 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,282 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 60s 2025-10-26 12:51:55,284 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 60s PASSED 60s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2025-10-26 12:51:55,290 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,294 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 60s 2025-10-26 12:51:55,295 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 60s PASSED 60s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2025-10-26 12:51:55,302 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,306 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 60s 2025-10-26 12:51:55,307 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 60s PASSED 60s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2025-10-26 12:51:55,313 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,317 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 60s 2025-10-26 12:51:55,319 INFO ValidateService: ticket ST-aGMAVSVxRN2jufAuiWRgpsdtqLtneD8F9BF2PKpSziqWwCFvMsJgSR1Jg2LU8 validated for user test on service http://127.0.0.1:45678. 60s PASSED 60s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2025-10-26 12:51:55,326 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,330 INFO Service ticket created for service https://one_attribute.example.com by user test. 60s 2025-10-26 12:51:55,333 INFO ValidateService: ticket ST-VI2iZXKO9Q3QWY5GPrjAZWqXVIm7Vh9BOIZEGExDRqAh1lXC5v6zUyS4De2Cx validated for user test on service https://one_attribute.example.com. 60s PASSED 60s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2025-10-26 12:51:55,339 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,343 INFO Service ticket created for service http://127.0.0.1:37921 by user test. 60s 2025-10-26 12:51:55,348 INFO ValidateService: ticket ST-WNqIgM5wzcfBdkvSHzcpZ1x6pYK6QgXICXSeAlkUdNSYKrHa5v4tDrjSNSFJb validated for user test on service http://127.0.0.1:37921. Proxy Granting Ticket transmited to http://127.0.0.1:37921. 60s PASSED 60s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2025-10-26 12:51:55,355 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,359 INFO Service ticket created for service https://user_field.example.com by user test. 60s 2025-10-26 12:51:55,361 INFO ValidateService: ticket ST-4SNG6092TUXrWy2m9EitDxdMRadafSnppASZ5s3Hey44uUJs3hRPHbnsCCvYY validated for user test on service https://user_field.example.com. 60s 2025-10-26 12:51:55,365 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,369 INFO Service ticket created for service https://user_field_alt.example.com by user test. 60s 2025-10-26 12:51:55,371 INFO ValidateService: ticket ST-APs6of3lo2OolS3WpPoaGkejdPr5wmHdUy3W9VHB972mwI4GcLOUTqQUFJhpu validated for user test on service https://user_field_alt.example.com. 60s PASSED 60s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2025-10-26 12:51:55,379 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,384 INFO Service ticket created for service http://127.0.0.1:41925 by user test. 60s 2025-10-26 12:51:55,388 INFO ValidateService: ticket ST-sp64Ke8f3MstI0ENjg1CCXnetMJVxsGCOaUdSU7kRYbbkPPcxSqndfHBueBwk validated for user test on service http://127.0.0.1:41925. Proxy Granting Ticket transmited to http://127.0.0.1:41925. 60s 2025-10-26 12:51:55,388 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 60s 2025-10-26 12:51:55,389 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 60s PASSED 60s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2025-10-26 12:51:55,396 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,400 INFO Service ticket created for service http://127.0.0.1:46169 by user test. 60s 2025-10-26 12:51:55,404 INFO ValidateService: ticket ST-eBldhA4fEsUCdW8PyR4VSJf7DBek3N9H00sI6E1MQTFmSwPFeHGOf156BiCZL validated for user test on service http://127.0.0.1:46169. Proxy Granting Ticket transmited to http://127.0.0.1:46169. 60s 2025-10-26 12:51:55,406 WARNING Username test not allowed on service restrict_user_fail 60s 2025-10-26 12:51:55,406 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 60s 2025-10-26 12:51:55,408 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 60s 2025-10-26 12:51:55,408 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 60s 2025-10-26 12:51:55,409 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 60s 2025-10-26 12:51:55,409 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 60s PASSED 60s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2025-10-26 12:51:55,416 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,420 INFO Service ticket created for service http://127.0.0.1:43335 by user test. 60s 2025-10-26 12:51:55,424 INFO ValidateService: ticket ST-RsBqvHyJKB3YMfCYoKyLBrRyhKvE0oyhKOoYfzTF3U039F9bioCFZ6Bynksbf validated for user test on service http://127.0.0.1:43335. Proxy Granting Ticket transmited to http://127.0.0.1:43335. 60s 2025-10-26 12:51:55,426 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 60s PASSED 60s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2025-10-26 12:51:55,434 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,438 INFO Service ticket created for service http://127.0.0.1:42291 by user test. 60s 2025-10-26 12:51:55,442 INFO ValidateService: ticket ST-W7533tjlnFoM0zUNDStFLz6ZNDoVIUIp2WbSJ7ZyUAcpPGcOWTmBjFMD2ohsj validated for user test on service http://127.0.0.1:42291. Proxy Granting Ticket transmited to http://127.0.0.1:42291. 60s 2025-10-26 12:51:55,443 WARNING Service https://www.example.org not allowed. 60s 2025-10-26 12:51:55,443 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 60s 2025-10-26 12:51:55,444 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:42291 does not allow proxy tickets 60s PASSED 60s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2025-10-26 12:51:55,452 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,457 INFO Service ticket created for service http://127.0.0.1:42369 by user test. 60s 2025-10-26 12:51:55,460 INFO ValidateService: ticket ST-C8KQPCoHffdB8cpLUIuu7HZqD2rTG5mdeM1qFrdbKuEwgJpzBiLVsfYdypvRN validated for user test on service http://127.0.0.1:42369. Proxy Granting Ticket transmited to http://127.0.0.1:42369. 60s 2025-10-26 12:51:55,464 INFO Proxy ticket created for user test on service https://www.example.com. 60s 2025-10-26 12:51:55,466 INFO ValidateService: ticket PT-qaEg0mGgbGKa3gW9LEyvCxlb0zuqtTo1q3rOeQecnkerJ6doYWRd3MuGFRS8u validated for user test on service https://www.example.com. 60s PASSED 60s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2025-10-26 12:51:55,473 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,477 INFO Service ticket created for service https://www.example.com by user test. 60s 2025-10-26 12:51:55,480 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 60s PASSED 60s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2025-10-26 12:51:55,483 WARNING SamlValidate: validation error: AuthnFailed ticket ST-x72vSYKCLAGP7P6uNiHJYLeB8o6hHzfYkcOPfVmhfmq2PpL4H4tNcoULrI2CZ not found 60s 2025-10-26 12:51:55,484 WARNING SamlValidate: validation error: AuthnFailed ticket PT-FXn3vGRSc7m1Dn4yfjmZOeSxxDMXlAIVZdJ7X8Wad5Vcg0CVRTaYIkke4rbJs not found 60s PASSED 60s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix 2025-10-26 12:51:55,487 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 60s PASSED 60s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml 2025-10-26 12:51:55,490 WARNING SamlValidate: validation error: VersionMismatch 60s PASSED 60s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2025-10-26 12:51:55,497 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,501 INFO Service ticket created for service https://www.example.com by user test. 60s 2025-10-26 12:51:55,506 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,510 INFO Service ticket created for service http://127.0.0.1:37159 by user test. 60s 2025-10-26 12:51:55,514 INFO ValidateService: ticket ST-UYfy4zgKUTDKIXWBB9GdzyC4ZiMGFQrHCldj7sZfsJ2mni0t8f1bOsRSBGELS validated for user test on service http://127.0.0.1:37159. Proxy Granting Ticket transmited to http://127.0.0.1:37159. 60s 2025-10-26 12:51:55,517 INFO Proxy ticket created for user test on service https://www.example.com. 60s 2025-10-26 12:51:55,519 INFO SamlValidate: ticket ST-tbcP1GVw9Eo92IKIloOP2YyyMK47YLoK3PiGpzSj7c32fNyGpvoYtmRxwOPU0 validated for user test on service https://www.example.com. 60s 2025-10-26 12:51:55,521 INFO SamlValidate: ticket PT-HNYCUnkWWBlBPUzfpeoYDKfm1ggelyDnoKgePfzi8st7Hou36ipmu4PDuZqGW validated for user test on service https://www.example.com. 60s PASSED 60s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2025-10-26 12:51:55,528 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,532 INFO Service ticket created for service https://field_needed_success.example.com by user test. 60s 2025-10-26 12:51:55,535 INFO SamlValidate: ticket ST-znAcCGecuCAKPkEPPVXa79KFh8zqJritL3LFe2iNMnXQYGfS08yYmETwtA92Y validated for user test on service https://field_needed_success.example.com. 60s 2025-10-26 12:51:55,539 INFO User test successfully authenticated 60s 2025-10-26 12:51:55,543 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 60s 2025-10-26 12:51:55,545 INFO SamlValidate: ticket ST-LfkTku43k2nbR53HnCWS1DpWT6Gw61SYmgH9eFXFe6lYfHLZ3OErIHmtm28gq validated for user test on service https://field_needed_success_alt.example.com. 60s PASSEDDestroying test database for alias 'default'... 60s 60s 60s =============================== warnings summary =============================== 60s cas_server/tests/test_federate.py: 8 warnings 60s cas_server/tests/test_view.py: 3 warnings 60s /tmp/autopkgtest.ZIY8bQ/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). 60s datetime.utcnow() + timedelta(seconds=max_age), 60s 60s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 60s /usr/lib/python3.13/unittest/case.py:707: DeprecationWarning: It is deprecated to return a value that is not None from a test case (>) 60s return self.run(*args, **kwds) 60s 60s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 60s ======================= 128 passed, 12 warnings in 3.12s ======================= 61s 61s autopkgtest [12:51:56]: test run-test-suite.sh: -----------------------] 61s autopkgtest [12:51:56]: test run-test-suite.sh: - - - - - - - - - - results - - - - - - - - - - 61s run-test-suite.sh PASS 61s autopkgtest [12:51:56]: @@@@@@@@@@@@@@@@@@@@ summary 61s run-test-suite.sh PASS