0s autopkgtest [01:42:25]: starting date and time: 2024-07-23 01:42:25+0000 0s autopkgtest [01:42:25]: git checkout: fd3bed09 nova: allow more retries for quota issues 0s autopkgtest [01:42:25]: host juju-7f2275-prod-proposed-migration-environment-3; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.f9uo59qb/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-3@lcy02-47.secgroup --name adt-oracular-amd64-backuppc-20240723-014224-juju-7f2275-prod-proposed-migration-environment-3-3a005150-115b-46c0-9d7c-f94bc4c4d714 --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/ 83s autopkgtest [01:43:48]: testbed dpkg architecture: amd64 83s autopkgtest [01:43:48]: testbed apt version: 2.9.6 83s autopkgtest [01:43:48]: @@@@@@@@@@@@@@@@@@@@ test bed setup 84s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 84s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [3440 B] 84s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [58.5 kB] 84s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [407 kB] 84s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 84s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 Packages [119 kB] 84s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main i386 Packages [77.2 kB] 84s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/main amd64 c-n-f Metadata [2116 B] 84s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted i386 Packages [6244 B] 84s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/restricted amd64 Packages [34.9 kB] 84s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/restricted amd64 c-n-f Metadata [368 B] 84s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/universe amd64 Packages [488 kB] 84s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/universe i386 Packages [298 kB] 84s Get:14 http://ftpmaster.internal/ubuntu oracular-proposed/universe amd64 c-n-f Metadata [9900 B] 84s Get:15 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse amd64 Packages [8256 B] 84s Get:16 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse i386 Packages [3052 B] 84s Get:17 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse amd64 c-n-f Metadata [276 B] 86s Fetched 1651 kB in 0s (6545 kB/s) 86s Reading package lists... 88s Reading package lists... 88s Building dependency tree... 88s Reading state information... 88s Calculating upgrade... 89s The following packages will be upgraded: 89s gcc-14-base libgcc-s1 libstdc++6 systemd-hwe-hwdb 89s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 89s Need to get 920 kB of archives. 89s After this operation, 1024 B of additional disk space will be used. 89s Get:1 http://ftpmaster.internal/ubuntu oracular/main amd64 gcc-14-base amd64 14.1.0-5ubuntu1 [49.9 kB] 89s Get:2 http://ftpmaster.internal/ubuntu oracular/main amd64 libstdc++6 amd64 14.1.0-5ubuntu1 [789 kB] 89s Get:3 http://ftpmaster.internal/ubuntu oracular/main amd64 libgcc-s1 amd64 14.1.0-5ubuntu1 [78.3 kB] 89s Get:4 http://ftpmaster.internal/ubuntu oracular/main amd64 systemd-hwe-hwdb all 256.2.1 [3210 B] 89s Fetched 920 kB in 0s (6106 kB/s) 89s (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 ... 74487 files and directories currently installed.) 89s Preparing to unpack .../gcc-14-base_14.1.0-5ubuntu1_amd64.deb ... 89s Unpacking gcc-14-base:amd64 (14.1.0-5ubuntu1) over (14.1.0-4ubuntu2) ... 89s Setting up gcc-14-base:amd64 (14.1.0-5ubuntu1) ... 90s (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 ... 74487 files and directories currently installed.) 90s Preparing to unpack .../libstdc++6_14.1.0-5ubuntu1_amd64.deb ... 90s Unpacking libstdc++6:amd64 (14.1.0-5ubuntu1) over (14.1.0-4ubuntu2) ... 90s Setting up libstdc++6:amd64 (14.1.0-5ubuntu1) ... 90s (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 ... 74487 files and directories currently installed.) 90s Preparing to unpack .../libgcc-s1_14.1.0-5ubuntu1_amd64.deb ... 90s Unpacking libgcc-s1:amd64 (14.1.0-5ubuntu1) over (14.1.0-4ubuntu2) ... 90s Setting up libgcc-s1:amd64 (14.1.0-5ubuntu1) ... 90s (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 ... 74487 files and directories currently installed.) 90s Preparing to unpack .../systemd-hwe-hwdb_256.2.1_all.deb ... 90s Unpacking systemd-hwe-hwdb (256.2.1) over (255.1.3) ... 90s Setting up systemd-hwe-hwdb (256.2.1) ... 90s Processing triggers for udev (256-1ubuntu1) ... 91s Processing triggers for libc-bin (2.39-0ubuntu9) ... 91s Reading package lists... 91s Building dependency tree... 91s Reading state information... 91s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 92s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 92s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 92s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 92s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 93s Reading package lists... 93s Reading package lists... 93s Building dependency tree... 93s Reading state information... 94s Calculating upgrade... 94s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 94s Reading package lists... 94s Building dependency tree... 94s Reading state information... 95s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 98s autopkgtest [01:44:03]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP PREEMPT_DYNAMIC Sat Apr 20 00:40:06 UTC 2024 98s autopkgtest [01:44:03]: @@@@@@@@@@@@@@@@@@@@ apt-source backuppc 100s Get:1 http://ftpmaster.internal/ubuntu oracular/main backuppc 4.4.0-8.1 (dsc) [2165 B] 100s Get:2 http://ftpmaster.internal/ubuntu oracular/main backuppc 4.4.0-8.1 (tar) [657 kB] 100s Get:3 http://ftpmaster.internal/ubuntu oracular/main backuppc 4.4.0-8.1 (diff) [61.1 kB] 100s gpgv: Signature made Sun Jul 7 23:09:26 2024 UTC 100s gpgv: using RSA key 7D1ACFFAD9E0806C9C4CD3925C13D6DB93052E03 100s gpgv: Can't check signature: No public key 100s dpkg-source: warning: cannot verify inline signature for ./backuppc_4.4.0-8.1.dsc: no acceptable signature found 100s autopkgtest [01:44:05]: testing package backuppc version 4.4.0-8.1 101s autopkgtest [01:44:06]: build not needed 111s autopkgtest [01:44:16]: test smb-backup: preparing testbed 113s Reading package lists... 113s Building dependency tree... 113s Reading state information... 113s Starting pkgProblemResolver with broken count: 0 113s Starting 2 pkgProblemResolver with broken count: 0 113s Done 114s The following additional packages will be installed: 114s apache2 apache2-bin apache2-data apache2-utils backuppc backuppc-rsync 114s libapr1t64 libaprutil1-dbd-sqlite3 libaprutil1-ldap libaprutil1t64 114s libarchive-zip-perl libavahi-client3 libavahi-common-data libavahi-common3 114s libbackuppc-xs-perl libcgi-pm-perl libcups2t64 libfile-listing-perl 114s libhtml-parser-perl libhtml-tagset-perl libhttp-date-perl libldb2 114s liblua5.4-0 libnsl2 libsmbclient0 libtalloc2 libtdb1 libtevent0t64 114s libtime-parsedate-perl libtimedate-perl liburi-perl libwbclient0 postfix 114s samba samba-common samba-common-bin samba-libs smbclient ssl-cert 114s Suggested packages: 114s apache2-doc apache2-suexec-pristine | apache2-suexec-custom www-browser 114s certbot | acme-tiny | acmetool | dehydrated | lacme | lecm | lego 114s libscgi-perl par2 w3m | www-browser cups-common libdata-dump-perl 114s libbusiness-isbn-perl libregexp-ipv6-perl libwww-perl mail-reader 114s postfix-cdb postfix-doc postfix-ldap postfix-lmdb postfix-mta-sts-resolver 114s postfix-mongodb postfix-mysql postfix-pcre postfix-pgsql postfix-sqlite 114s procmail sasl2-bin | dovecot-common ctdb winbind heimdal-clients cifs-utils 114s Recommended packages: 114s libio-dirent-perl rrdtool libcgi-fast-perl libhttp-message-perl attr 114s samba-vfs-modules python3-samba 114s The following NEW packages will be installed: 114s apache2 apache2-bin apache2-data apache2-utils autopkgtest-satdep backuppc 114s backuppc-rsync libapr1t64 libaprutil1-dbd-sqlite3 libaprutil1-ldap 114s libaprutil1t64 libarchive-zip-perl libavahi-client3 libavahi-common-data 114s libavahi-common3 libbackuppc-xs-perl libcgi-pm-perl libcups2t64 114s libfile-listing-perl libhtml-parser-perl libhtml-tagset-perl 114s libhttp-date-perl libldb2 liblua5.4-0 libnsl2 libsmbclient0 libtalloc2 114s libtdb1 libtevent0t64 libtime-parsedate-perl libtimedate-perl liburi-perl 114s libwbclient0 postfix samba samba-common samba-common-bin samba-libs 114s smbclient ssl-cert 114s 0 upgraded, 40 newly installed, 0 to remove and 0 not upgraded. 114s Need to get 14.7 MB/14.7 MB of archives. 114s After this operation, 56.9 MB of additional disk space will be used. 114s Get:1 /tmp/autopkgtest.jpuYzI/1-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [720 B] 114s Get:2 http://ftpmaster.internal/ubuntu oracular/main amd64 libapr1t64 amd64 1.7.2-3.2 [106 kB] 114s Get:3 http://ftpmaster.internal/ubuntu oracular/main amd64 libaprutil1t64 amd64 1.6.3-2ubuntu1 [92.1 kB] 114s Get:4 http://ftpmaster.internal/ubuntu oracular/main amd64 libaprutil1-dbd-sqlite3 amd64 1.6.3-2ubuntu1 [11.2 kB] 114s Get:5 http://ftpmaster.internal/ubuntu oracular/main amd64 libaprutil1-ldap amd64 1.6.3-2ubuntu1 [9110 B] 114s Get:6 http://ftpmaster.internal/ubuntu oracular/main amd64 liblua5.4-0 amd64 5.4.6-3build2 [166 kB] 114s Get:7 http://ftpmaster.internal/ubuntu oracular/main amd64 apache2-bin amd64 2.4.59-2ubuntu2 [1332 kB] 114s Get:8 http://ftpmaster.internal/ubuntu oracular/main amd64 apache2-data all 2.4.59-2ubuntu2 [163 kB] 114s Get:9 http://ftpmaster.internal/ubuntu oracular/main amd64 apache2-utils amd64 2.4.59-2ubuntu2 [97.0 kB] 114s Get:10 http://ftpmaster.internal/ubuntu oracular/main amd64 apache2 amd64 2.4.59-2ubuntu2 [90.3 kB] 114s Get:11 http://ftpmaster.internal/ubuntu oracular/main amd64 backuppc-rsync amd64 3.1.3.0-3ubuntu1 [252 kB] 114s Get:12 http://ftpmaster.internal/ubuntu oracular/main amd64 ssl-cert all 1.1.2ubuntu2 [18.0 kB] 114s Get:13 http://ftpmaster.internal/ubuntu oracular/main amd64 libnsl2 amd64 1.3.0-3build3 [41.4 kB] 114s Get:14 http://ftpmaster.internal/ubuntu oracular/main amd64 postfix amd64 3.9.0-2 [1263 kB] 114s Get:15 http://ftpmaster.internal/ubuntu oracular/main amd64 libarchive-zip-perl all 1.68-1 [90.2 kB] 114s Get:16 http://ftpmaster.internal/ubuntu oracular/main amd64 libbackuppc-xs-perl amd64 0.62-2build5 [65.2 kB] 114s Get:17 http://ftpmaster.internal/ubuntu oracular/main amd64 libhtml-tagset-perl all 3.24-1 [14.1 kB] 114s Get:18 http://ftpmaster.internal/ubuntu oracular/main amd64 liburi-perl all 5.28-1 [88.1 kB] 114s Get:19 http://ftpmaster.internal/ubuntu oracular/main amd64 libhtml-parser-perl amd64 3.82-1 [85.6 kB] 114s Get:20 http://ftpmaster.internal/ubuntu oracular/main amd64 libcgi-pm-perl all 4.66-1 [185 kB] 114s Get:21 http://ftpmaster.internal/ubuntu oracular/main amd64 libtimedate-perl all 2.3300-2 [34.0 kB] 114s Get:22 http://ftpmaster.internal/ubuntu oracular/main amd64 libhttp-date-perl all 6.06-1 [10.2 kB] 114s Get:23 http://ftpmaster.internal/ubuntu oracular/main amd64 libfile-listing-perl all 6.16-1 [11.3 kB] 114s Get:24 http://ftpmaster.internal/ubuntu oracular/main amd64 libtime-parsedate-perl all 2015.103-4 [28.6 kB] 114s Get:25 http://ftpmaster.internal/ubuntu oracular/main amd64 backuppc amd64 4.4.0-8.1 [540 kB] 114s Get:26 http://ftpmaster.internal/ubuntu oracular/main amd64 samba-common all 2:4.20.2+dfsg-2ubuntu2 [65.4 kB] 114s Get:27 http://ftpmaster.internal/ubuntu oracular/main amd64 libavahi-common-data amd64 0.8-13ubuntu6 [29.7 kB] 114s Get:28 http://ftpmaster.internal/ubuntu oracular/main amd64 libavahi-common3 amd64 0.8-13ubuntu6 [23.3 kB] 114s Get:29 http://ftpmaster.internal/ubuntu oracular/main amd64 libavahi-client3 amd64 0.8-13ubuntu6 [26.8 kB] 114s Get:30 http://ftpmaster.internal/ubuntu oracular/main amd64 libcups2t64 amd64 2.4.7-1.2ubuntu9 [267 kB] 114s Get:31 http://ftpmaster.internal/ubuntu oracular/main amd64 libtalloc2 amd64 2.4.2-1build2 [27.3 kB] 114s Get:32 http://ftpmaster.internal/ubuntu oracular/main amd64 libtdb1 amd64 1.4.10-1build1 [46.8 kB] 114s Get:33 http://ftpmaster.internal/ubuntu oracular/main amd64 libtevent0t64 amd64 0.16.1-2build1 [42.6 kB] 114s Get:34 http://ftpmaster.internal/ubuntu oracular/main amd64 libldb2 amd64 2:2.9.1+samba4.20.2+dfsg-2ubuntu2 [190 kB] 114s Get:35 http://ftpmaster.internal/ubuntu oracular/main amd64 libwbclient0 amd64 2:4.20.2+dfsg-2ubuntu2 [73.7 kB] 114s Get:36 http://ftpmaster.internal/ubuntu oracular/main amd64 samba-libs amd64 2:4.20.2+dfsg-2ubuntu2 [6209 kB] 114s Get:37 http://ftpmaster.internal/ubuntu oracular/main amd64 samba-common-bin amd64 2:4.20.2+dfsg-2ubuntu2 [1319 kB] 114s Get:38 http://ftpmaster.internal/ubuntu oracular/main amd64 samba amd64 2:4.20.2+dfsg-2ubuntu2 [1008 kB] 114s Get:39 http://ftpmaster.internal/ubuntu oracular/main amd64 libsmbclient0 amd64 2:4.20.2+dfsg-2ubuntu2 [62.5 kB] 114s Get:40 http://ftpmaster.internal/ubuntu oracular/main amd64 smbclient amd64 2:4.20.2+dfsg-2ubuntu2 [477 kB] 115s Preconfiguring packages ... 115s Fetched 14.7 MB in 0s (32.0 MB/s) 115s Selecting previously unselected package libapr1t64:amd64. 115s (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 ... 74487 files and directories currently installed.) 115s Preparing to unpack .../00-libapr1t64_1.7.2-3.2_amd64.deb ... 115s Unpacking libapr1t64:amd64 (1.7.2-3.2) ... 115s Selecting previously unselected package libaprutil1t64:amd64. 115s Preparing to unpack .../01-libaprutil1t64_1.6.3-2ubuntu1_amd64.deb ... 115s Unpacking libaprutil1t64:amd64 (1.6.3-2ubuntu1) ... 115s Selecting previously unselected package libaprutil1-dbd-sqlite3:amd64. 115s Preparing to unpack .../02-libaprutil1-dbd-sqlite3_1.6.3-2ubuntu1_amd64.deb ... 115s Unpacking libaprutil1-dbd-sqlite3:amd64 (1.6.3-2ubuntu1) ... 115s Selecting previously unselected package libaprutil1-ldap:amd64. 115s Preparing to unpack .../03-libaprutil1-ldap_1.6.3-2ubuntu1_amd64.deb ... 115s Unpacking libaprutil1-ldap:amd64 (1.6.3-2ubuntu1) ... 115s Selecting previously unselected package liblua5.4-0:amd64. 115s Preparing to unpack .../04-liblua5.4-0_5.4.6-3build2_amd64.deb ... 115s Unpacking liblua5.4-0:amd64 (5.4.6-3build2) ... 115s Selecting previously unselected package apache2-bin. 115s Preparing to unpack .../05-apache2-bin_2.4.59-2ubuntu2_amd64.deb ... 115s Unpacking apache2-bin (2.4.59-2ubuntu2) ... 115s Selecting previously unselected package apache2-data. 115s Preparing to unpack .../06-apache2-data_2.4.59-2ubuntu2_all.deb ... 115s Unpacking apache2-data (2.4.59-2ubuntu2) ... 115s Selecting previously unselected package apache2-utils. 115s Preparing to unpack .../07-apache2-utils_2.4.59-2ubuntu2_amd64.deb ... 115s Unpacking apache2-utils (2.4.59-2ubuntu2) ... 115s Selecting previously unselected package apache2. 115s Preparing to unpack .../08-apache2_2.4.59-2ubuntu2_amd64.deb ... 115s Unpacking apache2 (2.4.59-2ubuntu2) ... 115s Selecting previously unselected package backuppc-rsync. 115s Preparing to unpack .../09-backuppc-rsync_3.1.3.0-3ubuntu1_amd64.deb ... 115s Unpacking backuppc-rsync (3.1.3.0-3ubuntu1) ... 115s Selecting previously unselected package ssl-cert. 115s Preparing to unpack .../10-ssl-cert_1.1.2ubuntu2_all.deb ... 115s Unpacking ssl-cert (1.1.2ubuntu2) ... 115s Selecting previously unselected package libnsl2:amd64. 115s Preparing to unpack .../11-libnsl2_1.3.0-3build3_amd64.deb ... 115s Unpacking libnsl2:amd64 (1.3.0-3build3) ... 115s Selecting previously unselected package postfix. 115s Preparing to unpack .../12-postfix_3.9.0-2_amd64.deb ... 115s Unpacking postfix (3.9.0-2) ... 116s Selecting previously unselected package libarchive-zip-perl. 116s Preparing to unpack .../13-libarchive-zip-perl_1.68-1_all.deb ... 116s Unpacking libarchive-zip-perl (1.68-1) ... 116s Selecting previously unselected package libbackuppc-xs-perl:amd64. 116s Preparing to unpack .../14-libbackuppc-xs-perl_0.62-2build5_amd64.deb ... 116s Unpacking libbackuppc-xs-perl:amd64 (0.62-2build5) ... 116s Selecting previously unselected package libhtml-tagset-perl. 116s Preparing to unpack .../15-libhtml-tagset-perl_3.24-1_all.deb ... 116s Unpacking libhtml-tagset-perl (3.24-1) ... 116s Selecting previously unselected package liburi-perl. 116s Preparing to unpack .../16-liburi-perl_5.28-1_all.deb ... 116s Unpacking liburi-perl (5.28-1) ... 116s Selecting previously unselected package libhtml-parser-perl:amd64. 116s Preparing to unpack .../17-libhtml-parser-perl_3.82-1_amd64.deb ... 116s Unpacking libhtml-parser-perl:amd64 (3.82-1) ... 116s Selecting previously unselected package libcgi-pm-perl. 116s Preparing to unpack .../18-libcgi-pm-perl_4.66-1_all.deb ... 116s Unpacking libcgi-pm-perl (4.66-1) ... 116s Selecting previously unselected package libtimedate-perl. 116s Preparing to unpack .../19-libtimedate-perl_2.3300-2_all.deb ... 116s Unpacking libtimedate-perl (2.3300-2) ... 116s Selecting previously unselected package libhttp-date-perl. 116s Preparing to unpack .../20-libhttp-date-perl_6.06-1_all.deb ... 116s Unpacking libhttp-date-perl (6.06-1) ... 116s Selecting previously unselected package libfile-listing-perl. 116s Preparing to unpack .../21-libfile-listing-perl_6.16-1_all.deb ... 116s Unpacking libfile-listing-perl (6.16-1) ... 116s Selecting previously unselected package libtime-parsedate-perl. 116s Preparing to unpack .../22-libtime-parsedate-perl_2015.103-4_all.deb ... 116s Unpacking libtime-parsedate-perl (2015.103-4) ... 116s Selecting previously unselected package backuppc. 116s Preparing to unpack .../23-backuppc_4.4.0-8.1_amd64.deb ... 116s Unpacking backuppc (4.4.0-8.1) ... 116s Selecting previously unselected package samba-common. 116s Preparing to unpack .../24-samba-common_2%3a4.20.2+dfsg-2ubuntu2_all.deb ... 116s Unpacking samba-common (2:4.20.2+dfsg-2ubuntu2) ... 116s Selecting previously unselected package libavahi-common-data:amd64. 116s Preparing to unpack .../25-libavahi-common-data_0.8-13ubuntu6_amd64.deb ... 116s Unpacking libavahi-common-data:amd64 (0.8-13ubuntu6) ... 116s Selecting previously unselected package libavahi-common3:amd64. 116s Preparing to unpack .../26-libavahi-common3_0.8-13ubuntu6_amd64.deb ... 116s Unpacking libavahi-common3:amd64 (0.8-13ubuntu6) ... 116s Selecting previously unselected package libavahi-client3:amd64. 116s Preparing to unpack .../27-libavahi-client3_0.8-13ubuntu6_amd64.deb ... 116s Unpacking libavahi-client3:amd64 (0.8-13ubuntu6) ... 116s Selecting previously unselected package libcups2t64:amd64. 116s Preparing to unpack .../28-libcups2t64_2.4.7-1.2ubuntu9_amd64.deb ... 116s Unpacking libcups2t64:amd64 (2.4.7-1.2ubuntu9) ... 116s Selecting previously unselected package libtalloc2:amd64. 116s Preparing to unpack .../29-libtalloc2_2.4.2-1build2_amd64.deb ... 116s Unpacking libtalloc2:amd64 (2.4.2-1build2) ... 116s Selecting previously unselected package libtdb1:amd64. 116s Preparing to unpack .../30-libtdb1_1.4.10-1build1_amd64.deb ... 116s Unpacking libtdb1:amd64 (1.4.10-1build1) ... 116s Selecting previously unselected package libtevent0t64:amd64. 116s Preparing to unpack .../31-libtevent0t64_0.16.1-2build1_amd64.deb ... 116s Unpacking libtevent0t64:amd64 (0.16.1-2build1) ... 116s Selecting previously unselected package libldb2:amd64. 116s Preparing to unpack .../32-libldb2_2%3a2.9.1+samba4.20.2+dfsg-2ubuntu2_amd64.deb ... 116s Unpacking libldb2:amd64 (2:2.9.1+samba4.20.2+dfsg-2ubuntu2) ... 116s Selecting previously unselected package libwbclient0:amd64. 116s Preparing to unpack .../33-libwbclient0_2%3a4.20.2+dfsg-2ubuntu2_amd64.deb ... 116s Unpacking libwbclient0:amd64 (2:4.20.2+dfsg-2ubuntu2) ... 116s Selecting previously unselected package samba-libs:amd64. 116s Preparing to unpack .../34-samba-libs_2%3a4.20.2+dfsg-2ubuntu2_amd64.deb ... 116s Unpacking samba-libs:amd64 (2:4.20.2+dfsg-2ubuntu2) ... 116s Selecting previously unselected package samba-common-bin. 116s Preparing to unpack .../35-samba-common-bin_2%3a4.20.2+dfsg-2ubuntu2_amd64.deb ... 116s Unpacking samba-common-bin (2:4.20.2+dfsg-2ubuntu2) ... 116s Selecting previously unselected package samba. 116s Preparing to unpack .../36-samba_2%3a4.20.2+dfsg-2ubuntu2_amd64.deb ... 116s Unpacking samba (2:4.20.2+dfsg-2ubuntu2) ... 117s Selecting previously unselected package libsmbclient0:amd64. 117s Preparing to unpack .../37-libsmbclient0_2%3a4.20.2+dfsg-2ubuntu2_amd64.deb ... 117s Unpacking libsmbclient0:amd64 (2:4.20.2+dfsg-2ubuntu2) ... 117s Selecting previously unselected package smbclient. 117s Preparing to unpack .../38-smbclient_2%3a4.20.2+dfsg-2ubuntu2_amd64.deb ... 117s Unpacking smbclient (2:4.20.2+dfsg-2ubuntu2) ... 117s Selecting previously unselected package autopkgtest-satdep. 117s Preparing to unpack .../39-1-autopkgtest-satdep.deb ... 117s Unpacking autopkgtest-satdep (0) ... 117s Setting up libarchive-zip-perl (1.68-1) ... 117s Setting up libtdb1:amd64 (1.4.10-1build1) ... 117s Setting up libhtml-tagset-perl (3.24-1) ... 117s Setting up samba-common (2:4.20.2+dfsg-2ubuntu2) ... 117s 117s Creating config file /etc/samba/smb.conf with new version 117s Setting up libwbclient0:amd64 (2:4.20.2+dfsg-2ubuntu2) ... 117s Setting up libtalloc2:amd64 (2.4.2-1build2) ... 117s Setting up backuppc-rsync (3.1.3.0-3ubuntu1) ... 117s Setting up ssl-cert (1.1.2ubuntu2) ... 117s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 119s Setting up libavahi-common-data:amd64 (0.8-13ubuntu6) ... 119s Setting up libtimedate-perl (2.3300-2) ... 119s Setting up libtime-parsedate-perl (2015.103-4) ... 119s Setting up libapr1t64:amd64 (1.7.2-3.2) ... 119s Setting up libnsl2:amd64 (1.3.0-3build3) ... 119s Setting up liblua5.4-0:amd64 (5.4.6-3build2) ... 119s Setting up apache2-data (2.4.59-2ubuntu2) ... 119s Setting up libbackuppc-xs-perl:amd64 (0.62-2build5) ... 119s Setting up liburi-perl (5.28-1) ... 119s Setting up libtevent0t64:amd64 (0.16.1-2build1) ... 119s Setting up libaprutil1t64:amd64 (1.6.3-2ubuntu1) ... 119s Setting up libhttp-date-perl (6.06-1) ... 119s Setting up libfile-listing-perl (6.16-1) ... 119s Setting up libavahi-common3:amd64 (0.8-13ubuntu6) ... 119s Setting up libaprutil1-ldap:amd64 (1.6.3-2ubuntu1) ... 119s Setting up libaprutil1-dbd-sqlite3:amd64 (1.6.3-2ubuntu1) ... 119s Setting up postfix (3.9.0-2) ... 119s info: Selecting GID from range 100 to 999 ... 119s info: Adding group `postfix' (GID 112) ... 119s info: Selecting UID from range 100 to 999 ... 119s 119s info: Adding system user `postfix' (UID 108) ... 119s info: Adding new user `postfix' (UID 108) with group `postfix' ... 119s info: Not creating home directory `/var/spool/postfix'. 119s Creating /etc/postfix/dynamicmaps.cf 119s info: Selecting GID from range 100 to 999 ... 119s info: Adding group `postdrop' (GID 113) ... 119s setting myhostname: autopkgtest.local 119s setting alias maps 119s setting alias database 119s changing /etc/mailname to autopkgtest.local 119s setting myorigin 119s setting destinations: $myhostname, autopkgtest.local, localhost.local, , localhost 119s setting relayhost: 119s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 119s setting mailbox_size_limit: 0 119s setting recipient_delimiter: + 121s setting inet_interfaces: all 123s setting inet_protocols: all 123s /etc/aliases does not exist, creating it. 123s WARNING: /etc/aliases exists, but does not have a root alias. 123s 123s Postfix (main.cf) is now set up with a default configuration. If you need to 123s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 123s Postfix configuration values, see postconf(1). 123s 123s After modifying main.cf, be sure to run 'systemctl reload postfix'. 123s 125s Running newaliases 126s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 127s Setting up libhtml-parser-perl:amd64 (3.82-1) ... 127s Setting up libldb2:amd64 (2:2.9.1+samba4.20.2+dfsg-2ubuntu2) ... 127s Setting up libavahi-client3:amd64 (0.8-13ubuntu6) ... 127s Setting up apache2-utils (2.4.59-2ubuntu2) ... 127s Setting up libcgi-pm-perl (4.66-1) ... 127s Setting up samba-libs:amd64 (2:4.20.2+dfsg-2ubuntu2) ... 127s Setting up apache2-bin (2.4.59-2ubuntu2) ... 127s Setting up libsmbclient0:amd64 (2:4.20.2+dfsg-2ubuntu2) ... 127s Setting up libcups2t64:amd64 (2.4.7-1.2ubuntu9) ... 127s Setting up apache2 (2.4.59-2ubuntu2) ... 127s Enabling module mpm_event. 127s Enabling module authz_core. 127s Enabling module authz_host. 127s Enabling module authn_core. 127s Enabling module auth_basic. 127s Enabling module access_compat. 127s Enabling module authn_file. 127s Enabling module authz_user. 127s Enabling module alias. 127s Enabling module dir. 127s Enabling module autoindex. 127s Enabling module env. 127s Enabling module mime. 127s Enabling module negotiation. 128s Enabling module setenvif. 128s Enabling module filter. 128s Enabling module deflate. 128s Enabling module status. 128s Enabling module reqtimeout. 128s Enabling conf charset. 128s Enabling conf localized-error-pages. 128s Enabling conf other-vhosts-access-log. 128s Enabling conf security. 128s Enabling conf serve-cgi-bin. 128s Enabling site 000-default. 129s Created symlink '/etc/systemd/system/multi-user.target.wants/apache2.service' → '/usr/lib/systemd/system/apache2.service'. 129s Created symlink '/etc/systemd/system/multi-user.target.wants/apache-htcacheclean.service' → '/usr/lib/systemd/system/apache-htcacheclean.service'. 130s Setting up backuppc (4.4.0-8.1) ... 130s 130s apache2_invoke: Enable configuration backuppc.conf 131s warn: The home directory `/var/lib/backuppc' already exists. Not touching this directory. 131s warn: Warning: The home directory `/var/lib/backuppc' does not belong to the user you are currently creating. 131s Adding password for user backuppc 131s Considering dependency authn_core for auth_basic: 131s Module authn_core already enabled 131s Module auth_basic already enabled 131s Considering dependency authz_core for authz_groupfile: 131s Module authz_core already enabled 131s Enabling module authz_groupfile. 131s To activate the new configuration, you need to run: 131s systemctl restart apache2 131s Module authn_file already enabled 131s Considering dependency authz_core for authz_user: 131s Module authz_core already enabled 131s Module authz_user already enabled 131s Your MPM seems to be threaded. Selecting cgid instead of cgi. 131s Enabling module cgid. 131s To activate the new configuration, you need to run: 131s systemctl restart apache2 131s 131s Creating config file /etc/backuppc/config.pl with new version 131s Created symlink '/etc/systemd/system/multi-user.target.wants/backuppc.service' → '/usr/lib/systemd/system/backuppc.service'. 132s Setting up smbclient (2:4.20.2+dfsg-2ubuntu2) ... 132s Setting up samba-common-bin (2:4.20.2+dfsg-2ubuntu2) ... 132s Setting up samba (2:4.20.2+dfsg-2ubuntu2) ... 133s Created symlink '/etc/systemd/system/smb.service' → '/usr/lib/systemd/system/smbd.service'. 133s Created symlink '/etc/systemd/system/multi-user.target.wants/smbd.service' → '/usr/lib/systemd/system/smbd.service'. 133s Created symlink '/etc/systemd/system/nmb.service' → '/usr/lib/systemd/system/nmbd.service'. 133s Created symlink '/etc/systemd/system/multi-user.target.wants/nmbd.service' → '/usr/lib/systemd/system/nmbd.service'. 134s Setting up autopkgtest-satdep (0) ... 134s Processing triggers for rsyslog (8.2312.0-3ubuntu9) ... 134s Processing triggers for ufw (0.36.2-6) ... 134s Processing triggers for man-db (2.12.1-2) ... 136s Processing triggers for libc-bin (2.39-0ubuntu9) ... 140s (Reading database ... 76330 files and directories currently installed.) 140s Removing autopkgtest-satdep (0) ... 140s autopkgtest [01:44:45]: test smb-backup: [----------------------- 141s Populating share path 141s Adding samba share 141s Adding [public] share 141s Configuring backuppc 141s Performing a full backup 141s Backup type: type = full, needs_full = 1, needs_incr = 1, lastFullTime = 0, opts{f} = 1, opts{i} = , opts{F} = 141s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 141s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 141s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.016 141s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.0.z created 2024-07-23 01:44:46 141s Backup prep: type = full, case = 1, inPlace = 1, doDuplicate = 0, newBkupNum = 0, newBkupIdx = 0, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 141s __bpc_progress_state__ backup share "public" 141s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 141s full backup started for share public 141s started full dump, share=public 141s Xfer PIDs are now 4771,4770 141s xferPids 4771,4770 141s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 141s tarmode is now full, system, hidden, noreset, noverbose 141s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 141s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 141s __bpc_progress_fileCnt__ 0 141s new 644 0/0 480434 tmp.c8UpRv 141s new 644 0/0 480434 tmp.NghkC8 141s new 644 0/0 480434 tmp.MizPeN 141s new 644 0/0 480434 tmp.sW4BhN 141s new 644 0/0 480434 tmp.F4syrI 141s new 644 0/0 480434 tmp.jGCnmH 141s new 644 0/0 480434 tmp.EZMtaG 141s new 644 0/0 480434 tmp.0OnLLQ 141s new 644 0/0 480434 tmp.wPFMDV 141s tar: dumped 10 files and 0 directories 141s Total bytes written: 4804340 (29.4 MiB/s) 141s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 141s new 644 0/0 480434 tmp.XmcNn7 141s __bpc_progress_fileCnt__ 10 141s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377769 sizeNewComp, 13 inodeLast 141s Xfer PIDs are now 141s xferPids 141s full backup 0 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 141s BackupExpire: cntFull = 1, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 0 141s __bpc_progress_state__ fsck 141s Running BackupPC_refCountUpdate -h localhost on localhost 141s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 141s Xfer PIDs are now 4777 141s xferPids 4777 141s BackupPC_refCountUpdate: doing fsck on localhost #0 (full) since $ConfRefCntFsck == 1 141s __bpc_progress_state__ refCnt #0 141s __bpc_progress_state__ cntUpdate #0 141s __bpc_progress_state__ rename #0 141s __bpc_progress_state__ sumUpdate 141s __bpc_progress_fileCnt__ 0/128 141s __bpc_progress_fileCnt__ 8/128 141s __bpc_progress_fileCnt__ 16/128 141s __bpc_progress_fileCnt__ 24/128 141s __bpc_progress_fileCnt__ 32/128 141s __bpc_progress_fileCnt__ 40/128 141s __bpc_progress_fileCnt__ 48/128 141s __bpc_progress_fileCnt__ 56/128 141s __bpc_progress_fileCnt__ 64/128 141s __bpc_progress_fileCnt__ 72/128 141s __bpc_progress_fileCnt__ 80/128 141s __bpc_progress_fileCnt__ 88/128 141s __bpc_progress_fileCnt__ 96/128 141s __bpc_progress_fileCnt__ 104/128 141s __bpc_progress_fileCnt__ 112/128 141s __bpc_progress_fileCnt__ 120/128 141s __bpc_progress_state__ rename total 141s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 141s Xfer PIDs are now 141s xferPids 141s Finished BackupPC_refCountUpdate (running time: 0 sec) 141s Xfer PIDs are now 141s xferPids 141s full backup complete 141s OK: Full backup succeeded 141s Changing share content and performing an incremental backup 142s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1721699086, opts{f} = , opts{i} = 1, opts{F} = 142s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 142s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 142s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.023 142s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.1.z created 2024-07-23 01:44:47 142s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 1, newBkupIdx = 1, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 142s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 142s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 142s Old package separator used in string at /usr/share/backuppc/bin/BackupPC_backupDuplicate line 89. 142s (Did you mean "$Host\'s" instead?) 142s Xfer PIDs are now 4847 142s xferPids 4847 142s __bpc_progress_state__ copy #0 -> #1 142s Copying backup #0 to #1 142s __bpc_progress_fileCnt__ 3 dirs 142s Xfer PIDs are now 4850,4847 142s xferPids 4850,4847 142s __bpc_progress_state__ sumUpdate 142s __bpc_progress_fileCnt__ 0/128 142s __bpc_progress_fileCnt__ 8/128 142s __bpc_progress_fileCnt__ 16/128 142s __bpc_progress_fileCnt__ 24/128 142s __bpc_progress_fileCnt__ 32/128 142s __bpc_progress_fileCnt__ 40/128 142s __bpc_progress_fileCnt__ 48/128 142s __bpc_progress_fileCnt__ 56/128 142s __bpc_progress_fileCnt__ 64/128 142s __bpc_progress_fileCnt__ 72/128 142s __bpc_progress_fileCnt__ 80/128 142s __bpc_progress_fileCnt__ 88/128 142s __bpc_progress_fileCnt__ 96/128 142s __bpc_progress_fileCnt__ 104/128 142s __bpc_progress_fileCnt__ 112/128 142s __bpc_progress_fileCnt__ 120/128 142s __bpc_progress_state__ rename total 142s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 142s Xfer PIDs are now 4847 142s xferPids 4847 142s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 142s Finished BackupPC_backupDuplicate (running time: 0 sec) 142s __bpc_progress_state__ backup share "public" 142s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 142s incr backup started back to 2024-07-23 00:44:46 (backup #0) for share public 142s started incr dump, share=public 142s Xfer PIDs are now 4854,4853 142s xferPids 4854,4853 142s 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 - 142s tar:1298 Getting files newer than Tue Jul 23 00:44:46 2024 142s tarmode is now full, system, hidden, noreset, noverbose 142s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 142s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 142s __bpc_progress_fileCnt__ 0 142s new 644 0/0 480434 tmp.UVa6EX 142s new 644 0/0 480434 tmp.9R2v8I 142s same 644 0/0 480434 tmp.c8UpRv 142s same 644 0/0 480434 tmp.NghkC8 142s same 644 0/0 480434 tmp.MizPeN 142s new 644 0/0 480434 tmp.Q7zz52 142s same 644 0/0 480434 tmp.sW4BhN 142s new 644 0/0 480434 tmp.XJCYza 142s same 644 0/0 480434 tmp.F4syrI 142s same 644 0/0 480434 tmp.jGCnmH 142s same 644 0/0 480434 tmp.EZMtaG 142s new 644 0/0 480434 tmp.ENw4zl 142s new 644 0/0 480434 tmp.IBFQE8 142s same 644 0/0 480434 tmp.0OnLLQ 142s new 644 0/0 480434 tmp.KSPUoz 142s same 644 0/0 480434 tmp.wPFMDV 142s new 644 0/0 480434 tmp.wkysin 142s new 644 0/0 480434 tmp.qZRUX3 142s same 644 0/0 480434 tmp.XmcNn7 142s tar: dumped 20 files and 0 directories 142s Total bytes written: 9608680 (47.9 MiB/s) 142s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 142s new 644 0/0 480434 tmp.8tnaSi 142s __bpc_progress_fileCnt__ 20 142s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377769 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377801 sizeNewComp, 24 inodeLast 142s Xfer PIDs are now 142s xferPids 142s incr backup 1 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 142s BackupExpire: cntFull = 2, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 1.15740740740741e-05 142s __bpc_progress_state__ fsck 142s Running BackupPC_refCountUpdate -h localhost on localhost 142s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 142s Xfer PIDs are now 4860 142s xferPids 4860 142s __bpc_progress_state__ cntUpdate #1 142s __bpc_progress_state__ rename #1 142s __bpc_progress_state__ sumUpdate 142s __bpc_progress_fileCnt__ 0/128 142s __bpc_progress_fileCnt__ 8/128 142s __bpc_progress_fileCnt__ 16/128 142s __bpc_progress_fileCnt__ 24/128 142s __bpc_progress_fileCnt__ 32/128 142s __bpc_progress_fileCnt__ 40/128 142s __bpc_progress_fileCnt__ 48/128 142s __bpc_progress_fileCnt__ 56/128 142s __bpc_progress_fileCnt__ 64/128 142s __bpc_progress_fileCnt__ 72/128 142s __bpc_progress_fileCnt__ 80/128 142s __bpc_progress_fileCnt__ 88/128 142s __bpc_progress_fileCnt__ 96/128 142s __bpc_progress_fileCnt__ 104/128 142s __bpc_progress_fileCnt__ 112/128 142s __bpc_progress_fileCnt__ 120/128 142s __bpc_progress_state__ rename total 142s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 142s Xfer PIDs are now 142s xferPids 142s Finished BackupPC_refCountUpdate (running time: 0 sec) 142s Xfer PIDs are now 142s xferPids 142s incr backup complete 142s OK: Incremental backup succeeded 142s Done. 143s autopkgtest [01:44:48]: test smb-backup: -----------------------] 143s autopkgtest [01:44:48]: test smb-backup: - - - - - - - - - - results - - - - - - - - - - 143s smb-backup PASS 143s autopkgtest [01:44:48]: test authenticated-smb-backup: preparing testbed 146s Reading package lists... 146s Building dependency tree... 146s Reading state information... 146s Starting pkgProblemResolver with broken count: 0 146s Starting 2 pkgProblemResolver with broken count: 0 146s Done 147s The following NEW packages will be installed: 147s autopkgtest-satdep 147s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 147s Need to get 0 B/724 B of archives. 147s After this operation, 0 B of additional disk space will be used. 147s Get:1 /tmp/autopkgtest.jpuYzI/2-autopkgtest-satdep.deb autopkgtest-satdep amd64 0 [724 B] 147s Selecting previously unselected package autopkgtest-satdep. 147s (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 ... 76331 files and directories currently installed.) 147s Preparing to unpack .../2-autopkgtest-satdep.deb ... 147s Unpacking autopkgtest-satdep (0) ... 147s Setting up autopkgtest-satdep (0) ... 150s (Reading database ... 76330 files and directories currently installed.) 150s Removing autopkgtest-satdep (0) ... 150s autopkgtest [01:44:55]: test authenticated-smb-backup: [----------------------- 151s Populating share path 151s Adding samba share 151s Adding [private] share 151s Creating user 151s Creating a local and samba user called smbtest5112 151s Setting samba password for the smbtest5112 user 151s Added user smbtest5112. 151s Configuring backuppc 151s Performing a full backup 151s Backup type: type = full, needs_full = , needs_incr = , lastFullTime = 1721699086, opts{f} = 1, opts{i} = , opts{F} = 151s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 151s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 151s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.018 151s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.2.z created 2024-07-23 01:44:56 151s Backup prep: type = full, case = 4, inPlace = 0, doDuplicate = 0, newBkupNum = 2, newBkupIdx = 2, lastBkupNum = 1, lastBkupIdx = 1 (FillCycle = 0, noFillCnt = 0) 151s __bpc_progress_state__ backup share "private" 151s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest5112 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 151s full backup started for share private 151s started full dump, share=private 151s Xfer PIDs are now 5206,5205 151s xferPids 5206,5205 151s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest5112 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 151s tarmode is now full, system, hidden, noreset, noverbose 151s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 151s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 151s __bpc_progress_fileCnt__ 0 151s new 644 0/0 480434 tmp.fniKpB 151s new 644 0/0 480434 tmp.iwYO8l 151s new 644 0/0 480434 tmp.d7ep8d 151s new 644 0/0 480434 tmp.pBnSVh 151s new 644 0/0 480434 tmp.ha8G9Z 151s new 644 0/0 480434 tmp.GO7UIo 151s new 644 0/0 480434 tmp.V3PYor 151s new 644 0/0 480434 tmp.B8klFB 151s new 644 0/0 480434 tmp.sgSTfj 151s tar: dumped 10 files and 0 directories 151s Total bytes written: 4804340 (31.5 MiB/s) 151s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 151s new 644 0/0 480434 tmp.rGeupt 151s __bpc_progress_fileCnt__ 10 151s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377814 sizeNewComp, 36 inodeLast 151s Xfer PIDs are now 151s xferPids 151s OrphanShareNameClean: removing orphan share public from /var/lib/backuppc/pc/localhost/2 151s __bpc_progress_state__ delete share #2/public 151s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDelete -h localhost -n 2 -l -m -s public / 151s Old package separator used in string at /usr/share/backuppc/bin/BackupPC_backupDelete line 102. 151s (Did you mean "$Host\'s" instead?) 151s Xfer PIDs are now 5212 151s xferPids 5212 151s BackupPC_backupDelete: removing #2/public/ 151s __bpc_progress_state__ merge #2/public/ -> #1/public/ 151s BackupPC_backupDelete: Merge into backup 1/public/ 151s mergeDir: attr write to /var/lib/backuppc/pc/localhost/1/fpublic//attrib failed (digest was ) 151s bpc_attrib_dirWrite: can't open/create raw /var/lib/backuppc/pc/localhost/1/fpublic for writing 152s Xfer PIDs are now 5212,5215 152s xferPids 5212,5215 152s __bpc_progress_state__ refCnt #1 152s __bpc_progress_state__ cntUpdate #1 152s __bpc_progress_state__ rename #1 152s __bpc_progress_state__ refCnt #2 152s BackupPC_refCountUpdate: localhost #2 inodeLast set to 37 (was 24) 152s __bpc_progress_state__ cntUpdate #2 152s __bpc_progress_state__ rename #2 152s __bpc_progress_state__ sumUpdate 152s __bpc_progress_fileCnt__ 0/128 152s __bpc_progress_fileCnt__ 8/128 152s __bpc_progress_fileCnt__ 16/128 152s __bpc_progress_fileCnt__ 24/128 152s __bpc_progress_fileCnt__ 32/128 152s __bpc_progress_fileCnt__ 40/128 152s __bpc_progress_fileCnt__ 48/128 152s __bpc_progress_fileCnt__ 56/128 152s __bpc_progress_fileCnt__ 64/128 152s __bpc_progress_fileCnt__ 72/128 152s __bpc_progress_fileCnt__ 80/128 152s __bpc_progress_fileCnt__ 88/128 152s __bpc_progress_fileCnt__ 96/128 152s __bpc_progress_fileCnt__ 104/128 152s __bpc_progress_fileCnt__ 112/128 152s __bpc_progress_fileCnt__ 120/128 152s __bpc_progress_state__ rename total 152s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 152s Xfer PIDs are now 5212 152s xferPids 5212 152s Xfer PIDs are now 152s xferPids 152s Finished BackupPC_backupDelete, status = 0 (running time: 1 sec) 152s Xfer PIDs are now 152s xferPids 152s full backup 2 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 152s BackupExpire: cntFull = 2, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 0.000115740740740741, oldestFull = 0.000127314814814815 152s __bpc_progress_state__ fsck 152s Running BackupPC_refCountUpdate -h localhost on localhost 152s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 152s Xfer PIDs are now 5218 152s xferPids 5218 152s BackupPC_refCountUpdate: doing fsck on localhost #2 (full) since $ConfRefCntFsck == 1 152s __bpc_progress_state__ refCnt #2 152s __bpc_progress_state__ cntUpdate #2 152s __bpc_progress_state__ rename #2 152s __bpc_progress_state__ sumUpdate 152s __bpc_progress_fileCnt__ 0/128 152s __bpc_progress_fileCnt__ 8/128 152s __bpc_progress_fileCnt__ 16/128 152s __bpc_progress_fileCnt__ 24/128 152s __bpc_progress_fileCnt__ 32/128 152s __bpc_progress_fileCnt__ 40/128 152s __bpc_progress_fileCnt__ 48/128 152s __bpc_progress_fileCnt__ 56/128 152s __bpc_progress_fileCnt__ 64/128 152s __bpc_progress_fileCnt__ 72/128 152s __bpc_progress_fileCnt__ 80/128 152s __bpc_progress_fileCnt__ 88/128 152s __bpc_progress_fileCnt__ 96/128 152s __bpc_progress_fileCnt__ 104/128 152s __bpc_progress_fileCnt__ 112/128 152s __bpc_progress_fileCnt__ 120/128 152s __bpc_progress_state__ rename total 152s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 152s Xfer PIDs are now 152s xferPids 152s Finished BackupPC_refCountUpdate (running time: 0 sec) 152s Xfer PIDs are now 152s xferPids 152s full backup complete 152s OK: Full backup succeeded 152s Changing share content and performing an incremental backup 152s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1721699096, opts{f} = , opts{i} = 1, opts{F} = 152s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 152s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 152s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.015 152s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.3.z created 2024-07-23 01:44:57 152s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 3, newBkupIdx = 3, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = 1) 152s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 152s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 152s Old package separator used in string at /usr/share/backuppc/bin/BackupPC_backupDuplicate line 89. 152s (Did you mean "$Host\'s" instead?) 152s Xfer PIDs are now 5288 152s xferPids 5288 152s __bpc_progress_state__ copy #2 -> #3 152s Copying backup #2 to #3 152s __bpc_progress_fileCnt__ 3 dirs 152s Xfer PIDs are now 5288,5291 152s xferPids 5288,5291 152s __bpc_progress_state__ sumUpdate 152s __bpc_progress_fileCnt__ 0/128 152s __bpc_progress_fileCnt__ 8/128 152s __bpc_progress_fileCnt__ 16/128 152s __bpc_progress_fileCnt__ 24/128 152s __bpc_progress_fileCnt__ 32/128 152s __bpc_progress_fileCnt__ 40/128 152s __bpc_progress_fileCnt__ 48/128 152s __bpc_progress_fileCnt__ 56/128 152s __bpc_progress_fileCnt__ 64/128 152s __bpc_progress_fileCnt__ 72/128 152s __bpc_progress_fileCnt__ 80/128 152s __bpc_progress_fileCnt__ 88/128 152s __bpc_progress_fileCnt__ 96/128 152s __bpc_progress_fileCnt__ 104/128 152s __bpc_progress_fileCnt__ 112/128 152s __bpc_progress_fileCnt__ 120/128 152s __bpc_progress_state__ rename total 152s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 152s Xfer PIDs are now 5288 152s xferPids 5288 152s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 152s Finished BackupPC_backupDuplicate (running time: 0 sec) 152s __bpc_progress_state__ backup share "private" 152s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest5112 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 152s incr backup started back to 2024-07-23 00:44:56 (backup #2) for share private 152s started incr dump, share=private 152s Xfer PIDs are now 5295,5294 152s xferPids 5295,5294 152s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest5112 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 152s tar:1298 Getting files newer than Tue Jul 23 00:44:56 2024 152s tarmode is now full, system, hidden, noreset, noverbose 152s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 152s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 152s __bpc_progress_fileCnt__ 0 152s same 644 0/0 480434 tmp.fniKpB 152s same 644 0/0 480434 tmp.iwYO8l 152s same 644 0/0 480434 tmp.d7ep8d 152s same 644 0/0 480434 tmp.pBnSVh 152s same 644 0/0 480434 tmp.ha8G9Z 152s same 644 0/0 480434 tmp.GO7UIo 152s same 644 0/0 480434 tmp.V3PYor 152s new 644 0/0 480434 tmp.f4aBBt 152s new 644 0/0 480434 tmp.ykjKdc 152s same 644 0/0 480434 tmp.B8klFB 152s same 644 0/0 480434 tmp.sgSTfj 152s same 644 0/0 480434 tmp.rGeupt 152s new 644 0/0 480434 tmp.uv0U97 152s new 644 0/0 480434 tmp.pUtYgq 152s new 644 0/0 480434 tmp.GC2CEE 152s new 644 0/0 480434 tmp.1IXrv7 152s new 644 0/0 480434 tmp.ED6mlK 152s new 644 0/0 480434 tmp.s5Jtux 152s new 644 0/0 480434 tmp.A3GyLm 152s tar: dumped 20 files and 0 directories 152s Total bytes written: 9608680 (48.7 MiB/s) 152s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 152s new 644 0/0 480434 tmp.QNpyv4 152s __bpc_progress_fileCnt__ 20 152s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377814 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377838 sizeNewComp, 47 inodeLast 152s Xfer PIDs are now 152s xferPids 152s incr backup 3 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 152s BackupExpire: cntFull = 3, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 0.000115740740740741, oldestFull = 0.000127314814814815 152s __bpc_progress_state__ fsck 152s Running BackupPC_refCountUpdate -h localhost on localhost 152s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 152s Xfer PIDs are now 5301 152s xferPids 5301 152s __bpc_progress_state__ cntUpdate #3 153s __bpc_progress_state__ rename #3 153s __bpc_progress_state__ sumUpdate 153s __bpc_progress_fileCnt__ 0/128 153s __bpc_progress_fileCnt__ 8/128 153s __bpc_progress_fileCnt__ 16/128 153s __bpc_progress_fileCnt__ 24/128 153s __bpc_progress_fileCnt__ 32/128 153s __bpc_progress_fileCnt__ 40/128 153s __bpc_progress_fileCnt__ 48/128 153s __bpc_progress_fileCnt__ 56/128 153s __bpc_progress_fileCnt__ 64/128 153s __bpc_progress_fileCnt__ 72/128 153s __bpc_progress_fileCnt__ 80/128 153s __bpc_progress_fileCnt__ 88/128 153s __bpc_progress_fileCnt__ 96/128 153s __bpc_progress_fileCnt__ 104/128 153s __bpc_progress_fileCnt__ 112/128 153s __bpc_progress_fileCnt__ 120/128 153s __bpc_progress_state__ rename total 153s BackupPC_refCountUpdate: host localhost got 0 errors (took 1 secs) 153s Xfer PIDs are now 153s xferPids 153s Finished BackupPC_refCountUpdate (running time: 1 sec) 153s Xfer PIDs are now 153s xferPids 153s incr backup complete 153s OK: Incremental backup succeeded 153s Done. 153s Deleted user smbtest5112. 153s userdel: smbtest5112 mail spool (/var/mail/smbtest5112) not found 153s autopkgtest [01:44:58]: test authenticated-smb-backup: -----------------------] 154s autopkgtest [01:44:59]: test authenticated-smb-backup: - - - - - - - - - - results - - - - - - - - - - 154s authenticated-smb-backup PASS 154s autopkgtest [01:44:59]: @@@@@@@@@@@@@@@@@@@@ summary 154s smb-backup PASS 154s authenticated-smb-backup PASS 176s nova [W] Skipping flock for amd64 176s Creating nova instance adt-oracular-amd64-backuppc-20240723-014224-juju-7f2275-prod-proposed-migration-environment-3-3a005150-115b-46c0-9d7c-f94bc4c4d714 from image adt/ubuntu-oracular-amd64-server-20240722.img (UUID bc70dd19-bfc6-4d23-903d-48876650ffb2)...