0s autopkgtest [05:46:59]: starting date and time: 2025-01-17 05:46:59+0000 0s autopkgtest [05:46:59]: git checkout: 325255d2 Merge branch 'pin-any-arch' into 'ubuntu/production' 0s autopkgtest [05:46:59]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.zl53vj8v/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-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-ppc64el-22.secgroup --name adt-plucky-ppc64el-backuppc-20250117-054658-juju-7f2275-prod-proposed-migration-environment-20-1de1f0a7-d4dc-4b92-9212-e8c4c1adf855 --image adt/ubuntu-plucky-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration-ppc64el -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/ 85s autopkgtest [05:48:24]: testbed dpkg architecture: ppc64el 85s autopkgtest [05:48:24]: testbed apt version: 2.9.18 86s autopkgtest [05:48:25]: @@@@@@@@@@@@@@@@@@@@ test bed setup 86s autopkgtest [05:48:25]: testbed release detected to be: None 87s autopkgtest [05:48:26]: updating testbed package index (apt update) 87s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed InRelease [73.9 kB] 87s Hit:2 http://ftpmaster.internal/ubuntu plucky InRelease 87s Hit:3 http://ftpmaster.internal/ubuntu plucky-updates InRelease 87s Hit:4 http://ftpmaster.internal/ubuntu plucky-security InRelease 87s Get:5 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse Sources [15.4 kB] 88s Get:6 http://ftpmaster.internal/ubuntu plucky-proposed/restricted Sources [9708 B] 88s Get:7 http://ftpmaster.internal/ubuntu plucky-proposed/main Sources [168 kB] 88s Get:8 http://ftpmaster.internal/ubuntu plucky-proposed/universe Sources [908 kB] 88s Get:9 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el Packages [293 kB] 88s Get:10 http://ftpmaster.internal/ubuntu plucky-proposed/restricted ppc64el Packages [756 B] 88s Get:11 http://ftpmaster.internal/ubuntu plucky-proposed/universe ppc64el Packages [1045 kB] 88s Get:12 http://ftpmaster.internal/ubuntu plucky-proposed/multiverse ppc64el Packages [16.7 kB] 88s Fetched 2530 kB in 1s (2066 kB/s) 89s Reading package lists... 90s Reading package lists... 90s Building dependency tree... 90s Reading state information... 91s Calculating upgrade... 91s The following packages will be upgraded: 91s libgudev-1.0-0 91s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 91s Need to get 15.7 kB of archives. 91s After this operation, 1024 B of additional disk space will be used. 91s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el libgudev-1.0-0 ppc64el 1:238-6 [15.7 kB] 92s Fetched 15.7 kB in 0s (73.1 kB/s) 92s (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 ... 74045 files and directories currently installed.) 92s Preparing to unpack .../libgudev-1.0-0_1%3a238-6_ppc64el.deb ... 92s Unpacking libgudev-1.0-0:ppc64el (1:238-6) over (1:238-5ubuntu1) ... 93s Setting up libgudev-1.0-0:ppc64el (1:238-6) ... 93s Processing triggers for libc-bin (2.40-4ubuntu1) ... 93s Reading package lists... 93s Building dependency tree... 93s Reading state information... 94s 0 upgraded, 0 newly installed, 0 to remove and 1 not upgraded. 94s autopkgtest [05:48:33]: upgrading testbed (apt dist-upgrade and autopurge) 94s Reading package lists... 95s Building dependency tree... 95s Reading state information... 95s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 95s Starting 2 pkgProblemResolver with broken count: 0 95s Done 95s Entering ResolveByKeep 96s 96s The following packages will be upgraded: 96s ucf 96s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 96s Need to get 42.8 kB of archives. 96s After this operation, 0 B of additional disk space will be used. 96s Get:1 http://ftpmaster.internal/ubuntu plucky-proposed/main ppc64el ucf all 3.0048 [42.8 kB] 97s Preconfiguring packages ... 97s Fetched 42.8 kB in 0s (147 kB/s) 97s (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 ... 74045 files and directories currently installed.) 97s Preparing to unpack .../archives/ucf_3.0048_all.deb ... 97s Unpacking ucf (3.0048) over (3.0046) ... 97s Setting up ucf (3.0048) ... 97s Processing triggers for man-db (2.13.0-1) ... 99s Reading package lists... 99s Building dependency tree... 99s Reading state information... 99s Starting pkgProblemResolver with broken count: 0 99s Starting 2 pkgProblemResolver with broken count: 0 99s Done 100s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 102s autopkgtest [05:48:41]: testbed running kernel: Linux 6.11.0-8-generic #8-Ubuntu SMP Mon Sep 16 13:49:23 UTC 2024 102s autopkgtest [05:48:41]: @@@@@@@@@@@@@@@@@@@@ apt-source backuppc 104s Get:1 http://ftpmaster.internal/ubuntu plucky/main backuppc 4.4.0-10ubuntu1 (dsc) [2268 B] 104s Get:2 http://ftpmaster.internal/ubuntu plucky/main backuppc 4.4.0-10ubuntu1 (tar) [657 kB] 104s Get:3 http://ftpmaster.internal/ubuntu plucky/main backuppc 4.4.0-10ubuntu1 (diff) [37.7 kB] 104s gpgv: Signature made Tue Dec 10 12:24:29 2024 UTC 104s gpgv: using RSA key 8AFD08D3D1B817B2DF8982F501AC4B4083590A98 104s gpgv: Can't check signature: No public key 104s dpkg-source: warning: cannot verify inline signature for ./backuppc_4.4.0-10ubuntu1.dsc: no acceptable signature found 105s autopkgtest [05:48:44]: testing package backuppc version 4.4.0-10ubuntu1 105s autopkgtest [05:48:44]: build not needed 106s autopkgtest [05:48:45]: test smb-backup: preparing testbed 106s Reading package lists... 106s Building dependency tree... 106s Reading state information... 107s Starting pkgProblemResolver with broken count: 0 107s Starting 2 pkgProblemResolver with broken count: 0 107s Done 107s The following NEW packages will be installed: 107s apache2 apache2-bin apache2-data apache2-utils backuppc backuppc-rsync 107s libapr1t64 libaprutil1-dbd-sqlite3 libaprutil1-ldap libaprutil1t64 107s libarchive-zip-perl libavahi-client3 libavahi-common-data libavahi-common3 107s libbackuppc-xs-perl libcgi-pm-perl libcups2t64 libfile-listing-perl 107s libhtml-parser-perl libhtml-tagset-perl libhttp-date-perl libldb2 107s liblua5.4-0 libsmbclient0 libtalloc2 libtdb1 libtevent0t64 107s libtime-parsedate-perl libtimedate-perl liburi-perl liburing2 libwbclient0 107s postfix samba samba-common samba-common-bin samba-libs smbclient 107s 0 upgraded, 38 newly installed, 0 to remove and 0 not upgraded. 107s Need to get 16.7 MB of archives. 107s After this operation, 86.4 MB of additional disk space will be used. 107s Get:1 http://ftpmaster.internal/ubuntu plucky/main ppc64el libapr1t64 ppc64el 1.7.5-1 [137 kB] 107s Get:2 http://ftpmaster.internal/ubuntu plucky/main ppc64el libaprutil1t64 ppc64el 1.6.3-3ubuntu1 [112 kB] 107s Get:3 http://ftpmaster.internal/ubuntu plucky/main ppc64el libaprutil1-dbd-sqlite3 ppc64el 1.6.3-3ubuntu1 [12.3 kB] 107s Get:4 http://ftpmaster.internal/ubuntu plucky/main ppc64el libaprutil1-ldap ppc64el 1.6.3-3ubuntu1 [9318 B] 107s Get:5 http://ftpmaster.internal/ubuntu plucky/main ppc64el liblua5.4-0 ppc64el 5.4.7-1 [211 kB] 108s Get:6 http://ftpmaster.internal/ubuntu plucky/main ppc64el apache2-bin ppc64el 2.4.62-3ubuntu1 [1571 kB] 108s Get:7 http://ftpmaster.internal/ubuntu plucky/main ppc64el apache2-data all 2.4.62-3ubuntu1 [163 kB] 108s Get:8 http://ftpmaster.internal/ubuntu plucky/main ppc64el apache2-utils ppc64el 2.4.62-3ubuntu1 [103 kB] 108s Get:9 http://ftpmaster.internal/ubuntu plucky/main ppc64el apache2 ppc64el 2.4.62-3ubuntu1 [90.4 kB] 108s Get:10 http://ftpmaster.internal/ubuntu plucky/main ppc64el backuppc-rsync ppc64el 3.1.3.0-4 [323 kB] 108s Get:11 http://ftpmaster.internal/ubuntu plucky/main ppc64el postfix ppc64el 3.9.1-9 [1390 kB] 108s Get:12 http://ftpmaster.internal/ubuntu plucky/main ppc64el libarchive-zip-perl all 1.68-1 [90.2 kB] 108s Get:13 http://ftpmaster.internal/ubuntu plucky/main ppc64el libbackuppc-xs-perl ppc64el 0.62-2build6 [73.7 kB] 108s Get:14 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhtml-tagset-perl all 3.24-1 [14.1 kB] 108s Get:15 http://ftpmaster.internal/ubuntu plucky/main ppc64el liburi-perl all 5.30-1 [94.4 kB] 108s Get:16 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhtml-parser-perl ppc64el 3.83-1build1 [91.8 kB] 108s Get:17 http://ftpmaster.internal/ubuntu plucky/main ppc64el libcgi-pm-perl all 4.66-1 [185 kB] 108s Get:18 http://ftpmaster.internal/ubuntu plucky/main ppc64el libtimedate-perl all 2.3300-2 [34.0 kB] 108s Get:19 http://ftpmaster.internal/ubuntu plucky/main ppc64el libhttp-date-perl all 6.06-1 [10.2 kB] 108s Get:20 http://ftpmaster.internal/ubuntu plucky/main ppc64el libfile-listing-perl all 6.16-1 [11.3 kB] 108s Get:21 http://ftpmaster.internal/ubuntu plucky/main ppc64el libtime-parsedate-perl all 2015.103-5 [27.9 kB] 108s Get:22 http://ftpmaster.internal/ubuntu plucky/main ppc64el backuppc ppc64el 4.4.0-10ubuntu1 [518 kB] 108s Get:23 http://ftpmaster.internal/ubuntu plucky/main ppc64el samba-common all 2:4.20.4+dfsg-1ubuntu3 [68.1 kB] 108s Get:24 http://ftpmaster.internal/ubuntu plucky/main ppc64el libavahi-common-data ppc64el 0.8-14ubuntu1 [30.5 kB] 108s Get:25 http://ftpmaster.internal/ubuntu plucky/main ppc64el libavahi-common3 ppc64el 0.8-14ubuntu1 [26.0 kB] 108s Get:26 http://ftpmaster.internal/ubuntu plucky/main ppc64el libavahi-client3 ppc64el 0.8-14ubuntu1 [31.0 kB] 108s Get:27 http://ftpmaster.internal/ubuntu plucky/main ppc64el libcups2t64 ppc64el 2.4.11-0ubuntu2 [347 kB] 108s Get:28 http://ftpmaster.internal/ubuntu plucky/main ppc64el libtalloc2 ppc64el 2.4.2-1build2 [36.7 kB] 108s Get:29 http://ftpmaster.internal/ubuntu plucky/main ppc64el libtdb1 ppc64el 1.4.12-1 [63.0 kB] 108s Get:30 http://ftpmaster.internal/ubuntu plucky/main ppc64el libtevent0t64 ppc64el 0.16.1-3 [50.4 kB] 108s Get:31 http://ftpmaster.internal/ubuntu plucky/main ppc64el libldb2 ppc64el 2:2.9.1+samba4.20.4+dfsg-1ubuntu3 [223 kB] 108s Get:32 http://ftpmaster.internal/ubuntu plucky/main ppc64el libwbclient0 ppc64el 2:4.20.4+dfsg-1ubuntu3 [81.5 kB] 108s Get:33 http://ftpmaster.internal/ubuntu plucky/main ppc64el samba-libs ppc64el 2:4.20.4+dfsg-1ubuntu3 [6867 kB] 108s Get:34 http://ftpmaster.internal/ubuntu plucky/main ppc64el samba-common-bin ppc64el 2:4.20.4+dfsg-1ubuntu3 [1465 kB] 108s Get:35 http://ftpmaster.internal/ubuntu plucky/main ppc64el liburing2 ppc64el 2.8-1 [28.1 kB] 108s Get:36 http://ftpmaster.internal/ubuntu plucky/main ppc64el samba ppc64el 2:4.20.4+dfsg-1ubuntu3 [1522 kB] 108s Get:37 http://ftpmaster.internal/ubuntu plucky/main ppc64el libsmbclient0 ppc64el 2:4.20.4+dfsg-1ubuntu3 [70.7 kB] 108s Get:38 http://ftpmaster.internal/ubuntu plucky/main ppc64el smbclient ppc64el 2:4.20.4+dfsg-1ubuntu3 [529 kB] 109s Preconfiguring packages ... 109s Fetched 16.7 MB in 1s (12.9 MB/s) 109s Selecting previously unselected package libapr1t64:ppc64el. 109s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 74045 files and directories currently installed.) 109s Preparing to unpack .../00-libapr1t64_1.7.5-1_ppc64el.deb ... 109s Unpacking libapr1t64:ppc64el (1.7.5-1) ... 109s Selecting previously unselected package libaprutil1t64:ppc64el. 109s Preparing to unpack .../01-libaprutil1t64_1.6.3-3ubuntu1_ppc64el.deb ... 109s Unpacking libaprutil1t64:ppc64el (1.6.3-3ubuntu1) ... 109s Selecting previously unselected package libaprutil1-dbd-sqlite3:ppc64el. 109s Preparing to unpack .../02-libaprutil1-dbd-sqlite3_1.6.3-3ubuntu1_ppc64el.deb ... 109s Unpacking libaprutil1-dbd-sqlite3:ppc64el (1.6.3-3ubuntu1) ... 109s Selecting previously unselected package libaprutil1-ldap:ppc64el. 109s Preparing to unpack .../03-libaprutil1-ldap_1.6.3-3ubuntu1_ppc64el.deb ... 109s Unpacking libaprutil1-ldap:ppc64el (1.6.3-3ubuntu1) ... 109s Selecting previously unselected package liblua5.4-0:ppc64el. 109s Preparing to unpack .../04-liblua5.4-0_5.4.7-1_ppc64el.deb ... 109s Unpacking liblua5.4-0:ppc64el (5.4.7-1) ... 109s Selecting previously unselected package apache2-bin. 109s Preparing to unpack .../05-apache2-bin_2.4.62-3ubuntu1_ppc64el.deb ... 109s Unpacking apache2-bin (2.4.62-3ubuntu1) ... 109s Selecting previously unselected package apache2-data. 109s Preparing to unpack .../06-apache2-data_2.4.62-3ubuntu1_all.deb ... 109s Unpacking apache2-data (2.4.62-3ubuntu1) ... 109s Selecting previously unselected package apache2-utils. 109s Preparing to unpack .../07-apache2-utils_2.4.62-3ubuntu1_ppc64el.deb ... 109s Unpacking apache2-utils (2.4.62-3ubuntu1) ... 109s Selecting previously unselected package apache2. 109s Preparing to unpack .../08-apache2_2.4.62-3ubuntu1_ppc64el.deb ... 109s Unpacking apache2 (2.4.62-3ubuntu1) ... 110s Selecting previously unselected package backuppc-rsync. 110s Preparing to unpack .../09-backuppc-rsync_3.1.3.0-4_ppc64el.deb ... 110s Unpacking backuppc-rsync (3.1.3.0-4) ... 110s Selecting previously unselected package postfix. 110s Preparing to unpack .../10-postfix_3.9.1-9_ppc64el.deb ... 110s Unpacking postfix (3.9.1-9) ... 110s Selecting previously unselected package libarchive-zip-perl. 110s Preparing to unpack .../11-libarchive-zip-perl_1.68-1_all.deb ... 110s Unpacking libarchive-zip-perl (1.68-1) ... 110s Selecting previously unselected package libbackuppc-xs-perl:ppc64el. 110s Preparing to unpack .../12-libbackuppc-xs-perl_0.62-2build6_ppc64el.deb ... 110s Unpacking libbackuppc-xs-perl:ppc64el (0.62-2build6) ... 110s Selecting previously unselected package libhtml-tagset-perl. 110s Preparing to unpack .../13-libhtml-tagset-perl_3.24-1_all.deb ... 110s Unpacking libhtml-tagset-perl (3.24-1) ... 110s Selecting previously unselected package liburi-perl. 110s Preparing to unpack .../14-liburi-perl_5.30-1_all.deb ... 110s Unpacking liburi-perl (5.30-1) ... 110s Selecting previously unselected package libhtml-parser-perl:ppc64el. 110s Preparing to unpack .../15-libhtml-parser-perl_3.83-1build1_ppc64el.deb ... 110s Unpacking libhtml-parser-perl:ppc64el (3.83-1build1) ... 110s Selecting previously unselected package libcgi-pm-perl. 110s Preparing to unpack .../16-libcgi-pm-perl_4.66-1_all.deb ... 110s Unpacking libcgi-pm-perl (4.66-1) ... 110s Selecting previously unselected package libtimedate-perl. 110s Preparing to unpack .../17-libtimedate-perl_2.3300-2_all.deb ... 110s Unpacking libtimedate-perl (2.3300-2) ... 110s Selecting previously unselected package libhttp-date-perl. 110s Preparing to unpack .../18-libhttp-date-perl_6.06-1_all.deb ... 110s Unpacking libhttp-date-perl (6.06-1) ... 110s Selecting previously unselected package libfile-listing-perl. 110s Preparing to unpack .../19-libfile-listing-perl_6.16-1_all.deb ... 110s Unpacking libfile-listing-perl (6.16-1) ... 110s Selecting previously unselected package libtime-parsedate-perl. 110s Preparing to unpack .../20-libtime-parsedate-perl_2015.103-5_all.deb ... 110s Unpacking libtime-parsedate-perl (2015.103-5) ... 110s Selecting previously unselected package backuppc. 110s Preparing to unpack .../21-backuppc_4.4.0-10ubuntu1_ppc64el.deb ... 110s Unpacking backuppc (4.4.0-10ubuntu1) ... 110s Selecting previously unselected package samba-common. 110s Preparing to unpack .../22-samba-common_2%3a4.20.4+dfsg-1ubuntu3_all.deb ... 110s Unpacking samba-common (2:4.20.4+dfsg-1ubuntu3) ... 110s Selecting previously unselected package libavahi-common-data:ppc64el. 110s Preparing to unpack .../23-libavahi-common-data_0.8-14ubuntu1_ppc64el.deb ... 110s Unpacking libavahi-common-data:ppc64el (0.8-14ubuntu1) ... 110s Selecting previously unselected package libavahi-common3:ppc64el. 110s Preparing to unpack .../24-libavahi-common3_0.8-14ubuntu1_ppc64el.deb ... 110s Unpacking libavahi-common3:ppc64el (0.8-14ubuntu1) ... 110s Selecting previously unselected package libavahi-client3:ppc64el. 110s Preparing to unpack .../25-libavahi-client3_0.8-14ubuntu1_ppc64el.deb ... 110s Unpacking libavahi-client3:ppc64el (0.8-14ubuntu1) ... 110s Selecting previously unselected package libcups2t64:ppc64el. 110s Preparing to unpack .../26-libcups2t64_2.4.11-0ubuntu2_ppc64el.deb ... 110s Unpacking libcups2t64:ppc64el (2.4.11-0ubuntu2) ... 110s Selecting previously unselected package libtalloc2:ppc64el. 110s Preparing to unpack .../27-libtalloc2_2.4.2-1build2_ppc64el.deb ... 110s Unpacking libtalloc2:ppc64el (2.4.2-1build2) ... 110s Selecting previously unselected package libtdb1:ppc64el. 110s Preparing to unpack .../28-libtdb1_1.4.12-1_ppc64el.deb ... 110s Unpacking libtdb1:ppc64el (1.4.12-1) ... 110s Selecting previously unselected package libtevent0t64:ppc64el. 110s Preparing to unpack .../29-libtevent0t64_0.16.1-3_ppc64el.deb ... 110s Unpacking libtevent0t64:ppc64el (0.16.1-3) ... 110s Selecting previously unselected package libldb2:ppc64el. 110s Preparing to unpack .../30-libldb2_2%3a2.9.1+samba4.20.4+dfsg-1ubuntu3_ppc64el.deb ... 110s Unpacking libldb2:ppc64el (2:2.9.1+samba4.20.4+dfsg-1ubuntu3) ... 110s Selecting previously unselected package libwbclient0:ppc64el. 110s Preparing to unpack .../31-libwbclient0_2%3a4.20.4+dfsg-1ubuntu3_ppc64el.deb ... 110s Unpacking libwbclient0:ppc64el (2:4.20.4+dfsg-1ubuntu3) ... 110s Selecting previously unselected package samba-libs:ppc64el. 110s Preparing to unpack .../32-samba-libs_2%3a4.20.4+dfsg-1ubuntu3_ppc64el.deb ... 110s Unpacking samba-libs:ppc64el (2:4.20.4+dfsg-1ubuntu3) ... 111s Selecting previously unselected package samba-common-bin. 111s Preparing to unpack .../33-samba-common-bin_2%3a4.20.4+dfsg-1ubuntu3_ppc64el.deb ... 111s Unpacking samba-common-bin (2:4.20.4+dfsg-1ubuntu3) ... 111s Selecting previously unselected package liburing2:ppc64el. 111s Preparing to unpack .../34-liburing2_2.8-1_ppc64el.deb ... 111s Unpacking liburing2:ppc64el (2.8-1) ... 111s Selecting previously unselected package samba. 111s Preparing to unpack .../35-samba_2%3a4.20.4+dfsg-1ubuntu3_ppc64el.deb ... 111s Unpacking samba (2:4.20.4+dfsg-1ubuntu3) ... 111s Selecting previously unselected package libsmbclient0:ppc64el. 111s Preparing to unpack .../36-libsmbclient0_2%3a4.20.4+dfsg-1ubuntu3_ppc64el.deb ... 111s Unpacking libsmbclient0:ppc64el (2:4.20.4+dfsg-1ubuntu3) ... 111s Selecting previously unselected package smbclient. 111s Preparing to unpack .../37-smbclient_2%3a4.20.4+dfsg-1ubuntu3_ppc64el.deb ... 111s Unpacking smbclient (2:4.20.4+dfsg-1ubuntu3) ... 111s Setting up libarchive-zip-perl (1.68-1) ... 111s Setting up libtdb1:ppc64el (1.4.12-1) ... 111s Setting up libhtml-tagset-perl (3.24-1) ... 111s Setting up samba-common (2:4.20.4+dfsg-1ubuntu3) ... 111s Creating config file /etc/samba/smb.conf with new version 111s Setting up libwbclient0:ppc64el (2:4.20.4+dfsg-1ubuntu3) ... 111s Setting up libtalloc2:ppc64el (2.4.2-1build2) ... 111s Setting up backuppc-rsync (3.1.3.0-4) ... 111s Setting up libavahi-common-data:ppc64el (0.8-14ubuntu1) ... 111s Setting up libtimedate-perl (2.3300-2) ... 111s Setting up postfix (3.9.1-9) ... 112s Created symlink '/etc/systemd/system/multi-user.target.wants/postfix.service' → '/usr/lib/systemd/system/postfix.service'. 112s info: Selecting UID from range 100 to 999 ... 112s 112s info: Selecting GID from range 100 to 999 ... 112s info: Adding system user `postfix' (UID 107) ... 112s info: Adding new group `postfix' (GID 108) ... 113s info: Adding new user `postfix' (UID 107) with group `postfix' ... 113s info: Not creating home directory `/var/spool/postfix'. 113s info: Selecting GID from range 100 to 999 ... 113s info: Adding group `postdrop' (GID 111) ... 113s Creating /etc/postfix/dynamicmaps.cf 113s setting myhostname: autopkgtest.local 113s setting alias maps 113s setting alias database 113s changing /etc/mailname to autopkgtest.local 113s setting myorigin 113s setting destinations: $myhostname, autopkgtest.local, localhost.local, , localhost 113s setting relayhost: 113s setting mynetworks: 127.0.0.0/8 [::ffff:127.0.0.0]/104 [::1]/128 113s clearing mailbox_command 113s setting mailbox_size_limit: 0 113s setting recipient_delimiter: + 115s setting inet_interfaces: all 117s setting inet_protocols: all 117s /etc/aliases does not exist, creating it. 117s WARNING: /etc/aliases exists, but does not have a root alias. 117s 117s Postfix (main.cf) is now set up with a default configuration. If you need to 117s make changes, edit /etc/postfix/main.cf (and others) as needed. To view 117s Postfix configuration values, see postconf(1). 117s 117s After modifying main.cf, be sure to run 'systemctl reload postfix'. 117s 119s Setting up libtime-parsedate-perl (2015.103-5) ... 119s Setting up libapr1t64:ppc64el (1.7.5-1) ... 119s Setting up liblua5.4-0:ppc64el (5.4.7-1) ... 119s Setting up apache2-data (2.4.62-3ubuntu1) ... 119s Setting up liburing2:ppc64el (2.8-1) ... 119s Setting up libbackuppc-xs-perl:ppc64el (0.62-2build6) ... 119s Setting up liburi-perl (5.30-1) ... 119s Setting up libtevent0t64:ppc64el (0.16.1-3) ... 119s Setting up libaprutil1t64:ppc64el (1.6.3-3ubuntu1) ... 119s Setting up libhttp-date-perl (6.06-1) ... 119s Setting up libfile-listing-perl (6.16-1) ... 119s Setting up libavahi-common3:ppc64el (0.8-14ubuntu1) ... 119s Setting up libaprutil1-ldap:ppc64el (1.6.3-3ubuntu1) ... 119s Setting up libaprutil1-dbd-sqlite3:ppc64el (1.6.3-3ubuntu1) ... 119s Setting up libhtml-parser-perl:ppc64el (3.83-1build1) ... 119s Setting up libldb2:ppc64el (2:2.9.1+samba4.20.4+dfsg-1ubuntu3) ... 119s Setting up libavahi-client3:ppc64el (0.8-14ubuntu1) ... 119s Setting up apache2-utils (2.4.62-3ubuntu1) ... 119s Setting up libcgi-pm-perl (4.66-1) ... 119s Setting up samba-libs:ppc64el (2:4.20.4+dfsg-1ubuntu3) ... 119s Setting up apache2-bin (2.4.62-3ubuntu1) ... 119s Setting up libsmbclient0:ppc64el (2:4.20.4+dfsg-1ubuntu3) ... 119s Setting up libcups2t64:ppc64el (2.4.11-0ubuntu2) ... 119s Setting up apache2 (2.4.62-3ubuntu1) ... 119s Enabling module mpm_event. 119s Enabling module authz_core. 119s Enabling module authz_host. 119s Enabling module authn_core. 119s Enabling module auth_basic. 119s Enabling module access_compat. 119s Enabling module authn_file. 119s Enabling module authz_user. 119s Enabling module alias. 119s Enabling module dir. 119s Enabling module autoindex. 120s Enabling module env. 120s Enabling module mime. 120s Enabling module negotiation. 120s Enabling module setenvif. 120s Enabling module filter. 120s Enabling module deflate. 120s Enabling module status. 120s Enabling module reqtimeout. 120s Enabling conf charset. 120s Enabling conf localized-error-pages. 120s Enabling conf other-vhosts-access-log. 120s Enabling conf security. 120s Enabling conf serve-cgi-bin. 120s Enabling site 000-default. 121s Created symlink '/etc/systemd/system/multi-user.target.wants/apache2.service' → '/usr/lib/systemd/system/apache2.service'. 122s Created symlink '/etc/systemd/system/multi-user.target.wants/apache-htcacheclean.service' → '/usr/lib/systemd/system/apache-htcacheclean.service'. 123s Setting up backuppc (4.4.0-10ubuntu1) ... 123s 123s apache2_invoke: Enable configuration backuppc.conf 123s warn: The home directory `/var/lib/backuppc' already exists. Not touching this directory. 123s warn: Warning: The home directory `/var/lib/backuppc' does not belong to the user you are currently creating. 123s Adding password for user backuppc 123s Considering dependency authn_core for auth_basic: 123s Module authn_core already enabled 123s Module auth_basic already enabled 123s Considering dependency authz_core for authz_groupfile: 123s Module authz_core already enabled 123s Enabling module authz_groupfile. 123s To activate the new configuration, you need to run: 123s systemctl restart apache2 124s Module authn_file already enabled 124s Considering dependency authz_core for authz_user: 124s Module authz_core already enabled 124s Module authz_user already enabled 124s Your MPM seems to be threaded. Selecting cgid instead of cgi. 124s Enabling module cgid. 124s To activate the new configuration, you need to run: 124s systemctl restart apache2 124s Creating config file /etc/backuppc/config.pl with new version 124s Created symlink '/etc/systemd/system/multi-user.target.wants/backuppc.service' → '/usr/lib/systemd/system/backuppc.service'. 125s Setting up smbclient (2:4.20.4+dfsg-1ubuntu3) ... 125s Setting up samba-common-bin (2:4.20.4+dfsg-1ubuntu3) ... 125s Setting up samba (2:4.20.4+dfsg-1ubuntu3) ... 126s Created symlink '/etc/systemd/system/smb.service' → '/usr/lib/systemd/system/smbd.service'. 126s Created symlink '/etc/systemd/system/multi-user.target.wants/smbd.service' → '/usr/lib/systemd/system/smbd.service'. 126s Created symlink '/etc/systemd/system/nmb.service' → '/usr/lib/systemd/system/nmbd.service'. 126s Created symlink '/etc/systemd/system/multi-user.target.wants/nmbd.service' → '/usr/lib/systemd/system/nmbd.service'. 127s Processing triggers for rsyslog (8.2406.0-1ubuntu2) ... 127s Processing triggers for ufw (0.36.2-8) ... 127s Processing triggers for man-db (2.13.0-1) ... 129s Processing triggers for libc-bin (2.40-4ubuntu1) ... 129s Processing triggers for postfix (3.9.1-9) ... 129s Restarting postfix 130s autopkgtest [05:49:09]: test smb-backup: [----------------------- 130s net.ipv4.ping_group_range = 0 65534 130s Populating share path 130s Adding samba share 130s Adding [public] share 131s Configuring backuppc 131s Performing a full backup 131s Backup type: type = full, needs_full = 1, needs_incr = 1, lastFullTime = 0, opts{f} = 1, opts{i} = , opts{F} = 131s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 131s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 131s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.017 131s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.0.z created 2025-01-17 05:49:10 131s Backup prep: type = full, case = 1, inPlace = 1, doDuplicate = 0, newBkupNum = 0, newBkupIdx = 0, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 131s __bpc_progress_state__ backup share "public" 131s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 131s full backup started for share public 131s started full dump, share=public 131s Xfer PIDs are now 3935,3934 131s xferPids 3935,3934 131s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -Tc - 131s tarmode is now full, system, hidden, noreset, noverbose 131s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 131s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 131s __bpc_progress_fileCnt__ 0 131s new 644 0/0 480434 tmp.5l2Y3g 131s new 644 0/0 480434 tmp.j4cTnt 131s new 644 0/0 480434 tmp.RXWJwM 131s new 644 0/0 480434 tmp.M6lhc7 131s new 644 0/0 480434 tmp.hrTAhT 131s new 644 0/0 480434 tmp.mWI0rr 131s new 644 0/0 480434 tmp.V7rJTG 131s new 644 0/0 480434 tmp.oj2YrF 131s new 644 0/0 480434 tmp.YPSNo0 131s tar: dumped 10 files and 0 directories 131s Total bytes written: 4804340 (26.7 MiB/s) 131s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 131s new 644 0/0 480434 tmp.iubcVF 131s __bpc_progress_fileCnt__ 10 131s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377843 sizeNewComp, 13 inodeLast 131s Xfer PIDs are now 131s xferPids 131s full backup 0 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 131s BackupExpire: cntFull = 1, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 0 131s __bpc_progress_state__ fsck 131s Running BackupPC_refCountUpdate -h localhost on localhost 131s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 131s Xfer PIDs are now 3941 131s xferPids 3941 131s BackupPC_refCountUpdate: doing fsck on localhost #0 (full) since $ConfRefCntFsck == 1 131s __bpc_progress_state__ refCnt #0 131s __bpc_progress_state__ cntUpdate #0 131s __bpc_progress_state__ rename #0 131s __bpc_progress_state__ sumUpdate 131s __bpc_progress_fileCnt__ 0/128 131s __bpc_progress_fileCnt__ 8/128 131s __bpc_progress_fileCnt__ 16/128 131s __bpc_progress_fileCnt__ 24/128 131s __bpc_progress_fileCnt__ 32/128 131s __bpc_progress_fileCnt__ 40/128 131s __bpc_progress_fileCnt__ 48/128 131s __bpc_progress_fileCnt__ 56/128 131s __bpc_progress_fileCnt__ 64/128 131s __bpc_progress_fileCnt__ 72/128 131s __bpc_progress_fileCnt__ 80/128 131s __bpc_progress_fileCnt__ 88/128 131s __bpc_progress_fileCnt__ 96/128 131s __bpc_progress_fileCnt__ 104/128 131s __bpc_progress_fileCnt__ 112/128 131s __bpc_progress_fileCnt__ 120/128 131s __bpc_progress_state__ rename total 131s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 131s Xfer PIDs are now 131s xferPids 131s Finished BackupPC_refCountUpdate (running time: 0 sec) 131s Xfer PIDs are now 131s xferPids 131s full backup complete 131s OK: Full backup succeeded 131s Changing share content and performing an incremental backup 131s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1737092950, opts{f} = , opts{i} = 1, opts{F} = 131s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 131s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 131s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.019 131s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.1.z created 2025-01-17 05:49:10 131s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 1, newBkupIdx = 1, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = ) 131s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 131s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 131s Xfer PIDs are now 4011 131s xferPids 4011 131s __bpc_progress_state__ copy #0 -> #1 131s Copying backup #0 to #1 131s __bpc_progress_fileCnt__ 3 dirs 132s Xfer PIDs are now 4014,4011 132s xferPids 4014,4011 132s __bpc_progress_state__ sumUpdate 132s __bpc_progress_fileCnt__ 0/128 132s __bpc_progress_fileCnt__ 8/128 132s __bpc_progress_fileCnt__ 16/128 132s __bpc_progress_fileCnt__ 24/128 132s __bpc_progress_fileCnt__ 32/128 132s __bpc_progress_fileCnt__ 40/128 132s __bpc_progress_fileCnt__ 48/128 132s __bpc_progress_fileCnt__ 56/128 132s __bpc_progress_fileCnt__ 64/128 132s __bpc_progress_fileCnt__ 72/128 132s __bpc_progress_fileCnt__ 80/128 132s __bpc_progress_fileCnt__ 88/128 132s __bpc_progress_fileCnt__ 96/128 132s __bpc_progress_fileCnt__ 104/128 132s __bpc_progress_fileCnt__ 112/128 132s __bpc_progress_fileCnt__ 120/128 132s __bpc_progress_state__ rename total 132s BackupPC_refCountUpdate: host localhost got 0 errors (took 1 secs) 132s Xfer PIDs are now 4011 132s xferPids 4011 132s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 132s Finished BackupPC_backupDuplicate (running time: 1 sec) 132s __bpc_progress_state__ backup share "public" 132s Running: /usr/bin/smbclient \\\\localhost\\public -U guest -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 132s incr backup started back to 2025-01-17 04:49:10 (backup #0) for share public 132s started incr dump, share=public 132s Xfer PIDs are now 4018,4017 132s xferPids 4018,4017 132s 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 - 132s tar:1298 Getting files newer than Fri Jan 17 04:49:10 2025 132s tarmode is now full, system, hidden, noreset, noverbose 132s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 132s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 132s __bpc_progress_fileCnt__ 0 132s new 644 0/0 480434 tmp.47pwWT 132s same 644 0/0 480434 tmp.5l2Y3g 132s same 644 0/0 480434 tmp.j4cTnt 132s new 644 0/0 480434 tmp.puBy9q 132s new 644 0/0 480434 tmp.cpWmrI 132s new 644 0/0 480434 tmp.Emhab5 132s new 644 0/0 480434 tmp.zfpcem 132s same 644 0/0 480434 tmp.RXWJwM 132s new 644 0/0 480434 tmp.NdJ53Q 132s same 644 0/0 480434 tmp.M6lhc7 132s new 644 0/0 480434 tmp.x9bHMx 132s same 644 0/0 480434 tmp.hrTAhT 132s new 644 0/0 480434 tmp.dHwFqo 132s same 644 0/0 480434 tmp.mWI0rr 132s new 644 0/0 480434 tmp.RJBNdz 132s new 644 0/0 480434 tmp.YPWXe2 132s same 644 0/0 480434 tmp.V7rJTG 132s same 644 0/0 480434 tmp.oj2YrF 132s same 644 0/0 480434 tmp.YPSNo0 132s tar: dumped 20 files and 0 directories 132s Total bytes written: 9608680 (25.1 MiB/s) 132s same 644 0/0 480434 tmp.iubcVF 132s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 132s __bpc_progress_fileCnt__ 20 132s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377843 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377792 sizeNewComp, 24 inodeLast 132s Xfer PIDs are now 132s xferPids 132s incr backup 1 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 132s BackupExpire: cntFull = 2, cntIncr = 0, firstFull = 0, firstIncr = , oldestIncr = 0, oldestFull = 1.15740740740741e-05 132s __bpc_progress_state__ fsck 132s Running BackupPC_refCountUpdate -h localhost on localhost 132s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 132s Xfer PIDs are now 4024 132s xferPids 4024 132s __bpc_progress_state__ cntUpdate #1 132s __bpc_progress_state__ rename #1 132s __bpc_progress_state__ sumUpdate 132s __bpc_progress_fileCnt__ 0/128 132s __bpc_progress_fileCnt__ 8/128 132s __bpc_progress_fileCnt__ 16/128 132s __bpc_progress_fileCnt__ 24/128 132s __bpc_progress_fileCnt__ 32/128 132s __bpc_progress_fileCnt__ 40/128 132s __bpc_progress_fileCnt__ 48/128 132s __bpc_progress_fileCnt__ 56/128 132s __bpc_progress_fileCnt__ 64/128 132s __bpc_progress_fileCnt__ 72/128 132s __bpc_progress_fileCnt__ 80/128 132s __bpc_progress_fileCnt__ 88/128 132s __bpc_progress_fileCnt__ 96/128 132s __bpc_progress_fileCnt__ 104/128 132s __bpc_progress_fileCnt__ 112/128 132s __bpc_progress_fileCnt__ 120/128 132s __bpc_progress_state__ rename total 132s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 132s Xfer PIDs are now 132s xferPids 132s Finished BackupPC_refCountUpdate (running time: 0 sec) 132s Xfer PIDs are now 132s xferPids 132s incr backup complete 132s OK: Incremental backup succeeded 132s Done. 132s autopkgtest [05:49:11]: test smb-backup: -----------------------] 133s smb-backup PASS 133s autopkgtest [05:49:12]: test smb-backup: - - - - - - - - - - results - - - - - - - - - - 133s autopkgtest [05:49:12]: test authenticated-smb-backup: preparing testbed 134s Reading package lists... 134s Building dependency tree... 134s Reading state information... 134s Starting pkgProblemResolver with broken count: 0 134s Starting 2 pkgProblemResolver with broken count: 0 134s Done 134s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 135s autopkgtest [05:49:14]: test authenticated-smb-backup: [----------------------- 135s Populating share path 135s Adding samba share 135s Adding [private] share 135s Creating user 135s Creating a local and samba user called smbtest4174 136s Setting samba password for the smbtest4174 user 136s Added user smbtest4174. 136s Configuring backuppc 136s Performing a full backup 136s Backup type: type = full, needs_full = , needs_incr = , lastFullTime = 1737092950, opts{f} = 1, opts{i} = , opts{F} = 136s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 136s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 136s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.017 136s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.2.z created 2025-01-17 05:49:15 136s Backup prep: type = full, case = 4, inPlace = 0, doDuplicate = 0, newBkupNum = 2, newBkupIdx = 2, lastBkupNum = 1, lastBkupIdx = 1 (FillCycle = 0, noFillCnt = 0) 136s __bpc_progress_state__ backup share "private" 136s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest4174 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 136s full backup started for share private 136s started full dump, share=private 136s Xfer PIDs are now 4266,4265 136s xferPids 4266,4265 136s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest4174 -E -d 1 -c tarmode\ full -mSMB3 -Tc - 136s tarmode is now full, system, hidden, noreset, noverbose 136s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 1 136s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 136s __bpc_progress_fileCnt__ 0 136s new 644 0/0 480434 tmp.w2clNj 136s new 644 0/0 480434 tmp.1yXgqw 136s new 644 0/0 480434 tmp.Pv8oIA 136s new 644 0/0 480434 tmp.QqmoHo 136s new 644 0/0 480434 tmp.CiKVw3 136s new 644 0/0 480434 tmp.RRGtE3 136s new 644 0/0 480434 tmp.rudv9r 136s new 644 0/0 480434 tmp.PXnkjJ 136s new 644 0/0 480434 tmp.Z1Eheq 136s tar: dumped 10 files and 0 directories 136s Total bytes written: 4804340 (23.5 MiB/s) 136s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 136s new 644 0/0 480434 tmp.nZLvR9 136s __bpc_progress_fileCnt__ 10 136s tarExtract: Done: 0 errors, 0 filesExist, 0 sizeExist, 0 sizeExistComp, 10 filesTotal, 4804340 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377790 sizeNewComp, 36 inodeLast 136s Xfer PIDs are now 136s xferPids 136s OrphanShareNameClean: removing orphan share public from /var/lib/backuppc/pc/localhost/2 136s __bpc_progress_state__ delete share #2/public 136s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDelete -h localhost -n 2 -l -m -s public / 136s Xfer PIDs are now 4272 136s xferPids 4272 136s BackupPC_backupDelete: removing #2/public/ 136s __bpc_progress_state__ merge #2/public/ -> #1/public/ 136s BackupPC_backupDelete: Merge into backup 1/public/ 136s mergeDir: attr write to /var/lib/backuppc/pc/localhost/1/fpublic//attrib failed (digest was ) 136s bpc_attrib_dirWrite: can't open/create raw /var/lib/backuppc/pc/localhost/1/fpublic for writing 136s Xfer PIDs are now 4272,4275 136s xferPids 4272,4275 136s __bpc_progress_state__ refCnt #1 136s __bpc_progress_state__ cntUpdate #1 136s __bpc_progress_state__ rename #1 136s __bpc_progress_state__ refCnt #2 136s BackupPC_refCountUpdate: localhost #2 inodeLast set to 37 (was 24) 136s __bpc_progress_state__ cntUpdate #2 136s __bpc_progress_state__ rename #2 136s __bpc_progress_state__ sumUpdate 136s __bpc_progress_fileCnt__ 0/128 136s __bpc_progress_fileCnt__ 8/128 136s __bpc_progress_fileCnt__ 16/128 136s __bpc_progress_fileCnt__ 24/128 136s __bpc_progress_fileCnt__ 32/128 136s __bpc_progress_fileCnt__ 40/128 136s __bpc_progress_fileCnt__ 48/128 136s __bpc_progress_fileCnt__ 56/128 136s __bpc_progress_fileCnt__ 64/128 136s __bpc_progress_fileCnt__ 72/128 136s __bpc_progress_fileCnt__ 80/128 136s __bpc_progress_fileCnt__ 88/128 136s __bpc_progress_fileCnt__ 96/128 136s __bpc_progress_fileCnt__ 104/128 136s __bpc_progress_fileCnt__ 112/128 136s __bpc_progress_fileCnt__ 120/128 136s __bpc_progress_state__ rename total 136s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 136s Xfer PIDs are now 4272 136s xferPids 4272 136s Xfer PIDs are now 136s xferPids 136s Finished BackupPC_backupDelete, status = 0 (running time: 0 sec) 136s Xfer PIDs are now 136s xferPids 136s full backup 2 complete, 10 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 136s BackupExpire: cntFull = 2, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 4.62962962962963e-05, oldestFull = 5.78703703703704e-05 136s __bpc_progress_state__ fsck 136s Running BackupPC_refCountUpdate -h localhost on localhost 136s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 136s Xfer PIDs are now 4278 136s xferPids 4278 136s BackupPC_refCountUpdate: doing fsck on localhost #2 (full) since $ConfRefCntFsck == 1 136s __bpc_progress_state__ refCnt #2 136s __bpc_progress_state__ cntUpdate #2 136s __bpc_progress_state__ rename #2 136s __bpc_progress_state__ sumUpdate 136s __bpc_progress_fileCnt__ 0/128 136s __bpc_progress_fileCnt__ 8/128 136s __bpc_progress_fileCnt__ 16/128 136s __bpc_progress_fileCnt__ 24/128 136s __bpc_progress_fileCnt__ 32/128 136s __bpc_progress_fileCnt__ 40/128 136s __bpc_progress_fileCnt__ 48/128 136s __bpc_progress_fileCnt__ 56/128 136s __bpc_progress_fileCnt__ 64/128 136s __bpc_progress_fileCnt__ 72/128 136s __bpc_progress_fileCnt__ 80/128 137s __bpc_progress_fileCnt__ 88/128 137s __bpc_progress_fileCnt__ 96/128 137s __bpc_progress_fileCnt__ 104/128 137s __bpc_progress_fileCnt__ 112/128 137s __bpc_progress_fileCnt__ 120/128 137s __bpc_progress_state__ rename total 137s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 137s Xfer PIDs are now 137s xferPids 137s Finished BackupPC_refCountUpdate (running time: 0 sec) 137s Xfer PIDs are now 137s xferPids 137s full backup complete 137s OK: Full backup succeeded 137s Changing share content and performing an incremental backup 137s Backup type: type = incr, needs_full = , needs_incr = , lastFullTime = 1737092955, opts{f} = , opts{i} = 1, opts{F} = 137s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 137s cmdSystemOrEval: about to system /bin/ping6 -c 1 localhost 137s CheckHostAlive: ran '/bin/ping6 -c 1 localhost'; returning 0.020 137s XferLOG file /var/lib/backuppc/pc/localhost/XferLOG.3.z created 2025-01-17 05:49:16 137s Backup prep: type = incr, case = 3, inPlace = 1, doDuplicate = 1, newBkupNum = 3, newBkupIdx = 3, lastBkupNum = , lastBkupIdx = (FillCycle = 0, noFillCnt = 1) 137s Executing /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 137s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_backupDuplicate -m -h localhost 137s Xfer PIDs are now 4348 137s xferPids 4348 137s __bpc_progress_state__ copy #2 -> #3 137s Copying backup #2 to #3 137s __bpc_progress_fileCnt__ 3 dirs 137s Xfer PIDs are now 4348,4351 137s xferPids 4348,4351 137s __bpc_progress_state__ sumUpdate 137s __bpc_progress_fileCnt__ 0/128 137s __bpc_progress_fileCnt__ 8/128 137s __bpc_progress_fileCnt__ 16/128 137s __bpc_progress_fileCnt__ 24/128 137s __bpc_progress_fileCnt__ 32/128 137s __bpc_progress_fileCnt__ 40/128 137s __bpc_progress_fileCnt__ 48/128 137s __bpc_progress_fileCnt__ 56/128 137s __bpc_progress_fileCnt__ 64/128 137s __bpc_progress_fileCnt__ 72/128 137s __bpc_progress_fileCnt__ 80/128 137s __bpc_progress_fileCnt__ 88/128 137s __bpc_progress_fileCnt__ 96/128 137s __bpc_progress_fileCnt__ 104/128 137s __bpc_progress_fileCnt__ 112/128 137s __bpc_progress_fileCnt__ 120/128 137s __bpc_progress_state__ rename total 137s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 137s Xfer PIDs are now 4348 137s xferPids 4348 137s BackupPC_backupDuplicate: got 0 errors and 0 file open errors 137s Finished BackupPC_backupDuplicate (running time: 0 sec) 137s __bpc_progress_state__ backup share "private" 137s Running: /usr/bin/smbclient \\\\localhost\\private -U smbtest4174 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 137s incr backup started back to 2025-01-17 04:49:15 (backup #2) for share private 137s started incr dump, share=private 137s Xfer PIDs are now 4355,4354 137s xferPids 4355,4354 137s XferErr cmdExecOrEval: about to exec /usr/bin/smbclient \\\\localhost\\private -U smbtest4174 -E -d 1 -c tarmode\ full -mSMB3 -TcN /var/lib/backuppc/pc/localhost/timeStamp.level0 - 137s tar:1298 Getting files newer than Fri Jan 17 04:49:15 2025 137s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract: got Full = 137s tarExtract: /usr/share/backuppc/bin/BackupPC_tarExtract starting... (XferLogLevel = 1) 137s tarmode is now full, system, hidden, noreset, noverbose 137s __bpc_progress_fileCnt__ 0 137s same 644 0/0 480434 tmp.w2clNj 137s new 644 0/0 480434 tmp.veC1zB 137s new 644 0/0 480434 tmp.hZSBpa 137s same 644 0/0 480434 tmp.1yXgqw 137s same 644 0/0 480434 tmp.Pv8oIA 137s new 644 0/0 480434 tmp.0GxpS1 137s same 644 0/0 480434 tmp.QqmoHo 137s new 644 0/0 480434 tmp.MbxeZZ 137s new 644 0/0 480434 tmp.jy9pEw 137s same 644 0/0 480434 tmp.CiKVw3 137s new 644 0/0 480434 tmp.bzdgha 137s same 644 0/0 480434 tmp.RRGtE3 137s same 644 0/0 480434 tmp.rudv9r 137s same 644 0/0 480434 tmp.PXnkjJ 137s same 644 0/0 480434 tmp.Z1Eheq 137s new 644 0/0 480434 tmp.J3gZ7L 137s new 644 0/0 480434 tmp.nEB8Jd 137s new 644 0/0 480434 tmp.vj2jMK 137s same 644 0/0 480434 tmp.nZLvR9 137s tar: dumped 20 files and 0 directories 137s Total bytes written: 9608680 (48.4 MiB/s) 137s readOutput: sysread returns 0 and got EOF (exit ok = 1, ) 137s new 644 0/0 480434 tmp.YZWD4R 137s __bpc_progress_fileCnt__ 20 137s tarExtract: Done: 0 errors, 10 filesExist, 4804340 sizeExist, 1377790 sizeExistComp, 20 filesTotal, 9608680 sizeTotal, 10 filesNew, 4804340 sizeNew, 1377760 sizeNewComp, 47 inodeLast 137s Xfer PIDs are now 137s xferPids 137s incr backup 3 complete, 20 files, 0 bytes, 1 xferErrs (0 bad files, 0 bad shares, 1 other) 137s BackupExpire: cntFull = 3, cntIncr = 1, firstFull = 0, firstIncr = 1, oldestIncr = 5.78703703703704e-05, oldestFull = 6.94444444444444e-05 137s __bpc_progress_state__ fsck 137s Running BackupPC_refCountUpdate -h localhost on localhost 137s cmdSystemOrEval: about to system /usr/share/backuppc/bin/BackupPC_refCountUpdate -h localhost 137s Xfer PIDs are now 4361 137s xferPids 4361 137s __bpc_progress_state__ cntUpdate #3 137s __bpc_progress_state__ rename #3 137s __bpc_progress_state__ sumUpdate 137s __bpc_progress_fileCnt__ 0/128 137s __bpc_progress_fileCnt__ 8/128 137s __bpc_progress_fileCnt__ 16/128 137s __bpc_progress_fileCnt__ 24/128 137s __bpc_progress_fileCnt__ 32/128 137s __bpc_progress_fileCnt__ 40/128 137s __bpc_progress_fileCnt__ 48/128 137s __bpc_progress_fileCnt__ 56/128 137s __bpc_progress_fileCnt__ 64/128 137s __bpc_progress_fileCnt__ 72/128 137s __bpc_progress_fileCnt__ 80/128 137s __bpc_progress_fileCnt__ 88/128 137s __bpc_progress_fileCnt__ 96/128 137s __bpc_progress_fileCnt__ 104/128 137s __bpc_progress_fileCnt__ 112/128 137s __bpc_progress_fileCnt__ 120/128 137s __bpc_progress_state__ rename total 137s BackupPC_refCountUpdate: host localhost got 0 errors (took 0 secs) 137s Xfer PIDs are now 137s xferPids 137s Finished BackupPC_refCountUpdate (running time: 0 sec) 137s Xfer PIDs are now 137s xferPids 137s incr backup complete 137s OK: Incremental backup succeeded 137s Done. 138s Deleted user smbtest4174. 138s userdel: smbtest4174 mail spool (/var/mail/smbtest4174) not found 138s autopkgtest [05:49:17]: test authenticated-smb-backup: -----------------------] 138s authenticated-smb-backup PASS 138s autopkgtest [05:49:17]: test authenticated-smb-backup: - - - - - - - - - - results - - - - - - - - - - 139s autopkgtest [05:49:18]: @@@@@@@@@@@@@@@@@@@@ summary 139s smb-backup PASS 139s authenticated-smb-backup PASS 155s nova [W] Using flock in prodstack6-ppc64el 155s Creating nova instance adt-plucky-ppc64el-backuppc-20250117-054658-juju-7f2275-prod-proposed-migration-environment-20-1de1f0a7-d4dc-4b92-9212-e8c4c1adf855 from image adt/ubuntu-plucky-ppc64el-server-20250117.img (UUID fc4495f7-ff1c-4a92-a3fe-ee8c580d771a)... 155s nova [W] Timed out waiting for af83d20f-480f-418f-8c40-0e96b27e4609 to get deleted.