0s autopkgtest [12:13:57]: starting date and time: 2025-02-19 12:13:57+0000 0s autopkgtest [12:13:57]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [12:13:57]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.4jkl92ri/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:sphinx --apt-upgrade pyhamtools --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=sphinx/8.1.3-5 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-arm64-11.secgroup --name adt-plucky-arm64-pyhamtools-20250219-121357-juju-7f2275-prod-proposed-migration-environment-15-59555888-a005-4752-b1c7-1d2797f5ce7d --image adt/ubuntu-plucky-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 157s autopkgtest [12:16:34]: testbed dpkg architecture: arm64 158s autopkgtest [12:16:35]: testbed apt version: 2.9.29 158s autopkgtest [12:16:35]: @@@@@@@@@@@@@@@@@@@@ test bed setup 158s autopkgtest [12:16:35]: testbed release detected to be: None 159s autopkgtest [12:16:36]: updating testbed package index (apt update) 159s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 160s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 160s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 160s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 160s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [13.9 kB] 160s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [76.1 kB] 160s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [750 kB] 160s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3120 B] 160s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [93.3 kB] 160s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 Packages [7960 B] 160s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [686 kB] 160s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [11.2 kB] 160s Fetched 1752 kB in 1s (1897 kB/s) 161s Reading package lists... 162s + lsb_release --codename --short 162s + RELEASE=plucky 162s + cat 162s + [ plucky != trusty ] 162s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 162s Reading package lists... 162s Building dependency tree... 162s Reading state information... 163s Calculating upgrade... 163s The following NEW packages will be installed: 163s libapt-pkg7.0 163s The following packages will be upgraded: 163s apt apt-utils iproute2 liblsof0 libp11-kit0 lsof rsyslog 163s 7 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 163s Need to get 4842 kB of archives. 163s After this operation, 3409 kB of additional disk space will be used. 163s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 libapt-pkg7.0 arm64 2.9.30 [1023 kB] 164s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 apt arm64 2.9.30 [1364 kB] 164s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 apt-utils arm64 2.9.30 [207 kB] 164s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 iproute2 arm64 6.13.0-1ubuntu1 [1158 kB] 165s Get:5 http://ftpmaster.internal/ubuntu plucky/main arm64 libp11-kit0 arm64 0.25.5-2ubuntu3 [280 kB] 165s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 rsyslog arm64 8.2412.0-2ubuntu1 [521 kB] 165s Get:7 http://ftpmaster.internal/ubuntu plucky/main arm64 lsof arm64 4.99.4+dfsg-1 [236 kB] 165s Get:8 http://ftpmaster.internal/ubuntu plucky/main arm64 liblsof0 arm64 4.99.4+dfsg-1 [53.9 kB] 166s Preconfiguring packages ... 166s Fetched 4842 kB in 2s (2939 kB/s) 166s Selecting previously unselected package libapt-pkg7.0:arm64. 166s (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 ... 116620 files and directories currently installed.) 167s Preparing to unpack .../libapt-pkg7.0_2.9.30_arm64.deb ... 167s Unpacking libapt-pkg7.0:arm64 (2.9.30) ... 167s Setting up libapt-pkg7.0:arm64 (2.9.30) ... 167s (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 ... 116669 files and directories currently installed.) 167s Preparing to unpack .../archives/apt_2.9.30_arm64.deb ... 167s Unpacking apt (2.9.30) over (2.9.29) ... 167s Setting up apt (2.9.30) ... 168s (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 ... 116669 files and directories currently installed.) 168s Preparing to unpack .../0-apt-utils_2.9.30_arm64.deb ... 168s Unpacking apt-utils (2.9.30) over (2.9.29) ... 168s Preparing to unpack .../1-iproute2_6.13.0-1ubuntu1_arm64.deb ... 168s Unpacking iproute2 (6.13.0-1ubuntu1) over (6.10.0-2ubuntu1) ... 168s Preparing to unpack .../2-libp11-kit0_0.25.5-2ubuntu3_arm64.deb ... 168s Unpacking libp11-kit0:arm64 (0.25.5-2ubuntu3) over (0.25.5-2ubuntu2) ... 168s Preparing to unpack .../3-rsyslog_8.2412.0-2ubuntu1_arm64.deb ... 168s Unpacking rsyslog (8.2412.0-2ubuntu1) over (8.2412.0-1ubuntu1) ... 168s Preparing to unpack .../4-lsof_4.99.4+dfsg-1_arm64.deb ... 168s Unpacking lsof (4.99.4+dfsg-1) over (4.99.3+dfsg-2) ... 168s Preparing to unpack .../5-liblsof0_4.99.4+dfsg-1_arm64.deb ... 168s Unpacking liblsof0 (4.99.4+dfsg-1) over (4.99.3+dfsg-2) ... 168s Setting up apt-utils (2.9.30) ... 168s Setting up liblsof0 (4.99.4+dfsg-1) ... 168s Setting up iproute2 (6.13.0-1ubuntu1) ... 169s Setting up rsyslog (8.2412.0-2ubuntu1) ... 169s info: The user `syslog' is already a member of `adm'. 170s Setting up libp11-kit0:arm64 (0.25.5-2ubuntu3) ... 170s Setting up lsof (4.99.4+dfsg-1) ... 170s Processing triggers for man-db (2.13.0-1) ... 173s Processing triggers for libc-bin (2.40-4ubuntu1) ... 173s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 173s + /usr/lib/apt/apt-helper analyze-pattern ?true 173s + uname -r 173s + sed s/\./\\./g 173s + running_kernel_pattern=^linux-.*6\.12\.0-15-generic.* 173s + apt list ?obsolete 173s + tail -n+2 173s + grep -v ^linux-.*6\.12\.0-15-generic.* 173s + cut -d/ -f1 174s + true 174s + obsolete_pkgs= 174s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove 174s Reading package lists... 175s Building dependency tree... 175s Reading state information... 175s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 175s + grep -q trusty /etc/lsb-release 175s + [ ! -d /usr/share/doc/unattended-upgrades ] 175s + [ ! -d /usr/share/doc/lxd ] 175s + [ ! -d /usr/share/doc/lxd-client ] 175s + [ ! -d /usr/share/doc/snapd ] 175s + type iptables 175s + cat 175s + chmod 755 /etc/rc.local 175s + . /etc/rc.local 175s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 175s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 175s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 175s + uname -m 175s + [ aarch64 = ppc64le ] 175s + [ -d /run/systemd/system ] 175s + systemd-detect-virt --quiet --vm 175s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 175s + cat 175s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 175s + echo COMPRESS=lz4 176s autopkgtest [12:16:53]: upgrading testbed (apt dist-upgrade and autopurge) 176s Reading package lists... 176s Building dependency tree... 176s Reading state information... 177s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 177s Starting 2 pkgProblemResolver with broken count: 0 177s Done 178s Entering ResolveByKeep 178s 178s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 179s Reading package lists... 179s Building dependency tree... 179s Reading state information... 179s Starting pkgProblemResolver with broken count: 0 179s Starting 2 pkgProblemResolver with broken count: 0 179s Done 180s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 181s autopkgtest [12:16:58]: rebooting testbed after setup commands that affected boot 215s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 221s autopkgtest [12:17:38]: testbed running kernel: Linux 6.12.0-15-generic #15-Ubuntu SMP PREEMPT_DYNAMIC Tue Feb 4 15:49:33 UTC 2025 224s autopkgtest [12:17:41]: @@@@@@@@@@@@@@@@@@@@ apt-source pyhamtools 228s Get:1 http://ftpmaster.internal/ubuntu plucky/universe pyhamtools 0.10.0-1 (dsc) [2121 B] 228s Get:2 http://ftpmaster.internal/ubuntu plucky/universe pyhamtools 0.10.0-1 (tar) [5507 kB] 228s Get:3 http://ftpmaster.internal/ubuntu plucky/universe pyhamtools 0.10.0-1 (diff) [3564 B] 228s gpgv: Signature made Tue Jun 4 20:30:07 2024 UTC 228s gpgv: using RSA key 5C48FE6157F49179597087C64C5A6BAB12D2A7AE 228s gpgv: Can't check signature: No public key 228s dpkg-source: warning: cannot verify inline signature for ./pyhamtools_0.10.0-1.dsc: no acceptable signature found 228s autopkgtest [12:17:45]: testing package pyhamtools version 0.10.0-1 229s autopkgtest [12:17:46]: build not needed 230s autopkgtest [12:17:47]: test test: preparing testbed 231s Reading package lists... 231s Building dependency tree... 231s Reading state information... 231s Starting pkgProblemResolver with broken count: 0 231s Starting 2 pkgProblemResolver with broken count: 0 231s Done 232s The following NEW packages will be installed: 232s libjs-jquery libjs-sphinxdoc libjs-underscore libxslt1.1 232s python3-async-timeout python3-bs4 python3-deprecated python3-ephem 232s python3-lxml python3-packaging python3-pyhamtools python3-pytz python3-redis 232s python3-soupsieve python3-tz python3-wrapt 233s 0 upgraded, 16 newly installed, 0 to remove and 0 not upgraded. 233s Need to get 4222 kB of archives. 233s After this operation, 18.5 MB of additional disk space will be used. 233s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 233s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 233s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 libjs-sphinxdoc all 8.1.3-5 [31.0 kB] 233s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 libxslt1.1 arm64 1.1.39-0exp1ubuntu2 [168 kB] 233s Get:5 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-async-timeout all 5.0.1-1 [6830 B] 233s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-soupsieve all 2.6-1 [33.0 kB] 233s Get:7 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-bs4 all 4.13.3-1 [136 kB] 233s Get:8 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-wrapt arm64 1.15.0-4 [34.3 kB] 233s Get:9 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-deprecated all 1.2.18-1 [10.6 kB] 233s Get:10 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-ephem arm64 4.1.6-1build1 [1326 kB] 233s Get:11 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-lxml arm64 5.3.1-1 [1600 kB] 234s Get:12 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-packaging all 24.2-1 [51.5 kB] 234s Get:13 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-pytz all 2025.1-3 [162 kB] 234s Get:14 http://ftpmaster.internal/ubuntu plucky/main arm64 python3-tz all 2025.1-3 [1866 B] 234s Get:15 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-redis all 4.3.4-4 [166 kB] 234s Get:16 http://ftpmaster.internal/ubuntu plucky/universe arm64 python3-pyhamtools all 0.10.0-1 [49.5 kB] 234s Fetched 4222 kB in 1s (3127 kB/s) 234s Selecting previously unselected package libjs-jquery. 235s (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 ... 116670 files and directories currently installed.) 235s Preparing to unpack .../00-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 235s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 235s Selecting previously unselected package libjs-underscore. 235s Preparing to unpack .../01-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 235s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 235s Selecting previously unselected package libjs-sphinxdoc. 235s Preparing to unpack .../02-libjs-sphinxdoc_8.1.3-5_all.deb ... 235s Unpacking libjs-sphinxdoc (8.1.3-5) ... 235s Selecting previously unselected package libxslt1.1:arm64. 235s Preparing to unpack .../03-libxslt1.1_1.1.39-0exp1ubuntu2_arm64.deb ... 235s Unpacking libxslt1.1:arm64 (1.1.39-0exp1ubuntu2) ... 235s Selecting previously unselected package python3-async-timeout. 235s Preparing to unpack .../04-python3-async-timeout_5.0.1-1_all.deb ... 235s Unpacking python3-async-timeout (5.0.1-1) ... 235s Selecting previously unselected package python3-soupsieve. 235s Preparing to unpack .../05-python3-soupsieve_2.6-1_all.deb ... 235s Unpacking python3-soupsieve (2.6-1) ... 235s Selecting previously unselected package python3-bs4. 235s Preparing to unpack .../06-python3-bs4_4.13.3-1_all.deb ... 235s Unpacking python3-bs4 (4.13.3-1) ... 235s Selecting previously unselected package python3-wrapt. 235s Preparing to unpack .../07-python3-wrapt_1.15.0-4_arm64.deb ... 235s Unpacking python3-wrapt (1.15.0-4) ... 235s Selecting previously unselected package python3-deprecated. 236s Preparing to unpack .../08-python3-deprecated_1.2.18-1_all.deb ... 236s Unpacking python3-deprecated (1.2.18-1) ... 236s Selecting previously unselected package python3-ephem. 236s Preparing to unpack .../09-python3-ephem_4.1.6-1build1_arm64.deb ... 236s Unpacking python3-ephem (4.1.6-1build1) ... 236s Selecting previously unselected package python3-lxml:arm64. 236s Preparing to unpack .../10-python3-lxml_5.3.1-1_arm64.deb ... 236s Unpacking python3-lxml:arm64 (5.3.1-1) ... 236s Selecting previously unselected package python3-packaging. 236s Preparing to unpack .../11-python3-packaging_24.2-1_all.deb ... 236s Unpacking python3-packaging (24.2-1) ... 236s Selecting previously unselected package python3-pytz. 236s Preparing to unpack .../12-python3-pytz_2025.1-3_all.deb ... 236s Unpacking python3-pytz (2025.1-3) ... 236s Selecting previously unselected package python3-tz. 236s Preparing to unpack .../13-python3-tz_2025.1-3_all.deb ... 236s Unpacking python3-tz (2025.1-3) ... 236s Selecting previously unselected package python3-redis. 236s Preparing to unpack .../14-python3-redis_4.3.4-4_all.deb ... 236s Unpacking python3-redis (4.3.4-4) ... 236s Selecting previously unselected package python3-pyhamtools. 236s Preparing to unpack .../15-python3-pyhamtools_0.10.0-1_all.deb ... 236s Unpacking python3-pyhamtools (0.10.0-1) ... 236s Setting up python3-ephem (4.1.6-1build1) ... 237s Setting up python3-pytz (2025.1-3) ... 237s Setting up python3-tz (2025.1-3) ... 237s Setting up python3-async-timeout (5.0.1-1) ... 237s Setting up python3-packaging (24.2-1) ... 237s Setting up python3-wrapt (1.15.0-4) ... 237s Setting up libxslt1.1:arm64 (1.1.39-0exp1ubuntu2) ... 237s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 237s Setting up python3-soupsieve (2.6-1) ... 238s Setting up python3-deprecated (1.2.18-1) ... 238s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 238s Setting up python3-bs4 (4.13.3-1) ... 238s Setting up python3-redis (4.3.4-4) ... 238s Setting up libjs-sphinxdoc (8.1.3-5) ... 238s Setting up python3-lxml:arm64 (5.3.1-1) ... 239s Setting up python3-pyhamtools (0.10.0-1) ... 239s Processing triggers for libc-bin (2.40-4ubuntu1) ... 240s autopkgtest [12:17:57]: test test: [----------------------- 242s 240.98586185281698 242s {'morning_dawn': datetime.datetime(2025, 2, 20, 6, 1, 57, 523966, tzinfo=datetime.timezone.utc), 'sunrise': datetime.datetime(2025, 2, 20, 6, 36, 28, 923800, tzinfo=datetime.timezone.utc), 'evening_dawn': datetime.datetime(2025, 2, 19, 16, 56, 50, 189525, tzinfo=datetime.timezone.utc), 'sunset': datetime.datetime(2025, 2, 19, 17, 31, 29, 626592, tzinfo=datetime.timezone.utc)} 242s {'band': 20, 'mode': 'CW'} 242s {'country': 'Fed. Rep. of Germany', 'adif': 230, 'cqz': 14, 'ituz': 28, 'continent': 'EU', 'latitude': 51.0, 'longitude': 10.0} 243s autopkgtest [12:18:00]: test test: -----------------------] 243s test PASS 243s autopkgtest [12:18:00]: test test: - - - - - - - - - - results - - - - - - - - - - 244s autopkgtest [12:18:01]: @@@@@@@@@@@@@@@@@@@@ summary 244s test PASS 273s nova [W] Using flock in prodstack6-arm64 273s flock: timeout while waiting to get lock 273s Creating nova instance adt-plucky-arm64-pyhamtools-20250219-121357-juju-7f2275-prod-proposed-migration-environment-15-59555888-a005-4752-b1c7-1d2797f5ce7d from image adt/ubuntu-plucky-arm64-server-20250219.img (UUID 02c92adb-9d9d-4b0e-b071-488d8ee6210f)... 273s nova [W] Timed out waiting for 162f213a-ff11-4b2e-8513-6a130c5f01e7 to get deleted.