0s autopkgtest [15:26:02]: starting date and time: 2024-07-08 15:26:02+0000 0s autopkgtest [15:26:02]: git checkout: fd3bed09 nova: allow more retries for quota issues 0s autopkgtest [15:26:02]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.z2kmcek9/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\n" >> /etc/environment' --apt-pocket=proposed=src:pycairo --apt-upgrade gpsd --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=pycairo/1.26.1-1 -- lxd -r lxd-armhf-10.145.243.177 lxd-armhf-10.145.243.177:autopkgtest/ubuntu/oracular/armhf 25s autopkgtest [15:26:27]: testbed dpkg architecture: armhf 27s autopkgtest [15:26:29]: testbed apt version: 2.9.6 27s autopkgtest [15:26:29]: @@@@@@@@@@@@@@@@@@@@ test bed setup 35s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 36s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [360 kB] 36s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 36s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [50.6 kB] 36s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [3336 B] 36s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf Packages [68.1 kB] 36s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf c-n-f Metadata [1860 B] 36s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted armhf Packages [1368 B] 36s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted armhf c-n-f Metadata [120 B] 36s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe armhf Packages [315 kB] 36s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe armhf c-n-f Metadata [7224 B] 36s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse armhf Packages [2040 B] 36s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse armhf c-n-f Metadata [120 B] 38s Fetched 945 kB in 1s (1291 kB/s) 38s Reading package lists... 55s tee: /proc/self/fd/2: Permission denied 76s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 77s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 77s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 77s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 78s Reading package lists... 78s Reading package lists... 78s Building dependency tree... 78s Reading state information... 79s Calculating upgrade... 79s The following packages will be upgraded: 79s libnetplan1 netplan-generator netplan.io python3-netplan 79s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 79s Need to get 264 kB of archives. 79s After this operation, 1024 B of additional disk space will be used. 79s Get:1 http://ftpmaster.internal/ubuntu oracular/main armhf netplan-generator armhf 1.0.1-1ubuntu2 [59.1 kB] 79s Get:2 http://ftpmaster.internal/ubuntu oracular/main armhf python3-netplan armhf 1.0.1-1ubuntu2 [22.6 kB] 79s Get:3 http://ftpmaster.internal/ubuntu oracular/main armhf netplan.io armhf 1.0.1-1ubuntu2 [65.1 kB] 79s Get:4 http://ftpmaster.internal/ubuntu oracular/main armhf libnetplan1 armhf 1.0.1-1ubuntu2 [117 kB] 80s Fetched 264 kB in 0s (551 kB/s) 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 ... 58416 files and directories currently installed.) 80s Preparing to unpack .../netplan-generator_1.0.1-1ubuntu2_armhf.deb ... 80s Adding 'diversion of /lib/systemd/system-generators/netplan to /lib/systemd/system-generators/netplan.usr-is-merged by netplan-generator' 80s Unpacking netplan-generator (1.0.1-1ubuntu2) over (1.0.1-1ubuntu1) ... 80s Preparing to unpack .../python3-netplan_1.0.1-1ubuntu2_armhf.deb ... 80s Unpacking python3-netplan (1.0.1-1ubuntu2) over (1.0.1-1ubuntu1) ... 80s Preparing to unpack .../netplan.io_1.0.1-1ubuntu2_armhf.deb ... 80s Unpacking netplan.io (1.0.1-1ubuntu2) over (1.0.1-1ubuntu1) ... 80s Preparing to unpack .../libnetplan1_1.0.1-1ubuntu2_armhf.deb ... 80s Unpacking libnetplan1:armhf (1.0.1-1ubuntu2) over (1.0.1-1ubuntu1) ... 80s Setting up libnetplan1:armhf (1.0.1-1ubuntu2) ... 80s Setting up python3-netplan (1.0.1-1ubuntu2) ... 80s Setting up netplan-generator (1.0.1-1ubuntu2) ... 80s Removing 'diversion of /lib/systemd/system-generators/netplan to /lib/systemd/system-generators/netplan.usr-is-merged by netplan-generator' 80s Setting up netplan.io (1.0.1-1ubuntu2) ... 80s Processing triggers for libc-bin (2.39-0ubuntu9) ... 80s Processing triggers for man-db (2.12.1-2) ... 81s Processing triggers for dbus (1.14.10-4ubuntu4) ... 81s Reading package lists... 81s Building dependency tree... 81s Reading state information... 82s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 84s autopkgtest [15:27:26]: rebooting testbed after setup commands that affected boot 128s autopkgtest [15:28:10]: testbed running kernel: Linux 6.5.0-41-generic #41~22.04.2-Ubuntu SMP PREEMPT_DYNAMIC Mon Jun 3 16:28:24 UTC 2 155s autopkgtest [15:28:37]: @@@@@@@@@@@@@@@@@@@@ apt-source gpsd 197s Get:1 http://ftpmaster.internal/ubuntu oracular/main gpsd 3.25-4 (dsc) [3081 B] 197s Get:2 http://ftpmaster.internal/ubuntu oracular/main gpsd 3.25-4 (tar) [5225 kB] 197s Get:3 http://ftpmaster.internal/ubuntu oracular/main gpsd 3.25-4 (asc) [833 B] 197s Get:4 http://ftpmaster.internal/ubuntu oracular/main gpsd 3.25-4 (diff) [48.0 kB] 197s gpgv: Signature made Thu May 23 20:45:56 2024 UTC 197s gpgv: using RSA key BA60BC20F37E59444D6D25001365720913D2F22D 197s gpgv: issuer "bbonev@ipacct.com" 197s gpgv: Can't check signature: No public key 197s dpkg-source: warning: cannot verify inline signature for ./gpsd_3.25-4.dsc: no acceptable signature found 198s autopkgtest [15:29:20]: testing package gpsd version 3.25-4 200s autopkgtest [15:29:22]: build not needed 203s autopkgtest [15:29:25]: test check-service: preparing testbed 213s Reading package lists... 213s Building dependency tree... 213s Reading state information... 214s Starting pkgProblemResolver with broken count: 0 214s Starting 2 pkgProblemResolver with broken count: 0 214s Done 214s The following additional packages will be installed: 214s gpsd libbluetooth3 libgps30t64 214s Suggested packages: 214s gpsd-clients 214s Recommended packages: 214s gpsd-tools 214s The following NEW packages will be installed: 214s autopkgtest-satdep gpsd libbluetooth3 libgps30t64 214s 0 upgraded, 4 newly installed, 0 to remove and 0 not upgraded. 214s Need to get 534 kB/535 kB of archives. 214s After this operation, 1521 kB of additional disk space will be used. 214s Get:1 /tmp/autopkgtest.1nGUqX/1-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [728 B] 215s Get:2 http://ftpmaster.internal/ubuntu oracular/main armhf libgps30t64 armhf 3.25-4 [63.9 kB] 215s Get:3 http://ftpmaster.internal/ubuntu oracular/main armhf libbluetooth3 armhf 5.76-0ubuntu2 [76.0 kB] 215s Get:4 http://ftpmaster.internal/ubuntu oracular/main armhf gpsd armhf 3.25-4 [394 kB] 215s Fetched 534 kB in 1s (937 kB/s) 215s Selecting previously unselected package libgps30t64:armhf. 215s (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 ... 58416 files and directories currently installed.) 215s Preparing to unpack .../libgps30t64_3.25-4_armhf.deb ... 215s Unpacking libgps30t64:armhf (3.25-4) ... 215s Selecting previously unselected package libbluetooth3:armhf. 215s Preparing to unpack .../libbluetooth3_5.76-0ubuntu2_armhf.deb ... 215s Unpacking libbluetooth3:armhf (5.76-0ubuntu2) ... 216s Selecting previously unselected package gpsd. 216s Preparing to unpack .../archives/gpsd_3.25-4_armhf.deb ... 216s Unpacking gpsd (3.25-4) ... 216s Selecting previously unselected package autopkgtest-satdep. 216s Preparing to unpack .../1-autopkgtest-satdep.deb ... 216s Unpacking autopkgtest-satdep (0) ... 216s Setting up libgps30t64:armhf (3.25-4) ... 216s Setting up libbluetooth3:armhf (5.76-0ubuntu2) ... 216s Setting up gpsd (3.25-4) ... 216s Creating/updating gpsd user account... 216s apparmor_parser: Unable to replace "/usr/sbin/gpsd". apparmor_parser: Access denied. You need policy admin privileges to manage profiles. 216s 216s Created symlink /etc/systemd/system/sockets.target.wants/gpsd.socket → /usr/lib/systemd/system/gpsd.socket. 218s gpsd.service is a disabled or a static unit, not starting it. 218s Setting up autopkgtest-satdep (0) ... 218s Processing triggers for man-db (2.12.1-2) ... 219s Processing triggers for libc-bin (2.39-0ubuntu9) ... 230s (Reading database ... 58457 files and directories currently installed.) 230s Removing autopkgtest-satdep (0) ... 236s autopkgtest [15:29:58]: test check-service: [----------------------- 239s Check socket being active 239s enabled 239s active 239s Check service being inactive 239s inactive 239s disabled 239s Trying 127.0.0.1... 239s Connected to localhost. 239s Escape character is '^]'. 239s {"class":"VERSION","release":"3.25","rev":"3.25","proto_major":3,"proto_minor":15} 249s {"class":"DEVICES","devices":[]} 249s {"class":"WATCH","enable":true,"json":true,"nmea":false,"raw":0,"scaled":false,"timing":false,"split24":false,"pps":false} 259s {"class":"DEVICES","devices":[]} 259s {"class":"WATCH","enable":false,"json":true,"nmea":false,"raw":0,"scaled":false,"timing":false,"split24":false,"pps":false} 269s simplify test debug - full log 269s Trying 127.0.0.1... 269s Connected to localhost. 269s Escape character is '^]'. 269s {"class":"VERSION","release":"3.25","rev":"3.25","proto_major":3,"proto_minor":15} 269s {"class":"DEVICES","devices":[]} 269s {"class":"WATCH","enable":true,"json":true,"nmea":false,"raw":0,"scaled":false,"timing":false,"split24":false,"pps":false} 269s {"class":"DEVICES","devices":[]} 269s {"class":"WATCH","enable":false,"json":true,"nmea":false,"raw":0,"scaled":false,"timing":false,"split24":false,"pps":false} 269s Check log for version 269s {"class":"VERSION","release":"3.25","rev":"3.25","proto_major":3,"proto_minor":15} 269s Check log for devices 269s {"class":"DEVICES","devices":[]} 269s {"class":"DEVICES","devices":[]} 269s Check log for enabled watch 269s {"class":"WATCH","enable":true,"json":true,"nmea":false,"raw":0,"scaled":false,"timing":false,"split24":false,"pps":false} 269s Check log for disabled watch 269s {"class":"WATCH","enable":false,"json":true,"nmea":false,"raw":0,"scaled":false,"timing":false,"split24":false,"pps":false} 269s Check service being active now 269s active 269s autopkgtest [15:30:31]: test check-service: -----------------------] 274s autopkgtest [15:30:36]: test check-service: - - - - - - - - - - results - - - - - - - - - - 274s check-service PASS 277s autopkgtest [15:30:39]: @@@@@@@@@@@@@@@@@@@@ summary 277s check-service PASS