0s autopkgtest [14:13:15]: starting date and time: 2024-07-12 14:13:15+0000 0s autopkgtest [14:13:15]: git checkout: fd3bed09 nova: allow more retries for quota issues 0s autopkgtest [14:13:15]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.dld7vbw_/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_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\n" >> /etc/environment' --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 -- lxd -r lxd-armhf-10.145.243.242 lxd-armhf-10.145.243.242:autopkgtest/ubuntu/oracular/armhf 62s autopkgtest [14:14:17]: testbed dpkg architecture: armhf 64s autopkgtest [14:14:19]: testbed apt version: 2.9.6 64s autopkgtest [14:14:19]: @@@@@@@@@@@@@@@@@@@@ test bed setup 73s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 73s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 73s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [2576 B] 73s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [45.3 kB] 73s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [345 kB] 73s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf Packages [60.5 kB] 73s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf c-n-f Metadata [1444 B] 73s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted armhf Packages [1368 B] 73s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted armhf c-n-f Metadata [120 B] 73s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe armhf Packages [294 kB] 73s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe armhf c-n-f Metadata [6176 B] 73s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse armhf Packages [1448 B] 73s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse armhf c-n-f Metadata [120 B] 76s Fetched 893 kB in 1s (887 kB/s) 76s Reading package lists... 97s tee: /proc/self/fd/2: Permission denied 127s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 127s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 127s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 127s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 129s Reading package lists... 129s Reading package lists... 129s Building dependency tree... 129s Reading state information... 129s Calculating upgrade... 130s The following packages will be upgraded: 130s libc-bin libc6 libssl3t64 locales openssl 131s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 131s Need to get 10.4 MB of archives. 131s After this operation, 275 kB of additional disk space will be used. 131s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf libc6 armhf 2.39-3.1ubuntu3 [2825 kB] 132s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf libc-bin armhf 2.39-3.1ubuntu3 [527 kB] 132s Get:3 http://ftpmaster.internal/ubuntu oracular/main armhf libssl3t64 armhf 3.2.2-1ubuntu1 [1729 kB] 132s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/main armhf locales all 2.39-3.1ubuntu3 [4220 kB] 132s Get:5 http://ftpmaster.internal/ubuntu oracular/main armhf openssl armhf 3.2.2-1ubuntu1 [1095 kB] 132s Preconfiguring packages ... 133s Fetched 10.4 MB in 2s (5668 kB/s) 134s (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 ... 58437 files and directories currently installed.) 134s Preparing to unpack .../libc6_2.39-3.1ubuntu3_armhf.deb ... 136s Unpacking libc6:armhf (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 136s Setting up libc6:armhf (2.39-3.1ubuntu3) ... 137s Error: Could not restart systemd, systemd binary not working 137s (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 ... 58437 files and directories currently installed.) 137s Preparing to unpack .../libc-bin_2.39-3.1ubuntu3_armhf.deb ... 137s Unpacking libc-bin (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 137s Setting up libc-bin (2.39-3.1ubuntu3) ... 137s (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 ... 58437 files and directories currently installed.) 139s Preparing to unpack .../libssl3t64_3.2.2-1ubuntu1_armhf.deb ... 139s Unpacking libssl3t64:armhf (3.2.2-1ubuntu1) over (3.2.1-3ubuntu1) ... 139s Setting up libssl3t64:armhf (3.2.2-1ubuntu1) ... 139s (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 ... 58437 files and directories currently installed.) 139s Preparing to unpack .../locales_2.39-3.1ubuntu3_all.deb ... 139s Unpacking locales (2.39-3.1ubuntu3) over (2.39-0ubuntu9) ... 139s Preparing to unpack .../openssl_3.2.2-1ubuntu1_armhf.deb ... 139s Unpacking openssl (3.2.2-1ubuntu1) over (3.2.1-3ubuntu1) ... 139s Setting up locales (2.39-3.1ubuntu3) ... 140s Generating locales (this might take a while)... 144s en_US.UTF-8... done 144s Generation complete. 144s Setting up openssl (3.2.2-1ubuntu1) ... 144s Installing new version of config file /etc/ssl/openssl.cnf ... 144s Processing triggers for systemd (256-1ubuntu1) ... 145s Processing triggers for man-db (2.12.1-2) ... 146s Processing triggers for libc-bin (2.39-3.1ubuntu3) ... 146s Reading package lists... 147s Building dependency tree... 147s Reading state information... 147s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 149s autopkgtest [14:15:44]: rebooting testbed after setup commands that affected boot 269s autopkgtest [14:17:44]: testbed running kernel: Linux 6.5.0-41-generic #41~22.04.2-Ubuntu SMP PREEMPT_DYNAMIC Mon Jun 3 16:28:24 UTC 2 308s autopkgtest [14:18:23]: @@@@@@@@@@@@@@@@@@@@ apt-source backuppc 319s Get:1 http://ftpmaster.internal/ubuntu oracular/main backuppc 4.4.0-8build1 (dsc) [2289 B] 319s Get:2 http://ftpmaster.internal/ubuntu oracular/main backuppc 4.4.0-8build1 (tar) [657 kB] 319s Get:3 http://ftpmaster.internal/ubuntu oracular/main backuppc 4.4.0-8build1 (diff) [61.1 kB] 320s gpgv: Signature made Mon Apr 8 15:54:41 2024 UTC 320s gpgv: using RSA key 4FB588A84C2DDE79A74C77876FA458DD1DB03F71 320s gpgv: issuer "juliank@ubuntu.com" 320s gpgv: Can't check signature: No public key 320s dpkg-source: warning: cannot verify inline signature for ./backuppc_4.4.0-8build1.dsc: no acceptable signature found 321s autopkgtest [14:18:36]: testing package backuppc version 4.4.0-8build1 323s autopkgtest [14:18:38]: build not needed 326s autopkgtest [14:18:41]: test smb-backup: preparing testbed 346s Reading package lists... 349s Building dependency tree... 349s Reading state information... 351s Starting pkgProblemResolver with broken count: 0 351s Starting 2 pkgProblemResolver with broken count: 0 351s Done 353s The following additional packages will be installed: 353s apache2 apache2-bin apache2-data apache2-utils backuppc backuppc-rsync 353s libapr1t64 libaprutil1-dbd-sqlite3 libaprutil1-ldap libaprutil1t64 353s libarchive-zip-perl libavahi-client3 libavahi-common-data libavahi-common3 353s libbackuppc-xs-perl libcgi-pm-perl libcups2t64 libfile-listing-perl 353s libhtml-parser-perl libhtml-tagset-perl libhttp-date-perl libldb2 353s liblua5.4-0 libnsl2 libsmbclient0 libtalloc2 libtdb1 libtevent0t64 353s libtime-parsedate-perl libtimedate-perl liburi-perl libwbclient0 postfix 353s samba samba-common samba-common-bin samba-libs smbclient ssl-cert 353s Suggested packages: 353s apache2-doc apache2-suexec-pristine | apache2-suexec-custom www-browser 353s certbot | acme-tiny | acmetool | dehydrated | lacme | lecm | lego 353s libscgi-perl par2 w3m | www-browser cups-common libdata-dump-perl 353s libbusiness-isbn-perl libregexp-ipv6-perl libwww-perl mail-reader 353s postfix-cdb postfix-doc postfix-ldap postfix-lmdb postfix-mta-sts-resolver 353s postfix-mongodb postfix-mysql postfix-pcre postfix-pgsql postfix-sqlite 353s procmail sasl2-bin | dovecot-common ctdb winbind heimdal-clients cifs-utils 353s Recommended packages: 353s libio-dirent-perl rrdtool libcgi-fast-perl libhttp-message-perl attr 353s samba-vfs-modules python3-samba 355s The following NEW packages will be installed: 355s apache2 apache2-bin apache2-data apache2-utils autopkgtest-satdep backuppc 355s backuppc-rsync libapr1t64 libaprutil1-dbd-sqlite3 libaprutil1-ldap 355s libaprutil1t64 libarchive-zip-perl libavahi-client3 libavahi-common-data 355s libavahi-common3 libbackuppc-xs-perl libcgi-pm-perl libcups2t64 355s libfile-listing-perl libhtml-parser-perl libhtml-tagset-perl 355s libhttp-date-perl libldb2 liblua5.4-0 libnsl2 libsmbclient0 libtalloc2 355s libtdb1 libtevent0t64 libtime-parsedate-perl libtimedate-perl liburi-perl 355s libwbclient0 postfix samba samba-common samba-common-bin samba-libs 355s smbclient ssl-cert 357s 0 upgraded, 40 newly installed, 0 to remove and 0 not upgraded. 357s Need to get 13.8 MB/13.8 MB of archives. 357s After this operation, 42.3 MB of additional disk space will be used. 357s Get:1 /tmp/autopkgtest.yqzwRw/1-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [720 B] 360s Get:2 http://ftpmaster.internal/ubuntu oracular/main armhf libapr1t64 armhf 1.7.2-3.2 [86.0 kB] 360s Get:3 http://ftpmaster.internal/ubuntu oracular/main armhf libaprutil1t64 armhf 1.6.3-2ubuntu1 [82.5 kB] 360s Get:4 http://ftpmaster.internal/ubuntu oracular/main armhf libaprutil1-dbd-sqlite3 armhf 1.6.3-2ubuntu1 [9584 B] 360s Get:5 http://ftpmaster.internal/ubuntu oracular/main armhf libaprutil1-ldap armhf 1.6.3-2ubuntu1 [8490 B] 360s Get:6 http://ftpmaster.internal/ubuntu oracular/main armhf liblua5.4-0 armhf 5.4.6-3build2 [133 kB] 360s Get:7 http://ftpmaster.internal/ubuntu oracular/main armhf apache2-bin armhf 2.4.59-2ubuntu2 [1206 kB] 361s Get:8 http://ftpmaster.internal/ubuntu oracular/main armhf apache2-data all 2.4.59-2ubuntu2 [163 kB] 361s Get:9 http://ftpmaster.internal/ubuntu oracular/main armhf apache2-utils armhf 2.4.59-2ubuntu2 [98.0 kB] 361s Get:10 http://ftpmaster.internal/ubuntu oracular/main armhf apache2 armhf 2.4.59-2ubuntu2 [90.3 kB] 361s Get:11 http://ftpmaster.internal/ubuntu oracular/main armhf backuppc-rsync armhf 3.1.3.0-3ubuntu1 [233 kB] 361s Get:12 http://ftpmaster.internal/ubuntu oracular/main armhf ssl-cert all 1.1.2ubuntu2 [18.0 kB] 361s Get:13 http://ftpmaster.internal/ubuntu oracular/main armhf libnsl2 armhf 1.3.0-3build3 [36.5 kB] 361s Get:14 http://ftpmaster.internal/ubuntu oracular/main armhf postfix armhf 3.9.0-2 [1203 kB] 361s Get:15 http://ftpmaster.internal/ubuntu oracular/main armhf libarchive-zip-perl all 1.68-1 [90.2 kB] 361s Get:16 http://ftpmaster.internal/ubuntu oracular/main armhf libbackuppc-xs-perl armhf 0.62-2build5 [59.7 kB] 361s Get:17 http://ftpmaster.internal/ubuntu oracular/main armhf libhtml-tagset-perl all 3.24-1 [14.1 kB] 361s Get:18 http://ftpmaster.internal/ubuntu oracular/main armhf liburi-perl all 5.28-1 [88.1 kB] 361s Get:19 http://ftpmaster.internal/ubuntu oracular/main armhf libhtml-parser-perl armhf 3.82-1 [82.4 kB] 361s Get:20 http://ftpmaster.internal/ubuntu oracular/main armhf libcgi-pm-perl all 4.66-1 [185 kB] 361s Get:21 http://ftpmaster.internal/ubuntu oracular/main armhf libtimedate-perl all 2.3300-2 [34.0 kB] 361s Get:22 http://ftpmaster.internal/ubuntu oracular/main armhf libhttp-date-perl all 6.06-1 [10.2 kB] 361s Get:23 http://ftpmaster.internal/ubuntu oracular/main armhf libfile-listing-perl all 6.16-1 [11.3 kB] 361s Get:24 http://ftpmaster.internal/ubuntu oracular/main armhf libtime-parsedate-perl all 2015.103-4 [28.6 kB] 361s Get:25 http://ftpmaster.internal/ubuntu oracular/main armhf backuppc armhf 4.4.0-8build1 [540 kB] 361s Get:26 http://ftpmaster.internal/ubuntu oracular/main armhf samba-common all 2:4.20.2+dfsg-2ubuntu2 [65.4 kB] 361s Get:27 http://ftpmaster.internal/ubuntu oracular/main armhf libavahi-common-data armhf 0.8-13ubuntu6 [29.7 kB] 361s Get:28 http://ftpmaster.internal/ubuntu oracular/main armhf libavahi-common3 armhf 0.8-13ubuntu6 [20.2 kB] 361s Get:29 http://ftpmaster.internal/ubuntu oracular/main armhf libavahi-client3 armhf 0.8-13ubuntu6 [24.2 kB] 361s Get:30 http://ftpmaster.internal/ubuntu oracular/main armhf libcups2t64 armhf 2.4.7-1.2ubuntu9 [236 kB] 361s Get:31 http://ftpmaster.internal/ubuntu oracular/main armhf libtalloc2 armhf 2.4.2-1build2 [25.9 kB] 361s Get:32 http://ftpmaster.internal/ubuntu oracular/main armhf libtdb1 armhf 1.4.10-1build1 [43.1 kB] 361s Get:33 http://ftpmaster.internal/ubuntu oracular/main armhf libtevent0t64 armhf 0.16.1-2build1 [38.1 kB] 361s Get:34 http://ftpmaster.internal/ubuntu oracular/main armhf libldb2 armhf 2:2.9.1+samba4.20.2+dfsg-2ubuntu2 [166 kB] 361s Get:35 http://ftpmaster.internal/ubuntu oracular/main armhf libwbclient0 armhf 2:4.20.2+dfsg-2ubuntu2 [70.6 kB] 361s Get:36 http://ftpmaster.internal/ubuntu oracular/main armhf samba-libs armhf 2:4.20.2+dfsg-2ubuntu2 [5888 kB] 361s Get:37 http://ftpmaster.internal/ubuntu oracular/main armhf samba-common-bin armhf 2:4.20.2+dfsg-2ubuntu2 [1257 kB] 361s Get:38 http://ftpmaster.internal/ubuntu oracular/main armhf samba armhf 2:4.20.2+dfsg-2ubuntu2 [957 kB] 361s Get:39 http://ftpmaster.internal/ubuntu oracular/main armhf libsmbclient0 armhf 2:4.20.2+dfsg-2ubuntu2 [57.5 kB] 361s Get:40 http://ftpmaster.internal/ubuntu oracular/main armhf smbclient armhf 2:4.20.2+dfsg-2ubuntu2 [454 kB] 368s Preconfiguring packages ... 369s Fetched 13.8 MB in 5s (2521 kB/s) 369s Selecting previously unselected package libapr1t64:armhf. 369s (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 ... 58436 files and directories currently installed.) 369s Preparing to unpack .../00-libapr1t64_1.7.2-3.2_armhf.deb ... 369s Unpacking libapr1t64:armhf (1.7.2-3.2) ... 369s Selecting previously unselected package libaprutil1t64:armhf. 369s Preparing to unpack .../01-libaprutil1t64_1.6.3-2ubuntu1_armhf.deb ... 369s Unpacking libaprutil1t64:armhf (1.6.3-2ubuntu1) ... 369s Selecting previously unselected package libaprutil1-dbd-sqlite3:armhf. 369s Preparing to unpack .../02-libaprutil1-dbd-sqlite3_1.6.3-2ubuntu1_armhf.deb ... 369s Unpacking libaprutil1-dbd-sqlite3:armhf (1.6.3-2ubuntu1) ... 369s Selecting previously unselected package libaprutil1-ldap:armhf. 370s Preparing to unpack .../03-libaprutil1-ldap_1.6.3-2ubuntu1_armhf.deb ... 370s Unpacking libaprutil1-ldap:armhf (1.6.3-2ubuntu1) ... 370s Selecting previously unselected package liblua5.4-0:armhf. 370s Preparing to unpack .../04-liblua5.4-0_5.4.6-3build2_armhf.deb ... 370s Unpacking liblua5.4-0:armhf (5.4.6-3build2) ... 370s Selecting previously unselected package apache2-bin. 370s Preparing to unpack .../05-apache2-bin_2.4.59-2ubuntu2_armhf.deb ... 370s Unpacking apache2-bin (2.4.59-2ubuntu2) ... 370s Selecting previously unselected package apache2-data. 370s Preparing to unpack .../06-apache2-data_2.4.59-2ubuntu2_all.deb ... 370s Unpacking apache2-data (2.4.59-2ubuntu2) ... 370s Selecting previously unselected package apache2-utils. 370s Preparing to unpack .../07-apache2-utils_2.4.59-2ubuntu2_armhf.deb ... 370s Unpacking apache2-utils (2.4.59-2ubuntu2) ... 370s Selecting previously unselected package apache2. 370s Preparing to unpack .../08-apache2_2.4.59-2ubuntu2_armhf.deb ... 370s Unpacking apache2 (2.4.59-2ubuntu2) ... 371s Selecting previously unselected package backuppc-rsync. 371s Preparing to unpack .../09-backuppc-rsync_3.1.3.0-3ubuntu1_armhf.deb ... 371s Unpacking backuppc-rsync (3.1.3.0-3ubuntu1) ... 371s Selecting previously unselected package ssl-cert. 371s Preparing to unpack .../10-ssl-cert_1.1.2ubuntu2_all.deb ... 371s Unpacking ssl-cert (1.1.2ubuntu2) ... 371s Selecting previously unselected package libnsl2:armhf. 371s Preparing to unpack .../11-libnsl2_1.3.0-3build3_armhf.deb ... 371s Unpacking libnsl2:armhf (1.3.0-3build3) ... 372s Selecting previously unselected package postfix. 372s Preparing to unpack .../12-postfix_3.9.0-2_armhf.deb ... 372s Unpacking postfix (3.9.0-2) ... 373s Selecting previously unselected package libarchive-zip-perl. 373s Preparing to unpack .../13-libarchive-zip-perl_1.68-1_all.deb ... 373s Unpacking libarchive-zip-perl (1.68-1) ... 373s Selecting previously unselected package libbackuppc-xs-perl:armhf. 374s Preparing to unpack .../14-libbackuppc-xs-perl_0.62-2build5_armhf.deb ... 374s Unpacking libbackuppc-xs-perl:armhf (0.62-2build5) ... 375s Selecting previously unselected package libhtml-tagset-perl. 375s Preparing to unpack .../15-libhtml-tagset-perl_3.24-1_all.deb ... 375s Unpacking libhtml-tagset-perl (3.24-1) ... 375s Selecting previously unselected package liburi-perl. 376s Preparing to unpack .../16-liburi-perl_5.28-1_all.deb ... 376s Unpacking liburi-perl (5.28-1) ... 376s Selecting previously unselected package libhtml-parser-perl:armhf. 377s Preparing to unpack .../17-libhtml-parser-perl_3.82-1_armhf.deb ... 377s Unpacking libhtml-parser-perl:armhf (3.82-1) ... 377s Selecting previously unselected package libcgi-pm-perl. 377s Preparing to unpack .../18-libcgi-pm-perl_4.66-1_all.deb ... 377s Unpacking libcgi-pm-perl (4.66-1) ... 377s Selecting previously unselected package libtimedate-perl. 377s Preparing to unpack .../19-libtimedate-perl_2.3300-2_all.deb ... 377s Unpacking libtimedate-perl (2.3300-2) ... 378s Selecting previously unselected package libhttp-date-perl. 378s Preparing to unpack .../20-libhttp-date-perl_6.06-1_all.deb ... 378s Unpacking libhttp-date-perl (6.06-1) ... 378s Selecting previously unselected package libfile-listing-perl. 378s Preparing to unpack .../21-libfile-listing-perl_6.16-1_all.deb ... 378s Unpacking libfile-listing-perl (6.16-1) ... 378s Selecting previously unselected package libtime-parsedate-perl. 378s Preparing to unpack .../22-libtime-parsedate-perl_2015.103-4_all.deb ... 378s Unpacking libtime-parsedate-perl (2015.103-4) ... 378s Selecting previously unselected package backuppc. 378s Preparing to unpack .../23-backuppc_4.4.0-8build1_armhf.deb ... 378s Unpacking backuppc (4.4.0-8build1) ... 378s Selecting previously unselected package samba-common. 378s Preparing to unpack .../24-samba-common_2%3a4.20.2+dfsg-2ubuntu2_all.deb ... 378s Unpacking samba-common (2:4.20.2+dfsg-2ubuntu2) ... 378s Selecting previously unselected package libavahi-common-data:armhf. 378s Preparing to unpack .../25-libavahi-common-data_0.8-13ubuntu6_armhf.deb ... 378s Unpacking libavahi-common-data:armhf (0.8-13ubuntu6) ... 378s Selecting previously unselected package libavahi-common3:armhf. 378s Preparing to unpack .../26-libavahi-common3_0.8-13ubuntu6_armhf.deb ... 378s Unpacking libavahi-common3:armhf (0.8-13ubuntu6) ... 378s Selecting previously unselected package libavahi-client3:armhf. 378s Preparing to unpack .../27-libavahi-client3_0.8-13ubuntu6_armhf.deb ... 378s Unpacking libavahi-client3:armhf (0.8-13ubuntu6) ... 379s Selecting previously unselected package libcups2t64:armhf. 379s Preparing to unpack .../28-libcups2t64_2.4.7-1.2ubuntu9_armhf.deb ... 379s Unpacking libcups2t64:armhf (2.4.7-1.2ubuntu9) ... 379s Selecting previously unselected package libtalloc2:armhf. 379s Preparing to unpack .../29-libtalloc2_2.4.2-1build2_armhf.deb ... 379s Unpacking libtalloc2:armhf (2.4.2-1build2) ... 379s Selecting previously unselected package libtdb1:armhf. 379s Preparing to unpack .../30-libtdb1_1.4.10-1build1_armhf.deb ... 379s Unpacking libtdb1:armhf (1.4.10-1build1) ... 379s Selecting previously unselected package libtevent0t64:armhf. 379s Preparing to unpack .../31-libtevent0t64_0.16.1-2build1_armhf.deb ... 379s Unpacking libtevent0t64:armhf (0.16.1-2build1) ... 379s Selecting previously unselected package libldb2:armhf. 379s Preparing to unpack .../32-libldb2_2%3a2.9.1+samba4.20.2+dfsg-2ubuntu2_armhf.deb ... 379s Unpacking libldb2:armhf (2:2.9.1+samba4.20.2+dfsg-2ubuntu2) ... 379s Selecting previously unselected package libwbclient0:armhf. 379s Preparing to unpack .../33-libwbclient0_2%3a4.20.2+dfsg-2ubuntu2_armhf.deb ... 379s Unpacking libwbclient0:armhf (2:4.20.2+dfsg-2ubuntu2) ... 379s Selecting previously unselected package samba-libs:armhf. 379s Preparing to unpack .../34-samba-libs_2%3a4.20.2+dfsg-2ubuntu2_armhf.deb ... 379s Unpacking samba-libs:armhf (2:4.20.2+dfsg-2ubuntu2) ... 380s Selecting previously unselected package samba-common-bin. 380s Preparing to unpack .../35-samba-common-bin_2%3a4.20.2+dfsg-2ubuntu2_armhf.deb ... 380s Unpacking samba-common-bin (2:4.20.2+dfsg-2ubuntu2) ... 380s Selecting previously unselected package samba. 380s Preparing to unpack .../36-samba_2%3a4.20.2+dfsg-2ubuntu2_armhf.deb ... 380s Unpacking samba (2:4.20.2+dfsg-2ubuntu2) ... 380s Selecting previously unselected package libsmbclient0:armhf. 380s Preparing to unpack .../37-libsmbclient0_2%3a4.20.2+dfsg-2ubuntu2_armhf.deb ... 380s Unpacking libsmbclient0:armhf (2:4.20.2+dfsg-2ubuntu2) ... 381s Selecting previously unselected package smbclient. 381s Preparing to unpack .../38-smbclient_2%3a4.20.2+dfsg-2ubuntu2_armhf.deb ... 381s Unpacking smbclient (2:4.20.2+dfsg-2ubuntu2) ... 381s Selecting previously unselected package autopkgtest-satdep. 381s Preparing to unpack .../39-1-autopkgtest-satdep.deb ... 381s Unpacking autopkgtest-satdep (0) ... 381s Setting up libarchive-zip-perl (1.68-1) ... 381s Setting up libtdb1:armhf (1.4.10-1build1) ... 381s Setting up libhtml-tagset-perl (3.24-1) ... 381s Setting up samba-common (2:4.20.2+dfsg-2ubuntu2) ... 382s 382s Creating config file /etc/samba/smb.conf with new version 382s Setting up libwbclient0:armhf (2:4.20.2+dfsg-2ubuntu2) ... 382s Setting up libtalloc2:armhf (2.4.2-1build2) ... 382s Setting up backuppc-rsync (3.1.3.0-3ubuntu1) ... 382s Setting up ssl-cert (1.1.2ubuntu2) ... 386s Created symlink '/etc/systemd/system/multi-user.target.wants/ssl-cert.service' → '/usr/lib/systemd/system/ssl-cert.service'. 388s Setting up libavahi-common-data:armhf (0.8-13ubuntu6) ... 388s Setting up libtimedate-perl (2.3300-2) ... 388s Setting up libtime-parsedate-perl (2015.103-4) ... 388s Setting up libapr1t64:armhf (1.7.2-3.2) ... 388s Setting up libnsl2:armhf (1.3.0-3build3) ... 388s Setting up liblua5.4-0:armhf (5.4.6-3build2) ... 388s Setting up apache2-data (2.4.59-2ubuntu2) ... 388s Setting up libbackuppc-xs-perl:armhf (0.62-2build5) ... 388s Setting up liburi-perl (5.28-1) ... 388s Setting up libtevent0t64:armhf (0.16.1-2build1) ... 388s Setting up libaprutil1t64:armhf (1.6.3-2ubuntu1) ... 388s Setting up libhttp-date-perl (6.06-1) ... 388s Setting up libfile-listing-perl (6.16-1) ... 388s Setting up libavahi-common3:armhf (0.8-13ubuntu6) ... 388s Setting up libaprutil1-ldap:armhf (1.6.3-2ubuntu1) ... 388s Setting up libaprutil1-dbd-sqlite3:armhf (1.6.3-2ubuntu1) ... 388s Setting up postfix (3.9.0-2) ... 388s info: Selecting GID from range 100 to 999 ... 388s info: Adding group `postfix' (GID 111) ... 389s info: Selecting UID from range 100 to 999 ... 389s 389s info: Adding system user `postfix' (UID 107) ... 389s info: Adding new user `postfix' (UID 107) with group `postfix' ... 389s info: Not creating home directory `/var/spool/postfix'. 389s Creating /etc/postfix/dynamicmaps.cf 389s info: Selecting GID from range 100 to 999 ... 389s info: Adding group `postdrop' (GID 112) ... 389s setting myhostname: autopkgtest-lxd-zpnwxi 389s setting alias maps 389s setting alias database 389s mailname is not a fully qualified domain name. Not changing /etc/mailname. 389s setting destinations: $myhostname, autopkgtest-lxd-zpnwxi, localhost.localdomain, , localhost 389s setting relayhost: 389s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 389s setting mailbox_size_limit: 0 389s setting recipient_delimiter: + 391s setting inet_interfaces: all 393s setting inet_protocols: all 393s /etc/aliases does not exist, creating it. 393s WARNING: /etc/aliases exists, but does not have a root alias. 393s 393s Postfix (main.cf) is now set up with a default configuration. If you need to 393s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 393s Postfix configuration values, see postconf(1). 393s 393s After modifying main.cf, be sure to run 'systemctl reload postfix'. 393s 395s Running newaliases 396s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 402s Setting up libhtml-parser-perl:armhf (3.82-1) ... 402s Setting up libldb2:armhf (2:2.9.1+samba4.20.2+dfsg-2ubuntu2) ... 402s Setting up libavahi-client3:armhf (0.8-13ubuntu6) ... 402s Setting up apache2-utils (2.4.59-2ubuntu2) ... 402s Setting up libcgi-pm-perl (4.66-1) ... 402s Setting up samba-libs:armhf (2:4.20.2+dfsg-2ubuntu2) ... 402s Setting up apache2-bin (2.4.59-2ubuntu2) ... 402s Setting up libsmbclient0:armhf (2:4.20.2+dfsg-2ubuntu2) ... 402s Setting up libcups2t64:armhf (2.4.7-1.2ubuntu9) ... 402s Setting up apache2 (2.4.59-2ubuntu2) ... 402s Enabling module mpm_event. 402s Enabling module authz_core. 402s Enabling module authz_host. 402s Enabling module authn_core. 402s Enabling module auth_basic. 403s Enabling module access_compat. 403s Enabling module authn_file. 403s Enabling module authz_user. 403s Enabling module alias. 403s Enabling module dir. 403s Enabling module autoindex. 403s Enabling module env. 403s Enabling module mime. 403s Enabling module negotiation. 403s Enabling module setenvif. 403s Enabling module filter. 403s Enabling module deflate. 403s Enabling module status. 403s Enabling module reqtimeout. 403s Enabling conf charset. 403s Enabling conf localized-error-pages. 403s Enabling conf other-vhosts-access-log. 403s Enabling conf security. 403s Enabling conf serve-cgi-bin. 403s Enabling site 000-default. 404s Created symlink '/etc/systemd/system/multi-user.target.wants/apache2.service' → '/usr/lib/systemd/system/apache2.service'. 405s Created symlink '/etc/systemd/system/multi-user.target.wants/apache-htcacheclean.service' → '/usr/lib/systemd/system/apache-htcacheclean.service'. 407s Setting up backuppc (4.4.0-8build1) ... 407s 407s apache2_invoke: Enable configuration backuppc.conf 407s warn: The home directory `/var/lib/backuppc' already exists. Not touching this directory. 407s warn: Warning: The home directory `/var/lib/backuppc' does not belong to the user you are currently creating. 408s Adding password for user backuppc 408s Considering dependency authn_core for auth_basic: 408s Module authn_core already enabled 408s Module auth_basic already enabled 408s Considering dependency authz_core for authz_groupfile: 408s Module authz_core already enabled 408s Enabling module authz_groupfile. 408s To activate the new configuration, you need to run: 408s systemctl restart apache2 408s Module authn_file already enabled 408s Considering dependency authz_core for authz_user: 408s Module authz_core already enabled 408s Module authz_user already enabled 408s Your MPM seems to be threaded. Selecting cgid instead of cgi. 408s Enabling module cgid. 408s To activate the new configuration, you need to run: 408s systemctl restart apache2 408s 408s Creating config file /etc/backuppc/config.pl with new version 409s Created symlink '/etc/systemd/system/multi-user.target.wants/backuppc.service' → '/usr/lib/systemd/system/backuppc.service'. 409s Setting up smbclient (2:4.20.2+dfsg-2ubuntu2) ... 409s Setting up samba-common-bin (2:4.20.2+dfsg-2ubuntu2) ... 409s Setting up samba (2:4.20.2+dfsg-2ubuntu2) ... 411s Created symlink '/etc/systemd/system/smb.service' → '/usr/lib/systemd/system/smbd.service'. 411s Created symlink '/etc/systemd/system/multi-user.target.wants/smbd.service' → '/usr/lib/systemd/system/smbd.service'. 412s Created symlink '/etc/systemd/system/nmb.service' → '/usr/lib/systemd/system/nmbd.service'. 412s Created symlink '/etc/systemd/system/multi-user.target.wants/nmbd.service' → '/usr/lib/systemd/system/nmbd.service'. 413s Setting up autopkgtest-satdep (0) ... 413s Processing triggers for rsyslog (8.2312.0-3ubuntu9) ... 413s Processing triggers for ufw (0.36.2-6) ... 413s Processing triggers for man-db (2.12.1-2) ... 414s Processing triggers for libc-bin (2.39-3.1ubuntu3) ... 432s (Reading database ... 60277 files and directories currently installed.) 432s Removing autopkgtest-satdep (0) ... 444s autopkgtest [14:20:39]: test smb-backup: [----------------------- 456s Populating share path 464s Adding samba share 465s Adding [public] share 470s Configuring backuppc 471s Performing a full backup 483s Backup type: type = full, needs_full = 1, needs_incr = 1, lastFullTime = 0, opts{f} = 1, opts{i} = , opts{F} = 483s cmdSystemOrEval: about to system /bin/ping -c 1 localhost 485s cmdSystemOrEval: about to system /bin/ping -c 1 localhost 486s CheckHostAlive: ran '/bin/ping -c 1 localhost'; returning 0.019 486s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.0.z created 2024-07-12 14:21:20 486s Backup prep: type = full, case = 1, inPlace = 1, doDuplicate = 0, newBkupNum = 0, newBkupIdx = 0, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 486s __bpc_progress_state__ backup share "public" 486s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 486s full backup started for share public 486s started full dump, share=public 486s Xfer PIDs are now 3393,3392 486s xferPids 3393,3392 486s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 487s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 487s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 488s tarmode is now full, system, hidden, noreset, noverbose 489s __bpc_progress_fileCnt__ 0 489s new 644 0/0 480434 tmp.lLWSpW 489s new 644 0/0 480434 tmp.XvJ11b 489s new 644 0/0 480434 tmp.1GArxO 489s new 644 0/0 480434 tmp.iMypF5 489s new 644 0/0 480434 tmp.zp5j1m 489s new 644 0/0 480434 tmp.6NtJ9I 489s new 644 0/0 480434 tmp.RvgOSj 489s new 644 0/0 480434 tmp.9NvStU 489s new 644 0/0 480434 tmp.GR2vsp 489s tar: dumped 10 files and 0 directories 489s Total bytes written: 4804340 (10.0 MiB/s) 489s new 644 0/0 480434 tmp.9Sv8h9 489s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 490s __bpc_progress_fileCnt__ 10 490s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377775 sizeNewComp, 13 inodeLast 490s Xfer PIDs are now 490s xferPids 490s full backup 0 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 491s BackupExpire: cntFull = 1, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 4.62962962962963e-05 491s __bpc_progress_state__ fsck 491s Running BackupPC_refCountUpdate -h localhost on localhost 491s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 492s Xfer PIDs are now 3400 492s xferPids 3400 492s BackupPC_refCountUpdate: doing fsck on localhost #0 (full) since $ConfRefCntFsck == 1 492s __bpc_progress_state__ refCnt #0 493s __bpc_progress_state__ cntUpdate #0 494s __bpc_progress_state__ rename #0 494s __bpc_progress_state__ sumUpdate 494s __bpc_progress_fileCnt__ 0/128 494s __bpc_progress_fileCnt__ 8/128 494s __bpc_progress_fileCnt__ 16/128 494s __bpc_progress_fileCnt__ 24/128 494s __bpc_progress_fileCnt__ 32/128 494s __bpc_progress_fileCnt__ 40/128 494s __bpc_progress_fileCnt__ 48/128 494s __bpc_progress_fileCnt__ 56/128 494s __bpc_progress_fileCnt__ 64/128 494s __bpc_progress_fileCnt__ 72/128 494s __bpc_progress_fileCnt__ 80/128 494s __bpc_progress_fileCnt__ 88/128 494s __bpc_progress_fileCnt__ 96/128 494s __bpc_progress_fileCnt__ 104/128 494s __bpc_progress_fileCnt__ 112/128 494s __bpc_progress_fileCnt__ 120/128 494s __bpc_progress_state__ rename total 494s BackupPC_refCountUpdate: host localhost got 0 errors (took 2 secs) 494s Xfer PIDs are now 494s xferPids 494s Finished BackupPC_refCountUpdate (running time: 4 sec) 494s Xfer PIDs are now 494s xferPids 494s full backup complete 495s OK: Full backup succeeded 495s Changing share content and performing an incremental backup 498s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1720794081, opts{f} = , opts{i} = 1, opts{F} = 498s cmdSystemOrEval: about to system /bin/ping -c 1 localhost 498s cmdSystemOrEval: about to system /bin/ping -c 1 localhost 498s CheckHostAlive: ran '/bin/ping -c 1 localhost'; returning 0.021 498s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.1.z created 2024-07-12 14:21:33 498s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 1, newBkupIdx = 1, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 498s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 498s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 498s Old package separator used in string at /usr/share/backuppc/bin/BackupPC_backupDuplicate line 89. 498s (Did you mean "$Host\'s" instead?) 498s Xfer PIDs are now 3470 498s xferPids 3470 498s __bpc_progress_state__ copy #0 -> #1 498s Copying backup #0 to #1 498s __bpc_progress_fileCnt__ 3 dirs 498s Xfer PIDs are now 3470,3473 498s xferPids 3470,3473 498s __bpc_progress_state__ sumUpdate 498s __bpc_progress_fileCnt__ 0/128 498s __bpc_progress_fileCnt__ 8/128 498s __bpc_progress_fileCnt__ 16/128 498s __bpc_progress_fileCnt__ 24/128 498s __bpc_progress_fileCnt__ 32/128 498s __bpc_progress_fileCnt__ 40/128 498s __bpc_progress_fileCnt__ 48/128 498s __bpc_progress_fileCnt__ 56/128 498s __bpc_progress_fileCnt__ 64/128 498s __bpc_progress_fileCnt__ 72/128 498s __bpc_progress_fileCnt__ 80/128 498s __bpc_progress_fileCnt__ 88/128 498s __bpc_progress_fileCnt__ 96/128 498s __bpc_progress_fileCnt__ 104/128 498s __bpc_progress_fileCnt__ 112/128 498s __bpc_progress_fileCnt__ 120/128 498s __bpc_progress_state__ rename total 498s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 498s Xfer PIDs are now 3470 498s xferPids 3470 498s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 499s Finished BackupPC_backupDuplicate (running time: 0 sec) 499s __bpc_progress_state__ backup share "public" 499s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 499s incr backup started back to 2024-07-12 13:21:21 (backup #0) for share public 499s started incr dump, share=public 499s Xfer PIDs are now 3477,3476 499s xferPids 3477,3476 499s 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 - 499s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 499s tar:1298 Getting files newer than Fri Jul 12 13:21:21 2024 499s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 499s tarmode is now full, system, hidden, noreset, noverbose 499s __bpc_progress_fileCnt__ 0 499s same 644 0/0 480434 tmp.lLWSpW 499s same 644 0/0 480434 tmp.XvJ11b 499s same 644 0/0 480434 tmp.1GArxO 499s same 644 0/0 480434 tmp.iMypF5 499s same 644 0/0 480434 tmp.zp5j1m 499s same 644 0/0 480434 tmp.6NtJ9I 499s same 644 0/0 480434 tmp.RvgOSj 499s same 644 0/0 480434 tmp.9NvStU 499s same 644 0/0 480434 tmp.GR2vsp 499s same 644 0/0 480434 tmp.9Sv8h9 499s new 644 0/0 480434 tmp.2IfcxZ 499s new 644 0/0 480434 tmp.BhnBfS 499s new 644 0/0 480434 tmp.2OnYn9 499s new 644 0/0 480434 tmp.rNjkAM 499s new 644 0/0 480434 tmp.aFbDym 499s new 644 0/0 480434 tmp.4EQ1n0 499s new 644 0/0 480434 tmp.w9ijyC 499s new 644 0/0 480434 tmp.wezLTW 499s new 644 0/0 480434 tmp.VoTWC2 499s tar: dumped 20 files and 0 directories 499s Total bytes written: 9608680 (41.8 MiB/s) 499s new 644 0/0 480434 tmp.Eewkt1 499s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 500s __bpc_progress_fileCnt__ 20 500s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377775 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377775 sizeNewComp, 24 inodeLast 500s Xfer PIDs are now 500s xferPids 500s incr backup 1 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 500s BackupExpire: cntFull = 2, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 0.000162037037037037 500s __bpc_progress_state__ fsck 500s Running BackupPC_refCountUpdate -h localhost on localhost 500s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 500s Xfer PIDs are now 3483 500s xferPids 3483 500s __bpc_progress_state__ cntUpdate #1 500s __bpc_progress_state__ rename #1 500s __bpc_progress_state__ sumUpdate 500s __bpc_progress_fileCnt__ 0/128 500s __bpc_progress_fileCnt__ 8/128 500s __bpc_progress_fileCnt__ 16/128 500s __bpc_progress_fileCnt__ 24/128 500s __bpc_progress_fileCnt__ 32/128 500s __bpc_progress_fileCnt__ 40/128 500s __bpc_progress_fileCnt__ 48/128 500s __bpc_progress_fileCnt__ 56/128 500s __bpc_progress_fileCnt__ 64/128 500s __bpc_progress_fileCnt__ 72/128 500s __bpc_progress_fileCnt__ 80/128 500s __bpc_progress_fileCnt__ 88/128 500s __bpc_progress_fileCnt__ 96/128 500s __bpc_progress_fileCnt__ 104/128 500s __bpc_progress_fileCnt__ 112/128 500s __bpc_progress_fileCnt__ 120/128 500s __bpc_progress_state__ rename total 500s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 500s Xfer PIDs are now 500s xferPids 500s Finished BackupPC_refCountUpdate (running time: 0 sec) 500s Xfer PIDs are now 500s xferPids 500s incr backup complete 500s OK: Incremental backup succeeded 500s Done. 501s autopkgtest [14:21:36]: test smb-backup: -----------------------] 513s autopkgtest [14:21:48]: test smb-backup: - - - - - - - - - - results - - - - - - - - - - 513s smb-backup PASS 534s autopkgtest [14:22:09]: test authenticated-smb-backup: preparing testbed 569s Reading package lists... 569s Building dependency tree... 569s Reading state information... 569s Starting pkgProblemResolver with broken count: 0 570s Starting 2 pkgProblemResolver with broken count: 0 570s Done 570s The following NEW packages will be installed: 570s autopkgtest-satdep 571s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 571s Need to get 0 B/724 B of archives. 571s After this operation, 0 B of additional disk space will be used. 571s Get:1 /tmp/autopkgtest.yqzwRw/2-autopkgtest-satdep.deb autopkgtest-satdep armhf 0 [724 B] 573s Selecting previously unselected package autopkgtest-satdep. 573s (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 ... 60278 files and directories currently installed.) 573s Preparing to unpack .../2-autopkgtest-satdep.deb ... 573s Unpacking autopkgtest-satdep (0) ... 573s Setting up autopkgtest-satdep (0) ... 584s (Reading database ... 60277 files and directories currently installed.) 584s Removing autopkgtest-satdep (0) ... 590s autopkgtest [14:23:05]: test authenticated-smb-backup: [----------------------- 592s Populating share path 593s Adding samba share 593s Adding [private] share 593s Creating user 593s Creating a local and samba user called smbtest3796 593s Setting samba password for the smbtest3796 user 593s Added user smbtest3796. 593s Configuring backuppc 593s Performing a full backup 593s Backup type: type = full, needs_full = , needs_incr = , lastFullTime = 1720794081, opts{f} = 1, opts{i} = , opts{F} = 593s cmdSystemOrEval: about to system /bin/ping -c 1 localhost 594s cmdSystemOrEval: about to system /bin/ping -c 1 localhost 594s CheckHostAlive: ran '/bin/ping -c 1 localhost'; returning 0.021 594s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.2.z created 2024-07-12 14:23:09 594s Backup prep: type = full, case = 4, inPlace = 0, doDuplicate = 0, newBkupNum = 2, newBkupIdx = 2, lastBkupNum = 1, lastBkupIdx = 1 (FillCycle = 0, noFillCnt = 0) 594s __bpc_progress_state__ backup share "private" 594s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest3796 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 594s full backup started for share private 594s started full dump, share=private 594s Xfer PIDs are now 3890,3889 594s xferPids 3890,3889 594s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest3796 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 594s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 594s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 594s tarmode is now full, system, hidden, noreset, noverbose 594s __bpc_progress_fileCnt__ 0 594s new 644 0/0 480434 tmp.0lEocA 594s new 644 0/0 480434 tmp.nIzPdV 594s new 644 0/0 480434 tmp.fyMoFI 594s new 644 0/0 480434 tmp.2X9uGq 594s new 644 0/0 480434 tmp.MaH7XZ 594s new 644 0/0 480434 tmp.ye4BYW 594s new 644 0/0 480434 tmp.zelftl 594s new 644 0/0 480434 tmp.ZLDDzR 594s new 644 0/0 480434 tmp.g6rjQ7 594s new 644 0/0 480434 tmp.9UDXzp 594s tar: dumped 10 files and 0 directories 594s Total bytes written: 4804340 (31.5 MiB/s) 594s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 594s __bpc_progress_fileCnt__ 10 594s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377795 sizeNewComp, 36 inodeLast 594s Xfer PIDs are now 594s xferPids 594s OrphanShareNameClean: removing orphan share public from /var/lib/backuppc/pc/localhost/2 594s __bpc_progress_state__ delete share #2/public 594s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDelete -h localhost -n 2 -l -m -s public / 594s Old package separator used in string at /usr/share/backuppc/bin/BackupPC_backupDelete line 102. 594s (Did you mean "$Host\'s" instead?) 594s Xfer PIDs are now 3896 594s xferPids 3896 594s BackupPC_backupDelete: removing #2/public/ 594s __bpc_progress_state__ merge #2/public/ -> #1/public/ 594s BackupPC_backupDelete: Merge into backup 1/public/ 594s mergeDir: attr write to /var/lib/backuppc/pc/localhost/1/fpublic//attrib failed (digest was ) 594s bpc_attrib_dirWrite: can't open/create raw /var/lib/backuppc/pc/localhost/1/fpublic for writing 594s Xfer PIDs are now 3899,3896 594s xferPids 3899,3896 594s __bpc_progress_state__ refCnt #1 594s __bpc_progress_state__ cntUpdate #1 594s __bpc_progress_state__ rename #1 594s __bpc_progress_state__ refCnt #2 594s BackupPC_refCountUpdate: localhost #2 inodeLast set to 37 (was 24) 594s __bpc_progress_state__ cntUpdate #2 594s __bpc_progress_state__ rename #2 594s __bpc_progress_state__ sumUpdate 594s __bpc_progress_fileCnt__ 0/128 594s __bpc_progress_fileCnt__ 8/128 594s __bpc_progress_fileCnt__ 16/128 594s __bpc_progress_fileCnt__ 24/128 594s __bpc_progress_fileCnt__ 32/128 594s __bpc_progress_fileCnt__ 40/128 594s __bpc_progress_fileCnt__ 48/128 594s __bpc_progress_fileCnt__ 56/128 594s __bpc_progress_fileCnt__ 64/128 595s __bpc_progress_fileCnt__ 72/128 595s __bpc_progress_fileCnt__ 80/128 595s __bpc_progress_fileCnt__ 88/128 595s __bpc_progress_fileCnt__ 96/128 595s __bpc_progress_fileCnt__ 104/128 595s __bpc_progress_fileCnt__ 112/128 595s __bpc_progress_fileCnt__ 120/128 595s __bpc_progress_state__ rename total 595s BackupPC_refCountUpdate: host localhost got 0 errors (took 1 secs) 595s Xfer PIDs are now 3896 595s xferPids 3896 595s Xfer PIDs are now 595s xferPids 595s Finished BackupPC_backupDelete, status = 0 (running time: 1 sec) 595s Xfer PIDs are now 595s xferPids 595s full backup 2 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 595s BackupExpire: cntFull = 2, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 0.00111111111111111, oldestFull = 0.00126157407407407 595s __bpc_progress_state__ fsck 595s Running BackupPC_refCountUpdate -h localhost on localhost 595s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 595s Xfer PIDs are now 3902 595s xferPids 3902 595s BackupPC_refCountUpdate: doing fsck on localhost #2 (full) since $ConfRefCntFsck == 1 595s __bpc_progress_state__ refCnt #2 595s __bpc_progress_state__ cntUpdate #2 595s __bpc_progress_state__ rename #2 595s __bpc_progress_state__ sumUpdate 595s __bpc_progress_fileCnt__ 0/128 595s __bpc_progress_fileCnt__ 8/128 595s __bpc_progress_fileCnt__ 16/128 595s __bpc_progress_fileCnt__ 24/128 595s __bpc_progress_fileCnt__ 32/128 595s __bpc_progress_fileCnt__ 40/128 595s __bpc_progress_fileCnt__ 48/128 595s __bpc_progress_fileCnt__ 56/128 595s __bpc_progress_fileCnt__ 64/128 595s __bpc_progress_fileCnt__ 72/128 595s __bpc_progress_fileCnt__ 80/128 595s __bpc_progress_fileCnt__ 88/128 595s __bpc_progress_fileCnt__ 96/128 595s __bpc_progress_fileCnt__ 104/128 595s __bpc_progress_fileCnt__ 112/128 595s __bpc_progress_fileCnt__ 120/128 595s __bpc_progress_state__ rename total 595s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 595s Xfer PIDs are now 595s xferPids 595s Finished BackupPC_refCountUpdate (running time: 0 sec) 595s Xfer PIDs are now 595s xferPids 595s full backup complete 595s OK: Full backup succeeded 595s Changing share content and performing an incremental backup 595s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1720794189, opts{f} = , opts{i} = 1, opts{F} = 595s cmdSystemOrEval: about to system /bin/ping -c 1 localhost 595s cmdSystemOrEval: about to system /bin/ping -c 1 localhost 595s CheckHostAlive: ran '/bin/ping -c 1 localhost'; returning 0.021 595s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.3.z created 2024-07-12 14:23:10 595s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 3, newBkupIdx = 3, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = 1) 595s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 595s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 595s Old package separator used in string at /usr/share/backuppc/bin/BackupPC_backupDuplicate line 89. 595s (Did you mean "$Host\'s" instead?) 596s Xfer PIDs are now 3972 596s xferPids 3972 596s __bpc_progress_state__ copy #2 -> #3 596s Copying backup #2 to #3 596s __bpc_progress_fileCnt__ 3 dirs 596s Xfer PIDs are now 3975,3972 596s xferPids 3975,3972 596s __bpc_progress_state__ sumUpdate 596s __bpc_progress_fileCnt__ 0/128 596s __bpc_progress_fileCnt__ 8/128 596s __bpc_progress_fileCnt__ 16/128 596s __bpc_progress_fileCnt__ 24/128 596s __bpc_progress_fileCnt__ 32/128 596s __bpc_progress_fileCnt__ 40/128 596s __bpc_progress_fileCnt__ 48/128 596s __bpc_progress_fileCnt__ 56/128 596s __bpc_progress_fileCnt__ 64/128 596s __bpc_progress_fileCnt__ 72/128 596s __bpc_progress_fileCnt__ 80/128 596s __bpc_progress_fileCnt__ 88/128 596s __bpc_progress_fileCnt__ 96/128 596s __bpc_progress_fileCnt__ 104/128 596s __bpc_progress_fileCnt__ 112/128 596s __bpc_progress_fileCnt__ 120/128 596s __bpc_progress_state__ rename total 596s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 596s Xfer PIDs are now 3972 596s xferPids 3972 596s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 596s Finished BackupPC_backupDuplicate (running time: 1 sec) 596s __bpc_progress_state__ backup share "private" 596s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest3796 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 596s incr backup started back to 2024-07-12 13:23:09 (backup #2) for share private 596s started incr dump, share=private 596s Xfer PIDs are now 3979,3978 596s xferPids 3979,3978 596s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest3796 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 596s tar:1298 Getting files newer than Fri Jul 12 13:23:09 2024 596s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 596s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 596s tarmode is now full, system, hidden, noreset, noverbose 596s __bpc_progress_fileCnt__ 0 596s same 644 0/0 480434 tmp.0lEocA 596s same 644 0/0 480434 tmp.nIzPdV 596s same 644 0/0 480434 tmp.fyMoFI 596s same 644 0/0 480434 tmp.2X9uGq 596s same 644 0/0 480434 tmp.MaH7XZ 596s same 644 0/0 480434 tmp.ye4BYW 596s same 644 0/0 480434 tmp.zelftl 596s same 644 0/0 480434 tmp.ZLDDzR 596s same 644 0/0 480434 tmp.g6rjQ7 596s same 644 0/0 480434 tmp.9UDXzp 596s new 644 0/0 480434 tmp.mVvDCC 596s new 644 0/0 480434 tmp.Yk9rGK 596s new 644 0/0 480434 tmp.7Mipuq 596s new 644 0/0 480434 tmp.FkZ6cl 596s new 644 0/0 480434 tmp.2XuRFe 596s new 644 0/0 480434 tmp.6WyDN7 596s new 644 0/0 480434 tmp.1V7cMy 596s new 644 0/0 480434 tmp.1H1348 596s new 644 0/0 480434 tmp.hL1r2o 596s tar: dumped 20 files and 0 directories 596s Total bytes written: 9608680 (45.7 MiB/s) 596s new 644 0/0 480434 tmp.t1AHqB 596s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 596s __bpc_progress_fileCnt__ 20 596s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377795 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377757 sizeNewComp, 47 inodeLast 596s Xfer PIDs are now 596s xferPids 596s incr backup 3 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 596s BackupExpire: cntFull = 3, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 0.00112268518518519, oldestFull = 0.00127314814814815 596s __bpc_progress_state__ fsck 596s Running BackupPC_refCountUpdate -h localhost on localhost 596s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 596s Xfer PIDs are now 3985 596s xferPids 3985 596s __bpc_progress_state__ cntUpdate #3 596s __bpc_progress_state__ rename #3 596s __bpc_progress_state__ sumUpdate 596s __bpc_progress_fileCnt__ 0/128 596s __bpc_progress_fileCnt__ 8/128 596s __bpc_progress_fileCnt__ 16/128 596s __bpc_progress_fileCnt__ 24/128 596s __bpc_progress_fileCnt__ 32/128 596s __bpc_progress_fileCnt__ 40/128 596s __bpc_progress_fileCnt__ 48/128 597s __bpc_progress_fileCnt__ 56/128 597s __bpc_progress_fileCnt__ 64/128 597s __bpc_progress_fileCnt__ 72/128 597s __bpc_progress_fileCnt__ 80/128 597s __bpc_progress_fileCnt__ 88/128 597s __bpc_progress_fileCnt__ 96/128 597s __bpc_progress_fileCnt__ 104/128 597s __bpc_progress_fileCnt__ 112/128 597s __bpc_progress_fileCnt__ 120/128 597s __bpc_progress_state__ rename total 597s BackupPC_refCountUpdate: host localhost got 0 errors (took 1 secs) 597s Xfer PIDs are now 597s xferPids 597s Finished BackupPC_refCountUpdate (running time: 1 sec) 597s Xfer PIDs are now 597s xferPids 597s incr backup complete 597s OK: Incremental backup succeeded 597s Done. 597s Deleted user smbtest3796. 597s userdel: smbtest3796 mail spool (/var/mail/smbtest3796) not found 597s autopkgtest [14:23:12]: test authenticated-smb-backup: -----------------------] 602s autopkgtest [14:23:17]: test authenticated-smb-backup: - - - - - - - - - - results - - - - - - - - - - 602s authenticated-smb-backup PASS 606s autopkgtest [14:23:21]: @@@@@@@@@@@@@@@@@@@@ summary 606s smb-backup PASS 606s authenticated-smb-backup PASS