0s autopkgtest [01:48:39]: starting date and time: 2024-12-17 01:48:39+0000 0s autopkgtest [01:48:39]: git checkout: c2a78735 Merge branch 'timeout-nova-boot' into 'ubuntu/production' 0s autopkgtest [01:48:39]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.o9bwhzza/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:systemd,src:dpdk,src:netplan.io --apt-upgrade bluez --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=systemd/249.11-0ubuntu3.14 dpdk/21.11.7-0ubuntu0.22.04.1 netplan.io/0.107.1-3ubuntu0.22.04.2' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-arm64-3.secgroup --name adt-jammy-arm64-bluez-20241217-014838-juju-7f2275-prod-proposed-migration-environment-15-b44ad46f-3dd8-4ee9-a676-3ebd9d240637 --image adt/ubuntu-jammy-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/ 270s autopkgtest [01:53:09]: testbed dpkg architecture: arm64 271s autopkgtest [01:53:10]: testbed apt version: 2.4.13 271s autopkgtest [01:53:10]: @@@@@@@@@@@@@@@@@@@@ test bed setup 271s autopkgtest [01:53:10]: testbed release detected to be: None 272s autopkgtest [01:53:11]: updating testbed package index (apt update) 273s Hit:1 http://ftpmaster.internal/ubuntu jammy InRelease 273s Hit:2 http://ftpmaster.internal/ubuntu jammy-updates InRelease 273s Hit:3 http://ftpmaster.internal/ubuntu jammy-security InRelease 273s Get:4 http://ftpmaster.internal/ubuntu jammy-proposed InRelease [279 kB] 273s Get:5 http://ftpmaster.internal/ubuntu jammy-proposed/restricted Sources [29.9 kB] 274s Get:6 http://ftpmaster.internal/ubuntu jammy-proposed/multiverse Sources [4748 B] 274s Get:7 http://ftpmaster.internal/ubuntu jammy-proposed/universe Sources [26.6 kB] 274s Get:8 http://ftpmaster.internal/ubuntu jammy-proposed/main Sources [119 kB] 274s Get:9 http://ftpmaster.internal/ubuntu jammy-proposed/main arm64 Packages [345 kB] 274s Get:10 http://ftpmaster.internal/ubuntu jammy-proposed/main arm64 c-n-f Metadata [1748 B] 274s Get:11 http://ftpmaster.internal/ubuntu jammy-proposed/restricted arm64 Packages [490 kB] 274s Get:12 http://ftpmaster.internal/ubuntu jammy-proposed/restricted arm64 c-n-f Metadata [412 B] 274s Get:13 http://ftpmaster.internal/ubuntu jammy-proposed/universe arm64 Packages [114 kB] 274s Get:14 http://ftpmaster.internal/ubuntu jammy-proposed/universe arm64 c-n-f Metadata [1308 B] 274s Get:15 http://ftpmaster.internal/ubuntu jammy-proposed/multiverse arm64 Packages [2340 B] 274s Get:16 http://ftpmaster.internal/ubuntu jammy-proposed/multiverse arm64 c-n-f Metadata [208 B] 275s Fetched 1415 kB in 1s (1016 kB/s) 277s Reading package lists... 277s Reading package lists... 278s Building dependency tree... 278s Reading state information... 279s Calculating upgrade... 279s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 279s Reading package lists... 280s Building dependency tree... 280s Reading state information... 281s 0 upgraded, 0 newly installed, 0 to remove and 10 not upgraded. 281s autopkgtest [01:53:20]: upgrading testbed (apt dist-upgrade and autopurge) 281s Reading package lists... 281s Building dependency tree... 281s Reading state information... 282s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 282s Starting 2 pkgProblemResolver with broken count: 0 282s Done 282s Entering ResolveByKeep 283s 283s The following NEW packages will be installed: 283s netplan-generator python3-netplan 283s The following packages will be upgraded: 283s libnetplan0 libnss-systemd libpam-systemd libsystemd0 libudev1 netplan.io 283s systemd systemd-sysv systemd-timesyncd udev 283s 10 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 283s Need to get 6978 kB of archives. 283s After this operation, 174 kB of additional disk space will be used. 283s Get:1 http://ftpmaster.internal/ubuntu jammy-proposed/main arm64 libnss-systemd arm64 249.11-0ubuntu3.14 [133 kB] 284s Get:2 http://ftpmaster.internal/ubuntu jammy-proposed/main arm64 libsystemd0 arm64 249.11-0ubuntu3.14 [314 kB] 284s Get:3 http://ftpmaster.internal/ubuntu jammy-proposed/main arm64 systemd-timesyncd arm64 249.11-0ubuntu3.14 [29.9 kB] 284s Get:4 http://ftpmaster.internal/ubuntu jammy-proposed/main arm64 systemd-sysv arm64 249.11-0ubuntu3.14 [10.5 kB] 284s Get:5 http://ftpmaster.internal/ubuntu jammy-proposed/main arm64 libpam-systemd arm64 249.11-0ubuntu3.14 [205 kB] 284s Get:6 http://ftpmaster.internal/ubuntu jammy-proposed/main arm64 systemd arm64 249.11-0ubuntu3.14 [4423 kB] 284s Get:7 http://ftpmaster.internal/ubuntu jammy-proposed/main arm64 udev arm64 249.11-0ubuntu3.14 [1541 kB] 284s Get:8 http://ftpmaster.internal/ubuntu jammy-proposed/main arm64 libudev1 arm64 249.11-0ubuntu3.14 [75.5 kB] 284s Get:9 http://ftpmaster.internal/ubuntu jammy-proposed/main arm64 netplan.io arm64 0.107.1-3ubuntu0.22.04.2 [56.1 kB] 284s Get:10 http://ftpmaster.internal/ubuntu jammy-proposed/main arm64 netplan-generator arm64 0.107.1-3ubuntu0.22.04.2 [56.7 kB] 284s Get:11 http://ftpmaster.internal/ubuntu jammy-proposed/main arm64 python3-netplan arm64 0.107.1-3ubuntu0.22.04.2 [21.6 kB] 284s Get:12 http://ftpmaster.internal/ubuntu jammy-proposed/main arm64 libnetplan0 arm64 0.107.1-3ubuntu0.22.04.2 [111 kB] 284s Fetched 6978 kB in 1s (9031 kB/s) 285s (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 ... 102247 files and directories currently installed.) 285s Preparing to unpack .../libnss-systemd_249.11-0ubuntu3.14_arm64.deb ... 285s Unpacking libnss-systemd:arm64 (249.11-0ubuntu3.14) over (249.11-0ubuntu3.12) ... 285s Preparing to unpack .../libsystemd0_249.11-0ubuntu3.14_arm64.deb ... 285s Unpacking libsystemd0:arm64 (249.11-0ubuntu3.14) over (249.11-0ubuntu3.12) ... 285s Setting up libsystemd0:arm64 (249.11-0ubuntu3.14) ... 285s (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 ... 102247 files and directories currently installed.) 285s Preparing to unpack .../0-systemd-timesyncd_249.11-0ubuntu3.14_arm64.deb ... 285s Unpacking systemd-timesyncd (249.11-0ubuntu3.14) over (249.11-0ubuntu3.12) ... 285s Preparing to unpack .../1-systemd-sysv_249.11-0ubuntu3.14_arm64.deb ... 285s Unpacking systemd-sysv (249.11-0ubuntu3.14) over (249.11-0ubuntu3.12) ... 285s Preparing to unpack .../2-libpam-systemd_249.11-0ubuntu3.14_arm64.deb ... 285s Unpacking libpam-systemd:arm64 (249.11-0ubuntu3.14) over (249.11-0ubuntu3.12) ... 285s Preparing to unpack .../3-systemd_249.11-0ubuntu3.14_arm64.deb ... 285s Unpacking systemd (249.11-0ubuntu3.14) over (249.11-0ubuntu3.12) ... 285s Preparing to unpack .../4-udev_249.11-0ubuntu3.14_arm64.deb ... 285s Unpacking udev (249.11-0ubuntu3.14) over (249.11-0ubuntu3.12) ... 285s Preparing to unpack .../5-libudev1_249.11-0ubuntu3.14_arm64.deb ... 285s Unpacking libudev1:arm64 (249.11-0ubuntu3.14) over (249.11-0ubuntu3.12) ... 285s Setting up libudev1:arm64 (249.11-0ubuntu3.14) ... 285s (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 ... 102247 files and directories currently installed.) 285s Preparing to unpack .../netplan.io_0.107.1-3ubuntu0.22.04.2_arm64.deb ... 285s Unpacking netplan.io (0.107.1-3ubuntu0.22.04.2) over (0.106.1-7ubuntu0.22.04.4) ... 285s dpkg: warning: unable to delete old directory '/etc/netplan': Directory not empty 285s Selecting previously unselected package netplan-generator. 285s Preparing to unpack .../netplan-generator_0.107.1-3ubuntu0.22.04.2_arm64.deb ... 285s Unpacking netplan-generator (0.107.1-3ubuntu0.22.04.2) ... 285s Selecting previously unselected package python3-netplan. 285s Preparing to unpack .../python3-netplan_0.107.1-3ubuntu0.22.04.2_arm64.deb ... 285s Unpacking python3-netplan (0.107.1-3ubuntu0.22.04.2) ... 285s Preparing to unpack .../libnetplan0_0.107.1-3ubuntu0.22.04.2_arm64.deb ... 285s Unpacking libnetplan0:arm64 (0.107.1-3ubuntu0.22.04.2) over (0.106.1-7ubuntu0.22.04.4) ... 285s Setting up libnetplan0:arm64 (0.107.1-3ubuntu0.22.04.2) ... 285s Setting up systemd (249.11-0ubuntu3.14) ... 286s Setting up systemd-timesyncd (249.11-0ubuntu3.14) ... 287s Setting up udev (249.11-0ubuntu3.14) ... 287s Setting up python3-netplan (0.107.1-3ubuntu0.22.04.2) ... 287s Setting up netplan-generator (0.107.1-3ubuntu0.22.04.2) ... 287s Setting up systemd-sysv (249.11-0ubuntu3.14) ... 287s Setting up libnss-systemd:arm64 (249.11-0ubuntu3.14) ... 287s Setting up netplan.io (0.107.1-3ubuntu0.22.04.2) ... 287s Setting up libpam-systemd:arm64 (249.11-0ubuntu3.14) ... 288s Processing triggers for libc-bin (2.35-0ubuntu3.8) ... 288s Processing triggers for man-db (2.10.2-1) ... 288s Processing triggers for dbus (1.12.20-2ubuntu4.1) ... 288s Processing triggers for initramfs-tools (0.140ubuntu13.4) ... 288s update-initramfs: Generating /boot/initrd.img-5.15.0-127-generic 288s No lz4 in /usr/bin:/sbin:/bin, using gzip 322s System running in EFI mode, skipping. 323s Reading package lists... 324s Building dependency tree... 324s Reading state information... 324s Starting pkgProblemResolver with broken count: 0 325s Starting 2 pkgProblemResolver with broken count: 0 325s Done 326s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 326s autopkgtest [01:54:05]: rebooting testbed after setup commands that affected boot 352s autopkgtest [01:54:31]: testbed running kernel: Linux 5.15.0-127-generic #137-Ubuntu SMP Fri Nov 8 15:15:28 UTC 2024 354s autopkgtest [01:54:33]: @@@@@@@@@@@@@@@@@@@@ apt-source bluez 362s Get:1 http://ftpmaster.internal/ubuntu jammy-updates/main bluez 5.64-0ubuntu1.3 (dsc) [2807 B] 362s Get:2 http://ftpmaster.internal/ubuntu jammy-updates/main bluez 5.64-0ubuntu1.3 (tar) [2175 kB] 362s Get:3 http://ftpmaster.internal/ubuntu jammy-updates/main bluez 5.64-0ubuntu1.3 (diff) [39.4 kB] 362s gpgv: Signature made Wed Jun 5 13:49:33 2024 UTC 362s gpgv: using RSA key D9682DBE6C2672068404B967080BCAD50BC3E920 362s gpgv: issuer "fabian.toepfer@canonical.com" 362s gpgv: Can't check signature: No public key 362s dpkg-source: warning: cannot verify signature ./bluez_5.64-0ubuntu1.3.dsc 362s autopkgtest [01:54:41]: testing package bluez version 5.64-0ubuntu1.3 363s autopkgtest [01:54:42]: build not needed 364s autopkgtest [01:54:43]: test bluez_response: preparing testbed 365s Reading package lists... 365s Building dependency tree... 365s Reading state information... 365s Starting pkgProblemResolver with broken count: 0 365s Starting 2 pkgProblemResolver with broken count: 0 365s Done 366s The following NEW packages will be installed: 366s aptdaemon bluez bluez-tests python3-aptdaemon python3-aptdaemon.test 366s python3-defer 366s 0 upgraded, 6 newly installed, 0 to remove and 0 not upgraded. 366s Need to get 1620 kB of archives. 366s After this operation, 6743 kB of additional disk space will be used. 366s Get:1 http://ftpmaster.internal/ubuntu jammy-updates/main arm64 bluez arm64 5.64-0ubuntu1.3 [1078 kB] 367s Get:2 http://ftpmaster.internal/ubuntu jammy-updates/universe arm64 bluez-tests arm64 5.64-0ubuntu1.3 [353 kB] 367s Get:3 http://ftpmaster.internal/ubuntu jammy/main arm64 python3-defer all 1.0.6-2.1ubuntu1 [11.0 kB] 367s Get:4 http://ftpmaster.internal/ubuntu jammy/main arm64 python3-aptdaemon all 1.1.1+bzr982-0ubuntu39 [77.2 kB] 367s Get:5 http://ftpmaster.internal/ubuntu jammy/main arm64 aptdaemon all 1.1.1+bzr982-0ubuntu39 [12.9 kB] 367s Get:6 http://ftpmaster.internal/ubuntu jammy/universe arm64 python3-aptdaemon.test all 1.1.1+bzr982-0ubuntu39 [87.3 kB] 367s Fetched 1620 kB in 1s (2222 kB/s) 367s Selecting previously unselected package bluez. 368s (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 ... 102264 files and directories currently installed.) 368s Preparing to unpack .../0-bluez_5.64-0ubuntu1.3_arm64.deb ... 368s Unpacking bluez (5.64-0ubuntu1.3) ... 368s Selecting previously unselected package bluez-tests. 368s Preparing to unpack .../1-bluez-tests_5.64-0ubuntu1.3_arm64.deb ... 368s Unpacking bluez-tests (5.64-0ubuntu1.3) ... 368s Selecting previously unselected package python3-defer. 368s Preparing to unpack .../2-python3-defer_1.0.6-2.1ubuntu1_all.deb ... 368s Unpacking python3-defer (1.0.6-2.1ubuntu1) ... 368s Selecting previously unselected package python3-aptdaemon. 368s Preparing to unpack .../3-python3-aptdaemon_1.1.1+bzr982-0ubuntu39_all.deb ... 368s Unpacking python3-aptdaemon (1.1.1+bzr982-0ubuntu39) ... 368s Selecting previously unselected package aptdaemon. 368s Preparing to unpack .../4-aptdaemon_1.1.1+bzr982-0ubuntu39_all.deb ... 368s Unpacking aptdaemon (1.1.1+bzr982-0ubuntu39) ... 368s Selecting previously unselected package python3-aptdaemon.test. 368s Preparing to unpack .../5-python3-aptdaemon.test_1.1.1+bzr982-0ubuntu39_all.deb ... 368s Unpacking python3-aptdaemon.test (1.1.1+bzr982-0ubuntu39) ... 368s Setting up bluez-tests (5.64-0ubuntu1.3) ... 368s Setting up bluez (5.64-0ubuntu1.3) ... 368s Created symlink /etc/systemd/system/dbus-org.bluez.service → /lib/systemd/system/bluetooth.service. 368s Created symlink /etc/systemd/system/bluetooth.target.wants/bluetooth.service → /lib/systemd/system/bluetooth.service. 369s Setting up python3-defer (1.0.6-2.1ubuntu1) ... 369s Setting up aptdaemon (1.1.1+bzr982-0ubuntu39) ... 369s Setting up python3-aptdaemon (1.1.1+bzr982-0ubuntu39) ... 369s Setting up python3-aptdaemon.test (1.1.1+bzr982-0ubuntu39) ... 370s Processing triggers for man-db (2.10.2-1) ... 370s Processing triggers for dbus (1.12.20-2ubuntu4.1) ... 371s autopkgtest [01:54:50]: test bluez_response: [----------------------- 372s testAdapter (__main__.TestBluezResponse) ... skipped 'No bluetooth devices available for testing' 373s testDevice (__main__.TestBluezResponse) ... skipped 'No bluetooth devices available for testing' 373s 373s ---------------------------------------------------------------------- 373s Ran 2 tests in 1.313s 373s 373s OK (skipped=2) 373s autopkgtest [01:54:52]: test bluez_response: -----------------------] 374s autopkgtest [01:54:53]: test bluez_response: - - - - - - - - - - results - - - - - - - - - - 374s bluez_response PASS 374s autopkgtest [01:54:53]: @@@@@@@@@@@@@@@@@@@@ summary 374s bluez_response PASS 392s nova [W] Using flock in prodstack6-arm64 392s flock: timeout while waiting to get lock 392s Creating nova instance adt-jammy-arm64-bluez-20241217-014838-juju-7f2275-prod-proposed-migration-environment-15-b44ad46f-3dd8-4ee9-a676-3ebd9d240637 from image adt/ubuntu-jammy-arm64-server-20241217.img (UUID 99807863-5739-4719-a2bb-c5786471d9f3)... 392s nova [W] Timed out waiting for 6eeb5cec-087f-43eb-ac46-31963985f4eb to get deleted.