0s autopkgtest [01:02:26]: starting date and time: 2024-07-13 01:02:26+0000 0s autopkgtest [01:02:26]: git checkout: fd3bed09 nova: allow more retries for quota issues 0s autopkgtest [01:02:26]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.4h_bttsg/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade stenographer --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.39-3.1ubuntu3 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@bos03-ppc64el-15.secgroup --name adt-oracular-ppc64el-stenographer-20240713-010226-juju-7f2275-prod-proposed-migration-environment-3-7e8b4048-dfcd-4871-b027-55ce88940238 --image adt/ubuntu-oracular-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration-ppc64el -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'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 364s autopkgtest [01:08:30]: testbed dpkg architecture: ppc64el 364s autopkgtest [01:08:30]: testbed apt version: 2.9.6 364s autopkgtest [01:08:30]: @@@@@@@@@@@@@@@@@@@@ test bed setup 365s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 365s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [343 kB] 366s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 366s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [2576 B] 366s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [47.1 kB] 366s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el Packages [88.0 kB] 366s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el c-n-f Metadata [2116 B] 366s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el Packages [1368 B] 366s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted ppc64el c-n-f Metadata [120 B] 366s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el Packages [411 kB] 366s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe ppc64el c-n-f Metadata [8952 B] 366s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el Packages [1448 B] 366s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse ppc64el c-n-f Metadata [120 B] 368s Fetched 1040 kB in 1s (1407 kB/s) 368s Reading package lists... 370s Reading package lists... 370s Building dependency tree... 370s Reading state information... 370s Calculating upgrade... 370s The following packages will be upgraded: 370s inetutils-telnet libc-bin libc-dev-bin libc-devtools libc6 libc6-dev 370s libssl3t64 locales openssh-client openssh-server openssh-sftp-server openssl 370s telnet 371s 13 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 371s Need to get 15.5 MB of archives. 371s After this operation, 1588 kB disk space will be freed. 371s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libc-devtools ppc64el 2.39-3.1ubuntu3 [29.5 kB] 371s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libc6-dev ppc64el 2.39-3.1ubuntu3 [1982 kB] 371s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libc-dev-bin ppc64el 2.39-3.1ubuntu3 [21.0 kB] 371s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libc6 ppc64el 2.39-3.1ubuntu3 [3174 kB] 371s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el libc-bin ppc64el 2.39-3.1ubuntu3 [720 kB] 371s Get:6 http://ftpmaster.internal/ubuntu oracular/main ppc64el libssl3t64 ppc64el 3.2.2-1ubuntu1 [2345 kB] 371s Get:7 http://ftpmaster.internal/ubuntu oracular/main ppc64el openssh-sftp-server ppc64el 1:9.6p1-3ubuntu17 [43.6 kB] 371s Get:8 http://ftpmaster.internal/ubuntu oracular/main ppc64el openssh-server ppc64el 1:9.6p1-3ubuntu17 [624 kB] 371s Get:9 http://ftpmaster.internal/ubuntu oracular/main ppc64el openssh-client ppc64el 1:9.6p1-3ubuntu17 [1108 kB] 371s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/main ppc64el locales all 2.39-3.1ubuntu3 [4220 kB] 372s Get:11 http://ftpmaster.internal/ubuntu oracular/main ppc64el openssl ppc64el 3.2.2-1ubuntu1 [1147 kB] 372s Get:12 http://ftpmaster.internal/ubuntu oracular/main ppc64el inetutils-telnet ppc64el 2:2.5-5ubuntu1 [120 kB] 372s Get:13 http://ftpmaster.internal/ubuntu oracular/main ppc64el telnet all 0.17+2.5-5ubuntu1 [3688 B] 372s Preconfiguring packages ... 372s Fetched 15.5 MB in 1s (13.3 MB/s) 372s (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 ... 72765 files and directories currently installed.) 372s Preparing to unpack .../libc-devtools_2.39-3.1ubuntu3_ppc64el.deb ... 372s Unpacking libc-devtools (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 372s Preparing to unpack .../libc6-dev_2.39-3.1ubuntu3_ppc64el.deb ... 372s Unpacking libc6-dev:ppc64el (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 372s Preparing to unpack .../libc-dev-bin_2.39-3.1ubuntu3_ppc64el.deb ... 372s Unpacking libc-dev-bin (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 372s Preparing to unpack .../libc6_2.39-3.1ubuntu3_ppc64el.deb ... 372s Unpacking libc6:ppc64el (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 373s Setting up libc6:ppc64el (2.39-3.1ubuntu3) ... 373s Error: Could not restart systemd, systemd binary not working 373s (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 ... 72765 files and directories currently installed.) 373s Preparing to unpack .../libc-bin_2.39-3.1ubuntu3_ppc64el.deb ... 373s Unpacking libc-bin (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 373s Setting up libc-bin (2.39-3.1ubuntu3) ... 373s (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 ... 72765 files and directories currently installed.) 373s Preparing to unpack .../libssl3t64_3.2.2-1ubuntu1_ppc64el.deb ... 373s Unpacking libssl3t64:ppc64el (3.2.2-1ubuntu1) over (3.2.1-3ubuntu1) ... 373s Setting up libssl3t64:ppc64el (3.2.2-1ubuntu1) ... 373s (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 ... 72765 files and directories currently installed.) 373s Preparing to unpack .../0-openssh-sftp-server_1%3a9.6p1-3ubuntu17_ppc64el.deb ... 373s Unpacking openssh-sftp-server (1:9.6p1-3ubuntu17) over (1:9.6p1-3ubuntu15) ... 373s Preparing to unpack .../1-openssh-server_1%3a9.6p1-3ubuntu17_ppc64el.deb ... 373s Unpacking openssh-server (1:9.6p1-3ubuntu17) over (1:9.6p1-3ubuntu15) ... 373s Preparing to unpack .../2-openssh-client_1%3a9.6p1-3ubuntu17_ppc64el.deb ... 373s Unpacking openssh-client (1:9.6p1-3ubuntu17) over (1:9.6p1-3ubuntu15) ... 373s Preparing to unpack .../3-locales_2.39-3.1ubuntu3_all.deb ... 373s Unpacking locales (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 373s Preparing to unpack .../4-openssl_3.2.2-1ubuntu1_ppc64el.deb ... 373s Unpacking openssl (3.2.2-1ubuntu1) over (3.2.1-3ubuntu1) ... 374s Preparing to unpack .../5-inetutils-telnet_2%3a2.5-5ubuntu1_ppc64el.deb ... 374s Unpacking inetutils-telnet (2:2.5-5ubuntu1) over (2:2.5-3ubuntu4) ... 374s Preparing to unpack .../6-telnet_0.17+2.5-5ubuntu1_all.deb ... 374s Unpacking telnet (0.17+2.5-5ubuntu1) over (0.17+2.5-3ubuntu4) ... 374s Setting up openssh-client (1:9.6p1-3ubuntu17) ... 374s Setting up inetutils-telnet (2:2.5-5ubuntu1) ... 374s Setting up locales (2.39-3.1ubuntu3) ... 374s Generating locales (this might take a while)... 375s en_US.UTF-8... done 375s Generation complete. 375s Setting up libc-dev-bin (2.39-3.1ubuntu3) ... 375s Setting up openssl (3.2.2-1ubuntu1) ... 375s Installing new version of config file /etc/ssl/openssl.cnf ... 375s Setting up libc-devtools (2.39-3.1ubuntu3) ... 375s Setting up openssh-sftp-server (1:9.6p1-3ubuntu17) ... 375s Setting up telnet (0.17+2.5-5ubuntu1) ... 375s Setting up openssh-server (1:9.6p1-3ubuntu17) ... 375s Installing new version of config file /etc/pam.d/sshd ... 376s Setting up libc6-dev:ppc64el (2.39-3.1ubuntu3) ... 376s Processing triggers for ufw (0.36.2-6) ... 377s Processing triggers for systemd (256-1ubuntu1) ... 377s Processing triggers for man-db (2.12.1-2) ... 379s Processing triggers for libc-bin (2.39-3.1ubuntu3) ... 379s Reading package lists... 379s Building dependency tree... 379s Reading state information... 379s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 380s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 380s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 380s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 380s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 381s Reading package lists... 381s Reading package lists... 381s Building dependency tree... 381s Reading state information... 381s Calculating upgrade... 381s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 381s Reading package lists... 381s Building dependency tree... 381s Reading state information... 381s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 382s autopkgtest [01:08:48]: rebooting testbed after setup commands that affected boot 410s autopkgtest [01:09:16]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:05:55 UTC 2024 412s autopkgtest [01:09:18]: @@@@@@@@@@@@@@@@@@@@ apt-source stenographer 415s Get:1 http://ftpmaster.internal/ubuntu oracular/universe stenographer 1.0.1-4 (dsc) [2753 B] 415s Get:2 http://ftpmaster.internal/ubuntu oracular/universe stenographer 1.0.1-4 (tar) [149 kB] 415s Get:3 http://ftpmaster.internal/ubuntu oracular/universe stenographer 1.0.1-4 (diff) [14.1 kB] 416s gpgv: Signature made Sat May 4 00:20:45 2024 UTC 416s gpgv: using RSA key 5B34BA5AAB5507E903426E85E8D37AE2F09F4872 416s gpgv: Can't check signature: No public key 416s dpkg-source: warning: cannot verify inline signature for ./stenographer_1.0.1-4.dsc: no acceptable signature found 416s autopkgtest [01:09:22]: testing package stenographer version 1.0.1-4 416s autopkgtest [01:09:22]: build not needed 417s autopkgtest [01:09:23]: test run-example: preparing testbed 418s Reading package lists... 419s Building dependency tree... 419s Reading state information... 419s Starting pkgProblemResolver with broken count: 0 419s Starting 2 pkgProblemResolver with broken count: 0 419s Done 419s The following additional packages will be installed: 419s libleveldb1d libsnappy1v5 net-tools stenographer stenographer-client 419s stenographer-common 419s The following NEW packages will be installed: 419s autopkgtest-satdep libleveldb1d libsnappy1v5 net-tools stenographer 419s stenographer-client stenographer-common 419s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 419s Need to get 3806 kB/3806 kB of archives. 419s After this operation, 13.1 MB of additional disk space will be used. 419s Get:1 /tmp/autopkgtest.z0iuBA/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [748 B] 419s Get:2 http://ftpmaster.internal/ubuntu oracular/main ppc64el libsnappy1v5 ppc64el 1.2.1-1 [33.4 kB] 419s Get:3 http://ftpmaster.internal/ubuntu oracular/universe ppc64el libleveldb1d ppc64el 1.23-5build1 [150 kB] 420s Get:4 http://ftpmaster.internal/ubuntu oracular/main ppc64el net-tools ppc64el 2.10-1.1ubuntu1 [225 kB] 420s Get:5 http://ftpmaster.internal/ubuntu oracular/universe ppc64el stenographer-common all 1.0.1-4 [20.5 kB] 420s Get:6 http://ftpmaster.internal/ubuntu oracular/universe ppc64el stenographer ppc64el 1.0.1-4 [3371 kB] 420s Get:7 http://ftpmaster.internal/ubuntu oracular/universe ppc64el stenographer-client all 1.0.1-4 [5290 B] 420s Fetched 3806 kB in 1s (5421 kB/s) 420s Selecting previously unselected package libsnappy1v5:ppc64el. 421s (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 ... 72764 files and directories currently installed.) 421s Preparing to unpack .../0-libsnappy1v5_1.2.1-1_ppc64el.deb ... 421s Unpacking libsnappy1v5:ppc64el (1.2.1-1) ... 421s Selecting previously unselected package libleveldb1d:ppc64el. 421s Preparing to unpack .../1-libleveldb1d_1.23-5build1_ppc64el.deb ... 421s Unpacking libleveldb1d:ppc64el (1.23-5build1) ... 421s Selecting previously unselected package net-tools. 421s Preparing to unpack .../2-net-tools_2.10-1.1ubuntu1_ppc64el.deb ... 421s Unpacking net-tools (2.10-1.1ubuntu1) ... 421s Selecting previously unselected package stenographer-common. 421s Preparing to unpack .../3-stenographer-common_1.0.1-4_all.deb ... 421s Unpacking stenographer-common (1.0.1-4) ... 421s Selecting previously unselected package stenographer. 421s Preparing to unpack .../4-stenographer_1.0.1-4_ppc64el.deb ... 421s Unpacking stenographer (1.0.1-4) ... 421s Selecting previously unselected package stenographer-client. 421s Preparing to unpack .../5-stenographer-client_1.0.1-4_all.deb ... 421s Unpacking stenographer-client (1.0.1-4) ... 421s Selecting previously unselected package autopkgtest-satdep. 421s Preparing to unpack .../6-1-autopkgtest-satdep.deb ... 421s Unpacking autopkgtest-satdep (0) ... 421s Setting up net-tools (2.10-1.1ubuntu1) ... 421s Setting up stenographer-common (1.0.1-4) ... 421s info: Selecting UID from range 100 to 999 ... 421s 421s info: Selecting GID from range 100 to 999 ... 421s info: Adding system user `stenographer' (UID 107) ... 421s info: Adding new group `stenographer' (GID 108) ... 421s info: Adding new user `stenographer' (UID 107) with group `stenographer' ... 421s info: Not creating home directory `/var/lib/stenographer'. 421s Generating CA state 422s Generating key/cert for 'client' 423s Generating key/cert for 'server' 423s Setting up stenographer-client (1.0.1-4) ... 423s Setting up libsnappy1v5:ppc64el (1.2.1-1) ... 423s Setting up libleveldb1d:ppc64el (1.23-5build1) ... 423s Setting up stenographer (1.0.1-4) ... 423s setcap on /usr/sbin/stenotype worked. 423s Setting up autopkgtest-satdep (0) ... 423s Processing triggers for man-db (2.12.1-2) ... 425s Processing triggers for libc-bin (2.39-3.1ubuntu3) ... 427s (Reading database ... 72848 files and directories currently installed.) 427s Removing autopkgtest-satdep (0) ... 428s autopkgtest [01:09:34]: test run-example: [----------------------- 428s Filesystem info: 428s Filesystem Type Size Used Avail Use% Mounted on 428s /dev/vda1 ext4 20G 2.3G 18G 12% / 428s Kernel IP routing table 428s Destination Gateway Genmask Flags Metric Ref Use Iface 428s 0.0.0.0 10.145.227.1 0.0.0.0 UG 100 0 0 enp0s1 428s 10.145.227.0 0.0.0.0 255.255.255.0 U 100 0 0 enp0s1 428s 10.145.227.1 0.0.0.0 255.255.255.255 UH 100 0 0 enp0s1 428s 10.145.227.2 0.0.0.0 255.255.255.255 UH 100 0 0 enp0s1 428s 91.189.91.131 10.145.227.1 255.255.255.255 UGH 100 0 0 enp0s1 428s 91.189.91.132 10.145.227.1 255.255.255.255 UGH 100 0 0 enp0s1 428s 169.254.169.254 10.145.227.2 255.255.255.255 UGH 100 0 0 enp0s1 428s Using interface enp0s1 448s ● stenographer.service - packet capture to disk 448s Loaded: loaded (/usr/lib/systemd/system/stenographer.service; disabled; preset: enabled) 448s Active: active (running) since Sat 2024-07-13 01:09:34 UTC; 20s ago 448s Invocation: d80b9ecbbd2d43c1ae44b5a3521290f4 448s Docs: https://github.com/google/stenographer 448s https://github.com/google/stenographer/blob/master/DESIGN.md 448s https://github.com/google/stenographer/blob/master/INSTALL.md 448s Main PID: 1753 (stenographer) 448s Tasks: 12 (limit: 4591) 448s Memory: 15.4M (peak: 23.3M) 448s CPU: 31ms 448s CGroup: /system.slice/stenographer.service 448s ├─1753 /usr/sbin/stenographer 448s └─1759 /usr/sbin/stenotype --blocks=256 --seccomp=none --threads=1 --dir=/tmp/stenographer243212202 --iface=enp0s1 448s 448s Jul 13 01:09:34 autopkgtest systemd[1]: Started stenographer.service - packet capture to disk. 448s active 448s % Total % Received % Xferd Average Speed Time Time Time Current 448s Dload Upload Total Spent Left Speed 448s 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0 100 271 100 271 0 0 888 0 --:--:-- --:--:-- --:--:-- 891 628s ● stenographer.service - packet capture to disk 628s Loaded: loaded (/usr/lib/systemd/system/stenographer.service; disabled; preset: enabled) 628s Active: active (running) since Sat 2024-07-13 01:09:34 UTC; 3min 20s ago 628s Invocation: d80b9ecbbd2d43c1ae44b5a3521290f4 628s Docs: https://github.com/google/stenographer 628s https://github.com/google/stenographer/blob/master/DESIGN.md 628s https://github.com/google/stenographer/blob/master/INSTALL.md 628s Main PID: 1753 (stenographer) 628s Tasks: 12 (limit: 4591) 628s Memory: 19.3M (peak: 27.1M) 628s CPU: 53ms 628s CGroup: /system.slice/stenographer.service 628s ├─1753 /usr/sbin/stenographer 628s └─1759 /usr/sbin/stenotype --blocks=256 --seccomp=none --threads=1 --dir=/tmp/stenographer243212202 --iface=enp0s1 628s 628s Jul 13 01:09:34 autopkgtest systemd[1]: Started stenographer.service - packet capture to disk. 628s tcp 0 0 127.0.0.1:1234 0.0.0.0:* LISTEN 1753/stenographer 628s Running stenographer query 'after 5m ago', piping to 'tcpdump ' 628s reading from file /dev/stdin, link-type EN10MB (Ethernet), snapshot length 65536 629s 01:09:34.497884 IP 10.136.6.201.43790 > autopkgtest.ssh: Flags [.], ack 2822968862, win 24559, options [nop,nop,TS val 2181527356 ecr 2364409524], length 0 629s 01:09:37.571932 IP6 autopkgtest > ip6-allrouters: ICMP6, router solicitation, length 16 629s 01:09:41.701324 IP autopkgtest.59542 > csb-recursive-nameserver1.canonical.com.domain: 12709+ [1au] A? ntp.ubuntu.com. (43) 629s 01:09:41.701427 IP autopkgtest.57893 > csb-recursive-nameserver1.canonical.com.domain: 34151+ [1au] AAAA? ntp.ubuntu.com. (43) 629s 01:09:41.701773 IP csb-recursive-nameserver1.canonical.com.domain > autopkgtest.59542: 12709 4/0/1 A 185.125.190.57, A 185.125.190.56, A 91.189.91.157, A 185.125.190.58 (107) 629s 01:09:41.702145 IP csb-recursive-nameserver1.canonical.com.domain > autopkgtest.57893: 34151 3/0/1 AAAA 2620:2d:4000:1::41, AAAA 2620:2d:4000:1::40, AAAA 2620:2d:4000:1::3f (127) 629s 01:09:41.702507 IP autopkgtest.60402 > prod-ntp-4.ntp1.ps5.canonical.com.ntp: NTPv4, Client, length 48 629s 01:09:41.775161 IP prod-ntp-4.ntp1.ps5.canonical.com.ntp > autopkgtest.60402: NTPv4, Server, length 48 629s 01:09:48.151894 ARP, Request who-has 10.145.227.186 tell _gateway, length 28 629s 01:09:50.225366 ARP, Request who-has 10.145.227.68 tell _gateway, length 28 629s 01:09:54.421117 IP autopkgtest.ssh > 10.136.6.201.43790: Flags [P.], seq 1:941, ack 0, win 395, options [nop,nop,TS val 2364429556 ecr 2181527356], length 940 629s 01:09:54.424821 IP autopkgtest.ssh > 10.136.6.201.43790: Flags [P.], seq 941:985, ack 0, win 395, options [nop,nop,TS val 2364429560 ecr 2181527356], length 44 629s 01:09:54.468539 IP autopkgtest.ssh > 10.136.6.201.43790: Flags [P.], seq 985:1261, ack 0, win 395, options [nop,nop,TS val 2364429604 ecr 2181527356], length 276 629s 01:09:54.468556 IP autopkgtest.49056 > csb-recursive-nameserver1.canonical.com.domain: 30469+ [1au] A? squid.internal. (43) 629s 01:09:54.468633 IP autopkgtest.58125 > csb-recursive-nameserver1.canonical.com.domain: 34011+ [1au] AAAA? squid.internal. (43) 629s 01:09:54.470759 IP csb-recursive-nameserver1.canonical.com.domain > autopkgtest.58125: 34011 0/1/1 (116) 629s 01:09:54.470759 IP csb-recursive-nameserver1.canonical.com.domain > autopkgtest.49056: 30469 2/0/1 A 185.125.188.86, A 185.125.188.85 (75) 629s 01:09:54.471126 IP autopkgtest.57122 > internal-proxy-1.canonical.com.3128: Flags [S], seq 1967935626, win 32120, options [mss 1460,sackOK,TS val 1128069469 ecr 0,nop,wscale 7], length 0 629s 01:09:54.488286 IP 10.136.6.201.43790 > autopkgtest.ssh: Flags [.], ack 941, win 24557, options [nop,nop,TS val 2181547145 ecr 2364429556], length 0 629s 01:09:54.491317 IP 10.136.6.201.43790 > autopkgtest.ssh: Flags [.], ack 985, win 24559, options [nop,nop,TS val 2181547149 ecr 2364429560], length 0 629s 01:09:54.535680 IP 10.136.6.201.43790 > autopkgtest.ssh: Flags [.], ack 1261, win 24557, options [nop,nop,TS val 2181547192 ecr 2364429604], length 0 629s 01:09:54.541036 IP internal-proxy-1.canonical.com.3128 > autopkgtest.57122: Flags [S.], seq 2072309068, ack 1967935627, win 62230, options [mss 8902,sackOK,TS val 3631291647 ecr 1128069469,nop,wscale 7], length 0 629s 01:09:54.541077 IP autopkgtest.57122 > internal-proxy-1.canonical.com.3128: Flags [.], ack 1, win 251, options [nop,nop,TS val 1128069539 ecr 3631291647], length 0 629s 01:09:54.541193 IP autopkgtest.57122 > internal-proxy-1.canonical.com.3128: Flags [P.], seq 1:129, ack 1, win 251, options [nop,nop,TS val 1128069539 ecr 3631291647], length 128 629s 01:09:54.611404 IP internal-proxy-1.canonical.com.3128 > autopkgtest.57122: Flags [.], ack 129, win 486, options [nop,nop,TS val 3631291717 ecr 1128069539], length 0 629s 01:09:54.774622 IP internal-proxy-1.canonical.com.3128 > autopkgtest.57122: Flags [P.], seq 1:531, ack 129, win 486, options [nop,nop,TS val 3631291880 ecr 1128069539], length 530 629s 01:09:54.774622 IP internal-proxy-1.canonical.com.3128 > autopkgtest.57122: Flags [P.], seq 531:802, ack 129, win 486, options [nop,nop,TS val 3631291880 ecr 1128069539], length 271 629s 01:09:54.774671 IP autopkgtest.57122 > internal-proxy-1.canonical.com.3128: Flags [.], ack 531, win 247, options [nop,nop,TS val 1128069773 ecr 3631291880], length 0 629s 01:09:54.774691 IP autopkgtest.57122 > internal-proxy-1.canonical.com.3128: Flags [.], ack 802, win 245, options [nop,nop,TS val 1128069773 ecr 3631291880], length 0 629s 01:09:54.774993 IP autopkgtest.ssh > 10.136.6.201.43790: Flags [P.], seq 1261:1385, ack 0, win 395, options [nop,nop,TS val 2364429910 ecr 2181547192], length 124 629s 01:09:54.775005 IP autopkgtest.57122 > internal-proxy-1.canonical.com.3128: Flags [F.], seq 129, ack 802, win 245, options [nop,nop,TS val 1128069773 ecr 3631291880], length 0 629s 01:09:54.841734 IP internal-proxy-1.canonical.com.3128 > autopkgtest.57122: Flags [F.], seq 802, ack 130, win 486, options [nop,nop,TS val 3631291947 ecr 1128069773], length 0 629s 01:09:54.841763 IP autopkgtest.57122 > internal-proxy-1.canonical.com.3128: Flags [.], ack 803, win 245, options [nop,nop,TS val 1128069840 ecr 3631291947], length 0 629s 01:09:54.842885 IP 10.136.6.201.43790 > autopkgtest.ssh: Flags [.], ack 1385, win 24557, options [nop,nop,TS val 2181547497 ecr 2364429910], length 0 629s 01:10:01.535715 ARP, Request who-has 10.145.227.175 tell _gateway, length 28 629s 01:10:02.553774 ARP, Request who-has 10.145.227.175 tell _gateway, length 28 629s 01:10:04.572448 ARP, Request who-has 10.145.227.175 tell _gateway, length 28 629s 01:10:05.470063 IP6 autopkgtest > ip6-allrouters: ICMP6, router solicitation, length 16 629s 01:10:07.048672 ARP, Request who-has 10.145.227.106 tell _gateway, length 28 629s 01:10:07.048672 ARP, Request who-has 10.145.227.106 tell _gateway, length 28 629s 01:10:07.212773 ARP, Request who-has 10.145.227.106 tell _gateway, length 28 629s 01:10:07.521091 ARP, Request who-has 10.145.227.106 tell _gateway, length 28 629s 01:10:08.094430 ARP, Request who-has 10.145.227.106 tell _gateway, length 28 629s 01:10:08.736075 ARP, Request who-has 10.145.227.175 tell _gateway, length 28 629s 01:10:09.247398 ARP, Request who-has 10.145.227.106 tell _gateway, length 28 629s 01:10:11.562686 ARP, Request who-has 10.145.227.106 tell _gateway, length 28 629s 01:10:13.950944 IP autopkgtest.57175 > prod-ntp-4.ntp1.ps5.canonical.com.ntp: NTPv4, Client, length 48 629s 01:10:14.024417 IP prod-ntp-4.ntp1.ps5.canonical.com.ntp > autopkgtest.57175: NTPv4, Server, length 48 629s 01:10:15.884063 IP6 fe80::fc16:3eff:feda:5f99 > ip6-allrouters: ICMP6, router solicitation, length 16 629s 01:10:16.162877 ARP, Request who-has 10.145.227.106 tell _gateway, length 28 629s 01:10:16.441770 ARP, Request who-has 10.145.227.118 tell _gateway, length 28 629s 01:10:16.932798 ARP, Request who-has 10.145.227.175 tell _gateway, length 28 629s 01:10:17.458355 ARP, Request who-has 10.145.227.118 tell _gateway, length 28 629s 01:10:19.476832 ARP, Request who-has 10.145.227.118 tell _gateway, length 28 629s 01:10:23.637404 ARP, Request who-has 10.145.227.118 tell _gateway, length 28 629s 01:10:25.382866 ARP, Request who-has 10.145.227.106 tell _gateway, length 28 629s 01:10:27.726437 ARP, Request who-has 10.145.227.145 tell _gateway, length 28 629s 01:10:27.726437 ARP, Request who-has 10.145.227.145 tell _gateway, length 28 629s 01:10:27.884303 ARP, Request who-has 10.145.227.145 tell _gateway, length 28 629s 01:10:28.043743 ARP, Request who-has 10.145.227.124 tell _gateway, length 28 629s 01:10:28.043743 ARP, Request who-has 10.145.227.124 tell _gateway, length 28 629s 01:10:28.169877 ARP, Request who-has 10.145.227.145 tell _gateway, length 28 629s 01:10:28.193487 ARP, Request who-has 10.145.227.124 tell _gateway, length 28 629s 01:10:28.505885 ARP, Request who-has 10.145.227.124 tell _gateway, length 28 629s 01:10:28.743859 ARP, Request who-has 10.145.227.145 tell _gateway, length 28 629s 01:10:29.077173 ARP, Request who-has 10.145.227.124 tell _gateway, length 28 629s 01:10:29.863650 ARP, Request who-has 10.145.227.145 tell _gateway, length 28 629s 01:10:30.202025 ARP, Request who-has 10.145.227.124 tell _gateway, length 28 629s 01:10:31.836852 ARP, Request who-has 10.145.227.118 tell _gateway, length 28 629s 01:10:32.295175 ARP, Request who-has 10.145.227.145 tell _gateway, length 28 629s 01:10:32.598877 ARP, Request who-has 10.145.227.124 tell _gateway, length 28 629s 01:10:33.064345 ARP, Request who-has 10.145.227.175 tell _gateway, length 28 629s 01:10:36.904898 ARP, Request who-has 10.145.227.145 tell _gateway, length 28 629s 01:10:37.207912 ARP, Request who-has 10.145.227.124 tell _gateway, length 28 629s 01:10:43.562971 ARP, Request who-has 10.145.227.106 tell _gateway, length 28 629s 01:10:45.870130 ARP, Request who-has 10.145.227.145 tell _gateway, length 28 629s 01:10:46.168457 ARP, Request who-has 10.145.227.124 tell _gateway, length 28 629s 01:10:47.960730 ARP, Request who-has 10.145.227.118 tell _gateway, length 28 629s 01:10:56.858921 ARP, Request who-has 10.145.227.68 tell _gateway, length 28 629s 01:10:57.872242 ARP, Request who-has 10.145.227.68 tell _gateway, length 28 629s 01:10:59.884246 ARP, Request who-has 10.145.227.68 tell _gateway, length 28 629s 01:11:01.919363 IP6 autopkgtest > ip6-allrouters: ICMP6, router solicitation, length 16 629s 01:11:04.046059 ARP, Request who-has 10.145.227.145 tell _gateway, length 28 629s 01:11:04.046060 ARP, Request who-has 10.145.227.68 tell _gateway, length 28 629s 01:11:04.602814 ARP, Request who-has 10.145.227.124 tell _gateway, length 28 629s 01:11:06.098833 ARP, Request who-has 10.145.227.175 tell _gateway, length 28 629s 01:11:12.170221 ARP, Request who-has 10.145.227.121 tell _gateway, length 28 629s 01:11:12.170222 ARP, Request who-has 10.145.227.121 tell _gateway, length 28 629s 01:11:12.237316 ARP, Request who-has 10.145.227.68 tell _gateway, length 28 629s 01:11:12.336982 ARP, Request who-has 10.145.227.121 tell _gateway, length 28 629s 01:11:12.635527 ARP, Request who-has 10.145.227.121 tell _gateway, length 28 629s 01:11:13.216284 ARP, Request who-has 10.145.227.121 tell _gateway, length 28 629s 01:11:14.367359 ARP, Request who-has 10.145.227.121 tell _gateway, length 28 629s 01:11:16.640370 ARP, Request who-has 10.145.227.121 tell _gateway, length 28 629s 01:11:18.201386 IP autopkgtest.50182 > prod-ntp-4.ntp1.ps5.canonical.com.ntp: NTPv4, Client, length 48 629s 01:11:18.282791 IP prod-ntp-4.ntp1.ps5.canonical.com.ntp > autopkgtest.50182: NTPv4, Server, length 48 629s 01:11:20.990568 ARP, Request who-has 10.145.227.118 tell _gateway, length 28 629s 01:11:21.245558 ARP, Request who-has 10.145.227.121 tell _gateway, length 28 629s 01:11:23.231242 ARP, Request who-has _gateway tell autopkgtest, length 28 629s 01:11:23.231872 ARP, Reply _gateway is-at fa:16:3e:6b:37:a2 (oui Unknown), length 28 629s 01:11:28.367319 ARP, Request who-has 10.145.227.68 tell _gateway, length 28 629s 01:11:30.461773 ARP, Request who-has 10.145.227.121 tell _gateway, length 28 629s 01:11:49.655681 ARP, Request who-has 10.145.227.121 tell _gateway, length 28 629s 01:12:01.391957 ARP, Request who-has 10.145.227.68 tell _gateway, length 28 629s 01:12:12.643664 ARP, Request who-has 10.145.227.175 tell _gateway, length 28 629s 01:12:13.640333 ARP, Request who-has 10.145.227.175 tell _gateway, length 28 629s 01:12:15.662311 ARP, Request who-has 10.145.227.175 tell _gateway, length 28 629s 01:12:19.817057 ARP, Request who-has 10.145.227.175 tell _gateway, length 28 629s 01:12:20.951069 ARP, Request who-has 10.145.227.78 tell _gateway, length 28 629s 01:12:20.951069 ARP, Request who-has 10.145.227.78 tell _gateway, length 28 629s 01:12:21.113982 ARP, Request who-has 10.145.227.78 tell _gateway, length 28 629s 01:12:21.416903 ARP, Request who-has 10.145.227.78 tell _gateway, length 28 629s 01:12:21.992749 ARP, Request who-has 10.145.227.78 tell _gateway, length 28 629s 01:12:23.113030 ARP, Request who-has 10.145.227.78 tell _gateway, length 28 629s 01:12:24.911771 IP6 fe80::fc16:3eff:feda:5f99 > ip6-allrouters: ICMP6, router solicitation, length 16 629s 01:12:25.459025 ARP, Request who-has 10.145.227.78 tell _gateway, length 28 629s 01:12:27.524884 ARP, Request who-has 10.145.227.118 tell _gateway, length 28 629s 01:12:28.007825 ARP, Request who-has 10.145.227.175 tell _gateway, length 28 630s autopkgtest [01:12:56]: test run-example: -----------------------] 630s run-example PASS 630s autopkgtest [01:12:56]: test run-example: - - - - - - - - - - results - - - - - - - - - - 631s autopkgtest [01:12:57]: @@@@@@@@@@@@@@@@@@@@ summary 631s run-example PASS 635s nova [W] Using flock in prodstack6-ppc64el 635s Creating nova instance adt-oracular-ppc64el-stenographer-20240713-010226-juju-7f2275-prod-proposed-migration-environment-3-7e8b4048-dfcd-4871-b027-55ce88940238 from image adt/ubuntu-oracular-ppc64el-server-20240712.img (UUID 5558a1c2-7b31-4e21-8e90-3a11f77b6744)... 635s nova [W] nova quota exceeded (attempt #0)