0s autopkgtest [03:58:17]: starting date and time: 2026-02-03 03:58:17+0000 0s autopkgtest [03:58:17]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [03:58:17]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.gqused9i/out --timeout-copy=6000 --needs-internet=try --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:debconf --apt-upgrade backuppc --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=debconf/1.5.91build1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-arm64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-arm64-6.secgroup --name adt-resolute-arm64-backuppc-20260203-035817-juju-7f2275-prod-proposed-migration-environment-15-a3263211-b9ea-41cc-a62c-883d401c1f42 --image adt/ubuntu-resolute-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-arm64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-arm64-backuppc-20260203-035817-juju-7f2275-prod-proposed-migration-environment-15-a3263211-b9ea-41cc-a62c-883d401c1f42 from image adt/ubuntu-resolute-arm64-server-20260202.img (UUID e5b8703b-d12e-4238-a70e-a72a9b0d7948)... 58s autopkgtest [03:59:15]: testbed dpkg architecture: arm64 58s autopkgtest [03:59:15]: testbed apt version: 3.1.14 59s autopkgtest [03:59:16]: @@@@@@@@@@@@@@@@@@@@ test bed setup 59s autopkgtest [03:59:16]: testbed release detected to be: None 60s autopkgtest [03:59:17]: updating testbed package index (apt update) 60s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB] 60s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 60s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 60s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 60s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1275 kB] 60s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [28.4 kB] 60s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5256 B] 60s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [271 kB] 60s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 Packages [309 kB] 60s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 c-n-f Metadata [3628 B] 60s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 Packages [52.9 kB] 60s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 c-n-f Metadata [328 B] 60s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 Packages [1116 kB] 60s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 c-n-f Metadata [26.2 kB] 60s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 Packages [21.8 kB] 60s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 c-n-f Metadata [756 B] 63s Fetched 3234 kB in 1s (4958 kB/s) 64s Reading package lists... 64s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 64s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 64s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 64s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 65s Reading package lists... 65s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Calculating upgrade... 66s The following packages will be upgraded: 66s cloud-init cloud-init-base debconf debconf-i18n libexpat1 66s liblocale-gettext-perl python3-debconf 66s 7 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 66s Need to get 1085 kB of archives. 66s After this operation, 2048 B of additional disk space will be used. 66s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 libexpat1 arm64 2.7.4-1 [85.7 kB] 66s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 liblocale-gettext-perl arm64 1.07-8 [15.6 kB] 66s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 python3-debconf all 1.5.91build1 [4174 B] 66s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 debconf-i18n all 1.5.91build1 [227 kB] 66s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 debconf all 1.5.91build1 [126 kB] 66s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 cloud-init-base all 25.4~4g26c7d38d-0ubuntu1 [624 kB] 66s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 cloud-init all 25.4~4g26c7d38d-0ubuntu1 [2120 B] 67s dpkg-preconfigure: unable to re-open stdin: No such file or directory 67s Fetched 1085 kB in 0s (7669 kB/s) 67s (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 ... 89359 files and directories currently installed.) 67s Preparing to unpack .../libexpat1_2.7.4-1_arm64.deb ... 67s Unpacking libexpat1:arm64 (2.7.4-1) over (2.7.3-1) ... 67s Preparing to unpack .../liblocale-gettext-perl_1.07-8_arm64.deb ... 68s Unpacking liblocale-gettext-perl (1.07-8) over (1.07-7build1) ... 68s Preparing to unpack .../python3-debconf_1.5.91build1_all.deb ... 68s Unpacking python3-debconf (1.5.91build1) over (1.5.91) ... 68s Preparing to unpack .../debconf-i18n_1.5.91build1_all.deb ... 68s Unpacking debconf-i18n (1.5.91build1) over (1.5.91) ... 68s Preparing to unpack .../debconf_1.5.91build1_all.deb ... 68s Unpacking debconf (1.5.91build1) over (1.5.91) ... 68s Setting up debconf (1.5.91build1) ... 68s (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 ... 89359 files and directories currently installed.) 68s Preparing to unpack .../cloud-init-base_25.4~4g26c7d38d-0ubuntu1_all.deb ... 69s Unpacking cloud-init-base (25.4~4g26c7d38d-0ubuntu1) over (25.4~3g78f6f15e-0ubuntu1) ... 69s Preparing to unpack .../cloud-init_25.4~4g26c7d38d-0ubuntu1_all.deb ... 69s Unpacking cloud-init (25.4~4g26c7d38d-0ubuntu1) over (25.4~3g78f6f15e-0ubuntu1) ... 69s Setting up libexpat1:arm64 (2.7.4-1) ... 69s Setting up python3-debconf (1.5.91build1) ... 69s Setting up cloud-init-base (25.4~4g26c7d38d-0ubuntu1) ... 69s Clearing ignored debconf value cloud-init/datasources 69s Encountered debconf setting for cloud-init-base/datasources. 71s Setting up liblocale-gettext-perl (1.07-8) ... 71s Setting up cloud-init (25.4~4g26c7d38d-0ubuntu1) ... 71s Setting up debconf-i18n (1.5.91build1) ... 71s Processing triggers for libc-bin (2.42-2ubuntu4) ... 71s Processing triggers for rsyslog (8.2512.0-1ubuntu2) ... 71s Processing triggers for man-db (2.13.1-1) ... 73s autopkgtest [03:59:30]: upgrading testbed (apt dist-upgrade and autopurge) 73s Reading package lists... 73s Building dependency tree... 73s Reading state information... 73s Calculating upgrade... 74s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 74s Reading package lists... 74s Building dependency tree... 74s Reading state information... 74s Solving dependencies... 75s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 75s autopkgtest [03:59:32]: rebooting testbed after setup commands that affected boot 113s autopkgtest [04:00:10]: testbed running kernel: Linux 6.18.0-9-generic #9-Ubuntu SMP PREEMPT_DYNAMIC Mon Jan 12 16:41:39 UTC 2026 116s autopkgtest [04:00:13]: @@@@@@@@@@@@@@@@@@@@ apt-source backuppc 117s Get:1 http://ftpmaster.internal/ubuntu resolute/main backuppc 4.4.0-11 (dsc) [2169 B] 117s Get:2 http://ftpmaster.internal/ubuntu resolute/main backuppc 4.4.0-11 (tar) [657 kB] 117s Get:3 http://ftpmaster.internal/ubuntu resolute/main backuppc 4.4.0-11 (diff) [37.9 kB] 117s gpgv: Signature made Sun Mar 30 09:20:11 2025 UTC 117s gpgv: using RSA key FDDD0CFF3864277630A2186C9164FA1D17BD5D36 117s gpgv: Can't check signature: No public key 117s dpkg-source: warning: cannot verify inline signature for ./backuppc_4.4.0-11.dsc: no acceptable signature found 117s autopkgtest [04:00:14]: testing package backuppc version 4.4.0-11 118s autopkgtest [04:00:15]: build not needed 119s autopkgtest [04:00:16]: test smb-backup: preparing testbed 119s Reading package lists... 120s Building dependency tree... 120s Reading state information... 120s Solving dependencies... 120s The following NEW packages will be installed: 120s apache2 apache2-bin apache2-data apache2-utils backuppc backuppc-rsync 120s libapr1t64 libaprutil1-dbd-sqlite3 libaprutil1-ldap libaprutil1t64 120s libarchive-zip-perl libavahi-client3 libavahi-common-data libavahi-common3 120s libbackuppc-xs-perl libcgi-pm-perl libcups2t64 libfile-listing-perl 120s libhtml-parser-perl libhtml-tagset-perl libhttp-date-perl libldb2 120s liblua5.4-0 libnsl2 libsmbclient0 libtalloc2 libtdb1 libtevent0t64 120s libtime-parsedate-perl libtimedate-perl liburi-perl liburing2 libwbclient0 120s postfix samba samba-common samba-common-bin samba-libs smbclient 120s 0 upgraded, 39 newly installed, 0 to remove and 0 not upgraded. 120s Need to get 15.5 MB of archives. 120s After this operation, 77.9 MB of additional disk space will be used. 120s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 libapr1t64 arm64 1.7.6-3 [107 kB] 120s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 libaprutil1t64 arm64 1.6.3-3ubuntu3 [92.9 kB] 120s Get:3 http://ftpmaster.internal/ubuntu resolute/main arm64 libaprutil1-dbd-sqlite3 arm64 1.6.3-3ubuntu3 [11.4 kB] 120s Get:4 http://ftpmaster.internal/ubuntu resolute/main arm64 libaprutil1-ldap arm64 1.6.3-3ubuntu3 [9066 B] 120s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 liblua5.4-0 arm64 5.4.8-1 [171 kB] 120s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 apache2-bin arm64 2.4.65-3ubuntu1 [1322 kB] 121s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 apache2-data all 2.4.65-3ubuntu1 [163 kB] 121s Get:8 http://ftpmaster.internal/ubuntu resolute/main arm64 apache2-utils arm64 2.4.65-3ubuntu1 [98.8 kB] 121s Get:9 http://ftpmaster.internal/ubuntu resolute/main arm64 apache2 arm64 2.4.65-3ubuntu1 [93.4 kB] 121s Get:10 http://ftpmaster.internal/ubuntu resolute/main arm64 backuppc-rsync arm64 3.1.3.0-5 [255 kB] 121s Get:11 http://ftpmaster.internal/ubuntu resolute/main arm64 libnsl2 arm64 1.3.0-3build4 [41.7 kB] 121s Get:12 http://ftpmaster.internal/ubuntu resolute/main arm64 postfix arm64 3.10.6-4ubuntu1 [1292 kB] 121s Get:13 http://ftpmaster.internal/ubuntu resolute/main arm64 libarchive-zip-perl all 1.68-1 [90.2 kB] 121s Get:14 http://ftpmaster.internal/ubuntu resolute/main arm64 libbackuppc-xs-perl arm64 0.62-2build6 [64.6 kB] 121s Get:15 http://ftpmaster.internal/ubuntu resolute/main arm64 libhtml-tagset-perl all 3.24-1 [14.1 kB] 121s Get:16 http://ftpmaster.internal/ubuntu resolute/main arm64 liburi-perl all 5.34-2build1 [100 kB] 121s Get:17 http://ftpmaster.internal/ubuntu resolute/main arm64 libhtml-parser-perl arm64 3.83-1build1 [85.3 kB] 121s Get:18 http://ftpmaster.internal/ubuntu resolute/main arm64 libcgi-pm-perl all 4.71-1build1 [185 kB] 121s Get:19 http://ftpmaster.internal/ubuntu resolute/main arm64 libtimedate-perl all 2.3300-2 [34.0 kB] 121s Get:20 http://ftpmaster.internal/ubuntu resolute/main arm64 libhttp-date-perl all 6.06-1 [10.2 kB] 121s Get:21 http://ftpmaster.internal/ubuntu resolute/main arm64 libfile-listing-perl all 6.16-1 [11.3 kB] 121s Get:22 http://ftpmaster.internal/ubuntu resolute/main arm64 libtime-parsedate-perl all 2015.103-5 [27.9 kB] 121s Get:23 http://ftpmaster.internal/ubuntu resolute/main arm64 backuppc arm64 4.4.0-11 [518 kB] 121s Get:24 http://ftpmaster.internal/ubuntu resolute/main arm64 samba-common all 2:4.23.4+dfsg-1ubuntu1 [74.4 kB] 121s Get:25 http://ftpmaster.internal/ubuntu resolute/main arm64 libavahi-common-data arm64 0.8-17ubuntu2 [31.5 kB] 121s Get:26 http://ftpmaster.internal/ubuntu resolute/main arm64 libavahi-common3 arm64 0.8-17ubuntu2 [22.6 kB] 121s Get:27 http://ftpmaster.internal/ubuntu resolute/main arm64 libavahi-client3 arm64 0.8-17ubuntu2 [26.7 kB] 121s Get:28 http://ftpmaster.internal/ubuntu resolute/main arm64 libcups2t64 arm64 2.4.16-1ubuntu1 [271 kB] 121s Get:29 http://ftpmaster.internal/ubuntu resolute/main arm64 libtalloc2 arm64 2:2.4.3+samba4.23.4+dfsg-1ubuntu1 [76.8 kB] 121s Get:30 http://ftpmaster.internal/ubuntu resolute/main arm64 libtdb1 arm64 2:1.4.14+samba4.23.4+dfsg-1ubuntu1 [98.4 kB] 121s Get:31 http://ftpmaster.internal/ubuntu resolute/main arm64 libtevent0t64 arm64 2:0.17.1+samba4.23.4+dfsg-1ubuntu1 [41.1 kB] 121s Get:32 http://ftpmaster.internal/ubuntu resolute/main arm64 libldb2 arm64 2:2.11.0+samba4.23.4+dfsg-1ubuntu1 [151 kB] 121s Get:33 http://ftpmaster.internal/ubuntu resolute/main arm64 libwbclient0 arm64 2:4.23.4+dfsg-1ubuntu1 [85.8 kB] 121s Get:34 http://ftpmaster.internal/ubuntu resolute/main arm64 samba-libs arm64 2:4.23.4+dfsg-1ubuntu1 [6591 kB] 122s Get:35 http://ftpmaster.internal/ubuntu resolute/main arm64 samba-common-bin arm64 2:4.23.4+dfsg-1ubuntu1 [1385 kB] 122s Get:36 http://ftpmaster.internal/ubuntu resolute/main arm64 liburing2 arm64 2.11-1 [25.1 kB] 122s Get:37 http://ftpmaster.internal/ubuntu resolute/main arm64 samba arm64 2:4.23.4+dfsg-1ubuntu1 [1218 kB] 122s Get:38 http://ftpmaster.internal/ubuntu resolute/main arm64 libsmbclient0 arm64 2:4.23.4+dfsg-1ubuntu1 [62.9 kB] 122s Get:39 http://ftpmaster.internal/ubuntu resolute/main arm64 smbclient arm64 2:4.23.4+dfsg-1ubuntu1 [493 kB] 123s Preconfiguring packages ... 123s Fetched 15.5 MB in 2s (7100 kB/s) 123s Selecting previously unselected package libapr1t64:arm64. 123s (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 ... 89359 files and directories currently installed.) 123s Preparing to unpack .../00-libapr1t64_1.7.6-3_arm64.deb ... 123s Unpacking libapr1t64:arm64 (1.7.6-3) ... 123s Selecting previously unselected package libaprutil1t64:arm64. 123s Preparing to unpack .../01-libaprutil1t64_1.6.3-3ubuntu3_arm64.deb ... 123s Unpacking libaprutil1t64:arm64 (1.6.3-3ubuntu3) ... 123s Selecting previously unselected package libaprutil1-dbd-sqlite3:arm64. 123s Preparing to unpack .../02-libaprutil1-dbd-sqlite3_1.6.3-3ubuntu3_arm64.deb ... 123s Unpacking libaprutil1-dbd-sqlite3:arm64 (1.6.3-3ubuntu3) ... 123s Selecting previously unselected package libaprutil1-ldap:arm64. 123s Preparing to unpack .../03-libaprutil1-ldap_1.6.3-3ubuntu3_arm64.deb ... 123s Unpacking libaprutil1-ldap:arm64 (1.6.3-3ubuntu3) ... 123s Selecting previously unselected package liblua5.4-0:arm64. 123s Preparing to unpack .../04-liblua5.4-0_5.4.8-1_arm64.deb ... 123s Unpacking liblua5.4-0:arm64 (5.4.8-1) ... 123s Selecting previously unselected package apache2-bin. 123s Preparing to unpack .../05-apache2-bin_2.4.65-3ubuntu1_arm64.deb ... 123s Unpacking apache2-bin (2.4.65-3ubuntu1) ... 123s Selecting previously unselected package apache2-data. 123s Preparing to unpack .../06-apache2-data_2.4.65-3ubuntu1_all.deb ... 123s Unpacking apache2-data (2.4.65-3ubuntu1) ... 123s Selecting previously unselected package apache2-utils. 123s Preparing to unpack .../07-apache2-utils_2.4.65-3ubuntu1_arm64.deb ... 123s Unpacking apache2-utils (2.4.65-3ubuntu1) ... 123s Selecting previously unselected package apache2. 123s Preparing to unpack .../08-apache2_2.4.65-3ubuntu1_arm64.deb ... 124s Unpacking apache2 (2.4.65-3ubuntu1) ... 124s Selecting previously unselected package backuppc-rsync. 124s Preparing to unpack .../09-backuppc-rsync_3.1.3.0-5_arm64.deb ... 124s Unpacking backuppc-rsync (3.1.3.0-5) ... 124s Selecting previously unselected package libnsl2:arm64. 124s Preparing to unpack .../10-libnsl2_1.3.0-3build4_arm64.deb ... 124s Unpacking libnsl2:arm64 (1.3.0-3build4) ... 124s Selecting previously unselected package postfix. 124s Preparing to unpack .../11-postfix_3.10.6-4ubuntu1_arm64.deb ... 124s Unpacking postfix (3.10.6-4ubuntu1) ... 124s Selecting previously unselected package libarchive-zip-perl. 124s Preparing to unpack .../12-libarchive-zip-perl_1.68-1_all.deb ... 124s Unpacking libarchive-zip-perl (1.68-1) ... 124s Selecting previously unselected package libbackuppc-xs-perl:arm64. 124s Preparing to unpack .../13-libbackuppc-xs-perl_0.62-2build6_arm64.deb ... 124s Unpacking libbackuppc-xs-perl:arm64 (0.62-2build6) ... 124s Selecting previously unselected package libhtml-tagset-perl. 124s Preparing to unpack .../14-libhtml-tagset-perl_3.24-1_all.deb ... 124s Unpacking libhtml-tagset-perl (3.24-1) ... 124s Selecting previously unselected package liburi-perl. 124s Preparing to unpack .../15-liburi-perl_5.34-2build1_all.deb ... 124s Unpacking liburi-perl (5.34-2build1) ... 124s Selecting previously unselected package libhtml-parser-perl:arm64. 124s Preparing to unpack .../16-libhtml-parser-perl_3.83-1build1_arm64.deb ... 124s Unpacking libhtml-parser-perl:arm64 (3.83-1build1) ... 124s Selecting previously unselected package libcgi-pm-perl. 124s Preparing to unpack .../17-libcgi-pm-perl_4.71-1build1_all.deb ... 124s Unpacking libcgi-pm-perl (4.71-1build1) ... 124s Selecting previously unselected package libtimedate-perl. 124s Preparing to unpack .../18-libtimedate-perl_2.3300-2_all.deb ... 124s Unpacking libtimedate-perl (2.3300-2) ... 124s Selecting previously unselected package libhttp-date-perl. 124s Preparing to unpack .../19-libhttp-date-perl_6.06-1_all.deb ... 124s Unpacking libhttp-date-perl (6.06-1) ... 124s Selecting previously unselected package libfile-listing-perl. 124s Preparing to unpack .../20-libfile-listing-perl_6.16-1_all.deb ... 124s Unpacking libfile-listing-perl (6.16-1) ... 124s Selecting previously unselected package libtime-parsedate-perl. 124s Preparing to unpack .../21-libtime-parsedate-perl_2015.103-5_all.deb ... 124s Unpacking libtime-parsedate-perl (2015.103-5) ... 124s Selecting previously unselected package backuppc. 124s Preparing to unpack .../22-backuppc_4.4.0-11_arm64.deb ... 124s Unpacking backuppc (4.4.0-11) ... 124s Selecting previously unselected package samba-common. 124s Preparing to unpack .../23-samba-common_2%3a4.23.4+dfsg-1ubuntu1_all.deb ... 124s Unpacking samba-common (2:4.23.4+dfsg-1ubuntu1) ... 124s Selecting previously unselected package libavahi-common-data:arm64. 124s Preparing to unpack .../24-libavahi-common-data_0.8-17ubuntu2_arm64.deb ... 124s Unpacking libavahi-common-data:arm64 (0.8-17ubuntu2) ... 124s Selecting previously unselected package libavahi-common3:arm64. 124s Preparing to unpack .../25-libavahi-common3_0.8-17ubuntu2_arm64.deb ... 124s Unpacking libavahi-common3:arm64 (0.8-17ubuntu2) ... 124s Selecting previously unselected package libavahi-client3:arm64. 124s Preparing to unpack .../26-libavahi-client3_0.8-17ubuntu2_arm64.deb ... 124s Unpacking libavahi-client3:arm64 (0.8-17ubuntu2) ... 124s Selecting previously unselected package libcups2t64:arm64. 124s Preparing to unpack .../27-libcups2t64_2.4.16-1ubuntu1_arm64.deb ... 124s Unpacking libcups2t64:arm64 (2.4.16-1ubuntu1) ... 124s Selecting previously unselected package libtalloc2:arm64. 124s Preparing to unpack .../28-libtalloc2_2%3a2.4.3+samba4.23.4+dfsg-1ubuntu1_arm64.deb ... 124s Unpacking libtalloc2:arm64 (2:2.4.3+samba4.23.4+dfsg-1ubuntu1) ... 124s Selecting previously unselected package libtdb1:arm64. 124s Preparing to unpack .../29-libtdb1_2%3a1.4.14+samba4.23.4+dfsg-1ubuntu1_arm64.deb ... 124s Unpacking libtdb1:arm64 (2:1.4.14+samba4.23.4+dfsg-1ubuntu1) ... 124s Selecting previously unselected package libtevent0t64:arm64. 124s Preparing to unpack .../30-libtevent0t64_2%3a0.17.1+samba4.23.4+dfsg-1ubuntu1_arm64.deb ... 124s Unpacking libtevent0t64:arm64 (2:0.17.1+samba4.23.4+dfsg-1ubuntu1) ... 124s Selecting previously unselected package libldb2:arm64. 124s Preparing to unpack .../31-libldb2_2%3a2.11.0+samba4.23.4+dfsg-1ubuntu1_arm64.deb ... 124s Unpacking libldb2:arm64 (2:2.11.0+samba4.23.4+dfsg-1ubuntu1) ... 124s Selecting previously unselected package libwbclient0:arm64. 124s Preparing to unpack .../32-libwbclient0_2%3a4.23.4+dfsg-1ubuntu1_arm64.deb ... 124s Unpacking libwbclient0:arm64 (2:4.23.4+dfsg-1ubuntu1) ... 124s Selecting previously unselected package samba-libs:arm64. 124s Preparing to unpack .../33-samba-libs_2%3a4.23.4+dfsg-1ubuntu1_arm64.deb ... 124s Unpacking samba-libs:arm64 (2:4.23.4+dfsg-1ubuntu1) ... 125s Selecting previously unselected package samba-common-bin. 125s Preparing to unpack .../34-samba-common-bin_2%3a4.23.4+dfsg-1ubuntu1_arm64.deb ... 125s Unpacking samba-common-bin (2:4.23.4+dfsg-1ubuntu1) ... 125s Selecting previously unselected package liburing2:arm64. 125s Preparing to unpack .../35-liburing2_2.11-1_arm64.deb ... 125s Unpacking liburing2:arm64 (2.11-1) ... 125s Selecting previously unselected package samba. 125s Preparing to unpack .../36-samba_2%3a4.23.4+dfsg-1ubuntu1_arm64.deb ... 125s Unpacking samba (2:4.23.4+dfsg-1ubuntu1) ... 125s Selecting previously unselected package libsmbclient0:arm64. 125s Preparing to unpack .../37-libsmbclient0_2%3a4.23.4+dfsg-1ubuntu1_arm64.deb ... 125s Unpacking libsmbclient0:arm64 (2:4.23.4+dfsg-1ubuntu1) ... 125s Selecting previously unselected package smbclient. 125s Preparing to unpack .../38-smbclient_2%3a4.23.4+dfsg-1ubuntu1_arm64.deb ... 125s Unpacking smbclient (2:4.23.4+dfsg-1ubuntu1) ... 125s Setting up libarchive-zip-perl (1.68-1) ... 125s Setting up libtdb1:arm64 (2:1.4.14+samba4.23.4+dfsg-1ubuntu1) ... 125s Setting up libhtml-tagset-perl (3.24-1) ... 125s Setting up samba-common (2:4.23.4+dfsg-1ubuntu1) ... 125s Creating config file /etc/samba/smb.conf with new version 125s Setting up libwbclient0:arm64 (2:4.23.4+dfsg-1ubuntu1) ... 125s Setting up libtalloc2:arm64 (2:2.4.3+samba4.23.4+dfsg-1ubuntu1) ... 125s Setting up backuppc-rsync (3.1.3.0-5) ... 125s Setting up libavahi-common-data:arm64 (0.8-17ubuntu2) ... 125s Setting up libtimedate-perl (2.3300-2) ... 125s Setting up libtime-parsedate-perl (2015.103-5) ... 125s Setting up libapr1t64:arm64 (1.7.6-3) ... 125s Setting up libnsl2:arm64 (1.3.0-3build4) ... 125s Setting up liblua5.4-0:arm64 (5.4.8-1) ... 125s Setting up apache2-data (2.4.65-3ubuntu1) ... 125s Setting up liburing2:arm64 (2.11-1) ... 125s Setting up libbackuppc-xs-perl:arm64 (0.62-2build6) ... 125s Setting up liburi-perl (5.34-2build1) ... 125s Setting up libtevent0t64:arm64 (2:0.17.1+samba4.23.4+dfsg-1ubuntu1) ... 125s Setting up libaprutil1t64:arm64 (1.6.3-3ubuntu3) ... 125s Setting up libhttp-date-perl (6.06-1) ... 125s Setting up libfile-listing-perl (6.16-1) ... 125s Setting up libavahi-common3:arm64 (0.8-17ubuntu2) ... 125s Setting up libaprutil1-ldap:arm64 (1.6.3-3ubuntu3) ... 125s Setting up libaprutil1-dbd-sqlite3:arm64 (1.6.3-3ubuntu3) ... 125s Setting up postfix (3.10.6-4ubuntu1) ... 126s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 126s Creating /etc/postfix/dynamicmaps.cf 126s setting myhostname: autopkgtest.local 126s setting alias maps 126s setting alias database 126s changing /etc/mailname to autopkgtest.local 126s setting myorigin 126s setting destinations: $myhostname, autopkgtest.local, localhost.local, , localhost 126s setting relayhost: 126s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 126s clearing mailbox_command 126s setting mailbox_size_limit: 0 126s setting recipient_delimiter: + 128s setting inet_interfaces: all 131s setting inet_protocols: all 131s /etc/aliases does not exist, creating it. 131s WARNING: /etc/aliases exists, but does not have a root alias. 131s 131s Postfix (main.cf) is now set up with a default configuration. If you need to 131s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 131s Postfix configuration values, see postconf(1). 131s 131s After modifying main.cf, be sure to run 'systemctl reload postfix'. 131s 132s Setting up libhtml-parser-perl:arm64 (3.83-1build1) ... 132s Setting up libldb2:arm64 (2:2.11.0+samba4.23.4+dfsg-1ubuntu1) ... 132s Setting up libavahi-client3:arm64 (0.8-17ubuntu2) ... 132s Setting up apache2-utils (2.4.65-3ubuntu1) ... 132s Setting up libcgi-pm-perl (4.71-1build1) ... 132s Setting up samba-libs:arm64 (2:4.23.4+dfsg-1ubuntu1) ... 132s Setting up apache2-bin (2.4.65-3ubuntu1) ... 132s Setting up libsmbclient0:arm64 (2:4.23.4+dfsg-1ubuntu1) ... 132s Setting up libcups2t64:arm64 (2.4.16-1ubuntu1) ... 132s Setting up apache2 (2.4.65-3ubuntu1) ... 132s Enabling module mpm_event. 132s Enabling module authz_core. 132s Enabling module authz_host. 133s Enabling module authn_core. 133s Enabling module auth_basic. 133s Enabling module access_compat. 133s Enabling module authn_file. 133s Enabling module authz_user. 133s Enabling module alias. 133s Enabling module dir. 133s Enabling module autoindex. 133s Enabling module env. 133s Enabling module mime. 133s Enabling module negotiation. 133s Enabling module setenvif. 133s Enabling module filter. 133s Enabling module deflate. 133s Enabling module status. 133s Enabling module reqtimeout. 133s Enabling conf charset. 133s Enabling conf localized-error-pages. 133s Enabling conf other-vhosts-access-log. 133s Enabling conf security. 133s Enabling conf serve-cgi-bin. 133s Enabling site 000-default. 134s Created symlink '/etc/systemd/system/multi-user.target.wants/apache2.service' → '/usr/lib/systemd/system/apache2.service'. 134s Created symlink '/etc/systemd/system/multi-user.target.wants/apache-htcacheclean.service' → '/usr/lib/systemd/system/apache-htcacheclean.service'. 135s Setting up backuppc (4.4.0-11) ... 135s 136s apache2_invoke: Enable configuration backuppc.conf 136s Adding password for user backuppc 136s Considering dependency authn_core for auth_basic: 136s Module authn_core already enabled 136s Module auth_basic already enabled 136s Considering dependency authz_core for authz_groupfile: 136s Module authz_core already enabled 136s Enabling module authz_groupfile. 136s To activate the new configuration, you need to run: 136s systemctl restart apache2 136s Module authn_file already enabled 136s Considering dependency authz_core for authz_user: 136s Module authz_core already enabled 136s Module authz_user already enabled 136s Your MPM seems to be threaded. Selecting cgid instead of cgi. 136s Enabling module cgid. 136s To activate the new configuration, you need to run: 136s systemctl restart apache2 136s Creating config file /etc/backuppc/config.pl with new version 137s Created symlink '/etc/systemd/system/multi-user.target.wants/backuppc.service' → '/usr/lib/systemd/system/backuppc.service'. 137s Setting up smbclient (2:4.23.4+dfsg-1ubuntu1) ... 137s Setting up samba-common-bin (2:4.23.4+dfsg-1ubuntu1) ... 137s Setting up samba (2:4.23.4+dfsg-1ubuntu1) ... 138s Created symlink '/etc/systemd/system/smb.service' → '/usr/lib/systemd/system/smbd.service'. 138s Created symlink '/etc/systemd/system/multi-user.target.wants/smbd.service' → '/usr/lib/systemd/system/smbd.service'. 138s Created symlink '/etc/systemd/system/nmb.service' → '/usr/lib/systemd/system/nmbd.service'. 138s Created symlink '/etc/systemd/system/multi-user.target.wants/nmbd.service' → '/usr/lib/systemd/system/nmbd.service'. 139s Processing triggers for rsyslog (8.2512.0-1ubuntu2) ... 139s Processing triggers for ufw (0.36.2-9build1) ... 139s Processing triggers for man-db (2.13.1-1) ... 141s Processing triggers for libc-bin (2.42-2ubuntu4) ... 141s Processing triggers for postfix (3.10.6-4ubuntu1) ... 141s Restarting postfix 142s autopkgtest [04:00:39]: test smb-backup: [----------------------- 142s net.ipv4.ping_group_range = 0 1000 142s Populating share path 142s Adding samba share 142s Adding [public] share 142s Configuring backuppc 142s Performing a full backup 143s Backup type: type = full, needs_full = 1, needs_incr = 1, lastFullTime = 0, opts{f} = 1, opts{i} = , opts{F} = 143s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 143s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 143s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.018 143s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.0.z created 2026-02-03 04:00:39 143s Backup prep: type = full, case = 1, inPlace = 1, doDuplicate = 0, newBkupNum = 0, newBkupIdx = 0, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 143s __bpc_progress_state__ backup share "public" 143s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 143s full backup started for share public 143s started full dump, share=public 143s Xfer PIDs are now 3603,3602 143s xferPids 3603,3602 143s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 143s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 143s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 143s tarmode is now full, system, hidden, noreset, noverbose 143s __bpc_progress_fileCnt__ 0 143s new 644 0/0 480434 tmp.ud8cfo 143s new 644 0/0 480434 tmp.ZLaKDd 143s new 644 0/0 480434 tmp.rakl5R 143s new 644 0/0 480434 tmp.zqezVj 143s new 644 0/0 480434 tmp.jRMHNG 143s new 644 0/0 480434 tmp.6z1jza 143s new 644 0/0 480434 tmp.N9WPlB 143s new 644 0/0 480434 tmp.qzmHB4 143s tar: dumped 10 files and 0 directories 143s Total bytes written: 4804340 (38.9 MiB/s) 143s new 644 0/0 480434 tmp.zXmV7n 143s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 143s new 644 0/0 480434 tmp.C57YeY 143s __bpc_progress_fileCnt__ 10 143s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377773 sizeNewComp, 13 inodeLast 143s Xfer PIDs are now 143s xferPids 143s full backup 0 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 143s BackupExpire: cntFull = 1, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 0 143s __bpc_progress_state__ fsck 143s Running BackupPC_refCountUpdate -h localhost on localhost 143s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 143s Xfer PIDs are now 3609 143s xferPids 3609 143s BackupPC_refCountUpdate: doing fsck on localhost #0 (full) since $ConfRefCntFsck == 1 143s __bpc_progress_state__ refCnt #0 143s __bpc_progress_state__ cntUpdate #0 143s __bpc_progress_state__ rename #0 143s __bpc_progress_state__ sumUpdate 143s __bpc_progress_fileCnt__ 0/128 143s __bpc_progress_fileCnt__ 8/128 143s __bpc_progress_fileCnt__ 16/128 143s __bpc_progress_fileCnt__ 24/128 143s __bpc_progress_fileCnt__ 32/128 143s __bpc_progress_fileCnt__ 40/128 143s __bpc_progress_fileCnt__ 48/128 143s __bpc_progress_fileCnt__ 56/128 143s __bpc_progress_fileCnt__ 64/128 143s __bpc_progress_fileCnt__ 72/128 143s __bpc_progress_fileCnt__ 80/128 143s __bpc_progress_fileCnt__ 88/128 143s __bpc_progress_fileCnt__ 96/128 143s __bpc_progress_fileCnt__ 104/128 143s __bpc_progress_fileCnt__ 112/128 143s __bpc_progress_fileCnt__ 120/128 143s __bpc_progress_state__ rename total 143s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 143s Xfer PIDs are now 143s xferPids 143s Finished BackupPC_refCountUpdate (running time: 1 sec) 143s Xfer PIDs are now 143s xferPids 143s full backup complete 143s OK: Full backup succeeded 143s Changing share content and performing an incremental backup 143s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1770091239, opts{f} = , opts{i} = 1, opts{F} = 143s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 143s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 143s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.021 143s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.1.z created 2026-02-03 04:00:40 143s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 1, newBkupIdx = 1, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 143s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 143s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 143s Xfer PIDs are now 3680 143s xferPids 3680 143s __bpc_progress_state__ copy #0 -> #1 143s Copying backup #0 to #1 143s __bpc_progress_fileCnt__ 3 dirs 143s Xfer PIDs are now 3683,3680 143s xferPids 3683,3680 143s __bpc_progress_state__ sumUpdate 143s __bpc_progress_fileCnt__ 0/128 143s __bpc_progress_fileCnt__ 8/128 143s __bpc_progress_fileCnt__ 16/128 143s __bpc_progress_fileCnt__ 24/128 143s __bpc_progress_fileCnt__ 32/128 143s __bpc_progress_fileCnt__ 40/128 143s __bpc_progress_fileCnt__ 48/128 143s __bpc_progress_fileCnt__ 56/128 143s __bpc_progress_fileCnt__ 64/128 143s __bpc_progress_fileCnt__ 72/128 143s __bpc_progress_fileCnt__ 80/128 143s __bpc_progress_fileCnt__ 88/128 143s __bpc_progress_fileCnt__ 96/128 143s __bpc_progress_fileCnt__ 104/128 143s __bpc_progress_fileCnt__ 112/128 143s __bpc_progress_fileCnt__ 120/128 143s __bpc_progress_state__ rename total 143s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 143s Xfer PIDs are now 3680 143s xferPids 3680 143s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 143s Finished BackupPC_backupDuplicate (running time: 0 sec) 143s __bpc_progress_state__ backup share "public" 143s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 143s incr backup started back to 2026-02-03 03:00:39 (backup #0) for share public 143s started incr dump, share=public 143s Xfer PIDs are now 3687,3686 143s xferPids 3687,3686 143s 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 - 143s tar:1306 Getting files newer than Tue Feb 3 03:00:39 2026 143s tarmode is now full, system, hidden, noreset, noverbose 143s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 143s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 143s __bpc_progress_fileCnt__ 0 143s same 644 0/0 480434 tmp.ud8cfo 143s new 644 0/0 480434 tmp.G6KmPX 143s new 644 0/0 480434 tmp.QM9Vhb 144s same 644 0/0 480434 tmp.ZLaKDd 144s same 644 0/0 480434 tmp.rakl5R 144s same 644 0/0 480434 tmp.zqezVj 144s same 644 0/0 480434 tmp.jRMHNG 144s new 644 0/0 480434 tmp.Zfnaqo 144s new 644 0/0 480434 tmp.7CqP2w 144s new 644 0/0 480434 tmp.gWijhF 144s same 644 0/0 480434 tmp.6z1jza 144s new 644 0/0 480434 tmp.eFUrpc 144s same 644 0/0 480434 tmp.N9WPlB 144s same 644 0/0 480434 tmp.qzmHB4 144s same 644 0/0 480434 tmp.zXmV7n 144s new 644 0/0 480434 tmp.QGjxSi 144s new 644 0/0 480434 tmp.YfiNxA 144s same 644 0/0 480434 tmp.C57YeY 144s tar: dumped 20 files and 0 directories 144s Total bytes written: 9608680 (54.5 MiB/s) 144s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 144s new 644 0/0 480434 tmp.4ww6cV 144s new 644 0/0 480434 tmp.Bw21pK 144s __bpc_progress_fileCnt__ 20 144s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377773 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377744 sizeNewComp, 24 inodeLast 144s Xfer PIDs are now 144s xferPids 144s incr backup 1 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 144s BackupExpire: cntFull = 2, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 1.15740740740741e-05 144s __bpc_progress_state__ fsck 144s Running BackupPC_refCountUpdate -h localhost on localhost 144s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 144s Xfer PIDs are now 3693 144s xferPids 3693 144s __bpc_progress_state__ cntUpdate #1 144s __bpc_progress_state__ rename #1 144s __bpc_progress_state__ sumUpdate 144s __bpc_progress_fileCnt__ 0/128 144s __bpc_progress_fileCnt__ 8/128 144s __bpc_progress_fileCnt__ 16/128 144s __bpc_progress_fileCnt__ 24/128 144s __bpc_progress_fileCnt__ 32/128 144s __bpc_progress_fileCnt__ 40/128 144s __bpc_progress_fileCnt__ 48/128 144s __bpc_progress_fileCnt__ 56/128 144s __bpc_progress_fileCnt__ 64/128 144s __bpc_progress_fileCnt__ 72/128 144s __bpc_progress_fileCnt__ 80/128 144s __bpc_progress_fileCnt__ 88/128 144s __bpc_progress_fileCnt__ 96/128 144s __bpc_progress_fileCnt__ 104/128 144s __bpc_progress_fileCnt__ 112/128 144s __bpc_progress_fileCnt__ 120/128 144s __bpc_progress_state__ rename total 144s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 144s Xfer PIDs are now 144s xferPids 144s Finished BackupPC_refCountUpdate (running time: 0 sec) 144s Xfer PIDs are now 144s xferPids 144s incr backup complete 144s OK: Incremental backup succeeded 144s Done. 144s autopkgtest [04:00:41]: test smb-backup: -----------------------] 145s smb-backup PASS 145s autopkgtest [04:00:42]: test smb-backup: - - - - - - - - - - results - - - - - - - - - - 145s autopkgtest [04:00:42]: test authenticated-smb-backup: preparing testbed 145s Reading package lists... 145s Building dependency tree... 145s Reading state information... 145s Solving dependencies... 146s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 147s autopkgtest [04:00:44]: test authenticated-smb-backup: [----------------------- 147s Populating share path 147s Adding samba share 147s Adding [private] share 147s Creating user 147s Creating a local and samba user called smbtest3874 147s Setting samba password for the smbtest3874 user 147s Added user smbtest3874. 147s Configuring backuppc 147s Performing a full backup 147s Backup type: type = full, needs_full = , needs_incr = , lastFullTime = 1770091239, opts{f} = 1, opts{i} = , opts{F} = 147s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 147s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 147s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.020 147s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.2.z created 2026-02-03 04:00:44 147s Backup prep: type = full, case = 4, inPlace = 0, doDuplicate = 0, newBkupNum = 2, newBkupIdx = 2, lastBkupNum = 1, lastBkupIdx = 1 (FillCycle = 0, noFillCnt = 0) 147s __bpc_progress_state__ backup share "private" 147s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest3874 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 147s full backup started for share private 147s started full dump, share=private 147s Xfer PIDs are now 3966,3965 147s xferPids 3966,3965 147s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest3874 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 147s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 147s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 147s tarmode is now full, system, hidden, noreset, noverbose 147s __bpc_progress_fileCnt__ 0 147s new 644 0/0 480434 tmp.kYuFdt 147s new 644 0/0 480434 tmp.080LCA 147s new 644 0/0 480434 tmp.j0HImA 147s new 644 0/0 480434 tmp.IiOSMi 147s new 644 0/0 480434 tmp.yuhSSr 147s new 644 0/0 480434 tmp.5S1WjB 147s new 644 0/0 480434 tmp.GYe5MK 147s new 644 0/0 480434 tmp.zVAU5L 147s tar: dumped 10 files and 0 directories 147s Total bytes written: 4804340 (39.0 MiB/s) 147s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 147s new 644 0/0 480434 tmp.q7Gril 148s new 644 0/0 480434 tmp.6bmQMm 148s __bpc_progress_fileCnt__ 10 148s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377755 sizeNewComp, 36 inodeLast 148s Xfer PIDs are now 148s xferPids 148s OrphanShareNameClean: removing orphan share public from /var/lib/backuppc/pc/localhost/2 148s __bpc_progress_state__ delete share #2/public 148s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDelete -h localhost -n 2 -l -m -s public / 148s Xfer PIDs are now 3973 148s xferPids 3973 148s BackupPC_backupDelete: removing #2/public/ 148s __bpc_progress_state__ merge #2/public/ -> #1/public/ 148s BackupPC_backupDelete: Merge into backup 1/public/ 148s mergeDir: attr write to /var/lib/backuppc/pc/localhost/1/fpublic//attrib failed (digest was ) 148s bpc_attrib_dirWrite: can't open/create raw /var/lib/backuppc/pc/localhost/1/fpublic for writing 148s Xfer PIDs are now 3973,3976 148s xferPids 3973,3976 148s __bpc_progress_state__ refCnt #1 148s __bpc_progress_state__ cntUpdate #1 148s __bpc_progress_state__ rename #1 148s __bpc_progress_state__ refCnt #2 148s BackupPC_refCountUpdate: localhost #2 inodeLast set to 37 (was 24) 148s __bpc_progress_state__ cntUpdate #2 148s __bpc_progress_state__ rename #2 148s __bpc_progress_state__ sumUpdate 148s __bpc_progress_fileCnt__ 0/128 148s __bpc_progress_fileCnt__ 8/128 148s __bpc_progress_fileCnt__ 16/128 148s __bpc_progress_fileCnt__ 24/128 148s __bpc_progress_fileCnt__ 32/128 148s __bpc_progress_fileCnt__ 40/128 148s __bpc_progress_fileCnt__ 48/128 148s __bpc_progress_fileCnt__ 56/128 148s __bpc_progress_fileCnt__ 64/128 148s __bpc_progress_fileCnt__ 72/128 148s __bpc_progress_fileCnt__ 80/128 148s __bpc_progress_fileCnt__ 88/128 148s __bpc_progress_fileCnt__ 96/128 148s __bpc_progress_fileCnt__ 104/128 148s __bpc_progress_fileCnt__ 112/128 148s __bpc_progress_fileCnt__ 120/128 148s __bpc_progress_state__ rename total 148s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 148s Xfer PIDs are now 3973 148s xferPids 3973 148s Xfer PIDs are now 148s xferPids 148s Finished BackupPC_backupDelete, status = 0 (running time: 0 sec) 148s Xfer PIDs are now 148s xferPids 148s full backup 2 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 148s BackupExpire: cntFull = 2, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 4.62962962962963e-05, oldestFull = 5.78703703703704e-05 148s __bpc_progress_state__ fsck 148s Running BackupPC_refCountUpdate -h localhost on localhost 148s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 148s Xfer PIDs are now 3979 148s xferPids 3979 148s BackupPC_refCountUpdate: doing fsck on localhost #2 (full) since $ConfRefCntFsck == 1 148s __bpc_progress_state__ refCnt #2 148s __bpc_progress_state__ cntUpdate #2 148s __bpc_progress_state__ rename #2 148s __bpc_progress_state__ sumUpdate 148s __bpc_progress_fileCnt__ 0/128 148s __bpc_progress_fileCnt__ 8/128 148s __bpc_progress_fileCnt__ 16/128 148s __bpc_progress_fileCnt__ 24/128 148s __bpc_progress_fileCnt__ 32/128 148s __bpc_progress_fileCnt__ 40/128 148s __bpc_progress_fileCnt__ 48/128 148s __bpc_progress_fileCnt__ 56/128 148s __bpc_progress_fileCnt__ 64/128 148s __bpc_progress_fileCnt__ 72/128 148s __bpc_progress_fileCnt__ 80/128 148s __bpc_progress_fileCnt__ 88/128 148s __bpc_progress_fileCnt__ 96/128 148s __bpc_progress_fileCnt__ 104/128 148s __bpc_progress_fileCnt__ 112/128 148s __bpc_progress_fileCnt__ 120/128 148s __bpc_progress_state__ rename total 148s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 148s Xfer PIDs are now 148s xferPids 148s Finished BackupPC_refCountUpdate (running time: 0 sec) 148s Xfer PIDs are now 148s xferPids 148s full backup complete 148s OK: Full backup succeeded 148s Changing share content and performing an incremental backup 148s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1770091244, opts{f} = , opts{i} = 1, opts{F} = 148s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 148s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 148s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.019 148s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.3.z created 2026-02-03 04:00:45 148s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 3, newBkupIdx = 3, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = 1) 148s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 148s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 148s Xfer PIDs are now 4050 148s xferPids 4050 148s __bpc_progress_state__ copy #2 -> #3 148s Copying backup #2 to #3 148s __bpc_progress_fileCnt__ 3 dirs 148s Xfer PIDs are now 4053,4050 148s xferPids 4053,4050 148s __bpc_progress_state__ sumUpdate 148s __bpc_progress_fileCnt__ 0/128 148s __bpc_progress_fileCnt__ 8/128 148s __bpc_progress_fileCnt__ 16/128 148s __bpc_progress_fileCnt__ 24/128 148s __bpc_progress_fileCnt__ 32/128 148s __bpc_progress_fileCnt__ 40/128 148s __bpc_progress_fileCnt__ 48/128 148s __bpc_progress_fileCnt__ 56/128 148s __bpc_progress_fileCnt__ 64/128 148s __bpc_progress_fileCnt__ 72/128 148s __bpc_progress_fileCnt__ 80/128 148s __bpc_progress_fileCnt__ 88/128 148s __bpc_progress_fileCnt__ 96/128 148s __bpc_progress_fileCnt__ 104/128 148s __bpc_progress_fileCnt__ 112/128 148s __bpc_progress_fileCnt__ 120/128 148s __bpc_progress_state__ rename total 148s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 148s Xfer PIDs are now 4050 148s xferPids 4050 148s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 148s Finished BackupPC_backupDuplicate (running time: 0 sec) 148s __bpc_progress_state__ backup share "private" 148s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest3874 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 148s incr backup started back to 2026-02-03 03:00:44 (backup #2) for share private 148s started incr dump, share=private 148s Xfer PIDs are now 4057,4056 148s xferPids 4057,4056 148s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest3874 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 148s tar:1306 Getting files newer than Tue Feb 3 03:00:44 2026 148s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 148s tarmode is now full, system, hidden, noreset, noverbose 148s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 148s __bpc_progress_fileCnt__ 0 148s new 644 0/0 480434 tmp.ESvxyy 148s same 644 0/0 480434 tmp.kYuFdt 148s new 644 0/0 480434 tmp.izNGEI 148s same 644 0/0 480434 tmp.080LCA 148s same 644 0/0 480434 tmp.j0HImA 148s new 644 0/0 480434 tmp.Qobuis 148s same 644 0/0 480434 tmp.IiOSMi 148s new 644 0/0 480434 tmp.YSseYN 148s same 644 0/0 480434 tmp.yuhSSr 148s same 644 0/0 480434 tmp.5S1WjB 148s same 644 0/0 480434 tmp.GYe5MK 148s new 644 0/0 480434 tmp.6hDgz3 149s same 644 0/0 480434 tmp.zVAU5L 149s new 644 0/0 480434 tmp.8OsiNR 149s same 644 0/0 480434 tmp.q7Gril 149s new 644 0/0 480434 tmp.be47NS 149s new 644 0/0 480434 tmp.vflhoC 149s same 644 0/0 480434 tmp.6bmQMm 149s new 644 0/0 480434 tmp.2SP5TT 149s tar: dumped 20 files and 0 directories 149s Total bytes written: 9608680 (57.1 MiB/s) 149s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 149s new 644 0/0 480434 tmp.xbK2Fp 149s __bpc_progress_fileCnt__ 20 149s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377755 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377744 sizeNewComp, 47 inodeLast 149s Xfer PIDs are now 149s xferPids 149s incr backup 3 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 149s BackupExpire: cntFull = 3, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 5.78703703703704e-05, oldestFull = 6.94444444444444e-05 149s __bpc_progress_state__ fsck 149s Running BackupPC_refCountUpdate -h localhost on localhost 149s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 149s Xfer PIDs are now 4064 149s xferPids 4064 149s __bpc_progress_state__ cntUpdate #3 149s __bpc_progress_state__ rename #3 149s __bpc_progress_state__ sumUpdate 149s __bpc_progress_fileCnt__ 0/128 149s __bpc_progress_fileCnt__ 8/128 149s __bpc_progress_fileCnt__ 16/128 149s __bpc_progress_fileCnt__ 24/128 149s __bpc_progress_fileCnt__ 32/128 149s __bpc_progress_fileCnt__ 40/128 149s __bpc_progress_fileCnt__ 48/128 149s __bpc_progress_fileCnt__ 56/128 149s __bpc_progress_fileCnt__ 64/128 149s __bpc_progress_fileCnt__ 72/128 149s __bpc_progress_fileCnt__ 80/128 149s __bpc_progress_fileCnt__ 88/128 149s __bpc_progress_fileCnt__ 96/128 149s __bpc_progress_fileCnt__ 104/128 149s __bpc_progress_fileCnt__ 112/128 149s __bpc_progress_fileCnt__ 120/128 149s __bpc_progress_state__ rename total 149s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 149s Xfer PIDs are now 149s xferPids 149s Finished BackupPC_refCountUpdate (running time: 0 sec) 149s Xfer PIDs are now 149s xferPids 149s incr backup complete 149s OK: Incremental backup succeeded 149s Done. 149s Deleted user smbtest3874. 149s userdel: smbtest3874 mail spool (/var/mail/smbtest3874) not found 149s autopkgtest [04:00:46]: test authenticated-smb-backup: -----------------------] 150s authenticated-smb-backup PASS 150s autopkgtest [04:00:47]: test authenticated-smb-backup: - - - - - - - - - - results - - - - - - - - - - 150s autopkgtest [04:00:47]: @@@@@@@@@@@@@@@@@@@@ summary 150s smb-backup PASS 150s authenticated-smb-backup PASS