1s autopkgtest [14:08:17]: starting date and time: 2024-11-14 14:08:17+0000 1s autopkgtest [14:08:17]: git checkout: 6f3be7a8 Fix armhf LXD image generation for plucky 1s autopkgtest [14:08:17]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.lk1nku65/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:lxml --apt-upgrade django-cas-server --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=lxml/5.3.0-1build1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-ppc64el-6.secgroup --name adt-plucky-ppc64el-django-cas-server-20241114-140816-juju-7f2275-prod-proposed-migration-environment-20-31b03602-7986-491a-a449-8fe0e48c8030 --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_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'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 100s autopkgtest [14:09:56]: testbed dpkg architecture: ppc64el 100s autopkgtest [14:09:56]: testbed apt version: 2.9.8 100s autopkgtest [14:09:56]: @@@@@@@@@@@@@@@@@@@@ test bed setup 101s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 101s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.3 kB] 101s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 101s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [983 kB] 102s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [105 kB] 102s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [115 kB] 102s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [705 kB] 102s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [8540 B] 102s Fetched 2012 kB in 1s (1362 kB/s) 102s Reading package lists... 105s Reading package lists... 105s Building dependency tree... 105s Reading state information... 105s Calculating upgrade... 105s The following packages will be upgraded: 105s bpfcc-tools bpftrace libbpfcc libgnutls30t64 libjson-glib-1.0-0 105s libjson-glib-1.0-common libnewt0.52 libutempter0 pastebinit python3-bpfcc 105s python3-newt whiptail 106s 12 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 106s Need to get 4613 kB of archives. 106s After this operation, 73.7 kB of additional disk space will be used. 106s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el libgnutls30t64 ppc64el 3.8.8-2ubuntu1 [1072 kB] 106s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-newt ppc64el 0.52.24-2ubuntu4 [21.8 kB] 106s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el libnewt0.52 ppc64el 0.52.24-2ubuntu4 [62.1 kB] 106s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el whiptail ppc64el 0.52.24-2ubuntu4 [19.5 kB] 106s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el libbpfcc ppc64el 0.30.0+ds-1ubuntu5 [696 kB] 106s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-bpfcc all 0.30.0+ds-1ubuntu5 [40.4 kB] 106s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el bpfcc-tools all 0.30.0+ds-1ubuntu5 [697 kB] 106s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el bpftrace ppc64el 0.21.2-2ubuntu2 [1898 kB] 106s Get:9 http://ftpmaster.internal/ubuntu plucky/main ppc64el libjson-glib-1.0-common all 1.10.0+ds-3 [5586 B] 106s Get:10 http://ftpmaster.internal/ubuntu plucky/main ppc64el libjson-glib-1.0-0 ppc64el 1.10.0+ds-3 [76.0 kB] 106s Get:11 http://ftpmaster.internal/ubuntu plucky/main ppc64el libutempter0 ppc64el 1.2.1-4 [9850 B] 106s Get:12 http://ftpmaster.internal/ubuntu plucky/main ppc64el pastebinit all 1.7.1-1 [14.9 kB] 107s Fetched 4613 kB in 1s (4763 kB/s) 107s (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 ... 73767 files and directories currently installed.) 107s Preparing to unpack .../libgnutls30t64_3.8.8-2ubuntu1_ppc64el.deb ... 107s Unpacking libgnutls30t64:ppc64el (3.8.8-2ubuntu1) over (3.8.6-2ubuntu1) ... 107s Setting up libgnutls30t64:ppc64el (3.8.8-2ubuntu1) ... 107s (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 ... 73767 files and directories currently installed.) 107s Preparing to unpack .../00-python3-newt_0.52.24-2ubuntu4_ppc64el.deb ... 107s Unpacking python3-newt:ppc64el (0.52.24-2ubuntu4) over (0.52.24-2ubuntu3) ... 107s Preparing to unpack .../01-libnewt0.52_0.52.24-2ubuntu4_ppc64el.deb ... 107s Unpacking libnewt0.52:ppc64el (0.52.24-2ubuntu4) over (0.52.24-2ubuntu3) ... 107s Preparing to unpack .../02-whiptail_0.52.24-2ubuntu4_ppc64el.deb ... 107s Unpacking whiptail (0.52.24-2ubuntu4) over (0.52.24-2ubuntu3) ... 107s Preparing to unpack .../03-libbpfcc_0.30.0+ds-1ubuntu5_ppc64el.deb ... 107s Unpacking libbpfcc:ppc64el (0.30.0+ds-1ubuntu5) over (0.30.0+ds-1ubuntu4) ... 107s Preparing to unpack .../04-python3-bpfcc_0.30.0+ds-1ubuntu5_all.deb ... 107s Unpacking python3-bpfcc (0.30.0+ds-1ubuntu5) over (0.30.0+ds-1ubuntu4) ... 107s Preparing to unpack .../05-bpfcc-tools_0.30.0+ds-1ubuntu5_all.deb ... 107s Unpacking bpfcc-tools (0.30.0+ds-1ubuntu5) over (0.30.0+ds-1ubuntu4) ... 108s Preparing to unpack .../06-bpftrace_0.21.2-2ubuntu2_ppc64el.deb ... 108s Unpacking bpftrace (0.21.2-2ubuntu2) over (0.21.2-2) ... 108s Preparing to unpack .../07-libjson-glib-1.0-common_1.10.0+ds-3_all.deb ... 108s Unpacking libjson-glib-1.0-common (1.10.0+ds-3) over (1.10.0+ds-2) ... 108s Preparing to unpack .../08-libjson-glib-1.0-0_1.10.0+ds-3_ppc64el.deb ... 108s Unpacking libjson-glib-1.0-0:ppc64el (1.10.0+ds-3) over (1.10.0+ds-2) ... 108s Preparing to unpack .../09-libutempter0_1.2.1-4_ppc64el.deb ... 108s Unpacking libutempter0:ppc64el (1.2.1-4) over (1.2.1-3build1) ... 108s Preparing to unpack .../10-pastebinit_1.7.1-1_all.deb ... 108s Unpacking pastebinit (1.7.1-1) over (1.7.0-1) ... 108s Setting up libnewt0.52:ppc64el (0.52.24-2ubuntu4) ... 108s Setting up pastebinit (1.7.1-1) ... 108s Setting up python3-newt:ppc64el (0.52.24-2ubuntu4) ... 108s Setting up libutempter0:ppc64el (1.2.1-4) ... 108s Setting up whiptail (0.52.24-2ubuntu4) ... 108s Setting up libjson-glib-1.0-common (1.10.0+ds-3) ... 108s Setting up libbpfcc:ppc64el (0.30.0+ds-1ubuntu5) ... 108s Setting up python3-bpfcc (0.30.0+ds-1ubuntu5) ... 108s Setting up bpftrace (0.21.2-2ubuntu2) ... 108s Setting up libjson-glib-1.0-0:ppc64el (1.10.0+ds-3) ... 108s Setting up bpfcc-tools (0.30.0+ds-1ubuntu5) ... 108s Processing triggers for man-db (2.12.1-3) ... 109s Processing triggers for libc-bin (2.40-1ubuntu3) ... 110s Reading package lists... 110s Building dependency tree... 110s Reading state information... 110s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 110s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 110s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 111s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 111s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 112s Reading package lists... 112s Reading package lists... 112s Building dependency tree... 112s Reading state information... 112s Calculating upgrade... 113s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 113s Reading package lists... 113s Building dependency tree... 113s Reading state information... 113s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 116s autopkgtest [14:10:12]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 13:49:23 UTC 2024 116s autopkgtest [14:10:12]: @@@@@@@@@@@@@@@@@@@@ apt-source django-cas-server 118s Get:1 http://ftpmaster.internal/ubuntu plucky/universe django-cas-server 2.1.0-1 (dsc) [2613 B] 118s Get:2 http://ftpmaster.internal/ubuntu plucky/universe django-cas-server 2.1.0-1 (tar) [138 kB] 118s Get:3 http://ftpmaster.internal/ubuntu plucky/universe django-cas-server 2.1.0-1 (diff) [9752 B] 118s gpgv: Signature made Tue Aug 20 09:50:18 2024 UTC 118s gpgv: using RSA key 8F6DE104377F3B11E741748731F3144544A1741A 118s gpgv: issuer "tchet@debian.org" 118s gpgv: Can't check signature: No public key 118s dpkg-source: warning: cannot verify inline signature for ./django-cas-server_2.1.0-1.dsc: no acceptable signature found 119s autopkgtest [14:10:15]: testing package django-cas-server version 2.1.0-1 119s autopkgtest [14:10:15]: build not needed 119s autopkgtest [14:10:15]: test run-test-suite.sh: preparing testbed 123s Reading package lists... 123s Building dependency tree... 123s Reading state information... 123s Starting pkgProblemResolver with broken count: 0 124s Starting 2 pkgProblemResolver with broken count: 0 124s Done 124s The following additional packages will be installed: 124s fonts-font-awesome fonts-lato libjs-jquery libjs-sphinxdoc libjs-underscore 124s libxslt1.1 python3-all python3-asgiref python3-django 124s python3-django-cas-server python3-django-cas-server-doc python3-iniconfig 124s python3-lxml python3-mock python3-packaging python3-pluggy python3-pytest 124s python3-pytest-django python3-requests-futures python3-sqlparse 124s sphinx-rtd-theme-common 124s Suggested packages: 124s bpython3 geoip-database-contrib gettext ipython3 libgdal20 124s libsqlite3-mod-spatialite python-django-doc python3-bcrypt python3-flup 124s python3-memcache python3-mysqldb python3-pil python3-psycopg2 124s python3-selenium python3-sqlite python-lxml-doc python-mock-doc 124s python-sqlparse-doc 124s Recommended packages: 124s javascript-common python3-tz python3-bs4 python3-cssselect python3-html5lib 124s The following NEW packages will be installed: 124s autopkgtest-satdep fonts-font-awesome fonts-lato libjs-jquery 124s libjs-sphinxdoc libjs-underscore libxslt1.1 python3-all python3-asgiref 124s python3-django python3-django-cas-server python3-django-cas-server-doc 124s python3-iniconfig python3-lxml python3-mock python3-packaging python3-pluggy 124s python3-pytest python3-pytest-django python3-requests-futures 124s python3-sqlparse sphinx-rtd-theme-common 124s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 124s Need to get 10.5 MB/10.5 MB of archives. 124s After this operation, 58.0 MB of additional disk space will be used. 124s Get:1 /tmp/autopkgtest.2b8tcs/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [748 B] 124s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el fonts-lato all 2.015-1 [2781 kB] 125s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 125s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 125s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 125s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el libjs-sphinxdoc all 7.4.7-4 [158 kB] 125s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el libxslt1.1 ppc64el 1.1.39-0exp1ubuntu1 [191 kB] 125s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-all ppc64el 3.12.6-0ubuntu1 [888 B] 125s Get:9 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-asgiref all 3.8.1-1 [24.8 kB] 125s Get:10 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-sqlparse all 0.5.1-2 [32.7 kB] 125s Get:11 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-django all 3:4.2.15-1ubuntu1 [2916 kB] 125s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el python3-lxml ppc64el 5.3.0-1build1 [1751 kB] 125s Get:13 http://ftpmaster.internal/ubuntu plucky/universe ppc64el python3-requests-futures all 1.0.1-1 [11.7 kB] 125s Get:14 http://ftpmaster.internal/ubuntu plucky/universe ppc64el python3-django-cas-server all 2.1.0-1 [111 kB] 125s Get:15 http://ftpmaster.internal/ubuntu plucky/main ppc64el sphinx-rtd-theme-common all 3.0.1+dfsg-1 [1012 kB] 125s Get:16 http://ftpmaster.internal/ubuntu plucky/universe ppc64el python3-django-cas-server-doc all 2.1.0-1 [176 kB] 125s Get:17 http://ftpmaster.internal/ubuntu plucky/universe ppc64el python3-iniconfig all 1.1.1-2 [6024 B] 125s Get:18 http://ftpmaster.internal/ubuntu plucky/main ppc64el python3-packaging all 24.1-1 [41.4 kB] 125s Get:19 http://ftpmaster.internal/ubuntu plucky/universe ppc64el python3-pluggy all 1.5.0-1 [21.0 kB] 125s Get:20 http://ftpmaster.internal/ubuntu plucky/universe ppc64el python3-pytest all 8.3.3-1 [251 kB] 125s Get:21 http://ftpmaster.internal/ubuntu plucky/universe ppc64el python3-pytest-django all 4.5.2-3 [18.5 kB] 125s Get:22 http://ftpmaster.internal/ubuntu plucky/universe ppc64el python3-mock all 5.1.0-1 [64.1 kB] 126s Fetched 10.5 MB in 1s (7729 kB/s) 126s Selecting previously unselected package fonts-lato. 126s (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 ... 73768 files and directories currently installed.) 126s Preparing to unpack .../00-fonts-lato_2.015-1_all.deb ... 126s Unpacking fonts-lato (2.015-1) ... 126s Selecting previously unselected package fonts-font-awesome. 126s Preparing to unpack .../01-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 126s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 126s Selecting previously unselected package libjs-jquery. 126s Preparing to unpack .../02-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 126s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 126s Selecting previously unselected package libjs-underscore. 126s Preparing to unpack .../03-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 126s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 126s Selecting previously unselected package libjs-sphinxdoc. 126s Preparing to unpack .../04-libjs-sphinxdoc_7.4.7-4_all.deb ... 126s Unpacking libjs-sphinxdoc (7.4.7-4) ... 126s Selecting previously unselected package libxslt1.1:ppc64el. 126s Preparing to unpack .../05-libxslt1.1_1.1.39-0exp1ubuntu1_ppc64el.deb ... 126s Unpacking libxslt1.1:ppc64el (1.1.39-0exp1ubuntu1) ... 126s Selecting previously unselected package python3-all. 126s Preparing to unpack .../06-python3-all_3.12.6-0ubuntu1_ppc64el.deb ... 126s Unpacking python3-all (3.12.6-0ubuntu1) ... 126s Selecting previously unselected package python3-asgiref. 126s Preparing to unpack .../07-python3-asgiref_3.8.1-1_all.deb ... 126s Unpacking python3-asgiref (3.8.1-1) ... 126s Selecting previously unselected package python3-sqlparse. 126s Preparing to unpack .../08-python3-sqlparse_0.5.1-2_all.deb ... 126s Unpacking python3-sqlparse (0.5.1-2) ... 126s Selecting previously unselected package python3-django. 126s Preparing to unpack .../09-python3-django_3%3a4.2.15-1ubuntu1_all.deb ... 126s Unpacking python3-django (3:4.2.15-1ubuntu1) ... 127s Selecting previously unselected package python3-lxml:ppc64el. 127s Preparing to unpack .../10-python3-lxml_5.3.0-1build1_ppc64el.deb ... 127s Unpacking python3-lxml:ppc64el (5.3.0-1build1) ... 127s Selecting previously unselected package python3-requests-futures. 127s Preparing to unpack .../11-python3-requests-futures_1.0.1-1_all.deb ... 127s Unpacking python3-requests-futures (1.0.1-1) ... 127s Selecting previously unselected package python3-django-cas-server. 127s Preparing to unpack .../12-python3-django-cas-server_2.1.0-1_all.deb ... 127s Unpacking python3-django-cas-server (2.1.0-1) ... 127s Selecting previously unselected package sphinx-rtd-theme-common. 127s Preparing to unpack .../13-sphinx-rtd-theme-common_3.0.1+dfsg-1_all.deb ... 127s Unpacking sphinx-rtd-theme-common (3.0.1+dfsg-1) ... 127s Selecting previously unselected package python3-django-cas-server-doc. 127s Preparing to unpack .../14-python3-django-cas-server-doc_2.1.0-1_all.deb ... 127s Unpacking python3-django-cas-server-doc (2.1.0-1) ... 127s Selecting previously unselected package python3-iniconfig. 127s Preparing to unpack .../15-python3-iniconfig_1.1.1-2_all.deb ... 127s Unpacking python3-iniconfig (1.1.1-2) ... 127s Selecting previously unselected package python3-packaging. 127s Preparing to unpack .../16-python3-packaging_24.1-1_all.deb ... 127s Unpacking python3-packaging (24.1-1) ... 127s Selecting previously unselected package python3-pluggy. 127s Preparing to unpack .../17-python3-pluggy_1.5.0-1_all.deb ... 127s Unpacking python3-pluggy (1.5.0-1) ... 127s Selecting previously unselected package python3-pytest. 127s Preparing to unpack .../18-python3-pytest_8.3.3-1_all.deb ... 127s Unpacking python3-pytest (8.3.3-1) ... 128s Selecting previously unselected package python3-pytest-django. 128s Preparing to unpack .../19-python3-pytest-django_4.5.2-3_all.deb ... 128s Unpacking python3-pytest-django (4.5.2-3) ... 128s Selecting previously unselected package python3-mock. 128s Preparing to unpack .../20-python3-mock_5.1.0-1_all.deb ... 128s Unpacking python3-mock (5.1.0-1) ... 128s Selecting previously unselected package autopkgtest-satdep. 128s Preparing to unpack .../21-1-autopkgtest-satdep.deb ... 128s Unpacking autopkgtest-satdep (0) ... 128s Setting up python3-iniconfig (1.1.1-2) ... 128s Setting up fonts-lato (2.015-1) ... 128s Setting up python3-all (3.12.6-0ubuntu1) ... 128s Setting up python3-mock (5.1.0-1) ... 128s Setting up python3-packaging (24.1-1) ... 128s Setting up python3-sqlparse (0.5.1-2) ... 128s Setting up python3-pluggy (1.5.0-1) ... 129s Setting up libxslt1.1:ppc64el (1.1.39-0exp1ubuntu1) ... 129s Setting up python3-requests-futures (1.0.1-1) ... 129s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 129s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 129s Setting up sphinx-rtd-theme-common (3.0.1+dfsg-1) ... 129s Setting up python3-asgiref (3.8.1-1) ... 129s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 129s Setting up python3-django (3:4.2.15-1ubuntu1) ... 131s Setting up python3-pytest (8.3.3-1) ... 131s Setting up python3-pytest-django (4.5.2-3) ... 131s Setting up libjs-sphinxdoc (7.4.7-4) ... 131s Setting up python3-lxml:ppc64el (5.3.0-1build1) ... 131s Setting up python3-django-cas-server (2.1.0-1) ... 132s Setting up python3-django-cas-server-doc (2.1.0-1) ... 132s Setting up autopkgtest-satdep (0) ... 132s Processing triggers for man-db (2.12.1-3) ... 132s Processing triggers for libc-bin (2.40-1ubuntu3) ... 135s (Reading database ... 80584 files and directories currently installed.) 135s Removing autopkgtest-satdep (0) ... 136s autopkgtest [14:10:32]: test run-test-suite.sh: [----------------------- 136s 136s *************************** 136s *** Testing with python3.12 136s *************************** 136s 136s Content of current working folder: 136s 136s total 20 136s drwxr-xr-x 3 ubuntu ubuntu 4096 Nov 14 14:10 . 136s drwxrwxrwt 5 root root 4096 Nov 14 14:10 .. 136s drwxrwxr-x 9 ubuntu ubuntu 4096 Nov 14 14:10 cas_server 136s -rw-rw-r-- 1 ubuntu ubuntu 140 Aug 18 11:21 pytest.ini 136s -rw-rw-r-- 1 ubuntu ubuntu 152 Aug 18 11:21 setup.cfg 136s Running tests... 136s 136s ============================= test session starts ============================== 136s platform linux -- Python 3.12.7, pytest-8.3.3, pluggy-1.5.0 -- /usr/bin/python3.12 136s cachedir: .pytest_cache 136s django: settings: cas_server.tests.settings (from ini) 136s rootdir: /tmp/autopkgtest.2b8tcs/autopkgtest_tmp 136s configfile: pytest.ini 136s testpaths: cas_server/tests/ 136s plugins: django-4.5.2, typeguard-4.4.1 138s collecting ... collected 128 items 138s 138s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_errors Creating test database for alias 'default'... 138s 2024-11-14 14:10:33,844 WARNING Identity provider suffix exemple.fr not found 138s 2024-11-14 14:10:33,867 INFO Got an invalid ticket ST-HJRaVL08HLXDiPdvfLvKueWT9HJBm3jicxHs6MsqSU2LYKbgnjxVSC8dGo0Jx from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 138s 2024-11-14 14:10:33,871 INFO Got an invalid ticket ST-jT4RcHapZNpNWffnn5PCHyrAyhRLhhZYXZjgKbIBZhTa0yXt8Bc6CkI4XHWYe from http://127.0.0.1:8080 for service http://testserver/federate/example.com. Retrying authentication 138s 2024-11-14 14:10:33,912 WARNING CAS_FEDERATE is False, set it to True to use federation 138s 2024-11-14 14:10:33,912 WARNING CAS_FEDERATE is False, set it to True to use federation 138s 2024-11-14 14:10:33,932 INFO Trying to authenticate http://127.0.0.1:8080 again 138s 2024-11-14 14:10:33,939 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 138s 2024-11-14 14:10:33,958 INFO User dédé@example.com successfully authenticated 138s 2024-11-14 14:10:33,972 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 138s 2024-11-14 14:10:33,986 INFO Trying to authenticate http://127.0.0.1:8081 again 138s 2024-11-14 14:10:33,992 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 138s 2024-11-14 14:10:34,005 INFO User dédé@example.org successfully authenticated 138s 2024-11-14 14:10:34,016 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 138s 2024-11-14 14:10:34,031 INFO Trying to authenticate http://127.0.0.1:8082 again 138s 2024-11-14 14:10:34,037 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 138s 2024-11-14 14:10:34,050 INFO User dédé@example.net successfully authenticated 138s 2024-11-14 14:10:34,066 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 138s 2024-11-14 14:10:34,081 INFO Trying to authenticate http://127.0.0.1:8083 again 138s 2024-11-14 14:10:34,090 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 138s 2024-11-14 14:10:34,105 INFO User dédé@example.test successfully authenticated 138s 2024-11-14 14:10:34,117 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 138s 2024-11-14 14:10:34,166 INFO Got an SLO requests for ticket dédé@example.net, logging out user ST-QUzp4X82ogUfdmVxZN97cD6SzryvmSPAav7jGFoG6aB2XbcijjxkDfTJezVqb 138s 2024-11-14 14:10:34,210 INFO Got an SLO requests for ticket dédé@example.test, logging out user ST-3nnMYLdDq8gfH6bGAB6FAK5LTsmclVTNQA25JXhG6VA4ZDApfJyfWwJL5nuhJ 138s 2024-11-14 14:10:34,255 INFO Trying to authenticate http://127.0.0.1:8080 again 138s 2024-11-14 14:10:34,260 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 138s 2024-11-14 14:10:34,274 INFO User dédé@example.com successfully authenticated 138s 2024-11-14 14:10:34,286 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 138s 2024-11-14 14:10:34,300 INFO Trying to authenticate http://127.0.0.1:8081 again 138s 2024-11-14 14:10:34,305 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 138s 2024-11-14 14:10:34,319 INFO User dédé@example.org successfully authenticated 138s 2024-11-14 14:10:34,331 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 138s 2024-11-14 14:10:34,346 INFO Trying to authenticate http://127.0.0.1:8082 again 138s 2024-11-14 14:10:34,351 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 138s 2024-11-14 14:10:34,364 INFO User dédé@example.net successfully authenticated 138s 2024-11-14 14:10:34,375 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 138s 2024-11-14 14:10:34,388 INFO Trying to authenticate http://127.0.0.1:8083 again 138s 2024-11-14 14:10:34,393 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 138s 2024-11-14 14:10:34,406 INFO User dédé@example.test successfully authenticated 138s 2024-11-14 14:10:34,417 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 138s 2024-11-14 14:10:34,418 INFO logout requested 138s 2024-11-14 14:10:34,420 INFO Logging out user dédé@example.com. 138s 2024-11-14 14:10:34,425 INFO User dédé@example.com logged out 138s 2024-11-14 14:10:34,436 INFO logout requested 138s 2024-11-14 14:10:34,440 INFO logout requested 138s 2024-11-14 14:10:34,442 INFO Logging out user dédé@example.org. 138s 2024-11-14 14:10:34,447 INFO User dédé@example.org logged out 138s 2024-11-14 14:10:34,458 INFO logout requested 138s 2024-11-14 14:10:34,461 INFO logout requested 138s 2024-11-14 14:10:34,463 INFO Logging out user dédé@example.net. 138s 2024-11-14 14:10:34,468 INFO User dédé@example.net logged out 138s 2024-11-14 14:10:34,480 INFO logout requested 138s 2024-11-14 14:10:34,483 INFO logout requested 138s 2024-11-14 14:10:34,485 INFO Logging out user dédé@example.test. 138s 2024-11-14 14:10:34,490 INFO User dédé@example.test logged out 138s 2024-11-14 14:10:34,502 INFO logout requested 138s 2024-11-14 14:10:34,517 INFO Trying to authenticate http://127.0.0.1:8080 again 138s 2024-11-14 14:10:34,522 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 138s 2024-11-14 14:10:34,535 INFO User dédé@example.com successfully authenticated 138s 2024-11-14 14:10:34,546 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 138s 2024-11-14 14:10:34,560 INFO Trying to authenticate http://127.0.0.1:8081 again 138s PASSED 138s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_false PASSED 138s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_auth_federate_slo PASSED 138s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_default_settings PASSED 138s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_federate_logout 2024-11-14 14:10:34,566 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 138s 2024-11-14 14:10:34,579 INFO User dédé@example.org successfully authenticated 138s 2024-11-14 14:10:34,590 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 138s 2024-11-14 14:10:34,604 INFO Trying to authenticate http://127.0.0.1:8082 again 138s 2024-11-14 14:10:34,610 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 138s 2024-11-14 14:10:34,622 INFO User dédé@example.net successfully authenticated 138s 2024-11-14 14:10:34,633 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 138s 2024-11-14 14:10:34,646 INFO Trying to authenticate http://127.0.0.1:8083 again 138s 2024-11-14 14:10:34,653 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 138s 2024-11-14 14:10:34,666 INFO User dédé@example.test successfully authenticated 138s 2024-11-14 14:10:34,677 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 138s 2024-11-14 14:10:34,680 INFO logout requested 138s 2024-11-14 14:10:34,681 INFO Logging out user dédé. 138s 2024-11-14 14:10:34,682 INFO User dédé logged out 138s 2024-11-14 14:10:34,694 INFO logout requested 138s 2024-11-14 14:10:34,695 INFO Logging out user dédé. 138s 2024-11-14 14:10:34,696 INFO User dédé logged out 138s 2024-11-14 14:10:34,710 INFO logout requested 138s 2024-11-14 14:10:34,711 INFO Logging out user dédé. 138s 2024-11-14 14:10:34,712 INFO User dédé logged out 138s 2024-11-14 14:10:34,725 INFO logout requested 138s 2024-11-14 14:10:34,726 INFO Logging out user dédé. 138s 2024-11-14 14:10:34,727 INFO User dédé logged out 138s PASSED 138s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_forget_provider 2024-11-14 14:10:34,756 INFO Trying to authenticate http://127.0.0.1:8080 again 138s 2024-11-14 14:10:34,767 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 138s 2024-11-14 14:10:34,779 INFO User dédé@example.com successfully authenticated 138s 2024-11-14 14:10:34,791 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 138s 2024-11-14 14:10:34,805 INFO Trying to authenticate http://127.0.0.1:8081 again 138s 2024-11-14 14:10:34,810 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 138s 2024-11-14 14:10:34,823 INFO User dédé@example.org successfully authenticated 138s 2024-11-14 14:10:34,834 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 138s 2024-11-14 14:10:34,849 INFO Trying to authenticate http://127.0.0.1:8082 again 138s 2024-11-14 14:10:34,854 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 138s 2024-11-14 14:10:34,866 INFO User dédé@example.net successfully authenticated 138s 2024-11-14 14:10:34,877 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 138s 2024-11-14 14:10:34,890 INFO Trying to authenticate http://127.0.0.1:8083 again 138s 2024-11-14 14:10:34,896 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 138s 2024-11-14 14:10:34,908 INFO User dédé@example.test successfully authenticated 138s 2024-11-14 14:10:34,919 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 138s 2024-11-14 14:10:34,921 INFO logout requested 138s 2024-11-14 14:10:34,923 INFO Logging out user dédé@example.com. 138s 2024-11-14 14:10:34,929 INFO User dédé@example.com logged out 138s 2024-11-14 14:10:34,930 INFO logout requested 138s 2024-11-14 14:10:34,932 INFO Logging out user dédé@example.org. 138s 2024-11-14 14:10:34,937 INFO User dédé@example.org logged out 138s 2024-11-14 14:10:34,938 INFO logout requested 138s 2024-11-14 14:10:34,940 INFO Logging out user dédé@example.net. 138s 2024-11-14 14:10:34,945 INFO User dédé@example.net logged out 138s 2024-11-14 14:10:34,946 INFO logout requested 138s 2024-11-14 14:10:34,948 INFO Logging out user dédé@example.test. 138s 2024-11-14 14:10:34,953 INFO User dédé@example.test logged out 138s PASSED 139s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_bad_ticket 2024-11-14 14:10:34,971 WARNING A login attempt failed 139s PASSED 139s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_get_provider PASSED 139s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 2024-11-14 14:10:35,014 INFO Trying to authenticate http://127.0.0.1:8080 again 139s 2024-11-14 14:10:35,019 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 139s 2024-11-14 14:10:35,032 INFO User dédé@example.com successfully authenticated 139s 2024-11-14 14:10:35,042 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 139s 2024-11-14 14:10:35,056 INFO Trying to authenticate http://127.0.0.1:8081 again 139s 2024-11-14 14:10:35,062 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 139s 2024-11-14 14:10:35,076 INFO User dédé@example.org successfully authenticated 139s 2024-11-14 14:10:35,087 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 139s 2024-11-14 14:10:35,101 INFO Trying to authenticate http://127.0.0.1:8082 again 139s 2024-11-14 14:10:35,114 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 139s 2024-11-14 14:10:35,126 INFO User dédé@example.net successfully authenticated 139s 2024-11-14 14:10:35,137 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 139s 2024-11-14 14:10:35,151 INFO Trying to authenticate http://127.0.0.1:8083 again 139s 2024-11-14 14:10:35,158 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 139s 2024-11-14 14:10:35,171 INFO User dédé@example.test successfully authenticated 139s 2024-11-14 14:10:35,182 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 139s PASSED 139s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_twice 2024-11-14 14:10:35,205 INFO Trying to authenticate http://127.0.0.1:8080 again 139s 2024-11-14 14:10:35,211 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 139s 2024-11-14 14:10:35,223 INFO User dédé@example.com successfully authenticated 139s 2024-11-14 14:10:35,235 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 139s 2024-11-14 14:10:35,248 INFO Trying to authenticate http://127.0.0.1:8081 again 139s 2024-11-14 14:10:35,253 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 139s 2024-11-14 14:10:35,265 INFO User dédé@example.org successfully authenticated 139s 2024-11-14 14:10:35,276 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 139s 2024-11-14 14:10:35,291 INFO Trying to authenticate http://127.0.0.1:8082 again 139s 2024-11-14 14:10:35,297 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 139s 2024-11-14 14:10:35,310 INFO User dédé@example.net successfully authenticated 139s 2024-11-14 14:10:35,323 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 139s 2024-11-14 14:10:35,337 INFO Trying to authenticate http://127.0.0.1:8083 again 139s 2024-11-14 14:10:35,344 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 139s 2024-11-14 14:10:35,357 INFO User dédé@example.test successfully authenticated 139s 2024-11-14 14:10:35,370 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 139s 2024-11-14 14:10:35,385 INFO Trying to authenticate http://127.0.0.1:8080 again 139s 2024-11-14 14:10:35,392 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 139s 2024-11-14 14:10:35,405 INFO User dédé@example.com successfully authenticated 139s 2024-11-14 14:10:35,418 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 139s 2024-11-14 14:10:35,434 INFO Trying to authenticate http://127.0.0.1:8081 again 139s 2024-11-14 14:10:35,440 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 139s 2024-11-14 14:10:35,454 INFO User dédé@example.org successfully authenticated 139s 2024-11-14 14:10:35,467 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 139s 2024-11-14 14:10:35,485 INFO Trying to authenticate http://127.0.0.1:8082 again 139s 2024-11-14 14:10:35,493 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 139s 2024-11-14 14:10:35,510 INFO User dédé@example.net successfully authenticated 139s 2024-11-14 14:10:35,558 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 139s 2024-11-14 14:10:35,574 INFO Trying to authenticate http://127.0.0.1:8083 again 139s 2024-11-14 14:10:35,580 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 139s 2024-11-14 14:10:35,594 INFO User dédé@example.test successfully authenticated 139s 2024-11-14 14:10:35,606 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 139s 2024-11-14 14:10:35,608 WARNING User already authenticated, dropping federated authentication request 139s 2024-11-14 14:10:35,609 WARNING User already authenticated, dropping federated authentication request 139s 2024-11-14 14:10:35,611 WARNING User already authenticated, dropping federated authentication request 139s 2024-11-14 14:10:35,612 WARNING User already authenticated, dropping federated authentication request 139s PASSED 139s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_remember_provider 2024-11-14 14:10:35,634 INFO Trying to authenticate http://127.0.0.1:8080 again 139s 2024-11-14 14:10:35,651 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 139s 2024-11-14 14:10:35,666 INFO User dédé@example.com successfully authenticated 139s 2024-11-14 14:10:35,680 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 139s 2024-11-14 14:10:35,699 INFO Trying to authenticate http://127.0.0.1:8081 again 139s 2024-11-14 14:10:35,706 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 139s 2024-11-14 14:10:35,720 INFO User dédé@example.org successfully authenticated 139s 2024-11-14 14:10:35,733 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 139s 2024-11-14 14:10:35,753 INFO Trying to authenticate http://127.0.0.1:8082 again 139s 2024-11-14 14:10:35,758 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 139s 2024-11-14 14:10:35,783 INFO User dédé@example.net successfully authenticated 139s 2024-11-14 14:10:35,798 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 139s 2024-11-14 14:10:35,814 INFO Trying to authenticate http://127.0.0.1:8083 again 139s 2024-11-14 14:10:35,823 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 139s 2024-11-14 14:10:35,836 INFO User dédé@example.test successfully authenticated 139s 2024-11-14 14:10:35,847 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 139s 2024-11-14 14:10:35,848 INFO logout requested 139s 2024-11-14 14:10:35,850 INFO Logging out user dédé@example.com. 139s 2024-11-14 14:10:35,856 INFO User dédé@example.com logged out 139s 2024-11-14 14:10:35,860 INFO logout requested 139s 2024-11-14 14:10:35,862 INFO Logging out user dédé@example.org. 139s 2024-11-14 14:10:35,867 INFO User dédé@example.org logged out 139s 2024-11-14 14:10:35,871 INFO logout requested 139s 2024-11-14 14:10:35,873 INFO Logging out user dédé@example.net. 139s 2024-11-14 14:10:35,878 INFO User dédé@example.net logged out 139s 2024-11-14 14:10:35,882 INFO logout requested 139s 2024-11-14 14:10:35,884 INFO Logging out user dédé@example.test. 139s 2024-11-14 14:10:35,889 INFO User dédé@example.test logged out 139s PASSED 139s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_renew 2024-11-14 14:10:35,911 INFO Trying to authenticate http://127.0.0.1:8080 again 139s 2024-11-14 14:10:35,916 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 139s 2024-11-14 14:10:35,930 INFO User dédé@example.com successfully authenticated 139s 2024-11-14 14:10:35,941 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 139s 2024-11-14 14:10:35,955 INFO Trying to authenticate http://127.0.0.1:8081 again 140s 2024-11-14 14:10:35,966 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 140s 2024-11-14 14:10:35,986 INFO User dédé@example.org successfully authenticated 140s 2024-11-14 14:10:35,999 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 140s 2024-11-14 14:10:36,014 INFO Trying to authenticate http://127.0.0.1:8082 again 140s 2024-11-14 14:10:36,025 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 140s 2024-11-14 14:10:36,046 INFO User dédé@example.net successfully authenticated 140s 2024-11-14 14:10:36,058 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 140s 2024-11-14 14:10:36,072 INFO Trying to authenticate http://127.0.0.1:8083 again 140s 2024-11-14 14:10:36,077 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 140s 2024-11-14 14:10:36,090 INFO User dédé@example.test successfully authenticated 140s 2024-11-14 14:10:36,102 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 140s 2024-11-14 14:10:36,107 INFO Trying to authenticate http://127.0.0.1:8080 again 140s 2024-11-14 14:10:36,115 INFO Got a valid ticket for dédé from http://127.0.0.1:8080 140s 2024-11-14 14:10:36,129 INFO User dédé@example.com successfully authenticated 140s 2024-11-14 14:10:36,136 INFO Service ticket created for service https://www.example.com by user dédé@example.com. 140s 2024-11-14 14:10:36,143 INFO Trying to authenticate http://127.0.0.1:8081 again 140s 2024-11-14 14:10:36,153 INFO Got a valid ticket for dédé from http://127.0.0.1:8081 140s 2024-11-14 14:10:36,170 INFO User dédé@example.org successfully authenticated 140s 2024-11-14 14:10:36,177 INFO Service ticket created for service https://www.example.com by user dédé@example.org. 140s 2024-11-14 14:10:36,185 INFO Trying to authenticate http://127.0.0.1:8082 again 140s 2024-11-14 14:10:36,191 INFO Got a valid ticket for dédé from http://127.0.0.1:8082 140s 2024-11-14 14:10:36,206 INFO User dédé@example.net successfully authenticated 140s 2024-11-14 14:10:36,211 INFO Service ticket created for service https://www.example.com by user dédé@example.net. 140s 2024-11-14 14:10:36,218 INFO Trying to authenticate http://127.0.0.1:8083 again 140s 2024-11-14 14:10:36,227 INFO Got a valid ticket for dédé from http://127.0.0.1:8083 140s 2024-11-14 14:10:36,254 INFO User dédé@example.test successfully authenticated 140s 2024-11-14 14:10:36,259 INFO Service ticket created for service https://www.example.com by user dédé@example.test. 140s PASSED 140s cas_server/tests/test_models.py::FederatedUserTestCase::test_clean_old_entries PASSED 140s cas_server/tests/test_models.py::FederatedUserTestCase::test_json_attributes PASSED 140s cas_server/tests/test_models.py::FederateSLOTestCase::test_clean_deleted_sessions PASSED 140s cas_server/tests/test_models.py::UserAttributesTestCase::test_clean_old_entries 2024-11-14 14:10:36,317 INFO User test successfully authenticated 140s PASSED 140s cas_server/tests/test_models.py::UserTestCase::test_cached_attributs 2024-11-14 14:10:36,339 INFO User test successfully authenticated 140s PASSED 140s cas_server/tests/test_models.py::UserTestCase::test_clean_deleted_sessions 2024-11-14 14:10:36,356 INFO User test successfully authenticated 140s 2024-11-14 14:10:36,369 INFO User test successfully authenticated 140s 2024-11-14 14:10:36,381 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 140s 2024-11-14 14:10:36,385 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')) 140s PASSED 140s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries 2024-11-14 14:10:36,401 INFO User test successfully authenticated 140s PASSED 140s cas_server/tests/test_models.py::UserTestCase::test_clean_old_entries_tgt_expired 2024-11-14 14:10:36,422 INFO User test successfully authenticated 140s PASSED 140s cas_server/tests/test_models.py::TicketTestCase::test_clean_old_service_ticket 2024-11-14 14:10:36,448 INFO User test successfully authenticated 140s 2024-11-14 14:10:36,461 INFO Sending SLO requests to service http://127.0.0.1:38297 for user test 140s 2024-11-14 14:10:36,469 WARNING Error durring SLO ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 140s ConnectionError(ProtocolError('Connection aborted.', RemoteDisconnected('Remote end closed connection without response'))) 140s PASSED 140s cas_server/tests/test_models.py::TicketTestCase::test_json_attributes 2024-11-14 14:10:36,492 INFO User test successfully authenticated 140s PASSED 140s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails PASSED 140s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_disabled PASSED 140s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_no_admins PASSED 140s cas_server/tests/test_models.py::NewVersionWarningTestCase::test_send_mails_same_version PASSED 140s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_checkbox PASSED 140s cas_server/tests/test_templatetags.py::TemplateTagsTestCase::test_is_hidden PASSED 140s cas_server/tests/test_utils.py::CheckPasswordCase::test_bad_method PASSED 140s cas_server/tests/test_utils.py::CheckPasswordCase::test_crypt PASSED 140s cas_server/tests/test_utils.py::CheckPasswordCase::test_hex PASSED 140s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_fail PASSED 140s cas_server/tests/test_utils.py::CheckPasswordCase::test_ldap_password_valid PASSED 140s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain PASSED 140s cas_server/tests/test_utils.py::CheckPasswordCase::test_plain_unicode PASSED 140s cas_server/tests/test_utils.py::CheckPasswordCase::test_setup PASSED 140s cas_server/tests/test_utils.py::UtilsTestCase::test_crypt_salt_is_valid PASSED 140s cas_server/tests/test_utils.py::UtilsTestCase::test_dictfetchall PASSED 140s cas_server/tests/test_utils.py::UtilsTestCase::test_get_current_url PASSED 140s cas_server/tests/test_utils.py::UtilsTestCase::test_get_tuple PASSED 140s cas_server/tests/test_utils.py::UtilsTestCase::test_import_attr PASSED 141s cas_server/tests/test_utils.py::UtilsTestCase::test_last_version PASSED 141s cas_server/tests/test_utils.py::UtilsTestCase::test_regexpr_validator PASSED 141s cas_server/tests/test_utils.py::UtilsTestCase::test_update_url PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_fail 2024-11-14 14:10:37,425 INFO User test successfully authenticated 141s 2024-11-14 14:10:37,433 WARNING Service https://www.example.org not allowed. 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_success_alt 2024-11-14 14:10:37,452 INFO User test successfully authenticated 141s 2024-11-14 14:10:37,460 INFO Service ticket created for service https://www.example.com by user test. 141s 2024-11-14 14:10:37,477 INFO User test successfully authenticated 141s 2024-11-14 14:10:37,486 INFO Service ticket created for service https://www.example.com by user test. 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_ajax_get_ticket_warn 2024-11-14 14:10:37,503 INFO User test successfully authenticated 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged 2024-11-14 14:10:37,527 INFO User test successfully authenticated 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_ajax_logged_user_deleted 2024-11-14 14:10:37,550 INFO User test successfully authenticated 141s 2024-11-14 14:10:37,558 WARNING User test seems authenticated but is not found in the database. 141s 2024-11-14 14:10:37,559 INFO Logging out user test. 141s 2024-11-14 14:10:37,560 INFO User test logged out 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_ajax_login_required PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_gateway 2024-11-14 14:10:37,583 INFO User test successfully authenticated 141s 2024-11-14 14:10:37,591 WARNING Username test not allowed on service restrict_user_fail 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_login_post_missing_params 2024-11-14 14:10:37,611 WARNING A login attempt failed 141s 2024-11-14 14:10:37,631 WARNING A login attempt failed 141s 2024-11-14 14:10:37,650 WARNING A login attempt failed 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badlt 2024-11-14 14:10:37,675 WARNING Received an invalid login ticket 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_badpass_good_lt 2024-11-14 14:10:37,699 WARNING A login attempt failed 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt 2024-11-14 14:10:37,724 INFO User test successfully authenticated 141s PASSED 141s cas_server/tests/test_view.py::LoginTestCase::test_login_view_post_goodpass_goodlt_warn 2024-11-14 14:10:37,746 INFO User test successfully authenticated 141s PASSED 142s cas_server/tests/test_view.py::LoginTestCase::test_lt_max PASSED 142s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_bad_messages PASSED 142s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_disabled PASSED 142s cas_server/tests/test_view.py::LoginTestCase::test_messages_info_box_enabled PASSED 142s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_badpypi PASSED 142s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_disabled PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_new_version_available_ok PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_renew 2024-11-14 14:10:38,988 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,003 INFO User test successfully authenticated 143s PASSED2024-11-14 14:10:39,008 INFO Service ticket created for service https://www.example.com by user test. 143s 143s cas_server/tests/test_view.py::LoginTestCase::test_renew_message_disabled 2024-11-14 14:10:39,025 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,040 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,044 INFO Service ticket created for service https://www.example.com by user test. 143s PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_service_filter 2024-11-14 14:10:39,061 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,068 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 143s 2024-11-14 14:10:39,072 WARNING User constraint failed for test, service filter_fail_alt: ^toto$ do not match nom Nymous. 143s 2024-11-14 14:10:39,079 INFO Service ticket created for service https://filter_success.example.com by user test. 143s PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_service_restrict_user 2024-11-14 14:10:39,098 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,105 WARNING Username test not allowed on service restrict_user_fail 143s 2024-11-14 14:10:39,112 INFO Service ticket created for service https://restrict_user_success.example.com by user test. 143s PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field 2024-11-14 14:10:39,128 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,134 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 143s 2024-11-14 14:10:39,141 INFO Service ticket created for service https://field_needed_success.example.com by user test. 143s PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_service_user_field_evaluate_to_false 2024-11-14 14:10:39,157 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,163 WARNING Cannot use alias a loggin for user test on service field_needed_success because it is absent 143s PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_user_logged_not_in_db 2024-11-14 14:10:39,180 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,187 WARNING User test seems authenticated but is not found in the database. 143s 2024-11-14 14:10:39,187 INFO Logging out user test. 143s 2024-11-14 14:10:39,189 INFO User test logged out 143s PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_allowed_service_no_message PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service 2024-11-14 14:10:39,231 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,240 INFO Service ticket created for service https://www.example.com/é by user test. 143s PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_non_ascii 2024-11-14 14:10:39,258 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,267 INFO Service ticket created for service https://www.example.com by user test. 143s PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_allowed_service_warn 2024-11-14 14:10:39,285 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,298 WARNING Received a login attempt for an already-active user 143s 2024-11-14 14:10:39,302 INFO Service ticket created for service https://www.example.com by user test. 143s PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_auth_denied_service 2024-11-14 14:10:39,319 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,326 WARNING Service https://www.example.org not allowed. 143s PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service 2024-11-14 14:10:39,334 WARNING Service https://www.example.net not allowed. 143s PASSED 143s cas_server/tests/test_view.py::LoginTestCase::test_view_login_get_denied_service_no_message 2024-11-14 14:10:39,347 WARNING Service https://www.example.net not allowed. 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout 2024-11-14 14:10:39,367 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,371 INFO logout requested 143s 2024-11-14 14:10:39,372 INFO Logging out user test. 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_logout_all_session 2024-11-14 14:10:39,376 INFO User test logged out 143s 2024-11-14 14:10:39,389 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,402 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,415 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,428 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,441 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,454 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,467 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,480 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,484 INFO logout requested 143s 2024-11-14 14:10:39,485 INFO Logging out user test from all sessions. 143s 2024-11-14 14:10:39,518 INFO User test logged out 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_ajax_redirect_after_logout 2024-11-14 14:10:39,532 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,536 INFO logout requested 143s 2024-11-14 14:10:39,537 INFO Logging out user test. 143s 2024-11-14 14:10:39,542 INFO User test logged out 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_logout 2024-11-14 14:10:39,545 INFO logout requested 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_logout_from_all_session 2024-11-14 14:10:39,559 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,573 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,577 INFO logout requested 143s 2024-11-14 14:10:39,577 INFO Logging out user test from all sessions. 143s 2024-11-14 14:10:39,587 INFO User test logged out 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_logout_slo 2024-11-14 14:10:39,602 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,610 INFO Service ticket created for service http://127.0.0.1:35377 by user test. 143s 2024-11-14 14:10:39,614 INFO Validate: Service ticket ST-qpzvFGQTNScy7OCbtvBQmQbAXk5dip14xAcWFex7gexRejJT93fKIdIxOeDW5 validated, user test authenticated on service http://127.0.0.1:35377 143s 2024-11-14 14:10:39,616 INFO logout requested 143s 2024-11-14 14:10:39,617 INFO Logging out user test. 143s 2024-11-14 14:10:39,620 INFO Sending SLO requests to service http://127.0.0.1:35377 for user test 143s 2024-11-14 14:10:39,624 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 143s 2024-11-14 14:10:39,626 INFO User test logged out 143s 2024-11-14 14:10:39,638 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,647 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 143s 2024-11-14 14:10:39,651 INFO Validate: Service ticket ST-Su8k3mDFrqBYAGODuJTHJDNUSiy45veo2tuSbzBMyuJQvS1XQQEVRpK8bDwxH validated, user test authenticated on service http://127.0.0.1:45678 143s 2024-11-14 14:10:39,652 INFO logout requested 143s 2024-11-14 14:10:39,653 INFO Logging out user test. 143s 2024-11-14 14:10:39,657 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 143s 2024-11-14 14:10:39,661 WARNING Error during SLO for user test: ('Connection aborted.', RemoteDisconnected('Remote end closed connection without response')) 143s 2024-11-14 14:10:39,663 INFO User test logged out 143s 2024-11-14 14:10:39,675 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,683 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 143s 2024-11-14 14:10:39,687 INFO Validate: Service ticket ST-qC2LLm1aySqpP4n8JYRCrWHDLQJmCPKTyoXJlj5xPJzEyfHiMOhf9JE3NzDyT validated, user test authenticated on service http://127.0.0.1:45678 143s 2024-11-14 14:10:39,688 INFO logout requested 143s 2024-11-14 14:10:39,689 INFO Logging out user test. 143s 2024-11-14 14:10:39,692 INFO Sending SLO requests to service http://127.0.0.1:45678 for user test 143s 2024-11-14 14:10:39,708 WARNING Error during SLO for user test: HTTPConnectionPool(host='127.0.0.1', port=43947): Max retries exceeded with url: / (Caused by NewConnectionError(': Failed to establish a new connection: [Errno 111] Connection refused')) 143s 2024-11-14 14:10:39,710 INFO User test logged out 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view 2024-11-14 14:10:39,730 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,745 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,754 INFO logout requested 143s 2024-11-14 14:10:39,755 INFO Logging out user test. 143s 2024-11-14 14:10:39,760 INFO User test logged out 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_service 2024-11-14 14:10:39,784 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,788 INFO logout requested 143s 2024-11-14 14:10:39,789 INFO Logging out user test. 143s 2024-11-14 14:10:39,794 INFO User test logged out 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_logout_view_url 2024-11-14 14:10:39,815 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,819 INFO logout requested 143s 2024-11-14 14:10:39,820 INFO Logging out user test. 143s 2024-11-14 14:10:39,825 INFO User test logged out 143s 2024-11-14 14:10:39,846 INFO User test successfully authenticated 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout 2024-11-14 14:10:39,851 INFO logout requested 143s 2024-11-14 14:10:39,852 INFO Logging out user test. 143s 2024-11-14 14:10:39,857 INFO User test logged out 143s PASSED 143s cas_server/tests/test_view.py::LogoutTestCase::test_redirect_after_logout_to_service 2024-11-14 14:10:39,871 INFO User test successfully authenticated 143s 2024-11-14 14:10:39,876 INFO logout requested 143s 2024-11-14 14:10:39,876 INFO Logging out user test. 143s 2024-11-14 14:10:39,881 INFO User test logged out 143s 2024-11-14 14:10:39,890 INFO logout requested 143s PASSED 143s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badpass PASSED 143s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsecret PASSED 143s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badservice 2024-11-14 14:10:39,914 WARNING Service https://www.example.org not allowed. 143s PASSED 143s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_badsettings PASSED 143s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass PASSED 143s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_goodpass_logged 2024-11-14 14:10:39,937 INFO User test successfully authenticated 143s PASSED 143s cas_server/tests/test_view.py::AuthTestCase::test_auth_view_missing_parameter PASSED 143s cas_server/tests/test_view.py::ValidateTestCase::test_validate_missing_parameter 2024-11-14 14:10:39,965 INFO User test successfully authenticated 144s 2024-11-14 14:10:39,974 INFO Service ticket created for service https://www.example.com by user test. 144s PASSED2024-11-14 14:10:39,977 WARNING Validate: service or ticket missing 144s 2024-11-14 14:10:39,978 WARNING Validate: service or ticket missing 144s 144s cas_server/tests/test_view.py::ValidateTestCase::test_validate_service_renew 2024-11-14 14:10:39,990 INFO User test successfully authenticated 144s 2024-11-14 14:10:39,995 INFO Service ticket created for service https://www.example.com by user test. 144s 2024-11-14 14:10:39,999 INFO Validate: Service ticket ST-R9Hf2OClesU7EiG73LfCxktZzNfZcdcKEqrC6Capr8lJB3Xaas7YNBkOt8fR4 validated, user test authenticated on service https://www.example.com 144s 2024-11-14 14:10:40,009 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,014 INFO Service ticket created for service https://www.example.com by user test. 144s 2024-11-14 14:10:40,018 INFO Validate: Service ticket ST-JXaH7J0vXSZvfxr28OeZdjrzKMC33rZX3Zm8h9yHOaefP1NzP3CfFfANnSEo9 validated, user test authenticated on service https://www.example.com 144s 2024-11-14 14:10:40,023 INFO Service ticket created for service https://www.example.com by user test. 144s 2024-11-14 14:10:40,026 WARNING Validate: Service ticket ST-1OA0EOLl9X0U7bxan7IQxLNv6BKpdPIFN9W9BhheahB2ngaBbmyPtCP6KbxbI not found or already validated, auth to https://www.example.com failed 144s PASSED 144s cas_server/tests/test_view.py::ValidateTestCase::test_validate_user_field_ok 2024-11-14 14:10:40,038 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,046 INFO Service ticket created for service https://user_field.example.com by user test. 144s 2024-11-14 14:10:40,050 INFO Validate: Service ticket ST-U0OAp8lR96t4LshXNMktyUpBx3u0Um1QEu5qJfQ6cBQinMwnBdkDhEcYBGFGT validated, user test authenticated on service https://user_field.example.com 144s 2024-11-14 14:10:40,061 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,070 INFO Service ticket created for service https://user_field_alt.example.com by user test. 144s 2024-11-14 14:10:40,074 INFO Validate: Service ticket ST-9JQCmqDhRUj6eLJptSF0nGLgofZPPPnfcd6zUt9mYqaciIbmk7mAjiICjpUIm validated, user test authenticated on service https://user_field_alt.example.com 144s PASSED 144s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badservice 2024-11-14 14:10:40,091 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,100 INFO Service ticket created for service https://www.example.com by user test. 144s 2024-11-14 14:10:40,103 WARNING Validate: Service ticket ST-cLHhJyralG2e5tPfqAh8leUeUlqDsaIVea3ZTgF8c4LhuugxJiAoRIdaKWYcj not found or already validated, auth to https://www.example.org failed 144s PASSED 144s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_badticket 2024-11-14 14:10:40,116 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,124 INFO Service ticket created for service https://www.example.com by user test. 144s 2024-11-14 14:10:40,127 WARNING Validate: Service ticket ST-RANDOM not found or already validated, auth to https://www.example.com failed 144s PASSED 144s cas_server/tests/test_view.py::ValidateTestCase::test_validate_view_ok 2024-11-14 14:10:40,140 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,148 INFO Service ticket created for service https://www.example.com by user test. 144s 2024-11-14 14:10:40,153 INFO Validate: Service ticket ST-GPLxJDotCKLPZ36Zsb867HthjP82wYBtvTKZHSicFEXYSyHJIHH6bLxb6cCll validated, user test authenticated on service https://www.example.com 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_missing_parameter 2024-11-14 14:10:40,168 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,176 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 14:10:40,179 WARNING ValidateService: missing ticket or service 144s PASSED2024-11-14 14:10:40,180 WARNING ValidateService: missing ticket or service 144s 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_proxy 2024-11-14 14:10:40,195 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,204 INFO Service ticket created for service http://127.0.0.1:43983 by user test. 144s 2024-11-14 14:10:40,212 INFO ValidateService: ticket ST-PkqTqxhc1ngvW1aNwL2ojbNjII8etdMJb299ITNqrvjsZoWbVkOvtujt7EAH2 validated for user test on service http://127.0.0.1:43983. Proxy Granting Ticket transmited to http://127.0.0.1:43983. 144s 2024-11-14 14:10:40,219 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 144s 2024-11-14 14:10:40,224 INFO ValidateService: ticket PT-9Y7hiVnDtB3lldXzqoY9p336JAOs2HjksYmRp6uV8IThBnXY9JjLka4JSQ8Sg validated for user test on service http://127.0.0.1:45678. 144s 2024-11-14 14:10:40,236 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,245 INFO Service ticket created for service http://127.0.0.1:43121 by user test. 144s 2024-11-14 14:10:40,252 INFO ValidateService: ticket ST-Kxxjm02oRpuD6XoSSHNZEgSk5xVDH64jnsfblJ4c9QVFxoqT9N2PVfBuIqQmc validated for user test on service http://127.0.0.1:43121. Proxy Granting Ticket transmited to http://127.0.0.1:43121. 144s 2024-11-14 14:10:40,258 INFO Proxy ticket created for user test on service http://127.0.0.1:45678. 144s 2024-11-14 14:10:40,260 WARNING ValidateService: validation error: INVALID_TICKET PT-SJkQiHBXFcw8YoEpsqVnJM93sf9Yc6BRrDJxxSkv3PJuZdgxd5S0lyKgMrEjn 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_replace_attributes 2024-11-14 14:10:40,274 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,283 INFO Service ticket created for service https://replace_attribute.example.com by user test. 144s 2024-11-14 14:10:40,287 INFO ValidateService: ticket ST-7QO9M8EE6iGWOQuywgsDdnwf0HBi9ivZe1mDUw5yViQpOHjwG9wciCQefpEyE validated for user test on service https://replace_attribute.example.com. 144s 2024-11-14 14:10:40,298 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,307 INFO Service ticket created for service https://replace_attribute_list.example.com by user test. 144s 2024-11-14 14:10:40,311 INFO ValidateService: ticket ST-NRPRaR0LwEsDmVktT5fh5T92BX25mfeIoE1CGNTo7umNyZLA6ZLrUfRHaxmXg validated for user test on service https://replace_attribute_list.example.com. 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_404 2024-11-14 14:10:40,327 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,336 INFO Service ticket created for service http://127.0.0.1:38505 by user test. 144s 2024-11-14 14:10:40,347 INFO ValidateService: ticket ST-dmfRSnurkJvSC5pui6TQlr6acPVf6XkThF11OEtwhM2qWMuqENv9PQWl0edRr validated for user test on service http://127.0.0.1:38505. Proxy Granting Ticket transmited to http://127.0.0.1:38505. 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_bad_proxy_callback 2024-11-14 14:10:40,372 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,382 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 14:10:40,392 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 144s 2024-11-14 14:10:40,421 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,428 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 14:10:40,433 WARNING Service https://www.example.org not allowed. 144s 2024-11-14 14:10:40,434 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK callback url not allowed by configuration 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_pgturl_sslerror 2024-11-14 14:10:40,449 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,457 INFO Service ticket created for service https://127.0.0.1:34919 by user test. 144s PASSED2024-11-14 14:10:40,466 WARNING ValidateService: validation error: INVALID_PROXY_CALLBACK : HTTPSConnectionPool(host='127.0.0.1', port=34919): Max retries exceeded with url: /?pgtId=PGT-8J09tulu14dbFQqVJGB9Rhe1P7SLWGsRhni3hh6l7JUvyoLP4Ah5k6Kl2Zos&pgtIou=PGTIOU-FOwKFmnlsogJSs57ofBCLTG3I5eTVKgN7ih4NiA2DHb6KCbWfAXjmRXji (Caused by SSLError(SSLError(1, '[SSL] record layer failure (_ssl.c:1000)'))) 144s 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_renew 2024-11-14 14:10:40,504 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,510 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 14:10:40,515 INFO ValidateService: ticket ST-RHY58EaKf3TWyKvsQa4Uyd0oL538kTRRyARunKkwfz0D881D44HfQjDD44CS2 validated for user test on service http://127.0.0.1:45678. 144s 2024-11-14 14:10:40,527 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,532 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 14:10:40,537 INFO ValidateService: ticket ST-vzDPkOisDmPmEDestLwgcwn1snnO7YXtpCzidHmAMfrt4PLYSj6LDZQynrNEY validated for user test on service http://127.0.0.1:45678. 144s 2024-11-14 14:10:40,543 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 14:10:40,546 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badservice 2024-11-14 14:10:40,562 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,572 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 14:10:40,576 WARNING ValidateService: validation error: INVALID_SERVICE https://www.example.org 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_badprefix 2024-11-14 14:10:40,591 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,600 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 14:10:40,603 WARNING ValidateService: validation error: INVALID_TICKET RANDOM 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_badticket_goodprefix 2024-11-14 14:10:40,618 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,626 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 14:10:40,630 WARNING ValidateService: validation error: INVALID_TICKET ticket not found 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok 2024-11-14 14:10:40,646 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,654 INFO Service ticket created for service http://127.0.0.1:45678 by user test. 144s 2024-11-14 14:10:40,697 INFO ValidateService: ticket ST-3ajGtND12eKkd9IaqJbwhXnXMOVsOhxba0myjRpvQoMYXrSphWE2TKPbQnQnw validated for user test on service http://127.0.0.1:45678. 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_one_attribute 2024-11-14 14:10:40,712 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,720 INFO Service ticket created for service https://one_attribute.example.com by user test. 144s 2024-11-14 14:10:40,725 INFO ValidateService: ticket ST-sGUP57ZvQKi3qOAgoZYtLMaktaDeCnbTFab8ni2fdcVa53M9IaL2uaRNEmWKf validated for user test on service https://one_attribute.example.com. 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_service_view_ok_pgturl 2024-11-14 14:10:40,741 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,749 INFO Service ticket created for service http://127.0.0.1:45215 by user test. 144s PASSED 144s cas_server/tests/test_view.py::ValidateServiceTestCase::test_validate_user_field_ok 2024-11-14 14:10:40,757 INFO ValidateService: ticket ST-WyGMAWmZ8vDboMdnWWSzx5QR5ZirFwDha0cu5Mc5Iq5gUaDC43zwGycfZiGd7 validated for user test on service http://127.0.0.1:45215. Proxy Granting Ticket transmited to http://127.0.0.1:45215. 144s 2024-11-14 14:10:40,772 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,781 INFO Service ticket created for service https://user_field.example.com by user test. 144s 2024-11-14 14:10:40,785 INFO ValidateService: ticket ST-8TysmINvSc3rvWr3QxKnnJWcnjOZgVTMD8KewINZC0joUMAxia2Elzi5w4nGG validated for user test on service https://user_field.example.com. 144s 2024-11-14 14:10:40,795 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,803 INFO Service ticket created for service https://user_field_alt.example.com by user test. 144s 2024-11-14 14:10:40,808 INFO ValidateService: ticket ST-0aZUHuoc8m2mUffKKg6uOjmVa2zLYDaM6UeQEKfcxjSqOdYqQ8Uv1oTqJEa7s validated for user test on service https://user_field_alt.example.com. 144s PASSED 144s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_missing_parameter 2024-11-14 14:10:40,827 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,835 INFO Service ticket created for service http://127.0.0.1:41519 by user test. 144s 2024-11-14 14:10:40,846 INFO ValidateService: ticket ST-1OznwzHSjLcPEeM5WUfALMzInIzWBPFDk5RBNl1n8XZ35oJ3j1QEHShraSFoj validated for user test on service http://127.0.0.1:41519. Proxy Granting Ticket transmited to http://127.0.0.1:41519. 144s 2024-11-14 14:10:40,861 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 144s PASSED 144s cas_server/tests/test_view.py::ProxyTestCase::test_proxy_unauthorized_user 2024-11-14 14:10:40,862 WARNING Proxy: validation error: INVALID_REQUEST you must specify and pgt and targetService 144s 2024-11-14 14:10:40,878 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,886 INFO Service ticket created for service http://127.0.0.1:38129 by user test. 144s 2024-11-14 14:10:40,894 INFO ValidateService: ticket ST-WZetKiScf8jrdfJaIuwLVmzcyUupDzh8PuzadoAqbGLXLjctQnErocVs3Dl10 validated for user test on service http://127.0.0.1:38129. Proxy Granting Ticket transmited to http://127.0.0.1:38129. 144s 2024-11-14 14:10:40,900 WARNING Username test not allowed on service restrict_user_fail 144s 2024-11-14 14:10:40,903 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://restrict_user_fail.example.com 144s 2024-11-14 14:10:40,909 WARNING User constraint failed for test, service filter_fail: ^admin$ do not match right None. 144s 2024-11-14 14:10:40,909 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://filter_fail.example.com 144s 2024-11-14 14:10:40,912 WARNING Cannot use uid a loggin for user test on service field_needed_fail because it is absent 144s 2024-11-14 14:10:40,912 WARNING Proxy: validation error: UNAUTHORIZED_USER User test not allowed on https://field_needed_fail.example.com 144s PASSED 144s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_pgt 2024-11-14 14:10:40,936 INFO User test successfully authenticated 144s 2024-11-14 14:10:40,947 INFO Service ticket created for service http://127.0.0.1:39867 by user test. 144s 2024-11-14 14:10:40,960 INFO ValidateService: ticket ST-cd0By1AOSFZt7SJ0pMgFlVyMTOZIpjr8jOK9sT8inbKbszqS9zwAFI8CPx52P validated for user test on service http://127.0.0.1:39867. Proxy Granting Ticket transmited to http://127.0.0.1:39867. 145s PASSED 145s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_bad_service 2024-11-14 14:10:40,964 WARNING Proxy: validation error: INVALID_TICKET PGT PGT-RANDOM not found 145s 2024-11-14 14:10:40,986 INFO User test successfully authenticated 145s 2024-11-14 14:10:40,996 INFO Service ticket created for service http://127.0.0.1:41669 by user test. 145s 2024-11-14 14:10:41,006 INFO ValidateService: ticket ST-so2CZ88tQMdm694z7mY0evkDo0wuUnJBE31zGi5B2NitIA2luFqhQDWXUH6c9 validated for user test on service http://127.0.0.1:41669. Proxy Granting Ticket transmited to http://127.0.0.1:41669. 145s 2024-11-14 14:10:41,008 WARNING Service https://www.example.org not allowed. 145s 2024-11-14 14:10:41,009 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE https://www.example.org 145s 2024-11-14 14:10:41,011 WARNING Proxy: validation error: UNAUTHORIZED_SERVICE the service http://127.0.0.1:41669 does not allow proxy tickets 145s PASSED 145s cas_server/tests/test_view.py::ProxyTestCase::test_validate_proxy_ok 2024-11-14 14:10:41,028 INFO User test successfully authenticated 145s 2024-11-14 14:10:41,036 INFO Service ticket created for service http://127.0.0.1:42365 by user test. 145s 2024-11-14 14:10:41,048 INFO ValidateService: ticket ST-VYD7Bbr9zz8ag4CMSWMUyF5yif3lEhe5cvbHmlZNoAbIG099QwvmS303bA12S validated for user test on service http://127.0.0.1:42365. Proxy Granting Ticket transmited to http://127.0.0.1:42365. 145s 2024-11-14 14:10:41,056 INFO Proxy ticket created for user test on service https://www.example.com. 145s 2024-11-14 14:10:41,061 INFO ValidateService: ticket PT-L44SzcuXoPxK1BSZZdwQi8PeMmdu2qILYLgSvE96WxGCmuFai9TOu20MlWRL9 validated for user test on service https://www.example.com. 145s PASSED 145s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_target 2024-11-14 14:10:41,103 INFO User test successfully authenticated 145s 2024-11-14 14:10:41,114 INFO Service ticket created for service https://www.example.com by user test. 145s 2024-11-14 14:10:41,119 WARNING SamlValidate: validation error: AuthnFailed TARGET https://www.example.org does not match ticket service 145s PASSED 145s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket 2024-11-14 14:10:41,129 WARNING SamlValidate: validation error: AuthnFailed ticket ST-jx07FDZHbWhd9xmZCTLwh8BXkAO8Aw94d91SlusFqYzpRvPWhE7zW89dKc4Zn not found 145s 2024-11-14 14:10:41,133 WARNING SamlValidate: validation error: AuthnFailed ticket PT-YlRb6ddqCDQHxQFaw6PfApAV7E16bvMcTJRkqnJZn1R3h94Jc8a9vI70zJ8hS not found 145s PASSED 145s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_ticket_prefix 2024-11-14 14:10:41,145 WARNING SamlValidate: validation error: AuthnFailed ticket RANDOM-NOT-BEGINING-WITH-ST-OR-ST should begin with PT- or ST- 145s PASSED 145s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_bad_xml 2024-11-14 14:10:41,152 WARNING SamlValidate: validation error: VersionMismatch 145s PASSED 145s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok 2024-11-14 14:10:41,172 INFO User test successfully authenticated 145s 2024-11-14 14:10:41,181 INFO Service ticket created for service https://www.example.com by user test. 145s 2024-11-14 14:10:41,195 INFO User test successfully authenticated 145s 2024-11-14 14:10:41,204 INFO Service ticket created for service http://127.0.0.1:38559 by user test. 145s 2024-11-14 14:10:41,212 INFO ValidateService: ticket ST-W0r4S9o3iQYjQTOBInzmMBqnj1xFtYTFoRYqYCcvQco46nwB7IBY0Z8gOiYAv validated for user test on service http://127.0.0.1:38559. Proxy Granting Ticket transmited to http://127.0.0.1:38559. 145s 2024-11-14 14:10:41,219 INFO Proxy ticket created for user test on service https://www.example.com. 145s 2024-11-14 14:10:41,223 INFO SamlValidate: ticket ST-WeFdt8oK2z5m5wmnacZOFU5eGKvpOLejMPgFUKhlk4EGd3GfCIlsL4CeaOqKG validated for user test on service https://www.example.com. 145s 2024-11-14 14:10:41,227 INFO SamlValidate: ticket PT-bk1cDhBH6vvmCLdt2V1fSS7rSYyUbtv4qZqmC2rqH2ZNBORq8pYtNNAHQEpgw validated for user test on service https://www.example.com. 145s PASSED 145s cas_server/tests/test_view.py::SamlValidateTestCase::test_saml_ok_user_field 2024-11-14 14:10:41,246 INFO User test successfully authenticated 145s 2024-11-14 14:10:41,255 INFO Service ticket created for service https://field_needed_success.example.com by user test. 145s 2024-11-14 14:10:41,259 INFO SamlValidate: ticket ST-UqYbQB4c8hdiJPIUEJL7NLsrrXQSeNYIm2zpgNXucRXr2b1WaDysgn6lZnHlk validated for user test on service https://field_needed_success.example.com. 145s 2024-11-14 14:10:41,271 INFO User test successfully authenticated 145s 2024-11-14 14:10:41,282 INFO Service ticket created for service https://field_needed_success_alt.example.com by user test. 145s 2024-11-14 14:10:41,287 INFO SamlValidate: ticket ST-g4ragLoDpZqy9QoGbsEhLb9qrhf2PPUJsJTVCDSRrPhw49lWJ3QqPlFkPd0x7 validated for user test on service https://field_needed_success_alt.example.com. 145s PASSEDDestroying test database for alias 'default'... 145s 145s 145s =============================== warnings summary =============================== 145s cas_server/utils.py:40 145s /tmp/autopkgtest.2b8tcs/autopkgtest_tmp/cas_server/utils.py:40: DeprecationWarning: 'crypt' is deprecated and slated for removal in Python 3.13 145s import crypt 145s 145s cas_server/tests/utils.py:20 145s /tmp/autopkgtest.2b8tcs/autopkgtest_tmp/cas_server/tests/utils.py:20: DeprecationWarning: 'cgi' is deprecated and slated for removal in Python 3.13 145s import cgi 145s 145s cas_server/tests/test_federate.py: 8 warnings 145s cas_server/tests/test_view.py: 3 warnings 145s /tmp/autopkgtest.2b8tcs/autopkgtest_tmp/cas_server/utils.py:211: DeprecationWarning: datetime.datetime.utcnow() is deprecated and scheduled for removal in a future version. Use timezone-aware objects to represent datetimes in UTC: datetime.datetime.now(datetime.UTC). 145s datetime.utcnow() + timedelta(seconds=max_age), 145s 145s cas_server/tests/test_federate.py::FederateAuthLoginLogoutTestCase::test_login_post_provider 145s /usr/lib/python3.12/unittest/case.py:690: DeprecationWarning: It is deprecated to return a value that is not None from a test case (>) 145s return self.run(*args, **kwds) 145s 145s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 145s ======================= 128 passed, 14 warnings in 8.41s ======================= 145s 146s autopkgtest [14:10:41]: test run-test-suite.sh: -----------------------] 147s autopkgtest [14:10:43]: test run-test-suite.sh: - - - - - - - - - - results - - - - - - - - - - 147s run-test-suite.sh PASS 147s autopkgtest [14:10:43]: @@@@@@@@@@@@@@@@@@@@ summary 147s run-test-suite.sh PASS 152s nova [W] Using flock in prodstack6-ppc64el 152s Creating nova instance adt-plucky-ppc64el-django-cas-server-20241114-140816-juju-7f2275-prod-proposed-migration-environment-20-31b03602-7986-491a-a449-8fe0e48c8030 from image adt/ubuntu-plucky-ppc64el-server-20241113.img (UUID 0c5715b6-5cca-4485-b8bf-b85dfd917a5f)...