0s autopkgtest [11:39:47]: starting date and time: 2024-11-13 11:39:47+0000
  0s autopkgtest [11:39:47]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time
  0s autopkgtest [11:39:47]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.d8k5zuxr/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 ucspi-tcp --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-3.secgroup --name adt-plucky-s390x-ucspi-tcp-20241113-113947-juju-7f2275-prod-proposed-migration-environment-2-67d7534f-f9fd-4a71-8078-870fe5de16dd --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/
105s autopkgtest [11:41:32]: testbed dpkg architecture: s390x
105s autopkgtest [11:41:32]: testbed apt version: 2.9.8
105s autopkgtest [11:41:32]: @@@@@@@@@@@@@@@@@@@@ test bed setup
106s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB]
106s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [76.4 kB]
106s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B]
106s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.3 kB]
106s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [849 kB]
106s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [85.8 kB]
106s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [565 kB]
107s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [16.6 kB]
107s Fetched 1689 kB in 1s (2202 kB/s)
107s Reading package lists...
109s Reading package lists...
109s Building dependency tree...
109s Reading state information...
109s Calculating upgrade...
109s The following NEW packages will be installed:
109s   python3.13-gdbm
109s The following packages will be upgraded:
109s   libgpgme11t64 libpython3-stdlib python3 python3-gdbm python3-minimal
109s 5 upgraded, 1 newly installed, 0 to remove and 0 not upgraded.
109s Need to get 252 kB of archives.
109s After this operation, 98.3 kB of additional disk space will be used.
109s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-minimal s390x 3.12.7-1 [27.4 kB]
110s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3 s390x 3.12.7-1 [24.0 kB]
110s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpython3-stdlib s390x 3.12.7-1 [10.0 kB]
110s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13-gdbm s390x 3.13.0-2 [31.0 kB]
110s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-gdbm s390x 3.12.7-1 [8642 B]
110s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libgpgme11t64 s390x 1.23.2-5ubuntu4 [151 kB]
110s Fetched 252 kB in 0s (616 kB/s)
110s (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.)
110s Preparing to unpack .../python3-minimal_3.12.7-1_s390x.deb ...
110s Unpacking python3-minimal (3.12.7-1) over (3.12.6-0ubuntu1) ...
110s Setting up python3-minimal (3.12.7-1) ...
110s (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.)
110s Preparing to unpack .../python3_3.12.7-1_s390x.deb ...
110s Unpacking python3 (3.12.7-1) over (3.12.6-0ubuntu1) ...
110s Preparing to unpack .../libpython3-stdlib_3.12.7-1_s390x.deb ...
110s Unpacking libpython3-stdlib:s390x (3.12.7-1) over (3.12.6-0ubuntu1) ...
110s Selecting previously unselected package python3.13-gdbm.
110s Preparing to unpack .../python3.13-gdbm_3.13.0-2_s390x.deb ...
110s Unpacking python3.13-gdbm (3.13.0-2) ...
110s Preparing to unpack .../python3-gdbm_3.12.7-1_s390x.deb ...
110s Unpacking python3-gdbm:s390x (3.12.7-1) over (3.12.6-1ubuntu1) ...
110s Preparing to unpack .../libgpgme11t64_1.23.2-5ubuntu4_s390x.deb ...
110s Unpacking libgpgme11t64:s390x (1.23.2-5ubuntu4) over (1.18.0-4.1ubuntu4) ...
110s Setting up libgpgme11t64:s390x (1.23.2-5ubuntu4) ...
110s Setting up python3.13-gdbm (3.13.0-2) ...
110s Setting up libpython3-stdlib:s390x (3.12.7-1) ...
110s Setting up python3 (3.12.7-1) ...
111s Setting up python3-gdbm:s390x (3.12.7-1) ...
111s Processing triggers for man-db (2.12.1-3) ...
111s Processing triggers for libc-bin (2.40-1ubuntu3) ...
111s Reading package lists...
111s Building dependency tree...
111s Reading state information...
111s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
112s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease
112s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease
112s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease
112s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease
113s Reading package lists...
113s Reading package lists...
113s Building dependency tree...
113s Reading state information...
113s 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...
114s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
116s autopkgtest [11:41:43]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024
116s autopkgtest [11:41:43]: @@@@@@@@@@@@@@@@@@@@ apt-source ucspi-tcp
118s Get:1 http://ftpmaster.internal/ubuntu plucky/universe ucspi-tcp 1:0.88-8ubuntu1 (dsc) [2223 B]
118s Get:2 http://ftpmaster.internal/ubuntu plucky/universe ucspi-tcp 1:0.88-8ubuntu1 (tar) [53.0 kB]
118s Get:3 http://ftpmaster.internal/ubuntu plucky/universe ucspi-tcp 1:0.88-8ubuntu1 (diff) [44.6 kB]
119s gpgv: Signature made Sat Apr 13 22:04:12 2024 UTC
119s gpgv:                using RSA key AC483F68DE728F43F2202FCA568D30F321B2133D
119s gpgv:                issuer "vorlon@debian.org"
119s gpgv: Can't check signature: No public key
119s dpkg-source: warning: cannot verify inline signature for ./ucspi-tcp_0.88-8ubuntu1.dsc: no acceptable signature found
119s autopkgtest [11:41:46]: testing package ucspi-tcp version 1:0.88-8ubuntu1
119s autopkgtest [11:41:46]: build not needed
119s autopkgtest [11:41:46]: test debian-python-v4: preparing testbed
121s Reading package lists...
121s Building dependency tree...
121s Reading state information...
121s Starting pkgProblemResolver with broken count: 0
121s Starting 2 pkgProblemResolver with broken count: 0
121s Done
121s The following additional packages will be installed:
121s   libpython3.13-minimal libpython3.13-stdlib python3-all python3-netifaces
121s   python3-utf8-locale python3.13 python3.13-minimal ucspi-tcp
121s Suggested packages:
121s   python3.13-venv python3.13-doc binfmt-support
121s The following NEW packages will be installed:
121s   autopkgtest-satdep libpython3.13-minimal libpython3.13-stdlib python3-all
121s   python3-netifaces python3-utf8-locale python3.13 python3.13-minimal
121s   ucspi-tcp
121s 0 upgraded, 9 newly installed, 0 to remove and 0 not upgraded.
121s Need to get 5968 kB/5968 kB of archives.
121s After this operation, 22.9 MB of additional disk space will be used.
121s Get:1 /tmp/autopkgtest.kN4hZx/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [736 B]
121s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libpython3.13-minimal s390x 3.13.0-2 [877 kB]
122s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13-minimal s390x 3.13.0-2 [2172 kB]
122s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3-netifaces s390x 0.11.0-2build3 [18.2 kB]
122s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libpython3.13-stdlib s390x 3.13.0-2 [2086 kB]
122s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13 s390x 3.13.0-2 [719 kB]
122s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-all s390x 3.12.7-1 [890 B]
122s Get:8 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-utf8-locale all 1.0.3-1build1 [9500 B]
122s Get:9 http://ftpmaster.internal/ubuntu plucky/universe s390x ucspi-tcp s390x 1:0.88-8ubuntu1 [84.2 kB]
122s Fetched 5968 kB in 1s (7081 kB/s)
122s Selecting previously unselected package libpython3.13-minimal:s390x.
122s (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.)
122s Preparing to unpack .../0-libpython3.13-minimal_3.13.0-2_s390x.deb ...
122s Unpacking libpython3.13-minimal:s390x (3.13.0-2) ...
122s Selecting previously unselected package python3.13-minimal.
122s Preparing to unpack .../1-python3.13-minimal_3.13.0-2_s390x.deb ...
122s Unpacking python3.13-minimal (3.13.0-2) ...
122s Selecting previously unselected package python3-netifaces:s390x.
122s Preparing to unpack .../2-python3-netifaces_0.11.0-2build3_s390x.deb ...
122s Unpacking python3-netifaces:s390x (0.11.0-2build3) ...
122s Selecting previously unselected package libpython3.13-stdlib:s390x.
122s Preparing to unpack .../3-libpython3.13-stdlib_3.13.0-2_s390x.deb ...
122s Unpacking libpython3.13-stdlib:s390x (3.13.0-2) ...
122s Selecting previously unselected package python3.13.
122s Preparing to unpack .../4-python3.13_3.13.0-2_s390x.deb ...
122s Unpacking python3.13 (3.13.0-2) ...
122s Selecting previously unselected package python3-all.
122s Preparing to unpack .../5-python3-all_3.12.7-1_s390x.deb ...
122s Unpacking python3-all (3.12.7-1) ...
122s Selecting previously unselected package python3-utf8-locale.
122s Preparing to unpack .../6-python3-utf8-locale_1.0.3-1build1_all.deb ...
122s Unpacking python3-utf8-locale (1.0.3-1build1) ...
122s Selecting previously unselected package ucspi-tcp.
122s Preparing to unpack .../7-ucspi-tcp_1%3a0.88-8ubuntu1_s390x.deb ...
122s Unpacking ucspi-tcp (1:0.88-8ubuntu1) ...
122s Selecting previously unselected package autopkgtest-satdep.
122s Preparing to unpack .../8-1-autopkgtest-satdep.deb ...
122s Unpacking autopkgtest-satdep (0) ...
122s Setting up ucspi-tcp (1:0.88-8ubuntu1) ...
122s Setting up libpython3.13-minimal:s390x (3.13.0-2) ...
122s Setting up python3-netifaces:s390x (0.11.0-2build3) ...
122s Setting up python3-utf8-locale (1.0.3-1build1) ...
123s Setting up python3.13-minimal (3.13.0-2) ...
123s Setting up libpython3.13-stdlib:s390x (3.13.0-2) ...
123s Setting up python3.13 (3.13.0-2) ...
125s Setting up python3-all (3.12.7-1) ...
125s Setting up autopkgtest-satdep (0) ...
125s Processing triggers for man-db (2.12.1-3) ...
125s Processing triggers for systemd (256.5-2ubuntu4) ...
127s (Reading database ... 56313 files and directories currently installed.)
127s Removing autopkgtest-satdep (0) ...
127s autopkgtest [11:41:54]: test debian-python-v4: set -e; for py in $(py3versions -i); do printf -- '\n\n====== %s\n\n' "$py"; env PYTHONPATH="$(pwd)/debian/tests/python" "$py" -B -u -m ucspi_tcp_test -d /usr/bin -p tcp; done
127s autopkgtest [11:41:54]: test debian-python-v4: [-----------------------
128s 
128s 
128s ====== python3.12
128s 
128s tcp.find_listening_address() starting
128s - got 6502
128s 
128s === Testing tcpcat
128s - will spawn tcpcat at /usr/bin/tcpcat in a while
128s - test_local_cat: setting up a listening socket at 127.0.0.1:6502
128s - test_local_cat: starting /usr/bin/tcpcat 127.0.0.1 6502
128s - started the client as pid 2313
128s - waiting for a connection at 127.0.0.1:6502
128s - accepted a connection at fd 5 from 127.0.0.1:51695
128s - closing the incoming connection
128s - client exit code: 0; output: 'a01 hello\na02 bye\n'
128s - /usr/bin/tcpcat seems fine
128s 
128s === Testing tcpclient against our own listening socket
128s - will spawn tcpclient at /usr/bin/tcpclient in a while
128s - test_local_client_spew: setting up a listening socket at 127.0.0.1:6502
128s - test_local_client_spew: starting /usr/bin/tcpclient -R 127.0.0.1 6502 sh -c 'set -e; exec <&6; exec cat'
128s - started the client as pid 2314
128s - waiting for a connection at 127.0.0.1:6502
128s - accepted a connection at fd 5 from 127.0.0.1:55953
128s - closing the incoming connection
128s - client exit code: 0; output: 'a01 hello\na02 bye\n'
128s - /usr/bin/tcpclient seems fine
128s 
128s === Testing tcpserver against our own listening socket
128s - will spawn tcpserver at /usr/bin/tcpserver in a while
128s - test_server_local: starting stdbuf -oL -eL -- /usr/bin/tcpserver -v -R 127.0.0.1 6502 printf -- 'a01 hello\na02 bye\n'
128s - awaiting the first 'status' line from tcpserver
128s - test_server_local: setting up a socket connected to 127.0.0.1:6502
128s - got a connection at fd 4 to 127.0.0.1:6502
128s - reading all the data we can
128s - read 18 bytes from the socket
128s - read 0 bytes from the socket
128s - read a total of 18 bytes from the socket
128s - sending a SIGTERM signal to the tcpserver process
128s - waiting for the tcpserver process to exit
128s - test_server_local: /usr/bin/tcpserver seems fine
128s 
128s === Testing tcpserver against tcpclient
128s - will spawn tcpserver at /usr/bin/tcpserver in a while
128s - will spawn tcpclient at /usr/bin/tcpclient in a while
128s - test_server_client_spew: starting stdbuf -oL -eL -- /usr/bin/tcpserver -v -R 127.0.0.1 6502 printf -- 'a01 hello\na02 bye\n'
128s - awaiting the first 'status' line from tcpserver
128s - test_server_client_spew: starting /usr/bin/tcpclient -R 127.0.0.1 6502 sh -c 'set -e; exec <&6; exec cat'
128s - client exit code 0; output 'a01 hello\na02 bye\n'
128s - sending a SIGTERM signal to the tcpserver process
128s - waiting for the tcpserver process to exit
128s - /usr/bin/tcpserver seems fine
128s 
128s === The tests for tcp passed
128s 
128s 
128s ====== python3.13
128s 
128s Traceback (most recent call last):
128s   File "<frozen runpy>", line 198, in _run_module_as_main
128s   File "<frozen runpy>", line 88, in _run_code
128s   File "/tmp/autopkgtest.kN4hZx/build.8bu/src/debian/tests/python/ucspi_tcp_test/__main__.py", line 12, in <module>
128s     import netifaces
128s ModuleNotFoundError: No module named 'netifaces'
128s autopkgtest [11:41:55]: test debian-python-v4: -----------------------]
129s autopkgtest [11:41:56]: test debian-python-v4:  - - - - - - - - - - results - - - - - - - - - -
129s debian-python-v4     FAIL non-zero exit status 1
129s autopkgtest [11:41:56]: test debian-python-v4:  - - - - - - - - - - stderr - - - - - - - - - -
129s Traceback (most recent call last):
129s   File "<frozen runpy>", line 198, in _run_module_as_main
129s   File "<frozen runpy>", line 88, in _run_code
129s   File "/tmp/autopkgtest.kN4hZx/build.8bu/src/debian/tests/python/ucspi_tcp_test/__main__.py", line 12, in <module>
129s     import netifaces
129s ModuleNotFoundError: No module named 'netifaces'
129s autopkgtest [11:41:56]: test debian-python-v6-4: preparing testbed
244s autopkgtest [11:43:51]: testbed dpkg architecture: s390x
244s autopkgtest [11:43:51]: testbed apt version: 2.9.8
244s autopkgtest [11:43:51]: @@@@@@@@@@@@@@@@@@@@ test bed setup
246s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB]
246s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.3 kB]
246s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [76.4 kB]
246s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [849 kB]
246s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B]
246s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [85.8 kB]
246s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [565 kB]
246s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [16.6 kB]
246s Fetched 1689 kB in 1s (2081 kB/s)
247s Reading package lists...
249s Reading package lists...
249s Building dependency tree...
249s Reading state information...
249s Calculating upgrade...
249s The following NEW packages will be installed:
249s   python3.13-gdbm
249s The following packages will be upgraded:
249s   libgpgme11t64 libpython3-stdlib python3 python3-gdbm python3-minimal
249s 5 upgraded, 1 newly installed, 0 to remove and 0 not upgraded.
249s Need to get 252 kB of archives.
249s After this operation, 98.3 kB of additional disk space will be used.
249s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-minimal s390x 3.12.7-1 [27.4 kB]
250s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3 s390x 3.12.7-1 [24.0 kB]
250s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpython3-stdlib s390x 3.12.7-1 [10.0 kB]
250s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13-gdbm s390x 3.13.0-2 [31.0 kB]
250s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-gdbm s390x 3.12.7-1 [8642 B]
250s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libgpgme11t64 s390x 1.23.2-5ubuntu4 [151 kB]
250s Fetched 252 kB in 0s (624 kB/s)
250s (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.)
250s Preparing to unpack .../python3-minimal_3.12.7-1_s390x.deb ...
250s Unpacking python3-minimal (3.12.7-1) over (3.12.6-0ubuntu1) ...
250s Setting up python3-minimal (3.12.7-1) ...
250s (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.)
250s Preparing to unpack .../python3_3.12.7-1_s390x.deb ...
250s Unpacking python3 (3.12.7-1) over (3.12.6-0ubuntu1) ...
250s Preparing to unpack .../libpython3-stdlib_3.12.7-1_s390x.deb ...
250s Unpacking libpython3-stdlib:s390x (3.12.7-1) over (3.12.6-0ubuntu1) ...
250s Selecting previously unselected package python3.13-gdbm.
250s Preparing to unpack .../python3.13-gdbm_3.13.0-2_s390x.deb ...
250s Unpacking python3.13-gdbm (3.13.0-2) ...
250s Preparing to unpack .../python3-gdbm_3.12.7-1_s390x.deb ...
250s Unpacking python3-gdbm:s390x (3.12.7-1) over (3.12.6-1ubuntu1) ...
250s Preparing to unpack .../libgpgme11t64_1.23.2-5ubuntu4_s390x.deb ...
250s Unpacking libgpgme11t64:s390x (1.23.2-5ubuntu4) over (1.18.0-4.1ubuntu4) ...
250s Setting up libgpgme11t64:s390x (1.23.2-5ubuntu4) ...
250s Setting up python3.13-gdbm (3.13.0-2) ...
250s Setting up libpython3-stdlib:s390x (3.12.7-1) ...
250s Setting up python3 (3.12.7-1) ...
251s Setting up python3-gdbm:s390x (3.12.7-1) ...
251s Processing triggers for man-db (2.12.1-3) ...
251s Processing triggers for libc-bin (2.40-1ubuntu3) ...
251s Reading package lists...
251s Building dependency tree...
251s Reading state information...
252s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
252s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease
252s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease
252s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease
252s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease
253s Reading package lists...
253s Reading package lists...
253s Building dependency tree...
253s Reading state information...
253s Calculating upgrade...
253s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
253s Reading package lists...
253s Building dependency tree...
253s Reading state information...
254s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
258s Reading package lists...
258s Building dependency tree...
258s Reading state information...
258s Starting pkgProblemResolver with broken count: 0
258s Starting 2 pkgProblemResolver with broken count: 0
258s Done
258s The following additional packages will be installed:
258s   libpython3.13-minimal libpython3.13-stdlib python3-all python3-netifaces
258s   python3-utf8-locale python3.13 python3.13-minimal ucspi-tcp-ipv6
258s Suggested packages:
258s   python3.13-venv python3.13-doc binfmt-support
258s The following NEW packages will be installed:
258s   autopkgtest-satdep libpython3.13-minimal libpython3.13-stdlib python3-all
258s   python3-netifaces python3-utf8-locale python3.13 python3.13-minimal
258s   ucspi-tcp-ipv6
258s 0 upgraded, 9 newly installed, 0 to remove and 0 not upgraded.
258s Need to get 5971 kB/5972 kB of archives.
258s After this operation, 22.9 MB of additional disk space will be used.
258s Get:1 /tmp/autopkgtest.kN4hZx/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [740 B]
258s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libpython3.13-minimal s390x 3.13.0-2 [877 kB]
259s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13-minimal s390x 3.13.0-2 [2172 kB]
259s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3-netifaces s390x 0.11.0-2build3 [18.2 kB]
259s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x libpython3.13-stdlib s390x 3.13.0-2 [2086 kB]
259s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13 s390x 3.13.0-2 [719 kB]
259s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-all s390x 3.12.7-1 [890 B]
259s Get:8 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-utf8-locale all 1.0.3-1build1 [9500 B]
259s Get:9 http://ftpmaster.internal/ubuntu plucky/universe s390x ucspi-tcp-ipv6 s390x 1:0.88-8ubuntu1 [87.8 kB]
259s Fetched 5971 kB in 1s (7679 kB/s)
259s Selecting previously unselected package libpython3.13-minimal:s390x.
259s (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.)
259s Preparing to unpack .../0-libpython3.13-minimal_3.13.0-2_s390x.deb ...
259s Unpacking libpython3.13-minimal:s390x (3.13.0-2) ...
259s Selecting previously unselected package python3.13-minimal.
259s Preparing to unpack .../1-python3.13-minimal_3.13.0-2_s390x.deb ...
259s Unpacking python3.13-minimal (3.13.0-2) ...
259s Selecting previously unselected package python3-netifaces:s390x.
259s Preparing to unpack .../2-python3-netifaces_0.11.0-2build3_s390x.deb ...
259s Unpacking python3-netifaces:s390x (0.11.0-2build3) ...
259s Selecting previously unselected package libpython3.13-stdlib:s390x.
259s Preparing to unpack .../3-libpython3.13-stdlib_3.13.0-2_s390x.deb ...
259s Unpacking libpython3.13-stdlib:s390x (3.13.0-2) ...
259s Selecting previously unselected package python3.13.
259s Preparing to unpack .../4-python3.13_3.13.0-2_s390x.deb ...
259s Unpacking python3.13 (3.13.0-2) ...
260s Selecting previously unselected package python3-all.
260s Preparing to unpack .../5-python3-all_3.12.7-1_s390x.deb ...
260s Unpacking python3-all (3.12.7-1) ...
260s Selecting previously unselected package python3-utf8-locale.
260s Preparing to unpack .../6-python3-utf8-locale_1.0.3-1build1_all.deb ...
260s Unpacking python3-utf8-locale (1.0.3-1build1) ...
260s Selecting previously unselected package ucspi-tcp-ipv6.
260s Preparing to unpack .../7-ucspi-tcp-ipv6_1%3a0.88-8ubuntu1_s390x.deb ...
260s Unpacking ucspi-tcp-ipv6 (1:0.88-8ubuntu1) ...
260s Selecting previously unselected package autopkgtest-satdep.
260s Preparing to unpack .../8-2-autopkgtest-satdep.deb ...
260s Unpacking autopkgtest-satdep (0) ...
260s Setting up libpython3.13-minimal:s390x (3.13.0-2) ...
260s Setting up ucspi-tcp-ipv6 (1:0.88-8ubuntu1) ...
260s Setting up python3-netifaces:s390x (0.11.0-2build3) ...
260s Setting up python3-utf8-locale (1.0.3-1build1) ...
260s Setting up python3.13-minimal (3.13.0-2) ...
261s Setting up libpython3.13-stdlib:s390x (3.13.0-2) ...
261s Setting up python3.13 (3.13.0-2) ...
262s Setting up python3-all (3.12.7-1) ...
262s Setting up autopkgtest-satdep (0) ...
262s Processing triggers for man-db (2.12.1-3) ...
262s Processing triggers for systemd (256.5-2ubuntu4) ...
264s (Reading database ... 56314 files and directories currently installed.)
264s Removing autopkgtest-satdep (0) ...
266s autopkgtest [11:44:13]: test debian-python-v6-4: set -e; for py in $(py3versions -i); do printf -- '\n\n====== %s\n\n' "$py"; env PYTHONPATH="$(pwd)/debian/tests/python" "$py" -B -u -m ucspi_tcp_test -d /usr/bin -p tcp -i 4; done
266s autopkgtest [11:44:13]: test debian-python-v6-4: [-----------------------
266s 
266s 
266s ====== python3.12
266s 
266s tcp.find_listening_address() starting
266s - got 6502
266s 
266s === Testing tcpcat
266s - will spawn tcpcat at /usr/bin/tcpcat in a while
266s - test_local_cat: setting up a listening socket at 127.0.0.1:6502
266s - test_local_cat: starting /usr/bin/tcpcat 127.0.0.1 6502
266s - started the client as pid 2164
266s - waiting for a connection at 127.0.0.1:6502
266s - accepted a connection at fd 5 from 127.0.0.1:41165
266s - closing the incoming connection
266s - client exit code: 0; output: 'a01 hello\na02 bye\n'
266s - /usr/bin/tcpcat seems fine
266s 
266s === Testing tcpclient against our own listening socket
266s - will spawn tcpclient at /usr/bin/tcpclient in a while
266s - test_local_client_spew: setting up a listening socket at 127.0.0.1:6502
266s - test_local_client_spew: starting /usr/bin/tcpclient -R 127.0.0.1 6502 sh -c 'set -e; exec <&6; exec cat'
266s - started the client as pid 2165
266s - waiting for a connection at 127.0.0.1:6502
266s - accepted a connection at fd 5 from 127.0.0.1:52041
266s - closing the incoming connection
266s - client exit code: 0; output: 'a01 hello\na02 bye\n'
266s - /usr/bin/tcpclient seems fine
266s 
266s === Testing tcpserver against our own listening socket
266s - will spawn tcpserver at /usr/bin/tcpserver in a while
266s - test_server_local: starting stdbuf -oL -eL -- /usr/bin/tcpserver -v -R 127.0.0.1 6502 printf -- 'a01 hello\na02 bye\n'
266s - awaiting the first 'status' line from tcpserver
266s - test_server_local: setting up a socket connected to 127.0.0.1:6502
266s - got a connection at fd 4 to 127.0.0.1:6502
266s - reading all the data we can
266s - read 18 bytes from the socket
266s - read 0 bytes from the socket
266s - read a total of 18 bytes from the socket
266s - sending a SIGTERM signal to the tcpserver process
266s - waiting for the tcpserver process to exit
266s - test_server_local: /usr/bin/tcpserver seems fine
266s 
266s === Testing tcpserver against tcpclient
266s - will spawn tcpserver at /usr/bin/tcpserver in a while
266s - will spawn tcpclient at /usr/bin/tcpclient in a while
266s - test_server_client_spew: starting stdbuf -oL -eL -- /usr/bin/tcpserver -v -R 127.0.0.1 6502 printf -- 'a01 hello\na02 bye\n'
266s - awaiting the first 'status' line from tcpserver
266s - test_server_client_spew: starting /usr/bin/tcpclient -R 127.0.0.1 6502 sh -c 'set -e; exec <&6; exec cat'
266s - client exit code 0; output 'a01 hello\na02 bye\n'
266s - sending a SIGTERM signal to the tcpserver process
266s - waiting for the tcpserver process to exit
266s - /usr/bin/tcpserver seems fine
266s 
266s === The tests for tcp passed
266s 
266s 
266s ====== python3.13
266s 
266s Traceback (most recent call last):
266s   File "<frozen runpy>", line 198, in _run_module_as_main
266s   File "<frozen runpy>", line 88, in _run_code
266s   File "/tmp/autopkgtest.kN4hZx/build.8bu/src/debian/tests/python/ucspi_tcp_test/__main__.py", line 12, in <module>
266s     import netifaces
266s ModuleNotFoundError: No module named 'netifaces'
267s autopkgtest [11:44:14]: test debian-python-v6-4: -----------------------]
267s autopkgtest [11:44:14]: test debian-python-v6-4:  - - - - - - - - - - results - - - - - - - - - -
267s debian-python-v6-4   FAIL non-zero exit status 1
267s autopkgtest [11:44:14]: test debian-python-v6-4:  - - - - - - - - - - stderr - - - - - - - - - -
267s Traceback (most recent call last):
267s   File "<frozen runpy>", line 198, in _run_module_as_main
267s   File "<frozen runpy>", line 88, in _run_code
267s   File "/tmp/autopkgtest.kN4hZx/build.8bu/src/debian/tests/python/ucspi_tcp_test/__main__.py", line 12, in <module>
267s     import netifaces
267s ModuleNotFoundError: No module named 'netifaces'
268s autopkgtest [11:44:15]: test debian-python-v6: preparing testbed
269s Reading package lists...
269s Building dependency tree...
269s Reading state information...
269s Starting pkgProblemResolver with broken count: 0
269s Starting 2 pkgProblemResolver with broken count: 0
269s Done
269s The following NEW packages will be installed:
269s   autopkgtest-satdep
269s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded.
269s Need to get 0 B/740 B of archives.
269s After this operation, 0 B of additional disk space will be used.
269s Get:1 /tmp/autopkgtest.kN4hZx/3-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [740 B]
270s Selecting previously unselected package autopkgtest-satdep.
270s (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 ... 56314 files and directories currently installed.)
270s Preparing to unpack .../3-autopkgtest-satdep.deb ...
270s Unpacking autopkgtest-satdep (0) ...
270s Setting up autopkgtest-satdep (0) ...
271s (Reading database ... 56314 files and directories currently installed.)
271s Removing autopkgtest-satdep (0) ...
272s autopkgtest [11:44:19]: test debian-python-v6: set -e; for py in $(py3versions -i); do printf -- '\n\n====== %s\n\n' "$py"; env PYTHONPATH="$(pwd)/debian/tests/python" "$py" -B -u -m ucspi_tcp_test -d /usr/bin -p tcp -i 6; done
272s autopkgtest [11:44:19]: test debian-python-v6: [-----------------------
272s 
272s 
272s ====== python3.12
272s 
272s tcp.find_listening_address() starting
272s - got 6502
272s 
272s === Testing tcpcat
272s - will spawn tcpcat at /usr/bin/tcpcat in a while
272s - test_local_cat: setting up a listening socket at ::1:6502
272s - test_local_cat: starting /usr/bin/tcpcat ::1 6502
272s - started the client as pid 2422
272s - waiting for a connection at ::1:6502
272s - accepted a connection at fd 5 from ::1:48935
272s - closing the incoming connection
272s - client exit code: 0; output: 'a01 hello\na02 bye\n'
272s - /usr/bin/tcpcat seems fine
272s 
272s === Testing tcpclient against our own listening socket
272s - will spawn tcpclient at /usr/bin/tcpclient in a while
272s - test_local_client_spew: setting up a listening socket at ::1:6502
272s - test_local_client_spew: starting /usr/bin/tcpclient -R ::1 6502 sh -c 'set -e; exec <&6; exec cat'
272s - started the client as pid 2423
272s - waiting for a connection at ::1:6502
272s - accepted a connection at fd 5 from ::1:51895
272s - closing the incoming connection
272s - client exit code: 0; output: 'a01 hello\na02 bye\n'
272s - /usr/bin/tcpclient seems fine
272s 
272s === Testing tcpserver against our own listening socket
272s - will spawn tcpserver at /usr/bin/tcpserver in a while
272s - test_server_local: starting stdbuf -oL -eL -- /usr/bin/tcpserver -v -R ::1 6502 printf -- 'a01 hello\na02 bye\n'
272s - awaiting the first 'status' line from tcpserver
272s - test_server_local: setting up a socket connected to ::1:6502
272s - got a connection at fd 4 to ::1:6502
272s - reading all the data we can
272s - read 18 bytes from the socket
272s - read 0 bytes from the socket
272s - read a total of 18 bytes from the socket
272s - sending a SIGTERM signal to the tcpserver process
272s - waiting for the tcpserver process to exit
272s - test_server_local: /usr/bin/tcpserver seems fine
272s 
272s === Testing tcpserver against tcpclient
272s - will spawn tcpserver at /usr/bin/tcpserver in a while
272s - will spawn tcpclient at /usr/bin/tcpclient in a while
272s - test_server_client_spew: starting stdbuf -oL -eL -- /usr/bin/tcpserver -v -R ::1 6502 printf -- 'a01 hello\na02 bye\n'
272s - awaiting the first 'status' line from tcpserver
272s - test_server_client_spew: starting /usr/bin/tcpclient -R ::1 6502 sh -c 'set -e; exec <&6; exec cat'
272s - client exit code 0; output 'a01 hello\na02 bye\n'
272s - sending a SIGTERM signal to the tcpserver process
272s - waiting for the tcpserver process to exit
272s - /usr/bin/tcpserver seems fine
272s 
272s === The tests for tcp passed
272s 
272s 
272s ====== python3.13
272s 
272s Traceback (most recent call last):
272s   File "<frozen runpy>", line 198, in _run_module_as_main
272s   File "<frozen runpy>", line 88, in _run_code
272s   File "/tmp/autopkgtest.kN4hZx/build.8bu/src/debian/tests/python/ucspi_tcp_test/__main__.py", line 12, in <module>
272s     import netifaces
272s ModuleNotFoundError: No module named 'netifaces'
273s autopkgtest [11:44:20]: test debian-python-v6: -----------------------]
273s autopkgtest [11:44:20]: test debian-python-v6:  - - - - - - - - - - results - - - - - - - - - -
273s debian-python-v6     FAIL non-zero exit status 1
273s autopkgtest [11:44:20]: test debian-python-v6:  - - - - - - - - - - stderr - - - - - - - - - -
273s Traceback (most recent call last):
273s   File "<frozen runpy>", line 198, in _run_module_as_main
273s   File "<frozen runpy>", line 88, in _run_code
273s   File "/tmp/autopkgtest.kN4hZx/build.8bu/src/debian/tests/python/ucspi_tcp_test/__main__.py", line 12, in <module>
273s     import netifaces
273s ModuleNotFoundError: No module named 'netifaces'
274s autopkgtest [11:44:21]: @@@@@@@@@@@@@@@@@@@@ summary
274s debian-python-v4     FAIL non-zero exit status 1
274s debian-python-v6-4   FAIL non-zero exit status 1
274s debian-python-v6     FAIL non-zero exit status 1
288s virt: nova [W] Using flock in prodstack6-s390x
288s virt: flock: timeout while waiting to get lock
288s virt: Creating nova instance adt-plucky-s390x-ucspi-tcp-20241113-113947-juju-7f2275-prod-proposed-migration-environment-2-67d7534f-f9fd-4a71-8078-870fe5de16dd from image adt/ubuntu-plucky-s390x-server-20241113.img (UUID e740277e-1f72-40ae-bfbe-46030537c71c)...
288s virt: nova [W] Using flock in prodstack6-s390x
288s virt: flock: timeout while waiting to get lock
288s virt: Creating nova instance adt-plucky-s390x-ucspi-tcp-20241113-113947-juju-7f2275-prod-proposed-migration-environment-2-67d7534f-f9fd-4a71-8078-870fe5de16dd from image adt/ubuntu-plucky-s390x-server-20241113.img (UUID e740277e-1f72-40ae-bfbe-46030537c71c)...