0s autopkgtest [18:58:59]: starting date and time: 2024-12-17 18:58:59+0000 0s autopkgtest [18:58:59]: git checkout: c2a78735 Merge branch 'timeout-nova-boot' into 'ubuntu/production' 0s autopkgtest [18:58:59]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.2wzgt1w3/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:dpkg --apt-upgrade bind-dyndb-ldap --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=dpkg/1.22.6ubuntu6.2 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-s390x-22.secgroup --name adt-noble-s390x-bind-dyndb-ldap-20241217-185859-juju-7f2275-prod-proposed-migration-environment-15-4b74dbce-3a8e-4ba9-8fd6-8f6c2f763df2 --image adt/ubuntu-noble-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-s390x -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,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 99s autopkgtest [19:00:38]: testbed dpkg architecture: s390x 99s autopkgtest [19:00:38]: testbed apt version: 2.7.14build2 99s autopkgtest [19:00:38]: @@@@@@@@@@@@@@@@@@@@ test bed setup 100s autopkgtest [19:00:39]: testbed release detected to be: None 100s autopkgtest [19:00:39]: updating testbed package index (apt update) 101s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [265 kB] 101s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 101s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 101s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 101s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [4008 B] 101s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [75.5 kB] 101s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [75.0 kB] 101s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [98.0 kB] 102s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3744 B] 102s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1176 B] 102s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 102s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [306 kB] 102s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [5504 B] 102s Get:14 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 103s Fetched 834 kB in 2s (524 kB/s) 104s Reading package lists... 105s Reading package lists... 105s Building dependency tree... 105s Reading state information... 105s Calculating upgrade... 105s The following packages will be upgraded: 105s cloud-init fwupd libfwupd2 105s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 105s Need to get 5214 kB of archives. 105s After this operation, 129 kB of additional disk space will be used. 105s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libfwupd2 s390x 1.9.27-0ubuntu1~24.04.1 [134 kB] 105s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x fwupd s390x 1.9.27-0ubuntu1~24.04.1 [4479 kB] 110s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main s390x cloud-init all 24.4-0ubuntu1~24.04.2 [602 kB] 111s Preconfiguring packages ... 111s Fetched 5214 kB in 6s (890 kB/s) 111s (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 ... 78298 files and directories currently installed.) 111s Preparing to unpack .../libfwupd2_1.9.27-0ubuntu1~24.04.1_s390x.deb ... 111s Unpacking libfwupd2:s390x (1.9.27-0ubuntu1~24.04.1) over (1.9.24-1~24.04.1) ... 111s Preparing to unpack .../fwupd_1.9.27-0ubuntu1~24.04.1_s390x.deb ... 111s Unpacking fwupd (1.9.27-0ubuntu1~24.04.1) over (1.9.24-1~24.04.1) ... 111s Preparing to unpack .../cloud-init_24.4-0ubuntu1~24.04.2_all.deb ... 111s Unpacking cloud-init (24.4-0ubuntu1~24.04.2) over (24.3.1-0ubuntu0~24.04.2) ... 111s Setting up cloud-init (24.4-0ubuntu1~24.04.2) ... 112s Setting up libfwupd2:s390x (1.9.27-0ubuntu1~24.04.1) ... 112s Setting up fwupd (1.9.27-0ubuntu1~24.04.1) ... 113s fwupd-offline-update.service is a disabled or a static unit not running, not starting it. 113s fwupd-refresh.service is a disabled or a static unit not running, not starting it. 113s fwupd.service is a disabled or a static unit not running, not starting it. 113s Processing triggers for rsyslog (8.2312.0-3ubuntu9) ... 113s Processing triggers for man-db (2.12.0-4build2) ... 114s Processing triggers for dbus (1.14.10-4ubuntu4.1) ... 114s Processing triggers for libc-bin (2.39-0ubuntu8.3) ... 114s Reading package lists... 114s Building dependency tree... 114s Reading state information... 114s 0 upgraded, 0 newly installed, 0 to remove and 3 not upgraded. 114s autopkgtest [19:00:53]: upgrading testbed (apt dist-upgrade and autopurge) 114s Reading package lists... 114s Building dependency tree... 114s Reading state information... 114s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 114s Starting 2 pkgProblemResolver with broken count: 0 114s Done 115s Entering ResolveByKeep 115s 115s The following packages will be upgraded: 115s dpkg dpkg-dev libdpkg-perl 115s 3 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 115s Need to get 2619 kB of archives. 115s After this operation, 0 B of additional disk space will be used. 115s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main s390x dpkg s390x 1.22.6ubuntu6.2 [1276 kB] 117s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x dpkg-dev all 1.22.6ubuntu6.2 [1074 kB] 118s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libdpkg-perl all 1.22.6ubuntu6.2 [269 kB] 118s Fetched 2619 kB in 3s (828 kB/s) 118s (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 ... 78305 files and directories currently installed.) 118s Preparing to unpack .../dpkg_1.22.6ubuntu6.2_s390x.deb ... 118s Unpacking dpkg (1.22.6ubuntu6.2) over (1.22.6ubuntu6.1) ... 118s Setting up dpkg (1.22.6ubuntu6.2) ... 119s (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 ... 78305 files and directories currently installed.) 119s Preparing to unpack .../dpkg-dev_1.22.6ubuntu6.2_all.deb ... 119s Unpacking dpkg-dev (1.22.6ubuntu6.2) over (1.22.6ubuntu6.1) ... 119s Preparing to unpack .../libdpkg-perl_1.22.6ubuntu6.2_all.deb ... 119s Unpacking libdpkg-perl (1.22.6ubuntu6.2) over (1.22.6ubuntu6.1) ... 119s Setting up libdpkg-perl (1.22.6ubuntu6.2) ... 119s Setting up dpkg-dev (1.22.6ubuntu6.2) ... 119s Processing triggers for man-db (2.12.0-4build2) ... 119s Reading package lists... 119s Building dependency tree... 119s Reading state information... 119s Starting pkgProblemResolver with broken count: 0 119s Starting 2 pkgProblemResolver with broken count: 0 119s Done 120s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 120s autopkgtest [19:00:59]: rebooting testbed after setup commands that affected boot 124s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 145s autopkgtest [19:01:24]: testbed running kernel: Linux 6.8.0-51-generic #52-Ubuntu SMP Thu Dec 5 12:14:09 UTC 2024 148s autopkgtest [19:01:27]: @@@@@@@@@@@@@@@@@@@@ apt-source bind-dyndb-ldap 150s Get:1 http://ftpmaster.internal/ubuntu noble-updates/universe bind-dyndb-ldap 11.10-6ubuntu11 (dsc) [2271 B] 150s Get:2 http://ftpmaster.internal/ubuntu noble-updates/universe bind-dyndb-ldap 11.10-6ubuntu11 (tar) [355 kB] 150s Get:3 http://ftpmaster.internal/ubuntu noble-updates/universe bind-dyndb-ldap 11.10-6ubuntu11 (diff) [13.7 kB] 150s gpgv: Signature made Thu Jul 18 15:25:07 2024 UTC 150s gpgv: using RSA key 50C4A0DDCF31E452CEB19B516569D855A744BE93 150s gpgv: Can't check signature: No public key 150s dpkg-source: warning: cannot verify inline signature for ./bind-dyndb-ldap_11.10-6ubuntu11.dsc: no acceptable signature found 150s autopkgtest [19:01:29]: testing package bind-dyndb-ldap version 11.10-6ubuntu11 150s autopkgtest [19:01:29]: build not needed 154s autopkgtest [19:01:33]: test dyndb-ldap: preparing testbed 155s Reading package lists... 155s Building dependency tree... 155s Reading state information... 155s Starting pkgProblemResolver with broken count: 0 155s Starting 2 pkgProblemResolver with broken count: 0 155s Done 155s The following NEW packages will be installed: 155s bind9 bind9-dyndb-ldap bind9-utils dns-root-data ldap-utils libltdl7 155s libodbc2 slapd 155s 0 upgraded, 8 newly installed, 0 to remove and 0 not upgraded. 155s Need to get 2516 kB of archives. 155s After this operation, 8220 kB of additional disk space will be used. 155s Get:1 http://ftpmaster.internal/ubuntu noble-updates/main s390x bind9-utils s390x 1:9.18.28-0ubuntu0.24.04.1 [166 kB] 156s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x dns-root-data all 2023112702~willsync1 [4450 B] 156s Get:3 http://ftpmaster.internal/ubuntu noble-updates/main s390x bind9 s390x 1:9.18.28-0ubuntu0.24.04.1 [266 kB] 156s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x libltdl7 s390x 2.4.7-7build1 [41.8 kB] 156s Get:5 http://ftpmaster.internal/ubuntu noble-updates/main s390x libodbc2 s390x 2.3.12-1ubuntu0.24.04.1 [164 kB] 156s Get:6 http://ftpmaster.internal/ubuntu noble-updates/main s390x slapd s390x 2.6.7+dfsg-1~exp1ubuntu8.1 [1604 kB] 159s Get:7 http://ftpmaster.internal/ubuntu noble-updates/universe s390x bind9-dyndb-ldap s390x 11.10-6ubuntu11 [105 kB] 159s Get:8 http://ftpmaster.internal/ubuntu noble-updates/main s390x ldap-utils s390x 2.6.7+dfsg-1~exp1ubuntu8.1 [165 kB] 159s Preconfiguring packages ... 159s Fetched 2516 kB in 4s (645 kB/s) 159s Selecting previously unselected package bind9-utils. 159s (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 ... 78305 files and directories currently installed.) 159s Preparing to unpack .../0-bind9-utils_1%3a9.18.28-0ubuntu0.24.04.1_s390x.deb ... 159s Unpacking bind9-utils (1:9.18.28-0ubuntu0.24.04.1) ... 159s Selecting previously unselected package dns-root-data. 159s Preparing to unpack .../1-dns-root-data_2023112702~willsync1_all.deb ... 159s Unpacking dns-root-data (2023112702~willsync1) ... 159s Selecting previously unselected package bind9. 159s Preparing to unpack .../2-bind9_1%3a9.18.28-0ubuntu0.24.04.1_s390x.deb ... 159s Unpacking bind9 (1:9.18.28-0ubuntu0.24.04.1) ... 159s Selecting previously unselected package libltdl7:s390x. 159s Preparing to unpack .../3-libltdl7_2.4.7-7build1_s390x.deb ... 159s Unpacking libltdl7:s390x (2.4.7-7build1) ... 159s Selecting previously unselected package libodbc2:s390x. 159s Preparing to unpack .../4-libodbc2_2.3.12-1ubuntu0.24.04.1_s390x.deb ... 159s Unpacking libodbc2:s390x (2.3.12-1ubuntu0.24.04.1) ... 159s Selecting previously unselected package slapd. 159s Preparing to unpack .../5-slapd_2.6.7+dfsg-1~exp1ubuntu8.1_s390x.deb ... 160s Unpacking slapd (2.6.7+dfsg-1~exp1ubuntu8.1) ... 160s Selecting previously unselected package bind9-dyndb-ldap. 160s Preparing to unpack .../6-bind9-dyndb-ldap_11.10-6ubuntu11_s390x.deb ... 160s Unpacking bind9-dyndb-ldap (11.10-6ubuntu11) ... 160s Selecting previously unselected package ldap-utils. 160s Preparing to unpack .../7-ldap-utils_2.6.7+dfsg-1~exp1ubuntu8.1_s390x.deb ... 160s Unpacking ldap-utils (2.6.7+dfsg-1~exp1ubuntu8.1) ... 160s Setting up ldap-utils (2.6.7+dfsg-1~exp1ubuntu8.1) ... 160s Setting up dns-root-data (2023112702~willsync1) ... 160s Setting up bind9-utils (1:9.18.28-0ubuntu0.24.04.1) ... 160s Setting up libltdl7:s390x (2.4.7-7build1) ... 160s Setting up libodbc2:s390x (2.3.12-1ubuntu0.24.04.1) ... 160s Setting up bind9 (1:9.18.28-0ubuntu0.24.04.1) ... 160s info: Selecting GID from range 100 to 999 ... 160s info: Adding group `bind' (GID 110) ... 160s info: Selecting UID from range 100 to 999 ... 160s 160s info: Adding system user `bind' (UID 107) ... 160s info: Adding new user `bind' (UID 107) with group `bind' ... 160s info: Not creating home directory `/var/cache/bind'. 160s wrote key file "/etc/bind/rndc.key" 160s named-resolvconf.service is a disabled or a static unit, not starting it. 160s Created symlink /etc/systemd/system/bind9.service → /usr/lib/systemd/system/named.service. 160s Created symlink /etc/systemd/system/multi-user.target.wants/named.service → /usr/lib/systemd/system/named.service. 160s Setting up slapd (2.6.7+dfsg-1~exp1ubuntu8.1) ... 161s Creating new user openldap... done. 161s Creating initial configuration... done. 161s Creating LDAP directory... done. 161s Setting up bind9-dyndb-ldap (11.10-6ubuntu11) ... 161s Processing triggers for ufw (0.36.2-6) ... 161s Processing triggers for man-db (2.12.0-4build2) ... 161s Processing triggers for libc-bin (2.39-0ubuntu8.3) ... 162s autopkgtest [19:01:41]: test dyndb-ldap: [----------------------- 162s ## Adjust bind9's apparmor profile if needed 162s ## Setting up slapd 162s Backing up /etc/ldap/slapd.d in /var/backups/slapd-2.6.7+dfsg-1~exp1ubuntu8.1... done. 162s Moving old database directory to /var/backups: 162s - directory unknown... done. 162s Creating initial configuration... done. 162s Creating LDAP directory... done. 163s Waiting for slapd to be ready . 163s 163s ## Configuring slapd logging 163s SASL/EXTERNAL authentication started 163s SASL username: gidNumber=0+uidNumber=0,cn=peercred,cn=external,cn=auth 163s SASL SSF: 0 163s modifying entry "cn=config" 163s 163s 163s ## Creating bind9 ldap uid 163s adding new entry "uid=bind9,dc=example,dc=internal" 163s 163s modifying entry "olcDatabase={1}mdb,cn=config" 163s 163s 163s ## Loading bind9-dyndb-ldap schema 163s adding new entry "cn=dns,cn=schema,cn=config" 163s 163s 163s ## Loading syncproc module 163s modifying entry "cn=module{0},cn=config" 163s 163s adding new entry "olcOverlay=syncprov,olcDatabase={1}mdb,cn=config" 163s 163s 163s ## Loading DNS sample data 163s adding new entry "ou=dns,dc=example,dc=internal" 163s 163s adding new entry "idnsName=example.internal,ou=dns,dc=example,dc=internal" 163s 163s adding new entry "idnsName=dep8,idnsName=example.internal,ou=dns,dc=example,dc=internal" 163s 163s adding new entry "idnsName=_ldap._tcp,idnsName=example.internal,ou=dns,dc=example,dc=internal" 163s 163s adding new entry "idnsName=somehost,idnsName=example.internal,ou=dns,dc=example,dc=internal" 163s 163s 163s ## Configuring bind9 to use bind9-dyndb-ldap 163s ## Restarting bind9 163s 163s ## Checking DNS records 163s Using domain server: 163s Name: 127.0.0.1 163s Address: 127.0.0.1#53 163s Aliases: 163s 163s somehost.example.internal has address 192.168.141.6 163s 163s Using domain server: 163s Name: 127.0.0.1 163s Address: 127.0.0.1#53 163s Aliases: 163s 163s dep8.example.internal is an alias for example.internal. 163s example.internal has address 192.168.141.5 163s 163s Using domain server: 163s Name: 127.0.0.1 163s Address: 127.0.0.1#53 163s Aliases: 163s 163s _ldap._tcp.example.internal has SRV record 0 100 389 dep8.example.internal. 163s 163s Using domain server: 163s Name: 127.0.0.1 163s Address: 127.0.0.1#53 163s Aliases: 163s 163s example.internal has SOA record dep8.example.internal.example.internal. root.dep8.example.internal.example.internal. 1734462102 10800 900 604800 86400 163s autopkgtest [19:01:42]: test dyndb-ldap: -----------------------] 164s autopkgtest [19:01:43]: test dyndb-ldap: - - - - - - - - - - results - - - - - - - - - - 164s dyndb-ldap PASS 164s autopkgtest [19:01:43]: @@@@@@@@@@@@@@@@@@@@ summary 164s dyndb-ldap PASS 180s nova [W] Using flock in prodstack6-s390x 180s flock: timeout while waiting to get lock 180s Creating nova instance adt-noble-s390x-bind-dyndb-ldap-20241217-185859-juju-7f2275-prod-proposed-migration-environment-15-4b74dbce-3a8e-4ba9-8fd6-8f6c2f763df2 from image adt/ubuntu-noble-s390x-server-20241217.img (UUID bed51951-726a-4af0-aa3f-31def938a35d)... 180s nova [W] Timed out waiting for 13d251ed-569c-4dbc-9975-1f2dadef682c to get deleted.