0s autopkgtest [01:14:56]: starting date and time: 2025-05-04 01:14:56+0000 0s autopkgtest [01:14:56]: git checkout: 9986aa8c Merge branch 'skia/fix_network_interface' into 'ubuntu/production' 0s autopkgtest [01:14:56]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.s_7pidls/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-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-ppc64el-10.secgroup --name adt-questing-ppc64el-ext4magic-20250504-011456-juju-7f2275-prod-proposed-migration-environment-2-abe3c993-70ca-423f-ba75-582c209ff663 --image adt/ubuntu-questing-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 107s autopkgtest [01:16:43]: testbed dpkg architecture: ppc64el 107s autopkgtest [01:16:43]: testbed apt version: 3.0.0 108s autopkgtest [01:16:44]: @@@@@@@@@@@@@@@@@@@@ test bed setup 108s autopkgtest [01:16:44]: testbed release detected to be: None 109s autopkgtest [01:16:45]: updating testbed package index (apt update) 109s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [110 kB] 109s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 109s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 109s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 109s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [1389 kB] 110s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [143 kB] 110s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [33.2 kB] 110s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el Packages [183 kB] 110s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el Packages [1308 kB] 110s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/multiverse ppc64el Packages [29.6 kB] 110s Fetched 3196 kB in 1s (2640 kB/s) 111s Reading package lists... 112s autopkgtest [01:16:48]: upgrading testbed (apt dist-upgrade and autopurge) 112s Reading package lists... 112s Building dependency tree... 112s Reading state information... 112s Calculating upgrade...Starting pkgProblemResolver with broken count: 0 112s Starting 2 pkgProblemResolver with broken count: 0 112s Done 113s Entering ResolveByKeep 113s 113s Calculating upgrade... 113s The following packages will be upgraded: 113s cloud-init cloud-init-base debianutils file libmagic-mgc libmagic1t64 113s libmbim-glib4 libmbim-proxy libnftnl11 libpython3.12-minimal 113s libpython3.12-stdlib libpython3.12t64 libqmi-glib5 libqmi-proxy libsepol2 113s python3-lazr.restfulclient 113s 16 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 113s Need to get 8470 kB of archives. 113s After this operation, 1764 kB of additional disk space will be used. 113s Get:1 http://ftpmaster.internal/ubuntu questing/main ppc64el debianutils ppc64el 5.22 [93.9 kB] 114s Get:2 http://ftpmaster.internal/ubuntu questing/main ppc64el libsepol2 ppc64el 3.8.1-1 [411 kB] 114s Get:3 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el file ppc64el 1:5.46-5 [22.8 kB] 114s Get:4 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el libmagic1t64 ppc64el 1:5.46-5 [108 kB] 114s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main ppc64el libmagic-mgc ppc64el 1:5.46-5 [336 kB] 114s Get:6 http://ftpmaster.internal/ubuntu questing/main ppc64el libnftnl11 ppc64el 1.2.9-1 [77.1 kB] 114s Get:7 http://ftpmaster.internal/ubuntu questing/main ppc64el cloud-init-base all 25.2~1g7a0265d3-0ubuntu1 [619 kB] 114s Get:8 http://ftpmaster.internal/ubuntu questing/main ppc64el libmbim-proxy ppc64el 1.32.0-1 [6352 B] 114s Get:9 http://ftpmaster.internal/ubuntu questing/main ppc64el libmbim-glib4 ppc64el 1.32.0-1 [258 kB] 114s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el libpython3.12t64 ppc64el 3.12.10-1 [2558 kB] 114s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el libpython3.12-stdlib ppc64el 3.12.10-1 [2105 kB] 114s Get:12 http://ftpmaster.internal/ubuntu questing-proposed/universe ppc64el libpython3.12-minimal ppc64el 3.12.10-1 [841 kB] 114s Get:13 http://ftpmaster.internal/ubuntu questing/main ppc64el libqmi-proxy ppc64el 1.36.0-1 [6236 B] 114s Get:14 http://ftpmaster.internal/ubuntu questing/main ppc64el libqmi-glib5 ppc64el 1.36.0-1 [976 kB] 114s Get:15 http://ftpmaster.internal/ubuntu questing/main ppc64el python3-lazr.restfulclient all 0.14.6-3 [51.0 kB] 114s Get:16 http://ftpmaster.internal/ubuntu questing/main ppc64el cloud-init all 25.2~1g7a0265d3-0ubuntu1 [2106 B] 114s Preconfiguring packages ... 114s Fetched 8470 kB in 1s (10.5 MB/s) 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 ... 107205 files and directories currently installed.) 115s Preparing to unpack .../debianutils_5.22_ppc64el.deb ... 115s Unpacking debianutils (5.22) over (5.21) ... 115s Setting up debianutils (5.22) ... 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 ... 107205 files and directories currently installed.) 115s Preparing to unpack .../libsepol2_3.8.1-1_ppc64el.deb ... 115s Unpacking libsepol2:ppc64el (3.8.1-1) over (3.7-1) ... 115s Setting up libsepol2:ppc64el (3.8.1-1) ... 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 ... 107205 files and directories currently installed.) 115s Preparing to unpack .../00-file_1%3a5.46-5_ppc64el.deb ... 115s Unpacking file (1:5.46-5) over (1:5.45-3build1) ... 115s Preparing to unpack .../01-libmagic1t64_1%3a5.46-5_ppc64el.deb ... 115s Unpacking libmagic1t64:ppc64el (1:5.46-5) over (1:5.45-3build1) ... 115s Preparing to unpack .../02-libmagic-mgc_1%3a5.46-5_ppc64el.deb ... 115s Unpacking libmagic-mgc (1:5.46-5) over (1:5.45-3build1) ... 115s Preparing to unpack .../03-libnftnl11_1.2.9-1_ppc64el.deb ... 115s Unpacking libnftnl11:ppc64el (1.2.9-1) over (1.2.8-1) ... 115s Preparing to unpack .../04-cloud-init-base_25.2~1g7a0265d3-0ubuntu1_all.deb ... 115s Unpacking cloud-init-base (25.2~1g7a0265d3-0ubuntu1) over (25.1.1-0ubuntu2) ... 116s Preparing to unpack .../05-libmbim-proxy_1.32.0-1_ppc64el.deb ... 116s Unpacking libmbim-proxy (1.32.0-1) over (1.31.2-0ubuntu4) ... 116s Preparing to unpack .../06-libmbim-glib4_1.32.0-1_ppc64el.deb ... 116s Unpacking libmbim-glib4:ppc64el (1.32.0-1) over (1.31.2-0ubuntu4) ... 116s Preparing to unpack .../07-libpython3.12t64_3.12.10-1_ppc64el.deb ... 116s Unpacking libpython3.12t64:ppc64el (3.12.10-1) over (3.12.8-3) ... 116s Preparing to unpack .../08-libpython3.12-stdlib_3.12.10-1_ppc64el.deb ... 116s Unpacking libpython3.12-stdlib:ppc64el (3.12.10-1) over (3.12.8-3) ... 116s Preparing to unpack .../09-libpython3.12-minimal_3.12.10-1_ppc64el.deb ... 116s Unpacking libpython3.12-minimal:ppc64el (3.12.10-1) over (3.12.8-3) ... 116s Preparing to unpack .../10-libqmi-proxy_1.36.0-1_ppc64el.deb ... 116s Unpacking libqmi-proxy (1.36.0-1) over (1.35.6-1) ... 116s Preparing to unpack .../11-libqmi-glib5_1.36.0-1_ppc64el.deb ... 116s Unpacking libqmi-glib5:ppc64el (1.36.0-1) over (1.35.6-1) ... 116s Preparing to unpack .../12-python3-lazr.restfulclient_0.14.6-3_all.deb ... 116s Unpacking python3-lazr.restfulclient (0.14.6-3) over (0.14.6-2) ... 116s Preparing to unpack .../13-cloud-init_25.2~1g7a0265d3-0ubuntu1_all.deb ... 116s Unpacking cloud-init (25.2~1g7a0265d3-0ubuntu1) over (25.1.1-0ubuntu2) ... 116s Setting up libmagic-mgc (1:5.46-5) ... 116s Setting up libmagic1t64:ppc64el (1:5.46-5) ... 116s Setting up libpython3.12-minimal:ppc64el (3.12.10-1) ... 116s Setting up libnftnl11:ppc64el (1.2.9-1) ... 116s Setting up file (1:5.46-5) ... 116s Setting up cloud-init-base (25.2~1g7a0265d3-0ubuntu1) ... 118s Setting up python3-lazr.restfulclient (0.14.6-3) ... 118s Setting up libmbim-glib4:ppc64el (1.32.0-1) ... 118s Setting up cloud-init (25.2~1g7a0265d3-0ubuntu1) ... 118s Setting up libpython3.12-stdlib:ppc64el (3.12.10-1) ... 118s Setting up libmbim-proxy (1.32.0-1) ... 118s Setting up libpython3.12t64:ppc64el (3.12.10-1) ... 118s Setting up libqmi-glib5:ppc64el (1.36.0-1) ... 118s Setting up libqmi-proxy (1.36.0-1) ... 118s Processing triggers for libc-bin (2.41-6ubuntu1) ... 118s Processing triggers for rsyslog (8.2412.0-2ubuntu2) ... 119s Processing triggers for man-db (2.13.0-1) ... 120s Reading package lists... 121s Building dependency tree... 121s Reading state information... 121s Starting pkgProblemResolver with broken count: 0 121s Starting 2 pkgProblemResolver with broken count: 0 121s Done 121s Solving dependencies... 121s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 122s autopkgtest [01:16:58]: rebooting testbed after setup commands that affected boot 155s autopkgtest-virt-ssh: WARNING: ssh connection failed. Retrying in 3 seconds... 162s autopkgtest [01:17:38]: testbed running kernel: Linux 6.14.0-15-generic #15-Ubuntu SMP Sun Apr 6 14:52:42 UTC 2025 164s autopkgtest [01:17:40]: @@@@@@@@@@@@@@@@@@@@ apt-source ext4magic 166s Get:1 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (dsc) [2149 B] 166s Get:2 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (tar) [427 kB] 166s Get:3 http://ftpmaster.internal/ubuntu questing/universe ext4magic 0.3.2-14build2 (diff) [175 kB] 167s gpgv: Signature made Mon Apr 1 05:55:08 2024 UTC 167s gpgv: using RSA key A089FB36AAFBDAD5ACC1325069F790171A210984 167s gpgv: Can't check signature: No public key 167s dpkg-source: warning: cannot verify inline signature for ./ext4magic_0.3.2-14build2.dsc: no acceptable signature found 167s autopkgtest [01:17:43]: testing package ext4magic version 0.3.2-14build2 167s autopkgtest [01:17:43]: build not needed 168s autopkgtest [01:17:44]: test command1: preparing testbed 168s Reading package lists... 168s Building dependency tree... 168s Reading state information... 169s Starting pkgProblemResolver with broken count: 0 169s Starting 2 pkgProblemResolver with broken count: 0 169s Done 169s The following NEW packages will be installed: 169s ext4magic 169s 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. 169s Need to get 130 kB of archives. 169s After this operation, 365 kB of additional disk space will be used. 169s Get:1 http://ftpmaster.internal/ubuntu questing/universe ppc64el ext4magic ppc64el 0.3.2-14build2 [130 kB] 169s Fetched 130 kB in 0s (388 kB/s) 169s Selecting previously unselected package ext4magic. 170s (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 ... 107205 files and directories currently installed.) 170s Preparing to unpack .../ext4magic_0.3.2-14build2_ppc64el.deb ... 170s Unpacking ext4magic (0.3.2-14build2) ... 170s Setting up ext4magic (0.3.2-14build2) ... 170s Processing triggers for man-db (2.13.0-1) ... 171s autopkgtest [01:17:47]: test command1: ext4magic -V 171s autopkgtest [01:17:47]: test command1: [----------------------- 172s ext4magic version : 0.3.2 172s libext2fs version : 1.47.2 172s CPU is little endian. 172s Expert Mode is activ 172s autopkgtest [01:17:48]: test command1: -----------------------] 172s autopkgtest [01:17:48]: test command1: - - - - - - - - - - results - - - - - - - - - - 172s command1 PASS 173s autopkgtest [01:17:49]: test command2: 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 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 174s autopkgtest [01:17:50]: test command2: ext4magic debian/tests/test.img -M -d $AUTOPKGTEST_TMP 174s autopkgtest [01:17:50]: test command2: [----------------------- 175s Warning: Activate magic-scan or disaster-recovery function, may be some command line options ignored 175s "/tmp/autopkgtest.CcnKac/autopkgtest_tmp" accept for recoverdir 175s Filesystem in use: debian/tests/test.img 175s 175s Using internal Journal at Inode 8 175s Activ Time after : Mon Dec 10 13:06:19 2018 175s Activ Time before : Sun May 4 01:17:50 2025 175s Inode 2 is allocated 175s -------- /tmp/autopkgtest.CcnKac/autopkgtest_tmp/lost+found 175s -------- /tmp/autopkgtest.CcnKac/autopkgtest_tmp/openlogo-50.png 175s -------- /tmp/autopkgtest.CcnKac/autopkgtest_tmp/Debian25years-DanielLenharo.png 175s -------- /tmp/autopkgtest.CcnKac/autopkgtest_tmp/ 175s MAGIC-1 : start lost directory search 175s MAGIC-2 : start lost file search 175s MAGIC-2 : start lost in journal search 175s -------- /tmp/autopkgtest.CcnKac/autopkgtest_tmp/MAGIC-2/application/octet-stream/I_0000000263.unknown 175s -------- /tmp/autopkgtest.CcnKac/autopkgtest_tmp/MAGIC-2/application/octet-stream/I_0000000271.unknown 175s MAGIC-3 : start ext4-magic-scan search 175s ext4magic : EXIT_SUCCESS 176s autopkgtest [01:17:52]: test command2: -----------------------] 176s autopkgtest [01:17:52]: test command2: - - - - - - - - - - results - - - - - - - - - - 176s command2 PASS 177s autopkgtest [01:17:53]: @@@@@@@@@@@@@@@@@@@@ summary 177s command1 PASS 177s command2 PASS 181s nova [W] Using flock in prodstack6-ppc64el 181s Creating nova instance adt-questing-ppc64el-ext4magic-20250504-011456-juju-7f2275-prod-proposed-migration-environment-2-abe3c993-70ca-423f-ba75-582c209ff663 from image adt/ubuntu-questing-ppc64el-server-20250503.img (UUID 1e63be20-33d6-474d-9c56-b247a2188377)... 181s nova [W] Timed out waiting for 26c15645-859a-4db6-823a-f29697072fe8 to get deleted.