Diff of the two buildlogs: -- --- b1/build.log 2024-01-06 14:04:53.751692492 +0000 +++ b2/build.log 2024-01-06 14:18:33.130074732 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sat Jan 6 01:56:09 -12 2024 -I: pbuilder-time-stamp: 1704549369 +I: Current time: Sun Jan 7 04:05:54 +14 2024 +I: pbuilder-time-stamp: 1704549954 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bookworm-reproducible-base.tgz] I: copying local configuration @@ -31,49 +31,81 @@ dpkg-source: info: applying 0004-Use-github.com-golang-protobuf-ptypes-empty.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/26164/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/14746/tmp/hooks/D01_modify_environment starting +debug: Running on virt64a. +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 Jan 6 14:06 /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/14746/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/14746/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='bookworm' - 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]="15" [3]="1" [4]="release" [5]="arm-unknown-linux-gnueabihf") + BASH_VERSION='5.2.15(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=bookworm + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=arm + HOST_ARCH=armhf IFS=' ' - INVOCATION_ID='04318e636e0c426b8975e191e0efcd5d' - 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='26164' - PS1='# ' - PS2='> ' + INVOCATION_ID=e0f4803e41a94c7fb9c64db41a4d3828 + 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=14746 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.CyNbEsyK/pbuilderrc_FihH --distribution bookworm --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.CyNbEsyK/b1 --logfile b1/build.log notary_0.7.0+ds1-2.dsc' - SUDO_GID='113' - SUDO_UID='107' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://10.0.0.15:3142/' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.CyNbEsyK/pbuilderrc_C2aa --distribution bookworm --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.CyNbEsyK/b2 --logfile b2/build.log notary_0.7.0+ds1-2.dsc' + SUDO_GID=114 + SUDO_UID=108 + 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 virt32c 6.1.0-17-armmp-lpae #1 SMP Debian 6.1.69-1 (2023-12-30) armv7l GNU/Linux + Linux i-capture-the-hostname 6.1.0-17-arm64 #1 SMP Debian 6.1.69-1 (2023-12-30) aarch64 GNU/Linux I: ls -l /bin total 4964 -rwxr-xr-x 1 root root 838488 Apr 23 2023 bash @@ -133,7 +165,7 @@ -rwxr-xr-x 1 root root 67600 Sep 20 2022 rmdir -rwxr-xr-x 1 root root 14152 Jul 28 23:46 run-parts -rwxr-xr-x 1 root root 133372 Jan 5 2023 sed - lrwxrwxrwx 1 root root 4 Jan 5 2023 sh -> dash + lrwxrwxrwx 1 root root 9 Jan 6 14:06 sh -> /bin/bash -rwxr-xr-x 1 root root 67584 Sep 20 2022 sleep -rwxr-xr-x 1 root root 67644 Sep 20 2022 stty -rwsr-xr-x 1 root root 50800 Mar 23 2023 su @@ -159,7 +191,7 @@ -rwxr-xr-x 1 root root 2206 Apr 10 2022 zless -rwxr-xr-x 1 root root 1842 Apr 10 2022 zmore -rwxr-xr-x 1 root root 4577 Apr 10 2022 znew -I: user script /srv/workspace/pbuilder/26164/tmp/hooks/D02_print_environment finished +I: user script /srv/workspace/pbuilder/14746/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -435,7 +467,7 @@ Get: 184 http://deb.debian.org/debian bookworm/main armhf golang-goprotobuf-dev armhf 1.3.5-4+b3 [3432 B] Get: 185 http://deb.debian.org/debian bookworm/main armhf golang-gopkg-rethinkdb-rethinkdb-go.v6-dev all 6.2.1-2 [106 kB] Get: 186 http://deb.debian.org/debian bookworm/main armhf openssl armhf 3.0.11-1~deb12u2 [1386 kB] -Fetched 132 MB in 4s (36.6 MB/s) +Fetched 132 MB in 4s (32.2 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 ... 17881 files and directories currently installed.) @@ -1194,7 +1226,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/notary-0.7.0+ds1/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-genchanges -S > ../notary_0.7.0+ds1-2_source.changes +I: user script /srv/workspace/pbuilder/14746/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for bookworm +I: user script /srv/workspace/pbuilder/14746/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/notary-0.7.0+ds1/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-genchanges -S > ../notary_0.7.0+ds1-2_source.changes dpkg-buildpackage: info: source package notary dpkg-buildpackage: info: source version 0.7.0+ds1-2 dpkg-buildpackage: info: source distribution unstable @@ -1473,28 +1509,28 @@ 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/cpu internal/goexperiment -internal/abi +internal/cpu internal/goos +internal/abi runtime/internal/math runtime/internal/sys runtime/internal/syscall -internal/bytealg -runtime/internal/atomic internal/race sync/atomic math/bits +internal/bytealg +runtime/internal/atomic unicode/utf8 math -runtime internal/itoa -unicode encoding +unicode unicode/utf16 +runtime crypto/internal/subtle crypto/subtle crypto/internal/boring/sig @@ -1514,347 +1550,344 @@ math/rand internal/singleflight runtime/cgo +github.com/spf13/viper/internal/encoding +google.golang.org/grpc/internal/buffer +google.golang.org/grpc/internal/grpcsync errors sort +google.golang.org/protobuf/internal/pragma io strconv -hash internal/oserror syscall +hash +internal/safefilepath +path +bytes crypto reflect +strings +bufio internal/syscall/unix time -internal/safefilepath internal/syscall/execenv -path -bytes -strings +crypto/internal/randutil +crypto/internal/nistec/fiat +vendor/golang.org/x/net/dns/dnsmessage +regexp/syntax internal/poll io/fs -bufio -os encoding/binary internal/fmtsort context +os +regexp crypto/md5 encoding/base64 -github.com/theupdateframework/notary golang.org/x/sys/unix -fmt crypto/cipher +crypto/internal/nistec +github.com/theupdateframework/notary +fmt crypto/internal/boring -crypto/internal/randutil crypto/aes -crypto/internal/nistec/fiat -encoding/json -github.com/docker/go/canonical/json -log -github.com/sirupsen/logrus -math/big -crypto/internal/nistec crypto/sha512 crypto/sha256 crypto/des crypto/internal/edwards25519/field -crypto/internal/edwards25519 crypto/sha1 +encoding/json +github.com/docker/go/canonical/json +log +math/big +crypto/internal/edwards25519 encoding/hex +github.com/sirupsen/logrus encoding/pem internal/godebug -vendor/golang.org/x/net/dns/dnsmessage internal/intern net/netip +net/url crypto/rand -github.com/docker/distribution/uuid crypto/elliptic crypto/internal/boring/bbig encoding/asn1 +github.com/docker/distribution/uuid crypto/rsa crypto/dsa crypto/ed25519 net -net/url -vendor/golang.org/x/crypto/cryptobyte -crypto/x509/pkix path/filepath -crypto/ecdsa golang.org/x/crypto/ed25519 io/ioutil -regexp/syntax crypto/hmac golang.org/x/crypto/pbkdf2 +vendor/golang.org/x/crypto/cryptobyte +crypto/x509/pkix github.com/theupdateframework/notary/tuf/validation mime mime/quotedprintable compress/flate -regexp +crypto/ecdsa hash/crc32 crypto/rc4 vendor/golang.org/x/crypto/chacha20 -compress/gzip vendor/golang.org/x/crypto/internal/poly1305 +compress/gzip vendor/golang.org/x/crypto/curve25519/internal/field vendor/golang.org/x/crypto/chacha20poly1305 -vendor/golang.org/x/crypto/curve25519 vendor/golang.org/x/crypto/hkdf vendor/golang.org/x/text/transform vendor/golang.org/x/text/unicode/bidi -vendor/golang.org/x/text/unicode/norm -vendor/golang.org/x/text/secure/bidirule +vendor/golang.org/x/crypto/curve25519 vendor/golang.org/x/net/http2/hpack +vendor/golang.org/x/text/unicode/norm net/http/internal net/http/internal/ascii github.com/miekg/pkcs11 -vendor/golang.org/x/net/idna -crypto/x509 -net/textproto -mime/multipart -vendor/golang.org/x/net/http/httpguts -vendor/golang.org/x/net/http/httpproxy +vendor/golang.org/x/text/secure/bidirule flag -github.com/theupdateframework/notary/tuf/data -crypto/tls -github.com/theupdateframework/notary/client/changelist -github.com/theupdateframework/notary/tuf/utils encoding/csv github.com/fsnotify/fsnotify -github.com/mitchellh/mapstructure +vendor/golang.org/x/net/idna github.com/spf13/afero/internal/common -net/http/httptrace github.com/spf13/afero/mem -net/http golang.org/x/text/transform 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/theupdateframework/notary/storage -github.com/magiconair/properties -github.com/theupdateframework/notary/trustmanager -github.com/spf13/afero -github.com/theupdateframework/notary/cryptoservice -github.com/theupdateframework/notary/tuf/signed github.com/spf13/jwalterweatherman -github.com/spf13/pflag -github.com/theupdateframework/notary/trustmanager/yubikey -github.com/theupdateframework/notary/trustpinning -github.com/spf13/viper/internal/encoding github.com/hashicorp/hcl/hcl/strconv github.com/hashicorp/hcl/hcl/token -github.com/theupdateframework/notary/tuf +crypto/x509 +net/textproto +mime/multipart +vendor/golang.org/x/net/http/httpguts +vendor/golang.org/x/net/http/httpproxy +github.com/mitchellh/mapstructure +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/cast 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/theupdateframework/notary/client github.com/hashicorp/hcl/json/scanner text/tabwriter github.com/hashicorp/hcl/json/parser github.com/hashicorp/hcl/hcl/printer github.com/hashicorp/hcl +net/http/httptrace github.com/spf13/viper/internal/encoding/json github.com/pelletier/go-toml +net/http github.com/spf13/viper/internal/encoding/hcl gopkg.in/yaml.v2 +github.com/spf13/viper/internal/encoding/toml github.com/subosito/gotenv gopkg.in/ini.v1 -github.com/golang/protobuf/proto -github.com/spf13/viper/internal/encoding/toml github.com/spf13/viper/internal/encoding/yaml +github.com/golang/protobuf/proto golang.org/x/net/context -github.com/spf13/viper golang.org/x/net/internal/timeseries -golang.org/x/net/trace google.golang.org/grpc/backoff google.golang.org/grpc/internal/grpclog +google.golang.org/grpc/grpclog google.golang.org/grpc/attributes google.golang.org/grpc/metadata -google.golang.org/grpc/grpclog google.golang.org/grpc/internal/grpcrand google.golang.org/grpc/codes -google.golang.org/grpc/encoding google.golang.org/grpc/connectivity -google.golang.org/grpc/internal/credentials google.golang.org/grpc/internal +google.golang.org/grpc/internal/credentials +google.golang.org/grpc/encoding google.golang.org/grpc/internal/backoff google.golang.org/grpc/internal/balancerload -google.golang.org/grpc/internal/buffer google.golang.org/grpc/internal/envconfig -google.golang.org/grpc/internal/grpcsync golang.org/x/text/unicode/bidi golang.org/x/net/http2/hpack google.golang.org/grpc/internal/syscall google.golang.org/grpc/keepalive google.golang.org/grpc/stats google.golang.org/grpc/tap -net/http/httputil +github.com/bugsnag/bugsnag-go/device golang.org/x/text/secure/bidirule +github.com/pkg/errors +golang.org/x/net/idna +github.com/bugsnag/bugsnag-go/errors +github.com/bugsnag/bugsnag-go/headers +os/exec github.com/golang/protobuf/ptypes/empty google.golang.org/grpc/credentials google.golang.org/grpc/encoding/proto github.com/golang/protobuf/ptypes/any -github.com/golang/protobuf/ptypes/duration +github.com/theupdateframework/notary/storage +github.com/magiconair/properties +github.com/spf13/afero +golang.org/x/net/trace +github.com/theupdateframework/notary/trustmanager google.golang.org/grpc/resolver -github.com/golang/protobuf/ptypes/timestamp -google.golang.org/genproto/googleapis/rpc/status google.golang.org/grpc/balancer -github.com/golang/protobuf/ptypes -google.golang.org/grpc/binarylog/grpc_binarylog_v1 +github.com/theupdateframework/notary/cryptoservice +github.com/theupdateframework/notary/tuf/signed google.golang.org/grpc/balancer/base -google.golang.org/grpc/internal/grpcutil +github.com/golang/protobuf/ptypes/duration google.golang.org/grpc/balancer/roundrobin +github.com/golang/protobuf/ptypes/timestamp +google.golang.org/grpc/internal/grpcutil +github.com/theupdateframework/notary/trustmanager/yubikey +github.com/theupdateframework/notary/trustpinning +github.com/golang/protobuf/ptypes +google.golang.org/grpc/binarylog/grpc_binarylog_v1 +github.com/theupdateframework/notary/tuf +github.com/spf13/viper +google.golang.org/genproto/googleapis/rpc/status google.golang.org/grpc/internal/status google.golang.org/grpc/internal/channelz -google.golang.org/grpc/balancer/grpclb/state -google.golang.org/grpc/internal/resolver/dns google.golang.org/grpc/status google.golang.org/grpc/internal/binarylog +github.com/theupdateframework/notary/client +google.golang.org/grpc/balancer/grpclb/state +google.golang.org/grpc/internal/resolver/dns google.golang.org/grpc/internal/resolver/passthrough google.golang.org/grpc/internal/serviceconfig -golang.org/x/net/idna +golang.org/x/net/http/httpguts google.golang.org/grpc/peer -github.com/bugsnag/bugsnag-go/device -github.com/pkg/errors -github.com/bugsnag/bugsnag-go/headers -os/exec -github.com/bugsnag/bugsnag-go/errors +net/http/httputil os/signal -database/sql/driver -github.com/bugsnag/panicwrap -golang.org/x/net/http/httpguts golang.org/x/net/http2 +github.com/bugsnag/panicwrap +database/sql/driver github.com/gorilla/mux github.com/gofrs/uuid -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/bugsnag/bugsnag-go/sessions github.com/docker/distribution/registry/auth -github.com/Shopify/logrus-bugsnag +github.com/docker/distribution/context github.com/docker/go-connections/tlsconfig +github.com/bugsnag/bugsnag-go database/sql github.com/opencontainers/go-digest github.com/docker/distribution/digestset github.com/docker/distribution/reference +github.com/Shopify/logrus-bugsnag github.com/opencontainers/image-spec/specs-go github.com/opencontainers/image-spec/specs-go/v1 -github.com/go-sql-driver/mysql +github.com/docker/distribution/registry/client/auth/challenge github.com/docker/distribution github.com/docker/distribution/registry/api/v2 -google.golang.org/grpc/internal/transport -github.com/docker/distribution/registry/client/auth/challenge +github.com/go-sql-driver/mysql github.com/docker/distribution/registry/client/transport expvar -github.com/theupdateframework/notary/utils github.com/beorn7/perks/quantile github.com/cespare/xxhash github.com/prometheus/client_model/go github.com/prometheus/common/model -google.golang.org/grpc +google.golang.org/grpc/internal/transport github.com/matttproud/golang_protobuf_extensions/pbutil github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg hash/fnv -github.com/prometheus/client_golang/prometheus/internal +github.com/theupdateframework/notary/utils google.golang.org/protobuf/internal/detrand +github.com/prometheus/client_golang/prometheus/internal google.golang.org/protobuf/internal/errors google.golang.org/protobuf/encoding/protowire -google.golang.org/protobuf/internal/pragma -go/token google.golang.org/protobuf/reflect/protoreflect +go/token google.golang.org/protobuf/internal/version github.com/prometheus/procfs/internal/fs github.com/prometheus/procfs/internal/util +runtime/debug +google.golang.org/grpc github.com/prometheus/procfs +github.com/spf13/cobra google.golang.org/protobuf/internal/descfmt -github.com/theupdateframework/notary/trustmanager/remoteks google.golang.org/protobuf/internal/descopts google.golang.org/protobuf/internal/strs google.golang.org/protobuf/internal/encoding/text -github.com/theupdateframework/notary/cmd/escrow -google.golang.org/protobuf/internal/encoding/defval google.golang.org/protobuf/internal/encoding/messageset +google.golang.org/protobuf/internal/encoding/defval google.golang.org/protobuf/internal/genid google.golang.org/protobuf/internal/order google.golang.org/protobuf/reflect/protoregistry google.golang.org/protobuf/runtime/protoiface -runtime/debug -google.golang.org/protobuf/proto -github.com/spf13/cobra -google.golang.org/protobuf/internal/filedesc -google.golang.org/protobuf/encoding/prototext golang.org/x/term -google.golang.org/protobuf/internal/encoding/tag -github.com/theupdateframework/notary/passphrase -google.golang.org/protobuf/internal/impl +github.com/theupdateframework/notary/trustmanager/remoteks github.com/docker/distribution/health +google.golang.org/protobuf/proto golang.org/x/crypto/blowfish +github.com/theupdateframework/notary/passphrase +github.com/theupdateframework/notary/cmd/escrow golang.org/x/crypto/bcrypt -github.com/docker/distribution/registry/auth/htpasswd encoding/base32 +github.com/docker/distribution/registry/auth/htpasswd github.com/docker/libtrust github.com/lib/pq/scram os/user +google.golang.org/protobuf/internal/filedesc +google.golang.org/protobuf/encoding/prototext github.com/docker/distribution/registry/auth/token +google.golang.org/protobuf/internal/encoding/tag +google.golang.org/protobuf/internal/impl github.com/theupdateframework/notary/server/errors github.com/jinzhu/inflection go/scanner go/ast github.com/jinzhu/gorm -google.golang.org/protobuf/internal/filetype -google.golang.org/protobuf/runtime/protoimpl -github.com/lib/pq -google.golang.org/protobuf/types/known/timestamppb -github.com/prometheus/common/expfmt -github.com/prometheus/client_golang/prometheus -github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus github.com/hailocab/go-hostpool github.com/opentracing/opentracing-go/log github.com/opentracing/opentracing-go github.com/opentracing/opentracing-go/ext gopkg.in/rethinkdb/rethinkdb-go.v6/vendor/gopkg.in/cenkalti/backoff.v2 gopkg.in/rethinkdb/rethinkdb-go.v6/encoding -github.com/prometheus/client_golang/prometheus/promhttp +google.golang.org/protobuf/internal/filetype gopkg.in/rethinkdb/rethinkdb-go.v6/ql2 +google.golang.org/protobuf/runtime/protoimpl +google.golang.org/protobuf/types/known/timestamppb gopkg.in/rethinkdb/rethinkdb-go.v6/types +github.com/prometheus/common/expfmt github.com/theupdateframework/notary/proto gopkg.in/rethinkdb/rethinkdb-go.v6 google.golang.org/grpc/health/grpc_health_v1 -github.com/docker/go-metrics +github.com/prometheus/client_golang/prometheus +github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus github.com/theupdateframework/notary/signer/client +internal/profile +github.com/prometheus/client_golang/prometheus/promhttp +github.com/theupdateframework/notary/storage/rethinkdb +github.com/theupdateframework/notary/server/storage +github.com/theupdateframework/notary/server/snapshot +github.com/docker/go-metrics +github.com/theupdateframework/notary/server/timestamp +github.com/theupdateframework/notary/server/handlers github.com/docker/distribution/metrics github.com/docker/distribution/registry/storage/cache -internal/profile github.com/docker/distribution/registry/storage/cache/memory github.com/docker/distribution/registry/client +github.com/theupdateframework/notary/server github.com/docker/distribution/registry/client/auth github.com/theupdateframework/notary/cmd/notary runtime/pprof -github.com/theupdateframework/notary/storage/rethinkdb -github.com/theupdateframework/notary/server/storage runtime/trace -net/http/pprof github.com/theupdateframework/notary/signer github.com/theupdateframework/notary/signer/api -github.com/theupdateframework/notary/server/snapshot github.com/dvsekhvalnov/jose2go/base64url github.com/dvsekhvalnov/jose2go/arrays -github.com/theupdateframework/notary/server/timestamp github.com/dvsekhvalnov/jose2go/aes -github.com/theupdateframework/notary/server/handlers github.com/dvsekhvalnov/jose2go/compact github.com/dvsekhvalnov/jose2go/kdf github.com/dvsekhvalnov/jose2go/keys/ecc github.com/dvsekhvalnov/jose2go/padding github.com/dvsekhvalnov/jose2go -github.com/theupdateframework/notary/server -github.com/theupdateframework/notary/cmd/notary-server -github.com/theupdateframework/notary/signer/keydbstore +net/http/pprof google.golang.org/grpc/health -github.com/theupdateframework/notary/cmd/notary-signer +github.com/theupdateframework/notary/signer/keydbstore github.com/theupdateframework/notary/tuf/testutils/keys github.com/theupdateframework/notary/tuf/testutils github.com/davecgh/go-spew/spew @@ -1867,341 +1900,344 @@ github.com/stretchr/testify/assert github.com/stretchr/testify/require github.com/theupdateframework/notary/tuf/testutils/interfaces +github.com/lib/pq +github.com/theupdateframework/notary/cmd/notary-server +github.com/theupdateframework/notary/cmd/notary-signer make[1]: Leaving directory '/build/reproducible-path/notary-0.7.0+ds1' dh_auto_test -O--builddirectory=/build/reproducible-path/notary-0.7.0\+ds1/_build -O--buildsystem=golang - cd _build && go test -vet=off -v -p 3 github.com/theupdateframework/notary github.com/theupdateframework/notary/client github.com/theupdateframework/notary/client/changelist github.com/theupdateframework/notary/cmd/escrow github.com/theupdateframework/notary/cmd/notary github.com/theupdateframework/notary/cmd/notary-server github.com/theupdateframework/notary/cmd/notary-signer github.com/theupdateframework/notary/cryptoservice github.com/theupdateframework/notary/passphrase github.com/theupdateframework/notary/proto github.com/theupdateframework/notary/server github.com/theupdateframework/notary/server/errors github.com/theupdateframework/notary/server/handlers github.com/theupdateframework/notary/server/snapshot github.com/theupdateframework/notary/server/storage github.com/theupdateframework/notary/server/timestamp github.com/theupdateframework/notary/signer github.com/theupdateframework/notary/signer/api github.com/theupdateframework/notary/signer/client github.com/theupdateframework/notary/signer/keydbstore github.com/theupdateframework/notary/storage github.com/theupdateframework/notary/storage/rethinkdb github.com/theupdateframework/notary/trustmanager github.com/theupdateframework/notary/trustmanager/remoteks github.com/theupdateframework/notary/trustmanager/yubikey github.com/theupdateframework/notary/trustpinning github.com/theupdateframework/notary/tuf github.com/theupdateframework/notary/tuf/data github.com/theupdateframework/notary/tuf/signed github.com/theupdateframework/notary/tuf/testutils github.com/theupdateframework/notary/tuf/testutils/interfaces github.com/theupdateframework/notary/tuf/testutils/keys github.com/theupdateframework/notary/tuf/utils github.com/theupdateframework/notary/tuf/validation github.com/theupdateframework/notary/utils github.com/theupdateframework/notary/version + cd _build && go test -vet=off -v -p 4 github.com/theupdateframework/notary github.com/theupdateframework/notary/client github.com/theupdateframework/notary/client/changelist github.com/theupdateframework/notary/cmd/escrow github.com/theupdateframework/notary/cmd/notary github.com/theupdateframework/notary/cmd/notary-server github.com/theupdateframework/notary/cmd/notary-signer github.com/theupdateframework/notary/cryptoservice github.com/theupdateframework/notary/passphrase github.com/theupdateframework/notary/proto github.com/theupdateframework/notary/server github.com/theupdateframework/notary/server/errors github.com/theupdateframework/notary/server/handlers github.com/theupdateframework/notary/server/snapshot github.com/theupdateframework/notary/server/storage github.com/theupdateframework/notary/server/timestamp github.com/theupdateframework/notary/signer github.com/theupdateframework/notary/signer/api github.com/theupdateframework/notary/signer/client github.com/theupdateframework/notary/signer/keydbstore github.com/theupdateframework/notary/storage github.com/theupdateframework/notary/storage/rethinkdb github.com/theupdateframework/notary/trustmanager github.com/theupdateframework/notary/trustmanager/remoteks github.com/theupdateframework/notary/trustmanager/yubikey github.com/theupdateframework/notary/trustpinning github.com/theupdateframework/notary/tuf github.com/theupdateframework/notary/tuf/data github.com/theupdateframework/notary/tuf/signed github.com/theupdateframework/notary/tuf/testutils github.com/theupdateframework/notary/tuf/testutils/interfaces github.com/theupdateframework/notary/tuf/testutils/keys github.com/theupdateframework/notary/tuf/utils github.com/theupdateframework/notary/tuf/validation github.com/theupdateframework/notary/utils github.com/theupdateframework/notary/version ? github.com/theupdateframework/notary [no test files] === RUN Test0Dot1Migration ---- PASS: Test0Dot1Migration (0.21s) +--- PASS: Test0Dot1Migration (0.16s) === RUN Test0Dot3Migration ---- PASS: Test0Dot3Migration (0.16s) +--- PASS: Test0Dot3Migration (0.20s) === RUN Test0Dot1RepoFormat -time="2024-01-06T14: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.24s) +time="2024-01-06T14:12:17Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot1RepoFormat (1.81s) === RUN Test0Dot3RepoFormat -time="2024-01-06T14:00:54Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot3RepoFormat (1.30s) +time="2024-01-06T14:12:19Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot3RepoFormat (2.45s) === RUN TestDownloading0Dot1RepoFormat ---- PASS: TestDownloading0Dot1RepoFormat (0.03s) +--- PASS: TestDownloading0Dot1RepoFormat (0.12s) === RUN TestDownloading0Dot3RepoFormat ---- PASS: TestDownloading0Dot3RepoFormat (0.06s) +--- PASS: TestDownloading0Dot3RepoFormat (0.13s) === RUN TestInitRepositoryManagedRolesIncludingRoot ---- PASS: TestInitRepositoryManagedRolesIncludingRoot (0.02s) +--- PASS: TestInitRepositoryManagedRolesIncludingRoot (0.03s) === RUN TestInitRepositoryManagedRolesInvalidRole ---- PASS: TestInitRepositoryManagedRolesInvalidRole (0.01s) +--- PASS: TestInitRepositoryManagedRolesInvalidRole (0.03s) === RUN TestInitRepositoryManagedRolesIncludingTargets ---- PASS: TestInitRepositoryManagedRolesIncludingTargets (0.02s) +--- PASS: TestInitRepositoryManagedRolesIncludingTargets (0.05s) === RUN TestInitRepositoryManagedRolesIncludingTimestamp ---- PASS: TestInitRepositoryManagedRolesIncludingTimestamp (0.20s) +--- PASS: TestInitRepositoryManagedRolesIncludingTimestamp (0.26s) === RUN TestInitRepositoryWithCerts ---- PASS: TestInitRepositoryWithCerts (0.11s) +--- PASS: TestInitRepositoryWithCerts (0.36s) === RUN TestMatchKeyIDsWithPublicKeys ---- PASS: TestMatchKeyIDsWithPublicKeys (0.05s) +--- PASS: TestMatchKeyIDsWithPublicKeys (0.09s) === RUN TestInitRepositoryNeedsRemoteTimestampKey ---- PASS: TestInitRepositoryNeedsRemoteTimestampKey (0.06s) +--- PASS: TestInitRepositoryNeedsRemoteTimestampKey (0.08s) === RUN TestInitRepositoryNeedsRemoteSnapshotKey ---- PASS: TestInitRepositoryNeedsRemoteSnapshotKey (0.04s) +--- PASS: TestInitRepositoryNeedsRemoteSnapshotKey (0.05s) === RUN TestInitRepoServerOnlyManagesTimestampKey ---- PASS: TestInitRepoServerOnlyManagesTimestampKey (0.48s) +--- PASS: TestInitRepoServerOnlyManagesTimestampKey (1.01s) === RUN TestInitRepoServerManagesTimestampAndSnapshotKeys ---- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.39s) +--- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.66s) === RUN TestInitRepoAttemptsExceeded ---- PASS: TestInitRepoAttemptsExceeded (0.22s) +--- PASS: TestInitRepoAttemptsExceeded (0.25s) === RUN TestInitRepoPasswordInvalid --- PASS: TestInitRepoPasswordInvalid (0.04s) === RUN TestAddTargetToTargetRoleByDefault ---- PASS: TestAddTargetToTargetRoleByDefault (0.17s) +--- PASS: TestAddTargetToTargetRoleByDefault (0.26s) === RUN TestAddTargetToSpecifiedValidRoles ---- PASS: TestAddTargetToSpecifiedValidRoles (0.16s) +--- PASS: TestAddTargetToSpecifiedValidRoles (0.21s) === RUN TestAddTargetToSpecifiedInvalidRoles ---- PASS: TestAddTargetToSpecifiedInvalidRoles (0.19s) +--- PASS: TestAddTargetToSpecifiedInvalidRoles (0.39s) === RUN TestAddTargetWithInvalidTarget ---- PASS: TestAddTargetWithInvalidTarget (0.12s) +--- PASS: TestAddTargetWithInvalidTarget (0.11s) === RUN TestAddTargetErrorWritingChanges ---- PASS: TestAddTargetErrorWritingChanges (0.08s) +--- PASS: TestAddTargetErrorWritingChanges (0.15s) === RUN TestRemoveTargetToTargetRoleByDefault ---- PASS: TestRemoveTargetToTargetRoleByDefault (0.18s) +--- PASS: TestRemoveTargetToTargetRoleByDefault (0.49s) === RUN TestRemoveTargetFromSpecifiedValidRoles ---- PASS: TestRemoveTargetFromSpecifiedValidRoles (0.18s) +--- PASS: TestRemoveTargetFromSpecifiedValidRoles (0.44s) === RUN TestRemoveTargetToSpecifiedInvalidRoles ---- PASS: TestRemoveTargetToSpecifiedInvalidRoles (0.17s) +--- PASS: TestRemoveTargetToSpecifiedInvalidRoles (0.40s) === RUN TestRemoveTargetErrorWritingChanges ---- PASS: TestRemoveTargetErrorWritingChanges (0.08s) +--- PASS: TestRemoveTargetErrorWritingChanges (0.21s) === RUN TestListTarget ---- PASS: TestListTarget (2.27s) +--- PASS: TestListTarget (4.48s) === RUN TestListTargetRestrictsDelegationPaths ---- PASS: TestListTargetRestrictsDelegationPaths (0.37s) +--- PASS: TestListTargetRestrictsDelegationPaths (0.80s) === RUN TestValidateRootKey ---- PASS: TestValidateRootKey (0.20s) +--- PASS: TestValidateRootKey (0.46s) === RUN TestGetChangelist ---- PASS: TestGetChangelist (0.20s) +--- PASS: TestGetChangelist (0.46s) === RUN TestPublishBareRepo ---- PASS: TestPublishBareRepo (1.83s) +--- PASS: TestPublishBareRepo (4.61s) === RUN TestPublishUninitializedRepo -time="2024-01-06T14:01:03Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" ---- PASS: TestPublishUninitializedRepo (0.15s) +time="2024-01-06T14:12:38Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +--- PASS: TestPublishUninitializedRepo (0.26s) === RUN TestPublishClientHasSnapshotKey ---- PASS: TestPublishClientHasSnapshotKey (1.82s) +--- PASS: TestPublishClientHasSnapshotKey (3.72s) === RUN TestPublishAfterInitServerHasSnapshotKey ---- PASS: TestPublishAfterInitServerHasSnapshotKey (1.66s) +--- PASS: TestPublishAfterInitServerHasSnapshotKey (3.66s) === RUN TestPublishAfterPullServerHasSnapshotKey ---- PASS: TestPublishAfterPullServerHasSnapshotKey (0.60s) +--- PASS: TestPublishAfterPullServerHasSnapshotKey (1.30s) === RUN TestPublishNoOneHasSnapshotKey ---- PASS: TestPublishNoOneHasSnapshotKey (0.35s) +--- PASS: TestPublishNoOneHasSnapshotKey (1.06s) === RUN TestPublishSnapshotCorrupt ---- PASS: TestPublishSnapshotCorrupt (1.01s) +--- PASS: TestPublishSnapshotCorrupt (2.74s) === RUN TestPublishTargetsCorrupt ---- PASS: TestPublishTargetsCorrupt (0.51s) +--- PASS: TestPublishTargetsCorrupt (1.19s) === RUN TestPublishRootCorrupt -time="2024-01-06T14:01:09Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" -time="2024-01-06T14:01:09Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" ---- PASS: TestPublishRootCorrupt (0.36s) +time="2024-01-06T14:12:52Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +time="2024-01-06T14:12:53Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +--- PASS: TestPublishRootCorrupt (1.07s) === RUN TestPublishSnapshotLocalKeysCreatedFirst ---- PASS: TestPublishSnapshotLocalKeysCreatedFirst (0.02s) +--- PASS: TestPublishSnapshotLocalKeysCreatedFirst (0.07s) === RUN TestPublishDelegations ---- PASS: TestPublishDelegations (0.70s) +--- PASS: TestPublishDelegations (1.93s) === RUN TestPublishDelegationsX509 ---- PASS: TestPublishDelegationsX509 (0.76s) +--- PASS: TestPublishDelegationsX509 (1.12s) === RUN TestPublishTargetsDelegationScopeFailIfNoKeys -time="2024-01-06T14:01:11Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" -time="2024-01-06T14:01:12Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" ---- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (0.94s) +time="2024-01-06T14:12:57Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" +time="2024-01-06T14:12:57Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" +--- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (1.52s) === RUN TestPublishTargetsDelegationSuccessLocallyHasRoles ---- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (0.64s) +--- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (1.75s) === RUN TestPublishTargetsDelegationNoTargetsKeyNeeded ---- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (0.79s) +--- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (2.45s) === RUN TestPublishTargetsDelegationSuccessNeedsToDownloadRoles ---- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (0.78s) +--- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (1.95s) === RUN TestPublishTargetsDelegationFromTwoRepos ---- PASS: TestPublishTargetsDelegationFromTwoRepos (0.81s) +--- PASS: TestPublishTargetsDelegationFromTwoRepos (1.71s) === RUN TestPublishRemoveDelegationKeyFromDelegationRole -time="2024-01-06T14:01:15Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-06T14:01:15Z" level=error msg="couldn't add target to targets/a: could not find necessary signing keys, at least one of these keys must be available: bac4dbfdd6b0dbbdd50df9255187e18ee808a43ce149be1448d997fe449b7048" ---- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.53s) +time="2024-01-06T14:13:07Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T14:13:07Z" level=error msg="couldn't add target to targets/a: could not find necessary signing keys, at least one of these keys must be available: 6a21f2540878847d91d112158dc24ce82f8d67d5666a9be0cc3ec446cccd2b95" +--- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (1.46s) === RUN TestPublishRemoveDelegation -time="2024-01-06T14:01:16Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2024-01-06T14:01:16Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2024-01-06T14:01:16Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-06T14:01:16Z" level=error msg="couldn't add target to targets/a: could not find necessary signing keys, at least one of these keys must be available: " ---- PASS: TestPublishRemoveDelegation (0.43s) +time="2024-01-06T14:13:07Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-01-06T14:13:07Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-01-06T14:13:08Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T14:13:08Z" 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.71s) === RUN TestPublishSucceedsDespiteDelegationCorrupt ---- PASS: TestPublishSucceedsDespiteDelegationCorrupt (0.53s) +--- PASS: TestPublishSucceedsDespiteDelegationCorrupt (1.02s) === RUN TestRotateKeyInvalidRole ---- PASS: TestRotateKeyInvalidRole (0.24s) +--- PASS: TestRotateKeyInvalidRole (0.71s) === RUN TestRemoteRotationError ---- PASS: TestRemoteRotationError (0.06s) +--- PASS: TestRemoteRotationError (0.14s) === RUN TestRemoteRotationEndpointError ---- PASS: TestRemoteRotationEndpointError (0.17s) +--- PASS: TestRemoteRotationEndpointError (0.38s) === RUN TestRemoteRotationNoRootKey ---- PASS: TestRemoteRotationNoRootKey (0.26s) +--- PASS: TestRemoteRotationNoRootKey (0.61s) === RUN TestRemoteRotationNoInit -time="2024-01-06T14:01:17Z" 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-01-06T14:13:11Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +--- PASS: TestRemoteRotationNoInit (0.69s) === RUN TestRotateBeforePublishFromRemoteKeyToLocalKey ---- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (0.84s) +--- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (2.29s) === RUN TestRotateKeyAfterPublishNoServerManagementChange ---- PASS: TestRotateKeyAfterPublishNoServerManagementChange (2.66s) +--- PASS: TestRotateKeyAfterPublishNoServerManagementChange (8.92s) === RUN TestRotateKeyAfterPublishServerManagementChange ---- PASS: TestRotateKeyAfterPublishServerManagementChange (2.70s) +--- PASS: TestRotateKeyAfterPublishServerManagementChange (9.73s) === RUN TestRotateRootKey ---- PASS: TestRotateRootKey (0.82s) +--- PASS: TestRotateRootKey (3.40s) === RUN TestRotateRootMultiple ---- PASS: TestRotateRootMultiple (1.19s) +--- PASS: TestRotateRootMultiple (4.24s) === RUN TestRotateRootKeyProvided ---- PASS: TestRotateRootKeyProvided (0.89s) +--- PASS: TestRotateRootKeyProvided (3.49s) === RUN TestRotateRootKeyLegacySupport ---- PASS: TestRotateRootKeyLegacySupport (1.21s) +--- PASS: TestRotateRootKeyLegacySupport (2.71s) === RUN TestRemoteServerUnavailableNoLocalCache -time="2024-01-06T14:01:28Z" level=error msg="Could not publish Repository since we could not update: unable to reach trust server at this time: 500." ---- PASS: TestRemoteServerUnavailableNoLocalCache (0.01s) +time="2024-01-06T14:13:46Z" level=error msg="Could not publish Repository since we could not update: unable to reach trust server at this time: 500." +--- PASS: TestRemoteServerUnavailableNoLocalCache (0.00s) === RUN TestAddDelegationChangefileValid ---- PASS: TestAddDelegationChangefileValid (0.08s) +--- PASS: TestAddDelegationChangefileValid (0.18s) === RUN TestAddDelegationChangefileApplicable ---- PASS: TestAddDelegationChangefileApplicable (0.08s) +--- PASS: TestAddDelegationChangefileApplicable (0.18s) === RUN TestAddDelegationErrorWritingChanges ---- PASS: TestAddDelegationErrorWritingChanges (0.09s) +--- PASS: TestAddDelegationErrorWritingChanges (0.28s) === RUN TestRemoveDelegationChangefileValid ---- PASS: TestRemoveDelegationChangefileValid (0.08s) +--- PASS: TestRemoveDelegationChangefileValid (0.12s) === RUN TestRemoveDelegationChangefileApplicable -time="2024-01-06T14:01:28Z" 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-01-06T14:01:28Z" 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.08s) +time="2024-01-06T14:13:47Z" 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-01-06T14:13:47Z" 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.11s) === RUN TestClearAllPathsDelegationChangefileApplicable ---- PASS: TestClearAllPathsDelegationChangefileApplicable (0.09s) +--- PASS: TestClearAllPathsDelegationChangefileApplicable (0.10s) === RUN TestFullAddDelegationChangefileApplicable ---- PASS: TestFullAddDelegationChangefileApplicable (0.10s) +--- PASS: TestFullAddDelegationChangefileApplicable (0.09s) === RUN TestFullRemoveDelegationChangefileApplicable ---- PASS: TestFullRemoveDelegationChangefileApplicable (0.10s) +--- PASS: TestFullRemoveDelegationChangefileApplicable (0.13s) === RUN TestRemoveDelegationErrorWritingChanges ---- PASS: TestRemoveDelegationErrorWritingChanges (0.08s) +--- PASS: TestRemoveDelegationErrorWritingChanges (0.14s) === RUN TestClientInvalidURL --- PASS: TestClientInvalidURL (0.00s) === RUN TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole ---- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (1.50s) +--- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (12.14s) === RUN TestDeleteRepo ---- PASS: TestDeleteRepo (0.16s) +--- PASS: TestDeleteRepo (0.26s) === RUN TestDeleteRemoteRepo ---- PASS: TestDeleteRemoteRepo (0.48s) +--- PASS: TestDeleteRemoteRepo (0.72s) === RUN TestListRoles ---- PASS: TestListRoles (1.03s) +--- PASS: TestListRoles (1.44s) === RUN TestGetAllTargetInfo ---- PASS: TestGetAllTargetInfo (0.65s) +--- PASS: TestGetAllTargetInfo (0.69s) === RUN TestUpdateSucceedsEvenIfCannotWriteNewRepo -time="2024-01-06T14:01:33Z" level=error msg="could not save root to cache: Non-writable" ---- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.54s) +time="2024-01-06T14:14:03Z" level=error msg="could not save root to cache: Non-writable" +--- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.75s) === RUN TestUpdateSucceedsEvenIfCannotWriteExistingRepo ---- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (2.57s) +--- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (5.08s) === RUN TestUpdateInOfflineMode -time="2024-01-06T14:01:35Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" -time="2024-01-06T14:01:35Z" 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-01-06T14:14:09Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +time="2024-01-06T14:14:09Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateInOfflineMode (1.04s) === RUN TestUpdateReplacesCorruptOrMissingMetadata -time="2024-01-06T14:01:39Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:01:39Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateReplacesCorruptOrMissingMetadata (4.64s) +time="2024-01-06T14:14:19Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:14:19Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateReplacesCorruptOrMissingMetadata (16.91s) === RUN TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign -time="2024-01-06T14:01:40Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:01:40Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.40s) +time="2024-01-06T14:14:27Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:14:28Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (1.25s) === RUN TestUpdateRemoteRootNotExistNoLocalCache ---- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.20s) +--- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.80s) === RUN TestUpdateRemoteRootNotExistCanUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.47s) +--- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (1.34s) === RUN TestUpdateRemoteRootNotExistCannotUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.52s) +--- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (1.06s) === RUN TestUpdateRemoteRoot50XNoLocalCache ---- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.22s) +--- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.45s) === RUN TestUpdateRemoteRoot50XCanUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.39s) +--- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (1.03s) === RUN TestUpdateRemoteRoot50XCannotUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.48s) +--- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.90s) === RUN TestUpdateNonRootRemoteMissingMetadataNoLocalCache ---- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (1.02s) +--- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (1.89s) === RUN TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache -time="2024-01-06T14:01:44Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (1.35s) +time="2024-01-06T14:14:36Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (2.09s) === RUN TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache -time="2024-01-06T14:01:46Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (1.86s) +time="2024-01-06T14:14:39Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (3.74s) === RUN TestUpdateNonRootRemote50XNoLocalCache ---- PASS: TestUpdateNonRootRemote50XNoLocalCache (1.11s) +--- PASS: TestUpdateNonRootRemote50XNoLocalCache (2.06s) === RUN TestUpdateNonRootRemote50XCanUseLocalCache -time="2024-01-06T14:01:49Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (1.38s) +time="2024-01-06T14:14:44Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (3.05s) === RUN TestUpdateNonRootRemote50XCannotUseLocalCache -time="2024-01-06T14:01:50Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (1.71s) +time="2024-01-06T14:14:47Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (1.80s) === RUN TestUpdateRemoteChecksumWrongNoLocalCache ---- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (1.33s) +--- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (1.36s) === RUN TestUpdateRemoteChecksumWrongCanUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (1.87s) +--- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (1.77s) === RUN TestUpdateRemoteChecksumWrongCannotUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (2.56s) +--- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (2.15s) === RUN TestUpdateRootRemoteCorruptedNoLocalCache -time="2024-01-06T14:01:59Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:01:59Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (4.52s) +time="2024-01-06T14:14:56Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:14:56Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (7.01s) === RUN TestUpdateRootRemoteCorruptedCanUseLocalCache ---- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (8.55s) +--- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (18.08s) === RUN TestUpdateRootRemoteCorruptedCannotUseLocalCache -time="2024-01-06T14:02:14Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:02:15Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (8.25s) +time="2024-01-06T14:15:28Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:15:28Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (18.88s) === RUN TestUpdateNonRootRemoteCorruptedNoLocalCache -time="2024-01-06T14:02:20Z" level=error msg="Metadata for targets expired" -time="2024-01-06T14:02:20Z" level=error msg="Metadata for targets expired" -time="2024-01-06T14:02:21Z" level=error msg="Metadata for snapshot expired" -time="2024-01-06T14:02:21Z" level=error msg="Metadata for snapshot expired" -time="2024-01-06T14:02:22Z" level=error msg="Metadata for timestamp expired" -time="2024-01-06T14:02:22Z" level=error msg="Metadata for timestamp expired" -time="2024-01-06T14:02:23Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-06T14:02:24Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-06T14:02:24Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-06T14:02:24Z" level=error msg="Metadata for targets/a expired" -time="2024-01-06T14:02:24Z" level=warning msg="Error getting targets/a: targets/a expired at Mon Dec 5 14:02:24 UTC 2022" -time="2024-01-06T14:02:25Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-01-06T14:02:25Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-01-06T14:02:25Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-01-06T14:02:25Z" level=error msg="Metadata for targets/a/b expired" -time="2024-01-06T14:02:25Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Mon Dec 5 14:02:25 UTC 2022" -time="2024-01-06T14:02:27Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-01-06T14:02:27Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-01-06T14:02:27Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-01-06T14:02:27Z" level=error msg="Metadata for targets/b expired" -time="2024-01-06T14:02:27Z" level=warning msg="Error getting targets/b: targets/b expired at Mon Dec 5 14:02:27 UTC 2022" ---- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (9.59s) +time="2024-01-06T14:15:40Z" level=error msg="Metadata for targets expired" +time="2024-01-06T14:15:40Z" level=error msg="Metadata for targets expired" +time="2024-01-06T14:15:42Z" level=error msg="Metadata for snapshot expired" +time="2024-01-06T14:15:42Z" level=error msg="Metadata for snapshot expired" +time="2024-01-06T14:15:43Z" level=error msg="Metadata for timestamp expired" +time="2024-01-06T14:15:43Z" level=error msg="Metadata for timestamp expired" +time="2024-01-06T14:15:44Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T14:15:44Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T14:15:44Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T14:15:45Z" level=error msg="Metadata for targets/a expired" +time="2024-01-06T14:15:45Z" level=warning msg="Error getting targets/a: targets/a expired at Mon Dec 5 14:15:44 UTC 2022" +time="2024-01-06T14:15:46Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-01-06T14:15:46Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-01-06T14:15:46Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-01-06T14:15:46Z" level=error msg="Metadata for targets/a/b expired" +time="2024-01-06T14:15:46Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Mon Dec 5 14:15:46 UTC 2022" +time="2024-01-06T14:15:47Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-01-06T14:15:47Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-01-06T14:15:48Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-01-06T14:15:48Z" level=error msg="Metadata for targets/b expired" +time="2024-01-06T14:15:48Z" level=warning msg="Error getting targets/b: targets/b expired at Mon Dec 5 14:15:48 UTC 2022" +--- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (11.45s) === RUN TestUpdateNonRootRemoteCorruptedCanUseLocalCache -time="2024-01-06T14:02:34Z" level=error msg="Metadata for timestamp expired" -time="2024-01-06T14:02:34Z" level=error msg="Metadata for timestamp expired" ---- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (12.99s) +time="2024-01-06T14:15:54Z" level=error msg="Metadata for timestamp expired" +time="2024-01-06T14:15:54Z" level=error msg="Metadata for timestamp expired" +--- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (12.28s) === RUN TestUpdateNonRootRemoteCorruptedCannotUseLocalCache -time="2024-01-06T14:02:43Z" level=error msg="Metadata for targets expired" -time="2024-01-06T14:02:45Z" level=error msg="Metadata for snapshot expired" -time="2024-01-06T14:02:45Z" level=error msg="Metadata for snapshot expired" -time="2024-01-06T14:02:47Z" level=error msg="Metadata for timestamp expired" -time="2024-01-06T14:02:47Z" level=error msg="Metadata for timestamp expired" -time="2024-01-06T14:02:49Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-06T14:02:49Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-06T14:02:49Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-06T14:02:50Z" level=error msg="Metadata for targets/a expired" -time="2024-01-06T14:02:50Z" level=warning msg="Error getting targets/a: targets/a expired at Mon Dec 5 14:02:50 UTC 2022" -time="2024-01-06T14:02:51Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-01-06T14:02:52Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-01-06T14:02:52Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-01-06T14:02:52Z" level=error msg="Metadata for targets/a/b expired" -time="2024-01-06T14:02:52Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Mon Dec 5 14:02:52 UTC 2022" -time="2024-01-06T14:02:54Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-01-06T14:02:54Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-01-06T14:02:55Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-01-06T14:02:55Z" level=error msg="Metadata for targets/b expired" -time="2024-01-06T14:02:55Z" level=warning msg="Error getting targets/b: targets/b expired at Mon Dec 5 14:02:55 UTC 2022" ---- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (15.17s) +time="2024-01-06T14:16:03Z" level=error msg="Metadata for targets expired" +time="2024-01-06T14:16:05Z" level=error msg="Metadata for snapshot expired" +time="2024-01-06T14:16:05Z" level=error msg="Metadata for snapshot expired" +time="2024-01-06T14:16:07Z" level=error msg="Metadata for timestamp expired" +time="2024-01-06T14:16:07Z" level=error msg="Metadata for timestamp expired" +time="2024-01-06T14:16:09Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T14:16:09Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T14:16:09Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T14:16:10Z" level=error msg="Metadata for targets/a expired" +time="2024-01-06T14:16:10Z" level=warning msg="Error getting targets/a: targets/a expired at Mon Dec 5 14:16:09 UTC 2022" +time="2024-01-06T14:16:11Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-01-06T14:16:12Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-01-06T14:16:12Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-01-06T14:16:12Z" level=error msg="Metadata for targets/a/b expired" +time="2024-01-06T14:16:12Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Mon Dec 5 14:16:12 UTC 2022" +time="2024-01-06T14:16:14Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-01-06T14:16:14Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-01-06T14:16:14Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-01-06T14:16:15Z" level=error msg="Metadata for targets/b expired" +time="2024-01-06T14:16:15Z" level=warning msg="Error getting targets/b: targets/b expired at Mon Dec 5 14:16:15 UTC 2022" +--- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (15.07s) === RUN TestUpdateLocalAndRemoteRootCorrupt -time="2024-01-06T14:03:03Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:03Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:10Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:10Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:10Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:11Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:11Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:11Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:11Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:11Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:12Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:12Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:12Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:12Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:12Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:13Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:13Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:13Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:13Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:13Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:14Z" level=error msg="Metadata for root expired" -time="2024-01-06T14:03:14Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateLocalAndRemoteRootCorrupt (17.59s) +time="2024-01-06T14:16:22Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:22Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:29Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:29Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:30Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:30Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:30Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:30Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:30Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:31Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:31Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:31Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:31Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:31Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:32Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:32Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:32Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:32Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:32Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:32Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:33Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:16:33Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateLocalAndRemoteRootCorrupt (16.78s) === RUN TestUpdateRemoteKeyRotated -time="2024-01-06T14:03:15Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-06T14:03:15Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-01-06T14:03:15Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" ---- PASS: TestUpdateRemoteKeyRotated (1.80s) +time="2024-01-06T14:16:34Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T14:16:34Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-01-06T14:16:34Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +--- PASS: TestUpdateRemoteKeyRotated (1.59s) === RUN TestValidateRootRotationWithOldRole ---- PASS: TestValidateRootRotationWithOldRole (0.77s) +--- PASS: TestValidateRootRotationWithOldRole (0.72s) === RUN TestRootRoleInvariant ---- PASS: TestRootRoleInvariant (0.66s) +--- PASS: TestRootRoleInvariant (0.64s) === RUN TestBadIntermediateTransitions ---- PASS: TestBadIntermediateTransitions (0.27s) +--- PASS: TestBadIntermediateTransitions (0.26s) === RUN TestExpiredIntermediateTransitions -time="2024-01-06T14:03:17Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" +time="2024-01-06T14:16:36Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" --- PASS: TestExpiredIntermediateTransitions (0.32s) === RUN TestDownloadTargetsLarge ---- PASS: TestDownloadTargetsLarge (10.67s) +--- PASS: TestDownloadTargetsLarge (9.49s) === RUN TestDownloadTargetsDeep ---- PASS: TestDownloadTargetsDeep (0.26s) +--- PASS: TestDownloadTargetsDeep (0.22s) === RUN TestDownloadSnapshotLargeDelegationsMany ---- PASS: TestDownloadSnapshotLargeDelegationsMany (9.45s) +--- PASS: TestDownloadSnapshotLargeDelegationsMany (9.35s) === RUN TestRootOnDiskTrustPinning ---- PASS: TestRootOnDiskTrustPinning (0.17s) +--- PASS: TestRootOnDiskTrustPinning (0.15s) === RUN TestLoadTUFRepoBadURL --- PASS: TestLoadTUFRepoBadURL (0.00s) === RUN TestApplyTargetsChange @@ -2215,13 +2251,13 @@ === RUN TestApplyTargetsDelegationCreateDelete --- PASS: TestApplyTargetsDelegationCreateDelete (0.02s) === RUN TestApplyTargetsDelegationCreate2SharedKey ---- PASS: TestApplyTargetsDelegationCreate2SharedKey (0.03s) +--- PASS: TestApplyTargetsDelegationCreate2SharedKey (0.02s) === RUN TestApplyTargetsDelegationCreateEdit --- PASS: TestApplyTargetsDelegationCreateEdit (0.02s) === RUN TestApplyTargetsDelegationEditNonExisting --- PASS: TestApplyTargetsDelegationEditNonExisting (0.02s) === RUN TestApplyTargetsDelegationCreateAlreadyExisting ---- PASS: TestApplyTargetsDelegationCreateAlreadyExisting (0.03s) +--- PASS: TestApplyTargetsDelegationCreateAlreadyExisting (0.02s) === RUN TestApplyTargetsDelegationAlreadyExistingMergePaths --- PASS: TestApplyTargetsDelegationAlreadyExistingMergePaths (0.02s) === RUN TestApplyTargetsDelegationInvalidRole @@ -2241,14 +2277,14 @@ === RUN TestApplyChangelistTargetsToMultipleRoles --- PASS: TestApplyChangelistTargetsToMultipleRoles (0.02s) === RUN TestApplyChangelistTargetsFailsNonexistentRole -time="2024-01-06T14:03:38Z" 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-01-06T14:03:38Z" 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-01-06T14:16:56Z" 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-01-06T14:16:56Z" 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-01-06T14:03:38Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" +time="2024-01-06T14:16:56Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" --- PASS: TestChangeTargetMetaFailsInvalidRole (0.02s) === RUN TestChangeTargetMetaFailsIfPrefixError -time="2024-01-06T14:03:38Z" level=error msg="couldn't add target to targets/level1: Could not add all targets" +time="2024-01-06T14:16:56Z" level=error msg="couldn't add target to targets/level1: Could not add all targets" --- PASS: TestChangeTargetMetaFailsIfPrefixError (0.02s) === RUN TestAllNearExpiry --- PASS: TestAllNearExpiry (0.02s) @@ -2257,9 +2293,9 @@ === RUN TestRotateRemoteKeyOffline --- PASS: TestRotateRemoteKeyOffline (0.00s) PASS -ok github.com/theupdateframework/notary/client 166.612s +ok github.com/theupdateframework/notary/client 279.359s === RUN TestTUFDelegation ---- PASS: TestTUFDelegation (0.07s) +--- PASS: TestTUFDelegation (0.05s) === RUN TestMemChangelist --- PASS: TestMemChangelist (0.00s) === RUN TestMemChangeIterator @@ -2269,14 +2305,14 @@ === RUN TestAdd --- PASS: TestAdd (0.00s) === RUN TestErrorConditions -time="2024-01-06T14:00:44Z" level=warning msg="invalid character '\\x05' looking for beginning of value" +time="2024-01-06T14:12:02Z" level=warning msg="invalid character '\\x05' looking for beginning of value" --- PASS: TestErrorConditions (0.00s) === RUN TestListOrder --- PASS: TestListOrder (0.00s) === RUN TestFileChangeIterator --- PASS: TestFileChangeIterator (0.00s) PASS -ok github.com/theupdateframework/notary/client/changelist 0.118s +ok github.com/theupdateframework/notary/client/changelist 0.100s === RUN TestParseConfigError --- PASS: TestParseConfigError (0.00s) === RUN TestSetupGRPCServer @@ -2286,7 +2322,7 @@ === RUN TestSetupNetListener --- PASS: TestSetupNetListener (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/escrow 0.021s +ok github.com/theupdateframework/notary/cmd/escrow 0.035s === RUN TestPurgeDelegationKeys Usage: delegation [command] @@ -2312,9 +2348,9 @@ - abc --- PASS: TestPurgeDelegationKeys (0.01s) === RUN TestAddInvalidDelegationName ---- PASS: TestAddInvalidDelegationName (0.04s) +--- PASS: TestAddInvalidDelegationName (0.03s) === RUN TestAddInvalidDelegationCert ---- PASS: TestAddInvalidDelegationCert (0.03s) +--- PASS: TestAddInvalidDelegationCert (0.01s) === RUN TestAddInvalidShortPubkeyCert --- PASS: TestAddInvalidShortPubkeyCert (0.01s) === RUN TestRemoveInvalidDelegationName @@ -2358,162 +2394,162 @@ Use "delegation [command] --help" for more information about a command. --- PASS: TestRemoveInvalidNumArgs (0.00s) === RUN TestInitWithRootKey ---- PASS: TestInitWithRootKey (0.41s) +--- PASS: TestInitWithRootKey (0.79s) === RUN TestInitWithRootCert ---- PASS: TestInitWithRootCert (0.33s) +--- PASS: TestInitWithRootCert (0.75s) === RUN TestClientTUFInteraction ---- PASS: TestClientTUFInteraction (0.90s) +--- PASS: TestClientTUFInteraction (1.58s) === RUN TestClientDeleteTUFInteraction -time="2024-01-06T14:01:00Z" 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]:47633->[::1]:53: read: connection refused" ---- PASS: TestClientDeleteTUFInteraction (0.88s) +time="2024-01-06T14:12:23Z" 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]:37202->[::1]:53: read: connection refused" +--- PASS: TestClientDeleteTUFInteraction (2.00s) === RUN TestClientTUFAddByHashInteraction ---- PASS: TestClientTUFAddByHashInteraction (1.46s) +--- PASS: TestClientTUFAddByHashInteraction (2.73s) === RUN TestClientDelegationsInteraction -time="2024-01-06T14:01:02Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2024-01-06T14:01:02Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" ---- PASS: TestClientDelegationsInteraction (3.42s) +time="2024-01-06T14:12:29Z" 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-01-06T14:12:29Z" 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 (7.65s) === RUN TestClientDelegationsPublishing ---- PASS: TestClientDelegationsPublishing (3.57s) +--- PASS: TestClientDelegationsPublishing (7.94s) === RUN TestClientKeyGenerationRotation ---- PASS: TestClientKeyGenerationRotation (4.36s) +--- PASS: TestClientKeyGenerationRotation (8.15s) === RUN TestKeyRotation ---- PASS: TestKeyRotation (0.66s) +--- PASS: TestKeyRotation (1.54s) === RUN TestKeyRotationNonRoot ---- PASS: TestKeyRotationNonRoot (0.79s) +--- PASS: TestKeyRotationNonRoot (2.27s) === RUN TestDefaultRootKeyGeneration ---- PASS: TestDefaultRootKeyGeneration (0.02s) +--- PASS: TestDefaultRootKeyGeneration (0.04s) === RUN TestLogLevelFlags --- PASS: TestLogLevelFlags (0.00s) === RUN TestClientKeyPassphraseChange ---- PASS: TestClientKeyPassphraseChange (0.67s) +--- PASS: TestClientKeyPassphraseChange (1.14s) === RUN TestPurgeSingleKey Removal of the following keys from all delegations in gun staged for next publish: - - 2824573f1079cc5a66a2e69041ea7062b5ef6baba0da04adce507a8cfe43d478 ---- PASS: TestPurgeSingleKey (0.72s) + - 9cfcb282eeb3e5bce9dedc048f5c3711638d13e182ae51bb88643b0302d013b9 +--- PASS: TestPurgeSingleKey (1.66s) === RUN TestWitness -time="2024-01-06T14:01:16Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-01-06T14:01:16Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-01-06T14:01:17Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-01-06T14:01:17Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-01-06T14:01:17Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2024-01-06T14:01:17Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2024-01-06T14:01:17Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" ---- PASS: TestWitness (1.93s) +time="2024-01-06T14:12:59Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-01-06T14:13:00Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-01-06T14:13:02Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-01-06T14:13:02Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-01-06T14:13:02Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-01-06T14:13:02Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-01-06T14:13:03Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +--- PASS: TestWitness (6.00s) === RUN TestClientTUFInitWithAutoPublish -time="2024-01-06T14:01: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]:39936->[::1]:53: read: connection refused" ---- PASS: TestClientTUFInitWithAutoPublish (0.47s) +time="2024-01-06T14:13:03Z" 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]:35984->[::1]:53: read: connection refused" +--- PASS: TestClientTUFInitWithAutoPublish (1.24s) === RUN TestClientTUFAddWithAutoPublish -time="2024-01-06T14:01:18Z" 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]:52040->[::1]:53: read: connection refused" -time="2024-01-06T14:01:18Z" level=error msg="Could not publish Repository since we could not update: client is offline" -time="2024-01-06T14:01:18Z" level=error msg="could not reach https://notary-server-on-the-moon:12306: Get \"https://notary-server-on-the-moon:12306/v2/\": dial tcp: lookup notary-server-on-the-moon on [::1]:53: read udp [::1]:60639->[::1]:53: read: connection refused" -time="2024-01-06T14:01:18Z" level=error msg="Could not publish Repository since we could not update: client is offline" ---- PASS: TestClientTUFAddWithAutoPublish (0.52s) +time="2024-01-06T14:13:05Z" 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]:40477->[::1]:53: read: connection refused" +time="2024-01-06T14:13:05Z" level=error msg="Could not publish Repository since we could not update: client is offline" +time="2024-01-06T14:13:05Z" 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]:52687->[::1]:53: read: connection refused" +time="2024-01-06T14:13:05Z" level=error msg="Could not publish Repository since we could not update: client is offline" +--- PASS: TestClientTUFAddWithAutoPublish (1.79s) === RUN TestClientTUFRemoveWithAutoPublish ---- PASS: TestClientTUFRemoveWithAutoPublish (1.29s) +--- PASS: TestClientTUFRemoveWithAutoPublish (2.37s) === RUN TestClientDelegationAddWithAutoPublish ---- PASS: TestClientDelegationAddWithAutoPublish (0.50s) +--- PASS: TestClientDelegationAddWithAutoPublish (1.44s) === RUN TestClientDelegationRemoveWithAutoPublish -time="2024-01-06T14:01: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-01-06T14:01: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.06s) +time="2024-01-06T14:13: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-01-06T14:13: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 (2.74s) === RUN TestClientTUFAddByHashWithAutoPublish ---- PASS: TestClientTUFAddByHashWithAutoPublish (0.48s) +--- PASS: TestClientTUFAddByHashWithAutoPublish (1.38s) === RUN TestClientKeyImport -time="2024-01-06T14:01: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-01-06T14:01: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.17s) +time="2024-01-06T14:13:14Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" +time="2024-01-06T14:13:14Z" 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.42s) === RUN TestAddDelImportKeyPublishFlow -time="2024-01-06T14:01: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.16s) +time="2024-01-06T14:13:17Z" level=error msg="couldn't add target to targets/releases: could not find necessary signing keys, at least one of these keys must be available: 0570f9fdca00bdaf62f3f79bf84e70748ba2c8bff926f20dd996bac49e6164fc" +--- PASS: TestAddDelImportKeyPublishFlow (4.29s) === RUN TestExportImportFlow ---- PASS: TestExportImportFlow (0.34s) +--- PASS: TestExportImportFlow (0.94s) === RUN TestDelegationKeyImportExport ---- PASS: TestDelegationKeyImportExport (0.02s) +--- PASS: TestDelegationKeyImportExport (0.03s) === RUN TestImportKeysNoYubikey ---- PASS: TestImportKeysNoYubikey (0.03s) +--- PASS: TestImportKeysNoYubikey (0.06s) === RUN TestExportImportKeysNoYubikey ---- PASS: TestExportImportKeysNoYubikey (0.03s) +--- PASS: TestExportImportKeysNoYubikey (0.06s) === RUN TestRemoveIfNoKey --- PASS: TestRemoveIfNoKey (0.00s) === RUN TestRemoveOneKeyAbort ---- PASS: TestRemoveOneKeyAbort (0.04s) +--- PASS: TestRemoveOneKeyAbort (0.23s) === RUN TestRemoveOneKeyConfirm ---- PASS: TestRemoveOneKeyConfirm (0.02s) +--- PASS: TestRemoveOneKeyConfirm (0.08s) === RUN TestRemoveMultikeysInvalidInput ---- PASS: TestRemoveMultikeysInvalidInput (0.02s) +--- PASS: TestRemoveMultikeysInvalidInput (0.06s) === RUN TestRemoveMultikeysAbortChoice ---- PASS: TestRemoveMultikeysAbortChoice (0.02s) +--- PASS: TestRemoveMultikeysAbortChoice (0.06s) === RUN TestRemoveMultikeysRemoveOnlyChosenKey ---- PASS: TestRemoveMultikeysRemoveOnlyChosenKey (0.02s) +--- PASS: TestRemoveMultikeysRemoveOnlyChosenKey (0.09s) === RUN TestRotateKeyInvalidRoles -time="2024-01-06T14:01: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]:52066->[::1]:53: read: connection refused" -time="2024-01-06T14:01: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]:49194->[::1]:53: read: connection refused" -time="2024-01-06T14:01: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]:59408->[::1]:53: read: connection refused" -time="2024-01-06T14:01: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]:41858->[::1]:53: read: connection refused" ---- PASS: TestRotateKeyInvalidRoles (0.02s) +time="2024-01-06T14:13:20Z" 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]:43616->[::1]:53: read: connection refused" +time="2024-01-06T14:13:20Z" 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]:46051->[::1]:53: read: connection refused" +time="2024-01-06T14:13:20Z" 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]:35909->[::1]:53: read: connection refused" +time="2024-01-06T14:13:20Z" 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]:58923->[::1]:53: read: connection refused" +--- PASS: TestRotateKeyInvalidRoles (0.03s) === RUN TestRotateKeyTargetCannotBeServerManaged -time="2024-01-06T14:01: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]:37726->[::1]:53: read: connection refused" +time="2024-01-06T14:13:20Z" 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]:34722->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTargetCannotBeServerManaged (0.00s) === RUN TestRotateKeyTimestampCannotBeLocallyManaged -time="2024-01-06T14:01: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]:40599->[::1]:53: read: connection refused" +time="2024-01-06T14:13:20Z" 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]:59259->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTimestampCannotBeLocallyManaged (0.00s) === RUN TestRotateKeyNoGUN Usage: ---- PASS: TestRotateKeyNoGUN (0.00s) +--- PASS: TestRotateKeyNoGUN (0.01s) === RUN TestRotateKeyRemoteServerManagesKey Successfully rotated snapshot key for repository docker.com/notary Successfully rotated timestamp key for repository docker.com/notary ---- PASS: TestRotateKeyRemoteServerManagesKey (0.49s) +--- PASS: TestRotateKeyRemoteServerManagesKey (1.77s) === RUN TestRotateKeyBothKeys Successfully rotated targets key for repository docker.com/notary Successfully rotated snapshot key for repository docker.com/notary ---- PASS: TestRotateKeyBothKeys (0.46s) +--- PASS: TestRotateKeyBothKeys (2.62s) === RUN TestRotateKeyRootIsInteractive ---- PASS: TestRotateKeyRootIsInteractive (0.10s) +--- PASS: TestRotateKeyRootIsInteractive (0.58s) === RUN TestChangeKeyPassphraseInvalidID --- PASS: TestChangeKeyPassphraseInvalidID (0.00s) === RUN TestChangeKeyPassphraseInvalidNumArgs Usage: ---- PASS: TestChangeKeyPassphraseInvalidNumArgs (0.00s) +--- PASS: TestChangeKeyPassphraseInvalidNumArgs (0.01s) === RUN TestChangeKeyPassphraseNonexistentID --- PASS: TestChangeKeyPassphraseNonexistentID (0.00s) === RUN TestExportKeys ---- PASS: TestExportKeys (0.00s) +--- PASS: TestExportKeys (0.02s) === RUN TestExportKeysByGUN ---- PASS: TestExportKeysByGUN (0.01s) +--- PASS: TestExportKeysByGUN (0.06s) === RUN TestExportKeysByID ---- PASS: TestExportKeysByID (0.00s) +--- PASS: TestExportKeysByID (0.03s) === RUN TestExportKeysBadFlagCombo ---- PASS: TestExportKeysBadFlagCombo (0.00s) +--- PASS: TestExportKeysBadFlagCombo (0.02s) === RUN TestImportKeysNonexistentFile --- PASS: TestImportKeysNonexistentFile (0.00s) === RUN TestKeyGeneration -time="2024-01-06T14:01: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.05s) +time="2024-01-06T14:13: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.22s) === RUN TestNotaryConfigFileDefault --- PASS: TestNotaryConfigFileDefault (0.00s) === RUN TestRemoteServerDefault ---- PASS: TestRemoteServerDefault (0.00s) +--- PASS: TestRemoteServerDefault (0.03s) === RUN TestRemoteServerUsesConfigFile ---- PASS: TestRemoteServerUsesConfigFile (0.00s) +--- PASS: TestRemoteServerUsesConfigFile (0.01s) === RUN TestRemoteServerCommandLineFlagOverridesConfig ---- PASS: TestRemoteServerCommandLineFlagOverridesConfig (0.00s) +--- PASS: TestRemoteServerCommandLineFlagOverridesConfig (0.02s) === RUN TestInvalidAddHashCommands ---- PASS: TestInvalidAddHashCommands (0.01s) +--- PASS: TestInvalidAddHashCommands (0.06s) === RUN TestConfigParsingErrorsPropagatedByCommands ---- PASS: TestConfigParsingErrorsPropagatedByCommands (0.01s) +--- PASS: TestConfigParsingErrorsPropagatedByCommands (0.08s) === RUN TestInsufficientArgumentsReturnsErrorAndPrintsUsage ---- PASS: TestInsufficientArgumentsReturnsErrorAndPrintsUsage (0.05s) +--- PASS: TestInsufficientArgumentsReturnsErrorAndPrintsUsage (0.34s) === RUN TestBareCommandPrintsUsageAndNoError ---- PASS: TestBareCommandPrintsUsageAndNoError (0.01s) +--- PASS: TestBareCommandPrintsUsageAndNoError (0.04s) === RUN TestConfigFileTLSCannotBeRelativeToCWD ---- PASS: TestConfigFileTLSCannotBeRelativeToCWD (0.02s) +--- PASS: TestConfigFileTLSCannotBeRelativeToCWD (0.07s) === RUN TestConfigFileTLSCanBeRelativeToConfigOrAbsolute ---- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.19s) +--- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.96s) === RUN TestConfigFileOverridenByCmdLineFlags ---- PASS: TestConfigFileOverridenByCmdLineFlags (0.18s) +--- PASS: TestConfigFileOverridenByCmdLineFlags (1.14s) === RUN TestConfigFileTrustPinning ---- PASS: TestConfigFileTrustPinning (0.00s) +--- PASS: TestConfigFileTrustPinning (0.02s) === RUN TestPassphraseRetrieverCaching --- PASS: TestPassphraseRetrieverCaching (0.00s) === RUN TestPassphraseRetrieverDelegationRoleCaching @@ -2525,7 +2561,7 @@ === RUN TestPrettyPrintZeroKeys --- PASS: TestPrettyPrintZeroKeys (0.00s) === RUN TestPrettyPrintRootAndSigningKeys ---- PASS: TestPrettyPrintRootAndSigningKeys (0.07s) +--- PASS: TestPrettyPrintRootAndSigningKeys (0.06s) === RUN TestPrettyPrintZeroTargets --- PASS: TestPrettyPrintZeroTargets (0.00s) === RUN TestPrettyPrintSortedTargets @@ -2537,58 +2573,58 @@ === RUN TestImportRootCert --- PASS: TestImportRootCert (0.00s) === RUN TestTokenAuth -time="2024-01-06T14:01:25Z" 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-01-06T14:13:28Z" 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-01-06T14:01:25Z" 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-01-06T14:13:28Z" 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) +--- PASS: TestTokenAuth200Status (0.01s) === RUN TestAdminTokenAuth200Status --- PASS: TestAdminTokenAuth200Status (0.00s) === RUN TestTokenAuth401Status ---- PASS: TestTokenAuth401Status (0.00s) +--- PASS: TestTokenAuth401Status (0.01s) === RUN TestAdminTokenAuth401Status ---- PASS: TestAdminTokenAuth401Status (0.01s) +--- PASS: TestAdminTokenAuth401Status (0.00s) === RUN TestTokenAuthNon200Non401Status -time="2024-01-06T14:01:25Z" level=error msg="could not reach http://127.0.0.1:35341: 404" +time="2024-01-06T14:13:28Z" level=error msg="could not reach http://127.0.0.1:40269: 404" --- PASS: TestTokenAuthNon200Non401Status (0.00s) === RUN TestAdminTokenAuthNon200Non401Status -time="2024-01-06T14:01:25Z" level=error msg="could not reach http://127.0.0.1:46367: 404" ---- PASS: TestAdminTokenAuthNon200Non401Status (0.00s) +time="2024-01-06T14:13:28Z" level=error msg="could not reach http://127.0.0.1:34293: 404" +--- PASS: TestAdminTokenAuthNon200Non401Status (0.02s) === RUN TestConfigureRepo ---- PASS: TestConfigureRepo (0.00s) +--- PASS: TestConfigureRepo (0.03s) === RUN TestConfigureRepoRW -Enter username: time="2024-01-06T14:01:25Z" level=error msg="error processing username input: EOF" ---- PASS: TestConfigureRepoRW (0.00s) +Enter username: time="2024-01-06T14:13:28Z" level=error msg="error processing username input: EOF" +--- PASS: TestConfigureRepoRW (0.03s) === RUN TestConfigureRepoAdmin -Enter username: time="2024-01-06T14:01:25Z" level=error msg="error processing username input: EOF" ---- PASS: TestConfigureRepoAdmin (0.01s) +Enter username: time="2024-01-06T14:13:28Z" level=error msg="error processing username input: EOF" +--- PASS: TestConfigureRepoAdmin (0.02s) === RUN TestStatusUnstageAndReset Successfully reset specified changes for repository gun Addition of target "test1" by sha256 hash to repository "gun" staged for next publish. Addition of target "test2" by sha256 hash to repository "gun" staged for next publish. Addition of target "test3" by sha256 hash to repository "gun" staged for next publish. Addition of target "test4" by sha256 hash to repository "gun" staged for next publish. ---- PASS: TestStatusUnstageAndReset (0.01s) +--- PASS: TestStatusUnstageAndReset (0.06s) === RUN TestGetTrustPinningErrors Pushing changes to gun --- PASS: TestGetTrustPinningErrors (0.00s) === RUN TestPasswordStore -Enter username: time="2024-01-06T14:01:25Z" level=error msg="error processing username input: EOF" +Enter username: time="2024-01-06T14:13:28Z" level=error msg="error processing username input: EOF" --- PASS: TestPasswordStore (0.00s) === RUN TestPasswordStoreWithEnvvar -time="2024-01-06T14:01:25Z" level=error msg="Authentication string with zero-length username" -time="2024-01-06T14:01:25Z" level=error msg="Could not base64-decode authentication string" +time="2024-01-06T14:13:28Z" level=error msg="Authentication string with zero-length username" +time="2024-01-06T14:13:28Z" level=error msg="Could not base64-decode authentication string" --- PASS: TestPasswordStoreWithEnvvar (0.00s) === RUN TestGetPayload --- PASS: TestGetPayload (0.00s) === RUN TestFeedback ---- PASS: TestFeedback (0.00s) +--- PASS: TestFeedback (0.02s) === RUN TestHomeExpand --- PASS: TestHomeExpand (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary 28.176s +ok github.com/theupdateframework/notary/cmd/notary 70.178s === RUN TestBootstrap --- PASS: TestBootstrap (0.00s) === RUN TestGetAddrAndTLSConfigInvalidTLS @@ -2600,46 +2636,46 @@ === RUN TestGetAddrAndTLSConfigSuccessWithoutTLS --- PASS: TestGetAddrAndTLSConfigSuccessWithoutTLS (0.00s) === RUN TestGetAddrAndTLSConfigWithClientTLS ---- PASS: TestGetAddrAndTLSConfigWithClientTLS (0.00s) +--- PASS: TestGetAddrAndTLSConfigWithClientTLS (0.01s) === RUN TestGetInvalidTrustService --- PASS: TestGetInvalidTrustService (0.00s) === RUN TestGetLocalTrustService -time="2024-01-06T14:01:24Z" level=info msg="Using local signing service, which requires ED25519. Ignoring all other trust_service parameters, including keyAlgorithm" +time="2024-01-06T14:12:48Z" 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-01-06T14:01:24Z" level=info msg="Using remote signing service" +time="2024-01-06T14:12:48Z" level=info msg="Using remote signing service" --- PASS: TestGetTrustServiceNoTLSConfig (0.00s) === RUN TestGetTrustServiceTLSSuccess -time="2024-01-06T14:01:24Z" level=info msg="Using remote signing service" +time="2024-01-06T14:12:48Z" level=info msg="Using remote signing service" --- PASS: TestGetTrustServiceTLSSuccess (0.00s) === RUN TestGetTrustServiceTLSFailure --- PASS: TestGetTrustServiceTLSFailure (0.00s) === RUN TestGetStoreInvalid -time="2024-01-06T14:01:24Z" level=info msg="Using asdf backend" ---- PASS: TestGetStoreInvalid (0.00s) +time="2024-01-06T14:12:48Z" level=info msg="Using asdf backend" +--- PASS: TestGetStoreInvalid (0.01s) === RUN TestGetStoreDBStore -time="2024-01-06T14:01:24Z" level=info msg="Using sqlite3 backend" +time="2024-01-06T14:12:48Z" level=info msg="Using sqlite3 backend" --- PASS: TestGetStoreDBStore (0.00s) === RUN TestGetStoreRethinkDBStoreConnectionFails -time="2024-01-06T14:01:24Z" level=info msg="Using rethinkdb backend" +time="2024-01-06T14:12:48Z" level=info msg="Using rethinkdb backend" --- PASS: TestGetStoreRethinkDBStoreConnectionFails (0.00s) === RUN TestGetMemoryStore -time="2024-01-06T14:01:24Z" level=info msg="Using memory backend" +time="2024-01-06T14:12:48Z" 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-01-06T14:01:24Z" level=info msg="Using remote signing service" -time="2024-01-06T14:01:24Z" level=info msg="Using sqlite3 backend" ---- PASS: TestSampleConfig (0.00s) +time="2024-01-06T14:12:48Z" level=info msg="Using remote signing service" +time="2024-01-06T14:12:48Z" level=info msg="Using sqlite3 backend" +--- PASS: TestSampleConfig (0.01s) PASS -ok github.com/theupdateframework/notary/cmd/notary-server 0.054s +ok github.com/theupdateframework/notary/cmd/notary-server 0.096s === RUN TestGetAddrAndTLSConfigInvalidTLS --- PASS: TestGetAddrAndTLSConfigInvalidTLS (0.00s) === RUN TestGetAddrAndTLSConfigNoGRPCAddr @@ -2654,9 +2690,9 @@ main_test.go:121: DM - skipping malfunctioning test, fails on sbuild --- SKIP: TestSetupCryptoServicesRethinkDBStoreConnectionFails (0.00s) === RUN TestSetupCryptoServicesDBStoreSuccess ---- PASS: TestSetupCryptoServicesDBStoreSuccess (0.18s) +--- PASS: TestSetupCryptoServicesDBStoreSuccess (0.31s) === RUN TestSetupCryptoServicesMemoryStore ---- PASS: TestSetupCryptoServicesMemoryStore (0.01s) +--- PASS: TestSetupCryptoServicesMemoryStore (0.05s) === RUN TestSetupCryptoServicesInvalidStore --- PASS: TestSetupCryptoServicesInvalidStore (0.00s) === RUN TestSetupGRPCServerInvalidAddress @@ -2670,23 +2706,23 @@ === 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.240s +ok github.com/theupdateframework/notary/cmd/notary-signer 0.445s === RUN TestGenerateCertificate ---- PASS: TestGenerateCertificate (0.03s) +--- PASS: TestGenerateCertificate (0.05s) === RUN TestCryptoServiceWithNonEmptyGUN ---- PASS: TestCryptoServiceWithNonEmptyGUN (2.05s) +--- PASS: TestCryptoServiceWithNonEmptyGUN (6.23s) === RUN TestCryptoServiceWithEmptyGUN ---- PASS: TestCryptoServiceWithEmptyGUN (1.96s) +--- PASS: TestCryptoServiceWithEmptyGUN (4.28s) === RUN TestCryptoSignerInterfaceBehavior ---- PASS: TestCryptoSignerInterfaceBehavior (0.03s) +--- PASS: TestCryptoSignerInterfaceBehavior (0.14s) PASS -ok github.com/theupdateframework/notary/cryptoservice 4.090s +ok github.com/theupdateframework/notary/cryptoservice 10.745s === RUN TestGetPassphraseForUsingDelegationKey --- PASS: TestGetPassphraseForUsingDelegationKey (0.00s) === RUN TestGetPassphraseLimitsShortPassphrases ---- PASS: TestGetPassphraseLimitsShortPassphrases (0.00s) +--- PASS: TestGetPassphraseLimitsShortPassphrases (0.01s) === RUN TestGetPassphraseLimitsMismatchingPassphrases --- PASS: TestGetPassphraseLimitsMismatchingPassphrases (0.00s) === RUN TestGetPassphraseForCreatingDelegationKey @@ -2698,253 +2734,253 @@ === RUN TestGetPassphrase --- PASS: TestGetPassphrase (0.00s) PASS -ok github.com/theupdateframework/notary/passphrase 0.013s +ok github.com/theupdateframework/notary/passphrase 0.060s ? github.com/theupdateframework/notary/proto [no test files] === RUN TestValidationErrorFormat -time="2024-01-06T14:01:36Z" 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.19.8 http.request.contenttype="multipart/form-data; boundary=f05c6dfe2452f359b642c357b510adbb7340cb6b3de379f45381b642127e" http.request.host="127.0.0.1:40733" http.request.id=be81e19e-e5b4-4270-9fd2-2c3cff74e789 http.request.method=POST http.request.remoteaddr="127.0.0.1:37904" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype="multipart/form-data; boundary=f05c6dfe2452f359b642c357b510adbb7340cb6b3de379f45381b642127e" http.request.host="127.0.0.1:40733" http.request.id=be81e19e-e5b4-4270-9fd2-2c3cff74e789 http.request.method=POST http.request.remoteaddr="127.0.0.1:37904" 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=15.839824ms http.response.status=400 http.response.written=260 ---- PASS: TestValidationErrorFormat (0.10s) +time="2024-01-06T14:13:01Z" 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.19.8 http.request.contenttype="multipart/form-data; boundary=c41ccb6d019bf3d733ef8e2814122f485c56618e3a6ab7d3c6e0ba13202b" http.request.host="127.0.0.1:33893" http.request.id=76477d22-ec36-4456-9aff-31bc088591c8 http.request.method=POST http.request.remoteaddr="127.0.0.1:44998" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-01-06T14:13:01Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype="multipart/form-data; boundary=c41ccb6d019bf3d733ef8e2814122f485c56618e3a6ab7d3c6e0ba13202b" http.request.host="127.0.0.1:33893" http.request.id=76477d22-ec36-4456-9aff-31bc088591c8 http.request.method=POST http.request.remoteaddr="127.0.0.1:44998" 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=68.800061ms http.response.status=400 http.response.written=260 +--- PASS: TestValidationErrorFormat (0.38s) === RUN TestRunBadAddr --- PASS: TestRunBadAddr (0.00s) === RUN TestRunReservedPort --- PASS: TestRunReservedPort (0.00s) === RUN TestRepoPrefixMatches -time="2024-01-06T14:01:36Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum 89f5ec933c2105977b3cc7f4e57a0ee0216832c1162f8d46ba1fac5eb832feed" docker.io/notary=gun go.version=go1.19.8 http.request.contenttype="multipart/form-data; boundary=43337cdd671936b45919f68014af944e4bd2d8e7289c1312057e6db98cbf" http.request.host="127.0.0.1:45619" http.request.id=8b43ac51-c1ae-4b0c-ade8-85214aa771d3 http.request.method=POST http.request.remoteaddr="127.0.0.1:41534" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype="multipart/form-data; boundary=43337cdd671936b45919f68014af944e4bd2d8e7289c1312057e6db98cbf" http.request.host="127.0.0.1:45619" http.request.id=8b43ac51-c1ae-4b0c-ade8-85214aa771d3 http.request.method=POST http.request.remoteaddr="127.0.0.1:41534" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=27.840391ms http.response.status=0 http.response.written=0 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:45619" http.request.id=60f612d8-46cc-4ef5-91eb-25c32be3b1b8 http.request.method=GET http.request.remoteaddr="127.0.0.1:41534" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration=1.230764ms http.response.status=200 http.response.written=682 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:45619" http.request.id=62325c4f-e363-451a-82fb-173ad7da43a0 http.request.method=GET http.request.remoteaddr="127.0.0.1:41534" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.5e402336d32f991c57cc82977161ad227af42ec456fa9a1f9675b2c76b40e6b3.json http.request.useragent=Go-http-client/1.1 http.response.duration="104.25µs" http.response.status=200 http.response.written=682 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:45619" http.request.id=d0c6c2f4-2597-4b27-8a89-0ed1f1c10374 http.request.method=GET http.request.remoteaddr="127.0.0.1:41534" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration=2.486106ms http.response.status=200 http.response.written=181 -time="2024-01-06T14:01:36Z" level=info msg="trust data deleted for docker.io/notary" docker.io/notary=gun go.version=go1.19.8 http.request.host="127.0.0.1:45619" http.request.id=8bf69a7b-877a-4f52-b348-933839cd7406 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:41534" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:45619" http.request.id=8bf69a7b-877a-4f52-b348-933839cd7406 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:41534" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="329.749µs" http.response.status=0 http.response.written=0 ---- PASS: TestRepoPrefixMatches (0.11s) +time="2024-01-06T14:13:01Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum 7ac287c102e95850877f23e9bd265699955d88208ac96c69e8f6559eddc11bc7" docker.io/notary=gun go.version=go1.19.8 http.request.contenttype="multipart/form-data; boundary=75f2ca7fb8b02094c08fb8827341d1a74263920a15b453911a691cf5bb9d" http.request.host="127.0.0.1:34619" http.request.id=f5e8b791-06be-4596-b3df-2b048ae5f33a http.request.method=POST http.request.remoteaddr="127.0.0.1:53830" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-01-06T14:13:01Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype="multipart/form-data; boundary=75f2ca7fb8b02094c08fb8827341d1a74263920a15b453911a691cf5bb9d" http.request.host="127.0.0.1:34619" http.request.id=f5e8b791-06be-4596-b3df-2b048ae5f33a http.request.method=POST http.request.remoteaddr="127.0.0.1:53830" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=111.740372ms http.response.status=0 http.response.written=0 +time="2024-01-06T14:13:01Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:34619" http.request.id=a69e302d-d7ac-4062-8b83-289561e5972c http.request.method=GET http.request.remoteaddr="127.0.0.1:53830" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration=1.176009ms http.response.status=200 http.response.written=683 +time="2024-01-06T14:13:01Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:34619" http.request.id=82ea27d0-de85-45a9-a3b3-b8f562d58b67 http.request.method=GET http.request.remoteaddr="127.0.0.1:53830" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.3aef40b36744bbc5b89cae698d73e16d0468fcc1598681c71c5cc3c36e0f3695.json http.request.useragent=Go-http-client/1.1 http.response.duration="157.745µs" http.response.status=200 http.response.written=683 +time="2024-01-06T14:13:01Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:34619" http.request.id=90ed585d-c70c-49ec-bcc4-12040775380f http.request.method=GET http.request.remoteaddr="127.0.0.1:53830" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="933.131µs" http.response.status=200 http.response.written=181 +time="2024-01-06T14:13:01Z" level=info msg="trust data deleted for docker.io/notary" docker.io/notary=gun go.version=go1.19.8 http.request.host="127.0.0.1:34619" http.request.id=9e26215c-7956-4c7a-9c21-93626eb27035 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:53830" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-01-06T14:13:01Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:34619" http.request.id=9e26215c-7956-4c7a-9c21-93626eb27035 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:53830" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="138.127µs" http.response.status=0 http.response.written=0 +--- PASS: TestRepoPrefixMatches (0.37s) === RUN TestRepoPrefixDoesNotMatch ---- PASS: TestRepoPrefixDoesNotMatch (0.07s) +--- PASS: TestRepoPrefixDoesNotMatch (0.31s) === RUN TestMetricsEndpoint ---- PASS: TestMetricsEndpoint (0.02s) +--- PASS: TestMetricsEndpoint (0.09s) === RUN TestGetKeysEndpoint -time="2024-01-06T14:01:36Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.19.8 http.request.host="127.0.0.1:33267" http.request.id=8f80c869-9356-4010-b43e-6835359c727c http.request.method=GET http.request.remoteaddr="127.0.0.1:40916" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:33267" http.request.id=8f80c869-9356-4010-b43e-6835359c727c http.request.method=GET http.request.remoteaddr="127.0.0.1:40916" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="303.891µs" http.response.status=404 http.response.written=104 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:33267" http.request.id=979d72e1-a106-47dc-93ca-8317380eb416 http.request.method=GET http.request.remoteaddr="127.0.0.1:40918" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration=37.141733ms http.response.status=200 http.response.written=103 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:33267" http.request.id=b577897d-3ec9-4da3-87b6-eff63b24b553 http.request.method=GET http.request.remoteaddr="127.0.0.1:40932" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="780.566µs" http.response.status=200 http.response.written=103 -time="2024-01-06T14:01:36Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.19.8 http.request.host="127.0.0.1:33267" http.request.id=c6dbd5a3-3196-4656-8e73-8cd7f2f518c5 http.request.method=GET http.request.remoteaddr="127.0.0.1:40946" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:33267" http.request.id=c6dbd5a3-3196-4656-8e73-8cd7f2f518c5 http.request.method=GET http.request.remoteaddr="127.0.0.1:40946" 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="238.958µs" http.response.status=404 http.response.written=104 -time="2024-01-06T14:01:36Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.19.8 http.request.host="127.0.0.1:33267" http.request.id=d0ac3f8f-211a-47d4-8ce9-679d8287281c http.request.method=GET http.request.remoteaddr="127.0.0.1:40952" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:33267" http.request.id=d0ac3f8f-211a-47d4-8ce9-679d8287281c http.request.method=GET http.request.remoteaddr="127.0.0.1:40952" 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="195.002µs" http.response.status=404 http.response.written=104 ---- PASS: TestGetKeysEndpoint (0.06s) +time="2024-01-06T14:13:02Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.19.8 http.request.host="127.0.0.1:36697" http.request.id=2ebc5eee-61b3-4e75-bb4e-f1041430014f http.request.method=GET http.request.remoteaddr="127.0.0.1:58772" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2024-01-06T14:13:02Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:36697" http.request.id=2ebc5eee-61b3-4e75-bb4e-f1041430014f http.request.method=GET http.request.remoteaddr="127.0.0.1:58772" 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="304.211µs" http.response.status=404 http.response.written=104 +time="2024-01-06T14:13:02Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.19.8 http.request.host="127.0.0.1:36697" http.request.id=15a29664-bea4-4f38-8168-f8014271798b http.request.method=GET http.request.remoteaddr="127.0.0.1:58780" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2024-01-06T14:13:02Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:36697" http.request.id=15a29664-bea4-4f38-8168-f8014271798b http.request.method=GET http.request.remoteaddr="127.0.0.1:58780" 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="164.405µs" http.response.status=404 http.response.written=104 +time="2024-01-06T14:13:02Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.19.8 http.request.host="127.0.0.1:36697" http.request.id=db312e93-983d-4629-b8b2-3acbd2ecda45 http.request.method=GET http.request.remoteaddr="127.0.0.1:58786" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2024-01-06T14:13:02Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:36697" http.request.id=db312e93-983d-4629-b8b2-3acbd2ecda45 http.request.method=GET http.request.remoteaddr="127.0.0.1:58786" 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="159.285µs" http.response.status=404 http.response.written=104 +time="2024-01-06T14:13:02Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:36697" http.request.id=3dfb8ffd-19e0-4759-88b9-715bdecc2926 http.request.method=GET http.request.remoteaddr="127.0.0.1:58792" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration=92.036079ms http.response.status=200 http.response.written=103 +time="2024-01-06T14:13:02Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:36697" http.request.id=e90341ff-4bc2-4d32-95a4-237084c81ab1 http.request.method=GET http.request.remoteaddr="127.0.0.1:58800" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="800.164µs" http.response.status=200 http.response.written=103 +--- PASS: TestGetKeysEndpoint (0.12s) === RUN TestGetRoleByHash -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:42091" http.request.id=418b5db4-f32e-4cc2-8d6f-8614388ef63e http.request.method=GET http.request.remoteaddr="127.0.0.1:38802" http.request.uri=/v2/gun/_trust/tuf/timestamp.69767b7b6058a758730bc3488b8c0f0c3ee6a21bf965e1360eab842320b7632c.json http.request.useragent=Go-http-client/1.1 http.response.duration="158.445µs" http.response.status=200 http.response.written=115 ---- PASS: TestGetRoleByHash (0.01s) +time="2024-01-06T14:13:02Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:36003" http.request.id=b5cc1584-8301-41c8-8313-2daa8e89eff8 http.request.method=GET http.request.remoteaddr="127.0.0.1:47958" http.request.uri=/v2/gun/_trust/tuf/timestamp.ae286d10eb71c23676c8381f86b8335937c315998f7301b9dafa20b4b75e2623.json http.request.useragent=Go-http-client/1.1 http.response.duration="153.626µs" http.response.status=200 http.response.written=115 +--- PASS: TestGetRoleByHash (0.03s) === RUN TestGetRoleByVersion -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:44919" http.request.id=34358f58-4110-4c65-a38a-bf4f307ca261 http.request.method=GET http.request.remoteaddr="127.0.0.1:33356" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="208.321µs" http.response.status=200 http.response.written=115 ---- PASS: TestGetRoleByVersion (0.02s) +time="2024-01-06T14:13:02Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:38661" http.request.id=e0a53cf1-7b10-4ba6-9b21-b8ef25ffcc6c http.request.method=GET http.request.remoteaddr="127.0.0.1:42864" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="153.105µs" http.response.status=200 http.response.written=115 +--- PASS: TestGetRoleByVersion (0.05s) === RUN TestGetCurrentRole -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:42781" http.request.id=4d441239-8e09-44c2-a23b-768fdc0786d6 http.request.method=GET http.request.remoteaddr="127.0.0.1:60164" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration=1.276119ms http.response.status=200 http.response.written=495 ---- PASS: TestGetCurrentRole (0.07s) +time="2024-01-06T14:13:02Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:45401" http.request.id=0d996e72-41eb-4b45-a2dc-14132bf19f23 http.request.method=GET http.request.remoteaddr="127.0.0.1:45220" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration=1.16821ms http.response.status=200 http.response.written=494 +--- PASS: TestGetCurrentRole (0.20s) === RUN TestRotateKeyEndpoint -time="2024-01-06T14:01:36Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:42151" http.request.id=adbf0434-ab39-41dc-bb5d-79a07f0be2bd http.request.method=POST http.request.remoteaddr="127.0.0.1:43024" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:42151" http.request.id=adbf0434-ab39-41dc-bb5d-79a07f0be2bd http.request.method=POST http.request.remoteaddr="127.0.0.1:43024" 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="425.14µs" http.response.status=404 http.response.written=104 -time="2024-01-06T14:01:36Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:42151" http.request.id=05d49849-6df4-4b77-b496-9ab3458defb5 http.request.method=POST http.request.remoteaddr="127.0.0.1:43038" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:42151" http.request.id=05d49849-6df4-4b77-b496-9ab3458defb5 http.request.method=POST http.request.remoteaddr="127.0.0.1:43038" 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="388.744µs" http.response.status=404 http.response.written=104 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:42151" http.request.id=3327cf86-d8fd-4ecb-b94d-c3cfeab630b1 http.request.method=POST http.request.remoteaddr="127.0.0.1:43046" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="882.177µs" http.response.status=200 http.response.written=103 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:42151" http.request.id=5262c68e-a8dd-44a4-beb5-66c3d8263a2d http.request.method=POST http.request.remoteaddr="127.0.0.1:43058" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="798.524µs" http.response.status=200 http.response.written=103 -time="2024-01-06T14:01:36Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:42151" http.request.id=62f67f88-bbef-47b0-a71c-f2ca87b4fd14 http.request.method=POST http.request.remoteaddr="127.0.0.1:43068" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:42151" http.request.id=62f67f88-bbef-47b0-a71c-f2ca87b4fd14 http.request.method=POST http.request.remoteaddr="127.0.0.1:43068" 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="200.901µs" http.response.status=404 http.response.written=104 -time="2024-01-06T14:01:36Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:42151" http.request.id=1da0318d-80fb-4a4a-8de3-82fd9884006b http.request.method=POST http.request.remoteaddr="127.0.0.1:43070" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:42151" http.request.id=1da0318d-80fb-4a4a-8de3-82fd9884006b http.request.method=POST http.request.remoteaddr="127.0.0.1:43070" 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="167.685µs" http.response.status=404 http.response.written=104 ---- PASS: TestRotateKeyEndpoint (0.02s) +time="2024-01-06T14:13:02Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:33007" http.request.id=531289b1-3a8b-4952-99d1-bac957f0c988 http.request.method=POST http.request.remoteaddr="127.0.0.1:59952" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2024-01-06T14:13:02Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:33007" http.request.id=531289b1-3a8b-4952-99d1-bac957f0c988 http.request.method=POST http.request.remoteaddr="127.0.0.1:59952" 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="413.301µs" http.response.status=404 http.response.written=104 +time="2024-01-06T14:13:02Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:33007" http.request.id=2168d4fd-43dc-41b8-ae8b-563ed3607125 http.request.method=POST http.request.remoteaddr="127.0.0.1:59968" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 +time="2024-01-06T14:13:02Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:33007" http.request.id=2168d4fd-43dc-41b8-ae8b-563ed3607125 http.request.method=POST http.request.remoteaddr="127.0.0.1:59968" 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="361.685µs" http.response.status=404 http.response.written=104 +time="2024-01-06T14:13:02Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:33007" http.request.id=ec171f59-5c68-4860-aabf-b1102ccbc195 http.request.method=POST http.request.remoteaddr="127.0.0.1:59984" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2024-01-06T14:13:02Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:33007" http.request.id=ec171f59-5c68-4860-aabf-b1102ccbc195 http.request.method=POST http.request.remoteaddr="127.0.0.1:59984" 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="278.333µs" http.response.status=404 http.response.written=104 +time="2024-01-06T14:13:02Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:33007" http.request.id=48e0fdc5-77d8-4dae-9c0a-6edd1ec14e4e http.request.method=POST http.request.remoteaddr="127.0.0.1:59988" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration=8.947692ms http.response.status=200 http.response.written=103 +time="2024-01-06T14:13:02Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:33007" http.request.id=86bbbd87-5aeb-4d43-9514-0e468a9378f2 http.request.method=POST http.request.remoteaddr="127.0.0.1:59996" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="801.165µs" http.response.status=200 http.response.written=103 +time="2024-01-06T14:13:02Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:33007" http.request.id=5be90413-445e-48bb-a71d-6577d485a40c http.request.method=POST http.request.remoteaddr="127.0.0.1:60008" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2024-01-06T14:13:02Z" level=info msg="response completed" go.version=go1.19.8 http.request.contenttype=text/plain http.request.host="127.0.0.1:33007" http.request.id=5be90413-445e-48bb-a71d-6577d485a40c http.request.method=POST http.request.remoteaddr="127.0.0.1:60008" 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.28µs" http.response.status=404 http.response.written=104 +--- PASS: TestRotateKeyEndpoint (0.08s) PASS -ok github.com/theupdateframework/notary/server 0.524s +ok github.com/theupdateframework/notary/server 1.647s ? github.com/theupdateframework/notary/server/errors [no test files] === RUN Test_changefeed --- PASS: Test_changefeed (0.00s) === RUN Test_checkChangefeedInputs -time="2024-01-06T14:01:36Z" level=error msg="500 GET unable to retrieve storage" -time="2024-01-06T14:01:36Z" level=error msg="400 GET invalid pageSize: not_a_number" +time="2024-01-06T14:13:10Z" level=error msg="500 GET unable to retrieve storage" +time="2024-01-06T14:13:10Z" level=error msg="400 GET invalid pageSize: not_a_number" --- PASS: Test_checkChangefeedInputs (0.00s) === RUN TestMainHandlerGet -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:36725" http.request.id=5ce14fef-a00e-4ab9-a29d-5905134ce11f http.request.method=GET http.request.remoteaddr="127.0.0.1:40442" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="115.409µs" http.response.status=200 http.response.written=2 ---- PASS: TestMainHandlerGet (0.00s) +time="2024-01-06T14:13:10Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:41483" http.request.id=4d9ef298-8ccc-4a25-8920-aa3c294561f7 http.request.method=GET http.request.remoteaddr="127.0.0.1:57820" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="90.292µs" http.response.status=200 http.response.written=2 +--- PASS: TestMainHandlerGet (0.01s) === RUN TestMainHandlerNotGet -time="2024-01-06T14:01:36Z" level=info msg="generic not found: You have requested a resource that does not exist.: " go.version=go1.19.8 http.request.host="127.0.0.1:45605" http.request.id=413dc21a-5e1a-45dd-b8bc-d26207349281 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:33818" http.request.uri=/ http.request.useragent=Go-http-client/1.1 -time="2024-01-06T14:01:36Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:45605" http.request.id=413dc21a-5e1a-45dd-b8bc-d26207349281 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:33818" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="674.596µs" http.response.status=404 http.response.written=105 ---- PASS: TestMainHandlerNotGet (0.00s) +time="2024-01-06T14:13:10Z" level=info msg="generic not found: You have requested a resource that does not exist.: " go.version=go1.19.8 http.request.host="127.0.0.1:40711" http.request.id=cbe313a1-6583-44c7-83b9-494a7f1a2aed http.request.method=HEAD http.request.remoteaddr="127.0.0.1:54296" http.request.uri=/ http.request.useragent=Go-http-client/1.1 +time="2024-01-06T14:13:10Z" level=info msg="response completed" go.version=go1.19.8 http.request.host="127.0.0.1:40711" http.request.id=cbe313a1-6583-44c7-83b9-494a7f1a2aed http.request.method=HEAD http.request.remoteaddr="127.0.0.1:54296" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration=4.721353ms http.response.status=404 http.response.written=105 +--- PASS: TestMainHandlerNotGet (0.01s) === RUN TestKeyHandlersInvalidConfiguration -time="2024-01-06T14:01:36Z" level=error msg="500 GET storage not configured" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=error msg="500 GET storage not configured" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=error msg="500 GET crypto service not configured" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=error msg="500 GET crypto service not configured" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=error msg="500 GET key algorithm not configured" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=error msg="500 GET key algorithm not configured" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=error msg="500 POST crypto service not configured" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=error msg="500 POST crypto service not configured" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=error msg="500 POST key algorithm not configured" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=error msg="500 POST key algorithm not configured" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=error msg="500 POST storage not configured" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=error msg="500 POST storage not configured" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 GET storage not configured" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 GET storage not configured" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 GET crypto service not configured" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 GET crypto service not configured" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 GET key algorithm not configured" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 GET key algorithm not configured" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 POST storage not configured" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 POST storage not configured" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 POST crypto service not configured" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 POST crypto service not configured" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 POST key algorithm not configured" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 POST key algorithm not configured" go.version=go1.19.8 gun=gun --- PASS: TestKeyHandlersInvalidConfiguration (0.00s) === RUN TestKeyHandlersNoRoleOrRepo -time="2024-01-06T14:01:36Z" level=info msg="400 GET no gun in request" =gun go.version=go1.19.8 -time="2024-01-06T14:01:36Z" level=info msg="400 GET no gun in request" =gun go.version=go1.19.8 -time="2024-01-06T14:01:36Z" level=info msg="400 GET no role in request" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=info msg="400 GET no role in request" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=info msg="400 POST no gun in request" =gun go.version=go1.19.8 -time="2024-01-06T14:01:36Z" level=info msg="400 POST no gun in request" =gun go.version=go1.19.8 -time="2024-01-06T14:01:36Z" level=info msg="400 POST no role in request" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=info msg="400 POST no role in request" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=info msg="400 GET no gun in request" =gun go.version=go1.19.8 +time="2024-01-06T14:13:10Z" level=info msg="400 GET no gun in request" =gun go.version=go1.19.8 +time="2024-01-06T14:13:10Z" level=info msg="400 GET no role in request" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=info msg="400 GET no role in request" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=info msg="400 POST no gun in request" =gun go.version=go1.19.8 +time="2024-01-06T14:13:10Z" level=info msg="400 POST no gun in request" =gun go.version=go1.19.8 +time="2024-01-06T14:13:10Z" level=info msg="400 POST no role in request" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=info msg="400 POST no role in request" go.version=go1.19.8 gun=gun --- PASS: TestKeyHandlersNoRoleOrRepo (0.00s) === RUN TestKeyHandlersInvalidRole -time="2024-01-06T14:01:36Z" level=info msg="400 GET root key: " go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=info msg="400 GET targets key: " go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=info msg="400 GET targets/a key: " go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=info msg="400 GET invalidrole key: " go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=info msg="400 POST root key: " go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=info msg="400 POST targets key: " go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=info msg="400 POST targets/a key: " go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=info msg="400 POST invalidrole key: " go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=info msg="400 GET root key: " go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=info msg="400 GET targets key: " go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=info msg="400 GET targets/a key: " go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=info msg="400 GET invalidrole key: " go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=info msg="400 POST root key: " go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=info msg="400 POST targets key: " go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=info msg="400 POST targets/a key: " go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=info msg="400 POST invalidrole key: " go.version=go1.19.8 gun=gun --- PASS: TestKeyHandlersInvalidRole (0.00s) === RUN TestGetKeyHandlerCreatesOnce ---- PASS: TestGetKeyHandlerCreatesOnce (0.04s) +--- PASS: TestGetKeyHandlerCreatesOnce (0.08s) === RUN TestKeyHandlersInvalidKeyAlgo -time="2024-01-06T14:01:36Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.19.8 gun=gun -time="2024-01-06T14:01:36Z" level=error msg="500 POST snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=error msg="500 POST snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.19.8 gun=gun --- PASS: TestKeyHandlersInvalidKeyAlgo (0.00s) === RUN TestRotateKeyHandlerSuccessfulRotation --- PASS: TestRotateKeyHandlerSuccessfulRotation (0.00s) === RUN TestGetHandlerRoot -time="2024-01-06T14:01:36Z" level=info msg="404 GET root role" go.version=go1.19.8 gun=gun ---- PASS: TestGetHandlerRoot (0.05s) +time="2024-01-06T14:13:10Z" level=info msg="404 GET root role" go.version=go1.19.8 gun=gun +--- PASS: TestGetHandlerRoot (0.16s) === RUN TestGetHandlerTimestamp ---- PASS: TestGetHandlerTimestamp (0.04s) +--- PASS: TestGetHandlerTimestamp (0.11s) === RUN TestGetHandlerSnapshot ---- PASS: TestGetHandlerSnapshot (0.04s) +--- PASS: TestGetHandlerSnapshot (0.07s) === RUN TestGetHandler404 -time="2024-01-06T14:01:37Z" level=info msg="404 GET root role" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=info msg="404 GET root role" go.version=go1.19.8 gun=gun --- PASS: TestGetHandler404 (0.00s) === RUN TestGetHandlerNilData -time="2024-01-06T14:01:37Z" level=info msg="404 GET root role" go.version=go1.19.8 gun=gun +time="2024-01-06T14:13:10Z" level=info msg="404 GET root role" go.version=go1.19.8 gun=gun --- PASS: TestGetHandlerNilData (0.00s) === RUN TestGetHandlerNoStorage -time="2024-01-06T14:01:37Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.19.8 +time="2024-01-06T14:13:10Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.19.8 --- PASS: TestGetHandlerNoStorage (0.00s) === RUN TestAtomicUpdateValidationFailurePropagated ---- PASS: TestAtomicUpdateValidationFailurePropagated (0.08s) +--- PASS: TestAtomicUpdateValidationFailurePropagated (0.14s) === RUN TestAtomicUpdateNonValidationFailureNotPropagated -time="2024-01-06T14:01:37Z" level=info msg="400 POST error validating update" go.version=go1.19.8 testGUN=gun ---- PASS: TestAtomicUpdateNonValidationFailureNotPropagated (0.06s) +time="2024-01-06T14:13:11Z" level=info msg="400 POST error validating update" go.version=go1.19.8 testGUN=gun +--- PASS: TestAtomicUpdateNonValidationFailureNotPropagated (0.27s) === RUN TestAtomicUpdateVersionErrorPropagated -time="2024-01-06T14:01:37Z" level=info msg="400 POST old version error" go.version=go1.19.8 testGUN=gun ---- PASS: TestAtomicUpdateVersionErrorPropagated (0.09s) +time="2024-01-06T14:13:11Z" level=info msg="400 POST old version error" go.version=go1.19.8 testGUN=gun +--- PASS: TestAtomicUpdateVersionErrorPropagated (0.38s) === RUN TestGetMaybeServerSignedNoCrypto --- PASS: TestGetMaybeServerSignedNoCrypto (0.00s) === RUN TestGetMaybeServerSignedNoKey --- PASS: TestGetMaybeServerSignedNoKey (0.00s) === RUN TestValidateEmptyNew ---- PASS: TestValidateEmptyNew (0.09s) +--- PASS: TestValidateEmptyNew (0.19s) === RUN TestValidateRootCanContainOnlyx509KeysWithRightGun ---- PASS: TestValidateRootCanContainOnlyx509KeysWithRightGun (0.11s) +--- PASS: TestValidateRootCanContainOnlyx509KeysWithRightGun (0.18s) === RUN TestValidatePrevTimestamp ---- PASS: TestValidatePrevTimestamp (0.09s) +--- PASS: TestValidatePrevTimestamp (0.29s) === RUN TestValidatePreviousTimestampCorrupt -time="2024-01-06T14:01:37Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" ---- PASS: TestValidatePreviousTimestampCorrupt (0.09s) +time="2024-01-06T14:13:12Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" +--- PASS: TestValidatePreviousTimestampCorrupt (0.13s) === RUN TestValidateGetCurrentTimestampBroken ---- PASS: TestValidateGetCurrentTimestampBroken (0.09s) +--- PASS: TestValidateGetCurrentTimestampBroken (0.11s) === RUN TestValidateNoNewRoot ---- PASS: TestValidateNoNewRoot (0.09s) +--- PASS: TestValidateNoNewRoot (0.29s) === RUN TestValidateNoNewTargets ---- PASS: TestValidateNoNewTargets (0.09s) +--- PASS: TestValidateNoNewTargets (0.26s) === RUN TestValidateOnlySnapshot ---- PASS: TestValidateOnlySnapshot (0.08s) +--- PASS: TestValidateOnlySnapshot (0.15s) === RUN TestValidateOldRoot ---- PASS: TestValidateOldRoot (0.10s) +--- PASS: TestValidateOldRoot (0.24s) === RUN TestValidateOldRootCorrupt ---- PASS: TestValidateOldRootCorrupt (0.06s) +--- PASS: TestValidateOldRootCorrupt (0.09s) === RUN TestValidateOldRootCorruptRootRole ---- PASS: TestValidateOldRootCorruptRootRole (0.06s) +--- PASS: TestValidateOldRootCorruptRootRole (0.09s) === RUN TestValidateRootGetCurrentRootBroken ---- PASS: TestValidateRootGetCurrentRootBroken (0.06s) +--- PASS: TestValidateRootGetCurrentRootBroken (0.08s) === RUN TestValidateRootRotationWithOldSigs ---- PASS: TestValidateRootRotationWithOldSigs (0.29s) +--- PASS: TestValidateRootRotationWithOldSigs (0.85s) === RUN TestValidateRootRotationMultipleKeysThreshold1 ---- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.17s) +--- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.41s) === RUN TestRootRotationNotSignedWithOldKeysForOldRole ---- PASS: TestRootRotationNotSignedWithOldKeysForOldRole (0.24s) +--- PASS: TestRootRotationNotSignedWithOldKeysForOldRole (1.37s) === RUN TestRootRotationVersionIncrement ---- PASS: TestRootRotationVersionIncrement (0.15s) +--- PASS: TestRootRotationVersionIncrement (0.61s) === RUN TestValidateNoRoot ---- PASS: TestValidateNoRoot (0.06s) +--- PASS: TestValidateNoRoot (0.21s) === RUN TestValidateSnapshotMissingNoSnapshotKey ---- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.08s) +--- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.32s) === RUN TestValidateSnapshotGenerateNoPrev ---- PASS: TestValidateSnapshotGenerateNoPrev (0.10s) +--- PASS: TestValidateSnapshotGenerateNoPrev (0.61s) === RUN TestValidateSnapshotGenerateWithPrev ---- PASS: TestValidateSnapshotGenerateWithPrev (0.10s) +--- PASS: TestValidateSnapshotGenerateWithPrev (0.69s) === RUN TestValidateSnapshotGeneratePrevCorrupt -time="2024-01-06T14:01:39Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" ---- PASS: TestValidateSnapshotGeneratePrevCorrupt (0.09s) +time="2024-01-06T14:13:19Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" +--- PASS: TestValidateSnapshotGeneratePrevCorrupt (0.39s) === RUN TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken ---- PASS: TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken (0.10s) +--- PASS: TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken (0.53s) === RUN TestValidateSnapshotGenerateNoTargets ---- PASS: TestValidateSnapshotGenerateNoTargets (0.09s) +--- PASS: TestValidateSnapshotGenerateNoTargets (0.36s) === RUN TestValidateSnapshotGenerate ---- PASS: TestValidateSnapshotGenerate (0.10s) +--- PASS: TestValidateSnapshotGenerate (0.42s) === RUN TestValidateRootNoTimestampKey ---- PASS: TestValidateRootNoTimestampKey (0.08s) +--- PASS: TestValidateRootNoTimestampKey (0.20s) === RUN TestValidateRootInvalidTimestampKey ---- PASS: TestValidateRootInvalidTimestampKey (0.13s) +--- PASS: TestValidateRootInvalidTimestampKey (0.54s) === RUN TestValidateRootInvalidTimestampThreshold ---- PASS: TestValidateRootInvalidTimestampThreshold (0.15s) +--- PASS: TestValidateRootInvalidTimestampThreshold (0.50s) === RUN TestValidateRootInvalidZeroThreshold ---- PASS: TestValidateRootInvalidZeroThreshold (0.29s) +--- PASS: TestValidateRootInvalidZeroThreshold (1.29s) === RUN TestValidateRootRoleMissing ---- PASS: TestValidateRootRoleMissing (0.06s) +--- PASS: TestValidateRootRoleMissing (0.20s) === RUN TestValidateTargetsRoleMissing ---- PASS: TestValidateTargetsRoleMissing (0.06s) +--- PASS: TestValidateTargetsRoleMissing (0.45s) === RUN TestValidateSnapshotRoleMissing ---- PASS: TestValidateSnapshotRoleMissing (0.06s) +--- PASS: TestValidateSnapshotRoleMissing (0.41s) === RUN TestValidateRootSigMissing ---- PASS: TestValidateRootSigMissing (0.05s) +--- PASS: TestValidateRootSigMissing (0.31s) === RUN TestValidateTargetsSigMissing -time="2024-01-06T14:01:40Z" level=error msg="ErrBadTargets: tuf: data has no signatures" ---- PASS: TestValidateTargetsSigMissing (0.07s) +time="2024-01-06T14:13:25Z" level=error msg="ErrBadTargets: tuf: data has no signatures" +--- PASS: TestValidateTargetsSigMissing (0.52s) === RUN TestValidateSnapshotSigMissing ---- PASS: TestValidateSnapshotSigMissing (0.07s) +--- PASS: TestValidateSnapshotSigMissing (0.45s) === RUN TestValidateRootCorrupt ---- PASS: TestValidateRootCorrupt (0.06s) +--- PASS: TestValidateRootCorrupt (0.11s) === RUN TestValidateTargetsCorrupt -time="2024-01-06T14:01:41Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" ---- PASS: TestValidateTargetsCorrupt (0.07s) +time="2024-01-06T14:13:25Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" +--- PASS: TestValidateTargetsCorrupt (0.08s) === RUN TestValidateSnapshotCorrupt ---- PASS: TestValidateSnapshotCorrupt (0.07s) +--- PASS: TestValidateSnapshotCorrupt (0.09s) === RUN TestValidateRootModifiedSize ---- PASS: TestValidateRootModifiedSize (0.06s) +--- PASS: TestValidateRootModifiedSize (0.07s) === RUN TestValidateTargetsModifiedSize ---- PASS: TestValidateTargetsModifiedSize (0.08s) +--- PASS: TestValidateTargetsModifiedSize (0.12s) === RUN TestValidateRootModifiedHash ---- PASS: TestValidateRootModifiedHash (0.08s) +--- PASS: TestValidateRootModifiedHash (0.26s) === RUN TestValidateTargetsModifiedHash ---- PASS: TestValidateTargetsModifiedHash (0.08s) +--- PASS: TestValidateTargetsModifiedHash (0.34s) === RUN TestGenerateSnapshotRootNotLoaded --- PASS: TestGenerateSnapshotRootNotLoaded (0.00s) === RUN TestGenerateSnapshotNoKey --- PASS: TestGenerateSnapshotNoKey (0.07s) === RUN TestLoadTargetsLoadsNothingIfNoUpdates ---- PASS: TestLoadTargetsLoadsNothingIfNoUpdates (0.06s) +--- PASS: TestLoadTargetsLoadsNothingIfNoUpdates (0.26s) === RUN TestValidateTargetsRequiresStoredParent -time="2024-01-06T14:01:41Z" level=error msg="ErrBadTargets: targets must be loaded first" ---- PASS: TestValidateTargetsRequiresStoredParent (0.09s) +time="2024-01-06T14:13:27Z" level=error msg="ErrBadTargets: targets must be loaded first" +--- PASS: TestValidateTargetsRequiresStoredParent (0.21s) === RUN TestValidateTargetsParentInUpdate -time="2024-01-06T14:01:41Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" ---- PASS: TestValidateTargetsParentInUpdate (0.09s) +time="2024-01-06T14:13:27Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" +--- PASS: TestValidateTargetsParentInUpdate (0.38s) === RUN TestValidateTargetsRoleNotInParent -time="2024-01-06T14:01:41Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" -time="2024-01-06T14:01:41Z" level=error msg="ErrBadTargets: targets has already been loaded" -time="2024-01-06T14:01:41Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" ---- PASS: TestValidateTargetsRoleNotInParent (0.11s) +time="2024-01-06T14:13:27Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +time="2024-01-06T14:13:27Z" level=error msg="ErrBadTargets: targets has already been loaded" +time="2024-01-06T14:13:27Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +--- PASS: TestValidateTargetsRoleNotInParent (0.34s) PASS -ok github.com/theupdateframework/notary/server/handlers 4.962s +ok github.com/theupdateframework/notary/server/handlers 17.405s === RUN TestSnapshotExpired --- PASS: TestSnapshotExpired (0.00s) === RUN TestSnapshotNotExpired @@ -2952,28 +2988,28 @@ === RUN TestGetSnapshotKeyCreate --- PASS: TestGetSnapshotKeyCreate (0.04s) === RUN TestGetSnapshotKeyCreateWithFailingStore -time="2024-01-06T14:01:38Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2024-01-06T14:13:10Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetSnapshotKeyCreateWithFailingStore (0.00s) === RUN TestGetSnapshotKeyCreateWithCorruptedStore -time="2024-01-06T14:01:38Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve snapshot key ID" +time="2024-01-06T14:13:10Z" 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.07s) === RUN TestGetSnapshotNoPreviousSnapshot -time="2024-01-06T14:01:39Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" ---- PASS: TestGetSnapshotNoPreviousSnapshot (0.03s) +time="2024-01-06T14:13:10Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" +--- PASS: TestGetSnapshotNoPreviousSnapshot (0.09s) === RUN TestGetSnapshotReturnsPreviousSnapshotIfUnexpired ---- PASS: TestGetSnapshotReturnsPreviousSnapshotIfUnexpired (0.03s) +--- PASS: TestGetSnapshotReturnsPreviousSnapshotIfUnexpired (0.07s) === RUN TestGetSnapshotOldSnapshotExpired ---- PASS: TestGetSnapshotOldSnapshotExpired (0.05s) +--- PASS: TestGetSnapshotOldSnapshotExpired (0.06s) === RUN TestCannotMakeNewSnapshotIfNoRoot ---- PASS: TestCannotMakeNewSnapshotIfNoRoot (0.03s) +--- PASS: TestCannotMakeNewSnapshotIfNoRoot (0.05s) === RUN TestCreateSnapshotNoKeyInCrypto ---- PASS: TestCreateSnapshotNoKeyInCrypto (0.05s) +--- PASS: TestCreateSnapshotNoKeyInCrypto (0.09s) PASS -ok github.com/theupdateframework/notary/server/snapshot 0.308s +ok github.com/theupdateframework/notary/server/snapshot 0.526s === RUN TestMemoryUpdateCurrentEmpty --- PASS: TestMemoryUpdateCurrentEmpty (0.00s) === RUN TestMemoryUpdateCurrentVersionCheckOldVersionExists @@ -2987,7 +3023,7 @@ === RUN TestMemoryDeleteSuccess --- PASS: TestMemoryDeleteSuccess (0.00s) === RUN TestGetCurrent ---- PASS: TestGetCurrent (0.00s) +--- PASS: TestGetCurrent (0.03s) === RUN TestGetChecksumNotFound --- PASS: TestGetChecksumNotFound (0.00s) === RUN TestMemoryGetChanges @@ -2999,73 +3035,73 @@ === RUN TestRDBTUFFileJSONUnmarshallingFailure --- PASS: TestRDBTUFFileJSONUnmarshallingFailure (0.00s) === RUN TestSQLUpdateCurrentEmpty ---- PASS: TestSQLUpdateCurrentEmpty (0.30s) +--- PASS: TestSQLUpdateCurrentEmpty (1.15s) === RUN TestSQLUpdateCurrentVersionCheckOldVersionExists ---- PASS: TestSQLUpdateCurrentVersionCheckOldVersionExists (0.20s) +--- PASS: TestSQLUpdateCurrentVersionCheckOldVersionExists (0.29s) === RUN TestSQLUpdateCurrentVersionCheckOldVersionNotExist ---- PASS: TestSQLUpdateCurrentVersionCheckOldVersionNotExist (0.17s) +--- PASS: TestSQLUpdateCurrentVersionCheckOldVersionNotExist (0.31s) === RUN TestSQLUpdateManyNoConflicts ---- PASS: TestSQLUpdateManyNoConflicts (0.19s) +--- PASS: TestSQLUpdateManyNoConflicts (0.42s) === RUN TestSQLUpdateManyConflictRollback ---- PASS: TestSQLUpdateManyConflictRollback (0.14s) +--- PASS: TestSQLUpdateManyConflictRollback (0.29s) === RUN TestSQLDelete ---- PASS: TestSQLDelete (0.23s) +--- PASS: TestSQLDelete (0.56s) === RUN TestSQLDBCheckHealthTableMissing ---- PASS: TestSQLDBCheckHealthTableMissing (0.11s) +--- PASS: TestSQLDBCheckHealthTableMissing (0.28s) === RUN TestSQLDBCheckHealthDBConnectionFail ---- PASS: TestSQLDBCheckHealthDBConnectionFail (0.10s) +--- PASS: TestSQLDBCheckHealthDBConnectionFail (0.13s) === RUN TestSQLDBCheckHealthSucceeds ---- PASS: TestSQLDBCheckHealthSucceeds (0.11s) +--- PASS: TestSQLDBCheckHealthSucceeds (0.31s) === RUN TestSQLDBGetChecksum ---- PASS: TestSQLDBGetChecksum (0.13s) +--- PASS: TestSQLDBGetChecksum (0.25s) === RUN TestSQLDBGetChecksumNotFound ---- PASS: TestSQLDBGetChecksumNotFound (0.10s) +--- PASS: TestSQLDBGetChecksumNotFound (0.12s) === RUN TestSQLTUFMetaStoreGetCurrent ---- PASS: TestSQLTUFMetaStoreGetCurrent (0.31s) +--- PASS: TestSQLTUFMetaStoreGetCurrent (1.17s) === RUN TestSQLGetChanges ---- PASS: TestSQLGetChanges (0.34s) +--- PASS: TestSQLGetChanges (0.47s) === RUN TestSQLDBGetVersion ---- PASS: TestSQLDBGetVersion (0.12s) +--- PASS: TestSQLDBGetVersion (0.29s) PASS -ok github.com/theupdateframework/notary/server/storage 2.563s +ok github.com/theupdateframework/notary/server/storage 6.228s === RUN TestTimestampExpired --- PASS: TestTimestampExpired (0.00s) === RUN TestTimestampNotExpired --- PASS: TestTimestampNotExpired (0.00s) === RUN TestGetTimestampKey ---- PASS: TestGetTimestampKey (0.04s) +--- PASS: TestGetTimestampKey (0.19s) === RUN TestGetTimestampNoPreviousTimestamp -time="2024-01-06T14:01:44Z" level=error msg="Failed to unmarshal existing timestamp" ---- PASS: TestGetTimestampNoPreviousTimestamp (0.07s) +time="2024-01-06T14:13:38Z" level=error msg="Failed to unmarshal existing timestamp" +--- PASS: TestGetTimestampNoPreviousTimestamp (0.44s) === RUN TestGetTimestampReturnsPreviousTimestampIfUnexpired ---- PASS: TestGetTimestampReturnsPreviousTimestampIfUnexpired (0.05s) +--- PASS: TestGetTimestampReturnsPreviousTimestampIfUnexpired (0.30s) === RUN TestGetTimestampOldTimestampExpired ---- PASS: TestGetTimestampOldTimestampExpired (0.08s) +--- PASS: TestGetTimestampOldTimestampExpired (0.62s) === RUN TestCannotMakeNewTimestampIfNoRootOrSnapshot -time="2024-01-06T14:01:44Z" level=error msg="Failed to create a new timestamp" -time="2024-01-06T14:01:44Z" level=error msg="Failed to create a new timestamp" ---- PASS: TestCannotMakeNewTimestampIfNoRootOrSnapshot (0.06s) +time="2024-01-06T14:13:39Z" level=error msg="Failed to create a new timestamp" +time="2024-01-06T14:13:39Z" level=error msg="Failed to create a new timestamp" +--- PASS: TestCannotMakeNewTimestampIfNoRootOrSnapshot (0.50s) === RUN TestCreateTimestampNoKeyInCrypto -time="2024-01-06T14:01:44Z" level=error msg="Failed to create a new timestamp" ---- PASS: TestCreateTimestampNoKeyInCrypto (0.07s) +time="2024-01-06T14:13:40Z" level=error msg="Failed to create a new timestamp" +--- PASS: TestCreateTimestampNoKeyInCrypto (0.42s) === RUN TestGetTimestampKeyCreateWithFailingStore -time="2024-01-06T14:01:44Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2024-01-06T14:13:40Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetTimestampKeyCreateWithFailingStore (0.00s) === RUN TestGetTimestampKeyCreateWithCorruptedStore -time="2024-01-06T14:01:44Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve timestamp key ID" +time="2024-01-06T14:13:40Z" 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: TestGetTimestampKeyExistingMetadata (0.17s) PASS -ok github.com/theupdateframework/notary/server/timestamp 0.437s +ok github.com/theupdateframework/notary/server/timestamp 2.722s === RUN TestHealthCheckKMUnhealthy ---- PASS: TestHealthCheckKMUnhealthy (0.01s) +--- PASS: TestHealthCheckKMUnhealthy (0.02s) === RUN TestHealthCheckSignerUnhealthy --- PASS: TestHealthCheckSignerUnhealthy (0.00s) === RUN TestHealthCheckKMTimeout ---- PASS: TestHealthCheckKMTimeout (0.00s) +--- PASS: TestHealthCheckKMTimeout (0.02s) === RUN TestHealthCheckSignerTimeout --- PASS: TestHealthCheckSignerTimeout (0.00s) === RUN TestHealthCheckKMHealthy @@ -3077,33 +3113,33 @@ === RUN TestHealthCheckSignerConnectionDied --- PASS: TestHealthCheckSignerConnectionDied (0.00s) === RUN TestHealthCheckForOverallStatus ---- PASS: TestHealthCheckForOverallStatus (0.01s) +--- PASS: TestHealthCheckForOverallStatus (0.00s) === RUN TestHealthCheckNonexistentService --- PASS: TestHealthCheckNonexistentService (0.00s) === RUN TestGetPrivateKeyAndSignWithExistingKey -time="2024-01-06T14:01:47Z" level=info msg="Sign: Signed message! with KeyID e1602742cd0393f3218fa5755708f6721f1023baf0659cd7344a5d292422c3e4" go.version=go1.19.8 ---- PASS: TestGetPrivateKeyAndSignWithExistingKey (0.04s) +time="2024-01-06T14:13:41Z" level=info msg="Sign: Signed message! with KeyID 117ce51785929315b00c1fec72eca40b88ab7301ecaff5c442eadbfbe9e3c383" go.version=go1.19.8 +--- PASS: TestGetPrivateKeyAndSignWithExistingKey (0.29s) === RUN TestCannotSignWithKeyThatDoesntExist -time="2024-01-06T14:01:47Z" level=error msg="Sign: key b749dbd083eec350957ab24cf4727a39445cfeff0ebb3f0c9be60be175c77542 not found" go.version=go1.19.8 ---- PASS: TestCannotSignWithKeyThatDoesntExist (0.01s) +time="2024-01-06T14:13:41Z" level=error msg="Sign: key 902ea1b8ce949495b93553cb3d302a38db9aa93fca467774030734063961f9ce not found" go.version=go1.19.8 +--- PASS: TestCannotSignWithKeyThatDoesntExist (0.02s) === RUN TestCryptoSignerInterfaceBehavior -time="2024-01-06T14:01:47Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.19.8 -time="2024-01-06T14:01:47Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.19.8 -time="2024-01-06T14:01:47Z" level=info msg="CreateKey: Created KeyID 0898ed39833ed8e6121ee211705e7fa391ce6421616f9553e58c2b8fd51018d7" go.version=go1.19.8 -time="2024-01-06T14:01:47Z" level=info msg="CreateKey: Created KeyID f3ad22f5d4261984b4c43d073f28d1da787a8b3b0323b73d029208928a101d65" go.version=go1.19.8 -time="2024-01-06T14:01:47Z" level=error msg="GetKeyInfo: key 0898ed39833ed8e6121ee211705e7fa391ce6421616f9553e58c2b8fd51018d7 not found" go.version=go1.19.8 -time="2024-01-06T14:01:47Z" level=error msg="GetKeyInfo: key f3ad22f5d4261984b4c43d073f28d1da787a8b3b0323b73d029208928a101d65 not found" go.version=go1.19.8 ---- PASS: TestCryptoSignerInterfaceBehavior (0.04s) +time="2024-01-06T14:13:41Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.19.8 +time="2024-01-06T14:13:41Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.19.8 +time="2024-01-06T14:13:41Z" level=info msg="CreateKey: Created KeyID 918f3be5db86cb907aead1fe7f225e635f29af25c69433749737fc857027c943" go.version=go1.19.8 +time="2024-01-06T14:13:41Z" level=info msg="CreateKey: Created KeyID e86ac772846be2568179be2ee6c5061c998d334aae9c5f1a1a3de7aacd31a50c" go.version=go1.19.8 +time="2024-01-06T14:13:41Z" level=error msg="GetKeyInfo: key 918f3be5db86cb907aead1fe7f225e635f29af25c69433749737fc857027c943 not found" go.version=go1.19.8 +time="2024-01-06T14:13:41Z" level=error msg="GetKeyInfo: key e86ac772846be2568179be2ee6c5061c998d334aae9c5f1a1a3de7aacd31a50c not found" go.version=go1.19.8 +--- PASS: TestCryptoSignerInterfaceBehavior (0.13s) PASS -ok github.com/theupdateframework/notary/signer 0.131s +ok github.com/theupdateframework/notary/signer 0.603s ? github.com/theupdateframework/notary/signer/api [no test files] ? github.com/theupdateframework/notary/signer/client [no test files] === RUN TestGetSuccessPopulatesCache ---- PASS: TestGetSuccessPopulatesCache (0.05s) +--- PASS: TestGetSuccessPopulatesCache (0.06s) === RUN TestAddKeyPopulatesCacheIfSuccessful ---- PASS: TestAddKeyPopulatesCacheIfSuccessful (0.01s) +--- PASS: TestAddKeyPopulatesCacheIfSuccessful (0.04s) === RUN TestDeleteKeyRemovesKeyFromCache ---- PASS: TestDeleteKeyRemovesKeyFromCache (0.02s) +--- PASS: TestDeleteKeyRemovesKeyFromCache (0.06s) === RUN TestRDBPrivateKeyJSONUnmarshalling --- PASS: TestRDBPrivateKeyJSONUnmarshalling (0.00s) === RUN TestRDBPrivateKeyJSONUnmarshallingFailure @@ -3112,50 +3148,50 @@ `nodb` is not officially supported, running under compatibility mode. --- PASS: TestNewSQLKeyDBStorePropagatesDBError (0.00s) === RUN TestSQLDBHealthCheckMissingTable ---- PASS: TestSQLDBHealthCheckMissingTable (0.11s) +--- PASS: TestSQLDBHealthCheckMissingTable (25.05s) === RUN TestSQLDBHealthCheckNoConnection --- PASS: TestSQLDBHealthCheckNoConnection (0.08s) === RUN TestSQLKeyCanOnlyBeAddedOnce (/build/reproducible-path/notary-0.7.0+ds1/_build/src/github.com/theupdateframework/notary/signer/keydbstore/sql_keydbstore.go:98) -[2024-01-06 14:01:49]  UNIQUE constraint failed: private_keys.key_id  ---- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.30s) +[2024-01-06 14:14:09]  UNIQUE constraint failed: private_keys.key_id  +--- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.75s) === RUN TestSQLCreateDelete ---- PASS: TestSQLCreateDelete (0.35s) +--- PASS: TestSQLCreateDelete (1.07s) === RUN TestSQLKeyRotation ---- PASS: TestSQLKeyRotation (0.42s) +--- PASS: TestSQLKeyRotation (1.05s) === RUN TestSQLSigningMarksKeyActive -time="2024-01-06T14:01:50Z" level=error msg="Key 86effdf711769907e064f965fa08d6aad9903d4c93e406be56435ac00cf7dfee was just used to sign hash successful, db closed, error when trying to mark key as active: sql: database is closed" (/build/reproducible-path/notary-0.7.0+ds1/_build/src/github.com/theupdateframework/notary/signer/keydbstore/sql_keydbstore.go:197) -[2024-01-06 14:01:50]  sql: database is closed  ---- PASS: TestSQLSigningMarksKeyActive (0.56s) +[2024-01-06 14:14:12]  sql: database is closed  +time="2024-01-06T14:14:12Z" level=error msg="Key 53bcf83be001b788795488c6ddc60d3bea0a09d4444e7efc293fa54211c2a62f was just used to sign hash successful, db closed, error when trying to mark key as active: sql: database is closed" +--- PASS: TestSQLSigningMarksKeyActive (0.90s) === RUN TestSQLCreateKey ---- PASS: TestSQLCreateKey (0.54s) +--- PASS: TestSQLCreateKey (1.81s) === RUN TestSQLUnimplementedInterfaceBehavior ---- PASS: TestSQLUnimplementedInterfaceBehavior (0.18s) +--- PASS: TestSQLUnimplementedInterfaceBehavior (0.27s) PASS -ok github.com/theupdateframework/notary/signer/keydbstore 2.671s +ok github.com/theupdateframework/notary/signer/keydbstore 31.232s === RUN TestSet --- PASS: TestSet (0.00s) === RUN TestSetWithNoParentDirectory --- PASS: TestSetWithNoParentDirectory (0.00s) === RUN TestSetRemovesExistingFileBeforeWriting ---- PASS: TestSetRemovesExistingFileBeforeWriting (0.00s) +--- PASS: TestSetRemovesExistingFileBeforeWriting (0.01s) === 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 @@ -3165,13 +3201,13 @@ === 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.01s) === RUN TestHTTPStoreGetSized --- PASS: TestHTTPStoreGetSized (0.00s) === RUN TestHTTPStoreGetAllMeta ---- PASS: TestHTTPStoreGetAllMeta (0.00s) +--- PASS: TestHTTPStoreGetAllMeta (0.01s) === RUN TestSetSingleAndSetMultiMeta --- PASS: TestSetSingleAndSetMultiMeta (0.00s) === RUN Test404Error @@ -3181,7 +3217,7 @@ === RUN Test400Error --- PASS: Test400Error (0.00s) === RUN TestTranslateErrorsParse400Errors ---- PASS: TestTranslateErrorsParse400Errors (0.00s) +--- PASS: TestTranslateErrorsParse400Errors (0.02s) === RUN TestTranslateErrorsWhenCannotParse400 --- PASS: TestTranslateErrorsWhenCannotParse400 (0.00s) === RUN TestTranslateErrorsLimitsErrorSize @@ -3191,9 +3227,9 @@ === RUN TestHTTPStoreRotateKey --- PASS: TestHTTPStoreRotateKey (0.00s) === RUN TestHTTPStoreGetKey ---- PASS: TestHTTPStoreGetKey (0.00s) +--- PASS: TestHTTPStoreGetKey (0.01s) === RUN TestHTTPStoreGetRotateKeySizeLimited ---- PASS: TestHTTPStoreGetRotateKeySizeLimited (0.01s) +--- PASS: TestHTTPStoreGetRotateKeySizeLimited (0.03s) === RUN TestHTTPOffline --- PASS: TestHTTPOffline (0.00s) === RUN TestErrServerUnavailable @@ -3214,7 +3250,7 @@ === RUN TestMemoryStoreMetadata --- PASS: TestMemoryStoreMetadata (0.00s) PASS -ok github.com/theupdateframework/notary/storage 0.078s +ok github.com/theupdateframework/notary/storage 0.195s ? github.com/theupdateframework/notary/storage/rethinkdb [no test files] === RUN TestExportKeys --- PASS: TestExportKeys (0.00s) @@ -3237,9 +3273,9 @@ === RUN TestImportKeys2InOneFile --- PASS: TestImportKeys2InOneFile (0.00s) === RUN TestImportKeys2InOneFileNoPath ---- PASS: TestImportKeys2InOneFileNoPath (0.03s) +--- PASS: TestImportKeys2InOneFileNoPath (0.02s) === RUN TestEncryptedKeyImportFail -time="2024-01-06T14:01:52Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" +time="2024-01-06T14:13:54Z" 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) @@ -3248,7 +3284,7 @@ === RUN TestAddKey --- PASS: TestAddKey (0.07s) === RUN TestKeyStoreInternalState ---- PASS: TestKeyStoreInternalState (0.03s) +--- PASS: TestKeyStoreInternalState (0.04s) === RUN TestGet --- PASS: TestGet (0.03s) === RUN TestGetLegacyKey @@ -3258,51 +3294,51 @@ === RUN TestAddGetKeyMemStore --- PASS: TestAddGetKeyMemStore (0.01s) === RUN TestAddGetKeyInfoMemStore ---- PASS: TestAddGetKeyInfoMemStore (0.03s) +--- PASS: TestAddGetKeyInfoMemStore (0.04s) === RUN TestGetDecryptedWithTamperedCipherText -time="2024-01-06T14:01:52Z" level=error msg="PEM block is empty" ---- PASS: TestGetDecryptedWithTamperedCipherText (0.01s) +time="2024-01-06T14:13:55Z" level=error msg="PEM block is empty" +--- PASS: TestGetDecryptedWithTamperedCipherText (0.02s) === RUN TestGetDecryptedWithInvalidPassphrase ---- PASS: TestGetDecryptedWithInvalidPassphrase (0.06s) +--- PASS: TestGetDecryptedWithInvalidPassphrase (0.11s) === RUN TestGetDecryptedWithConsistentlyInvalidPassphrase ---- PASS: TestGetDecryptedWithConsistentlyInvalidPassphrase (0.10s) +--- PASS: TestGetDecryptedWithConsistentlyInvalidPassphrase (0.27s) === RUN TestRemoveKey ---- PASS: TestRemoveKey (0.07s) +--- PASS: TestRemoveKey (0.13s) === RUN TestKeysAreCached ---- PASS: TestKeysAreCached (0.02s) +--- PASS: TestKeysAreCached (0.03s) PASS -ok github.com/theupdateframework/notary/trustmanager 0.705s +ok github.com/theupdateframework/notary/trustmanager 1.017s === RUN TestRemoteStore -time="2024-01-06T14:01:54Z" level=info msg="listing files from localhost:9888" -time="2024-01-06T14:01:54Z" level=info msg="listing files from localhost:9888" ---- PASS: TestRemoteStore (0.26s) +time="2024-01-06T14:14:10Z" level=info msg="listing files from localhost:9888" +time="2024-01-06T14:14:10Z" level=info msg="listing files from localhost:9888" +--- PASS: TestRemoteStore (1.06s) === RUN TestErrors -time="2024-01-06T14:01:54Z" level=error msg="failed to store: test error" ---- PASS: TestErrors (0.21s) +time="2024-01-06T14:14:11Z" level=error msg="failed to store: test error" +--- PASS: TestErrors (0.97s) === RUN TestNewGRPCStorage --- PASS: TestNewGRPCStorage (0.00s) === RUN TestGRPCStorage --- PASS: TestGRPCStorage (0.00s) PASS -ok github.com/theupdateframework/notary/trustmanager/remoteks 0.506s +ok github.com/theupdateframework/notary/trustmanager/remoteks 2.105s ? github.com/theupdateframework/notary/trustmanager/yubikey [no test files] === RUN TestWildcardMatch --- PASS: TestWildcardMatch (0.00s) === RUN TestValidateRoot -2024/01/06 14:02:00 [INFO] generate received request -2024/01/06 14:02:00 [INFO] received CSR -2024/01/06 14:02:00 [INFO] generating key: ecdsa-256 -2024/01/06 14:02:00 [INFO] encoded CSR -2024/01/06 14:02:00 [INFO] signed certificate with serial number 23308772322170740201696526026494127555093275000 -2024/01/06 14:02:00 [INFO] received CSR -2024/01/06 14:02:00 [INFO] generating key: ecdsa-256 -2024/01/06 14:02:00 [INFO] encoded CSR -2024/01/06 14:02:00 [INFO] signed certificate with serial number 237792040506199239918226956058801115315369685397 -2024/01/06 14:02:00 [INFO] received CSR -2024/01/06 14:02:00 [INFO] generating key: ecdsa-256 -2024/01/06 14:02:00 [INFO] encoded CSR -2024/01/06 14:02:00 [INFO] signed certificate with serial number 170003902880559830210245919756568664738885497572 ---- PASS: TestValidateRoot (0.18s) +2024/01/06 14:14:20 [INFO] generate received request +2024/01/06 14:14:20 [INFO] received CSR +2024/01/06 14:14:21 [INFO] generating key: ecdsa-256 +2024/01/06 14:14:21 [INFO] encoded CSR +2024/01/06 14:14:21 [INFO] signed certificate with serial number 163628913394633914104525674104368295713303031668 +2024/01/06 14:14:21 [INFO] received CSR +2024/01/06 14:14:21 [INFO] generating key: ecdsa-256 +2024/01/06 14:14:21 [INFO] encoded CSR +2024/01/06 14:14:21 [INFO] signed certificate with serial number 61105752968885353245745028038681198731938388699 +2024/01/06 14:14:21 [INFO] received CSR +2024/01/06 14:14:21 [INFO] generating key: ecdsa-256 +2024/01/06 14:14:21 [INFO] encoded CSR +2024/01/06 14:14:21 [INFO] signed certificate with serial number 727993444819316587880105293905052663628098134148 +--- PASS: TestValidateRoot (0.61s) === RUN TestValidateRootWithoutTOFUS --- PASS: TestValidateRootWithoutTOFUS (0.00s) === RUN TestValidateRootWithPinnedCert @@ -3312,101 +3348,101 @@ === RUN TestValidateRootFailuresWithPinnedCert --- PASS: TestValidateRootFailuresWithPinnedCert (0.01s) === RUN TestValidateRootWithPinnedCA ---- PASS: TestValidateRootWithPinnedCA (0.14s) +--- PASS: TestValidateRootWithPinnedCA (0.43s) === RUN TestValidateSuccessfulRootRotation ---- PASS: TestValidateSuccessfulRootRotation (0.23s) +--- PASS: TestValidateSuccessfulRootRotation (0.86s) === RUN TestValidateRootRotationMissingOrigSig ---- PASS: TestValidateRootRotationMissingOrigSig (0.17s) +--- PASS: TestValidateRootRotationMissingOrigSig (0.59s) === RUN TestValidateRootRotationMissingNewSig ---- PASS: TestValidateRootRotationMissingNewSig (0.17s) +--- PASS: TestValidateRootRotationMissingNewSig (0.83s) === RUN TestValidateRootRotationTrustPinning ---- PASS: TestValidateRootRotationTrustPinning (0.17s) +--- PASS: TestValidateRootRotationTrustPinning (0.78s) === RUN TestValidateRootRotationTrustPinningInvalidCA ---- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.13s) +--- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.58s) === RUN TestParsePEMPublicKey -time="2024-01-06T14:02:02Z" level=warning msg="certificate with CN notary is near expiry" ---- PASS: TestParsePEMPublicKey (0.03s) +time="2024-01-06T14:14:25Z" level=warning msg="certificate with CN notary is near expiry" +--- PASS: TestParsePEMPublicKey (0.09s) === RUN TestCheckingCertExpiry -time="2024-01-06T14:02:02Z" level=warning msg="certificate with CN notary is near expiry" ---- PASS: TestCheckingCertExpiry (0.04s) +time="2024-01-06T14:14:25Z" level=warning msg="certificate with CN notary is near expiry" +--- PASS: TestCheckingCertExpiry (0.15s) === RUN TestValidateRootWithExpiredIntermediate ---- PASS: TestValidateRootWithExpiredIntermediate (0.03s) +--- PASS: TestValidateRootWithExpiredIntermediate (0.10s) === RUN TestCheckingWildcardCert ---- PASS: TestCheckingWildcardCert (0.03s) +--- PASS: TestCheckingWildcardCert (0.06s) === RUN TestWildcardMatching --- PASS: TestWildcardMatching (0.00s) PASS -ok github.com/theupdateframework/notary/trustpinning 1.443s +ok github.com/theupdateframework/notary/trustpinning 5.262s === RUN TestInitSnapshotNoTargets ---- PASS: TestInitSnapshotNoTargets (0.05s) +--- PASS: TestInitSnapshotNoTargets (0.10s) === RUN TestInitRepo ---- PASS: TestInitRepo (0.03s) +--- PASS: TestInitRepo (0.06s) === RUN TestUpdateDelegations ---- PASS: TestUpdateDelegations (0.00s) +--- PASS: TestUpdateDelegations (0.01s) === RUN TestPurgeDelegationsKeyFromTop -time="2024-01-06T14:02:01Z" level=warning msg="role targets/sybil has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2024-01-06T14:02:01Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" ---- PASS: TestPurgeDelegationsKeyFromTop (0.00s) +time="2024-01-06T14:14:25Z" 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-01-06T14:14:25Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +--- PASS: TestPurgeDelegationsKeyFromTop (0.01s) === RUN TestPurgeDelegationsKeyFromDeep -time="2024-01-06T14:02:01Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" ---- PASS: TestPurgeDelegationsKeyFromDeep (0.00s) +time="2024-01-06T14:14:25Z" 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) +--- PASS: TestUpdateDelegationsInvalidRole (0.01s) === RUN TestUpdateDelegationsRoleThatIsMissingDelegationKey ---- PASS: TestUpdateDelegationsRoleThatIsMissingDelegationKey (0.00s) +--- PASS: TestUpdateDelegationsRoleThatIsMissingDelegationKey (0.01s) === RUN TestUpdateDelegationsNotEnoughKeys -time="2024-01-06T14:02:01Z" level=warning msg="role targets/role has fewer keys than its threshold of 2; it will not be usable until keys are added to it" ---- PASS: TestUpdateDelegationsNotEnoughKeys (0.00s) +time="2024-01-06T14:14:25Z" 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) +--- PASS: TestDeleteDelegationsRoleNotExist (0.01s) === RUN TestDeleteDelegationsInvalidRole ---- PASS: TestDeleteDelegationsInvalidRole (0.00s) +--- PASS: TestDeleteDelegationsInvalidRole (0.01s) === RUN TestDeleteDelegationsParentMissing --- PASS: TestDeleteDelegationsParentMissing (0.00s) === RUN TestDeleteDelegationsMissingParentSigningKey ---- PASS: TestDeleteDelegationsMissingParentSigningKey (0.00s) +--- PASS: TestDeleteDelegationsMissingParentSigningKey (0.01s) === RUN TestDeleteDelegationsMidSliceRole --- PASS: TestDeleteDelegationsMidSliceRole (0.00s) === RUN TestGetDelegationRoleAndMetadataExistDelegationExists ---- PASS: TestGetDelegationRoleAndMetadataExistDelegationExists (0.00s) +--- PASS: TestGetDelegationRoleAndMetadataExistDelegationExists (0.02s) === RUN TestGetDelegationRoleAndMetadataExistDelegationDoesntExists ---- PASS: TestGetDelegationRoleAndMetadataExistDelegationDoesntExists (0.00s) +--- PASS: TestGetDelegationRoleAndMetadataExistDelegationDoesntExists (0.01s) === RUN TestGetDelegationRoleAndMetadataDoesntExists ---- PASS: TestGetDelegationRoleAndMetadataDoesntExists (0.00s) +--- PASS: TestGetDelegationRoleAndMetadataDoesntExists (0.01s) === RUN TestGetDelegationParentMissing --- PASS: TestGetDelegationParentMissing (0.00s) === 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.02s) === RUN TestRemoveExistingAndNonexistingTargets ---- PASS: TestRemoveExistingAndNonexistingTargets (0.00s) +--- PASS: TestRemoveExistingAndNonexistingTargets (0.01s) === RUN TestRemoveTargetsRoleDoesntExist ---- PASS: TestRemoveTargetsRoleDoesntExist (0.00s) +--- PASS: TestRemoveTargetsRoleDoesntExist (0.01s) === RUN TestRemoveTargetsNoSigningKeys ---- PASS: TestRemoveTargetsNoSigningKeys (0.00s) +--- PASS: TestRemoveTargetsNoSigningKeys (0.01s) === RUN TestAddBaseKeysToRoot ---- PASS: TestAddBaseKeysToRoot (0.02s) +--- PASS: TestAddBaseKeysToRoot (0.03s) === RUN TestRemoveBaseKeysFromRoot ---- PASS: TestRemoveBaseKeysFromRoot (0.01s) +--- PASS: TestRemoveBaseKeysFromRoot (0.03s) === RUN TestReplaceBaseKeysInRoot ---- PASS: TestReplaceBaseKeysInRoot (0.03s) +--- PASS: TestReplaceBaseKeysInRoot (0.12s) === RUN TestGetAllRoles --- PASS: TestGetAllRoles (0.00s) === RUN TestGetBaseRoles @@ -3414,55 +3450,55 @@ === RUN TestGetBaseRolesInvalidName --- PASS: TestGetBaseRolesInvalidName (0.00s) === RUN TestGetDelegationValidRoles ---- PASS: TestGetDelegationValidRoles (0.01s) +--- PASS: TestGetDelegationValidRoles (0.02s) === RUN TestGetDelegationRolesInvalidName ---- PASS: TestGetDelegationRolesInvalidName (0.00s) +--- PASS: TestGetDelegationRolesInvalidName (0.02s) === RUN TestGetDelegationRolesInvalidPaths ---- PASS: TestGetDelegationRolesInvalidPaths (0.00s) +--- PASS: TestGetDelegationRolesInvalidPaths (0.01s) === RUN TestDelegationRolesParent --- PASS: TestDelegationRolesParent (0.00s) === RUN TestGetBaseRoleEmptyRepo --- PASS: TestGetBaseRoleEmptyRepo (0.00s) === RUN TestGetBaseRoleKeyMissing ---- PASS: TestGetBaseRoleKeyMissing (0.00s) +--- PASS: TestGetBaseRoleKeyMissing (0.01s) === RUN TestGetDelegationRoleKeyMissing ---- PASS: TestGetDelegationRoleKeyMissing (0.00s) +--- PASS: TestGetDelegationRoleKeyMissing (0.01s) === RUN TestSignRootOldKeyCertExists ---- PASS: TestSignRootOldKeyCertExists (0.13s) +--- PASS: TestSignRootOldKeyCertExists (0.50s) === RUN TestSignRootOldKeyCertMissing ---- PASS: TestSignRootOldKeyCertMissing (0.09s) +--- PASS: TestSignRootOldKeyCertMissing (0.36s) === RUN TestRootKeyRotation ---- PASS: TestRootKeyRotation (0.34s) +--- PASS: TestRootKeyRotation (1.18s) === RUN TestBuilderLoadsValidRolesOnly ---- PASS: TestBuilderLoadsValidRolesOnly (0.08s) +--- PASS: TestBuilderLoadsValidRolesOnly (0.29s) === RUN TestBuilderOnlyAcceptsRootFirstWhenLoading ---- PASS: TestBuilderOnlyAcceptsRootFirstWhenLoading (0.01s) +--- PASS: TestBuilderOnlyAcceptsRootFirstWhenLoading (0.02s) === RUN TestBuilderOnlyAcceptsDelegationsAfterParent ---- PASS: TestBuilderOnlyAcceptsDelegationsAfterParent (0.03s) +--- PASS: TestBuilderOnlyAcceptsDelegationsAfterParent (0.06s) === RUN TestMarkingIsValid ---- PASS: TestMarkingIsValid (0.04s) +--- PASS: TestMarkingIsValid (0.10s) === RUN TestBuilderLoadInvalidDelegations ---- PASS: TestBuilderLoadInvalidDelegations (0.08s) +--- PASS: TestBuilderLoadInvalidDelegations (0.20s) === RUN TestBuilderLoadInvalidDelegationsOldVersion ---- PASS: TestBuilderLoadInvalidDelegationsOldVersion (0.07s) +--- PASS: TestBuilderLoadInvalidDelegationsOldVersion (0.25s) === RUN TestBuilderAcceptRoleOnce ---- PASS: TestBuilderAcceptRoleOnce (0.04s) +--- PASS: TestBuilderAcceptRoleOnce (0.12s) === RUN TestBuilderStopsAcceptingOrProducingDataOnceDone ---- PASS: TestBuilderStopsAcceptingOrProducingDataOnceDone (0.02s) +--- PASS: TestBuilderStopsAcceptingOrProducingDataOnceDone (0.03s) === RUN TestGenerateSnapshotInvalidOperations ---- PASS: TestGenerateSnapshotInvalidOperations (0.28s) +--- PASS: TestGenerateSnapshotInvalidOperations (0.95s) === RUN TestGenerateTimestampInvalidOperations ---- PASS: TestGenerateTimestampInvalidOperations (0.23s) +--- PASS: TestGenerateTimestampInvalidOperations (0.93s) === RUN TestGetConsistentInfo ---- PASS: TestGetConsistentInfo (0.08s) +--- PASS: TestGetConsistentInfo (0.29s) === RUN TestTimestampPreAndPostChecksumming ---- PASS: TestTimestampPreAndPostChecksumming (0.13s) +--- PASS: TestTimestampPreAndPostChecksumming (0.39s) === RUN TestSnapshotLoadedFirstChecksumsOthers ---- PASS: TestSnapshotLoadedFirstChecksumsOthers (0.19s) +--- PASS: TestSnapshotLoadedFirstChecksumsOthers (0.22s) === RUN TestSnapshotLoadedAfterChecksumsOthersRetroactively ---- PASS: TestSnapshotLoadedAfterChecksumsOthersRetroactively (0.37s) +--- PASS: TestSnapshotLoadedAfterChecksumsOthersRetroactively (0.29s) PASS -ok github.com/theupdateframework/notary/tuf 2.517s +ok github.com/theupdateframework/notary/tuf 6.937s === RUN TestMergeStrSlicesExclusive --- PASS: TestMergeStrSlicesExclusive (0.00s) === RUN TestMergeStrSlicesOverlap @@ -3510,11 +3546,11 @@ === RUN TestRootFromSignedCopiesSignatures --- PASS: TestRootFromSignedCopiesSignatures (0.00s) === RUN TestRootFromSignedValidatesRoleData ---- PASS: TestRootFromSignedValidatesRoleData (0.01s) +--- PASS: TestRootFromSignedValidatesRoleData (0.06s) === RUN TestRootFromSignedValidatesRoleType ---- PASS: TestRootFromSignedValidatesRoleType (0.00s) +--- PASS: TestRootFromSignedValidatesRoleType (0.01s) === RUN TestRootFromSignedValidatesVersion ---- PASS: TestRootFromSignedValidatesVersion (0.00s) +--- PASS: TestRootFromSignedValidatesVersion (0.01s) === RUN TestSnapshotToSignedMarshalsSignedPortionWithCanonicalJSON --- PASS: TestSnapshotToSignedMarshalsSignedPortionWithCanonicalJSON (0.00s) === RUN TestSnapshotToSignCopiesSignatures @@ -3530,7 +3566,7 @@ === RUN TestSnapshotFromSignedCopiesSignatures --- PASS: TestSnapshotFromSignedCopiesSignatures (0.00s) === RUN TestSnapshotFromSignedValidatesMeta ---- PASS: TestSnapshotFromSignedValidatesMeta (0.00s) +--- PASS: TestSnapshotFromSignedValidatesMeta (0.03s) === RUN TestSnapshotFromSignedValidatesRoleType --- PASS: TestSnapshotFromSignedValidatesRoleType (0.00s) === RUN TestSnapshotFromSignedValidatesVersion @@ -3550,9 +3586,9 @@ === RUN TestTargetsFromSignedUnmarshallingErrorsPropagated --- PASS: TestTargetsFromSignedUnmarshallingErrorsPropagated (0.00s) === RUN TestTargetsFromSignedCopiesSignatures ---- PASS: TestTargetsFromSignedCopiesSignatures (0.00s) +--- PASS: TestTargetsFromSignedCopiesSignatures (0.01s) === RUN TestTargetsFromSignedValidatesDelegations ---- PASS: TestTargetsFromSignedValidatesDelegations (0.00s) +--- PASS: TestTargetsFromSignedValidatesDelegations (0.02s) === RUN TestTargetsFromSignedValidatesRoleType --- PASS: TestTargetsFromSignedValidatesRoleType (0.01s) === RUN TestTargetsFromSignedValidatesRoleName @@ -3566,7 +3602,7 @@ === RUN TestTimestampToSignedMarshallingErrorsPropagated --- PASS: TestTimestampToSignedMarshallingErrorsPropagated (0.00s) === RUN TestTimestampMarshalJSONMarshalsSignedWithRegularJSON ---- PASS: TestTimestampMarshalJSONMarshalsSignedWithRegularJSON (0.00s) +--- PASS: TestTimestampMarshalJSONMarshalsSignedWithRegularJSON (0.01s) === RUN TestTimestampMarshalJSONMarshallingErrorsPropagated --- PASS: TestTimestampMarshalJSONMarshallingErrorsPropagated (0.00s) === RUN TestTimestampFromSignedUnmarshallingErrorsPropagated @@ -3596,58 +3632,58 @@ === RUN TestFileMetaEquals --- PASS: TestFileMetaEquals (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/data 0.068s +ok github.com/theupdateframework/notary/tuf/data 0.255s === RUN TestListKeys ---- PASS: TestListKeys (0.04s) +--- PASS: TestListKeys (0.13s) === RUN TestGetKeys --- PASS: TestGetKeys (0.00s) === RUN TestBasicSign ---- PASS: TestBasicSign (0.00s) +--- PASS: TestBasicSign (0.01s) === RUN TestReSign ---- PASS: TestReSign (0.00s) +--- PASS: TestReSign (0.01s) === RUN TestMultiSign ---- PASS: TestMultiSign (0.01s) +--- PASS: TestMultiSign (0.04s) === RUN TestSignReturnsNoSigs --- PASS: TestSignReturnsNoSigs (0.00s) === RUN TestSignWithX509 ---- PASS: TestSignWithX509 (0.01s) +--- PASS: TestSignWithX509 (0.03s) === RUN TestSignRemovesValidSigByInvalidKey ---- PASS: TestSignRemovesValidSigByInvalidKey (0.01s) +--- PASS: TestSignRemovesValidSigByInvalidKey (0.02s) === RUN TestSignRemovesInvalidSig --- PASS: TestSignRemovesInvalidSig (0.00s) === RUN TestSignMinSignatures ---- PASS: TestSignMinSignatures (0.00s) +--- PASS: TestSignMinSignatures (0.02s) === RUN TestSignFailingKeys ---- PASS: TestSignFailingKeys (0.02s) +--- PASS: TestSignFailingKeys (0.05s) === RUN TestErrInsufficientSignaturesMessaging --- PASS: TestErrInsufficientSignaturesMessaging (0.00s) === RUN TestRSAPSSVerifier ---- PASS: TestRSAPSSVerifier (0.04s) +--- PASS: TestRSAPSSVerifier (0.09s) === RUN TestRSAPSSx509Verifier ---- PASS: TestRSAPSSx509Verifier (0.00s) +--- PASS: TestRSAPSSx509Verifier (0.01s) === RUN TestRSAPSSVerifierWithInvalidKeyType --- PASS: TestRSAPSSVerifierWithInvalidKeyType (0.00s) === RUN TestRSAPSSVerifierWithInvalidKeyLength ---- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.44s) +--- PASS: TestRSAPSSVerifierWithInvalidKeyLength (1.23s) === RUN TestRSAPSSVerifierWithInvalidKey --- PASS: TestRSAPSSVerifierWithInvalidKey (0.00s) === RUN TestRSAPSSVerifierWithInvalidSignature ---- PASS: TestRSAPSSVerifierWithInvalidSignature (0.03s) +--- PASS: TestRSAPSSVerifierWithInvalidSignature (0.05s) === RUN TestRSAPKCS1v15Verifier ---- PASS: TestRSAPKCS1v15Verifier (0.03s) +--- PASS: TestRSAPKCS1v15Verifier (0.06s) === RUN TestRSAPKCS1v15x509Verifier ---- PASS: TestRSAPKCS1v15x509Verifier (0.00s) +--- PASS: TestRSAPKCS1v15x509Verifier (0.01s) === RUN TestRSAPKCS1v15VerifierWithInvalidKeyType --- PASS: TestRSAPKCS1v15VerifierWithInvalidKeyType (0.00s) === RUN TestRSAPKCS1v15VerifierWithInvalidKey --- PASS: TestRSAPKCS1v15VerifierWithInvalidKey (0.00s) === RUN TestRSAPKCS1v15VerifierWithInvalidSignature -time="2024-01-06T14:02:07Z" level=error msg="Failed verification: crypto/rsa: verification error" ---- PASS: TestRSAPKCS1v15VerifierWithInvalidSignature (0.03s) +time="2024-01-06T14:14:34Z" level=error msg="Failed verification: crypto/rsa: verification error" +--- PASS: TestRSAPKCS1v15VerifierWithInvalidSignature (0.05s) === RUN TestECDSAVerifier ---- PASS: TestECDSAVerifier (0.01s) +--- PASS: TestECDSAVerifier (0.03s) === RUN TestECDSAVerifierOtherCurves ---- PASS: TestECDSAVerifierOtherCurves (0.92s) +--- PASS: TestECDSAVerifierOtherCurves (1.90s) === RUN TestECDSAx509Verifier --- PASS: TestECDSAx509Verifier (0.01s) === RUN TestECDSAVerifierWithInvalidKeyType @@ -3655,7 +3691,7 @@ === RUN TestECDSAVerifierWithInvalidKey --- PASS: TestECDSAVerifierWithInvalidKey (0.00s) === RUN TestECDSAVerifierWithInvalidSignature ---- PASS: TestECDSAVerifierWithInvalidSignature (0.01s) +--- PASS: TestECDSAVerifierWithInvalidSignature (0.02s) === RUN TestED25519VerifierInvalidKeyType --- PASS: TestED25519VerifierInvalidKeyType (0.00s) === RUN TestRSAPyCryptoVerifierInvalidKeyType @@ -3663,92 +3699,92 @@ === RUN TestPyCryptoRSAPSSCompat --- PASS: TestPyCryptoRSAPSSCompat (0.00s) === RUN TestPyNaCled25519Compat ---- PASS: TestPyNaCled25519Compat (0.00s) +--- PASS: TestPyNaCled25519Compat (0.01s) === 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) +--- PASS: TestExactlyEnoughSigs (0.01s) === RUN TestIsValidNotExported ---- PASS: TestIsValidNotExported (0.00s) +--- PASS: TestIsValidNotExported (0.01s) === RUN TestMoreThanEnoughSigs ---- PASS: TestMoreThanEnoughSigs (0.01s) +--- PASS: TestMoreThanEnoughSigs (0.02s) === RUN TestValidSigWithIncorrectKeyID --- PASS: TestValidSigWithIncorrectKeyID (0.00s) === RUN TestDuplicateSigs --- PASS: TestDuplicateSigs (0.01s) === RUN TestUnknownKeyBelowThreshold ---- PASS: TestUnknownKeyBelowThreshold (0.00s) +--- PASS: TestUnknownKeyBelowThreshold (0.01s) === RUN TestVerifyVersion --- PASS: TestVerifyVersion (0.00s) === RUN TestVerifyExpiry -time="2024-01-06T14:02:08Z" level=error msg="Metadata for root expired" +time="2024-01-06T14:14:36Z" level=error msg="Metadata for root expired" --- PASS: TestVerifyExpiry (0.00s) === RUN TestVerifyPublicKeyMatchesPrivateKeyHappyCase ---- PASS: TestVerifyPublicKeyMatchesPrivateKeyHappyCase (0.00s) +--- PASS: TestVerifyPublicKeyMatchesPrivateKeyHappyCase (0.01s) === RUN TestVerifyPublicKeyMatchesPrivateKeyFails --- PASS: TestVerifyPublicKeyMatchesPrivateKeyFails (0.01s) PASS -ok github.com/theupdateframework/notary/tuf/signed 1.693s +ok github.com/theupdateframework/notary/tuf/signed 3.953s === RUN TestNewSwizzler ---- PASS: TestNewSwizzler (0.10s) +--- PASS: TestNewSwizzler (0.28s) === RUN TestSwizzlerSetInvalidJSON ---- PASS: TestSwizzlerSetInvalidJSON (0.08s) +--- PASS: TestSwizzlerSetInvalidJSON (0.12s) === RUN TestSwizzlerAddExtraSpace ---- PASS: TestSwizzlerAddExtraSpace (0.08s) +--- PASS: TestSwizzlerAddExtraSpace (0.14s) === RUN TestSwizzlerSetInvalidSigned ---- PASS: TestSwizzlerSetInvalidSigned (0.08s) +--- PASS: TestSwizzlerSetInvalidSigned (0.17s) === RUN TestSwizzlerSetInvalidSignedMeta ---- PASS: TestSwizzlerSetInvalidSignedMeta (0.08s) +--- PASS: TestSwizzlerSetInvalidSignedMeta (0.25s) === RUN TestSwizzlerSetInvalidMetadataType ---- PASS: TestSwizzlerSetInvalidMetadataType (0.08s) +--- PASS: TestSwizzlerSetInvalidMetadataType (0.16s) === RUN TestSwizzlerInvalidateMetadataSignatures ---- PASS: TestSwizzlerInvalidateMetadataSignatures (0.08s) +--- PASS: TestSwizzlerInvalidateMetadataSignatures (0.20s) === RUN TestSwizzlerRemoveMetadata ---- PASS: TestSwizzlerRemoveMetadata (0.08s) +--- PASS: TestSwizzlerRemoveMetadata (0.18s) === RUN TestSwizzlerSignMetadataWithInvalidKey --- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.08s) === RUN TestSwizzlerOffsetMetadataVersion ---- PASS: TestSwizzlerOffsetMetadataVersion (0.08s) +--- PASS: TestSwizzlerOffsetMetadataVersion (0.12s) === RUN TestSwizzlerExpireMetadata ---- PASS: TestSwizzlerExpireMetadata (0.08s) +--- PASS: TestSwizzlerExpireMetadata (0.18s) === RUN TestSwizzlerSetThresholdBaseRole ---- PASS: TestSwizzlerSetThresholdBaseRole (0.09s) +--- PASS: TestSwizzlerSetThresholdBaseRole (0.19s) === RUN TestSwizzlerSetThresholdDelegatedRole ---- PASS: TestSwizzlerSetThresholdDelegatedRole (0.08s) +--- PASS: TestSwizzlerSetThresholdDelegatedRole (0.25s) === RUN TestSwizzlerChangeRootKey ---- PASS: TestSwizzlerChangeRootKey (0.12s) +--- PASS: TestSwizzlerChangeRootKey (0.18s) === RUN TestSwizzlerUpdateSnapshotHashesSpecifiedRoles ---- PASS: TestSwizzlerUpdateSnapshotHashesSpecifiedRoles (0.08s) +--- PASS: TestSwizzlerUpdateSnapshotHashesSpecifiedRoles (0.10s) === RUN TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles ---- PASS: TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles (0.15s) +--- PASS: TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles (0.16s) === RUN TestSwizzlerUpdateTimestamp ---- PASS: TestSwizzlerUpdateTimestamp (0.09s) +--- PASS: TestSwizzlerUpdateTimestamp (0.12s) === RUN TestMissingSigningKey ---- PASS: TestMissingSigningKey (0.08s) +--- PASS: TestMissingSigningKey (0.10s) === RUN TestSwizzlerMutateRoot --- PASS: TestSwizzlerMutateRoot (0.09s) === RUN TestSwizzlerMutateTimestamp ---- PASS: TestSwizzlerMutateTimestamp (0.08s) +--- PASS: TestSwizzlerMutateTimestamp (0.09s) === RUN TestSwizzlerMutateSnapshot --- PASS: TestSwizzlerMutateSnapshot (0.09s) === RUN TestSwizzlerMutateTargets --- PASS: TestSwizzlerMutateTargets (0.08s) === RUN TestSwizzlerRotateKeyBaseRole ---- PASS: TestSwizzlerRotateKeyBaseRole (0.12s) +--- PASS: TestSwizzlerRotateKeyBaseRole (0.13s) === RUN TestSwizzlerRotateKeyDelegationRole ---- PASS: TestSwizzlerRotateKeyDelegationRole (0.08s) +--- PASS: TestSwizzlerRotateKeyDelegationRole (0.09s) PASS -ok github.com/theupdateframework/notary/tuf/testutils 2.157s +ok github.com/theupdateframework/notary/tuf/testutils 3.620s ? github.com/theupdateframework/notary/tuf/testutils/interfaces [no test files] ? github.com/theupdateframework/notary/tuf/testutils/keys [no test files] === RUN TestConvertTUFKeyToPKCS8 ---- PASS: TestConvertTUFKeyToPKCS8 (0.11s) +--- PASS: TestConvertTUFKeyToPKCS8 (0.23s) === RUN TestParsePKCS8ToTufKey ---- PASS: TestParsePKCS8ToTufKey (0.69s) +--- PASS: TestParsePKCS8ToTufKey (1.59s) === RUN TestPEMtoPEM --- PASS: TestPEMtoPEM (0.00s) === RUN TestRoleListLen @@ -3790,29 +3826,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.26s) === RUN TestRSAX509PublickeyID --- PASS: TestRSAX509PublickeyID (0.00s) === 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) === RUN TestValidateCertificateWithShortKey ---- PASS: TestValidateCertificateWithShortKey (0.18s) +--- PASS: TestValidateCertificateWithShortKey (0.29s) PASS -ok github.com/theupdateframework/notary/tuf/utils 1.121s +ok github.com/theupdateframework/notary/tuf/utils 2.523s === RUN TestNewSerializableErrorNonValidationError --- PASS: TestNewSerializableErrorNonValidationError (0.00s) === RUN TestNewSerializableErrorValidationError @@ -3828,7 +3864,7 @@ === RUN TestUnmarshalInvalidJSON --- PASS: TestUnmarshalInvalidJSON (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/validation 0.024s +ok github.com/theupdateframework/notary/tuf/validation 0.038s === RUN TestSetSignalTrap --- PASS: TestSetSignalTrap (0.00s) === RUN TestLogLevelSignalHandle @@ -3928,7 +3964,7 @@ === RUN TestDoAuthWildcardImage --- PASS: TestDoAuthWildcardImage (0.00s) PASS -ok github.com/theupdateframework/notary/utils 0.049s +ok github.com/theupdateframework/notary/utils 0.064s ? github.com/theupdateframework/notary/version [no test files] create-stamp debian/debhelper-build-stamp dh_testroot -O--builddirectory=/build/reproducible-path/notary-0.7.0\+ds1/_build -O--buildsystem=golang @@ -3967,8 +4003,8 @@ dh_md5sums -O--builddirectory=/build/reproducible-path/notary-0.7.0\+ds1/_build -O--buildsystem=golang dh_builddeb -O--builddirectory=/build/reproducible-path/notary-0.7.0\+ds1/_build -O--buildsystem=golang dpkg-deb: building package 'notary' in '../notary_0.7.0+ds1-2_armhf.deb'. -dpkg-deb: building package 'notary-dbgsym' in '../notary-dbgsym_0.7.0+ds1-2_armhf.deb'. dpkg-deb: building package 'golang-github-docker-notary-dev' in '../golang-github-docker-notary-dev_0.7.0+ds1-2_all.deb'. +dpkg-deb: building package 'notary-dbgsym' in '../notary-dbgsym_0.7.0+ds1-2_armhf.deb'. dpkg-genbuildinfo --build=binary -O../notary_0.7.0+ds1-2_armhf.buildinfo dpkg-genchanges --build=binary -O../notary_0.7.0+ds1-2_armhf.changes dpkg-genchanges: info: binary-only upload (no source code included) @@ -3976,12 +4012,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: not including original source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/14746/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/14746/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/26164 and its subdirectories -I: Current time: Sat Jan 6 02:04:49 -12 2024 -I: pbuilder-time-stamp: 1704549889 +I: removing directory /srv/workspace/pbuilder/14746 and its subdirectories +I: Current time: Sun Jan 7 04:18:22 +14 2024 +I: pbuilder-time-stamp: 1704550702