0s autopkgtest [12:38:47]: starting date and time: 2024-11-13 12:38:47+0000 0s autopkgtest [12:38:47]: git checkout: 6f3be7a8 Fix armhf LXD image generation for plucky 0s autopkgtest [12:38:47]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.2trfd0ji/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 pyscard --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-20@bos03-s390x-13.secgroup --name adt-plucky-s390x-pyscard-20241113-123847-juju-7f2275-prod-proposed-migration-environment-20-cad8b0d1-ee78-4967-a806-42b3d0cf58dd --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --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/ 116s autopkgtest [12:40:43]: testbed dpkg architecture: s390x 116s autopkgtest [12:40:43]: testbed apt version: 2.9.8 116s autopkgtest [12:40:43]: @@@@@@@@@@@@@@@@@@@@ test bed setup 117s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 118s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [16.5 kB] 118s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [104 kB] 118s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [967 kB] 118s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 118s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [107 kB] 118s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [641 kB] 118s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [17.4 kB] 118s Fetched 1934 kB in 1s (2220 kB/s) 118s Reading package lists... 120s Reading package lists... 120s Building dependency tree... 120s Reading state information... 120s Calculating upgrade... 120s The following NEW packages will be installed: 120s python3.13-gdbm 120s The following packages will be upgraded: 120s libgpgme11t64 libpython3-stdlib python3 python3-gdbm python3-minimal 121s 5 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 121s Need to get 252 kB of archives. 121s After this operation, 98.3 kB of additional disk space will be used. 121s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-minimal s390x 3.12.7-1 [27.4 kB] 121s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3 s390x 3.12.7-1 [24.0 kB] 121s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x libpython3-stdlib s390x 3.12.7-1 [10.0 kB] 121s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3.13-gdbm s390x 3.13.0-2 [31.0 kB] 121s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x python3-gdbm s390x 3.12.7-1 [8642 B] 121s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x libgpgme11t64 s390x 1.23.2-5ubuntu4 [151 kB] 121s Fetched 252 kB in 0s (626 kB/s) 121s (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.) 121s Preparing to unpack .../python3-minimal_3.12.7-1_s390x.deb ... 121s Unpacking python3-minimal (3.12.7-1) over (3.12.6-0ubuntu1) ... 121s Setting up python3-minimal (3.12.7-1) ... 121s (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.) 121s Preparing to unpack .../python3_3.12.7-1_s390x.deb ... 122s Unpacking python3 (3.12.7-1) over (3.12.6-0ubuntu1) ... 122s Preparing to unpack .../libpython3-stdlib_3.12.7-1_s390x.deb ... 122s Unpacking libpython3-stdlib:s390x (3.12.7-1) over (3.12.6-0ubuntu1) ... 122s Selecting previously unselected package python3.13-gdbm. 122s Preparing to unpack .../python3.13-gdbm_3.13.0-2_s390x.deb ... 122s Unpacking python3.13-gdbm (3.13.0-2) ... 122s Preparing to unpack .../python3-gdbm_3.12.7-1_s390x.deb ... 122s Unpacking python3-gdbm:s390x (3.12.7-1) over (3.12.6-1ubuntu1) ... 122s Preparing to unpack .../libgpgme11t64_1.23.2-5ubuntu4_s390x.deb ... 122s Unpacking libgpgme11t64:s390x (1.23.2-5ubuntu4) over (1.18.0-4.1ubuntu4) ... 122s Setting up libgpgme11t64:s390x (1.23.2-5ubuntu4) ... 122s Setting up python3.13-gdbm (3.13.0-2) ... 122s Setting up libpython3-stdlib:s390x (3.12.7-1) ... 122s Setting up python3 (3.12.7-1) ... 122s Setting up python3-gdbm:s390x (3.12.7-1) ... 122s Processing triggers for man-db (2.12.1-3) ... 122s Processing triggers for libc-bin (2.40-1ubuntu3) ... 122s Reading package lists... 123s Building dependency tree... 123s Reading state information... 123s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 123s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 123s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 123s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 123s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 124s Reading package lists... 124s Reading package lists... 124s Building dependency tree... 124s Reading state information... 124s Calculating upgrade... 125s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 125s Reading package lists... 125s Building dependency tree... 125s Reading state information... 125s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 127s autopkgtest [12:40:54]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 127s autopkgtest [12:40:54]: @@@@@@@@@@@@@@@@@@@@ apt-source pyscard 129s Get:1 http://ftpmaster.internal/ubuntu plucky/universe pyscard 2.2.0-2 (dsc) [2390 B] 129s Get:2 http://ftpmaster.internal/ubuntu plucky/universe pyscard 2.2.0-2 (tar) [155 kB] 129s Get:3 http://ftpmaster.internal/ubuntu plucky/universe pyscard 2.2.0-2 (asc) [833 B] 129s Get:4 http://ftpmaster.internal/ubuntu plucky/universe pyscard 2.2.0-2 (diff) [8624 B] 129s gpgv: Signature made Wed Oct 23 12:21:14 2024 UTC 129s gpgv: using RSA key F5E11B9FFE911146F41D953D78A1B4DFE8F9C57E 129s gpgv: issuer "rousseau@debian.org" 129s gpgv: Can't check signature: No public key 129s dpkg-source: warning: cannot verify inline signature for ./pyscard_2.2.0-2.dsc: no acceptable signature found 129s autopkgtest [12:40:56]: testing package pyscard version 2.2.0-2 130s autopkgtest [12:40:57]: build not needed 130s autopkgtest [12:40:57]: test pyscard-tests: preparing testbed 131s Reading package lists... 132s Building dependency tree... 132s Reading state information... 132s Starting pkgProblemResolver with broken count: 0 132s Starting 2 pkgProblemResolver with broken count: 0 132s Done 132s The following additional packages will be installed: 132s libccid libpcsclite1 pcscd python3-iniconfig python3-packaging 132s python3-pluggy python3-pyscard python3-pytest 132s Suggested packages: 132s pcmciautils python3-wxgtk4.0 132s The following NEW packages will be installed: 132s autopkgtest-satdep libccid libpcsclite1 pcscd python3-iniconfig 132s python3-packaging python3-pluggy python3-pyscard python3-pytest 132s 0 upgraded, 9 newly installed, 0 to remove and 0 not upgraded. 132s Need to get 579 kB/580 kB of archives. 132s After this operation, 2825 kB of additional disk space will be used. 132s Get:1 /tmp/autopkgtest.EIoUJZ/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [740 B] 132s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libpcsclite1 s390x 2.3.0-1 [24.0 kB] 132s Get:3 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 132s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x python3-packaging all 24.1-1 [41.4 kB] 133s Get:5 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-pluggy all 1.5.0-1 [21.0 kB] 133s Get:6 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-pyscard s390x 2.2.0-2 [86.0 kB] 133s Get:7 http://ftpmaster.internal/ubuntu plucky/universe s390x python3-pytest all 8.3.3-1 [251 kB] 133s Get:8 http://ftpmaster.internal/ubuntu plucky/universe s390x libccid s390x 1.6.1-2 [90.2 kB] 133s Get:9 http://ftpmaster.internal/ubuntu plucky/universe s390x pcscd s390x 2.3.0-1 [59.0 kB] 133s Fetched 579 kB in 1s (830 kB/s) 133s Selecting previously unselected package libpcsclite1:s390x. 133s (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.) 133s Preparing to unpack .../0-libpcsclite1_2.3.0-1_s390x.deb ... 133s Unpacking libpcsclite1:s390x (2.3.0-1) ... 133s Selecting previously unselected package python3-iniconfig. 133s Preparing to unpack .../1-python3-iniconfig_1.1.1-2_all.deb ... 133s Unpacking python3-iniconfig (1.1.1-2) ... 133s Selecting previously unselected package python3-packaging. 133s Preparing to unpack .../2-python3-packaging_24.1-1_all.deb ... 133s Unpacking python3-packaging (24.1-1) ... 133s Selecting previously unselected package python3-pluggy. 133s Preparing to unpack .../3-python3-pluggy_1.5.0-1_all.deb ... 133s Unpacking python3-pluggy (1.5.0-1) ... 133s Selecting previously unselected package python3-pyscard. 133s Preparing to unpack .../4-python3-pyscard_2.2.0-2_s390x.deb ... 133s Unpacking python3-pyscard (2.2.0-2) ... 133s Selecting previously unselected package python3-pytest. 133s Preparing to unpack .../5-python3-pytest_8.3.3-1_all.deb ... 133s Unpacking python3-pytest (8.3.3-1) ... 133s Selecting previously unselected package libccid. 133s Preparing to unpack .../6-libccid_1.6.1-2_s390x.deb ... 133s Unpacking libccid (1.6.1-2) ... 133s Selecting previously unselected package pcscd. 133s Preparing to unpack .../7-pcscd_2.3.0-1_s390x.deb ... 133s Unpacking pcscd (2.3.0-1) ... 133s Selecting previously unselected package autopkgtest-satdep. 133s Preparing to unpack .../8-1-autopkgtest-satdep.deb ... 133s Unpacking autopkgtest-satdep (0) ... 133s Setting up python3-iniconfig (1.1.1-2) ... 133s Setting up libccid (1.6.1-2) ... 133s Setting up python3-packaging (24.1-1) ... 133s Setting up libpcsclite1:s390x (2.3.0-1) ... 133s Setting up python3-pluggy (1.5.0-1) ... 133s Setting up python3-pyscard (2.2.0-2) ... 133s Setting up python3-pytest (8.3.3-1) ... 134s Setting up pcscd (2.3.0-1) ... 134s Created symlink '/etc/systemd/system/sockets.target.wants/pcscd.socket' → '/usr/lib/systemd/system/pcscd.socket'. 134s pcscd.service is a disabled or a static unit, not starting it. 134s Setting up autopkgtest-satdep (0) ... 134s Processing triggers for man-db (2.12.1-3) ... 135s Processing triggers for libc-bin (2.40-1ubuntu3) ... 137s (Reading database ... 55843 files and directories currently installed.) 137s Removing autopkgtest-satdep (0) ... 138s autopkgtest [12:41:05]: test pyscard-tests: [----------------------- 138s PCSCD_ARGS=--disable-polkit 138s ============================= test session starts ============================== 138s platform linux -- Python 3.12.7, pytest-8.3.3, pluggy-1.5.0 -- /usr/bin/python3 138s cachedir: .pytest_cache 138s rootdir: /tmp/autopkgtest.EIoUJZ/build.mP2/src 138s configfile: pyproject.toml 138s plugins: typeguard-4.4.1 138s collecting ... collected 83 items 138s 138s test/test_ATR.py::test_atr1 PASSED [ 1%] 138s test/test_ATR.py::test_atr2 PASSED [ 2%] 138s test/test_ATR.py::test_atr3 PASSED [ 3%] 138s test/test_ATR.py::test_atr4 PASSED [ 4%] 138s test/test_ATR.py::test_atr5 PASSED [ 6%] 138s test/test_ATR.py::test_atr6 PASSED [ 7%] 138s test/test_ATR.py::test_atr_ts PASSED [ 8%] 138s test/test_ATR.py::test_atr_get PASSED [ 9%] 138s test/test_ATR.py::test_map_lengths[clockrateconversion-16] PASSED [ 10%] 138s test/test_ATR.py::test_map_lengths[bitratefactor-16] PASSED [ 12%] 138s test/test_Exceptions.py::test_hresult_value PASSED [ 13%] 138s test/test_Exceptions.py::test_list_readers_exception PASSED [ 14%] 138s test/test_Exceptions.py::test_no_readers_exception PASSED [ 15%] 138s test/test_Exceptions.py::test_invalid_reader_exception PASSED [ 16%] 138s test/test_Exceptions.py::test_card_connection_exception PASSED [ 18%] 138s test/test_Exceptions.py::test_hresult PASSED [ 19%] 138s test/test_Exceptions.py::test_wrong_type[arg0] PASSED [ 20%] 138s test/test_Exceptions.py::test_wrong_type[foo] PASSED [ 21%] 138s test/test_Exceptions.py::test_card_request_timeout_exception PASSED [ 22%] 138s test/test_Exceptions.py::test_invalid_atr_mask_length_exception PASSED [ 24%] 138s test/test_Exceptions.py::test_no_card_exception PASSED [ 25%] 138s test/test_PCSC.py::test_low_level PASSED [ 26%] 138s test/test_PCSCExceptions.py::test_list_readers_exception PASSED [ 27%] 138s test/test_PCSCExceptions.py::test_establish_context_exception PASSED [ 28%] 138s test/test_PCSCExceptions.py::test_introduce_reader_exception PASSED [ 30%] 138s test/test_PCSCExceptions.py::test_remove_reader_from_group_exception PASSED [ 31%] 138s test/test_PCSCExceptions.py::test_add_reader_to_group_exception PASSED [ 32%] 138s test/test_PCSCExceptions.py::test_release_context_exception PASSED [ 33%] 138s test/test_PCSCExceptions.py::test_base_scard_exception PASSED [ 34%] 138s test/test_SCardGetErrorMessage.py::test_scard_get_error_message PASSED [ 36%] 138s test/test_guid.py::test_roundtrip_string PASSED [ 37%] 138s test/test_guid.py::test_roundtrip_list_of_ints PASSED [ 38%] 138s test/test_observer.py::test_state_changes PASSED [ 39%] 138s test/test_observer.py::test_synchronization[addObserver] PASSED [ 40%] 138s test/test_observer.py::test_synchronization[countObservers] PASSED [ 42%] 138s test/test_observer.py::test_synchronization[deleteObserver] PASSED [ 43%] 138s test/test_observer.py::test_synchronization[deleteObservers] PASSED [ 44%] 138s test/test_observer.py::test_synchronization[notifyObservers] PASSED [ 45%] 138s test/test_observer.py::test_synchronization[clearChanged] PASSED [ 46%] 138s test/test_observer.py::test_synchronization[hasChanged] PASSED [ 48%] 138s test/test_observer.py::test_synchronization[setChanged] PASSED [ 49%] 138s test/test_observer.py::test_registered_observers_are_always_notified PASSED [ 50%] 138s test/test_observer.py::test_double_observer_additions PASSED [ 51%] 138s test/test_observer.py::test_double_observer_removals PASSED [ 53%] 138s test/test_observer.py::test_no_notifications_when_no_changes PASSED [ 54%] 138s test/test_observer.py::test_default_observer_updates PASSED [ 55%] 138s test/test_readergroups.py::test_reader_groups_acts_like_a_singleton PASSED [ 56%] 138s test/test_readergroups.py::test_reader_groups_method_calls PASSED [ 57%] 138s test/test_readergroups.py::test_reader_groups_is_a_singleton XFAIL (...) [ 59%] 138s test/test_readergroups.py::test_demonstrate_initlist_values_may_be_silently_ignored XFAIL [ 60%] 138s test/test_readergroups.py::test_demonstrate_adding_is_impossible PASSED [ 61%] 138s test/test_readergroups.py::test_demonstrate_removing_is_impossible PASSED [ 62%] 138s test/test_readergroups.py::test_demonstrate_getting_is_impossible PASSED [ 63%] 138s test/test_synchronization.py::test_synchronize[wrap nothing (str)] PASSED [ 65%] 138s test/test_synchronization.py::test_synchronize[wrap nothing (a, str)] PASSED [ 66%] 138s test/test_synchronization.py::test_synchronize[wrap nothing (a+b, str)] PASSED [ 67%] 138s test/test_synchronization.py::test_synchronize[wrap nothing (list)] PASSED [ 68%] 138s test/test_synchronization.py::test_synchronize[wrap nothing (a, list)] PASSED [ 69%] 138s test/test_synchronization.py::test_synchronize[wrap nothing (a+b, list)] PASSED [ 71%] 138s test/test_synchronization.py::test_synchronize[wrap all] PASSED [ 72%] 138s test/test_synchronization.py::test_synchronize[wrap all (a)] PASSED [ 73%] 138s test/test_synchronization.py::test_synchronize[wrap all (a+b)] PASSED [ 74%] 138s test/test_synchronization.py::test_synchronize[wrap a only (a, str)] PASSED [ 75%] 138s test/test_synchronization.py::test_synchronize[wrap a only (a+b, str)] PASSED [ 77%] 138s test/test_synchronization.py::test_synchronize[wrap a only (a, list)] PASSED [ 78%] 138s test/test_synchronization.py::test_synchronize[wrap a only (a+b, list)] PASSED [ 79%] 138s test/test_synchronization.py::test_synchronization_reentrant_lock PASSED [ 80%] 138s test/test_synchronization.py::test_synchronization_wrapping PASSED [ 81%] 138s test/test_synchronization.py::test_synchronization_kwargs PASSED [ 83%] 138s test/test_ulist.py::test_demonstrate_ulist_cannot_be_instantiated PASSED [ 84%] 138s test/test_ulist.py::test_demonstrate_order_is_not_respected XFAIL (o...) [ 85%] 138s test/test_ulist.py::test_demonstrate_non_unique_behavior XFAIL (not ...) [ 86%] 138s test/test_ulist.py::test_ulist_methods PASSED [ 87%] 138s test/test_util.py::test_to_bytes PASSED [ 89%] 138s test/test_util.py::test_padd PASSED [ 90%] 138s test/test_util.py::test_to_ascii_bytes PASSED [ 91%] 138s test/test_util.py::test_to_ascii_string PASSED [ 92%] 138s test/test_util.py::test_to_gsm3_38_bytes PASSED [ 93%] 138s test/test_util.py::test_to_hex_string PASSED [ 95%] 138s test/test_util.py::test_hex_list_to_bin_string PASSED [ 96%] 138s test/test_util.py::test_bin_string_to_hex_list PASSED [ 97%] 138s test/test_util.py::test_hl2bs PASSED [ 98%] 138s test/test_util.py::test_bs2hl PASSED [100%] 138s 138s ======================== 79 passed, 4 xfailed in 0.25s ========================= 139s autopkgtest [12:41:06]: test pyscard-tests: -----------------------] 139s autopkgtest [12:41:06]: test pyscard-tests: - - - - - - - - - - results - - - - - - - - - - 139s pyscard-tests PASS 139s autopkgtest [12:41:06]: @@@@@@@@@@@@@@@@@@@@ summary 139s pyscard-tests PASS 151s nova [W] Using flock in prodstack6-s390x 151s Creating nova instance adt-plucky-s390x-pyscard-20241113-123847-juju-7f2275-prod-proposed-migration-environment-20-cad8b0d1-ee78-4967-a806-42b3d0cf58dd from image adt/ubuntu-plucky-s390x-server-20241113.img (UUID e740277e-1f72-40ae-bfbe-46030537c71c)...