0s autopkgtest [14:27:58]: starting date and time: 2025-01-16 14:27:58+0000 0s autopkgtest [14:27:58]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [14:27:58]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work._wlaho29/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:ucf --apt-upgrade backuppc --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=ucf/3.0048 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-5.secgroup --name adt-plucky-s390x-backuppc-20250116-142758-juju-7f2275-prod-proposed-migration-environment-2-b3660f87-cc9c-4174-aba4-e766b6371166 --image adt/ubuntu-plucky-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 129s autopkgtest [14:30:07]: testbed dpkg architecture: s390x 129s autopkgtest [14:30:07]: testbed apt version: 2.9.18 129s autopkgtest [14:30:07]: @@@@@@@@@@@@@@@@@@@@ test bed setup 130s autopkgtest [14:30:08]: testbed release detected to be: None 131s autopkgtest [14:30:09]: updating testbed package index (apt update) 131s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 131s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 131s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 131s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 132s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 132s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.7 kB] 132s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [163 kB] 132s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [912 kB] 132s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x Packages [276 kB] 132s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted s390x Packages [756 B] 132s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe s390x Packages [975 kB] 133s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse s390x Packages [4668 B] 133s Fetched 2429 kB in 2s (1207 kB/s) 134s Reading package lists... 135s + lsb_release --codename --short 135s + RELEASE=plucky 135s + cat 135s + [ plucky != trusty ] 135s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y --allow-downgrades -o Dpkg::Options::=--force-confnew dist-upgrade 135s Reading package lists... 135s Building dependency tree... 135s Reading state information... 136s Calculating upgrade... 137s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 137s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 137s + /usr/lib/apt/apt-helper analyze-pattern ?true 137s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove ?obsolete 137s Reading package lists... 137s Building dependency tree... 137s Reading state information... 138s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 138s + grep -q trusty /etc/lsb-release 138s + [ ! -d /usr/share/doc/unattended-upgrades ] 138s + [ ! -d /usr/share/doc/lxd ] 138s + [ ! -d /usr/share/doc/lxd-client ] 138s + [ ! -d /usr/share/doc/snapd ] 138s + type iptables 138s + cat 138s + chmod 755 /etc/rc.local 138s + . /etc/rc.local 138s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 138s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 138s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 138s + uname -m 138s + [ s390x = ppc64le ] 138s + [ -d /run/systemd/system ] 138s + systemd-detect-virt --quiet --vm 138s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 138s + cat 138s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 138s + echo COMPRESS=lz4 138s + sync 138s autopkgtest [14:30:16]: upgrading testbed (apt dist-upgrade and autopurge) 138s Reading package lists... 138s Building dependency tree... 138s Reading state information... 139s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 139s Starting 2 pkgProblemResolver with broken count: 0 139s Done 140s Entering ResolveByKeep 140s 140s The following packages will be upgraded: 140s ucf 140s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 140s Need to get 42.8 kB of archives. 140s After this operation, 0 B of additional disk space will be used. 140s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main s390x ucf all 3.0048 [42.8 kB] 142s Preconfiguring packages ... 142s Fetched 42.8 kB in 1s (74.3 kB/s) 146s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 55658 files and directories currently installed.) 146s Preparing to unpack .../archives/ucf_3.0048_all.deb ... 146s Unpacking ucf (3.0048) over (3.0046) ... 146s Setting up ucf (3.0048) ... 146s Processing triggers for man-db (2.13.0-1) ... 152s Reading package lists... 152s Building dependency tree... 152s Reading state information... 153s Starting pkgProblemResolver with broken count: 0 153s Starting 2 pkgProblemResolver with broken count: 0 153s Done 154s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 157s autopkgtest [14:30:35]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 12:49:35 UTC 2024 157s autopkgtest [14:30:35]: @@@@@@@@@@@@@@@@@@@@ apt-source backuppc 159s Get:1 http://ftpmaster.internal/ubuntu plucky/main backuppc 4.4.0-10ubuntu1 (dsc) [2268 B] 159s Get:2 http://ftpmaster.internal/ubuntu plucky/main backuppc 4.4.0-10ubuntu1 (tar) [657 kB] 159s Get:3 http://ftpmaster.internal/ubuntu plucky/main backuppc 4.4.0-10ubuntu1 (diff) [37.7 kB] 160s gpgv: Signature made Tue Dec 10 12:24:29 2024 UTC 160s gpgv: using RSA key 8AFD08D3D1B817B2DF8982F501AC4B4083590A98 160s gpgv: Can't check signature: No public key 160s dpkg-source: warning: cannot verify inline signature for ./backuppc_4.4.0-10ubuntu1.dsc: no acceptable signature found 161s autopkgtest [14:30:39]: testing package backuppc version 4.4.0-10ubuntu1 161s autopkgtest [14:30:39]: build not needed 163s autopkgtest [14:30:41]: test smb-backup: preparing testbed 163s Reading package lists... 163s Building dependency tree... 163s Reading state information... 163s Starting pkgProblemResolver with broken count: 0 163s Starting 2 pkgProblemResolver with broken count: 0 163s Done 164s The following NEW packages will be installed: 164s apache2 apache2-bin apache2-data apache2-utils backuppc backuppc-rsync 164s libapr1t64 libaprutil1-dbd-sqlite3 libaprutil1-ldap libaprutil1t64 164s libarchive-zip-perl libavahi-client3 libavahi-common-data libavahi-common3 164s libbackuppc-xs-perl libcgi-pm-perl libcups2t64 libfile-listing-perl 164s libhtml-parser-perl libhtml-tagset-perl libhttp-date-perl libldb2 164s liblua5.4-0 libsmbclient0 libtalloc2 libtdb1 libtevent0t64 164s libtime-parsedate-perl libtimedate-perl liburi-perl liburing2 libwbclient0 164s postfix samba samba-common samba-common-bin samba-libs smbclient 164s 0 upgraded, 38 newly installed, 0 to remove and 0 not upgraded. 164s Need to get 15.4 MB of archives. 164s After this operation, 59.0 MB of additional disk space will be used. 164s Get:1 http://ftpmaster.internal/ubuntu plucky/main s390x libapr1t64 s390x 1.7.5-1 [114 kB] 164s Get:2 http://ftpmaster.internal/ubuntu plucky/main s390x libaprutil1t64 s390x 1.6.3-3ubuntu1 [98.3 kB] 164s Get:3 http://ftpmaster.internal/ubuntu plucky/main s390x libaprutil1-dbd-sqlite3 s390x 1.6.3-3ubuntu1 [11.7 kB] 164s Get:4 http://ftpmaster.internal/ubuntu plucky/main s390x libaprutil1-ldap s390x 1.6.3-3ubuntu1 [9052 B] 164s Get:5 http://ftpmaster.internal/ubuntu plucky/main s390x liblua5.4-0 s390x 5.4.7-1 [174 kB] 164s Get:6 http://ftpmaster.internal/ubuntu plucky/main s390x apache2-bin s390x 2.4.62-3ubuntu1 [1411 kB] 165s Get:7 http://ftpmaster.internal/ubuntu plucky/main s390x apache2-data all 2.4.62-3ubuntu1 [163 kB] 165s Get:8 http://ftpmaster.internal/ubuntu plucky/main s390x apache2-utils s390x 2.4.62-3ubuntu1 [102 kB] 165s Get:9 http://ftpmaster.internal/ubuntu plucky/main s390x apache2 s390x 2.4.62-3ubuntu1 [90.4 kB] 165s Get:10 http://ftpmaster.internal/ubuntu plucky/main s390x backuppc-rsync s390x 3.1.3.0-4 [273 kB] 165s Get:11 http://ftpmaster.internal/ubuntu plucky/main s390x postfix s390x 3.9.1-9 [1304 kB] 166s Get:12 http://ftpmaster.internal/ubuntu plucky/main s390x libarchive-zip-perl all 1.68-1 [90.2 kB] 166s Get:13 http://ftpmaster.internal/ubuntu plucky/main s390x libbackuppc-xs-perl s390x 0.62-2build6 [70.6 kB] 166s Get:14 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-tagset-perl all 3.24-1 [14.1 kB] 166s Get:15 http://ftpmaster.internal/ubuntu plucky/main s390x liburi-perl all 5.30-1 [94.4 kB] 166s Get:16 http://ftpmaster.internal/ubuntu plucky/main s390x libhtml-parser-perl s390x 3.83-1build1 [87.8 kB] 166s Get:17 http://ftpmaster.internal/ubuntu plucky/main s390x libcgi-pm-perl all 4.66-1 [185 kB] 166s Get:18 http://ftpmaster.internal/ubuntu plucky/main s390x libtimedate-perl all 2.3300-2 [34.0 kB] 166s Get:19 http://ftpmaster.internal/ubuntu plucky/main s390x libhttp-date-perl all 6.06-1 [10.2 kB] 166s Get:20 http://ftpmaster.internal/ubuntu plucky/main s390x libfile-listing-perl all 6.16-1 [11.3 kB] 166s Get:21 http://ftpmaster.internal/ubuntu plucky/main s390x libtime-parsedate-perl all 2015.103-5 [27.9 kB] 166s Get:22 http://ftpmaster.internal/ubuntu plucky/main s390x backuppc s390x 4.4.0-10ubuntu1 [518 kB] 166s Get:23 http://ftpmaster.internal/ubuntu plucky/main s390x samba-common all 2:4.20.4+dfsg-1ubuntu3 [68.1 kB] 166s Get:24 http://ftpmaster.internal/ubuntu plucky/main s390x libavahi-common-data s390x 0.8-14ubuntu1 [30.5 kB] 166s Get:25 http://ftpmaster.internal/ubuntu plucky/main s390x libavahi-common3 s390x 0.8-14ubuntu1 [23.6 kB] 166s Get:26 http://ftpmaster.internal/ubuntu plucky/main s390x libavahi-client3 s390x 0.8-14ubuntu1 [26.8 kB] 166s Get:27 http://ftpmaster.internal/ubuntu plucky/main s390x libcups2t64 s390x 2.4.11-0ubuntu2 [282 kB] 166s Get:28 http://ftpmaster.internal/ubuntu plucky/main s390x libtalloc2 s390x 2.4.2-1build2 [28.4 kB] 166s Get:29 http://ftpmaster.internal/ubuntu plucky/main s390x libtdb1 s390x 1.4.12-1 [49.4 kB] 166s Get:30 http://ftpmaster.internal/ubuntu plucky/main s390x libtevent0t64 s390x 0.16.1-3 [42.6 kB] 166s Get:31 http://ftpmaster.internal/ubuntu plucky/main s390x libldb2 s390x 2:2.9.1+samba4.20.4+dfsg-1ubuntu3 [194 kB] 166s Get:32 http://ftpmaster.internal/ubuntu plucky/main s390x libwbclient0 s390x 2:4.20.4+dfsg-1ubuntu3 [75.4 kB] 166s Get:33 http://ftpmaster.internal/ubuntu plucky/main s390x samba-libs s390x 2:4.20.4+dfsg-1ubuntu3 [6353 kB] 168s Get:34 http://ftpmaster.internal/ubuntu plucky/main s390x samba-common-bin s390x 2:4.20.4+dfsg-1ubuntu3 [1333 kB] 169s Get:35 http://ftpmaster.internal/ubuntu plucky/main s390x liburing2 s390x 2.8-1 [25.5 kB] 169s Get:36 http://ftpmaster.internal/ubuntu plucky/main s390x samba s390x 2:4.20.4+dfsg-1ubuntu3 [1387 kB] 169s Get:37 http://ftpmaster.internal/ubuntu plucky/main s390x libsmbclient0 s390x 2:4.20.4+dfsg-1ubuntu3 [64.8 kB] 169s Get:38 http://ftpmaster.internal/ubuntu plucky/main s390x smbclient s390x 2:4.20.4+dfsg-1ubuntu3 [495 kB] 170s Preconfiguring packages ... 170s Fetched 15.4 MB in 5s (2940 kB/s) 170s Selecting previously unselected package libapr1t64:s390x. 170s (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 ... 55658 files and directories currently installed.) 170s Preparing to unpack .../00-libapr1t64_1.7.5-1_s390x.deb ... 170s Unpacking libapr1t64:s390x (1.7.5-1) ... 170s Selecting previously unselected package libaprutil1t64:s390x. 170s Preparing to unpack .../01-libaprutil1t64_1.6.3-3ubuntu1_s390x.deb ... 170s Unpacking libaprutil1t64:s390x (1.6.3-3ubuntu1) ... 170s Selecting previously unselected package libaprutil1-dbd-sqlite3:s390x. 170s Preparing to unpack .../02-libaprutil1-dbd-sqlite3_1.6.3-3ubuntu1_s390x.deb ... 170s Unpacking libaprutil1-dbd-sqlite3:s390x (1.6.3-3ubuntu1) ... 170s Selecting previously unselected package libaprutil1-ldap:s390x. 170s Preparing to unpack .../03-libaprutil1-ldap_1.6.3-3ubuntu1_s390x.deb ... 170s Unpacking libaprutil1-ldap:s390x (1.6.3-3ubuntu1) ... 170s Selecting previously unselected package liblua5.4-0:s390x. 170s Preparing to unpack .../04-liblua5.4-0_5.4.7-1_s390x.deb ... 170s Unpacking liblua5.4-0:s390x (5.4.7-1) ... 170s Selecting previously unselected package apache2-bin. 170s Preparing to unpack .../05-apache2-bin_2.4.62-3ubuntu1_s390x.deb ... 170s Unpacking apache2-bin (2.4.62-3ubuntu1) ... 170s Selecting previously unselected package apache2-data. 170s Preparing to unpack .../06-apache2-data_2.4.62-3ubuntu1_all.deb ... 170s Unpacking apache2-data (2.4.62-3ubuntu1) ... 170s Selecting previously unselected package apache2-utils. 170s Preparing to unpack .../07-apache2-utils_2.4.62-3ubuntu1_s390x.deb ... 170s Unpacking apache2-utils (2.4.62-3ubuntu1) ... 170s Selecting previously unselected package apache2. 170s Preparing to unpack .../08-apache2_2.4.62-3ubuntu1_s390x.deb ... 170s Unpacking apache2 (2.4.62-3ubuntu1) ... 170s Selecting previously unselected package backuppc-rsync. 170s Preparing to unpack .../09-backuppc-rsync_3.1.3.0-4_s390x.deb ... 170s Unpacking backuppc-rsync (3.1.3.0-4) ... 170s Selecting previously unselected package postfix. 170s Preparing to unpack .../10-postfix_3.9.1-9_s390x.deb ... 170s Unpacking postfix (3.9.1-9) ... 170s Selecting previously unselected package libarchive-zip-perl. 170s Preparing to unpack .../11-libarchive-zip-perl_1.68-1_all.deb ... 170s Unpacking libarchive-zip-perl (1.68-1) ... 171s Selecting previously unselected package libbackuppc-xs-perl:s390x. 171s Preparing to unpack .../12-libbackuppc-xs-perl_0.62-2build6_s390x.deb ... 171s Unpacking libbackuppc-xs-perl:s390x (0.62-2build6) ... 171s Selecting previously unselected package libhtml-tagset-perl. 171s Preparing to unpack .../13-libhtml-tagset-perl_3.24-1_all.deb ... 171s Unpacking libhtml-tagset-perl (3.24-1) ... 171s Selecting previously unselected package liburi-perl. 171s Preparing to unpack .../14-liburi-perl_5.30-1_all.deb ... 171s Unpacking liburi-perl (5.30-1) ... 171s Selecting previously unselected package libhtml-parser-perl:s390x. 171s Preparing to unpack .../15-libhtml-parser-perl_3.83-1build1_s390x.deb ... 171s Unpacking libhtml-parser-perl:s390x (3.83-1build1) ... 171s Selecting previously unselected package libcgi-pm-perl. 171s Preparing to unpack .../16-libcgi-pm-perl_4.66-1_all.deb ... 171s Unpacking libcgi-pm-perl (4.66-1) ... 171s Selecting previously unselected package libtimedate-perl. 171s Preparing to unpack .../17-libtimedate-perl_2.3300-2_all.deb ... 171s Unpacking libtimedate-perl (2.3300-2) ... 171s Selecting previously unselected package libhttp-date-perl. 171s Preparing to unpack .../18-libhttp-date-perl_6.06-1_all.deb ... 171s Unpacking libhttp-date-perl (6.06-1) ... 171s Selecting previously unselected package libfile-listing-perl. 171s Preparing to unpack .../19-libfile-listing-perl_6.16-1_all.deb ... 171s Unpacking libfile-listing-perl (6.16-1) ... 171s Selecting previously unselected package libtime-parsedate-perl. 171s Preparing to unpack .../20-libtime-parsedate-perl_2015.103-5_all.deb ... 171s Unpacking libtime-parsedate-perl (2015.103-5) ... 171s Selecting previously unselected package backuppc. 171s Preparing to unpack .../21-backuppc_4.4.0-10ubuntu1_s390x.deb ... 171s Unpacking backuppc (4.4.0-10ubuntu1) ... 171s Selecting previously unselected package samba-common. 171s Preparing to unpack .../22-samba-common_2%3a4.20.4+dfsg-1ubuntu3_all.deb ... 171s Unpacking samba-common (2:4.20.4+dfsg-1ubuntu3) ... 171s Selecting previously unselected package libavahi-common-data:s390x. 171s Preparing to unpack .../23-libavahi-common-data_0.8-14ubuntu1_s390x.deb ... 171s Unpacking libavahi-common-data:s390x (0.8-14ubuntu1) ... 171s Selecting previously unselected package libavahi-common3:s390x. 171s Preparing to unpack .../24-libavahi-common3_0.8-14ubuntu1_s390x.deb ... 171s Unpacking libavahi-common3:s390x (0.8-14ubuntu1) ... 171s Selecting previously unselected package libavahi-client3:s390x. 171s Preparing to unpack .../25-libavahi-client3_0.8-14ubuntu1_s390x.deb ... 171s Unpacking libavahi-client3:s390x (0.8-14ubuntu1) ... 171s Selecting previously unselected package libcups2t64:s390x. 171s Preparing to unpack .../26-libcups2t64_2.4.11-0ubuntu2_s390x.deb ... 171s Unpacking libcups2t64:s390x (2.4.11-0ubuntu2) ... 171s Selecting previously unselected package libtalloc2:s390x. 171s Preparing to unpack .../27-libtalloc2_2.4.2-1build2_s390x.deb ... 171s Unpacking libtalloc2:s390x (2.4.2-1build2) ... 171s Selecting previously unselected package libtdb1:s390x. 171s Preparing to unpack .../28-libtdb1_1.4.12-1_s390x.deb ... 171s Unpacking libtdb1:s390x (1.4.12-1) ... 171s Selecting previously unselected package libtevent0t64:s390x. 171s Preparing to unpack .../29-libtevent0t64_0.16.1-3_s390x.deb ... 171s Unpacking libtevent0t64:s390x (0.16.1-3) ... 171s Selecting previously unselected package libldb2:s390x. 171s Preparing to unpack .../30-libldb2_2%3a2.9.1+samba4.20.4+dfsg-1ubuntu3_s390x.deb ... 171s Unpacking libldb2:s390x (2:2.9.1+samba4.20.4+dfsg-1ubuntu3) ... 171s Selecting previously unselected package libwbclient0:s390x. 171s Preparing to unpack .../31-libwbclient0_2%3a4.20.4+dfsg-1ubuntu3_s390x.deb ... 171s Unpacking libwbclient0:s390x (2:4.20.4+dfsg-1ubuntu3) ... 171s Selecting previously unselected package samba-libs:s390x. 172s Preparing to unpack .../32-samba-libs_2%3a4.20.4+dfsg-1ubuntu3_s390x.deb ... 172s Unpacking samba-libs:s390x (2:4.20.4+dfsg-1ubuntu3) ... 172s Selecting previously unselected package samba-common-bin. 172s Preparing to unpack .../33-samba-common-bin_2%3a4.20.4+dfsg-1ubuntu3_s390x.deb ... 172s Unpacking samba-common-bin (2:4.20.4+dfsg-1ubuntu3) ... 172s Selecting previously unselected package liburing2:s390x. 172s Preparing to unpack .../34-liburing2_2.8-1_s390x.deb ... 172s Unpacking liburing2:s390x (2.8-1) ... 172s Selecting previously unselected package samba. 172s Preparing to unpack .../35-samba_2%3a4.20.4+dfsg-1ubuntu3_s390x.deb ... 172s Unpacking samba (2:4.20.4+dfsg-1ubuntu3) ... 172s Selecting previously unselected package libsmbclient0:s390x. 172s Preparing to unpack .../36-libsmbclient0_2%3a4.20.4+dfsg-1ubuntu3_s390x.deb ... 172s Unpacking libsmbclient0:s390x (2:4.20.4+dfsg-1ubuntu3) ... 172s Selecting previously unselected package smbclient. 172s Preparing to unpack .../37-smbclient_2%3a4.20.4+dfsg-1ubuntu3_s390x.deb ... 172s Unpacking smbclient (2:4.20.4+dfsg-1ubuntu3) ... 172s Setting up libarchive-zip-perl (1.68-1) ... 172s Setting up libtdb1:s390x (1.4.12-1) ... 172s Setting up libhtml-tagset-perl (3.24-1) ... 172s Setting up samba-common (2:4.20.4+dfsg-1ubuntu3) ... 173s Creating config file /etc/samba/smb.conf with new version 173s Setting up libwbclient0:s390x (2:4.20.4+dfsg-1ubuntu3) ... 173s Setting up libtalloc2:s390x (2.4.2-1build2) ... 173s Setting up backuppc-rsync (3.1.3.0-4) ... 173s Setting up libavahi-common-data:s390x (0.8-14ubuntu1) ... 173s Setting up libtimedate-perl (2.3300-2) ... 173s Setting up postfix (3.9.1-9) ... 174s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 174s info: Selecting UID from range 100 to 999 ... 174s 174s info: Selecting GID from range 100 to 999 ... 174s info: Adding system user `postfix' (UID 107) ... 174s info: Adding new group `postfix' (GID 110) ... 174s info: Adding new user `postfix' (UID 107) with group `postfix' ... 174s info: Not creating home directory `/var/spool/postfix'. 174s info: Selecting GID from range 100 to 999 ... 175s info: Adding group `postdrop' (GID 113) ... 175s Creating /etc/postfix/dynamicmaps.cf 175s setting myhostname: autopkgtest.local 175s setting alias maps 175s setting alias database 175s changing /etc/mailname to autopkgtest.local 175s setting myorigin 175s setting destinations: $myhostname, autopkgtest.local, localhost.local, , localhost 175s setting relayhost: 175s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 175s clearing mailbox_command 175s setting mailbox_size_limit: 0 175s setting recipient_delimiter: + 177s setting inet_interfaces: all 179s setting inet_protocols: all 179s /etc/aliases does not exist, creating it. 179s WARNING: /etc/aliases exists, but does not have a root alias. 179s 179s Postfix (main.cf) is now set up with a default configuration. If you need to 179s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 179s Postfix configuration values, see postconf(1). 179s 179s After modifying main.cf, be sure to run 'systemctl reload postfix'. 179s 181s Setting up libtime-parsedate-perl (2015.103-5) ... 181s Setting up libapr1t64:s390x (1.7.5-1) ... 181s Setting up liblua5.4-0:s390x (5.4.7-1) ... 181s Setting up apache2-data (2.4.62-3ubuntu1) ... 181s Setting up liburing2:s390x (2.8-1) ... 181s Setting up libbackuppc-xs-perl:s390x (0.62-2build6) ... 181s Setting up liburi-perl (5.30-1) ... 181s Setting up libtevent0t64:s390x (0.16.1-3) ... 181s Setting up libaprutil1t64:s390x (1.6.3-3ubuntu1) ... 181s Setting up libhttp-date-perl (6.06-1) ... 181s Setting up libfile-listing-perl (6.16-1) ... 181s Setting up libavahi-common3:s390x (0.8-14ubuntu1) ... 181s Setting up libaprutil1-ldap:s390x (1.6.3-3ubuntu1) ... 181s Setting up libaprutil1-dbd-sqlite3:s390x (1.6.3-3ubuntu1) ... 181s Setting up libhtml-parser-perl:s390x (3.83-1build1) ... 181s Setting up libldb2:s390x (2:2.9.1+samba4.20.4+dfsg-1ubuntu3) ... 181s Setting up libavahi-client3:s390x (0.8-14ubuntu1) ... 181s Setting up apache2-utils (2.4.62-3ubuntu1) ... 181s Setting up libcgi-pm-perl (4.66-1) ... 181s Setting up samba-libs:s390x (2:4.20.4+dfsg-1ubuntu3) ... 181s Setting up apache2-bin (2.4.62-3ubuntu1) ... 181s Setting up libsmbclient0:s390x (2:4.20.4+dfsg-1ubuntu3) ... 181s Setting up libcups2t64:s390x (2.4.11-0ubuntu2) ... 181s Setting up apache2 (2.4.62-3ubuntu1) ... 181s Enabling module mpm_event. 181s Enabling module authz_core. 181s Enabling module authz_host. 181s Enabling module authn_core. 181s Enabling module auth_basic. 181s Enabling module access_compat. 182s Enabling module authn_file. 182s Enabling module authz_user. 182s Enabling module alias. 182s Enabling module dir. 182s Enabling module autoindex. 182s Enabling module env. 182s Enabling module mime. 182s Enabling module negotiation. 182s Enabling module setenvif. 182s Enabling module filter. 182s Enabling module deflate. 182s Enabling module status. 182s Enabling module reqtimeout. 182s Enabling conf charset. 183s Enabling conf localized-error-pages. 183s Enabling conf other-vhosts-access-log. 183s Enabling conf security. 183s Enabling conf serve-cgi-bin. 183s Enabling site 000-default. 184s Created symlink '/etc/systemd/system/multi-user.target.wants/apache2.service' → '/usr/lib/systemd/system/apache2.service'. 185s Created symlink '/etc/systemd/system/multi-user.target.wants/apache-htcacheclean.service' → '/usr/lib/systemd/system/apache-htcacheclean.service'. 187s Setting up backuppc (4.4.0-10ubuntu1) ... 187s 187s apache2_invoke: Enable configuration backuppc.conf 188s warn: The home directory `/var/lib/backuppc' already exists. Not touching this directory. 188s warn: Warning: The home directory `/var/lib/backuppc' does not belong to the user you are currently creating. 188s Adding password for user backuppc 188s Considering dependency authn_core for auth_basic: 188s Module authn_core already enabled 188s Module auth_basic already enabled 188s Considering dependency authz_core for authz_groupfile: 188s Module authz_core already enabled 188s Enabling module authz_groupfile. 188s To activate the new configuration, you need to run: 188s systemctl restart apache2 188s Module authn_file already enabled 188s Considering dependency authz_core for authz_user: 188s Module authz_core already enabled 188s Module authz_user already enabled 188s Your MPM seems to be threaded. Selecting cgid instead of cgi. 188s Enabling module cgid. 188s To activate the new configuration, you need to run: 188s systemctl restart apache2 188s Creating config file /etc/backuppc/config.pl with new version 189s Created symlink '/etc/systemd/system/multi-user.target.wants/backuppc.service' → '/usr/lib/systemd/system/backuppc.service'. 190s Setting up smbclient (2:4.20.4+dfsg-1ubuntu3) ... 190s Setting up samba-common-bin (2:4.20.4+dfsg-1ubuntu3) ... 190s Setting up samba (2:4.20.4+dfsg-1ubuntu3) ... 192s Created symlink '/etc/systemd/system/smb.service' → '/usr/lib/systemd/system/smbd.service'. 192s Created symlink '/etc/systemd/system/multi-user.target.wants/smbd.service' → '/usr/lib/systemd/system/smbd.service'. 194s Created symlink '/etc/systemd/system/nmb.service' → '/usr/lib/systemd/system/nmbd.service'. 194s Created symlink '/etc/systemd/system/multi-user.target.wants/nmbd.service' → '/usr/lib/systemd/system/nmbd.service'. 194s Processing triggers for rsyslog (8.2406.0-1ubuntu2) ... 195s Processing triggers for ufw (0.36.2-8) ... 195s Processing triggers for man-db (2.13.0-1) ... 203s Processing triggers for libc-bin (2.40-4ubuntu1) ... 204s Processing triggers for postfix (3.9.1-9) ... 204s Restarting postfix 207s autopkgtest [14:31:25]: test smb-backup: [----------------------- 207s net.ipv4.ping_group_range = 0 65534 207s Populating share path 207s Adding samba share 207s Adding [public] share 207s Configuring backuppc 207s Performing a full backup 207s Backup type: type = full, needs_full = 1, needs_incr = 1, lastFullTime = 0, opts{f} = 1, opts{i} = , opts{F} = 207s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 207s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 207s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.006 207s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.0.z created 2025-01-16 14:31:25 207s Backup prep: type = full, case = 1, inPlace = 1, doDuplicate = 0, newBkupNum = 0, newBkupIdx = 0, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 207s __bpc_progress_state__ backup share "public" 207s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 207s full backup started for share public 207s started full dump, share=public 207s Xfer PIDs are now 3864,3863 207s xferPids 3864,3863 207s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 207s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 207s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 208s tarmode is now full, system, hidden, noreset, noverbose 208s __bpc_progress_fileCnt__ 0 208s new 644 0/0 480434 tmp.EuhdEf 208s new 644 0/0 480434 tmp.kUvobt 208s new 644 0/0 480434 tmp.tOpQl8 208s new 644 0/0 480434 tmp.b5xB9s 208s new 644 0/0 480434 tmp.dIpjE0 208s new 644 0/0 480434 tmp.W7SUqt 208s new 644 0/0 480434 tmp.rS3xaG 208s new 644 0/0 480434 tmp.ZJIBS2 208s new 644 0/0 480434 tmp.gqCVPe 208s tar: dumped 10 files and 0 directories 208s Total bytes written: 4804340 (11.0 MiB/s) 208s new 644 0/0 480434 tmp.ETQEzg 208s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 208s __bpc_progress_fileCnt__ 10 208s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377786 sizeNewComp, 13 inodeLast 208s Xfer PIDs are now 208s xferPids 208s full backup 0 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 208s BackupExpire: cntFull = 1, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 1.15740740740741e-05 208s __bpc_progress_state__ fsck 208s Running BackupPC_refCountUpdate -h localhost on localhost 208s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 208s Xfer PIDs are now 3871 208s xferPids 3871 208s BackupPC_refCountUpdate: doing fsck on localhost #0 (full) since $ConfRefCntFsck == 1 208s __bpc_progress_state__ refCnt #0 208s __bpc_progress_state__ cntUpdate #0 208s __bpc_progress_state__ rename #0 208s __bpc_progress_state__ sumUpdate 208s __bpc_progress_fileCnt__ 0/128 208s __bpc_progress_fileCnt__ 8/128 208s __bpc_progress_fileCnt__ 16/128 208s __bpc_progress_fileCnt__ 24/128 208s __bpc_progress_fileCnt__ 32/128 208s __bpc_progress_fileCnt__ 40/128 208s __bpc_progress_fileCnt__ 48/128 208s __bpc_progress_fileCnt__ 56/128 208s __bpc_progress_fileCnt__ 64/128 208s __bpc_progress_fileCnt__ 72/128 208s __bpc_progress_fileCnt__ 80/128 208s __bpc_progress_fileCnt__ 88/128 208s __bpc_progress_fileCnt__ 96/128 208s __bpc_progress_fileCnt__ 104/128 208s __bpc_progress_fileCnt__ 112/128 208s __bpc_progress_fileCnt__ 120/128 208s __bpc_progress_state__ rename total 208s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 208s Xfer PIDs are now 208s xferPids 208s Finished BackupPC_refCountUpdate (running time: 0 sec) 208s Xfer PIDs are now 208s xferPids 208s full backup complete 208s OK: Full backup succeeded 208s Changing share content and performing an incremental backup 209s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1737037885, opts{f} = , opts{i} = 1, opts{F} = 209s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 209s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 209s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.020 209s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.1.z created 2025-01-16 14:31:27 209s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 1, newBkupIdx = 1, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 209s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 209s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 209s Xfer PIDs are now 3941 209s xferPids 3941 209s __bpc_progress_state__ copy #0 -> #1 209s Copying backup #0 to #1 209s __bpc_progress_fileCnt__ 3 dirs 209s Xfer PIDs are now 3944,3941 209s xferPids 3944,3941 209s __bpc_progress_state__ sumUpdate 209s __bpc_progress_fileCnt__ 0/128 209s __bpc_progress_fileCnt__ 8/128 209s __bpc_progress_fileCnt__ 16/128 209s __bpc_progress_fileCnt__ 24/128 209s __bpc_progress_fileCnt__ 32/128 209s __bpc_progress_fileCnt__ 40/128 209s __bpc_progress_fileCnt__ 48/128 209s __bpc_progress_fileCnt__ 56/128 209s __bpc_progress_fileCnt__ 64/128 209s __bpc_progress_fileCnt__ 72/128 209s __bpc_progress_fileCnt__ 80/128 209s __bpc_progress_fileCnt__ 88/128 209s __bpc_progress_fileCnt__ 96/128 209s __bpc_progress_fileCnt__ 104/128 209s __bpc_progress_fileCnt__ 112/128 209s __bpc_progress_fileCnt__ 120/128 209s __bpc_progress_state__ rename total 209s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 209s Xfer PIDs are now 3941 209s xferPids 3941 209s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 209s Finished BackupPC_backupDuplicate (running time: 0 sec) 209s __bpc_progress_state__ backup share "public" 209s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 209s incr backup started back to 2025-01-16 13:31:25 (backup #0) for share public 209s started incr dump, share=public 209s Xfer PIDs are now 3948,3947 209s xferPids 3948,3947 209s 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 - 209s tar:1298 Getting files newer than Thu Jan 16 13:31:25 2025 209s tarmode is now full, system, hidden, noreset, noverbose 209s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 209s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 209s __bpc_progress_fileCnt__ 0 209s same 644 0/0 480434 tmp.EuhdEf 209s new 644 0/0 480434 tmp.nryPWO 209s same 644 0/0 480434 tmp.kUvobt 209s new 644 0/0 480434 tmp.n4CfT7 209s same 644 0/0 480434 tmp.tOpQl8 209s new 644 0/0 480434 tmp.wd7dVc 209s new 644 0/0 480434 tmp.1mZ0Qx 209s new 644 0/0 480434 tmp.c9kTZy 209s same 644 0/0 480434 tmp.b5xB9s 209s new 644 0/0 480434 tmp.RTTOCm 209s same 644 0/0 480434 tmp.dIpjE0 209s new 644 0/0 480434 tmp.VCHG4t 209s same 644 0/0 480434 tmp.W7SUqt 209s same 644 0/0 480434 tmp.rS3xaG 209s same 644 0/0 480434 tmp.ZJIBS2 209s new 644 0/0 480434 tmp.PcDRDW 209s same 644 0/0 480434 tmp.gqCVPe 209s new 644 0/0 480434 tmp.ddr67V 209s same 644 0/0 480434 tmp.ETQEzg 209s tar: dumped 20 files and 0 directories 209s Total bytes written: 9608680 (41.3 MiB/s) 209s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 209s new 644 0/0 480434 tmp.3NBVOH 209s __bpc_progress_fileCnt__ 20 209s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377786 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377753 sizeNewComp, 24 inodeLast 209s Xfer PIDs are now 209s xferPids 209s incr backup 1 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 209s BackupExpire: cntFull = 2, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 2.31481481481481e-05 209s __bpc_progress_state__ fsck 209s Running BackupPC_refCountUpdate -h localhost on localhost 209s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 209s Xfer PIDs are now 3954 209s xferPids 3954 209s __bpc_progress_state__ cntUpdate #1 209s __bpc_progress_state__ rename #1 209s __bpc_progress_state__ sumUpdate 209s __bpc_progress_fileCnt__ 0/128 209s __bpc_progress_fileCnt__ 8/128 209s __bpc_progress_fileCnt__ 16/128 209s __bpc_progress_fileCnt__ 24/128 209s __bpc_progress_fileCnt__ 32/128 209s __bpc_progress_fileCnt__ 40/128 209s __bpc_progress_fileCnt__ 48/128 209s __bpc_progress_fileCnt__ 56/128 209s __bpc_progress_fileCnt__ 64/128 209s __bpc_progress_fileCnt__ 72/128 210s __bpc_progress_fileCnt__ 80/128 210s __bpc_progress_fileCnt__ 88/128 210s __bpc_progress_fileCnt__ 96/128 210s __bpc_progress_fileCnt__ 104/128 210s __bpc_progress_fileCnt__ 112/128 210s __bpc_progress_fileCnt__ 120/128 210s __bpc_progress_state__ rename total 210s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 210s Xfer PIDs are now 210s xferPids 210s Finished BackupPC_refCountUpdate (running time: 0 sec) 210s Xfer PIDs are now 210s xferPids 210s incr backup complete 210s OK: Incremental backup succeeded 210s Done. 210s autopkgtest [14:31:28]: test smb-backup: -----------------------] 210s smb-backup PASS 210s autopkgtest [14:31:28]: test smb-backup: - - - - - - - - - - results - - - - - - - - - - 211s autopkgtest [14:31:29]: test authenticated-smb-backup: preparing testbed 211s Reading package lists... 212s Building dependency tree... 212s Reading state information... 213s Starting pkgProblemResolver with broken count: 0 213s Starting 2 pkgProblemResolver with broken count: 0 213s Done 214s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 215s autopkgtest [14:31:33]: test authenticated-smb-backup: [----------------------- 216s Populating share path 216s Adding samba share 216s Adding [private] share 216s Creating user 216s Creating a local and samba user called smbtest4104 217s Setting samba password for the smbtest4104 user 217s Added user smbtest4104. 217s Configuring backuppc 217s Performing a full backup 217s Backup type: type = full, needs_full = , needs_incr = , lastFullTime = 1737037885, opts{f} = 1, opts{i} = , opts{F} = 217s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 217s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 217s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.004 217s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.2.z created 2025-01-16 14:31:35 217s Backup prep: type = full, case = 4, inPlace = 0, doDuplicate = 0, newBkupNum = 2, newBkupIdx = 2, lastBkupNum = 1, lastBkupIdx = 1 (FillCycle = 0, noFillCnt = 0) 217s __bpc_progress_state__ backup share "private" 217s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest4104 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 217s full backup started for share private 217s started full dump, share=private 217s Xfer PIDs are now 4196,4195 217s xferPids 4196,4195 217s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest4104 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 217s tarmode is now full, system, hidden, noreset, noverbose 218s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 218s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 218s __bpc_progress_fileCnt__ 0 218s new 644 0/0 480434 tmp.1d8jdA 218s new 644 0/0 480434 tmp.r3jdk4 218s new 644 0/0 480434 tmp.fbifNH 218s new 644 0/0 480434 tmp.YFVd3N 218s new 644 0/0 480434 tmp.n28Jpk 218s new 644 0/0 480434 tmp.0FvhOR 218s new 644 0/0 480434 tmp.nmhv6j 218s new 644 0/0 480434 tmp.s9OGvK 218s new 644 0/0 480434 tmp.zThOs3 218s tar: dumped 10 files and 0 directories 218s Total bytes written: 4804340 (11.2 MiB/s) 218s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 218s new 644 0/0 480434 tmp.qqFYtS 218s __bpc_progress_fileCnt__ 10 218s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377812 sizeNewComp, 36 inodeLast 218s Xfer PIDs are now 218s xferPids 218s OrphanShareNameClean: removing orphan share public from /var/lib/backuppc/pc/localhost/2 218s __bpc_progress_state__ delete share #2/public 218s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDelete -h localhost -n 2 -l -m -s public / 218s Xfer PIDs are now 4202 218s xferPids 4202 218s BackupPC_backupDelete: removing #2/public/ 218s __bpc_progress_state__ merge #2/public/ -> #1/public/ 218s BackupPC_backupDelete: Merge into backup 1/public/ 218s mergeDir: attr write to /var/lib/backuppc/pc/localhost/1/fpublic//attrib failed (digest was ) 218s bpc_attrib_dirWrite: can't open/create raw /var/lib/backuppc/pc/localhost/1/fpublic for writing 218s Xfer PIDs are now 4205,4202 218s xferPids 4205,4202 218s __bpc_progress_state__ refCnt #1 218s __bpc_progress_state__ cntUpdate #1 218s __bpc_progress_state__ rename #1 218s __bpc_progress_state__ refCnt #2 218s BackupPC_refCountUpdate: localhost #2 inodeLast set to 37 (was 24) 218s __bpc_progress_state__ cntUpdate #2 219s __bpc_progress_state__ rename #2 219s __bpc_progress_state__ sumUpdate 219s __bpc_progress_fileCnt__ 0/128 219s __bpc_progress_fileCnt__ 8/128 219s __bpc_progress_fileCnt__ 16/128 219s __bpc_progress_fileCnt__ 24/128 219s __bpc_progress_fileCnt__ 32/128 219s __bpc_progress_fileCnt__ 40/128 219s __bpc_progress_fileCnt__ 48/128 219s __bpc_progress_fileCnt__ 56/128 219s __bpc_progress_fileCnt__ 64/128 219s __bpc_progress_fileCnt__ 72/128 219s __bpc_progress_fileCnt__ 80/128 219s __bpc_progress_fileCnt__ 88/128 219s __bpc_progress_fileCnt__ 96/128 219s __bpc_progress_fileCnt__ 104/128 219s __bpc_progress_fileCnt__ 112/128 219s __bpc_progress_fileCnt__ 120/128 219s __bpc_progress_state__ rename total 219s BackupPC_refCountUpdate: host localhost got 0 errors (took 1 secs) 219s Xfer PIDs are now 4202 219s xferPids 4202 219s Xfer PIDs are now 219s xferPids 219s Finished BackupPC_backupDelete, status = 0 (running time: 1 sec) 219s Xfer PIDs are now 219s xferPids 219s full backup 2 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 219s BackupExpire: cntFull = 2, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 0.000115740740740741, oldestFull = 0.000138888888888889 219s __bpc_progress_state__ fsck 219s Running BackupPC_refCountUpdate -h localhost on localhost 219s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 219s Xfer PIDs are now 4208 219s xferPids 4208 219s BackupPC_refCountUpdate: doing fsck on localhost #2 (full) since $ConfRefCntFsck == 1 219s __bpc_progress_state__ refCnt #2 219s __bpc_progress_state__ cntUpdate #2 219s __bpc_progress_state__ rename #2 219s __bpc_progress_state__ sumUpdate 219s __bpc_progress_fileCnt__ 0/128 219s __bpc_progress_fileCnt__ 8/128 219s __bpc_progress_fileCnt__ 16/128 219s __bpc_progress_fileCnt__ 24/128 219s __bpc_progress_fileCnt__ 32/128 219s __bpc_progress_fileCnt__ 40/128 219s __bpc_progress_fileCnt__ 48/128 219s __bpc_progress_fileCnt__ 56/128 219s __bpc_progress_fileCnt__ 64/128 219s __bpc_progress_fileCnt__ 72/128 219s __bpc_progress_fileCnt__ 80/128 219s __bpc_progress_fileCnt__ 88/128 219s __bpc_progress_fileCnt__ 96/128 219s __bpc_progress_fileCnt__ 104/128 219s __bpc_progress_fileCnt__ 112/128 219s __bpc_progress_fileCnt__ 120/128 219s __bpc_progress_state__ rename total 219s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 219s Xfer PIDs are now 219s xferPids 219s Finished BackupPC_refCountUpdate (running time: 0 sec) 219s Xfer PIDs are now 219s xferPids 219s full backup complete 219s OK: Full backup succeeded 219s Changing share content and performing an incremental backup 220s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1737037895, opts{f} = , opts{i} = 1, opts{F} = 220s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 220s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 220s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.012 220s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.3.z created 2025-01-16 14:31:38 220s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 3, newBkupIdx = 3, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = 1) 220s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 220s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 220s Xfer PIDs are now 4278 220s xferPids 4278 220s __bpc_progress_state__ copy #2 -> #3 220s Copying backup #2 to #3 220s __bpc_progress_fileCnt__ 3 dirs 220s Xfer PIDs are now 4281,4278 220s xferPids 4281,4278 220s __bpc_progress_state__ sumUpdate 220s __bpc_progress_fileCnt__ 0/128 220s __bpc_progress_fileCnt__ 8/128 220s __bpc_progress_fileCnt__ 16/128 220s __bpc_progress_fileCnt__ 24/128 220s __bpc_progress_fileCnt__ 32/128 220s __bpc_progress_fileCnt__ 40/128 220s __bpc_progress_fileCnt__ 48/128 220s __bpc_progress_fileCnt__ 56/128 220s __bpc_progress_fileCnt__ 64/128 220s __bpc_progress_fileCnt__ 72/128 220s __bpc_progress_fileCnt__ 80/128 220s __bpc_progress_fileCnt__ 88/128 220s __bpc_progress_fileCnt__ 96/128 220s __bpc_progress_fileCnt__ 104/128 220s __bpc_progress_fileCnt__ 112/128 220s __bpc_progress_fileCnt__ 120/128 220s __bpc_progress_state__ rename total 220s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 220s Xfer PIDs are now 4278 220s xferPids 4278 220s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 220s Finished BackupPC_backupDuplicate (running time: 0 sec) 220s __bpc_progress_state__ backup share "private" 220s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest4104 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 220s incr backup started back to 2025-01-16 13:31:35 (backup #2) for share private 220s started incr dump, share=private 220s Xfer PIDs are now 4285,4284 220s xferPids 4285,4284 220s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest4104 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 220s tar:1298 Getting files newer than Thu Jan 16 13:31:35 2025 220s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 220s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 220s tarmode is now full, system, hidden, noreset, noverbose 220s __bpc_progress_fileCnt__ 0 220s new 644 0/0 480434 tmp.zxuFfT 220s new 644 0/0 480434 tmp.btayXv 220s same 644 0/0 480434 tmp.1d8jdA 220s same 644 0/0 480434 tmp.r3jdk4 220s same 644 0/0 480434 tmp.fbifNH 220s same 644 0/0 480434 tmp.YFVd3N 220s new 644 0/0 480434 tmp.Z5s0Ko 221s new 644 0/0 480434 tmp.bnz8YH 221s new 644 0/0 480434 tmp.xjtsTl 221s same 644 0/0 480434 tmp.n28Jpk 221s new 644 0/0 480434 tmp.eoGNKr 221s same 644 0/0 480434 tmp.0FvhOR 221s new 644 0/0 480434 tmp.7hoqev 221s new 644 0/0 480434 tmp.j9kRpb 221s same 644 0/0 480434 tmp.nmhv6j 221s same 644 0/0 480434 tmp.s9OGvK 221s same 644 0/0 480434 tmp.zThOs3 221s same 644 0/0 480434 tmp.qqFYtS 221s new 644 0/0 480434 tmp.TeK2ZQ 221s tar: dumped 20 files and 0 directories 221s Total bytes written: 9608680 (20.5 MiB/s) 221s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 221s new 644 0/0 480434 tmp.6Ox7E5 221s __bpc_progress_fileCnt__ 20 221s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377812 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377808 sizeNewComp, 47 inodeLast 221s Xfer PIDs are now 221s xferPids 221s incr backup 3 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 221s BackupExpire: cntFull = 3, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 0.000138888888888889, oldestFull = 0.000162037037037037 221s __bpc_progress_state__ fsck 221s Running BackupPC_refCountUpdate -h localhost on localhost 221s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 221s Xfer PIDs are now 4292 221s xferPids 4292 221s __bpc_progress_state__ cntUpdate #3 221s __bpc_progress_state__ rename #3 221s __bpc_progress_state__ sumUpdate 221s __bpc_progress_fileCnt__ 0/128 221s __bpc_progress_fileCnt__ 8/128 221s __bpc_progress_fileCnt__ 16/128 221s __bpc_progress_fileCnt__ 24/128 221s __bpc_progress_fileCnt__ 32/128 221s __bpc_progress_fileCnt__ 40/128 221s __bpc_progress_fileCnt__ 48/128 221s __bpc_progress_fileCnt__ 56/128 221s __bpc_progress_fileCnt__ 64/128 221s __bpc_progress_fileCnt__ 72/128 221s __bpc_progress_fileCnt__ 80/128 221s __bpc_progress_fileCnt__ 88/128 221s __bpc_progress_fileCnt__ 96/128 221s __bpc_progress_fileCnt__ 104/128 221s __bpc_progress_fileCnt__ 112/128 221s __bpc_progress_fileCnt__ 120/128 221s __bpc_progress_state__ rename total 221s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 221s Xfer PIDs are now 221s xferPids 221s Finished BackupPC_refCountUpdate (running time: 0 sec) 221s Xfer PIDs are now 221s xferPids 221s incr backup complete 221s OK: Incremental backup succeeded 221s Done. 221s Deleted user smbtest4104. 222s userdel: smbtest4104 mail spool (/var/mail/smbtest4104) not found 223s autopkgtest [14:31:41]: test authenticated-smb-backup: -----------------------] 223s autopkgtest [14:31:41]: test authenticated-smb-backup: - - - - - - - - - - results - - - - - - - - - - 223s authenticated-smb-backup PASS 224s autopkgtest [14:31:42]: @@@@@@@@@@@@@@@@@@@@ summary 224s smb-backup PASS 224s authenticated-smb-backup PASS 243s nova [W] Using flock in prodstack6-s390x 243s Creating nova instance adt-plucky-s390x-backuppc-20250116-142758-juju-7f2275-prod-proposed-migration-environment-2-b3660f87-cc9c-4174-aba4-e766b6371166 from image adt/ubuntu-plucky-s390x-server-20250116.img (UUID e981a3af-6e9f-4d05-be37-4d186896cb71)... 243s nova [W] Timed out waiting for a1d32c98-270e-4545-8162-fc38c5d4b7f1 to get deleted.