0s autopkgtest [15:33:34]: starting date and time: 2026-01-24 15:33:34+0000 0s autopkgtest [15:33:34]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [15:33:34]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.iwahf20y/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults --apt-upgrade python-rq --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=python3-defaults/3.14.2-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-amd64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-3.secgroup --name adt-resolute-amd64-python-rq-20260124-153334-juju-7f2275-prod-proposed-migration-environment-15-99b6c0cd-ea07-42f0-bc01-13c2d64c544e --image adt/ubuntu-resolute-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-amd64-python-rq-20260124-153334-juju-7f2275-prod-proposed-migration-environment-15-99b6c0cd-ea07-42f0-bc01-13c2d64c544e from image adt/ubuntu-resolute-amd64-server-20260124.img (UUID 46811f22-3700-43f4-953b-ee3c613d71bb)... 77s autopkgtest [15:34:51]: testbed dpkg architecture: amd64 77s autopkgtest [15:34:51]: testbed apt version: 3.1.13 77s autopkgtest [15:34:51]: @@@@@@@@@@@@@@@@@@@@ test bed setup 78s autopkgtest [15:34:52]: testbed release detected to be: None 78s autopkgtest [15:34:52]: updating testbed package index (apt update) 78s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 78s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 78s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 78s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 78s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [24.3 kB] 78s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [91.5 kB] 79s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 79s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [991 kB] 79s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [113 kB] 79s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [96.0 kB] 79s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [3712 B] 79s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [12.3 kB] 79s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [3696 B] 79s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [336 B] 79s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [897 kB] 79s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [377 kB] 79s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [24.6 kB] 79s Get:18 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [12.4 kB] 79s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [4704 B] 79s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [824 B] 80s Fetched 2779 kB in 1s (3072 kB/s) 80s Reading package lists... 81s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 81s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 81s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 81s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 81s Reading package lists... 81s Reading package lists... 82s Building dependency tree... 82s Reading state information... 82s Calculating upgrade... 82s The following packages were automatically installed and are no longer required: 82s python3.13 python3.13-minimal 82s Use 'sudo apt autoremove' to remove them. 82s The following NEW packages will be installed: 82s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 82s The following packages will be upgraded: 82s libpython3-stdlib python3 python3-gdbm python3-minimal rust-coreutils 82s 5 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 82s Need to get 10.4 MB of archives. 82s After this operation, 25.2 MB of additional disk space will be used. 82s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-minimal amd64 3.14.2-1 [920 kB] 82s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14-minimal amd64 3.14.2-1 [2563 kB] 82s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-minimal amd64 3.14.2-1 [28.2 kB] 82s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3 amd64 3.14.2-1 [22.9 kB] 82s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-stdlib amd64 3.14.2-1 [2398 kB] 82s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14 amd64 3.14.2-1 [816 kB] 82s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 libpython3-stdlib amd64 3.14.2-1 [10.9 kB] 82s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 rust-coreutils amd64 0.5.0-0ubuntu1 [3628 kB] 82s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-gdbm amd64 3.14.2-1 [11.1 kB] 82s dpkg-preconfigure: unable to re-open stdin: No such file or directory 82s Fetched 10.4 MB in 0s (28.5 MB/s) 82s Selecting previously unselected package libpython3.14-minimal:amd64. 83s (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 ... 124177 files and directories currently installed.) 83s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_amd64.deb ... 83s Unpacking libpython3.14-minimal:amd64 (3.14.2-1) ... 83s Selecting previously unselected package python3.14-minimal. 83s Preparing to unpack .../python3.14-minimal_3.14.2-1_amd64.deb ... 83s Unpacking python3.14-minimal (3.14.2-1) ... 83s Setting up libpython3.14-minimal:amd64 (3.14.2-1) ... 83s Setting up python3.14-minimal (3.14.2-1) ... 83s (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 ... 124519 files and directories currently installed.) 83s Preparing to unpack .../python3-minimal_3.14.2-1_amd64.deb ... 83s Unpacking python3-minimal (3.14.2-1) over (3.13.9-3) ... 83s Setting up python3-minimal (3.14.2-1) ... 83s (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 ... 124519 files and directories currently installed.) 83s Preparing to unpack .../python3_3.14.2-1_amd64.deb ... 84s running python pre-rtupdate hooks for python3.14... 84s Unpacking python3 (3.14.2-1) over (3.13.9-3) ... 84s Selecting previously unselected package libpython3.14-stdlib:amd64. 84s Preparing to unpack .../libpython3.14-stdlib_3.14.2-1_amd64.deb ... 84s Unpacking libpython3.14-stdlib:amd64 (3.14.2-1) ... 84s Selecting previously unselected package python3.14. 84s Preparing to unpack .../python3.14_3.14.2-1_amd64.deb ... 84s Unpacking python3.14 (3.14.2-1) ... 84s Preparing to unpack .../libpython3-stdlib_3.14.2-1_amd64.deb ... 84s Unpacking libpython3-stdlib:amd64 (3.14.2-1) over (3.13.9-3) ... 84s Preparing to unpack .../rust-coreutils_0.5.0-0ubuntu1_amd64.deb ... 84s Unpacking rust-coreutils (0.5.0-0ubuntu1) over (0.2.2-0ubuntu2) ... 84s Setting up rust-coreutils (0.5.0-0ubuntu1) ... 84s (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 ... 124980 files and directories currently installed.) 84s Preparing to unpack .../python3-gdbm_3.14.2-1_amd64.deb ... 84s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-3) ... 84s Setting up libpython3.14-stdlib:amd64 (3.14.2-1) ... 84s Setting up python3.14 (3.14.2-1) ... 85s Setting up libpython3-stdlib:amd64 (3.14.2-1) ... 85s Setting up python3-gdbm (3.14.2-1) ... 85s Setting up python3 (3.14.2-1) ... 85s running python rtupdate hooks for python3.14... 85s running python post-rtupdate hooks for python3.14... 85s Processing triggers for man-db (2.13.1-1) ... 85s Processing triggers for systemd (259-1ubuntu2) ... 85s autopkgtest [15:34:59]: upgrading testbed (apt dist-upgrade and autopurge) 85s Reading package lists... 85s Building dependency tree... 85s Reading state information... 86s Calculating upgrade... 86s The following packages were automatically installed and are no longer required: 86s python3.13 python3.13-minimal 86s Use 'sudo apt autoremove' to remove them. 86s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 86s Reading package lists... 86s Building dependency tree... 86s Reading state information... 86s Solving dependencies... 86s The following packages will be REMOVED: 86s python3.13* python3.13-minimal* 86s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 86s After this operation, 7860 kB disk space will be freed. 86s (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 ... 124978 files and directories currently installed.) 86s Removing python3.13 (3.13.11-1) ... 86s Removing python3.13-minimal (3.13.11-1) ... 87s Processing triggers for man-db (2.13.1-1) ... 87s Processing triggers for systemd (259-1ubuntu2) ... 87s (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 ... 124951 files and directories currently installed.) 87s Purging configuration files for python3.13-minimal (3.13.11-1) ... 89s autopkgtest [15:35:03]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:49:02 UTC 2026 89s autopkgtest [15:35:03]: @@@@@@@@@@@@@@@@@@@@ apt-source python-rq 90s Get:1 http://ftpmaster.internal/ubuntu resolute/universe python-rq 2.6.1-1 (dsc) [2206 B] 90s Get:2 http://ftpmaster.internal/ubuntu resolute/universe python-rq 2.6.1-1 (tar) [680 kB] 90s Get:3 http://ftpmaster.internal/ubuntu resolute/universe python-rq 2.6.1-1 (diff) [7124 B] 90s gpgv: Signature made Sun Nov 30 07:46:24 2025 UTC 90s gpgv: using RSA key B70DFC6F134FECFC011E62AA83016014251D1DB0 90s gpgv: issuer "c.schoenert@t-online.de" 90s gpgv: Can't check signature: No public key 90s dpkg-source: warning: cannot verify inline signature for ./python-rq_2.6.1-1.dsc: no acceptable signature found 90s autopkgtest [15:35:04]: testing package python-rq version 2.6.1-1 91s autopkgtest [15:35:05]: build not needed 91s autopkgtest [15:35:05]: test smoke: preparing testbed 91s Reading package lists... 91s Building dependency tree... 91s Reading state information... 91s Solving dependencies... 91s The following NEW packages will be installed: 91s libjs-jquery libjs-jquery-hotkeys libjs-jquery-isonscreen 91s libjs-jquery-metadata libjs-jquery-tablesorter 91s libjs-jquery-throttle-debounce liblzf1 python3-all python3-click 91s python3-coverage python3-croniter python3-iniconfig python3-pluggy 91s python3-psutil python3-pytest python3-pytest-cov python3-redis python3-rq 91s python3.13 python3.13-minimal redis-server redis-tools 91s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 91s Need to get 6234 kB of archives. 91s After this operation, 23.5 MB of additional disk space will be used. 91s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.13-minimal amd64 3.13.11-1 [2408 kB] 91s Get:2 http://ftpmaster.internal/ubuntu resolute/universe amd64 liblzf1 amd64 3.6-4build1 [7490 B] 91s Get:3 http://ftpmaster.internal/ubuntu resolute/universe amd64 redis-tools amd64 5:8.0.5-1 [1363 kB] 91s Get:4 http://ftpmaster.internal/ubuntu resolute/universe amd64 redis-server amd64 5:8.0.5-1 [53.2 kB] 92s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.13 amd64 3.13.11-1 [758 kB] 92s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 libjs-jquery all 3.7.1+dfsg+~3.5.33-1build1 [321 kB] 92s Get:7 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-metadata all 12-4build1 [6692 B] 92s Get:8 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-tablesorter all 1:2.31.3+dfsg1-5 [192 kB] 92s Get:9 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-throttle-debounce all 1.1+dfsg.1-2build1 [12.6 kB] 92s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-all amd64 3.14.2-1 [890 B] 92s Get:11 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-click all 8.2.0+0.really.8.1.8-1build1 [80.2 kB] 92s Get:12 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-coverage amd64 7.8.2+dfsg1-1ubuntu1 [159 kB] 92s Get:13 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-croniter all 6.1~rc1-0ubuntu1 [21.7 kB] 92s Get:14 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-iniconfig all 2.1.0-2 [6962 B] 92s Get:15 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pluggy all 1.6.0-2 [21.1 kB] 92s Get:16 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-psutil amd64 7.1.0-1ubuntu1 [191 kB] 92s Get:17 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest all 9.0.2-2 [277 kB] 92s Get:18 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-hotkeys all 0.2.0-1 [13.3 kB] 92s Get:19 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-isonscreen all 1.2.0-2 [3814 B] 92s Get:20 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest-cov all 5.0.0-1 [21.3 kB] 92s Get:21 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-redis all 6.4.0-1 [228 kB] 92s Get:22 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-rq all 2.6.1-1 [86.3 kB] 92s Fetched 6234 kB in 0s (23.1 MB/s) 92s Selecting previously unselected package python3.13-minimal. 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 ... 124953 files and directories currently installed.) 92s Preparing to unpack .../00-python3.13-minimal_3.13.11-1_amd64.deb ... 92s Unpacking python3.13-minimal (3.13.11-1) ... 92s Selecting previously unselected package liblzf1:amd64. 92s Preparing to unpack .../01-liblzf1_3.6-4build1_amd64.deb ... 92s Unpacking liblzf1:amd64 (3.6-4build1) ... 92s Selecting previously unselected package redis-tools. 92s Preparing to unpack .../02-redis-tools_5%3a8.0.5-1_amd64.deb ... 92s Unpacking redis-tools (5:8.0.5-1) ... 92s Selecting previously unselected package redis-server. 92s Preparing to unpack .../03-redis-server_5%3a8.0.5-1_amd64.deb ... 92s Unpacking redis-server (5:8.0.5-1) ... 92s Selecting previously unselected package python3.13. 92s Preparing to unpack .../04-python3.13_3.13.11-1_amd64.deb ... 92s Unpacking python3.13 (3.13.11-1) ... 92s Selecting previously unselected package libjs-jquery. 92s Preparing to unpack .../05-libjs-jquery_3.7.1+dfsg+~3.5.33-1build1_all.deb ... 92s Unpacking libjs-jquery (3.7.1+dfsg+~3.5.33-1build1) ... 92s Selecting previously unselected package libjs-jquery-metadata. 92s Preparing to unpack .../06-libjs-jquery-metadata_12-4build1_all.deb ... 92s Unpacking libjs-jquery-metadata (12-4build1) ... 92s Selecting previously unselected package libjs-jquery-tablesorter. 92s Preparing to unpack .../07-libjs-jquery-tablesorter_1%3a2.31.3+dfsg1-5_all.deb ... 92s Unpacking libjs-jquery-tablesorter (1:2.31.3+dfsg1-5) ... 92s Selecting previously unselected package libjs-jquery-throttle-debounce. 92s Preparing to unpack .../08-libjs-jquery-throttle-debounce_1.1+dfsg.1-2build1_all.deb ... 92s Unpacking libjs-jquery-throttle-debounce (1.1+dfsg.1-2build1) ... 92s Selecting previously unselected package python3-all. 92s Preparing to unpack .../09-python3-all_3.14.2-1_amd64.deb ... 92s Unpacking python3-all (3.14.2-1) ... 92s Selecting previously unselected package python3-click. 92s Preparing to unpack .../10-python3-click_8.2.0+0.really.8.1.8-1build1_all.deb ... 92s Unpacking python3-click (8.2.0+0.really.8.1.8-1build1) ... 92s Selecting previously unselected package python3-coverage. 92s Preparing to unpack .../11-python3-coverage_7.8.2+dfsg1-1ubuntu1_amd64.deb ... 92s Unpacking python3-coverage (7.8.2+dfsg1-1ubuntu1) ... 92s Selecting previously unselected package python3-croniter. 92s Preparing to unpack .../12-python3-croniter_6.1~rc1-0ubuntu1_all.deb ... 92s Unpacking python3-croniter (6.1~rc1-0ubuntu1) ... 92s Selecting previously unselected package python3-iniconfig. 92s Preparing to unpack .../13-python3-iniconfig_2.1.0-2_all.deb ... 92s Unpacking python3-iniconfig (2.1.0-2) ... 92s Selecting previously unselected package python3-pluggy. 92s Preparing to unpack .../14-python3-pluggy_1.6.0-2_all.deb ... 92s Unpacking python3-pluggy (1.6.0-2) ... 92s Selecting previously unselected package python3-psutil. 92s Preparing to unpack .../15-python3-psutil_7.1.0-1ubuntu1_amd64.deb ... 92s Unpacking python3-psutil (7.1.0-1ubuntu1) ... 92s Selecting previously unselected package python3-pytest. 92s Preparing to unpack .../16-python3-pytest_9.0.2-2_all.deb ... 92s Unpacking python3-pytest (9.0.2-2) ... 92s Selecting previously unselected package libjs-jquery-hotkeys. 92s Preparing to unpack .../17-libjs-jquery-hotkeys_0.2.0-1_all.deb ... 92s Unpacking libjs-jquery-hotkeys (0.2.0-1) ... 92s Selecting previously unselected package libjs-jquery-isonscreen. 92s Preparing to unpack .../18-libjs-jquery-isonscreen_1.2.0-2_all.deb ... 92s Unpacking libjs-jquery-isonscreen (1.2.0-2) ... 92s Selecting previously unselected package python3-pytest-cov. 92s Preparing to unpack .../19-python3-pytest-cov_5.0.0-1_all.deb ... 92s Unpacking python3-pytest-cov (5.0.0-1) ... 92s Selecting previously unselected package python3-redis. 92s Preparing to unpack .../20-python3-redis_6.4.0-1_all.deb ... 92s Unpacking python3-redis (6.4.0-1) ... 92s Selecting previously unselected package python3-rq. 92s Preparing to unpack .../21-python3-rq_2.6.1-1_all.deb ... 92s Unpacking python3-rq (2.6.1-1) ... 92s Setting up python3-iniconfig (2.1.0-2) ... 92s Setting up python3-croniter (6.1~rc1-0ubuntu1) ... 92s Setting up libjs-jquery-throttle-debounce (1.1+dfsg.1-2build1) ... 92s Setting up python3-click (8.2.0+0.really.8.1.8-1build1) ... 93s Setting up python3-psutil (7.1.0-1ubuntu1) ... 93s Setting up liblzf1:amd64 (3.6-4build1) ... 93s Setting up python3-redis (6.4.0-1) ... 93s Setting up python3-pluggy (1.6.0-2) ... 93s Setting up python3.13-minimal (3.13.11-1) ... 94s Setting up libjs-jquery (3.7.1+dfsg+~3.5.33-1build1) ... 94s Setting up libjs-jquery-hotkeys (0.2.0-1) ... 94s Setting up python3.13 (3.13.11-1) ... 94s Setting up python3-pytest (9.0.2-2) ... 95s Setting up python3-all (3.14.2-1) ... 95s Setting up python3-coverage (7.8.2+dfsg1-1ubuntu1) ... 95s Setting up python3-rq (2.6.1-1) ... 95s Setting up redis-tools (5:8.0.5-1) ... 95s Setting up libjs-jquery-metadata (12-4build1) ... 95s Setting up libjs-jquery-isonscreen (1.2.0-2) ... 95s Setting up libjs-jquery-tablesorter (1:2.31.3+dfsg1-5) ... 95s Setting up python3-pytest-cov (5.0.0-1) ... 96s Setting up redis-server (5:8.0.5-1) ... 96s Created symlink '/etc/systemd/system/redis.service' → '/usr/lib/systemd/system/redis-server.service'. 96s Created symlink '/etc/systemd/system/multi-user.target.wants/redis-server.service' → '/usr/lib/systemd/system/redis-server.service'. 96s Processing triggers for systemd (259-1ubuntu2) ... 96s Processing triggers for man-db (2.13.1-1) ... 96s Processing triggers for libc-bin (2.42-2ubuntu4) ... 97s autopkgtest [15:35:11]: test smoke: [----------------------- 97s 97s *************************** 97s *** Testing with python3.13 97s *************************** 97s 97s Content of current working folder: 97s 97s total 12 97s drwxr-xr-x 3 ubuntu ubuntu 4096 Jan 24 15:35 . 97s drwxrwxrwx 5 root root 4096 Jan 24 15:35 .. 97s drwxrwxr-x 4 ubuntu ubuntu 4096 Jan 24 15:35 tests 97s Running tests... 97s 98s ============================= test session starts ============================== 98s platform linux -- Python 3.13.11, pytest-9.0.2, pluggy-1.6.0 98s rootdir: /tmp/autopkgtest.onwLoM/autopkgtest_tmp 98s plugins: typeguard-4.4.4, cov-5.0.0 98s collected 536 items / 3 deselected / 533 selected 98s 98s tests/test_callbacks.py ........Traceback (most recent call last): 98s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 98s return_value = job.perform() 98s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 98s self._result = self._execute() 98s ~~~~~~~~~~~~~^^ 98s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 98s result = self.func(*self.args, **self.kwargs) 98s TypeError: div_by_zero() missing 1 required positional argument: 'x' 98s 98s Traceback (most recent call last): 98s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 98s return_value = job.perform() 98s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 98s self._result = self._execute() 98s ~~~~~~~~~~~~~^^ 98s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 98s result = self.func(*self.args, **self.kwargs) 98s TypeError: div_by_zero() missing 1 required positional argument: 'x' 98s 98s ..... 111s tests/test_cli.py ......................................... 115s tests/test_commands.py ..... 115s tests/test_connection.py . 116s tests/test_cron.py ............................ 116s tests/test_cron_job.py ........... 116s tests/test_cron_scheduler_registry.py .... 116s tests/test_decorator.py .............. 117s tests/test_dependencies.py ............. 120s tests/test_executions.py ............ 120s tests/test_fixtures.py .. 126s tests/test_group.py ................ 126s tests/test_helpers.py . 126s tests/test_intermediate_queue.py ........ 131s tests/test_job.py .......................................................... 131s tests/test_job_dependency.py ...# Post enqueue {b'46b44357-86b3-48d6-b230-61d161c5c2c8'} 131s # Post work {b'46b44357-86b3-48d6-b230-61d161c5c2c8'} 131s # Pre cancel {b'46b44357-86b3-48d6-b230-61d161c5c2c8'} 131s #Post cancel set() 138s ....................... 138s tests/test_queue.py ....................................................... 138s tests/test_registry.py ....................................... 138s tests/test_repeat.py ......... 139s tests/test_results.py ............. 139s tests/test_retry.py ........... 149s tests/test_scheduler.py ..................s...... 149s tests/test_serializers.py . 149s tests/test_spawn_worker.py Worker e295846691474e9db35a97138bf67d0d: job 76bb97a3-f468-444a-88a5-ea532be94cc6: exception raised while executing (tests.fixtures.div_by_zero) 149s Traceback (most recent call last): 149s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 149s return_value = job.perform() 149s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 149s self._result = self._execute() 149s ~~~~~~~~~~~~~^^ 149s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 149s result = self.func(*self.args, **self.kwargs) 149s TypeError: div_by_zero() missing 1 required positional argument: 'x' 149s 149s .ss 154s tests/test_timeouts.py .. 154s tests/test_utils.py ......................... 173s tests/test_worker.py ......................ss...........s............s.s...................sssss15:36:26 Worker 92b9359843a6439c9cba662386d22114: started with PID 2949, version 2.6.1 173s 15:36:26 Worker 92b9359843a6439c9cba662386d22114: subscribing to channel rq:pubsub:92b9359843a6439c9cba662386d22114 173s 15:36:26 *** Listening on default... 173s 15:36:26 Worker 92b9359843a6439c9cba662386d22114: cleaning registries for queue: default 173s 15:36:26 default: tests.fixtures.access_self() (0158dc53-7be3-4626-832a-75a56deeb271) 173s 15:36:26 Successfully completed tests.fixtures.access_self() job in 0:00:00.080632s on worker 92b9359843a6439c9cba662386d22114 173s 15:36:26 default: Job OK (0158dc53-7be3-4626-832a-75a56deeb271) 173s 15:36:26 Result is kept for 500 seconds 173s 15:36:26 Worker 92b9359843a6439c9cba662386d22114: done, quitting 173s 15:36:26 Worker 92b9359843a6439c9cba662386d22114: unsubscribing from channel rq:pubsub:92b9359843a6439c9cba662386d22114 173s .15:36:26 Worker 3255912b21a24dc192ef573edad16f95: started with PID 2953, version 2.6.1 173s 15:36:26 Worker 3255912b21a24dc192ef573edad16f95: subscribing to channel rq:pubsub:3255912b21a24dc192ef573edad16f95 173s 15:36:26 *** Listening on default... 173s 15:36:26 Worker 3255912b21a24dc192ef573edad16f95: cleaning registries for queue: default 173s 15:36:26 Worker 3255912b21a24dc192ef573edad16f95: done, quitting 173s 15:36:26 Worker 3255912b21a24dc192ef573edad16f95: unsubscribing from channel rq:pubsub:3255912b21a24dc192ef573edad16f95 173s .15:36:26 Worker 79c9af8b17b242f9835cda77bd7fb756: started with PID 2956, version 2.6.1 173s 15:36:26 Worker 79c9af8b17b242f9835cda77bd7fb756: subscribing to channel rq:pubsub:79c9af8b17b242f9835cda77bd7fb756 173s 15:36:26 *** Listening on default... 173s 15:36:26 Worker 79c9af8b17b242f9835cda77bd7fb756: cleaning registries for queue: default 173s 15:36:26 default: tests.test_worker.schedule_access_self() (c7d9bfb4-6ea7-42ba-81ab-b64c7803cfa4) 173s 15:36:26 Successfully completed tests.test_worker.schedule_access_self() job in 0:00:00.095612s on worker 79c9af8b17b242f9835cda77bd7fb756 173s 15:36:26 default: Job OK (c7d9bfb4-6ea7-42ba-81ab-b64c7803cfa4) 173s 15:36:26 Result is kept for 500 seconds 173s 15:36:26 Worker 79c9af8b17b242f9835cda77bd7fb756: done, quitting 173s 15:36:26 Worker 79c9af8b17b242f9835cda77bd7fb756: unsubscribing from channel rq:pubsub:79c9af8b17b242f9835cda77bd7fb756 174s .s..ss... 176s tests/test_worker_pool.py .Process Worker 605cc34cc968464181fc5fcacd724b43 (WorkerPool 103b4304087840d0a457dd70ce766a77): 176s Traceback (most recent call last): 176s File "/usr/lib/python3.13/multiprocessing/process.py", line 313, in _bootstrap 176s self.run() 176s ~~~~~~~~^^ 176s File "/usr/lib/python3.13/multiprocessing/process.py", line 108, in run 176s self._target(*self._args, **self._kwargs) 176s ~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 176s File "/usr/lib/python3/dist-packages/rq/worker_pool.py", line 276, in run_worker 176s worker.work(burst=burst, with_scheduler=True, logging_level=logging_level) 176s ~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 176s File "/usr/lib/python3/dist-packages/rq/worker.py", line 631, in work 176s self.teardown() 176s ~~~~~~~~~~~~~^^ 176s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1151, in teardown 176s self.unsubscribe() 176s ~~~~~~~~~~~~~~~~^^ 176s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1049, in unsubscribe 176s self.pubsub_thread.join(timeout=1) 176s ~~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^ 176s File "/usr/lib/python3.13/threading.py", line 1095, in join 176s self._handle.join(timeout) 176s ~~~~~~~~~~~~~~~~~^^^^^^^^^ 176s File "/usr/lib/python3/dist-packages/rq/worker.py", line 510, in request_stop 176s self._shutdown() 176s ~~~~~~~~~~~~~~^^ 176s File "/usr/lib/python3/dist-packages/rq/worker.py", line 529, in _shutdown 176s raise StopRequested() 176s rq.exceptions.StopRequested 183s ...... 183s tests/test_worker_registration.py .... 183s 183s =============================== warnings summary =============================== 183s tests/test_group.py:99 183s /tmp/autopkgtest.onwLoM/autopkgtest_tmp/tests/test_group.py:99: PytestUnknownMarkWarning: Unknown pytest.mark.slow - is this a typo? You can register custom marks to avoid this warning - for details, see https://docs.pytest.org/en/stable/how-to/mark.html 183s @pytest.mark.slow 183s 183s tests/test_group.py:114 183s /tmp/autopkgtest.onwLoM/autopkgtest_tmp/tests/test_group.py:114: PytestUnknownMarkWarning: Unknown pytest.mark.slow - is this a typo? You can register custom marks to avoid this warning - for details, see https://docs.pytest.org/en/stable/how-to/mark.html 183s @pytest.mark.slow 183s 183s tests/test_group.py:127 183s /tmp/autopkgtest.onwLoM/autopkgtest_tmp/tests/test_group.py:127: PytestUnknownMarkWarning: Unknown pytest.mark.slow - is this a typo? You can register custom marks to avoid this warning - for details, see https://docs.pytest.org/en/stable/how-to/mark.html 183s @pytest.mark.slow 183s 183s tests/__init__.py:44 183s /tmp/autopkgtest.onwLoM/autopkgtest_tmp/tests/__init__.py:44: PytestUnknownMarkWarning: Unknown pytest.mark.ssl_test - is this a typo? You can register custom marks to avoid this warning - for details, see https://docs.pytest.org/en/stable/how-to/mark.html 183s f = pytest.mark.ssl_test(f) 183s 183s tests/__init__.py:39 183s /tmp/autopkgtest.onwLoM/autopkgtest_tmp/tests/__init__.py:39: PytestUnknownMarkWarning: Unknown pytest.mark.slow - is this a typo? You can register custom marks to avoid this warning - for details, see https://docs.pytest.org/en/stable/how-to/mark.html 183s f = pytest.mark.slow(f) 183s 183s tests/test_callbacks.py: 12 warnings 183s tests/test_decorator.py: 1 warning 183s /usr/lib/python3/dist-packages/rq/job.py:313: DeprecationWarning: Passing a string or function for `on_success` is deprecated, pass `Callback` instead 183s warnings.warn( 183s 183s tests/test_callbacks.py: 7 warnings 183s tests/test_decorator.py: 3 warnings 183s tests/test_job.py: 1 warning 183s /usr/lib/python3/dist-packages/rq/job.py:323: DeprecationWarning: Passing a string or function for `on_failure` is deprecated, pass `Callback` instead 183s warnings.warn( 183s 183s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_many_callback 183s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_with_stopped_callback 183s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_with_stopped_callback 183s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_with_stopped_callback 183s tests/test_callbacks.py::SyncJobCallback::test_stopped_callback 183s tests/test_callbacks.py::JobCallbackTestCase::test_job_creation_with_stopped_callback 183s tests/test_job.py::TestJob::test_persistence_of_callbacks 183s /usr/lib/python3/dist-packages/rq/job.py:333: DeprecationWarning: Passing a string or function for `on_stopped` is deprecated, pass `Callback` instead 183s warnings.warn( 183s 183s tests/test_callbacks.py: 2 warnings 183s tests/test_cli.py: 3 warnings 183s tests/test_dependencies.py: 1 warning 183s tests/test_job.py: 6 warnings 183s tests/test_results.py: 1 warning 183s tests/test_worker.py: 13 warnings 183s /usr/lib/python3/dist-packages/rq/job.py:868: DeprecationWarning: job.result is deprecated, use job.return_value instead. 183s warnings.warn('job.result is deprecated, use job.return_value instead.', DeprecationWarning) 183s 183s tests/test_callbacks.py: 2 warnings 183s tests/test_cli.py: 16 warnings 183s tests/test_commands.py: 1 warning 183s tests/test_dependencies.py: 7 warnings 183s tests/test_fixtures.py: 1 warning 183s tests/test_intermediate_queue.py: 1 warning 183s tests/test_job.py: 1 warning 183s tests/test_job_dependency.py: 7 warnings 183s tests/test_queue.py: 2 warnings 183s tests/test_registry.py: 9 warnings 183s tests/test_repeat.py: 3 warnings 183s tests/test_retry.py: 6 warnings 183s tests/test_scheduler.py: 2 warnings 183s tests/test_worker.py: 94 warnings 183s tests/test_worker_pool.py: 1 warning 183s /usr/lib/python3/dist-packages/rq/worker.py:1602: DeprecationWarning: This process (pid=2611) is multi-threaded, use of fork() may lead to deadlocks in the child. 183s child_pid = os.fork() 183s 183s tests/test_callbacks.py::WorkerCallbackTestCase::test_failure_callback 183s tests/test_callbacks.py::WorkerCallbackTestCase::test_failure_callback 183s tests/test_job.py::TestJob::test_compressed_exc_info_handling 183s tests/test_job.py::TestJob::test_compressed_exc_info_handling 183s tests/test_registry.py::TestDeferredRegistry::test_cleanup_moves_jobs_to_failed_job_registry 183s tests/test_results.py::TestResult::test_job_failed_result 183s tests/test_timeouts.py::TestTimeouts::test_timer_death_penalty 183s tests/test_worker.py::TestWorker::test_horse_fails 183s tests/test_worker.py::TestWorker::test_job_timeout_moved_to_failed_job_registry 183s /usr/lib/python3/dist-packages/rq/job.py:816: DeprecationWarning: job.exc_info is deprecated, use job.latest_result() instead. 183s warnings.warn('job.exc_info is deprecated, use job.latest_result() instead.', DeprecationWarning) 183s 183s tests/test_commands.py::TestCommands::test_pubsub_thread_exits_other_error 183s /usr/lib/python3/dist-packages/_pytest/threadexception.py:58: PytestUnhandledThreadExceptionWarning: Exception in thread Thread-34 183s 183s Traceback (most recent call last): 183s File "/usr/lib/python3.13/threading.py", line 1044, in _bootstrap_inner 183s self.run() 183s ~~~~~~~~^^ 183s File "/usr/lib/python3/dist-packages/redis/client.py", line 1269, in run 183s self.exception_handler(e, pubsub, self) 183s ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^ 183s File "/usr/lib/python3/dist-packages/redis/client.py", line 1265, in run 183s pubsub.get_message(ignore_subscribe_messages=True, timeout=sleep_time) 183s ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 183s File "/tmp/autopkgtest.onwLoM/autopkgtest_tmp/tests/fixtures.py", line 51, in raise_exc 183s raise Exception('raise_exc error') 183s Exception: raise_exc error 183s 183s Enable tracemalloc to get traceback where the object was allocated. 183s See https://docs.pytest.org/en/stable/how-to/capture-warnings.html#resource-warnings for more info. 183s warnings.warn(pytest.PytestUnhandledThreadExceptionWarning(msg)) 183s 183s tests/test_commands.py::TestCommands::test_shutdown_command 183s tests/test_commands.py::TestCommands::test_stop_job_command 183s tests/test_scheduler.py::TestWorker::test_work 183s tests/test_scheduler.py::TestWorker::test_work_with_serializer 183s /usr/lib/python3.13/multiprocessing/popen_fork.py:67: DeprecationWarning: This process (pid=2611) is multi-threaded, use of fork() may lead to deadlocks in the child. 183s self.pid = os.fork() 183s 183s tests/test_connection.py::TestConnectionInheritance::test_parse_connection 183s /usr/lib/python3/dist-packages/_pytest/threadexception.py:58: PytestUnhandledThreadExceptionWarning: Exception in thread Thread-35 183s 183s Traceback (most recent call last): 183s File "/usr/lib/python3/dist-packages/redis/_parsers/socket.py", line 65, in _read_from_socket 183s data = self._sock.recv(socket_read_size) 183s OSError: [Errno 9] Bad file descriptor 183s 183s During handling of the above exception, another exception occurred: 183s 183s Traceback (most recent call last): 183s File "/usr/lib/python3.13/threading.py", line 1044, in _bootstrap_inner 183s self.run() 183s ~~~~~~~~^^ 183s File "/usr/lib/python3/dist-packages/redis/client.py", line 1269, in run 183s self.exception_handler(e, pubsub, self) 183s ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^ 183s File "/usr/lib/python3/dist-packages/redis/client.py", line 1265, in run 183s pubsub.get_message(ignore_subscribe_messages=True, timeout=sleep_time) 183s ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 183s File "/usr/lib/python3/dist-packages/redis/client.py", line 1127, in get_message 183s response = self.parse_response(block=(timeout is None), timeout=timeout) 183s File "/usr/lib/python3/dist-packages/redis/client.py", line 938, in parse_response 183s response = self._execute(conn, try_read) 183s File "/usr/lib/python3/dist-packages/redis/client.py", line 914, in _execute 183s return conn.retry.call_with_retry( 183s ~~~~~~~~~~~~~~~~~~~~~~~~~~^ 183s lambda: command(*args, **kwargs), 183s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 183s lambda _: self._reconnect(conn), 183s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 183s ) 183s ^ 183s File "/usr/lib/python3/dist-packages/redis/retry.py", line 105, in call_with_retry 183s return do() 183s File "/usr/lib/python3/dist-packages/redis/client.py", line 915, in 183s lambda: command(*args, **kwargs), 183s ~~~~~~~^^^^^^^^^^^^^^^^^ 183s File "/usr/lib/python3/dist-packages/redis/client.py", line 932, in try_read 183s if not conn.can_read(timeout=timeout): 183s ~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^ 183s File "/usr/lib/python3/dist-packages/redis/connection.py", line 628, in can_read 183s return self._parser.can_read(timeout) 183s ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 183s File "/usr/lib/python3/dist-packages/redis/_parsers/base.py", line 140, in can_read 183s return self._buffer and self._buffer.can_read(timeout) 183s ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 183s File "/usr/lib/python3/dist-packages/redis/_parsers/socket.py", line 95, in can_read 183s return bool(self.unread_bytes()) or self._read_from_socket( 183s ~~~~~~~~~~~~~~~~~~~~~~^ 183s timeout=timeout, raise_on_timeout=False 183s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 183s ) 183s ^ 183s File "/usr/lib/python3/dist-packages/redis/_parsers/socket.py", line 90, in _read_from_socket 183s buf.seek(current_pos) 183s ~~~~~~~~^^^^^^^^^^^^^ 183s ValueError: I/O operation on closed file. 183s 183s Enable tracemalloc to get traceback where the object was allocated. 183s See https://docs.pytest.org/en/stable/how-to/capture-warnings.html#resource-warnings for more info. 183s warnings.warn(pytest.PytestUnhandledThreadExceptionWarning(msg)) 183s 183s tests/test_registry.py::TestStartedJobRegistry::test_count 183s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got will-be-cleaned-up 183s warnings.warn( 183s 183s tests/test_retry.py::TestRetry::test_cleanup_handles_retries 183s tests/test_retry.py::TestRetry::test_cleanup_handles_retries 183s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got 20f6275f-bf5c-47e9-ba98-5ffe24ba6cc5 183s warnings.warn( 183s 183s tests/test_scheduler.py::TestQueue::test_custom_connection_pool 183s /tmp/autopkgtest.onwLoM/autopkgtest_tmp/tests/test_scheduler.py:513: DeprecationWarning: Call to 'get_connection' function with deprecated usage of input argument/s '['command_name']'. (Use get_connection() without args instead) -- Deprecated since version 5.3.0. 183s scheduler_connection = scheduler.connection.connection_pool.get_connection('info') 183s 183s tests/test_scheduler.py::TestQueue::test_no_custom_connection_pool 183s /tmp/autopkgtest.onwLoM/autopkgtest_tmp/tests/test_scheduler.py:526: DeprecationWarning: Call to 'get_connection' function with deprecated usage of input argument/s '['command_name']'. (Use get_connection() without args instead) -- Deprecated since version 5.3.0. 183s scheduler_connection = scheduler.connection.connection_pool.get_connection('info') 183s 183s tests/test_spawn_worker.py::TestWorker::test_work_fails 183s :891: DeprecationWarning: This process (pid=2611) is multi-threaded, use of fork() may lead to deadlocks in the child. 183s 183s tests/test_worker.py::TestWorker::test_clean_queue_registries 183s tests/test_worker.py::TestWorker::test_worker_calls_clean_registries 183s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got foo 183s warnings.warn( 183s 183s tests/test_worker.py::TestWorker::test_clean_queue_registries 183s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got bar 183s warnings.warn( 183s 183s tests/test_worker.py::TestWorker::test_worker_ttl 183s /tmp/autopkgtest.onwLoM/autopkgtest_tmp/tests/test_worker.py:164: DeprecationWarning: default_worker_ttl is deprecated, use worker_ttl. 183s w = Worker([], connection=self.connection, default_worker_ttl=10) 183s 183s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 183s ==== 517 passed, 16 skipped, 3 deselected, 240 warnings in 85.44s (0:01:25) ==== 183s 183s *************************** 183s *** Testing with python3.14 183s *************************** 183s 183s Content of current working folder: 183s 183s total 12 183s drwxr-xr-x 3 ubuntu ubuntu 4096 Jan 24 15:36 . 183s drwxrwxrwx 5 root root 4096 Jan 24 15:35 .. 183s drwxrwxr-x 5 ubuntu ubuntu 4096 Jan 24 15:35 tests 183s Running tests... 183s 183s ============================= test session starts ============================== 183s platform linux -- Python 3.14.2, pytest-9.0.2, pluggy-1.6.0 183s rootdir: /tmp/autopkgtest.onwLoM/autopkgtest_tmp 183s plugins: typeguard-4.4.4, cov-5.0.0 183s collected 536 items / 3 deselected / 533 selected 183s 183s tests/test_callbacks.py ........Traceback (most recent call last): 183s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 183s return_value = job.perform() 183s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 183s self._result = self._execute() 183s ~~~~~~~~~~~~~^^ 183s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 183s result = self.func(*self.args, **self.kwargs) 183s TypeError: div_by_zero() missing 1 required positional argument: 'x' 183s 183s Traceback (most recent call last): 183s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 183s return_value = job.perform() 183s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 183s self._result = self._execute() 183s ~~~~~~~~~~~~~^^ 183s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 183s result = self.func(*self.args, **self.kwargs) 183s TypeError: div_by_zero() missing 1 required positional argument: 'x' 183s 184s ..... 195s tests/test_cli.py .................................15:36:48 Worker 6dc5f6514ab84e7593cc9ef289784146: started with PID 3087, version 2.6.1 195s 15:36:48 Worker 6dc5f6514ab84e7593cc9ef289784146: subscribing to channel rq:pubsub:6dc5f6514ab84e7593cc9ef289784146 195s 15:36:48 *** Listening on default... 195s 15:36:48 Acquired scheduler lock for default 195s 15:36:48 Worker 6dc5f6514ab84e7593cc9ef289784146: cleaning registries for queue: default 195s 15:36:48 Worker 6dc5f6514ab84e7593cc9ef289784146: done, quitting 195s 15:36:48 Worker 6dc5f6514ab84e7593cc9ef289784146: unsubscribing from channel rq:pubsub:6dc5f6514ab84e7593cc9ef289784146 196s .15:36:49 Worker 368b7d9341c842cda4cd16d093f421e0: started with PID 3089, version 2.6.1 196s 15:36:49 Worker 368b7d9341c842cda4cd16d093f421e0: subscribing to channel rq:pubsub:368b7d9341c842cda4cd16d093f421e0 196s 15:36:49 *** Listening on foo, bar... 196s 15:36:49 Acquired scheduler lock for bar 196s 15:36:49 Acquired scheduler lock for foo 196s 15:36:49 Worker 368b7d9341c842cda4cd16d093f421e0: cleaning registries for queue: foo 196s 15:36:49 Worker 368b7d9341c842cda4cd16d093f421e0: cleaning registries for queue: bar 196s 15:36:49 foo: tests.fixtures.say_hello('Hello') (7a2ea1e0-b69a-49d7-b79c-716eb2f3c1f3) 196s 15:36:49 Successfully completed tests.fixtures.say_hello('Hello') job in 0:00:00.072611s on worker 368b7d9341c842cda4cd16d093f421e0 196s 15:36:49 foo: Job OK (7a2ea1e0-b69a-49d7-b79c-716eb2f3c1f3) 196s 15:36:49 Result is kept for 500 seconds 196s 15:36:49 bar: tests.fixtures.say_hello('Hello') (bfe24e9e-d958-44e5-8967-4ba008be1ae4) 196s 15:36:49 Successfully completed tests.fixtures.say_hello('Hello') job in 0:00:00.073280s on worker 368b7d9341c842cda4cd16d093f421e0 196s 15:36:49 bar: Job OK (bfe24e9e-d958-44e5-8967-4ba008be1ae4) 196s 15:36:49 Result is kept for 500 seconds 196s 15:36:49 Worker 368b7d9341c842cda4cd16d093f421e0: done, quitting 196s 15:36:49 Worker 368b7d9341c842cda4cd16d093f421e0: unsubscribing from channel rq:pubsub:368b7d9341c842cda4cd16d093f421e0 197s .15:36:50 Worker 5cebdf18bdaf481895708415387d0c0a: started with PID 3093, version 2.6.1 197s 15:36:50 Worker 5cebdf18bdaf481895708415387d0c0a: subscribing to channel rq:pubsub:5cebdf18bdaf481895708415387d0c0a 197s 15:36:50 *** Listening on default... 197s 15:36:50 Acquired scheduler lock for default 197s 15:36:50 Worker 5cebdf18bdaf481895708415387d0c0a: cleaning registries for queue: default 197s 15:36:50 Worker 5cebdf18bdaf481895708415387d0c0a: done, quitting 197s 15:36:50 Worker 5cebdf18bdaf481895708415387d0c0a: unsubscribing from channel rq:pubsub:5cebdf18bdaf481895708415387d0c0a 197s 15:36:50 Worker 3756555e0b7341799069e380310d487f: started with PID 3095, version 2.6.1 197s 15:36:50 Worker 3756555e0b7341799069e380310d487f: subscribing to channel rq:pubsub:3756555e0b7341799069e380310d487f 197s 15:36:50 *** Listening on default... 197s 15:36:50 Acquired scheduler lock for default 197s 15:36:50 Worker 3756555e0b7341799069e380310d487f: cleaning registries for queue: default 197s 15:36:50 Worker 3756555e0b7341799069e380310d487f: done, quitting 197s 15:36:50 Worker 3756555e0b7341799069e380310d487f: unsubscribing from channel rq:pubsub:3756555e0b7341799069e380310d487f 197s .15:36:50 Worker c84c9ac5131e465b8162c29d2de20b48: started with PID 3097, version 2.6.1 197s 15:36:50 Worker c84c9ac5131e465b8162c29d2de20b48: subscribing to channel rq:pubsub:c84c9ac5131e465b8162c29d2de20b48 197s 15:36:50 *** Listening on default... 197s 15:36:50 Acquired scheduler lock for default 197s 15:36:50 Worker c84c9ac5131e465b8162c29d2de20b48: cleaning registries for queue: default 197s 15:36:50 Worker c84c9ac5131e465b8162c29d2de20b48: done, quitting 197s 15:36:50 Worker c84c9ac5131e465b8162c29d2de20b48: unsubscribing from channel rq:pubsub:c84c9ac5131e465b8162c29d2de20b48 197s 15:36:50 Worker 07f65d24210e4393b74e165670298f12: started with PID 3099, version 2.6.1 197s 15:36:50 Worker 04873879645f4f918bc85d9e99e1bae0: started with PID 3098, version 2.6.1 197s 15:36:50 Worker 04873879645f4f918bc85d9e99e1bae0: subscribing to channel rq:pubsub:04873879645f4f918bc85d9e99e1bae0 197s 15:36:50 Worker 07f65d24210e4393b74e165670298f12: subscribing to channel rq:pubsub:07f65d24210e4393b74e165670298f12 197s 15:36:50 *** Listening on default... 197s 15:36:50 *** Listening on default... 197s 15:36:50 Acquired scheduler lock for default 197s 15:36:50 Worker 04873879645f4f918bc85d9e99e1bae0: cleaning registries for queue: default 197s 15:36:50 Worker 07f65d24210e4393b74e165670298f12: done, quitting 197s 15:36:50 Worker 07f65d24210e4393b74e165670298f12: unsubscribing from channel rq:pubsub:07f65d24210e4393b74e165670298f12 197s 15:36:50 Worker 04873879645f4f918bc85d9e99e1bae0: done, quitting 197s 15:36:50 Worker 04873879645f4f918bc85d9e99e1bae0: unsubscribing from channel rq:pubsub:04873879645f4f918bc85d9e99e1bae0 197s .15:36:50 Worker 040e2d301f804a939c57b7fe776d5ce3: registering birth 197s 15:36:50 Worker 040e2d301f804a939c57b7fe776d5ce3: started with PID 3103, version 2.6.1 197s 15:36:50 Worker 040e2d301f804a939c57b7fe776d5ce3: subscribing to channel rq:pubsub:040e2d301f804a939c57b7fe776d5ce3 197s 15:36:50 *** Listening on default... 197s 15:36:50 Acquiring scheduler lock for default 197s 15:36:50 Acquired scheduler lock for default 197s 15:36:50 Worker 040e2d301f804a939c57b7fe776d5ce3: sent heartbeat to prevent worker timeout. Next one should arrive in 480 seconds. 197s 15:36:50 Worker 040e2d301f804a939c57b7fe776d5ce3: cleaning registries for queue: default 197s 15:36:50 Worker 040e2d301f804a939c57b7fe776d5ce3: *** Listening on default... 197s 15:36:50 Worker 040e2d301f804a939c57b7fe776d5ce3: sent heartbeat to prevent worker timeout. Next one should arrive in 480 seconds. 197s 15:36:50 Worker 040e2d301f804a939c57b7fe776d5ce3: dequeueing jobs on queues default and timeout None 197s 15:36:50 Worker 040e2d301f804a939c57b7fe776d5ce3: sent heartbeat to prevent worker timeout. Next one should arrive in 480 seconds. 197s 15:36:50 Worker 040e2d301f804a939c57b7fe776d5ce3: done, quitting 197s 15:36:50 Worker 040e2d301f804a939c57b7fe776d5ce3: registering death 197s 15:36:50 Worker 040e2d301f804a939c57b7fe776d5ce3: unsubscribing from channel rq:pubsub:040e2d301f804a939c57b7fe776d5ce3 197s .... 198s tests/test_commands.py 15:36:51 Worker 544d7e74536f4a18833f39de17109455: started with PID 3110, version 2.6.1 198s 15:36:51 Worker 544d7e74536f4a18833f39de17109455: subscribing to channel rq:pubsub:544d7e74536f4a18833f39de17109455 198s 15:36:51 *** Listening on foo... 198s 15:36:51 Worker 544d7e74536f4a18833f39de17109455: cleaning registries for queue: foo 200s 15:36:53 Received kill horse command. 200s 15:36:53 Worker is not working, kill horse command ignored 200s 15:36:53 Received shutdown command, sending SIGINT signal. 200s .15:36:53 Worker 544d7e74536f4a18833f39de17109455 [PID 3110]: warm shut down requested 200s 15:36:53 Worker 544d7e74536f4a18833f39de17109455: unsubscribing from channel rq:pubsub:544d7e74536f4a18833f39de17109455 200s ...15:36:53 Worker 6d02e0e44139444b818d79ccf9e3ef7a: started with PID 3116, version 2.6.1 200s 15:36:53 Worker 6d02e0e44139444b818d79ccf9e3ef7a: subscribing to channel rq:pubsub:6d02e0e44139444b818d79ccf9e3ef7a 200s 15:36:53 *** Listening on foo... 200s 15:36:53 Worker 6d02e0e44139444b818d79ccf9e3ef7a: cleaning registries for queue: foo 200s 15:36:53 foo: tests.fixtures.long_running_job(3) (213a529f-e642-4864-a69d-b0f4c2ccfefe) 201s 15:36:54 Not working on job 1, command ignored. 202s 15:36:55 Worker 6d02e0e44139444b818d79ccf9e3ef7a: killed horse pid 3118 202s 15:36:55 Worker 6d02e0e44139444b818d79ccf9e3ef7a: job 213a529f-e642-4864-a69d-b0f4c2ccfefe stopped by user, moving job to FailedJobRegistry 202s 15:36:55 Worker 6d02e0e44139444b818d79ccf9e3ef7a: done, quitting 202s 15:36:55 Worker 6d02e0e44139444b818d79ccf9e3ef7a: unsubscribing from channel rq:pubsub:6d02e0e44139444b818d79ccf9e3ef7a 202s . 202s tests/test_connection.py . 202s tests/test_cron.py ........................15:36:55 Registered 'tests.fixtures.do_nothing' to run on default every 1 seconds 202s 15:36:55 CronScheduler autopkgtest:3119:b6332d: starting... 202s 15:36:55 CronScheduler autopkgtest:3119:b6332d: registering birth... 202s 15:36:55 Enqueued job do_nothing to queue default 202s 15:36:55 CronScheduler autopkgtest:3119:b6332d: received shutdown signal 2 202s 15:36:55 CronScheduler autopkgtest:3119:b6332d: stop requested 202s 15:36:55 CronScheduler autopkgtest:3119:b6332d: registering death... 202s 15:36:55 CronScheduler autopkgtest:3119:b6332d: shutdown complete 202s .... 202s tests/test_cron_job.py ........... 202s tests/test_cron_scheduler_registry.py .... 202s tests/test_decorator.py .............. 204s tests/test_dependencies.py ............. 204s tests/test_executions.py ...15:36:57 Worker w1: started with PID 3150, version 2.6.1 204s 15:36:57 Worker w1: subscribing to channel rq:pubsub:w1 204s 15:36:57 *** Listening on default... 204s 15:36:57 Worker w1: cleaning registries for queue: default 204s 15:36:57 default: tests.fixtures.long_running_job(timeout=3) (7f515f3e-2c01-4dd9-9cc0-da1142e8c37a) 207s 15:37:00 Successfully completed tests.fixtures.long_running_job(timeout=3) job in 0:00:03.000408s on worker w1 207s 15:37:00 default: Job OK (7f515f3e-2c01-4dd9-9cc0-da1142e8c37a) 207s 15:37:00 Result is kept for 500 seconds 207s 15:37:00 Worker w1: done, quitting 207s 15:37:00 Worker w1: unsubscribing from channel rq:pubsub:w1 207s ......... 207s tests/test_fixtures.py .. 213s tests/test_group.py ................ 213s tests/test_helpers.py . 213s tests/test_intermediate_queue.py ........ 213s tests/test_job.py 15:37:06 Worker 3b0dfa06225a4ed2ad411e3556040fb5: started with PID 3165, version 2.6.1 213s 15:37:06 Worker 3b0dfa06225a4ed2ad411e3556040fb5: subscribing to channel rq:pubsub:3b0dfa06225a4ed2ad411e3556040fb5 213s 15:37:06 *** Listening on test_blocking_queue... 213s 15:37:06 Worker 3b0dfa06225a4ed2ad411e3556040fb5: cleaning registries for queue: test_blocking_queue 213s 15:37:06 test_blocking_queue: tests.fixtures.long_running_job(2) (aaee810a-1a87-4044-8590-17ed9f6b7f1d) 215s 15:37:08 Successfully completed tests.fixtures.long_running_job(2) job in 0:00:02.000419s on worker 3b0dfa06225a4ed2ad411e3556040fb5 215s 15:37:08 test_blocking_queue: Job OK (aaee810a-1a87-4044-8590-17ed9f6b7f1d) 215s 15:37:08 Result is kept for 500 seconds 215s ..15:37:08 Worker 3b0dfa06225a4ed2ad411e3556040fb5: done, quitting 215s 15:37:08 Worker 3b0dfa06225a4ed2ad411e3556040fb5: unsubscribing from channel rq:pubsub:3b0dfa06225a4ed2ad411e3556040fb5 218s ........................................................ 218s tests/test_job_dependency.py ...# Post enqueue {b'5fc2c7b4-1b3c-485c-8122-23c6271e5abf'} 218s # Post work {b'5fc2c7b4-1b3c-485c-8122-23c6271e5abf'} 218s # Pre cancel {b'5fc2c7b4-1b3c-485c-8122-23c6271e5abf'} 218s #Post cancel set() 218s ............15:37:11 Worker w1: started with PID 3173, version 2.6.1 218s 15:37:11 Worker w1: subscribing to channel rq:pubsub:w1 218s 15:37:11 *** Listening on default... 218s 15:37:11 Worker w1: cleaning registries for queue: default 218s 15:37:11 default: tests.fixtures.rpush('test_job:job_order', 'slow_1', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True, 0.5) (slow_1) 219s 15:37:12 Successfully completed tests.fixtures.rpush('test_job:job_order', 'slow_1', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True, 0.5) job in 0:00:00.501414s on worker w1 219s 15:37:12 default: Job OK (slow_1) 219s 15:37:12 Result is kept for 500 seconds 219s 15:37:12 default: tests.fixtures.rpush('test_job:job_order', 'A', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True) (c5cf9062-1def-4244-9639-7e108b6b865d) 219s 15:37:12 Successfully completed tests.fixtures.rpush('test_job:job_order', 'A', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True) job in 0:00:00.000781s on worker w1 219s 15:37:12 default: Job OK (c5cf9062-1def-4244-9639-7e108b6b865d) 219s 15:37:12 Result is kept for 500 seconds 219s 15:37:12 default: tests.fixtures.rpush('test_job:job_order', 'B', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True) (7008d581-0b28-4d4a-812f-5e63776b068b) 219s 15:37:12 Successfully completed tests.fixtures.rpush('test_job:job_order', 'B', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True) job in 0:00:00.000719s on worker w1 219s 15:37:12 default: Job OK (7008d581-0b28-4d4a-812f-5e63776b068b) 219s 15:37:12 Result is kept for 500 seconds 219s 15:37:12 Worker w1: done, quitting 219s 15:37:12 Worker w1: unsubscribing from channel rq:pubsub:w1 220s 15:37:13 Worker w1: started with PID 3180, version 2.6.1 220s 15:37:13 Worker w1: subscribing to channel rq:pubsub:w1 220s 15:37:13 *** Listening on default... 220s 15:37:13 Worker w1: cleaning registries for queue: default 220s 15:37:13 default: tests.fixtures.rpush('test_job:job_order', 'slow_1', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True, 0.5) (slow_1) 221s 15:37:14 Successfully completed tests.fixtures.rpush('test_job:job_order', 'slow_1', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True, 0.5) job in 0:00:00.501357s on worker w1 221s 15:37:14 default: Job OK (slow_1) 221s 15:37:14 Result is kept for 500 seconds 221s 15:37:14 default: tests.fixtures.rpush('test_job:job_order', 'B', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True) (89c13356-64b7-42cc-83d3-184dd9f877f7) 221s 15:37:14 Successfully completed tests.fixtures.rpush('test_job:job_order', 'B', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True) job in 0:00:00.000893s on worker w1 221s 15:37:14 default: Job OK (89c13356-64b7-42cc-83d3-184dd9f877f7) 221s 15:37:14 Result is kept for 500 seconds 221s 15:37:14 Worker w1: done, quitting 221s 15:37:14 Worker w1: unsubscribing from channel rq:pubsub:w1 222s .15:37:15 Worker w1: started with PID 3187, version 2.6.1 222s 15:37:15 Worker w1: subscribing to channel rq:pubsub:w1 222s 15:37:15 *** Listening on default... 222s 15:37:15 Worker w1: cleaning registries for queue: default 222s 15:37:15 default: tests.fixtures.rpush('test_job:job_order', 'slow', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True, 0.5) (slow_job) 223s 15:37:16 Successfully completed tests.fixtures.rpush('test_job:job_order', 'slow', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True, 0.5) job in 0:00:00.501464s on worker w1 223s 15:37:16 default: Job OK (slow_job) 223s 15:37:16 Result is kept for 500 seconds 223s 15:37:16 Worker w1: done, quitting 223s 15:37:16 Worker w1: unsubscribing from channel rq:pubsub:w1 224s 15:37:17 Worker w1: started with PID 3193, version 2.6.1 224s 15:37:17 Worker w1: subscribing to channel rq:pubsub:w1 224s 15:37:17 *** Listening on default... 224s 15:37:17 Worker w1: cleaning registries for queue: default 224s 15:37:17 default: tests.fixtures.rpush('test_job:job_order', 'slow', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True, 0.5) (slow_job) 224s 15:37:17 Successfully completed tests.fixtures.rpush('test_job:job_order', 'slow', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True, 0.5) job in 0:00:00.501380s on worker w1 224s 15:37:17 default: Job OK (slow_job) 224s 15:37:17 Result is kept for 500 seconds 224s 15:37:17 default: tests.fixtures.rpush('test_job:job_order', 'A', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., False) (306589a0-cad7-4afe-8c04-1574be51f6e3) 224s 15:37:17 Successfully completed tests.fixtures.rpush('test_job:job_order', 'A', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., False) job in 0:00:00.000775s on worker w1 224s 15:37:17 default: Job OK (306589a0-cad7-4afe-8c04-1574be51f6e3) 224s 15:37:17 Result is kept for 500 seconds 224s 15:37:17 Worker w1: done, quitting 224s 15:37:17 Worker w1: unsubscribing from channel rq:pubsub:w1 225s .......... 225s tests/test_queue.py ....................................................... 225s tests/test_registry.py ....................................... 225s tests/test_repeat.py ......... 226s tests/test_results.py ............. 226s tests/test_retry.py ........... 240s tests/test_scheduler.py ..................s...... 240s tests/test_serializers.py . 240s tests/test_spawn_worker.py Worker 3bd47599ca1d48d7b70f080b3c70425c: job 40a1c170-4dd9-4e9d-8936-776d09789eaa: exception raised while executing (tests.fixtures.div_by_zero) 240s Traceback (most recent call last): 240s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 240s return_value = job.perform() 240s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 240s self._result = self._execute() 240s ~~~~~~~~~~~~~^^ 240s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 240s result = self.func(*self.args, **self.kwargs) 240s TypeError: div_by_zero() missing 1 required positional argument: 'x' 240s 240s .ss 245s tests/test_timeouts.py .. 245s tests/test_utils.py ......................... 266s tests/test_worker.py ......................ss...........s............s.s...................sssss15:37:59 Worker eaf209275c7641c8b4735eacb02bb036: started with PID 3360, version 2.6.1 266s 15:37:59 Worker eaf209275c7641c8b4735eacb02bb036: subscribing to channel rq:pubsub:eaf209275c7641c8b4735eacb02bb036 266s 15:37:59 *** Listening on default... 266s 15:37:59 Worker eaf209275c7641c8b4735eacb02bb036: cleaning registries for queue: default 266s 15:37:59 default: tests.fixtures.access_self() (67111f79-7b74-471e-8ce4-ad5b22f09b39) 266s 15:37:59 Successfully completed tests.fixtures.access_self() job in 0:00:00.077511s on worker eaf209275c7641c8b4735eacb02bb036 266s 15:37:59 default: Job OK (67111f79-7b74-471e-8ce4-ad5b22f09b39) 266s 15:37:59 Result is kept for 500 seconds 266s 15:37:59 Worker eaf209275c7641c8b4735eacb02bb036: done, quitting 266s 15:37:59 Worker eaf209275c7641c8b4735eacb02bb036: unsubscribing from channel rq:pubsub:eaf209275c7641c8b4735eacb02bb036 266s .15:37:59 Worker 79fd8ad7443d4fb79c81f1d2b49a6cce: started with PID 3364, version 2.6.1 266s 15:37:59 Worker 79fd8ad7443d4fb79c81f1d2b49a6cce: subscribing to channel rq:pubsub:79fd8ad7443d4fb79c81f1d2b49a6cce 266s 15:37:59 *** Listening on default... 266s 15:37:59 Worker 79fd8ad7443d4fb79c81f1d2b49a6cce: cleaning registries for queue: default 266s 15:37:59 Worker 79fd8ad7443d4fb79c81f1d2b49a6cce: done, quitting 266s 15:37:59 Worker 79fd8ad7443d4fb79c81f1d2b49a6cce: unsubscribing from channel rq:pubsub:79fd8ad7443d4fb79c81f1d2b49a6cce 266s .15:37:59 Worker a72fd41eb41d4d13afb4adc28408122b: started with PID 3367, version 2.6.1 266s 15:37:59 Worker a72fd41eb41d4d13afb4adc28408122b: subscribing to channel rq:pubsub:a72fd41eb41d4d13afb4adc28408122b 266s 15:37:59 *** Listening on default... 266s 15:37:59 Worker a72fd41eb41d4d13afb4adc28408122b: cleaning registries for queue: default 266s 15:37:59 default: tests.test_worker.schedule_access_self() (73dc370e-7df7-4276-bce3-b4401b2fbec5) 266s 15:37:59 Successfully completed tests.test_worker.schedule_access_self() job in 0:00:00.088037s on worker a72fd41eb41d4d13afb4adc28408122b 266s 15:37:59 default: Job OK (73dc370e-7df7-4276-bce3-b4401b2fbec5) 266s 15:37:59 Result is kept for 500 seconds 266s 15:37:59 Worker a72fd41eb41d4d13afb4adc28408122b: done, quitting 266s 15:37:59 Worker a72fd41eb41d4d13afb4adc28408122b: unsubscribing from channel rq:pubsub:a72fd41eb41d4d13afb4adc28408122b 267s .sReal-time signal 0 268s autopkgtest [15:38:02]: test smoke: -----------------------] 268s autopkgtest [15:38:02]: test smoke: - - - - - - - - - - results - - - - - - - - - - 268s smoke FAIL non-zero exit status 162 268s autopkgtest [15:38:02]: test autodep8-python3: preparing testbed 286s Creating nova instance adt-resolute-amd64-python-rq-20260124-153334-juju-7f2275-prod-proposed-migration-environment-15-99b6c0cd-ea07-42f0-bc01-13c2d64c544e from image adt/ubuntu-resolute-amd64-server-20260124.img (UUID 46811f22-3700-43f4-953b-ee3c613d71bb)... 360s autopkgtest [15:39:34]: testbed dpkg architecture: amd64 360s autopkgtest [15:39:34]: testbed apt version: 3.1.13 360s autopkgtest [15:39:34]: @@@@@@@@@@@@@@@@@@@@ test bed setup 360s autopkgtest [15:39:34]: testbed release detected to be: resolute 361s autopkgtest [15:39:35]: updating testbed package index (apt update) 361s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 361s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 361s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 361s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 361s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [991 kB] 361s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 361s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [91.5 kB] 361s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [24.3 kB] 361s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [96.0 kB] 361s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [113 kB] 361s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [3712 B] 361s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [3696 B] 361s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [12.3 kB] 361s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [336 B] 362s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [897 kB] 362s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [377 kB] 362s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [24.6 kB] 362s Get:18 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [4704 B] 362s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [12.4 kB] 362s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [824 B] 362s Fetched 2779 kB in 1s (3228 kB/s) 363s Reading package lists... 363s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 363s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 363s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 363s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 364s Reading package lists... 364s Reading package lists... 364s Building dependency tree... 364s Reading state information... 364s Calculating upgrade... 364s The following packages were automatically installed and are no longer required: 364s python3.13 python3.13-minimal 364s Use 'sudo apt autoremove' to remove them. 364s The following NEW packages will be installed: 364s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 364s The following packages will be upgraded: 364s libpython3-stdlib python3 python3-gdbm python3-minimal rust-coreutils 364s 5 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 364s Need to get 10.4 MB of archives. 364s After this operation, 25.2 MB of additional disk space will be used. 364s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-minimal amd64 3.14.2-1 [920 kB] 364s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14-minimal amd64 3.14.2-1 [2563 kB] 365s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-minimal amd64 3.14.2-1 [28.2 kB] 365s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3 amd64 3.14.2-1 [22.9 kB] 365s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-stdlib amd64 3.14.2-1 [2398 kB] 365s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14 amd64 3.14.2-1 [816 kB] 365s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 libpython3-stdlib amd64 3.14.2-1 [10.9 kB] 365s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 rust-coreutils amd64 0.5.0-0ubuntu1 [3628 kB] 365s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-gdbm amd64 3.14.2-1 [11.1 kB] 365s dpkg-preconfigure: unable to re-open stdin: No such file or directory 365s Fetched 10.4 MB in 1s (14.8 MB/s) 365s Selecting previously unselected package libpython3.14-minimal:amd64. 365s (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 ... 124177 files and directories currently installed.) 365s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_amd64.deb ... 365s Unpacking libpython3.14-minimal:amd64 (3.14.2-1) ... 365s Selecting previously unselected package python3.14-minimal. 365s Preparing to unpack .../python3.14-minimal_3.14.2-1_amd64.deb ... 365s Unpacking python3.14-minimal (3.14.2-1) ... 365s Setting up libpython3.14-minimal:amd64 (3.14.2-1) ... 365s Setting up python3.14-minimal (3.14.2-1) ... 366s (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 ... 124519 files and directories currently installed.) 366s Preparing to unpack .../python3-minimal_3.14.2-1_amd64.deb ... 366s Unpacking python3-minimal (3.14.2-1) over (3.13.9-3) ... 366s Setting up python3-minimal (3.14.2-1) ... 366s (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 ... 124519 files and directories currently installed.) 366s Preparing to unpack .../python3_3.14.2-1_amd64.deb ... 366s running python pre-rtupdate hooks for python3.14... 366s Unpacking python3 (3.14.2-1) over (3.13.9-3) ... 366s Selecting previously unselected package libpython3.14-stdlib:amd64. 366s Preparing to unpack .../libpython3.14-stdlib_3.14.2-1_amd64.deb ... 366s Unpacking libpython3.14-stdlib:amd64 (3.14.2-1) ... 366s Selecting previously unselected package python3.14. 366s Preparing to unpack .../python3.14_3.14.2-1_amd64.deb ... 366s Unpacking python3.14 (3.14.2-1) ... 367s Preparing to unpack .../libpython3-stdlib_3.14.2-1_amd64.deb ... 367s Unpacking libpython3-stdlib:amd64 (3.14.2-1) over (3.13.9-3) ... 367s Preparing to unpack .../rust-coreutils_0.5.0-0ubuntu1_amd64.deb ... 367s Unpacking rust-coreutils (0.5.0-0ubuntu1) over (0.2.2-0ubuntu2) ... 367s Setting up rust-coreutils (0.5.0-0ubuntu1) ... 367s (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 ... 124980 files and directories currently installed.) 367s Preparing to unpack .../python3-gdbm_3.14.2-1_amd64.deb ... 367s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-3) ... 367s Setting up libpython3.14-stdlib:amd64 (3.14.2-1) ... 367s Setting up python3.14 (3.14.2-1) ... 367s Setting up libpython3-stdlib:amd64 (3.14.2-1) ... 367s Setting up python3-gdbm (3.14.2-1) ... 367s Setting up python3 (3.14.2-1) ... 367s running python rtupdate hooks for python3.14... 367s running python post-rtupdate hooks for python3.14... 367s Processing triggers for man-db (2.13.1-1) ... 368s Processing triggers for systemd (259-1ubuntu2) ... 368s autopkgtest [15:39:42]: upgrading testbed (apt dist-upgrade and autopurge) 368s Reading package lists... 368s Building dependency tree... 368s Reading state information... 368s Calculating upgrade... 368s The following packages were automatically installed and are no longer required: 368s python3.13 python3.13-minimal 368s Use 'sudo apt autoremove' to remove them. 368s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 369s Reading package lists... 369s Building dependency tree... 369s Reading state information... 369s Solving dependencies... 369s The following packages will be REMOVED: 369s python3.13* python3.13-minimal* 369s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 369s After this operation, 7860 kB disk space will be freed. 369s (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 ... 124978 files and directories currently installed.) 369s Removing python3.13 (3.13.11-1) ... 369s Removing python3.13-minimal (3.13.11-1) ... 370s Processing triggers for man-db (2.13.1-1) ... 370s Processing triggers for systemd (259-1ubuntu2) ... 370s (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 ... 124951 files and directories currently installed.) 370s Purging configuration files for python3.13-minimal (3.13.11-1) ... 373s Reading package lists... 373s Building dependency tree... 373s Reading state information... 373s Solving dependencies... 373s The following NEW packages will be installed: 373s python3-all python3-click python3-croniter python3-redis python3-rq 373s python3.13 python3.13-minimal 373s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 373s Need to get 3583 kB of archives. 373s After this operation, 10.3 MB of additional disk space will be used. 373s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.13-minimal amd64 3.13.11-1 [2408 kB] 373s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.13 amd64 3.13.11-1 [758 kB] 373s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-all amd64 3.14.2-1 [890 B] 373s Get:4 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-click all 8.2.0+0.really.8.1.8-1build1 [80.2 kB] 373s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-croniter all 6.1~rc1-0ubuntu1 [21.7 kB] 373s Get:6 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-redis all 6.4.0-1 [228 kB] 373s Get:7 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-rq all 2.6.1-1 [86.3 kB] 374s Fetched 3583 kB in 1s (5312 kB/s) 374s Selecting previously unselected package python3.13-minimal. 374s (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 ... 124953 files and directories currently installed.) 374s Preparing to unpack .../0-python3.13-minimal_3.13.11-1_amd64.deb ... 374s Unpacking python3.13-minimal (3.13.11-1) ... 374s Selecting previously unselected package python3.13. 374s Preparing to unpack .../1-python3.13_3.13.11-1_amd64.deb ... 374s Unpacking python3.13 (3.13.11-1) ... 374s Selecting previously unselected package python3-all. 374s Preparing to unpack .../2-python3-all_3.14.2-1_amd64.deb ... 374s Unpacking python3-all (3.14.2-1) ... 374s Selecting previously unselected package python3-click. 374s Preparing to unpack .../3-python3-click_8.2.0+0.really.8.1.8-1build1_all.deb ... 374s Unpacking python3-click (8.2.0+0.really.8.1.8-1build1) ... 374s Selecting previously unselected package python3-croniter. 374s Preparing to unpack .../4-python3-croniter_6.1~rc1-0ubuntu1_all.deb ... 374s Unpacking python3-croniter (6.1~rc1-0ubuntu1) ... 374s Selecting previously unselected package python3-redis. 374s Preparing to unpack .../5-python3-redis_6.4.0-1_all.deb ... 374s Unpacking python3-redis (6.4.0-1) ... 374s Selecting previously unselected package python3-rq. 374s Preparing to unpack .../6-python3-rq_2.6.1-1_all.deb ... 374s Unpacking python3-rq (2.6.1-1) ... 374s Setting up python3-croniter (6.1~rc1-0ubuntu1) ... 374s Setting up python3-click (8.2.0+0.really.8.1.8-1build1) ... 374s Setting up python3-redis (6.4.0-1) ... 375s Setting up python3.13-minimal (3.13.11-1) ... 375s Setting up python3.13 (3.13.11-1) ... 376s Setting up python3-all (3.14.2-1) ... 376s Setting up python3-rq (2.6.1-1) ... 376s Processing triggers for man-db (2.13.1-1) ... 376s Processing triggers for systemd (259-1ubuntu2) ... 377s autopkgtest [15:39:51]: test autodep8-python3: set -e ; for py in $(py3versions -r 2>/dev/null) ; do cd "$AUTOPKGTEST_TMP" ; echo "Testing with $py:" ; $py -c "import rq; print(rq)" ; done 377s autopkgtest [15:39:51]: test autodep8-python3: [----------------------- 378s Testing with python3.13: 378s 378s Testing with python3.14: 378s 378s autopkgtest [15:39:52]: test autodep8-python3: -----------------------] 379s autodep8-python3 PASS (superficial) 379s autopkgtest [15:39:53]: test autodep8-python3: - - - - - - - - - - results - - - - - - - - - - 379s autopkgtest [15:39:53]: @@@@@@@@@@@@@@@@@@@@ summary 379s smoke FAIL non-zero exit status 162 379s autodep8-python3 PASS (superficial)