0s autopkgtest [17:29:58]: starting date and time: 2025-05-03 17:29:58+0000 0s autopkgtest [17:29:58]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [17:29:58]: host juju-7f2275-prod-proposed-migration-environment-21; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.k_6irs52/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:e2fsprogs,src:fuse3 --apt-upgrade ext4magic --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=e2fsprogs/1.47.2-1ubuntu2 fuse3/3.17.1+git250416-1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-21@bos03-ppc64el-3.secgroup --name adt-questing-ppc64el-ext4magic-20250503-172958-juju-7f2275-prod-proposed-migration-environment-21-8f18c89c-c86d-4ed8-9e3b-211bf5925bba --image adt/ubuntu-questing-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-21 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 79s autopkgtest [17:31:17]: testbed dpkg architecture: ppc64el 79s autopkgtest [17:31:17]: testbed apt version: 3.0.0 80s autopkgtest [17:31:18]: @@@@@@@@@@@@@@@@@@@@ test bed setup 80s autopkgtest [17:31:18]: testbed release detected to be: None 81s autopkgtest [17:31:19]: updating testbed package index (apt update) 81s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 81s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 81s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 81s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 81s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [33.7 kB] 81s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [1457 kB] 82s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [147 kB] 82s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el Packages [186 kB] 82s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el Packages [1321 kB] 82s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse ppc64el Packages [29.2 kB] 82s Fetched 3284 kB in 1s (2550 kB/s) 83s Reading package lists... 84s autopkgtest [17:31:22]: upgrading testbed (apt dist-upgrade and autopurge) 84s Reading package lists... 84s Building dependency tree... 84s Reading state information... 85s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 85s Starting 2 pkgProblemResolver with broken count: 0 85s Done 86s Entering ResolveByKeep 86s 86s Calculating upgrade... 86s The following NEW packages will be installed: 86s libfuse3-4 86s The following packages will be upgraded: 86s cloud-init cloud-init-base debianutils e2fsprogs e2fsprogs-l10n fuse3 86s libcom-err2 libext2fs2t64 libmbim-glib4 libmbim-proxy libnftnl11 86s libpython3.12-minimal libpython3.12-stdlib libpython3.12t64 libqmi-glib5 86s libqmi-proxy libss2 logsave python3-lazr.restfulclient 86s 19 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 86s Need to get 8758 kB of archives. 86s After this operation, 361 kB of additional disk space will be used. 86s Get:1 http://ftpmaster.internal/ubuntu questing/main ppc64el debianutils ppc64el 5.22 [93.9 kB] 87s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el logsave ppc64el 1.47.2-1ubuntu2 [27.0 kB] 87s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el e2fsprogs-l10n all 1.47.2-1ubuntu2 [7036 B] 87s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el libext2fs2t64 ppc64el 1.47.2-1ubuntu2 [273 kB] 87s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el e2fsprogs ppc64el 1.47.2-1ubuntu2 [676 kB] 87s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el libcom-err2 ppc64el 1.47.2-1ubuntu2 [26.9 kB] 87s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el libss2 ppc64el 1.47.2-1ubuntu2 [18.9 kB] 87s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el libfuse3-4 ppc64el 3.17.1+git250416-1 [106 kB] 87s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el fuse3 ppc64el 3.17.1+git250416-1 [29.5 kB] 87s Get:10 http://ftpmaster.internal/ubuntu questing/main ppc64el libnftnl11 ppc64el 1.2.9-1 [77.1 kB] 87s Get:11 http://ftpmaster.internal/ubuntu questing/main ppc64el cloud-init-base all 25.2~1g7a0265d3-0ubuntu1 [619 kB] 87s Get:12 http://ftpmaster.internal/ubuntu questing/main ppc64el libmbim-proxy ppc64el 1.32.0-1 [6352 B] 87s Get:13 http://ftpmaster.internal/ubuntu questing/main ppc64el libmbim-glib4 ppc64el 1.32.0-1 [258 kB] 87s Get:14 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el libpython3.12t64 ppc64el 3.12.10-1 [2558 kB] 87s Get:15 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el libpython3.12-stdlib ppc64el 3.12.10-1 [2105 kB] 87s Get:16 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el libpython3.12-minimal ppc64el 3.12.10-1 [841 kB] 87s Get:17 http://ftpmaster.internal/ubuntu questing/main ppc64el libqmi-proxy ppc64el 1.36.0-1 [6236 B] 87s Get:18 http://ftpmaster.internal/ubuntu questing/main ppc64el libqmi-glib5 ppc64el 1.36.0-1 [976 kB] 87s Get:19 http://ftpmaster.internal/ubuntu questing/main ppc64el python3-lazr.restfulclient all 0.14.6-3 [51.0 kB] 87s Get:20 http://ftpmaster.internal/ubuntu questing/main ppc64el cloud-init all 25.2~1g7a0265d3-0ubuntu1 [2106 B] 88s Preconfiguring packages ... 88s Fetched 8758 kB in 1s (9474 kB/s) 88s (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 ... 107205 files and directories currently installed.) 88s Preparing to unpack .../debianutils_5.22_ppc64el.deb ... 88s Unpacking debianutils (5.22) over (5.21) ... 88s Setting up debianutils (5.22) ... 88s (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 ... 107205 files and directories currently installed.) 88s Preparing to unpack .../logsave_1.47.2-1ubuntu2_ppc64el.deb ... 88s Unpacking logsave (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 88s Preparing to unpack .../e2fsprogs-l10n_1.47.2-1ubuntu2_all.deb ... 88s Unpacking e2fsprogs-l10n (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 88s Preparing to unpack .../libext2fs2t64_1.47.2-1ubuntu2_ppc64el.deb ... 88s Leaving 'diversion of /lib/powerpc64le-linux-gnu/libe2p.so.2 to /lib/powerpc64le-linux-gnu/libe2p.so.2.usr-is-merged by libext2fs2t64' 88s Leaving 'diversion of /lib/powerpc64le-linux-gnu/libe2p.so.2.3 to /lib/powerpc64le-linux-gnu/libe2p.so.2.3.usr-is-merged by libext2fs2t64' 88s Leaving 'diversion of /lib/powerpc64le-linux-gnu/libext2fs.so.2 to /lib/powerpc64le-linux-gnu/libext2fs.so.2.usr-is-merged by libext2fs2t64' 88s Leaving 'diversion of /lib/powerpc64le-linux-gnu/libext2fs.so.2.4 to /lib/powerpc64le-linux-gnu/libext2fs.so.2.4.usr-is-merged by libext2fs2t64' 88s Unpacking libext2fs2t64:ppc64el (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 88s Setting up libext2fs2t64:ppc64el (1.47.2-1ubuntu2) ... 88s (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 ... 107205 files and directories currently installed.) 88s Preparing to unpack .../00-e2fsprogs_1.47.2-1ubuntu2_ppc64el.deb ... 88s Unpacking e2fsprogs (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 88s Preparing to unpack .../01-libcom-err2_1.47.2-1ubuntu2_ppc64el.deb ... 88s Unpacking libcom-err2:ppc64el (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 88s Preparing to unpack .../02-libss2_1.47.2-1ubuntu2_ppc64el.deb ... 88s Unpacking libss2:ppc64el (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 88s Selecting previously unselected package libfuse3-4:ppc64el. 88s Preparing to unpack .../03-libfuse3-4_3.17.1+git250416-1_ppc64el.deb ... 88s Unpacking libfuse3-4:ppc64el (3.17.1+git250416-1) ... 88s Preparing to unpack .../04-fuse3_3.17.1+git250416-1_ppc64el.deb ... 89s Adding 'diversion of /bin/fusermount to /bin/fusermount.usr-is-merged by fuse3' 89s Adding 'diversion of /sbin/mount.fuse to /sbin/mount.fuse.usr-is-merged by fuse3' 89s Unpacking fuse3 (3.17.1+git250416-1) over (3.14.0-10) ... 89s Preparing to unpack .../05-libnftnl11_1.2.9-1_ppc64el.deb ... 89s Unpacking libnftnl11:ppc64el (1.2.9-1) over (1.2.8-1) ... 89s Preparing to unpack .../06-cloud-init-base_25.2~1g7a0265d3-0ubuntu1_all.deb ... 89s Unpacking cloud-init-base (25.2~1g7a0265d3-0ubuntu1) over (25.1.1-0ubuntu2) ... 89s Preparing to unpack .../07-libmbim-proxy_1.32.0-1_ppc64el.deb ... 89s Unpacking libmbim-proxy (1.32.0-1) over (1.31.2-0ubuntu4) ... 89s Preparing to unpack .../08-libmbim-glib4_1.32.0-1_ppc64el.deb ... 89s Unpacking libmbim-glib4:ppc64el (1.32.0-1) over (1.31.2-0ubuntu4) ... 89s Preparing to unpack .../09-libpython3.12t64_3.12.10-1_ppc64el.deb ... 89s Unpacking libpython3.12t64:ppc64el (3.12.10-1) over (3.12.8-3) ... 89s Preparing to unpack .../10-libpython3.12-stdlib_3.12.10-1_ppc64el.deb ... 89s Unpacking libpython3.12-stdlib:ppc64el (3.12.10-1) over (3.12.8-3) ... 89s Preparing to unpack .../11-libpython3.12-minimal_3.12.10-1_ppc64el.deb ... 89s Unpacking libpython3.12-minimal:ppc64el (3.12.10-1) over (3.12.8-3) ... 89s Preparing to unpack .../12-libqmi-proxy_1.36.0-1_ppc64el.deb ... 89s Unpacking libqmi-proxy (1.36.0-1) over (1.35.6-1) ... 89s Preparing to unpack .../13-libqmi-glib5_1.36.0-1_ppc64el.deb ... 89s Unpacking libqmi-glib5:ppc64el (1.36.0-1) over (1.35.6-1) ... 89s Preparing to unpack .../14-python3-lazr.restfulclient_0.14.6-3_all.deb ... 90s Unpacking python3-lazr.restfulclient (0.14.6-3) over (0.14.6-2) ... 90s Preparing to unpack .../15-cloud-init_25.2~1g7a0265d3-0ubuntu1_all.deb ... 90s Unpacking cloud-init (25.2~1g7a0265d3-0ubuntu1) over (25.1.1-0ubuntu2) ... 90s Setting up libpython3.12-minimal:ppc64el (3.12.10-1) ... 90s Setting up libnftnl11:ppc64el (1.2.9-1) ... 90s Setting up libcom-err2:ppc64el (1.47.2-1ubuntu2) ... 90s Setting up cloud-init-base (25.2~1g7a0265d3-0ubuntu1) ... 92s Setting up libss2:ppc64el (1.47.2-1ubuntu2) ... 92s Setting up logsave (1.47.2-1ubuntu2) ... 92s Setting up libfuse3-4:ppc64el (3.17.1+git250416-1) ... 92s Setting up python3-lazr.restfulclient (0.14.6-3) ... 92s Setting up libmbim-glib4:ppc64el (1.32.0-1) ... 92s Setting up cloud-init (25.2~1g7a0265d3-0ubuntu1) ... 92s Setting up libpython3.12-stdlib:ppc64el (3.12.10-1) ... 92s Setting up e2fsprogs (1.47.2-1ubuntu2) ... 92s update-initramfs: deferring update (trigger activated) 92s Setting up libmbim-proxy (1.32.0-1) ... 92s Setting up fuse3 (3.17.1+git250416-1) ... 92s Installing new version of config file /etc/fuse.conf ... 92s Removing 'diversion of /bin/fusermount to /bin/fusermount.usr-is-merged by fuse3' 92s Removing 'diversion of /sbin/mount.fuse to /sbin/mount.fuse.usr-is-merged by fuse3' 92s update-initramfs: deferring update (trigger activated) 92s Setting up libpython3.12t64:ppc64el (3.12.10-1) ... 92s Setting up e2fsprogs-l10n (1.47.2-1ubuntu2) ... 92s Setting up libqmi-glib5:ppc64el (1.36.0-1) ... 92s Setting up libqmi-proxy (1.36.0-1) ... 92s Processing triggers for initramfs-tools (0.147ubuntu1) ... 92s update-initramfs: Generating /boot/initrd.img-6.14.0-15-generic 92s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 102s Processing triggers for libc-bin (2.41-6ubuntu1) ... 102s Processing triggers for rsyslog (8.2412.0-2ubuntu2) ... 103s Processing triggers for man-db (2.13.0-1) ... 105s Reading package lists... 105s Building dependency tree... 105s Reading state information... 105s Starting pkgProblemResolver with broken count: 0 105s Starting 2 pkgProblemResolver with broken count: 0 105s Done 106s Solving dependencies... 106s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 106s autopkgtest [17:31:44]: rebooting testbed after setup commands that affected boot 128s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 139s autopkgtest [17:32:17]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr 6 14:52:42 UTC 2025 142s autopkgtest [17:32:20]: @@@@@@@@@@@@@@@@@@@@ apt-source ext4magic 144s Get:1 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (dsc) [2149 B] 144s Get:2 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (tar) [427 kB] 144s Get:3 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (diff) [175 kB] 144s gpgv: Signature made Mon Apr 1 05:55:08 2024 UTC 144s gpgv: using RSA key A089FB36AAFBDAD5ACC1325069F790171A210984 144s gpgv: Can't check signature: No public key 144s dpkg-source: warning: cannot verify inline signature for ./ext4magic_0.3.2-14build2.dsc: no acceptable signature found 144s autopkgtest [17:32:22]: testing package ext4magic version 0.3.2-14build2 145s autopkgtest [17:32:23]: build not needed 145s autopkgtest [17:32:23]: test command1: preparing testbed 146s Reading package lists... 146s Building dependency tree... 146s Reading state information... 146s Starting pkgProblemResolver with broken count: 0 146s Starting 2 pkgProblemResolver with broken count: 0 146s Done 146s The following NEW packages will be installed: 146s ext4magic 147s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 147s Need to get 130 kB of archives. 147s After this operation, 365 kB of additional disk space will be used. 147s Get:1 http://ftpmaster.internal/ubuntu questing/universe ppc64el ext4magic ppc64el 0.3.2-14build2 [130 kB] 147s Fetched 130 kB in 0s (383 kB/s) 147s Selecting previously unselected package ext4magic. 147s (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 ... 107215 files and directories currently installed.) 147s Preparing to unpack .../ext4magic_0.3.2-14build2_ppc64el.deb ... 147s Unpacking ext4magic (0.3.2-14build2) ... 147s Setting up ext4magic (0.3.2-14build2) ... 147s Processing triggers for man-db (2.13.0-1) ... 149s autopkgtest [17:32:27]: test command1: ext4magic -V 149s autopkgtest [17:32:27]: test command1: [----------------------- 149s ext4magic version : 0.3.2 149s libext2fs version : 1.47.2 149s CPU is little endian. 149s Expert Mode is activ 150s autopkgtest [17:32:28]: test command1: -----------------------] 150s command1 PASS 150s autopkgtest [17:32:28]: test command1: - - - - - - - - - - results - - - - - - - - - - 150s autopkgtest [17:32:28]: test command2: preparing testbed 151s Reading package lists... 151s Building dependency tree... 151s Reading state information... 151s Starting pkgProblemResolver with broken count: 0 151s Starting 2 pkgProblemResolver with broken count: 0 151s Done 151s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 152s autopkgtest [17:32:30]: test command2: ext4magic debian/tests/test.img -M -d $AUTOPKGTEST_TMP 152s autopkgtest [17:32:30]: test command2: [----------------------- 153s Warning: Activate magic-scan or disaster-recovery function, may be some command line options ignored 153s "/tmp/autopkgtest.aRKymr/autopkgtest_tmp" accept for recoverdir 153s Filesystem in use: debian/tests/test.img 153s 153s Using internal Journal at Inode 8 153s Activ Time after : Mon Dec 10 13:06:19 2018 153s Activ Time before : Sat May 3 17:32:30 2025 153s Inode 2 is allocated 153s -------- /tmp/autopkgtest.aRKymr/autopkgtest_tmp/lost+found 153s -------- /tmp/autopkgtest.aRKymr/autopkgtest_tmp/openlogo-50.png 153s -------- /tmp/autopkgtest.aRKymr/autopkgtest_tmp/Debian25years-DanielLenharo.png 153s -------- /tmp/autopkgtest.aRKymr/autopkgtest_tmp/ 153s MAGIC-1 : start lost directory search 153s MAGIC-2 : start lost file search 153s MAGIC-2 : start lost in journal search 153s -------- /tmp/autopkgtest.aRKymr/autopkgtest_tmp/MAGIC-2/application/octet-stream/I_0000000263.unknown 153s -------- /tmp/autopkgtest.aRKymr/autopkgtest_tmp/MAGIC-2/application/octet-stream/I_0000000271.unknown 153s MAGIC-3 : start ext4-magic-scan search 153s ext4magic : EXIT_SUCCESS 154s autopkgtest [17:32:32]: test command2: -----------------------] 154s command2 PASS 154s autopkgtest [17:32:32]: test command2: - - - - - - - - - - results - - - - - - - - - - 155s autopkgtest [17:32:33]: @@@@@@@@@@@@@@@@@@@@ summary 155s command1 PASS 155s command2 PASS 171s nova [W] Using flock in prodstack6-ppc64el 171s Creating nova instance adt-questing-ppc64el-ext4magic-20250503-172958-juju-7f2275-prod-proposed-migration-environment-21-8f18c89c-c86d-4ed8-9e3b-211bf5925bba from image adt/ubuntu-questing-ppc64el-server-20250503.img (UUID 1e63be20-33d6-474d-9c56-b247a2188377)... 171s nova [W] Timed out waiting for 53ef08df-9528-451b-bdd5-21ac127d2315 to get deleted.