0s autopkgtest [20:53:39]: starting date and time: 2025-03-11 20:53:39+0000 0s autopkgtest [20:53:39]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [20:53:39]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.dmarqz99/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_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\n" >> /etc/environment' --apt-pocket=proposed=src:systemd --apt-upgrade bluez --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=systemd/257.4-1ubuntu1 -- lxd -r lxd-armhf-10.145.243.76 lxd-armhf-10.145.243.76:autopkgtest/ubuntu/plucky/armhf 22s autopkgtest [20:54:01]: testbed dpkg architecture: armhf 25s autopkgtest [20:54:04]: testbed apt version: 2.9.31ubuntu3 29s autopkgtest [20:54:08]: @@@@@@@@@@@@@@@@@@@@ test bed setup 31s autopkgtest [20:54:10]: testbed release detected to be: None 40s autopkgtest [20:54:19]: updating testbed package index (apt update) 42s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [110 kB] 43s Get:2 http://ftpmaster.internal/ubuntu plucky InRelease [249 kB] 43s Get:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease [110 kB] 43s Get:4 http://ftpmaster.internal/ubuntu plucky-security InRelease [110 kB] 43s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [437 kB] 43s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [48.1 kB] 43s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [12.1 kB] 43s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [3400 B] 43s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf Packages [61.5 kB] 43s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/universe armhf Packages [291 kB] 43s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse armhf Packages [1712 B] 43s Get:12 http://ftpmaster.internal/ubuntu plucky/multiverse Sources [298 kB] 43s Get:13 http://ftpmaster.internal/ubuntu plucky/universe Sources [21.0 MB] 44s Get:14 http://ftpmaster.internal/ubuntu plucky/main Sources [1401 kB] 44s Get:15 http://ftpmaster.internal/ubuntu plucky/main armhf Packages [1378 kB] 44s Get:16 http://ftpmaster.internal/ubuntu plucky/universe armhf Packages [15.2 MB] 44s Get:17 http://ftpmaster.internal/ubuntu plucky/multiverse armhf Packages [172 kB] 48s Fetched 40.9 MB in 5s (7672 kB/s) 49s Reading package lists... 55s autopkgtest [20:54:34]: upgrading testbed (apt dist-upgrade and autopurge) 57s Reading package lists... 57s Building dependency tree... 57s Reading state information... 58s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 58s Starting 2 pkgProblemResolver with broken count: 0 58s Done 59s Entering ResolveByKeep 59s 59s Calculating upgrade... 60s The following packages will be upgraded: 60s libncurses6 libncursesw6 libnl-3-200 libnl-route-3-200 libnss-systemd 60s libpam-systemd libsystemd-shared libsystemd0 libtinfo6 libudev1 ncurses-base 60s ncurses-bin ncurses-term rsyslog systemd systemd-cryptsetup systemd-resolved 60s systemd-sysv systemd-timesyncd udev 60s 20 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 60s Need to get 9682 kB of archives. 60s After this operation, 73.7 kB of additional disk space will be used. 60s Get:1 http://ftpmaster.internal/ubuntu plucky/main armhf ncurses-bin armhf 6.5+20250216-2 [179 kB] 60s Get:2 http://ftpmaster.internal/ubuntu plucky/main armhf ncurses-base all 6.5+20250216-2 [25.9 kB] 60s Get:3 http://ftpmaster.internal/ubuntu plucky/main armhf ncurses-term all 6.5+20250216-2 [276 kB] 60s Get:4 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libsystemd0 armhf 257.4-1ubuntu1 [496 kB] 61s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf systemd-sysv armhf 257.4-1ubuntu1 [11.9 kB] 61s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf systemd-resolved armhf 257.4-1ubuntu1 [315 kB] 61s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libnss-systemd armhf 257.4-1ubuntu1 [164 kB] 61s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libpam-systemd armhf 257.4-1ubuntu1 [238 kB] 61s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libsystemd-shared armhf 257.4-1ubuntu1 [2204 kB] 61s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf systemd armhf 257.4-1ubuntu1 [3028 kB] 61s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf systemd-timesyncd armhf 257.4-1ubuntu1 [42.1 kB] 61s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf systemd-cryptsetup armhf 257.4-1ubuntu1 [126 kB] 61s Get:13 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf udev armhf 257.4-1ubuntu1 [1402 kB] 61s Get:14 http://ftpmaster.internal/ubuntu plucky-proposed/main armhf libudev1 armhf 257.4-1ubuntu1 [194 kB] 61s Get:15 http://ftpmaster.internal/ubuntu plucky/main armhf libncurses6 armhf 6.5+20250216-2 [88.8 kB] 61s Get:16 http://ftpmaster.internal/ubuntu plucky/main armhf libncursesw6 armhf 6.5+20250216-2 [118 kB] 61s Get:17 http://ftpmaster.internal/ubuntu plucky/main armhf libtinfo6 armhf 6.5+20250216-2 [92.0 kB] 61s Get:18 http://ftpmaster.internal/ubuntu plucky/main armhf rsyslog armhf 8.2412.0-2ubuntu2 [471 kB] 61s Get:19 http://ftpmaster.internal/ubuntu plucky/main armhf libnl-route-3-200 armhf 3.7.0-1 [161 kB] 61s Get:20 http://ftpmaster.internal/ubuntu plucky/main armhf libnl-3-200 armhf 3.7.0-1 [49.6 kB] 61s Fetched 9682 kB in 1s (10.4 MB/s) 62s (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 ... 64289 files and directories currently installed.) 62s Preparing to unpack .../ncurses-bin_6.5+20250216-2_armhf.deb ... 62s Unpacking ncurses-bin (6.5+20250216-2) over (6.5+20250216-1) ... 62s Setting up ncurses-bin (6.5+20250216-2) ... 62s (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 ... 64289 files and directories currently installed.) 62s Preparing to unpack .../ncurses-base_6.5+20250216-2_all.deb ... 62s Unpacking ncurses-base (6.5+20250216-2) over (6.5+20250216-1) ... 62s Setting up ncurses-base (6.5+20250216-2) ... 62s (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 ... 64289 files and directories currently installed.) 62s Preparing to unpack .../ncurses-term_6.5+20250216-2_all.deb ... 62s Unpacking ncurses-term (6.5+20250216-2) over (6.5+20250216-1) ... 62s Preparing to unpack .../libsystemd0_257.4-1ubuntu1_armhf.deb ... 62s Unpacking libsystemd0:armhf (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 62s Setting up libsystemd0:armhf (257.4-1ubuntu1) ... 63s (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 ... 64289 files and directories currently installed.) 63s Preparing to unpack .../systemd-sysv_257.4-1ubuntu1_armhf.deb ... 63s Unpacking systemd-sysv (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 63s Preparing to unpack .../systemd-resolved_257.4-1ubuntu1_armhf.deb ... 63s Unpacking systemd-resolved (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 63s Preparing to unpack .../libnss-systemd_257.4-1ubuntu1_armhf.deb ... 63s Unpacking libnss-systemd:armhf (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 63s Preparing to unpack .../libpam-systemd_257.4-1ubuntu1_armhf.deb ... 63s Unpacking libpam-systemd:armhf (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 63s Preparing to unpack .../libsystemd-shared_257.4-1ubuntu1_armhf.deb ... 63s Unpacking libsystemd-shared:armhf (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 63s Setting up libsystemd-shared:armhf (257.4-1ubuntu1) ... 63s (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 ... 64289 files and directories currently installed.) 63s Preparing to unpack .../systemd_257.4-1ubuntu1_armhf.deb ... 63s Unpacking systemd (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 63s Preparing to unpack .../systemd-timesyncd_257.4-1ubuntu1_armhf.deb ... 63s Unpacking systemd-timesyncd (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 63s Preparing to unpack .../systemd-cryptsetup_257.4-1ubuntu1_armhf.deb ... 63s Unpacking systemd-cryptsetup (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 63s Preparing to unpack .../udev_257.4-1ubuntu1_armhf.deb ... 63s Unpacking udev (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 63s Preparing to unpack .../libudev1_257.4-1ubuntu1_armhf.deb ... 63s Unpacking libudev1:armhf (257.4-1ubuntu1) over (257.3-1ubuntu3) ... 63s Setting up libudev1:armhf (257.4-1ubuntu1) ... 64s (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 ... 64289 files and directories currently installed.) 64s Preparing to unpack .../libncurses6_6.5+20250216-2_armhf.deb ... 64s Unpacking libncurses6:armhf (6.5+20250216-2) over (6.5+20250216-1) ... 64s Preparing to unpack .../libncursesw6_6.5+20250216-2_armhf.deb ... 64s Unpacking libncursesw6:armhf (6.5+20250216-2) over (6.5+20250216-1) ... 64s Preparing to unpack .../libtinfo6_6.5+20250216-2_armhf.deb ... 64s Unpacking libtinfo6:armhf (6.5+20250216-2) over (6.5+20250216-1) ... 64s Setting up libtinfo6:armhf (6.5+20250216-2) ... 64s (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 ... 64289 files and directories currently installed.) 64s Preparing to unpack .../rsyslog_8.2412.0-2ubuntu2_armhf.deb ... 64s Unpacking rsyslog (8.2412.0-2ubuntu2) over (8.2412.0-2ubuntu1) ... 64s Preparing to unpack .../libnl-route-3-200_3.7.0-1_armhf.deb ... 64s Unpacking libnl-route-3-200:armhf (3.7.0-1) over (3.7.0-0.3build2) ... 64s Preparing to unpack .../libnl-3-200_3.7.0-1_armhf.deb ... 64s Unpacking libnl-3-200:armhf (3.7.0-1) over (3.7.0-0.3build2) ... 64s Setting up rsyslog (8.2412.0-2ubuntu2) ... 64s info: The user `syslog' is already a member of `adm'. 64s apparmor_parser: Unable to replace "rsyslogd". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 64s 65s Setting up systemd (257.4-1ubuntu1) ... 65s /usr/lib/tmpfiles.d/legacy.conf:14: Duplicate line for path "/run/lock", ignoring. 65s Created symlink '/run/systemd/system/tmp.mount' → '/dev/null'. 66s /usr/lib/tmpfiles.d/legacy.conf:14: Duplicate line for path "/run/lock", ignoring. 66s Setting up systemd-cryptsetup (257.4-1ubuntu1) ... 66s Setting up libncurses6:armhf (6.5+20250216-2) ... 66s Setting up systemd-timesyncd (257.4-1ubuntu1) ... 67s systemd-time-wait-sync.service is a disabled or a static unit not running, not starting it. 67s Setting up udev (257.4-1ubuntu1) ... 68s Setting up libncursesw6:armhf (6.5+20250216-2) ... 68s Setting up libnl-3-200:armhf (3.7.0-1) ... 68s Setting up systemd-resolved (257.4-1ubuntu1) ... 68s Setting up ncurses-term (6.5+20250216-2) ... 68s Setting up systemd-sysv (257.4-1ubuntu1) ... 68s Setting up libnss-systemd:armhf (257.4-1ubuntu1) ... 68s Setting up libnl-route-3-200:armhf (3.7.0-1) ... 68s Setting up libpam-systemd:armhf (257.4-1ubuntu1) ... 68s Processing triggers for libc-bin (2.41-1ubuntu1) ... 69s Processing triggers for man-db (2.13.0-1) ... 70s Processing triggers for dbus (1.16.2-1ubuntu1) ... 70s Processing triggers for shared-mime-info (2.4-5) ... 70s Warning: program compiled against libxml 212 using older 209 71s Processing triggers for initramfs-tools (0.146ubuntu1) ... 73s Reading package lists... 73s Building dependency tree... 73s Reading state information... 73s Starting pkgProblemResolver with broken count: 0 73s Starting 2 pkgProblemResolver with broken count: 0 73s Done 74s Solving dependencies... 74s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 76s autopkgtest [20:54:55]: rebooting testbed after setup commands that affected boot 129s autopkgtest [20:55:48]: testbed running kernel: Linux 6.8.0-52-generic #53~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Wed Jan 15 18:10:51 UTC 2 159s autopkgtest [20:56:18]: @@@@@@@@@@@@@@@@@@@@ apt-source bluez 215s Get:1 http://ftpmaster.internal/ubuntu plucky/main bluez 5.79-1 (dsc) [2868 B] 215s Get:2 http://ftpmaster.internal/ubuntu plucky/main bluez 5.79-1 (tar) [3661 kB] 215s Get:3 http://ftpmaster.internal/ubuntu plucky/main bluez 5.79-1 (diff) [44.7 kB] 215s gpgv: Signature made Sat Nov 16 23:57:47 2024 UTC 215s gpgv: using RSA key 5E629EE5232197357B84CF4332247FBB40AD1FA6 215s gpgv: Can't check signature: No public key 215s dpkg-source: warning: cannot verify inline signature for ./bluez_5.79-1.dsc: no acceptable signature found 215s autopkgtest [20:57:14]: testing package bluez version 5.79-1 219s autopkgtest [20:57:18]: build not needed 222s autopkgtest [20:57:21]: test bluez-response: preparing testbed 224s Reading package lists... 225s Building dependency tree... 225s Reading state information... 225s Starting pkgProblemResolver with broken count: 0 225s Starting 2 pkgProblemResolver with broken count: 0 225s Done 226s The following NEW packages will be installed: 226s bluez bluez-test-scripts libasound2-data libasound2t64 libdw1t64 226s 0 upgraded, 5 newly installed, 0 to remove and 0 not upgraded. 226s Need to get 2000 kB of archives. 226s After this operation, 5111 kB of additional disk space will be used. 226s Get:1 http://ftpmaster.internal/ubuntu plucky/main armhf libasound2-data all 1.2.13-1build1 [21.1 kB] 226s Get:2 http://ftpmaster.internal/ubuntu plucky/main armhf libasound2t64 armhf 1.2.13-1build1 [347 kB] 227s Get:3 http://ftpmaster.internal/ubuntu plucky/main armhf libdw1t64 armhf 0.192-4 [243 kB] 227s Get:4 http://ftpmaster.internal/ubuntu plucky/main armhf bluez armhf 5.79-1 [1346 kB] 227s Get:5 http://ftpmaster.internal/ubuntu plucky/universe armhf bluez-test-scripts all 5.79-1 [42.9 kB] 227s Fetched 2000 kB in 1s (2949 kB/s) 227s Selecting previously unselected package libasound2-data. 227s (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 ... 64289 files and directories currently installed.) 227s Preparing to unpack .../libasound2-data_1.2.13-1build1_all.deb ... 227s Unpacking libasound2-data (1.2.13-1build1) ... 227s Selecting previously unselected package libasound2t64:armhf. 227s Preparing to unpack .../libasound2t64_1.2.13-1build1_armhf.deb ... 227s Unpacking libasound2t64:armhf (1.2.13-1build1) ... 227s Selecting previously unselected package libdw1t64:armhf. 227s Preparing to unpack .../libdw1t64_0.192-4_armhf.deb ... 227s Unpacking libdw1t64:armhf (0.192-4) ... 227s Selecting previously unselected package bluez. 227s Preparing to unpack .../bluez_5.79-1_armhf.deb ... 228s Unpacking bluez (5.79-1) ... 228s Selecting previously unselected package bluez-test-scripts. 228s Preparing to unpack .../bluez-test-scripts_5.79-1_all.deb ... 228s Unpacking bluez-test-scripts (5.79-1) ... 228s Setting up libdw1t64:armhf (0.192-4) ... 228s Setting up libasound2-data (1.2.13-1build1) ... 228s Setting up libasound2t64:armhf (1.2.13-1build1) ... 228s Setting up bluez-test-scripts (5.79-1) ... 228s Setting up bluez (5.79-1) ... 228s Created symlink '/etc/systemd/user/default.target.wants/mpris-proxy.service' → '/usr/lib/systemd/user/mpris-proxy.service'. 228s Failed to preset unit: Unit /etc/systemd/system/bluetooth.service is masked 228s /usr/bin/deb-systemd-helper: error: systemctl preset failed on bluetooth.service: No such file or directory 229s bluetooth.service is a disabled or a static unit, not starting it. 229s Processing triggers for libc-bin (2.41-1ubuntu1) ... 229s Processing triggers for man-db (2.13.0-1) ... 229s Processing triggers for dbus (1.16.2-1ubuntu1) ... 240s autopkgtest [20:57:39]: test bluez-response: [----------------------- 243s testAdapter (__main__.TestBluezResponse.testAdapter) ... Failed to start bluetooth.service: Unit bluetooth.service is masked. 243s Can't open HCI socket.: Address family not supported by protocol 243s skipped 'No bluetooth devices available for testing' 243s testDevice (__main__.TestBluezResponse.testDevice) ... Failed to start bluetooth.service: Unit bluetooth.service is masked. 243s Can't open HCI socket.: Address family not supported by protocol 243s skipped 'No bluetooth devices available for testing' 243s 243s ---------------------------------------------------------------------- 243s Ran 2 tests in 1.071s 243s 243s OK (skipped=2) 244s autopkgtest [20:57:43]: test bluez-response: -----------------------] 248s autopkgtest [20:57:47]: test bluez-response: - - - - - - - - - - results - - - - - - - - - - 248s bluez-response PASS (superficial) 252s autopkgtest [20:57:51]: @@@@@@@@@@@@@@@@@@@@ summary 252s bluez-response PASS (superficial)