0s autopkgtest [17:12:14]: starting date and time: 2025-05-03 17:12:14+0000 0s autopkgtest [17:12:14]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [17:12:14]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.9rbegbxj/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 builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-6.secgroup --name adt-questing-amd64-ext4magic-20250503-171214-juju-7f2275-prod-proposed-migration-environment-2-26cf2d2e-7709-43b6-bd41-674bfe0771d1 --image adt/ubuntu-questing-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 48s autopkgtest [17:13:02]: testbed dpkg architecture: amd64 49s autopkgtest [17:13:03]: testbed apt version: 3.0.0 49s autopkgtest [17:13:03]: @@@@@@@@@@@@@@@@@@@@ test bed setup 49s autopkgtest [17:13:03]: testbed release detected to be: None 50s autopkgtest [17:13:04]: updating testbed package index (apt update) 50s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 50s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 51s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 51s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 51s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [33.0 kB] 51s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [150 kB] 51s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [1470 kB] 51s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main i386 Packages [140 kB] 51s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 Packages [197 kB] 51s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 Packages [1431 kB] 51s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe i386 Packages [578 kB] 51s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse i386 Packages [23.1 kB] 51s Get:13 http://ftpmaster.internal/ubuntu questing-proposed/multiverse amd64 Packages [48.6 kB] 51s Fetched 4181 kB in 1s (3914 kB/s) 52s Reading package lists... 52s autopkgtest [17:13:06]: upgrading testbed (apt dist-upgrade and autopurge) 53s Reading package lists... 53s Building dependency tree... 53s Reading state information... 53s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 53s Starting 2 pkgProblemResolver with broken count: 0 53s Done 54s Entering ResolveByKeep 54s 54s Calculating upgrade... 54s The following NEW packages will be installed: 54s libfuse3-4 54s The following packages will be upgraded: 54s cloud-init cloud-init-base debianutils e2fsprogs e2fsprogs-l10n fuse3 54s libcom-err2 libext2fs2t64 libmbim-glib4 libmbim-proxy libnftnl11 54s libqmi-glib5 libqmi-proxy libss2 logsave python3-lazr.restfulclient sos 54s 17 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 54s Need to get 3403 kB of archives. 54s After this operation, 106 kB of additional disk space will be used. 54s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 debianutils amd64 5.22 [93.0 kB] 55s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 logsave amd64 1.47.2-1ubuntu2 [26.7 kB] 55s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 e2fsprogs-l10n all 1.47.2-1ubuntu2 [7036 B] 55s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 libext2fs2t64 amd64 1.47.2-1ubuntu2 [227 kB] 55s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 e2fsprogs amd64 1.47.2-1ubuntu2 [615 kB] 55s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 libcom-err2 amd64 1.47.2-1ubuntu2 [26.5 kB] 55s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 libss2 amd64 1.47.2-1ubuntu2 [17.9 kB] 55s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 libfuse3-4 amd64 3.17.1+git250416-1 [90.9 kB] 55s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 fuse3 amd64 3.17.1+git250416-1 [27.1 kB] 55s Get:10 http://ftpmaster.internal/ubuntu questing/main amd64 libnftnl11 amd64 1.2.9-1 [69.1 kB] 55s Get:11 http://ftpmaster.internal/ubuntu questing/main amd64 cloud-init-base all 25.2~1g7a0265d3-0ubuntu1 [619 kB] 55s Get:12 http://ftpmaster.internal/ubuntu questing/main amd64 libmbim-proxy amd64 1.32.0-1 [6276 B] 55s Get:13 http://ftpmaster.internal/ubuntu questing/main amd64 libmbim-glib4 amd64 1.32.0-1 [233 kB] 55s Get:14 http://ftpmaster.internal/ubuntu questing/main amd64 libqmi-proxy amd64 1.36.0-1 [6220 B] 55s Get:15 http://ftpmaster.internal/ubuntu questing/main amd64 libqmi-glib5 amd64 1.36.0-1 [920 kB] 56s Get:16 http://ftpmaster.internal/ubuntu questing/main amd64 python3-lazr.restfulclient all 0.14.6-3 [51.0 kB] 56s Get:17 http://ftpmaster.internal/ubuntu questing/main amd64 sos all 4.9.1-1 [367 kB] 56s Get:18 http://ftpmaster.internal/ubuntu questing/main amd64 cloud-init all 25.2~1g7a0265d3-0ubuntu1 [2106 B] 56s Preconfiguring packages ... 56s Fetched 3403 kB in 1s (2283 kB/s) 56s (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 ... 80514 files and directories currently installed.) 56s Preparing to unpack .../debianutils_5.22_amd64.deb ... 56s Unpacking debianutils (5.22) over (5.21) ... 56s Setting up debianutils (5.22) ... 57s (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 ... 80514 files and directories currently installed.) 57s Preparing to unpack .../logsave_1.47.2-1ubuntu2_amd64.deb ... 57s Unpacking logsave (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 57s Preparing to unpack .../e2fsprogs-l10n_1.47.2-1ubuntu2_all.deb ... 57s Unpacking e2fsprogs-l10n (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 57s Preparing to unpack .../libext2fs2t64_1.47.2-1ubuntu2_amd64.deb ... 57s Leaving 'diversion of /lib/x86_64-linux-gnu/libe2p.so.2 to /lib/x86_64-linux-gnu/libe2p.so.2.usr-is-merged by libext2fs2t64' 57s Leaving 'diversion of /lib/x86_64-linux-gnu/libe2p.so.2.3 to /lib/x86_64-linux-gnu/libe2p.so.2.3.usr-is-merged by libext2fs2t64' 57s Leaving 'diversion of /lib/x86_64-linux-gnu/libext2fs.so.2 to /lib/x86_64-linux-gnu/libext2fs.so.2.usr-is-merged by libext2fs2t64' 57s Leaving 'diversion of /lib/x86_64-linux-gnu/libext2fs.so.2.4 to /lib/x86_64-linux-gnu/libext2fs.so.2.4.usr-is-merged by libext2fs2t64' 57s Unpacking libext2fs2t64:amd64 (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 57s Setting up libext2fs2t64:amd64 (1.47.2-1ubuntu2) ... 57s (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 ... 80514 files and directories currently installed.) 57s Preparing to unpack .../00-e2fsprogs_1.47.2-1ubuntu2_amd64.deb ... 57s Unpacking e2fsprogs (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 57s Preparing to unpack .../01-libcom-err2_1.47.2-1ubuntu2_amd64.deb ... 57s Unpacking libcom-err2:amd64 (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 57s Preparing to unpack .../02-libss2_1.47.2-1ubuntu2_amd64.deb ... 57s Unpacking libss2:amd64 (1.47.2-1ubuntu2) over (1.47.2-1ubuntu1) ... 57s Selecting previously unselected package libfuse3-4:amd64. 57s Preparing to unpack .../03-libfuse3-4_3.17.1+git250416-1_amd64.deb ... 57s Unpacking libfuse3-4:amd64 (3.17.1+git250416-1) ... 57s Preparing to unpack .../04-fuse3_3.17.1+git250416-1_amd64.deb ... 57s Adding 'diversion of /bin/fusermount to /bin/fusermount.usr-is-merged by fuse3' 57s Adding 'diversion of /sbin/mount.fuse to /sbin/mount.fuse.usr-is-merged by fuse3' 57s Unpacking fuse3 (3.17.1+git250416-1) over (3.14.0-10) ... 57s Preparing to unpack .../05-libnftnl11_1.2.9-1_amd64.deb ... 57s Unpacking libnftnl11:amd64 (1.2.9-1) over (1.2.8-1) ... 57s Preparing to unpack .../06-cloud-init-base_25.2~1g7a0265d3-0ubuntu1_all.deb ... 57s Unpacking cloud-init-base (25.2~1g7a0265d3-0ubuntu1) over (25.1.1-0ubuntu2) ... 57s Preparing to unpack .../07-libmbim-proxy_1.32.0-1_amd64.deb ... 57s Unpacking libmbim-proxy (1.32.0-1) over (1.31.2-0ubuntu4) ... 57s Preparing to unpack .../08-libmbim-glib4_1.32.0-1_amd64.deb ... 57s Unpacking libmbim-glib4:amd64 (1.32.0-1) over (1.31.2-0ubuntu4) ... 57s Preparing to unpack .../09-libqmi-proxy_1.36.0-1_amd64.deb ... 57s Unpacking libqmi-proxy (1.36.0-1) over (1.35.6-1) ... 57s Preparing to unpack .../10-libqmi-glib5_1.36.0-1_amd64.deb ... 57s Unpacking libqmi-glib5:amd64 (1.36.0-1) over (1.35.6-1) ... 58s Preparing to unpack .../11-python3-lazr.restfulclient_0.14.6-3_all.deb ... 58s Unpacking python3-lazr.restfulclient (0.14.6-3) over (0.14.6-2) ... 58s Preparing to unpack .../12-sos_4.9.1-1_all.deb ... 58s Unpacking sos (4.9.1-1) over (4.9.0-6) ... 58s Preparing to unpack .../13-cloud-init_25.2~1g7a0265d3-0ubuntu1_all.deb ... 58s Unpacking cloud-init (25.2~1g7a0265d3-0ubuntu1) over (25.1.1-0ubuntu2) ... 58s Setting up sos (4.9.1-1) ... 59s Setting up libnftnl11:amd64 (1.2.9-1) ... 59s Setting up libcom-err2:amd64 (1.47.2-1ubuntu2) ... 59s Setting up cloud-init-base (25.2~1g7a0265d3-0ubuntu1) ... 60s Setting up libss2:amd64 (1.47.2-1ubuntu2) ... 60s Setting up logsave (1.47.2-1ubuntu2) ... 60s Setting up libfuse3-4:amd64 (3.17.1+git250416-1) ... 60s Setting up python3-lazr.restfulclient (0.14.6-3) ... 60s Setting up libmbim-glib4:amd64 (1.32.0-1) ... 60s Setting up cloud-init (25.2~1g7a0265d3-0ubuntu1) ... 60s Setting up e2fsprogs (1.47.2-1ubuntu2) ... 60s update-initramfs: deferring update (trigger activated) 61s Setting up libmbim-proxy (1.32.0-1) ... 61s Setting up fuse3 (3.17.1+git250416-1) ... 61s Installing new version of config file /etc/fuse.conf ... 61s Removing 'diversion of /bin/fusermount to /bin/fusermount.usr-is-merged by fuse3' 61s Removing 'diversion of /sbin/mount.fuse to /sbin/mount.fuse.usr-is-merged by fuse3' 61s update-initramfs: deferring update (trigger activated) 61s Setting up e2fsprogs-l10n (1.47.2-1ubuntu2) ... 61s Setting up libqmi-glib5:amd64 (1.36.0-1) ... 61s Setting up libqmi-proxy (1.36.0-1) ... 61s Processing triggers for initramfs-tools (0.147ubuntu1) ... 61s update-initramfs: Generating /boot/initrd.img-6.14.0-15-generic 61s W: No lz4 in /usr/bin:/sbin:/bin, using gzip 72s Processing triggers for libc-bin (2.41-6ubuntu1) ... 72s Processing triggers for rsyslog (8.2412.0-2ubuntu2) ... 73s Processing triggers for man-db (2.13.0-1) ... 75s Reading package lists... 75s Building dependency tree... 75s Reading state information... 75s Starting pkgProblemResolver with broken count: 0 75s Starting 2 pkgProblemResolver with broken count: 0 75s Done 75s Solving dependencies... 76s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 76s autopkgtest [17:13:30]: rebooting testbed after setup commands that affected boot 99s autopkgtest [17:13:53]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP PREEMPT_DYNAMIC Sun Apr 6 15:05:05 UTC 2025 101s autopkgtest [17:13:55]: @@@@@@@@@@@@@@@@@@@@ apt-source ext4magic 103s Get:1 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (dsc) [2149 B] 103s Get:2 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (tar) [427 kB] 103s Get:3 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (diff) [175 kB] 104s gpgv: Signature made Mon Apr 1 05:55:08 2024 UTC 104s gpgv: using RSA key A089FB36AAFBDAD5ACC1325069F790171A210984 104s gpgv: Can't check signature: No public key 104s dpkg-source: warning: cannot verify inline signature for ./ext4magic_0.3.2-14build2.dsc: no acceptable signature found 104s autopkgtest [17:13:58]: testing package ext4magic version 0.3.2-14build2 104s autopkgtest [17:13:58]: build not needed 105s autopkgtest [17:13:59]: test command1: preparing testbed 105s Reading package lists... 105s Building dependency tree... 105s Reading state information... 106s Starting pkgProblemResolver with broken count: 0 106s Starting 2 pkgProblemResolver with broken count: 0 106s Done 106s The following NEW packages will be installed: 106s ext4magic 106s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 106s Need to get 105 kB of archives. 106s After this operation, 222 kB of additional disk space will be used. 106s Get:1 http://ftpmaster.internal/ubuntu questing/universe amd64 ext4magic amd64 0.3.2-14build2 [105 kB] 107s Fetched 105 kB in 0s (312 kB/s) 107s Selecting previously unselected package ext4magic. 107s (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 ... 80528 files and directories currently installed.) 107s Preparing to unpack .../ext4magic_0.3.2-14build2_amd64.deb ... 107s Unpacking ext4magic (0.3.2-14build2) ... 107s Setting up ext4magic (0.3.2-14build2) ... 107s Processing triggers for man-db (2.13.0-1) ... 109s autopkgtest [17:14:03]: test command1: ext4magic -V 109s autopkgtest [17:14:03]: test command1: [----------------------- 109s ext4magic version : 0.3.2 109s libext2fs version : 1.47.2 109s CPU is little endian. 109s Expert Mode is activ 109s autopkgtest [17:14:03]: test command1: -----------------------] 110s autopkgtest [17:14:04]: test command1: - - - - - - - - - - results - - - - - - - - - - 110s command1 PASS 110s autopkgtest [17:14:04]: test command2: preparing testbed 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 112s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 112s autopkgtest [17:14:06]: test command2: ext4magic debian/tests/test.img -M -d $AUTOPKGTEST_TMP 112s autopkgtest [17:14:06]: test command2: [----------------------- 114s Warning: Activate magic-scan or disaster-recovery function, may be some command line options ignored 114s "/tmp/autopkgtest.sS1KQN/autopkgtest_tmp" accept for recoverdir 114s Filesystem in use: debian/tests/test.img 114s 114s Using internal Journal at Inode 8 114s Activ Time after : Mon Dec 10 13:06:19 2018 114s Activ Time before : Sat May 3 17:14:07 2025 114s Inode 2 is allocated 114s -------- /tmp/autopkgtest.sS1KQN/autopkgtest_tmp/lost+found 114s -------- /tmp/autopkgtest.sS1KQN/autopkgtest_tmp/openlogo-50.png 114s -------- /tmp/autopkgtest.sS1KQN/autopkgtest_tmp/Debian25years-DanielLenharo.png 114s -------- /tmp/autopkgtest.sS1KQN/autopkgtest_tmp/ 114s MAGIC-1 : start lost directory search 114s MAGIC-2 : start lost file search 114s MAGIC-2 : start lost in journal search 114s -------- /tmp/autopkgtest.sS1KQN/autopkgtest_tmp/MAGIC-2/application/octet-stream/I_0000000263.unknown 114s -------- /tmp/autopkgtest.sS1KQN/autopkgtest_tmp/MAGIC-2/application/octet-stream/I_0000000271.unknown 114s MAGIC-3 : start ext4-magic-scan search 114s ext4magic : EXIT_SUCCESS 114s autopkgtest [17:14:08]: test command2: -----------------------] 114s command2 PASS 114s autopkgtest [17:14:08]: test command2: - - - - - - - - - - results - - - - - - - - - - 115s autopkgtest [17:14:09]: @@@@@@@@@@@@@@@@@@@@ summary 115s command1 PASS 115s command2 PASS 119s nova [W] Skipping flock for amd64 119s Creating nova instance adt-questing-amd64-ext4magic-20250503-171214-juju-7f2275-prod-proposed-migration-environment-2-26cf2d2e-7709-43b6-bd41-674bfe0771d1 from image adt/ubuntu-questing-amd64-server-20250503.img (UUID 57b7c1b6-ad80-4bdb-b031-36f045f7f3b3)... 119s nova [W] Timed out waiting for 96a53d53-df55-4514-9bca-15c06fac451a to get deleted.