Diff of the two buildlogs: -- --- b1/build.log 2024-05-02 01:34:55.828824316 +0000 +++ b2/build.log 2024-05-02 01:37:02.103447970 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Tue Jun 3 19:55:49 -12 2025 -I: pbuilder-time-stamp: 1749023749 +I: Current time: Wed Jun 4 21:57:59 +14 2025 +I: pbuilder-time-stamp: 1749023879 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/trixie-reproducible-base.tgz] I: copying local configuration @@ -31,52 +31,84 @@ dpkg-source: info: applying 0004-Use-github.com-golang-protobuf-ptypes-empty.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/1512115/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/955172/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 Jun 4 07:58 /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/955172/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/955172/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='trixie' - HOME='/root' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION=trixie + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - INVOCATION_ID='cc85ff399e65442094dae642e8529fd3' - 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='1512115' - PS1='# ' - PS2='> ' + INVOCATION_ID=5ce471828438494aa72f54d1af8c4bfd + 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=955172 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.ETWzyvWk/pbuilderrc_xRVp --distribution trixie --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.ETWzyvWk/b1 --logfile b1/build.log notary_0.7.0+ds1-2.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.ETWzyvWk/pbuilderrc_hfCi --distribution trixie --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.ETWzyvWk/b2 --logfile b2/build.log notary_0.7.0+ds1-2.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-20-cloud-arm64 #1 SMP Debian 6.1.85-1 (2024-04-11) aarch64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-20-cloud-arm64 #1 SMP Debian 6.1.85-1 (2024-04-11) aarch64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 24 13:38 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/1512115/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 May 26 17:47 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/955172/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -377,7 +409,7 @@ Get: 188 http://deb.debian.org/debian trixie/main arm64 golang-goprotobuf-dev arm64 1.3.5-4+b6 [3432 B] Get: 189 http://deb.debian.org/debian trixie/main arm64 golang-gopkg-rethinkdb-rethinkdb-go.v6-dev all 6.2.1-2 [106 kB] Get: 190 http://deb.debian.org/debian trixie/main arm64 openssl arm64 3.2.1-3 [1324 kB] -Fetched 107 MB in 1s (108 MB/s) +Fetched 107 MB in 1s (202 MB/s) debconf: delaying package configuration, since apt-utils is not installed dpkg: libssl3:arm64: dependency problems, but removing anyway as you requested: libkrb5-3:arm64 depends on libssl3 (>= 3.0.0). @@ -1159,7 +1191,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/notary-0.7.0+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+ds1-2_source.changes +I: user script /srv/workspace/pbuilder/955172/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/955172/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/notary-0.7.0+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+ds1-2_source.changes dpkg-buildpackage: info: source package notary dpkg-buildpackage: info: source version 0.7.0+ds1-2 dpkg-buildpackage: info: source distribution unstable @@ -1439,42 +1475,42 @@ 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 -internal/coverage/rtcov -internal/goarch internal/unsafeheader -internal/goos -internal/godebugs +internal/goarch math/bits -unicode/utf8 -internal/cpu -internal/race internal/goexperiment +internal/godebugs +internal/goos internal/itoa -internal/abi -internal/chacha8rand +internal/coverage/rtcov +unicode runtime/internal/syscall -sync/atomic +internal/abi +internal/race runtime/internal/math runtime/internal/sys -unicode +internal/chacha8rand +internal/cpu +unicode/utf8 +sync/atomic cmp +math encoding unicode/utf16 log/internal -math crypto/internal/alias -crypto/subtle slices -crypto/internal/boring/sig vendor/golang.org/x/crypto/cryptobyte/asn1 internal/nettrace +internal/bytealg +runtime/internal/atomic container/list vendor/golang.org/x/crypto/internal/alias github.com/pelletier/go-toml/v2/internal/characters +crypto/internal/boring/sig google.golang.org/grpc/serviceconfig +crypto/subtle google.golang.org/protobuf/internal/flags -internal/bytealg -runtime/internal/atomic google.golang.org/protobuf/internal/set github.com/theupdateframework/notary/version github.com/lib/pq/oid @@ -1483,121 +1519,120 @@ runtime/metrics sync internal/testlog -internal/singleflight internal/bisect github.com/spf13/viper/internal/encoding google.golang.org/grpc/internal/buffer -google.golang.org/grpc/internal/grpcsync google.golang.org/protobuf/internal/pragma +google.golang.org/grpc/internal/grpcsync +internal/singleflight runtime/cgo errors sort internal/godebug -io internal/oserror -internal/safefilepath strconv -github.com/beorn7/perks/quantile +internal/safefilepath +io +path +syscall internal/intern crypto/internal/nistec/fiat +github.com/hashicorp/hcl/hcl/strconv vendor/golang.org/x/net/dns/dnsmessage math/rand -github.com/hashicorp/hcl/hcl/strconv hash -path -syscall strings -crypto/internal/randutil +text/tabwriter +github.com/beorn7/perks/quantile bytes +crypto/internal/randutil hash/crc32 hash/fnv -text/tabwriter crypto reflect +golang.org/x/crypto/blowfish net/netip crypto/rc4 -golang.org/x/crypto/blowfish -encoding/base32 vendor/golang.org/x/text/transform -github.com/dvsekhvalnov/jose2go/padding golang.org/x/text/transform +encoding/base32 +github.com/dvsekhvalnov/jose2go/padding bufio regexp/syntax net/http/internal/ascii +golang.org/x/text/runes html google.golang.org/grpc/encoding github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg -golang.org/x/text/runes net/http/internal/testcert internal/syscall/unix time internal/syscall/execenv regexp github.com/jinzhu/inflection -internal/fmtsort -encoding/binary internal/poll -google.golang.org/grpc/backoff -google.golang.org/grpc/internal/grpcrand github.com/bugsnag/bugsnag-go/headers io/fs +google.golang.org/grpc/backoff +google.golang.org/grpc/internal/grpcrand context google.golang.org/grpc/keepalive google.golang.org/grpc/internal/backoff -embed -github.com/spf13/afero/internal/common golang.org/x/net/context google.golang.org/grpc/tap gopkg.in/rethinkdb/rethinkdb-go.v6/vendor/gopkg.in/cenkalti/backoff.v2 +embed +github.com/spf13/afero/internal/common +os +encoding/binary +internal/fmtsort google.golang.org/protobuf/internal/editiondefaults +crypto/md5 encoding/base64 crypto/cipher crypto/internal/edwards25519/field -vendor/golang.org/x/crypto/internal/poly1305 +golang.org/x/sys/unix github.com/cespare/xxhash crypto/internal/nistec -golang.org/x/sys/unix -crypto/md5 -os +vendor/golang.org/x/crypto/internal/poly1305 encoding/pem github.com/dvsekhvalnov/jose2go/base64url crypto/internal/boring crypto/des vendor/golang.org/x/crypto/chacha20 -github.com/dvsekhvalnov/jose2go/compact crypto/internal/edwards25519 +github.com/dvsekhvalnov/jose2go/compact crypto/aes -crypto/sha512 crypto/sha256 -crypto/hmac crypto/sha1 +crypto/hmac +crypto/sha512 golang.org/x/crypto/pbkdf2 -vendor/golang.org/x/crypto/hkdf vendor/golang.org/x/crypto/chacha20poly1305 -crypto/ecdh +vendor/golang.org/x/crypto/hkdf github.com/theupdateframework/notary +fmt io/ioutil -google.golang.org/grpc/internal/envconfig -path/filepath github.com/bugsnag/bugsnag-go/device -fmt +net +path/filepath os/signal -google.golang.org/protobuf/internal/detrand +google.golang.org/grpc/internal/envconfig github.com/prometheus/procfs/internal/util +google.golang.org/protobuf/internal/detrand internal/lazyregexp internal/sysinfo -net +crypto/ecdh github.com/spf13/afero/mem os/exec -github.com/bugsnag/panicwrap encoding/json -log github.com/docker/go/canonical/json -net/url -encoding/hex +log math/big -mime/quotedprintable +encoding/hex +net/url mime +mime/quotedprintable compress/flate vendor/golang.org/x/text/unicode/norm vendor/golang.org/x/net/http2/hpack @@ -1605,147 +1640,148 @@ vendor/golang.org/x/text/unicode/bidi github.com/miekg/pkcs11 flag +compress/gzip encoding/csv golang.org/x/text/unicode/norm -compress/gzip -vendor/golang.org/x/text/secure/bidirule text/template/parse +vendor/golang.org/x/text/secure/bidirule github.com/spf13/jwalterweatherman github.com/subosito/gotenv github.com/hashicorp/hcl/hcl/token gopkg.in/ini.v1 +github.com/pelletier/go-toml/v2/internal/danger github.com/hashicorp/hcl/hcl/ast github.com/hashicorp/hcl/hcl/scanner -github.com/theupdateframework/notary/tuf/validation +vendor/golang.org/x/net/idna github.com/hashicorp/hcl/json/token -github.com/sirupsen/logrus -github.com/fsnotify/fsnotify -github.com/spf13/viper/internal/encoding/json +github.com/pelletier/go-toml/v2/unstable +github.com/theupdateframework/notary/tuf/validation github.com/hashicorp/hcl/hcl/parser -vendor/golang.org/x/net/idna -github.com/pelletier/go-toml/v2/internal/danger github.com/hashicorp/hcl/json/scanner -github.com/pelletier/go-toml/v2/unstable -github.com/hashicorp/hcl/hcl/printer +github.com/spf13/viper/internal/encoding/json gopkg.in/yaml.v3 github.com/hashicorp/hcl/json/parser +github.com/sirupsen/logrus +github.com/fsnotify/fsnotify text/template +github.com/hashicorp/hcl/hcl/printer +github.com/hashicorp/hcl +github.com/pelletier/go-toml/v2/internal/tracker +github.com/golang/protobuf/proto crypto/rand crypto/elliptic crypto/internal/bigmod crypto/internal/boring/bbig +github.com/docker/distribution/uuid encoding/asn1 crypto/dsa -github.com/hashicorp/hcl -github.com/pelletier/go-toml/v2/internal/tracker -github.com/docker/distribution/uuid crypto/ed25519 -github.com/golang/protobuf/proto -golang.org/x/net/internal/timeseries github.com/spf13/viper/internal/encoding/hcl -google.golang.org/grpc/internal/grpclog -github.com/pelletier/go-toml/v2 golang.org/x/crypto/ed25519 +github.com/pelletier/go-toml/v2 +golang.org/x/net/internal/timeseries +google.golang.org/grpc/internal/grpclog google.golang.org/grpc/attributes -google.golang.org/grpc/grpclog +crypto/rsa google.golang.org/grpc/metadata google.golang.org/grpc/codes golang.org/x/text/unicode/bidi -crypto/rsa -vendor/golang.org/x/crypto/cryptobyte -crypto/x509/pkix +google.golang.org/grpc/grpclog google.golang.org/grpc/internal/balancerload golang.org/x/net/http2/hpack -google.golang.org/grpc/connectivity -google.golang.org/grpc/internal github.com/pkg/errors +github.com/bugsnag/panicwrap html/template +google.golang.org/grpc/connectivity database/sql/driver -github.com/opencontainers/go-digest -golang.org/x/text/secure/bidirule github.com/bugsnag/bugsnag-go/errors -github.com/docker/distribution/digestset -crypto/ecdsa +google.golang.org/grpc/internal +golang.org/x/text/secure/bidirule +github.com/opencontainers/go-digest +vendor/golang.org/x/crypto/cryptobyte +crypto/x509/pkix +golang.org/x/net/idna github.com/opencontainers/image-spec/specs-go +database/sql +github.com/docker/distribution/digestset github.com/opencontainers/image-spec/specs-go/v1 +github.com/spf13/viper/internal/encoding/toml google.golang.org/protobuf/internal/errors go/token -database/sql -github.com/docker/distribution/reference -golang.org/x/net/idna -github.com/spf13/viper/internal/encoding/toml -google.golang.org/protobuf/encoding/protowire -google.golang.org/protobuf/internal/version -google.golang.org/protobuf/reflect/protoreflect github.com/spf13/viper/internal/encoding/yaml +google.golang.org/protobuf/internal/version +google.golang.org/protobuf/encoding/protowire +github.com/docker/distribution/reference github.com/prometheus/procfs/internal/fs runtime/debug +google.golang.org/protobuf/reflect/protoreflect golang.org/x/term +crypto/ecdsa +github.com/spf13/cast golang.org/x/crypto/bcrypt github.com/lib/pq/scram os/user go/scanner github.com/hailocab/go-hostpool +github.com/theupdateframework/notary/passphrase github.com/opentracing/opentracing-go/log -github.com/spf13/cast +github.com/spf13/viper/internal/encoding/dotenv +github.com/spf13/viper/internal/encoding/ini gopkg.in/rethinkdb/rethinkdb-go.v6/encoding -github.com/theupdateframework/notary/passphrase +go/ast +gopkg.in/rethinkdb/rethinkdb-go.v6/types +internal/profile google.golang.org/protobuf/internal/descfmt -google.golang.org/protobuf/internal/descopts google.golang.org/protobuf/internal/strs +google.golang.org/protobuf/internal/descopts google.golang.org/protobuf/internal/encoding/messageset -google.golang.org/protobuf/internal/genid -google.golang.org/protobuf/internal/order google.golang.org/protobuf/internal/encoding/text +google.golang.org/protobuf/internal/order +google.golang.org/protobuf/internal/genid google.golang.org/protobuf/runtime/protoiface google.golang.org/protobuf/reflect/protoregistry -go/ast -gopkg.in/rethinkdb/rethinkdb-go.v6/types -internal/profile -github.com/spf13/viper/internal/encoding/dotenv -github.com/spf13/viper/internal/encoding/ini runtime/pprof runtime/trace github.com/dvsekhvalnov/jose2go/arrays -google.golang.org/protobuf/proto -github.com/dvsekhvalnov/jose2go/aes -github.com/dvsekhvalnov/jose2go/kdf -github.com/davecgh/go-spew/spew -google.golang.org/protobuf/internal/encoding/defval -github.com/pmezard/go-difflib/difflib -testing github.com/golang/protobuf/ptypes/empty google.golang.org/grpc/encoding/proto github.com/golang/protobuf/ptypes/any github.com/golang/protobuf/ptypes/duration github.com/golang/protobuf/ptypes/timestamp -github.com/jinzhu/gorm +google.golang.org/protobuf/proto gopkg.in/rethinkdb/rethinkdb-go.v6/ql2 +google.golang.org/protobuf/internal/encoding/defval google.golang.org/genproto/googleapis/rpc/status +github.com/dvsekhvalnov/jose2go/aes +github.com/dvsekhvalnov/jose2go/kdf +github.com/jinzhu/gorm github.com/golang/protobuf/ptypes google.golang.org/grpc/binarylog/grpc_binarylog_v1 +github.com/davecgh/go-spew/spew +github.com/pmezard/go-difflib/difflib +testing +google.golang.org/grpc/internal/status +google.golang.org/grpc/status google.golang.org/protobuf/internal/filedesc google.golang.org/protobuf/encoding/prototext google.golang.org/protobuf/encoding/protodelim -google.golang.org/grpc/internal/status -google.golang.org/grpc/status google.golang.org/protobuf/internal/encoding/tag google.golang.org/protobuf/internal/impl vendor/golang.org/x/net/http/httpproxy -net/textproto github.com/mitchellh/mapstructure -google.golang.org/grpc/internal/syscall crypto/x509 +google.golang.org/grpc/internal/syscall github.com/spf13/pflag google.golang.org/grpc/stats -github.com/prometheus/procfs 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/theupdateframework/notary/tuf/data github.com/dvsekhvalnov/jose2go/keys/ecc crypto/tls -github.com/theupdateframework/notary/tuf/data github.com/dvsekhvalnov/jose2go github.com/spf13/cobra github.com/theupdateframework/notary/client/changelist @@ -1758,11 +1794,11 @@ net/http/httptrace google.golang.org/grpc/internal/credentials github.com/docker/go-connections/tlsconfig +github.com/go-sql-driver/mysql github.com/docker/libtrust github.com/lib/pq -github.com/go-sql-driver/mysql -github.com/prometheus/client_golang/prometheus/internal net/http +github.com/prometheus/client_golang/prometheus/internal google.golang.org/grpc/credentials google.golang.org/grpc/resolver google.golang.org/grpc/internal/channelz @@ -1772,82 +1808,82 @@ google.golang.org/grpc/balancer/grpclb/state google.golang.org/grpc/internal/resolver/passthrough google.golang.org/grpc/internal/resolver/dns +google.golang.org/grpc/internal/binarylog google.golang.org/grpc/balancer/base google.golang.org/grpc/internal/serviceconfig -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 +net/http/httputil github.com/bugsnag/bugsnag-go/sessions github.com/gorilla/mux -github.com/spf13/afero -golang.org/x/net/http2 -net/http/httputil github.com/docker/distribution/registry/api/errcode -github.com/docker/distribution/registry/auth -github.com/docker/distribution/registry/client/auth/challenge +github.com/theupdateframework/notary/storage github.com/magiconair/properties +github.com/docker/distribution/registry/auth +golang.org/x/net/http2 github.com/docker/distribution +github.com/docker/distribution/registry/client/auth/challenge github.com/docker/distribution/registry/client/transport expvar +github.com/bugsnag/bugsnag-go github.com/prometheus/common/expfmt +github.com/theupdateframework/notary/trustmanager github.com/theupdateframework/notary/server/errors github.com/opentracing/opentracing-go -github.com/bugsnag/bugsnag-go -github.com/theupdateframework/notary/trustmanager -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/opentracing/opentracing-go/ext +github.com/docker/distribution/context github.com/theupdateframework/notary/cryptoservice github.com/theupdateframework/notary/tuf/signed -gopkg.in/rethinkdb/rethinkdb-go.v6 +github.com/docker/distribution/registry/api/v2 +github.com/opentracing/opentracing-go/ext github.com/Shopify/logrus-bugsnag +github.com/spf13/viper/internal/encoding/javaproperties +gopkg.in/rethinkdb/rethinkdb-go.v6 github.com/docker/distribution/health +github.com/theupdateframework/notary/trustmanager/yubikey +github.com/theupdateframework/notary/tuf/testutils/keys +github.com/theupdateframework/notary/trustpinning +github.com/stretchr/testify/assert github.com/docker/distribution/registry/auth/htpasswd github.com/docker/distribution/registry/auth/token +github.com/spf13/viper github.com/prometheus/client_golang/prometheus github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus -github.com/theupdateframework/notary/trustmanager/yubikey -github.com/theupdateframework/notary/trustpinning -github.com/theupdateframework/notary/tuf/testutils/keys -github.com/stretchr/testify/assert github.com/theupdateframework/notary/tuf -github.com/spf13/viper +github.com/theupdateframework/notary/utils github.com/theupdateframework/notary/client github.com/theupdateframework/notary/tuf/testutils -github.com/theupdateframework/notary/utils github.com/stretchr/testify/require -google.golang.org/grpc/internal/transport github.com/prometheus/client_golang/prometheus/promhttp -github.com/theupdateframework/notary/storage/rethinkdb github.com/theupdateframework/notary/tuf/testutils/interfaces -github.com/theupdateframework/notary/signer/keydbstore -github.com/theupdateframework/notary/server/storage +google.golang.org/grpc/internal/transport +github.com/theupdateframework/notary/storage/rethinkdb github.com/docker/go-metrics -github.com/theupdateframework/notary/server/snapshot -github.com/theupdateframework/notary/server/timestamp -google.golang.org/grpc +github.com/theupdateframework/notary/server/storage +github.com/theupdateframework/notary/signer/keydbstore github.com/docker/distribution/metrics github.com/docker/distribution/registry/storage/cache -github.com/theupdateframework/notary/server/handlers +github.com/theupdateframework/notary/server/snapshot github.com/docker/distribution/registry/storage/cache/memory +github.com/theupdateframework/notary/server/timestamp github.com/docker/distribution/registry/client +github.com/theupdateframework/notary/server/handlers +google.golang.org/grpc github.com/theupdateframework/notary/server github.com/docker/distribution/registry/client/auth github.com/theupdateframework/notary/cmd/notary github.com/theupdateframework/notary/trustmanager/remoteks -google.golang.org/grpc/health/grpc_health_v1 github.com/theupdateframework/notary/proto -google.golang.org/grpc/health +google.golang.org/grpc/health/grpc_health_v1 github.com/theupdateframework/notary/cmd/escrow -github.com/theupdateframework/notary/signer/client github.com/theupdateframework/notary/signer +github.com/theupdateframework/notary/signer/client +google.golang.org/grpc/health github.com/theupdateframework/notary/signer/api -github.com/theupdateframework/notary/cmd/notary-server github.com/theupdateframework/notary/cmd/notary-signer +github.com/theupdateframework/notary/cmd/notary-server make[1]: Leaving directory '/build/reproducible-path/notary-0.7.0+ds1' dh_auto_test -O--builddirectory=/build/reproducible-path/notary-0.7.0\+ds1/_build -O--buildsystem=golang cd _build && go test -vet=off -v -p 12 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 @@ -1864,15 +1900,15 @@ === RUN Test0Dot1Migration --- PASS: Test0Dot1Migration (0.01s) === RUN Test0Dot3Migration ---- PASS: Test0Dot3Migration (0.01s) +--- PASS: Test0Dot3Migration (0.00s) === RUN Test0Dot1RepoFormat -time="2025-06-04T07:56:58Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot1RepoFormat (0.14s) +time="2025-06-04T07:59:13Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot1RepoFormat (0.09s) === RUN Test0Dot3RepoFormat -time="2025-06-04T07:56:58Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot3RepoFormat (0.13s) +time="2025-06-04T07:59:14Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot3RepoFormat (0.10s) === RUN TestDownloading0Dot1RepoFormat ---- PASS: TestDownloading0Dot1RepoFormat (0.01s) +--- PASS: TestDownloading0Dot1RepoFormat (0.00s) === RUN TestDownloading0Dot3RepoFormat --- PASS: TestDownloading0Dot3RepoFormat (0.01s) === RUN TestInitRepositoryManagedRolesIncludingRoot @@ -1886,17 +1922,17 @@ === RUN TestInitRepositoryWithCerts --- PASS: TestInitRepositoryWithCerts (0.01s) === RUN TestMatchKeyIDsWithPublicKeys ---- PASS: TestMatchKeyIDsWithPublicKeys (0.01s) +--- PASS: TestMatchKeyIDsWithPublicKeys (0.00s) === RUN TestInitRepositoryNeedsRemoteTimestampKey --- PASS: TestInitRepositoryNeedsRemoteTimestampKey (0.01s) === RUN TestInitRepositoryNeedsRemoteSnapshotKey ---- PASS: TestInitRepositoryNeedsRemoteSnapshotKey (0.01s) +--- PASS: TestInitRepositoryNeedsRemoteSnapshotKey (0.00s) === RUN TestInitRepoServerOnlyManagesTimestampKey ---- PASS: TestInitRepoServerOnlyManagesTimestampKey (0.08s) +--- PASS: TestInitRepoServerOnlyManagesTimestampKey (0.05s) === RUN TestInitRepoServerManagesTimestampAndSnapshotKeys ---- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.06s) +--- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.04s) === RUN TestInitRepoAttemptsExceeded ---- PASS: TestInitRepoAttemptsExceeded (0.04s) +--- PASS: TestInitRepoAttemptsExceeded (0.02s) === RUN TestInitRepoPasswordInvalid --- PASS: TestInitRepoPasswordInvalid (0.01s) === RUN TestAddTargetToTargetRoleByDefault @@ -1904,7 +1940,7 @@ === RUN TestAddTargetToSpecifiedValidRoles --- PASS: TestAddTargetToSpecifiedValidRoles (0.02s) === RUN TestAddTargetToSpecifiedInvalidRoles ---- PASS: TestAddTargetToSpecifiedInvalidRoles (0.02s) +--- PASS: TestAddTargetToSpecifiedInvalidRoles (0.01s) === RUN TestAddTargetWithInvalidTarget --- PASS: TestAddTargetWithInvalidTarget (0.01s) === RUN TestAddTargetErrorWritingChanges @@ -1912,286 +1948,286 @@ === RUN TestRemoveTargetToTargetRoleByDefault --- PASS: TestRemoveTargetToTargetRoleByDefault (0.01s) === RUN TestRemoveTargetFromSpecifiedValidRoles ---- PASS: TestRemoveTargetFromSpecifiedValidRoles (0.02s) +--- PASS: TestRemoveTargetFromSpecifiedValidRoles (0.01s) === RUN TestRemoveTargetToSpecifiedInvalidRoles --- PASS: TestRemoveTargetToSpecifiedInvalidRoles (0.02s) === RUN TestRemoveTargetErrorWritingChanges ---- PASS: TestRemoveTargetErrorWritingChanges (0.02s) +--- PASS: TestRemoveTargetErrorWritingChanges (0.01s) === RUN TestListTarget ---- PASS: TestListTarget (0.26s) +--- PASS: TestListTarget (0.28s) === RUN TestListTargetRestrictsDelegationPaths ---- PASS: TestListTargetRestrictsDelegationPaths (0.04s) +--- PASS: TestListTargetRestrictsDelegationPaths (0.07s) === RUN TestValidateRootKey ---- PASS: TestValidateRootKey (0.02s) +--- PASS: TestValidateRootKey (0.04s) === RUN TestGetChangelist ---- PASS: TestGetChangelist (0.02s) +--- PASS: TestGetChangelist (0.03s) === RUN TestPublishBareRepo ---- PASS: TestPublishBareRepo (0.24s) +--- PASS: TestPublishBareRepo (0.34s) === RUN TestPublishUninitializedRepo -time="2025-06-04T07:56:59Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +time="2025-06-04T07:59:15Z" 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.26s) === RUN TestPublishAfterInitServerHasSnapshotKey ---- PASS: TestPublishAfterInitServerHasSnapshotKey (0.22s) +--- PASS: TestPublishAfterInitServerHasSnapshotKey (0.21s) === RUN TestPublishAfterPullServerHasSnapshotKey --- PASS: TestPublishAfterPullServerHasSnapshotKey (0.07s) === RUN TestPublishNoOneHasSnapshotKey ---- PASS: TestPublishNoOneHasSnapshotKey (0.06s) +--- PASS: TestPublishNoOneHasSnapshotKey (0.05s) === RUN TestPublishSnapshotCorrupt --- PASS: TestPublishSnapshotCorrupt (0.12s) === RUN TestPublishTargetsCorrupt --- PASS: TestPublishTargetsCorrupt (0.05s) === RUN TestPublishRootCorrupt -time="2025-06-04T07:57:00Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" -time="2025-06-04T07:57:00Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" ---- PASS: TestPublishRootCorrupt (0.05s) +time="2025-06-04T07:59:15Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +time="2025-06-04T07:59:15Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +--- PASS: TestPublishRootCorrupt (0.04s) === RUN TestPublishSnapshotLocalKeysCreatedFirst --- PASS: TestPublishSnapshotLocalKeysCreatedFirst (0.00s) === RUN TestPublishDelegations ---- PASS: TestPublishDelegations (0.08s) +--- PASS: TestPublishDelegations (0.06s) === RUN TestPublishDelegationsX509 ---- PASS: TestPublishDelegationsX509 (0.09s) +--- PASS: TestPublishDelegationsX509 (0.10s) === RUN TestPublishTargetsDelegationScopeFailIfNoKeys -time="2025-06-04T07:57:00Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" -time="2025-06-04T07:57:00Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" ---- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (0.07s) +time="2025-06-04T07:59:16Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" +time="2025-06-04T07:59:16Z" 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) === RUN TestPublishTargetsDelegationSuccessLocallyHasRoles ---- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (0.07s) +--- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (0.09s) === RUN TestPublishTargetsDelegationNoTargetsKeyNeeded ---- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (0.07s) +--- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (0.08s) === RUN TestPublishTargetsDelegationSuccessNeedsToDownloadRoles --- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (0.08s) === RUN TestPublishTargetsDelegationFromTwoRepos ---- PASS: TestPublishTargetsDelegationFromTwoRepos (0.07s) +--- PASS: TestPublishTargetsDelegationFromTwoRepos (0.08s) === RUN TestPublishRemoveDelegationKeyFromDelegationRole -time="2025-06-04T07:57:01Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-06-04T07:57:01Z" 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: 4ed53e049c0e68a492a66a60d5f1d01db06e7a8313dd7a65bab40129655b2ab6" ---- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.04s) +time="2025-06-04T07:59:16Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-06-04T07:59:16Z" 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: fd010fb88df339ae92146a675a4bedf13bd82d6ed1596d140859361ed594773a" +--- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.08s) === RUN TestPublishRemoveDelegation -time="2025-06-04T07:57:01Z" 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-06-04T07:57:01Z" 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-06-04T07:57:01Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-06-04T07:57:01Z" 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) +time="2025-06-04T07:59:16Z" 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-06-04T07:59:16Z" 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-06-04T07:59:16Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-06-04T07:59:16Z" 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) === RUN TestPublishSucceedsDespiteDelegationCorrupt ---- PASS: TestPublishSucceedsDespiteDelegationCorrupt (0.06s) +--- PASS: TestPublishSucceedsDespiteDelegationCorrupt (0.08s) === RUN TestRotateKeyInvalidRole --- PASS: TestRotateKeyInvalidRole (0.02s) === RUN TestRemoteRotationError ---- PASS: TestRemoteRotationError (0.00s) +--- PASS: TestRemoteRotationError (0.01s) === RUN TestRemoteRotationEndpointError --- PASS: TestRemoteRotationEndpointError (0.02s) === RUN TestRemoteRotationNoRootKey ---- PASS: TestRemoteRotationNoRootKey (0.03s) +--- PASS: TestRemoteRotationNoRootKey (0.04s) === RUN TestRemoteRotationNoInit -time="2025-06-04T07:57:01Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" ---- PASS: TestRemoteRotationNoInit (0.02s) +time="2025-06-04T07:59:16Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +--- PASS: TestRemoteRotationNoInit (0.03s) === RUN TestRotateBeforePublishFromRemoteKeyToLocalKey ---- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (0.08s) +--- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (0.12s) === RUN TestRotateKeyAfterPublishNoServerManagementChange --- PASS: TestRotateKeyAfterPublishNoServerManagementChange (0.25s) === RUN TestRotateKeyAfterPublishServerManagementChange ---- PASS: TestRotateKeyAfterPublishServerManagementChange (0.34s) +--- PASS: TestRotateKeyAfterPublishServerManagementChange (0.28s) === RUN TestRotateRootKey ---- PASS: TestRotateRootKey (0.14s) +--- PASS: TestRotateRootKey (0.08s) === RUN TestRotateRootMultiple ---- PASS: TestRotateRootMultiple (0.22s) +--- PASS: TestRotateRootMultiple (0.12s) === RUN TestRotateRootKeyProvided ---- PASS: TestRotateRootKeyProvided (0.17s) +--- PASS: TestRotateRootKeyProvided (0.11s) === RUN TestRotateRootKeyLegacySupport ---- PASS: TestRotateRootKeyLegacySupport (0.25s) +--- PASS: TestRotateRootKeyLegacySupport (0.14s) === RUN TestRemoteServerUnavailableNoLocalCache -time="2025-06-04T07:57:02Z" level=error msg="Could not publish Repository since we could not update: unable to reach trust server at this time: 500." +time="2025-06-04T07:59:17Z" 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.02s) +--- PASS: TestAddDelegationChangefileValid (0.01s) === RUN TestAddDelegationChangefileApplicable ---- PASS: TestAddDelegationChangefileApplicable (0.02s) +--- PASS: TestAddDelegationChangefileApplicable (0.01s) === RUN TestAddDelegationErrorWritingChanges ---- PASS: TestAddDelegationErrorWritingChanges (0.02s) +--- PASS: TestAddDelegationErrorWritingChanges (0.01s) === RUN TestRemoveDelegationChangefileValid ---- PASS: TestRemoveDelegationChangefileValid (0.02s) +--- PASS: TestRemoveDelegationChangefileValid (0.01s) === RUN TestRemoveDelegationChangefileApplicable -time="2025-06-04T07:57: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-06-04T07:57: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" ---- PASS: TestRemoveDelegationChangefileApplicable (0.02s) +time="2025-06-04T07:59:18Z" 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-06-04T07:59:18Z" 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.02s) +--- 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.02s) +--- PASS: TestRemoveDelegationErrorWritingChanges (0.01s) === RUN TestClientInvalidURL --- PASS: TestClientInvalidURL (0.00s) === RUN TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole ---- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (0.31s) +--- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (0.16s) === RUN TestDeleteRepo ---- PASS: TestDeleteRepo (0.04s) +--- PASS: TestDeleteRepo (0.01s) === RUN TestDeleteRemoteRepo ---- PASS: TestDeleteRemoteRepo (0.11s) +--- PASS: TestDeleteRemoteRepo (0.06s) === RUN TestListRoles ---- PASS: TestListRoles (0.21s) +--- PASS: TestListRoles (0.11s) === RUN TestGetAllTargetInfo ---- PASS: TestGetAllTargetInfo (0.10s) +--- PASS: TestGetAllTargetInfo (0.05s) === RUN TestUpdateSucceedsEvenIfCannotWriteNewRepo -time="2025-06-04T07:57:03Z" level=error msg="could not save root to cache: Non-writable" ---- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.11s) +time="2025-06-04T07:59:18Z" level=error msg="could not save root to cache: Non-writable" +--- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.06s) === RUN TestUpdateSucceedsEvenIfCannotWriteExistingRepo ---- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (0.48s) +--- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (0.29s) === RUN TestUpdateInOfflineMode -time="2025-06-04T07:57:04Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" -time="2025-06-04T07:57:04Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateInOfflineMode (0.04s) +time="2025-06-04T07:59:18Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +time="2025-06-04T07:59:18Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateInOfflineMode (0.01s) === RUN TestUpdateReplacesCorruptOrMissingMetadata -time="2025-06-04T07:57:04Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:04Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateReplacesCorruptOrMissingMetadata (0.85s) +time="2025-06-04T07:59:19Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:19Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateReplacesCorruptOrMissingMetadata (0.48s) === RUN TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign -time="2025-06-04T07:57:05Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:05Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.09s) +time="2025-06-04T07:59:19Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:19Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.05s) === RUN TestUpdateRemoteRootNotExistNoLocalCache ---- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.03s) +--- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.01s) === RUN TestUpdateRemoteRootNotExistCanUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.06s) +--- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.04s) === RUN TestUpdateRemoteRootNotExistCannotUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.08s) +--- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.03s) === RUN TestUpdateRemoteRoot50XNoLocalCache ---- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.03s) +--- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.01s) === RUN TestUpdateRemoteRoot50XCanUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.07s) +--- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.03s) === RUN TestUpdateRemoteRoot50XCannotUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.08s) +--- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.05s) === RUN TestUpdateNonRootRemoteMissingMetadataNoLocalCache ---- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (0.16s) +--- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (0.10s) === RUN TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache -time="2025-06-04T07:57:05Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (0.23s) +time="2025-06-04T07:59:19Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (0.14s) === RUN TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache -time="2025-06-04T07:57:06Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (0.29s) +time="2025-06-04T07:59:19Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (0.18s) === RUN TestUpdateNonRootRemote50XNoLocalCache ---- PASS: TestUpdateNonRootRemote50XNoLocalCache (0.16s) +--- PASS: TestUpdateNonRootRemote50XNoLocalCache (0.10s) === RUN TestUpdateNonRootRemote50XCanUseLocalCache -time="2025-06-04T07:57:06Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (0.22s) +time="2025-06-04T07:59:20Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (0.11s) === RUN TestUpdateNonRootRemote50XCannotUseLocalCache -time="2025-06-04T07:57:06Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (0.29s) +time="2025-06-04T07:59:20Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (0.16s) === RUN TestUpdateRemoteChecksumWrongNoLocalCache ---- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (0.22s) +--- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (0.10s) === RUN TestUpdateRemoteChecksumWrongCanUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (0.31s) +--- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (0.18s) === RUN TestUpdateRemoteChecksumWrongCannotUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (0.38s) +--- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (0.19s) === RUN TestUpdateRootRemoteCorruptedNoLocalCache -time="2025-06-04T07:57:08Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:08Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (0.57s) +time="2025-06-04T07:59:20Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:20Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (0.36s) === RUN TestUpdateRootRemoteCorruptedCanUseLocalCache ---- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (1.06s) +--- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (0.64s) === RUN TestUpdateRootRemoteCorruptedCannotUseLocalCache -time="2025-06-04T07:57:10Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:10Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (1.56s) +time="2025-06-04T07:59:22Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:22Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (0.85s) === RUN TestUpdateNonRootRemoteCorruptedNoLocalCache -time="2025-06-04T07:57:11Z" level=error msg="Metadata for targets expired" -time="2025-06-04T07:57:11Z" level=error msg="Metadata for targets expired" -time="2025-06-04T07:57:11Z" level=error msg="Metadata for snapshot expired" -time="2025-06-04T07:57:11Z" level=error msg="Metadata for snapshot expired" -time="2025-06-04T07:57:11Z" level=error msg="Metadata for timestamp expired" -time="2025-06-04T07:57:11Z" level=error msg="Metadata for timestamp expired" -time="2025-06-04T07:57:12Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-06-04T07:57:12Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-06-04T07:57:12Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-06-04T07:57:12Z" level=error msg="Metadata for targets/a expired" -time="2025-06-04T07:57:12Z" level=warning msg="Error getting targets/a: targets/a expired at Fri May 3 07:57:12 UTC 2024" -time="2025-06-04T07:57:12Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-06-04T07:57:12Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-06-04T07:57:12Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-06-04T07:57:12Z" level=error msg="Metadata for targets/a/b expired" -time="2025-06-04T07:57:12Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Fri May 3 07:57:12 UTC 2024" -time="2025-06-04T07:57:12Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-06-04T07:57:12Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-06-04T07:57:12Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-06-04T07:57:12Z" level=error msg="Metadata for targets/b expired" -time="2025-06-04T07:57:12Z" level=warning msg="Error getting targets/b: targets/b expired at Fri May 3 07:57:12 UTC 2024" ---- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (1.73s) +time="2025-06-04T07:59:22Z" level=error msg="Metadata for targets expired" +time="2025-06-04T07:59:22Z" level=error msg="Metadata for targets expired" +time="2025-06-04T07:59:22Z" level=error msg="Metadata for snapshot expired" +time="2025-06-04T07:59:22Z" level=error msg="Metadata for snapshot expired" +time="2025-06-04T07:59:22Z" level=error msg="Metadata for timestamp expired" +time="2025-06-04T07:59:22Z" level=error msg="Metadata for timestamp expired" +time="2025-06-04T07:59:23Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-06-04T07:59:23Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-06-04T07:59:23Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-06-04T07:59:23Z" level=error msg="Metadata for targets/a expired" +time="2025-06-04T07:59:23Z" level=warning msg="Error getting targets/a: targets/a expired at Fri May 3 07:59:23 UTC 2024" +time="2025-06-04T07:59:23Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-06-04T07:59:23Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-06-04T07:59:23Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-06-04T07:59:23Z" level=error msg="Metadata for targets/a/b expired" +time="2025-06-04T07:59:23Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Fri May 3 07:59:23 UTC 2024" +time="2025-06-04T07:59:23Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-06-04T07:59:23Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-06-04T07:59:23Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-06-04T07:59:23Z" level=error msg="Metadata for targets/b expired" +time="2025-06-04T07:59:23Z" level=warning msg="Error getting targets/b: targets/b expired at Fri May 3 07:59:23 UTC 2024" +--- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (1.00s) === RUN TestUpdateNonRootRemoteCorruptedCanUseLocalCache -time="2025-06-04T07:57:13Z" level=error msg="Metadata for timestamp expired" -time="2025-06-04T07:57:13Z" level=error msg="Metadata for timestamp expired" ---- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (2.26s) +time="2025-06-04T07:59:24Z" level=error msg="Metadata for timestamp expired" +time="2025-06-04T07:59:24Z" level=error msg="Metadata for timestamp expired" +--- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (1.08s) === RUN TestUpdateNonRootRemoteCorruptedCannotUseLocalCache -time="2025-06-04T07:57:15Z" level=error msg="Metadata for targets expired" -time="2025-06-04T07:57:15Z" level=error msg="Metadata for snapshot expired" -time="2025-06-04T07:57:15Z" level=error msg="Metadata for snapshot expired" -time="2025-06-04T07:57:16Z" level=error msg="Metadata for timestamp expired" -time="2025-06-04T07:57:16Z" level=error msg="Metadata for timestamp expired" -time="2025-06-04T07:57:16Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-06-04T07:57:16Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-06-04T07:57:16Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-06-04T07:57:16Z" level=error msg="Metadata for targets/a expired" -time="2025-06-04T07:57:16Z" level=warning msg="Error getting targets/a: targets/a expired at Fri May 3 07:57:16 UTC 2024" -time="2025-06-04T07:57:17Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-06-04T07:57:17Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-06-04T07:57:17Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-06-04T07:57:17Z" level=error msg="Metadata for targets/a/b expired" -time="2025-06-04T07:57:17Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Fri May 3 07:57:17 UTC 2024" -time="2025-06-04T07:57:17Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-06-04T07:57:17Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-06-04T07:57:17Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-06-04T07:57:17Z" level=error msg="Metadata for targets/b expired" -time="2025-06-04T07:57:17Z" level=warning msg="Error getting targets/b: targets/b expired at Fri May 3 07:57:17 UTC 2024" ---- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (3.07s) +time="2025-06-04T07:59:24Z" level=error msg="Metadata for targets expired" +time="2025-06-04T07:59:25Z" level=error msg="Metadata for snapshot expired" +time="2025-06-04T07:59:25Z" level=error msg="Metadata for snapshot expired" +time="2025-06-04T07:59:25Z" level=error msg="Metadata for timestamp expired" +time="2025-06-04T07:59:25Z" level=error msg="Metadata for timestamp expired" +time="2025-06-04T07:59:25Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-06-04T07:59:25Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-06-04T07:59:25Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-06-04T07:59:25Z" level=error msg="Metadata for targets/a expired" +time="2025-06-04T07:59:25Z" level=warning msg="Error getting targets/a: targets/a expired at Fri May 3 07:59:25 UTC 2024" +time="2025-06-04T07:59:25Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-06-04T07:59:25Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-06-04T07:59:25Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-06-04T07:59:25Z" level=error msg="Metadata for targets/a/b expired" +time="2025-06-04T07:59:25Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Fri May 3 07:59:25 UTC 2024" +time="2025-06-04T07:59:26Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-06-04T07:59:26Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-06-04T07:59:26Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-06-04T07:59:26Z" level=error msg="Metadata for targets/b expired" +time="2025-06-04T07:59:26Z" level=warning msg="Error getting targets/b: targets/b expired at Fri May 3 07:59:26 UTC 2024" +--- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (1.55s) === RUN TestUpdateLocalAndRemoteRootCorrupt -time="2025-06-04T07:57:19Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:19Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:20Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:20Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:20Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:20Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" -time="2025-06-04T07:57:21Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateLocalAndRemoteRootCorrupt (3.38s) +time="2025-06-04T07:59:26Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:26Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:27Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:27Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:27Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:27Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:27Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:27Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:27Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:27Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:27Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:27Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:27Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:27Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:27Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:27Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:28Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:28Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:28Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:28Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:28Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:28Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateLocalAndRemoteRootCorrupt (1.84s) === RUN TestUpdateRemoteKeyRotated -time="2025-06-04T07:57:21Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-06-04T07:57:21Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-06-04T07:57:21Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" ---- PASS: TestUpdateRemoteKeyRotated (0.33s) +time="2025-06-04T07:59:28Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-06-04T07:59:28Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-06-04T07:59:28Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +--- PASS: TestUpdateRemoteKeyRotated (0.18s) === RUN TestValidateRootRotationWithOldRole ---- PASS: TestValidateRootRotationWithOldRole (0.16s) +--- PASS: TestValidateRootRotationWithOldRole (0.11s) === RUN TestRootRoleInvariant ---- PASS: TestRootRoleInvariant (0.15s) +--- PASS: TestRootRoleInvariant (0.07s) === RUN TestBadIntermediateTransitions ---- PASS: TestBadIntermediateTransitions (0.06s) +--- PASS: TestBadIntermediateTransitions (0.03s) === RUN TestExpiredIntermediateTransitions -time="2025-06-04T07:57:22Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" ---- PASS: TestExpiredIntermediateTransitions (0.06s) +time="2025-06-04T07:59:28Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" +--- PASS: TestExpiredIntermediateTransitions (0.03s) === RUN TestDownloadTargetsLarge ---- PASS: TestDownloadTargetsLarge (3.13s) +--- PASS: TestDownloadTargetsLarge (2.48s) === RUN TestDownloadTargetsDeep --- PASS: TestDownloadTargetsDeep (0.02s) === RUN TestDownloadSnapshotLargeDelegationsMany ---- PASS: TestDownloadSnapshotLargeDelegationsMany (2.43s) +--- PASS: TestDownloadSnapshotLargeDelegationsMany (2.01s) === RUN TestRootOnDiskTrustPinning ---- PASS: TestRootOnDiskTrustPinning (0.03s) +--- PASS: TestRootOnDiskTrustPinning (0.02s) === RUN TestLoadTUFRepoBadURL --- PASS: TestLoadTUFRepoBadURL (0.00s) === RUN TestApplyTargetsChange @@ -2231,14 +2267,14 @@ === RUN TestApplyChangelistTargetsToMultipleRoles --- PASS: TestApplyChangelistTargetsToMultipleRoles (0.00s) === RUN TestApplyChangelistTargetsFailsNonexistentRole -time="2025-06-04T07:57:28Z" 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-06-04T07:57:28Z" 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-06-04T07:59:33Z" 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-06-04T07:59:33Z" 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-06-04T07:57:28Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" +time="2025-06-04T07:59:33Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" --- PASS: TestChangeTargetMetaFailsInvalidRole (0.00s) === RUN TestChangeTargetMetaFailsIfPrefixError -time="2025-06-04T07:57:28Z" level=error msg="couldn't add target to targets/level1: Could not add all targets" +time="2025-06-04T07:59:33Z" 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) @@ -2247,9 +2283,9 @@ === RUN TestRotateRemoteKeyOffline --- PASS: TestRotateRemoteKeyOffline (0.00s) PASS -ok github.com/theupdateframework/notary/client 29.602s +ok github.com/theupdateframework/notary/client 19.203s === RUN TestTUFDelegation ---- PASS: TestTUFDelegation (0.01s) +--- PASS: TestTUFDelegation (0.00s) === RUN TestMemChangelist --- PASS: TestMemChangelist (0.00s) === RUN TestMemChangeIterator @@ -2259,14 +2295,14 @@ === RUN TestAdd --- PASS: TestAdd (0.00s) === RUN TestErrorConditions -time="2025-06-04T07:56:58Z" level=warning msg="invalid character '\\x05' looking for beginning of value" +time="2025-06-04T07:59:13Z" 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.028s +ok github.com/theupdateframework/notary/client/changelist 0.025s === RUN TestParseConfigError --- PASS: TestParseConfigError (0.00s) === RUN TestSetupGRPCServer @@ -2276,7 +2312,7 @@ === RUN TestSetupNetListener --- PASS: TestSetupNetListener (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/escrow 0.039s +ok github.com/theupdateframework/notary/cmd/escrow 0.041s === RUN TestPurgeDelegationKeys Usage: delegation [command] @@ -2350,22 +2386,22 @@ === RUN TestInitWithRootKey --- PASS: TestInitWithRootKey (0.07s) === RUN TestInitWithRootCert ---- PASS: TestInitWithRootCert (0.07s) +--- PASS: TestInitWithRootCert (0.04s) === RUN TestClientTUFInteraction ---- PASS: TestClientTUFInteraction (0.14s) +--- PASS: TestClientTUFInteraction (0.10s) === RUN TestClientDeleteTUFInteraction -time="2025-06-04T07:56:58Z" 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]:50705->[::1]:53: read: connection refused" ---- PASS: TestClientDeleteTUFInteraction (0.19s) +time="2025-06-04T07:59:14Z" 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]:50875->[::1]:53: read: connection refused" +--- PASS: TestClientDeleteTUFInteraction (0.11s) === RUN TestClientTUFAddByHashInteraction --- PASS: TestClientTUFAddByHashInteraction (0.29s) === RUN TestClientDelegationsInteraction -time="2025-06-04T07:56:59Z" 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-06-04T07:56:59Z" 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.45s) +time="2025-06-04T07:59:14Z" 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-06-04T07:59:14Z" 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.57s) === RUN TestClientDelegationsPublishing ---- PASS: TestClientDelegationsPublishing (0.53s) +--- PASS: TestClientDelegationsPublishing (0.57s) === RUN TestClientKeyGenerationRotation ---- PASS: TestClientKeyGenerationRotation (0.65s) +--- PASS: TestClientKeyGenerationRotation (0.62s) === RUN TestKeyRotation --- PASS: TestKeyRotation (0.07s) === RUN TestKeyRotationNonRoot @@ -2375,54 +2411,54 @@ === RUN TestLogLevelFlags --- PASS: TestLogLevelFlags (0.00s) === RUN TestClientKeyPassphraseChange ---- PASS: TestClientKeyPassphraseChange (0.08s) +--- PASS: TestClientKeyPassphraseChange (0.11s) === RUN TestPurgeSingleKey Removal of the following keys from all delegations in gun staged for next publish: - - 2c2983b8d0d546f780dec4d80a24a4cad4d41a35e38497b19bcbf60a0b88ce9e ---- PASS: TestPurgeSingleKey (0.08s) + - d171a1c4540bb2621f29614710474bec804c6f672215e1079062d44a177dc069 +--- PASS: TestPurgeSingleKey (0.07s) === RUN TestWitness -time="2025-06-04T07:57:01Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2025-06-04T07:57:01Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2025-06-04T07:57:01Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2025-06-04T07:57:01Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2025-06-04T07:57:01Z" 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-06-04T07:57:01Z" 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-06-04T07:57:01Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" ---- PASS: TestWitness (0.23s) +time="2025-06-04T07:59:16Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2025-06-04T07:59:16Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2025-06-04T07:59:16Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2025-06-04T07:59:16Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2025-06-04T07:59:16Z" 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-06-04T07:59:16Z" 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-06-04T07:59:16Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +--- PASS: TestWitness (0.32s) === RUN TestClientTUFInitWithAutoPublish -time="2025-06-04T07:57:01Z" 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]:33065->[::1]:53: read: connection refused" +time="2025-06-04T07:59:16Z" 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]:54056->[::1]:53: read: connection refused" --- PASS: TestClientTUFInitWithAutoPublish (0.05s) === RUN TestClientTUFAddWithAutoPublish -time="2025-06-04T07:57:01Z" 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]:41013->[::1]:53: read: connection refused" -time="2025-06-04T07:57:01Z" level=error msg="Could not publish Repository since we could not update: client is offline" -time="2025-06-04T07:57:01Z" 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]:43165->[::1]:53: read: connection refused" -time="2025-06-04T07:57:01Z" level=error msg="Could not publish Repository since we could not update: client is offline" ---- PASS: TestClientTUFAddWithAutoPublish (0.07s) +time="2025-06-04T07:59:17Z" 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]:36380->[::1]:53: read: connection refused" +time="2025-06-04T07:59:17Z" level=error msg="Could not publish Repository since we could not update: client is offline" +time="2025-06-04T07:59:17Z" 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]:37616->[::1]:53: read: connection refused" +time="2025-06-04T07:59:17Z" level=error msg="Could not publish Repository since we could not update: client is offline" +--- PASS: TestClientTUFAddWithAutoPublish (0.08s) === RUN TestClientTUFRemoveWithAutoPublish ---- PASS: TestClientTUFRemoveWithAutoPublish (0.13s) +--- PASS: TestClientTUFRemoveWithAutoPublish (0.17s) === RUN TestClientDelegationAddWithAutoPublish ---- PASS: TestClientDelegationAddWithAutoPublish (0.05s) +--- PASS: TestClientDelegationAddWithAutoPublish (0.08s) === RUN TestClientDelegationRemoveWithAutoPublish -time="2025-06-04T07:57:01Z" 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-06-04T07:57:01Z" 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.13s) +time="2025-06-04T07:59:17Z" 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-06-04T07:59:17Z" 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.15s) === RUN TestClientTUFAddByHashWithAutoPublish ---- PASS: TestClientTUFAddByHashWithAutoPublish (0.08s) +--- PASS: TestClientTUFAddByHashWithAutoPublish (0.06s) === RUN TestClientKeyImport -time="2025-06-04T07:57:01Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" -time="2025-06-04T07:57:01Z" 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-06-04T07:59:17Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" +time="2025-06-04T07:59:17Z" 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.04s) === RUN TestAddDelImportKeyPublishFlow -time="2025-06-04T07:57: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.24s) +time="2025-06-04T07:59:17Z" 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.20s) === RUN TestExportImportFlow ---- PASS: TestExportImportFlow (0.06s) +--- PASS: TestExportImportFlow (0.04s) === RUN TestDelegationKeyImportExport --- PASS: TestDelegationKeyImportExport (0.01s) === RUN TestImportKeysNoYubikey ---- PASS: TestImportKeysNoYubikey (0.01s) +--- PASS: TestImportKeysNoYubikey (0.00s) === RUN TestExportImportKeysNoYubikey ---- PASS: TestExportImportKeysNoYubikey (0.01s) +--- PASS: TestExportImportKeysNoYubikey (0.00s) === RUN TestRemoveIfNoKey --- PASS: TestRemoveIfNoKey (0.00s) === RUN TestRemoveOneKeyAbort @@ -2436,16 +2472,16 @@ === RUN TestRemoveMultikeysRemoveOnlyChosenKey --- PASS: TestRemoveMultikeysRemoveOnlyChosenKey (0.00s) === RUN TestRotateKeyInvalidRoles -time="2025-06-04T07:57: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]:35677->[::1]:53: read: connection refused" -time="2025-06-04T07:57: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]:44837->[::1]:53: read: connection refused" -time="2025-06-04T07:57: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]:41164->[::1]:53: read: connection refused" -time="2025-06-04T07:57: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]:51483->[::1]:53: read: connection refused" +time="2025-06-04T07:59:17Z" 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]:52830->[::1]:53: read: connection refused" +time="2025-06-04T07:59:17Z" 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]:33458->[::1]:53: read: connection refused" +time="2025-06-04T07:59:17Z" 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]:54104->[::1]:53: read: connection refused" +time="2025-06-04T07:59:17Z" 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]:38403->[::1]:53: read: connection refused" --- PASS: TestRotateKeyInvalidRoles (0.00s) === RUN TestRotateKeyTargetCannotBeServerManaged -time="2025-06-04T07:57: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]:57545->[::1]:53: read: connection refused" +time="2025-06-04T07:59:17Z" 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]:47531->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTargetCannotBeServerManaged (0.00s) === RUN TestRotateKeyTimestampCannotBeLocallyManaged -time="2025-06-04T07:57: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]:39156->[::1]:53: read: connection refused" +time="2025-06-04T07:59:17Z" 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]:48646->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTimestampCannotBeLocallyManaged (0.00s) === RUN TestRotateKeyNoGUN Usage: @@ -2453,13 +2489,13 @@ === RUN TestRotateKeyRemoteServerManagesKey Successfully rotated snapshot key for repository docker.com/notary Successfully rotated timestamp key for repository docker.com/notary ---- PASS: TestRotateKeyRemoteServerManagesKey (0.12s) +--- 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.10s) +--- PASS: TestRotateKeyBothKeys (0.06s) === RUN TestRotateKeyRootIsInteractive ---- PASS: TestRotateKeyRootIsInteractive (0.03s) +--- PASS: TestRotateKeyRootIsInteractive (0.01s) === RUN TestChangeKeyPassphraseInvalidID --- PASS: TestChangeKeyPassphraseInvalidID (0.00s) === RUN TestChangeKeyPassphraseInvalidNumArgs @@ -2478,8 +2514,8 @@ === RUN TestImportKeysNonexistentFile --- PASS: TestImportKeysNonexistentFile (0.00s) === RUN TestKeyGeneration -time="2025-06-04T07:57: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" ---- PASS: TestKeyGeneration (0.01s) +time="2025-06-04T07:59:17Z" 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) === RUN TestRemoteServerDefault @@ -2489,9 +2525,9 @@ === RUN TestRemoteServerCommandLineFlagOverridesConfig --- PASS: TestRemoteServerCommandLineFlagOverridesConfig (0.00s) === RUN TestInvalidAddHashCommands ---- PASS: TestInvalidAddHashCommands (0.01s) +--- PASS: TestInvalidAddHashCommands (0.00s) === RUN TestConfigParsingErrorsPropagatedByCommands ---- PASS: TestConfigParsingErrorsPropagatedByCommands (0.01s) +--- PASS: TestConfigParsingErrorsPropagatedByCommands (0.00s) === RUN TestInsufficientArgumentsReturnsErrorAndPrintsUsage --- PASS: TestInsufficientArgumentsReturnsErrorAndPrintsUsage (0.01s) === RUN TestBareCommandPrintsUsageAndNoError @@ -2499,9 +2535,9 @@ === RUN TestConfigFileTLSCannotBeRelativeToCWD --- PASS: TestConfigFileTLSCannotBeRelativeToCWD (0.01s) === RUN TestConfigFileTLSCanBeRelativeToConfigOrAbsolute ---- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.07s) +--- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.03s) === RUN TestConfigFileOverridenByCmdLineFlags ---- PASS: TestConfigFileOverridenByCmdLineFlags (0.07s) +--- PASS: TestConfigFileOverridenByCmdLineFlags (0.03s) === RUN TestConfigFileTrustPinning --- PASS: TestConfigFileTrustPinning (0.00s) === RUN TestPassphraseRetrieverCaching @@ -2515,7 +2551,7 @@ === RUN TestPrettyPrintZeroKeys --- PASS: TestPrettyPrintZeroKeys (0.00s) === RUN TestPrettyPrintRootAndSigningKeys ---- PASS: TestPrettyPrintRootAndSigningKeys (0.02s) +--- PASS: TestPrettyPrintRootAndSigningKeys (0.01s) === RUN TestPrettyPrintZeroTargets --- PASS: TestPrettyPrintZeroTargets (0.00s) === RUN TestPrettyPrintSortedTargets @@ -2527,10 +2563,10 @@ === RUN TestImportRootCert --- PASS: TestImportRootCert (0.00s) === RUN TestTokenAuth -time="2025-06-04T07:57:02Z" 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-06-04T07:59:18Z" 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-06-04T07:57:02Z" 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-06-04T07:59:18Z" 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) @@ -2541,18 +2577,18 @@ === RUN TestAdminTokenAuth401Status --- PASS: TestAdminTokenAuth401Status (0.00s) === RUN TestTokenAuthNon200Non401Status -time="2025-06-04T07:57:02Z" level=error msg="could not reach http://127.0.0.1:43597: 404" +time="2025-06-04T07:59:18Z" level=error msg="could not reach http://127.0.0.1:37191: 404" --- PASS: TestTokenAuthNon200Non401Status (0.00s) === RUN TestAdminTokenAuthNon200Non401Status -time="2025-06-04T07:57:02Z" level=error msg="could not reach http://127.0.0.1:34411: 404" +time="2025-06-04T07:59:18Z" level=error msg="could not reach http://127.0.0.1:33935: 404" --- PASS: TestAdminTokenAuthNon200Non401Status (0.00s) === RUN TestConfigureRepo --- PASS: TestConfigureRepo (0.00s) === RUN TestConfigureRepoRW -Enter username: time="2025-06-04T07:57:02Z" level=error msg="error processing username input: EOF" +Enter username: time="2025-06-04T07:59:18Z" level=error msg="error processing username input: EOF" --- PASS: TestConfigureRepoRW (0.00s) === RUN TestConfigureRepoAdmin -Enter username: time="2025-06-04T07:57:02Z" level=error msg="error processing username input: EOF" +Enter username: time="2025-06-04T07:59:18Z" level=error msg="error processing username input: EOF" --- PASS: TestConfigureRepoAdmin (0.00s) === RUN TestStatusUnstageAndReset Successfully reset specified changes for repository gun @@ -2565,11 +2601,11 @@ Pushing changes to gun --- PASS: TestGetTrustPinningErrors (0.00s) === RUN TestPasswordStore -Enter username: time="2025-06-04T07:57:02Z" level=error msg="error processing username input: EOF" +Enter username: time="2025-06-04T07:59:18Z" level=error msg="error processing username input: EOF" --- PASS: TestPasswordStore (0.00s) === RUN TestPasswordStoreWithEnvvar -time="2025-06-04T07:57:02Z" level=error msg="Authentication string with zero-length username" -time="2025-06-04T07:57:02Z" level=error msg="Could not base64-decode authentication string" +time="2025-06-04T07:59:18Z" level=error msg="Authentication string with zero-length username" +time="2025-06-04T07:59:18Z" level=error msg="Could not base64-decode authentication string" --- PASS: TestPasswordStoreWithEnvvar (0.00s) === RUN TestGetPayload --- PASS: TestGetPayload (0.00s) @@ -2578,7 +2614,7 @@ === RUN TestHomeExpand --- PASS: TestHomeExpand (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary 4.397s +ok github.com/theupdateframework/notary/cmd/notary 4.231s === RUN TestBootstrap --- PASS: TestBootstrap (0.00s) === RUN TestGetAddrAndTLSConfigInvalidTLS @@ -2594,42 +2630,42 @@ === RUN TestGetInvalidTrustService --- PASS: TestGetInvalidTrustService (0.00s) === RUN TestGetLocalTrustService -time="2025-06-04T07:56:59Z" level=info msg="Using local signing service, which requires ED25519. Ignoring all other trust_service parameters, including keyAlgorithm" +time="2025-06-04T07:59:14Z" 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-06-04T07:56:59Z" level=info msg="Using remote signing service" +time="2025-06-04T07:59:14Z" level=info msg="Using remote signing service" --- PASS: TestGetTrustServiceNoTLSConfig (0.00s) === RUN TestGetTrustServiceTLSSuccess -time="2025-06-04T07:56:59Z" level=info msg="Using remote signing service" +time="2025-06-04T07:59:14Z" level=info msg="Using remote signing service" --- PASS: TestGetTrustServiceTLSSuccess (0.00s) === RUN TestGetTrustServiceTLSFailure --- PASS: TestGetTrustServiceTLSFailure (0.00s) === RUN TestGetStoreInvalid -time="2025-06-04T07:56:59Z" level=info msg="Using asdf backend" +time="2025-06-04T07:59:14Z" level=info msg="Using asdf backend" --- PASS: TestGetStoreInvalid (0.00s) === RUN TestGetStoreDBStore -time="2025-06-04T07:56:59Z" level=info msg="Using sqlite3 backend" +time="2025-06-04T07:59:14Z" level=info msg="Using sqlite3 backend" --- PASS: TestGetStoreDBStore (0.00s) === RUN TestGetStoreRethinkDBStoreConnectionFails -time="2025-06-04T07:56:59Z" level=info msg="Using rethinkdb backend" +time="2025-06-04T07:59:14Z" level=info msg="Using rethinkdb backend" --- PASS: TestGetStoreRethinkDBStoreConnectionFails (0.00s) === RUN TestGetMemoryStore -time="2025-06-04T07:56:59Z" level=info msg="Using memory backend" +time="2025-06-04T07:59:14Z" 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-06-04T07:56:59Z" level=info msg="Using remote signing service" -time="2025-06-04T07:56:59Z" level=info msg="Using sqlite3 backend" +time="2025-06-04T07:59:14Z" level=info msg="Using remote signing service" +time="2025-06-04T07:59:14Z" level=info msg="Using sqlite3 backend" --- PASS: TestSampleConfig (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary-server 0.076s +ok github.com/theupdateframework/notary/cmd/notary-server 0.032s === RUN TestGetAddrAndTLSConfigInvalidTLS --- PASS: TestGetAddrAndTLSConfigInvalidTLS (0.00s) === RUN TestGetAddrAndTLSConfigNoGRPCAddr @@ -2644,7 +2680,7 @@ main_test.go:121: DM - skipping malfunctioning test, fails on sbuild --- SKIP: TestSetupCryptoServicesRethinkDBStoreConnectionFails (0.00s) === RUN TestSetupCryptoServicesDBStoreSuccess ---- PASS: TestSetupCryptoServicesDBStoreSuccess (0.01s) +--- PASS: TestSetupCryptoServicesDBStoreSuccess (0.02s) === RUN TestSetupCryptoServicesMemoryStore --- PASS: TestSetupCryptoServicesMemoryStore (0.00s) === RUN TestSetupCryptoServicesInvalidStore @@ -2652,7 +2688,7 @@ === RUN TestSetupGRPCServerInvalidAddress --- PASS: TestSetupGRPCServerInvalidAddress (0.00s) === RUN TestSetupGRPCServerSuccess ---- PASS: TestSetupGRPCServerSuccess (0.00s) +--- PASS: TestSetupGRPCServerSuccess (0.01s) === RUN TestBootstrap --- PASS: TestBootstrap (0.00s) === RUN TestGetEnv @@ -2662,17 +2698,17 @@ === RUN TestSampleConfig --- PASS: TestSampleConfig (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary-signer 0.075s +ok github.com/theupdateframework/notary/cmd/notary-signer 0.111s === RUN TestGenerateCertificate ---- PASS: TestGenerateCertificate (0.01s) +--- PASS: TestGenerateCertificate (0.00s) === RUN TestCryptoServiceWithNonEmptyGUN ---- PASS: TestCryptoServiceWithNonEmptyGUN (0.26s) +--- PASS: TestCryptoServiceWithNonEmptyGUN (0.24s) === RUN TestCryptoServiceWithEmptyGUN ---- PASS: TestCryptoServiceWithEmptyGUN (0.20s) +--- PASS: TestCryptoServiceWithEmptyGUN (0.31s) === RUN TestCryptoSignerInterfaceBehavior --- PASS: TestCryptoSignerInterfaceBehavior (0.00s) PASS -ok github.com/theupdateframework/notary/cryptoservice 0.508s +ok github.com/theupdateframework/notary/cryptoservice 0.594s === RUN TestGetPassphraseForUsingDelegationKey --- PASS: TestGetPassphraseForUsingDelegationKey (0.00s) === RUN TestGetPassphraseLimitsShortPassphrases @@ -2688,141 +2724,141 @@ === RUN TestGetPassphrase --- PASS: TestGetPassphrase (0.00s) PASS -ok github.com/theupdateframework/notary/passphrase 0.032s +ok github.com/theupdateframework/notary/passphrase 0.028s === RUN TestValidationErrorFormat -time="2025-06-04T07:56:59Z" 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.2 http.request.contenttype="multipart/form-data; boundary=71857affb94492290082662fe76b01a4c1a9c2cfff4df9ea50c1cf20171c" http.request.host="127.0.0.1:32995" http.request.id=ad7ea6d4-995d-498c-bf79-467c6e9cf344 http.request.method=POST http.request.remoteaddr="127.0.0.1:34588" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=71857affb94492290082662fe76b01a4c1a9c2cfff4df9ea50c1cf20171c" http.request.host="127.0.0.1:32995" http.request.id=ad7ea6d4-995d-498c-bf79-467c6e9cf344 http.request.method=POST http.request.remoteaddr="127.0.0.1:34588" 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=1.5434ms http.response.status=400 http.response.written=260 +time="2025-06-04T07:59:14Z" 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.2 http.request.contenttype="multipart/form-data; boundary=45a13edfcdd33b4ee9385d3f9560c7cb7d9f2c0f34aedb2a16d50fc6c452" http.request.host="127.0.0.1:38227" http.request.id=07a3f66d-975d-413d-97cc-1112b2ffb5b1 http.request.method=POST http.request.remoteaddr="127.0.0.1:51220" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=45a13edfcdd33b4ee9385d3f9560c7cb7d9f2c0f34aedb2a16d50fc6c452" http.request.host="127.0.0.1:38227" http.request.id=07a3f66d-975d-413d-97cc-1112b2ffb5b1 http.request.method=POST http.request.remoteaddr="127.0.0.1:51220" 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=1.58996ms http.response.status=400 http.response.written=260 --- PASS: TestValidationErrorFormat (0.03s) === RUN TestRunBadAddr --- PASS: TestRunBadAddr (0.00s) === RUN TestRunReservedPort --- PASS: TestRunReservedPort (0.00s) === RUN TestRepoPrefixMatches -time="2025-06-04T07:56:59Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum db68566339075aa387908e752b2f21f1531fec786281c60cfed0f70a41c017f0" docker.io/notary=gun go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=ca6efa750b0013179567f51cabcb220e3230b5cdc53f824f42c201f1cce4" http.request.host="127.0.0.1:37969" http.request.id=b0741fb7-a84a-42a2-8e44-b433ae25bffc http.request.method=POST http.request.remoteaddr="127.0.0.1:34296" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=ca6efa750b0013179567f51cabcb220e3230b5cdc53f824f42c201f1cce4" http.request.host="127.0.0.1:37969" http.request.id=b0741fb7-a84a-42a2-8e44-b433ae25bffc http.request.method=POST http.request.remoteaddr="127.0.0.1:34296" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=2.25416ms http.response.status=0 http.response.written=0 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:37969" http.request.id=5e6df610-b8ce-4dac-83d3-103b2f23a7c9 http.request.method=GET http.request.remoteaddr="127.0.0.1:34296" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration="302.28µs" http.response.status=200 http.response.written=683 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:37969" http.request.id=ee57ee2a-e62f-4d31-9a71-b28c2aa1e075 http.request.method=GET http.request.remoteaddr="127.0.0.1:34296" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.a4f07ce7545c7e7b32a9b0d1c03daa2fe62bf350b4664698d857155a7476320b.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-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:37969" http.request.id=a87900b3-1330-4135-91ed-77dc3b59adeb http.request.method=GET http.request.remoteaddr="127.0.0.1:34296" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="433.72µs" http.response.status=200 http.response.written=181 -time="2025-06-04T07:56:59Z" level=info msg="trust data deleted for docker.io/notary" docker.io/notary=gun go.version=go1.22.2 http.request.host="127.0.0.1:37969" http.request.id=c3b78285-acba-4eb1-b891-7b26959482bd http.request.method=DELETE http.request.remoteaddr="127.0.0.1:34296" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:37969" http.request.id=c3b78285-acba-4eb1-b891-7b26959482bd http.request.method=DELETE http.request.remoteaddr="127.0.0.1:34296" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="43.52µs" http.response.status=0 http.response.written=0 ---- PASS: TestRepoPrefixMatches (0.01s) +time="2025-06-04T07:59:14Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum a754cc22f0075eb888bd1145a4439dc04497ed810a9a314f7fb93a57e8fdd1a5" docker.io/notary=gun go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=e30089d58364ab4caff22cc69df417e1eff3a4f32c88a3ba60f4f46271a7" http.request.host="127.0.0.1:40841" http.request.id=f5b0f35d-378c-4f99-b8f7-e66f882f42dd http.request.method=POST http.request.remoteaddr="127.0.0.1:42842" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=e30089d58364ab4caff22cc69df417e1eff3a4f32c88a3ba60f4f46271a7" http.request.host="127.0.0.1:40841" http.request.id=f5b0f35d-378c-4f99-b8f7-e66f882f42dd http.request.method=POST http.request.remoteaddr="127.0.0.1:42842" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=2.1312ms http.response.status=0 http.response.written=0 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:40841" http.request.id=549d9f2e-d106-471a-b49f-a201c2ccfd18 http.request.method=GET http.request.remoteaddr="127.0.0.1:42842" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration="327.24µs" http.response.status=200 http.response.written=683 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:40841" http.request.id=fb21c442-5721-4b54-bb77-db64e1632357 http.request.method=GET http.request.remoteaddr="127.0.0.1:42842" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.13428f55d8100ce64c23165b00ae50f9e2cc48d43d645d2e0732574e2aa64bb4.json http.request.useragent=Go-http-client/1.1 http.response.duration="45.12µs" http.response.status=200 http.response.written=683 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:40841" http.request.id=d54b6c05-ec63-4336-90ee-2c6b9358c8b8 http.request.method=GET http.request.remoteaddr="127.0.0.1:42842" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="231µs" http.response.status=200 http.response.written=181 +time="2025-06-04T07:59:14Z" level=info msg="trust data deleted for docker.io/notary" docker.io/notary=gun go.version=go1.22.2 http.request.host="127.0.0.1:40841" http.request.id=7513dc88-e3b2-4e28-bf04-923dc54f211d http.request.method=DELETE http.request.remoteaddr="127.0.0.1:42842" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:40841" http.request.id=7513dc88-e3b2-4e28-bf04-923dc54f211d http.request.method=DELETE http.request.remoteaddr="127.0.0.1:42842" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="27.36µs" http.response.status=0 http.response.written=0 +--- PASS: TestRepoPrefixMatches (0.02s) === RUN TestRepoPrefixDoesNotMatch --- PASS: TestRepoPrefixDoesNotMatch (0.01s) === RUN TestMetricsEndpoint ---- PASS: TestMetricsEndpoint (0.00s) +--- PASS: TestMetricsEndpoint (0.03s) === RUN TestGetKeysEndpoint -time="2025-06-04T07:56:59Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.2 http.request.host="127.0.0.1:44461" http.request.id=9520114f-a9b8-45ba-a17c-65e51b8082ab http.request.method=GET http.request.remoteaddr="127.0.0.1:33916" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:44461" http.request.id=9520114f-a9b8-45ba-a17c-65e51b8082ab http.request.method=GET http.request.remoteaddr="127.0.0.1:33916" 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="103.76µs" http.response.status=404 http.response.written=104 -time="2025-06-04T07:56:59Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.2 http.request.host="127.0.0.1:44461" http.request.id=57749598-c568-46a4-a435-7066ad154009 http.request.method=GET http.request.remoteaddr="127.0.0.1:33928" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:44461" http.request.id=57749598-c568-46a4-a435-7066ad154009 http.request.method=GET http.request.remoteaddr="127.0.0.1:33928" 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="59.48µs" http.response.status=404 http.response.written=104 -time="2025-06-04T07:56:59Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.2 http.request.host="127.0.0.1:44461" http.request.id=6300ffeb-7c96-4fbf-8fb8-d39989ae8ea2 http.request.method=GET http.request.remoteaddr="127.0.0.1:33944" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:44461" http.request.id=6300ffeb-7c96-4fbf-8fb8-d39989ae8ea2 http.request.method=GET http.request.remoteaddr="127.0.0.1:33944" 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="61.68µs" http.response.status=404 http.response.written=104 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:44461" http.request.id=d65e8385-d810-443a-9c2f-11c1e602d86b http.request.method=GET http.request.remoteaddr="127.0.0.1:33958" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration=4.11012ms http.response.status=200 http.response.written=103 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:44461" http.request.id=5e8465af-6062-4069-b4ce-f630771b8f30 http.request.method=GET http.request.remoteaddr="127.0.0.1:33972" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="138.6µs" http.response.status=200 http.response.written=103 ---- PASS: TestGetKeysEndpoint (0.01s) +time="2025-06-04T07:59:14Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.2 http.request.host="127.0.0.1:36087" http.request.id=c623a879-2d26-4cd5-bc84-06f4bfb34aa1 http.request.method=GET http.request.remoteaddr="127.0.0.1:43656" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:36087" http.request.id=c623a879-2d26-4cd5-bc84-06f4bfb34aa1 http.request.method=GET http.request.remoteaddr="127.0.0.1:43656" 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="104.84µs" http.response.status=404 http.response.written=104 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:36087" http.request.id=df927745-579d-4b5e-b214-134f458972d7 http.request.method=GET http.request.remoteaddr="127.0.0.1:43660" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration=4.13688ms http.response.status=200 http.response.written=103 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:36087" http.request.id=ffa51aad-6ccc-4dd8-bbcb-76c8f7898078 http.request.method=GET http.request.remoteaddr="127.0.0.1:43664" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="128.04µs" http.response.status=200 http.response.written=103 +time="2025-06-04T07:59:14Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.2 http.request.host="127.0.0.1:36087" http.request.id=6cd1dd43-2d50-4f6f-b3a6-f0efe6464039 http.request.method=GET http.request.remoteaddr="127.0.0.1:43668" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:36087" http.request.id=6cd1dd43-2d50-4f6f-b3a6-f0efe6464039 http.request.method=GET http.request.remoteaddr="127.0.0.1:43668" 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="62.04µs" http.response.status=404 http.response.written=104 +time="2025-06-04T07:59:14Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.2 http.request.host="127.0.0.1:36087" http.request.id=887623a9-a496-4be7-bcf4-9126d3832a5b http.request.method=GET http.request.remoteaddr="127.0.0.1:43682" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:36087" http.request.id=887623a9-a496-4be7-bcf4-9126d3832a5b http.request.method=GET http.request.remoteaddr="127.0.0.1:43682" 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="45.12µs" http.response.status=404 http.response.written=104 +--- PASS: TestGetKeysEndpoint (0.02s) === RUN TestGetRoleByHash -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:35505" http.request.id=ffa9b8d4-8a0d-4ef0-b743-ff56da71b142 http.request.method=GET http.request.remoteaddr="127.0.0.1:60552" http.request.uri=/v2/gun/_trust/tuf/timestamp.695d8967d9d37b55cb7ce6ccdb660a06d6b0fd2bb4d0f686005e210857fb06d4.json http.request.useragent=Go-http-client/1.1 http.response.duration="57.64µs" http.response.status=200 http.response.written=115 ---- PASS: TestGetRoleByHash (0.00s) +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:41871" http.request.id=d18f4e65-1802-4e60-84f3-10ff5403d7e0 http.request.method=GET http.request.remoteaddr="127.0.0.1:54594" http.request.uri=/v2/gun/_trust/tuf/timestamp.8f7ef4d19cca52cef0814048a6acb054e23fbefff7f3ee8234524e57042c52b5.json http.request.useragent=Go-http-client/1.1 http.response.duration="60µs" http.response.status=200 http.response.written=115 +--- PASS: TestGetRoleByHash (0.01s) === RUN TestGetRoleByVersion -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:41375" http.request.id=4ecbceff-99f1-4445-a970-075352479d92 http.request.method=GET http.request.remoteaddr="127.0.0.1:37270" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="71.04µs" http.response.status=200 http.response.written=115 ---- PASS: TestGetRoleByVersion (0.01s) +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:46641" http.request.id=e7bf83db-c8a7-4dc4-9179-65ca812d9b0b http.request.method=GET http.request.remoteaddr="127.0.0.1:58616" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="53.56µs" http.response.status=200 http.response.written=115 +--- PASS: TestGetRoleByVersion (0.02s) === RUN TestGetCurrentRole -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:44629" http.request.id=a7b0ed7f-4851-428d-b5e7-86033c513303 http.request.method=GET http.request.remoteaddr="127.0.0.1:33594" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="324.92µs" http.response.status=200 http.response.written=495 ---- PASS: TestGetCurrentRole (0.02s) +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:36145" http.request.id=aa6b06ca-ad82-4b72-898f-2b6f659a868d http.request.method=GET http.request.remoteaddr="127.0.0.1:36202" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="370.68µs" http.response.status=200 http.response.written=495 +--- PASS: TestGetCurrentRole (0.01s) === RUN TestRotateKeyEndpoint -time="2025-06-04T07:56:59Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:33903" http.request.id=0826ff28-6596-4f62-a969-086fde303771 http.request.method=POST http.request.remoteaddr="127.0.0.1:40184" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:33903" http.request.id=0826ff28-6596-4f62-a969-086fde303771 http.request.method=POST http.request.remoteaddr="127.0.0.1:40184" 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="143.52µs" http.response.status=404 http.response.written=104 -time="2025-06-04T07:56:59Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:33903" http.request.id=7f9c385e-9416-442c-a419-5ce7bf688fd0 http.request.method=POST http.request.remoteaddr="127.0.0.1:40200" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:33903" http.request.id=7f9c385e-9416-442c-a419-5ce7bf688fd0 http.request.method=POST http.request.remoteaddr="127.0.0.1:40200" 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="89.28µs" http.response.status=404 http.response.written=104 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:33903" http.request.id=791a9998-5780-4876-8672-7d6afde893a6 http.request.method=POST http.request.remoteaddr="127.0.0.1:40206" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="147.2µs" http.response.status=200 http.response.written=103 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:33903" http.request.id=3779f087-75a0-40f6-a611-f62665f514e9 http.request.method=POST http.request.remoteaddr="127.0.0.1:40212" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="122.2µs" http.response.status=200 http.response.written=103 -time="2025-06-04T07:56:59Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:33903" http.request.id=b84fc18b-4798-4655-a69e-f87aef7c93cc http.request.method=POST http.request.remoteaddr="127.0.0.1:40224" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:33903" http.request.id=b84fc18b-4798-4655-a69e-f87aef7c93cc http.request.method=POST http.request.remoteaddr="127.0.0.1:40224" 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="74.24µs" http.response.status=404 http.response.written=104 -time="2025-06-04T07:56:59Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:33903" http.request.id=77676e9f-407a-47c0-b47c-e430adffcccd http.request.method=POST http.request.remoteaddr="127.0.0.1:40234" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:33903" http.request.id=77676e9f-407a-47c0-b47c-e430adffcccd http.request.method=POST http.request.remoteaddr="127.0.0.1:40234" 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="59.12µs" http.response.status=404 http.response.written=104 +time="2025-06-04T07:59:14Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44871" http.request.id=95ae0614-5bf9-4a90-b0a8-16acc7da360f http.request.method=POST http.request.remoteaddr="127.0.0.1:33348" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44871" http.request.id=95ae0614-5bf9-4a90-b0a8-16acc7da360f http.request.method=POST http.request.remoteaddr="127.0.0.1:33348" 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="96.08µs" http.response.status=404 http.response.written=104 +time="2025-06-04T07:59:14Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44871" http.request.id=ab256ca9-a3a6-49bb-896c-acae66fc4a32 http.request.method=POST http.request.remoteaddr="127.0.0.1:33354" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44871" http.request.id=ab256ca9-a3a6-49bb-896c-acae66fc4a32 http.request.method=POST http.request.remoteaddr="127.0.0.1:33354" 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="52.44µs" http.response.status=404 http.response.written=104 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44871" http.request.id=a9f2f0ca-3622-4bb3-84c9-497e8f580264 http.request.method=POST http.request.remoteaddr="127.0.0.1:33356" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="129.28µs" http.response.status=200 http.response.written=103 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44871" http.request.id=4c35e67d-cc31-42d5-b1a9-bc52892b5a77 http.request.method=POST http.request.remoteaddr="127.0.0.1:33364" 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-06-04T07:59:14Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44871" http.request.id=3e0a3d75-b46d-49f3-b66d-8bf776d4bd67 http.request.method=POST http.request.remoteaddr="127.0.0.1:33376" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44871" http.request.id=3e0a3d75-b46d-49f3-b66d-8bf776d4bd67 http.request.method=POST http.request.remoteaddr="127.0.0.1:33376" 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="55.76µs" http.response.status=404 http.response.written=104 +time="2025-06-04T07:59:14Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44871" http.request.id=f311455e-9ba2-4861-9a4c-e5d64de9a802 http.request.method=POST http.request.remoteaddr="127.0.0.1:33382" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2025-06-04T07:59:14Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44871" http.request.id=f311455e-9ba2-4861-9a4c-e5d64de9a802 http.request.method=POST http.request.remoteaddr="127.0.0.1:33382" 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="45.2µs" http.response.status=404 http.response.written=104 --- PASS: TestRotateKeyEndpoint (0.01s) PASS -ok github.com/theupdateframework/notary/server 0.163s +ok github.com/theupdateframework/notary/server 0.213s === RUN Test_changefeed --- PASS: Test_changefeed (0.00s) === RUN Test_checkChangefeedInputs -time="2025-06-04T07:56:59Z" level=error msg="500 GET unable to retrieve storage" -time="2025-06-04T07:56:59Z" level=error msg="400 GET invalid pageSize: not_a_number" +time="2025-06-04T07:59:15Z" level=error msg="500 GET unable to retrieve storage" +time="2025-06-04T07:59:15Z" level=error msg="400 GET invalid pageSize: not_a_number" --- PASS: Test_checkChangefeedInputs (0.00s) === RUN TestMainHandlerGet -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:33403" http.request.id=0786d447-f255-4a91-ba09-cb73c257dc31 http.request.method=GET http.request.remoteaddr="127.0.0.1:50666" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="29.44µs" http.response.status=200 http.response.written=2 +time="2025-06-04T07:59:15Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:45025" http.request.id=c99b9775-4edc-41f5-89ae-cdbb6da5f25f http.request.method=GET http.request.remoteaddr="127.0.0.1:41898" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="27.96µs" http.response.status=200 http.response.written=2 --- PASS: TestMainHandlerGet (0.00s) === RUN TestMainHandlerNotGet -time="2025-06-04T07:56:59Z" level=info msg="generic not found: You have requested a resource that does not exist.: " go.version=go1.22.2 http.request.host="127.0.0.1:35301" http.request.id=cf049baf-1184-4240-8bd9-3bfa83349597 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:37326" http.request.uri=/ http.request.useragent=Go-http-client/1.1 -time="2025-06-04T07:56:59Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:35301" http.request.id=cf049baf-1184-4240-8bd9-3bfa83349597 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:37326" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="232.52µs" http.response.status=404 http.response.written=105 +time="2025-06-04T07:59:15Z" level=info msg="generic not found: You have requested a resource that does not exist.: " go.version=go1.22.2 http.request.host="127.0.0.1:45321" http.request.id=d0d6f45d-f09f-48a4-971d-27a502477f02 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:51082" http.request.uri=/ http.request.useragent=Go-http-client/1.1 +time="2025-06-04T07:59:15Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:45321" http.request.id=d0d6f45d-f09f-48a4-971d-27a502477f02 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:51082" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="218.8µs" http.response.status=404 http.response.written=105 --- PASS: TestMainHandlerNotGet (0.00s) === RUN TestKeyHandlersInvalidConfiguration -time="2025-06-04T07:56:59Z" level=error msg="500 GET storage not configured" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=error msg="500 GET storage not configured" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=error msg="500 POST storage not configured" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=error msg="500 POST storage not configured" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 GET storage not configured" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 GET storage not configured" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 POST storage not configured" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 POST storage not configured" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.2 gun=gun --- PASS: TestKeyHandlersInvalidConfiguration (0.00s) === RUN TestKeyHandlersNoRoleOrRepo -time="2025-06-04T07:56:59Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.2 -time="2025-06-04T07:56:59Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.2 -time="2025-06-04T07:56:59Z" level=info msg="400 GET no role in request" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=info msg="400 GET no role in request" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.2 -time="2025-06-04T07:56:59Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.2 -time="2025-06-04T07:56:59Z" level=info msg="400 POST no role in request" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=info msg="400 POST no role in request" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.2 +time="2025-06-04T07:59:15Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.2 +time="2025-06-04T07:59:15Z" level=info msg="400 GET no role in request" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="400 GET no role in request" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.2 +time="2025-06-04T07:59:15Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.2 +time="2025-06-04T07:59:15Z" level=info msg="400 POST no role in request" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="400 POST no role in request" go.version=go1.22.2 gun=gun --- PASS: TestKeyHandlersNoRoleOrRepo (0.00s) === RUN TestKeyHandlersInvalidRole -time="2025-06-04T07:56:59Z" level=info msg="400 GET root key: " go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=info msg="400 GET targets key: " go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=info msg="400 GET targets/a key: " go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=info msg="400 GET invalidrole key: " go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=info msg="400 POST root key: " go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=info msg="400 POST targets key: " go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=info msg="400 POST targets/a key: " go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=info msg="400 POST invalidrole key: " go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="400 GET root key: " go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="400 GET targets key: " go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="400 GET targets/a key: " go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="400 GET invalidrole key: " go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="400 POST root key: " go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="400 POST targets key: " go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="400 POST targets/a key: " go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="400 POST invalidrole key: " go.version=go1.22.2 gun=gun --- PASS: TestKeyHandlersInvalidRole (0.00s) === RUN TestGetKeyHandlerCreatesOnce --- PASS: TestGetKeyHandlerCreatesOnce (0.00s) === RUN TestKeyHandlersInvalidKeyAlgo -time="2025-06-04T07:56:59Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun -time="2025-06-04T07:56:59Z" level=error msg="500 POST snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=error msg="500 POST snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun --- PASS: TestKeyHandlersInvalidKeyAlgo (0.00s) === RUN TestRotateKeyHandlerSuccessfulRotation --- PASS: TestRotateKeyHandlerSuccessfulRotation (0.00s) === RUN TestGetHandlerRoot -time="2025-06-04T07:56:59Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun --- PASS: TestGetHandlerRoot (0.00s) === RUN TestGetHandlerTimestamp --- PASS: TestGetHandlerTimestamp (0.00s) === RUN TestGetHandlerSnapshot --- PASS: TestGetHandlerSnapshot (0.00s) === RUN TestGetHandler404 -time="2025-06-04T07:56:59Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun --- PASS: TestGetHandler404 (0.00s) === RUN TestGetHandlerNilData -time="2025-06-04T07:56:59Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun +time="2025-06-04T07:59:15Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun --- PASS: TestGetHandlerNilData (0.00s) === RUN TestGetHandlerNoStorage -time="2025-06-04T07:56:59Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.22.2 +time="2025-06-04T07:59:15Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.22.2 --- PASS: TestGetHandlerNoStorage (0.00s) === RUN TestAtomicUpdateValidationFailurePropagated ---- PASS: TestAtomicUpdateValidationFailurePropagated (0.00s) +--- PASS: TestAtomicUpdateValidationFailurePropagated (0.01s) === RUN TestAtomicUpdateNonValidationFailureNotPropagated -time="2025-06-04T07:56:59Z" level=info msg="400 POST error validating update" go.version=go1.22.2 testGUN=gun +time="2025-06-04T07:59:15Z" level=info msg="400 POST error validating update" go.version=go1.22.2 testGUN=gun --- PASS: TestAtomicUpdateNonValidationFailureNotPropagated (0.00s) === RUN TestAtomicUpdateVersionErrorPropagated -time="2025-06-04T07:56:59Z" level=info msg="400 POST old version error" go.version=go1.22.2 testGUN=gun +time="2025-06-04T07:59:15Z" level=info msg="400 POST old version error" go.version=go1.22.2 testGUN=gun --- PASS: TestAtomicUpdateVersionErrorPropagated (0.01s) === RUN TestGetMaybeServerSignedNoCrypto --- PASS: TestGetMaybeServerSignedNoCrypto (0.00s) @@ -2835,8 +2871,8 @@ === RUN TestValidatePrevTimestamp --- PASS: TestValidatePrevTimestamp (0.01s) === RUN TestValidatePreviousTimestampCorrupt -time="2025-06-04T07:56:59Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" ---- PASS: TestValidatePreviousTimestampCorrupt (0.00s) +time="2025-06-04T07:59:15Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" +--- PASS: TestValidatePreviousTimestampCorrupt (0.01s) === RUN TestValidateGetCurrentTimestampBroken --- PASS: TestValidateGetCurrentTimestampBroken (0.00s) === RUN TestValidateNoNewRoot @@ -2856,21 +2892,21 @@ === RUN TestValidateRootRotationWithOldSigs --- PASS: TestValidateRootRotationWithOldSigs (0.02s) === RUN TestValidateRootRotationMultipleKeysThreshold1 ---- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.01s) +--- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.02s) === RUN TestRootRotationNotSignedWithOldKeysForOldRole --- PASS: TestRootRotationNotSignedWithOldKeysForOldRole (0.02s) === RUN TestRootRotationVersionIncrement --- PASS: TestRootRotationVersionIncrement (0.01s) === RUN TestValidateNoRoot ---- PASS: TestValidateNoRoot (0.00s) +--- PASS: TestValidateNoRoot (0.01s) === RUN TestValidateSnapshotMissingNoSnapshotKey ---- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.00s) +--- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.01s) === RUN TestValidateSnapshotGenerateNoPrev --- PASS: TestValidateSnapshotGenerateNoPrev (0.01s) === RUN TestValidateSnapshotGenerateWithPrev --- PASS: TestValidateSnapshotGenerateWithPrev (0.01s) === RUN TestValidateSnapshotGeneratePrevCorrupt -time="2025-06-04T07:56:59Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" +time="2025-06-04T07:59:15Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" --- PASS: TestValidateSnapshotGeneratePrevCorrupt (0.01s) === RUN TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken --- PASS: TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken (0.01s) @@ -2889,25 +2925,25 @@ === RUN TestValidateRootRoleMissing --- PASS: TestValidateRootRoleMissing (0.00s) === RUN TestValidateTargetsRoleMissing ---- PASS: TestValidateTargetsRoleMissing (0.00s) +--- PASS: TestValidateTargetsRoleMissing (0.01s) === RUN TestValidateSnapshotRoleMissing ---- PASS: TestValidateSnapshotRoleMissing (0.00s) +--- PASS: TestValidateSnapshotRoleMissing (0.01s) === RUN TestValidateRootSigMissing --- PASS: TestValidateRootSigMissing (0.00s) === RUN TestValidateTargetsSigMissing -time="2025-06-04T07:56:59Z" level=error msg="ErrBadTargets: tuf: data has no signatures" ---- PASS: TestValidateTargetsSigMissing (0.00s) +time="2025-06-04T07:59:15Z" level=error msg="ErrBadTargets: tuf: data has no signatures" +--- PASS: TestValidateTargetsSigMissing (0.01s) === RUN TestValidateSnapshotSigMissing --- PASS: TestValidateSnapshotSigMissing (0.00s) === RUN TestValidateRootCorrupt --- PASS: TestValidateRootCorrupt (0.00s) === RUN TestValidateTargetsCorrupt -time="2025-06-04T07:56:59Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" +time="2025-06-04T07:59:15Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" --- PASS: TestValidateTargetsCorrupt (0.00s) === RUN TestValidateSnapshotCorrupt --- PASS: TestValidateSnapshotCorrupt (0.01s) === RUN TestValidateRootModifiedSize ---- PASS: TestValidateRootModifiedSize (0.01s) +--- PASS: TestValidateRootModifiedSize (0.00s) === RUN TestValidateTargetsModifiedSize --- PASS: TestValidateTargetsModifiedSize (0.01s) === RUN TestValidateRootModifiedHash @@ -2917,22 +2953,22 @@ === RUN TestGenerateSnapshotRootNotLoaded --- PASS: TestGenerateSnapshotRootNotLoaded (0.00s) === RUN TestGenerateSnapshotNoKey ---- PASS: TestGenerateSnapshotNoKey (0.01s) +--- PASS: TestGenerateSnapshotNoKey (0.00s) === RUN TestLoadTargetsLoadsNothingIfNoUpdates --- PASS: TestLoadTargetsLoadsNothingIfNoUpdates (0.00s) === RUN TestValidateTargetsRequiresStoredParent -time="2025-06-04T07:56:59Z" level=error msg="ErrBadTargets: targets must be loaded first" +time="2025-06-04T07:59:15Z" level=error msg="ErrBadTargets: targets must be loaded first" --- PASS: TestValidateTargetsRequiresStoredParent (0.01s) === RUN TestValidateTargetsParentInUpdate -time="2025-06-04T07:56:59Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" ---- PASS: TestValidateTargetsParentInUpdate (0.00s) +time="2025-06-04T07:59:15Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" +--- PASS: TestValidateTargetsParentInUpdate (0.01s) === RUN TestValidateTargetsRoleNotInParent -time="2025-06-04T07:56:59Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" -time="2025-06-04T07:56:59Z" level=error msg="ErrBadTargets: targets has already been loaded" -time="2025-06-04T07:56:59Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +time="2025-06-04T07:59:15Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +time="2025-06-04T07:59:15Z" level=error msg="ErrBadTargets: targets has already been loaded" +time="2025-06-04T07:59:15Z" 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.396s +ok github.com/theupdateframework/notary/server/handlers 0.404s === RUN TestSnapshotExpired --- PASS: TestSnapshotExpired (0.00s) === RUN TestSnapshotNotExpired @@ -2940,17 +2976,17 @@ === RUN TestGetSnapshotKeyCreate --- PASS: TestGetSnapshotKeyCreate (0.00s) === RUN TestGetSnapshotKeyCreateWithFailingStore -time="2025-06-04T07:56:59Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2025-06-04T07:59:15Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetSnapshotKeyCreateWithFailingStore (0.00s) === RUN TestGetSnapshotKeyCreateWithCorruptedStore -time="2025-06-04T07:56:59Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve snapshot key ID" +time="2025-06-04T07:59:15Z" 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-06-04T07:56:59Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" +time="2025-06-04T07:59:15Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" --- PASS: TestGetSnapshotNoPreviousSnapshot (0.00s) === RUN TestGetSnapshotReturnsPreviousSnapshotIfUnexpired --- PASS: TestGetSnapshotReturnsPreviousSnapshotIfUnexpired (0.00s) @@ -2961,7 +2997,7 @@ === RUN TestCreateSnapshotNoKeyInCrypto --- PASS: TestCreateSnapshotNoKeyInCrypto (0.00s) PASS -ok github.com/theupdateframework/notary/server/snapshot 0.035s +ok github.com/theupdateframework/notary/server/snapshot 0.057s === RUN TestMemoryUpdateCurrentEmpty --- PASS: TestMemoryUpdateCurrentEmpty (0.00s) === RUN TestMemoryUpdateCurrentVersionCheckOldVersionExists @@ -3001,7 +3037,7 @@ === RUN TestSQLDBCheckHealthTableMissing --- PASS: TestSQLDBCheckHealthTableMissing (0.00s) === RUN TestSQLDBCheckHealthDBConnectionFail ---- PASS: TestSQLDBCheckHealthDBConnectionFail (0.01s) +--- PASS: TestSQLDBCheckHealthDBConnectionFail (0.00s) === RUN TestSQLDBCheckHealthSucceeds --- PASS: TestSQLDBCheckHealthSucceeds (0.00s) === RUN TestSQLDBGetChecksum @@ -3009,13 +3045,13 @@ === RUN TestSQLDBGetChecksumNotFound --- PASS: TestSQLDBGetChecksumNotFound (0.00s) === RUN TestSQLTUFMetaStoreGetCurrent ---- PASS: TestSQLTUFMetaStoreGetCurrent (0.01s) +--- PASS: TestSQLTUFMetaStoreGetCurrent (0.03s) === RUN TestSQLGetChanges --- PASS: TestSQLGetChanges (0.01s) === RUN TestSQLDBGetVersion --- PASS: TestSQLDBGetVersion (0.00s) PASS -ok github.com/theupdateframework/notary/server/storage 0.141s +ok github.com/theupdateframework/notary/server/storage 0.125s === RUN TestTimestampExpired --- PASS: TestTimestampExpired (0.00s) === RUN TestTimestampNotExpired @@ -3023,31 +3059,31 @@ === RUN TestGetTimestampKey --- PASS: TestGetTimestampKey (0.01s) === RUN TestGetTimestampNoPreviousTimestamp -time="2025-06-04T07:57:00Z" level=error msg="Failed to unmarshal existing timestamp" +time="2025-06-04T07:59:15Z" level=error msg="Failed to unmarshal existing timestamp" --- PASS: TestGetTimestampNoPreviousTimestamp (0.00s) === RUN TestGetTimestampReturnsPreviousTimestampIfUnexpired --- PASS: TestGetTimestampReturnsPreviousTimestampIfUnexpired (0.00s) === RUN TestGetTimestampOldTimestampExpired --- PASS: TestGetTimestampOldTimestampExpired (0.00s) === RUN TestCannotMakeNewTimestampIfNoRootOrSnapshot -time="2025-06-04T07:57:00Z" level=error msg="Failed to create a new timestamp" -time="2025-06-04T07:57:00Z" level=error msg="Failed to create a new timestamp" +time="2025-06-04T07:59:15Z" level=error msg="Failed to create a new timestamp" +time="2025-06-04T07:59:15Z" level=error msg="Failed to create a new timestamp" --- PASS: TestCannotMakeNewTimestampIfNoRootOrSnapshot (0.00s) === RUN TestCreateTimestampNoKeyInCrypto -time="2025-06-04T07:57:00Z" level=error msg="Failed to create a new timestamp" +time="2025-06-04T07:59:15Z" level=error msg="Failed to create a new timestamp" --- PASS: TestCreateTimestampNoKeyInCrypto (0.00s) === RUN TestGetTimestampKeyCreateWithFailingStore -time="2025-06-04T07:57:00Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2025-06-04T07:59:15Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetTimestampKeyCreateWithFailingStore (0.00s) === RUN TestGetTimestampKeyCreateWithCorruptedStore -time="2025-06-04T07:57:00Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve timestamp key ID" +time="2025-06-04T07:59:15Z" 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 -ok github.com/theupdateframework/notary/server/timestamp 0.051s +ok github.com/theupdateframework/notary/server/timestamp 0.056s === RUN TestHealthCheckKMUnhealthy --- PASS: TestHealthCheckKMUnhealthy (0.00s) === RUN TestHealthCheckSignerUnhealthy @@ -3069,23 +3105,23 @@ === RUN TestHealthCheckNonexistentService --- PASS: TestHealthCheckNonexistentService (0.00s) === RUN TestGetPrivateKeyAndSignWithExistingKey -time="2025-06-04T07:57:00Z" level=info msg="Sign: Signed message! with KeyID 917ad3271c6c93e257b817a8a2e7102bbf9a213c03b2b0c8a498309360144c32" go.version=go1.22.2 +time="2025-06-04T07:59:15Z" level=info msg="Sign: Signed message! with KeyID fd04aebcb11cf884b7b2c6bcfa7ca217fb02dd0b435fb7164be5da0b51d61ae1" go.version=go1.22.2 --- PASS: TestGetPrivateKeyAndSignWithExistingKey (0.00s) === RUN TestCannotSignWithKeyThatDoesntExist -time="2025-06-04T07:57:00Z" level=error msg="Sign: key 690065300e46fca49d6b3c831d8691d443518f97c8ed820f16389c8ab7cd8c4b not found" go.version=go1.22.2 +time="2025-06-04T07:59:15Z" level=error msg="Sign: key 76884e7991c9d6203a992ac2236c9d8fa31098a1e541a4eb575127c6693406c1 not found" go.version=go1.22.2 --- PASS: TestCannotSignWithKeyThatDoesntExist (0.00s) === RUN TestCryptoSignerInterfaceBehavior -time="2025-06-04T07:57:00Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.22.2 -time="2025-06-04T07:57:00Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.22.2 -time="2025-06-04T07:57:00Z" level=info msg="CreateKey: Created KeyID c3be9af4d8313a35486b6375ee5d9e9689e1d02222187f737b4564f1bd3bfd6d" go.version=go1.22.2 -time="2025-06-04T07:57:00Z" level=info msg="CreateKey: Created KeyID 618ffdd82c7cdfab8067f5328a3496862859b1744c21f6c9c9c473a66cdfcee9" go.version=go1.22.2 -time="2025-06-04T07:57:00Z" level=error msg="GetKeyInfo: key c3be9af4d8313a35486b6375ee5d9e9689e1d02222187f737b4564f1bd3bfd6d not found" go.version=go1.22.2 -time="2025-06-04T07:57:00Z" level=error msg="GetKeyInfo: key 618ffdd82c7cdfab8067f5328a3496862859b1744c21f6c9c9c473a66cdfcee9 not found" go.version=go1.22.2 ---- PASS: TestCryptoSignerInterfaceBehavior (0.03s) +time="2025-06-04T07:59:15Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.22.2 +time="2025-06-04T07:59:15Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.22.2 +time="2025-06-04T07:59:15Z" level=info msg="CreateKey: Created KeyID 3e9c64a094f063c4f98bad1ef0b04a95c79c079b30129f2753fb5e6f246c3aec" go.version=go1.22.2 +time="2025-06-04T07:59:15Z" level=info msg="CreateKey: Created KeyID c8689ee50cc48a453c05d830b0f12e1e8d50af4c5a1ac14b973a7fef30eb46bc" go.version=go1.22.2 +time="2025-06-04T07:59:15Z" level=error msg="GetKeyInfo: key c8689ee50cc48a453c05d830b0f12e1e8d50af4c5a1ac14b973a7fef30eb46bc not found" go.version=go1.22.2 +time="2025-06-04T07:59:15Z" level=error msg="GetKeyInfo: key 3e9c64a094f063c4f98bad1ef0b04a95c79c079b30129f2753fb5e6f246c3aec not found" go.version=go1.22.2 +--- PASS: TestCryptoSignerInterfaceBehavior (0.00s) PASS -ok github.com/theupdateframework/notary/signer 0.077s +ok github.com/theupdateframework/notary/signer 0.044s === RUN TestGetSuccessPopulatesCache ---- PASS: TestGetSuccessPopulatesCache (0.00s) +--- PASS: TestGetSuccessPopulatesCache (0.01s) === RUN TestAddKeyPopulatesCacheIfSuccessful --- PASS: TestAddKeyPopulatesCacheIfSuccessful (0.00s) === RUN TestDeleteKeyRemovesKeyFromCache @@ -3104,24 +3140,24 @@ === RUN TestSQLKeyCanOnlyBeAddedOnce (/build/reproducible-path/notary-0.7.0+ds1/_build/src/github.com/theupdateframework/notary/signer/keydbstore/sql_keydbstore.go:98) -[2025-06-04 07:57:00]  UNIQUE constraint failed: private_keys.key_id  ---- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.02s) +[2025-06-04 07:59:16]  UNIQUE constraint failed: private_keys.key_id  +--- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.04s) === RUN TestSQLCreateDelete ---- PASS: TestSQLCreateDelete (0.02s) +--- PASS: TestSQLCreateDelete (0.05s) === RUN TestSQLKeyRotation --- PASS: TestSQLKeyRotation (0.05s) === RUN TestSQLSigningMarksKeyActive -time="2025-06-04T07:57:00Z" level=error msg="Key 948349e565c6511199db18f635cacbde75aa5109ed69881599f4a623ecb06d2d was just used to sign hash successful, db closed, error when trying to mark key as active: sql: database is closed" ---- PASS: TestSQLSigningMarksKeyActive (0.07s) +time="2025-06-04T07:59:16Z" level=error msg="Key 6e3311888aa2a16ede56464cbabe24393874c4b7f88b513877873b9eb8e10aa9 was just used to sign hash successful, db closed, error when trying to mark key as active: sql: database is closed" +--- PASS: TestSQLSigningMarksKeyActive (0.06s) === RUN TestSQLCreateKey (/build/reproducible-path/notary-0.7.0+ds1/_build/src/github.com/theupdateframework/notary/signer/keydbstore/sql_keydbstore.go:197) -[2025-06-04 07:57:00]  sql: database is closed  ---- PASS: TestSQLCreateKey (0.06s) +[2025-06-04 07:59:16]  sql: database is closed  +--- PASS: TestSQLCreateKey (0.04s) === RUN TestSQLUnimplementedInterfaceBehavior --- PASS: TestSQLUnimplementedInterfaceBehavior (0.01s) PASS -ok github.com/theupdateframework/notary/signer/keydbstore 0.281s +ok github.com/theupdateframework/notary/signer/keydbstore 0.320s === RUN TestSet --- PASS: TestSet (0.00s) === RUN TestSetWithNoParentDirectory @@ -3163,7 +3199,7 @@ === RUN Test404Error --- PASS: Test404Error (0.00s) === RUN Test50XErrors ---- PASS: Test50XErrors (0.04s) +--- PASS: Test50XErrors (0.00s) === RUN Test400Error --- PASS: Test400Error (0.00s) === RUN TestTranslateErrorsParse400Errors @@ -3179,7 +3215,7 @@ === RUN TestHTTPStoreGetKey --- PASS: TestHTTPStoreGetKey (0.00s) === RUN TestHTTPStoreGetRotateKeySizeLimited ---- PASS: TestHTTPStoreGetRotateKeySizeLimited (0.01s) +--- PASS: TestHTTPStoreGetRotateKeySizeLimited (0.03s) === RUN TestHTTPOffline --- PASS: TestHTTPOffline (0.00s) === RUN TestErrServerUnavailable @@ -3200,7 +3236,7 @@ === RUN TestMemoryStoreMetadata --- PASS: TestMemoryStoreMetadata (0.00s) PASS -ok github.com/theupdateframework/notary/storage 0.085s +ok github.com/theupdateframework/notary/storage 0.064s === RUN TestExportKeys --- PASS: TestExportKeys (0.00s) === RUN TestExportKeysByGUN @@ -3212,28 +3248,28 @@ === RUN TestImportKeys --- PASS: TestImportKeys (0.00s) === RUN TestImportNoPath ---- PASS: TestImportNoPath (0.00s) +--- PASS: TestImportNoPath (0.01s) === RUN TestNonRootPathInference --- PASS: TestNonRootPathInference (0.00s) === RUN TestBlockHeaderPrecedenceRoleAndGun ---- PASS: TestBlockHeaderPrecedenceRoleAndGun (0.00s) +--- PASS: TestBlockHeaderPrecedenceRoleAndGun (0.01s) === RUN TestBlockHeaderPrecedenceGunFromPath --- PASS: TestBlockHeaderPrecedenceGunFromPath (0.00s) === RUN TestImportKeys2InOneFile --- PASS: TestImportKeys2InOneFile (0.00s) === RUN TestImportKeys2InOneFileNoPath ---- PASS: TestImportKeys2InOneFileNoPath (0.00s) +--- PASS: TestImportKeys2InOneFileNoPath (0.01s) === RUN TestEncryptedKeyImportFail -time="2025-06-04T07:57:00Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" +time="2025-06-04T07:59:16Z" 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) +--- PASS: TestEncryptedKeyImportSuccess (0.01s) === RUN TestEncryption ---- PASS: TestEncryption (0.00s) +--- PASS: TestEncryption (0.01s) === RUN TestAddKey --- PASS: TestAddKey (0.01s) === RUN TestKeyStoreInternalState ---- PASS: TestKeyStoreInternalState (0.00s) +--- PASS: TestKeyStoreInternalState (0.01s) === RUN TestGet --- PASS: TestGet (0.01s) === RUN TestGetLegacyKey @@ -3241,11 +3277,11 @@ === RUN TestListKeys --- PASS: TestListKeys (0.01s) === RUN TestAddGetKeyMemStore ---- PASS: TestAddGetKeyMemStore (0.01s) +--- PASS: TestAddGetKeyMemStore (0.00s) === RUN TestAddGetKeyInfoMemStore --- PASS: TestAddGetKeyInfoMemStore (0.00s) === RUN TestGetDecryptedWithTamperedCipherText -time="2025-06-04T07:57:00Z" level=error msg="PEM block is empty" +time="2025-06-04T07:59:16Z" level=error msg="PEM block is empty" --- PASS: TestGetDecryptedWithTamperedCipherText (0.00s) === RUN TestGetDecryptedWithInvalidPassphrase --- PASS: TestGetDecryptedWithInvalidPassphrase (0.01s) @@ -3256,43 +3292,43 @@ === RUN TestKeysAreCached --- PASS: TestKeysAreCached (0.00s) PASS -ok github.com/theupdateframework/notary/trustmanager 0.109s +ok github.com/theupdateframework/notary/trustmanager 0.160s === RUN TestRemoteStore -time="2025-06-04T07:57:01Z" level=info msg="listing files from localhost:9888" -time="2025-06-04T07:57:01Z" level=info msg="listing files from localhost:9888" ---- PASS: TestRemoteStore (0.04s) +time="2025-06-04T07:59:16Z" level=info msg="listing files from localhost:9888" +time="2025-06-04T07:59:16Z" level=info msg="listing files from localhost:9888" +--- PASS: TestRemoteStore (0.07s) === RUN TestErrors -time="2025-06-04T07:57:01Z" level=error msg="failed to store: test error" ---- PASS: TestErrors (0.03s) +time="2025-06-04T07:59:16Z" level=error msg="failed to store: test error" +--- PASS: TestErrors (0.06s) === RUN TestNewGRPCStorage --- PASS: TestNewGRPCStorage (0.00s) === RUN TestGRPCStorage --- PASS: TestGRPCStorage (0.00s) PASS -ok github.com/theupdateframework/notary/trustmanager/remoteks 0.093s +ok github.com/theupdateframework/notary/trustmanager/remoteks 0.166s === RUN TestWildcardMatch --- PASS: TestWildcardMatch (0.00s) === RUN TestValidateRoot -2025/06/04 07:57:01 [INFO] generate received request -2025/06/04 07:57:01 [INFO] received CSR -2025/06/04 07:57:01 [INFO] generating key: ecdsa-256 -2025/06/04 07:57:01 [INFO] encoded CSR -2025/06/04 07:57:01 [INFO] signed certificate with serial number 560588551443598792010727494469060396176680212654 -2025/06/04 07:57:01 [INFO] received CSR -2025/06/04 07:57:01 [INFO] generating key: ecdsa-256 -2025/06/04 07:57:01 [INFO] encoded CSR -2025/06/04 07:57:01 [INFO] signed certificate with serial number 247945898488757233078461697438163210237628482250 -2025/06/04 07:57:01 [INFO] received CSR -2025/06/04 07:57:01 [INFO] generating key: ecdsa-256 -2025/06/04 07:57:01 [INFO] encoded CSR -2025/06/04 07:57:01 [INFO] signed certificate with serial number 161678875670397156126268499378351688552600538682 ---- PASS: TestValidateRoot (0.01s) +2025/06/04 07:59:17 [INFO] generate received request +2025/06/04 07:59:17 [INFO] received CSR +2025/06/04 07:59:17 [INFO] generating key: ecdsa-256 +2025/06/04 07:59:17 [INFO] encoded CSR +2025/06/04 07:59:17 [INFO] signed certificate with serial number 18350220478689129973642338792154190321988826304 +2025/06/04 07:59:17 [INFO] received CSR +2025/06/04 07:59:17 [INFO] generating key: ecdsa-256 +2025/06/04 07:59:17 [INFO] encoded CSR +2025/06/04 07:59:17 [INFO] signed certificate with serial number 636943492065933186059617002851926262805393869016 +2025/06/04 07:59:17 [INFO] received CSR +2025/06/04 07:59:17 [INFO] generating key: ecdsa-256 +2025/06/04 07:59:17 [INFO] encoded CSR +2025/06/04 07:59:17 [INFO] signed certificate with serial number 634716308658653529112898128317829472196568220379 +--- PASS: TestValidateRoot (0.03s) === RUN TestValidateRootWithoutTOFUS --- PASS: TestValidateRootWithoutTOFUS (0.00s) === RUN TestValidateRootWithPinnedCert --- PASS: TestValidateRootWithPinnedCert (0.00s) === RUN TestValidateRootWithPinnedCertAndIntermediates ---- PASS: TestValidateRootWithPinnedCertAndIntermediates (0.01s) +--- PASS: TestValidateRootWithPinnedCertAndIntermediates (0.03s) === RUN TestValidateRootFailuresWithPinnedCert --- PASS: TestValidateRootFailuresWithPinnedCert (0.00s) === RUN TestValidateRootWithPinnedCA @@ -3300,18 +3336,18 @@ === RUN TestValidateSuccessfulRootRotation --- PASS: TestValidateSuccessfulRootRotation (0.03s) === RUN TestValidateRootRotationMissingOrigSig ---- PASS: TestValidateRootRotationMissingOrigSig (0.02s) +--- PASS: TestValidateRootRotationMissingOrigSig (0.03s) === RUN TestValidateRootRotationMissingNewSig --- PASS: TestValidateRootRotationMissingNewSig (0.02s) === RUN TestValidateRootRotationTrustPinning --- PASS: TestValidateRootRotationTrustPinning (0.03s) === RUN TestValidateRootRotationTrustPinningInvalidCA ---- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.02s) +--- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.03s) === RUN TestParsePEMPublicKey -time="2025-06-04T07:57:01Z" level=warning msg="certificate with CN notary is near expiry" +time="2025-06-04T07:59:17Z" level=warning msg="certificate with CN notary is near expiry" --- PASS: TestParsePEMPublicKey (0.00s) === RUN TestCheckingCertExpiry -time="2025-06-04T07:57:01Z" level=warning msg="certificate with CN notary is near expiry" +time="2025-06-04T07:59:17Z" level=warning msg="certificate with CN notary is near expiry" --- PASS: TestCheckingCertExpiry (0.00s) === RUN TestValidateRootWithExpiredIntermediate --- PASS: TestValidateRootWithExpiredIntermediate (0.00s) @@ -3320,7 +3356,7 @@ === RUN TestWildcardMatching --- PASS: TestWildcardMatching (0.00s) PASS -ok github.com/theupdateframework/notary/trustpinning 0.184s +ok github.com/theupdateframework/notary/trustpinning 0.288s === RUN TestInitSnapshotNoTargets --- PASS: TestInitSnapshotNoTargets (0.01s) === RUN TestInitRepo @@ -3328,11 +3364,11 @@ === RUN TestUpdateDelegations --- PASS: TestUpdateDelegations (0.00s) === RUN TestPurgeDelegationsKeyFromTop -time="2025-06-04T07:57:01Z" 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-06-04T07:57:01Z" 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-06-04T07:59:17Z" 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-06-04T07:59:17Z" 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-06-04T07:57:01Z" 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-06-04T07:59:17Z" 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) @@ -3345,7 +3381,7 @@ === RUN TestUpdateDelegationsRoleThatIsMissingDelegationKey --- PASS: TestUpdateDelegationsRoleThatIsMissingDelegationKey (0.00s) === RUN TestUpdateDelegationsNotEnoughKeys -time="2025-06-04T07:57:01Z" 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-06-04T07:59:17Z" 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) @@ -3416,9 +3452,9 @@ === RUN TestSignRootOldKeyCertMissing --- PASS: TestSignRootOldKeyCertMissing (0.01s) === RUN TestRootKeyRotation ---- PASS: TestRootKeyRotation (0.03s) +--- PASS: TestRootKeyRotation (0.05s) === RUN TestBuilderLoadsValidRolesOnly ---- PASS: TestBuilderLoadsValidRolesOnly (0.00s) +--- PASS: TestBuilderLoadsValidRolesOnly (0.01s) === RUN TestBuilderOnlyAcceptsRootFirstWhenLoading --- PASS: TestBuilderOnlyAcceptsRootFirstWhenLoading (0.00s) === RUN TestBuilderOnlyAcceptsDelegationsAfterParent @@ -3438,7 +3474,7 @@ === RUN TestGenerateTimestampInvalidOperations --- PASS: TestGenerateTimestampInvalidOperations (0.02s) === RUN TestGetConsistentInfo ---- PASS: TestGetConsistentInfo (0.00s) +--- PASS: TestGetConsistentInfo (0.02s) === RUN TestTimestampPreAndPostChecksumming --- PASS: TestTimestampPreAndPostChecksumming (0.01s) === RUN TestSnapshotLoadedFirstChecksumsOthers @@ -3446,7 +3482,7 @@ === RUN TestSnapshotLoadedAfterChecksumsOthersRetroactively --- PASS: TestSnapshotLoadedAfterChecksumsOthersRetroactively (0.01s) PASS -ok github.com/theupdateframework/notary/tuf 0.193s +ok github.com/theupdateframework/notary/tuf 0.223s === RUN TestMergeStrSlicesExclusive --- PASS: TestMergeStrSlicesExclusive (0.00s) === RUN TestMergeStrSlicesOverlap @@ -3494,7 +3530,7 @@ === RUN TestRootFromSignedCopiesSignatures --- PASS: TestRootFromSignedCopiesSignatures (0.00s) === RUN TestRootFromSignedValidatesRoleData ---- PASS: TestRootFromSignedValidatesRoleData (0.00s) +--- PASS: TestRootFromSignedValidatesRoleData (0.01s) === RUN TestRootFromSignedValidatesRoleType --- PASS: TestRootFromSignedValidatesRoleType (0.00s) === RUN TestRootFromSignedValidatesVersion @@ -3580,7 +3616,7 @@ === RUN TestFileMetaEquals --- PASS: TestFileMetaEquals (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/data 0.028s +ok github.com/theupdateframework/notary/tuf/data 0.043s === RUN TestListKeys --- PASS: TestListKeys (0.00s) === RUN TestGetKeys @@ -3612,7 +3648,7 @@ === RUN TestRSAPSSVerifierWithInvalidKeyType --- PASS: TestRSAPSSVerifierWithInvalidKeyType (0.00s) === RUN TestRSAPSSVerifierWithInvalidKeyLength ---- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.04s) +--- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.05s) === RUN TestRSAPSSVerifierWithInvalidKey --- PASS: TestRSAPSSVerifierWithInvalidKey (0.00s) === RUN TestRSAPSSVerifierWithInvalidSignature @@ -3626,7 +3662,7 @@ === RUN TestRSAPKCS1v15VerifierWithInvalidKey --- PASS: TestRSAPKCS1v15VerifierWithInvalidKey (0.00s) === RUN TestRSAPKCS1v15VerifierWithInvalidSignature -time="2025-06-04T07:57:01Z" level=error msg="Failed verification: crypto/rsa: verification error" +time="2025-06-04T07:59:17Z" level=error msg="Failed verification: crypto/rsa: verification error" --- PASS: TestRSAPKCS1v15VerifierWithInvalidSignature (0.00s) === RUN TestECDSAVerifier --- PASS: TestECDSAVerifier (0.00s) @@ -3669,14 +3705,14 @@ === RUN TestVerifyVersion --- PASS: TestVerifyVersion (0.00s) === RUN TestVerifyExpiry -time="2025-06-04T07:57:01Z" level=error msg="Metadata for root expired" +time="2025-06-04T07:59:17Z" 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.143s +ok github.com/theupdateframework/notary/tuf/signed 0.142s === RUN TestNewSwizzler --- PASS: TestNewSwizzler (0.01s) === RUN TestSwizzlerSetInvalidJSON @@ -3694,7 +3730,7 @@ === RUN TestSwizzlerRemoveMetadata --- PASS: TestSwizzlerRemoveMetadata (0.00s) === RUN TestSwizzlerSignMetadataWithInvalidKey ---- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.00s) +--- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.01s) === RUN TestSwizzlerOffsetMetadataVersion --- PASS: TestSwizzlerOffsetMetadataVersion (0.01s) === RUN TestSwizzlerExpireMetadata @@ -3710,13 +3746,13 @@ === RUN TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles --- PASS: TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles (0.00s) === RUN TestSwizzlerUpdateTimestamp ---- PASS: TestSwizzlerUpdateTimestamp (0.00s) +--- PASS: TestSwizzlerUpdateTimestamp (0.01s) === RUN TestMissingSigningKey --- PASS: TestMissingSigningKey (0.01s) === RUN TestSwizzlerMutateRoot ---- PASS: TestSwizzlerMutateRoot (0.00s) +--- PASS: TestSwizzlerMutateRoot (0.01s) === RUN TestSwizzlerMutateTimestamp ---- PASS: TestSwizzlerMutateTimestamp (0.01s) +--- PASS: TestSwizzlerMutateTimestamp (0.00s) === RUN TestSwizzlerMutateSnapshot --- PASS: TestSwizzlerMutateSnapshot (0.00s) === RUN TestSwizzlerMutateTargets @@ -3726,11 +3762,11 @@ === RUN TestSwizzlerRotateKeyDelegationRole --- PASS: TestSwizzlerRotateKeyDelegationRole (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/testutils 0.132s +ok github.com/theupdateframework/notary/tuf/testutils 0.154s === RUN TestConvertTUFKeyToPKCS8 ---- PASS: TestConvertTUFKeyToPKCS8 (0.01s) +--- PASS: TestConvertTUFKeyToPKCS8 (0.02s) === RUN TestParsePKCS8ToTufKey ---- PASS: TestParsePKCS8ToTufKey (0.03s) +--- PASS: TestParsePKCS8ToTufKey (0.05s) === RUN TestPEMtoPEM --- PASS: TestPEMtoPEM (0.00s) === RUN TestRoleListLen @@ -3794,7 +3830,7 @@ === RUN TestValidateCertificateWithShortKey --- PASS: TestValidateCertificateWithShortKey (0.03s) PASS -ok github.com/theupdateframework/notary/tuf/utils 0.099s +ok github.com/theupdateframework/notary/tuf/utils 0.153s === RUN TestNewSerializableErrorNonValidationError --- PASS: TestNewSerializableErrorNonValidationError (0.00s) === RUN TestNewSerializableErrorValidationError @@ -3810,7 +3846,7 @@ === RUN TestUnmarshalInvalidJSON --- PASS: TestUnmarshalInvalidJSON (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/validation 0.010s +ok github.com/theupdateframework/notary/tuf/validation 0.029s === RUN TestSetSignalTrap --- PASS: TestSetSignalTrap (0.00s) === RUN TestLogLevelSignalHandle @@ -3910,7 +3946,7 @@ === RUN TestDoAuthWildcardImage --- PASS: TestDoAuthWildcardImage (0.00s) PASS -ok github.com/theupdateframework/notary/utils 0.033s +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\+ds1/_build -O--buildsystem=golang dh_prep -O--builddirectory=/build/reproducible-path/notary-0.7.0\+ds1/_build -O--buildsystem=golang @@ -3948,8 +3984,8 @@ dh_md5sums -O--builddirectory=/build/reproducible-path/notary-0.7.0\+ds1/_build -O--buildsystem=golang dh_builddeb -O--builddirectory=/build/reproducible-path/notary-0.7.0\+ds1/_build -O--buildsystem=golang dpkg-deb: building package 'notary' in '../notary_0.7.0+ds1-2_arm64.deb'. -dpkg-deb: building package 'notary-dbgsym' in '../notary-dbgsym_0.7.0+ds1-2_arm64.deb'. dpkg-deb: building package 'golang-github-docker-notary-dev' in '../golang-github-docker-notary-dev_0.7.0+ds1-2_all.deb'. +dpkg-deb: building package 'notary-dbgsym' in '../notary-dbgsym_0.7.0+ds1-2_arm64.deb'. dpkg-genbuildinfo --build=binary -O../notary_0.7.0+ds1-2_arm64.buildinfo dpkg-genchanges --build=binary -O../notary_0.7.0+ds1-2_arm64.changes dpkg-genchanges: info: binary-only upload (no source code included) @@ -3957,12 +3993,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: not including original source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/955172/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/955172/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/1512115 and its subdirectories -I: Current time: Tue Jun 3 19:57:53 -12 2025 -I: pbuilder-time-stamp: 1749023873 +I: removing directory /srv/workspace/pbuilder/955172 and its subdirectories +I: Current time: Wed Jun 4 22:00:00 +14 2025 +I: pbuilder-time-stamp: 1749024000