0s autopkgtest [08:43:24]: starting date and time: 2026-01-30 08:43:24+0000 0s autopkgtest [08:43:24]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [08:43:24]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work._o3n6ac5/out --timeout-copy=6000 --needs-internet=try --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:tntnet --apt-upgrade backuppc --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=tntnet/3.0-4build1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-s390x-7.secgroup --name adt-resolute-s390x-backuppc-20260130-084324-juju-7f2275-prod-proposed-migration-environment-15-f22aa845-1978-42b2-9bc6-c55ea6e327bf --image adt/ubuntu-resolute-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-s390x-backuppc-20260130-084324-juju-7f2275-prod-proposed-migration-environment-15-f22aa845-1978-42b2-9bc6-c55ea6e327bf from image adt/ubuntu-resolute-s390x-server-20260129.img (UUID 0fdc3f45-c7c7-4a2c-aa6d-edc1dbbf6141)... 86s autopkgtest [08:44:50]: testbed dpkg architecture: s390x 86s autopkgtest [08:44:50]: testbed apt version: 3.1.14 87s autopkgtest [08:44:51]: @@@@@@@@@@@@@@@@@@@@ test bed setup 87s autopkgtest [08:44:51]: testbed release detected to be: None 88s autopkgtest [08:44:52]: updating testbed package index (apt update) 89s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 89s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 89s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 89s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 89s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [2820 B] 89s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [103 kB] 89s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1543 kB] 89s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [32.1 kB] 90s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x Packages [116 kB] 90s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x Packages [1066 kB] 90s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse s390x Packages [10.9 kB] 91s Fetched 2998 kB in 2s (1285 kB/s) 95s Reading package lists... 97s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 97s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 98s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 98s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 102s Reading package lists... 102s Reading package lists... 103s Building dependency tree... 103s Reading state information... 104s Calculating upgrade... 104s The following packages will be upgraded: 104s bzip2 gcc-15-base inetutils-telnet libatomic1 libbz2-1.0 libgcc-s1 104s libsgutils2-1.48 libsqlite3-0 libstdc++6 sg3-utils sg3-utils-udev telnet 104s 12 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 104s Need to get 3103 kB of archives. 104s After this operation, 3072 B of additional disk space will be used. 104s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x libatomic1 s390x 15.2.0-12ubuntu1 [9486 B] 104s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x libstdc++6 s390x 15.2.0-12ubuntu1 [907 kB] 105s Get:3 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-15-base s390x 15.2.0-12ubuntu1 [59.6 kB] 105s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x libgcc-s1 s390x 15.2.0-12ubuntu1 [35.7 kB] 105s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x bzip2 s390x 1.0.8-6build2 [35.2 kB] 105s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x libbz2-1.0 s390x 1.0.8-6build2 [41.6 kB] 105s Get:7 http://ftpmaster.internal/ubuntu resolute/main s390x libsqlite3-0 s390x 3.46.1-9 [748 kB] 105s Get:8 http://ftpmaster.internal/ubuntu resolute/main s390x inetutils-telnet s390x 2:2.7-2ubuntu1 [112 kB] 105s Get:9 http://ftpmaster.internal/ubuntu resolute/main s390x telnet all 0.17+2.7-2ubuntu1 [3636 B] 105s Get:10 http://ftpmaster.internal/ubuntu resolute/main s390x libsgutils2-1.48 s390x 1.48-3ubuntu2 [120 kB] 105s Get:11 http://ftpmaster.internal/ubuntu resolute/main s390x sg3-utils s390x 1.48-3ubuntu2 [1025 kB] 105s Get:12 http://ftpmaster.internal/ubuntu resolute/main s390x sg3-utils-udev all 1.48-3ubuntu2 [6610 B] 106s dpkg-preconfigure: unable to re-open stdin: No such file or directory 106s Fetched 3103 kB in 1s (2912 kB/s) 107s (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 ... 87720 files and directories currently installed.) 107s Preparing to unpack .../libatomic1_15.2.0-12ubuntu1_s390x.deb ... 107s Unpacking libatomic1:s390x (15.2.0-12ubuntu1) over (15.2.0-11ubuntu1) ... 107s Preparing to unpack .../libstdc++6_15.2.0-12ubuntu1_s390x.deb ... 107s Unpacking libstdc++6:s390x (15.2.0-12ubuntu1) over (15.2.0-11ubuntu1) ... 107s Preparing to unpack .../gcc-15-base_15.2.0-12ubuntu1_s390x.deb ... 107s Unpacking gcc-15-base:s390x (15.2.0-12ubuntu1) over (15.2.0-11ubuntu1) ... 107s Setting up gcc-15-base:s390x (15.2.0-12ubuntu1) ... 108s (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 ... 87720 files and directories currently installed.) 108s Preparing to unpack .../libgcc-s1_15.2.0-12ubuntu1_s390x.deb ... 108s Unpacking libgcc-s1:s390x (15.2.0-12ubuntu1) over (15.2.0-11ubuntu1) ... 108s Setting up libgcc-s1:s390x (15.2.0-12ubuntu1) ... 108s (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 ... 87720 files and directories currently installed.) 108s Preparing to unpack .../bzip2_1.0.8-6build2_s390x.deb ... 108s Unpacking bzip2 (1.0.8-6build2) over (1.0.8-6build1) ... 108s Preparing to unpack .../libbz2-1.0_1.0.8-6build2_s390x.deb ... 108s Unpacking libbz2-1.0:s390x (1.0.8-6build2) over (1.0.8-6build1) ... 109s Setting up libbz2-1.0:s390x (1.0.8-6build2) ... 109s (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 ... 87720 files and directories currently installed.) 109s Preparing to unpack .../0-libsqlite3-0_3.46.1-9_s390x.deb ... 109s Unpacking libsqlite3-0:s390x (3.46.1-9) over (3.46.1-8) ... 109s Preparing to unpack .../1-inetutils-telnet_2%3a2.7-2ubuntu1_s390x.deb ... 109s Unpacking inetutils-telnet (2:2.7-2ubuntu1) over (2:2.6-4ubuntu1) ... 109s Preparing to unpack .../2-telnet_0.17+2.7-2ubuntu1_all.deb ... 109s Unpacking telnet (0.17+2.7-2ubuntu1) over (0.17+2.6-4ubuntu1) ... 109s Preparing to unpack .../3-libsgutils2-1.48_1.48-3ubuntu2_s390x.deb ... 109s Unpacking libsgutils2-1.48:s390x (1.48-3ubuntu2) over (1.48-3ubuntu1) ... 110s Preparing to unpack .../4-sg3-utils_1.48-3ubuntu2_s390x.deb ... 110s Unpacking sg3-utils (1.48-3ubuntu2) over (1.48-3ubuntu1) ... 110s Preparing to unpack .../5-sg3-utils-udev_1.48-3ubuntu2_all.deb ... 110s Unpacking sg3-utils-udev (1.48-3ubuntu2) over (1.48-3ubuntu1) ... 111s Setting up libsqlite3-0:s390x (3.46.1-9) ... 111s Setting up inetutils-telnet (2:2.7-2ubuntu1) ... 111s Setting up libsgutils2-1.48:s390x (1.48-3ubuntu2) ... 111s Setting up bzip2 (1.0.8-6build2) ... 111s Setting up libatomic1:s390x (15.2.0-12ubuntu1) ... 111s Setting up libstdc++6:s390x (15.2.0-12ubuntu1) ... 111s Setting up sg3-utils (1.48-3ubuntu2) ... 111s Setting up telnet (0.17+2.7-2ubuntu1) ... 111s Setting up sg3-utils-udev (1.48-3ubuntu2) ... 112s update-initramfs: deferring update (trigger activated) 112s Processing triggers for libc-bin (2.42-2ubuntu4) ... 112s Processing triggers for man-db (2.13.1-1) ... 116s Processing triggers for initramfs-tools (0.150ubuntu7) ... 116s update-initramfs: Generating /boot/initrd.img-6.18.0-9-generic 138s Using config file '/etc/zipl.conf' 138s Building bootmap in '/boot' 138s Adding IPL section 'ubuntu' (default) 138s Preparing boot device: vda. 138s Done. 139s autopkgtest [08:45:43]: upgrading testbed (apt dist-upgrade and autopurge) 140s Reading package lists... 140s Building dependency tree... 140s Reading state information... 141s Calculating upgrade... 142s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 142s Reading package lists... 143s Building dependency tree... 143s Reading state information... 143s Solving dependencies... 144s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 144s autopkgtest [08:45:48]: rebooting testbed after setup commands that affected boot 186s autopkgtest [08:46:30]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP Mon Jan 12 15:39:23 UTC 2026 190s autopkgtest [08:46:34]: @@@@@@@@@@@@@@@@@@@@ apt-source backuppc 192s Get:1 http://ftpmaster.internal/ubuntu resolute/main backuppc 4.4.0-11 (dsc) [2169 B] 192s Get:2 http://ftpmaster.internal/ubuntu resolute/main backuppc 4.4.0-11 (tar) [657 kB] 192s Get:3 http://ftpmaster.internal/ubuntu resolute/main backuppc 4.4.0-11 (diff) [37.9 kB] 194s gpgv: Signature made Sun Mar 30 09:20:11 2025 UTC 194s gpgv: using RSA key FDDD0CFF3864277630A2186C9164FA1D17BD5D36 194s gpgv: Can't check signature: No public key 194s dpkg-source: warning: cannot verify inline signature for ./backuppc_4.4.0-11.dsc: no acceptable signature found 195s autopkgtest [08:46:39]: testing package backuppc version 4.4.0-11 195s autopkgtest [08:46:39]: build not needed 196s autopkgtest [08:46:40]: test smb-backup: preparing testbed 197s Reading package lists... 198s Building dependency tree... 198s Reading state information... 198s Solving dependencies... 199s The following NEW packages will be installed: 199s apache2 apache2-bin apache2-data apache2-utils backuppc backuppc-rsync 199s libapr1t64 libaprutil1-dbd-sqlite3 libaprutil1-ldap libaprutil1t64 199s libarchive-zip-perl libavahi-client3 libavahi-common-data libavahi-common3 199s libbackuppc-xs-perl libcgi-pm-perl libcups2t64 libfile-listing-perl 199s libhtml-parser-perl libhtml-tagset-perl libhttp-date-perl libldb2 199s liblua5.4-0 libnsl2 libsmbclient0 libtalloc2 libtdb1 libtevent0t64 199s libtime-parsedate-perl libtimedate-perl liburi-perl liburing2 libwbclient0 199s postfix samba samba-common samba-common-bin samba-libs smbclient 199s 0 upgraded, 39 newly installed, 0 to remove and 0 not upgraded. 199s Need to get 15.8 MB of archives. 199s After this operation, 60.3 MB of additional disk space will be used. 199s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x libapr1t64 s390x 1.7.6-3 [114 kB] 199s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x libaprutil1t64 s390x 1.6.3-3ubuntu3 [98.9 kB] 199s Get:3 http://ftpmaster.internal/ubuntu resolute/main s390x libaprutil1-dbd-sqlite3 s390x 1.6.3-3ubuntu3 [11.7 kB] 199s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x libaprutil1-ldap s390x 1.6.3-3ubuntu3 [9104 B] 199s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x liblua5.4-0 s390x 5.4.8-1 [173 kB] 199s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x apache2-bin s390x 2.4.65-3ubuntu1 [1410 kB] 199s Get:7 http://ftpmaster.internal/ubuntu resolute/main s390x apache2-data all 2.4.65-3ubuntu1 [163 kB] 199s Get:8 http://ftpmaster.internal/ubuntu resolute/main s390x apache2-utils s390x 2.4.65-3ubuntu1 [102 kB] 199s Get:9 http://ftpmaster.internal/ubuntu resolute/main s390x apache2 s390x 2.4.65-3ubuntu1 [93.4 kB] 199s Get:10 http://ftpmaster.internal/ubuntu resolute/main s390x backuppc-rsync s390x 3.1.3.0-5 [273 kB] 199s Get:11 http://ftpmaster.internal/ubuntu resolute/main s390x libnsl2 s390x 1.3.0-3build4 [44.0 kB] 199s Get:12 http://ftpmaster.internal/ubuntu resolute/main s390x postfix s390x 3.10.6-1ubuntu1 [1319 kB] 199s Get:13 http://ftpmaster.internal/ubuntu resolute/main s390x libarchive-zip-perl all 1.68-1 [90.2 kB] 199s Get:14 http://ftpmaster.internal/ubuntu resolute/main s390x libbackuppc-xs-perl s390x 0.62-2build6 [70.6 kB] 199s Get:15 http://ftpmaster.internal/ubuntu resolute/main s390x libhtml-tagset-perl all 3.24-1 [14.1 kB] 199s Get:16 http://ftpmaster.internal/ubuntu resolute/main s390x liburi-perl all 5.34-2build1 [100 kB] 199s Get:17 http://ftpmaster.internal/ubuntu resolute/main s390x libhtml-parser-perl s390x 3.83-1build1 [87.8 kB] 199s Get:18 http://ftpmaster.internal/ubuntu resolute/main s390x libcgi-pm-perl all 4.71-1build1 [185 kB] 199s Get:19 http://ftpmaster.internal/ubuntu resolute/main s390x libtimedate-perl all 2.3300-2 [34.0 kB] 199s Get:20 http://ftpmaster.internal/ubuntu resolute/main s390x libhttp-date-perl all 6.06-1 [10.2 kB] 199s Get:21 http://ftpmaster.internal/ubuntu resolute/main s390x libfile-listing-perl all 6.16-1 [11.3 kB] 199s Get:22 http://ftpmaster.internal/ubuntu resolute/main s390x libtime-parsedate-perl all 2015.103-5 [27.9 kB] 199s Get:23 http://ftpmaster.internal/ubuntu resolute/main s390x backuppc s390x 4.4.0-11 [518 kB] 199s Get:24 http://ftpmaster.internal/ubuntu resolute/main s390x samba-common all 2:4.23.4+dfsg-1ubuntu1 [74.4 kB] 199s Get:25 http://ftpmaster.internal/ubuntu resolute/main s390x libavahi-common-data s390x 0.8-17ubuntu2 [31.5 kB] 199s Get:26 http://ftpmaster.internal/ubuntu resolute/main s390x libavahi-common3 s390x 0.8-17ubuntu2 [23.3 kB] 199s Get:27 http://ftpmaster.internal/ubuntu resolute/main s390x libavahi-client3 s390x 0.8-17ubuntu2 [26.2 kB] 199s Get:28 http://ftpmaster.internal/ubuntu resolute/main s390x libcups2t64 s390x 2.4.16-1ubuntu1 [281 kB] 199s Get:29 http://ftpmaster.internal/ubuntu resolute/main s390x libtalloc2 s390x 2:2.4.3+samba4.23.4+dfsg-1ubuntu1 [77.6 kB] 199s Get:30 http://ftpmaster.internal/ubuntu resolute/main s390x libtdb1 s390x 2:1.4.14+samba4.23.4+dfsg-1ubuntu1 [99.2 kB] 199s Get:31 http://ftpmaster.internal/ubuntu resolute/main s390x libtevent0t64 s390x 2:0.17.1+samba4.23.4+dfsg-1ubuntu1 [41.7 kB] 199s Get:32 http://ftpmaster.internal/ubuntu resolute/main s390x libldb2 s390x 2:2.11.0+samba4.23.4+dfsg-1ubuntu1 [152 kB] 199s Get:33 http://ftpmaster.internal/ubuntu resolute/main s390x libwbclient0 s390x 2:4.23.4+dfsg-1ubuntu1 [84.6 kB] 199s Get:34 http://ftpmaster.internal/ubuntu resolute/main s390x samba-libs s390x 2:4.23.4+dfsg-1ubuntu1 [6703 kB] 200s Get:35 http://ftpmaster.internal/ubuntu resolute/main s390x samba-common-bin s390x 2:4.23.4+dfsg-1ubuntu1 [1407 kB] 200s Get:36 http://ftpmaster.internal/ubuntu resolute/main s390x liburing2 s390x 2.11-1 [26.3 kB] 200s Get:37 http://ftpmaster.internal/ubuntu resolute/main s390x samba s390x 2:4.23.4+dfsg-1ubuntu1 [1238 kB] 200s Get:38 http://ftpmaster.internal/ubuntu resolute/main s390x libsmbclient0 s390x 2:4.23.4+dfsg-1ubuntu1 [65.4 kB] 200s Get:39 http://ftpmaster.internal/ubuntu resolute/main s390x smbclient s390x 2:4.23.4+dfsg-1ubuntu1 [493 kB] 202s Preconfiguring packages ... 203s Fetched 15.8 MB in 1s (14.7 MB/s) 203s Selecting previously unselected package libapr1t64:s390x. 205s (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 ... 87720 files and directories currently installed.) 205s Preparing to unpack .../00-libapr1t64_1.7.6-3_s390x.deb ... 205s Unpacking libapr1t64:s390x (1.7.6-3) ... 205s Selecting previously unselected package libaprutil1t64:s390x. 205s Preparing to unpack .../01-libaprutil1t64_1.6.3-3ubuntu3_s390x.deb ... 205s Unpacking libaprutil1t64:s390x (1.6.3-3ubuntu3) ... 205s Selecting previously unselected package libaprutil1-dbd-sqlite3:s390x. 205s Preparing to unpack .../02-libaprutil1-dbd-sqlite3_1.6.3-3ubuntu3_s390x.deb ... 205s Unpacking libaprutil1-dbd-sqlite3:s390x (1.6.3-3ubuntu3) ... 205s Selecting previously unselected package libaprutil1-ldap:s390x. 205s Preparing to unpack .../03-libaprutil1-ldap_1.6.3-3ubuntu3_s390x.deb ... 205s Unpacking libaprutil1-ldap:s390x (1.6.3-3ubuntu3) ... 205s Selecting previously unselected package liblua5.4-0:s390x. 205s Preparing to unpack .../04-liblua5.4-0_5.4.8-1_s390x.deb ... 205s Unpacking liblua5.4-0:s390x (5.4.8-1) ... 205s Selecting previously unselected package apache2-bin. 205s Preparing to unpack .../05-apache2-bin_2.4.65-3ubuntu1_s390x.deb ... 205s Unpacking apache2-bin (2.4.65-3ubuntu1) ... 206s Selecting previously unselected package apache2-data. 206s Preparing to unpack .../06-apache2-data_2.4.65-3ubuntu1_all.deb ... 206s Unpacking apache2-data (2.4.65-3ubuntu1) ... 206s Selecting previously unselected package apache2-utils. 206s Preparing to unpack .../07-apache2-utils_2.4.65-3ubuntu1_s390x.deb ... 206s Unpacking apache2-utils (2.4.65-3ubuntu1) ... 206s Selecting previously unselected package apache2. 206s Preparing to unpack .../08-apache2_2.4.65-3ubuntu1_s390x.deb ... 206s Unpacking apache2 (2.4.65-3ubuntu1) ... 206s Selecting previously unselected package backuppc-rsync. 206s Preparing to unpack .../09-backuppc-rsync_3.1.3.0-5_s390x.deb ... 206s Unpacking backuppc-rsync (3.1.3.0-5) ... 206s Selecting previously unselected package libnsl2:s390x. 206s Preparing to unpack .../10-libnsl2_1.3.0-3build4_s390x.deb ... 206s Unpacking libnsl2:s390x (1.3.0-3build4) ... 206s Selecting previously unselected package postfix. 206s Preparing to unpack .../11-postfix_3.10.6-1ubuntu1_s390x.deb ... 206s Unpacking postfix (3.10.6-1ubuntu1) ... 206s Selecting previously unselected package libarchive-zip-perl. 206s Preparing to unpack .../12-libarchive-zip-perl_1.68-1_all.deb ... 206s Unpacking libarchive-zip-perl (1.68-1) ... 206s Selecting previously unselected package libbackuppc-xs-perl:s390x. 206s Preparing to unpack .../13-libbackuppc-xs-perl_0.62-2build6_s390x.deb ... 206s Unpacking libbackuppc-xs-perl:s390x (0.62-2build6) ... 206s Selecting previously unselected package libhtml-tagset-perl. 206s Preparing to unpack .../14-libhtml-tagset-perl_3.24-1_all.deb ... 206s Unpacking libhtml-tagset-perl (3.24-1) ... 206s Selecting previously unselected package liburi-perl. 206s Preparing to unpack .../15-liburi-perl_5.34-2build1_all.deb ... 206s Unpacking liburi-perl (5.34-2build1) ... 207s Selecting previously unselected package libhtml-parser-perl:s390x. 207s Preparing to unpack .../16-libhtml-parser-perl_3.83-1build1_s390x.deb ... 207s Unpacking libhtml-parser-perl:s390x (3.83-1build1) ... 207s Selecting previously unselected package libcgi-pm-perl. 207s Preparing to unpack .../17-libcgi-pm-perl_4.71-1build1_all.deb ... 207s Unpacking libcgi-pm-perl (4.71-1build1) ... 207s Selecting previously unselected package libtimedate-perl. 207s Preparing to unpack .../18-libtimedate-perl_2.3300-2_all.deb ... 207s Unpacking libtimedate-perl (2.3300-2) ... 207s Selecting previously unselected package libhttp-date-perl. 207s Preparing to unpack .../19-libhttp-date-perl_6.06-1_all.deb ... 207s Unpacking libhttp-date-perl (6.06-1) ... 207s Selecting previously unselected package libfile-listing-perl. 207s Preparing to unpack .../20-libfile-listing-perl_6.16-1_all.deb ... 207s Unpacking libfile-listing-perl (6.16-1) ... 207s Selecting previously unselected package libtime-parsedate-perl. 207s Preparing to unpack .../21-libtime-parsedate-perl_2015.103-5_all.deb ... 207s Unpacking libtime-parsedate-perl (2015.103-5) ... 207s Selecting previously unselected package backuppc. 207s Preparing to unpack .../22-backuppc_4.4.0-11_s390x.deb ... 207s Unpacking backuppc (4.4.0-11) ... 207s Selecting previously unselected package samba-common. 207s Preparing to unpack .../23-samba-common_2%3a4.23.4+dfsg-1ubuntu1_all.deb ... 208s Unpacking samba-common (2:4.23.4+dfsg-1ubuntu1) ... 208s Selecting previously unselected package libavahi-common-data:s390x. 208s Preparing to unpack .../24-libavahi-common-data_0.8-17ubuntu2_s390x.deb ... 208s Unpacking libavahi-common-data:s390x (0.8-17ubuntu2) ... 208s Selecting previously unselected package libavahi-common3:s390x. 208s Preparing to unpack .../25-libavahi-common3_0.8-17ubuntu2_s390x.deb ... 208s Unpacking libavahi-common3:s390x (0.8-17ubuntu2) ... 208s Selecting previously unselected package libavahi-client3:s390x. 208s Preparing to unpack .../26-libavahi-client3_0.8-17ubuntu2_s390x.deb ... 208s Unpacking libavahi-client3:s390x (0.8-17ubuntu2) ... 208s Selecting previously unselected package libcups2t64:s390x. 208s Preparing to unpack .../27-libcups2t64_2.4.16-1ubuntu1_s390x.deb ... 208s Unpacking libcups2t64:s390x (2.4.16-1ubuntu1) ... 208s Selecting previously unselected package libtalloc2:s390x. 208s Preparing to unpack .../28-libtalloc2_2%3a2.4.3+samba4.23.4+dfsg-1ubuntu1_s390x.deb ... 208s Unpacking libtalloc2:s390x (2:2.4.3+samba4.23.4+dfsg-1ubuntu1) ... 208s Selecting previously unselected package libtdb1:s390x. 208s Preparing to unpack .../29-libtdb1_2%3a1.4.14+samba4.23.4+dfsg-1ubuntu1_s390x.deb ... 208s Unpacking libtdb1:s390x (2:1.4.14+samba4.23.4+dfsg-1ubuntu1) ... 208s Selecting previously unselected package libtevent0t64:s390x. 208s Preparing to unpack .../30-libtevent0t64_2%3a0.17.1+samba4.23.4+dfsg-1ubuntu1_s390x.deb ... 208s Unpacking libtevent0t64:s390x (2:0.17.1+samba4.23.4+dfsg-1ubuntu1) ... 208s Selecting previously unselected package libldb2:s390x. 208s Preparing to unpack .../31-libldb2_2%3a2.11.0+samba4.23.4+dfsg-1ubuntu1_s390x.deb ... 208s Unpacking libldb2:s390x (2:2.11.0+samba4.23.4+dfsg-1ubuntu1) ... 208s Selecting previously unselected package libwbclient0:s390x. 208s Preparing to unpack .../32-libwbclient0_2%3a4.23.4+dfsg-1ubuntu1_s390x.deb ... 208s Unpacking libwbclient0:s390x (2:4.23.4+dfsg-1ubuntu1) ... 208s Selecting previously unselected package samba-libs:s390x. 208s Preparing to unpack .../33-samba-libs_2%3a4.23.4+dfsg-1ubuntu1_s390x.deb ... 208s Unpacking samba-libs:s390x (2:4.23.4+dfsg-1ubuntu1) ... 209s Selecting previously unselected package samba-common-bin. 209s Preparing to unpack .../34-samba-common-bin_2%3a4.23.4+dfsg-1ubuntu1_s390x.deb ... 209s Unpacking samba-common-bin (2:4.23.4+dfsg-1ubuntu1) ... 209s Selecting previously unselected package liburing2:s390x. 209s Preparing to unpack .../35-liburing2_2.11-1_s390x.deb ... 209s Unpacking liburing2:s390x (2.11-1) ... 209s Selecting previously unselected package samba. 209s Preparing to unpack .../36-samba_2%3a4.23.4+dfsg-1ubuntu1_s390x.deb ... 209s Unpacking samba (2:4.23.4+dfsg-1ubuntu1) ... 210s Selecting previously unselected package libsmbclient0:s390x. 210s Preparing to unpack .../37-libsmbclient0_2%3a4.23.4+dfsg-1ubuntu1_s390x.deb ... 210s Unpacking libsmbclient0:s390x (2:4.23.4+dfsg-1ubuntu1) ... 210s Selecting previously unselected package smbclient. 210s Preparing to unpack .../38-smbclient_2%3a4.23.4+dfsg-1ubuntu1_s390x.deb ... 210s Unpacking smbclient (2:4.23.4+dfsg-1ubuntu1) ... 210s Setting up libarchive-zip-perl (1.68-1) ... 210s Setting up libtdb1:s390x (2:1.4.14+samba4.23.4+dfsg-1ubuntu1) ... 210s Setting up libhtml-tagset-perl (3.24-1) ... 210s Setting up samba-common (2:4.23.4+dfsg-1ubuntu1) ... 211s Creating config file /etc/samba/smb.conf with new version 212s Setting up libwbclient0:s390x (2:4.23.4+dfsg-1ubuntu1) ... 212s Setting up libtalloc2:s390x (2:2.4.3+samba4.23.4+dfsg-1ubuntu1) ... 212s Setting up backuppc-rsync (3.1.3.0-5) ... 212s Setting up libavahi-common-data:s390x (0.8-17ubuntu2) ... 212s Setting up libtimedate-perl (2.3300-2) ... 212s Setting up libtime-parsedate-perl (2015.103-5) ... 212s Setting up libapr1t64:s390x (1.7.6-3) ... 212s Setting up libnsl2:s390x (1.3.0-3build4) ... 212s Setting up liblua5.4-0:s390x (5.4.8-1) ... 212s Setting up apache2-data (2.4.65-3ubuntu1) ... 212s Setting up liburing2:s390x (2.11-1) ... 212s Setting up libbackuppc-xs-perl:s390x (0.62-2build6) ... 212s Setting up liburi-perl (5.34-2build1) ... 212s Setting up libtevent0t64:s390x (2:0.17.1+samba4.23.4+dfsg-1ubuntu1) ... 212s Setting up libaprutil1t64:s390x (1.6.3-3ubuntu3) ... 212s Setting up libhttp-date-perl (6.06-1) ... 212s Setting up libfile-listing-perl (6.16-1) ... 212s Setting up libavahi-common3:s390x (0.8-17ubuntu2) ... 212s Setting up libaprutil1-ldap:s390x (1.6.3-3ubuntu3) ... 212s Setting up libaprutil1-dbd-sqlite3:s390x (1.6.3-3ubuntu3) ... 212s Setting up postfix (3.10.6-1ubuntu1) ... 213s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 216s Creating /etc/postfix/dynamicmaps.cf 216s setting myhostname: autopkgtest.local 216s setting alias maps 216s setting alias database 216s changing /etc/mailname to autopkgtest.local 216s setting myorigin 216s setting destinations: $myhostname, autopkgtest.local, localhost.local, , localhost 217s setting relayhost: 217s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 217s clearing mailbox_command 217s setting mailbox_size_limit: 0 217s setting recipient_delimiter: + 218s setting inet_interfaces: all 220s setting inet_protocols: all 220s /etc/aliases does not exist, creating it. 220s WARNING: /etc/aliases exists, but does not have a root alias. 220s 220s Postfix (main.cf) is now set up with a default configuration. If you need to 220s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 220s Postfix configuration values, see postconf(1). 220s 220s After modifying main.cf, be sure to run 'systemctl reload postfix'. 220s 222s Setting up libhtml-parser-perl:s390x (3.83-1build1) ... 222s Setting up libldb2:s390x (2:2.11.0+samba4.23.4+dfsg-1ubuntu1) ... 222s Setting up libavahi-client3:s390x (0.8-17ubuntu2) ... 222s Setting up apache2-utils (2.4.65-3ubuntu1) ... 222s Setting up libcgi-pm-perl (4.71-1build1) ... 222s Setting up samba-libs:s390x (2:4.23.4+dfsg-1ubuntu1) ... 222s Setting up apache2-bin (2.4.65-3ubuntu1) ... 222s Setting up libsmbclient0:s390x (2:4.23.4+dfsg-1ubuntu1) ... 222s Setting up libcups2t64:s390x (2.4.16-1ubuntu1) ... 222s Setting up apache2 (2.4.65-3ubuntu1) ... 222s Enabling module mpm_event. 223s Enabling module authz_core. 223s Enabling module authz_host. 223s Enabling module authn_core. 223s Enabling module auth_basic. 223s Enabling module access_compat. 223s Enabling module authn_file. 224s Enabling module authz_user. 224s Enabling module alias. 224s Enabling module dir. 224s Enabling module autoindex. 225s Enabling module env. 225s Enabling module mime. 225s Enabling module negotiation. 225s Enabling module setenvif. 226s Enabling module filter. 226s Enabling module deflate. 226s Enabling module status. 226s Enabling module reqtimeout. 226s Enabling conf charset. 227s Enabling conf localized-error-pages. 227s Enabling conf other-vhosts-access-log. 227s Enabling conf security. 227s Enabling conf serve-cgi-bin. 227s Enabling site 000-default. 230s Created symlink '/etc/systemd/system/multi-user.target.wants/apache2.service' → '/usr/lib/systemd/system/apache2.service'. 233s Created symlink '/etc/systemd/system/multi-user.target.wants/apache-htcacheclean.service' → '/usr/lib/systemd/system/apache-htcacheclean.service'. 237s Setting up backuppc (4.4.0-11) ... 237s 237s apache2_invoke: Enable configuration backuppc.conf 238s Adding password for user backuppc 238s Considering dependency authn_core for auth_basic: 238s Module authn_core already enabled 238s Module auth_basic already enabled 239s Considering dependency authz_core for authz_groupfile: 239s Module authz_core already enabled 239s Enabling module authz_groupfile. 239s To activate the new configuration, you need to run: 239s systemctl restart apache2 239s Module authn_file already enabled 239s Considering dependency authz_core for authz_user: 239s Module authz_core already enabled 239s Module authz_user already enabled 239s Your MPM seems to be threaded. Selecting cgid instead of cgi. 239s Enabling module cgid. 239s To activate the new configuration, you need to run: 239s systemctl restart apache2 239s Creating config file /etc/backuppc/config.pl with new version 242s Created symlink '/etc/systemd/system/multi-user.target.wants/backuppc.service' → '/usr/lib/systemd/system/backuppc.service'. 244s Setting up smbclient (2:4.23.4+dfsg-1ubuntu1) ... 244s Setting up samba-common-bin (2:4.23.4+dfsg-1ubuntu1) ... 244s Setting up samba (2:4.23.4+dfsg-1ubuntu1) ... 246s Created symlink '/etc/systemd/system/smb.service' → '/usr/lib/systemd/system/smbd.service'. 246s Created symlink '/etc/systemd/system/multi-user.target.wants/smbd.service' → '/usr/lib/systemd/system/smbd.service'. 249s Created symlink '/etc/systemd/system/nmb.service' → '/usr/lib/systemd/system/nmbd.service'. 249s Created symlink '/etc/systemd/system/multi-user.target.wants/nmbd.service' → '/usr/lib/systemd/system/nmbd.service'. 250s Processing triggers for rsyslog (8.2512.0-1ubuntu2) ... 251s Processing triggers for ufw (0.36.2-9build1) ... 251s Processing triggers for man-db (2.13.1-1) ... 258s Processing triggers for libc-bin (2.42-2ubuntu4) ... 258s Processing triggers for postfix (3.10.6-1ubuntu1) ... 258s Restarting postfix 262s autopkgtest [08:47:46]: test smb-backup: [----------------------- 262s net.ipv4.ping_group_range = 0 1000 262s Populating share path 263s Adding samba share 263s Adding [public] share 263s Configuring backuppc 263s Performing a full backup 264s Backup type: type = full, needs_full = 1, needs_incr = 1, lastFullTime = 0, opts{f} = 1, opts{i} = , opts{F} = 264s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 264s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 264s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.011 264s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.0.z created 2026-01-30 08:49:09 264s Backup prep: type = full, case = 1, inPlace = 1, doDuplicate = 0, newBkupNum = 0, newBkupIdx = 0, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 264s __bpc_progress_state__ backup share "public" 264s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 264s full backup started for share public 264s started full dump, share=public 264s Xfer PIDs are now 3656,3655 264s xferPids 3656,3655 264s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 264s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 264s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 264s __bpc_progress_fileCnt__ 0 264s tarmode is now full, system, hidden, noreset, noverbose 264s new 644 0/0 480434 tmp.fZzcLl 265s new 644 0/0 480434 tmp.NW4LrQ 265s new 644 0/0 480434 tmp.A1e2LS 265s new 644 0/0 480434 tmp.28woN4 265s new 644 0/0 480434 tmp.IpjcGL 265s new 644 0/0 480434 tmp.iP9mbq 265s new 644 0/0 480434 tmp.F4Gy4w 265s new 644 0/0 480434 tmp.HYIx5U 265s tar: dumped 10 files and 0 directories 265s Total bytes written: 4804340 (4.5 MiB/s) 265s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 266s new 644 0/0 480434 tmp.XaBydP 266s new 644 0/0 480434 tmp.7sysJs 266s __bpc_progress_fileCnt__ 10 266s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377837 sizeNewComp, 13 inodeLast 266s Xfer PIDs are now 266s xferPids 266s full backup 0 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 266s BackupExpire: cntFull = 1, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 1.15740740740741e-05 266s __bpc_progress_state__ fsck 266s Running BackupPC_refCountUpdate -h localhost on localhost 266s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 266s Xfer PIDs are now 3667 266s xferPids 3667 266s BackupPC_refCountUpdate: doing fsck on localhost #0 (full) since $ConfRefCntFsck == 1 266s __bpc_progress_state__ refCnt #0 266s __bpc_progress_state__ cntUpdate #0 266s __bpc_progress_state__ rename #0 266s __bpc_progress_state__ sumUpdate 266s __bpc_progress_fileCnt__ 0/128 266s __bpc_progress_fileCnt__ 8/128 266s __bpc_progress_fileCnt__ 16/128 266s __bpc_progress_fileCnt__ 24/128 266s __bpc_progress_fileCnt__ 32/128 266s __bpc_progress_fileCnt__ 40/128 266s __bpc_progress_fileCnt__ 48/128 266s __bpc_progress_fileCnt__ 56/128 266s __bpc_progress_fileCnt__ 64/128 266s __bpc_progress_fileCnt__ 72/128 266s __bpc_progress_fileCnt__ 80/128 266s __bpc_progress_fileCnt__ 88/128 266s __bpc_progress_fileCnt__ 96/128 266s __bpc_progress_fileCnt__ 104/128 266s __bpc_progress_fileCnt__ 112/128 266s __bpc_progress_fileCnt__ 120/128 266s __bpc_progress_state__ rename total 266s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 266s Xfer PIDs are now 266s xferPids 266s Finished BackupPC_refCountUpdate (running time: 0 sec) 266s Xfer PIDs are now 266s xferPids 266s full backup complete 266s OK: Full backup succeeded 266s Changing share content and performing an incremental backup 268s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1769762949, opts{f} = , opts{i} = 1, opts{F} = 268s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 268s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 268s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.010 268s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.1.z created 2026-01-30 08:49:12 268s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 1, newBkupIdx = 1, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 268s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 268s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 268s Xfer PIDs are now 3738 268s xferPids 3738 268s __bpc_progress_state__ copy #0 -> #1 268s Copying backup #0 to #1 268s __bpc_progress_fileCnt__ 3 dirs 268s Xfer PIDs are now 3741,3738 268s xferPids 3741,3738 268s __bpc_progress_state__ sumUpdate 268s __bpc_progress_fileCnt__ 0/128 268s __bpc_progress_fileCnt__ 8/128 268s __bpc_progress_fileCnt__ 16/128 268s __bpc_progress_fileCnt__ 24/128 268s __bpc_progress_fileCnt__ 32/128 268s __bpc_progress_fileCnt__ 40/128 268s __bpc_progress_fileCnt__ 48/128 268s __bpc_progress_fileCnt__ 56/128 268s __bpc_progress_fileCnt__ 64/128 268s __bpc_progress_fileCnt__ 72/128 268s __bpc_progress_fileCnt__ 80/128 268s __bpc_progress_fileCnt__ 88/128 268s __bpc_progress_fileCnt__ 96/128 268s __bpc_progress_fileCnt__ 104/128 268s __bpc_progress_fileCnt__ 112/128 268s __bpc_progress_fileCnt__ 120/128 268s __bpc_progress_state__ rename total 268s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 268s Xfer PIDs are now 3738 268s xferPids 3738 268s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 268s Finished BackupPC_backupDuplicate (running time: 1 sec) 268s __bpc_progress_state__ backup share "public" 268s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 268s incr backup started back to 2026-01-30 07:49:09 (backup #0) for share public 268s started incr dump, share=public 268s Xfer PIDs are now 3745,3744 268s xferPids 3745,3744 268s 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 - 269s tar:1306 Getting files newer than Fri Jan 30 07:49:09 2026 269s tarmode is now full, system, hidden, noreset, noverbose 269s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 269s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 269s __bpc_progress_fileCnt__ 0 269s new 644 0/0 480434 tmp.fr5qYc 269s new 644 0/0 480434 tmp.l4hlqe 269s new 644 0/0 480434 tmp.VSS1Pq 269s new 644 0/0 480434 tmp.bykfc3 269s same 644 0/0 480434 tmp.fZzcLl 269s same 644 0/0 480434 tmp.NW4LrQ 269s new 644 0/0 480434 tmp.UKsjg3 269s new 644 0/0 480434 tmp.p79xlr 269s new 644 0/0 480434 tmp.VW4siJ 269s same 644 0/0 480434 tmp.A1e2LS 269s same 644 0/0 480434 tmp.28woN4 269s same 644 0/0 480434 tmp.IpjcGL 269s same 644 0/0 480434 tmp.iP9mbq 269s new 644 0/0 480434 tmp.RuZ6VH 270s same 644 0/0 480434 tmp.F4Gy4w 270s same 644 0/0 480434 tmp.HYIx5U 270s same 644 0/0 480434 tmp.XaBydP 270s same 644 0/0 480434 tmp.7sysJs 270s new 644 0/0 480434 tmp.qpTYLC 270s tar: dumped 20 files and 0 directories 270s Total bytes written: 9608680 (9.5 MiB/s) 270s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 270s new 644 0/0 480434 tmp.NPcqku 270s __bpc_progress_fileCnt__ 20 270s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377837 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377852 sizeNewComp, 24 inodeLast 270s Xfer PIDs are now 270s xferPids 270s incr backup 1 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 270s BackupExpire: cntFull = 2, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 5.78703703703704e-05 270s __bpc_progress_state__ fsck 270s Running BackupPC_refCountUpdate -h localhost on localhost 270s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 270s Xfer PIDs are now 3751 270s xferPids 3751 270s __bpc_progress_state__ cntUpdate #1 270s __bpc_progress_state__ rename #1 270s __bpc_progress_state__ sumUpdate 270s __bpc_progress_fileCnt__ 0/128 270s __bpc_progress_fileCnt__ 8/128 270s __bpc_progress_fileCnt__ 16/128 270s __bpc_progress_fileCnt__ 24/128 270s __bpc_progress_fileCnt__ 32/128 270s __bpc_progress_fileCnt__ 40/128 270s __bpc_progress_fileCnt__ 48/128 270s __bpc_progress_fileCnt__ 56/128 270s __bpc_progress_fileCnt__ 64/128 270s __bpc_progress_fileCnt__ 72/128 270s __bpc_progress_fileCnt__ 80/128 270s __bpc_progress_fileCnt__ 88/128 270s __bpc_progress_fileCnt__ 96/128 270s __bpc_progress_fileCnt__ 104/128 270s __bpc_progress_fileCnt__ 112/128 270s __bpc_progress_fileCnt__ 120/128 270s __bpc_progress_state__ rename total 270s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 270s Xfer PIDs are now 270s xferPids 271s Finished BackupPC_refCountUpdate (running time: 1 sec) 271s Xfer PIDs are now 271s xferPids 271s incr backup complete 271s OK: Incremental backup succeeded 271s Done. 271s autopkgtest [08:47:55]: test smb-backup: -----------------------] 272s smb-backup PASS 272s autopkgtest [08:47:56]: test smb-backup: - - - - - - - - - - results - - - - - - - - - - 273s autopkgtest [08:47:57]: test authenticated-smb-backup: preparing testbed 273s Reading package lists... 275s Building dependency tree... 275s Reading state information... 275s Solving dependencies... 276s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 277s autopkgtest [08:48:01]: test authenticated-smb-backup: [----------------------- 277s Populating share path 278s Adding samba share 278s Adding [private] share 278s Creating user 278s Creating a local and samba user called smbtest3932 279s Setting samba password for the smbtest3932 user 279s Added user smbtest3932. 279s Configuring backuppc 279s Performing a full backup 279s Backup type: type = full, needs_full = , needs_incr = , lastFullTime = 1769762949, opts{f} = 1, opts{i} = , opts{F} = 279s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 279s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 279s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.003 279s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.2.z created 2026-01-30 08:49:24 279s Backup prep: type = full, case = 4, inPlace = 0, doDuplicate = 0, newBkupNum = 2, newBkupIdx = 2, lastBkupNum = 1, lastBkupIdx = 1 (FillCycle = 0, noFillCnt = 0) 279s __bpc_progress_state__ backup share "private" 279s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest3932 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 279s full backup started for share private 279s started full dump, share=private 279s Xfer PIDs are now 4024,4023 279s xferPids 4024,4023 279s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest3932 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 279s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 279s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 279s tarmode is now full, system, hidden, noreset, noverbose 279s __bpc_progress_fileCnt__ 0 279s new 644 0/0 480434 tmp.XjfT1s 279s new 644 0/0 480434 tmp.1GGPpl 279s new 644 0/0 480434 tmp.axqtgQ 279s new 644 0/0 480434 tmp.mLrCMx 279s new 644 0/0 480434 tmp.kgGglb 279s new 644 0/0 480434 tmp.b28ACP 279s new 644 0/0 480434 tmp.A6dFsZ 279s new 644 0/0 480434 tmp.aYiG6V 279s tar: dumped 10 files and 0 directories 279s Total bytes written: 4804340 (16.2 MiB/s) 279s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 279s new 644 0/0 480434 tmp.6FfZJs 279s new 644 0/0 480434 tmp.9ME7fW 279s __bpc_progress_fileCnt__ 10 279s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377852 sizeNewComp, 36 inodeLast 279s Xfer PIDs are now 279s xferPids 279s OrphanShareNameClean: removing orphan share public from /var/lib/backuppc/pc/localhost/2 279s __bpc_progress_state__ delete share #2/public 279s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDelete -h localhost -n 2 -l -m -s public / 279s Xfer PIDs are now 4033 279s xferPids 4033 279s BackupPC_backupDelete: removing #2/public/ 279s __bpc_progress_state__ merge #2/public/ -> #1/public/ 279s BackupPC_backupDelete: Merge into backup 1/public/ 279s mergeDir: attr write to /var/lib/backuppc/pc/localhost/1/fpublic//attrib failed (digest was ) 279s bpc_attrib_dirWrite: can't open/create raw /var/lib/backuppc/pc/localhost/1/fpublic for writing 280s Xfer PIDs are now 4036,4033 280s xferPids 4036,4033 280s __bpc_progress_state__ refCnt #1 280s __bpc_progress_state__ cntUpdate #1 280s __bpc_progress_state__ rename #1 280s __bpc_progress_state__ refCnt #2 280s BackupPC_refCountUpdate: localhost #2 inodeLast set to 37 (was 24) 280s __bpc_progress_state__ cntUpdate #2 280s __bpc_progress_state__ rename #2 280s __bpc_progress_state__ sumUpdate 280s __bpc_progress_fileCnt__ 0/128 280s __bpc_progress_fileCnt__ 8/128 280s __bpc_progress_fileCnt__ 16/128 280s __bpc_progress_fileCnt__ 24/128 280s __bpc_progress_fileCnt__ 32/128 280s __bpc_progress_fileCnt__ 40/128 280s __bpc_progress_fileCnt__ 48/128 280s __bpc_progress_fileCnt__ 56/128 280s __bpc_progress_fileCnt__ 64/128 280s __bpc_progress_fileCnt__ 72/128 280s __bpc_progress_fileCnt__ 80/128 280s __bpc_progress_fileCnt__ 88/128 280s __bpc_progress_fileCnt__ 96/128 280s __bpc_progress_fileCnt__ 104/128 280s __bpc_progress_fileCnt__ 112/128 280s __bpc_progress_fileCnt__ 120/128 280s __bpc_progress_state__ rename total 280s BackupPC_refCountUpdate: host localhost got 0 errors (took 1 secs) 280s Xfer PIDs are now 4033 280s xferPids 4033 280s Xfer PIDs are now 280s xferPids 280s Finished BackupPC_backupDelete, status = 0 (running time: 1 sec) 280s Xfer PIDs are now 280s xferPids 280s full backup 2 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 280s BackupExpire: cntFull = 2, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 0.000138888888888889, oldestFull = 0.000185185185185185 280s __bpc_progress_state__ fsck 280s Running BackupPC_refCountUpdate -h localhost on localhost 280s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 280s Xfer PIDs are now 4039 280s xferPids 4039 280s BackupPC_refCountUpdate: doing fsck on localhost #2 (full) since $ConfRefCntFsck == 1 280s __bpc_progress_state__ refCnt #2 280s __bpc_progress_state__ cntUpdate #2 280s __bpc_progress_state__ rename #2 280s __bpc_progress_state__ sumUpdate 280s __bpc_progress_fileCnt__ 0/128 280s __bpc_progress_fileCnt__ 8/128 280s __bpc_progress_fileCnt__ 16/128 280s __bpc_progress_fileCnt__ 24/128 280s __bpc_progress_fileCnt__ 32/128 280s __bpc_progress_fileCnt__ 40/128 280s __bpc_progress_fileCnt__ 48/128 280s __bpc_progress_fileCnt__ 56/128 280s __bpc_progress_fileCnt__ 64/128 280s __bpc_progress_fileCnt__ 72/128 280s __bpc_progress_fileCnt__ 80/128 280s __bpc_progress_fileCnt__ 88/128 280s __bpc_progress_fileCnt__ 96/128 280s __bpc_progress_fileCnt__ 104/128 280s __bpc_progress_fileCnt__ 112/128 280s __bpc_progress_fileCnt__ 120/128 280s __bpc_progress_state__ rename total 280s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 280s Xfer PIDs are now 280s xferPids 280s Finished BackupPC_refCountUpdate (running time: 0 sec) 280s Xfer PIDs are now 280s xferPids 280s full backup complete 280s OK: Full backup succeeded 280s Changing share content and performing an incremental backup 281s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1769762964, opts{f} = , opts{i} = 1, opts{F} = 281s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 281s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 281s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.007 281s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.3.z created 2026-01-30 08:49:26 281s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 3, newBkupIdx = 3, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = 1) 281s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 281s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 281s Xfer PIDs are now 4110 281s xferPids 4110 281s __bpc_progress_state__ copy #2 -> #3 281s Copying backup #2 to #3 281s __bpc_progress_fileCnt__ 3 dirs 281s Xfer PIDs are now 4110,4113 281s xferPids 4110,4113 281s __bpc_progress_state__ sumUpdate 281s __bpc_progress_fileCnt__ 0/128 281s __bpc_progress_fileCnt__ 8/128 281s __bpc_progress_fileCnt__ 16/128 281s __bpc_progress_fileCnt__ 24/128 281s __bpc_progress_fileCnt__ 32/128 281s __bpc_progress_fileCnt__ 40/128 281s __bpc_progress_fileCnt__ 48/128 281s __bpc_progress_fileCnt__ 56/128 281s __bpc_progress_fileCnt__ 64/128 281s __bpc_progress_fileCnt__ 72/128 281s __bpc_progress_fileCnt__ 80/128 281s __bpc_progress_fileCnt__ 88/128 282s __bpc_progress_fileCnt__ 96/128 282s __bpc_progress_fileCnt__ 104/128 282s __bpc_progress_fileCnt__ 112/128 282s __bpc_progress_fileCnt__ 120/128 282s __bpc_progress_state__ rename total 282s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 282s Xfer PIDs are now 4110 282s xferPids 4110 282s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 282s Finished BackupPC_backupDuplicate (running time: 0 sec) 282s __bpc_progress_state__ backup share "private" 282s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest3932 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 282s incr backup started back to 2026-01-30 07:49:24 (backup #2) for share private 282s started incr dump, share=private 282s Xfer PIDs are now 4117,4116 282s xferPids 4117,4116 282s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest3932 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 282s tar:1306 Getting files newer than Fri Jan 30 07:49:24 2026 282s tarmode is now full, system, hidden, noreset, noverbose 282s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 282s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 282s __bpc_progress_fileCnt__ 0 282s new 644 0/0 480434 tmp.JQ5kWa 282s same 644 0/0 480434 tmp.XjfT1s 282s same 644 0/0 480434 tmp.1GGPpl 282s new 644 0/0 480434 tmp.5BiJJN 282s same 644 0/0 480434 tmp.axqtgQ 282s new 644 0/0 480434 tmp.tb7eDK 283s same 644 0/0 480434 tmp.mLrCMx 283s same 644 0/0 480434 tmp.kgGglb 283s new 644 0/0 480434 tmp.NavJx1 283s same 644 0/0 480434 tmp.b28ACP 283s new 644 0/0 480434 tmp.jvhOa1 283s new 644 0/0 480434 tmp.ov9jLI 283s same 644 0/0 480434 tmp.A6dFsZ 283s new 644 0/0 480434 tmp.EIsXMq 283s new 644 0/0 480434 tmp.IJBELW 283s same 644 0/0 480434 tmp.aYiG6V 283s same 644 0/0 480434 tmp.6FfZJs 283s same 644 0/0 480434 tmp.9ME7fW 283s new 644 0/0 480434 tmp.iWXIa9 283s tar: dumped 20 files and 0 directories 283s Total bytes written: 9608680 (6.5 MiB/s) 283s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 283s new 644 0/0 480434 tmp.kv3bCk 283s __bpc_progress_fileCnt__ 20 283s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377852 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377846 sizeNewComp, 47 inodeLast 283s Xfer PIDs are now 283s xferPids 283s incr backup 3 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 283s BackupExpire: cntFull = 3, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 0.000173611111111111, oldestFull = 0.000219907407407407 283s __bpc_progress_state__ fsck 283s Running BackupPC_refCountUpdate -h localhost on localhost 283s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 284s Xfer PIDs are now 4126 284s xferPids 4126 284s __bpc_progress_state__ cntUpdate #3 284s __bpc_progress_state__ rename #3 284s __bpc_progress_state__ sumUpdate 284s __bpc_progress_fileCnt__ 0/128 284s __bpc_progress_fileCnt__ 8/128 284s __bpc_progress_fileCnt__ 16/128 284s __bpc_progress_fileCnt__ 24/128 284s __bpc_progress_fileCnt__ 32/128 284s __bpc_progress_fileCnt__ 40/128 284s __bpc_progress_fileCnt__ 48/128 284s __bpc_progress_fileCnt__ 56/128 284s __bpc_progress_fileCnt__ 64/128 284s __bpc_progress_fileCnt__ 72/128 284s __bpc_progress_fileCnt__ 80/128 284s __bpc_progress_fileCnt__ 88/128 284s __bpc_progress_fileCnt__ 96/128 284s __bpc_progress_fileCnt__ 104/128 284s __bpc_progress_fileCnt__ 112/128 284s __bpc_progress_fileCnt__ 120/128 284s __bpc_progress_state__ rename total 284s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 284s Xfer PIDs are now 284s xferPids 284s Finished BackupPC_refCountUpdate (running time: 0 sec) 284s Xfer PIDs are now 284s xferPids 284s incr backup complete 284s OK: Incremental backup succeeded 284s Done. 284s Deleted user smbtest3932. 284s userdel: smbtest3932 mail spool (/var/mail/smbtest3932) not found 285s autopkgtest [08:48:09]: test authenticated-smb-backup: -----------------------] 286s autopkgtest [08:48:10]: test authenticated-smb-backup: - - - - - - - - - - results - - - - - - - - - - 286s authenticated-smb-backup PASS 286s autopkgtest [08:48:10]: @@@@@@@@@@@@@@@@@@@@ summary 286s smb-backup PASS 286s authenticated-smb-backup PASS