0s autopkgtest [05:24:20]: starting date and time: 2024-07-21 05:24:20+0000 0s autopkgtest [05:24:20]: git checkout: fd3bed09 nova: allow more retries for quota issues 0s autopkgtest [05:24:20]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.afnicv60/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:python3-defaults,src:python3-stdlib-extensions --apt-upgrade python-uvicorn --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=python3-defaults/3.12.4-1 python3-stdlib-extensions/3.12.4-2' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos01-s390x-17.secgroup --name adt-oracular-s390x-python-uvicorn-20240721-052420-juju-7f2275-prod-proposed-migration-environment-2-11a4c7f5-ad01-4172-86e3-9ae5f0ac6fd2 --image adt/ubuntu-oracular-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://us.ports.ubuntu.com/ubuntu-ports/ 121s autopkgtest [05:26:21]: testbed dpkg architecture: s390x 121s autopkgtest [05:26:21]: testbed apt version: 2.9.6 121s autopkgtest [05:26:21]: @@@@@@@@@@@@@@@@@@@@ test bed setup 122s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 122s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [62.2 kB] 122s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 122s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [4724 B] 122s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [407 kB] 122s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x Packages [101 kB] 122s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x c-n-f Metadata [2112 B] 122s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted s390x Packages [1368 B] 122s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted s390x c-n-f Metadata [120 B] 122s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe s390x Packages [464 kB] 122s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe s390x c-n-f Metadata [8372 B] 122s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse s390x Packages [2744 B] 122s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse s390x c-n-f Metadata [120 B] 123s Fetched 1188 kB in 1s (1265 kB/s) 123s Reading package lists... 126s Reading package lists... 126s Building dependency tree... 126s Reading state information... 126s Calculating upgrade... 127s The following packages will be upgraded: 127s libbpf1 libpython3-stdlib python3 python3-gdbm python3-minimal 127s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 127s Need to get 259 kB of archives. 127s After this operation, 26.6 kB of additional disk space will be used. 127s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x python3-minimal s390x 3.12.4-1 [27.1 kB] 127s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x python3 s390x 3.12.4-1 [24.0 kB] 127s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x libpython3-stdlib s390x 3.12.4-1 [9826 B] 127s Get:4 http://ftpmaster.internal/ubuntu oracular/main s390x libbpf1 s390x 1:1.4.5-1 [180 kB] 127s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x python3-gdbm s390x 3.12.4-2 [18.5 kB] 127s Fetched 259 kB in 0s (615 kB/s) 128s (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 ... 54753 files and directories currently installed.) 128s Preparing to unpack .../python3-minimal_3.12.4-1_s390x.deb ... 128s Unpacking python3-minimal (3.12.4-1) over (3.12.3-0ubuntu1) ... 128s Setting up python3-minimal (3.12.4-1) ... 128s (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 ... 54753 files and directories currently installed.) 128s Preparing to unpack .../python3_3.12.4-1_s390x.deb ... 128s Unpacking python3 (3.12.4-1) over (3.12.3-0ubuntu1) ... 128s Preparing to unpack .../libpython3-stdlib_3.12.4-1_s390x.deb ... 128s Unpacking libpython3-stdlib:s390x (3.12.4-1) over (3.12.3-0ubuntu1) ... 128s Preparing to unpack .../libbpf1_1%3a1.4.5-1_s390x.deb ... 128s Unpacking libbpf1:s390x (1:1.4.5-1) over (1:1.4.3-1) ... 128s Preparing to unpack .../python3-gdbm_3.12.4-2_s390x.deb ... 128s Unpacking python3-gdbm:s390x (3.12.4-2) over (3.12.3-0ubuntu1) ... 128s Setting up libbpf1:s390x (1:1.4.5-1) ... 128s Setting up libpython3-stdlib:s390x (3.12.4-1) ... 128s Setting up python3 (3.12.4-1) ... 129s Setting up python3-gdbm:s390x (3.12.4-2) ... 129s Processing triggers for man-db (2.12.1-2) ... 129s Processing triggers for libc-bin (2.39-0ubuntu9) ... 130s Reading package lists... 130s Building dependency tree... 130s Reading state information... 131s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 131s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 131s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 131s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 131s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 133s Reading package lists... 133s Reading package lists... 133s Building dependency tree... 133s Reading state information... 133s Calculating upgrade... 134s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 134s Reading package lists... 134s Building dependency tree... 134s Reading state information... 134s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 137s autopkgtest [05:26:37]: testbed running kernel: Linux 6.8.0-31-generic #31-Ubuntu SMP Sat Apr 20 00:14:26 UTC 2024 137s autopkgtest [05:26:37]: @@@@@@@@@@@@@@@@@@@@ apt-source python-uvicorn 140s Get:1 http://ftpmaster.internal/ubuntu oracular/universe python-uvicorn 0.30.1-1 (dsc) [2337 B] 140s Get:2 http://ftpmaster.internal/ubuntu oracular/universe python-uvicorn 0.30.1-1 (tar) [723 kB] 140s Get:3 http://ftpmaster.internal/ubuntu oracular/universe python-uvicorn 0.30.1-1 (diff) [7360 B] 140s gpgv: Signature made Thu Jul 18 08:04:21 2024 UTC 140s gpgv: using RSA key A954A54575BCED49240A725CFFD3C88B997DD16A 140s gpgv: Can't check signature: No public key 140s dpkg-source: warning: cannot verify inline signature for ./python-uvicorn_0.30.1-1.dsc: no acceptable signature found 141s autopkgtest [05:26:41]: testing package python-uvicorn version 0.30.1-1 141s autopkgtest [05:26:41]: build not needed 142s autopkgtest [05:26:42]: test unittests: preparing testbed 143s Reading package lists... 143s Building dependency tree... 143s Reading state information... 144s Starting pkgProblemResolver with broken count: 0 144s Starting 2 pkgProblemResolver with broken count: 0 144s Done 144s The following additional packages will be installed: 144s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 144s cpp-13-s390x-linux-gnu cpp-s390x-linux-gnu debhelper debugedit dh-autoreconf 144s dh-python dh-strip-nondeterminism dwz fonts-font-awesome fonts-lato g++ 144s g++-13 g++-13-s390x-linux-gnu g++-s390x-linux-gnu gcc gcc-13 gcc-13-base 144s gcc-13-s390x-linux-gnu gcc-s390x-linux-gnu gettext ghp-import help2man 144s intltool-debian javascript-common libarchive-zip-perl libasan8 libatomic1 144s libcc1-0 libdebhelper-perl libfile-stripnondeterminism-perl libgcc-13-dev 144s libgomp1 libhttp-parser2.9 libisl23 libitm1 libjs-bootstrap4 144s libjs-highlight.js libjs-jquery libjs-lunr libjs-modernizr libjs-popper.js 144s libjs-sphinxdoc libjs-underscore libmpc3 libstdc++-13-dev libtool libubsan1 144s m4 mkdocs po-debconf pybuild-plugin-pyproject python-uvicorn-doc 144s python3-a2wsgi python3-all python3-anyio python3-async-generator 144s python3-build python3-click python3-colorama python3-dateutil python3-dotenv 144s python3-h11 python3-hatchling python3-httpcore python3-httptools 144s python3-httpx python3-iniconfig python3-installer python3-joblib 144s python3-livereload python3-lunr python3-markdown python3-mergedeep 144s python3-nltk python3-packaging python3-pathspec python3-platformdirs 144s python3-pluggy python3-pymdownx python3-pyproject-hooks python3-pytest 144s python3-pytest-asyncio python3-pytest-mock python3-pyyaml-env-tag 144s python3-regex python3-sniffio python3-toml python3-tornado python3-tqdm 144s python3-trove-classifiers python3-trustme python3-uvicorn python3-uvloop 144s python3-watchdog python3-watchfiles python3-websockets python3-wheel 144s python3-wsproto sphinx-rtd-theme-common uvicorn 144s Suggested packages: 144s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 144s cpp-13-doc dh-make flit gcc-13-doc gcc-multilib manpages-dev flex bison gdb 144s gcc-doc gdb-s390x-linux-gnu gettext-doc libasprintf-dev libgettextpo-dev 144s apache2 | lighttpd | httpd libjs-es5-shim libstdc++-13-doc libtool-doc 144s gfortran | fortran95-compiler gcj-jdk m4-doc mkdocs-doc nodejs 144s libmail-box-perl python3-pip python3-venv python-build-doc 144s python-installer-doc coffeescript node-less node-uglify 144s python-livereload-doc python3-django python3-flask python3-slimmer 144s python-lunr-doc python-markdown-doc python-pytest-mock-doc python3-pycurl 144s python-tornado-doc python3-twisted 144s Recommended packages: 144s libarchive-cpio-perl node-jquery libltdl-dev libmail-sendmail-perl 144s python3-numpy python3-simplejson python3-psutil python3-pyinotify prover9 144s python3-tk 145s The following NEW packages will be installed: 145s autoconf automake autopkgtest-satdep autopoint autotools-dev build-essential 145s cpp cpp-13 cpp-13-s390x-linux-gnu cpp-s390x-linux-gnu debhelper debugedit 145s dh-autoreconf dh-python dh-strip-nondeterminism dwz fonts-font-awesome 145s fonts-lato g++ g++-13 g++-13-s390x-linux-gnu g++-s390x-linux-gnu gcc gcc-13 145s gcc-13-base gcc-13-s390x-linux-gnu gcc-s390x-linux-gnu gettext ghp-import 145s help2man intltool-debian javascript-common libarchive-zip-perl libasan8 145s libatomic1 libcc1-0 libdebhelper-perl libfile-stripnondeterminism-perl 145s libgcc-13-dev libgomp1 libhttp-parser2.9 libisl23 libitm1 libjs-bootstrap4 145s libjs-highlight.js libjs-jquery libjs-lunr libjs-modernizr libjs-popper.js 145s libjs-sphinxdoc libjs-underscore libmpc3 libstdc++-13-dev libtool libubsan1 145s m4 mkdocs po-debconf pybuild-plugin-pyproject python-uvicorn-doc 145s python3-a2wsgi python3-all python3-anyio python3-async-generator 145s python3-build python3-click python3-colorama python3-dateutil python3-dotenv 145s python3-h11 python3-hatchling python3-httpcore python3-httptools 145s python3-httpx python3-iniconfig python3-installer python3-joblib 145s python3-livereload python3-lunr python3-markdown python3-mergedeep 145s python3-nltk python3-packaging python3-pathspec python3-platformdirs 145s python3-pluggy python3-pymdownx python3-pyproject-hooks python3-pytest 145s python3-pytest-asyncio python3-pytest-mock python3-pyyaml-env-tag 145s python3-regex python3-sniffio python3-toml python3-tornado python3-tqdm 145s python3-trove-classifiers python3-trustme python3-uvicorn python3-uvloop 145s python3-watchdog python3-watchfiles python3-websockets python3-wheel 145s python3-wsproto sphinx-rtd-theme-common uvicorn 145s 0 upgraded, 108 newly installed, 0 to remove and 0 not upgraded. 145s Need to get 63.7 MB/63.7 MB of archives. 145s After this operation, 208 MB of additional disk space will be used. 145s Get:1 /tmp/autopkgtest.OYyCaA/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [916 B] 145s Get:2 http://ftpmaster.internal/ubuntu oracular/main s390x fonts-lato all 2.015-1 [2781 kB] 145s Get:3 http://ftpmaster.internal/ubuntu oracular/main s390x m4 s390x 1.4.19-4build1 [256 kB] 145s Get:4 http://ftpmaster.internal/ubuntu oracular/main s390x autoconf all 2.71-3 [339 kB] 145s Get:5 http://ftpmaster.internal/ubuntu oracular/main s390x autotools-dev all 20220109.1 [44.9 kB] 145s Get:6 http://ftpmaster.internal/ubuntu oracular/main s390x automake all 1:1.16.5-1.3ubuntu1 [558 kB] 145s Get:7 http://ftpmaster.internal/ubuntu oracular/main s390x autopoint all 0.22.5-1 [616 kB] 145s Get:8 http://ftpmaster.internal/ubuntu oracular/main s390x gcc-13-base s390x 13.3.0-3ubuntu1 [50.8 kB] 145s Get:9 http://ftpmaster.internal/ubuntu oracular/main s390x libisl23 s390x 0.26-3build1 [713 kB] 145s Get:10 http://ftpmaster.internal/ubuntu oracular/main s390x libmpc3 s390x 1.3.1-1build1 [58.4 kB] 145s Get:11 http://ftpmaster.internal/ubuntu oracular/main s390x cpp-13-s390x-linux-gnu s390x 13.3.0-3ubuntu1 [8777 kB] 146s Get:12 http://ftpmaster.internal/ubuntu oracular/main s390x cpp-13 s390x 13.3.0-3ubuntu1 [1026 B] 146s Get:13 http://ftpmaster.internal/ubuntu oracular/main s390x cpp-s390x-linux-gnu s390x 4:13.3.0-2ubuntu2 [5388 B] 146s Get:14 http://ftpmaster.internal/ubuntu oracular/main s390x cpp s390x 4:13.3.0-2ubuntu2 [22.4 kB] 146s Get:15 http://ftpmaster.internal/ubuntu oracular/main s390x libcc1-0 s390x 14.1.0-4ubuntu2 [50.6 kB] 146s Get:16 http://ftpmaster.internal/ubuntu oracular/main s390x libgomp1 s390x 14.1.0-4ubuntu2 [150 kB] 146s Get:17 http://ftpmaster.internal/ubuntu oracular/main s390x libitm1 s390x 14.1.0-4ubuntu2 [30.9 kB] 146s Get:18 http://ftpmaster.internal/ubuntu oracular/main s390x libatomic1 s390x 14.1.0-4ubuntu2 [9428 B] 146s Get:19 http://ftpmaster.internal/ubuntu oracular/main s390x libasan8 s390x 14.1.0-4ubuntu2 [2965 kB] 146s Get:20 http://ftpmaster.internal/ubuntu oracular/main s390x libubsan1 s390x 14.1.0-4ubuntu2 [1184 kB] 146s Get:21 http://ftpmaster.internal/ubuntu oracular/main s390x libgcc-13-dev s390x 13.3.0-3ubuntu1 [997 kB] 146s Get:22 http://ftpmaster.internal/ubuntu oracular/main s390x gcc-13-s390x-linux-gnu s390x 13.3.0-3ubuntu1 [17.1 MB] 146s Get:23 http://ftpmaster.internal/ubuntu oracular/main s390x gcc-13 s390x 13.3.0-3ubuntu1 [482 kB] 146s Get:24 http://ftpmaster.internal/ubuntu oracular/main s390x gcc-s390x-linux-gnu s390x 4:13.3.0-2ubuntu2 [1204 B] 146s Get:25 http://ftpmaster.internal/ubuntu oracular/main s390x gcc s390x 4:13.3.0-2ubuntu2 [5014 B] 146s Get:26 http://ftpmaster.internal/ubuntu oracular/main s390x libstdc++-13-dev s390x 13.3.0-3ubuntu1 [2525 kB] 146s Get:27 http://ftpmaster.internal/ubuntu oracular/main s390x g++-13-s390x-linux-gnu s390x 13.3.0-3ubuntu1 [10.2 MB] 146s Get:28 http://ftpmaster.internal/ubuntu oracular/main s390x g++-13 s390x 13.3.0-3ubuntu1 [16.0 kB] 146s Get:29 http://ftpmaster.internal/ubuntu oracular/main s390x g++-s390x-linux-gnu s390x 4:13.3.0-2ubuntu2 [956 B] 146s Get:30 http://ftpmaster.internal/ubuntu oracular/main s390x g++ s390x 4:13.3.0-2ubuntu2 [1080 B] 146s Get:31 http://ftpmaster.internal/ubuntu oracular/main s390x build-essential s390x 12.10ubuntu1 [4930 B] 146s Get:32 http://ftpmaster.internal/ubuntu oracular/main s390x libdebhelper-perl all 13.16ubuntu2 [92.9 kB] 146s Get:33 http://ftpmaster.internal/ubuntu oracular/main s390x libtool all 2.4.7-7build1 [166 kB] 146s Get:34 http://ftpmaster.internal/ubuntu oracular/main s390x dh-autoreconf all 20 [16.1 kB] 146s Get:35 http://ftpmaster.internal/ubuntu oracular/main s390x libarchive-zip-perl all 1.68-1 [90.2 kB] 146s Get:36 http://ftpmaster.internal/ubuntu oracular/main s390x libfile-stripnondeterminism-perl all 1.14.0-1 [20.1 kB] 146s Get:37 http://ftpmaster.internal/ubuntu oracular/main s390x dh-strip-nondeterminism all 1.14.0-1 [5058 B] 146s Get:38 http://ftpmaster.internal/ubuntu oracular/main s390x debugedit s390x 1:5.0-6 [49.9 kB] 146s Get:39 http://ftpmaster.internal/ubuntu oracular/main s390x dwz s390x 0.15-1build6 [122 kB] 147s Get:40 http://ftpmaster.internal/ubuntu oracular/main s390x gettext s390x 0.22.5-1 [989 kB] 147s Get:41 http://ftpmaster.internal/ubuntu oracular/main s390x intltool-debian all 0.35.0+20060710.6 [23.2 kB] 147s Get:42 http://ftpmaster.internal/ubuntu oracular/main s390x po-debconf all 1.0.21+nmu1 [233 kB] 147s Get:43 http://ftpmaster.internal/ubuntu oracular/main s390x debhelper all 13.16ubuntu2 [869 kB] 147s Get:44 http://ftpmaster.internal/ubuntu oracular/universe s390x dh-python all 6.20240603 [112 kB] 147s Get:45 http://ftpmaster.internal/ubuntu oracular/main s390x fonts-font-awesome all 5.0.10+really4.7.0~dfsg-4.1 [516 kB] 147s Get:46 http://ftpmaster.internal/ubuntu oracular/main s390x python3-dateutil all 2.9.0-2 [80.3 kB] 147s Get:47 http://ftpmaster.internal/ubuntu oracular/universe s390x ghp-import all 2.1.0-3 [12.4 kB] 147s Get:48 http://ftpmaster.internal/ubuntu oracular/universe s390x help2man s390x 1.49.3 [201 kB] 147s Get:49 http://ftpmaster.internal/ubuntu oracular/main s390x javascript-common all 11+nmu1 [5936 B] 147s Get:50 http://ftpmaster.internal/ubuntu oracular/main s390x libhttp-parser2.9 s390x 2.9.4-6build1 [24.7 kB] 147s Get:51 http://ftpmaster.internal/ubuntu oracular/universe s390x libjs-popper.js all 1.16.1+ds-6 [54.1 kB] 147s Get:52 http://ftpmaster.internal/ubuntu oracular/universe s390x libjs-bootstrap4 all 4.6.1+dfsg1-4 [537 kB] 147s Get:53 http://ftpmaster.internal/ubuntu oracular/universe s390x libjs-highlight.js all 9.18.5+dfsg1-2 [385 kB] 147s Get:54 http://ftpmaster.internal/ubuntu oracular/main s390x libjs-jquery all 3.6.1+dfsg+~3.5.14-1 [328 kB] 147s Get:55 http://ftpmaster.internal/ubuntu oracular/universe s390x libjs-lunr all 2.3.9~dfsg-2 [66.8 kB] 147s Get:56 http://ftpmaster.internal/ubuntu oracular/main s390x libjs-underscore all 1.13.4~dfsg+~1.11.4-3 [118 kB] 147s Get:57 http://ftpmaster.internal/ubuntu oracular/main s390x libjs-sphinxdoc all 7.3.7-3 [154 kB] 147s Get:58 http://ftpmaster.internal/ubuntu oracular/universe s390x libjs-modernizr all 3.13.0-0.1 [101 kB] 147s Get:59 http://ftpmaster.internal/ubuntu oracular/main s390x python3-tornado s390x 6.4.1-1 [298 kB] 147s Get:60 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-livereload all 2.6.3-2 [24.7 kB] 147s Get:61 http://ftpmaster.internal/ubuntu oracular/main s390x python3-colorama all 0.4.6-4 [32.1 kB] 147s Get:62 http://ftpmaster.internal/ubuntu oracular/main s390x python3-click all 8.1.7-1 [79.1 kB] 147s Get:63 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-joblib all 1.3.2-2 [208 kB] 147s Get:64 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-regex s390x 0.1.20221031-2build1 [298 kB] 147s Get:65 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-tqdm all 4.66.4-1 [91.3 kB] 147s Get:66 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-nltk all 3.8.1-1 [1002 kB] 147s Get:67 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-lunr all 0.7.0-1 [26.8 kB] 147s Get:68 http://ftpmaster.internal/ubuntu oracular/main s390x sphinx-rtd-theme-common all 2.0.0+dfsg-2 [1012 kB] 147s Get:69 http://ftpmaster.internal/ubuntu oracular/main s390x python3-markdown all 3.6-1 [73.4 kB] 147s Get:70 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-mergedeep all 1.3.4-3 [6560 B] 147s Get:71 http://ftpmaster.internal/ubuntu oracular/main s390x python3-packaging all 24.1-1 [41.4 kB] 147s Get:72 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-pathspec all 0.12.1-1 [24.5 kB] 147s Get:73 http://ftpmaster.internal/ubuntu oracular/main s390x python3-platformdirs all 4.2.1-1 [16.3 kB] 147s Get:74 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-pyyaml-env-tag all 0.1-3 [5268 B] 147s Get:75 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-watchdog all 3.0.0-1 [121 kB] 147s Get:76 http://ftpmaster.internal/ubuntu oracular/universe s390x mkdocs all 1.5.3+dfsg-1 [2256 kB] 147s Get:77 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-pyproject-hooks all 1.1.0-2 [9916 B] 147s Get:78 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-toml all 0.10.2-1 [16.5 kB] 147s Get:79 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-wheel all 0.43.0-1 [53.5 kB] 147s Get:80 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-build all 1.2.1-1 [29.7 kB] 147s Get:81 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-installer all 0.7.0+dfsg1-3 [17.4 kB] 147s Get:82 http://ftpmaster.internal/ubuntu oracular/universe s390x pybuild-plugin-pyproject all 6.20240603 [1732 B] 147s Get:83 http://ftpmaster.internal/ubuntu oracular/universe s390x python-uvicorn-doc all 0.30.1-1 [656 kB] 147s Get:84 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-a2wsgi all 1.10.6-2 [13.2 kB] 147s Get:85 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x python3-all s390x 3.12.4-1 [884 B] 147s Get:86 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-sniffio all 1.3.1-1 [7262 B] 147s Get:87 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-anyio all 4.3.0-1 [56.6 kB] 147s Get:88 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-async-generator all 1.10-4 [17.5 kB] 147s Get:89 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-dotenv all 1.0.1-1 [22.3 kB] 147s Get:90 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-h11 all 0.14.0-1 [51.1 kB] 147s Get:91 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-pluggy all 1.5.0-1 [21.0 kB] 147s Get:92 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-trove-classifiers all 2024.4.10-1 [10.6 kB] 147s Get:93 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-hatchling all 1.25.0-1 [54.8 kB] 147s Get:94 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-httpcore all 1.0.4-1 [38.8 kB] 147s Get:95 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-httptools s390x 0.1.1-3build3 [45.7 kB] 147s Get:96 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-httpx all 0.26.0-2 [61.3 kB] 147s Get:97 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-iniconfig all 1.1.1-2 [6024 B] 147s Get:98 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-pymdownx all 10.8.1-1 [150 kB] 147s Get:99 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-pytest all 7.4.4-1 [305 kB] 147s Get:100 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-pytest-asyncio all 0.20.3-1.3 [10.7 kB] 147s Get:101 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-pytest-mock all 3.14.0-1 [11.6 kB] 147s Get:102 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-trustme all 1.1.0-1 [12.0 kB] 147s Get:103 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-wsproto all 1.2.0-1 [23.6 kB] 148s Get:104 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-uvloop s390x 0.19.0+ds1-2.1 [594 kB] 148s Get:105 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-uvicorn all 0.30.1-1 [41.2 kB] 148s Get:106 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-watchfiles s390x 0.21.0-4 [365 kB] 148s Get:107 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-websockets all 10.4-1 [64.0 kB] 148s Get:108 http://ftpmaster.internal/ubuntu oracular/universe s390x uvicorn all 0.30.1-1 [4754 B] 149s Fetched 63.7 MB in 3s (19.1 MB/s) 149s Selecting previously unselected package fonts-lato. 149s (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 ... 54756 files and directories currently installed.) 149s Preparing to unpack .../000-fonts-lato_2.015-1_all.deb ... 149s Unpacking fonts-lato (2.015-1) ... 149s Selecting previously unselected package m4. 149s Preparing to unpack .../001-m4_1.4.19-4build1_s390x.deb ... 149s Unpacking m4 (1.4.19-4build1) ... 149s Selecting previously unselected package autoconf. 149s Preparing to unpack .../002-autoconf_2.71-3_all.deb ... 149s Unpacking autoconf (2.71-3) ... 149s Selecting previously unselected package autotools-dev. 149s Preparing to unpack .../003-autotools-dev_20220109.1_all.deb ... 149s Unpacking autotools-dev (20220109.1) ... 149s Selecting previously unselected package automake. 149s Preparing to unpack .../004-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 149s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 149s Selecting previously unselected package autopoint. 149s Preparing to unpack .../005-autopoint_0.22.5-1_all.deb ... 149s Unpacking autopoint (0.22.5-1) ... 149s Selecting previously unselected package gcc-13-base:s390x. 149s Preparing to unpack .../006-gcc-13-base_13.3.0-3ubuntu1_s390x.deb ... 149s Unpacking gcc-13-base:s390x (13.3.0-3ubuntu1) ... 149s Selecting previously unselected package libisl23:s390x. 149s Preparing to unpack .../007-libisl23_0.26-3build1_s390x.deb ... 149s Unpacking libisl23:s390x (0.26-3build1) ... 149s Selecting previously unselected package libmpc3:s390x. 149s Preparing to unpack .../008-libmpc3_1.3.1-1build1_s390x.deb ... 149s Unpacking libmpc3:s390x (1.3.1-1build1) ... 149s Selecting previously unselected package cpp-13-s390x-linux-gnu. 149s Preparing to unpack .../009-cpp-13-s390x-linux-gnu_13.3.0-3ubuntu1_s390x.deb ... 149s Unpacking cpp-13-s390x-linux-gnu (13.3.0-3ubuntu1) ... 150s Selecting previously unselected package cpp-13. 150s Preparing to unpack .../010-cpp-13_13.3.0-3ubuntu1_s390x.deb ... 150s Unpacking cpp-13 (13.3.0-3ubuntu1) ... 150s Selecting previously unselected package cpp-s390x-linux-gnu. 150s Preparing to unpack .../011-cpp-s390x-linux-gnu_4%3a13.3.0-2ubuntu2_s390x.deb ... 150s Unpacking cpp-s390x-linux-gnu (4:13.3.0-2ubuntu2) ... 150s Selecting previously unselected package cpp. 150s Preparing to unpack .../012-cpp_4%3a13.3.0-2ubuntu2_s390x.deb ... 150s Unpacking cpp (4:13.3.0-2ubuntu2) ... 150s Selecting previously unselected package libcc1-0:s390x. 150s Preparing to unpack .../013-libcc1-0_14.1.0-4ubuntu2_s390x.deb ... 150s Unpacking libcc1-0:s390x (14.1.0-4ubuntu2) ... 150s Selecting previously unselected package libgomp1:s390x. 150s Preparing to unpack .../014-libgomp1_14.1.0-4ubuntu2_s390x.deb ... 150s Unpacking libgomp1:s390x (14.1.0-4ubuntu2) ... 150s Selecting previously unselected package libitm1:s390x. 150s Preparing to unpack .../015-libitm1_14.1.0-4ubuntu2_s390x.deb ... 150s Unpacking libitm1:s390x (14.1.0-4ubuntu2) ... 150s Selecting previously unselected package libatomic1:s390x. 150s Preparing to unpack .../016-libatomic1_14.1.0-4ubuntu2_s390x.deb ... 150s Unpacking libatomic1:s390x (14.1.0-4ubuntu2) ... 150s Selecting previously unselected package libasan8:s390x. 150s Preparing to unpack .../017-libasan8_14.1.0-4ubuntu2_s390x.deb ... 150s Unpacking libasan8:s390x (14.1.0-4ubuntu2) ... 150s Selecting previously unselected package libubsan1:s390x. 150s Preparing to unpack .../018-libubsan1_14.1.0-4ubuntu2_s390x.deb ... 150s Unpacking libubsan1:s390x (14.1.0-4ubuntu2) ... 150s Selecting previously unselected package libgcc-13-dev:s390x. 150s Preparing to unpack .../019-libgcc-13-dev_13.3.0-3ubuntu1_s390x.deb ... 150s Unpacking libgcc-13-dev:s390x (13.3.0-3ubuntu1) ... 150s Selecting previously unselected package gcc-13-s390x-linux-gnu. 150s Preparing to unpack .../020-gcc-13-s390x-linux-gnu_13.3.0-3ubuntu1_s390x.deb ... 150s Unpacking gcc-13-s390x-linux-gnu (13.3.0-3ubuntu1) ... 151s Selecting previously unselected package gcc-13. 151s Preparing to unpack .../021-gcc-13_13.3.0-3ubuntu1_s390x.deb ... 151s Unpacking gcc-13 (13.3.0-3ubuntu1) ... 151s Selecting previously unselected package gcc-s390x-linux-gnu. 151s Preparing to unpack .../022-gcc-s390x-linux-gnu_4%3a13.3.0-2ubuntu2_s390x.deb ... 151s Unpacking gcc-s390x-linux-gnu (4:13.3.0-2ubuntu2) ... 151s Selecting previously unselected package gcc. 151s Preparing to unpack .../023-gcc_4%3a13.3.0-2ubuntu2_s390x.deb ... 151s Unpacking gcc (4:13.3.0-2ubuntu2) ... 151s Selecting previously unselected package libstdc++-13-dev:s390x. 151s Preparing to unpack .../024-libstdc++-13-dev_13.3.0-3ubuntu1_s390x.deb ... 151s Unpacking libstdc++-13-dev:s390x (13.3.0-3ubuntu1) ... 151s Selecting previously unselected package g++-13-s390x-linux-gnu. 151s Preparing to unpack .../025-g++-13-s390x-linux-gnu_13.3.0-3ubuntu1_s390x.deb ... 151s Unpacking g++-13-s390x-linux-gnu (13.3.0-3ubuntu1) ... 151s Selecting previously unselected package g++-13. 151s Preparing to unpack .../026-g++-13_13.3.0-3ubuntu1_s390x.deb ... 151s Unpacking g++-13 (13.3.0-3ubuntu1) ... 151s Selecting previously unselected package g++-s390x-linux-gnu. 151s Preparing to unpack .../027-g++-s390x-linux-gnu_4%3a13.3.0-2ubuntu2_s390x.deb ... 151s Unpacking g++-s390x-linux-gnu (4:13.3.0-2ubuntu2) ... 151s Selecting previously unselected package g++. 151s Preparing to unpack .../028-g++_4%3a13.3.0-2ubuntu2_s390x.deb ... 151s Unpacking g++ (4:13.3.0-2ubuntu2) ... 151s Selecting previously unselected package build-essential. 151s Preparing to unpack .../029-build-essential_12.10ubuntu1_s390x.deb ... 151s Unpacking build-essential (12.10ubuntu1) ... 151s Selecting previously unselected package libdebhelper-perl. 151s Preparing to unpack .../030-libdebhelper-perl_13.16ubuntu2_all.deb ... 151s Unpacking libdebhelper-perl (13.16ubuntu2) ... 152s Selecting previously unselected package libtool. 152s Preparing to unpack .../031-libtool_2.4.7-7build1_all.deb ... 152s Unpacking libtool (2.4.7-7build1) ... 152s Selecting previously unselected package dh-autoreconf. 152s Preparing to unpack .../032-dh-autoreconf_20_all.deb ... 152s Unpacking dh-autoreconf (20) ... 152s Selecting previously unselected package libarchive-zip-perl. 152s Preparing to unpack .../033-libarchive-zip-perl_1.68-1_all.deb ... 152s Unpacking libarchive-zip-perl (1.68-1) ... 152s Selecting previously unselected package libfile-stripnondeterminism-perl. 152s Preparing to unpack .../034-libfile-stripnondeterminism-perl_1.14.0-1_all.deb ... 152s Unpacking libfile-stripnondeterminism-perl (1.14.0-1) ... 152s Selecting previously unselected package dh-strip-nondeterminism. 152s Preparing to unpack .../035-dh-strip-nondeterminism_1.14.0-1_all.deb ... 152s Unpacking dh-strip-nondeterminism (1.14.0-1) ... 152s Selecting previously unselected package debugedit. 152s Preparing to unpack .../036-debugedit_1%3a5.0-6_s390x.deb ... 152s Unpacking debugedit (1:5.0-6) ... 152s Selecting previously unselected package dwz. 152s Preparing to unpack .../037-dwz_0.15-1build6_s390x.deb ... 152s Unpacking dwz (0.15-1build6) ... 152s Selecting previously unselected package gettext. 152s Preparing to unpack .../038-gettext_0.22.5-1_s390x.deb ... 152s Unpacking gettext (0.22.5-1) ... 152s Selecting previously unselected package intltool-debian. 152s Preparing to unpack .../039-intltool-debian_0.35.0+20060710.6_all.deb ... 152s Unpacking intltool-debian (0.35.0+20060710.6) ... 152s Selecting previously unselected package po-debconf. 152s Preparing to unpack .../040-po-debconf_1.0.21+nmu1_all.deb ... 152s Unpacking po-debconf (1.0.21+nmu1) ... 152s Selecting previously unselected package debhelper. 152s Preparing to unpack .../041-debhelper_13.16ubuntu2_all.deb ... 152s Unpacking debhelper (13.16ubuntu2) ... 152s Selecting previously unselected package dh-python. 152s Preparing to unpack .../042-dh-python_6.20240603_all.deb ... 152s Unpacking dh-python (6.20240603) ... 152s Selecting previously unselected package fonts-font-awesome. 152s Preparing to unpack .../043-fonts-font-awesome_5.0.10+really4.7.0~dfsg-4.1_all.deb ... 152s Unpacking fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 152s Selecting previously unselected package python3-dateutil. 152s Preparing to unpack .../044-python3-dateutil_2.9.0-2_all.deb ... 152s Unpacking python3-dateutil (2.9.0-2) ... 152s Selecting previously unselected package ghp-import. 152s Preparing to unpack .../045-ghp-import_2.1.0-3_all.deb ... 152s Unpacking ghp-import (2.1.0-3) ... 152s Selecting previously unselected package help2man. 152s Preparing to unpack .../046-help2man_1.49.3_s390x.deb ... 152s Unpacking help2man (1.49.3) ... 152s Selecting previously unselected package javascript-common. 152s Preparing to unpack .../047-javascript-common_11+nmu1_all.deb ... 152s Unpacking javascript-common (11+nmu1) ... 152s Selecting previously unselected package libhttp-parser2.9:s390x. 152s Preparing to unpack .../048-libhttp-parser2.9_2.9.4-6build1_s390x.deb ... 152s Unpacking libhttp-parser2.9:s390x (2.9.4-6build1) ... 152s Selecting previously unselected package libjs-popper.js. 152s Preparing to unpack .../049-libjs-popper.js_1.16.1+ds-6_all.deb ... 152s Unpacking libjs-popper.js (1.16.1+ds-6) ... 152s Selecting previously unselected package libjs-bootstrap4. 152s Preparing to unpack .../050-libjs-bootstrap4_4.6.1+dfsg1-4_all.deb ... 152s Unpacking libjs-bootstrap4 (4.6.1+dfsg1-4) ... 152s Selecting previously unselected package libjs-highlight.js. 152s Preparing to unpack .../051-libjs-highlight.js_9.18.5+dfsg1-2_all.deb ... 152s Unpacking libjs-highlight.js (9.18.5+dfsg1-2) ... 152s Selecting previously unselected package libjs-jquery. 152s Preparing to unpack .../052-libjs-jquery_3.6.1+dfsg+~3.5.14-1_all.deb ... 152s Unpacking libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 153s Selecting previously unselected package libjs-lunr. 153s Preparing to unpack .../053-libjs-lunr_2.3.9~dfsg-2_all.deb ... 153s Unpacking libjs-lunr (2.3.9~dfsg-2) ... 153s Selecting previously unselected package libjs-underscore. 153s Preparing to unpack .../054-libjs-underscore_1.13.4~dfsg+~1.11.4-3_all.deb ... 153s Unpacking libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 153s Selecting previously unselected package libjs-sphinxdoc. 153s Preparing to unpack .../055-libjs-sphinxdoc_7.3.7-3_all.deb ... 153s Unpacking libjs-sphinxdoc (7.3.7-3) ... 153s Selecting previously unselected package libjs-modernizr. 153s Preparing to unpack .../056-libjs-modernizr_3.13.0-0.1_all.deb ... 153s Unpacking libjs-modernizr (3.13.0-0.1) ... 153s Selecting previously unselected package python3-tornado. 153s Preparing to unpack .../057-python3-tornado_6.4.1-1_s390x.deb ... 153s Unpacking python3-tornado (6.4.1-1) ... 153s Selecting previously unselected package python3-livereload. 153s Preparing to unpack .../058-python3-livereload_2.6.3-2_all.deb ... 153s Unpacking python3-livereload (2.6.3-2) ... 153s Selecting previously unselected package python3-colorama. 153s Preparing to unpack .../059-python3-colorama_0.4.6-4_all.deb ... 153s Unpacking python3-colorama (0.4.6-4) ... 153s Selecting previously unselected package python3-click. 153s Preparing to unpack .../060-python3-click_8.1.7-1_all.deb ... 153s Unpacking python3-click (8.1.7-1) ... 153s Selecting previously unselected package python3-joblib. 153s Preparing to unpack .../061-python3-joblib_1.3.2-2_all.deb ... 153s Unpacking python3-joblib (1.3.2-2) ... 153s Selecting previously unselected package python3-regex. 153s Preparing to unpack .../062-python3-regex_0.1.20221031-2build1_s390x.deb ... 153s Unpacking python3-regex (0.1.20221031-2build1) ... 153s Selecting previously unselected package python3-tqdm. 153s Preparing to unpack .../063-python3-tqdm_4.66.4-1_all.deb ... 153s Unpacking python3-tqdm (4.66.4-1) ... 153s Selecting previously unselected package python3-nltk. 153s Preparing to unpack .../064-python3-nltk_3.8.1-1_all.deb ... 153s Unpacking python3-nltk (3.8.1-1) ... 153s Selecting previously unselected package python3-lunr. 153s Preparing to unpack .../065-python3-lunr_0.7.0-1_all.deb ... 153s Unpacking python3-lunr (0.7.0-1) ... 153s Selecting previously unselected package sphinx-rtd-theme-common. 153s Preparing to unpack .../066-sphinx-rtd-theme-common_2.0.0+dfsg-2_all.deb ... 153s Unpacking sphinx-rtd-theme-common (2.0.0+dfsg-2) ... 153s Selecting previously unselected package python3-markdown. 153s Preparing to unpack .../067-python3-markdown_3.6-1_all.deb ... 153s Unpacking python3-markdown (3.6-1) ... 153s Selecting previously unselected package python3-mergedeep. 153s Preparing to unpack .../068-python3-mergedeep_1.3.4-3_all.deb ... 153s Unpacking python3-mergedeep (1.3.4-3) ... 153s Selecting previously unselected package python3-packaging. 153s Preparing to unpack .../069-python3-packaging_24.1-1_all.deb ... 153s Unpacking python3-packaging (24.1-1) ... 153s Selecting previously unselected package python3-pathspec. 153s Preparing to unpack .../070-python3-pathspec_0.12.1-1_all.deb ... 153s Unpacking python3-pathspec (0.12.1-1) ... 153s Selecting previously unselected package python3-platformdirs. 153s Preparing to unpack .../071-python3-platformdirs_4.2.1-1_all.deb ... 153s Unpacking python3-platformdirs (4.2.1-1) ... 153s Selecting previously unselected package python3-pyyaml-env-tag. 153s Preparing to unpack .../072-python3-pyyaml-env-tag_0.1-3_all.deb ... 153s Unpacking python3-pyyaml-env-tag (0.1-3) ... 153s Selecting previously unselected package python3-watchdog. 153s Preparing to unpack .../073-python3-watchdog_3.0.0-1_all.deb ... 153s Unpacking python3-watchdog (3.0.0-1) ... 153s Selecting previously unselected package mkdocs. 153s Preparing to unpack .../074-mkdocs_1.5.3+dfsg-1_all.deb ... 153s Unpacking mkdocs (1.5.3+dfsg-1) ... 153s Selecting previously unselected package python3-pyproject-hooks. 153s Preparing to unpack .../075-python3-pyproject-hooks_1.1.0-2_all.deb ... 153s Unpacking python3-pyproject-hooks (1.1.0-2) ... 153s Selecting previously unselected package python3-toml. 153s Preparing to unpack .../076-python3-toml_0.10.2-1_all.deb ... 153s Unpacking python3-toml (0.10.2-1) ... 153s Selecting previously unselected package python3-wheel. 153s Preparing to unpack .../077-python3-wheel_0.43.0-1_all.deb ... 153s Unpacking python3-wheel (0.43.0-1) ... 154s Selecting previously unselected package python3-build. 154s Preparing to unpack .../078-python3-build_1.2.1-1_all.deb ... 154s Unpacking python3-build (1.2.1-1) ... 154s Selecting previously unselected package python3-installer. 154s Preparing to unpack .../079-python3-installer_0.7.0+dfsg1-3_all.deb ... 154s Unpacking python3-installer (0.7.0+dfsg1-3) ... 154s Selecting previously unselected package pybuild-plugin-pyproject. 154s Preparing to unpack .../080-pybuild-plugin-pyproject_6.20240603_all.deb ... 154s Unpacking pybuild-plugin-pyproject (6.20240603) ... 154s Selecting previously unselected package python-uvicorn-doc. 154s Preparing to unpack .../081-python-uvicorn-doc_0.30.1-1_all.deb ... 154s Unpacking python-uvicorn-doc (0.30.1-1) ... 154s Selecting previously unselected package python3-a2wsgi. 154s Preparing to unpack .../082-python3-a2wsgi_1.10.6-2_all.deb ... 154s Unpacking python3-a2wsgi (1.10.6-2) ... 154s Selecting previously unselected package python3-all. 154s Preparing to unpack .../083-python3-all_3.12.4-1_s390x.deb ... 154s Unpacking python3-all (3.12.4-1) ... 154s Selecting previously unselected package python3-sniffio. 154s Preparing to unpack .../084-python3-sniffio_1.3.1-1_all.deb ... 154s Unpacking python3-sniffio (1.3.1-1) ... 154s Selecting previously unselected package python3-anyio. 154s Preparing to unpack .../085-python3-anyio_4.3.0-1_all.deb ... 154s Unpacking python3-anyio (4.3.0-1) ... 154s Selecting previously unselected package python3-async-generator. 154s Preparing to unpack .../086-python3-async-generator_1.10-4_all.deb ... 154s Unpacking python3-async-generator (1.10-4) ... 154s Selecting previously unselected package python3-dotenv. 154s Preparing to unpack .../087-python3-dotenv_1.0.1-1_all.deb ... 154s Unpacking python3-dotenv (1.0.1-1) ... 154s Selecting previously unselected package python3-h11. 154s Preparing to unpack .../088-python3-h11_0.14.0-1_all.deb ... 154s Unpacking python3-h11 (0.14.0-1) ... 154s Selecting previously unselected package python3-pluggy. 154s Preparing to unpack .../089-python3-pluggy_1.5.0-1_all.deb ... 154s Unpacking python3-pluggy (1.5.0-1) ... 154s Selecting previously unselected package python3-trove-classifiers. 154s Preparing to unpack .../090-python3-trove-classifiers_2024.4.10-1_all.deb ... 154s Unpacking python3-trove-classifiers (2024.4.10-1) ... 154s Selecting previously unselected package python3-hatchling. 154s Preparing to unpack .../091-python3-hatchling_1.25.0-1_all.deb ... 154s Unpacking python3-hatchling (1.25.0-1) ... 154s Selecting previously unselected package python3-httpcore. 154s Preparing to unpack .../092-python3-httpcore_1.0.4-1_all.deb ... 154s Unpacking python3-httpcore (1.0.4-1) ... 154s Selecting previously unselected package python3-httptools. 154s Preparing to unpack .../093-python3-httptools_0.1.1-3build3_s390x.deb ... 154s Unpacking python3-httptools (0.1.1-3build3) ... 154s Selecting previously unselected package python3-httpx. 154s Preparing to unpack .../094-python3-httpx_0.26.0-2_all.deb ... 154s Unpacking python3-httpx (0.26.0-2) ... 154s Selecting previously unselected package python3-iniconfig. 154s Preparing to unpack .../095-python3-iniconfig_1.1.1-2_all.deb ... 154s Unpacking python3-iniconfig (1.1.1-2) ... 154s Selecting previously unselected package python3-pymdownx. 154s Preparing to unpack .../096-python3-pymdownx_10.8.1-1_all.deb ... 154s Unpacking python3-pymdownx (10.8.1-1) ... 154s Selecting previously unselected package python3-pytest. 154s Preparing to unpack .../097-python3-pytest_7.4.4-1_all.deb ... 154s Unpacking python3-pytest (7.4.4-1) ... 154s Selecting previously unselected package python3-pytest-asyncio. 154s Preparing to unpack .../098-python3-pytest-asyncio_0.20.3-1.3_all.deb ... 154s Unpacking python3-pytest-asyncio (0.20.3-1.3) ... 154s Selecting previously unselected package python3-pytest-mock. 154s Preparing to unpack .../099-python3-pytest-mock_3.14.0-1_all.deb ... 154s Unpacking python3-pytest-mock (3.14.0-1) ... 154s Selecting previously unselected package python3-trustme. 154s Preparing to unpack .../100-python3-trustme_1.1.0-1_all.deb ... 154s Unpacking python3-trustme (1.1.0-1) ... 154s Selecting previously unselected package python3-wsproto. 154s Preparing to unpack .../101-python3-wsproto_1.2.0-1_all.deb ... 154s Unpacking python3-wsproto (1.2.0-1) ... 155s Selecting previously unselected package python3-uvloop. 155s Preparing to unpack .../102-python3-uvloop_0.19.0+ds1-2.1_s390x.deb ... 155s Unpacking python3-uvloop (0.19.0+ds1-2.1) ... 155s Selecting previously unselected package python3-uvicorn. 155s Preparing to unpack .../103-python3-uvicorn_0.30.1-1_all.deb ... 155s Unpacking python3-uvicorn (0.30.1-1) ... 155s Selecting previously unselected package python3-watchfiles. 155s Preparing to unpack .../104-python3-watchfiles_0.21.0-4_s390x.deb ... 155s Unpacking python3-watchfiles (0.21.0-4) ... 155s Selecting previously unselected package python3-websockets. 155s Preparing to unpack .../105-python3-websockets_10.4-1_all.deb ... 155s Unpacking python3-websockets (10.4-1) ... 155s Selecting previously unselected package uvicorn. 155s Preparing to unpack .../106-uvicorn_0.30.1-1_all.deb ... 155s Unpacking uvicorn (0.30.1-1) ... 155s Selecting previously unselected package autopkgtest-satdep. 155s Preparing to unpack .../107-1-autopkgtest-satdep.deb ... 155s Unpacking autopkgtest-satdep (0) ... 155s Setting up python3-dotenv (1.0.1-1) ... 155s Setting up dh-python (6.20240603) ... 155s Setting up javascript-common (11+nmu1) ... 155s Setting up python3-iniconfig (1.1.1-2) ... 156s Setting up python3-sniffio (1.3.1-1) ... 156s Setting up python3-tornado (6.4.1-1) ... 157s Setting up fonts-lato (2.015-1) ... 157s Setting up python3-async-generator (1.10-4) ... 157s Setting up python3-anyio (4.3.0-1) ... 157s Setting up python3-joblib (1.3.2-2) ... 158s Setting up libjs-popper.js (1.16.1+ds-6) ... 158s Setting up python3-colorama (0.4.6-4) ... 158s Setting up libarchive-zip-perl (1.68-1) ... 158s Setting up libjs-lunr (2.3.9~dfsg-2) ... 158s Setting up libdebhelper-perl (13.16ubuntu2) ... 158s Setting up python3-tqdm (4.66.4-1) ... 158s Setting up m4 (1.4.19-4build1) ... 158s Setting up python3-watchfiles (0.21.0-4) ... 158s Setting up python3-all (3.12.4-1) ... 158s Setting up python3-trustme (1.1.0-1) ... 158s Setting up libgomp1:s390x (14.1.0-4ubuntu2) ... 158s Setting up python3-click (8.1.7-1) ... 159s Setting up libjs-modernizr (3.13.0-0.1) ... 159s Setting up python3-wheel (0.43.0-1) ... 159s Setting up python3-platformdirs (4.2.1-1) ... 159s Setting up python3-pyyaml-env-tag (0.1-3) ... 159s Setting up help2man (1.49.3) ... 159s Setting up python3-a2wsgi (1.10.6-2) ... 159s Setting up autotools-dev (20220109.1) ... 159s Setting up python3-packaging (24.1-1) ... 160s Setting up gcc-13-base:s390x (13.3.0-3ubuntu1) ... 160s Setting up python3-pyproject-hooks (1.1.0-2) ... 160s Setting up libmpc3:s390x (1.3.1-1build1) ... 160s Setting up libatomic1:s390x (14.1.0-4ubuntu2) ... 160s Setting up python3-markdown (3.6-1) ... 160s Setting up python3-h11 (0.14.0-1) ... 160s Setting up autopoint (0.22.5-1) ... 160s Setting up python3-mergedeep (1.3.4-3) ... 161s Setting up python3-regex (0.1.20221031-2build1) ... 161s Setting up python3-toml (0.10.2-1) ... 161s Setting up python3-uvloop (0.19.0+ds1-2.1) ... 162s Setting up python3-installer (0.7.0+dfsg1-3) ... 162s Setting up autoconf (2.71-3) ... 162s Setting up python3-pluggy (1.5.0-1) ... 162s Setting up libubsan1:s390x (14.1.0-4ubuntu2) ... 162s Setting up dwz (0.15-1build6) ... 162s Setting up libjs-highlight.js (9.18.5+dfsg1-2) ... 162s Setting up python3-trove-classifiers (2024.4.10-1) ... 162s Setting up python3-livereload (2.6.3-2) ... 162s Setting up libasan8:s390x (14.1.0-4ubuntu2) ... 162s Setting up debugedit (1:5.0-6) ... 162s Setting up python3-dateutil (2.9.0-2) ... 163s Setting up libjs-bootstrap4 (4.6.1+dfsg1-4) ... 163s Setting up libjs-jquery (3.6.1+dfsg+~3.5.14-1) ... 163s Setting up libisl23:s390x (0.26-3build1) ... 163s Setting up python3-build (1.2.1-1) ... 163s Setting up python3-pathspec (0.12.1-1) ... 163s Setting up python3-nltk (3.8.1-1) ... 165s Setting up fonts-font-awesome (5.0.10+really4.7.0~dfsg-4.1) ... 165s Setting up sphinx-rtd-theme-common (2.0.0+dfsg-2) ... 165s Setting up libcc1-0:s390x (14.1.0-4ubuntu2) ... 165s Setting up libitm1:s390x (14.1.0-4ubuntu2) ... 165s Setting up libhttp-parser2.9:s390x (2.9.4-6build1) ... 165s Setting up libjs-underscore (1.13.4~dfsg+~1.11.4-3) ... 165s Setting up python3-lunr (0.7.0-1) ... 165s Setting up python3-websockets (10.4-1) ... 165s Setting up automake (1:1.16.5-1.3ubuntu1) ... 165s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 165s Setting up libfile-stripnondeterminism-perl (1.14.0-1) ... 165s Setting up python3-httpcore (1.0.4-1) ... 165s Setting up gettext (0.22.5-1) ... 165s Setting up python3-wsproto (1.2.0-1) ... 166s Setting up pybuild-plugin-pyproject (6.20240603) ... 166s Setting up python3-pytest (7.4.4-1) ... 166s Setting up python3-pymdownx (10.8.1-1) ... 167s Setting up intltool-debian (0.35.0+20060710.6) ... 167s Setting up cpp-13-s390x-linux-gnu (13.3.0-3ubuntu1) ... 167s Setting up python3-httpx (0.26.0-2) ... 167s Setting up libjs-sphinxdoc (7.3.7-3) ... 167s Setting up python3-pytest-asyncio (0.20.3-1.3) ... 167s Setting up dh-strip-nondeterminism (1.14.0-1) ... 167s Setting up ghp-import (2.1.0-3) ... 168s Setting up python3-pytest-mock (3.14.0-1) ... 168s Setting up libgcc-13-dev:s390x (13.3.0-3ubuntu1) ... 168s Setting up python3-httptools (0.1.1-3build3) ... 168s Setting up python3-hatchling (1.25.0-1) ... 168s Setting up libstdc++-13-dev:s390x (13.3.0-3ubuntu1) ... 168s Setting up python3-uvicorn (0.30.1-1) ... 168s Setting up cpp-13 (13.3.0-3ubuntu1) ... 168s Setting up cpp-s390x-linux-gnu (4:13.3.0-2ubuntu2) ... 168s Setting up python3-watchdog (3.0.0-1) ... 169s Setting up po-debconf (1.0.21+nmu1) ... 169s Setting up gcc-13-s390x-linux-gnu (13.3.0-3ubuntu1) ... 169s Setting up gcc-s390x-linux-gnu (4:13.3.0-2ubuntu2) ... 169s Setting up g++-13-s390x-linux-gnu (13.3.0-3ubuntu1) ... 169s Setting up gcc-13 (13.3.0-3ubuntu1) ... 169s Setting up cpp (4:13.3.0-2ubuntu2) ... 169s Setting up mkdocs (1.5.3+dfsg-1) ... 169s Setting up g++-13 (13.3.0-3ubuntu1) ... 169s Setting up uvicorn (0.30.1-1) ... 169s Setting up libtool (2.4.7-7build1) ... 169s Setting up g++-s390x-linux-gnu (4:13.3.0-2ubuntu2) ... 169s Setting up gcc (4:13.3.0-2ubuntu2) ... 169s Setting up dh-autoreconf (20) ... 169s Setting up g++ (4:13.3.0-2ubuntu2) ... 169s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 169s Setting up python-uvicorn-doc (0.30.1-1) ... 169s Setting up build-essential (12.10ubuntu1) ... 169s Setting up debhelper (13.16ubuntu2) ... 169s Setting up autopkgtest-satdep (0) ... 169s Processing triggers for man-db (2.12.1-2) ... 170s Processing triggers for install-info (7.1-3build2) ... 170s Processing triggers for libc-bin (2.39-0ubuntu9) ... 175s (Reading database ... 60144 files and directories currently installed.) 175s Removing autopkgtest-satdep (0) ... 176s autopkgtest [05:27:16]: test unittests: [----------------------- 176s === python3.12 === 177s ============================= test session starts ============================== 177s platform linux -- Python 3.12.4, pytest-7.4.4, pluggy-1.5.0 -- /usr/bin/python3.12 177s cachedir: .pytest_cache 177s rootdir: /tmp/autopkgtest.OYyCaA/autopkgtest_tmp 177s plugins: anyio-4.3.0, asyncio-0.20.3, mock-3.14.0 177s asyncio: mode=Mode.STRICT 177s collecting ... collected 401 items / 26 deselected / 375 selected 177s 177s tests/test_auto_detection.py::test_loop_auto PASSED 177s tests/test_auto_detection.py::test_http_auto PASSED 177s tests/test_cli.py::test_cli_print_version PASSED 177s tests/test_cli.py::test_cli_headers PASSED 177s tests/test_cli.py::test_cli_call_server_run PASSED 177s tests/test_cli.py::test_cli_call_change_reload_run PASSED 177s tests/test_cli.py::test_cli_call_multiprocess_run PASSED 177s tests/test_cli.py::test_cli_uds[True] PASSED 177s tests/test_cli.py::test_cli_uds[False] PASSED 177s tests/test_cli.py::test_cli_incomplete_app_parameter PASSED 177s tests/test_cli.py::test_cli_event_size PASSED 177s tests/test_cli.py::test_env_variables[h11] PASSED 177s tests/test_cli.py::test_env_variables[httptools] PASSED 177s tests/test_cli.py::test_ignore_environment_variable_when_set_on_cli PASSED 177s tests/test_cli.py::test_app_dir PASSED 177s tests/test_cli.py::test_set_app_via_environment_variable PASSED 177s tests/test_config.py::test_config_should_reload_is_set[asgi_app-False] PASSED 177s tests/test_config.py::test_config_should_reload_is_set[tests.test_config:asgi_app-True] INFO: Will watch for changes in these directories: ['/tmp/autopkgtest.OYyCaA/autopkgtest_tmp'] 177s PASSED 177s tests/test_config.py::test_should_warn_on_invalid_reload_configuration WARNING: Current configuration will not reload as not all conditions are met, please refer to documentation. 177s WARNING: Current configuration will not reload as not all conditions are met, please refer to documentation. 177s PASSED 177s tests/test_config.py::test_reload_dir_is_set INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory0/app'] 177s INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory0/app'] 177s PASSED 177s tests/test_config.py::test_non_existant_reload_dir_is_not_set WARNING: Provided reload directories ['reload'] did not contain valid directories, watching current working directory. 177s INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory0'] 177s PASSED 177s tests/test_config.py::test_reload_subdir_removal INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory0'] 177s PASSED 177s tests/test_config.py::test_reload_included_dir_is_added_to_reload_dirs INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory0/app', '/tmp/pytest-of-ubuntu/pytest-0/reload_directory0/ext'] 177s PASSED 177s tests/test_config.py::test_reload_dir_subdirectories_are_removed INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory0/app', '/tmp/pytest-of-ubuntu/pytest-0/reload_directory0/ext'] 177s PASSED 177s tests/test_config.py::test_reload_excluded_subdirectories_are_removed INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory0'] 177s PASSED 177s tests/test_config.py::test_reload_includes_exclude_dir_patterns_are_matched INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory0/app_first/src', '/tmp/pytest-of-ubuntu/pytest-0/reload_directory0/app_second/src'] 177s PASSED 177s tests/test_config.py::test_wsgi_app PASSED 177s tests/test_config.py::test_proxy_headers PASSED 177s tests/test_config.py::test_app_unimportable_module PASSED 177s tests/test_config.py::test_app_unimportable_other ERROR: Error loading ASGI app. Attribute "app" not found in module "tests.test_config". 177s PASSED 177s tests/test_config.py::test_app_factory WARNING: ASGI app factory detected. Using it, but please consider setting the --factory flag explicitly. 177s ERROR: Error loading ASGI app factory: asgi_app() missing 3 required positional arguments: 'scope', 'receive', and 'send' 177s PASSED 177s tests/test_config.py::test_concrete_http_class PASSED 177s tests/test_config.py::test_socket_bind INFO: Uvicorn running on http://127.0.0.1:8000 (Press CTRL+C to quit) 177s PASSED 177s tests/test_config.py::test_ssl_config PASSED 177s tests/test_config.py::test_ssl_config_combined PASSED 178s tests/test_config.py::test_asgi_version[asgi_app-3.0] PASSED 178s tests/test_config.py::test_asgi_version[asgi2_app-2.0] PASSED 178s tests/test_config.py::test_log_config_default[use_colors_not_provided] PASSED 178s tests/test_config.py::test_log_config_default[use_colors_invalid_value] PASSED 178s tests/test_config.py::test_log_config_default[use_colors_enabled] PASSED 178s tests/test_config.py::test_log_config_default[use_colors_disabled] PASSED 178s tests/test_config.py::test_log_config_json PASSED 178s tests/test_config.py::test_log_config_yaml[log_config.yml] PASSED 178s tests/test_config.py::test_log_config_yaml[log_config.yaml] PASSED 178s tests/test_config.py::test_log_config_file[log_config.ini] PASSED 178s tests/test_config.py::test_log_config_file[config_file1] PASSED 178s tests/test_config.py::test_log_config_file[config_file2] PASSED 178s tests/test_config.py::test_env_file[0-127.0.0.1] INFO: Loading environment from '/tmp/pytest-of-ubuntu/pytest-0/test_env_file_0_127_0_0_1_0/.env' 178s PASSED 178s tests/test_config.py::test_env_file[0-127.0.0.2] INFO: Loading environment from '/tmp/pytest-of-ubuntu/pytest-0/test_env_file_0_127_0_0_2_0/.env' 178s PASSED 178s tests/test_config.py::test_env_file[1-127.0.0.1] INFO: Loading environment from '/tmp/pytest-of-ubuntu/pytest-0/test_env_file_1_127_0_0_1_0/.env' 178s PASSED 178s tests/test_config.py::test_env_file[1-127.0.0.2] INFO: Loading environment from '/tmp/pytest-of-ubuntu/pytest-0/test_env_file_1_127_0_0_2_0/.env' 178s PASSED 178s tests/test_config.py::test_config_access_log[access log enabled should have single handler] PASSED 178s tests/test_config.py::test_config_access_log[access log disabled shouldn't have handlers] PASSED 178s tests/test_config.py::test_config_log_level[5] PASSED 178s tests/test_config.py::test_config_log_level[10] PASSED 178s tests/test_config.py::test_config_log_level[20] PASSED 178s tests/test_config.py::test_config_log_level[30] PASSED 178s tests/test_config.py::test_config_log_level[40] PASSED 178s tests/test_config.py::test_config_log_level[50] PASSED 178s tests/test_config.py::test_config_log_effective_level[0-None] PASSED 178s tests/test_config.py::test_config_log_effective_level[0-0] PASSED 178s tests/test_config.py::test_config_log_effective_level[0-5] PASSED 178s tests/test_config.py::test_config_log_effective_level[0-10] PASSED 178s tests/test_config.py::test_config_log_effective_level[0-20] PASSED 178s tests/test_config.py::test_config_log_effective_level[0-30] PASSED 178s tests/test_config.py::test_config_log_effective_level[0-40] PASSED 178s tests/test_config.py::test_config_log_effective_level[0-50] PASSED 178s tests/test_config.py::test_config_log_effective_level[5-None] PASSED 178s tests/test_config.py::test_config_log_effective_level[5-0] PASSED 178s tests/test_config.py::test_config_log_effective_level[5-5] PASSED 178s tests/test_config.py::test_config_log_effective_level[5-10] PASSED 178s tests/test_config.py::test_config_log_effective_level[5-20] PASSED 178s tests/test_config.py::test_config_log_effective_level[5-30] PASSED 178s tests/test_config.py::test_config_log_effective_level[5-40] PASSED 178s tests/test_config.py::test_config_log_effective_level[5-50] PASSED 178s tests/test_config.py::test_config_log_effective_level[10-None] PASSED 178s tests/test_config.py::test_config_log_effective_level[10-0] PASSED 178s tests/test_config.py::test_config_log_effective_level[10-5] PASSED 178s tests/test_config.py::test_config_log_effective_level[10-10] PASSED 178s tests/test_config.py::test_config_log_effective_level[10-20] PASSED 178s tests/test_config.py::test_config_log_effective_level[10-30] PASSED 178s tests/test_config.py::test_config_log_effective_level[10-40] PASSED 178s tests/test_config.py::test_config_log_effective_level[10-50] PASSED 178s tests/test_config.py::test_config_log_effective_level[20-None] PASSED 178s tests/test_config.py::test_config_log_effective_level[20-0] PASSED 178s tests/test_config.py::test_config_log_effective_level[20-5] PASSED 178s tests/test_config.py::test_config_log_effective_level[20-10] PASSED 178s tests/test_config.py::test_config_log_effective_level[20-20] PASSED 178s tests/test_config.py::test_config_log_effective_level[20-30] PASSED 178s tests/test_config.py::test_config_log_effective_level[20-40] PASSED 178s tests/test_config.py::test_config_log_effective_level[20-50] PASSED 178s tests/test_config.py::test_config_log_effective_level[30-None] PASSED 178s tests/test_config.py::test_config_log_effective_level[30-0] PASSED 178s tests/test_config.py::test_config_log_effective_level[30-5] PASSED 178s tests/test_config.py::test_config_log_effective_level[30-10] PASSED 178s tests/test_config.py::test_config_log_effective_level[30-20] PASSED 178s tests/test_config.py::test_config_log_effective_level[30-30] PASSED 178s tests/test_config.py::test_config_log_effective_level[30-40] PASSED 178s tests/test_config.py::test_config_log_effective_level[30-50] PASSED 178s tests/test_config.py::test_config_log_effective_level[40-None] PASSED 178s tests/test_config.py::test_config_log_effective_level[40-0] PASSED 178s tests/test_config.py::test_config_log_effective_level[40-5] PASSED 178s tests/test_config.py::test_config_log_effective_level[40-10] PASSED 178s tests/test_config.py::test_config_log_effective_level[40-20] PASSED 178s tests/test_config.py::test_config_log_effective_level[40-30] PASSED 178s tests/test_config.py::test_config_log_effective_level[40-40] PASSED 178s tests/test_config.py::test_config_log_effective_level[40-50] PASSED 178s tests/test_config.py::test_config_log_effective_level[50-None] PASSED 178s tests/test_config.py::test_config_log_effective_level[50-0] PASSED 178s tests/test_config.py::test_config_log_effective_level[50-5] PASSED 178s tests/test_config.py::test_config_log_effective_level[50-10] PASSED 178s tests/test_config.py::test_config_log_effective_level[50-20] PASSED 178s tests/test_config.py::test_config_log_effective_level[50-30] PASSED 178s tests/test_config.py::test_config_log_effective_level[50-40] PASSED 178s tests/test_config.py::test_config_log_effective_level[50-50] PASSED 178s tests/test_config.py::test_ws_max_size PASSED 178s tests/test_config.py::test_ws_max_queue PASSED 178s tests/test_config.py::test_bind_unix_socket_works_with_reload_or_workers[--reload=True --workers=1] INFO: Uvicorn running on unix socket /tmp/pytest-of-ubuntu/pytest-0/p-y2knry4s-5198cba4e6fe9481556184fc90a4ef02/my.sock (Press CTRL+C to quit) 178s PASSED 178s tests/test_config.py::test_bind_unix_socket_works_with_reload_or_workers[--reload=False --workers=2] INFO: Uvicorn running on unix socket /tmp/pytest-of-ubuntu/pytest-0/p-3yuis_f7-5198cba4e6fe9481556184fc90a4ef02/my.sock (Press CTRL+C to quit) 178s PASSED 178s tests/test_config.py::test_bind_fd_works_with_reload_or_workers[--reload=True --workers=1] INFO: Uvicorn running on socket (Press CTRL+C to quit) 178s PASSED 178s tests/test_config.py::test_bind_fd_works_with_reload_or_workers[--reload=False --workers=2] INFO: Uvicorn running on socket (Press CTRL+C to quit) 178s PASSED 178s tests/test_config.py::test_config_use_subprocess[--reload=True --workers=1] PASSED 178s tests/test_config.py::test_config_use_subprocess[--reload=False --workers=2] PASSED 178s tests/test_config.py::test_config_use_subprocess[--reload=False --workers=1] PASSED 178s tests/test_config.py::test_warn_when_using_reload_and_workers WARNING: "workers" flag is ignored when reloading is enabled. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_on INFO: Waiting for application startup. 178s INFO: Application startup complete. 178s INFO: Waiting for application shutdown. 178s INFO: Application shutdown complete. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_off PASSED 178s tests/test_lifespan.py::test_lifespan_auto INFO: Waiting for application startup. 178s INFO: Application startup complete. 178s INFO: Waiting for application shutdown. 178s INFO: Application shutdown complete. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_auto_with_error INFO: Waiting for application startup. 178s INFO: ASGI 'lifespan' protocol appears unsupported. 178s INFO: Application startup complete. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_on_with_error INFO: Waiting for application startup. 178s ERROR: Exception in 'lifespan' protocol 178s Traceback (most recent call last): 178s File "/usr/lib/python3/dist-packages/uvicorn/lifespan/on.py", line 86, in main 178s await app(scope, self.receive, self.send) 178s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 178s return await self.app(scope, receive, send) 178s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 178s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/test_lifespan.py", line 113, in app 178s raise RuntimeError() 178s RuntimeError 178s ERROR: Application startup failed. Exiting. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_with_failed_startup[True-auto] INFO: Waiting for application startup. 178s ERROR: the lifespan event failed 178s ERROR: Application startup failed. Exiting. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_with_failed_startup[True-on] INFO: Waiting for application startup. 178s ERROR: the lifespan event failed 178s ERROR: Application startup failed. Exiting. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_with_failed_startup[False-auto] INFO: Waiting for application startup. 178s ERROR: the lifespan event failed 178s ERROR: Application startup failed. Exiting. 178s INFO: Waiting for application shutdown. 178s INFO: Application shutdown complete. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_with_failed_startup[False-on] INFO: Waiting for application startup. 178s ERROR: the lifespan event failed 178s ERROR: Application startup failed. Exiting. 178s INFO: Waiting for application shutdown. 178s INFO: Application shutdown complete. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_scope_asgi3app INFO: Waiting for application startup. 178s INFO: Application startup complete. 178s INFO: Waiting for application shutdown. 178s INFO: Application shutdown complete. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_scope_asgi2app INFO: Waiting for application startup. 178s INFO: Application startup complete. 178s INFO: Waiting for application shutdown. 178s INFO: Application shutdown complete. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_with_failed_shutdown[True-auto] INFO: Waiting for application startup. 178s INFO: Application startup complete. 178s INFO: Waiting for application shutdown. 178s ERROR: the lifespan event failed 178s ERROR: Application shutdown failed. Exiting. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_with_failed_shutdown[True-on] INFO: Waiting for application startup. 178s INFO: Application startup complete. 178s INFO: Waiting for application shutdown. 178s ERROR: the lifespan event failed 178s ERROR: Application shutdown failed. Exiting. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_with_failed_shutdown[False-auto] INFO: Waiting for application startup. 178s INFO: Application startup complete. 178s INFO: Waiting for application shutdown. 178s ERROR: the lifespan event failed 178s ERROR: Application shutdown failed. Exiting. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_with_failed_shutdown[False-on] INFO: Waiting for application startup. 178s INFO: Application startup complete. 178s INFO: Waiting for application shutdown. 178s ERROR: the lifespan event failed 178s ERROR: Application shutdown failed. Exiting. 178s PASSED 178s tests/test_lifespan.py::test_lifespan_state INFO: Waiting for application startup. 178s INFO: Application startup complete. 178s INFO: Waiting for application shutdown. 178s INFO: Application shutdown complete. 178s PASSED 178s tests/test_main.py::test_exit_on_create_server_with_invalid_host INFO: Started server process [3747] 178s INFO: Waiting for application startup. 178s INFO: ASGI 'lifespan' protocol appears unsupported. 178s INFO: Application startup complete. 178s ERROR: [Errno -3] Temporary failure in name resolution 178s PASSED 178s tests/test_server.py::test_server_interrupt[capture_signal_sync-15] INFO: Started server process [3747] 178s INFO: Waiting for application startup. 178s INFO: Application startup complete. 178s INFO: Uvicorn running on http://127.0.0.1:8000 (Press CTRL+C to quit) 178s INFO: Shutting down 178s INFO: Waiting for application shutdown. 178s INFO: Application shutdown complete. 178s INFO: Finished server process [3747] 178s PASSED 178s tests/test_server.py::test_server_interrupt[capture_signal_sync-2] INFO: Started server process [3747] 178s INFO: Waiting for application startup. 178s INFO: Application startup complete. 178s INFO: Uvicorn running on http://127.0.0.1:8000 (Press CTRL+C to quit) 178s INFO: Shutting down 178s INFO: Waiting for application shutdown. 178s INFO: Application shutdown complete. 178s INFO: Finished server process [3747] 178s PASSED 178s tests/test_server.py::test_server_interrupt[capture_signal_async-15] INFO: Started server process [3747] 178s INFO: Waiting for application startup. 178s INFO: Application startup complete. 178s INFO: Uvicorn running on http://127.0.0.1:8000 (Press CTRL+C to quit) 178s INFO: Shutting down 178s INFO: Waiting for application shutdown. 178s INFO: Application shutdown complete. 178s INFO: Finished server process [3747] 178s PASSED 178s tests/test_server.py::test_server_interrupt[capture_signal_async-2] INFO: Started server process [3747] 178s INFO: Waiting for application startup. 178s INFO: Application startup complete. 178s INFO: Uvicorn running on http://127.0.0.1:8000 (Press CTRL+C to quit) 179s INFO: Shutting down 179s INFO: Waiting for application shutdown. 179s INFO: Application shutdown complete. 179s INFO: Finished server process [3747] 179s PASSED 179s tests/test_subprocess.py::test_get_subprocess PASSED 179s tests/test_subprocess.py::test_subprocess_started PASSED 179s tests/importer/test_importer.py::test_invalid_format PASSED 179s tests/importer/test_importer.py::test_invalid_module PASSED 179s tests/importer/test_importer.py::test_invalid_attr PASSED 179s tests/importer/test_importer.py::test_internal_import_error PASSED 179s tests/importer/test_importer.py::test_valid_import PASSED 179s tests/importer/test_importer.py::test_no_import_needed PASSED 179s tests/importer/test_importer.py::test_circular_import_error PASSED 179s tests/middleware/test_logging.py::test_access_logging[True] INFO: Started server process [3747] 179s INFO: Waiting for application startup. 179s INFO: ASGI 'lifespan' protocol appears unsupported. 179s INFO: Application startup complete. 179s INFO: Uvicorn running on http://127.0.0.1:57733 (Press CTRL+C to quit) 179s INFO: 127.0.0.1:46396 - "GET / HTTP/1.1" 204 No Content 179s INFO: Shutting down 179s PASSED 179s tests/middleware/test_logging.py::test_access_logging[False] INFO: Started server process [3747] 179s INFO: Waiting for application startup. 179s INFO: ASGI 'lifespan' protocol appears unsupported. 179s INFO: Application startup complete. 179s INFO: Uvicorn running on http://127.0.0.1:37735 (Press CTRL+C to quit) 179s INFO: 127.0.0.1:46736 - "GET / HTTP/1.1" 204 No Content 179s INFO: Shutting down 179s PASSED 179s tests/middleware/test_logging.py::test_access_logging[None] INFO: Started server process [3747] 179s INFO: Waiting for application startup. 179s INFO: ASGI 'lifespan' protocol appears unsupported. 179s INFO: Application startup complete. 179s INFO: Uvicorn running on http://127.0.0.1:39153 (Press CTRL+C to quit) 179s INFO: 127.0.0.1:54988 - "GET / HTTP/1.1" 204 No Content 179s INFO: Shutting down 179s PASSED 179s tests/middleware/test_logging.py::test_default_logging[True] INFO: Started server process [3747] 179s INFO: Waiting for application startup. 179s INFO: ASGI 'lifespan' protocol appears unsupported. 179s INFO: Application startup complete. 179s INFO: Uvicorn running on http://127.0.0.1:56133 (Press CTRL+C to quit) 180s INFO: 127.0.0.1:47868 - "GET / HTTP/1.1" 204 No Content 180s INFO: Shutting down 180s PASSED 180s tests/middleware/test_logging.py::test_default_logging[False] INFO: Started server process [3747] 180s INFO: Waiting for application startup. 180s INFO: ASGI 'lifespan' protocol appears unsupported. 180s INFO: Application startup complete. 180s INFO: Uvicorn running on http://127.0.0.1:41809 (Press CTRL+C to quit) 180s INFO: 127.0.0.1:60906 - "GET / HTTP/1.1" 204 No Content 180s INFO: Shutting down 180s PASSED 180s tests/middleware/test_logging.py::test_unknown_status_code INFO: Started server process [3747] 180s INFO: Waiting for application startup. 180s INFO: ASGI 'lifespan' protocol appears unsupported. 180s INFO: Application startup complete. 180s INFO: Uvicorn running on http://127.0.0.1:56063 (Press CTRL+C to quit) 180s INFO: 127.0.0.1:46208 - "GET / HTTP/1.1" 599 180s INFO: Shutting down 180s PASSED 180s tests/middleware/test_logging.py::test_server_start_with_port_zero INFO: Started server process [3747] 180s INFO: Waiting for application startup. 180s INFO: ASGI 'lifespan' protocol appears unsupported. 180s INFO: Application startup complete. 180s INFO: Uvicorn running on http://127.0.0.1:33359 (Press CTRL+C to quit) 180s INFO: Shutting down 180s PASSED 180s tests/middleware/test_message_logger.py::test_message_logger PASSED 180s tests/middleware/test_message_logger.py::test_message_logger_exc PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_trusted_hosts[*-Remote: https://1.2.3.4:0] PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_trusted_hosts[127.0.0.1-Remote: https://1.2.3.4:0] PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_trusted_hosts[trusted_hosts2-Remote: https://1.2.3.4:0] PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_trusted_hosts[trusted_hosts3-Remote: https://1.2.3.4:0] PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_trusted_hosts[127.0.0.1, 10.0.0.1-Remote: https://1.2.3.4:0] PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_trusted_hosts[192.168.0.1-Remote: http://127.0.0.1:123] PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_multiple_proxies[*-Remote: https://1.2.3.4:0] PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_multiple_proxies[trusted_hosts1-Remote: https://1.2.3.4:0] PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_multiple_proxies[trusted_hosts2-Remote: https://1.2.3.4:0] PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_multiple_proxies[trusted_hosts3-Remote: https://10.0.2.1:0] PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_invalid_x_forwarded_for PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[wsproto-httptools-http-ws://1.2.3.4:0] INFO: Started server process [3747] 181s INFO: Uvicorn running on http://127.0.0.1:42069 (Press CTRL+C to quit) 181s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 181s INFO: Shutting down 181s PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[wsproto-httptools-https-wss://1.2.3.4:0] INFO: Started server process [3747] 181s INFO: Uvicorn running on http://127.0.0.1:44505 (Press CTRL+C to quit) 181s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 181s INFO: Shutting down 181s PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[wsproto-httptools-ws-ws://1.2.3.4:0] INFO: Started server process [3747] 181s INFO: Uvicorn running on http://127.0.0.1:42101 (Press CTRL+C to quit) 181s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 181s INFO: Shutting down 181s PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[wsproto-httptools-wss-wss://1.2.3.4:0] INFO: Started server process [3747] 181s INFO: Uvicorn running on http://127.0.0.1:38529 (Press CTRL+C to quit) 181s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 181s INFO: Shutting down 181s PASSED 181s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[wsproto-h11-http-ws://1.2.3.4:0] INFO: Started server process [3747] 181s INFO: Uvicorn running on http://127.0.0.1:32903 (Press CTRL+C to quit) 182s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 182s INFO: Shutting down 182s PASSED 182s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[wsproto-h11-https-wss://1.2.3.4:0] INFO: Started server process [3747] 182s INFO: Uvicorn running on http://127.0.0.1:48063 (Press CTRL+C to quit) 182s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 182s INFO: Shutting down 182s PASSED 182s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[wsproto-h11-ws-ws://1.2.3.4:0] INFO: Started server process [3747] 182s INFO: Uvicorn running on http://127.0.0.1:57367 (Press CTRL+C to quit) 182s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 182s INFO: Shutting down 182s PASSED 182s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[wsproto-h11-wss-wss://1.2.3.4:0] INFO: Started server process [3747] 182s INFO: Uvicorn running on http://127.0.0.1:45099 (Press CTRL+C to quit) 182s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 182s INFO: Shutting down 182s PASSED 182s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[websockets-httptools-http-ws://1.2.3.4:0] INFO: Started server process [3747] 182s INFO: Uvicorn running on http://127.0.0.1:38931 (Press CTRL+C to quit) 182s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 182s INFO: connection open 182s INFO: Shutting down 182s INFO: connection closed 183s PASSED 183s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[websockets-httptools-https-wss://1.2.3.4:0] INFO: Started server process [3747] 183s INFO: Uvicorn running on http://127.0.0.1:48081 (Press CTRL+C to quit) 183s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 183s INFO: connection open 183s INFO: Shutting down 183s INFO: connection closed 183s PASSED 183s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[websockets-httptools-ws-ws://1.2.3.4:0] INFO: Started server process [3747] 183s INFO: Uvicorn running on http://127.0.0.1:49101 (Press CTRL+C to quit) 183s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 183s INFO: connection open 183s INFO: Shutting down 183s INFO: connection closed 183s PASSED 183s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[websockets-httptools-wss-wss://1.2.3.4:0] INFO: Started server process [3747] 183s INFO: Uvicorn running on http://127.0.0.1:48559 (Press CTRL+C to quit) 183s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 183s INFO: connection open 183s INFO: Shutting down 183s INFO: connection closed 183s PASSED 183s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[websockets-h11-http-ws://1.2.3.4:0] INFO: Started server process [3747] 183s INFO: Uvicorn running on http://127.0.0.1:41799 (Press CTRL+C to quit) 183s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 183s INFO: connection open 183s INFO: Shutting down 183s INFO: connection closed 183s PASSED 183s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[websockets-h11-https-wss://1.2.3.4:0] INFO: Started server process [3747] 183s INFO: Uvicorn running on http://127.0.0.1:36561 (Press CTRL+C to quit) 183s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 183s INFO: connection open 183s INFO: Shutting down 183s INFO: connection closed 184s PASSED 184s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[websockets-h11-ws-ws://1.2.3.4:0] INFO: Started server process [3747] 184s INFO: Uvicorn running on http://127.0.0.1:49869 (Press CTRL+C to quit) 184s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 184s INFO: connection open 184s INFO: Shutting down 184s INFO: connection closed 184s PASSED 184s tests/middleware/test_proxy_headers.py::test_proxy_headers_websocket_x_forwarded_proto[websockets-h11-wss-wss://1.2.3.4:0] INFO: Started server process [3747] 184s INFO: Uvicorn running on http://127.0.0.1:40149 (Press CTRL+C to quit) 184s INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] 184s INFO: connection open 184s INFO: Shutting down 184s INFO: connection closed 184s PASSED 184s tests/middleware/test_wsgi.py::test_wsgi_get[_WSGIMiddleware] PASSED 184s tests/middleware/test_wsgi.py::test_wsgi_get[WSGIMiddleware] PASSED 184s tests/middleware/test_wsgi.py::test_wsgi_post[_WSGIMiddleware] PASSED 184s tests/middleware/test_wsgi.py::test_wsgi_post[WSGIMiddleware] PASSED 184s tests/middleware/test_wsgi.py::test_wsgi_put_more_body[_WSGIMiddleware] PASSED 184s tests/middleware/test_wsgi.py::test_wsgi_put_more_body[WSGIMiddleware] PASSED 184s tests/middleware/test_wsgi.py::test_wsgi_exception[_WSGIMiddleware] PASSED 184s tests/middleware/test_wsgi.py::test_wsgi_exception[WSGIMiddleware] PASSED 184s tests/middleware/test_wsgi.py::test_wsgi_exc_info[_WSGIMiddleware] PASSED 184s tests/middleware/test_wsgi.py::test_wsgi_exc_info[WSGIMiddleware] PASSED 184s tests/middleware/test_wsgi.py::test_build_environ_encoding PASSED 184s tests/protocols/test_http.py::test_get_request[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_get_request[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_header_value_allowed_characters[httptools-allow_ascii_letter] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_header_value_allowed_characters[httptools-allow_tab] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_header_value_allowed_characters[httptools-allow_space] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_header_value_allowed_characters[httptools-allow_non_ascii_char] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_header_value_allowed_characters[h11-allow_ascii_letter] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_header_value_allowed_characters[h11-allow_tab] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_header_value_allowed_characters[h11-allow_space] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_header_value_allowed_characters[h11-allow_non_ascii_char] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_request_logging[httptools-/] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 184s PASSED 184s tests/protocols/test_http.py::test_request_logging[httptools-/?foo] INFO: 127.0.0.1:8001 - "GET /?foo HTTP/1.1" 200 OK 184s INFO: 127.0.0.1:8001 - "GET /?foo HTTP/1.1" 200 184s PASSED 184s tests/protocols/test_http.py::test_request_logging[httptools-/?foo=bar] INFO: 127.0.0.1:8001 - "GET /?foo=bar HTTP/1.1" 200 OK 184s INFO: 127.0.0.1:8001 - "GET /?foo=bar HTTP/1.1" 200 184s PASSED 184s tests/protocols/test_http.py::test_request_logging[httptools-/?foo=bar&baz=1] INFO: 127.0.0.1:8001 - "GET /?foo=bar&baz=1 HTTP/1.1" 200 OK 184s INFO: 127.0.0.1:8001 - "GET /?foo=bar&baz=1 HTTP/1.1" 200 184s PASSED 184s tests/protocols/test_http.py::test_request_logging[h11-/] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 184s PASSED 184s tests/protocols/test_http.py::test_request_logging[h11-/?foo] INFO: 127.0.0.1:8001 - "GET /?foo HTTP/1.1" 200 OK 184s INFO: 127.0.0.1:8001 - "GET /?foo HTTP/1.1" 200 184s PASSED 184s tests/protocols/test_http.py::test_request_logging[h11-/?foo=bar] INFO: 127.0.0.1:8001 - "GET /?foo=bar HTTP/1.1" 200 OK 184s INFO: 127.0.0.1:8001 - "GET /?foo=bar HTTP/1.1" 200 184s PASSED 184s tests/protocols/test_http.py::test_request_logging[h11-/?foo=bar&baz=1] INFO: 127.0.0.1:8001 - "GET /?foo=bar&baz=1 HTTP/1.1" 200 OK 184s INFO: 127.0.0.1:8001 - "GET /?foo=bar&baz=1 HTTP/1.1" 200 184s PASSED 184s tests/protocols/test_http.py::test_head_request[httptools] INFO: 127.0.0.1:8001 - "HEAD / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_head_request[h11] INFO: 127.0.0.1:8001 - "HEAD / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_post_request[httptools] INFO: 127.0.0.1:8001 - "POST / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_post_request[h11] INFO: 127.0.0.1:8001 - "POST / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_keepalive[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 204 No Content 184s PASSED 184s tests/protocols/test_http.py::test_keepalive[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 204 No Content 184s PASSED 184s tests/protocols/test_http.py::test_keepalive_timeout[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 204 No Content 184s PASSED 184s tests/protocols/test_http.py::test_keepalive_timeout[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 204 No Content 184s PASSED 184s tests/protocols/test_http.py::test_keepalive_timeout_with_pipelined_requests[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_keepalive_timeout_with_pipelined_requests[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_close[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 204 No Content 184s PASSED 184s tests/protocols/test_http.py::test_close[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 204 No Content 184s PASSED 184s tests/protocols/test_http.py::test_chunked_encoding[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_chunked_encoding[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_chunked_encoding_empty_body[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_chunked_encoding_empty_body[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_chunked_encoding_head_request[httptools] INFO: 127.0.0.1:8001 - "HEAD / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_chunked_encoding_head_request[h11] INFO: 127.0.0.1:8001 - "HEAD / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_pipelined_requests[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_pipelined_requests[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_undersized_request[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 399, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/response.py", line 21, in __call__ 184s await send({"type": prefix + "http.response.body", "body": self.body}) 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 534, in send 184s raise RuntimeError("Response content shorter than Content-Length") 184s RuntimeError: Response content shorter than Content-Length 184s PASSED 184s tests/protocols/test_http.py::test_undersized_request[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/h11_impl.py", line 396, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/response.py", line 21, in __call__ 184s await send({"type": prefix + "http.response.body", "body": self.body}) 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/h11_impl.py", line 500, in send 184s output = self.conn.send(event=h11.EndOfMessage()) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/h11/_connection.py", line 512, in send 184s data_list = self.send_with_data_passthrough(event) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/h11/_connection.py", line 545, in send_with_data_passthrough 184s writer(event, data_list.append) 184s File "/usr/lib/python3/dist-packages/h11/_writers.py", line 67, in __call__ 184s self.send_eom(event.headers, write) 184s File "/usr/lib/python3/dist-packages/h11/_writers.py", line 96, in send_eom 184s raise LocalProtocolError("Too little data for declared Content-Length") 184s h11._util.LocalProtocolError: Too little data for declared Content-Length 184s PASSED 184s tests/protocols/test_http.py::test_oversized_request[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 399, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/response.py", line 21, in __call__ 184s await send({"type": prefix + "http.response.body", "body": self.body}) 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 526, in send 184s raise RuntimeError("Response content longer than Content-Length") 184s RuntimeError: Response content longer than Content-Length 184s PASSED 184s tests/protocols/test_http.py::test_oversized_request[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/h11_impl.py", line 396, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/response.py", line 21, in __call__ 184s await send({"type": prefix + "http.response.body", "body": self.body}) 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/h11_impl.py", line 493, in send 184s output = self.conn.send(event=h11.Data(data=data)) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/h11/_connection.py", line 512, in send 184s data_list = self.send_with_data_passthrough(event) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/h11/_connection.py", line 545, in send_with_data_passthrough 184s writer(event, data_list.append) 184s File "/usr/lib/python3/dist-packages/h11/_writers.py", line 65, in __call__ 184s self.send_data(event.data, write) 184s File "/usr/lib/python3/dist-packages/h11/_writers.py", line 91, in send_data 184s raise LocalProtocolError("Too much data for declared Content-Length") 184s h11._util.LocalProtocolError: Too much data for declared Content-Length 184s PASSED 184s tests/protocols/test_http.py::test_large_post_request[httptools] INFO: 127.0.0.1:8001 - "POST / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_large_post_request[h11] INFO: 127.0.0.1:8001 - "POST / HTTP/1.1" 200 OK 184s PASSED 184s tests/protocols/test_http.py::test_invalid_http[httptools] WARNING: Invalid HTTP request received. 184s PASSED 184s tests/protocols/test_http.py::test_invalid_http[h11] WARNING: Invalid HTTP request received. 184s PASSED 184s tests/protocols/test_http.py::test_app_exception[httptools] ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 399, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/protocols/test_http.py", line 471, in app 184s raise Exception() 184s Exception 184s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 500 Internal Server Error 184s PASSED 184s tests/protocols/test_http.py::test_app_exception[h11] ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/h11_impl.py", line 396, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/protocols/test_http.py", line 471, in app 184s raise Exception() 184s Exception 184s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 500 Internal Server Error 184s PASSED 184s tests/protocols/test_http.py::test_exception_during_response[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 399, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/protocols/test_http.py", line 484, in app 184s raise Exception() 184s Exception 184s PASSED 184s tests/protocols/test_http.py::test_exception_during_response[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/h11_impl.py", line 396, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/protocols/test_http.py", line 484, in app 184s raise Exception() 184s Exception 184s PASSED 184s tests/protocols/test_http.py::test_no_response_returned[httptools] ERROR: ASGI callable returned without starting response. 184s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 500 Internal Server Error 184s PASSED 184s tests/protocols/test_http.py::test_no_response_returned[h11] ERROR: ASGI callable returned without starting response. 184s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 500 Internal Server Error 184s PASSED 184s tests/protocols/test_http.py::test_partial_response_returned[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s ERROR: ASGI callable returned without completing response. 184s PASSED 184s tests/protocols/test_http.py::test_partial_response_returned[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s ERROR: ASGI callable returned without completing response. 184s PASSED 184s tests/protocols/test_http.py::test_response_header_splitting[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 399, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/response.py", line 14, in __call__ 184s await send( 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 482, in send 184s raise RuntimeError("Invalid HTTP header value.") 184s RuntimeError: Invalid HTTP header value. 184s PASSED 184s tests/protocols/test_http.py::test_response_header_splitting[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/h11_impl.py", line 396, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/response.py", line 14, in __call__ 184s await send( 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/h11_impl.py", line 477, in send 184s response = h11.Response(status_code=status, headers=headers, reason=reason) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/h11/_events.py", line 151, in __init__ 184s self, "headers", normalize_and_validate(headers, _parsed=_parsed) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/h11/_headers.py", line 164, in normalize_and_validate 184s validate(_field_value_re, value, "Illegal header value {!r}", value) 184s File "/usr/lib/python3/dist-packages/h11/_util.py", line 91, in validate 184s raise LocalProtocolError(msg) 184s h11._util.LocalProtocolError: Illegal header value b'value\r\nCookie: smuggled=value' 184s PASSED 184s tests/protocols/test_http.py::test_duplicate_start_message[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 399, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/protocols/test_http.py", line 528, in app 184s await send({"type": "http.response.start", "status": 200}) 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 507, in send 184s raise RuntimeError(msg % message_type) 184s RuntimeError: Expected ASGI message 'http.response.body', but got 'http.response.start'. 184s PASSED 184s tests/protocols/test_http.py::test_duplicate_start_message[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/h11_impl.py", line 396, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/protocols/test_http.py", line 528, in app 184s await send({"type": "http.response.start", "status": 200}) 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/h11_impl.py", line 485, in send 184s raise RuntimeError(msg % message_type) 184s RuntimeError: Expected ASGI message 'http.response.body', but got 'http.response.start'. 184s PASSED 184s tests/protocols/test_http.py::test_missing_start_message[httptools] ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 399, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/protocols/test_http.py", line 539, in app 184s await send({"type": "http.response.body", "body": b""}) 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 453, in send 184s raise RuntimeError(msg % message_type) 184s RuntimeError: Expected ASGI message 'http.response.start', but got 'http.response.body'. 184s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 500 Internal Server Error 184s PASSED 184s tests/protocols/test_http.py::test_missing_start_message[h11] ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/h11_impl.py", line 396, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/protocols/test_http.py", line 539, in app 184s await send({"type": "http.response.body", "body": b""}) 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/h11_impl.py", line 453, in send 184s raise RuntimeError(msg % message_type) 184s RuntimeError: Expected ASGI message 'http.response.start', but got 'http.response.body'. 184s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 500 Internal Server Error 184s PASSED 184s tests/protocols/test_http.py::test_message_after_body_complete[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 399, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/protocols/test_http.py", line 552, in app 184s await send({"type": "http.response.body", "body": b""}) 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 544, in send 184s raise RuntimeError(msg % message_type) 184s RuntimeError: Unexpected ASGI message 'http.response.body' sent, after response already completed. 184s PASSED 184s tests/protocols/test_http.py::test_message_after_body_complete[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 184s ERROR: Exception in ASGI application 184s Traceback (most recent call last): 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/h11_impl.py", line 396, in run_asgi 184s result = await app( # type: ignore[func-returns-value] 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 184s return await self.app(scope, receive, send) 184s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 184s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/protocols/test_http.py", line 552, in app 184s await send({"type": "http.response.body", "body": b""}) 184s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/h11_impl.py", line 506, in send 184s raise RuntimeError(msg % message_type) 184s RuntimeError: Unexpected ASGI message 'http.response.body' sent, after response already completed. 185s PASSED 185s tests/protocols/test_http.py::test_value_returned[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 185s ERROR: ASGI callable should return None, but returned '123'. 185s PASSED 185s tests/protocols/test_http.py::test_value_returned[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 185s ERROR: ASGI callable should return None, but returned '123'. 185s PASSED 185s tests/protocols/test_http.py::test_early_disconnect[httptools] PASSED 185s tests/protocols/test_http.py::test_early_disconnect[h11] PASSED 185s tests/protocols/test_http.py::test_early_response[httptools] INFO: 127.0.0.1:8001 - "POST / HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_early_response[h11] INFO: 127.0.0.1:8001 - "POST / HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_read_after_response[httptools] INFO: 127.0.0.1:8001 - "POST / HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_read_after_response[h11] INFO: 127.0.0.1:8001 - "POST / HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_http10_request[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.0" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_http10_request[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.0" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_root_path[httptools] INFO: 127.0.0.1:8001 - "GET /app/ HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_root_path[h11] INFO: 127.0.0.1:8001 - "GET /app/ HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_raw_path[httptools] INFO: 127.0.0.1:8001 - "GET /app/one/two HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_raw_path[h11] INFO: 127.0.0.1:8001 - "GET /app/one/two HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_max_concurrency[httptools] WARNING: Exceeded concurrency limit. 185s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 503 Service Unavailable 185s PASSED 185s tests/protocols/test_http.py::test_max_concurrency[h11] WARNING: Exceeded concurrency limit. 185s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 503 Service Unavailable 185s PASSED 185s tests/protocols/test_http.py::test_shutdown_during_request[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 204 No Content 185s PASSED 185s tests/protocols/test_http.py::test_shutdown_during_request[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 204 No Content 185s PASSED 185s tests/protocols/test_http.py::test_shutdown_during_idle[httptools] PASSED 185s tests/protocols/test_http.py::test_shutdown_during_idle[h11] PASSED 185s tests/protocols/test_http.py::test_100_continue_sent_when_body_consumed[httptools] INFO: 127.0.0.1:8001 - "POST / HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_100_continue_sent_when_body_consumed[h11] INFO: 127.0.0.1:8001 - "POST / HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_100_continue_not_sent_when_body_not_consumed[httptools] INFO: 127.0.0.1:8001 - "POST / HTTP/1.1" 204 No Content 185s PASSED 185s tests/protocols/test_http.py::test_100_continue_not_sent_when_body_not_consumed[h11] INFO: 127.0.0.1:8001 - "POST / HTTP/1.1" 204 No Content 185s PASSED 185s tests/protocols/test_http.py::test_supported_upgrade_request[httptools] PASSED 185s tests/protocols/test_http.py::test_supported_upgrade_request[h11] PASSED 185s tests/protocols/test_http.py::test_unsupported_ws_upgrade_request[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_unsupported_ws_upgrade_request[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_unsupported_ws_upgrade_request_warn_on_auto[httptools] WARNING: Unsupported upgrade request. 185s WARNING: No supported WebSocket library detected. Please use "pip install 'uvicorn[standard]'", or install 'websockets' or 'wsproto' manually. 185s WARNING: Unsupported upgrade request. 185s WARNING: No supported WebSocket library detected. Please use "pip install 'uvicorn[standard]'", or install 'websockets' or 'wsproto' manually. 185s WARNING: Unsupported upgrade request. 185s WARNING: No supported WebSocket library detected. Please use "pip install 'uvicorn[standard]'", or install 'websockets' or 'wsproto' manually. 185s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_unsupported_ws_upgrade_request_warn_on_auto[h11] WARNING: Unsupported upgrade request. 185s WARNING: No supported WebSocket library detected. Please use "pip install 'uvicorn[standard]'", or install 'websockets' or 'wsproto' manually. 185s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_http2_upgrade_request[httptools-wsproto] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_http2_upgrade_request[httptools-websockets] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_http2_upgrade_request[h11-wsproto] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_http2_upgrade_request[h11-websockets] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 185s PASSED 185s tests/protocols/test_http.py::test_scopes[httptools-asgi3app-expected_scopes0] ERROR: ASGI callable returned without starting response. 185s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 500 Internal Server Error 185s PASSED 185s tests/protocols/test_http.py::test_scopes[httptools-asgi2app-expected_scopes1] ERROR: ASGI callable returned without starting response. 185s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 500 Internal Server Error 185s PASSED 185s tests/protocols/test_http.py::test_scopes[h11-asgi3app-expected_scopes0] ERROR: ASGI callable returned without starting response. 185s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 500 Internal Server Error 185s PASSED 185s tests/protocols/test_http.py::test_scopes[h11-asgi2app-expected_scopes1] ERROR: ASGI callable returned without starting response. 185s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 500 Internal Server Error 185s PASSED 185s tests/protocols/test_http.py::test_invalid_http_request[httptools-invalid-method] WARNING: Invalid HTTP request received. 185s PASSED 185s tests/protocols/test_http.py::test_invalid_http_request[httptools-invalid-path] WARNING: Invalid HTTP request received. 185s PASSED 185s tests/protocols/test_http.py::test_invalid_http_request[httptools-invalid-http-version] WARNING: Invalid HTTP request received. 185s PASSED 185s tests/protocols/test_http.py::test_invalid_http_request[h11-invalid-method] WARNING: Invalid HTTP request received. 185s PASSED 185s tests/protocols/test_http.py::test_invalid_http_request[h11-invalid-path] WARNING: Invalid HTTP request received. 185s PASSED 185s tests/protocols/test_http.py::test_invalid_http_request[h11-invalid-http-version] WARNING: Invalid HTTP request received. 185s PASSED 185s tests/protocols/test_http.py::test_fragmentation INFO: Started server process [3747] 185s INFO: Waiting for application startup. 185s INFO: ASGI 'lifespan' protocol appears unsupported. 185s INFO: Application startup complete. 185s INFO: Uvicorn running on http://127.0.0.1:51547 (Press CTRL+C to quit) 186s INFO: 127.0.0.1:42940 - "GET /?param=qqqqqqqqqq HTTP/1.1" 200 OK 186s INFO: Shutting down 186s INFO: Finished server process [3747] 186s PASSED 186s tests/protocols/test_http.py::test_huge_headers_h11protocol_failure WARNING: Invalid HTTP request received. 186s PASSED 186s tests/protocols/test_http.py::test_huge_headers_httptools_will_pass INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 186s PASSED 186s tests/protocols/test_http.py::test_huge_headers_h11protocol_failure_with_setting WARNING: Invalid HTTP request received. 186s PASSED 186s tests/protocols/test_http.py::test_huge_headers_httptools INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 186s PASSED 186s tests/protocols/test_http.py::test_huge_headers_h11_max_incomplete INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 186s WARNING: Invalid HTTP request received. 186s PASSED 186s tests/protocols/test_http.py::test_return_close_header[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 186s PASSED 186s tests/protocols/test_http.py::test_return_close_header[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 186s PASSED 186s tests/protocols/test_http.py::test_iterator_headers[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 186s PASSED 186s tests/protocols/test_http.py::test_iterator_headers[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 186s PASSED 186s tests/protocols/test_http.py::test_lifespan_state[httptools] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 186s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 186s PASSED 186s tests/protocols/test_http.py::test_lifespan_state[h11] INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 186s INFO: 127.0.0.1:8001 - "GET / HTTP/1.1" 200 OK 186s PASSED 186s tests/protocols/test_utils.py::test_get_local_addr_with_socket PASSED 186s tests/protocols/test_utils.py::test_get_remote_addr_with_socket PASSED 186s tests/protocols/test_utils.py::test_get_local_addr PASSED 186s tests/protocols/test_utils.py::test_get_remote_addr PASSED 186s tests/protocols/test_utils.py::test_get_client_addr[ip:port client] PASSED 186s tests/protocols/test_utils.py::test_get_client_addr[None client] PASSED 186s tests/supervisors/test_multiprocess.py::test_process_ping_pong PASSED 186s tests/supervisors/test_multiprocess.py::test_process_ping_pong_timeout PASSED 186s tests/supervisors/test_multiprocess.py::test_multiprocess_run INFO: Started parent process [3747] 186s PASSED 186s tests/supervisors/test_multiprocess.py::test_multiprocess_health_check INFO: Started parent process [3747] 187s INFO: Received SIGINT, exiting. 187s INFO: Terminated child process [3970] 187s INFO: Terminated child process [3972] 187s INFO: Waiting for child process [3970] 187s INFO: Waiting for child process [3972] 187s INFO: Stopping parent process [3747] 187s INFO: Waiting for child process [3971] 187s INFO: Child process [3971] died 193s INFO: Waiting for child process [3973] 193s INFO: Received SIGINT, exiting. 193s INFO: Terminated child process [3973] 193s INFO: Terminated child process [3976] 193s INFO: Waiting for child process [3973] 193s INFO: Waiting for child process [3976] 193s INFO: Waiting for child process [3976] 193s PASSEDINFO: Stopping parent process [3747] 193s 193s tests/supervisors/test_multiprocess.py::test_multiprocess_sigterm INFO: Started parent process [3747] 194s INFO: Waiting for child process [3979] 194s INFO: Received SIGTERM, exiting. 194s INFO: Terminated child process [3979] 194s INFO: Terminated child process [3980] 194s INFO: Waiting for child process [3979] 194s INFO: Waiting for child process [3980] 194s INFO: Waiting for child process [3980] 194s INFO: Stopping parent process [3747] 194s PASSED 194s tests/supervisors/test_multiprocess.py::test_multiprocess_sigbreak SKIPPED 194s tests/supervisors/test_multiprocess.py::test_multiprocess_sighup INFO: Started parent process [3747] 195s INFO: Received SIGHUP, restarting processes. 195s INFO: Terminated child process [3984] 195s INFO: Waiting for child process [3984] 195s INFO: Terminated child process [3985] 195s INFO: Waiting for child process [3985] 196s INFO: Waiting for child process [3988] 197s INFO: Received SIGINT, exiting. 197s INFO: Terminated child process [3988] 197s INFO: Terminated child process [3989] 197s INFO: Waiting for child process [3988] 197s INFO: Waiting for child process [3989] 197s INFO: Waiting for child process [3989] 197s PASSED 197s tests/supervisors/test_multiprocess.py::test_multiprocess_sigttin INFO: Stopping parent process [3747] 197s INFO: Started parent process [3747] 197s INFO: Received SIGTTIN, increasing the number of processes. 198s INFO: Waiting for child process [3993] 198s INFO: Received SIGINT, exiting. 198s INFO: Terminated child process [3993] 198s INFO: Terminated child process [3994] 198s INFO: Terminated child process [3997] 198s INFO: Waiting for child process [3993] 198s INFO: Waiting for child process [3994] 198s INFO: Waiting for child process [3994] 198s INFO: Waiting for child process [3997] 198s INFO: Waiting for child process [3997] 198s INFO: Stopping parent process [3747] 198s PASSED 198s tests/supervisors/test_multiprocess.py::test_multiprocess_sigttou INFO: Started parent process [3747] 198s INFO: Received SIGTTOU, decreasing number of processes. 198s INFO: Terminated child process [4001] 198s INFO: Waiting for child process [4001] 199s INFO: Received SIGTTOU, decreasing number of processes. 199s INFO: Already reached one process, cannot decrease the number of processes anymore. 200s INFO: Waiting for child process [4000] 200s INFO: Received SIGINT, exiting. 200s INFO: Terminated child process [4000] 200s INFO: Waiting for child process [4000] 200s INFO: Stopping parent process [3747] 200s PASSED 200s tests/supervisors/test_reload.py::TestBaseReload::test_reloader_should_initialize[StatReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1'] 200s INFO: Started reloader process [3747] using StatReload 200s INFO: Stopping reloader process [3747] 200s PASSED 200s tests/supervisors/test_reload.py::TestBaseReload::test_reloader_should_initialize[None] SKIPPED 200s tests/supervisors/test_reload.py::TestBaseReload::test_reloader_should_initialize[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1'] 200s INFO: Started reloader process [3747] using WatchFiles 200s INFO: Stopping reloader process [3747] 200s PASSED 200s tests/supervisors/test_reload.py::TestBaseReload::test_reload_when_python_file_is_changed[StatReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1'] 200s INFO: Started reloader process [3747] using StatReload 200s INFO: Stopping reloader process [3747] 200s PASSED 200s tests/supervisors/test_reload.py::TestBaseReload::test_reload_when_python_file_is_changed[None] SKIPPED 200s tests/supervisors/test_reload.py::TestBaseReload::test_reload_when_python_file_is_changed[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1'] 200s INFO: Started reloader process [3747] using WatchFiles 200s INFO: Stopping reloader process [3747] 200s PASSED 200s tests/supervisors/test_reload.py::TestBaseReload::test_should_reload_when_python_file_in_subdir_is_changed[StatReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1'] 200s INFO: Started reloader process [3747] using StatReload 200s INFO: Stopping reloader process [3747] 200s PASSED 200s tests/supervisors/test_reload.py::TestBaseReload::test_should_reload_when_python_file_in_subdir_is_changed[None] SKIPPED 200s tests/supervisors/test_reload.py::TestBaseReload::test_should_reload_when_python_file_in_subdir_is_changed[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1'] 200s INFO: Started reloader process [3747] using WatchFiles 201s INFO: Stopping reloader process [3747] 201s PASSED 201s tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_python_file_in_excluded_subdir_is_changed[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1'] 201s INFO: Started reloader process [3747] using WatchFiles 201s INFO: Stopping reloader process [3747] 201s PASSED 201s tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_python_file_in_excluded_subdir_is_changed[None] SKIPPED 201s tests/supervisors/test_reload.py::TestBaseReload::test_reload_when_pattern_matched_file_is_changed[StatReload-False] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1'] 201s WARNING: --reload-include and --reload-exclude have no effect unless watchfiles is installed. 201s INFO: Started reloader process [3747] using StatReload 201s INFO: Stopping reloader process [3747] 201s PASSED 201s tests/supervisors/test_reload.py::TestBaseReload::test_reload_when_pattern_matched_file_is_changed[WatchFilesReload-True] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1'] 201s INFO: Started reloader process [3747] using WatchFiles 201s INFO: Stopping reloader process [3747] 201s PASSED 201s tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_exclude_pattern_match_file_is_changed[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/.dotted_dir', '/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/app', '/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/app_first', '/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/app_second', '/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/app_third', '/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/ext'] 201s INFO: Started reloader process [3747] using WatchFiles 202s INFO: Stopping reloader process [3747] 202s PASSED 202s tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_exclude_pattern_match_file_is_changed[None] SKIPPED 202s tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_dot_file_is_changed[StatReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1'] 202s INFO: Started reloader process [3747] using StatReload 202s INFO: Stopping reloader process [3747] 202s PASSED 202s tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_dot_file_is_changed[None] SKIPPED 202s tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_dot_file_is_changed[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1'] 202s INFO: Started reloader process [3747] using WatchFiles 202s INFO: Stopping reloader process [3747] 202s PASSED 202s tests/supervisors/test_reload.py::TestBaseReload::test_should_reload_when_directories_have_same_prefix[StatReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/app', '/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/app_first'] 202s INFO: Started reloader process [3747] using StatReload 202s INFO: Stopping reloader process [3747] 202s PASSED 202s tests/supervisors/test_reload.py::TestBaseReload::test_should_reload_when_directories_have_same_prefix[None] SKIPPED 202s tests/supervisors/test_reload.py::TestBaseReload::test_should_reload_when_directories_have_same_prefix[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/app', '/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/app_first'] 202s INFO: Started reloader process [3747] using WatchFiles 203s INFO: Stopping reloader process [3747] 203s PASSED 203s tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_only_subdirectory_is_watched[StatReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/app'] 203s INFO: Started reloader process [3747] using StatReload 203s INFO: Stopping reloader process [3747] 203s PASSED 203s tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_only_subdirectory_is_watched[None] SKIPPED 203s tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_only_subdirectory_is_watched[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/app'] 203s INFO: Started reloader process [3747] using WatchFiles 203s INFO: Stopping reloader process [3747] 203s PASSED 203s tests/supervisors/test_reload.py::TestBaseReload::test_override_defaults[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1'] 203s INFO: Started reloader process [3747] using WatchFiles 204s INFO: Stopping reloader process [3747] 204s PASSED 204s tests/supervisors/test_reload.py::TestBaseReload::test_override_defaults[None] SKIPPED 204s tests/supervisors/test_reload.py::TestBaseReload::test_explicit_paths[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1'] 204s INFO: Started reloader process [3747] using WatchFiles 205s INFO: Stopping reloader process [3747] 205s PASSED 205s tests/supervisors/test_reload.py::TestBaseReload::test_explicit_paths[None] SKIPPED 205s tests/supervisors/test_reload.py::TestBaseReload::test_watchfiles_no_changes[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1'] 205s INFO: Started reloader process [3747] using WatchFiles 205s INFO: Stopping reloader process [3747] 205s PASSED 205s tests/supervisors/test_reload.py::TestBaseReload::test_should_detect_new_reload_dirs[None] SKIPPED 205s tests/supervisors/test_reload.py::test_should_watch_one_dir_cwd INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/app', '/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/app_first'] 205s PASSED 205s tests/supervisors/test_reload.py::test_should_watch_separate_dirs_outside_cwd INFO: Will watch for changes in these directories: ['/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/app', '/tmp/pytest-of-ubuntu/pytest-0/reload_directory1/app_first'] 205s PASSED 205s tests/supervisors/test_reload.py::test_display_path_relative PASSED 205s tests/supervisors/test_reload.py::test_display_path_non_relative PASSED 205s tests/supervisors/test_reload.py::test_base_reloader_run INFO: Will watch for changes in these directories: ['/tmp/autopkgtest.OYyCaA/autopkgtest_tmp'] 205s WARNING: None detected changes in '/tmp/pytest-of-ubuntu/pytest-0/test_base_reloader_run0/foobar.py'. Reloading... 205s PASSED 205s tests/supervisors/test_reload.py::test_base_reloader_should_exit INFO: Will watch for changes in these directories: ['/tmp/autopkgtest.OYyCaA/autopkgtest_tmp'] 205s PASSED 205s tests/supervisors/test_reload.py::test_base_reloader_closes_sockets_on_shutdown INFO: Will watch for changes in these directories: ['/tmp/autopkgtest.OYyCaA/autopkgtest_tmp'] 205s INFO: Started reloader process [3747] using None 205s INFO: Stopping reloader process [3747] 205s PASSED 205s tests/supervisors/test_signal.py::test_sigint_finish_req INFO: Started server process [3747] 205s INFO: Waiting for application startup. 205s INFO: ASGI 'lifespan' protocol appears unsupported. 205s INFO: Application startup complete. 205s INFO: Uvicorn running on http://127.0.0.1:57121 (Press CTRL+C to quit) 205s INFO: 127.0.0.1:48822 - "GET / HTTP/1.1" 200 OK 205s INFO: Shutting down 205s INFO: Shutting down 205s INFO: Finished server process [3747] 205s PASSED 205s tests/supervisors/test_signal.py::test_sigint_abort_req INFO: Started server process [3747] 205s INFO: Waiting for application startup. 205s INFO: ASGI 'lifespan' protocol appears unsupported. 205s INFO: Application startup complete. 205s INFO: Uvicorn running on http://127.0.0.1:52605 (Press CTRL+C to quit) 206s INFO: 127.0.0.1:60728 - "GET / HTTP/1.1" 200 OK 206s INFO: Shutting down 206s INFO: Waiting for connections to close. (CTRL+C to force quit) 207s ERROR: Cancel 1 running task(s), timeout graceful shutdown exceeded 207s INFO: Finished server process [3747] 207s ERROR: Exception in ASGI application 207s Traceback (most recent call last): 207s File "/usr/lib/python3/dist-packages/uvicorn/protocols/http/httptools_impl.py", line 399, in run_asgi 207s result = await app( # type: ignore[func-returns-value] 207s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 207s File "/usr/lib/python3/dist-packages/uvicorn/middleware/proxy_headers.py", line 70, in __call__ 207s return await self.app(scope, receive, send) 207s ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 207s File "/tmp/autopkgtest.OYyCaA/autopkgtest_tmp/tests/supervisors/test_signal.py", line 62, in forever_app 207s await server_event.wait() 207s File "/usr/lib/python3.12/asyncio/locks.py", line 212, in wait 207s await fut 207s asyncio.exceptions.CancelledError: Task cancelled, timeout graceful shutdown exceeded 207s INFO: Shutting down 207s PASSED 207s tests/supervisors/test_signal.py::test_sigint_deny_request_after_triggered INFO: Started server process [3747] 207s INFO: Waiting for application startup. 207s INFO: ASGI 'lifespan' protocol appears unsupported. 207s INFO: Application startup complete. 207s INFO: Uvicorn running on http://127.0.0.1:43911 (Press CTRL+C to quit) 207s INFO: Shutting down 207s INFO: Finished server process [3747] 207s INFO: Shutting down 207s PASSED 207s 207s =============================== warnings summary =============================== 207s tests/middleware/test_wsgi.py::test_wsgi_get[_WSGIMiddleware] 207s tests/middleware/test_wsgi.py::test_wsgi_post[_WSGIMiddleware] 207s tests/middleware/test_wsgi.py::test_wsgi_put_more_body[_WSGIMiddleware] 207s tests/middleware/test_wsgi.py::test_wsgi_exception[_WSGIMiddleware] 207s tests/middleware/test_wsgi.py::test_wsgi_exc_info[_WSGIMiddleware] 207s /usr/lib/python3/dist-packages/uvicorn/middleware/wsgi.py:84: DeprecationWarning: Uvicorn's native WSGI implementation is deprecated, you should switch to a2wsgi (`pip install a2wsgi`). 207s warnings.warn( 207s 207s -- Docs: https://docs.pytest.org/en/stable/how-to/capture-warnings.html 207s ========= 363 passed, 12 skipped, 26 deselected, 5 warnings in 30.68s ========== 208s /tmp/autopkgtest.OYyCaA/wrapper.sh: Killing leaked background processes: 3969 208s PID TTY STAT TIME COMMAND 208s /tmp/autopkgtest.OYyCaA/wrapper.sh: 235: kill: No such process 208s 208s /tmp/autopkgtest.OYyCaA/wrapper.sh: 237: kill: No such process 208s 208s autopkgtest [05:27:48]: test unittests: -----------------------] 208s unittests PASS 208s autopkgtest [05:27:48]: test unittests: - - - - - - - - - - results - - - - - - - - - - 209s autopkgtest [05:27:49]: test autodep8-python3: preparing testbed 355s autopkgtest [05:30:15]: testbed dpkg architecture: s390x 355s autopkgtest [05:30:15]: testbed apt version: 2.9.6 355s autopkgtest [05:30:15]: @@@@@@@@@@@@@@@@@@@@ test bed setup 356s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease [126 kB] 357s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main Sources [62.2 kB] 357s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/restricted Sources [8548 B] 357s Get:4 http://ftpmaster.internal/ubuntu oracular-proposed/universe Sources [407 kB] 357s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse Sources [4724 B] 357s Get:6 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x Packages [101 kB] 357s Get:7 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x c-n-f Metadata [2112 B] 357s Get:8 http://ftpmaster.internal/ubuntu oracular-proposed/restricted s390x Packages [1368 B] 357s Get:9 http://ftpmaster.internal/ubuntu oracular-proposed/restricted s390x c-n-f Metadata [120 B] 357s Get:10 http://ftpmaster.internal/ubuntu oracular-proposed/universe s390x Packages [464 kB] 357s Get:11 http://ftpmaster.internal/ubuntu oracular-proposed/universe s390x c-n-f Metadata [8372 B] 357s Get:12 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse s390x Packages [2744 B] 357s Get:13 http://ftpmaster.internal/ubuntu oracular-proposed/multiverse s390x c-n-f Metadata [120 B] 357s Fetched 1188 kB in 1s (1495 kB/s) 357s Reading package lists... 359s Reading package lists... 360s Building dependency tree... 360s Reading state information... 360s Calculating upgrade... 360s The following packages will be upgraded: 360s libbpf1 libpython3-stdlib python3 python3-gdbm python3-minimal 360s 5 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 360s Need to get 259 kB of archives. 360s After this operation, 26.6 kB of additional disk space will be used. 360s Get:1 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x python3-minimal s390x 3.12.4-1 [27.1 kB] 360s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x python3 s390x 3.12.4-1 [24.0 kB] 360s Get:3 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x libpython3-stdlib s390x 3.12.4-1 [9826 B] 360s Get:4 http://ftpmaster.internal/ubuntu oracular/main s390x libbpf1 s390x 1:1.4.5-1 [180 kB] 361s Get:5 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x python3-gdbm s390x 3.12.4-2 [18.5 kB] 361s Fetched 259 kB in 0s (607 kB/s) 361s (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 ... 54753 files and directories currently installed.) 361s Preparing to unpack .../python3-minimal_3.12.4-1_s390x.deb ... 361s Unpacking python3-minimal (3.12.4-1) over (3.12.3-0ubuntu1) ... 361s Setting up python3-minimal (3.12.4-1) ... 361s (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 ... 54753 files and directories currently installed.) 361s Preparing to unpack .../python3_3.12.4-1_s390x.deb ... 361s Unpacking python3 (3.12.4-1) over (3.12.3-0ubuntu1) ... 361s Preparing to unpack .../libpython3-stdlib_3.12.4-1_s390x.deb ... 361s Unpacking libpython3-stdlib:s390x (3.12.4-1) over (3.12.3-0ubuntu1) ... 361s Preparing to unpack .../libbpf1_1%3a1.4.5-1_s390x.deb ... 361s Unpacking libbpf1:s390x (1:1.4.5-1) over (1:1.4.3-1) ... 361s Preparing to unpack .../python3-gdbm_3.12.4-2_s390x.deb ... 361s Unpacking python3-gdbm:s390x (3.12.4-2) over (3.12.3-0ubuntu1) ... 361s Setting up libbpf1:s390x (1:1.4.5-1) ... 361s Setting up libpython3-stdlib:s390x (3.12.4-1) ... 361s Setting up python3 (3.12.4-1) ... 362s Setting up python3-gdbm:s390x (3.12.4-2) ... 362s Processing triggers for man-db (2.12.1-2) ... 362s Processing triggers for libc-bin (2.39-0ubuntu9) ... 362s Reading package lists... 363s Building dependency tree... 363s Reading state information... 363s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 363s Hit:1 http://ftpmaster.internal/ubuntu oracular-proposed InRelease 363s Hit:2 http://ftpmaster.internal/ubuntu oracular InRelease 363s Hit:3 http://ftpmaster.internal/ubuntu oracular-updates InRelease 364s Hit:4 http://ftpmaster.internal/ubuntu oracular-security InRelease 365s Reading package lists... 365s Reading package lists... 365s Building dependency tree... 365s Reading state information... 365s Calculating upgrade... 366s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 366s Reading package lists... 366s Building dependency tree... 366s Reading state information... 366s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 370s Reading package lists... 370s Building dependency tree... 370s Reading state information... 371s Starting pkgProblemResolver with broken count: 0 371s Starting 2 pkgProblemResolver with broken count: 0 371s Done 371s The following additional packages will be installed: 371s python3-all python3-click python3-colorama python3-h11 python3-uvicorn 371s python3-uvloop python3-wsproto 371s Suggested packages: 371s python-uvicorn-doc 371s The following NEW packages will be installed: 371s autopkgtest-satdep python3-all python3-click python3-colorama python3-h11 371s python3-uvicorn python3-uvloop python3-wsproto 371s 0 upgraded, 8 newly installed, 0 to remove and 0 not upgraded. 371s Need to get 822 kB/823 kB of archives. 371s After this operation, 3090 kB of additional disk space will be used. 371s Get:1 /tmp/autopkgtest.OYyCaA/2-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [716 B] 371s Get:2 http://ftpmaster.internal/ubuntu oracular-proposed/main s390x python3-all s390x 3.12.4-1 [884 B] 371s Get:3 http://ftpmaster.internal/ubuntu oracular/main s390x python3-colorama all 0.4.6-4 [32.1 kB] 371s Get:4 http://ftpmaster.internal/ubuntu oracular/main s390x python3-click all 8.1.7-1 [79.1 kB] 371s Get:5 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-h11 all 0.14.0-1 [51.1 kB] 371s Get:6 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-wsproto all 1.2.0-1 [23.6 kB] 371s Get:7 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-uvloop s390x 0.19.0+ds1-2.1 [594 kB] 372s Get:8 http://ftpmaster.internal/ubuntu oracular/universe s390x python3-uvicorn all 0.30.1-1 [41.2 kB] 372s Fetched 822 kB in 1s (1473 kB/s) 372s Selecting previously unselected package python3-all. 372s (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 ... 54756 files and directories currently installed.) 372s Preparing to unpack .../0-python3-all_3.12.4-1_s390x.deb ... 372s Unpacking python3-all (3.12.4-1) ... 372s Selecting previously unselected package python3-colorama. 372s Preparing to unpack .../1-python3-colorama_0.4.6-4_all.deb ... 372s Unpacking python3-colorama (0.4.6-4) ... 372s Selecting previously unselected package python3-click. 372s Preparing to unpack .../2-python3-click_8.1.7-1_all.deb ... 372s Unpacking python3-click (8.1.7-1) ... 372s Selecting previously unselected package python3-h11. 372s Preparing to unpack .../3-python3-h11_0.14.0-1_all.deb ... 372s Unpacking python3-h11 (0.14.0-1) ... 372s Selecting previously unselected package python3-wsproto. 372s Preparing to unpack .../4-python3-wsproto_1.2.0-1_all.deb ... 372s Unpacking python3-wsproto (1.2.0-1) ... 372s Selecting previously unselected package python3-uvloop. 372s Preparing to unpack .../5-python3-uvloop_0.19.0+ds1-2.1_s390x.deb ... 372s Unpacking python3-uvloop (0.19.0+ds1-2.1) ... 372s Selecting previously unselected package python3-uvicorn. 372s Preparing to unpack .../6-python3-uvicorn_0.30.1-1_all.deb ... 372s Unpacking python3-uvicorn (0.30.1-1) ... 372s Selecting previously unselected package autopkgtest-satdep. 372s Preparing to unpack .../7-2-autopkgtest-satdep.deb ... 372s Unpacking autopkgtest-satdep (0) ... 372s Setting up python3-colorama (0.4.6-4) ... 372s Setting up python3-all (3.12.4-1) ... 372s Setting up python3-click (8.1.7-1) ... 373s Setting up python3-h11 (0.14.0-1) ... 373s Setting up python3-uvloop (0.19.0+ds1-2.1) ... 373s Setting up python3-wsproto (1.2.0-1) ... 373s Setting up python3-uvicorn (0.30.1-1) ... 374s Setting up autopkgtest-satdep (0) ... 376s (Reading database ... 54959 files and directories currently installed.) 376s Removing autopkgtest-satdep (0) ... 377s autopkgtest [05:30:37]: test autodep8-python3: set -e ; for py in $(py3versions -r 2>/dev/null) ; do cd "$AUTOPKGTEST_TMP" ; echo "Testing with $py:" ; $py -c "import uvicorn; print(uvicorn)" ; done 377s autopkgtest [05:30:37]: test autodep8-python3: [----------------------- 378s Testing with python3.12: 378s 378s autopkgtest [05:30:38]: test autodep8-python3: -----------------------] 379s autopkgtest [05:30:39]: test autodep8-python3: - - - - - - - - - - results - - - - - - - - - - 379s autodep8-python3 PASS (superficial) 379s autopkgtest [05:30:39]: @@@@@@@@@@@@@@@@@@@@ summary 379s unittests PASS 379s autodep8-python3 PASS (superficial) 395s nova [W] Using flock in scalingstack-bos01-s390x 395s flock: timeout while waiting to get lock 395s Creating nova instance adt-oracular-s390x-python-uvicorn-20240721-052420-juju-7f2275-prod-proposed-migration-environment-2-11a4c7f5-ad01-4172-86e3-9ae5f0ac6fd2 from image adt/ubuntu-oracular-s390x-server-20240720.img (UUID c4813fc9-ab2b-41de-947e-4df7311c6440)... 395s nova [W] Using flock in scalingstack-bos01-s390x 395s flock: timeout while waiting to get lock 395s Creating nova instance adt-oracular-s390x-python-uvicorn-20240721-052420-juju-7f2275-prod-proposed-migration-environment-2-11a4c7f5-ad01-4172-86e3-9ae5f0ac6fd2 from image adt/ubuntu-oracular-s390x-server-20240720.img (UUID c4813fc9-ab2b-41de-947e-4df7311c6440)...