Diff of the two buildlogs: -- --- b1/build.log 2024-04-05 20:00:09.207812224 +0000 +++ b2/build.log 2024-04-05 20:09:07.936895117 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Fri Apr 5 07:45:11 -12 2024 -I: pbuilder-time-stamp: 1712346311 +I: Current time: Sat Apr 6 10:00:51 +14 2024 +I: pbuilder-time-stamp: 1712347251 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-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/7412/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/9903/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 5 20:01 /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/9903/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/9903/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='armhf' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=3 ' - DISTRIBUTION='unstable' - HOME='/root' - HOST_ARCH='armhf' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="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=unstable + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=arm + HOST_ARCH=armhf IFS=' ' - INVOCATION_ID='27763b30bb5747bfb31df59fb1d5f74e' - 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='7412' - PS1='# ' - PS2='> ' + INVOCATION_ID=ee725003c4a04976b57304ba534b8717 + 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=9903 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.ddmOMxW3/pbuilderrc_aaxc --distribution unstable --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.ddmOMxW3/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.ddmOMxW3/pbuilderrc_WFzR --distribution unstable --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/unstable-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.ddmOMxW3/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 4 09:45 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/7412/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Apr 2 07:43 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/9903/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 unstable/main armhf golang-goprotobuf-dev armhf 1.3.5-4+b6 [3428 B] Get: 188 http://deb.debian.org/debian unstable/main armhf golang-gopkg-rethinkdb-rethinkdb-go.v6-dev all 6.2.1-2 [106 kB] Get: 189 http://deb.debian.org/debian unstable/main armhf openssl armhf 3.2.1-3 [1326 kB] -Fetched 104 MB in 2s (47.0 MB/s) +Fetched 104 MB in 2s (55.0 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 ... 19466 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/9903/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/9903/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/godebugs internal/abi internal/chacha8rand -internal/bytealg internal/goexperiment +internal/bytealg internal/goos runtime/internal/atomic runtime/internal/math @@ -1423,16 +1459,16 @@ internal/race sync/atomic math/bits -math unicode/utf8 -runtime internal/itoa -unicode +math cmp +unicode slices encoding unicode/utf16 log/internal +runtime crypto/internal/alias crypto/internal/boring/sig vendor/golang.org/x/crypto/cryptobyte/asn1 @@ -1452,118 +1488,117 @@ internal/testlog internal/bisect internal/singleflight +github.com/spf13/viper/internal/encoding runtime/cgo +google.golang.org/grpc/internal/buffer +google.golang.org/grpc/internal/grpcsync internal/godebug errors -io sort -hash +io strconv -internal/oserror -syscall +hash crypto +internal/safefilepath +internal/oserror reflect +path +syscall +bytes +strings +bufio +crypto/internal/randutil +math/rand internal/syscall/unix time +internal/syscall/execenv +crypto/internal/nistec/fiat +internal/poll encoding/binary crypto/md5 -internal/poll -internal/safefilepath -internal/syscall/execenv -path -bytes io/fs -os encoding/base64 internal/fmtsort -strings -github.com/theupdateframework/notary -fmt -bufio +os context golang.org/x/sys/unix -encoding/json -github.com/docker/go/canonical/json -log crypto/cipher crypto/internal/boring -crypto/internal/randutil -math/rand -math/big crypto/aes -github.com/sirupsen/logrus -crypto/rand -github.com/docker/distribution/uuid crypto/internal/edwards25519/field -crypto/internal/nistec/fiat -crypto/internal/bigmod -crypto/internal/boring/bbig crypto/sha512 -encoding/asn1 -vendor/golang.org/x/crypto/cryptobyte -crypto/rsa +github.com/theupdateframework/notary +fmt crypto/sha256 crypto/des -crypto/dsa +crypto/internal/nistec crypto/internal/edwards25519 +encoding/json +github.com/docker/go/canonical/json +log +math/big +crypto/ecdh crypto/sha1 -crypto/internal/nistec -crypto/ed25519 encoding/hex -crypto/x509/pkix +github.com/sirupsen/logrus encoding/pem vendor/golang.org/x/net/dns/dnsmessage internal/intern net/netip -crypto/ecdh -crypto/elliptic net/url -net -crypto/ecdsa path/filepath -golang.org/x/crypto/ed25519 +net io/ioutil regexp/syntax +crypto/rand +crypto/elliptic +github.com/docker/distribution/uuid +crypto/internal/bigmod +crypto/internal/boring/bbig +encoding/asn1 +crypto/rsa +crypto/dsa +crypto/ed25519 +regexp +vendor/golang.org/x/crypto/cryptobyte +crypto/x509/pkix +golang.org/x/crypto/ed25519 crypto/hmac golang.org/x/crypto/pbkdf2 github.com/theupdateframework/notary/tuf/validation mime +crypto/ecdsa mime/quotedprintable compress/flate -regexp hash/crc32 -compress/gzip crypto/rc4 vendor/golang.org/x/crypto/chacha20 vendor/golang.org/x/crypto/internal/poly1305 vendor/golang.org/x/crypto/hkdf -vendor/golang.org/x/crypto/chacha20poly1305 vendor/golang.org/x/text/transform +vendor/golang.org/x/crypto/chacha20poly1305 +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 -vendor/golang.org/x/net/idna net/http/internal net/http/internal/ascii github.com/miekg/pkcs11 +vendor/golang.org/x/text/secure/bidirule flag encoding/csv +vendor/golang.org/x/net/idna github.com/fsnotify/fsnotify github.com/spf13/afero/internal/common github.com/spf13/afero/mem golang.org/x/text/transform +html golang.org/x/text/runes golang.org/x/text/unicode/norm -html text/template/parse -text/template -html/template -github.com/spf13/cast github.com/spf13/jwalterweatherman -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 @@ -1572,22 +1607,37 @@ github.com/hashicorp/hcl/json/token github.com/hashicorp/hcl/json/scanner github.com/hashicorp/hcl/json/parser +html/template github.com/hashicorp/hcl +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/spf13/cast +github.com/spf13/pflag +github.com/spf13/viper/internal/encoding/dotenv text/tabwriter +github.com/theupdateframework/notary/tuf/data +crypto/tls +github.com/theupdateframework/notary/client/changelist +github.com/theupdateframework/notary/tuf/utils github.com/hashicorp/hcl/hcl/printer -github.com/spf13/viper/internal/encoding/hcl gopkg.in/ini.v1 -github.com/spf13/viper/internal/encoding/ini +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 github.com/pelletier/go-toml/v2/internal/tracker +github.com/spf13/viper/internal/encoding/ini github.com/pelletier/go-toml/v2 -github.com/spf13/viper/internal/encoding/toml gopkg.in/yaml.v3 -github.com/spf13/viper/internal/encoding/yaml +net/http/httptrace +github.com/spf13/viper/internal/encoding/toml github.com/golang/protobuf/proto -github.com/golang/protobuf/ptypes/empty +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 @@ -1596,140 +1646,170 @@ 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 google.golang.org/grpc/internal/grpcrand google.golang.org/grpc/codes google.golang.org/grpc/encoding -google.golang.org/grpc/encoding/proto google.golang.org/grpc/internal/backoff google.golang.org/grpc/internal/balancerload +google.golang.org/grpc/internal/envconfig +golang.org/x/text/unicode/bidi +github.com/golang/protobuf/ptypes/empty +google.golang.org/grpc/credentials +google.golang.org/grpc/resolver +google.golang.org/grpc/balancer +google.golang.org/grpc/balancer/base +google.golang.org/grpc/encoding/proto +google.golang.org/grpc/balancer/roundrobin github.com/golang/protobuf/ptypes/any github.com/golang/protobuf/ptypes/duration github.com/golang/protobuf/ptypes/timestamp +google.golang.org/grpc/internal/grpcutil github.com/golang/protobuf/ptypes -google.golang.org/grpc/binarylog/grpc_binarylog_v1 google.golang.org/genproto/googleapis/rpc/status +google.golang.org/grpc/binarylog/grpc_binarylog_v1 +google.golang.org/grpc/internal/channelz google.golang.org/grpc/internal/status google.golang.org/grpc/status -google.golang.org/grpc/internal/buffer -google.golang.org/grpc/internal/envconfig -google.golang.org/grpc/internal/grpcsync -golang.org/x/text/unicode/bidi +google.golang.org/grpc/balancer/grpclb/state +google.golang.org/grpc/internal/binarylog +google.golang.org/grpc/internal/resolver/dns +google.golang.org/grpc/internal/resolver/passthrough +google.golang.org/grpc/internal/serviceconfig golang.org/x/text/secure/bidirule -golang.org/x/net/idna golang.org/x/net/http2/hpack +google.golang.org/grpc/internal/syscall +golang.org/x/net/idna google.golang.org/grpc/keepalive +google.golang.org/grpc/peer +google.golang.org/grpc/stats google.golang.org/grpc/tap github.com/bugsnag/bugsnag-go/device github.com/pkg/errors -github.com/bugsnag/bugsnag-go/errors github.com/bugsnag/bugsnag-go/headers os/exec +github.com/bugsnag/bugsnag-go/errors os/signal -github.com/bugsnag/panicwrap +golang.org/x/net/http/httpguts database/sql/driver -database/sql +github.com/bugsnag/panicwrap +github.com/docker/go-connections/tlsconfig github.com/opencontainers/go-digest +github.com/google/uuid +database/sql github.com/docker/distribution/digestset github.com/docker/distribution/reference github.com/opencontainers/image-spec/specs-go github.com/opencontainers/image-spec/specs-go/v1 github.com/beorn7/perks/quantile github.com/cespare/xxhash +github.com/theupdateframework/notary/storage +github.com/spf13/afero +github.com/magiconair/properties +golang.org/x/net/trace +github.com/theupdateframework/notary/trustmanager +github.com/spf13/viper/internal/encoding/javaproperties +github.com/theupdateframework/notary/cryptoservice +github.com/theupdateframework/notary/tuf/signed +golang.org/x/net/http2 +net/http/httputil +github.com/spf13/viper +github.com/theupdateframework/notary/trustmanager/yubikey +github.com/theupdateframework/notary/trustpinning +github.com/bugsnag/bugsnag-go/sessions +github.com/theupdateframework/notary/tuf +github.com/gorilla/mux +github.com/bugsnag/bugsnag-go +github.com/docker/distribution/context +github.com/theupdateframework/notary/client +github.com/Shopify/logrus-bugsnag +github.com/docker/distribution/registry/api/errcode +github.com/docker/distribution/registry/auth +github.com/docker/distribution +github.com/go-sql-driver/mysql +github.com/docker/distribution/registry/api/v2 +github.com/docker/distribution/registry/client/auth/challenge +github.com/docker/distribution/registry/client/transport +expvar hash/fnv google.golang.org/protobuf/internal/detrand google.golang.org/protobuf/internal/errors -google.golang.org/protobuf/encoding/protowire +github.com/theupdateframework/notary/utils google.golang.org/protobuf/internal/pragma -google.golang.org/protobuf/reflect/protoreflect -google.golang.org/protobuf/internal/descfmt -google.golang.org/protobuf/internal/descopts embed +google.golang.org/grpc/internal/transport +google.golang.org/protobuf/encoding/protowire google.golang.org/protobuf/internal/editiondefaults go/token +google.golang.org/protobuf/reflect/protoreflect +google.golang.org/protobuf/internal/version +github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg +github.com/prometheus/procfs/internal/util +github.com/prometheus/procfs/internal/fs +runtime/debug +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/grpc google.golang.org/protobuf/internal/encoding/text -google.golang.org/protobuf/internal/encoding/defval google.golang.org/protobuf/internal/encoding/messageset google.golang.org/protobuf/internal/genid +google.golang.org/protobuf/internal/encoding/defval google.golang.org/protobuf/internal/order google.golang.org/protobuf/reflect/protoregistry google.golang.org/protobuf/runtime/protoiface -google.golang.org/protobuf/proto -google.golang.org/protobuf/internal/filedesc -google.golang.org/protobuf/encoding/prototext -google.golang.org/protobuf/internal/encoding/tag -google.golang.org/protobuf/internal/impl -google.golang.org/protobuf/internal/filetype -google.golang.org/protobuf/internal/version -google.golang.org/protobuf/runtime/protoimpl -google.golang.org/protobuf/types/known/timestamppb -github.com/prometheus/client_model/go -github.com/prometheus/common/model -github.com/prometheus/client_golang/prometheus/internal -github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg -google.golang.org/protobuf/encoding/protodelim -github.com/prometheus/procfs/internal/fs -github.com/prometheus/procfs/internal/util -runtime/debug golang.org/x/term +google.golang.org/protobuf/proto github.com/theupdateframework/notary/passphrase +github.com/docker/distribution/health golang.org/x/crypto/blowfish -golang.org/x/crypto/bcrypt encoding/base32 +golang.org/x/crypto/bcrypt +google.golang.org/protobuf/internal/filedesc +google.golang.org/protobuf/encoding/prototext +github.com/theupdateframework/notary/trustmanager/remoteks +google.golang.org/protobuf/encoding/protodelim +github.com/docker/distribution/registry/auth/htpasswd +github.com/theupdateframework/notary/cmd/escrow +github.com/docker/libtrust github.com/lib/pq/scram os/user +github.com/docker/distribution/registry/auth/token +google.golang.org/protobuf/internal/encoding/tag +google.golang.org/protobuf/internal/impl +github.com/theupdateframework/notary/server/errors github.com/jinzhu/inflection go/scanner go/ast -github.com/hailocab/go-hostpool -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/spf13/pflag -github.com/theupdateframework/notary/tuf/data -crypto/tls -github.com/theupdateframework/notary/client/changelist -github.com/theupdateframework/notary/tuf/utils -golang.org/x/net/http/httpguts -google.golang.org/grpc/internal/syscall -google.golang.org/grpc/stats -github.com/google/uuid -github.com/prometheus/procfs -github.com/spf13/cobra github.com/jinzhu/gorm -net/http/httptrace -net/http -google.golang.org/grpc/internal/credentials -google.golang.org/grpc/credentials -google.golang.org/grpc/resolver -google.golang.org/grpc/balancer -google.golang.org/grpc/balancer/base -google.golang.org/grpc/balancer/roundrobin -google.golang.org/grpc/internal/grpcutil -google.golang.org/grpc/internal/binarylog -google.golang.org/grpc/internal/channelz -google.golang.org/grpc/balancer/grpclb/state -google.golang.org/grpc/internal/resolver/passthrough -google.golang.org/grpc/internal/resolver/dns -google.golang.org/grpc/internal/serviceconfig -google.golang.org/grpc/peer -github.com/docker/go-connections/tlsconfig -github.com/go-sql-driver/mysql -github.com/docker/libtrust github.com/lib/pq +github.com/hailocab/go-hostpool github.com/opentracing/opentracing-go/log +github.com/opentracing/opentracing-go +github.com/opentracing/opentracing-go/ext gopkg.in/rethinkdb/rethinkdb-go.v6/vendor/gopkg.in/cenkalti/backoff.v2 gopkg.in/rethinkdb/rethinkdb-go.v6/encoding gopkg.in/rethinkdb/rethinkdb-go.v6/ql2 gopkg.in/rethinkdb/rethinkdb-go.v6/types +github.com/theupdateframework/notary/proto +google.golang.org/grpc/health/grpc_health_v1 +gopkg.in/rethinkdb/rethinkdb-go.v6 internal/lazyregexp -runtime/pprof +github.com/theupdateframework/notary/signer/client internal/profile +google.golang.org/protobuf/internal/filetype +google.golang.org/protobuf/runtime/protoimpl +runtime/pprof +google.golang.org/protobuf/types/known/timestamppb +github.com/prometheus/client_model/go runtime/trace +github.com/theupdateframework/notary/signer +net/http/pprof +github.com/theupdateframework/notary/signer/api +github.com/prometheus/common/model github.com/dvsekhvalnov/jose2go/base64url github.com/dvsekhvalnov/jose2go/arrays github.com/dvsekhvalnov/jose2go/compact @@ -1737,100 +1817,65 @@ github.com/dvsekhvalnov/jose2go/kdf github.com/dvsekhvalnov/jose2go/keys/ecc github.com/dvsekhvalnov/jose2go/padding -github.com/davecgh/go-spew/spew +google.golang.org/grpc/health github.com/dvsekhvalnov/jose2go -github.com/pmezard/go-difflib/difflib -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 -github.com/theupdateframework/notary/trustmanager/yubikey -github.com/theupdateframework/notary/trustpinning -github.com/theupdateframework/notary/tuf -github.com/spf13/viper -golang.org/x/net/http2 -github.com/theupdateframework/notary/client -net/http/httputil -github.com/bugsnag/bugsnag-go/sessions -github.com/gorilla/mux -github.com/bugsnag/bugsnag-go -github.com/docker/distribution/context -github.com/Shopify/logrus-bugsnag -github.com/docker/distribution/registry/api/errcode -github.com/docker/distribution/registry/auth -github.com/docker/distribution -github.com/theupdateframework/notary/utils -github.com/docker/distribution/registry/api/v2 -github.com/docker/distribution/registry/client/auth/challenge -github.com/docker/distribution/registry/client/transport -expvar +github.com/theupdateframework/notary/tuf/testutils/keys +github.com/theupdateframework/notary/tuf/testutils +github.com/prometheus/client_golang/prometheus/internal github.com/prometheus/common/expfmt -google.golang.org/grpc/internal/transport -github.com/docker/distribution/health -github.com/docker/distribution/registry/auth/htpasswd -github.com/docker/distribution/registry/auth/token -github.com/prometheus/client_golang/prometheus -google.golang.org/grpc -github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus -github.com/theupdateframework/notary/server/errors -github.com/opentracing/opentracing-go -github.com/prometheus/client_golang/prometheus/promhttp -github.com/opentracing/opentracing-go/ext -gopkg.in/rethinkdb/rethinkdb-go.v6 -github.com/theupdateframework/notary/trustmanager/remoteks -github.com/theupdateframework/notary/cmd/escrow -github.com/docker/go-metrics -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/storage/rethinkdb -github.com/theupdateframework/notary/cmd/notary +github.com/davecgh/go-spew/spew github.com/theupdateframework/notary/server/storage -github.com/theupdateframework/notary/proto +github.com/theupdateframework/notary/signer/keydbstore +github.com/pmezard/go-difflib/difflib +github.com/prometheus/client_golang/prometheus +github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus +github.com/theupdateframework/notary/cmd/notary-signer github.com/theupdateframework/notary/server/snapshot github.com/theupdateframework/notary/server/timestamp github.com/theupdateframework/notary/server/handlers -google.golang.org/grpc/health/grpc_health_v1 -github.com/theupdateframework/notary/server -github.com/theupdateframework/notary/signer/client -net/http/pprof -github.com/theupdateframework/notary/signer -github.com/theupdateframework/notary/signer/api -github.com/theupdateframework/notary/signer/keydbstore -github.com/theupdateframework/notary/cmd/notary-server -google.golang.org/grpc/health -github.com/theupdateframework/notary/cmd/notary-signer -github.com/theupdateframework/notary/tuf/testutils/keys -github.com/theupdateframework/notary/tuf/testutils 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.26s) +--- PASS: Test0Dot1Migration (0.10s) === RUN Test0Dot3Migration ---- PASS: Test0Dot3Migration (0.18s) +--- PASS: Test0Dot3Migration (0.08s) === RUN Test0Dot1RepoFormat -time="2024-04-05T19:52:53Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot1RepoFormat (1.00s) +time="2024-04-05T20:05:28Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot1RepoFormat (0.96s) === RUN Test0Dot3RepoFormat -time="2024-04-05T19:52:54Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot3RepoFormat (1.12s) +time="2024-04-05T20:05:29Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot3RepoFormat (0.95s) === RUN TestDownloading0Dot1RepoFormat ---- PASS: TestDownloading0Dot1RepoFormat (0.07s) +--- PASS: TestDownloading0Dot1RepoFormat (0.03s) === RUN TestDownloading0Dot3RepoFormat --- PASS: TestDownloading0Dot3RepoFormat (0.05s) === RUN TestInitRepositoryManagedRolesIncludingRoot @@ -1840,27 +1885,27 @@ === RUN TestInitRepositoryManagedRolesIncludingTargets --- PASS: TestInitRepositoryManagedRolesIncludingTargets (0.01s) === RUN TestInitRepositoryManagedRolesIncludingTimestamp ---- PASS: TestInitRepositoryManagedRolesIncludingTimestamp (0.07s) +--- PASS: TestInitRepositoryManagedRolesIncludingTimestamp (0.09s) === RUN TestInitRepositoryWithCerts ---- PASS: TestInitRepositoryWithCerts (0.10s) +--- PASS: TestInitRepositoryWithCerts (0.09s) === RUN TestMatchKeyIDsWithPublicKeys ---- PASS: TestMatchKeyIDsWithPublicKeys (0.04s) +--- PASS: TestMatchKeyIDsWithPublicKeys (0.03s) === RUN TestInitRepositoryNeedsRemoteTimestampKey --- PASS: TestInitRepositoryNeedsRemoteTimestampKey (0.05s) === RUN TestInitRepositoryNeedsRemoteSnapshotKey --- PASS: TestInitRepositoryNeedsRemoteSnapshotKey (0.04s) === RUN TestInitRepoServerOnlyManagesTimestampKey ---- PASS: TestInitRepoServerOnlyManagesTimestampKey (0.49s) +--- PASS: TestInitRepoServerOnlyManagesTimestampKey (0.44s) === RUN TestInitRepoServerManagesTimestampAndSnapshotKeys ---- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.44s) +--- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.35s) === RUN TestInitRepoAttemptsExceeded ---- PASS: TestInitRepoAttemptsExceeded (0.22s) +--- PASS: TestInitRepoAttemptsExceeded (0.19s) === RUN TestInitRepoPasswordInvalid ---- PASS: TestInitRepoPasswordInvalid (0.04s) +--- PASS: TestInitRepoPasswordInvalid (0.03s) === RUN TestAddTargetToTargetRoleByDefault ---- PASS: TestAddTargetToTargetRoleByDefault (0.15s) +--- PASS: TestAddTargetToTargetRoleByDefault (0.19s) === RUN TestAddTargetToSpecifiedValidRoles ---- PASS: TestAddTargetToSpecifiedValidRoles (0.16s) +--- PASS: TestAddTargetToSpecifiedValidRoles (0.14s) === RUN TestAddTargetToSpecifiedInvalidRoles --- PASS: TestAddTargetToSpecifiedInvalidRoles (0.15s) === RUN TestAddTargetWithInvalidTarget @@ -1868,346 +1913,346 @@ === RUN TestAddTargetErrorWritingChanges --- PASS: TestAddTargetErrorWritingChanges (0.08s) === RUN TestRemoveTargetToTargetRoleByDefault ---- PASS: TestRemoveTargetToTargetRoleByDefault (0.16s) +--- PASS: TestRemoveTargetToTargetRoleByDefault (0.14s) === RUN TestRemoveTargetFromSpecifiedValidRoles ---- PASS: TestRemoveTargetFromSpecifiedValidRoles (0.17s) +--- PASS: TestRemoveTargetFromSpecifiedValidRoles (0.14s) === RUN TestRemoveTargetToSpecifiedInvalidRoles ---- PASS: TestRemoveTargetToSpecifiedInvalidRoles (0.16s) +--- PASS: TestRemoveTargetToSpecifiedInvalidRoles (0.18s) === RUN TestRemoveTargetErrorWritingChanges ---- PASS: TestRemoveTargetErrorWritingChanges (0.09s) +--- PASS: TestRemoveTargetErrorWritingChanges (0.07s) === RUN TestListTarget ---- PASS: TestListTarget (2.33s) +--- PASS: TestListTarget (1.80s) === RUN TestListTargetRestrictsDelegationPaths ---- PASS: TestListTargetRestrictsDelegationPaths (0.40s) +--- PASS: TestListTargetRestrictsDelegationPaths (0.45s) === RUN TestValidateRootKey ---- PASS: TestValidateRootKey (0.21s) +--- PASS: TestValidateRootKey (0.31s) === RUN TestGetChangelist ---- PASS: TestGetChangelist (0.22s) +--- PASS: TestGetChangelist (0.26s) === RUN TestPublishBareRepo ---- PASS: TestPublishBareRepo (2.12s) +--- PASS: TestPublishBareRepo (1.91s) === RUN TestPublishUninitializedRepo -time="2024-04-05T19:53:03Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" ---- PASS: TestPublishUninitializedRepo (0.16s) +time="2024-04-05T20:05:37Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +--- PASS: TestPublishUninitializedRepo (0.14s) === RUN TestPublishClientHasSnapshotKey ---- PASS: TestPublishClientHasSnapshotKey (2.36s) +--- PASS: TestPublishClientHasSnapshotKey (1.77s) === RUN TestPublishAfterInitServerHasSnapshotKey ---- PASS: TestPublishAfterInitServerHasSnapshotKey (1.61s) +--- PASS: TestPublishAfterInitServerHasSnapshotKey (1.47s) === RUN TestPublishAfterPullServerHasSnapshotKey ---- PASS: TestPublishAfterPullServerHasSnapshotKey (0.60s) +--- PASS: TestPublishAfterPullServerHasSnapshotKey (0.52s) === RUN TestPublishNoOneHasSnapshotKey ---- PASS: TestPublishNoOneHasSnapshotKey (0.34s) +--- PASS: TestPublishNoOneHasSnapshotKey (0.32s) === RUN TestPublishSnapshotCorrupt ---- PASS: TestPublishSnapshotCorrupt (0.94s) +--- PASS: TestPublishSnapshotCorrupt (1.17s) === RUN TestPublishTargetsCorrupt ---- PASS: TestPublishTargetsCorrupt (0.48s) +--- PASS: TestPublishTargetsCorrupt (0.52s) === RUN TestPublishRootCorrupt -time="2024-04-05T19:53:10Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" -time="2024-04-05T19:53:10Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" ---- PASS: TestPublishRootCorrupt (0.35s) +time="2024-04-05T20:05:43Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +time="2024-04-05T20:05:44Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +--- PASS: TestPublishRootCorrupt (0.33s) === RUN TestPublishSnapshotLocalKeysCreatedFirst --- PASS: TestPublishSnapshotLocalKeysCreatedFirst (0.02s) === RUN TestPublishDelegations ---- PASS: TestPublishDelegations (0.64s) +--- PASS: TestPublishDelegations (0.97s) === RUN TestPublishDelegationsX509 ---- PASS: TestPublishDelegationsX509 (0.69s) +--- PASS: TestPublishDelegationsX509 (1.37s) === RUN TestPublishTargetsDelegationScopeFailIfNoKeys -time="2024-04-05T19:53:12Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" -time="2024-04-05T19:53:12Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" ---- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (0.58s) +time="2024-04-05T20:05: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-05T20:05:47Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" +--- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (0.78s) === RUN TestPublishTargetsDelegationSuccessLocallyHasRoles ---- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (0.61s) +--- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (0.77s) === RUN TestPublishTargetsDelegationNoTargetsKeyNeeded ---- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (0.69s) +--- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (0.67s) === RUN TestPublishTargetsDelegationSuccessNeedsToDownloadRoles ---- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (0.67s) +--- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (1.07s) === RUN TestPublishTargetsDelegationFromTwoRepos ---- PASS: TestPublishTargetsDelegationFromTwoRepos (0.62s) +--- PASS: TestPublishTargetsDelegationFromTwoRepos (0.68s) === RUN TestPublishRemoveDelegationKeyFromDelegationRole -time="2024-04-05T19:53:15Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-05T19:53:15Z" 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: 74229bbd0605684615b5a531838787785fe4da2142c77d26ccb69e6da2172103" ---- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.42s) +time="2024-04-05T20:05:50Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-05T20:05:50Z" 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: 6495385ee3dc6fc033beb79c66b97e07f53400b7751b0ea6f2f92c5ff66084ce" +--- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.43s) === RUN TestPublishRemoveDelegation -time="2024-04-05T19:53:15Z" 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-05T19:53:15Z" 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-05T19:53:16Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-05T19:53:16Z" level=error msg="couldn't add target to targets/a: could not find necessary signing keys, at least one of these keys must be available: " ---- PASS: TestPublishRemoveDelegation (0.40s) +time="2024-04-05T20:05: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-05T20:05: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-05T20:05:51Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-05T20:05: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.38s) === RUN TestPublishSucceedsDespiteDelegationCorrupt ---- PASS: TestPublishSucceedsDespiteDelegationCorrupt (0.50s) +--- PASS: TestPublishSucceedsDespiteDelegationCorrupt (0.48s) === RUN TestRotateKeyInvalidRole ---- PASS: TestRotateKeyInvalidRole (0.23s) +--- PASS: TestRotateKeyInvalidRole (0.26s) === RUN TestRemoteRotationError ---- PASS: TestRemoteRotationError (0.06s) +--- PASS: TestRemoteRotationError (0.05s) === RUN TestRemoteRotationEndpointError --- PASS: TestRemoteRotationEndpointError (0.15s) === RUN TestRemoteRotationNoRootKey ---- PASS: TestRemoteRotationNoRootKey (0.24s) +--- PASS: TestRemoteRotationNoRootKey (0.22s) === RUN TestRemoteRotationNoInit -time="2024-04-05T19:53:17Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" ---- PASS: TestRemoteRotationNoInit (0.15s) +time="2024-04-05T20:05:52Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +--- PASS: TestRemoteRotationNoInit (0.14s) === RUN TestRotateBeforePublishFromRemoteKeyToLocalKey ---- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (0.73s) +--- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (0.77s) === RUN TestRotateKeyAfterPublishNoServerManagementChange ---- PASS: TestRotateKeyAfterPublishNoServerManagementChange (2.10s) +--- PASS: TestRotateKeyAfterPublishNoServerManagementChange (3.35s) === RUN TestRotateKeyAfterPublishServerManagementChange ---- PASS: TestRotateKeyAfterPublishServerManagementChange (2.36s) +--- PASS: TestRotateKeyAfterPublishServerManagementChange (2.50s) === RUN TestRotateRootKey ---- PASS: TestRotateRootKey (0.70s) +--- PASS: TestRotateRootKey (0.80s) === RUN TestRotateRootMultiple ---- PASS: TestRotateRootMultiple (0.94s) +--- PASS: TestRotateRootMultiple (1.04s) === RUN TestRotateRootKeyProvided ---- PASS: TestRotateRootKeyProvided (0.72s) +--- PASS: TestRotateRootKeyProvided (0.68s) === RUN TestRotateRootKeyLegacySupport ---- PASS: TestRotateRootKeyLegacySupport (1.01s) +--- PASS: TestRotateRootKeyLegacySupport (1.05s) === RUN TestRemoteServerUnavailableNoLocalCache -time="2024-04-05T19:53:25Z" 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) +time="2024-04-05T20:06:02Z" 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) === 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.08s) +--- PASS: TestRemoveDelegationChangefileValid (0.06s) === RUN TestRemoveDelegationChangefileApplicable -time="2024-04-05T19:53:26Z" 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-05T19:53:26Z" 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-05T20:06:03Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-04-05T20:06:03Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" --- PASS: TestRemoveDelegationChangefileApplicable (0.07s) === RUN TestClearAllPathsDelegationChangefileApplicable --- PASS: TestClearAllPathsDelegationChangefileApplicable (0.07s) === RUN TestFullAddDelegationChangefileApplicable ---- PASS: TestFullAddDelegationChangefileApplicable (0.08s) +--- PASS: TestFullAddDelegationChangefileApplicable (0.10s) === RUN TestFullRemoveDelegationChangefileApplicable ---- PASS: TestFullRemoveDelegationChangefileApplicable (0.09s) +--- PASS: TestFullRemoveDelegationChangefileApplicable (0.08s) === RUN TestRemoveDelegationErrorWritingChanges ---- PASS: TestRemoveDelegationErrorWritingChanges (0.08s) +--- PASS: TestRemoveDelegationErrorWritingChanges (0.06s) === RUN TestClientInvalidURL --- PASS: TestClientInvalidURL (0.00s) === RUN TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole ---- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (1.30s) +--- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (1.21s) === RUN TestDeleteRepo ---- PASS: TestDeleteRepo (0.16s) +--- PASS: TestDeleteRepo (0.13s) === RUN TestDeleteRemoteRepo ---- PASS: TestDeleteRemoteRepo (0.50s) +--- PASS: TestDeleteRemoteRepo (0.36s) === RUN TestListRoles ---- PASS: TestListRoles (1.67s) +--- PASS: TestListRoles (0.88s) === RUN TestGetAllTargetInfo ---- PASS: TestGetAllTargetInfo (0.87s) +--- PASS: TestGetAllTargetInfo (0.54s) === RUN TestUpdateSucceedsEvenIfCannotWriteNewRepo -time="2024-04-05T19:53:31Z" level=error msg="could not save root to cache: Non-writable" ---- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.46s) +time="2024-04-05T20:06:06Z" level=error msg="could not save root to cache: Non-writable" +--- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.47s) === RUN TestUpdateSucceedsEvenIfCannotWriteExistingRepo ---- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (3.79s) +--- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (1.99s) === RUN TestUpdateInOfflineMode -time="2024-04-05T19:53:35Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" -time="2024-04-05T19:53:35Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateInOfflineMode (0.23s) +time="2024-04-05T20:06:09Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +time="2024-04-05T20:06:09Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateInOfflineMode (0.18s) === RUN TestUpdateReplacesCorruptOrMissingMetadata -time="2024-04-05T19:53:36Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:53:36Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateReplacesCorruptOrMissingMetadata (7.25s) +time="2024-04-05T20:06:11Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:06:11Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateReplacesCorruptOrMissingMetadata (4.21s) === RUN TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign -time="2024-04-05T19:53:43Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:53:43Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.37s) +time="2024-04-05T20:06:13Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:06:13Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.34s) === RUN TestUpdateRemoteRootNotExistNoLocalCache ---- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.23s) +--- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.17s) === RUN TestUpdateRemoteRootNotExistCanUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.56s) +--- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.34s) === RUN TestUpdateRemoteRootNotExistCannotUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.86s) +--- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.48s) === RUN TestUpdateRemoteRoot50XNoLocalCache ---- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.25s) +--- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.18s) === RUN TestUpdateRemoteRoot50XCanUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.66s) +--- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.41s) === RUN TestUpdateRemoteRoot50XCannotUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.78s) +--- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.40s) === RUN TestUpdateNonRootRemoteMissingMetadataNoLocalCache ---- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (2.26s) +--- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (0.98s) === RUN TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache -time="2024-04-05T19:53:49Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (3.22s) +time="2024-04-05T20:06:17Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (1.60s) === RUN TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache -time="2024-04-05T19:53:53Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (3.74s) +time="2024-04-05T20:06:19Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (1.62s) === RUN TestUpdateNonRootRemote50XNoLocalCache ---- PASS: TestUpdateNonRootRemote50XNoLocalCache (2.07s) +--- PASS: TestUpdateNonRootRemote50XNoLocalCache (0.95s) === RUN TestUpdateNonRootRemote50XCanUseLocalCache -time="2024-04-05T19:53:59Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (3.00s) +time="2024-04-05T20:06:21Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (1.21s) === RUN TestUpdateNonRootRemote50XCannotUseLocalCache -time="2024-04-05T19:54:02Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (3.69s) +time="2024-04-05T20:06:22Z" 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 (2.06s) +--- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (1.12s) === RUN TestUpdateRemoteChecksumWrongCanUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (2.10s) +--- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (1.52s) === RUN TestUpdateRemoteChecksumWrongCannotUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (2.61s) +--- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (2.16s) === RUN TestUpdateRootRemoteCorruptedNoLocalCache -time="2024-04-05T19:54:13Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:54:13Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (4.31s) +time="2024-04-05T20:06:30Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:06:30Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (4.21s) === RUN TestUpdateRootRemoteCorruptedCanUseLocalCache ---- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (8.77s) +--- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (7.32s) === RUN TestUpdateRootRemoteCorruptedCannotUseLocalCache -time="2024-04-05T19:54:29Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:54:29Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (8.93s) +time="2024-04-05T20:06:43Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:06:43Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (7.35s) === RUN TestUpdateNonRootRemoteCorruptedNoLocalCache -time="2024-04-05T19:54:35Z" level=error msg="Metadata for targets expired" -time="2024-04-05T19:54:35Z" level=error msg="Metadata for targets expired" -time="2024-04-05T19:54:36Z" level=error msg="Metadata for snapshot expired" -time="2024-04-05T19:54:36Z" level=error msg="Metadata for snapshot expired" -time="2024-04-05T19:54:38Z" level=error msg="Metadata for timestamp expired" -time="2024-04-05T19:54:38Z" level=error msg="Metadata for timestamp expired" -time="2024-04-05T19:54:39Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-05T19:54:39Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-05T19:54:40Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-05T19:54:40Z" level=error msg="Metadata for targets/a expired" -time="2024-04-05T19:54:40Z" level=warning msg="Error getting targets/a: targets/a expired at Sat Mar 4 19:54:40 UTC 2023" -time="2024-04-05T19:54:42Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-04-05T19:54:42Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-04-05T19:54:42Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-04-05T19:54:42Z" level=error msg="Metadata for targets/a/b expired" -time="2024-04-05T19:54:42Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Sat Mar 4 19:54:42 UTC 2023" -time="2024-04-05T19:54:45Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-04-05T19:54:45Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-04-05T19:54:45Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-04-05T19:54:45Z" level=error msg="Metadata for targets/b expired" -time="2024-04-05T19:54:45Z" level=warning msg="Error getting targets/b: targets/b expired at Sat Mar 4 19:54:45 UTC 2023" ---- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (14.12s) +time="2024-04-05T20:06:48Z" level=error msg="Metadata for targets expired" +time="2024-04-05T20:06:48Z" level=error msg="Metadata for targets expired" +time="2024-04-05T20:06:49Z" level=error msg="Metadata for snapshot expired" +time="2024-04-05T20:06:49Z" level=error msg="Metadata for snapshot expired" +time="2024-04-05T20:06:51Z" level=error msg="Metadata for timestamp expired" +time="2024-04-05T20:06:51Z" level=error msg="Metadata for timestamp expired" +time="2024-04-05T20:06:51Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-05T20:06:52Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-05T20:06:52Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-05T20:06:52Z" level=error msg="Metadata for targets/a expired" +time="2024-04-05T20:06:52Z" level=warning msg="Error getting targets/a: targets/a expired at Sat Mar 4 20:06:52 UTC 2023" +time="2024-04-05T20:06:53Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-04-05T20:06:53Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-04-05T20:06:53Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-04-05T20:06:53Z" level=error msg="Metadata for targets/a/b expired" +time="2024-04-05T20:06:53Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Sat Mar 4 20:06:53 UTC 2023" +time="2024-04-05T20:06:54Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-04-05T20:06:55Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-04-05T20:06:55Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-04-05T20:06:55Z" level=error msg="Metadata for targets/b expired" +time="2024-04-05T20:06:55Z" level=warning msg="Error getting targets/b: targets/b expired at Sat Mar 4 20:06:55 UTC 2023" +--- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (8.73s) === RUN TestUpdateNonRootRemoteCorruptedCanUseLocalCache -time="2024-04-05T19:54:58Z" level=error msg="Metadata for timestamp expired" -time="2024-04-05T19:54:58Z" level=error msg="Metadata for timestamp expired" ---- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (23.63s) +time="2024-04-05T20:07:00Z" level=error msg="Metadata for timestamp expired" +time="2024-04-05T20:07:00Z" level=error msg="Metadata for timestamp expired" +--- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (11.24s) === RUN TestUpdateNonRootRemoteCorruptedCannotUseLocalCache -time="2024-04-05T19:55:14Z" level=error msg="Metadata for targets expired" -time="2024-04-05T19:55:18Z" level=error msg="Metadata for snapshot expired" -time="2024-04-05T19:55:18Z" level=error msg="Metadata for snapshot expired" -time="2024-04-05T19:55:21Z" level=error msg="Metadata for timestamp expired" -time="2024-04-05T19:55:21Z" level=error msg="Metadata for timestamp expired" -time="2024-04-05T19:55:23Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-05T19:55:23Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-05T19:55:24Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-05T19:55:24Z" level=error msg="Metadata for targets/a expired" -time="2024-04-05T19:55:24Z" level=warning msg="Error getting targets/a: targets/a expired at Sat Mar 4 19:55:24 UTC 2023" -time="2024-04-05T19:55:26Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-04-05T19:55:27Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-04-05T19:55:27Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-04-05T19:55:27Z" level=error msg="Metadata for targets/a/b expired" -time="2024-04-05T19:55:27Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Sat Mar 4 19:55:27 UTC 2023" -time="2024-04-05T19:55:30Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-04-05T19:55:30Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-04-05T19:55:31Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-04-05T19:55:31Z" level=error msg="Metadata for targets/b expired" -time="2024-04-05T19:55:31Z" level=warning msg="Error getting targets/b: targets/b expired at Sat Mar 4 19:55:31 UTC 2023" ---- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (21.93s) +time="2024-04-05T20:07:09Z" level=error msg="Metadata for targets expired" +time="2024-04-05T20:07:11Z" level=error msg="Metadata for snapshot expired" +time="2024-04-05T20:07:11Z" level=error msg="Metadata for snapshot expired" +time="2024-04-05T20:07:13Z" level=error msg="Metadata for timestamp expired" +time="2024-04-05T20:07:13Z" level=error msg="Metadata for timestamp expired" +time="2024-04-05T20:07:14Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-05T20:07:15Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-05T20:07:15Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-05T20:07:15Z" level=error msg="Metadata for targets/a expired" +time="2024-04-05T20:07:15Z" level=warning msg="Error getting targets/a: targets/a expired at Sat Mar 4 20:07:15 UTC 2023" +time="2024-04-05T20:07:17Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-04-05T20:07:17Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-04-05T20:07:17Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-04-05T20:07:18Z" level=error msg="Metadata for targets/a/b expired" +time="2024-04-05T20:07:18Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Sat Mar 4 20:07:18 UTC 2023" +time="2024-04-05T20:07:20Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-04-05T20:07:20Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-04-05T20:07:20Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-04-05T20:07:21Z" level=error msg="Metadata for targets/b expired" +time="2024-04-05T20:07:21Z" level=warning msg="Error getting targets/b: targets/b expired at Sat Mar 4 20:07:21 UTC 2023" +--- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (15.49s) === RUN TestUpdateLocalAndRemoteRootCorrupt -time="2024-04-05T19:55:39Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:39Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:47Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:47Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:47Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:47Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:47Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:48Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:48Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:48Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:48Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:48Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:49Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:49Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:49Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:49Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:49Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:50Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:50Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:50Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:50Z" level=error msg="Metadata for root expired" -time="2024-04-05T19:55:50Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateLocalAndRemoteRootCorrupt (17.60s) +time="2024-04-05T20:07:29Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:29Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:36Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:36Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:36Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:36Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:36Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:37Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:37Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:37Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:37Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:37Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:37Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:38Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:38Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:38Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:38Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:38Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:39Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:39Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:39Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:39Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateLocalAndRemoteRootCorrupt (16.46s) === RUN TestUpdateRemoteKeyRotated -time="2024-04-05T19:55:51Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-04-05T19:55:52Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-04-05T19:55:52Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" ---- PASS: TestUpdateRemoteKeyRotated (1.68s) +time="2024-04-05T20:07:40Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-04-05T20:07:40Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-04-05T20:07:40Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +--- PASS: TestUpdateRemoteKeyRotated (1.41s) === RUN TestValidateRootRotationWithOldRole ---- PASS: TestValidateRootRotationWithOldRole (0.73s) +--- PASS: TestValidateRootRotationWithOldRole (0.66s) === RUN TestRootRoleInvariant ---- PASS: TestRootRoleInvariant (0.87s) +--- PASS: TestRootRoleInvariant (0.55s) === RUN TestBadIntermediateTransitions ---- PASS: TestBadIntermediateTransitions (0.63s) +--- PASS: TestBadIntermediateTransitions (0.23s) === RUN TestExpiredIntermediateTransitions -time="2024-04-05T19:55:54Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" ---- PASS: TestExpiredIntermediateTransitions (0.34s) +time="2024-04-05T20:07:42Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" +--- PASS: TestExpiredIntermediateTransitions (0.30s) === RUN TestDownloadTargetsLarge ---- PASS: TestDownloadTargetsLarge (13.17s) +--- PASS: TestDownloadTargetsLarge (8.11s) === RUN TestDownloadTargetsDeep ---- PASS: TestDownloadTargetsDeep (0.24s) +--- PASS: TestDownloadTargetsDeep (0.20s) === RUN TestDownloadSnapshotLargeDelegationsMany ---- PASS: TestDownloadSnapshotLargeDelegationsMany (14.04s) +--- PASS: TestDownloadSnapshotLargeDelegationsMany (7.79s) === RUN TestRootOnDiskTrustPinning ---- PASS: TestRootOnDiskTrustPinning (0.33s) +--- PASS: TestRootOnDiskTrustPinning (0.13s) === RUN TestLoadTUFRepoBadURL --- PASS: TestLoadTUFRepoBadURL (0.00s) === RUN TestApplyTargetsChange ---- PASS: TestApplyTargetsChange (0.03s) +--- PASS: TestApplyTargetsChange (0.02s) === RUN TestApplyAddTargetTwice --- PASS: TestApplyAddTargetTwice (0.02s) === RUN TestApplyChangelist --- PASS: TestApplyChangelist (0.02s) === RUN TestApplyChangelistMulti ---- PASS: TestApplyChangelistMulti (0.04s) +--- PASS: TestApplyChangelistMulti (0.02s) === RUN TestApplyTargetsDelegationCreateDelete ---- PASS: TestApplyTargetsDelegationCreateDelete (0.04s) +--- PASS: TestApplyTargetsDelegationCreateDelete (0.02s) === RUN TestApplyTargetsDelegationCreate2SharedKey ---- PASS: TestApplyTargetsDelegationCreate2SharedKey (0.04s) +--- PASS: TestApplyTargetsDelegationCreate2SharedKey (0.02s) === RUN TestApplyTargetsDelegationCreateEdit ---- PASS: TestApplyTargetsDelegationCreateEdit (0.03s) +--- PASS: TestApplyTargetsDelegationCreateEdit (0.02s) === RUN TestApplyTargetsDelegationEditNonExisting --- PASS: TestApplyTargetsDelegationEditNonExisting (0.02s) === RUN TestApplyTargetsDelegationCreateAlreadyExisting ---- PASS: TestApplyTargetsDelegationCreateAlreadyExisting (0.03s) +--- PASS: TestApplyTargetsDelegationCreateAlreadyExisting (0.02s) === RUN TestApplyTargetsDelegationAlreadyExistingMergePaths ---- PASS: TestApplyTargetsDelegationAlreadyExistingMergePaths (0.04s) +--- PASS: TestApplyTargetsDelegationAlreadyExistingMergePaths (0.02s) === RUN TestApplyTargetsDelegationInvalidRole ---- PASS: TestApplyTargetsDelegationInvalidRole (0.04s) +--- PASS: TestApplyTargetsDelegationInvalidRole (0.02s) === RUN TestApplyTargetsDelegationInvalidJSONContent ---- PASS: TestApplyTargetsDelegationInvalidJSONContent (0.03s) +--- PASS: TestApplyTargetsDelegationInvalidJSONContent (0.02s) === RUN TestApplyTargetsDelegationInvalidAction ---- PASS: TestApplyTargetsDelegationInvalidAction (0.04s) +--- PASS: TestApplyTargetsDelegationInvalidAction (0.02s) === RUN TestApplyTargetsChangeInvalidType ---- PASS: TestApplyTargetsChangeInvalidType (0.04s) +--- PASS: TestApplyTargetsChangeInvalidType (0.02s) === RUN TestApplyTargetsDelegationCreate2Deep ---- PASS: TestApplyTargetsDelegationCreate2Deep (0.05s) +--- PASS: TestApplyTargetsDelegationCreate2Deep (0.02s) === RUN TestApplyTargetsDelegationParentDoesntExist ---- PASS: TestApplyTargetsDelegationParentDoesntExist (0.05s) +--- PASS: TestApplyTargetsDelegationParentDoesntExist (0.02s) === RUN TestApplyChangelistCreatesDelegation ---- PASS: TestApplyChangelistCreatesDelegation (0.03s) +--- PASS: TestApplyChangelistCreatesDelegation (0.02s) === RUN TestApplyChangelistTargetsToMultipleRoles ---- PASS: TestApplyChangelistTargetsToMultipleRoles (0.03s) +--- PASS: TestApplyChangelistTargetsToMultipleRoles (0.02s) === RUN TestApplyChangelistTargetsFailsNonexistentRole -time="2024-04-05T19:56:23Z" 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-05T19:56:23Z" 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.04s) +time="2024-04-05T20:07:58Z" 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-05T20:07:58Z" 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-05T19:56:23Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" ---- PASS: TestChangeTargetMetaFailsInvalidRole (0.07s) +time="2024-04-05T20:07:58Z" 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-05T19:56:23Z" level=error msg="couldn't add target to targets/level1: Could not add all targets" ---- PASS: TestChangeTargetMetaFailsIfPrefixError (0.04s) +time="2024-04-05T20:07:59Z" level=error msg="couldn't add target to targets/level1: Could not add all targets" +--- PASS: TestChangeTargetMetaFailsIfPrefixError (0.02s) === RUN TestAllNearExpiry ---- PASS: TestAllNearExpiry (0.04s) +--- 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 210.416s +ok github.com/theupdateframework/notary/client 150.603s === RUN TestTUFDelegation ---- PASS: TestTUFDelegation (0.08s) +--- PASS: TestTUFDelegation (0.03s) === RUN TestMemChangelist --- PASS: TestMemChangelist (0.00s) === RUN TestMemChangeIterator @@ -2217,14 +2262,14 @@ === RUN TestAdd --- PASS: TestAdd (0.00s) === RUN TestErrorConditions -time="2024-04-05T19:56:27Z" level=warning msg="invalid character '\\x05' looking for beginning of value" +time="2024-04-05T20:05:28Z" 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.132s +ok github.com/theupdateframework/notary/client/changelist 0.054s === RUN TestParseConfigError --- PASS: TestParseConfigError (0.00s) === RUN TestSetupGRPCServer @@ -2232,9 +2277,9 @@ === RUN TestSetupStorage --- PASS: TestSetupStorage (0.00s) === RUN TestSetupNetListener ---- PASS: TestSetupNetListener (0.00s) +--- PASS: TestSetupNetListener (0.01s) PASS -ok github.com/theupdateframework/notary/cmd/escrow 0.012s +ok github.com/theupdateframework/notary/cmd/escrow 0.040s === RUN TestPurgeDelegationKeys Usage: delegation [command] @@ -2258,13 +2303,13 @@ 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.00s) +--- PASS: TestPurgeDelegationKeys (0.01s) === RUN TestAddInvalidDelegationName ---- PASS: TestAddInvalidDelegationName (0.04s) +--- PASS: TestAddInvalidDelegationName (0.05s) === RUN TestAddInvalidDelegationCert ---- PASS: TestAddInvalidDelegationCert (0.01s) +--- PASS: TestAddInvalidDelegationCert (0.02s) === RUN TestAddInvalidShortPubkeyCert ---- PASS: TestAddInvalidShortPubkeyCert (0.01s) +--- PASS: TestAddInvalidShortPubkeyCert (0.02s) === RUN TestRemoveInvalidDelegationName --- PASS: TestRemoveInvalidDelegationName (0.00s) === RUN TestRemoveAllInvalidDelegationName @@ -2292,7 +2337,7 @@ remove Remove KeyID(s) from the specified Role delegation. Use "delegation [command] --help" for more information about a command. ---- PASS: TestListInvalidNumArgs (0.00s) +--- PASS: TestListInvalidNumArgs (0.01s) === RUN TestRemoveInvalidNumArgs Usage: delegation [command] @@ -2306,28 +2351,28 @@ Use "delegation [command] --help" for more information about a command. --- PASS: TestRemoveInvalidNumArgs (0.00s) === RUN TestInitWithRootKey ---- PASS: TestInitWithRootKey (0.36s) +--- PASS: TestInitWithRootKey (0.40s) === RUN TestInitWithRootCert ---- PASS: TestInitWithRootCert (0.28s) +--- PASS: TestInitWithRootCert (0.33s) === RUN TestClientTUFInteraction ---- PASS: TestClientTUFInteraction (0.71s) +--- PASS: TestClientTUFInteraction (0.74s) === RUN TestClientDeleteTUFInteraction -time="2024-04-05T19:56:57Z" 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]:41342->[::1]:53: read: connection refused" ---- PASS: TestClientDeleteTUFInteraction (0.85s) +time="2024-04-05T20:05:51Z" 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]:38228->[::1]:53: read: connection refused" +--- PASS: TestClientDeleteTUFInteraction (0.81s) === RUN TestClientTUFAddByHashInteraction ---- PASS: TestClientTUFAddByHashInteraction (1.42s) +--- PASS: TestClientTUFAddByHashInteraction (2.21s) === RUN TestClientDelegationsInteraction -time="2024-04-05T19:56:59Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2024-04-05T19:56:59Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" ---- PASS: TestClientDelegationsInteraction (3.32s) +time="2024-04-05T20:05:55Z" 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-05T20:05:55Z" 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.81s) === RUN TestClientDelegationsPublishing ---- PASS: TestClientDelegationsPublishing (4.52s) +--- PASS: TestClientDelegationsPublishing (3.40s) === RUN TestClientKeyGenerationRotation ---- PASS: TestClientKeyGenerationRotation (4.74s) +--- PASS: TestClientKeyGenerationRotation (3.51s) === RUN TestKeyRotation ---- PASS: TestKeyRotation (0.66s) +--- PASS: TestKeyRotation (0.48s) === RUN TestKeyRotationNonRoot ---- PASS: TestKeyRotationNonRoot (0.72s) +--- PASS: TestKeyRotationNonRoot (0.66s) === RUN TestDefaultRootKeyGeneration --- PASS: TestDefaultRootKeyGeneration (0.01s) === RUN TestLogLevelFlags @@ -2336,51 +2381,51 @@ --- PASS: TestClientKeyPassphraseChange (0.52s) === RUN TestPurgeSingleKey Removal of the following keys from all delegations in gun staged for next publish: - - af7d3b0f7ae82786f5202b4af5cd429836d94845abed8cfa7fe5369df53ae696 ---- PASS: TestPurgeSingleKey (0.54s) + - 09eb18b6aa7275992a0db48b1790df386f7be0e2c404bbd7cff0f22debb39e43 +--- PASS: TestPurgeSingleKey (0.55s) === RUN TestWitness -time="2024-04-05T19:57:14Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-04-05T19:57:14Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-04-05T19:57:15Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-04-05T19:57:15Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-04-05T19:57:15Z" 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-05T19:57:15Z" 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-05T19:57:15Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" ---- PASS: TestWitness (1.68s) +time="2024-04-05T20:06:08Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-04-05T20:06:08Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-04-05T20:06:08Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-04-05T20:06:08Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-04-05T20:06:08Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-04-05T20:06:08Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-04-05T20:06:09Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +--- PASS: TestWitness (1.72s) === RUN TestClientTUFInitWithAutoPublish -time="2024-04-05T19:57:15Z" 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]:48719->[::1]:53: read: connection refused" ---- PASS: TestClientTUFInitWithAutoPublish (0.40s) +time="2024-04-05T20:06:09Z" 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]:51549->[::1]:53: read: connection refused" +--- PASS: TestClientTUFInitWithAutoPublish (0.38s) === RUN TestClientTUFAddWithAutoPublish -time="2024-04-05T19:57:16Z" 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]:59249->[::1]:53: read: connection refused" -time="2024-04-05T19:57:16Z" level=error msg="Could not publish Repository since we could not update: client is offline" -time="2024-04-05T19:57:16Z" level=error msg="could not reach https://notary-server-on-the-moon:12306: Get \"https://notary-server-on-the-moon:12306/v2/\": dial tcp: lookup notary-server-on-the-moon on [::1]:53: read udp [::1]:43986->[::1]:53: read: connection refused" -time="2024-04-05T19:57:16Z" level=error msg="Could not publish Repository since we could not update: client is offline" ---- PASS: TestClientTUFAddWithAutoPublish (0.47s) +time="2024-04-05T20:06:09Z" 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]:59746->[::1]:53: read: connection refused" +time="2024-04-05T20:06:09Z" level=error msg="Could not publish Repository since we could not update: client is offline" +time="2024-04-05T20:06:09Z" 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]:36210->[::1]:53: read: connection refused" +time="2024-04-05T20:06:09Z" level=error msg="Could not publish Repository since we could not update: client is offline" +--- PASS: TestClientTUFAddWithAutoPublish (0.42s) === RUN TestClientTUFRemoveWithAutoPublish ---- PASS: TestClientTUFRemoveWithAutoPublish (0.99s) +--- PASS: TestClientTUFRemoveWithAutoPublish (1.06s) === RUN TestClientDelegationAddWithAutoPublish ---- PASS: TestClientDelegationAddWithAutoPublish (0.44s) +--- PASS: TestClientDelegationAddWithAutoPublish (0.42s) === RUN TestClientDelegationRemoveWithAutoPublish -time="2024-04-05T19:57:19Z" 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-05T19:57:19Z" 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.44s) +time="2024-04-05T20:06:12Z" 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-05T20:06:12Z" 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.92s) === RUN TestClientTUFAddByHashWithAutoPublish ---- PASS: TestClientTUFAddByHashWithAutoPublish (0.47s) +--- PASS: TestClientTUFAddByHashWithAutoPublish (0.48s) === RUN TestClientKeyImport -time="2024-04-05T19:57:20Z" 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-05T19:57:20Z" level=warning msg="failed to import key () to store: Cannot have canonical role key without a gun, don't know what gun it belongs to" +time="2024-04-05T20:06:12Z" 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-05T20:06:12Z" 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.14s) === RUN TestAddDelImportKeyPublishFlow -time="2024-04-05T19:57:20Z" 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.02s) +time="2024-04-05T20:06:13Z" 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.09s) === RUN TestExportImportFlow ---- PASS: TestExportImportFlow (0.28s) +--- PASS: TestExportImportFlow (0.32s) === RUN TestDelegationKeyImportExport --- PASS: TestDelegationKeyImportExport (0.02s) === RUN TestImportKeysNoYubikey ---- PASS: TestImportKeysNoYubikey (0.03s) +--- PASS: TestImportKeysNoYubikey (0.02s) === RUN TestExportImportKeysNoYubikey ---- PASS: TestExportImportKeysNoYubikey (0.03s) +--- PASS: TestExportImportKeysNoYubikey (0.02s) === RUN TestRemoveIfNoKey --- PASS: TestRemoveIfNoKey (0.00s) === RUN TestRemoveOneKeyAbort @@ -2394,16 +2439,16 @@ === RUN TestRemoveMultikeysRemoveOnlyChosenKey --- PASS: TestRemoveMultikeysRemoveOnlyChosenKey (0.02s) === RUN TestRotateKeyInvalidRoles -time="2024-04-05T19:57:21Z" 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]:40371->[::1]:53: read: connection refused" -time="2024-04-05T19:57:21Z" 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]:38940->[::1]:53: read: connection refused" -time="2024-04-05T19:57:21Z" 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]:33809->[::1]:53: read: connection refused" -time="2024-04-05T19:57:21Z" 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]:38777->[::1]:53: read: connection refused" +time="2024-04-05T20:06:14Z" 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]:46403->[::1]:53: read: connection refused" +time="2024-04-05T20:06:14Z" 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]:44337->[::1]:53: read: connection refused" +time="2024-04-05T20:06:14Z" 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]:57201->[::1]:53: read: connection refused" +time="2024-04-05T20:06:14Z" 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]:46162->[::1]:53: read: connection refused" --- PASS: TestRotateKeyInvalidRoles (0.01s) === RUN TestRotateKeyTargetCannotBeServerManaged -time="2024-04-05T19:57:21Z" 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]:39105->[::1]:53: read: connection refused" +time="2024-04-05T20:06:14Z" 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]:41018->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTargetCannotBeServerManaged (0.00s) === RUN TestRotateKeyTimestampCannotBeLocallyManaged -time="2024-04-05T19:57:21Z" 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]:40886->[::1]:53: read: connection refused" +time="2024-04-05T20:06:14Z" 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]:51616->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTimestampCannotBeLocallyManaged (0.00s) === RUN TestRotateKeyNoGUN Usage: @@ -2411,13 +2456,13 @@ === RUN TestRotateKeyRemoteServerManagesKey Successfully rotated snapshot key for repository docker.com/notary Successfully rotated timestamp key for repository docker.com/notary ---- PASS: TestRotateKeyRemoteServerManagesKey (0.46s) +--- PASS: TestRotateKeyRemoteServerManagesKey (0.43s) === RUN TestRotateKeyBothKeys Successfully rotated targets key for repository docker.com/notary Successfully rotated snapshot key for repository docker.com/notary ---- PASS: TestRotateKeyBothKeys (0.44s) +--- PASS: TestRotateKeyBothKeys (0.39s) === RUN TestRotateKeyRootIsInteractive ---- PASS: TestRotateKeyRootIsInteractive (0.09s) +--- PASS: TestRotateKeyRootIsInteractive (0.10s) === RUN TestChangeKeyPassphraseInvalidID --- PASS: TestChangeKeyPassphraseInvalidID (0.00s) === RUN TestChangeKeyPassphraseInvalidNumArgs @@ -2436,7 +2481,7 @@ === RUN TestImportKeysNonexistentFile --- PASS: TestImportKeysNonexistentFile (0.00s) === RUN TestKeyGeneration -time="2024-04-05T19:57:22Z" level=warning msg="failed to import key () to store: Cannot have canonical role key without a gun, don't know what gun it belongs to" +time="2024-04-05T20:06:15Z" 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) @@ -2449,7 +2494,7 @@ === RUN TestInvalidAddHashCommands --- PASS: TestInvalidAddHashCommands (0.01s) === RUN TestConfigParsingErrorsPropagatedByCommands ---- PASS: TestConfigParsingErrorsPropagatedByCommands (0.01s) +--- PASS: TestConfigParsingErrorsPropagatedByCommands (0.02s) === RUN TestInsufficientArgumentsReturnsErrorAndPrintsUsage --- PASS: TestInsufficientArgumentsReturnsErrorAndPrintsUsage (0.03s) === RUN TestBareCommandPrintsUsageAndNoError @@ -2457,9 +2502,9 @@ === RUN TestConfigFileTLSCannotBeRelativeToCWD --- PASS: TestConfigFileTLSCannotBeRelativeToCWD (0.01s) === RUN TestConfigFileTLSCanBeRelativeToConfigOrAbsolute ---- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.20s) +--- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.17s) === RUN TestConfigFileOverridenByCmdLineFlags ---- PASS: TestConfigFileOverridenByCmdLineFlags (0.24s) +--- PASS: TestConfigFileOverridenByCmdLineFlags (0.16s) === RUN TestConfigFileTrustPinning --- PASS: TestConfigFileTrustPinning (0.00s) === RUN TestPassphraseRetrieverCaching @@ -2473,7 +2518,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 @@ -2485,10 +2530,10 @@ === RUN TestImportRootCert --- PASS: TestImportRootCert (0.00s) === RUN TestTokenAuth -time="2024-04-05T19:57:23Z" 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-05T20:06:15Z" 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-05T19:57:23Z" 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-05T20:06:15Z" 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) @@ -2499,35 +2544,35 @@ === RUN TestAdminTokenAuth401Status --- PASS: TestAdminTokenAuth401Status (0.00s) === RUN TestTokenAuthNon200Non401Status -time="2024-04-05T19:57:23Z" level=error msg="could not reach http://127.0.0.1:37935: 404" +time="2024-04-05T20:06:15Z" level=error msg="could not reach http://127.0.0.1:37085: 404" --- PASS: TestTokenAuthNon200Non401Status (0.00s) === RUN TestAdminTokenAuthNon200Non401Status -time="2024-04-05T19:57:23Z" level=error msg="could not reach http://127.0.0.1:41255: 404" +time="2024-04-05T20:06:15Z" level=error msg="could not reach http://127.0.0.1:44047: 404" --- PASS: TestAdminTokenAuthNon200Non401Status (0.00s) === RUN TestConfigureRepo --- PASS: TestConfigureRepo (0.00s) === RUN TestConfigureRepoRW -Enter username: time="2024-04-05T19:57:23Z" level=error msg="error processing username input: EOF" ---- PASS: TestConfigureRepoRW (0.00s) +Enter username: time="2024-04-05T20:06:15Z" level=error msg="error processing username input: EOF" +--- PASS: TestConfigureRepoRW (0.01s) === RUN TestConfigureRepoAdmin -Enter username: time="2024-04-05T19:57:23Z" level=error msg="error processing username input: EOF" ---- PASS: TestConfigureRepoAdmin (0.00s) +Enter username: time="2024-04-05T20:06:15Z" 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. Addition of target "test2" by sha256 hash to repository "gun" staged for next publish. Addition of target "test3" by sha256 hash to repository "gun" staged for next publish. Addition of target "test4" by sha256 hash to repository "gun" staged for next publish. ---- PASS: TestStatusUnstageAndReset (0.01s) +--- PASS: TestStatusUnstageAndReset (0.02s) === RUN TestGetTrustPinningErrors Pushing changes to gun ---- PASS: TestGetTrustPinningErrors (0.00s) +--- PASS: TestGetTrustPinningErrors (0.02s) === RUN TestPasswordStore -Enter username: time="2024-04-05T19:57:23Z" level=error msg="error processing username input: EOF" +Enter username: time="2024-04-05T20:06:16Z" level=error msg="error processing username input: EOF" --- PASS: TestPasswordStore (0.00s) === RUN TestPasswordStoreWithEnvvar -time="2024-04-05T19:57:23Z" level=error msg="Authentication string with zero-length username" -time="2024-04-05T19:57:23Z" level=error msg="Could not base64-decode authentication string" +time="2024-04-05T20:06:16Z" level=error msg="Authentication string with zero-length username" +time="2024-04-05T20:06:16Z" level=error msg="Could not base64-decode authentication string" --- PASS: TestPasswordStoreWithEnvvar (0.00s) === RUN TestGetPayload --- PASS: TestGetPayload (0.00s) @@ -2536,7 +2581,7 @@ === RUN TestHomeExpand --- PASS: TestHomeExpand (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary 27.924s +ok github.com/theupdateframework/notary/cmd/notary 26.302s === RUN TestBootstrap --- PASS: TestBootstrap (0.00s) === RUN TestGetAddrAndTLSConfigInvalidTLS @@ -2552,42 +2597,42 @@ === RUN TestGetInvalidTrustService --- PASS: TestGetInvalidTrustService (0.00s) === RUN TestGetLocalTrustService -time="2024-04-05T19:58:02Z" level=info msg="Using local signing service, which requires ED25519. Ignoring all other trust_service parameters, including keyAlgorithm" +time="2024-04-05T20:06:39Z" 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-05T19:58:02Z" level=info msg="Using remote signing service" +time="2024-04-05T20:06:39Z" level=info msg="Using remote signing service" --- PASS: TestGetTrustServiceNoTLSConfig (0.00s) === RUN TestGetTrustServiceTLSSuccess -time="2024-04-05T19:58:02Z" level=info msg="Using remote signing service" +time="2024-04-05T20:06:39Z" level=info msg="Using remote signing service" --- PASS: TestGetTrustServiceTLSSuccess (0.00s) === RUN TestGetTrustServiceTLSFailure --- PASS: TestGetTrustServiceTLSFailure (0.00s) === RUN TestGetStoreInvalid -time="2024-04-05T19:58:02Z" level=info msg="Using asdf backend" +time="2024-04-05T20:06:39Z" level=info msg="Using asdf backend" --- PASS: TestGetStoreInvalid (0.00s) === RUN TestGetStoreDBStore -time="2024-04-05T19:58:02Z" level=info msg="Using sqlite3 backend" ---- PASS: TestGetStoreDBStore (0.03s) +time="2024-04-05T20:06:39Z" level=info msg="Using sqlite3 backend" +--- PASS: TestGetStoreDBStore (0.00s) === RUN TestGetStoreRethinkDBStoreConnectionFails -time="2024-04-05T19:58:02Z" level=info msg="Using rethinkdb backend" +time="2024-04-05T20:06:39Z" level=info msg="Using rethinkdb backend" --- PASS: TestGetStoreRethinkDBStoreConnectionFails (0.00s) === RUN TestGetMemoryStore -time="2024-04-05T19:58:02Z" level=info msg="Using memory backend" +time="2024-04-05T20:06:39Z" 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-05T19:58:02Z" level=info msg="Using remote signing service" -time="2024-04-05T19:58:02Z" level=info msg="Using sqlite3 backend" ---- PASS: TestSampleConfig (0.01s) +time="2024-04-05T20:06:39Z" level=info msg="Using remote signing service" +time="2024-04-05T20:06:39Z" level=info msg="Using sqlite3 backend" +--- PASS: TestSampleConfig (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary-server 0.104s +ok github.com/theupdateframework/notary/cmd/notary-server 0.061s === RUN TestGetAddrAndTLSConfigInvalidTLS --- PASS: TestGetAddrAndTLSConfigInvalidTLS (0.00s) === RUN TestGetAddrAndTLSConfigNoGRPCAddr @@ -2602,7 +2647,7 @@ main_test.go:121: DM - skipping malfunctioning test, fails on sbuild --- SKIP: TestSetupCryptoServicesRethinkDBStoreConnectionFails (0.00s) === RUN TestSetupCryptoServicesDBStoreSuccess ---- PASS: TestSetupCryptoServicesDBStoreSuccess (0.13s) +--- PASS: TestSetupCryptoServicesDBStoreSuccess (0.23s) === RUN TestSetupCryptoServicesMemoryStore --- PASS: TestSetupCryptoServicesMemoryStore (0.01s) === RUN TestSetupCryptoServicesInvalidStore @@ -2620,17 +2665,17 @@ === RUN TestSampleConfig --- PASS: TestSampleConfig (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary-signer 0.196s +ok github.com/theupdateframework/notary/cmd/notary-signer 0.291s === RUN TestGenerateCertificate --- PASS: TestGenerateCertificate (0.02s) === RUN TestCryptoServiceWithNonEmptyGUN ---- PASS: TestCryptoServiceWithNonEmptyGUN (1.88s) +--- PASS: TestCryptoServiceWithNonEmptyGUN (1.71s) === RUN TestCryptoServiceWithEmptyGUN ---- PASS: TestCryptoServiceWithEmptyGUN (1.80s) +--- PASS: TestCryptoServiceWithEmptyGUN (1.63s) === RUN TestCryptoSignerInterfaceBehavior ---- PASS: TestCryptoSignerInterfaceBehavior (0.03s) +--- PASS: TestCryptoSignerInterfaceBehavior (0.02s) PASS -ok github.com/theupdateframework/notary/cryptoservice 3.748s +ok github.com/theupdateframework/notary/cryptoservice 3.416s === RUN TestGetPassphraseForUsingDelegationKey --- PASS: TestGetPassphraseForUsingDelegationKey (0.00s) === RUN TestGetPassphraseLimitsShortPassphrases @@ -2646,144 +2691,142 @@ === RUN TestGetPassphrase --- PASS: TestGetPassphrase (0.00s) PASS -ok github.com/theupdateframework/notary/passphrase 0.028s -? github.com/theupdateframework/notary/proto [no test files] +ok github.com/theupdateframework/notary/passphrase 0.017s === RUN TestValidationErrorFormat -time="2024-04-05T19:58:17Z" 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=534e1b752789d8e9b315e41a54a5b61e3cdb2ce6eace840dc372a1ad6396" http.request.host="127.0.0.1:46395" http.request.id=77283408-5c91-49cb-a932-0d4a38eb6c0a http.request.method=POST http.request.remoteaddr="127.0.0.1:44916" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=534e1b752789d8e9b315e41a54a5b61e3cdb2ce6eace840dc372a1ad6396" http.request.host="127.0.0.1:46395" http.request.id=77283408-5c91-49cb-a932-0d4a38eb6c0a http.request.method=POST http.request.remoteaddr="127.0.0.1:44916" 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=13.858391ms http.response.status=400 http.response.written=260 ---- PASS: TestValidationErrorFormat (0.09s) +time="2024-04-05T20:06:52Z" 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=0d3f6c5c0aca5001c98e55cd3f9a08bd22e0d2e7e29331fd26afbcb46be4" http.request.host="127.0.0.1:42013" http.request.id=989eee2f-2018-4e86-8ed4-3641f6344828 http.request.method=POST http.request.remoteaddr="127.0.0.1:50494" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=0d3f6c5c0aca5001c98e55cd3f9a08bd22e0d2e7e29331fd26afbcb46be4" http.request.host="127.0.0.1:42013" http.request.id=989eee2f-2018-4e86-8ed4-3641f6344828 http.request.method=POST http.request.remoteaddr="127.0.0.1:50494" 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=13.541292ms 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-05T19:58:17Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum 48064e24c5cb23845f1f756b2be075ecf18be21615da4d0d159b0dfb3024357f" docker.io/notary=gun go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=90cbf8eecceab440dd4d738d73275b6372e5211b3a4b574cf617e9acaab7" http.request.host="127.0.0.1:42825" http.request.id=23c74dbc-d06b-43b1-9e27-7ce16e4cd330 http.request.method=POST http.request.remoteaddr="127.0.0.1:60366" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=90cbf8eecceab440dd4d738d73275b6372e5211b3a4b574cf617e9acaab7" http.request.host="127.0.0.1:42825" http.request.id=23c74dbc-d06b-43b1-9e27-7ce16e4cd330 http.request.method=POST http.request.remoteaddr="127.0.0.1:60366" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=25.561825ms http.response.status=0 http.response.written=0 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:42825" http.request.id=82caf709-b5c6-4276-aafd-ffc630e49277 http.request.method=GET http.request.remoteaddr="127.0.0.1:60366" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration=1.070519ms http.response.status=200 http.response.written=683 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:42825" http.request.id=2e5d6c8d-4e87-4eb3-8fa4-0a2dd3874c44 http.request.method=GET http.request.remoteaddr="127.0.0.1:60366" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.9006172c458ce092489f2976ba8785d15132766f87029c18502e1b8c7d73501b.json http.request.useragent=Go-http-client/1.1 http.response.duration="89.451µs" http.response.status=200 http.response.written=683 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:42825" http.request.id=c6abfcea-6920-47f6-973b-4f9544064c17 http.request.method=GET http.request.remoteaddr="127.0.0.1:60366" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="759.868µs" http.response.status=200 http.response.written=181 -time="2024-04-05T19:58:17Z" 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:42825" http.request.id=1ae831f0-bdae-4073-a1e5-26974597f682 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:60366" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:42825" http.request.id=1ae831f0-bdae-4073-a1e5-26974597f682 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:60366" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="129.628µs" http.response.status=0 http.response.written=0 ---- PASS: TestRepoPrefixMatches (0.11s) +time="2024-04-05T20:06:52Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum 0c5d6d4e39b0a15fe8c4012cee5f88479f29a32b7dde0440bfd981158b23daa7" docker.io/notary=gun go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=bc06935cc43d62da0eb9282a5549c8742e2922c7d6b95daefde3d27c2ce7" http.request.host="127.0.0.1:39805" http.request.id=d64b9bd0-87ce-465e-8d59-8006f82289d4 http.request.method=POST http.request.remoteaddr="127.0.0.1:50194" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype="multipart/form-data; boundary=bc06935cc43d62da0eb9282a5549c8742e2922c7d6b95daefde3d27c2ce7" http.request.host="127.0.0.1:39805" http.request.id=d64b9bd0-87ce-465e-8d59-8006f82289d4 http.request.method=POST http.request.remoteaddr="127.0.0.1:50194" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=23.48788ms http.response.status=0 http.response.written=0 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:39805" http.request.id=1afbe0b2-bc25-4b87-8de1-cba596ac7eec http.request.method=GET http.request.remoteaddr="127.0.0.1:50194" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration="944.365µs" http.response.status=200 http.response.written=683 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:39805" http.request.id=e7fa6b20-0208-41d9-b8e5-38fe8fad69e0 http.request.method=GET http.request.remoteaddr="127.0.0.1:50194" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.2272af9ec5b97a12fd7f02581dd21945e848c486e7d17be6486a73945832c047.json http.request.useragent=Go-http-client/1.1 http.response.duration="86.237µs" http.response.status=200 http.response.written=683 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:39805" http.request.id=c252935f-6947-4844-89cb-9f34a7e72e59 http.request.method=GET http.request.remoteaddr="127.0.0.1:50194" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="832.489µs" http.response.status=200 http.response.written=181 +time="2024-04-05T20:06:52Z" 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:39805" http.request.id=be434685-8c2b-43a5-b475-f9e4b2c43e4e http.request.method=DELETE http.request.remoteaddr="127.0.0.1:50194" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:39805" http.request.id=be434685-8c2b-43a5-b475-f9e4b2c43e4e http.request.method=DELETE http.request.remoteaddr="127.0.0.1:50194" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="259.791µs" http.response.status=0 http.response.written=0 +--- PASS: TestRepoPrefixMatches (0.08s) === RUN TestRepoPrefixDoesNotMatch ---- PASS: TestRepoPrefixDoesNotMatch (0.07s) +--- PASS: TestRepoPrefixDoesNotMatch (0.06s) === RUN TestMetricsEndpoint ---- PASS: TestMetricsEndpoint (0.01s) +--- PASS: TestMetricsEndpoint (0.02s) === RUN TestGetKeysEndpoint -time="2024-04-05T19:58:17Z" 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:34545" http.request.id=ae5922fe-8afa-422b-8235-4a6c06d2cb90 http.request.method=GET http.request.remoteaddr="127.0.0.1:38628" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:34545" http.request.id=ae5922fe-8afa-422b-8235-4a6c06d2cb90 http.request.method=GET http.request.remoteaddr="127.0.0.1:38628" 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="257.576µs" http.response.status=404 http.response.written=104 -time="2024-04-05T19:58:17Z" 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:34545" http.request.id=dd813bb9-b301-4ef0-a3b5-72d7439bd4ad http.request.method=GET http.request.remoteaddr="127.0.0.1:38642" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:34545" http.request.id=dd813bb9-b301-4ef0-a3b5-72d7439bd4ad http.request.method=GET http.request.remoteaddr="127.0.0.1:38642" 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="148.366µs" http.response.status=404 http.response.written=104 -time="2024-04-05T19:58:17Z" 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:34545" http.request.id=0270c3dd-e7cb-485f-bcee-5d9d116fba6c http.request.method=GET http.request.remoteaddr="127.0.0.1:38654" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:34545" http.request.id=0270c3dd-e7cb-485f-bcee-5d9d116fba6c http.request.method=GET http.request.remoteaddr="127.0.0.1:38654" 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="149.566µs" http.response.status=404 http.response.written=104 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:34545" http.request.id=be2845ea-a963-4b27-a81c-7575d12de4a7 http.request.method=GET http.request.remoteaddr="127.0.0.1:38662" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration=28.635675ms http.response.status=200 http.response.written=103 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:34545" http.request.id=1f5f8d9b-56aa-437d-a1ac-57abde87d792 http.request.method=GET http.request.remoteaddr="127.0.0.1:38672" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="725.032µs" http.response.status=200 http.response.written=103 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:34235" http.request.id=5c74d08c-726e-4962-a251-421e4d3e9178 http.request.method=GET http.request.remoteaddr="127.0.0.1:49984" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration=30.230306ms http.response.status=200 http.response.written=103 +time="2024-04-05T20:06:52Z" 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:34235" http.request.id=23ba8214-2c25-4ded-94b4-a59db1ca9b80 http.request.method=GET http.request.remoteaddr="127.0.0.1:49988" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:34235" http.request.id=23ba8214-2c25-4ded-94b4-a59db1ca9b80 http.request.method=GET http.request.remoteaddr="127.0.0.1:49988" 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="215.312µs" http.response.status=404 http.response.written=104 +time="2024-04-05T20:06:52Z" 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:34235" http.request.id=d2607100-03c9-4f14-b7d6-e0528ec595f3 http.request.method=GET http.request.remoteaddr="127.0.0.1:49990" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:34235" http.request.id=d2607100-03c9-4f14-b7d6-e0528ec595f3 http.request.method=GET http.request.remoteaddr="127.0.0.1:49990" 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="196.073µs" http.response.status=404 http.response.written=104 +time="2024-04-05T20:06:52Z" 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:34235" http.request.id=b9bab291-9323-44cf-9af8-c085fdcbc591 http.request.method=GET http.request.remoteaddr="127.0.0.1:50004" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:34235" http.request.id=b9bab291-9323-44cf-9af8-c085fdcbc591 http.request.method=GET http.request.remoteaddr="127.0.0.1:50004" 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-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:34235" http.request.id=17e0e41a-8f54-4cbf-af72-8a357e593319 http.request.method=GET http.request.remoteaddr="127.0.0.1:50020" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="722.813µs" http.response.status=200 http.response.written=103 --- PASS: TestGetKeysEndpoint (0.05s) === RUN TestGetRoleByHash -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:44231" http.request.id=347a5b02-d533-4b77-a346-6b810efd639d http.request.method=GET http.request.remoteaddr="127.0.0.1:47398" http.request.uri=/v2/gun/_trust/tuf/timestamp.42d5de34a74e473b2626e4e75091c89845d3d010d761d92734fe8dbe0a2b4f48.json http.request.useragent=Go-http-client/1.1 http.response.duration="123.928µs" http.response.status=200 http.response.written=115 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:46371" http.request.id=f0f4ae45-9184-4180-9392-74ba8d863c51 http.request.method=GET http.request.remoteaddr="127.0.0.1:42010" http.request.uri=/v2/gun/_trust/tuf/timestamp.690a19dfcdfa72ad6d0e2fa1d14ae8ad8f443eed05a3427b5769ae3a547dc5f9.json http.request.useragent=Go-http-client/1.1 http.response.duration="103.037µs" http.response.status=200 http.response.written=115 --- PASS: TestGetRoleByHash (0.01s) === RUN TestGetRoleByVersion -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:36059" http.request.id=7d404cf3-2d4d-458f-be20-5e99df2aed59 http.request.method=GET http.request.remoteaddr="127.0.0.1:46972" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="119.929µs" http.response.status=200 http.response.written=115 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:40701" http.request.id=9d6c18c8-99b9-47bf-8112-ce88d677aece http.request.method=GET http.request.remoteaddr="127.0.0.1:42650" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="107.356µs" http.response.status=200 http.response.written=115 --- PASS: TestGetRoleByVersion (0.01s) === RUN TestGetCurrentRole -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:33101" http.request.id=44d2d193-52df-4e37-8eec-3239696616dc http.request.method=GET http.request.remoteaddr="127.0.0.1:50512" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration=1.683261ms http.response.status=200 http.response.written=495 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:42019" http.request.id=c522be54-8afc-47cc-a9fd-eb6fa5500f3f http.request.method=GET http.request.remoteaddr="127.0.0.1:40214" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="893.167µs" http.response.status=200 http.response.written=495 --- PASS: TestGetCurrentRole (0.06s) === RUN TestRotateKeyEndpoint -time="2024-04-05T19:58:17Z" 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:42557" http.request.id=c1195f54-22d0-4f49-8e96-32ea0aecb7e8 http.request.method=POST http.request.remoteaddr="127.0.0.1:45756" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:42557" http.request.id=c1195f54-22d0-4f49-8e96-32ea0aecb7e8 http.request.method=POST http.request.remoteaddr="127.0.0.1:45756" 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="303.671µs" http.response.status=404 http.response.written=104 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:42557" http.request.id=4d519724-17da-4d44-a5e4-6457292d05dc http.request.method=POST http.request.remoteaddr="127.0.0.1:45766" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="725.252µs" http.response.status=200 http.response.written=103 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:42557" http.request.id=0a6a1dde-4771-4bab-b8d9-943a909ee59e http.request.method=POST http.request.remoteaddr="127.0.0.1:45774" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="670.417µs" http.response.status=200 http.response.written=103 -time="2024-04-05T19:58:17Z" 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:42557" http.request.id=60c1a088-ff03-4c63-961a-93a1b591b268 http.request.method=POST http.request.remoteaddr="127.0.0.1:45778" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:42557" http.request.id=60c1a088-ff03-4c63-961a-93a1b591b268 http.request.method=POST http.request.remoteaddr="127.0.0.1:45778" 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="205.201µs" http.response.status=404 http.response.written=104 -time="2024-04-05T19:58:17Z" 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:42557" http.request.id=340283c1-93a7-4128-b52f-c0a342219f28 http.request.method=POST http.request.remoteaddr="127.0.0.1:45784" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:42557" http.request.id=340283c1-93a7-4128-b52f-c0a342219f28 http.request.method=POST http.request.remoteaddr="127.0.0.1:45784" 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="185.982µs" http.response.status=404 http.response.written=104 -time="2024-04-05T19:58:17Z" 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:42557" http.request.id=90177402-d638-4d4a-8630-8fd028a68971 http.request.method=POST http.request.remoteaddr="127.0.0.1:45794" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 -time="2024-04-05T19:58:17Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:42557" http.request.id=90177402-d638-4d4a-8630-8fd028a68971 http.request.method=POST http.request.remoteaddr="127.0.0.1:45794" 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="157.485µs" http.response.status=404 http.response.written=104 ---- PASS: TestRotateKeyEndpoint (0.01s) +time="2024-04-05T20:06:52Z" 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:39257" http.request.id=2de2c36b-58fb-4dd7-a73f-843b4900480c http.request.method=POST http.request.remoteaddr="127.0.0.1:58714" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:39257" http.request.id=2de2c36b-58fb-4dd7-a73f-843b4900480c http.request.method=POST http.request.remoteaddr="127.0.0.1:58714" 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="210.472µs" http.response.status=404 http.response.written=104 +time="2024-04-05T20:06:52Z" 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:39257" http.request.id=0f95c196-285a-4a92-a0e5-baf47dd902b1 http.request.method=POST http.request.remoteaddr="127.0.0.1:58726" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:39257" http.request.id=0f95c196-285a-4a92-a0e5-baf47dd902b1 http.request.method=POST http.request.remoteaddr="127.0.0.1:58726" 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="160.554µs" http.response.status=404 http.response.written=104 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:39257" http.request.id=d981165d-b28d-4c7d-8f6b-393b2c091729 http.request.method=POST http.request.remoteaddr="127.0.0.1:58732" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="645.015µs" http.response.status=200 http.response.written=103 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:39257" http.request.id=b2307665-a549-4805-8742-44acc561f7db http.request.method=POST http.request.remoteaddr="127.0.0.1:58744" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="682.014µs" http.response.status=200 http.response.written=103 +time="2024-04-05T20:06:52Z" 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:39257" http.request.id=3dc2a495-c617-40ce-b6f6-5e1577d8fb64 http.request.method=POST http.request.remoteaddr="127.0.0.1:58758" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:39257" http.request.id=3dc2a495-c617-40ce-b6f6-5e1577d8fb64 http.request.method=POST http.request.remoteaddr="127.0.0.1:58758" 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="182.713µs" http.response.status=404 http.response.written=104 +time="2024-04-05T20:06:52Z" 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:39257" http.request.id=a7cdc616-5f69-47fc-b968-b16a1dc1d98d http.request.method=POST http.request.remoteaddr="127.0.0.1:58774" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2024-04-05T20:06:52Z" level=info msg="response completed" go.version=go1.22.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:39257" http.request.id=a7cdc616-5f69-47fc-b968-b16a1dc1d98d http.request.method=POST http.request.remoteaddr="127.0.0.1:58774" 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="184.473µs" http.response.status=404 http.response.written=104 +--- PASS: TestRotateKeyEndpoint (0.02s) PASS -ok github.com/theupdateframework/notary/server 0.435s -? github.com/theupdateframework/notary/server/errors [no test files] +ok github.com/theupdateframework/notary/server 0.452s === RUN Test_changefeed --- PASS: Test_changefeed (0.00s) === RUN Test_checkChangefeedInputs -time="2024-04-05T19:58:20Z" level=error msg="500 GET unable to retrieve storage" -time="2024-04-05T19:58:20Z" level=error msg="400 GET invalid pageSize: not_a_number" +time="2024-04-05T20:06:55Z" level=error msg="500 GET unable to retrieve storage" +time="2024-04-05T20:06:55Z" level=error msg="400 GET invalid pageSize: not_a_number" --- PASS: Test_checkChangefeedInputs (0.00s) === RUN TestMainHandlerGet -time="2024-04-05T19:58:20Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:38315" http.request.id=ed55a555-c48d-4a9d-b029-8cb2b82adeb5 http.request.method=GET http.request.remoteaddr="127.0.0.1:50846" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="93.532µs" http.response.status=200 http.response.written=2 +time="2024-04-05T20:06:55Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:36581" http.request.id=ae664091-855d-4d40-ad42-0a22444e2d3a http.request.method=GET http.request.remoteaddr="127.0.0.1:60594" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="57.718µs" http.response.status=200 http.response.written=2 --- PASS: TestMainHandlerGet (0.00s) === RUN TestMainHandlerNotGet -time="2024-04-05T19:58:20Z" 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:33377" http.request.id=5ca99049-633b-4f57-b41c-31fad53460e7 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:47684" http.request.uri=/ http.request.useragent=Go-http-client/1.1 -time="2024-04-05T19:58:20Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:33377" http.request.id=5ca99049-633b-4f57-b41c-31fad53460e7 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:47684" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="457.677µs" http.response.status=404 http.response.written=105 +time="2024-04-05T20:06:55Z" 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:36453" http.request.id=0aa57779-ad4d-4c58-a78e-130986b9a218 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:41922" http.request.uri=/ http.request.useragent=Go-http-client/1.1 +time="2024-04-05T20:06:55Z" level=info msg="response completed" go.version=go1.22.2 http.request.host="127.0.0.1:36453" http.request.id=0aa57779-ad4d-4c58-a78e-130986b9a218 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:41922" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="322.428µs" http.response.status=404 http.response.written=105 --- PASS: TestMainHandlerNotGet (0.00s) === RUN TestKeyHandlersInvalidConfiguration -time="2024-04-05T19:58:20Z" level=error msg="500 GET storage not configured" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=error msg="500 GET storage not configured" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=error msg="500 POST storage not configured" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=error msg="500 POST storage not configured" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=error msg="500 GET storage not configured" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=error msg="500 GET storage not configured" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=error msg="500 GET crypto service not configured" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=error msg="500 GET key algorithm not configured" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=error msg="500 POST storage not configured" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=error msg="500 POST storage not configured" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=error msg="500 POST crypto service not configured" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=error msg="500 POST key algorithm not configured" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" 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-05T19:58:20Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.2 -time="2024-04-05T19:58:20Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.2 -time="2024-04-05T19:58:20Z" level=info msg="400 GET no role in request" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=info msg="400 GET no role in request" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.2 -time="2024-04-05T19:58:20Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.2 -time="2024-04-05T19:58:20Z" level=info msg="400 POST no role in request" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=info msg="400 POST no role in request" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.2 +time="2024-04-05T20:06:55Z" level=info msg="400 GET no gun in request" =gun go.version=go1.22.2 +time="2024-04-05T20:06:55Z" level=info msg="400 GET no role in request" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=info msg="400 GET no role in request" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.2 +time="2024-04-05T20:06:55Z" level=info msg="400 POST no gun in request" =gun go.version=go1.22.2 +time="2024-04-05T20:06:55Z" level=info msg="400 POST no role in request" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" 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-05T19:58:20Z" level=info msg="400 GET root key: " go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=info msg="400 GET targets key: " go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=info msg="400 GET targets/a key: " go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=info msg="400 GET invalidrole key: " go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=info msg="400 POST root key: " go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=info msg="400 POST targets key: " go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=info msg="400 POST targets/a key: " go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=info msg="400 POST invalidrole key: " go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=info msg="400 GET root key: " go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=info msg="400 GET targets key: " go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=info msg="400 GET targets/a key: " go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=info msg="400 GET invalidrole key: " go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=info msg="400 POST root key: " go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=info msg="400 POST targets key: " go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=info msg="400 POST targets/a key: " go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=info msg="400 POST invalidrole key: " go.version=go1.22.2 gun=gun --- PASS: TestKeyHandlersInvalidRole (0.00s) === RUN TestGetKeyHandlerCreatesOnce --- PASS: TestGetKeyHandlerCreatesOnce (0.03s) === RUN TestKeyHandlersInvalidKeyAlgo -time="2024-04-05T19:58:20Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun -time="2024-04-05T19:58:20Z" level=error msg="500 POST snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" 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-05T19:58:20Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun --- PASS: TestGetHandlerRoot (0.04s) === RUN TestGetHandlerTimestamp --- PASS: TestGetHandlerTimestamp (0.03s) === RUN TestGetHandlerSnapshot --- PASS: TestGetHandlerSnapshot (0.03s) === RUN TestGetHandler404 -time="2024-04-05T19:58:20Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun --- PASS: TestGetHandler404 (0.00s) === RUN TestGetHandlerNilData -time="2024-04-05T19:58:20Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun +time="2024-04-05T20:06:55Z" level=info msg="404 GET root role" go.version=go1.22.2 gun=gun --- PASS: TestGetHandlerNilData (0.00s) === RUN TestGetHandlerNoStorage -time="2024-04-05T19:58:20Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.22.2 +time="2024-04-05T20:06:55Z" 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-05T19:58:20Z" level=info msg="400 POST error validating update" go.version=go1.22.2 testGUN=gun +time="2024-04-05T20:06:55Z" level=info msg="400 POST error validating update" go.version=go1.22.2 testGUN=gun --- PASS: TestAtomicUpdateNonValidationFailureNotPropagated (0.06s) === RUN TestAtomicUpdateVersionErrorPropagated -time="2024-04-05T19:58:20Z" level=info msg="400 POST old version error" go.version=go1.22.2 testGUN=gun ---- PASS: TestAtomicUpdateVersionErrorPropagated (0.08s) +time="2024-04-05T20:06:55Z" level=info msg="400 POST old version error" go.version=go1.22.2 testGUN=gun +--- PASS: TestAtomicUpdateVersionErrorPropagated (0.07s) === RUN TestGetMaybeServerSignedNoCrypto --- PASS: TestGetMaybeServerSignedNoCrypto (0.00s) === RUN TestGetMaybeServerSignedNoKey @@ -2793,59 +2836,59 @@ === RUN TestValidateRootCanContainOnlyx509KeysWithRightGun --- PASS: TestValidateRootCanContainOnlyx509KeysWithRightGun (0.10s) === RUN TestValidatePrevTimestamp ---- PASS: TestValidatePrevTimestamp (0.10s) +--- PASS: TestValidatePrevTimestamp (0.07s) === RUN TestValidatePreviousTimestampCorrupt -time="2024-04-05T19:58:21Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" +time="2024-04-05T20:06:55Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" --- PASS: TestValidatePreviousTimestampCorrupt (0.07s) === RUN TestValidateGetCurrentTimestampBroken ---- PASS: TestValidateGetCurrentTimestampBroken (0.07s) +--- PASS: TestValidateGetCurrentTimestampBroken (0.10s) === 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) === RUN TestValidateRootRotationWithOldSigs ---- PASS: TestValidateRootRotationWithOldSigs (0.27s) +--- PASS: TestValidateRootRotationWithOldSigs (0.23s) === RUN TestValidateRootRotationMultipleKeysThreshold1 ---- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.16s) +--- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.14s) === RUN TestRootRotationNotSignedWithOldKeysForOldRole ---- PASS: TestRootRotationNotSignedWithOldKeysForOldRole (0.22s) +--- PASS: TestRootRotationNotSignedWithOldKeysForOldRole (0.20s) === RUN TestRootRotationVersionIncrement ---- PASS: TestRootRotationVersionIncrement (0.21s) +--- PASS: TestRootRotationVersionIncrement (0.13s) === RUN TestValidateNoRoot ---- PASS: TestValidateNoRoot (0.06s) +--- PASS: TestValidateNoRoot (0.05s) === RUN TestValidateSnapshotMissingNoSnapshotKey --- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.07s) === RUN TestValidateSnapshotGenerateNoPrev ---- PASS: TestValidateSnapshotGenerateNoPrev (0.09s) +--- PASS: TestValidateSnapshotGenerateNoPrev (0.08s) === RUN TestValidateSnapshotGenerateWithPrev --- PASS: TestValidateSnapshotGenerateWithPrev (0.09s) === RUN TestValidateSnapshotGeneratePrevCorrupt -time="2024-04-05T19:58:23Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" ---- PASS: TestValidateSnapshotGeneratePrevCorrupt (0.08s) +time="2024-04-05T20:06:57Z" 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.07s) === RUN TestValidateSnapshotGenerateNoTargets ---- PASS: TestValidateSnapshotGenerateNoTargets (0.09s) +--- PASS: TestValidateSnapshotGenerateNoTargets (0.07s) === RUN TestValidateSnapshotGenerate ---- PASS: TestValidateSnapshotGenerate (0.09s) +--- PASS: TestValidateSnapshotGenerate (0.08s) === RUN TestValidateRootNoTimestampKey ---- PASS: TestValidateRootNoTimestampKey (0.07s) +--- PASS: TestValidateRootNoTimestampKey (0.06s) === RUN TestValidateRootInvalidTimestampKey ---- PASS: TestValidateRootInvalidTimestampKey (0.07s) +--- PASS: TestValidateRootInvalidTimestampKey (0.06s) === RUN TestValidateRootInvalidTimestampThreshold --- PASS: TestValidateRootInvalidTimestampThreshold (0.08s) === RUN TestValidateRootInvalidZeroThreshold ---- PASS: TestValidateRootInvalidZeroThreshold (0.24s) +--- PASS: TestValidateRootInvalidZeroThreshold (0.21s) === RUN TestValidateRootRoleMissing --- PASS: TestValidateRootRoleMissing (0.05s) === RUN TestValidateTargetsRoleMissing @@ -2855,21 +2898,21 @@ === RUN TestValidateRootSigMissing --- PASS: TestValidateRootSigMissing (0.05s) === RUN TestValidateTargetsSigMissing -time="2024-04-05T19:58:24Z" level=error msg="ErrBadTargets: tuf: data has no signatures" +time="2024-04-05T20:06:58Z" level=error msg="ErrBadTargets: tuf: data has no signatures" --- PASS: TestValidateTargetsSigMissing (0.08s) === RUN TestValidateSnapshotSigMissing ---- PASS: TestValidateSnapshotSigMissing (0.07s) +--- PASS: TestValidateSnapshotSigMissing (0.06s) === RUN TestValidateRootCorrupt ---- PASS: TestValidateRootCorrupt (0.06s) +--- PASS: TestValidateRootCorrupt (0.05s) === RUN TestValidateTargetsCorrupt -time="2024-04-05T19:58:24Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" +time="2024-04-05T20:06:58Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" --- PASS: TestValidateTargetsCorrupt (0.06s) === RUN TestValidateSnapshotCorrupt ---- PASS: TestValidateSnapshotCorrupt (0.07s) +--- PASS: TestValidateSnapshotCorrupt (0.06s) === RUN TestValidateRootModifiedSize ---- PASS: TestValidateRootModifiedSize (0.06s) +--- PASS: TestValidateRootModifiedSize (0.05s) === RUN TestValidateTargetsModifiedSize ---- PASS: TestValidateTargetsModifiedSize (0.08s) +--- PASS: TestValidateTargetsModifiedSize (0.07s) === RUN TestValidateRootModifiedHash --- PASS: TestValidateRootModifiedHash (0.07s) === RUN TestValidateTargetsModifiedHash @@ -2879,20 +2922,20 @@ === RUN TestGenerateSnapshotNoKey --- PASS: TestGenerateSnapshotNoKey (0.06s) === RUN TestLoadTargetsLoadsNothingIfNoUpdates ---- PASS: TestLoadTargetsLoadsNothingIfNoUpdates (0.06s) +--- PASS: TestLoadTargetsLoadsNothingIfNoUpdates (0.05s) === RUN TestValidateTargetsRequiresStoredParent -time="2024-04-05T19:58:24Z" level=error msg="ErrBadTargets: targets must be loaded first" ---- PASS: TestValidateTargetsRequiresStoredParent (0.08s) +time="2024-04-05T20:06:58Z" level=error msg="ErrBadTargets: targets must be loaded first" +--- PASS: TestValidateTargetsRequiresStoredParent (0.07s) === RUN TestValidateTargetsParentInUpdate -time="2024-04-05T19:58:24Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" +time="2024-04-05T20:06:58Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" --- PASS: TestValidateTargetsParentInUpdate (0.08s) === RUN TestValidateTargetsRoleNotInParent -time="2024-04-05T19:58:24Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" -time="2024-04-05T19:58:24Z" level=error msg="ErrBadTargets: targets has already been loaded" -time="2024-04-05T19:58:24Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +time="2024-04-05T20:06:59Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +time="2024-04-05T20:06:59Z" level=error msg="ErrBadTargets: targets has already been loaded" +time="2024-04-05T20:06:59Z" 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.459s +ok github.com/theupdateframework/notary/server/handlers 4.061s === RUN TestSnapshotExpired --- PASS: TestSnapshotExpired (0.00s) === RUN TestSnapshotNotExpired @@ -2900,28 +2943,28 @@ === RUN TestGetSnapshotKeyCreate --- PASS: TestGetSnapshotKeyCreate (0.03s) === RUN TestGetSnapshotKeyCreateWithFailingStore -time="2024-04-05T19:58:26Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2024-04-05T20:07:00Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetSnapshotKeyCreateWithFailingStore (0.00s) === RUN TestGetSnapshotKeyCreateWithCorruptedStore -time="2024-04-05T19:58:26Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve snapshot key ID" +time="2024-04-05T20:07:00Z" 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-05T19:58:26Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" +time="2024-04-05T20:07:00Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" --- PASS: TestGetSnapshotNoPreviousSnapshot (0.03s) === RUN TestGetSnapshotReturnsPreviousSnapshotIfUnexpired ---- PASS: TestGetSnapshotReturnsPreviousSnapshotIfUnexpired (0.03s) +--- PASS: TestGetSnapshotReturnsPreviousSnapshotIfUnexpired (0.02s) === RUN TestGetSnapshotOldSnapshotExpired --- PASS: TestGetSnapshotOldSnapshotExpired (0.05s) === RUN TestCannotMakeNewSnapshotIfNoRoot --- PASS: TestCannotMakeNewSnapshotIfNoRoot (0.03s) === RUN TestCreateSnapshotNoKeyInCrypto ---- PASS: TestCreateSnapshotNoKeyInCrypto (0.06s) +--- PASS: TestCreateSnapshotNoKeyInCrypto (0.05s) PASS -ok github.com/theupdateframework/notary/server/snapshot 0.291s +ok github.com/theupdateframework/notary/server/snapshot 0.268s === RUN TestMemoryUpdateCurrentEmpty --- PASS: TestMemoryUpdateCurrentEmpty (0.00s) === RUN TestMemoryUpdateCurrentVersionCheckOldVersionExists @@ -2947,35 +2990,35 @@ === RUN TestRDBTUFFileJSONUnmarshallingFailure --- PASS: TestRDBTUFFileJSONUnmarshallingFailure (0.00s) === RUN TestSQLUpdateCurrentEmpty ---- PASS: TestSQLUpdateCurrentEmpty (0.20s) +--- PASS: TestSQLUpdateCurrentEmpty (0.18s) === RUN TestSQLUpdateCurrentVersionCheckOldVersionExists ---- PASS: TestSQLUpdateCurrentVersionCheckOldVersionExists (0.10s) +--- PASS: TestSQLUpdateCurrentVersionCheckOldVersionExists (0.09s) === RUN TestSQLUpdateCurrentVersionCheckOldVersionNotExist ---- PASS: TestSQLUpdateCurrentVersionCheckOldVersionNotExist (0.13s) +--- PASS: TestSQLUpdateCurrentVersionCheckOldVersionNotExist (0.07s) === RUN TestSQLUpdateManyNoConflicts ---- PASS: TestSQLUpdateManyNoConflicts (0.13s) +--- PASS: TestSQLUpdateManyNoConflicts (0.11s) === RUN TestSQLUpdateManyConflictRollback ---- PASS: TestSQLUpdateManyConflictRollback (0.08s) +--- PASS: TestSQLUpdateManyConflictRollback (0.07s) === RUN TestSQLDelete ---- PASS: TestSQLDelete (0.13s) +--- PASS: TestSQLDelete (0.12s) === RUN TestSQLDBCheckHealthTableMissing ---- PASS: TestSQLDBCheckHealthTableMissing (0.08s) +--- PASS: TestSQLDBCheckHealthTableMissing (0.06s) === RUN TestSQLDBCheckHealthDBConnectionFail ---- PASS: TestSQLDBCheckHealthDBConnectionFail (0.06s) +--- PASS: TestSQLDBCheckHealthDBConnectionFail (0.05s) === RUN TestSQLDBCheckHealthSucceeds --- PASS: TestSQLDBCheckHealthSucceeds (0.05s) === RUN TestSQLDBGetChecksum ---- PASS: TestSQLDBGetChecksum (0.08s) +--- PASS: TestSQLDBGetChecksum (0.07s) === RUN TestSQLDBGetChecksumNotFound ---- PASS: TestSQLDBGetChecksumNotFound (0.06s) +--- PASS: TestSQLDBGetChecksumNotFound (0.05s) === RUN TestSQLTUFMetaStoreGetCurrent ---- PASS: TestSQLTUFMetaStoreGetCurrent (0.21s) +--- PASS: TestSQLTUFMetaStoreGetCurrent (0.18s) === RUN TestSQLGetChanges ---- PASS: TestSQLGetChanges (0.26s) +--- PASS: TestSQLGetChanges (0.16s) === RUN TestSQLDBGetVersion ---- PASS: TestSQLDBGetVersion (0.08s) +--- PASS: TestSQLDBGetVersion (0.06s) PASS -ok github.com/theupdateframework/notary/server/storage 1.677s +ok github.com/theupdateframework/notary/server/storage 1.345s === RUN TestTimestampExpired --- PASS: TestTimestampExpired (0.00s) === RUN TestTimestampNotExpired @@ -2983,41 +3026,41 @@ === RUN TestGetTimestampKey --- PASS: TestGetTimestampKey (0.03s) === RUN TestGetTimestampNoPreviousTimestamp -time="2024-04-05T19:58:33Z" level=error msg="Failed to unmarshal existing timestamp" +time="2024-04-05T20:07:06Z" level=error msg="Failed to unmarshal existing timestamp" --- PASS: TestGetTimestampNoPreviousTimestamp (0.06s) === RUN TestGetTimestampReturnsPreviousTimestampIfUnexpired ---- PASS: TestGetTimestampReturnsPreviousTimestampIfUnexpired (0.05s) +--- PASS: TestGetTimestampReturnsPreviousTimestampIfUnexpired (0.04s) === RUN TestGetTimestampOldTimestampExpired --- PASS: TestGetTimestampOldTimestampExpired (0.07s) === RUN TestCannotMakeNewTimestampIfNoRootOrSnapshot -time="2024-04-05T19:58:33Z" level=error msg="Failed to create a new timestamp" -time="2024-04-05T19:58:33Z" level=error msg="Failed to create a new timestamp" ---- PASS: TestCannotMakeNewTimestampIfNoRootOrSnapshot (0.06s) +time="2024-04-05T20:07:06Z" level=error msg="Failed to create a new timestamp" +time="2024-04-05T20:07:06Z" level=error msg="Failed to create a new timestamp" +--- PASS: TestCannotMakeNewTimestampIfNoRootOrSnapshot (0.09s) === RUN TestCreateTimestampNoKeyInCrypto -time="2024-04-05T19:58:33Z" level=error msg="Failed to create a new timestamp" ---- PASS: TestCreateTimestampNoKeyInCrypto (0.07s) +time="2024-04-05T20:07:07Z" level=error msg="Failed to create a new timestamp" +--- PASS: TestCreateTimestampNoKeyInCrypto (0.06s) === RUN TestGetTimestampKeyCreateWithFailingStore -time="2024-04-05T19:58:33Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2024-04-05T20:07:07Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetTimestampKeyCreateWithFailingStore (0.00s) === RUN TestGetTimestampKeyCreateWithCorruptedStore -time="2024-04-05T19:58:33Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve timestamp key ID" +time="2024-04-05T20:07:07Z" 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.380s +ok github.com/theupdateframework/notary/server/timestamp 0.411s === RUN TestHealthCheckKMUnhealthy ---- PASS: TestHealthCheckKMUnhealthy (0.00s) +--- PASS: TestHealthCheckKMUnhealthy (0.01s) === RUN TestHealthCheckSignerUnhealthy ---- PASS: TestHealthCheckSignerUnhealthy (0.00s) +--- PASS: TestHealthCheckSignerUnhealthy (0.01s) === RUN TestHealthCheckKMTimeout --- PASS: TestHealthCheckKMTimeout (0.00s) === RUN TestHealthCheckSignerTimeout --- PASS: TestHealthCheckSignerTimeout (0.00s) === RUN TestHealthCheckKMHealthy ---- PASS: TestHealthCheckKMHealthy (0.00s) +--- PASS: TestHealthCheckKMHealthy (0.01s) === RUN TestHealthCheckSignerHealthy --- PASS: TestHealthCheckSignerHealthy (0.00s) === RUN TestHealthCheckKMConnectionDied @@ -3025,33 +3068,31 @@ === RUN TestHealthCheckSignerConnectionDied --- PASS: TestHealthCheckSignerConnectionDied (0.00s) === RUN TestHealthCheckForOverallStatus ---- PASS: TestHealthCheckForOverallStatus (0.00s) +--- PASS: TestHealthCheckForOverallStatus (0.01s) === RUN TestHealthCheckNonexistentService --- PASS: TestHealthCheckNonexistentService (0.00s) === RUN TestGetPrivateKeyAndSignWithExistingKey -time="2024-04-05T19:58:35Z" level=info msg="Sign: Signed message! with KeyID 9635860cdf28bf486beb4197ce6c63a7e502396ff1f97fb27b2694cc6af0a2b2" go.version=go1.22.2 +time="2024-04-05T20:07:08Z" level=info msg="Sign: Signed message! with KeyID 2f7161d6dc20b648220efc9ac61f24b849a9244277f8c73eac289c9af42446a5" go.version=go1.22.2 --- PASS: TestGetPrivateKeyAndSignWithExistingKey (0.04s) === RUN TestCannotSignWithKeyThatDoesntExist -time="2024-04-05T19:58:35Z" level=error msg="Sign: key e0afb25974042efdcc9f11a47be2f7ee0e15c686e5016bda9dc5c1637f8ce581 not found" go.version=go1.22.2 +time="2024-04-05T20:07:08Z" level=error msg="Sign: key 545be155cdb12546056a4300f3b96174c656ecaff0e45eb412532fbf3cdb3953 not found" go.version=go1.22.2 --- PASS: TestCannotSignWithKeyThatDoesntExist (0.01s) === RUN TestCryptoSignerInterfaceBehavior -time="2024-04-05T19:58:35Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.22.2 -time="2024-04-05T19:58:35Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.22.2 -time="2024-04-05T19:58:35Z" level=info msg="CreateKey: Created KeyID 24e5733824268bdab705b81e06beff4083db832ff8ff31e93f1717e0107688da" go.version=go1.22.2 -time="2024-04-05T19:58:35Z" level=info msg="CreateKey: Created KeyID 9c156866c1e8fd7cd259425456e4342ad4c39b4fd1bde683c12f1bb9e2cf6cef" go.version=go1.22.2 -time="2024-04-05T19:58:35Z" level=error msg="GetKeyInfo: key 24e5733824268bdab705b81e06beff4083db832ff8ff31e93f1717e0107688da not found" go.version=go1.22.2 -time="2024-04-05T19:58:35Z" level=error msg="GetKeyInfo: key 9c156866c1e8fd7cd259425456e4342ad4c39b4fd1bde683c12f1bb9e2cf6cef not found" go.version=go1.22.2 ---- PASS: TestCryptoSignerInterfaceBehavior (0.06s) +time="2024-04-05T20:07:08Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.22.2 +time="2024-04-05T20:07:08Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.22.2 +time="2024-04-05T20:07:08Z" level=info msg="CreateKey: Created KeyID 9ff7a736b2cd1b294be8bb25d868846033ae8548bd5d5a45bb1445a2043a889e" go.version=go1.22.2 +time="2024-04-05T20:07:08Z" level=info msg="CreateKey: Created KeyID b47be053167c06bad2b9d1b975889bb412dd56902a42bc8d3970519a5cc350fb" go.version=go1.22.2 +time="2024-04-05T20:07:08Z" level=error msg="GetKeyInfo: key 9ff7a736b2cd1b294be8bb25d868846033ae8548bd5d5a45bb1445a2043a889e not found" go.version=go1.22.2 +time="2024-04-05T20:07:08Z" level=error msg="GetKeyInfo: key b47be053167c06bad2b9d1b975889bb412dd56902a42bc8d3970519a5cc350fb not found" go.version=go1.22.2 +--- PASS: TestCryptoSignerInterfaceBehavior (0.04s) PASS -ok github.com/theupdateframework/notary/signer 0.142s -? 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.133s === RUN TestGetSuccessPopulatesCache ---- PASS: TestGetSuccessPopulatesCache (0.05s) +--- PASS: TestGetSuccessPopulatesCache (0.02s) === RUN TestAddKeyPopulatesCacheIfSuccessful ---- PASS: TestAddKeyPopulatesCacheIfSuccessful (0.03s) +--- PASS: TestAddKeyPopulatesCacheIfSuccessful (0.01s) === RUN TestDeleteKeyRemovesKeyFromCache ---- PASS: TestDeleteKeyRemovesKeyFromCache (0.03s) +--- PASS: TestDeleteKeyRemovesKeyFromCache (0.02s) === RUN TestRDBPrivateKeyJSONUnmarshalling --- PASS: TestRDBPrivateKeyJSONUnmarshalling (0.00s) === RUN TestRDBPrivateKeyJSONUnmarshallingFailure @@ -3060,30 +3101,30 @@ `nodb` is not officially supported, running under compatibility mode. --- PASS: TestNewSQLKeyDBStorePropagatesDBError (0.00s) === RUN TestSQLDBHealthCheckMissingTable ---- PASS: TestSQLDBHealthCheckMissingTable (0.09s) +--- PASS: TestSQLDBHealthCheckMissingTable (0.06s) === RUN TestSQLDBHealthCheckNoConnection ---- PASS: TestSQLDBHealthCheckNoConnection (0.06s) +--- 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-05 19:58:39]  UNIQUE constraint failed: private_keys.key_id  ---- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.36s) +[2024-04-05 20:07:11]  UNIQUE constraint failed: private_keys.key_id  +--- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.21s) === RUN TestSQLCreateDelete ---- PASS: TestSQLCreateDelete (0.45s) +--- PASS: TestSQLCreateDelete (0.24s) === RUN TestSQLKeyRotation ---- PASS: TestSQLKeyRotation (0.47s) +--- PASS: TestSQLKeyRotation (0.33s) === RUN TestSQLSigningMarksKeyActive -time="2024-04-05T19:58:41Z" level=error msg="Key 68c7a7064e2e1cb0319dd87873b264cdee354098e4e4aff5aa26bddc9da021d8 was just used to sign hash successful, db closed, error when trying to mark key as active: sql: database is closed" +time="2024-04-05T20:07:12Z" level=error msg="Key caaaed4db7e5d326b6384ea74d9015218a7c23cd3dd016aa3914f62572a2f3b6 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-05 19:58:41]  sql: database is closed  ---- PASS: TestSQLSigningMarksKeyActive (0.79s) +[2024-04-05 20:07:12]  sql: database is closed  +--- PASS: TestSQLSigningMarksKeyActive (0.46s) === RUN TestSQLCreateKey ---- PASS: TestSQLCreateKey (0.74s) +--- PASS: TestSQLCreateKey (0.39s) === RUN TestSQLUnimplementedInterfaceBehavior ---- PASS: TestSQLUnimplementedInterfaceBehavior (0.20s) +--- PASS: TestSQLUnimplementedInterfaceBehavior (0.13s) PASS -ok github.com/theupdateframework/notary/signer/keydbstore 3.318s +ok github.com/theupdateframework/notary/signer/keydbstore 1.946s === RUN TestSet --- PASS: TestSet (0.00s) === RUN TestSetWithNoParentDirectory @@ -3093,7 +3134,7 @@ === RUN TestGetSized --- PASS: TestGetSized (0.00s) === RUN TestGetSizedSet ---- PASS: TestGetSizedSet (0.01s) +--- PASS: TestGetSizedSet (0.00s) === RUN TestRemove --- PASS: TestRemove (0.00s) === RUN TestRemoveAll @@ -3109,25 +3150,25 @@ === RUN TestGetPathProtection --- PASS: TestGetPathProtection (0.00s) === RUN TestGetData ---- PASS: TestGetData (0.01s) +--- PASS: TestGetData (0.00s) === RUN TestCreateDirectory --- PASS: TestCreateDirectory (0.00s) === RUN TestCreatePrivateDirectory --- PASS: TestCreatePrivateDirectory (0.00s) === RUN TestFileStoreConsistency ---- PASS: TestFileStoreConsistency (0.01s) +--- PASS: TestFileStoreConsistency (0.00s) === RUN TestHTTPStoreGetSized ---- PASS: TestHTTPStoreGetSized (0.02s) +--- PASS: TestHTTPStoreGetSized (0.00s) === RUN TestHTTPStoreGetAllMeta ---- PASS: TestHTTPStoreGetAllMeta (0.01s) +--- PASS: TestHTTPStoreGetAllMeta (0.00s) === RUN TestSetSingleAndSetMultiMeta ---- PASS: TestSetSingleAndSetMultiMeta (0.01s) +--- PASS: TestSetSingleAndSetMultiMeta (0.00s) === RUN Test404Error --- PASS: Test404Error (0.00s) === RUN Test50XErrors ---- PASS: Test50XErrors (0.02s) +--- PASS: Test50XErrors (0.01s) === RUN Test400Error ---- PASS: Test400Error (0.01s) +--- PASS: Test400Error (0.00s) === RUN TestTranslateErrorsParse400Errors --- PASS: TestTranslateErrorsParse400Errors (0.00s) === RUN TestTranslateErrorsWhenCannotParse400 @@ -3139,9 +3180,9 @@ === RUN TestHTTPStoreRotateKey --- PASS: TestHTTPStoreRotateKey (0.00s) === RUN TestHTTPStoreGetKey ---- PASS: TestHTTPStoreGetKey (0.01s) +--- PASS: TestHTTPStoreGetKey (0.00s) === RUN TestHTTPStoreGetRotateKeySizeLimited ---- PASS: TestHTTPStoreGetRotateKeySizeLimited (0.04s) +--- PASS: TestHTTPStoreGetRotateKeySizeLimited (0.02s) === RUN TestHTTPOffline --- PASS: TestHTTPOffline (0.00s) === RUN TestErrServerUnavailable @@ -3162,8 +3203,7 @@ === RUN TestMemoryStoreMetadata --- PASS: TestMemoryStoreMetadata (0.00s) PASS -ok github.com/theupdateframework/notary/storage 0.193s -? github.com/theupdateframework/notary/storage/rethinkdb [no test files] +ok github.com/theupdateframework/notary/storage 0.080s === RUN TestExportKeys --- PASS: TestExportKeys (0.00s) === RUN TestExportKeysByGUN @@ -3187,116 +3227,115 @@ === RUN TestImportKeys2InOneFileNoPath --- PASS: TestImportKeys2InOneFileNoPath (0.02s) === RUN TestEncryptedKeyImportFail -time="2024-04-05T19:58:46Z" 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-05T20:07:16Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" --- PASS: TestEncryptedKeyImportFail (0.02s) === RUN TestEncryptedKeyImportSuccess --- PASS: TestEncryptedKeyImportSuccess (0.02s) === RUN TestEncryption ---- PASS: TestEncryption (0.03s) +--- PASS: TestEncryption (0.02s) === RUN TestAddKey ---- PASS: TestAddKey (0.06s) +--- PASS: TestAddKey (0.05s) === RUN TestKeyStoreInternalState ---- PASS: TestKeyStoreInternalState (0.03s) +--- PASS: TestKeyStoreInternalState (0.02s) === RUN TestGet ---- PASS: TestGet (0.04s) +--- PASS: TestGet (0.03s) === RUN TestGetLegacyKey --- PASS: TestGetLegacyKey (0.00s) === RUN TestListKeys ---- PASS: TestListKeys (0.07s) +--- PASS: TestListKeys (0.06s) === RUN TestAddGetKeyMemStore --- PASS: TestAddGetKeyMemStore (0.01s) === RUN TestAddGetKeyInfoMemStore ---- PASS: TestAddGetKeyInfoMemStore (0.04s) +--- PASS: TestAddGetKeyInfoMemStore (0.03s) === RUN TestGetDecryptedWithTamperedCipherText -time="2024-04-05T19:58:46Z" level=error msg="PEM block is empty" +time="2024-04-05T20:07:16Z" 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) +--- PASS: TestRemoveKey (0.05s) === RUN TestKeysAreCached --- PASS: TestKeysAreCached (0.02s) PASS -ok github.com/theupdateframework/notary/trustmanager 0.642s -? github.com/theupdateframework/notary/trustmanager/yubikey [no test files] +ok github.com/theupdateframework/notary/trustmanager 0.588s === RUN TestRemoteStore -time="2024-04-05T19:58:49Z" level=info msg="listing files from localhost:9888" -time="2024-04-05T19:58:49Z" level=info msg="listing files from localhost:9888" +time="2024-04-05T20:07:18Z" level=info msg="listing files from localhost:9888" +time="2024-04-05T20:07:18Z" level=info msg="listing files from localhost:9888" --- PASS: TestRemoteStore (0.23s) === RUN TestErrors -time="2024-04-05T19:58:49Z" level=error msg="failed to store: test error" ---- PASS: TestErrors (0.23s) +time="2024-04-05T20:07:18Z" level=error msg="failed to store: test error" +--- PASS: TestErrors (0.18s) === RUN TestNewGRPCStorage --- PASS: TestNewGRPCStorage (0.00s) === RUN TestGRPCStorage --- PASS: TestGRPCStorage (0.00s) PASS -ok github.com/theupdateframework/notary/trustmanager/remoteks 0.469s +ok github.com/theupdateframework/notary/trustmanager/remoteks 0.453s === RUN TestWildcardMatch --- PASS: TestWildcardMatch (0.00s) === RUN TestValidateRoot -2024/04/05 19:58:54 [INFO] generate received request -2024/04/05 19:58:54 [INFO] received CSR -2024/04/05 19:58:54 [INFO] generating key: ecdsa-256 -2024/04/05 19:58:54 [INFO] encoded CSR -2024/04/05 19:58:54 [INFO] signed certificate with serial number 339033735859897058710512590897590938816780008574 -2024/04/05 19:58:54 [INFO] received CSR -2024/04/05 19:58:54 [INFO] generating key: ecdsa-256 -2024/04/05 19:58:54 [INFO] encoded CSR -2024/04/05 19:58:54 [INFO] signed certificate with serial number 379569468411561734135816935083419134216823176557 -2024/04/05 19:58:54 [INFO] received CSR -2024/04/05 19:58:54 [INFO] generating key: ecdsa-256 -2024/04/05 19:58:54 [INFO] encoded CSR -2024/04/05 19:58:54 [INFO] signed certificate with serial number 330598532063442592212801487629728510244304292300 ---- PASS: TestValidateRoot (0.28s) +2024/04/05 20:07:23 [INFO] generate received request +2024/04/05 20:07:23 [INFO] received CSR +2024/04/05 20:07:23 [INFO] generating key: ecdsa-256 +2024/04/05 20:07:23 [INFO] encoded CSR +2024/04/05 20:07:23 [INFO] signed certificate with serial number 396435729264507263630479941914784434242076494381 +2024/04/05 20:07:23 [INFO] received CSR +2024/04/05 20:07:23 [INFO] generating key: ecdsa-256 +2024/04/05 20:07:23 [INFO] encoded CSR +2024/04/05 20:07:23 [INFO] signed certificate with serial number 221029680022421835233035234361280008084944351979 +2024/04/05 20:07:23 [INFO] received CSR +2024/04/05 20:07:23 [INFO] generating key: ecdsa-256 +2024/04/05 20:07:23 [INFO] encoded CSR +2024/04/05 20:07:23 [INFO] signed certificate with serial number 190903047098772756676081708128865451092260946761 +--- PASS: TestValidateRoot (0.15s) === RUN TestValidateRootWithoutTOFUS --- PASS: TestValidateRootWithoutTOFUS (0.00s) === RUN TestValidateRootWithPinnedCert ---- PASS: TestValidateRootWithPinnedCert (0.02s) +--- PASS: TestValidateRootWithPinnedCert (0.01s) === RUN TestValidateRootWithPinnedCertAndIntermediates ---- PASS: TestValidateRootWithPinnedCertAndIntermediates (0.07s) +--- PASS: TestValidateRootWithPinnedCertAndIntermediates (0.08s) === RUN TestValidateRootFailuresWithPinnedCert ---- PASS: TestValidateRootFailuresWithPinnedCert (0.01s) +--- PASS: TestValidateRootFailuresWithPinnedCert (0.03s) === RUN TestValidateRootWithPinnedCA ---- PASS: TestValidateRootWithPinnedCA (0.19s) +--- PASS: TestValidateRootWithPinnedCA (0.18s) === RUN TestValidateSuccessfulRootRotation ---- PASS: TestValidateSuccessfulRootRotation (0.30s) +--- PASS: TestValidateSuccessfulRootRotation (0.18s) === RUN TestValidateRootRotationMissingOrigSig ---- PASS: TestValidateRootRotationMissingOrigSig (0.25s) +--- PASS: TestValidateRootRotationMissingOrigSig (0.14s) === RUN TestValidateRootRotationMissingNewSig ---- PASS: TestValidateRootRotationMissingNewSig (0.17s) +--- PASS: TestValidateRootRotationMissingNewSig (0.13s) === RUN TestValidateRootRotationTrustPinning ---- PASS: TestValidateRootRotationTrustPinning (0.18s) +--- PASS: TestValidateRootRotationTrustPinning (0.13s) === RUN TestValidateRootRotationTrustPinningInvalidCA ---- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.17s) +--- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.10s) === RUN TestParsePEMPublicKey -time="2024-04-05T19:58:56Z" level=warning msg="certificate with CN notary is near expiry" +time="2024-04-05T20:07:24Z" level=warning msg="certificate with CN notary is near expiry" --- PASS: TestParsePEMPublicKey (0.02s) === RUN TestCheckingCertExpiry -time="2024-04-05T19:58:56Z" level=warning msg="certificate with CN notary is near expiry" ---- PASS: TestCheckingCertExpiry (0.04s) +time="2024-04-05T20:07:24Z" level=warning msg="certificate with CN notary is near expiry" +--- PASS: TestCheckingCertExpiry (0.03s) === RUN TestValidateRootWithExpiredIntermediate ---- PASS: TestValidateRootWithExpiredIntermediate (0.04s) +--- PASS: TestValidateRootWithExpiredIntermediate (0.02s) === RUN TestCheckingWildcardCert ---- PASS: TestCheckingWildcardCert (0.04s) +--- PASS: TestCheckingWildcardCert (0.02s) === RUN TestWildcardMatching --- PASS: TestWildcardMatching (0.00s) PASS -ok github.com/theupdateframework/notary/trustpinning 1.813s +ok github.com/theupdateframework/notary/trustpinning 1.263s === RUN TestInitSnapshotNoTargets ---- PASS: TestInitSnapshotNoTargets (0.04s) +--- PASS: TestInitSnapshotNoTargets (0.03s) === RUN TestInitRepo --- PASS: TestInitRepo (0.02s) === RUN TestUpdateDelegations --- PASS: TestUpdateDelegations (0.00s) === RUN TestPurgeDelegationsKeyFromTop -time="2024-04-05T19:58:55Z" 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-05T19:58:55Z" 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-05T20:07:23Z" 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-05T20:07:23Z" 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-05T19:58:55Z" 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-05T20:07:23Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" --- PASS: TestPurgeDelegationsKeyFromDeep (0.00s) === RUN TestPurgeDelegationsKeyBadWildRole --- PASS: TestPurgeDelegationsKeyBadWildRole (0.00s) @@ -3309,7 +3348,7 @@ === RUN TestUpdateDelegationsRoleThatIsMissingDelegationKey --- PASS: TestUpdateDelegationsRoleThatIsMissingDelegationKey (0.00s) === RUN TestUpdateDelegationsNotEnoughKeys -time="2024-04-05T19:58:55Z" 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-05T20:07:23Z" level=warning msg="role targets/role has fewer keys than its threshold of 2; it will not be usable until keys are added to it" --- PASS: TestUpdateDelegationsNotEnoughKeys (0.00s) === RUN TestUpdateDelegationsAddKeyToRole --- PASS: TestUpdateDelegationsAddKeyToRole (0.00s) @@ -3376,41 +3415,41 @@ === RUN TestGetDelegationRoleKeyMissing --- PASS: TestGetDelegationRoleKeyMissing (0.00s) === RUN TestSignRootOldKeyCertExists ---- PASS: TestSignRootOldKeyCertExists (0.12s) +--- PASS: TestSignRootOldKeyCertExists (0.11s) === RUN TestSignRootOldKeyCertMissing ---- PASS: TestSignRootOldKeyCertMissing (0.09s) +--- PASS: TestSignRootOldKeyCertMissing (0.08s) === RUN TestRootKeyRotation ---- PASS: TestRootKeyRotation (0.38s) +--- PASS: TestRootKeyRotation (0.34s) === RUN TestBuilderLoadsValidRolesOnly ---- PASS: TestBuilderLoadsValidRolesOnly (0.10s) +--- PASS: TestBuilderLoadsValidRolesOnly (0.07s) === RUN TestBuilderOnlyAcceptsRootFirstWhenLoading --- PASS: TestBuilderOnlyAcceptsRootFirstWhenLoading (0.01s) === RUN TestBuilderOnlyAcceptsDelegationsAfterParent ---- PASS: TestBuilderOnlyAcceptsDelegationsAfterParent (0.03s) +--- PASS: TestBuilderOnlyAcceptsDelegationsAfterParent (0.02s) === RUN TestMarkingIsValid ---- PASS: TestMarkingIsValid (0.08s) +--- PASS: TestMarkingIsValid (0.04s) === RUN TestBuilderLoadInvalidDelegations ---- PASS: TestBuilderLoadInvalidDelegations (0.10s) +--- PASS: TestBuilderLoadInvalidDelegations (0.06s) === RUN TestBuilderLoadInvalidDelegationsOldVersion ---- PASS: TestBuilderLoadInvalidDelegationsOldVersion (0.09s) +--- PASS: TestBuilderLoadInvalidDelegationsOldVersion (0.06s) === RUN TestBuilderAcceptRoleOnce ---- PASS: TestBuilderAcceptRoleOnce (0.04s) +--- PASS: TestBuilderAcceptRoleOnce (0.03s) === RUN TestBuilderStopsAcceptingOrProducingDataOnceDone ---- PASS: TestBuilderStopsAcceptingOrProducingDataOnceDone (0.03s) +--- PASS: TestBuilderStopsAcceptingOrProducingDataOnceDone (0.02s) === RUN TestGenerateSnapshotInvalidOperations ---- PASS: TestGenerateSnapshotInvalidOperations (0.68s) +--- PASS: TestGenerateSnapshotInvalidOperations (0.32s) === RUN TestGenerateTimestampInvalidOperations ---- PASS: TestGenerateTimestampInvalidOperations (0.30s) +--- PASS: TestGenerateTimestampInvalidOperations (0.40s) === RUN TestGetConsistentInfo ---- PASS: TestGetConsistentInfo (0.07s) +--- PASS: TestGetConsistentInfo (0.10s) === RUN TestTimestampPreAndPostChecksumming ---- PASS: TestTimestampPreAndPostChecksumming (0.12s) +--- PASS: TestTimestampPreAndPostChecksumming (0.10s) === RUN TestSnapshotLoadedFirstChecksumsOthers ---- PASS: TestSnapshotLoadedFirstChecksumsOthers (0.11s) +--- PASS: TestSnapshotLoadedFirstChecksumsOthers (0.09s) === RUN TestSnapshotLoadedAfterChecksumsOthersRetroactively ---- PASS: TestSnapshotLoadedAfterChecksumsOthersRetroactively (0.24s) +--- PASS: TestSnapshotLoadedAfterChecksumsOthersRetroactively (0.27s) PASS -ok github.com/theupdateframework/notary/tuf 2.867s +ok github.com/theupdateframework/notary/tuf 2.343s === RUN TestMergeStrSlicesExclusive --- PASS: TestMergeStrSlicesExclusive (0.00s) === RUN TestMergeStrSlicesOverlap @@ -3458,9 +3497,9 @@ === RUN TestRootFromSignedCopiesSignatures --- PASS: TestRootFromSignedCopiesSignatures (0.00s) === RUN TestRootFromSignedValidatesRoleData ---- PASS: TestRootFromSignedValidatesRoleData (0.02s) +--- PASS: TestRootFromSignedValidatesRoleData (0.01s) === RUN TestRootFromSignedValidatesRoleType ---- PASS: TestRootFromSignedValidatesRoleType (0.01s) +--- PASS: TestRootFromSignedValidatesRoleType (0.00s) === RUN TestRootFromSignedValidatesVersion --- PASS: TestRootFromSignedValidatesVersion (0.00s) === RUN TestSnapshotToSignedMarshalsSignedPortionWithCanonicalJSON @@ -3478,7 +3517,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 @@ -3500,9 +3539,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 @@ -3544,60 +3583,60 @@ === RUN TestFileMetaEquals --- PASS: TestFileMetaEquals (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/data 0.100s +ok github.com/theupdateframework/notary/tuf/data 0.052s === RUN TestListKeys ---- PASS: TestListKeys (0.05s) +--- PASS: TestListKeys (0.03s) === RUN TestGetKeys --- PASS: TestGetKeys (0.00s) === RUN TestBasicSign --- PASS: TestBasicSign (0.00s) === RUN TestReSign ---- PASS: TestReSign (0.01s) +--- PASS: TestReSign (0.00s) === RUN TestMultiSign ---- PASS: TestMultiSign (0.02s) +--- PASS: TestMultiSign (0.01s) === RUN TestSignReturnsNoSigs --- PASS: TestSignReturnsNoSigs (0.00s) === RUN TestSignWithX509 ---- PASS: TestSignWithX509 (0.03s) +--- PASS: TestSignWithX509 (0.01s) === RUN TestSignRemovesValidSigByInvalidKey ---- PASS: TestSignRemovesValidSigByInvalidKey (0.00s) +--- PASS: TestSignRemovesValidSigByInvalidKey (0.01s) === RUN TestSignRemovesInvalidSig ---- PASS: TestSignRemovesInvalidSig (0.00s) +--- PASS: TestSignRemovesInvalidSig (0.01s) === RUN TestSignMinSignatures --- PASS: TestSignMinSignatures (0.00s) === RUN TestSignFailingKeys ---- PASS: TestSignFailingKeys (0.02s) +--- PASS: TestSignFailingKeys (0.03s) === RUN TestErrInsufficientSignaturesMessaging --- PASS: TestErrInsufficientSignaturesMessaging (0.00s) === RUN TestRSAPSSVerifier --- PASS: TestRSAPSSVerifier (0.03s) === RUN TestRSAPSSx509Verifier ---- PASS: TestRSAPSSx509Verifier (0.02s) +--- PASS: TestRSAPSSx509Verifier (0.01s) === RUN TestRSAPSSVerifierWithInvalidKeyType --- PASS: TestRSAPSSVerifierWithInvalidKeyType (0.00s) === RUN TestRSAPSSVerifierWithInvalidKeyLength ---- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.25s) +--- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.13s) === RUN TestRSAPSSVerifierWithInvalidKey --- PASS: TestRSAPSSVerifierWithInvalidKey (0.00s) === RUN TestRSAPSSVerifierWithInvalidSignature ---- PASS: TestRSAPSSVerifierWithInvalidSignature (0.04s) +--- PASS: TestRSAPSSVerifierWithInvalidSignature (0.02s) === RUN TestRSAPKCS1v15Verifier ---- PASS: TestRSAPKCS1v15Verifier (0.06s) +--- PASS: TestRSAPKCS1v15Verifier (0.02s) === RUN TestRSAPKCS1v15x509Verifier ---- PASS: TestRSAPKCS1v15x509Verifier (0.02s) +--- PASS: TestRSAPKCS1v15x509Verifier (0.01s) === RUN TestRSAPKCS1v15VerifierWithInvalidKeyType --- PASS: TestRSAPKCS1v15VerifierWithInvalidKeyType (0.00s) === RUN TestRSAPKCS1v15VerifierWithInvalidKey --- PASS: TestRSAPKCS1v15VerifierWithInvalidKey (0.00s) === RUN TestRSAPKCS1v15VerifierWithInvalidSignature -time="2024-04-05T19:59:01Z" level=error msg="Failed verification: crypto/rsa: verification error" ---- PASS: TestRSAPKCS1v15VerifierWithInvalidSignature (0.03s) +time="2024-04-05T20:07:28Z" level=error msg="Failed verification: crypto/rsa: verification error" +--- PASS: TestRSAPKCS1v15VerifierWithInvalidSignature (0.02s) === RUN TestECDSAVerifier --- PASS: TestECDSAVerifier (0.01s) === RUN TestECDSAVerifierOtherCurves ---- PASS: TestECDSAVerifierOtherCurves (0.88s) +--- PASS: TestECDSAVerifierOtherCurves (0.60s) === RUN TestECDSAx509Verifier ---- PASS: TestECDSAx509Verifier (0.01s) +--- PASS: TestECDSAx509Verifier (0.00s) === RUN TestECDSAVerifierWithInvalidKeyType --- PASS: TestECDSAVerifierWithInvalidKeyType (0.00s) === RUN TestECDSAVerifierWithInvalidKey @@ -3633,34 +3672,32 @@ === RUN TestVerifyVersion --- PASS: TestVerifyVersion (0.00s) === RUN TestVerifyExpiry -time="2024-04-05T19:59:02Z" level=error msg="Metadata for root expired" +time="2024-04-05T20:07:29Z" 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.555s -? 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.029s === RUN TestNewSwizzler ---- PASS: TestNewSwizzler (0.17s) +--- PASS: TestNewSwizzler (0.08s) === RUN TestSwizzlerSetInvalidJSON ---- PASS: TestSwizzlerSetInvalidJSON (0.15s) +--- PASS: TestSwizzlerSetInvalidJSON (0.06s) === RUN TestSwizzlerAddExtraSpace ---- PASS: TestSwizzlerAddExtraSpace (0.18s) +--- PASS: TestSwizzlerAddExtraSpace (0.07s) === RUN TestSwizzlerSetInvalidSigned ---- PASS: TestSwizzlerSetInvalidSigned (0.10s) +--- PASS: TestSwizzlerSetInvalidSigned (0.06s) === RUN TestSwizzlerSetInvalidSignedMeta --- PASS: TestSwizzlerSetInvalidSignedMeta (0.07s) === RUN TestSwizzlerSetInvalidMetadataType --- PASS: TestSwizzlerSetInvalidMetadataType (0.07s) === RUN TestSwizzlerInvalidateMetadataSignatures ---- PASS: TestSwizzlerInvalidateMetadataSignatures (0.07s) +--- PASS: TestSwizzlerInvalidateMetadataSignatures (0.11s) === RUN TestSwizzlerRemoveMetadata ---- PASS: TestSwizzlerRemoveMetadata (0.07s) +--- PASS: TestSwizzlerRemoveMetadata (0.06s) === RUN TestSwizzlerSignMetadataWithInvalidKey ---- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.09s) +--- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.07s) === RUN TestSwizzlerOffsetMetadataVersion --- PASS: TestSwizzlerOffsetMetadataVersion (0.07s) === RUN TestSwizzlerExpireMetadata @@ -3668,35 +3705,35 @@ === RUN TestSwizzlerSetThresholdBaseRole --- PASS: TestSwizzlerSetThresholdBaseRole (0.08s) === RUN TestSwizzlerSetThresholdDelegatedRole ---- PASS: TestSwizzlerSetThresholdDelegatedRole (0.07s) +--- PASS: TestSwizzlerSetThresholdDelegatedRole (0.08s) === RUN TestSwizzlerChangeRootKey ---- PASS: TestSwizzlerChangeRootKey (0.09s) +--- PASS: TestSwizzlerChangeRootKey (0.14s) === RUN TestSwizzlerUpdateSnapshotHashesSpecifiedRoles ---- PASS: TestSwizzlerUpdateSnapshotHashesSpecifiedRoles (0.08s) +--- PASS: TestSwizzlerUpdateSnapshotHashesSpecifiedRoles (0.13s) === RUN TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles ---- PASS: TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles (0.08s) +--- PASS: TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles (0.09s) === RUN TestSwizzlerUpdateTimestamp --- PASS: TestSwizzlerUpdateTimestamp (0.08s) === RUN TestMissingSigningKey --- PASS: TestMissingSigningKey (0.07s) === RUN TestSwizzlerMutateRoot ---- PASS: TestSwizzlerMutateRoot (0.08s) +--- PASS: TestSwizzlerMutateRoot (0.07s) === RUN TestSwizzlerMutateTimestamp ---- PASS: TestSwizzlerMutateTimestamp (0.07s) +--- PASS: TestSwizzlerMutateTimestamp (0.08s) === RUN TestSwizzlerMutateSnapshot ---- PASS: TestSwizzlerMutateSnapshot (0.08s) +--- PASS: TestSwizzlerMutateSnapshot (0.07s) === RUN TestSwizzlerMutateTargets ---- PASS: TestSwizzlerMutateTargets (0.07s) +--- PASS: TestSwizzlerMutateTargets (0.08s) === RUN TestSwizzlerRotateKeyBaseRole ---- PASS: TestSwizzlerRotateKeyBaseRole (0.10s) +--- PASS: TestSwizzlerRotateKeyBaseRole (0.16s) === RUN TestSwizzlerRotateKeyDelegationRole --- PASS: TestSwizzlerRotateKeyDelegationRole (0.07s) PASS -ok github.com/theupdateframework/notary/tuf/testutils 2.184s +ok github.com/theupdateframework/notary/tuf/testutils 1.997s === RUN TestConvertTUFKeyToPKCS8 ---- PASS: TestConvertTUFKeyToPKCS8 (0.10s) +--- PASS: TestConvertTUFKeyToPKCS8 (0.09s) === RUN TestParsePKCS8ToTufKey ---- PASS: TestParsePKCS8ToTufKey (0.62s) +--- PASS: TestParsePKCS8ToTufKey (0.57s) === RUN TestPEMtoPEM --- PASS: TestPEMtoPEM (0.00s) === RUN TestRoleListLen @@ -3742,25 +3779,25 @@ === RUN TestNewCertificate --- PASS: TestNewCertificate (0.00s) === RUN TestKeyOperations ---- PASS: TestKeyOperations (0.09s) +--- PASS: TestKeyOperations (0.08s) === RUN TestRSAX509PublickeyID ---- PASS: TestRSAX509PublickeyID (0.00s) +--- PASS: TestRSAX509PublickeyID (0.01s) === RUN TestECDSAX509PublickeyID ---- PASS: TestECDSAX509PublickeyID (0.02s) +--- PASS: TestECDSAX509PublickeyID (0.01s) === RUN TestExtractPrivateKeyAttributes --- PASS: TestExtractPrivateKeyAttributes (0.00s) === RUN TestParsePEMPrivateKeyLegacy ---- PASS: TestParsePEMPrivateKeyLegacy (0.01s) +--- PASS: TestParsePEMPrivateKeyLegacy (0.00s) === RUN TestValidateCertificateWithSHA1 --- PASS: TestValidateCertificateWithSHA1 (0.02s) === RUN TestValidateCertificateWithExpiredCert --- PASS: TestValidateCertificateWithExpiredCert (0.02s) === RUN TestValidateCertificateWithInvalidExpiry ---- PASS: TestValidateCertificateWithInvalidExpiry (0.02s) +--- PASS: TestValidateCertificateWithInvalidExpiry (0.01s) === RUN TestValidateCertificateWithShortKey ---- PASS: TestValidateCertificateWithShortKey (0.42s) +--- PASS: TestValidateCertificateWithShortKey (0.16s) PASS -ok github.com/theupdateframework/notary/tuf/utils 1.336s +ok github.com/theupdateframework/notary/tuf/utils 1.008s === RUN TestNewSerializableErrorNonValidationError --- PASS: TestNewSerializableErrorNonValidationError (0.00s) === RUN TestNewSerializableErrorValidationError @@ -3776,8 +3813,7 @@ === RUN TestUnmarshalInvalidJSON --- PASS: TestUnmarshalInvalidJSON (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/validation 0.008s -? github.com/theupdateframework/notary/version [no test files] +ok github.com/theupdateframework/notary/tuf/validation 0.025s === RUN TestSetSignalTrap --- PASS: TestSetSignalTrap (0.00s) === RUN TestLogLevelSignalHandle @@ -3877,7 +3913,7 @@ === RUN TestDoAuthWildcardImage --- PASS: TestDoAuthWildcardImage (0.00s) PASS -ok github.com/theupdateframework/notary/utils 0.034s +ok github.com/theupdateframework/notary/utils 0.057s 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 @@ -3905,9 +3941,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 @@ -3927,12 +3963,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/9903/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/9903/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/7412 and its subdirectories -I: Current time: Fri Apr 5 08:00:03 -12 2024 -I: pbuilder-time-stamp: 1712347203 +I: removing directory /srv/workspace/pbuilder/9903 and its subdirectories +I: Current time: Sat Apr 6 10:09:03 +14 2024 +I: pbuilder-time-stamp: 1712347743