Diff of the two buildlogs: -- --- b1/build.log 2024-05-15 06:19:24.129863847 +0000 +++ b2/build.log 2024-05-15 06:22:59.440695142 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Tue May 14 18:16:35 -12 2024 -I: pbuilder-time-stamp: 1715753795 +I: Current time: Wed Jun 18 02:42:30 +14 2025 +I: pbuilder-time-stamp: 1750164150 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/trixie-reproducible-base.tgz] I: copying local configuration @@ -31,52 +31,84 @@ dpkg-source: info: applying 0005-Compatibility-with-httpx-0.27.0.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/2410362/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/2149763/tmp/hooks/D01_modify_environment starting +debug: Running on ionos15-amd64. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 Jun 17 12:42 /bin/sh -> /bin/bash +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/2149763/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/2149763/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='amd64' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=20 ' - DISTRIBUTION='trixie' - HOME='/root' - HOST_ARCH='amd64' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="21" [3]="1" [4]="release" [5]="x86_64-pc-linux-gnu") + BASH_VERSION='5.2.21(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=amd64 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=42 ' + DIRSTACK=() + DISTRIBUTION=trixie + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=x86_64 + HOST_ARCH=amd64 IFS=' ' - INVOCATION_ID='2e694a358ab642b9a1ea0289d6c39b76' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - MAIL='/var/mail/root' - OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' - PBCURRENTCOMMANDLINEOPERATION='build' - PBUILDER_OPERATION='build' - PBUILDER_PKGDATADIR='/usr/share/pbuilder' - PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' - PBUILDER_SYSCONFDIR='/etc' - PPID='2410362' - PS1='# ' - PS2='> ' + INVOCATION_ID=2849e54960bb4a7db9dee500b8eebc79 + LANG=C + LANGUAGE=et_EE:et + LC_ALL=C + MACHTYPE=x86_64-pc-linux-gnu + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnu + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=2149763 PS4='+ ' - PWD='/' - SHELL='/bin/bash' - SHLVL='2' - SUDO_COMMAND='/usr/bin/timeout -k 18.1h 18h /usr/bin/ionice -c 3 /usr/bin/nice /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.m5s9uKCH/pbuilderrc_pgvz --distribution trixie --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.m5s9uKCH/b1 --logfile b1/build.log python-uvicorn_0.29.0-1.dsc' - SUDO_GID='111' - SUDO_UID='106' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://46.16.76.132:3128' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.m5s9uKCH/pbuilderrc_kjS6 --distribution trixie --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.m5s9uKCH/b2 --logfile b2/build.log python-uvicorn_0.29.0-1.dsc' + SUDO_GID=111 + SUDO_UID=106 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://213.165.73.152:3128 I: uname -a - Linux ionos11-amd64 6.1.0-21-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.90-1 (2024-05-03) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.6.13+bpo-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.6.13-1~bpo12+1 (2024-02-15) x86_64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 13 11:26 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/2410362/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Jun 14 18:01 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/2149763/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -294,7 +326,7 @@ Get: 134 http://deb.debian.org/debian trixie/main amd64 python3-watchfiles amd64 0.21.0-3 [359 kB] Get: 135 http://deb.debian.org/debian trixie/main amd64 python3-websockets all 10.4-1 [62.8 kB] Get: 136 http://deb.debian.org/debian trixie/main amd64 python3-wsproto all 1.2.0-1 [25.4 kB] -Fetched 50.5 MB in 1s (56.8 MB/s) +Fetched 50.5 MB in 1s (44.4 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package fonts-lato. (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 ... 19699 files and directories currently installed.) @@ -738,8 +770,8 @@ Setting up tzdata (2024a-4) ... Current default time zone: 'Etc/UTC' -Local time is now: Wed May 15 06:17:31 UTC 2024. -Universal Time is now: Wed May 15 06:17:31 UTC 2024. +Local time is now: Tue Jun 17 12:44:10 UTC 2025. +Universal Time is now: Tue Jun 17 12:44:10 UTC 2025. Run 'dpkg-reconfigure tzdata' if you wish to change it. Setting up autotools-dev (20220109.1) ... @@ -873,7 +905,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/python-uvicorn-0.29.0/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-genchanges -S > ../python-uvicorn_0.29.0-1_source.changes +I: user script /srv/workspace/pbuilder/2149763/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/2149763/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/python-uvicorn-0.29.0/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-genchanges -S > ../python-uvicorn_0.29.0-1_source.changes dpkg-buildpackage: info: source package python-uvicorn dpkg-buildpackage: info: source version 0.29.0-1 dpkg-buildpackage: info: source distribution unstable @@ -910,7 +946,7 @@ LC_ALL=C.UTF-8 LANG=C.UTF-8 mkdocs build && mv site html INFO - Cleaning site directory INFO - Building documentation to directory: /build/reproducible-path/python-uvicorn-0.29.0/site -INFO - Documentation built in 0.47 seconds +INFO - Documentation built in 0.56 seconds rm -f html/sitemap.xml.gz make[1]: Leaving directory '/build/reproducible-path/python-uvicorn-0.29.0' debian/rules override_dh_auto_test @@ -922,7 +958,7 @@ cachedir: .pytest_cache rootdir: /build/reproducible-path/python-uvicorn-0.29.0/.pybuild/cpython3_3.12/build configfile: pyproject.toml -plugins: asyncio-0.20.3, mock-3.12.0, anyio-4.3.0 +plugins: anyio-4.3.0, asyncio-0.20.3, mock-3.12.0 asyncio: mode=Mode.STRICT collecting ... collected 381 items / 27 deselected / 354 selected @@ -948,21 +984,21 @@ 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. WARNING: Current configuration will not reload as not all conditions are met, please refer to documentation. PASSED -tests/test_config.py::test_reload_dir_is_set INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory0/app'] -INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory0/app'] +tests/test_config.py::test_reload_dir_is_set INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory0/app'] +INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory0/app'] PASSED 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. -INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory0'] +INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory0'] PASSED -tests/test_config.py::test_reload_subdir_removal INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory0'] +tests/test_config.py::test_reload_subdir_removal INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory0'] PASSED -tests/test_config.py::test_reload_included_dir_is_added_to_reload_dirs INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory0/app', '/tmp/pytest-of-pbuilder1/pytest-0/reload_directory0/ext'] +tests/test_config.py::test_reload_included_dir_is_added_to_reload_dirs INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory0/app', '/tmp/pytest-of-pbuilder2/pytest-0/reload_directory0/ext'] PASSED -tests/test_config.py::test_reload_dir_subdirectories_are_removed INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory0/app', '/tmp/pytest-of-pbuilder1/pytest-0/reload_directory0/ext'] +tests/test_config.py::test_reload_dir_subdirectories_are_removed INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory0/app', '/tmp/pytest-of-pbuilder2/pytest-0/reload_directory0/ext'] PASSED -tests/test_config.py::test_reload_excluded_subdirectories_are_removed INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory0'] +tests/test_config.py::test_reload_excluded_subdirectories_are_removed INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory0'] PASSED -tests/test_config.py::test_reload_includes_exclude_dir_patterns_are_matched INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory0/app_first/src', '/tmp/pytest-of-pbuilder1/pytest-0/reload_directory0/app_second/src'] +tests/test_config.py::test_reload_includes_exclude_dir_patterns_are_matched INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory0/app_first/src', '/tmp/pytest-of-pbuilder2/pytest-0/reload_directory0/app_second/src'] PASSED tests/test_config.py::test_wsgi_app PASSED tests/test_config.py::test_proxy_headers PASSED @@ -985,13 +1021,13 @@ tests/test_config.py::test_log_config_yaml[log_config.yml] PASSED tests/test_config.py::test_log_config_yaml[log_config.yaml] PASSED tests/test_config.py::test_log_config_file PASSED -tests/test_config.py::test_env_file[0-127.0.0.1] INFO: Loading environment from '/tmp/pytest-of-pbuilder1/pytest-0/test_env_file_0_127_0_0_1_0/.env' +tests/test_config.py::test_env_file[0-127.0.0.1] INFO: Loading environment from '/tmp/pytest-of-pbuilder2/pytest-0/test_env_file_0_127_0_0_1_0/.env' PASSED -tests/test_config.py::test_env_file[0-127.0.0.2] INFO: Loading environment from '/tmp/pytest-of-pbuilder1/pytest-0/test_env_file_0_127_0_0_2_0/.env' +tests/test_config.py::test_env_file[0-127.0.0.2] INFO: Loading environment from '/tmp/pytest-of-pbuilder2/pytest-0/test_env_file_0_127_0_0_2_0/.env' PASSED -tests/test_config.py::test_env_file[1-127.0.0.1] INFO: Loading environment from '/tmp/pytest-of-pbuilder1/pytest-0/test_env_file_1_127_0_0_1_0/.env' +tests/test_config.py::test_env_file[1-127.0.0.1] INFO: Loading environment from '/tmp/pytest-of-pbuilder2/pytest-0/test_env_file_1_127_0_0_1_0/.env' PASSED -tests/test_config.py::test_env_file[1-127.0.0.2] INFO: Loading environment from '/tmp/pytest-of-pbuilder1/pytest-0/test_env_file_1_127_0_0_2_0/.env' +tests/test_config.py::test_env_file[1-127.0.0.2] INFO: Loading environment from '/tmp/pytest-of-pbuilder2/pytest-0/test_env_file_1_127_0_0_2_0/.env' PASSED tests/test_config.py::test_config_access_log[access log enabled should have single handler] PASSED tests/test_config.py::test_config_access_log[access log disabled shouldn't have handlers] PASSED @@ -1059,9 +1095,9 @@ tests/test_config.py::test_config_log_effective_level[50-50] PASSED tests/test_config.py::test_ws_max_size PASSED tests/test_config.py::test_ws_max_queue PASSED -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-pbuilder1/pytest-0/p-67z6sig_-ea4969ddf4df7cb1984aed084943b702/my.sock (Press CTRL+C to quit) +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-pbuilder2/pytest-0/p-nxyb89mq-fde12b5ee711eb55f99b74c330754f45/my.sock (Press CTRL+C to quit) PASSED -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-pbuilder1/pytest-0/p-qibw_aeu-ea4969ddf4df7cb1984aed084943b702/my.sock (Press CTRL+C to quit) +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-pbuilder2/pytest-0/p-348c0h_u-fde12b5ee711eb55f99b74c330754f45/my.sock (Press CTRL+C to quit) PASSED 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) PASSED @@ -1159,47 +1195,47 @@ INFO: Waiting for application shutdown. INFO: Application shutdown complete. PASSED -tests/test_main.py::test_exit_on_create_server_with_invalid_host INFO: Started server process [2440875] +tests/test_main.py::test_exit_on_create_server_with_invalid_host INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. ERROR: [Errno -3] Temporary failure in name resolution PASSED -tests/test_server.py::test_server_interrupt[capture_signal_sync-15] INFO: Started server process [2440875] +tests/test_server.py::test_server_interrupt[capture_signal_sync-15] INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: Application startup complete. INFO: Uvicorn running on http://127.0.0.1:8000 (Press CTRL+C to quit) INFO: Shutting down INFO: Waiting for application shutdown. INFO: Application shutdown complete. -INFO: Finished server process [2440875] +INFO: Finished server process [2211262] PASSED -tests/test_server.py::test_server_interrupt[capture_signal_sync-2] INFO: Started server process [2440875] +tests/test_server.py::test_server_interrupt[capture_signal_sync-2] INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: Application startup complete. INFO: Uvicorn running on http://127.0.0.1:8000 (Press CTRL+C to quit) INFO: Shutting down INFO: Waiting for application shutdown. INFO: Application shutdown complete. -INFO: Finished server process [2440875] +INFO: Finished server process [2211262] PASSED -tests/test_server.py::test_server_interrupt[capture_signal_async-15] INFO: Started server process [2440875] +tests/test_server.py::test_server_interrupt[capture_signal_async-15] INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: Application startup complete. INFO: Uvicorn running on http://127.0.0.1:8000 (Press CTRL+C to quit) INFO: Shutting down INFO: Waiting for application shutdown. INFO: Application shutdown complete. -INFO: Finished server process [2440875] +INFO: Finished server process [2211262] PASSED -tests/test_server.py::test_server_interrupt[capture_signal_async-2] INFO: Started server process [2440875] +tests/test_server.py::test_server_interrupt[capture_signal_async-2] INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: Application startup complete. INFO: Uvicorn running on http://127.0.0.1:8000 (Press CTRL+C to quit) INFO: Shutting down INFO: Waiting for application shutdown. INFO: Application shutdown complete. -INFO: Finished server process [2440875] +INFO: Finished server process [2211262] PASSED tests/test_subprocess.py::test_get_subprocess PASSED tests/test_subprocess.py::test_subprocess_started PASSED @@ -1210,59 +1246,59 @@ tests/importer/test_importer.py::test_valid_import PASSED tests/importer/test_importer.py::test_no_import_needed PASSED tests/importer/test_importer.py::test_circular_import_error PASSED -tests/middleware/test_logging.py::test_access_logging[True] INFO: Started server process [2440875] +tests/middleware/test_logging.py::test_access_logging[True] INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:58405 (Press CTRL+C to quit) -INFO: 127.0.0.1:45884 - "GET / HTTP/1.1" 204 No Content +INFO: Uvicorn running on http://127.0.0.1:48379 (Press CTRL+C to quit) +INFO: 127.0.0.1:58072 - "GET / HTTP/1.1" 204 No Content INFO: Shutting down PASSED -tests/middleware/test_logging.py::test_access_logging[False] INFO: Started server process [2440875] +tests/middleware/test_logging.py::test_access_logging[False] INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:58021 (Press CTRL+C to quit) -INFO: 127.0.0.1:35128 - "GET / HTTP/1.1" 204 No Content +INFO: Uvicorn running on http://127.0.0.1:40229 (Press CTRL+C to quit) +INFO: 127.0.0.1:49740 - "GET / HTTP/1.1" 204 No Content INFO: Shutting down PASSED -tests/middleware/test_logging.py::test_access_logging[None] INFO: Started server process [2440875] +tests/middleware/test_logging.py::test_access_logging[None] INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:36227 (Press CTRL+C to quit) -INFO: 127.0.0.1:47120 - "GET / HTTP/1.1" 204 No Content +INFO: Uvicorn running on http://127.0.0.1:55041 (Press CTRL+C to quit) +INFO: 127.0.0.1:58226 - "GET / HTTP/1.1" 204 No Content INFO: Shutting down PASSED -tests/middleware/test_logging.py::test_default_logging[True] INFO: Started server process [2440875] +tests/middleware/test_logging.py::test_default_logging[True] INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:37479 (Press CTRL+C to quit) -INFO: 127.0.0.1:50506 - "GET / HTTP/1.1" 204 No Content +INFO: Uvicorn running on http://127.0.0.1:50657 (Press CTRL+C to quit) +INFO: 127.0.0.1:55410 - "GET / HTTP/1.1" 204 No Content INFO: Shutting down PASSED -tests/middleware/test_logging.py::test_default_logging[False] INFO: Started server process [2440875] +tests/middleware/test_logging.py::test_default_logging[False] INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:57415 (Press CTRL+C to quit) -INFO: 127.0.0.1:43610 - "GET / HTTP/1.1" 204 No Content +INFO: Uvicorn running on http://127.0.0.1:41277 (Press CTRL+C to quit) +INFO: 127.0.0.1:57300 - "GET / HTTP/1.1" 204 No Content INFO: Shutting down PASSED -tests/middleware/test_logging.py::test_unknown_status_code INFO: Started server process [2440875] +tests/middleware/test_logging.py::test_unknown_status_code INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:37821 (Press CTRL+C to quit) -INFO: 127.0.0.1:52600 - "GET / HTTP/1.1" 599 +INFO: Uvicorn running on http://127.0.0.1:41241 (Press CTRL+C to quit) +INFO: 127.0.0.1:57494 - "GET / HTTP/1.1" 599 INFO: Shutting down PASSED -tests/middleware/test_logging.py::test_server_start_with_port_zero INFO: Started server process [2440875] +tests/middleware/test_logging.py::test_server_start_with_port_zero INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:41939 (Press CTRL+C to quit) +INFO: Uvicorn running on http://127.0.0.1:34631 (Press CTRL+C to quit) INFO: Shutting down PASSED tests/middleware/test_message_logger.py::test_message_logger PASSED @@ -1278,97 +1314,97 @@ tests/middleware/test_proxy_headers.py::test_proxy_headers_multiple_proxies[trusted_hosts2-Remote: https://1.2.3.4:0] PASSED tests/middleware/test_proxy_headers.py::test_proxy_headers_multiple_proxies[trusted_hosts3-Remote: https://10.0.2.1:0] PASSED tests/middleware/test_proxy_headers.py::test_proxy_headers_invalid_x_forwarded_for PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:46539 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:58691 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:60091 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:41899 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:34115 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:60171 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:37649 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:37309 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:50597 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:46231 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:54755 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:59905 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:58457 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:60699 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:48231 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:41009 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:39581 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:54105 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down INFO: connection closed PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:56225 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:52573 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down INFO: connection closed PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:52493 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:60911 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down INFO: connection closed PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:58949 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:56213 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down INFO: connection closed PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:58953 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:52733 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down INFO: connection closed PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:44713 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:45781 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down INFO: connection closed PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:45373 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:59811 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down INFO: connection closed PASSED -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 [2440875] -INFO: Uvicorn running on http://127.0.0.1:53155 (Press CTRL+C to quit) +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 [2211262] +INFO: Uvicorn running on http://127.0.0.1:55583 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down @@ -1799,14 +1835,14 @@ PASSED tests/protocols/test_http.py::test_invalid_http_request[h11-invalid-http-version] WARNING: Invalid HTTP request received. PASSED -tests/protocols/test_http.py::test_fragmentation INFO: Started server process [2440875] +tests/protocols/test_http.py::test_fragmentation INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:55601 (Press CTRL+C to quit) -INFO: 127.0.0.1:60510 - "GET /?param=qqqqqqqqqq HTTP/1.1" 200 OK +INFO: Uvicorn running on http://127.0.0.1:44769 (Press CTRL+C to quit) +INFO: 127.0.0.1:50484 - "GET /?param=qqqqqqqqqq HTTP/1.1" 200 OK INFO: Shutting down -INFO: Finished server process [2440875] +INFO: Finished server process [2211262] PASSED tests/protocols/test_http.py::test_huge_headers_h11protocol_failure WARNING: Invalid HTTP request received. PASSED @@ -1839,132 +1875,132 @@ tests/protocols/test_utils.py::test_get_remote_addr PASSED tests/protocols/test_utils.py::test_get_client_addr[ip:port client] PASSED tests/protocols/test_utils.py::test_get_client_addr[None client] PASSED -tests/supervisors/test_multiprocess.py::test_multiprocess_run INFO: Started parent process [2440875] -INFO: Stopping parent process [2440875] +tests/supervisors/test_multiprocess.py::test_multiprocess_run INFO: Started parent process [2211262] +INFO: Stopping parent process [2211262] PASSED -tests/supervisors/test_reload.py::TestBaseReload::test_reloader_should_initialize[StatReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1'] -INFO: Started reloader process [2440875] using StatReload -INFO: Stopping reloader process [2440875] +tests/supervisors/test_reload.py::TestBaseReload::test_reloader_should_initialize[StatReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1'] +INFO: Started reloader process [2211262] using StatReload +INFO: Stopping reloader process [2211262] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_reloader_should_initialize[None] SKIPPED -tests/supervisors/test_reload.py::TestBaseReload::test_reloader_should_initialize[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1'] -INFO: Started reloader process [2440875] using WatchFiles -INFO: Stopping reloader process [2440875] -PASSED -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-pbuilder1/pytest-0/reload_directory1'] -INFO: Started reloader process [2440875] using StatReload -INFO: Stopping reloader process [2440875] +tests/supervisors/test_reload.py::TestBaseReload::test_reloader_should_initialize[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1'] +INFO: Started reloader process [2211262] using WatchFiles +INFO: Stopping reloader process [2211262] +PASSED +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-pbuilder2/pytest-0/reload_directory1'] +INFO: Started reloader process [2211262] using StatReload +INFO: Stopping reloader process [2211262] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_reload_when_python_file_is_changed[None] SKIPPED -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-pbuilder1/pytest-0/reload_directory1'] -INFO: Started reloader process [2440875] using WatchFiles -INFO: Stopping reloader process [2440875] -PASSED -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-pbuilder1/pytest-0/reload_directory1'] -INFO: Started reloader process [2440875] using StatReload -INFO: Stopping reloader process [2440875] +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-pbuilder2/pytest-0/reload_directory1'] +INFO: Started reloader process [2211262] using WatchFiles +INFO: Stopping reloader process [2211262] +PASSED +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-pbuilder2/pytest-0/reload_directory1'] +INFO: Started reloader process [2211262] using StatReload +INFO: Stopping reloader process [2211262] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_should_reload_when_python_file_in_subdir_is_changed[None] SKIPPED -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-pbuilder1/pytest-0/reload_directory1'] -INFO: Started reloader process [2440875] using WatchFiles -INFO: Stopping reloader process [2440875] -PASSED -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-pbuilder1/pytest-0/reload_directory1'] -INFO: Started reloader process [2440875] using WatchFiles -INFO: Stopping reloader process [2440875] +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-pbuilder2/pytest-0/reload_directory1'] +INFO: Started reloader process [2211262] using WatchFiles +INFO: Stopping reloader process [2211262] +PASSED +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-pbuilder2/pytest-0/reload_directory1'] +INFO: Started reloader process [2211262] using WatchFiles +INFO: Stopping reloader process [2211262] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_python_file_in_excluded_subdir_is_changed[None] SKIPPED -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-pbuilder1/pytest-0/reload_directory1'] +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-pbuilder2/pytest-0/reload_directory1'] WARNING: --reload-include and --reload-exclude have no effect unless watchfiles is installed. -INFO: Started reloader process [2440875] using StatReload -INFO: Stopping reloader process [2440875] +INFO: Started reloader process [2211262] using StatReload +INFO: Stopping reloader process [2211262] PASSED -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-pbuilder1/pytest-0/reload_directory1'] -INFO: Started reloader process [2440875] using WatchFiles -INFO: Stopping reloader process [2440875] -PASSED -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-pbuilder1/pytest-0/reload_directory1/.dotted_dir', '/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1/app', '/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1/app_first', '/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1/app_second', '/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1/app_third', '/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1/ext'] -INFO: Started reloader process [2440875] using WatchFiles -INFO: Stopping reloader process [2440875] +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-pbuilder2/pytest-0/reload_directory1'] +INFO: Started reloader process [2211262] using WatchFiles +INFO: Stopping reloader process [2211262] +PASSED +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-pbuilder2/pytest-0/reload_directory1/.dotted_dir', '/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1/app', '/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1/app_first', '/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1/app_second', '/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1/app_third', '/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1/ext'] +INFO: Started reloader process [2211262] using WatchFiles +INFO: Stopping reloader process [2211262] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_exclude_pattern_match_file_is_changed[None] SKIPPED -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-pbuilder1/pytest-0/reload_directory1'] -INFO: Started reloader process [2440875] using StatReload -INFO: Stopping reloader process [2440875] +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-pbuilder2/pytest-0/reload_directory1'] +INFO: Started reloader process [2211262] using StatReload +INFO: Stopping reloader process [2211262] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_dot_file_is_changed[None] SKIPPED -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-pbuilder1/pytest-0/reload_directory1'] -INFO: Started reloader process [2440875] using WatchFiles -INFO: Stopping reloader process [2440875] -PASSED -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-pbuilder1/pytest-0/reload_directory1/app', '/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1/app_first'] -INFO: Started reloader process [2440875] using StatReload -INFO: Stopping reloader process [2440875] +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-pbuilder2/pytest-0/reload_directory1'] +INFO: Started reloader process [2211262] using WatchFiles +INFO: Stopping reloader process [2211262] +PASSED +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-pbuilder2/pytest-0/reload_directory1/app', '/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1/app_first'] +INFO: Started reloader process [2211262] using StatReload +INFO: Stopping reloader process [2211262] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_should_reload_when_directories_have_same_prefix[None] SKIPPED -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-pbuilder1/pytest-0/reload_directory1/app', '/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1/app_first'] -INFO: Started reloader process [2440875] using WatchFiles -INFO: Stopping reloader process [2440875] -PASSED -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-pbuilder1/pytest-0/reload_directory1/app'] -INFO: Started reloader process [2440875] using StatReload -INFO: Stopping reloader process [2440875] +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-pbuilder2/pytest-0/reload_directory1/app', '/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1/app_first'] +INFO: Started reloader process [2211262] using WatchFiles +INFO: Stopping reloader process [2211262] +PASSED +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-pbuilder2/pytest-0/reload_directory1/app'] +INFO: Started reloader process [2211262] using StatReload +INFO: Stopping reloader process [2211262] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_only_subdirectory_is_watched[None] SKIPPED -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-pbuilder1/pytest-0/reload_directory1/app'] -INFO: Started reloader process [2440875] using WatchFiles -INFO: Stopping reloader process [2440875] -PASSED -tests/supervisors/test_reload.py::TestBaseReload::test_override_defaults[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1'] -INFO: Started reloader process [2440875] using WatchFiles -INFO: Stopping reloader process [2440875] +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-pbuilder2/pytest-0/reload_directory1/app'] +INFO: Started reloader process [2211262] using WatchFiles +INFO: Stopping reloader process [2211262] +PASSED +tests/supervisors/test_reload.py::TestBaseReload::test_override_defaults[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1'] +INFO: Started reloader process [2211262] using WatchFiles +INFO: Stopping reloader process [2211262] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_override_defaults[None] SKIPPED -tests/supervisors/test_reload.py::TestBaseReload::test_explicit_paths[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1'] -INFO: Started reloader process [2440875] using WatchFiles -INFO: Stopping reloader process [2440875] +tests/supervisors/test_reload.py::TestBaseReload::test_explicit_paths[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1'] +INFO: Started reloader process [2211262] using WatchFiles +INFO: Stopping reloader process [2211262] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_explicit_paths[None] SKIPPED -tests/supervisors/test_reload.py::TestBaseReload::test_watchfiles_no_changes[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1'] -INFO: Started reloader process [2440875] using WatchFiles -INFO: Stopping reloader process [2440875] +tests/supervisors/test_reload.py::TestBaseReload::test_watchfiles_no_changes[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1'] +INFO: Started reloader process [2211262] using WatchFiles +INFO: Stopping reloader process [2211262] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_should_detect_new_reload_dirs[None] SKIPPED -tests/supervisors/test_reload.py::test_should_watch_one_dir_cwd INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1/app', '/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1/app_first'] +tests/supervisors/test_reload.py::test_should_watch_one_dir_cwd INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1/app', '/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1/app_first'] PASSED -tests/supervisors/test_reload.py::test_should_watch_separate_dirs_outside_cwd INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1/app', '/tmp/pytest-of-pbuilder1/pytest-0/reload_directory1/app_first'] +tests/supervisors/test_reload.py::test_should_watch_separate_dirs_outside_cwd INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1/app', '/tmp/pytest-of-pbuilder2/pytest-0/reload_directory1/app_first'] PASSED tests/supervisors/test_reload.py::test_display_path_relative PASSED tests/supervisors/test_reload.py::test_display_path_non_relative PASSED tests/supervisors/test_reload.py::test_base_reloader_run INFO: Will watch for changes in these directories: ['/build/reproducible-path/python-uvicorn-0.29.0/.pybuild/cpython3_3.12/build'] -WARNING: None detected changes in '/tmp/pytest-of-pbuilder1/pytest-0/test_base_reloader_run0/foobar.py'. Reloading... +WARNING: None detected changes in '/tmp/pytest-of-pbuilder2/pytest-0/test_base_reloader_run0/foobar.py'. Reloading... PASSED tests/supervisors/test_reload.py::test_base_reloader_should_exit INFO: Will watch for changes in these directories: ['/build/reproducible-path/python-uvicorn-0.29.0/.pybuild/cpython3_3.12/build'] PASSED tests/supervisors/test_reload.py::test_base_reloader_closes_sockets_on_shutdown INFO: Will watch for changes in these directories: ['/build/reproducible-path/python-uvicorn-0.29.0/.pybuild/cpython3_3.12/build'] -INFO: Started reloader process [2440875] using None -INFO: Stopping reloader process [2440875] +INFO: Started reloader process [2211262] using None +INFO: Stopping reloader process [2211262] PASSED -tests/supervisors/test_signal.py::test_sigint_finish_req INFO: Started server process [2440875] +tests/supervisors/test_signal.py::test_sigint_finish_req INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:48995 (Press CTRL+C to quit) -INFO: 127.0.0.1:44526 - "GET / HTTP/1.1" 200 OK +INFO: Uvicorn running on http://127.0.0.1:50347 (Press CTRL+C to quit) +INFO: 127.0.0.1:34632 - "GET / HTTP/1.1" 200 OK INFO: Shutting down INFO: Shutting down -INFO: Finished server process [2440875] +INFO: Finished server process [2211262] PASSED -tests/supervisors/test_signal.py::test_sigint_abort_req INFO: Started server process [2440875] +tests/supervisors/test_signal.py::test_sigint_abort_req INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:57175 (Press CTRL+C to quit) -INFO: 127.0.0.1:47480 - "GET / HTTP/1.1" 200 OK +INFO: Uvicorn running on http://127.0.0.1:52067 (Press CTRL+C to quit) +INFO: 127.0.0.1:58956 - "GET / HTTP/1.1" 200 OK INFO: Shutting down INFO: Waiting for connections to close. (CTRL+C to force quit) ERROR: Cancel 1 running task(s), timeout graceful shutdown exceeded -INFO: Finished server process [2440875] +INFO: Finished server process [2211262] ERROR: Exception in ASGI application Traceback (most recent call last): File "/build/reproducible-path/python-uvicorn-0.29.0/.pybuild/cpython3_3.12/build/uvicorn/protocols/http/httptools_impl.py", line 411, in run_asgi @@ -1980,13 +2016,13 @@ asyncio.exceptions.CancelledError: Task cancelled, timeout graceful shutdown exceeded INFO: Shutting down PASSED -tests/supervisors/test_signal.py::test_sigint_deny_request_after_triggered INFO: Started server process [2440875] +tests/supervisors/test_signal.py::test_sigint_deny_request_after_triggered INFO: Started server process [2211262] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:56403 (Press CTRL+C to quit) +INFO: Uvicorn running on http://127.0.0.1:33107 (Press CTRL+C to quit) INFO: Shutting down -INFO: Finished server process [2440875] +INFO: Finished server process [2211262] INFO: Shutting down PASSED @@ -2002,14 +2038,14 @@ SKIPPED [1] tests/supervisors/test_reload.py:227: Needed dependency not installed SKIPPED [1] tests/supervisors/test_reload.py:255: Needed dependency not installed SKIPPED [1] tests/supervisors/test_reload.py:310: Needed dependency not installed -=============== 343 passed, 11 skipped, 27 deselected in 16.64s ================ +=============== 343 passed, 11 skipped, 27 deselected in 17.06s ================ I: pybuild base:311: cd /build/reproducible-path/python-uvicorn-0.29.0/.pybuild/cpython3_3.11/build; python3.11 -m pytest -s --verbose -k 'not test_run and not test_invalid_upgrade and not test_default_headers and not test_trace_logging and not test_websocket_auto and not test_socket_bind' --ignore=tests/protocols/test_websocket.py ============================= test session starts ============================== platform linux -- Python 3.11.9, pytest-7.4.4, pluggy-1.5.0 -- /usr/bin/python3.11 cachedir: .pytest_cache rootdir: /build/reproducible-path/python-uvicorn-0.29.0/.pybuild/cpython3_3.11/build configfile: pyproject.toml -plugins: asyncio-0.20.3, mock-3.12.0, anyio-4.3.0 +plugins: anyio-4.3.0, asyncio-0.20.3, mock-3.12.0 asyncio: mode=Mode.STRICT collecting ... collected 381 items / 27 deselected / 354 selected @@ -2035,21 +2071,21 @@ 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. WARNING: Current configuration will not reload as not all conditions are met, please refer to documentation. PASSED -tests/test_config.py::test_reload_dir_is_set INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory0/app'] -INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory0/app'] +tests/test_config.py::test_reload_dir_is_set INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory0/app'] +INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory0/app'] PASSED 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. -INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory0'] +INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory0'] PASSED -tests/test_config.py::test_reload_subdir_removal INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory0'] +tests/test_config.py::test_reload_subdir_removal INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory0'] PASSED -tests/test_config.py::test_reload_included_dir_is_added_to_reload_dirs INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory0/app', '/tmp/pytest-of-pbuilder1/pytest-1/reload_directory0/ext'] +tests/test_config.py::test_reload_included_dir_is_added_to_reload_dirs INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory0/app', '/tmp/pytest-of-pbuilder2/pytest-1/reload_directory0/ext'] PASSED -tests/test_config.py::test_reload_dir_subdirectories_are_removed INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory0/app', '/tmp/pytest-of-pbuilder1/pytest-1/reload_directory0/ext'] +tests/test_config.py::test_reload_dir_subdirectories_are_removed INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory0/app', '/tmp/pytest-of-pbuilder2/pytest-1/reload_directory0/ext'] PASSED -tests/test_config.py::test_reload_excluded_subdirectories_are_removed INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory0'] +tests/test_config.py::test_reload_excluded_subdirectories_are_removed INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory0'] PASSED -tests/test_config.py::test_reload_includes_exclude_dir_patterns_are_matched INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory0/app_first/src', '/tmp/pytest-of-pbuilder1/pytest-1/reload_directory0/app_second/src'] +tests/test_config.py::test_reload_includes_exclude_dir_patterns_are_matched INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory0/app_first/src', '/tmp/pytest-of-pbuilder2/pytest-1/reload_directory0/app_second/src'] PASSED tests/test_config.py::test_wsgi_app PASSED tests/test_config.py::test_proxy_headers PASSED @@ -2072,13 +2108,13 @@ tests/test_config.py::test_log_config_yaml[log_config.yml] PASSED tests/test_config.py::test_log_config_yaml[log_config.yaml] PASSED tests/test_config.py::test_log_config_file PASSED -tests/test_config.py::test_env_file[0-127.0.0.1] INFO: Loading environment from '/tmp/pytest-of-pbuilder1/pytest-1/test_env_file_0_127_0_0_1_0/.env' +tests/test_config.py::test_env_file[0-127.0.0.1] INFO: Loading environment from '/tmp/pytest-of-pbuilder2/pytest-1/test_env_file_0_127_0_0_1_0/.env' PASSED -tests/test_config.py::test_env_file[0-127.0.0.2] INFO: Loading environment from '/tmp/pytest-of-pbuilder1/pytest-1/test_env_file_0_127_0_0_2_0/.env' +tests/test_config.py::test_env_file[0-127.0.0.2] INFO: Loading environment from '/tmp/pytest-of-pbuilder2/pytest-1/test_env_file_0_127_0_0_2_0/.env' PASSED -tests/test_config.py::test_env_file[1-127.0.0.1] INFO: Loading environment from '/tmp/pytest-of-pbuilder1/pytest-1/test_env_file_1_127_0_0_1_0/.env' +tests/test_config.py::test_env_file[1-127.0.0.1] INFO: Loading environment from '/tmp/pytest-of-pbuilder2/pytest-1/test_env_file_1_127_0_0_1_0/.env' PASSED -tests/test_config.py::test_env_file[1-127.0.0.2] INFO: Loading environment from '/tmp/pytest-of-pbuilder1/pytest-1/test_env_file_1_127_0_0_2_0/.env' +tests/test_config.py::test_env_file[1-127.0.0.2] INFO: Loading environment from '/tmp/pytest-of-pbuilder2/pytest-1/test_env_file_1_127_0_0_2_0/.env' PASSED tests/test_config.py::test_config_access_log[access log enabled should have single handler] PASSED tests/test_config.py::test_config_access_log[access log disabled shouldn't have handlers] PASSED @@ -2146,9 +2182,9 @@ tests/test_config.py::test_config_log_effective_level[50-50] PASSED tests/test_config.py::test_ws_max_size PASSED tests/test_config.py::test_ws_max_queue PASSED -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-pbuilder1/pytest-1/p-q504gjws-fa88f9a345cfc843594efbf5b2409e26/my.sock (Press CTRL+C to quit) +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-pbuilder2/pytest-1/p-1me5zhfk-cfd6fc2554360776ceea0cf5a5bfcf64/my.sock (Press CTRL+C to quit) PASSED -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-pbuilder1/pytest-1/p-m5mhrbil-fa88f9a345cfc843594efbf5b2409e26/my.sock (Press CTRL+C to quit) +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-pbuilder2/pytest-1/p-k7efbxtc-cfd6fc2554360776ceea0cf5a5bfcf64/my.sock (Press CTRL+C to quit) PASSED 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) PASSED @@ -2246,47 +2282,47 @@ INFO: Waiting for application shutdown. INFO: Application shutdown complete. PASSED -tests/test_main.py::test_exit_on_create_server_with_invalid_host INFO: Started server process [2443080] +tests/test_main.py::test_exit_on_create_server_with_invalid_host INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. ERROR: [Errno -3] Temporary failure in name resolution PASSED -tests/test_server.py::test_server_interrupt[capture_signal_sync-15] INFO: Started server process [2443080] +tests/test_server.py::test_server_interrupt[capture_signal_sync-15] INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: Application startup complete. INFO: Uvicorn running on http://127.0.0.1:8000 (Press CTRL+C to quit) INFO: Shutting down INFO: Waiting for application shutdown. INFO: Application shutdown complete. -INFO: Finished server process [2443080] +INFO: Finished server process [2216267] PASSED -tests/test_server.py::test_server_interrupt[capture_signal_sync-2] INFO: Started server process [2443080] +tests/test_server.py::test_server_interrupt[capture_signal_sync-2] INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: Application startup complete. INFO: Uvicorn running on http://127.0.0.1:8000 (Press CTRL+C to quit) INFO: Shutting down INFO: Waiting for application shutdown. INFO: Application shutdown complete. -INFO: Finished server process [2443080] +INFO: Finished server process [2216267] PASSED -tests/test_server.py::test_server_interrupt[capture_signal_async-15] INFO: Started server process [2443080] +tests/test_server.py::test_server_interrupt[capture_signal_async-15] INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: Application startup complete. INFO: Uvicorn running on http://127.0.0.1:8000 (Press CTRL+C to quit) INFO: Shutting down INFO: Waiting for application shutdown. INFO: Application shutdown complete. -INFO: Finished server process [2443080] +INFO: Finished server process [2216267] PASSED -tests/test_server.py::test_server_interrupt[capture_signal_async-2] INFO: Started server process [2443080] +tests/test_server.py::test_server_interrupt[capture_signal_async-2] INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: Application startup complete. INFO: Uvicorn running on http://127.0.0.1:8000 (Press CTRL+C to quit) INFO: Shutting down INFO: Waiting for application shutdown. INFO: Application shutdown complete. -INFO: Finished server process [2443080] +INFO: Finished server process [2216267] PASSED tests/test_subprocess.py::test_get_subprocess PASSED tests/test_subprocess.py::test_subprocess_started PASSED @@ -2297,59 +2333,59 @@ tests/importer/test_importer.py::test_valid_import PASSED tests/importer/test_importer.py::test_no_import_needed PASSED tests/importer/test_importer.py::test_circular_import_error PASSED -tests/middleware/test_logging.py::test_access_logging[True] INFO: Started server process [2443080] +tests/middleware/test_logging.py::test_access_logging[True] INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:36683 (Press CTRL+C to quit) -INFO: 127.0.0.1:50144 - "GET / HTTP/1.1" 204 No Content +INFO: Uvicorn running on http://127.0.0.1:37277 (Press CTRL+C to quit) +INFO: 127.0.0.1:46574 - "GET / HTTP/1.1" 204 No Content INFO: Shutting down PASSED -tests/middleware/test_logging.py::test_access_logging[False] INFO: Started server process [2443080] +tests/middleware/test_logging.py::test_access_logging[False] INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:39071 (Press CTRL+C to quit) -INFO: 127.0.0.1:33214 - "GET / HTTP/1.1" 204 No Content +INFO: Uvicorn running on http://127.0.0.1:57823 (Press CTRL+C to quit) +INFO: 127.0.0.1:52388 - "GET / HTTP/1.1" 204 No Content INFO: Shutting down PASSED -tests/middleware/test_logging.py::test_access_logging[None] INFO: Started server process [2443080] +tests/middleware/test_logging.py::test_access_logging[None] INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:47047 (Press CTRL+C to quit) -INFO: 127.0.0.1:51638 - "GET / HTTP/1.1" 204 No Content +INFO: Uvicorn running on http://127.0.0.1:60919 (Press CTRL+C to quit) +INFO: 127.0.0.1:58638 - "GET / HTTP/1.1" 204 No Content INFO: Shutting down PASSED -tests/middleware/test_logging.py::test_default_logging[True] INFO: Started server process [2443080] +tests/middleware/test_logging.py::test_default_logging[True] INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:52801 (Press CTRL+C to quit) -INFO: 127.0.0.1:42504 - "GET / HTTP/1.1" 204 No Content +INFO: Uvicorn running on http://127.0.0.1:34419 (Press CTRL+C to quit) +INFO: 127.0.0.1:52586 - "GET / HTTP/1.1" 204 No Content INFO: Shutting down PASSED -tests/middleware/test_logging.py::test_default_logging[False] INFO: Started server process [2443080] +tests/middleware/test_logging.py::test_default_logging[False] INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:38245 (Press CTRL+C to quit) -INFO: 127.0.0.1:42834 - "GET / HTTP/1.1" 204 No Content +INFO: Uvicorn running on http://127.0.0.1:60603 (Press CTRL+C to quit) +INFO: 127.0.0.1:55968 - "GET / HTTP/1.1" 204 No Content INFO: Shutting down PASSED -tests/middleware/test_logging.py::test_unknown_status_code INFO: Started server process [2443080] +tests/middleware/test_logging.py::test_unknown_status_code INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:46027 (Press CTRL+C to quit) -INFO: 127.0.0.1:35700 - "GET / HTTP/1.1" 599 +INFO: Uvicorn running on http://127.0.0.1:49763 (Press CTRL+C to quit) +INFO: 127.0.0.1:38216 - "GET / HTTP/1.1" 599 INFO: Shutting down PASSED -tests/middleware/test_logging.py::test_server_start_with_port_zero INFO: Started server process [2443080] +tests/middleware/test_logging.py::test_server_start_with_port_zero INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:46387 (Press CTRL+C to quit) +INFO: Uvicorn running on http://127.0.0.1:39079 (Press CTRL+C to quit) INFO: Shutting down PASSED tests/middleware/test_message_logger.py::test_message_logger PASSED @@ -2365,97 +2401,97 @@ tests/middleware/test_proxy_headers.py::test_proxy_headers_multiple_proxies[trusted_hosts2-Remote: https://1.2.3.4:0] PASSED tests/middleware/test_proxy_headers.py::test_proxy_headers_multiple_proxies[trusted_hosts3-Remote: https://10.0.2.1:0] PASSED tests/middleware/test_proxy_headers.py::test_proxy_headers_invalid_x_forwarded_for PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:50449 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:56433 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:43161 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:44147 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:48353 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:33603 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:51305 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:45671 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:46011 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:60913 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:52737 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:57183 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:40647 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:49205 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:35181 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:49699 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: Shutting down PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:54457 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:51999 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down INFO: connection closed PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:35501 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:57463 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down INFO: connection closed PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:56973 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:53167 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down INFO: connection closed PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:35347 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:57087 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down INFO: connection closed PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:34741 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:60851 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down INFO: connection closed PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:34583 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:50667 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down INFO: connection closed PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:53195 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:54467 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down INFO: connection closed PASSED -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 [2443080] -INFO: Uvicorn running on http://127.0.0.1:46945 (Press CTRL+C to quit) +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 [2216267] +INFO: Uvicorn running on http://127.0.0.1:37393 (Press CTRL+C to quit) INFO: ('1.2.3.4', 0) - "WebSocket /" [accepted] INFO: connection open INFO: Shutting down @@ -2886,14 +2922,14 @@ PASSED tests/protocols/test_http.py::test_invalid_http_request[h11-invalid-http-version] WARNING: Invalid HTTP request received. PASSED -tests/protocols/test_http.py::test_fragmentation INFO: Started server process [2443080] +tests/protocols/test_http.py::test_fragmentation INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:50711 (Press CTRL+C to quit) -INFO: 127.0.0.1:44104 - "GET /?param=qqqqqqqqqq HTTP/1.1" 200 OK +INFO: Uvicorn running on http://127.0.0.1:51221 (Press CTRL+C to quit) +INFO: 127.0.0.1:53182 - "GET /?param=qqqqqqqqqq HTTP/1.1" 200 OK INFO: Shutting down -INFO: Finished server process [2443080] +INFO: Finished server process [2216267] PASSED tests/protocols/test_http.py::test_huge_headers_h11protocol_failure WARNING: Invalid HTTP request received. PASSED @@ -2926,132 +2962,132 @@ tests/protocols/test_utils.py::test_get_remote_addr PASSED tests/protocols/test_utils.py::test_get_client_addr[ip:port client] PASSED tests/protocols/test_utils.py::test_get_client_addr[None client] PASSED -tests/supervisors/test_multiprocess.py::test_multiprocess_run INFO: Started parent process [2443080] -INFO: Stopping parent process [2443080] +tests/supervisors/test_multiprocess.py::test_multiprocess_run INFO: Started parent process [2216267] +INFO: Stopping parent process [2216267] PASSED -tests/supervisors/test_reload.py::TestBaseReload::test_reloader_should_initialize[StatReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1'] -INFO: Started reloader process [2443080] using StatReload -INFO: Stopping reloader process [2443080] +tests/supervisors/test_reload.py::TestBaseReload::test_reloader_should_initialize[StatReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1'] +INFO: Started reloader process [2216267] using StatReload +INFO: Stopping reloader process [2216267] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_reloader_should_initialize[None] SKIPPED -tests/supervisors/test_reload.py::TestBaseReload::test_reloader_should_initialize[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1'] -INFO: Started reloader process [2443080] using WatchFiles -INFO: Stopping reloader process [2443080] -PASSED -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-pbuilder1/pytest-1/reload_directory1'] -INFO: Started reloader process [2443080] using StatReload -INFO: Stopping reloader process [2443080] +tests/supervisors/test_reload.py::TestBaseReload::test_reloader_should_initialize[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1'] +INFO: Started reloader process [2216267] using WatchFiles +INFO: Stopping reloader process [2216267] +PASSED +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-pbuilder2/pytest-1/reload_directory1'] +INFO: Started reloader process [2216267] using StatReload +INFO: Stopping reloader process [2216267] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_reload_when_python_file_is_changed[None] SKIPPED -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-pbuilder1/pytest-1/reload_directory1'] -INFO: Started reloader process [2443080] using WatchFiles -INFO: Stopping reloader process [2443080] -PASSED -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-pbuilder1/pytest-1/reload_directory1'] -INFO: Started reloader process [2443080] using StatReload -INFO: Stopping reloader process [2443080] +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-pbuilder2/pytest-1/reload_directory1'] +INFO: Started reloader process [2216267] using WatchFiles +INFO: Stopping reloader process [2216267] +PASSED +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-pbuilder2/pytest-1/reload_directory1'] +INFO: Started reloader process [2216267] using StatReload +INFO: Stopping reloader process [2216267] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_should_reload_when_python_file_in_subdir_is_changed[None] SKIPPED -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-pbuilder1/pytest-1/reload_directory1'] -INFO: Started reloader process [2443080] using WatchFiles -INFO: Stopping reloader process [2443080] -PASSED -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-pbuilder1/pytest-1/reload_directory1'] -INFO: Started reloader process [2443080] using WatchFiles -INFO: Stopping reloader process [2443080] +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-pbuilder2/pytest-1/reload_directory1'] +INFO: Started reloader process [2216267] using WatchFiles +INFO: Stopping reloader process [2216267] +PASSED +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-pbuilder2/pytest-1/reload_directory1'] +INFO: Started reloader process [2216267] using WatchFiles +INFO: Stopping reloader process [2216267] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_python_file_in_excluded_subdir_is_changed[None] SKIPPED -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-pbuilder1/pytest-1/reload_directory1'] +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-pbuilder2/pytest-1/reload_directory1'] WARNING: --reload-include and --reload-exclude have no effect unless watchfiles is installed. -INFO: Started reloader process [2443080] using StatReload -INFO: Stopping reloader process [2443080] +INFO: Started reloader process [2216267] using StatReload +INFO: Stopping reloader process [2216267] PASSED -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-pbuilder1/pytest-1/reload_directory1'] -INFO: Started reloader process [2443080] using WatchFiles -INFO: Stopping reloader process [2443080] -PASSED -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-pbuilder1/pytest-1/reload_directory1/.dotted_dir', '/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1/app', '/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1/app_first', '/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1/app_second', '/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1/app_third', '/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1/ext'] -INFO: Started reloader process [2443080] using WatchFiles -INFO: Stopping reloader process [2443080] +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-pbuilder2/pytest-1/reload_directory1'] +INFO: Started reloader process [2216267] using WatchFiles +INFO: Stopping reloader process [2216267] +PASSED +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-pbuilder2/pytest-1/reload_directory1/.dotted_dir', '/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1/app', '/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1/app_first', '/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1/app_second', '/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1/app_third', '/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1/ext'] +INFO: Started reloader process [2216267] using WatchFiles +INFO: Stopping reloader process [2216267] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_exclude_pattern_match_file_is_changed[None] SKIPPED -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-pbuilder1/pytest-1/reload_directory1'] -INFO: Started reloader process [2443080] using StatReload -INFO: Stopping reloader process [2443080] +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-pbuilder2/pytest-1/reload_directory1'] +INFO: Started reloader process [2216267] using StatReload +INFO: Stopping reloader process [2216267] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_dot_file_is_changed[None] SKIPPED -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-pbuilder1/pytest-1/reload_directory1'] -INFO: Started reloader process [2443080] using WatchFiles -INFO: Stopping reloader process [2443080] -PASSED -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-pbuilder1/pytest-1/reload_directory1/app', '/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1/app_first'] -INFO: Started reloader process [2443080] using StatReload -INFO: Stopping reloader process [2443080] +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-pbuilder2/pytest-1/reload_directory1'] +INFO: Started reloader process [2216267] using WatchFiles +INFO: Stopping reloader process [2216267] +PASSED +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-pbuilder2/pytest-1/reload_directory1/app', '/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1/app_first'] +INFO: Started reloader process [2216267] using StatReload +INFO: Stopping reloader process [2216267] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_should_reload_when_directories_have_same_prefix[None] SKIPPED -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-pbuilder1/pytest-1/reload_directory1/app', '/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1/app_first'] -INFO: Started reloader process [2443080] using WatchFiles -INFO: Stopping reloader process [2443080] -PASSED -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-pbuilder1/pytest-1/reload_directory1/app'] -INFO: Started reloader process [2443080] using StatReload -INFO: Stopping reloader process [2443080] +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-pbuilder2/pytest-1/reload_directory1/app', '/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1/app_first'] +INFO: Started reloader process [2216267] using WatchFiles +INFO: Stopping reloader process [2216267] +PASSED +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-pbuilder2/pytest-1/reload_directory1/app'] +INFO: Started reloader process [2216267] using StatReload +INFO: Stopping reloader process [2216267] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_should_not_reload_when_only_subdirectory_is_watched[None] SKIPPED -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-pbuilder1/pytest-1/reload_directory1/app'] -INFO: Started reloader process [2443080] using WatchFiles -INFO: Stopping reloader process [2443080] -PASSED -tests/supervisors/test_reload.py::TestBaseReload::test_override_defaults[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1'] -INFO: Started reloader process [2443080] using WatchFiles -INFO: Stopping reloader process [2443080] +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-pbuilder2/pytest-1/reload_directory1/app'] +INFO: Started reloader process [2216267] using WatchFiles +INFO: Stopping reloader process [2216267] +PASSED +tests/supervisors/test_reload.py::TestBaseReload::test_override_defaults[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1'] +INFO: Started reloader process [2216267] using WatchFiles +INFO: Stopping reloader process [2216267] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_override_defaults[None] SKIPPED -tests/supervisors/test_reload.py::TestBaseReload::test_explicit_paths[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1'] -INFO: Started reloader process [2443080] using WatchFiles -INFO: Stopping reloader process [2443080] +tests/supervisors/test_reload.py::TestBaseReload::test_explicit_paths[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1'] +INFO: Started reloader process [2216267] using WatchFiles +INFO: Stopping reloader process [2216267] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_explicit_paths[None] SKIPPED -tests/supervisors/test_reload.py::TestBaseReload::test_watchfiles_no_changes[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1'] -INFO: Started reloader process [2443080] using WatchFiles -INFO: Stopping reloader process [2443080] +tests/supervisors/test_reload.py::TestBaseReload::test_watchfiles_no_changes[WatchFilesReload] INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1'] +INFO: Started reloader process [2216267] using WatchFiles +INFO: Stopping reloader process [2216267] PASSED tests/supervisors/test_reload.py::TestBaseReload::test_should_detect_new_reload_dirs[None] SKIPPED -tests/supervisors/test_reload.py::test_should_watch_one_dir_cwd INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1/app', '/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1/app_first'] +tests/supervisors/test_reload.py::test_should_watch_one_dir_cwd INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1/app', '/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1/app_first'] PASSED -tests/supervisors/test_reload.py::test_should_watch_separate_dirs_outside_cwd INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1/app', '/tmp/pytest-of-pbuilder1/pytest-1/reload_directory1/app_first'] +tests/supervisors/test_reload.py::test_should_watch_separate_dirs_outside_cwd INFO: Will watch for changes in these directories: ['/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1/app', '/tmp/pytest-of-pbuilder2/pytest-1/reload_directory1/app_first'] PASSED tests/supervisors/test_reload.py::test_display_path_relative PASSED tests/supervisors/test_reload.py::test_display_path_non_relative PASSED tests/supervisors/test_reload.py::test_base_reloader_run INFO: Will watch for changes in these directories: ['/build/reproducible-path/python-uvicorn-0.29.0/.pybuild/cpython3_3.11/build'] -WARNING: None detected changes in '/tmp/pytest-of-pbuilder1/pytest-1/test_base_reloader_run0/foobar.py'. Reloading... +WARNING: None detected changes in '/tmp/pytest-of-pbuilder2/pytest-1/test_base_reloader_run0/foobar.py'. Reloading... PASSED tests/supervisors/test_reload.py::test_base_reloader_should_exit INFO: Will watch for changes in these directories: ['/build/reproducible-path/python-uvicorn-0.29.0/.pybuild/cpython3_3.11/build'] PASSED tests/supervisors/test_reload.py::test_base_reloader_closes_sockets_on_shutdown INFO: Will watch for changes in these directories: ['/build/reproducible-path/python-uvicorn-0.29.0/.pybuild/cpython3_3.11/build'] -INFO: Started reloader process [2443080] using None -INFO: Stopping reloader process [2443080] +INFO: Started reloader process [2216267] using None +INFO: Stopping reloader process [2216267] PASSED -tests/supervisors/test_signal.py::test_sigint_finish_req INFO: Started server process [2443080] +tests/supervisors/test_signal.py::test_sigint_finish_req INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:44373 (Press CTRL+C to quit) -INFO: 127.0.0.1:42610 - "GET / HTTP/1.1" 200 OK +INFO: Uvicorn running on http://127.0.0.1:59005 (Press CTRL+C to quit) +INFO: 127.0.0.1:54588 - "GET / HTTP/1.1" 200 OK INFO: Shutting down INFO: Shutting down -INFO: Finished server process [2443080] +INFO: Finished server process [2216267] PASSED -tests/supervisors/test_signal.py::test_sigint_abort_req INFO: Started server process [2443080] +tests/supervisors/test_signal.py::test_sigint_abort_req INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:54087 (Press CTRL+C to quit) -INFO: 127.0.0.1:44514 - "GET / HTTP/1.1" 200 OK +INFO: Uvicorn running on http://127.0.0.1:57367 (Press CTRL+C to quit) +INFO: 127.0.0.1:45040 - "GET / HTTP/1.1" 200 OK INFO: Shutting down INFO: Waiting for connections to close. (CTRL+C to force quit) ERROR: Cancel 1 running task(s), timeout graceful shutdown exceeded -INFO: Finished server process [2443080] +INFO: Finished server process [2216267] ERROR: Exception in ASGI application Traceback (most recent call last): File "/build/reproducible-path/python-uvicorn-0.29.0/.pybuild/cpython3_3.11/build/uvicorn/protocols/http/httptools_impl.py", line 411, in run_asgi @@ -3067,13 +3103,13 @@ asyncio.exceptions.CancelledError: Task cancelled, timeout graceful shutdown exceeded INFO: Shutting down PASSED -tests/supervisors/test_signal.py::test_sigint_deny_request_after_triggered INFO: Started server process [2443080] +tests/supervisors/test_signal.py::test_sigint_deny_request_after_triggered INFO: Started server process [2216267] INFO: Waiting for application startup. INFO: ASGI 'lifespan' protocol appears unsupported. INFO: Application startup complete. -INFO: Uvicorn running on http://127.0.0.1:50939 (Press CTRL+C to quit) +INFO: Uvicorn running on http://127.0.0.1:40189 (Press CTRL+C to quit) INFO: Shutting down -INFO: Finished server process [2443080] +INFO: Finished server process [2216267] INFO: Shutting down PASSED @@ -3089,7 +3125,7 @@ SKIPPED [1] tests/supervisors/test_reload.py:227: Needed dependency not installed SKIPPED [1] tests/supervisors/test_reload.py:255: Needed dependency not installed SKIPPED [1] tests/supervisors/test_reload.py:310: Needed dependency not installed -=============== 343 passed, 11 skipped, 27 deselected in 18.72s ================ +=============== 343 passed, 11 skipped, 27 deselected in 15.00s ================ make[1]: Leaving directory '/build/reproducible-path/python-uvicorn-0.29.0' create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=pybuild @@ -3125,8 +3161,8 @@ dh_gencontrol -O--buildsystem=pybuild dh_md5sums -O--buildsystem=pybuild dh_builddeb -O--buildsystem=pybuild -dpkg-deb: building package 'python3-uvicorn' in '../python3-uvicorn_0.29.0-1_all.deb'. dpkg-deb: building package 'python-uvicorn-doc' in '../python-uvicorn-doc_0.29.0-1_all.deb'. +dpkg-deb: building package 'python3-uvicorn' in '../python3-uvicorn_0.29.0-1_all.deb'. dpkg-deb: building package 'uvicorn' in '../uvicorn_0.29.0-1_all.deb'. dpkg-genbuildinfo --build=binary -O../python-uvicorn_0.29.0-1_amd64.buildinfo dpkg-genchanges --build=binary -O../python-uvicorn_0.29.0-1_amd64.changes @@ -3135,12 +3171,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: including full source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/2149763/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/2149763/tmp/hooks/B01_cleanup finished I: unmounting dev/ptmx filesystem I: unmounting dev/pts filesystem I: unmounting dev/shm filesystem I: unmounting proc filesystem I: unmounting sys filesystem I: cleaning the build env -I: removing directory /srv/workspace/pbuilder/2410362 and its subdirectories -I: Current time: Tue May 14 18:19:22 -12 2024 -I: pbuilder-time-stamp: 1715753962 +I: removing directory /srv/workspace/pbuilder/2149763 and its subdirectories +I: Current time: Wed Jun 18 02:45:58 +14 2025 +I: pbuilder-time-stamp: 1750164358