0s autopkgtest [15:03:42]: starting date and time: 2026-01-23 15:03:42+0000 0s autopkgtest [15:03:42]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [15:03:42]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.gbkfqb72/out --timeout-copy=6000 -a i386 --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-20@sto01-14.secgroup --name adt-resolute-i386-python-rq-20260123-150342-juju-7f2275-prod-proposed-migration-environment-20-a3476ab3-80a1-498f-804d-3dd57117bd69 --image adt/ubuntu-resolute-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-autopkgtest-workers-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-i386-python-rq-20260123-150342-juju-7f2275-prod-proposed-migration-environment-20-a3476ab3-80a1-498f-804d-3dd57117bd69 from image adt/ubuntu-resolute-amd64-server-20260123.img (UUID 789dbc23-4f1b-4e7c-bcab-98e6eebf04f2)... 82s autopkgtest [15:05:04]: testbed dpkg architecture: amd64 83s autopkgtest [15:05:05]: testbed apt version: 3.1.13 83s autopkgtest [15:05:05]: @@@@@@@@@@@@@@@@@@@@ test bed setup 83s autopkgtest [15:05:05]: testbed release detected to be: None 84s autopkgtest [15:05:06]: updating testbed package index (apt update) 84s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 84s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 84s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 84s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 84s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1019 kB] 84s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [23.6 kB] 84s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 84s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [95.0 kB] 84s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [129 kB] 84s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [107 kB] 84s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [3656 B] 84s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [3696 B] 84s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [12.3 kB] 84s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [336 B] 85s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [401 kB] 85s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [913 kB] 85s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [23.5 kB] 85s Get:18 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [11.6 kB] 85s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [4704 B] 85s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [668 B] 85s Fetched 2876 kB in 1s (3375 kB/s) 86s Reading package lists... 86s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 86s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 86s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 86s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 87s Reading package lists... 87s Reading package lists... 87s Building dependency tree... 87s Reading state information... 87s Calculating upgrade... 87s The following packages were automatically installed and are no longer required: 87s python3.13 python3.13-minimal 87s Use 'sudo apt autoremove' to remove them. 87s The following NEW packages will be installed: 87s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 87s The following packages will be upgraded: 87s libplymouth5 libpython3-stdlib libtasn1-6 plymouth 87s plymouth-theme-ubuntu-text python3 python3-gdbm python3-jaraco.context 87s python3-minimal 87s 9 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 87s Need to get 7110 kB of archives. 87s After this operation, 24.5 MB of additional disk space will be used. 87s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-minimal amd64 3.14.2-1 [920 kB] 87s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14-minimal amd64 3.14.2-1 [2563 kB] 88s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-minimal amd64 3.14.2-1 [28.2 kB] 88s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3 amd64 3.14.2-1 [22.9 kB] 88s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-stdlib amd64 3.14.2-1 [2398 kB] 88s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14 amd64 3.14.2-1 [816 kB] 88s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 libpython3-stdlib amd64 3.14.2-1 [10.9 kB] 88s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 libtasn1-6 amd64 4.21.0-2 [45.3 kB] 88s Get:9 http://ftpmaster.internal/ubuntu resolute/main amd64 libplymouth5 amd64 24.004.60+git20250831.4a3c171d-0ubuntu5 [143 kB] 88s Get:10 http://ftpmaster.internal/ubuntu resolute/main amd64 plymouth-theme-ubuntu-text amd64 24.004.60+git20250831.4a3c171d-0ubuntu5 [10.2 kB] 88s Get:11 http://ftpmaster.internal/ubuntu resolute/main amd64 plymouth amd64 24.004.60+git20250831.4a3c171d-0ubuntu5 [135 kB] 88s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-gdbm amd64 3.14.2-1 [11.1 kB] 88s Get:13 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-jaraco.context all 6.0.1-2 [8198 B] 88s dpkg-preconfigure: unable to re-open stdin: No such file or directory 88s Fetched 7110 kB in 0s (17.4 MB/s) 88s Selecting previously unselected package libpython3.14-minimal:amd64. 88s (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 ... 124176 files and directories currently installed.) 88s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_amd64.deb ... 88s Unpacking libpython3.14-minimal:amd64 (3.14.2-1) ... 88s Selecting previously unselected package python3.14-minimal. 88s Preparing to unpack .../python3.14-minimal_3.14.2-1_amd64.deb ... 88s Unpacking python3.14-minimal (3.14.2-1) ... 88s Setting up libpython3.14-minimal:amd64 (3.14.2-1) ... 88s Setting up python3.14-minimal (3.14.2-1) ... 89s (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 ... 124518 files and directories currently installed.) 89s Preparing to unpack .../python3-minimal_3.14.2-1_amd64.deb ... 89s Unpacking python3-minimal (3.14.2-1) over (3.13.9-3) ... 89s Setting up python3-minimal (3.14.2-1) ... 89s (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 ... 124518 files and directories currently installed.) 89s Preparing to unpack .../0-python3_3.14.2-1_amd64.deb ... 89s running python pre-rtupdate hooks for python3.14... 89s Unpacking python3 (3.14.2-1) over (3.13.9-3) ... 89s Selecting previously unselected package libpython3.14-stdlib:amd64. 89s Preparing to unpack .../1-libpython3.14-stdlib_3.14.2-1_amd64.deb ... 89s Unpacking libpython3.14-stdlib:amd64 (3.14.2-1) ... 89s Selecting previously unselected package python3.14. 89s Preparing to unpack .../2-python3.14_3.14.2-1_amd64.deb ... 89s Unpacking python3.14 (3.14.2-1) ... 89s Preparing to unpack .../3-libpython3-stdlib_3.14.2-1_amd64.deb ... 89s Unpacking libpython3-stdlib:amd64 (3.14.2-1) over (3.13.9-3) ... 89s Preparing to unpack .../4-libtasn1-6_4.21.0-2_amd64.deb ... 89s Unpacking libtasn1-6:amd64 (4.21.0-2) over (4.20.0-2ubuntu1) ... 89s Preparing to unpack .../5-libplymouth5_24.004.60+git20250831.4a3c171d-0ubuntu5_amd64.deb ... 89s Unpacking libplymouth5:amd64 (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 89s Preparing to unpack .../6-plymouth-theme-ubuntu-text_24.004.60+git20250831.4a3c171d-0ubuntu5_amd64.deb ... 89s Unpacking plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 89s Preparing to unpack .../7-plymouth_24.004.60+git20250831.4a3c171d-0ubuntu5_amd64.deb ... 89s Unpacking plymouth (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 89s Preparing to unpack .../8-python3-gdbm_3.14.2-1_amd64.deb ... 89s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-3) ... 89s Preparing to unpack .../9-python3-jaraco.context_6.0.1-2_all.deb ... 90s Unpacking python3-jaraco.context (6.0.1-2) over (6.0.1-1build1) ... 90s Setting up libpython3.14-stdlib:amd64 (3.14.2-1) ... 90s Setting up libplymouth5:amd64 (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 90s Setting up libtasn1-6:amd64 (4.21.0-2) ... 90s Setting up python3.14 (3.14.2-1) ... 90s Setting up libpython3-stdlib:amd64 (3.14.2-1) ... 90s Setting up python3-gdbm (3.14.2-1) ... 90s Setting up plymouth (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 90s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 90s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 91s Setting up python3 (3.14.2-1) ... 91s running python rtupdate hooks for python3.14... 91s running python post-rtupdate hooks for python3.14... 91s Setting up python3-jaraco.context (6.0.1-2) ... 91s Processing triggers for libc-bin (2.42-2ubuntu4) ... 91s Processing triggers for systemd (259-1ubuntu2) ... 91s Processing triggers for man-db (2.13.1-1) ... 91s Processing triggers for initramfs-tools (0.150ubuntu7) ... 92s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 96s Setting up plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 96s Processing triggers for initramfs-tools (0.150ubuntu7) ... 96s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 100s autopkgtest [15:05:22]: upgrading testbed (apt dist-upgrade and autopurge) 101s Reading package lists... 101s Building dependency tree... 101s Reading state information... 101s Calculating upgrade... 101s The following packages were automatically installed and are no longer required: 101s python3.13 python3.13-minimal 101s Use 'sudo apt autoremove' to remove them. 101s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 101s Reading package lists... 101s Building dependency tree... 101s Reading state information... 101s Solving dependencies... 101s The following packages will be REMOVED: 101s python3.13* python3.13-minimal* 101s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 101s After this operation, 7860 kB disk space will be freed. 101s (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 ... 124945 files and directories currently installed.) 101s Removing python3.13 (3.13.11-1) ... 101s Removing python3.13-minimal (3.13.11-1) ... 102s Processing triggers for man-db (2.13.1-1) ... 102s Processing triggers for systemd (259-1ubuntu2) ... 102s (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 ... 124918 files and directories currently installed.) 102s Purging configuration files for python3.13-minimal (3.13.11-1) ... 102s autopkgtest [15:05:24]: rebooting testbed after setup commands that affected boot 164s autopkgtest [15:06:26]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:49:02 UTC 2026 166s autopkgtest [15:06:28]: @@@@@@@@@@@@@@@@@@@@ apt-source python-rq 168s Get:1 http://ftpmaster.internal/ubuntu resolute/universe python-rq 2.6.1-1 (dsc) [2206 B] 168s Get:2 http://ftpmaster.internal/ubuntu resolute/universe python-rq 2.6.1-1 (tar) [680 kB] 168s Get:3 http://ftpmaster.internal/ubuntu resolute/universe python-rq 2.6.1-1 (diff) [7124 B] 168s gpgv: Signature made Sun Nov 30 07:46:24 2025 UTC 168s gpgv: using RSA key B70DFC6F134FECFC011E62AA83016014251D1DB0 168s gpgv: issuer "c.schoenert@t-online.de" 168s gpgv: Can't check signature: No public key 168s dpkg-source: warning: cannot verify inline signature for ./python-rq_2.6.1-1.dsc: no acceptable signature found 168s autopkgtest [15:06:30]: testing package python-rq version 2.6.1-1 168s autopkgtest [15:06:30]: build not needed 168s autopkgtest [15:06:30]: test smoke: preparing testbed 169s Reading package lists... 169s Building dependency tree... 169s Reading state information... 169s Solving dependencies... 169s The following NEW packages will be installed: 169s libjs-jquery libjs-jquery-hotkeys libjs-jquery-isonscreen 169s libjs-jquery-metadata libjs-jquery-tablesorter 169s libjs-jquery-throttle-debounce liblzf1 python3-all python3-click 169s python3-coverage python3-croniter python3-iniconfig python3-pluggy 169s python3-psutil python3-pytest python3-pytest-cov python3-redis python3-rq 169s python3.13 python3.13-minimal redis-server redis-tools 169s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 169s Need to get 6234 kB of archives. 169s After this operation, 23.5 MB of additional disk space will be used. 169s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.13-minimal amd64 3.13.11-1 [2408 kB] 169s Get:2 http://ftpmaster.internal/ubuntu resolute/universe amd64 liblzf1 amd64 3.6-4build1 [7490 B] 169s Get:3 http://ftpmaster.internal/ubuntu resolute/universe amd64 redis-tools amd64 5:8.0.5-1 [1363 kB] 169s Get:4 http://ftpmaster.internal/ubuntu resolute/universe amd64 redis-server amd64 5:8.0.5-1 [53.2 kB] 169s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.13 amd64 3.13.11-1 [758 kB] 169s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 libjs-jquery all 3.7.1+dfsg+~3.5.33-1build1 [321 kB] 169s Get:7 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-metadata all 12-4build1 [6692 B] 169s Get:8 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-tablesorter all 1:2.31.3+dfsg1-5 [192 kB] 169s Get:9 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-throttle-debounce all 1.1+dfsg.1-2build1 [12.6 kB] 169s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-all amd64 3.14.2-1 [890 B] 169s Get:11 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-click all 8.2.0+0.really.8.1.8-1build1 [80.2 kB] 169s Get:12 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-coverage amd64 7.8.2+dfsg1-1ubuntu1 [159 kB] 169s Get:13 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-croniter all 6.1~rc1-0ubuntu1 [21.7 kB] 169s Get:14 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-iniconfig all 2.1.0-2 [6962 B] 169s Get:15 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pluggy all 1.6.0-2 [21.1 kB] 169s Get:16 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-psutil amd64 7.1.0-1ubuntu1 [191 kB] 169s Get:17 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest all 9.0.2-2 [277 kB] 169s Get:18 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-hotkeys all 0.2.0-1 [13.3 kB] 169s Get:19 http://ftpmaster.internal/ubuntu resolute/universe amd64 libjs-jquery-isonscreen all 1.2.0-2 [3814 B] 169s Get:20 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-pytest-cov all 5.0.0-1 [21.3 kB] 169s Get:21 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-redis all 6.4.0-1 [228 kB] 169s Get:22 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-rq all 2.6.1-1 [86.3 kB] 170s Fetched 6234 kB in 0s (17.2 MB/s) 170s Selecting previously unselected package python3.13-minimal. 170s (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 ... 124920 files and directories currently installed.) 170s Preparing to unpack .../00-python3.13-minimal_3.13.11-1_amd64.deb ... 170s Unpacking python3.13-minimal (3.13.11-1) ... 170s Selecting previously unselected package liblzf1:amd64. 170s Preparing to unpack .../01-liblzf1_3.6-4build1_amd64.deb ... 170s Unpacking liblzf1:amd64 (3.6-4build1) ... 170s Selecting previously unselected package redis-tools. 170s Preparing to unpack .../02-redis-tools_5%3a8.0.5-1_amd64.deb ... 170s Unpacking redis-tools (5:8.0.5-1) ... 170s Selecting previously unselected package redis-server. 170s Preparing to unpack .../03-redis-server_5%3a8.0.5-1_amd64.deb ... 170s Unpacking redis-server (5:8.0.5-1) ... 170s Selecting previously unselected package python3.13. 170s Preparing to unpack .../04-python3.13_3.13.11-1_amd64.deb ... 170s Unpacking python3.13 (3.13.11-1) ... 170s Selecting previously unselected package libjs-jquery. 170s Preparing to unpack .../05-libjs-jquery_3.7.1+dfsg+~3.5.33-1build1_all.deb ... 170s Unpacking libjs-jquery (3.7.1+dfsg+~3.5.33-1build1) ... 170s Selecting previously unselected package libjs-jquery-metadata. 170s Preparing to unpack .../06-libjs-jquery-metadata_12-4build1_all.deb ... 170s Unpacking libjs-jquery-metadata (12-4build1) ... 170s Selecting previously unselected package libjs-jquery-tablesorter. 170s Preparing to unpack .../07-libjs-jquery-tablesorter_1%3a2.31.3+dfsg1-5_all.deb ... 170s Unpacking libjs-jquery-tablesorter (1:2.31.3+dfsg1-5) ... 170s Selecting previously unselected package libjs-jquery-throttle-debounce. 170s Preparing to unpack .../08-libjs-jquery-throttle-debounce_1.1+dfsg.1-2build1_all.deb ... 170s Unpacking libjs-jquery-throttle-debounce (1.1+dfsg.1-2build1) ... 170s Selecting previously unselected package python3-all. 170s Preparing to unpack .../09-python3-all_3.14.2-1_amd64.deb ... 170s Unpacking python3-all (3.14.2-1) ... 170s Selecting previously unselected package python3-click. 170s Preparing to unpack .../10-python3-click_8.2.0+0.really.8.1.8-1build1_all.deb ... 170s Unpacking python3-click (8.2.0+0.really.8.1.8-1build1) ... 170s Selecting previously unselected package python3-coverage. 170s Preparing to unpack .../11-python3-coverage_7.8.2+dfsg1-1ubuntu1_amd64.deb ... 170s Unpacking python3-coverage (7.8.2+dfsg1-1ubuntu1) ... 170s Selecting previously unselected package python3-croniter. 170s Preparing to unpack .../12-python3-croniter_6.1~rc1-0ubuntu1_all.deb ... 170s Unpacking python3-croniter (6.1~rc1-0ubuntu1) ... 170s Selecting previously unselected package python3-iniconfig. 170s Preparing to unpack .../13-python3-iniconfig_2.1.0-2_all.deb ... 170s Unpacking python3-iniconfig (2.1.0-2) ... 170s Selecting previously unselected package python3-pluggy. 170s Preparing to unpack .../14-python3-pluggy_1.6.0-2_all.deb ... 170s Unpacking python3-pluggy (1.6.0-2) ... 170s Selecting previously unselected package python3-psutil. 170s Preparing to unpack .../15-python3-psutil_7.1.0-1ubuntu1_amd64.deb ... 170s Unpacking python3-psutil (7.1.0-1ubuntu1) ... 170s Selecting previously unselected package python3-pytest. 170s Preparing to unpack .../16-python3-pytest_9.0.2-2_all.deb ... 170s Unpacking python3-pytest (9.0.2-2) ... 170s Selecting previously unselected package libjs-jquery-hotkeys. 170s Preparing to unpack .../17-libjs-jquery-hotkeys_0.2.0-1_all.deb ... 170s Unpacking libjs-jquery-hotkeys (0.2.0-1) ... 170s Selecting previously unselected package libjs-jquery-isonscreen. 170s Preparing to unpack .../18-libjs-jquery-isonscreen_1.2.0-2_all.deb ... 170s Unpacking libjs-jquery-isonscreen (1.2.0-2) ... 170s Selecting previously unselected package python3-pytest-cov. 170s Preparing to unpack .../19-python3-pytest-cov_5.0.0-1_all.deb ... 170s Unpacking python3-pytest-cov (5.0.0-1) ... 170s Selecting previously unselected package python3-redis. 170s Preparing to unpack .../20-python3-redis_6.4.0-1_all.deb ... 170s Unpacking python3-redis (6.4.0-1) ... 170s Selecting previously unselected package python3-rq. 170s Preparing to unpack .../21-python3-rq_2.6.1-1_all.deb ... 170s Unpacking python3-rq (2.6.1-1) ... 170s Setting up python3-iniconfig (2.1.0-2) ... 170s Setting up python3-croniter (6.1~rc1-0ubuntu1) ... 170s Setting up libjs-jquery-throttle-debounce (1.1+dfsg.1-2build1) ... 170s Setting up python3-click (8.2.0+0.really.8.1.8-1build1) ... 171s Setting up python3-psutil (7.1.0-1ubuntu1) ... 171s Setting up liblzf1:amd64 (3.6-4build1) ... 171s Setting up python3-redis (6.4.0-1) ... 171s Setting up python3-pluggy (1.6.0-2) ... 171s Setting up python3.13-minimal (3.13.11-1) ... 172s Setting up libjs-jquery (3.7.1+dfsg+~3.5.33-1build1) ... 172s Setting up libjs-jquery-hotkeys (0.2.0-1) ... 172s Setting up python3.13 (3.13.11-1) ... 173s Setting up python3-pytest (9.0.2-2) ... 173s Setting up python3-all (3.14.2-1) ... 173s Setting up python3-coverage (7.8.2+dfsg1-1ubuntu1) ... 173s Setting up python3-rq (2.6.1-1) ... 174s Setting up redis-tools (5:8.0.5-1) ... 174s Setting up libjs-jquery-metadata (12-4build1) ... 174s Setting up libjs-jquery-isonscreen (1.2.0-2) ... 174s Setting up libjs-jquery-tablesorter (1:2.31.3+dfsg1-5) ... 174s Setting up python3-pytest-cov (5.0.0-1) ... 174s Setting up redis-server (5:8.0.5-1) ... 174s Created symlink '/etc/systemd/system/redis.service' → '/usr/lib/systemd/system/redis-server.service'. 174s Created symlink '/etc/systemd/system/multi-user.target.wants/redis-server.service' → '/usr/lib/systemd/system/redis-server.service'. 174s Processing triggers for systemd (259-1ubuntu2) ... 174s Processing triggers for man-db (2.13.1-1) ... 175s Processing triggers for libc-bin (2.42-2ubuntu4) ... 176s autopkgtest [15:06:38]: test environment configured for cross building 176s autopkgtest [15:06:38]: test smoke: [----------------------- 176s 176s *************************** 176s *** Testing with python3.13 176s *************************** 176s 176s Content of current working folder: 176s 176s total 12 176s drwxr-xr-x 3 ubuntu ubuntu 4096 Jan 23 15:06 . 176s drwxrwxrwx 5 root root 4096 Jan 23 15:06 .. 176s drwxrwxr-x 4 ubuntu ubuntu 4096 Jan 23 15:06 tests 176s Running tests... 176s 176s ============================= test session starts ============================== 176s platform linux -- Python 3.13.11, pytest-9.0.2, pluggy-1.6.0 176s rootdir: /tmp/autopkgtest.swVRpi/autopkgtest_tmp 176s plugins: typeguard-4.4.4, cov-5.0.0 176s collected 536 items / 3 deselected / 533 selected 176s 176s tests/test_callbacks.py ........Traceback (most recent call last): 176s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 176s return_value = job.perform() 176s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 176s self._result = self._execute() 176s ~~~~~~~~~~~~~^^ 176s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 176s result = self.func(*self.args, **self.kwargs) 176s TypeError: div_by_zero() missing 1 required positional argument: 'x' 176s 176s Traceback (most recent call last): 176s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 176s return_value = job.perform() 176s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 176s self._result = self._execute() 176s ~~~~~~~~~~~~~^^ 176s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 176s result = self.func(*self.args, **self.kwargs) 176s TypeError: div_by_zero() missing 1 required positional argument: 'x' 176s 176s ..... 189s tests/test_cli.py ......................................... 193s tests/test_commands.py ..... 193s tests/test_connection.py . 193s tests/test_cron.py ............................ 193s tests/test_cron_job.py ........... 193s tests/test_cron_scheduler_registry.py .... 193s tests/test_decorator.py .............. 194s tests/test_dependencies.py ............. 198s tests/test_executions.py ............ 198s tests/test_fixtures.py .. 204s tests/test_group.py ................ 204s tests/test_helpers.py . 204s tests/test_intermediate_queue.py ........ 209s tests/test_job.py .......................................................... 209s tests/test_job_dependency.py ...# Post enqueue {b'aa31efc2-12ed-4155-ade5-b14dd9bdef9f'} 209s # Post work {b'aa31efc2-12ed-4155-ade5-b14dd9bdef9f'} 209s # Pre cancel {b'aa31efc2-12ed-4155-ade5-b14dd9bdef9f'} 209s #Post cancel set() 215s ....................... 215s tests/test_queue.py ....................................................... 215s tests/test_registry.py ....................................... 215s tests/test_repeat.py ......... 217s tests/test_results.py ............. 217s tests/test_retry.py ........... 227s tests/test_scheduler.py ..................s...... 227s tests/test_serializers.py . 227s tests/test_spawn_worker.py Worker 58d35c13e5e64ea4a33f4f2a80691015: job 84478f9f-9f9c-4027-bd6b-ea266d26c4e4: exception raised while executing (tests.fixtures.div_by_zero) 227s Traceback (most recent call last): 227s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 227s return_value = job.perform() 227s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 227s self._result = self._execute() 227s ~~~~~~~~~~~~~^^ 227s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 227s result = self.func(*self.args, **self.kwargs) 227s TypeError: div_by_zero() missing 1 required positional argument: 'x' 227s 227s .ss 232s tests/test_timeouts.py .. 232s tests/test_utils.py ......................... 252s tests/test_worker.py ......................ss...........s............s.s...................sssss15:07:53 Worker 7739d92f32a840779d0791bd5d580aec: started with PID 2404, version 2.6.1 252s 15:07:53 Worker 7739d92f32a840779d0791bd5d580aec: subscribing to channel rq:pubsub:7739d92f32a840779d0791bd5d580aec 252s 15:07:53 *** Listening on default... 252s 15:07:53 Worker 7739d92f32a840779d0791bd5d580aec: cleaning registries for queue: default 252s 15:07:53 default: tests.fixtures.access_self() (8d556847-3d1e-40a6-8957-7820b1f995b3) 252s 15:07:53 Successfully completed tests.fixtures.access_self() job in 0:00:00.085022s on worker 7739d92f32a840779d0791bd5d580aec 252s 15:07:53 default: Job OK (8d556847-3d1e-40a6-8957-7820b1f995b3) 252s 15:07:53 Result is kept for 500 seconds 252s 15:07:53 Worker 7739d92f32a840779d0791bd5d580aec: done, quitting 252s 15:07:53 Worker 7739d92f32a840779d0791bd5d580aec: unsubscribing from channel rq:pubsub:7739d92f32a840779d0791bd5d580aec 252s .15:07:53 Worker 9fdf57f1507c4f7bab45c3f07b3fdc7f: started with PID 2408, version 2.6.1 252s 15:07:53 Worker 9fdf57f1507c4f7bab45c3f07b3fdc7f: subscribing to channel rq:pubsub:9fdf57f1507c4f7bab45c3f07b3fdc7f 252s 15:07:53 *** Listening on default... 252s 15:07:53 Worker 9fdf57f1507c4f7bab45c3f07b3fdc7f: cleaning registries for queue: default 252s 15:07:53 Worker 9fdf57f1507c4f7bab45c3f07b3fdc7f: done, quitting 252s 15:07:53 Worker 9fdf57f1507c4f7bab45c3f07b3fdc7f: unsubscribing from channel rq:pubsub:9fdf57f1507c4f7bab45c3f07b3fdc7f 252s .15:07:53 Worker cab114e0ee1d4401914002d28a0897b5: started with PID 2411, version 2.6.1 252s 15:07:53 Worker cab114e0ee1d4401914002d28a0897b5: subscribing to channel rq:pubsub:cab114e0ee1d4401914002d28a0897b5 252s 15:07:53 *** Listening on default... 252s 15:07:53 Worker cab114e0ee1d4401914002d28a0897b5: cleaning registries for queue: default 252s 15:07:53 default: tests.test_worker.schedule_access_self() (b904aa1e-6ee0-43a1-ba03-7604a85b849e) 252s 15:07:53 Successfully completed tests.test_worker.schedule_access_self() job in 0:00:00.096337s on worker cab114e0ee1d4401914002d28a0897b5 252s 15:07:53 default: Job OK (b904aa1e-6ee0-43a1-ba03-7604a85b849e) 252s 15:07:53 Result is kept for 500 seconds 252s 15:07:53 Worker cab114e0ee1d4401914002d28a0897b5: done, quitting 252s 15:07:53 Worker cab114e0ee1d4401914002d28a0897b5: unsubscribing from channel rq:pubsub:cab114e0ee1d4401914002d28a0897b5 252s .s..ss... 260s tests/test_worker_pool.py ....... 260s tests/test_worker_registration.py .... 260s 260s =============================== warnings summary =============================== 260s tests/test_group.py:99 260s /tmp/autopkgtest.swVRpi/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 260s @pytest.mark.slow 260s 260s tests/test_group.py:114 260s /tmp/autopkgtest.swVRpi/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 260s @pytest.mark.slow 260s 260s tests/test_group.py:127 260s /tmp/autopkgtest.swVRpi/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 260s @pytest.mark.slow 260s 260s tests/__init__.py:44 260s /tmp/autopkgtest.swVRpi/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 260s f = pytest.mark.ssl_test(f) 260s 260s tests/__init__.py:39 260s /tmp/autopkgtest.swVRpi/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 260s f = pytest.mark.slow(f) 260s 260s tests/test_callbacks.py: 12 warnings 260s tests/test_decorator.py: 1 warning 260s /usr/lib/python3/dist-packages/rq/job.py:313: DeprecationWarning: Passing a string or function for `on_success` is deprecated, pass `Callback` instead 260s warnings.warn( 260s 260s tests/test_callbacks.py: 7 warnings 260s tests/test_decorator.py: 3 warnings 260s tests/test_job.py: 1 warning 260s /usr/lib/python3/dist-packages/rq/job.py:323: DeprecationWarning: Passing a string or function for `on_failure` is deprecated, pass `Callback` instead 260s warnings.warn( 260s 260s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_many_callback 260s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_with_stopped_callback 260s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_with_stopped_callback 260s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_with_stopped_callback 260s tests/test_callbacks.py::SyncJobCallback::test_stopped_callback 260s tests/test_callbacks.py::JobCallbackTestCase::test_job_creation_with_stopped_callback 260s tests/test_job.py::TestJob::test_persistence_of_callbacks 260s /usr/lib/python3/dist-packages/rq/job.py:333: DeprecationWarning: Passing a string or function for `on_stopped` is deprecated, pass `Callback` instead 260s warnings.warn( 260s 260s tests/test_callbacks.py: 2 warnings 260s tests/test_cli.py: 3 warnings 260s tests/test_dependencies.py: 1 warning 260s tests/test_job.py: 6 warnings 260s tests/test_results.py: 1 warning 260s tests/test_worker.py: 13 warnings 260s /usr/lib/python3/dist-packages/rq/job.py:868: DeprecationWarning: job.result is deprecated, use job.return_value instead. 260s warnings.warn('job.result is deprecated, use job.return_value instead.', DeprecationWarning) 260s 260s tests/test_callbacks.py: 2 warnings 260s tests/test_cli.py: 16 warnings 260s tests/test_commands.py: 1 warning 260s tests/test_dependencies.py: 7 warnings 260s tests/test_fixtures.py: 1 warning 260s tests/test_intermediate_queue.py: 1 warning 260s tests/test_job.py: 1 warning 260s tests/test_job_dependency.py: 7 warnings 260s tests/test_queue.py: 2 warnings 260s tests/test_registry.py: 9 warnings 260s tests/test_repeat.py: 3 warnings 260s tests/test_retry.py: 6 warnings 260s tests/test_scheduler.py: 2 warnings 260s tests/test_worker.py: 94 warnings 260s tests/test_worker_pool.py: 1 warning 260s /usr/lib/python3/dist-packages/rq/worker.py:1602: DeprecationWarning: This process (pid=2068) is multi-threaded, use of fork() may lead to deadlocks in the child. 260s child_pid = os.fork() 260s 260s tests/test_callbacks.py::WorkerCallbackTestCase::test_failure_callback 260s tests/test_callbacks.py::WorkerCallbackTestCase::test_failure_callback 260s tests/test_job.py::TestJob::test_compressed_exc_info_handling 260s tests/test_job.py::TestJob::test_compressed_exc_info_handling 260s tests/test_registry.py::TestDeferredRegistry::test_cleanup_moves_jobs_to_failed_job_registry 260s tests/test_results.py::TestResult::test_job_failed_result 260s tests/test_timeouts.py::TestTimeouts::test_timer_death_penalty 260s tests/test_worker.py::TestWorker::test_horse_fails 260s tests/test_worker.py::TestWorker::test_job_timeout_moved_to_failed_job_registry 260s /usr/lib/python3/dist-packages/rq/job.py:816: DeprecationWarning: job.exc_info is deprecated, use job.latest_result() instead. 260s warnings.warn('job.exc_info is deprecated, use job.latest_result() instead.', DeprecationWarning) 260s 260s tests/test_commands.py::TestCommands::test_pubsub_thread_exits_other_error 260s /usr/lib/python3/dist-packages/_pytest/threadexception.py:58: PytestUnhandledThreadExceptionWarning: Exception in thread Thread-34 260s 260s Traceback (most recent call last): 260s File "/usr/lib/python3.13/threading.py", line 1044, in _bootstrap_inner 260s self.run() 260s ~~~~~~~~^^ 260s File "/usr/lib/python3/dist-packages/redis/client.py", line 1269, in run 260s self.exception_handler(e, pubsub, self) 260s ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^ 260s File "/usr/lib/python3/dist-packages/redis/client.py", line 1265, in run 260s pubsub.get_message(ignore_subscribe_messages=True, timeout=sleep_time) 260s ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 260s File "/tmp/autopkgtest.swVRpi/autopkgtest_tmp/tests/fixtures.py", line 51, in raise_exc 260s raise Exception('raise_exc error') 260s Exception: raise_exc error 260s 260s Enable tracemalloc to get traceback where the object was allocated. 260s See https://docs.pytest.org/en/stable/how-to/capture-warnings.html#resource-warnings for more info. 260s warnings.warn(pytest.PytestUnhandledThreadExceptionWarning(msg)) 260s 260s tests/test_commands.py::TestCommands::test_shutdown_command 260s tests/test_commands.py::TestCommands::test_stop_job_command 260s tests/test_scheduler.py::TestWorker::test_work 260s tests/test_scheduler.py::TestWorker::test_work_with_serializer 260s /usr/lib/python3.13/multiprocessing/popen_fork.py:67: DeprecationWarning: This process (pid=2068) is multi-threaded, use of fork() may lead to deadlocks in the child. 260s self.pid = os.fork() 260s 260s tests/test_connection.py::TestConnectionInheritance::test_parse_connection 260s /usr/lib/python3/dist-packages/_pytest/threadexception.py:58: PytestUnhandledThreadExceptionWarning: Exception in thread Thread-35 260s 260s Traceback (most recent call last): 260s File "/usr/lib/python3/dist-packages/redis/_parsers/socket.py", line 68, in _read_from_socket 260s raise ConnectionError(SERVER_CLOSED_CONNECTION_ERROR) 260s redis.exceptions.ConnectionError: Connection closed by server. 260s 260s During handling of the above exception, another exception occurred: 260s 260s Traceback (most recent call last): 260s File "/usr/lib/python3.13/threading.py", line 1044, in _bootstrap_inner 260s self.run() 260s ~~~~~~~~^^ 260s File "/usr/lib/python3/dist-packages/redis/client.py", line 1269, in run 260s self.exception_handler(e, pubsub, self) 260s ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^ 260s File "/usr/lib/python3/dist-packages/redis/client.py", line 1265, in run 260s pubsub.get_message(ignore_subscribe_messages=True, timeout=sleep_time) 260s ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 260s File "/usr/lib/python3/dist-packages/redis/client.py", line 1127, in get_message 260s response = self.parse_response(block=(timeout is None), timeout=timeout) 260s File "/usr/lib/python3/dist-packages/redis/client.py", line 938, in parse_response 260s response = self._execute(conn, try_read) 260s File "/usr/lib/python3/dist-packages/redis/client.py", line 914, in _execute 260s return conn.retry.call_with_retry( 260s ~~~~~~~~~~~~~~~~~~~~~~~~~~^ 260s lambda: command(*args, **kwargs), 260s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 260s lambda _: self._reconnect(conn), 260s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 260s ) 260s ^ 260s File "/usr/lib/python3/dist-packages/redis/retry.py", line 105, in call_with_retry 260s return do() 260s File "/usr/lib/python3/dist-packages/redis/client.py", line 915, in 260s lambda: command(*args, **kwargs), 260s ~~~~~~~^^^^^^^^^^^^^^^^^ 260s File "/usr/lib/python3/dist-packages/redis/client.py", line 932, in try_read 260s if not conn.can_read(timeout=timeout): 260s ~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^ 260s File "/usr/lib/python3/dist-packages/redis/connection.py", line 628, in can_read 260s return self._parser.can_read(timeout) 260s ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 260s File "/usr/lib/python3/dist-packages/redis/_parsers/base.py", line 140, in can_read 260s return self._buffer and self._buffer.can_read(timeout) 260s ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 260s File "/usr/lib/python3/dist-packages/redis/_parsers/socket.py", line 95, in can_read 260s return bool(self.unread_bytes()) or self._read_from_socket( 260s ~~~~~~~~~~~~~~~~~~~~~~^ 260s timeout=timeout, raise_on_timeout=False 260s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 260s ) 260s ^ 260s File "/usr/lib/python3/dist-packages/redis/_parsers/socket.py", line 90, in _read_from_socket 260s buf.seek(current_pos) 260s ~~~~~~~~^^^^^^^^^^^^^ 260s ValueError: I/O operation on closed file. 260s 260s Enable tracemalloc to get traceback where the object was allocated. 260s See https://docs.pytest.org/en/stable/how-to/capture-warnings.html#resource-warnings for more info. 260s warnings.warn(pytest.PytestUnhandledThreadExceptionWarning(msg)) 260s 260s tests/test_registry.py::TestStartedJobRegistry::test_count 260s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got will-be-cleaned-up 260s warnings.warn( 260s 260s tests/test_retry.py::TestRetry::test_cleanup_handles_retries 260s tests/test_retry.py::TestRetry::test_cleanup_handles_retries 260s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got 2d25f242-768c-4211-b15a-df7492583e8a 260s warnings.warn( 260s 260s tests/test_scheduler.py::TestQueue::test_custom_connection_pool 260s /tmp/autopkgtest.swVRpi/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. 260s scheduler_connection = scheduler.connection.connection_pool.get_connection('info') 260s 260s tests/test_scheduler.py::TestQueue::test_no_custom_connection_pool 260s /tmp/autopkgtest.swVRpi/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. 260s scheduler_connection = scheduler.connection.connection_pool.get_connection('info') 260s 260s tests/test_spawn_worker.py::TestWorker::test_work_fails 260s :891: DeprecationWarning: This process (pid=2068) is multi-threaded, use of fork() may lead to deadlocks in the child. 260s 260s tests/test_worker.py::TestWorker::test_clean_queue_registries 260s tests/test_worker.py::TestWorker::test_worker_calls_clean_registries 260s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got foo 260s warnings.warn( 260s 260s tests/test_worker.py::TestWorker::test_clean_queue_registries 260s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got bar 260s warnings.warn( 260s 260s tests/test_worker.py::TestWorker::test_worker_ttl 260s /tmp/autopkgtest.swVRpi/autopkgtest_tmp/tests/test_worker.py:164: DeprecationWarning: default_worker_ttl is deprecated, use worker_ttl. 260s w = Worker([], connection=self.connection, default_worker_ttl=10) 260s 260s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 260s ==== 517 passed, 16 skipped, 3 deselected, 240 warnings in 84.01s (0:01:24) ==== 260s 260s *************************** 260s *** Testing with python3.14 260s *************************** 260s 260s Content of current working folder: 260s 260s total 12 260s drwxr-xr-x 3 ubuntu ubuntu 4096 Jan 23 15:08 . 260s drwxrwxrwx 5 root root 4096 Jan 23 15:06 .. 260s drwxrwxr-x 5 ubuntu ubuntu 4096 Jan 23 15:06 tests 260s Running tests... 260s 261s ============================= test session starts ============================== 261s platform linux -- Python 3.14.2, pytest-9.0.2, pluggy-1.6.0 261s rootdir: /tmp/autopkgtest.swVRpi/autopkgtest_tmp 261s plugins: typeguard-4.4.4, cov-5.0.0 261s collected 536 items / 3 deselected / 533 selected 261s 261s tests/test_callbacks.py ........Traceback (most recent call last): 261s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 261s return_value = job.perform() 261s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 261s self._result = self._execute() 261s ~~~~~~~~~~~~~^^ 261s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 261s result = self.func(*self.args, **self.kwargs) 261s TypeError: div_by_zero() missing 1 required positional argument: 'x' 261s 261s Traceback (most recent call last): 261s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 261s return_value = job.perform() 261s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 261s self._result = self._execute() 261s ~~~~~~~~~~~~~^^ 261s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 261s result = self.func(*self.args, **self.kwargs) 261s TypeError: div_by_zero() missing 1 required positional argument: 'x' 261s 261s ..... 272s tests/test_cli.py .................................15:08:13 Worker 463587b20b164d0c9023bc2e77a34aa8: started with PID 2545, version 2.6.1 272s 15:08:13 Worker 463587b20b164d0c9023bc2e77a34aa8: subscribing to channel rq:pubsub:463587b20b164d0c9023bc2e77a34aa8 272s 15:08:13 *** Listening on default... 272s 15:08:13 Acquired scheduler lock for default 272s 15:08:13 Worker 463587b20b164d0c9023bc2e77a34aa8: cleaning registries for queue: default 272s 15:08:13 Worker 463587b20b164d0c9023bc2e77a34aa8: done, quitting 272s 15:08:13 Worker 463587b20b164d0c9023bc2e77a34aa8: unsubscribing from channel rq:pubsub:463587b20b164d0c9023bc2e77a34aa8 272s .15:08:14 Worker ed0fe1cca1b7442791110a65dddd7d89: started with PID 2547, version 2.6.1 272s 15:08:14 Worker ed0fe1cca1b7442791110a65dddd7d89: subscribing to channel rq:pubsub:ed0fe1cca1b7442791110a65dddd7d89 272s 15:08:14 *** Listening on foo, bar... 272s 15:08:14 Acquired scheduler lock for bar 272s 15:08:14 Acquired scheduler lock for foo 272s 15:08:14 Worker ed0fe1cca1b7442791110a65dddd7d89: cleaning registries for queue: foo 272s 15:08:14 Worker ed0fe1cca1b7442791110a65dddd7d89: cleaning registries for queue: bar 272s 15:08:14 foo: tests.fixtures.say_hello('Hello') (29f9cf5b-eb34-4462-b733-e304804f11b7) 272s 15:08:14 Successfully completed tests.fixtures.say_hello('Hello') job in 0:00:00.073091s on worker ed0fe1cca1b7442791110a65dddd7d89 272s 15:08:14 foo: Job OK (29f9cf5b-eb34-4462-b733-e304804f11b7) 272s 15:08:14 Result is kept for 500 seconds 272s 15:08:14 bar: tests.fixtures.say_hello('Hello') (2eca2a33-64a2-44e5-a47b-02cf505de07d) 272s 15:08:14 Successfully completed tests.fixtures.say_hello('Hello') job in 0:00:00.073231s on worker ed0fe1cca1b7442791110a65dddd7d89 272s 15:08:14 bar: Job OK (2eca2a33-64a2-44e5-a47b-02cf505de07d) 272s 15:08:14 Result is kept for 500 seconds 272s 15:08:14 Worker ed0fe1cca1b7442791110a65dddd7d89: done, quitting 272s 15:08:14 Worker ed0fe1cca1b7442791110a65dddd7d89: unsubscribing from channel rq:pubsub:ed0fe1cca1b7442791110a65dddd7d89 273s .15:08:15 Worker 960562700a4f4c908f5cebe731078a4e: started with PID 2551, version 2.6.1 273s 15:08:15 Worker 960562700a4f4c908f5cebe731078a4e: subscribing to channel rq:pubsub:960562700a4f4c908f5cebe731078a4e 273s 15:08:15 *** Listening on default... 273s 15:08:15 Acquired scheduler lock for default 273s 15:08:15 Worker 960562700a4f4c908f5cebe731078a4e: cleaning registries for queue: default 273s 15:08:15 Worker 960562700a4f4c908f5cebe731078a4e: done, quitting 273s 15:08:15 Worker 960562700a4f4c908f5cebe731078a4e: unsubscribing from channel rq:pubsub:960562700a4f4c908f5cebe731078a4e 273s 15:08:15 Worker 3021cfd9fb004d43b1b8e2e937806c84: started with PID 2553, version 2.6.1 273s 15:08:15 Worker 3021cfd9fb004d43b1b8e2e937806c84: subscribing to channel rq:pubsub:3021cfd9fb004d43b1b8e2e937806c84 273s 15:08:15 *** Listening on default... 273s 15:08:15 Acquired scheduler lock for default 273s 15:08:15 Worker 3021cfd9fb004d43b1b8e2e937806c84: cleaning registries for queue: default 273s 15:08:15 Worker 3021cfd9fb004d43b1b8e2e937806c84: done, quitting 273s 15:08:15 Worker 3021cfd9fb004d43b1b8e2e937806c84: unsubscribing from channel rq:pubsub:3021cfd9fb004d43b1b8e2e937806c84 274s .15:08:15 Worker 496a7390f7ce404eb8437461fb290be5: started with PID 2555, version 2.6.1 274s 15:08:15 Worker 496a7390f7ce404eb8437461fb290be5: subscribing to channel rq:pubsub:496a7390f7ce404eb8437461fb290be5 274s 15:08:15 *** Listening on default... 274s 15:08:15 Acquired scheduler lock for default 274s 15:08:15 Worker 496a7390f7ce404eb8437461fb290be5: cleaning registries for queue: default 274s 15:08:15 Worker 496a7390f7ce404eb8437461fb290be5: done, quitting 274s 15:08:15 Worker 496a7390f7ce404eb8437461fb290be5: unsubscribing from channel rq:pubsub:496a7390f7ce404eb8437461fb290be5 274s 15:08:15 Worker 1a62b9ab057044eb825384329d4cfb08: started with PID 2556, version 2.6.1 274s 15:08:15 Worker 1a62b9ab057044eb825384329d4cfb08: subscribing to channel rq:pubsub:1a62b9ab057044eb825384329d4cfb08 274s 15:08:15 *** Listening on default... 274s 15:08:15 Acquired scheduler lock for default 274s 15:08:15 Worker 05c730aa80e6456192200632adf4a4e3: started with PID 2557, version 2.6.1 274s 15:08:15 Worker 05c730aa80e6456192200632adf4a4e3: subscribing to channel rq:pubsub:05c730aa80e6456192200632adf4a4e3 274s 15:08:15 Worker 1a62b9ab057044eb825384329d4cfb08: cleaning registries for queue: default 274s 15:08:15 *** Listening on default... 274s 15:08:15 Acquired scheduler lock for default 274s 15:08:15 Worker 05c730aa80e6456192200632adf4a4e3: cleaning registries for queue: default 274s 15:08:15 Worker 1a62b9ab057044eb825384329d4cfb08: done, quitting 274s 15:08:15 Worker 1a62b9ab057044eb825384329d4cfb08: unsubscribing from channel rq:pubsub:1a62b9ab057044eb825384329d4cfb08 274s 15:08:15 Worker 05c730aa80e6456192200632adf4a4e3: done, quitting 274s 15:08:15 Worker 05c730aa80e6456192200632adf4a4e3: unsubscribing from channel rq:pubsub:05c730aa80e6456192200632adf4a4e3 275s .15:08:16 Worker 0cb60d1859634f1b892b8703688744d8: registering birth 275s 15:08:16 Worker 0cb60d1859634f1b892b8703688744d8: started with PID 2561, version 2.6.1 275s 15:08:16 Worker 0cb60d1859634f1b892b8703688744d8: subscribing to channel rq:pubsub:0cb60d1859634f1b892b8703688744d8 275s 15:08:16 *** Listening on default... 275s 15:08:16 Acquiring scheduler lock for default 275s 15:08:16 Acquired scheduler lock for default 275s 15:08:16 Worker 0cb60d1859634f1b892b8703688744d8: sent heartbeat to prevent worker timeout. Next one should arrive in 480 seconds. 275s 15:08:16 Worker 0cb60d1859634f1b892b8703688744d8: cleaning registries for queue: default 275s 15:08:16 Worker 0cb60d1859634f1b892b8703688744d8: *** Listening on default... 275s 15:08:16 Worker 0cb60d1859634f1b892b8703688744d8: sent heartbeat to prevent worker timeout. Next one should arrive in 480 seconds. 275s 15:08:16 Worker 0cb60d1859634f1b892b8703688744d8: dequeueing jobs on queues default and timeout None 275s 15:08:16 Worker 0cb60d1859634f1b892b8703688744d8: sent heartbeat to prevent worker timeout. Next one should arrive in 480 seconds. 275s 15:08:16 Worker 0cb60d1859634f1b892b8703688744d8: done, quitting 275s 15:08:16 Worker 0cb60d1859634f1b892b8703688744d8: registering death 275s 15:08:16 Worker 0cb60d1859634f1b892b8703688744d8: unsubscribing from channel rq:pubsub:0cb60d1859634f1b892b8703688744d8 275s .... 275s tests/test_commands.py 15:08:17 Worker df57d6d17da14409b52db1cb3df945f7: started with PID 2568, version 2.6.1 275s 15:08:17 Worker df57d6d17da14409b52db1cb3df945f7: subscribing to channel rq:pubsub:df57d6d17da14409b52db1cb3df945f7 275s 15:08:17 *** Listening on foo... 275s 15:08:17 Worker df57d6d17da14409b52db1cb3df945f7: cleaning registries for queue: foo 277s 15:08:18 Received kill horse command. 277s 15:08:18 Worker is not working, kill horse command ignored 277s 15:08:18 Received shutdown command, sending SIGINT signal. 277s 15:08:18 Worker df57d6d17da14409b52db1cb3df945f7 [PID 2568]: warm shut down requested 277s .15:08:18 Worker df57d6d17da14409b52db1cb3df945f7: unsubscribing from channel rq:pubsub:df57d6d17da14409b52db1cb3df945f7 278s ...15:08:19 Worker 224b9210eb724909bc8791365d5571e5: started with PID 2574, version 2.6.1 278s 15:08:19 Worker 224b9210eb724909bc8791365d5571e5: subscribing to channel rq:pubsub:224b9210eb724909bc8791365d5571e5 278s 15:08:19 *** Listening on foo... 278s 15:08:19 Worker 224b9210eb724909bc8791365d5571e5: cleaning registries for queue: foo 278s 15:08:19 foo: tests.fixtures.long_running_job(3) (5da2d118-8176-473e-9a1b-7b84f8f4f422) 279s 15:08:20 Not working on job 1, command ignored. 279s 15:08:20 Worker 224b9210eb724909bc8791365d5571e5: killed horse pid 2576 279s 15:08:20 Worker 224b9210eb724909bc8791365d5571e5: job 5da2d118-8176-473e-9a1b-7b84f8f4f422 stopped by user, moving job to FailedJobRegistry 279s 15:08:20 Worker 224b9210eb724909bc8791365d5571e5: done, quitting 279s 15:08:20 Worker 224b9210eb724909bc8791365d5571e5: unsubscribing from channel rq:pubsub:224b9210eb724909bc8791365d5571e5 279s . 279s tests/test_connection.py . 279s tests/test_cron.py ........................15:08:21 Registered 'tests.fixtures.do_nothing' to run on default every 1 seconds 279s 15:08:21 CronScheduler autopkgtest:2577:c19e4f: starting... 279s 15:08:21 CronScheduler autopkgtest:2577:c19e4f: registering birth... 279s 15:08:21 Enqueued job do_nothing to queue default 279s 15:08:21 CronScheduler autopkgtest:2577:c19e4f: received shutdown signal 2 279s 15:08:21 CronScheduler autopkgtest:2577:c19e4f: stop requested 279s 15:08:21 CronScheduler autopkgtest:2577:c19e4f: registering death... 279s 15:08:21 CronScheduler autopkgtest:2577:c19e4f: shutdown complete 279s .... 279s tests/test_cron_job.py ........... 279s tests/test_cron_scheduler_registry.py .... 279s tests/test_decorator.py .............. 281s tests/test_dependencies.py ............. 281s tests/test_executions.py ...15:08:22 Worker w1: started with PID 2608, version 2.6.1 281s 15:08:22 Worker w1: subscribing to channel rq:pubsub:w1 281s 15:08:22 *** Listening on default... 281s 15:08:22 Worker w1: cleaning registries for queue: default 281s 15:08:22 default: tests.fixtures.long_running_job(timeout=3) (3f2b642b-cfde-4d27-8291-59c93aa61345) 284s 15:08:25 Successfully completed tests.fixtures.long_running_job(timeout=3) job in 0:00:03.000434s on worker w1 284s 15:08:25 default: Job OK (3f2b642b-cfde-4d27-8291-59c93aa61345) 284s 15:08:25 Result is kept for 500 seconds 284s 15:08:25 Worker w1: done, quitting 284s 15:08:25 Worker w1: unsubscribing from channel rq:pubsub:w1 284s ......... 284s tests/test_fixtures.py .. 290s tests/test_group.py ................ 290s tests/test_helpers.py . 290s tests/test_intermediate_queue.py ........ 290s tests/test_job.py 15:08:31 Worker 7362229ce71141ab9052de6015b2bd45: started with PID 2622, version 2.6.1 290s 15:08:31 Worker 7362229ce71141ab9052de6015b2bd45: subscribing to channel rq:pubsub:7362229ce71141ab9052de6015b2bd45 290s 15:08:31 *** Listening on test_blocking_queue... 290s 15:08:31 Worker 7362229ce71141ab9052de6015b2bd45: cleaning registries for queue: test_blocking_queue 290s 15:08:31 test_blocking_queue: tests.fixtures.long_running_job(2) (102cae9b-c180-4ac9-bcea-70b0e1dc16ab) 292s 15:08:33 Successfully completed tests.fixtures.long_running_job(2) job in 0:00:02.000747s on worker 7362229ce71141ab9052de6015b2bd45 292s 15:08:33 test_blocking_queue: Job OK (102cae9b-c180-4ac9-bcea-70b0e1dc16ab) 292s 15:08:33 Result is kept for 500 seconds 292s ...15:08:34 Worker 7362229ce71141ab9052de6015b2bd45: done, quitting 292s .15:08:34 Worker 7362229ce71141ab9052de6015b2bd45: unsubscribing from channel rq:pubsub:7362229ce71141ab9052de6015b2bd45 295s ...................................................... 295s tests/test_job_dependency.py ...# Post enqueue {b'6be96560-7f5c-47c8-be18-ff717efb07a3'} 295s # Post work {b'6be96560-7f5c-47c8-be18-ff717efb07a3'} 295s # Pre cancel {b'6be96560-7f5c-47c8-be18-ff717efb07a3'} 295s #Post cancel set() 296s ............15:08:37 Worker w1: started with PID 2629, version 2.6.1 296s 15:08:37 Worker w1: subscribing to channel rq:pubsub:w1 296s 15:08:37 *** Listening on default... 296s 15:08:37 Worker w1: cleaning registries for queue: default 296s 15:08:37 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) 296s 15:08:37 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.501913s on worker w1 296s 15:08:37 default: Job OK (slow_1) 296s 15:08:37 Result is kept for 500 seconds 296s 15:08:37 default: tests.fixtures.rpush('test_job:job_order', 'A', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True) (46642fee-3cc3-407c-8bb5-07e69c458f71) 296s 15:08:37 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.000941s on worker w1 296s 15:08:37 default: Job OK (46642fee-3cc3-407c-8bb5-07e69c458f71) 296s 15:08:37 Result is kept for 500 seconds 296s 15:08:37 default: tests.fixtures.rpush('test_job:job_order', 'B', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True) (c60bfb0e-b669-4169-b823-7d73086851c5) 296s 15:08:37 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.001025s on worker w1 296s 15:08:37 default: Job OK (c60bfb0e-b669-4169-b823-7d73086851c5) 296s 15:08:37 Result is kept for 500 seconds 296s 15:08:37 Worker w1: done, quitting 296s 15:08:37 Worker w1: unsubscribing from channel rq:pubsub:w1 298s 15:08:39 Worker w1: started with PID 2637, version 2.6.1 298s 15:08:39 Worker w1: subscribing to channel rq:pubsub:w1 298s 15:08:39 *** Listening on default... 298s 15:08:39 Worker w1: cleaning registries for queue: default 298s 15:08:39 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) 298s 15:08:39 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.501400s on worker w1 298s 15:08:39 default: Job OK (slow_1) 298s 15:08:39 Result is kept for 500 seconds 298s 15:08:39 default: tests.fixtures.rpush('test_job:job_order', 'B', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True) (e576e2be-2157-4d8c-ac8d-b6b80bf74191) 298s 15:08:39 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.000828s on worker w1 298s 15:08:39 default: Job OK (e576e2be-2157-4d8c-ac8d-b6b80bf74191) 298s 15:08:39 Result is kept for 500 seconds 298s 15:08:39 Worker w1: done, quitting 298s 15:08:39 Worker w1: unsubscribing from channel rq:pubsub:w1 300s .15:08:41 Worker w1: started with PID 2644, version 2.6.1 300s 15:08:41 Worker w1: subscribing to channel rq:pubsub:w1 300s 15:08:41 *** Listening on default... 300s 15:08:41 Worker w1: cleaning registries for queue: default 300s 15:08:41 default: tests.fixtures.rpush('test_job:job_order', 'slow', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True, 0.5) (slow_job) 300s 15:08:41 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.501419s on worker w1 300s 15:08:41 default: Job OK (slow_job) 300s 15:08:41 Result is kept for 500 seconds 300s 15:08:41 Worker w1: done, quitting 300s 15:08:41 Worker w1: unsubscribing from channel rq:pubsub:w1 301s 15:08:42 Worker w1: started with PID 2650, version 2.6.1 301s 15:08:42 Worker w1: subscribing to channel rq:pubsub:w1 301s 15:08:42 *** Listening on default... 301s 15:08:42 Worker w1: cleaning registries for queue: default 301s 15:08:42 default: tests.fixtures.rpush('test_job:job_order', 'slow', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True, 0.5) (slow_job) 301s 15:08:43 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.501432s on worker w1 301s 15:08:43 default: Job OK (slow_job) 301s 15:08:43 Result is kept for 500 seconds 301s 15:08:43 default: tests.fixtures.rpush('test_job:job_order', 'A', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., False) (fb2945c0-fbaf-4b11-b61d-b4cca23695a1) 301s 15:08:43 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.000675s on worker w1 301s 15:08:43 default: Job OK (fb2945c0-fbaf-4b11-b61d-b4cca23695a1) 301s 15:08:43 Result is kept for 500 seconds 301s 15:08:43 Worker w1: done, quitting 301s 15:08:43 Worker w1: unsubscribing from channel rq:pubsub:w1 302s .......... 302s tests/test_queue.py ....................................................... 303s tests/test_registry.py ....................................... 303s tests/test_repeat.py ......... 304s tests/test_results.py ............. 304s tests/test_retry.py ........... 316s tests/test_scheduler.py ..................s...... 316s tests/test_serializers.py . 316s tests/test_spawn_worker.py Worker 8ecfb0b210d444a9ba47e53bcddecb3f: job 6c6a4bfe-394f-45f1-b1b1-f30438914703: exception raised while executing (tests.fixtures.div_by_zero) 316s Traceback (most recent call last): 316s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 316s return_value = job.perform() 316s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 316s self._result = self._execute() 316s ~~~~~~~~~~~~~^^ 316s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 316s result = self.func(*self.args, **self.kwargs) 316s TypeError: div_by_zero() missing 1 required positional argument: 'x' 316s 316s .ss 321s tests/test_timeouts.py .. 321s tests/test_utils.py ......................... 341s tests/test_worker.py ......................ss...........s............s.s...................sssss15:09:22 Worker 9572368008c7426a9e7716caac9bb5c7: started with PID 2817, version 2.6.1 341s 15:09:22 Worker 9572368008c7426a9e7716caac9bb5c7: subscribing to channel rq:pubsub:9572368008c7426a9e7716caac9bb5c7 341s 15:09:22 *** Listening on default... 341s 15:09:22 Worker 9572368008c7426a9e7716caac9bb5c7: cleaning registries for queue: default 341s 15:09:22 default: tests.fixtures.access_self() (093b3976-5be0-493e-94b2-28eea95ad57d) 341s 15:09:22 Successfully completed tests.fixtures.access_self() job in 0:00:00.077640s on worker 9572368008c7426a9e7716caac9bb5c7 341s 15:09:22 default: Job OK (093b3976-5be0-493e-94b2-28eea95ad57d) 341s 15:09:22 Result is kept for 500 seconds 341s 15:09:22 Worker 9572368008c7426a9e7716caac9bb5c7: done, quitting 341s 15:09:22 Worker 9572368008c7426a9e7716caac9bb5c7: unsubscribing from channel rq:pubsub:9572368008c7426a9e7716caac9bb5c7 341s .15:09:22 Worker 6fa6d5cd416d49a6b8f86af1966fc3f8: started with PID 2821, version 2.6.1 341s 15:09:22 Worker 6fa6d5cd416d49a6b8f86af1966fc3f8: subscribing to channel rq:pubsub:6fa6d5cd416d49a6b8f86af1966fc3f8 341s 15:09:22 *** Listening on default... 341s 15:09:22 Worker 6fa6d5cd416d49a6b8f86af1966fc3f8: cleaning registries for queue: default 341s 15:09:22 Worker 6fa6d5cd416d49a6b8f86af1966fc3f8: done, quitting 341s 15:09:22 Worker 6fa6d5cd416d49a6b8f86af1966fc3f8: unsubscribing from channel rq:pubsub:6fa6d5cd416d49a6b8f86af1966fc3f8 341s .15:09:23 Worker 6471660a993c4457ba8c7d223dae257a: started with PID 2824, version 2.6.1 341s 15:09:23 Worker 6471660a993c4457ba8c7d223dae257a: subscribing to channel rq:pubsub:6471660a993c4457ba8c7d223dae257a 341s 15:09:23 *** Listening on default... 341s 15:09:23 Worker 6471660a993c4457ba8c7d223dae257a: cleaning registries for queue: default 341s 15:09:23 default: tests.test_worker.schedule_access_self() (d2e8e645-90f4-45a4-bde2-cf73ad6dd3aa) 341s 15:09:23 Successfully completed tests.test_worker.schedule_access_self() job in 0:00:00.097757s on worker 6471660a993c4457ba8c7d223dae257a 341s 15:09:23 default: Job OK (d2e8e645-90f4-45a4-bde2-cf73ad6dd3aa) 341s 15:09:23 Result is kept for 500 seconds 341s 15:09:23 Worker 6471660a993c4457ba8c7d223dae257a: done, quitting 341s 15:09:23 Worker 6471660a993c4457ba8c7d223dae257a: unsubscribing from channel rq:pubsub:6471660a993c4457ba8c7d223dae257a 343s .sReal-time signal 0 343s autopkgtest [15:09:25]: test smoke: -----------------------] 344s autopkgtest [15:09:25]: test smoke: - - - - - - - - - - results - - - - - - - - - - 344s smoke FAIL non-zero exit status 162 344s autopkgtest [15:09:26]: test autodep8-python3: preparing testbed 367s Creating nova instance adt-resolute-i386-python-rq-20260123-150342-juju-7f2275-prod-proposed-migration-environment-20-a3476ab3-80a1-498f-804d-3dd57117bd69 from image adt/ubuntu-resolute-amd64-server-20260123.img (UUID 789dbc23-4f1b-4e7c-bcab-98e6eebf04f2)... 445s autopkgtest [15:11:07]: testbed dpkg architecture: amd64 445s autopkgtest [15:11:07]: testbed apt version: 3.1.13 446s autopkgtest [15:11:08]: @@@@@@@@@@@@@@@@@@@@ test bed setup 446s autopkgtest [15:11:08]: testbed release detected to be: resolute 446s autopkgtest [15:11:08]: updating testbed package index (apt update) 447s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 447s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 447s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 447s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 447s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [23.6 kB] 447s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [95.0 kB] 447s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 447s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1019 kB] 447s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [107 kB] 447s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [129 kB] 447s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [3656 B] 447s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [3696 B] 447s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [12.3 kB] 447s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [336 B] 447s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [913 kB] 447s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [401 kB] 447s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [23.5 kB] 447s Get:18 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [11.6 kB] 447s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [4704 B] 447s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [668 B] 448s Fetched 2876 kB in 1s (2947 kB/s) 448s Reading package lists... 449s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 449s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 449s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 449s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 450s Reading package lists... 450s Reading package lists... 450s Building dependency tree... 450s Reading state information... 450s Calculating upgrade... 450s The following packages were automatically installed and are no longer required: 450s python3.13 python3.13-minimal 450s Use 'sudo apt autoremove' to remove them. 450s The following NEW packages will be installed: 450s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 450s The following packages will be upgraded: 450s libplymouth5 libpython3-stdlib libtasn1-6 plymouth 450s plymouth-theme-ubuntu-text python3 python3-gdbm python3-jaraco.context 450s python3-minimal 450s 9 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 450s Need to get 7110 kB of archives. 450s After this operation, 24.5 MB of additional disk space will be used. 450s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-minimal amd64 3.14.2-1 [920 kB] 451s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14-minimal amd64 3.14.2-1 [2563 kB] 451s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-minimal amd64 3.14.2-1 [28.2 kB] 451s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3 amd64 3.14.2-1 [22.9 kB] 451s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 libpython3.14-stdlib amd64 3.14.2-1 [2398 kB] 451s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.14 amd64 3.14.2-1 [816 kB] 451s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 libpython3-stdlib amd64 3.14.2-1 [10.9 kB] 451s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 libtasn1-6 amd64 4.21.0-2 [45.3 kB] 451s Get:9 http://ftpmaster.internal/ubuntu resolute/main amd64 libplymouth5 amd64 24.004.60+git20250831.4a3c171d-0ubuntu5 [143 kB] 451s Get:10 http://ftpmaster.internal/ubuntu resolute/main amd64 plymouth-theme-ubuntu-text amd64 24.004.60+git20250831.4a3c171d-0ubuntu5 [10.2 kB] 451s Get:11 http://ftpmaster.internal/ubuntu resolute/main amd64 plymouth amd64 24.004.60+git20250831.4a3c171d-0ubuntu5 [135 kB] 451s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-gdbm amd64 3.14.2-1 [11.1 kB] 451s Get:13 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-jaraco.context all 6.0.1-2 [8198 B] 452s dpkg-preconfigure: unable to re-open stdin: No such file or directory 452s Fetched 7110 kB in 1s (6346 kB/s) 452s Selecting previously unselected package libpython3.14-minimal:amd64. 452s (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 ... 124176 files and directories currently installed.) 452s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_amd64.deb ... 452s Unpacking libpython3.14-minimal:amd64 (3.14.2-1) ... 452s Selecting previously unselected package python3.14-minimal. 452s Preparing to unpack .../python3.14-minimal_3.14.2-1_amd64.deb ... 452s Unpacking python3.14-minimal (3.14.2-1) ... 452s Setting up libpython3.14-minimal:amd64 (3.14.2-1) ... 452s Setting up python3.14-minimal (3.14.2-1) ... 453s (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 ... 124518 files and directories currently installed.) 453s Preparing to unpack .../python3-minimal_3.14.2-1_amd64.deb ... 453s Unpacking python3-minimal (3.14.2-1) over (3.13.9-3) ... 453s Setting up python3-minimal (3.14.2-1) ... 453s (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 ... 124518 files and directories currently installed.) 453s Preparing to unpack .../0-python3_3.14.2-1_amd64.deb ... 453s running python pre-rtupdate hooks for python3.14... 453s Unpacking python3 (3.14.2-1) over (3.13.9-3) ... 453s Selecting previously unselected package libpython3.14-stdlib:amd64. 453s Preparing to unpack .../1-libpython3.14-stdlib_3.14.2-1_amd64.deb ... 453s Unpacking libpython3.14-stdlib:amd64 (3.14.2-1) ... 453s Selecting previously unselected package python3.14. 453s Preparing to unpack .../2-python3.14_3.14.2-1_amd64.deb ... 453s Unpacking python3.14 (3.14.2-1) ... 453s Preparing to unpack .../3-libpython3-stdlib_3.14.2-1_amd64.deb ... 453s Unpacking libpython3-stdlib:amd64 (3.14.2-1) over (3.13.9-3) ... 453s Preparing to unpack .../4-libtasn1-6_4.21.0-2_amd64.deb ... 453s Unpacking libtasn1-6:amd64 (4.21.0-2) over (4.20.0-2ubuntu1) ... 453s Preparing to unpack .../5-libplymouth5_24.004.60+git20250831.4a3c171d-0ubuntu5_amd64.deb ... 453s Unpacking libplymouth5:amd64 (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 453s Preparing to unpack .../6-plymouth-theme-ubuntu-text_24.004.60+git20250831.4a3c171d-0ubuntu5_amd64.deb ... 453s Unpacking plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 453s Preparing to unpack .../7-plymouth_24.004.60+git20250831.4a3c171d-0ubuntu5_amd64.deb ... 453s Unpacking plymouth (24.004.60+git20250831.4a3c171d-0ubuntu5) over (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 453s Preparing to unpack .../8-python3-gdbm_3.14.2-1_amd64.deb ... 453s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-3) ... 453s Preparing to unpack .../9-python3-jaraco.context_6.0.1-2_all.deb ... 453s Unpacking python3-jaraco.context (6.0.1-2) over (6.0.1-1build1) ... 453s Setting up libpython3.14-stdlib:amd64 (3.14.2-1) ... 453s Setting up libplymouth5:amd64 (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 453s Setting up libtasn1-6:amd64 (4.21.0-2) ... 453s Setting up python3.14 (3.14.2-1) ... 454s Setting up libpython3-stdlib:amd64 (3.14.2-1) ... 454s Setting up python3-gdbm (3.14.2-1) ... 454s Setting up plymouth (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 454s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 454s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 454s Setting up python3 (3.14.2-1) ... 454s running python rtupdate hooks for python3.14... 454s running python post-rtupdate hooks for python3.14... 454s Setting up python3-jaraco.context (6.0.1-2) ... 455s Processing triggers for libc-bin (2.42-2ubuntu4) ... 455s Processing triggers for systemd (259-1ubuntu2) ... 455s Processing triggers for man-db (2.13.1-1) ... 455s Processing triggers for initramfs-tools (0.150ubuntu7) ... 455s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 460s Setting up plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu5) ... 460s Processing triggers for initramfs-tools (0.150ubuntu7) ... 460s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 464s autopkgtest [15:11:26]: upgrading testbed (apt dist-upgrade and autopurge) 464s Reading package lists... 464s Building dependency tree... 464s Reading state information... 464s Calculating upgrade... 464s The following packages were automatically installed and are no longer required: 464s python3.13 python3.13-minimal 464s Use 'sudo apt autoremove' to remove them. 464s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 465s Reading package lists... 465s Building dependency tree... 465s Reading state information... 465s Solving dependencies... 465s The following packages will be REMOVED: 465s python3.13* python3.13-minimal* 465s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 465s After this operation, 7860 kB disk space will be freed. 465s (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 ... 124945 files and directories currently installed.) 465s Removing python3.13 (3.13.11-1) ... 465s Removing python3.13-minimal (3.13.11-1) ... 466s Processing triggers for man-db (2.13.1-1) ... 466s Processing triggers for systemd (259-1ubuntu2) ... 466s (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 ... 124918 files and directories currently installed.) 466s Purging configuration files for python3.13-minimal (3.13.11-1) ... 466s autopkgtest [15:11:28]: rebooting testbed after setup commands that affected boot 530s Reading package lists... 531s Building dependency tree... 531s Reading state information... 531s Solving dependencies... 531s The following NEW packages will be installed: 531s python3-all python3-click python3-croniter python3-redis python3-rq 531s python3.13 python3.13-minimal 531s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 531s Need to get 3583 kB of archives. 531s After this operation, 10.3 MB of additional disk space will be used. 531s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.13-minimal amd64 3.13.11-1 [2408 kB] 531s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 python3.13 amd64 3.13.11-1 [758 kB] 531s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 python3-all amd64 3.14.2-1 [890 B] 531s Get:4 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-click all 8.2.0+0.really.8.1.8-1build1 [80.2 kB] 531s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 python3-croniter all 6.1~rc1-0ubuntu1 [21.7 kB] 531s Get:6 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-redis all 6.4.0-1 [228 kB] 531s Get:7 http://ftpmaster.internal/ubuntu resolute/universe amd64 python3-rq all 2.6.1-1 [86.3 kB] 531s Fetched 3583 kB in 0s (7920 kB/s) 531s Selecting previously unselected package python3.13-minimal. 532s (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 ... 124920 files and directories currently installed.) 532s Preparing to unpack .../0-python3.13-minimal_3.13.11-1_amd64.deb ... 532s Unpacking python3.13-minimal (3.13.11-1) ... 532s Selecting previously unselected package python3.13. 532s Preparing to unpack .../1-python3.13_3.13.11-1_amd64.deb ... 532s Unpacking python3.13 (3.13.11-1) ... 532s Selecting previously unselected package python3-all. 532s Preparing to unpack .../2-python3-all_3.14.2-1_amd64.deb ... 532s Unpacking python3-all (3.14.2-1) ... 532s Selecting previously unselected package python3-click. 532s Preparing to unpack .../3-python3-click_8.2.0+0.really.8.1.8-1build1_all.deb ... 532s Unpacking python3-click (8.2.0+0.really.8.1.8-1build1) ... 532s Selecting previously unselected package python3-croniter. 532s Preparing to unpack .../4-python3-croniter_6.1~rc1-0ubuntu1_all.deb ... 532s Unpacking python3-croniter (6.1~rc1-0ubuntu1) ... 532s Selecting previously unselected package python3-redis. 532s Preparing to unpack .../5-python3-redis_6.4.0-1_all.deb ... 532s Unpacking python3-redis (6.4.0-1) ... 532s Selecting previously unselected package python3-rq. 532s Preparing to unpack .../6-python3-rq_2.6.1-1_all.deb ... 532s Unpacking python3-rq (2.6.1-1) ... 532s Setting up python3-croniter (6.1~rc1-0ubuntu1) ... 532s Setting up python3-click (8.2.0+0.really.8.1.8-1build1) ... 532s Setting up python3-redis (6.4.0-1) ... 532s Setting up python3.13-minimal (3.13.11-1) ... 533s Setting up python3.13 (3.13.11-1) ... 534s Setting up python3-all (3.14.2-1) ... 534s Setting up python3-rq (2.6.1-1) ... 534s Processing triggers for man-db (2.13.1-1) ... 534s Processing triggers for systemd (259-1ubuntu2) ... 536s autopkgtest [15:12:38]: test environment configured for cross building 536s autopkgtest [15:12:38]: 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 536s autopkgtest [15:12:38]: test autodep8-python3: [----------------------- 536s Testing with python3.13: 536s 536s Testing with python3.14: 536s 536s autopkgtest [15:12:38]: test autodep8-python3: -----------------------] 537s autodep8-python3 PASS (superficial) 537s autopkgtest [15:12:39]: test autodep8-python3: - - - - - - - - - - results - - - - - - - - - - 537s autopkgtest [15:12:39]: @@@@@@@@@@@@@@@@@@@@ summary 537s smoke FAIL non-zero exit status 162 537s autodep8-python3 PASS (superficial)