0s autopkgtest [03:04:12]: starting date and time: 2026-01-23 03:04:12+0000 0s autopkgtest [03:04:12]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [03:04:12]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.qc8v2gai/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-arm64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@sto01-arm64-2.secgroup --name adt-resolute-arm64-python-rq-20260123-030411-juju-7f2275-prod-proposed-migration-environment-20-cc31e4c9-88f4-4602-ac97-22f6f1c381c6 --image adt/ubuntu-resolute-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-autopkgtest-workers-arm64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 6s Creating nova instance adt-resolute-arm64-python-rq-20260123-030411-juju-7f2275-prod-proposed-migration-environment-20-cc31e4c9-88f4-4602-ac97-22f6f1c381c6 from image adt/ubuntu-resolute-arm64-server-20260122.img (UUID ed27afc7-5f46-4e8b-ad73-864280a0ddbb)... 63s autopkgtest [03:05:15]: testbed dpkg architecture: arm64 63s autopkgtest [03:05:15]: testbed apt version: 3.1.12 63s autopkgtest [03:05:15]: @@@@@@@@@@@@@@@@@@@@ test bed setup 63s autopkgtest [03:05:15]: testbed release detected to be: None 64s autopkgtest [03:05:16]: updating testbed package index (apt update) 65s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 65s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 65s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 65s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 65s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1020 kB] 65s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [23.5 kB] 65s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 65s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [87.5 kB] 65s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 Packages [105 kB] 65s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 c-n-f Metadata [3424 B] 65s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 Packages [7876 B] 65s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 c-n-f Metadata [304 B] 65s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 Packages [757 kB] 65s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 c-n-f Metadata [18.1 kB] 65s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 Packages [7156 B] 66s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 c-n-f Metadata [332 B] 68s Fetched 2157 kB in 1s (1922 kB/s) 69s Reading package lists... 70s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 70s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 70s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 70s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 71s Reading package lists... 71s Reading package lists... 71s Building dependency tree... 71s Reading state information... 71s Calculating upgrade... 72s The following packages were automatically installed and are no longer required: 72s python3.13 python3.13-minimal 72s Use 'sudo apt autoremove' to remove them. 72s The following NEW packages will be installed: 72s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 72s The following packages will be upgraded: 72s apt libapt-pkg7.0 libplymouth5 libpython3-stdlib plymouth 72s plymouth-theme-ubuntu-text python3 python3-gdbm python3-minimal 72s 9 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 72s Need to get 9472 kB of archives. 72s After this operation, 26.0 MB of additional disk space will be used. 72s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 libpython3.14-minimal arm64 3.14.2-1 [917 kB] 72s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 python3.14-minimal arm64 3.14.2-1 [2548 kB] 72s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 python3-gdbm arm64 3.14.2-1 [11.1 kB] 72s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 python3-minimal arm64 3.14.2-1 [28.2 kB] 72s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 python3 arm64 3.14.2-1 [22.9 kB] 72s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 libpython3.14-stdlib arm64 3.14.2-1 [2351 kB] 72s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 python3.14 arm64 3.14.2-1 [816 kB] 72s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libpython3-stdlib arm64 3.14.2-1 [10.9 kB] 72s Get:9 http://ftpmaster.internal/ubuntu resolute/main arm64 libapt-pkg7.0 arm64 3.1.13 [1067 kB] 72s Get:10 http://ftpmaster.internal/ubuntu resolute/main arm64 apt arm64 3.1.13 [1412 kB] 72s Get:11 http://ftpmaster.internal/ubuntu resolute/main arm64 libplymouth5 arm64 24.004.60+git20250831.4a3c171d-0ubuntu4 [141 kB] 72s Get:12 http://ftpmaster.internal/ubuntu resolute/main arm64 plymouth-theme-ubuntu-text arm64 24.004.60+git20250831.4a3c171d-0ubuntu4 [10.0 kB] 72s Get:13 http://ftpmaster.internal/ubuntu resolute/main arm64 plymouth arm64 24.004.60+git20250831.4a3c171d-0ubuntu4 [138 kB] 73s dpkg-preconfigure: unable to re-open stdin: No such file or directory 73s Fetched 9472 kB in 1s (15.4 MB/s) 73s Selecting previously unselected package libpython3.14-minimal:arm64. 73s (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 ... 135223 files and directories currently installed.) 73s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_arm64.deb ... 73s Unpacking libpython3.14-minimal:arm64 (3.14.2-1) ... 73s Selecting previously unselected package python3.14-minimal. 73s Preparing to unpack .../python3.14-minimal_3.14.2-1_arm64.deb ... 73s Unpacking python3.14-minimal (3.14.2-1) ... 73s Preparing to unpack .../python3-gdbm_3.14.2-1_arm64.deb ... 73s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-1) ... 73s Setting up libpython3.14-minimal:arm64 (3.14.2-1) ... 73s Setting up python3.14-minimal (3.14.2-1) ... 75s (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 ... 135565 files and directories currently installed.) 75s Preparing to unpack .../python3-minimal_3.14.2-1_arm64.deb ... 75s Unpacking python3-minimal (3.14.2-1) over (3.13.7-1) ... 75s Setting up python3-minimal (3.14.2-1) ... 75s (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 ... 135565 files and directories currently installed.) 75s Preparing to unpack .../0-python3_3.14.2-1_arm64.deb ... 75s running python pre-rtupdate hooks for python3.14... 75s Unpacking python3 (3.14.2-1) over (3.13.7-1) ... 75s Selecting previously unselected package libpython3.14-stdlib:arm64. 75s Preparing to unpack .../1-libpython3.14-stdlib_3.14.2-1_arm64.deb ... 75s Unpacking libpython3.14-stdlib:arm64 (3.14.2-1) ... 75s Selecting previously unselected package python3.14. 75s Preparing to unpack .../2-python3.14_3.14.2-1_arm64.deb ... 75s Unpacking python3.14 (3.14.2-1) ... 75s Preparing to unpack .../3-libpython3-stdlib_3.14.2-1_arm64.deb ... 75s Unpacking libpython3-stdlib:arm64 (3.14.2-1) over (3.13.7-1) ... 76s Preparing to unpack .../4-libapt-pkg7.0_3.1.13_arm64.deb ... 76s Unpacking libapt-pkg7.0:arm64 (3.1.13) over (3.1.12) ... 76s Preparing to unpack .../5-apt_3.1.13_arm64.deb ... 76s Unpacking apt (3.1.13) over (3.1.12) ... 76s Preparing to unpack .../6-libplymouth5_24.004.60+git20250831.4a3c171d-0ubuntu4_arm64.deb ... 76s Unpacking libplymouth5:arm64 (24.004.60+git20250831.4a3c171d-0ubuntu4) over (24.004.60+git20250831.4a3c171d-0ubuntu3) ... 76s Preparing to unpack .../7-plymouth-theme-ubuntu-text_24.004.60+git20250831.4a3c171d-0ubuntu4_arm64.deb ... 76s Unpacking plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu4) over (24.004.60+git20250831.4a3c171d-0ubuntu3) ... 76s Preparing to unpack .../8-plymouth_24.004.60+git20250831.4a3c171d-0ubuntu4_arm64.deb ... 76s Unpacking plymouth (24.004.60+git20250831.4a3c171d-0ubuntu4) over (24.004.60+git20250831.4a3c171d-0ubuntu3) ... 76s Setting up libpython3.14-stdlib:arm64 (3.14.2-1) ... 76s Setting up libplymouth5:arm64 (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 76s Setting up libapt-pkg7.0:arm64 (3.1.13) ... 76s Setting up python3.14 (3.14.2-1) ... 77s Setting up libpython3-stdlib:arm64 (3.14.2-1) ... 77s Setting up apt (3.1.13) ... 78s Setting up python3-gdbm (3.14.2-1) ... 78s Setting up plymouth (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 78s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 79s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 79s Setting up python3 (3.14.2-1) ... 79s running python rtupdate hooks for python3.14... 79s running python post-rtupdate hooks for python3.14... 79s Processing triggers for libc-bin (2.42-2ubuntu4) ... 79s Processing triggers for systemd (259-1ubuntu2) ... 79s Processing triggers for man-db (2.13.1-1) ... 81s Processing triggers for initramfs-tools (0.150ubuntu7) ... 81s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 98s System running in EFI mode, skipping. 98s Setting up plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 98s Processing triggers for initramfs-tools (0.150ubuntu7) ... 98s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 113s System running in EFI mode, skipping. 113s autopkgtest [03:06:05]: upgrading testbed (apt dist-upgrade and autopurge) 114s Reading package lists... 114s Building dependency tree... 114s Reading state information... 114s Calculating upgrade... 115s The following packages were automatically installed and are no longer required: 115s python3.13 python3.13-minimal 115s Use 'sudo apt autoremove' to remove them. 115s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s Reading package lists... 115s Building dependency tree... 115s Reading state information... 115s Solving dependencies... 116s The following packages will be REMOVED: 116s python3.13* python3.13-minimal* 116s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 116s After this operation, 7766 kB disk space will be freed. 116s (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 ... 135995 files and directories currently installed.) 116s Removing python3.13 (3.13.11-1) ... 116s Removing python3.13-minimal (3.13.11-1) ... 117s Processing triggers for man-db (2.13.1-1) ... 117s Processing triggers for systemd (259-1ubuntu2) ... 118s (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 ... 135968 files and directories currently installed.) 118s Purging configuration files for python3.13-minimal (3.13.11-1) ... 118s autopkgtest [03:06:10]: rebooting testbed after setup commands that affected boot 156s autopkgtest [03:06:48]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:41:39 UTC 2026 158s autopkgtest [03:06:50]: @@@@@@@@@@@@@@@@@@@@ apt-source python-rq 160s Get:1 http://ftpmaster.internal/ubuntu resolute/universe python-rq 2.6.1-1 (dsc) [2206 B] 160s Get:2 http://ftpmaster.internal/ubuntu resolute/universe python-rq 2.6.1-1 (tar) [680 kB] 160s Get:3 http://ftpmaster.internal/ubuntu resolute/universe python-rq 2.6.1-1 (diff) [7124 B] 160s gpgv: Signature made Sun Nov 30 07:46:24 2025 UTC 160s gpgv: using RSA key B70DFC6F134FECFC011E62AA83016014251D1DB0 160s gpgv: issuer "c.schoenert@t-online.de" 160s gpgv: Can't check signature: No public key 160s dpkg-source: warning: cannot verify inline signature for ./python-rq_2.6.1-1.dsc: no acceptable signature found 160s autopkgtest [03:06:52]: testing package python-rq version 2.6.1-1 161s autopkgtest [03:06:53]: build not needed 161s autopkgtest [03:06:53]: test smoke: preparing testbed 161s Reading package lists... 162s Building dependency tree... 162s Reading state information... 162s Solving dependencies... 162s The following NEW packages will be installed: 162s libjs-jquery libjs-jquery-hotkeys libjs-jquery-isonscreen 162s libjs-jquery-metadata libjs-jquery-tablesorter 162s libjs-jquery-throttle-debounce liblzf1 python3-all python3-click 162s python3-coverage python3-croniter python3-iniconfig python3-pluggy 162s python3-psutil python3-pytest python3-pytest-cov python3-redis python3-rq 162s python3.13 python3.13-minimal redis-server redis-tools 162s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 162s Need to get 6140 kB of archives. 162s After this operation, 23.8 MB of additional disk space will be used. 162s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 python3.13-minimal arm64 3.13.11-1 [2346 kB] 162s Get:2 http://ftpmaster.internal/ubuntu resolute/universe arm64 liblzf1 arm64 3.6-4build1 [7330 B] 163s Get:3 http://ftpmaster.internal/ubuntu resolute/universe arm64 redis-tools arm64 5:8.0.5-1 [1332 kB] 163s Get:4 http://ftpmaster.internal/ubuntu resolute/universe arm64 redis-server arm64 5:8.0.5-1 [53.2 kB] 163s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 python3.13 arm64 3.13.11-1 [758 kB] 163s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 libjs-jquery all 3.7.1+dfsg+~3.5.33-1build1 [321 kB] 163s Get:7 http://ftpmaster.internal/ubuntu resolute/universe arm64 libjs-jquery-metadata all 12-4build1 [6692 B] 163s Get:8 http://ftpmaster.internal/ubuntu resolute/universe arm64 libjs-jquery-tablesorter all 1:2.31.3+dfsg1-5 [192 kB] 163s Get:9 http://ftpmaster.internal/ubuntu resolute/universe arm64 libjs-jquery-throttle-debounce all 1.1+dfsg.1-2build1 [12.6 kB] 163s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 python3-all arm64 3.14.2-1 [888 B] 163s Get:11 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-click all 8.2.0+0.really.8.1.8-1build1 [80.2 kB] 163s Get:12 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-coverage arm64 7.8.2+dfsg1-1ubuntu1 [159 kB] 163s Get:13 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-croniter all 6.1~rc1-0ubuntu1 [21.7 kB] 163s Get:14 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-iniconfig all 2.1.0-2 [6962 B] 163s Get:15 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-pluggy all 1.6.0-2 [21.1 kB] 163s Get:16 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-psutil arm64 7.1.0-1ubuntu1 [191 kB] 163s Get:17 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-pytest all 9.0.2-2 [277 kB] 163s Get:18 http://ftpmaster.internal/ubuntu resolute/universe arm64 libjs-jquery-hotkeys all 0.2.0-1 [13.3 kB] 163s Get:19 http://ftpmaster.internal/ubuntu resolute/universe arm64 libjs-jquery-isonscreen all 1.2.0-2 [3814 B] 163s Get:20 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-pytest-cov all 5.0.0-1 [21.3 kB] 163s Get:21 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-redis all 6.4.0-1 [228 kB] 163s Get:22 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-rq all 2.6.1-1 [86.3 kB] 163s Fetched 6140 kB in 1s (9316 kB/s) 163s Selecting previously unselected package python3.13-minimal. 163s (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 ... 135970 files and directories currently installed.) 163s Preparing to unpack .../00-python3.13-minimal_3.13.11-1_arm64.deb ... 163s Unpacking python3.13-minimal (3.13.11-1) ... 163s Selecting previously unselected package liblzf1:arm64. 164s Preparing to unpack .../01-liblzf1_3.6-4build1_arm64.deb ... 164s Unpacking liblzf1:arm64 (3.6-4build1) ... 164s Selecting previously unselected package redis-tools. 164s Preparing to unpack .../02-redis-tools_5%3a8.0.5-1_arm64.deb ... 164s Unpacking redis-tools (5:8.0.5-1) ... 164s Selecting previously unselected package redis-server. 164s Preparing to unpack .../03-redis-server_5%3a8.0.5-1_arm64.deb ... 164s Unpacking redis-server (5:8.0.5-1) ... 164s Selecting previously unselected package python3.13. 164s Preparing to unpack .../04-python3.13_3.13.11-1_arm64.deb ... 164s Unpacking python3.13 (3.13.11-1) ... 164s Selecting previously unselected package libjs-jquery. 164s Preparing to unpack .../05-libjs-jquery_3.7.1+dfsg+~3.5.33-1build1_all.deb ... 164s Unpacking libjs-jquery (3.7.1+dfsg+~3.5.33-1build1) ... 164s Selecting previously unselected package libjs-jquery-metadata. 164s Preparing to unpack .../06-libjs-jquery-metadata_12-4build1_all.deb ... 164s Unpacking libjs-jquery-metadata (12-4build1) ... 164s Selecting previously unselected package libjs-jquery-tablesorter. 164s Preparing to unpack .../07-libjs-jquery-tablesorter_1%3a2.31.3+dfsg1-5_all.deb ... 164s Unpacking libjs-jquery-tablesorter (1:2.31.3+dfsg1-5) ... 164s Selecting previously unselected package libjs-jquery-throttle-debounce. 164s Preparing to unpack .../08-libjs-jquery-throttle-debounce_1.1+dfsg.1-2build1_all.deb ... 164s Unpacking libjs-jquery-throttle-debounce (1.1+dfsg.1-2build1) ... 164s Selecting previously unselected package python3-all. 164s Preparing to unpack .../09-python3-all_3.14.2-1_arm64.deb ... 164s Unpacking python3-all (3.14.2-1) ... 164s Selecting previously unselected package python3-click. 164s Preparing to unpack .../10-python3-click_8.2.0+0.really.8.1.8-1build1_all.deb ... 164s Unpacking python3-click (8.2.0+0.really.8.1.8-1build1) ... 164s Selecting previously unselected package python3-coverage. 164s Preparing to unpack .../11-python3-coverage_7.8.2+dfsg1-1ubuntu1_arm64.deb ... 164s Unpacking python3-coverage (7.8.2+dfsg1-1ubuntu1) ... 164s Selecting previously unselected package python3-croniter. 164s Preparing to unpack .../12-python3-croniter_6.1~rc1-0ubuntu1_all.deb ... 164s Unpacking python3-croniter (6.1~rc1-0ubuntu1) ... 164s Selecting previously unselected package python3-iniconfig. 164s Preparing to unpack .../13-python3-iniconfig_2.1.0-2_all.deb ... 164s Unpacking python3-iniconfig (2.1.0-2) ... 164s Selecting previously unselected package python3-pluggy. 164s Preparing to unpack .../14-python3-pluggy_1.6.0-2_all.deb ... 164s Unpacking python3-pluggy (1.6.0-2) ... 164s Selecting previously unselected package python3-psutil. 164s Preparing to unpack .../15-python3-psutil_7.1.0-1ubuntu1_arm64.deb ... 164s Unpacking python3-psutil (7.1.0-1ubuntu1) ... 164s Selecting previously unselected package python3-pytest. 164s Preparing to unpack .../16-python3-pytest_9.0.2-2_all.deb ... 164s Unpacking python3-pytest (9.0.2-2) ... 164s Selecting previously unselected package libjs-jquery-hotkeys. 164s Preparing to unpack .../17-libjs-jquery-hotkeys_0.2.0-1_all.deb ... 164s Unpacking libjs-jquery-hotkeys (0.2.0-1) ... 165s Selecting previously unselected package libjs-jquery-isonscreen. 165s Preparing to unpack .../18-libjs-jquery-isonscreen_1.2.0-2_all.deb ... 165s Unpacking libjs-jquery-isonscreen (1.2.0-2) ... 165s Selecting previously unselected package python3-pytest-cov. 165s Preparing to unpack .../19-python3-pytest-cov_5.0.0-1_all.deb ... 165s Unpacking python3-pytest-cov (5.0.0-1) ... 165s Selecting previously unselected package python3-redis. 165s Preparing to unpack .../20-python3-redis_6.4.0-1_all.deb ... 165s Unpacking python3-redis (6.4.0-1) ... 165s Selecting previously unselected package python3-rq. 165s Preparing to unpack .../21-python3-rq_2.6.1-1_all.deb ... 165s Unpacking python3-rq (2.6.1-1) ... 165s Setting up python3-iniconfig (2.1.0-2) ... 165s Setting up python3-croniter (6.1~rc1-0ubuntu1) ... 165s Setting up libjs-jquery-throttle-debounce (1.1+dfsg.1-2build1) ... 165s Setting up python3-click (8.2.0+0.really.8.1.8-1build1) ... 166s Setting up python3-psutil (7.1.0-1ubuntu1) ... 166s Setting up liblzf1:arm64 (3.6-4build1) ... 166s Setting up python3-redis (6.4.0-1) ... 167s Setting up python3-pluggy (1.6.0-2) ... 167s Setting up python3.13-minimal (3.13.11-1) ... 168s Setting up libjs-jquery (3.7.1+dfsg+~3.5.33-1build1) ... 168s Setting up libjs-jquery-hotkeys (0.2.0-1) ... 168s Setting up python3.13 (3.13.11-1) ... 169s Setting up python3-pytest (9.0.2-2) ... 170s Setting up python3-all (3.14.2-1) ... 170s Setting up python3-coverage (7.8.2+dfsg1-1ubuntu1) ... 170s Setting up python3-rq (2.6.1-1) ... 170s Setting up redis-tools (5:8.0.5-1) ... 171s Setting up libjs-jquery-metadata (12-4build1) ... 171s Setting up libjs-jquery-isonscreen (1.2.0-2) ... 171s Setting up libjs-jquery-tablesorter (1:2.31.3+dfsg1-5) ... 171s Setting up python3-pytest-cov (5.0.0-1) ... 171s Setting up redis-server (5:8.0.5-1) ... 171s Created symlink '/etc/systemd/system/redis.service' → '/usr/lib/systemd/system/redis-server.service'. 171s Created symlink '/etc/systemd/system/multi-user.target.wants/redis-server.service' → '/usr/lib/systemd/system/redis-server.service'. 172s Processing triggers for systemd (259-1ubuntu2) ... 172s Processing triggers for man-db (2.13.1-1) ... 172s Processing triggers for libc-bin (2.42-2ubuntu4) ... 174s autopkgtest [03:07:06]: test smoke: [----------------------- 174s 174s *************************** 174s *** Testing with python3.13 174s *************************** 174s 174s Content of current working folder: 174s 174s total 12 174s drwxr-xr-x 3 ubuntu ubuntu 4096 Jan 23 03:07 . 174s drwxrwxrwx 5 root root 4096 Jan 23 03:07 .. 174s drwxrwxr-x 4 ubuntu ubuntu 4096 Jan 23 03:07 tests 174s Running tests... 174s 175s ============================= test session starts ============================== 175s platform linux -- Python 3.13.11, pytest-9.0.2, pluggy-1.6.0 175s rootdir: /tmp/autopkgtest.dcRtyt/autopkgtest_tmp 175s plugins: cov-5.0.0, typeguard-4.4.4 175s collected 536 items / 3 deselected / 533 selected 175s 175s tests/test_callbacks.py ........Traceback (most recent call last): 175s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 175s return_value = job.perform() 175s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 175s self._result = self._execute() 175s ~~~~~~~~~~~~~^^ 175s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 175s result = self.func(*self.args, **self.kwargs) 175s TypeError: div_by_zero() missing 1 required positional argument: 'x' 175s 175s Traceback (most recent call last): 175s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 175s return_value = job.perform() 175s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 175s self._result = self._execute() 175s ~~~~~~~~~~~~~^^ 175s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 175s result = self.func(*self.args, **self.kwargs) 175s TypeError: div_by_zero() missing 1 required positional argument: 'x' 175s 175s ..... 188s tests/test_cli.py ......................................... 192s tests/test_commands.py ..... 192s 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 ............. 197s tests/test_executions.py ............ 197s tests/test_fixtures.py .. 203s tests/test_group.py ................ 203s tests/test_helpers.py . 203s tests/test_intermediate_queue.py ........ 209s tests/test_job.py .......................................................... 209s tests/test_job_dependency.py ...# Post enqueue {b'35f41e7d-4aac-4f1c-a8ac-23b1cfb2254d'} 209s # Post work {b'35f41e7d-4aac-4f1c-a8ac-23b1cfb2254d'} 209s # Pre cancel {b'35f41e7d-4aac-4f1c-a8ac-23b1cfb2254d'} 209s #Post cancel set() 215s ....................... 215s tests/test_queue.py ....................................................... 216s tests/test_registry.py ....................................... 216s 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 . 228s tests/test_spawn_worker.py Worker 14f1ed9656a3470cb3f7cf0df77162de: job de39fed0-a9cb-4b29-b330-806771da0320: exception raised while executing (tests.fixtures.div_by_zero) 228s Traceback (most recent call last): 228s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 228s return_value = job.perform() 228s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 228s self._result = self._execute() 228s ~~~~~~~~~~~~~^^ 228s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 228s result = self.func(*self.args, **self.kwargs) 228s TypeError: div_by_zero() missing 1 required positional argument: 'x' 228s 228s .ss 233s tests/test_timeouts.py .. 233s tests/test_utils.py ......................... 253s tests/test_worker.py ......................ss...........s............s.s...................sssss03:08:24 Worker 25db5f07d35045edab79cb6bd6d3cc04: started with PID 2321, version 2.6.1 253s 03:08:24 Worker 25db5f07d35045edab79cb6bd6d3cc04: subscribing to channel rq:pubsub:25db5f07d35045edab79cb6bd6d3cc04 253s 03:08:24 *** Listening on default... 253s 03:08:24 Worker 25db5f07d35045edab79cb6bd6d3cc04: cleaning registries for queue: default 253s 03:08:24 default: tests.fixtures.access_self() (b9e2baa5-4234-45c3-b2b0-12a2b9d14c48) 253s 03:08:24 Successfully completed tests.fixtures.access_self() job in 0:00:00.145912s on worker 25db5f07d35045edab79cb6bd6d3cc04 253s 03:08:24 default: Job OK (b9e2baa5-4234-45c3-b2b0-12a2b9d14c48) 253s 03:08:24 Result is kept for 500 seconds 253s 03:08:24 Worker 25db5f07d35045edab79cb6bd6d3cc04: done, quitting 253s 03:08:24 Worker 25db5f07d35045edab79cb6bd6d3cc04: unsubscribing from channel rq:pubsub:25db5f07d35045edab79cb6bd6d3cc04 253s .03:08:25 Worker daa6638aee624555901a485d12855053: started with PID 2325, version 2.6.1 253s 03:08:25 Worker daa6638aee624555901a485d12855053: subscribing to channel rq:pubsub:daa6638aee624555901a485d12855053 253s 03:08:25 *** Listening on default... 253s 03:08:25 Worker daa6638aee624555901a485d12855053: cleaning registries for queue: default 253s 03:08:25 Worker daa6638aee624555901a485d12855053: done, quitting 253s 03:08:25 Worker daa6638aee624555901a485d12855053: unsubscribing from channel rq:pubsub:daa6638aee624555901a485d12855053 253s .03:08:25 Worker 73feccdf965e4bc5b18c207a7f2c9dff: started with PID 2328, version 2.6.1 253s 03:08:25 Worker 73feccdf965e4bc5b18c207a7f2c9dff: subscribing to channel rq:pubsub:73feccdf965e4bc5b18c207a7f2c9dff 253s 03:08:25 *** Listening on default... 253s 03:08:25 Worker 73feccdf965e4bc5b18c207a7f2c9dff: cleaning registries for queue: default 253s 03:08:25 default: tests.test_worker.schedule_access_self() (5118ee0d-0aa3-4c96-9a3d-3805eeb933a2) 253s 03:08:25 Successfully completed tests.test_worker.schedule_access_self() job in 0:00:00.169697s on worker 73feccdf965e4bc5b18c207a7f2c9dff 253s 03:08:25 default: Job OK (5118ee0d-0aa3-4c96-9a3d-3805eeb933a2) 253s 03:08:25 Result is kept for 500 seconds 253s 03:08:25 Worker 73feccdf965e4bc5b18c207a7f2c9dff: done, quitting 253s 03:08:25 Worker 73feccdf965e4bc5b18c207a7f2c9dff: unsubscribing from channel rq:pubsub:73feccdf965e4bc5b18c207a7f2c9dff 254s .s..ss... 262s tests/test_worker_pool.py ....... 262s tests/test_worker_registration.py .... 262s 262s =============================== warnings summary =============================== 262s tests/test_group.py:99 262s /tmp/autopkgtest.dcRtyt/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 262s @pytest.mark.slow 262s 262s tests/test_group.py:114 262s /tmp/autopkgtest.dcRtyt/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 262s @pytest.mark.slow 262s 262s tests/test_group.py:127 262s /tmp/autopkgtest.dcRtyt/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 262s @pytest.mark.slow 262s 262s tests/__init__.py:44 262s /tmp/autopkgtest.dcRtyt/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 262s f = pytest.mark.ssl_test(f) 262s 262s tests/__init__.py:39 262s /tmp/autopkgtest.dcRtyt/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 262s f = pytest.mark.slow(f) 262s 262s tests/test_callbacks.py: 12 warnings 262s tests/test_decorator.py: 1 warning 262s /usr/lib/python3/dist-packages/rq/job.py:313: DeprecationWarning: Passing a string or function for `on_success` is deprecated, pass `Callback` instead 262s warnings.warn( 262s 262s tests/test_callbacks.py: 7 warnings 262s tests/test_decorator.py: 3 warnings 262s tests/test_job.py: 1 warning 262s /usr/lib/python3/dist-packages/rq/job.py:323: DeprecationWarning: Passing a string or function for `on_failure` is deprecated, pass `Callback` instead 262s warnings.warn( 262s 262s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_many_callback 262s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_with_stopped_callback 262s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_with_stopped_callback 262s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_with_stopped_callback 262s tests/test_callbacks.py::SyncJobCallback::test_stopped_callback 262s tests/test_callbacks.py::JobCallbackTestCase::test_job_creation_with_stopped_callback 262s tests/test_job.py::TestJob::test_persistence_of_callbacks 262s /usr/lib/python3/dist-packages/rq/job.py:333: DeprecationWarning: Passing a string or function for `on_stopped` is deprecated, pass `Callback` instead 262s warnings.warn( 262s 262s tests/test_callbacks.py: 2 warnings 262s tests/test_cli.py: 3 warnings 262s tests/test_dependencies.py: 1 warning 262s tests/test_job.py: 6 warnings 262s tests/test_results.py: 1 warning 262s tests/test_worker.py: 13 warnings 262s /usr/lib/python3/dist-packages/rq/job.py:868: DeprecationWarning: job.result is deprecated, use job.return_value instead. 262s warnings.warn('job.result is deprecated, use job.return_value instead.', DeprecationWarning) 262s 262s tests/test_callbacks.py: 2 warnings 262s tests/test_cli.py: 16 warnings 262s tests/test_commands.py: 1 warning 262s tests/test_dependencies.py: 7 warnings 262s tests/test_fixtures.py: 1 warning 262s tests/test_intermediate_queue.py: 1 warning 262s tests/test_job.py: 1 warning 262s tests/test_job_dependency.py: 7 warnings 262s tests/test_queue.py: 2 warnings 262s tests/test_registry.py: 9 warnings 262s tests/test_repeat.py: 3 warnings 262s tests/test_retry.py: 6 warnings 262s tests/test_scheduler.py: 2 warnings 262s tests/test_worker.py: 94 warnings 262s tests/test_worker_pool.py: 1 warning 262s /usr/lib/python3/dist-packages/rq/worker.py:1602: DeprecationWarning: This process (pid=1990) is multi-threaded, use of fork() may lead to deadlocks in the child. 262s child_pid = os.fork() 262s 262s tests/test_callbacks.py::WorkerCallbackTestCase::test_failure_callback 262s tests/test_callbacks.py::WorkerCallbackTestCase::test_failure_callback 262s tests/test_job.py::TestJob::test_compressed_exc_info_handling 262s tests/test_job.py::TestJob::test_compressed_exc_info_handling 262s tests/test_registry.py::TestDeferredRegistry::test_cleanup_moves_jobs_to_failed_job_registry 262s tests/test_results.py::TestResult::test_job_failed_result 262s tests/test_timeouts.py::TestTimeouts::test_timer_death_penalty 262s tests/test_worker.py::TestWorker::test_horse_fails 262s tests/test_worker.py::TestWorker::test_job_timeout_moved_to_failed_job_registry 262s /usr/lib/python3/dist-packages/rq/job.py:816: DeprecationWarning: job.exc_info is deprecated, use job.latest_result() instead. 262s warnings.warn('job.exc_info is deprecated, use job.latest_result() instead.', DeprecationWarning) 262s 262s tests/test_commands.py::TestCommands::test_pubsub_thread_exits_other_error 262s /usr/lib/python3/dist-packages/_pytest/threadexception.py:58: PytestUnhandledThreadExceptionWarning: Exception in thread Thread-34 262s 262s Traceback (most recent call last): 262s File "/usr/lib/python3.13/threading.py", line 1044, in _bootstrap_inner 262s self.run() 262s ~~~~~~~~^^ 262s File "/usr/lib/python3/dist-packages/redis/client.py", line 1269, in run 262s self.exception_handler(e, pubsub, self) 262s ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^ 262s File "/usr/lib/python3/dist-packages/redis/client.py", line 1265, in run 262s pubsub.get_message(ignore_subscribe_messages=True, timeout=sleep_time) 262s ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 262s File "/tmp/autopkgtest.dcRtyt/autopkgtest_tmp/tests/fixtures.py", line 51, in raise_exc 262s raise Exception('raise_exc error') 262s Exception: raise_exc error 262s 262s Enable tracemalloc to get traceback where the object was allocated. 262s See https://docs.pytest.org/en/stable/how-to/capture-warnings.html#resource-warnings for more info. 262s warnings.warn(pytest.PytestUnhandledThreadExceptionWarning(msg)) 262s 262s tests/test_commands.py::TestCommands::test_shutdown_command 262s tests/test_commands.py::TestCommands::test_stop_job_command 262s tests/test_scheduler.py::TestWorker::test_work 262s tests/test_scheduler.py::TestWorker::test_work_with_serializer 262s /usr/lib/python3.13/multiprocessing/popen_fork.py:67: DeprecationWarning: This process (pid=1990) is multi-threaded, use of fork() may lead to deadlocks in the child. 262s self.pid = os.fork() 262s 262s tests/test_connection.py::TestConnectionInheritance::test_parse_connection 262s /usr/lib/python3/dist-packages/_pytest/threadexception.py:58: PytestUnhandledThreadExceptionWarning: Exception in thread Thread-35 262s 262s Traceback (most recent call last): 262s File "/usr/lib/python3/dist-packages/redis/_parsers/socket.py", line 65, in _read_from_socket 262s data = self._sock.recv(socket_read_size) 262s OSError: [Errno 9] Bad file descriptor 262s 262s During handling of the above exception, another exception occurred: 262s 262s Traceback (most recent call last): 262s File "/usr/lib/python3.13/threading.py", line 1044, in _bootstrap_inner 262s self.run() 262s ~~~~~~~~^^ 262s File "/usr/lib/python3/dist-packages/redis/client.py", line 1269, in run 262s self.exception_handler(e, pubsub, self) 262s ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^ 262s File "/usr/lib/python3/dist-packages/redis/client.py", line 1265, in run 262s pubsub.get_message(ignore_subscribe_messages=True, timeout=sleep_time) 262s ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 262s File "/usr/lib/python3/dist-packages/redis/client.py", line 1127, in get_message 262s response = self.parse_response(block=(timeout is None), timeout=timeout) 262s File "/usr/lib/python3/dist-packages/redis/client.py", line 938, in parse_response 262s response = self._execute(conn, try_read) 262s File "/usr/lib/python3/dist-packages/redis/client.py", line 914, in _execute 262s return conn.retry.call_with_retry( 262s ~~~~~~~~~~~~~~~~~~~~~~~~~~^ 262s lambda: command(*args, **kwargs), 262s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 262s lambda _: self._reconnect(conn), 262s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 262s ) 262s ^ 262s File "/usr/lib/python3/dist-packages/redis/retry.py", line 105, in call_with_retry 262s return do() 262s File "/usr/lib/python3/dist-packages/redis/client.py", line 915, in 262s lambda: command(*args, **kwargs), 262s ~~~~~~~^^^^^^^^^^^^^^^^^ 262s File "/usr/lib/python3/dist-packages/redis/client.py", line 932, in try_read 262s if not conn.can_read(timeout=timeout): 262s ~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^ 262s File "/usr/lib/python3/dist-packages/redis/connection.py", line 628, in can_read 262s return self._parser.can_read(timeout) 262s ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 262s File "/usr/lib/python3/dist-packages/redis/_parsers/base.py", line 140, in can_read 262s return self._buffer and self._buffer.can_read(timeout) 262s ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 262s File "/usr/lib/python3/dist-packages/redis/_parsers/socket.py", line 95, in can_read 262s return bool(self.unread_bytes()) or self._read_from_socket( 262s ~~~~~~~~~~~~~~~~~~~~~~^ 262s timeout=timeout, raise_on_timeout=False 262s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 262s ) 262s ^ 262s File "/usr/lib/python3/dist-packages/redis/_parsers/socket.py", line 90, in _read_from_socket 262s buf.seek(current_pos) 262s ~~~~~~~~^^^^^^^^^^^^^ 262s ValueError: I/O operation on closed file. 262s 262s Enable tracemalloc to get traceback where the object was allocated. 262s See https://docs.pytest.org/en/stable/how-to/capture-warnings.html#resource-warnings for more info. 262s warnings.warn(pytest.PytestUnhandledThreadExceptionWarning(msg)) 262s 262s tests/test_registry.py::TestStartedJobRegistry::test_count 262s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got will-be-cleaned-up 262s warnings.warn( 262s 262s tests/test_retry.py::TestRetry::test_cleanup_handles_retries 262s tests/test_retry.py::TestRetry::test_cleanup_handles_retries 262s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got f7562a20-f228-4a3d-a016-ae21e2b1335c 262s warnings.warn( 262s 262s tests/test_scheduler.py::TestQueue::test_custom_connection_pool 262s /tmp/autopkgtest.dcRtyt/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. 262s scheduler_connection = scheduler.connection.connection_pool.get_connection('info') 262s 262s tests/test_scheduler.py::TestQueue::test_no_custom_connection_pool 262s /tmp/autopkgtest.dcRtyt/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. 262s scheduler_connection = scheduler.connection.connection_pool.get_connection('info') 262s 262s tests/test_spawn_worker.py::TestWorker::test_work_fails 262s :891: DeprecationWarning: This process (pid=1990) is multi-threaded, use of fork() may lead to deadlocks in the child. 262s 262s tests/test_worker.py::TestWorker::test_clean_queue_registries 262s tests/test_worker.py::TestWorker::test_worker_calls_clean_registries 262s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got foo 262s warnings.warn( 262s 262s tests/test_worker.py::TestWorker::test_clean_queue_registries 262s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got bar 262s warnings.warn( 262s 262s tests/test_worker.py::TestWorker::test_worker_ttl 262s /tmp/autopkgtest.dcRtyt/autopkgtest_tmp/tests/test_worker.py:164: DeprecationWarning: default_worker_ttl is deprecated, use worker_ttl. 262s w = Worker([], connection=self.connection, default_worker_ttl=10) 262s 262s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 262s ==== 517 passed, 16 skipped, 3 deselected, 240 warnings in 87.73s (0:01:27) ==== 262s 262s *************************** 262s *** Testing with python3.14 262s *************************** 262s 262s Content of current working folder: 262s 262s total 12 262s drwxr-xr-x 3 ubuntu ubuntu 4096 Jan 23 03:08 . 262s drwxrwxrwx 5 root root 4096 Jan 23 03:07 .. 262s drwxrwxr-x 5 ubuntu ubuntu 4096 Jan 23 03:07 tests 262s Running tests... 262s 263s ============================= test session starts ============================== 263s platform linux -- Python 3.14.2, pytest-9.0.2, pluggy-1.6.0 263s rootdir: /tmp/autopkgtest.dcRtyt/autopkgtest_tmp 263s plugins: cov-5.0.0, typeguard-4.4.4 263s collected 536 items / 3 deselected / 533 selected 263s 263s tests/test_callbacks.py ........Traceback (most recent call last): 263s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 263s return_value = job.perform() 263s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 263s self._result = self._execute() 263s ~~~~~~~~~~~~~^^ 263s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 263s result = self.func(*self.args, **self.kwargs) 263s TypeError: div_by_zero() missing 1 required positional argument: 'x' 263s 263s Traceback (most recent call last): 263s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 263s return_value = job.perform() 263s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 263s self._result = self._execute() 263s ~~~~~~~~~~~~~^^ 263s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 263s result = self.func(*self.args, **self.kwargs) 263s TypeError: div_by_zero() missing 1 required positional argument: 'x' 263s 263s ..... 276s tests/test_cli.py .................................03:08:48 Worker 4d555b85fded43d882a5b049b789a619: started with PID 2460, version 2.6.1 276s 03:08:48 Worker 4d555b85fded43d882a5b049b789a619: subscribing to channel rq:pubsub:4d555b85fded43d882a5b049b789a619 276s 03:08:48 *** Listening on default... 276s 03:08:48 Acquired scheduler lock for default 276s 03:08:48 Worker 4d555b85fded43d882a5b049b789a619: cleaning registries for queue: default 276s 03:08:48 Worker 4d555b85fded43d882a5b049b789a619: done, quitting 276s 03:08:48 Worker 4d555b85fded43d882a5b049b789a619: unsubscribing from channel rq:pubsub:4d555b85fded43d882a5b049b789a619 277s .03:08:49 Worker 107854318a044e39961010feeb14b968: started with PID 2462, version 2.6.1 277s 03:08:49 Worker 107854318a044e39961010feeb14b968: subscribing to channel rq:pubsub:107854318a044e39961010feeb14b968 277s 03:08:49 *** Listening on foo, bar... 277s 03:08:49 Acquired scheduler lock for foo 277s 03:08:49 Acquired scheduler lock for bar 277s 03:08:49 Worker 107854318a044e39961010feeb14b968: cleaning registries for queue: foo 277s 03:08:49 Worker 107854318a044e39961010feeb14b968: cleaning registries for queue: bar 277s 03:08:49 foo: tests.fixtures.say_hello('Hello') (23c93156-439c-4331-9ac8-be01d5230d61) 278s 03:08:49 Successfully completed tests.fixtures.say_hello('Hello') job in 0:00:00.128840s on worker 107854318a044e39961010feeb14b968 278s 03:08:49 foo: Job OK (23c93156-439c-4331-9ac8-be01d5230d61) 278s 03:08:49 Result is kept for 500 seconds 278s 03:08:49 bar: tests.fixtures.say_hello('Hello') (962fb6a3-f9be-46df-8811-8c2b8bbeb5fe) 278s 03:08:49 Successfully completed tests.fixtures.say_hello('Hello') job in 0:00:00.125306s on worker 107854318a044e39961010feeb14b968 278s 03:08:49 bar: Job OK (962fb6a3-f9be-46df-8811-8c2b8bbeb5fe) 278s 03:08:49 Result is kept for 500 seconds 278s 03:08:49 Worker 107854318a044e39961010feeb14b968: done, quitting 278s 03:08:49 Worker 107854318a044e39961010feeb14b968: unsubscribing from channel rq:pubsub:107854318a044e39961010feeb14b968 279s .03:08:50 Worker 1afe8aee13694f4d93d39a40e27c7353: started with PID 2466, version 2.6.1 279s 03:08:50 Worker 1afe8aee13694f4d93d39a40e27c7353: subscribing to channel rq:pubsub:1afe8aee13694f4d93d39a40e27c7353 279s 03:08:50 *** Listening on default... 279s 03:08:50 Acquired scheduler lock for default 279s 03:08:50 Worker 1afe8aee13694f4d93d39a40e27c7353: cleaning registries for queue: default 279s 03:08:50 Worker 1afe8aee13694f4d93d39a40e27c7353: done, quitting 279s 03:08:50 Worker 1afe8aee13694f4d93d39a40e27c7353: unsubscribing from channel rq:pubsub:1afe8aee13694f4d93d39a40e27c7353 280s 03:08:51 Worker 3f30ee5ce0fe4e55a465131283fc7f16: started with PID 2468, version 2.6.1 280s 03:08:51 Worker 3f30ee5ce0fe4e55a465131283fc7f16: subscribing to channel rq:pubsub:3f30ee5ce0fe4e55a465131283fc7f16 280s 03:08:51 *** Listening on default... 280s 03:08:51 Acquired scheduler lock for default 280s 03:08:51 Worker 3f30ee5ce0fe4e55a465131283fc7f16: cleaning registries for queue: default 280s 03:08:51 Worker 3f30ee5ce0fe4e55a465131283fc7f16: done, quitting 280s 03:08:51 Worker 3f30ee5ce0fe4e55a465131283fc7f16: unsubscribing from channel rq:pubsub:3f30ee5ce0fe4e55a465131283fc7f16 281s .03:08:52 Worker d999ac9959e4491e8fdd576422611ff2: started with PID 2470, version 2.6.1 281s 03:08:52 Worker d999ac9959e4491e8fdd576422611ff2: subscribing to channel rq:pubsub:d999ac9959e4491e8fdd576422611ff2 281s 03:08:52 *** Listening on default... 281s 03:08:52 Acquired scheduler lock for default 281s 03:08:52 Worker d999ac9959e4491e8fdd576422611ff2: cleaning registries for queue: default 281s 03:08:52 Worker d999ac9959e4491e8fdd576422611ff2: done, quitting 281s 03:08:52 Worker d999ac9959e4491e8fdd576422611ff2: unsubscribing from channel rq:pubsub:d999ac9959e4491e8fdd576422611ff2 281s 03:08:52 Worker 67c868bd22c4478f8f21a354682be48e: started with PID 2471, version 2.6.1 281s 03:08:52 Worker 67c868bd22c4478f8f21a354682be48e: subscribing to channel rq:pubsub:67c868bd22c4478f8f21a354682be48e 281s 03:08:52 *** Listening on default... 281s 03:08:52 Acquired scheduler lock for default 281s 03:08:52 Worker 67c868bd22c4478f8f21a354682be48e: cleaning registries for queue: default 281s 03:08:52 Worker 67c868bd22c4478f8f21a354682be48e: done, quitting 281s 03:08:52 Worker 67c868bd22c4478f8f21a354682be48e: unsubscribing from channel rq:pubsub:67c868bd22c4478f8f21a354682be48e 281s 03:08:52 Worker 45aaf85a41b942f6ae37be5b57531919: started with PID 2472, version 2.6.1 281s 03:08:52 Worker 45aaf85a41b942f6ae37be5b57531919: subscribing to channel rq:pubsub:45aaf85a41b942f6ae37be5b57531919 281s 03:08:52 *** Listening on default... 281s 03:08:52 Acquired scheduler lock for default 281s 03:08:52 Worker 45aaf85a41b942f6ae37be5b57531919: cleaning registries for queue: default 281s 03:08:52 Worker 45aaf85a41b942f6ae37be5b57531919: done, quitting 281s 03:08:52 Worker 45aaf85a41b942f6ae37be5b57531919: unsubscribing from channel rq:pubsub:45aaf85a41b942f6ae37be5b57531919 282s .03:08:54 Worker 5f72bdaa977e4b0286e02195fabd511d: registering birth 282s 03:08:54 Worker 5f72bdaa977e4b0286e02195fabd511d: started with PID 2476, version 2.6.1 282s 03:08:54 Worker 5f72bdaa977e4b0286e02195fabd511d: subscribing to channel rq:pubsub:5f72bdaa977e4b0286e02195fabd511d 282s 03:08:54 *** Listening on default... 282s 03:08:54 Acquiring scheduler lock for default 282s 03:08:54 Acquired scheduler lock for default 282s 03:08:54 Worker 5f72bdaa977e4b0286e02195fabd511d: sent heartbeat to prevent worker timeout. Next one should arrive in 480 seconds. 282s 03:08:54 Worker 5f72bdaa977e4b0286e02195fabd511d: cleaning registries for queue: default 282s 03:08:54 Worker 5f72bdaa977e4b0286e02195fabd511d: *** Listening on default... 282s 03:08:54 Worker 5f72bdaa977e4b0286e02195fabd511d: sent heartbeat to prevent worker timeout. Next one should arrive in 480 seconds. 282s 03:08:54 Worker 5f72bdaa977e4b0286e02195fabd511d: dequeueing jobs on queues default and timeout None 282s 03:08:54 Worker 5f72bdaa977e4b0286e02195fabd511d: sent heartbeat to prevent worker timeout. Next one should arrive in 480 seconds. 282s 03:08:54 Worker 5f72bdaa977e4b0286e02195fabd511d: done, quitting 282s 03:08:54 Worker 5f72bdaa977e4b0286e02195fabd511d: registering death 282s 03:08:54 Worker 5f72bdaa977e4b0286e02195fabd511d: unsubscribing from channel rq:pubsub:5f72bdaa977e4b0286e02195fabd511d 284s .... 285s tests/test_commands.py 03:08:56 Worker 805d1ab0546f4cacb191c09446e20602: started with PID 2483, version 2.6.1 285s 03:08:56 Worker 805d1ab0546f4cacb191c09446e20602: subscribing to channel rq:pubsub:805d1ab0546f4cacb191c09446e20602 285s 03:08:56 *** Listening on foo... 285s 03:08:56 Worker 805d1ab0546f4cacb191c09446e20602: cleaning registries for queue: foo 287s 03:08:58 Received kill horse command. 287s 03:08:58 Worker is not working, kill horse command ignored 287s 03:08:58 Received shutdown command, sending SIGINT signal. 287s 03:08:58 Worker 805d1ab0546f4cacb191c09446e20602 [PID 2483]: warm shut down requested 287s .03:08:58 Worker 805d1ab0546f4cacb191c09446e20602: unsubscribing from channel rq:pubsub:805d1ab0546f4cacb191c09446e20602 287s ...03:08:59 Worker c4b0e9e161614b349bb770101ffb4fe1: started with PID 2489, version 2.6.1 287s 03:08:59 Worker c4b0e9e161614b349bb770101ffb4fe1: subscribing to channel rq:pubsub:c4b0e9e161614b349bb770101ffb4fe1 287s 03:08:59 *** Listening on foo... 287s 03:08:59 Worker c4b0e9e161614b349bb770101ffb4fe1: cleaning registries for queue: foo 287s 03:08:59 foo: tests.fixtures.long_running_job(3) (0590f27f-44af-4fdb-83d4-a60dddfdd257) 288s 03:09:00 Not working on job 1, command ignored. 288s 03:09:00 Worker c4b0e9e161614b349bb770101ffb4fe1: killed horse pid 2491 288s 03:09:00 Worker c4b0e9e161614b349bb770101ffb4fe1: job 0590f27f-44af-4fdb-83d4-a60dddfdd257 stopped by user, moving job to FailedJobRegistry 288s 03:09:00 Worker c4b0e9e161614b349bb770101ffb4fe1: done, quitting 288s 03:09:00 Worker c4b0e9e161614b349bb770101ffb4fe1: unsubscribing from channel rq:pubsub:c4b0e9e161614b349bb770101ffb4fe1 289s . 289s tests/test_connection.py . 289s tests/test_cron.py ........................F... 289s tests/test_cron_job.py ........... 289s tests/test_cron_scheduler_registry.py .... 289s tests/test_decorator.py 03:09:01 Registered 'tests.fixtures.do_nothing' to run on default every 1 seconds 289s 03:09:01 CronScheduler autopkgtest:2492:2f6db1: starting... 289s 03:09:01 CronScheduler autopkgtest:2492:2f6db1: registering birth... 289s ..03:09:01 Enqueued job do_nothing to queue default 289s 03:09:01 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 289s ............ 290s tests/test_dependencies.py .03:09:02 Enqueued job do_nothing to queue default 290s 03:09:02 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 291s ............ 291s tests/test_executions.py ...03:09:03 Worker w1: started with PID 2524, version 2.6.1 291s 03:09:03 Worker w1: subscribing to channel rq:pubsub:w1 291s 03:09:03 *** Listening on default... 291s 03:09:03 Worker w1: cleaning registries for queue: default 291s 03:09:03 default: tests.fixtures.long_running_job(timeout=3) (3cc3e1e9-6f82-41b6-b0f9-86c6892ec64f) 291s 03:09:03 Enqueued job do_nothing to queue default 291s 03:09:03 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 292s 03:09:04 Enqueued job do_nothing to queue default 292s 03:09:04 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 293s 03:09:05 Enqueued job do_nothing to queue default 293s 03:09:05 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 294s 03:09:06 Successfully completed tests.fixtures.long_running_job(timeout=3) job in 0:00:03.000892s on worker w1 294s 03:09:06 default: Job OK (3cc3e1e9-6f82-41b6-b0f9-86c6892ec64f) 294s 03:09:06 Result is kept for 500 seconds 294s 03:09:06 default: tests.fixtures.do_nothing() (6bd5f3aa-e996-44e0-9690-af35f744ed7d) 294s 03:09:06 Successfully completed tests.fixtures.do_nothing() job in 0:00:00.000655s on worker w1 294s 03:09:06 default: Job OK (6bd5f3aa-e996-44e0-9690-af35f744ed7d) 294s 03:09:06 Result is kept for 500 seconds 294s 03:09:06 default: tests.fixtures.do_nothing() (5ac0fe42-4952-42e5-bd29-7dbe9c339d36) 294s 03:09:06 Successfully completed tests.fixtures.do_nothing() job in 0:00:00.000526s on worker w1 294s 03:09:06 default: Job OK (5ac0fe42-4952-42e5-bd29-7dbe9c339d36) 294s 03:09:06 Result is kept for 500 seconds 294s 03:09:06 default: tests.fixtures.do_nothing() (7e7c4dd4-f9b6-4800-871a-3d8882ea4662) 294s 03:09:06 Successfully completed tests.fixtures.do_nothing() job in 0:00:00.000500s on worker w1 294s 03:09:06 default: Job OK (7e7c4dd4-f9b6-4800-871a-3d8882ea4662) 294s 03:09:06 Result is kept for 500 seconds 294s 03:09:06 Worker w1: done, quitting 294s 03:09:06 Worker w1: unsubscribing from channel rq:pubsub:w1 294s .03:09:06 Enqueued job do_nothing to queue default 294s 03:09:06 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 294s ........ 294s tests/test_fixtures.py .. 295s tests/test_group.py .....03:09:07 Enqueued job do_nothing to queue default 295s 03:09:07 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 296s 03:09:08 Enqueued job do_nothing to queue default 296s 03:09:08 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 297s .03:09:09 Enqueued job do_nothing to queue default 297s 03:09:09 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 299s 03:09:10 Enqueued job do_nothing to queue default 299s 03:09:10 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 299s .03:09:11 Enqueued job do_nothing to queue default 299s 03:09:11 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 300s 03:09:12 Enqueued job do_nothing to queue default 300s 03:09:12 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 300s ......... 300s tests/test_helpers.py . 300s tests/test_intermediate_queue.py ........ 301s tests/test_job.py 03:09:12 Worker 1151dff1f17c48dbb0baa65f55f986ad: started with PID 2542, version 2.6.1 301s 03:09:12 Worker 1151dff1f17c48dbb0baa65f55f986ad: subscribing to channel rq:pubsub:1151dff1f17c48dbb0baa65f55f986ad 301s 03:09:12 *** Listening on test_blocking_queue... 301s 03:09:12 Worker 1151dff1f17c48dbb0baa65f55f986ad: cleaning registries for queue: test_blocking_queue 301s 03:09:12 test_blocking_queue: tests.fixtures.long_running_job(2) (90d3876a-d7c9-4805-8b3c-2dcd7dbc0b80) 301s 03:09:13 Enqueued job do_nothing to queue default 301s 03:09:13 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 302s 03:09:14 Enqueued job do_nothing to queue default 302s 03:09:14 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 303s 03:09:14 Successfully completed tests.fixtures.long_running_job(2) job in 0:00:02.001158s on worker 1151dff1f17c48dbb0baa65f55f986ad 303s 03:09:14 test_blocking_queue: Job OK (90d3876a-d7c9-4805-8b3c-2dcd7dbc0b80) 303s 03:09:14 Result is kept for 500 seconds 303s ..03:09:14 Worker 1151dff1f17c48dbb0baa65f55f986ad: done, quitting 303s 03:09:14 Worker 1151dff1f17c48dbb0baa65f55f986ad: unsubscribing from channel rq:pubsub:1151dff1f17c48dbb0baa65f55f986ad 303s ..............03:09:15 Enqueued job do_nothing to queue default 303s 03:09:15 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 304s F.............................03:09:16 Enqueued job do_nothing to queue default 304s 03:09:16 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 305s 03:09:17 Enqueued job do_nothing to queue default 305s 03:09:17 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 306s ............ 306s tests/test_job_dependency.py ...# Post enqueue {b'b3c1cb9e-2e52-4aac-8fae-503c60d22df9'} 306s # Post work {b'b3c1cb9e-2e52-4aac-8fae-503c60d22df9'} 306s # Pre cancel {b'b3c1cb9e-2e52-4aac-8fae-503c60d22df9'} 306s #Post cancel set() 306s ............03:09:18 Enqueued job do_nothing to queue default 306s 03:09:18 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 306s 03:09:18 Worker w1: started with PID 2549, version 2.6.1 306s 03:09:18 Worker w1: subscribing to channel rq:pubsub:w1 306s 03:09:18 *** Listening on default... 306s 03:09:18 Worker w1: cleaning registries for queue: default 306s 03:09:18 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) 307s 03:09:18 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.502879s on worker w1 307s 03:09:18 default: Job OK (slow_1) 307s 03:09:18 Result is kept for 500 seconds 307s 03:09:18 default: tests.fixtures.rpush('test_job:job_order', 'A', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True) (daa51101-bd0c-4843-9ae5-ff7d01e37123) 307s 03:09:18 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.002044s on worker w1 307s 03:09:18 default: Job OK (daa51101-bd0c-4843-9ae5-ff7d01e37123) 307s 03:09:18 Result is kept for 500 seconds 307s 03:09:18 default: tests.fixtures.rpush('test_job:job_order', 'B', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True) (45940810-67bb-415b-8ea9-98fbb63aa3d3) 307s 03:09:18 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.001838s on worker w1 307s 03:09:18 default: Job OK (45940810-67bb-415b-8ea9-98fbb63aa3d3) 307s 03:09:18 Result is kept for 500 seconds 307s 03:09:18 default: tests.fixtures.do_nothing() (7a1d2638-94a0-4b7c-90d3-7ce4f7de0839) 307s 03:09:18 Successfully completed tests.fixtures.do_nothing() job in 0:00:00.000466s on worker w1 307s 03:09:18 default: Job OK (7a1d2638-94a0-4b7c-90d3-7ce4f7de0839) 307s 03:09:18 Result is kept for 500 seconds 307s 03:09:18 Worker w1: done, quitting 307s 03:09:18 Worker w1: unsubscribing from channel rq:pubsub:w1 307s 03:09:19 Enqueued job do_nothing to queue default 307s 03:09:19 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 308s F03:09:20 Enqueued job do_nothing to queue default 308s 03:09:20 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 308s 03:09:20 Worker w1: started with PID 2557, version 2.6.1 308s 03:09:20 Worker w1: subscribing to channel rq:pubsub:w1 308s 03:09:20 *** Listening on default... 308s 03:09:20 Worker w1: cleaning registries for queue: default 308s 03:09:20 default: tests.fixtures.rpush('test_job:job_order', 'slow', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True, 0.5) (slow_job) 309s 03:09:20 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.502310s on worker w1 309s 03:09:20 default: Job OK (slow_job) 309s 03:09:20 Result is kept for 500 seconds 309s 03:09:20 Worker w1: done, quitting 309s 03:09:20 Worker w1: unsubscribing from channel rq:pubsub:w1 309s 03:09:21 Enqueued job do_nothing to queue default 309s 03:09:21 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 310s F......... 310s tests/test_queue.py ....................................................... 310s tests/test_registry.py ..........................03:09:22 Enqueued job do_nothing to queue default 310s 03:09:22 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 310s ............. 310s tests/test_repeat.py ......... 311s tests/test_results.py 03:09:23 Enqueued job do_nothing to queue default 311s 03:09:23 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 312s ............. 312s tests/test_retry.py ........... 312s tests/test_scheduler.py ...............03:09:24 Enqueued job do_nothing to queue default 312s 03:09:24 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 313s 03:09:25 Enqueued job do_nothing to queue default 313s 03:09:25 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 314s 03:09:26 Enqueued job do_nothing to queue default 314s 03:09:26 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 315s 03:09:27 Enqueued job do_nothing to queue default 315s 03:09:27 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 316s 03:09:28 Enqueued job do_nothing to queue default 316s 03:09:28 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 317s 03:09:29 Enqueued job do_nothing to queue default 317s 03:09:29 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 318s F.03:09:30 Enqueued job do_nothing to queue default 318s 03:09:30 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 319s 03:09:31 Enqueued job do_nothing to queue default 319s 03:09:31 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 320s 03:09:32 Enqueued job do_nothing to queue default 320s 03:09:32 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 321s 03:09:33 Enqueued job do_nothing to queue default 321s 03:09:33 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 322s 03:09:34 Enqueued job do_nothing to queue default 322s 03:09:34 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 323s 03:09:35 Enqueued job do_nothing to queue default 323s 03:09:35 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 324s 03:09:36 Enqueued job do_nothing to queue default 324s 03:09:36 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 325s 03:09:37 Enqueued job do_nothing to queue default 325s 03:09:37 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 325s .s...... 325s tests/test_serializers.py . 325s tests/test_spawn_worker.py Worker 85c4c788e3db42a6bca9b42bb86e8ab7: job 88f1c868-f180-4ff8-ac72-feb83f456e9c: exception raised while executing (tests.fixtures.div_by_zero) 325s Traceback (most recent call last): 325s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 325s return_value = job.perform() 325s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 325s self._result = self._execute() 325s ~~~~~~~~~~~~~^^ 325s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 325s result = self.func(*self.args, **self.kwargs) 325s TypeError: div_by_zero() missing 1 required positional argument: 'x' 325s 326s .ss 326s tests/test_timeouts.py .03:09:38 Enqueued job do_nothing to queue default 326s 03:09:38 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 327s 03:09:39 Enqueued job do_nothing to queue default 327s 03:09:39 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 328s 03:09:40 Enqueued job do_nothing to queue default 328s 03:09:40 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 329s 03:09:41 Enqueued job do_nothing to queue default 329s 03:09:41 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 330s 03:09:42 Enqueued job do_nothing to queue default 330s 03:09:42 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 331s . 331s tests/test_utils.py ......................... 331s tests/test_worker.py 03:09:43 Enqueued job do_nothing to queue default 331s 03:09:43 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 332s 03:09:44 Enqueued job do_nothing to queue default 332s 03:09:44 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 333s 03:09:45 Enqueued job do_nothing to queue default 333s 03:09:45 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 334s 03:09:46 Enqueued job do_nothing to queue default 334s 03:09:46 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 335s 03:09:47 Enqueued job do_nothing to queue default 335s 03:09:47 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 336s .03:09:48 Enqueued job do_nothing to queue default 336s 03:09:48 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 337s 03:09:49 Enqueued job do_nothing to queue default 337s 03:09:49 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 338s 03:09:50 Enqueued job do_nothing to queue default 338s 03:09:50 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 339s 03:09:51 Enqueued job do_nothing to queue default 339s 03:09:51 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 340s 03:09:52 Enqueued job do_nothing to queue default 340s 03:09:52 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 341s .03:09:53 Enqueued job do_nothing to queue default 341s 03:09:53 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 342s 03:09:54 Enqueued job do_nothing to queue default 342s 03:09:54 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 343s 03:09:55 Enqueued job do_nothing to queue default 343s 03:09:55 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 344s 03:09:56 Enqueued job do_nothing to queue default 344s 03:09:56 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 345s 03:09:57 Enqueued job do_nothing to queue default 345s 03:09:57 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 346s ............03:09:58 Enqueued job do_nothing to queue default 346s 03:09:58 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 347s ........ss..03:09:59 Enqueued job do_nothing to queue default 347s 03:09:59 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 348s .........s............s03:10:00 Enqueued job do_nothing to queue default 348s 03:10:00 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 349s 03:10:01 Enqueued job do_nothing to queue default 349s 03:10:01 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 350s 03:10:02 Enqueued job do_nothing to queue default 350s 03:10:02 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 351s 03:10:03 Enqueued job do_nothing to queue default 351s 03:10:03 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 352s .s03:10:04 Enqueued job do_nothing to queue default 352s 03:10:04 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 353s ...................sssss03:10:04 Worker 57ef081e22e34c08a669fe747cabe87e: started with PID 2748, version 2.6.1 353s 03:10:04 Worker 57ef081e22e34c08a669fe747cabe87e: subscribing to channel rq:pubsub:57ef081e22e34c08a669fe747cabe87e 353s 03:10:04 *** Listening on default... 353s 03:10:04 Worker 57ef081e22e34c08a669fe747cabe87e: cleaning registries for queue: default 353s 03:10:04 default: tests.fixtures.access_self() (07b4b2c2-d7c8-4367-afde-d4b3c0d4d90c) 353s 03:10:05 Successfully completed tests.fixtures.access_self() job in 0:00:00.148636s on worker 57ef081e22e34c08a669fe747cabe87e 353s 03:10:05 default: Job OK (07b4b2c2-d7c8-4367-afde-d4b3c0d4d90c) 353s 03:10:05 Result is kept for 500 seconds 353s 03:10:05 Worker 57ef081e22e34c08a669fe747cabe87e: done, quitting 353s 03:10:05 Worker 57ef081e22e34c08a669fe747cabe87e: unsubscribing from channel rq:pubsub:57ef081e22e34c08a669fe747cabe87e 353s .03:10:05 Enqueued job do_nothing to queue default 353s 03:10:05 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 353s 03:10:05 Worker 3387ac3168cd41a28ec131c0973b89e7: started with PID 2752, version 2.6.1 353s 03:10:05 Worker 3387ac3168cd41a28ec131c0973b89e7: subscribing to channel rq:pubsub:3387ac3168cd41a28ec131c0973b89e7 353s 03:10:05 *** Listening on default... 353s 03:10:05 Worker 3387ac3168cd41a28ec131c0973b89e7: cleaning registries for queue: default 353s 03:10:05 Worker 3387ac3168cd41a28ec131c0973b89e7: done, quitting 353s 03:10:05 Worker 3387ac3168cd41a28ec131c0973b89e7: unsubscribing from channel rq:pubsub:3387ac3168cd41a28ec131c0973b89e7 354s 03:10:06 Enqueued job do_nothing to queue default 354s 03:10:06 CronScheduler autopkgtest:2492:2f6db1: heartbeat failed - scheduler not found in registry 355s .03:10:06 Worker ce25fdd81b07469295a54044bd14c019: started with PID 2756, version 2.6.1 355s 03:10:06 Worker ce25fdd81b07469295a54044bd14c019: subscribing to channel rq:pubsub:ce25fdd81b07469295a54044bd14c019 355s 03:10:06 *** Listening on default... 355s 03:10:06 Worker ce25fdd81b07469295a54044bd14c019: cleaning registries for queue: default 355s 03:10:06 default: tests.test_worker.schedule_access_self() (feaa039e-c100-44e6-8c35-d9337f88b88b) 355s 03:10:06 Successfully completed tests.test_worker.schedule_access_self() job in 0:00:00.172208s on worker ce25fdd81b07469295a54044bd14c019 355s 03:10:06 default: Job OK (feaa039e-c100-44e6-8c35-d9337f88b88b) 355s 03:10:06 Result is kept for 500 seconds 355s 03:10:06 Worker ce25fdd81b07469295a54044bd14c019: done, quitting 355s 03:10:06 Worker ce25fdd81b07469295a54044bd14c019: unsubscribing from channel rq:pubsub:ce25fdd81b07469295a54044bd14c019 355s .sReal-time signal 0 355s autopkgtest [03:10:07]: test smoke: -----------------------] 356s smoke FAIL non-zero exit status 162 356s autopkgtest [03:10:08]: test smoke: - - - - - - - - - - results - - - - - - - - - - 356s autopkgtest [03:10:08]: test autodep8-python3: preparing testbed 383s Creating nova instance adt-resolute-arm64-python-rq-20260123-030411-juju-7f2275-prod-proposed-migration-environment-20-cc31e4c9-88f4-4602-ac97-22f6f1c381c6 from image adt/ubuntu-resolute-arm64-server-20260122.img (UUID ed27afc7-5f46-4e8b-ad73-864280a0ddbb)... 453s autopkgtest [03:11:45]: testbed dpkg architecture: arm64 453s autopkgtest [03:11:45]: testbed apt version: 3.1.12 453s autopkgtest [03:11:45]: @@@@@@@@@@@@@@@@@@@@ test bed setup 453s autopkgtest [03:11:45]: testbed release detected to be: resolute 454s autopkgtest [03:11:46]: updating testbed package index (apt update) 454s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 454s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 454s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 454s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 454s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 455s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [23.6 kB] 455s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [95.1 kB] 455s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1181 kB] 455s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 Packages [119 kB] 455s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 c-n-f Metadata [3324 B] 455s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 Packages [7876 B] 455s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 c-n-f Metadata [304 B] 455s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 Packages [991 kB] 455s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 c-n-f Metadata [20.8 kB] 455s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 Packages [10.7 kB] 455s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 c-n-f Metadata [456 B] 458s Fetched 2581 kB in 1s (3863 kB/s) 458s Reading package lists... 459s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 459s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 459s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 459s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 460s Reading package lists... 460s Reading package lists... 460s Building dependency tree... 460s Reading state information... 461s Calculating upgrade... 461s The following packages were automatically installed and are no longer required: 461s python3.13 python3.13-minimal 461s Use 'sudo apt autoremove' to remove them. 461s The following NEW packages will be installed: 461s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 461s The following packages will be upgraded: 461s apt dhcpcd-base libapt-pkg7.0 libplymouth5 libpython3-stdlib plymouth 461s plymouth-theme-ubuntu-text python3 python3-gdbm python3-minimal 462s 10 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 462s Need to get 9695 kB of archives. 462s After this operation, 26.0 MB of additional disk space will be used. 462s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 libpython3.14-minimal arm64 3.14.2-1 [917 kB] 462s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 python3.14-minimal arm64 3.14.2-1 [2548 kB] 462s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 python3-gdbm arm64 3.14.2-1 [11.1 kB] 462s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 python3-minimal arm64 3.14.2-1 [28.2 kB] 462s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 python3 arm64 3.14.2-1 [22.9 kB] 462s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 libpython3.14-stdlib arm64 3.14.2-1 [2351 kB] 462s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 python3.14 arm64 3.14.2-1 [816 kB] 462s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 libpython3-stdlib arm64 3.14.2-1 [10.9 kB] 463s Get:9 http://ftpmaster.internal/ubuntu resolute/main arm64 libapt-pkg7.0 arm64 3.1.13 [1067 kB] 463s Get:10 http://ftpmaster.internal/ubuntu resolute/main arm64 apt arm64 3.1.13 [1412 kB] 463s Get:11 http://ftpmaster.internal/ubuntu resolute/main arm64 dhcpcd-base arm64 1:10.3.0-7 [223 kB] 463s Get:12 http://ftpmaster.internal/ubuntu resolute/main arm64 libplymouth5 arm64 24.004.60+git20250831.4a3c171d-0ubuntu4 [141 kB] 463s Get:13 http://ftpmaster.internal/ubuntu resolute/main arm64 plymouth-theme-ubuntu-text arm64 24.004.60+git20250831.4a3c171d-0ubuntu4 [10.0 kB] 463s Get:14 http://ftpmaster.internal/ubuntu resolute/main arm64 plymouth arm64 24.004.60+git20250831.4a3c171d-0ubuntu4 [138 kB] 463s dpkg-preconfigure: unable to re-open stdin: No such file or directory 463s Fetched 9695 kB in 1s (7234 kB/s) 463s Selecting previously unselected package libpython3.14-minimal:arm64. 464s (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 ... 135223 files and directories currently installed.) 464s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_arm64.deb ... 464s Unpacking libpython3.14-minimal:arm64 (3.14.2-1) ... 464s Selecting previously unselected package python3.14-minimal. 464s Preparing to unpack .../python3.14-minimal_3.14.2-1_arm64.deb ... 464s Unpacking python3.14-minimal (3.14.2-1) ... 464s Preparing to unpack .../python3-gdbm_3.14.2-1_arm64.deb ... 464s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-1) ... 464s Setting up libpython3.14-minimal:arm64 (3.14.2-1) ... 464s Setting up python3.14-minimal (3.14.2-1) ... 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 ... 135565 files and directories currently installed.) 465s Preparing to unpack .../python3-minimal_3.14.2-1_arm64.deb ... 465s Unpacking python3-minimal (3.14.2-1) over (3.13.7-1) ... 465s Setting up python3-minimal (3.14.2-1) ... 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 ... 135565 files and directories currently installed.) 465s Preparing to unpack .../0-python3_3.14.2-1_arm64.deb ... 465s running python pre-rtupdate hooks for python3.14... 465s Unpacking python3 (3.14.2-1) over (3.13.7-1) ... 465s Selecting previously unselected package libpython3.14-stdlib:arm64. 465s Preparing to unpack .../1-libpython3.14-stdlib_3.14.2-1_arm64.deb ... 465s Unpacking libpython3.14-stdlib:arm64 (3.14.2-1) ... 465s Selecting previously unselected package python3.14. 465s Preparing to unpack .../2-python3.14_3.14.2-1_arm64.deb ... 465s Unpacking python3.14 (3.14.2-1) ... 465s Preparing to unpack .../3-libpython3-stdlib_3.14.2-1_arm64.deb ... 465s Unpacking libpython3-stdlib:arm64 (3.14.2-1) over (3.13.7-1) ... 466s Preparing to unpack .../4-libapt-pkg7.0_3.1.13_arm64.deb ... 466s Unpacking libapt-pkg7.0:arm64 (3.1.13) over (3.1.12) ... 466s Preparing to unpack .../5-apt_3.1.13_arm64.deb ... 466s Unpacking apt (3.1.13) over (3.1.12) ... 466s Preparing to unpack .../6-dhcpcd-base_1%3a10.3.0-7_arm64.deb ... 466s Unpacking dhcpcd-base (1:10.3.0-7) over (1:10.3.0-3) ... 466s Preparing to unpack .../7-libplymouth5_24.004.60+git20250831.4a3c171d-0ubuntu4_arm64.deb ... 466s Unpacking libplymouth5:arm64 (24.004.60+git20250831.4a3c171d-0ubuntu4) over (24.004.60+git20250831.4a3c171d-0ubuntu3) ... 466s Preparing to unpack .../8-plymouth-theme-ubuntu-text_24.004.60+git20250831.4a3c171d-0ubuntu4_arm64.deb ... 466s Unpacking plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu4) over (24.004.60+git20250831.4a3c171d-0ubuntu3) ... 466s Preparing to unpack .../9-plymouth_24.004.60+git20250831.4a3c171d-0ubuntu4_arm64.deb ... 466s Unpacking plymouth (24.004.60+git20250831.4a3c171d-0ubuntu4) over (24.004.60+git20250831.4a3c171d-0ubuntu3) ... 466s Setting up libpython3.14-stdlib:arm64 (3.14.2-1) ... 466s Setting up dhcpcd-base (1:10.3.0-7) ... 466s Setting up libplymouth5:arm64 (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 466s Setting up libapt-pkg7.0:arm64 (3.1.13) ... 466s Setting up python3.14 (3.14.2-1) ... 468s Setting up libpython3-stdlib:arm64 (3.14.2-1) ... 468s Setting up apt (3.1.13) ... 468s Setting up python3-gdbm (3.14.2-1) ... 468s Setting up plymouth (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 468s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 468s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 469s Setting up python3 (3.14.2-1) ... 469s running python rtupdate hooks for python3.14... 469s running python post-rtupdate hooks for python3.14... 469s Processing triggers for libc-bin (2.42-2ubuntu4) ... 469s Processing triggers for systemd (259-1ubuntu2) ... 469s Processing triggers for man-db (2.13.1-1) ... 471s Processing triggers for initramfs-tools (0.150ubuntu7) ... 471s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 486s System running in EFI mode, skipping. 486s Setting up plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 486s Processing triggers for initramfs-tools (0.150ubuntu7) ... 486s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 499s System running in EFI mode, skipping. 499s autopkgtest [03:12:31]: upgrading testbed (apt dist-upgrade and autopurge) 500s Reading package lists... 500s Building dependency tree... 500s Reading state information... 501s Calculating upgrade... 501s The following packages were automatically installed and are no longer required: 501s python3.13 python3.13-minimal 501s Use 'sudo apt autoremove' to remove them. 501s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 501s Reading package lists... 502s Building dependency tree... 502s Reading state information... 502s Solving dependencies... 502s The following packages will be REMOVED: 502s python3.13* python3.13-minimal* 502s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 502s After this operation, 7766 kB disk space will be freed. 502s (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 ... 135992 files and directories currently installed.) 502s Removing python3.13 (3.13.11-1) ... 503s Removing python3.13-minimal (3.13.11-1) ... 503s Processing triggers for man-db (2.13.1-1) ... 504s Processing triggers for systemd (259-1ubuntu2) ... 504s (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 ... 135965 files and directories currently installed.) 504s Purging configuration files for python3.13-minimal (3.13.11-1) ... 504s autopkgtest [03:12:36]: rebooting testbed after setup commands that affected boot 545s Reading package lists... 545s Building dependency tree... 545s Reading state information... 545s Solving dependencies... 545s The following NEW packages will be installed: 545s python3-all python3-click python3-croniter python3-redis python3-rq 545s python3.13 python3.13-minimal 546s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 546s Need to get 3521 kB of archives. 546s After this operation, 10.2 MB of additional disk space will be used. 546s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 python3.13-minimal arm64 3.13.11-1 [2346 kB] 546s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 python3.13 arm64 3.13.11-1 [758 kB] 546s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 python3-all arm64 3.14.2-1 [888 B] 546s Get:4 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-click all 8.2.0+0.really.8.1.8-1build1 [80.2 kB] 546s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 python3-croniter all 6.1~rc1-0ubuntu1 [21.7 kB] 546s Get:6 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-redis all 6.4.0-1 [228 kB] 546s Get:7 http://ftpmaster.internal/ubuntu resolute/universe arm64 python3-rq all 2.6.1-1 [86.3 kB] 546s Fetched 3521 kB in 0s (10.8 MB/s) 546s Selecting previously unselected package python3.13-minimal. 546s (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 ... 135967 files and directories currently installed.) 546s Preparing to unpack .../0-python3.13-minimal_3.13.11-1_arm64.deb ... 546s Unpacking python3.13-minimal (3.13.11-1) ... 547s Selecting previously unselected package python3.13. 547s Preparing to unpack .../1-python3.13_3.13.11-1_arm64.deb ... 547s Unpacking python3.13 (3.13.11-1) ... 547s Selecting previously unselected package python3-all. 547s Preparing to unpack .../2-python3-all_3.14.2-1_arm64.deb ... 547s Unpacking python3-all (3.14.2-1) ... 547s Selecting previously unselected package python3-click. 547s Preparing to unpack .../3-python3-click_8.2.0+0.really.8.1.8-1build1_all.deb ... 547s Unpacking python3-click (8.2.0+0.really.8.1.8-1build1) ... 547s Selecting previously unselected package python3-croniter. 547s Preparing to unpack .../4-python3-croniter_6.1~rc1-0ubuntu1_all.deb ... 547s Unpacking python3-croniter (6.1~rc1-0ubuntu1) ... 547s Selecting previously unselected package python3-redis. 547s Preparing to unpack .../5-python3-redis_6.4.0-1_all.deb ... 547s Unpacking python3-redis (6.4.0-1) ... 547s Selecting previously unselected package python3-rq. 547s Preparing to unpack .../6-python3-rq_2.6.1-1_all.deb ... 547s Unpacking python3-rq (2.6.1-1) ... 547s Setting up python3-croniter (6.1~rc1-0ubuntu1) ... 547s Setting up python3-click (8.2.0+0.really.8.1.8-1build1) ... 547s Setting up python3-redis (6.4.0-1) ... 548s Setting up python3.13-minimal (3.13.11-1) ... 549s Setting up python3.13 (3.13.11-1) ... 550s Setting up python3-all (3.14.2-1) ... 550s Setting up python3-rq (2.6.1-1) ... 551s Processing triggers for man-db (2.13.1-1) ... 551s Processing triggers for systemd (259-1ubuntu2) ... 553s autopkgtest [03:13:25]: 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 553s autopkgtest [03:13:25]: test autodep8-python3: [----------------------- 553s Testing with python3.13: 553s 553s Testing with python3.14: 553s 554s autopkgtest [03:13:26]: test autodep8-python3: -----------------------] 554s autodep8-python3 PASS (superficial) 554s autopkgtest [03:13:26]: test autodep8-python3: - - - - - - - - - - results - - - - - - - - - - 555s autopkgtest [03:13:27]: @@@@@@@@@@@@@@@@@@@@ summary 555s smoke FAIL non-zero exit status 162 555s autodep8-python3 PASS (superficial)