Diff of the two buildlogs: -- --- b1/build.log 2024-06-17 19:33:45.517489868 +0000 +++ b2/build.log 2024-06-17 19:35:59.139810445 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sun Jul 20 13:54:45 -12 2025 -I: pbuilder-time-stamp: 1753062885 +I: Current time: Mon Jul 21 15:56:48 +14 2025 +I: pbuilder-time-stamp: 1753063008 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/experimental-reproducible-base.tgz] I: copying local configuration @@ -31,52 +31,84 @@ dpkg-source: info: applying avoid-newer-grpc-in-tests.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/2328772/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/1266493/tmp/hooks/D01_modify_environment starting +debug: Running on codethink01-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 Jul 21 01:56 /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/1266493/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/1266493/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='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]="21" [3]="1" [4]="release" [5]="aarch64-unknown-linux-gnu") + BASH_VERSION='5.2.21(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=arm64 + DEBIAN_FRONTEND=noninteractive DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=12 ' - DISTRIBUTION='experimental' - HOME='/root' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION=experimental + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - INVOCATION_ID='cd0bf8e8675643eda7cfaf89de9e81df' - 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='2328772' - PS1='# ' - PS2='> ' + INVOCATION_ID=283093c9e62b404b98d37ffea907fa24 + 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=1266493 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.ZO3W2vC8/pbuilderrc_uI0n --distribution experimental --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/experimental-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.ZO3W2vC8/b1 --logfile b1/build.log notary_0.7.0+git20240416.9d2b3b3+ds1-1.dsc' - SUDO_GID='109' - SUDO_UID='104' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://192.168.101.4: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.ZO3W2vC8/pbuilderrc_XFtc --distribution experimental --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/experimental-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.ZO3W2vC8/b2 --logfile b2/build.log notary_0.7.0+git20240416.9d2b3b3+ds1-1.dsc' + SUDO_GID=109 + SUDO_UID=104 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://192.168.101.4:3128 I: uname -a - Linux codethink03-arm64 6.1.0-21-cloud-arm64 #1 SMP Debian 6.1.90-1 (2024-05-03) aarch64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-21-cloud-arm64 #1 SMP Debian 6.1.90-1 (2024-05-03) aarch64 GNU/Linux I: ls -l /bin lrwxrwxrwx 1 root root 7 Jun 5 2024 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/2328772/tmp/hooks/D02_print_environment finished +I: user script /srv/workspace/pbuilder/1266493/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -356,7 +388,7 @@ Get: 186 http://deb.debian.org/debian unstable/main arm64 protobuf-compiler arm64 3.21.12-8.2 [84.5 kB] Get: 187 http://deb.debian.org/debian unstable/main arm64 protoc-gen-go-1-5 arm64 1.5.4-1 [1235 kB] Get: 188 http://deb.debian.org/debian unstable/main arm64 protoc-gen-go-grpc arm64 1.38.0+really1.33.3-1+b3 [1595 kB] -Fetched 106 MB in 2s (68.8 MB/s) +Fetched 106 MB in 1s (150 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package golang-golang-x-sys-dev. (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 ... 19700 files and directories currently installed.) @@ -1123,7 +1155,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/notary-0.7.0+git20240416.9d2b3b3+ds1/ && 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 > ../notary_0.7.0+git20240416.9d2b3b3+ds1-1_source.changes +I: user script /srv/workspace/pbuilder/1266493/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for experimental +I: user script /srv/workspace/pbuilder/1266493/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/notary-0.7.0+git20240416.9d2b3b3+ds1/ && 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 > ../notary_0.7.0+git20240416.9d2b3b3+ds1-1_source.changes dpkg-buildpackage: info: source package notary dpkg-buildpackage: info: source version 0.7.0+git20240416.9d2b3b3+ds1-1 dpkg-buildpackage: info: source distribution experimental @@ -1408,37 +1444,37 @@ src/github.com/theupdateframework/notary/utils/http_test.go src/github.com/theupdateframework/notary/version/version.go cd _build && go install -trimpath -v -p 12 -tags pkcs11 github.com/theupdateframework/notary github.com/theupdateframework/notary/client github.com/theupdateframework/notary/client/changelist github.com/theupdateframework/notary/cmd/escrow github.com/theupdateframework/notary/cmd/notary github.com/theupdateframework/notary/cmd/notary-server github.com/theupdateframework/notary/cmd/notary-signer github.com/theupdateframework/notary/cryptoservice github.com/theupdateframework/notary/passphrase github.com/theupdateframework/notary/proto github.com/theupdateframework/notary/server github.com/theupdateframework/notary/server/errors github.com/theupdateframework/notary/server/handlers github.com/theupdateframework/notary/server/snapshot github.com/theupdateframework/notary/server/storage github.com/theupdateframework/notary/server/timestamp github.com/theupdateframework/notary/signer github.com/theupdateframework/notary/signer/api github.com/theupdateframework/notary/signer/client github.com/theupdateframework/notary/signer/keydbstore github.com/theupdateframework/notary/storage github.com/theupdateframework/notary/storage/rethinkdb github.com/theupdateframework/notary/trustmanager github.com/theupdateframework/notary/trustmanager/remoteks github.com/theupdateframework/notary/trustmanager/yubikey github.com/theupdateframework/notary/trustpinning github.com/theupdateframework/notary/tuf github.com/theupdateframework/notary/tuf/data github.com/theupdateframework/notary/tuf/signed github.com/theupdateframework/notary/tuf/testutils github.com/theupdateframework/notary/tuf/testutils/interfaces github.com/theupdateframework/notary/tuf/testutils/keys github.com/theupdateframework/notary/tuf/utils github.com/theupdateframework/notary/tuf/validation github.com/theupdateframework/notary/utils github.com/theupdateframework/notary/version -math/bits -internal/race +internal/unsafeheader +internal/goarch +internal/itoa internal/godebugs -internal/goos +internal/coverage/rtcov internal/goexperiment -sync/atomic +internal/goos +unicode/utf8 +internal/race runtime/internal/syscall -internal/itoa -cmp +math/bits +runtime/internal/math internal/cpu +sync/atomic +runtime/internal/sys unicode +cmp encoding -internal/goarch -math -unicode/utf8 unicode/utf16 -internal/coverage/rtcov -slices internal/abi -internal/unsafeheader internal/chacha8rand -runtime/internal/math -runtime/internal/sys log/internal crypto/internal/alias +math +slices crypto/subtle crypto/internal/boring/sig vendor/golang.org/x/crypto/cryptobyte/asn1 internal/nettrace -vendor/golang.org/x/crypto/internal/alias container/list +vendor/golang.org/x/crypto/internal/alias github.com/pelletier/go-toml/v2/internal/characters google.golang.org/protobuf/internal/flags google.golang.org/protobuf/internal/set @@ -1449,42 +1485,42 @@ github.com/lib/pq/oid runtime internal/reflectlite -runtime/metrics sync +runtime/metrics internal/testlog internal/singleflight -internal/bisect -google.golang.org/grpc/internal/buffer github.com/spf13/viper/internal/encoding -google.golang.org/grpc/internal/grpcsync google.golang.org/protobuf/internal/pragma +google.golang.org/grpc/internal/buffer +google.golang.org/grpc/internal/grpcsync +internal/bisect runtime/cgo +internal/godebug errors sort -internal/godebug -io -github.com/hashicorp/hcl/hcl/strconv +internal/oserror internal/safefilepath -crypto/internal/nistec/fiat -vendor/golang.org/x/net/dns/dnsmessage strconv -internal/oserror -path -syscall +io hash +vendor/golang.org/x/net/dns/dnsmessage math/rand +github.com/hashicorp/hcl/hcl/strconv +path internal/intern -crypto -crypto/rc4 -reflect -strings +crypto/internal/nistec/fiat crypto/internal/randutil -bytes -net/netip -hash/crc32 text/tabwriter +hash/crc32 hash/fnv +strings +syscall +bytes github.com/beorn7/perks/quantile +crypto +reflect +net/netip +crypto/rc4 golang.org/x/crypto/blowfish encoding/base32 vendor/golang.org/x/text/transform @@ -1493,81 +1529,81 @@ bufio regexp/syntax net/http/internal/ascii -github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg html -golang.org/x/text/runes google.golang.org/grpc/encoding +github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg net/http/internal/testcert -time -internal/syscall/execenv -internal/syscall/unix +golang.org/x/text/runes regexp +internal/syscall/unix +internal/syscall/execenv +time github.com/jinzhu/inflection -context -io/fs +internal/fmtsort +encoding/binary internal/poll +context +github.com/bugsnag/bugsnag-go/headers +google.golang.org/grpc/keepalive google.golang.org/grpc/backoff +io/fs google.golang.org/grpc/internal/grpcrand -google.golang.org/grpc/keepalive -github.com/bugsnag/bugsnag-go/headers google.golang.org/grpc/internal/backoff +crypto/md5 +github.com/cespare/xxhash +encoding/base64 +crypto/cipher +crypto/internal/edwards25519/field +vendor/golang.org/x/crypto/internal/poly1305 +golang.org/x/sys/unix embed github.com/spf13/afero/internal/common golang.org/x/net/context +encoding/pem google.golang.org/grpc/tap gopkg.in/rethinkdb/rethinkdb-go.v6/vendor/gopkg.in/cenkalti/backoff.v2 +github.com/dvsekhvalnov/jose2go/base64url +crypto/internal/nistec google.golang.org/protobuf/internal/editiondefaults -encoding/binary -internal/fmtsort +github.com/dvsekhvalnov/jose2go/compact os -crypto/md5 -encoding/base64 -golang.org/x/sys/unix -crypto/cipher -crypto/internal/edwards25519/field -crypto/internal/nistec -vendor/golang.org/x/crypto/internal/poly1305 -github.com/cespare/xxhash +crypto/internal/edwards25519 crypto/internal/boring crypto/des vendor/golang.org/x/crypto/chacha20 +crypto/aes crypto/sha512 crypto/sha256 crypto/sha1 -encoding/pem -crypto/aes crypto/hmac -github.com/dvsekhvalnov/jose2go/base64url -crypto/internal/edwards25519 -github.com/dvsekhvalnov/jose2go/compact golang.org/x/crypto/pbkdf2 vendor/golang.org/x/crypto/hkdf vendor/golang.org/x/crypto/chacha20poly1305 +crypto/ecdh +fmt github.com/theupdateframework/notary -io/ioutil -path/filepath -google.golang.org/protobuf/internal/detrand google.golang.org/grpc/internal/envconfig -fmt -net github.com/bugsnag/bugsnag-go/device +path/filepath os/signal github.com/prometheus/procfs/internal/util +io/ioutil +google.golang.org/protobuf/internal/detrand +net internal/lazyregexp internal/sysinfo github.com/spf13/afero/mem os/exec -crypto/ecdh github.com/bugsnag/panicwrap encoding/json github.com/docker/go/canonical/json log -mime math/big -mime/quotedprintable -compress/flate encoding/hex net/url +mime +mime/quotedprintable +compress/flate vendor/golang.org/x/text/unicode/norm vendor/golang.org/x/net/http2/hpack net/http/internal @@ -1576,167 +1612,167 @@ flag encoding/csv golang.org/x/text/unicode/norm -text/template/parse compress/gzip -github.com/theupdateframework/notary/tuf/validation +text/template/parse github.com/spf13/jwalterweatherman github.com/subosito/gotenv +vendor/golang.org/x/text/secure/bidirule github.com/hashicorp/hcl/hcl/token gopkg.in/ini.v1 +github.com/fsnotify/fsnotify +github.com/pelletier/go-toml/v2/internal/danger github.com/hashicorp/hcl/hcl/ast github.com/hashicorp/hcl/hcl/scanner github.com/hashicorp/hcl/json/token -vendor/golang.org/x/text/secure/bidirule -github.com/spf13/viper/internal/encoding/json -github.com/hashicorp/hcl/json/scanner vendor/golang.org/x/net/idna -github.com/pelletier/go-toml/v2/internal/danger -gopkg.in/yaml.v3 -github.com/hashicorp/hcl/hcl/parser -github.com/hashicorp/hcl/json/parser github.com/pelletier/go-toml/v2/unstable +gopkg.in/yaml.v3 github.com/sirupsen/logrus -github.com/fsnotify/fsnotify -text/template +github.com/theupdateframework/notary/tuf/validation +github.com/hashicorp/hcl/hcl/parser +github.com/hashicorp/hcl/json/scanner github.com/hashicorp/hcl/hcl/printer -github.com/hashicorp/hcl +crypto/rand +crypto/elliptic +crypto/internal/bigmod +crypto/internal/boring/bbig +encoding/asn1 +crypto/dsa +text/template +github.com/docker/distribution/uuid +crypto/ed25519 +github.com/hashicorp/hcl/json/parser +github.com/spf13/viper/internal/encoding/json github.com/pelletier/go-toml/v2/internal/tracker google.golang.org/protobuf/internal/errors -github.com/spf13/viper/internal/encoding/hcl +crypto/rsa go/token -google.golang.org/protobuf/internal/version +golang.org/x/crypto/ed25519 +github.com/pelletier/go-toml/v2 google.golang.org/protobuf/encoding/protowire +google.golang.org/protobuf/internal/version +github.com/hashicorp/hcl golang.org/x/net/internal/timeseries -github.com/pelletier/go-toml/v2 +google.golang.org/protobuf/reflect/protoreflect google.golang.org/grpc/internal/grpclog google.golang.org/grpc/attributes -google.golang.org/grpc/grpclog -google.golang.org/protobuf/reflect/protoreflect google.golang.org/grpc/metadata +vendor/golang.org/x/crypto/cryptobyte +crypto/x509/pkix +google.golang.org/grpc/grpclog google.golang.org/grpc/codes +github.com/spf13/viper/internal/encoding/hcl +google.golang.org/grpc/internal/balancerload golang.org/x/text/unicode/bidi golang.org/x/net/http2/hpack -google.golang.org/grpc/internal/balancerload github.com/pkg/errors -crypto/rand -crypto/elliptic -crypto/internal/bigmod -github.com/docker/distribution/uuid -crypto/internal/boring/bbig -encoding/asn1 -crypto/dsa -crypto/ed25519 -crypto/rsa +database/sql/driver google.golang.org/grpc/connectivity -github.com/bugsnag/bugsnag-go/errors -golang.org/x/text/secure/bidirule -html/template -github.com/spf13/viper/internal/encoding/toml google.golang.org/grpc/internal -golang.org/x/crypto/ed25519 -database/sql/driver -github.com/opencontainers/go-digest -golang.org/x/net/idna -github.com/opencontainers/image-spec/specs-go -github.com/prometheus/procfs/internal/fs -runtime/debug -github.com/docker/distribution/digestset -github.com/opencontainers/image-spec/specs-go/v1 google.golang.org/protobuf/internal/descfmt +crypto/ecdsa google.golang.org/protobuf/internal/descopts -vendor/golang.org/x/crypto/cryptobyte -crypto/x509/pkix google.golang.org/protobuf/internal/strs +html/template google.golang.org/protobuf/internal/encoding/messageset google.golang.org/protobuf/internal/genid google.golang.org/protobuf/internal/order google.golang.org/protobuf/runtime/protoiface +github.com/spf13/viper/internal/encoding/yaml google.golang.org/protobuf/internal/encoding/text +github.com/spf13/viper/internal/encoding/toml +golang.org/x/text/secure/bidirule +github.com/bugsnag/bugsnag-go/errors database/sql +github.com/opencontainers/go-digest google.golang.org/protobuf/reflect/protoregistry -github.com/docker/distribution/reference +github.com/opencontainers/image-spec/specs-go +github.com/prometheus/procfs/internal/fs +runtime/debug +golang.org/x/net/idna golang.org/x/term golang.org/x/crypto/bcrypt -crypto/ecdsa +github.com/docker/distribution/digestset +github.com/opencontainers/image-spec/specs-go/v1 github.com/lib/pq/scram -github.com/spf13/viper/internal/encoding/yaml -os/user +google.golang.org/protobuf/internal/encoding/defval +github.com/docker/distribution/reference google.golang.org/protobuf/proto +os/user go/scanner -github.com/theupdateframework/notary/passphrase github.com/hailocab/go-hostpool -github.com/spf13/cast -google.golang.org/protobuf/internal/encoding/defval github.com/opentracing/opentracing-go/log +github.com/theupdateframework/notary/passphrase gopkg.in/rethinkdb/rethinkdb-go.v6/encoding gopkg.in/rethinkdb/rethinkdb-go.v6/types internal/profile -go/ast runtime/pprof +github.com/spf13/cast +go/ast runtime/trace github.com/dvsekhvalnov/jose2go/arrays github.com/davecgh/go-spew/spew +google.golang.org/protobuf/internal/filedesc +google.golang.org/protobuf/encoding/prototext +google.golang.org/protobuf/encoding/protodelim github.com/dvsekhvalnov/jose2go/aes +github.com/dvsekhvalnov/jose2go/kdf github.com/spf13/viper/internal/encoding/dotenv github.com/spf13/viper/internal/encoding/ini -github.com/dvsekhvalnov/jose2go/kdf github.com/pmezard/go-difflib/difflib -google.golang.org/protobuf/internal/filedesc -google.golang.org/protobuf/encoding/prototext -google.golang.org/protobuf/encoding/protodelim -testing github.com/jinzhu/gorm +testing google.golang.org/protobuf/internal/encoding/tag google.golang.org/protobuf/internal/impl crypto/x509 -net/textproto vendor/golang.org/x/net/http/httpproxy github.com/mitchellh/mapstructure +github.com/spf13/pflag +github.com/prometheus/procfs google.golang.org/grpc/internal/syscall google.golang.org/grpc/stats -github.com/spf13/pflag github.com/google/uuid -github.com/prometheus/procfs +net/textproto mime/multipart vendor/golang.org/x/net/http/httpguts golang.org/x/net/http/httpguts -github.com/spf13/cobra github.com/theupdateframework/notary/tuf/data -github.com/dvsekhvalnov/jose2go/keys/ecc crypto/tls +github.com/dvsekhvalnov/jose2go/keys/ecc +github.com/spf13/cobra github.com/dvsekhvalnov/jose2go github.com/theupdateframework/notary/client/changelist github.com/theupdateframework/notary/tuf/utils google.golang.org/protobuf/internal/filetype google.golang.org/protobuf/runtime/protoimpl -google.golang.org/protobuf/types/known/emptypb -google.golang.org/protobuf/types/descriptorpb google.golang.org/protobuf/types/known/anypb +google.golang.org/protobuf/types/descriptorpb google.golang.org/protobuf/types/known/durationpb google.golang.org/protobuf/types/known/timestamppb -github.com/golang/protobuf/ptypes/empty +google.golang.org/protobuf/types/known/emptypb +github.com/golang/protobuf/ptypes/duration +github.com/golang/protobuf/ptypes/any github.com/golang/protobuf/ptypes/timestamp github.com/prometheus/client_model/go -github.com/golang/protobuf/ptypes/any -github.com/golang/protobuf/ptypes/duration +github.com/golang/protobuf/ptypes/empty github.com/prometheus/common/model +google.golang.org/protobuf/types/gofeaturespb +google.golang.org/protobuf/reflect/protodesc +github.com/prometheus/client_golang/prometheus/internal net/http/httptrace google.golang.org/grpc/internal/credentials github.com/docker/go-connections/tlsconfig -github.com/docker/libtrust github.com/go-sql-driver/mysql +github.com/docker/libtrust github.com/lib/pq net/http -github.com/prometheus/client_golang/prometheus/internal -google.golang.org/protobuf/types/gofeaturespb -google.golang.org/protobuf/reflect/protodesc github.com/golang/protobuf/proto +google.golang.org/grpc/credentials google.golang.org/grpc/encoding/proto github.com/golang/protobuf/ptypes google.golang.org/grpc/binarylog/grpc_binarylog_v1 google.golang.org/genproto/googleapis/rpc/status gopkg.in/rethinkdb/rethinkdb-go.v6/ql2 -google.golang.org/grpc/credentials google.golang.org/grpc/internal/status google.golang.org/grpc/resolver google.golang.org/grpc/internal/channelz @@ -1752,74 +1788,74 @@ google.golang.org/grpc/internal/binarylog google.golang.org/grpc/balancer/roundrobin github.com/theupdateframework/notary/storage -github.com/spf13/afero -golang.org/x/net/trace -github.com/magiconair/properties net/http/httputil +golang.org/x/net/trace golang.org/x/net/http2 -github.com/bugsnag/bugsnag-go/sessions -github.com/gorilla/mux github.com/docker/distribution/registry/api/errcode github.com/docker/distribution/registry/auth -github.com/docker/distribution/registry/client/auth/challenge github.com/docker/distribution +github.com/gorilla/mux +github.com/magiconair/properties +github.com/bugsnag/bugsnag-go/sessions +github.com/spf13/afero +github.com/docker/distribution/registry/client/auth/challenge github.com/docker/distribution/registry/client/transport expvar github.com/prometheus/common/expfmt -github.com/bugsnag/bugsnag-go -github.com/theupdateframework/notary/trustmanager github.com/theupdateframework/notary/server/errors +github.com/spf13/viper/internal/encoding/javaproperties +github.com/theupdateframework/notary/trustmanager +github.com/bugsnag/bugsnag-go github.com/opentracing/opentracing-go +github.com/docker/distribution/context +github.com/docker/distribution/registry/api/v2 net/http/pprof net/http/httptest -github.com/spf13/viper/internal/encoding/javaproperties github.com/theupdateframework/notary/cryptoservice github.com/theupdateframework/notary/tuf/signed github.com/opentracing/opentracing-go/ext -github.com/docker/distribution/context -github.com/docker/distribution/registry/api/v2 -gopkg.in/rethinkdb/rethinkdb-go.v6 -github.com/stretchr/testify/assert github.com/docker/distribution/health github.com/docker/distribution/registry/auth/htpasswd github.com/docker/distribution/registry/auth/token +github.com/stretchr/testify/assert +github.com/prometheus/client_golang/prometheus +github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus +gopkg.in/rethinkdb/rethinkdb-go.v6 github.com/theupdateframework/notary/trustmanager/yubikey github.com/theupdateframework/notary/trustpinning github.com/Shopify/logrus-bugsnag github.com/theupdateframework/notary/tuf/testutils/keys -github.com/prometheus/client_golang/prometheus -github.com/theupdateframework/notary/tuf -github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus github.com/spf13/viper +github.com/theupdateframework/notary/tuf github.com/theupdateframework/notary/client github.com/theupdateframework/notary/tuf/testutils github.com/theupdateframework/notary/utils github.com/stretchr/testify/require -github.com/theupdateframework/notary/tuf/testutils/interfaces github.com/prometheus/client_golang/prometheus/promhttp -github.com/theupdateframework/notary/storage/rethinkdb google.golang.org/grpc/internal/transport -github.com/theupdateframework/notary/signer/keydbstore +github.com/theupdateframework/notary/storage/rethinkdb +github.com/theupdateframework/notary/tuf/testutils/interfaces github.com/theupdateframework/notary/server/storage +github.com/theupdateframework/notary/signer/keydbstore github.com/docker/go-metrics -github.com/theupdateframework/notary/server/snapshot -github.com/theupdateframework/notary/server/timestamp github.com/docker/distribution/metrics -github.com/theupdateframework/notary/server/handlers github.com/docker/distribution/registry/storage/cache github.com/docker/distribution/registry/storage/cache/memory -github.com/theupdateframework/notary/server github.com/docker/distribution/registry/client +github.com/theupdateframework/notary/server/snapshot +github.com/theupdateframework/notary/server/timestamp google.golang.org/grpc +github.com/theupdateframework/notary/server/handlers github.com/docker/distribution/registry/client/auth github.com/theupdateframework/notary/cmd/notary +github.com/theupdateframework/notary/server github.com/theupdateframework/notary/trustmanager/remoteks -google.golang.org/grpc/health/grpc_health_v1 github.com/theupdateframework/notary/proto +google.golang.org/grpc/health/grpc_health_v1 google.golang.org/grpc/health -github.com/theupdateframework/notary/cmd/escrow github.com/theupdateframework/notary/signer/client github.com/theupdateframework/notary/signer +github.com/theupdateframework/notary/cmd/escrow github.com/theupdateframework/notary/signer/api github.com/theupdateframework/notary/cmd/notary-signer github.com/theupdateframework/notary/cmd/notary-server @@ -1844,15 +1880,15 @@ === RUN Test0Dot3Migration --- PASS: Test0Dot3Migration (0.01s) === RUN Test0Dot1RepoFormat -time="2025-07-21T01:55:59Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot1RepoFormat (0.09s) +time="2025-07-21T01:58:04Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot1RepoFormat (0.15s) === RUN Test0Dot3RepoFormat -time="2025-07-21T01:55:59Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot3RepoFormat (0.11s) +time="2025-07-21T01:58:04Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot3RepoFormat (0.13s) === RUN TestDownloading0Dot1RepoFormat --- PASS: TestDownloading0Dot1RepoFormat (0.00s) === RUN TestDownloading0Dot3RepoFormat ---- PASS: TestDownloading0Dot3RepoFormat (0.00s) +--- PASS: TestDownloading0Dot3RepoFormat (0.01s) === RUN TestInitRepositoryManagedRolesIncludingRoot --- PASS: TestInitRepositoryManagedRolesIncludingRoot (0.00s) === RUN TestInitRepositoryManagedRolesInvalidRole @@ -1866,23 +1902,23 @@ === RUN TestMatchKeyIDsWithPublicKeys --- PASS: TestMatchKeyIDsWithPublicKeys (0.00s) === RUN TestInitRepositoryNeedsRemoteTimestampKey ---- PASS: TestInitRepositoryNeedsRemoteTimestampKey (0.02s) +--- PASS: TestInitRepositoryNeedsRemoteTimestampKey (0.00s) === RUN TestInitRepositoryNeedsRemoteSnapshotKey --- PASS: TestInitRepositoryNeedsRemoteSnapshotKey (0.00s) === RUN TestInitRepoServerOnlyManagesTimestampKey ---- PASS: TestInitRepoServerOnlyManagesTimestampKey (0.07s) +--- PASS: TestInitRepoServerOnlyManagesTimestampKey (0.04s) === RUN TestInitRepoServerManagesTimestampAndSnapshotKeys ---- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.09s) +--- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.04s) === RUN TestInitRepoAttemptsExceeded ---- PASS: TestInitRepoAttemptsExceeded (0.04s) +--- PASS: TestInitRepoAttemptsExceeded (0.03s) === RUN TestInitRepoPasswordInvalid --- PASS: TestInitRepoPasswordInvalid (0.00s) === RUN TestAddTargetToTargetRoleByDefault --- PASS: TestAddTargetToTargetRoleByDefault (0.01s) === RUN TestAddTargetToSpecifiedValidRoles ---- PASS: TestAddTargetToSpecifiedValidRoles (0.03s) +--- PASS: TestAddTargetToSpecifiedValidRoles (0.01s) === RUN TestAddTargetToSpecifiedInvalidRoles ---- PASS: TestAddTargetToSpecifiedInvalidRoles (0.02s) +--- PASS: TestAddTargetToSpecifiedInvalidRoles (0.01s) === RUN TestAddTargetWithInvalidTarget --- PASS: TestAddTargetWithInvalidTarget (0.01s) === RUN TestAddTargetErrorWritingChanges @@ -1898,60 +1934,60 @@ === RUN TestListTarget --- PASS: TestListTarget (0.24s) === RUN TestListTargetRestrictsDelegationPaths ---- PASS: TestListTargetRestrictsDelegationPaths (0.03s) +--- PASS: TestListTargetRestrictsDelegationPaths (0.04s) === RUN TestValidateRootKey --- PASS: TestValidateRootKey (0.02s) === RUN TestGetChangelist --- PASS: TestGetChangelist (0.02s) === RUN TestPublishBareRepo ---- PASS: TestPublishBareRepo (0.26s) +--- PASS: TestPublishBareRepo (0.24s) === RUN TestPublishUninitializedRepo -time="2025-07-21T01:56:00Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +time="2025-07-21T01:58:05Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" --- PASS: TestPublishUninitializedRepo (0.02s) === RUN TestPublishClientHasSnapshotKey ---- PASS: TestPublishClientHasSnapshotKey (0.22s) +--- PASS: TestPublishClientHasSnapshotKey (0.21s) === RUN TestPublishAfterInitServerHasSnapshotKey ---- PASS: TestPublishAfterInitServerHasSnapshotKey (0.20s) +--- PASS: TestPublishAfterInitServerHasSnapshotKey (0.18s) === RUN TestPublishAfterPullServerHasSnapshotKey ---- PASS: TestPublishAfterPullServerHasSnapshotKey (0.07s) +--- PASS: TestPublishAfterPullServerHasSnapshotKey (0.08s) === RUN TestPublishNoOneHasSnapshotKey --- PASS: TestPublishNoOneHasSnapshotKey (0.05s) === RUN TestPublishSnapshotCorrupt ---- PASS: TestPublishSnapshotCorrupt (0.10s) +--- PASS: TestPublishSnapshotCorrupt (0.11s) === RUN TestPublishTargetsCorrupt ---- PASS: TestPublishTargetsCorrupt (0.05s) +--- PASS: TestPublishTargetsCorrupt (0.06s) === RUN TestPublishRootCorrupt -time="2025-07-21T01:56:01Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" -time="2025-07-21T01:56:01Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" ---- PASS: TestPublishRootCorrupt (0.06s) +time="2025-07-21T01:58:06Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +time="2025-07-21T01:58:06Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +--- PASS: TestPublishRootCorrupt (0.05s) === RUN TestPublishSnapshotLocalKeysCreatedFirst --- PASS: TestPublishSnapshotLocalKeysCreatedFirst (0.00s) === RUN TestPublishDelegations ---- PASS: TestPublishDelegations (0.11s) +--- PASS: TestPublishDelegations (0.08s) === RUN TestPublishDelegationsX509 ---- PASS: TestPublishDelegationsX509 (0.13s) +--- PASS: TestPublishDelegationsX509 (0.07s) === RUN TestPublishTargetsDelegationScopeFailIfNoKeys -time="2025-07-21T01:56:01Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" -time="2025-07-21T01:56:01Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" ---- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (0.08s) +time="2025-07-21T01:58:06Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" +time="2025-07-21T01:58:06Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" +--- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (0.06s) === RUN TestPublishTargetsDelegationSuccessLocallyHasRoles ---- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (0.06s) +--- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (0.07s) === RUN TestPublishTargetsDelegationNoTargetsKeyNeeded ---- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (0.08s) +--- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (0.07s) === RUN TestPublishTargetsDelegationSuccessNeedsToDownloadRoles ---- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (0.07s) +--- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (0.08s) === RUN TestPublishTargetsDelegationFromTwoRepos ---- PASS: TestPublishTargetsDelegationFromTwoRepos (0.07s) +--- PASS: TestPublishTargetsDelegationFromTwoRepos (0.06s) === RUN TestPublishRemoveDelegationKeyFromDelegationRole -time="2025-07-21T01:56:02Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-07-21T01:56:02Z" level=error msg="couldn't add target to targets/a: could not find necessary signing keys, at least one of these keys must be available: 5c5d1fb47c3aa8efcb36441f59034fa9bb7c8bb882315c312645fad91f9dec98" ---- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.05s) +time="2025-07-21T01:58:07Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-07-21T01:58:07Z" level=error msg="couldn't add target to targets/a: could not find necessary signing keys, at least one of these keys must be available: efae1e45e1a9c77916739a0bdebce1e2e67de6277ff440cfb87d205ea2165f8b" +--- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.04s) === RUN TestPublishRemoveDelegation -time="2025-07-21T01:56:02Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-07-21T01:56:02Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-07-21T01:56:02Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-07-21T01:56:02Z" level=error msg="couldn't add target to targets/a: could not find necessary signing keys, at least one of these keys must be available: " ---- PASS: TestPublishRemoveDelegation (0.04s) +time="2025-07-21T01:58:07Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2025-07-21T01:58:07Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2025-07-21T01:58:07Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-07-21T01:58:07Z" level=error msg="couldn't add target to targets/a: could not find necessary signing keys, at least one of these keys must be available: " +--- PASS: TestPublishRemoveDelegation (0.05s) === RUN TestPublishSucceedsDespiteDelegationCorrupt --- PASS: TestPublishSucceedsDespiteDelegationCorrupt (0.05s) === RUN TestRotateKeyInvalidRole @@ -1963,213 +1999,213 @@ === RUN TestRemoteRotationNoRootKey --- PASS: TestRemoteRotationNoRootKey (0.03s) === RUN TestRemoteRotationNoInit -time="2025-07-21T01:56:02Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +time="2025-07-21T01:58:07Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" --- PASS: TestRemoteRotationNoInit (0.02s) === RUN TestRotateBeforePublishFromRemoteKeyToLocalKey ---- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (0.08s) +--- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (0.11s) === RUN TestRotateKeyAfterPublishNoServerManagementChange ---- PASS: TestRotateKeyAfterPublishNoServerManagementChange (0.29s) +--- PASS: TestRotateKeyAfterPublishNoServerManagementChange (0.26s) === RUN TestRotateKeyAfterPublishServerManagementChange ---- PASS: TestRotateKeyAfterPublishServerManagementChange (0.33s) +--- PASS: TestRotateKeyAfterPublishServerManagementChange (0.25s) === RUN TestRotateRootKey ---- PASS: TestRotateRootKey (0.15s) +--- PASS: TestRotateRootKey (0.08s) === RUN TestRotateRootMultiple ---- PASS: TestRotateRootMultiple (0.12s) +--- PASS: TestRotateRootMultiple (0.09s) === RUN TestRotateRootKeyProvided ---- PASS: TestRotateRootKeyProvided (0.08s) +--- PASS: TestRotateRootKeyProvided (0.07s) === RUN TestRotateRootKeyLegacySupport ---- PASS: TestRotateRootKeyLegacySupport (0.14s) +--- PASS: TestRotateRootKeyLegacySupport (0.11s) === RUN TestRemoteServerUnavailableNoLocalCache -time="2025-07-21T01:56:03Z" level=error msg="Could not publish Repository since we could not update: unable to reach trust server at this time: 500." +time="2025-07-21T01:58:08Z" level=error msg="Could not publish Repository since we could not update: unable to reach trust server at this time: 500." --- PASS: TestRemoteServerUnavailableNoLocalCache (0.00s) === RUN TestAddDelegationChangefileValid --- PASS: TestAddDelegationChangefileValid (0.01s) === RUN TestAddDelegationChangefileApplicable ---- PASS: TestAddDelegationChangefileApplicable (0.02s) +--- PASS: TestAddDelegationChangefileApplicable (0.01s) === RUN TestAddDelegationErrorWritingChanges --- PASS: TestAddDelegationErrorWritingChanges (0.01s) === RUN TestRemoveDelegationChangefileValid ---- PASS: TestRemoveDelegationChangefileValid (0.02s) +--- PASS: TestRemoveDelegationChangefileValid (0.01s) === RUN TestRemoveDelegationChangefileApplicable -time="2025-07-21T01:56:03Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-07-21T01:56:03Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2025-07-21T01:58:08Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2025-07-21T01:58:08Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" --- PASS: TestRemoveDelegationChangefileApplicable (0.01s) === RUN TestClearAllPathsDelegationChangefileApplicable --- PASS: TestClearAllPathsDelegationChangefileApplicable (0.01s) === RUN TestFullAddDelegationChangefileApplicable ---- PASS: TestFullAddDelegationChangefileApplicable (0.02s) +--- PASS: TestFullAddDelegationChangefileApplicable (0.01s) === RUN TestFullRemoveDelegationChangefileApplicable ---- PASS: TestFullRemoveDelegationChangefileApplicable (0.02s) +--- PASS: TestFullRemoveDelegationChangefileApplicable (0.01s) === RUN TestRemoveDelegationErrorWritingChanges --- PASS: TestRemoveDelegationErrorWritingChanges (0.01s) === RUN TestClientInvalidURL --- PASS: TestClientInvalidURL (0.00s) === RUN TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole ---- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (0.20s) +--- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (0.15s) === RUN TestDeleteRepo --- PASS: TestDeleteRepo (0.01s) === RUN TestDeleteRemoteRepo ---- PASS: TestDeleteRemoteRepo (0.05s) +--- PASS: TestDeleteRemoteRepo (0.04s) === RUN TestListRoles ---- PASS: TestListRoles (0.18s) +--- PASS: TestListRoles (0.10s) === RUN TestGetAllTargetInfo ---- PASS: TestGetAllTargetInfo (0.08s) +--- PASS: TestGetAllTargetInfo (0.06s) === RUN TestUpdateSucceedsEvenIfCannotWriteNewRepo -time="2025-07-21T01:56:04Z" level=error msg="could not save root to cache: Non-writable" ---- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.06s) +time="2025-07-21T01:58:08Z" level=error msg="could not save root to cache: Non-writable" +--- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.05s) === RUN TestUpdateSucceedsEvenIfCannotWriteExistingRepo ---- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (0.22s) +--- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (0.23s) === RUN TestUpdateInOfflineMode -time="2025-07-21T01:56:04Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" -time="2025-07-21T01:56:04Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +time="2025-07-21T01:58:09Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +time="2025-07-21T01:58:09Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" --- PASS: TestUpdateInOfflineMode (0.02s) === RUN TestUpdateReplacesCorruptOrMissingMetadata -time="2025-07-21T01:56:04Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:04Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateReplacesCorruptOrMissingMetadata (0.44s) +time="2025-07-21T01:58:09Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:09Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateReplacesCorruptOrMissingMetadata (0.72s) === RUN TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign -time="2025-07-21T01:56:05Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:05Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.07s) +time="2025-07-21T01:58:09Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:09Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.08s) === RUN TestUpdateRemoteRootNotExistNoLocalCache ---- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.02s) +--- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.03s) === RUN TestUpdateRemoteRootNotExistCanUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.03s) +--- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.07s) === RUN TestUpdateRemoteRootNotExistCannotUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.03s) +--- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.08s) === RUN TestUpdateRemoteRoot50XNoLocalCache ---- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.01s) +--- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.03s) === RUN TestUpdateRemoteRoot50XCanUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.04s) +--- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.07s) === RUN TestUpdateRemoteRoot50XCannotUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.03s) +--- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.08s) === RUN TestUpdateNonRootRemoteMissingMetadataNoLocalCache ---- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (0.08s) +--- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (0.17s) === RUN TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache -time="2025-07-21T01:56:05Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (0.10s) +time="2025-07-21T01:58:10Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (0.23s) === RUN TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache -time="2025-07-21T01:56:05Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (0.14s) +time="2025-07-21T01:58:10Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (0.29s) === RUN TestUpdateNonRootRemote50XNoLocalCache ---- PASS: TestUpdateNonRootRemote50XNoLocalCache (0.08s) +--- PASS: TestUpdateNonRootRemote50XNoLocalCache (0.17s) === RUN TestUpdateNonRootRemote50XCanUseLocalCache -time="2025-07-21T01:56:05Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (0.10s) +time="2025-07-21T01:58:11Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (0.23s) === RUN TestUpdateNonRootRemote50XCannotUseLocalCache -time="2025-07-21T01:56:05Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (0.12s) +time="2025-07-21T01:58:11Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (0.29s) === RUN TestUpdateRemoteChecksumWrongNoLocalCache ---- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (0.09s) +--- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (0.21s) === RUN TestUpdateRemoteChecksumWrongCanUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (0.13s) +--- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (0.30s) === RUN TestUpdateRemoteChecksumWrongCannotUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (0.20s) +--- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (0.40s) === RUN TestUpdateRootRemoteCorruptedNoLocalCache -time="2025-07-21T01:56:06Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:06Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (0.31s) +time="2025-07-21T01:58:12Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:12Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (0.62s) === RUN TestUpdateRootRemoteCorruptedCanUseLocalCache ---- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (0.58s) +--- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (1.33s) === RUN TestUpdateRootRemoteCorruptedCannotUseLocalCache -time="2025-07-21T01:56:07Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:07Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (0.74s) +time="2025-07-21T01:58:15Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:15Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (1.44s) === RUN TestUpdateNonRootRemoteCorruptedNoLocalCache -time="2025-07-21T01:56:08Z" level=error msg="Metadata for targets expired" -time="2025-07-21T01:56:08Z" level=error msg="Metadata for targets expired" -time="2025-07-21T01:56:08Z" level=error msg="Metadata for snapshot expired" -time="2025-07-21T01:56:08Z" level=error msg="Metadata for snapshot expired" -time="2025-07-21T01:56:08Z" level=error msg="Metadata for timestamp expired" -time="2025-07-21T01:56:08Z" level=error msg="Metadata for timestamp expired" -time="2025-07-21T01:56:08Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-07-21T01:56:08Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-07-21T01:56:08Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-07-21T01:56:08Z" level=error msg="Metadata for targets/a expired" -time="2025-07-21T01:56:08Z" level=warning msg="Error getting targets/a: targets/a expired at Thu Jun 20 01:56:08 UTC 2024" -time="2025-07-21T01:56:08Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-07-21T01:56:08Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-07-21T01:56:08Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-07-21T01:56:08Z" level=error msg="Metadata for targets/a/b expired" -time="2025-07-21T01:56:08Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Thu Jun 20 01:56:08 UTC 2024" -time="2025-07-21T01:56:08Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-07-21T01:56:08Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-07-21T01:56:08Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-07-21T01:56:08Z" level=error msg="Metadata for targets/b expired" -time="2025-07-21T01:56:08Z" level=warning msg="Error getting targets/b: targets/b expired at Thu Jun 20 01:56:08 UTC 2024" ---- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (0.75s) +time="2025-07-21T01:58:16Z" level=error msg="Metadata for targets expired" +time="2025-07-21T01:58:16Z" level=error msg="Metadata for targets expired" +time="2025-07-21T01:58:16Z" level=error msg="Metadata for snapshot expired" +time="2025-07-21T01:58:16Z" level=error msg="Metadata for snapshot expired" +time="2025-07-21T01:58:16Z" level=error msg="Metadata for timestamp expired" +time="2025-07-21T01:58:16Z" level=error msg="Metadata for timestamp expired" +time="2025-07-21T01:58:16Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-07-21T01:58:16Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-07-21T01:58:16Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-07-21T01:58:16Z" level=error msg="Metadata for targets/a expired" +time="2025-07-21T01:58:16Z" level=warning msg="Error getting targets/a: targets/a expired at Thu Jun 20 01:58:16 UTC 2024" +time="2025-07-21T01:58:17Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-07-21T01:58:17Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-07-21T01:58:17Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-07-21T01:58:17Z" level=error msg="Metadata for targets/a/b expired" +time="2025-07-21T01:58:17Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Thu Jun 20 01:58:17 UTC 2024" +time="2025-07-21T01:58:17Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-07-21T01:58:17Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-07-21T01:58:17Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-07-21T01:58:17Z" level=error msg="Metadata for targets/b expired" +time="2025-07-21T01:58:17Z" level=warning msg="Error getting targets/b: targets/b expired at Thu Jun 20 01:58:17 UTC 2024" +--- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (1.55s) === RUN TestUpdateNonRootRemoteCorruptedCanUseLocalCache -time="2025-07-21T01:56:09Z" level=error msg="Metadata for timestamp expired" -time="2025-07-21T01:56:09Z" level=error msg="Metadata for timestamp expired" ---- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (0.99s) +time="2025-07-21T01:58:18Z" level=error msg="Metadata for timestamp expired" +time="2025-07-21T01:58:18Z" level=error msg="Metadata for timestamp expired" +--- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (2.18s) === RUN TestUpdateNonRootRemoteCorruptedCannotUseLocalCache -time="2025-07-21T01:56:09Z" level=error msg="Metadata for targets expired" -time="2025-07-21T01:56:10Z" level=error msg="Metadata for snapshot expired" -time="2025-07-21T01:56:10Z" level=error msg="Metadata for snapshot expired" -time="2025-07-21T01:56:10Z" level=error msg="Metadata for timestamp expired" -time="2025-07-21T01:56:10Z" level=error msg="Metadata for timestamp expired" -time="2025-07-21T01:56:10Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-07-21T01:56:10Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-07-21T01:56:10Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-07-21T01:56:10Z" level=error msg="Metadata for targets/a expired" -time="2025-07-21T01:56:10Z" level=warning msg="Error getting targets/a: targets/a expired at Thu Jun 20 01:56:10 UTC 2024" -time="2025-07-21T01:56:10Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-07-21T01:56:10Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-07-21T01:56:10Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-07-21T01:56:10Z" level=error msg="Metadata for targets/a/b expired" -time="2025-07-21T01:56:10Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Thu Jun 20 01:56:10 UTC 2024" -time="2025-07-21T01:56:10Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-07-21T01:56:10Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-07-21T01:56:11Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-07-21T01:56:11Z" level=error msg="Metadata for targets/b expired" -time="2025-07-21T01:56:11Z" level=warning msg="Error getting targets/b: targets/b expired at Thu Jun 20 01:56:11 UTC 2024" ---- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (1.49s) +time="2025-07-21T01:58:20Z" level=error msg="Metadata for targets expired" +time="2025-07-21T01:58:20Z" level=error msg="Metadata for snapshot expired" +time="2025-07-21T01:58:20Z" level=error msg="Metadata for snapshot expired" +time="2025-07-21T01:58:20Z" level=error msg="Metadata for timestamp expired" +time="2025-07-21T01:58:20Z" level=error msg="Metadata for timestamp expired" +time="2025-07-21T01:58:21Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-07-21T01:58:21Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-07-21T01:58:21Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-07-21T01:58:21Z" level=error msg="Metadata for targets/a expired" +time="2025-07-21T01:58:21Z" level=warning msg="Error getting targets/a: targets/a expired at Thu Jun 20 01:58:21 UTC 2024" +time="2025-07-21T01:58:21Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-07-21T01:58:21Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-07-21T01:58:21Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-07-21T01:58:21Z" level=error msg="Metadata for targets/a/b expired" +time="2025-07-21T01:58:21Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Thu Jun 20 01:58:21 UTC 2024" +time="2025-07-21T01:58:22Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-07-21T01:58:22Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-07-21T01:58:22Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-07-21T01:58:22Z" level=error msg="Metadata for targets/b expired" +time="2025-07-21T01:58:22Z" level=warning msg="Error getting targets/b: targets/b expired at Thu Jun 20 01:58:22 UTC 2024" +--- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (2.85s) === RUN TestUpdateLocalAndRemoteRootCorrupt -time="2025-07-21T01:56:11Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:11Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:12Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:12Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:12Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:12Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:12Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:12Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:12Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:12Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:12Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:12Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:13Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:13Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:13Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:13Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:13Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:13Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:13Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:13Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:13Z" level=error msg="Metadata for root expired" -time="2025-07-21T01:56:13Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateLocalAndRemoteRootCorrupt (2.16s) +time="2025-07-21T01:58:23Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:23Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:24Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:24Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:24Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:24Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:25Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateLocalAndRemoteRootCorrupt (2.98s) === RUN TestUpdateRemoteKeyRotated -time="2025-07-21T01:56:13Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-07-21T01:56:13Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-07-21T01:56:13Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" ---- PASS: TestUpdateRemoteKeyRotated (0.31s) +time="2025-07-21T01:58:25Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-07-21T01:58:25Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-07-21T01:58:25Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +--- PASS: TestUpdateRemoteKeyRotated (0.29s) === RUN TestValidateRootRotationWithOldRole ---- PASS: TestValidateRootRotationWithOldRole (0.16s) +--- PASS: TestValidateRootRotationWithOldRole (0.12s) === RUN TestRootRoleInvariant ---- PASS: TestRootRoleInvariant (0.15s) +--- PASS: TestRootRoleInvariant (0.13s) === RUN TestBadIntermediateTransitions --- PASS: TestBadIntermediateTransitions (0.05s) === RUN TestExpiredIntermediateTransitions -time="2025-07-21T01:56:14Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" ---- PASS: TestExpiredIntermediateTransitions (0.06s) +time="2025-07-21T01:58:26Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" +--- PASS: TestExpiredIntermediateTransitions (0.05s) === RUN TestDownloadTargetsLarge ---- PASS: TestDownloadTargetsLarge (2.84s) +--- PASS: TestDownloadTargetsLarge (2.68s) === RUN TestDownloadTargetsDeep ---- PASS: TestDownloadTargetsDeep (0.01s) +--- PASS: TestDownloadTargetsDeep (0.02s) === RUN TestDownloadSnapshotLargeDelegationsMany ---- PASS: TestDownloadSnapshotLargeDelegationsMany (2.09s) +--- PASS: TestDownloadSnapshotLargeDelegationsMany (2.16s) === RUN TestRootOnDiskTrustPinning ---- PASS: TestRootOnDiskTrustPinning (0.01s) +--- PASS: TestRootOnDiskTrustPinning (0.03s) === RUN TestLoadTUFRepoBadURL --- PASS: TestLoadTUFRepoBadURL (0.00s) === RUN TestApplyTargetsChange @@ -2209,14 +2245,14 @@ === RUN TestApplyChangelistTargetsToMultipleRoles --- PASS: TestApplyChangelistTargetsToMultipleRoles (0.00s) === RUN TestApplyChangelistTargetsFailsNonexistentRole -time="2025-07-21T01:56:19Z" level=error msg="couldn't add target to targets/level1/level2/level3/level4: tuf: invalid role targets/level1/level2/level3/level4. delegation does not exist" -time="2025-07-21T01:56:19Z" level=error msg="couldn't remove target from targets/level1/level2/level3/level4: tuf: invalid role targets/level1/level2/level3/level4. delegation does not exist" +time="2025-07-21T01:58:31Z" level=error msg="couldn't add target to targets/level1/level2/level3/level4: tuf: invalid role targets/level1/level2/level3/level4. delegation does not exist" +time="2025-07-21T01:58:31Z" level=error msg="couldn't remove target from targets/level1/level2/level3/level4: tuf: invalid role targets/level1/level2/level3/level4. delegation does not exist" --- PASS: TestApplyChangelistTargetsFailsNonexistentRole (0.00s) === RUN TestChangeTargetMetaFailsInvalidRole -time="2025-07-21T01:56:19Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" +time="2025-07-21T01:58:31Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" --- PASS: TestChangeTargetMetaFailsInvalidRole (0.00s) === RUN TestChangeTargetMetaFailsIfPrefixError -time="2025-07-21T01:56:19Z" level=error msg="couldn't add target to targets/level1: could not add all targets" +time="2025-07-21T01:58:31Z" level=error msg="couldn't add target to targets/level1: could not add all targets" --- PASS: TestChangeTargetMetaFailsIfPrefixError (0.00s) === RUN TestAllNearExpiry --- PASS: TestAllNearExpiry (0.00s) @@ -2225,9 +2261,9 @@ === RUN TestRotateRemoteKeyOffline --- PASS: TestRotateRemoteKeyOffline (0.00s) PASS -ok github.com/theupdateframework/notary/client 19.482s +ok github.com/theupdateframework/notary/client 26.380s === RUN TestTUFDelegation ---- PASS: TestTUFDelegation (0.01s) +--- PASS: TestTUFDelegation (0.00s) === RUN TestMemChangelist --- PASS: TestMemChangelist (0.00s) === RUN TestMemChangeIterator @@ -2237,14 +2273,14 @@ === RUN TestAdd --- PASS: TestAdd (0.00s) === RUN TestErrorConditions -time="2025-07-21T01:55:59Z" level=warning msg="invalid character '\\x05' looking for beginning of value" +time="2025-07-21T01:58:04Z" level=warning msg="invalid character '\\x05' looking for beginning of value" --- PASS: TestErrorConditions (0.00s) === RUN TestListOrder --- PASS: TestListOrder (0.00s) === RUN TestFileChangeIterator --- PASS: TestFileChangeIterator (0.00s) PASS -ok github.com/theupdateframework/notary/client/changelist 0.051s +ok github.com/theupdateframework/notary/client/changelist 0.045s === RUN TestParseConfigError --- PASS: TestParseConfigError (0.00s) === RUN TestSetupGRPCServer @@ -2254,7 +2290,7 @@ === RUN TestSetupNetListener --- PASS: TestSetupNetListener (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/escrow 0.054s +ok github.com/theupdateframework/notary/cmd/escrow 0.053s === RUN TestPurgeDelegationKeys Usage: delegation [command] @@ -2286,7 +2322,7 @@ === RUN TestAddInvalidShortPubkeyCert --- PASS: TestAddInvalidShortPubkeyCert (0.00s) === RUN TestRemoveInvalidDelegationName ---- PASS: TestRemoveInvalidDelegationName (0.00s) +--- PASS: TestRemoveInvalidDelegationName (0.01s) === RUN TestRemoveAllInvalidDelegationName --- PASS: TestRemoveAllInvalidDelegationName (0.00s) === RUN TestAddInvalidNumArgs @@ -2332,20 +2368,20 @@ === RUN TestClientTUFInteraction --- PASS: TestClientTUFInteraction (0.09s) === RUN TestClientDeleteTUFInteraction -time="2025-07-21T01:55:59Z" level=error msg="could not reach https://invalid-server: Get \"https://invalid-server/v2/\": dial tcp: lookup invalid-server on [::1]:53: read udp [::1]:50500->[::1]:53: read: connection refused" +time="2025-07-21T01:58:05Z" level=error msg="could not reach https://invalid-server: Get \"https://invalid-server/v2/\": dial tcp: lookup invalid-server on [::1]:53: read udp [::1]:38825->[::1]:53: read: connection refused" --- PASS: TestClientDeleteTUFInteraction (0.11s) === RUN TestClientTUFAddByHashInteraction ---- PASS: TestClientTUFAddByHashInteraction (0.21s) +--- PASS: TestClientTUFAddByHashInteraction (0.18s) === RUN TestClientDelegationsInteraction -time="2025-07-21T01:56:00Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-07-21T01:56:00Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" ---- PASS: TestClientDelegationsInteraction (0.41s) +time="2025-07-21T01:58:05Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2025-07-21T01:58:05Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +--- PASS: TestClientDelegationsInteraction (0.47s) === RUN TestClientDelegationsPublishing ---- PASS: TestClientDelegationsPublishing (0.64s) +--- PASS: TestClientDelegationsPublishing (0.50s) === RUN TestClientKeyGenerationRotation ---- PASS: TestClientKeyGenerationRotation (0.48s) +--- PASS: TestClientKeyGenerationRotation (0.60s) === RUN TestKeyRotation ---- PASS: TestKeyRotation (0.08s) +--- PASS: TestKeyRotation (0.07s) === RUN TestKeyRotationNonRoot --- PASS: TestKeyRotationNonRoot (0.09s) === RUN TestDefaultRootKeyGeneration @@ -2356,43 +2392,43 @@ --- PASS: TestClientKeyPassphraseChange (0.07s) === RUN TestPurgeSingleKey Removal of the following keys from all delegations in gun staged for next publish: - - a3f9070e7fcd57374d236f73ceaa5a933a11338212bd6170d546c1cb4993cadf + - a8154683e5e8982b635bb466ddca51a68bdbdc01c7dd9dd3a98848f1d78cc8ac --- PASS: TestPurgeSingleKey (0.07s) === RUN TestWitness -time="2025-07-21T01:56:02Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2025-07-21T01:56:02Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2025-07-21T01:56:02Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2025-07-21T01:56:02Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2025-07-21T01:56:02Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-07-21T01:56:02Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-07-21T01:56:02Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" ---- PASS: TestWitness (0.21s) +time="2025-07-21T01:58:07Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2025-07-21T01:58:07Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2025-07-21T01:58:07Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2025-07-21T01:58:07Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2025-07-21T01:58:07Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2025-07-21T01:58:07Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2025-07-21T01:58:07Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +--- PASS: TestWitness (0.28s) === RUN TestClientTUFInitWithAutoPublish -time="2025-07-21T01:56:02Z" level=error msg="could not reach https://notary-server-on-the-moon:12306: Get \"https://notary-server-on-the-moon:12306/v2/\": dial tcp: lookup notary-server-on-the-moon on [::1]:53: read udp [::1]:48113->[::1]:53: read: connection refused" ---- PASS: TestClientTUFInitWithAutoPublish (0.05s) +time="2025-07-21T01:58:07Z" level=error msg="could not reach https://notary-server-on-the-moon:12306: Get \"https://notary-server-on-the-moon:12306/v2/\": dial tcp: lookup notary-server-on-the-moon on [::1]:53: read udp [::1]:49962->[::1]:53: read: connection refused" +--- PASS: TestClientTUFInitWithAutoPublish (0.07s) === RUN TestClientTUFAddWithAutoPublish -time="2025-07-21T01:56:02Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:37497->[::1]:53: read: connection refused" -time="2025-07-21T01:56:02Z" level=error msg="Could not publish Repository since we could not update: client is offline" -time="2025-07-21T01:56:02Z" level=error msg="could not reach https://notary-server-on-the-moon:12306: Get \"https://notary-server-on-the-moon:12306/v2/\": dial tcp: lookup notary-server-on-the-moon on [::1]:53: read udp [::1]:53780->[::1]:53: read: connection refused" -time="2025-07-21T01:56:02Z" level=error msg="Could not publish Repository since we could not update: client is offline" ---- PASS: TestClientTUFAddWithAutoPublish (0.06s) +time="2025-07-21T01:58:07Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:39841->[::1]:53: read: connection refused" +time="2025-07-21T01:58:07Z" level=error msg="Could not publish Repository since we could not update: client is offline" +time="2025-07-21T01:58:07Z" level=error msg="could not reach https://notary-server-on-the-moon:12306: Get \"https://notary-server-on-the-moon:12306/v2/\": dial tcp: lookup notary-server-on-the-moon on [::1]:53: read udp [::1]:53770->[::1]:53: read: connection refused" +time="2025-07-21T01:58:07Z" level=error msg="Could not publish Repository since we could not update: client is offline" +--- PASS: TestClientTUFAddWithAutoPublish (0.11s) === RUN TestClientTUFRemoveWithAutoPublish ---- PASS: TestClientTUFRemoveWithAutoPublish (0.13s) +--- PASS: TestClientTUFRemoveWithAutoPublish (0.14s) === RUN TestClientDelegationAddWithAutoPublish ---- PASS: TestClientDelegationAddWithAutoPublish (0.04s) +--- PASS: TestClientDelegationAddWithAutoPublish (0.07s) === RUN TestClientDelegationRemoveWithAutoPublish -time="2025-07-21T01:56:02Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-07-21T01:56:02Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" ---- PASS: TestClientDelegationRemoveWithAutoPublish (0.11s) +time="2025-07-21T01:58:08Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2025-07-21T01:58:08Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +--- PASS: TestClientDelegationRemoveWithAutoPublish (0.14s) === RUN TestClientTUFAddByHashWithAutoPublish ---- PASS: TestClientTUFAddByHashWithAutoPublish (0.06s) +--- PASS: TestClientTUFAddByHashWithAutoPublish (0.05s) === RUN TestClientKeyImport -time="2025-07-21T01:56:02Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" -time="2025-07-21T01:56:02Z" level=warning msg="failed to import key () to store: Cannot have canonical role key without a gun, don't know what gun it belongs to" +time="2025-07-21T01:58:08Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" +time="2025-07-21T01:58:08Z" level=warning msg="failed to import key () to store: Cannot have canonical role key without a gun, don't know what gun it belongs to" --- PASS: TestClientKeyImport (0.02s) === RUN TestAddDelImportKeyPublishFlow -time="2025-07-21T01:56:02Z" level=error msg="couldn't add target to targets/releases: could not find necessary signing keys, at least one of these keys must be available: 0570f9fdca00bdaf62f3f79bf84e70748ba2c8bff926f20dd996bac49e6164fc" ---- PASS: TestAddDelImportKeyPublishFlow (0.12s) +time="2025-07-21T01:58:08Z" level=error msg="couldn't add target to targets/releases: could not find necessary signing keys, at least one of these keys must be available: 0570f9fdca00bdaf62f3f79bf84e70748ba2c8bff926f20dd996bac49e6164fc" +--- PASS: TestAddDelImportKeyPublishFlow (0.16s) === RUN TestExportImportFlow --- PASS: TestExportImportFlow (0.03s) === RUN TestDelegationKeyImportExport @@ -2400,11 +2436,11 @@ === RUN TestImportKeysNoYubikey --- PASS: TestImportKeysNoYubikey (0.00s) === RUN TestExportImportKeysNoYubikey ---- PASS: TestExportImportKeysNoYubikey (0.01s) +--- PASS: TestExportImportKeysNoYubikey (0.00s) === RUN TestRemoveIfNoKey --- PASS: TestRemoveIfNoKey (0.00s) === RUN TestRemoveOneKeyAbort ---- PASS: TestRemoveOneKeyAbort (0.00s) +--- PASS: TestRemoveOneKeyAbort (0.01s) === RUN TestRemoveOneKeyConfirm --- PASS: TestRemoveOneKeyConfirm (0.00s) === RUN TestRemoveMultikeysInvalidInput @@ -2414,16 +2450,16 @@ === RUN TestRemoveMultikeysRemoveOnlyChosenKey --- PASS: TestRemoveMultikeysRemoveOnlyChosenKey (0.00s) === RUN TestRotateKeyInvalidRoles -time="2025-07-21T01:56:02Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:39081->[::1]:53: read: connection refused" -time="2025-07-21T01:56:02Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:33678->[::1]:53: read: connection refused" -time="2025-07-21T01:56:02Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:59843->[::1]:53: read: connection refused" -time="2025-07-21T01:56:02Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:50455->[::1]:53: read: connection refused" +time="2025-07-21T01:58:08Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:35257->[::1]:53: read: connection refused" +time="2025-07-21T01:58:08Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:40893->[::1]:53: read: connection refused" +time="2025-07-21T01:58:08Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:55115->[::1]:53: read: connection refused" +time="2025-07-21T01:58:08Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:57656->[::1]:53: read: connection refused" --- PASS: TestRotateKeyInvalidRoles (0.00s) === RUN TestRotateKeyTargetCannotBeServerManaged -time="2025-07-21T01:56:02Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:59736->[::1]:53: read: connection refused" +time="2025-07-21T01:58:08Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:48364->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTargetCannotBeServerManaged (0.00s) === RUN TestRotateKeyTimestampCannotBeLocallyManaged -time="2025-07-21T01:56:02Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:52942->[::1]:53: read: connection refused" +time="2025-07-21T01:58:08Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:44218->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTimestampCannotBeLocallyManaged (0.00s) === RUN TestRotateKeyNoGUN Usage: @@ -2431,13 +2467,13 @@ === RUN TestRotateKeyRemoteServerManagesKey Successfully rotated snapshot key for repository docker.com/notary Successfully rotated timestamp key for repository docker.com/notary ---- PASS: TestRotateKeyRemoteServerManagesKey (0.06s) +--- PASS: TestRotateKeyRemoteServerManagesKey (0.05s) === RUN TestRotateKeyBothKeys Successfully rotated targets key for repository docker.com/notary Successfully rotated snapshot key for repository docker.com/notary ---- PASS: TestRotateKeyBothKeys (0.06s) +--- PASS: TestRotateKeyBothKeys (0.04s) === RUN TestRotateKeyRootIsInteractive ---- PASS: TestRotateKeyRootIsInteractive (0.01s) +--- PASS: TestRotateKeyRootIsInteractive (0.02s) === RUN TestChangeKeyPassphraseInvalidID --- PASS: TestChangeKeyPassphraseInvalidID (0.00s) === RUN TestChangeKeyPassphraseInvalidNumArgs @@ -2456,7 +2492,7 @@ === RUN TestImportKeysNonexistentFile --- PASS: TestImportKeysNonexistentFile (0.00s) === RUN TestKeyGeneration -time="2025-07-21T01:56:03Z" level=warning msg="failed to import key () to store: Cannot have canonical role key without a gun, don't know what gun it belongs to" +time="2025-07-21T01:58:08Z" level=warning msg="failed to import key () to store: Cannot have canonical role key without a gun, don't know what gun it belongs to" --- PASS: TestKeyGeneration (0.00s) === RUN TestNotaryConfigFileDefault --- PASS: TestNotaryConfigFileDefault (0.00s) @@ -2467,7 +2503,7 @@ === RUN TestRemoteServerCommandLineFlagOverridesConfig --- PASS: TestRemoteServerCommandLineFlagOverridesConfig (0.00s) === RUN TestInvalidAddHashCommands ---- PASS: TestInvalidAddHashCommands (0.00s) +--- PASS: TestInvalidAddHashCommands (0.01s) === RUN TestConfigParsingErrorsPropagatedByCommands --- PASS: TestConfigParsingErrorsPropagatedByCommands (0.00s) === RUN TestInsufficientArgumentsReturnsErrorAndPrintsUsage @@ -2477,9 +2513,9 @@ === RUN TestConfigFileTLSCannotBeRelativeToCWD --- PASS: TestConfigFileTLSCannotBeRelativeToCWD (0.00s) === RUN TestConfigFileTLSCanBeRelativeToConfigOrAbsolute ---- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.03s) +--- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.04s) === RUN TestConfigFileOverridenByCmdLineFlags ---- PASS: TestConfigFileOverridenByCmdLineFlags (0.04s) +--- PASS: TestConfigFileOverridenByCmdLineFlags (0.03s) === RUN TestConfigFileTrustPinning --- PASS: TestConfigFileTrustPinning (0.00s) === RUN TestPassphraseRetrieverCaching @@ -2505,10 +2541,10 @@ === RUN TestImportRootCert --- PASS: TestImportRootCert (0.00s) === RUN TestTokenAuth -time="2025-07-21T01:56:03Z" level=error msg="could not reach https://localhost:9999: Get \"https://localhost:9999/v2/\": dial tcp 127.0.0.1:9999: connect: connection refused" +time="2025-07-21T01:58:08Z" level=error msg="could not reach https://localhost:9999: Get \"https://localhost:9999/v2/\": dial tcp 127.0.0.1:9999: connect: connection refused" --- PASS: TestTokenAuth (0.00s) === RUN TestAdminTokenAuth -time="2025-07-21T01:56:03Z" level=error msg="could not reach https://localhost:9999: Get \"https://localhost:9999/v2/\": dial tcp 127.0.0.1:9999: connect: connection refused" +time="2025-07-21T01:58:08Z" level=error msg="could not reach https://localhost:9999: Get \"https://localhost:9999/v2/\": dial tcp 127.0.0.1:9999: connect: connection refused" --- PASS: TestAdminTokenAuth (0.00s) === RUN TestTokenAuth200Status --- PASS: TestTokenAuth200Status (0.00s) @@ -2519,18 +2555,18 @@ === RUN TestAdminTokenAuth401Status --- PASS: TestAdminTokenAuth401Status (0.00s) === RUN TestTokenAuthNon200Non401Status -time="2025-07-21T01:56:03Z" level=error msg="could not reach http://127.0.0.1:40529: 404" +time="2025-07-21T01:58:08Z" level=error msg="could not reach http://127.0.0.1:44265: 404" --- PASS: TestTokenAuthNon200Non401Status (0.00s) === RUN TestAdminTokenAuthNon200Non401Status -time="2025-07-21T01:56:03Z" level=error msg="could not reach http://127.0.0.1:37903: 404" +time="2025-07-21T01:58:08Z" level=error msg="could not reach http://127.0.0.1:44683: 404" --- PASS: TestAdminTokenAuthNon200Non401Status (0.00s) === RUN TestConfigureRepo --- PASS: TestConfigureRepo (0.00s) === RUN TestConfigureRepoRW -Enter username: time="2025-07-21T01:56:03Z" level=error msg="error processing username input: EOF" +Enter username: time="2025-07-21T01:58:08Z" level=error msg="error processing username input: EOF" --- PASS: TestConfigureRepoRW (0.00s) === RUN TestConfigureRepoAdmin -Enter username: time="2025-07-21T01:56:03Z" level=error msg="error processing username input: EOF" +Enter username: time="2025-07-21T01:58:08Z" level=error msg="error processing username input: EOF" --- PASS: TestConfigureRepoAdmin (0.00s) === RUN TestStatusUnstageAndReset Successfully reset specified changes for repository gun @@ -2543,11 +2579,11 @@ Pushing changes to gun --- PASS: TestGetTrustPinningErrors (0.00s) === RUN TestPasswordStore -Enter username: time="2025-07-21T01:56:03Z" level=error msg="error processing username input: EOF" +Enter username: time="2025-07-21T01:58:08Z" level=error msg="error processing username input: EOF" --- PASS: TestPasswordStore (0.00s) === RUN TestPasswordStoreWithEnvvar -time="2025-07-21T01:56:03Z" level=error msg="Authentication string with zero-length username" -time="2025-07-21T01:56:03Z" level=error msg="Could not base64-decode authentication string" +time="2025-07-21T01:58:08Z" level=error msg="Authentication string with zero-length username" +time="2025-07-21T01:58:08Z" level=error msg="Could not base64-decode authentication string" --- PASS: TestPasswordStoreWithEnvvar (0.00s) === RUN TestGetPayload --- PASS: TestGetPayload (0.00s) @@ -2556,7 +2592,7 @@ === RUN TestHomeExpand --- PASS: TestHomeExpand (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary 3.580s +ok github.com/theupdateframework/notary/cmd/notary 3.869s === RUN TestBootstrap --- PASS: TestBootstrap (0.00s) === RUN TestGetAddrAndTLSConfigInvalidTLS @@ -2572,42 +2608,42 @@ === RUN TestGetInvalidTrustService --- PASS: TestGetInvalidTrustService (0.00s) === RUN TestGetLocalTrustService -time="2025-07-21T01:56:00Z" level=info msg="Using local signing service, which requires ED25519. Ignoring all other trust_service parameters, including keyAlgorithm" +time="2025-07-21T01:58:05Z" level=info msg="Using local signing service, which requires ED25519. Ignoring all other trust_service parameters, including keyAlgorithm" --- PASS: TestGetLocalTrustService (0.00s) === RUN TestGetTrustServiceInvalidKeyAlgorithm --- PASS: TestGetTrustServiceInvalidKeyAlgorithm (0.00s) === RUN TestGetTrustServiceTLSMissingCertOrKey --- PASS: TestGetTrustServiceTLSMissingCertOrKey (0.00s) === RUN TestGetTrustServiceNoTLSConfig -time="2025-07-21T01:56:00Z" level=info msg="Using remote signing service" +time="2025-07-21T01:58:05Z" level=info msg="Using remote signing service" --- PASS: TestGetTrustServiceNoTLSConfig (0.00s) === RUN TestGetTrustServiceTLSSuccess -time="2025-07-21T01:56:00Z" level=info msg="Using remote signing service" +time="2025-07-21T01:58:05Z" level=info msg="Using remote signing service" --- PASS: TestGetTrustServiceTLSSuccess (0.00s) === RUN TestGetTrustServiceTLSFailure --- PASS: TestGetTrustServiceTLSFailure (0.00s) === RUN TestGetStoreInvalid -time="2025-07-21T01:56:00Z" level=info msg="Using asdf backend" +time="2025-07-21T01:58:05Z" level=info msg="Using asdf backend" --- PASS: TestGetStoreInvalid (0.00s) === RUN TestGetStoreDBStore -time="2025-07-21T01:56:00Z" level=info msg="Using sqlite3 backend" +time="2025-07-21T01:58:05Z" level=info msg="Using sqlite3 backend" --- PASS: TestGetStoreDBStore (0.00s) === RUN TestGetStoreRethinkDBStoreConnectionFails -time="2025-07-21T01:56:00Z" level=info msg="Using rethinkdb backend" +time="2025-07-21T01:58:05Z" level=info msg="Using rethinkdb backend" --- PASS: TestGetStoreRethinkDBStoreConnectionFails (0.00s) === RUN TestGetMemoryStore -time="2025-07-21T01:56:00Z" level=info msg="Using memory backend" +time="2025-07-21T01:58:05Z" level=info msg="Using memory backend" --- PASS: TestGetMemoryStore (0.00s) === RUN TestGetCacheConfig --- PASS: TestGetCacheConfig (0.00s) === RUN TestGetGUNPRefixes --- PASS: TestGetGUNPRefixes (0.00s) === RUN TestSampleConfig -time="2025-07-21T01:56:00Z" level=info msg="Using remote signing service" -time="2025-07-21T01:56:00Z" level=info msg="Using sqlite3 backend" ---- PASS: TestSampleConfig (0.01s) +time="2025-07-21T01:58:05Z" level=info msg="Using remote signing service" +time="2025-07-21T01:58:05Z" level=info msg="Using sqlite3 backend" +--- PASS: TestSampleConfig (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary-server 0.174s +ok github.com/theupdateframework/notary/cmd/notary-server 0.031s === RUN TestGetAddrAndTLSConfigInvalidTLS --- PASS: TestGetAddrAndTLSConfigInvalidTLS (0.00s) === RUN TestGetAddrAndTLSConfigNoGRPCAddr @@ -2640,17 +2676,17 @@ === RUN TestSampleConfig --- PASS: TestSampleConfig (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary-signer 0.107s +ok github.com/theupdateframework/notary/cmd/notary-signer 0.046s === RUN TestGenerateCertificate --- PASS: TestGenerateCertificate (0.00s) === RUN TestCryptoServiceWithNonEmptyGUN ---- PASS: TestCryptoServiceWithNonEmptyGUN (0.42s) +--- PASS: TestCryptoServiceWithNonEmptyGUN (0.22s) === RUN TestCryptoServiceWithEmptyGUN ---- PASS: TestCryptoServiceWithEmptyGUN (0.25s) +--- PASS: TestCryptoServiceWithEmptyGUN (0.20s) === RUN TestCryptoSignerInterfaceBehavior --- PASS: TestCryptoSignerInterfaceBehavior (0.00s) PASS -ok github.com/theupdateframework/notary/cryptoservice 0.751s +ok github.com/theupdateframework/notary/cryptoservice 0.453s === RUN TestGetPassphraseForUsingDelegationKey --- PASS: TestGetPassphraseForUsingDelegationKey (0.00s) === RUN TestGetPassphraseLimitsShortPassphrases @@ -2666,139 +2702,139 @@ === RUN TestGetPassphrase --- PASS: TestGetPassphrase (0.00s) PASS -ok github.com/theupdateframework/notary/passphrase 0.029s +ok github.com/theupdateframework/notary/passphrase 0.033s === RUN TestValidationErrorFormat -time="2025-07-21T01:56:00Z" level=info msg="invalid update: Update sent by the client is invalid.: &{ErrBadHierarchy Metadata hierarchy is incomplete: no snapshot was included in update and server does not hold current snapshot key for repository}" go.version=go1.22.4 http.request.contenttype="multipart/form-data; boundary=6a27f4cacf11dd81fb79534403ac0b0e93aeb8e531750088aa43486dbcfe" http.request.host="127.0.0.1:38813" http.request.id=2ef52dc0-7a1d-4b48-b1d7-bec3d0e85c29 http.request.method=POST http.request.remoteaddr="127.0.0.1:35006" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype="multipart/form-data; boundary=6a27f4cacf11dd81fb79534403ac0b0e93aeb8e531750088aa43486dbcfe" http.request.host="127.0.0.1:38813" http.request.id=2ef52dc0-7a1d-4b48-b1d7-bec3d0e85c29 http.request.method=POST http.request.remoteaddr="127.0.0.1:35006" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration=14.99188ms http.response.status=400 http.response.written=260 ---- PASS: TestValidationErrorFormat (0.05s) +time="2025-07-21T01:58:05Z" level=info msg="invalid update: Update sent by the client is invalid.: &{ErrBadHierarchy Metadata hierarchy is incomplete: no snapshot was included in update and server does not hold current snapshot key for repository}" go.version=go1.22.4 http.request.contenttype="multipart/form-data; boundary=93f20f66f58f43cda1548f6a0913eae5f3f427bb52e3aad866ebb4de399a" http.request.host="127.0.0.1:42673" http.request.id=57d85f01-a78d-4203-b65d-571de7c14c59 http.request.method=POST http.request.remoteaddr="127.0.0.1:37390" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype="multipart/form-data; boundary=93f20f66f58f43cda1548f6a0913eae5f3f427bb52e3aad866ebb4de399a" http.request.host="127.0.0.1:42673" http.request.id=57d85f01-a78d-4203-b65d-571de7c14c59 http.request.method=POST http.request.remoteaddr="127.0.0.1:37390" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration=3.56424ms http.response.status=400 http.response.written=260 +--- PASS: TestValidationErrorFormat (0.01s) === RUN TestRunBadAddr --- PASS: TestRunBadAddr (0.00s) === RUN TestRepoPrefixMatches -time="2025-07-21T01:56:00Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum 727b732328e27d86536056fddd19bd9a203083c6cb12b3c3756808bbbf2faecb" docker.io/notary=gun go.version=go1.22.4 http.request.contenttype="multipart/form-data; boundary=8fd942234614d923d8b6896c26f8fea69ad5e189daab26567a6147670954" http.request.host="127.0.0.1:44985" http.request.id=1b62a9be-c650-4fdd-8305-99bac91cf509 http.request.method=POST http.request.remoteaddr="127.0.0.1:58980" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype="multipart/form-data; boundary=8fd942234614d923d8b6896c26f8fea69ad5e189daab26567a6147670954" http.request.host="127.0.0.1:44985" http.request.id=1b62a9be-c650-4fdd-8305-99bac91cf509 http.request.method=POST http.request.remoteaddr="127.0.0.1:58980" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=2.01228ms http.response.status=0 http.response.written=0 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:44985" http.request.id=06497e0b-3cd2-48a2-a1b6-9675c37e9eee http.request.method=GET http.request.remoteaddr="127.0.0.1:58980" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration="294.84µs" http.response.status=200 http.response.written=683 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:44985" http.request.id=e6e8542c-3b4b-453c-b6bf-114c6d453892 http.request.method=GET http.request.remoteaddr="127.0.0.1:58980" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.94774d6ce0974e9b20c03dc744100e066424a0920d052d5c10f6aae6a992d392.json http.request.useragent=Go-http-client/1.1 http.response.duration="20.84µs" http.response.status=200 http.response.written=683 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:44985" http.request.id=63b52f51-77dc-4cc0-9b4a-94bf440e9d4d http.request.method=GET http.request.remoteaddr="127.0.0.1:58980" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="246.16µs" http.response.status=200 http.response.written=181 -time="2025-07-21T01:56:00Z" level=info msg="trust data deleted for docker.io/notary" docker.io/notary=gun go.version=go1.22.4 http.request.host="127.0.0.1:44985" http.request.id=0c88c9f2-9d2c-46ab-a332-345d6fea3be2 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:58980" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:44985" http.request.id=0c88c9f2-9d2c-46ab-a332-345d6fea3be2 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:58980" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="30.12µs" http.response.status=0 http.response.written=0 +time="2025-07-21T01:58:05Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum 66618d992056c1b3bae3a9aecac86824e139b5353392e3dd5839a6598c129f3d" docker.io/notary=gun go.version=go1.22.4 http.request.contenttype="multipart/form-data; boundary=a74a88f9135fed033734e60486c1343d2d5a8cc6a353b100ec05e75558f3" http.request.host="127.0.0.1:37893" http.request.id=101ffb54-9bf4-4bf0-a5c9-2596c8109649 http.request.method=POST http.request.remoteaddr="127.0.0.1:60448" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype="multipart/form-data; boundary=a74a88f9135fed033734e60486c1343d2d5a8cc6a353b100ec05e75558f3" http.request.host="127.0.0.1:37893" http.request.id=101ffb54-9bf4-4bf0-a5c9-2596c8109649 http.request.method=POST http.request.remoteaddr="127.0.0.1:60448" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=2.0532ms http.response.status=0 http.response.written=0 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:37893" http.request.id=fd71eeae-b7ee-45ef-9f51-311c9c14f002 http.request.method=GET http.request.remoteaddr="127.0.0.1:60448" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration="291.16µs" http.response.status=200 http.response.written=683 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:37893" http.request.id=f5d27d91-4a40-463e-9fec-35d21617ac8a http.request.method=GET http.request.remoteaddr="127.0.0.1:60448" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.b2e61ec187f9a846c9b89789d2a855956bbc953f65bdd99681768c639455c5ce.json http.request.useragent=Go-http-client/1.1 http.response.duration="28.68µs" http.response.status=200 http.response.written=683 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:37893" http.request.id=82091206-42b3-4331-a7c6-f92c71dc63c4 http.request.method=GET http.request.remoteaddr="127.0.0.1:60448" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="224.84µs" http.response.status=200 http.response.written=181 +time="2025-07-21T01:58:05Z" level=info msg="trust data deleted for docker.io/notary" docker.io/notary=gun go.version=go1.22.4 http.request.host="127.0.0.1:37893" http.request.id=135273ba-5f17-495b-9b97-d78a5b8860c1 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:60448" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:37893" http.request.id=135273ba-5f17-495b-9b97-d78a5b8860c1 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:60448" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="30.6µs" http.response.status=0 http.response.written=0 --- PASS: TestRepoPrefixMatches (0.01s) === RUN TestRepoPrefixDoesNotMatch ---- PASS: TestRepoPrefixDoesNotMatch (0.04s) +--- PASS: TestRepoPrefixDoesNotMatch (0.01s) === RUN TestMetricsEndpoint ---- PASS: TestMetricsEndpoint (0.02s) +--- PASS: TestMetricsEndpoint (0.03s) === RUN TestGetKeysEndpoint -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:42955" http.request.id=6e137abe-4e4d-40b6-b5b0-3b37f9d25c94 http.request.method=GET http.request.remoteaddr="127.0.0.1:42158" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration=4.12836ms http.response.status=200 http.response.written=103 -time="2025-07-21T01:56:00Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.4 http.request.host="127.0.0.1:42955" http.request.id=7ec44fdd-8f31-41df-a531-fe3ef0550e51 http.request.method=GET http.request.remoteaddr="127.0.0.1:42170" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:42955" http.request.id=7ec44fdd-8f31-41df-a531-fe3ef0550e51 http.request.method=GET http.request.remoteaddr="127.0.0.1:42170" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="78.64µs" http.response.status=404 http.response.written=104 -time="2025-07-21T01:56:00Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.4 http.request.host="127.0.0.1:42955" http.request.id=b1cf9089-47cf-417a-9840-c1ff8b724a8f http.request.method=GET http.request.remoteaddr="127.0.0.1:42174" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:42955" http.request.id=b1cf9089-47cf-417a-9840-c1ff8b724a8f http.request.method=GET http.request.remoteaddr="127.0.0.1:42174" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="57.04µs" http.response.status=404 http.response.written=104 -time="2025-07-21T01:56:00Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.4 http.request.host="127.0.0.1:42955" http.request.id=7a9bf10b-2cd3-4b65-8db0-d7db22c6176f http.request.method=GET http.request.remoteaddr="127.0.0.1:42176" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:42955" http.request.id=7a9bf10b-2cd3-4b65-8db0-d7db22c6176f http.request.method=GET http.request.remoteaddr="127.0.0.1:42176" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="54.48µs" http.response.status=404 http.response.written=104 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:42955" http.request.id=2284f04f-ebae-403a-85cc-f1ec4a1cd61e http.request.method=GET http.request.remoteaddr="127.0.0.1:42182" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="130.52µs" http.response.status=200 http.response.written=103 ---- PASS: TestGetKeysEndpoint (0.01s) +time="2025-07-21T01:58:05Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.4 http.request.host="127.0.0.1:41601" http.request.id=8cfdc878-f5d6-4a6e-95d3-c5eb4f0832f5 http.request.method=GET http.request.remoteaddr="127.0.0.1:49712" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:41601" http.request.id=8cfdc878-f5d6-4a6e-95d3-c5eb4f0832f5 http.request.method=GET http.request.remoteaddr="127.0.0.1:49712" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration=2.596ms http.response.status=404 http.response.written=104 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:41601" http.request.id=f244a4e1-9f51-4d16-9269-f3e9aae3a05f http.request.method=GET http.request.remoteaddr="127.0.0.1:49726" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration=5.14376ms http.response.status=200 http.response.written=103 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:41601" http.request.id=f918f2a2-8fbc-4329-b596-82dcbe457691 http.request.method=GET http.request.remoteaddr="127.0.0.1:49738" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="151.8µs" http.response.status=200 http.response.written=103 +time="2025-07-21T01:58:05Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.4 http.request.host="127.0.0.1:41601" http.request.id=d4e2dfcd-5283-4d6a-9095-ccd2e7da1e6c http.request.method=GET http.request.remoteaddr="127.0.0.1:49748" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:41601" http.request.id=d4e2dfcd-5283-4d6a-9095-ccd2e7da1e6c http.request.method=GET http.request.remoteaddr="127.0.0.1:49748" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="67.08µs" http.response.status=404 http.response.written=104 +time="2025-07-21T01:58:05Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.4 http.request.host="127.0.0.1:41601" http.request.id=4e94864e-cb65-451e-bcaa-abba52cc7fb9 http.request.method=GET http.request.remoteaddr="127.0.0.1:49750" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:41601" http.request.id=4e94864e-cb65-451e-bcaa-abba52cc7fb9 http.request.method=GET http.request.remoteaddr="127.0.0.1:49750" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="49.32µs" http.response.status=404 http.response.written=104 +--- PASS: TestGetKeysEndpoint (0.02s) === RUN TestGetRoleByHash -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:44781" http.request.id=92831908-4408-40ea-8bc4-4b8fceee4992 http.request.method=GET http.request.remoteaddr="127.0.0.1:40582" http.request.uri=/v2/gun/_trust/tuf/timestamp.9575f3d30075e7f3ff58ed19604fe32257022e7d6bfdb996090fba1f5a3e3786.json http.request.useragent=Go-http-client/1.1 http.response.duration="50.84µs" http.response.status=200 http.response.written=115 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:35649" http.request.id=01d74477-1224-44ac-906d-39e010e4cfe0 http.request.method=GET http.request.remoteaddr="127.0.0.1:38058" http.request.uri=/v2/gun/_trust/tuf/timestamp.4958dd44da2923901566f95e2e1dabfa4ecb31ebe71ac1f1d2fb3c99636d72b1.json http.request.useragent=Go-http-client/1.1 http.response.duration="63.72µs" http.response.status=200 http.response.written=115 --- PASS: TestGetRoleByHash (0.01s) === RUN TestGetRoleByVersion -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:46403" http.request.id=0e223d92-4d30-4002-b1c3-ed375c103382 http.request.method=GET http.request.remoteaddr="127.0.0.1:46994" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="197.08µs" http.response.status=200 http.response.written=115 ---- PASS: TestGetRoleByVersion (0.00s) +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:39397" http.request.id=c0b01318-3400-47e3-8b92-985b5706f6e1 http.request.method=GET http.request.remoteaddr="127.0.0.1:37722" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="55.32µs" http.response.status=200 http.response.written=115 +--- PASS: TestGetRoleByVersion (0.02s) === RUN TestGetCurrentRole -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:46291" http.request.id=139cfd88-e1f8-4f0e-9f7e-b92af5677886 http.request.method=GET http.request.remoteaddr="127.0.0.1:41062" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="273.88µs" http.response.status=200 http.response.written=495 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:45373" http.request.id=cc0032e3-0a8a-4949-9319-9f6bce12cb26 http.request.method=GET http.request.remoteaddr="127.0.0.1:44226" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="294.6µs" http.response.status=200 http.response.written=495 --- PASS: TestGetCurrentRole (0.01s) === RUN TestRotateKeyEndpoint -time="2025-07-21T01:56:00Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:34755" http.request.id=dba9ef4b-d787-45e3-a4cd-486885b264f3 http.request.method=POST http.request.remoteaddr="127.0.0.1:51916" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:34755" http.request.id=dba9ef4b-d787-45e3-a4cd-486885b264f3 http.request.method=POST http.request.remoteaddr="127.0.0.1:51916" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="99.36µs" http.response.status=404 http.response.written=104 -time="2025-07-21T01:56:00Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:34755" http.request.id=12f42f77-d7e9-42a1-ae50-f1d9af5ece43 http.request.method=POST http.request.remoteaddr="127.0.0.1:51918" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:34755" http.request.id=12f42f77-d7e9-42a1-ae50-f1d9af5ece43 http.request.method=POST http.request.remoteaddr="127.0.0.1:51918" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="53.16µs" http.response.status=404 http.response.written=104 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:34755" http.request.id=d8339208-dc21-4489-91da-60f6b267ca86 http.request.method=POST http.request.remoteaddr="127.0.0.1:51920" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="132.8µs" http.response.status=200 http.response.written=103 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:34755" http.request.id=2c3f47f5-6854-467b-bb64-db1d0edba9a4 http.request.method=POST http.request.remoteaddr="127.0.0.1:51924" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="115.52µs" http.response.status=200 http.response.written=103 -time="2025-07-21T01:56:00Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:34755" http.request.id=b311419d-ec5d-4fe8-b241-e92be0e15d9e http.request.method=POST http.request.remoteaddr="127.0.0.1:51932" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:34755" http.request.id=b311419d-ec5d-4fe8-b241-e92be0e15d9e http.request.method=POST http.request.remoteaddr="127.0.0.1:51932" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="64.04µs" http.response.status=404 http.response.written=104 -time="2025-07-21T01:56:00Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:34755" http.request.id=e1bc48b7-96df-48f4-a8ea-1327beb2de20 http.request.method=POST http.request.remoteaddr="127.0.0.1:51946" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:34755" http.request.id=e1bc48b7-96df-48f4-a8ea-1327beb2de20 http.request.method=POST http.request.remoteaddr="127.0.0.1:51946" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="46µs" http.response.status=404 http.response.written=104 +time="2025-07-21T01:58:05Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:36495" http.request.id=49bd0c90-4a16-4412-92bd-f8da2f1f6757 http.request.method=POST http.request.remoteaddr="127.0.0.1:36726" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:36495" http.request.id=49bd0c90-4a16-4412-92bd-f8da2f1f6757 http.request.method=POST http.request.remoteaddr="127.0.0.1:36726" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="102.52µs" http.response.status=404 http.response.written=104 +time="2025-07-21T01:58:05Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:36495" http.request.id=55740c8e-7bd9-4c3e-bf94-c8b2fa61dca8 http.request.method=POST http.request.remoteaddr="127.0.0.1:36742" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:36495" http.request.id=55740c8e-7bd9-4c3e-bf94-c8b2fa61dca8 http.request.method=POST http.request.remoteaddr="127.0.0.1:36742" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="57.12µs" http.response.status=404 http.response.written=104 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:36495" http.request.id=8dc68a89-7271-4c23-913e-17d90903f4b6 http.request.method=POST http.request.remoteaddr="127.0.0.1:36752" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="129.32µs" http.response.status=200 http.response.written=103 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:36495" http.request.id=5667351d-c96c-4b73-8aa0-c89b7e05b913 http.request.method=POST http.request.remoteaddr="127.0.0.1:36756" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="110.76µs" http.response.status=200 http.response.written=103 +time="2025-07-21T01:58:05Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:36495" http.request.id=27a1d65a-5a30-4b2f-8873-f34081198062 http.request.method=POST http.request.remoteaddr="127.0.0.1:36758" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:36495" http.request.id=27a1d65a-5a30-4b2f-8873-f34081198062 http.request.method=POST http.request.remoteaddr="127.0.0.1:36758" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="56.72µs" http.response.status=404 http.response.written=104 +time="2025-07-21T01:58:05Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:36495" http.request.id=604896f1-2fab-4416-abc6-1e57f1a35ba6 http.request.method=POST http.request.remoteaddr="127.0.0.1:36774" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2025-07-21T01:58:05Z" level=info msg="response completed" go.version=go1.22.4 http.request.contenttype=text/plain http.request.host="127.0.0.1:36495" http.request.id=604896f1-2fab-4416-abc6-1e57f1a35ba6 http.request.method=POST http.request.remoteaddr="127.0.0.1:36774" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="55.72µs" http.response.status=404 http.response.written=104 --- PASS: TestRotateKeyEndpoint (0.01s) PASS -ok github.com/theupdateframework/notary/server 0.256s +ok github.com/theupdateframework/notary/server 0.177s === RUN Test_changefeed --- PASS: Test_changefeed (0.00s) === RUN Test_checkChangefeedInputs -time="2025-07-21T01:56:00Z" level=error msg="500 GET unable to retrieve storage" -time="2025-07-21T01:56:00Z" level=error msg="400 GET invalid pageSize: not_a_number" +time="2025-07-21T01:58:06Z" level=error msg="500 GET unable to retrieve storage" +time="2025-07-21T01:58:06Z" level=error msg="400 GET invalid pageSize: not_a_number" --- PASS: Test_checkChangefeedInputs (0.00s) === RUN TestMainHandlerGet -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:37297" http.request.id=e167901d-5f7c-495e-aff7-d58063a78c45 http.request.method=GET http.request.remoteaddr="127.0.0.1:52982" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="25.68µs" http.response.status=200 http.response.written=2 ---- PASS: TestMainHandlerGet (0.01s) +time="2025-07-21T01:58:06Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:45853" http.request.id=b21aa3be-a9a8-4e7d-8420-ae9984dfbaf7 http.request.method=GET http.request.remoteaddr="127.0.0.1:41346" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="28.32µs" http.response.status=200 http.response.written=2 +--- PASS: TestMainHandlerGet (0.00s) === RUN TestMainHandlerNotGet -time="2025-07-21T01:56:00Z" level=info msg="generic not found: You have requested a resource that does not exist.: " go.version=go1.22.4 http.request.host="127.0.0.1:40657" http.request.id=b073203c-6cd3-42d6-9bb8-89ec02490755 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:40314" http.request.uri=/ http.request.useragent=Go-http-client/1.1 -time="2025-07-21T01:56:00Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:40657" http.request.id=b073203c-6cd3-42d6-9bb8-89ec02490755 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:40314" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="157.88µs" http.response.status=404 http.response.written=105 +time="2025-07-21T01:58:06Z" level=info msg="generic not found: You have requested a resource that does not exist.: " go.version=go1.22.4 http.request.host="127.0.0.1:41059" http.request.id=468b1dbe-8e8c-457b-9157-21c1e22031bc http.request.method=HEAD http.request.remoteaddr="127.0.0.1:46386" http.request.uri=/ http.request.useragent=Go-http-client/1.1 +time="2025-07-21T01:58:06Z" level=info msg="response completed" go.version=go1.22.4 http.request.host="127.0.0.1:41059" http.request.id=468b1dbe-8e8c-457b-9157-21c1e22031bc http.request.method=HEAD http.request.remoteaddr="127.0.0.1:46386" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="153.12µs" http.response.status=404 http.response.written=105 --- PASS: TestMainHandlerNotGet (0.00s) === RUN TestKeyHandlersInvalidConfiguration -time="2025-07-21T01:56:00Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=error msg="500 GET storage not configured" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=error msg="500 GET storage not configured" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=error msg="500 POST storage not configured" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=error msg="500 POST storage not configured" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 GET storage not configured" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 GET storage not configured" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 POST storage not configured" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 POST storage not configured" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.4 gun=gun --- PASS: TestKeyHandlersInvalidConfiguration (0.00s) === RUN TestKeyHandlersNoRoleOrRepo -time="2025-07-21T01:56:00Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.4 -time="2025-07-21T01:56:00Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.4 -time="2025-07-21T01:56:00Z" level=info msg="400 GET no role in request" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=info msg="400 GET no role in request" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.4 -time="2025-07-21T01:56:00Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.4 -time="2025-07-21T01:56:00Z" level=info msg="400 POST no role in request" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=info msg="400 POST no role in request" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.4 +time="2025-07-21T01:58:06Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.4 +time="2025-07-21T01:58:06Z" level=info msg="400 GET no role in request" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="400 GET no role in request" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.4 +time="2025-07-21T01:58:06Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.4 +time="2025-07-21T01:58:06Z" level=info msg="400 POST no role in request" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="400 POST no role in request" go.version=go1.22.4 gun=gun --- PASS: TestKeyHandlersNoRoleOrRepo (0.00s) === RUN TestKeyHandlersInvalidRole -time="2025-07-21T01:56:00Z" level=info msg="400 GET root key: " go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=info msg="400 GET targets key: " go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=info msg="400 GET targets/a key: " go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=info msg="400 GET invalidrole key: " go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=info msg="400 POST root key: " go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=info msg="400 POST targets key: " go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=info msg="400 POST targets/a key: " go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=info msg="400 POST invalidrole key: " go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="400 GET root key: " go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="400 GET targets key: " go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="400 GET targets/a key: " go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="400 GET invalidrole key: " go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="400 POST root key: " go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="400 POST targets key: " go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="400 POST targets/a key: " go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="400 POST invalidrole key: " go.version=go1.22.4 gun=gun --- PASS: TestKeyHandlersInvalidRole (0.00s) === RUN TestGetKeyHandlerCreatesOnce --- PASS: TestGetKeyHandlerCreatesOnce (0.01s) === RUN TestKeyHandlersInvalidKeyAlgo -time="2025-07-21T01:56:00Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.4 gun=gun -time="2025-07-21T01:56:00Z" level=error msg="500 POST snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=error msg="500 POST snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.22.4 gun=gun --- PASS: TestKeyHandlersInvalidKeyAlgo (0.00s) === RUN TestRotateKeyHandlerSuccessfulRotation --- PASS: TestRotateKeyHandlerSuccessfulRotation (0.00s) === RUN TestGetHandlerRoot -time="2025-07-21T01:56:00Z" level=info msg="404 GET root role" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="404 GET root role" go.version=go1.22.4 gun=gun --- PASS: TestGetHandlerRoot (0.00s) === RUN TestGetHandlerTimestamp --- PASS: TestGetHandlerTimestamp (0.00s) === RUN TestGetHandlerSnapshot --- PASS: TestGetHandlerSnapshot (0.00s) === RUN TestGetHandler404 -time="2025-07-21T01:56:00Z" level=info msg="404 GET root role" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="404 GET root role" go.version=go1.22.4 gun=gun --- PASS: TestGetHandler404 (0.00s) === RUN TestGetHandlerNilData -time="2025-07-21T01:56:00Z" level=info msg="404 GET root role" go.version=go1.22.4 gun=gun +time="2025-07-21T01:58:06Z" level=info msg="404 GET root role" go.version=go1.22.4 gun=gun --- PASS: TestGetHandlerNilData (0.00s) === RUN TestGetHandlerNoStorage -time="2025-07-21T01:56:00Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.22.4 +time="2025-07-21T01:58:06Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.22.4 --- PASS: TestGetHandlerNoStorage (0.00s) === RUN TestAtomicUpdateValidationFailurePropagated --- PASS: TestAtomicUpdateValidationFailurePropagated (0.01s) === RUN TestAtomicUpdateNonValidationFailureNotPropagated -time="2025-07-21T01:56:00Z" level=info msg="400 POST error validating update" go.version=go1.22.4 testGUN=gun ---- PASS: TestAtomicUpdateNonValidationFailureNotPropagated (0.00s) +time="2025-07-21T01:58:06Z" level=info msg="400 POST error validating update" go.version=go1.22.4 testGUN=gun +--- PASS: TestAtomicUpdateNonValidationFailureNotPropagated (0.01s) === RUN TestAtomicUpdateVersionErrorPropagated -time="2025-07-21T01:56:00Z" level=info msg="400 POST old version error" go.version=go1.22.4 testGUN=gun +time="2025-07-21T01:58:06Z" level=info msg="400 POST old version error" go.version=go1.22.4 testGUN=gun --- PASS: TestAtomicUpdateVersionErrorPropagated (0.01s) === RUN TestGetMaybeServerSignedNoCrypto --- PASS: TestGetMaybeServerSignedNoCrypto (0.00s) @@ -2811,7 +2847,7 @@ === RUN TestValidatePrevTimestamp --- PASS: TestValidatePrevTimestamp (0.01s) === RUN TestValidatePreviousTimestampCorrupt -time="2025-07-21T01:56:00Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" +time="2025-07-21T01:58:06Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" --- PASS: TestValidatePreviousTimestampCorrupt (0.01s) === RUN TestValidateGetCurrentTimestampBroken --- PASS: TestValidateGetCurrentTimestampBroken (0.01s) @@ -2820,17 +2856,17 @@ === RUN TestValidateNoNewTargets --- PASS: TestValidateNoNewTargets (0.01s) === RUN TestValidateOnlySnapshot ---- PASS: TestValidateOnlySnapshot (0.00s) +--- PASS: TestValidateOnlySnapshot (0.01s) === RUN TestValidateOldRoot --- PASS: TestValidateOldRoot (0.01s) === RUN TestValidateOldRootCorrupt --- PASS: TestValidateOldRootCorrupt (0.00s) === RUN TestValidateOldRootCorruptRootRole ---- PASS: TestValidateOldRootCorruptRootRole (0.00s) +--- PASS: TestValidateOldRootCorruptRootRole (0.01s) === RUN TestValidateRootGetCurrentRootBroken --- PASS: TestValidateRootGetCurrentRootBroken (0.00s) === RUN TestValidateRootRotationWithOldSigs ---- PASS: TestValidateRootRotationWithOldSigs (0.02s) +--- PASS: TestValidateRootRotationWithOldSigs (0.03s) === RUN TestValidateRootRotationMultipleKeysThreshold1 --- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.01s) === RUN TestRootRotationNotSignedWithOldKeysForOldRole @@ -2838,15 +2874,15 @@ === RUN TestRootRotationVersionIncrement --- PASS: TestRootRotationVersionIncrement (0.01s) === RUN TestValidateNoRoot ---- PASS: TestValidateNoRoot (0.01s) +--- PASS: TestValidateNoRoot (0.00s) === RUN TestValidateSnapshotMissingNoSnapshotKey ---- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.01s) +--- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.00s) === RUN TestValidateSnapshotGenerateNoPrev --- PASS: TestValidateSnapshotGenerateNoPrev (0.01s) === RUN TestValidateSnapshotGenerateWithPrev --- PASS: TestValidateSnapshotGenerateWithPrev (0.01s) === RUN TestValidateSnapshotGeneratePrevCorrupt -time="2025-07-21T01:56:01Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" +time="2025-07-21T01:58:06Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" --- PASS: TestValidateSnapshotGeneratePrevCorrupt (0.01s) === RUN TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken --- PASS: TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken (0.01s) @@ -2869,21 +2905,21 @@ === RUN TestValidateSnapshotRoleMissing --- PASS: TestValidateSnapshotRoleMissing (0.00s) === RUN TestValidateRootSigMissing ---- PASS: TestValidateRootSigMissing (0.01s) +--- PASS: TestValidateRootSigMissing (0.00s) === RUN TestValidateTargetsSigMissing -time="2025-07-21T01:56:01Z" level=error msg="ErrBadTargets: tuf: data has no signatures" +time="2025-07-21T01:58:06Z" level=error msg="ErrBadTargets: tuf: data has no signatures" --- PASS: TestValidateTargetsSigMissing (0.00s) === RUN TestValidateSnapshotSigMissing --- PASS: TestValidateSnapshotSigMissing (0.00s) === RUN TestValidateRootCorrupt --- PASS: TestValidateRootCorrupt (0.00s) === RUN TestValidateTargetsCorrupt -time="2025-07-21T01:56:01Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" +time="2025-07-21T01:58:06Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" --- PASS: TestValidateTargetsCorrupt (0.00s) === RUN TestValidateSnapshotCorrupt ---- PASS: TestValidateSnapshotCorrupt (0.01s) +--- PASS: TestValidateSnapshotCorrupt (0.00s) === RUN TestValidateRootModifiedSize ---- PASS: TestValidateRootModifiedSize (0.00s) +--- PASS: TestValidateRootModifiedSize (0.01s) === RUN TestValidateTargetsModifiedSize --- PASS: TestValidateTargetsModifiedSize (0.01s) === RUN TestValidateRootModifiedHash @@ -2897,36 +2933,36 @@ === RUN TestLoadTargetsLoadsNothingIfNoUpdates --- PASS: TestLoadTargetsLoadsNothingIfNoUpdates (0.00s) === RUN TestValidateTargetsRequiresStoredParent -time="2025-07-21T01:56:01Z" level=error msg="ErrBadTargets: targets must be loaded first" +time="2025-07-21T01:58:06Z" level=error msg="ErrBadTargets: targets must be loaded first" --- PASS: TestValidateTargetsRequiresStoredParent (0.01s) === RUN TestValidateTargetsParentInUpdate -time="2025-07-21T01:56:01Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" ---- PASS: TestValidateTargetsParentInUpdate (0.01s) +time="2025-07-21T01:58:06Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" +--- PASS: TestValidateTargetsParentInUpdate (0.00s) === RUN TestValidateTargetsRoleNotInParent -time="2025-07-21T01:56:01Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" -time="2025-07-21T01:56:01Z" level=error msg="ErrBadTargets: targets has already been loaded" -time="2025-07-21T01:56:01Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +time="2025-07-21T01:58:06Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +time="2025-07-21T01:58:06Z" level=error msg="ErrBadTargets: targets has already been loaded" +time="2025-07-21T01:58:06Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" --- PASS: TestValidateTargetsRoleNotInParent (0.01s) PASS -ok github.com/theupdateframework/notary/server/handlers 0.457s +ok github.com/theupdateframework/notary/server/handlers 0.442s === RUN TestSnapshotExpired --- PASS: TestSnapshotExpired (0.00s) === RUN TestSnapshotNotExpired --- PASS: TestSnapshotNotExpired (0.00s) === RUN TestGetSnapshotKeyCreate ---- PASS: TestGetSnapshotKeyCreate (0.01s) +--- PASS: TestGetSnapshotKeyCreate (0.00s) === RUN TestGetSnapshotKeyCreateWithFailingStore -time="2025-07-21T01:56:01Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2025-07-21T01:58:06Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetSnapshotKeyCreateWithFailingStore (0.00s) === RUN TestGetSnapshotKeyCreateWithCorruptedStore -time="2025-07-21T01:56:01Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve snapshot key ID" +time="2025-07-21T01:58:06Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve snapshot key ID" --- PASS: TestGetSnapshotKeyCreateWithCorruptedStore (0.00s) === RUN TestGetSnapshotKeyCreateWithInvalidAlgo --- PASS: TestGetSnapshotKeyCreateWithInvalidAlgo (0.00s) === RUN TestGetSnapshotKeyExistingMetadata --- PASS: TestGetSnapshotKeyExistingMetadata (0.00s) === RUN TestGetSnapshotNoPreviousSnapshot -time="2025-07-21T01:56:01Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" +time="2025-07-21T01:58:06Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" --- PASS: TestGetSnapshotNoPreviousSnapshot (0.00s) === RUN TestGetSnapshotReturnsPreviousSnapshotIfUnexpired --- PASS: TestGetSnapshotReturnsPreviousSnapshotIfUnexpired (0.00s) @@ -2937,7 +2973,7 @@ === RUN TestCreateSnapshotNoKeyInCrypto --- PASS: TestCreateSnapshotNoKeyInCrypto (0.00s) PASS -ok github.com/theupdateframework/notary/server/snapshot 0.064s +ok github.com/theupdateframework/notary/server/snapshot 0.070s === RUN TestMemoryUpdateCurrentEmpty --- PASS: TestMemoryUpdateCurrentEmpty (0.00s) === RUN TestMemoryUpdateCurrentVersionCheckOldVersionExists @@ -2991,7 +3027,7 @@ === RUN TestSQLDBGetVersion --- PASS: TestSQLDBGetVersion (0.00s) PASS -ok github.com/theupdateframework/notary/server/storage 0.143s +ok github.com/theupdateframework/notary/server/storage 0.114s === RUN TestTimestampExpired --- PASS: TestTimestampExpired (0.00s) === RUN TestTimestampNotExpired @@ -2999,31 +3035,31 @@ === RUN TestGetTimestampKey --- PASS: TestGetTimestampKey (0.01s) === RUN TestGetTimestampNoPreviousTimestamp -time="2025-07-21T01:56:02Z" level=error msg="Failed to unmarshal existing timestamp" +time="2025-07-21T01:58:06Z" level=error msg="Failed to unmarshal existing timestamp" --- PASS: TestGetTimestampNoPreviousTimestamp (0.00s) === RUN TestGetTimestampReturnsPreviousTimestampIfUnexpired --- PASS: TestGetTimestampReturnsPreviousTimestampIfUnexpired (0.00s) === RUN TestGetTimestampOldTimestampExpired ---- PASS: TestGetTimestampOldTimestampExpired (0.01s) +--- PASS: TestGetTimestampOldTimestampExpired (0.00s) === RUN TestCannotMakeNewTimestampIfNoRootOrSnapshot -time="2025-07-21T01:56:03Z" level=error msg="Failed to create a new timestamp" -time="2025-07-21T01:56:03Z" level=error msg="Failed to create a new timestamp" ---- PASS: TestCannotMakeNewTimestampIfNoRootOrSnapshot (0.01s) +time="2025-07-21T01:58:06Z" level=error msg="Failed to create a new timestamp" +time="2025-07-21T01:58:06Z" level=error msg="Failed to create a new timestamp" +--- PASS: TestCannotMakeNewTimestampIfNoRootOrSnapshot (0.00s) === RUN TestCreateTimestampNoKeyInCrypto -time="2025-07-21T01:56:03Z" level=error msg="Failed to create a new timestamp" ---- PASS: TestCreateTimestampNoKeyInCrypto (0.02s) +time="2025-07-21T01:58:06Z" level=error msg="Failed to create a new timestamp" +--- PASS: TestCreateTimestampNoKeyInCrypto (0.00s) === RUN TestGetTimestampKeyCreateWithFailingStore -time="2025-07-21T01:56:03Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2025-07-21T01:58:06Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetTimestampKeyCreateWithFailingStore (0.00s) === RUN TestGetTimestampKeyCreateWithCorruptedStore -time="2025-07-21T01:56:03Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve timestamp key ID" +time="2025-07-21T01:58:06Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve timestamp key ID" --- PASS: TestGetTimestampKeyCreateWithCorruptedStore (0.00s) === RUN TestGetTimestampKeyCreateWithInvalidAlgo --- PASS: TestGetTimestampKeyCreateWithInvalidAlgo (0.00s) === RUN TestGetTimestampKeyExistingMetadata ---- PASS: TestGetTimestampKeyExistingMetadata (0.00s) +--- PASS: TestGetTimestampKeyExistingMetadata (0.01s) PASS -ok github.com/theupdateframework/notary/server/timestamp 0.133s +ok github.com/theupdateframework/notary/server/timestamp 0.078s === RUN TestGetSuccessPopulatesCache --- PASS: TestGetSuccessPopulatesCache (0.00s) === RUN TestAddKeyPopulatesCacheIfSuccessful @@ -3038,30 +3074,30 @@ `nodb` is not officially supported, running under compatibility mode. --- PASS: TestNewSQLKeyDBStorePropagatesDBError (0.00s) === RUN TestSQLDBHealthCheckMissingTable ---- PASS: TestSQLDBHealthCheckMissingTable (0.01s) +--- PASS: TestSQLDBHealthCheckMissingTable (0.00s) === RUN TestSQLDBHealthCheckNoConnection --- PASS: TestSQLDBHealthCheckNoConnection (0.00s) === RUN TestSQLKeyCanOnlyBeAddedOnce (/build/reproducible-path/notary-0.7.0+git20240416.9d2b3b3+ds1/_build/src/github.com/theupdateframework/notary/signer/keydbstore/sql_keydbstore.go:98) -[2025-07-21 01:56:03]  UNIQUE constraint failed: private_keys.key_id  ---- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.04s) +[2025-07-21 01:58:07]  UNIQUE constraint failed: private_keys.key_id  +--- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.02s) === RUN TestSQLCreateDelete ---- PASS: TestSQLCreateDelete (0.05s) +--- PASS: TestSQLCreateDelete (0.02s) === RUN TestSQLKeyRotation ---- PASS: TestSQLKeyRotation (0.04s) +--- PASS: TestSQLKeyRotation (0.05s) === RUN TestSQLSigningMarksKeyActive -time="2025-07-21T01:56:03Z" level=error msg="Key f00ffb674bf737dea1fbb7f081c3935cc353cf7328b3ffc19bf9a8f466711c42 was just used to sign hash successful, db closed, error when trying to mark key as active: sql: database is closed" ---- PASS: TestSQLSigningMarksKeyActive (0.05s) +time="2025-07-21T01:58:07Z" level=error msg="Key e2c88cd2a89a94d496f2234b08ae9a780b35263adc4897123ad52281017452d3 was just used to sign hash successful, db closed, error when trying to mark key as active: sql: database is closed" +--- PASS: TestSQLSigningMarksKeyActive (0.08s) === RUN TestSQLCreateKey (/build/reproducible-path/notary-0.7.0+git20240416.9d2b3b3+ds1/_build/src/github.com/theupdateframework/notary/signer/keydbstore/sql_keydbstore.go:197) -[2025-07-21 01:56:03]  sql: database is closed  +[2025-07-21 01:58:07]  sql: database is closed  --- PASS: TestSQLCreateKey (0.06s) === RUN TestSQLUnimplementedInterfaceBehavior ---- PASS: TestSQLUnimplementedInterfaceBehavior (0.02s) +--- PASS: TestSQLUnimplementedInterfaceBehavior (0.01s) PASS -ok github.com/theupdateframework/notary/signer/keydbstore 0.357s +ok github.com/theupdateframework/notary/signer/keydbstore 0.304s === RUN TestSet --- PASS: TestSet (0.00s) === RUN TestSetWithNoParentDirectory @@ -3089,7 +3125,7 @@ === RUN TestGetData --- PASS: TestGetData (0.00s) === RUN TestCreateDirectory ---- PASS: TestCreateDirectory (0.00s) +--- PASS: TestCreateDirectory (0.01s) === RUN TestCreatePrivateDirectory --- PASS: TestCreatePrivateDirectory (0.00s) === RUN TestFileStoreConsistency @@ -3103,7 +3139,7 @@ === RUN Test404Error --- PASS: Test404Error (0.00s) === RUN Test50XErrors ---- PASS: Test50XErrors (0.01s) +--- PASS: Test50XErrors (0.00s) === RUN Test400Error --- PASS: Test400Error (0.00s) === RUN TestTranslateErrorsParse400Errors @@ -3119,7 +3155,7 @@ === RUN TestHTTPStoreGetKey --- PASS: TestHTTPStoreGetKey (0.00s) === RUN TestHTTPStoreGetRotateKeySizeLimited ---- PASS: TestHTTPStoreGetRotateKeySizeLimited (0.02s) +--- PASS: TestHTTPStoreGetRotateKeySizeLimited (0.03s) === RUN TestHTTPOffline --- PASS: TestHTTPOffline (0.00s) === RUN TestErrServerUnavailable @@ -3140,7 +3176,7 @@ === RUN TestMemoryStoreMetadata --- PASS: TestMemoryStoreMetadata (0.00s) PASS -ok github.com/theupdateframework/notary/storage 0.107s +ok github.com/theupdateframework/notary/storage 0.101s === RUN TestExportKeys --- PASS: TestExportKeys (0.00s) === RUN TestExportKeysByGUN @@ -3164,7 +3200,7 @@ === RUN TestImportKeys2InOneFileNoPath --- PASS: TestImportKeys2InOneFileNoPath (0.00s) === RUN TestEncryptedKeyImportFail -time="2025-07-21T01:56:04Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" +time="2025-07-21T01:58:07Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" --- PASS: TestEncryptedKeyImportFail (0.00s) === RUN TestEncryptedKeyImportSuccess --- PASS: TestEncryptedKeyImportSuccess (0.00s) @@ -3185,7 +3221,7 @@ === RUN TestAddGetKeyInfoMemStore --- PASS: TestAddGetKeyInfoMemStore (0.00s) === RUN TestGetDecryptedWithTamperedCipherText -time="2025-07-21T01:56:04Z" level=error msg="PEM block is empty" +time="2025-07-21T01:58:07Z" level=error msg="PEM block is empty" --- PASS: TestGetDecryptedWithTamperedCipherText (0.00s) === RUN TestGetDecryptedWithInvalidPassphrase --- PASS: TestGetDecryptedWithInvalidPassphrase (0.01s) @@ -3196,71 +3232,71 @@ === RUN TestKeysAreCached --- PASS: TestKeysAreCached (0.00s) PASS -ok github.com/theupdateframework/notary/trustmanager 0.109s +ok github.com/theupdateframework/notary/trustmanager 0.099s === RUN TestRemoteStore -time="2025-07-21T01:56:04Z" level=info msg="listing files from localhost:9888" -time="2025-07-21T01:56:04Z" level=info msg="listing files from localhost:9888" ---- PASS: TestRemoteStore (0.08s) +time="2025-07-21T01:58:07Z" level=info msg="listing files from localhost:9888" +time="2025-07-21T01:58:07Z" level=info msg="listing files from localhost:9888" +--- PASS: TestRemoteStore (0.06s) === RUN TestErrors -time="2025-07-21T01:56:04Z" level=error msg="failed to store: test error" ---- PASS: TestErrors (0.07s) +time="2025-07-21T01:58:07Z" level=error msg="failed to store: test error" +--- PASS: TestErrors (0.03s) === RUN TestNewGRPCStorage --- PASS: TestNewGRPCStorage (0.00s) === RUN TestGRPCStorage --- PASS: TestGRPCStorage (0.00s) PASS -ok github.com/theupdateframework/notary/trustmanager/remoteks 0.213s +ok github.com/theupdateframework/notary/trustmanager/remoteks 0.143s === RUN TestWildcardMatch --- PASS: TestWildcardMatch (0.00s) === RUN TestValidateRoot -2025/07/21 01:56:04 [INFO] generate received request -2025/07/21 01:56:04 [INFO] received CSR -2025/07/21 01:56:04 [INFO] generating key: ecdsa-256 -2025/07/21 01:56:04 [INFO] encoded CSR -2025/07/21 01:56:04 [INFO] signed certificate with serial number 683498939741029010088210402694341169739528008255 -2025/07/21 01:56:04 [INFO] received CSR -2025/07/21 01:56:04 [INFO] generating key: ecdsa-256 -2025/07/21 01:56:04 [INFO] encoded CSR -2025/07/21 01:56:04 [INFO] signed certificate with serial number 701961839628146682735431433395293012505416982273 -2025/07/21 01:56:04 [INFO] received CSR -2025/07/21 01:56:04 [INFO] generating key: ecdsa-256 -2025/07/21 01:56:04 [INFO] encoded CSR -2025/07/21 01:56:04 [INFO] signed certificate with serial number 270809311277196074085179676047444458198684356312 ---- PASS: TestValidateRoot (0.03s) +2025/07/21 01:58:08 [INFO] generate received request +2025/07/21 01:58:08 [INFO] received CSR +2025/07/21 01:58:08 [INFO] generating key: ecdsa-256 +2025/07/21 01:58:08 [INFO] encoded CSR +2025/07/21 01:58:08 [INFO] signed certificate with serial number 714402346768943520588066197504452710026130670718 +2025/07/21 01:58:08 [INFO] received CSR +2025/07/21 01:58:08 [INFO] generating key: ecdsa-256 +2025/07/21 01:58:08 [INFO] encoded CSR +2025/07/21 01:58:08 [INFO] signed certificate with serial number 505845188343256171237486198953711734293738315561 +2025/07/21 01:58:08 [INFO] received CSR +2025/07/21 01:58:08 [INFO] generating key: ecdsa-256 +2025/07/21 01:58:08 [INFO] encoded CSR +2025/07/21 01:58:08 [INFO] signed certificate with serial number 400899706280254459901288276993238594300804558212 +--- PASS: TestValidateRoot (0.01s) === RUN TestValidateRootWithoutTOFUS --- PASS: TestValidateRootWithoutTOFUS (0.00s) === RUN TestValidateRootWithPinnedCert --- PASS: TestValidateRootWithPinnedCert (0.00s) === RUN TestValidateRootWithPinnedCertAndIntermediates ---- PASS: TestValidateRootWithPinnedCertAndIntermediates (0.02s) +--- PASS: TestValidateRootWithPinnedCertAndIntermediates (0.01s) === RUN TestValidateRootFailuresWithPinnedCert ---- PASS: TestValidateRootFailuresWithPinnedCert (0.01s) +--- PASS: TestValidateRootFailuresWithPinnedCert (0.00s) === RUN TestValidateRootWithPinnedCA ---- PASS: TestValidateRootWithPinnedCA (0.04s) +--- PASS: TestValidateRootWithPinnedCA (0.02s) === RUN TestValidateSuccessfulRootRotation ---- PASS: TestValidateSuccessfulRootRotation (0.06s) +--- PASS: TestValidateSuccessfulRootRotation (0.04s) === RUN TestValidateRootRotationMissingOrigSig ---- PASS: TestValidateRootRotationMissingOrigSig (0.04s) +--- PASS: TestValidateRootRotationMissingOrigSig (0.02s) === RUN TestValidateRootRotationMissingNewSig ---- PASS: TestValidateRootRotationMissingNewSig (0.05s) +--- PASS: TestValidateRootRotationMissingNewSig (0.02s) === RUN TestValidateRootRotationTrustPinning ---- PASS: TestValidateRootRotationTrustPinning (0.05s) +--- PASS: TestValidateRootRotationTrustPinning (0.03s) === RUN TestValidateRootRotationTrustPinningInvalidCA ---- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.04s) +--- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.02s) === RUN TestParsePEMPublicKey -time="2025-07-21T01:56:04Z" level=warning msg="certificate with CN notary is near expiry" +time="2025-07-21T01:58:08Z" level=warning msg="certificate with CN notary is near expiry" --- PASS: TestParsePEMPublicKey (0.00s) === RUN TestCheckingCertExpiry -time="2025-07-21T01:56:04Z" level=warning msg="certificate with CN notary is near expiry" ---- PASS: TestCheckingCertExpiry (0.01s) +time="2025-07-21T01:58:08Z" level=warning msg="certificate with CN notary is near expiry" +--- PASS: TestCheckingCertExpiry (0.00s) === RUN TestValidateRootWithExpiredIntermediate ---- PASS: TestValidateRootWithExpiredIntermediate (0.01s) +--- PASS: TestValidateRootWithExpiredIntermediate (0.00s) === RUN TestCheckingWildcardCert --- PASS: TestCheckingWildcardCert (0.00s) === RUN TestWildcardMatching --- PASS: TestWildcardMatching (0.00s) PASS -ok github.com/theupdateframework/notary/trustpinning 0.402s +ok github.com/theupdateframework/notary/trustpinning 0.205s === RUN TestInitSnapshotNoTargets --- PASS: TestInitSnapshotNoTargets (0.01s) === RUN TestInitRepo @@ -3268,11 +3304,11 @@ === RUN TestUpdateDelegations --- PASS: TestUpdateDelegations (0.00s) === RUN TestPurgeDelegationsKeyFromTop -time="2025-07-21T01:56:04Z" level=warning msg="role targets/sybil has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-07-21T01:56:04Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" ---- PASS: TestPurgeDelegationsKeyFromTop (0.01s) +time="2025-07-21T01:58:08Z" level=warning msg="role targets/sybil has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2025-07-21T01:58:08Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +--- PASS: TestPurgeDelegationsKeyFromTop (0.00s) === RUN TestPurgeDelegationsKeyFromDeep -time="2025-07-21T01:56:04Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2025-07-21T01:58:08Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" --- PASS: TestPurgeDelegationsKeyFromDeep (0.00s) === RUN TestPurgeDelegationsKeyBadWildRole --- PASS: TestPurgeDelegationsKeyBadWildRole (0.00s) @@ -3285,7 +3321,7 @@ === RUN TestUpdateDelegationsRoleThatIsMissingDelegationKey --- PASS: TestUpdateDelegationsRoleThatIsMissingDelegationKey (0.00s) === RUN TestUpdateDelegationsNotEnoughKeys -time="2025-07-21T01:56:04Z" level=warning msg="role targets/role has fewer keys than its threshold of 2; it will not be usable until keys are added to it" +time="2025-07-21T01:58:08Z" level=warning msg="role targets/role has fewer keys than its threshold of 2; it will not be usable until keys are added to it" --- PASS: TestUpdateDelegationsNotEnoughKeys (0.00s) === RUN TestUpdateDelegationsAddKeyToRole --- PASS: TestUpdateDelegationsAddKeyToRole (0.00s) @@ -3304,7 +3340,7 @@ === RUN TestDeleteDelegationsMidSliceRole --- PASS: TestDeleteDelegationsMidSliceRole (0.00s) === RUN TestGetDelegationRoleAndMetadataExistDelegationExists ---- PASS: TestGetDelegationRoleAndMetadataExistDelegationExists (0.01s) +--- PASS: TestGetDelegationRoleAndMetadataExistDelegationExists (0.00s) === RUN TestGetDelegationRoleAndMetadataExistDelegationDoesntExists --- PASS: TestGetDelegationRoleAndMetadataExistDelegationDoesntExists (0.00s) === RUN TestGetDelegationRoleAndMetadataDoesntExists @@ -3314,7 +3350,7 @@ === RUN TestAddTargetsRoleAndMetadataExist --- PASS: TestAddTargetsRoleAndMetadataExist (0.00s) === RUN TestAddTargetsRoleExistsAndMetadataDoesntExist ---- PASS: TestAddTargetsRoleExistsAndMetadataDoesntExist (0.01s) +--- PASS: TestAddTargetsRoleExistsAndMetadataDoesntExist (0.00s) === RUN TestAddTargetsRoleDoesntExist --- PASS: TestAddTargetsRoleDoesntExist (0.00s) === RUN TestAddTargetsNoSigningKeys @@ -3326,11 +3362,11 @@ === RUN TestRemoveTargetsNoSigningKeys --- PASS: TestRemoveTargetsNoSigningKeys (0.00s) === RUN TestAddBaseKeysToRoot ---- PASS: TestAddBaseKeysToRoot (0.01s) +--- PASS: TestAddBaseKeysToRoot (0.00s) === RUN TestRemoveBaseKeysFromRoot --- PASS: TestRemoveBaseKeysFromRoot (0.00s) === RUN TestReplaceBaseKeysInRoot ---- PASS: TestReplaceBaseKeysInRoot (0.01s) +--- PASS: TestReplaceBaseKeysInRoot (0.00s) === RUN TestGetAllRoles --- PASS: TestGetAllRoles (0.00s) === RUN TestGetBaseRoles @@ -3354,31 +3390,31 @@ === RUN TestSignRootOldKeyCertExists --- PASS: TestSignRootOldKeyCertExists (0.01s) === RUN TestSignRootOldKeyCertMissing ---- PASS: TestSignRootOldKeyCertMissing (0.02s) +--- PASS: TestSignRootOldKeyCertMissing (0.01s) === RUN TestRootKeyRotation ---- PASS: TestRootKeyRotation (0.06s) +--- PASS: TestRootKeyRotation (0.02s) === RUN TestBuilderLoadsValidRolesOnly --- PASS: TestBuilderLoadsValidRolesOnly (0.00s) === RUN TestBuilderOnlyAcceptsRootFirstWhenLoading --- PASS: TestBuilderOnlyAcceptsRootFirstWhenLoading (0.00s) === RUN TestBuilderOnlyAcceptsDelegationsAfterParent ---- PASS: TestBuilderOnlyAcceptsDelegationsAfterParent (0.01s) +--- PASS: TestBuilderOnlyAcceptsDelegationsAfterParent (0.00s) === RUN TestMarkingIsValid ---- PASS: TestMarkingIsValid (0.01s) +--- PASS: TestMarkingIsValid (0.00s) === RUN TestBuilderLoadInvalidDelegations --- PASS: TestBuilderLoadInvalidDelegations (0.00s) === RUN TestBuilderLoadInvalidDelegationsOldVersion ---- PASS: TestBuilderLoadInvalidDelegationsOldVersion (0.01s) +--- PASS: TestBuilderLoadInvalidDelegationsOldVersion (0.00s) === RUN TestBuilderAcceptRoleOnce --- PASS: TestBuilderAcceptRoleOnce (0.00s) === RUN TestBuilderStopsAcceptingOrProducingDataOnceDone ---- PASS: TestBuilderStopsAcceptingOrProducingDataOnceDone (0.01s) +--- PASS: TestBuilderStopsAcceptingOrProducingDataOnceDone (0.00s) === RUN TestGenerateSnapshotInvalidOperations ---- PASS: TestGenerateSnapshotInvalidOperations (0.03s) +--- PASS: TestGenerateSnapshotInvalidOperations (0.04s) === RUN TestGenerateTimestampInvalidOperations --- PASS: TestGenerateTimestampInvalidOperations (0.02s) === RUN TestGetConsistentInfo ---- PASS: TestGetConsistentInfo (0.01s) +--- PASS: TestGetConsistentInfo (0.00s) === RUN TestTimestampPreAndPostChecksumming --- PASS: TestTimestampPreAndPostChecksumming (0.01s) === RUN TestSnapshotLoadedFirstChecksumsOthers @@ -3386,7 +3422,7 @@ === RUN TestSnapshotLoadedAfterChecksumsOthersRetroactively --- PASS: TestSnapshotLoadedAfterChecksumsOthersRetroactively (0.02s) PASS -ok github.com/theupdateframework/notary/tuf 0.361s +ok github.com/theupdateframework/notary/tuf 0.203s === RUN TestMergeStrSlicesExclusive --- PASS: TestMergeStrSlicesExclusive (0.00s) === RUN TestMergeStrSlicesOverlap @@ -3434,13 +3470,13 @@ === RUN TestRootFromSignedCopiesSignatures --- PASS: TestRootFromSignedCopiesSignatures (0.00s) === RUN TestRootFromSignedValidatesRoleData ---- PASS: TestRootFromSignedValidatesRoleData (0.01s) +--- PASS: TestRootFromSignedValidatesRoleData (0.00s) === RUN TestRootFromSignedValidatesRoleType --- PASS: TestRootFromSignedValidatesRoleType (0.00s) === RUN TestRootFromSignedValidatesVersion --- PASS: TestRootFromSignedValidatesVersion (0.00s) === RUN TestSnapshotToSignedMarshalsSignedPortionWithCanonicalJSON ---- PASS: TestSnapshotToSignedMarshalsSignedPortionWithCanonicalJSON (0.01s) +--- PASS: TestSnapshotToSignedMarshalsSignedPortionWithCanonicalJSON (0.00s) === RUN TestSnapshotToSignCopiesSignatures --- PASS: TestSnapshotToSignCopiesSignatures (0.00s) === RUN TestSnapshotToSignedMarshallingErrorsPropagated @@ -3482,7 +3518,7 @@ === RUN TestTargetsFromSignedValidatesRoleName --- PASS: TestTargetsFromSignedValidatesRoleName (0.00s) === RUN TestTargetsFromSignedValidatesVersion ---- PASS: TestTargetsFromSignedValidatesVersion (0.02s) +--- PASS: TestTargetsFromSignedValidatesVersion (0.00s) === RUN TestTimestampToSignedMarshalsSignedPortionWithCanonicalJSON --- PASS: TestTimestampToSignedMarshalsSignedPortionWithCanonicalJSON (0.00s) === RUN TestTimestampToSignCopiesSignatures @@ -3520,9 +3556,9 @@ === RUN TestFileMetaEquals --- PASS: TestFileMetaEquals (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/data 0.076s +ok github.com/theupdateframework/notary/tuf/data 0.030s === RUN TestListKeys ---- PASS: TestListKeys (0.01s) +--- PASS: TestListKeys (0.00s) === RUN TestGetKeys --- PASS: TestGetKeys (0.00s) === RUN TestBasicSign @@ -3534,7 +3570,7 @@ === RUN TestSignReturnsNoSigs --- PASS: TestSignReturnsNoSigs (0.00s) === RUN TestSignWithX509 ---- PASS: TestSignWithX509 (0.01s) +--- PASS: TestSignWithX509 (0.00s) === RUN TestSignRemovesValidSigByInvalidKey --- PASS: TestSignRemovesValidSigByInvalidKey (0.00s) === RUN TestSignRemovesInvalidSig @@ -3546,13 +3582,13 @@ === RUN TestErrInsufficientSignaturesMessaging --- PASS: TestErrInsufficientSignaturesMessaging (0.00s) === RUN TestRSAPSSVerifier ---- PASS: TestRSAPSSVerifier (0.01s) +--- PASS: TestRSAPSSVerifier (0.00s) === RUN TestRSAPSSx509Verifier --- PASS: TestRSAPSSx509Verifier (0.00s) === RUN TestRSAPSSVerifierWithInvalidKeyType --- PASS: TestRSAPSSVerifierWithInvalidKeyType (0.00s) === RUN TestRSAPSSVerifierWithInvalidKeyLength ---- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.03s) +--- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.04s) === RUN TestRSAPSSVerifierWithInvalidKey --- PASS: TestRSAPSSVerifierWithInvalidKey (0.00s) === RUN TestRSAPSSVerifierWithInvalidSignature @@ -3566,12 +3602,12 @@ === RUN TestRSAPKCS1v15VerifierWithInvalidKey --- PASS: TestRSAPKCS1v15VerifierWithInvalidKey (0.00s) === RUN TestRSAPKCS1v15VerifierWithInvalidSignature -time="2025-07-21T01:56:04Z" level=error msg="Failed verification: crypto/rsa: verification error" +time="2025-07-21T01:58:08Z" level=error msg="Failed verification: crypto/rsa: verification error" --- PASS: TestRSAPKCS1v15VerifierWithInvalidSignature (0.00s) === RUN TestECDSAVerifier --- PASS: TestECDSAVerifier (0.00s) === RUN TestECDSAVerifierOtherCurves ---- PASS: TestECDSAVerifierOtherCurves (0.04s) +--- PASS: TestECDSAVerifierOtherCurves (0.03s) === RUN TestECDSAx509Verifier --- PASS: TestECDSAx509Verifier (0.00s) === RUN TestECDSAVerifierWithInvalidKeyType @@ -3609,54 +3645,54 @@ === RUN TestVerifyVersion --- PASS: TestVerifyVersion (0.00s) === RUN TestVerifyExpiry -time="2025-07-21T01:56:05Z" level=error msg="Metadata for root expired" +time="2025-07-21T01:58:08Z" level=error msg="Metadata for root expired" --- PASS: TestVerifyExpiry (0.00s) === RUN TestVerifyPublicKeyMatchesPrivateKeyHappyCase --- PASS: TestVerifyPublicKeyMatchesPrivateKeyHappyCase (0.00s) === RUN TestVerifyPublicKeyMatchesPrivateKeyFails --- PASS: TestVerifyPublicKeyMatchesPrivateKeyFails (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/signed 0.192s +ok github.com/theupdateframework/notary/tuf/signed 0.145s === RUN TestNewSwizzler --- PASS: TestNewSwizzler (0.01s) === RUN TestSwizzlerSetInvalidJSON ---- PASS: TestSwizzlerSetInvalidJSON (0.01s) +--- PASS: TestSwizzlerSetInvalidJSON (0.00s) === RUN TestSwizzlerAddExtraSpace --- PASS: TestSwizzlerAddExtraSpace (0.01s) === RUN TestSwizzlerSetInvalidSigned ---- PASS: TestSwizzlerSetInvalidSigned (0.01s) +--- PASS: TestSwizzlerSetInvalidSigned (0.00s) === RUN TestSwizzlerSetInvalidSignedMeta ---- PASS: TestSwizzlerSetInvalidSignedMeta (0.01s) +--- PASS: TestSwizzlerSetInvalidSignedMeta (0.00s) === RUN TestSwizzlerSetInvalidMetadataType ---- PASS: TestSwizzlerSetInvalidMetadataType (0.01s) +--- PASS: TestSwizzlerSetInvalidMetadataType (0.00s) === RUN TestSwizzlerInvalidateMetadataSignatures ---- PASS: TestSwizzlerInvalidateMetadataSignatures (0.01s) +--- PASS: TestSwizzlerInvalidateMetadataSignatures (0.00s) === RUN TestSwizzlerRemoveMetadata --- PASS: TestSwizzlerRemoveMetadata (0.00s) === RUN TestSwizzlerSignMetadataWithInvalidKey ---- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.02s) +--- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.00s) === RUN TestSwizzlerOffsetMetadataVersion ---- PASS: TestSwizzlerOffsetMetadataVersion (0.01s) +--- PASS: TestSwizzlerOffsetMetadataVersion (0.02s) === RUN TestSwizzlerExpireMetadata ---- PASS: TestSwizzlerExpireMetadata (0.01s) +--- PASS: TestSwizzlerExpireMetadata (0.00s) === RUN TestSwizzlerSetThresholdBaseRole ---- PASS: TestSwizzlerSetThresholdBaseRole (0.01s) +--- PASS: TestSwizzlerSetThresholdBaseRole (0.00s) === RUN TestSwizzlerSetThresholdDelegatedRole ---- PASS: TestSwizzlerSetThresholdDelegatedRole (0.01s) +--- PASS: TestSwizzlerSetThresholdDelegatedRole (0.00s) === RUN TestSwizzlerChangeRootKey --- PASS: TestSwizzlerChangeRootKey (0.01s) === RUN TestSwizzlerUpdateSnapshotHashesSpecifiedRoles ---- PASS: TestSwizzlerUpdateSnapshotHashesSpecifiedRoles (0.01s) +--- PASS: TestSwizzlerUpdateSnapshotHashesSpecifiedRoles (0.00s) === RUN TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles --- PASS: TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles (0.00s) === RUN TestSwizzlerUpdateTimestamp ---- PASS: TestSwizzlerUpdateTimestamp (0.01s) +--- PASS: TestSwizzlerUpdateTimestamp (0.00s) === RUN TestMissingSigningKey ---- PASS: TestMissingSigningKey (0.00s) +--- PASS: TestMissingSigningKey (0.01s) === RUN TestSwizzlerMutateRoot ---- PASS: TestSwizzlerMutateRoot (0.01s) +--- PASS: TestSwizzlerMutateRoot (0.00s) === RUN TestSwizzlerMutateTimestamp ---- PASS: TestSwizzlerMutateTimestamp (0.01s) +--- PASS: TestSwizzlerMutateTimestamp (0.00s) === RUN TestSwizzlerMutateSnapshot --- PASS: TestSwizzlerMutateSnapshot (0.00s) === RUN TestSwizzlerMutateTargets @@ -3666,11 +3702,11 @@ === RUN TestSwizzlerRotateKeyDelegationRole --- PASS: TestSwizzlerRotateKeyDelegationRole (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/testutils 0.244s +ok github.com/theupdateframework/notary/tuf/testutils 0.153s === RUN TestConvertTUFKeyToPKCS8 ---- PASS: TestConvertTUFKeyToPKCS8 (0.02s) +--- PASS: TestConvertTUFKeyToPKCS8 (0.01s) === RUN TestParsePKCS8ToTufKey ---- PASS: TestParsePKCS8ToTufKey (0.04s) +--- PASS: TestParsePKCS8ToTufKey (0.03s) === RUN TestPEMtoPEM --- PASS: TestPEMtoPEM (0.00s) === RUN TestRoleListLen @@ -3732,9 +3768,9 @@ === RUN TestValidateCertificateWithInvalidExpiry --- PASS: TestValidateCertificateWithInvalidExpiry (0.00s) === RUN TestValidateCertificateWithShortKey ---- PASS: TestValidateCertificateWithShortKey (0.08s) +--- PASS: TestValidateCertificateWithShortKey (0.05s) PASS -ok github.com/theupdateframework/notary/tuf/utils 0.179s +ok github.com/theupdateframework/notary/tuf/utils 0.146s === RUN TestNewSerializableErrorNonValidationError --- PASS: TestNewSerializableErrorNonValidationError (0.00s) === RUN TestNewSerializableErrorValidationError @@ -3750,7 +3786,7 @@ === RUN TestUnmarshalInvalidJSON --- PASS: TestUnmarshalInvalidJSON (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/validation 0.020s +ok github.com/theupdateframework/notary/tuf/validation 0.036s === RUN TestSetSignalTrap --- PASS: TestSetSignalTrap (0.00s) === RUN TestLogLevelSignalHandle @@ -3850,7 +3886,7 @@ === RUN TestDoAuthWildcardImage --- PASS: TestDoAuthWildcardImage (0.00s) PASS -ok github.com/theupdateframework/notary/utils 0.036s +ok github.com/theupdateframework/notary/utils 0.020s create-stamp debian/debhelper-build-stamp dh_testroot -O--builddirectory=/build/reproducible-path/notary-0.7.0\+git20240416.9d2b3b3\+ds1/_build -O--buildsystem=golang dh_prep -O--builddirectory=/build/reproducible-path/notary-0.7.0\+git20240416.9d2b3b3\+ds1/_build -O--buildsystem=golang @@ -3897,12 +3933,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/1266493/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/1266493/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/2328772 and its subdirectories -I: Current time: Sun Jul 20 13:56:44 -12 2025 -I: pbuilder-time-stamp: 1753063004 +I: removing directory /srv/workspace/pbuilder/1266493 and its subdirectories +I: Current time: Mon Jul 21 15:58:57 +14 2025 +I: pbuilder-time-stamp: 1753063137