Diff of the two buildlogs: -- --- b1/build.log 2024-11-12 06:50:33.546703800 +0000 +++ b2/build.log 2024-11-12 07:15:06.406070370 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Mon Nov 11 18:46:46 -12 2024 -I: pbuilder-time-stamp: 1731394006 +I: Current time: Tue Nov 12 20:50:46 +14 2024 +I: pbuilder-time-stamp: 1731394246 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-reproducible-base.tgz] I: copying local configuration @@ -29,52 +29,84 @@ dpkg-source: info: applying 0002-Work-around-slog-logger-breaking-change.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/29236/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/16966/tmp/hooks/D01_modify_environment starting +debug: Running on ff64a. +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 Nov 12 06:51 /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/16966/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/16966/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='armhf' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=3 ' - DISTRIBUTION='unstable' - HOME='/root' - HOST_ARCH='armhf' + 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]="32" [3]="1" [4]="release" [5]="arm-unknown-linux-gnueabihf") + BASH_VERSION='5.2.32(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=armhf + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=6 ' + DIRSTACK=() + DISTRIBUTION=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=arm + HOST_ARCH=armhf IFS=' ' - INVOCATION_ID='948579531d284b4b83b00be80b8e33de' - 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='29236' - PS1='# ' - PS2='> ' + INVOCATION_ID=90acd666463648ba9dfd38f2b152cbb7 + LANG=C + LANGUAGE=it_CH:it + LC_ALL=C + MACHTYPE=arm-unknown-linux-gnueabihf + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnueabihf + 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=16966 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.7miTsDVO/pbuilderrc_asrw --distribution unstable --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.7miTsDVO/b1 --logfile b1/build.log golang-github-prometheus-exporter-toolkit_0.13.1-1.dsc' - SUDO_GID='110' - SUDO_UID='103' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://10.0.0.15:3142/' + 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.7miTsDVO/pbuilderrc_qe8I --distribution unstable --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.7miTsDVO/b2 --logfile b2/build.log golang-github-prometheus-exporter-toolkit_0.13.1-1.dsc' + SUDO_GID=114 + SUDO_UID=109 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://10.0.0.15:3142/ I: uname -a - Linux virt32z 6.1.0-27-armmp-lpae #1 SMP Debian 6.1.115-1 (2024-11-01) armv7l GNU/Linux + Linux i-capture-the-hostname 6.1.0-27-arm64 #1 SMP Debian 6.1.115-1 (2024-11-01) aarch64 GNU/Linux I: ls -l /bin lrwxrwxrwx 1 root root 7 Aug 4 21:30 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/29236/tmp/hooks/D02_print_environment finished +I: user script /srv/workspace/pbuilder/16966/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -206,7 +238,7 @@ Get: 74 http://deb.debian.org/debian unstable/main armhf golang-github-prometheus-procfs-dev all 0.14.0-1 [183 kB] Get: 75 http://deb.debian.org/debian unstable/main armhf golang-github-prometheus-client-golang-dev all 1.20.5-1 [188 kB] Get: 76 http://deb.debian.org/debian unstable/main armhf golang-github-mwitkow-go-conntrack-dev all 0.0~git20190716.2f06839-3 [14.6 kB] -Fetched 119 MB in 3s (46.6 MB/s) +Fetched 119 MB in 14s (8202 kB/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 ... 19689 files and directories currently installed.) @@ -525,7 +557,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/golang-github-prometheus-exporter-toolkit-0.13.1/ && 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-github-prometheus-exporter-toolkit_0.13.1-1_source.changes +I: user script /srv/workspace/pbuilder/16966/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/16966/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/golang-github-prometheus-exporter-toolkit-0.13.1/ && 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-github-prometheus-exporter-toolkit_0.13.1-1_source.changes dpkg-buildpackage: info: source package golang-github-prometheus-exporter-toolkit dpkg-buildpackage: info: source version 0.13.1-1 dpkg-buildpackage: info: source distribution unstable @@ -543,36 +579,34 @@ dh_autoreconf -O--buildsystem=golang -O--builddirectory=_build dh_auto_configure -O--buildsystem=golang -O--builddirectory=_build dh_auto_build -O--buildsystem=golang -O--builddirectory=_build - cd _build && go install -trimpath -v -p 3 github.com/prometheus/exporter-toolkit/web github.com/prometheus/exporter-toolkit/web/kingpinflag + cd _build && go install -trimpath -v -p 6 github.com/prometheus/exporter-toolkit/web github.com/prometheus/exporter-toolkit/web/kingpinflag internal/goarch internal/unsafeheader -internal/cpu +internal/godebugs internal/byteorder -internal/abi -internal/chacha8rand -internal/bytealg internal/coverage/rtcov -internal/godebugs -internal/goos +internal/cpu internal/goexperiment +internal/abi internal/profilerecord -internal/runtime/atomic +internal/goos internal/runtime/syscall -internal/stringslite +internal/chacha8rand runtime/internal/math runtime/internal/sys -internal/runtime/exithook internal/race +internal/bytealg sync/atomic +internal/runtime/atomic unicode -runtime unicode/utf8 +internal/stringslite container/list internal/asan internal/itoa internal/msan math/bits -math +internal/runtime/exithook crypto/internal/alias crypto/internal/boring/sig cmp @@ -584,480 +618,482 @@ log/internal google.golang.org/protobuf/internal/flags google.golang.org/protobuf/internal/set +math log/slog/internal +runtime crypto/subtle -sync -internal/reflectlite iter -slices +internal/reflectlite +sync +runtime/metrics internal/weak maps +slices internal/bisect internal/testlog internal/singleflight +google.golang.org/protobuf/internal/pragma +runtime/cgo +log/slog/internal/buffer errors sort internal/godebug -io internal/oserror +io +strconv syscall +crypto/internal/edwards25519/field bytes hash -strconv -crypto/cipher -crypto/internal/edwards25519/field -crypto -crypto/internal/boring -crypto/des -crypto/aes crypto/internal/nistec/fiat crypto/internal/randutil -reflect -time -context path -io/fs -crypto/internal/nistec -internal/filepathlite -internal/syscall/unix -internal/poll -crypto/ecdh -internal/syscall/execenv -internal/fmtsort -os +crypto +reflect math/rand +crypto/cipher +crypto/internal/boring +crypto/aes +crypto/des strings crypto/sha512 crypto/internal/edwards25519 crypto/hmac -encoding/binary +time +internal/syscall/unix +internal/syscall/execenv vendor/golang.org/x/crypto/hkdf -vendor/golang.org/x/crypto/chacha20 -vendor/golang.org/x/crypto/internal/poly1305 -fmt -vendor/golang.org/x/sys/cpu -vendor/golang.org/x/crypto/chacha20poly1305 -vendor/golang.org/x/crypto/sha3 crypto/md5 crypto/rc4 crypto/sha1 crypto/sha256 -encoding/base64 vendor/golang.org/x/net/dns/dnsmessage -encoding/hex -math/big -encoding/pem math/rand/v2 +bufio internal/concurrent unique -runtime/cgo +regexp/syntax net/netip -crypto/elliptic -crypto/internal/bigmod -crypto/internal/boring/bbig -encoding/asn1 -crypto/rand -crypto/ed25519 -crypto/internal/hpke -vendor/golang.org/x/crypto/cryptobyte -crypto/internal/mlkem768 -crypto/rsa -crypto/ecdsa -crypto/dsa -crypto/x509/pkix +hash/crc32 +vendor/golang.org/x/text/transform +context +io/fs +internal/fmtsort +crypto/internal/nistec +internal/poll +encoding/binary +internal/filepathlite +embed +regexp +github.com/jpillora/backoff +net/http/internal/ascii +github.com/beorn7/perks/quantile +hash/fnv +google.golang.org/protobuf/internal/editiondefaults +github.com/munnerz/goautoneg +golang.org/x/text/transform +html +vendor/golang.org/x/crypto/chacha20 +crypto/ecdh +vendor/golang.org/x/crypto/internal/poly1305 +encoding/base64 +os +vendor/golang.org/x/crypto/chacha20poly1305 +golang.org/x/sys/unix +github.com/cespare/xxhash +encoding/pem +golang.org/x/crypto/blowfish +google.golang.org/protobuf/internal/detrand +vendor/golang.org/x/sys/cpu path/filepath -net/url +fmt net -embed +io/ioutil +vendor/golang.org/x/crypto/sha3 +github.com/prometheus/procfs/internal/util +internal/lazyregexp +encoding/hex +net/url encoding/json -bufio +math/big github.com/go-logfmt/logfmt log -regexp/syntax golang.org/x/net/bpf golang.org/x/sync/errgroup -golang.org/x/sys/unix -regexp -github.com/go-kit/log -github.com/jpillora/backoff compress/flate -hash/crc32 -compress/gzip -vendor/golang.org/x/text/transform vendor/golang.org/x/text/unicode/bidi github.com/mdlayher/socket -vendor/golang.org/x/text/secure/bidirule +compress/gzip +github.com/go-kit/log vendor/golang.org/x/text/unicode/norm +vendor/golang.org/x/text/secure/bidirule vendor/golang.org/x/net/http2/hpack mime -vendor/golang.org/x/net/idna +crypto/elliptic +crypto/internal/bigmod +crypto/internal/boring/bbig +encoding/asn1 +crypto/rand +crypto/dsa +crypto/ed25519 +crypto/internal/hpke +crypto/internal/mlkem768 +crypto/rsa mime/quotedprintable net/http/internal -net/http/internal/ascii -github.com/beorn7/perks/quantile -github.com/cespare/xxhash -hash/fnv -google.golang.org/protobuf/internal/pragma -google.golang.org/protobuf/internal/detrand -crypto/x509 -github.com/mdlayher/vsock -net/textproto -vendor/golang.org/x/net/http/httpproxy -vendor/golang.org/x/net/http/httpguts -mime/multipart google.golang.org/protobuf/internal/errors +vendor/golang.org/x/crypto/cryptobyte +crypto/x509/pkix google.golang.org/protobuf/encoding/protowire -google.golang.org/protobuf/reflect/protoreflect -google.golang.org/protobuf/internal/editiondefaults go/token -io/ioutil google.golang.org/protobuf/internal/version -runtime/metrics +google.golang.org/protobuf/reflect/protoreflect +github.com/prometheus/procfs/internal/fs +runtime/debug +golang.org/x/net/internal/timeseries +text/template/parse +crypto/ecdsa +vendor/golang.org/x/net/idna +text/tabwriter +golang.org/x/text/unicode/bidi google.golang.org/protobuf/internal/descfmt google.golang.org/protobuf/internal/descopts -crypto/tls google.golang.org/protobuf/internal/strs google.golang.org/protobuf/internal/encoding/text google.golang.org/protobuf/internal/encoding/messageset google.golang.org/protobuf/internal/genid google.golang.org/protobuf/internal/order -google.golang.org/protobuf/internal/encoding/defval google.golang.org/protobuf/reflect/protoregistry google.golang.org/protobuf/runtime/protoiface -github.com/munnerz/goautoneg -github.com/prometheus/procfs/internal/fs -github.com/prometheus/procfs/internal/util +text/template +golang.org/x/text/secure/bidirule +golang.org/x/text/unicode/norm +golang.org/x/net/http2/hpack +google.golang.org/protobuf/internal/encoding/defval google.golang.org/protobuf/proto -github.com/prometheus/procfs +gopkg.in/yaml.v2 +log/slog google.golang.org/protobuf/internal/filedesc google.golang.org/protobuf/encoding/prototext -google.golang.org/protobuf/internal/encoding/tag google.golang.org/protobuf/encoding/protodelim -google.golang.org/protobuf/internal/impl -github.com/coreos/go-systemd/activation -net/http/httptrace -runtime/debug -net/http -golang.org/x/net/internal/timeseries -html -text/template/parse -text/template html/template -text/tabwriter -golang.org/x/text/transform -golang.org/x/text/unicode/bidi +golang.org/x/net/idna +crypto/x509 +github.com/mdlayher/vsock +net/textproto +vendor/golang.org/x/net/http/httpproxy +vendor/golang.org/x/net/http/httpguts +mime/multipart +github.com/prometheus/procfs +github.com/prometheus/common/promslog +golang.org/x/net/http/httpproxy +golang.org/x/net/http/httpguts +google.golang.org/protobuf/internal/encoding/tag +golang.org/x/crypto/bcrypt +github.com/alecthomas/units +go/scanner +google.golang.org/protobuf/internal/impl +go/doc/comment +go/ast +crypto/tls +go/doc +gopkg.in/alecthomas/kingpin.v2 google.golang.org/protobuf/internal/filetype google.golang.org/protobuf/runtime/protoimpl google.golang.org/protobuf/types/known/timestamppb -golang.org/x/text/secure/bidirule -golang.org/x/text/unicode/norm +net/http/httptrace +github.com/coreos/go-systemd/activation github.com/prometheus/client_model/go +net/http github.com/prometheus/common/model -golang.org/x/net/idna github.com/prometheus/client_golang/prometheus/internal -golang.org/x/net/http2/hpack -gopkg.in/yaml.v2 -golang.org/x/net/http/httpproxy expvar -github.com/prometheus/common/expfmt golang.org/x/net/trace -golang.org/x/net/http/httpguts -github.com/prometheus/client_golang/prometheus -golang.org/x/net/http2 +github.com/prometheus/common/expfmt golang.org/x/oauth2/internal +golang.org/x/net/http2 golang.org/x/oauth2 golang.org/x/oauth2/clientcredentials -log/slog/internal/buffer -log/slog +github.com/prometheus/client_golang/prometheus github.com/mwitkow/go-conntrack -github.com/prometheus/common/promslog -golang.org/x/crypto/blowfish -github.com/alecthomas/units -golang.org/x/crypto/bcrypt -go/scanner -go/doc/comment -go/ast -internal/lazyregexp github.com/prometheus/common/config github.com/prometheus/exporter-toolkit/web -go/doc -gopkg.in/alecthomas/kingpin.v2 github.com/prometheus/exporter-toolkit/web/kingpinflag dh_auto_test -O--buildsystem=golang -O--builddirectory=_build - cd _build && go test -vet=off -v -p 3 github.com/prometheus/exporter-toolkit/web github.com/prometheus/exporter-toolkit/web/kingpinflag + cd _build && go test -vet=off -v -p 6 github.com/prometheus/exporter-toolkit/web github.com/prometheus/exporter-toolkit/web/kingpinflag ? github.com/prometheus/exporter-toolkit/web/kingpinflag [no test files] === RUN TestCacheSize --- PASS: TestCacheSize (0.00s) === RUN TestBasicAuthCache -time=2024-11-12T06:50:01.750Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:01.751Z level=INFO msg="TLS is disabled." http2=false address=[::]:33241 ---- PASS: TestBasicAuthCache (1.12s) +time=2024-11-12T07:11:52.922Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:11:52.943Z level=INFO msg="TLS is disabled." http2=false address=[::]:36107 +--- PASS: TestBasicAuthCache (14.06s) === RUN TestBasicAuthWithFakepassword -time=2024-11-12T06:50:02.867Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:02.867Z level=INFO msg="TLS is disabled." http2=false address=[::]:33241 ---- PASS: TestBasicAuthWithFakepassword (0.22s) +time=2024-11-12T07:12:06.984Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:06.986Z level=INFO msg="TLS is disabled." http2=false address=[::]:36107 +--- PASS: TestBasicAuthWithFakepassword (2.00s) === RUN TestByPassBasicAuthVuln -time=2024-11-12T06:50:03.087Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:03.087Z level=INFO msg="TLS is disabled." http2=false address=[::]:33241 ---- PASS: TestByPassBasicAuthVuln (0.68s) +time=2024-11-12T07:12:08.983Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:08.984Z level=INFO msg="TLS is disabled." http2=false address=[::]:36107 +--- PASS: TestByPassBasicAuthVuln (8.63s) === RUN TestHTTPHeaders -time=2024-11-12T06:50:03.769Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:03.770Z level=INFO msg="TLS is disabled." http2=false address=[::]:33241 ---- PASS: TestHTTPHeaders (0.10s) +time=2024-11-12T07:12:17.616Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:17.627Z level=INFO msg="TLS is disabled." http2=false address=[::]:36107 +--- PASS: TestHTTPHeaders (0.12s) === RUN TestServerBehaviour121 === RUN TestServerBehaviour121/valid_tls_config_yml_and_tls_client_with_RequireAnyClientCert -time=2024-11-12T06:50:03.872Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:03.876Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 -2024/11/12 06:50:04 http: TLS handshake error from 127.0.0.1:40742: tls: client didn't provide a certificate +time=2024-11-12T07:12:17.737Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:17.780Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 +2024/11/12 07:12:19 http: TLS handshake error from 127.0.0.1:60768: tls: client didn't provide a certificate === RUN TestServerBehaviour121/valid_tls_config_yml_and_tls_client_with_RequireAndVerifyClientCert -time=2024-11-12T06:50:04.279Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:04.284Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 -2024/11/12 06:50:04 http: TLS handshake error from 127.0.0.1:40746: tls: client didn't provide a certificate +time=2024-11-12T07:12:19.360Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:19.399Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 +2024/11/12 07:12:21 http: TLS handshake error from 127.0.0.1:60770: tls: client didn't provide a certificate === RUN TestServerBehaviour121/valid_tls_config_yml_and_tls_client_with_RequireAndVerifyClientCert_(present_wrong_certificate) -time=2024-11-12T06:50:04.685Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:04.689Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 -2024/11/12 06:50:05 http: TLS handshake error from 127.0.0.1:40758: tls: failed to verify certificate: x509: certificate signed by unknown authority ---- PASS: TestServerBehaviour121 (1.27s) - --- PASS: TestServerBehaviour121/valid_tls_config_yml_and_tls_client_with_RequireAnyClientCert (0.41s) - --- PASS: TestServerBehaviour121/valid_tls_config_yml_and_tls_client_with_RequireAndVerifyClientCert (0.41s) - --- PASS: TestServerBehaviour121/valid_tls_config_yml_and_tls_client_with_RequireAndVerifyClientCert_(present_wrong_certificate) (0.46s) +time=2024-11-12T07:12:21.549Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:21.585Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 +2024/11/12 07:12:24 http: TLS handshake error from 127.0.0.1:40716: tls: failed to verify certificate: x509: certificate signed by unknown authority +--- PASS: TestServerBehaviour121 (6.59s) + --- PASS: TestServerBehaviour121/valid_tls_config_yml_and_tls_client_with_RequireAnyClientCert (1.62s) + --- PASS: TestServerBehaviour121/valid_tls_config_yml_and_tls_client_with_RequireAndVerifyClientCert (2.19s) + --- PASS: TestServerBehaviour121/valid_tls_config_yml_and_tls_client_with_RequireAndVerifyClientCert_(present_wrong_certificate) (2.78s) === RUN TestYAMLFiles === RUN TestYAMLFiles/run/path_to_config_yml_invalid -time=2024-11-12T06:50:05.146Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.328Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/path_to_config_yml_invalid === RUN TestYAMLFiles/run/empty_config_yml -time=2024-11-12T06:50:05.146Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:05.146Z level=INFO msg="TLS is disabled." http2=false address=[::]:33241 +time=2024-11-12T07:12:24.330Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:24.339Z level=INFO msg="TLS is disabled." http2=false address=[::]:36107 === RUN TestYAMLFiles/validate/empty_config_yml === RUN TestYAMLFiles/run/invalid_config_yml_(invalid_structure) -time=2024-11-12T06:50:05.398Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.596Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(invalid_structure) === RUN TestYAMLFiles/run/invalid_config_yml_(invalid_key) -time=2024-11-12T06:50:05.400Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.605Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(invalid_key) === RUN TestYAMLFiles/run/invalid_config_yml_(cert_path_empty) -time=2024-11-12T06:50:05.401Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.613Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(cert_path_empty) === RUN TestYAMLFiles/run/invalid_config_yml_(cert_empty) -time=2024-11-12T06:50:05.402Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.622Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(cert_empty) === RUN TestYAMLFiles/run/invalid_config_yml_(key_path_empty) -time=2024-11-12T06:50:05.404Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.630Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(key_path_empty) === RUN TestYAMLFiles/run/invalid_config_yml_(key_empty) -time=2024-11-12T06:50:05.405Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.638Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(key_empty) === RUN TestYAMLFiles/run/invalid_config_yml_(cert_path_and_key_path_empty) -time=2024-11-12T06:50:05.406Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.650Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(cert_path_and_key_path_empty) === RUN TestYAMLFiles/run/invalid_config_yml_(cert_path_invalid) -time=2024-11-12T06:50:05.408Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.662Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(cert_path_invalid) === RUN TestYAMLFiles/run/invalid_config_yml_(key_path_invalid) -time=2024-11-12T06:50:05.409Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.677Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(key_path_invalid) === RUN TestYAMLFiles/run/invalid_config_yml_(cert_path_and_key_path_invalid) -time=2024-11-12T06:50:05.410Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.706Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(cert_path_and_key_path_invalid) === RUN TestYAMLFiles/run/invalid_config_yml_(invalid_ClientAuth) -time=2024-11-12T06:50:05.411Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.728Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(invalid_ClientAuth) === RUN TestYAMLFiles/run/invalid_config_yml_(invalid_ClientCAs_filepath) -time=2024-11-12T06:50:05.419Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.802Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(invalid_ClientCAs_filepath) === RUN TestYAMLFiles/run/invalid_config_yml_(invalid_user_list) -time=2024-11-12T06:50:05.426Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.878Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(invalid_user_list) === RUN TestYAMLFiles/run/invalid_config_yml_(bad_cipher) -time=2024-11-12T06:50:05.427Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.890Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(bad_cipher) === RUN TestYAMLFiles/run/invalid_config_yml_(bad_curves) -time=2024-11-12T06:50:05.429Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.898Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(bad_curves) === RUN TestYAMLFiles/run/invalid_config_yml_(bad_TLS_version) -time=2024-11-12T06:50:05.430Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:24.905Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestYAMLFiles/validate/invalid_config_yml_(bad_TLS_version) ---- PASS: TestYAMLFiles (0.29s) +--- PASS: TestYAMLFiles (0.58s) --- PASS: TestYAMLFiles/run/path_to_config_yml_invalid (0.00s) --- PASS: TestYAMLFiles/validate/path_to_config_yml_invalid (0.00s) - --- PASS: TestYAMLFiles/run/empty_config_yml (0.25s) + --- PASS: TestYAMLFiles/run/empty_config_yml (0.26s) --- PASS: TestYAMLFiles/validate/empty_config_yml (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(invalid_structure) (0.00s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(invalid_structure) (0.01s) --- PASS: TestYAMLFiles/validate/invalid_config_yml_(invalid_structure) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(invalid_key) (0.00s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(invalid_key) (0.01s) --- PASS: TestYAMLFiles/validate/invalid_config_yml_(invalid_key) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(cert_path_empty) (0.00s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(cert_path_empty) (0.01s) --- PASS: TestYAMLFiles/validate/invalid_config_yml_(cert_path_empty) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(cert_empty) (0.00s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(cert_empty) (0.01s) --- PASS: TestYAMLFiles/validate/invalid_config_yml_(cert_empty) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(key_path_empty) (0.00s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(key_path_empty) (0.01s) --- PASS: TestYAMLFiles/validate/invalid_config_yml_(key_path_empty) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(key_empty) (0.00s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(key_empty) (0.01s) --- PASS: TestYAMLFiles/validate/invalid_config_yml_(key_empty) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(cert_path_and_key_path_empty) (0.00s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(cert_path_and_key_path_empty) (0.01s) --- PASS: TestYAMLFiles/validate/invalid_config_yml_(cert_path_and_key_path_empty) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(cert_path_invalid) (0.00s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(cert_path_invalid) (0.01s) --- PASS: TestYAMLFiles/validate/invalid_config_yml_(cert_path_invalid) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(key_path_invalid) (0.00s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(key_path_invalid) (0.03s) --- PASS: TestYAMLFiles/validate/invalid_config_yml_(key_path_invalid) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(cert_path_and_key_path_invalid) (0.00s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(cert_path_and_key_path_invalid) (0.02s) --- PASS: TestYAMLFiles/validate/invalid_config_yml_(cert_path_and_key_path_invalid) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(invalid_ClientAuth) (0.00s) - --- PASS: TestYAMLFiles/validate/invalid_config_yml_(invalid_ClientAuth) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(invalid_ClientCAs_filepath) (0.00s) - --- PASS: TestYAMLFiles/validate/invalid_config_yml_(invalid_ClientCAs_filepath) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(invalid_user_list) (0.00s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(invalid_ClientAuth) (0.03s) + --- PASS: TestYAMLFiles/validate/invalid_config_yml_(invalid_ClientAuth) (0.04s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(invalid_ClientCAs_filepath) (0.05s) + --- PASS: TestYAMLFiles/validate/invalid_config_yml_(invalid_ClientCAs_filepath) (0.03s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(invalid_user_list) (0.01s) --- PASS: TestYAMLFiles/validate/invalid_config_yml_(invalid_user_list) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(bad_cipher) (0.00s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(bad_cipher) (0.01s) --- PASS: TestYAMLFiles/validate/invalid_config_yml_(bad_cipher) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(bad_curves) (0.00s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(bad_curves) (0.01s) --- PASS: TestYAMLFiles/validate/invalid_config_yml_(bad_curves) (0.00s) - --- PASS: TestYAMLFiles/run/invalid_config_yml_(bad_TLS_version) (0.00s) + --- PASS: TestYAMLFiles/run/invalid_config_yml_(bad_TLS_version) (0.01s) --- PASS: TestYAMLFiles/validate/invalid_config_yml_(bad_TLS_version) (0.00s) === RUN TestServerBehaviour === RUN TestServerBehaviour/empty_string_YAMLConfigPath_and_default_client -time=2024-11-12T06:50:05.431Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:05.431Z level=INFO msg="TLS is disabled." http2=false address=[::]:33241 +time=2024-11-12T07:12:24.914Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:24.914Z level=INFO msg="TLS is disabled." http2=false address=[::]:36107 === RUN TestServerBehaviour/empty_string_YAMLConfigPath_and_TLS_client -time=2024-11-12T06:50:05.683Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:05.683Z level=INFO msg="TLS is disabled." http2=false address=[::]:33241 +time=2024-11-12T07:12:25.175Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:25.175Z level=INFO msg="TLS is disabled." http2=false address=[::]:36107 === RUN TestServerBehaviour/valid_tls_config_yml_and_default_client -time=2024-11-12T06:50:05.936Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:05.941Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 -2024/11/12 06:50:06 http: TLS handshake error from 127.0.0.1:40794: client sent an HTTP request to an HTTPS server +time=2024-11-12T07:12:25.461Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:25.498Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 +2024/11/12 07:12:25 http: TLS handshake error from 127.0.0.1:40878: client sent an HTTP request to an HTTPS server === RUN TestServerBehaviour/valid_tls_config_yml_and_tls_client -time=2024-11-12T06:50:06.189Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:06.192Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:12:25.719Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:25.750Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestServerBehaviour/valid_tls_config_yml_(cert_and_key_inline)_and_tls_client -time=2024-11-12T06:50:06.601Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:06.605Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:12:28.295Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:28.332Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestServerBehaviour/valid_tls_config_yml_with_TLS_1.1_client -time=2024-11-12T06:50:07.007Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:07.009Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 -2024/11/12 06:50:07 http: TLS handshake error from 127.0.0.1:40818: EOF +time=2024-11-12T07:12:31.015Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:31.059Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 +2024/11/12 07:12:31 http: TLS handshake error from 127.0.0.1:60744: EOF === RUN TestServerBehaviour/valid_tls_config_yml_with_all_ciphers -time=2024-11-12T06:50:07.258Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:07.263Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:12:31.280Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:31.318Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestServerBehaviour/valid_tls_config_yml_with_some_ciphers -time=2024-11-12T06:50:07.664Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:07.668Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:12:33.336Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:33.373Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestServerBehaviour/valid_tls_config_yml_with_no_common_cipher -time=2024-11-12T06:50:08.070Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:08.073Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 -2024/11/12 06:50:08 http: TLS handshake error from 127.0.0.1:40848: tls: no cipher suite supported by both client and server +time=2024-11-12T07:12:35.834Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:35.867Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 +2024/11/12 07:12:36 http: TLS handshake error from 127.0.0.1:60770: tls: no cipher suite supported by both client and server === RUN TestServerBehaviour/valid_tls_config_yml_with_multiple_client_ciphers -time=2024-11-12T06:50:08.328Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:08.331Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:12:36.159Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:36.188Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestServerBehaviour/valid_tls_config_yml_with_multiple_client_ciphers,_client_chooses_cipher -time=2024-11-12T06:50:08.733Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:08.736Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:12:38.432Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:38.465Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestServerBehaviour/valid_tls_config_yml_with_all_curves -time=2024-11-12T06:50:09.138Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:09.141Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:12:41.124Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:41.156Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestServerBehaviour/valid_tls_config_yml_with_some_curves -time=2024-11-12T06:50:09.545Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:09.548Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:12:43.831Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:43.887Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestServerBehaviour/valid_tls_config_yml_with_no_common_curves -time=2024-11-12T06:50:10.465Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:10.468Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 -2024/11/12 06:50:10 http: TLS handshake error from 127.0.0.1:57674: tls: no ECDHE curve supported by both client and server +time=2024-11-12T07:12:51.298Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:51.317Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 +2024/11/12 07:12:51 http: TLS handshake error from 127.0.0.1:33168: tls: no ECDHE curve supported by both client and server === RUN TestServerBehaviour/valid_tls_config_yml_with_non-http2_ciphers -time=2024-11-12T06:50:10.724Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:10.732Z level=INFO msg="TLS is enabled." http2=false address=[::]:33241 +time=2024-11-12T07:12:51.653Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:51.685Z level=INFO msg="TLS is enabled." http2=false address=[::]:36107 === RUN TestServerBehaviour/valid_tls_config_yml_with_non-http2_ciphers_but_http2_enabled -time=2024-11-12T06:50:11.138Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:11.144Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:12:53.776Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:53.808Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestServerBehaviour/valid_headers_config -time=2024-11-12T06:50:11.144Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:11.145Z level=INFO msg="TLS is disabled." http2=false address=[::]:33241 +time=2024-11-12T07:12:53.810Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:53.819Z level=INFO msg="TLS is disabled." http2=false address=[::]:36107 === RUN TestServerBehaviour/invalid_X-Content-Type-Options_headers_config -time=2024-11-12T06:50:11.396Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:54.068Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestServerBehaviour/invalid_X-Frame-Options_headers_config -time=2024-11-12T06:50:11.398Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:54.072Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestServerBehaviour/HTTP_header_that_can_not_be_overridden -time=2024-11-12T06:50:11.398Z level=INFO msg="Listening on" address=[::]:33241 +time=2024-11-12T07:12:54.080Z level=INFO msg="Listening on" address=[::]:36107 === RUN TestServerBehaviour/valid_tls_config_yml_and_tls_client_with_RequireAnyClientCert_(present_certificate) -time=2024-11-12T06:50:11.399Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:11.402Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 -2024/11/12 06:50:11 http: TLS handshake error from 127.0.0.1:57716: client sent an HTTP request to an HTTPS server -2024/11/12 06:50:11 http: TLS handshake error from 127.0.0.1:57730: client sent an HTTP request to an HTTPS server -2024/11/12 06:50:11 http: TLS handshake error from 127.0.0.1:57746: client sent an HTTP request to an HTTPS server +time=2024-11-12T07:12:54.083Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:54.117Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 +2024/11/12 07:12:54 http: TLS handshake error from 127.0.0.1:33198: client sent an HTTP request to an HTTPS server +2024/11/12 07:12:54 http: TLS handshake error from 127.0.0.1:33200: client sent an HTTP request to an HTTPS server +2024/11/12 07:12:54 http: TLS handshake error from 127.0.0.1:33204: client sent an HTTP request to an HTTPS server === RUN TestServerBehaviour/valid_tls_config_yml_(cert_from_file,_key_inline)_and_tls_client_with_RequireAnyClientCert_(present_certificate) -time=2024-11-12T06:50:11.834Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:11.836Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:12:56.341Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:56.358Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestServerBehaviour/valid_tls_config_yml_and_tls_client_with_RequireAndVerifyClientCert_(present_certificate) -time=2024-11-12T06:50:12.266Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:12.269Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:12:58.654Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:12:58.687Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestServerBehaviour/valid_tls_config_yml_and_tls_client_with_VerifyPeerCertificate_(present_good_SAN_DNS_entry) -time=2024-11-12T06:50:12.694Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:12.697Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:13:01.222Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:13:01.254Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestServerBehaviour/valid_tls_config_yml_and_tls_client_with_VerifyPeerCertificate_(present_invalid_SAN_DNS_entries) -time=2024-11-12T06:50:13.120Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:13.124Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 -2024/11/12 06:50:13 http: TLS handshake error from 127.0.0.1:57800: could not find allowed SANs in client cert, found: [bad] ---- PASS: TestServerBehaviour (8.10s) - --- PASS: TestServerBehaviour/empty_string_YAMLConfigPath_and_default_client (0.25s) - --- PASS: TestServerBehaviour/empty_string_YAMLConfigPath_and_TLS_client (0.25s) +time=2024-11-12T07:13:03.685Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:13:03.717Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 +2024/11/12 07:13:06 http: TLS handshake error from 127.0.0.1:45498: could not find allowed SANs in client cert, found: [bad] +--- PASS: TestServerBehaviour (41.13s) + --- PASS: TestServerBehaviour/empty_string_YAMLConfigPath_and_default_client (0.26s) + --- PASS: TestServerBehaviour/empty_string_YAMLConfigPath_and_TLS_client (0.29s) --- PASS: TestServerBehaviour/valid_tls_config_yml_and_default_client (0.25s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_and_tls_client (0.41s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_(cert_and_key_inline)_and_tls_client (0.41s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_with_TLS_1.1_client (0.25s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_with_all_ciphers (0.41s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_with_some_ciphers (0.41s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_with_no_common_cipher (0.26s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_with_multiple_client_ciphers (0.40s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_with_multiple_client_ciphers,_client_chooses_cipher (0.40s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_with_all_curves (0.41s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_with_some_curves (0.92s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_with_no_common_curves (0.26s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_with_non-http2_ciphers (0.41s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_with_non-http2_ciphers_but_http2_enabled (0.01s) - --- PASS: TestServerBehaviour/valid_headers_config (0.25s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_and_tls_client (2.57s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_(cert_and_key_inline)_and_tls_client (2.72s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_with_TLS_1.1_client (0.26s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_with_all_ciphers (2.06s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_with_some_ciphers (2.50s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_with_no_common_cipher (0.33s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_with_multiple_client_ciphers (2.27s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_with_multiple_client_ciphers,_client_chooses_cipher (2.69s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_with_all_curves (2.68s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_with_some_curves (7.47s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_with_no_common_curves (0.35s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_with_non-http2_ciphers (2.12s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_with_non-http2_ciphers_but_http2_enabled (0.03s) + --- PASS: TestServerBehaviour/valid_headers_config (0.26s) --- PASS: TestServerBehaviour/invalid_X-Content-Type-Options_headers_config (0.00s) - --- PASS: TestServerBehaviour/invalid_X-Frame-Options_headers_config (0.00s) + --- PASS: TestServerBehaviour/invalid_X-Frame-Options_headers_config (0.01s) --- PASS: TestServerBehaviour/HTTP_header_that_can_not_be_overridden (0.00s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_and_tls_client_with_RequireAnyClientCert_(present_certificate) (0.43s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_(cert_from_file,_key_inline)_and_tls_client_with_RequireAnyClientCert_(present_certificate) (0.43s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_and_tls_client_with_RequireAndVerifyClientCert_(present_certificate) (0.43s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_and_tls_client_with_VerifyPeerCertificate_(present_good_SAN_DNS_entry) (0.43s) - --- PASS: TestServerBehaviour/valid_tls_config_yml_and_tls_client_with_VerifyPeerCertificate_(present_invalid_SAN_DNS_entries) (0.41s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_and_tls_client_with_RequireAnyClientCert_(present_certificate) (2.26s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_(cert_from_file,_key_inline)_and_tls_client_with_RequireAnyClientCert_(present_certificate) (2.31s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_and_tls_client_with_RequireAndVerifyClientCert_(present_certificate) (2.57s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_and_tls_client_with_VerifyPeerCertificate_(present_good_SAN_DNS_entry) (2.46s) + --- PASS: TestServerBehaviour/valid_tls_config_yml_and_tls_client_with_VerifyPeerCertificate_(present_invalid_SAN_DNS_entries) (2.36s) === RUN TestConfigReloading -time=2024-11-12T06:50:13.531Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:13.535Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 -2024/11/12 06:50:13 http: TLS handshake error from 127.0.0.1:57802: tls: client didn't provide a certificate ---- PASS: TestConfigReloading (0.82s) +time=2024-11-12T07:13:06.047Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:13:06.068Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 +2024/11/12 07:13:08 http: TLS handshake error from 127.0.0.1:45506: tls: client didn't provide a certificate +--- PASS: TestConfigReloading (5.08s) === RUN TestUsers === RUN TestUsers/without_basic_auth -time=2024-11-12T06:50:14.348Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:14.348Z level=INFO msg="TLS is disabled." http2=false address=[::]:33241 +time=2024-11-12T07:13:11.123Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:13:11.124Z level=INFO msg="TLS is disabled." http2=false address=[::]:36107 === RUN TestUsers/with_correct_basic_auth -time=2024-11-12T06:50:14.600Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:14.600Z level=INFO msg="TLS is disabled." http2=false address=[::]:33241 +time=2024-11-12T07:13:11.383Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:13:11.384Z level=INFO msg="TLS is disabled." http2=false address=[::]:36107 === RUN TestUsers/without_basic_auth_and_TLS -time=2024-11-12T06:50:14.973Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:14.978Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:13:13.306Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:13:13.351Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestUsers/with_correct_basic_auth_and_TLS -time=2024-11-12T06:50:15.382Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:15.385Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:13:15.923Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:13:15.950Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestUsers/with_another_correct_basic_auth_and_TLS -time=2024-11-12T06:50:16.054Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:16.057Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:13:19.105Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:13:19.138Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestUsers/with_bad_password_and_TLS -time=2024-11-12T06:50:16.652Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:16.655Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 +time=2024-11-12T07:13:23.128Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:13:23.173Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 === RUN TestUsers/with_bad_username_and_TLS -time=2024-11-12T06:50:17.179Z level=INFO msg="Listening on" address=[::]:33241 -time=2024-11-12T06:50:17.182Z level=INFO msg="TLS is enabled." http2=true address=[::]:33241 ---- PASS: TestUsers (3.35s) +time=2024-11-12T07:13:27.512Z level=INFO msg="Listening on" address=[::]:36107 +time=2024-11-12T07:13:27.557Z level=INFO msg="TLS is enabled." http2=true address=[::]:36107 +--- PASS: TestUsers (20.55s) --- PASS: TestUsers/without_basic_auth (0.25s) - --- PASS: TestUsers/with_correct_basic_auth (0.37s) - --- PASS: TestUsers/without_basic_auth_and_TLS (0.41s) - --- PASS: TestUsers/with_correct_basic_auth_and_TLS (0.67s) - --- PASS: TestUsers/with_another_correct_basic_auth_and_TLS (0.60s) - --- PASS: TestUsers/with_bad_password_and_TLS (0.53s) - --- PASS: TestUsers/with_bad_username_and_TLS (0.52s) + --- PASS: TestUsers/with_correct_basic_auth (1.92s) + --- PASS: TestUsers/without_basic_auth_and_TLS (2.61s) + --- PASS: TestUsers/with_correct_basic_auth_and_TLS (3.18s) + --- PASS: TestUsers/with_another_correct_basic_auth_and_TLS (4.02s) + --- PASS: TestUsers/with_bad_password_and_TLS (4.38s) + --- PASS: TestUsers/with_bad_username_and_TLS (4.16s) PASS -ok github.com/prometheus/exporter-toolkit/web 15.989s +ok github.com/prometheus/exporter-toolkit/web 99.014s create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=golang -O--builddirectory=_build dh_prep -O--buildsystem=golang -O--builddirectory=_build @@ -1084,12 +1120,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/16966/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/16966/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/29236 and its subdirectories -I: Current time: Mon Nov 11 18:50:29 -12 2024 -I: pbuilder-time-stamp: 1731394229 +I: removing directory /srv/workspace/pbuilder/16966 and its subdirectories +I: Current time: Tue Nov 12 21:15:01 +14 2024 +I: pbuilder-time-stamp: 1731395701