Diff of the two buildlogs: -- --- b1/build.log 2023-05-22 18:19:47.726179720 +0000 +++ b2/build.log 2023-05-22 18:25:52.706209360 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Mon May 22 06:13:25 -12 2023 -I: pbuilder-time-stamp: 1684779205 +I: Current time: Mon Jun 24 14:42:51 +14 2024 +I: pbuilder-time-stamp: 1719189771 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bookworm-reproducible-base.tgz] I: copying local configuration @@ -16,7 +16,7 @@ I: copying [./golang-golang-x-net_0.7.0+dfsg.orig.tar.gz] I: copying [./golang-golang-x-net_0.7.0+dfsg-1.debian.tar.xz] I: Extracting source -gpgv: Signature made Tue Feb 14 19:37:23 2023 -12 +gpgv: Signature made Wed Feb 15 21:37:23 2023 +14 gpgv: using EDDSA key 9185D4F57767AEEE6C55D41E7F44FA25EA5514CB gpgv: Can't check signature: No public key dpkg-source: warning: cannot verify inline signature for ./golang-golang-x-net_0.7.0+dfsg-1.dsc: no acceptable signature found @@ -27,52 +27,84 @@ dpkg-source: info: applying publicsuffix.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/5924/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/29662/tmp/hooks/D01_modify_environment starting +debug: Running on codethink15-arm64. +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 24 14:43 /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/29662/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/29662/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='arm64' - DEBIAN_FRONTEND='noninteractive' + 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]="15" [3]="1" [4]="release" [5]="aarch64-unknown-linux-gnu") + BASH_VERSION='5.2.15(1)-release' + BUILDDIR=/build + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=arm64 + DEBIAN_FRONTEND=noninteractive DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=8 ' - DISTRIBUTION='bookworm' - HOME='/var/lib/jenkins' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION=bookworm + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/var/lib/jenkins + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - 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='5924' - PS1='# ' - PS2='> ' + LANG=C + LANGUAGE=nl_BE:nl + LC_ALL=C + MACHTYPE=aarch64-unknown-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=29662 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.AqNwMZOM/pbuilderrc_iv6H --distribution bookworm --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.AqNwMZOM/b1 --logfile b1/build.log golang-golang-x-net_0.7.0+dfsg-1.dsc' - SUDO_GID='117' - SUDO_UID='110' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - USERNAME='root' - _='/usr/bin/systemd-run' - http_proxy='http://192.168.101.16: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.AqNwMZOM/pbuilderrc_wVfJ --distribution bookworm --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.AqNwMZOM/b2 --logfile b2/build.log --extrapackages usrmerge golang-golang-x-net_0.7.0+dfsg-1.dsc' + SUDO_GID=117 + SUDO_UID=110 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + USERNAME=root + _='I: set' + http_proxy=http://192.168.101.16:3128 I: uname -a - Linux codethink12-arm64 4.15.0-211-generic #222-Ubuntu SMP Tue Apr 18 18:58:27 UTC 2023 aarch64 GNU/Linux + Linux i-capture-the-hostname 4.15.0-211-generic #222-Ubuntu SMP Tue Apr 18 18:58:27 UTC 2023 aarch64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 20 22:26 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/5924/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Jun 24 06:47 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/29662/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -157,7 +189,7 @@ Get: 37 http://deb.debian.org/debian bookworm/main arm64 golang-golang-x-sys-dev all 0.3.0-1 [393 kB] Get: 38 http://deb.debian.org/debian bookworm/main arm64 golang-golang-x-text-dev all 0.7.0-1 [3863 kB] Get: 39 http://deb.debian.org/debian bookworm/main arm64 publicsuffix all 20230209.2326-1 [126 kB] -Fetched 97.7 MB in 4s (22.6 MB/s) +Fetched 97.7 MB in 5s (18.9 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package sensible-utils. (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 ... 19616 files and directories currently installed.) @@ -327,8 +359,17 @@ Writing extended state information... Building tag database... -> Finished parsing the build-deps +Reading package lists... +Building dependency tree... +Reading state information... +usrmerge is already the newest version (35). +0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/golang-golang-x-net-0.7.0+dfsg/ && 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 > ../golang-golang-x-net_0.7.0+dfsg-1_source.changes +I: user script /srv/workspace/pbuilder/29662/tmp/hooks/A99_set_merged_usr starting +Re-configuring usrmerge... +I: user script /srv/workspace/pbuilder/29662/tmp/hooks/A99_set_merged_usr finished +hostname: Temporary failure in name resolution +I: Running cd /build/golang-golang-x-net-0.7.0+dfsg/ && 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 > ../golang-golang-x-net_0.7.0+dfsg-1_source.changes dpkg-buildpackage: info: source package golang-golang-x-net dpkg-buildpackage: info: source version 1:0.7.0+dfsg-1 dpkg-buildpackage: info: source distribution unstable @@ -656,29 +697,29 @@ src/golang.org/x/net/xsrftoken/xsrf_test.go cd _build && go install -trimpath -v -p 8 golang.org/x/net/bpf golang.org/x/net/context golang.org/x/net/context/ctxhttp golang.org/x/net/dict golang.org/x/net/dns/dnsmessage golang.org/x/net/html golang.org/x/net/html/atom golang.org/x/net/html/charset golang.org/x/net/http/httpguts golang.org/x/net/http/httpproxy golang.org/x/net/http2 golang.org/x/net/http2/h2c golang.org/x/net/http2/hpack golang.org/x/net/icmp golang.org/x/net/idna golang.org/x/net/internal/iana golang.org/x/net/internal/socket golang.org/x/net/internal/socks golang.org/x/net/internal/sockstest golang.org/x/net/internal/timeseries golang.org/x/net/ipv4 golang.org/x/net/ipv6 golang.org/x/net/nettest golang.org/x/net/netutil golang.org/x/net/proxy golang.org/x/net/publicsuffix golang.org/x/net/trace golang.org/x/net/webdav golang.org/x/net/webdav/internal/xml golang.org/x/net/websocket golang.org/x/net/xsrftoken internal/goarch -internal/race +internal/unsafeheader internal/goexperiment +internal/race internal/goos -internal/unsafeheader -sync/atomic internal/cpu +sync/atomic runtime/internal/syscall -internal/abi runtime/internal/math +internal/abi math/bits -runtime/internal/sys internal/itoa +runtime/internal/sys unicode/utf8 unicode container/list crypto/internal/subtle crypto/subtle -crypto/internal/boring/sig -unicode/utf16 -vendor/golang.org/x/crypto/cryptobyte/asn1 internal/bytealg +crypto/internal/boring/sig runtime/internal/atomic math +unicode/utf16 +vendor/golang.org/x/crypto/cryptobyte/asn1 internal/nettrace vendor/golang.org/x/crypto/internal/subtle golang.org/x/net/html/atom @@ -690,108 +731,109 @@ internal/reflectlite sync internal/testlog -internal/singleflight math/rand +internal/singleflight internal/sysinfo runtime/cgo -errors sort +errors strconv internal/oserror internal/safefilepath path io crypto/internal/nistec/fiat -vendor/golang.org/x/net/dns/dnsmessage syscall +vendor/golang.org/x/net/dns/dnsmessage golang.org/x/net/dns/dnsmessage golang.org/x/text/internal/tag container/heap -strings bytes +strings hash hash/crc32 reflect +bufio crypto crypto/internal/randutil crypto/rc4 -bufio vendor/golang.org/x/text/transform net/http/internal/ascii golang.org/x/text/transform html text/tabwriter -golang.org/x/text/encoding golang.org/x/text/runes -golang.org/x/text/encoding/internal +golang.org/x/text/encoding internal/syscall/unix time +internal/syscall/execenv +golang.org/x/text/encoding/internal golang.org/x/text/encoding/charmap golang.org/x/text/encoding/japanese golang.org/x/text/encoding/korean golang.org/x/text/encoding/simplifiedchinese -internal/syscall/execenv golang.org/x/text/encoding/traditionalchinese internal/poll io/fs context +golang.org/x/text/encoding/unicode os -embed golang.org/x/net/context -golang.org/x/text/encoding/unicode encoding/binary internal/fmtsort -fmt -internal/godebug -path/filepath -io/ioutil -internal/intern -net/netip +embed crypto/cipher -crypto/internal/nistec -encoding/base64 crypto/internal/edwards25519/field +crypto/internal/nistec crypto/md5 -vendor/golang.org/x/crypto/internal/poly1305 +encoding/base64 vendor/golang.org/x/crypto/curve25519/internal/field +vendor/golang.org/x/crypto/internal/poly1305 encoding/pem golang.org/x/sys/unix -os/exec +fmt +internal/godebug +path/filepath +crypto/internal/edwards25519 +io/ioutil +internal/intern crypto/internal/boring crypto/des crypto/aes crypto/sha512 -crypto/internal/edwards25519 crypto/hmac crypto/sha1 crypto/sha256 -net +net/netip vendor/golang.org/x/crypto/chacha20 vendor/golang.org/x/crypto/hkdf +os/exec +vendor/golang.org/x/crypto/chacha20poly1305 golang.org/x/net/bpf compress/flate -math/big encoding/hex net/url -vendor/golang.org/x/crypto/chacha20poly1305 +math/big +net vendor/golang.org/x/crypto/curve25519 log vendor/golang.org/x/text/unicode/norm vendor/golang.org/x/text/unicode/bidi vendor/golang.org/x/net/http2/hpack mime -compress/gzip mime/quotedprintable +compress/gzip net/http/internal golang.org/x/net/html golang.org/x/text/internal/language golang.org/x/text/unicode/bidi vendor/golang.org/x/text/secure/bidirule golang.org/x/text/unicode/norm -golang.org/x/text/secure/bidirule golang.org/x/net/http2/hpack -vendor/golang.org/x/net/idna flag +vendor/golang.org/x/net/idna +golang.org/x/text/secure/bidirule +runtime/debug crypto/rand crypto/elliptic crypto/internal/boring/bbig @@ -800,40 +842,39 @@ crypto/rsa crypto/dsa golang.org/x/text/internal/language/compact -runtime/debug runtime/trace golang.org/x/net/internal/timeseries golang.org/x/text/language +testing +encoding/json vendor/golang.org/x/crypto/cryptobyte crypto/x509/pkix golang.org/x/net/idna -testing -encoding/json text/template/parse encoding/xml crypto/ecdsa golang.org/x/text/encoding/htmlindex -golang.org/x/net/webdav/internal/xml golang.org/x/net/html/charset +golang.org/x/net/webdav/internal/xml golang.org/x/net/xsrftoken text/template html/template -net/textproto vendor/golang.org/x/net/http/httpproxy +golang.org/x/net/nettest golang.org/x/net/http/httpproxy -golang.org/x/net/netutil crypto/x509 +net/textproto golang.org/x/net/internal/socket golang.org/x/net/internal/socks -golang.org/x/net/nettest +golang.org/x/net/netutil golang.org/x/net/proxy vendor/golang.org/x/net/http/httpguts mime/multipart golang.org/x/net/dict golang.org/x/net/http/httpguts golang.org/x/net/internal/sockstest -golang.org/x/net/ipv4 golang.org/x/net/ipv6 +golang.org/x/net/ipv4 crypto/tls golang.org/x/net/icmp net/http/httptrace @@ -841,8 +882,8 @@ golang.org/x/net/context/ctxhttp net/http/cookiejar golang.org/x/net/trace -golang.org/x/net/websocket golang.org/x/net/webdav +golang.org/x/net/websocket golang.org/x/net/http2 golang.org/x/net/publicsuffix golang.org/x/net/http2/h2c @@ -860,9 +901,9 @@ === RUN TestString --- PASS: TestString (0.00s) === RUN TestVMALUOpAdd ---- PASS: TestVMALUOpAdd (0.11s) +--- PASS: TestVMALUOpAdd (0.07s) === RUN TestVMALUOpSub ---- PASS: TestVMALUOpSub (0.05s) +--- PASS: TestVMALUOpSub (0.06s) === RUN TestVMALUOpMul --- PASS: TestVMALUOpMul (0.00s) === RUN TestVMALUOpDiv @@ -870,7 +911,7 @@ === RUN TestVMALUOpDivByZeroALUOpConstant --- PASS: TestVMALUOpDivByZeroALUOpConstant (0.00s) === RUN TestVMALUOpDivByZeroALUOpX ---- PASS: TestVMALUOpDivByZeroALUOpX (0.06s) +--- PASS: TestVMALUOpDivByZeroALUOpX (0.05s) === RUN TestVMALUOpOr --- PASS: TestVMALUOpOr (0.00s) === RUN TestVMALUOpAnd @@ -884,7 +925,7 @@ === RUN TestVMALUOpModByZeroALUOpConstant --- PASS: TestVMALUOpModByZeroALUOpConstant (0.00s) === RUN TestVMALUOpModByZeroALUOpX ---- PASS: TestVMALUOpModByZeroALUOpX (0.06s) +--- PASS: TestVMALUOpModByZeroALUOpX (0.05s) === RUN TestVMALUOpXor --- PASS: TestVMALUOpXor (0.00s) === RUN TestVMALUOpUnknown @@ -914,7 +955,7 @@ === RUN TestVMJumpIfLessThan --- PASS: TestVMJumpIfLessThan (0.00s) === RUN TestVMJumpIfGreaterOrEqual ---- PASS: TestVMJumpIfGreaterOrEqual (0.02s) +--- PASS: TestVMJumpIfGreaterOrEqual (0.00s) === RUN TestVMJumpIfLessOrEqual --- PASS: TestVMJumpIfLessOrEqual (0.00s) === RUN TestVMJumpIfBitsSet @@ -932,21 +973,21 @@ === RUN TestVMJumpIfXGreaterOrEqual --- PASS: TestVMJumpIfXGreaterOrEqual (0.00s) === RUN TestVMJumpIfXLessOrEqual ---- PASS: TestVMJumpIfXLessOrEqual (0.04s) +--- PASS: TestVMJumpIfXLessOrEqual (0.00s) === RUN TestVMJumpIfXBitsSet ---- PASS: TestVMJumpIfXBitsSet (0.01s) +--- PASS: TestVMJumpIfXBitsSet (0.00s) === RUN TestVMJumpIfXBitsNotSet --- PASS: TestVMJumpIfXBitsNotSet (0.00s) === RUN TestVMLoadAbsoluteOffsetOutOfBounds --- PASS: TestVMLoadAbsoluteOffsetOutOfBounds (0.06s) === RUN TestVMLoadAbsoluteOffsetPlusSizeOutOfBounds ---- PASS: TestVMLoadAbsoluteOffsetPlusSizeOutOfBounds (0.05s) +--- PASS: TestVMLoadAbsoluteOffsetPlusSizeOutOfBounds (0.06s) === RUN TestVMLoadAbsoluteBadInstructionSize --- PASS: TestVMLoadAbsoluteBadInstructionSize (0.00s) === RUN TestVMLoadConstantOK --- PASS: TestVMLoadConstantOK (0.00s) === RUN TestVMLoadIndirectOutOfBounds ---- PASS: TestVMLoadIndirectOutOfBounds (0.06s) +--- PASS: TestVMLoadIndirectOutOfBounds (0.05s) === RUN TestVMLoadMemShiftOutOfBounds --- PASS: TestVMLoadMemShiftOutOfBounds (0.05s) === RUN TestVMLoadMemShiftLoadIndirectNoResult @@ -978,7 +1019,7 @@ === RUN TestVMStoreScratchLoadScratchMultipleValues --- PASS: TestVMStoreScratchLoadScratchMultipleValues (0.00s) === RUN TestVMUnknownInstruction ---- PASS: TestVMUnknownInstruction (0.06s) +--- PASS: TestVMUnknownInstruction (0.05s) === RUN TestVMNoReturnInstruction --- PASS: TestVMNoReturnInstruction (0.00s) === RUN TestVMNoInputInstructions @@ -986,21 +1027,21 @@ === RUN ExampleNewVM --- PASS: ExampleNewVM (0.00s) PASS -ok golang.org/x/net/bpf 0.796s +ok golang.org/x/net/bpf 0.620s === RUN ExampleWithTimeout --- PASS: ExampleWithTimeout (0.05s) PASS -ok golang.org/x/net/context 0.119s +ok golang.org/x/net/context 0.067s === RUN TestGo17Context --- PASS: TestGo17Context (0.03s) === RUN TestNoTimeout ---- PASS: TestNoTimeout (0.10s) +--- PASS: TestNoTimeout (0.12s) === RUN TestCancelBeforeHeaders ---- PASS: TestCancelBeforeHeaders (0.00s) +--- PASS: TestCancelBeforeHeaders (0.01s) === RUN TestCancelAfterHangingRequest ---- PASS: TestCancelAfterHangingRequest (0.02s) +--- PASS: TestCancelAfterHangingRequest (0.00s) PASS -ok golang.org/x/net/context/ctxhttp 0.211s +ok golang.org/x/net/context/ctxhttp 0.207s ? golang.org/x/net/dict [no test files] === RUN TestPrintPaddedUint8 --- PASS: TestPrintPaddedUint8 (0.00s) @@ -1039,9 +1080,9 @@ === RUN TestSkipNotStarted --- PASS: TestSkipNotStarted (0.00s) === RUN TestTooManyRecords ---- PASS: TestTooManyRecords (0.10s) +--- PASS: TestTooManyRecords (0.20s) === RUN TestVeryLongTxt ---- PASS: TestVeryLongTxt (0.00s) +--- PASS: TestVeryLongTxt (0.04s) === RUN TestTooLongTxt --- PASS: TestTooLongTxt (0.00s) === RUN TestStartAppends @@ -1053,7 +1094,7 @@ === RUN TestFinishError --- PASS: TestFinishError (0.00s) === RUN TestBuilder ---- PASS: TestBuilder (0.01s) +--- PASS: TestBuilder (0.00s) === RUN TestResourcePack --- PASS: TestResourcePack (0.00s) === RUN TestResourcePackLength @@ -1067,17 +1108,17 @@ === RUN TestGoString --- PASS: TestGoString (0.00s) === RUN TestParsingAllocs ---- PASS: TestParsingAllocs (0.01s) +--- PASS: TestParsingAllocs (0.00s) === RUN TestBuildingAllocs ---- PASS: TestBuildingAllocs (0.00s) +--- PASS: TestBuildingAllocs (0.01s) === RUN TestNoFmt --- PASS: TestNoFmt (0.00s) === RUN ExampleParser --- PASS: ExampleParser (0.00s) PASS -ok golang.org/x/net/dns/dnsmessage 0.129s +ok golang.org/x/net/dns/dnsmessage 0.273s === RUN TestComments ---- PASS: TestComments (0.42s) +--- PASS: TestComments (0.84s) === RUN TestEntityLength --- PASS: TestEntityLength (0.00s) === RUN TestUnescape @@ -1085,7 +1126,7 @@ === RUN TestUnescapeEscape --- PASS: TestUnescapeEscape (0.00s) === RUN TestParser ---- PASS: TestParser (0.69s) +--- PASS: TestParser (0.81s) === RUN TestParserWithoutScripting --- PASS: TestParserWithoutScripting (0.00s) === RUN TestNodeConsistency @@ -1188,7 +1229,7 @@ === RUN TestTokenizer/Attribute_name_characters === RUN TestTokenizer/Mixed_attributes === RUN TestTokenizer/Attributes_with_a_solitary_single_quote ---- PASS: TestTokenizer (0.01s) +--- PASS: TestTokenizer (0.03s) --- PASS: TestTokenizer/empty (0.00s) --- PASS: TestTokenizer/text (0.00s) --- PASS: TestTokenizer/entity (0.00s) @@ -1283,7 +1324,7 @@ === RUN TestMaxBuffer --- PASS: TestMaxBuffer (0.00s) === RUN TestMaxBufferReconstruction ---- PASS: TestMaxBufferReconstruction (0.04s) +--- PASS: TestMaxBufferReconstruction (0.08s) === RUN TestPassthrough --- PASS: TestPassthrough (0.00s) === RUN TestBufAPI @@ -1295,7 +1336,7 @@ === RUN ExampleParse --- PASS: ExampleParse (0.00s) PASS -ok golang.org/x/net/html 1.241s +ok golang.org/x/net/html 1.847s === RUN TestKnown --- PASS: TestKnown (0.00s) === RUN TestHits @@ -1305,7 +1346,7 @@ === RUN TestForeignObject --- PASS: TestForeignObject (0.00s) PASS -ok golang.org/x/net/html/atom 0.010s +ok golang.org/x/net/html/atom 0.058s === RUN TestDecode --- PASS: TestDecode (0.00s) === RUN TestEncode @@ -1317,9 +1358,9 @@ === RUN TestFromMeta --- PASS: TestFromMeta (0.00s) === RUN TestXML ---- PASS: TestXML (0.00s) +--- PASS: TestXML (0.03s) PASS -ok golang.org/x/net/html/charset 0.070s +ok golang.org/x/net/html/charset 0.106s === RUN TestIsToken --- PASS: TestIsToken (0.00s) === RUN TestHeaderValuesContainsToken @@ -1327,7 +1368,7 @@ === RUN TestPunycodeHostPort --- PASS: TestPunycodeHostPort (0.00s) PASS -ok golang.org/x/net/http/httpguts 0.084s +ok golang.org/x/net/http/httpguts 0.026s === RUN TestProxyForURL --- PASS: TestProxyForURL (0.00s) === RUN TestFromEnvironment @@ -1343,7 +1384,7 @@ === RUN TestAllNoProxy --- PASS: TestAllNoProxy (0.00s) PASS -ok golang.org/x/net/http/httpproxy 0.067s +ok golang.org/x/net/http/httpproxy 0.040s === RUN TestASCIIEqualFold === RUN TestASCIIEqualFold/empty === RUN TestASCIIEqualFold/simple_match @@ -1381,7 +1422,7 @@ === RUN TestDataBufferAllocation/ReadSize=32768 --- PASS: TestDataBufferAllocation (0.01s) --- PASS: TestDataBufferAllocation/ReadSize=1 (0.00s) - --- PASS: TestDataBufferAllocation/ReadSize=2 (0.00s) + --- PASS: TestDataBufferAllocation/ReadSize=2 (0.01s) --- PASS: TestDataBufferAllocation/ReadSize=1024 (0.00s) --- PASS: TestDataBufferAllocation/ReadSize=32768 (0.00s) === RUN TestDataBufferAllocationWithExpected @@ -1389,9 +1430,9 @@ === RUN TestDataBufferAllocationWithExpected/ReadSize=2 === RUN TestDataBufferAllocationWithExpected/ReadSize=1024 === RUN TestDataBufferAllocationWithExpected/ReadSize=32768 ---- PASS: TestDataBufferAllocationWithExpected (0.01s) +--- PASS: TestDataBufferAllocationWithExpected (0.00s) --- PASS: TestDataBufferAllocationWithExpected/ReadSize=1 (0.00s) - --- PASS: TestDataBufferAllocationWithExpected/ReadSize=2 (0.01s) + --- PASS: TestDataBufferAllocationWithExpected/ReadSize=2 (0.00s) --- PASS: TestDataBufferAllocationWithExpected/ReadSize=1024 (0.00s) --- PASS: TestDataBufferAllocationWithExpected/ReadSize=32768 (0.00s) === RUN TestDataBufferWriteAfterPartialRead @@ -1451,9 +1492,9 @@ === RUN TestReadFrameHeader --- PASS: TestReadFrameHeader (0.00s) === RUN TestReadWriteFrameHeader ---- PASS: TestReadWriteFrameHeader (0.08s) +--- PASS: TestReadWriteFrameHeader (0.07s) === RUN TestWriteTooLargeFrame ---- PASS: TestWriteTooLargeFrame (0.01s) +--- PASS: TestWriteTooLargeFrame (0.04s) === RUN TestWriteGoAway --- PASS: TestWriteGoAway (0.00s) === RUN TestWritePushPromise @@ -1475,7 +1516,7 @@ === RUN TestSettingString --- PASS: TestSettingString (0.00s) === RUN TestSorterPoolAllocs ---- PASS: TestSorterPoolAllocs (0.00s) +--- PASS: TestSorterPoolAllocs (0.03s) === RUN TestConfigureServerIdleTimeout_Go18 --- PASS: TestConfigureServerIdleTimeout_Go18 (0.00s) === RUN TestNoUnicodeStrings @@ -1497,29 +1538,29 @@ === RUN TestServer_Push_Success --- PASS: TestServer_Push_Success (0.03s) === RUN TestServer_Push_SuccessNoRace ---- PASS: TestServer_Push_SuccessNoRace (0.02s) +--- PASS: TestServer_Push_SuccessNoRace (0.04s) === RUN TestServer_Push_RejectRecursivePush ---- PASS: TestServer_Push_RejectRecursivePush (0.02s) +--- PASS: TestServer_Push_RejectRecursivePush (0.06s) === RUN TestServer_Push_RejectIfDisabled ---- PASS: TestServer_Push_RejectIfDisabled (0.02s) +--- PASS: TestServer_Push_RejectIfDisabled (0.04s) === RUN TestServer_Push_RejectWhenNoConcurrentStreams ---- PASS: TestServer_Push_RejectWhenNoConcurrentStreams (0.02s) +--- PASS: TestServer_Push_RejectWhenNoConcurrentStreams (0.06s) === RUN TestServer_Push_RejectWrongScheme ---- PASS: TestServer_Push_RejectWrongScheme (0.02s) +--- PASS: TestServer_Push_RejectWrongScheme (0.04s) === RUN TestServer_Push_RejectMissingHost ---- PASS: TestServer_Push_RejectMissingHost (0.02s) +--- PASS: TestServer_Push_RejectMissingHost (0.03s) === RUN TestServer_Push_RejectRelativePath ---- PASS: TestServer_Push_RejectRelativePath (0.02s) +--- PASS: TestServer_Push_RejectRelativePath (0.03s) === RUN TestServer_Push_RejectForbiddenMethod ---- PASS: TestServer_Push_RejectForbiddenMethod (0.02s) +--- PASS: TestServer_Push_RejectForbiddenMethod (0.04s) === RUN TestServer_Push_RejectForbiddenHeader ---- PASS: TestServer_Push_RejectForbiddenHeader (0.02s) +--- PASS: TestServer_Push_RejectForbiddenHeader (0.04s) === RUN TestServer_Push_StateTransitions ---- PASS: TestServer_Push_StateTransitions (0.02s) +--- PASS: TestServer_Push_StateTransitions (0.04s) === RUN TestServer_Push_RejectAfterGoAway ---- PASS: TestServer_Push_RejectAfterGoAway (0.02s) +--- PASS: TestServer_Push_RejectAfterGoAway (0.03s) === RUN TestServer ---- PASS: TestServer (0.02s) +--- PASS: TestServer (0.03s) === RUN TestServer_Request_Get --- PASS: TestServer_Request_Get (0.02s) === RUN TestServer_Request_Get_PathSlashes @@ -1527,9 +1568,9 @@ === RUN TestServer_Request_Post_NoContentLength_EndStream --- PASS: TestServer_Request_Post_NoContentLength_EndStream (0.02s) === RUN TestServer_Request_Post_Body_ImmediateEOF ---- PASS: TestServer_Request_Post_Body_ImmediateEOF (0.02s) +--- PASS: TestServer_Request_Post_Body_ImmediateEOF (0.03s) === RUN TestServer_Request_Post_Body_OneData ---- PASS: TestServer_Request_Post_Body_OneData (0.02s) +--- PASS: TestServer_Request_Post_Body_OneData (0.03s) === RUN TestServer_Request_Post_Body_TwoData --- PASS: TestServer_Request_Post_Body_TwoData (0.02s) === RUN TestServer_Request_Post_Body_ContentLength_Correct @@ -1537,9 +1578,9 @@ === RUN TestServer_Request_Post_Body_ContentLength_TooLarge --- PASS: TestServer_Request_Post_Body_ContentLength_TooLarge (0.02s) === RUN TestServer_Request_Post_Body_ContentLength_TooSmall ---- PASS: TestServer_Request_Post_Body_ContentLength_TooSmall (0.02s) +--- PASS: TestServer_Request_Post_Body_ContentLength_TooSmall (0.03s) === RUN TestServer_Request_Get_Host ---- PASS: TestServer_Request_Get_Host (0.01s) +--- PASS: TestServer_Request_Get_Host (0.02s) === RUN TestServer_Request_Get_Authority --- PASS: TestServer_Request_Get_Authority (0.02s) === RUN TestServer_Request_WithContinuation @@ -1547,29 +1588,29 @@ === RUN TestServer_Request_CookieConcat --- PASS: TestServer_Request_CookieConcat (0.01s) === RUN TestServer_Request_Reject_CapitalHeader ---- PASS: TestServer_Request_Reject_CapitalHeader (0.02s) +--- PASS: TestServer_Request_Reject_CapitalHeader (0.03s) === RUN TestServer_Request_Reject_HeaderFieldNameColon --- PASS: TestServer_Request_Reject_HeaderFieldNameColon (0.02s) === RUN TestServer_Request_Reject_HeaderFieldNameNULL ---- PASS: TestServer_Request_Reject_HeaderFieldNameNULL (0.02s) +--- PASS: TestServer_Request_Reject_HeaderFieldNameNULL (0.03s) === RUN TestServer_Request_Reject_HeaderFieldNameEmpty ---- PASS: TestServer_Request_Reject_HeaderFieldNameEmpty (0.01s) +--- PASS: TestServer_Request_Reject_HeaderFieldNameEmpty (0.02s) === RUN TestServer_Request_Reject_HeaderFieldValueNewline ---- PASS: TestServer_Request_Reject_HeaderFieldValueNewline (0.02s) +--- PASS: TestServer_Request_Reject_HeaderFieldValueNewline (0.03s) === RUN TestServer_Request_Reject_HeaderFieldValueCR --- PASS: TestServer_Request_Reject_HeaderFieldValueCR (0.02s) === RUN TestServer_Request_Reject_HeaderFieldValueDEL ---- PASS: TestServer_Request_Reject_HeaderFieldValueDEL (0.01s) +--- PASS: TestServer_Request_Reject_HeaderFieldValueDEL (0.03s) === RUN TestServer_Request_Reject_Pseudo_Missing_method --- PASS: TestServer_Request_Reject_Pseudo_Missing_method (0.02s) === RUN TestServer_Request_Reject_Pseudo_ExactlyOne ---- PASS: TestServer_Request_Reject_Pseudo_ExactlyOne (0.03s) +--- PASS: TestServer_Request_Reject_Pseudo_ExactlyOne (0.02s) === RUN TestServer_Request_Reject_Pseudo_AfterRegular --- PASS: TestServer_Request_Reject_Pseudo_AfterRegular (0.02s) === RUN TestServer_Request_Reject_Pseudo_Missing_path ---- PASS: TestServer_Request_Reject_Pseudo_Missing_path (0.02s) +--- PASS: TestServer_Request_Reject_Pseudo_Missing_path (0.03s) === RUN TestServer_Request_Reject_Pseudo_Missing_scheme ---- PASS: TestServer_Request_Reject_Pseudo_Missing_scheme (0.01s) +--- PASS: TestServer_Request_Reject_Pseudo_Missing_scheme (0.02s) === RUN TestServer_Request_Reject_Pseudo_scheme_invalid --- PASS: TestServer_Request_Reject_Pseudo_scheme_invalid (0.02s) === RUN TestServer_Request_Reject_Pseudo_Unknown @@ -1577,42 +1618,42 @@ === RUN TestRejectFrameOnIdle_WindowUpdate --- PASS: TestRejectFrameOnIdle_WindowUpdate (0.02s) === RUN TestRejectFrameOnIdle_Data ---- PASS: TestRejectFrameOnIdle_Data (0.01s) +--- PASS: TestRejectFrameOnIdle_Data (0.02s) === RUN TestRejectFrameOnIdle_RSTStream --- PASS: TestRejectFrameOnIdle_RSTStream (0.02s) === RUN TestServer_Request_Connect --- PASS: TestServer_Request_Connect (0.02s) === RUN TestServer_Request_Connect_InvalidPath ---- PASS: TestServer_Request_Connect_InvalidPath (0.01s) +--- PASS: TestServer_Request_Connect_InvalidPath (0.02s) === RUN TestServer_Request_Connect_InvalidScheme ---- PASS: TestServer_Request_Connect_InvalidScheme (0.01s) +--- PASS: TestServer_Request_Connect_InvalidScheme (0.03s) === RUN TestServer_Ping ---- PASS: TestServer_Ping (0.01s) +--- PASS: TestServer_Ping (0.02s) === RUN TestServer_MaxQueuedControlFrames server_test.go:1222: skipping in short mode --- SKIP: TestServer_MaxQueuedControlFrames (0.00s) === RUN TestServer_RejectsLargeFrames ---- PASS: TestServer_RejectsLargeFrames (0.04s) +--- PASS: TestServer_RejectsLargeFrames (0.05s) === RUN TestServer_Handler_Sends_WindowUpdate ---- PASS: TestServer_Handler_Sends_WindowUpdate (0.06s) +--- PASS: TestServer_Handler_Sends_WindowUpdate (0.05s) === RUN TestServer_Handler_Sends_WindowUpdate_Padding --- PASS: TestServer_Handler_Sends_WindowUpdate_Padding (0.05s) === RUN TestServer_Send_GoAway_After_Bogus_WindowUpdate ---- PASS: TestServer_Send_GoAway_After_Bogus_WindowUpdate (0.04s) +--- PASS: TestServer_Send_GoAway_After_Bogus_WindowUpdate (0.02s) === RUN TestServer_Send_RstStream_After_Bogus_WindowUpdate ---- PASS: TestServer_Send_RstStream_After_Bogus_WindowUpdate (0.02s) +--- PASS: TestServer_Send_RstStream_After_Bogus_WindowUpdate (0.03s) === RUN TestServer_RSTStream_Unblocks_Read --- PASS: TestServer_RSTStream_Unblocks_Read (0.02s) === RUN TestServer_RSTStream_Unblocks_Header_Write ---- PASS: TestServer_RSTStream_Unblocks_Header_Write (0.13s) +--- PASS: TestServer_RSTStream_Unblocks_Header_Write (0.16s) === RUN TestServer_DeadConn_Unblocks_Read ---- PASS: TestServer_DeadConn_Unblocks_Read (0.01s) +--- PASS: TestServer_DeadConn_Unblocks_Read (0.02s) === RUN TestServer_CloseNotify_After_RSTStream --- PASS: TestServer_CloseNotify_After_RSTStream (0.02s) === RUN TestServer_CloseNotify_After_ConnClose --- PASS: TestServer_CloseNotify_After_ConnClose (0.02s) === RUN TestServer_CloseNotify_After_StreamError ---- PASS: TestServer_CloseNotify_After_StreamError (0.02s) +--- PASS: TestServer_CloseNotify_After_StreamError (0.03s) === RUN TestServer_StateTransitions --- PASS: TestServer_StateTransitions (0.02s) === RUN TestServer_Rejects_HeadersNoEnd_Then_Headers @@ -1626,9 +1667,9 @@ === RUN TestServer_Rejects_Headers0 --- PASS: TestServer_Rejects_Headers0 (0.05s) === RUN TestServer_Rejects_Continuation0 ---- PASS: TestServer_Rejects_Continuation0 (0.06s) +--- PASS: TestServer_Rejects_Continuation0 (0.05s) === RUN TestServer_Rejects_Priority0 ---- PASS: TestServer_Rejects_Priority0 (0.05s) +--- PASS: TestServer_Rejects_Priority0 (0.04s) === RUN TestServer_Rejects_HeadersSelfDependence --- PASS: TestServer_Rejects_HeadersSelfDependence (0.02s) === RUN TestServer_Rejects_PrioritySelfDependence @@ -1636,53 +1677,53 @@ === RUN TestServer_Rejects_PushPromise --- PASS: TestServer_Rejects_PushPromise (0.05s) === RUN TestServer_Response_NoData ---- PASS: TestServer_Response_NoData (0.03s) +--- PASS: TestServer_Response_NoData (0.02s) === RUN TestServer_Response_NoData_Header_FooBar --- PASS: TestServer_Response_NoData_Header_FooBar (0.02s) === RUN TestServerIgnoresContentLengthSignWhenWritingChunks ---- PASS: TestServerIgnoresContentLengthSignWhenWritingChunks (0.14s) +--- PASS: TestServerIgnoresContentLengthSignWhenWritingChunks (0.12s) === RUN TestServerRejectsContentLengthWithSignNewRequests === RUN TestServerRejectsContentLengthWithSignNewRequests/proper_content-length === RUN TestServerRejectsContentLengthWithSignNewRequests/ignore_cl_with_plus_sign === RUN TestServerRejectsContentLengthWithSignNewRequests/ignore_cl_with_minus_sign === RUN TestServerRejectsContentLengthWithSignNewRequests/max_int64,_for_safe_uint64->int64_conversion === RUN TestServerRejectsContentLengthWithSignNewRequests/overflows_int64,_so_ignored ---- PASS: TestServerRejectsContentLengthWithSignNewRequests (0.10s) +--- PASS: TestServerRejectsContentLengthWithSignNewRequests (0.12s) --- PASS: TestServerRejectsContentLengthWithSignNewRequests/proper_content-length (0.02s) --- PASS: TestServerRejectsContentLengthWithSignNewRequests/ignore_cl_with_plus_sign (0.02s) - --- PASS: TestServerRejectsContentLengthWithSignNewRequests/ignore_cl_with_minus_sign (0.02s) + --- PASS: TestServerRejectsContentLengthWithSignNewRequests/ignore_cl_with_minus_sign (0.03s) --- PASS: TestServerRejectsContentLengthWithSignNewRequests/max_int64,_for_safe_uint64->int64_conversion (0.02s) --- PASS: TestServerRejectsContentLengthWithSignNewRequests/overflows_int64,_so_ignored (0.02s) === RUN TestServer_Response_Data_Sniff_DoesntOverride --- PASS: TestServer_Response_Data_Sniff_DoesntOverride (0.02s) === RUN TestServer_Response_TransferEncoding_chunked ---- PASS: TestServer_Response_TransferEncoding_chunked (0.02s) +--- PASS: TestServer_Response_TransferEncoding_chunked (0.04s) === RUN TestServer_Response_Data_IgnoreHeaderAfterWrite_After ---- PASS: TestServer_Response_Data_IgnoreHeaderAfterWrite_After (0.02s) +--- PASS: TestServer_Response_Data_IgnoreHeaderAfterWrite_After (0.03s) === RUN TestServer_Response_Data_IgnoreHeaderAfterWrite_Overwrite ---- PASS: TestServer_Response_Data_IgnoreHeaderAfterWrite_Overwrite (0.02s) +--- PASS: TestServer_Response_Data_IgnoreHeaderAfterWrite_Overwrite (0.03s) === RUN TestServer_Response_Data_SniffLenType ---- PASS: TestServer_Response_Data_SniffLenType (0.02s) +--- PASS: TestServer_Response_Data_SniffLenType (0.03s) === RUN TestServer_Response_Header_Flush_MidWrite --- PASS: TestServer_Response_Header_Flush_MidWrite (0.02s) === RUN TestServer_Response_LargeWrite ---- PASS: TestServer_Response_LargeWrite (0.10s) +--- PASS: TestServer_Response_LargeWrite (0.09s) === RUN TestServer_Response_LargeWrite_FlowControlled ---- PASS: TestServer_Response_LargeWrite_FlowControlled (0.03s) +--- PASS: TestServer_Response_LargeWrite_FlowControlled (0.06s) === RUN TestServer_Response_RST_Unblocks_LargeWrite ---- PASS: TestServer_Response_RST_Unblocks_LargeWrite (0.04s) +--- PASS: TestServer_Response_RST_Unblocks_LargeWrite (0.03s) === RUN TestServer_Response_Empty_Data_Not_FlowControlled ---- PASS: TestServer_Response_Empty_Data_Not_FlowControlled (0.03s) +--- PASS: TestServer_Response_Empty_Data_Not_FlowControlled (0.05s) === RUN TestServer_Response_Automatic100Continue ---- PASS: TestServer_Response_Automatic100Continue (0.02s) +--- PASS: TestServer_Response_Automatic100Continue (0.03s) === RUN TestServer_HandlerWriteErrorOnDisconnect --- PASS: TestServer_HandlerWriteErrorOnDisconnect (0.03s) === RUN TestServer_Rejects_Too_Many_Streams ---- PASS: TestServer_Rejects_Too_Many_Streams (0.63s) +--- PASS: TestServer_Rejects_Too_Many_Streams (0.59s) === RUN TestServer_Response_ManyHeaders_With_Continuation --- PASS: TestServer_Response_ManyHeaders_With_Continuation (0.10s) === RUN TestServer_NoCrash_HandlerClose_Then_ClientClose ---- PASS: TestServer_NoCrash_HandlerClose_Then_ClientClose (0.02s) +--- PASS: TestServer_NoCrash_HandlerClose_Then_ClientClose (0.03s) === RUN TestServer_Rejects_TLS10 --- PASS: TestServer_Rejects_TLS10 (0.01s) === RUN TestServer_Rejects_TLS11 @@ -1701,11 +1742,11 @@ server_test.go:2772: Skipping without --h2load flag. --- SKIP: TestServerWithH2Load (0.00s) === RUN TestServer_MaxDecoderHeaderTableSize ---- PASS: TestServer_MaxDecoderHeaderTableSize (0.01s) +--- PASS: TestServer_MaxDecoderHeaderTableSize (0.03s) === RUN TestServer_MaxEncoderHeaderTableSize ---- PASS: TestServer_MaxEncoderHeaderTableSize (0.03s) +--- PASS: TestServer_MaxEncoderHeaderTableSize (0.02s) === RUN TestServerDoS_MaxHeaderListSize ---- PASS: TestServerDoS_MaxHeaderListSize (0.24s) +--- PASS: TestServerDoS_MaxHeaderListSize (0.17s) === RUN TestServer_Response_Stream_With_Missing_Trailer --- PASS: TestServer_Response_Stream_With_Missing_Trailer (0.02s) === RUN TestCompressionErrorOnWrite @@ -1715,30 +1756,30 @@ === RUN TestServerReadsTrailers --- PASS: TestServerReadsTrailers (0.02s) === RUN TestServerWritesTrailers_WithFlush - http2_test.go:70: 2023/05/22 06:18:15 ignoring invalid trailer "Transfer-Encoding" - http2_test.go:70: 2023/05/22 06:18:15 ignoring invalid trailer "Content-Length" - http2_test.go:70: 2023/05/22 06:18:15 ignoring invalid trailer "Trailer" - http2_test.go:70: 2023/05/22 06:18:15 ignoring invalid trailer "Range" + http2_test.go:70: 2024/06/24 14:47:24 ignoring invalid trailer "Transfer-Encoding" + http2_test.go:70: 2024/06/24 14:47:24 ignoring invalid trailer "Content-Length" + http2_test.go:70: 2024/06/24 14:47:24 ignoring invalid trailer "Trailer" + http2_test.go:70: 2024/06/24 14:47:24 ignoring invalid trailer "Range" --- PASS: TestServerWritesTrailers_WithFlush (0.03s) === RUN TestServerWritesTrailers_WithoutFlush - http2_test.go:70: 2023/05/22 06:18:15 ignoring invalid trailer "Range" - http2_test.go:70: 2023/05/22 06:18:15 ignoring invalid trailer "Transfer-Encoding" - http2_test.go:70: 2023/05/22 06:18:15 ignoring invalid trailer "Content-Length" - http2_test.go:70: 2023/05/22 06:18:15 ignoring invalid trailer "Trailer" ---- PASS: TestServerWritesTrailers_WithoutFlush (0.02s) + http2_test.go:70: 2024/06/24 14:47:24 ignoring invalid trailer "Range" + http2_test.go:70: 2024/06/24 14:47:24 ignoring invalid trailer "Transfer-Encoding" + http2_test.go:70: 2024/06/24 14:47:24 ignoring invalid trailer "Content-Length" + http2_test.go:70: 2024/06/24 14:47:24 ignoring invalid trailer "Trailer" +--- PASS: TestServerWritesTrailers_WithoutFlush (0.03s) === RUN TestServerWritesUndeclaredTrailers --- PASS: TestServerWritesUndeclaredTrailers (0.02s) === RUN TestServerDoesntWriteInvalidHeaders --- PASS: TestServerDoesntWriteInvalidHeaders (0.01s) === RUN TestIssue53 - http2_test.go:70: 2023/05/22 06:18:15 http2: server connection error from 127.0.0.1:49706: connection error: PROTOCOL_ERROR + http2_test.go:70: 2024/06/24 14:47:24 http2: server connection error from 127.0.0.1:49706: connection error: PROTOCOL_ERROR --- PASS: TestIssue53 (0.00s) === RUN TestServeConnOptsNilReceiverBehavior --- PASS: TestServeConnOptsNilReceiverBehavior (0.00s) === RUN TestConfigureServer --- PASS: TestConfigureServer (0.00s) === RUN TestServerNoAutoContentLengthOnHead ---- PASS: TestServerNoAutoContentLengthOnHead (0.01s) +--- PASS: TestServerNoAutoContentLengthOnHead (0.02s) === RUN TestServerNoDuplicateContentType --- PASS: TestServerNoDuplicateContentType (0.02s) === RUN TestServerHandleCustomConn @@ -1748,15 +1789,15 @@ === RUN TestCheckValidHTTP2Request --- PASS: TestCheckValidHTTP2Request (0.00s) === RUN TestExpect100ContinueAfterHandlerWrites ---- PASS: TestExpect100ContinueAfterHandlerWrites (0.04s) +--- PASS: TestExpect100ContinueAfterHandlerWrites (0.06s) === RUN TestUnreadFlowControlReturned_Server === RUN TestUnreadFlowControlReturned_Server/body-open === RUN TestUnreadFlowControlReturned_Server/body-closed === RUN TestUnreadFlowControlReturned_Server/read-1-byte-and-close ---- PASS: TestUnreadFlowControlReturned_Server (0.35s) - --- PASS: TestUnreadFlowControlReturned_Server/body-open (0.09s) +--- PASS: TestUnreadFlowControlReturned_Server (0.30s) + --- PASS: TestUnreadFlowControlReturned_Server/body-open (0.12s) --- PASS: TestUnreadFlowControlReturned_Server/body-closed (0.09s) - --- PASS: TestUnreadFlowControlReturned_Server/read-1-byte-and-close (0.16s) + --- PASS: TestUnreadFlowControlReturned_Server/read-1-byte-and-close (0.09s) === RUN TestServerIdleTimeout server_test.go:3911: skipping in short mode --- SKIP: TestServerIdleTimeout (0.00s) @@ -1764,48 +1805,48 @@ server_test.go:3929: skipping in short mode --- SKIP: TestServerIdleTimeout_AfterRequest (0.00s) === RUN TestRequestBodyReadCloseRace ---- PASS: TestRequestBodyReadCloseRace (0.22s) +--- PASS: TestRequestBodyReadCloseRace (0.12s) === RUN TestIssue20704Race server_test.go:3981: skipping in short mode --- SKIP: TestIssue20704Race (0.00s) === RUN TestServer_Rejects_TooSmall ---- PASS: TestServer_Rejects_TooSmall (0.03s) +--- PASS: TestServer_Rejects_TooSmall (0.02s) === RUN TestServerHandlerConnectionClose ---- PASS: TestServerHandlerConnectionClose (0.08s) +--- PASS: TestServerHandlerConnectionClose (0.07s) === RUN TestServer_Headers_HalfCloseRemote ---- PASS: TestServer_Headers_HalfCloseRemote (0.02s) +--- PASS: TestServer_Headers_HalfCloseRemote (0.01s) === RUN TestServerGracefulShutdown --- PASS: TestServerGracefulShutdown (0.05s) === RUN TestContentEncodingNoSniffing === RUN TestContentEncodingNoSniffing/gzip_content-encoding,_gzipped - server_test.go:4299: https://127.0.0.1:39267: Content-Encoding: application/gzip - server_test.go:4313: https://127.0.0.1:39267: Content-Type: + server_test.go:4299: https://127.0.0.1:40459: Content-Encoding: application/gzip + server_test.go:4313: https://127.0.0.1:40459: Content-Type: === RUN TestContentEncodingNoSniffing/zlib_content-encoding,_zlibbed - server_test.go:4299: https://127.0.0.1:43443: Content-Encoding: application/zlib - server_test.go:4313: https://127.0.0.1:43443: Content-Type: + server_test.go:4299: https://127.0.0.1:41427: Content-Encoding: application/zlib + server_test.go:4313: https://127.0.0.1:41427: Content-Type: === RUN TestContentEncodingNoSniffing/no_content-encoding - server_test.go:4299: https://127.0.0.1:37987: Content-Encoding: - server_test.go:4313: https://127.0.0.1:37987: Content-Type: application/x-gzip + server_test.go:4299: https://127.0.0.1:35823: Content-Encoding: + server_test.go:4313: https://127.0.0.1:35823: Content-Type: application/x-gzip === RUN TestContentEncodingNoSniffing/phony_content-encoding - server_test.go:4299: https://127.0.0.1:33847: Content-Encoding: foo/bar - server_test.go:4313: https://127.0.0.1:33847: Content-Type: + server_test.go:4299: https://127.0.0.1:42869: Content-Encoding: foo/bar + server_test.go:4313: https://127.0.0.1:42869: Content-Type: === RUN TestContentEncodingNoSniffing/empty_but_set_content-encoding - server_test.go:4299: https://127.0.0.1:37811: Content-Encoding: - server_test.go:4313: https://127.0.0.1:37811: Content-Type: audio/mpeg ---- PASS: TestContentEncodingNoSniffing (0.10s) + server_test.go:4299: https://127.0.0.1:37643: Content-Encoding: + server_test.go:4313: https://127.0.0.1:37643: Content-Type: audio/mpeg +--- PASS: TestContentEncodingNoSniffing (0.11s) --- PASS: TestContentEncodingNoSniffing/gzip_content-encoding,_gzipped (0.02s) --- PASS: TestContentEncodingNoSniffing/zlib_content-encoding,_zlibbed (0.02s) --- PASS: TestContentEncodingNoSniffing/no_content-encoding (0.02s) --- PASS: TestContentEncodingNoSniffing/phony_content-encoding (0.02s) --- PASS: TestContentEncodingNoSniffing/empty_but_set_content-encoding (0.02s) === RUN TestServerWindowUpdateOnBodyClose ---- PASS: TestServerWindowUpdateOnBodyClose (0.05s) +--- PASS: TestServerWindowUpdateOnBodyClose (0.03s) === RUN TestNoErrorLoggedOnPostAfterGOAWAY --- PASS: TestNoErrorLoggedOnPostAfterGOAWAY (0.22s) === RUN TestServerSendsProcessing --- PASS: TestServerSendsProcessing (0.02s) === RUN TestServerSendsEarlyHints ---- PASS: TestServerSendsEarlyHints (0.02s) +--- PASS: TestServerSendsEarlyHints (0.03s) === RUN TestProtocolErrorAfterGoAway --- PASS: TestProtocolErrorAfterGoAway (0.05s) === RUN TestServerInitialFlowControlWindow @@ -1813,116 +1854,116 @@ === RUN TestServerInitialFlowControlWindow/524288 === RUN TestServerInitialFlowControlWindow/2097152 === RUN TestServerInitialFlowControlWindow/131070 ---- PASS: TestServerInitialFlowControlWindow (0.08s) - --- PASS: TestServerInitialFlowControlWindow/65535 (0.02s) - --- PASS: TestServerInitialFlowControlWindow/524288 (0.02s) - --- PASS: TestServerInitialFlowControlWindow/2097152 (0.02s) - --- PASS: TestServerInitialFlowControlWindow/131070 (0.02s) +--- PASS: TestServerInitialFlowControlWindow (0.13s) + --- PASS: TestServerInitialFlowControlWindow/65535 (0.01s) + --- PASS: TestServerInitialFlowControlWindow/524288 (0.03s) + --- PASS: TestServerInitialFlowControlWindow/2097152 (0.05s) + --- PASS: TestServerInitialFlowControlWindow/131070 (0.03s) === RUN TestCanonicalHeaderCacheGrowth ---- PASS: TestCanonicalHeaderCacheGrowth (25.74s) +--- PASS: TestCanonicalHeaderCacheGrowth (23.94s) === RUN TestServerWriteDoesNotRetainBufferAfterReturn ---- PASS: TestServerWriteDoesNotRetainBufferAfterReturn (0.03s) +--- PASS: TestServerWriteDoesNotRetainBufferAfterReturn (0.04s) === RUN TestServerWriteDoesNotRetainBufferAfterServerClose --- PASS: TestServerWriteDoesNotRetainBufferAfterServerClose (0.06s) === RUN TestTransportDialTLSContext - http2_test.go:70: 2023/05/22 06:18:43 http: TLS handshake error from 127.0.0.1:60312: read tcp 127.0.0.1:42447->127.0.0.1:60312: use of closed network connection ---- PASS: TestTransportDialTLSContext (0.03s) + http2_test.go:70: 2024/06/24 14:47:49 http: TLS handshake error from 127.0.0.1:43360: read tcp 127.0.0.1:39757->127.0.0.1:43360: use of closed network connection +--- PASS: TestTransportDialTLSContext (0.02s) === RUN TestDialRaceResumesDial - http2_test.go:70: 2023/05/22 06:18:43 http: TLS handshake error from 127.0.0.1:59758: EOF ---- PASS: TestDialRaceResumesDial (0.06s) + http2_test.go:70: 2024/06/24 14:47:49 http: TLS handshake error from 127.0.0.1:33076: EOF +--- PASS: TestDialRaceResumesDial (0.05s) === RUN TestTransportExternal transport_test.go:60: skipping external network test --- SKIP: TestTransportExternal (0.00s) === RUN TestTransportH2c --- PASS: TestTransportH2c (0.01s) === RUN TestTransport - transport_test.go:163: 0: Got res: &{Status:200 OK StatusCode:200 Proto:HTTP/2.0 ProtoMajor:2 ProtoMinor:0 Header:map[Content-Length:[3] Content-Type:[text/plain; charset=utf-8] Date:[Mon, 22 May 2023 18:18:43 GMT]] Body:{cs:0x40000de000} ContentLength:3 TransferEncoding:[] Close:false Uncompressed:false Trailer:map[] Request:0x4000354700 TLS:0x4000150a50} - transport_test.go:163: 1: Got res: &{Status:200 OK StatusCode:200 Proto:HTTP/2.0 ProtoMajor:2 ProtoMinor:0 Header:map[Content-Length:[3] Content-Type:[text/plain; charset=utf-8] Date:[Mon, 22 May 2023 18:18:43 GMT]] Body:{cs:0x40001a4000} ContentLength:3 TransferEncoding:[] Close:false Uncompressed:false Trailer:map[] Request:0x400149e900 TLS:0x4000150a50} ---- PASS: TestTransport (0.03s) + transport_test.go:163: 0: Got res: &{Status:200 OK StatusCode:200 Proto:HTTP/2.0 ProtoMajor:2 ProtoMinor:0 Header:map[Content-Length:[3] Content-Type:[text/plain; charset=utf-8] Date:[Mon, 24 Jun 2024 00:47:49 GMT]] Body:{cs:0x400032db00} ContentLength:3 TransferEncoding:[] Close:false Uncompressed:false Trailer:map[] Request:0x400019a300 TLS:0x40010484d0} + transport_test.go:163: 1: Got res: &{Status:200 OK StatusCode:200 Proto:HTTP/2.0 ProtoMajor:2 ProtoMinor:0 Header:map[Content-Length:[3] Content-Type:[text/plain; charset=utf-8] Date:[Mon, 24 Jun 2024 00:47:49 GMT]] Body:{cs:0x400007e180} ContentLength:3 TransferEncoding:[] Close:false Uncompressed:false Trailer:map[] Request:0x400019a000 TLS:0x40010484d0} +--- PASS: TestTransport (0.05s) === RUN TestTransportReusesConns === RUN TestTransportReusesConns/ReuseConn === RUN TestTransportReusesConns/ReuseConn/Transport - transport_test.go:199: conn 127.0.0.1:53718 is now state new - transport_test.go:199: conn 127.0.0.1:53718 is now state active - transport_test.go:199: conn 127.0.0.1:53718 is now state idle - transport_test.go:199: conn 127.0.0.1:53718 is now state active - transport_test.go:199: conn 127.0.0.1:53718 is now state idle - transport_test.go:199: conn 127.0.0.1:53718 is now state active - transport_test.go:199: conn 127.0.0.1:53718 is now state idle - transport_test.go:199: conn 127.0.0.1:53718 is now state closed + transport_test.go:199: conn 127.0.0.1:42906 is now state new + transport_test.go:199: conn 127.0.0.1:42906 is now state active + transport_test.go:199: conn 127.0.0.1:42906 is now state idle + transport_test.go:199: conn 127.0.0.1:42906 is now state active + transport_test.go:199: conn 127.0.0.1:42906 is now state idle + transport_test.go:199: conn 127.0.0.1:42906 is now state active + transport_test.go:199: conn 127.0.0.1:42906 is now state idle + transport_test.go:199: conn 127.0.0.1:42906 is now state closed === RUN TestTransportReusesConns/ReuseConn/Client - transport_test.go:199: conn 127.0.0.1:40234 is now state new - transport_test.go:199: conn 127.0.0.1:40234 is now state active - transport_test.go:199: conn 127.0.0.1:40234 is now state idle - transport_test.go:199: conn 127.0.0.1:40234 is now state active - transport_test.go:199: conn 127.0.0.1:40234 is now state idle - transport_test.go:199: conn 127.0.0.1:40234 is now state active - transport_test.go:199: conn 127.0.0.1:40234 is now state idle - transport_test.go:199: conn 127.0.0.1:40234 is now state closed + transport_test.go:199: conn 127.0.0.1:48190 is now state new + transport_test.go:199: conn 127.0.0.1:48190 is now state active + transport_test.go:199: conn 127.0.0.1:48190 is now state idle + transport_test.go:199: conn 127.0.0.1:48190 is now state active + transport_test.go:199: conn 127.0.0.1:48190 is now state idle + transport_test.go:199: conn 127.0.0.1:48190 is now state active + transport_test.go:199: conn 127.0.0.1:48190 is now state idle + transport_test.go:199: conn 127.0.0.1:48190 is now state closed === RUN TestTransportReusesConns/RequestClose === RUN TestTransportReusesConns/RequestClose/Transport - transport_test.go:199: conn 127.0.0.1:47874 is now state new - transport_test.go:199: conn 127.0.0.1:47874 is now state active - transport_test.go:199: conn 127.0.0.1:47874 is now state idle - transport_test.go:199: conn 127.0.0.1:47874 is now state active - transport_test.go:199: conn 127.0.0.1:47874 is now state idle - transport_test.go:199: conn 127.0.0.1:47890 is now state new - transport_test.go:199: conn 127.0.0.1:47874 is now state closed - transport_test.go:199: conn 127.0.0.1:47890 is now state active - transport_test.go:199: conn 127.0.0.1:47890 is now state idle - transport_test.go:199: conn 127.0.0.1:47890 is now state active - transport_test.go:199: conn 127.0.0.1:47890 is now state idle - transport_test.go:199: conn 127.0.0.1:47890 is now state closed + transport_test.go:199: conn 127.0.0.1:53034 is now state new + transport_test.go:199: conn 127.0.0.1:53034 is now state active + transport_test.go:199: conn 127.0.0.1:53034 is now state idle + transport_test.go:199: conn 127.0.0.1:53034 is now state active + transport_test.go:199: conn 127.0.0.1:53034 is now state idle + transport_test.go:199: conn 127.0.0.1:53034 is now state closed + transport_test.go:199: conn 127.0.0.1:53036 is now state new + transport_test.go:199: conn 127.0.0.1:53036 is now state active + transport_test.go:199: conn 127.0.0.1:53036 is now state idle + transport_test.go:199: conn 127.0.0.1:53036 is now state active + transport_test.go:199: conn 127.0.0.1:53036 is now state idle + transport_test.go:199: conn 127.0.0.1:53036 is now state closed === RUN TestTransportReusesConns/RequestClose/Client - transport_test.go:199: conn 127.0.0.1:32808 is now state new - transport_test.go:199: conn 127.0.0.1:32808 is now state active - transport_test.go:199: conn 127.0.0.1:32808 is now state idle - transport_test.go:199: conn 127.0.0.1:32808 is now state active - transport_test.go:199: conn 127.0.0.1:32808 is now state idle - transport_test.go:199: conn 127.0.0.1:32812 is now state new - transport_test.go:199: conn 127.0.0.1:32808 is now state closed - transport_test.go:199: conn 127.0.0.1:32812 is now state active - transport_test.go:199: conn 127.0.0.1:32812 is now state idle - transport_test.go:199: conn 127.0.0.1:32812 is now state active - transport_test.go:199: conn 127.0.0.1:32812 is now state idle - transport_test.go:199: conn 127.0.0.1:32812 is now state closed + transport_test.go:199: conn 127.0.0.1:33102 is now state new + transport_test.go:199: conn 127.0.0.1:33102 is now state active + transport_test.go:199: conn 127.0.0.1:33102 is now state idle + transport_test.go:199: conn 127.0.0.1:33102 is now state active + transport_test.go:199: conn 127.0.0.1:33102 is now state idle + transport_test.go:199: conn 127.0.0.1:33114 is now state new + transport_test.go:199: conn 127.0.0.1:33102 is now state closed + transport_test.go:199: conn 127.0.0.1:33114 is now state active + transport_test.go:199: conn 127.0.0.1:33114 is now state idle + transport_test.go:199: conn 127.0.0.1:33114 is now state active + transport_test.go:199: conn 127.0.0.1:33114 is now state idle + transport_test.go:199: conn 127.0.0.1:33114 is now state closed === RUN TestTransportReusesConns/ConnClose === RUN TestTransportReusesConns/ConnClose/Transport - transport_test.go:199: conn 127.0.0.1:53414 is now state new - transport_test.go:199: conn 127.0.0.1:53414 is now state active - transport_test.go:199: conn 127.0.0.1:53414 is now state idle - transport_test.go:199: conn 127.0.0.1:53414 is now state active - transport_test.go:199: conn 127.0.0.1:53414 is now state idle - transport_test.go:199: conn 127.0.0.1:53420 is now state new - transport_test.go:199: conn 127.0.0.1:53414 is now state closed - transport_test.go:199: conn 127.0.0.1:53420 is now state active - transport_test.go:199: conn 127.0.0.1:53420 is now state idle - transport_test.go:199: conn 127.0.0.1:53420 is now state active - transport_test.go:199: conn 127.0.0.1:53420 is now state idle - transport_test.go:199: conn 127.0.0.1:53420 is now state closed + transport_test.go:199: conn 127.0.0.1:47028 is now state new + transport_test.go:199: conn 127.0.0.1:47028 is now state active + transport_test.go:199: conn 127.0.0.1:47028 is now state idle + transport_test.go:199: conn 127.0.0.1:47028 is now state active + transport_test.go:199: conn 127.0.0.1:47028 is now state idle + transport_test.go:199: conn 127.0.0.1:47044 is now state new + transport_test.go:199: conn 127.0.0.1:47028 is now state closed + transport_test.go:199: conn 127.0.0.1:47044 is now state active + transport_test.go:199: conn 127.0.0.1:47044 is now state idle + transport_test.go:199: conn 127.0.0.1:47044 is now state active + transport_test.go:199: conn 127.0.0.1:47044 is now state idle + transport_test.go:199: conn 127.0.0.1:47044 is now state closed === RUN TestTransportReusesConns/ConnClose/Client - transport_test.go:199: conn 127.0.0.1:38232 is now state new - transport_test.go:199: conn 127.0.0.1:38232 is now state active - transport_test.go:199: conn 127.0.0.1:38232 is now state idle - transport_test.go:199: conn 127.0.0.1:38232 is now state active - transport_test.go:199: conn 127.0.0.1:38232 is now state idle - transport_test.go:199: conn 127.0.0.1:38236 is now state new - transport_test.go:199: conn 127.0.0.1:38232 is now state closed - transport_test.go:199: conn 127.0.0.1:38236 is now state active - transport_test.go:199: conn 127.0.0.1:38236 is now state idle - transport_test.go:199: conn 127.0.0.1:38236 is now state active - transport_test.go:199: conn 127.0.0.1:38236 is now state idle - transport_test.go:199: conn 127.0.0.1:38236 is now state closed ---- PASS: TestTransportReusesConns (0.22s) - --- PASS: TestTransportReusesConns/ReuseConn (0.06s) - --- PASS: TestTransportReusesConns/ReuseConn/Transport (0.02s) - --- PASS: TestTransportReusesConns/ReuseConn/Client (0.04s) - --- PASS: TestTransportReusesConns/RequestClose (0.08s) + transport_test.go:199: conn 127.0.0.1:52924 is now state new + transport_test.go:199: conn 127.0.0.1:52924 is now state active + transport_test.go:199: conn 127.0.0.1:52924 is now state idle + transport_test.go:199: conn 127.0.0.1:52924 is now state active + transport_test.go:199: conn 127.0.0.1:52924 is now state idle + transport_test.go:199: conn 127.0.0.1:52940 is now state new + transport_test.go:199: conn 127.0.0.1:52924 is now state closed + transport_test.go:199: conn 127.0.0.1:52940 is now state active + transport_test.go:199: conn 127.0.0.1:52940 is now state idle + transport_test.go:199: conn 127.0.0.1:52940 is now state active + transport_test.go:199: conn 127.0.0.1:52940 is now state idle + transport_test.go:199: conn 127.0.0.1:52940 is now state closed +--- PASS: TestTransportReusesConns (0.34s) + --- PASS: TestTransportReusesConns/ReuseConn (0.09s) + --- PASS: TestTransportReusesConns/ReuseConn/Transport (0.06s) + --- PASS: TestTransportReusesConns/ReuseConn/Client (0.03s) + --- PASS: TestTransportReusesConns/RequestClose (0.12s) --- PASS: TestTransportReusesConns/RequestClose/Transport (0.04s) - --- PASS: TestTransportReusesConns/RequestClose/Client (0.04s) - --- PASS: TestTransportReusesConns/ConnClose (0.08s) - --- PASS: TestTransportReusesConns/ConnClose/Transport (0.04s) - --- PASS: TestTransportReusesConns/ConnClose/Client (0.04s) + --- PASS: TestTransportReusesConns/RequestClose/Client (0.08s) + --- PASS: TestTransportReusesConns/ConnClose (0.13s) + --- PASS: TestTransportReusesConns/ConnClose/Transport (0.05s) + --- PASS: TestTransportReusesConns/ConnClose/Client (0.07s) === RUN TestTransportGetGotConnHooks_HTTP2Transport --- PASS: TestTransportGetGotConnHooks_HTTP2Transport (0.03s) === RUN TestTransportGetGotConnHooks_Client @@ -1936,27 +1977,27 @@ === RUN TestActualContentLength --- PASS: TestActualContentLength (0.00s) === RUN TestTransportBody ---- PASS: TestTransportBody (1.41s) +--- PASS: TestTransportBody (1.62s) === RUN TestTransportDialTLS ---- PASS: TestTransportDialTLS (0.06s) +--- PASS: TestTransportDialTLS (0.02s) === RUN TestConfigureTransport --- PASS: TestConfigureTransport (0.04s) === RUN TestTransportReqBodyAfterResponse_200 ---- PASS: TestTransportReqBodyAfterResponse_200 (0.20s) +--- PASS: TestTransportReqBodyAfterResponse_200 (0.21s) === RUN TestTransportReqBodyAfterResponse_403 ---- PASS: TestTransportReqBodyAfterResponse_403 (0.05s) +--- PASS: TestTransportReqBodyAfterResponse_403 (0.09s) === RUN TestTransportFullDuplex ---- PASS: TestTransportFullDuplex (0.03s) +--- PASS: TestTransportFullDuplex (0.04s) === RUN TestTransportConnectRequest --- PASS: TestTransportConnectRequest (0.02s) === RUN TestTransportResPattern_c0h1d0t0 ---- PASS: TestTransportResPattern_c0h1d0t0 (0.00s) +--- PASS: TestTransportResPattern_c0h1d0t0 (0.01s) === RUN TestTransportResPattern_c0h1d0t1 ---- PASS: TestTransportResPattern_c0h1d0t1 (0.01s) +--- PASS: TestTransportResPattern_c0h1d0t1 (0.00s) === RUN TestTransportResPattern_c0h1d0t2 --- PASS: TestTransportResPattern_c0h1d0t2 (0.00s) === RUN TestTransportResPattern_c0h1d1t0 ---- PASS: TestTransportResPattern_c0h1d1t0 (0.00s) +--- PASS: TestTransportResPattern_c0h1d1t0 (0.01s) === RUN TestTransportResPattern_c0h1d1t1 --- PASS: TestTransportResPattern_c0h1d1t1 (0.00s) === RUN TestTransportResPattern_c0h1d1t2 @@ -1974,9 +2015,9 @@ === RUN TestTransportResPattern_c0h2d1t2 --- PASS: TestTransportResPattern_c0h2d1t2 (0.00s) === RUN TestTransportResPattern_c1h1d0t0 ---- PASS: TestTransportResPattern_c1h1d0t0 (0.01s) +--- PASS: TestTransportResPattern_c1h1d0t0 (0.00s) === RUN TestTransportResPattern_c1h1d0t1 ---- PASS: TestTransportResPattern_c1h1d0t1 (0.00s) +--- PASS: TestTransportResPattern_c1h1d0t1 (0.01s) === RUN TestTransportResPattern_c1h1d0t2 --- PASS: TestTransportResPattern_c1h1d0t2 (0.00s) === RUN TestTransportResPattern_c1h1d1t0 @@ -1992,13 +2033,13 @@ === RUN TestTransportResPattern_c1h2d0t2 --- PASS: TestTransportResPattern_c1h2d0t2 (0.00s) === RUN TestTransportResPattern_c1h2d1t0 ---- PASS: TestTransportResPattern_c1h2d1t0 (0.00s) +--- PASS: TestTransportResPattern_c1h2d1t0 (0.01s) === RUN TestTransportResPattern_c1h2d1t1 --- PASS: TestTransportResPattern_c1h2d1t1 (0.00s) === RUN TestTransportResPattern_c1h2d1t2 --- PASS: TestTransportResPattern_c1h2d1t2 (0.00s) === RUN TestTransportResPattern_c2h1d0t0 ---- PASS: TestTransportResPattern_c2h1d0t0 (0.01s) +--- PASS: TestTransportResPattern_c2h1d0t0 (0.00s) === RUN TestTransportResPattern_c2h1d0t1 --- PASS: TestTransportResPattern_c2h1d0t1 (0.00s) === RUN TestTransportResPattern_c2h1d0t2 @@ -2008,7 +2049,7 @@ === RUN TestTransportResPattern_c2h1d1t1 --- PASS: TestTransportResPattern_c2h1d1t1 (0.00s) === RUN TestTransportResPattern_c2h1d1t2 ---- PASS: TestTransportResPattern_c2h1d1t2 (0.00s) +--- PASS: TestTransportResPattern_c2h1d1t2 (0.01s) === RUN TestTransportResPattern_c2h2d0t0 --- PASS: TestTransportResPattern_c2h2d0t0 (0.00s) === RUN TestTransportResPattern_c2h2d0t1 @@ -2022,9 +2063,9 @@ === RUN TestTransportResPattern_c2h2d1t2 --- PASS: TestTransportResPattern_c2h2d1t2 (0.00s) === RUN TestTransportUnknown1xx ---- PASS: TestTransportUnknown1xx (0.01s) +--- PASS: TestTransportUnknown1xx (0.00s) === RUN TestTransportReceiveUndeclaredTrailer ---- PASS: TestTransportReceiveUndeclaredTrailer (0.00s) +--- PASS: TestTransportReceiveUndeclaredTrailer (0.01s) === RUN TestTransportInvalidTrailer_Pseudo1 --- PASS: TestTransportInvalidTrailer_Pseudo1 (0.00s) === RUN TestTransportInvalidTrailer_Pseudo2 @@ -2040,20 +2081,20 @@ === RUN TestPadHeaders --- PASS: TestPadHeaders (0.02s) === RUN TestTransportChecksRequestHeaderListSize ---- PASS: TestTransportChecksRequestHeaderListSize (0.04s) +--- PASS: TestTransportChecksRequestHeaderListSize (0.03s) === RUN TestTransportChecksResponseHeaderListSize ---- PASS: TestTransportChecksResponseHeaderListSize (0.08s) +--- PASS: TestTransportChecksResponseHeaderListSize (0.06s) === RUN TestTransportCookieHeaderSplit --- PASS: TestTransportCookieHeaderSplit (0.00s) === RUN TestTransportBodyReadErrorType --- PASS: TestTransportBodyReadErrorType (0.02s) === RUN TestTransportDoubleCloseOnWriteError ---- PASS: TestTransportDoubleCloseOnWriteError (0.04s) +--- PASS: TestTransportDoubleCloseOnWriteError (0.03s) === RUN TestTransportDisableKeepAlives --- PASS: TestTransportDisableKeepAlives (0.02s) === RUN TestTransportDisableKeepAlives_Concurrency transport_test.go:2185: did 1 dials, 20 requests ---- PASS: TestTransportDisableKeepAlives_Concurrency (0.09s) +--- PASS: TestTransportDisableKeepAlives_Concurrency (0.10s) === RUN TestTransportResponseHeaderTimeout_NoBody --- PASS: TestTransportResponseHeaderTimeout_NoBody (0.01s) === RUN TestTransportResponseHeaderTimeout_Body @@ -2061,7 +2102,7 @@ === RUN TestTransportDisableCompression --- PASS: TestTransportDisableCompression (0.02s) === RUN TestTransportRejectsConnHeaders ---- PASS: TestTransportRejectsConnHeaders (0.09s) +--- PASS: TestTransportRejectsConnHeaders (0.08s) === RUN TestTransportRejectsContentLengthWithSign === RUN TestTransportRejectsContentLengthWithSign/proper_content-length === RUN TestTransportRejectsContentLengthWithSign/ignore_cl_with_plus_sign @@ -2087,11 +2128,11 @@ === RUN TestTransportReadHeadResponseWithBody --- PASS: TestTransportReadHeadResponseWithBody (0.00s) === RUN TestTransportHandlerBodyClose ---- PASS: TestTransportHandlerBodyClose (18.75s) +--- PASS: TestTransportHandlerBodyClose (18.98s) === RUN TestTransportFlowControl ---- PASS: TestTransportFlowControl (0.88s) +--- PASS: TestTransportFlowControl (0.81s) === RUN TestTransportUsesGoAwayDebugError_RoundTrip ---- PASS: TestTransportUsesGoAwayDebugError_RoundTrip (0.01s) +--- PASS: TestTransportUsesGoAwayDebugError_RoundTrip (0.00s) === RUN TestTransportUsesGoAwayDebugError_Body --- PASS: TestTransportUsesGoAwayDebugError_Body (0.00s) === RUN TestTransportReturnsUnusedFlowControlSingleWrite @@ -2101,21 +2142,21 @@ === RUN TestTransportAdjustsFlowControl --- PASS: TestTransportAdjustsFlowControl (0.01s) === RUN TestTransportReturnsDataPaddingFlowControl ---- PASS: TestTransportReturnsDataPaddingFlowControl (0.00s) +--- PASS: TestTransportReturnsDataPaddingFlowControl (0.01s) === RUN TestTransportReturnsErrorOnBadResponseHeaders ---- PASS: TestTransportReturnsErrorOnBadResponseHeaders (0.00s) +--- PASS: TestTransportReturnsErrorOnBadResponseHeaders (0.01s) === RUN TestTransportBodyDoubleEndStream ---- PASS: TestTransportBodyDoubleEndStream (0.04s) +--- PASS: TestTransportBodyDoubleEndStream (0.03s) === RUN TestTransportRequestPathPseudo --- PASS: TestTransportRequestPathPseudo (0.00s) === RUN TestRoundTripDoesntConsumeRequestBodyEarly --- PASS: TestRoundTripDoesntConsumeRequestBodyEarly (0.00s) === RUN TestClientConnPing ---- PASS: TestClientConnPing (0.03s) +--- PASS: TestClientConnPing (0.02s) === RUN TestTransportCancelDataResponseRace ---- PASS: TestTransportCancelDataResponseRace (0.14s) +--- PASS: TestTransportCancelDataResponseRace (0.09s) === RUN TestTransportNoRaceOnRequestObjectAfterRequestComplete ---- PASS: TestTransportNoRaceOnRequestObjectAfterRequestComplete (0.04s) +--- PASS: TestTransportNoRaceOnRequestObjectAfterRequestComplete (0.02s) === RUN TestTransportCloseAfterLostPing --- PASS: TestTransportCloseAfterLostPing (2.01s) === RUN TestTransportPingWriteBlocks @@ -2142,9 +2183,9 @@ === RUN TestTransportMaxFrameReadSize --- PASS: TestTransportMaxFrameReadSize (0.00s) === RUN TestTransportRequestsLowServerLimit ---- PASS: TestTransportRequestsLowServerLimit (0.05s) +--- PASS: TestTransportRequestsLowServerLimit (0.02s) === RUN TestTransportRequestsStallAtServerLimit ---- PASS: TestTransportRequestsStallAtServerLimit (0.07s) +--- PASS: TestTransportRequestsStallAtServerLimit (0.05s) === RUN TestTransportMaxDecoderHeaderTableSize --- PASS: TestTransportMaxDecoderHeaderTableSize (0.00s) === RUN TestTransportMaxEncoderHeaderTableSize @@ -2152,21 +2193,21 @@ === RUN TestAuthorityAddr --- PASS: TestAuthorityAddr (0.00s) === RUN TestTransportAllocationsAfterResponseBodyClose ---- PASS: TestTransportAllocationsAfterResponseBodyClose (0.05s) +--- PASS: TestTransportAllocationsAfterResponseBodyClose (0.02s) === RUN TestTransportNoBodyMeansNoDATA --- PASS: TestTransportNoBodyMeansNoDATA (0.00s) === RUN TestTransportResponseAndResetWithoutConsumingBodyRace --- PASS: TestTransportResponseAndResetWithoutConsumingBodyRace (0.04s) === RUN TestTransportHandlesInvalidStatuslessResponse ---- PASS: TestTransportHandlesInvalidStatuslessResponse (0.00s) +--- PASS: TestTransportHandlesInvalidStatuslessResponse (0.01s) === RUN TestClientConnCloseAtHeaders ---- PASS: TestClientConnCloseAtHeaders (0.03s) +--- PASS: TestClientConnCloseAtHeaders (0.02s) === RUN TestClientConnCloseAtBody ---- PASS: TestClientConnCloseAtBody (0.04s) +--- PASS: TestClientConnCloseAtBody (0.03s) === RUN TestClientConnShutdown ---- PASS: TestClientConnShutdown (0.04s) +--- PASS: TestClientConnShutdown (0.02s) === RUN TestClientConnShutdownCancel ---- PASS: TestClientConnShutdownCancel (0.03s) +--- PASS: TestClientConnShutdownCancel (0.02s) === RUN TestTransportUsesGetBodyWhenPresent --- PASS: TestTransportUsesGetBodyWhenPresent (0.00s) === RUN TestNoDialH2RoundTripperType @@ -2176,30 +2217,29 @@ transport_test.go:5093: server: ReadFrame = [FrameHeader HEADERS flags=END_HEADERS stream=1 len=36], transport_test.go:5093: server: ReadFrame = [FrameHeader RST_STREAM stream=1 len=4], transport_test.go:5093: server: ReadFrame = , EOF ---- PASS: TestTransportBodyReadError_Immediately (0.01s) +--- PASS: TestTransportBodyReadError_Immediately (0.00s) === RUN TestTransportBodyReadError_Some transport_test.go:5093: server: ReadFrame = [FrameHeader WINDOW_UPDATE len=4], transport_test.go:5093: server: ReadFrame = [FrameHeader HEADERS flags=END_HEADERS stream=1 len=36], transport_test.go:5093: server: ReadFrame = [FrameHeader DATA stream=1 len=3], transport_test.go:5093: server: ReadFrame = [FrameHeader RST_STREAM stream=1 len=4], transport_test.go:5093: server: ReadFrame = , EOF ---- PASS: TestTransportBodyReadError_Some (0.01s) +--- PASS: TestTransportBodyReadError_Some (0.00s) === RUN TestTransportBodyEagerEndStream --- PASS: TestTransportBodyEagerEndStream (0.00s) === RUN TestTransportBodyLargerThanSpecifiedContentLength_len3 - http2_test.go:70: 2023/05/22 06:19:09 http: TLS handshake error from 127.0.0.1:57166: read tcp 127.0.0.1:40443->127.0.0.1:57166: use of closed network connection ---- PASS: TestTransportBodyLargerThanSpecifiedContentLength_len3 (0.03s) +--- PASS: TestTransportBodyLargerThanSpecifiedContentLength_len3 (0.02s) === RUN TestTransportBodyLargerThanSpecifiedContentLength_len2 - http2_test.go:70: 2023/05/22 06:19:09 http: TLS handshake error from 127.0.0.1:44664: read tcp 127.0.0.1:38017->127.0.0.1:44664: use of closed network connection --- PASS: TestTransportBodyLargerThanSpecifiedContentLength_len2 (0.02s) === RUN TestClientConnTooIdle --- PASS: TestClientConnTooIdle (0.00s) === RUN TestTransportNewClientConnCloseOnWriteError --- PASS: TestTransportNewClientConnCloseOnWriteError (0.00s) === RUN TestTransportRoundtripCloseOnWriteError ---- PASS: TestTransportRoundtripCloseOnWriteError (0.03s) + http2_test.go:70: 2024/06/24 14:48:16 http: TLS handshake error from 127.0.0.1:36230: read tcp 127.0.0.1:42693->127.0.0.1:36230: use of closed network connection +--- PASS: TestTransportRoundtripCloseOnWriteError (0.01s) === RUN TestTransportBodyRewindRace ---- PASS: TestTransportBodyRewindRace (1.44s) +--- PASS: TestTransportBodyRewindRace (0.67s) === RUN TestTransportServerResetStreamAtHeaders --- PASS: TestTransportServerResetStreamAtHeaders (0.03s) === RUN TestTransportExpectContinue @@ -2207,35 +2247,35 @@ === RUN TestTransportExpectContinue/reject --- PASS: TestTransportExpectContinue (0.05s) --- PASS: TestTransportExpectContinue/read-all (0.04s) - --- PASS: TestTransportExpectContinue/reject (0.01s) + --- PASS: TestTransportExpectContinue/reject (0.00s) === RUN TestTransportFrameBufferReuse ---- PASS: TestTransportFrameBufferReuse (0.10s) +--- PASS: TestTransportFrameBufferReuse (0.11s) === RUN TestTransportBlockingRequestWrite === RUN TestTransportBlockingRequestWrite/headers === RUN TestTransportBlockingRequestWrite/body === RUN TestTransportBlockingRequestWrite/trailer ---- PASS: TestTransportBlockingRequestWrite (0.14s) +--- PASS: TestTransportBlockingRequestWrite (0.17s) --- PASS: TestTransportBlockingRequestWrite/headers (0.05s) - --- PASS: TestTransportBlockingRequestWrite/body (0.04s) + --- PASS: TestTransportBlockingRequestWrite/body (0.07s) --- PASS: TestTransportBlockingRequestWrite/trailer (0.05s) === RUN TestTransportCloseRequestBody === RUN TestTransportCloseRequestBody/status=200 === RUN TestTransportCloseRequestBody/status=401 --- PASS: TestTransportCloseRequestBody (0.03s) --- PASS: TestTransportCloseRequestBody/status=200 (0.01s) - --- PASS: TestTransportCloseRequestBody/status=401 (0.01s) + --- PASS: TestTransportCloseRequestBody/status=401 (0.00s) === RUN TestTransportRetriesOnStreamProtocolError --- PASS: TestTransportRetriesOnStreamProtocolError (0.00s) === RUN TestClientConnReservations ---- PASS: TestClientConnReservations (0.00s) +--- PASS: TestClientConnReservations (0.01s) === RUN TestTransportTimeoutServerHangs --- PASS: TestTransportTimeoutServerHangs (1.00s) === RUN TestTransportContentLengthWithoutBody === RUN TestTransportContentLengthWithoutBody/non-zero_content_length === RUN TestTransportContentLengthWithoutBody/zero_content_length ---- PASS: TestTransportContentLengthWithoutBody (0.03s) +--- PASS: TestTransportContentLengthWithoutBody (0.02s) --- PASS: TestTransportContentLengthWithoutBody/non-zero_content_length (0.01s) - --- PASS: TestTransportContentLengthWithoutBody/zero_content_length (0.01s) + --- PASS: TestTransportContentLengthWithoutBody/zero_content_length (0.00s) === RUN TestTransportCloseResponseBodyWhileRequestBodyHangs --- PASS: TestTransportCloseResponseBodyWhileRequestBodyHangs (0.02s) === RUN TestTransport300ResponseBody @@ -2243,13 +2283,13 @@ === RUN TestTransportWriteByteTimeout --- PASS: TestTransportWriteByteTimeout (0.00s) === RUN TestTransportSlowWrites ---- PASS: TestTransportSlowWrites (0.85s) +--- PASS: TestTransportSlowWrites (0.62s) === RUN TestTransportClosesConnAfterGoAwayNoStreams --- PASS: TestTransportClosesConnAfterGoAwayNoStreams (0.00s) === RUN TestTransportClosesConnAfterGoAwayLastStream --- PASS: TestTransportClosesConnAfterGoAwayLastStream (0.00s) === RUN TestTransportSlowClose ---- PASS: TestTransportSlowClose (0.02s) +--- PASS: TestTransportSlowClose (0.03s) === RUN TestPriorityTwoStreams --- PASS: TestPriorityTwoStreams (0.00s) === RUN TestPriorityAdjustExclusiveZero @@ -2289,7 +2329,7 @@ === RUN TestPriorityRstStreamOnNonOpenStreams --- PASS: TestPriorityRstStreamOnNonOpenStreams (0.00s) === RUN TestRandomScheduler - writesched_random_test.go:29: got frames: [[FrameWriteRequest stream=0, ch=false, writer=http2.writeSettingsAck] [FrameWriteRequest stream=0, ch=false, writer=http2.writeSettingsAck] [FrameWriteRequest stream=1, ch=false, writer=http2.StreamError] [FrameWriteRequest stream=3, ch=false, writer=*http2.writeResHeaders] [FrameWriteRequest stream=2, ch=false, writer=*http2.writeResHeaders] [FrameWriteRequest stream=4, ch=false, writer=*http2.writeResHeaders] [FrameWriteRequest stream=1, ch=false, writer=*http2.writeResHeaders]] + writesched_random_test.go:29: got frames: [[FrameWriteRequest stream=0, ch=false, writer=http2.writeSettingsAck] [FrameWriteRequest stream=0, ch=false, writer=http2.writeSettingsAck] [FrameWriteRequest stream=1, ch=false, writer=http2.StreamError] [FrameWriteRequest stream=3, ch=false, writer=*http2.writeResHeaders] [FrameWriteRequest stream=4, ch=false, writer=*http2.writeResHeaders] [FrameWriteRequest stream=1, ch=false, writer=*http2.writeResHeaders] [FrameWriteRequest stream=2, ch=false, writer=*http2.writeResHeaders]] --- PASS: TestRandomScheduler (0.00s) === RUN TestFrameWriteRequestNonData --- PASS: TestFrameWriteRequestNonData (0.00s) @@ -2300,71 +2340,71 @@ === RUN TestFrameWriteRequest_StreamID --- PASS: TestFrameWriteRequest_StreamID (0.00s) PASS -ok golang.org/x/net/http2 61.282s +ok golang.org/x/net/http2 59.168s === RUN TestContext ---- PASS: TestContext (0.04s) +--- PASS: TestContext (0.01s) === RUN TestPropagation - h2c_test.go:107: server conn state: conn 127.0.0.1:51244 -> 127.0.0.1:44701, status changed to new - h2c_test.go:119: client dial tls: 127.0.0.1:44701 -> 127.0.0.1:51244 - h2c_test.go:107: server conn state: conn 127.0.0.1:51244 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:51244 -> 127.0.0.1:44701, status changed to hijacked - h2c_test.go:107: server conn state: conn 127.0.0.1:51244 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:51244 -> 127.0.0.1:44701, status changed to idle -2023/05/22 06:17:44 http2: server connection error from 127.0.0.1:51244: connection error: COMPRESSION_ERROR - h2c_test.go:107: server conn state: conn 127.0.0.1:51250 -> 127.0.0.1:44701, status changed to new - h2c_test.go:119: client dial tls: 127.0.0.1:44701 -> 127.0.0.1:51250 - h2c_test.go:107: server conn state: conn 127.0.0.1:51250 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:51250 -> 127.0.0.1:44701, status changed to hijacked - h2c_test.go:107: server conn state: conn 127.0.0.1:51250 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:51250 -> 127.0.0.1:44701, status changed to idle -2023/05/22 06:17:44 http2: server connection error from 127.0.0.1:51250: connection error: COMPRESSION_ERROR - h2c_test.go:119: client dial tls: 127.0.0.1:44701 -> 127.0.0.1:51252 - h2c_test.go:107: server conn state: conn 127.0.0.1:51252 -> 127.0.0.1:44701, status changed to new - h2c_test.go:107: server conn state: conn 127.0.0.1:51252 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:51252 -> 127.0.0.1:44701, status changed to hijacked - h2c_test.go:107: server conn state: conn 127.0.0.1:51252 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:51252 -> 127.0.0.1:44701, status changed to idle -2023/05/22 06:17:45 http2: server connection error from 127.0.0.1:51252: connection error: COMPRESSION_ERROR - h2c_test.go:119: client dial tls: 127.0.0.1:44701 -> 127.0.0.1:51258 - h2c_test.go:107: server conn state: conn 127.0.0.1:51258 -> 127.0.0.1:44701, status changed to new - h2c_test.go:107: server conn state: conn 127.0.0.1:51258 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:51258 -> 127.0.0.1:44701, status changed to hijacked - h2c_test.go:107: server conn state: conn 127.0.0.1:51258 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:51258 -> 127.0.0.1:44701, status changed to idle -2023/05/22 06:17:47 http2: server connection error from 127.0.0.1:51258: connection error: COMPRESSION_ERROR - h2c_test.go:119: client dial tls: 127.0.0.1:44701 -> 127.0.0.1:51266 - h2c_test.go:107: server conn state: conn 127.0.0.1:51266 -> 127.0.0.1:44701, status changed to new - h2c_test.go:107: server conn state: conn 127.0.0.1:51266 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:51266 -> 127.0.0.1:44701, status changed to hijacked - h2c_test.go:107: server conn state: conn 127.0.0.1:51266 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:51266 -> 127.0.0.1:44701, status changed to idle -2023/05/22 06:17:51 http2: server connection error from 127.0.0.1:51266: connection error: COMPRESSION_ERROR - h2c_test.go:119: client dial tls: 127.0.0.1:44701 -> 127.0.0.1:36898 - h2c_test.go:107: server conn state: conn 127.0.0.1:36898 -> 127.0.0.1:44701, status changed to new - h2c_test.go:107: server conn state: conn 127.0.0.1:36898 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:36898 -> 127.0.0.1:44701, status changed to hijacked - h2c_test.go:107: server conn state: conn 127.0.0.1:36898 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:36898 -> 127.0.0.1:44701, status changed to idle -2023/05/22 06:17:59 http2: server connection error from 127.0.0.1:36898: connection error: COMPRESSION_ERROR - h2c_test.go:119: client dial tls: 127.0.0.1:44701 -> 127.0.0.1:40452 - h2c_test.go:107: server conn state: conn 127.0.0.1:40452 -> 127.0.0.1:44701, status changed to new - h2c_test.go:107: server conn state: conn 127.0.0.1:40452 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:40452 -> 127.0.0.1:44701, status changed to hijacked - h2c_test.go:107: server conn state: conn 127.0.0.1:40452 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:40452 -> 127.0.0.1:44701, status changed to idle -2023/05/22 06:18:15 http2: server connection error from 127.0.0.1:40452: connection error: COMPRESSION_ERROR - h2c_test.go:119: client dial tls: 127.0.0.1:44701 -> 127.0.0.1:57982 - h2c_test.go:107: server conn state: conn 127.0.0.1:57982 -> 127.0.0.1:44701, status changed to new - h2c_test.go:107: server conn state: conn 127.0.0.1:57982 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:57982 -> 127.0.0.1:44701, status changed to hijacked - h2c_test.go:107: server conn state: conn 127.0.0.1:57982 -> 127.0.0.1:44701, status changed to active - h2c_test.go:107: server conn state: conn 127.0.0.1:57982 -> 127.0.0.1:44701, status changed to idle -2023/05/22 06:18:49 http2: server connection error from 127.0.0.1:57982: connection error: COMPRESSION_ERROR ---- PASS: TestPropagation (65.31s) + h2c_test.go:107: server conn state: conn 127.0.0.1:56344 -> 127.0.0.1:39745, status changed to new + h2c_test.go:119: client dial tls: 127.0.0.1:39745 -> 127.0.0.1:56344 + h2c_test.go:107: server conn state: conn 127.0.0.1:56344 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:56344 -> 127.0.0.1:39745, status changed to hijacked + h2c_test.go:107: server conn state: conn 127.0.0.1:56344 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:56344 -> 127.0.0.1:39745, status changed to idle +2024/06/24 14:46:55 http2: server connection error from 127.0.0.1:56344: connection error: COMPRESSION_ERROR + h2c_test.go:119: client dial tls: 127.0.0.1:39745 -> 127.0.0.1:56354 + h2c_test.go:107: server conn state: conn 127.0.0.1:56354 -> 127.0.0.1:39745, status changed to new + h2c_test.go:107: server conn state: conn 127.0.0.1:56354 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:56354 -> 127.0.0.1:39745, status changed to hijacked + h2c_test.go:107: server conn state: conn 127.0.0.1:56354 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:56354 -> 127.0.0.1:39745, status changed to idle +2024/06/24 14:46:55 http2: server connection error from 127.0.0.1:56354: connection error: COMPRESSION_ERROR + h2c_test.go:119: client dial tls: 127.0.0.1:39745 -> 127.0.0.1:56370 + h2c_test.go:107: server conn state: conn 127.0.0.1:56370 -> 127.0.0.1:39745, status changed to new + h2c_test.go:107: server conn state: conn 127.0.0.1:56370 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:56370 -> 127.0.0.1:39745, status changed to hijacked + h2c_test.go:107: server conn state: conn 127.0.0.1:56370 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:56370 -> 127.0.0.1:39745, status changed to idle +2024/06/24 14:46:56 http2: server connection error from 127.0.0.1:56370: connection error: COMPRESSION_ERROR + h2c_test.go:119: client dial tls: 127.0.0.1:39745 -> 127.0.0.1:56380 + h2c_test.go:107: server conn state: conn 127.0.0.1:56380 -> 127.0.0.1:39745, status changed to new + h2c_test.go:107: server conn state: conn 127.0.0.1:56380 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:56380 -> 127.0.0.1:39745, status changed to hijacked + h2c_test.go:107: server conn state: conn 127.0.0.1:56380 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:56380 -> 127.0.0.1:39745, status changed to idle +2024/06/24 14:46:58 http2: server connection error from 127.0.0.1:56380: connection error: COMPRESSION_ERROR + h2c_test.go:119: client dial tls: 127.0.0.1:39745 -> 127.0.0.1:56392 + h2c_test.go:107: server conn state: conn 127.0.0.1:56392 -> 127.0.0.1:39745, status changed to new + h2c_test.go:107: server conn state: conn 127.0.0.1:56392 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:56392 -> 127.0.0.1:39745, status changed to hijacked + h2c_test.go:107: server conn state: conn 127.0.0.1:56392 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:56392 -> 127.0.0.1:39745, status changed to idle +2024/06/24 14:47:02 http2: server connection error from 127.0.0.1:56392: connection error: COMPRESSION_ERROR + h2c_test.go:119: client dial tls: 127.0.0.1:39745 -> 127.0.0.1:47204 + h2c_test.go:107: server conn state: conn 127.0.0.1:47204 -> 127.0.0.1:39745, status changed to new + h2c_test.go:107: server conn state: conn 127.0.0.1:47204 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:47204 -> 127.0.0.1:39745, status changed to hijacked + h2c_test.go:107: server conn state: conn 127.0.0.1:47204 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:47204 -> 127.0.0.1:39745, status changed to idle +2024/06/24 14:47:10 http2: server connection error from 127.0.0.1:47204: connection error: COMPRESSION_ERROR + h2c_test.go:119: client dial tls: 127.0.0.1:39745 -> 127.0.0.1:55976 + h2c_test.go:107: server conn state: conn 127.0.0.1:55976 -> 127.0.0.1:39745, status changed to new + h2c_test.go:107: server conn state: conn 127.0.0.1:55976 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:55976 -> 127.0.0.1:39745, status changed to hijacked + h2c_test.go:107: server conn state: conn 127.0.0.1:55976 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:55976 -> 127.0.0.1:39745, status changed to idle +2024/06/24 14:47:26 http2: server connection error from 127.0.0.1:55976: connection error: COMPRESSION_ERROR + h2c_test.go:119: client dial tls: 127.0.0.1:39745 -> 127.0.0.1:37068 + h2c_test.go:107: server conn state: conn 127.0.0.1:37068 -> 127.0.0.1:39745, status changed to new + h2c_test.go:107: server conn state: conn 127.0.0.1:37068 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:37068 -> 127.0.0.1:39745, status changed to hijacked + h2c_test.go:107: server conn state: conn 127.0.0.1:37068 -> 127.0.0.1:39745, status changed to active + h2c_test.go:107: server conn state: conn 127.0.0.1:37068 -> 127.0.0.1:39745, status changed to idle +2024/06/24 14:48:01 http2: server connection error from 127.0.0.1:37068: connection error: COMPRESSION_ERROR +--- PASS: TestPropagation (65.27s) === RUN TestMaxBytesHandler --- PASS: TestMaxBytesHandler (0.50s) PASS -ok golang.org/x/net/http2/h2c 65.947s +ok golang.org/x/net/http2/h2c 65.826s === RUN TestEncoderTableSizeUpdate --- PASS: TestEncoderTableSizeUpdate (0.00s) === RUN TestEncoderWriteField @@ -2416,13 +2456,13 @@ === RUN TestHuffmanMaxStrLen --- PASS: TestHuffmanMaxStrLen (0.00s) === RUN TestHuffmanRoundtripStress - hpack_test.go:565: Seed = 1684779438525928219 - hpack_test.go:583: Compressed size of original: 229.34% (5000 -> 11467) + hpack_test.go:565: Seed = 1719189996635730220 + hpack_test.go:583: Compressed size of original: 228.42% (5000 -> 11421) --- PASS: TestHuffmanRoundtripStress (0.00s) === RUN TestHuffmanDecodeFuzz - hpack_test.go:595: Seed = 1684779438526574045 - hpack_test.go:619: 58.00% are invalid (58 / 100) ---- PASS: TestHuffmanDecodeFuzz (0.00s) + hpack_test.go:595: Seed = 1719189996636393980 + hpack_test.go:619: 69.00% are invalid (69 / 100) +--- PASS: TestHuffmanDecodeFuzz (0.01s) === RUN TestReadVarInt --- PASS: TestReadVarInt (0.00s) === RUN TestHuffmanFuzzCrash @@ -2441,9 +2481,9 @@ === RUN TestHeaderFieldTable_LookupMapEviction --- PASS: TestHeaderFieldTable_LookupMapEviction (0.00s) === RUN TestStaticTable ---- PASS: TestStaticTable (0.04s) +--- PASS: TestStaticTable (0.00s) PASS -ok golang.org/x/net/http2/hpack 0.087s +ok golang.org/x/net/http2/hpack 0.105s === RUN TestMarshalAndParseExtension === RUN TestMarshalAndParseExtension/MPLSLabelStack === RUN TestMarshalAndParseExtension/InterfaceInfo @@ -2479,13 +2519,13 @@ === RUN TestMarshalAndParseMultipartMessage === RUN TestMarshalAndParseMultipartMessage/IPv4 === RUN TestMarshalAndParseMultipartMessage/IPv6 ---- PASS: TestMarshalAndParseMultipartMessage (0.00s) +--- PASS: TestMarshalAndParseMultipartMessage (0.02s) --- PASS: TestMarshalAndParseMultipartMessage/IPv4 (0.00s) - --- PASS: TestMarshalAndParseMultipartMessage/IPv6 (0.00s) + --- PASS: TestMarshalAndParseMultipartMessage/IPv6 (0.02s) === RUN TestMultipartMessageBodyLen --- PASS: TestMultipartMessageBodyLen (0.00s) PASS -ok golang.org/x/net/icmp 0.145s +ok golang.org/x/net/icmp 0.091s === RUN TestIDNA --- PASS: TestIDNA (0.00s) === RUN TestIDNASeparators @@ -2507,12 +2547,12 @@ === RUN ExampleNew --- PASS: ExampleNew (0.00s) PASS -ok golang.org/x/net/idna 0.059s +ok golang.org/x/net/idna 0.106s ? golang.org/x/net/internal/iana [no test files] === RUN TestUDPDontwait === RUN TestUDPDontwait/Message-dontwait === RUN TestUDPDontwait/Messages ---- PASS: TestUDPDontwait (0.05s) +--- PASS: TestUDPDontwait (0.04s) --- PASS: TestUDPDontwait/Message-dontwait (0.00s) --- PASS: TestUDPDontwait/Messages (0.00s) === RUN TestSocket @@ -2537,7 +2577,7 @@ socket_test.go:456: /usr/lib/go-1.19/bin/go version socket_test.go:459: go version go1.19.8 linux/arm64 === RUN TestRace/test_0 - socket_test.go:470: /usr/lib/go-1.19/bin/go run -race /tmp/testrace83750353/test0.go + socket_test.go:470: /usr/lib/go-1.19/bin/go run -race /tmp/testrace3896969691/test0.go socket_test.go:473: ================== WARNING: DATA RACE Write at 0x00c0000262a0 by goroutine 8: @@ -2550,23 +2590,23 @@ golang.org/x/net/ipv4.(*payloadHandler).ReadFrom() /build/golang-golang-x-net-0.7.0+dfsg/_build/src/golang.org/x/net/ipv4/payload_cmsg.go:32 +0x604 main.main.func2() - /tmp/testrace83750353/test0.go:27 +0x64 + /tmp/testrace3896969691/test0.go:27 +0x64 Previous read at 0x00c0000262a0 by main goroutine: main.main() - /tmp/testrace83750353/test0.go:32 +0x2d4 + /tmp/testrace3896969691/test0.go:32 +0x2d4 Goroutine 8 (running) created at: main.main() - /tmp/testrace83750353/test0.go:26 +0x2cc + /tmp/testrace3896969691/test0.go:26 +0x2cc ================== Found 1 data race(s) exit status 66 === RUN TestRace/test_1 - socket_test.go:470: /usr/lib/go-1.19/bin/go run -race /tmp/testrace83750353/test1.go + socket_test.go:470: /usr/lib/go-1.19/bin/go run -race /tmp/testrace3896969691/test1.go socket_test.go:473: ================== WARNING: DATA RACE - Read at 0x00c0000c8000 by goroutine 7: + Read at 0x00c000026230 by goroutine 7: runtime.racereadrange() :1 +0x14 golang.org/x/net/internal/socket.(*Conn).sendMsg() @@ -2576,51 +2616,51 @@ golang.org/x/net/ipv4.(*payloadHandler).WriteTo() /build/golang-golang-x-net-0.7.0+dfsg/_build/src/golang.org/x/net/ipv4/payload_cmsg.go:80 +0x64 main.main.func1() - /tmp/testrace83750353/test1.go:20 +0x94 + /tmp/testrace3896969691/test1.go:20 +0x94 - Previous write at 0x00c0000c8000 by main goroutine: + Previous write at 0x00c000026230 by main goroutine: main.main() - /tmp/testrace83750353/test1.go:25 +0x230 + /tmp/testrace3896969691/test1.go:25 +0x230 Goroutine 7 (running) created at: main.main() - /tmp/testrace83750353/test1.go:19 +0x228 + /tmp/testrace3896969691/test1.go:19 +0x228 ================== Found 1 data race(s) exit status 66 ---- PASS: TestRace (104.57s) - --- PASS: TestRace/test_0 (101.90s) - --- PASS: TestRace/test_1 (2.59s) +--- PASS: TestRace (89.74s) + --- PASS: TestRace/test_0 (86.92s) + --- PASS: TestRace/test_1 (2.79s) PASS -ok golang.org/x/net/internal/socket 104.626s +ok golang.org/x/net/internal/socket 89.826s === RUN TestDial === RUN TestDial/Connect === RUN TestDial/ConnectWithConn === RUN TestDial/Cancel === RUN TestDial/Deadline === RUN TestDial/WithRogueServer ---- PASS: TestDial (0.32s) - --- PASS: TestDial/Connect (0.06s) +--- PASS: TestDial (0.34s) + --- PASS: TestDial/Connect (0.10s) --- PASS: TestDial/ConnectWithConn (0.00s) - --- PASS: TestDial/Cancel (0.10s) - --- PASS: TestDial/Deadline (0.11s) - --- PASS: TestDial/WithRogueServer (0.05s) + --- PASS: TestDial/Cancel (0.11s) + --- PASS: TestDial/Deadline (0.10s) + --- PASS: TestDial/WithRogueServer (0.02s) PASS -ok golang.org/x/net/internal/socks 0.350s +ok golang.org/x/net/internal/socks 0.500s === RUN TestParseAuthRequest --- PASS: TestParseAuthRequest (0.00s) === RUN TestParseCmdRequest --- PASS: TestParseCmdRequest (0.00s) PASS -ok golang.org/x/net/internal/sockstest 0.059s +ok golang.org/x/net/internal/sockstest 0.144s === RUN TestBasicTimeSeries ---- PASS: TestBasicTimeSeries (0.01s) +--- PASS: TestBasicTimeSeries (0.00s) === RUN TestFloat --- PASS: TestFloat (0.00s) === RUN TestExpectedErrorRate ---- PASS: TestExpectedErrorRate (0.01s) +--- PASS: TestExpectedErrorRate (0.04s) PASS -ok golang.org/x/net/internal/timeseries 0.035s +ok golang.org/x/net/internal/timeseries 0.115s === RUN TestMarshalHeader --- PASS: TestMarshalHeader (0.00s) === RUN TestParseHeader @@ -2635,7 +2675,7 @@ --- PASS: TestICMPFilter (0.00s) === RUN TestSetICMPFilter icmp_test.go:70: not supported on linux/arm64 ---- SKIP: TestSetICMPFilter (0.09s) +--- SKIP: TestSetICMPFilter (0.07s) === RUN TestPacketConnReadWriteMulticastUDP multicast_test.go:38: no available interface --- SKIP: TestPacketConnReadWriteMulticastUDP (0.00s) @@ -2674,10 +2714,10 @@ === RUN TestPacketConnConcurrentReadWriteUnicast/UDP/Batch === RUN TestPacketConnConcurrentReadWriteUnicast/IP readwrite_test.go:371: not supported on linux/arm64: listen ip4:47 127.0.0.1: socket: operation not permitted ---- PASS: TestPacketConnConcurrentReadWriteUnicast (0.01s) - --- PASS: TestPacketConnConcurrentReadWriteUnicast/UDP (0.01s) +--- PASS: TestPacketConnConcurrentReadWriteUnicast (0.04s) + --- PASS: TestPacketConnConcurrentReadWriteUnicast/UDP (0.04s) --- PASS: TestPacketConnConcurrentReadWriteUnicast/UDP/ToFrom (0.00s) - --- PASS: TestPacketConnConcurrentReadWriteUnicast/UDP/Batch (0.01s) + --- PASS: TestPacketConnConcurrentReadWriteUnicast/UDP/Batch (0.04s) --- SKIP: TestPacketConnConcurrentReadWriteUnicast/IP (0.00s) === RUN TestPacketConnReadWriteUnicastUDP --- PASS: TestPacketConnReadWriteUnicastUDP (0.00s) @@ -2696,7 +2736,7 @@ unicastsockopt_test.go:93: not supported on linux/arm64 --- SKIP: TestRawConnUnicastSocketOptions (0.00s) PASS -ok golang.org/x/net/ipv4 0.209s +ok golang.org/x/net/ipv4 0.175s === RUN TestBPF bpf_test.go:23: ipv6 is not enabled for loopback interface --- SKIP: TestBPF (0.00s) @@ -2710,7 +2750,7 @@ --- PASS: TestICMPFilter (0.00s) === RUN TestSetICMPFilter icmp_test.go:68: ipv6 is not supported ---- SKIP: TestSetICMPFilter (0.07s) +--- SKIP: TestSetICMPFilter (0.06s) === RUN TestPacketConnReadWriteMulticastUDP multicast_test.go:36: ipv6 is not supported --- SKIP: TestPacketConnReadWriteMulticastUDP (0.00s) @@ -2744,11 +2784,11 @@ === RUN TestPacketConnConcurrentReadWriteUnicast/UDP/Batch === RUN TestPacketConnConcurrentReadWriteUnicast/IP readwrite_test.go:369: not supported on linux/arm64: listen ip6:47 ::1: socket: operation not permitted ---- PASS: TestPacketConnConcurrentReadWriteUnicast (0.02s) +--- PASS: TestPacketConnConcurrentReadWriteUnicast (0.01s) --- PASS: TestPacketConnConcurrentReadWriteUnicast/UDP (0.01s) --- PASS: TestPacketConnConcurrentReadWriteUnicast/UDP/ToFrom (0.00s) --- PASS: TestPacketConnConcurrentReadWriteUnicast/UDP/Batch (0.00s) - --- SKIP: TestPacketConnConcurrentReadWriteUnicast/IP (0.02s) + --- SKIP: TestPacketConnConcurrentReadWriteUnicast/IP (0.00s) === RUN TestConnInitiatorPathMTU sockopt_test.go:25: ipv6 is not enabled for loopback interface --- SKIP: TestConnInitiatorPathMTU (0.00s) @@ -2766,12 +2806,12 @@ --- SKIP: TestPacketConnReadWriteUnicastICMP (0.00s) === RUN TestConnUnicastSocketOptions unicastsockopt_test.go:23: ipv6 is not enabled for loopback interface ---- SKIP: TestConnUnicastSocketOptions (0.00s) +--- SKIP: TestConnUnicastSocketOptions (0.03s) === RUN TestPacketConnUnicastSocketOptions unicastsockopt_test.go:68: ipv6 is not enabled for loopback interface --- SKIP: TestPacketConnUnicastSocketOptions (0.00s) PASS -ok golang.org/x/net/ipv6 0.180s +ok golang.org/x/net/ipv6 0.197s === RUN TestTestConn === RUN TestTestConn/TCP === RUN TestTestConn/TCP/BasicIO @@ -2809,54 +2849,54 @@ === RUN TestTestConn/UnixPacketPipe/FutureTimeout === RUN TestTestConn/UnixPacketPipe/CloseTimeout === RUN TestTestConn/UnixPacketPipe/ConcurrentMethods ---- PASS: TestTestConn (2.29s) - --- PASS: TestTestConn/TCP (1.10s) - --- PASS: TestTestConn/TCP/BasicIO (0.10s) - --- PASS: TestTestConn/TCP/PingPong (0.12s) - --- PASS: TestTestConn/TCP/RacyRead (0.12s) - --- PASS: TestTestConn/TCP/RacyWrite (0.06s) +--- PASS: TestTestConn (2.47s) + --- PASS: TestTestConn/TCP (1.36s) + --- PASS: TestTestConn/TCP/BasicIO (0.22s) + --- PASS: TestTestConn/TCP/PingPong (0.17s) + --- PASS: TestTestConn/TCP/RacyRead (0.15s) + --- PASS: TestTestConn/TCP/RacyWrite (0.02s) --- PASS: TestTestConn/TCP/ReadTimeout (0.00s) --- PASS: TestTestConn/TCP/WriteTimeout (0.01s) --- PASS: TestTestConn/TCP/PastTimeout (0.00s) - --- PASS: TestTestConn/TCP/PresentTimeout (0.11s) - --- PASS: TestTestConn/TCP/FutureTimeout (0.39s) - --- PASS: TestTestConn/TCP/CloseTimeout (0.16s) - --- PASS: TestTestConn/TCP/ConcurrentMethods (0.04s) - --- PASS: TestTestConn/UnixPipe (0.62s) - --- PASS: TestTestConn/UnixPipe/BasicIO (0.06s) - --- PASS: TestTestConn/UnixPipe/PingPong (0.06s) - --- PASS: TestTestConn/UnixPipe/RacyRead (0.06s) - --- PASS: TestTestConn/UnixPipe/RacyWrite (0.02s) + --- PASS: TestTestConn/TCP/PresentTimeout (0.12s) + --- PASS: TestTestConn/TCP/FutureTimeout (0.51s) + --- PASS: TestTestConn/TCP/CloseTimeout (0.14s) + --- PASS: TestTestConn/TCP/ConcurrentMethods (0.02s) + --- PASS: TestTestConn/UnixPipe (0.63s) + --- PASS: TestTestConn/UnixPipe/BasicIO (0.07s) + --- PASS: TestTestConn/UnixPipe/PingPong (0.08s) + --- PASS: TestTestConn/UnixPipe/RacyRead (0.08s) + --- PASS: TestTestConn/UnixPipe/RacyWrite (0.07s) --- PASS: TestTestConn/UnixPipe/ReadTimeout (0.00s) --- PASS: TestTestConn/UnixPipe/WriteTimeout (0.00s) --- PASS: TestTestConn/UnixPipe/PastTimeout (0.00s) - --- PASS: TestTestConn/UnixPipe/PresentTimeout (0.11s) - --- PASS: TestTestConn/UnixPipe/FutureTimeout (0.11s) - --- PASS: TestTestConn/UnixPipe/CloseTimeout (0.14s) - --- PASS: TestTestConn/UnixPipe/ConcurrentMethods (0.04s) - --- PASS: TestTestConn/UnixPacketPipe (0.57s) - --- PASS: TestTestConn/UnixPacketPipe/BasicIO (0.05s) - --- PASS: TestTestConn/UnixPacketPipe/PingPong (0.10s) - --- PASS: TestTestConn/UnixPacketPipe/RacyRead (0.04s) - --- PASS: TestTestConn/UnixPacketPipe/RacyWrite (0.02s) - --- PASS: TestTestConn/UnixPacketPipe/ReadTimeout (0.00s) - --- PASS: TestTestConn/UnixPacketPipe/WriteTimeout (0.01s) + --- PASS: TestTestConn/UnixPipe/PresentTimeout (0.10s) + --- PASS: TestTestConn/UnixPipe/FutureTimeout (0.10s) + --- PASS: TestTestConn/UnixPipe/CloseTimeout (0.11s) + --- PASS: TestTestConn/UnixPipe/ConcurrentMethods (0.02s) + --- PASS: TestTestConn/UnixPacketPipe (0.47s) + --- PASS: TestTestConn/UnixPacketPipe/BasicIO (0.03s) + --- PASS: TestTestConn/UnixPacketPipe/PingPong (0.04s) + --- PASS: TestTestConn/UnixPacketPipe/RacyRead (0.02s) + --- PASS: TestTestConn/UnixPacketPipe/RacyWrite (0.04s) + --- PASS: TestTestConn/UnixPacketPipe/ReadTimeout (0.01s) + --- PASS: TestTestConn/UnixPacketPipe/WriteTimeout (0.00s) --- PASS: TestTestConn/UnixPacketPipe/PastTimeout (0.00s) --- PASS: TestTestConn/UnixPacketPipe/PresentTimeout (0.10s) - --- PASS: TestTestConn/UnixPacketPipe/FutureTimeout (0.11s) - --- PASS: TestTestConn/UnixPacketPipe/CloseTimeout (0.12s) - --- PASS: TestTestConn/UnixPacketPipe/ConcurrentMethods (0.02s) + --- PASS: TestTestConn/UnixPacketPipe/FutureTimeout (0.10s) + --- PASS: TestTestConn/UnixPacketPipe/CloseTimeout (0.10s) + --- PASS: TestTestConn/UnixPacketPipe/ConcurrentMethods (0.01s) PASS -ok golang.org/x/net/nettest 2.353s +ok golang.org/x/net/nettest 2.576s === RUN TestLimitListenerOverload listen_test.go:52: with limit 5, accepted 5 simultaneous connections - listen_test.go:89: read tcp 127.0.0.1:33172->127.0.0.1:42187: read: connection reset by peer - listen_test.go:89: read tcp 127.0.0.1:33162->127.0.0.1:42187: read: connection reset by peer - listen_test.go:89: read tcp 127.0.0.1:33158->127.0.0.1:42187: read: connection reset by peer - listen_test.go:89: read tcp 127.0.0.1:33200->127.0.0.1:42187: read: connection reset by peer - listen_test.go:89: read tcp 127.0.0.1:33186->127.0.0.1:42187: read: connection reset by peer + listen_test.go:89: read tcp 127.0.0.1:54148->127.0.0.1:39295: read: connection reset by peer + listen_test.go:89: read tcp 127.0.0.1:54144->127.0.0.1:39295: read: connection reset by peer + listen_test.go:89: read tcp 127.0.0.1:54142->127.0.0.1:39295: read: connection reset by peer + listen_test.go:89: read tcp 127.0.0.1:54134->127.0.0.1:39295: read: connection reset by peer + listen_test.go:89: read tcp 127.0.0.1:54164->127.0.0.1:39295: read: connection reset by peer listen_test.go:108: served 5 simultaneous connections (of 10 dialed, 10 attempted) ---- PASS: TestLimitListenerOverload (0.04s) +--- PASS: TestLimitListenerOverload (0.03s) === RUN TestLimitListenerSaturation listen_test.go:205: served 10 connections (of 10 dialed, 10 attempted) listen_test.go:205: served 10 connections (of 10 dialed, 10 attempted) @@ -2869,13 +2909,13 @@ listen_test.go:205: served 10 connections (of 10 dialed, 10 attempted) listen_test.go:205: served 10 connections (of 10 dialed, 10 attempted) listen_test.go:174: with limit 5, accepted a peak of 5 simultaneous connections ---- PASS: TestLimitListenerSaturation (0.17s) +--- PASS: TestLimitListenerSaturation (0.09s) === RUN TestLimitListenerError --- PASS: TestLimitListenerError (0.00s) === RUN TestLimitListenerClose ---- PASS: TestLimitListenerClose (0.02s) +--- PASS: TestLimitListenerClose (0.01s) PASS -ok golang.org/x/net/netutil 0.301s +ok golang.org/x/net/netutil 0.177s === RUN TestDial === RUN TestDial/DirectWithCancel === RUN TestDial/DirectWithTimeout @@ -2884,12 +2924,12 @@ === RUN TestDial/SOCKS5WithTimeout === RUN TestDial/SOCKS5WithTimeoutExceeded --- PASS: TestDial (0.07s) - --- PASS: TestDial/DirectWithCancel (0.07s) + --- PASS: TestDial/DirectWithCancel (0.06s) --- PASS: TestDial/DirectWithTimeout (0.00s) --- PASS: TestDial/DirectWithTimeoutExceeded (0.00s) --- PASS: TestDial/SOCKS5 (0.00s) --- PASS: TestDial/SOCKS5WithTimeout (0.00s) - --- PASS: TestDial/SOCKS5WithTimeoutExceeded (0.00s) + --- PASS: TestDial/SOCKS5WithTimeoutExceeded (0.01s) === RUN TestPerHost === RUN TestPerHost/Dial === RUN TestPerHost/DialContext @@ -2905,7 +2945,7 @@ === RUN TestFromEnvironmentUsing --- PASS: TestFromEnvironmentUsing (0.00s) PASS -ok golang.org/x/net/proxy 0.113s +ok golang.org/x/net/proxy 0.120s === RUN TestNodeLabel --- PASS: TestNodeLabel (0.00s) === RUN TestFind @@ -2915,7 +2955,7 @@ === RUN TestPublicSuffix --- PASS: TestPublicSuffix (0.00s) === RUN TestSlowPublicSuffix ---- PASS: TestSlowPublicSuffix (1.58s) +--- PASS: TestSlowPublicSuffix (1.42s) === RUN TestNumICANNRules --- PASS: TestNumICANNRules (0.00s) === RUN TestEffectiveTLDPlusOne @@ -2923,7 +2963,7 @@ === RUN ExamplePublicSuffix_manager --- PASS: ExamplePublicSuffix_manager (0.00s) PASS -ok golang.org/x/net/publicsuffix 1.631s +ok golang.org/x/net/publicsuffix 1.476s === RUN TestSum --- PASS: TestSum (0.00s) === RUN TestMultiply @@ -2953,7 +2993,7 @@ === RUN TestParseTemplate --- PASS: TestParseTemplate (0.00s) PASS -ok golang.org/x/net/trace 0.067s +ok golang.org/x/net/trace 0.100s === RUN TestSlashClean --- PASS: TestSlashClean (0.00s) === RUN TestDirResolve @@ -2961,9 +3001,9 @@ === RUN TestWalk --- PASS: TestWalk (0.00s) === RUN TestDir ---- PASS: TestDir (0.03s) +--- PASS: TestDir (0.02s) === RUN TestMemFS ---- PASS: TestMemFS (0.00s) +--- PASS: TestMemFS (0.01s) === RUN TestMemFSRoot --- PASS: TestMemFSRoot (0.00s) === RUN TestMemFileReaddir @@ -2971,7 +3011,7 @@ === RUN TestMemFile --- PASS: TestMemFile (0.00s) === RUN TestMemFileWriteAllocs ---- PASS: TestMemFileWriteAllocs (0.02s) +--- PASS: TestMemFileWriteAllocs (0.01s) === RUN TestCopyMoveProps --- PASS: TestCopyMoveProps (0.00s) === RUN TestWalkFS @@ -2983,19 +3023,19 @@ === RUN TestMemLSCanCreate --- PASS: TestMemLSCanCreate (0.00s) === RUN TestMemLSLookup - lock_test.go:163: badToken="1684779487" - lock_test.go:174: "/_/_/_/_/z" -> node=0x400010aa10 token="1684779488" - lock_test.go:174: "/_/_/i" -> node=0x400010acb0 token="1684779489" - lock_test.go:174: "/_/z" -> node=0x400010ad20 token="1684779490" - lock_test.go:174: "/_/z/i" -> node=0x400010ad90 token="1684779491" - lock_test.go:174: "/_/z/z" -> node=0x400010ae00 token="1684779492" - lock_test.go:174: "/_/z/_/i" -> node=0x400010ae70 token="1684779493" - lock_test.go:174: "/_/z/_/z" -> node=0x400010af50 token="1684779494" - lock_test.go:174: "/i" -> node=0x400010afc0 token="1684779495" - lock_test.go:174: "/z" -> node=0x400010b030 token="1684779496" - lock_test.go:174: "/z/_/i" -> node=0x400010b0a0 token="1684779497" - lock_test.go:174: "/z/_/z" -> node=0x400010b180 token="1684779498" ---- PASS: TestMemLSLookup (0.02s) + lock_test.go:163: badToken="1719190037" + lock_test.go:174: "/_/_/_/_/z" -> node=0x4000138a10 token="1719190038" + lock_test.go:174: "/_/_/i" -> node=0x4000138cb0 token="1719190039" + lock_test.go:174: "/_/z" -> node=0x4000138d20 token="1719190040" + lock_test.go:174: "/_/z/i" -> node=0x4000138d90 token="1719190041" + lock_test.go:174: "/_/z/z" -> node=0x4000138e00 token="1719190042" + lock_test.go:174: "/_/z/_/i" -> node=0x4000138e70 token="1719190043" + lock_test.go:174: "/_/z/_/z" -> node=0x4000138f50 token="1719190044" + lock_test.go:174: "/i" -> node=0x4000138fc0 token="1719190045" + lock_test.go:174: "/z" -> node=0x4000139030 token="1719190046" + lock_test.go:174: "/z/_/i" -> node=0x40001390a0 token="1719190047" + lock_test.go:174: "/z/_/z" -> node=0x4000139180 token="1719190048" +--- PASS: TestMemLSLookup (0.00s) === RUN TestMemLSConfirm --- PASS: TestMemLSConfirm (0.00s) === RUN TestMemLSNonCanonicalRoot @@ -3003,21 +3043,21 @@ === RUN TestMemLSExpiry --- PASS: TestMemLSExpiry (0.00s) === RUN TestMemLS ---- PASS: TestMemLS (0.15s) +--- PASS: TestMemLS (0.16s) === RUN TestParseTimeout --- PASS: TestParseTimeout (0.00s) === RUN TestMemPS ---- PASS: TestMemPS (0.01s) +--- PASS: TestMemPS (0.00s) === RUN TestFindContentTypeOverride --- PASS: TestFindContentTypeOverride (0.00s) === RUN TestFindETagOverride --- PASS: TestFindETagOverride (0.00s) === RUN TestPrefix ---- PASS: TestPrefix (0.08s) +--- PASS: TestPrefix (0.29s) === RUN TestEscapeXML --- PASS: TestEscapeXML (0.00s) === RUN TestFilenameEscape ---- PASS: TestFilenameEscape (0.01s) +--- PASS: TestFilenameEscape (0.03s) === RUN TestReadLockInfo --- PASS: TestReadLockInfo (0.00s) === RUN TestReadPropfind @@ -3025,11 +3065,11 @@ === RUN TestMultistatusWriter --- PASS: TestMultistatusWriter (0.00s) === RUN TestReadProppatch ---- PASS: TestReadProppatch (0.00s) +--- PASS: TestReadProppatch (0.01s) === RUN TestUnmarshalXMLValue --- PASS: TestUnmarshalXMLValue (0.00s) PASS -ok golang.org/x/net/webdav 0.347s +ok golang.org/x/net/webdav 0.587s === RUN TestMarshal --- PASS: TestMarshal (0.01s) === RUN TestMarshalErrors @@ -3073,7 +3113,7 @@ === RUN TestUnmarshalNS --- PASS: TestUnmarshalNS (0.00s) === RUN TestRoundTrip ---- PASS: TestRoundTrip (0.02s) +--- PASS: TestRoundTrip (0.00s) === RUN TestMarshalNS --- PASS: TestMarshalNS (0.00s) === RUN TestUnmarshalNSAttr @@ -3137,16 +3177,16 @@ === RUN ExampleUnmarshal --- PASS: ExampleUnmarshal (0.00s) PASS -ok golang.org/x/net/webdav/internal/xml 0.193s +ok golang.org/x/net/webdav/internal/xml 0.090s === RUN TestDialConfigTLSWithDialer -2023/05/22 06:18:01 Test TLS WebSocket server listening on 127.0.0.1:39147 ---- PASS: TestDialConfigTLSWithDialer (0.01s) +2024/06/24 14:47:10 Test TLS WebSocket server listening on 127.0.0.1:36253 +--- PASS: TestDialConfigTLSWithDialer (0.02s) === RUN TestSecWebSocketAccept --- PASS: TestSecWebSocketAccept (0.00s) === RUN TestHybiClientHandshake --- PASS: TestHybiClientHandshake (0.00s) === RUN TestHybiClientHandshakeWithHeader ---- PASS: TestHybiClientHandshakeWithHeader (0.00s) +--- PASS: TestHybiClientHandshakeWithHeader (0.02s) === RUN TestHybiServerHandshake --- PASS: TestHybiServerHandshake (0.00s) === RUN TestHybiServerHandshakeNoSubProtocol @@ -3176,14 +3216,14 @@ === RUN TestHybiServerFirefoxHandshake --- PASS: TestHybiServerFirefoxHandshake (0.00s) === RUN TestEcho -2023/05/22 06:18:01 Test WebSocket server listening on 127.0.0.1:35771 ---- PASS: TestEcho (0.00s) +2024/06/24 14:47:11 Test WebSocket server listening on 127.0.0.1:45595 +--- PASS: TestEcho (0.01s) === RUN TestAddr --- PASS: TestAddr (0.00s) === RUN TestCount --- PASS: TestCount (0.00s) === RUN TestWithQuery ---- PASS: TestWithQuery (0.02s) +--- PASS: TestWithQuery (0.08s) === RUN TestWithProtocol --- PASS: TestWithProtocol (0.00s) === RUN TestWithTwoProtocol @@ -3191,9 +3231,9 @@ === RUN TestWithBadProtocol --- PASS: TestWithBadProtocol (0.00s) === RUN TestHTTP ---- PASS: TestHTTP (0.00s) +--- PASS: TestHTTP (0.01s) === RUN TestTrailingSpaces ---- PASS: TestTrailingSpaces (0.08s) +--- PASS: TestTrailingSpaces (0.10s) === RUN TestDialConfigBadVersion --- PASS: TestDialConfigBadVersion (0.00s) === RUN TestDialConfigWithDialer @@ -3207,7 +3247,7 @@ === RUN TestOrigin --- PASS: TestOrigin (0.00s) === RUN TestCtrlAndData ---- PASS: TestCtrlAndData (0.00s) +--- PASS: TestCtrlAndData (0.01s) === RUN TestCodec_ReceiveLimited websocket_test.go:629: payload #0 (size 1024, exceeds limit: false) websocket_test.go:629: payload #1 (size 2048, exceeds limit: false) @@ -3215,7 +3255,7 @@ websocket_test.go:629: payload #3 (size 2048, exceeds limit: false) --- PASS: TestCodec_ReceiveLimited (0.00s) PASS -ok golang.org/x/net/websocket 0.193s +ok golang.org/x/net/websocket 0.343s === RUN TestValidToken --- PASS: TestValidToken (0.00s) === RUN TestSeparatorReplacement @@ -3225,7 +3265,7 @@ === RUN TestValidateBadData --- PASS: TestValidateBadData (0.00s) PASS -ok golang.org/x/net/xsrftoken 0.080s +ok golang.org/x/net/xsrftoken 0.042s make[1]: Leaving directory '/build/golang-golang-x-net-0.7.0+dfsg' create-stamp debian/debhelper-build-stamp dh_testroot -O--builddirectory=_build -O--buildsystem=golang @@ -3262,12 +3302,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/29662/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/29662/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/5924 and its subdirectories -I: Current time: Mon May 22 06:19:46 -12 2023 -I: pbuilder-time-stamp: 1684779586 +I: removing directory /srv/workspace/pbuilder/29662 and its subdirectories +I: Current time: Mon Jun 24 14:48:50 +14 2024 +I: pbuilder-time-stamp: 1719190130