0s autopkgtest [09:24:55]: starting date and time: 2024-11-13 09:24:55+0000 0s autopkgtest [09:24:55]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [09:24:55]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.3osho1_v/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 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@lcy02-16.secgroup --name adt-plucky-amd64-pyalsaaudio-20241113-092455-juju-7f2275-prod-proposed-migration-environment-2-964d7f80-5dab-4298-a109-3ce8aa4090f1 --image adt/ubuntu-plucky-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -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/ 60s autopkgtest [09:25:55]: testbed dpkg architecture: amd64 60s autopkgtest [09:25:55]: testbed apt version: 2.9.8 60s autopkgtest [09:25:55]: @@@@@@@@@@@@@@@@@@@@ test bed setup 60s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 60s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 60s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.3 kB] 60s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [76.4 kB] 60s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [849 kB] 60s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [65.2 kB] 60s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [111 kB] 60s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [32.6 kB] 60s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [255 kB] 60s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [637 kB] 60s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [13.0 kB] 60s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [37.7 kB] 61s Fetched 2173 kB in 0s (6498 kB/s) 61s Reading package lists... 62s Reading package lists... 63s Building dependency tree... 63s Reading state information... 63s Calculating upgrade... 64s The following NEW packages will be installed: 64s python3.13-gdbm 64s The following packages will be upgraded: 64s libgpgme11t64 libpython3-stdlib python3 python3-gdbm python3-minimal 64s 5 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 64s Need to get 253 kB of archives. 64s After this operation, 147 kB of additional disk space will be used. 64s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 python3-minimal amd64 3.12.7-1 [27.4 kB] 64s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 python3 amd64 3.12.7-1 [24.0 kB] 64s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libpython3-stdlib amd64 3.12.7-1 [10.0 kB] 64s Get:4 http://ftpmaster.internal/ubuntu plucky/main amd64 python3.13-gdbm amd64 3.13.0-2 [31.3 kB] 64s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 python3-gdbm amd64 3.12.7-1 [8642 B] 64s Get:6 http://ftpmaster.internal/ubuntu plucky/main amd64 libgpgme11t64 amd64 1.23.2-5ubuntu4 [152 kB] 64s Fetched 253 kB in 0s (5425 kB/s) 65s (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 ... 75541 files and directories currently installed.) 65s Preparing to unpack .../python3-minimal_3.12.7-1_amd64.deb ... 65s Unpacking python3-minimal (3.12.7-1) over (3.12.6-0ubuntu1) ... 65s Setting up python3-minimal (3.12.7-1) ... 65s (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 ... 75541 files and directories currently installed.) 65s Preparing to unpack .../python3_3.12.7-1_amd64.deb ... 65s Unpacking python3 (3.12.7-1) over (3.12.6-0ubuntu1) ... 65s Preparing to unpack .../libpython3-stdlib_3.12.7-1_amd64.deb ... 65s Unpacking libpython3-stdlib:amd64 (3.12.7-1) over (3.12.6-0ubuntu1) ... 65s Selecting previously unselected package python3.13-gdbm. 65s Preparing to unpack .../python3.13-gdbm_3.13.0-2_amd64.deb ... 65s Unpacking python3.13-gdbm (3.13.0-2) ... 65s Preparing to unpack .../python3-gdbm_3.12.7-1_amd64.deb ... 65s Unpacking python3-gdbm:amd64 (3.12.7-1) over (3.12.6-1ubuntu1) ... 65s Preparing to unpack .../libgpgme11t64_1.23.2-5ubuntu4_amd64.deb ... 65s Unpacking libgpgme11t64:amd64 (1.23.2-5ubuntu4) over (1.18.0-4.1ubuntu4) ... 65s Setting up libgpgme11t64:amd64 (1.23.2-5ubuntu4) ... 65s Setting up python3.13-gdbm (3.13.0-2) ... 65s Setting up libpython3-stdlib:amd64 (3.12.7-1) ... 65s Setting up python3 (3.12.7-1) ... 65s Setting up python3-gdbm:amd64 (3.12.7-1) ... 65s Processing triggers for man-db (2.12.1-3) ... 66s Processing triggers for libc-bin (2.40-1ubuntu3) ... 67s Reading package lists... 67s Building dependency tree... 67s Reading state information... 67s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 68s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 68s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 68s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 68s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 69s Reading package lists... 69s Reading package lists... 69s Building dependency tree... 69s Reading state information... 70s Calculating upgrade... 70s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 70s Reading package lists... 71s Building dependency tree... 71s Reading state information... 71s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 72s autopkgtest [09:26:07]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 13:41:20 UTC 2024 72s autopkgtest [09:26:07]: @@@@@@@@@@@@@@@@@@@@ apt-source pyalsaaudio 72s Get:1 http://ftpmaster.internal/ubuntu plucky/universe pyalsaaudio 0.10.0-0.1build2 (dsc) [2158 B] 72s Get:2 http://ftpmaster.internal/ubuntu plucky/universe pyalsaaudio 0.10.0-0.1build2 (tar) [637 kB] 72s Get:3 http://ftpmaster.internal/ubuntu plucky/universe pyalsaaudio 0.10.0-0.1build2 (diff) [4944 B] 73s gpgv: Signature made Mon Apr 1 07:20:52 2024 UTC 73s gpgv: using RSA key A089FB36AAFBDAD5ACC1325069F790171A210984 73s gpgv: Can't check signature: No public key 73s dpkg-source: warning: cannot verify inline signature for ./pyalsaaudio_0.10.0-0.1build2.dsc: no acceptable signature found 73s autopkgtest [09:26:08]: testing package pyalsaaudio version 0.10.0-0.1build2 73s autopkgtest [09:26:08]: build not needed 73s autopkgtest [09:26:08]: test py3xtests: preparing testbed 73s Reading package lists... 74s Building dependency tree... 74s Reading state information... 74s Starting pkgProblemResolver with broken count: 0 74s Starting 2 pkgProblemResolver with broken count: 0 74s Done 75s The following additional packages will be installed: 75s libasound2-data libasound2t64 python3-alsaaudio 75s Suggested packages: 75s alsa-utils libasound2-plugins 75s Recommended packages: 75s alsa-ucm-conf alsa-topology-conf libjs-jquery libjs-underscore 75s The following NEW packages will be installed: 75s autopkgtest-satdep libasound2-data libasound2t64 python3-alsaaudio 75s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 75s Need to get 489 kB/489 kB of archives. 75s After this operation, 1778 kB of additional disk space will be used. 75s Get:1 /tmp/autopkgtest.srSY5l/1-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [708 B] 75s Get:2 http://ftpmaster.internal/ubuntu plucky/main amd64 libasound2-data all 1.2.12-1 [21.0 kB] 75s Get:3 http://ftpmaster.internal/ubuntu plucky/main amd64 libasound2t64 amd64 1.2.12-1 [394 kB] 75s Get:4 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-alsaaudio amd64 0.10.0-0.1build2 [73.6 kB] 75s Fetched 489 kB in 0s (4246 kB/s) 75s Selecting previously unselected package libasound2-data. 75s (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 ... 75548 files and directories currently installed.) 75s Preparing to unpack .../libasound2-data_1.2.12-1_all.deb ... 75s Unpacking libasound2-data (1.2.12-1) ... 75s Selecting previously unselected package libasound2t64:amd64. 75s Preparing to unpack .../libasound2t64_1.2.12-1_amd64.deb ... 75s Unpacking libasound2t64:amd64 (1.2.12-1) ... 75s Selecting previously unselected package python3-alsaaudio. 75s Preparing to unpack .../python3-alsaaudio_0.10.0-0.1build2_amd64.deb ... 75s Unpacking python3-alsaaudio (0.10.0-0.1build2) ... 75s Selecting previously unselected package autopkgtest-satdep. 75s Preparing to unpack .../1-autopkgtest-satdep.deb ... 75s Unpacking autopkgtest-satdep (0) ... 75s Setting up libasound2-data (1.2.12-1) ... 75s Setting up libasound2t64:amd64 (1.2.12-1) ... 75s Setting up python3-alsaaudio (0.10.0-0.1build2) ... 75s Setting up autopkgtest-satdep (0) ... 75s Processing triggers for libc-bin (2.40-1ubuntu3) ... 78s (Reading database ... 75686 files and directories currently installed.) 78s Removing autopkgtest-satdep (0) ... 78s autopkgtest [09:26:13]: test py3xtests: [----------------------- 78s testMixer (__main__.MixerTest.testMixer) 78s Open the default Mixers and the Mixers on every card ... ok 78s testMixerAll (__main__.MixerTest.testMixerAll) 78s Run common Mixer methods on an open object ... ALSA lib confmisc.c:855:(parse_card) cannot find card '0' 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory 78s ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory 78s ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 78s ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such file or directory 78s ALSA lib control.c:1570:(snd_ctl_open_noupdate) Invalid CTL default 78s ERROR 78s testMixerClose (__main__.MixerTest.testMixerClose) 78s Run common Mixer methods on a closed object and verify it raises an ... ALSA lib confmisc.c:855:(parse_card) cannot find card '0' 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory 78s ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory 78s ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 78s ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such file or directory 78s ALSA lib control.c:1570:(snd_ctl_open_noupdate) Invalid CTL default 78s ERROR 78s testPCM (__main__.PCMTest.testPCM) 78s Open a PCM object on every card ... ok 78s testPCMAll (__main__.PCMTest.testPCMAll) 78s Run all PCM methods on an open object ... ALSA lib confmisc.c:855:(parse_card) cannot find card '0' 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory 78s ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory 78s ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 78s ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such file or directory 78s ALSA lib pcm.c:2722:(snd_pcm_open_noupdate) Unknown PCM default 78s ERROR 78s testPCMClose (__main__.PCMTest.testPCMClose) 78s 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' 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory 78s ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory 78s ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 78s ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such file or directory 78s ALSA lib pcm.c:2722:(snd_pcm_open_noupdate) Unknown PCM default 78s ERROR 78s testPCMDeprecated (__main__.PCMTest.testPCMDeprecated) ... ALSA lib confmisc.c:855:(parse_card) cannot find card 'default' 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such device 78s ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such device 78s ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such device 78s ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such device 78s ALSA lib pcm.c:2722:(snd_pcm_open_noupdate) Unknown PCM default:CARD=default 78s ALSA lib confmisc.c:855:(parse_card) cannot find card '0' 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_card_inum returned error: No such file or directory 78s ALSA lib confmisc.c:422:(snd_func_concat) error evaluating strings 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_concat returned error: No such file or directory 78s ALSA lib confmisc.c:1342:(snd_func_refer) error evaluating name 78s ALSA lib conf.c:5204:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 78s ALSA lib conf.c:5727:(snd_config_expand) Evaluate error: No such file or directory 78s ALSA lib pcm.c:2722:(snd_pcm_open_noupdate) Unknown PCM default 78s ERROR 78s 78s ====================================================================== 78s ERROR: testMixerAll (__main__.MixerTest.testMixerAll) 78s Run common Mixer methods on an open object 78s ---------------------------------------------------------------------- 78s Traceback (most recent call last): 78s File "/tmp/autopkgtest.srSY5l/build.40t/src/test.py", line 63, in testMixerAll 78s mixers = alsaaudio.mixers() 78s ^^^^^^^^^^^^^^^^^^ 78s alsaaudio.ALSAAudioError: No such file or directory [default] 78s 78s ====================================================================== 78s ERROR: testMixerClose (__main__.MixerTest.testMixerClose) 78s Run common Mixer methods on a closed object and verify it raises an 78s ---------------------------------------------------------------------- 78s Traceback (most recent call last): 78s File "/tmp/autopkgtest.srSY5l/build.40t/src/test.py", line 79, in testMixerClose 78s mixers = alsaaudio.mixers() 78s ^^^^^^^^^^^^^^^^^^ 78s alsaaudio.ALSAAudioError: No such file or directory [default] 78s 78s ====================================================================== 78s ERROR: testPCMAll (__main__.PCMTest.testPCMAll) 78s Run all PCM methods on an open object 78s ---------------------------------------------------------------------- 78s Traceback (most recent call last): 78s File "/tmp/autopkgtest.srSY5l/build.40t/src/test.py", line 103, in testPCMAll 78s pcm = alsaaudio.PCM() 78s ^^^^^^^^^^^^^^^ 78s alsaaudio.ALSAAudioError: No such file or directory [default] 78s 78s ====================================================================== 78s ERROR: testPCMClose (__main__.PCMTest.testPCMClose) 78s Run all PCM methods on a closed object and verify it raises an error 78s ---------------------------------------------------------------------- 78s Traceback (most recent call last): 78s File "/tmp/autopkgtest.srSY5l/build.40t/src/test.py", line 117, in testPCMClose 78s pcm = alsaaudio.PCM() 78s ^^^^^^^^^^^^^^^ 78s alsaaudio.ALSAAudioError: No such file or directory [default] 78s 78s ====================================================================== 78s ERROR: testPCMDeprecated (__main__.PCMTest.testPCMDeprecated) 78s ---------------------------------------------------------------------- 78s Traceback (most recent call last): 78s File "/tmp/autopkgtest.srSY5l/build.40t/src/test.py", line 146, in testPCMDeprecated 78s pcm = alsaaudio.PCM() 78s ^^^^^^^^^^^^^^^ 78s alsaaudio.ALSAAudioError: No such file or directory [default] 78s 78s ---------------------------------------------------------------------- 78s Ran 7 tests in 0.005s 78s 78s FAILED (errors=5) 79s autopkgtest [09:26:14]: test py3xtests: -----------------------] 79s autopkgtest [09:26:14]: test py3xtests: - - - - - - - - - - results - - - - - - - - - - 79s py3xtests FLAKY non-zero exit status 1 79s autopkgtest [09:26:14]: @@@@@@@@@@@@@@@@@@@@ summary 79s py3xtests FLAKY non-zero exit status 1 90s virt: nova [W] Skipping flock for amd64 90s virt: Creating nova instance adt-plucky-amd64-pyalsaaudio-20241113-092455-juju-7f2275-prod-proposed-migration-environment-2-964d7f80-5dab-4298-a109-3ce8aa4090f1 from image adt/ubuntu-plucky-amd64-server-20241113.img (UUID 76b850f9-98f4-4b79-af06-fa11000b95b2)...