0s autopkgtest [13:46:02]: starting date and time: 2024-07-12 13:46:02+0000 0s autopkgtest [13:46:02]: git checkout: fd3bed09 nova: allow more retries for quota issues 0s autopkgtest [13:46:02]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.kr3r1qdp/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:glibc --apt-upgrade backuppc --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.39-3.1ubuntu3 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-arm64-24.secgroup --name adt-oracular-arm64-backuppc-20240712-134601-juju-7f2275-prod-proposed-migration-environment-2-c3072ba8-3321-4ce7-8928-0d28e2b76923 --image adt/ubuntu-oracular-arm64-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://ftpmaster.internal/ubuntu/ 69s autopkgtest [13:47:11]: testbed dpkg architecture: arm64 69s autopkgtest [13:47:11]: testbed apt version: 2.9.6 69s autopkgtest [13:47:11]: @@@@@@@@@@@@@@@@@@@@ test bed setup 69s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 70s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 70s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [345 kB] 70s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [2576 B] 70s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [45.3 kB] 70s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main arm64 Packages [91.8 kB] 70s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main arm64 c-n-f Metadata [2104 B] 70s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted arm64 Packages [43.9 kB] 70s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted arm64 c-n-f Metadata [364 B] 70s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe arm64 Packages [356 kB] 70s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe arm64 c-n-f Metadata [7988 B] 70s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse arm64 Packages [7596 B] 70s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse arm64 c-n-f Metadata [276 B] 74s Fetched 1037 kB in 1s (1310 kB/s) 74s Reading package lists... 77s Reading package lists... 77s Building dependency tree... 77s Reading state information... 78s Calculating upgrade... 79s The following packages will be upgraded: 79s libc-bin libc-dev-bin libc-devtools libc6 libc6-dev locales 79s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 79s Need to get 9208 kB of archives. 79s After this operation, 29.7 kB disk space will be freed. 79s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main arm64 libc-devtools arm64 2.39-3.1ubuntu3 [27.7 kB] 79s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main arm64 libc6-dev arm64 2.39-3.1ubuntu3 [1591 kB] 80s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main arm64 libc-dev-bin arm64 2.39-3.1ubuntu3 [19.7 kB] 80s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main arm64 libc6 arm64 2.39-3.1ubuntu3 [2770 kB] 80s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main arm64 libc-bin arm64 2.39-3.1ubuntu3 [579 kB] 80s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main arm64 locales all 2.39-3.1ubuntu3 [4220 kB] 81s Preconfiguring packages ... 81s Fetched 9208 kB in 1s (9820 kB/s) 81s (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 ... 78392 files and directories currently installed.) 81s Preparing to unpack .../libc-devtools_2.39-3.1ubuntu3_arm64.deb ... 81s Unpacking libc-devtools (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 82s Preparing to unpack .../libc6-dev_2.39-3.1ubuntu3_arm64.deb ... 82s Unpacking libc6-dev:arm64 (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 82s Preparing to unpack .../libc-dev-bin_2.39-3.1ubuntu3_arm64.deb ... 82s Unpacking libc-dev-bin (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 82s Preparing to unpack .../libc6_2.39-3.1ubuntu3_arm64.deb ... 82s Unpacking libc6:arm64 (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 82s Setting up libc6:arm64 (2.39-3.1ubuntu3) ... 83s Error: Could not restart systemd, systemd binary not working 83s (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 ... 78392 files and directories currently installed.) 83s Preparing to unpack .../libc-bin_2.39-3.1ubuntu3_arm64.deb ... 83s Unpacking libc-bin (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 83s Setting up libc-bin (2.39-3.1ubuntu3) ... 83s (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 ... 78392 files and directories currently installed.) 83s Preparing to unpack .../locales_2.39-3.1ubuntu3_all.deb ... 83s Unpacking locales (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 84s Setting up locales (2.39-3.1ubuntu3) ... 85s Generating locales (this might take a while)... 87s en_US.UTF-8... done 87s Generation complete. 87s Setting up libc-dev-bin (2.39-3.1ubuntu3) ... 87s Setting up libc-devtools (2.39-3.1ubuntu3) ... 87s Setting up libc6-dev:arm64 (2.39-3.1ubuntu3) ... 87s Processing triggers for man-db (2.12.1-2) ... 89s Processing triggers for systemd (256-1ubuntu1) ... 90s Reading package lists... 90s Building dependency tree... 90s Reading state information... 91s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 92s Hit:1 http://ftpmaster.internal/ubuntu oracular InRelease 92s Hit:2 http://ftpmaster.internal/ubuntu oracular-updates InRelease 92s Hit:3 http://ftpmaster.internal/ubuntu oracular-security InRelease 92s Hit:4 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 93s Reading package lists... 93s Reading package lists... 94s Building dependency tree... 94s Reading state information... 94s Calculating upgrade... 95s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 95s Reading package lists... 95s Building dependency tree... 95s Reading state information... 96s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 105s autopkgtest [13:47:47]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP PREEMPT_DYNAMIC Sat Apr 20 02:32:42 UTC 2024 107s autopkgtest [13:47:49]: @@@@@@@@@@@@@@@@@@@@ apt-source backuppc 117s Get:1 http://ftpmaster.internal/ubuntu oracular/main backuppc 4.4.0-8build1 (dsc) [2289 B] 117s Get:2 http://ftpmaster.internal/ubuntu oracular/main backuppc 4.4.0-8build1 (tar) [657 kB] 117s Get:3 http://ftpmaster.internal/ubuntu oracular/main backuppc 4.4.0-8build1 (diff) [61.1 kB] 117s gpgv: Signature made Mon Apr 8 15:54:41 2024 UTC 117s gpgv: using RSA key 4FB588A84C2DDE79A74C77876FA458DD1DB03F71 117s gpgv: issuer "juliank@ubuntu.com" 117s gpgv: Can't check signature: No public key 117s dpkg-source: warning: cannot verify inline signature for ./backuppc_4.4.0-8build1.dsc: no acceptable signature found 118s autopkgtest [13:48:00]: testing package backuppc version 4.4.0-8build1 118s autopkgtest [13:48:00]: build not needed 119s autopkgtest [13:48:01]: test smb-backup: preparing testbed 142s Reading package lists... 143s Building dependency tree... 143s Reading state information... 143s Starting pkgProblemResolver with broken count: 0 143s Starting 2 pkgProblemResolver with broken count: 0 143s Done 144s The following additional packages will be installed: 144s apache2 apache2-bin apache2-data apache2-utils backuppc backuppc-rsync 144s libapr1t64 libaprutil1-dbd-sqlite3 libaprutil1-ldap libaprutil1t64 144s libarchive-zip-perl libavahi-client3 libavahi-common-data libavahi-common3 144s libbackuppc-xs-perl libcgi-pm-perl libcups2t64 libfile-listing-perl 144s libhtml-parser-perl libhtml-tagset-perl libhttp-date-perl libldb2 144s liblua5.4-0 libsmbclient0 libtalloc2 libtdb1 libtevent0t64 144s libtime-parsedate-perl libtimedate-perl liburi-perl libwbclient0 postfix 144s samba samba-common samba-common-bin samba-libs smbclient ssl-cert 144s Suggested packages: 144s apache2-doc apache2-suexec-pristine | apache2-suexec-custom www-browser 144s certbot | acme-tiny | acmetool | dehydrated | lacme | lecm | lego 144s libscgi-perl par2 w3m | www-browser cups-common libdata-dump-perl 144s libbusiness-isbn-perl libregexp-ipv6-perl libwww-perl mail-reader 144s postfix-cdb postfix-doc postfix-ldap postfix-lmdb postfix-mta-sts-resolver 144s postfix-mongodb postfix-mysql postfix-pcre postfix-pgsql postfix-sqlite 144s procmail sasl2-bin | dovecot-common ctdb winbind heimdal-clients cifs-utils 144s Recommended packages: 144s libio-dirent-perl rrdtool libcgi-fast-perl libhttp-message-perl attr 144s samba-vfs-modules python3-samba 144s The following NEW packages will be installed: 144s apache2 apache2-bin apache2-data apache2-utils autopkgtest-satdep backuppc 144s backuppc-rsync libapr1t64 libaprutil1-dbd-sqlite3 libaprutil1-ldap 144s libaprutil1t64 libarchive-zip-perl libavahi-client3 libavahi-common-data 144s libavahi-common3 libbackuppc-xs-perl libcgi-pm-perl libcups2t64 144s libfile-listing-perl libhtml-parser-perl libhtml-tagset-perl 144s libhttp-date-perl libldb2 liblua5.4-0 libsmbclient0 libtalloc2 libtdb1 144s libtevent0t64 libtime-parsedate-perl libtimedate-perl liburi-perl 144s libwbclient0 postfix samba samba-common samba-common-bin samba-libs 144s smbclient ssl-cert 144s 0 upgraded, 39 newly installed, 0 to remove and 0 not upgraded. 144s Need to get 14.7 MB/14.7 MB of archives. 144s After this operation, 73.1 MB of additional disk space will be used. 144s Get:1 /tmp/autopkgtest.LU9X3M/1-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [720 B] 144s Get:2 http://ftpmaster.internal/ubuntu oracular/main arm64 libapr1t64 arm64 1.7.2-3.2 [105 kB] 144s Get:3 http://ftpmaster.internal/ubuntu oracular/main arm64 libaprutil1t64 arm64 1.6.3-2ubuntu1 [94.1 kB] 145s Get:4 http://ftpmaster.internal/ubuntu oracular/main arm64 libaprutil1-dbd-sqlite3 arm64 1.6.3-2ubuntu1 [11.2 kB] 145s Get:5 http://ftpmaster.internal/ubuntu oracular/main arm64 libaprutil1-ldap arm64 1.6.3-2ubuntu1 [9044 B] 145s Get:6 http://ftpmaster.internal/ubuntu oracular/main arm64 liblua5.4-0 arm64 5.4.6-3build2 [158 kB] 145s Get:7 http://ftpmaster.internal/ubuntu oracular/main arm64 apache2-bin arm64 2.4.59-2ubuntu2 [1320 kB] 145s Get:8 http://ftpmaster.internal/ubuntu oracular/main arm64 apache2-data all 2.4.59-2ubuntu2 [163 kB] 145s Get:9 http://ftpmaster.internal/ubuntu oracular/main arm64 apache2-utils arm64 2.4.59-2ubuntu2 [96.4 kB] 145s Get:10 http://ftpmaster.internal/ubuntu oracular/main arm64 apache2 arm64 2.4.59-2ubuntu2 [90.3 kB] 145s Get:11 http://ftpmaster.internal/ubuntu oracular/main arm64 backuppc-rsync arm64 3.1.3.0-3ubuntu1 [248 kB] 145s Get:12 http://ftpmaster.internal/ubuntu oracular/main arm64 ssl-cert all 1.1.2ubuntu2 [18.0 kB] 145s Get:13 http://ftpmaster.internal/ubuntu oracular/main arm64 postfix arm64 3.9.0-2 [1260 kB] 145s Get:14 http://ftpmaster.internal/ubuntu oracular/main arm64 libarchive-zip-perl all 1.68-1 [90.2 kB] 145s Get:15 http://ftpmaster.internal/ubuntu oracular/main arm64 libbackuppc-xs-perl arm64 0.62-2build5 [65.1 kB] 145s Get:16 http://ftpmaster.internal/ubuntu oracular/main arm64 libhtml-tagset-perl all 3.24-1 [14.1 kB] 145s Get:17 http://ftpmaster.internal/ubuntu oracular/main arm64 liburi-perl all 5.28-1 [88.1 kB] 145s Get:18 http://ftpmaster.internal/ubuntu oracular/main arm64 libhtml-parser-perl arm64 3.82-1 [84.8 kB] 145s Get:19 http://ftpmaster.internal/ubuntu oracular/main arm64 libcgi-pm-perl all 4.66-1 [185 kB] 145s Get:20 http://ftpmaster.internal/ubuntu oracular/main arm64 libtimedate-perl all 2.3300-2 [34.0 kB] 145s Get:21 http://ftpmaster.internal/ubuntu oracular/main arm64 libhttp-date-perl all 6.06-1 [10.2 kB] 145s Get:22 http://ftpmaster.internal/ubuntu oracular/main arm64 libfile-listing-perl all 6.16-1 [11.3 kB] 145s Get:23 http://ftpmaster.internal/ubuntu oracular/main arm64 libtime-parsedate-perl all 2015.103-4 [28.6 kB] 145s Get:24 http://ftpmaster.internal/ubuntu oracular/main arm64 backuppc arm64 4.4.0-8build1 [541 kB] 145s Get:25 http://ftpmaster.internal/ubuntu oracular/main arm64 samba-common all 2:4.20.2+dfsg-2ubuntu2 [65.4 kB] 145s Get:26 http://ftpmaster.internal/ubuntu oracular/main arm64 libavahi-common-data arm64 0.8-13ubuntu6 [29.6 kB] 145s Get:27 http://ftpmaster.internal/ubuntu oracular/main arm64 libavahi-common3 arm64 0.8-13ubuntu6 [23.3 kB] 145s Get:28 http://ftpmaster.internal/ubuntu oracular/main arm64 libavahi-client3 arm64 0.8-13ubuntu6 [27.2 kB] 145s Get:29 http://ftpmaster.internal/ubuntu oracular/main arm64 libcups2t64 arm64 2.4.7-1.2ubuntu9 [268 kB] 145s Get:30 http://ftpmaster.internal/ubuntu oracular/main arm64 libtalloc2 arm64 2.4.2-1build2 [26.7 kB] 145s Get:31 http://ftpmaster.internal/ubuntu oracular/main arm64 libtdb1 arm64 1.4.10-1build1 [48.5 kB] 145s Get:32 http://ftpmaster.internal/ubuntu oracular/main arm64 libtevent0t64 arm64 0.16.1-2build1 [42.3 kB] 145s Get:33 http://ftpmaster.internal/ubuntu oracular/main arm64 libldb2 arm64 2:2.9.1+samba4.20.2+dfsg-2ubuntu2 [191 kB] 145s Get:34 http://ftpmaster.internal/ubuntu oracular/main arm64 libwbclient0 arm64 2:4.20.2+dfsg-2ubuntu2 [74.5 kB] 145s Get:35 http://ftpmaster.internal/ubuntu oracular/main arm64 samba-libs arm64 2:4.20.2+dfsg-2ubuntu2 [6269 kB] 145s Get:36 http://ftpmaster.internal/ubuntu oracular/main arm64 samba-common-bin arm64 2:4.20.2+dfsg-2ubuntu2 [1314 kB] 145s Get:37 http://ftpmaster.internal/ubuntu oracular/main arm64 samba arm64 2:4.20.2+dfsg-2ubuntu2 [1002 kB] 145s Get:38 http://ftpmaster.internal/ubuntu oracular/main arm64 libsmbclient0 arm64 2:4.20.2+dfsg-2ubuntu2 [62.3 kB] 145s Get:39 http://ftpmaster.internal/ubuntu oracular/main arm64 smbclient arm64 2:4.20.2+dfsg-2ubuntu2 [494 kB] 146s Preconfiguring packages ... 146s Fetched 14.7 MB in 1s (11.6 MB/s) 146s Selecting previously unselected package libapr1t64:arm64. 146s (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 ... 78392 files and directories currently installed.) 146s Preparing to unpack .../00-libapr1t64_1.7.2-3.2_arm64.deb ... 146s Unpacking libapr1t64:arm64 (1.7.2-3.2) ... 146s Selecting previously unselected package libaprutil1t64:arm64. 146s Preparing to unpack .../01-libaprutil1t64_1.6.3-2ubuntu1_arm64.deb ... 146s Unpacking libaprutil1t64:arm64 (1.6.3-2ubuntu1) ... 146s Selecting previously unselected package libaprutil1-dbd-sqlite3:arm64. 146s Preparing to unpack .../02-libaprutil1-dbd-sqlite3_1.6.3-2ubuntu1_arm64.deb ... 146s Unpacking libaprutil1-dbd-sqlite3:arm64 (1.6.3-2ubuntu1) ... 146s Selecting previously unselected package libaprutil1-ldap:arm64. 146s Preparing to unpack .../03-libaprutil1-ldap_1.6.3-2ubuntu1_arm64.deb ... 146s Unpacking libaprutil1-ldap:arm64 (1.6.3-2ubuntu1) ... 146s Selecting previously unselected package liblua5.4-0:arm64. 146s Preparing to unpack .../04-liblua5.4-0_5.4.6-3build2_arm64.deb ... 146s Unpacking liblua5.4-0:arm64 (5.4.6-3build2) ... 146s Selecting previously unselected package apache2-bin. 146s Preparing to unpack .../05-apache2-bin_2.4.59-2ubuntu2_arm64.deb ... 146s Unpacking apache2-bin (2.4.59-2ubuntu2) ... 147s Selecting previously unselected package apache2-data. 147s Preparing to unpack .../06-apache2-data_2.4.59-2ubuntu2_all.deb ... 147s Unpacking apache2-data (2.4.59-2ubuntu2) ... 147s Selecting previously unselected package apache2-utils. 147s Preparing to unpack .../07-apache2-utils_2.4.59-2ubuntu2_arm64.deb ... 147s Unpacking apache2-utils (2.4.59-2ubuntu2) ... 147s Selecting previously unselected package apache2. 147s Preparing to unpack .../08-apache2_2.4.59-2ubuntu2_arm64.deb ... 147s Unpacking apache2 (2.4.59-2ubuntu2) ... 147s Selecting previously unselected package backuppc-rsync. 147s Preparing to unpack .../09-backuppc-rsync_3.1.3.0-3ubuntu1_arm64.deb ... 147s Unpacking backuppc-rsync (3.1.3.0-3ubuntu1) ... 147s Selecting previously unselected package ssl-cert. 147s Preparing to unpack .../10-ssl-cert_1.1.2ubuntu2_all.deb ... 147s Unpacking ssl-cert (1.1.2ubuntu2) ... 147s Selecting previously unselected package postfix. 147s Preparing to unpack .../11-postfix_3.9.0-2_arm64.deb ... 147s Unpacking postfix (3.9.0-2) ... 147s Selecting previously unselected package libarchive-zip-perl. 147s Preparing to unpack .../12-libarchive-zip-perl_1.68-1_all.deb ... 147s Unpacking libarchive-zip-perl (1.68-1) ... 147s Selecting previously unselected package libbackuppc-xs-perl:arm64. 147s Preparing to unpack .../13-libbackuppc-xs-perl_0.62-2build5_arm64.deb ... 147s Unpacking libbackuppc-xs-perl:arm64 (0.62-2build5) ... 147s Selecting previously unselected package libhtml-tagset-perl. 147s Preparing to unpack .../14-libhtml-tagset-perl_3.24-1_all.deb ... 147s Unpacking libhtml-tagset-perl (3.24-1) ... 147s Selecting previously unselected package liburi-perl. 147s Preparing to unpack .../15-liburi-perl_5.28-1_all.deb ... 147s Unpacking liburi-perl (5.28-1) ... 147s Selecting previously unselected package libhtml-parser-perl:arm64. 147s Preparing to unpack .../16-libhtml-parser-perl_3.82-1_arm64.deb ... 147s Unpacking libhtml-parser-perl:arm64 (3.82-1) ... 147s Selecting previously unselected package libcgi-pm-perl. 147s Preparing to unpack .../17-libcgi-pm-perl_4.66-1_all.deb ... 147s Unpacking libcgi-pm-perl (4.66-1) ... 147s Selecting previously unselected package libtimedate-perl. 147s Preparing to unpack .../18-libtimedate-perl_2.3300-2_all.deb ... 147s Unpacking libtimedate-perl (2.3300-2) ... 147s Selecting previously unselected package libhttp-date-perl. 148s Preparing to unpack .../19-libhttp-date-perl_6.06-1_all.deb ... 148s Unpacking libhttp-date-perl (6.06-1) ... 148s Selecting previously unselected package libfile-listing-perl. 148s Preparing to unpack .../20-libfile-listing-perl_6.16-1_all.deb ... 148s Unpacking libfile-listing-perl (6.16-1) ... 148s Selecting previously unselected package libtime-parsedate-perl. 148s Preparing to unpack .../21-libtime-parsedate-perl_2015.103-4_all.deb ... 148s Unpacking libtime-parsedate-perl (2015.103-4) ... 148s Selecting previously unselected package backuppc. 148s Preparing to unpack .../22-backuppc_4.4.0-8build1_arm64.deb ... 148s Unpacking backuppc (4.4.0-8build1) ... 148s Selecting previously unselected package samba-common. 148s Preparing to unpack .../23-samba-common_2%3a4.20.2+dfsg-2ubuntu2_all.deb ... 148s Unpacking samba-common (2:4.20.2+dfsg-2ubuntu2) ... 148s Selecting previously unselected package libavahi-common-data:arm64. 148s Preparing to unpack .../24-libavahi-common-data_0.8-13ubuntu6_arm64.deb ... 148s Unpacking libavahi-common-data:arm64 (0.8-13ubuntu6) ... 148s Selecting previously unselected package libavahi-common3:arm64. 148s Preparing to unpack .../25-libavahi-common3_0.8-13ubuntu6_arm64.deb ... 148s Unpacking libavahi-common3:arm64 (0.8-13ubuntu6) ... 148s Selecting previously unselected package libavahi-client3:arm64. 148s Preparing to unpack .../26-libavahi-client3_0.8-13ubuntu6_arm64.deb ... 148s Unpacking libavahi-client3:arm64 (0.8-13ubuntu6) ... 148s Selecting previously unselected package libcups2t64:arm64. 148s Preparing to unpack .../27-libcups2t64_2.4.7-1.2ubuntu9_arm64.deb ... 148s Unpacking libcups2t64:arm64 (2.4.7-1.2ubuntu9) ... 148s Selecting previously unselected package libtalloc2:arm64. 148s Preparing to unpack .../28-libtalloc2_2.4.2-1build2_arm64.deb ... 148s Unpacking libtalloc2:arm64 (2.4.2-1build2) ... 148s Selecting previously unselected package libtdb1:arm64. 148s Preparing to unpack .../29-libtdb1_1.4.10-1build1_arm64.deb ... 148s Unpacking libtdb1:arm64 (1.4.10-1build1) ... 148s Selecting previously unselected package libtevent0t64:arm64. 148s Preparing to unpack .../30-libtevent0t64_0.16.1-2build1_arm64.deb ... 148s Unpacking libtevent0t64:arm64 (0.16.1-2build1) ... 148s Selecting previously unselected package libldb2:arm64. 148s Preparing to unpack .../31-libldb2_2%3a2.9.1+samba4.20.2+dfsg-2ubuntu2_arm64.deb ... 148s Unpacking libldb2:arm64 (2:2.9.1+samba4.20.2+dfsg-2ubuntu2) ... 148s Selecting previously unselected package libwbclient0:arm64. 148s Preparing to unpack .../32-libwbclient0_2%3a4.20.2+dfsg-2ubuntu2_arm64.deb ... 148s Unpacking libwbclient0:arm64 (2:4.20.2+dfsg-2ubuntu2) ... 148s Selecting previously unselected package samba-libs:arm64. 148s Preparing to unpack .../33-samba-libs_2%3a4.20.2+dfsg-2ubuntu2_arm64.deb ... 148s Unpacking samba-libs:arm64 (2:4.20.2+dfsg-2ubuntu2) ... 149s Selecting previously unselected package samba-common-bin. 149s Preparing to unpack .../34-samba-common-bin_2%3a4.20.2+dfsg-2ubuntu2_arm64.deb ... 149s Unpacking samba-common-bin (2:4.20.2+dfsg-2ubuntu2) ... 149s Selecting previously unselected package samba. 149s Preparing to unpack .../35-samba_2%3a4.20.2+dfsg-2ubuntu2_arm64.deb ... 149s Unpacking samba (2:4.20.2+dfsg-2ubuntu2) ... 149s Selecting previously unselected package libsmbclient0:arm64. 149s Preparing to unpack .../36-libsmbclient0_2%3a4.20.2+dfsg-2ubuntu2_arm64.deb ... 149s Unpacking libsmbclient0:arm64 (2:4.20.2+dfsg-2ubuntu2) ... 149s Selecting previously unselected package smbclient. 149s Preparing to unpack .../37-smbclient_2%3a4.20.2+dfsg-2ubuntu2_arm64.deb ... 149s Unpacking smbclient (2:4.20.2+dfsg-2ubuntu2) ... 149s Selecting previously unselected package autopkgtest-satdep. 149s Preparing to unpack .../38-1-autopkgtest-satdep.deb ... 149s Unpacking autopkgtest-satdep (0) ... 149s Setting up libarchive-zip-perl (1.68-1) ... 149s Setting up libtdb1:arm64 (1.4.10-1build1) ... 149s Setting up libhtml-tagset-perl (3.24-1) ... 149s Setting up samba-common (2:4.20.2+dfsg-2ubuntu2) ... 149s 149s Creating config file /etc/samba/smb.conf with new version 149s Setting up libwbclient0:arm64 (2:4.20.2+dfsg-2ubuntu2) ... 149s Setting up libtalloc2:arm64 (2.4.2-1build2) ... 149s Setting up backuppc-rsync (3.1.3.0-3ubuntu1) ... 149s Setting up ssl-cert (1.1.2ubuntu2) ... 150s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 151s Setting up libavahi-common-data:arm64 (0.8-13ubuntu6) ... 151s Setting up libtimedate-perl (2.3300-2) ... 151s Setting up postfix (3.9.0-2) ... 152s info: Selecting GID from range 100 to 999 ... 152s info: Adding group `postfix' (GID 115) ... 152s info: Selecting UID from range 100 to 999 ... 152s 152s info: Adding system user `postfix' (UID 107) ... 152s info: Adding new user `postfix' (UID 107) with group `postfix' ... 152s info: Not creating home directory `/var/spool/postfix'. 152s Creating /etc/postfix/dynamicmaps.cf 152s info: Selecting GID from range 100 to 999 ... 152s info: Adding group `postdrop' (GID 116) ... 152s setting myhostname: autopkgtest 152s setting alias maps 152s setting alias database 152s mailname is not a fully qualified domain name. Not changing /etc/mailname. 152s setting destinations: $myhostname, autopkgtest, localhost.localdomain, , localhost 152s setting relayhost: 152s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 152s setting mailbox_size_limit: 0 152s setting recipient_delimiter: + 154s setting inet_interfaces: all 156s setting inet_protocols: all 156s /etc/aliases does not exist, creating it. 156s WARNING: /etc/aliases exists, but does not have a root alias. 156s 156s Postfix (main.cf) is now set up with a default configuration. If you need to 156s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 156s Postfix configuration values, see postconf(1). 156s 156s After modifying main.cf, be sure to run 'systemctl reload postfix'. 156s 158s Running newaliases 162s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 167s Setting up libtime-parsedate-perl (2015.103-4) ... 167s Setting up libapr1t64:arm64 (1.7.2-3.2) ... 167s Setting up liblua5.4-0:arm64 (5.4.6-3build2) ... 167s Setting up apache2-data (2.4.59-2ubuntu2) ... 167s Setting up libbackuppc-xs-perl:arm64 (0.62-2build5) ... 167s Setting up liburi-perl (5.28-1) ... 167s Setting up libtevent0t64:arm64 (0.16.1-2build1) ... 167s Setting up libaprutil1t64:arm64 (1.6.3-2ubuntu1) ... 167s Setting up libhttp-date-perl (6.06-1) ... 167s Setting up libfile-listing-perl (6.16-1) ... 167s Setting up libavahi-common3:arm64 (0.8-13ubuntu6) ... 167s Setting up libaprutil1-ldap:arm64 (1.6.3-2ubuntu1) ... 167s Setting up libaprutil1-dbd-sqlite3:arm64 (1.6.3-2ubuntu1) ... 167s Setting up libhtml-parser-perl:arm64 (3.82-1) ... 167s Setting up libldb2:arm64 (2:2.9.1+samba4.20.2+dfsg-2ubuntu2) ... 167s Setting up libavahi-client3:arm64 (0.8-13ubuntu6) ... 167s Setting up apache2-utils (2.4.59-2ubuntu2) ... 167s Setting up libcgi-pm-perl (4.66-1) ... 167s Setting up samba-libs:arm64 (2:4.20.2+dfsg-2ubuntu2) ... 167s Setting up apache2-bin (2.4.59-2ubuntu2) ... 167s Setting up libsmbclient0:arm64 (2:4.20.2+dfsg-2ubuntu2) ... 167s Setting up libcups2t64:arm64 (2.4.7-1.2ubuntu9) ... 167s Setting up apache2 (2.4.59-2ubuntu2) ... 167s Enabling module mpm_event. 167s Enabling module authz_core. 167s Enabling module authz_host. 167s Enabling module authn_core. 167s Enabling module auth_basic. 167s Enabling module access_compat. 167s Enabling module authn_file. 168s Enabling module authz_user. 168s Enabling module alias. 168s Enabling module dir. 168s Enabling module autoindex. 168s Enabling module env. 168s Enabling module mime. 168s Enabling module negotiation. 168s Enabling module setenvif. 168s Enabling module filter. 168s Enabling module deflate. 168s Enabling module status. 168s Enabling module reqtimeout. 168s Enabling conf charset. 168s Enabling conf localized-error-pages. 168s Enabling conf other-vhosts-access-log. 168s Enabling conf security. 168s Enabling conf serve-cgi-bin. 168s Enabling site 000-default. 169s Created symlink '/etc/systemd/system/multi-user.target.wants/apache2.service' → '/usr/lib/systemd/system/apache2.service'. 171s Created symlink '/etc/systemd/system/multi-user.target.wants/apache-htcacheclean.service' → '/usr/lib/systemd/system/apache-htcacheclean.service'. 174s Setting up backuppc (4.4.0-8build1) ... 174s 175s apache2_invoke: Enable configuration backuppc.conf 176s warn: The home directory `/var/lib/backuppc' already exists. Not touching this directory. 176s warn: Warning: The home directory `/var/lib/backuppc' does not belong to the user you are currently creating. 176s Adding password for user backuppc 176s Considering dependency authn_core for auth_basic: 176s Module authn_core already enabled 176s Module auth_basic already enabled 176s Considering dependency authz_core for authz_groupfile: 176s Module authz_core already enabled 176s Enabling module authz_groupfile. 176s To activate the new configuration, you need to run: 176s systemctl restart apache2 176s Module authn_file already enabled 176s Considering dependency authz_core for authz_user: 176s Module authz_core already enabled 176s Module authz_user already enabled 177s Your MPM seems to be threaded. Selecting cgid instead of cgi. 177s Enabling module cgid. 177s To activate the new configuration, you need to run: 177s systemctl restart apache2 177s 177s Creating config file /etc/backuppc/config.pl with new version 177s Created symlink '/etc/systemd/system/multi-user.target.wants/backuppc.service' → '/usr/lib/systemd/system/backuppc.service'. 178s Setting up smbclient (2:4.20.2+dfsg-2ubuntu2) ... 178s Setting up samba-common-bin (2:4.20.2+dfsg-2ubuntu2) ... 178s Setting up samba (2:4.20.2+dfsg-2ubuntu2) ... 180s Created symlink '/etc/systemd/system/smb.service' → '/usr/lib/systemd/system/smbd.service'. 180s Created symlink '/etc/systemd/system/multi-user.target.wants/smbd.service' → '/usr/lib/systemd/system/smbd.service'. 181s Created symlink '/etc/systemd/system/nmb.service' → '/usr/lib/systemd/system/nmbd.service'. 181s Created symlink '/etc/systemd/system/multi-user.target.wants/nmbd.service' → '/usr/lib/systemd/system/nmbd.service'. 181s Setting up autopkgtest-satdep (0) ... 181s Processing triggers for rsyslog (8.2312.0-3ubuntu9) ... 181s Processing triggers for ufw (0.36.2-6) ... 182s Processing triggers for man-db (2.12.1-2) ... 182s Processing triggers for libc-bin (2.39-3.1ubuntu3) ... 187s (Reading database ... 80230 files and directories currently installed.) 187s Removing autopkgtest-satdep (0) ... 188s autopkgtest [13:49:10]: test smb-backup: [----------------------- 189s Populating share path 189s Adding samba share 189s Adding [public] share 189s Configuring backuppc 189s Performing a full backup 189s Backup type: type = full, needs_full = 1, needs_incr = 1, lastFullTime = 0, opts{f} = 1, opts{i} = , opts{F} = 189s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 189s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 189s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.021 189s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.0.z created 2024-07-12 13:49:11 189s Backup prep: type = full, case = 1, inPlace = 1, doDuplicate = 0, newBkupNum = 0, newBkupIdx = 0, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 189s __bpc_progress_state__ backup share "public" 189s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 189s full backup started for share public 189s started full dump, share=public 189s Xfer PIDs are now 5594,5593 189s xferPids 5594,5593 189s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 189s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 189s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 189s tarmode is now full, system, hidden, noreset, noverbose 189s __bpc_progress_fileCnt__ 0 189s new 644 0/0 480434 tmp.t9WELm 190s new 644 0/0 480434 tmp.9YWRnw 190s new 644 0/0 480434 tmp.5McrIQ 190s new 644 0/0 480434 tmp.RGv59k 190s new 644 0/0 480434 tmp.zMqTsr 190s new 644 0/0 480434 tmp.vePDih 190s new 644 0/0 480434 tmp.qvGvln 190s new 644 0/0 480434 tmp.T0TDPC 190s new 644 0/0 480434 tmp.A0NXM7 190s tar: dumped 10 files and 0 directories 190s Total bytes written: 4804340 (18.3 MiB/s) 190s new 644 0/0 480434 tmp.4ZgX1x 190s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 190s __bpc_progress_fileCnt__ 10 190s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377750 sizeNewComp, 13 inodeLast 190s Xfer PIDs are now 190s xferPids 190s full backup 0 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 190s BackupExpire: cntFull = 1, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 1.15740740740741e-05 190s __bpc_progress_state__ fsck 190s Running BackupPC_refCountUpdate -h localhost on localhost 190s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 190s Xfer PIDs are now 5600 190s xferPids 5600 190s BackupPC_refCountUpdate: doing fsck on localhost #0 (full) since $ConfRefCntFsck == 1 190s __bpc_progress_state__ refCnt #0 190s __bpc_progress_state__ cntUpdate #0 190s __bpc_progress_state__ rename #0 190s __bpc_progress_state__ sumUpdate 190s __bpc_progress_fileCnt__ 0/128 190s __bpc_progress_fileCnt__ 8/128 190s __bpc_progress_fileCnt__ 16/128 190s __bpc_progress_fileCnt__ 24/128 190s __bpc_progress_fileCnt__ 32/128 190s __bpc_progress_fileCnt__ 40/128 190s __bpc_progress_fileCnt__ 48/128 190s __bpc_progress_fileCnt__ 56/128 190s __bpc_progress_fileCnt__ 64/128 190s __bpc_progress_fileCnt__ 72/128 190s __bpc_progress_fileCnt__ 80/128 190s __bpc_progress_fileCnt__ 88/128 190s __bpc_progress_fileCnt__ 96/128 190s __bpc_progress_fileCnt__ 104/128 190s __bpc_progress_fileCnt__ 112/128 190s __bpc_progress_fileCnt__ 120/128 190s __bpc_progress_state__ rename total 190s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 190s Xfer PIDs are now 190s xferPids 190s Finished BackupPC_refCountUpdate (running time: 0 sec) 190s Xfer PIDs are now 190s xferPids 190s full backup complete 190s OK: Full backup succeeded 190s Changing share content and performing an incremental backup 190s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1720792151, opts{f} = , opts{i} = 1, opts{F} = 190s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 190s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 190s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.023 190s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.1.z created 2024-07-12 13:49:12 190s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 1, newBkupIdx = 1, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 190s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 190s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 190s Old package separator used in string at /usr/share/backuppc/bin/BackupPC_backupDuplicate line 89. 190s (Did you mean "$Host\'s" instead?) 190s Xfer PIDs are now 5670 190s xferPids 5670 190s __bpc_progress_state__ copy #0 -> #1 190s Copying backup #0 to #1 190s __bpc_progress_fileCnt__ 3 dirs 190s Xfer PIDs are now 5673,5670 190s xferPids 5673,5670 190s __bpc_progress_state__ sumUpdate 190s __bpc_progress_fileCnt__ 0/128 190s __bpc_progress_fileCnt__ 8/128 191s __bpc_progress_fileCnt__ 16/128 191s __bpc_progress_fileCnt__ 24/128 191s __bpc_progress_fileCnt__ 32/128 191s __bpc_progress_fileCnt__ 40/128 191s __bpc_progress_fileCnt__ 48/128 191s __bpc_progress_fileCnt__ 56/128 191s __bpc_progress_fileCnt__ 64/128 191s __bpc_progress_fileCnt__ 72/128 191s __bpc_progress_fileCnt__ 80/128 191s __bpc_progress_fileCnt__ 88/128 191s __bpc_progress_fileCnt__ 96/128 191s __bpc_progress_fileCnt__ 104/128 191s __bpc_progress_fileCnt__ 112/128 191s __bpc_progress_fileCnt__ 120/128 191s __bpc_progress_state__ rename total 191s BackupPC_refCountUpdate: host localhost got 0 errors (took 1 secs) 191s Xfer PIDs are now 5670 191s xferPids 5670 191s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 191s Finished BackupPC_backupDuplicate (running time: 1 sec) 191s __bpc_progress_state__ backup share "public" 191s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 191s incr backup started back to 2024-07-12 12:49:11 (backup #0) for share public 191s started incr dump, share=public 191s Xfer PIDs are now 5677,5676 191s xferPids 5677,5676 191s 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 - 191s tar:1298 Getting files newer than Fri Jul 12 12:49:11 2024 191s tarmode is now full, system, hidden, noreset, noverbose 191s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 191s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 191s __bpc_progress_fileCnt__ 0 191s new 644 0/0 480434 tmp.lfDTb5 191s same 644 0/0 480434 tmp.t9WELm 191s same 644 0/0 480434 tmp.9YWRnw 191s same 644 0/0 480434 tmp.5McrIQ 191s new 644 0/0 480434 tmp.9sOJWE 191s new 644 0/0 480434 tmp.cXj5Tp 191s same 644 0/0 480434 tmp.RGv59k 191s new 644 0/0 480434 tmp.07zHPU 191s new 644 0/0 480434 tmp.0Frnaz 191s new 644 0/0 480434 tmp.Nvod24 191s same 644 0/0 480434 tmp.zMqTsr 191s new 644 0/0 480434 tmp.gFOEYj 191s new 644 0/0 480434 tmp.RI3Hwh 191s same 644 0/0 480434 tmp.vePDih 191s same 644 0/0 480434 tmp.qvGvln 191s new 644 0/0 480434 tmp.YDPy9D 191s new 644 0/0 480434 tmp.WUDd0l 191s same 644 0/0 480434 tmp.T0TDPC 191s same 644 0/0 480434 tmp.A0NXM7 191s tar: dumped 20 files and 0 directories 191s Total bytes written: 9608680 (45.0 MiB/s) 191s same 644 0/0 480434 tmp.4ZgX1x 191s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 191s __bpc_progress_fileCnt__ 20 191s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377750 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377788 sizeNewComp, 24 inodeLast 191s Xfer PIDs are now 191s xferPids 191s incr backup 1 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 191s BackupExpire: cntFull = 2, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 2.31481481481481e-05 191s __bpc_progress_state__ fsck 191s Running BackupPC_refCountUpdate -h localhost on localhost 191s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 191s Xfer PIDs are now 5683 191s xferPids 5683 191s __bpc_progress_state__ cntUpdate #1 191s __bpc_progress_state__ rename #1 191s __bpc_progress_state__ sumUpdate 191s __bpc_progress_fileCnt__ 0/128 191s __bpc_progress_fileCnt__ 8/128 191s __bpc_progress_fileCnt__ 16/128 191s __bpc_progress_fileCnt__ 24/128 191s __bpc_progress_fileCnt__ 32/128 191s __bpc_progress_fileCnt__ 40/128 191s __bpc_progress_fileCnt__ 48/128 191s __bpc_progress_fileCnt__ 56/128 191s __bpc_progress_fileCnt__ 64/128 191s __bpc_progress_fileCnt__ 72/128 191s __bpc_progress_fileCnt__ 80/128 191s __bpc_progress_fileCnt__ 88/128 191s __bpc_progress_fileCnt__ 96/128 191s __bpc_progress_fileCnt__ 104/128 191s __bpc_progress_fileCnt__ 112/128 191s __bpc_progress_fileCnt__ 120/128 191s __bpc_progress_state__ rename total 191s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 191s Xfer PIDs are now 191s xferPids 191s Finished BackupPC_refCountUpdate (running time: 0 sec) 191s Xfer PIDs are now 191s xferPids 191s incr backup complete 191s OK: Incremental backup succeeded 191s Done. 191s autopkgtest [13:49:13]: test smb-backup: -----------------------] 192s autopkgtest [13:49:14]: test smb-backup: - - - - - - - - - - results - - - - - - - - - - 192s smb-backup PASS 193s autopkgtest [13:49:15]: test authenticated-smb-backup: preparing testbed 195s Reading package lists... 195s Building dependency tree... 195s Reading state information... 195s Starting pkgProblemResolver with broken count: 0 195s Starting 2 pkgProblemResolver with broken count: 0 195s Done 196s The following NEW packages will be installed: 196s autopkgtest-satdep 196s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 196s Need to get 0 B/724 B of archives. 196s After this operation, 0 B of additional disk space will be used. 196s Get:1 /tmp/autopkgtest.LU9X3M/2-autopkgtest-satdep.deb autopkgtest-satdep arm64 0 [724 B] 198s Selecting previously unselected package autopkgtest-satdep. 198s (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 ... 80231 files and directories currently installed.) 198s Preparing to unpack .../2-autopkgtest-satdep.deb ... 198s Unpacking autopkgtest-satdep (0) ... 198s Setting up autopkgtest-satdep (0) ... 202s (Reading database ... 80230 files and directories currently installed.) 202s Removing autopkgtest-satdep (0) ... 203s autopkgtest [13:49:25]: test authenticated-smb-backup: [----------------------- 203s Populating share path 203s Adding samba share 203s Adding [private] share 203s Creating user 203s Creating a local and samba user called smbtest5935 203s Setting samba password for the smbtest5935 user 204s Added user smbtest5935. 204s Configuring backuppc 204s Performing a full backup 204s Backup type: type = full, needs_full = , needs_incr = , lastFullTime = 1720792151, opts{f} = 1, opts{i} = , opts{F} = 204s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 204s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 204s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.021 204s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.2.z created 2024-07-12 13:49:26 204s Backup prep: type = full, case = 4, inPlace = 0, doDuplicate = 0, newBkupNum = 2, newBkupIdx = 2, lastBkupNum = 1, lastBkupIdx = 1 (FillCycle = 0, noFillCnt = 0) 204s __bpc_progress_state__ backup share "private" 204s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest5935 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 204s full backup started for share private 204s started full dump, share=private 204s Xfer PIDs are now 6029,6028 204s xferPids 6029,6028 204s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest5935 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 204s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 204s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 204s tarmode is now full, system, hidden, noreset, noverbose 204s __bpc_progress_fileCnt__ 0 204s new 644 0/0 480434 tmp.Z3TTUm 204s new 644 0/0 480434 tmp.ESqDMk 204s new 644 0/0 480434 tmp.4TnDrG 204s new 644 0/0 480434 tmp.HSOKJ5 204s new 644 0/0 480434 tmp.HmNNul 204s new 644 0/0 480434 tmp.qkm8ha 204s new 644 0/0 480434 tmp.8L62oW 204s new 644 0/0 480434 tmp.WM6aJ0 204s new 644 0/0 480434 tmp.9prZ0V 204s tar: dumped 10 files and 0 directories 204s Total bytes written: 4804340 (36.0 MiB/s) 204s new 644 0/0 480434 tmp.h9suTr 204s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 204s __bpc_progress_fileCnt__ 10 204s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377767 sizeNewComp, 36 inodeLast 204s Xfer PIDs are now 204s xferPids 204s OrphanShareNameClean: removing orphan share public from /var/lib/backuppc/pc/localhost/2 204s __bpc_progress_state__ delete share #2/public 204s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDelete -h localhost -n 2 -l -m -s public / 204s Old package separator used in string at /usr/share/backuppc/bin/BackupPC_backupDelete line 102. 204s (Did you mean "$Host\'s" instead?) 204s Xfer PIDs are now 6035 204s xferPids 6035 204s BackupPC_backupDelete: removing #2/public/ 204s __bpc_progress_state__ merge #2/public/ -> #1/public/ 204s BackupPC_backupDelete: Merge into backup 1/public/ 204s mergeDir: attr write to /var/lib/backuppc/pc/localhost/1/fpublic//attrib failed (digest was ) 204s bpc_attrib_dirWrite: can't open/create raw /var/lib/backuppc/pc/localhost/1/fpublic for writing 204s Xfer PIDs are now 6035,6038 204s xferPids 6035,6038 204s __bpc_progress_state__ refCnt #1 204s __bpc_progress_state__ cntUpdate #1 204s __bpc_progress_state__ rename #1 204s __bpc_progress_state__ refCnt #2 205s BackupPC_refCountUpdate: localhost #2 inodeLast set to 37 (was 24) 205s __bpc_progress_state__ cntUpdate #2 205s __bpc_progress_state__ rename #2 205s __bpc_progress_state__ sumUpdate 205s __bpc_progress_fileCnt__ 0/128 205s __bpc_progress_fileCnt__ 8/128 205s __bpc_progress_fileCnt__ 16/128 205s __bpc_progress_fileCnt__ 24/128 205s __bpc_progress_fileCnt__ 32/128 205s __bpc_progress_fileCnt__ 40/128 205s __bpc_progress_fileCnt__ 48/128 205s __bpc_progress_fileCnt__ 56/128 205s __bpc_progress_fileCnt__ 64/128 205s __bpc_progress_fileCnt__ 72/128 205s __bpc_progress_fileCnt__ 80/128 205s __bpc_progress_fileCnt__ 88/128 205s __bpc_progress_fileCnt__ 96/128 205s __bpc_progress_fileCnt__ 104/128 205s __bpc_progress_fileCnt__ 112/128 205s __bpc_progress_fileCnt__ 120/128 205s __bpc_progress_state__ rename total 205s BackupPC_refCountUpdate: host localhost got 0 errors (took 1 secs) 205s Xfer PIDs are now 6035 205s xferPids 6035 205s Xfer PIDs are now 205s xferPids 205s Finished BackupPC_backupDelete, status = 0 (running time: 1 sec) 205s Xfer PIDs are now 205s xferPids 205s full backup 2 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 205s BackupExpire: cntFull = 2, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 0.000162037037037037, oldestFull = 0.000185185185185185 205s __bpc_progress_state__ fsck 205s Running BackupPC_refCountUpdate -h localhost on localhost 205s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 205s Xfer PIDs are now 6041 205s xferPids 6041 205s BackupPC_refCountUpdate: doing fsck on localhost #2 (full) since $ConfRefCntFsck == 1 205s __bpc_progress_state__ refCnt #2 205s __bpc_progress_state__ cntUpdate #2 205s __bpc_progress_state__ rename #2 205s __bpc_progress_state__ sumUpdate 205s __bpc_progress_fileCnt__ 0/128 205s __bpc_progress_fileCnt__ 8/128 205s __bpc_progress_fileCnt__ 16/128 205s __bpc_progress_fileCnt__ 24/128 205s __bpc_progress_fileCnt__ 32/128 205s __bpc_progress_fileCnt__ 40/128 205s __bpc_progress_fileCnt__ 48/128 205s __bpc_progress_fileCnt__ 56/128 205s __bpc_progress_fileCnt__ 64/128 205s __bpc_progress_fileCnt__ 72/128 205s __bpc_progress_fileCnt__ 80/128 205s __bpc_progress_fileCnt__ 88/128 205s __bpc_progress_fileCnt__ 96/128 205s __bpc_progress_fileCnt__ 104/128 205s __bpc_progress_fileCnt__ 112/128 205s __bpc_progress_fileCnt__ 120/128 205s __bpc_progress_state__ rename total 205s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 205s Xfer PIDs are now 205s xferPids 205s Finished BackupPC_refCountUpdate (running time: 0 sec) 205s Xfer PIDs are now 205s xferPids 205s full backup complete 205s OK: Full backup succeeded 205s Changing share content and performing an incremental backup 207s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1720792166, opts{f} = , opts{i} = 1, opts{F} = 207s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 207s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 207s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.021 207s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.3.z created 2024-07-12 13:49:29 207s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 3, newBkupIdx = 3, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = 1) 207s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 207s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 207s Old package separator used in string at /usr/share/backuppc/bin/BackupPC_backupDuplicate line 89. 207s (Did you mean "$Host\'s" instead?) 207s Xfer PIDs are now 6111 207s xferPids 6111 207s __bpc_progress_state__ copy #2 -> #3 207s Copying backup #2 to #3 207s __bpc_progress_fileCnt__ 3 dirs 207s Xfer PIDs are now 6114,6111 207s xferPids 6114,6111 207s __bpc_progress_state__ sumUpdate 207s __bpc_progress_fileCnt__ 0/128 207s __bpc_progress_fileCnt__ 8/128 207s __bpc_progress_fileCnt__ 16/128 207s __bpc_progress_fileCnt__ 24/128 207s __bpc_progress_fileCnt__ 32/128 207s __bpc_progress_fileCnt__ 40/128 207s __bpc_progress_fileCnt__ 48/128 207s __bpc_progress_fileCnt__ 56/128 207s __bpc_progress_fileCnt__ 64/128 207s __bpc_progress_fileCnt__ 72/128 207s __bpc_progress_fileCnt__ 80/128 207s __bpc_progress_fileCnt__ 88/128 207s __bpc_progress_fileCnt__ 96/128 207s __bpc_progress_fileCnt__ 104/128 207s __bpc_progress_fileCnt__ 112/128 207s __bpc_progress_fileCnt__ 120/128 207s __bpc_progress_state__ rename total 207s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 207s Xfer PIDs are now 6111 207s xferPids 6111 207s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 207s Finished BackupPC_backupDuplicate (running time: 0 sec) 207s __bpc_progress_state__ backup share "private" 207s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest5935 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 207s incr backup started back to 2024-07-12 12:49:26 (backup #2) for share private 207s started incr dump, share=private 207s Xfer PIDs are now 6118,6117 207s xferPids 6118,6117 207s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest5935 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 207s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 207s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 208s tar:1298 Getting files newer than Fri Jul 12 12:49:26 2024 208s tarmode is now full, system, hidden, noreset, noverbose 208s __bpc_progress_fileCnt__ 0 208s new 644 0/0 480434 tmp.F9KYxq 208s same 644 0/0 480434 tmp.Z3TTUm 208s same 644 0/0 480434 tmp.ESqDMk 208s new 644 0/0 480434 tmp.eHnLNa 208s same 644 0/0 480434 tmp.4TnDrG 208s same 644 0/0 480434 tmp.HSOKJ5 208s same 644 0/0 480434 tmp.HmNNul 208s new 644 0/0 480434 tmp.0bJcKM 208s same 644 0/0 480434 tmp.qkm8ha 208s new 644 0/0 480434 tmp.ADxFpy 208s same 644 0/0 480434 tmp.8L62oW 208s new 644 0/0 480434 tmp.F28e9o 208s new 644 0/0 480434 tmp.4VMLNc 208s same 644 0/0 480434 tmp.WM6aJ0 208s same 644 0/0 480434 tmp.9prZ0V 208s same 644 0/0 480434 tmp.h9suTr 208s new 644 0/0 480434 tmp.PrljPZ 208s new 644 0/0 480434 tmp.mao8Vr 208s new 644 0/0 480434 tmp.nftlf9 208s tar: dumped 20 files and 0 directories 208s Total bytes written: 9608680 (19.8 MiB/s) 208s new 644 0/0 480434 tmp.thD6xO 208s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 209s __bpc_progress_fileCnt__ 20 209s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377767 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377791 sizeNewComp, 47 inodeLast 209s Xfer PIDs are now 209s xferPids 209s incr backup 3 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 209s BackupExpire: cntFull = 3, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 0.000208333333333333, oldestFull = 0.000231481481481481 209s __bpc_progress_state__ fsck 209s Running BackupPC_refCountUpdate -h localhost on localhost 209s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 209s Xfer PIDs are now 6124 209s xferPids 6124 209s __bpc_progress_state__ cntUpdate #3 209s __bpc_progress_state__ rename #3 209s __bpc_progress_state__ sumUpdate 209s __bpc_progress_fileCnt__ 0/128 209s __bpc_progress_fileCnt__ 8/128 209s __bpc_progress_fileCnt__ 16/128 209s __bpc_progress_fileCnt__ 24/128 209s __bpc_progress_fileCnt__ 32/128 209s __bpc_progress_fileCnt__ 40/128 209s __bpc_progress_fileCnt__ 48/128 209s __bpc_progress_fileCnt__ 56/128 209s __bpc_progress_fileCnt__ 64/128 209s __bpc_progress_fileCnt__ 72/128 209s __bpc_progress_fileCnt__ 80/128 209s __bpc_progress_fileCnt__ 88/128 209s __bpc_progress_fileCnt__ 96/128 209s __bpc_progress_fileCnt__ 104/128 209s __bpc_progress_fileCnt__ 112/128 209s __bpc_progress_fileCnt__ 120/128 209s __bpc_progress_state__ rename total 209s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 209s Xfer PIDs are now 209s xferPids 209s Finished BackupPC_refCountUpdate (running time: 0 sec) 209s Xfer PIDs are now 209s xferPids 209s incr backup complete 209s OK: Incremental backup succeeded 209s Done. 209s Deleted user smbtest5935. 209s userdel: smbtest5935 mail spool (/var/mail/smbtest5935) not found 210s autopkgtest [13:49:32]: test authenticated-smb-backup: -----------------------] 210s autopkgtest [13:49:32]: test authenticated-smb-backup: - - - - - - - - - - results - - - - - - - - - - 210s authenticated-smb-backup PASS 210s autopkgtest [13:49:32]: @@@@@@@@@@@@@@@@@@@@ summary 210s smb-backup PASS 210s authenticated-smb-backup PASS 231s nova [W] Skipping flock in bos03-arm64 231s Creating nova instance adt-oracular-arm64-backuppc-20240712-134601-juju-7f2275-prod-proposed-migration-environment-2-c3072ba8-3321-4ce7-8928-0d28e2b76923 from image adt/ubuntu-oracular-arm64-server-20240712.img (UUID a9c53646-2a41-49d7-a14b-d40262a2cb6c)...