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