0s autopkgtest [11:36:50]: starting date and time: 2025-05-02 11:36:50+0000 0s autopkgtest [11:36:50]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [11:36:50]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.c07t83u9/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-cpu2-ram4-disk20-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@sto01-s390x-4.secgroup --name adt-questing-s390x-pyscard-20250502-113650-juju-7f2275-prod-proposed-migration-environment-2-5992546c-3515-4959-b728-ff6ec574aa4e --image adt/ubuntu-questing-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-autopkgtest-workers-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 106s autopkgtest [11:38:36]: testbed dpkg architecture: s390x 107s autopkgtest [11:38:37]: testbed apt version: 3.0.0 109s autopkgtest [11:38:39]: @@@@@@@@@@@@@@@@@@@@ test bed setup 110s autopkgtest [11:38:40]: testbed release detected to be: None 112s autopkgtest [11:38:42]: updating testbed package index (apt update) 113s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 113s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 113s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 113s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 113s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [44.4 kB] 113s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [175 kB] 113s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [2076 kB] 114s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [208 kB] 114s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [1079 kB] 114s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [9148 B] 116s Fetched 3701 kB in 4s (936 kB/s) 121s Reading package lists... 123s autopkgtest [11:38:53]: upgrading testbed (apt dist-upgrade and autopurge) 123s Reading package lists... 125s Building dependency tree... 125s Reading state information... 126s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 126s Starting 2 pkgProblemResolver with broken count: 0 126s Done 128s Entering ResolveByKeep 128s 129s Calculating upgrade... 130s The following packages will be upgraded: 130s htop libperl5.40 nano perl perl-base perl-modules-5.40 130s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 130s Need to get 10.9 MB of archives. 130s After this operation, 1024 B disk space will be freed. 130s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libperl5.40 s390x 5.40.1-3 [4972 kB] 131s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main s390x perl s390x 5.40.1-3 [262 kB] 131s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main s390x perl-base s390x 5.40.1-3 [1954 kB] 131s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/main s390x perl-modules-5.40 all 5.40.1-3 [3217 kB] 131s Get:5 http://ftpmaster.internal/ubuntu questing/main s390x nano s390x 8.4-1 [299 kB] 131s Get:6 http://ftpmaster.internal/ubuntu questing/main s390x htop s390x 3.4.1-4 [189 kB] 133s Fetched 10.9 MB in 1s (11.5 MB/s) 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 ... 59826 files and directories currently installed.) 133s Preparing to unpack .../libperl5.40_5.40.1-3_s390x.deb ... 133s Unpacking libperl5.40:s390x (5.40.1-3) over (5.40.1-2ubuntu0.1) ... 133s Preparing to unpack .../perl_5.40.1-3_s390x.deb ... 133s Unpacking perl (5.40.1-3) over (5.40.1-2ubuntu0.1) ... 133s Preparing to unpack .../perl-base_5.40.1-3_s390x.deb ... 133s Unpacking perl-base (5.40.1-3) over (5.40.1-2ubuntu0.1) ... 134s Setting up perl-base (5.40.1-3) ... 134s (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 ... 59826 files and directories currently installed.) 134s Preparing to unpack .../perl-modules-5.40_5.40.1-3_all.deb ... 134s Unpacking perl-modules-5.40 (5.40.1-3) over (5.40.1-2ubuntu0.1) ... 135s Preparing to unpack .../archives/nano_8.4-1_s390x.deb ... 135s Unpacking nano (8.4-1) over (8.3-1) ... 135s Preparing to unpack .../htop_3.4.1-4_s390x.deb ... 135s Unpacking htop (3.4.1-4) over (3.4.0-2) ... 135s Setting up htop (3.4.1-4) ... 135s Setting up nano (8.4-1) ... 135s Installing new version of config file /etc/nanorc ... 135s Setting up perl-modules-5.40 (5.40.1-3) ... 135s Setting up libperl5.40:s390x (5.40.1-3) ... 135s Setting up perl (5.40.1-3) ... 135s Processing triggers for libc-bin (2.41-6ubuntu1) ... 136s Processing triggers for man-db (2.13.0-1) ... 140s Processing triggers for install-info (7.1.1-1) ... 142s Reading package lists... 143s Building dependency tree... 143s Reading state information... 143s Starting pkgProblemResolver with broken count: 0 143s Starting 2 pkgProblemResolver with broken count: 0 143s Done 144s Solving dependencies... 144s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 149s autopkgtest [11:39:19]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr 6 13:39:00 UTC 2025 149s autopkgtest [11:39:19]: @@@@@@@@@@@@@@@@@@@@ apt-source pyscard 155s Get:1 http://ftpmaster.internal/ubuntu questing/universe pyscard 2.2.1-1build1 (dsc) [2385 B] 155s Get:2 http://ftpmaster.internal/ubuntu questing/universe pyscard 2.2.1-1build1 (tar) [157 kB] 155s Get:3 http://ftpmaster.internal/ubuntu questing/universe pyscard 2.2.1-1build1 (asc) [833 B] 155s Get:4 http://ftpmaster.internal/ubuntu questing/universe pyscard 2.2.1-1build1 (diff) [8704 B] 155s gpgv: Signature made Tue Mar 4 23:07:34 2025 UTC 155s gpgv: using RSA key 25E3FF2D7F469DBE7D0D4E50AFCFEC8E669CE1C2 155s gpgv: Can't check signature: No public key 155s dpkg-source: warning: cannot verify inline signature for ./pyscard_2.2.1-1build1.dsc: no acceptable signature found 155s autopkgtest [11:39:25]: testing package pyscard version 2.2.1-1build1 156s autopkgtest [11:39:26]: build not needed 157s autopkgtest [11:39:27]: test pyscard-tests: preparing testbed 157s Reading package lists... 158s Building dependency tree... 158s Reading state information... 159s Starting pkgProblemResolver with broken count: 0 159s Starting 2 pkgProblemResolver with broken count: 0 159s Done 161s The following NEW packages will be installed: 161s libccid libpcsclite1 pcscd python3-iniconfig python3-pluggy python3-pyscard 161s python3-pytest 161s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 161s Need to get 542 kB of archives. 161s After this operation, 2653 kB of additional disk space will be used. 161s Get:1 http://ftpmaster.internal/ubuntu questing/universe s390x libccid s390x 1.6.1-2 [90.2 kB] 161s Get:2 http://ftpmaster.internal/ubuntu questing/main s390x libpcsclite1 s390x 2.3.1-1 [24.5 kB] 161s Get:3 http://ftpmaster.internal/ubuntu questing/universe s390x pcscd s390x 2.3.1-1 [61.0 kB] 161s Get:4 http://ftpmaster.internal/ubuntu questing/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 161s Get:5 http://ftpmaster.internal/ubuntu questing/universe s390x python3-pluggy all 1.5.0-1 [21.0 kB] 161s Get:6 http://ftpmaster.internal/ubuntu questing/universe s390x python3-pyscard s390x 2.2.1-1build1 [87.0 kB] 162s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x python3-pytest all 8.3.5-2 [252 kB] 163s Fetched 542 kB in 0s (1363 kB/s) 163s Selecting previously unselected package libccid. 163s (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 ... 59826 files and directories currently installed.) 163s Preparing to unpack .../0-libccid_1.6.1-2_s390x.deb ... 163s Unpacking libccid (1.6.1-2) ... 163s Selecting previously unselected package libpcsclite1:s390x. 163s Preparing to unpack .../1-libpcsclite1_2.3.1-1_s390x.deb ... 163s Unpacking libpcsclite1:s390x (2.3.1-1) ... 163s Selecting previously unselected package pcscd. 163s Preparing to unpack .../2-pcscd_2.3.1-1_s390x.deb ... 163s Unpacking pcscd (2.3.1-1) ... 163s Selecting previously unselected package python3-iniconfig. 163s Preparing to unpack .../3-python3-iniconfig_1.1.1-2_all.deb ... 163s Unpacking python3-iniconfig (1.1.1-2) ... 163s Selecting previously unselected package python3-pluggy. 163s Preparing to unpack .../4-python3-pluggy_1.5.0-1_all.deb ... 163s Unpacking python3-pluggy (1.5.0-1) ... 163s Selecting previously unselected package python3-pyscard. 163s Preparing to unpack .../5-python3-pyscard_2.2.1-1build1_s390x.deb ... 163s Unpacking python3-pyscard (2.2.1-1build1) ... 163s Selecting previously unselected package python3-pytest. 163s Preparing to unpack .../6-python3-pytest_8.3.5-2_all.deb ... 163s Unpacking python3-pytest (8.3.5-2) ... 163s Setting up python3-iniconfig (1.1.1-2) ... 164s Setting up libccid (1.6.1-2) ... 165s Setting up libpcsclite1:s390x (2.3.1-1) ... 165s Setting up python3-pluggy (1.5.0-1) ... 165s Setting up python3-pyscard (2.2.1-1build1) ... 165s Setting up python3-pytest (8.3.5-2) ... 167s Setting up pcscd (2.3.1-1) ... 169s Created symlink '/etc/systemd/system/sockets.target.wants/pcscd.socket' → '/usr/lib/systemd/system/pcscd.socket'. 170s pcscd.service is a disabled or a static unit, not starting it. 170s Processing triggers for libc-bin (2.41-6ubuntu1) ... 171s Processing triggers for man-db (2.13.0-1) ... 175s autopkgtest [11:39:45]: test pyscard-tests: [----------------------- 175s PCSCD_ARGS=--disable-polkit 176s ============================= test session starts ============================== 176s platform linux -- Python 3.13.3, pytest-8.3.5, pluggy-1.5.0 -- /usr/bin/python3 176s cachedir: .pytest_cache 176s rootdir: /tmp/autopkgtest.FUhn0r/build.9fd/src 176s configfile: pyproject.toml 176s plugins: typeguard-4.4.2 177s collecting ... collected 102 items 177s 177s test/test_ATR.py::test_atr1 PASSED [ 0%] 177s test/test_ATR.py::test_atr2 PASSED [ 1%] 177s test/test_ATR.py::test_atr3 PASSED [ 2%] 177s test/test_ATR.py::test_atr4 PASSED [ 3%] 177s test/test_ATR.py::test_atr5 PASSED [ 4%] 177s test/test_ATR.py::test_atr6 PASSED [ 5%] 177s test/test_ATR.py::test_invalid_ts[numeric] PASSED [ 6%] 177s test/test_ATR.py::test_invalid_ts[lowercase] PASSED [ 7%] 177s test/test_ATR.py::test_invalid_ts[zero padding] PASSED [ 8%] 177s test/test_ATR.py::test_atr_get PASSED [ 9%] 177s test/test_ATR.py::test_map_lengths[clockrateconversion-16] PASSED [ 10%] 177s test/test_ATR.py::test_map_lengths[bitratefactor-16] PASSED [ 11%] 177s test/test_ATR.py::test_invalid_atr_lengths[ATR is too short (0 bytes)] PASSED [ 12%] 177s test/test_ATR.py::test_invalid_atr_lengths[ATR is too short (1 byte, valid TS)] PASSED [ 13%] 177s test/test_ATR.py::test_2_bytes[59] PASSED [ 14%] 177s test/test_ATR.py::test_2_bytes[63] PASSED [ 15%] 177s test/test_ATR.py::test_only_ta1 PASSED [ 16%] 177s test/test_ATR.py::test_only_tb1 PASSED [ 17%] 177s test/test_ATR.py::test_only_tc1 PASSED [ 18%] 177s test/test_ATR.py::test_only_td1 PASSED [ 19%] 177s test/test_ATR.py::test_historical_bytes PASSED [ 20%] 177s test/test_ATR.py::test_valid_checksums[atr_bytes0-59] PASSED [ 21%] 177s test/test_ATR.py::test_valid_checksums[atr_bytes0-63] PASSED [ 22%] 177s test/test_ATR.py::test_valid_checksums[atr_bytes1-59] PASSED [ 23%] 177s test/test_ATR.py::test_valid_checksums[atr_bytes1-63] PASSED [ 24%] 177s test/test_ATR.py::test_invalid_checksums[atr_bytes0-59] PASSED [ 25%] 177s test/test_ATR.py::test_invalid_checksums[atr_bytes0-63] PASSED [ 26%] 177s test/test_ATR.py::test_invalid_checksums[atr_bytes1-59] PASSED [ 27%] 177s test/test_ATR.py::test_invalid_checksums[atr_bytes1-63] PASSED [ 28%] 177s test/test_Exceptions.py::test_hresult_value PASSED [ 29%] 177s test/test_Exceptions.py::test_list_readers_exception PASSED [ 30%] 177s test/test_Exceptions.py::test_no_readers_exception PASSED [ 31%] 177s test/test_Exceptions.py::test_invalid_reader_exception PASSED [ 32%] 177s test/test_Exceptions.py::test_card_connection_exception PASSED [ 33%] 177s test/test_Exceptions.py::test_hresult PASSED [ 34%] 177s test/test_Exceptions.py::test_wrong_type[arg0] PASSED [ 35%] 177s test/test_Exceptions.py::test_wrong_type[foo] PASSED [ 36%] 177s test/test_Exceptions.py::test_card_request_timeout_exception PASSED [ 37%] 177s test/test_Exceptions.py::test_invalid_atr_mask_length_exception PASSED [ 38%] 177s test/test_Exceptions.py::test_no_card_exception PASSED [ 39%] 177s test/test_PCSC.py::test_low_level PASSED [ 40%] 177s test/test_PCSCExceptions.py::test_list_readers_exception PASSED [ 41%] 177s test/test_PCSCExceptions.py::test_establish_context_exception PASSED [ 42%] 177s test/test_PCSCExceptions.py::test_introduce_reader_exception PASSED [ 43%] 177s test/test_PCSCExceptions.py::test_remove_reader_from_group_exception PASSED [ 44%] 177s test/test_PCSCExceptions.py::test_add_reader_to_group_exception PASSED [ 45%] 177s test/test_PCSCExceptions.py::test_release_context_exception PASSED [ 46%] 177s test/test_PCSCExceptions.py::test_base_scard_exception PASSED [ 47%] 177s test/test_SCardGetErrorMessage.py::test_scard_get_error_message PASSED [ 48%] 177s test/test_guid.py::test_roundtrip_string PASSED [ 49%] 177s test/test_guid.py::test_roundtrip_list_of_ints PASSED [ 50%] 177s test/test_observer.py::test_state_changes PASSED [ 50%] 177s test/test_observer.py::test_synchronization[addObserver] PASSED [ 51%] 177s test/test_observer.py::test_synchronization[countObservers] PASSED [ 52%] 177s test/test_observer.py::test_synchronization[deleteObserver] PASSED [ 53%] 177s test/test_observer.py::test_synchronization[deleteObservers] PASSED [ 54%] 177s test/test_observer.py::test_synchronization[notifyObservers] PASSED [ 55%] 177s test/test_observer.py::test_synchronization[clearChanged] PASSED [ 56%] 177s test/test_observer.py::test_synchronization[hasChanged] PASSED [ 57%] 177s test/test_observer.py::test_synchronization[setChanged] PASSED [ 58%] 177s test/test_observer.py::test_registered_observers_are_always_notified PASSED [ 59%] 177s test/test_observer.py::test_double_observer_additions PASSED [ 60%] 177s test/test_observer.py::test_double_observer_removals PASSED [ 61%] 177s test/test_observer.py::test_no_notifications_when_no_changes PASSED [ 62%] 177s test/test_observer.py::test_default_observer_updates PASSED [ 63%] 177s test/test_readergroups.py::test_reader_groups_acts_like_a_singleton PASSED [ 64%] 177s test/test_readergroups.py::test_reader_groups_method_calls PASSED [ 65%] 177s test/test_readergroups.py::test_reader_groups_is_a_singleton XFAIL (...) [ 66%] 177s test/test_readergroups.py::test_demonstrate_initlist_values_may_be_silently_ignored XFAIL [ 67%] 177s test/test_readergroups.py::test_demonstrate_adding_is_impossible PASSED [ 68%] 177s test/test_readergroups.py::test_demonstrate_removing_is_impossible PASSED [ 69%] 177s test/test_readergroups.py::test_demonstrate_getting_is_impossible PASSED [ 70%] 177s test/test_synchronization.py::test_synchronize[wrap nothing (str)] PASSED [ 71%] 177s test/test_synchronization.py::test_synchronize[wrap nothing (a, str)] PASSED [ 72%] 177s test/test_synchronization.py::test_synchronize[wrap nothing (a+b, str)] PASSED [ 73%] 177s test/test_synchronization.py::test_synchronize[wrap nothing (list)] PASSED [ 74%] 177s test/test_synchronization.py::test_synchronize[wrap nothing (a, list)] PASSED [ 75%] 177s test/test_synchronization.py::test_synchronize[wrap nothing (a+b, list)] PASSED [ 76%] 177s test/test_synchronization.py::test_synchronize[wrap all] PASSED [ 77%] 177s test/test_synchronization.py::test_synchronize[wrap all (a)] PASSED [ 78%] 177s test/test_synchronization.py::test_synchronize[wrap all (a+b)] PASSED [ 79%] 177s test/test_synchronization.py::test_synchronize[wrap a only (a, str)] PASSED [ 80%] 177s test/test_synchronization.py::test_synchronize[wrap a only (a+b, str)] PASSED [ 81%] 177s test/test_synchronization.py::test_synchronize[wrap a only (a, list)] PASSED [ 82%] 177s test/test_synchronization.py::test_synchronize[wrap a only (a+b, list)] PASSED [ 83%] 177s test/test_synchronization.py::test_synchronization_reentrant_lock PASSED [ 84%] 177s test/test_synchronization.py::test_synchronization_wrapping PASSED [ 85%] 177s test/test_synchronization.py::test_synchronization_kwargs PASSED [ 86%] 177s test/test_ulist.py::test_demonstrate_ulist_cannot_be_instantiated PASSED [ 87%] 178s test/test_ulist.py::test_demonstrate_order_is_not_respected XFAIL (o...) [ 88%] 178s test/test_ulist.py::test_demonstrate_non_unique_behavior XFAIL (not ...) [ 89%] 178s test/test_ulist.py::test_ulist_methods PASSED [ 90%] 178s test/test_util.py::test_to_bytes PASSED [ 91%] 178s test/test_util.py::test_padd PASSED [ 92%] 178s test/test_util.py::test_to_ascii_bytes PASSED [ 93%] 178s test/test_util.py::test_to_ascii_string PASSED [ 94%] 178s test/test_util.py::test_to_gsm3_38_bytes PASSED [ 95%] 178s test/test_util.py::test_to_hex_string PASSED [ 96%] 178s test/test_util.py::test_hex_list_to_bin_string PASSED [ 97%] 178s test/test_util.py::test_bin_string_to_hex_list PASSED [ 98%] 178s test/test_util.py::test_hl2bs PASSED [ 99%] 178s test/test_util.py::test_bs2hl PASSED [100%] 178s 178s ======================== 98 passed, 4 xfailed in 2.07s ========================= 178s :0: DeprecationWarning: builtin type swigvarlink has no __module__ attribute 178s autopkgtest [11:39:48]: test pyscard-tests: -----------------------] 179s pyscard-tests PASS 179s autopkgtest [11:39:49]: test pyscard-tests: - - - - - - - - - - results - - - - - - - - - - 179s autopkgtest [11:39:49]: @@@@@@@@@@@@@@@@@@@@ summary 179s pyscard-tests PASS 196s nova [W] Using flock in prodstack7-s390x 196s Creating nova instance adt-questing-s390x-pyscard-20250502-113650-juju-7f2275-prod-proposed-migration-environment-2-5992546c-3515-4959-b728-ff6ec574aa4e from image adt/ubuntu-questing-s390x-server-20250501.img (UUID 2a693762-e567-4bbe-9838-630e77f0775f)... 196s nova [W] Timed out waiting for 212ead66-3b56-4a41-a6dc-fec92241cde9 to get deleted.