Diff of the two buildlogs: -- --- b1/build.log 2024-11-06 04:47:43.417331173 +0000 +++ b2/build.log 2024-11-06 05:08:09.261601990 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Tue Nov 5 16:31:08 -12 2024 -I: pbuilder-time-stamp: 1730867468 +I: Current time: Wed Nov 6 18:49:42 +14 2024 +I: pbuilder-time-stamp: 1730868582 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/unstable-reproducible-base.tgz] I: copying local configuration @@ -32,52 +32,84 @@ dpkg-source: info: applying fix-newer-cloudflare-cfssl.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/2979/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/14887/tmp/hooks/D01_modify_environment starting +debug: Running on virt64c. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 Nov 6 04:50 /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/14887/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/14887/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='armhf' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=3 ' - DISTRIBUTION='unstable' - HOME='/root' - HOST_ARCH='armhf' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="32" [3]="1" [4]="release" [5]="arm-unknown-linux-gnueabihf") + BASH_VERSION='5.2.32(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=armhf + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=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='5f4bb7a213144e0d82509d20db54a146' - 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='2979' - PS1='# ' - PS2='> ' + INVOCATION_ID=964be89308564f86a4d22cb3627c72ea + 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=14887 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.UahlOsOd/pbuilderrc_K3NR --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.UahlOsOd/b1 --logfile b1/build.log notary_0.7.0+git20240416.9d2b3b3+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.UahlOsOd/pbuilderrc_xutp --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.UahlOsOd/b2 --logfile b2/build.log notary_0.7.0+git20240416.9d2b3b3+ds1-2.dsc' + SUDO_GID=113 + SUDO_UID=107 + 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 virt32a 6.1.0-26-armmp-lpae #1 SMP Debian 6.1.112-1 (2024-09-30) armv7l GNU/Linux + Linux i-capture-the-hostname 6.1.0-26-arm64 #1 SMP Debian 6.1.112-1 (2024-09-30) aarch64 GNU/Linux I: ls -l /bin lrwxrwxrwx 1 root root 7 Aug 4 21:30 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/2979/tmp/hooks/D02_print_environment finished +I: user script /srv/workspace/pbuilder/14887/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -401,7 +433,7 @@ Get: 230 http://deb.debian.org/debian unstable/main armhf protobuf-compiler armhf 3.21.12-10 [84.3 kB] Get: 231 http://deb.debian.org/debian unstable/main armhf protoc-gen-go-1-5 armhf 1.5.4-1 [1249 kB] Get: 232 http://deb.debian.org/debian unstable/main armhf protoc-gen-go-grpc armhf 1.64.0-6+b1 [1202 kB] -Fetched 156 MB in 7s (23.3 MB/s) +Fetched 156 MB in 10s (16.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 ... 19688 files and directories currently installed.) @@ -1344,7 +1376,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/notary-0.7.0+git20240416.9d2b3b3+ds1/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-genchanges -S > ../notary_0.7.0+git20240416.9d2b3b3+ds1-2_source.changes +I: user script /srv/workspace/pbuilder/14887/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for unstable +I: user script /srv/workspace/pbuilder/14887/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/notary-0.7.0+git20240416.9d2b3b3+ds1/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-genchanges -S > ../notary_0.7.0+git20240416.9d2b3b3+ds1-2_source.changes dpkg-buildpackage: info: source package notary dpkg-buildpackage: info: source version 0.7.0+git20240416.9d2b3b3+ds1-2 dpkg-buildpackage: info: source distribution unstable @@ -1628,17 +1664,17 @@ 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 + 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/unsafeheader internal/goarch internal/byteorder internal/cpu -internal/abi -internal/chacha8rand -internal/bytealg internal/coverage/rtcov +internal/abi internal/godebugs +internal/chacha8rand internal/goexperiment +internal/bytealg internal/goos internal/profilerecord internal/runtime/atomic @@ -1646,19 +1682,18 @@ internal/stringslite runtime/internal/math runtime/internal/sys -internal/runtime/exithook internal/race sync/atomic math/bits -math +internal/runtime/exithook unicode/utf8 -runtime +math cmp internal/asan internal/itoa internal/msan -unicode encoding +unicode unicode/utf16 log/internal crypto/internal/alias @@ -1666,33 +1701,36 @@ vendor/golang.org/x/crypto/cryptobyte/asn1 internal/nettrace container/list +runtime vendor/golang.org/x/crypto/internal/alias github.com/pelletier/go-toml/v2/internal/characters google.golang.org/protobuf/internal/flags google.golang.org/protobuf/internal/set google.golang.org/grpc/serviceconfig -github.com/klauspost/compress github.com/theupdateframework/notary/version github.com/lib/pq/oid -internal/reflectlite +github.com/klauspost/compress +crypto/subtle sync iter +internal/reflectlite slices -crypto/subtle internal/weak maps runtime/metrics internal/bisect internal/testlog internal/singleflight +github.com/spf13/viper/internal/encoding +runtime/cgo errors sort -internal/godebug io strconv hash -internal/oserror path +internal/oserror +internal/godebug syscall bytes crypto @@ -1700,729 +1738,739 @@ reflect strings bufio -time -internal/syscall/unix -internal/syscall/execenv crypto/cipher crypto/internal/boring crypto/internal/randutil +time +internal/syscall/unix +internal/syscall/execenv math/rand +crypto/aes +crypto/internal/edwards25519/field +crypto/internal/nistec/fiat +crypto/sha512 +crypto/sha256 +crypto/des io/fs internal/poll -internal/filepathlite -context encoding/binary +internal/filepathlite internal/fmtsort os +context +crypto/internal/edwards25519 encoding/base64 +golang.org/x/sys/unix +crypto/sha1 +encoding/pem +vendor/golang.org/x/net/dns/dnsmessage github.com/theupdateframework/notary fmt -crypto/aes -golang.org/x/sys/unix -crypto/internal/edwards25519/field -crypto/internal/nistec/fiat +crypto/internal/nistec +math/rand/v2 +internal/concurrent +unique encoding/json github.com/docker/go/canonical/json log math/big -crypto/internal/nistec -github.com/sirupsen/logrus crypto/ecdh -crypto/sha512 -crypto/sha256 -crypto/des -crypto/internal/edwards25519 -crypto/sha1 encoding/hex -encoding/pem +github.com/sirupsen/logrus +net/netip +net/url +path/filepath +io/ioutil +regexp/syntax +crypto/hmac +golang.org/x/crypto/pbkdf2 +net crypto/rand crypto/elliptic -crypto/internal/bigmod github.com/docker/distribution/uuid +crypto/internal/bigmod crypto/internal/boring/bbig encoding/asn1 -crypto/dsa crypto/rsa +crypto/dsa crypto/ed25519 -vendor/golang.org/x/net/dns/dnsmessage -math/rand/v2 -vendor/golang.org/x/crypto/cryptobyte -crypto/x509/pkix -internal/concurrent -unique -net/netip -crypto/ecdsa -runtime/cgo -net/url -path/filepath +regexp golang.org/x/crypto/ed25519 -io/ioutil -regexp/syntax -crypto/hmac -golang.org/x/crypto/pbkdf2 github.com/theupdateframework/notary/tuf/validation mime +vendor/golang.org/x/crypto/cryptobyte +crypto/x509/pkix mime/quotedprintable -regexp +crypto/ecdsa compress/flate hash/crc32 vendor/golang.org/x/crypto/chacha20 -compress/gzip vendor/golang.org/x/crypto/internal/poly1305 -vendor/golang.org/x/crypto/hkdf vendor/golang.org/x/crypto/chacha20poly1305 +vendor/golang.org/x/crypto/hkdf vendor/golang.org/x/sys/cpu crypto/internal/hpke -vendor/golang.org/x/crypto/sha3 +compress/gzip crypto/rc4 +vendor/golang.org/x/crypto/sha3 vendor/golang.org/x/text/transform vendor/golang.org/x/text/unicode/bidi -crypto/internal/mlkem768 vendor/golang.org/x/text/unicode/norm -net -vendor/golang.org/x/text/secure/bidirule +crypto/internal/mlkem768 vendor/golang.org/x/net/http2/hpack +vendor/golang.org/x/text/secure/bidirule net/http/internal -vendor/golang.org/x/net/idna net/http/internal/ascii github.com/miekg/pkcs11 flag +vendor/golang.org/x/net/idna encoding/csv 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 github.com/subosito/gotenv -github.com/spf13/viper/internal/encoding/dotenv github.com/hashicorp/hcl/hcl/strconv github.com/hashicorp/hcl/hcl/token github.com/hashicorp/hcl/hcl/ast github.com/hashicorp/hcl/hcl/scanner -github.com/hashicorp/hcl/hcl/parser -github.com/hashicorp/hcl/json/token -github.com/hashicorp/hcl/json/scanner -github.com/hashicorp/hcl/json/parser -github.com/hashicorp/hcl -text/tabwriter -github.com/hashicorp/hcl/hcl/printer crypto/x509 net/textproto -mime/multipart -vendor/golang.org/x/net/http/httpguts vendor/golang.org/x/net/http/httpproxy github.com/mitchellh/mapstructure +mime/multipart +vendor/golang.org/x/net/http/httpguts +html/template +github.com/spf13/pflag github.com/theupdateframework/notary/tuf/data crypto/tls github.com/theupdateframework/notary/client/changelist github.com/theupdateframework/notary/tuf/utils -github.com/spf13/pflag -github.com/spf13/viper/internal/encoding/hcl +github.com/spf13/cast +github.com/hashicorp/hcl/hcl/parser +github.com/hashicorp/hcl/json/token +github.com/hashicorp/hcl/json/scanner +github.com/spf13/viper/internal/encoding/dotenv +github.com/hashicorp/hcl/json/parser +text/tabwriter +github.com/hashicorp/hcl/hcl/printer +github.com/hashicorp/hcl gopkg.in/ini.v1 -net/http/httptrace -net/http -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 -gopkg.in/yaml.v3 github.com/pelletier/go-toml/v2 +github.com/spf13/viper/internal/encoding/ini +gopkg.in/yaml.v3 github.com/spf13/viper/internal/encoding/toml +net/http/httptrace hash/fnv google.golang.org/protobuf/internal/detrand +net/http google.golang.org/protobuf/internal/errors -github.com/spf13/viper/internal/encoding/yaml google.golang.org/protobuf/encoding/protowire google.golang.org/protobuf/internal/pragma -embed google.golang.org/protobuf/reflect/protoreflect -google.golang.org/protobuf/internal/editiondefaults -go/token -google.golang.org/protobuf/internal/version -golang.org/x/net/context -golang.org/x/net/internal/timeseries -google.golang.org/grpc/backoff -google.golang.org/grpc/internal/grpclog google.golang.org/protobuf/internal/descfmt +github.com/spf13/viper/internal/encoding/yaml google.golang.org/protobuf/internal/descopts -google.golang.org/protobuf/internal/strs -google.golang.org/protobuf/internal/encoding/text +embed +go/token +google.golang.org/protobuf/internal/editiondefaults google.golang.org/protobuf/internal/encoding/messageset google.golang.org/protobuf/internal/genid +google.golang.org/protobuf/internal/strs google.golang.org/protobuf/internal/order -google.golang.org/protobuf/internal/encoding/defval +google.golang.org/protobuf/internal/encoding/text google.golang.org/protobuf/reflect/protoregistry google.golang.org/protobuf/runtime/protoiface -google.golang.org/grpc/grpclog +google.golang.org/protobuf/internal/encoding/defval google.golang.org/protobuf/proto +google.golang.org/protobuf/internal/version +golang.org/x/net/context +golang.org/x/net/internal/timeseries +google.golang.org/grpc/backoff +google.golang.org/grpc/internal/grpclog +google.golang.org/grpc/grpclog +google.golang.org/protobuf/internal/filedesc +google.golang.org/protobuf/encoding/prototext google.golang.org/grpc/connectivity google.golang.org/grpc/attributes google.golang.org/grpc/internal/credentials google.golang.org/grpc/internal -google.golang.org/protobuf/internal/filedesc -google.golang.org/protobuf/encoding/prototext google.golang.org/grpc/credentials -github.com/theupdateframework/notary/storage -github.com/spf13/afero -github.com/theupdateframework/notary/trustmanager -github.com/theupdateframework/notary/cryptoservice -github.com/theupdateframework/notary/tuf/signed -github.com/magiconair/properties -github.com/theupdateframework/notary/trustmanager/yubikey -github.com/theupdateframework/notary/trustpinning -github.com/spf13/viper/internal/encoding/javaproperties -github.com/spf13/viper -google.golang.org/protobuf/internal/encoding/tag -github.com/theupdateframework/notary/tuf -google.golang.org/protobuf/internal/impl -github.com/theupdateframework/notary/client -golang.org/x/net/trace -google.golang.org/grpc/internal/channelz google.golang.org/grpc/metadata -google.golang.org/grpc/resolver -google.golang.org/grpc/channelz google.golang.org/grpc/internal/grpcrand google.golang.org/grpc/codes +google.golang.org/grpc/internal/channelz +google.golang.org/grpc/resolver google.golang.org/grpc/credentials/insecure -google.golang.org/grpc/balancer google.golang.org/grpc/internal/grpcutil google.golang.org/grpc/encoding -google.golang.org/grpc/balancer/base google.golang.org/grpc/internal/backoff google.golang.org/grpc/internal/balancerload -google.golang.org/grpc/balancer/roundrobin -google.golang.org/grpc/internal/balancer/gracefulswitch google.golang.org/grpc/internal/buffer +google.golang.org/grpc/channelz +google.golang.org/grpc/balancer google.golang.org/grpc/internal/grpcsync +google.golang.org/grpc/balancer/base google.golang.org/grpc/internal/idle google.golang.org/grpc/internal/metadata +google.golang.org/grpc/balancer/roundrobin +google.golang.org/grpc/internal/balancer/gracefulswitch google.golang.org/protobuf/internal/encoding/json +google.golang.org/protobuf/internal/encoding/tag google.golang.org/grpc/internal/serviceconfig google.golang.org/grpc/internal/resolver google.golang.org/grpc/internal/resolver/passthrough +google.golang.org/protobuf/internal/impl google.golang.org/grpc/internal/transport/networktype -google.golang.org/protobuf/encoding/protojson google.golang.org/grpc/internal/resolver/unix +google.golang.org/protobuf/encoding/protojson golang.org/x/text/unicode/bidi golang.org/x/text/secure/bidirule golang.org/x/net/idna golang.org/x/net/http2/hpack -google.golang.org/protobuf/internal/filetype google.golang.org/grpc/internal/syscall -golang.org/x/net/http/httpguts -golang.org/x/net/http2 google.golang.org/grpc/keepalive -google.golang.org/protobuf/runtime/protoimpl google.golang.org/grpc/peer +golang.org/x/net/http/httpguts google.golang.org/grpc/stats +google.golang.org/grpc/tap +google.golang.org/grpc/balancer/grpclb/state +google.golang.org/grpc/internal/envconfig +google.golang.org/grpc/internal/resolver/dns/internal +github.com/bugsnag/bugsnag-go/device +google.golang.org/grpc/internal/resolver/dns +github.com/pkg/errors +github.com/theupdateframework/notary/storage +github.com/spf13/afero +github.com/magiconair/properties +github.com/theupdateframework/notary/trustmanager +github.com/spf13/viper/internal/encoding/javaproperties +golang.org/x/net/trace +github.com/theupdateframework/notary/cryptoservice +github.com/theupdateframework/notary/tuf/signed +github.com/spf13/viper +github.com/theupdateframework/notary/trustmanager/yubikey +github.com/theupdateframework/notary/trustpinning +github.com/theupdateframework/notary/tuf +golang.org/x/net/http2 +net/http/httputil +github.com/theupdateframework/notary/client +google.golang.org/protobuf/internal/filetype +google.golang.org/protobuf/runtime/protoimpl +google.golang.org/grpc/resolver/dns google.golang.org/protobuf/types/known/emptypb google.golang.org/protobuf/protoadapt -google.golang.org/grpc/encoding/proto github.com/golang/protobuf/ptypes/empty +google.golang.org/grpc/encoding/proto google.golang.org/protobuf/types/known/durationpb google.golang.org/protobuf/types/known/timestamppb -github.com/golang/protobuf/ptypes/timestamp github.com/golang/protobuf/ptypes/duration google.golang.org/protobuf/types/known/anypb +github.com/golang/protobuf/ptypes/timestamp +google.golang.org/grpc/internal/pretty +github.com/bugsnag/bugsnag-go/errors google.golang.org/grpc/binarylog/grpc_binarylog_v1 google.golang.org/genproto/googleapis/rpc/status google.golang.org/grpc/internal/status -google.golang.org/grpc/internal/pretty -google.golang.org/grpc/status -google.golang.org/grpc/tap -net/http/httputil -google.golang.org/grpc/internal/binarylog -google.golang.org/grpc/balancer/grpclb/state -google.golang.org/grpc/internal/envconfig -google.golang.org/grpc/internal/resolver/dns/internal -github.com/bugsnag/bugsnag-go/device -google.golang.org/grpc/internal/resolver/dns -github.com/pkg/errors -github.com/bugsnag/bugsnag-go/errors -google.golang.org/grpc/resolver/dns github.com/bugsnag/bugsnag-go/headers os/exec +google.golang.org/grpc/status os/signal +google.golang.org/grpc/internal/binarylog database/sql/driver github.com/bugsnag/panicwrap -google.golang.org/grpc/internal/transport github.com/google/uuid -github.com/gorilla/mux -github.com/bugsnag/bugsnag-go/sessions -github.com/docker/distribution/context -github.com/bugsnag/bugsnag-go github.com/docker/distribution/registry/api/errcode +github.com/gorilla/mux github.com/docker/distribution/registry/auth -github.com/Shopify/logrus-bugsnag +github.com/bugsnag/bugsnag-go/sessions github.com/docker/go-connections/tlsconfig +google.golang.org/grpc/internal/transport +github.com/docker/distribution/context database/sql +github.com/bugsnag/bugsnag-go filippo.io/edwards25519/field filippo.io/edwards25519 -google.golang.org/grpc +github.com/Shopify/logrus-bugsnag github.com/opencontainers/go-digest github.com/distribution/reference -github.com/go-sql-driver/mysql github.com/opencontainers/image-spec/specs-go +github.com/go-sql-driver/mysql github.com/opencontainers/image-spec/specs-go/v1 -github.com/docker/distribution -github.com/docker/distribution/registry/api/v2 github.com/docker/distribution/registry/client/auth/challenge github.com/docker/distribution/registry/client/transport -github.com/theupdateframework/notary/utils +github.com/docker/distribution +github.com/docker/distribution/registry/api/v2 expvar -github.com/theupdateframework/notary/trustmanager/remoteks github.com/beorn7/perks/quantile github.com/cespare/xxhash +google.golang.org/grpc github.com/prometheus/client_model/go github.com/munnerz/goautoneg google.golang.org/protobuf/encoding/protodelim -github.com/theupdateframework/notary/cmd/escrow github.com/prometheus/procfs/internal/fs github.com/prometheus/common/model github.com/prometheus/procfs/internal/util github.com/prometheus/procfs +github.com/theupdateframework/notary/utils +runtime/debug github.com/prometheus/client_golang/prometheus/internal github.com/prometheus/common/expfmt -runtime/debug github.com/klauspost/compress/fse github.com/klauspost/compress/huff0 -github.com/prometheus/client_golang/prometheus github.com/klauspost/compress/internal/snapref -github.com/klauspost/compress/zstd github.com/prometheus/client_golang/internal/github.com/golang/gddo/httputil/header github.com/prometheus/client_golang/internal/github.com/golang/gddo/httputil +github.com/theupdateframework/notary/trustmanager/remoteks github.com/spf13/cobra +github.com/klauspost/compress/zstd +github.com/theupdateframework/notary/cmd/escrow +github.com/prometheus/client_golang/prometheus golang.org/x/term +github.com/theupdateframework/notary/passphrase github.com/docker/distribution/health golang.org/x/crypto/blowfish -github.com/theupdateframework/notary/passphrase golang.org/x/crypto/bcrypt -github.com/prometheus/client_golang/prometheus/promhttp -encoding/base32 github.com/docker/distribution/registry/auth/htpasswd +encoding/base32 github.com/docker/libtrust github.com/lib/pq/scram +github.com/prometheus/client_golang/prometheus/promhttp os/user github.com/docker/distribution/registry/auth/token -github.com/docker/go-metrics google.golang.org/protobuf/types/descriptorpb +github.com/docker/go-metrics +github.com/theupdateframework/notary/server/errors +github.com/jinzhu/inflection +go/scanner github.com/docker/distribution/metrics +go/ast +google.golang.org/protobuf/types/gofeaturespb github.com/docker/distribution/registry/storage/cache github.com/docker/distribution/registry/storage/cache/memory +google.golang.org/protobuf/reflect/protodesc +github.com/lib/pq github.com/docker/distribution/registry/client github.com/docker/distribution/registry/client/auth github.com/theupdateframework/notary/cmd/notary -google.golang.org/protobuf/types/gofeaturespb -google.golang.org/protobuf/reflect/protodesc -github.com/lib/pq github.com/golang/protobuf/proto -github.com/theupdateframework/notary/server/errors -github.com/jinzhu/inflection -github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus -go/scanner -go/ast +github.com/jinzhu/gorm github.com/hailocab/go-hostpool github.com/opentracing/opentracing-go/log +github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus github.com/opentracing/opentracing-go -github.com/jinzhu/gorm 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 -gopkg.in/rethinkdb/rethinkdb-go.v6 github.com/theupdateframework/notary/proto +gopkg.in/rethinkdb/rethinkdb-go.v6 google.golang.org/grpc/health/grpc_health_v1 internal/profile github.com/theupdateframework/notary/signer/client runtime/pprof -github.com/theupdateframework/notary/storage/rethinkdb runtime/trace github.com/theupdateframework/notary/signer -github.com/theupdateframework/notary/server/storage github.com/theupdateframework/notary/signer/api github.com/dvsekhvalnov/jose2go/base64url github.com/dvsekhvalnov/jose2go/arrays github.com/dvsekhvalnov/jose2go/aes github.com/dvsekhvalnov/jose2go/compact github.com/dvsekhvalnov/jose2go/kdf -net/http/pprof github.com/dvsekhvalnov/jose2go/keys/ecc +net/http/pprof github.com/dvsekhvalnov/jose2go/padding -github.com/theupdateframework/notary/server/snapshot github.com/dvsekhvalnov/jose2go -github.com/theupdateframework/notary/server/timestamp google.golang.org/grpc/health -github.com/theupdateframework/notary/server/handlers github.com/theupdateframework/notary/tuf/testutils/keys +github.com/theupdateframework/notary/storage/rethinkdb github.com/theupdateframework/notary/tuf/testutils -github.com/theupdateframework/notary/signer/keydbstore -github.com/theupdateframework/notary/server github.com/davecgh/go-spew/spew -github.com/theupdateframework/notary/cmd/notary-server -github.com/theupdateframework/notary/cmd/notary-signer +github.com/theupdateframework/notary/server/storage +github.com/theupdateframework/notary/signer/keydbstore github.com/pmezard/go-difflib/difflib net/http/internal/testcert net/http/httptest -github.com/stretchr/testify/assert -github.com/stretchr/testify/require +github.com/theupdateframework/notary/cmd/notary-signer internal/sysinfo +github.com/theupdateframework/notary/server/snapshot testing +github.com/theupdateframework/notary/server/timestamp +github.com/stretchr/testify/assert +github.com/theupdateframework/notary/server/handlers +github.com/theupdateframework/notary/server +github.com/theupdateframework/notary/cmd/notary-server +github.com/stretchr/testify/require github.com/theupdateframework/notary/tuf/testutils/interfaces make[1]: Leaving directory '/build/reproducible-path/notary-0.7.0+git20240416.9d2b3b3+ds1' dh_auto_test -O--builddirectory=/build/reproducible-path/notary-0.7.0\+git20240416.9d2b3b3\+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/proto/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/remoteks/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/proto/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/remoteks/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/proto/github.com/theupdateframework/notary/proto [no test files] +? github.com/theupdateframework/notary/server/errors [no test files] +? github.com/theupdateframework/notary/signer [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/remoteks/github.com/theupdateframework/notary/trustmanager/remoteks [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.11s) +--- PASS: Test0Dot1Migration (0.14s) === RUN Test0Dot3Migration ---- PASS: Test0Dot3Migration (0.08s) +--- PASS: Test0Dot3Migration (0.16s) === RUN Test0Dot1RepoFormat -time="2024-11-06T04:40:37Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot1RepoFormat (0.92s) +time="2024-11-06T05:00:53Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot1RepoFormat (1.85s) === RUN Test0Dot3RepoFormat -time="2024-11-06T04:40:38Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot3RepoFormat (1.19s) +time="2024-11-06T05:00:55Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot3RepoFormat (2.24s) === RUN TestDownloading0Dot1RepoFormat ---- PASS: TestDownloading0Dot1RepoFormat (0.03s) +--- PASS: TestDownloading0Dot1RepoFormat (0.07s) === RUN TestDownloading0Dot3RepoFormat ---- PASS: TestDownloading0Dot3RepoFormat (0.04s) +--- PASS: TestDownloading0Dot3RepoFormat (0.10s) === RUN TestInitRepositoryManagedRolesIncludingRoot ---- PASS: TestInitRepositoryManagedRolesIncludingRoot (0.01s) +--- PASS: TestInitRepositoryManagedRolesIncludingRoot (0.03s) === RUN TestInitRepositoryManagedRolesInvalidRole ---- PASS: TestInitRepositoryManagedRolesInvalidRole (0.01s) +--- PASS: TestInitRepositoryManagedRolesInvalidRole (0.03s) === RUN TestInitRepositoryManagedRolesIncludingTargets --- PASS: TestInitRepositoryManagedRolesIncludingTargets (0.03s) === RUN TestInitRepositoryManagedRolesIncludingTimestamp ---- PASS: TestInitRepositoryManagedRolesIncludingTimestamp (0.07s) +--- PASS: TestInitRepositoryManagedRolesIncludingTimestamp (0.18s) === RUN TestInitRepositoryWithCerts ---- PASS: TestInitRepositoryWithCerts (0.10s) +--- PASS: TestInitRepositoryWithCerts (0.26s) === RUN TestMatchKeyIDsWithPublicKeys ---- PASS: TestMatchKeyIDsWithPublicKeys (0.04s) +--- PASS: TestMatchKeyIDsWithPublicKeys (0.08s) === RUN TestInitRepositoryNeedsRemoteTimestampKey ---- PASS: TestInitRepositoryNeedsRemoteTimestampKey (0.05s) +--- PASS: TestInitRepositoryNeedsRemoteTimestampKey (0.12s) === RUN TestInitRepositoryNeedsRemoteSnapshotKey ---- PASS: TestInitRepositoryNeedsRemoteSnapshotKey (0.04s) +--- PASS: TestInitRepositoryNeedsRemoteSnapshotKey (0.09s) === RUN TestInitRepoServerOnlyManagesTimestampKey ---- PASS: TestInitRepoServerOnlyManagesTimestampKey (0.43s) +--- PASS: TestInitRepoServerOnlyManagesTimestampKey (0.75s) === RUN TestInitRepoServerManagesTimestampAndSnapshotKeys ---- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.35s) +--- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.88s) === RUN TestInitRepoAttemptsExceeded ---- PASS: TestInitRepoAttemptsExceeded (0.22s) +--- PASS: TestInitRepoAttemptsExceeded (0.50s) === RUN TestInitRepoPasswordInvalid ---- PASS: TestInitRepoPasswordInvalid (0.04s) +--- PASS: TestInitRepoPasswordInvalid (0.10s) === RUN TestAddTargetToTargetRoleByDefault ---- PASS: TestAddTargetToTargetRoleByDefault (0.16s) +--- PASS: TestAddTargetToTargetRoleByDefault (0.37s) === RUN TestAddTargetToSpecifiedValidRoles ---- PASS: TestAddTargetToSpecifiedValidRoles (0.16s) +--- PASS: TestAddTargetToSpecifiedValidRoles (0.35s) === RUN TestAddTargetToSpecifiedInvalidRoles ---- PASS: TestAddTargetToSpecifiedInvalidRoles (0.16s) +--- PASS: TestAddTargetToSpecifiedInvalidRoles (0.39s) === RUN TestAddTargetWithInvalidTarget ---- PASS: TestAddTargetWithInvalidTarget (0.07s) +--- PASS: TestAddTargetWithInvalidTarget (0.18s) === RUN TestAddTargetErrorWritingChanges ---- PASS: TestAddTargetErrorWritingChanges (0.07s) +--- PASS: TestAddTargetErrorWritingChanges (0.20s) === RUN TestRemoveTargetToTargetRoleByDefault ---- PASS: TestRemoveTargetToTargetRoleByDefault (0.15s) +--- PASS: TestRemoveTargetToTargetRoleByDefault (0.26s) === RUN TestRemoveTargetFromSpecifiedValidRoles ---- PASS: TestRemoveTargetFromSpecifiedValidRoles (0.16s) +--- PASS: TestRemoveTargetFromSpecifiedValidRoles (0.31s) === RUN TestRemoveTargetToSpecifiedInvalidRoles ---- PASS: TestRemoveTargetToSpecifiedInvalidRoles (0.15s) +--- PASS: TestRemoveTargetToSpecifiedInvalidRoles (0.27s) === RUN TestRemoveTargetErrorWritingChanges ---- PASS: TestRemoveTargetErrorWritingChanges (0.08s) +--- PASS: TestRemoveTargetErrorWritingChanges (0.11s) === RUN TestListTarget ---- PASS: TestListTarget (1.98s) +--- PASS: TestListTarget (3.36s) === RUN TestListTargetRestrictsDelegationPaths ---- PASS: TestListTargetRestrictsDelegationPaths (0.39s) +--- PASS: TestListTargetRestrictsDelegationPaths (0.37s) === RUN TestValidateRootKey ---- PASS: TestValidateRootKey (0.20s) +--- PASS: TestValidateRootKey (0.24s) === RUN TestGetChangelist ---- PASS: TestGetChangelist (0.20s) +--- PASS: TestGetChangelist (0.27s) === RUN TestPublishBareRepo ---- PASS: TestPublishBareRepo (1.76s) +--- PASS: TestPublishBareRepo (3.35s) === RUN TestPublishUninitializedRepo -time="2024-11-06T04:40:47Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" ---- PASS: TestPublishUninitializedRepo (0.14s) +time="2024-11-06T05:01:10Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +--- PASS: TestPublishUninitializedRepo (0.34s) === RUN TestPublishClientHasSnapshotKey ---- PASS: TestPublishClientHasSnapshotKey (1.71s) +--- PASS: TestPublishClientHasSnapshotKey (3.25s) === RUN TestPublishAfterInitServerHasSnapshotKey ---- PASS: TestPublishAfterInitServerHasSnapshotKey (1.56s) +--- PASS: TestPublishAfterInitServerHasSnapshotKey (3.35s) === RUN TestPublishAfterPullServerHasSnapshotKey ---- PASS: TestPublishAfterPullServerHasSnapshotKey (0.59s) +--- PASS: TestPublishAfterPullServerHasSnapshotKey (0.95s) === RUN TestPublishNoOneHasSnapshotKey ---- PASS: TestPublishNoOneHasSnapshotKey (0.34s) +--- PASS: TestPublishNoOneHasSnapshotKey (0.71s) === RUN TestPublishSnapshotCorrupt ---- PASS: TestPublishSnapshotCorrupt (0.91s) +--- PASS: TestPublishSnapshotCorrupt (2.12s) === RUN TestPublishTargetsCorrupt ---- PASS: TestPublishTargetsCorrupt (0.48s) +--- PASS: TestPublishTargetsCorrupt (1.01s) === RUN TestPublishRootCorrupt -time="2024-11-06T04:40:52Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" -time="2024-11-06T04:40:52Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" ---- PASS: TestPublishRootCorrupt (0.32s) +time="2024-11-06T05:01:22Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +time="2024-11-06T05:01:22Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +--- PASS: TestPublishRootCorrupt (0.51s) === RUN TestPublishSnapshotLocalKeysCreatedFirst ---- PASS: TestPublishSnapshotLocalKeysCreatedFirst (0.02s) +--- PASS: TestPublishSnapshotLocalKeysCreatedFirst (0.04s) === RUN TestPublishDelegations ---- PASS: TestPublishDelegations (0.63s) +--- PASS: TestPublishDelegations (1.42s) === RUN TestPublishDelegationsX509 ---- PASS: TestPublishDelegationsX509 (0.65s) +--- PASS: TestPublishDelegationsX509 (1.23s) === RUN TestPublishTargetsDelegationScopeFailIfNoKeys -time="2024-11-06T04:40:54Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" -time="2024-11-06T04:40:54Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" ---- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (0.53s) +time="2024-11-06T05:01:26Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" +time="2024-11-06T05:01:26Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" +--- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (1.04s) === RUN TestPublishTargetsDelegationSuccessLocallyHasRoles ---- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (0.59s) +--- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (1.15s) === RUN TestPublishTargetsDelegationNoTargetsKeyNeeded ---- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (0.70s) +--- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (1.31s) === RUN TestPublishTargetsDelegationSuccessNeedsToDownloadRoles ---- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (0.69s) +--- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (1.03s) === RUN TestPublishTargetsDelegationFromTwoRepos ---- PASS: TestPublishTargetsDelegationFromTwoRepos (0.59s) +--- PASS: TestPublishTargetsDelegationFromTwoRepos (1.15s) === RUN TestPublishRemoveDelegationKeyFromDelegationRole -time="2024-11-06T04:40:57Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-11-06T04:40:57Z" 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: 0a0934b765068f816e592bf01dca1d7c1474a334c1ed15e50657d5bd3e27f373" ---- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.40s) +time="2024-11-06T05:01:32Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-11-06T05:01:32Z" 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: 733e812c358038f425670a2660564ad97aec75e64370eb6d16a5c068f0c99aac" +--- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.87s) === RUN TestPublishRemoveDelegation -time="2024-11-06T04:40:58Z" 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-11-06T04:40:58Z" 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-11-06T04:40:58Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-11-06T04:40:58Z" 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.39s) +time="2024-11-06T05:01:33Z" 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-11-06T05:01:33Z" 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-11-06T05:01:33Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-11-06T05:01:33Z" 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.93s) === RUN TestPublishSucceedsDespiteDelegationCorrupt ---- PASS: TestPublishSucceedsDespiteDelegationCorrupt (0.51s) +--- PASS: TestPublishSucceedsDespiteDelegationCorrupt (0.91s) === RUN TestRotateKeyInvalidRole ---- PASS: TestRotateKeyInvalidRole (0.23s) +--- PASS: TestRotateKeyInvalidRole (0.47s) === RUN TestRemoteRotationError ---- PASS: TestRemoteRotationError (0.05s) +--- PASS: TestRemoteRotationError (0.07s) === RUN TestRemoteRotationEndpointError ---- PASS: TestRemoteRotationEndpointError (0.15s) +--- PASS: TestRemoteRotationEndpointError (0.14s) === RUN TestRemoteRotationNoRootKey ---- PASS: TestRemoteRotationNoRootKey (0.24s) +--- PASS: TestRemoteRotationNoRootKey (0.32s) === RUN TestRemoteRotationNoInit -time="2024-11-06T04:40:59Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" ---- PASS: TestRemoteRotationNoInit (0.17s) +time="2024-11-06T05:01:35Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +--- PASS: TestRemoteRotationNoInit (0.20s) === RUN TestRotateBeforePublishFromRemoteKeyToLocalKey ---- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (0.74s) +--- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (1.00s) === RUN TestRotateKeyAfterPublishNoServerManagementChange ---- PASS: TestRotateKeyAfterPublishNoServerManagementChange (2.14s) +--- PASS: TestRotateKeyAfterPublishNoServerManagementChange (4.97s) === RUN TestRotateKeyAfterPublishServerManagementChange ---- PASS: TestRotateKeyAfterPublishServerManagementChange (2.51s) +--- PASS: TestRotateKeyAfterPublishServerManagementChange (4.50s) === RUN TestRotateRootKey ---- PASS: TestRotateRootKey (0.76s) +--- PASS: TestRotateRootKey (1.00s) === RUN TestRotateRootMultiple ---- PASS: TestRotateRootMultiple (1.01s) +--- PASS: TestRotateRootMultiple (1.43s) === RUN TestRotateRootKeyProvided ---- PASS: TestRotateRootKeyProvided (0.79s) +--- PASS: TestRotateRootKeyProvided (1.50s) === RUN TestRotateRootKeyLegacySupport ---- PASS: TestRotateRootKeyLegacySupport (1.13s) +--- PASS: TestRotateRootKeyLegacySupport (1.81s) === RUN TestRemoteServerUnavailableNoLocalCache -time="2024-11-06T04:41:08Z" level=error msg="Could not publish Repository since we could not update: unable to reach trust server at this time: 500." +time="2024-11-06T05:01:51Z" 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.09s) +--- PASS: TestAddDelegationChangefileValid (0.17s) === RUN TestAddDelegationChangefileApplicable ---- PASS: TestAddDelegationChangefileApplicable (0.08s) +--- PASS: TestAddDelegationChangefileApplicable (0.17s) === RUN TestAddDelegationErrorWritingChanges ---- PASS: TestAddDelegationErrorWritingChanges (0.08s) +--- PASS: TestAddDelegationErrorWritingChanges (0.17s) === RUN TestRemoveDelegationChangefileValid ---- PASS: TestRemoveDelegationChangefileValid (0.08s) +--- PASS: TestRemoveDelegationChangefileValid (0.16s) === RUN TestRemoveDelegationChangefileApplicable -time="2024-11-06T04:41:09Z" 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-11-06T04:41:09Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" ---- PASS: TestRemoveDelegationChangefileApplicable (0.09s) +time="2024-11-06T05:01:52Z" 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-11-06T05:01:52Z" 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.14s) === RUN TestClearAllPathsDelegationChangefileApplicable ---- PASS: TestClearAllPathsDelegationChangefileApplicable (0.08s) +--- PASS: TestClearAllPathsDelegationChangefileApplicable (0.15s) === RUN TestFullAddDelegationChangefileApplicable ---- PASS: TestFullAddDelegationChangefileApplicable (0.10s) +--- PASS: TestFullAddDelegationChangefileApplicable (0.19s) === RUN TestFullRemoveDelegationChangefileApplicable ---- PASS: TestFullRemoveDelegationChangefileApplicable (0.10s) +--- PASS: TestFullRemoveDelegationChangefileApplicable (0.19s) === RUN TestRemoveDelegationErrorWritingChanges ---- PASS: TestRemoveDelegationErrorWritingChanges (0.08s) +--- PASS: TestRemoveDelegationErrorWritingChanges (0.18s) === RUN TestClientInvalidURL --- PASS: TestClientInvalidURL (0.00s) === RUN TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole ---- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (1.46s) +--- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (2.78s) === RUN TestDeleteRepo ---- PASS: TestDeleteRepo (0.14s) +--- PASS: TestDeleteRepo (0.30s) === RUN TestDeleteRemoteRepo ---- PASS: TestDeleteRemoteRepo (0.45s) +--- PASS: TestDeleteRemoteRepo (0.83s) === RUN TestListRoles ---- PASS: TestListRoles (1.00s) +--- PASS: TestListRoles (1.57s) === RUN TestGetAllTargetInfo ---- PASS: TestGetAllTargetInfo (0.62s) +--- PASS: TestGetAllTargetInfo (0.74s) === RUN TestUpdateSucceedsEvenIfCannotWriteNewRepo -time="2024-11-06T04:41:13Z" level=error msg="could not save root to cache: Non-writable" ---- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.49s) +time="2024-11-06T05:01:59Z" level=error msg="could not save root to cache: Non-writable" +--- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.46s) === RUN TestUpdateSucceedsEvenIfCannotWriteExistingRepo ---- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (2.23s) +--- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (3.49s) === RUN TestUpdateInOfflineMode -time="2024-11-06T04:41:15Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" -time="2024-11-06T04:41:16Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateInOfflineMode (0.21s) +time="2024-11-06T05:02:03Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +time="2024-11-06T05:02:03Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateInOfflineMode (0.31s) === RUN TestUpdateReplacesCorruptOrMissingMetadata -time="2024-11-06T04:41:20Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:41:20Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateReplacesCorruptOrMissingMetadata (4.33s) +time="2024-11-06T05:02:09Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:02:10Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateReplacesCorruptOrMissingMetadata (7.81s) === RUN TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign -time="2024-11-06T04:41:20Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:41:20Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.41s) +time="2024-11-06T05:02:11Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:02:11Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.54s) === RUN TestUpdateRemoteRootNotExistNoLocalCache ---- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.20s) +--- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.31s) === RUN TestUpdateRemoteRootNotExistCanUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.40s) +--- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.70s) === RUN TestUpdateRemoteRootNotExistCannotUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.41s) +--- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.66s) === RUN TestUpdateRemoteRoot50XNoLocalCache ---- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.19s) +--- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.27s) === RUN TestUpdateRemoteRoot50XCanUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.40s) +--- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.52s) === RUN TestUpdateRemoteRoot50XCannotUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.43s) +--- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.53s) === RUN TestUpdateNonRootRemoteMissingMetadataNoLocalCache ---- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (0.95s) +--- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (1.59s) === RUN TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache -time="2024-11-06T04:41:24Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (1.29s) +time="2024-11-06T05:02:17Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (2.19s) === RUN TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache -time="2024-11-06T04:41:25Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (1.60s) +time="2024-11-06T05:02:19Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (1.65s) === RUN TestUpdateNonRootRemote50XNoLocalCache ---- PASS: TestUpdateNonRootRemote50XNoLocalCache (1.00s) +--- PASS: TestUpdateNonRootRemote50XNoLocalCache (1.44s) === RUN TestUpdateNonRootRemote50XCanUseLocalCache -time="2024-11-06T04:41:28Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (1.32s) +time="2024-11-06T05:02:22Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (1.50s) === RUN TestUpdateNonRootRemote50XCannotUseLocalCache -time="2024-11-06T04:41:29Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (1.59s) +time="2024-11-06T05:02:24Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (2.49s) === RUN TestUpdateRemoteChecksumWrongNoLocalCache ---- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (1.26s) +--- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (1.38s) === RUN TestUpdateRemoteChecksumWrongCanUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (1.73s) +--- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (2.59s) === RUN TestUpdateRemoteChecksumWrongCannotUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (2.11s) +--- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (3.60s) === RUN TestUpdateRootRemoteCorruptedNoLocalCache -time="2024-11-06T04:41:37Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:41:37Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (4.03s) +time="2024-11-06T05:02:36Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:02:36Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (5.89s) === RUN TestUpdateRootRemoteCorruptedCanUseLocalCache ---- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (7.43s) +--- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (10.95s) === RUN TestUpdateRootRemoteCorruptedCannotUseLocalCache -time="2024-11-06T04:41:51Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:41:51Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (8.42s) +time="2024-11-06T05:02:56Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:02:57Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (12.99s) === RUN TestUpdateNonRootRemoteCorruptedNoLocalCache -time="2024-11-06T04:41:57Z" level=error msg="Metadata for targets expired" -time="2024-11-06T04:41:57Z" level=error msg="Metadata for targets expired" -time="2024-11-06T04:41:58Z" level=error msg="Metadata for snapshot expired" -time="2024-11-06T04:41:58Z" level=error msg="Metadata for snapshot expired" -time="2024-11-06T04:42:00Z" level=error msg="Metadata for timestamp expired" -time="2024-11-06T04:42:00Z" level=error msg="Metadata for timestamp expired" -time="2024-11-06T04:42:01Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-11-06T04:42:01Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-11-06T04:42:01Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-11-06T04:42:01Z" level=error msg="Metadata for targets/a expired" -time="2024-11-06T04:42:01Z" level=warning msg="Error getting targets/a: targets/a expired at Thu Oct 5 04:42:01 UTC 2023" -time="2024-11-06T04:42:02Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-11-06T04:42:02Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-11-06T04:42:02Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-11-06T04:42:02Z" level=error msg="Metadata for targets/a/b expired" -time="2024-11-06T04:42:02Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Thu Oct 5 04:42:02 UTC 2023" -time="2024-11-06T04:42:03Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-11-06T04:42:04Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-11-06T04:42:04Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-11-06T04:42:04Z" level=error msg="Metadata for targets/b expired" -time="2024-11-06T04:42:04Z" level=warning msg="Error getting targets/b: targets/b expired at Thu Oct 5 04:42:04 UTC 2023" ---- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (9.55s) +time="2024-11-06T05:03:06Z" level=error msg="Metadata for targets expired" +time="2024-11-06T05:03:06Z" level=error msg="Metadata for targets expired" +time="2024-11-06T05:03:09Z" level=error msg="Metadata for snapshot expired" +time="2024-11-06T05:03:09Z" level=error msg="Metadata for snapshot expired" +time="2024-11-06T05:03:11Z" level=error msg="Metadata for timestamp expired" +time="2024-11-06T05:03:11Z" level=error msg="Metadata for timestamp expired" +time="2024-11-06T05:03:14Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-11-06T05:03:14Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-11-06T05:03:14Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-11-06T05:03:14Z" level=error msg="Metadata for targets/a expired" +time="2024-11-06T05:03:14Z" level=warning msg="Error getting targets/a: targets/a expired at Thu Oct 5 05:03:14 UTC 2023" +time="2024-11-06T05:03:17Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-11-06T05:03:17Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-11-06T05:03:18Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-11-06T05:03:18Z" level=error msg="Metadata for targets/a/b expired" +time="2024-11-06T05:03:18Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Thu Oct 5 05:03:18 UTC 2023" +time="2024-11-06T05:03:20Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-11-06T05:03:21Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-11-06T05:03:21Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-11-06T05:03:21Z" level=error msg="Metadata for targets/b expired" +time="2024-11-06T05:03:21Z" level=warning msg="Error getting targets/b: targets/b expired at Thu Oct 5 05:03:21 UTC 2023" +--- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (19.95s) === RUN TestUpdateNonRootRemoteCorruptedCanUseLocalCache -time="2024-11-06T04:42:10Z" level=error msg="Metadata for timestamp expired" -time="2024-11-06T04:42:10Z" level=error msg="Metadata for timestamp expired" ---- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (12.29s) +time="2024-11-06T05:03:34Z" level=error msg="Metadata for timestamp expired" +time="2024-11-06T05:03:34Z" level=error msg="Metadata for timestamp expired" +--- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (23.92s) === RUN TestUpdateNonRootRemoteCorruptedCannotUseLocalCache -time="2024-11-06T04:42:19Z" level=error msg="Metadata for targets expired" -time="2024-11-06T04:42:21Z" level=error msg="Metadata for snapshot expired" -time="2024-11-06T04:42:21Z" level=error msg="Metadata for snapshot expired" -time="2024-11-06T04:42:23Z" level=error msg="Metadata for timestamp expired" -time="2024-11-06T04:42:23Z" level=error msg="Metadata for timestamp expired" -time="2024-11-06T04:42:25Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-11-06T04:42:25Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-11-06T04:42:25Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-11-06T04:42:26Z" level=error msg="Metadata for targets/a expired" -time="2024-11-06T04:42:26Z" level=warning msg="Error getting targets/a: targets/a expired at Thu Oct 5 04:42:25 UTC 2023" -time="2024-11-06T04:42:27Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-11-06T04:42:28Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-11-06T04:42:28Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-11-06T04:42:28Z" level=error msg="Metadata for targets/a/b expired" -time="2024-11-06T04:42:28Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Thu Oct 5 04:42:28 UTC 2023" -time="2024-11-06T04:42:30Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-11-06T04:42:30Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-11-06T04:42:31Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-11-06T04:42:31Z" level=error msg="Metadata for targets/b expired" -time="2024-11-06T04:42:31Z" level=warning msg="Error getting targets/b: targets/b expired at Thu Oct 5 04:42:31 UTC 2023" ---- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (15.35s) +time="2024-11-06T05:03:51Z" level=error msg="Metadata for targets expired" +time="2024-11-06T05:03:55Z" level=error msg="Metadata for snapshot expired" +time="2024-11-06T05:03:55Z" level=error msg="Metadata for snapshot expired" +time="2024-11-06T05:03:59Z" level=error msg="Metadata for timestamp expired" +time="2024-11-06T05:03:59Z" level=error msg="Metadata for timestamp expired" +time="2024-11-06T05:04:02Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-11-06T05:04:03Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-11-06T05:04:03Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-11-06T05:04:04Z" level=error msg="Metadata for targets/a expired" +time="2024-11-06T05:04:04Z" level=warning msg="Error getting targets/a: targets/a expired at Thu Oct 5 05:04:04 UTC 2023" +time="2024-11-06T05:04:07Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-11-06T05:04:08Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-11-06T05:04:08Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-11-06T05:04:09Z" level=error msg="Metadata for targets/a/b expired" +time="2024-11-06T05:04:09Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Thu Oct 5 05:04:09 UTC 2023" +time="2024-11-06T05:04:12Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-11-06T05:04:13Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-11-06T05:04:13Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-11-06T05:04:14Z" level=error msg="Metadata for targets/b expired" +time="2024-11-06T05:04:14Z" level=warning msg="Error getting targets/b: targets/b expired at Thu Oct 5 05:04:14 UTC 2023" +--- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (29.77s) === RUN TestUpdateLocalAndRemoteRootCorrupt -time="2024-11-06T04:42:39Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:39Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:46Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:46Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:47Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:47Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:47Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:47Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:47Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:48Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:48Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:48Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:48Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:49Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:49Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:49Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:49Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:49Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:50Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:50Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:50Z" level=error msg="Metadata for root expired" -time="2024-11-06T04:42:50Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateLocalAndRemoteRootCorrupt (17.57s) +time="2024-11-06T05:04:28Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:28Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:42Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:43Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:44Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:44Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:44Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:45Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:45Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:46Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:46Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:47Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:47Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:48Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:48Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:48Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:49Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:49Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:50Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:50Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:50Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:04:50Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateLocalAndRemoteRootCorrupt (33.95s) === RUN TestUpdateRemoteKeyRotated -time="2024-11-06T04:42:51Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-11-06T04:42:51Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-11-06T04:42:52Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" ---- PASS: TestUpdateRemoteKeyRotated (1.68s) +time="2024-11-06T05:04:53Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-11-06T05:04:54Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-11-06T05:04:55Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +--- PASS: TestUpdateRemoteKeyRotated (4.32s) === RUN TestValidateRootRotationWithOldRole ---- PASS: TestValidateRootRotationWithOldRole (0.76s) +--- PASS: TestValidateRootRotationWithOldRole (1.87s) === RUN TestRootRoleInvariant ---- PASS: TestRootRoleInvariant (0.68s) +--- PASS: TestRootRoleInvariant (1.29s) === RUN TestBadIntermediateTransitions ---- PASS: TestBadIntermediateTransitions (0.27s) +--- PASS: TestBadIntermediateTransitions (0.74s) === RUN TestExpiredIntermediateTransitions -time="2024-11-06T04:42:54Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" ---- PASS: TestExpiredIntermediateTransitions (0.33s) +time="2024-11-06T05:04:59Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" +--- PASS: TestExpiredIntermediateTransitions (0.80s) === RUN TestDownloadTargetsLarge ---- PASS: TestDownloadTargetsLarge (10.57s) +--- PASS: TestDownloadTargetsLarge (22.06s) === RUN TestDownloadTargetsDeep ---- PASS: TestDownloadTargetsDeep (0.22s) +--- PASS: TestDownloadTargetsDeep (0.67s) === RUN TestDownloadSnapshotLargeDelegationsMany ---- PASS: TestDownloadSnapshotLargeDelegationsMany (9.63s) +--- PASS: TestDownloadSnapshotLargeDelegationsMany (20.82s) === RUN TestRootOnDiskTrustPinning ---- PASS: TestRootOnDiskTrustPinning (0.16s) +--- PASS: TestRootOnDiskTrustPinning (0.41s) === RUN TestLoadTUFRepoBadURL --- PASS: TestLoadTUFRepoBadURL (0.00s) === RUN TestApplyTargetsChange ---- PASS: TestApplyTargetsChange (0.02s) +--- PASS: TestApplyTargetsChange (0.04s) === RUN TestApplyAddTargetTwice --- PASS: TestApplyAddTargetTwice (0.02s) === RUN TestApplyChangelist ---- PASS: TestApplyChangelist (0.02s) +--- PASS: TestApplyChangelist (0.05s) === RUN TestApplyChangelistMulti ---- PASS: TestApplyChangelistMulti (0.02s) +--- PASS: TestApplyChangelistMulti (0.03s) === RUN TestApplyTargetsDelegationCreateDelete --- PASS: TestApplyTargetsDelegationCreateDelete (0.02s) === RUN TestApplyTargetsDelegationCreate2SharedKey @@ -2452,25 +2500,25 @@ === RUN TestApplyChangelistTargetsToMultipleRoles --- PASS: TestApplyChangelistTargetsToMultipleRoles (0.02s) === RUN TestApplyChangelistTargetsFailsNonexistentRole -time="2024-11-06T04:43:15Z" 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-11-06T04:43:15Z" level=error msg="couldn't remove target from targets/level1/level2/level3/level4: tuf: invalid role targets/level1/level2/level3/level4. delegation does not exist" +time="2024-11-06T05:05:44Z" 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-11-06T05:05:44Z" 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-11-06T04:43:15Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" ---- PASS: TestChangeTargetMetaFailsInvalidRole (0.02s) +time="2024-11-06T05:05:44Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" +--- PASS: TestChangeTargetMetaFailsInvalidRole (0.03s) === RUN TestChangeTargetMetaFailsIfPrefixError -time="2024-11-06T04:43:15Z" level=error msg="couldn't add target to targets/level1: could not add all targets" ---- PASS: TestChangeTargetMetaFailsIfPrefixError (0.02s) +time="2024-11-06T05:05:44Z" level=error msg="couldn't add target to targets/level1: could not add all targets" +--- PASS: TestChangeTargetMetaFailsIfPrefixError (0.05s) === RUN TestAllNearExpiry ---- PASS: TestAllNearExpiry (0.02s) +--- PASS: TestAllNearExpiry (0.05s) === RUN TestAllNotNearExpiry ---- PASS: TestAllNotNearExpiry (0.02s) +--- PASS: TestAllNotNearExpiry (0.05s) === RUN TestRotateRemoteKeyOffline --- PASS: TestRotateRemoteKeyOffline (0.00s) PASS -ok github.com/theupdateframework/notary/client 157.686s +ok github.com/theupdateframework/notary/client 291.380s === RUN TestTUFDelegation ---- PASS: TestTUFDelegation (0.03s) +--- PASS: TestTUFDelegation (0.06s) === RUN TestMemChangelist --- PASS: TestMemChangelist (0.00s) === RUN TestMemChangeIterator @@ -2480,14 +2528,14 @@ === RUN TestAdd --- PASS: TestAdd (0.00s) === RUN TestErrorConditions -time="2024-11-06T04:43:18Z" level=warning msg="invalid character '\\x05' looking for beginning of value" +time="2024-11-06T05:00:53Z" level=warning msg="invalid character '\\x05' looking for beginning of value" --- PASS: TestErrorConditions (0.00s) === RUN TestListOrder --- PASS: TestListOrder (0.00s) === RUN TestFileChangeIterator ---- PASS: TestFileChangeIterator (0.00s) +--- PASS: TestFileChangeIterator (0.01s) PASS -ok github.com/theupdateframework/notary/client/changelist 0.051s +ok github.com/theupdateframework/notary/client/changelist 0.108s === RUN TestParseConfigError --- PASS: TestParseConfigError (0.00s) === RUN TestSetupGRPCServer @@ -2497,7 +2545,7 @@ === RUN TestSetupNetListener --- PASS: TestSetupNetListener (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/escrow 0.026s +ok github.com/theupdateframework/notary/cmd/escrow 0.034s === RUN TestPurgeDelegationKeys Usage: delegation [command] @@ -2523,7 +2571,7 @@ - abc --- PASS: TestPurgeDelegationKeys (0.01s) === RUN TestAddInvalidDelegationName ---- PASS: TestAddInvalidDelegationName (0.03s) +--- PASS: TestAddInvalidDelegationName (0.05s) === RUN TestAddInvalidDelegationCert --- PASS: TestAddInvalidDelegationCert (0.01s) === RUN TestAddInvalidShortPubkeyCert @@ -2569,79 +2617,79 @@ Use "delegation [command] --help" for more information about a command. --- PASS: TestRemoveInvalidNumArgs (0.00s) === RUN TestInitWithRootKey ---- PASS: TestInitWithRootKey (0.46s) +--- PASS: TestInitWithRootKey (0.67s) === RUN TestInitWithRootCert ---- PASS: TestInitWithRootCert (0.33s) +--- PASS: TestInitWithRootCert (0.59s) === RUN TestClientTUFInteraction ---- PASS: TestClientTUFInteraction (0.83s) +--- PASS: TestClientTUFInteraction (1.57s) === RUN TestClientDeleteTUFInteraction -time="2024-11-06T04:43: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]:59736->[::1]:53: read: connection refused" ---- PASS: TestClientDeleteTUFInteraction (0.85s) +time="2024-11-06T05:01:50Z" 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]:33479->[::1]:53: read: connection refused" +--- PASS: TestClientDeleteTUFInteraction (1.46s) === RUN TestClientTUFAddByHashInteraction ---- PASS: TestClientTUFAddByHashInteraction (1.44s) +--- PASS: TestClientTUFAddByHashInteraction (2.93s) === RUN TestClientDelegationsInteraction -time="2024-11-06T04:43:54Z" 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-11-06T04:43:54Z" 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.43s) +time="2024-11-06T05:01: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-11-06T05:01: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 (4.99s) === RUN TestClientDelegationsPublishing ---- PASS: TestClientDelegationsPublishing (3.76s) +--- PASS: TestClientDelegationsPublishing (5.64s) === RUN TestClientKeyGenerationRotation ---- PASS: TestClientKeyGenerationRotation (3.98s) +--- PASS: TestClientKeyGenerationRotation (6.02s) === RUN TestKeyRotation ---- PASS: TestKeyRotation (0.54s) +--- PASS: TestKeyRotation (0.67s) === RUN TestKeyRotationNonRoot ---- PASS: TestKeyRotationNonRoot (0.74s) +--- PASS: TestKeyRotationNonRoot (1.20s) === RUN TestDefaultRootKeyGeneration ---- PASS: TestDefaultRootKeyGeneration (0.01s) +--- PASS: TestDefaultRootKeyGeneration (0.02s) === RUN TestLogLevelFlags --- PASS: TestLogLevelFlags (0.00s) === RUN TestClientKeyPassphraseChange ---- PASS: TestClientKeyPassphraseChange (0.62s) +--- PASS: TestClientKeyPassphraseChange (0.83s) === RUN TestPurgeSingleKey Removal of the following keys from all delegations in gun staged for next publish: - - 46d3c5e9ead3509285047b7c4782b32d7b8250c1ee980f70e244b172613bf219 + - f6947532bb4baa9d8b11337cef3669b2791f0672eea2982de045e84176b694bd --- PASS: TestPurgeSingleKey (0.61s) === RUN TestWitness -time="2024-11-06T04:44:07Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-11-06T04:44:08Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-11-06T04:44:08Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-11-06T04:44:08Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-11-06T04:44: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-11-06T04:44: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-11-06T04:44:08Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" ---- PASS: TestWitness (1.89s) +time="2024-11-06T05:02:14Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-11-06T05:02:14Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-11-06T05:02:15Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-11-06T05:02:15Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-11-06T05:02: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-11-06T05:02: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-11-06T05:02:15Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +--- PASS: TestWitness (2.35s) === RUN TestClientTUFInitWithAutoPublish -time="2024-11-06T04:44: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]:50573->[::1]:53: read: connection refused" ---- PASS: TestClientTUFInitWithAutoPublish (0.43s) +time="2024-11-06T05:02: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]:52806->[::1]:53: read: connection refused" +--- PASS: TestClientTUFInitWithAutoPublish (0.82s) === RUN TestClientTUFAddWithAutoPublish -time="2024-11-06T04:44: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]:43765->[::1]:53: read: connection refused" -time="2024-11-06T04:44:09Z" level=error msg="Could not publish Repository since we could not update: client is offline" -time="2024-11-06T04:44: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]:55157->[::1]:53: read: connection refused" -time="2024-11-06T04:44:09Z" level=error msg="Could not publish Repository since we could not update: client is offline" ---- PASS: TestClientTUFAddWithAutoPublish (0.49s) +time="2024-11-06T05:02: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]:37454->[::1]:53: read: connection refused" +time="2024-11-06T05:02:16Z" level=error msg="Could not publish Repository since we could not update: client is offline" +time="2024-11-06T05:02:17Z" level=error msg="could not reach https://notary-server-on-the-moon:12306: Get \"https://notary-server-on-the-moon:12306/v2/\": dial tcp: lookup notary-server-on-the-moon on [::1]:53: read udp [::1]:53346->[::1]:53: read: connection refused" +time="2024-11-06T05:02:17Z" level=error msg="Could not publish Repository since we could not update: client is offline" +--- PASS: TestClientTUFAddWithAutoPublish (0.65s) === RUN TestClientTUFRemoveWithAutoPublish ---- PASS: TestClientTUFRemoveWithAutoPublish (0.98s) +--- PASS: TestClientTUFRemoveWithAutoPublish (1.66s) === RUN TestClientDelegationAddWithAutoPublish ---- PASS: TestClientDelegationAddWithAutoPublish (0.47s) +--- PASS: TestClientDelegationAddWithAutoPublish (0.95s) === RUN TestClientDelegationRemoveWithAutoPublish -time="2024-11-06T04:44: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-11-06T04:44: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.98s) +time="2024-11-06T05:02:21Z" 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-11-06T05:02:21Z" 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.24s) === RUN TestClientTUFAddByHashWithAutoPublish ---- PASS: TestClientTUFAddByHashWithAutoPublish (0.45s) +--- PASS: TestClientTUFAddByHashWithAutoPublish (0.78s) === RUN TestClientKeyImport -time="2024-11-06T04:44: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-11-06T04:44: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.15s) +time="2024-11-06T05:02:22Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" +time="2024-11-06T05:02: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" +--- PASS: TestClientKeyImport (0.28s) === RUN TestAddDelImportKeyPublishFlow -time="2024-11-06T04:44: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.08s) +time="2024-11-06T05:02:23Z" 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.25s) === RUN TestExportImportFlow ---- PASS: TestExportImportFlow (0.29s) +--- PASS: TestExportImportFlow (0.27s) === RUN TestDelegationKeyImportExport ---- PASS: TestDelegationKeyImportExport (0.03s) +--- PASS: TestDelegationKeyImportExport (0.02s) === RUN TestImportKeysNoYubikey ---- PASS: TestImportKeysNoYubikey (0.03s) +--- PASS: TestImportKeysNoYubikey (0.02s) === RUN TestExportImportKeysNoYubikey --- PASS: TestExportImportKeysNoYubikey (0.03s) === RUN TestRemoveIfNoKey @@ -2657,16 +2705,16 @@ === RUN TestRemoveMultikeysRemoveOnlyChosenKey --- PASS: TestRemoveMultikeysRemoveOnlyChosenKey (0.02s) === RUN TestRotateKeyInvalidRoles -time="2024-11-06T04:44: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]:56602->[::1]:53: read: connection refused" -time="2024-11-06T04:44: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]:48243->[::1]:53: read: connection refused" -time="2024-11-06T04:44: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]:60646->[::1]:53: read: connection refused" -time="2024-11-06T04:44: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]:50319->[::1]:53: read: connection refused" +time="2024-11-06T05:02:24Z" 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]:57179->[::1]:53: read: connection refused" +time="2024-11-06T05:02:24Z" 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]:58205->[::1]:53: read: connection refused" +time="2024-11-06T05:02:24Z" 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]:56099->[::1]:53: read: connection refused" +time="2024-11-06T05:02:24Z" 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]:44516->[::1]:53: read: connection refused" --- PASS: TestRotateKeyInvalidRoles (0.01s) === RUN TestRotateKeyTargetCannotBeServerManaged -time="2024-11-06T04:44: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]:49274->[::1]:53: read: connection refused" +time="2024-11-06T05:02:24Z" 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]:54143->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTargetCannotBeServerManaged (0.00s) === RUN TestRotateKeyTimestampCannotBeLocallyManaged -time="2024-11-06T04:44: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]:36303->[::1]:53: read: connection refused" +time="2024-11-06T05:02:24Z" 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]:42375->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTimestampCannotBeLocallyManaged (0.00s) === RUN TestRotateKeyNoGUN Usage: @@ -2674,13 +2722,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.40s) +--- PASS: TestRotateKeyBothKeys (0.69s) === RUN TestRotateKeyRootIsInteractive ---- PASS: TestRotateKeyRootIsInteractive (0.11s) +--- PASS: TestRotateKeyRootIsInteractive (0.09s) === RUN TestChangeKeyPassphraseInvalidID --- PASS: TestChangeKeyPassphraseInvalidID (0.00s) === RUN TestChangeKeyPassphraseInvalidNumArgs @@ -2689,18 +2737,18 @@ === RUN TestChangeKeyPassphraseNonexistentID --- PASS: TestChangeKeyPassphraseNonexistentID (0.00s) === RUN TestExportKeys ---- PASS: TestExportKeys (0.00s) +--- PASS: TestExportKeys (0.01s) === RUN TestExportKeysByGUN ---- PASS: TestExportKeysByGUN (0.00s) +--- PASS: TestExportKeysByGUN (0.01s) === RUN TestExportKeysByID ---- PASS: TestExportKeysByID (0.00s) +--- PASS: TestExportKeysByID (0.01s) === RUN TestExportKeysBadFlagCombo --- PASS: TestExportKeysBadFlagCombo (0.00s) === RUN TestImportKeysNonexistentFile --- PASS: TestImportKeysNonexistentFile (0.00s) === RUN TestKeyGeneration -time="2024-11-06T04:44: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) +time="2024-11-06T05:02:25Z" 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.08s) === RUN TestNotaryConfigFileDefault --- PASS: TestNotaryConfigFileDefault (0.00s) === RUN TestRemoteServerDefault @@ -2710,19 +2758,19 @@ === RUN TestRemoteServerCommandLineFlagOverridesConfig --- PASS: TestRemoteServerCommandLineFlagOverridesConfig (0.00s) === RUN TestInvalidAddHashCommands ---- PASS: TestInvalidAddHashCommands (0.01s) +--- PASS: TestInvalidAddHashCommands (0.02s) === RUN TestConfigParsingErrorsPropagatedByCommands ---- PASS: TestConfigParsingErrorsPropagatedByCommands (0.01s) +--- PASS: TestConfigParsingErrorsPropagatedByCommands (0.03s) === RUN TestInsufficientArgumentsReturnsErrorAndPrintsUsage --- PASS: TestInsufficientArgumentsReturnsErrorAndPrintsUsage (0.04s) === RUN TestBareCommandPrintsUsageAndNoError ---- PASS: TestBareCommandPrintsUsageAndNoError (0.01s) +--- PASS: TestBareCommandPrintsUsageAndNoError (0.02s) === RUN TestConfigFileTLSCannotBeRelativeToCWD ---- PASS: TestConfigFileTLSCannotBeRelativeToCWD (0.02s) +--- PASS: TestConfigFileTLSCannotBeRelativeToCWD (0.04s) === RUN TestConfigFileTLSCanBeRelativeToConfigOrAbsolute ---- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.21s) +--- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.25s) === RUN TestConfigFileOverridenByCmdLineFlags ---- PASS: TestConfigFileOverridenByCmdLineFlags (0.22s) +--- PASS: TestConfigFileOverridenByCmdLineFlags (0.20s) === RUN TestConfigFileTrustPinning --- PASS: TestConfigFileTrustPinning (0.00s) === RUN TestPassphraseRetrieverCaching @@ -2748,10 +2796,10 @@ === RUN TestImportRootCert --- PASS: TestImportRootCert (0.00s) === RUN TestTokenAuth -time="2024-11-06T04:44:16Z" 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-11-06T05:02:26Z" 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-11-06T04:44:16Z" 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-11-06T05:02:26Z" 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) @@ -2760,21 +2808,21 @@ === RUN TestTokenAuth401Status --- PASS: TestTokenAuth401Status (0.00s) === RUN TestAdminTokenAuth401Status ---- PASS: TestAdminTokenAuth401Status (0.01s) +--- PASS: TestAdminTokenAuth401Status (0.00s) === RUN TestTokenAuthNon200Non401Status -time="2024-11-06T04:44:16Z" level=error msg="could not reach http://127.0.0.1:37363: 404" ---- PASS: TestTokenAuthNon200Non401Status (0.01s) +time="2024-11-06T05:02:26Z" level=error msg="could not reach http://127.0.0.1:33495: 404" +--- PASS: TestTokenAuthNon200Non401Status (0.00s) === RUN TestAdminTokenAuthNon200Non401Status -time="2024-11-06T04:44:16Z" level=error msg="could not reach http://127.0.0.1:38441: 404" +time="2024-11-06T05:02:26Z" level=error msg="could not reach http://127.0.0.1:46825: 404" --- PASS: TestAdminTokenAuthNon200Non401Status (0.00s) === RUN TestConfigureRepo ---- PASS: TestConfigureRepo (0.01s) +--- PASS: TestConfigureRepo (0.00s) === RUN TestConfigureRepoRW -Enter username: time="2024-11-06T04:44:16Z" level=error msg="error processing username input: EOF" ---- PASS: TestConfigureRepoRW (0.01s) +Enter username: time="2024-11-06T05:02:26Z" level=error msg="error processing username input: EOF" +--- PASS: TestConfigureRepoRW (0.00s) === RUN TestConfigureRepoAdmin -Enter username: time="2024-11-06T04:44:16Z" level=error msg="error processing username input: EOF" ---- PASS: TestConfigureRepoAdmin (0.01s) +Enter username: time="2024-11-06T05:02:26Z" level=error msg="error processing username input: EOF" +--- PASS: TestConfigureRepoAdmin (0.00s) === RUN TestStatusUnstageAndReset Successfully reset specified changes for repository gun Addition of target "test1" by sha256 hash to repository "gun" staged for next publish. @@ -2786,11 +2834,11 @@ Pushing changes to gun --- PASS: TestGetTrustPinningErrors (0.00s) === RUN TestPasswordStore -Enter username: time="2024-11-06T04:44:16Z" level=error msg="error processing username input: EOF" +Enter username: time="2024-11-06T05:02:26Z" level=error msg="error processing username input: EOF" --- PASS: TestPasswordStore (0.00s) === RUN TestPasswordStoreWithEnvvar -time="2024-11-06T04:44:16Z" level=error msg="Authentication string with zero-length username" -time="2024-11-06T04:44:16Z" level=error msg="Could not base64-decode authentication string" +time="2024-11-06T05:02:26Z" level=error msg="Authentication string with zero-length username" +time="2024-11-06T05:02:26Z" level=error msg="Could not base64-decode authentication string" --- PASS: TestPasswordStoreWithEnvvar (0.00s) === RUN TestGetPayload --- PASS: TestGetPayload (0.00s) @@ -2799,7 +2847,7 @@ === RUN TestHomeExpand --- PASS: TestHomeExpand (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary 26.818s +ok github.com/theupdateframework/notary/cmd/notary 39.887s === RUN TestBootstrap --- PASS: TestBootstrap (0.00s) === RUN TestGetAddrAndTLSConfigInvalidTLS @@ -2807,7 +2855,7 @@ === RUN TestGetAddrAndTLSConfigNoHTTPAddr --- PASS: TestGetAddrAndTLSConfigNoHTTPAddr (0.00s) === RUN TestGetAddrAndTLSConfigSuccessWithTLS ---- PASS: TestGetAddrAndTLSConfigSuccessWithTLS (0.00s) +--- PASS: TestGetAddrAndTLSConfigSuccessWithTLS (0.01s) === RUN TestGetAddrAndTLSConfigSuccessWithoutTLS --- PASS: TestGetAddrAndTLSConfigSuccessWithoutTLS (0.00s) === RUN TestGetAddrAndTLSConfigWithClientTLS @@ -2815,46 +2863,46 @@ === RUN TestGetInvalidTrustService --- PASS: TestGetInvalidTrustService (0.00s) === RUN TestGetLocalTrustService -time="2024-11-06T04:44:38Z" level=info msg="Using local signing service, which requires ED25519. Ignoring all other trust_service parameters, including keyAlgorithm" +time="2024-11-06T05:03:03Z" 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-11-06T04:44:38Z" level=info msg="Using remote signing service" +time="2024-11-06T05:03:03Z" level=info msg="Using remote signing service" --- PASS: TestGetTrustServiceNoTLSConfig (0.00s) === RUN TestGetTrustServiceTLSSuccess -time="2024-11-06T04:44:38Z" level=info msg="Using remote signing service" ---- PASS: TestGetTrustServiceTLSSuccess (0.00s) +time="2024-11-06T05:03:03Z" level=info msg="Using remote signing service" +--- PASS: TestGetTrustServiceTLSSuccess (0.01s) === RUN TestGetTrustServiceTLSFailure --- PASS: TestGetTrustServiceTLSFailure (0.00s) === RUN TestGetStoreInvalid -time="2024-11-06T04:44:38Z" level=info msg="Using asdf backend" +time="2024-11-06T05:03:03Z" level=info msg="Using asdf backend" --- PASS: TestGetStoreInvalid (0.00s) === RUN TestGetStoreDBStore -time="2024-11-06T04:44:38Z" level=info msg="Using sqlite3 backend" ---- PASS: TestGetStoreDBStore (0.00s) +time="2024-11-06T05:03:03Z" level=info msg="Using sqlite3 backend" +--- PASS: TestGetStoreDBStore (0.05s) === RUN TestGetStoreRethinkDBStoreConnectionFails -time="2024-11-06T04:44:38Z" level=info msg="Using rethinkdb backend" +time="2024-11-06T05:03:03Z" level=info msg="Using rethinkdb backend" --- PASS: TestGetStoreRethinkDBStoreConnectionFails (0.00s) === RUN TestGetMemoryStore -time="2024-11-06T04:44:38Z" level=info msg="Using memory backend" +time="2024-11-06T05:03:03Z" 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-11-06T04:44:38Z" level=info msg="Using remote signing service" -time="2024-11-06T04:44:38Z" level=info msg="Using sqlite3 backend" +time="2024-11-06T05:03:03Z" level=info msg="Using remote signing service" +time="2024-11-06T05:03:03Z" level=info msg="Using sqlite3 backend" --- PASS: TestSampleConfig (0.01s) PASS -ok github.com/theupdateframework/notary/cmd/notary-server 0.057s +ok github.com/theupdateframework/notary/cmd/notary-server 0.177s === RUN TestGetAddrAndTLSConfigInvalidTLS --- PASS: TestGetAddrAndTLSConfigInvalidTLS (0.00s) === RUN TestGetAddrAndTLSConfigNoGRPCAddr ---- PASS: TestGetAddrAndTLSConfigNoGRPCAddr (0.01s) +--- PASS: TestGetAddrAndTLSConfigNoGRPCAddr (0.00s) === RUN TestGetAddrAndTLSConfigSuccess --- PASS: TestGetAddrAndTLSConfigSuccess (0.00s) === RUN TestSetupCryptoServicesDBStoreNoDefaultAlias @@ -2865,9 +2913,9 @@ main_test.go:121: DM - skipping malfunctioning test, fails on sbuild --- SKIP: TestSetupCryptoServicesRethinkDBStoreConnectionFails (0.00s) === RUN TestSetupCryptoServicesDBStoreSuccess ---- PASS: TestSetupCryptoServicesDBStoreSuccess (0.11s) +--- PASS: TestSetupCryptoServicesDBStoreSuccess (0.28s) === RUN TestSetupCryptoServicesMemoryStore ---- PASS: TestSetupCryptoServicesMemoryStore (0.01s) +--- PASS: TestSetupCryptoServicesMemoryStore (0.03s) === RUN TestSetupCryptoServicesInvalidStore --- PASS: TestSetupCryptoServicesInvalidStore (0.00s) === RUN TestSetupGRPCServerInvalidAddress @@ -2881,19 +2929,19 @@ === RUN TestPassphraseRetrieverInvalid --- PASS: TestPassphraseRetrieverInvalid (0.00s) === RUN TestSampleConfig ---- PASS: TestSampleConfig (0.00s) +--- PASS: TestSampleConfig (0.01s) PASS -ok github.com/theupdateframework/notary/cmd/notary-signer 0.173s +ok github.com/theupdateframework/notary/cmd/notary-signer 0.345s === RUN TestGenerateCertificate --- PASS: TestGenerateCertificate (0.03s) === RUN TestCryptoServiceWithNonEmptyGUN ---- PASS: TestCryptoServiceWithNonEmptyGUN (1.85s) +--- PASS: TestCryptoServiceWithNonEmptyGUN (3.26s) === RUN TestCryptoServiceWithEmptyGUN ---- PASS: TestCryptoServiceWithEmptyGUN (1.88s) +--- PASS: TestCryptoServiceWithEmptyGUN (4.00s) === RUN TestCryptoSignerInterfaceBehavior ---- PASS: TestCryptoSignerInterfaceBehavior (0.03s) +--- PASS: TestCryptoSignerInterfaceBehavior (0.07s) PASS -ok github.com/theupdateframework/notary/cryptoservice 3.801s +ok github.com/theupdateframework/notary/cryptoservice 7.381s === RUN TestGetPassphraseForUsingDelegationKey --- PASS: TestGetPassphraseForUsingDelegationKey (0.00s) === RUN TestGetPassphraseLimitsShortPassphrases @@ -2905,285 +2953,282 @@ === RUN TestRolePromptingAndCaching --- PASS: TestRolePromptingAndCaching (0.00s) === RUN TestPromptRetrieverNeedsTerminal ---- PASS: TestPromptRetrieverNeedsTerminal (0.00s) +--- PASS: TestPromptRetrieverNeedsTerminal (0.01s) === RUN TestGetPassphrase --- PASS: TestGetPassphrase (0.00s) PASS -ok github.com/theupdateframework/notary/passphrase 0.013s -? github.com/theupdateframework/notary/proto [no test files] -? github.com/theupdateframework/notary/proto/github.com/theupdateframework/notary/proto [no test files] +ok github.com/theupdateframework/notary/passphrase 0.051s === RUN TestValidationErrorFormat -time="2024-11-06T04:44:55Z" 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.23.2 http.request.contenttype="multipart/form-data; boundary=3309f76bd3d6b1f8042d9263c134d4c2111357d0be46ba918c05df629b67" http.request.host="127.0.0.1:39175" http.request.id=8d09688e-375e-4f7f-a428-f829f8a915d9 http.request.method=POST http.request.remoteaddr="127.0.0.1:39436" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype="multipart/form-data; boundary=3309f76bd3d6b1f8042d9263c134d4c2111357d0be46ba918c05df629b67" http.request.host="127.0.0.1:39175" http.request.id=8d09688e-375e-4f7f-a428-f829f8a915d9 http.request.method=POST http.request.remoteaddr="127.0.0.1:39436" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration=14.230271ms http.response.status=400 http.response.written=260 ---- PASS: TestValidationErrorFormat (0.10s) +time="2024-11-06T05:03:37Z" 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.23.2 http.request.contenttype="multipart/form-data; boundary=cf75713f622b1bc5dbbef488a846815c7a622a32fdbe5ab52fe60e690cd2" http.request.host="127.0.0.1:35807" http.request.id=fa371459-43a7-4bf7-8a10-1d5be04d0ade http.request.method=POST http.request.remoteaddr="127.0.0.1:52956" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype="multipart/form-data; boundary=cf75713f622b1bc5dbbef488a846815c7a622a32fdbe5ab52fe60e690cd2" http.request.host="127.0.0.1:35807" http.request.id=fa371459-43a7-4bf7-8a10-1d5be04d0ade http.request.method=POST http.request.remoteaddr="127.0.0.1:52956" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration=26.62154ms http.response.status=400 http.response.written=260 +--- PASS: TestValidationErrorFormat (0.22s) === RUN TestRunBadAddr --- PASS: TestRunBadAddr (0.00s) === RUN TestRepoPrefixMatches -time="2024-11-06T04:44:55Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum e463e5e0664682b95f176134ad894df4c2f1fed154bab98b5ae84eb2a60e9da1" docker.io/notary=gun go.version=go1.23.2 http.request.contenttype="multipart/form-data; boundary=be7cfc54a492a9db3544b38d9ef0f18866c5443dcc2a9d190571ff67858d" http.request.host="127.0.0.1:37567" http.request.id=0dc0160e-1e07-4174-97fe-62c6ba70170c http.request.method=POST http.request.remoteaddr="127.0.0.1:34968" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype="multipart/form-data; boundary=be7cfc54a492a9db3544b38d9ef0f18866c5443dcc2a9d190571ff67858d" http.request.host="127.0.0.1:37567" http.request.id=0dc0160e-1e07-4174-97fe-62c6ba70170c http.request.method=POST http.request.remoteaddr="127.0.0.1:34968" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=26.732824ms http.response.status=0 http.response.written=0 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:37567" http.request.id=e0e79ba6-781e-40d2-bdcd-0ed3761b6c7f http.request.method=GET http.request.remoteaddr="127.0.0.1:34968" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration=1.129635ms http.response.status=200 http.response.written=683 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:37567" http.request.id=e9b30adc-3165-43c1-877f-0d84725e8285 http.request.method=GET http.request.remoteaddr="127.0.0.1:34968" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.cabf444ab1fc632718a1cfc7d727a71c7bd455054e5dd6b849b2ae903479ee37.json http.request.useragent=Go-http-client/1.1 http.response.duration="77.773µs" http.response.status=200 http.response.written=683 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:37567" http.request.id=8d057b0d-7cab-486f-a449-55a77f300fae http.request.method=GET http.request.remoteaddr="127.0.0.1:34968" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="771.248µs" http.response.status=200 http.response.written=181 -time="2024-11-06T04:44:55Z" level=info msg="trust data deleted for docker.io/notary" docker.io/notary=gun go.version=go1.23.2 http.request.host="127.0.0.1:37567" http.request.id=7dfc8316-ac5a-4b5d-9b3f-488298d795c8 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:34968" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:37567" http.request.id=7dfc8316-ac5a-4b5d-9b3f-488298d795c8 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:34968" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="142.247µs" http.response.status=0 http.response.written=0 ---- PASS: TestRepoPrefixMatches (0.09s) +time="2024-11-06T05:03:37Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum 1aee975e08a70523ca5f98258311dfcb260b121dcb319846c500fcfe5f4e2e50" docker.io/notary=gun go.version=go1.23.2 http.request.contenttype="multipart/form-data; boundary=83dda0315f7fdffcfec935151043d747b4740a4810ed406d7172789ba1c7" http.request.host="127.0.0.1:37497" http.request.id=b78848b4-b7ae-484b-9f79-db05b6d20de0 http.request.method=POST http.request.remoteaddr="127.0.0.1:49718" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype="multipart/form-data; boundary=83dda0315f7fdffcfec935151043d747b4740a4810ed406d7172789ba1c7" http.request.host="127.0.0.1:37497" http.request.id=b78848b4-b7ae-484b-9f79-db05b6d20de0 http.request.method=POST http.request.remoteaddr="127.0.0.1:49718" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=42.643075ms http.response.status=0 http.response.written=0 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:37497" http.request.id=7cda25cd-fa0e-4f82-ac17-19f6f20db4ae http.request.method=GET http.request.remoteaddr="127.0.0.1:49718" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration=1.184409ms http.response.status=200 http.response.written=683 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:37497" http.request.id=3ebf321a-836e-42c2-99c3-60bcba752889 http.request.method=GET http.request.remoteaddr="127.0.0.1:49718" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.06b9fc190c0809467c03657f73f598f646b3eb8422cb5822463e5f01d551ad09.json http.request.useragent=Go-http-client/1.1 http.response.duration="83.832µs" http.response.status=200 http.response.written=683 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:37497" http.request.id=19cbd3a5-23b1-4cbc-bf14-f3f6849c848e http.request.method=GET http.request.remoteaddr="127.0.0.1:49718" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="814.643µs" http.response.status=200 http.response.written=181 +time="2024-11-06T05:03:37Z" level=info msg="trust data deleted for docker.io/notary" docker.io/notary=gun go.version=go1.23.2 http.request.host="127.0.0.1:37497" http.request.id=560b6f2a-0eb4-4af4-9216-1cd61f0aa956 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:49718" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:37497" http.request.id=560b6f2a-0eb4-4af4-9216-1cd61f0aa956 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:49718" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="144.746µs" http.response.status=0 http.response.written=0 +--- PASS: TestRepoPrefixMatches (0.15s) === RUN TestRepoPrefixDoesNotMatch ---- PASS: TestRepoPrefixDoesNotMatch (0.06s) +--- PASS: TestRepoPrefixDoesNotMatch (0.12s) === RUN TestMetricsEndpoint ---- PASS: TestMetricsEndpoint (0.01s) +--- PASS: TestMetricsEndpoint (0.03s) === RUN TestGetKeysEndpoint -time="2024-11-06T04:44:55Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.23.2 http.request.host="127.0.0.1:34139" http.request.id=9c68e339-0233-43b1-9532-21a5e68a07e8 http.request.method=GET http.request.remoteaddr="127.0.0.1:55426" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:34139" http.request.id=9c68e339-0233-43b1-9532-21a5e68a07e8 http.request.method=GET http.request.remoteaddr="127.0.0.1:55426" 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="224.799µs" http.response.status=404 http.response.written=104 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:34139" http.request.id=97604dcf-5ce7-4e4e-a49a-675ac8486502 http.request.method=GET http.request.remoteaddr="127.0.0.1:55430" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration=27.698594ms http.response.status=200 http.response.written=103 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:34139" http.request.id=1260ef98-6f06-4222-b7ad-7bde49167aea http.request.method=GET http.request.remoteaddr="127.0.0.1:55432" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="652.779µs" http.response.status=200 http.response.written=103 -time="2024-11-06T04:44:55Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.23.2 http.request.host="127.0.0.1:34139" http.request.id=6acdab1c-e728-4897-b17e-261b1575f363 http.request.method=GET http.request.remoteaddr="127.0.0.1:55440" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:34139" http.request.id=6acdab1c-e728-4897-b17e-261b1575f363 http.request.method=GET http.request.remoteaddr="127.0.0.1:55440" 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="163.705µs" http.response.status=404 http.response.written=104 -time="2024-11-06T04:44:55Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.23.2 http.request.host="127.0.0.1:34139" http.request.id=5cb7a84f-3106-497e-83b4-7958a8ef4844 http.request.method=GET http.request.remoteaddr="127.0.0.1:55456" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:34139" http.request.id=5cb7a84f-3106-497e-83b4-7958a8ef4844 http.request.method=GET http.request.remoteaddr="127.0.0.1:55456" 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="208.901µs" http.response.status=404 http.response.written=104 ---- PASS: TestGetKeysEndpoint (0.04s) +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:45377" http.request.id=c96f1068-65b0-4946-b045-38a66a1acd04 http.request.method=GET http.request.remoteaddr="127.0.0.1:59294" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration=56.305532ms http.response.status=200 http.response.written=103 +time="2024-11-06T05:03:37Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.23.2 http.request.host="127.0.0.1:45377" http.request.id=7ee590de-c66b-40a2-8867-9df1b3195eb9 http.request.method=GET http.request.remoteaddr="127.0.0.1:59306" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:45377" http.request.id=7ee590de-c66b-40a2-8867-9df1b3195eb9 http.request.method=GET http.request.remoteaddr="127.0.0.1:59306" 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="210.081µs" http.response.status=404 http.response.written=104 +time="2024-11-06T05:03:37Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.23.2 http.request.host="127.0.0.1:45377" http.request.id=42575a4d-d579-481c-aceb-f8071f46aba1 http.request.method=GET http.request.remoteaddr="127.0.0.1:59318" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:45377" http.request.id=42575a4d-d579-481c-aceb-f8071f46aba1 http.request.method=GET http.request.remoteaddr="127.0.0.1:59318" 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=4.246941ms http.response.status=404 http.response.written=104 +time="2024-11-06T05:03:37Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.23.2 http.request.host="127.0.0.1:45377" http.request.id=6529d023-358f-42c2-9a17-4fa4d9cc6033 http.request.method=GET http.request.remoteaddr="127.0.0.1:59334" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:45377" http.request.id=6529d023-358f-42c2-9a17-4fa4d9cc6033 http.request.method=GET http.request.remoteaddr="127.0.0.1:59334" 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="176.764µs" http.response.status=404 http.response.written=104 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:45377" http.request.id=e54db081-2e4b-4456-a7a6-5b31aeac8565 http.request.method=GET http.request.remoteaddr="127.0.0.1:59348" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="674.497µs" http.response.status=200 http.response.written=103 +--- PASS: TestGetKeysEndpoint (0.09s) === RUN TestGetRoleByHash -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:37399" http.request.id=487ce27a-a4b1-4593-8b14-00f23bf5d35e http.request.method=GET http.request.remoteaddr="127.0.0.1:58830" http.request.uri=/v2/gun/_trust/tuf/timestamp.4ef0921ee242505052fbdb09cd7bdb2a50694685ab986c1e860a6c319e8fb77a.json http.request.useragent=Go-http-client/1.1 http.response.duration="109.909µs" http.response.status=200 http.response.written=115 ---- PASS: TestGetRoleByHash (0.01s) +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:34229" http.request.id=d848d1e9-5ded-4beb-a21e-7dff1f640f1c http.request.method=GET http.request.remoteaddr="127.0.0.1:44196" http.request.uri=/v2/gun/_trust/tuf/timestamp.54c171940e65a2fac40fef9ce9fa5cca79e088497cd17cbc62216f560350a100.json http.request.useragent=Go-http-client/1.1 http.response.duration="139.887µs" http.response.status=200 http.response.written=115 +--- PASS: TestGetRoleByHash (0.03s) === RUN TestGetRoleByVersion -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:41015" http.request.id=091b3ce9-73fe-41b9-a0f9-fa8bbb78bbd1 http.request.method=GET http.request.remoteaddr="127.0.0.1:58166" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="126.028µs" http.response.status=200 http.response.written=115 ---- PASS: TestGetRoleByVersion (0.01s) +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:42259" http.request.id=467a9494-e2c9-4ea0-a7e5-90ba84bab598 http.request.method=GET http.request.remoteaddr="127.0.0.1:47426" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="190.962µs" http.response.status=200 http.response.written=115 +--- PASS: TestGetRoleByVersion (0.03s) === RUN TestGetCurrentRole -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:36835" http.request.id=fd381270-1cb8-4bcf-aab9-351303cf4ca4 http.request.method=GET http.request.remoteaddr="127.0.0.1:40122" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="934.932µs" http.response.status=200 http.response.written=495 ---- PASS: TestGetCurrentRole (0.07s) +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:33923" http.request.id=f8d35c2c-3b3b-493b-a23c-2ec3b487dc5e http.request.method=GET http.request.remoteaddr="127.0.0.1:45472" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration=2.931065ms http.response.status=200 http.response.written=495 +--- PASS: TestGetCurrentRole (0.13s) === RUN TestRotateKeyEndpoint -time="2024-11-06T04:44:55Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:38433" http.request.id=11b2917c-0865-4c54-bde3-c6c01a92e306 http.request.method=POST http.request.remoteaddr="127.0.0.1:60512" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:38433" http.request.id=11b2917c-0865-4c54-bde3-c6c01a92e306 http.request.method=POST http.request.remoteaddr="127.0.0.1:60512" 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="248.376µs" http.response.status=404 http.response.written=104 -time="2024-11-06T04:44:55Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:38433" http.request.id=41c0a8aa-4d6d-4c3e-8231-46b80a7cf9b5 http.request.method=POST http.request.remoteaddr="127.0.0.1:60520" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:38433" http.request.id=41c0a8aa-4d6d-4c3e-8231-46b80a7cf9b5 http.request.method=POST http.request.remoteaddr="127.0.0.1:60520" 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="206.821µs" http.response.status=404 http.response.written=104 -time="2024-11-06T04:44:55Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:38433" http.request.id=20c10409-1134-45ab-9a81-8a63f0132ad6 http.request.method=POST http.request.remoteaddr="127.0.0.1:60524" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:38433" http.request.id=20c10409-1134-45ab-9a81-8a63f0132ad6 http.request.method=POST http.request.remoteaddr="127.0.0.1:60524" 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="165.004µs" http.response.status=404 http.response.written=104 -time="2024-11-06T04:44:55Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:38433" http.request.id=a27c86d9-706f-4f64-bbad-2dc92edcf955 http.request.method=POST http.request.remoteaddr="127.0.0.1:60526" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:38433" http.request.id=a27c86d9-706f-4f64-bbad-2dc92edcf955 http.request.method=POST http.request.remoteaddr="127.0.0.1:60526" 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="158.505µs" http.response.status=404 http.response.written=104 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:38433" http.request.id=a2a598cc-a651-480d-a798-692d4c4cef37 http.request.method=POST http.request.remoteaddr="127.0.0.1:60536" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="797.385µs" http.response.status=200 http.response.written=103 -time="2024-11-06T04:44:55Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:38433" http.request.id=82041a18-17f2-446f-9fdc-b510956fd953 http.request.method=POST http.request.remoteaddr="127.0.0.1:60552" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="606.463µs" http.response.status=200 http.response.written=103 ---- PASS: TestRotateKeyEndpoint (0.02s) +time="2024-11-06T05:03:37Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44673" http.request.id=75dcd302-d688-4e60-9560-de3c310fd4c1 http.request.method=POST http.request.remoteaddr="127.0.0.1:58244" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44673" http.request.id=75dcd302-d688-4e60-9560-de3c310fd4c1 http.request.method=POST http.request.remoteaddr="127.0.0.1:58244" 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="383.444µs" http.response.status=404 http.response.written=104 +time="2024-11-06T05:03:37Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44673" http.request.id=184e6700-42c3-4ad8-a48d-9afde2b16a75 http.request.method=POST http.request.remoteaddr="127.0.0.1:58256" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44673" http.request.id=184e6700-42c3-4ad8-a48d-9afde2b16a75 http.request.method=POST http.request.remoteaddr="127.0.0.1:58256" 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="324.91µs" http.response.status=404 http.response.written=104 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44673" http.request.id=74991456-9306-4079-be08-46613de0bb7f http.request.method=POST http.request.remoteaddr="127.0.0.1:58260" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="977.589µs" http.response.status=200 http.response.written=103 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44673" http.request.id=12c287c5-2938-4ea9-b833-d00b1a919713 http.request.method=POST http.request.remoteaddr="127.0.0.1:58272" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="698.555µs" http.response.status=200 http.response.written=103 +time="2024-11-06T05:03:37Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44673" http.request.id=d421f733-cd57-4098-b753-b6c9223efd60 http.request.method=POST http.request.remoteaddr="127.0.0.1:58276" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44673" http.request.id=d421f733-cd57-4098-b753-b6c9223efd60 http.request.method=POST http.request.remoteaddr="127.0.0.1:58276" 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="212.501µs" http.response.status=404 http.response.written=104 +time="2024-11-06T05:03:37Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44673" http.request.id=bee920a8-c3cd-442e-9c8a-a9afbddaf60f http.request.method=POST http.request.remoteaddr="127.0.0.1:58284" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2024-11-06T05:03:37Z" level=info msg="response completed" go.version=go1.23.2 http.request.contenttype=text/plain http.request.host="127.0.0.1:44673" http.request.id=bee920a8-c3cd-442e-9c8a-a9afbddaf60f http.request.method=POST http.request.remoteaddr="127.0.0.1:58284" 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="154.665µs" http.response.status=404 http.response.written=104 +--- PASS: TestRotateKeyEndpoint (0.04s) PASS -ok github.com/theupdateframework/notary/server 0.443s -? github.com/theupdateframework/notary/server/errors [no test files] +ok github.com/theupdateframework/notary/server 0.928s === RUN Test_changefeed --- PASS: Test_changefeed (0.00s) === RUN Test_checkChangefeedInputs -time="2024-11-06T04:45:00Z" level=error msg="500 GET unable to retrieve storage" -time="2024-11-06T04:45:00Z" level=error msg="400 GET invalid pageSize: not_a_number" ---- PASS: Test_checkChangefeedInputs (0.00s) +time="2024-11-06T05:03:44Z" level=error msg="500 GET unable to retrieve storage" +time="2024-11-06T05:03:44Z" level=error msg="400 GET invalid pageSize: not_a_number" +--- PASS: Test_checkChangefeedInputs (0.01s) === RUN TestMainHandlerGet -time="2024-11-06T04:45:00Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:35003" http.request.id=2daf34b2-ee47-40da-a3dd-b26c238b2c3e http.request.method=GET http.request.remoteaddr="127.0.0.1:47934" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="103.131µs" http.response.status=200 http.response.written=2 +time="2024-11-06T05:03:44Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:41927" http.request.id=4dff5381-29e1-4822-ad68-407436fd1d63 http.request.method=GET http.request.remoteaddr="127.0.0.1:49138" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="96.111µs" http.response.status=200 http.response.written=2 --- PASS: TestMainHandlerGet (0.00s) === RUN TestMainHandlerNotGet -time="2024-11-06T04:45:00Z" level=info msg="generic not found: You have requested a resource that does not exist.: " go.version=go1.23.2 http.request.host="127.0.0.1:37447" http.request.id=37c9035e-43ed-4b09-bf09-732d4b28e74e http.request.method=HEAD http.request.remoteaddr="127.0.0.1:33376" http.request.uri=/ http.request.useragent=Go-http-client/1.1 -time="2024-11-06T04:45:00Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:37447" http.request.id=37c9035e-43ed-4b09-bf09-732d4b28e74e http.request.method=HEAD http.request.remoteaddr="127.0.0.1:33376" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="393.783µs" http.response.status=404 http.response.written=105 +time="2024-11-06T05:03:44Z" level=info msg="generic not found: You have requested a resource that does not exist.: " go.version=go1.23.2 http.request.host="127.0.0.1:37287" http.request.id=b9c1c6d6-5ef1-4271-9468-d967af7b0897 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:50168" http.request.uri=/ http.request.useragent=Go-http-client/1.1 +time="2024-11-06T05:03:44Z" level=info msg="response completed" go.version=go1.23.2 http.request.host="127.0.0.1:37287" http.request.id=b9c1c6d6-5ef1-4271-9468-d967af7b0897 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:50168" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="450.297µs" http.response.status=404 http.response.written=105 --- PASS: TestMainHandlerNotGet (0.00s) === RUN TestKeyHandlersInvalidConfiguration -time="2024-11-06T04:45:00Z" level=error msg="500 GET crypto service not configured" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=error msg="500 GET crypto service not configured" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=error msg="500 GET key algorithm not configured" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=error msg="500 GET key algorithm not configured" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=error msg="500 GET storage not configured" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=error msg="500 GET storage not configured" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=error msg="500 POST storage not configured" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=error msg="500 POST storage not configured" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=error msg="500 POST crypto service not configured" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=error msg="500 POST crypto service not configured" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=error msg="500 POST key algorithm not configured" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=error msg="500 POST key algorithm not configured" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=error msg="500 GET key algorithm not configured" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=error msg="500 GET key algorithm not configured" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=error msg="500 GET storage not configured" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=error msg="500 GET storage not configured" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=error msg="500 GET crypto service not configured" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=error msg="500 GET crypto service not configured" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=error msg="500 POST storage not configured" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=error msg="500 POST storage not configured" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=error msg="500 POST crypto service not configured" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=error msg="500 POST crypto service not configured" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=error msg="500 POST key algorithm not configured" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=error msg="500 POST key algorithm not configured" go.version=go1.23.2 gun=gun --- PASS: TestKeyHandlersInvalidConfiguration (0.00s) === RUN TestKeyHandlersNoRoleOrRepo -time="2024-11-06T04:45:00Z" level=info msg="400 GET no gun in request" =gun go.version=go1.23.2 -time="2024-11-06T04:45:00Z" level=info msg="400 GET no gun in request" =gun go.version=go1.23.2 -time="2024-11-06T04:45:00Z" level=info msg="400 GET no role in request" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=info msg="400 GET no role in request" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=info msg="400 POST no gun in request" =gun go.version=go1.23.2 -time="2024-11-06T04:45:00Z" level=info msg="400 POST no gun in request" =gun go.version=go1.23.2 -time="2024-11-06T04:45:00Z" level=info msg="400 POST no role in request" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=info msg="400 POST no role in request" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=info msg="400 GET no gun in request" =gun go.version=go1.23.2 +time="2024-11-06T05:03:44Z" level=info msg="400 GET no gun in request" =gun go.version=go1.23.2 +time="2024-11-06T05:03:44Z" level=info msg="400 GET no role in request" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=info msg="400 GET no role in request" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=info msg="400 POST no gun in request" =gun go.version=go1.23.2 +time="2024-11-06T05:03:44Z" level=info msg="400 POST no gun in request" =gun go.version=go1.23.2 +time="2024-11-06T05:03:44Z" level=info msg="400 POST no role in request" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=info msg="400 POST no role in request" go.version=go1.23.2 gun=gun --- PASS: TestKeyHandlersNoRoleOrRepo (0.00s) === RUN TestKeyHandlersInvalidRole -time="2024-11-06T04:45:00Z" level=info msg="400 GET root key: " go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=info msg="400 GET targets key: " go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=info msg="400 GET targets/a key: " go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=info msg="400 GET invalidrole key: " go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=info msg="400 POST root key: " go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=info msg="400 POST targets key: " go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=info msg="400 POST targets/a key: " go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=info msg="400 POST invalidrole key: " go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=info msg="400 GET root key: " go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=info msg="400 GET targets key: " go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=info msg="400 GET targets/a key: " go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=info msg="400 GET invalidrole key: " go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=info msg="400 POST root key: " go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=info msg="400 POST targets key: " go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=info msg="400 POST targets/a key: " go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:44Z" level=info msg="400 POST invalidrole key: " go.version=go1.23.2 gun=gun --- PASS: TestKeyHandlersInvalidRole (0.00s) === RUN TestGetKeyHandlerCreatesOnce ---- PASS: TestGetKeyHandlerCreatesOnce (0.03s) +--- PASS: TestGetKeyHandlerCreatesOnce (0.07s) === RUN TestKeyHandlersInvalidKeyAlgo -time="2024-11-06T04:45:00Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.23.2 gun=gun -time="2024-11-06T04:45:00Z" level=error msg="500 POST snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:45Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:45Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:45Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:45Z" level=error msg="500 POST snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.23.2 gun=gun --- PASS: TestKeyHandlersInvalidKeyAlgo (0.00s) === RUN TestRotateKeyHandlerSuccessfulRotation --- PASS: TestRotateKeyHandlerSuccessfulRotation (0.00s) === RUN TestGetHandlerRoot -time="2024-11-06T04:45:00Z" level=info msg="404 GET root role" go.version=go1.23.2 gun=gun ---- PASS: TestGetHandlerRoot (0.04s) +time="2024-11-06T05:03:45Z" level=info msg="404 GET root role" go.version=go1.23.2 gun=gun +--- PASS: TestGetHandlerRoot (0.05s) === RUN TestGetHandlerTimestamp --- PASS: TestGetHandlerTimestamp (0.04s) === RUN TestGetHandlerSnapshot --- PASS: TestGetHandlerSnapshot (0.04s) === RUN TestGetHandler404 -time="2024-11-06T04:45:00Z" level=info msg="404 GET root role" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:45Z" level=info msg="404 GET root role" go.version=go1.23.2 gun=gun --- PASS: TestGetHandler404 (0.00s) === RUN TestGetHandlerNilData -time="2024-11-06T04:45:00Z" level=info msg="404 GET root role" go.version=go1.23.2 gun=gun +time="2024-11-06T05:03:45Z" level=info msg="404 GET root role" go.version=go1.23.2 gun=gun --- PASS: TestGetHandlerNilData (0.00s) === RUN TestGetHandlerNoStorage -time="2024-11-06T04:45:00Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.23.2 +time="2024-11-06T05:03:45Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.23.2 --- PASS: TestGetHandlerNoStorage (0.00s) === RUN TestAtomicUpdateValidationFailurePropagated ---- PASS: TestAtomicUpdateValidationFailurePropagated (0.07s) +--- PASS: TestAtomicUpdateValidationFailurePropagated (0.09s) === RUN TestAtomicUpdateNonValidationFailureNotPropagated -time="2024-11-06T04:45:00Z" level=info msg="400 POST error validating update" go.version=go1.23.2 testGUN=gun ---- PASS: TestAtomicUpdateNonValidationFailureNotPropagated (0.06s) +time="2024-11-06T05:03:45Z" level=info msg="400 POST error validating update" go.version=go1.23.2 testGUN=gun +--- PASS: TestAtomicUpdateNonValidationFailureNotPropagated (0.12s) === RUN TestAtomicUpdateVersionErrorPropagated -time="2024-11-06T04:45:00Z" level=info msg="400 POST old version error" go.version=go1.23.2 testGUN=gun ---- PASS: TestAtomicUpdateVersionErrorPropagated (0.08s) +time="2024-11-06T05:03:45Z" level=info msg="400 POST old version error" go.version=go1.23.2 testGUN=gun +--- PASS: TestAtomicUpdateVersionErrorPropagated (0.10s) === RUN TestGetMaybeServerSignedNoCrypto --- PASS: TestGetMaybeServerSignedNoCrypto (0.00s) === RUN TestGetMaybeServerSignedNoKey --- PASS: TestGetMaybeServerSignedNoKey (0.00s) === RUN TestValidateEmptyNew ---- PASS: TestValidateEmptyNew (0.08s) +--- PASS: TestValidateEmptyNew (0.17s) === RUN TestValidateRootCanContainOnlyx509KeysWithRightGun ---- PASS: TestValidateRootCanContainOnlyx509KeysWithRightGun (0.10s) +--- PASS: TestValidateRootCanContainOnlyx509KeysWithRightGun (0.24s) === RUN TestValidatePrevTimestamp ---- PASS: TestValidatePrevTimestamp (0.08s) +--- PASS: TestValidatePrevTimestamp (0.18s) === RUN TestValidatePreviousTimestampCorrupt -time="2024-11-06T04:45:01Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" ---- PASS: TestValidatePreviousTimestampCorrupt (0.07s) +time="2024-11-06T05:03:46Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" +--- PASS: TestValidatePreviousTimestampCorrupt (0.18s) === RUN TestValidateGetCurrentTimestampBroken ---- PASS: TestValidateGetCurrentTimestampBroken (0.07s) +--- PASS: TestValidateGetCurrentTimestampBroken (0.17s) === RUN TestValidateNoNewRoot ---- PASS: TestValidateNoNewRoot (0.09s) +--- PASS: TestValidateNoNewRoot (0.08s) === RUN TestValidateNoNewTargets --- PASS: TestValidateNoNewTargets (0.08s) === RUN TestValidateOnlySnapshot ---- PASS: TestValidateOnlySnapshot (0.10s) +--- PASS: TestValidateOnlySnapshot (0.08s) === RUN TestValidateOldRoot --- PASS: TestValidateOldRoot (0.11s) === RUN TestValidateOldRootCorrupt ---- PASS: TestValidateOldRootCorrupt (0.06s) +--- PASS: TestValidateOldRootCorrupt (0.15s) === RUN TestValidateOldRootCorruptRootRole ---- PASS: TestValidateOldRootCorruptRootRole (0.06s) +--- PASS: TestValidateOldRootCorruptRootRole (0.13s) === RUN TestValidateRootGetCurrentRootBroken ---- PASS: TestValidateRootGetCurrentRootBroken (0.07s) +--- PASS: TestValidateRootGetCurrentRootBroken (0.15s) === RUN TestValidateRootRotationWithOldSigs ---- PASS: TestValidateRootRotationWithOldSigs (0.25s) +--- PASS: TestValidateRootRotationWithOldSigs (0.60s) === RUN TestValidateRootRotationMultipleKeysThreshold1 ---- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.16s) +--- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.39s) === RUN TestRootRotationNotSignedWithOldKeysForOldRole ---- PASS: TestRootRotationNotSignedWithOldKeysForOldRole (0.22s) +--- PASS: TestRootRotationNotSignedWithOldKeysForOldRole (0.54s) === RUN TestRootRotationVersionIncrement ---- PASS: TestRootRotationVersionIncrement (0.14s) +--- PASS: TestRootRotationVersionIncrement (0.34s) === RUN TestValidateNoRoot ---- PASS: TestValidateNoRoot (0.06s) +--- PASS: TestValidateNoRoot (0.13s) === RUN TestValidateSnapshotMissingNoSnapshotKey ---- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.07s) +--- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.16s) === RUN TestValidateSnapshotGenerateNoPrev ---- PASS: TestValidateSnapshotGenerateNoPrev (0.09s) +--- PASS: TestValidateSnapshotGenerateNoPrev (0.21s) === RUN TestValidateSnapshotGenerateWithPrev ---- PASS: TestValidateSnapshotGenerateWithPrev (0.09s) +--- PASS: TestValidateSnapshotGenerateWithPrev (0.21s) === RUN TestValidateSnapshotGeneratePrevCorrupt -time="2024-11-06T04:45:02Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" ---- PASS: TestValidateSnapshotGeneratePrevCorrupt (0.08s) +time="2024-11-06T05:03:50Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" +--- PASS: TestValidateSnapshotGeneratePrevCorrupt (0.18s) === RUN TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken ---- PASS: TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken (0.08s) +--- PASS: TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken (0.18s) === RUN TestValidateSnapshotGenerateNoTargets ---- PASS: TestValidateSnapshotGenerateNoTargets (0.07s) +--- PASS: TestValidateSnapshotGenerateNoTargets (0.16s) === RUN TestValidateSnapshotGenerate ---- PASS: TestValidateSnapshotGenerate (0.09s) +--- PASS: TestValidateSnapshotGenerate (0.22s) === RUN TestValidateRootNoTimestampKey ---- PASS: TestValidateRootNoTimestampKey (0.07s) +--- PASS: TestValidateRootNoTimestampKey (0.15s) === RUN TestValidateRootInvalidTimestampKey ---- PASS: TestValidateRootInvalidTimestampKey (0.07s) +--- PASS: TestValidateRootInvalidTimestampKey (0.15s) === RUN TestValidateRootInvalidTimestampThreshold ---- PASS: TestValidateRootInvalidTimestampThreshold (0.08s) +--- PASS: TestValidateRootInvalidTimestampThreshold (0.19s) === RUN TestValidateRootInvalidZeroThreshold ---- PASS: TestValidateRootInvalidZeroThreshold (0.23s) +--- PASS: TestValidateRootInvalidZeroThreshold (0.44s) === RUN TestValidateRootRoleMissing ---- PASS: TestValidateRootRoleMissing (0.06s) +--- PASS: TestValidateRootRoleMissing (0.13s) === RUN TestValidateTargetsRoleMissing ---- PASS: TestValidateTargetsRoleMissing (0.05s) +--- PASS: TestValidateTargetsRoleMissing (0.14s) === RUN TestValidateSnapshotRoleMissing ---- PASS: TestValidateSnapshotRoleMissing (0.05s) +--- PASS: TestValidateSnapshotRoleMissing (0.14s) === RUN TestValidateRootSigMissing ---- PASS: TestValidateRootSigMissing (0.05s) +--- PASS: TestValidateRootSigMissing (0.12s) === RUN TestValidateTargetsSigMissing -time="2024-11-06T04:45:03Z" level=error msg="ErrBadTargets: tuf: data has no signatures" ---- PASS: TestValidateTargetsSigMissing (0.07s) +time="2024-11-06T05:03:52Z" level=error msg="ErrBadTargets: tuf: data has no signatures" +--- PASS: TestValidateTargetsSigMissing (0.17s) === RUN TestValidateSnapshotSigMissing ---- PASS: TestValidateSnapshotSigMissing (0.07s) +--- PASS: TestValidateSnapshotSigMissing (0.17s) === RUN TestValidateRootCorrupt ---- PASS: TestValidateRootCorrupt (0.06s) +--- PASS: TestValidateRootCorrupt (0.12s) === RUN TestValidateTargetsCorrupt -time="2024-11-06T04:45:03Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" ---- PASS: TestValidateTargetsCorrupt (0.06s) +time="2024-11-06T05:03:52Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" +--- PASS: TestValidateTargetsCorrupt (0.16s) === RUN TestValidateSnapshotCorrupt ---- PASS: TestValidateSnapshotCorrupt (0.07s) +--- PASS: TestValidateSnapshotCorrupt (0.16s) === RUN TestValidateRootModifiedSize ---- PASS: TestValidateRootModifiedSize (0.06s) +--- PASS: TestValidateRootModifiedSize (0.13s) === RUN TestValidateTargetsModifiedSize ---- PASS: TestValidateTargetsModifiedSize (0.08s) +--- PASS: TestValidateTargetsModifiedSize (0.19s) === RUN TestValidateRootModifiedHash ---- PASS: TestValidateRootModifiedHash (0.07s) +--- PASS: TestValidateRootModifiedHash (0.19s) === RUN TestValidateTargetsModifiedHash ---- PASS: TestValidateTargetsModifiedHash (0.07s) +--- PASS: TestValidateTargetsModifiedHash (0.17s) === RUN TestGenerateSnapshotRootNotLoaded --- PASS: TestGenerateSnapshotRootNotLoaded (0.00s) === RUN TestGenerateSnapshotNoKey ---- PASS: TestGenerateSnapshotNoKey (0.07s) +--- PASS: TestGenerateSnapshotNoKey (0.10s) === RUN TestLoadTargetsLoadsNothingIfNoUpdates ---- PASS: TestLoadTargetsLoadsNothingIfNoUpdates (0.05s) +--- PASS: TestLoadTargetsLoadsNothingIfNoUpdates (0.12s) === RUN TestValidateTargetsRequiresStoredParent -time="2024-11-06T04:45:04Z" level=error msg="ErrBadTargets: targets must be loaded first" ---- PASS: TestValidateTargetsRequiresStoredParent (0.08s) +time="2024-11-06T05:03:53Z" level=error msg="ErrBadTargets: targets must be loaded first" +--- PASS: TestValidateTargetsRequiresStoredParent (0.17s) === RUN TestValidateTargetsParentInUpdate -time="2024-11-06T04:45:04Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" ---- PASS: TestValidateTargetsParentInUpdate (0.08s) +time="2024-11-06T05:03:54Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" +--- PASS: TestValidateTargetsParentInUpdate (0.13s) === RUN TestValidateTargetsRoleNotInParent -time="2024-11-06T04:45:04Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" -time="2024-11-06T04:45:04Z" level=error msg="ErrBadTargets: targets has already been loaded" -time="2024-11-06T04:45:04Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +time="2024-11-06T05:03:54Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +time="2024-11-06T05:03:54Z" level=error msg="ErrBadTargets: targets has already been loaded" +time="2024-11-06T05:03:54Z" 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.456s +ok github.com/theupdateframework/notary/server/handlers 9.256s === RUN TestSnapshotExpired --- PASS: TestSnapshotExpired (0.00s) === RUN TestSnapshotNotExpired --- PASS: TestSnapshotNotExpired (0.00s) === RUN TestGetSnapshotKeyCreate ---- PASS: TestGetSnapshotKeyCreate (0.03s) +--- PASS: TestGetSnapshotKeyCreate (0.07s) === RUN TestGetSnapshotKeyCreateWithFailingStore -time="2024-11-06T04:45:06Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2024-11-06T05:03:58Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetSnapshotKeyCreateWithFailingStore (0.00s) === RUN TestGetSnapshotKeyCreateWithCorruptedStore -time="2024-11-06T04:45:06Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve snapshot key ID" +time="2024-11-06T05:03:58Z" 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.05s) +--- PASS: TestGetSnapshotKeyExistingMetadata (0.10s) === RUN TestGetSnapshotNoPreviousSnapshot -time="2024-11-06T04:45:06Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" ---- PASS: TestGetSnapshotNoPreviousSnapshot (0.03s) +time="2024-11-06T05:03:58Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" +--- PASS: TestGetSnapshotNoPreviousSnapshot (0.07s) === RUN TestGetSnapshotReturnsPreviousSnapshotIfUnexpired ---- PASS: TestGetSnapshotReturnsPreviousSnapshotIfUnexpired (0.03s) +--- PASS: TestGetSnapshotReturnsPreviousSnapshotIfUnexpired (0.07s) === RUN TestGetSnapshotOldSnapshotExpired ---- PASS: TestGetSnapshotOldSnapshotExpired (0.05s) +--- PASS: TestGetSnapshotOldSnapshotExpired (0.08s) === RUN TestCannotMakeNewSnapshotIfNoRoot --- PASS: TestCannotMakeNewSnapshotIfNoRoot (0.03s) === RUN TestCreateSnapshotNoKeyInCrypto --- PASS: TestCreateSnapshotNoKeyInCrypto (0.04s) PASS -ok github.com/theupdateframework/notary/server/snapshot 0.299s +ok github.com/theupdateframework/notary/server/snapshot 0.514s === RUN TestMemoryUpdateCurrentEmpty --- PASS: TestMemoryUpdateCurrentEmpty (0.00s) === RUN TestMemoryUpdateCurrentVersionCheckOldVersionExists @@ -3191,7 +3236,7 @@ === RUN TestMemoryUpdateCurrentVersionCheckOldVersionNotExist --- PASS: TestMemoryUpdateCurrentVersionCheckOldVersionNotExist (0.00s) === RUN TestMemoryUpdateManyNoConflicts ---- PASS: TestMemoryUpdateManyNoConflicts (0.00s) +--- PASS: TestMemoryUpdateManyNoConflicts (0.01s) === RUN TestMemoryUpdateManyConflictRollback --- PASS: TestMemoryUpdateManyConflictRollback (0.00s) === RUN TestMemoryDeleteSuccess @@ -3209,76 +3254,73 @@ === RUN TestRDBTUFFileJSONUnmarshallingFailure --- PASS: TestRDBTUFFileJSONUnmarshallingFailure (0.00s) === RUN TestSQLUpdateCurrentEmpty ---- PASS: TestSQLUpdateCurrentEmpty (0.18s) +--- PASS: TestSQLUpdateCurrentEmpty (0.39s) === RUN TestSQLUpdateCurrentVersionCheckOldVersionExists ---- PASS: TestSQLUpdateCurrentVersionCheckOldVersionExists (0.08s) +--- PASS: TestSQLUpdateCurrentVersionCheckOldVersionExists (0.21s) === RUN TestSQLUpdateCurrentVersionCheckOldVersionNotExist ---- PASS: TestSQLUpdateCurrentVersionCheckOldVersionNotExist (0.08s) +--- PASS: TestSQLUpdateCurrentVersionCheckOldVersionNotExist (0.22s) === RUN TestSQLUpdateManyNoConflicts ---- PASS: TestSQLUpdateManyNoConflicts (0.08s) +--- PASS: TestSQLUpdateManyNoConflicts (0.24s) === RUN TestSQLUpdateManyConflictRollback ---- PASS: TestSQLUpdateManyConflictRollback (0.07s) +--- PASS: TestSQLUpdateManyConflictRollback (0.18s) === RUN TestSQLDelete ---- PASS: TestSQLDelete (0.12s) +--- PASS: TestSQLDelete (0.28s) === RUN TestSQLDBCheckHealthTableMissing ---- PASS: TestSQLDBCheckHealthTableMissing (0.06s) +--- PASS: TestSQLDBCheckHealthTableMissing (0.13s) === RUN TestSQLDBCheckHealthDBConnectionFail ---- PASS: TestSQLDBCheckHealthDBConnectionFail (0.05s) +--- PASS: TestSQLDBCheckHealthDBConnectionFail (0.10s) === RUN TestSQLDBCheckHealthSucceeds ---- PASS: TestSQLDBCheckHealthSucceeds (0.04s) +--- PASS: TestSQLDBCheckHealthSucceeds (0.11s) === RUN TestSQLDBGetChecksum ---- PASS: TestSQLDBGetChecksum (0.08s) +--- PASS: TestSQLDBGetChecksum (0.15s) === RUN TestSQLDBGetChecksumNotFound ---- PASS: TestSQLDBGetChecksumNotFound (0.05s) +--- PASS: TestSQLDBGetChecksumNotFound (0.11s) === RUN TestSQLTUFMetaStoreGetCurrent ---- PASS: TestSQLTUFMetaStoreGetCurrent (0.19s) +--- PASS: TestSQLTUFMetaStoreGetCurrent (0.81s) === RUN TestSQLGetChanges ---- PASS: TestSQLGetChanges (0.16s) +--- PASS: TestSQLGetChanges (0.38s) === RUN TestSQLDBGetVersion ---- PASS: TestSQLDBGetVersion (0.06s) +--- PASS: TestSQLDBGetVersion (0.14s) PASS -ok github.com/theupdateframework/notary/server/storage 1.328s +ok github.com/theupdateframework/notary/server/storage 3.513s === RUN TestTimestampExpired --- PASS: TestTimestampExpired (0.00s) === RUN TestTimestampNotExpired --- PASS: TestTimestampNotExpired (0.00s) === RUN TestGetTimestampKey ---- PASS: TestGetTimestampKey (0.03s) +--- PASS: TestGetTimestampKey (0.07s) === RUN TestGetTimestampNoPreviousTimestamp -time="2024-11-06T04:45:14Z" level=error msg="Failed to unmarshal existing timestamp" ---- PASS: TestGetTimestampNoPreviousTimestamp (0.06s) +time="2024-11-06T05:04:14Z" level=error msg="Failed to unmarshal existing timestamp" +--- PASS: TestGetTimestampNoPreviousTimestamp (0.19s) === RUN TestGetTimestampReturnsPreviousTimestampIfUnexpired ---- PASS: TestGetTimestampReturnsPreviousTimestampIfUnexpired (0.05s) +--- PASS: TestGetTimestampReturnsPreviousTimestampIfUnexpired (0.13s) === RUN TestGetTimestampOldTimestampExpired ---- PASS: TestGetTimestampOldTimestampExpired (0.08s) +--- PASS: TestGetTimestampOldTimestampExpired (0.10s) === RUN TestCannotMakeNewTimestampIfNoRootOrSnapshot -time="2024-11-06T04:45:15Z" level=error msg="Failed to create a new timestamp" -time="2024-11-06T04:45:15Z" level=error msg="Failed to create a new timestamp" +time="2024-11-06T05:04:14Z" level=error msg="Failed to create a new timestamp" +time="2024-11-06T05:04:14Z" level=error msg="Failed to create a new timestamp" --- PASS: TestCannotMakeNewTimestampIfNoRootOrSnapshot (0.06s) === RUN TestCreateTimestampNoKeyInCrypto -time="2024-11-06T04:45:15Z" level=error msg="Failed to create a new timestamp" ---- PASS: TestCreateTimestampNoKeyInCrypto (0.07s) +time="2024-11-06T05:04:15Z" level=error msg="Failed to create a new timestamp" +--- PASS: TestCreateTimestampNoKeyInCrypto (0.08s) === RUN TestGetTimestampKeyCreateWithFailingStore -time="2024-11-06T04:45:15Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2024-11-06T05:04:15Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetTimestampKeyCreateWithFailingStore (0.00s) === RUN TestGetTimestampKeyCreateWithCorruptedStore -time="2024-11-06T04:45:15Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve timestamp key ID" +time="2024-11-06T05:04:15Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve timestamp key ID" --- PASS: TestGetTimestampKeyCreateWithCorruptedStore (0.00s) === RUN TestGetTimestampKeyCreateWithInvalidAlgo --- PASS: TestGetTimestampKeyCreateWithInvalidAlgo (0.00s) === RUN TestGetTimestampKeyExistingMetadata --- PASS: TestGetTimestampKeyExistingMetadata (0.03s) PASS -ok github.com/theupdateframework/notary/server/timestamp 0.405s -? github.com/theupdateframework/notary/signer [no test files] -? github.com/theupdateframework/notary/signer/api [no test files] -? github.com/theupdateframework/notary/signer/client [no test files] +ok github.com/theupdateframework/notary/server/timestamp 0.715s === RUN TestGetSuccessPopulatesCache ---- PASS: TestGetSuccessPopulatesCache (0.03s) +--- PASS: TestGetSuccessPopulatesCache (0.06s) === RUN TestAddKeyPopulatesCacheIfSuccessful ---- PASS: TestAddKeyPopulatesCacheIfSuccessful (0.01s) +--- PASS: TestAddKeyPopulatesCacheIfSuccessful (0.03s) === RUN TestDeleteKeyRemovesKeyFromCache ---- PASS: TestDeleteKeyRemovesKeyFromCache (0.02s) +--- PASS: TestDeleteKeyRemovesKeyFromCache (0.07s) === RUN TestRDBPrivateKeyJSONUnmarshalling --- PASS: TestRDBPrivateKeyJSONUnmarshalling (0.00s) === RUN TestRDBPrivateKeyJSONUnmarshallingFailure @@ -3287,30 +3329,30 @@ `nodb` is not officially supported, running under compatibility mode. --- PASS: TestNewSQLKeyDBStorePropagatesDBError (0.00s) === RUN TestSQLDBHealthCheckMissingTable ---- PASS: TestSQLDBHealthCheckMissingTable (0.04s) +--- PASS: TestSQLDBHealthCheckMissingTable (0.18s) === RUN TestSQLDBHealthCheckNoConnection ---- PASS: TestSQLDBHealthCheckNoConnection (0.04s) +--- PASS: TestSQLDBHealthCheckNoConnection (0.10s) === RUN TestSQLKeyCanOnlyBeAddedOnce (/build/reproducible-path/notary-0.7.0+git20240416.9d2b3b3+ds1/_build/src/github.com/theupdateframework/notary/signer/keydbstore/sql_keydbstore.go:98) -[2024-11-06 04:45:19]  UNIQUE constraint failed: private_keys.key_id  ---- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.22s) +[2024-11-06 05:04:22]  UNIQUE constraint failed: private_keys.key_id  +--- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.70s) === RUN TestSQLCreateDelete ---- PASS: TestSQLCreateDelete (0.26s) +--- PASS: TestSQLCreateDelete (0.60s) === RUN TestSQLKeyRotation ---- PASS: TestSQLKeyRotation (0.35s) +--- PASS: TestSQLKeyRotation (0.73s) === RUN TestSQLSigningMarksKeyActive -time="2024-11-06T04:45:20Z" level=error msg="Key 6092803974f1cd2ae3ebc31f279f46b822387e9c71501adc50011a941e81d314 was just used to sign hash successful, db closed, error when trying to mark key as active: sql: database is closed" +time="2024-11-06T05:04:25Z" level=error msg="Key 4717813a87698738678966540fe4479d1226b48035f40079662aa45fc96b39d9 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+git20240416.9d2b3b3+ds1/_build/src/github.com/theupdateframework/notary/signer/keydbstore/sql_keydbstore.go:197) -[2024-11-06 04:45:20]  sql: database is closed  ---- PASS: TestSQLSigningMarksKeyActive (0.50s) +[2024-11-06 05:04:25]  sql: database is closed  +--- PASS: TestSQLSigningMarksKeyActive (1.15s) === RUN TestSQLCreateKey ---- PASS: TestSQLCreateKey (0.43s) +--- PASS: TestSQLCreateKey (0.78s) === RUN TestSQLUnimplementedInterfaceBehavior ---- PASS: TestSQLUnimplementedInterfaceBehavior (0.12s) +--- PASS: TestSQLUnimplementedInterfaceBehavior (0.32s) PASS -ok github.com/theupdateframework/notary/signer/keydbstore 2.036s +ok github.com/theupdateframework/notary/signer/keydbstore 4.796s === RUN TestSet --- PASS: TestSet (0.00s) === RUN TestSetWithNoParentDirectory @@ -3320,17 +3362,17 @@ === RUN TestGetSized --- PASS: TestGetSized (0.00s) === RUN TestGetSizedSet ---- PASS: TestGetSizedSet (0.00s) +--- PASS: TestGetSizedSet (0.01s) === RUN TestRemove --- PASS: TestRemove (0.00s) === RUN TestRemoveAll --- PASS: TestRemoveAll (0.00s) === RUN TestAddFile ---- PASS: TestAddFile (0.00s) +--- PASS: TestAddFile (0.01s) === RUN TestRemoveFile --- PASS: TestRemoveFile (0.00s) === RUN TestListFiles ---- PASS: TestListFiles (0.00s) +--- PASS: TestListFiles (0.01s) === RUN TestGetPath --- PASS: TestGetPath (0.00s) === RUN TestGetPathProtection @@ -3340,19 +3382,19 @@ === RUN TestCreateDirectory --- PASS: TestCreateDirectory (0.00s) === RUN TestCreatePrivateDirectory ---- PASS: TestCreatePrivateDirectory (0.00s) +--- PASS: TestCreatePrivateDirectory (0.01s) === RUN TestFileStoreConsistency ---- PASS: TestFileStoreConsistency (0.00s) +--- PASS: TestFileStoreConsistency (0.02s) === RUN TestHTTPStoreGetSized ---- PASS: TestHTTPStoreGetSized (0.02s) +--- PASS: TestHTTPStoreGetSized (0.00s) === RUN TestHTTPStoreGetAllMeta --- PASS: TestHTTPStoreGetAllMeta (0.00s) === RUN TestSetSingleAndSetMultiMeta ---- PASS: TestSetSingleAndSetMultiMeta (0.00s) +--- PASS: TestSetSingleAndSetMultiMeta (0.01s) === RUN Test404Error --- PASS: Test404Error (0.00s) === RUN Test50XErrors ---- PASS: Test50XErrors (0.01s) +--- PASS: Test50XErrors (0.02s) === RUN Test400Error --- PASS: Test400Error (0.00s) === RUN TestTranslateErrorsParse400Errors @@ -3364,11 +3406,11 @@ === RUN TestHTTPStoreRemoveAll --- PASS: TestHTTPStoreRemoveAll (0.00s) === RUN TestHTTPStoreRotateKey ---- PASS: TestHTTPStoreRotateKey (0.00s) +--- PASS: TestHTTPStoreRotateKey (0.03s) === RUN TestHTTPStoreGetKey --- PASS: TestHTTPStoreGetKey (0.00s) === RUN TestHTTPStoreGetRotateKeySizeLimited ---- PASS: TestHTTPStoreGetRotateKeySizeLimited (0.01s) +--- PASS: TestHTTPStoreGetRotateKeySizeLimited (0.03s) === RUN TestHTTPOffline --- PASS: TestHTTPOffline (0.00s) === RUN TestErrServerUnavailable @@ -3389,8 +3431,7 @@ === RUN TestMemoryStoreMetadata --- PASS: TestMemoryStoreMetadata (0.00s) PASS -ok github.com/theupdateframework/notary/storage 0.082s -? github.com/theupdateframework/notary/storage/rethinkdb [no test files] +ok github.com/theupdateframework/notary/storage 0.204s === RUN TestExportKeys --- PASS: TestExportKeys (0.00s) === RUN TestExportKeysByGUN @@ -3400,199 +3441,197 @@ === RUN TestExport2InOneFile --- PASS: TestExport2InOneFile (0.00s) === RUN TestImportKeys ---- PASS: TestImportKeys (0.01s) +--- PASS: TestImportKeys (0.00s) === RUN TestImportNoPath ---- PASS: TestImportNoPath (0.01s) +--- PASS: TestImportNoPath (0.03s) === RUN TestNonRootPathInference ---- PASS: TestNonRootPathInference (0.01s) +--- PASS: TestNonRootPathInference (0.04s) === RUN TestBlockHeaderPrecedenceRoleAndGun ---- PASS: TestBlockHeaderPrecedenceRoleAndGun (0.01s) +--- PASS: TestBlockHeaderPrecedenceRoleAndGun (0.04s) === RUN TestBlockHeaderPrecedenceGunFromPath ---- PASS: TestBlockHeaderPrecedenceGunFromPath (0.01s) +--- PASS: TestBlockHeaderPrecedenceGunFromPath (0.04s) === RUN TestImportKeys2InOneFile --- PASS: TestImportKeys2InOneFile (0.00s) === RUN TestImportKeys2InOneFileNoPath ---- PASS: TestImportKeys2InOneFileNoPath (0.02s) +--- PASS: TestImportKeys2InOneFileNoPath (0.07s) === RUN TestEncryptedKeyImportFail -time="2024-11-06T04:45:24Z" 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) +time="2024-11-06T05:04:34Z" 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.03s) === RUN TestEncryptedKeyImportSuccess ---- PASS: TestEncryptedKeyImportSuccess (0.02s) +--- PASS: TestEncryptedKeyImportSuccess (0.03s) === RUN TestEncryption --- PASS: TestEncryption (0.03s) === RUN TestAddKey ---- PASS: TestAddKey (0.06s) +--- PASS: TestAddKey (0.19s) === RUN TestKeyStoreInternalState ---- PASS: TestKeyStoreInternalState (0.03s) +--- PASS: TestKeyStoreInternalState (0.09s) === RUN TestGet ---- PASS: TestGet (0.04s) +--- PASS: TestGet (0.11s) === RUN TestGetLegacyKey ---- PASS: TestGetLegacyKey (0.00s) +--- PASS: TestGetLegacyKey (0.01s) === RUN TestListKeys ---- PASS: TestListKeys (0.07s) +--- PASS: TestListKeys (0.10s) === RUN TestAddGetKeyMemStore --- PASS: TestAddGetKeyMemStore (0.01s) === RUN TestAddGetKeyInfoMemStore ---- PASS: TestAddGetKeyInfoMemStore (0.03s) +--- PASS: TestAddGetKeyInfoMemStore (0.05s) === RUN TestGetDecryptedWithTamperedCipherText -time="2024-11-06T04:45:24Z" level=error msg="PEM block is empty" ---- PASS: TestGetDecryptedWithTamperedCipherText (0.01s) +time="2024-11-06T05:04:34Z" level=error msg="PEM block is empty" +--- PASS: TestGetDecryptedWithTamperedCipherText (0.02s) === RUN TestGetDecryptedWithInvalidPassphrase ---- PASS: TestGetDecryptedWithInvalidPassphrase (0.06s) +--- PASS: TestGetDecryptedWithInvalidPassphrase (0.13s) === RUN TestGetDecryptedWithConsistentlyInvalidPassphrase ---- PASS: TestGetDecryptedWithConsistentlyInvalidPassphrase (0.10s) +--- PASS: TestGetDecryptedWithConsistentlyInvalidPassphrase (0.21s) === RUN TestRemoveKey ---- PASS: TestRemoveKey (0.06s) +--- PASS: TestRemoveKey (0.11s) === RUN TestKeysAreCached ---- PASS: TestKeysAreCached (0.02s) +--- PASS: TestKeysAreCached (0.05s) PASS -ok github.com/theupdateframework/notary/trustmanager 0.655s +ok github.com/theupdateframework/notary/trustmanager 1.414s === RUN TestRemoteStore -time="2024-11-06T04:45:26Z" level=info msg="listing files from localhost:9888" -time="2024-11-06T04:45:26Z" level=info msg="listing files from localhost:9888" ---- PASS: TestRemoteStore (0.26s) +time="2024-11-06T05:04:40Z" level=info msg="listing files from localhost:9888" +time="2024-11-06T05:04:40Z" level=info msg="listing files from localhost:9888" +--- PASS: TestRemoteStore (0.52s) === RUN TestErrors -time="2024-11-06T04:45:27Z" level=error msg="failed to store: test error" ---- PASS: TestErrors (0.22s) +time="2024-11-06T05:04:40Z" level=error msg="failed to store: test error" +--- PASS: TestErrors (0.52s) === RUN TestNewGRPCStorage --- PASS: TestNewGRPCStorage (0.00s) === RUN TestGRPCStorage --- PASS: TestGRPCStorage (0.00s) PASS -ok github.com/theupdateframework/notary/trustmanager/remoteks 0.499s -? github.com/theupdateframework/notary/trustmanager/yubikey [no test files] -? github.com/theupdateframework/notary/trustmanager/remoteks/github.com/theupdateframework/notary/trustmanager/remoteks [no test files] +ok github.com/theupdateframework/notary/trustmanager/remoteks 1.088s === RUN TestWildcardMatch --- PASS: TestWildcardMatch (0.00s) === RUN TestValidateRoot -2024/11/06 04:45:49 [INFO] generate received request -2024/11/06 04:45:49 [INFO] received CSR -2024/11/06 04:45:49 [INFO] generating key: ecdsa-256 -2024/11/06 04:45:49 [INFO] encoded CSR -2024/11/06 04:45:49 [INFO] signed certificate with serial number 345404845968527318157387540732258072126486814850 -2024/11/06 04:45:49 [INFO] received CSR -2024/11/06 04:45:49 [INFO] generating key: ecdsa-256 -2024/11/06 04:45:49 [INFO] encoded CSR -2024/11/06 04:45:49 [INFO] signed certificate with serial number 528704265263841678742372514919986904464267270111 -2024/11/06 04:45:49 [INFO] received CSR -2024/11/06 04:45:49 [INFO] generating key: ecdsa-256 -2024/11/06 04:45:49 [INFO] encoded CSR -2024/11/06 04:45:49 [INFO] signed certificate with serial number 547451538166593925100875733428173777587768276946 ---- PASS: TestValidateRoot (0.18s) +2024/11/06 05:05:16 [INFO] generate received request +2024/11/06 05:05:16 [INFO] received CSR +2024/11/06 05:05:16 [INFO] generating key: ecdsa-256 +2024/11/06 05:05:16 [INFO] encoded CSR +2024/11/06 05:05:16 [INFO] signed certificate with serial number 718070332675000201555798991601766897812671665044 +2024/11/06 05:05:16 [INFO] received CSR +2024/11/06 05:05:16 [INFO] generating key: ecdsa-256 +2024/11/06 05:05:16 [INFO] encoded CSR +2024/11/06 05:05:16 [INFO] signed certificate with serial number 126893943768085329423320878485458999262858909843 +2024/11/06 05:05:16 [INFO] received CSR +2024/11/06 05:05:16 [INFO] generating key: ecdsa-256 +2024/11/06 05:05:16 [INFO] encoded CSR +2024/11/06 05:05:16 [INFO] signed certificate with serial number 589615330533960721270341052152476187254732648626 +--- PASS: TestValidateRoot (0.53s) === RUN TestValidateRootWithoutTOFUS ---- PASS: TestValidateRootWithoutTOFUS (0.00s) +--- PASS: TestValidateRootWithoutTOFUS (0.01s) === RUN TestValidateRootWithPinnedCert ---- PASS: TestValidateRootWithPinnedCert (0.02s) +--- PASS: TestValidateRootWithPinnedCert (0.05s) === RUN TestValidateRootWithPinnedCertAndIntermediates ---- PASS: TestValidateRootWithPinnedCertAndIntermediates (0.07s) +--- PASS: TestValidateRootWithPinnedCertAndIntermediates (0.24s) === RUN TestValidateRootFailuresWithPinnedCert ---- PASS: TestValidateRootFailuresWithPinnedCert (0.02s) +--- PASS: TestValidateRootFailuresWithPinnedCert (0.05s) === RUN TestValidateRootWithPinnedCA ---- PASS: TestValidateRootWithPinnedCA (1.96s) +--- PASS: TestValidateRootWithPinnedCA (0.38s) === RUN TestValidateSuccessfulRootRotation ---- PASS: TestValidateSuccessfulRootRotation (0.27s) +--- PASS: TestValidateSuccessfulRootRotation (0.57s) === RUN TestValidateRootRotationMissingOrigSig ---- PASS: TestValidateRootRotationMissingOrigSig (0.18s) +--- PASS: TestValidateRootRotationMissingOrigSig (0.40s) === RUN TestValidateRootRotationMissingNewSig ---- PASS: TestValidateRootRotationMissingNewSig (0.17s) +--- PASS: TestValidateRootRotationMissingNewSig (0.55s) === RUN TestValidateRootRotationTrustPinning ---- PASS: TestValidateRootRotationTrustPinning (0.18s) +--- PASS: TestValidateRootRotationTrustPinning (0.43s) === RUN TestValidateRootRotationTrustPinningInvalidCA ---- PASS: TestValidateRootRotationTrustPinningInvalidCA (4.13s) +--- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.31s) === RUN TestParsePEMPublicKey -time="2024-11-06T04:45:56Z" level=warning msg="certificate with CN notary is near expiry" ---- PASS: TestParsePEMPublicKey (0.03s) +time="2024-11-06T05:05:19Z" level=warning msg="certificate with CN notary is near expiry" +--- PASS: TestParsePEMPublicKey (0.06s) === RUN TestCheckingCertExpiry -time="2024-11-06T04:45:56Z" level=warning msg="certificate with CN notary is near expiry" ---- PASS: TestCheckingCertExpiry (0.04s) +time="2024-11-06T05:05:20Z" level=warning msg="certificate with CN notary is near expiry" +--- PASS: TestCheckingCertExpiry (0.08s) === RUN TestValidateRootWithExpiredIntermediate ---- PASS: TestValidateRootWithExpiredIntermediate (0.03s) +--- PASS: TestValidateRootWithExpiredIntermediate (0.09s) === RUN TestCheckingWildcardCert ---- PASS: TestCheckingWildcardCert (0.03s) +--- PASS: TestCheckingWildcardCert (0.09s) === RUN TestWildcardMatching --- PASS: TestWildcardMatching (0.00s) PASS -ok github.com/theupdateframework/notary/trustpinning 7.327s +ok github.com/theupdateframework/notary/trustpinning 3.931s === RUN TestInitSnapshotNoTargets ---- PASS: TestInitSnapshotNoTargets (0.05s) +--- PASS: TestInitSnapshotNoTargets (0.07s) === RUN TestInitRepo ---- PASS: TestInitRepo (4.74s) +--- PASS: TestInitRepo (0.04s) === RUN TestUpdateDelegations --- PASS: TestUpdateDelegations (0.00s) === RUN TestPurgeDelegationsKeyFromTop -time="2024-11-06T04:45:56Z" 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-11-06T04:45:56Z" 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-11-06T05:05:17Z" level=warning msg="role targets/sybil has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-11-06T05:05:17Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" --- PASS: TestPurgeDelegationsKeyFromTop (0.00s) === RUN TestPurgeDelegationsKeyFromDeep -time="2024-11-06T04:45:56Z" 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) +time="2024-11-06T05:05:17Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +--- PASS: TestPurgeDelegationsKeyFromDeep (0.01s) === RUN TestPurgeDelegationsKeyBadWildRole ---- PASS: TestPurgeDelegationsKeyBadWildRole (0.00s) +--- PASS: TestPurgeDelegationsKeyBadWildRole (0.01s) === RUN TestUpdateDelegationsParentMissing ---- PASS: TestUpdateDelegationsParentMissing (0.00s) +--- PASS: TestUpdateDelegationsParentMissing (0.01s) === RUN TestUpdateDelegationsMissingParentKey ---- PASS: TestUpdateDelegationsMissingParentKey (0.00s) +--- PASS: TestUpdateDelegationsMissingParentKey (0.01s) === RUN TestUpdateDelegationsInvalidRole --- PASS: TestUpdateDelegationsInvalidRole (0.00s) === RUN TestUpdateDelegationsRoleThatIsMissingDelegationKey ---- PASS: TestUpdateDelegationsRoleThatIsMissingDelegationKey (0.00s) +--- PASS: TestUpdateDelegationsRoleThatIsMissingDelegationKey (0.01s) === RUN TestUpdateDelegationsNotEnoughKeys -time="2024-11-06T04:45:56Z" 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) +time="2024-11-06T05:05:17Z" level=warning msg="role targets/role has fewer keys than its threshold of 2; it will not be usable until keys are added to it" +--- PASS: TestUpdateDelegationsNotEnoughKeys (0.01s) === RUN TestUpdateDelegationsAddKeyToRole ---- PASS: TestUpdateDelegationsAddKeyToRole (0.00s) +--- PASS: TestUpdateDelegationsAddKeyToRole (0.01s) === RUN TestDeleteDelegations ---- PASS: TestDeleteDelegations (0.00s) +--- PASS: TestDeleteDelegations (0.01s) === RUN TestDeleteDelegationsRoleNotExistBecauseNoParentMeta ---- PASS: TestDeleteDelegationsRoleNotExistBecauseNoParentMeta (0.00s) +--- PASS: TestDeleteDelegationsRoleNotExistBecauseNoParentMeta (0.01s) === RUN TestDeleteDelegationsRoleNotExist --- PASS: TestDeleteDelegationsRoleNotExist (0.00s) === RUN TestDeleteDelegationsInvalidRole ---- PASS: TestDeleteDelegationsInvalidRole (0.00s) +--- PASS: TestDeleteDelegationsInvalidRole (0.01s) === RUN TestDeleteDelegationsParentMissing ---- PASS: TestDeleteDelegationsParentMissing (0.00s) +--- PASS: TestDeleteDelegationsParentMissing (0.01s) === RUN TestDeleteDelegationsMissingParentSigningKey ---- PASS: TestDeleteDelegationsMissingParentSigningKey (0.00s) +--- PASS: TestDeleteDelegationsMissingParentSigningKey (0.01s) === RUN TestDeleteDelegationsMidSliceRole ---- PASS: TestDeleteDelegationsMidSliceRole (0.00s) +--- PASS: TestDeleteDelegationsMidSliceRole (0.01s) === RUN TestGetDelegationRoleAndMetadataExistDelegationExists ---- PASS: TestGetDelegationRoleAndMetadataExistDelegationExists (0.00s) +--- PASS: TestGetDelegationRoleAndMetadataExistDelegationExists (0.01s) === RUN TestGetDelegationRoleAndMetadataExistDelegationDoesntExists ---- PASS: TestGetDelegationRoleAndMetadataExistDelegationDoesntExists (0.00s) +--- PASS: TestGetDelegationRoleAndMetadataExistDelegationDoesntExists (0.01s) === RUN TestGetDelegationRoleAndMetadataDoesntExists --- PASS: TestGetDelegationRoleAndMetadataDoesntExists (0.00s) === RUN TestGetDelegationParentMissing ---- PASS: TestGetDelegationParentMissing (0.00s) +--- PASS: TestGetDelegationParentMissing (0.01s) === RUN TestAddTargetsRoleAndMetadataExist ---- PASS: TestAddTargetsRoleAndMetadataExist (0.00s) +--- PASS: TestAddTargetsRoleAndMetadataExist (0.01s) === RUN TestAddTargetsRoleExistsAndMetadataDoesntExist ---- PASS: TestAddTargetsRoleExistsAndMetadataDoesntExist (0.00s) +--- PASS: TestAddTargetsRoleExistsAndMetadataDoesntExist (0.01s) === RUN TestAddTargetsRoleDoesntExist --- PASS: TestAddTargetsRoleDoesntExist (0.00s) === RUN TestAddTargetsNoSigningKeys ---- PASS: TestAddTargetsNoSigningKeys (0.00s) +--- PASS: TestAddTargetsNoSigningKeys (0.01s) === RUN TestRemoveExistingAndNonexistingTargets --- PASS: TestRemoveExistingAndNonexistingTargets (0.00s) === RUN TestRemoveTargetsRoleDoesntExist ---- PASS: TestRemoveTargetsRoleDoesntExist (0.00s) +--- PASS: TestRemoveTargetsRoleDoesntExist (0.01s) === RUN TestRemoveTargetsNoSigningKeys --- PASS: TestRemoveTargetsNoSigningKeys (0.00s) === RUN TestAddBaseKeysToRoot ---- PASS: TestAddBaseKeysToRoot (0.01s) +--- PASS: TestAddBaseKeysToRoot (0.03s) === RUN TestRemoveBaseKeysFromRoot ---- PASS: TestRemoveBaseKeysFromRoot (0.01s) +--- PASS: TestRemoveBaseKeysFromRoot (0.02s) === RUN TestReplaceBaseKeysInRoot ---- PASS: TestReplaceBaseKeysInRoot (0.03s) +--- PASS: TestReplaceBaseKeysInRoot (0.06s) === RUN TestGetAllRoles --- PASS: TestGetAllRoles (0.00s) === RUN TestGetBaseRoles ---- PASS: TestGetBaseRoles (0.00s) +--- PASS: TestGetBaseRoles (0.01s) === RUN TestGetBaseRolesInvalidName --- PASS: TestGetBaseRolesInvalidName (0.00s) === RUN TestGetDelegationValidRoles ---- PASS: TestGetDelegationValidRoles (0.00s) +--- PASS: TestGetDelegationValidRoles (0.01s) === RUN TestGetDelegationRolesInvalidName ---- PASS: TestGetDelegationRolesInvalidName (0.00s) +--- PASS: TestGetDelegationRolesInvalidName (0.01s) === RUN TestGetDelegationRolesInvalidPaths --- PASS: TestGetDelegationRolesInvalidPaths (0.00s) === RUN TestDelegationRolesParent @@ -3600,45 +3639,45 @@ === RUN TestGetBaseRoleEmptyRepo --- PASS: TestGetBaseRoleEmptyRepo (0.00s) === RUN TestGetBaseRoleKeyMissing ---- PASS: TestGetBaseRoleKeyMissing (0.00s) +--- PASS: TestGetBaseRoleKeyMissing (0.01s) === RUN TestGetDelegationRoleKeyMissing --- PASS: TestGetDelegationRoleKeyMissing (0.00s) === RUN TestSignRootOldKeyCertExists ---- PASS: TestSignRootOldKeyCertExists (0.13s) +--- PASS: TestSignRootOldKeyCertExists (0.33s) === RUN TestSignRootOldKeyCertMissing ---- PASS: TestSignRootOldKeyCertMissing (0.09s) +--- PASS: TestSignRootOldKeyCertMissing (0.30s) === RUN TestRootKeyRotation ---- PASS: TestRootKeyRotation (0.33s) +--- PASS: TestRootKeyRotation (0.80s) === RUN TestBuilderLoadsValidRolesOnly ---- PASS: TestBuilderLoadsValidRolesOnly (0.07s) +--- PASS: TestBuilderLoadsValidRolesOnly (0.17s) === RUN TestBuilderOnlyAcceptsRootFirstWhenLoading ---- PASS: TestBuilderOnlyAcceptsRootFirstWhenLoading (0.01s) +--- PASS: TestBuilderOnlyAcceptsRootFirstWhenLoading (0.02s) === RUN TestBuilderOnlyAcceptsDelegationsAfterParent ---- PASS: TestBuilderOnlyAcceptsDelegationsAfterParent (0.02s) +--- PASS: TestBuilderOnlyAcceptsDelegationsAfterParent (0.05s) === RUN TestMarkingIsValid ---- PASS: TestMarkingIsValid (0.03s) +--- PASS: TestMarkingIsValid (0.08s) === RUN TestBuilderLoadInvalidDelegations ---- PASS: TestBuilderLoadInvalidDelegations (0.07s) +--- PASS: TestBuilderLoadInvalidDelegations (0.15s) === RUN TestBuilderLoadInvalidDelegationsOldVersion ---- PASS: TestBuilderLoadInvalidDelegationsOldVersion (0.13s) +--- PASS: TestBuilderLoadInvalidDelegationsOldVersion (0.15s) === RUN TestBuilderAcceptRoleOnce ---- PASS: TestBuilderAcceptRoleOnce (0.06s) +--- PASS: TestBuilderAcceptRoleOnce (0.08s) === RUN TestBuilderStopsAcceptingOrProducingDataOnceDone ---- PASS: TestBuilderStopsAcceptingOrProducingDataOnceDone (0.04s) +--- PASS: TestBuilderStopsAcceptingOrProducingDataOnceDone (0.06s) === RUN TestGenerateSnapshotInvalidOperations ---- PASS: TestGenerateSnapshotInvalidOperations (0.29s) +--- PASS: TestGenerateSnapshotInvalidOperations (0.38s) === RUN TestGenerateTimestampInvalidOperations ---- PASS: TestGenerateTimestampInvalidOperations (0.23s) +--- PASS: TestGenerateTimestampInvalidOperations (0.28s) === RUN TestGetConsistentInfo --- PASS: TestGetConsistentInfo (0.07s) === RUN TestTimestampPreAndPostChecksumming ---- PASS: TestTimestampPreAndPostChecksumming (0.12s) +--- PASS: TestTimestampPreAndPostChecksumming (0.20s) === RUN TestSnapshotLoadedFirstChecksumsOthers ---- PASS: TestSnapshotLoadedFirstChecksumsOthers (0.10s) +--- PASS: TestSnapshotLoadedFirstChecksumsOthers (0.26s) === RUN TestSnapshotLoadedAfterChecksumsOthersRetroactively ---- PASS: TestSnapshotLoadedAfterChecksumsOthersRetroactively (0.23s) +--- PASS: TestSnapshotLoadedAfterChecksumsOthersRetroactively (0.51s) PASS -ok github.com/theupdateframework/notary/tuf 9.296s +ok github.com/theupdateframework/notary/tuf 4.403s === RUN TestMergeStrSlicesExclusive --- PASS: TestMergeStrSlicesExclusive (0.00s) === RUN TestMergeStrSlicesOverlap @@ -3688,7 +3727,7 @@ === RUN TestRootFromSignedValidatesRoleData --- PASS: TestRootFromSignedValidatesRoleData (0.01s) === RUN TestRootFromSignedValidatesRoleType ---- PASS: TestRootFromSignedValidatesRoleType (0.01s) +--- PASS: TestRootFromSignedValidatesRoleType (0.00s) === RUN TestRootFromSignedValidatesVersion --- PASS: TestRootFromSignedValidatesVersion (0.00s) === RUN TestSnapshotToSignedMarshalsSignedPortionWithCanonicalJSON @@ -3772,60 +3811,58 @@ === RUN TestFileMetaEquals --- PASS: TestFileMetaEquals (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/data 0.063s -? 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/data 0.059s === RUN TestListKeys ---- PASS: TestListKeys (0.03s) +--- PASS: TestListKeys (0.07s) === RUN TestGetKeys --- PASS: TestGetKeys (0.00s) === RUN TestBasicSign ---- PASS: TestBasicSign (0.00s) +--- PASS: TestBasicSign (0.01s) === RUN TestReSign --- PASS: TestReSign (0.00s) === RUN TestMultiSign ---- PASS: TestMultiSign (0.01s) +--- PASS: TestMultiSign (0.03s) === RUN TestSignReturnsNoSigs --- PASS: TestSignReturnsNoSigs (0.00s) === RUN TestSignWithX509 ---- PASS: TestSignWithX509 (0.01s) +--- PASS: TestSignWithX509 (0.04s) === 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) +--- PASS: TestSignMinSignatures (0.01s) === RUN TestSignFailingKeys ---- PASS: TestSignFailingKeys (0.02s) +--- PASS: TestSignFailingKeys (0.04s) === RUN TestErrInsufficientSignaturesMessaging --- PASS: TestErrInsufficientSignaturesMessaging (0.00s) === RUN TestRSAPSSVerifier ---- PASS: TestRSAPSSVerifier (0.03s) +--- PASS: TestRSAPSSVerifier (0.06s) === RUN TestRSAPSSx509Verifier ---- PASS: TestRSAPSSx509Verifier (0.01s) +--- PASS: TestRSAPSSx509Verifier (0.02s) === RUN TestRSAPSSVerifierWithInvalidKeyType --- PASS: TestRSAPSSVerifierWithInvalidKeyType (0.00s) === RUN TestRSAPSSVerifierWithInvalidKeyLength ---- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.45s) +--- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.59s) === RUN TestRSAPSSVerifierWithInvalidKey ---- PASS: TestRSAPSSVerifierWithInvalidKey (0.00s) +--- PASS: TestRSAPSSVerifierWithInvalidKey (0.01s) === RUN TestRSAPSSVerifierWithInvalidSignature ---- PASS: TestRSAPSSVerifierWithInvalidSignature (0.03s) +--- PASS: TestRSAPSSVerifierWithInvalidSignature (0.06s) === RUN TestRSAPKCS1v15Verifier ---- PASS: TestRSAPKCS1v15Verifier (0.03s) +--- PASS: TestRSAPKCS1v15Verifier (0.06s) === RUN TestRSAPKCS1v15x509Verifier ---- PASS: TestRSAPKCS1v15x509Verifier (0.01s) +--- PASS: TestRSAPKCS1v15x509Verifier (0.02s) === RUN TestRSAPKCS1v15VerifierWithInvalidKeyType --- PASS: TestRSAPKCS1v15VerifierWithInvalidKeyType (0.00s) === RUN TestRSAPKCS1v15VerifierWithInvalidKey --- PASS: TestRSAPKCS1v15VerifierWithInvalidKey (0.00s) === RUN TestRSAPKCS1v15VerifierWithInvalidSignature -time="2024-11-06T04:46:01Z" level=error msg="Failed verification: crypto/rsa: verification error" ---- PASS: TestRSAPKCS1v15VerifierWithInvalidSignature (0.03s) +time="2024-11-06T05:05:27Z" level=error msg="Failed verification: crypto/rsa: verification error" +--- PASS: TestRSAPKCS1v15VerifierWithInvalidSignature (0.06s) === RUN TestECDSAVerifier ---- PASS: TestECDSAVerifier (0.01s) +--- PASS: TestECDSAVerifier (0.02s) === RUN TestECDSAVerifierOtherCurves ---- PASS: TestECDSAVerifierOtherCurves (0.68s) +--- PASS: TestECDSAVerifierOtherCurves (1.86s) === RUN TestECDSAx509Verifier --- PASS: TestECDSAx509Verifier (0.01s) === RUN TestECDSAVerifierWithInvalidKeyType @@ -3833,100 +3870,100 @@ === RUN TestECDSAVerifierWithInvalidKey --- PASS: TestECDSAVerifierWithInvalidKey (0.00s) === RUN TestECDSAVerifierWithInvalidSignature ---- PASS: TestECDSAVerifierWithInvalidSignature (0.01s) +--- PASS: TestECDSAVerifierWithInvalidSignature (0.02s) === RUN TestED25519VerifierInvalidKeyType --- PASS: TestED25519VerifierInvalidKeyType (0.00s) === RUN TestRSAPyCryptoVerifierInvalidKeyType --- PASS: TestRSAPyCryptoVerifierInvalidKeyType (0.00s) === RUN TestPyCryptoRSAPSSCompat ---- PASS: TestPyCryptoRSAPSSCompat (0.00s) +--- PASS: TestPyCryptoRSAPSSCompat (0.01s) === RUN TestPyNaCled25519Compat --- PASS: TestPyNaCled25519Compat (0.00s) === RUN TestRoleNoKeys --- PASS: TestRoleNoKeys (0.00s) === RUN TestNotEnoughSigs ---- PASS: TestNotEnoughSigs (0.00s) +--- PASS: TestNotEnoughSigs (0.01s) === RUN TestNoSigs --- PASS: TestNoSigs (0.00s) === RUN TestExactlyEnoughSigs --- PASS: TestExactlyEnoughSigs (0.00s) === RUN TestIsValidNotExported ---- PASS: TestIsValidNotExported (0.00s) +--- PASS: TestIsValidNotExported (0.01s) === RUN TestMoreThanEnoughSigs --- PASS: TestMoreThanEnoughSigs (0.01s) === RUN TestValidSigWithIncorrectKeyID --- PASS: TestValidSigWithIncorrectKeyID (0.00s) === RUN TestDuplicateSigs ---- PASS: TestDuplicateSigs (0.00s) +--- PASS: TestDuplicateSigs (0.01s) === RUN TestUnknownKeyBelowThreshold ---- PASS: TestUnknownKeyBelowThreshold (0.00s) +--- PASS: TestUnknownKeyBelowThreshold (0.01s) === RUN TestVerifyVersion --- PASS: TestVerifyVersion (0.00s) === RUN TestVerifyExpiry -time="2024-11-06T04:46:02Z" level=error msg="Metadata for root expired" +time="2024-11-06T05:05: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: TestVerifyPublicKeyMatchesPrivateKeyFails (0.01s) PASS -ok github.com/theupdateframework/notary/tuf/signed 1.434s +ok github.com/theupdateframework/notary/tuf/signed 3.138s === RUN TestNewSwizzler ---- PASS: TestNewSwizzler (0.09s) +--- PASS: TestNewSwizzler (0.26s) === RUN TestSwizzlerSetInvalidJSON ---- PASS: TestSwizzlerSetInvalidJSON (0.07s) +--- PASS: TestSwizzlerSetInvalidJSON (0.22s) === RUN TestSwizzlerAddExtraSpace ---- PASS: TestSwizzlerAddExtraSpace (0.07s) +--- PASS: TestSwizzlerAddExtraSpace (0.25s) === RUN TestSwizzlerSetInvalidSigned ---- PASS: TestSwizzlerSetInvalidSigned (0.07s) +--- PASS: TestSwizzlerSetInvalidSigned (0.16s) === RUN TestSwizzlerSetInvalidSignedMeta ---- PASS: TestSwizzlerSetInvalidSignedMeta (0.07s) +--- PASS: TestSwizzlerSetInvalidSignedMeta (0.16s) === RUN TestSwizzlerSetInvalidMetadataType ---- PASS: TestSwizzlerSetInvalidMetadataType (0.07s) +--- PASS: TestSwizzlerSetInvalidMetadataType (0.22s) === RUN TestSwizzlerInvalidateMetadataSignatures ---- PASS: TestSwizzlerInvalidateMetadataSignatures (0.07s) +--- PASS: TestSwizzlerInvalidateMetadataSignatures (0.18s) === RUN TestSwizzlerRemoveMetadata ---- PASS: TestSwizzlerRemoveMetadata (0.07s) +--- PASS: TestSwizzlerRemoveMetadata (0.22s) === RUN TestSwizzlerSignMetadataWithInvalidKey ---- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.08s) +--- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.13s) === RUN TestSwizzlerOffsetMetadataVersion ---- PASS: TestSwizzlerOffsetMetadataVersion (0.08s) +--- PASS: TestSwizzlerOffsetMetadataVersion (0.09s) === RUN TestSwizzlerExpireMetadata ---- PASS: TestSwizzlerExpireMetadata (0.09s) +--- PASS: TestSwizzlerExpireMetadata (0.17s) === RUN TestSwizzlerSetThresholdBaseRole ---- PASS: TestSwizzlerSetThresholdBaseRole (0.07s) +--- PASS: TestSwizzlerSetThresholdBaseRole (0.16s) === RUN TestSwizzlerSetThresholdDelegatedRole ---- PASS: TestSwizzlerSetThresholdDelegatedRole (0.07s) +--- PASS: TestSwizzlerSetThresholdDelegatedRole (0.28s) === RUN TestSwizzlerChangeRootKey ---- PASS: TestSwizzlerChangeRootKey (0.09s) +--- PASS: TestSwizzlerChangeRootKey (0.33s) === RUN TestSwizzlerUpdateSnapshotHashesSpecifiedRoles ---- PASS: TestSwizzlerUpdateSnapshotHashesSpecifiedRoles (0.08s) +--- PASS: TestSwizzlerUpdateSnapshotHashesSpecifiedRoles (0.26s) === RUN TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles ---- PASS: TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles (0.08s) +--- PASS: TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles (0.23s) === RUN TestSwizzlerUpdateTimestamp ---- PASS: TestSwizzlerUpdateTimestamp (0.08s) +--- PASS: TestSwizzlerUpdateTimestamp (0.17s) === RUN TestMissingSigningKey ---- PASS: TestMissingSigningKey (0.07s) +--- PASS: TestMissingSigningKey (0.16s) === RUN TestSwizzlerMutateRoot ---- PASS: TestSwizzlerMutateRoot (0.07s) +--- PASS: TestSwizzlerMutateRoot (0.17s) === RUN TestSwizzlerMutateTimestamp ---- PASS: TestSwizzlerMutateTimestamp (0.08s) +--- PASS: TestSwizzlerMutateTimestamp (0.17s) === RUN TestSwizzlerMutateSnapshot ---- PASS: TestSwizzlerMutateSnapshot (0.07s) +--- PASS: TestSwizzlerMutateSnapshot (0.08s) === RUN TestSwizzlerMutateTargets ---- PASS: TestSwizzlerMutateTargets (0.07s) +--- PASS: TestSwizzlerMutateTargets (0.08s) === RUN TestSwizzlerRotateKeyBaseRole ---- PASS: TestSwizzlerRotateKeyBaseRole (0.10s) +--- PASS: TestSwizzlerRotateKeyBaseRole (0.11s) === RUN TestSwizzlerRotateKeyDelegationRole ---- PASS: TestSwizzlerRotateKeyDelegationRole (0.07s) +--- PASS: TestSwizzlerRotateKeyDelegationRole (0.15s) PASS -ok github.com/theupdateframework/notary/tuf/testutils 1.861s +ok github.com/theupdateframework/notary/tuf/testutils 4.510s === RUN TestConvertTUFKeyToPKCS8 ---- PASS: TestConvertTUFKeyToPKCS8 (0.11s) +--- PASS: TestConvertTUFKeyToPKCS8 (0.21s) === RUN TestParsePKCS8ToTufKey ---- PASS: TestParsePKCS8ToTufKey (0.62s) +--- PASS: TestParsePKCS8ToTufKey (1.72s) === RUN TestPEMtoPEM ---- PASS: TestPEMtoPEM (0.00s) +--- PASS: TestPEMtoPEM (0.01s) === RUN TestRoleListLen --- PASS: TestRoleListLen (0.00s) === RUN TestRoleListLess @@ -3966,29 +4003,29 @@ === RUN TestRoleNameSliceRemove --- PASS: TestRoleNameSliceRemove (0.00s) === RUN TestCertsToKeys ---- PASS: TestCertsToKeys (0.00s) +--- PASS: TestCertsToKeys (0.01s) === RUN TestNewCertificate --- PASS: TestNewCertificate (0.00s) === RUN TestKeyOperations ---- PASS: TestKeyOperations (0.08s) +--- PASS: TestKeyOperations (0.25s) === RUN TestRSAX509PublickeyID ---- PASS: TestRSAX509PublickeyID (0.00s) +--- PASS: TestRSAX509PublickeyID (0.01s) === RUN TestECDSAX509PublickeyID ---- PASS: TestECDSAX509PublickeyID (0.01s) +--- PASS: TestECDSAX509PublickeyID (0.03s) === RUN TestExtractPrivateKeyAttributes --- PASS: TestExtractPrivateKeyAttributes (0.00s) === RUN TestParsePEMPrivateKeyLegacy ---- PASS: TestParsePEMPrivateKeyLegacy (0.00s) +--- PASS: TestParsePEMPrivateKeyLegacy (0.01s) === RUN TestValidateCertificateWithSHA1 ---- PASS: TestValidateCertificateWithSHA1 (0.01s) +--- PASS: TestValidateCertificateWithSHA1 (0.03s) === RUN TestValidateCertificateWithExpiredCert ---- PASS: TestValidateCertificateWithExpiredCert (0.01s) +--- PASS: TestValidateCertificateWithExpiredCert (0.02s) === RUN TestValidateCertificateWithInvalidExpiry ---- PASS: TestValidateCertificateWithInvalidExpiry (0.01s) +--- PASS: TestValidateCertificateWithInvalidExpiry (0.03s) === RUN TestValidateCertificateWithShortKey ---- PASS: TestValidateCertificateWithShortKey (0.19s) +--- PASS: TestValidateCertificateWithShortKey (0.91s) PASS -ok github.com/theupdateframework/notary/tuf/utils 1.064s +ok github.com/theupdateframework/notary/tuf/utils 3.294s === RUN TestNewSerializableErrorNonValidationError --- PASS: TestNewSerializableErrorNonValidationError (0.00s) === RUN TestNewSerializableErrorValidationError @@ -4004,8 +4041,7 @@ === RUN TestUnmarshalInvalidJSON --- PASS: TestUnmarshalInvalidJSON (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/validation 0.016s -? github.com/theupdateframework/notary/version [no test files] +ok github.com/theupdateframework/notary/tuf/validation 0.042s === RUN TestSetSignalTrap --- PASS: TestSetSignalTrap (0.00s) === RUN TestLogLevelSignalHandle @@ -4067,11 +4103,11 @@ === RUN TestParseTLSNoTLSNotRequired --- PASS: TestParseTLSNoTLSNotRequired (0.00s) === RUN TestParseTLSWithTLS ---- PASS: TestParseTLSWithTLS (0.00s) +--- PASS: TestParseTLSWithTLS (0.01s) === RUN TestParseTLSWithTLSRelativeToConfigFile --- PASS: TestParseTLSWithTLSRelativeToConfigFile (0.00s) === RUN TestParseTLSWithEnvironmentVariables ---- PASS: TestParseTLSWithEnvironmentVariables (0.00s) +--- PASS: TestParseTLSWithEnvironmentVariables (0.02s) === RUN TestParseViperWithInvalidFile --- PASS: TestParseViperWithInvalidFile (0.00s) === RUN TestParseViperWithValidFile @@ -4105,7 +4141,7 @@ === RUN TestDoAuthWildcardImage --- PASS: TestDoAuthWildcardImage (0.00s) PASS -ok github.com/theupdateframework/notary/utils 0.044s +ok github.com/theupdateframework/notary/utils 0.109s create-stamp debian/debhelper-build-stamp dh_testroot -O--builddirectory=/build/reproducible-path/notary-0.7.0\+git20240416.9d2b3b3\+ds1/_build -O--buildsystem=golang dh_prep -O--builddirectory=/build/reproducible-path/notary-0.7.0\+git20240416.9d2b3b3\+ds1/_build -O--buildsystem=golang @@ -4133,8 +4169,8 @@ dh_fixperms -O--builddirectory=/build/reproducible-path/notary-0.7.0\+git20240416.9d2b3b3\+ds1/_build -O--buildsystem=golang dh_missing -O--builddirectory=/build/reproducible-path/notary-0.7.0\+git20240416.9d2b3b3\+ds1/_build -O--buildsystem=golang dh_strip -a -O--builddirectory=/build/reproducible-path/notary-0.7.0\+git20240416.9d2b3b3\+ds1/_build -O--buildsystem=golang -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/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\+git20240416.9d2b3b3\+ds1/_build -O--buildsystem=golang dh_shlibdeps -a -O--builddirectory=/build/reproducible-path/notary-0.7.0\+git20240416.9d2b3b3\+ds1/_build -O--buildsystem=golang @@ -4145,8 +4181,8 @@ dpkg-gencontrol: warning: package notary: substitution variable ${misc:Static-Built-Using} unused, but is defined dh_md5sums -O--builddirectory=/build/reproducible-path/notary-0.7.0\+git20240416.9d2b3b3\+ds1/_build -O--buildsystem=golang dh_builddeb -O--builddirectory=/build/reproducible-path/notary-0.7.0\+git20240416.9d2b3b3\+ds1/_build -O--buildsystem=golang -dpkg-deb: building package 'notary-dbgsym' in '../notary-dbgsym_0.7.0+git20240416.9d2b3b3+ds1-2_armhf.deb'. dpkg-deb: building package 'notary' in '../notary_0.7.0+git20240416.9d2b3b3+ds1-2_armhf.deb'. +dpkg-deb: building package 'notary-dbgsym' in '../notary-dbgsym_0.7.0+git20240416.9d2b3b3+ds1-2_armhf.deb'. dpkg-deb: building package 'golang-github-docker-notary-dev' in '../golang-github-docker-notary-dev_0.7.0+git20240416.9d2b3b3+ds1-2_all.deb'. dpkg-genbuildinfo --build=binary -O../notary_0.7.0+git20240416.9d2b3b3+ds1-2_armhf.buildinfo dpkg-genchanges --build=binary -O../notary_0.7.0+git20240416.9d2b3b3+ds1-2_armhf.changes @@ -4155,12 +4191,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: including full source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/14887/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/14887/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/2979 and its subdirectories -I: Current time: Tue Nov 5 16:47:33 -12 2024 -I: pbuilder-time-stamp: 1730868453 +I: removing directory /srv/workspace/pbuilder/14887 and its subdirectories +I: Current time: Wed Nov 6 19:08:03 +14 2024 +I: pbuilder-time-stamp: 1730869683