0s autopkgtest [22:21:38]: starting date and time: 2026-01-22 22:21:38+0000 0s autopkgtest [22:21:38]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [22:21:38]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.xdcmiw10/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-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@sto01-ppc64el-20.secgroup --name adt-resolute-ppc64el-python-rq-20260122-222138-juju-7f2275-prod-proposed-migration-environment-20-ea9c6c84-bc51-4742-b2ad-e8cd975bc01d --image adt/ubuntu-resolute-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-autopkgtest-workers-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 5s Creating nova instance adt-resolute-ppc64el-python-rq-20260122-222138-juju-7f2275-prod-proposed-migration-environment-20-ea9c6c84-bc51-4742-b2ad-e8cd975bc01d from image adt/ubuntu-resolute-ppc64el-server-20260122.img (UUID 987e5803-394f-43eb-a969-151b6b75a57a)... 52s autopkgtest [22:22:30]: testbed dpkg architecture: ppc64el 53s autopkgtest [22:22:31]: testbed apt version: 3.1.12 53s autopkgtest [22:22:31]: @@@@@@@@@@@@@@@@@@@@ test bed setup 53s autopkgtest [22:22:31]: testbed release detected to be: None 54s autopkgtest [22:22:32]: updating testbed package index (apt update) 54s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 54s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 54s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 54s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 54s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [81.6 kB] 54s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1011 kB] 54s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 54s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [22.2 kB] 54s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el Packages [100 kB] 54s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el Packages [703 kB] 54s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse ppc64el Packages [5552 B] 55s Fetched 2050 kB in 1s (2809 kB/s) 55s Reading package lists... 56s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 56s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 56s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 56s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 57s Reading package lists... 57s Reading package lists... 57s Building dependency tree... 57s Reading state information... 57s Calculating upgrade... 57s The following packages were automatically installed and are no longer required: 57s python3.13 python3.13-minimal 57s Use 'sudo apt autoremove' to remove them. 57s The following NEW packages will be installed: 57s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 57s The following packages will be upgraded: 57s libplymouth5 libpython3-stdlib plymouth plymouth-theme-ubuntu-text python3 57s python3-gdbm python3-minimal 57s 7 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 57s Need to get 7312 kB of archives. 57s After this operation, 27.7 MB of additional disk space will be used. 57s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el libpython3.14-minimal ppc64el 3.14.2-1 [921 kB] 57s Get:2 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.14-minimal ppc64el 3.14.2-1 [2712 kB] 57s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el python3-gdbm ppc64el 3.14.2-1 [11.1 kB] 57s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el python3-minimal ppc64el 3.14.2-1 [28.2 kB] 57s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el python3 ppc64el 3.14.2-1 [22.9 kB] 58s Get:6 http://ftpmaster.internal/ubuntu resolute/main ppc64el libpython3.14-stdlib ppc64el 3.14.2-1 [2444 kB] 58s Get:7 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.14 ppc64el 3.14.2-1 [816 kB] 58s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libpython3-stdlib ppc64el 3.14.2-1 [10.9 kB] 58s Get:9 http://ftpmaster.internal/ubuntu resolute/main ppc64el libplymouth5 ppc64el 24.004.60+git20250831.4a3c171d-0ubuntu4 [174 kB] 58s Get:10 http://ftpmaster.internal/ubuntu resolute/main ppc64el plymouth-theme-ubuntu-text ppc64el 24.004.60+git20250831.4a3c171d-0ubuntu4 [11.2 kB] 58s Get:11 http://ftpmaster.internal/ubuntu resolute/main ppc64el plymouth ppc64el 24.004.60+git20250831.4a3c171d-0ubuntu4 [160 kB] 58s dpkg-preconfigure: unable to re-open stdin: No such file or directory 58s Fetched 7312 kB in 0s (18.0 MB/s) 58s Selecting previously unselected package libpython3.14-minimal:ppc64el. 59s (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 ... 120824 files and directories currently installed.) 59s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_ppc64el.deb ... 59s Unpacking libpython3.14-minimal:ppc64el (3.14.2-1) ... 59s Selecting previously unselected package python3.14-minimal. 59s Preparing to unpack .../python3.14-minimal_3.14.2-1_ppc64el.deb ... 59s Unpacking python3.14-minimal (3.14.2-1) ... 59s Preparing to unpack .../python3-gdbm_3.14.2-1_ppc64el.deb ... 59s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-1) ... 59s Setting up libpython3.14-minimal:ppc64el (3.14.2-1) ... 59s Setting up python3.14-minimal (3.14.2-1) ... 60s (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 ... 121166 files and directories currently installed.) 60s Preparing to unpack .../python3-minimal_3.14.2-1_ppc64el.deb ... 60s Unpacking python3-minimal (3.14.2-1) over (3.13.7-1) ... 60s Setting up python3-minimal (3.14.2-1) ... 61s (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 ... 121166 files and directories currently installed.) 61s Preparing to unpack .../0-python3_3.14.2-1_ppc64el.deb ... 61s running python pre-rtupdate hooks for python3.14... 61s Unpacking python3 (3.14.2-1) over (3.13.7-1) ... 61s Selecting previously unselected package libpython3.14-stdlib:ppc64el. 61s Preparing to unpack .../1-libpython3.14-stdlib_3.14.2-1_ppc64el.deb ... 61s Unpacking libpython3.14-stdlib:ppc64el (3.14.2-1) ... 61s Selecting previously unselected package python3.14. 61s Preparing to unpack .../2-python3.14_3.14.2-1_ppc64el.deb ... 61s Unpacking python3.14 (3.14.2-1) ... 61s Preparing to unpack .../3-libpython3-stdlib_3.14.2-1_ppc64el.deb ... 61s Unpacking libpython3-stdlib:ppc64el (3.14.2-1) over (3.13.7-1) ... 61s Preparing to unpack .../4-libplymouth5_24.004.60+git20250831.4a3c171d-0ubuntu4_ppc64el.deb ... 61s Unpacking libplymouth5:ppc64el (24.004.60+git20250831.4a3c171d-0ubuntu4) over (24.004.60+git20250831.4a3c171d-0ubuntu3) ... 61s Preparing to unpack .../5-plymouth-theme-ubuntu-text_24.004.60+git20250831.4a3c171d-0ubuntu4_ppc64el.deb ... 61s Unpacking plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu4) over (24.004.60+git20250831.4a3c171d-0ubuntu3) ... 61s Preparing to unpack .../6-plymouth_24.004.60+git20250831.4a3c171d-0ubuntu4_ppc64el.deb ... 62s Unpacking plymouth (24.004.60+git20250831.4a3c171d-0ubuntu4) over (24.004.60+git20250831.4a3c171d-0ubuntu3) ... 62s Setting up libpython3.14-stdlib:ppc64el (3.14.2-1) ... 62s Setting up libplymouth5:ppc64el (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 62s Setting up python3.14 (3.14.2-1) ... 63s Setting up libpython3-stdlib:ppc64el (3.14.2-1) ... 63s Setting up python3-gdbm (3.14.2-1) ... 63s Setting up plymouth (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 63s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 63s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 64s Setting up python3 (3.14.2-1) ... 64s running python rtupdate hooks for python3.14... 64s running python post-rtupdate hooks for python3.14... 64s Processing triggers for libc-bin (2.42-2ubuntu4) ... 64s Processing triggers for systemd (259-1ubuntu2) ... 64s Processing triggers for man-db (2.13.1-1) ... 65s Processing triggers for initramfs-tools (0.150ubuntu7) ... 65s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 73s Setting up plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 73s Processing triggers for initramfs-tools (0.150ubuntu7) ... 73s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 79s autopkgtest [22:22:57]: upgrading testbed (apt dist-upgrade and autopurge) 79s Reading package lists... 79s Building dependency tree... 79s Reading state information... 79s Calculating upgrade... 79s The following packages were automatically installed and are no longer required: 79s python3.13 python3.13-minimal 79s Use 'sudo apt autoremove' to remove them. 79s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 80s Reading package lists... 80s Building dependency tree... 80s Reading state information... 80s Solving dependencies... 80s The following packages will be REMOVED: 80s python3.13* python3.13-minimal* 80s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 80s After this operation, 9077 kB disk space will be freed. 80s (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 ... 121596 files and directories currently installed.) 80s Removing python3.13 (3.13.11-1) ... 80s Removing python3.13-minimal (3.13.11-1) ... 82s Processing triggers for man-db (2.13.1-1) ... 82s Processing triggers for systemd (259-1ubuntu2) ... 82s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 121569 files and directories currently installed.) 82s Purging configuration files for python3.13-minimal (3.13.11-1) ... 83s autopkgtest [22:23:01]: rebooting testbed after setup commands that affected boot 123s autopkgtest [22:23:41]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:45:54 UTC 2026 126s autopkgtest [22:23:44]: @@@@@@@@@@@@@@@@@@@@ apt-source python-rq 127s Get:1 http://ftpmaster.internal/ubuntu resolute/universe python-rq 2.6.1-1 (dsc) [2206 B] 127s Get:2 http://ftpmaster.internal/ubuntu resolute/universe python-rq 2.6.1-1 (tar) [680 kB] 127s Get:3 http://ftpmaster.internal/ubuntu resolute/universe python-rq 2.6.1-1 (diff) [7124 B] 128s gpgv: Signature made Sun Nov 30 07:46:24 2025 UTC 128s gpgv: using RSA key B70DFC6F134FECFC011E62AA83016014251D1DB0 128s gpgv: issuer "c.schoenert@t-online.de" 128s gpgv: Can't check signature: No public key 128s dpkg-source: warning: cannot verify inline signature for ./python-rq_2.6.1-1.dsc: no acceptable signature found 128s autopkgtest [22:23:46]: testing package python-rq version 2.6.1-1 128s autopkgtest [22:23:46]: build not needed 128s autopkgtest [22:23:46]: test smoke: preparing testbed 129s Reading package lists... 129s Building dependency tree... 129s Reading state information... 129s Solving dependencies... 129s The following NEW packages will be installed: 129s libjs-jquery libjs-jquery-hotkeys libjs-jquery-isonscreen 129s libjs-jquery-metadata libjs-jquery-tablesorter 129s libjs-jquery-throttle-debounce liblzf1 python3-all python3-click 129s python3-coverage python3-croniter python3-iniconfig python3-pluggy 129s python3-psutil python3-pytest python3-pytest-cov python3-redis python3-rq 129s python3.13 python3.13-minimal redis-server redis-tools 129s 0 upgraded, 22 newly installed, 0 to remove and 0 not upgraded. 129s Need to get 6826 kB of archives. 129s After this operation, 28.1 MB of additional disk space will be used. 129s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.13-minimal ppc64el 3.13.11-1 [2571 kB] 130s Get:2 http://ftpmaster.internal/ubuntu resolute/universe ppc64el liblzf1 ppc64el 3.6-4build1 [8064 B] 130s Get:3 http://ftpmaster.internal/ubuntu resolute/universe ppc64el redis-tools ppc64el 5:8.0.5-1 [1790 kB] 130s Get:4 http://ftpmaster.internal/ubuntu resolute/universe ppc64el redis-server ppc64el 5:8.0.5-1 [53.2 kB] 130s Get:5 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.13 ppc64el 3.13.11-1 [758 kB] 130s Get:6 http://ftpmaster.internal/ubuntu resolute/main ppc64el libjs-jquery all 3.7.1+dfsg+~3.5.33-1build1 [321 kB] 130s Get:7 http://ftpmaster.internal/ubuntu resolute/universe ppc64el libjs-jquery-metadata all 12-4build1 [6692 B] 130s Get:8 http://ftpmaster.internal/ubuntu resolute/universe ppc64el libjs-jquery-tablesorter all 1:2.31.3+dfsg1-5 [192 kB] 130s Get:9 http://ftpmaster.internal/ubuntu resolute/universe ppc64el libjs-jquery-throttle-debounce all 1.1+dfsg.1-2build1 [12.6 kB] 130s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el python3-all ppc64el 3.14.2-1 [890 B] 130s Get:11 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-click all 8.2.0+0.really.8.1.8-1build1 [80.2 kB] 130s Get:12 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-coverage ppc64el 7.8.2+dfsg1-1ubuntu1 [160 kB] 130s Get:13 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-croniter all 6.1~rc1-0ubuntu1 [21.7 kB] 130s Get:14 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-iniconfig all 2.1.0-2 [6962 B] 130s Get:15 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-pluggy all 1.6.0-2 [21.1 kB] 130s Get:16 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-psutil ppc64el 7.1.0-1ubuntu1 [192 kB] 130s Get:17 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-pytest all 9.0.2-2 [277 kB] 130s Get:18 http://ftpmaster.internal/ubuntu resolute/universe ppc64el libjs-jquery-hotkeys all 0.2.0-1 [13.3 kB] 130s Get:19 http://ftpmaster.internal/ubuntu resolute/universe ppc64el libjs-jquery-isonscreen all 1.2.0-2 [3814 B] 130s Get:20 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-pytest-cov all 5.0.0-1 [21.3 kB] 130s Get:21 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-redis all 6.4.0-1 [228 kB] 130s Get:22 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-rq all 2.6.1-1 [86.3 kB] 130s Fetched 6826 kB in 1s (6563 kB/s) 130s Selecting previously unselected package python3.13-minimal. 131s (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 ... 121571 files and directories currently installed.) 131s Preparing to unpack .../00-python3.13-minimal_3.13.11-1_ppc64el.deb ... 131s Unpacking python3.13-minimal (3.13.11-1) ... 131s Selecting previously unselected package liblzf1:ppc64el. 131s Preparing to unpack .../01-liblzf1_3.6-4build1_ppc64el.deb ... 131s Unpacking liblzf1:ppc64el (3.6-4build1) ... 131s Selecting previously unselected package redis-tools. 131s Preparing to unpack .../02-redis-tools_5%3a8.0.5-1_ppc64el.deb ... 131s Unpacking redis-tools (5:8.0.5-1) ... 131s Selecting previously unselected package redis-server. 131s Preparing to unpack .../03-redis-server_5%3a8.0.5-1_ppc64el.deb ... 131s Unpacking redis-server (5:8.0.5-1) ... 131s Selecting previously unselected package python3.13. 131s Preparing to unpack .../04-python3.13_3.13.11-1_ppc64el.deb ... 131s Unpacking python3.13 (3.13.11-1) ... 131s Selecting previously unselected package libjs-jquery. 131s Preparing to unpack .../05-libjs-jquery_3.7.1+dfsg+~3.5.33-1build1_all.deb ... 131s Unpacking libjs-jquery (3.7.1+dfsg+~3.5.33-1build1) ... 131s Selecting previously unselected package libjs-jquery-metadata. 131s Preparing to unpack .../06-libjs-jquery-metadata_12-4build1_all.deb ... 131s Unpacking libjs-jquery-metadata (12-4build1) ... 131s Selecting previously unselected package libjs-jquery-tablesorter. 131s Preparing to unpack .../07-libjs-jquery-tablesorter_1%3a2.31.3+dfsg1-5_all.deb ... 131s Unpacking libjs-jquery-tablesorter (1:2.31.3+dfsg1-5) ... 131s Selecting previously unselected package libjs-jquery-throttle-debounce. 131s Preparing to unpack .../08-libjs-jquery-throttle-debounce_1.1+dfsg.1-2build1_all.deb ... 131s Unpacking libjs-jquery-throttle-debounce (1.1+dfsg.1-2build1) ... 131s Selecting previously unselected package python3-all. 131s Preparing to unpack .../09-python3-all_3.14.2-1_ppc64el.deb ... 131s Unpacking python3-all (3.14.2-1) ... 131s Selecting previously unselected package python3-click. 131s Preparing to unpack .../10-python3-click_8.2.0+0.really.8.1.8-1build1_all.deb ... 131s Unpacking python3-click (8.2.0+0.really.8.1.8-1build1) ... 131s Selecting previously unselected package python3-coverage. 131s Preparing to unpack .../11-python3-coverage_7.8.2+dfsg1-1ubuntu1_ppc64el.deb ... 131s Unpacking python3-coverage (7.8.2+dfsg1-1ubuntu1) ... 131s Selecting previously unselected package python3-croniter. 131s Preparing to unpack .../12-python3-croniter_6.1~rc1-0ubuntu1_all.deb ... 131s Unpacking python3-croniter (6.1~rc1-0ubuntu1) ... 131s Selecting previously unselected package python3-iniconfig. 131s Preparing to unpack .../13-python3-iniconfig_2.1.0-2_all.deb ... 131s Unpacking python3-iniconfig (2.1.0-2) ... 131s Selecting previously unselected package python3-pluggy. 131s Preparing to unpack .../14-python3-pluggy_1.6.0-2_all.deb ... 131s Unpacking python3-pluggy (1.6.0-2) ... 131s Selecting previously unselected package python3-psutil. 131s Preparing to unpack .../15-python3-psutil_7.1.0-1ubuntu1_ppc64el.deb ... 131s Unpacking python3-psutil (7.1.0-1ubuntu1) ... 131s Selecting previously unselected package python3-pytest. 131s Preparing to unpack .../16-python3-pytest_9.0.2-2_all.deb ... 131s Unpacking python3-pytest (9.0.2-2) ... 131s Selecting previously unselected package libjs-jquery-hotkeys. 131s Preparing to unpack .../17-libjs-jquery-hotkeys_0.2.0-1_all.deb ... 131s Unpacking libjs-jquery-hotkeys (0.2.0-1) ... 131s Selecting previously unselected package libjs-jquery-isonscreen. 131s Preparing to unpack .../18-libjs-jquery-isonscreen_1.2.0-2_all.deb ... 131s Unpacking libjs-jquery-isonscreen (1.2.0-2) ... 131s Selecting previously unselected package python3-pytest-cov. 131s Preparing to unpack .../19-python3-pytest-cov_5.0.0-1_all.deb ... 131s Unpacking python3-pytest-cov (5.0.0-1) ... 131s Selecting previously unselected package python3-redis. 131s Preparing to unpack .../20-python3-redis_6.4.0-1_all.deb ... 131s Unpacking python3-redis (6.4.0-1) ... 131s Selecting previously unselected package python3-rq. 131s Preparing to unpack .../21-python3-rq_2.6.1-1_all.deb ... 131s Unpacking python3-rq (2.6.1-1) ... 131s Setting up python3-iniconfig (2.1.0-2) ... 131s Setting up python3-croniter (6.1~rc1-0ubuntu1) ... 131s Setting up libjs-jquery-throttle-debounce (1.1+dfsg.1-2build1) ... 131s Setting up python3-click (8.2.0+0.really.8.1.8-1build1) ... 132s Setting up python3-psutil (7.1.0-1ubuntu1) ... 132s Setting up liblzf1:ppc64el (3.6-4build1) ... 132s Setting up python3-redis (6.4.0-1) ... 133s Setting up python3-pluggy (1.6.0-2) ... 133s Setting up python3.13-minimal (3.13.11-1) ... 134s Setting up libjs-jquery (3.7.1+dfsg+~3.5.33-1build1) ... 134s Setting up libjs-jquery-hotkeys (0.2.0-1) ... 134s Setting up python3.13 (3.13.11-1) ... 135s Setting up python3-pytest (9.0.2-2) ... 136s Setting up python3-all (3.14.2-1) ... 136s Setting up python3-coverage (7.8.2+dfsg1-1ubuntu1) ... 136s Setting up python3-rq (2.6.1-1) ... 137s Setting up redis-tools (5:8.0.5-1) ... 137s Setting up libjs-jquery-metadata (12-4build1) ... 137s Setting up libjs-jquery-isonscreen (1.2.0-2) ... 137s Setting up libjs-jquery-tablesorter (1:2.31.3+dfsg1-5) ... 137s Setting up python3-pytest-cov (5.0.0-1) ... 137s Setting up redis-server (5:8.0.5-1) ... 138s Created symlink '/etc/systemd/system/redis.service' → '/usr/lib/systemd/system/redis-server.service'. 138s Created symlink '/etc/systemd/system/multi-user.target.wants/redis-server.service' → '/usr/lib/systemd/system/redis-server.service'. 138s Processing triggers for systemd (259-1ubuntu2) ... 138s Processing triggers for man-db (2.13.1-1) ... 139s Processing triggers for libc-bin (2.42-2ubuntu4) ... 140s autopkgtest [22:23:58]: test smoke: [----------------------- 140s 140s *************************** 140s *** Testing with python3.13 140s *************************** 140s 140s Content of current working folder: 140s 140s total 12 140s drwxr-xr-x 3 ubuntu ubuntu 4096 Jan 22 22:23 . 140s drwxrwxrwx 5 root root 4096 Jan 22 22:23 .. 140s drwxrwxr-x 4 ubuntu ubuntu 4096 Jan 22 22:23 tests 140s Running tests... 140s 141s ============================= test session starts ============================== 141s platform linux -- Python 3.13.11, pytest-9.0.2, pluggy-1.6.0 141s rootdir: /tmp/autopkgtest.37odl5/autopkgtest_tmp 141s plugins: cov-5.0.0, typeguard-4.4.4 141s collected 536 items / 3 deselected / 533 selected 141s 141s tests/test_callbacks.py ........Traceback (most recent call last): 141s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 141s return_value = job.perform() 141s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 141s self._result = self._execute() 141s ~~~~~~~~~~~~~^^ 141s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 141s result = self.func(*self.args, **self.kwargs) 141s TypeError: div_by_zero() missing 1 required positional argument: 'x' 141s 141s Traceback (most recent call last): 141s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 141s return_value = job.perform() 141s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 141s self._result = self._execute() 141s ~~~~~~~~~~~~~^^ 141s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 141s result = self.func(*self.args, **self.kwargs) 141s TypeError: div_by_zero() missing 1 required positional argument: 'x' 141s 141s ..... 154s tests/test_cli.py ......................................... 158s tests/test_commands.py ..... 158s tests/test_connection.py . 158s tests/test_cron.py ............................ 158s tests/test_cron_job.py ........... 158s tests/test_cron_scheduler_registry.py .... 158s tests/test_decorator.py .............. 160s tests/test_dependencies.py ............. 163s tests/test_executions.py ............ 163s tests/test_fixtures.py .. 169s tests/test_group.py ................ 169s tests/test_helpers.py . 170s tests/test_intermediate_queue.py ........ 175s tests/test_job.py .......................................................... 175s tests/test_job_dependency.py ...# Post enqueue {b'c8463807-6daa-4687-bb4e-c65990f36b89'} 175s # Post work {b'c8463807-6daa-4687-bb4e-c65990f36b89'} 175s # Pre cancel {b'c8463807-6daa-4687-bb4e-c65990f36b89'} 175s #Post cancel set() 181s ....................... 182s tests/test_queue.py ....................................................... 182s tests/test_registry.py ....................................... 182s tests/test_repeat.py ......... 183s tests/test_results.py ............. 184s tests/test_retry.py ........... 194s tests/test_scheduler.py ..................s...... 194s tests/test_serializers.py . 195s tests/test_spawn_worker.py Worker 20ff0d8c518a48a0800196e7fdd496df: job f7e422b9-385a-4275-bb35-e3be958afa59: exception raised while executing (tests.fixtures.div_by_zero) 195s Traceback (most recent call last): 195s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 195s return_value = job.perform() 195s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 195s self._result = self._execute() 195s ~~~~~~~~~~~~~^^ 195s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 195s result = self.func(*self.args, **self.kwargs) 195s TypeError: div_by_zero() missing 1 required positional argument: 'x' 195s 195s .ss 200s tests/test_timeouts.py .. 200s tests/test_utils.py ......................... 219s tests/test_worker.py ......................ss...........s............s.s...................sssss22:25:17 Worker a5053893a6984154a6feb97df5ca31ec: started with PID 2443, version 2.6.1 219s 22:25:17 Worker a5053893a6984154a6feb97df5ca31ec: subscribing to channel rq:pubsub:a5053893a6984154a6feb97df5ca31ec 219s 22:25:17 *** Listening on default... 219s 22:25:17 Worker a5053893a6984154a6feb97df5ca31ec: cleaning registries for queue: default 219s 22:25:17 default: tests.fixtures.access_self() (cb671283-3694-4e0d-ba39-7c52de1c44cf) 219s 22:25:18 Successfully completed tests.fixtures.access_self() job in 0:00:00.150633s on worker a5053893a6984154a6feb97df5ca31ec 219s 22:25:18 default: Job OK (cb671283-3694-4e0d-ba39-7c52de1c44cf) 219s 22:25:18 Result is kept for 500 seconds 219s 22:25:18 Worker a5053893a6984154a6feb97df5ca31ec: done, quitting 219s 22:25:18 Worker a5053893a6984154a6feb97df5ca31ec: unsubscribing from channel rq:pubsub:a5053893a6984154a6feb97df5ca31ec 220s .22:25:18 Worker 1d8b894202094a939a5d6ad8a35f287d: started with PID 2447, version 2.6.1 220s 22:25:18 Worker 1d8b894202094a939a5d6ad8a35f287d: subscribing to channel rq:pubsub:1d8b894202094a939a5d6ad8a35f287d 220s 22:25:18 *** Listening on default... 220s 22:25:18 Worker 1d8b894202094a939a5d6ad8a35f287d: cleaning registries for queue: default 220s 22:25:18 Worker 1d8b894202094a939a5d6ad8a35f287d: done, quitting 220s 22:25:18 Worker 1d8b894202094a939a5d6ad8a35f287d: unsubscribing from channel rq:pubsub:1d8b894202094a939a5d6ad8a35f287d 220s .22:25:18 Worker aec4c6a1a2574d4f84de24ca18920208: started with PID 2450, version 2.6.1 220s 22:25:18 Worker aec4c6a1a2574d4f84de24ca18920208: subscribing to channel rq:pubsub:aec4c6a1a2574d4f84de24ca18920208 220s 22:25:18 *** Listening on default... 220s 22:25:18 Worker aec4c6a1a2574d4f84de24ca18920208: cleaning registries for queue: default 220s 22:25:18 default: tests.test_worker.schedule_access_self() (c05800a4-e66b-4c89-891d-7f027d0324dc) 220s 22:25:18 Successfully completed tests.test_worker.schedule_access_self() job in 0:00:00.147647s on worker aec4c6a1a2574d4f84de24ca18920208 220s 22:25:18 default: Job OK (c05800a4-e66b-4c89-891d-7f027d0324dc) 220s 22:25:18 Result is kept for 500 seconds 220s 22:25:18 Worker aec4c6a1a2574d4f84de24ca18920208: done, quitting 220s 22:25:18 Worker aec4c6a1a2574d4f84de24ca18920208: unsubscribing from channel rq:pubsub:aec4c6a1a2574d4f84de24ca18920208 222s .s..ss... 228s tests/test_worker_pool.py ....... 229s tests/test_worker_registration.py .... 229s 229s =============================== warnings summary =============================== 229s tests/test_group.py:99 229s /tmp/autopkgtest.37odl5/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 229s @pytest.mark.slow 229s 229s tests/test_group.py:114 229s /tmp/autopkgtest.37odl5/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 229s @pytest.mark.slow 229s 229s tests/test_group.py:127 229s /tmp/autopkgtest.37odl5/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 229s @pytest.mark.slow 229s 229s tests/__init__.py:44 229s /tmp/autopkgtest.37odl5/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 229s f = pytest.mark.ssl_test(f) 229s 229s tests/__init__.py:39 229s /tmp/autopkgtest.37odl5/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 229s f = pytest.mark.slow(f) 229s 229s tests/test_callbacks.py: 12 warnings 229s tests/test_decorator.py: 1 warning 229s /usr/lib/python3/dist-packages/rq/job.py:313: DeprecationWarning: Passing a string or function for `on_success` is deprecated, pass `Callback` instead 229s warnings.warn( 229s 229s tests/test_callbacks.py: 7 warnings 229s tests/test_decorator.py: 3 warnings 229s tests/test_job.py: 1 warning 229s /usr/lib/python3/dist-packages/rq/job.py:323: DeprecationWarning: Passing a string or function for `on_failure` is deprecated, pass `Callback` instead 229s warnings.warn( 229s 229s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_many_callback 229s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_with_stopped_callback 229s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_with_stopped_callback 229s tests/test_callbacks.py::QueueCallbackTestCase::test_enqueue_with_stopped_callback 229s tests/test_callbacks.py::SyncJobCallback::test_stopped_callback 229s tests/test_callbacks.py::JobCallbackTestCase::test_job_creation_with_stopped_callback 229s tests/test_job.py::TestJob::test_persistence_of_callbacks 229s /usr/lib/python3/dist-packages/rq/job.py:333: DeprecationWarning: Passing a string or function for `on_stopped` is deprecated, pass `Callback` instead 229s warnings.warn( 229s 229s tests/test_callbacks.py: 2 warnings 229s tests/test_cli.py: 3 warnings 229s tests/test_dependencies.py: 1 warning 229s tests/test_job.py: 6 warnings 229s tests/test_results.py: 1 warning 229s tests/test_worker.py: 13 warnings 229s /usr/lib/python3/dist-packages/rq/job.py:868: DeprecationWarning: job.result is deprecated, use job.return_value instead. 229s warnings.warn('job.result is deprecated, use job.return_value instead.', DeprecationWarning) 229s 229s tests/test_callbacks.py: 2 warnings 229s tests/test_cli.py: 16 warnings 229s tests/test_commands.py: 1 warning 229s tests/test_dependencies.py: 7 warnings 229s tests/test_fixtures.py: 1 warning 229s tests/test_intermediate_queue.py: 1 warning 229s tests/test_job.py: 1 warning 229s tests/test_job_dependency.py: 7 warnings 229s tests/test_queue.py: 2 warnings 229s tests/test_registry.py: 9 warnings 229s tests/test_repeat.py: 3 warnings 229s tests/test_retry.py: 6 warnings 229s tests/test_scheduler.py: 2 warnings 229s tests/test_worker.py: 94 warnings 229s tests/test_worker_pool.py: 1 warning 229s /usr/lib/python3/dist-packages/rq/worker.py:1602: DeprecationWarning: This process (pid=2112) is multi-threaded, use of fork() may lead to deadlocks in the child. 229s child_pid = os.fork() 229s 229s tests/test_callbacks.py::WorkerCallbackTestCase::test_failure_callback 229s tests/test_callbacks.py::WorkerCallbackTestCase::test_failure_callback 229s tests/test_job.py::TestJob::test_compressed_exc_info_handling 229s tests/test_job.py::TestJob::test_compressed_exc_info_handling 229s tests/test_registry.py::TestDeferredRegistry::test_cleanup_moves_jobs_to_failed_job_registry 229s tests/test_results.py::TestResult::test_job_failed_result 229s tests/test_timeouts.py::TestTimeouts::test_timer_death_penalty 229s tests/test_worker.py::TestWorker::test_horse_fails 229s tests/test_worker.py::TestWorker::test_job_timeout_moved_to_failed_job_registry 229s /usr/lib/python3/dist-packages/rq/job.py:816: DeprecationWarning: job.exc_info is deprecated, use job.latest_result() instead. 229s warnings.warn('job.exc_info is deprecated, use job.latest_result() instead.', DeprecationWarning) 229s 229s tests/test_commands.py::TestCommands::test_pubsub_thread_exits_other_error 229s /usr/lib/python3/dist-packages/_pytest/threadexception.py:58: PytestUnhandledThreadExceptionWarning: Exception in thread Thread-34 229s 229s Traceback (most recent call last): 229s File "/usr/lib/python3.13/threading.py", line 1044, in _bootstrap_inner 229s self.run() 229s ~~~~~~~~^^ 229s File "/usr/lib/python3/dist-packages/redis/client.py", line 1269, in run 229s self.exception_handler(e, pubsub, self) 229s ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^ 229s File "/usr/lib/python3/dist-packages/redis/client.py", line 1265, in run 229s pubsub.get_message(ignore_subscribe_messages=True, timeout=sleep_time) 229s ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 229s File "/tmp/autopkgtest.37odl5/autopkgtest_tmp/tests/fixtures.py", line 51, in raise_exc 229s raise Exception('raise_exc error') 229s Exception: raise_exc error 229s 229s Enable tracemalloc to get traceback where the object was allocated. 229s See https://docs.pytest.org/en/stable/how-to/capture-warnings.html#resource-warnings for more info. 229s warnings.warn(pytest.PytestUnhandledThreadExceptionWarning(msg)) 229s 229s tests/test_commands.py: 2 warnings 229s tests/test_job.py: 1 warning 229s tests/test_job_dependency.py: 4 warnings 229s tests/test_scheduler.py: 4 warnings 229s tests/test_worker.py: 2 warnings 229s tests/test_worker_pool.py: 18 warnings 229s /usr/lib/python3.13/multiprocessing/popen_fork.py:67: DeprecationWarning: This process (pid=2112) is multi-threaded, use of fork() may lead to deadlocks in the child. 229s self.pid = os.fork() 229s 229s tests/test_connection.py::TestConnectionInheritance::test_parse_connection 229s /usr/lib/python3/dist-packages/_pytest/threadexception.py:58: PytestUnhandledThreadExceptionWarning: Exception in thread Thread-35 229s 229s Traceback (most recent call last): 229s File "/usr/lib/python3/dist-packages/redis/_parsers/socket.py", line 65, in _read_from_socket 229s data = self._sock.recv(socket_read_size) 229s OSError: [Errno 9] Bad file descriptor 229s 229s During handling of the above exception, another exception occurred: 229s 229s Traceback (most recent call last): 229s File "/usr/lib/python3.13/threading.py", line 1044, in _bootstrap_inner 229s self.run() 229s ~~~~~~~~^^ 229s File "/usr/lib/python3/dist-packages/redis/client.py", line 1269, in run 229s self.exception_handler(e, pubsub, self) 229s ~~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^ 229s File "/usr/lib/python3/dist-packages/redis/client.py", line 1265, in run 229s pubsub.get_message(ignore_subscribe_messages=True, timeout=sleep_time) 229s ~~~~~~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 229s File "/usr/lib/python3/dist-packages/redis/client.py", line 1127, in get_message 229s response = self.parse_response(block=(timeout is None), timeout=timeout) 229s File "/usr/lib/python3/dist-packages/redis/client.py", line 938, in parse_response 229s response = self._execute(conn, try_read) 229s File "/usr/lib/python3/dist-packages/redis/client.py", line 914, in _execute 229s return conn.retry.call_with_retry( 229s ~~~~~~~~~~~~~~~~~~~~~~~~~~^ 229s lambda: command(*args, **kwargs), 229s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 229s lambda _: self._reconnect(conn), 229s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 229s ) 229s ^ 229s File "/usr/lib/python3/dist-packages/redis/retry.py", line 105, in call_with_retry 229s return do() 229s File "/usr/lib/python3/dist-packages/redis/client.py", line 915, in 229s lambda: command(*args, **kwargs), 229s ~~~~~~~^^^^^^^^^^^^^^^^^ 229s File "/usr/lib/python3/dist-packages/redis/client.py", line 932, in try_read 229s if not conn.can_read(timeout=timeout): 229s ~~~~~~~~~~~~~^^^^^^^^^^^^^^^^^ 229s File "/usr/lib/python3/dist-packages/redis/connection.py", line 628, in can_read 229s return self._parser.can_read(timeout) 229s ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 229s File "/usr/lib/python3/dist-packages/redis/_parsers/base.py", line 140, in can_read 229s return self._buffer and self._buffer.can_read(timeout) 229s ~~~~~~~~~~~~~~~~~~~~~^^^^^^^^^ 229s File "/usr/lib/python3/dist-packages/redis/_parsers/socket.py", line 95, in can_read 229s return bool(self.unread_bytes()) or self._read_from_socket( 229s ~~~~~~~~~~~~~~~~~~~~~~^ 229s timeout=timeout, raise_on_timeout=False 229s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 229s ) 229s ^ 229s File "/usr/lib/python3/dist-packages/redis/_parsers/socket.py", line 90, in _read_from_socket 229s buf.seek(current_pos) 229s ~~~~~~~~^^^^^^^^^^^^^ 229s ValueError: I/O operation on closed file. 229s 229s Enable tracemalloc to get traceback where the object was allocated. 229s See https://docs.pytest.org/en/stable/how-to/capture-warnings.html#resource-warnings for more info. 229s warnings.warn(pytest.PytestUnhandledThreadExceptionWarning(msg)) 229s 229s tests/test_registry.py::TestStartedJobRegistry::test_count 229s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got will-be-cleaned-up 229s warnings.warn( 229s 229s tests/test_retry.py::TestRetry::test_cleanup_handles_retries 229s tests/test_retry.py::TestRetry::test_cleanup_handles_retries 229s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got 2efb84ab-ecb5-439d-9025-533d47b89be8 229s warnings.warn( 229s 229s tests/test_scheduler.py::TestQueue::test_custom_connection_pool 229s /tmp/autopkgtest.37odl5/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. 229s scheduler_connection = scheduler.connection.connection_pool.get_connection('info') 229s 229s tests/test_scheduler.py::TestQueue::test_no_custom_connection_pool 229s /tmp/autopkgtest.37odl5/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. 229s scheduler_connection = scheduler.connection.connection_pool.get_connection('info') 229s 229s tests/test_spawn_worker.py::TestWorker::test_work_fails 229s :891: DeprecationWarning: This process (pid=2112) is multi-threaded, use of fork() may lead to deadlocks in the child. 229s 229s tests/test_worker.py::TestWorker::test_clean_queue_registries 229s tests/test_worker.py::TestWorker::test_worker_calls_clean_registries 229s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got foo 229s warnings.warn( 229s 229s tests/test_worker.py::TestWorker::test_clean_queue_registries 229s /usr/lib/python3/dist-packages/rq/utils.py:551: DeprecationWarning: Composite key must contain job_id:execution_id, got bar 229s warnings.warn( 229s 229s tests/test_worker.py::TestWorker::test_worker_ttl 229s /tmp/autopkgtest.37odl5/autopkgtest_tmp/tests/test_worker.py:164: DeprecationWarning: default_worker_ttl is deprecated, use worker_ttl. 229s w = Worker([], connection=self.connection, default_worker_ttl=10) 229s 229s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 229s ==== 517 passed, 16 skipped, 3 deselected, 267 warnings in 88.27s (0:01:28) ==== 229s 229s *************************** 229s *** Testing with python3.14 229s *************************** 229s 229s Content of current working folder: 229s 229s total 12 229s drwxr-xr-x 3 ubuntu ubuntu 4096 Jan 22 22:25 . 229s drwxrwxrwx 5 root root 4096 Jan 22 22:23 .. 229s drwxrwxr-x 5 ubuntu ubuntu 4096 Jan 22 22:23 tests 229s Running tests... 229s 229s ============================= test session starts ============================== 229s platform linux -- Python 3.14.2, pytest-9.0.2, pluggy-1.6.0 229s rootdir: /tmp/autopkgtest.37odl5/autopkgtest_tmp 229s plugins: cov-5.0.0, typeguard-4.4.4 229s collected 536 items / 3 deselected / 533 selected 229s 230s tests/test_callbacks.py ........Traceback (most recent call last): 230s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 230s return_value = job.perform() 230s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 230s self._result = self._execute() 230s ~~~~~~~~~~~~~^^ 230s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 230s result = self.func(*self.args, **self.kwargs) 230s TypeError: div_by_zero() missing 1 required positional argument: 'x' 230s 230s Traceback (most recent call last): 230s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 230s return_value = job.perform() 230s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 230s self._result = self._execute() 230s ~~~~~~~~~~~~~^^ 230s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 230s result = self.func(*self.args, **self.kwargs) 230s TypeError: div_by_zero() missing 1 required positional argument: 'x' 230s 230s ..... 241s tests/test_cli.py .................................22:25:40 Worker 6b2a49d184f64710a601e083e1179933: started with PID 2582, version 2.6.1 241s 22:25:40 Worker 6b2a49d184f64710a601e083e1179933: subscribing to channel rq:pubsub:6b2a49d184f64710a601e083e1179933 241s 22:25:40 *** Listening on default... 241s 22:25:40 Acquired scheduler lock for default 241s 22:25:40 Worker 6b2a49d184f64710a601e083e1179933: cleaning registries for queue: default 241s 22:25:40 Worker 6b2a49d184f64710a601e083e1179933: done, quitting 241s 22:25:40 Worker 6b2a49d184f64710a601e083e1179933: unsubscribing from channel rq:pubsub:6b2a49d184f64710a601e083e1179933 242s .22:25:41 Worker be9ec6a92f8f40b78dab9b83d6c98101: started with PID 2584, version 2.6.1 242s 22:25:41 Worker be9ec6a92f8f40b78dab9b83d6c98101: subscribing to channel rq:pubsub:be9ec6a92f8f40b78dab9b83d6c98101 242s 22:25:41 *** Listening on foo, bar... 242s 22:25:41 Acquired scheduler lock for bar 242s 22:25:41 Acquired scheduler lock for foo 242s 22:25:41 Worker be9ec6a92f8f40b78dab9b83d6c98101: cleaning registries for queue: foo 242s 22:25:41 Worker be9ec6a92f8f40b78dab9b83d6c98101: cleaning registries for queue: bar 242s 22:25:41 foo: tests.fixtures.say_hello('Hello') (dd769fd7-8a20-495e-b2db-237d0016c6c1) 243s 22:25:41 Successfully completed tests.fixtures.say_hello('Hello') job in 0:00:00.112576s on worker be9ec6a92f8f40b78dab9b83d6c98101 243s 22:25:41 foo: Job OK (dd769fd7-8a20-495e-b2db-237d0016c6c1) 243s 22:25:41 Result is kept for 500 seconds 243s 22:25:41 bar: tests.fixtures.say_hello('Hello') (e32790c4-8782-4041-9ed1-9e275348ef9d) 243s 22:25:41 Successfully completed tests.fixtures.say_hello('Hello') job in 0:00:00.114341s on worker be9ec6a92f8f40b78dab9b83d6c98101 243s 22:25:41 bar: Job OK (e32790c4-8782-4041-9ed1-9e275348ef9d) 243s 22:25:41 Result is kept for 500 seconds 243s 22:25:41 Worker be9ec6a92f8f40b78dab9b83d6c98101: done, quitting 243s 22:25:41 Worker be9ec6a92f8f40b78dab9b83d6c98101: unsubscribing from channel rq:pubsub:be9ec6a92f8f40b78dab9b83d6c98101 244s .22:25:42 Worker 9a77a7a1c51d423c96cc52d881f37b5f: started with PID 2588, version 2.6.1 244s 22:25:42 Worker 9a77a7a1c51d423c96cc52d881f37b5f: subscribing to channel rq:pubsub:9a77a7a1c51d423c96cc52d881f37b5f 244s 22:25:42 *** Listening on default... 244s 22:25:42 Acquired scheduler lock for default 244s 22:25:42 Worker 9a77a7a1c51d423c96cc52d881f37b5f: cleaning registries for queue: default 244s 22:25:42 Worker 9a77a7a1c51d423c96cc52d881f37b5f: done, quitting 244s 22:25:42 Worker 9a77a7a1c51d423c96cc52d881f37b5f: unsubscribing from channel rq:pubsub:9a77a7a1c51d423c96cc52d881f37b5f 245s 22:25:43 Worker 53fcad1aae7d48fd9cb0f965b543415b: started with PID 2590, version 2.6.1 245s 22:25:43 Worker 53fcad1aae7d48fd9cb0f965b543415b: subscribing to channel rq:pubsub:53fcad1aae7d48fd9cb0f965b543415b 245s 22:25:43 *** Listening on default... 245s 22:25:43 Acquired scheduler lock for default 245s 22:25:43 Worker 53fcad1aae7d48fd9cb0f965b543415b: cleaning registries for queue: default 245s 22:25:43 Worker 53fcad1aae7d48fd9cb0f965b543415b: done, quitting 245s 22:25:43 Worker 53fcad1aae7d48fd9cb0f965b543415b: unsubscribing from channel rq:pubsub:53fcad1aae7d48fd9cb0f965b543415b 246s .22:25:44 Worker d69088145e44446686c412771a210f9f: started with PID 2594, version 2.6.1 246s 22:25:44 Worker d69088145e44446686c412771a210f9f: subscribing to channel rq:pubsub:d69088145e44446686c412771a210f9f 246s 22:25:44 *** Listening on default... 246s 22:25:44 Acquired scheduler lock for default 246s 22:25:44 Worker d69088145e44446686c412771a210f9f: cleaning registries for queue: default 246s 22:25:44 Worker d69088145e44446686c412771a210f9f: done, quitting 246s 22:25:44 Worker d69088145e44446686c412771a210f9f: unsubscribing from channel rq:pubsub:d69088145e44446686c412771a210f9f 246s 22:25:44 Worker 9d87c191e7904e839058fc75e9484025: started with PID 2593, version 2.6.1 246s 22:25:44 Worker 9d87c191e7904e839058fc75e9484025: subscribing to channel rq:pubsub:9d87c191e7904e839058fc75e9484025 246s 22:25:44 *** Listening on default... 246s 22:25:44 Acquired scheduler lock for default 246s 22:25:44 Worker 9d87c191e7904e839058fc75e9484025: cleaning registries for queue: default 246s 22:25:44 Worker 9d87c191e7904e839058fc75e9484025: done, quitting 246s 22:25:44 Worker 9d87c191e7904e839058fc75e9484025: unsubscribing from channel rq:pubsub:9d87c191e7904e839058fc75e9484025 246s 22:25:44 Worker a6025a978d0f456cb2848fc4168f924f: started with PID 2592, version 2.6.1 246s 22:25:44 Worker a6025a978d0f456cb2848fc4168f924f: subscribing to channel rq:pubsub:a6025a978d0f456cb2848fc4168f924f 246s 22:25:44 *** Listening on default... 246s 22:25:44 Acquired scheduler lock for default 246s 22:25:44 Worker a6025a978d0f456cb2848fc4168f924f: cleaning registries for queue: default 246s 22:25:44 Worker a6025a978d0f456cb2848fc4168f924f: done, quitting 246s 22:25:44 Worker a6025a978d0f456cb2848fc4168f924f: unsubscribing from channel rq:pubsub:a6025a978d0f456cb2848fc4168f924f 247s .22:25:45 Worker 4d7db962082541148f8176229b50904d: registering birth 247s 22:25:45 Worker 4d7db962082541148f8176229b50904d: started with PID 2598, version 2.6.1 247s 22:25:45 Worker 4d7db962082541148f8176229b50904d: subscribing to channel rq:pubsub:4d7db962082541148f8176229b50904d 247s 22:25:45 *** Listening on default... 247s 22:25:45 Acquiring scheduler lock for default 247s 22:25:45 Acquired scheduler lock for default 247s 22:25:45 Worker 4d7db962082541148f8176229b50904d: sent heartbeat to prevent worker timeout. Next one should arrive in 480 seconds. 247s 22:25:45 Worker 4d7db962082541148f8176229b50904d: cleaning registries for queue: default 247s 22:25:45 Worker 4d7db962082541148f8176229b50904d: *** Listening on default... 247s 22:25:45 Worker 4d7db962082541148f8176229b50904d: sent heartbeat to prevent worker timeout. Next one should arrive in 480 seconds. 247s 22:25:45 Worker 4d7db962082541148f8176229b50904d: dequeueing jobs on queues default and timeout None 247s 22:25:45 Worker 4d7db962082541148f8176229b50904d: sent heartbeat to prevent worker timeout. Next one should arrive in 480 seconds. 247s 22:25:45 Worker 4d7db962082541148f8176229b50904d: done, quitting 247s 22:25:45 Worker 4d7db962082541148f8176229b50904d: registering death 247s 22:25:45 Worker 4d7db962082541148f8176229b50904d: unsubscribing from channel rq:pubsub:4d7db962082541148f8176229b50904d 249s .... 251s tests/test_commands.py 22:25:49 Worker 566469828f6042ee97475bff8edbff1c: started with PID 2605, version 2.6.1 251s 22:25:49 Worker 566469828f6042ee97475bff8edbff1c: subscribing to channel rq:pubsub:566469828f6042ee97475bff8edbff1c 251s 22:25:49 *** Listening on foo... 251s 22:25:49 Worker 566469828f6042ee97475bff8edbff1c: cleaning registries for queue: foo 253s 22:25:51 Received kill horse command. 253s 22:25:51 Worker is not working, kill horse command ignored 253s 22:25:51 Received shutdown command, sending SIGINT signal. 253s .22:25:51 Worker 566469828f6042ee97475bff8edbff1c [PID 2605]: warm shut down requested 253s 22:25:51 Worker 566469828f6042ee97475bff8edbff1c: unsubscribing from channel rq:pubsub:566469828f6042ee97475bff8edbff1c 253s ...22:25:51 Worker e54ebf2de5ce4b5ca09ff36141d6644e: started with PID 2611, version 2.6.1 253s 22:25:51 Worker e54ebf2de5ce4b5ca09ff36141d6644e: subscribing to channel rq:pubsub:e54ebf2de5ce4b5ca09ff36141d6644e 253s 22:25:51 *** Listening on foo... 253s 22:25:51 Worker e54ebf2de5ce4b5ca09ff36141d6644e: cleaning registries for queue: foo 253s 22:25:51 foo: tests.fixtures.long_running_job(3) (39390569-32c9-4cb5-b33b-2f2e1646ef5b) 254s 22:25:52 Not working on job 1, command ignored. 254s 22:25:53 Worker e54ebf2de5ce4b5ca09ff36141d6644e: killed horse pid 2613 254s 22:25:53 Worker e54ebf2de5ce4b5ca09ff36141d6644e: job 39390569-32c9-4cb5-b33b-2f2e1646ef5b stopped by user, moving job to FailedJobRegistry 254s 22:25:53 Worker e54ebf2de5ce4b5ca09ff36141d6644e: done, quitting 254s 22:25:53 Worker e54ebf2de5ce4b5ca09ff36141d6644e: unsubscribing from channel rq:pubsub:e54ebf2de5ce4b5ca09ff36141d6644e 255s . 255s tests/test_connection.py . 255s tests/test_cron.py ........................F... 255s tests/test_cron_job.py 22:25:53 Registered 'tests.fixtures.do_nothing' to run on default every 1 seconds 255s 22:25:53 CronScheduler autopkgtest:2614:9e5435: starting... 255s 22:25:53 CronScheduler autopkgtest:2614:9e5435: registering birth... 255s ....22:25:53 Enqueued job do_nothing to queue default 255s 22:25:53 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 255s F...... 255s tests/test_cron_scheduler_registry.py .... 255s tests/test_decorator.py .............. 256s tests/test_dependencies.py .22:25:54 Enqueued job do_nothing to queue default 256s 22:25:54 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 257s ........22:25:55 Enqueued job do_nothing to queue default 257s 22:25:55 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 258s .... 258s tests/test_executions.py ...22:25:56 Worker w1: started with PID 2646, version 2.6.1 258s 22:25:56 Worker w1: subscribing to channel rq:pubsub:w1 258s 22:25:56 *** Listening on default... 258s 22:25:56 Worker w1: cleaning registries for queue: default 258s 22:25:56 default: tests.fixtures.long_running_job(timeout=3) (c141b646-50f1-4c4b-8970-486e9b3e938c) 258s 22:25:56 Enqueued job do_nothing to queue default 258s 22:25:56 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 259s 22:25:57 Enqueued job do_nothing to queue default 259s 22:25:57 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 260s 22:25:58 Enqueued job do_nothing to queue default 260s 22:25:58 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 261s 22:25:59 Successfully completed tests.fixtures.long_running_job(timeout=3) job in 0:00:03.002353s on worker w1 261s 22:25:59 default: Job OK (c141b646-50f1-4c4b-8970-486e9b3e938c) 261s 22:25:59 Result is kept for 500 seconds 261s 22:25:59 default: tests.fixtures.do_nothing() (359a1dd1-9949-4125-9ec3-3fe8fb7afb01) 261s 22:25:59 Successfully completed tests.fixtures.do_nothing() job in 0:00:00.000295s on worker w1 261s 22:25:59 default: Job OK (359a1dd1-9949-4125-9ec3-3fe8fb7afb01) 261s 22:25:59 Result is kept for 500 seconds 261s 22:25:59 default: tests.fixtures.do_nothing() (21d5936e-1604-4838-9977-21ccc805a359) 261s 22:25:59 Successfully completed tests.fixtures.do_nothing() job in 0:00:00.000337s on worker w1 261s 22:25:59 default: Job OK (21d5936e-1604-4838-9977-21ccc805a359) 261s 22:25:59 Result is kept for 500 seconds 261s 22:25:59 default: tests.fixtures.do_nothing() (0242444e-ffca-4d67-886c-a993df8d463d) 261s 22:25:59 Successfully completed tests.fixtures.do_nothing() job in 0:00:00.000328s on worker w1 261s 22:25:59 default: Job OK (0242444e-ffca-4d67-886c-a993df8d463d) 261s 22:25:59 Result is kept for 500 seconds 261s 22:25:59 Worker w1: done, quitting 261s 22:25:59 Worker w1: unsubscribing from channel rq:pubsub:w1 261s ......... 261s tests/test_fixtures.py .. 261s tests/test_group.py .....22:25:59 Enqueued job do_nothing to queue default 261s 22:25:59 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 262s 22:26:00 Enqueued job do_nothing to queue default 262s 22:26:00 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 263s .22:26:01 Enqueued job do_nothing to queue default 263s 22:26:01 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 264s 22:26:02 Enqueued job do_nothing to queue default 264s 22:26:02 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 265s .22:26:03 Enqueued job do_nothing to queue default 265s 22:26:03 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 266s 22:26:04 Enqueued job do_nothing to queue default 266s 22:26:04 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 267s ......... 267s tests/test_helpers.py . 267s tests/test_intermediate_queue.py .22:26:05 Enqueued job do_nothing to queue default 267s 22:26:05 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 268s 22:26:06 Enqueued job do_nothing to queue default 268s 22:26:06 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 268s ....... 268s tests/test_job.py 22:26:06 Worker 473234db91394fe6b667ace1002ecc8a: started with PID 2664, version 2.6.1 268s 22:26:06 Worker 473234db91394fe6b667ace1002ecc8a: subscribing to channel rq:pubsub:473234db91394fe6b667ace1002ecc8a 268s 22:26:06 *** Listening on test_blocking_queue... 268s 22:26:06 Worker 473234db91394fe6b667ace1002ecc8a: cleaning registries for queue: test_blocking_queue 268s 22:26:06 test_blocking_queue: tests.fixtures.long_running_job(2) (910b82fa-455e-46a1-9acd-800d89bd9187) 269s 22:26:07 Enqueued job do_nothing to queue default 269s 22:26:07 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 270s 22:26:08 Enqueued job do_nothing to queue default 270s 22:26:08 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 270s .22:26:08 Successfully completed tests.fixtures.long_running_job(2) job in 0:00:02.000616s on worker 473234db91394fe6b667ace1002ecc8a 270s 22:26:08 test_blocking_queue: Job OK (910b82fa-455e-46a1-9acd-800d89bd9187) 270s 22:26:08 Result is kept for 500 seconds 270s ....22:26:08 Worker 473234db91394fe6b667ace1002ecc8a: done, quitting 270s 22:26:08 Worker 473234db91394fe6b667ace1002ecc8a: unsubscribing from channel rq:pubsub:473234db91394fe6b667ace1002ecc8a 271s ...........22:26:09 Enqueued job do_nothing to queue default 271s 22:26:09 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 272s F.............................22:26:10 Enqueued job do_nothing to queue default 272s 22:26:10 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 273s 22:26:11 Enqueued job do_nothing to queue default 273s 22:26:11 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 274s ............ 274s tests/test_job_dependency.py ...# Post enqueue {b'34e76e4f-e384-4503-842b-799240bb91bd'} 274s # Post work {b'34e76e4f-e384-4503-842b-799240bb91bd'} 274s # Pre cancel {b'34e76e4f-e384-4503-842b-799240bb91bd'} 274s #Post cancel set() 274s ............22:26:12 Worker w1: started with PID 2671, version 2.6.1 274s 22:26:12 Worker w1: subscribing to channel rq:pubsub:w1 274s 22:26:12 *** Listening on default... 274s 22:26:12 Worker w1: cleaning registries for queue: default 274s 22:26:12 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) 274s 22:26:12 Enqueued job do_nothing to queue default 274s 22:26:12 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 274s 22:26:13 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.501431s on worker w1 274s 22:26:13 default: Job OK (slow_1) 274s 22:26:13 Result is kept for 500 seconds 274s 22:26:13 default: tests.fixtures.rpush('test_job:job_order', 'A', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True) (c6e0961d-f4f4-412c-9db6-edd92a00c158) 274s 22:26:13 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.001059s on worker w1 274s 22:26:13 default: Job OK (c6e0961d-f4f4-412c-9db6-edd92a00c158) 274s 22:26:13 Result is kept for 500 seconds 274s 22:26:13 default: tests.fixtures.rpush('test_job:job_order', 'B', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True) (65d9ff09-65cd-4c03-98db-1e6b05f786c2) 274s 22:26:13 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.001045s on worker w1 274s 22:26:13 default: Job OK (65d9ff09-65cd-4c03-98db-1e6b05f786c2) 274s 22:26:13 Result is kept for 500 seconds 274s 22:26:13 default: tests.fixtures.do_nothing() (4b382c55-87e6-4f92-bfbb-d94b03cc26c2) 274s 22:26:13 Successfully completed tests.fixtures.do_nothing() job in 0:00:00.000295s on worker w1 274s 22:26:13 default: Job OK (4b382c55-87e6-4f92-bfbb-d94b03cc26c2) 274s 22:26:13 Result is kept for 500 seconds 274s 22:26:13 Worker w1: done, quitting 274s 22:26:13 Worker w1: unsubscribing from channel rq:pubsub:w1 275s 22:26:13 Enqueued job do_nothing to queue default 275s 22:26:13 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 276s F22:26:14 Enqueued job do_nothing to queue default 276s 22:26:14 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 276s 22:26:14 Worker w1: started with PID 2679, version 2.6.1 276s 22:26:14 Worker w1: subscribing to channel rq:pubsub:w1 276s 22:26:14 *** Listening on default... 276s 22:26:14 Worker w1: cleaning registries for queue: default 276s 22:26:14 default: tests.fixtures.rpush('test_job:job_order', 'slow', {'db': 8, 'username': None, 'password': None, 'socket_timeout': 415, 'encod..., True, 0.5) (slow_job) 276s 22:26:15 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.501498s on worker w1 276s 22:26:15 default: Job OK (slow_job) 276s 22:26:15 Result is kept for 500 seconds 276s 22:26:15 Worker w1: done, quitting 276s 22:26:15 Worker w1: unsubscribing from channel rq:pubsub:w1 277s 22:26:15 Enqueued job do_nothing to queue default 277s 22:26:15 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 277s F......... 277s tests/test_queue.py ....................................................... 278s tests/test_registry.py ....................................... 278s tests/test_repeat.py ......... 278s tests/test_results.py 22:26:16 Enqueued job do_nothing to queue default 278s 22:26:16 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 279s .........22:26:17 Enqueued job do_nothing to queue default 279s 22:26:17 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 279s .... 279s tests/test_retry.py ........... 280s tests/test_scheduler.py ...............22:26:18 Enqueued job do_nothing to queue default 280s 22:26:18 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 281s 22:26:19 Enqueued job do_nothing to queue default 281s 22:26:19 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 282s 22:26:20 Enqueued job do_nothing to queue default 282s 22:26:20 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 283s 22:26:21 Enqueued job do_nothing to queue default 283s 22:26:21 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 284s 22:26:22 Enqueued job do_nothing to queue default 284s 22:26:22 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 285s 22:26:23 Enqueued job do_nothing to queue default 285s 22:26:23 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 286s F.22:26:24 Enqueued job do_nothing to queue default 286s 22:26:24 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 287s 22:26:25 Enqueued job do_nothing to queue default 287s 22:26:25 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 288s 22:26:26 Enqueued job do_nothing to queue default 288s 22:26:26 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 289s 22:26:27 Enqueued job do_nothing to queue default 289s 22:26:27 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 290s 22:26:28 Enqueued job do_nothing to queue default 290s 22:26:28 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 291s 22:26:29 Enqueued job do_nothing to queue default 291s 22:26:29 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 291s .s...... 291s tests/test_serializers.py . 292s tests/test_spawn_worker.py Worker 27648d09c05b4cdaa03768555815f277: job aa00c636-ba07-4ae3-9f19-48a13f96d14d: exception raised while executing (tests.fixtures.div_by_zero) 292s Traceback (most recent call last): 292s File "/usr/lib/python3/dist-packages/rq/worker.py", line 1439, in perform_job 292s return_value = job.perform() 292s File "/usr/lib/python3/dist-packages/rq/job.py", line 1318, in perform 292s self._result = self._execute() 292s ~~~~~~~~~~~~~^^ 292s File "/usr/lib/python3/dist-packages/rq/job.py", line 1376, in _execute 292s result = self.func(*self.args, **self.kwargs) 292s TypeError: div_by_zero() missing 1 required positional argument: 'x' 292s 292s .ss 292s tests/test_timeouts.py .22:26:30 Enqueued job do_nothing to queue default 292s 22:26:30 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 293s 22:26:31 Enqueued job do_nothing to queue default 293s 22:26:31 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 294s 22:26:32 Enqueued job do_nothing to queue default 294s 22:26:32 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 295s 22:26:33 Enqueued job do_nothing to queue default 295s 22:26:33 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 296s 22:26:34 Enqueued job do_nothing to queue default 296s 22:26:34 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 297s . 297s tests/test_utils.py ......................... 297s tests/test_worker.py 22:26:35 Enqueued job do_nothing to queue default 297s 22:26:35 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 298s 22:26:36 Enqueued job do_nothing to queue default 298s 22:26:36 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 299s 22:26:37 Enqueued job do_nothing to queue default 299s 22:26:37 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 300s 22:26:38 Enqueued job do_nothing to queue default 300s 22:26:38 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 301s 22:26:39 Enqueued job do_nothing to queue default 301s 22:26:39 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 302s .22:26:40 Enqueued job do_nothing to queue default 302s 22:26:40 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 303s 22:26:41 Enqueued job do_nothing to queue default 303s 22:26:41 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 304s 22:26:42 Enqueued job do_nothing to queue default 304s 22:26:42 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 305s 22:26:43 Enqueued job do_nothing to queue default 305s 22:26:43 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 306s 22:26:44 Enqueued job do_nothing to queue default 306s 22:26:44 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 307s .22:26:45 Enqueued job do_nothing to queue default 307s 22:26:45 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 308s 22:26:46 Enqueued job do_nothing to queue default 308s 22:26:46 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 309s 22:26:47 Enqueued job do_nothing to queue default 309s 22:26:47 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 310s 22:26:48 Enqueued job do_nothing to queue default 310s 22:26:48 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 311s 22:26:49 Enqueued job do_nothing to queue default 311s 22:26:49 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 312s 22:26:50 Enqueued job do_nothing to queue default 312s 22:26:50 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 313s ....................ss..22:26:51 Enqueued job do_nothing to queue default 313s 22:26:51 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 314s 22:26:52 Enqueued job do_nothing to queue default 314s 22:26:52 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 315s .........s............s22:26:53 Enqueued job do_nothing to queue default 315s 22:26:53 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 316s 22:26:54 Enqueued job do_nothing to queue default 316s 22:26:54 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 317s 22:26:55 Enqueued job do_nothing to queue default 317s 22:26:55 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 318s Fs...................sssss22:26:56 Enqueued job do_nothing to queue default 318s 22:26:56 CronScheduler autopkgtest:2614:9e5435: heartbeat failed - scheduler not found in registry 318s 22:26:56 Worker 230e9f3099c84ade9f972a3f82c438c0: started with PID 2888, version 2.6.1 318s 22:26:56 Worker 230e9f3099c84ade9f972a3f82c438c0: subscribing to channel rq:pubsub:230e9f3099c84ade9f972a3f82c438c0 318s 22:26:56 *** Listening on default... 318s 22:26:56 Worker 230e9f3099c84ade9f972a3f82c438c0: cleaning registries for queue: default 318s 22:26:56 default: tests.fixtures.access_self() (2d9d4ef5-e5fe-4395-abda-5db3553ac1f2) 318s 22:26:56 Successfully completed tests.fixtures.access_self() job in 0:00:00.116511s on worker 230e9f3099c84ade9f972a3f82c438c0 318s 22:26:56 default: Job OK (2d9d4ef5-e5fe-4395-abda-5db3553ac1f2) 318s 22:26:56 Result is kept for 500 seconds 318s 22:26:56 Worker 230e9f3099c84ade9f972a3f82c438c0: done, quitting 318s 22:26:56 Worker 230e9f3099c84ade9f972a3f82c438c0: unsubscribing from channel rq:pubsub:230e9f3099c84ade9f972a3f82c438c0 318s .22:26:57 Worker 6ec2db6e67fc447fbb2f6f8afa2860e7: started with PID 2892, version 2.6.1 318s 22:26:57 Worker 6ec2db6e67fc447fbb2f6f8afa2860e7: subscribing to channel rq:pubsub:6ec2db6e67fc447fbb2f6f8afa2860e7 318s 22:26:57 *** Listening on default... 318s 22:26:57 Worker 6ec2db6e67fc447fbb2f6f8afa2860e7: cleaning registries for queue: default 318s 22:26:57 Worker 6ec2db6e67fc447fbb2f6f8afa2860e7: done, quitting 318s 22:26:57 Worker 6ec2db6e67fc447fbb2f6f8afa2860e7: unsubscribing from channel rq:pubsub:6ec2db6e67fc447fbb2f6f8afa2860e7 319s .22:26:57 Worker 1faf78f2d351443aa6fa73322180bef1: started with PID 2895, version 2.6.1 319s 22:26:57 Worker 1faf78f2d351443aa6fa73322180bef1: subscribing to channel rq:pubsub:1faf78f2d351443aa6fa73322180bef1 319s 22:26:57 *** Listening on default... 319s 22:26:57 Worker 1faf78f2d351443aa6fa73322180bef1: cleaning registries for queue: default 319s 22:26:57 default: tests.test_worker.schedule_access_self() (1f4eb229-72fe-47a1-8c31-003529e59fdb) 319s 22:26:57 Successfully completed tests.test_worker.schedule_access_self() job in 0:00:00.131410s on worker 1faf78f2d351443aa6fa73322180bef1 319s 22:26:57 default: Job OK (1f4eb229-72fe-47a1-8c31-003529e59fdb) 319s 22:26:57 Result is kept for 500 seconds 319s 22:26:57 Worker 1faf78f2d351443aa6fa73322180bef1: done, quitting 319s 22:26:57 Worker 1faf78f2d351443aa6fa73322180bef1: unsubscribing from channel rq:pubsub:1faf78f2d351443aa6fa73322180bef1 319s .sReal-time signal 0 319s autopkgtest [22:26:57]: test smoke: -----------------------] 320s smoke FAIL non-zero exit status 162 320s autopkgtest [22:26:58]: test smoke: - - - - - - - - - - results - - - - - - - - - - 320s autopkgtest [22:26:58]: test autodep8-python3: preparing testbed 328s Creating nova instance adt-resolute-ppc64el-python-rq-20260122-222138-juju-7f2275-prod-proposed-migration-environment-20-ea9c6c84-bc51-4742-b2ad-e8cd975bc01d from image adt/ubuntu-resolute-ppc64el-server-20260122.img (UUID 987e5803-394f-43eb-a969-151b6b75a57a)... 405s autopkgtest [22:28:23]: testbed dpkg architecture: ppc64el 405s autopkgtest [22:28:23]: testbed apt version: 3.1.12 405s autopkgtest [22:28:23]: @@@@@@@@@@@@@@@@@@@@ test bed setup 405s autopkgtest [22:28:23]: testbed release detected to be: resolute 406s autopkgtest [22:28:24]: updating testbed package index (apt update) 406s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 406s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 406s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 406s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 406s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1011 kB] 407s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [81.6 kB] 407s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 407s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [22.2 kB] 407s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el Packages [100 kB] 407s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el Packages [703 kB] 407s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse ppc64el Packages [5552 B] 407s Fetched 2050 kB in 1s (3761 kB/s) 408s Reading package lists... 408s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 408s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 408s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 408s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 409s Reading package lists... 409s Reading package lists... 409s Building dependency tree... 409s Reading state information... 409s Calculating upgrade... 410s The following packages were automatically installed and are no longer required: 410s python3.13 python3.13-minimal 410s Use 'sudo apt autoremove' to remove them. 410s The following NEW packages will be installed: 410s libpython3.14-minimal libpython3.14-stdlib python3.14 python3.14-minimal 410s The following packages will be upgraded: 410s libplymouth5 libpython3-stdlib plymouth plymouth-theme-ubuntu-text python3 410s python3-gdbm python3-minimal 410s 7 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 410s Need to get 7312 kB of archives. 410s After this operation, 27.7 MB of additional disk space will be used. 410s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el libpython3.14-minimal ppc64el 3.14.2-1 [921 kB] 410s Get:2 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.14-minimal ppc64el 3.14.2-1 [2712 kB] 410s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el python3-gdbm ppc64el 3.14.2-1 [11.1 kB] 410s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el python3-minimal ppc64el 3.14.2-1 [28.2 kB] 410s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el python3 ppc64el 3.14.2-1 [22.9 kB] 410s Get:6 http://ftpmaster.internal/ubuntu resolute/main ppc64el libpython3.14-stdlib ppc64el 3.14.2-1 [2444 kB] 410s Get:7 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.14 ppc64el 3.14.2-1 [816 kB] 410s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el libpython3-stdlib ppc64el 3.14.2-1 [10.9 kB] 410s Get:9 http://ftpmaster.internal/ubuntu resolute/main ppc64el libplymouth5 ppc64el 24.004.60+git20250831.4a3c171d-0ubuntu4 [174 kB] 410s Get:10 http://ftpmaster.internal/ubuntu resolute/main ppc64el plymouth-theme-ubuntu-text ppc64el 24.004.60+git20250831.4a3c171d-0ubuntu4 [11.2 kB] 410s Get:11 http://ftpmaster.internal/ubuntu resolute/main ppc64el plymouth ppc64el 24.004.60+git20250831.4a3c171d-0ubuntu4 [160 kB] 411s dpkg-preconfigure: unable to re-open stdin: No such file or directory 411s Fetched 7312 kB in 1s (9594 kB/s) 411s Selecting previously unselected package libpython3.14-minimal:ppc64el. 411s (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 ... 120824 files and directories currently installed.) 411s Preparing to unpack .../libpython3.14-minimal_3.14.2-1_ppc64el.deb ... 411s Unpacking libpython3.14-minimal:ppc64el (3.14.2-1) ... 412s Selecting previously unselected package python3.14-minimal. 412s Preparing to unpack .../python3.14-minimal_3.14.2-1_ppc64el.deb ... 412s Unpacking python3.14-minimal (3.14.2-1) ... 412s Preparing to unpack .../python3-gdbm_3.14.2-1_ppc64el.deb ... 412s Unpacking python3-gdbm (3.14.2-1) over (3.13.9-1) ... 412s Setting up libpython3.14-minimal:ppc64el (3.14.2-1) ... 412s Setting up python3.14-minimal (3.14.2-1) ... 413s (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 ... 121166 files and directories currently installed.) 413s Preparing to unpack .../python3-minimal_3.14.2-1_ppc64el.deb ... 413s Unpacking python3-minimal (3.14.2-1) over (3.13.7-1) ... 413s Setting up python3-minimal (3.14.2-1) ... 413s (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 ... 121166 files and directories currently installed.) 413s Preparing to unpack .../0-python3_3.14.2-1_ppc64el.deb ... 413s running python pre-rtupdate hooks for python3.14... 413s Unpacking python3 (3.14.2-1) over (3.13.7-1) ... 414s Selecting previously unselected package libpython3.14-stdlib:ppc64el. 414s Preparing to unpack .../1-libpython3.14-stdlib_3.14.2-1_ppc64el.deb ... 414s Unpacking libpython3.14-stdlib:ppc64el (3.14.2-1) ... 414s Selecting previously unselected package python3.14. 414s Preparing to unpack .../2-python3.14_3.14.2-1_ppc64el.deb ... 414s Unpacking python3.14 (3.14.2-1) ... 414s Preparing to unpack .../3-libpython3-stdlib_3.14.2-1_ppc64el.deb ... 414s Unpacking libpython3-stdlib:ppc64el (3.14.2-1) over (3.13.7-1) ... 414s Preparing to unpack .../4-libplymouth5_24.004.60+git20250831.4a3c171d-0ubuntu4_ppc64el.deb ... 414s Unpacking libplymouth5:ppc64el (24.004.60+git20250831.4a3c171d-0ubuntu4) over (24.004.60+git20250831.4a3c171d-0ubuntu3) ... 414s Preparing to unpack .../5-plymouth-theme-ubuntu-text_24.004.60+git20250831.4a3c171d-0ubuntu4_ppc64el.deb ... 414s Unpacking plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu4) over (24.004.60+git20250831.4a3c171d-0ubuntu3) ... 414s Preparing to unpack .../6-plymouth_24.004.60+git20250831.4a3c171d-0ubuntu4_ppc64el.deb ... 414s Unpacking plymouth (24.004.60+git20250831.4a3c171d-0ubuntu4) over (24.004.60+git20250831.4a3c171d-0ubuntu3) ... 414s Setting up libpython3.14-stdlib:ppc64el (3.14.2-1) ... 414s Setting up libplymouth5:ppc64el (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 414s Setting up python3.14 (3.14.2-1) ... 415s Setting up libpython3-stdlib:ppc64el (3.14.2-1) ... 415s Setting up python3-gdbm (3.14.2-1) ... 415s Setting up plymouth (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 415s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 416s update-rc.d: warning: start and stop actions are no longer supported; falling back to defaults 416s Setting up python3 (3.14.2-1) ... 416s running python rtupdate hooks for python3.14... 416s running python post-rtupdate hooks for python3.14... 416s Processing triggers for libc-bin (2.42-2ubuntu4) ... 416s Processing triggers for systemd (259-1ubuntu2) ... 416s Processing triggers for man-db (2.13.1-1) ... 417s Processing triggers for initramfs-tools (0.150ubuntu7) ... 417s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 428s Setting up plymouth-theme-ubuntu-text (24.004.60+git20250831.4a3c171d-0ubuntu4) ... 428s Processing triggers for initramfs-tools (0.150ubuntu7) ... 428s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 433s autopkgtest [22:28:51]: upgrading testbed (apt dist-upgrade and autopurge) 433s Reading package lists... 434s Building dependency tree... 434s Reading state information... 434s Calculating upgrade... 434s The following packages were automatically installed and are no longer required: 434s python3.13 python3.13-minimal 434s Use 'sudo apt autoremove' to remove them. 434s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 434s Reading package lists... 434s Building dependency tree... 434s Reading state information... 434s Solving dependencies... 434s The following packages will be REMOVED: 434s python3.13* python3.13-minimal* 434s 0 upgraded, 0 newly installed, 2 to remove and 0 not upgraded. 434s After this operation, 9077 kB disk space will be freed. 434s (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 ... 121596 files and directories currently installed.) 434s Removing python3.13 (3.13.11-1) ... 435s Removing python3.13-minimal (3.13.11-1) ... 436s Processing triggers for man-db (2.13.1-1) ... 436s Processing triggers for systemd (259-1ubuntu2) ... 437s (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 ... 121569 files and directories currently installed.) 437s Purging configuration files for python3.13-minimal (3.13.11-1) ... 437s autopkgtest [22:28:55]: rebooting testbed after setup commands that affected boot 480s Reading package lists... 480s Building dependency tree... 480s Reading state information... 480s Solving dependencies... 480s The following NEW packages will be installed: 480s python3-all python3-click python3-croniter python3-redis python3-rq 480s python3.13 python3.13-minimal 480s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 480s Need to get 3747 kB of archives. 480s After this operation, 11.5 MB of additional disk space will be used. 480s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.13-minimal ppc64el 3.13.11-1 [2571 kB] 481s Get:2 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3.13 ppc64el 3.13.11-1 [758 kB] 481s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el python3-all ppc64el 3.14.2-1 [890 B] 481s Get:4 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-click all 8.2.0+0.really.8.1.8-1build1 [80.2 kB] 481s Get:5 http://ftpmaster.internal/ubuntu resolute/main ppc64el python3-croniter all 6.1~rc1-0ubuntu1 [21.7 kB] 481s Get:6 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-redis all 6.4.0-1 [228 kB] 481s Get:7 http://ftpmaster.internal/ubuntu resolute/universe ppc64el python3-rq all 2.6.1-1 [86.3 kB] 481s Fetched 3747 kB in 1s (6864 kB/s) 481s Selecting previously unselected package python3.13-minimal. 481s (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 ... 121571 files and directories currently installed.) 481s Preparing to unpack .../0-python3.13-minimal_3.13.11-1_ppc64el.deb ... 481s Unpacking python3.13-minimal (3.13.11-1) ... 481s Selecting previously unselected package python3.13. 481s Preparing to unpack .../1-python3.13_3.13.11-1_ppc64el.deb ... 481s Unpacking python3.13 (3.13.11-1) ... 481s Selecting previously unselected package python3-all. 481s Preparing to unpack .../2-python3-all_3.14.2-1_ppc64el.deb ... 481s Unpacking python3-all (3.14.2-1) ... 481s Selecting previously unselected package python3-click. 481s Preparing to unpack .../3-python3-click_8.2.0+0.really.8.1.8-1build1_all.deb ... 481s Unpacking python3-click (8.2.0+0.really.8.1.8-1build1) ... 481s Selecting previously unselected package python3-croniter. 481s Preparing to unpack .../4-python3-croniter_6.1~rc1-0ubuntu1_all.deb ... 481s Unpacking python3-croniter (6.1~rc1-0ubuntu1) ... 481s Selecting previously unselected package python3-redis. 481s Preparing to unpack .../5-python3-redis_6.4.0-1_all.deb ... 481s Unpacking python3-redis (6.4.0-1) ... 481s Selecting previously unselected package python3-rq. 481s Preparing to unpack .../6-python3-rq_2.6.1-1_all.deb ... 481s Unpacking python3-rq (2.6.1-1) ... 481s Setting up python3-croniter (6.1~rc1-0ubuntu1) ... 482s Setting up python3-click (8.2.0+0.really.8.1.8-1build1) ... 482s Setting up python3-redis (6.4.0-1) ... 483s Setting up python3.13-minimal (3.13.11-1) ... 483s Setting up python3.13 (3.13.11-1) ... 485s Setting up python3-all (3.14.2-1) ... 485s Setting up python3-rq (2.6.1-1) ... 485s Processing triggers for man-db (2.13.1-1) ... 486s Processing triggers for systemd (259-1ubuntu2) ... 488s autopkgtest [22:29:46]: 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 488s autopkgtest [22:29:46]: test autodep8-python3: [----------------------- 488s Testing with python3.13: 488s 488s Testing with python3.14: 489s 489s autopkgtest [22:29:47]: test autodep8-python3: -----------------------] 489s autopkgtest [22:29:47]: test autodep8-python3: - - - - - - - - - - results - - - - - - - - - - 489s autodep8-python3 PASS (superficial) 490s autopkgtest [22:29:48]: @@@@@@@@@@@@@@@@@@@@ summary 490s smoke FAIL non-zero exit status 162 490s autodep8-python3 PASS (superficial)