1s autopkgtest [01:42:50]: starting date and time: 2024-07-23 01:42:50+0000 1s autopkgtest [01:42:50]: git checkout: fd3bed09 nova: allow more retries for quota issues 1s autopkgtest [01:42:50]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.omfd1vod/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:merecat --apt-upgrade backuppc --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=merecat/2.31+git20220513+ds-5 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos01-s390x-7.secgroup --name adt-oracular-s390x-backuppc-20240723-014245-juju-7f2275-prod-proposed-migration-environment-2-b1170d88-8c3a-4cfa-be1d-1592e47c2b52 --image adt/ubuntu-oracular-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --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://us.ports.ubuntu.com/ubuntu-ports/ 369s autopkgtest [01:48:58]: testbed dpkg architecture: s390x 369s autopkgtest [01:48:58]: testbed apt version: 2.9.6 369s autopkgtest [01:48:58]: @@@@@@@@@@@@@@@@@@@@ test bed setup 371s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 371s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [3440 B] 371s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [407 kB] 371s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [58.5 kB] 371s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 371s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x Packages [91.4 kB] 371s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x c-n-f Metadata [2112 B] 371s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted s390x Packages [1368 B] 371s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted s390x c-n-f Metadata [120 B] 371s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe s390x Packages [404 kB] 371s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe s390x c-n-f Metadata [8372 B] 371s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse s390x Packages [2072 B] 371s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse s390x c-n-f Metadata [120 B] 372s Fetched 1113 kB in 1s (1305 kB/s) 372s Reading package lists... 376s Reading package lists... 376s Building dependency tree... 376s Reading state information... 376s Calculating upgrade... 376s The following packages will be upgraded: 376s systemd-hwe-hwdb 377s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 377s Need to get 3210 B of archives. 377s After this operation, 0 B of additional disk space will be used. 377s Get:1 http://ftpmaster.internal/ubuntu oracular/main s390x systemd-hwe-hwdb all 256.2.1 [3210 B] 377s Fetched 3210 B in 0s (22.0 kB/s) 377s (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 ... 54753 files and directories currently installed.) 377s Preparing to unpack .../systemd-hwe-hwdb_256.2.1_all.deb ... 377s Unpacking systemd-hwe-hwdb (256.2.1) over (255.1.3) ... 377s Setting up systemd-hwe-hwdb (256.2.1) ... 377s Processing triggers for udev (256-1ubuntu1) ... 378s Reading package lists... 378s Building dependency tree... 378s Reading state information... 378s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 379s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 379s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 379s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 379s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 380s Reading package lists... 380s 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... 382s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 386s autopkgtest [01:49:15]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:14:26 UTC 2024 386s autopkgtest [01:49:15]: @@@@@@@@@@@@@@@@@@@@ apt-source backuppc 388s Get:1 http://ftpmaster.internal/ubuntu oracular/main backuppc 4.4.0-8.1 (dsc) [2165 B] 388s Get:2 http://ftpmaster.internal/ubuntu oracular/main backuppc 4.4.0-8.1 (tar) [657 kB] 388s Get:3 http://ftpmaster.internal/ubuntu oracular/main backuppc 4.4.0-8.1 (diff) [61.1 kB] 389s gpgv: Signature made Sun Jul 7 23:09:26 2024 UTC 389s gpgv: using RSA key 7D1ACFFAD9E0806C9C4CD3925C13D6DB93052E03 389s gpgv: Can't check signature: No public key 389s dpkg-source: warning: cannot verify inline signature for ./backuppc_4.4.0-8.1.dsc: no acceptable signature found 389s autopkgtest [01:49:18]: testing package backuppc version 4.4.0-8.1 390s autopkgtest [01:49:19]: build not needed 392s autopkgtest [01:49:21]: test smb-backup: preparing testbed 395s Reading package lists... 395s Building dependency tree... 395s Reading state information... 395s Starting pkgProblemResolver with broken count: 0 395s Starting 2 pkgProblemResolver with broken count: 0 395s Done 395s The following additional packages will be installed: 395s apache2 apache2-bin apache2-data apache2-utils backuppc backuppc-rsync 395s libapr1t64 libaprutil1-dbd-sqlite3 libaprutil1-ldap libaprutil1t64 395s libarchive-zip-perl libavahi-client3 libavahi-common-data libavahi-common3 395s libbackuppc-xs-perl libcgi-pm-perl libcups2t64 libfile-listing-perl 395s libhtml-parser-perl libhtml-tagset-perl libhttp-date-perl libldb2 395s liblua5.4-0 libnsl2 libsmbclient0 libtalloc2 libtdb1 libtevent0t64 395s libtime-parsedate-perl libtimedate-perl liburi-perl libwbclient0 postfix 395s samba samba-common samba-common-bin samba-libs smbclient ssl-cert 395s Suggested packages: 395s apache2-doc apache2-suexec-pristine | apache2-suexec-custom www-browser 395s certbot | acme-tiny | acmetool | dehydrated | lacme | lecm | lego 395s libscgi-perl par2 w3m | www-browser cups-common libdata-dump-perl 395s libbusiness-isbn-perl libregexp-ipv6-perl libwww-perl mail-reader 395s postfix-cdb postfix-doc postfix-ldap postfix-lmdb postfix-mta-sts-resolver 395s postfix-mongodb postfix-mysql postfix-pcre postfix-pgsql postfix-sqlite 395s procmail sasl2-bin | dovecot-common ctdb winbind heimdal-clients cifs-utils 395s Recommended packages: 395s libio-dirent-perl rrdtool libcgi-fast-perl libhttp-message-perl attr 395s samba-vfs-modules python3-samba 395s The following NEW packages will be installed: 395s apache2 apache2-bin apache2-data apache2-utils autopkgtest-satdep backuppc 395s backuppc-rsync libapr1t64 libaprutil1-dbd-sqlite3 libaprutil1-ldap 395s libaprutil1t64 libarchive-zip-perl libavahi-client3 libavahi-common-data 395s libavahi-common3 libbackuppc-xs-perl libcgi-pm-perl libcups2t64 395s libfile-listing-perl libhtml-parser-perl libhtml-tagset-perl 395s libhttp-date-perl libldb2 liblua5.4-0 libnsl2 libsmbclient0 libtalloc2 395s libtdb1 libtevent0t64 libtime-parsedate-perl libtimedate-perl liburi-perl 395s libwbclient0 postfix samba samba-common samba-common-bin samba-libs 395s smbclient ssl-cert 395s 0 upgraded, 40 newly installed, 0 to remove and 0 not upgraded. 395s Need to get 15.0 MB/15.0 MB of archives. 395s After this operation, 57.7 MB of additional disk space will be used. 395s Get:1 /tmp/autopkgtest.1iTWP6/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [724 B] 396s Get:2 http://ftpmaster.internal/ubuntu oracular/main s390x libapr1t64 s390x 1.7.2-3.2 [113 kB] 396s Get:3 http://ftpmaster.internal/ubuntu oracular/main s390x libaprutil1t64 s390x 1.6.3-2ubuntu1 [97.9 kB] 396s Get:4 http://ftpmaster.internal/ubuntu oracular/main s390x libaprutil1-dbd-sqlite3 s390x 1.6.3-2ubuntu1 [11.6 kB] 396s Get:5 http://ftpmaster.internal/ubuntu oracular/main s390x libaprutil1-ldap s390x 1.6.3-2ubuntu1 [8958 B] 396s Get:6 http://ftpmaster.internal/ubuntu oracular/main s390x liblua5.4-0 s390x 5.4.6-3build2 [179 kB] 396s Get:7 http://ftpmaster.internal/ubuntu oracular/main s390x apache2-bin s390x 2.4.59-2ubuntu2 [1400 kB] 396s Get:8 http://ftpmaster.internal/ubuntu oracular/main s390x apache2-data all 2.4.59-2ubuntu2 [163 kB] 396s Get:9 http://ftpmaster.internal/ubuntu oracular/main s390x apache2-utils s390x 2.4.59-2ubuntu2 [100 kB] 396s Get:10 http://ftpmaster.internal/ubuntu oracular/main s390x apache2 s390x 2.4.59-2ubuntu2 [90.3 kB] 396s Get:11 http://ftpmaster.internal/ubuntu oracular/main s390x backuppc-rsync s390x 3.1.3.0-3ubuntu1 [271 kB] 396s Get:12 http://ftpmaster.internal/ubuntu oracular/main s390x ssl-cert all 1.1.2ubuntu2 [18.0 kB] 396s Get:13 http://ftpmaster.internal/ubuntu oracular/main s390x libnsl2 s390x 1.3.0-3build3 [44.1 kB] 396s Get:14 http://ftpmaster.internal/ubuntu oracular/main s390x postfix s390x 3.9.0-2 [1283 kB] 396s Get:15 http://ftpmaster.internal/ubuntu oracular/main s390x libarchive-zip-perl all 1.68-1 [90.2 kB] 396s Get:16 http://ftpmaster.internal/ubuntu oracular/main s390x libbackuppc-xs-perl s390x 0.62-2build5 [71.9 kB] 396s Get:17 http://ftpmaster.internal/ubuntu oracular/main s390x libhtml-tagset-perl all 3.24-1 [14.1 kB] 396s Get:18 http://ftpmaster.internal/ubuntu oracular/main s390x liburi-perl all 5.28-1 [88.1 kB] 396s Get:19 http://ftpmaster.internal/ubuntu oracular/main s390x libhtml-parser-perl s390x 3.82-1 [87.2 kB] 396s Get:20 http://ftpmaster.internal/ubuntu oracular/main s390x libcgi-pm-perl all 4.66-1 [185 kB] 396s Get:21 http://ftpmaster.internal/ubuntu oracular/main s390x libtimedate-perl all 2.3300-2 [34.0 kB] 396s Get:22 http://ftpmaster.internal/ubuntu oracular/main s390x libhttp-date-perl all 6.06-1 [10.2 kB] 396s Get:23 http://ftpmaster.internal/ubuntu oracular/main s390x libfile-listing-perl all 6.16-1 [11.3 kB] 396s Get:24 http://ftpmaster.internal/ubuntu oracular/main s390x libtime-parsedate-perl all 2015.103-4 [28.6 kB] 396s Get:25 http://ftpmaster.internal/ubuntu oracular/main s390x backuppc s390x 4.4.0-8.1 [540 kB] 396s Get:26 http://ftpmaster.internal/ubuntu oracular/main s390x samba-common all 2:4.20.2+dfsg-2ubuntu2 [65.4 kB] 396s Get:27 http://ftpmaster.internal/ubuntu oracular/main s390x libavahi-common-data s390x 0.8-13ubuntu6 [29.7 kB] 396s Get:28 http://ftpmaster.internal/ubuntu oracular/main s390x libavahi-common3 s390x 0.8-13ubuntu6 [24.1 kB] 396s Get:29 http://ftpmaster.internal/ubuntu oracular/main s390x libavahi-client3 s390x 0.8-13ubuntu6 [27.2 kB] 396s Get:30 http://ftpmaster.internal/ubuntu oracular/main s390x libcups2t64 s390x 2.4.7-1.2ubuntu9 [277 kB] 396s Get:31 http://ftpmaster.internal/ubuntu oracular/main s390x libtalloc2 s390x 2.4.2-1build2 [28.4 kB] 396s Get:32 http://ftpmaster.internal/ubuntu oracular/main s390x libtdb1 s390x 1.4.10-1build1 [50.0 kB] 396s Get:33 http://ftpmaster.internal/ubuntu oracular/main s390x libtevent0t64 s390x 0.16.1-2build1 [43.4 kB] 397s Get:34 http://ftpmaster.internal/ubuntu oracular/main s390x libldb2 s390x 2:2.9.1+samba4.20.2+dfsg-2ubuntu2 [193 kB] 397s Get:35 http://ftpmaster.internal/ubuntu oracular/main s390x libwbclient0 s390x 2:4.20.2+dfsg-2ubuntu2 [73.7 kB] 397s Get:36 http://ftpmaster.internal/ubuntu oracular/main s390x samba-libs s390x 2:4.20.2+dfsg-2ubuntu2 [6334 kB] 397s Get:37 http://ftpmaster.internal/ubuntu oracular/main s390x samba-common-bin s390x 2:4.20.2+dfsg-2ubuntu2 [1333 kB] 397s Get:38 http://ftpmaster.internal/ubuntu oracular/main s390x samba s390x 2:4.20.2+dfsg-2ubuntu2 [1009 kB] 397s Get:39 http://ftpmaster.internal/ubuntu oracular/main s390x libsmbclient0 s390x 2:4.20.2+dfsg-2ubuntu2 [63.9 kB] 397s Get:40 http://ftpmaster.internal/ubuntu oracular/main s390x smbclient s390x 2:4.20.2+dfsg-2ubuntu2 [494 kB] 397s Preconfiguring packages ... 398s Fetched 15.0 MB in 2s (9532 kB/s) 398s Selecting previously unselected package libapr1t64:s390x. 398s (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 ... 54753 files and directories currently installed.) 398s Preparing to unpack .../00-libapr1t64_1.7.2-3.2_s390x.deb ... 398s Unpacking libapr1t64:s390x (1.7.2-3.2) ... 398s Selecting previously unselected package libaprutil1t64:s390x. 398s Preparing to unpack .../01-libaprutil1t64_1.6.3-2ubuntu1_s390x.deb ... 398s Unpacking libaprutil1t64:s390x (1.6.3-2ubuntu1) ... 398s Selecting previously unselected package libaprutil1-dbd-sqlite3:s390x. 398s Preparing to unpack .../02-libaprutil1-dbd-sqlite3_1.6.3-2ubuntu1_s390x.deb ... 398s Unpacking libaprutil1-dbd-sqlite3:s390x (1.6.3-2ubuntu1) ... 398s Selecting previously unselected package libaprutil1-ldap:s390x. 398s Preparing to unpack .../03-libaprutil1-ldap_1.6.3-2ubuntu1_s390x.deb ... 398s Unpacking libaprutil1-ldap:s390x (1.6.3-2ubuntu1) ... 398s Selecting previously unselected package liblua5.4-0:s390x. 398s Preparing to unpack .../04-liblua5.4-0_5.4.6-3build2_s390x.deb ... 398s Unpacking liblua5.4-0:s390x (5.4.6-3build2) ... 398s Selecting previously unselected package apache2-bin. 398s Preparing to unpack .../05-apache2-bin_2.4.59-2ubuntu2_s390x.deb ... 398s Unpacking apache2-bin (2.4.59-2ubuntu2) ... 398s Selecting previously unselected package apache2-data. 398s Preparing to unpack .../06-apache2-data_2.4.59-2ubuntu2_all.deb ... 398s Unpacking apache2-data (2.4.59-2ubuntu2) ... 398s Selecting previously unselected package apache2-utils. 398s Preparing to unpack .../07-apache2-utils_2.4.59-2ubuntu2_s390x.deb ... 398s Unpacking apache2-utils (2.4.59-2ubuntu2) ... 398s Selecting previously unselected package apache2. 398s Preparing to unpack .../08-apache2_2.4.59-2ubuntu2_s390x.deb ... 398s Unpacking apache2 (2.4.59-2ubuntu2) ... 398s Selecting previously unselected package backuppc-rsync. 398s Preparing to unpack .../09-backuppc-rsync_3.1.3.0-3ubuntu1_s390x.deb ... 398s Unpacking backuppc-rsync (3.1.3.0-3ubuntu1) ... 398s Selecting previously unselected package ssl-cert. 398s Preparing to unpack .../10-ssl-cert_1.1.2ubuntu2_all.deb ... 398s Unpacking ssl-cert (1.1.2ubuntu2) ... 398s Selecting previously unselected package libnsl2:s390x. 398s Preparing to unpack .../11-libnsl2_1.3.0-3build3_s390x.deb ... 398s Unpacking libnsl2:s390x (1.3.0-3build3) ... 398s Selecting previously unselected package postfix. 398s Preparing to unpack .../12-postfix_3.9.0-2_s390x.deb ... 398s Unpacking postfix (3.9.0-2) ... 398s Selecting previously unselected package libarchive-zip-perl. 398s Preparing to unpack .../13-libarchive-zip-perl_1.68-1_all.deb ... 398s Unpacking libarchive-zip-perl (1.68-1) ... 398s Selecting previously unselected package libbackuppc-xs-perl:s390x. 398s Preparing to unpack .../14-libbackuppc-xs-perl_0.62-2build5_s390x.deb ... 398s Unpacking libbackuppc-xs-perl:s390x (0.62-2build5) ... 398s Selecting previously unselected package libhtml-tagset-perl. 398s Preparing to unpack .../15-libhtml-tagset-perl_3.24-1_all.deb ... 398s Unpacking libhtml-tagset-perl (3.24-1) ... 398s Selecting previously unselected package liburi-perl. 398s Preparing to unpack .../16-liburi-perl_5.28-1_all.deb ... 398s Unpacking liburi-perl (5.28-1) ... 398s Selecting previously unselected package libhtml-parser-perl:s390x. 398s Preparing to unpack .../17-libhtml-parser-perl_3.82-1_s390x.deb ... 398s Unpacking libhtml-parser-perl:s390x (3.82-1) ... 398s Selecting previously unselected package libcgi-pm-perl. 398s Preparing to unpack .../18-libcgi-pm-perl_4.66-1_all.deb ... 398s Unpacking libcgi-pm-perl (4.66-1) ... 399s Selecting previously unselected package libtimedate-perl. 399s Preparing to unpack .../19-libtimedate-perl_2.3300-2_all.deb ... 399s Unpacking libtimedate-perl (2.3300-2) ... 399s Selecting previously unselected package libhttp-date-perl. 399s Preparing to unpack .../20-libhttp-date-perl_6.06-1_all.deb ... 399s Unpacking libhttp-date-perl (6.06-1) ... 399s Selecting previously unselected package libfile-listing-perl. 399s Preparing to unpack .../21-libfile-listing-perl_6.16-1_all.deb ... 399s Unpacking libfile-listing-perl (6.16-1) ... 399s Selecting previously unselected package libtime-parsedate-perl. 399s Preparing to unpack .../22-libtime-parsedate-perl_2015.103-4_all.deb ... 399s Unpacking libtime-parsedate-perl (2015.103-4) ... 399s Selecting previously unselected package backuppc. 399s Preparing to unpack .../23-backuppc_4.4.0-8.1_s390x.deb ... 399s Unpacking backuppc (4.4.0-8.1) ... 399s Selecting previously unselected package samba-common. 399s Preparing to unpack .../24-samba-common_2%3a4.20.2+dfsg-2ubuntu2_all.deb ... 399s Unpacking samba-common (2:4.20.2+dfsg-2ubuntu2) ... 399s Selecting previously unselected package libavahi-common-data:s390x. 399s Preparing to unpack .../25-libavahi-common-data_0.8-13ubuntu6_s390x.deb ... 399s Unpacking libavahi-common-data:s390x (0.8-13ubuntu6) ... 399s Selecting previously unselected package libavahi-common3:s390x. 399s Preparing to unpack .../26-libavahi-common3_0.8-13ubuntu6_s390x.deb ... 399s Unpacking libavahi-common3:s390x (0.8-13ubuntu6) ... 399s Selecting previously unselected package libavahi-client3:s390x. 399s Preparing to unpack .../27-libavahi-client3_0.8-13ubuntu6_s390x.deb ... 399s Unpacking libavahi-client3:s390x (0.8-13ubuntu6) ... 399s Selecting previously unselected package libcups2t64:s390x. 399s Preparing to unpack .../28-libcups2t64_2.4.7-1.2ubuntu9_s390x.deb ... 399s Unpacking libcups2t64:s390x (2.4.7-1.2ubuntu9) ... 399s Selecting previously unselected package libtalloc2:s390x. 399s Preparing to unpack .../29-libtalloc2_2.4.2-1build2_s390x.deb ... 399s Unpacking libtalloc2:s390x (2.4.2-1build2) ... 399s Selecting previously unselected package libtdb1:s390x. 399s Preparing to unpack .../30-libtdb1_1.4.10-1build1_s390x.deb ... 399s Unpacking libtdb1:s390x (1.4.10-1build1) ... 399s Selecting previously unselected package libtevent0t64:s390x. 399s Preparing to unpack .../31-libtevent0t64_0.16.1-2build1_s390x.deb ... 399s Unpacking libtevent0t64:s390x (0.16.1-2build1) ... 399s Selecting previously unselected package libldb2:s390x. 399s Preparing to unpack .../32-libldb2_2%3a2.9.1+samba4.20.2+dfsg-2ubuntu2_s390x.deb ... 399s Unpacking libldb2:s390x (2:2.9.1+samba4.20.2+dfsg-2ubuntu2) ... 399s Selecting previously unselected package libwbclient0:s390x. 399s Preparing to unpack .../33-libwbclient0_2%3a4.20.2+dfsg-2ubuntu2_s390x.deb ... 399s Unpacking libwbclient0:s390x (2:4.20.2+dfsg-2ubuntu2) ... 399s Selecting previously unselected package samba-libs:s390x. 399s Preparing to unpack .../34-samba-libs_2%3a4.20.2+dfsg-2ubuntu2_s390x.deb ... 399s Unpacking samba-libs:s390x (2:4.20.2+dfsg-2ubuntu2) ... 399s Selecting previously unselected package samba-common-bin. 399s Preparing to unpack .../35-samba-common-bin_2%3a4.20.2+dfsg-2ubuntu2_s390x.deb ... 399s Unpacking samba-common-bin (2:4.20.2+dfsg-2ubuntu2) ... 399s Selecting previously unselected package samba. 399s Preparing to unpack .../36-samba_2%3a4.20.2+dfsg-2ubuntu2_s390x.deb ... 399s Unpacking samba (2:4.20.2+dfsg-2ubuntu2) ... 399s Selecting previously unselected package libsmbclient0:s390x. 399s Preparing to unpack .../37-libsmbclient0_2%3a4.20.2+dfsg-2ubuntu2_s390x.deb ... 399s Unpacking libsmbclient0:s390x (2:4.20.2+dfsg-2ubuntu2) ... 399s Selecting previously unselected package smbclient. 399s Preparing to unpack .../38-smbclient_2%3a4.20.2+dfsg-2ubuntu2_s390x.deb ... 399s Unpacking smbclient (2:4.20.2+dfsg-2ubuntu2) ... 399s Selecting previously unselected package autopkgtest-satdep. 399s Preparing to unpack .../39-1-autopkgtest-satdep.deb ... 399s Unpacking autopkgtest-satdep (0) ... 399s Setting up libarchive-zip-perl (1.68-1) ... 399s Setting up libtdb1:s390x (1.4.10-1build1) ... 399s Setting up libhtml-tagset-perl (3.24-1) ... 399s Setting up samba-common (2:4.20.2+dfsg-2ubuntu2) ... 400s 400s Creating config file /etc/samba/smb.conf with new version 400s Setting up libwbclient0:s390x (2:4.20.2+dfsg-2ubuntu2) ... 400s Setting up libtalloc2:s390x (2.4.2-1build2) ... 400s Setting up backuppc-rsync (3.1.3.0-3ubuntu1) ... 400s Setting up ssl-cert (1.1.2ubuntu2) ... 400s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 401s Setting up libavahi-common-data:s390x (0.8-13ubuntu6) ... 401s Setting up libtimedate-perl (2.3300-2) ... 402s Setting up libtime-parsedate-perl (2015.103-4) ... 402s Setting up libapr1t64:s390x (1.7.2-3.2) ... 402s Setting up libnsl2:s390x (1.3.0-3build3) ... 402s Setting up liblua5.4-0:s390x (5.4.6-3build2) ... 402s Setting up apache2-data (2.4.59-2ubuntu2) ... 402s Setting up libbackuppc-xs-perl:s390x (0.62-2build5) ... 402s Setting up liburi-perl (5.28-1) ... 402s Setting up libtevent0t64:s390x (0.16.1-2build1) ... 402s Setting up libaprutil1t64:s390x (1.6.3-2ubuntu1) ... 402s Setting up libhttp-date-perl (6.06-1) ... 402s Setting up libfile-listing-perl (6.16-1) ... 402s Setting up libavahi-common3:s390x (0.8-13ubuntu6) ... 402s Setting up libaprutil1-ldap:s390x (1.6.3-2ubuntu1) ... 402s Setting up libaprutil1-dbd-sqlite3:s390x (1.6.3-2ubuntu1) ... 402s Setting up postfix (3.9.0-2) ... 402s info: Selecting GID from range 100 to 999 ... 402s info: Adding group `postfix' (GID 113) ... 402s info: Selecting UID from range 100 to 999 ... 402s 402s info: Adding system user `postfix' (UID 107) ... 402s info: Adding new user `postfix' (UID 107) with group `postfix' ... 402s info: Not creating home directory `/var/spool/postfix'. 402s Creating /etc/postfix/dynamicmaps.cf 402s info: Selecting GID from range 100 to 999 ... 402s info: Adding group `postdrop' (GID 114) ... 402s setting myhostname: autopkgtest.local 402s setting alias maps 402s setting alias database 402s changing /etc/mailname to autopkgtest.local 402s setting myorigin 402s setting destinations: $myhostname, autopkgtest.local, localhost.local, , localhost 402s setting relayhost: 402s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 402s setting mailbox_size_limit: 0 402s setting recipient_delimiter: + 404s setting inet_interfaces: all 406s setting inet_protocols: all 406s /etc/aliases does not exist, creating it. 406s WARNING: /etc/aliases exists, but does not have a root alias. 406s 406s Postfix (main.cf) is now set up with a default configuration. If you need to 406s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 406s Postfix configuration values, see postconf(1). 406s 406s After modifying main.cf, be sure to run 'systemctl reload postfix'. 406s 408s Running newaliases 409s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 410s Setting up libhtml-parser-perl:s390x (3.82-1) ... 410s Setting up libldb2:s390x (2:2.9.1+samba4.20.2+dfsg-2ubuntu2) ... 410s Setting up libavahi-client3:s390x (0.8-13ubuntu6) ... 410s Setting up apache2-utils (2.4.59-2ubuntu2) ... 410s Setting up libcgi-pm-perl (4.66-1) ... 410s Setting up samba-libs:s390x (2:4.20.2+dfsg-2ubuntu2) ... 410s Setting up apache2-bin (2.4.59-2ubuntu2) ... 410s Setting up libsmbclient0:s390x (2:4.20.2+dfsg-2ubuntu2) ... 410s Setting up libcups2t64:s390x (2.4.7-1.2ubuntu9) ... 410s Setting up apache2 (2.4.59-2ubuntu2) ... 410s Enabling module mpm_event. 410s Enabling module authz_core. 410s Enabling module authz_host. 410s Enabling module authn_core. 410s Enabling module auth_basic. 410s Enabling module access_compat. 410s Enabling module authn_file. 410s Enabling module authz_user. 410s Enabling module alias. 410s Enabling module dir. 410s Enabling module autoindex. 410s Enabling module env. 410s Enabling module mime. 410s Enabling module negotiation. 410s Enabling module setenvif. 410s Enabling module filter. 411s Enabling module deflate. 411s Enabling module status. 411s Enabling module reqtimeout. 411s Enabling conf charset. 411s Enabling conf localized-error-pages. 411s Enabling conf other-vhosts-access-log. 411s Enabling conf security. 411s Enabling conf serve-cgi-bin. 411s Enabling site 000-default. 412s Created symlink '/etc/systemd/system/multi-user.target.wants/apache2.service' → '/usr/lib/systemd/system/apache2.service'. 412s Created symlink '/etc/systemd/system/multi-user.target.wants/apache-htcacheclean.service' → '/usr/lib/systemd/system/apache-htcacheclean.service'. 413s Setting up backuppc (4.4.0-8.1) ... 413s 413s apache2_invoke: Enable configuration backuppc.conf 414s warn: The home directory `/var/lib/backuppc' already exists. Not touching this directory. 414s warn: Warning: The home directory `/var/lib/backuppc' does not belong to the user you are currently creating. 414s Adding password for user backuppc 414s Considering dependency authn_core for auth_basic: 414s Module authn_core already enabled 414s Module auth_basic already enabled 414s Considering dependency authz_core for authz_groupfile: 414s Module authz_core already enabled 414s Enabling module authz_groupfile. 414s To activate the new configuration, you need to run: 414s systemctl restart apache2 414s Module authn_file already enabled 414s Considering dependency authz_core for authz_user: 414s Module authz_core already enabled 414s Module authz_user already enabled 414s Your MPM seems to be threaded. Selecting cgid instead of cgi. 414s Enabling module cgid. 414s To activate the new configuration, you need to run: 414s systemctl restart apache2 414s 414s Creating config file /etc/backuppc/config.pl with new version 414s Created symlink '/etc/systemd/system/multi-user.target.wants/backuppc.service' → '/usr/lib/systemd/system/backuppc.service'. 415s Setting up smbclient (2:4.20.2+dfsg-2ubuntu2) ... 415s Setting up samba-common-bin (2:4.20.2+dfsg-2ubuntu2) ... 415s Setting up samba (2:4.20.2+dfsg-2ubuntu2) ... 416s Created symlink '/etc/systemd/system/smb.service' → '/usr/lib/systemd/system/smbd.service'. 416s Created symlink '/etc/systemd/system/multi-user.target.wants/smbd.service' → '/usr/lib/systemd/system/smbd.service'. 416s Created symlink '/etc/systemd/system/nmb.service' → '/usr/lib/systemd/system/nmbd.service'. 416s Created symlink '/etc/systemd/system/multi-user.target.wants/nmbd.service' → '/usr/lib/systemd/system/nmbd.service'. 417s Setting up autopkgtest-satdep (0) ... 417s Processing triggers for rsyslog (8.2312.0-3ubuntu9) ... 417s Processing triggers for ufw (0.36.2-6) ... 417s Processing triggers for man-db (2.12.1-2) ... 418s Processing triggers for libc-bin (2.39-0ubuntu9) ... 421s (Reading database ... 56596 files and directories currently installed.) 421s Removing autopkgtest-satdep (0) ... 422s autopkgtest [01:49:51]: test smb-backup: [----------------------- 423s Populating share path 423s Adding samba share 423s Adding [public] share 423s Configuring backuppc 423s Performing a full backup 423s Backup type: type = full, needs_full = 1, needs_incr = 1, lastFullTime = 0, opts{f} = 1, opts{i} = , opts{F} = 423s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 423s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 423s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.006 423s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.0.z created 2024-07-23 01:49:52 423s Backup prep: type = full, case = 1, inPlace = 1, doDuplicate = 0, newBkupNum = 0, newBkupIdx = 0, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 423s __bpc_progress_state__ backup share "public" 423s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 423s full backup started for share public 423s started full dump, share=public 423s Xfer PIDs are now 4622,4621 423s xferPids 4622,4621 423s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 423s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 423s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 423s tarmode is now full, system, hidden, noreset, noverbose 424s __bpc_progress_fileCnt__ 0 424s new 644 0/0 480434 tmp.idYxib 424s new 644 0/0 480434 tmp.Ynspdu 424s new 644 0/0 480434 tmp.MjnW6O 424s new 644 0/0 480434 tmp.Xn3Kiv 424s new 644 0/0 480434 tmp.BJmY4X 424s new 644 0/0 480434 tmp.j6V7eC 424s new 644 0/0 480434 tmp.rsfyhd 424s new 644 0/0 480434 tmp.foJrxi 424s new 644 0/0 480434 tmp.UDIWBo 424s tar: dumped 10 files and 0 directories 424s Total bytes written: 4804340 (19.7 MiB/s) 424s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 424s new 644 0/0 480434 tmp.veYErz 424s __bpc_progress_fileCnt__ 10 424s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377812 sizeNewComp, 13 inodeLast 424s Xfer PIDs are now 424s xferPids 424s full backup 0 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 424s BackupExpire: cntFull = 1, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 1.15740740740741e-05 424s __bpc_progress_state__ fsck 424s Running BackupPC_refCountUpdate -h localhost on localhost 424s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 424s Xfer PIDs are now 4628 424s xferPids 4628 424s BackupPC_refCountUpdate: doing fsck on localhost #0 (full) since $ConfRefCntFsck == 1 424s __bpc_progress_state__ refCnt #0 424s __bpc_progress_state__ cntUpdate #0 424s __bpc_progress_state__ rename #0 424s __bpc_progress_state__ sumUpdate 424s __bpc_progress_fileCnt__ 0/128 424s __bpc_progress_fileCnt__ 8/128 424s __bpc_progress_fileCnt__ 16/128 424s __bpc_progress_fileCnt__ 24/128 424s __bpc_progress_fileCnt__ 32/128 424s __bpc_progress_fileCnt__ 40/128 424s __bpc_progress_fileCnt__ 48/128 424s __bpc_progress_fileCnt__ 56/128 424s __bpc_progress_fileCnt__ 64/128 424s __bpc_progress_fileCnt__ 72/128 424s __bpc_progress_fileCnt__ 80/128 424s __bpc_progress_fileCnt__ 88/128 424s __bpc_progress_fileCnt__ 96/128 424s __bpc_progress_fileCnt__ 104/128 424s __bpc_progress_fileCnt__ 112/128 424s __bpc_progress_fileCnt__ 120/128 424s __bpc_progress_state__ rename total 424s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 424s Xfer PIDs are now 424s xferPids 424s Finished BackupPC_refCountUpdate (running time: 0 sec) 424s Xfer PIDs are now 424s xferPids 424s full backup complete 424s OK: Full backup succeeded 424s Changing share content and performing an incremental backup 424s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1721699392, opts{f} = , opts{i} = 1, opts{F} = 424s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 424s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 424s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.043 424s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.1.z created 2024-07-23 01:49:53 424s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 1, newBkupIdx = 1, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 424s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 424s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 424s Old package separator used in string at /usr/share/backuppc/bin/BackupPC_backupDuplicate line 89. 424s (Did you mean "$Host\'s" instead?) 424s Xfer PIDs are now 4698 424s xferPids 4698 424s __bpc_progress_state__ copy #0 -> #1 424s Copying backup #0 to #1 424s __bpc_progress_fileCnt__ 3 dirs 424s Xfer PIDs are now 4701,4698 424s xferPids 4701,4698 424s __bpc_progress_state__ sumUpdate 424s __bpc_progress_fileCnt__ 0/128 424s __bpc_progress_fileCnt__ 8/128 424s __bpc_progress_fileCnt__ 16/128 424s __bpc_progress_fileCnt__ 24/128 424s __bpc_progress_fileCnt__ 32/128 424s __bpc_progress_fileCnt__ 40/128 424s __bpc_progress_fileCnt__ 48/128 424s __bpc_progress_fileCnt__ 56/128 424s __bpc_progress_fileCnt__ 64/128 424s __bpc_progress_fileCnt__ 72/128 424s __bpc_progress_fileCnt__ 80/128 424s __bpc_progress_fileCnt__ 88/128 424s __bpc_progress_fileCnt__ 96/128 424s __bpc_progress_fileCnt__ 104/128 424s __bpc_progress_fileCnt__ 112/128 424s __bpc_progress_fileCnt__ 120/128 424s __bpc_progress_state__ rename total 424s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 424s Xfer PIDs are now 4698 424s xferPids 4698 424s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 424s Finished BackupPC_backupDuplicate (running time: 0 sec) 424s __bpc_progress_state__ backup share "public" 424s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 424s incr backup started back to 2024-07-23 00:49:52 (backup #0) for share public 424s started incr dump, share=public 424s Xfer PIDs are now 4705,4704 424s xferPids 4705,4704 424s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 424s tar:1298 Getting files newer than Tue Jul 23 00:49:52 2024 424s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 424s tarmode is now full, system, hidden, noreset, noverbose 424s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 424s __bpc_progress_fileCnt__ 0 424s same 644 0/0 480434 tmp.idYxib 424s new 644 0/0 480434 tmp.w0B5g1 424s same 644 0/0 480434 tmp.Ynspdu 424s new 644 0/0 480434 tmp.3Jbph8 424s same 644 0/0 480434 tmp.MjnW6O 424s new 644 0/0 480434 tmp.lzHCWq 424s same 644 0/0 480434 tmp.Xn3Kiv 424s new 644 0/0 480434 tmp.XCQw7P 425s new 644 0/0 480434 tmp.Qy3yTa 425s same 644 0/0 480434 tmp.BJmY4X 425s same 644 0/0 480434 tmp.j6V7eC 425s new 644 0/0 480434 tmp.M1DXoV 425s new 644 0/0 480434 tmp.uOol5O 425s same 644 0/0 480434 tmp.rsfyhd 425s new 644 0/0 480434 tmp.a7lpPg 425s same 644 0/0 480434 tmp.foJrxi 425s new 644 0/0 480434 tmp.PCOldR 425s new 644 0/0 480434 tmp.G2Q6up 425s same 644 0/0 480434 tmp.UDIWBo 425s tar: dumped 20 files and 0 directories 425s Total bytes written: 9608680 (35.0 MiB/s) 425s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 425s same 644 0/0 480434 tmp.veYErz 425s __bpc_progress_fileCnt__ 20 425s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377812 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377844 sizeNewComp, 24 inodeLast 425s Xfer PIDs are now 425s xferPids 425s incr backup 1 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 425s BackupExpire: cntFull = 2, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 2.31481481481481e-05 425s __bpc_progress_state__ fsck 425s Running BackupPC_refCountUpdate -h localhost on localhost 425s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 425s Xfer PIDs are now 4712 425s xferPids 4712 425s __bpc_progress_state__ cntUpdate #1 425s __bpc_progress_state__ rename #1 425s __bpc_progress_state__ sumUpdate 425s __bpc_progress_fileCnt__ 0/128 425s __bpc_progress_fileCnt__ 8/128 425s __bpc_progress_fileCnt__ 16/128 425s __bpc_progress_fileCnt__ 24/128 425s __bpc_progress_fileCnt__ 32/128 425s __bpc_progress_fileCnt__ 40/128 425s __bpc_progress_fileCnt__ 48/128 425s __bpc_progress_fileCnt__ 56/128 425s __bpc_progress_fileCnt__ 64/128 425s __bpc_progress_fileCnt__ 72/128 425s __bpc_progress_fileCnt__ 80/128 425s __bpc_progress_fileCnt__ 88/128 425s __bpc_progress_fileCnt__ 96/128 425s __bpc_progress_fileCnt__ 104/128 425s __bpc_progress_fileCnt__ 112/128 425s __bpc_progress_fileCnt__ 120/128 425s __bpc_progress_state__ rename total 425s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 425s Xfer PIDs are now 425s xferPids 425s Finished BackupPC_refCountUpdate (running time: 0 sec) 425s Xfer PIDs are now 425s xferPids 425s incr backup complete 425s OK: Incremental backup succeeded 425s Done. 425s autopkgtest [01:49:54]: test smb-backup: -----------------------] 426s autopkgtest [01:49:55]: test smb-backup: - - - - - - - - - - results - - - - - - - - - - 426s smb-backup PASS 426s autopkgtest [01:49:55]: test authenticated-smb-backup: preparing testbed 428s Reading package lists... 428s Building dependency tree... 428s Reading state information... 429s Starting pkgProblemResolver with broken count: 0 429s Starting 2 pkgProblemResolver with broken count: 0 429s Done 429s The following NEW packages will be installed: 429s autopkgtest-satdep 429s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 429s Need to get 0 B/724 B of archives. 429s After this operation, 0 B of additional disk space will be used. 429s Get:1 /tmp/autopkgtest.1iTWP6/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [724 B] 429s Selecting previously unselected package autopkgtest-satdep. 429s (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 ... 56597 files and directories currently installed.) 429s Preparing to unpack .../2-autopkgtest-satdep.deb ... 429s Unpacking autopkgtest-satdep (0) ... 429s Setting up autopkgtest-satdep (0) ... 431s (Reading database ... 56596 files and directories currently installed.) 431s Removing autopkgtest-satdep (0) ... 432s autopkgtest [01:50:01]: test authenticated-smb-backup: [----------------------- 433s Populating share path 433s Adding samba share 433s Adding [private] share 433s Creating user 433s Creating a local and samba user called smbtest4966 433s Setting samba password for the smbtest4966 user 433s Added user smbtest4966. 433s Configuring backuppc 433s Performing a full backup 433s Backup type: type = full, needs_full = , needs_incr = , lastFullTime = 1721699392, opts{f} = 1, opts{i} = , opts{F} = 433s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 433s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 433s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.005 433s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.2.z created 2024-07-23 01:50:02 433s Backup prep: type = full, case = 4, inPlace = 0, doDuplicate = 0, newBkupNum = 2, newBkupIdx = 2, lastBkupNum = 1, lastBkupIdx = 1 (FillCycle = 0, noFillCnt = 0) 433s __bpc_progress_state__ backup share "private" 433s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest4966 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 433s full backup started for share private 433s started full dump, share=private 433s Xfer PIDs are now 5060,5059 433s xferPids 5060,5059 433s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest4966 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 433s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 433s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 433s tarmode is now full, system, hidden, noreset, noverbose 433s __bpc_progress_fileCnt__ 0 433s new 644 0/0 480434 tmp.hRt3fg 433s new 644 0/0 480434 tmp.FnyCQc 433s new 644 0/0 480434 tmp.Qq8n54 433s new 644 0/0 480434 tmp.FiYRhO 433s new 644 0/0 480434 tmp.6Sk1wx 433s new 644 0/0 480434 tmp.iPuLpq 433s new 644 0/0 480434 tmp.q0wGkO 433s new 644 0/0 480434 tmp.44Ten6 433s new 644 0/0 480434 tmp.CWP4zF 433s tar: dumped 10 files and 0 directories 433s Total bytes written: 4804340 (29.0 MiB/s) 433s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 433s new 644 0/0 480434 tmp.aRFXsV 433s __bpc_progress_fileCnt__ 10 433s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377773 sizeNewComp, 36 inodeLast 433s Xfer PIDs are now 433s xferPids 433s OrphanShareNameClean: removing orphan share public from /var/lib/backuppc/pc/localhost/2 433s __bpc_progress_state__ delete share #2/public 433s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDelete -h localhost -n 2 -l -m -s public / 433s Old package separator used in string at /usr/share/backuppc/bin/BackupPC_backupDelete line 102. 433s (Did you mean "$Host\'s" instead?) 433s Xfer PIDs are now 5066 433s xferPids 5066 433s BackupPC_backupDelete: removing #2/public/ 433s __bpc_progress_state__ merge #2/public/ -> #1/public/ 433s BackupPC_backupDelete: Merge into backup 1/public/ 433s mergeDir: attr write to /var/lib/backuppc/pc/localhost/1/fpublic//attrib failed (digest was ) 433s bpc_attrib_dirWrite: can't open/create raw /var/lib/backuppc/pc/localhost/1/fpublic for writing 433s Xfer PIDs are now 5066,5069 433s xferPids 5066,5069 433s __bpc_progress_state__ refCnt #1 433s __bpc_progress_state__ cntUpdate #1 433s __bpc_progress_state__ rename #1 433s __bpc_progress_state__ refCnt #2 433s BackupPC_refCountUpdate: localhost #2 inodeLast set to 37 (was 24) 433s __bpc_progress_state__ cntUpdate #2 433s __bpc_progress_state__ rename #2 433s __bpc_progress_state__ sumUpdate 433s __bpc_progress_fileCnt__ 0/128 433s __bpc_progress_fileCnt__ 8/128 433s __bpc_progress_fileCnt__ 16/128 433s __bpc_progress_fileCnt__ 24/128 433s __bpc_progress_fileCnt__ 32/128 433s __bpc_progress_fileCnt__ 40/128 433s __bpc_progress_fileCnt__ 48/128 433s __bpc_progress_fileCnt__ 56/128 433s __bpc_progress_fileCnt__ 64/128 433s __bpc_progress_fileCnt__ 72/128 433s __bpc_progress_fileCnt__ 80/128 433s __bpc_progress_fileCnt__ 88/128 433s __bpc_progress_fileCnt__ 96/128 433s __bpc_progress_fileCnt__ 104/128 433s __bpc_progress_fileCnt__ 112/128 433s __bpc_progress_fileCnt__ 120/128 433s __bpc_progress_state__ rename total 433s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 433s Xfer PIDs are now 5066 433s xferPids 5066 433s Xfer PIDs are now 433s xferPids 433s Finished BackupPC_backupDelete, status = 0 (running time: 0 sec) 433s Xfer PIDs are now 433s xferPids 433s full backup 2 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 433s BackupExpire: cntFull = 2, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 0.000104166666666667, oldestFull = 0.000115740740740741 433s __bpc_progress_state__ fsck 433s Running BackupPC_refCountUpdate -h localhost on localhost 433s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 434s Xfer PIDs are now 5072 434s xferPids 5072 434s BackupPC_refCountUpdate: doing fsck on localhost #2 (full) since $ConfRefCntFsck == 1 434s __bpc_progress_state__ refCnt #2 434s __bpc_progress_state__ cntUpdate #2 434s __bpc_progress_state__ rename #2 434s __bpc_progress_state__ sumUpdate 434s __bpc_progress_fileCnt__ 0/128 434s __bpc_progress_fileCnt__ 8/128 434s __bpc_progress_fileCnt__ 16/128 434s __bpc_progress_fileCnt__ 24/128 434s __bpc_progress_fileCnt__ 32/128 434s __bpc_progress_fileCnt__ 40/128 434s __bpc_progress_fileCnt__ 48/128 434s __bpc_progress_fileCnt__ 56/128 434s __bpc_progress_fileCnt__ 64/128 434s __bpc_progress_fileCnt__ 72/128 434s __bpc_progress_fileCnt__ 80/128 434s __bpc_progress_fileCnt__ 88/128 434s __bpc_progress_fileCnt__ 96/128 434s __bpc_progress_fileCnt__ 104/128 434s __bpc_progress_fileCnt__ 112/128 434s __bpc_progress_fileCnt__ 120/128 434s __bpc_progress_state__ rename total 434s BackupPC_refCountUpdate: host localhost got 0 errors (took 1 secs) 434s Xfer PIDs are now 434s xferPids 434s Finished BackupPC_refCountUpdate (running time: 1 sec) 434s Xfer PIDs are now 434s xferPids 434s full backup complete 434s OK: Full backup succeeded 434s Changing share content and performing an incremental backup 434s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1721699402, opts{f} = , opts{i} = 1, opts{F} = 434s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 434s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 434s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.007 434s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.3.z created 2024-07-23 01:50:03 434s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 3, newBkupIdx = 3, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = 1) 434s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 434s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 434s Old package separator used in string at /usr/share/backuppc/bin/BackupPC_backupDuplicate line 89. 434s (Did you mean "$Host\'s" instead?) 434s Xfer PIDs are now 5142 434s xferPids 5142 434s __bpc_progress_state__ copy #2 -> #3 434s Copying backup #2 to #3 434s __bpc_progress_fileCnt__ 3 dirs 434s Xfer PIDs are now 5145,5142 434s xferPids 5145,5142 434s __bpc_progress_state__ sumUpdate 434s __bpc_progress_fileCnt__ 0/128 434s __bpc_progress_fileCnt__ 8/128 434s __bpc_progress_fileCnt__ 16/128 434s __bpc_progress_fileCnt__ 24/128 434s __bpc_progress_fileCnt__ 32/128 434s __bpc_progress_fileCnt__ 40/128 434s __bpc_progress_fileCnt__ 48/128 434s __bpc_progress_fileCnt__ 56/128 434s __bpc_progress_fileCnt__ 64/128 434s __bpc_progress_fileCnt__ 72/128 434s __bpc_progress_fileCnt__ 80/128 434s __bpc_progress_fileCnt__ 88/128 434s __bpc_progress_fileCnt__ 96/128 434s __bpc_progress_fileCnt__ 104/128 434s __bpc_progress_fileCnt__ 112/128 434s __bpc_progress_fileCnt__ 120/128 434s __bpc_progress_state__ rename total 434s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 434s Xfer PIDs are now 5142 434s xferPids 5142 434s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 434s Finished BackupPC_backupDuplicate (running time: 0 sec) 434s __bpc_progress_state__ backup share "private" 434s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest4966 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 434s incr backup started back to 2024-07-23 00:50:02 (backup #2) for share private 434s started incr dump, share=private 434s Xfer PIDs are now 5149,5148 434s xferPids 5149,5148 434s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest4966 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 434s tar:1298 Getting files newer than Tue Jul 23 00:50:02 2024 434s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 434s tarmode is now full, system, hidden, noreset, noverbose 434s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 434s __bpc_progress_fileCnt__ 0 434s new 644 0/0 480434 tmp.1XTxzG 434s same 644 0/0 480434 tmp.hRt3fg 434s new 644 0/0 480434 tmp.H57rwX 434s new 644 0/0 480434 tmp.y0CgPu 434s same 644 0/0 480434 tmp.FnyCQc 434s same 644 0/0 480434 tmp.Qq8n54 434s new 644 0/0 480434 tmp.PMlBHt 434s new 644 0/0 480434 tmp.LmuBHT 434s new 644 0/0 480434 tmp.NUPDCJ 434s new 644 0/0 480434 tmp.ze6ptN 434s new 644 0/0 480434 tmp.MDyUVQ 434s same 644 0/0 480434 tmp.FiYRhO 434s new 644 0/0 480434 tmp.8yPKkF 434s same 644 0/0 480434 tmp.6Sk1wx 434s same 644 0/0 480434 tmp.iPuLpq 434s same 644 0/0 480434 tmp.q0wGkO 434s same 644 0/0 480434 tmp.44Ten6 434s new 644 0/0 480434 tmp.dXcEps 434s same 644 0/0 480434 tmp.CWP4zF 434s same 644 0/0 480434 tmp.aRFXsV 434s tar: dumped 20 files and 0 directories 434s Total bytes written: 9608680 (41.8 MiB/s) 434s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 434s __bpc_progress_fileCnt__ 20 434s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377773 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377782 sizeNewComp, 47 inodeLast 434s Xfer PIDs are now 434s xferPids 434s incr backup 3 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 434s BackupExpire: cntFull = 3, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 0.000115740740740741, oldestFull = 0.000127314814814815 434s __bpc_progress_state__ fsck 434s Running BackupPC_refCountUpdate -h localhost on localhost 434s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 434s Xfer PIDs are now 5155 434s xferPids 5155 434s __bpc_progress_state__ cntUpdate #3 434s __bpc_progress_state__ rename #3 434s __bpc_progress_state__ sumUpdate 434s __bpc_progress_fileCnt__ 0/128 434s __bpc_progress_fileCnt__ 8/128 434s __bpc_progress_fileCnt__ 16/128 434s __bpc_progress_fileCnt__ 24/128 434s __bpc_progress_fileCnt__ 32/128 434s __bpc_progress_fileCnt__ 40/128 434s __bpc_progress_fileCnt__ 48/128 434s __bpc_progress_fileCnt__ 56/128 434s __bpc_progress_fileCnt__ 64/128 434s __bpc_progress_fileCnt__ 72/128 434s __bpc_progress_fileCnt__ 80/128 434s __bpc_progress_fileCnt__ 88/128 434s __bpc_progress_fileCnt__ 96/128 434s __bpc_progress_fileCnt__ 104/128 434s __bpc_progress_fileCnt__ 112/128 434s __bpc_progress_fileCnt__ 120/128 434s __bpc_progress_state__ rename total 434s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 434s Xfer PIDs are now 434s xferPids 434s Finished BackupPC_refCountUpdate (running time: 0 sec) 434s Xfer PIDs are now 434s xferPids 435s incr backup complete 435s OK: Incremental backup succeeded 435s Done. 435s Deleted user smbtest4966. 435s userdel: smbtest4966 mail spool (/var/mail/smbtest4966) not found 435s autopkgtest [01:50:04]: test authenticated-smb-backup: -----------------------] 435s autopkgtest [01:50:04]: test authenticated-smb-backup: - - - - - - - - - - results - - - - - - - - - - 435s authenticated-smb-backup PASS 436s autopkgtest [01:50:05]: @@@@@@@@@@@@@@@@@@@@ summary 436s smb-backup PASS 436s authenticated-smb-backup PASS 451s nova [W] Using flock in scalingstack-bos01-s390x 451s Creating nova instance adt-oracular-s390x-backuppc-20240723-014245-juju-7f2275-prod-proposed-migration-environment-2-b1170d88-8c3a-4cfa-be1d-1592e47c2b52 from image adt/ubuntu-oracular-s390x-server-20240722.img (UUID e58e753e-731a-4089-a679-22831da5b207)...