0s autopkgtest [11:42:26]: starting date and time: 2025-05-03 11:42:26+0000 0s autopkgtest [11:42:26]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [11:42:26]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.n9_aof5v/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:pytest --apt-upgrade poolcounter --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 builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-17.secgroup --name adt-questing-amd64-poolcounter-20250503-114226-juju-7f2275-prod-proposed-migration-environment-20-053cc8d9-5dbc-4616-8be7-6e3f0bb54ac2 --image adt/ubuntu-questing-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 64s autopkgtest [11:43:30]: testbed dpkg architecture: amd64 64s autopkgtest [11:43:30]: testbed apt version: 3.0.0 64s autopkgtest [11:43:30]: @@@@@@@@@@@@@@@@@@@@ test bed setup 65s autopkgtest [11:43:31]: testbed release detected to be: None 65s autopkgtest [11:43:31]: updating testbed package index (apt update) 66s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 66s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 66s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 66s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 66s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [44.6 kB] 66s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [1655 kB] 66s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [155 kB] 66s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main i386 Packages [146 kB] 66s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 Packages [198 kB] 66s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 Packages [1372 kB] 66s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe i386 Packages [562 kB] 67s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse i386 Packages [13.2 kB] 67s Get:13 http://ftpmaster.internal/ubuntu questing-proposed/multiverse amd64 Packages [15.0 kB] 67s Fetched 4272 kB in 1s (3832 kB/s) 68s Reading package lists... 68s autopkgtest [11:43:34]: upgrading testbed (apt dist-upgrade and autopurge) 69s Reading package lists... 69s Building dependency tree... 69s Reading state information... 69s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 69s Starting 2 pkgProblemResolver with broken count: 0 69s Done 70s Entering ResolveByKeep 70s 70s Calculating upgrade... 70s The following packages will be upgraded: 70s debianutils libnftnl11 python3-lazr.restfulclient 70s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 70s Need to get 213 kB of archives. 70s After this operation, 3072 B disk space will be freed. 70s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 debianutils amd64 5.22 [93.0 kB] 70s Get:2 http://ftpmaster.internal/ubuntu questing/main amd64 libnftnl11 amd64 1.2.9-1 [69.1 kB] 70s Get:3 http://ftpmaster.internal/ubuntu questing/main amd64 python3-lazr.restfulclient all 0.14.6-3 [51.0 kB] 71s Fetched 213 kB in 0s (527 kB/s) 71s (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 ... 80514 files and directories currently installed.) 71s Preparing to unpack .../debianutils_5.22_amd64.deb ... 71s Unpacking debianutils (5.22) over (5.21) ... 71s Setting up debianutils (5.22) ... 72s (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 ... 80514 files and directories currently installed.) 72s Preparing to unpack .../libnftnl11_1.2.9-1_amd64.deb ... 72s Unpacking libnftnl11:amd64 (1.2.9-1) over (1.2.8-1) ... 72s Preparing to unpack .../python3-lazr.restfulclient_0.14.6-3_all.deb ... 72s Unpacking python3-lazr.restfulclient (0.14.6-3) over (0.14.6-2) ... 72s Setting up libnftnl11:amd64 (1.2.9-1) ... 72s Setting up python3-lazr.restfulclient (0.14.6-3) ... 72s Processing triggers for man-db (2.13.0-1) ... 73s Processing triggers for libc-bin (2.41-6ubuntu1) ... 74s Reading package lists... 74s Building dependency tree... 74s Reading state information... 74s Starting pkgProblemResolver with broken count: 0 74s Starting 2 pkgProblemResolver with broken count: 0 74s Done 75s Solving dependencies... 75s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 78s autopkgtest [11:43:44]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP PREEMPT_DYNAMIC Sun Apr 6 15:05:05 UTC 2025 78s autopkgtest [11:43:44]: @@@@@@@@@@@@@@@@@@@@ apt-source poolcounter 79s Get:1 http://ftpmaster.internal/ubuntu questing/universe poolcounter 1.1.3-3 (dsc) [2065 B] 79s Get:2 http://ftpmaster.internal/ubuntu questing/universe poolcounter 1.1.3-3 (tar) [26.9 kB] 79s Get:3 http://ftpmaster.internal/ubuntu questing/universe poolcounter 1.1.3-3 (diff) [3480 B] 79s gpgv: Signature made Sun Jun 30 05:09:49 2024 UTC 79s gpgv: using RSA key D8CB59F05DBB9E0538C4819DF105F8101B05269B 79s gpgv: issuer "legoktm@riseup.net" 79s gpgv: Can't check signature: No public key 79s dpkg-source: warning: cannot verify inline signature for ./poolcounter_1.1.3-3.dsc: no acceptable signature found 80s autopkgtest [11:43:46]: testing package poolcounter version 1.1.3-3 80s autopkgtest [11:43:46]: build not needed 80s autopkgtest [11:43:46]: test command1: preparing testbed 80s Reading package lists... 81s Building dependency tree... 81s Reading state information... 81s Starting pkgProblemResolver with broken count: 0 81s Starting 2 pkgProblemResolver with broken count: 0 81s Done 81s The following NEW packages will be installed: 81s libevent-2.1-7t64 poolcounter python3-iniconfig python3-pluggy 81s python3-pytest 82s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 82s Need to get 437 kB of archives. 82s After this operation, 1840 kB of additional disk space will be used. 82s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 libevent-2.1-7t64 amd64 2.1.12-stable-10 [144 kB] 82s Get:2 http://ftpmaster.internal/ubuntu questing/universe amd64 poolcounter amd64 1.1.3-3 [12.9 kB] 82s Get:3 http://ftpmaster.internal/ubuntu questing/universe amd64 python3-iniconfig all 1.1.1-2 [6024 B] 82s Get:4 http://ftpmaster.internal/ubuntu questing/universe amd64 python3-pluggy all 1.5.0-1 [21.0 kB] 82s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 python3-pytest all 8.3.5-2 [252 kB] 82s Fetched 437 kB in 1s (871 kB/s) 82s Selecting previously unselected package libevent-2.1-7t64:amd64. 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 ... 80514 files and directories currently installed.) 82s Preparing to unpack .../libevent-2.1-7t64_2.1.12-stable-10_amd64.deb ... 82s Unpacking libevent-2.1-7t64:amd64 (2.1.12-stable-10) ... 82s Selecting previously unselected package poolcounter. 82s Preparing to unpack .../poolcounter_1.1.3-3_amd64.deb ... 82s Unpacking poolcounter (1.1.3-3) ... 82s Selecting previously unselected package python3-iniconfig. 83s Preparing to unpack .../python3-iniconfig_1.1.1-2_all.deb ... 83s Unpacking python3-iniconfig (1.1.1-2) ... 83s Selecting previously unselected package python3-pluggy. 83s Preparing to unpack .../python3-pluggy_1.5.0-1_all.deb ... 83s Unpacking python3-pluggy (1.5.0-1) ... 83s Selecting previously unselected package python3-pytest. 83s Preparing to unpack .../python3-pytest_8.3.5-2_all.deb ... 83s Unpacking python3-pytest (8.3.5-2) ... 83s Setting up python3-iniconfig (1.1.1-2) ... 83s Setting up libevent-2.1-7t64:amd64 (2.1.12-stable-10) ... 83s Setting up poolcounter (1.1.3-3) ... 83s Created symlink '/etc/systemd/system/multi-user.target.wants/poolcounter.service' → '/usr/lib/systemd/system/poolcounter.service'. 83s Setting up python3-pluggy (1.5.0-1) ... 83s Setting up python3-pytest (8.3.5-2) ... 84s Processing triggers for man-db (2.13.0-1) ... 84s Processing triggers for libc-bin (2.41-6ubuntu1) ... 85s autopkgtest [11:43:51]: test command1: POOLCOUNTERD=/usr/bin/poolcounterd pytest-3 -v 85s autopkgtest [11:43:51]: test command1: [----------------------- 86s ============================= test session starts ============================== 86s platform linux -- Python 3.13.3, pytest-8.3.5, pluggy-1.5.0 -- /usr/bin/python3 86s cachedir: .pytest_cache 86s rootdir: /tmp/autopkgtest.afr9Qv/build.zH5/src 86s plugins: typeguard-4.4.2 86s collecting ... collected 74 items 86s 86s tests/test_errors.py::test_garbage PASSED [ 1%] 86s tests/test_errors.py::test_locking_just_lockname[ACQ4ME] PASSED [ 2%] 86s tests/test_errors.py::test_locking_just_lockname[ACQ4ANY] PASSED [ 4%] 86s tests/test_errors.py::test_locking_no_workers[ACQ4ME] PASSED [ 5%] 86s tests/test_errors.py::test_locking_no_workers[ACQ4ANY] PASSED [ 6%] 86s tests/test_errors.py::test_locking_no_queue[ACQ4ME] PASSED [ 8%] 86s tests/test_errors.py::test_locking_no_queue[ACQ4ANY] PASSED [ 9%] 86s tests/test_errors.py::test_locking_non_integers[ACQ4ME] PASSED [ 10%] 86s tests/test_errors.py::test_locking_non_integers[ACQ4ANY] PASSED [ 12%] 91s tests/test_errors.py::test_locking_while_waiting[ACQ4ME] XFAIL [ 13%] 91s tests/test_errors.py::test_locking_while_waiting[ACQ4ANY] XPASS [ 14%] 91s tests/test_errors.py::test_invalid_listen PASSED [ 16%] 91s tests/test_single_client.py::test_can_lock[ACQ4ME] PASSED [ 17%] 91s tests/test_single_client.py::test_can_lock[ACQ4ANY] PASSED [ 18%] 91s tests/test_single_client.py::test_can_lock_and_release[ACQ4ME] PASSED [ 20%] 91s tests/test_single_client.py::test_can_lock_and_release[ACQ4ANY] PASSED [ 21%] 91s tests/test_single_client.py::test_can_lock_and_release_and_relock[ACQ4ME] PASSED [ 22%] 91s tests/test_single_client.py::test_can_lock_and_release_and_relock[ACQ4ANY] PASSED [ 24%] 91s tests/test_single_client.py::test_can_lock_and_release_and_relock_and_unlock[ACQ4ME] PASSED [ 25%] 91s tests/test_single_client.py::test_can_lock_and_release_and_relock_and_unlock[ACQ4ANY] PASSED [ 27%] 91s tests/test_single_client.py::test_release_without_lock PASSED [ 28%] 91s tests/test_single_client.py::test_locking_holding_four_locks[ACQ4ME] PASSED [ 29%] 91s tests/test_single_client.py::test_locking_holding_four_locks[ACQ4ANY] PASSED [ 31%] 91s tests/test_single_client.py::test_locking_no_timeout[ACQ4ME] PASSED [ 32%] 91s tests/test_single_client.py::test_locking_no_timeout[ACQ4ANY] PASSED [ 33%] 91s tests/test_single_client.py::test_locking_timeout_left_off[ACQ4ME] PASSED [ 35%] 91s tests/test_single_client.py::test_locking_timeout_left_off[ACQ4ANY] PASSED [ 36%] 91s tests/test_single_client.py::test_locking_garbage_timeout[ACQ4ME] PASSED [ 37%] 91s tests/test_single_client.py::test_locking_garbage_timeout[ACQ4ANY] PASSED [ 39%] 91s tests/test_single_client.py::test_same_lock_twice[ACQ4ME] PASSED [ 40%] 91s tests/test_single_client.py::test_same_lock_twice[ACQ4ANY] PASSED [ 41%] 92s tests/test_single_client.py::test_same_lock_twice_timeout[ACQ4ME] PASSED [ 43%] 93s tests/test_single_client.py::test_same_lock_twice_timeout[ACQ4ANY] PASSED [ 44%] 93s tests/test_single_client.py::test_same_lock_twice_zero_timeout[ACQ4ME] PASSED [ 45%] 93s tests/test_single_client.py::test_same_lock_twice_zero_timeout[ACQ4ANY] PASSED [ 47%] 93s tests/test_single_client.py::test_same_lock_twice_queue_full[ACQ4ME] PASSED [ 48%] 93s tests/test_single_client.py::test_same_lock_twice_queue_full[ACQ4ANY] PASSED [ 50%] 93s tests/test_single_client.py::test_timeout_doesnt_unlock[ACQ4ME] PASSED [ 51%] 93s tests/test_single_client.py::test_timeout_doesnt_unlock[ACQ4ANY] PASSED [ 52%] 93s tests/test_single_client.py::test_timeout_doesnt_consume[ACQ4ME-0] PASSED [ 54%] 93s tests/test_single_client.py::test_timeout_doesnt_consume[ACQ4ME-1] PASSED [ 55%] 93s tests/test_single_client.py::test_timeout_doesnt_consume[ACQ4ANY-0] PASSED [ 56%] 93s tests/test_single_client.py::test_timeout_doesnt_consume[ACQ4ANY-1] PASSED [ 58%] 93s tests/test_single_client.py::test_no_queue_slots_not_consumed[ACQ4ME] PASSED [ 59%] 93s tests/test_single_client.py::test_no_queue_slots_not_consumed[ACQ4ANY] PASSED [ 60%] 93s tests/test_single_client.py::test_release_not_consumed[ACQ4ME] PASSED [ 62%] 93s tests/test_single_client.py::test_release_not_consumed[ACQ4ANY] PASSED [ 63%] 93s tests/test_single_client.py::test_disconnect_release[ACQ4ME] PASSED [ 64%] 93s tests/test_single_client.py::test_disconnect_release[ACQ4ANY] PASSED [ 66%] 93s tests/test_stats.py::test_uptime PASSED [ 67%] 93s tests/test_stats.py::test_full PASSED [ 68%] 93s tests/test_stats.py::test_waiting_workers PASSED [ 70%] 93s tests/test_stats.py::test_hashtable_entries PASSED [ 71%] 93s tests/test_two_clients.py::test_both_lock[ACQ4ME] PASSED [ 72%] 93s tests/test_two_clients.py::test_both_lock[ACQ4ANY] PASSED [ 74%] 93s tests/test_two_clients.py::test_only_one_lock_no_queue_space[ACQ4ME] PASSED [ 75%] 93s tests/test_two_clients.py::test_only_one_lock_no_queue_space[ACQ4ANY] PASSED [ 77%] 93s tests/test_two_clients.py::test_only_one_lock_no_workers_nor_queue_space[ACQ4ME] PASSED [ 78%] 93s tests/test_two_clients.py::test_only_one_lock_no_workers_nor_queue_space[ACQ4ANY] PASSED [ 79%] 93s tests/test_two_clients.py::test_close_socket_release PASSED [ 81%] 94s tests/test_two_clients.py::test_second_timeout_gt_zero PASSED [ 82%] 94s tests/test_two_clients.py::test_second_timeout_eq_zero PASSED [ 83%] 96s tests/test_two_clients.py::test_second_timeout_after_first_finish PASSED [ 85%] 96s tests/test_two_clients.py::test_second_timeout_after_first_finish_zero_timeout PASSED [ 86%] 96s tests/test_two_clients.py::test_second_lock_first_unlock PASSED [ 87%] 96s tests/test_two_clients.py::test_second_lock_first_unlock_any XPASS [ 89%] 96s tests/test_two_clients.py::test_done_no_unlock XPASS [ 90%] 96s tests/test_two_clients.py::test_done_no_consume_lock XPASS [ 91%] 96s tests/test_two_clients.py::test_lock_twice_multiple_executors[ACQ4ME] PASSED [ 93%] 96s tests/test_two_clients.py::test_lock_twice_multiple_executors[ACQ4ANY] PASSED [ 94%] 96s tests/test_two_clients.py::test_disconnect_while_timeout[ACQ4ME] PASSED [ 95%] 96s tests/test_two_clients.py::test_disconnect_while_timeout[ACQ4ANY] PASSED [ 97%] 101s tests/test_two_clients.py::test_no_timeout_after_initial_delay PASSED [ 98%] 106s tests/test_two_clients.py::test_no_early_timeout_after_initial_delay PASSED [100%] 106s 106s ================== 69 passed, 1 xfailed, 4 xpassed in 20.27s =================== 106s autopkgtest [11:44:12]: test command1: -----------------------] 107s command1 PASS 107s autopkgtest [11:44:13]: test command1: - - - - - - - - - - results - - - - - - - - - - 107s autopkgtest [11:44:13]: @@@@@@@@@@@@@@@@@@@@ summary 107s command1 PASS 124s nova [W] Skipping flock for amd64 124s Creating nova instance adt-questing-amd64-poolcounter-20250503-114226-juju-7f2275-prod-proposed-migration-environment-20-053cc8d9-5dbc-4616-8be7-6e3f0bb54ac2 from image adt/ubuntu-questing-amd64-server-20250503.img (UUID 57b7c1b6-ad80-4bdb-b031-36f045f7f3b3)... 124s nova [W] Timed out waiting for d128a8b1-e498-47ce-8b1c-604144d787f2 to get deleted.