0s autopkgtest [09:21:44]: starting date and time: 2025-05-04 09:21:44+0000 0s autopkgtest [09:21:44]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [09:21:44]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.p3cvu2ro/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:pytest --apt-upgrade pyscard --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=pytest/8.3.5-2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-ppc64el-2.secgroup --name adt-questing-ppc64el-pyscard-20250504-092144-juju-7f2275-prod-proposed-migration-environment-15-15255c5c-c5ae-4aa5-a1e4-694da4084aea --image adt/ubuntu-questing-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 75s autopkgtest [09:22:59]: testbed dpkg architecture: ppc64el 75s autopkgtest [09:22:59]: testbed apt version: 3.0.0 75s autopkgtest [09:22:59]: @@@@@@@@@@@@@@@@@@@@ test bed setup 75s autopkgtest [09:22:59]: testbed release detected to be: None 76s autopkgtest [09:23:00]: updating testbed package index (apt update) 76s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 77s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 77s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 77s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 77s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [33.3 kB] 77s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [1235 kB] 77s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [130 kB] 77s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el Packages [173 kB] 77s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el Packages [1216 kB] 77s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse ppc64el Packages [31.7 kB] 77s Fetched 2929 kB in 1s (2537 kB/s) 78s Reading package lists... 79s autopkgtest [09:23:03]: upgrading testbed (apt dist-upgrade and autopurge) 79s Reading package lists... 79s Building dependency tree... 79s Reading state information... 79s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 79s Starting 2 pkgProblemResolver with broken count: 0 79s Done 80s Entering ResolveByKeep 80s 80s Calculating upgrade... 80s The following packages will be upgraded: 80s libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 publicsuffix 80s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 80s Need to get 5638 kB of archives. 80s After this operation, 221 kB disk space will be freed. 80s Get:1 http://ftpmaster.internal/ubuntu questing/main ppc64el publicsuffix all 20250328.1952-0.1 [135 kB] 80s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el libpython3.12t64 ppc64el 3.12.10-1 [2558 kB] 81s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el libpython3.12-stdlib ppc64el 3.12.10-1 [2105 kB] 81s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el libpython3.12-minimal ppc64el 3.12.10-1 [841 kB] 82s Fetched 5638 kB in 1s (4763 kB/s) 82s (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 ... 107204 files and directories currently installed.) 82s Preparing to unpack .../publicsuffix_20250328.1952-0.1_all.deb ... 82s Unpacking publicsuffix (20250328.1952-0.1) over (20250108.1153-0.1) ... 82s Preparing to unpack .../libpython3.12t64_3.12.10-1_ppc64el.deb ... 82s Unpacking libpython3.12t64:ppc64el (3.12.10-1) over (3.12.8-3) ... 82s Preparing to unpack .../libpython3.12-stdlib_3.12.10-1_ppc64el.deb ... 82s Unpacking libpython3.12-stdlib:ppc64el (3.12.10-1) over (3.12.8-3) ... 82s Preparing to unpack .../libpython3.12-minimal_3.12.10-1_ppc64el.deb ... 82s Unpacking libpython3.12-minimal:ppc64el (3.12.10-1) over (3.12.8-3) ... 82s Setting up libpython3.12-minimal:ppc64el (3.12.10-1) ... 82s Setting up publicsuffix (20250328.1952-0.1) ... 82s Setting up libpython3.12-stdlib:ppc64el (3.12.10-1) ... 82s Setting up libpython3.12t64:ppc64el (3.12.10-1) ... 82s Processing triggers for libc-bin (2.41-6ubuntu1) ... 82s Reading package lists... 83s Building dependency tree... 83s Reading state information... 83s Starting pkgProblemResolver with broken count: 0 83s Starting 2 pkgProblemResolver with broken count: 0 83s Done 83s Solving dependencies... 83s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 86s autopkgtest [09:23:10]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr 6 14:52:42 UTC 2025 86s autopkgtest [09:23:10]: @@@@@@@@@@@@@@@@@@@@ apt-source pyscard 87s Get:1 http://ftpmaster.internal/ubuntu questing/universe pyscard 2.2.1-1build1 (dsc) [2385 B] 87s Get:2 http://ftpmaster.internal/ubuntu questing/universe pyscard 2.2.1-1build1 (tar) [157 kB] 87s Get:3 http://ftpmaster.internal/ubuntu questing/universe pyscard 2.2.1-1build1 (asc) [833 B] 87s Get:4 http://ftpmaster.internal/ubuntu questing/universe pyscard 2.2.1-1build1 (diff) [8704 B] 88s gpgv: Signature made Tue Mar 4 23:07:34 2025 UTC 88s gpgv: using RSA key 25E3FF2D7F469DBE7D0D4E50AFCFEC8E669CE1C2 88s gpgv: Can't check signature: No public key 88s dpkg-source: warning: cannot verify inline signature for ./pyscard_2.2.1-1build1.dsc: no acceptable signature found 88s autopkgtest [09:23:12]: testing package pyscard version 2.2.1-1build1 88s autopkgtest [09:23:12]: build not needed 88s autopkgtest [09:23:12]: test pyscard-tests: preparing testbed 89s Reading package lists... 89s Building dependency tree... 89s Reading state information... 89s Starting pkgProblemResolver with broken count: 0 89s Starting 2 pkgProblemResolver with broken count: 0 89s Done 89s The following NEW packages will be installed: 89s libccid libpcsclite1 pcscd python3-iniconfig python3-packaging 89s python3-pluggy python3-pyscard python3-pytest 89s 0 upgraded, 8 newly installed, 0 to remove and 0 not upgraded. 89s Need to get 615 kB of archives. 89s After this operation, 3183 kB of additional disk space will be used. 89s Get:1 http://ftpmaster.internal/ubuntu questing/universe ppc64el libccid ppc64el 1.6.2-1 [94.1 kB] 90s Get:2 http://ftpmaster.internal/ubuntu questing/main ppc64el libpcsclite1 ppc64el 2.3.1-1 [31.4 kB] 90s Get:3 http://ftpmaster.internal/ubuntu questing/universe ppc64el pcscd ppc64el 2.3.1-1 [68.6 kB] 90s Get:4 http://ftpmaster.internal/ubuntu questing/universe ppc64el python3-iniconfig all 1.1.1-2 [6024 B] 90s Get:5 http://ftpmaster.internal/ubuntu questing/main ppc64el python3-packaging all 24.2-1 [51.5 kB] 90s Get:6 http://ftpmaster.internal/ubuntu questing/universe ppc64el python3-pluggy all 1.5.0-1 [21.0 kB] 90s Get:7 http://ftpmaster.internal/ubuntu questing/universe ppc64el python3-pyscard ppc64el 2.2.1-1build1 [90.0 kB] 90s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el python3-pytest all 8.3.5-2 [252 kB] 90s Fetched 615 kB in 0s (1279 kB/s) 90s Selecting previously unselected package libccid. 90s (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 ... 107204 files and directories currently installed.) 90s Preparing to unpack .../0-libccid_1.6.2-1_ppc64el.deb ... 90s Unpacking libccid (1.6.2-1) ... 90s Selecting previously unselected package libpcsclite1:ppc64el. 90s Preparing to unpack .../1-libpcsclite1_2.3.1-1_ppc64el.deb ... 90s Unpacking libpcsclite1:ppc64el (2.3.1-1) ... 90s Selecting previously unselected package pcscd. 90s Preparing to unpack .../2-pcscd_2.3.1-1_ppc64el.deb ... 90s Unpacking pcscd (2.3.1-1) ... 90s Selecting previously unselected package python3-iniconfig. 90s Preparing to unpack .../3-python3-iniconfig_1.1.1-2_all.deb ... 90s Unpacking python3-iniconfig (1.1.1-2) ... 90s Selecting previously unselected package python3-packaging. 90s Preparing to unpack .../4-python3-packaging_24.2-1_all.deb ... 90s Unpacking python3-packaging (24.2-1) ... 90s Selecting previously unselected package python3-pluggy. 90s Preparing to unpack .../5-python3-pluggy_1.5.0-1_all.deb ... 90s Unpacking python3-pluggy (1.5.0-1) ... 90s Selecting previously unselected package python3-pyscard. 90s Preparing to unpack .../6-python3-pyscard_2.2.1-1build1_ppc64el.deb ... 90s Unpacking python3-pyscard (2.2.1-1build1) ... 90s Selecting previously unselected package python3-pytest. 90s Preparing to unpack .../7-python3-pytest_8.3.5-2_all.deb ... 90s Unpacking python3-pytest (8.3.5-2) ... 90s Setting up python3-iniconfig (1.1.1-2) ... 90s Setting up libccid (1.6.2-1) ... 90s Setting up python3-packaging (24.2-1) ... 90s Setting up libpcsclite1:ppc64el (2.3.1-1) ... 90s Setting up python3-pluggy (1.5.0-1) ... 91s Setting up python3-pyscard (2.2.1-1build1) ... 91s Setting up python3-pytest (8.3.5-2) ... 91s Setting up pcscd (2.3.1-1) ... 91s Created symlink '/etc/systemd/system/sockets.target.wants/pcscd.socket' → '/usr/lib/systemd/system/pcscd.socket'. 92s pcscd.service is a disabled or a static unit, not starting it. 92s Processing triggers for libc-bin (2.41-6ubuntu1) ... 92s Processing triggers for man-db (2.13.0-1) ... 94s autopkgtest [09:23:18]: test pyscard-tests: [----------------------- 95s PCSCD_ARGS=--disable-polkit 95s ============================= test session starts ============================== 95s platform linux -- Python 3.13.3, pytest-8.3.5, pluggy-1.5.0 -- /usr/bin/python3 95s cachedir: .pytest_cache 95s rootdir: /tmp/autopkgtest.6OZojq/build.zvZ/src 95s configfile: pyproject.toml 95s plugins: typeguard-4.4.2 95s collecting ... collected 102 items 95s 95s test/test_ATR.py::test_atr1 PASSED [ 0%] 95s test/test_ATR.py::test_atr2 PASSED [ 1%] 95s test/test_ATR.py::test_atr3 PASSED [ 2%] 95s test/test_ATR.py::test_atr4 PASSED [ 3%] 95s test/test_ATR.py::test_atr5 PASSED [ 4%] 95s test/test_ATR.py::test_atr6 PASSED [ 5%] 95s test/test_ATR.py::test_invalid_ts[numeric] PASSED [ 6%] 95s test/test_ATR.py::test_invalid_ts[lowercase] PASSED [ 7%] 95s test/test_ATR.py::test_invalid_ts[zero padding] PASSED [ 8%] 95s test/test_ATR.py::test_atr_get PASSED [ 9%] 95s test/test_ATR.py::test_map_lengths[clockrateconversion-16] PASSED [ 10%] 95s test/test_ATR.py::test_map_lengths[bitratefactor-16] PASSED [ 11%] 95s test/test_ATR.py::test_invalid_atr_lengths[ATR is too short (0 bytes)] PASSED [ 12%] 95s test/test_ATR.py::test_invalid_atr_lengths[ATR is too short (1 byte, valid TS)] PASSED [ 13%] 95s test/test_ATR.py::test_2_bytes[59] PASSED [ 14%] 95s test/test_ATR.py::test_2_bytes[63] PASSED [ 15%] 95s test/test_ATR.py::test_only_ta1 PASSED [ 16%] 95s test/test_ATR.py::test_only_tb1 PASSED [ 17%] 95s test/test_ATR.py::test_only_tc1 PASSED [ 18%] 95s test/test_ATR.py::test_only_td1 PASSED [ 19%] 95s test/test_ATR.py::test_historical_bytes PASSED [ 20%] 95s test/test_ATR.py::test_valid_checksums[atr_bytes0-59] PASSED [ 21%] 95s test/test_ATR.py::test_valid_checksums[atr_bytes0-63] PASSED [ 22%] 95s test/test_ATR.py::test_valid_checksums[atr_bytes1-59] PASSED [ 23%] 95s test/test_ATR.py::test_valid_checksums[atr_bytes1-63] PASSED [ 24%] 95s test/test_ATR.py::test_invalid_checksums[atr_bytes0-59] PASSED [ 25%] 95s test/test_ATR.py::test_invalid_checksums[atr_bytes0-63] PASSED [ 26%] 95s test/test_ATR.py::test_invalid_checksums[atr_bytes1-59] PASSED [ 27%] 95s test/test_ATR.py::test_invalid_checksums[atr_bytes1-63] PASSED [ 28%] 95s test/test_Exceptions.py::test_hresult_value PASSED [ 29%] 95s test/test_Exceptions.py::test_list_readers_exception PASSED [ 30%] 95s test/test_Exceptions.py::test_no_readers_exception PASSED [ 31%] 95s test/test_Exceptions.py::test_invalid_reader_exception PASSED [ 32%] 95s test/test_Exceptions.py::test_card_connection_exception PASSED [ 33%] 95s test/test_Exceptions.py::test_hresult PASSED [ 34%] 95s test/test_Exceptions.py::test_wrong_type[arg0] PASSED [ 35%] 95s test/test_Exceptions.py::test_wrong_type[foo] PASSED [ 36%] 95s test/test_Exceptions.py::test_card_request_timeout_exception PASSED [ 37%] 95s test/test_Exceptions.py::test_invalid_atr_mask_length_exception PASSED [ 38%] 95s test/test_Exceptions.py::test_no_card_exception PASSED [ 39%] 95s test/test_PCSC.py::test_low_level PASSED [ 40%] 95s test/test_PCSCExceptions.py::test_list_readers_exception PASSED [ 41%] 95s test/test_PCSCExceptions.py::test_establish_context_exception PASSED [ 42%] 95s test/test_PCSCExceptions.py::test_introduce_reader_exception PASSED [ 43%] 95s test/test_PCSCExceptions.py::test_remove_reader_from_group_exception PASSED [ 44%] 95s test/test_PCSCExceptions.py::test_add_reader_to_group_exception PASSED [ 45%] 95s test/test_PCSCExceptions.py::test_release_context_exception PASSED [ 46%] 95s test/test_PCSCExceptions.py::test_base_scard_exception PASSED [ 47%] 95s test/test_SCardGetErrorMessage.py::test_scard_get_error_message PASSED [ 48%] 95s test/test_guid.py::test_roundtrip_string PASSED [ 49%] 95s test/test_guid.py::test_roundtrip_list_of_ints PASSED [ 50%] 95s test/test_observer.py::test_state_changes PASSED [ 50%] 95s test/test_observer.py::test_synchronization[addObserver] PASSED [ 51%] 95s test/test_observer.py::test_synchronization[countObservers] PASSED [ 52%] 95s test/test_observer.py::test_synchronization[deleteObserver] PASSED [ 53%] 95s test/test_observer.py::test_synchronization[deleteObservers] PASSED [ 54%] 95s test/test_observer.py::test_synchronization[notifyObservers] PASSED [ 55%] 95s test/test_observer.py::test_synchronization[clearChanged] PASSED [ 56%] 95s test/test_observer.py::test_synchronization[hasChanged] PASSED [ 57%] 95s test/test_observer.py::test_synchronization[setChanged] PASSED [ 58%] 95s test/test_observer.py::test_registered_observers_are_always_notified PASSED [ 59%] 95s test/test_observer.py::test_double_observer_additions PASSED [ 60%] 95s test/test_observer.py::test_double_observer_removals PASSED [ 61%] 95s test/test_observer.py::test_no_notifications_when_no_changes PASSED [ 62%] 95s test/test_observer.py::test_default_observer_updates PASSED [ 63%] 95s test/test_readergroups.py::test_reader_groups_acts_like_a_singleton PASSED [ 64%] 95s test/test_readergroups.py::test_reader_groups_method_calls PASSED [ 65%] 95s test/test_readergroups.py::test_reader_groups_is_a_singleton XFAIL (...) [ 66%] 95s test/test_readergroups.py::test_demonstrate_initlist_values_may_be_silently_ignored XFAIL [ 67%] 95s test/test_readergroups.py::test_demonstrate_adding_is_impossible PASSED [ 68%] 95s test/test_readergroups.py::test_demonstrate_removing_is_impossible PASSED [ 69%] 95s test/test_readergroups.py::test_demonstrate_getting_is_impossible PASSED [ 70%] 95s test/test_synchronization.py::test_synchronize[wrap nothing (str)] PASSED [ 71%] 95s test/test_synchronization.py::test_synchronize[wrap nothing (a, str)] PASSED [ 72%] 95s test/test_synchronization.py::test_synchronize[wrap nothing (a+b, str)] PASSED [ 73%] 95s test/test_synchronization.py::test_synchronize[wrap nothing (list)] PASSED [ 74%] 95s test/test_synchronization.py::test_synchronize[wrap nothing (a, list)] PASSED [ 75%] 95s test/test_synchronization.py::test_synchronize[wrap nothing (a+b, list)] PASSED [ 76%] 95s test/test_synchronization.py::test_synchronize[wrap all] PASSED [ 77%] 95s test/test_synchronization.py::test_synchronize[wrap all (a)] PASSED [ 78%] 95s test/test_synchronization.py::test_synchronize[wrap all (a+b)] PASSED [ 79%] 95s test/test_synchronization.py::test_synchronize[wrap a only (a, str)] PASSED [ 80%] 95s test/test_synchronization.py::test_synchronize[wrap a only (a+b, str)] PASSED [ 81%] 95s test/test_synchronization.py::test_synchronize[wrap a only (a, list)] PASSED [ 82%] 95s test/test_synchronization.py::test_synchronize[wrap a only (a+b, list)] PASSED [ 83%] 95s test/test_synchronization.py::test_synchronization_reentrant_lock PASSED [ 84%] 95s test/test_synchronization.py::test_synchronization_wrapping PASSED [ 85%] 95s test/test_synchronization.py::test_synchronization_kwargs PASSED [ 86%] 95s test/test_ulist.py::test_demonstrate_ulist_cannot_be_instantiated PASSED [ 87%] 95s test/test_ulist.py::test_demonstrate_order_is_not_respected XFAIL (o...) [ 88%] 95s test/test_ulist.py::test_demonstrate_non_unique_behavior XFAIL (not ...) [ 89%] 95s test/test_ulist.py::test_ulist_methods PASSED [ 90%] 95s test/test_util.py::test_to_bytes PASSED [ 91%] 95s test/test_util.py::test_padd PASSED [ 92%] 95s test/test_util.py::test_to_ascii_bytes PASSED [ 93%] 95s test/test_util.py::test_to_ascii_string PASSED [ 94%] 95s test/test_util.py::test_to_gsm3_38_bytes PASSED [ 95%] 95s test/test_util.py::test_to_hex_string PASSED [ 96%] 95s test/test_util.py::test_hex_list_to_bin_string PASSED [ 97%] 95s test/test_util.py::test_bin_string_to_hex_list PASSED [ 98%] 95s test/test_util.py::test_hl2bs PASSED [ 99%] 95s test/test_util.py::test_bs2hl PASSED [100%] 95s 95s ======================== 98 passed, 4 xfailed in 0.44s ========================= 95s :0: DeprecationWarning: builtin type swigvarlink has no __module__ attribute 96s autopkgtest [09:23:20]: test pyscard-tests: -----------------------] 96s pyscard-tests PASS 96s autopkgtest [09:23:20]: test pyscard-tests: - - - - - - - - - - results - - - - - - - - - - 96s autopkgtest [09:23:20]: @@@@@@@@@@@@@@@@@@@@ summary 96s pyscard-tests PASS 113s nova [W] Using flock in prodstack6-ppc64el 113s Creating nova instance adt-questing-ppc64el-pyscard-20250504-092144-juju-7f2275-prod-proposed-migration-environment-15-15255c5c-c5ae-4aa5-a1e4-694da4084aea from image adt/ubuntu-questing-ppc64el-server-20250504.img (UUID 65e029e2-4bd9-4b30-b646-f26a73cdeb97)... 113s nova [W] Timed out waiting for 8a23a3c0-1478-4d41-a234-c8d5de10f6b0 to get deleted.