0s autopkgtest [09:37:31]: starting date and time: 2024-11-13 09:37:31+0000 0s autopkgtest [09:37:31]: git checkout: 0acbae0a WIP show VirtSubproc stderr in real-time 0s autopkgtest [09:37:31]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.51cwsqb9/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 pykcs11 --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-6.secgroup --name adt-plucky-amd64-pykcs11-20241113-093730-juju-7f2275-prod-proposed-migration-environment-2-1cde3453-f299-4561-9c8d-0d017219803d --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/ 75s autopkgtest [09:38:46]: testbed dpkg architecture: amd64 75s autopkgtest [09:38:46]: testbed apt version: 2.9.8 75s autopkgtest [09:38:46]: @@@@@@@@@@@@@@@@@@@@ test bed setup 75s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 76s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.3 kB] 76s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [76.4 kB] 76s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [849 kB] 76s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [7016 B] 76s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 Packages [111 kB] 76s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main i386 Packages [65.2 kB] 76s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted amd64 Packages [32.6 kB] 76s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/universe i386 Packages [255 kB] 76s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/universe amd64 Packages [637 kB] 76s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse amd64 Packages [37.7 kB] 76s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse i386 Packages [13.0 kB] 76s Fetched 2173 kB in 1s (3744 kB/s) 76s Reading package lists... 77s Reading package lists... 78s Building dependency tree... 78s Reading state information... 78s Calculating upgrade... 78s The following NEW packages will be installed: 78s python3.13-gdbm 78s The following packages will be upgraded: 78s libgpgme11t64 libpython3-stdlib python3 python3-gdbm python3-minimal 78s 5 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 78s Need to get 253 kB of archives. 78s After this operation, 147 kB of additional disk space will be used. 78s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 python3-minimal amd64 3.12.7-1 [27.4 kB] 78s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 python3 amd64 3.12.7-1 [24.0 kB] 78s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 libpython3-stdlib amd64 3.12.7-1 [10.0 kB] 78s Get:4 http://ftpmaster.internal/ubuntu plucky/main amd64 python3.13-gdbm amd64 3.13.0-2 [31.3 kB] 78s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main amd64 python3-gdbm amd64 3.12.7-1 [8642 B] 78s Get:6 http://ftpmaster.internal/ubuntu plucky/main amd64 libgpgme11t64 amd64 1.23.2-5ubuntu4 [152 kB] 79s Fetched 253 kB in 0s (5778 kB/s) 79s (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.) 79s Preparing to unpack .../python3-minimal_3.12.7-1_amd64.deb ... 79s Unpacking python3-minimal (3.12.7-1) over (3.12.6-0ubuntu1) ... 79s Setting up python3-minimal (3.12.7-1) ... 79s (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.) 79s Preparing to unpack .../python3_3.12.7-1_amd64.deb ... 79s Unpacking python3 (3.12.7-1) over (3.12.6-0ubuntu1) ... 79s Preparing to unpack .../libpython3-stdlib_3.12.7-1_amd64.deb ... 79s Unpacking libpython3-stdlib:amd64 (3.12.7-1) over (3.12.6-0ubuntu1) ... 79s Selecting previously unselected package python3.13-gdbm. 79s Preparing to unpack .../python3.13-gdbm_3.13.0-2_amd64.deb ... 79s Unpacking python3.13-gdbm (3.13.0-2) ... 79s Preparing to unpack .../python3-gdbm_3.12.7-1_amd64.deb ... 79s Unpacking python3-gdbm:amd64 (3.12.7-1) over (3.12.6-1ubuntu1) ... 79s Preparing to unpack .../libgpgme11t64_1.23.2-5ubuntu4_amd64.deb ... 79s Unpacking libgpgme11t64:amd64 (1.23.2-5ubuntu4) over (1.18.0-4.1ubuntu4) ... 79s Setting up libgpgme11t64:amd64 (1.23.2-5ubuntu4) ... 79s Setting up python3.13-gdbm (3.13.0-2) ... 79s Setting up libpython3-stdlib:amd64 (3.12.7-1) ... 79s Setting up python3 (3.12.7-1) ... 80s Setting up python3-gdbm:amd64 (3.12.7-1) ... 80s Processing triggers for man-db (2.12.1-3) ... 80s Processing triggers for libc-bin (2.40-1ubuntu3) ... 80s Reading package lists... 81s Building dependency tree... 81s Reading state information... 81s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 81s Hit:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease 81s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 81s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 81s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 83s Reading package lists... 83s Reading package lists... 83s Building dependency tree... 83s Reading state information... 83s Calculating upgrade... 83s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 83s Reading package lists... 84s Building dependency tree... 84s Reading state information... 84s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 86s autopkgtest [09:38:57]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 13:41:20 UTC 2024 86s autopkgtest [09:38:57]: @@@@@@@@@@@@@@@@@@@@ apt-source pykcs11 87s Get:1 http://ftpmaster.internal/ubuntu plucky/universe pykcs11 1.5.17-1 (dsc) [2127 B] 87s Get:2 http://ftpmaster.internal/ubuntu plucky/universe pykcs11 1.5.17-1 (tar) [82.7 kB] 87s Get:3 http://ftpmaster.internal/ubuntu plucky/universe pykcs11 1.5.17-1 (diff) [8256 B] 87s gpgv: Signature made Wed Oct 16 11:52:54 2024 UTC 87s gpgv: using RSA key F5E11B9FFE911146F41D953D78A1B4DFE8F9C57E 87s gpgv: issuer "rousseau@debian.org" 87s gpgv: Can't check signature: No public key 87s dpkg-source: warning: cannot verify inline signature for ./pykcs11_1.5.17-1.dsc: no acceptable signature found 87s autopkgtest [09:38:58]: testing package pykcs11 version 1.5.17-1 87s autopkgtest [09:38:58]: build not needed 87s autopkgtest [09:38:58]: test unittest: preparing testbed 88s Reading package lists... 88s Building dependency tree... 88s Reading state information... 89s Starting pkgProblemResolver with broken count: 0 89s Starting 2 pkgProblemResolver with broken count: 0 89s Done 89s The following additional packages will be installed: 89s libsofthsm2 python3-asn1crypto python3-pykcs11 softhsm2 softhsm2-common 89s The following NEW packages will be installed: 89s autopkgtest-satdep libsofthsm2 python3-asn1crypto python3-pykcs11 softhsm2 89s softhsm2-common 89s 0 upgraded, 6 newly installed, 0 to remove and 0 not upgraded. 89s Need to get 702 kB/703 kB of archives. 89s After this operation, 2999 kB of additional disk space will be used. 89s Get:1 /tmp/autopkgtest.5865BW/1-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [740 B] 89s Get:2 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-asn1crypto all 1.5.1-3 [79.7 kB] 89s Get:3 http://ftpmaster.internal/ubuntu plucky/universe amd64 python3-pykcs11 amd64 1.5.17-1 [176 kB] 89s Get:4 http://ftpmaster.internal/ubuntu plucky/universe amd64 softhsm2-common amd64 2.6.1-2.2ubuntu3 [6198 B] 89s Get:5 http://ftpmaster.internal/ubuntu plucky/universe amd64 libsofthsm2 amd64 2.6.1-2.2ubuntu3 [266 kB] 89s Get:6 http://ftpmaster.internal/ubuntu plucky/universe amd64 softhsm2 amd64 2.6.1-2.2ubuntu3 [175 kB] 90s Fetched 702 kB in 0s (3288 kB/s) 90s Selecting previously unselected package python3-asn1crypto. 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 ... 75548 files and directories currently installed.) 90s Preparing to unpack .../0-python3-asn1crypto_1.5.1-3_all.deb ... 90s Unpacking python3-asn1crypto (1.5.1-3) ... 90s Selecting previously unselected package python3-pykcs11. 90s Preparing to unpack .../1-python3-pykcs11_1.5.17-1_amd64.deb ... 90s Unpacking python3-pykcs11 (1.5.17-1) ... 90s Selecting previously unselected package softhsm2-common. 90s Preparing to unpack .../2-softhsm2-common_2.6.1-2.2ubuntu3_amd64.deb ... 90s Unpacking softhsm2-common (2.6.1-2.2ubuntu3) ... 90s Selecting previously unselected package libsofthsm2. 90s Preparing to unpack .../3-libsofthsm2_2.6.1-2.2ubuntu3_amd64.deb ... 90s Unpacking libsofthsm2 (2.6.1-2.2ubuntu3) ... 90s Selecting previously unselected package softhsm2. 90s Preparing to unpack .../4-softhsm2_2.6.1-2.2ubuntu3_amd64.deb ... 90s Unpacking softhsm2 (2.6.1-2.2ubuntu3) ... 90s Selecting previously unselected package autopkgtest-satdep. 90s Preparing to unpack .../5-1-autopkgtest-satdep.deb ... 90s Unpacking autopkgtest-satdep (0) ... 90s Setting up softhsm2-common (2.6.1-2.2ubuntu3) ... 90s 90s Creating config file /etc/softhsm/softhsm2.conf with new version 90s Setting up python3-pykcs11 (1.5.17-1) ... 90s Setting up python3-asn1crypto (1.5.1-3) ... 91s Setting up libsofthsm2 (2.6.1-2.2ubuntu3) ... 91s Setting up softhsm2 (2.6.1-2.2ubuntu3) ... 91s Setting up autopkgtest-satdep (0) ... 91s Processing triggers for man-db (2.12.1-3) ... 93s (Reading database ... 75645 files and directories currently installed.) 93s Removing autopkgtest-satdep (0) ... 94s autopkgtest [09:39:05]: test unittest: [----------------------- 94s The token has been initialized and is reassigned to slot 204738806 94s test_CKH (test_CK.TestUtil.test_CKH) ... ok 94s test_CKM (test_CK.TestUtil.test_CKM) ... ok 94s test_CKR (test_CK.TestUtil.test_CKR) ... ok 94s test_LowLevel (test_LowLevel.TestUtil.test_LowLevel) ... ok 94s test_RSA_OAEP (test_asymetric.TestUtil.test_RSA_OAEP) ... ok 94s test_RSA_OAEPwithAAD (test_asymetric.TestUtil.test_RSA_OAEPwithAAD) ... skipped "'AAD' not (yet) supported." 94s test_RSA_PSS_SHA1 (test_asymetric.TestUtil.test_RSA_PSS_SHA1) ... ok 94s test_RSA_PSS_SHA256 (test_asymetric.TestUtil.test_RSA_PSS_SHA256) ... ok 94s test_encrypt_PKCS (test_asymetric.TestUtil.test_encrypt_PKCS) ... ok 94s test_encrypt_X509 (test_asymetric.TestUtil.test_encrypt_X509) ... ok 94s test_pubKey (test_asymetric.TestUtil.test_pubKey) ... ok 94s test_sign_PKCS (test_asymetric.TestUtil.test_sign_PKCS) ... ok 94s test_sign_PKCS_SHA256 (test_asymetric.TestUtil.test_sign_PKCS_SHA256) ... ok 94s test_sign_X509 (test_asymetric.TestUtil.test_sign_X509) ... ok 94s test_sign_integer (test_asymetric.TestUtil.test_sign_integer) ... ok 94s test_sign_integer (test_asymetric_ECC.TestUtil.test_sign_integer) ... ok 94s test_sign_text (test_asymetric_ECC.TestUtil.test_sign_text) ... ok 94s test_gost (test_asymetric_gost.TestUtil.test_gost) ... skipped 'GOST not supported by SoftHSMv2 on Windows?' 94s test_append (test_ckbytelist.Testutil.test_append) ... ok 94s test_data (test_ckbytelist.Testutil.test_data) ... ok 94s test_empty (test_ckbytelist.Testutil.test_empty) ... ok 94s test_init_bytes (test_ckbytelist.Testutil.test_init_bytes) ... ok 94s test_init_ckbytelist (test_ckbytelist.Testutil.test_init_ckbytelist) ... ok 94s test_init_list0 (test_ckbytelist.Testutil.test_init_list0) ... ok 94s test_init_list1 (test_ckbytelist.Testutil.test_init_list1) ... ok 94s test_init_list5 (test_ckbytelist.Testutil.test_init_list5) ... ok 94s test_init_str (test_ckbytelist.Testutil.test_init_str) ... ok 94s test_length0 (test_ckbytelist.Testutil.test_length0) ... ok 94s test_length5 (test_ckbytelist.Testutil.test_length5) ... ok 94s test_resize (test_ckbytelist.Testutil.test_resize) ... ok 94s test_string (test_ckbytelist.Testutil.test_string) ... ok 94s test_unknown_format (test_ckbytelist.Testutil.test_unknown_format) ... ok 94s test_deriveKey_CKM_CONCATENATE_BASE_AND_DATA (test_derive.TestUtil.test_deriveKey_CKM_CONCATENATE_BASE_AND_DATA) ... skipped 'CKM_CONCATENATE_BASE_AND_DATA is not supported by SoftHSM <= 2.6' 95s test_deriveKey_CKM_CONCATENATE_BASE_AND_KEY (test_derive.TestUtil.test_deriveKey_CKM_CONCATENATE_BASE_AND_KEY) ... skipped 'CKM_CONCATENATE_BASE_AND_KEY is not supported by SoftHSM <= 2.6' 95s test_deriveKey_CKM_CONCATENATE_DATA_AND_BASE (test_derive.TestUtil.test_deriveKey_CKM_CONCATENATE_DATA_AND_BASE) ... skipped 'CKM_CONCATENATE_DATA_AND_BASE is not supported by SoftHSM <= 2.6' 95s test_deriveKey_CKM_XOR_BASE_AND_DATA (test_derive.TestUtil.test_deriveKey_CKM_XOR_BASE_AND_DATA) ... skipped 'SoftHSM does not support CKM_XOR_BASE_AND_DATA' 95s test_deriveKey_ECDH1_DERIVE (test_derive.TestUtil.test_deriveKey_ECDH1_DERIVE) ... ok 95s test_digest (test_digest.TestUtil.test_digest) ... ok 95s test_digestSession (test_digest.TestUtil.test_digestSession) ... ok 95s test_CKR_OK (test_exception.Testutil.test_CKR_OK) ... ok 95s test_CKR_PIN_INVALID (test_exception.Testutil.test_CKR_PIN_INVALID) ... ok 95s test_Load (test_exception.Testutil.test_Load) ... ok 95s test_empty (test_exception.Testutil.test_empty) ... ok 95s test_raise (test_exception.Testutil.test_raise) ... ok 95s test_unknown (test_exception.Testutil.test_unknown) ... ok 95s test_vendor0 (test_exception.Testutil.test_vendor0) ... ok 95s test_vendor10 (test_exception.Testutil.test_vendor10) ... ok 95s test_gc (test_gc.TestUtil.test_gc) ... ok 95s test_getInfo (test_info.TestUtil.test_getInfo) ... ok 95s test_getMechanismList (test_info.TestUtil.test_getMechanismList) ... ok 95s test_getSessionInfo (test_info.TestUtil.test_getSessionInfo) ... ok 95s test_getSlotInfo (test_info.TestUtil.test_getSlotInfo) ... ok 95s test_getTokenInfo (test_info.TestUtil.test_getTokenInfo) ... ok 95s test_initPin (test_init.TestUtil.test_initPin) ... ok 95s test_initToken (test_init.TestUtil.test_initToken) ... ok 95s test_initToken_utf8 (test_init.TestUtil.test_initToken_utf8) ... ok 95s test_setPin (test_init.TestUtil.test_setPin) ... ok 95s test_invalid_load (test_load.TestUtil.test_invalid_load) ... ok 95s test_load (test_load.TestUtil.test_load) ... ok 95s test_multiple_load (test_load.TestUtil.test_multiple_load) ... ok 95s test_specific_load (test_load.TestUtil.test_specific_load) ... ok 95s test_unload (test_load.TestUtil.test_unload) ... ok 95s test_getAttributeValue (test_objects.TestGetSetAttributeValues.test_getAttributeValue) ... ok 95s test_setAttributeValue_with_a_list_of_attributes (test_objects.TestGetSetAttributeValues.test_setAttributeValue_with_a_list_of_attributes) ... ok 95s test_setAttributeValue_with_label_attribute (test_objects.TestGetSetAttributeValues.test_setAttributeValue_with_label_attribute) ... ok 95s test_setAttributeValue_with_single_binary_attribute (test_objects.TestGetSetAttributeValues.test_setAttributeValue_with_single_binary_attribute) ... ok 95s test_BoolAttributes (test_objects.TestUtil.test_BoolAttributes) ... ok 95s test_objects (test_objects.TestUtil.test_objects) ... ok 95s test_binary (test_pin.TestUtil.test_binary) ... ok 95s test_ckbytelist (test_pin.TestUtil.test_ckbytelist) ... ok 95s test_login (test_pin.TestUtil.test_login) ... ok 95s test_null (test_pin.TestUtil.test_null) ... ok 95s test_wrong (test_pin.TestUtil.test_wrong) ... ok 95s test_generateRandom (test_random.TestUtil.test_generateRandom) ... ok 95s test_seedRandom (test_random.TestUtil.test_seedRandom) ... ok 95s test_symetric (test_symetric.TestUtil.test_symetric) ... ok 95s test_wrapKey (test_wrap.TestUtil.test_wrapKey) ... ok 95s test_wrapKey_OAEP (test_wrap.TestUtil.test_wrapKey_OAEP) ... ok 95s test_wrapKey_UNWRAP_TEMPLATE (test_wrap.TestUtil.test_wrapKey_UNWRAP_TEMPLATE) ... ok 95s 95s ---------------------------------------------------------------------- 95s Ran 79 tests in 0.744s 95s 95s OK (skipped=6) 95s src/dyn_unix.c:34:SYS_dyn_LoadLibrary() NoFile: cannot open shared object file: No such file or directory 95s src/dyn_unix.c:76:SYS_dyn_GetAddress() C_GetFunctionList: /lib/x86_64-linux-gnu/libc.so.6: undefined symbol: C_GetFunctionList 95s src/dyn_unix.c:34:SYS_dyn_LoadLibrary() nolib: cannot open shared object file: No such file or directory 95s src/dyn_unix.c:76:SYS_dyn_GetAddress() C_GetFunctionList: /lib/x86_64-linux-gnu/libc.so.6: undefined symbol: C_GetFunctionList 95s src/dyn_unix.c:76:SYS_dyn_GetAddress() C_GetFunctionList: /lib/x86_64-linux-gnu/libc.so.6: undefined symbol: C_GetFunctionList 95s autopkgtest [09:39:06]: test unittest: -----------------------] 95s autopkgtest [09:39:06]: test unittest: - - - - - - - - - - results - - - - - - - - - - 95s unittest PASS 96s autopkgtest [09:39:07]: @@@@@@@@@@@@@@@@@@@@ summary 96s unittest PASS 106s virt: nova [W] Skipping flock for amd64 106s virt: Creating nova instance adt-plucky-amd64-pykcs11-20241113-093730-juju-7f2275-prod-proposed-migration-environment-2-1cde3453-f299-4561-9c8d-0d017219803d from image adt/ubuntu-plucky-amd64-server-20241113.img (UUID 76b850f9-98f4-4b79-af06-fa11000b95b2)...