0s autopkgtest [18:52:54]: starting date and time: 2025-05-01 18:52:54+0000 1s autopkgtest [18:52:55]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 1s autopkgtest [18:52:55]: host juju-7f2275-prod-proposed-migration-environment-23; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.2w4r1gsp/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-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-23@bos03-s390x-11.secgroup --name adt-questing-s390x-ext4magic-20250501-185254-juju-7f2275-prod-proposed-migration-environment-23-7ffa33ac-fecc-40ff-9c74-6adea30cf1a6 --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/ 79s autopkgtest [18:54:13]: testbed dpkg architecture: s390x 79s autopkgtest [18:54:13]: testbed apt version: 3.0.0 80s autopkgtest [18:54:14]: @@@@@@@@@@@@@@@@@@@@ test bed setup 80s autopkgtest [18:54:14]: testbed release detected to be: None 80s autopkgtest [18:54:14]: 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 [27.6 kB] 81s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [126 kB] 81s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [1149 kB] 83s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [49.5 kB] 83s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [243 kB] 83s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [1804 B] 83s Fetched 1707 kB in 2s (765 kB/s) 84s Reading package lists... 84s autopkgtest [18:54:18]: upgrading testbed (apt dist-upgrade and autopurge) 84s Reading package lists... 85s Building dependency tree... 85s Reading state information... 85s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 85s Starting 2 pkgProblemResolver with broken count: 0 85s Done 85s Entering ResolveByKeep 85s 85s Calculating upgrade... 85s The following packages will be upgraded: 85s dpkg dpkg-dev file libdpkg-perl libmagic-mgc libmagic1t64 85s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 85s Need to get 3121 kB of archives. 85s After this operation, 1844 kB of additional disk space will be used. 85s Get:1 http://ftpmaster.internal/ubuntu questing/main s390x dpkg s390x 1.22.18ubuntu3 [1297 kB] 87s Get:2 http://ftpmaster.internal/ubuntu questing-proposed/main s390x file s390x 1:5.46-5 [22.3 kB] 87s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libmagic1t64 s390x 1:5.46-5 [94.4 kB] 87s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/main s390x libmagic-mgc s390x 1:5.46-5 [337 kB] 87s Get:5 http://ftpmaster.internal/ubuntu questing/main s390x dpkg-dev all 1.22.18ubuntu3 [1089 kB] 88s Get:6 http://ftpmaster.internal/ubuntu questing/main s390x libdpkg-perl all 1.22.18ubuntu3 [281 kB] 88s Fetched 3121 kB in 3s (1106 kB/s) 89s (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.) 89s Preparing to unpack .../dpkg_1.22.18ubuntu3_s390x.deb ... 89s Unpacking dpkg (1.22.18ubuntu3) over (1.22.18ubuntu2) ... 89s Setting up dpkg (1.22.18ubuntu3) ... 89s (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.) 89s Preparing to unpack .../file_1%3a5.46-5_s390x.deb ... 89s Unpacking file (1:5.46-5) over (1:5.45-3build1) ... 89s Preparing to unpack .../libmagic1t64_1%3a5.46-5_s390x.deb ... 89s Unpacking libmagic1t64:s390x (1:5.46-5) over (1:5.45-3build1) ... 89s Preparing to unpack .../libmagic-mgc_1%3a5.46-5_s390x.deb ... 89s Unpacking libmagic-mgc (1:5.46-5) over (1:5.45-3build1) ... 89s Preparing to unpack .../dpkg-dev_1.22.18ubuntu3_all.deb ... 89s Unpacking dpkg-dev (1.22.18ubuntu3) over (1.22.18ubuntu2) ... 89s Preparing to unpack .../libdpkg-perl_1.22.18ubuntu3_all.deb ... 89s Unpacking libdpkg-perl (1.22.18ubuntu3) over (1.22.18ubuntu2) ... 89s Setting up libmagic-mgc (1:5.46-5) ... 89s Setting up libmagic1t64:s390x (1:5.46-5) ... 89s Setting up file (1:5.46-5) ... 89s Setting up libdpkg-perl (1.22.18ubuntu3) ... 89s Setting up dpkg-dev (1.22.18ubuntu3) ... 89s Processing triggers for man-db (2.13.0-1) ... 91s Processing triggers for libc-bin (2.41-6ubuntu1) ... 91s Reading package lists... 91s Building dependency tree... 91s Reading state information... 91s Starting pkgProblemResolver with broken count: 0 91s Starting 2 pkgProblemResolver with broken count: 0 91s Done 92s Solving dependencies... 92s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 92s autopkgtest [18:54:26]: rebooting testbed after setup commands that affected boot 115s autopkgtest [18:54:49]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr 6 13:39:00 UTC 2025 118s autopkgtest [18:54:52]: @@@@@@@@@@@@@@@@@@@@ apt-source ext4magic 120s Get:1 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (dsc) [2149 B] 120s Get:2 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (tar) [427 kB] 120s Get:3 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (diff) [175 kB] 120s gpgv: Signature made Mon Apr 1 05:55:08 2024 UTC 120s gpgv: using RSA key A089FB36AAFBDAD5ACC1325069F790171A210984 120s gpgv: Can't check signature: No public key 120s dpkg-source: warning: cannot verify inline signature for ./ext4magic_0.3.2-14build2.dsc: no acceptable signature found 120s autopkgtest [18:54:54]: testing package ext4magic version 0.3.2-14build2 121s autopkgtest [18:54:55]: build not needed 121s autopkgtest [18:54:55]: test command1: preparing testbed 121s Reading package lists... 122s Building dependency tree... 122s Reading state information... 122s Starting pkgProblemResolver with broken count: 0 122s Starting 2 pkgProblemResolver with broken count: 0 122s Done 122s The following NEW packages will be installed: 122s ext4magic 122s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 122s Need to get 120 kB of archives. 122s After this operation, 270 kB of additional disk space will be used. 122s Get:1 http://ftpmaster.internal/ubuntu questing/universe s390x ext4magic s390x 0.3.2-14build2 [120 kB] 123s Fetched 120 kB in 0s (324 kB/s) 123s Selecting previously unselected package ext4magic. 123s (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.) 123s Preparing to unpack .../ext4magic_0.3.2-14build2_s390x.deb ... 123s Unpacking ext4magic (0.3.2-14build2) ... 123s Setting up ext4magic (0.3.2-14build2) ... 123s Processing triggers for man-db (2.13.0-1) ... 124s autopkgtest [18:54:58]: test command1: ext4magic -V 124s autopkgtest [18:54:58]: test command1: [----------------------- 125s ext4magic version : 0.3.2 125s libext2fs version : 1.47.2 125s CPU is big endian. 125s Expert Mode is activ 125s autopkgtest [18:54:59]: test command1: -----------------------] 125s autopkgtest [18:54:59]: test command1: - - - - - - - - - - results - - - - - - - - - - 125s command1 PASS 126s autopkgtest [18:55:00]: test command2: preparing testbed 126s Reading package lists... 126s Building dependency tree... 126s Reading state information... 126s Starting pkgProblemResolver with broken count: 0 126s Starting 2 pkgProblemResolver with broken count: 0 126s Done 126s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 127s autopkgtest [18:55:01]: test command2: ext4magic debian/tests/test.img -M -d $AUTOPKGTEST_TMP 127s autopkgtest [18:55:01]: test command2: [----------------------- 127s Warning: Activate magic-scan or disaster-recovery function, may be some command line options ignored 127s "/tmp/autopkgtest.SdopC0/autopkgtest_tmp" accept for recoverdir 127s Filesystem in use: debian/tests/test.img 127s 127s Using internal Journal at Inode 8 127s Activ Time after : Mon Dec 10 13:06:19 2018 127s Activ Time before : Thu May 1 18:55:01 2025 127s Inode 2 is allocated 127s -------- /tmp/autopkgtest.SdopC0/autopkgtest_tmp/lost+found 127s -------- /tmp/autopkgtest.SdopC0/autopkgtest_tmp/openlogo-50.png 127s -------- /tmp/autopkgtest.SdopC0/autopkgtest_tmp/Debian25years-DanielLenharo.png 127s -------- /tmp/autopkgtest.SdopC0/autopkgtest_tmp/ 127s MAGIC-1 : start lost directory search 127s MAGIC-2 : start lost file search 127s MAGIC-2 : start lost in journal search 127s -------- /tmp/autopkgtest.SdopC0/autopkgtest_tmp/MAGIC-2/application/octet-stream/I_0000000263.unknown 127s -------- /tmp/autopkgtest.SdopC0/autopkgtest_tmp/MAGIC-2/application/octet-stream/I_0000000271.unknown 127s MAGIC-3 : start ext4-magic-scan search 127s ext4magic : EXIT_SUCCESS 128s autopkgtest [18:55:02]: test command2: -----------------------] 128s autopkgtest [18:55:02]: test command2: - - - - - - - - - - results - - - - - - - - - - 128s command2 PASS 128s autopkgtest [18:55:02]: @@@@@@@@@@@@@@@@@@@@ summary 128s command1 PASS 128s command2 PASS 146s nova [W] Using flock in prodstack6-s390x 146s Creating nova instance adt-questing-s390x-ext4magic-20250501-185254-juju-7f2275-prod-proposed-migration-environment-23-7ffa33ac-fecc-40ff-9c74-6adea30cf1a6 from image adt/ubuntu-questing-s390x-server-20250501.img (UUID 06a11845-48a4-4c28-9288-51dd7b757c52)... 146s nova [W] Timed out waiting for a8af73e9-b830-42a5-93c1-070f2eff1fc3 to get deleted.