0s autopkgtest [07:52:13]: starting date and time: 2025-05-03 07:52:13+0000 0s autopkgtest [07:52:13]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [07:52:13]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.fjjvlyba/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:file --apt-upgrade ext4magic --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=file/1:5.46-5 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-amd64 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-9.secgroup --name adt-questing-amd64-ext4magic-20250503-075213-juju-7f2275-prod-proposed-migration-environment-15-401d14a9-c199-4c9f-9321-41e66ef18380 --image adt/ubuntu-questing-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 40s autopkgtest [07:52:53]: testbed dpkg architecture: amd64 40s autopkgtest [07:52:53]: testbed apt version: 3.0.0 40s autopkgtest [07:52:53]: @@@@@@@@@@@@@@@@@@@@ test bed setup 40s autopkgtest [07:52:53]: testbed release detected to be: None 41s autopkgtest [07:52:54]: updating testbed package index (apt update) 41s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 41s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 41s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 41s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 41s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [1722 kB] 41s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [156 kB] 41s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [43.3 kB] 41s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main i386 Packages [149 kB] 41s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 Packages [200 kB] 41s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/universe amd64 Packages [1408 kB] 41s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe i386 Packages [587 kB] 41s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/multiverse amd64 Packages [14.5 kB] 42s Get:13 http://ftpmaster.internal/ubuntu questing-proposed/multiverse i386 Packages [13.2 kB] 42s Fetched 4405 kB in 1s (6253 kB/s) 42s Reading package lists... 43s autopkgtest [07:52:56]: upgrading testbed (apt dist-upgrade and autopurge) 43s Reading package lists... 43s Building dependency tree... 43s Reading state information... 43s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 43s Starting 2 pkgProblemResolver with broken count: 0 43s Done 43s Entering ResolveByKeep 43s 44s Calculating upgrade... 44s The following packages will be upgraded: 44s debianutils ed file libmagic-mgc libmagic1t64 libnftnl11 libnvme1t64 44s libperl5.40 perl perl-base perl-modules-5.40 44s 11 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 44s Need to get 10.9 MB of archives. 44s After this operation, 1823 kB of additional disk space will be used. 44s Get:1 http://ftpmaster.internal/ubuntu questing/main amd64 debianutils amd64 5.22 [93.0 kB] 44s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 libperl5.40 amd64 5.40.1-3 [4877 kB] 45s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 perl amd64 5.40.1-3 [262 kB] 45s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 perl-base amd64 5.40.1-3 [1840 kB] 45s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 perl-modules-5.40 all 5.40.1-3 [3217 kB] 45s Get:6 http://ftpmaster.internal/ubuntu questing/main amd64 ed amd64 1.21.1-1 [57.2 kB] 45s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 file amd64 1:5.46-5 [22.1 kB] 45s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 libmagic1t64 amd64 1:5.46-5 [90.8 kB] 45s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main amd64 libmagic-mgc amd64 1:5.46-5 [336 kB] 45s Get:10 http://ftpmaster.internal/ubuntu questing/main amd64 libnftnl11 amd64 1.2.9-1 [69.1 kB] 45s Get:11 http://ftpmaster.internal/ubuntu questing/main amd64 libnvme1t64 amd64 1.13-2 [84.2 kB] 45s Fetched 10.9 MB in 1s (7348 kB/s) 46s (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.) 46s Preparing to unpack .../debianutils_5.22_amd64.deb ... 46s Unpacking debianutils (5.22) over (5.21) ... 46s Setting up debianutils (5.22) ... 46s (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.) 46s Preparing to unpack .../libperl5.40_5.40.1-3_amd64.deb ... 46s Unpacking libperl5.40:amd64 (5.40.1-3) over (5.40.1-2ubuntu0.1) ... 46s Preparing to unpack .../perl_5.40.1-3_amd64.deb ... 46s Unpacking perl (5.40.1-3) over (5.40.1-2ubuntu0.1) ... 46s Preparing to unpack .../perl-base_5.40.1-3_amd64.deb ... 46s Unpacking perl-base (5.40.1-3) over (5.40.1-2ubuntu0.1) ... 46s Setting up perl-base (5.40.1-3) ... 46s (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.) 46s Preparing to unpack .../0-perl-modules-5.40_5.40.1-3_all.deb ... 46s Unpacking perl-modules-5.40 (5.40.1-3) over (5.40.1-2ubuntu0.1) ... 46s Preparing to unpack .../1-ed_1.21.1-1_amd64.deb ... 46s Unpacking ed (1.21.1-1) over (1.21-1) ... 46s Preparing to unpack .../2-file_1%3a5.46-5_amd64.deb ... 46s Unpacking file (1:5.46-5) over (1:5.45-3build1) ... 46s Preparing to unpack .../3-libmagic1t64_1%3a5.46-5_amd64.deb ... 46s Unpacking libmagic1t64:amd64 (1:5.46-5) over (1:5.45-3build1) ... 46s Preparing to unpack .../4-libmagic-mgc_1%3a5.46-5_amd64.deb ... 46s Unpacking libmagic-mgc (1:5.46-5) over (1:5.45-3build1) ... 46s Preparing to unpack .../5-libnftnl11_1.2.9-1_amd64.deb ... 46s Unpacking libnftnl11:amd64 (1.2.9-1) over (1.2.8-1) ... 46s Preparing to unpack .../6-libnvme1t64_1.13-2_amd64.deb ... 46s Unpacking libnvme1t64 (1.13-2) over (1.11.1-2) ... 46s Setting up libmagic-mgc (1:5.46-5) ... 46s Setting up libmagic1t64:amd64 (1:5.46-5) ... 46s Setting up libnftnl11:amd64 (1.2.9-1) ... 46s Setting up file (1:5.46-5) ... 46s Setting up libnvme1t64 (1.13-2) ... 46s Setting up ed (1.21.1-1) ... 46s Setting up perl-modules-5.40 (5.40.1-3) ... 46s Setting up libperl5.40:amd64 (5.40.1-3) ... 46s Setting up perl (5.40.1-3) ... 46s Processing triggers for libc-bin (2.41-6ubuntu1) ... 47s Processing triggers for man-db (2.13.0-1) ... 47s Processing triggers for install-info (7.1.1-1) ... 48s Reading package lists... 48s Building dependency tree... 48s Reading state information... 48s Starting pkgProblemResolver with broken count: 0 48s Starting 2 pkgProblemResolver with broken count: 0 48s Done 48s Solving dependencies... 48s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 50s autopkgtest [07:53:03]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP PREEMPT_DYNAMIC Sun Apr 6 15:05:05 UTC 2025 50s autopkgtest [07:53:03]: @@@@@@@@@@@@@@@@@@@@ apt-source ext4magic 52s Get:1 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (dsc) [2149 B] 52s Get:2 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (tar) [427 kB] 52s Get:3 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (diff) [175 kB] 52s gpgv: Signature made Mon Apr 1 05:55:08 2024 UTC 52s gpgv: using RSA key A089FB36AAFBDAD5ACC1325069F790171A210984 52s gpgv: Can't check signature: No public key 52s dpkg-source: warning: cannot verify inline signature for ./ext4magic_0.3.2-14build2.dsc: no acceptable signature found 52s autopkgtest [07:53:05]: testing package ext4magic version 0.3.2-14build2 52s autopkgtest [07:53:05]: build not needed 53s autopkgtest [07:53:06]: test command1: preparing testbed 53s Reading package lists... 53s Building dependency tree... 53s Reading state information... 53s Starting pkgProblemResolver with broken count: 0 53s Starting 2 pkgProblemResolver with broken count: 0 53s Done 53s The following NEW packages will be installed: 53s ext4magic 53s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 53s Need to get 105 kB of archives. 53s After this operation, 222 kB of additional disk space will be used. 53s Get:1 http://ftpmaster.internal/ubuntu questing/universe amd64 ext4magic amd64 0.3.2-14build2 [105 kB] 53s Fetched 105 kB in 0s (9953 kB/s) 54s Selecting previously unselected package ext4magic. 54s (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.) 54s Preparing to unpack .../ext4magic_0.3.2-14build2_amd64.deb ... 54s Unpacking ext4magic (0.3.2-14build2) ... 54s Setting up ext4magic (0.3.2-14build2) ... 54s Processing triggers for man-db (2.13.0-1) ... 54s autopkgtest [07:53:07]: test command1: ext4magic -V 54s autopkgtest [07:53:07]: test command1: [----------------------- 55s ext4magic version : 0.3.2 55s libext2fs version : 1.47.2 55s CPU is little endian. 55s Expert Mode is activ 55s autopkgtest [07:53:08]: test command1: -----------------------] 55s command1 PASS 55s autopkgtest [07:53:08]: test command1: - - - - - - - - - - results - - - - - - - - - - 55s autopkgtest [07:53:08]: test command2: preparing testbed 56s Reading package lists... 56s Building dependency tree... 56s Reading state information... 56s Starting pkgProblemResolver with broken count: 0 56s Starting 2 pkgProblemResolver with broken count: 0 56s Done 56s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 57s autopkgtest [07:53:10]: test command2: ext4magic debian/tests/test.img -M -d $AUTOPKGTEST_TMP 57s autopkgtest [07:53:10]: test command2: [----------------------- 57s Warning: Activate magic-scan or disaster-recovery function, may be some command line options ignored 57s "/tmp/autopkgtest.6UUnvK/autopkgtest_tmp" accept for recoverdir 57s Filesystem in use: debian/tests/test.img 57s 57s Using internal Journal at Inode 8 57s Activ Time after : Mon Dec 10 13:06:19 2018 57s Activ Time before : Sat May 3 07:53:10 2025 57s Inode 2 is allocated 57s -------- /tmp/autopkgtest.6UUnvK/autopkgtest_tmp/lost+found 57s -------- /tmp/autopkgtest.6UUnvK/autopkgtest_tmp/openlogo-50.png 57s -------- /tmp/autopkgtest.6UUnvK/autopkgtest_tmp/Debian25years-DanielLenharo.png 57s -------- /tmp/autopkgtest.6UUnvK/autopkgtest_tmp/ 57s MAGIC-1 : start lost directory search 57s MAGIC-2 : start lost file search 57s MAGIC-2 : start lost in journal search 57s -------- /tmp/autopkgtest.6UUnvK/autopkgtest_tmp/MAGIC-2/application/octet-stream/I_0000000263.unknown 57s -------- /tmp/autopkgtest.6UUnvK/autopkgtest_tmp/MAGIC-2/application/octet-stream/I_0000000271.unknown 57s MAGIC-3 : start ext4-magic-scan search 57s ext4magic : EXIT_SUCCESS 58s autopkgtest [07:53:11]: test command2: -----------------------] 58s autopkgtest [07:53:11]: test command2: - - - - - - - - - - results - - - - - - - - - - 58s command2 PASS 58s autopkgtest [07:53:11]: @@@@@@@@@@@@@@@@@@@@ summary 58s command1 PASS 58s command2 PASS 85s nova [W] Skipping flock for amd64 85s Creating nova instance adt-questing-amd64-ext4magic-20250503-075213-juju-7f2275-prod-proposed-migration-environment-15-401d14a9-c199-4c9f-9321-41e66ef18380 from image adt/ubuntu-questing-amd64-server-20250502.img (UUID 8201ee40-745e-4033-979b-719f9e8a69e6)... 85s nova [W] Timed out waiting for 5e072b0b-31c9-4c37-a9e7-e167f60f0716 to get deleted.