0s autopkgtest [12:19:57]: starting date and time: 2025-07-08 12:19:57+0000 0s autopkgtest [12:19:57]: git checkout: 508d4a25 a-v-ssh wait_for_ssh: demote "ssh connection failed" to a debug message 0s autopkgtest [12:19:57]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.9okw9e0e/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:distro-info --apt-upgrade pbuilder --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=distro-info/1.14 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-cpu2-ram4-disk20-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@sto01-s390x-9.secgroup --name adt-questing-s390x-pbuilder-20250708-121957-juju-7f2275-prod-proposed-migration-environment-15-93e20a1c-1538-46ad-9495-32e19bacaa63 --image adt/ubuntu-questing-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-autopkgtest-workers-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-questing-s390x-pbuilder-20250708-121957-juju-7f2275-prod-proposed-migration-environment-15-93e20a1c-1538-46ad-9495-32e19bacaa63 from image adt/ubuntu-questing-s390x-server-20250708.img (UUID 00752f72-6a66-4c57-8f8d-2dec18ad5dfe)... 62s autopkgtest [12:20:59]: testbed dpkg architecture: s390x 63s autopkgtest [12:21:00]: testbed apt version: 3.1.3 63s autopkgtest [12:21:00]: @@@@@@@@@@@@@@@@@@@@ test bed setup 63s autopkgtest [12:21:00]: testbed release detected to be: None 64s autopkgtest [12:21:01]: updating testbed package index (apt update) 64s Get:1 http://ftpmaster.internal/ubuntu questing-proposed InRelease [249 kB] 64s Hit:2 http://ftpmaster.internal/ubuntu questing InRelease 65s Hit:3 http://ftpmaster.internal/ubuntu questing-updates InRelease 65s Hit:4 http://ftpmaster.internal/ubuntu questing-security InRelease 65s Get:5 http://ftpmaster.internal/ubuntu questing-proposed/main Sources [32.7 kB] 65s Get:6 http://ftpmaster.internal/ubuntu questing-proposed/multiverse Sources [15.4 kB] 65s Get:7 http://ftpmaster.internal/ubuntu questing-proposed/universe Sources [180 kB] 65s Get:8 http://ftpmaster.internal/ubuntu questing-proposed/restricted Sources [2260 B] 65s Get:9 http://ftpmaster.internal/ubuntu questing-proposed/main s390x Packages [58.5 kB] 65s Get:10 http://ftpmaster.internal/ubuntu questing-proposed/universe s390x Packages [154 kB] 65s Get:11 http://ftpmaster.internal/ubuntu questing-proposed/multiverse s390x Packages [4452 B] 65s Fetched 697 kB in 1s (978 kB/s) 68s Reading package lists... 69s autopkgtest [12:21:06]: upgrading testbed (apt dist-upgrade and autopurge) 69s Reading package lists... 70s Building dependency tree... 70s Reading state information... 70s Calculating upgrade... 70s The following packages will be upgraded: 70s distro-info 70s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 70s Need to get 20.2 kB of archives. 70s After this operation, 0 B of additional disk space will be used. 70s Get:1 http://ftpmaster.internal/ubuntu questing-proposed/main s390x distro-info s390x 1.14 [20.2 kB] 71s Fetched 20.2 kB in 0s (362 kB/s) 72s (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 ... 85973 files and directories currently installed.) 72s Preparing to unpack .../distro-info_1.14_s390x.deb ... 72s Unpacking distro-info (1.14) over (1.13) ... 72s Setting up distro-info (1.14) ... 72s Processing triggers for man-db (2.13.1-1) ... 75s Reading package lists... 76s Building dependency tree... 76s Reading state information... 76s Solving dependencies... 76s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 79s autopkgtest [12:21:16]: testbed running kernel: Linux 6.15.0-3-generic #3-Ubuntu SMP Wed Jun 4 07:31:50 UTC 2025 79s autopkgtest [12:21:16]: @@@@@@@@@@@@@@@@@@@@ apt-source pbuilder 80s Get:1 http://ftpmaster.internal/ubuntu questing/universe pbuilder 0.231.1 (dsc) [1867 B] 80s Get:2 http://ftpmaster.internal/ubuntu questing/universe pbuilder 0.231.1 (tar) [321 kB] 80s gpgv: Signature made Fri Sep 6 22:10:14 2024 UTC 80s gpgv: using RSA key 85367C39FE5D3C8A84915D716F5982D88300A641 80s gpgv: Can't check signature: No public key 80s dpkg-source: warning: cannot verify inline signature for ./pbuilder_0.231.1.dsc: no acceptable signature found 81s autopkgtest [12:21:18]: testing package pbuilder version 0.231.1 81s autopkgtest [12:21:18]: build not needed 81s autopkgtest [12:21:18]: test unit-tests: preparing testbed 82s Reading package lists... 82s Building dependency tree... 82s Reading state information... 82s Solving dependencies... 83s The following NEW packages will be installed: 83s debootstrap pbuilder 83s 0 upgraded, 2 newly installed, 0 to remove and 0 not upgraded. 83s Need to get 370 kB of archives. 83s After this operation, 1295 kB of additional disk space will be used. 83s Get:1 http://ftpmaster.internal/ubuntu questing/main s390x debootstrap all 1.0.141 [50.1 kB] 83s Get:2 http://ftpmaster.internal/ubuntu questing/universe s390x pbuilder all 0.231.1 [320 kB] 83s Preconfiguring packages ... 84s Configuring pbuilder 84s -------------------- 84s 84s Default mirror not found 84s 84s Mirror information detection failed and the user provided no mirror 84s information. 84s 84s Please enter valid mirror information. 84s 84s Fetched 370 kB in 0s (23.7 MB/s) 84s Selecting previously unselected package debootstrap. 84s (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 ... 85973 files and directories currently installed.) 84s Preparing to unpack .../debootstrap_1.0.141_all.deb ... 84s Unpacking debootstrap (1.0.141) ... 84s Selecting previously unselected package pbuilder. 84s Preparing to unpack .../pbuilder_0.231.1_all.deb ... 84s Unpacking pbuilder (0.231.1) ... 84s Setting up debootstrap (1.0.141) ... 84s Setting up pbuilder (0.231.1) ... 84s Processing triggers for man-db (2.13.1-1) ... 86s autopkgtest [12:21:23]: test unit-tests: [----------------------- 86s Copying tests files... 86s [OK] run_suite_mode 87s [OK] run_suite_mode 87s Unknown mirror for archive=nomirror 87s [OK] run_suite_mode 87s [OK] run_suite_mode 87s [OK] run_suite_mode 87s [OK] run_suite_mode 87s [OK] test_components 87s [OK] test_pockets 87s [OK] test_profile 87s [OK] test_profile 87s [OK] test_profile 87s [OK] test_profile 87s [OK] test_profile 87s [OK] test_profile 87s [OK] test_profile 87s [OK] test_profile 87s [OK] test_profile 87s [OK] test_profile 87s [OK] test_profile 87s [OK] test_profile 87s [OK] test_profile 87s [OK] test_profile 87s [OK] test_profile 87s [OK] run_suite_mode 87s [OK] run_suite_mode 87s [OK] run_suite_mode 87s [OK] run_suite_mode 87s [OK] run_suite_mode 87s [OK] run_suite_mode 87s ./testlib.sh: line 146: run_hamm_mode: command not found 87s [OK] run_hamm_mode 87s deb ubuntu-mirror xenial component 87s [OK] run_suite_mode 87s Unknown archive for dist=suite 87s [OK] run_suite_mode 87s deb ubuntu-mirror suite component 87s [OK] run_suite_mode 87s [OK] run_suite_mode 87s [OK] run_suite_mode 87s [OK] debootstrap_suite 87s [OK] debootstrap_suite 87s [OK] debootstrap_suite 87s [OK] debootstrap_mirror 87s [OK] debootstrap_suite 87s [OK] debootstrap_suite 87s [OK] debootstrap_mirror 87s [OK] debootstrap_mirror 87s ./test_pbuilder-apt-config: Ran 43 tests and 43 succeeded, 0 failed 87s [OK] test_getdscfilesNormal 87s [OK] test_getdscfilesWithoutNL 87s [OK] test_getchangesfilesNormal 88s [OK] test_getdscfilesWithInsaneParentDir 88s [OK] test_dsc_get_basename_normal_with_revision 88s [OK] test_dsc_get_basename_normal_without_revision 88s [OK] test_dsc_get_basename_epoch_with_revision 88s [OK] test_dsc_get_basename_epoch_without_revision 88s [OK] test_dsc_get_basename_native_with_revision 88s [OK] test_dsc_get_basename_native_without_revision 88s [OK] test_extraresults1 88s ./test_pbuilder-buildpackage-funcs: Ran 13 tests and 13 succeeded, 0 failed 88s [OK] test_extraresults2 88s [OK] test_extraresults3 88s [OK] test_debuildopts 88s [OK] test_debuildopts 88s [OK] test_debuildopts 88s [OK] test_debuildopts 88s ./test_pbuilder-checkparams: Ran 5 tests and 5 succeeded, 0 failed 88s [OK] test_debuildopts 88s [OK] test_information 88s [OK] test_information 88s [OK] test_log 88s [OK] test_log 88s [OK] test_log 88s [OK] test_log 88s [OK] test_conditional_cp_a 88s [OK] test_conditional_cp_a_copy 88s ./test_pbuilder-modules: Ran 10 tests and 10 succeeded, 0 failed 88s [OK] get822field 88s [OK] get822field 88s --chroot specified after --internal-chrootexec 88s [OK] test_chrootexec2 88s [OK] test_chrootexec1 88s --chroot specified after --internal-chrootexec 88s [OK] test_chrootexec_echo 88s [OK] test_chrootexec_echo2 88s [OK] test_chrootexec_expect 88s ./test_pbuilder-satisfydepends-checkparams: Ran 5 tests and 5 succeeded, 0 failed 89s [OK] test_split_deps 89s [OK] split_alternates 89s [OK] get_pkg_name 89s ./test_pbuilder-satisfydepends-classic: Ran 4 tests and 4 succeeded, 0 failed 89s [OK] get_dep_op_and_ver 89s [OK] test_get_build_deps 89s [OK] test_get_build_deps 89s [OK] test_get_build_deps 89s [OK] test_get_build_deps_source_para_only 89s [OK] test_get_build_deps_comment_between_lines 89s [OK] test_get_build_deps_one_line_preceding_comment 89s [OK] test_get_build_deps_one_line_trailing_comment 89s [OK] test_get_build_deps_one_line_preceding_blank 89s [OK] checkbuilddep_archdeps 89s [OK] checkbuilddep_archdeps 90s [OK] checkbuilddep_archdeps 90s [OK] checkbuilddep_archdeps 90s [OK] checkbuilddep_archdeps 90s [OK] checkbuilddep_archdeps 91s [OK] checkbuilddep_archdeps 91s [OK] test_filter_arch_deps 91s [OK] test_filter_arch_deps 91s [OK] test_filter_arch_deps 91s [OK] test_filter_arch_deps 91s [OK] test_filter_arch_deps 91s [OK] checkbuilddep_restrictiondeps 91s [OK] checkbuilddep_restrictiondeps 91s [OK] checkbuilddep_restrictiondeps 91s [OK] checkbuilddep_restrictiondeps 91s [OK] checkbuilddep_restrictiondeps 91s [OK] checkbuilddep_restrictiondeps 91s [OK] checkbuilddep_restrictiondeps 91s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] checkbuilddep_restrictiondeps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 92s [OK] test_filter_restriction_deps 93s [OK] test_filter_arch_restriction_deps 93s [OK] test_filter_arch_restriction_deps 93s [OK] test_filter_arch_restriction_deps 93s [OK] test_filter_arch_restriction_deps 93s [OK] test_filter_version_restriction_deps 93s [OK] test_filter_version_restriction_deps 93s [OK] test_filter_version_restriction_deps 93s [OK] test_filter_version_restriction_deps 93s [OK] test_filter_version_restriction_deps 93s [OK] test_get_build_deps_dsc 94s [OK] test_get_parsed_build_deps_dsc 94s [OK] test_get_source_control_field_with_comments 94s ./test_pbuilder-satisfydepends-funcs: Ran 77 tests and 77 succeeded, 0 failed 94s [OK] test_success 94s hello world 94s ./test_testlib.sh: Ran 4 tests and 4 succeeded, 0 failed 94s [OK] test_fail 94s [OK] test_options 94s [OK] test_output 94s autopkgtest [12:21:31]: test unit-tests: -----------------------] 95s autopkgtest [12:21:32]: test unit-tests: - - - - - - - - - - results - - - - - - - - - - 95s unit-tests PASS 95s autopkgtest [12:21:32]: test build-procenv: preparing testbed 96s Reading package lists... 96s Building dependency tree... 96s Reading state information... 96s Solving dependencies... 97s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 99s autopkgtest [12:21:36]: test build-procenv: [----------------------- 99s INFO: Creating pbuilder chroot for release 'questing' 99s + pbuilder create --debug --distribution 'questing' --components 'main universe' --debootstrapopts --mirror 'http://ports.ubuntu.com' 99s W: /root/.pbuilderrc does not exist 99s ++ shift 99s ++ '[' -n --distribution ']' 99s ++ case "$1" in 99s ++ DISTRIBUTION=questing 99s ++ OVERRIDE_APTLINES_WARN=yes 99s ++ shift 99s ++ shift 99s ++ '[' -n --components ']' 99s ++ case "$1" in 99s ++ COMPONENTS='main universe' 99s ++ OVERRIDE_APTLINES_WARN=yes 99s ++ shift 99s ++ shift 99s ++ '[' -n --mirror ']' 99s ++ case "$1" in 99s ++ MIRRORSITE=http://ports.ubuntu.com 99s ++ OVERRIDE_APTLINES_WARN=yes 99s ++ shift 99s ++ shift 99s ++ '[' -n '' ']' 99s ++ log.d 'cmdline: create --debug --distribution questing --components main universe --mirror http://ports.ubuntu.com' 99s ++ case "${LOGLEVEL-I}" in 99s ++ BUILDPLACE=/var/cache/pbuilder/build 99s ++ BASEBUILDPLACE=/var/cache/pbuilder/build 99s ++ '[' '' '!=' yes -a no '!=' yes ']' 99s ++ BUILDPLACE=/var/cache/pbuilder/build/4270 99s ++ case "$LOGLEVEL" in 99s ++ '[' -z '' ']' 99s ++ CHROOTEXEC='chroot /var/cache/pbuilder/build/4270 ' 99s ++ '[' no = yes ']' 99s ++ '[' yes = yes ']' 99s ++ systemctl is-system-running --quiet 99s +++ dpkg-query -W '--showformat=${Version}' systemd 99s ++ dpkg --compare-versions 257.7-1ubuntu1 gt 215 99s ++ SYSTEMD_SLICE=system-pbuilder-create-4270.slice 99s ++ systemctl_run=(systemd-run --quiet --scope --description="pbuilder_${PBUILDER_OPERATION}${1:+_"$(basename "$1")"}" --slice="$SYSTEMD_SLICE") 99s ++ CHROOTEXEC='systemd-run --quiet --scope --description=pbuilder_create --slice=system-pbuilder-create-4270.slice chroot /var/cache/pbuilder/build/4270 ' 99s ++ case "$PBUILDER_OPERATION" in 99s ++ '[' questing = experimental ']' 99s ++ EXPERIMENTAL= 99s ++ case "$PBUILDER_OPERATION" in 99s ++ '[' noninteractive = noninteractive -o noninteractive = Noninteractive ']' 99s ++ exec 99s ++ FORCE_CONFNEW[0]=-o 99s ++ FORCE_CONFNEW[1]=DPkg::Options::=--force-confnew 99s ++ '[' -n '' ']' 99s +++ sort -u 99s ++ BINDMOUNTS= 99s ++ '[' no = yes ']' 99s + '[' -z questing ']' 99s + log.i 'Distribution is questing.' 99s + case "${LOGLEVEL-I}" in 99s + log 'I: Distribution is questing.' 99s + set +x 99s I: Distribution is questing. 99s + '[' yes = yes ']' 99s + TRAP='echo ignoring trap ' 99s + echobacktime 99s ++ date 99s + log.i 'Current time: Tue Jul 8 12:23:50 UTC 2025' 99s + case "${LOGLEVEL-I}" in 99s + log 'I: Current time: Tue Jul 8 12:23:50 UTC 2025' 99s + set +x 99s I: Current time: Tue Jul 8 12:23:50 UTC 2025 99s ++ date +%s 99s + log.i 'pbuilder-time-stamp: 1751977430' 99s + case "${LOGLEVEL-I}" in 99s + log 'I: pbuilder-time-stamp: 1751977430' 99s + set +x 99s I: pbuilder-time-stamp: 1751977430 99s + cleanbuildplace 99s + '[' 0 -ne 0 ']' 99s + unloadhooks 99s + '[' -z '' ']' 99s + return 99s + '[' '' '!=' yes ']' 99s + '[' yes = yes ']' 99s + local TasksCurrent 99s ++ systemctl show system-pbuilder-create-4270.slice --property=TasksCurrent 99s ++ tr -d '\n' 99s + TasksCurrent='TasksCurrent=[not set]' 99s + TasksCurrent='[not set]' 99s + '[' '[not set]' '!=' 0 -a '[not set]' '!=' '[not set]' -a '[not set]' '!=' 18446744073709551615 ']' 99s + systemctl stop system-pbuilder-create-4270.slice 99s + '[' -d /var/cache/pbuilder/build/4270 ']' 99s + log.i 'Building the build environment' 99s + case "${LOGLEVEL-I}" in 99s + log 'I: Building the build environment' 99s + set +x 99s I: Building the build environment 99s + mkdir -p /var/cache/pbuilder/build/4270 99s + '[' '!' -d /var/cache/pbuilder/build/4270 ']' 99s + echo ignoring trap cleanbuildplace exit 99s ignoring trap cleanbuildplace exit 99s + log.i 'running debootstrap' 99s + case "${LOGLEVEL-I}" in 99s + log 'I: running debootstrap' 99s + set +x 99s I: running debootstrap 99s + unset DEBOOTSTRAPSCRIPT 99s + '[' -n '' ']' 99s + which debootstrap 99s /usr/sbin/debootstrap 99s + : 99s + '[' -n /var/cache/pbuilder/aptcache/ ']' 99s + '[' debootstrap = debootstrap -o debootstrap = qemu-debootstrap ']' 99s + USE_CACHE=--cache-dir=/var/cache/pbuilder/aptcache/ 99s + debootstrap --include=apt --cache-dir=/var/cache/pbuilder/aptcache/ --variant=buildd --force-check-gpg questing /var/cache/pbuilder/build/4270 http://ports.ubuntu.com 99s I: Retrieving InRelease 99s I: Retrieving Release 99s E: Failed getting release file http://ports.ubuntu.com/dists/questing/Release 100s + log.e 'debootstrap failed' 100s + case "${LOGLEVEL-I}" in 100s + log 'E: debootstrap failed' 100s + set +x 100s E: debootstrap failed 100s + '[' -f /var/cache/pbuilder/build/4270/debootstrap/debootstrap.log ']' 100s + log.e 'Tail of debootstrap.log:' 100s + case "${LOGLEVEL-I}" in 100s + log 'E: Tail of debootstrap.log:' 100s + set +x 100s E: Tail of debootstrap.log: 100s + tail -n 50 /var/cache/pbuilder/build/4270/debootstrap/debootstrap.log 100s http://ports.ubuntu.com/dists/questing/InRelease: 100s 2025-07-08 12:23:51 ERROR 403: Forbidden. 100s http://ports.ubuntu.com/dists/questing/Release: 100s 2025-07-08 12:23:51 ERROR 403: Forbidden. 100s + log.e 'End of debootstrap.log' 100s + case "${LOGLEVEL-I}" in 100s + log 'E: End of debootstrap.log' 100s + set +x 100s E: End of debootstrap.log 100s + exit 1 100s autopkgtest [12:21:37]: test build-procenv: -----------------------] 100s build-procenv FAIL non-zero exit status 1 100s autopkgtest [12:21:37]: test build-procenv: - - - - - - - - - - results - - - - - - - - - - 101s autopkgtest [12:21:38]: @@@@@@@@@@@@@@@@@@@@ summary 101s unit-tests PASS 101s build-procenv FAIL non-zero exit status 1