0s autopkgtest [05:52:42]: starting date and time: 2024-06-08 05:52:42+0000 0s autopkgtest [05:52:42]: git checkout: 433ed4c Merge branch 'skia/nova_flock' into 'ubuntu/5.34+prod' 0s autopkgtest [05:52:42]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.4bb2axtt/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com\n" >> /etc/environment' --apt-pocket=proposed --apt-upgrade django-cas-server --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=pytest/8.2.2-1ubuntu1 -- lxd -r lxd-armhf-10.145.243.194 lxd-armhf-10.145.243.194:autopkgtest/ubuntu/oracular/armhf 36s autopkgtest [05:53:18]: testbed dpkg architecture: armhf 38s autopkgtest [05:53:20]: testbed apt version: 2.9.3 38s autopkgtest [05:53:20]: @@@@@@@@@@@@@@@@@@@@ test bed setup 45s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [110 kB] 46s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [44.3 kB] 46s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [327 kB] 46s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [7972 B] 46s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [1680 B] 46s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf Packages [35.4 kB] 46s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/restricted armhf Packages [1860 B] 46s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/universe armhf Packages [236 kB] 46s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse armhf Packages [1768 B] 46s Fetched 765 kB in 1s (958 kB/s) 46s Reading package lists... 55s tee: /proc/self/fd/2: Permission denied 76s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 76s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 76s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 76s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 77s Reading package lists... 78s Reading package lists... 78s Building dependency tree... 78s Reading state information... 78s Calculating upgrade... 79s The following package was automatically installed and is no longer required: 79s systemd-dev 79s Use 'apt autoremove' to remove it. 79s The following packages have been kept back: 79s kpartx multipath-tools 79s The following packages will be upgraded: 79s apt apt-utils bash debianutils libapt-pkg6.0t64 libnetplan1 libnss-systemd 79s libpam-systemd libsystemd-shared libsystemd0 libudev1 liburcu8t64 libxml2 79s man-db netplan-generator netplan.io python3-cryptography python3-netplan 79s python3-openssl systemd systemd-dev systemd-resolved systemd-sysv 79s systemd-timesyncd udev 79s 25 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. 79s Need to get 15.6 MB of archives. 79s After this operation, 1380 kB of additional disk space will be used. 79s Get:1 http://ftpmaster.internal/ubuntu oracular/main armhf debianutils armhf 5.18 [89.2 kB] 79s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf bash armhf 5.2.21-2.1ubuntu1 [668 kB] 79s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf systemd-timesyncd armhf 256~rc3-5ubuntu1 [36.2 kB] 79s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf systemd-resolved armhf 256~rc3-5ubuntu1 [306 kB] 79s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf systemd-dev all 256~rc3-5ubuntu1 [110 kB] 79s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf systemd armhf 256~rc3-5ubuntu1 [3677 kB] 80s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf udev armhf 256~rc3-5ubuntu1 [1935 kB] 80s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf systemd-sysv armhf 256~rc3-5ubuntu1 [11.9 kB] 80s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf libnss-systemd armhf 256~rc3-5ubuntu1 [153 kB] 80s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf libpam-systemd armhf 256~rc3-5ubuntu1 [225 kB] 80s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf libsystemd-shared armhf 256~rc3-5ubuntu1 [2113 kB] 80s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf libudev1 armhf 256~rc3-5ubuntu1 [184 kB] 80s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf libsystemd0 armhf 256~rc3-5ubuntu1 [418 kB] 80s Get:14 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf libapt-pkg6.0t64 armhf 2.9.4 [1028 kB] 80s Get:15 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf apt armhf 2.9.4 [1398 kB] 80s Get:16 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf apt-utils armhf 2.9.4 [217 kB] 80s Get:17 http://ftpmaster.internal/ubuntu oracular/main armhf netplan-generator armhf 1.0-3ubuntu1 [58.8 kB] 80s Get:18 http://ftpmaster.internal/ubuntu oracular/main armhf python3-netplan armhf 1.0-3ubuntu1 [22.5 kB] 80s Get:19 http://ftpmaster.internal/ubuntu oracular/main armhf netplan.io armhf 1.0-3ubuntu1 [64.7 kB] 80s Get:20 http://ftpmaster.internal/ubuntu oracular/main armhf libnetplan1 armhf 1.0-3ubuntu1 [115 kB] 80s Get:21 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf libxml2 armhf 2.12.7+dfsg-3 [560 kB] 80s Get:22 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf man-db armhf 2.12.1-2 [1288 kB] 80s Get:23 http://ftpmaster.internal/ubuntu oracular/main armhf liburcu8t64 armhf 0.14.0-4 [56.5 kB] 80s Get:24 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf python3-cryptography armhf 42.0.5-2 [828 kB] 80s Get:25 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf python3-openssl all 24.1.0-1 [46.8 kB] 80s Preconfiguring packages ... 80s Fetched 15.6 MB in 1s (15.0 MB/s) 80s (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 ... 58338 files and directories currently installed.) 80s Preparing to unpack .../debianutils_5.18_armhf.deb ... 80s Unpacking debianutils (5.18) over (5.17build1) ... 81s Setting up debianutils (5.18) ... 81s (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 ... 58338 files and directories currently installed.) 81s Preparing to unpack .../bash_5.2.21-2.1ubuntu1_armhf.deb ... 81s Unpacking bash (5.2.21-2.1ubuntu1) over (5.2.21-2ubuntu5) ... 81s Setting up bash (5.2.21-2.1ubuntu1) ... 81s update-alternatives: using /usr/share/man/man7/bash-builtins.7.gz to provide /usr/share/man/man7/builtins.7.gz (builtins.7.gz) in auto mode 81s (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 ... 58338 files and directories currently installed.) 81s Preparing to unpack .../0-systemd-timesyncd_256~rc3-5ubuntu1_armhf.deb ... 81s Unpacking systemd-timesyncd (256~rc3-5ubuntu1) over (255.4-1ubuntu8) ... 81s Preparing to unpack .../1-systemd-resolved_256~rc3-5ubuntu1_armhf.deb ... 81s Unpacking systemd-resolved (256~rc3-5ubuntu1) over (255.4-1ubuntu8) ... 81s Preparing to unpack .../2-systemd-dev_256~rc3-5ubuntu1_all.deb ... 81s Unpacking systemd-dev (256~rc3-5ubuntu1) over (255.4-1ubuntu8) ... 81s Preparing to unpack .../3-systemd_256~rc3-5ubuntu1_armhf.deb ... 81s Unpacking systemd (256~rc3-5ubuntu1) over (255.4-1ubuntu8) ... 82s Preparing to unpack .../4-udev_256~rc3-5ubuntu1_armhf.deb ... 82s Unpacking udev (256~rc3-5ubuntu1) over (255.4-1ubuntu8) ... 82s Preparing to unpack .../5-libsystemd-shared_256~rc3-5ubuntu1_armhf.deb ... 82s Unpacking libsystemd-shared:armhf (256~rc3-5ubuntu1) over (255.4-1ubuntu8) ... 82s Setting up libsystemd-shared:armhf (256~rc3-5ubuntu1) ... 83s (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 ... 58401 files and directories currently installed.) 83s Preparing to unpack .../libsystemd0_256~rc3-5ubuntu1_armhf.deb ... 83s Unpacking libsystemd0:armhf (256~rc3-5ubuntu1) over (255.4-1ubuntu8) ... 83s Setting up libsystemd0:armhf (256~rc3-5ubuntu1) ... 83s Setting up systemd (256~rc3-5ubuntu1) ... 83s Installing new version of config file /etc/systemd/journald.conf ... 83s Installing new version of config file /etc/systemd/logind.conf ... 83s Installing new version of config file /etc/systemd/networkd.conf ... 83s Installing new version of config file /etc/systemd/sleep.conf ... 83s Installing new version of config file /etc/systemd/system.conf ... 83s /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. 83s Created symlink '/run/systemd/system/tmp.mount' → '/dev/null'. 83s /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. 84s (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 ... 58401 files and directories currently installed.) 84s Preparing to unpack .../systemd-sysv_256~rc3-5ubuntu1_armhf.deb ... 84s Unpacking systemd-sysv (256~rc3-5ubuntu1) over (255.4-1ubuntu8) ... 84s Preparing to unpack .../libnss-systemd_256~rc3-5ubuntu1_armhf.deb ... 84s Unpacking libnss-systemd:armhf (256~rc3-5ubuntu1) over (255.4-1ubuntu8) ... 84s Preparing to unpack .../libpam-systemd_256~rc3-5ubuntu1_armhf.deb ... 84s Unpacking libpam-systemd:armhf (256~rc3-5ubuntu1) over (255.4-1ubuntu8) ... 84s Preparing to unpack .../libudev1_256~rc3-5ubuntu1_armhf.deb ... 84s Unpacking libudev1:armhf (256~rc3-5ubuntu1) over (255.4-1ubuntu8) ... 84s Setting up libudev1:armhf (256~rc3-5ubuntu1) ... 84s (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 ... 58401 files and directories currently installed.) 84s Preparing to unpack .../libapt-pkg6.0t64_2.9.4_armhf.deb ... 84s Unpacking libapt-pkg6.0t64:armhf (2.9.4) over (2.9.3) ... 84s Setting up libapt-pkg6.0t64:armhf (2.9.4) ... 84s (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 ... 58401 files and directories currently installed.) 84s Preparing to unpack .../archives/apt_2.9.4_armhf.deb ... 84s Unpacking apt (2.9.4) over (2.9.3) ... 85s Setting up apt (2.9.4) ... 85s (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 ... 58401 files and directories currently installed.) 85s Preparing to unpack .../0-apt-utils_2.9.4_armhf.deb ... 85s Unpacking apt-utils (2.9.4) over (2.9.3) ... 85s Preparing to unpack .../1-netplan-generator_1.0-3ubuntu1_armhf.deb ... 85s Adding 'diversion of /lib/systemd/system-generators/netplan to /lib/systemd/system-generators/netplan.usr-is-merged by netplan-generator' 85s Unpacking netplan-generator (1.0-3ubuntu1) over (1.0-2ubuntu1) ... 85s Preparing to unpack .../2-python3-netplan_1.0-3ubuntu1_armhf.deb ... 85s Unpacking python3-netplan (1.0-3ubuntu1) over (1.0-2ubuntu1) ... 86s Preparing to unpack .../3-netplan.io_1.0-3ubuntu1_armhf.deb ... 86s Unpacking netplan.io (1.0-3ubuntu1) over (1.0-2ubuntu1) ... 86s Preparing to unpack .../4-libnetplan1_1.0-3ubuntu1_armhf.deb ... 86s Unpacking libnetplan1:armhf (1.0-3ubuntu1) over (1.0-2ubuntu1) ... 86s Preparing to unpack .../5-libxml2_2.12.7+dfsg-3_armhf.deb ... 86s Unpacking libxml2:armhf (2.12.7+dfsg-3) over (2.9.14+dfsg-1.3ubuntu3) ... 86s Preparing to unpack .../6-man-db_2.12.1-2_armhf.deb ... 86s Unpacking man-db (2.12.1-2) over (2.12.1-1) ... 86s Preparing to unpack .../7-liburcu8t64_0.14.0-4_armhf.deb ... 86s Unpacking liburcu8t64:armhf (0.14.0-4) over (0.14.0-3.1build1) ... 86s Preparing to unpack .../8-python3-cryptography_42.0.5-2_armhf.deb ... 86s Unpacking python3-cryptography (42.0.5-2) over (41.0.7-4build3) ... 86s Preparing to unpack .../9-python3-openssl_24.1.0-1_all.deb ... 86s Unpacking python3-openssl (24.1.0-1) over (23.2.0-1) ... 86s Setting up liburcu8t64:armhf (0.14.0-4) ... 86s Setting up systemd-sysv (256~rc3-5ubuntu1) ... 86s Setting up apt-utils (2.9.4) ... 86s Setting up libnss-systemd:armhf (256~rc3-5ubuntu1) ... 86s Setting up systemd-dev (256~rc3-5ubuntu1) ... 86s Setting up libnetplan1:armhf (1.0-3ubuntu1) ... 86s Setting up man-db (2.12.1-2) ... 86s Updating database of manual pages ... 88s apparmor_parser: Unable to replace "/usr/bin/man". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 88s 88s man-db.service is a disabled or a static unit not running, not starting it. 88s Setting up python3-cryptography (42.0.5-2) ... 89s Setting up systemd-timesyncd (256~rc3-5ubuntu1) ... 90s Setting up udev (256~rc3-5ubuntu1) ... 90s Setting up libpam-systemd:armhf (256~rc3-5ubuntu1) ... 90s Setting up python3-netplan (1.0-3ubuntu1) ... 90s Setting up netplan-generator (1.0-3ubuntu1) ... 90s Removing 'diversion of /lib/systemd/system-generators/netplan to /lib/systemd/system-generators/netplan.usr-is-merged by netplan-generator' 90s Setting up libxml2:armhf (2.12.7+dfsg-3) ... 90s Setting up systemd-resolved (256~rc3-5ubuntu1) ... 90s Installing new version of config file /etc/systemd/resolved.conf ... 91s Setting up python3-openssl (24.1.0-1) ... 91s Setting up netplan.io (1.0-3ubuntu1) ... 91s Processing triggers for shared-mime-info (2.4-5) ... 92s Processing triggers for debianutils (5.18) ... 92s Processing triggers for install-info (7.1-3build2) ... 92s Processing triggers for initramfs-tools (0.142ubuntu28) ... 92s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 92s Processing triggers for dbus (1.14.10-4ubuntu4) ... 94s Reading package lists... 94s Building dependency tree... 94s Reading state information... 94s The following packages will be REMOVED: 94s systemd-dev* 95s 0 upgraded, 0 newly installed, 1 to remove and 2 not upgraded. 95s After this operation, 758 kB disk space will be freed. 95s (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 ... 58468 files and directories currently installed.) 95s Removing systemd-dev (256~rc3-5ubuntu1) ... 97s autopkgtest [05:54:19]: rebooting testbed after setup commands that affected boot 167s autopkgtest [05:55:29]: testbed running kernel: Linux 5.15.0-107-generic #117-Ubuntu SMP Mon Apr 29 14:37:09 UTC 2024 194s autopkgtest [05:55:56]: @@@@@@@@@@@@@@@@@@@@ apt-source django-cas-server 207s Get:1 http://ftpmaster.internal/ubuntu oracular/universe django-cas-server 2.0.0-2 (dsc) [2588 B] 207s Get:2 http://ftpmaster.internal/ubuntu oracular/universe django-cas-server 2.0.0-2 (tar) [169 kB] 207s Get:3 http://ftpmaster.internal/ubuntu oracular/universe django-cas-server 2.0.0-2 (diff) [6480 B] 208s gpgv: Signature made Sat Dec 3 08:42:02 2022 UTC 208s gpgv: using RSA key B70DFC6F134FECFC011E62AA83016014251D1DB0 208s gpgv: Can't check signature: No public key 208s dpkg-source: warning: cannot verify inline signature for ./django-cas-server_2.0.0-2.dsc: no acceptable signature found 208s autopkgtest [05:56:10]: testing package django-cas-server version 2.0.0-2 210s autopkgtest [05:56:12]: build not needed 212s autopkgtest [05:56:14]: test run-test-suite.sh: preparing testbed 221s Reading package lists... 222s Building dependency tree... 222s Reading state information... 222s Starting pkgProblemResolver with broken count: 0 222s Starting 2 pkgProblemResolver with broken count: 0 222s Done 223s The following additional packages will be installed: 223s fonts-font-awesome fonts-lato libjs-jquery libjs-sphinxdoc libjs-underscore 223s libxslt1.1 python3-all python3-asgiref python3-django 223s python3-django-cas-server python3-django-cas-server-doc python3-iniconfig 223s python3-lxml python3-mock python3-packaging python3-pluggy python3-pytest 223s python3-pytest-django python3-requests-futures python3-sqlparse 223s sphinx-rtd-theme-common 223s Suggested packages: 223s bpython3 geoip-database-contrib gettext ipython3 libgdal20 223s libsqlite3-mod-spatialite python-django-doc python3-bcrypt python3-flup 223s python3-memcache python3-mysqldb python3-pil python3-psycopg2 223s python3-selenium python3-sqlite python-lxml-doc python-mock-doc 223s python-sqlparse-doc 223s Recommended packages: 223s javascript-common python3-tz python3-bs4 python3-cssselect python3-html5lib 223s The following NEW packages will be installed: 223s autopkgtest-satdep fonts-font-awesome fonts-lato libjs-jquery 223s libjs-sphinxdoc libjs-underscore libxslt1.1 python3-all python3-asgiref 223s python3-django python3-django-cas-server python3-django-cas-server-doc 223s python3-iniconfig python3-lxml python3-mock python3-packaging python3-pluggy 223s python3-pytest python3-pytest-django python3-requests-futures 223s python3-sqlparse sphinx-rtd-theme-common 224s 0 upgraded, 22 newly installed, 0 to remove and 2 not upgraded. 224s Need to get 9914 kB/9914 kB of archives. 224s After this operation, 50.5 MB of additional disk space will be used. 224s Get:1 /tmp/autopkgtest.QfD5BL/1-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [744 B] 224s Get:2 http://ftpmaster.internal/ubuntu oracular/main armhf fonts-lato all 2.015-1 [2781 kB] 224s Get:3 http://ftpmaster.internal/ubuntu oracular/main armhf fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 224s Get:4 http://ftpmaster.internal/ubuntu oracular/main armhf libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 224s Get:5 http://ftpmaster.internal/ubuntu oracular/main armhf libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 224s Get:6 http://ftpmaster.internal/ubuntu oracular/main armhf libjs-sphinxdoc all 7.2.6-8 [150 kB] 224s Get:7 http://ftpmaster.internal/ubuntu oracular/main armhf libxslt1.1 armhf 1.1.39-0exp1build1 [150 kB] 224s Get:8 http://ftpmaster.internal/ubuntu oracular/main armhf python3-all armhf 3.12.3-0ubuntu1 [886 B] 224s Get:9 http://ftpmaster.internal/ubuntu oracular/main armhf python3-asgiref all 3.8.1-1 [24.8 kB] 224s Get:10 http://ftpmaster.internal/ubuntu oracular/main armhf python3-sqlparse all 0.5.0-1 [32.4 kB] 224s Get:11 http://ftpmaster.internal/ubuntu oracular/main armhf python3-django all 3:4.2.13-1 [2919 kB] 224s Get:12 http://ftpmaster.internal/ubuntu oracular/main armhf python3-lxml armhf 5.2.1-1 [1200 kB] 224s Get:13 http://ftpmaster.internal/ubuntu oracular/universe armhf python3-requests-futures all 1.0.1-1 [11.7 kB] 224s Get:14 http://ftpmaster.internal/ubuntu oracular/universe armhf python3-django-cas-server all 2.0.0-2 [111 kB] 224s Get:15 http://ftpmaster.internal/ubuntu oracular/main armhf sphinx-rtd-theme-common all 2.0.0+dfsg-1 [1012 kB] 225s Get:16 http://ftpmaster.internal/ubuntu oracular/universe armhf python3-django-cas-server-doc all 2.0.0-2 [159 kB] 225s Get:17 http://ftpmaster.internal/ubuntu oracular/universe armhf python3-iniconfig all 1.1.1-2 [6024 B] 225s Get:18 http://ftpmaster.internal/ubuntu oracular/main armhf python3-packaging all 24.0-1 [41.1 kB] 225s Get:19 http://ftpmaster.internal/ubuntu oracular/universe armhf python3-pluggy all 1.5.0-1 [21.0 kB] 225s Get:20 http://ftpmaster.internal/ubuntu oracular-proposed/universe armhf python3-pytest all 8.2.2-1ubuntu1 [250 kB] 225s Get:21 http://ftpmaster.internal/ubuntu oracular/universe armhf python3-pytest-django all 4.5.2-3 [18.5 kB] 225s Get:22 http://ftpmaster.internal/ubuntu oracular/universe armhf python3-mock all 5.1.0-1 [64.1 kB] 226s Fetched 9914 kB in 1s (7947 kB/s) 226s Selecting previously unselected package fonts-lato. 226s (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 ... 58421 files and directories currently installed.) 226s Preparing to unpack .../00-fonts-lato_2.015-1_all.deb ... 226s Unpacking fonts-lato (2.015-1) ... 226s Selecting previously unselected package fonts-font-awesome. 226s Preparing to unpack .../01-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 226s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 227s Selecting previously unselected package libjs-jquery. 227s Preparing to unpack .../02-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 227s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 227s Selecting previously unselected package libjs-underscore. 227s Preparing to unpack .../03-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 227s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 227s Selecting previously unselected package libjs-sphinxdoc. 227s Preparing to unpack .../04-libjs-sphinxdoc_7.2.6-8_all.deb ... 227s Unpacking libjs-sphinxdoc (7.2.6-8) ... 227s Selecting previously unselected package libxslt1.1:armhf. 227s Preparing to unpack .../05-libxslt1.1_1.1.39-0exp1build1_armhf.deb ... 227s Unpacking libxslt1.1:armhf (1.1.39-0exp1build1) ... 227s Selecting previously unselected package python3-all. 227s Preparing to unpack .../06-python3-all_3.12.3-0ubuntu1_armhf.deb ... 227s Unpacking python3-all (3.12.3-0ubuntu1) ... 227s Selecting previously unselected package python3-asgiref. 227s Preparing to unpack .../07-python3-asgiref_3.8.1-1_all.deb ... 227s Unpacking python3-asgiref (3.8.1-1) ... 227s Selecting previously unselected package python3-sqlparse. 227s Preparing to unpack .../08-python3-sqlparse_0.5.0-1_all.deb ... 227s Unpacking python3-sqlparse (0.5.0-1) ... 227s Selecting previously unselected package python3-django. 227s Preparing to unpack .../09-python3-django_3%3a4.2.13-1_all.deb ... 227s Unpacking python3-django (3:4.2.13-1) ... 228s Selecting previously unselected package python3-lxml:armhf. 228s Preparing to unpack .../10-python3-lxml_5.2.1-1_armhf.deb ... 228s Unpacking python3-lxml:armhf (5.2.1-1) ... 228s Selecting previously unselected package python3-requests-futures. 228s Preparing to unpack .../11-python3-requests-futures_1.0.1-1_all.deb ... 228s Unpacking python3-requests-futures (1.0.1-1) ... 228s Selecting previously unselected package python3-django-cas-server. 228s Preparing to unpack .../12-python3-django-cas-server_2.0.0-2_all.deb ... 228s Unpacking python3-django-cas-server (2.0.0-2) ... 229s Selecting previously unselected package sphinx-rtd-theme-common. 229s Preparing to unpack .../13-sphinx-rtd-theme-common_2.0.0+dfsg-1_all.deb ... 229s Unpacking sphinx-rtd-theme-common (2.0.0+dfsg-1) ... 229s Selecting previously unselected package python3-django-cas-server-doc. 229s Preparing to unpack .../14-python3-django-cas-server-doc_2.0.0-2_all.deb ... 229s Unpacking python3-django-cas-server-doc (2.0.0-2) ... 231s Selecting previously unselected package python3-iniconfig. 231s Preparing to unpack .../15-python3-iniconfig_1.1.1-2_all.deb ... 231s Unpacking python3-iniconfig (1.1.1-2) ... 231s Selecting previously unselected package python3-packaging. 231s Preparing to unpack .../16-python3-packaging_24.0-1_all.deb ... 231s Unpacking python3-packaging (24.0-1) ... 231s Selecting previously unselected package python3-pluggy. 231s Preparing to unpack .../17-python3-pluggy_1.5.0-1_all.deb ... 231s Unpacking python3-pluggy (1.5.0-1) ... 231s Selecting previously unselected package python3-pytest. 231s Preparing to unpack .../18-python3-pytest_8.2.2-1ubuntu1_all.deb ... 231s Unpacking python3-pytest (8.2.2-1ubuntu1) ... 231s Selecting previously unselected package python3-pytest-django. 231s Preparing to unpack .../19-python3-pytest-django_4.5.2-3_all.deb ... 231s Unpacking python3-pytest-django (4.5.2-3) ... 231s Selecting previously unselected package python3-mock. 231s Preparing to unpack .../20-python3-mock_5.1.0-1_all.deb ... 231s Unpacking python3-mock (5.1.0-1) ... 231s Selecting previously unselected package autopkgtest-satdep. 231s Preparing to unpack .../21-1-autopkgtest-satdep.deb ... 231s Unpacking autopkgtest-satdep (0) ... 231s Setting up python3-iniconfig (1.1.1-2) ... 231s Setting up fonts-lato (2.015-1) ... 231s Setting up python3-all (3.12.3-0ubuntu1) ... 231s Setting up python3-mock (5.1.0-1) ... 231s Setting up python3-packaging (24.0-1) ... 232s Setting up python3-sqlparse (0.5.0-1) ... 232s Setting up python3-pluggy (1.5.0-1) ... 232s Setting up libxslt1.1:armhf (1.1.39-0exp1build1) ... 232s Setting up python3-requests-futures (1.0.1-1) ... 232s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 232s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 232s Setting up sphinx-rtd-theme-common (2.0.0+dfsg-1) ... 232s Setting up python3-asgiref (3.8.1-1) ... 232s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 232s Setting up python3-django (3:4.2.13-1) ... 234s Setting up python3-pytest (8.2.2-1ubuntu1) ... 235s Setting up python3-pytest-django (4.5.2-3) ... 235s Setting up libjs-sphinxdoc (7.2.6-8) ... 235s Setting up python3-lxml:armhf (5.2.1-1) ... 236s Setting up python3-django-cas-server (2.0.0-2) ... 236s Setting up python3-django-cas-server-doc (2.0.0-2) ... 236s Setting up autopkgtest-satdep (0) ... 236s Processing triggers for man-db (2.12.1-2) ... 236s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 255s (Reading database ... 65230 files and directories currently installed.) 255s Removing autopkgtest-satdep (0) ... 263s autopkgtest [05:57:05]: test run-test-suite.sh: [----------------------- 269s 269s *************************** 269s *** Testing with python3.12 269s *************************** 269s 269s Content of current working folder: 269s 269s total 8 269s drwxr-xr-x 3 ubuntu ubuntu 100 Jun 8 05:57 . 269s drwxrwxrwt 5 root root 340 Jun 8 05:57 .. 269s drwxrwxr-x 9 ubuntu ubuntu 420 Oct 17 2022 cas_server 269s -rw-rw-r-- 1 ubuntu ubuntu 140 Oct 17 2022 pytest.ini 269s -rw-rw-r-- 1 ubuntu ubuntu 152 Oct 17 2022 setup.cfg 269s Running tests... 269s 270s ============================= test session starts ============================== 270s platform linux -- Python 3.12.3, pytest-8.2.2, pluggy-1.5.0 -- /usr/bin/python3.12 270s cachedir: .pytest_cache 270s django: settings: cas_server.tests.settings (from ini) 270s rootdir: /tmp/autopkgtest.QfD5BL/autopkgtest_tmp 270s configfile: pytest.ini 270s testpaths: cas_server/tests/ 270s plugins: django-4.5.2 270s collecting ... collected 128 items 270s 270s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 271s 2024-06-08 05:57:13,685 WARNING Identity provider suffix exemple.fr not found 272s 2024-06-08 05:57:14,505 INFO Got an invalid ticket ST-ObkHSSEpv7xKgXokc6STsmEL7mop60z1QJOfrpNEN021N1WBDqgSWbwGYlUwS from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 272s 2024-06-08 05:57:14,596 INFO Got an invalid ticket ST-KRmO1y4Yb9y132XT5PbiJ9aDEHacmgn5xj0LO3A1MHHWb7Wer3AjU3zsdwbQJ from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 272s PASSED 272s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false 2024-06-08 05:57:14,689 WARNING CAS_FEDERATE is False, set it to True to use federation 272s 2024-06-08 05:57:14,690 WARNING CAS_FEDERATE is False, set it to True to use federation 272s PASSED 272s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo 2024-06-08 05:57:14,704 INFO Trying to authenticate http://127.0.0.1:8080 again 272s 2024-06-08 05:57:14,710 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 272s 2024-06-08 05:57:14,722 INFO User dédé@example.com successfully authenticated 272s 2024-06-08 05:57:14,733 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 272s 2024-06-08 05:57:14,743 INFO Trying to authenticate http://127.0.0.1:8081 again 272s 2024-06-08 05:57:14,748 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 272s 2024-06-08 05:57:14,757 INFO User dédé@example.org successfully authenticated 272s 2024-06-08 05:57:14,766 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 272s 2024-06-08 05:57:14,775 INFO Trying to authenticate http://127.0.0.1:8082 again 272s 2024-06-08 05:57:14,781 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 272s 2024-06-08 05:57:14,789 INFO User dédé@example.net successfully authenticated 272s 2024-06-08 05:57:14,798 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 272s 2024-06-08 05:57:14,807 INFO Trying to authenticate http://127.0.0.1:8083 again 272s 2024-06-08 05:57:14,951 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 272s 2024-06-08 05:57:14,961 INFO User dédé@example.test successfully authenticated 273s 2024-06-08 05:57:14,969 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 273s 2024-06-08 05:57:15,030 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-BtJJAnBXgbULt8VuYjKyqcWyxf6LSU4ZOU05mvk5OKuNaX0fXw25JLm1NiFB7 273s 2024-06-08 05:57:15,051 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-ZBmcYqDqqz540DQVFUvTpvglC7J4PAsrs46VaW91fZXeQoyuiRuSnKHMaHeWv 273s PASSED 273s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 273s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2024-06-08 05:57:15,079 INFO Trying to authenticate http://127.0.0.1:8080 again 273s 2024-06-08 05:57:15,085 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 273s 2024-06-08 05:57:15,094 INFO User dédé@example.com successfully authenticated 273s 2024-06-08 05:57:15,103 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 273s 2024-06-08 05:57:15,112 INFO Trying to authenticate http://127.0.0.1:8081 again 273s 2024-06-08 05:57:15,117 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 273s 2024-06-08 05:57:15,125 INFO User dédé@example.org successfully authenticated 273s 2024-06-08 05:57:15,134 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 273s 2024-06-08 05:57:15,144 INFO Trying to authenticate http://127.0.0.1:8082 again 273s 2024-06-08 05:57:15,148 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 273s 2024-06-08 05:57:15,157 INFO User dédé@example.net successfully authenticated 273s 2024-06-08 05:57:15,166 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 273s 2024-06-08 05:57:15,175 INFO Trying to authenticate http://127.0.0.1:8083 again 273s 2024-06-08 05:57:15,181 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 273s 2024-06-08 05:57:15,189 INFO User dédé@example.test successfully authenticated 273s 2024-06-08 05:57:15,198 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 273s 2024-06-08 05:57:15,199 INFO logout requested 273s 2024-06-08 05:57:15,201 INFO Logging out user dédé@example.com. 273s 2024-06-08 05:57:15,205 INFO User dédé@example.com logged out 273s 2024-06-08 05:57:15,213 INFO logout requested 273s 2024-06-08 05:57:15,216 INFO logout requested 273s 2024-06-08 05:57:15,217 INFO Logging out user dédé@example.org. 273s 2024-06-08 05:57:15,221 INFO User dédé@example.org logged out 273s 2024-06-08 05:57:15,229 INFO logout requested 273s 2024-06-08 05:57:15,231 INFO logout requested 273s 2024-06-08 05:57:15,233 INFO Logging out user dédé@example.net. 273s 2024-06-08 05:57:15,237 INFO User dédé@example.net logged out 273s 2024-06-08 05:57:15,244 INFO logout requested 273s 2024-06-08 05:57:15,247 INFO logout requested 273s 2024-06-08 05:57:15,250 INFO Logging out user dédé@example.test. 273s 2024-06-08 05:57:15,254 INFO User dédé@example.test logged out 273s 2024-06-08 05:57:15,261 INFO logout requested 273s 2024-06-08 05:57:15,271 INFO Trying to authenticate http://127.0.0.1:8080 again 273s 2024-06-08 05:57:15,276 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 273s 2024-06-08 05:57:15,285 INFO User dédé@example.com successfully authenticated 273s 2024-06-08 05:57:15,294 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 273s 2024-06-08 05:57:15,304 INFO Trying to authenticate http://127.0.0.1:8081 again 273s 2024-06-08 05:57:15,309 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 273s 2024-06-08 05:57:15,318 INFO User dédé@example.org successfully authenticated 273s 2024-06-08 05:57:15,326 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 273s 2024-06-08 05:57:15,336 INFO Trying to authenticate http://127.0.0.1:8082 again 273s 2024-06-08 05:57:15,341 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 273s 2024-06-08 05:57:15,350 INFO User dédé@example.net successfully authenticated 273s 2024-06-08 05:57:15,359 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 273s 2024-06-08 05:57:15,368 INFO Trying to authenticate http://127.0.0.1:8083 again 273s 2024-06-08 05:57:15,374 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 273s 2024-06-08 05:57:15,382 INFO User dédé@example.test successfully authenticated 273s 2024-06-08 05:57:15,392 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 273s 2024-06-08 05:57:15,394 INFO logout requested 273s 2024-06-08 05:57:15,395 INFO Logging out user dédé. 273s 2024-06-08 05:57:15,396 INFO User dédé logged out 273s 2024-06-08 05:57:15,404 INFO logout requested 273s 2024-06-08 05:57:15,405 INFO Logging out user dédé. 273s 2024-06-08 05:57:15,406 INFO User dédé logged out 273s 2024-06-08 05:57:15,414 INFO logout requested 273s 2024-06-08 05:57:15,415 INFO Logging out user dédé. 273s 2024-06-08 05:57:15,416 INFO User dédé logged out 273s 2024-06-08 05:57:15,424 INFO logout requested 273s 2024-06-08 05:57:15,425 INFO Logging out user dédé. 273s 2024-06-08 05:57:15,426 INFO User dédé logged out 273s PASSED 273s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2024-06-08 05:57:15,446 INFO Trying to authenticate http://127.0.0.1:8080 again 273s 2024-06-08 05:57:15,463 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 273s 2024-06-08 05:57:15,472 INFO User dédé@example.com successfully authenticated 273s 2024-06-08 05:57:15,481 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 273s 2024-06-08 05:57:15,491 INFO Trying to authenticate http://127.0.0.1:8081 again 273s 2024-06-08 05:57:15,497 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 273s 2024-06-08 05:57:15,505 INFO User dédé@example.org successfully authenticated 273s 2024-06-08 05:57:15,514 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 273s 2024-06-08 05:57:15,524 INFO Trying to authenticate http://127.0.0.1:8082 again 273s 2024-06-08 05:57:15,529 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 273s 2024-06-08 05:57:15,538 INFO User dédé@example.net successfully authenticated 273s 2024-06-08 05:57:15,547 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 273s 2024-06-08 05:57:15,557 INFO Trying to authenticate http://127.0.0.1:8083 again 273s 2024-06-08 05:57:15,562 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 273s 2024-06-08 05:57:15,570 INFO User dédé@example.test successfully authenticated 273s 2024-06-08 05:57:15,958 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 274s 2024-06-08 05:57:15,969 INFO logout requested 274s 2024-06-08 05:57:15,971 INFO Logging out user dédé@example.com. 274s 2024-06-08 05:57:15,976 INFO User dédé@example.com logged out 274s 2024-06-08 05:57:15,977 INFO logout requested 274s 2024-06-08 05:57:15,979 INFO Logging out user dédé@example.org. 274s 2024-06-08 05:57:15,983 INFO User dédé@example.org logged out 274s 2024-06-08 05:57:15,983 INFO logout requested 274s 2024-06-08 05:57:15,985 INFO Logging out user dédé@example.net. 274s 2024-06-08 05:57:15,989 INFO User dédé@example.net logged out 274s 2024-06-08 05:57:15,990 INFO logout requested 274s 2024-06-08 05:57:15,991 INFO Logging out user dédé@example.test. 274s 2024-06-08 05:57:15,995 INFO User dédé@example.test logged out 274s PASSED 274s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2024-06-08 05:57:16,008 WARNING A login attempt failed 274s PASSED 274s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 274s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2024-06-08 05:57:16,035 INFO Trying to authenticate http://127.0.0.1:8080 again 274s 2024-06-08 05:57:16,040 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 274s 2024-06-08 05:57:16,048 INFO User dédé@example.com successfully authenticated 274s 2024-06-08 05:57:16,057 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 274s 2024-06-08 05:57:16,066 INFO Trying to authenticate http://127.0.0.1:8081 again 274s 2024-06-08 05:57:16,072 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 274s 2024-06-08 05:57:16,080 INFO User dédé@example.org successfully authenticated 274s 2024-06-08 05:57:16,089 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 274s 2024-06-08 05:57:16,099 INFO Trying to authenticate http://127.0.0.1:8082 again 274s 2024-06-08 05:57:16,104 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 274s 2024-06-08 05:57:16,112 INFO User dédé@example.net successfully authenticated 274s 2024-06-08 05:57:16,121 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 274s 2024-06-08 05:57:16,130 INFO Trying to authenticate http://127.0.0.1:8083 again 274s 2024-06-08 05:57:16,135 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 274s 2024-06-08 05:57:16,143 INFO User dédé@example.test successfully authenticated 274s 2024-06-08 05:57:16,152 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 274s PASSED 274s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2024-06-08 05:57:16,188 INFO Trying to authenticate http://127.0.0.1:8080 again 274s 2024-06-08 05:57:16,193 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 274s 2024-06-08 05:57:16,202 INFO User dédé@example.com successfully authenticated 274s 2024-06-08 05:57:16,210 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 274s 2024-06-08 05:57:16,220 INFO Trying to authenticate http://127.0.0.1:8081 again 274s 2024-06-08 05:57:16,225 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 274s 2024-06-08 05:57:16,233 INFO User dédé@example.org successfully authenticated 274s 2024-06-08 05:57:16,242 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 274s 2024-06-08 05:57:16,251 INFO Trying to authenticate http://127.0.0.1:8082 again 274s 2024-06-08 05:57:16,258 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 274s 2024-06-08 05:57:16,267 INFO User dédé@example.net successfully authenticated 274s 2024-06-08 05:57:16,275 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 274s 2024-06-08 05:57:16,285 INFO Trying to authenticate http://127.0.0.1:8083 again 274s 2024-06-08 05:57:16,290 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 274s 2024-06-08 05:57:16,299 INFO User dédé@example.test successfully authenticated 274s 2024-06-08 05:57:16,308 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 274s 2024-06-08 05:57:16,318 INFO Trying to authenticate http://127.0.0.1:8080 again 274s 2024-06-08 05:57:16,322 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 274s 2024-06-08 05:57:16,331 INFO User dédé@example.com successfully authenticated 274s 2024-06-08 05:57:16,339 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 274s 2024-06-08 05:57:16,349 INFO Trying to authenticate http://127.0.0.1:8081 again 274s 2024-06-08 05:57:16,354 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 274s 2024-06-08 05:57:16,362 INFO User dédé@example.org successfully authenticated 274s 2024-06-08 05:57:16,371 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 274s 2024-06-08 05:57:16,380 INFO Trying to authenticate http://127.0.0.1:8082 again 274s 2024-06-08 05:57:16,385 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 274s 2024-06-08 05:57:16,394 INFO User dédé@example.net successfully authenticated 274s 2024-06-08 05:57:16,404 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 274s 2024-06-08 05:57:16,451 INFO Trying to authenticate http://127.0.0.1:8083 again 274s 2024-06-08 05:57:16,457 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 274s 2024-06-08 05:57:16,466 INFO User dédé@example.test successfully authenticated 274s 2024-06-08 05:57:16,474 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 274s 2024-06-08 05:57:16,476 WARNING User already authenticated, dropping federated authentication request 274s 2024-06-08 05:57:16,477 WARNING User already authenticated, dropping federated authentication request 274s 2024-06-08 05:57:16,478 WARNING User already authenticated, dropping federated authentication request 274s 2024-06-08 05:57:16,479 WARNING User already authenticated, dropping federated authentication request 274s PASSED 274s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2024-06-08 05:57:16,493 INFO Trying to authenticate http://127.0.0.1:8080 again 274s 2024-06-08 05:57:16,498 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 274s 2024-06-08 05:57:16,506 INFO User dédé@example.com successfully authenticated 274s 2024-06-08 05:57:16,514 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 274s 2024-06-08 05:57:16,524 INFO Trying to authenticate http://127.0.0.1:8081 again 274s 2024-06-08 05:57:16,530 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 274s 2024-06-08 05:57:16,538 INFO User dédé@example.org successfully authenticated 274s 2024-06-08 05:57:16,547 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 274s 2024-06-08 05:57:16,556 INFO Trying to authenticate http://127.0.0.1:8082 again 274s 2024-06-08 05:57:16,562 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 274s 2024-06-08 05:57:16,571 INFO User dédé@example.net successfully authenticated 274s 2024-06-08 05:57:16,580 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 274s 2024-06-08 05:57:16,591 INFO Trying to authenticate http://127.0.0.1:8083 again 274s 2024-06-08 05:57:16,596 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 274s 2024-06-08 05:57:16,605 INFO User dédé@example.test successfully authenticated 274s 2024-06-08 05:57:16,613 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 274s 2024-06-08 05:57:16,615 INFO logout requested 274s 2024-06-08 05:57:16,616 INFO Logging out user dédé@example.com. 274s 2024-06-08 05:57:16,621 INFO User dédé@example.com logged out 274s 2024-06-08 05:57:16,624 INFO logout requested 274s 2024-06-08 05:57:16,626 INFO Logging out user dédé@example.org. 274s 2024-06-08 05:57:16,630 INFO User dédé@example.org logged out 274s 2024-06-08 05:57:16,633 INFO logout requested 274s 2024-06-08 05:57:16,635 INFO Logging out user dédé@example.net. 274s 2024-06-08 05:57:16,639 INFO User dédé@example.net logged out 274s 2024-06-08 05:57:16,642 INFO logout requested 274s 2024-06-08 05:57:16,643 INFO Logging out user dédé@example.test. 274s 2024-06-08 05:57:16,647 INFO User dédé@example.test logged out 274s PASSED 274s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2024-06-08 05:57:16,663 INFO Trying to authenticate http://127.0.0.1:8080 again 274s 2024-06-08 05:57:16,668 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 274s 2024-06-08 05:57:16,676 INFO User dédé@example.com successfully authenticated 274s 2024-06-08 05:57:16,685 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 274s 2024-06-08 05:57:16,694 INFO Trying to authenticate http://127.0.0.1:8081 again 274s 2024-06-08 05:57:16,699 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 274s 2024-06-08 05:57:16,707 INFO User dédé@example.org successfully authenticated 274s 2024-06-08 05:57:16,716 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 274s 2024-06-08 05:57:16,725 INFO Trying to authenticate http://127.0.0.1:8082 again 274s 2024-06-08 05:57:16,730 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 274s 2024-06-08 05:57:16,739 INFO User dédé@example.net successfully authenticated 274s 2024-06-08 05:57:16,748 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 274s 2024-06-08 05:57:16,759 INFO Trying to authenticate http://127.0.0.1:8083 again 274s 2024-06-08 05:57:16,765 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 274s 2024-06-08 05:57:16,774 INFO User dédé@example.test successfully authenticated 274s 2024-06-08 05:57:16,782 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 274s 2024-06-08 05:57:16,787 INFO Trying to authenticate http://127.0.0.1:8080 again 274s 2024-06-08 05:57:16,792 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 274s 2024-06-08 05:57:16,802 INFO User dédé@example.com successfully authenticated 274s 2024-06-08 05:57:16,806 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 274s 2024-06-08 05:57:16,812 INFO Trying to authenticate http://127.0.0.1:8081 again 274s 2024-06-08 05:57:16,817 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 274s 2024-06-08 05:57:16,826 INFO User dédé@example.org successfully authenticated 274s 2024-06-08 05:57:16,830 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 274s 2024-06-08 05:57:16,836 INFO Trying to authenticate http://127.0.0.1:8082 again 274s 2024-06-08 05:57:16,841 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 274s 2024-06-08 05:57:16,850 INFO User dédé@example.net successfully authenticated 274s 2024-06-08 05:57:16,855 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 274s 2024-06-08 05:57:16,860 INFO Trying to authenticate http://127.0.0.1:8083 again 274s 2024-06-08 05:57:16,865 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 274s 2024-06-08 05:57:16,875 INFO User dédé@example.test successfully authenticated 274s 2024-06-08 05:57:16,879 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 274s PASSED 274s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 274s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 274s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 274s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2024-06-08 05:57:16,916 INFO User test successfully authenticated 274s PASSED 274s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2024-06-08 05:57:16,942 INFO User test successfully authenticated 274s PASSED 274s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2024-06-08 05:57:16,954 INFO User test successfully authenticated 274s 2024-06-08 05:57:16,962 INFO User test successfully authenticated 275s 2024-06-08 05:57:16,972 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 275s 2024-06-08 05:57:16,976 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')) 275s PASSED 275s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2024-06-08 05:57:16,988 INFO User test successfully authenticated 275s PASSED 275s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired PASSED 275s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2024-06-08 05:57:17,003 INFO User test successfully authenticated 275s 2024-06-08 05:57:17,021 INFO User test successfully authenticated 275s 2024-06-08 05:57:17,031 INFO Sending SLO requests to service http://127.0.0.1:35945 for user test 275s 2024-06-08 05:57:17,035 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 275s PASSEDConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 275s 275s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2024-06-08 05:57:17,046 INFO User test successfully authenticated 275s PASSED 275s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 275s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 275s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 275s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 275s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 275s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 275s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 275s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 275s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 275s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 275s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 275s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 275s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 275s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 275s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 275s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 275s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 275s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 275s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 276s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version 2024-06-08 05:57:18,408 INFO User test successfully authenticated 276s 2024-06-08 05:57:18,415 WARNING Service https://www.example.org not allowed. 276s 2024-06-08 05:57:18,427 INFO User test successfully authenticated 276s 2024-06-08 05:57:18,434 INFO Service ticket created for service https://www.example.com by user test. 276s 2024-06-08 05:57:18,445 INFO User test successfully authenticated 276s 2024-06-08 05:57:18,451 INFO Service ticket created for service https://www.example.com by user test. 276s 2024-06-08 05:57:18,461 INFO User test successfully authenticated 276s 2024-06-08 05:57:18,478 INFO User test successfully authenticated 276s PASSED 276s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 276s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 276s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail PASSED 276s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success PASSED 276s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt PASSED 276s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn PASSED 276s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged PASSED 276s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2024-06-08 05:57:18,753 INFO User test successfully authenticated 276s 2024-06-08 05:57:18,791 WARNING User test seems authenticated but is not found in the database. 276s 2024-06-08 05:57:18,791 INFO Logging out user test. 276s 2024-06-08 05:57:18,792 INFO User test logged out 276s PASSED 276s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 276s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2024-06-08 05:57:18,808 INFO User test successfully authenticated 276s 2024-06-08 05:57:18,813 WARNING Username test not allowed on service restrict_user_fail 276s PASSED 276s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2024-06-08 05:57:18,825 WARNING A login attempt failed 276s 2024-06-08 05:57:18,836 WARNING A login attempt failed 276s 2024-06-08 05:57:18,849 WARNING A login attempt failed 276s PASSED 276s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2024-06-08 05:57:18,864 WARNING Received an invalid login ticket 276s PASSED 276s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2024-06-08 05:57:18,887 WARNING A login attempt failed 276s PASSED 276s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2024-06-08 05:57:18,902 INFO User test successfully authenticated 276s PASSED 276s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2024-06-08 05:57:18,916 INFO User test successfully authenticated 276s PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled 2024-06-08 05:57:19,635 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,645 INFO User test successfully authenticated 277s PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_renew 2024-06-08 05:57:19,648 INFO Service ticket created for service https://www.example.com by user test. 277s PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2024-06-08 05:57:19,660 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,669 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,672 INFO Service ticket created for service https://www.example.com by user test. 277s PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2024-06-08 05:57:19,683 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,688 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 277s 2024-06-08 05:57:19,691 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 277s 2024-06-08 05:57:19,696 INFO Service ticket created for service https://filter_success.example.com by user test. 277s PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2024-06-08 05:57:19,707 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,712 WARNING Username test not allowed on service restrict_user_fail 277s 2024-06-08 05:57:19,717 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 277s PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2024-06-08 05:57:19,727 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,732 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 277s 2024-06-08 05:57:19,737 INFO Service ticket created for service https://field_needed_success.example.com by user test. 277s PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2024-06-08 05:57:19,748 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,753 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 277s PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2024-06-08 05:57:19,764 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,769 WARNING User test seems authenticated but is not found in the database. 277s 2024-06-08 05:57:19,770 INFO Logging out user test. 277s 2024-06-08 05:57:19,770 INFO User test logged out 277s PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2024-06-08 05:57:19,796 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,803 INFO Service ticket created for service https://www.example.com/é by user test. 277s PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2024-06-08 05:57:19,814 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,820 INFO Service ticket created for service https://www.example.com by user test. 277s PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2024-06-08 05:57:19,832 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,841 WARNING Received a login attempt for an already-active user 277s 2024-06-08 05:57:19,843 INFO Service ticket created for service https://www.example.com by user test. 277s PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2024-06-08 05:57:19,854 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,859 WARNING Service https://www.example.org not allowed. 277s PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2024-06-08 05:57:19,865 WARNING Service https://www.example.net not allowed. 277s PASSED 277s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2024-06-08 05:57:19,873 WARNING Service https://www.example.net not allowed. 277s PASSED 277s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2024-06-08 05:57:19,885 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,888 INFO logout requested 277s 2024-06-08 05:57:19,889 INFO Logging out user test. 277s 2024-06-08 05:57:19,892 INFO User test logged out 277s PASSED 277s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2024-06-08 05:57:19,901 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,909 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,918 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,926 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,935 INFO User test successfully authenticated 277s 2024-06-08 05:57:19,938 INFO logout requested 277s 2024-06-08 05:57:19,939 INFO Logging out user test from all sessions. 277s 2024-06-08 05:57:19,954 INFO User test logged out 277s PASSED 277s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2024-06-08 05:57:19,963 INFO User test successfully authenticated 278s 2024-06-08 05:57:19,966 INFO logout requested 278s 2024-06-08 05:57:19,967 INFO Logging out user test. 278s 2024-06-08 05:57:19,970 INFO User test logged out 278s PASSED 278s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2024-06-08 05:57:19,973 INFO logout requested 278s PASSED 278s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2024-06-08 05:57:19,983 INFO User test successfully authenticated 278s 2024-06-08 05:57:19,992 INFO User test successfully authenticated 278s 2024-06-08 05:57:19,995 INFO logout requested 278s 2024-06-08 05:57:19,996 INFO Logging out user test from all sessions. 278s 2024-06-08 05:57:20,002 INFO User test logged out 278s PASSED 278s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2024-06-08 05:57:20,013 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,020 INFO Service ticket created for service http://127.0.0.1:37005 by user test. 278s 2024-06-08 05:57:20,023 INFO Validate: Service ticket ST-7q2Rp3iQTPiBkyR0g5VQUgiGv1jhE265rsZSuyyaYEQG1XmSRAbUGT8LXucNU validated, user test authenticated on service http://127.0.0.1:37005 278s 2024-06-08 05:57:20,025 INFO logout requested 278s 2024-06-08 05:57:20,025 INFO Logging out user test. 278s 2024-06-08 05:57:20,028 INFO Sending SLO requests to service http://127.0.0.1:37005 for user test 278s 2024-06-08 05:57:20,031 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 278s 2024-06-08 05:57:20,033 INFO User test logged out 278s 2024-06-08 05:57:20,041 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,048 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 278s 2024-06-08 05:57:20,051 INFO Validate: Service ticket ST-so9YC9YX6Qmz4aAmAhieE6pwtXoLi8qZGo9P6fqoJPD0cE1fAkCVUZL8Jmv5u validated, user test authenticated on service http://127.0.0.1:45678 278s 2024-06-08 05:57:20,052 INFO logout requested 278s 2024-06-08 05:57:20,053 INFO Logging out user test. 278s 2024-06-08 05:57:20,055 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 278s 2024-06-08 05:57:20,059 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 278s 2024-06-08 05:57:20,061 INFO User test logged out 278s 2024-06-08 05:57:20,083 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,090 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 278s 2024-06-08 05:57:20,093 INFO Validate: Service ticket ST-9nhda05vwbLsYpcFBJK0DS1Tj0C51CvW3rpWwa33FimHy3B4EFHsh7HUXvLmt validated, user test authenticated on service http://127.0.0.1:45678 278s 2024-06-08 05:57:20,094 INFO logout requested 278s 2024-06-08 05:57:20,095 INFO Logging out user test. 278s 2024-06-08 05:57:20,097 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 278s 2024-06-08 05:57:20,100 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=44545): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 278s 2024-06-08 05:57:20,102 INFO User test logged out 278s PASSED 278s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2024-06-08 05:57:20,111 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,120 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,126 INFO logout requested 278s 2024-06-08 05:57:20,127 INFO Logging out user test. 278s 2024-06-08 05:57:20,130 INFO User test logged out 278s PASSED 278s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2024-06-08 05:57:20,375 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,379 INFO logout requested 278s 2024-06-08 05:57:20,379 INFO Logging out user test. 278s 2024-06-08 05:57:20,384 INFO User test logged out 278s PASSED 278s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2024-06-08 05:57:20,396 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,399 INFO logout requested 278s 2024-06-08 05:57:20,400 INFO Logging out user test. 278s 2024-06-08 05:57:20,404 INFO User test logged out 278s PASSED 278s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2024-06-08 05:57:20,417 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,420 INFO logout requested 278s 2024-06-08 05:57:20,421 INFO Logging out user test. 278s 2024-06-08 05:57:20,424 INFO User test logged out 278s PASSED 278s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2024-06-08 05:57:20,434 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,437 INFO logout requested 278s 2024-06-08 05:57:20,438 INFO Logging out user test. 278s 2024-06-08 05:57:20,441 INFO User test logged out 278s 2024-06-08 05:57:20,446 INFO logout requested 278s PASSED 278s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 278s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 278s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice 2024-06-08 05:57:20,462 WARNING Service https://www.example.org not allowed. 278s PASSED 278s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings PASSED 278s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 278s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2024-06-08 05:57:20,477 INFO User test successfully authenticated 278s PASSED 278s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 278s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2024-06-08 05:57:20,495 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,502 INFO Service ticket created for service https://www.example.com by user test. 278s 2024-06-08 05:57:20,504 WARNING Validate: service or ticket missing 278s 2024-06-08 05:57:20,504 WARNING Validate: service or ticket missing 278s PASSED 278s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2024-06-08 05:57:20,514 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,517 INFO Service ticket created for service https://www.example.com by user test. 278s 2024-06-08 05:57:20,521 INFO Validate: Service ticket ST-elinZA2cgfM0q4gqAzsP15u860LQ7CAG10gBfhUzHUTB8wS2coKqT1HxhS6LC validated, user test authenticated on service https://www.example.com 278s 2024-06-08 05:57:20,528 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,532 INFO Service ticket created for service https://www.example.com by user test. 278s 2024-06-08 05:57:20,535 INFO Validate: Service ticket ST-peSREtT3T7uQBVNmiK5NKSh7iMGflDSbysNExLx0UPQCZU1xXUZg3JdxUbPUD validated, user test authenticated on service https://www.example.com 278s 2024-06-08 05:57:20,539 INFO Service ticket created for service https://www.example.com by user test. 278s 2024-06-08 05:57:20,541 WARNING Validate: Service ticket ST-ZmA1J4grTs4EpkyNvSpCrpzgi00koHBfzBcK2NkpO19vybrnUpAegi0BVQFrL not found or already validated, auth to https://www.example.com failed 278s PASSED 278s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2024-06-08 05:57:20,549 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,556 INFO Service ticket created for service https://user_field.example.com by user test. 278s 2024-06-08 05:57:20,559 INFO Validate: Service ticket ST-XCNbY9ZLJrGXJAvVcnudeRIQVTxDlW6lQg9VglwKTpCaYKSgkORv942NbcRpX validated, user test authenticated on service https://user_field.example.com 278s 2024-06-08 05:57:20,566 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,573 INFO Service ticket created for service https://user_field_alt.example.com by user test. 278s 2024-06-08 05:57:20,576 INFO Validate: Service ticket ST-rsrUXOOY8uCJjdRTylxNs9M8MdHPRY0fCikLKEp8uMgR766j2AqA27Wzig7kj validated, user test authenticated on service https://user_field_alt.example.com 278s PASSED 278s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2024-06-08 05:57:20,585 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,591 INFO Service ticket created for service https://www.example.com by user test. 278s 2024-06-08 05:57:20,593 WARNING Validate: Service ticket ST-XWPqtPsXre5LoVuWugzwev5WKG66IIAaQfH9PnQOMNTO4Jgb1I0GEFGbVLAgn not found or already validated, auth to https://www.example.org failed 278s PASSED 278s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2024-06-08 05:57:20,602 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,608 INFO Service ticket created for service https://www.example.com by user test. 278s 2024-06-08 05:57:20,610 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 278s PASSED 278s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2024-06-08 05:57:20,619 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,625 INFO Service ticket created for service https://www.example.com by user test. 278s 2024-06-08 05:57:20,628 INFO Validate: Service ticket ST-YFz7DHLHkvmPkAZ7wjd1seO3TkS4jYs6OV4CfgdAGqLXyYcoUCJ21TpcNuMdz validated, user test authenticated on service https://www.example.com 278s PASSED 278s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2024-06-08 05:57:20,639 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,645 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 278s 2024-06-08 05:57:20,647 WARNING ValidateService: missing ticket or service 278s 2024-06-08 05:57:20,678 WARNING ValidateService: missing ticket or service 278s PASSED 278s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2024-06-08 05:57:20,690 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,696 INFO Service ticket created for service http://127.0.0.1:33443 by user test. 278s 2024-06-08 05:57:20,703 INFO ValidateService: ticket ST-Bz8cDC5IytiOb7DRGlAJRzTfTc9Gcz11puiTBQtuKmM5fZXrPjwVe7wcv3Ktv validated for user test on service http://127.0.0.1:33443. Proxy Granting Ticket transmited to http://127.0.0.1:33443. 278s 2024-06-08 05:57:20,708 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 278s 2024-06-08 05:57:20,712 INFO ValidateService: ticket PT-CCDB2CahZb9VI5p3oFm7fqaDGsAS2TNnW7D0DZEfUH6wfAFApRji0TzBSpJZY validated for user test on service http://127.0.0.1:45678. 278s 2024-06-08 05:57:20,719 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,726 INFO Service ticket created for service http://127.0.0.1:33197 by user test. 278s 2024-06-08 05:57:20,732 INFO ValidateService: ticket ST-Otan2HFCXPV0OCAab5Tb7UZKvtSUjoxtRyNhaThOOXdSU6kDMh3tL95Pk6ZCA validated for user test on service http://127.0.0.1:33197. Proxy Granting Ticket transmited to http://127.0.0.1:33197. 278s 2024-06-08 05:57:20,736 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 278s 2024-06-08 05:57:20,738 WARNING ValidateService: validation error: INVALID_TICKET PT-foxsl2mCzH0YgyGvzhGskATfGvb42O9kaFBZymMh4J26AMGW3rGe8DDCd5OC0 278s PASSED 278s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2024-06-08 05:57:20,748 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,755 INFO Service ticket created for service https://replace_attribute.example.com by user test. 278s 2024-06-08 05:57:20,758 INFO ValidateService: ticket ST-yJDyXOSh8J50LT586Gxz1Kwk71vZOT6Cx1zhZAwROWIqQoFBbMqGbgm74iXzy validated for user test on service https://replace_attribute.example.com. 278s 2024-06-08 05:57:20,765 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,772 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 278s 2024-06-08 05:57:20,775 INFO ValidateService: ticket ST-vhcCSW6tk9m3Jr8mUliaYWx8wgWumaaWlKcCEKADZdncANiWI5M4Xo78BiacL validated for user test on service https://replace_attribute_list.example.com. 278s PASSED 278s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2024-06-08 05:57:20,786 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,792 INFO Service ticket created for service http://127.0.0.1:40843 by user test. 278s 2024-06-08 05:57:20,799 INFO ValidateService: ticket ST-fcknAuwSa7dya1nkhnfgtYv9C7oGWlu15fnM9gG6xYnn5KlaxZ9k9i898UzGq validated for user test on service http://127.0.0.1:40843. Proxy Granting Ticket transmited to http://127.0.0.1:40843. 278s PASSED 278s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2024-06-08 05:57:20,810 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,816 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 278s 2024-06-08 05:57:20,820 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 278s 2024-06-08 05:57:20,827 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,834 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 278s 2024-06-08 05:57:20,838 WARNING Service https://www.example.org not allowed. 278s 2024-06-08 05:57:20,838 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 278s PASSED 278s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2024-06-08 05:57:20,848 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,854 INFO Service ticket created for service https://127.0.0.1:41947 by user test. 278s PASSED 278s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2024-06-08 05:57:20,898 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=41947): Max retries exceeded with url: /?pgtId=PGT-Ep7jxnQH02CswahV4RUUa6mo3tg86KyJolV9el2io9hcDRmDZ6XoWHjw5y4X&pgtIou=PGTIOU-0cf4bOz2rnvucOfnNf8gEbUkfPzpRJuTTdvkVQiblQLs4nT7MX97dfuZw (Caused by SSLError(SSLError(1, '[SSL: WRONG_VERSION_NUMBER] wrong version number (_ssl.c:1000)'))) 278s 2024-06-08 05:57:20,909 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,913 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 278s 2024-06-08 05:57:20,916 INFO ValidateService: ticket ST-LFG9LZjBigZ8RL6toQOPBJbqcGkOnByB9gPczd83VVN87amh3AdnpHepc227U validated for user test on service http://127.0.0.1:45678. 278s 2024-06-08 05:57:20,923 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,926 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 278s 2024-06-08 05:57:20,930 INFO ValidateService: ticket ST-h2dt6XtkJUdI1ucOthRFZ5Lv1qWmk0sL6wmAGoTEsnripJKJudBiRxFdsEsMM validated for user test on service http://127.0.0.1:45678. 278s 2024-06-08 05:57:20,934 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 278s 2024-06-08 05:57:20,936 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 278s PASSED 278s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2024-06-08 05:57:20,946 INFO User test successfully authenticated 278s 2024-06-08 05:57:20,952 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 278s 2024-06-08 05:57:20,955 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 278s PASSED 279s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2024-06-08 05:57:20,965 INFO User test successfully authenticated 279s 2024-06-08 05:57:20,971 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 279s 2024-06-08 05:57:20,973 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 279s PASSED 279s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2024-06-08 05:57:20,982 INFO User test successfully authenticated 279s 2024-06-08 05:57:20,988 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 279s 2024-06-08 05:57:20,991 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 279s PASSED 279s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2024-06-08 05:57:21,001 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,007 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 279s 2024-06-08 05:57:21,010 INFO ValidateService: ticket ST-SdXBYyyA29r6pp555EhittcKYmwRODvgBQKzJ18wsVnO2A8asiVxueM0Nl6YI validated for user test on service http://127.0.0.1:45678. 279s PASSED 279s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2024-06-08 05:57:21,020 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,026 INFO Service ticket created for service https://one_attribute.example.com by user test. 279s 2024-06-08 05:57:21,030 INFO ValidateService: ticket ST-tCuzdrC3iTMWJHDNpLTdlrQU3KEeMwsflQ9vJGVwmXW0jyZ4ACYBGNZVctob0 validated for user test on service https://one_attribute.example.com. 279s PASSED 279s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2024-06-08 05:57:21,040 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,048 INFO Service ticket created for service http://127.0.0.1:41893 by user test. 279s 2024-06-08 05:57:21,054 INFO ValidateService: ticket ST-T3GcAsGe0wBC8vUsdaneXubc2km9RCkyQqTaeXw5GWXNQmCv6IuNl6eWpR83D validated for user test on service http://127.0.0.1:41893. Proxy Granting Ticket transmited to http://127.0.0.1:41893. 279s PASSED 279s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2024-06-08 05:57:21,065 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,071 INFO Service ticket created for service https://user_field.example.com by user test. 279s 2024-06-08 05:57:21,075 INFO ValidateService: ticket ST-a8QIUY2mohzgAkWFbc8PlhX5V65zMSxkgks2AAjEAl5Ze23IxTNjBYzQLAkqr validated for user test on service https://user_field.example.com. 279s 2024-06-08 05:57:21,081 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,088 INFO Service ticket created for service https://user_field_alt.example.com by user test. 279s 2024-06-08 05:57:21,091 INFO ValidateService: ticket ST-RbBrNa2qOobJdBlq5iBM6Tszm6A7vKFKFvMDeOvobhh470Xs4y5FxOserZOTj validated for user test on service https://user_field_alt.example.com. 279s PASSED 279s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2024-06-08 05:57:21,103 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,109 INFO Service ticket created for service http://127.0.0.1:46439 by user test. 279s 2024-06-08 05:57:21,116 INFO ValidateService: ticket ST-OPmIajAZKE0Bos2uKk4ub9Gxh3X8LVJWw45IDTA2joUE6OGusA69GeSzzR0D6 validated for user test on service http://127.0.0.1:46439. Proxy Granting Ticket transmited to http://127.0.0.1:46439. 279s 2024-06-08 05:57:21,117 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 279s 2024-06-08 05:57:21,118 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 279s PASSED 279s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2024-06-08 05:57:21,129 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,135 INFO Service ticket created for service http://127.0.0.1:42885 by user test. 279s 2024-06-08 05:57:21,142 INFO ValidateService: ticket ST-HZ0I2nDAutFah4wLJH7eH8iuKB2nghEEwVrrMEMlAobRIfGbKVmVNWPpHKIC1 validated for user test on service http://127.0.0.1:42885. Proxy Granting Ticket transmited to http://127.0.0.1:42885. 279s 2024-06-08 05:57:21,145 WARNING Username test not allowed on service restrict_user_fail 279s 2024-06-08 05:57:21,145 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 279s 2024-06-08 05:57:21,148 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 279s 2024-06-08 05:57:21,148 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 279s 2024-06-08 05:57:21,150 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 279s 2024-06-08 05:57:21,150 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 279s PASSED 279s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2024-06-08 05:57:21,161 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,168 INFO Service ticket created for service http://127.0.0.1:46355 by user test. 279s 2024-06-08 05:57:21,174 INFO ValidateService: ticket ST-vg4c8OOFSDuHJnKaVMX9kqiF8rletwz3zBQgPaBfk8lHqFQK0RWuerTTjYFDf validated for user test on service http://127.0.0.1:46355. Proxy Granting Ticket transmited to http://127.0.0.1:46355. 279s 2024-06-08 05:57:21,176 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 279s PASSED 279s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2024-06-08 05:57:21,187 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,193 INFO Service ticket created for service http://127.0.0.1:43513 by user test. 279s 2024-06-08 05:57:21,200 INFO ValidateService: ticket ST-ynXMt9jc61vWwE2Z1ZDrANsQoxdnh3dEZrRR8arTP3ngYpLcajfKl5uNtx2Y2 validated for user test on service http://127.0.0.1:43513. Proxy Granting Ticket transmited to http://127.0.0.1:43513. 279s 2024-06-08 05:57:21,201 WARNING Service https://www.example.org not allowed. 279s 2024-06-08 05:57:21,201 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 279s 2024-06-08 05:57:21,203 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:43513 does not allow proxy tickets 279s PASSED 279s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2024-06-08 05:57:21,215 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,221 INFO Service ticket created for service http://127.0.0.1:33087 by user test. 279s 2024-06-08 05:57:21,227 INFO ValidateService: ticket ST-nTfPOWOUo01vdNFG4R5RP8T4QqFiszg4Su8EaOltlKzbAxBjH4rF39atOzWX4 validated for user test on service http://127.0.0.1:33087. Proxy Granting Ticket transmited to http://127.0.0.1:33087. 279s 2024-06-08 05:57:21,232 INFO Proxy ticket created for user test on service https://www.example.com. 279s 2024-06-08 05:57:21,276 INFO ValidateService: ticket PT-gbqDSbuDkqMZmdAlu9Tn9DxFlYL7EEjUtT37RxdSAYUIaEWdL1hjorPl355Pk validated for user test on service https://www.example.com. 279s PASSED 279s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2024-06-08 05:57:21,288 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,295 INFO Service ticket created for service https://www.example.com by user test. 279s 2024-06-08 05:57:21,298 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 279s PASSED 279s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2024-06-08 05:57:21,304 WARNING SamlValidate: validation error: AuthnFailed ticket ST-DI2qJg5tLeNcESuMMkmUs8fTQcuqPRbma7r3RZQIyYO3iT7tDwOu1wVY2mYjc not found 279s 2024-06-08 05:57:21,306 WARNING SamlValidate: validation error: AuthnFailed ticket PT-3jT18x5iXcjl5dEm2z1kzJCm23GIAjwOfFaR0E5qJFFcHQkD4w9IdMbmpffcv not found 279s PASSED 279s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix 2024-06-08 05:57:21,311 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 279s PASSED 279s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml 2024-06-08 05:57:21,316 WARNING SamlValidate: validation error: VersionMismatch 279s PASSED 279s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2024-06-08 05:57:21,326 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,332 INFO Service ticket created for service https://www.example.com by user test. 279s 2024-06-08 05:57:21,340 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,347 INFO Service ticket created for service http://127.0.0.1:37007 by user test. 279s 2024-06-08 05:57:21,353 INFO ValidateService: ticket ST-XZcVrPNgmqXGe06qT3JkKf8LMsUs93kZzuf7CN4FyIOxV5sss5QNWcHXb6Dij validated for user test on service http://127.0.0.1:37007. Proxy Granting Ticket transmited to http://127.0.0.1:37007. 279s 2024-06-08 05:57:21,358 INFO Proxy ticket created for user test on service https://www.example.com. 279s 2024-06-08 05:57:21,361 INFO SamlValidate: ticket ST-DiLNJ1tPHnsHzSOtlc6ubsYdFXEmoAbU1e0rDZQKvbiPddpKmOPioXKMLRkM4 validated for user test on service https://www.example.com. 279s 2024-06-08 05:57:21,364 INFO SamlValidate: ticket PT-Bn1tlzYmqOCZhdH5h1oL8AcIO7c5GANehJpMM8lsGlDCZHtiBeT5IL8gdvvF7 validated for user test on service https://www.example.com. 279s PASSED 279s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2024-06-08 05:57:21,375 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,381 INFO Service ticket created for service https://field_needed_success.example.com by user test. 279s 2024-06-08 05:57:21,385 INFO SamlValidate: ticket ST-SAr1bHUzYQBWR1kuRcX6eJ9aHhbGRpgk8IELMhPGIL7zIqAFBhKelr1wysbGW validated for user test on service https://field_needed_success.example.com. 279s 2024-06-08 05:57:21,392 INFO User test successfully authenticated 279s 2024-06-08 05:57:21,398 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 279s 2024-06-08 05:57:21,401 INFO SamlValidate: ticket ST-022JESGjdEW5srMY4julJqzWJCbKwy2DfihSYb1HxzP4bCY5XUNsGEX5n8Jfd validated for user test on service https://field_needed_success_alt.example.com. 279s Destroying test database for alias 'default'... 279s PASSED 279s 279s =============================== warnings summary =============================== 279s cas_server/utils.py:33 279s /tmp/autopkgtest.QfD5BL/autopkgtest_tmp/cas_server/utils.py:33: DeprecationWarning: 'crypt' is deprecated and slated for removal in Python 3.13 279s import crypt 279s 279s cas_server/tests/utils.py:20 279s /tmp/autopkgtest.QfD5BL/autopkgtest_tmp/cas_server/tests/utils.py:20: DeprecationWarning: 'cgi' is deprecated and slated for removal in Python 3.13 279s import cgi 279s 279s cas_server/tests/test_federate.py: 8 warnings 279s cas_server/tests/test_view.py: 3 warnings 279s /tmp/autopkgtest.QfD5BL/autopkgtest_tmp/cas_server/utils.py:208: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC). 279s datetime.utcnow() + timedelta(seconds=max_age), 279s 279s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 279s /usr/lib/python3.12/unittest/case.py:690: DeprecationWarning: It is deprecated to return a value that is not None from a test case (>) 279s return self.run(*args, **kwds) 279s 279s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 279s ======================= 128 passed, 14 warnings in 9.04s ======================= 280s 281s autopkgtest [05:57:23]: test run-test-suite.sh: -----------------------] 285s autopkgtest [05:57:27]: test run-test-suite.sh: - - - - - - - - - - results - - - - - - - - - - 285s run-test-suite.sh PASS 290s autopkgtest [05:57:32]: @@@@@@@@@@@@@@@@@@@@ summary 290s run-test-suite.sh PASS