0s autopkgtest [23:54:44]: starting date and time: 2025-03-11 23:54:44+0000 0s autopkgtest [23:54:44]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [23:54:44]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.13dhq8_v/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:systemd --apt-upgrade bluez --timeout-short=1200 --timeout-copy=80000 --timeout-build=80000 --env=ADT_TEST_TRIGGERS=systemd/257.4-1ubuntu1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-riscv64-30.secgroup --name adt-plucky-riscv64-bluez-20250311-235441-juju-7f2275-prod-proposed-migration-environment-2-30d0374b-79e6-4dc6-80fe-a4539b4787f4 --image adt/ubuntu-plucky-riscv64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-riscv64 -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/ 1553s autopkgtest [00:20:37]: testbed dpkg architecture: riscv64 1554s autopkgtest [00:20:38]: testbed apt version: 2.9.31ubuntu3 1558s autopkgtest [00:20:42]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1560s autopkgtest [00:20:44]: testbed release detected to be: None 1566s autopkgtest [00:20:50]: updating testbed package index (apt update) 1569s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 1570s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 1570s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 1570s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 1571s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [2420 B] 1572s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [45.0 kB] 1572s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [398 kB] 1573s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [11.5 kB] 1573s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 Packages [58.1 kB] 1573s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/universe riscv64 Packages [298 kB] 1573s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse riscv64 Packages [2516 B] 1576s Fetched 926 kB in 6s (161 kB/s) 1598s Reading package lists... 1606s Reading package lists... 1609s Building dependency tree... 1609s Reading state information... 1611s Calculating upgrade... 1613s Calculating upgrade... 1615s The following packages will be upgraded: 1615s apt apt-utils libapt-pkg7.0 linux-firmware pci.ids 1615s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1615s Need to get 575 MB of archives. 1615s After this operation, 5061 kB of additional disk space will be used. 1615s Get:1 http://ftpmaster.internal/ubuntu plucky/main riscv64 libapt-pkg7.0 riscv64 2.9.32ubuntu1 [1177 kB] 1616s Get:2 http://ftpmaster.internal/ubuntu plucky/main riscv64 apt riscv64 2.9.32ubuntu1 [1415 kB] 1616s Get:3 http://ftpmaster.internal/ubuntu plucky/main riscv64 apt-utils riscv64 2.9.32ubuntu1 [231 kB] 1617s Get:4 http://ftpmaster.internal/ubuntu plucky/main riscv64 pci.ids all 0.0~2025.03.09-1 [285 kB] 1617s Get:5 http://ftpmaster.internal/ubuntu plucky/main riscv64 linux-firmware riscv64 20250310.git9e1370d3-0ubuntu1 [571 MB] 1750s Fetched 575 MB in 2min 7s (4515 kB/s) 1752s (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 ... 75444 files and directories currently installed.) 1752s Preparing to unpack .../libapt-pkg7.0_2.9.32ubuntu1_riscv64.deb ... 1752s Unpacking libapt-pkg7.0:riscv64 (2.9.32ubuntu1) over (2.9.31ubuntu3) ... 1754s Setting up libapt-pkg7.0:riscv64 (2.9.32ubuntu1) ... 1756s (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 ... 75444 files and directories currently installed.) 1756s Preparing to unpack .../apt_2.9.32ubuntu1_riscv64.deb ... 1758s Unpacking apt (2.9.32ubuntu1) over (2.9.31ubuntu3) ... 1761s Setting up apt (2.9.32ubuntu1) ... 1782s (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 ... 75444 files and directories currently installed.) 1782s Preparing to unpack .../apt-utils_2.9.32ubuntu1_riscv64.deb ... 1782s Unpacking apt-utils (2.9.32ubuntu1) over (2.9.31ubuntu3) ... 1783s Preparing to unpack .../pci.ids_0.0~2025.03.09-1_all.deb ... 1783s Unpacking pci.ids (0.0~2025.03.09-1) over (0.0~2025.02.12-1) ... 1784s Preparing to unpack .../linux-firmware_20250310.git9e1370d3-0ubuntu1_riscv64.deb ... 1784s Unpacking linux-firmware (20250310.git9e1370d3-0ubuntu1) over (20250204.git0fd450ee-0ubuntu1) ... 1900s Setting up pci.ids (0.0~2025.03.09-1) ... 1900s Setting up apt-utils (2.9.32ubuntu1) ... 1900s Setting up linux-firmware (20250310.git9e1370d3-0ubuntu1) ... 1900s Processing triggers for man-db (2.13.0-1) ... 1911s Processing triggers for libc-bin (2.41-1ubuntu1) ... 1911s Processing triggers for initramfs-tools (0.146ubuntu1) ... 1913s update-initramfs: Generating /boot/initrd.img-6.11.0-8-generic 1914s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 2320s Reading package lists... 2324s Building dependency tree... 2324s Reading state information... 2326s Solving dependencies... 2328s 0 upgraded, 0 newly installed, 0 to remove and 11 not upgraded. 2329s autopkgtest [00:33:33]: upgrading testbed (apt dist-upgrade and autopurge) 2331s Reading package lists... 2333s Building dependency tree... 2333s Reading state information... 2336s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 2337s Starting 2 pkgProblemResolver with broken count: 0 2337s Done 2342s Entering ResolveByKeep 2344s 2345s Calculating upgrade... 2346s The following packages will be upgraded: 2346s libnss-systemd libpam-systemd libsystemd-shared libsystemd0 libudev1 systemd 2346s systemd-cryptsetup systemd-resolved systemd-sysv systemd-timesyncd udev 2347s 11 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 2347s Need to get 8586 kB of archives. 2347s After this operation, 5120 B of additional disk space will be used. 2347s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libsystemd0 riscv64 257.4-1ubuntu1 [557 kB] 2347s Get:2 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 systemd-sysv riscv64 257.4-1ubuntu1 [11.9 kB] 2347s Get:3 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 systemd-resolved riscv64 257.4-1ubuntu1 [339 kB] 2347s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libnss-systemd riscv64 257.4-1ubuntu1 [182 kB] 2347s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libpam-systemd riscv64 257.4-1ubuntu1 [269 kB] 2347s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libsystemd-shared riscv64 257.4-1ubuntu1 [2392 kB] 2348s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 systemd riscv64 257.4-1ubuntu1 [3057 kB] 2348s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 systemd-timesyncd riscv64 257.4-1ubuntu1 [42.4 kB] 2348s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 systemd-cryptsetup riscv64 257.4-1ubuntu1 [125 kB] 2348s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 udev riscv64 257.4-1ubuntu1 [1402 kB] 2349s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/main riscv64 libudev1 riscv64 257.4-1ubuntu1 [208 kB] 2356s Fetched 8586 kB in 3s (3275 kB/s) 2357s (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 ... 75702 files and directories currently installed.) 2357s Preparing to unpack .../libsystemd0_257.4-1ubuntu1_riscv64.deb ... 2357s Unpacking libsystemd0:riscv64 (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 2358s Setting up libsystemd0:riscv64 (257.4-1ubuntu1) ... 2360s (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 ... 75702 files and directories currently installed.) 2360s Preparing to unpack .../systemd-sysv_257.4-1ubuntu1_riscv64.deb ... 2360s Unpacking systemd-sysv (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 2361s Preparing to unpack .../systemd-resolved_257.4-1ubuntu1_riscv64.deb ... 2361s Unpacking systemd-resolved (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 2362s Preparing to unpack .../libnss-systemd_257.4-1ubuntu1_riscv64.deb ... 2362s Unpacking libnss-systemd:riscv64 (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 2363s Preparing to unpack .../libpam-systemd_257.4-1ubuntu1_riscv64.deb ... 2363s Unpacking libpam-systemd:riscv64 (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 2364s Preparing to unpack .../libsystemd-shared_257.4-1ubuntu1_riscv64.deb ... 2364s Unpacking libsystemd-shared:riscv64 (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 2367s Setting up libsystemd-shared:riscv64 (257.4-1ubuntu1) ... 2369s (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 ... 75702 files and directories currently installed.) 2369s Preparing to unpack .../systemd_257.4-1ubuntu1_riscv64.deb ... 2370s Unpacking systemd (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 2375s Preparing to unpack .../systemd-timesyncd_257.4-1ubuntu1_riscv64.deb ... 2375s Unpacking systemd-timesyncd (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 2376s Preparing to unpack .../systemd-cryptsetup_257.4-1ubuntu1_riscv64.deb ... 2376s Unpacking systemd-cryptsetup (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 2377s Preparing to unpack .../udev_257.4-1ubuntu1_riscv64.deb ... 2378s Unpacking udev (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 2381s Preparing to unpack .../libudev1_257.4-1ubuntu1_riscv64.deb ... 2381s Unpacking libudev1:riscv64 (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 2382s Setting up libudev1:riscv64 (257.4-1ubuntu1) ... 2382s Setting up systemd (257.4-1ubuntu1) ... 2383s /usr/lib/tmpfiles.d/legacy.conf:14: Duplicate line for path "/run/lock", ignoring. 2384s Created symlink '/run/systemd/system/tmp.mount' → '/dev/null'. 2392s /usr/lib/tmpfiles.d/legacy.conf:14: Duplicate line for path "/run/lock", ignoring. 2404s Setting up systemd-cryptsetup (257.4-1ubuntu1) ... 2404s Setting up systemd-timesyncd (257.4-1ubuntu1) ... 2420s systemd-time-wait-sync.service is a disabled or a static unit not running, not starting it. 2422s Setting up udev (257.4-1ubuntu1) ... 2441s Setting up systemd-resolved (257.4-1ubuntu1) ... 2455s Setting up systemd-sysv (257.4-1ubuntu1) ... 2456s Setting up libnss-systemd:riscv64 (257.4-1ubuntu1) ... 2456s Setting up libpam-systemd:riscv64 (257.4-1ubuntu1) ... 2461s Processing triggers for libc-bin (2.41-1ubuntu1) ... 2462s Processing triggers for man-db (2.13.0-1) ... 2467s Processing triggers for dbus (1.16.2-1ubuntu1) ... 2467s Processing triggers for shared-mime-info (2.4-5) ... 2468s Warning: program compiled against libxml 212 using older 209 2476s Processing triggers for initramfs-tools (0.146ubuntu1) ... 2478s update-initramfs: Generating /boot/initrd.img-6.11.0-8-generic 2478s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 2858s Reading package lists... 2861s Building dependency tree... 2861s Reading state information... 2864s Starting pkgProblemResolver with broken count: 0 2864s Starting 2 pkgProblemResolver with broken count: 0 2864s Done 2867s Solving dependencies... 2868s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 2870s autopkgtest [00:42:34]: rebooting testbed after setup commands that affected boot 2880s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 2913s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 2946s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 2979s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 3020s autopkgtest [00:45:04]: testbed running kernel: Linux 6.11.0-8-generic #8.1-Ubuntu SMP PREEMPT_DYNAMIC Tue Oct 1 11:40:56 UTC 2024 3037s autopkgtest [00:45:21]: @@@@@@@@@@@@@@@@@@@@ apt-source bluez 3105s Get:1 http://ftpmaster.internal/ubuntu plucky/main bluez 5.79-1 (dsc) [2868 B] 3105s Get:2 http://ftpmaster.internal/ubuntu plucky/main bluez 5.79-1 (tar) [3661 kB] 3105s Get:3 http://ftpmaster.internal/ubuntu plucky/main bluez 5.79-1 (diff) [44.7 kB] 3109s gpgv: Signature made Sat Nov 16 23:57:47 2024 UTC 3109s gpgv: using RSA key 5E629EE5232197357B84CF4332247FBB40AD1FA6 3109s gpgv: Can't check signature: No public key 3109s dpkg-source: warning: cannot verify inline signature for ./bluez_5.79-1.dsc: no acceptable signature found 3117s autopkgtest [00:46:41]: testing package bluez version 5.79-1 3119s autopkgtest [00:46:43]: build not needed 3124s autopkgtest [00:46:48]: test bluez-response: preparing testbed 3129s Reading package lists... 3131s Building dependency tree... 3131s Reading state information... 3132s Starting pkgProblemResolver with broken count: 0 3133s Starting 2 pkgProblemResolver with broken count: 0 3133s Done 3135s The following NEW packages will be installed: 3135s bluez bluez-test-scripts libasound2-data libasound2t64 3136s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 3136s Need to get 2013 kB of archives. 3136s After this operation, 6174 kB of additional disk space will be used. 3136s Get:1 http://ftpmaster.internal/ubuntu plucky/main riscv64 libasound2-data all 1.2.13-1build1 [21.1 kB] 3136s Get:2 http://ftpmaster.internal/ubuntu plucky/main riscv64 libasound2t64 riscv64 1.2.13-1build1 [419 kB] 3136s Get:3 http://ftpmaster.internal/ubuntu plucky/main riscv64 bluez riscv64 5.79-1 [1530 kB] 3136s Get:4 http://ftpmaster.internal/ubuntu plucky/universe riscv64 bluez-test-scripts all 5.79-1 [42.9 kB] 3143s Fetched 2013 kB in 1s (1790 kB/s) 3144s Selecting previously unselected package libasound2-data. 3145s (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 ... 75702 files and directories currently installed.) 3145s Preparing to unpack .../libasound2-data_1.2.13-1build1_all.deb ... 3145s Unpacking libasound2-data (1.2.13-1build1) ... 3145s Selecting previously unselected package libasound2t64:riscv64. 3146s Preparing to unpack .../libasound2t64_1.2.13-1build1_riscv64.deb ... 3146s Unpacking libasound2t64:riscv64 (1.2.13-1build1) ... 3146s Selecting previously unselected package bluez. 3146s Preparing to unpack .../bluez_5.79-1_riscv64.deb ... 3149s Unpacking bluez (5.79-1) ... 3150s Selecting previously unselected package bluez-test-scripts. 3150s Preparing to unpack .../bluez-test-scripts_5.79-1_all.deb ... 3150s Unpacking bluez-test-scripts (5.79-1) ... 3151s Setting up libasound2-data (1.2.13-1build1) ... 3151s Setting up libasound2t64:riscv64 (1.2.13-1build1) ... 3151s Setting up bluez-test-scripts (5.79-1) ... 3151s Setting up bluez (5.79-1) ... 3160s Created symlink '/etc/systemd/user/default.target.wants/mpris-proxy.service' → '/usr/lib/systemd/user/mpris-proxy.service'. 3172s Created symlink '/etc/systemd/system/dbus-org.bluez.service' → '/usr/lib/systemd/system/bluetooth.service'. 3172s Created symlink '/etc/systemd/system/bluetooth.target.wants/bluetooth.service' → '/usr/lib/systemd/system/bluetooth.service'. 3178s Processing triggers for libc-bin (2.41-1ubuntu1) ... 3179s Processing triggers for man-db (2.13.0-1) ... 3185s Processing triggers for dbus (1.16.2-1ubuntu1) ... 3199s autopkgtest [00:48:03]: test bluez-response: [----------------------- 3212s testAdapter (__main__.TestBluezResponse.testAdapter) ... skipped 'No bluetooth devices available for testing' 3222s testDevice (__main__.TestBluezResponse.testDevice) ... skipped 'No bluetooth devices available for testing' 3222s 3222s ---------------------------------------------------------------------- 3222s Ran 2 tests in 19.333s 3222s 3222s OK (skipped=2) 3224s autopkgtest [00:48:28]: test bluez-response: -----------------------] 3227s bluez-response PASS (superficial) 3227s autopkgtest [00:48:31]: test bluez-response: - - - - - - - - - - results - - - - - - - - - - 3229s autopkgtest [00:48:33]: @@@@@@@@@@@@@@@@@@@@ summary 3229s bluez-response PASS (superficial) 3237s nova [W] Using flock in prodstack6-riscv64 3237s flock: timeout while waiting to get lock 3237s Creating nova instance adt-plucky-riscv64-bluez-20250311-235441-juju-7f2275-prod-proposed-migration-environment-2-30d0374b-79e6-4dc6-80fe-a4539b4787f4 from image adt/ubuntu-plucky-riscv64-server-20250311.img (UUID f4c26e12-fa0a-45b3-9c3a-c2be2050e961)... 3237s nova [W] nova quota exceeded (attempt #0) 3237s nova [W] nova quota exceeded (attempt #1) 3237s nova [W] nova quota exceeded (attempt #2) 3237s nova [W] nova quota exceeded (attempt #3) 3237s nova [W] Timed out waiting for fe5b0a73-973c-4394-95a2-70ab489daa1b to get deleted.