0s autopkgtest [02:46:42]: starting date and time: 2024-06-29 02:46:42+0000 0s autopkgtest [02:46:42]: git checkout: 85adf9a1 setup-testbed: prevent /tmp from getting a tmpfs mount 0s autopkgtest [02:46:42]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.3asqjznl/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_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\n" >> /etc/environment' --apt-pocket=proposed=src:glibc --apt-upgrade poolcounter --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.39-3.1ubuntu2 -- lxd -r lxd-armhf-10.145.243.202 lxd-armhf-10.145.243.202:autopkgtest/ubuntu/oracular/armhf 36s autopkgtest [02:47:18]: testbed dpkg architecture: armhf 37s autopkgtest [02:47:19]: testbed apt version: 2.9.5 37s autopkgtest [02:47:19]: @@@@@@@@@@@@@@@@@@@@ test bed setup 46s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [110 kB] 47s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [385 kB] 47s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [2576 B] 47s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [55.3 kB] 47s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [7052 B] 47s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf Packages [77.0 kB] 47s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/restricted armhf Packages [1368 B] 47s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/universe armhf Packages [324 kB] 47s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse armhf Packages [1768 B] 47s Fetched 964 kB in 2s (522 kB/s) 48s Reading package lists... 67s tee: /proc/self/fd/2: Permission denied 85s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [110 kB] 86s Get:2 http://ftpmaster.internal/ubuntu oracular InRelease [110 kB] 86s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 86s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 86s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [55.3 kB] 86s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [384 kB] 86s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf Packages [78.0 kB] 86s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/universe armhf Packages [326 kB] 87s Fetched 1065 kB in 1s (783 kB/s) 88s Reading package lists... 88s Reading package lists... 88s Building dependency tree... 88s Reading state information... 89s Calculating upgrade... 90s The following packages will be upgraded: 90s libc-bin libc6 locales 90s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 90s Need to get 7572 kB of archives. 90s After this operation, 16.4 kB of additional disk space will be used. 90s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf libc6 armhf 2.39-3.1ubuntu2 [2824 kB] 90s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf libc-bin armhf 2.39-3.1ubuntu2 [528 kB] 90s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf locales all 2.39-3.1ubuntu2 [4220 kB] 91s Preconfiguring packages ... 92s Fetched 7572 kB in 1s (9276 kB/s) 92s (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 ... 58374 files and directories currently installed.) 92s Preparing to unpack .../libc6_2.39-3.1ubuntu2_armhf.deb ... 92s Unpacking libc6:armhf (2.39-3.1ubuntu2) over (2.39-0ubuntu9) ... 93s Setting up libc6:armhf (2.39-3.1ubuntu2) ... 93s (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 ... 58374 files and directories currently installed.) 93s Preparing to unpack .../libc-bin_2.39-3.1ubuntu2_armhf.deb ... 93s Unpacking libc-bin (2.39-3.1ubuntu2) over (2.39-0ubuntu9) ... 93s Setting up libc-bin (2.39-3.1ubuntu2) ... 94s (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 ... 58374 files and directories currently installed.) 94s Preparing to unpack .../locales_2.39-3.1ubuntu2_all.deb ... 94s Unpacking locales (2.39-3.1ubuntu2) over (2.39-0ubuntu9) ... 94s Setting up locales (2.39-3.1ubuntu2) ... 95s Generating locales (this might take a while)... 98s en_US.UTF-8... done 98s Generation complete. 98s Processing triggers for man-db (2.12.1-2) ... 99s Reading package lists... 100s Building dependency tree... 100s Reading state information... 101s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 103s autopkgtest [02:48:25]: rebooting testbed after setup commands that affected boot 145s autopkgtest [02:49:07]: testbed running kernel: Linux 6.5.0-35-generic #35~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Tue May 7 11:19:33 UTC 2 173s autopkgtest [02:49:35]: @@@@@@@@@@@@@@@@@@@@ apt-source poolcounter 183s Get:1 http://ftpmaster.internal/ubuntu oracular/universe poolcounter 1.1.3-2build4 (dsc) [2139 B] 183s Get:2 http://ftpmaster.internal/ubuntu oracular/universe poolcounter 1.1.3-2build4 (tar) [26.9 kB] 183s Get:3 http://ftpmaster.internal/ubuntu oracular/universe poolcounter 1.1.3-2build4 (diff) [3796 B] 183s gpgv: Signature made Mon Apr 1 07:12:32 2024 UTC 183s gpgv: using RSA key A089FB36AAFBDAD5ACC1325069F790171A210984 183s gpgv: Can't check signature: No public key 183s dpkg-source: warning: cannot verify inline signature for ./poolcounter_1.1.3-2build4.dsc: no acceptable signature found 183s autopkgtest [02:49:45]: testing package poolcounter version 1.1.3-2build4 185s autopkgtest [02:49:47]: build not needed 187s autopkgtest [02:49:49]: test command1: preparing testbed 197s Reading package lists... 197s Building dependency tree... 197s Reading state information... 198s Starting pkgProblemResolver with broken count: 0 198s Starting 2 pkgProblemResolver with broken count: 0 198s Done 199s The following additional packages will be installed: 199s libevent-2.1-7t64 poolcounter python3-iniconfig python3-packaging 199s python3-pluggy python3-pytest 199s The following NEW packages will be installed: 199s autopkgtest-satdep libevent-2.1-7t64 poolcounter python3-iniconfig 199s python3-packaging python3-pluggy python3-pytest 199s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 199s Need to get 514 kB/515 kB of archives. 199s After this operation, 1842 kB of additional disk space will be used. 199s Get:1 /tmp/autopkgtest.07FwIG/1-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [716 B] 200s Get:2 http://ftpmaster.internal/ubuntu oracular/main armhf libevent-2.1-7t64 armhf 2.1.12-stable-10 [127 kB] 200s Get:3 http://ftpmaster.internal/ubuntu oracular/universe armhf poolcounter armhf 1.1.3-2build4 [13.4 kB] 200s Get:4 http://ftpmaster.internal/ubuntu oracular/universe armhf python3-iniconfig all 1.1.1-2 [6024 B] 200s Get:5 http://ftpmaster.internal/ubuntu oracular/main armhf python3-packaging all 24.0-1 [41.1 kB] 200s Get:6 http://ftpmaster.internal/ubuntu oracular/universe armhf python3-pluggy all 1.5.0-1 [21.0 kB] 200s Get:7 http://ftpmaster.internal/ubuntu oracular/universe armhf python3-pytest all 7.4.4-1 [305 kB] 201s Fetched 514 kB in 1s (877 kB/s) 201s Selecting previously unselected package libevent-2.1-7t64:armhf. 201s (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 ... 58374 files and directories currently installed.) 201s Preparing to unpack .../0-libevent-2.1-7t64_2.1.12-stable-10_armhf.deb ... 201s Unpacking libevent-2.1-7t64:armhf (2.1.12-stable-10) ... 201s Selecting previously unselected package poolcounter. 201s Preparing to unpack .../1-poolcounter_1.1.3-2build4_armhf.deb ... 201s Unpacking poolcounter (1.1.3-2build4) ... 201s Selecting previously unselected package python3-iniconfig. 201s Preparing to unpack .../2-python3-iniconfig_1.1.1-2_all.deb ... 201s Unpacking python3-iniconfig (1.1.1-2) ... 201s Selecting previously unselected package python3-packaging. 201s Preparing to unpack .../3-python3-packaging_24.0-1_all.deb ... 201s Unpacking python3-packaging (24.0-1) ... 201s Selecting previously unselected package python3-pluggy. 201s Preparing to unpack .../4-python3-pluggy_1.5.0-1_all.deb ... 201s Unpacking python3-pluggy (1.5.0-1) ... 201s Selecting previously unselected package python3-pytest. 201s Preparing to unpack .../5-python3-pytest_7.4.4-1_all.deb ... 201s Unpacking python3-pytest (7.4.4-1) ... 201s Selecting previously unselected package autopkgtest-satdep. 201s Preparing to unpack .../6-1-autopkgtest-satdep.deb ... 201s Unpacking autopkgtest-satdep (0) ... 201s Setting up python3-iniconfig (1.1.1-2) ... 202s Setting up libevent-2.1-7t64:armhf (2.1.12-stable-10) ... 202s Setting up python3-packaging (24.0-1) ... 202s Setting up poolcounter (1.1.3-2build4) ... 202s info: Selecting GID from range 100 to 999 ... 202s info: Adding group `poolcounter' (GID 108) ... 202s info: The home dir / you specified already exists. 202s 202s info: Selecting UID from range 100 to 999 ... 202s 202s info: Adding system user `poolcounter' (UID 107) ... 202s info: Adding new user `poolcounter' (UID 107) with group `poolcounter' ... 202s warn: The home directory `/' already exists. Not touching this directory. 202s warn: Warning: The home directory `/' does not belong to the user you are currently creating. 202s Created symlink /etc/systemd/system/multi-user.target.wants/poolcounter.service → /usr/lib/systemd/system/poolcounter.service. 203s Setting up python3-pluggy (1.5.0-1) ... 203s Setting up python3-pytest (7.4.4-1) ... 203s Setting up autopkgtest-satdep (0) ... 203s Processing triggers for man-db (2.12.1-2) ... 204s Processing triggers for libc-bin (2.39-3.1ubuntu2) ... 218s (Reading database ... 58550 files and directories currently installed.) 218s Removing autopkgtest-satdep (0) ... 224s autopkgtest [02:50:26]: test command1: POOLCOUNTERD=/usr/bin/poolcounterd pytest-3 -v 224s autopkgtest [02:50:26]: test command1: [----------------------- 226s ============================= test session starts ============================== 226s platform linux -- Python 3.12.4, pytest-7.4.4, pluggy-1.5.0 -- /usr/bin/python3 226s cachedir: .pytest_cache 226s rootdir: /tmp/autopkgtest.07FwIG/build.weP/src 226s collecting ... collected 74 items 226s 226s tests/test_errors.py::test_garbage PASSED [ 1%] 226s tests/test_errors.py::test_locking_just_lockname[ACQ4ME] PASSED [ 2%] 226s tests/test_errors.py::test_locking_just_lockname[ACQ4ANY] PASSED [ 4%] 226s tests/test_errors.py::test_locking_no_workers[ACQ4ME] PASSED [ 5%] 226s tests/test_errors.py::test_locking_no_workers[ACQ4ANY] PASSED [ 6%] 226s tests/test_errors.py::test_locking_no_queue[ACQ4ME] PASSED [ 8%] 226s tests/test_errors.py::test_locking_no_queue[ACQ4ANY] PASSED [ 9%] 226s tests/test_errors.py::test_locking_non_integers[ACQ4ME] PASSED [ 10%] 226s tests/test_errors.py::test_locking_non_integers[ACQ4ANY] PASSED [ 12%] 231s tests/test_errors.py::test_locking_while_waiting[ACQ4ME] XFAIL [ 13%] 231s tests/test_errors.py::test_locking_while_waiting[ACQ4ANY] XPASS [ 14%] 231s tests/test_errors.py::test_invalid_listen PASSED [ 16%] 231s tests/test_single_client.py::test_can_lock[ACQ4ME] PASSED [ 17%] 231s tests/test_single_client.py::test_can_lock[ACQ4ANY] PASSED [ 18%] 231s tests/test_single_client.py::test_can_lock_and_release[ACQ4ME] PASSED [ 20%] 231s tests/test_single_client.py::test_can_lock_and_release[ACQ4ANY] PASSED [ 21%] 231s tests/test_single_client.py::test_can_lock_and_release_and_relock[ACQ4ME] PASSED [ 22%] 231s tests/test_single_client.py::test_can_lock_and_release_and_relock[ACQ4ANY] PASSED [ 24%] 231s tests/test_single_client.py::test_can_lock_and_release_and_relock_and_unlock[ACQ4ME] PASSED [ 25%] 231s tests/test_single_client.py::test_can_lock_and_release_and_relock_and_unlock[ACQ4ANY] PASSED [ 27%] 231s tests/test_single_client.py::test_release_without_lock PASSED [ 28%] 231s tests/test_single_client.py::test_locking_holding_four_locks[ACQ4ME] PASSED [ 29%] 231s tests/test_single_client.py::test_locking_holding_four_locks[ACQ4ANY] PASSED [ 31%] 231s tests/test_single_client.py::test_locking_no_timeout[ACQ4ME] PASSED [ 32%] 231s tests/test_single_client.py::test_locking_no_timeout[ACQ4ANY] PASSED [ 33%] 231s tests/test_single_client.py::test_locking_timeout_left_off[ACQ4ME] PASSED [ 35%] 231s tests/test_single_client.py::test_locking_timeout_left_off[ACQ4ANY] PASSED [ 36%] 231s tests/test_single_client.py::test_locking_garbage_timeout[ACQ4ME] PASSED [ 37%] 231s tests/test_single_client.py::test_locking_garbage_timeout[ACQ4ANY] PASSED [ 39%] 231s tests/test_single_client.py::test_same_lock_twice[ACQ4ME] PASSED [ 40%] 231s tests/test_single_client.py::test_same_lock_twice[ACQ4ANY] PASSED [ 41%] 232s tests/test_single_client.py::test_same_lock_twice_timeout[ACQ4ME] PASSED [ 43%] 233s tests/test_single_client.py::test_same_lock_twice_timeout[ACQ4ANY] PASSED [ 44%] 233s tests/test_single_client.py::test_same_lock_twice_zero_timeout[ACQ4ME] PASSED [ 45%] 233s tests/test_single_client.py::test_same_lock_twice_zero_timeout[ACQ4ANY] PASSED [ 47%] 233s tests/test_single_client.py::test_same_lock_twice_queue_full[ACQ4ME] PASSED [ 48%] 233s tests/test_single_client.py::test_same_lock_twice_queue_full[ACQ4ANY] PASSED [ 50%] 234s tests/test_single_client.py::test_timeout_doesnt_unlock[ACQ4ME] PASSED [ 51%] 234s tests/test_single_client.py::test_timeout_doesnt_unlock[ACQ4ANY] PASSED [ 52%] 234s tests/test_single_client.py::test_timeout_doesnt_consume[ACQ4ME-0] PASSED [ 54%] 234s tests/test_single_client.py::test_timeout_doesnt_consume[ACQ4ME-1] PASSED [ 55%] 234s tests/test_single_client.py::test_timeout_doesnt_consume[ACQ4ANY-0] PASSED [ 56%] 234s tests/test_single_client.py::test_timeout_doesnt_consume[ACQ4ANY-1] PASSED [ 58%] 234s tests/test_single_client.py::test_no_queue_slots_not_consumed[ACQ4ME] PASSED [ 59%] 234s tests/test_single_client.py::test_no_queue_slots_not_consumed[ACQ4ANY] PASSED [ 60%] 234s tests/test_single_client.py::test_release_not_consumed[ACQ4ME] PASSED [ 62%] 234s tests/test_single_client.py::test_release_not_consumed[ACQ4ANY] PASSED [ 63%] 234s tests/test_single_client.py::test_disconnect_release[ACQ4ME] PASSED [ 64%] 234s tests/test_single_client.py::test_disconnect_release[ACQ4ANY] PASSED [ 66%] 234s tests/test_stats.py::test_uptime PASSED [ 67%] 234s tests/test_stats.py::test_full PASSED [ 68%] 234s tests/test_stats.py::test_waiting_workers PASSED [ 70%] 234s tests/test_stats.py::test_hashtable_entries PASSED [ 71%] 234s tests/test_two_clients.py::test_both_lock[ACQ4ME] PASSED [ 72%] 234s tests/test_two_clients.py::test_both_lock[ACQ4ANY] PASSED [ 74%] 234s tests/test_two_clients.py::test_only_one_lock_no_queue_space[ACQ4ME] PASSED [ 75%] 234s tests/test_two_clients.py::test_only_one_lock_no_queue_space[ACQ4ANY] PASSED [ 77%] 234s tests/test_two_clients.py::test_only_one_lock_no_workers_nor_queue_space[ACQ4ME] PASSED [ 78%] 234s tests/test_two_clients.py::test_only_one_lock_no_workers_nor_queue_space[ACQ4ANY] PASSED [ 79%] 234s tests/test_two_clients.py::test_close_socket_release PASSED [ 81%] 235s tests/test_two_clients.py::test_second_timeout_gt_zero PASSED [ 82%] 235s tests/test_two_clients.py::test_second_timeout_eq_zero PASSED [ 83%] 237s tests/test_two_clients.py::test_second_timeout_after_first_finish PASSED [ 85%] 237s tests/test_two_clients.py::test_second_timeout_after_first_finish_zero_timeout PASSED [ 86%] 237s tests/test_two_clients.py::test_second_lock_first_unlock PASSED [ 87%] 237s tests/test_two_clients.py::test_second_lock_first_unlock_any XPASS [ 89%] 237s tests/test_two_clients.py::test_done_no_unlock XPASS [ 90%] 237s tests/test_two_clients.py::test_done_no_consume_lock XPASS [ 91%] 237s tests/test_two_clients.py::test_lock_twice_multiple_executors[ACQ4ME] PASSED [ 93%] 237s tests/test_two_clients.py::test_lock_twice_multiple_executors[ACQ4ANY] PASSED [ 94%] 237s tests/test_two_clients.py::test_disconnect_while_timeout[ACQ4ME] PASSED [ 95%] 237s tests/test_two_clients.py::test_disconnect_while_timeout[ACQ4ANY] PASSED [ 97%] 242s tests/test_two_clients.py::test_no_timeout_after_initial_delay PASSED [ 98%] 247s tests/test_two_clients.py::test_no_early_timeout_after_initial_delay PASSED [100%] 247s 247s ================== 69 passed, 1 xfailed, 4 xpassed in 20.34s =================== 247s autopkgtest [02:50:49]: test command1: -----------------------] 251s command1 PASS 251s autopkgtest [02:50:53]: test command1: - - - - - - - - - - results - - - - - - - - - - 255s autopkgtest [02:50:57]: @@@@@@@@@@@@@@@@@@@@ summary 255s command1 PASS