0s autopkgtest [07:32:43]: starting date and time: 2024-05-03 07:32:43+0000 0s autopkgtest [07:32:43]: git checkout: 699e7f9f ssh-setup/nova: explicitely set 'fqdn' in cloud-init 0s autopkgtest [07:32:43]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.j7pil25a/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:faketime --apt-upgrade ngircd --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=faketime/0.9.10-2.1ubuntu1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-3@lcy02-84.secgroup --name adt-oracular-amd64-ngircd-20240503-073242-juju-7f2275-prod-proposed-migration-environment-3-d12ce762-3e47-44e6-94e3-8792681ca43c --image adt/ubuntu-oracular-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-3 --net-id=net_prod-proposed-migration -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/ 1213s autopkgtest [07:52:56]: testbed dpkg architecture: amd64 1213s autopkgtest [07:52:56]: testbed apt version: 2.7.14build2 1213s autopkgtest [07:52:56]: @@@@@@@@@@@@@@@@@@@@ test bed setup 1213s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [64.7 kB] 1213s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [145 kB] 1213s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [2800 B] 1213s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [1993 kB] 1213s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [34.6 kB] 1213s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main i386 Packages [164 kB] 1213s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 Packages [205 kB] 1213s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted i386 Packages [1260 B] 1213s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted amd64 Packages [8572 B] 1213s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe amd64 Packages [1285 kB] 1213s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe i386 Packages [721 kB] 1213s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse i386 Packages [20.6 kB] 1213s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse amd64 Packages [58.1 kB] 1214s Fetched 4704 kB in 1s (7524 kB/s) 1214s Reading package lists... 1215s Reading package lists... 1216s Building dependency tree... 1216s Reading state information... 1216s Calculating upgrade... 1216s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1216s Reading package lists... 1216s Building dependency tree... 1216s Reading state information... 1217s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1217s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 1217s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 1217s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 1217s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 1218s Reading package lists... 1218s Reading package lists... 1219s Building dependency tree... 1219s Reading state information... 1219s Calculating upgrade... 1219s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1219s Reading package lists... 1220s Building dependency tree... 1220s Reading state information... 1220s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 1221s autopkgtest [07:53:04]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP PREEMPT_DYNAMIC Sat Apr 20 00:40:06 UTC 2024 1221s autopkgtest [07:53:04]: @@@@@@@@@@@@@@@@@@@@ apt-source ngircd 1222s Get:1 http://ftpmaster.internal/ubuntu oracular/universe ngircd 26.1-2build1 (dsc) [2304 B] 1222s Get:2 http://ftpmaster.internal/ubuntu oracular/universe ngircd 26.1-2build1 (tar) [376 kB] 1222s Get:3 http://ftpmaster.internal/ubuntu oracular/universe ngircd 26.1-2build1 (asc) [488 B] 1222s Get:4 http://ftpmaster.internal/ubuntu oracular/universe ngircd 26.1-2build1 (diff) [25.9 kB] 1222s gpgv: Signature made Mon Apr 1 07:02:31 2024 UTC 1222s gpgv: using RSA key A089FB36AAFBDAD5ACC1325069F790171A210984 1222s gpgv: Can't check signature: No public key 1222s dpkg-source: warning: cannot verify inline signature for ./ngircd_26.1-2build1.dsc: no acceptable signature found 1222s autopkgtest [07:53:05]: testing package ngircd version 26.1-2build1 1223s autopkgtest [07:53:06]: build not needed 1223s autopkgtest [07:53:06]: test run-testsuite: preparing testbed 1223s Reading package lists... 1223s Building dependency tree... 1223s Reading state information... 1224s Starting pkgProblemResolver with broken count: 0 1224s Starting 2 pkgProblemResolver with broken count: 0 1224s Done 1224s The following additional packages will be installed: 1224s expect libident0 libtcl8.6 ngircd tcl-expect tcl8.6 1224s Suggested packages: 1224s tk8.6 tcl-tclreadline 1224s The following NEW packages will be installed: 1224s autopkgtest-satdep expect libident0 libtcl8.6 ngircd tcl-expect tcl8.6 1225s 0 upgraded, 7 newly installed, 0 to remove and 0 not upgraded. 1225s Need to get 1435 kB/1436 kB of archives. 1225s After this operation, 5250 kB of additional disk space will be used. 1225s Get:1 /tmp/autopkgtest.3aDCsX/1-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [720 B] 1225s Get:2 http://ftpmaster.internal/ubuntu oracular/universe amd64 libident0 amd64 0.32-3build1 [8798 B] 1225s Get:3 http://ftpmaster.internal/ubuntu oracular/universe amd64 ngircd amd64 26.1-2build1 [177 kB] 1225s Get:4 http://ftpmaster.internal/ubuntu oracular/main amd64 libtcl8.6 amd64 8.6.14+dfsg-1build1 [988 kB] 1225s Get:5 http://ftpmaster.internal/ubuntu oracular/main amd64 tcl8.6 amd64 8.6.14+dfsg-1build1 [14.7 kB] 1225s Get:6 http://ftpmaster.internal/ubuntu oracular/universe amd64 tcl-expect amd64 5.45.4-3 [110 kB] 1225s Get:7 http://ftpmaster.internal/ubuntu oracular/universe amd64 expect amd64 5.45.4-3 [137 kB] 1225s Fetched 1435 kB in 0s (18.9 MB/s) 1225s Selecting previously unselected package libident0:amd64. 1225s (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 ... 73888 files and directories currently installed.) 1225s Preparing to unpack .../0-libident0_0.32-3build1_amd64.deb ... 1225s Unpacking libident0:amd64 (0.32-3build1) ... 1225s Selecting previously unselected package ngircd. 1225s Preparing to unpack .../1-ngircd_26.1-2build1_amd64.deb ... 1225s Unpacking ngircd (26.1-2build1) ... 1225s Selecting previously unselected package libtcl8.6:amd64. 1225s Preparing to unpack .../2-libtcl8.6_8.6.14+dfsg-1build1_amd64.deb ... 1225s Unpacking libtcl8.6:amd64 (8.6.14+dfsg-1build1) ... 1225s Selecting previously unselected package tcl8.6. 1225s Preparing to unpack .../3-tcl8.6_8.6.14+dfsg-1build1_amd64.deb ... 1225s Unpacking tcl8.6 (8.6.14+dfsg-1build1) ... 1225s Selecting previously unselected package tcl-expect:amd64. 1225s Preparing to unpack .../4-tcl-expect_5.45.4-3_amd64.deb ... 1225s Unpacking tcl-expect:amd64 (5.45.4-3) ... 1225s Selecting previously unselected package expect. 1225s Preparing to unpack .../5-expect_5.45.4-3_amd64.deb ... 1225s Unpacking expect (5.45.4-3) ... 1225s Selecting previously unselected package autopkgtest-satdep. 1225s Preparing to unpack .../6-1-autopkgtest-satdep.deb ... 1225s Unpacking autopkgtest-satdep (0) ... 1225s Setting up libtcl8.6:amd64 (8.6.14+dfsg-1build1) ... 1225s Setting up libident0:amd64 (0.32-3build1) ... 1225s Setting up tcl8.6 (8.6.14+dfsg-1build1) ... 1225s Setting up tcl-expect:amd64 (5.45.4-3) ... 1225s Setting up ngircd (26.1-2build1) ... 1226s Created symlink /etc/systemd/system/multi-user.target.wants/ngircd.service → /usr/lib/systemd/system/ngircd.service. 1226s Setting up expect (5.45.4-3) ... 1226s Setting up autopkgtest-satdep (0) ... 1226s Processing triggers for man-db (2.12.0-4build2) ... 1227s Processing triggers for libc-bin (2.39-0ubuntu8) ... 1230s (Reading database ... 74257 files and directories currently installed.) 1230s Removing autopkgtest-satdep (0) ... 1230s autopkgtest [07:53:13]: test run-testsuite: [----------------------- 1230s + cd src/testsuite 1230s + cp /usr/sbin/ngircd T-ngircd1 1230s + cp /usr/sbin/ngircd T-ngircd2 1230s + cp /usr/sbin/ngircd T-ngircd3 1230s + ln -s tests.sh connect-test 1230s + ln -s tests.sh connect-ssl-cert1-test 1230s + ln -s tests.sh connect-ssl-cert2-test 1230s + ln -s tests.sh channel-test 1230s + ln -s tests.sh invite-test 1230s + ln -s tests.sh join-test 1230s + ln -s tests.sh kick-test 1230s + ln -s tests.sh message-test 1230s + ln -s tests.sh misc-test 1230s + ln -s tests.sh mode-test 1230s + ln -s tests.sh opless-channel-test 1230s + ln -s tests.sh server-link-test 1230s + ln -s tests.sh server-login-test 1230s + ln -s tests.sh who-test 1230s + ln -s tests.sh whois-test 1230s + ./start-server1 1231s starting server 1 ... ok. 1231s + ./connect-test 1232s running connect-test ... ok. 1232s + ./start-server2 1233s starting server 2 ... ok. 1233s + ./channel-test 1238s running channel-test ... ok. 1238s + ./invite-test 1256s running invite-test ... ok. 1256s + ./join-test 1267s running join-test ... ok. 1267s + ./kick-test 1282s running kick-test ... ok. 1282s + ./message-test 1298s running message-test ... ok. 1298s + ./misc-test 1320s running misc-test ... ok. 1320s + ./mode-test 1342s running mode-test ... ok. 1342s + ./opless-channel-test 1345s running opless-channel-test ... ok. 1345s + ./who-test 1380s running who-test ... ok. 1380s + ./whois-test 1396s running whois-test ... ok. 1396s + ./server-link-test 1402s running server-link-test ... ok. 1402s + ./server-login-test 1402s running server-login-test ... ok. 1402s + ./stop-server2 1403s stopping server 2 ... ok. 1403s + ./stress-server.sh 1403s stressing server with 5 clients (be patient!): 1413s checking stress script ... ok. 1413s started client 1/5. 1413s started client 2/5. 1413s started client 3/5. 1413s started client 4/5. 1413s started client 5/5. 1425s waiting for clients to complete: ... ok. 1425s + ./stop-server1 1426s stopping server 1 ... ok. 1426s + ./prep-server3 1426s + ./start-server3 1427s starting server 3 ... ok. 1427s + ./connect-ssl-cert1-test 1430s running connect-ssl-cert1-test ... ok. 1430s + ./switch-server3 1430s + ./reload-server3 1432s reloading server 3 ... ok. 1432s + ./connect-ssl-cert2-test 1436s running connect-ssl-cert2-test ... ok. 1436s + ./cleanup-server3 1436s + ./stop-server3 1437s stopping server 3 ... ok. 1437s autopkgtest [07:56:40]: test run-testsuite: -----------------------] 1437s run-testsuite PASS 1437s autopkgtest [07:56:40]: test run-testsuite: - - - - - - - - - - results - - - - - - - - - - 1438s autopkgtest [07:56:41]: @@@@@@@@@@@@@@@@@@@@ summary 1438s run-testsuite PASS 1448s Creating nova instance adt-oracular-amd64-ngircd-20240503-073242-juju-7f2275-prod-proposed-migration-environment-3-d12ce762-3e47-44e6-94e3-8792681ca43c from image adt/ubuntu-oracular-amd64-server-20240502.img (UUID 432a677a-ac12-431d-bd62-ba444f127b06)...