0s autopkgtest [13:37:39]: starting date and time: 2025-05-02 13:37:39+0000 0s autopkgtest [13:37:39]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [13:37:39]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.in677nzc/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 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-arm64-3.secgroup --name adt-questing-arm64-pyscard-20250502-133739-juju-7f2275-prod-proposed-migration-environment-15-847cc961-955b-4244-b3da-a668cc749119 --image adt/ubuntu-questing-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 112s autopkgtest [13:39:31]: testbed dpkg architecture: arm64 113s autopkgtest [13:39:32]: testbed apt version: 3.0.0 113s autopkgtest [13:39:32]: @@@@@@@@@@@@@@@@@@@@ test bed setup 113s autopkgtest [13:39:32]: testbed release detected to be: None 114s autopkgtest [13:39:33]: updating testbed package index (apt update) 114s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 115s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 115s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 115s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 115s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [44.4 kB] 115s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [171 kB] 115s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [2029 kB] 115s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main arm64 Packages [218 kB] 115s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe arm64 Packages [1476 kB] 115s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse arm64 Packages [15.4 kB] 116s Fetched 4063 kB in 1s (3314 kB/s) 117s Reading package lists... 117s autopkgtest [13:39:36]: upgrading testbed (apt dist-upgrade and autopurge) 118s Reading package lists... 118s Building dependency tree... 118s Reading state information... 118s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 119s Starting 2 pkgProblemResolver with broken count: 0 119s Done 119s Entering ResolveByKeep 120s 120s Calculating upgrade... 120s The following packages will be upgraded: 120s htop libnpth0t64 mokutil mtd-utils nano usb.ids 120s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 120s Need to get 1196 kB of archives. 120s After this operation, 727 kB of additional disk space will be used. 120s Get:1 http://ftpmaster.internal/ubuntu questing/main arm64 libnpth0t64 arm64 1.8-3 [8818 B] 120s Get:2 http://ftpmaster.internal/ubuntu questing/main arm64 nano arm64 8.4-1 [290 kB] 121s Get:3 http://ftpmaster.internal/ubuntu questing/main arm64 usb.ids all 2025.04.01-1 [223 kB] 121s Get:4 http://ftpmaster.internal/ubuntu questing/main arm64 htop arm64 3.4.1-4 [177 kB] 121s Get:5 http://ftpmaster.internal/ubuntu questing/main arm64 mokutil arm64 0.7.2-1 [28.4 kB] 121s Get:6 http://ftpmaster.internal/ubuntu questing/main arm64 mtd-utils arm64 1:2.3.0-1ubuntu1 [469 kB] 122s Fetched 1196 kB in 1s (1801 kB/s) 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 ... 117820 files and directories currently installed.) 122s Preparing to unpack .../libnpth0t64_1.8-3_arm64.deb ... 122s Unpacking libnpth0t64:arm64 (1.8-3) over (1.8-2) ... 122s Setting up libnpth0t64:arm64 (1.8-3) ... 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 ... 117820 files and directories currently installed.) 122s Preparing to unpack .../archives/nano_8.4-1_arm64.deb ... 122s Unpacking nano (8.4-1) over (8.3-1) ... 122s Preparing to unpack .../usb.ids_2025.04.01-1_all.deb ... 122s Unpacking usb.ids (2025.04.01-1) over (2025.01.14-1) ... 122s Preparing to unpack .../htop_3.4.1-4_arm64.deb ... 122s Unpacking htop (3.4.1-4) over (3.4.0-2) ... 122s Preparing to unpack .../mokutil_0.7.2-1_arm64.deb ... 122s Unpacking mokutil (0.7.2-1) over (0.6.0-2build3) ... 123s Preparing to unpack .../mtd-utils_1%3a2.3.0-1ubuntu1_arm64.deb ... 123s Unpacking mtd-utils (1:2.3.0-1ubuntu1) over (1:2.2.0-2ubuntu2) ... 123s Setting up mokutil (0.7.2-1) ... 123s Setting up htop (3.4.1-4) ... 123s Setting up mtd-utils (1:2.3.0-1ubuntu1) ... 123s Setting up usb.ids (2025.04.01-1) ... 123s Setting up nano (8.4-1) ... 123s Installing new version of config file /etc/nanorc ... 123s Processing triggers for libc-bin (2.41-6ubuntu1) ... 123s Processing triggers for man-db (2.13.0-1) ... 124s Processing triggers for install-info (7.1.1-1) ... 125s Reading package lists... 125s Building dependency tree... 125s Reading state information... 126s Starting pkgProblemResolver with broken count: 0 126s Starting 2 pkgProblemResolver with broken count: 0 126s Done 126s Solving dependencies... 127s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 129s autopkgtest [13:39:48]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP PREEMPT_DYNAMIC Sun Apr 6 14:37:51 UTC 2025 130s autopkgtest [13:39:48]: @@@@@@@@@@@@@@@@@@@@ apt-source pyscard 131s Get:1 http://ftpmaster.internal/ubuntu questing/universe pyscard 2.2.1-1build1 (dsc) [2385 B] 131s Get:2 http://ftpmaster.internal/ubuntu questing/universe pyscard 2.2.1-1build1 (tar) [157 kB] 131s Get:3 http://ftpmaster.internal/ubuntu questing/universe pyscard 2.2.1-1build1 (asc) [833 B] 131s Get:4 http://ftpmaster.internal/ubuntu questing/universe pyscard 2.2.1-1build1 (diff) [8704 B] 131s gpgv: Signature made Tue Mar 4 23:07:34 2025 UTC 131s gpgv: using RSA key 25E3FF2D7F469DBE7D0D4E50AFCFEC8E669CE1C2 131s gpgv: Can't check signature: No public key 131s dpkg-source: warning: cannot verify inline signature for ./pyscard_2.2.1-1build1.dsc: no acceptable signature found 132s autopkgtest [13:39:51]: testing package pyscard version 2.2.1-1build1 132s autopkgtest [13:39:51]: build not needed 132s autopkgtest [13:39:51]: test pyscard-tests: preparing testbed 133s Reading package lists... 133s Building dependency tree... 133s Reading state information... 133s Starting pkgProblemResolver with broken count: 0 133s Starting 2 pkgProblemResolver with broken count: 0 133s Done 134s The following NEW packages will be installed: 134s libccid libpcsclite1 pcscd python3-iniconfig python3-packaging 134s python3-pluggy python3-pyscard python3-pytest 134s 0 upgraded, 8 newly installed, 0 to remove and 0 not upgraded. 134s Need to get 590 kB of archives. 134s After this operation, 3052 kB of additional disk space will be used. 134s Get:1 http://ftpmaster.internal/ubuntu questing/universe arm64 libccid arm64 1.6.1-2 [86.7 kB] 135s Get:2 http://ftpmaster.internal/ubuntu questing/main arm64 libpcsclite1 arm64 2.3.1-1 [25.0 kB] 135s Get:3 http://ftpmaster.internal/ubuntu questing/universe arm64 pcscd arm64 2.3.1-1 [60.5 kB] 135s Get:4 http://ftpmaster.internal/ubuntu questing/universe arm64 python3-iniconfig all 1.1.1-2 [6024 B] 135s Get:5 http://ftpmaster.internal/ubuntu questing/main arm64 python3-packaging all 24.2-1 [51.5 kB] 135s Get:6 http://ftpmaster.internal/ubuntu questing/universe arm64 python3-pluggy all 1.5.0-1 [21.0 kB] 135s Get:7 http://ftpmaster.internal/ubuntu questing/universe arm64 python3-pyscard arm64 2.2.1-1build1 [86.3 kB] 135s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/universe arm64 python3-pytest all 8.3.5-2 [252 kB] 136s Fetched 590 kB in 1s (831 kB/s) 136s Selecting previously unselected package libccid. 136s (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 ... 117821 files and directories currently installed.) 136s Preparing to unpack .../0-libccid_1.6.1-2_arm64.deb ... 136s Unpacking libccid (1.6.1-2) ... 136s Selecting previously unselected package libpcsclite1:arm64. 136s Preparing to unpack .../1-libpcsclite1_2.3.1-1_arm64.deb ... 136s Unpacking libpcsclite1:arm64 (2.3.1-1) ... 136s Selecting previously unselected package pcscd. 136s Preparing to unpack .../2-pcscd_2.3.1-1_arm64.deb ... 136s Unpacking pcscd (2.3.1-1) ... 136s Selecting previously unselected package python3-iniconfig. 136s Preparing to unpack .../3-python3-iniconfig_1.1.1-2_all.deb ... 136s Unpacking python3-iniconfig (1.1.1-2) ... 136s Selecting previously unselected package python3-packaging. 136s Preparing to unpack .../4-python3-packaging_24.2-1_all.deb ... 136s Unpacking python3-packaging (24.2-1) ... 136s Selecting previously unselected package python3-pluggy. 137s Preparing to unpack .../5-python3-pluggy_1.5.0-1_all.deb ... 137s Unpacking python3-pluggy (1.5.0-1) ... 137s Selecting previously unselected package python3-pyscard. 137s Preparing to unpack .../6-python3-pyscard_2.2.1-1build1_arm64.deb ... 137s Unpacking python3-pyscard (2.2.1-1build1) ... 137s Selecting previously unselected package python3-pytest. 137s Preparing to unpack .../7-python3-pytest_8.3.5-2_all.deb ... 137s Unpacking python3-pytest (8.3.5-2) ... 137s Setting up python3-iniconfig (1.1.1-2) ... 137s Setting up libccid (1.6.1-2) ... 137s Setting up python3-packaging (24.2-1) ... 137s Setting up libpcsclite1:arm64 (2.3.1-1) ... 137s Setting up python3-pluggy (1.5.0-1) ... 138s Setting up python3-pyscard (2.2.1-1build1) ... 138s Setting up python3-pytest (8.3.5-2) ... 138s Setting up pcscd (2.3.1-1) ... 139s Created symlink '/etc/systemd/system/sockets.target.wants/pcscd.socket' → '/usr/lib/systemd/system/pcscd.socket'. 139s pcscd.service is a disabled or a static unit, not starting it. 139s Processing triggers for libc-bin (2.41-6ubuntu1) ... 139s Processing triggers for man-db (2.13.0-1) ... 141s autopkgtest [13:40:00]: test pyscard-tests: [----------------------- 142s PCSCD_ARGS=--disable-polkit 142s ============================= test session starts ============================== 142s platform linux -- Python 3.13.3, pytest-8.3.5, pluggy-1.5.0 -- /usr/bin/python3 142s cachedir: .pytest_cache 142s rootdir: /tmp/autopkgtest.sQBCRJ/build.Gyy/src 142s configfile: pyproject.toml 142s plugins: typeguard-4.4.2 143s collecting ... collected 102 items 143s 143s test/test_ATR.py::test_atr1 PASSED [ 0%] 143s test/test_ATR.py::test_atr2 PASSED [ 1%] 143s test/test_ATR.py::test_atr3 PASSED [ 2%] 143s test/test_ATR.py::test_atr4 PASSED [ 3%] 143s test/test_ATR.py::test_atr5 PASSED [ 4%] 143s test/test_ATR.py::test_atr6 PASSED [ 5%] 143s test/test_ATR.py::test_invalid_ts[numeric] PASSED [ 6%] 143s test/test_ATR.py::test_invalid_ts[lowercase] PASSED [ 7%] 143s test/test_ATR.py::test_invalid_ts[zero padding] PASSED [ 8%] 143s test/test_ATR.py::test_atr_get PASSED [ 9%] 143s test/test_ATR.py::test_map_lengths[clockrateconversion-16] PASSED [ 10%] 143s test/test_ATR.py::test_map_lengths[bitratefactor-16] PASSED [ 11%] 143s test/test_ATR.py::test_invalid_atr_lengths[ATR is too short (0 bytes)] PASSED [ 12%] 143s test/test_ATR.py::test_invalid_atr_lengths[ATR is too short (1 byte, valid TS)] PASSED [ 13%] 143s test/test_ATR.py::test_2_bytes[59] PASSED [ 14%] 143s test/test_ATR.py::test_2_bytes[63] PASSED [ 15%] 143s test/test_ATR.py::test_only_ta1 PASSED [ 16%] 143s test/test_ATR.py::test_only_tb1 PASSED [ 17%] 143s test/test_ATR.py::test_only_tc1 PASSED [ 18%] 143s test/test_ATR.py::test_only_td1 PASSED [ 19%] 143s test/test_ATR.py::test_historical_bytes PASSED [ 20%] 143s test/test_ATR.py::test_valid_checksums[atr_bytes0-59] PASSED [ 21%] 143s test/test_ATR.py::test_valid_checksums[atr_bytes0-63] PASSED [ 22%] 143s test/test_ATR.py::test_valid_checksums[atr_bytes1-59] PASSED [ 23%] 143s test/test_ATR.py::test_valid_checksums[atr_bytes1-63] PASSED [ 24%] 143s test/test_ATR.py::test_invalid_checksums[atr_bytes0-59] PASSED [ 25%] 143s test/test_ATR.py::test_invalid_checksums[atr_bytes0-63] PASSED [ 26%] 143s test/test_ATR.py::test_invalid_checksums[atr_bytes1-59] PASSED [ 27%] 143s test/test_ATR.py::test_invalid_checksums[atr_bytes1-63] PASSED [ 28%] 143s test/test_Exceptions.py::test_hresult_value PASSED [ 29%] 143s test/test_Exceptions.py::test_list_readers_exception PASSED [ 30%] 143s test/test_Exceptions.py::test_no_readers_exception PASSED [ 31%] 143s test/test_Exceptions.py::test_invalid_reader_exception PASSED [ 32%] 143s test/test_Exceptions.py::test_card_connection_exception PASSED [ 33%] 143s test/test_Exceptions.py::test_hresult PASSED [ 34%] 143s test/test_Exceptions.py::test_wrong_type[arg0] PASSED [ 35%] 143s test/test_Exceptions.py::test_wrong_type[foo] PASSED [ 36%] 143s test/test_Exceptions.py::test_card_request_timeout_exception PASSED [ 37%] 143s test/test_Exceptions.py::test_invalid_atr_mask_length_exception PASSED [ 38%] 143s test/test_Exceptions.py::test_no_card_exception PASSED [ 39%] 143s test/test_PCSC.py::test_low_level PASSED [ 40%] 143s test/test_PCSCExceptions.py::test_list_readers_exception PASSED [ 41%] 143s test/test_PCSCExceptions.py::test_establish_context_exception PASSED [ 42%] 143s test/test_PCSCExceptions.py::test_introduce_reader_exception PASSED [ 43%] 143s test/test_PCSCExceptions.py::test_remove_reader_from_group_exception PASSED [ 44%] 143s test/test_PCSCExceptions.py::test_add_reader_to_group_exception PASSED [ 45%] 143s test/test_PCSCExceptions.py::test_release_context_exception PASSED [ 46%] 143s test/test_PCSCExceptions.py::test_base_scard_exception PASSED [ 47%] 143s test/test_SCardGetErrorMessage.py::test_scard_get_error_message PASSED [ 48%] 143s test/test_guid.py::test_roundtrip_string PASSED [ 49%] 143s test/test_guid.py::test_roundtrip_list_of_ints PASSED [ 50%] 143s test/test_observer.py::test_state_changes PASSED [ 50%] 143s test/test_observer.py::test_synchronization[addObserver] PASSED [ 51%] 143s test/test_observer.py::test_synchronization[countObservers] PASSED [ 52%] 143s test/test_observer.py::test_synchronization[deleteObserver] PASSED [ 53%] 143s test/test_observer.py::test_synchronization[deleteObservers] PASSED [ 54%] 143s test/test_observer.py::test_synchronization[notifyObservers] PASSED [ 55%] 143s test/test_observer.py::test_synchronization[clearChanged] PASSED [ 56%] 143s test/test_observer.py::test_synchronization[hasChanged] PASSED [ 57%] 143s test/test_observer.py::test_synchronization[setChanged] PASSED [ 58%] 143s test/test_observer.py::test_registered_observers_are_always_notified PASSED [ 59%] 143s test/test_observer.py::test_double_observer_additions PASSED [ 60%] 143s test/test_observer.py::test_double_observer_removals PASSED [ 61%] 143s test/test_observer.py::test_no_notifications_when_no_changes PASSED [ 62%] 143s test/test_observer.py::test_default_observer_updates PASSED [ 63%] 143s test/test_readergroups.py::test_reader_groups_acts_like_a_singleton PASSED [ 64%] 143s test/test_readergroups.py::test_reader_groups_method_calls PASSED [ 65%] 143s test/test_readergroups.py::test_reader_groups_is_a_singleton XFAIL (...) [ 66%] 143s test/test_readergroups.py::test_demonstrate_initlist_values_may_be_silently_ignored XFAIL [ 67%] 143s test/test_readergroups.py::test_demonstrate_adding_is_impossible PASSED [ 68%] 143s test/test_readergroups.py::test_demonstrate_removing_is_impossible PASSED [ 69%] 143s test/test_readergroups.py::test_demonstrate_getting_is_impossible PASSED [ 70%] 143s test/test_synchronization.py::test_synchronize[wrap nothing (str)] PASSED [ 71%] 143s test/test_synchronization.py::test_synchronize[wrap nothing (a, str)] PASSED [ 72%] 143s test/test_synchronization.py::test_synchronize[wrap nothing (a+b, str)] PASSED [ 73%] 143s test/test_synchronization.py::test_synchronize[wrap nothing (list)] PASSED [ 74%] 143s test/test_synchronization.py::test_synchronize[wrap nothing (a, list)] PASSED [ 75%] 143s test/test_synchronization.py::test_synchronize[wrap nothing (a+b, list)] PASSED [ 76%] 143s test/test_synchronization.py::test_synchronize[wrap all] PASSED [ 77%] 143s test/test_synchronization.py::test_synchronize[wrap all (a)] PASSED [ 78%] 143s test/test_synchronization.py::test_synchronize[wrap all (a+b)] PASSED [ 79%] 143s test/test_synchronization.py::test_synchronize[wrap a only (a, str)] PASSED [ 80%] 143s test/test_synchronization.py::test_synchronize[wrap a only (a+b, str)] PASSED [ 81%] 143s test/test_synchronization.py::test_synchronize[wrap a only (a, list)] PASSED [ 82%] 143s test/test_synchronization.py::test_synchronize[wrap a only (a+b, list)] PASSED [ 83%] 143s test/test_synchronization.py::test_synchronization_reentrant_lock PASSED [ 84%] 143s test/test_synchronization.py::test_synchronization_wrapping PASSED [ 85%] 143s test/test_synchronization.py::test_synchronization_kwargs PASSED [ 86%] 143s test/test_ulist.py::test_demonstrate_ulist_cannot_be_instantiated PASSED [ 87%] 143s test/test_ulist.py::test_demonstrate_order_is_not_respected XFAIL (o...) [ 88%] 143s test/test_ulist.py::test_demonstrate_non_unique_behavior XFAIL (not ...) [ 89%] 143s test/test_ulist.py::test_ulist_methods PASSED [ 90%] 143s test/test_util.py::test_to_bytes PASSED [ 91%] 143s test/test_util.py::test_padd PASSED [ 92%] 143s test/test_util.py::test_to_ascii_bytes PASSED [ 93%] 143s test/test_util.py::test_to_ascii_string PASSED [ 94%] 143s test/test_util.py::test_to_gsm3_38_bytes PASSED [ 95%] 143s test/test_util.py::test_to_hex_string PASSED [ 96%] 143s test/test_util.py::test_hex_list_to_bin_string PASSED [ 97%] 143s test/test_util.py::test_bin_string_to_hex_list PASSED [ 98%] 143s test/test_util.py::test_hl2bs PASSED [ 99%] 143s test/test_util.py::test_bs2hl PASSED [100%] 143s 143s ======================== 98 passed, 4 xfailed in 0.44s ========================= 143s :0: DeprecationWarning: builtin type swigvarlink has no __module__ attribute 143s autopkgtest [13:40:02]: test pyscard-tests: -----------------------] 144s autopkgtest [13:40:03]: test pyscard-tests: - - - - - - - - - - results - - - - - - - - - - 144s pyscard-tests PASS 144s autopkgtest [13:40:03]: @@@@@@@@@@@@@@@@@@@@ summary 144s pyscard-tests PASS 162s nova [W] Using flock in prodstack6-arm64 162s Creating nova instance adt-questing-arm64-pyscard-20250502-133739-juju-7f2275-prod-proposed-migration-environment-15-847cc961-955b-4244-b3da-a668cc749119 from image adt/ubuntu-questing-arm64-server-20250502.img (UUID a0b7b7fd-ad05-4ea9-97eb-0f52dbbe98e1)... 162s nova [W] Timed out waiting for 43f400e7-cecd-4901-bcd9-1c2b6e102d07 to get deleted.