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