0s autopkgtest [06:27:33]: starting date and time: 2024-05-16 06:27:33+0000 0s autopkgtest [06:27:33]: git checkout: d60bd36 autopkgtest-build-lxd: Add missing remote prefix in get_boot_id(). 0s autopkgtest [06:27:33]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.k7m4rlmr/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:curl --apt-upgrade balboa --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=curl/8.5.0-2ubuntu10.1 -- lxd -r lxd-armhf-10.145.243.116 lxd-armhf-10.145.243.116:autopkgtest/ubuntu/oracular/armhf 29s autopkgtest [06:28:02]: testbed dpkg architecture: armhf 31s autopkgtest [06:28:04]: testbed apt version: 2.7.14build2 31s autopkgtest [06:28:04]: @@@@@@@@@@@@@@@@@@@@ test bed setup 39s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [73.9 kB] 39s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [1964 B] 39s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [9628 B] 39s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [138 kB] 40s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [1851 kB] 40s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf Packages [200 kB] 40s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/universe armhf Packages [1456 kB] 40s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse armhf Packages [6188 B] 40s Fetched 3737 kB in 2s (2156 kB/s) 41s Reading package lists... 57s tee: /proc/self/fd/2: Permission denied 78s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 78s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 78s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 78s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 79s Reading package lists... 79s Reading package lists... 80s Building dependency tree... 80s Reading state information... 80s Calculating upgrade... 81s The following packages will be upgraded: 81s libgnutls30t64 pastebinit 81s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 81s Need to get 973 kB of archives. 81s After this operation, 24.6 kB of additional disk space will be used. 81s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf libgnutls30t64 armhf 3.8.5-2ubuntu1 [958 kB] 82s Get:2 http://ftpmaster.internal/ubuntu oracular/main armhf pastebinit all 1.7.0-1 [14.9 kB] 82s Fetched 973 kB in 1s (1579 kB/s) 82s (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 ... 57881 files and directories currently installed.) 82s Preparing to unpack .../libgnutls30t64_3.8.5-2ubuntu1_armhf.deb ... 82s Unpacking libgnutls30t64:armhf (3.8.5-2ubuntu1) over (3.8.3-1.1ubuntu3.1) ... 83s Setting up libgnutls30t64:armhf (3.8.5-2ubuntu1) ... 83s (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 ... 57881 files and directories currently installed.) 83s Preparing to unpack .../pastebinit_1.7.0-1_all.deb ... 83s Unpacking pastebinit (1.7.0-1) over (1.6.2-1) ... 83s Setting up pastebinit (1.7.0-1) ... 83s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 83s Processing triggers for man-db (2.12.0-4build2) ... 84s Reading package lists... 84s Building dependency tree... 84s Reading state information... 85s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 87s autopkgtest [06:29:00]: rebooting testbed after setup commands that affected boot 125s autopkgtest [06:29:38]: testbed running kernel: Linux 5.15.0-106-generic #116-Ubuntu SMP Wed Apr 17 09:19:22 UTC 2024 150s autopkgtest [06:30:03]: @@@@@@@@@@@@@@@@@@@@ apt-source balboa 168s Get:1 http://ftpmaster.internal/ubuntu oracular/universe balboa 2.0.0+ds-6build1 (dsc) [2430 B] 168s Get:2 http://ftpmaster.internal/ubuntu oracular/universe balboa 2.0.0+ds-6build1 (tar) [110 kB] 168s Get:3 http://ftpmaster.internal/ubuntu oracular/universe balboa 2.0.0+ds-6build1 (diff) [171 kB] 168s gpgv: Signature made Fri Jan 19 11:32:33 2024 UTC 168s gpgv: using RSA key 2E26BA81BAC6B82B536E2A7DF9BE70D6D8319E21 168s gpgv: Can't check signature: No public key 168s dpkg-source: warning: cannot verify inline signature for ./balboa_2.0.0+ds-6build1.dsc: no acceptable signature found 168s autopkgtest [06:30:21]: testing package balboa version 2.0.0+ds-6build1 170s autopkgtest [06:30:23]: build not needed 172s autopkgtest [06:30:25]: test test-run: preparing testbed 181s Reading package lists... 181s Building dependency tree... 181s Reading state information... 182s Starting pkgProblemResolver with broken count: 0 182s Starting 2 pkgProblemResolver with broken count: 0 182s Done 182s The following additional packages will be installed: 182s balboa balboa-backend-common balboa-backend-rocksdb libgflags2.2 182s librocksdb8.9 libsnappy1v5 183s The following NEW packages will be installed: 183s autopkgtest-satdep balboa balboa-backend-common balboa-backend-rocksdb 183s libgflags2.2 librocksdb8.9 libsnappy1v5 183s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 183s Need to get 7918 kB/7919 kB of archives. 183s After this operation, 20.0 MB of additional disk space will be used. 183s Get:1 /tmp/autopkgtest.YZQHb5/1-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [732 B] 183s Get:2 http://ftpmaster.internal/ubuntu oracular/universe armhf balboa armhf 2.0.0+ds-6build1 [3391 kB] 183s Get:3 http://ftpmaster.internal/ubuntu oracular/universe armhf balboa-backend-common armhf 2.0.0+ds-6build1 [287 kB] 183s Get:4 http://ftpmaster.internal/ubuntu oracular/universe armhf libgflags2.2 armhf 2.2.2-2build1 [68.6 kB] 183s Get:5 http://ftpmaster.internal/ubuntu oracular/main armhf libsnappy1v5 armhf 1.2.0-2 [28.2 kB] 183s Get:6 http://ftpmaster.internal/ubuntu oracular/universe armhf librocksdb8.9 armhf 8.9.1-2 [4113 kB] 183s Get:7 http://ftpmaster.internal/ubuntu oracular/universe armhf balboa-backend-rocksdb armhf 2.0.0+ds-6build1 [30.1 kB] 184s Fetched 7918 kB in 1s (8993 kB/s) 184s Selecting previously unselected package balboa. 184s (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 ... 57883 files and directories currently installed.) 184s Preparing to unpack .../0-balboa_2.0.0+ds-6build1_armhf.deb ... 184s Unpacking balboa (2.0.0+ds-6build1) ... 184s Selecting previously unselected package balboa-backend-common. 184s Preparing to unpack .../1-balboa-backend-common_2.0.0+ds-6build1_armhf.deb ... 184s Unpacking balboa-backend-common (2.0.0+ds-6build1) ... 184s Selecting previously unselected package libgflags2.2. 184s Preparing to unpack .../2-libgflags2.2_2.2.2-2build1_armhf.deb ... 184s Unpacking libgflags2.2 (2.2.2-2build1) ... 184s Selecting previously unselected package libsnappy1v5:armhf. 184s Preparing to unpack .../3-libsnappy1v5_1.2.0-2_armhf.deb ... 184s Unpacking libsnappy1v5:armhf (1.2.0-2) ... 184s Selecting previously unselected package librocksdb8.9. 184s Preparing to unpack .../4-librocksdb8.9_8.9.1-2_armhf.deb ... 184s Unpacking librocksdb8.9 (8.9.1-2) ... 184s Selecting previously unselected package balboa-backend-rocksdb. 184s Preparing to unpack .../5-balboa-backend-rocksdb_2.0.0+ds-6build1_armhf.deb ... 184s Unpacking balboa-backend-rocksdb (2.0.0+ds-6build1) ... 184s Selecting previously unselected package autopkgtest-satdep. 184s Preparing to unpack .../6-1-autopkgtest-satdep.deb ... 184s Unpacking autopkgtest-satdep (0) ... 184s Setting up balboa-backend-common (2.0.0+ds-6build1) ... 184s Setting up balboa (2.0.0+ds-6build1) ... 184s info: Selecting UID from range 100 to 999 ... 184s 184s info: Selecting GID from range 100 to 999 ... 184s info: Adding system user `balboa' (UID 107) ... 184s info: Adding new group `balboa' (GID 108) ... 184s info: Adding new user `balboa' (UID 107) with group `balboa' ... 184s info: Creating home directory `/var/lib/balboa' ... 185s Created symlink /etc/systemd/system/multi-user.target.wants/balboa.service → /usr/lib/systemd/system/balboa.service. 185s Setting up libsnappy1v5:armhf (1.2.0-2) ... 185s Setting up libgflags2.2 (2.2.2-2build1) ... 185s Setting up librocksdb8.9 (8.9.1-2) ... 185s Setting up balboa-backend-rocksdb (2.0.0+ds-6build1) ... 185s Setting up autopkgtest-satdep (0) ... 185s Processing triggers for man-db (2.12.0-4build2) ... 185s Processing triggers for libc-bin (2.39-0ubuntu8.1) ... 200s (Reading database ... 57928 files and directories currently installed.) 200s Removing autopkgtest-satdep (0) ... 206s autopkgtest [06:30:59]: test test-run: [----------------------- 207s May 16 06:30:38 autopkgtest-lxd-isimlp systemd[1]: Started balboa-backend@rocksdb.service - Basic Little Book of Answers, 'rocksdb' Backend. 207s May 16 06:30:38 autopkgtest-lxd-isimlp (-rocksdb)[956]: balboa-backend@rocksdb.service: Referenced but unset environment variable evaluates to an empty string: BALBOA_BACKEND_ARGS 207s May 16 06:30:48 autopkgtest-lxd-isimlp balboa-rocksdb[956]: (blb_engine_stats_report) delta_t `10` q `0` i `0` e `0` s `0` r `0` c `1` 207s May 16 06:30:58 autopkgtest-lxd-isimlp balboa-rocksdb[956]: (blb_engine_stats_report) delta_t `10` q `0` i `0` e `0` s `0` r `0` c `0` 207s ● balboa-backend@rocksdb.service - Basic Little Book of Answers, 'rocksdb' Backend 207s Loaded: loaded (/usr/lib/systemd/system/balboa-backend@.service; enabled; preset: enabled) 207s Active: active (running) since Thu 2024-05-16 06:30:38 UTC; 22s ago 207s Docs: https://github.com/DCSO/balboa 207s Main PID: 956 (balboa-rocksdb) 207s Tasks: 15 (limit: 18986) 207s Memory: 1.5M () 207s CGroup: /system.slice/system-balboa\x2dbackend.slice/balboa-backend@rocksdb.service 207s └─956 /usr/bin/balboa-rocksdb 207s 207s May 16 06:30:38 autopkgtest-lxd-isimlp systemd[1]: Started balboa-backend@rocksdb.service - Basic Little Book of Answers, 'rocksdb' Backend. 207s May 16 06:30:38 autopkgtest-lxd-isimlp (-rocksdb)[956]: balboa-backend@rocksdb.service: Referenced but unset environment variable evaluates to an empty string: BALBOA_BACKEND_ARGS 207s May 16 06:30:48 autopkgtest-lxd-isimlp balboa-rocksdb[956]: (blb_engine_stats_report) delta_t `10` q `0` i `0` e `0` s `0` r `0` c `1` 207s May 16 06:30:58 autopkgtest-lxd-isimlp balboa-rocksdb[956]: (blb_engine_stats_report) delta_t `10` q `0` i `0` e `0` s `0` r `0` c `0` 207s May 16 06:30:38 autopkgtest-lxd-isimlp systemd[1]: Started balboa.service - Basic Little Book of Answers. 207s May 16 06:30:38 autopkgtest-lxd-isimlp balboa[904]: time="2024-05-16T06:30:38Z" level=info msg="switching to log file /var/log/balboa.log" 207s ● balboa.service - Basic Little Book of Answers 207s Loaded: loaded (/usr/lib/systemd/system/balboa.service; enabled; preset: enabled) 207s Active: active (running) since Thu 2024-05-16 06:30:38 UTC; 22s ago 207s Docs: https://github.com/DCSO/balboa 207s Main PID: 904 (balboa) 207s Tasks: 9 (limit: 18986) 207s Memory: 2.0M () 207s CGroup: /system.slice/balboa.service 207s └─904 /usr/bin/balboa serve -f /etc/balboa/feeders.yaml -l /var/log/balboa.log 207s 207s May 16 06:30:38 autopkgtest-lxd-isimlp systemd[1]: Started balboa.service - Basic Little Book of Answers. 207s May 16 06:30:38 autopkgtest-lxd-isimlp balboa[904]: time="2024-05-16T06:30:38Z" level=info msg="switching to log file /var/log/balboa.log" 207s {"level":"info","msg":"starting feeder HTTP Input","time":"2024-05-16T06:30:38Z"} 207s {"level":"info","msg":"accepting submissions on port 8081","time":"2024-05-16T06:30:38Z"} 207s {"level":"info","msg":"starting feeder Socket Input","time":"2024-05-16T06:30:38Z"} 207s {"level":"info","msg":"starting feeder Suricata Socket Input","time":"2024-05-16T06:30:38Z"} 207s {"level":"info","msg":"ConsumeFeed() starting","time":"2024-05-16T06:30:38Z"} 207s {"level":"warning","msg":"connecting to backend failed: dial tcp 127.0.0.1:4242: connect: connection refused","time":"2024-05-16T06:30:38Z"} 207s {"level":"info","msg":"ConsumeFeed() finished","time":"2024-05-16T06:30:38Z"} 207s {"level":"info","msg":"serving GraphQL on port 8080","time":"2024-05-16T06:30:38Z"} 207s {"level":"info","msg":"ConsumeFeed() starting","time":"2024-05-16T06:30:48Z"} 214s {"level":"info","msg":"starting feeder HTTP Input","time":"2024-05-16T06:30:38Z"} 214s {"level":"info","msg":"accepting submissions on port 8081","time":"2024-05-16T06:30:38Z"} 214s {"level":"info","msg":"starting feeder Socket Input","time":"2024-05-16T06:30:38Z"} 214s {"level":"info","msg":"starting feeder Suricata Socket Input","time":"2024-05-16T06:30:38Z"} 214s {"level":"info","msg":"ConsumeFeed() starting","time":"2024-05-16T06:30:38Z"} 214s {"level":"warning","msg":"connecting to backend failed: dial tcp 127.0.0.1:4242: connect: connection refused","time":"2024-05-16T06:30:38Z"} 214s {"level":"info","msg":"ConsumeFeed() finished","time":"2024-05-16T06:30:38Z"} 214s {"level":"info","msg":"serving GraphQL on port 8080","time":"2024-05-16T06:30:38Z"} 214s {"level":"info","msg":"ConsumeFeed() starting","time":"2024-05-16T06:30:48Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:00Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:00Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:00Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:01Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:02Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:02Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:02Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:02Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:03Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:03Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:03Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:03Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:04Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:04Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:04Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:04Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:05Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:05Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:05Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:05Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:06Z"} 214s {"level":"info","msg":"got 1562529 bytes via HTTP","time":"2024-05-16T06:31:06Z"} 214s {"level":"info","msg":"enqueued 11931 observations","time":"2024-05-16T06:31:06Z"} 214s % Total % Received % Xferd Average Speed Time Time Time Current 214s Dload Upload Total Spent Left Speed 214s 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 145 100 73 100 72 34548 34074 --:--:-- --:--:-- --:--:-- 72500 214s {"data":{"entries":[{"rdata":"39.49.168.110","rrtype":"NS","count":12}]}} 214s autopkgtest [06:31:07]: test test-run: -----------------------] 218s autopkgtest [06:31:11]: test test-run: - - - - - - - - - - results - - - - - - - - - - 218s test-run PASS 221s autopkgtest [06:31:14]: @@@@@@@@@@@@@@@@@@@@ summary 221s test-run PASS