0s autopkgtest [12:36:24]: starting date and time: 2025-10-26 12:36:24+0000 0s autopkgtest [12:36:24]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [12:36:24]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.u037qiy5/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-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-ppc64el-7.secgroup --name adt-resolute-ppc64el-django-cas-server-20251026-123624-juju-7f2275-prod-proposed-migration-environment-15-a84f8bfc-3530-4f44-b5da-58935f330c62 --image adt/ubuntu-resolute-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-ppc64el-django-cas-server-20251026-123624-juju-7f2275-prod-proposed-migration-environment-15-a84f8bfc-3530-4f44-b5da-58935f330c62 from image adt/ubuntu-resolute-ppc64el-server-20251026.img (UUID 85266b2b-ff15-446c-b3e7-72f61d0e75cb)... 67s autopkgtest [12:37:31]: testbed dpkg architecture: ppc64el 68s autopkgtest [12:37:32]: testbed apt version: 3.1.8ubuntu1 68s autopkgtest [12:37:32]: @@@@@@@@@@@@@@@@@@@@ test bed setup 68s autopkgtest [12:37:32]: testbed release detected to be: None 69s autopkgtest [12:37:33]: updating testbed package index (apt update) 69s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [87.8 kB] 69s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 69s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 70s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 70s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [61.7 kB] 70s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5028 B] 70s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [422 kB] 70s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [16.5 kB] 70s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el Packages [142 kB] 70s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/restricted ppc64el Packages [940 B] 70s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el Packages [294 kB] 70s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse ppc64el Packages [4596 B] 70s Fetched 1035 kB in 1s (937 kB/s) 71s Reading package lists... 72s Failed to check for VM: Permission denied 72s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 72s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 72s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 72s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 73s Reading package lists... 73s Reading package lists... 73s Building dependency tree... 73s Reading state information... 73s Calculating upgrade... 73s The following packages will be upgraded: 73s binutils binutils-common binutils-powerpc64le-linux-gnu gir1.2-glib-2.0 73s libbinutils libctf-nobfd0 libctf0 libglib2.0-0t64 libglib2.0-data 73s libpython3-stdlib libsframe2 python3 python3-minimal 73s 13 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 73s Need to get 4386 kB of archives. 73s After this operation, 3072 B of additional disk space will be used. 73s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el python3-minimal ppc64el 3.13.7-2 [27.8 kB] 74s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el python3 ppc64el 3.13.7-2 [23.9 kB] 74s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libpython3-stdlib ppc64el 3.13.7-2 [10.6 kB] 74s Get:4 http://ftpmaster.internal/ubuntu resolute/main ppc64el gir1.2-glib-2.0 ppc64el 2.86.1-1 [182 kB] 74s Get:5 http://ftpmaster.internal/ubuntu resolute/main ppc64el libglib2.0-0t64 ppc64el 2.86.1-1 [1848 kB] 74s Get:6 http://ftpmaster.internal/ubuntu resolute/main ppc64el libglib2.0-data all 2.86.1-1 [56.7 kB] 74s Get:7 http://ftpmaster.internal/ubuntu resolute/main ppc64el libctf0 ppc64el 2.45-8ubuntu1 [118 kB] 74s Get:8 http://ftpmaster.internal/ubuntu resolute/main ppc64el libctf-nobfd0 ppc64el 2.45-8ubuntu1 [122 kB] 74s Get:9 http://ftpmaster.internal/ubuntu resolute/main ppc64el binutils-powerpc64le-linux-gnu ppc64el 2.45-8ubuntu1 [1021 kB] 74s Get:10 http://ftpmaster.internal/ubuntu resolute/main ppc64el libbinutils ppc64el 2.45-8ubuntu1 [734 kB] 74s Get:11 http://ftpmaster.internal/ubuntu resolute/main ppc64el binutils ppc64el 2.45-8ubuntu1 [3236 B] 74s Get:12 http://ftpmaster.internal/ubuntu resolute/main ppc64el binutils-common ppc64el 2.45-8ubuntu1 [221 kB] 74s Get:13 http://ftpmaster.internal/ubuntu resolute/main ppc64el libsframe2 ppc64el 2.45-8ubuntu1 [17.9 kB] 75s dpkg-preconfigure: unable to re-open stdin: No such file or directory 75s Fetched 4386 kB in 1s (3901 kB/s) 75s (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 ... 76482 files and directories currently installed.) 75s Preparing to unpack .../python3-minimal_3.13.7-2_ppc64el.deb ... 75s Unpacking python3-minimal (3.13.7-2) over (3.13.7-1) ... 75s Setting up python3-minimal (3.13.7-2) ... 75s (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 ... 76482 files and directories currently installed.) 75s Preparing to unpack .../00-python3_3.13.7-2_ppc64el.deb ... 75s running python pre-rtupdate hooks for python3.13... 75s Unpacking python3 (3.13.7-2) over (3.13.7-1) ... 75s Preparing to unpack .../01-libpython3-stdlib_3.13.7-2_ppc64el.deb ... 75s Unpacking libpython3-stdlib:ppc64el (3.13.7-2) over (3.13.7-1) ... 75s Preparing to unpack .../02-gir1.2-glib-2.0_2.86.1-1_ppc64el.deb ... 75s Unpacking gir1.2-glib-2.0:ppc64el (2.86.1-1) over (2.86.0-2) ... 76s Preparing to unpack .../03-libglib2.0-0t64_2.86.1-1_ppc64el.deb ... 76s Unpacking libglib2.0-0t64:ppc64el (2.86.1-1) over (2.86.0-2) ... 76s Preparing to unpack .../04-libglib2.0-data_2.86.1-1_all.deb ... 76s Unpacking libglib2.0-data (2.86.1-1) over (2.86.0-2) ... 76s Preparing to unpack .../05-libctf0_2.45-8ubuntu1_ppc64el.deb ... 76s Unpacking libctf0:ppc64el (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 76s Preparing to unpack .../06-libctf-nobfd0_2.45-8ubuntu1_ppc64el.deb ... 76s Unpacking libctf-nobfd0:ppc64el (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 76s Preparing to unpack .../07-binutils-powerpc64le-linux-gnu_2.45-8ubuntu1_ppc64el.deb ... 76s Unpacking binutils-powerpc64le-linux-gnu (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 76s Preparing to unpack .../08-libbinutils_2.45-8ubuntu1_ppc64el.deb ... 76s Unpacking libbinutils:ppc64el (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 76s Preparing to unpack .../09-binutils_2.45-8ubuntu1_ppc64el.deb ... 76s Unpacking binutils (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 76s Preparing to unpack .../10-binutils-common_2.45-8ubuntu1_ppc64el.deb ... 76s Unpacking binutils-common:ppc64el (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 76s Preparing to unpack .../11-libsframe2_2.45-8ubuntu1_ppc64el.deb ... 76s Unpacking libsframe2:ppc64el (2.45-8ubuntu1) over (2.45-7ubuntu2) ... 76s Setting up libsframe2:ppc64el (2.45-8ubuntu1) ... 76s Setting up binutils-common:ppc64el (2.45-8ubuntu1) ... 77s Setting up libctf-nobfd0:ppc64el (2.45-8ubuntu1) ... 77s Setting up libglib2.0-0t64:ppc64el (2.86.1-1) ... 77s No schema files found: doing nothing. 77s Setting up libglib2.0-data (2.86.1-1) ... 77s Setting up gir1.2-glib-2.0:ppc64el (2.86.1-1) ... 77s Setting up libbinutils:ppc64el (2.45-8ubuntu1) ... 77s Setting up libpython3-stdlib:ppc64el (3.13.7-2) ... 77s Setting up libctf0:ppc64el (2.45-8ubuntu1) ... 77s Setting up python3 (3.13.7-2) ... 77s running python rtupdate hooks for python3.13... 77s running python post-rtupdate hooks for python3.13... 77s Setting up binutils-powerpc64le-linux-gnu (2.45-8ubuntu1) ... 77s Setting up binutils (2.45-8ubuntu1) ... 77s Processing triggers for libc-bin (2.42-0ubuntu3) ... 77s Processing triggers for man-db (2.13.1-1) ... 78s autopkgtest [12:37:42]: upgrading testbed (apt dist-upgrade and autopurge) 78s Reading package lists... 78s Building dependency tree... 78s Reading state information... 79s Calculating upgrade... 79s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 79s Reading package lists... 79s Building dependency tree... 79s Reading state information... 79s Solving dependencies... 79s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 82s autopkgtest [12:37:46]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 10:02:41 UTC 2025 82s autopkgtest [12:37:46]: @@@@@@@@@@@@@@@@@@@@ apt-source django-cas-server 84s Get:1 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (dsc) [2597 B] 84s Get:2 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (tar) [138 kB] 84s Get:3 http://ftpmaster.internal/ubuntu resolute/universe django-cas-server 2.1.0-3 (diff) [10.2 kB] 84s gpgv: Signature made Sat Dec 14 20:43:18 2024 UTC 84s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 84s gpgv: issuer "tchet@debian.org" 84s gpgv: Can't check signature: No public key 84s dpkg-source: warning: cannot verify inline signature for ./django-cas-server_2.1.0-3.dsc: no acceptable signature found 84s autopkgtest [12:37:48]: testing package django-cas-server version 2.1.0-3 84s autopkgtest [12:37:48]: build not needed 85s autopkgtest [12:37:49]: test run-test-suite.sh: preparing testbed 85s Reading package lists... 86s Building dependency tree... 86s Reading state information... 86s Solving dependencies... 86s The following NEW packages will be installed: 86s fonts-font-awesome fonts-lato libjs-jquery libjs-sphinxdoc libjs-underscore 86s libpython3.14-minimal libpython3.14-stdlib libxslt1.1 python3-all 86s python3-asgiref python3-django python3-django-cas-server 86s python3-django-cas-server-doc python3-iniconfig python3-legacy-cgi 86s python3-lxml python3-packaging python3-pluggy python3-pytest 86s python3-pytest-django python3-requests-futures python3-sqlparse python3.14 86s python3.14-minimal sphinx-rtd-theme-common 86s 0 upgraded, 25 newly installed, 0 to remove and 0 not upgraded. 86s Need to get 18.1 MB of archives. 86s After this operation, 88.6 MB of additional disk space will be used. 86s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el fonts-lato all 2.015-1 [2781 kB] 87s Get:2 http://ftpmaster.internal/ubuntu resolute/main ppc64el libpython3.14-minimal ppc64el 3.14.0-4 [908 kB] 87s Get:3 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.14-minimal ppc64el 3.14.0-4 [2705 kB] 87s Get:4 http://ftpmaster.internal/ubuntu resolute/main ppc64el fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 87s Get:5 http://ftpmaster.internal/ubuntu resolute/main ppc64el libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 87s Get:6 http://ftpmaster.internal/ubuntu resolute/main ppc64el libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 87s Get:7 http://ftpmaster.internal/ubuntu resolute/main ppc64el libjs-sphinxdoc all 8.2.3-1ubuntu2 [28.0 kB] 87s Get:8 http://ftpmaster.internal/ubuntu resolute/main ppc64el libpython3.14-stdlib ppc64el 3.14.0-4 [2446 kB] 88s Get:9 http://ftpmaster.internal/ubuntu resolute/main ppc64el libxslt1.1 ppc64el 1.1.43-0.1 [189 kB] 88s Get:10 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.14 ppc64el 3.14.0-4 [805 kB] 88s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el python3-all ppc64el 3.13.7-2 [892 B] 88s Get:12 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-asgiref all 3.9.1-2 [24.9 kB] 88s Get:13 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-sqlparse all 0.5.3-1 [32.9 kB] 88s Get:14 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-django all 3:5.2.4-1ubuntu2 [3036 kB] 88s Get:15 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-legacy-cgi all 2.6.3-1 [16.8 kB] 88s Get:16 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-lxml ppc64el 6.0.1-1build1 [2449 kB] 88s Get:17 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-requests-futures all 1.0.2-1 [12.0 kB] 88s Get:18 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-django-cas-server all 2.1.0-3 [111 kB] 88s Get:19 http://ftpmaster.internal/ubuntu resolute/main ppc64el sphinx-rtd-theme-common all 3.0.2+dfsg-3 [1013 kB] 88s Get:20 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-django-cas-server-doc all 2.1.0-3 [178 kB] 88s Get:21 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-iniconfig all 1.1.1-2 [6024 B] 88s Get:22 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-packaging all 25.0-1 [52.8 kB] 88s Get:23 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-pluggy all 1.6.0-1 [21.0 kB] 88s Get:24 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-pytest all 8.3.5-2 [252 kB] 88s Get:25 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-pytest-django all 4.11.1-2 [21.8 kB] 88s Fetched 18.1 MB in 2s (7715 kB/s) 88s Selecting previously unselected package fonts-lato. 88s (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 ... 76482 files and directories currently installed.) 88s Preparing to unpack .../00-fonts-lato_2.015-1_all.deb ... 89s Unpacking fonts-lato (2.015-1) ... 89s Selecting previously unselected package libpython3.14-minimal:ppc64el. 89s Preparing to unpack .../01-libpython3.14-minimal_3.14.0-4_ppc64el.deb ... 89s Unpacking libpython3.14-minimal:ppc64el (3.14.0-4) ... 89s Selecting previously unselected package python3.14-minimal. 89s Preparing to unpack .../02-python3.14-minimal_3.14.0-4_ppc64el.deb ... 89s Unpacking python3.14-minimal (3.14.0-4) ... 89s Selecting previously unselected package fonts-font-awesome. 89s Preparing to unpack .../03-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 89s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 89s Selecting previously unselected package libjs-jquery. 89s Preparing to unpack .../04-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 89s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 89s Selecting previously unselected package libjs-underscore. 89s Preparing to unpack .../05-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 89s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 89s Selecting previously unselected package libjs-sphinxdoc. 89s Preparing to unpack .../06-libjs-sphinxdoc_8.2.3-1ubuntu2_all.deb ... 89s Unpacking libjs-sphinxdoc (8.2.3-1ubuntu2) ... 89s Selecting previously unselected package libpython3.14-stdlib:ppc64el. 89s Preparing to unpack .../07-libpython3.14-stdlib_3.14.0-4_ppc64el.deb ... 89s Unpacking libpython3.14-stdlib:ppc64el (3.14.0-4) ... 89s Selecting previously unselected package libxslt1.1:ppc64el. 89s Preparing to unpack .../08-libxslt1.1_1.1.43-0.1_ppc64el.deb ... 89s Unpacking libxslt1.1:ppc64el (1.1.43-0.1) ... 89s Selecting previously unselected package python3.14. 89s Preparing to unpack .../09-python3.14_3.14.0-4_ppc64el.deb ... 89s Unpacking python3.14 (3.14.0-4) ... 89s Selecting previously unselected package python3-all. 89s Preparing to unpack .../10-python3-all_3.13.7-2_ppc64el.deb ... 89s Unpacking python3-all (3.13.7-2) ... 89s Selecting previously unselected package python3-asgiref. 89s Preparing to unpack .../11-python3-asgiref_3.9.1-2_all.deb ... 89s Unpacking python3-asgiref (3.9.1-2) ... 89s Selecting previously unselected package python3-sqlparse. 89s Preparing to unpack .../12-python3-sqlparse_0.5.3-1_all.deb ... 89s Unpacking python3-sqlparse (0.5.3-1) ... 89s Selecting previously unselected package python3-django. 89s Preparing to unpack .../13-python3-django_3%3a5.2.4-1ubuntu2_all.deb ... 89s Unpacking python3-django (3:5.2.4-1ubuntu2) ... 90s Selecting previously unselected package python3-legacy-cgi. 90s Preparing to unpack .../14-python3-legacy-cgi_2.6.3-1_all.deb ... 90s Unpacking python3-legacy-cgi (2.6.3-1) ... 90s Selecting previously unselected package python3-lxml:ppc64el. 90s Preparing to unpack .../15-python3-lxml_6.0.1-1build1_ppc64el.deb ... 90s Unpacking python3-lxml:ppc64el (6.0.1-1build1) ... 90s Selecting previously unselected package python3-requests-futures. 90s Preparing to unpack .../16-python3-requests-futures_1.0.2-1_all.deb ... 90s Unpacking python3-requests-futures (1.0.2-1) ... 90s Selecting previously unselected package python3-django-cas-server. 90s Preparing to unpack .../17-python3-django-cas-server_2.1.0-3_all.deb ... 90s Unpacking python3-django-cas-server (2.1.0-3) ... 90s Selecting previously unselected package sphinx-rtd-theme-common. 90s Preparing to unpack .../18-sphinx-rtd-theme-common_3.0.2+dfsg-3_all.deb ... 90s Unpacking sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 90s Selecting previously unselected package python3-django-cas-server-doc. 90s Preparing to unpack .../19-python3-django-cas-server-doc_2.1.0-3_all.deb ... 90s Unpacking python3-django-cas-server-doc (2.1.0-3) ... 90s Selecting previously unselected package python3-iniconfig. 90s Preparing to unpack .../20-python3-iniconfig_1.1.1-2_all.deb ... 90s Unpacking python3-iniconfig (1.1.1-2) ... 90s Selecting previously unselected package python3-packaging. 90s Preparing to unpack .../21-python3-packaging_25.0-1_all.deb ... 90s Unpacking python3-packaging (25.0-1) ... 90s Selecting previously unselected package python3-pluggy. 90s Preparing to unpack .../22-python3-pluggy_1.6.0-1_all.deb ... 90s Unpacking python3-pluggy (1.6.0-1) ... 90s Selecting previously unselected package python3-pytest. 90s Preparing to unpack .../23-python3-pytest_8.3.5-2_all.deb ... 90s Unpacking python3-pytest (8.3.5-2) ... 90s Selecting previously unselected package python3-pytest-django. 90s Preparing to unpack .../24-python3-pytest-django_4.11.1-2_all.deb ... 90s Unpacking python3-pytest-django (4.11.1-2) ... 90s Setting up python3-iniconfig (1.1.1-2) ... 91s Setting up fonts-lato (2.015-1) ... 91s Setting up libpython3.14-minimal:ppc64el (3.14.0-4) ... 91s Setting up python3-packaging (25.0-1) ... 91s Setting up python3-sqlparse (0.5.3-1) ... 91s Setting up python3-pluggy (1.6.0-1) ... 91s Setting up python3-legacy-cgi (2.6.3-1) ... 91s Setting up libxslt1.1:ppc64el (1.1.43-0.1) ... 91s Setting up python3-requests-futures (1.0.2-1) ... 92s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 92s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 92s Setting up sphinx-rtd-theme-common (3.0.2+dfsg-3) ... 92s Setting up python3.14-minimal (3.14.0-4) ... 93s Setting up python3-asgiref (3.9.1-2) ... 93s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 93s Setting up python3-django (3:5.2.4-1ubuntu2) ... 96s Setting up libpython3.14-stdlib:ppc64el (3.14.0-4) ... 96s Setting up python3-pytest (8.3.5-2) ... 96s Setting up python3-pytest-django (4.11.1-2) ... 97s Setting up libjs-sphinxdoc (8.2.3-1ubuntu2) ... 97s Setting up python3-lxml:ppc64el (6.0.1-1build1) ... 97s Setting up python3.14 (3.14.0-4) ... 98s Setting up python3-django-cas-server (2.1.0-3) ... 98s Setting up python3-all (3.13.7-2) ... 98s Setting up python3-django-cas-server-doc (2.1.0-3) ... 98s Processing triggers for systemd (257.9-0ubuntu2) ... 98s Processing triggers for man-db (2.13.1-1) ... 99s Processing triggers for libc-bin (2.42-0ubuntu3) ... 100s autopkgtest [12:38:04]: test run-test-suite.sh: [----------------------- 100s 100s *************************** 100s *** Testing with python3.14 100s *************************** 100s 100s Content of current working folder: 100s 100s total 20 100s drwxr-xr-x 3 ubuntu ubuntu 4096 Oct 26 12:38 . 100s drwxrwxrwx 5 root root 4096 Oct 26 12:38 .. 100s drwxrwxr-x 9 ubuntu ubuntu 4096 Oct 26 12:37 cas_server 100s -rw-rw-r-- 1 ubuntu ubuntu 140 Aug 18 2024 pytest.ini 100s -rw-rw-r-- 1 ubuntu ubuntu 152 Aug 18 2024 setup.cfg 100s Running tests... 100s 101s ============================= test session starts ============================== 101s platform linux -- Python 3.14.0, pytest-8.3.5, pluggy-1.6.0 -- /usr/bin/python3.14 101s cachedir: .pytest_cache 101s django: version: 5.2.4, settings: cas_server.tests.settings (from ini) 101s rootdir: /tmp/autopkgtest.K9P1jf/autopkgtest_tmp 101s configfile: pytest.ini 101s testpaths: cas_server/tests/ 101s plugins: typeguard-4.4.2, django-4.11.1 101s collecting ... collected 128 items 101s 101s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 102s 2025-10-26 12:38:06,604 WARNING Identity provider suffix exemple.fr not found 102s 2025-10-26 12:38:06,624 INFO Got an invalid ticket ST-9AYQECNcxydqU857zJsr3s4cxuUcDlTPAZoMKhMuKD0OHWWtiYw3FX5zoIpGy from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 102s 2025-10-26 12:38:06,628 INFO Got an invalid ticket ST-fwlkrr5waWeqXJaH584hNbk0pXfvtcr9BPgHygTLQLKyKCUq3RbuDOcRovGOv from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 102s PASSED 102s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2025-10-26 12:38:06,667 WARNING CAS_FEDERATE is False, set it to True to use federation 102s 2025-10-26 12:38:06,668 WARNING CAS_FEDERATE is False, set it to True to use federation 102s PASSED 102s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2025-10-26 12:38:06,687 INFO Trying to authenticate http://127.0.0.1:8080 again 102s 2025-10-26 12:38:06,692 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 102s 2025-10-26 12:38:06,707 INFO User dédé@example.com successfully authenticated 102s 2025-10-26 12:38:06,721 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 102s 2025-10-26 12:38:06,733 INFO Trying to authenticate http://127.0.0.1:8081 again 102s 2025-10-26 12:38:06,740 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 102s 2025-10-26 12:38:06,751 INFO User dédé@example.org successfully authenticated 102s 2025-10-26 12:38:06,762 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 102s 2025-10-26 12:38:06,775 INFO Trying to authenticate http://127.0.0.1:8082 again 102s 2025-10-26 12:38:06,780 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 102s 2025-10-26 12:38:06,791 INFO User dédé@example.net successfully authenticated 102s 2025-10-26 12:38:06,802 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 102s 2025-10-26 12:38:06,815 INFO Trying to authenticate http://127.0.0.1:8083 again 102s 2025-10-26 12:38:06,822 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 102s 2025-10-26 12:38:06,833 INFO User dédé@example.test successfully authenticated 102s 2025-10-26 12:38:06,844 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 102s 2025-10-26 12:38:06,884 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-S8H4xHwPK02Gn6OuDc2xEDKhj2KdgYkPCK0kQN0kTchF1ZHXQIS0lUYOlmhyr 102s 2025-10-26 12:38:06,912 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-kKu9vlg7IENQgeVgUYmeCpcBnLjoLzaFFxsSsnZJaZtqSMfSPDbjHcsQmDnEP 102s PASSED 102s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 102s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2025-10-26 12:38:06,953 INFO Trying to authenticate http://127.0.0.1:8080 again 102s 2025-10-26 12:38:06,958 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 102s 2025-10-26 12:38:06,969 INFO User dédé@example.com successfully authenticated 103s 2025-10-26 12:38:06,980 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 103s 2025-10-26 12:38:06,993 INFO Trying to authenticate http://127.0.0.1:8081 again 103s 2025-10-26 12:38:06,998 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 103s 2025-10-26 12:38:07,009 INFO User dédé@example.org successfully authenticated 103s 2025-10-26 12:38:07,020 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 103s 2025-10-26 12:38:07,033 INFO Trying to authenticate http://127.0.0.1:8082 again 103s 2025-10-26 12:38:07,037 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 103s 2025-10-26 12:38:07,048 INFO User dédé@example.net successfully authenticated 103s 2025-10-26 12:38:07,059 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 103s 2025-10-26 12:38:07,072 INFO Trying to authenticate http://127.0.0.1:8083 again 103s 2025-10-26 12:38:07,077 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 103s 2025-10-26 12:38:07,088 INFO User dédé@example.test successfully authenticated 103s 2025-10-26 12:38:07,099 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 103s 2025-10-26 12:38:07,100 INFO logout requested 103s 2025-10-26 12:38:07,102 INFO Logging out user dédé@example.com. 103s 2025-10-26 12:38:07,108 INFO User dédé@example.com logged out 103s 2025-10-26 12:38:07,118 INFO logout requested 103s 2025-10-26 12:38:07,121 INFO logout requested 103s 2025-10-26 12:38:07,123 INFO Logging out user dédé@example.org. 103s 2025-10-26 12:38:07,128 INFO User dédé@example.org logged out 103s 2025-10-26 12:38:07,139 INFO logout requested 103s 2025-10-26 12:38:07,142 INFO logout requested 103s 2025-10-26 12:38:07,144 INFO Logging out user dédé@example.net. 103s 2025-10-26 12:38:07,150 INFO User dédé@example.net logged out 103s 2025-10-26 12:38:07,160 INFO logout requested 103s 2025-10-26 12:38:07,163 INFO logout requested 103s 2025-10-26 12:38:07,165 INFO Logging out user dédé@example.test. 103s 2025-10-26 12:38:07,170 INFO User dédé@example.test logged out 103s 2025-10-26 12:38:07,180 INFO logout requested 103s 2025-10-26 12:38:07,196 INFO Trying to authenticate http://127.0.0.1:8080 again 103s 2025-10-26 12:38:07,200 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 103s 2025-10-26 12:38:07,211 INFO User dédé@example.com successfully authenticated 103s 2025-10-26 12:38:07,222 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 103s 2025-10-26 12:38:07,235 INFO Trying to authenticate http://127.0.0.1:8081 again 103s 2025-10-26 12:38:07,240 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 103s 2025-10-26 12:38:07,251 INFO User dédé@example.org successfully authenticated 103s 2025-10-26 12:38:07,262 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 103s 2025-10-26 12:38:07,275 INFO Trying to authenticate http://127.0.0.1:8082 again 103s 2025-10-26 12:38:07,280 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 103s 2025-10-26 12:38:07,291 INFO User dédé@example.net successfully authenticated 103s 2025-10-26 12:38:07,302 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 103s 2025-10-26 12:38:07,315 INFO Trying to authenticate http://127.0.0.1:8083 again 103s 2025-10-26 12:38:07,321 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 103s 2025-10-26 12:38:07,331 INFO User dédé@example.test successfully authenticated 103s 2025-10-26 12:38:07,342 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 103s 2025-10-26 12:38:07,345 INFO logout requested 103s 2025-10-26 12:38:07,346 INFO Logging out user dédé. 103s 2025-10-26 12:38:07,347 INFO User dédé logged out 103s 2025-10-26 12:38:07,359 INFO logout requested 103s 2025-10-26 12:38:07,360 INFO Logging out user dédé. 103s 2025-10-26 12:38:07,361 INFO User dédé logged out 103s 2025-10-26 12:38:07,372 INFO logout requested 103s 2025-10-26 12:38:07,373 INFO Logging out user dédé. 103s 2025-10-26 12:38:07,374 INFO User dédé logged out 103s 2025-10-26 12:38:07,386 INFO logout requested 103s 2025-10-26 12:38:07,387 INFO Logging out user dédé. 103s 2025-10-26 12:38:07,388 INFO User dédé logged out 103s PASSED 103s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2025-10-26 12:38:07,417 INFO Trying to authenticate http://127.0.0.1:8080 again 103s 2025-10-26 12:38:07,421 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 103s 2025-10-26 12:38:07,432 INFO User dédé@example.com successfully authenticated 103s 2025-10-26 12:38:07,443 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 103s 2025-10-26 12:38:07,456 INFO Trying to authenticate http://127.0.0.1:8081 again 103s 2025-10-26 12:38:07,461 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 103s 2025-10-26 12:38:07,472 INFO User dédé@example.org successfully authenticated 103s 2025-10-26 12:38:07,482 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 103s 2025-10-26 12:38:07,496 INFO Trying to authenticate http://127.0.0.1:8082 again 103s 2025-10-26 12:38:07,500 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 103s 2025-10-26 12:38:07,511 INFO User dédé@example.net successfully authenticated 103s 2025-10-26 12:38:07,522 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 103s 2025-10-26 12:38:07,535 INFO Trying to authenticate http://127.0.0.1:8083 again 103s 2025-10-26 12:38:07,540 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 103s 2025-10-26 12:38:07,551 INFO User dédé@example.test successfully authenticated 103s 2025-10-26 12:38:07,562 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 103s 2025-10-26 12:38:07,563 INFO logout requested 103s 2025-10-26 12:38:07,565 INFO Logging out user dédé@example.com. 103s 2025-10-26 12:38:07,570 INFO User dédé@example.com logged out 103s 2025-10-26 12:38:07,571 INFO logout requested 103s 2025-10-26 12:38:07,573 INFO Logging out user dédé@example.org. 103s 2025-10-26 12:38:07,579 INFO User dédé@example.org logged out 103s 2025-10-26 12:38:07,579 INFO logout requested 103s 2025-10-26 12:38:07,581 INFO Logging out user dédé@example.net. 103s 2025-10-26 12:38:07,587 INFO User dédé@example.net logged out 103s 2025-10-26 12:38:07,587 INFO logout requested 103s 2025-10-26 12:38:07,589 INFO Logging out user dédé@example.test. 103s 2025-10-26 12:38:07,594 INFO User dédé@example.test logged out 103s PASSED 103s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2025-10-26 12:38:07,612 WARNING A login attempt failed 103s PASSED 103s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 103s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2025-10-26 12:38:07,653 INFO Trying to authenticate http://127.0.0.1:8080 again 103s 2025-10-26 12:38:07,659 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 103s 2025-10-26 12:38:07,670 INFO User dédé@example.com successfully authenticated 103s 2025-10-26 12:38:07,681 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 103s 2025-10-26 12:38:07,695 INFO Trying to authenticate http://127.0.0.1:8081 again 103s 2025-10-26 12:38:07,699 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 103s 2025-10-26 12:38:07,711 INFO User dédé@example.org successfully authenticated 103s 2025-10-26 12:38:07,721 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 103s 2025-10-26 12:38:07,734 INFO Trying to authenticate http://127.0.0.1:8082 again 103s 2025-10-26 12:38:07,740 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 103s 2025-10-26 12:38:07,751 INFO User dédé@example.net successfully authenticated 103s 2025-10-26 12:38:07,762 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 103s 2025-10-26 12:38:07,775 INFO Trying to authenticate http://127.0.0.1:8083 again 103s 2025-10-26 12:38:07,780 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 103s 2025-10-26 12:38:07,792 INFO User dédé@example.test successfully authenticated 103s 2025-10-26 12:38:07,803 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 103s PASSED 103s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2025-10-26 12:38:07,821 INFO Trying to authenticate http://127.0.0.1:8080 again 103s 2025-10-26 12:38:07,826 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 103s 2025-10-26 12:38:07,837 INFO User dédé@example.com successfully authenticated 103s 2025-10-26 12:38:07,848 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 103s 2025-10-26 12:38:07,861 INFO Trying to authenticate http://127.0.0.1:8081 again 103s 2025-10-26 12:38:07,865 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 103s 2025-10-26 12:38:07,876 INFO User dédé@example.org successfully authenticated 103s 2025-10-26 12:38:07,888 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 103s 2025-10-26 12:38:07,901 INFO Trying to authenticate http://127.0.0.1:8082 again 103s 2025-10-26 12:38:07,906 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 103s 2025-10-26 12:38:07,917 INFO User dédé@example.net successfully authenticated 103s 2025-10-26 12:38:07,928 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 103s 2025-10-26 12:38:07,942 INFO Trying to authenticate http://127.0.0.1:8083 again 103s 2025-10-26 12:38:07,947 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 103s 2025-10-26 12:38:07,958 INFO User dédé@example.test successfully authenticated 103s 2025-10-26 12:38:07,969 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 104s 2025-10-26 12:38:07,984 INFO Trying to authenticate http://127.0.0.1:8080 again 104s 2025-10-26 12:38:07,989 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 104s 2025-10-26 12:38:07,999 INFO User dédé@example.com successfully authenticated 104s 2025-10-26 12:38:08,010 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 104s 2025-10-26 12:38:08,025 INFO Trying to authenticate http://127.0.0.1:8081 again 104s 2025-10-26 12:38:08,030 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 104s 2025-10-26 12:38:08,041 INFO User dédé@example.org successfully authenticated 104s 2025-10-26 12:38:08,052 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 104s 2025-10-26 12:38:08,064 INFO Trying to authenticate http://127.0.0.1:8082 again 104s 2025-10-26 12:38:08,074 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 104s 2025-10-26 12:38:08,086 INFO User dédé@example.net successfully authenticated 104s 2025-10-26 12:38:08,097 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 104s 2025-10-26 12:38:08,110 INFO Trying to authenticate http://127.0.0.1:8083 again 104s 2025-10-26 12:38:08,115 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 104s 2025-10-26 12:38:08,128 INFO User dédé@example.test successfully authenticated 104s 2025-10-26 12:38:08,139 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 104s 2025-10-26 12:38:08,141 WARNING User already authenticated, dropping federated authentication request 104s 2025-10-26 12:38:08,142 WARNING User already authenticated, dropping federated authentication request 104s 2025-10-26 12:38:08,143 WARNING User already authenticated, dropping federated authentication request 104s 2025-10-26 12:38:08,144 WARNING User already authenticated, dropping federated authentication request 104s PASSED 104s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2025-10-26 12:38:08,163 INFO Trying to authenticate http://127.0.0.1:8080 again 104s 2025-10-26 12:38:08,168 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 104s 2025-10-26 12:38:08,180 INFO User dédé@example.com successfully authenticated 104s 2025-10-26 12:38:08,191 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 104s 2025-10-26 12:38:08,204 INFO Trying to authenticate http://127.0.0.1:8081 again 104s 2025-10-26 12:38:08,209 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 104s 2025-10-26 12:38:08,223 INFO User dédé@example.org successfully authenticated 104s 2025-10-26 12:38:08,234 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 104s 2025-10-26 12:38:08,247 INFO Trying to authenticate http://127.0.0.1:8082 again 104s 2025-10-26 12:38:08,252 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 104s 2025-10-26 12:38:08,263 INFO User dédé@example.net successfully authenticated 104s 2025-10-26 12:38:08,285 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 104s 2025-10-26 12:38:08,298 INFO Trying to authenticate http://127.0.0.1:8083 again 104s 2025-10-26 12:38:08,302 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 104s 2025-10-26 12:38:08,313 INFO User dédé@example.test successfully authenticated 104s 2025-10-26 12:38:08,325 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 104s 2025-10-26 12:38:08,327 INFO logout requested 104s 2025-10-26 12:38:08,329 INFO Logging out user dédé@example.com. 104s 2025-10-26 12:38:08,334 INFO User dédé@example.com logged out 104s 2025-10-26 12:38:08,338 INFO logout requested 104s 2025-10-26 12:38:08,342 INFO Logging out user dédé@example.org. 104s 2025-10-26 12:38:08,348 INFO User dédé@example.org logged out 104s 2025-10-26 12:38:08,351 INFO logout requested 104s 2025-10-26 12:38:08,353 INFO Logging out user dédé@example.net. 104s 2025-10-26 12:38:08,358 INFO User dédé@example.net logged out 104s 2025-10-26 12:38:08,361 INFO logout requested 104s 2025-10-26 12:38:08,363 INFO Logging out user dédé@example.test. 104s 2025-10-26 12:38:08,369 INFO User dédé@example.test logged out 104s PASSED 104s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2025-10-26 12:38:08,389 INFO Trying to authenticate http://127.0.0.1:8080 again 104s 2025-10-26 12:38:08,394 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 104s 2025-10-26 12:38:08,405 INFO User dédé@example.com successfully authenticated 104s 2025-10-26 12:38:08,416 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 104s 2025-10-26 12:38:08,429 INFO Trying to authenticate http://127.0.0.1:8081 again 104s 2025-10-26 12:38:08,433 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 104s 2025-10-26 12:38:08,445 INFO User dédé@example.org successfully authenticated 104s 2025-10-26 12:38:08,456 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 104s 2025-10-26 12:38:08,468 INFO Trying to authenticate http://127.0.0.1:8082 again 104s 2025-10-26 12:38:08,473 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 104s 2025-10-26 12:38:08,484 INFO User dédé@example.net successfully authenticated 104s 2025-10-26 12:38:08,495 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 104s 2025-10-26 12:38:08,508 INFO Trying to authenticate http://127.0.0.1:8083 again 104s 2025-10-26 12:38:08,513 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 104s 2025-10-26 12:38:08,524 INFO User dédé@example.test successfully authenticated 104s 2025-10-26 12:38:08,535 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 104s 2025-10-26 12:38:08,541 INFO Trying to authenticate http://127.0.0.1:8080 again 104s 2025-10-26 12:38:08,546 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 104s 2025-10-26 12:38:08,558 INFO User dédé@example.com successfully authenticated 104s 2025-10-26 12:38:08,563 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 104s 2025-10-26 12:38:08,569 INFO Trying to authenticate http://127.0.0.1:8081 again 104s 2025-10-26 12:38:08,575 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 104s 2025-10-26 12:38:08,586 INFO User dédé@example.org successfully authenticated 104s 2025-10-26 12:38:08,592 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 104s 2025-10-26 12:38:08,599 INFO Trying to authenticate http://127.0.0.1:8082 again 104s 2025-10-26 12:38:08,605 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 104s 2025-10-26 12:38:08,617 INFO User dédé@example.net successfully authenticated 104s 2025-10-26 12:38:08,622 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 104s 2025-10-26 12:38:08,628 INFO Trying to authenticate http://127.0.0.1:8083 again 104s 2025-10-26 12:38:08,634 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 104s 2025-10-26 12:38:08,646 INFO User dédé@example.test successfully authenticated 104s 2025-10-26 12:38:08,651 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 104s PASSED 104s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 104s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 104s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 104s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2025-10-26 12:38:08,703 INFO User test successfully authenticated 104s PASSED 104s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2025-10-26 12:38:08,725 INFO User test successfully authenticated 104s PASSED 104s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2025-10-26 12:38:08,741 INFO User test successfully authenticated 104s 2025-10-26 12:38:08,754 INFO User test successfully authenticated 104s 2025-10-26 12:38:08,766 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 104s 2025-10-26 12:38:08,770 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')) 104s PASSED 104s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2025-10-26 12:38:08,784 INFO User test successfully authenticated 104s PASSED 104s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2025-10-26 12:38:08,805 INFO User test successfully authenticated 104s PASSED 104s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2025-10-26 12:38:08,829 INFO User test successfully authenticated 104s 2025-10-26 12:38:08,843 INFO Sending SLO requests to service http://127.0.0.1:35859 for user test 104s 2025-10-26 12:38:08,845 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 104s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 104s PASSED 104s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2025-10-26 12:38:08,860 INFO User test successfully authenticated 104s PASSED 104s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 104s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 104s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 104s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 104s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 104s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 104s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 104s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 104s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 104s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 104s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 104s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 104s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 104s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 104s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 104s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 104s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 104s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 104s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 105s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 105s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 105s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 105s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2025-10-26 12:38:09,870 INFO User test successfully authenticated 105s 2025-10-26 12:38:09,877 WARNING Service https://www.example.org not allowed. 105s PASSED 105s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success PASSED 105s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt 2025-10-26 12:38:09,893 INFO User test successfully authenticated 105s 2025-10-26 12:38:09,902 INFO Service ticket created for service https://www.example.com by user test. 105s 2025-10-26 12:38:09,918 INFO User test successfully authenticated 105s 2025-10-26 12:38:09,927 INFO Service ticket created for service https://www.example.com by user test. 105s PASSED 105s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn 2025-10-26 12:38:09,942 INFO User test successfully authenticated 105s PASSED 105s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged 2025-10-26 12:38:09,964 INFO User test successfully authenticated 105s PASSED 106s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2025-10-26 12:38:09,984 INFO User test successfully authenticated 106s 2025-10-26 12:38:09,992 WARNING User test seems authenticated but is not found in the database. 106s 2025-10-26 12:38:09,992 INFO Logging out user test. 106s 2025-10-26 12:38:09,993 INFO User test logged out 106s PASSED 106s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 106s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2025-10-26 12:38:10,017 INFO User test successfully authenticated 106s 2025-10-26 12:38:10,023 WARNING Username test not allowed on service restrict_user_fail 106s PASSED 106s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2025-10-26 12:38:10,041 WARNING A login attempt failed 106s 2025-10-26 12:38:10,061 WARNING A login attempt failed 106s 2025-10-26 12:38:10,080 WARNING A login attempt failed 106s PASSED 106s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2025-10-26 12:38:10,105 WARNING Received an invalid login ticket 106s PASSED 106s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2025-10-26 12:38:10,128 WARNING A login attempt failed 106s PASSED 106s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2025-10-26 12:38:10,153 INFO User test successfully authenticated 106s PASSED 106s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2025-10-26 12:38:10,175 INFO User test successfully authenticated 106s PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_renew 2025-10-26 12:38:11,300 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,315 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,319 INFO Service ticket created for service https://www.example.com by user test. 107s PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2025-10-26 12:38:11,334 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,346 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,352 INFO Service ticket created for service https://www.example.com by user test. 107s PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2025-10-26 12:38:11,367 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,373 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 107s 2025-10-26 12:38:11,377 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 107s 2025-10-26 12:38:11,383 INFO Service ticket created for service https://filter_success.example.com by user test. 107s PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2025-10-26 12:38:11,397 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,405 WARNING Username test not allowed on service restrict_user_fail 107s 2025-10-26 12:38:11,411 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 107s PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2025-10-26 12:38:11,426 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,431 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 107s 2025-10-26 12:38:11,437 INFO Service ticket created for service https://field_needed_success.example.com by user test. 107s PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2025-10-26 12:38:11,451 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,457 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 107s PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2025-10-26 12:38:11,474 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,481 WARNING User test seems authenticated but is not found in the database. 107s 2025-10-26 12:38:11,481 INFO Logging out user test. 107s 2025-10-26 12:38:11,482 INFO User test logged out 107s PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2025-10-26 12:38:11,522 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,530 INFO Service ticket created for service https://www.example.com/é by user test. 107s PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2025-10-26 12:38:11,546 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,553 INFO Service ticket created for service https://www.example.com by user test. 107s PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2025-10-26 12:38:11,569 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,582 WARNING Received a login attempt for an already-active user 107s 2025-10-26 12:38:11,586 INFO Service ticket created for service https://www.example.com by user test. 107s PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2025-10-26 12:38:11,601 INFO User test successfully authenticated 107s PASSED2025-10-26 12:38:11,607 WARNING Service https://www.example.org not allowed. 107s 107s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2025-10-26 12:38:11,615 WARNING Service https://www.example.net not allowed. 107s PASSED 107s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2025-10-26 12:38:11,630 WARNING Service https://www.example.net not allowed. 107s PASSED 107s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2025-10-26 12:38:11,647 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,651 INFO logout requested 107s 2025-10-26 12:38:11,651 INFO Logging out user test. 107s 2025-10-26 12:38:11,656 INFO User test logged out 107s PASSED 107s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2025-10-26 12:38:11,678 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,689 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,700 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,713 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,717 INFO logout requested 107s 2025-10-26 12:38:11,717 INFO Logging out user test from all sessions. 107s 2025-10-26 12:38:11,734 INFO User test logged out 107s PASSED 107s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2025-10-26 12:38:11,745 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,748 INFO logout requested 107s 2025-10-26 12:38:11,749 INFO Logging out user test. 107s 2025-10-26 12:38:11,753 INFO User test logged out 107s PASSED 107s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2025-10-26 12:38:11,757 INFO logout requested 107s PASSED 107s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2025-10-26 12:38:11,770 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,782 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,786 INFO logout requested 107s 2025-10-26 12:38:11,786 INFO Logging out user test from all sessions. 107s 2025-10-26 12:38:11,795 INFO User test logged out 107s PASSED 107s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2025-10-26 12:38:11,809 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,817 INFO Service ticket created for service http://127.0.0.1:42647 by user test. 107s 2025-10-26 12:38:11,822 INFO Validate: Service ticket ST-dYmjxW9NDnlLIQJhhIu0Kcv2UfpMvZxEBj6ApJEe2Ifb61uRZOQ0czmRSkH2U validated, user test authenticated on service http://127.0.0.1:42647 107s 2025-10-26 12:38:11,823 INFO logout requested 107s 2025-10-26 12:38:11,824 INFO Logging out user test. 107s 2025-10-26 12:38:11,827 INFO Sending SLO requests to service http://127.0.0.1:42647 for user test 107s 2025-10-26 12:38:11,830 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 107s 2025-10-26 12:38:11,832 INFO User test logged out 107s 2025-10-26 12:38:11,842 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,850 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 107s 2025-10-26 12:38:11,853 INFO Validate: Service ticket ST-88bTMVXwPXtFsUyNpfA9AK4rTAG0dCr6frT6h2CDImtfVoRKgKVCG1CKQfEXC validated, user test authenticated on service http://127.0.0.1:45678 107s 2025-10-26 12:38:11,855 INFO logout requested 107s 2025-10-26 12:38:11,856 INFO Logging out user test. 107s 2025-10-26 12:38:11,859 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 107s 2025-10-26 12:38:11,862 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 107s 2025-10-26 12:38:11,863 INFO User test logged out 107s 2025-10-26 12:38:11,874 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,881 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 107s 2025-10-26 12:38:11,885 INFO Validate: Service ticket ST-5iM6XfhDplUI6Een9oMnC9UvS7kCE3kTBnie8JS0FhkidYO36PUX57gQBCkva validated, user test authenticated on service http://127.0.0.1:45678 107s 2025-10-26 12:38:11,887 INFO logout requested 107s 2025-10-26 12:38:11,887 INFO Logging out user test. 107s 2025-10-26 12:38:11,891 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 107s 2025-10-26 12:38:11,893 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=45307): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 107s 2025-10-26 12:38:11,895 INFO User test logged out 107s PASSED 107s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2025-10-26 12:38:11,906 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,919 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,926 INFO logout requested 107s 2025-10-26 12:38:11,927 INFO Logging out user test. 107s 2025-10-26 12:38:11,931 INFO User test logged out 107s PASSED 107s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2025-10-26 12:38:11,951 INFO User test successfully authenticated 107s 2025-10-26 12:38:11,955 INFO logout requested 107s 2025-10-26 12:38:11,956 INFO Logging out user test. 107s 2025-10-26 12:38:11,960 INFO User test logged out 107s PASSED 108s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2025-10-26 12:38:11,977 INFO User test successfully authenticated 108s 2025-10-26 12:38:11,981 INFO logout requested 108s 2025-10-26 12:38:11,982 INFO Logging out user test. 108s 2025-10-26 12:38:11,986 INFO User test logged out 108s PASSED 108s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2025-10-26 12:38:12,004 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,008 INFO logout requested 108s 2025-10-26 12:38:12,009 INFO Logging out user test. 108s 2025-10-26 12:38:12,013 INFO User test logged out 108s PASSED 108s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2025-10-26 12:38:12,025 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,029 INFO logout requested 108s 2025-10-26 12:38:12,029 INFO Logging out user test. 108s 2025-10-26 12:38:12,034 INFO User test logged out 108s 2025-10-26 12:38:12,041 INFO logout requested 108s PASSED 108s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 108s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 108s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice 2025-10-26 12:38:12,061 WARNING Service https://www.example.org not allowed. 108s PASSED 108s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings PASSED 108s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 108s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2025-10-26 12:38:12,081 INFO User test successfully authenticated 108s PASSED 108s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 108s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2025-10-26 12:38:12,104 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,112 INFO Service ticket created for service https://www.example.com by user test. 108s 2025-10-26 12:38:12,114 WARNING Validate: service or ticket missing 108s 2025-10-26 12:38:12,115 WARNING Validate: service or ticket missing 108s PASSED 108s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2025-10-26 12:38:12,126 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,131 INFO Service ticket created for service https://www.example.com by user test. 108s 2025-10-26 12:38:12,135 INFO Validate: Service ticket ST-AF2rBFqWtSfSSPUsszfWX1AFsaaHQw4dSu6cDBr0HJyUKIJri64spWX8Rsy8B validated, user test authenticated on service https://www.example.com 108s 2025-10-26 12:38:12,144 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,148 INFO Service ticket created for service https://www.example.com by user test. 108s 2025-10-26 12:38:12,152 INFO Validate: Service ticket ST-m49JThuIVXmG7ZxMTc8SuiYutHdk13qECSHxqGYo2whTorXCMevJH9kuIBoVl validated, user test authenticated on service https://www.example.com 108s 2025-10-26 12:38:12,157 INFO Service ticket created for service https://www.example.com by user test. 108s 2025-10-26 12:38:12,159 WARNING Validate: Service ticket ST-2wzv6hryyWepM2gumflz14SoKPAizre6azE3nYQzXZaAQcp1ugfcEiBHcnLAT not found or already validated, auth to https://www.example.com failed 108s PASSED 108s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2025-10-26 12:38:12,171 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,179 INFO Service ticket created for service https://user_field.example.com by user test. 108s 2025-10-26 12:38:12,183 INFO Validate: Service ticket ST-CuBKarc3IU04HmqJj0nBvmdURJVoRbLNvV2RldRkV7DODNDjJCazojtvJt78E validated, user test authenticated on service https://user_field.example.com 108s 2025-10-26 12:38:12,191 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,199 INFO Service ticket created for service https://user_field_alt.example.com by user test. 108s 2025-10-26 12:38:12,203 INFO Validate: Service ticket ST-w2kgr2gIXoxXSyPKdNbXs2CbnMW000LlinV8iOnx82U8H9kGbe8E44AZURToD validated, user test authenticated on service https://user_field_alt.example.com 108s PASSED 108s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2025-10-26 12:38:12,215 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,224 INFO Service ticket created for service https://www.example.com by user test. 108s PASSED2025-10-26 12:38:12,227 WARNING Validate: Service ticket ST-HRyjD7KlwQFM525uBG3EuNoitvH3fjFCh3jXYxtMZ3zk3qBxMCAeGvwYDx28q not found or already validated, auth to https://www.example.org failed 108s 108s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2025-10-26 12:38:12,238 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,246 INFO Service ticket created for service https://www.example.com by user test. 108s 2025-10-26 12:38:12,249 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 108s PASSED 108s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2025-10-26 12:38:12,259 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,268 INFO Service ticket created for service https://www.example.com by user test. 108s 2025-10-26 12:38:12,272 INFO Validate: Service ticket ST-n2rLpqpKd5LedDeyfMK2nQVqngfBu0RcuKCiimptUsypcwLSiCQHyjIT29DT3 validated, user test authenticated on service https://www.example.com 108s PASSED 108s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2025-10-26 12:38:12,286 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,294 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 108s PASSED2025-10-26 12:38:12,296 WARNING ValidateService: missing ticket or service 108s 2025-10-26 12:38:12,298 WARNING ValidateService: missing ticket or service 108s 108s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2025-10-26 12:38:12,311 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,320 INFO Service ticket created for service http://127.0.0.1:43805 by user test. 108s 2025-10-26 12:38:12,329 INFO ValidateService: ticket ST-SUGX1qZqqG7oCiIGLjSkEEtDAqQ4bqpvGAVJmS2QZIBpFv4H2Bl4fQSDGCz0G validated for user test on service http://127.0.0.1:43805. Proxy Granting Ticket transmited to http://127.0.0.1:43805. 108s 2025-10-26 12:38:12,335 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 108s 2025-10-26 12:38:12,340 INFO ValidateService: ticket PT-aOxFf3thPNFEDd1d91uTON5thKU841PWzKBrPVeRcxrn1wBGyEXNsJvNJXLqC validated for user test on service http://127.0.0.1:45678. 108s 2025-10-26 12:38:12,349 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,357 INFO Service ticket created for service http://127.0.0.1:34499 by user test. 108s 2025-10-26 12:38:12,365 INFO ValidateService: ticket ST-oqCia6EJU4jVG0H1wb8LTJWy5G7SK6UnTJg1k37CAvOtpQLQZynYgdyPClQ9m validated for user test on service http://127.0.0.1:34499. Proxy Granting Ticket transmited to http://127.0.0.1:34499. 108s 2025-10-26 12:38:12,370 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 108s 2025-10-26 12:38:12,372 WARNING ValidateService: validation error: INVALID_TICKET PT-6xWjnJQ74j5r0F7gxoOfA7Ct7dxsBE6hSEg3f7oJyNJtCXXrBATFkbjwb1glK 108s PASSED 108s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2025-10-26 12:38:12,386 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,394 INFO Service ticket created for service https://replace_attribute.example.com by user test. 108s 2025-10-26 12:38:12,398 INFO ValidateService: ticket ST-LmneigbgoN7veTqdqkS3iGejWvFpu0l8y1YtYUtJlW9GOm8e8QT90s7aT1JqT validated for user test on service https://replace_attribute.example.com. 108s 2025-10-26 12:38:12,407 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,415 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 108s PASSED 108s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2025-10-26 12:38:12,420 INFO ValidateService: ticket ST-LxACwfvx53wedOlY6wYUqBjgkPnXpDI3tTKwfuNRM99vEbeLY2kt8aojlwObo validated for user test on service https://replace_attribute_list.example.com. 108s 2025-10-26 12:38:12,434 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,441 INFO Service ticket created for service http://127.0.0.1:35709 by user test. 108s 2025-10-26 12:38:12,449 INFO ValidateService: ticket ST-sozlk8koENM67n9NjdlzqxT0KOcnQlUuTQM1Q2ZlTFlUcb8WNfm8QgLnODKpN validated for user test on service http://127.0.0.1:35709. Proxy Granting Ticket transmited to http://127.0.0.1:35709. 108s PASSED 108s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2025-10-26 12:38:12,464 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,473 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 108s 2025-10-26 12:38:12,478 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 108s 2025-10-26 12:38:12,486 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,494 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 108s 2025-10-26 12:38:12,499 WARNING Service https://www.example.org not allowed. 108s 2025-10-26 12:38:12,499 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 108s PASSED 108s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2025-10-26 12:38:12,512 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,521 INFO Service ticket created for service https://127.0.0.1:41177 by user test. 108s 2025-10-26 12:38:12,528 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=41177): Max retries exceeded with url: /?pgtId=PGT-Idk7KVWrJjOH34TBWH63xxF6z5Hqa8VCeI67lzz89VJve61bNhodNkB16mb7&pgtIou=PGTIOU-tWz7wwQwN9yfkfuHLUMnDjIMwKyUfPpf1y2DgaRSkie0j2kIc4QKLDCoM (Caused by SSLError(SSLError(1, '[SSL: RECORD_LAYER_FAILURE] record layer failure (_ssl.c:1077)'))) 108s PASSED 108s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2025-10-26 12:38:12,541 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,545 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 108s 2025-10-26 12:38:12,549 INFO ValidateService: ticket ST-0mLWaVkJe3mMX90gs9iU1hheu6su6hOEhVcmr6cHs17dHwefUNG6W1Fe8Swdc validated for user test on service http://127.0.0.1:45678. 108s 2025-10-26 12:38:12,558 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,563 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 108s 2025-10-26 12:38:12,568 INFO ValidateService: ticket ST-6N6GAcuiUgpzK5Jw4I0WeLstu3UyDvk4OhjUyD7FbxjXexeO5wXMTBr9Y47kb validated for user test on service http://127.0.0.1:45678. 108s 2025-10-26 12:38:12,573 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 108s 2025-10-26 12:38:12,575 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 108s PASSED 108s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2025-10-26 12:38:12,588 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,596 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 108s PASSED 108s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2025-10-26 12:38:12,599 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 108s 2025-10-26 12:38:12,612 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,620 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 108s 2025-10-26 12:38:12,623 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 108s PASSED 108s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2025-10-26 12:38:12,635 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,643 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 108s 2025-10-26 12:38:12,646 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 108s PASSED 108s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2025-10-26 12:38:12,659 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,667 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 108s 2025-10-26 12:38:12,671 INFO ValidateService: ticket ST-eRCN0dnLLyOO3tcv1VcXz46AnkmDWCaYT6hBL6ctAy71kb5jCbpGUaGrDD24E validated for user test on service http://127.0.0.1:45678. 108s PASSED 108s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2025-10-26 12:38:12,685 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,692 INFO Service ticket created for service https://one_attribute.example.com by user test. 108s 2025-10-26 12:38:12,697 INFO ValidateService: ticket ST-9WtubFnEh8kBaBTKiPDkz53g7LAXrvxkniOmQ1diyIEXoStHmYsk4W457bpzS validated for user test on service https://one_attribute.example.com. 108s PASSED 108s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2025-10-26 12:38:12,711 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,719 INFO Service ticket created for service http://127.0.0.1:37003 by user test. 108s 2025-10-26 12:38:12,727 INFO ValidateService: ticket ST-RFmcXtzrGrRE2m5dACmvOmchR9JTLQCs9IHz6WxlrCKREQNBcGaFw6dX9iyVS validated for user test on service http://127.0.0.1:37003. Proxy Granting Ticket transmited to http://127.0.0.1:37003. 108s PASSED 108s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2025-10-26 12:38:12,742 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,750 INFO Service ticket created for service https://user_field.example.com by user test. 108s 2025-10-26 12:38:12,754 INFO ValidateService: ticket ST-jd6fwIRx5YinmFFkzQJvMrztFLyq9znCgioFlVSaWwcNiSc4sovDJTcLpwMZg validated for user test on service https://user_field.example.com. 108s 2025-10-26 12:38:12,763 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,771 INFO Service ticket created for service https://user_field_alt.example.com by user test. 108s 2025-10-26 12:38:12,776 INFO ValidateService: ticket ST-VBi1KCcyaIIqcq0YAEAumxzGtdjZspPhXo7ptzufhH5gj9ZLFLh456d4jqm1d validated for user test on service https://user_field_alt.example.com. 108s PASSED 108s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2025-10-26 12:38:12,793 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,801 INFO Service ticket created for service http://127.0.0.1:46099 by user test. 108s 2025-10-26 12:38:12,809 INFO ValidateService: ticket ST-NvryLm8WDVlFrFS11B4UqwiNUr7C7YjHGEnp2PgT7peSqZKnzzBI3KmiOgEs8 validated for user test on service http://127.0.0.1:46099. Proxy Granting Ticket transmited to http://127.0.0.1:46099. 108s 2025-10-26 12:38:12,810 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 108s 2025-10-26 12:38:12,811 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 108s PASSED 108s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2025-10-26 12:38:12,826 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,834 INFO Service ticket created for service http://127.0.0.1:36113 by user test. 108s 2025-10-26 12:38:12,842 INFO ValidateService: ticket ST-Bd27OxhYb3GT1RYVJXwB8vqPPIdml1AGNjKvHsxHJAGlXSgh081D63Aovlul8 validated for user test on service http://127.0.0.1:36113. Proxy Granting Ticket transmited to http://127.0.0.1:36113. 108s 2025-10-26 12:38:12,846 WARNING Username test not allowed on service restrict_user_fail 108s 2025-10-26 12:38:12,846 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 108s 2025-10-26 12:38:12,849 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 108s 2025-10-26 12:38:12,849 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 108s 2025-10-26 12:38:12,852 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 108s 2025-10-26 12:38:12,852 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 108s PASSED 108s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2025-10-26 12:38:12,867 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,875 INFO Service ticket created for service http://127.0.0.1:37381 by user test. 108s 2025-10-26 12:38:12,882 INFO ValidateService: ticket ST-ZNfEiaEMnLtuonaX8skGnKDs69gAtnPibDAAunCqJ9b3XxsJqsMiEtgxAO8aQ validated for user test on service http://127.0.0.1:37381. Proxy Granting Ticket transmited to http://127.0.0.1:37381. 108s 2025-10-26 12:38:12,885 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 108s PASSED 108s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2025-10-26 12:38:12,901 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,909 INFO Service ticket created for service http://127.0.0.1:38983 by user test. 108s 2025-10-26 12:38:12,917 INFO ValidateService: ticket ST-KaerkFoiq3KwIDcylgohYDWqDFFTbOdkcOhVkfF7iU0Z8c9ulkvZdRABAGJyu validated for user test on service http://127.0.0.1:38983. Proxy Granting Ticket transmited to http://127.0.0.1:38983. 108s 2025-10-26 12:38:12,919 WARNING Service https://www.example.org not allowed. 108s 2025-10-26 12:38:12,919 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 108s 2025-10-26 12:38:12,921 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:38983 does not allow proxy tickets 108s PASSED 108s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2025-10-26 12:38:12,936 INFO User test successfully authenticated 108s 2025-10-26 12:38:12,944 INFO Service ticket created for service http://127.0.0.1:37509 by user test. 108s 2025-10-26 12:38:12,952 INFO ValidateService: ticket ST-g8Z085Qd6XlGMBFAsV4r2XGlx6khvWmGxJmFebPXvlT18wA2qHosnDa7YOF5E validated for user test on service http://127.0.0.1:37509. Proxy Granting Ticket transmited to http://127.0.0.1:37509. 108s 2025-10-26 12:38:12,958 INFO Proxy ticket created for user test on service https://www.example.com. 108s 2025-10-26 12:38:12,962 INFO ValidateService: ticket PT-PUdpiDLzCLSFNvb2G9W94XMCfpk9NnyvG14WBvDGOjEEpCBcdEXYzks4qTGJa validated for user test on service https://www.example.com. 108s PASSED 109s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2025-10-26 12:38:12,977 INFO User test successfully authenticated 109s 2025-10-26 12:38:12,985 INFO Service ticket created for service https://www.example.com by user test. 109s 2025-10-26 12:38:12,989 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 109s PASSED 109s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2025-10-26 12:38:12,997 WARNING SamlValidate: validation error: AuthnFailed ticket ST-xwXZ3IEp9O4LXoQVrPZG49vhCJrUIHk0whGK8d8GdjRH6LHSFn1lvyVpyXnhI not found 109s 2025-10-26 12:38:12,999 WARNING SamlValidate: validation error: AuthnFailed ticket PT-AbFb1ZH1Ij2OOEzCDhvUCqggCKmn8ko5pcFi43keXmshIddsKiTnYF8PiS2ms not found 109s PASSED 109s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix 2025-10-26 12:38:13,005 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 109s PASSED 109s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml 2025-10-26 12:38:13,012 WARNING SamlValidate: validation error: VersionMismatch 109s PASSED 109s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2025-10-26 12:38:13,026 INFO User test successfully authenticated 109s 2025-10-26 12:38:13,034 INFO Service ticket created for service https://www.example.com by user test. 109s 2025-10-26 12:38:13,047 INFO User test successfully authenticated 109s 2025-10-26 12:38:13,056 INFO Service ticket created for service http://127.0.0.1:35777 by user test. 109s 2025-10-26 12:38:13,065 INFO ValidateService: ticket ST-e5HHZrqDORKRdjCAnKIK1YeYXwu02YQrvnm5lRJSPd6FN5e5ouG2Q9hOIKEFb validated for user test on service http://127.0.0.1:35777. Proxy Granting Ticket transmited to http://127.0.0.1:35777. 109s 2025-10-26 12:38:13,072 INFO Proxy ticket created for user test on service https://www.example.com. 109s 2025-10-26 12:38:13,076 INFO SamlValidate: ticket ST-vU4ywIWV3Q4254Nx5IsAZYuhkvLxVxXMP2XettfMYLhbP4KU6IKZyno7iwm4s validated for user test on service https://www.example.com. 109s 2025-10-26 12:38:13,080 INFO SamlValidate: ticket PT-PDt7qT6y1FHzjpst9QlHyQkUS2y1VbLq8ZdXmehgCaQmNTSoapjV5cGa9gqqe validated for user test on service https://www.example.com. 109s PASSED 109s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2025-10-26 12:38:13,096 INFO User test successfully authenticated 109s 2025-10-26 12:38:13,105 INFO Service ticket created for service https://field_needed_success.example.com by user test. 109s 2025-10-26 12:38:13,110 INFO SamlValidate: ticket ST-ATX9GBBoAkiZGQ5YJPCmLalzVxMkriggPDN4MRwTG2w7e7T0N47pXPG6pA578 validated for user test on service https://field_needed_success.example.com. 109s 2025-10-26 12:38:13,119 INFO User test successfully authenticated 109s 2025-10-26 12:38:13,129 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 109s 2025-10-26 12:38:13,133 INFO SamlValidate: ticket ST-M1KHgJxLZNz5RSZhfkdOw4x0J280eet8UMpuuVyfB43DygQ4QjJf81wv9tLhX validated for user test on service https://field_needed_success_alt.example.com. 109s PASSEDDestroying test database for alias 'default'... 109s 109s 109s =============================== warnings summary =============================== 109s cas_server/tests/test_federate.py: 8 warnings 109s cas_server/tests/test_view.py: 3 warnings 109s /tmp/autopkgtest.K9P1jf/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). 109s datetime.utcnow() + timedelta(seconds=max_age), 109s 109s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 109s /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') 109s return self.run(*args, **kwds) 109s 109s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 109s ======================= 128 passed, 12 warnings in 7.45s ======================= 109s 109s *************************** 109s *** Testing with python3.13 109s *************************** 109s 109s Content of current working folder: 109s 109s total 20 109s drwxr-xr-x 3 ubuntu ubuntu 4096 Oct 26 12:38 . 109s drwxrwxrwx 5 root root 4096 Oct 26 12:38 .. 109s drwxrwxr-x 10 ubuntu ubuntu 4096 Oct 26 12:38 cas_server 109s -rw-rw-r-- 1 ubuntu ubuntu 140 Aug 18 2024 pytest.ini 109s -rw-rw-r-- 1 ubuntu ubuntu 152 Aug 18 2024 setup.cfg 109s Running tests... 109s 109s ============================= test session starts ============================== 109s platform linux -- Python 3.13.9, pytest-8.3.5, pluggy-1.6.0 -- /usr/bin/python3.13 109s cachedir: .pytest_cache 109s django: version: 5.2.4, settings: cas_server.tests.settings (from ini) 109s rootdir: /tmp/autopkgtest.K9P1jf/autopkgtest_tmp 109s configfile: pytest.ini 109s testpaths: cas_server/tests/ 109s plugins: typeguard-4.4.2, django-4.11.1 110s collecting ... collected 128 items 110s 110s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 110s 2025-10-26 12:38:14,615 WARNING Identity provider suffix exemple.fr not found 110s 2025-10-26 12:38:14,634 INFO Got an invalid ticket ST-5dZsrDcTkhbjBDte9mn2v339jgP5GFbkVyMqiYRcH6VLIRupI8sh3fIQPHYCO from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 110s 2025-10-26 12:38:14,639 INFO Got an invalid ticket ST-wFMn23vOpWbklGZkosEilnUHZfsx1nrsRn7I6u1QSnDDnLBEiG6uUMdtR3JXK from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 110s PASSED 110s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2025-10-26 12:38:14,677 WARNING CAS_FEDERATE is False, set it to True to use federation 110s 2025-10-26 12:38:14,678 WARNING CAS_FEDERATE is False, set it to True to use federation 110s PASSED 110s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2025-10-26 12:38:14,696 INFO Trying to authenticate http://127.0.0.1:8080 again 110s 2025-10-26 12:38:14,702 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 110s 2025-10-26 12:38:14,718 INFO User dédé@example.com successfully authenticated 110s 2025-10-26 12:38:14,731 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 110s 2025-10-26 12:38:14,744 INFO Trying to authenticate http://127.0.0.1:8081 again 110s 2025-10-26 12:38:14,752 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 110s 2025-10-26 12:38:14,763 INFO User dédé@example.org successfully authenticated 110s 2025-10-26 12:38:14,774 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 110s 2025-10-26 12:38:14,787 INFO Trying to authenticate http://127.0.0.1:8082 again 110s 2025-10-26 12:38:14,797 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 110s 2025-10-26 12:38:14,808 INFO User dédé@example.net successfully authenticated 110s 2025-10-26 12:38:14,819 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 110s 2025-10-26 12:38:14,832 INFO Trying to authenticate http://127.0.0.1:8083 again 110s 2025-10-26 12:38:14,840 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 110s 2025-10-26 12:38:14,851 INFO User dédé@example.test successfully authenticated 110s 2025-10-26 12:38:14,862 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 110s 2025-10-26 12:38:14,903 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-4P0xzpNSvFOrQR7SBLjFar707rJDUJOhhoyHTeUESgPOahvphxBNeMOtcFSq8 110s 2025-10-26 12:38:14,930 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-p8jXNQf2eWmX0E7327Jv3B7nehB3SmpsWjlsafhkX3A5CFLYMq5x2xJ2nH2qu 110s PASSED 110s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 111s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2025-10-26 12:38:14,973 INFO Trying to authenticate http://127.0.0.1:8080 again 111s 2025-10-26 12:38:14,979 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 111s 2025-10-26 12:38:14,990 INFO User dédé@example.com successfully authenticated 111s 2025-10-26 12:38:15,001 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 111s 2025-10-26 12:38:15,014 INFO Trying to authenticate http://127.0.0.1:8081 again 111s 2025-10-26 12:38:15,020 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 111s 2025-10-26 12:38:15,031 INFO User dédé@example.org successfully authenticated 111s 2025-10-26 12:38:15,042 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 111s 2025-10-26 12:38:15,055 INFO Trying to authenticate http://127.0.0.1:8082 again 111s 2025-10-26 12:38:15,061 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 111s 2025-10-26 12:38:15,072 INFO User dédé@example.net successfully authenticated 111s 2025-10-26 12:38:15,083 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 111s 2025-10-26 12:38:15,096 INFO Trying to authenticate http://127.0.0.1:8083 again 111s 2025-10-26 12:38:15,103 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 111s 2025-10-26 12:38:15,114 INFO User dédé@example.test successfully authenticated 111s 2025-10-26 12:38:15,125 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 111s 2025-10-26 12:38:15,126 INFO logout requested 111s 2025-10-26 12:38:15,128 INFO Logging out user dédé@example.com. 111s 2025-10-26 12:38:15,134 INFO User dédé@example.com logged out 111s 2025-10-26 12:38:15,144 INFO logout requested 111s 2025-10-26 12:38:15,147 INFO logout requested 111s 2025-10-26 12:38:15,150 INFO Logging out user dédé@example.org. 111s 2025-10-26 12:38:15,155 INFO User dédé@example.org logged out 111s 2025-10-26 12:38:15,165 INFO logout requested 111s 2025-10-26 12:38:15,168 INFO logout requested 111s 2025-10-26 12:38:15,170 INFO Logging out user dédé@example.net. 111s 2025-10-26 12:38:15,176 INFO User dédé@example.net logged out 111s 2025-10-26 12:38:15,186 INFO logout requested 111s 2025-10-26 12:38:15,189 INFO logout requested 111s 2025-10-26 12:38:15,191 INFO Logging out user dédé@example.test. 111s 2025-10-26 12:38:15,196 INFO User dédé@example.test logged out 111s 2025-10-26 12:38:15,207 INFO logout requested 111s 2025-10-26 12:38:15,221 INFO Trying to authenticate http://127.0.0.1:8080 again 111s 2025-10-26 12:38:15,227 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 111s 2025-10-26 12:38:15,239 INFO User dédé@example.com successfully authenticated 111s 2025-10-26 12:38:15,250 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 111s 2025-10-26 12:38:15,262 INFO Trying to authenticate http://127.0.0.1:8081 again 111s 2025-10-26 12:38:15,268 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 111s 2025-10-26 12:38:15,280 INFO User dédé@example.org successfully authenticated 111s 2025-10-26 12:38:15,291 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 111s 2025-10-26 12:38:15,303 INFO Trying to authenticate http://127.0.0.1:8082 again 111s 2025-10-26 12:38:15,310 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 111s 2025-10-26 12:38:15,322 INFO User dédé@example.net successfully authenticated 111s 2025-10-26 12:38:15,333 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 111s 2025-10-26 12:38:15,345 INFO Trying to authenticate http://127.0.0.1:8083 again 111s 2025-10-26 12:38:15,352 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 111s 2025-10-26 12:38:15,364 INFO User dédé@example.test successfully authenticated 111s 2025-10-26 12:38:15,375 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 111s 2025-10-26 12:38:15,377 INFO logout requested 111s 2025-10-26 12:38:15,378 INFO Logging out user dédé. 111s 2025-10-26 12:38:15,379 INFO User dédé logged out 111s 2025-10-26 12:38:15,390 INFO logout requested 111s 2025-10-26 12:38:15,391 INFO Logging out user dédé. 111s 2025-10-26 12:38:15,392 INFO User dédé logged out 111s 2025-10-26 12:38:15,404 INFO logout requested 111s 2025-10-26 12:38:15,405 INFO Logging out user dédé. 111s 2025-10-26 12:38:15,406 INFO User dédé logged out 111s 2025-10-26 12:38:15,418 INFO logout requested 111s 2025-10-26 12:38:15,419 INFO Logging out user dédé. 111s 2025-10-26 12:38:15,420 INFO User dédé logged out 111s PASSED 111s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2025-10-26 12:38:15,448 INFO Trying to authenticate http://127.0.0.1:8080 again 111s 2025-10-26 12:38:15,454 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 111s 2025-10-26 12:38:15,465 INFO User dédé@example.com successfully authenticated 111s 2025-10-26 12:38:15,476 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 111s 2025-10-26 12:38:15,491 INFO Trying to authenticate http://127.0.0.1:8081 again 111s 2025-10-26 12:38:15,497 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 111s 2025-10-26 12:38:15,508 INFO User dédé@example.org successfully authenticated 111s 2025-10-26 12:38:15,519 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 111s 2025-10-26 12:38:15,559 INFO Trying to authenticate http://127.0.0.1:8082 again 111s 2025-10-26 12:38:15,565 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 111s 2025-10-26 12:38:15,576 INFO User dédé@example.net successfully authenticated 111s 2025-10-26 12:38:15,587 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 111s 2025-10-26 12:38:15,600 INFO Trying to authenticate http://127.0.0.1:8083 again 111s 2025-10-26 12:38:15,611 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 111s 2025-10-26 12:38:15,622 INFO User dédé@example.test successfully authenticated 111s 2025-10-26 12:38:15,633 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 111s 2025-10-26 12:38:15,634 INFO logout requested 111s 2025-10-26 12:38:15,636 INFO Logging out user dédé@example.com. 111s 2025-10-26 12:38:15,642 INFO User dédé@example.com logged out 111s 2025-10-26 12:38:15,642 INFO logout requested 111s 2025-10-26 12:38:15,644 INFO Logging out user dédé@example.org. 111s 2025-10-26 12:38:15,650 INFO User dédé@example.org logged out 111s 2025-10-26 12:38:15,650 INFO logout requested 111s 2025-10-26 12:38:15,652 INFO Logging out user dédé@example.net. 111s 2025-10-26 12:38:15,657 INFO User dédé@example.net logged out 111s 2025-10-26 12:38:15,658 INFO logout requested 111s 2025-10-26 12:38:15,660 INFO Logging out user dédé@example.test. 111s 2025-10-26 12:38:15,666 INFO User dédé@example.test logged out 111s PASSED 111s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2025-10-26 12:38:15,682 WARNING A login attempt failed 111s PASSED 111s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 111s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2025-10-26 12:38:15,723 INFO Trying to authenticate http://127.0.0.1:8080 again 111s 2025-10-26 12:38:15,729 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 111s 2025-10-26 12:38:15,741 INFO User dédé@example.com successfully authenticated 111s 2025-10-26 12:38:15,752 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 111s 2025-10-26 12:38:15,764 INFO Trying to authenticate http://127.0.0.1:8081 again 111s 2025-10-26 12:38:15,772 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 111s 2025-10-26 12:38:15,783 INFO User dédé@example.org successfully authenticated 111s 2025-10-26 12:38:15,794 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 111s 2025-10-26 12:38:15,807 INFO Trying to authenticate http://127.0.0.1:8082 again 111s 2025-10-26 12:38:15,816 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 111s 2025-10-26 12:38:15,828 INFO User dédé@example.net successfully authenticated 111s 2025-10-26 12:38:15,839 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 111s 2025-10-26 12:38:15,851 INFO Trying to authenticate http://127.0.0.1:8083 again 111s 2025-10-26 12:38:15,858 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 111s 2025-10-26 12:38:15,870 INFO User dédé@example.test successfully authenticated 111s 2025-10-26 12:38:15,881 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 111s PASSED 111s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2025-10-26 12:38:15,901 INFO Trying to authenticate http://127.0.0.1:8080 again 111s 2025-10-26 12:38:15,907 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 111s 2025-10-26 12:38:15,919 INFO User dédé@example.com successfully authenticated 111s 2025-10-26 12:38:15,929 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 111s 2025-10-26 12:38:15,942 INFO Trying to authenticate http://127.0.0.1:8081 again 111s 2025-10-26 12:38:15,948 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 111s 2025-10-26 12:38:15,959 INFO User dédé@example.org successfully authenticated 111s 2025-10-26 12:38:15,970 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 112s 2025-10-26 12:38:15,982 INFO Trying to authenticate http://127.0.0.1:8082 again 112s 2025-10-26 12:38:15,990 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 112s 2025-10-26 12:38:16,002 INFO User dédé@example.net successfully authenticated 112s 2025-10-26 12:38:16,012 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 112s 2025-10-26 12:38:16,025 INFO Trying to authenticate http://127.0.0.1:8083 again 112s 2025-10-26 12:38:16,032 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 112s 2025-10-26 12:38:16,044 INFO User dédé@example.test successfully authenticated 112s 2025-10-26 12:38:16,055 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 112s 2025-10-26 12:38:16,067 INFO Trying to authenticate http://127.0.0.1:8080 again 112s 2025-10-26 12:38:16,074 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 112s 2025-10-26 12:38:16,087 INFO User dédé@example.com successfully authenticated 112s 2025-10-26 12:38:16,098 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 112s 2025-10-26 12:38:16,110 INFO Trying to authenticate http://127.0.0.1:8081 again 112s 2025-10-26 12:38:16,118 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 112s 2025-10-26 12:38:16,130 INFO User dédé@example.org successfully authenticated 112s 2025-10-26 12:38:16,140 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 112s 2025-10-26 12:38:16,153 INFO Trying to authenticate http://127.0.0.1:8082 again 112s 2025-10-26 12:38:16,159 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 112s 2025-10-26 12:38:16,170 INFO User dédé@example.net successfully authenticated 112s 2025-10-26 12:38:16,182 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 112s 2025-10-26 12:38:16,194 INFO Trying to authenticate http://127.0.0.1:8083 again 112s 2025-10-26 12:38:16,202 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 112s 2025-10-26 12:38:16,213 INFO User dédé@example.test successfully authenticated 112s 2025-10-26 12:38:16,224 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 112s 2025-10-26 12:38:16,226 WARNING User already authenticated, dropping federated authentication request 112s 2025-10-26 12:38:16,227 WARNING User already authenticated, dropping federated authentication request 112s 2025-10-26 12:38:16,228 WARNING User already authenticated, dropping federated authentication request 112s 2025-10-26 12:38:16,229 WARNING User already authenticated, dropping federated authentication request 112s PASSED 112s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2025-10-26 12:38:16,247 INFO Trying to authenticate http://127.0.0.1:8080 again 112s 2025-10-26 12:38:16,253 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 112s 2025-10-26 12:38:16,265 INFO User dédé@example.com successfully authenticated 112s 2025-10-26 12:38:16,276 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 112s 2025-10-26 12:38:16,288 INFO Trying to authenticate http://127.0.0.1:8081 again 112s 2025-10-26 12:38:16,295 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 112s 2025-10-26 12:38:16,307 INFO User dédé@example.org successfully authenticated 112s 2025-10-26 12:38:16,317 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 112s 2025-10-26 12:38:16,330 INFO Trying to authenticate http://127.0.0.1:8082 again 112s 2025-10-26 12:38:16,336 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 112s 2025-10-26 12:38:16,347 INFO User dédé@example.net successfully authenticated 112s 2025-10-26 12:38:16,358 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 112s 2025-10-26 12:38:16,370 INFO Trying to authenticate http://127.0.0.1:8083 again 112s 2025-10-26 12:38:16,377 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 112s 2025-10-26 12:38:16,389 INFO User dédé@example.test successfully authenticated 112s 2025-10-26 12:38:16,400 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 112s 2025-10-26 12:38:16,401 INFO logout requested 112s 2025-10-26 12:38:16,403 INFO Logging out user dédé@example.com. 112s 2025-10-26 12:38:16,409 INFO User dédé@example.com logged out 112s 2025-10-26 12:38:16,412 INFO logout requested 112s 2025-10-26 12:38:16,414 INFO Logging out user dédé@example.org. 112s 2025-10-26 12:38:16,419 INFO User dédé@example.org logged out 112s 2025-10-26 12:38:16,422 INFO logout requested 112s 2025-10-26 12:38:16,424 INFO Logging out user dédé@example.net. 112s 2025-10-26 12:38:16,429 INFO User dédé@example.net logged out 112s 2025-10-26 12:38:16,432 INFO logout requested 112s 2025-10-26 12:38:16,434 INFO Logging out user dédé@example.test. 112s 2025-10-26 12:38:16,439 INFO User dédé@example.test logged out 112s PASSED 112s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2025-10-26 12:38:16,460 INFO Trying to authenticate http://127.0.0.1:8080 again 112s 2025-10-26 12:38:16,467 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 112s 2025-10-26 12:38:16,478 INFO User dédé@example.com successfully authenticated 112s 2025-10-26 12:38:16,489 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 112s 2025-10-26 12:38:16,502 INFO Trying to authenticate http://127.0.0.1:8081 again 112s 2025-10-26 12:38:16,510 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 112s 2025-10-26 12:38:16,521 INFO User dédé@example.org successfully authenticated 112s 2025-10-26 12:38:16,532 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 112s 2025-10-26 12:38:16,545 INFO Trying to authenticate http://127.0.0.1:8082 again 112s 2025-10-26 12:38:16,556 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 112s 2025-10-26 12:38:16,568 INFO User dédé@example.net successfully authenticated 112s 2025-10-26 12:38:16,579 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 112s 2025-10-26 12:38:16,592 INFO Trying to authenticate http://127.0.0.1:8083 again 112s 2025-10-26 12:38:16,599 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 112s 2025-10-26 12:38:16,610 INFO User dédé@example.test successfully authenticated 112s 2025-10-26 12:38:16,621 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 112s 2025-10-26 12:38:16,626 INFO Trying to authenticate http://127.0.0.1:8080 again 112s 2025-10-26 12:38:16,632 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 112s 2025-10-26 12:38:16,646 INFO User dédé@example.com successfully authenticated 112s 2025-10-26 12:38:16,652 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 112s 2025-10-26 12:38:16,658 INFO Trying to authenticate http://127.0.0.1:8081 again 112s 2025-10-26 12:38:16,664 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 112s 2025-10-26 12:38:16,675 INFO User dédé@example.org successfully authenticated 112s 2025-10-26 12:38:16,681 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 112s 2025-10-26 12:38:16,687 INFO Trying to authenticate http://127.0.0.1:8082 again 112s 2025-10-26 12:38:16,693 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 112s 2025-10-26 12:38:16,705 INFO User dédé@example.net successfully authenticated 112s 2025-10-26 12:38:16,711 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 112s 2025-10-26 12:38:16,716 INFO Trying to authenticate http://127.0.0.1:8083 again 112s 2025-10-26 12:38:16,723 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 112s 2025-10-26 12:38:16,734 INFO User dédé@example.test successfully authenticated 112s 2025-10-26 12:38:16,740 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 112s PASSED 112s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 112s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 112s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 112s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2025-10-26 12:38:16,790 INFO User test successfully authenticated 112s PASSED 112s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2025-10-26 12:38:16,811 INFO User test successfully authenticated 112s PASSED 112s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2025-10-26 12:38:16,827 INFO User test successfully authenticated 112s 2025-10-26 12:38:16,840 INFO User test successfully authenticated 112s 2025-10-26 12:38:16,852 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 112s 2025-10-26 12:38:16,857 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')) 112s PASSED 112s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2025-10-26 12:38:16,871 INFO User test successfully authenticated 112s PASSED 112s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2025-10-26 12:38:16,891 INFO User test successfully authenticated 112s PASSED 112s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2025-10-26 12:38:16,915 INFO User test successfully authenticated 112s 2025-10-26 12:38:16,929 INFO Sending SLO requests to service http://127.0.0.1:36047 for user test 112s 2025-10-26 12:38:16,933 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 112s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 112s PASSED 112s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2025-10-26 12:38:16,947 INFO User test successfully authenticated 112s PASSED 112s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 112s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 112s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 112s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 112s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 112s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 112s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 112s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 113s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 113s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 113s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 113s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 113s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 113s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 113s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 113s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 113s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 113s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 113s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 113s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 113s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 113s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 114s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2025-10-26 12:38:17,972 INFO User test successfully authenticated 114s 2025-10-26 12:38:17,978 WARNING Service https://www.example.org not allowed. 114s PASSED 114s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success PASSED 114s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt 2025-10-26 12:38:17,993 INFO User test successfully authenticated 114s 2025-10-26 12:38:18,001 INFO Service ticket created for service https://www.example.com by user test. 114s 2025-10-26 12:38:18,016 INFO User test successfully authenticated 114s 2025-10-26 12:38:18,024 INFO Service ticket created for service https://www.example.com by user test. 114s PASSED 114s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn 2025-10-26 12:38:18,038 INFO User test successfully authenticated 114s PASSED 114s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged 2025-10-26 12:38:18,059 INFO User test successfully authenticated 114s PASSED 114s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2025-10-26 12:38:18,079 INFO User test successfully authenticated 114s 2025-10-26 12:38:18,085 WARNING User test seems authenticated but is not found in the database. 114s 2025-10-26 12:38:18,086 INFO Logging out user test. 114s 2025-10-26 12:38:18,087 INFO User test logged out 114s PASSED 114s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 114s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2025-10-26 12:38:18,108 INFO User test successfully authenticated 114s 2025-10-26 12:38:18,114 WARNING Username test not allowed on service restrict_user_fail 114s PASSED 114s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2025-10-26 12:38:18,131 WARNING A login attempt failed 114s 2025-10-26 12:38:18,148 WARNING A login attempt failed 114s 2025-10-26 12:38:18,164 WARNING A login attempt failed 114s PASSED 114s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2025-10-26 12:38:18,186 WARNING Received an invalid login ticket 114s PASSED 114s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2025-10-26 12:38:18,207 WARNING A login attempt failed 114s PASSED 114s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2025-10-26 12:38:18,228 INFO User test successfully authenticated 114s PASSED 114s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2025-10-26 12:38:18,247 INFO User test successfully authenticated 114s PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_renew 2025-10-26 12:38:19,354 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,367 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,371 INFO Service ticket created for service https://www.example.com by user test. 115s PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2025-10-26 12:38:19,386 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,399 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,403 INFO Service ticket created for service https://www.example.com by user test. 115s PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2025-10-26 12:38:19,417 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,424 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 115s 2025-10-26 12:38:19,428 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 115s 2025-10-26 12:38:19,434 INFO Service ticket created for service https://filter_success.example.com by user test. 115s PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2025-10-26 12:38:19,448 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,454 WARNING Username test not allowed on service restrict_user_fail 115s 2025-10-26 12:38:19,460 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 115s PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2025-10-26 12:38:19,475 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,480 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 115s 2025-10-26 12:38:19,486 INFO Service ticket created for service https://field_needed_success.example.com by user test. 115s PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2025-10-26 12:38:19,500 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,506 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 115s PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2025-10-26 12:38:19,522 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,529 WARNING User test seems authenticated but is not found in the database. 115s 2025-10-26 12:38:19,529 INFO Logging out user test. 115s 2025-10-26 12:38:19,530 INFO User test logged out 115s PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2025-10-26 12:38:19,569 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,577 INFO Service ticket created for service https://www.example.com/é by user test. 115s PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2025-10-26 12:38:19,592 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,600 INFO Service ticket created for service https://www.example.com by user test. 115s PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2025-10-26 12:38:19,616 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,628 WARNING Received a login attempt for an already-active user 115s 2025-10-26 12:38:19,631 INFO Service ticket created for service https://www.example.com by user test. 115s PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2025-10-26 12:38:19,647 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,652 WARNING Service https://www.example.org not allowed. 115s PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2025-10-26 12:38:19,660 WARNING Service https://www.example.net not allowed. 115s PASSED 115s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2025-10-26 12:38:19,673 WARNING Service https://www.example.net not allowed. 115s PASSED 115s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2025-10-26 12:38:19,690 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,694 INFO logout requested 115s 2025-10-26 12:38:19,695 INFO Logging out user test. 115s PASSED 115s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2025-10-26 12:38:19,700 INFO User test logged out 115s 2025-10-26 12:38:19,711 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,722 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,734 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,746 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,758 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,770 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,781 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,793 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,805 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,817 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,821 INFO logout requested 115s 2025-10-26 12:38:19,822 INFO Logging out user test from all sessions. 115s PASSED 115s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2025-10-26 12:38:19,861 INFO User test logged out 115s 2025-10-26 12:38:19,872 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,876 INFO logout requested 115s 2025-10-26 12:38:19,877 INFO Logging out user test. 115s PASSED 115s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2025-10-26 12:38:19,881 INFO User test logged out 115s 2025-10-26 12:38:19,884 INFO logout requested 115s PASSED 115s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2025-10-26 12:38:19,897 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,909 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,913 INFO logout requested 115s 2025-10-26 12:38:19,914 INFO Logging out user test from all sessions. 115s PASSED 115s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2025-10-26 12:38:19,922 INFO User test logged out 115s 2025-10-26 12:38:19,938 INFO User test successfully authenticated 115s 2025-10-26 12:38:19,946 INFO Service ticket created for service http://127.0.0.1:33489 by user test. 115s 2025-10-26 12:38:19,950 INFO Validate: Service ticket ST-GJrpVjk0KqakVGrKahdSD3Bsn9hEcTsbOZBfWnxAwD4XnOUOcnSDZXBv1RmPg validated, user test authenticated on service http://127.0.0.1:33489 115s 2025-10-26 12:38:19,952 INFO logout requested 115s 2025-10-26 12:38:19,953 INFO Logging out user test. 115s 2025-10-26 12:38:19,956 INFO Sending SLO requests to service http://127.0.0.1:33489 for user test 115s 2025-10-26 12:38:19,964 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 115s 2025-10-26 12:38:19,966 INFO User test logged out 116s 2025-10-26 12:38:19,977 INFO User test successfully authenticated 116s 2025-10-26 12:38:19,985 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 116s 2025-10-26 12:38:19,989 INFO Validate: Service ticket ST-9DBwQBsbDmQid1zkukmgY0OuqfoHncEgnvJJb00A71Y0Z8R6EApOklKzdeqNp validated, user test authenticated on service http://127.0.0.1:45678 116s 2025-10-26 12:38:19,990 INFO logout requested 116s 2025-10-26 12:38:19,991 INFO Logging out user test. 116s 2025-10-26 12:38:19,995 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 116s 2025-10-26 12:38:20,001 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 116s 2025-10-26 12:38:20,003 INFO User test logged out 116s 2025-10-26 12:38:20,016 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,024 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 116s 2025-10-26 12:38:20,028 INFO Validate: Service ticket ST-ChrX2Wjj5ysxKNYcP4t0HT5jIYWsZ3JnEYY5aSHASUlpOGHrwANfZ2N01w7Uv validated, user test authenticated on service http://127.0.0.1:45678 116s 2025-10-26 12:38:20,029 INFO logout requested 116s 2025-10-26 12:38:20,030 INFO Logging out user test. 116s 2025-10-26 12:38:20,033 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 116s 2025-10-26 12:38:20,041 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=44013): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 116s 2025-10-26 12:38:20,043 INFO User test logged out 116s PASSED 116s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2025-10-26 12:38:20,055 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,067 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,075 INFO logout requested 116s 2025-10-26 12:38:20,076 INFO Logging out user test. 116s 2025-10-26 12:38:20,080 INFO User test logged out 116s PASSED 116s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2025-10-26 12:38:20,102 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,106 INFO logout requested 116s 2025-10-26 12:38:20,107 INFO Logging out user test. 116s 2025-10-26 12:38:20,111 INFO User test logged out 116s PASSED 116s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2025-10-26 12:38:20,129 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,133 INFO logout requested 116s 2025-10-26 12:38:20,133 INFO Logging out user test. 116s 2025-10-26 12:38:20,138 INFO User test logged out 116s PASSED 116s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2025-10-26 12:38:20,155 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,159 INFO logout requested 116s 2025-10-26 12:38:20,160 INFO Logging out user test. 116s 2025-10-26 12:38:20,165 INFO User test logged out 116s PASSED 116s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2025-10-26 12:38:20,177 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,180 INFO logout requested 116s 2025-10-26 12:38:20,181 INFO Logging out user test. 116s 2025-10-26 12:38:20,185 INFO User test logged out 116s 2025-10-26 12:38:20,193 INFO logout requested 116s PASSED 116s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 116s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 116s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice PASSED 116s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings 2025-10-26 12:38:20,213 WARNING Service https://www.example.org not allowed. 116s PASSED 116s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 116s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2025-10-26 12:38:20,233 INFO User test successfully authenticated 116s PASSED 116s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 116s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2025-10-26 12:38:20,256 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,264 INFO Service ticket created for service https://www.example.com by user test. 116s PASSED 116s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2025-10-26 12:38:20,266 WARNING Validate: service or ticket missing 116s 2025-10-26 12:38:20,267 WARNING Validate: service or ticket missing 116s 2025-10-26 12:38:20,279 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,283 INFO Service ticket created for service https://www.example.com by user test. 116s 2025-10-26 12:38:20,287 INFO Validate: Service ticket ST-GuUv03tEePHUjFHokAjLPNU61HhZKEFKnYeKJCFekk8A61CMazTGgi7HNahSR validated, user test authenticated on service https://www.example.com 116s 2025-10-26 12:38:20,296 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,300 INFO Service ticket created for service https://www.example.com by user test. 116s 2025-10-26 12:38:20,304 INFO Validate: Service ticket ST-idPy3I2m0jwtmSBYjnOpEnaVuwj7oRVF8A5OnOmi6uIaO75Ws0hO5o2w6ZEUV validated, user test authenticated on service https://www.example.com 116s 2025-10-26 12:38:20,308 INFO Service ticket created for service https://www.example.com by user test. 116s 2025-10-26 12:38:20,311 WARNING Validate: Service ticket ST-VnAJq2u1puMMrca3pLWH7M6ryqLf57RxqkmHOdrO02vUHMXLg1pSbtxzVUu91 not found or already validated, auth to https://www.example.com failed 116s PASSED 116s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2025-10-26 12:38:20,322 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,330 INFO Service ticket created for service https://user_field.example.com by user test. 116s 2025-10-26 12:38:20,334 INFO Validate: Service ticket ST-KmvvRNcneKsmEizWn1kfOaOZo1uMBugvjKxNcahVmaRQWom8etoULO6MhUwJv validated, user test authenticated on service https://user_field.example.com 116s 2025-10-26 12:38:20,343 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,351 INFO Service ticket created for service https://user_field_alt.example.com by user test. 116s 2025-10-26 12:38:20,354 INFO Validate: Service ticket ST-UaQNrouSel4LznuCiD8FKZCnbq7FpEoHxRNPEwuHmNyHoaD0K1j9ihNzk9tGq validated, user test authenticated on service https://user_field_alt.example.com 116s PASSED 116s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2025-10-26 12:38:20,367 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,375 INFO Service ticket created for service https://www.example.com by user test. 116s 2025-10-26 12:38:20,378 WARNING Validate: Service ticket ST-9b7LQvKPv8M46XrbDWqcX47yKudIwgPJRIJVLaPEIUafBBualRouZSpBCL9jS not found or already validated, auth to https://www.example.org failed 116s PASSED 116s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2025-10-26 12:38:20,389 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,396 INFO Service ticket created for service https://www.example.com by user test. 116s 2025-10-26 12:38:20,399 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 116s PASSED 116s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2025-10-26 12:38:20,411 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,419 INFO Service ticket created for service https://www.example.com by user test. 116s 2025-10-26 12:38:20,422 INFO Validate: Service ticket ST-ErLwInjXmCy8HpURUSFk2ZiBc2SR912FRaspeYn4TenFApYVTjj7mLv9gUcGI validated, user test authenticated on service https://www.example.com 116s PASSED 116s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2025-10-26 12:38:20,437 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,444 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 116s 2025-10-26 12:38:20,447 WARNING ValidateService: missing ticket or service 116s 2025-10-26 12:38:20,448 WARNING ValidateService: missing ticket or service 116s PASSED 116s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2025-10-26 12:38:20,462 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,470 INFO Service ticket created for service http://127.0.0.1:40081 by user test. 116s 2025-10-26 12:38:20,478 INFO ValidateService: ticket ST-01pIxyJ289v9QU1OcH2R1bOb5Kf1o2I6mlLdePSNiTTqGIhzpPqCf0BoDtzrZ validated for user test on service http://127.0.0.1:40081. Proxy Granting Ticket transmited to http://127.0.0.1:40081. 116s 2025-10-26 12:38:20,484 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 116s 2025-10-26 12:38:20,489 INFO ValidateService: ticket PT-aWifkfIZYGljp7AJ6vME7HgHtxci72lMU6wsT7rJ2YdmFsFX9LUacbqSFKrNl validated for user test on service http://127.0.0.1:45678. 116s 2025-10-26 12:38:20,499 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,507 INFO Service ticket created for service http://127.0.0.1:46775 by user test. 116s 2025-10-26 12:38:20,514 INFO ValidateService: ticket ST-kKxjSsVK1zeemAF2xmJKL5yxBlZQx1gZHDTd8cj0ytyH9vfSNi76d0F300i8W validated for user test on service http://127.0.0.1:46775. Proxy Granting Ticket transmited to http://127.0.0.1:46775. 116s PASSED 116s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2025-10-26 12:38:20,520 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 116s 2025-10-26 12:38:20,522 WARNING ValidateService: validation error: INVALID_TICKET PT-iOVwjZv57WFEAVEbA3KtSt7TWLTmj5y9gNPw68WfPT8tHQWQmYYD60t5SL2fS 116s 2025-10-26 12:38:20,535 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,543 INFO Service ticket created for service https://replace_attribute.example.com by user test. 116s 2025-10-26 12:38:20,548 INFO ValidateService: ticket ST-sNQgzoWm93VgUkbO3NePGB1UFkMDCU6hI0nYCf29T6KLqhlKpTnOo22onJ9Ck validated for user test on service https://replace_attribute.example.com. 116s 2025-10-26 12:38:20,557 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,565 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 116s 2025-10-26 12:38:20,569 INFO ValidateService: ticket ST-qM3vduoFcVSMCsUryedeLVV1S54JrF32af5NvydnUPqAzECQbYtEaVmOploAS validated for user test on service https://replace_attribute_list.example.com. 116s PASSED 116s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2025-10-26 12:38:20,583 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,591 INFO Service ticket created for service http://127.0.0.1:42775 by user test. 116s 2025-10-26 12:38:20,604 INFO ValidateService: ticket ST-LP2kV2XEjWTOjxhSbpswuq6OEZrzlwMDtxudjwk9eY1fX53r6mbccO0E4vN65 validated for user test on service http://127.0.0.1:42775. Proxy Granting Ticket transmited to http://127.0.0.1:42775. 116s PASSED 116s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2025-10-26 12:38:20,618 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,626 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 116s 2025-10-26 12:38:20,631 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 116s 2025-10-26 12:38:20,640 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,649 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 116s PASSED 116s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2025-10-26 12:38:20,653 WARNING Service https://www.example.org not allowed. 116s 2025-10-26 12:38:20,653 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 116s 2025-10-26 12:38:20,672 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,679 INFO Service ticket created for service https://127.0.0.1:43505 by user test. 116s 2025-10-26 12:38:20,695 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=43505): Max retries exceeded with url: /?pgtId=PGT-DEBobR3QcTa6DF9Pg07It6aJhWcfy9738yz40MIQFzDQ9GtV466PKQsTvdZm&pgtIou=PGTIOU-ea6m8H90xQpqalbiRwWGsQuexx7DNvS6rD6lwOaPFSs4KSGjCgzyyMOV5 (Caused by SSLError(SSLError(1, '[SSL: RECORD_LAYER_FAILURE] record layer failure (_ssl.c:1033)'))) 116s PASSED 116s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2025-10-26 12:38:20,710 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,714 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 116s 2025-10-26 12:38:20,719 INFO ValidateService: ticket ST-bNDP4vBnyQhgDjGsMOK8JwSDppdmIlhKQ0Qmmq47dKvNjS63CzjDOZRD1OxMF validated for user test on service http://127.0.0.1:45678. 116s 2025-10-26 12:38:20,728 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,732 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 116s 2025-10-26 12:38:20,736 INFO ValidateService: ticket ST-qIrII2Jo9Staf47yPBygZsi1jUATnZBgLo0KSW89gtCEbbRBL7I1JHL65SI5p validated for user test on service http://127.0.0.1:45678. 116s 2025-10-26 12:38:20,741 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 116s 2025-10-26 12:38:20,743 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 116s PASSED 116s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2025-10-26 12:38:20,757 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,765 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 116s 2025-10-26 12:38:20,769 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 116s PASSED 116s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2025-10-26 12:38:20,782 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,790 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 116s 2025-10-26 12:38:20,792 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 116s PASSED 116s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2025-10-26 12:38:20,806 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,814 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 116s 2025-10-26 12:38:20,817 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 116s PASSED 116s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2025-10-26 12:38:20,830 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,838 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 116s 2025-10-26 12:38:20,842 INFO ValidateService: ticket ST-EKJ9WiJgc2s1aazggYnIhTXCWPrTlcIK3cgqEml97LiM9IMiMjBRaqjdisbnF validated for user test on service http://127.0.0.1:45678. 116s PASSED 116s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2025-10-26 12:38:20,856 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,864 INFO Service ticket created for service https://one_attribute.example.com by user test. 116s 2025-10-26 12:38:20,868 INFO ValidateService: ticket ST-1dFFWwJleeVHOFulvdvOsir2TcQ6ynwnxwwoDhTiYvMo4uBUkwbuCKeDOBF3k validated for user test on service https://one_attribute.example.com. 116s PASSED 116s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2025-10-26 12:38:20,882 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,890 INFO Service ticket created for service http://127.0.0.1:33533 by user test. 116s 2025-10-26 12:38:20,898 INFO ValidateService: ticket ST-wobeGK5Tmf84amqxHl1hr6ZCaWJMpMl5GOBQfDt6HJg8tIMbJRuXzhr9RGkiT validated for user test on service http://127.0.0.1:33533. Proxy Granting Ticket transmited to http://127.0.0.1:33533. 116s PASSED 116s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2025-10-26 12:38:20,911 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,919 INFO Service ticket created for service https://user_field.example.com by user test. 116s 2025-10-26 12:38:20,923 INFO ValidateService: ticket ST-IlihzOvDQnQqma27ZHJJdMom90hvkWlCMGOjJt2F5RYW4VNGi2z3gmkG4Vz2R validated for user test on service https://user_field.example.com. 116s 2025-10-26 12:38:20,932 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,940 INFO Service ticket created for service https://user_field_alt.example.com by user test. 116s 2025-10-26 12:38:20,944 INFO ValidateService: ticket ST-sIfBYuoMT6duAELJQdfwyXrWODbVrvYnFSuUpW17iLV6lurqOTNcnzcv01nRC validated for user test on service https://user_field_alt.example.com. 116s PASSED 116s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2025-10-26 12:38:20,960 INFO User test successfully authenticated 116s 2025-10-26 12:38:20,968 INFO Service ticket created for service http://127.0.0.1:44841 by user test. 117s 2025-10-26 12:38:20,976 INFO ValidateService: ticket ST-B3WEErLRR5bHTMRbuXh3QN6kukGocVAuXRpGHZiqjxa7UXJQSEdG1ZxVj3BaN validated for user test on service http://127.0.0.1:44841. Proxy Granting Ticket transmited to http://127.0.0.1:44841. 117s 2025-10-26 12:38:20,978 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 117s PASSED 117s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2025-10-26 12:38:20,979 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 117s 2025-10-26 12:38:20,994 INFO User test successfully authenticated 117s 2025-10-26 12:38:21,002 INFO Service ticket created for service http://127.0.0.1:33383 by user test. 117s 2025-10-26 12:38:21,009 INFO ValidateService: ticket ST-cpjT0dq9N3vpt55nkM4e1l6V8pLftvCkDtPbgvMPTtDGgAL6fDhl9NHJfxya5 validated for user test on service http://127.0.0.1:33383. Proxy Granting Ticket transmited to http://127.0.0.1:33383. 117s 2025-10-26 12:38:21,013 WARNING Username test not allowed on service restrict_user_fail 117s 2025-10-26 12:38:21,013 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 117s 2025-10-26 12:38:21,016 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 117s 2025-10-26 12:38:21,016 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 117s PASSED 117s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2025-10-26 12:38:21,019 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 117s 2025-10-26 12:38:21,019 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 117s 2025-10-26 12:38:21,034 INFO User test successfully authenticated 117s 2025-10-26 12:38:21,042 INFO Service ticket created for service http://127.0.0.1:36019 by user test. 117s 2025-10-26 12:38:21,050 INFO ValidateService: ticket ST-qZkEBNjJY3C9MuPce1XJ4jPc1NKljh5FC9c7Lb7DNqp8LM2JJFMBf7yycJWwE validated for user test on service http://127.0.0.1:36019. Proxy Granting Ticket transmited to http://127.0.0.1:36019. 117s 2025-10-26 12:38:21,053 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 117s PASSED 117s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2025-10-26 12:38:21,067 INFO User test successfully authenticated 117s 2025-10-26 12:38:21,075 INFO Service ticket created for service http://127.0.0.1:41235 by user test. 117s 2025-10-26 12:38:21,083 INFO ValidateService: ticket ST-lVupCPEno0gyXacKfHWmwEcSP6aEFidfpuUJzscTnWiRClP7TxmgraiVaXrVd validated for user test on service http://127.0.0.1:41235. Proxy Granting Ticket transmited to http://127.0.0.1:41235. 117s 2025-10-26 12:38:21,084 WARNING Service https://www.example.org not allowed. 117s 2025-10-26 12:38:21,085 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 117s 2025-10-26 12:38:21,087 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:41235 does not allow proxy tickets 117s PASSED 117s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2025-10-26 12:38:21,103 INFO User test successfully authenticated 117s 2025-10-26 12:38:21,111 INFO Service ticket created for service http://127.0.0.1:43703 by user test. 117s 2025-10-26 12:38:21,118 INFO ValidateService: ticket ST-Bgp1x0HhkuSl6pLASKPqZr5QsG3wymAZyd1EsGIeyZsp6HKx1ew65IzyvhD8F validated for user test on service http://127.0.0.1:43703. Proxy Granting Ticket transmited to http://127.0.0.1:43703. 117s 2025-10-26 12:38:21,124 INFO Proxy ticket created for user test on service https://www.example.com. 117s 2025-10-26 12:38:21,127 INFO ValidateService: ticket PT-8t7UWwEQH4S3E9y3e4ZEmBO9UtWPy8FFwAqgu1mrfKDCkS7Kln3OEm0sGn7fy validated for user test on service https://www.example.com. 117s PASSED 117s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2025-10-26 12:38:21,144 INFO User test successfully authenticated 117s 2025-10-26 12:38:21,152 INFO Service ticket created for service https://www.example.com by user test. 117s PASSED 117s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2025-10-26 12:38:21,155 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 117s 2025-10-26 12:38:21,163 WARNING SamlValidate: validation error: AuthnFailed ticket ST-sH1FkcvQwwnYAWQcoEquLdwSEWHeBr1VV9KQrw0EI9Kia7ZeT40PEpBVfckEG not found 117s 2025-10-26 12:38:21,165 WARNING SamlValidate: validation error: AuthnFailed ticket PT-nAlsvevqbySbssFNiOzo4nXTMSoZvQPDoQmuo78FSulGu0XMOCxak9068u4wF not found 117s PASSED 117s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix 2025-10-26 12:38:21,171 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 117s PASSED 117s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml 2025-10-26 12:38:21,177 WARNING SamlValidate: validation error: VersionMismatch 117s PASSED 117s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2025-10-26 12:38:21,192 INFO User test successfully authenticated 117s 2025-10-26 12:38:21,200 INFO Service ticket created for service https://www.example.com by user test. 117s 2025-10-26 12:38:21,211 INFO User test successfully authenticated 117s 2025-10-26 12:38:21,219 INFO Service ticket created for service http://127.0.0.1:45375 by user test. 117s 2025-10-26 12:38:21,228 INFO ValidateService: ticket ST-UrsesANK1yS2aEB30e4eMyQP5omRdaMDYad8jrV0WMmTUJxNjWf21228gxeem validated for user test on service http://127.0.0.1:45375. Proxy Granting Ticket transmited to http://127.0.0.1:45375. 117s 2025-10-26 12:38:21,233 INFO Proxy ticket created for user test on service https://www.example.com. 117s 2025-10-26 12:38:21,237 INFO SamlValidate: ticket ST-4jtpCooF7exDjwvJhWoqKahyahw4kyZmw0muGLol86ub2SMkWwPkJ4JFCPt7t validated for user test on service https://www.example.com. 117s 2025-10-26 12:38:21,240 INFO SamlValidate: ticket PT-u0YvfnaCkyM77D13a9QmmuP0sok9pBc03u3IRFs16ZY1Y6x57LLDe0UDwGbYv validated for user test on service https://www.example.com. 117s PASSED 117s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2025-10-26 12:38:21,255 INFO User test successfully authenticated 117s 2025-10-26 12:38:21,263 INFO Service ticket created for service https://field_needed_success.example.com by user test. 117s 2025-10-26 12:38:21,268 INFO SamlValidate: ticket ST-M7NBpo6rqGt9ecJ9fQnP9wlDMA0xKpgJxHxLV95qkGCYkn6z9suj70BAyxRXj validated for user test on service https://field_needed_success.example.com. 117s 2025-10-26 12:38:21,276 INFO User test successfully authenticated 117s 2025-10-26 12:38:21,284 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 117s 2025-10-26 12:38:21,289 INFO SamlValidate: ticket ST-8sp0S7gdqoPwEycq9DUUPQBll8dgplwu05lVnsGH0asqCkfJQaLe2KEOWGkOw validated for user test on service https://field_needed_success_alt.example.com. 117s PASSEDDestroying test database for alias 'default'... 117s 117s 117s =============================== warnings summary =============================== 117s cas_server/tests/test_federate.py: 8 warnings 117s cas_server/tests/test_view.py: 3 warnings 117s /tmp/autopkgtest.K9P1jf/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). 117s datetime.utcnow() + timedelta(seconds=max_age), 117s 117s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 117s /usr/lib/python3.13/unittest/case.py:707: DeprecationWarning: It is deprecated to return a value that is not None from a test case (>) 117s return self.run(*args, **kwds) 117s 117s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 117s ======================= 128 passed, 12 warnings in 7.44s ======================= 117s 117s autopkgtest [12:38:21]: test run-test-suite.sh: -----------------------] 118s autopkgtest [12:38:22]: test run-test-suite.sh: - - - - - - - - - - results - - - - - - - - - - 118s run-test-suite.sh PASS 118s autopkgtest [12:38:22]: @@@@@@@@@@@@@@@@@@@@ summary 118s run-test-suite.sh PASS