Diff of the two buildlogs: -- --- b1/build.log 2024-04-12 18:36:24.007989174 +0000 +++ b2/build.log 2024-04-12 18:46:56.241086555 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Fri Apr 12 06:23:46 -12 2024 -I: pbuilder-time-stamp: 1712946226 +I: Current time: Sat Apr 13 08:38:24 +14 2024 +I: pbuilder-time-stamp: 1712947104 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/14826/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/32491/tmp/hooks/D01_modify_environment starting +debug: Running on virt32z. +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 Apr 12 18:38 /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/32491/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/32491/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='armhf' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=3 ' - DISTRIBUTION='trixie' - HOME='/root' - HOST_ARCH='armhf' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="21" [3]="1" [4]="release" [5]="arm-unknown-linux-gnueabihf") + 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=armhf + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=4 ' + DIRSTACK=() + DISTRIBUTION=trixie + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=arm + HOST_ARCH=armhf IFS=' ' - INVOCATION_ID='cf9363a60e9c4b2a943691226b4d979e' - 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='14826' - PS1='# ' - PS2='> ' + INVOCATION_ID=9b63b265e04445aeb5b4e807dcfc76d6 + LANG=C + LANGUAGE=it_CH:it + LC_ALL=C + MACHTYPE=arm-unknown-linux-gnueabihf + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnueabihf + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=32491 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.DZdrm7gR/pbuilderrc_ZC45 --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.DZdrm7gR/b1 --logfile b1/build.log notary_0.7.0+ds1-2.dsc' - SUDO_GID='113' - SUDO_UID='107' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://10.0.0.15:3142/' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.DZdrm7gR/pbuilderrc_jRmR --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.DZdrm7gR/b2 --logfile b2/build.log notary_0.7.0+ds1-2.dsc' + SUDO_GID=110 + SUDO_UID=103 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://10.0.0.15:3142/ I: uname -a - Linux virt64b 6.1.0-18-arm64 #1 SMP Debian 6.1.76-1 (2024-02-01) aarch64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-18-armmp-lpae #1 SMP Debian 6.1.76-1 (2024-02-01) armv7l GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 Apr 11 11:24 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/14826/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Apr 10 11:24 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/32491/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -355,7 +387,7 @@ Get: 187 http://deb.debian.org/debian trixie/main armhf golang-goprotobuf-dev armhf 1.3.5-4+b6 [3428 B] Get: 188 http://deb.debian.org/debian trixie/main armhf golang-gopkg-rethinkdb-rethinkdb-go.v6-dev all 6.2.1-2 [106 kB] Get: 189 http://deb.debian.org/debian trixie/main armhf openssl armhf 3.1.5-1 [1208 kB] -Fetched 104 MB in 2s (46.0 MB/s) +Fetched 104 MB in 1s (80.3 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package golang-golang-x-sys-dev. (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 19635 files and directories currently installed.) @@ -1126,7 +1158,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/32491/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/32491/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 @@ -1405,16 +1441,16 @@ src/github.com/theupdateframework/notary/utils/http.go src/github.com/theupdateframework/notary/utils/http_test.go src/github.com/theupdateframework/notary/version/version.go - cd _build && go install -trimpath -v -p 3 -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/unsafeheader + cd _build && go install -trimpath -v -p 4 -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/goarch +internal/unsafeheader internal/cpu internal/coverage/rtcov -internal/abi internal/chacha8rand -internal/bytealg +internal/abi internal/godebugs internal/goexperiment +internal/bytealg internal/goos runtime/internal/atomic runtime/internal/math @@ -1423,11 +1459,10 @@ internal/race sync/atomic math/bits -math unicode/utf8 +math internal/itoa unicode -runtime cmp slices encoding @@ -1435,6 +1470,7 @@ log/internal crypto/internal/alias crypto/internal/boring/sig +runtime vendor/golang.org/x/crypto/cryptobyte/asn1 internal/nettrace container/list @@ -1450,146 +1486,146 @@ sync runtime/metrics internal/testlog -internal/bisect internal/singleflight +internal/bisect runtime/cgo +github.com/spf13/viper/internal/encoding +google.golang.org/grpc/internal/buffer internal/godebug +google.golang.org/grpc/internal/grpcsync errors -io sort -hash -strconv +math/rand +io internal/oserror +strconv syscall +hash +internal/safefilepath +path +bytes crypto reflect +strings +bufio +crypto/internal/randutil internal/syscall/unix time -internal/safefilepath internal/syscall/execenv -path -bytes -strings +crypto/internal/nistec/fiat internal/poll -encoding/binary io/fs +context +encoding/binary +os internal/fmtsort +vendor/golang.org/x/net/dns/dnsmessage crypto/md5 encoding/base64 -os -context -bufio golang.org/x/sys/unix crypto/cipher crypto/internal/boring -crypto/internal/randutil -math/rand github.com/theupdateframework/notary fmt crypto/aes crypto/internal/edwards25519/field -crypto/internal/nistec/fiat -encoding/json -github.com/docker/go/canonical/json -log -github.com/sirupsen/logrus -math/big crypto/internal/nistec crypto/sha512 crypto/sha256 crypto/des crypto/internal/edwards25519 +encoding/json +github.com/docker/go/canonical/json +log +math/big crypto/ecdh crypto/sha1 encoding/hex encoding/pem -vendor/golang.org/x/net/dns/dnsmessage internal/intern +net/url net/netip +github.com/sirupsen/logrus +path/filepath +io/ioutil +net +regexp/syntax +crypto/hmac +golang.org/x/crypto/pbkdf2 crypto/rand -github.com/docker/distribution/uuid crypto/elliptic +github.com/docker/distribution/uuid crypto/internal/bigmod crypto/internal/boring/bbig -encoding/asn1 crypto/rsa +encoding/asn1 crypto/dsa crypto/ed25519 -net -net/url -vendor/golang.org/x/crypto/cryptobyte -crypto/x509/pkix -crypto/ecdsa -path/filepath golang.org/x/crypto/ed25519 -io/ioutil -regexp/syntax -crypto/hmac -golang.org/x/crypto/pbkdf2 +regexp github.com/theupdateframework/notary/tuf/validation mime +vendor/golang.org/x/crypto/cryptobyte +crypto/x509/pkix mime/quotedprintable +crypto/ecdsa compress/flate -regexp hash/crc32 crypto/rc4 vendor/golang.org/x/crypto/chacha20 -compress/gzip vendor/golang.org/x/crypto/internal/poly1305 vendor/golang.org/x/crypto/hkdf -vendor/golang.org/x/text/transform vendor/golang.org/x/crypto/chacha20poly1305 +vendor/golang.org/x/text/transform +compress/gzip vendor/golang.org/x/text/unicode/bidi vendor/golang.org/x/text/unicode/norm -vendor/golang.org/x/text/secure/bidirule vendor/golang.org/x/net/http2/hpack net/http/internal +vendor/golang.org/x/text/secure/bidirule net/http/internal/ascii github.com/miekg/pkcs11 -vendor/golang.org/x/net/idna flag encoding/csv github.com/fsnotify/fsnotify +vendor/golang.org/x/net/idna github.com/spf13/afero/internal/common github.com/spf13/afero/mem golang.org/x/text/transform golang.org/x/text/runes -golang.org/x/text/unicode/norm -crypto/x509 -net/textproto -mime/multipart -vendor/golang.org/x/net/http/httpguts -vendor/golang.org/x/net/http/httpproxy -github.com/mitchellh/mapstructure -github.com/theupdateframework/notary/tuf/data -crypto/tls -github.com/theupdateframework/notary/client/changelist -github.com/theupdateframework/notary/tuf/utils html +golang.org/x/text/unicode/norm text/template/parse -text/template -html/template -net/http/httptrace -net/http -github.com/spf13/cast github.com/spf13/jwalterweatherman -github.com/spf13/pflag -github.com/spf13/viper/internal/encoding +text/template github.com/subosito/gotenv -github.com/spf13/viper/internal/encoding/dotenv github.com/hashicorp/hcl/hcl/strconv github.com/hashicorp/hcl/hcl/token github.com/hashicorp/hcl/hcl/ast github.com/hashicorp/hcl/hcl/scanner github.com/hashicorp/hcl/hcl/parser +html/template github.com/hashicorp/hcl/json/token github.com/hashicorp/hcl/json/scanner +crypto/x509 +net/textproto +vendor/golang.org/x/net/http/httpproxy +mime/multipart +vendor/golang.org/x/net/http/httpguts +github.com/mitchellh/mapstructure +github.com/spf13/cast +github.com/spf13/pflag +github.com/spf13/viper/internal/encoding/dotenv github.com/hashicorp/hcl/json/parser +github.com/theupdateframework/notary/tuf/data +crypto/tls +github.com/theupdateframework/notary/client/changelist +github.com/theupdateframework/notary/tuf/utils github.com/hashicorp/hcl text/tabwriter github.com/hashicorp/hcl/hcl/printer -github.com/spf13/viper/internal/encoding/hcl gopkg.in/ini.v1 +github.com/spf13/viper/internal/encoding/hcl github.com/spf13/viper/internal/encoding/json github.com/pelletier/go-toml/v2/internal/danger github.com/pelletier/go-toml/v2/unstable @@ -1597,557 +1633,566 @@ github.com/pelletier/go-toml/v2 github.com/spf13/viper/internal/encoding/ini gopkg.in/yaml.v3 -github.com/theupdateframework/notary/storage -github.com/spf13/afero -github.com/theupdateframework/notary/trustmanager -github.com/theupdateframework/notary/cryptoservice -github.com/theupdateframework/notary/tuf/signed -github.com/theupdateframework/notary/trustmanager/yubikey -github.com/theupdateframework/notary/trustpinning -github.com/magiconair/properties github.com/spf13/viper/internal/encoding/toml -github.com/spf13/viper/internal/encoding/yaml -github.com/theupdateframework/notary/tuf github.com/golang/protobuf/proto -github.com/spf13/viper/internal/encoding/javaproperties -github.com/spf13/viper -github.com/theupdateframework/notary/client +net/http/httptrace +net/http +github.com/spf13/viper/internal/encoding/yaml golang.org/x/net/context golang.org/x/net/internal/timeseries google.golang.org/grpc/backoff google.golang.org/grpc/internal/grpclog google.golang.org/grpc/grpclog -golang.org/x/net/trace google.golang.org/grpc/connectivity google.golang.org/grpc/attributes google.golang.org/grpc/internal google.golang.org/grpc/internal/credentials google.golang.org/grpc/metadata +github.com/golang/protobuf/ptypes/empty +google.golang.org/grpc/credentials google.golang.org/grpc/internal/grpcrand google.golang.org/grpc/codes +google.golang.org/grpc/resolver google.golang.org/grpc/encoding +google.golang.org/grpc/encoding/proto +google.golang.org/grpc/balancer google.golang.org/grpc/internal/backoff +google.golang.org/grpc/balancer/base google.golang.org/grpc/internal/balancerload -google.golang.org/grpc/internal/buffer -google.golang.org/grpc/internal/envconfig -google.golang.org/grpc/internal/grpcsync -golang.org/x/text/unicode/bidi -golang.org/x/net/http2/hpack -github.com/golang/protobuf/ptypes/empty -google.golang.org/grpc/credentials -google.golang.org/grpc/encoding/proto github.com/golang/protobuf/ptypes/any +google.golang.org/grpc/balancer/roundrobin github.com/golang/protobuf/ptypes/duration -google.golang.org/grpc/resolver github.com/golang/protobuf/ptypes/timestamp -google.golang.org/genproto/googleapis/rpc/status -google.golang.org/grpc/balancer +google.golang.org/grpc/internal/grpcutil github.com/golang/protobuf/ptypes google.golang.org/grpc/binarylog/grpc_binarylog_v1 -google.golang.org/grpc/balancer/base -google.golang.org/grpc/internal/grpcutil -google.golang.org/grpc/balancer/roundrobin +google.golang.org/genproto/googleapis/rpc/status google.golang.org/grpc/internal/status -google.golang.org/grpc/internal/channelz google.golang.org/grpc/status +google.golang.org/grpc/internal/channelz +google.golang.org/grpc/internal/envconfig +google.golang.org/grpc/internal/binarylog google.golang.org/grpc/balancer/grpclb/state google.golang.org/grpc/internal/resolver/dns -google.golang.org/grpc/internal/binarylog google.golang.org/grpc/internal/resolver/passthrough google.golang.org/grpc/internal/serviceconfig -golang.org/x/text/secure/bidirule +golang.org/x/text/unicode/bidi +golang.org/x/net/http2/hpack google.golang.org/grpc/internal/syscall google.golang.org/grpc/keepalive -golang.org/x/net/idna google.golang.org/grpc/peer google.golang.org/grpc/stats google.golang.org/grpc/tap -net/http/httputil -github.com/bugsnag/bugsnag-go/device github.com/pkg/errors -github.com/bugsnag/bugsnag-go/errors +github.com/bugsnag/bugsnag-go/device +golang.org/x/text/secure/bidirule github.com/bugsnag/bugsnag-go/headers +golang.org/x/net/idna os/exec +github.com/bugsnag/bugsnag-go/errors os/signal -golang.org/x/net/http/httpguts -golang.org/x/net/http2 database/sql/driver github.com/bugsnag/panicwrap -github.com/google/uuid -github.com/gorilla/mux -github.com/bugsnag/bugsnag-go/sessions -github.com/docker/distribution/context -github.com/bugsnag/bugsnag-go -github.com/docker/distribution/registry/api/errcode -github.com/docker/distribution/registry/auth github.com/docker/go-connections/tlsconfig -github.com/Shopify/logrus-bugsnag +github.com/google/uuid database/sql +golang.org/x/net/http/httpguts github.com/opencontainers/go-digest -github.com/docker/distribution/digestset -github.com/docker/distribution/reference github.com/opencontainers/image-spec/specs-go +github.com/beorn7/perks/quantile +github.com/docker/distribution/digestset github.com/opencontainers/image-spec/specs-go/v1 -github.com/docker/distribution +github.com/docker/distribution/reference +github.com/cespare/xxhash +hash/fnv github.com/go-sql-driver/mysql +google.golang.org/protobuf/internal/pragma +go/token +github.com/theupdateframework/notary/storage +github.com/spf13/afero +github.com/magiconair/properties +github.com/theupdateframework/notary/trustmanager +github.com/theupdateframework/notary/cryptoservice +github.com/theupdateframework/notary/tuf/signed +github.com/spf13/viper/internal/encoding/javaproperties +golang.org/x/net/trace +golang.org/x/net/http2 +github.com/theupdateframework/notary/trustmanager/yubikey +github.com/theupdateframework/notary/trustpinning +github.com/spf13/viper +github.com/theupdateframework/notary/tuf +net/http/httputil +github.com/bugsnag/bugsnag-go/sessions +github.com/gorilla/mux +github.com/theupdateframework/notary/client +github.com/bugsnag/bugsnag-go +github.com/docker/distribution/context +github.com/docker/distribution/registry/api/errcode +github.com/Shopify/logrus-bugsnag +github.com/docker/distribution/registry/auth +github.com/docker/distribution github.com/docker/distribution/registry/api/v2 -google.golang.org/grpc/internal/transport +github.com/theupdateframework/notary/utils github.com/docker/distribution/registry/client/auth/challenge github.com/docker/distribution/registry/client/transport expvar -github.com/beorn7/perks/quantile -github.com/theupdateframework/notary/utils -github.com/cespare/xxhash -hash/fnv -google.golang.org/protobuf/internal/pragma -go/token google.golang.org/protobuf/internal/detrand google.golang.org/protobuf/internal/errors google.golang.org/protobuf/internal/version -github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg google.golang.org/protobuf/encoding/protowire +github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg github.com/prometheus/procfs/internal/fs +google.golang.org/grpc/internal/transport github.com/prometheus/procfs/internal/util -google.golang.org/grpc +runtime/debug google.golang.org/protobuf/reflect/protoreflect github.com/prometheus/procfs +github.com/spf13/cobra google.golang.org/protobuf/internal/descfmt google.golang.org/protobuf/internal/descopts google.golang.org/protobuf/internal/strs -google.golang.org/protobuf/internal/encoding/text -google.golang.org/protobuf/internal/encoding/defval google.golang.org/protobuf/internal/encoding/messageset -github.com/theupdateframework/notary/trustmanager/remoteks google.golang.org/protobuf/internal/genid +google.golang.org/protobuf/internal/encoding/text +google.golang.org/grpc google.golang.org/protobuf/internal/order google.golang.org/protobuf/reflect/protoregistry -github.com/theupdateframework/notary/cmd/escrow +google.golang.org/protobuf/internal/encoding/defval google.golang.org/protobuf/runtime/protoiface -runtime/debug +golang.org/x/term google.golang.org/protobuf/proto -github.com/spf13/cobra +github.com/theupdateframework/notary/passphrase +github.com/docker/distribution/health google.golang.org/protobuf/internal/filedesc google.golang.org/protobuf/encoding/prototext google.golang.org/protobuf/encoding/protodelim -golang.org/x/term -google.golang.org/protobuf/internal/encoding/tag -github.com/theupdateframework/notary/passphrase -github.com/docker/distribution/health -google.golang.org/protobuf/internal/impl golang.org/x/crypto/blowfish golang.org/x/crypto/bcrypt -github.com/docker/distribution/registry/auth/htpasswd encoding/base32 -github.com/lib/pq/scram +github.com/docker/distribution/registry/auth/htpasswd github.com/docker/libtrust +github.com/theupdateframework/notary/trustmanager/remoteks +github.com/lib/pq/scram os/user +github.com/theupdateframework/notary/cmd/escrow +google.golang.org/protobuf/internal/encoding/tag github.com/docker/distribution/registry/auth/token +google.golang.org/protobuf/internal/impl github.com/theupdateframework/notary/server/errors github.com/jinzhu/inflection go/scanner go/ast github.com/jinzhu/gorm github.com/lib/pq -google.golang.org/protobuf/internal/filetype -google.golang.org/protobuf/runtime/protoimpl -google.golang.org/protobuf/types/known/timestamppb -github.com/prometheus/client_model/go github.com/hailocab/go-hostpool -github.com/prometheus/common/model github.com/opentracing/opentracing-go/log github.com/opentracing/opentracing-go gopkg.in/rethinkdb/rethinkdb-go.v6/vendor/gopkg.in/cenkalti/backoff.v2 github.com/opentracing/opentracing-go/ext gopkg.in/rethinkdb/rethinkdb-go.v6/encoding gopkg.in/rethinkdb/rethinkdb-go.v6/ql2 -github.com/prometheus/client_golang/prometheus/internal -github.com/prometheus/common/expfmt gopkg.in/rethinkdb/rethinkdb-go.v6/types github.com/theupdateframework/notary/proto -gopkg.in/rethinkdb/rethinkdb-go.v6 +google.golang.org/protobuf/internal/filetype google.golang.org/grpc/health/grpc_health_v1 -github.com/prometheus/client_golang/prometheus -github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus -github.com/theupdateframework/notary/signer/client +gopkg.in/rethinkdb/rethinkdb-go.v6 +google.golang.org/protobuf/runtime/protoimpl internal/lazyregexp +github.com/theupdateframework/notary/signer/client internal/profile -github.com/theupdateframework/notary/storage/rethinkdb -github.com/prometheus/client_golang/prometheus/promhttp -github.com/theupdateframework/notary/server/storage +google.golang.org/protobuf/types/known/timestamppb +github.com/prometheus/client_model/go runtime/pprof -github.com/theupdateframework/notary/server/snapshot -github.com/theupdateframework/notary/server/timestamp -github.com/theupdateframework/notary/server/handlers -github.com/docker/go-metrics +github.com/prometheus/common/model runtime/trace -net/http/pprof -github.com/theupdateframework/notary/server -github.com/docker/distribution/metrics github.com/theupdateframework/notary/signer -github.com/docker/distribution/registry/storage/cache +net/http/pprof github.com/theupdateframework/notary/signer/api -github.com/docker/distribution/registry/storage/cache/memory github.com/dvsekhvalnov/jose2go/base64url -github.com/theupdateframework/notary/cmd/notary-server github.com/dvsekhvalnov/jose2go/arrays -github.com/docker/distribution/registry/client github.com/dvsekhvalnov/jose2go/aes github.com/dvsekhvalnov/jose2go/compact github.com/dvsekhvalnov/jose2go/kdf github.com/dvsekhvalnov/jose2go/keys/ecc github.com/dvsekhvalnov/jose2go/padding -github.com/dvsekhvalnov/jose2go -github.com/docker/distribution/registry/client/auth -github.com/theupdateframework/notary/cmd/notary -github.com/theupdateframework/notary/signer/keydbstore google.golang.org/grpc/health -github.com/theupdateframework/notary/cmd/notary-signer +github.com/prometheus/client_golang/prometheus/internal +github.com/prometheus/common/expfmt +github.com/dvsekhvalnov/jose2go +github.com/theupdateframework/notary/storage/rethinkdb github.com/theupdateframework/notary/tuf/testutils/keys github.com/theupdateframework/notary/tuf/testutils +github.com/theupdateframework/notary/server/storage +github.com/theupdateframework/notary/signer/keydbstore github.com/davecgh/go-spew/spew +github.com/prometheus/client_golang/prometheus +github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus +github.com/theupdateframework/notary/server/snapshot +github.com/theupdateframework/notary/cmd/notary-signer +github.com/theupdateframework/notary/server/timestamp +github.com/theupdateframework/notary/server/handlers github.com/pmezard/go-difflib/difflib net/http/internal/testcert net/http/httptest -internal/sysinfo -testing +github.com/theupdateframework/notary/server github.com/stretchr/testify/assert +github.com/theupdateframework/notary/cmd/notary-server +github.com/prometheus/client_golang/prometheus/promhttp github.com/stretchr/testify/require +github.com/docker/go-metrics +internal/sysinfo +testing +github.com/docker/distribution/metrics +github.com/docker/distribution/registry/storage/cache +github.com/docker/distribution/registry/storage/cache/memory +github.com/docker/distribution/registry/client +github.com/docker/distribution/registry/client/auth github.com/theupdateframework/notary/tuf/testutils/interfaces +github.com/theupdateframework/notary/cmd/notary 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 3 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 + cd _build && go test -vet=off -v -p 4 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 ? github.com/theupdateframework/notary [no test files] +? github.com/theupdateframework/notary/proto [no test files] +? github.com/theupdateframework/notary/server/errors [no test files] +? github.com/theupdateframework/notary/signer/api [no test files] +? github.com/theupdateframework/notary/signer/client [no test files] +? github.com/theupdateframework/notary/storage/rethinkdb [no test files] +? github.com/theupdateframework/notary/trustmanager/yubikey [no test files] +? github.com/theupdateframework/notary/tuf/testutils/interfaces [no test files] +? github.com/theupdateframework/notary/tuf/testutils/keys [no test files] +? github.com/theupdateframework/notary/version [no test files] === RUN Test0Dot1Migration --- PASS: Test0Dot1Migration (0.13s) === RUN Test0Dot3Migration ---- PASS: Test0Dot3Migration (0.14s) +--- PASS: Test0Dot3Migration (0.12s) === RUN Test0Dot1RepoFormat -time="2024-04-12T18:29:31Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot1RepoFormat (0.90s) +time="2024-04-12T18:43:02Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot1RepoFormat (1.24s) === RUN Test0Dot3RepoFormat -time="2024-04-12T18:29:32Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot3RepoFormat (1.02s) +time="2024-04-12T18:43:03Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot3RepoFormat (1.16s) === RUN TestDownloading0Dot1RepoFormat ---- PASS: TestDownloading0Dot1RepoFormat (0.03s) +--- PASS: TestDownloading0Dot1RepoFormat (0.04s) === RUN TestDownloading0Dot3RepoFormat --- PASS: TestDownloading0Dot3RepoFormat (0.04s) === RUN TestInitRepositoryManagedRolesIncludingRoot ---- PASS: TestInitRepositoryManagedRolesIncludingRoot (0.01s) +--- PASS: TestInitRepositoryManagedRolesIncludingRoot (0.03s) === RUN TestInitRepositoryManagedRolesInvalidRole ---- PASS: TestInitRepositoryManagedRolesInvalidRole (0.01s) +--- PASS: TestInitRepositoryManagedRolesInvalidRole (0.02s) === RUN TestInitRepositoryManagedRolesIncludingTargets --- PASS: TestInitRepositoryManagedRolesIncludingTargets (0.01s) === RUN TestInitRepositoryManagedRolesIncludingTimestamp ---- PASS: TestInitRepositoryManagedRolesIncludingTimestamp (0.07s) +--- PASS: TestInitRepositoryManagedRolesIncludingTimestamp (0.08s) === RUN TestInitRepositoryWithCerts --- PASS: TestInitRepositoryWithCerts (0.10s) === RUN TestMatchKeyIDsWithPublicKeys --- PASS: TestMatchKeyIDsWithPublicKeys (0.04s) === RUN TestInitRepositoryNeedsRemoteTimestampKey ---- PASS: TestInitRepositoryNeedsRemoteTimestampKey (0.05s) +--- PASS: TestInitRepositoryNeedsRemoteTimestampKey (0.04s) === RUN TestInitRepositoryNeedsRemoteSnapshotKey ---- PASS: TestInitRepositoryNeedsRemoteSnapshotKey (0.04s) +--- PASS: TestInitRepositoryNeedsRemoteSnapshotKey (0.03s) === RUN TestInitRepoServerOnlyManagesTimestampKey --- PASS: TestInitRepoServerOnlyManagesTimestampKey (0.43s) === RUN TestInitRepoServerManagesTimestampAndSnapshotKeys ---- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.35s) +--- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.32s) === RUN TestInitRepoAttemptsExceeded ---- PASS: TestInitRepoAttemptsExceeded (0.21s) +--- PASS: TestInitRepoAttemptsExceeded (0.18s) === RUN TestInitRepoPasswordInvalid ---- PASS: TestInitRepoPasswordInvalid (0.04s) +--- PASS: TestInitRepoPasswordInvalid (0.03s) === RUN TestAddTargetToTargetRoleByDefault ---- PASS: TestAddTargetToTargetRoleByDefault (0.15s) +--- PASS: TestAddTargetToTargetRoleByDefault (0.16s) === RUN TestAddTargetToSpecifiedValidRoles ---- PASS: TestAddTargetToSpecifiedValidRoles (0.15s) +--- PASS: TestAddTargetToSpecifiedValidRoles (0.25s) === RUN TestAddTargetToSpecifiedInvalidRoles ---- PASS: TestAddTargetToSpecifiedInvalidRoles (0.14s) +--- PASS: TestAddTargetToSpecifiedInvalidRoles (0.20s) === RUN TestAddTargetWithInvalidTarget --- PASS: TestAddTargetWithInvalidTarget (0.07s) === RUN TestAddTargetErrorWritingChanges ---- PASS: TestAddTargetErrorWritingChanges (0.08s) +--- PASS: TestAddTargetErrorWritingChanges (0.07s) === RUN TestRemoveTargetToTargetRoleByDefault ---- PASS: TestRemoveTargetToTargetRoleByDefault (0.15s) +--- PASS: TestRemoveTargetToTargetRoleByDefault (0.16s) === RUN TestRemoveTargetFromSpecifiedValidRoles ---- PASS: TestRemoveTargetFromSpecifiedValidRoles (0.15s) +--- PASS: TestRemoveTargetFromSpecifiedValidRoles (0.16s) === RUN TestRemoveTargetToSpecifiedInvalidRoles --- PASS: TestRemoveTargetToSpecifiedInvalidRoles (0.15s) === RUN TestRemoveTargetErrorWritingChanges ---- PASS: TestRemoveTargetErrorWritingChanges (0.07s) +--- PASS: TestRemoveTargetErrorWritingChanges (0.06s) === RUN TestListTarget ---- PASS: TestListTarget (1.97s) +--- PASS: TestListTarget (2.51s) === RUN TestListTargetRestrictsDelegationPaths ---- PASS: TestListTargetRestrictsDelegationPaths (0.34s) +--- PASS: TestListTargetRestrictsDelegationPaths (0.42s) === RUN TestValidateRootKey ---- PASS: TestValidateRootKey (0.20s) +--- PASS: TestValidateRootKey (0.23s) === RUN TestGetChangelist ---- PASS: TestGetChangelist (0.19s) +--- PASS: TestGetChangelist (0.26s) === RUN TestPublishBareRepo ---- PASS: TestPublishBareRepo (1.74s) +--- PASS: TestPublishBareRepo (1.97s) === RUN TestPublishUninitializedRepo -time="2024-04-12T18:29:40Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +time="2024-04-12T18:43:12Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" --- PASS: TestPublishUninitializedRepo (0.15s) === RUN TestPublishClientHasSnapshotKey ---- PASS: TestPublishClientHasSnapshotKey (1.68s) +--- PASS: TestPublishClientHasSnapshotKey (1.74s) === RUN TestPublishAfterInitServerHasSnapshotKey ---- PASS: TestPublishAfterInitServerHasSnapshotKey (1.63s) +--- PASS: TestPublishAfterInitServerHasSnapshotKey (1.86s) === RUN TestPublishAfterPullServerHasSnapshotKey ---- PASS: TestPublishAfterPullServerHasSnapshotKey (0.56s) +--- PASS: TestPublishAfterPullServerHasSnapshotKey (0.50s) === RUN TestPublishNoOneHasSnapshotKey ---- PASS: TestPublishNoOneHasSnapshotKey (0.32s) +--- PASS: TestPublishNoOneHasSnapshotKey (0.29s) === RUN TestPublishSnapshotCorrupt ---- PASS: TestPublishSnapshotCorrupt (0.90s) +--- PASS: TestPublishSnapshotCorrupt (0.86s) === RUN TestPublishTargetsCorrupt ---- PASS: TestPublishTargetsCorrupt (0.46s) +--- PASS: TestPublishTargetsCorrupt (0.43s) === RUN TestPublishRootCorrupt -time="2024-04-12T18:29:46Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" -time="2024-04-12T18:29:46Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" ---- PASS: TestPublishRootCorrupt (0.33s) +time="2024-04-12T18:43:18Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +time="2024-04-12T18:43:18Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +--- PASS: TestPublishRootCorrupt (0.32s) === RUN TestPublishSnapshotLocalKeysCreatedFirst --- PASS: TestPublishSnapshotLocalKeysCreatedFirst (0.02s) === RUN TestPublishDelegations ---- PASS: TestPublishDelegations (0.63s) +--- PASS: TestPublishDelegations (0.83s) === RUN TestPublishDelegationsX509 ---- PASS: TestPublishDelegationsX509 (0.66s) +--- PASS: TestPublishDelegationsX509 (0.61s) === RUN TestPublishTargetsDelegationScopeFailIfNoKeys -time="2024-04-12T18:29:47Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" -time="2024-04-12T18:29:48Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" ---- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (0.55s) +time="2024-04-12T18:43:20Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" +time="2024-04-12T18:43:20Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" +--- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (0.52s) === RUN TestPublishTargetsDelegationSuccessLocallyHasRoles ---- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (0.61s) +--- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (0.55s) === RUN TestPublishTargetsDelegationNoTargetsKeyNeeded ---- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (0.68s) +--- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (0.82s) === RUN TestPublishTargetsDelegationSuccessNeedsToDownloadRoles ---- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (0.69s) +--- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (0.93s) === RUN TestPublishTargetsDelegationFromTwoRepos ---- PASS: TestPublishTargetsDelegationFromTwoRepos (0.59s) +--- PASS: TestPublishTargetsDelegationFromTwoRepos (0.60s) === RUN TestPublishRemoveDelegationKeyFromDelegationRole -time="2024-04-12T18:29:51Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-12T18:29:51Z" 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: 52c99929960328cd836829feb52f177e0609bf7ae10ffd447dc29f647f8ba735" ---- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.46s) +time="2024-04-12T18:43:23Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-12T18:43:23Z" 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: 9e90edcb579a8e7c2bbc50e32fd7e44f6521a1fba66faee71a4d46ab00b0316e" +--- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.43s) === RUN TestPublishRemoveDelegation -time="2024-04-12T18:29:51Z" 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="2024-04-12T18:29:51Z" 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="2024-04-12T18:29:51Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-12T18:29:51Z" 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.41s) +time="2024-04-12T18:43:24Z" 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="2024-04-12T18:43:24Z" 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="2024-04-12T18:43:24Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-12T18:43:24Z" 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.47s) === RUN TestPublishSucceedsDespiteDelegationCorrupt ---- PASS: TestPublishSucceedsDespiteDelegationCorrupt (0.48s) +--- PASS: TestPublishSucceedsDespiteDelegationCorrupt (0.55s) === RUN TestRotateKeyInvalidRole --- PASS: TestRotateKeyInvalidRole (0.23s) === RUN TestRemoteRotationError ---- PASS: TestRemoteRotationError (0.06s) +--- PASS: TestRemoteRotationError (0.05s) === RUN TestRemoteRotationEndpointError ---- PASS: TestRemoteRotationEndpointError (0.14s) +--- PASS: TestRemoteRotationEndpointError (0.15s) === RUN TestRemoteRotationNoRootKey ---- PASS: TestRemoteRotationNoRootKey (0.23s) +--- PASS: TestRemoteRotationNoRootKey (0.22s) === RUN TestRemoteRotationNoInit -time="2024-04-12T18:29:52Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" ---- PASS: TestRemoteRotationNoInit (0.16s) +time="2024-04-12T18:43:25Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +--- PASS: TestRemoteRotationNoInit (0.15s) === RUN TestRotateBeforePublishFromRemoteKeyToLocalKey ---- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (0.70s) +--- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (0.77s) === RUN TestRotateKeyAfterPublishNoServerManagementChange ---- PASS: TestRotateKeyAfterPublishNoServerManagementChange (2.03s) +--- PASS: TestRotateKeyAfterPublishNoServerManagementChange (2.24s) === RUN TestRotateKeyAfterPublishServerManagementChange ---- PASS: TestRotateKeyAfterPublishServerManagementChange (2.20s) +--- PASS: TestRotateKeyAfterPublishServerManagementChange (2.38s) === RUN TestRotateRootKey ---- PASS: TestRotateRootKey (0.68s) +--- PASS: TestRotateRootKey (0.78s) === RUN TestRotateRootMultiple ---- PASS: TestRotateRootMultiple (0.88s) +--- PASS: TestRotateRootMultiple (1.05s) === RUN TestRotateRootKeyProvided ---- PASS: TestRotateRootKeyProvided (0.66s) +--- PASS: TestRotateRootKeyProvided (0.79s) === RUN TestRotateRootKeyLegacySupport ---- PASS: TestRotateRootKeyLegacySupport (0.97s) +--- PASS: TestRotateRootKeyLegacySupport (1.15s) === RUN TestRemoteServerUnavailableNoLocalCache -time="2024-04-12T18:30:00Z" level=error msg="Could not publish Repository since we could not update: unable to reach trust server at this time: 500." ---- PASS: TestRemoteServerUnavailableNoLocalCache (0.01s) +time="2024-04-12T18:43:34Z" 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.07s) === RUN TestAddDelegationChangefileApplicable ---- PASS: TestAddDelegationChangefileApplicable (0.07s) +--- PASS: TestAddDelegationChangefileApplicable (0.08s) === RUN TestAddDelegationErrorWritingChanges ---- PASS: TestAddDelegationErrorWritingChanges (0.08s) +--- PASS: TestAddDelegationErrorWritingChanges (0.07s) === RUN TestRemoveDelegationChangefileValid --- PASS: TestRemoveDelegationChangefileValid (0.07s) === RUN TestRemoveDelegationChangefileApplicable -time="2024-04-12T18:30: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="2024-04-12T18:30: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" ---- PASS: TestRemoveDelegationChangefileApplicable (0.07s) +time="2024-04-12T18:43:35Z" 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="2024-04-12T18:43:35Z" 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.09s) === RUN TestClearAllPathsDelegationChangefileApplicable --- PASS: TestClearAllPathsDelegationChangefileApplicable (0.07s) === RUN TestFullAddDelegationChangefileApplicable ---- PASS: TestFullAddDelegationChangefileApplicable (0.08s) +--- PASS: TestFullAddDelegationChangefileApplicable (0.11s) === RUN TestFullRemoveDelegationChangefileApplicable ---- PASS: TestFullRemoveDelegationChangefileApplicable (0.08s) +--- PASS: TestFullRemoveDelegationChangefileApplicable (0.09s) === RUN TestRemoveDelegationErrorWritingChanges --- PASS: TestRemoveDelegationErrorWritingChanges (0.07s) === RUN TestClientInvalidURL --- PASS: TestClientInvalidURL (0.00s) === RUN TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole ---- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (1.26s) +--- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (1.49s) === RUN TestDeleteRepo --- PASS: TestDeleteRepo (0.14s) === RUN TestDeleteRemoteRepo ---- PASS: TestDeleteRemoteRepo (0.40s) +--- PASS: TestDeleteRemoteRepo (0.47s) === RUN TestListRoles ---- PASS: TestListRoles (0.95s) +--- PASS: TestListRoles (1.00s) === RUN TestGetAllTargetInfo ---- PASS: TestGetAllTargetInfo (0.59s) +--- PASS: TestGetAllTargetInfo (0.60s) === RUN TestUpdateSucceedsEvenIfCannotWriteNewRepo -time="2024-04-12T18:30:05Z" level=error msg="could not save root to cache: Non-writable" ---- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.47s) +time="2024-04-12T18:43:39Z" level=error msg="could not save root to cache: Non-writable" +--- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.54s) === RUN TestUpdateSucceedsEvenIfCannotWriteExistingRepo ---- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (2.07s) +--- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (2.32s) === RUN TestUpdateInOfflineMode -time="2024-04-12T18:30:07Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" -time="2024-04-12T18:30:07Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateInOfflineMode (0.20s) +time="2024-04-12T18:43:42Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +time="2024-04-12T18:43:42Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateInOfflineMode (0.25s) === RUN TestUpdateReplacesCorruptOrMissingMetadata -time="2024-04-12T18:30:10Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:30:10Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateReplacesCorruptOrMissingMetadata (3.97s) +time="2024-04-12T18:43:45Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:43:45Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateReplacesCorruptOrMissingMetadata (4.78s) === RUN TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign -time="2024-04-12T18:30:12Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:30:12Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.35s) +time="2024-04-12T18:43:47Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:43:47Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.37s) === RUN TestUpdateRemoteRootNotExistNoLocalCache --- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.18s) === RUN TestUpdateRemoteRootNotExistCanUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.35s) +--- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.40s) === RUN TestUpdateRemoteRootNotExistCannotUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.38s) +--- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.43s) === RUN TestUpdateRemoteRoot50XNoLocalCache ---- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.18s) +--- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.19s) === RUN TestUpdateRemoteRoot50XCanUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.34s) +--- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.35s) === RUN TestUpdateRemoteRoot50XCannotUseLocalCache --- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.38s) === RUN TestUpdateNonRootRemoteMissingMetadataNoLocalCache ---- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (0.89s) +--- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (0.88s) === RUN TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache -time="2024-04-12T18:30:15Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (1.17s) +time="2024-04-12T18:43:51Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (1.33s) === RUN TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache -time="2024-04-12T18:30:16Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (1.42s) +time="2024-04-12T18:43:52Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (1.55s) === RUN TestUpdateNonRootRemote50XNoLocalCache ---- PASS: TestUpdateNonRootRemote50XNoLocalCache (0.89s) +--- PASS: TestUpdateNonRootRemote50XNoLocalCache (0.94s) === RUN TestUpdateNonRootRemote50XCanUseLocalCache -time="2024-04-12T18:30:18Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (1.20s) +time="2024-04-12T18:43:54Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (1.26s) === RUN TestUpdateNonRootRemote50XCannotUseLocalCache -time="2024-04-12T18:30:20Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (1.40s) +time="2024-04-12T18:43:56Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (1.42s) === RUN TestUpdateRemoteChecksumWrongNoLocalCache ---- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (1.12s) +--- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (1.21s) === RUN TestUpdateRemoteChecksumWrongCanUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (1.62s) +--- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (1.93s) === RUN TestUpdateRemoteChecksumWrongCannotUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (1.92s) +--- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (2.03s) === RUN TestUpdateRootRemoteCorruptedNoLocalCache -time="2024-04-12T18:30:27Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:30:27Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (3.69s) +time="2024-04-12T18:44:03Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:44:03Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (4.01s) === RUN TestUpdateRootRemoteCorruptedCanUseLocalCache ---- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (6.85s) +--- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (7.86s) === RUN TestUpdateRootRemoteCorruptedCannotUseLocalCache -time="2024-04-12T18:30:39Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:30:39Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (7.51s) +time="2024-04-12T18:44:17Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:44:18Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (8.04s) === RUN TestUpdateNonRootRemoteCorruptedNoLocalCache -time="2024-04-12T18:30:44Z" level=error msg="Metadata for targets expired" -time="2024-04-12T18:30:44Z" level=error msg="Metadata for targets expired" -time="2024-04-12T18:30:46Z" level=error msg="Metadata for snapshot expired" -time="2024-04-12T18:30:46Z" level=error msg="Metadata for snapshot expired" -time="2024-04-12T18:30:47Z" level=error msg="Metadata for timestamp expired" -time="2024-04-12T18:30:47Z" level=error msg="Metadata for timestamp expired" -time="2024-04-12T18:30:48Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-12T18:30:48Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-12T18:30:48Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-12T18:30:48Z" level=error msg="Metadata for targets/a expired" -time="2024-04-12T18:30:48Z" level=warning msg="Error getting targets/a: targets/a expired at Sat Mar 11 18:30:48 UTC 2023" -time="2024-04-12T18:30:49Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-04-12T18:30:49Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-04-12T18:30:50Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-04-12T18:30:50Z" level=error msg="Metadata for targets/a/b expired" -time="2024-04-12T18:30:50Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Sat Mar 11 18:30:50 UTC 2023" -time="2024-04-12T18:30:51Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-04-12T18:30:51Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-04-12T18:30:51Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-04-12T18:30:51Z" level=error msg="Metadata for targets/b expired" -time="2024-04-12T18:30:51Z" level=warning msg="Error getting targets/b: targets/b expired at Sat Mar 11 18:30:51 UTC 2023" ---- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (8.99s) +time="2024-04-12T18:44:23Z" level=error msg="Metadata for targets expired" +time="2024-04-12T18:44:23Z" level=error msg="Metadata for targets expired" +time="2024-04-12T18:44:24Z" level=error msg="Metadata for snapshot expired" +time="2024-04-12T18:44:24Z" level=error msg="Metadata for snapshot expired" +time="2024-04-12T18:44:26Z" level=error msg="Metadata for timestamp expired" +time="2024-04-12T18:44:26Z" level=error msg="Metadata for timestamp expired" +time="2024-04-12T18:44:27Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-12T18:44:27Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-12T18:44:27Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-12T18:44:27Z" level=error msg="Metadata for targets/a expired" +time="2024-04-12T18:44:27Z" level=warning msg="Error getting targets/a: targets/a expired at Sat Mar 11 18:44:27 UTC 2023" +time="2024-04-12T18:44:28Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-04-12T18:44:28Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-04-12T18:44:28Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-04-12T18:44:28Z" level=error msg="Metadata for targets/a/b expired" +time="2024-04-12T18:44:28Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Sat Mar 11 18:44:28 UTC 2023" +time="2024-04-12T18:44:30Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-04-12T18:44:30Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-04-12T18:44:30Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-04-12T18:44:30Z" level=error msg="Metadata for targets/b expired" +time="2024-04-12T18:44:30Z" level=warning msg="Error getting targets/b: targets/b expired at Sat Mar 11 18:44:30 UTC 2023" +--- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (9.39s) === RUN TestUpdateNonRootRemoteCorruptedCanUseLocalCache -time="2024-04-12T18:30:58Z" level=error msg="Metadata for timestamp expired" -time="2024-04-12T18:30:58Z" level=error msg="Metadata for timestamp expired" ---- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (15.45s) +time="2024-04-12T18:44:36Z" level=error msg="Metadata for timestamp expired" +time="2024-04-12T18:44:36Z" level=error msg="Metadata for timestamp expired" +--- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (12.63s) === RUN TestUpdateNonRootRemoteCorruptedCannotUseLocalCache -time="2024-04-12T18:31:11Z" level=error msg="Metadata for targets expired" -time="2024-04-12T18:31:13Z" level=error msg="Metadata for snapshot expired" -time="2024-04-12T18:31:13Z" level=error msg="Metadata for snapshot expired" -time="2024-04-12T18:31:16Z" level=error msg="Metadata for timestamp expired" -time="2024-04-12T18:31:17Z" level=error msg="Metadata for timestamp expired" -time="2024-04-12T18:31:19Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-12T18:31:19Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-12T18:31:20Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-12T18:31:20Z" level=error msg="Metadata for targets/a expired" -time="2024-04-12T18:31:20Z" level=warning msg="Error getting targets/a: targets/a expired at Sat Mar 11 18:31:20 UTC 2023" -time="2024-04-12T18:31:23Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-04-12T18:31:23Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-04-12T18:31:24Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-04-12T18:31:24Z" level=error msg="Metadata for targets/a/b expired" -time="2024-04-12T18:31:24Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Sat Mar 11 18:31:24 UTC 2023" -time="2024-04-12T18:31:26Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-04-12T18:31:26Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-04-12T18:31:27Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-04-12T18:31:27Z" level=error msg="Metadata for targets/b expired" -time="2024-04-12T18:31:27Z" level=warning msg="Error getting targets/b: targets/b expired at Sat Mar 11 18:31:27 UTC 2023" ---- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (20.79s) +time="2024-04-12T18:44:46Z" level=error msg="Metadata for targets expired" +time="2024-04-12T18:44:48Z" level=error msg="Metadata for snapshot expired" +time="2024-04-12T18:44:48Z" level=error msg="Metadata for snapshot expired" +time="2024-04-12T18:44:50Z" level=error msg="Metadata for timestamp expired" +time="2024-04-12T18:44:50Z" level=error msg="Metadata for timestamp expired" +time="2024-04-12T18:44:51Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-12T18:44:52Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-12T18:44:52Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-12T18:44:52Z" level=error msg="Metadata for targets/a expired" +time="2024-04-12T18:44:52Z" level=warning msg="Error getting targets/a: targets/a expired at Sat Mar 11 18:44:52 UTC 2023" +time="2024-04-12T18:44:54Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-04-12T18:44:54Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-04-12T18:44:55Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-04-12T18:44:55Z" level=error msg="Metadata for targets/a/b expired" +time="2024-04-12T18:44:55Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Sat Mar 11 18:44:55 UTC 2023" +time="2024-04-12T18:44:57Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-04-12T18:44:57Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-04-12T18:44:58Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-04-12T18:44:58Z" level=error msg="Metadata for targets/b expired" +time="2024-04-12T18:44:58Z" level=warning msg="Error getting targets/b: targets/b expired at Sat Mar 11 18:44:58 UTC 2023" +--- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (15.89s) === RUN TestUpdateLocalAndRemoteRootCorrupt -time="2024-04-12T18:31:36Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:36Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:45Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:46Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:46Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:46Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:46Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:47Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:47Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:47Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:47Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:48Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:48Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:48Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:49Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:49Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:49Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:50Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:50Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:50Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:51Z" level=error msg="Metadata for root expired" -time="2024-04-12T18:31:51Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateLocalAndRemoteRootCorrupt (22.33s) +time="2024-04-12T18:45:08Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:08Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:17Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:17Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:17Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:17Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:17Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:18Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:18Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:18Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:18Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:18Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:19Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:19Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:19Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:19Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:19Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:20Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:20Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:20Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:20Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:20Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateLocalAndRemoteRootCorrupt (20.58s) === RUN TestUpdateRemoteKeyRotated -time="2024-04-12T18:31:52Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-12T18:31:53Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-04-12T18:31:53Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" ---- PASS: TestUpdateRemoteKeyRotated (2.45s) +time="2024-04-12T18:45:21Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-12T18:45:21Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-04-12T18:45:21Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +--- PASS: TestUpdateRemoteKeyRotated (1.42s) === RUN TestValidateRootRotationWithOldRole ---- PASS: TestValidateRootRotationWithOldRole (1.22s) +--- PASS: TestValidateRootRotationWithOldRole (0.75s) === RUN TestRootRoleInvariant ---- PASS: TestRootRoleInvariant (0.95s) +--- PASS: TestRootRoleInvariant (0.60s) === RUN TestBadIntermediateTransitions ---- PASS: TestBadIntermediateTransitions (0.42s) +--- PASS: TestBadIntermediateTransitions (0.22s) === RUN TestExpiredIntermediateTransitions -time="2024-04-12T18:31:56Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" ---- PASS: TestExpiredIntermediateTransitions (0.36s) +time="2024-04-12T18:45:23Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" +--- PASS: TestExpiredIntermediateTransitions (0.28s) === RUN TestDownloadTargetsLarge ---- PASS: TestDownloadTargetsLarge (11.44s) +--- PASS: TestDownloadTargetsLarge (8.49s) === RUN TestDownloadTargetsDeep ---- PASS: TestDownloadTargetsDeep (0.20s) +--- PASS: TestDownloadTargetsDeep (0.18s) === RUN TestDownloadSnapshotLargeDelegationsMany ---- PASS: TestDownloadSnapshotLargeDelegationsMany (10.78s) +--- PASS: TestDownloadSnapshotLargeDelegationsMany (8.01s) === RUN TestRootOnDiskTrustPinning ---- PASS: TestRootOnDiskTrustPinning (0.25s) +--- PASS: TestRootOnDiskTrustPinning (0.13s) === RUN TestLoadTUFRepoBadURL --- PASS: TestLoadTUFRepoBadURL (0.00s) === RUN TestApplyTargetsChange @@ -2161,7 +2206,7 @@ === RUN TestApplyTargetsDelegationCreateDelete --- PASS: TestApplyTargetsDelegationCreateDelete (0.02s) === RUN TestApplyTargetsDelegationCreate2SharedKey ---- PASS: TestApplyTargetsDelegationCreate2SharedKey (0.03s) +--- PASS: TestApplyTargetsDelegationCreate2SharedKey (0.02s) === RUN TestApplyTargetsDelegationCreateEdit --- PASS: TestApplyTargetsDelegationCreateEdit (0.02s) === RUN TestApplyTargetsDelegationEditNonExisting @@ -2187,25 +2232,25 @@ === RUN TestApplyChangelistTargetsToMultipleRoles --- PASS: TestApplyChangelistTargetsToMultipleRoles (0.02s) === RUN TestApplyChangelistTargetsFailsNonexistentRole -time="2024-04-12T18:32:19Z" level=error msg="couldn't add target to targets/level1/level2/level3/level4: tuf: invalid role targets/level1/level2/level3/level4. delegation does not exist" -time="2024-04-12T18:32:19Z" level=error msg="couldn't remove target from targets/level1/level2/level3/level4: tuf: invalid role targets/level1/level2/level3/level4. delegation does not exist" +time="2024-04-12T18:45:40Z" 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="2024-04-12T18:45:40Z" 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.02s) === RUN TestChangeTargetMetaFailsInvalidRole -time="2024-04-12T18:32:19Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" +time="2024-04-12T18:45:40Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" --- PASS: TestChangeTargetMetaFailsInvalidRole (0.02s) === RUN TestChangeTargetMetaFailsIfPrefixError -time="2024-04-12T18:32:19Z" level=error msg="couldn't add target to targets/level1: Could not add all targets" +time="2024-04-12T18:45:40Z" level=error msg="couldn't add target to targets/level1: Could not add all targets" --- PASS: TestChangeTargetMetaFailsIfPrefixError (0.02s) === RUN TestAllNearExpiry --- PASS: TestAllNearExpiry (0.02s) === RUN TestAllNotNearExpiry ---- PASS: TestAllNotNearExpiry (0.04s) +--- PASS: TestAllNotNearExpiry (0.02s) === RUN TestRotateRemoteKeyOffline --- PASS: TestRotateRemoteKeyOffline (0.00s) PASS -ok github.com/theupdateframework/notary/client 168.751s +ok github.com/theupdateframework/notary/client 159.274s === RUN TestTUFDelegation ---- PASS: TestTUFDelegation (0.05s) +--- PASS: TestTUFDelegation (0.03s) === RUN TestMemChangelist --- PASS: TestMemChangelist (0.00s) === RUN TestMemChangeIterator @@ -2215,14 +2260,14 @@ === RUN TestAdd --- PASS: TestAdd (0.00s) === RUN TestErrorConditions -time="2024-04-12T18:32:23Z" level=warning msg="invalid character '\\x05' looking for beginning of value" +time="2024-04-12T18:43:01Z" 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.01s) +--- PASS: TestFileChangeIterator (0.00s) PASS -ok github.com/theupdateframework/notary/client/changelist 0.094s +ok github.com/theupdateframework/notary/client/changelist 0.055s === RUN TestParseConfigError --- PASS: TestParseConfigError (0.00s) === RUN TestSetupGRPCServer @@ -2232,7 +2277,7 @@ === RUN TestSetupNetListener --- PASS: TestSetupNetListener (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/escrow 0.030s +ok github.com/theupdateframework/notary/cmd/escrow 0.026s === RUN TestPurgeDelegationKeys Usage: delegation [command] @@ -2256,9 +2301,9 @@ Use "delegation [command] --help" for more information about a command. Removal of the following keys from all delegations in gun staged for next publish: - abc ---- PASS: TestPurgeDelegationKeys (0.01s) +--- PASS: TestPurgeDelegationKeys (0.00s) === RUN TestAddInvalidDelegationName ---- PASS: TestAddInvalidDelegationName (0.03s) +--- PASS: TestAddInvalidDelegationName (0.02s) === RUN TestAddInvalidDelegationCert --- PASS: TestAddInvalidDelegationCert (0.01s) === RUN TestAddInvalidShortPubkeyCert @@ -2304,118 +2349,118 @@ Use "delegation [command] --help" for more information about a command. --- PASS: TestRemoveInvalidNumArgs (0.00s) === RUN TestInitWithRootKey ---- PASS: TestInitWithRootKey (0.64s) +--- PASS: TestInitWithRootKey (0.34s) === RUN TestInitWithRootCert ---- PASS: TestInitWithRootCert (0.52s) +--- PASS: TestInitWithRootCert (0.28s) === RUN TestClientTUFInteraction ---- PASS: TestClientTUFInteraction (0.96s) +--- PASS: TestClientTUFInteraction (0.79s) === RUN TestClientDeleteTUFInteraction -time="2024-04-12T18:32:54Z" 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]:42551->[::1]:53: read: connection refused" ---- PASS: TestClientDeleteTUFInteraction (1.32s) +time="2024-04-12T18:43:27Z" 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]:44582->[::1]:53: read: connection refused" +--- PASS: TestClientDeleteTUFInteraction (0.89s) === RUN TestClientTUFAddByHashInteraction ---- PASS: TestClientTUFAddByHashInteraction (2.28s) +--- PASS: TestClientTUFAddByHashInteraction (1.54s) === RUN TestClientDelegationsInteraction -time="2024-04-12T18:32: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="2024-04-12T18:32: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 (5.14s) +time="2024-04-12T18:43:30Z" 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="2024-04-12T18:43:30Z" 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 (3.41s) === RUN TestClientDelegationsPublishing ---- PASS: TestClientDelegationsPublishing (4.61s) +--- PASS: TestClientDelegationsPublishing (3.42s) === RUN TestClientKeyGenerationRotation ---- PASS: TestClientKeyGenerationRotation (5.14s) +--- PASS: TestClientKeyGenerationRotation (4.09s) === RUN TestKeyRotation ---- PASS: TestKeyRotation (0.90s) +--- PASS: TestKeyRotation (0.59s) === RUN TestKeyRotationNonRoot ---- PASS: TestKeyRotationNonRoot (1.17s) +--- PASS: TestKeyRotationNonRoot (0.76s) === RUN TestDefaultRootKeyGeneration --- PASS: TestDefaultRootKeyGeneration (0.02s) === RUN TestLogLevelFlags --- PASS: TestLogLevelFlags (0.00s) === RUN TestClientKeyPassphraseChange ---- PASS: TestClientKeyPassphraseChange (0.85s) +--- PASS: TestClientKeyPassphraseChange (0.54s) === RUN TestPurgeSingleKey Removal of the following keys from all delegations in gun staged for next publish: - - d78aa5653210c503df699f951de354fec3ee41527a1e70381a6238f2641e0fcf ---- PASS: TestPurgeSingleKey (0.60s) + - 2dedef4c7d8a84c4bfd5611529d38ab058270a14a47b20ef85add80b2f960fa2 +--- PASS: TestPurgeSingleKey (0.55s) === RUN TestWitness -time="2024-04-12T18:33:16Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-04-12T18:33:16Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-04-12T18:33:17Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-04-12T18:33:17Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-04-12T18:33:18Z" 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="2024-04-12T18:33:18Z" 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="2024-04-12T18:33:18Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" ---- PASS: TestWitness (2.33s) +time="2024-04-12T18:43:43Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-04-12T18:43:43Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-04-12T18:43:44Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-04-12T18:43:44Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-04-12T18:43:44Z" 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="2024-04-12T18:43:44Z" 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="2024-04-12T18:43:44Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +--- PASS: TestWitness (1.88s) === RUN TestClientTUFInitWithAutoPublish -time="2024-04-12T18:33:18Z" 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]:56932->[::1]:53: read: connection refused" ---- PASS: TestClientTUFInitWithAutoPublish (0.73s) +time="2024-04-12T18:43:44Z" 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]:59358->[::1]:53: read: connection refused" +--- PASS: TestClientTUFInitWithAutoPublish (0.44s) === RUN TestClientTUFAddWithAutoPublish -time="2024-04-12T18:33:19Z" 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]:37414->[::1]:53: read: connection refused" -time="2024-04-12T18:33:19Z" level=error msg="Could not publish Repository since we could not update: client is offline" -time="2024-04-12T18:33:19Z" 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]:35599->[::1]:53: read: connection refused" -time="2024-04-12T18:33:19Z" level=error msg="Could not publish Repository since we could not update: client is offline" ---- PASS: TestClientTUFAddWithAutoPublish (0.85s) +time="2024-04-12T18:43:45Z" 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]:39515->[::1]:53: read: connection refused" +time="2024-04-12T18:43:45Z" level=error msg="Could not publish Repository since we could not update: client is offline" +time="2024-04-12T18:43:45Z" 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]:55218->[::1]:53: read: connection refused" +time="2024-04-12T18:43:45Z" level=error msg="Could not publish Repository since we could not update: client is offline" +--- PASS: TestClientTUFAddWithAutoPublish (0.53s) === RUN TestClientTUFRemoveWithAutoPublish ---- PASS: TestClientTUFRemoveWithAutoPublish (1.62s) +--- PASS: TestClientTUFRemoveWithAutoPublish (1.19s) === RUN TestClientDelegationAddWithAutoPublish ---- PASS: TestClientDelegationAddWithAutoPublish (0.78s) +--- PASS: TestClientDelegationAddWithAutoPublish (0.43s) === RUN TestClientDelegationRemoveWithAutoPublish -time="2024-04-12T18:33:23Z" 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="2024-04-12T18:33:23Z" 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 (1.50s) +time="2024-04-12T18:43:47Z" 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="2024-04-12T18:43:47Z" 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.94s) === RUN TestClientTUFAddByHashWithAutoPublish ---- PASS: TestClientTUFAddByHashWithAutoPublish (0.70s) +--- PASS: TestClientTUFAddByHashWithAutoPublish (0.45s) === RUN TestClientKeyImport -time="2024-04-12T18:33:24Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" -time="2024-04-12T18:33:24Z" 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.22s) +time="2024-04-12T18:43:48Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" +time="2024-04-12T18:43:48Z" 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.16s) === RUN TestAddDelImportKeyPublishFlow -time="2024-04-12T18:33:26Z" 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 (1.81s) +time="2024-04-12T18:43:49Z" 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 (1.01s) === RUN TestExportImportFlow ---- PASS: TestExportImportFlow (0.50s) +--- PASS: TestExportImportFlow (0.26s) === RUN TestDelegationKeyImportExport ---- PASS: TestDelegationKeyImportExport (0.03s) +--- PASS: TestDelegationKeyImportExport (0.02s) === RUN TestImportKeysNoYubikey ---- PASS: TestImportKeysNoYubikey (0.05s) +--- PASS: TestImportKeysNoYubikey (0.03s) === RUN TestExportImportKeysNoYubikey ---- PASS: TestExportImportKeysNoYubikey (0.06s) +--- PASS: TestExportImportKeysNoYubikey (0.02s) === RUN TestRemoveIfNoKey --- PASS: TestRemoveIfNoKey (0.00s) === RUN TestRemoveOneKeyAbort ---- PASS: TestRemoveOneKeyAbort (0.06s) +--- PASS: TestRemoveOneKeyAbort (0.04s) === RUN TestRemoveOneKeyConfirm ---- PASS: TestRemoveOneKeyConfirm (0.03s) +--- PASS: TestRemoveOneKeyConfirm (0.02s) === RUN TestRemoveMultikeysInvalidInput ---- PASS: TestRemoveMultikeysInvalidInput (0.04s) +--- PASS: TestRemoveMultikeysInvalidInput (0.02s) === RUN TestRemoveMultikeysAbortChoice ---- PASS: TestRemoveMultikeysAbortChoice (0.05s) +--- PASS: TestRemoveMultikeysAbortChoice (0.02s) === RUN TestRemoveMultikeysRemoveOnlyChosenKey ---- PASS: TestRemoveMultikeysRemoveOnlyChosenKey (0.03s) +--- PASS: TestRemoveMultikeysRemoveOnlyChosenKey (0.02s) === RUN TestRotateKeyInvalidRoles -time="2024-04-12T18:33:27Z" 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]:35891->[::1]:53: read: connection refused" -time="2024-04-12T18:33:27Z" 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]:49690->[::1]:53: read: connection refused" -time="2024-04-12T18:33:27Z" 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]:40167->[::1]:53: read: connection refused" -time="2024-04-12T18:33:27Z" 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]:47885->[::1]:53: read: connection refused" ---- PASS: TestRotateKeyInvalidRoles (0.03s) +time="2024-04-12T18:43:50Z" 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]:58217->[::1]:53: read: connection refused" +time="2024-04-12T18:43:50Z" 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]:44613->[::1]:53: read: connection refused" +time="2024-04-12T18:43:50Z" 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]:45691->[::1]:53: read: connection refused" +time="2024-04-12T18:43:50Z" 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]:39357->[::1]:53: read: connection refused" +--- PASS: TestRotateKeyInvalidRoles (0.01s) === RUN TestRotateKeyTargetCannotBeServerManaged -time="2024-04-12T18:33:27Z" 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]:34851->[::1]:53: read: connection refused" +time="2024-04-12T18:43:50Z" 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]:59325->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTargetCannotBeServerManaged (0.00s) === RUN TestRotateKeyTimestampCannotBeLocallyManaged -time="2024-04-12T18:33:27Z" 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]:37686->[::1]:53: read: connection refused" ---- PASS: TestRotateKeyTimestampCannotBeLocallyManaged (0.01s) +time="2024-04-12T18:43:50Z" 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]:56984->[::1]:53: read: connection refused" +--- PASS: TestRotateKeyTimestampCannotBeLocallyManaged (0.00s) === RUN TestRotateKeyNoGUN Usage: --- PASS: TestRotateKeyNoGUN (0.00s) === RUN TestRotateKeyRemoteServerManagesKey Successfully rotated snapshot key for repository docker.com/notary Successfully rotated timestamp key for repository docker.com/notary ---- PASS: TestRotateKeyRemoteServerManagesKey (0.68s) +--- PASS: TestRotateKeyRemoteServerManagesKey (0.42s) === RUN TestRotateKeyBothKeys Successfully rotated targets key for repository docker.com/notary Successfully rotated snapshot key for repository docker.com/notary ---- PASS: TestRotateKeyBothKeys (0.48s) +--- PASS: TestRotateKeyBothKeys (0.38s) === RUN TestRotateKeyRootIsInteractive ---- PASS: TestRotateKeyRootIsInteractive (0.16s) +--- PASS: TestRotateKeyRootIsInteractive (0.14s) === RUN TestChangeKeyPassphraseInvalidID --- PASS: TestChangeKeyPassphraseInvalidID (0.00s) === RUN TestChangeKeyPassphraseInvalidNumArgs @@ -2426,7 +2471,7 @@ === RUN TestExportKeys --- PASS: TestExportKeys (0.01s) === RUN TestExportKeysByGUN ---- PASS: TestExportKeysByGUN (0.00s) +--- PASS: TestExportKeysByGUN (0.01s) === RUN TestExportKeysByID --- PASS: TestExportKeysByID (0.01s) === RUN TestExportKeysBadFlagCombo @@ -2434,30 +2479,30 @@ === RUN TestImportKeysNonexistentFile --- PASS: TestImportKeysNonexistentFile (0.00s) === RUN TestKeyGeneration -time="2024-04-12T18:33:28Z" 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.07s) +time="2024-04-12T18:43:51Z" 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.04s) === RUN TestNotaryConfigFileDefault --- PASS: TestNotaryConfigFileDefault (0.00s) === RUN TestRemoteServerDefault --- PASS: TestRemoteServerDefault (0.00s) === RUN TestRemoteServerUsesConfigFile ---- PASS: TestRemoteServerUsesConfigFile (0.01s) +--- PASS: TestRemoteServerUsesConfigFile (0.00s) === RUN TestRemoteServerCommandLineFlagOverridesConfig --- PASS: TestRemoteServerCommandLineFlagOverridesConfig (0.00s) === RUN TestInvalidAddHashCommands --- PASS: TestInvalidAddHashCommands (0.01s) === RUN TestConfigParsingErrorsPropagatedByCommands ---- PASS: TestConfigParsingErrorsPropagatedByCommands (0.03s) +--- PASS: TestConfigParsingErrorsPropagatedByCommands (0.02s) === RUN TestInsufficientArgumentsReturnsErrorAndPrintsUsage ---- PASS: TestInsufficientArgumentsReturnsErrorAndPrintsUsage (0.05s) +--- PASS: TestInsufficientArgumentsReturnsErrorAndPrintsUsage (0.03s) === RUN TestBareCommandPrintsUsageAndNoError --- PASS: TestBareCommandPrintsUsageAndNoError (0.01s) === RUN TestConfigFileTLSCannotBeRelativeToCWD ---- PASS: TestConfigFileTLSCannotBeRelativeToCWD (0.03s) +--- PASS: TestConfigFileTLSCannotBeRelativeToCWD (0.01s) === RUN TestConfigFileTLSCanBeRelativeToConfigOrAbsolute ---- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.35s) +--- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.17s) === RUN TestConfigFileOverridenByCmdLineFlags ---- PASS: TestConfigFileOverridenByCmdLineFlags (0.28s) +--- PASS: TestConfigFileOverridenByCmdLineFlags (0.20s) === RUN TestConfigFileTrustPinning --- PASS: TestConfigFileTrustPinning (0.00s) === RUN TestPassphraseRetrieverCaching @@ -2471,7 +2516,7 @@ === RUN TestPrettyPrintZeroKeys --- PASS: TestPrettyPrintZeroKeys (0.00s) === RUN TestPrettyPrintRootAndSigningKeys ---- PASS: TestPrettyPrintRootAndSigningKeys (0.05s) +--- PASS: TestPrettyPrintRootAndSigningKeys (0.04s) === RUN TestPrettyPrintZeroTargets --- PASS: TestPrettyPrintZeroTargets (0.00s) === RUN TestPrettyPrintSortedTargets @@ -2483,10 +2528,10 @@ === RUN TestImportRootCert --- PASS: TestImportRootCert (0.00s) === RUN TestTokenAuth -time="2024-04-12T18:33:29Z" 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="2024-04-12T18:43:51Z" 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="2024-04-12T18:33:29Z" 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="2024-04-12T18:43:51Z" 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) @@ -2497,19 +2542,19 @@ === RUN TestAdminTokenAuth401Status --- PASS: TestAdminTokenAuth401Status (0.00s) === RUN TestTokenAuthNon200Non401Status -time="2024-04-12T18:33:29Z" level=error msg="could not reach http://127.0.0.1:32797: 404" ---- PASS: TestTokenAuthNon200Non401Status (0.00s) +time="2024-04-12T18:43:51Z" level=error msg="could not reach http://127.0.0.1:34277: 404" +--- PASS: TestTokenAuthNon200Non401Status (0.01s) === RUN TestAdminTokenAuthNon200Non401Status -time="2024-04-12T18:33:29Z" level=error msg="could not reach http://127.0.0.1:43627: 404" +time="2024-04-12T18:43:51Z" level=error msg="could not reach http://127.0.0.1:39073: 404" --- PASS: TestAdminTokenAuthNon200Non401Status (0.00s) === RUN TestConfigureRepo --- PASS: TestConfigureRepo (0.00s) === RUN TestConfigureRepoRW -Enter username: time="2024-04-12T18:33:29Z" level=error msg="error processing username input: EOF" +Enter username: time="2024-04-12T18:43:51Z" level=error msg="error processing username input: EOF" --- PASS: TestConfigureRepoRW (0.01s) === RUN TestConfigureRepoAdmin -Enter username: time="2024-04-12T18:33:29Z" level=error msg="error processing username input: EOF" ---- PASS: TestConfigureRepoAdmin (0.00s) +Enter username: time="2024-04-12T18:43:51Z" level=error msg="error processing username input: EOF" +--- PASS: TestConfigureRepoAdmin (0.01s) === RUN TestStatusUnstageAndReset Successfully reset specified changes for repository gun Addition of target "test1" by sha256 hash to repository "gun" staged for next publish. @@ -2521,11 +2566,11 @@ Pushing changes to gun --- PASS: TestGetTrustPinningErrors (0.00s) === RUN TestPasswordStore -Enter username: time="2024-04-12T18:33:29Z" level=error msg="error processing username input: EOF" +Enter username: time="2024-04-12T18:43:51Z" level=error msg="error processing username input: EOF" --- PASS: TestPasswordStore (0.00s) === RUN TestPasswordStoreWithEnvvar -time="2024-04-12T18:33:29Z" level=error msg="Authentication string with zero-length username" -time="2024-04-12T18:33:29Z" level=error msg="Could not base64-decode authentication string" +time="2024-04-12T18:43:51Z" level=error msg="Authentication string with zero-length username" +time="2024-04-12T18:43:51Z" level=error msg="Could not base64-decode authentication string" --- PASS: TestPasswordStoreWithEnvvar (0.00s) === RUN TestGetPayload --- PASS: TestGetPayload (0.00s) @@ -2534,7 +2579,7 @@ === RUN TestHomeExpand --- PASS: TestHomeExpand (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary 37.960s +ok github.com/theupdateframework/notary/cmd/notary 26.351s === RUN TestBootstrap --- PASS: TestBootstrap (0.00s) === RUN TestGetAddrAndTLSConfigInvalidTLS @@ -2546,46 +2591,46 @@ === RUN TestGetAddrAndTLSConfigSuccessWithoutTLS --- PASS: TestGetAddrAndTLSConfigSuccessWithoutTLS (0.00s) === RUN TestGetAddrAndTLSConfigWithClientTLS ---- PASS: TestGetAddrAndTLSConfigWithClientTLS (0.01s) +--- PASS: TestGetAddrAndTLSConfigWithClientTLS (0.00s) === RUN TestGetInvalidTrustService --- PASS: TestGetInvalidTrustService (0.00s) === RUN TestGetLocalTrustService -time="2024-04-12T18:34:06Z" level=info msg="Using local signing service, which requires ED25519. Ignoring all other trust_service parameters, including keyAlgorithm" +time="2024-04-12T18:44:15Z" 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="2024-04-12T18:34:06Z" level=info msg="Using remote signing service" +time="2024-04-12T18:44:15Z" level=info msg="Using remote signing service" --- PASS: TestGetTrustServiceNoTLSConfig (0.00s) === RUN TestGetTrustServiceTLSSuccess -time="2024-04-12T18:34:06Z" level=info msg="Using remote signing service" ---- PASS: TestGetTrustServiceTLSSuccess (0.01s) +time="2024-04-12T18:44:15Z" level=info msg="Using remote signing service" +--- PASS: TestGetTrustServiceTLSSuccess (0.00s) === RUN TestGetTrustServiceTLSFailure --- PASS: TestGetTrustServiceTLSFailure (0.00s) === RUN TestGetStoreInvalid -time="2024-04-12T18:34:06Z" level=info msg="Using asdf backend" +time="2024-04-12T18:44:15Z" level=info msg="Using asdf backend" --- PASS: TestGetStoreInvalid (0.00s) === RUN TestGetStoreDBStore -time="2024-04-12T18:34:06Z" level=info msg="Using sqlite3 backend" ---- PASS: TestGetStoreDBStore (0.01s) +time="2024-04-12T18:44:15Z" level=info msg="Using sqlite3 backend" +--- PASS: TestGetStoreDBStore (0.00s) === RUN TestGetStoreRethinkDBStoreConnectionFails -time="2024-04-12T18:34:06Z" level=info msg="Using rethinkdb backend" +time="2024-04-12T18:44:15Z" level=info msg="Using rethinkdb backend" --- PASS: TestGetStoreRethinkDBStoreConnectionFails (0.00s) === RUN TestGetMemoryStore -time="2024-04-12T18:34:06Z" level=info msg="Using memory backend" +time="2024-04-12T18:44:15Z" 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="2024-04-12T18:34:06Z" level=info msg="Using remote signing service" -time="2024-04-12T18:34:06Z" level=info msg="Using sqlite3 backend" +time="2024-04-12T18:44:15Z" level=info msg="Using remote signing service" +time="2024-04-12T18:44:15Z" level=info msg="Using sqlite3 backend" --- PASS: TestSampleConfig (0.01s) PASS -ok github.com/theupdateframework/notary/cmd/notary-server 0.080s +ok github.com/theupdateframework/notary/cmd/notary-server 0.070s === RUN TestGetAddrAndTLSConfigInvalidTLS --- PASS: TestGetAddrAndTLSConfigInvalidTLS (0.00s) === RUN TestGetAddrAndTLSConfigNoGRPCAddr @@ -2600,7 +2645,7 @@ main_test.go:121: DM - skipping malfunctioning test, fails on sbuild --- SKIP: TestSetupCryptoServicesRethinkDBStoreConnectionFails (0.00s) === RUN TestSetupCryptoServicesDBStoreSuccess ---- PASS: TestSetupCryptoServicesDBStoreSuccess (0.19s) +--- PASS: TestSetupCryptoServicesDBStoreSuccess (0.15s) === RUN TestSetupCryptoServicesMemoryStore --- PASS: TestSetupCryptoServicesMemoryStore (0.02s) === RUN TestSetupCryptoServicesInvalidStore @@ -2616,19 +2661,19 @@ === RUN TestPassphraseRetrieverInvalid --- PASS: TestPassphraseRetrieverInvalid (0.00s) === RUN TestSampleConfig ---- PASS: TestSampleConfig (0.01s) +--- PASS: TestSampleConfig (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary-signer 0.272s +ok github.com/theupdateframework/notary/cmd/notary-signer 0.232s === RUN TestGenerateCertificate ---- PASS: TestGenerateCertificate (0.05s) +--- PASS: TestGenerateCertificate (0.03s) === RUN TestCryptoServiceWithNonEmptyGUN ---- PASS: TestCryptoServiceWithNonEmptyGUN (2.24s) +--- PASS: TestCryptoServiceWithNonEmptyGUN (1.68s) === RUN TestCryptoServiceWithEmptyGUN ---- PASS: TestCryptoServiceWithEmptyGUN (2.07s) +--- PASS: TestCryptoServiceWithEmptyGUN (1.78s) === RUN TestCryptoSignerInterfaceBehavior ---- PASS: TestCryptoSignerInterfaceBehavior (0.05s) +--- PASS: TestCryptoSignerInterfaceBehavior (0.02s) PASS -ok github.com/theupdateframework/notary/cryptoservice 4.439s +ok github.com/theupdateframework/notary/cryptoservice 3.538s === RUN TestGetPassphraseForUsingDelegationKey --- PASS: TestGetPassphraseForUsingDelegationKey (0.00s) === RUN TestGetPassphraseLimitsShortPassphrases @@ -2644,143 +2689,141 @@ === RUN TestGetPassphrase --- PASS: TestGetPassphrase (0.00s) PASS -ok github.com/theupdateframework/notary/passphrase 0.039s -? github.com/theupdateframework/notary/proto [no test files] +ok github.com/theupdateframework/notary/passphrase 0.015s === RUN TestValidationErrorFormat -time="2024-04-12T18:34:25Z" 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=aea4df4941d57d5a29b9efeabd3bbcec972cf72d1105175bb20ad83fe3cb" http.request.host="127.0.0.1:34305" http.request.id=1fad0285-b6dd-4ec4-88a8-cfbf299e00e9 http.request.method=POST http.request.remoteaddr="127.0.0.1:32890" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=aea4df4941d57d5a29b9efeabd3bbcec972cf72d1105175bb20ad83fe3cb" http.request.host="127.0.0.1:34305" http.request.id=1fad0285-b6dd-4ec4-88a8-cfbf299e00e9 http.request.method=POST http.request.remoteaddr="127.0.0.1:32890" 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=26.455028ms http.response.status=400 http.response.written=260 ---- PASS: TestValidationErrorFormat (0.16s) +time="2024-04-12T18:44:29Z" 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=72235258215c1243f8c17ca4c807d46aa22bba5cf9ca35dff8fb79564ce1" http.request.host="127.0.0.1:39787" http.request.id=5afc2a66-9403-4fef-8c62-3158efef1dbe http.request.method=POST http.request.remoteaddr="127.0.0.1:57734" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=72235258215c1243f8c17ca4c807d46aa22bba5cf9ca35dff8fb79564ce1" http.request.host="127.0.0.1:39787" http.request.id=5afc2a66-9403-4fef-8c62-3158efef1dbe http.request.method=POST http.request.remoteaddr="127.0.0.1:57734" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration=14.279711ms http.response.status=400 http.response.written=260 +--- PASS: TestValidationErrorFormat (0.10s) === RUN TestRunBadAddr --- PASS: TestRunBadAddr (0.00s) === RUN TestRunReservedPort --- PASS: TestRunReservedPort (0.00s) === RUN TestRepoPrefixMatches -time="2024-04-12T18:34:25Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum 78fad46acc97ece1d11c1b793d4b4c35d1b21d5f7bc5e74399e0763834d92c34" docker.io/notary=gun go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=1b5ec491a300e51bf26e6d6142c7aeab6320da08e01fef3c9f8c3bd14e5c" http.request.host="127.0.0.1:32823" http.request.id=8f682a92-16bc-445c-a331-82a243a0752e http.request.method=POST http.request.remoteaddr="127.0.0.1:54150" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=1b5ec491a300e51bf26e6d6142c7aeab6320da08e01fef3c9f8c3bd14e5c" http.request.host="127.0.0.1:32823" http.request.id=8f682a92-16bc-445c-a331-82a243a0752e http.request.method=POST http.request.remoteaddr="127.0.0.1:54150" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=41.825381ms http.response.status=0 http.response.written=0 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:32823" http.request.id=85678a20-caf5-4e25-b80a-47ae3897cd4b http.request.method=GET http.request.remoteaddr="127.0.0.1:54150" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration=1.205386ms http.response.status=200 http.response.written=683 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:32823" http.request.id=c4c00927-2928-45d5-93f8-ed3451d80391 http.request.method=GET http.request.remoteaddr="127.0.0.1:54150" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.f9e715638c7cc5ac9a9cd3884585677b9308d59a66ee4a3ba92f5bc3473eea11.json http.request.useragent=Go-http-client/1.1 http.response.duration="89.131µs" http.response.status=200 http.response.written=683 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:32823" http.request.id=36abd8a2-8cef-46d6-91e7-27c7a34724f6 http.request.method=GET http.request.remoteaddr="127.0.0.1:54150" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration=4.99527ms http.response.status=200 http.response.written=181 -time="2024-04-12T18:34:25Z" 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:32823" http.request.id=52e73c19-91cd-4de3-a375-60014de94804 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:54150" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:32823" http.request.id=52e73c19-91cd-4de3-a375-60014de94804 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:54150" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="196.402µs" http.response.status=0 http.response.written=0 ---- PASS: TestRepoPrefixMatches (0.12s) +time="2024-04-12T18:44:29Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum 32ff71caaa8fd56176642a77c5543c2f90c30f633e166a956d9149e651bc8738" docker.io/notary=gun go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=d01457e0cec59a45a392799971608dfe0a7cc0dc82053bd237be69fad9e3" http.request.host="127.0.0.1:33983" http.request.id=e3cb93f6-891a-434a-a5f3-393d1e6f4b8f http.request.method=POST http.request.remoteaddr="127.0.0.1:50014" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=d01457e0cec59a45a392799971608dfe0a7cc0dc82053bd237be69fad9e3" http.request.host="127.0.0.1:33983" http.request.id=e3cb93f6-891a-434a-a5f3-393d1e6f4b8f http.request.method=POST http.request.remoteaddr="127.0.0.1:50014" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=27.591658ms http.response.status=0 http.response.written=0 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:33983" http.request.id=4f4792db-82b5-49bc-ba49-dabe952272e5 http.request.method=GET http.request.remoteaddr="127.0.0.1:50014" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration=1.135918ms http.response.status=200 http.response.written=683 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:33983" http.request.id=a0bb22bf-f785-414e-b29c-0059f8e98194 http.request.method=GET http.request.remoteaddr="127.0.0.1:50014" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.0ded9063007f4f6b3ef932331923769ef37eb5480d8d6091fe1c169f9b4bfc7f.json http.request.useragent=Go-http-client/1.1 http.response.duration="97.316µs" http.response.status=200 http.response.written=683 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:33983" http.request.id=e06fb425-3aad-48f9-b268-78be12adb661 http.request.method=GET http.request.remoteaddr="127.0.0.1:50014" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="890.127µs" http.response.status=200 http.response.written=181 +time="2024-04-12T18:44:29Z" 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:33983" http.request.id=23a18705-16e4-4fd2-b575-9612dff135ac http.request.method=DELETE http.request.remoteaddr="127.0.0.1:50014" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:33983" http.request.id=23a18705-16e4-4fd2-b575-9612dff135ac http.request.method=DELETE http.request.remoteaddr="127.0.0.1:50014" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="108.436µs" http.response.status=0 http.response.written=0 +--- PASS: TestRepoPrefixMatches (0.10s) === RUN TestRepoPrefixDoesNotMatch ---- PASS: TestRepoPrefixDoesNotMatch (0.12s) +--- PASS: TestRepoPrefixDoesNotMatch (0.06s) === RUN TestMetricsEndpoint ---- PASS: TestMetricsEndpoint (0.05s) +--- PASS: TestMetricsEndpoint (0.02s) === RUN TestGetKeysEndpoint -time="2024-04-12T18:34:25Z" 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:43795" http.request.id=68cf5be2-6b7d-4858-af0c-0f9f7ba8cdaf http.request.method=GET http.request.remoteaddr="127.0.0.1:39154" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:43795" http.request.id=68cf5be2-6b7d-4858-af0c-0f9f7ba8cdaf http.request.method=GET http.request.remoteaddr="127.0.0.1:39154" 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="366.406µs" http.response.status=404 http.response.written=104 -time="2024-04-12T18:34:25Z" 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:43795" http.request.id=0e690c1d-25f9-4962-9785-13f515b34ede http.request.method=GET http.request.remoteaddr="127.0.0.1:39160" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:43795" http.request.id=0e690c1d-25f9-4962-9785-13f515b34ede http.request.method=GET http.request.remoteaddr="127.0.0.1:39160" 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="228.439µs" http.response.status=404 http.response.written=104 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:43795" http.request.id=4c3d9032-ef4b-44f6-9c29-c9fba9c0a921 http.request.method=GET http.request.remoteaddr="127.0.0.1:39162" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration=48.492713ms http.response.status=200 http.response.written=103 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:43795" http.request.id=13d06079-bd8b-4ef1-a377-9b46c8d74649 http.request.method=GET http.request.remoteaddr="127.0.0.1:39164" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="711.173µs" http.response.status=200 http.response.written=103 -time="2024-04-12T18:34:25Z" 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:43795" http.request.id=e6fb9735-00f6-44aa-ba03-f70ddf461a3a http.request.method=GET http.request.remoteaddr="127.0.0.1:39176" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:43795" http.request.id=e6fb9735-00f6-44aa-ba03-f70ddf461a3a http.request.method=GET http.request.remoteaddr="127.0.0.1:39176" 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="223.419µs" http.response.status=404 http.response.written=104 ---- PASS: TestGetKeysEndpoint (0.09s) +time="2024-04-12T18:44:29Z" 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:37049" http.request.id=a93768fe-6cc6-4aad-ba87-e94a8db8a195 http.request.method=GET http.request.remoteaddr="127.0.0.1:51220" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:37049" http.request.id=a93768fe-6cc6-4aad-ba87-e94a8db8a195 http.request.method=GET http.request.remoteaddr="127.0.0.1:51220" 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="273.71µs" http.response.status=404 http.response.written=104 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:37049" http.request.id=660ac791-e3c3-46d1-9040-74c09bd9eb73 http.request.method=GET http.request.remoteaddr="127.0.0.1:51226" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration=30.22356ms http.response.status=200 http.response.written=103 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:37049" http.request.id=f766031d-4cc9-4fed-9f52-0d7015dcbdc9 http.request.method=GET http.request.remoteaddr="127.0.0.1:51242" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="615.697µs" http.response.status=200 http.response.written=103 +time="2024-04-12T18:44:29Z" 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:37049" http.request.id=30512bd5-22b7-44e3-b88d-15c352e965bf http.request.method=GET http.request.remoteaddr="127.0.0.1:51256" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:37049" http.request.id=30512bd5-22b7-44e3-b88d-15c352e965bf http.request.method=GET http.request.remoteaddr="127.0.0.1:51256" 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="216.792µs" http.response.status=404 http.response.written=104 +time="2024-04-12T18:44:29Z" 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:37049" http.request.id=4359eb6b-a637-4092-a570-1ae76c49e063 http.request.method=GET http.request.remoteaddr="127.0.0.1:51258" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:37049" http.request.id=4359eb6b-a637-4092-a570-1ae76c49e063 http.request.method=GET http.request.remoteaddr="127.0.0.1:51258" 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="124.835µs" http.response.status=404 http.response.written=104 +--- PASS: TestGetKeysEndpoint (0.05s) === RUN TestGetRoleByHash -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:42273" http.request.id=596eb9c8-ab73-47e6-a52a-3172de0b4de3 http.request.method=GET http.request.remoteaddr="127.0.0.1:47936" http.request.uri=/v2/gun/_trust/tuf/timestamp.ad87b6df1c949286adf533fdf86f15c9b97120213f610de11056afec2b90118e.json http.request.useragent=Go-http-client/1.1 http.response.duration="133.247µs" http.response.status=200 http.response.written=115 ---- PASS: TestGetRoleByHash (0.01s) +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:41961" http.request.id=7df76730-7b84-4207-83bd-fd0f670d3363 http.request.method=GET http.request.remoteaddr="127.0.0.1:44722" http.request.uri=/v2/gun/_trust/tuf/timestamp.0514275bbbfcdb06fe7428a81691ba4e0e9d2b1a9c4334222fafb42f1dacbde6.json http.request.useragent=Go-http-client/1.1 http.response.duration="142.674µs" http.response.status=200 http.response.written=115 +--- PASS: TestGetRoleByHash (0.02s) === RUN TestGetRoleByVersion -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:43639" http.request.id=7d093597-0eb5-4ff0-8f35-c4c2596ecfb0 http.request.method=GET http.request.remoteaddr="127.0.0.1:51234" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="148.466µs" http.response.status=200 http.response.written=115 ---- PASS: TestGetRoleByVersion (0.03s) +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:44161" http.request.id=c55abd2b-cbdb-4138-9d84-cd381020bbef http.request.method=GET http.request.remoteaddr="127.0.0.1:46602" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="100.836µs" http.response.status=200 http.response.written=115 +--- PASS: TestGetRoleByVersion (0.01s) === RUN TestGetCurrentRole -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:43483" http.request.id=e1523fb0-775b-4de1-8401-4af2d36b648c http.request.method=GET http.request.remoteaddr="127.0.0.1:44054" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration=1.077018ms http.response.status=200 http.response.written=495 ---- PASS: TestGetCurrentRole (0.11s) +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:40621" http.request.id=66110245-719f-4f15-9968-4975761b2243 http.request.method=GET http.request.remoteaddr="127.0.0.1:44802" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="837.769µs" http.response.status=200 http.response.written=495 +--- PASS: TestGetCurrentRole (0.06s) === RUN TestRotateKeyEndpoint -time="2024-04-12T18:34:25Z" 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:40481" http.request.id=38774b34-b713-4e55-8598-e3b110ff24be http.request.method=POST http.request.remoteaddr="127.0.0.1:60166" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:40481" http.request.id=38774b34-b713-4e55-8598-e3b110ff24be http.request.method=POST http.request.remoteaddr="127.0.0.1:60166" 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=3.971426ms http.response.status=404 http.response.written=104 -time="2024-04-12T18:34:25Z" 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:40481" http.request.id=321899f8-774d-4220-9413-c8cb093f989e http.request.method=POST http.request.remoteaddr="127.0.0.1:60182" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:40481" http.request.id=321899f8-774d-4220-9413-c8cb093f989e http.request.method=POST http.request.remoteaddr="127.0.0.1:60182" 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="307.231µs" http.response.status=404 http.response.written=104 -time="2024-04-12T18:34:25Z" 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:40481" http.request.id=26052e40-d753-4e32-bd89-bdc4a353bf4d http.request.method=POST http.request.remoteaddr="127.0.0.1:60186" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:40481" http.request.id=26052e40-d753-4e32-bd89-bdc4a353bf4d http.request.method=POST http.request.remoteaddr="127.0.0.1:60186" 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="192.802µs" http.response.status=404 http.response.written=104 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:40481" http.request.id=459e3423-6c6f-4980-8f88-c67d5b5755c4 http.request.method=POST http.request.remoteaddr="127.0.0.1:60190" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="767.128µs" http.response.status=200 http.response.written=103 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:40481" http.request.id=0e8bed63-7e62-4e1c-933c-f29eabcc84cd http.request.method=POST http.request.remoteaddr="127.0.0.1:60194" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="686.255µs" http.response.status=200 http.response.written=103 -time="2024-04-12T18:34:25Z" 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:40481" http.request.id=0b34a153-2426-417e-92ff-787ebdb78c49 http.request.method=POST http.request.remoteaddr="127.0.0.1:60202" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2024-04-12T18:34:25Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:40481" http.request.id=0b34a153-2426-417e-92ff-787ebdb78c49 http.request.method=POST http.request.remoteaddr="127.0.0.1:60202" 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="197.521µs" http.response.status=404 http.response.written=104 ---- PASS: TestRotateKeyEndpoint (0.05s) +time="2024-04-12T18:44:29Z" 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:41067" http.request.id=0d0f34c0-1e37-4a8c-9596-94bfb8e4cca5 http.request.method=POST http.request.remoteaddr="127.0.0.1:33612" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:41067" http.request.id=0d0f34c0-1e37-4a8c-9596-94bfb8e4cca5 http.request.method=POST http.request.remoteaddr="127.0.0.1:33612" 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="182.953µs" http.response.status=404 http.response.written=104 +time="2024-04-12T18:44:29Z" 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:41067" http.request.id=56afd6b6-59a2-47a1-a715-392b28951c82 http.request.method=POST http.request.remoteaddr="127.0.0.1:33616" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:41067" http.request.id=56afd6b6-59a2-47a1-a715-392b28951c82 http.request.method=POST http.request.remoteaddr="127.0.0.1:33616" 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="150.874µs" http.response.status=404 http.response.written=104 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:41067" http.request.id=a71364fc-27db-44b9-b976-6f8a6b7cfb71 http.request.method=POST http.request.remoteaddr="127.0.0.1:33628" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="645.976µs" http.response.status=200 http.response.written=103 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:41067" http.request.id=29e150e3-943b-447f-b075-0532be7bebda http.request.method=POST http.request.remoteaddr="127.0.0.1:33642" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="978.683µs" http.response.status=200 http.response.written=103 +time="2024-04-12T18:44:29Z" 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:41067" http.request.id=e68adcb3-6845-4a99-a345-1437b7787ede http.request.method=POST http.request.remoteaddr="127.0.0.1:33654" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:41067" http.request.id=e68adcb3-6845-4a99-a345-1437b7787ede http.request.method=POST http.request.remoteaddr="127.0.0.1:33654" 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="169.794µs" http.response.status=404 http.response.written=104 +time="2024-04-12T18:44:29Z" 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:41067" http.request.id=b9a6e6b6-4e6b-4369-b7dc-9f0bfa19991f http.request.method=POST http.request.remoteaddr="127.0.0.1:33660" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2024-04-12T18:44:29Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:41067" http.request.id=b9a6e6b6-4e6b-4369-b7dc-9f0bfa19991f http.request.method=POST http.request.remoteaddr="127.0.0.1:33660" 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="160.514µs" http.response.status=404 http.response.written=104 +--- PASS: TestRotateKeyEndpoint (0.02s) PASS -ok github.com/theupdateframework/notary/server 0.792s -? github.com/theupdateframework/notary/server/errors [no test files] +ok github.com/theupdateframework/notary/server 0.487s === RUN Test_changefeed --- PASS: Test_changefeed (0.00s) === RUN Test_checkChangefeedInputs -time="2024-04-12T18:34:30Z" level=error msg="500 GET unable to retrieve storage" -time="2024-04-12T18:34:30Z" level=error msg="400 GET invalid pageSize: not_a_number" +time="2024-04-12T18:44:32Z" level=error msg="500 GET unable to retrieve storage" +time="2024-04-12T18:44:32Z" level=error msg="400 GET invalid pageSize: not_a_number" --- PASS: Test_checkChangefeedInputs (0.00s) === RUN TestMainHandlerGet -time="2024-04-12T18:34:30Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:46417" http.request.id=4694ad72-1ec0-4ac6-878a-bc03848d105e http.request.method=GET http.request.remoteaddr="127.0.0.1:41616" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="77.792µs" http.response.status=200 http.response.written=2 +time="2024-04-12T18:44:32Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:33975" http.request.id=05009216-e0f2-45bc-a9ec-b5eb546e3f1c http.request.method=GET http.request.remoteaddr="127.0.0.1:33126" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="55.797µs" http.response.status=200 http.response.written=2 --- PASS: TestMainHandlerGet (0.00s) === RUN TestMainHandlerNotGet -time="2024-04-12T18:34:30Z" 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:39697" http.request.id=76efea16-0b0e-4cac-bdf8-1761ed2efe98 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:36604" http.request.uri=/ http.request.useragent=Go-http-client/1.1 -time="2024-04-12T18:34:30Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:39697" http.request.id=76efea16-0b0e-4cac-bdf8-1761ed2efe98 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:36604" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="518.532µs" http.response.status=404 http.response.written=105 +time="2024-04-12T18:44:32Z" 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:46241" http.request.id=4adad31d-d22f-4e5a-b2c2-d656e05cf20d http.request.method=HEAD http.request.remoteaddr="127.0.0.1:37146" http.request.uri=/ http.request.useragent=Go-http-client/1.1 +time="2024-04-12T18:44:32Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:46241" http.request.id=4adad31d-d22f-4e5a-b2c2-d656e05cf20d http.request.method=HEAD http.request.remoteaddr="127.0.0.1:37146" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="577.179µs" http.response.status=404 http.response.written=105 --- PASS: TestMainHandlerNotGet (0.00s) === RUN TestKeyHandlersInvalidConfiguration -time="2024-04-12T18:34:30Z" level=error msg="500 GET storage not configured" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=error msg="500 GET storage not configured" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=error msg="500 POST storage not configured" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=error msg="500 POST storage not configured" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 GET storage not configured" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 GET storage not configured" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 POST storage not configured" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 POST storage not configured" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.2 gun=gun --- PASS: TestKeyHandlersInvalidConfiguration (0.00s) === RUN TestKeyHandlersNoRoleOrRepo -time="2024-04-12T18:34:30Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.2 -time="2024-04-12T18:34:30Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.2 -time="2024-04-12T18:34:30Z" level=info msg="400 GET no role in request" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=info msg="400 GET no role in request" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.2 -time="2024-04-12T18:34:30Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.2 -time="2024-04-12T18:34:30Z" level=info msg="400 POST no role in request" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=info msg="400 POST no role in request" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.2 +time="2024-04-12T18:44:32Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.2 +time="2024-04-12T18:44:32Z" level=info msg="400 GET no role in request" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=info msg="400 GET no role in request" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.2 +time="2024-04-12T18:44:32Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.2 +time="2024-04-12T18:44:32Z" level=info msg="400 POST no role in request" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=info msg="400 POST no role in request" go.version=go1.22.2 gun=gun --- PASS: TestKeyHandlersNoRoleOrRepo (0.00s) === RUN TestKeyHandlersInvalidRole -time="2024-04-12T18:34:30Z" level=info msg="400 GET root key: " go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=info msg="400 GET targets key: " go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=info msg="400 GET targets/a key: " go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=info msg="400 GET invalidrole key: " go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=info msg="400 POST root key: " go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=info msg="400 POST targets key: " go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=info msg="400 POST targets/a key: " go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=info msg="400 POST invalidrole key: " go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=info msg="400 GET root key: " go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=info msg="400 GET targets key: " go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=info msg="400 GET targets/a key: " go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=info msg="400 GET invalidrole key: " go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=info msg="400 POST root key: " go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=info msg="400 POST targets key: " go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=info msg="400 POST targets/a key: " go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=info msg="400 POST invalidrole key: " go.version=go1.22.2 gun=gun --- PASS: TestKeyHandlersInvalidRole (0.00s) === RUN TestGetKeyHandlerCreatesOnce ---- PASS: TestGetKeyHandlerCreatesOnce (0.06s) +--- PASS: TestGetKeyHandlerCreatesOnce (0.03s) === RUN TestKeyHandlersInvalidKeyAlgo -time="2024-04-12T18:34:30Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun -time="2024-04-12T18:34:30Z" level=error msg="500 POST snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" 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="2024-04-12T18:34:30Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun ---- PASS: TestGetHandlerRoot (0.07s) +time="2024-04-12T18:44:32Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun +--- PASS: TestGetHandlerRoot (0.04s) === RUN TestGetHandlerTimestamp ---- PASS: TestGetHandlerTimestamp (0.06s) +--- PASS: TestGetHandlerTimestamp (0.03s) === RUN TestGetHandlerSnapshot ---- PASS: TestGetHandlerSnapshot (0.04s) +--- PASS: TestGetHandlerSnapshot (0.03s) === RUN TestGetHandler404 -time="2024-04-12T18:34:30Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun --- PASS: TestGetHandler404 (0.00s) === RUN TestGetHandlerNilData -time="2024-04-12T18:34:30Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun +time="2024-04-12T18:44:32Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun --- PASS: TestGetHandlerNilData (0.00s) === RUN TestGetHandlerNoStorage -time="2024-04-12T18:34:30Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.22.2 +time="2024-04-12T18:44:32Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.22.2 --- PASS: TestGetHandlerNoStorage (0.00s) === RUN TestAtomicUpdateValidationFailurePropagated ---- PASS: TestAtomicUpdateValidationFailurePropagated (0.07s) +--- PASS: TestAtomicUpdateValidationFailurePropagated (0.06s) === RUN TestAtomicUpdateNonValidationFailureNotPropagated -time="2024-04-12T18:34:30Z" level=info msg="400 POST error validating update" go.version=go1.22.2 testGUN=gun ---- PASS: TestAtomicUpdateNonValidationFailureNotPropagated (0.06s) +time="2024-04-12T18:44:32Z" level=info msg="400 POST error validating update" go.version=go1.22.2 testGUN=gun +--- PASS: TestAtomicUpdateNonValidationFailureNotPropagated (0.05s) === RUN TestAtomicUpdateVersionErrorPropagated -time="2024-04-12T18:34:30Z" level=info msg="400 POST old version error" go.version=go1.22.2 testGUN=gun +time="2024-04-12T18:44:32Z" level=info msg="400 POST old version error" go.version=go1.22.2 testGUN=gun --- PASS: TestAtomicUpdateVersionErrorPropagated (0.08s) === RUN TestGetMaybeServerSignedNoCrypto --- PASS: TestGetMaybeServerSignedNoCrypto (0.00s) @@ -2789,53 +2832,53 @@ === RUN TestValidateEmptyNew --- PASS: TestValidateEmptyNew (0.08s) === RUN TestValidateRootCanContainOnlyx509KeysWithRightGun ---- PASS: TestValidateRootCanContainOnlyx509KeysWithRightGun (0.10s) +--- PASS: TestValidateRootCanContainOnlyx509KeysWithRightGun (0.09s) === RUN TestValidatePrevTimestamp --- PASS: TestValidatePrevTimestamp (0.08s) === RUN TestValidatePreviousTimestampCorrupt -time="2024-04-12T18:34:30Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" ---- PASS: TestValidatePreviousTimestampCorrupt (0.08s) +time="2024-04-12T18:44:32Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" +--- PASS: TestValidatePreviousTimestampCorrupt (0.07s) === RUN TestValidateGetCurrentTimestampBroken --- PASS: TestValidateGetCurrentTimestampBroken (0.07s) === RUN TestValidateNoNewRoot ---- PASS: TestValidateNoNewRoot (0.08s) +--- PASS: TestValidateNoNewRoot (0.07s) === RUN TestValidateNoNewTargets ---- PASS: TestValidateNoNewTargets (0.08s) +--- PASS: TestValidateNoNewTargets (0.07s) === RUN TestValidateOnlySnapshot ---- PASS: TestValidateOnlySnapshot (0.08s) +--- PASS: TestValidateOnlySnapshot (0.07s) === RUN TestValidateOldRoot ---- PASS: TestValidateOldRoot (0.09s) +--- PASS: TestValidateOldRoot (0.08s) === RUN TestValidateOldRootCorrupt ---- PASS: TestValidateOldRootCorrupt (0.06s) +--- PASS: TestValidateOldRootCorrupt (0.05s) === RUN TestValidateOldRootCorruptRootRole ---- PASS: TestValidateOldRootCorruptRootRole (0.06s) +--- PASS: TestValidateOldRootCorruptRootRole (0.05s) === RUN TestValidateRootGetCurrentRootBroken ---- PASS: TestValidateRootGetCurrentRootBroken (0.06s) +--- PASS: TestValidateRootGetCurrentRootBroken (0.05s) === RUN TestValidateRootRotationWithOldSigs ---- PASS: TestValidateRootRotationWithOldSigs (0.26s) +--- PASS: TestValidateRootRotationWithOldSigs (0.25s) === RUN TestValidateRootRotationMultipleKeysThreshold1 ---- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.16s) +--- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.17s) === RUN TestRootRotationNotSignedWithOldKeysForOldRole ---- PASS: TestRootRotationNotSignedWithOldKeysForOldRole (0.22s) +--- PASS: TestRootRotationNotSignedWithOldKeysForOldRole (0.21s) === RUN TestRootRotationVersionIncrement --- PASS: TestRootRotationVersionIncrement (0.15s) === RUN TestValidateNoRoot ---- PASS: TestValidateNoRoot (0.06s) +--- PASS: TestValidateNoRoot (0.05s) === RUN TestValidateSnapshotMissingNoSnapshotKey ---- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.07s) +--- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.09s) === RUN TestValidateSnapshotGenerateNoPrev --- PASS: TestValidateSnapshotGenerateNoPrev (0.09s) === RUN TestValidateSnapshotGenerateWithPrev ---- PASS: TestValidateSnapshotGenerateWithPrev (0.09s) +--- PASS: TestValidateSnapshotGenerateWithPrev (0.08s) === RUN TestValidateSnapshotGeneratePrevCorrupt -time="2024-04-12T18:34:32Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" ---- PASS: TestValidateSnapshotGeneratePrevCorrupt (0.08s) +time="2024-04-12T18:44:34Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" +--- PASS: TestValidateSnapshotGeneratePrevCorrupt (0.07s) === RUN TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken ---- PASS: TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken (0.08s) +--- PASS: TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken (0.09s) === RUN TestValidateSnapshotGenerateNoTargets --- PASS: TestValidateSnapshotGenerateNoTargets (0.07s) === RUN TestValidateSnapshotGenerate ---- PASS: TestValidateSnapshotGenerate (0.09s) +--- PASS: TestValidateSnapshotGenerate (0.08s) === RUN TestValidateRootNoTimestampKey --- PASS: TestValidateRootNoTimestampKey (0.07s) === RUN TestValidateRootInvalidTimestampKey @@ -2843,31 +2886,31 @@ === RUN TestValidateRootInvalidTimestampThreshold --- PASS: TestValidateRootInvalidTimestampThreshold (0.08s) === RUN TestValidateRootInvalidZeroThreshold ---- PASS: TestValidateRootInvalidZeroThreshold (0.23s) +--- PASS: TestValidateRootInvalidZeroThreshold (0.25s) === RUN TestValidateRootRoleMissing ---- PASS: TestValidateRootRoleMissing (0.05s) +--- PASS: TestValidateRootRoleMissing (0.04s) === RUN TestValidateTargetsRoleMissing ---- PASS: TestValidateTargetsRoleMissing (0.05s) +--- PASS: TestValidateTargetsRoleMissing (0.06s) === RUN TestValidateSnapshotRoleMissing ---- PASS: TestValidateSnapshotRoleMissing (0.05s) +--- PASS: TestValidateSnapshotRoleMissing (0.04s) === RUN TestValidateRootSigMissing ---- PASS: TestValidateRootSigMissing (0.05s) +--- PASS: TestValidateRootSigMissing (0.04s) === RUN TestValidateTargetsSigMissing -time="2024-04-12T18:34:33Z" level=error msg="ErrBadTargets: tuf: data has no signatures" +time="2024-04-12T18:44:35Z" level=error msg="ErrBadTargets: tuf: data has no signatures" --- PASS: TestValidateTargetsSigMissing (0.06s) === RUN TestValidateSnapshotSigMissing --- PASS: TestValidateSnapshotSigMissing (0.07s) === RUN TestValidateRootCorrupt ---- PASS: TestValidateRootCorrupt (0.06s) +--- PASS: TestValidateRootCorrupt (0.05s) === RUN TestValidateTargetsCorrupt -time="2024-04-12T18:34:33Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" ---- PASS: TestValidateTargetsCorrupt (0.06s) +time="2024-04-12T18:44:35Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" +--- PASS: TestValidateTargetsCorrupt (0.07s) === RUN TestValidateSnapshotCorrupt --- PASS: TestValidateSnapshotCorrupt (0.07s) === RUN TestValidateRootModifiedSize ---- PASS: TestValidateRootModifiedSize (0.06s) +--- PASS: TestValidateRootModifiedSize (0.07s) === RUN TestValidateTargetsModifiedSize ---- PASS: TestValidateTargetsModifiedSize (0.08s) +--- PASS: TestValidateTargetsModifiedSize (0.09s) === RUN TestValidateRootModifiedHash --- PASS: TestValidateRootModifiedHash (0.08s) === RUN TestValidateTargetsModifiedHash @@ -2877,20 +2920,20 @@ === RUN TestGenerateSnapshotNoKey --- PASS: TestGenerateSnapshotNoKey (0.06s) === RUN TestLoadTargetsLoadsNothingIfNoUpdates ---- PASS: TestLoadTargetsLoadsNothingIfNoUpdates (0.06s) +--- PASS: TestLoadTargetsLoadsNothingIfNoUpdates (0.05s) === RUN TestValidateTargetsRequiresStoredParent -time="2024-04-12T18:34:34Z" level=error msg="ErrBadTargets: targets must be loaded first" ---- PASS: TestValidateTargetsRequiresStoredParent (0.08s) +time="2024-04-12T18:44:36Z" level=error msg="ErrBadTargets: targets must be loaded first" +--- PASS: TestValidateTargetsRequiresStoredParent (0.09s) === RUN TestValidateTargetsParentInUpdate -time="2024-04-12T18:34:34Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" +time="2024-04-12T18:44:36Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" --- PASS: TestValidateTargetsParentInUpdate (0.08s) === RUN TestValidateTargetsRoleNotInParent -time="2024-04-12T18:34:34Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" -time="2024-04-12T18:34:34Z" level=error msg="ErrBadTargets: targets has already been loaded" -time="2024-04-12T18:34:34Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +time="2024-04-12T18:44:36Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +time="2024-04-12T18:44:36Z" level=error msg="ErrBadTargets: targets has already been loaded" +time="2024-04-12T18:44:36Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" --- PASS: TestValidateTargetsRoleNotInParent (0.10s) PASS -ok github.com/theupdateframework/notary/server/handlers 4.480s +ok github.com/theupdateframework/notary/server/handlers 4.293s === RUN TestSnapshotExpired --- PASS: TestSnapshotExpired (0.00s) === RUN TestSnapshotNotExpired @@ -2898,17 +2941,17 @@ === RUN TestGetSnapshotKeyCreate --- PASS: TestGetSnapshotKeyCreate (0.03s) === RUN TestGetSnapshotKeyCreateWithFailingStore -time="2024-04-12T18:34:36Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2024-04-12T18:44:38Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetSnapshotKeyCreateWithFailingStore (0.00s) === RUN TestGetSnapshotKeyCreateWithCorruptedStore -time="2024-04-12T18:34:36Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve snapshot key ID" +time="2024-04-12T18:44:38Z" 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.04s) === RUN TestGetSnapshotNoPreviousSnapshot -time="2024-04-12T18:34:36Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" +time="2024-04-12T18:44:38Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" --- PASS: TestGetSnapshotNoPreviousSnapshot (0.03s) === RUN TestGetSnapshotReturnsPreviousSnapshotIfUnexpired --- PASS: TestGetSnapshotReturnsPreviousSnapshotIfUnexpired (0.03s) @@ -2919,7 +2962,7 @@ === RUN TestCreateSnapshotNoKeyInCrypto --- PASS: TestCreateSnapshotNoKeyInCrypto (0.04s) PASS -ok github.com/theupdateframework/notary/server/snapshot 0.269s +ok github.com/theupdateframework/notary/server/snapshot 0.288s === RUN TestMemoryUpdateCurrentEmpty --- PASS: TestMemoryUpdateCurrentEmpty (0.00s) === RUN TestMemoryUpdateCurrentVersionCheckOldVersionExists @@ -2945,35 +2988,35 @@ === RUN TestRDBTUFFileJSONUnmarshallingFailure --- PASS: TestRDBTUFFileJSONUnmarshallingFailure (0.00s) === RUN TestSQLUpdateCurrentEmpty ---- PASS: TestSQLUpdateCurrentEmpty (0.20s) +--- PASS: TestSQLUpdateCurrentEmpty (0.16s) === RUN TestSQLUpdateCurrentVersionCheckOldVersionExists ---- PASS: TestSQLUpdateCurrentVersionCheckOldVersionExists (0.09s) +--- PASS: TestSQLUpdateCurrentVersionCheckOldVersionExists (0.10s) === RUN TestSQLUpdateCurrentVersionCheckOldVersionNotExist --- PASS: TestSQLUpdateCurrentVersionCheckOldVersionNotExist (0.09s) === RUN TestSQLUpdateManyNoConflicts ---- PASS: TestSQLUpdateManyNoConflicts (0.11s) +--- PASS: TestSQLUpdateManyNoConflicts (0.14s) === RUN TestSQLUpdateManyConflictRollback ---- PASS: TestSQLUpdateManyConflictRollback (0.08s) +--- PASS: TestSQLUpdateManyConflictRollback (0.12s) === RUN TestSQLDelete ---- PASS: TestSQLDelete (0.14s) +--- PASS: TestSQLDelete (0.16s) === RUN TestSQLDBCheckHealthTableMissing ---- PASS: TestSQLDBCheckHealthTableMissing (0.07s) +--- PASS: TestSQLDBCheckHealthTableMissing (0.08s) === RUN TestSQLDBCheckHealthDBConnectionFail --- PASS: TestSQLDBCheckHealthDBConnectionFail (0.06s) === RUN TestSQLDBCheckHealthSucceeds ---- PASS: TestSQLDBCheckHealthSucceeds (0.05s) +--- PASS: TestSQLDBCheckHealthSucceeds (0.06s) === RUN TestSQLDBGetChecksum ---- PASS: TestSQLDBGetChecksum (0.08s) +--- PASS: TestSQLDBGetChecksum (0.11s) === RUN TestSQLDBGetChecksumNotFound ---- PASS: TestSQLDBGetChecksumNotFound (0.06s) +--- PASS: TestSQLDBGetChecksumNotFound (0.05s) === RUN TestSQLTUFMetaStoreGetCurrent ---- PASS: TestSQLTUFMetaStoreGetCurrent (0.22s) +--- PASS: TestSQLTUFMetaStoreGetCurrent (0.23s) === RUN TestSQLGetChanges ---- PASS: TestSQLGetChanges (0.18s) +--- PASS: TestSQLGetChanges (0.19s) === RUN TestSQLDBGetVersion ---- PASS: TestSQLDBGetVersion (0.07s) +--- PASS: TestSQLDBGetVersion (0.10s) PASS -ok github.com/theupdateframework/notary/server/storage 1.535s +ok github.com/theupdateframework/notary/server/storage 1.709s === RUN TestTimestampExpired --- PASS: TestTimestampExpired (0.00s) === RUN TestTimestampNotExpired @@ -2981,35 +3024,35 @@ === RUN TestGetTimestampKey --- PASS: TestGetTimestampKey (0.03s) === RUN TestGetTimestampNoPreviousTimestamp -time="2024-04-12T18:34:42Z" level=error msg="Failed to unmarshal existing timestamp" ---- PASS: TestGetTimestampNoPreviousTimestamp (0.06s) +time="2024-04-12T18:44:45Z" level=error msg="Failed to unmarshal existing timestamp" +--- PASS: TestGetTimestampNoPreviousTimestamp (0.07s) === RUN TestGetTimestampReturnsPreviousTimestampIfUnexpired ---- PASS: TestGetTimestampReturnsPreviousTimestampIfUnexpired (0.05s) +--- PASS: TestGetTimestampReturnsPreviousTimestampIfUnexpired (0.06s) === RUN TestGetTimestampOldTimestampExpired --- PASS: TestGetTimestampOldTimestampExpired (0.08s) === RUN TestCannotMakeNewTimestampIfNoRootOrSnapshot -time="2024-04-12T18:34:42Z" level=error msg="Failed to create a new timestamp" -time="2024-04-12T18:34:42Z" level=error msg="Failed to create a new timestamp" ---- PASS: TestCannotMakeNewTimestampIfNoRootOrSnapshot (0.06s) +time="2024-04-12T18:44:45Z" level=error msg="Failed to create a new timestamp" +time="2024-04-12T18:44:45Z" level=error msg="Failed to create a new timestamp" +--- PASS: TestCannotMakeNewTimestampIfNoRootOrSnapshot (0.05s) === RUN TestCreateTimestampNoKeyInCrypto -time="2024-04-12T18:34:42Z" level=error msg="Failed to create a new timestamp" ---- PASS: TestCreateTimestampNoKeyInCrypto (0.07s) +time="2024-04-12T18:44:46Z" level=error msg="Failed to create a new timestamp" +--- PASS: TestCreateTimestampNoKeyInCrypto (0.06s) === RUN TestGetTimestampKeyCreateWithFailingStore -time="2024-04-12T18:34:42Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2024-04-12T18:44:46Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetTimestampKeyCreateWithFailingStore (0.00s) === RUN TestGetTimestampKeyCreateWithCorruptedStore -time="2024-04-12T18:34:42Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve timestamp key ID" +time="2024-04-12T18:44:46Z" 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.03s) PASS -ok github.com/theupdateframework/notary/server/timestamp 0.390s +ok github.com/theupdateframework/notary/server/timestamp 0.414s === RUN TestHealthCheckKMUnhealthy ---- PASS: TestHealthCheckKMUnhealthy (0.00s) +--- PASS: TestHealthCheckKMUnhealthy (0.01s) === RUN TestHealthCheckSignerUnhealthy ---- PASS: TestHealthCheckSignerUnhealthy (0.01s) +--- PASS: TestHealthCheckSignerUnhealthy (0.00s) === RUN TestHealthCheckKMTimeout --- PASS: TestHealthCheckKMTimeout (0.00s) === RUN TestHealthCheckSignerTimeout @@ -3027,27 +3070,25 @@ === RUN TestHealthCheckNonexistentService --- PASS: TestHealthCheckNonexistentService (0.00s) === RUN TestGetPrivateKeyAndSignWithExistingKey -time="2024-04-12T18:34:44Z" level=info msg="Sign: Signed message! with KeyID ff5d377bba511cd4828e2ac4eca17cc6067c570c8552b16f2fb5668cdeee6939" go.version=go1.22.2 ---- PASS: TestGetPrivateKeyAndSignWithExistingKey (0.04s) +time="2024-04-12T18:44:47Z" level=info msg="Sign: Signed message! with KeyID d571819a4894c92dad91277d6460b5aeeb3bb672543b2af27ad6fc76416fe1ec" go.version=go1.22.2 +--- PASS: TestGetPrivateKeyAndSignWithExistingKey (0.03s) === RUN TestCannotSignWithKeyThatDoesntExist -time="2024-04-12T18:34:44Z" level=error msg="Sign: key 0a86ca9e543520b4fcdd183e58b8230b6e2e8cc8b351e9e78d4857488e519b95 not found" go.version=go1.22.2 ---- PASS: TestCannotSignWithKeyThatDoesntExist (0.00s) +time="2024-04-12T18:44:47Z" level=error msg="Sign: key f51f8eaae7498740b5a812878b64bc13467dfcaf39724b32a3c0d6cccc25a661 not found" go.version=go1.22.2 +--- PASS: TestCannotSignWithKeyThatDoesntExist (0.01s) === RUN TestCryptoSignerInterfaceBehavior -time="2024-04-12T18:34:44Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.22.2 -time="2024-04-12T18:34:44Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.22.2 -time="2024-04-12T18:34:44Z" level=info msg="CreateKey: Created KeyID e75aff368320f23362f8195ce3d8bcb4b55fd1ddbafbee7677fd98e5c41a7592" go.version=go1.22.2 -time="2024-04-12T18:34:44Z" level=info msg="CreateKey: Created KeyID 62a16c2a0d477f4c56c50eae3851f6a27ee96da7a3cf9a945206bbc1eeb77af4" go.version=go1.22.2 -time="2024-04-12T18:34:44Z" level=error msg="GetKeyInfo: key e75aff368320f23362f8195ce3d8bcb4b55fd1ddbafbee7677fd98e5c41a7592 not found" go.version=go1.22.2 -time="2024-04-12T18:34:44Z" level=error msg="GetKeyInfo: key 62a16c2a0d477f4c56c50eae3851f6a27ee96da7a3cf9a945206bbc1eeb77af4 not found" go.version=go1.22.2 +time="2024-04-12T18:44:47Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.22.2 +time="2024-04-12T18:44:47Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.22.2 +time="2024-04-12T18:44:47Z" level=info msg="CreateKey: Created KeyID 5abfda80d5ff8e9603b4e5110bb98cd5bc2d24cfc77bef850cc357ea483f20ce" go.version=go1.22.2 +time="2024-04-12T18:44:47Z" level=info msg="CreateKey: Created KeyID e9598606416f48e52ef59893419f32e10618b11394160cedf8fc963f9df5f142" go.version=go1.22.2 +time="2024-04-12T18:44:47Z" level=error msg="GetKeyInfo: key 5abfda80d5ff8e9603b4e5110bb98cd5bc2d24cfc77bef850cc357ea483f20ce not found" go.version=go1.22.2 +time="2024-04-12T18:44:47Z" level=error msg="GetKeyInfo: key e9598606416f48e52ef59893419f32e10618b11394160cedf8fc963f9df5f142 not found" go.version=go1.22.2 --- PASS: TestCryptoSignerInterfaceBehavior (0.03s) PASS -ok github.com/theupdateframework/notary/signer 0.108s -? github.com/theupdateframework/notary/signer/api [no test files] -? github.com/theupdateframework/notary/signer/client [no test files] +ok github.com/theupdateframework/notary/signer 0.116s === RUN TestGetSuccessPopulatesCache ---- PASS: TestGetSuccessPopulatesCache (0.02s) +--- PASS: TestGetSuccessPopulatesCache (0.03s) === RUN TestAddKeyPopulatesCacheIfSuccessful ---- PASS: TestAddKeyPopulatesCacheIfSuccessful (0.01s) +--- PASS: TestAddKeyPopulatesCacheIfSuccessful (0.02s) === RUN TestDeleteKeyRemovesKeyFromCache --- PASS: TestDeleteKeyRemovesKeyFromCache (0.02s) === RUN TestRDBPrivateKeyJSONUnmarshalling @@ -3058,30 +3099,30 @@ `nodb` is not officially supported, running under compatibility mode. --- PASS: TestNewSQLKeyDBStorePropagatesDBError (0.00s) === RUN TestSQLDBHealthCheckMissingTable ---- PASS: TestSQLDBHealthCheckMissingTable (0.06s) +--- PASS: TestSQLDBHealthCheckMissingTable (0.04s) === RUN TestSQLDBHealthCheckNoConnection --- PASS: TestSQLDBHealthCheckNoConnection (0.04s) === RUN TestSQLKeyCanOnlyBeAddedOnce (/build/reproducible-path/notary-0.7.0+ds1/_build/src/github.com/theupdateframework/notary/signer/keydbstore/sql_keydbstore.go:98) -[2024-04-12 18:34:47]  UNIQUE constraint failed: private_keys.key_id  ---- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.24s) +[2024-04-12 18:44:51]  UNIQUE constraint failed: private_keys.key_id  +--- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.21s) === RUN TestSQLCreateDelete ---- PASS: TestSQLCreateDelete (0.31s) +--- PASS: TestSQLCreateDelete (0.25s) === RUN TestSQLKeyRotation ---- PASS: TestSQLKeyRotation (0.34s) +--- PASS: TestSQLKeyRotation (0.31s) === RUN TestSQLSigningMarksKeyActive -time="2024-04-12T18:34:48Z" level=error msg="Key 3ca7a6d89f3301885bac3453a496c05eb30cb1dd8353440dc479efa4842dbdd0 was just used to sign hash successful, db closed, error when trying to mark key as active: sql: database is closed" ---- PASS: TestSQLSigningMarksKeyActive (0.52s) -=== RUN TestSQLCreateKey +time="2024-04-12T18:44:52Z" level=error msg="Key 4629c900b4e2a4e3d2302e2ceca50d35f06a8c77b8640f2044fffa046fa98c68 was just used to sign hash successful, db closed, error when trying to mark key as active: sql: database is closed" (/build/reproducible-path/notary-0.7.0+ds1/_build/src/github.com/theupdateframework/notary/signer/keydbstore/sql_keydbstore.go:197) -[2024-04-12 18:34:48]  sql: database is closed  ---- PASS: TestSQLCreateKey (0.46s) +[2024-04-12 18:44:52]  sql: database is closed  +--- PASS: TestSQLSigningMarksKeyActive (0.49s) +=== RUN TestSQLCreateKey +--- PASS: TestSQLCreateKey (0.40s) === RUN TestSQLUnimplementedInterfaceBehavior ---- PASS: TestSQLUnimplementedInterfaceBehavior (0.13s) +--- PASS: TestSQLUnimplementedInterfaceBehavior (0.11s) PASS -ok github.com/theupdateframework/notary/signer/keydbstore 2.193s +ok github.com/theupdateframework/notary/signer/keydbstore 1.988s === RUN TestSet --- PASS: TestSet (0.00s) === RUN TestSetWithNoParentDirectory @@ -3113,7 +3154,7 @@ === RUN TestCreatePrivateDirectory --- PASS: TestCreatePrivateDirectory (0.00s) === RUN TestFileStoreConsistency ---- PASS: TestFileStoreConsistency (0.01s) +--- PASS: TestFileStoreConsistency (0.00s) === RUN TestHTTPStoreGetSized --- PASS: TestHTTPStoreGetSized (0.00s) === RUN TestHTTPStoreGetAllMeta @@ -3123,7 +3164,7 @@ === RUN Test404Error --- PASS: Test404Error (0.00s) === RUN Test50XErrors ---- PASS: Test50XErrors (0.01s) +--- PASS: Test50XErrors (0.00s) === RUN Test400Error --- PASS: Test400Error (0.00s) === RUN TestTranslateErrorsParse400Errors @@ -3160,8 +3201,7 @@ === RUN TestMemoryStoreMetadata --- PASS: TestMemoryStoreMetadata (0.00s) PASS -ok github.com/theupdateframework/notary/storage 0.071s -? github.com/theupdateframework/notary/storage/rethinkdb [no test files] +ok github.com/theupdateframework/notary/storage 0.088s === RUN TestExportKeys --- PASS: TestExportKeys (0.00s) === RUN TestExportKeysByGUN @@ -3185,18 +3225,18 @@ === RUN TestImportKeys2InOneFileNoPath --- PASS: TestImportKeys2InOneFileNoPath (0.02s) === RUN TestEncryptedKeyImportFail -time="2024-04-12T18:34:52Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" +time="2024-04-12T18:44:56Z" 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.02s) === RUN TestEncryptedKeyImportSuccess --- PASS: TestEncryptedKeyImportSuccess (0.02s) === RUN TestEncryption --- PASS: TestEncryption (0.03s) === RUN TestAddKey ---- PASS: TestAddKey (0.06s) +--- PASS: TestAddKey (0.07s) === RUN TestKeyStoreInternalState --- PASS: TestKeyStoreInternalState (0.03s) === RUN TestGet ---- PASS: TestGet (0.04s) +--- PASS: TestGet (0.05s) === RUN TestGetLegacyKey --- PASS: TestGetLegacyKey (0.00s) === RUN TestListKeys @@ -3206,75 +3246,74 @@ === RUN TestAddGetKeyInfoMemStore --- PASS: TestAddGetKeyInfoMemStore (0.03s) === RUN TestGetDecryptedWithTamperedCipherText -time="2024-04-12T18:34:53Z" level=error msg="PEM block is empty" +time="2024-04-12T18:44:56Z" level=error msg="PEM block is empty" --- PASS: TestGetDecryptedWithTamperedCipherText (0.01s) === RUN TestGetDecryptedWithInvalidPassphrase ---- PASS: TestGetDecryptedWithInvalidPassphrase (0.06s) +--- PASS: TestGetDecryptedWithInvalidPassphrase (0.05s) === RUN TestGetDecryptedWithConsistentlyInvalidPassphrase ---- PASS: TestGetDecryptedWithConsistentlyInvalidPassphrase (0.10s) +--- PASS: TestGetDecryptedWithConsistentlyInvalidPassphrase (0.09s) === RUN TestRemoveKey --- PASS: TestRemoveKey (0.06s) === RUN TestKeysAreCached ---- PASS: TestKeysAreCached (0.02s) +--- PASS: TestKeysAreCached (0.03s) PASS -ok github.com/theupdateframework/notary/trustmanager 0.632s -? github.com/theupdateframework/notary/trustmanager/yubikey [no test files] +ok github.com/theupdateframework/notary/trustmanager 0.659s === RUN TestRemoteStore -time="2024-04-12T18:34:55Z" level=info msg="listing files from localhost:9888" -time="2024-04-12T18:34:55Z" level=info msg="listing files from localhost:9888" ---- PASS: TestRemoteStore (0.27s) +time="2024-04-12T18:44:58Z" level=info msg="listing files from localhost:9888" +time="2024-04-12T18:44:58Z" level=info msg="listing files from localhost:9888" +--- PASS: TestRemoteStore (0.24s) === RUN TestErrors -time="2024-04-12T18:34:55Z" level=error msg="failed to store: test error" ---- PASS: TestErrors (0.22s) +time="2024-04-12T18:44:59Z" level=error msg="failed to store: test error" +--- PASS: TestErrors (0.20s) === RUN TestNewGRPCStorage --- PASS: TestNewGRPCStorage (0.00s) === RUN TestGRPCStorage --- PASS: TestGRPCStorage (0.00s) PASS -ok github.com/theupdateframework/notary/trustmanager/remoteks 0.506s +ok github.com/theupdateframework/notary/trustmanager/remoteks 0.514s === RUN TestWildcardMatch --- PASS: TestWildcardMatch (0.00s) === RUN TestValidateRoot -2024/04/12 18:35:01 [INFO] generate received request -2024/04/12 18:35:01 [INFO] received CSR -2024/04/12 18:35:01 [INFO] generating key: ecdsa-256 -2024/04/12 18:35:01 [INFO] encoded CSR -2024/04/12 18:35:01 [INFO] signed certificate with serial number 148288687456711861454497433149737194677720918423 -2024/04/12 18:35:01 [INFO] received CSR -2024/04/12 18:35:01 [INFO] generating key: ecdsa-256 -2024/04/12 18:35:01 [INFO] encoded CSR -2024/04/12 18:35:01 [INFO] signed certificate with serial number 455954556989060825154326127386050509903790805729 -2024/04/12 18:35:01 [INFO] received CSR -2024/04/12 18:35:01 [INFO] generating key: ecdsa-256 -2024/04/12 18:35:01 [INFO] encoded CSR -2024/04/12 18:35:01 [INFO] signed certificate with serial number 30619207447325996728561557163536028346213509439 ---- PASS: TestValidateRoot (0.23s) +2024/04/12 18:45:05 [INFO] generate received request +2024/04/12 18:45:05 [INFO] received CSR +2024/04/12 18:45:05 [INFO] generating key: ecdsa-256 +2024/04/12 18:45:05 [INFO] encoded CSR +2024/04/12 18:45:05 [INFO] signed certificate with serial number 674003714748167893033678892554276810799070659928 +2024/04/12 18:45:05 [INFO] received CSR +2024/04/12 18:45:05 [INFO] generating key: ecdsa-256 +2024/04/12 18:45:05 [INFO] encoded CSR +2024/04/12 18:45:05 [INFO] signed certificate with serial number 517466521125444762732726040537582714036380735648 +2024/04/12 18:45:05 [INFO] received CSR +2024/04/12 18:45:05 [INFO] generating key: ecdsa-256 +2024/04/12 18:45:05 [INFO] encoded CSR +2024/04/12 18:45:05 [INFO] signed certificate with serial number 352578529602435006123179300517814077994932027854 +--- PASS: TestValidateRoot (0.20s) === RUN TestValidateRootWithoutTOFUS --- PASS: TestValidateRootWithoutTOFUS (0.00s) === RUN TestValidateRootWithPinnedCert --- PASS: TestValidateRootWithPinnedCert (0.01s) === RUN TestValidateRootWithPinnedCertAndIntermediates ---- PASS: TestValidateRootWithPinnedCertAndIntermediates (0.08s) +--- PASS: TestValidateRootWithPinnedCertAndIntermediates (0.07s) === RUN TestValidateRootFailuresWithPinnedCert --- PASS: TestValidateRootFailuresWithPinnedCert (0.01s) === RUN TestValidateRootWithPinnedCA ---- PASS: TestValidateRootWithPinnedCA (0.14s) +--- PASS: TestValidateRootWithPinnedCA (0.13s) === RUN TestValidateSuccessfulRootRotation --- PASS: TestValidateSuccessfulRootRotation (0.24s) === RUN TestValidateRootRotationMissingOrigSig ---- PASS: TestValidateRootRotationMissingOrigSig (0.26s) +--- PASS: TestValidateRootRotationMissingOrigSig (0.14s) === RUN TestValidateRootRotationMissingNewSig ---- PASS: TestValidateRootRotationMissingNewSig (0.17s) +--- PASS: TestValidateRootRotationMissingNewSig (0.16s) === RUN TestValidateRootRotationTrustPinning ---- PASS: TestValidateRootRotationTrustPinning (0.17s) +--- PASS: TestValidateRootRotationTrustPinning (0.21s) === RUN TestValidateRootRotationTrustPinningInvalidCA ---- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.13s) +--- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.10s) === RUN TestParsePEMPublicKey -time="2024-04-12T18:35:03Z" level=warning msg="certificate with CN notary is near expiry" ---- PASS: TestParsePEMPublicKey (0.03s) +time="2024-04-12T18:45:06Z" level=warning msg="certificate with CN notary is near expiry" +--- PASS: TestParsePEMPublicKey (0.02s) === RUN TestCheckingCertExpiry -time="2024-04-12T18:35:03Z" level=warning msg="certificate with CN notary is near expiry" ---- PASS: TestCheckingCertExpiry (0.04s) +time="2024-04-12T18:45:06Z" level=warning msg="certificate with CN notary is near expiry" +--- PASS: TestCheckingCertExpiry (0.03s) === RUN TestValidateRootWithExpiredIntermediate --- PASS: TestValidateRootWithExpiredIntermediate (0.03s) === RUN TestCheckingWildcardCert @@ -3282,87 +3321,87 @@ === RUN TestWildcardMatching --- PASS: TestWildcardMatching (0.00s) PASS -ok github.com/theupdateframework/notary/trustpinning 1.620s +ok github.com/theupdateframework/notary/trustpinning 1.444s === RUN TestInitSnapshotNoTargets ---- PASS: TestInitSnapshotNoTargets (0.05s) +--- PASS: TestInitSnapshotNoTargets (0.06s) === RUN TestInitRepo ---- PASS: TestInitRepo (0.04s) +--- PASS: TestInitRepo (0.05s) === RUN TestUpdateDelegations --- PASS: TestUpdateDelegations (0.01s) === RUN TestPurgeDelegationsKeyFromTop -time="2024-04-12T18:35:02Z" 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="2024-04-12T18:35:02Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" ---- PASS: TestPurgeDelegationsKeyFromTop (0.01s) +time="2024-04-12T18:45:06Z" 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="2024-04-12T18:45:06Z" 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="2024-04-12T18:35:02Z" 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="2024-04-12T18:45:06Z" 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.01s) === RUN TestPurgeDelegationsKeyBadWildRole --- PASS: TestPurgeDelegationsKeyBadWildRole (0.01s) === RUN TestUpdateDelegationsParentMissing --- PASS: TestUpdateDelegationsParentMissing (0.00s) === RUN TestUpdateDelegationsMissingParentKey ---- PASS: TestUpdateDelegationsMissingParentKey (0.01s) +--- PASS: TestUpdateDelegationsMissingParentKey (0.00s) === RUN TestUpdateDelegationsInvalidRole ---- PASS: TestUpdateDelegationsInvalidRole (0.00s) +--- PASS: TestUpdateDelegationsInvalidRole (0.01s) === RUN TestUpdateDelegationsRoleThatIsMissingDelegationKey ---- PASS: TestUpdateDelegationsRoleThatIsMissingDelegationKey (0.01s) +--- PASS: TestUpdateDelegationsRoleThatIsMissingDelegationKey (0.00s) === RUN TestUpdateDelegationsNotEnoughKeys -time="2024-04-12T18:35:02Z" 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="2024-04-12T18:45:06Z" 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.01s) === RUN TestUpdateDelegationsAddKeyToRole ---- PASS: TestUpdateDelegationsAddKeyToRole (0.00s) +--- PASS: TestUpdateDelegationsAddKeyToRole (0.02s) === RUN TestDeleteDelegations --- PASS: TestDeleteDelegations (0.01s) === RUN TestDeleteDelegationsRoleNotExistBecauseNoParentMeta ---- PASS: TestDeleteDelegationsRoleNotExistBecauseNoParentMeta (0.00s) +--- PASS: TestDeleteDelegationsRoleNotExistBecauseNoParentMeta (0.01s) === RUN TestDeleteDelegationsRoleNotExist ---- PASS: TestDeleteDelegationsRoleNotExist (0.01s) +--- PASS: TestDeleteDelegationsRoleNotExist (0.00s) === RUN TestDeleteDelegationsInvalidRole ---- PASS: TestDeleteDelegationsInvalidRole (0.00s) +--- PASS: TestDeleteDelegationsInvalidRole (0.01s) === RUN TestDeleteDelegationsParentMissing ---- PASS: TestDeleteDelegationsParentMissing (0.00s) +--- PASS: TestDeleteDelegationsParentMissing (0.01s) === RUN TestDeleteDelegationsMissingParentSigningKey ---- PASS: TestDeleteDelegationsMissingParentSigningKey (0.00s) +--- PASS: TestDeleteDelegationsMissingParentSigningKey (0.01s) === RUN TestDeleteDelegationsMidSliceRole ---- PASS: TestDeleteDelegationsMidSliceRole (0.01s) +--- PASS: TestDeleteDelegationsMidSliceRole (0.00s) === RUN TestGetDelegationRoleAndMetadataExistDelegationExists --- PASS: TestGetDelegationRoleAndMetadataExistDelegationExists (0.01s) === RUN TestGetDelegationRoleAndMetadataExistDelegationDoesntExists --- PASS: TestGetDelegationRoleAndMetadataExistDelegationDoesntExists (0.00s) === RUN TestGetDelegationRoleAndMetadataDoesntExists ---- PASS: TestGetDelegationRoleAndMetadataDoesntExists (0.00s) +--- PASS: TestGetDelegationRoleAndMetadataDoesntExists (0.01s) === RUN TestGetDelegationParentMissing ---- PASS: TestGetDelegationParentMissing (0.00s) +--- PASS: TestGetDelegationParentMissing (0.01s) === RUN TestAddTargetsRoleAndMetadataExist --- PASS: TestAddTargetsRoleAndMetadataExist (0.00s) === RUN TestAddTargetsRoleExistsAndMetadataDoesntExist --- PASS: TestAddTargetsRoleExistsAndMetadataDoesntExist (0.01s) === RUN TestAddTargetsRoleDoesntExist ---- PASS: TestAddTargetsRoleDoesntExist (0.00s) +--- PASS: TestAddTargetsRoleDoesntExist (0.01s) === RUN TestAddTargetsNoSigningKeys --- PASS: TestAddTargetsNoSigningKeys (0.00s) === RUN TestRemoveExistingAndNonexistingTargets ---- PASS: TestRemoveExistingAndNonexistingTargets (0.01s) +--- PASS: TestRemoveExistingAndNonexistingTargets (0.00s) === RUN TestRemoveTargetsRoleDoesntExist ---- PASS: TestRemoveTargetsRoleDoesntExist (0.01s) +--- PASS: TestRemoveTargetsRoleDoesntExist (0.00s) === RUN TestRemoveTargetsNoSigningKeys ---- PASS: TestRemoveTargetsNoSigningKeys (0.01s) +--- PASS: TestRemoveTargetsNoSigningKeys (0.00s) === RUN TestAddBaseKeysToRoot ---- PASS: TestAddBaseKeysToRoot (0.02s) +--- PASS: TestAddBaseKeysToRoot (0.01s) === RUN TestRemoveBaseKeysFromRoot --- PASS: TestRemoveBaseKeysFromRoot (0.01s) === RUN TestReplaceBaseKeysInRoot ---- PASS: TestReplaceBaseKeysInRoot (0.05s) +--- PASS: TestReplaceBaseKeysInRoot (0.03s) === RUN TestGetAllRoles ---- PASS: TestGetAllRoles (0.01s) +--- PASS: TestGetAllRoles (0.00s) === RUN TestGetBaseRoles --- PASS: TestGetBaseRoles (0.00s) === RUN TestGetBaseRolesInvalidName ---- PASS: TestGetBaseRolesInvalidName (0.01s) +--- PASS: TestGetBaseRolesInvalidName (0.00s) === RUN TestGetDelegationValidRoles --- PASS: TestGetDelegationValidRoles (0.01s) === RUN TestGetDelegationRolesInvalidName ---- PASS: TestGetDelegationRolesInvalidName (0.01s) +--- PASS: TestGetDelegationRolesInvalidName (0.00s) === RUN TestGetDelegationRolesInvalidPaths --- PASS: TestGetDelegationRolesInvalidPaths (0.00s) === RUN TestDelegationRolesParent @@ -3370,45 +3409,45 @@ === RUN TestGetBaseRoleEmptyRepo --- PASS: TestGetBaseRoleEmptyRepo (0.00s) === RUN TestGetBaseRoleKeyMissing ---- PASS: TestGetBaseRoleKeyMissing (0.01s) +--- PASS: TestGetBaseRoleKeyMissing (0.00s) === RUN TestGetDelegationRoleKeyMissing --- PASS: TestGetDelegationRoleKeyMissing (0.00s) === RUN TestSignRootOldKeyCertExists ---- PASS: TestSignRootOldKeyCertExists (0.19s) +--- PASS: TestSignRootOldKeyCertExists (0.18s) === RUN TestSignRootOldKeyCertMissing ---- PASS: TestSignRootOldKeyCertMissing (0.09s) +--- PASS: TestSignRootOldKeyCertMissing (0.08s) === RUN TestRootKeyRotation ---- PASS: TestRootKeyRotation (0.38s) +--- PASS: TestRootKeyRotation (0.63s) === RUN TestBuilderLoadsValidRolesOnly ---- PASS: TestBuilderLoadsValidRolesOnly (0.09s) +--- PASS: TestBuilderLoadsValidRolesOnly (0.22s) === RUN TestBuilderOnlyAcceptsRootFirstWhenLoading ---- PASS: TestBuilderOnlyAcceptsRootFirstWhenLoading (0.01s) +--- PASS: TestBuilderOnlyAcceptsRootFirstWhenLoading (0.02s) === RUN TestBuilderOnlyAcceptsDelegationsAfterParent ---- PASS: TestBuilderOnlyAcceptsDelegationsAfterParent (0.02s) +--- PASS: TestBuilderOnlyAcceptsDelegationsAfterParent (0.04s) === RUN TestMarkingIsValid ---- PASS: TestMarkingIsValid (0.04s) +--- PASS: TestMarkingIsValid (0.03s) === RUN TestBuilderLoadInvalidDelegations ---- PASS: TestBuilderLoadInvalidDelegations (0.07s) +--- PASS: TestBuilderLoadInvalidDelegations (0.16s) === RUN TestBuilderLoadInvalidDelegationsOldVersion ---- PASS: TestBuilderLoadInvalidDelegationsOldVersion (0.09s) +--- PASS: TestBuilderLoadInvalidDelegationsOldVersion (0.07s) === RUN TestBuilderAcceptRoleOnce ---- PASS: TestBuilderAcceptRoleOnce (0.07s) +--- PASS: TestBuilderAcceptRoleOnce (0.03s) === RUN TestBuilderStopsAcceptingOrProducingDataOnceDone ---- PASS: TestBuilderStopsAcceptingOrProducingDataOnceDone (0.04s) +--- PASS: TestBuilderStopsAcceptingOrProducingDataOnceDone (0.03s) === RUN TestGenerateSnapshotInvalidOperations ---- PASS: TestGenerateSnapshotInvalidOperations (0.35s) +--- PASS: TestGenerateSnapshotInvalidOperations (0.29s) === RUN TestGenerateTimestampInvalidOperations ---- PASS: TestGenerateTimestampInvalidOperations (0.25s) +--- PASS: TestGenerateTimestampInvalidOperations (0.27s) === RUN TestGetConsistentInfo --- PASS: TestGetConsistentInfo (0.07s) === RUN TestTimestampPreAndPostChecksumming ---- PASS: TestTimestampPreAndPostChecksumming (0.12s) +--- PASS: TestTimestampPreAndPostChecksumming (0.19s) === RUN TestSnapshotLoadedFirstChecksumsOthers ---- PASS: TestSnapshotLoadedFirstChecksumsOthers (0.10s) +--- PASS: TestSnapshotLoadedFirstChecksumsOthers (0.13s) === RUN TestSnapshotLoadedAfterChecksumsOthersRetroactively ---- PASS: TestSnapshotLoadedAfterChecksumsOthersRetroactively (0.23s) +--- PASS: TestSnapshotLoadedAfterChecksumsOthersRetroactively (0.25s) PASS -ok github.com/theupdateframework/notary/tuf 2.607s +ok github.com/theupdateframework/notary/tuf 3.124s === RUN TestMergeStrSlicesExclusive --- PASS: TestMergeStrSlicesExclusive (0.00s) === RUN TestMergeStrSlicesOverlap @@ -3448,7 +3487,7 @@ === RUN TestRootToSignedMarshallingErrorsPropagated --- PASS: TestRootToSignedMarshallingErrorsPropagated (0.00s) === RUN TestRootMarshalJSONMarshalsSignedWithRegularJSON ---- PASS: TestRootMarshalJSONMarshalsSignedWithRegularJSON (0.03s) +--- PASS: TestRootMarshalJSONMarshalsSignedWithRegularJSON (0.00s) === RUN TestRootMarshalJSONMarshallingErrorsPropagated --- PASS: TestRootMarshalJSONMarshallingErrorsPropagated (0.00s) === RUN TestRootFromSignedUnmarshallingErrorsPropagated @@ -3456,9 +3495,9 @@ === RUN TestRootFromSignedCopiesSignatures --- PASS: TestRootFromSignedCopiesSignatures (0.00s) === RUN TestRootFromSignedValidatesRoleData ---- PASS: TestRootFromSignedValidatesRoleData (0.02s) +--- PASS: TestRootFromSignedValidatesRoleData (0.01s) === RUN TestRootFromSignedValidatesRoleType ---- PASS: TestRootFromSignedValidatesRoleType (0.00s) +--- PASS: TestRootFromSignedValidatesRoleType (0.01s) === RUN TestRootFromSignedValidatesVersion --- PASS: TestRootFromSignedValidatesVersion (0.00s) === RUN TestSnapshotToSignedMarshalsSignedPortionWithCanonicalJSON @@ -3476,7 +3515,7 @@ === RUN TestSnapshotFromSignedCopiesSignatures --- PASS: TestSnapshotFromSignedCopiesSignatures (0.00s) === RUN TestSnapshotFromSignedValidatesMeta ---- PASS: TestSnapshotFromSignedValidatesMeta (0.01s) +--- PASS: TestSnapshotFromSignedValidatesMeta (0.00s) === RUN TestSnapshotFromSignedValidatesRoleType --- PASS: TestSnapshotFromSignedValidatesRoleType (0.00s) === RUN TestSnapshotFromSignedValidatesVersion @@ -3498,9 +3537,9 @@ === RUN TestTargetsFromSignedCopiesSignatures --- PASS: TestTargetsFromSignedCopiesSignatures (0.00s) === RUN TestTargetsFromSignedValidatesDelegations ---- PASS: TestTargetsFromSignedValidatesDelegations (0.01s) +--- PASS: TestTargetsFromSignedValidatesDelegations (0.00s) === RUN TestTargetsFromSignedValidatesRoleType ---- PASS: TestTargetsFromSignedValidatesRoleType (0.01s) +--- PASS: TestTargetsFromSignedValidatesRoleType (0.00s) === RUN TestTargetsFromSignedValidatesRoleName --- PASS: TestTargetsFromSignedValidatesRoleName (0.00s) === RUN TestTargetsFromSignedValidatesVersion @@ -3542,9 +3581,9 @@ === RUN TestFileMetaEquals --- PASS: TestFileMetaEquals (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/data 0.153s +ok github.com/theupdateframework/notary/tuf/data 0.067s === RUN TestListKeys ---- PASS: TestListKeys (0.03s) +--- PASS: TestListKeys (0.06s) === RUN TestGetKeys --- PASS: TestGetKeys (0.00s) === RUN TestBasicSign @@ -3564,23 +3603,23 @@ === RUN TestSignMinSignatures --- PASS: TestSignMinSignatures (0.00s) === RUN TestSignFailingKeys ---- PASS: TestSignFailingKeys (0.02s) +--- PASS: TestSignFailingKeys (0.01s) === RUN TestErrInsufficientSignaturesMessaging --- PASS: TestErrInsufficientSignaturesMessaging (0.00s) === RUN TestRSAPSSVerifier ---- PASS: TestRSAPSSVerifier (0.03s) +--- PASS: TestRSAPSSVerifier (0.02s) === RUN TestRSAPSSx509Verifier --- PASS: TestRSAPSSx509Verifier (0.01s) === RUN TestRSAPSSVerifierWithInvalidKeyType --- PASS: TestRSAPSSVerifierWithInvalidKeyType (0.00s) === RUN TestRSAPSSVerifierWithInvalidKeyLength ---- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.12s) +--- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.27s) === RUN TestRSAPSSVerifierWithInvalidKey --- PASS: TestRSAPSSVerifierWithInvalidKey (0.00s) === RUN TestRSAPSSVerifierWithInvalidSignature ---- PASS: TestRSAPSSVerifierWithInvalidSignature (0.03s) +--- PASS: TestRSAPSSVerifierWithInvalidSignature (0.02s) === RUN TestRSAPKCS1v15Verifier ---- PASS: TestRSAPKCS1v15Verifier (0.03s) +--- PASS: TestRSAPKCS1v15Verifier (0.02s) === RUN TestRSAPKCS1v15x509Verifier --- PASS: TestRSAPKCS1v15x509Verifier (0.01s) === RUN TestRSAPKCS1v15VerifierWithInvalidKeyType @@ -3588,12 +3627,12 @@ === RUN TestRSAPKCS1v15VerifierWithInvalidKey --- PASS: TestRSAPKCS1v15VerifierWithInvalidKey (0.00s) === RUN TestRSAPKCS1v15VerifierWithInvalidSignature -time="2024-04-12T18:35:07Z" level=error msg="Failed verification: crypto/rsa: verification error" ---- PASS: TestRSAPKCS1v15VerifierWithInvalidSignature (0.03s) +time="2024-04-12T18:45:11Z" level=error msg="Failed verification: crypto/rsa: verification error" +--- PASS: TestRSAPKCS1v15VerifierWithInvalidSignature (0.02s) === RUN TestECDSAVerifier --- PASS: TestECDSAVerifier (0.01s) === RUN TestECDSAVerifierOtherCurves ---- PASS: TestECDSAVerifierOtherCurves (0.67s) +--- PASS: TestECDSAVerifierOtherCurves (0.91s) === RUN TestECDSAx509Verifier --- PASS: TestECDSAx509Verifier (0.01s) === RUN TestECDSAVerifierWithInvalidKeyType @@ -3607,7 +3646,7 @@ === RUN TestRSAPyCryptoVerifierInvalidKeyType --- PASS: TestRSAPyCryptoVerifierInvalidKeyType (0.00s) === RUN TestPyCryptoRSAPSSCompat ---- PASS: TestPyCryptoRSAPSSCompat (0.00s) +--- PASS: TestPyCryptoRSAPSSCompat (0.01s) === RUN TestPyNaCled25519Compat --- PASS: TestPyNaCled25519Compat (0.00s) === RUN TestRoleNoKeys @@ -3631,26 +3670,24 @@ === RUN TestVerifyVersion --- PASS: TestVerifyVersion (0.00s) === RUN TestVerifyExpiry -time="2024-04-12T18:35:08Z" level=error msg="Metadata for root expired" +time="2024-04-12T18:45:12Z" 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 1.089s -? github.com/theupdateframework/notary/tuf/testutils/interfaces [no test files] -? github.com/theupdateframework/notary/tuf/testutils/keys [no test files] +ok github.com/theupdateframework/notary/tuf/signed 1.537s === RUN TestNewSwizzler ---- PASS: TestNewSwizzler (0.09s) +--- PASS: TestNewSwizzler (0.15s) === RUN TestSwizzlerSetInvalidJSON --- PASS: TestSwizzlerSetInvalidJSON (0.07s) === RUN TestSwizzlerAddExtraSpace ---- PASS: TestSwizzlerAddExtraSpace (0.07s) +--- PASS: TestSwizzlerAddExtraSpace (0.09s) === RUN TestSwizzlerSetInvalidSigned --- PASS: TestSwizzlerSetInvalidSigned (0.07s) === RUN TestSwizzlerSetInvalidSignedMeta ---- PASS: TestSwizzlerSetInvalidSignedMeta (0.08s) +--- PASS: TestSwizzlerSetInvalidSignedMeta (0.07s) === RUN TestSwizzlerSetInvalidMetadataType --- PASS: TestSwizzlerSetInvalidMetadataType (0.08s) === RUN TestSwizzlerInvalidateMetadataSignatures @@ -3658,43 +3695,43 @@ === RUN TestSwizzlerRemoveMetadata --- PASS: TestSwizzlerRemoveMetadata (0.07s) === RUN TestSwizzlerSignMetadataWithInvalidKey ---- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.08s) +--- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.09s) === RUN TestSwizzlerOffsetMetadataVersion ---- PASS: TestSwizzlerOffsetMetadataVersion (0.08s) +--- PASS: TestSwizzlerOffsetMetadataVersion (0.07s) === RUN TestSwizzlerExpireMetadata --- PASS: TestSwizzlerExpireMetadata (0.08s) === RUN TestSwizzlerSetThresholdBaseRole ---- PASS: TestSwizzlerSetThresholdBaseRole (0.08s) +--- PASS: TestSwizzlerSetThresholdBaseRole (0.07s) === RUN TestSwizzlerSetThresholdDelegatedRole --- PASS: TestSwizzlerSetThresholdDelegatedRole (0.08s) === RUN TestSwizzlerChangeRootKey ---- PASS: TestSwizzlerChangeRootKey (0.09s) +--- PASS: TestSwizzlerChangeRootKey (0.11s) === RUN TestSwizzlerUpdateSnapshotHashesSpecifiedRoles ---- PASS: TestSwizzlerUpdateSnapshotHashesSpecifiedRoles (0.09s) +--- PASS: TestSwizzlerUpdateSnapshotHashesSpecifiedRoles (0.11s) === RUN TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles ---- PASS: TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles (0.09s) +--- PASS: TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles (0.07s) === RUN TestSwizzlerUpdateTimestamp ---- PASS: TestSwizzlerUpdateTimestamp (0.08s) +--- PASS: TestSwizzlerUpdateTimestamp (0.10s) === RUN TestMissingSigningKey --- PASS: TestMissingSigningKey (0.08s) === RUN TestSwizzlerMutateRoot ---- PASS: TestSwizzlerMutateRoot (0.08s) +--- PASS: TestSwizzlerMutateRoot (0.11s) === RUN TestSwizzlerMutateTimestamp ---- PASS: TestSwizzlerMutateTimestamp (0.08s) +--- PASS: TestSwizzlerMutateTimestamp (0.14s) === RUN TestSwizzlerMutateSnapshot ---- PASS: TestSwizzlerMutateSnapshot (0.08s) +--- PASS: TestSwizzlerMutateSnapshot (0.15s) === RUN TestSwizzlerMutateTargets ---- PASS: TestSwizzlerMutateTargets (0.08s) +--- PASS: TestSwizzlerMutateTargets (0.11s) === RUN TestSwizzlerRotateKeyBaseRole ---- PASS: TestSwizzlerRotateKeyBaseRole (0.10s) +--- PASS: TestSwizzlerRotateKeyBaseRole (0.12s) === RUN TestSwizzlerRotateKeyDelegationRole ---- PASS: TestSwizzlerRotateKeyDelegationRole (0.07s) +--- PASS: TestSwizzlerRotateKeyDelegationRole (0.09s) PASS -ok github.com/theupdateframework/notary/tuf/testutils 1.931s +ok github.com/theupdateframework/notary/tuf/testutils 2.274s === RUN TestConvertTUFKeyToPKCS8 ---- PASS: TestConvertTUFKeyToPKCS8 (0.10s) +--- PASS: TestConvertTUFKeyToPKCS8 (0.18s) === RUN TestParsePKCS8ToTufKey ---- PASS: TestParsePKCS8ToTufKey (0.71s) +--- PASS: TestParsePKCS8ToTufKey (1.01s) === RUN TestPEMtoPEM --- PASS: TestPEMtoPEM (0.00s) === RUN TestRoleListLen @@ -3736,7 +3773,7 @@ === RUN TestRoleNameSliceRemove --- PASS: TestRoleNameSliceRemove (0.00s) === RUN TestCertsToKeys ---- PASS: TestCertsToKeys (0.07s) +--- PASS: TestCertsToKeys (0.00s) === RUN TestNewCertificate --- PASS: TestNewCertificate (0.00s) === RUN TestKeyOperations @@ -3752,13 +3789,13 @@ === RUN TestValidateCertificateWithSHA1 --- PASS: TestValidateCertificateWithSHA1 (0.01s) === RUN TestValidateCertificateWithExpiredCert ---- PASS: TestValidateCertificateWithExpiredCert (0.04s) +--- PASS: TestValidateCertificateWithExpiredCert (0.01s) === RUN TestValidateCertificateWithInvalidExpiry ---- PASS: TestValidateCertificateWithInvalidExpiry (0.06s) +--- PASS: TestValidateCertificateWithInvalidExpiry (0.01s) === RUN TestValidateCertificateWithShortKey ---- PASS: TestValidateCertificateWithShortKey (0.62s) +--- PASS: TestValidateCertificateWithShortKey (0.10s) PASS -ok github.com/theupdateframework/notary/tuf/utils 1.719s +ok github.com/theupdateframework/notary/tuf/utils 1.438s === RUN TestNewSerializableErrorNonValidationError --- PASS: TestNewSerializableErrorNonValidationError (0.00s) === RUN TestNewSerializableErrorValidationError @@ -3774,10 +3811,9 @@ === RUN TestUnmarshalInvalidJSON --- PASS: TestUnmarshalInvalidJSON (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/validation 0.018s -? github.com/theupdateframework/notary/version [no test files] +ok github.com/theupdateframework/notary/tuf/validation 0.022s === RUN TestSetSignalTrap ---- PASS: TestSetSignalTrap (0.00s) +--- PASS: TestSetSignalTrap (0.01s) === RUN TestLogLevelSignalHandle Attempt to increase log level failed, will remain at debug level, error: log level can not be set higher than Debug Successfully setting log level to debug @@ -3875,7 +3911,7 @@ === RUN TestDoAuthWildcardImage --- PASS: TestDoAuthWildcardImage (0.00s) PASS -ok github.com/theupdateframework/notary/utils 0.037s +ok github.com/theupdateframework/notary/utils 0.051s 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 @@ -3903,9 +3939,9 @@ dh_fixperms -O--builddirectory=/build/reproducible-path/notary-0.7.0\+ds1/_build -O--buildsystem=golang dh_missing -O--builddirectory=/build/reproducible-path/notary-0.7.0\+ds1/_build -O--buildsystem=golang dh_strip -a -O--builddirectory=/build/reproducible-path/notary-0.7.0\+ds1/_build -O--buildsystem=golang -dh_strip: warning: Could not find the BuildID in debian/notary/usr/bin/notary-signer dh_strip: warning: Could not find the BuildID in debian/notary/usr/bin/escrow dh_strip: warning: Could not find the BuildID in debian/notary/usr/bin/notary-server +dh_strip: warning: Could not find the BuildID in debian/notary/usr/bin/notary-signer dh_makeshlibs -a -O--builddirectory=/build/reproducible-path/notary-0.7.0\+ds1/_build -O--buildsystem=golang dh_shlibdeps -a -O--builddirectory=/build/reproducible-path/notary-0.7.0\+ds1/_build -O--buildsystem=golang dh_installdeb -O--builddirectory=/build/reproducible-path/notary-0.7.0\+ds1/_build -O--buildsystem=golang @@ -3916,8 +3952,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_armhf.deb'. -dpkg-deb: building package 'notary-dbgsym' in '../notary-dbgsym_0.7.0+ds1-2_armhf.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_armhf.deb'. dpkg-genbuildinfo --build=binary -O../notary_0.7.0+ds1-2_armhf.buildinfo dpkg-genchanges --build=binary -O../notary_0.7.0+ds1-2_armhf.changes dpkg-genchanges: info: binary-only upload (no source code included) @@ -3925,12 +3961,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/32491/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/32491/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/14826 and its subdirectories -I: Current time: Fri Apr 12 06:36:12 -12 2024 -I: pbuilder-time-stamp: 1712946972 +I: removing directory /srv/workspace/pbuilder/32491 and its subdirectories +I: Current time: Sat Apr 13 08:46:45 +14 2024 +I: pbuilder-time-stamp: 1712947605