0s autopkgtest [08:53:20]: starting date and time: 2025-05-02 08:53:20+0000 0s autopkgtest [08:53:20]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [08:53:20]: host juju-7f2275-prod-proposed-migration-environment-23; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.igb844_w/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-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-23@bos03-s390x-4.secgroup --name adt-questing-s390x-ext4magic-20250502-085320-juju-7f2275-prod-proposed-migration-environment-23-0fb6c996-75e9-4c39-a87e-eb0e0471f1ae --image adt/ubuntu-questing-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-23 --net-id=net_prod-proposed-migration-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 99s autopkgtest [08:54:59]: testbed dpkg architecture: s390x 99s autopkgtest [08:54:59]: testbed apt version: 3.0.0 100s autopkgtest [08:55:00]: @@@@@@@@@@@@@@@@@@@@ test bed setup 100s autopkgtest [08:55:00]: testbed release detected to be: None 101s autopkgtest [08:55:01]: updating testbed package index (apt update) 101s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 101s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 101s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 101s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 101s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [2076 kB] 102s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [44.4 kB] 102s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [175 kB] 102s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [208 kB] 102s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [1079 kB] 103s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [9148 B] 103s Fetched 3701 kB in 2s (1947 kB/s) 103s Reading package lists... 104s autopkgtest [08:55:04]: upgrading testbed (apt dist-upgrade and autopurge) 104s Reading package lists... 104s Building dependency tree... 104s Reading state information... 104s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 104s Starting 2 pkgProblemResolver with broken count: 0 104s Done 105s Entering ResolveByKeep 105s 105s Calculating upgrade... 105s The following NEW packages will be installed: 105s libfuse3-4 105s The following packages will be upgraded: 105s e2fsprogs e2fsprogs-l10n fuse3 htop libcom-err2 libext2fs2t64 libss2 logsave 105s nano 105s 9 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 105s Need to get 1544 kB of archives. 105s After this operation, 321 kB of additional disk space will be used. 105s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main s390x logsave s390x 1.47.2-1ubuntu2 [26.7 kB] 105s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main s390x e2fsprogs-l10n all 1.47.2-1ubuntu2 [7036 B] 105s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libext2fs2t64 s390x 1.47.2-1ubuntu2 [236 kB] 105s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/main s390x e2fsprogs s390x 1.47.2-1ubuntu2 [624 kB] 106s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libcom-err2 s390x 1.47.2-1ubuntu2 [26.9 kB] 106s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libss2 s390x 1.47.2-1ubuntu2 [18.0 kB] 106s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libfuse3-4 s390x 3.17.1+git250416-1 [89.1 kB] 106s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x fuse3 s390x 3.17.1+git250416-1 [28.4 kB] 106s Get:9 http://ftpmaster.internal/ubuntu questing/main s390x nano s390x 8.4-1 [299 kB] 106s Get:10 http://ftpmaster.internal/ubuntu questing/main s390x htop s390x 3.4.1-4 [189 kB] 106s Fetched 1544 kB in 1s (1786 kB/s) 106s (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 ... 81472 files and directories currently installed.) 106s Preparing to unpack .../logsave_1.47.2-1ubuntu2_s390x.deb ... 106s Unpacking logsave (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 106s Preparing to unpack .../e2fsprogs-l10n_1.47.2-1ubuntu2_all.deb ... 106s Unpacking e2fsprogs-l10n (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 106s Preparing to unpack .../libext2fs2t64_1.47.2-1ubuntu2_s390x.deb ... 106s Leaving 'diversion of /lib/s390x-linux-gnu/libe2p.so.2 to /lib/s390x-linux-gnu/libe2p.so.2.usr-is-merged by libext2fs2t64' 106s Leaving 'diversion of /lib/s390x-linux-gnu/libe2p.so.2.3 to /lib/s390x-linux-gnu/libe2p.so.2.3.usr-is-merged by libext2fs2t64' 106s Leaving 'diversion of /lib/s390x-linux-gnu/libext2fs.so.2 to /lib/s390x-linux-gnu/libext2fs.so.2.usr-is-merged by libext2fs2t64' 106s Leaving 'diversion of /lib/s390x-linux-gnu/libext2fs.so.2.4 to /lib/s390x-linux-gnu/libext2fs.so.2.4.usr-is-merged by libext2fs2t64' 106s Unpacking libext2fs2t64:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 106s Setting up libext2fs2t64:s390x (1.47.2-1ubuntu2) ... 106s (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 ... 81472 files and directories currently installed.) 106s Preparing to unpack .../0-e2fsprogs_1.47.2-1ubuntu2_s390x.deb ... 106s Unpacking e2fsprogs (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 106s Preparing to unpack .../1-libcom-err2_1.47.2-1ubuntu2_s390x.deb ... 106s Unpacking libcom-err2:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 106s Preparing to unpack .../2-libss2_1.47.2-1ubuntu2_s390x.deb ... 106s Unpacking libss2:s390x (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 106s Selecting previously unselected package libfuse3-4:s390x. 106s Preparing to unpack .../3-libfuse3-4_3.17.1+git250416-1_s390x.deb ... 106s Unpacking libfuse3-4:s390x (3.17.1+git250416-1) ... 106s Preparing to unpack .../4-fuse3_3.17.1+git250416-1_s390x.deb ... 106s Adding 'diversion of /bin/fusermount to /bin/fusermount.usr-is-merged by fuse3' 106s Adding 'diversion of /sbin/mount.fuse to /sbin/mount.fuse.usr-is-merged by fuse3' 106s Unpacking fuse3 (3.17.1+git250416-1) over (3.14.0-10) ... 106s Preparing to unpack .../5-nano_8.4-1_s390x.deb ... 106s Unpacking nano (8.4-1) over (8.3-1) ... 106s Preparing to unpack .../6-htop_3.4.1-4_s390x.deb ... 106s Unpacking htop (3.4.1-4) over (3.4.0-2) ... 106s Setting up htop (3.4.1-4) ... 106s Setting up libcom-err2:s390x (1.47.2-1ubuntu2) ... 106s Setting up libss2:s390x (1.47.2-1ubuntu2) ... 106s Setting up logsave (1.47.2-1ubuntu2) ... 106s Setting up nano (8.4-1) ... 106s Installing new version of config file /etc/nanorc ... 106s Setting up libfuse3-4:s390x (3.17.1+git250416-1) ... 106s Setting up e2fsprogs (1.47.2-1ubuntu2) ... 106s update-initramfs: deferring update (trigger activated) 107s Setting up fuse3 (3.17.1+git250416-1) ... 107s Installing new version of config file /etc/fuse.conf ... 107s Removing 'diversion of /bin/fusermount to /bin/fusermount.usr-is-merged by fuse3' 107s Removing 'diversion of /sbin/mount.fuse to /sbin/mount.fuse.usr-is-merged by fuse3' 107s update-initramfs: deferring update (trigger activated) 107s Setting up e2fsprogs-l10n (1.47.2-1ubuntu2) ... 107s Processing triggers for man-db (2.13.0-1) ... 108s Processing triggers for install-info (7.1.1-1) ... 108s Processing triggers for initramfs-tools (0.147ubuntu1) ... 108s update-initramfs: Generating /boot/initrd.img-6.14.0-15-generic 108s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 110s Using config file '/etc/zipl.conf' 110s Building bootmap in '/boot' 110s Adding IPL section 'ubuntu' (default) 110s Preparing boot device for LD-IPL: vda (0000). 110s Done. 110s Processing triggers for libc-bin (2.41-6ubuntu1) ... 110s Reading package lists... 111s Building dependency tree... 111s Reading state information... 111s Starting pkgProblemResolver with broken count: 0 111s Starting 2 pkgProblemResolver with broken count: 0 111s Done 111s Solving dependencies... 111s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 111s autopkgtest [08:55:11]: rebooting testbed after setup commands that affected boot 134s autopkgtest [08:55:34]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr 6 13:39:00 UTC 2025 136s autopkgtest [08:55:36]: @@@@@@@@@@@@@@@@@@@@ apt-source ext4magic 138s Get:1 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (dsc) [2149 B] 138s Get:2 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (tar) [427 kB] 138s Get:3 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (diff) [175 kB] 138s gpgv: Signature made Mon Apr 1 05:55:08 2024 UTC 138s gpgv: using RSA key A089FB36AAFBDAD5ACC1325069F790171A210984 138s gpgv: Can't check signature: No public key 138s dpkg-source: warning: cannot verify inline signature for ./ext4magic_0.3.2-14build2.dsc: no acceptable signature found 138s autopkgtest [08:55:38]: testing package ext4magic version 0.3.2-14build2 139s autopkgtest [08:55:39]: build not needed 139s autopkgtest [08:55:39]: test command1: preparing testbed 139s Reading package lists... 139s Building dependency tree... 139s Reading state information... 140s Starting pkgProblemResolver with broken count: 0 140s Starting 2 pkgProblemResolver with broken count: 0 140s Done 140s The following NEW packages will be installed: 140s ext4magic 140s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 140s Need to get 120 kB of archives. 140s After this operation, 270 kB of additional disk space will be used. 140s Get:1 http://ftpmaster.internal/ubuntu questing/universe s390x ext4magic s390x 0.3.2-14build2 [120 kB] 140s Fetched 120 kB in 0s (358 kB/s) 140s Selecting previously unselected package ext4magic. 141s (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 ... 81482 files and directories currently installed.) 141s Preparing to unpack .../ext4magic_0.3.2-14build2_s390x.deb ... 141s Unpacking ext4magic (0.3.2-14build2) ... 141s Setting up ext4magic (0.3.2-14build2) ... 141s Processing triggers for man-db (2.13.0-1) ... 142s autopkgtest [08:55:42]: test command1: ext4magic -V 142s autopkgtest [08:55:42]: test command1: [----------------------- 142s ext4magic version : 0.3.2 142s libext2fs version : 1.47.2 142s CPU is big endian. 142s Expert Mode is activ 142s autopkgtest [08:55:42]: test command1: -----------------------] 143s command1 PASS 143s autopkgtest [08:55:43]: test command1: - - - - - - - - - - results - - - - - - - - - - 143s autopkgtest [08:55:43]: test command2: preparing testbed 143s Reading package lists... 143s Building dependency tree... 143s Reading state information... 144s Starting pkgProblemResolver with broken count: 0 144s Starting 2 pkgProblemResolver with broken count: 0 144s Done 144s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 144s autopkgtest [08:55:44]: test command2: ext4magic debian/tests/test.img -M -d $AUTOPKGTEST_TMP 144s autopkgtest [08:55:44]: test command2: [----------------------- 145s Warning: Activate magic-scan or disaster-recovery function, may be some command line options ignored 145s "/tmp/autopkgtest.kumBCV/autopkgtest_tmp" accept for recoverdir 145s Filesystem in use: debian/tests/test.img 145s 145s Using internal Journal at Inode 8 145s Activ Time after : Mon Dec 10 13:06:19 2018 145s Activ Time before : Fri May 2 08:55:45 2025 145s Inode 2 is allocated 145s -------- /tmp/autopkgtest.kumBCV/autopkgtest_tmp/lost+found 145s -------- /tmp/autopkgtest.kumBCV/autopkgtest_tmp/openlogo-50.png 145s -------- /tmp/autopkgtest.kumBCV/autopkgtest_tmp/Debian25years-DanielLenharo.png 145s -------- /tmp/autopkgtest.kumBCV/autopkgtest_tmp/ 145s MAGIC-1 : start lost directory search 145s MAGIC-2 : start lost file search 145s MAGIC-2 : start lost in journal search 145s -------- /tmp/autopkgtest.kumBCV/autopkgtest_tmp/MAGIC-2/application/octet-stream/I_0000000263.unknown 145s -------- /tmp/autopkgtest.kumBCV/autopkgtest_tmp/MAGIC-2/application/octet-stream/I_0000000271.unknown 145s MAGIC-3 : start ext4-magic-scan search 145s ext4magic : EXIT_SUCCESS 145s autopkgtest [08:55:45]: test command2: -----------------------] 145s command2 PASS 145s autopkgtest [08:55:45]: test command2: - - - - - - - - - - results - - - - - - - - - - 146s autopkgtest [08:55:46]: @@@@@@@@@@@@@@@@@@@@ summary 146s command1 PASS 146s command2 PASS 163s nova [W] Using flock in prodstack6-s390x 163s Creating nova instance adt-questing-s390x-ext4magic-20250502-085320-juju-7f2275-prod-proposed-migration-environment-23-0fb6c996-75e9-4c39-a87e-eb0e0471f1ae from image adt/ubuntu-questing-s390x-server-20250502.img (UUID 632ecfca-a19d-4f3a-af48-b7883fa0ccf2)... 163s nova [W] Timed out waiting for 1f5765f2-bfd3-4950-896c-7cb612427f57 to get deleted.