0s autopkgtest [12:24:56]: starting date and time: 2024-11-13 12:24:56+0000 0s autopkgtest [12:24:56]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [12:24:56]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.3h6_eiox/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 pyalsaaudio --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-pyalsaaudio-20241113-122456-juju-7f2275-prod-proposed-migration-environment-2-241bfccb-73da-4271-b596-da0402154f1b --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/ 194s autopkgtest [12:28:10]: testbed dpkg architecture: s390x 194s autopkgtest [12:28:10]: testbed apt version: 2.9.8 194s autopkgtest [12:28:10]: @@@@@@@@@@@@@@@@@@@@ test bed setup 195s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 195s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 195s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [967 kB] 196s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [104 kB] 196s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [16.5 kB] 196s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [107 kB] 196s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [641 kB] 196s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [17.4 kB] 196s Fetched 1934 kB in 1s (2338 kB/s) 196s Reading package lists... 257s Reading package lists... 257s Building dependency tree... 257s Reading state information... 257s Calculating upgrade... 257s The following NEW packages will be installed: 257s python3.13-gdbm 257s The following packages will be upgraded: 257s libgpgme11t64 libpython3-stdlib python3 python3-gdbm python3-minimal 257s 5 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 257s Need to get 252 kB of archives. 257s After this operation, 98.3 kB of additional disk space will be used. 257s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-minimal s390x 3.12.7-1 [27.4 kB] 258s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3 s390x 3.12.7-1 [24.0 kB] 258s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpython3-stdlib s390x 3.12.7-1 [10.0 kB] 258s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13-gdbm s390x 3.13.0-2 [31.0 kB] 258s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-gdbm s390x 3.12.7-1 [8642 B] 258s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libgpgme11t64 s390x 1.23.2-5ubuntu4 [151 kB] 258s Fetched 252 kB in 0s (577 kB/s) 258s (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.) 258s Preparing to unpack .../python3-minimal_3.12.7-1_s390x.deb ... 258s Unpacking python3-minimal (3.12.7-1) over (3.12.6-0ubuntu1) ... 258s Setting up python3-minimal (3.12.7-1) ... 258s (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.) 258s Preparing to unpack .../python3_3.12.7-1_s390x.deb ... 258s Unpacking python3 (3.12.7-1) over (3.12.6-0ubuntu1) ... 258s Preparing to unpack .../libpython3-stdlib_3.12.7-1_s390x.deb ... 258s Unpacking libpython3-stdlib:s390x (3.12.7-1) over (3.12.6-0ubuntu1) ... 258s Selecting previously unselected package python3.13-gdbm. 258s Preparing to unpack .../python3.13-gdbm_3.13.0-2_s390x.deb ... 258s Unpacking python3.13-gdbm (3.13.0-2) ... 258s Preparing to unpack .../python3-gdbm_3.12.7-1_s390x.deb ... 258s Unpacking python3-gdbm:s390x (3.12.7-1) over (3.12.6-1ubuntu1) ... 258s Preparing to unpack .../libgpgme11t64_1.23.2-5ubuntu4_s390x.deb ... 258s Unpacking libgpgme11t64:s390x (1.23.2-5ubuntu4) over (1.18.0-4.1ubuntu4) ... 258s Setting up libgpgme11t64:s390x (1.23.2-5ubuntu4) ... 258s Setting up python3.13-gdbm (3.13.0-2) ... 258s Setting up libpython3-stdlib:s390x (3.12.7-1) ... 258s Setting up python3 (3.12.7-1) ... 259s Setting up python3-gdbm:s390x (3.12.7-1) ... 259s Processing triggers for man-db (2.12.1-3) ... 259s Processing triggers for libc-bin (2.40-1ubuntu3) ... 259s Reading package lists... 259s Building dependency tree... 259s Reading state information... 260s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 271s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 271s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 272s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 272s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 272s Reading package lists... 272s Reading package lists... 272s Building dependency tree... 272s Reading state information... 273s Calculating upgrade... 273s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 273s Reading package lists... 273s Building dependency tree... 273s Reading state information... 273s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 334s autopkgtest [12:30:30]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 335s autopkgtest [12:30:31]: @@@@@@@@@@@@@@@@@@@@ apt-source pyalsaaudio 364s Get:1 http://ftpmaster.internal/ubuntu plucky/universe pyalsaaudio 0.10.0-0.1build2 (dsc) [2158 B] 364s Get:2 http://ftpmaster.internal/ubuntu plucky/universe pyalsaaudio 0.10.0-0.1build2 (tar) [637 kB] 364s Get:3 http://ftpmaster.internal/ubuntu plucky/universe pyalsaaudio 0.10.0-0.1build2 (diff) [4944 B] 364s gpgv: Signature made Mon Apr 1 07:20:52 2024 UTC 364s gpgv: using RSA key A089FB36AAFBDAD5ACC1325069F790171A210984 364s gpgv: Can't check signature: No public key 364s dpkg-source: warning: cannot verify inline signature for ./pyalsaaudio_0.10.0-0.1build2.dsc: no acceptable signature found 364s autopkgtest [12:31:00]: testing package pyalsaaudio version 0.10.0-0.1build2 369s autopkgtest [12:31:05]: build not needed 382s autopkgtest [12:31:18]: test py3xtests: preparing testbed 411s Reading package lists... 411s Building dependency tree... 411s Reading state information... 411s Starting pkgProblemResolver with broken count: 0 411s Starting 2 pkgProblemResolver with broken count: 0 411s Done 411s The following additional packages will be installed: 411s libasound2-data libasound2t64 python3-alsaaudio 411s Suggested packages: 411s alsa-utils libasound2-plugins 411s Recommended packages: 411s alsa-ucm-conf alsa-topology-conf libjs-jquery libjs-underscore 411s The following NEW packages will be installed: 411s autopkgtest-satdep libasound2-data libasound2t64 python3-alsaaudio 411s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 411s Need to get 502 kB/503 kB of archives. 411s After this operation, 1847 kB of additional disk space will be used. 411s Get:1 /tmp/autopkgtest.AZoNgI/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [708 B] 411s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libasound2-data all 1.2.12-1 [21.0 kB] 411s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libasound2t64 s390x 1.2.12-1 [408 kB] 412s Get:4 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-alsaaudio s390x 0.10.0-0.1build2 [73.4 kB] 412s Fetched 502 kB in 1s (710 kB/s) 412s Selecting previously unselected package libasound2-data. 412s (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.) 412s Preparing to unpack .../libasound2-data_1.2.12-1_all.deb ... 412s Unpacking libasound2-data (1.2.12-1) ... 412s Selecting previously unselected package libasound2t64:s390x. 412s Preparing to unpack .../libasound2t64_1.2.12-1_s390x.deb ... 412s Unpacking libasound2t64:s390x (1.2.12-1) ... 412s Selecting previously unselected package python3-alsaaudio. 412s Preparing to unpack .../python3-alsaaudio_0.10.0-0.1build2_s390x.deb ... 412s Unpacking python3-alsaaudio (0.10.0-0.1build2) ... 412s Selecting previously unselected package autopkgtest-satdep. 412s Preparing to unpack .../1-autopkgtest-satdep.deb ... 412s Unpacking autopkgtest-satdep (0) ... 412s Setting up libasound2-data (1.2.12-1) ... 412s Setting up libasound2t64:s390x (1.2.12-1) ... 412s Setting up python3-alsaaudio (0.10.0-0.1build2) ... 412s Setting up autopkgtest-satdep (0) ... 412s Processing triggers for libc-bin (2.40-1ubuntu3) ... 420s (Reading database ... 55655 files and directories currently installed.) 420s Removing autopkgtest-satdep (0) ... 451s autopkgtest [12:32:27]: test py3xtests: [----------------------- 451s testMixer (__main__.MixerTest.testMixer) 451s Open the default Mixers and the Mixers on every card ... ok 451s testMixerAll (__main__.MixerTest.testMixerAll) 451s Run common Mixer methods on an open object ... ALSA lib confmisc.c:855:(parse_card) cannot find card '0' 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory 451s ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory 451s ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 451s ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such file or directory 451s ALSA lib control.c:1570:(snd_ctl_open_noupdate) Invalid CTL default 451s ERROR 451s testMixerClose (__main__.MixerTest.testMixerClose) 451s Run common Mixer methods on a closed object and verify it raises an ... ALSA lib confmisc.c:855:(parse_card) cannot find card '0' 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory 451s ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory 451s ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 451s ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such file or directory 451s ALSA lib control.c:1570:(snd_ctl_open_noupdate) Invalid CTL default 451s ERROR 451s testPCM (__main__.PCMTest.testPCM) 451s Open a PCM object on every card ... ok 451s testPCMAll (__main__.PCMTest.testPCMAll) 451s Run all PCM methods on an open object ... ALSA lib confmisc.c:855:(parse_card) cannot find card '0' 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory 451s ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory 451s ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 451s ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such file or directory 451s ALSA lib pcm.c:2722:(snd_pcm_open_noupdate) Unknown PCM default 451s ERROR 451s testPCMClose (__main__.PCMTest.testPCMClose) 451s Run all PCM methods on a closed object and verify it raises an error ... ALSA lib confmisc.c:855:(parse_card) cannot find card '0' 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory 451s ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory 451s ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 451s ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such file or directory 451s ALSA lib pcm.c:2722:(snd_pcm_open_noupdate) Unknown PCM default 451s ERROR 451s testPCMDeprecated (__main__.PCMTest.testPCMDeprecated) ... ALSA lib confmisc.c:855:(parse_card) cannot find card 'default' 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such device 451s ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such device 451s ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such device 451s ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such device 451s ALSA lib pcm.c:2722:(snd_pcm_open_noupdate) Unknown PCM default:CARD=default 451s ALSA lib confmisc.c:855:(parse_card) cannot find card '0' 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory 451s ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory 451s ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name 451s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 451s ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such file or directory 451s ALSA lib pcm.c:2722:(snd_pcm_open_noupdate) Unknown PCM default 451s ERROR 451s 451s ====================================================================== 451s ERROR: testMixerAll (__main__.MixerTest.testMixerAll) 451s Run common Mixer methods on an open object 451s ---------------------------------------------------------------------- 451s Traceback (most recent call last): 451s File "/tmp/autopkgtest.AZoNgI/build.VVI/src/test.py", line 63, in testMixerAll 451s mixers = alsaaudio.mixers() 451s ^^^^^^^^^^^^^^^^^^ 451s alsaaudio.ALSAAudioError: No such file or directory [default] 451s 451s ====================================================================== 451s ERROR: testMixerClose (__main__.MixerTest.testMixerClose) 451s Run common Mixer methods on a closed object and verify it raises an 451s ---------------------------------------------------------------------- 451s Traceback (most recent call last): 451s File "/tmp/autopkgtest.AZoNgI/build.VVI/src/test.py", line 79, in testMixerClose 451s mixers = alsaaudio.mixers() 451s ^^^^^^^^^^^^^^^^^^ 451s alsaaudio.ALSAAudioError: No such file or directory [default] 451s 451s ====================================================================== 451s ERROR: testPCMAll (__main__.PCMTest.testPCMAll) 451s Run all PCM methods on an open object 451s ---------------------------------------------------------------------- 451s Traceback (most recent call last): 451s File "/tmp/autopkgtest.AZoNgI/build.VVI/src/test.py", line 103, in testPCMAll 451s pcm = alsaaudio.PCM() 451s ^^^^^^^^^^^^^^^ 451s alsaaudio.ALSAAudioError: No such file or directory [default] 451s 451s ====================================================================== 451s ERROR: testPCMClose (__main__.PCMTest.testPCMClose) 451s Run all PCM methods on a closed object and verify it raises an error 451s ---------------------------------------------------------------------- 451s Traceback (most recent call last): 451s File "/tmp/autopkgtest.AZoNgI/build.VVI/src/test.py", line 117, in testPCMClose 451s pcm = alsaaudio.PCM() 451s ^^^^^^^^^^^^^^^ 451s alsaaudio.ALSAAudioError: No such file or directory [default] 451s 451s ====================================================================== 451s ERROR: testPCMDeprecated (__main__.PCMTest.testPCMDeprecated) 451s ---------------------------------------------------------------------- 451s Traceback (most recent call last): 451s File "/tmp/autopkgtest.AZoNgI/build.VVI/src/test.py", line 146, in testPCMDeprecated 451s pcm = alsaaudio.PCM() 451s ^^^^^^^^^^^^^^^ 451s alsaaudio.ALSAAudioError: No such file or directory [default] 451s 451s ---------------------------------------------------------------------- 451s Ran 7 tests in 0.002s 451s 451s FAILED (errors=5) 451s autopkgtest [12:32:27]: test py3xtests: -----------------------] 478s autopkgtest [12:32:54]: test py3xtests: - - - - - - - - - - results - - - - - - - - - - 478s py3xtests FLAKY non-zero exit status 1 516s autopkgtest [12:33:32]: @@@@@@@@@@@@@@@@@@@@ summary 516s py3xtests FLAKY non-zero exit status 1 771s virt: nova [W] Using flock in prodstack6-s390x 771s virt: flock: timeout while waiting to get lock 771s virt: Creating nova instance adt-plucky-s390x-pyalsaaudio-20241113-122456-juju-7f2275-prod-proposed-migration-environment-2-241bfccb-73da-4271-b596-da0402154f1b from image adt/ubuntu-plucky-s390x-server-20241113.img (UUID e740277e-1f72-40ae-bfbe-46030537c71c)...