0s autopkgtest [11:32:48]: starting date and time: 2025-01-16 11:32:48+0000 0s autopkgtest [11:32:48]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [11:32:48]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.x7fjzlc7/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 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-arm64-33.secgroup --name adt-plucky-arm64-backuppc-20250116-113247-juju-7f2275-prod-proposed-migration-environment-2-216ef7e5-87a8-45b4-bfe7-129564636428 --image adt/ubuntu-plucky-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 130s autopkgtest [11:34:58]: testbed dpkg architecture: arm64 131s autopkgtest [11:34:59]: testbed apt version: 2.9.18 131s autopkgtest [11:34:59]: @@@@@@@@@@@@@@@@@@@@ test bed setup 131s autopkgtest [11:34:59]: testbed release detected to be: None 132s autopkgtest [11:35:00]: updating testbed package index (apt update) 133s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 133s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 133s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 133s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 133s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [904 kB] 134s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [160 kB] 134s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [14.7 kB] 134s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 134s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 Packages [303 kB] 134s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted arm64 Packages [57.8 kB] 134s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe arm64 Packages [1080 kB] 134s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse arm64 Packages [10.5 kB] 134s Fetched 2613 kB in 1s (2583 kB/s) 135s 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... 136s Building dependency tree... 136s Reading state information... 137s Calculating upgrade... 138s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 138s + rm /etc/apt/preferences.d/force-downgrade-to-release.pref 138s + /usr/lib/apt/apt-helper analyze-pattern ?true 138s + DEBIAN_FRONTEND=noninteractive eatmydata apt-get -y purge --autoremove ?obsolete 138s Reading package lists... 138s Building dependency tree... 138s Reading state information... 139s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 139s + grep -q trusty /etc/lsb-release 139s + [ ! -d /usr/share/doc/unattended-upgrades ] 139s + [ ! -d /usr/share/doc/lxd ] 139s + [ ! -d /usr/share/doc/lxd-client ] 139s + [ ! -d /usr/share/doc/snapd ] 139s + type iptables 139s + cat 139s + chmod 755 /etc/rc.local 139s + . /etc/rc.local 139s + iptables -w -t mangle -A FORWARD -p tcp --tcp-flags SYN,RST SYN -j TCPMSS --clamp-mss-to-pmtu 139s + iptables -A OUTPUT -d 10.255.255.1/32 -p tcp -j DROP 139s + iptables -A OUTPUT -d 10.255.255.2/32 -p tcp -j DROP 139s + uname -m 139s + [ aarch64 = ppc64le ] 139s + [ -d /run/systemd/system ] 139s + systemd-detect-virt --quiet --vm 139s + mkdir -p /etc/systemd/system/systemd-random-seed.service.d/ 139s + cat 139s + grep -q lz4 /etc/initramfs-tools/initramfs.conf 139s + echo COMPRESS=lz4 139s + sync 139s autopkgtest [11:35:07]: upgrading testbed (apt dist-upgrade and autopurge) 139s Reading package lists... 140s Building dependency tree... 140s Reading state information... 140s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 140s Starting 2 pkgProblemResolver with broken count: 0 140s Done 141s Entering ResolveByKeep 141s 142s The following packages will be upgraded: 142s ucf 142s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 142s Need to get 42.8 kB of archives. 142s After this operation, 0 B of additional disk space will be used. 142s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main arm64 ucf all 3.0048 [42.8 kB] 142s Preconfiguring packages ... 142s Fetched 42.8 kB in 0s (154 kB/s) 143s (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 ... 80205 files and directories currently installed.) 143s Preparing to unpack .../archives/ucf_3.0048_all.deb ... 143s Unpacking ucf (3.0048) over (3.0046) ... 143s Setting up ucf (3.0048) ... 143s Processing triggers for man-db (2.13.0-1) ... 144s Reading package lists... 144s Building dependency tree... 144s Reading state information... 144s Starting pkgProblemResolver with broken count: 0 145s Starting 2 pkgProblemResolver with broken count: 0 145s Done 145s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 148s autopkgtest [11:35:16]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 16 14:19:41 UTC 2024 148s autopkgtest [11:35:16]: @@@@@@@@@@@@@@@@@@@@ apt-source backuppc 150s Get:1 http://ftpmaster.internal/ubuntu plucky/main backuppc 4.4.0-10ubuntu1 (dsc) [2268 B] 150s Get:2 http://ftpmaster.internal/ubuntu plucky/main backuppc 4.4.0-10ubuntu1 (tar) [657 kB] 150s Get:3 http://ftpmaster.internal/ubuntu plucky/main backuppc 4.4.0-10ubuntu1 (diff) [37.7 kB] 150s gpgv: Signature made Tue Dec 10 12:24:29 2024 UTC 150s gpgv: using RSA key 8AFD08D3D1B817B2DF8982F501AC4B4083590A98 150s gpgv: Can't check signature: No public key 150s dpkg-source: warning: cannot verify inline signature for ./backuppc_4.4.0-10ubuntu1.dsc: no acceptable signature found 151s autopkgtest [11:35:19]: testing package backuppc version 4.4.0-10ubuntu1 151s autopkgtest [11:35:19]: build not needed 152s autopkgtest [11:35:20]: test smb-backup: preparing testbed 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 153s The following NEW packages will be installed: 153s apache2 apache2-bin apache2-data apache2-utils backuppc backuppc-rsync 153s libapr1t64 libaprutil1-dbd-sqlite3 libaprutil1-ldap libaprutil1t64 153s libarchive-zip-perl libavahi-client3 libavahi-common-data libavahi-common3 153s libbackuppc-xs-perl libcgi-pm-perl libcups2t64 libfile-listing-perl 153s libhtml-parser-perl libhtml-tagset-perl libhttp-date-perl libldb2 153s liblua5.4-0 libsmbclient0 libtalloc2 libtdb1 libtevent0t64 153s libtime-parsedate-perl libtimedate-perl liburi-perl liburing2 libwbclient0 153s postfix samba samba-common samba-common-bin samba-libs smbclient 153s 0 upgraded, 38 newly installed, 0 to remove and 0 not upgraded. 153s Need to get 15.1 MB of archives. 153s After this operation, 76.8 MB of additional disk space will be used. 153s Get:1 http://ftpmaster.internal/ubuntu plucky/main arm64 libapr1t64 arm64 1.7.5-1 [106 kB] 154s Get:2 http://ftpmaster.internal/ubuntu plucky/main arm64 libaprutil1t64 arm64 1.6.3-3ubuntu1 [94.9 kB] 154s Get:3 http://ftpmaster.internal/ubuntu plucky/main arm64 libaprutil1-dbd-sqlite3 arm64 1.6.3-3ubuntu1 [11.4 kB] 154s Get:4 http://ftpmaster.internal/ubuntu plucky/main arm64 libaprutil1-ldap arm64 1.6.3-3ubuntu1 [9114 B] 154s Get:5 http://ftpmaster.internal/ubuntu plucky/main arm64 liblua5.4-0 arm64 5.4.7-1 [158 kB] 154s Get:6 http://ftpmaster.internal/ubuntu plucky/main arm64 apache2-bin arm64 2.4.62-3ubuntu1 [1330 kB] 154s Get:7 http://ftpmaster.internal/ubuntu plucky/main arm64 apache2-data all 2.4.62-3ubuntu1 [163 kB] 154s Get:8 http://ftpmaster.internal/ubuntu plucky/main arm64 apache2-utils arm64 2.4.62-3ubuntu1 [97.5 kB] 154s Get:9 http://ftpmaster.internal/ubuntu plucky/main arm64 apache2 arm64 2.4.62-3ubuntu1 [90.4 kB] 154s Get:10 http://ftpmaster.internal/ubuntu plucky/main arm64 backuppc-rsync arm64 3.1.3.0-4 [259 kB] 154s Get:11 http://ftpmaster.internal/ubuntu plucky/main arm64 postfix arm64 3.9.1-9 [1283 kB] 154s Get:12 http://ftpmaster.internal/ubuntu plucky/main arm64 libarchive-zip-perl all 1.68-1 [90.2 kB] 154s Get:13 http://ftpmaster.internal/ubuntu plucky/main arm64 libbackuppc-xs-perl arm64 0.62-2build6 [64.6 kB] 154s Get:14 http://ftpmaster.internal/ubuntu plucky/main arm64 libhtml-tagset-perl all 3.24-1 [14.1 kB] 154s Get:15 http://ftpmaster.internal/ubuntu plucky/main arm64 liburi-perl all 5.30-1 [94.4 kB] 154s Get:16 http://ftpmaster.internal/ubuntu plucky/main arm64 libhtml-parser-perl arm64 3.83-1build1 [85.3 kB] 154s Get:17 http://ftpmaster.internal/ubuntu plucky/main arm64 libcgi-pm-perl all 4.66-1 [185 kB] 154s Get:18 http://ftpmaster.internal/ubuntu plucky/main arm64 libtimedate-perl all 2.3300-2 [34.0 kB] 154s Get:19 http://ftpmaster.internal/ubuntu plucky/main arm64 libhttp-date-perl all 6.06-1 [10.2 kB] 154s Get:20 http://ftpmaster.internal/ubuntu plucky/main arm64 libfile-listing-perl all 6.16-1 [11.3 kB] 154s Get:21 http://ftpmaster.internal/ubuntu plucky/main arm64 libtime-parsedate-perl all 2015.103-5 [27.9 kB] 154s Get:22 http://ftpmaster.internal/ubuntu plucky/main arm64 backuppc arm64 4.4.0-10ubuntu1 [518 kB] 154s Get:23 http://ftpmaster.internal/ubuntu plucky/main arm64 samba-common all 2:4.20.4+dfsg-1ubuntu3 [68.1 kB] 154s Get:24 http://ftpmaster.internal/ubuntu plucky/main arm64 libavahi-common-data arm64 0.8-14ubuntu1 [30.5 kB] 154s Get:25 http://ftpmaster.internal/ubuntu plucky/main arm64 libavahi-common3 arm64 0.8-14ubuntu1 [23.1 kB] 154s Get:26 http://ftpmaster.internal/ubuntu plucky/main arm64 libavahi-client3 arm64 0.8-14ubuntu1 [27.3 kB] 154s Get:27 http://ftpmaster.internal/ubuntu plucky/main arm64 libcups2t64 arm64 2.4.11-0ubuntu2 [274 kB] 154s Get:28 http://ftpmaster.internal/ubuntu plucky/main arm64 libtalloc2 arm64 2.4.2-1build2 [26.7 kB] 154s Get:29 http://ftpmaster.internal/ubuntu plucky/main arm64 libtdb1 arm64 1.4.12-1 [48.9 kB] 154s Get:30 http://ftpmaster.internal/ubuntu plucky/main arm64 libtevent0t64 arm64 0.16.1-3 [42.3 kB] 154s Get:31 http://ftpmaster.internal/ubuntu plucky/main arm64 libldb2 arm64 2:2.9.1+samba4.20.4+dfsg-1ubuntu3 [193 kB] 154s Get:32 http://ftpmaster.internal/ubuntu plucky/main arm64 libwbclient0 arm64 2:4.20.4+dfsg-1ubuntu3 [76.6 kB] 154s Get:33 http://ftpmaster.internal/ubuntu plucky/main arm64 samba-libs arm64 2:4.20.4+dfsg-1ubuntu3 [6306 kB] 155s Get:34 http://ftpmaster.internal/ubuntu plucky/main arm64 samba-common-bin arm64 2:4.20.4+dfsg-1ubuntu3 [1321 kB] 155s Get:35 http://ftpmaster.internal/ubuntu plucky/main arm64 liburing2 arm64 2.8-1 [23.9 kB] 155s Get:36 http://ftpmaster.internal/ubuntu plucky/main arm64 samba arm64 2:4.20.4+dfsg-1ubuntu3 [1374 kB] 155s Get:37 http://ftpmaster.internal/ubuntu plucky/main arm64 libsmbclient0 arm64 2:4.20.4+dfsg-1ubuntu3 [62.8 kB] 155s Get:38 http://ftpmaster.internal/ubuntu plucky/main arm64 smbclient arm64 2:4.20.4+dfsg-1ubuntu3 [497 kB] 155s Preconfiguring packages ... 156s Fetched 15.1 MB in 2s (9940 kB/s) 156s Selecting previously unselected package libapr1t64:arm64. 156s (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 ... 80205 files and directories currently installed.) 156s Preparing to unpack .../00-libapr1t64_1.7.5-1_arm64.deb ... 156s Unpacking libapr1t64:arm64 (1.7.5-1) ... 156s Selecting previously unselected package libaprutil1t64:arm64. 156s Preparing to unpack .../01-libaprutil1t64_1.6.3-3ubuntu1_arm64.deb ... 156s Unpacking libaprutil1t64:arm64 (1.6.3-3ubuntu1) ... 156s Selecting previously unselected package libaprutil1-dbd-sqlite3:arm64. 156s Preparing to unpack .../02-libaprutil1-dbd-sqlite3_1.6.3-3ubuntu1_arm64.deb ... 156s Unpacking libaprutil1-dbd-sqlite3:arm64 (1.6.3-3ubuntu1) ... 156s Selecting previously unselected package libaprutil1-ldap:arm64. 156s Preparing to unpack .../03-libaprutil1-ldap_1.6.3-3ubuntu1_arm64.deb ... 156s Unpacking libaprutil1-ldap:arm64 (1.6.3-3ubuntu1) ... 156s Selecting previously unselected package liblua5.4-0:arm64. 156s Preparing to unpack .../04-liblua5.4-0_5.4.7-1_arm64.deb ... 156s Unpacking liblua5.4-0:arm64 (5.4.7-1) ... 156s Selecting previously unselected package apache2-bin. 156s Preparing to unpack .../05-apache2-bin_2.4.62-3ubuntu1_arm64.deb ... 156s Unpacking apache2-bin (2.4.62-3ubuntu1) ... 156s Selecting previously unselected package apache2-data. 156s Preparing to unpack .../06-apache2-data_2.4.62-3ubuntu1_all.deb ... 156s Unpacking apache2-data (2.4.62-3ubuntu1) ... 156s Selecting previously unselected package apache2-utils. 156s Preparing to unpack .../07-apache2-utils_2.4.62-3ubuntu1_arm64.deb ... 156s Unpacking apache2-utils (2.4.62-3ubuntu1) ... 156s Selecting previously unselected package apache2. 156s Preparing to unpack .../08-apache2_2.4.62-3ubuntu1_arm64.deb ... 156s Unpacking apache2 (2.4.62-3ubuntu1) ... 156s Selecting previously unselected package backuppc-rsync. 156s Preparing to unpack .../09-backuppc-rsync_3.1.3.0-4_arm64.deb ... 156s Unpacking backuppc-rsync (3.1.3.0-4) ... 156s Selecting previously unselected package postfix. 156s Preparing to unpack .../10-postfix_3.9.1-9_arm64.deb ... 157s Unpacking postfix (3.9.1-9) ... 157s Selecting previously unselected package libarchive-zip-perl. 157s Preparing to unpack .../11-libarchive-zip-perl_1.68-1_all.deb ... 157s Unpacking libarchive-zip-perl (1.68-1) ... 157s Selecting previously unselected package libbackuppc-xs-perl:arm64. 157s Preparing to unpack .../12-libbackuppc-xs-perl_0.62-2build6_arm64.deb ... 157s Unpacking libbackuppc-xs-perl:arm64 (0.62-2build6) ... 157s Selecting previously unselected package libhtml-tagset-perl. 157s Preparing to unpack .../13-libhtml-tagset-perl_3.24-1_all.deb ... 157s Unpacking libhtml-tagset-perl (3.24-1) ... 157s Selecting previously unselected package liburi-perl. 157s Preparing to unpack .../14-liburi-perl_5.30-1_all.deb ... 157s Unpacking liburi-perl (5.30-1) ... 157s Selecting previously unselected package libhtml-parser-perl:arm64. 157s Preparing to unpack .../15-libhtml-parser-perl_3.83-1build1_arm64.deb ... 157s Unpacking libhtml-parser-perl:arm64 (3.83-1build1) ... 157s Selecting previously unselected package libcgi-pm-perl. 157s Preparing to unpack .../16-libcgi-pm-perl_4.66-1_all.deb ... 157s Unpacking libcgi-pm-perl (4.66-1) ... 157s Selecting previously unselected package libtimedate-perl. 157s Preparing to unpack .../17-libtimedate-perl_2.3300-2_all.deb ... 157s Unpacking libtimedate-perl (2.3300-2) ... 157s Selecting previously unselected package libhttp-date-perl. 157s Preparing to unpack .../18-libhttp-date-perl_6.06-1_all.deb ... 157s Unpacking libhttp-date-perl (6.06-1) ... 157s Selecting previously unselected package libfile-listing-perl. 157s Preparing to unpack .../19-libfile-listing-perl_6.16-1_all.deb ... 157s Unpacking libfile-listing-perl (6.16-1) ... 157s Selecting previously unselected package libtime-parsedate-perl. 157s Preparing to unpack .../20-libtime-parsedate-perl_2015.103-5_all.deb ... 157s Unpacking libtime-parsedate-perl (2015.103-5) ... 157s Selecting previously unselected package backuppc. 157s Preparing to unpack .../21-backuppc_4.4.0-10ubuntu1_arm64.deb ... 157s Unpacking backuppc (4.4.0-10ubuntu1) ... 157s Selecting previously unselected package samba-common. 157s Preparing to unpack .../22-samba-common_2%3a4.20.4+dfsg-1ubuntu3_all.deb ... 157s Unpacking samba-common (2:4.20.4+dfsg-1ubuntu3) ... 157s Selecting previously unselected package libavahi-common-data:arm64. 157s Preparing to unpack .../23-libavahi-common-data_0.8-14ubuntu1_arm64.deb ... 157s Unpacking libavahi-common-data:arm64 (0.8-14ubuntu1) ... 157s Selecting previously unselected package libavahi-common3:arm64. 157s Preparing to unpack .../24-libavahi-common3_0.8-14ubuntu1_arm64.deb ... 157s Unpacking libavahi-common3:arm64 (0.8-14ubuntu1) ... 157s Selecting previously unselected package libavahi-client3:arm64. 157s Preparing to unpack .../25-libavahi-client3_0.8-14ubuntu1_arm64.deb ... 157s Unpacking libavahi-client3:arm64 (0.8-14ubuntu1) ... 157s Selecting previously unselected package libcups2t64:arm64. 157s Preparing to unpack .../26-libcups2t64_2.4.11-0ubuntu2_arm64.deb ... 157s Unpacking libcups2t64:arm64 (2.4.11-0ubuntu2) ... 157s Selecting previously unselected package libtalloc2:arm64. 157s Preparing to unpack .../27-libtalloc2_2.4.2-1build2_arm64.deb ... 157s Unpacking libtalloc2:arm64 (2.4.2-1build2) ... 157s Selecting previously unselected package libtdb1:arm64. 157s Preparing to unpack .../28-libtdb1_1.4.12-1_arm64.deb ... 157s Unpacking libtdb1:arm64 (1.4.12-1) ... 157s Selecting previously unselected package libtevent0t64:arm64. 157s Preparing to unpack .../29-libtevent0t64_0.16.1-3_arm64.deb ... 157s Unpacking libtevent0t64:arm64 (0.16.1-3) ... 157s Selecting previously unselected package libldb2:arm64. 157s Preparing to unpack .../30-libldb2_2%3a2.9.1+samba4.20.4+dfsg-1ubuntu3_arm64.deb ... 157s Unpacking libldb2:arm64 (2:2.9.1+samba4.20.4+dfsg-1ubuntu3) ... 157s Selecting previously unselected package libwbclient0:arm64. 157s Preparing to unpack .../31-libwbclient0_2%3a4.20.4+dfsg-1ubuntu3_arm64.deb ... 157s Unpacking libwbclient0:arm64 (2:4.20.4+dfsg-1ubuntu3) ... 157s Selecting previously unselected package samba-libs:arm64. 158s Preparing to unpack .../32-samba-libs_2%3a4.20.4+dfsg-1ubuntu3_arm64.deb ... 158s Unpacking samba-libs:arm64 (2:4.20.4+dfsg-1ubuntu3) ... 158s Selecting previously unselected package samba-common-bin. 158s Preparing to unpack .../33-samba-common-bin_2%3a4.20.4+dfsg-1ubuntu3_arm64.deb ... 158s Unpacking samba-common-bin (2:4.20.4+dfsg-1ubuntu3) ... 158s Selecting previously unselected package liburing2:arm64. 158s Preparing to unpack .../34-liburing2_2.8-1_arm64.deb ... 158s Unpacking liburing2:arm64 (2.8-1) ... 158s Selecting previously unselected package samba. 158s Preparing to unpack .../35-samba_2%3a4.20.4+dfsg-1ubuntu3_arm64.deb ... 158s Unpacking samba (2:4.20.4+dfsg-1ubuntu3) ... 158s Selecting previously unselected package libsmbclient0:arm64. 158s Preparing to unpack .../36-libsmbclient0_2%3a4.20.4+dfsg-1ubuntu3_arm64.deb ... 158s Unpacking libsmbclient0:arm64 (2:4.20.4+dfsg-1ubuntu3) ... 158s Selecting previously unselected package smbclient. 158s Preparing to unpack .../37-smbclient_2%3a4.20.4+dfsg-1ubuntu3_arm64.deb ... 158s Unpacking smbclient (2:4.20.4+dfsg-1ubuntu3) ... 158s Setting up libarchive-zip-perl (1.68-1) ... 158s Setting up libtdb1:arm64 (1.4.12-1) ... 158s Setting up libhtml-tagset-perl (3.24-1) ... 158s Setting up samba-common (2:4.20.4+dfsg-1ubuntu3) ... 158s Creating config file /etc/samba/smb.conf with new version 158s Setting up libwbclient0:arm64 (2:4.20.4+dfsg-1ubuntu3) ... 158s Setting up libtalloc2:arm64 (2.4.2-1build2) ... 158s Setting up backuppc-rsync (3.1.3.0-4) ... 158s Setting up libavahi-common-data:arm64 (0.8-14ubuntu1) ... 158s Setting up libtimedate-perl (2.3300-2) ... 158s Setting up postfix (3.9.1-9) ... 159s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 159s info: Selecting UID from range 100 to 999 ... 159s 159s info: Selecting GID from range 100 to 999 ... 159s info: Adding system user `postfix' (UID 107) ... 159s info: Adding new group `postfix' (GID 108) ... 159s info: Adding new user `postfix' (UID 107) with group `postfix' ... 159s info: Not creating home directory `/var/spool/postfix'. 159s info: Selecting GID from range 100 to 999 ... 159s info: Adding group `postdrop' (GID 111) ... 159s Creating /etc/postfix/dynamicmaps.cf 159s setting myhostname: autopkgtest.local 159s setting alias maps 159s setting alias database 159s changing /etc/mailname to autopkgtest.local 159s setting myorigin 159s setting destinations: $myhostname, autopkgtest.local, localhost.local, , localhost 159s setting relayhost: 159s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 159s clearing mailbox_command 159s setting mailbox_size_limit: 0 159s setting recipient_delimiter: + 161s setting inet_interfaces: all 163s setting inet_protocols: all 163s /etc/aliases does not exist, creating it. 163s WARNING: /etc/aliases exists, but does not have a root alias. 163s 163s Postfix (main.cf) is now set up with a default configuration. If you need to 163s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 163s Postfix configuration values, see postconf(1). 163s 163s After modifying main.cf, be sure to run 'systemctl reload postfix'. 163s 165s Setting up libtime-parsedate-perl (2015.103-5) ... 165s Setting up libapr1t64:arm64 (1.7.5-1) ... 165s Setting up liblua5.4-0:arm64 (5.4.7-1) ... 165s Setting up apache2-data (2.4.62-3ubuntu1) ... 165s Setting up liburing2:arm64 (2.8-1) ... 165s Setting up libbackuppc-xs-perl:arm64 (0.62-2build6) ... 165s Setting up liburi-perl (5.30-1) ... 165s Setting up libtevent0t64:arm64 (0.16.1-3) ... 165s Setting up libaprutil1t64:arm64 (1.6.3-3ubuntu1) ... 165s Setting up libhttp-date-perl (6.06-1) ... 165s Setting up libfile-listing-perl (6.16-1) ... 165s Setting up libavahi-common3:arm64 (0.8-14ubuntu1) ... 165s Setting up libaprutil1-ldap:arm64 (1.6.3-3ubuntu1) ... 165s Setting up libaprutil1-dbd-sqlite3:arm64 (1.6.3-3ubuntu1) ... 165s Setting up libhtml-parser-perl:arm64 (3.83-1build1) ... 165s Setting up libldb2:arm64 (2:2.9.1+samba4.20.4+dfsg-1ubuntu3) ... 165s Setting up libavahi-client3:arm64 (0.8-14ubuntu1) ... 165s Setting up apache2-utils (2.4.62-3ubuntu1) ... 165s Setting up libcgi-pm-perl (4.66-1) ... 165s Setting up samba-libs:arm64 (2:4.20.4+dfsg-1ubuntu3) ... 165s Setting up apache2-bin (2.4.62-3ubuntu1) ... 165s Setting up libsmbclient0:arm64 (2:4.20.4+dfsg-1ubuntu3) ... 165s Setting up libcups2t64:arm64 (2.4.11-0ubuntu2) ... 165s Setting up apache2 (2.4.62-3ubuntu1) ... 165s Enabling module mpm_event. 165s Enabling module authz_core. 165s Enabling module authz_host. 165s Enabling module authn_core. 165s Enabling module auth_basic. 165s Enabling module access_compat. 165s Enabling module authn_file. 165s Enabling module authz_user. 165s Enabling module alias. 165s Enabling module dir. 165s Enabling module autoindex. 165s Enabling module env. 165s Enabling module mime. 165s Enabling module negotiation. 166s Enabling module setenvif. 166s Enabling module filter. 166s Enabling module deflate. 166s Enabling module status. 166s Enabling module reqtimeout. 166s Enabling conf charset. 166s Enabling conf localized-error-pages. 166s Enabling conf other-vhosts-access-log. 166s Enabling conf security. 166s Enabling conf serve-cgi-bin. 166s Enabling site 000-default. 167s Created symlink '/etc/systemd/system/multi-user.target.wants/apache2.service' → '/usr/lib/systemd/system/apache2.service'. 167s Created symlink '/etc/systemd/system/multi-user.target.wants/apache-htcacheclean.service' → '/usr/lib/systemd/system/apache-htcacheclean.service'. 168s Setting up backuppc (4.4.0-10ubuntu1) ... 168s 168s apache2_invoke: Enable configuration backuppc.conf 169s warn: The home directory `/var/lib/backuppc' already exists. Not touching this directory. 169s warn: Warning: The home directory `/var/lib/backuppc' does not belong to the user you are currently creating. 169s Adding password for user backuppc 169s Considering dependency authn_core for auth_basic: 169s Module authn_core already enabled 169s Module auth_basic already enabled 169s Considering dependency authz_core for authz_groupfile: 169s Module authz_core already enabled 169s Enabling module authz_groupfile. 169s To activate the new configuration, you need to run: 169s systemctl restart apache2 169s Module authn_file already enabled 169s Considering dependency authz_core for authz_user: 169s Module authz_core already enabled 169s Module authz_user already enabled 169s Your MPM seems to be threaded. Selecting cgid instead of cgi. 169s Enabling module cgid. 169s To activate the new configuration, you need to run: 169s systemctl restart apache2 169s Creating config file /etc/backuppc/config.pl with new version 169s Created symlink '/etc/systemd/system/multi-user.target.wants/backuppc.service' → '/usr/lib/systemd/system/backuppc.service'. 170s Setting up smbclient (2:4.20.4+dfsg-1ubuntu3) ... 170s Setting up samba-common-bin (2:4.20.4+dfsg-1ubuntu3) ... 170s Setting up samba (2:4.20.4+dfsg-1ubuntu3) ... 171s Created symlink '/etc/systemd/system/smb.service' → '/usr/lib/systemd/system/smbd.service'. 171s Created symlink '/etc/systemd/system/multi-user.target.wants/smbd.service' → '/usr/lib/systemd/system/smbd.service'. 171s Created symlink '/etc/systemd/system/nmb.service' → '/usr/lib/systemd/system/nmbd.service'. 171s Created symlink '/etc/systemd/system/multi-user.target.wants/nmbd.service' → '/usr/lib/systemd/system/nmbd.service'. 172s Processing triggers for rsyslog (8.2406.0-1ubuntu2) ... 172s Processing triggers for ufw (0.36.2-8) ... 172s Processing triggers for man-db (2.13.0-1) ... 173s Processing triggers for libc-bin (2.40-4ubuntu1) ... 173s Processing triggers for postfix (3.9.1-9) ... 173s Restarting postfix 174s autopkgtest [11:35:42]: test smb-backup: [----------------------- 175s net.ipv4.ping_group_range = 0 65534 175s Populating share path 175s Adding samba share 175s Adding [public] share 175s Configuring backuppc 175s Performing a full backup 175s Backup type: type = full, needs_full = 1, needs_incr = 1, lastFullTime = 0, opts{f} = 1, opts{i} = , opts{F} = 175s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 175s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 175s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.024 175s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.0.z created 2025-01-16 11:35:43 175s Backup prep: type = full, case = 1, inPlace = 1, doDuplicate = 0, newBkupNum = 0, newBkupIdx = 0, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 175s __bpc_progress_state__ backup share "public" 175s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 175s full backup started for share public 175s started full dump, share=public 175s Xfer PIDs are now 3783,3782 175s xferPids 3783,3782 175s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 175s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 175s tarmode is now full, system, hidden, noreset, noverbose 175s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 175s __bpc_progress_fileCnt__ 0 175s new 644 0/0 480434 tmp.eST4ZD 175s new 644 0/0 480434 tmp.pwjI2X 175s new 644 0/0 480434 tmp.ndA3Hl 175s new 644 0/0 480434 tmp.0aKagg 175s new 644 0/0 480434 tmp.sCxkIp 175s new 644 0/0 480434 tmp.dC3HTa 175s new 644 0/0 480434 tmp.b0wgQn 175s new 644 0/0 480434 tmp.Pvuk7d 175s new 644 0/0 480434 tmp.p0XJSV 175s tar: dumped 10 files and 0 directories 175s Total bytes written: 4804340 (31.8 MiB/s) 175s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 175s new 644 0/0 480434 tmp.0Fzj7K 175s __bpc_progress_fileCnt__ 10 175s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377755 sizeNewComp, 13 inodeLast 175s Xfer PIDs are now 175s xferPids 175s full backup 0 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 175s BackupExpire: cntFull = 1, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 0 175s __bpc_progress_state__ fsck 175s Running BackupPC_refCountUpdate -h localhost on localhost 175s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 175s Xfer PIDs are now 3789 175s xferPids 3789 175s BackupPC_refCountUpdate: doing fsck on localhost #0 (full) since $ConfRefCntFsck == 1 175s __bpc_progress_state__ refCnt #0 175s __bpc_progress_state__ cntUpdate #0 175s __bpc_progress_state__ rename #0 175s __bpc_progress_state__ sumUpdate 175s __bpc_progress_fileCnt__ 0/128 175s __bpc_progress_fileCnt__ 8/128 175s __bpc_progress_fileCnt__ 16/128 175s __bpc_progress_fileCnt__ 24/128 175s __bpc_progress_fileCnt__ 32/128 175s __bpc_progress_fileCnt__ 40/128 175s __bpc_progress_fileCnt__ 48/128 175s __bpc_progress_fileCnt__ 56/128 175s __bpc_progress_fileCnt__ 64/128 175s __bpc_progress_fileCnt__ 72/128 175s __bpc_progress_fileCnt__ 80/128 175s __bpc_progress_fileCnt__ 88/128 175s __bpc_progress_fileCnt__ 96/128 175s __bpc_progress_fileCnt__ 104/128 175s __bpc_progress_fileCnt__ 112/128 175s __bpc_progress_fileCnt__ 120/128 175s __bpc_progress_state__ rename total 175s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 175s Xfer PIDs are now 175s xferPids 175s Finished BackupPC_refCountUpdate (running time: 0 sec) 175s Xfer PIDs are now 175s xferPids 175s full backup complete 175s OK: Full backup succeeded 175s Changing share content and performing an incremental backup 176s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1737027343, opts{f} = , opts{i} = 1, opts{F} = 176s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 176s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 176s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.022 176s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.1.z created 2025-01-16 11:35:44 176s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 1, newBkupIdx = 1, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 176s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 176s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 176s Xfer PIDs are now 3859 176s xferPids 3859 176s __bpc_progress_state__ copy #0 -> #1 176s Copying backup #0 to #1 176s __bpc_progress_fileCnt__ 3 dirs 176s Xfer PIDs are now 3859,3862 176s xferPids 3859,3862 176s __bpc_progress_state__ sumUpdate 176s __bpc_progress_fileCnt__ 0/128 176s __bpc_progress_fileCnt__ 8/128 176s __bpc_progress_fileCnt__ 16/128 176s __bpc_progress_fileCnt__ 24/128 176s __bpc_progress_fileCnt__ 32/128 176s __bpc_progress_fileCnt__ 40/128 176s __bpc_progress_fileCnt__ 48/128 176s __bpc_progress_fileCnt__ 56/128 176s __bpc_progress_fileCnt__ 64/128 176s __bpc_progress_fileCnt__ 72/128 176s __bpc_progress_fileCnt__ 80/128 176s __bpc_progress_fileCnt__ 88/128 176s __bpc_progress_fileCnt__ 96/128 176s __bpc_progress_fileCnt__ 104/128 176s __bpc_progress_fileCnt__ 112/128 176s __bpc_progress_fileCnt__ 120/128 176s __bpc_progress_state__ rename total 176s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 176s Xfer PIDs are now 3859 176s xferPids 3859 176s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 176s Finished BackupPC_backupDuplicate (running time: 0 sec) 176s __bpc_progress_state__ backup share "public" 176s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 176s incr backup started back to 2025-01-16 10:35:43 (backup #0) for share public 176s started incr dump, share=public 176s Xfer PIDs are now 3866,3865 176s xferPids 3866,3865 176s 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 - 176s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 176s tar:1298 Getting files newer than Thu Jan 16 10:35:43 2025 176s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 176s tarmode is now full, system, hidden, noreset, noverbose 176s __bpc_progress_fileCnt__ 0 176s new 644 0/0 480434 tmp.foOp7I 176s same 644 0/0 480434 tmp.eST4ZD 176s same 644 0/0 480434 tmp.pwjI2X 176s new 644 0/0 480434 tmp.964qlG 176s new 644 0/0 480434 tmp.jbtNtU 176s new 644 0/0 480434 tmp.4srUsE 176s same 644 0/0 480434 tmp.ndA3Hl 176s new 644 0/0 480434 tmp.2IARxk 176s same 644 0/0 480434 tmp.0aKagg 176s new 644 0/0 480434 tmp.0hBtmV 176s same 644 0/0 480434 tmp.sCxkIp 176s new 644 0/0 480434 tmp.dwI9CV 176s same 644 0/0 480434 tmp.dC3HTa 176s same 644 0/0 480434 tmp.b0wgQn 176s new 644 0/0 480434 tmp.pdddL1 176s same 644 0/0 480434 tmp.Pvuk7d 176s new 644 0/0 480434 tmp.xqSnRX 176s same 644 0/0 480434 tmp.p0XJSV 176s same 644 0/0 480434 tmp.0Fzj7K 176s tar: dumped 20 files and 0 directories 176s Total bytes written: 9608680 (37.9 MiB/s) 176s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 176s new 644 0/0 480434 tmp.XGfwQv 176s __bpc_progress_fileCnt__ 20 176s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377755 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377797 sizeNewComp, 24 inodeLast 176s Xfer PIDs are now 176s xferPids 176s incr backup 1 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 176s BackupExpire: cntFull = 2, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 1.15740740740741e-05 176s __bpc_progress_state__ fsck 176s Running BackupPC_refCountUpdate -h localhost on localhost 176s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 176s Xfer PIDs are now 3872 176s xferPids 3872 176s __bpc_progress_state__ cntUpdate #1 176s __bpc_progress_state__ rename #1 176s __bpc_progress_state__ sumUpdate 176s __bpc_progress_fileCnt__ 0/128 176s __bpc_progress_fileCnt__ 8/128 176s __bpc_progress_fileCnt__ 16/128 176s __bpc_progress_fileCnt__ 24/128 176s __bpc_progress_fileCnt__ 32/128 176s __bpc_progress_fileCnt__ 40/128 176s __bpc_progress_fileCnt__ 48/128 176s __bpc_progress_fileCnt__ 56/128 176s __bpc_progress_fileCnt__ 64/128 176s __bpc_progress_fileCnt__ 72/128 176s __bpc_progress_fileCnt__ 80/128 176s __bpc_progress_fileCnt__ 88/128 176s __bpc_progress_fileCnt__ 96/128 176s __bpc_progress_fileCnt__ 104/128 176s __bpc_progress_fileCnt__ 112/128 176s __bpc_progress_fileCnt__ 120/128 176s __bpc_progress_state__ rename total 176s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 176s Xfer PIDs are now 176s xferPids 176s Finished BackupPC_refCountUpdate (running time: 0 sec) 176s Xfer PIDs are now 176s xferPids 176s incr backup complete 176s OK: Incremental backup succeeded 176s Done. 177s autopkgtest [11:35:45]: test smb-backup: -----------------------] 177s smb-backup PASS 177s autopkgtest [11:35:45]: test smb-backup: - - - - - - - - - - results - - - - - - - - - - 178s autopkgtest [11:35:46]: test authenticated-smb-backup: preparing testbed 178s Reading package lists... 178s Building dependency tree... 178s Reading state information... 179s Starting pkgProblemResolver with broken count: 0 179s Starting 2 pkgProblemResolver with broken count: 0 179s Done 179s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 180s autopkgtest [11:35:48]: test authenticated-smb-backup: [----------------------- 180s Populating share path 181s Adding samba share 181s Adding [private] share 181s Creating user 181s Creating a local and samba user called smbtest4022 181s Setting samba password for the smbtest4022 user 181s Added user smbtest4022. 181s Configuring backuppc 181s Performing a full backup 181s Backup type: type = full, needs_full = , needs_incr = , lastFullTime = 1737027343, opts{f} = 1, opts{i} = , opts{F} = 181s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 181s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 181s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.021 181s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.2.z created 2025-01-16 11:35:49 181s Backup prep: type = full, case = 4, inPlace = 0, doDuplicate = 0, newBkupNum = 2, newBkupIdx = 2, lastBkupNum = 1, lastBkupIdx = 1 (FillCycle = 0, noFillCnt = 0) 181s __bpc_progress_state__ backup share "private" 181s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest4022 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 181s full backup started for share private 181s started full dump, share=private 181s Xfer PIDs are now 4114,4113 181s xferPids 4114,4113 181s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest4022 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 181s tarmode is now full, system, hidden, noreset, noverbose 181s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 181s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 181s __bpc_progress_fileCnt__ 0 181s new 644 0/0 480434 tmp.ayd0v7 181s new 644 0/0 480434 tmp.EHREAy 181s new 644 0/0 480434 tmp.P0Z5rh 181s new 644 0/0 480434 tmp.F5vETv 181s new 644 0/0 480434 tmp.LgY574 181s new 644 0/0 480434 tmp.XV68mJ 181s new 644 0/0 480434 tmp.okxAa5 181s new 644 0/0 480434 tmp.Qf289e 181s new 644 0/0 480434 tmp.gULt58 181s tar: dumped 10 files and 0 directories 181s Total bytes written: 4804340 (30.2 MiB/s) 181s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 181s new 644 0/0 480434 tmp.2oGgey 181s __bpc_progress_fileCnt__ 10 181s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377756 sizeNewComp, 36 inodeLast 181s Xfer PIDs are now 181s xferPids 181s OrphanShareNameClean: removing orphan share public from /var/lib/backuppc/pc/localhost/2 181s __bpc_progress_state__ delete share #2/public 181s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDelete -h localhost -n 2 -l -m -s public / 181s Xfer PIDs are now 4120 181s xferPids 4120 181s BackupPC_backupDelete: removing #2/public/ 181s __bpc_progress_state__ merge #2/public/ -> #1/public/ 181s BackupPC_backupDelete: Merge into backup 1/public/ 181s mergeDir: attr write to /var/lib/backuppc/pc/localhost/1/fpublic//attrib failed (digest was ) 181s bpc_attrib_dirWrite: can't open/create raw /var/lib/backuppc/pc/localhost/1/fpublic for writing 181s Xfer PIDs are now 4123,4120 181s xferPids 4123,4120 181s __bpc_progress_state__ refCnt #1 181s __bpc_progress_state__ cntUpdate #1 181s __bpc_progress_state__ rename #1 181s __bpc_progress_state__ refCnt #2 181s BackupPC_refCountUpdate: localhost #2 inodeLast set to 37 (was 24) 181s __bpc_progress_state__ cntUpdate #2 181s __bpc_progress_state__ rename #2 181s __bpc_progress_state__ sumUpdate 181s __bpc_progress_fileCnt__ 0/128 181s __bpc_progress_fileCnt__ 8/128 181s __bpc_progress_fileCnt__ 16/128 181s __bpc_progress_fileCnt__ 24/128 181s __bpc_progress_fileCnt__ 32/128 181s __bpc_progress_fileCnt__ 40/128 181s __bpc_progress_fileCnt__ 48/128 181s __bpc_progress_fileCnt__ 56/128 181s __bpc_progress_fileCnt__ 64/128 181s __bpc_progress_fileCnt__ 72/128 181s __bpc_progress_fileCnt__ 80/128 181s __bpc_progress_fileCnt__ 88/128 181s __bpc_progress_fileCnt__ 96/128 181s __bpc_progress_fileCnt__ 104/128 181s __bpc_progress_fileCnt__ 112/128 181s __bpc_progress_fileCnt__ 120/128 181s __bpc_progress_state__ rename total 181s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 181s Xfer PIDs are now 4120 181s xferPids 4120 181s Xfer PIDs are now 181s xferPids 181s Finished BackupPC_backupDelete, status = 0 (running time: 0 sec) 181s Xfer PIDs are now 181s xferPids 181s full backup 2 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 181s BackupExpire: cntFull = 2, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 5.78703703703704e-05, oldestFull = 6.94444444444444e-05 181s __bpc_progress_state__ fsck 181s Running BackupPC_refCountUpdate -h localhost on localhost 181s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 181s Xfer PIDs are now 4126 181s xferPids 4126 181s BackupPC_refCountUpdate: doing fsck on localhost #2 (full) since $ConfRefCntFsck == 1 181s __bpc_progress_state__ refCnt #2 181s __bpc_progress_state__ cntUpdate #2 181s __bpc_progress_state__ rename #2 181s __bpc_progress_state__ sumUpdate 181s __bpc_progress_fileCnt__ 0/128 181s __bpc_progress_fileCnt__ 8/128 181s __bpc_progress_fileCnt__ 16/128 181s __bpc_progress_fileCnt__ 24/128 181s __bpc_progress_fileCnt__ 32/128 181s __bpc_progress_fileCnt__ 40/128 181s __bpc_progress_fileCnt__ 48/128 181s __bpc_progress_fileCnt__ 56/128 181s __bpc_progress_fileCnt__ 64/128 181s __bpc_progress_fileCnt__ 72/128 181s __bpc_progress_fileCnt__ 80/128 181s __bpc_progress_fileCnt__ 88/128 181s __bpc_progress_fileCnt__ 96/128 181s __bpc_progress_fileCnt__ 104/128 181s __bpc_progress_fileCnt__ 112/128 181s __bpc_progress_fileCnt__ 120/128 181s __bpc_progress_state__ rename total 181s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 181s Xfer PIDs are now 181s xferPids 181s Finished BackupPC_refCountUpdate (running time: 0 sec) 181s Xfer PIDs are now 181s xferPids 181s full backup complete 181s OK: Full backup succeeded 181s Changing share content and performing an incremental backup 182s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1737027349, opts{f} = , opts{i} = 1, opts{F} = 182s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 182s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 182s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.021 182s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.3.z created 2025-01-16 11:35:50 182s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 3, newBkupIdx = 3, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = 1) 182s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 182s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 182s Xfer PIDs are now 4196 182s xferPids 4196 182s __bpc_progress_state__ copy #2 -> #3 182s Copying backup #2 to #3 182s __bpc_progress_fileCnt__ 3 dirs 182s Xfer PIDs are now 4199,4196 182s xferPids 4199,4196 182s __bpc_progress_state__ sumUpdate 182s __bpc_progress_fileCnt__ 0/128 182s __bpc_progress_fileCnt__ 8/128 182s __bpc_progress_fileCnt__ 16/128 182s __bpc_progress_fileCnt__ 24/128 182s __bpc_progress_fileCnt__ 32/128 182s __bpc_progress_fileCnt__ 40/128 182s __bpc_progress_fileCnt__ 48/128 182s __bpc_progress_fileCnt__ 56/128 182s __bpc_progress_fileCnt__ 64/128 182s __bpc_progress_fileCnt__ 72/128 182s __bpc_progress_fileCnt__ 80/128 182s __bpc_progress_fileCnt__ 88/128 182s __bpc_progress_fileCnt__ 96/128 182s __bpc_progress_fileCnt__ 104/128 182s __bpc_progress_fileCnt__ 112/128 182s __bpc_progress_fileCnt__ 120/128 182s __bpc_progress_state__ rename total 182s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 182s Xfer PIDs are now 4196 182s xferPids 4196 182s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 182s Finished BackupPC_backupDuplicate (running time: 0 sec) 182s __bpc_progress_state__ backup share "private" 182s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest4022 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 182s incr backup started back to 2025-01-16 10:35:49 (backup #2) for share private 182s started incr dump, share=private 182s Xfer PIDs are now 4203,4202 182s xferPids 4203,4202 182s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest4022 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 182s tar:1298 Getting files newer than Thu Jan 16 10:35:49 2025 182s tarmode is now full, system, hidden, noreset, noverbose 182s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 182s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 182s __bpc_progress_fileCnt__ 0 182s new 644 0/0 480434 tmp.5GyV42 182s new 644 0/0 480434 tmp.IdDKfe 182s same 644 0/0 480434 tmp.ayd0v7 182s new 644 0/0 480434 tmp.3sHH2f 182s same 644 0/0 480434 tmp.EHREAy 182s new 644 0/0 480434 tmp.J9FyNF 182s new 644 0/0 480434 tmp.bzsjJq 182s new 644 0/0 480434 tmp.paZCRb 182s same 644 0/0 480434 tmp.P0Z5rh 182s new 644 0/0 480434 tmp.5wfOeF 182s same 644 0/0 480434 tmp.F5vETv 182s same 644 0/0 480434 tmp.LgY574 182s same 644 0/0 480434 tmp.XV68mJ 182s same 644 0/0 480434 tmp.okxAa5 182s new 644 0/0 480434 tmp.rfhI1z 182s new 644 0/0 480434 tmp.IDy6Jc 182s same 644 0/0 480434 tmp.Qf289e 182s same 644 0/0 480434 tmp.gULt58 182s same 644 0/0 480434 tmp.2oGgey 182s tar: dumped 20 files and 0 directories 182s Total bytes written: 9608680 (48.4 MiB/s) 182s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 182s new 644 0/0 480434 tmp.eJXv08 182s __bpc_progress_fileCnt__ 20 182s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377756 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377820 sizeNewComp, 47 inodeLast 182s Xfer PIDs are now 182s xferPids 182s incr backup 3 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 182s BackupExpire: cntFull = 3, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 6.94444444444444e-05, oldestFull = 8.10185185185185e-05 182s __bpc_progress_state__ fsck 182s Running BackupPC_refCountUpdate -h localhost on localhost 182s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 182s Xfer PIDs are now 4209 182s xferPids 4209 182s __bpc_progress_state__ cntUpdate #3 182s __bpc_progress_state__ rename #3 182s __bpc_progress_state__ sumUpdate 182s __bpc_progress_fileCnt__ 0/128 182s __bpc_progress_fileCnt__ 8/128 182s __bpc_progress_fileCnt__ 16/128 182s __bpc_progress_fileCnt__ 24/128 182s __bpc_progress_fileCnt__ 32/128 182s __bpc_progress_fileCnt__ 40/128 182s __bpc_progress_fileCnt__ 48/128 182s __bpc_progress_fileCnt__ 56/128 182s __bpc_progress_fileCnt__ 64/128 182s __bpc_progress_fileCnt__ 72/128 182s __bpc_progress_fileCnt__ 80/128 182s __bpc_progress_fileCnt__ 88/128 182s __bpc_progress_fileCnt__ 96/128 182s __bpc_progress_fileCnt__ 104/128 182s __bpc_progress_fileCnt__ 112/128 182s __bpc_progress_fileCnt__ 120/128 182s __bpc_progress_state__ rename total 182s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 182s Xfer PIDs are now 182s xferPids 182s Finished BackupPC_refCountUpdate (running time: 0 sec) 182s Xfer PIDs are now 182s xferPids 182s incr backup complete 182s OK: Incremental backup succeeded 182s Done. 182s Deleted user smbtest4022. 182s userdel: smbtest4022 mail spool (/var/mail/smbtest4022) not found 183s autopkgtest [11:35:51]: test authenticated-smb-backup: -----------------------] 183s autopkgtest [11:35:51]: test authenticated-smb-backup: - - - - - - - - - - results - - - - - - - - - - 183s authenticated-smb-backup PASS 184s autopkgtest [11:35:52]: @@@@@@@@@@@@@@@@@@@@ summary 184s smb-backup PASS 184s authenticated-smb-backup PASS 200s nova [W] Using flock in prodstack6-arm64 200s Creating nova instance adt-plucky-arm64-backuppc-20250116-113247-juju-7f2275-prod-proposed-migration-environment-2-216ef7e5-87a8-45b4-bfe7-129564636428 from image adt/ubuntu-plucky-arm64-server-20250116.img (UUID b3960940-0e71-49d1-8b5d-cc75ec0ebb81)... 200s nova [W] Timed out waiting for caf2bac6-85ac-4036-837d-360cd54dc64f to get deleted.