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.