0s autopkgtest [10:35:58]: starting date and time: 2024-11-13 10:35:58+0000 0s autopkgtest [10:35:58]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [10:35:58]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.68gb1vkv/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults,src:python3-stdlib-extensions --apt-upgrade cheetah --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=python3-defaults/3.12.7-1 python3-stdlib-extensions/3.12.7-1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-8.secgroup --name adt-plucky-s390x-cheetah-20241113-103557-juju-7f2275-prod-proposed-migration-environment-2-a50119c9-ac2f-4b2b-b823-296d959ae4e2 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -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/ 108s autopkgtest [10:37:46]: testbed dpkg architecture: s390x 108s autopkgtest [10:37:46]: testbed apt version: 2.9.8 108s autopkgtest [10:37:46]: @@@@@@@@@@@@@@@@@@@@ test bed setup 109s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 109s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [849 kB] 110s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 110s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [76.4 kB] 110s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.3 kB] 110s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [85.8 kB] 110s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [565 kB] 110s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [16.6 kB] 110s Fetched 1689 kB in 1s (2253 kB/s) 110s Reading package lists... 112s Reading package lists... 112s Building dependency tree... 112s Reading state information... 112s Calculating upgrade... 112s The following NEW packages will be installed: 112s python3.13-gdbm 112s The following packages will be upgraded: 112s libgpgme11t64 libpython3-stdlib python3 python3-gdbm python3-minimal 112s 5 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 112s Need to get 252 kB of archives. 112s After this operation, 98.3 kB of additional disk space will be used. 112s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-minimal s390x 3.12.7-1 [27.4 kB] 112s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3 s390x 3.12.7-1 [24.0 kB] 112s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpython3-stdlib s390x 3.12.7-1 [10.0 kB] 112s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13-gdbm s390x 3.13.0-2 [31.0 kB] 113s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-gdbm s390x 3.12.7-1 [8642 B] 113s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libgpgme11t64 s390x 1.23.2-5ubuntu4 [151 kB] 113s Fetched 252 kB in 0s (560 kB/s) 113s (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 ... 55510 files and directories currently installed.) 113s Preparing to unpack .../python3-minimal_3.12.7-1_s390x.deb ... 113s Unpacking python3-minimal (3.12.7-1) over (3.12.6-0ubuntu1) ... 113s Setting up python3-minimal (3.12.7-1) ... 113s (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 ... 55510 files and directories currently installed.) 113s Preparing to unpack .../python3_3.12.7-1_s390x.deb ... 113s Unpacking python3 (3.12.7-1) over (3.12.6-0ubuntu1) ... 113s Preparing to unpack .../libpython3-stdlib_3.12.7-1_s390x.deb ... 113s Unpacking libpython3-stdlib:s390x (3.12.7-1) over (3.12.6-0ubuntu1) ... 113s Selecting previously unselected package python3.13-gdbm. 113s Preparing to unpack .../python3.13-gdbm_3.13.0-2_s390x.deb ... 113s Unpacking python3.13-gdbm (3.13.0-2) ... 113s Preparing to unpack .../python3-gdbm_3.12.7-1_s390x.deb ... 113s Unpacking python3-gdbm:s390x (3.12.7-1) over (3.12.6-1ubuntu1) ... 113s Preparing to unpack .../libgpgme11t64_1.23.2-5ubuntu4_s390x.deb ... 113s Unpacking libgpgme11t64:s390x (1.23.2-5ubuntu4) over (1.18.0-4.1ubuntu4) ... 113s Setting up libgpgme11t64:s390x (1.23.2-5ubuntu4) ... 113s Setting up python3.13-gdbm (3.13.0-2) ... 113s Setting up libpython3-stdlib:s390x (3.12.7-1) ... 113s Setting up python3 (3.12.7-1) ... 113s Setting up python3-gdbm:s390x (3.12.7-1) ... 113s Processing triggers for man-db (2.12.1-3) ... 114s Processing triggers for libc-bin (2.40-1ubuntu3) ... 114s Reading package lists... 114s Building dependency tree... 114s Reading state information... 114s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 115s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 115s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 115s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 116s Reading package lists...Reading package lists... 116s Building dependency tree... 116s Reading state information... 116s Calculating upgrade... 116s 116s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 116s Reading package lists... 116s Building dependency tree... 116s Reading state information... 116s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 119s autopkgtest [10:37:57]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 119s autopkgtest [10:37:57]: @@@@@@@@@@@@@@@@@@@@ apt-source cheetah 121s Get:1 http://ftpmaster.internal/ubuntu plucky/universe cheetah 3.3.3-1build1 (dsc) [2279 B] 121s Get:2 http://ftpmaster.internal/ubuntu plucky/universe cheetah 3.3.3-1build1 (tar) [251 kB] 121s Get:3 http://ftpmaster.internal/ubuntu plucky/universe cheetah 3.3.3-1build1 (diff) [47.0 kB] 121s gpgv: Signature made Thu Apr 11 11:10:41 2024 UTC 121s gpgv: using RSA key 25E3FF2D7F469DBE7D0D4E50AFCFEC8E669CE1C2 121s gpgv: Can't check signature: No public key 121s dpkg-source: warning: cannot verify inline signature for ./cheetah_3.3.3-1build1.dsc: no acceptable signature found 121s autopkgtest [10:37:59]: testing package cheetah version 3.3.3-1build1 121s autopkgtest [10:37:59]: build not needed 122s autopkgtest [10:38:00]: test load-module-v3: preparing testbed 124s Reading package lists... 124s Building dependency tree... 124s Reading state information... 124s 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 libpython3.13-minimal libpython3.13-stdlib python3-all python3-cheetah 124s python3.13 python3.13-minimal 124s Suggested packages: 124s python-cheetah-doc python3-markdown python3-memcache python3.13-venv 124s python3.13-doc binfmt-support 124s The following NEW packages will be installed: 124s autopkgtest-satdep libpython3.13-minimal libpython3.13-stdlib python3-all 124s python3-cheetah python3.13 python3.13-minimal 124s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 124s Need to get 5994 kB/5995 kB of archives. 124s After this operation, 23.2 MB of additional disk space will be used. 124s Get:1 /tmp/autopkgtest.WAfLxC/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [716 B] 124s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libpython3.13-minimal s390x 3.13.0-2 [877 kB] 125s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13-minimal s390x 3.13.0-2 [2172 kB] 126s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libpython3.13-stdlib s390x 3.13.0-2 [2086 kB] 126s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13 s390x 3.13.0-2 [719 kB] 127s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-all s390x 3.12.7-1 [890 B] 127s Get:7 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-cheetah s390x 3.3.3-1build1 [139 kB] 127s Fetched 5994 kB in 2s (2590 kB/s) 127s Selecting previously unselected package libpython3.13-minimal:s390x. 127s (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 ... 55517 files and directories currently installed.) 127s Preparing to unpack .../0-libpython3.13-minimal_3.13.0-2_s390x.deb ... 127s Unpacking libpython3.13-minimal:s390x (3.13.0-2) ... 127s Selecting previously unselected package python3.13-minimal. 127s Preparing to unpack .../1-python3.13-minimal_3.13.0-2_s390x.deb ... 127s Unpacking python3.13-minimal (3.13.0-2) ... 127s Selecting previously unselected package libpython3.13-stdlib:s390x. 127s Preparing to unpack .../2-libpython3.13-stdlib_3.13.0-2_s390x.deb ... 127s Unpacking libpython3.13-stdlib:s390x (3.13.0-2) ... 127s Selecting previously unselected package python3.13. 127s Preparing to unpack .../3-python3.13_3.13.0-2_s390x.deb ... 127s Unpacking python3.13 (3.13.0-2) ... 127s Selecting previously unselected package python3-all. 127s Preparing to unpack .../4-python3-all_3.12.7-1_s390x.deb ... 127s Unpacking python3-all (3.12.7-1) ... 127s Selecting previously unselected package python3-cheetah. 127s Preparing to unpack .../5-python3-cheetah_3.3.3-1build1_s390x.deb ... 127s Unpacking python3-cheetah (3.3.3-1build1) ... 127s Selecting previously unselected package autopkgtest-satdep. 127s Preparing to unpack .../6-1-autopkgtest-satdep.deb ... 127s Unpacking autopkgtest-satdep (0) ... 127s Setting up libpython3.13-minimal:s390x (3.13.0-2) ... 127s Setting up python3-cheetah (3.3.3-1build1) ... 127s Setting up python3.13-minimal (3.13.0-2) ... 128s Setting up libpython3.13-stdlib:s390x (3.13.0-2) ... 128s Setting up python3.13 (3.13.0-2) ... 129s Setting up python3-all (3.12.7-1) ... 129s Setting up autopkgtest-satdep (0) ... 129s Processing triggers for man-db (2.12.1-3) ... 129s Processing triggers for systemd (256.5-2ubuntu4) ... 131s (Reading database ... 56340 files and directories currently installed.) 131s Removing autopkgtest-satdep (0) ... 132s autopkgtest [10:38:10]: test load-module-v3: [----------------------- 132s Loading module with python3.13: 132s 132s Loading module with python3.12: 132s 133s autopkgtest [10:38:11]: test load-module-v3: -----------------------] 133s autopkgtest [10:38:11]: test load-module-v3: - - - - - - - - - - results - - - - - - - - - - 133s load-module-v3 PASS 134s autopkgtest [10:38:12]: test upstream-test-v3: preparing testbed 135s Reading package lists... 135s Building dependency tree... 135s Reading state information... 135s Starting pkgProblemResolver with broken count: 0 135s Starting 2 pkgProblemResolver with broken count: 0 135s Done 135s The following additional packages will be installed: 135s python3-markdown 135s Suggested packages: 135s python-markdown-doc 135s The following NEW packages will be installed: 135s autopkgtest-satdep python3-markdown 135s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 135s Need to get 73.4 kB/74.2 kB of archives. 135s After this operation, 366 kB of additional disk space will be used. 135s Get:1 /tmp/autopkgtest.WAfLxC/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [732 B] 136s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x python3-markdown all 3.6-1 [73.4 kB] 136s Fetched 73.4 kB in 0s (260 kB/s) 136s Selecting previously unselected package python3-markdown. 136s (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 ... 56340 files and directories currently installed.) 136s Preparing to unpack .../python3-markdown_3.6-1_all.deb ... 136s Unpacking python3-markdown (3.6-1) ... 136s Selecting previously unselected package autopkgtest-satdep. 136s Preparing to unpack .../2-autopkgtest-satdep.deb ... 136s Unpacking autopkgtest-satdep (0) ... 136s Setting up python3-markdown (3.6-1) ... 136s Setting up autopkgtest-satdep (0) ... 136s Processing triggers for man-db (2.12.1-3) ... 138s (Reading database ... 56387 files and directories currently installed.) 138s Removing autopkgtest-satdep (0) ... 139s autopkgtest [10:38:17]: test upstream-test-v3: [----------------------- 139s Running upstream Test.py with python3.13: 139s /usr/lib/python3/dist-packages/Cheetah/Compiler.py:1631: UserWarning: 139s You don't have the C version of NameMapper installed! I'm disabling Cheetah's useStackFrames option as it is painfully slow with the Python version of NameMapper. You should get a copy of Cheetah with compiled C version of NameMapper. 139s warnings.warn( 141s .............................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................E...E............................................................................................................................................................................................................................................................................................................................/usr/lib/python3/dist-packages/Cheetah/Compiler.py:1631: UserWarning: 141s You don't have the C version of NameMapper installed! I'm disabling Cheetah's useStackFrames option as it is painfully slow with the Python version of NameMapper. You should get a copy of Cheetah with compiled C version of NameMapper. 141s warnings.warn( 141s ./usr/lib/python3/dist-packages/Cheetah/Compiler.py:1631: UserWarning: 141s You don't have the C version of NameMapper installed! I'm disabling Cheetah's useStackFrames option as it is painfully slow with the Python version of NameMapper. You should get a copy of Cheetah with compiled C version of NameMapper. 141s warnings.warn( 146s ...................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................... 146s ====================================================================== 146s ERROR: test4 (Cheetah.Tests.SyntaxAndOutput.CGI.test4) 146s A CGI script with a GET variable. 146s ---------------------------------------------------------------------- 146s Traceback (most recent call last): 146s File "/usr/lib/python3/dist-packages/Cheetah/Tests/SyntaxAndOutput.py", line 3141, in test4 146s self.verify(source, 146s ~~~~~~~~~~~^^^^^^^^ 146s "Content-type: text/html\n\nHello, world!") 146s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 146s File "/usr/lib/python3/dist-packages/Cheetah/Tests/SyntaxAndOutput.py", line 179, in verify 146s output = templateObj.respond() 146s File "cheetah_DynamicallyCompiledCheetahTemplate_1731494299_243648_21349.py", line 89, in respond 146s File "/usr/lib/python3/dist-packages/Cheetah/Template.py", line 1937, in webInput 146s source, func = 'field', self.request().field 146s ~~~~~~~~~~~~^^ 146s TypeError: 'NoneType' object is not callable 146s 146s ====================================================================== 146s ERROR: test4 (Cheetah.Tests.SyntaxAndOutput.CGI_DiffBaseClass.test4) 146s A CGI script with a GET variable. 146s ---------------------------------------------------------------------- 146s Traceback (most recent call last): 146s File "/usr/lib/python3/dist-packages/Cheetah/Tests/SyntaxAndOutput.py", line 3141, in test4 146s self.verify(source, 146s ~~~~~~~~~~~^^^^^^^^ 146s "Content-type: text/html\n\nHello, world!") 146s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 146s File "/usr/lib/python3/dist-packages/Cheetah/Tests/SyntaxAndOutput.py", line 179, in verify 146s output = templateObj.respond() 146s File "cheetah_DynamicallyCompiledCheetahTemplate_1731494299_2492049_11186.py", line 89, in respond 146s File "/usr/lib/python3/dist-packages/Cheetah/Template.py", line 1937, in webInput 146s source, func = 'field', self.request().field 146s ~~~~~~~~~~~~^^ 146s TypeError: 'NoneType' object is not callable 146s 146s ---------------------------------------------------------------------- 146s Ran 2181 tests in 7.007s 146s 146s FAILED (errors=2) 146s autopkgtest [10:38:24]: test upstream-test-v3: -----------------------] 147s autopkgtest [10:38:25]: test upstream-test-v3: - - - - - - - - - - results - - - - - - - - - - 147s upstream-test-v3 FAIL non-zero exit status 1 147s autopkgtest [10:38:25]: @@@@@@@@@@@@@@@@@@@@ summary 147s load-module-v3 PASS 147s upstream-test-v3 FAIL non-zero exit status 1 154s virt: nova [W] Using flock in prodstack6-s390x 154s virt: flock: timeout while waiting to get lock 154s virt: Creating nova instance adt-plucky-s390x-cheetah-20241113-103557-juju-7f2275-prod-proposed-migration-environment-2-a50119c9-ac2f-4b2b-b823-296d959ae4e2 from image adt/ubuntu-plucky-s390x-server-20241113.img (UUID e740277e-1f72-40ae-bfbe-46030537c71c)...