0s autopkgtest [22:16:12]: starting date and time: 2025-05-01 22:16:12+0000 0s autopkgtest [22:16:12]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [22:16:12]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.pu02h5ia/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 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-arm64-8.secgroup --name adt-questing-arm64-ext4magic-20250501-221612-juju-7f2275-prod-proposed-migration-environment-20-9cc44cef-fa95-4f7a-97ad-1c1d33e6c416 --image adt/ubuntu-questing-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 100s autopkgtest [22:17:52]: testbed dpkg architecture: arm64 100s autopkgtest [22:17:52]: testbed apt version: 3.0.0 100s autopkgtest [22:17:52]: @@@@@@@@@@@@@@@@@@@@ test bed setup 101s autopkgtest [22:17:53]: testbed release detected to be: None 102s autopkgtest [22:17:54]: updating testbed package index (apt update) 102s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 102s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 102s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 102s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 103s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [179 kB] 103s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [57.4 kB] 103s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [2255 kB] 103s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main arm64 Packages [196 kB] 103s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe arm64 Packages [567 kB] 103s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse arm64 Packages [3024 B] 104s Fetched 3367 kB in 2s (2036 kB/s) 105s Reading package lists... 106s autopkgtest [22:17:58]: upgrading testbed (apt dist-upgrade and autopurge) 106s Reading package lists... 106s Building dependency tree... 106s Reading state information... 108s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 108s Starting 2 pkgProblemResolver with broken count: 0 108s Done 109s Entering ResolveByKeep 110s 110s Calculating upgrade... 111s The following packages will be upgraded: 111s dpkg dpkg-dev file libdpkg-perl libmagic-mgc libmagic1t64 111s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 111s Need to get 3106 kB of archives. 111s After this operation, 1844 kB of additional disk space will be used. 111s Get:1 http://ftpmaster.internal/ubuntu questing/main arm64 dpkg arm64 1.22.18ubuntu3 [1288 kB] 112s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main arm64 file arm64 1:5.46-5 [22.1 kB] 112s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main arm64 libmagic1t64 arm64 1:5.46-5 [88.7 kB] 112s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/main arm64 libmagic-mgc arm64 1:5.46-5 [336 kB] 112s Get:5 http://ftpmaster.internal/ubuntu questing/main arm64 dpkg-dev all 1.22.18ubuntu3 [1089 kB] 113s Get:6 http://ftpmaster.internal/ubuntu questing/main arm64 libdpkg-perl all 1.22.18ubuntu3 [281 kB] 113s Fetched 3106 kB in 2s (1689 kB/s) 114s (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 ... 117820 files and directories currently installed.) 114s Preparing to unpack .../dpkg_1.22.18ubuntu3_arm64.deb ... 114s Unpacking dpkg (1.22.18ubuntu3) over (1.22.18ubuntu2) ... 114s Setting up dpkg (1.22.18ubuntu3) ... 115s (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 ... 117820 files and directories currently installed.) 115s Preparing to unpack .../file_1%3a5.46-5_arm64.deb ... 115s Unpacking file (1:5.46-5) over (1:5.45-3build1) ... 115s Preparing to unpack .../libmagic1t64_1%3a5.46-5_arm64.deb ... 115s Unpacking libmagic1t64:arm64 (1:5.46-5) over (1:5.45-3build1) ... 115s Preparing to unpack .../libmagic-mgc_1%3a5.46-5_arm64.deb ... 115s Unpacking libmagic-mgc (1:5.46-5) over (1:5.45-3build1) ... 115s Preparing to unpack .../dpkg-dev_1.22.18ubuntu3_all.deb ... 115s Unpacking dpkg-dev (1.22.18ubuntu3) over (1.22.18ubuntu2) ... 115s Preparing to unpack .../libdpkg-perl_1.22.18ubuntu3_all.deb ... 115s Unpacking libdpkg-perl (1.22.18ubuntu3) over (1.22.18ubuntu2) ... 115s Setting up libmagic-mgc (1:5.46-5) ... 115s Setting up libmagic1t64:arm64 (1:5.46-5) ... 115s Setting up file (1:5.46-5) ... 115s Setting up libdpkg-perl (1.22.18ubuntu3) ... 115s Setting up dpkg-dev (1.22.18ubuntu3) ... 115s Processing triggers for man-db (2.13.0-1) ... 118s Processing triggers for libc-bin (2.41-6ubuntu1) ... 118s Reading package lists... 119s Building dependency tree... 119s Reading state information... 120s Starting pkgProblemResolver with broken count: 0 120s Starting 2 pkgProblemResolver with broken count: 0 120s Done 121s Solving dependencies... 123s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 123s autopkgtest [22:18:15]: rebooting testbed after setup commands that affected boot 157s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 164s autopkgtest [22:18:56]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP PREEMPT_DYNAMIC Sun Apr 6 14:37:51 UTC 2025 168s autopkgtest [22:19:00]: @@@@@@@@@@@@@@@@@@@@ apt-source ext4magic 171s Get:1 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (dsc) [2149 B] 171s Get:2 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (tar) [427 kB] 171s Get:3 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (diff) [175 kB] 171s gpgv: Signature made Mon Apr 1 05:55:08 2024 UTC 171s gpgv: using RSA key A089FB36AAFBDAD5ACC1325069F790171A210984 171s gpgv: Can't check signature: No public key 171s dpkg-source: warning: cannot verify inline signature for ./ext4magic_0.3.2-14build2.dsc: no acceptable signature found 171s autopkgtest [22:19:03]: testing package ext4magic version 0.3.2-14build2 172s autopkgtest [22:19:04]: build not needed 172s autopkgtest [22:19:04]: test command1: preparing testbed 173s Reading package lists... 173s Building dependency tree... 173s Reading state information... 173s Starting pkgProblemResolver with broken count: 0 173s Starting 2 pkgProblemResolver with broken count: 0 173s Done 174s The following NEW packages will be installed: 174s ext4magic 175s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 175s Need to get 106 kB of archives. 175s After this operation, 233 kB of additional disk space will be used. 175s Get:1 http://ftpmaster.internal/ubuntu questing/universe arm64 ext4magic arm64 0.3.2-14build2 [106 kB] 175s Fetched 106 kB in 0s (268 kB/s) 175s Selecting previously unselected package ext4magic. 176s (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 ... 117820 files and directories currently installed.) 176s Preparing to unpack .../ext4magic_0.3.2-14build2_arm64.deb ... 176s Unpacking ext4magic (0.3.2-14build2) ... 176s Setting up ext4magic (0.3.2-14build2) ... 176s Processing triggers for man-db (2.13.0-1) ... 178s autopkgtest [22:19:10]: test command1: ext4magic -V 178s autopkgtest [22:19:10]: test command1: [----------------------- 178s ext4magic version : 0.3.2 178s libext2fs version : 1.47.2 178s CPU is little endian. 178s Expert Mode is activ 179s autopkgtest [22:19:11]: test command1: -----------------------] 179s autopkgtest [22:19:11]: test command1: - - - - - - - - - - results - - - - - - - - - - 179s command1 PASS 180s autopkgtest [22:19:12]: test command2: preparing testbed 180s Reading package lists... 180s Building dependency tree... 180s Reading state information... 181s Starting pkgProblemResolver with broken count: 0 181s Starting 2 pkgProblemResolver with broken count: 0 181s Done 182s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 183s autopkgtest [22:19:15]: test command2: ext4magic debian/tests/test.img -M -d $AUTOPKGTEST_TMP 183s autopkgtest [22:19:15]: test command2: [----------------------- 184s Warning: Activate magic-scan or disaster-recovery function, may be some command line options ignored 184s "/tmp/autopkgtest.OHJt2h/autopkgtest_tmp" accept for recoverdir 184s Filesystem in use: debian/tests/test.img 184s 184s Using internal Journal at Inode 8 184s Activ Time after : Mon Dec 10 13:06:19 2018 184s Activ Time before : Thu May 1 22:19:15 2025 184s Inode 2 is allocated 184s -------- /tmp/autopkgtest.OHJt2h/autopkgtest_tmp/lost+found 184s -------- /tmp/autopkgtest.OHJt2h/autopkgtest_tmp/openlogo-50.png 184s -------- /tmp/autopkgtest.OHJt2h/autopkgtest_tmp/Debian25years-DanielLenharo.png 184s -------- /tmp/autopkgtest.OHJt2h/autopkgtest_tmp/ 184s MAGIC-1 : start lost directory search 184s MAGIC-2 : start lost file search 184s MAGIC-2 : start lost in journal search 184s -------- /tmp/autopkgtest.OHJt2h/autopkgtest_tmp/MAGIC-2/application/octet-stream/I_0000000263.unknown 184s -------- /tmp/autopkgtest.OHJt2h/autopkgtest_tmp/MAGIC-2/application/octet-stream/I_0000000271.unknown 184s MAGIC-3 : start ext4-magic-scan search 184s ext4magic : EXIT_SUCCESS 184s autopkgtest [22:19:16]: test command2: -----------------------] 185s command2 PASS 185s autopkgtest [22:19:17]: test command2: - - - - - - - - - - results - - - - - - - - - - 185s autopkgtest [22:19:17]: @@@@@@@@@@@@@@@@@@@@ summary 185s command1 PASS 185s command2 PASS 203s nova [W] Using flock in prodstack6-arm64 203s Creating nova instance adt-questing-arm64-ext4magic-20250501-221612-juju-7f2275-prod-proposed-migration-environment-20-9cc44cef-fa95-4f7a-97ad-1c1d33e6c416 from image adt/ubuntu-questing-arm64-server-20250501.img (UUID a16a0402-3b0c-4b8f-ba85-1dd4107760ca)... 203s nova [W] Timed out waiting for 0799317e-64be-4702-bd91-0e93bc4abf10 to get deleted.