Diff of the two buildlogs: -- --- b1/build.log 2024-01-06 17:39:05.479346977 +0000 +++ b2/build.log 2024-01-06 17:42:33.186332233 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Fri Feb 7 11:59:51 -12 2025 -I: pbuilder-time-stamp: 1738972791 +I: Current time: Sun Jan 7 07:39:09 +14 2024 +I: pbuilder-time-stamp: 1704562749 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bullseye-reproducible-base.tgz] I: copying local configuration @@ -17,7 +17,7 @@ I: copying [./notary_0.6.1~ds2-6.debian.tar.xz] I: Extracting source gpgv: unknown type of key resource 'trustedkeys.kbx' -gpgv: keyblock resource '/tmp/dpkg-verify-sig.MLfSAVRg/trustedkeys.kbx': General error +gpgv: keyblock resource '/tmp/dpkg-verify-sig.higmpcRR/trustedkeys.kbx': General error gpgv: Signature made Sat Apr 18 01:51:52 2020 gpgv: using RSA key 50BC7CF939D20C272A6B065652B6BBD953968D1B gpgv: Can't check signature: No public key @@ -34,49 +34,80 @@ dpkg-source: info: applying remove-use-of-distributions-old-wrapped-context.patch I: using fakeroot in build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/2980511/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/2090345/tmp/hooks/D01_modify_environment starting +debug: Running on ionos11-amd64. +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 17:39 /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/2090345/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/2090345/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='amd64' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all,-fixfilepath parallel=16 ' - DISTRIBUTION='bullseye' - HOME='/root' - HOST_ARCH='amd64' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:hostcomplete:interactive_comments:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="1" [2]="4" [3]="1" [4]="release" [5]="x86_64-pc-linux-gnu") + BASH_VERSION='5.1.4(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=amd64 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all,-fixfilepath parallel=15 ' + DIRSTACK=() + DISTRIBUTION=bullseye + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=x86_64 + HOST_ARCH=amd64 IFS=' ' - INVOCATION_ID='cda6f5a8adbc41f686ac163c71c582ac' - 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='2980511' - PS1='# ' - PS2='> ' + INVOCATION_ID=1e48cafe8db3470db61870f82e73ed87 + LANG=C + LANGUAGE=et_EE:et + LC_ALL=C + MACHTYPE=x86_64-pc-linux-gnu + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnu + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=2090345 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.2AA6BUQq/pbuilderrc_7ABX --distribution bullseye --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.2AA6BUQq/b1 --logfile b1/build.log notary_0.6.1~ds2-6.dsc' - SUDO_GID='111' - SUDO_UID='106' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://85.184.249.68:3128' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.2AA6BUQq/pbuilderrc_oghS --distribution bullseye --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bullseye-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.2AA6BUQq/b2 --logfile b2/build.log notary_0.6.1~ds2-6.dsc' + SUDO_GID=111 + SUDO_UID=106 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://78.137.99.97:3128 I: uname -a - Linux ionos15-amd64 6.5.0-0.deb12.4-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.5.10-1~bpo12+1 (2023-11-23) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-17-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.69-1 (2023-12-30) x86_64 GNU/Linux I: ls -l /bin total 5476 -rwxr-xr-x 1 root root 1234376 Mar 27 2022 bash @@ -136,7 +167,7 @@ -rwxr-xr-x 1 root root 52032 Sep 24 2020 rmdir -rwxr-xr-x 1 root root 27472 Sep 27 2020 run-parts -rwxr-xr-x 1 root root 122224 Dec 22 2018 sed - lrwxrwxrwx 1 root root 4 Jan 8 15:47 sh -> dash + lrwxrwxrwx 1 root root 9 Jan 6 17:39 sh -> /bin/bash -rwxr-xr-x 1 root root 43808 Sep 24 2020 sleep -rwxr-xr-x 1 root root 84928 Sep 24 2020 stty -rwsr-xr-x 1 root root 71912 Jan 20 2022 su @@ -162,7 +193,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/2980511/tmp/hooks/D02_print_environment finished +I: user script /srv/workspace/pbuilder/2090345/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -455,7 +486,7 @@ Get: 203 http://deb.debian.org/debian bullseye/main amd64 golang-gopkg-fatih-pool.v2-dev all 0.0~git20171010.010e0b7-3 [5612 B] Get: 204 http://deb.debian.org/debian bullseye/main amd64 golang-gopkg-gorethink-gorethink.v3-dev all 3.0.5-1.1 [113 kB] Get: 205 http://deb.debian.org/debian bullseye/main amd64 openssl amd64 1.1.1w-0+deb11u1 [859 kB] -Fetched 116 MB in 2s (54.1 MB/s) +Fetched 116 MB in 9s (12.4 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package bsdextrautils. (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 ... 17743 files and directories currently installed.) @@ -1297,7 +1328,11 @@ fakeroot is already the newest version (1.25.3-1.1). 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. I: Building the package -I: Running cd /build/reproducible-path/notary-0.6.1~ds2/ && 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.6.1~ds2-6_source.changes +I: user script /srv/workspace/pbuilder/2090345/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for bullseye +I: user script /srv/workspace/pbuilder/2090345/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/notary-0.6.1~ds2/ && 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.6.1~ds2-6_source.changes dpkg-buildpackage: info: source package notary dpkg-buildpackage: info: source version 0.6.1~ds2-6 dpkg-buildpackage: info: source distribution unstable @@ -1325,15 +1360,15 @@ sed -i '/command -v cfssljson/i exit 0' ./fixtures/regenerateTestingCerts.sh cd fixtures && ./regenerateTestingCerts.sh Generating RSA private key, 4096 bit long modulus (2 primes) -...............++++ -...........................................++++ +...........................................................................................................................................................................................++++ +..............................................++++ e is 65537 (0x010001) Signature ok subject=C = US, ST = CA, L = San Francisco, O = Docker, CN = Notary Testing CA Getting Private key Generating RSA private key, 4096 bit long modulus (2 primes) -.......++++ -................................................................................++++ +................................++++ +.............................................................................................................................................................................................................................................................................................................................................................................................++++ e is 65537 (0x010001) Signature ok subject=C = US, ST = CA, L = San Francisco, O = Docker, CN = Notary Intermediate Testing CA @@ -1361,54 +1396,54 @@ debian/rules override_dh_auto_build make[1]: Entering directory '/build/reproducible-path/notary-0.6.1~ds2' dh_auto_build -- -tags "pkcs11" - cd _build && go install -trimpath -v -p 16 -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 15 -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 -unicode/utf8 runtime/internal/sys -unicode -encoding +vendor/golang.org/x/crypto/cryptobyte/asn1 +unicode/utf8 internal/race -math/bits unicode/utf16 -runtime/internal/atomic +math/bits +encoding +golang.org/x/sys/internal/unsafeheader +internal/nettrace +unicode +crypto/internal/subtle +crypto/subtle internal/cpu sync/atomic +runtime/internal/atomic +runtime/internal/math container/list vendor/golang.org/x/crypto/internal/subtle -runtime/internal/math google.golang.org/grpc/serviceconfig github.com/theupdateframework/notary/version -crypto/subtle -runtime/cgo -vendor/golang.org/x/crypto/cryptobyte/asn1 -golang.org/x/sys/internal/unsafeheader -internal/nettrace -crypto/internal/subtle github.com/lib/pq/oid +runtime/cgo internal/testlog internal/bytealg math runtime internal/reflectlite sync -google.golang.org/grpc/internal/grpcsync internal/singleflight google.golang.org/grpc/internal/buffer +google.golang.org/grpc/internal/grpcsync math/rand errors sort -internal/oserror io -github.com/hashicorp/hcl/hcl/strconv strconv +internal/oserror vendor/golang.org/x/net/dns/dnsmessage +github.com/hashicorp/hcl/hcl/strconv syscall github.com/beorn7/perks/quantile hash +bytes crypto/internal/randutil -text/tabwriter strings -bytes +text/tabwriter crypto/hmac hash/fnv hash/crc32 @@ -1416,78 +1451,78 @@ vendor/golang.org/x/crypto/hkdf crypto crypto/rc4 -encoding/base32 golang.org/x/crypto/blowfish +encoding/base32 reflect vendor/golang.org/x/text/transform -golang.org/x/text/transform github.com/dvsekhvalnov/jose2go/padding -path +golang.org/x/text/transform bufio -google.golang.org/grpc/encoding -github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg +path regexp/syntax +google.golang.org/grpc/encoding html +github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg internal/syscall/unix internal/syscall/execenv time regexp -context +google.golang.org/grpc/internal/grpcrand internal/poll +context google.golang.org/grpc/backoff -google.golang.org/grpc/internal/grpcrand -github.com/bugsnag/bugsnag-go/headers google.golang.org/grpc/keepalive +github.com/bugsnag/bugsnag-go/headers +github.com/jinzhu/inflection google.golang.org/grpc/internal/backoff golang.org/x/net/context google.golang.org/grpc/tap github.com/cenkalti/backoff -github.com/jinzhu/inflection -encoding/binary internal/fmtsort +encoding/binary os -encoding/base64 crypto/md5 +encoding/base64 crypto/cipher -crypto/sha512 crypto/sha1 -crypto/ed25519/internal/edwards25519 vendor/golang.org/x/crypto/poly1305 +crypto/sha512 github.com/cespare/xxhash crypto/sha256 +crypto/ed25519/internal/edwards25519 golang.org/x/sys/unix -encoding/pem -github.com/dvsekhvalnov/jose2go/base64url -github.com/dvsekhvalnov/jose2go/compact fmt path/filepath google.golang.org/grpc/internal/envconfig -os/signal -runtime/debug github.com/bugsnag/bugsnag-go/device net +encoding/pem +os/signal github.com/theupdateframework/notary +runtime/debug +github.com/dvsekhvalnov/jose2go/base64url +github.com/dvsekhvalnov/jose2go/compact crypto/des -crypto/aes vendor/golang.org/x/crypto/chacha20 +crypto/aes io/ioutil github.com/spf13/afero/mem os/exec -vendor/golang.org/x/sys/cpu github.com/prometheus/procfs/internal/util +vendor/golang.org/x/sys/cpu vendor/golang.org/x/crypto/chacha20poly1305 +encoding/json +github.com/docker/go/canonical/json log encoding/hex net/url +math/big mime/quotedprintable -github.com/docker/go/canonical/json -mime -encoding/json compress/flate +mime vendor/golang.org/x/crypto/curve25519 -math/big -vendor/golang.org/x/net/http2/hpack vendor/golang.org/x/text/unicode/norm +vendor/golang.org/x/net/http2/hpack net/http/internal vendor/golang.org/x/text/unicode/bidi github.com/miekg/pkcs11 @@ -1496,87 +1531,86 @@ github.com/hashicorp/hcl/hcl/token golang.org/x/text/unicode/norm text/template/parse -github.com/subosito/gotenv github.com/spf13/jwalterweatherman +github.com/subosito/gotenv gopkg.in/ini.v1 github.com/hashicorp/hcl/hcl/ast +compress/gzip github.com/fsnotify/fsnotify github.com/hashicorp/hcl/hcl/scanner -compress/gzip github.com/hashicorp/hcl/json/token -golang.org/x/net/internal/timeseries -gopkg.in/yaml.v2 vendor/golang.org/x/text/secure/bidirule -google.golang.org/grpc/grpclog +gopkg.in/yaml.v2 +golang.org/x/net/internal/timeseries +github.com/sirupsen/logrus +github.com/theupdateframework/notary/tuf/validation github.com/hashicorp/hcl/json/scanner +github.com/golang/protobuf/proto +google.golang.org/grpc/grpclog google.golang.org/grpc/metadata +github.com/hashicorp/hcl/hcl/parser google.golang.org/grpc/attributes +vendor/golang.org/x/net/idna +github.com/hashicorp/hcl/json/parser +google.golang.org/grpc/connectivity google.golang.org/grpc/codes -github.com/hashicorp/hcl/hcl/parser +text/template google.golang.org/grpc/internal/balancerload -github.com/hashicorp/hcl/json/parser +google.golang.org/grpc/internal golang.org/x/text/unicode/bidi -vendor/golang.org/x/net/idna -google.golang.org/grpc/connectivity golang.org/x/net/http2/hpack +github.com/hashicorp/hcl/hcl/printer github.com/pkg/errors github.com/bugsnag/panicwrap -github.com/sirupsen/logrus -github.com/theupdateframework/notary/tuf/validation -github.com/hashicorp/hcl/hcl/printer -github.com/golang/protobuf/proto -text/template -google.golang.org/grpc/internal -github.com/bugsnag/bugsnag-go/errors github.com/hashicorp/hcl -database/sql/driver crypto/rand crypto/elliptic encoding/asn1 crypto/dsa github.com/pelletier/go-toml -github.com/opencontainers/go-digest -golang.org/x/text/secure/bidirule -github.com/opencontainers/image-spec/specs-go +github.com/bugsnag/bugsnag-go/errors +database/sql/driver github.com/docker/distribution/uuid crypto/rsa crypto/ed25519 +golang.org/x/text/secure/bidirule +github.com/opencontainers/go-digest +github.com/opencontainers/image-spec/specs-go github.com/prometheus/common/model -golang.org/x/net/idna github.com/prometheus/procfs/internal/fs +golang.org/x/net/idna database/sql -golang.org/x/term -golang.org/x/crypto/bcrypt -github.com/docker/distribution/digestset golang.org/x/crypto/ed25519 +github.com/docker/distribution/digestset github.com/opencontainers/image-spec/specs-go/v1 +golang.org/x/term +golang.org/x/crypto/bcrypt github.com/lib/pq/scram os/user go/token -github.com/docker/distribution/reference -golang.org/x/crypto/ssh/terminal -github.com/hailocab/go-hostpool -github.com/theupdateframework/notary/passphrase html/template -gopkg.in/gorethink/gorethink.v3/encoding vendor/golang.org/x/crypto/cryptobyte crypto/x509/pkix +github.com/docker/distribution/reference +github.com/hailocab/go-hostpool +golang.org/x/crypto/ssh/terminal go/scanner +github.com/theupdateframework/notary/passphrase +gopkg.in/gorethink/gorethink.v3/encoding gopkg.in/gorethink/gorethink.v3/types internal/profile runtime/pprof runtime/trace github.com/dvsekhvalnov/jose2go/arrays github.com/davecgh/go-spew/spew +crypto/ecdsa github.com/pmezard/go-difflib/difflib -go/ast github.com/dvsekhvalnov/jose2go/aes +go/ast github.com/dvsekhvalnov/jose2go/kdf +github.com/spf13/cast gopkg.in/yaml.v3 testing -crypto/ecdsa -github.com/spf13/cast -github.com/jinzhu/gorm github.com/golang/protobuf/ptypes/empty google.golang.org/grpc/encoding/proto github.com/golang/protobuf/ptypes/any @@ -1584,112 +1618,113 @@ github.com/golang/protobuf/ptypes/duration github.com/matttproud/golang_protobuf_extensions/pbutil gopkg.in/gorethink/gorethink.v3/ql2 -github.com/prometheus/client_model/go google.golang.org/genproto/googleapis/rpc/status github.com/golang/protobuf/ptypes google.golang.org/grpc/binarylog/grpc_binarylog_v1 +github.com/prometheus/client_model/go +github.com/jinzhu/gorm google.golang.org/grpc/status github.com/prometheus/client_golang/prometheus/internal crypto/x509 -net/textproto vendor/golang.org/x/net/http/httpproxy -google.golang.org/grpc/credentials/internal +net/textproto github.com/mitchellh/mapstructure -google.golang.org/grpc/internal/syscall -google.golang.org/grpc/stats -gopkg.in/fatih/pool.v2 -github.com/gofrs/uuid +google.golang.org/grpc/credentials/internal +google.golang.org/grpc/internal/binarylog google.golang.org/grpc/naming github.com/spf13/pflag -google.golang.org/grpc/internal/binarylog +google.golang.org/grpc/stats +github.com/gofrs/uuid +google.golang.org/grpc/internal/syscall +gopkg.in/fatih/pool.v2 github.com/prometheus/procfs mime/multipart vendor/golang.org/x/net/http/httpguts golang.org/x/net/http/httpguts -github.com/dvsekhvalnov/jose2go/keys/ecc github.com/theupdateframework/notary/tuf/data +github.com/dvsekhvalnov/jose2go/keys/ecc crypto/tls github.com/dvsekhvalnov/jose2go github.com/spf13/cobra github.com/theupdateframework/notary/client/changelist github.com/theupdateframework/notary/tuf/utils net/http/httptrace +gopkg.in/dancannon/gorethink.v3 github.com/docker/go-connections/tlsconfig -google.golang.org/grpc/credentials github.com/docker/libtrust github.com/go-sql-driver/mysql github.com/lib/pq -gopkg.in/dancannon/gorethink.v3 +google.golang.org/grpc/credentials net/http -google.golang.org/grpc/resolver -google.golang.org/grpc/internal/channelz google.golang.org/grpc/peer -google.golang.org/grpc/internal/resolver/dns +google.golang.org/grpc/internal/channelz +google.golang.org/grpc/resolver google.golang.org/grpc/balancer +google.golang.org/grpc/internal/resolver/dns google.golang.org/grpc/internal/resolver/passthrough google.golang.org/grpc/balancer/base google.golang.org/grpc/balancer/roundrobin github.com/theupdateframework/notary/storage/rethinkdb -github.com/docker/distribution/registry/api/errcode -golang.org/x/net/trace +github.com/theupdateframework/notary/storage github.com/magiconair/properties +golang.org/x/net/http2 +github.com/bugsnag/bugsnag-go/sessions net/http/httputil -github.com/theupdateframework/notary/storage -github.com/docker/distribution/registry/client/auth/challenge -github.com/docker/distribution/registry/client/transport +golang.org/x/net/trace github.com/gorilla/mux -expvar -github.com/bugsnag/bugsnag-go/sessions -github.com/docker/distribution/registry/auth -net/http/pprof github.com/spf13/afero -github.com/prometheus/common/expfmt github.com/docker/distribution -golang.org/x/net/http2 +github.com/docker/distribution/registry/api/errcode +github.com/docker/distribution/registry/auth +expvar +github.com/docker/distribution/registry/client/transport +github.com/docker/distribution/registry/client/auth/challenge +github.com/prometheus/common/expfmt +net/http/pprof net/http/httptest -github.com/theupdateframework/notary/server/errors github.com/bugsnag/bugsnag-go -github.com/stretchr/testify/assert github.com/theupdateframework/notary/trustmanager github.com/theupdateframework/notary/server/storage +github.com/theupdateframework/notary/server/errors +github.com/stretchr/testify/assert github.com/docker/distribution/context github.com/docker/distribution/registry/api/v2 -github.com/Shopify/logrus-bugsnag -github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus github.com/theupdateframework/notary/cryptoservice github.com/theupdateframework/notary/tuf/signed -github.com/prometheus/client_golang/prometheus -github.com/spf13/viper +github.com/Shopify/logrus-bugsnag github.com/docker/distribution/health github.com/docker/distribution/registry/auth/htpasswd github.com/docker/distribution/registry/auth/token -github.com/theupdateframework/notary/trustpinning -github.com/theupdateframework/notary/signer/keydbstore +github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus +github.com/prometheus/client_golang/prometheus +github.com/spf13/viper github.com/theupdateframework/notary/trustmanager/yubikey +github.com/theupdateframework/notary/trustpinning github.com/theupdateframework/notary/tuf/testutils/keys -github.com/stretchr/testify/require +github.com/theupdateframework/notary/signer/keydbstore github.com/theupdateframework/notary/tuf +github.com/stretchr/testify/require github.com/theupdateframework/notary/utils -github.com/theupdateframework/notary/tuf/testutils/interfaces -github.com/theupdateframework/notary/server/snapshot +google.golang.org/grpc/internal/transport github.com/theupdateframework/notary/client +github.com/theupdateframework/notary/server/snapshot +github.com/theupdateframework/notary/tuf/testutils/interfaces github.com/theupdateframework/notary/tuf/testutils -github.com/prometheus/client_golang/prometheus/promhttp -google.golang.org/grpc/internal/transport github.com/theupdateframework/notary/server/timestamp +github.com/prometheus/client_golang/prometheus/promhttp github.com/theupdateframework/notary/server/handlers github.com/theupdateframework/notary/server github.com/docker/go-metrics +google.golang.org/grpc github.com/docker/distribution/metrics github.com/docker/distribution/registry/storage/cache github.com/docker/distribution/registry/storage/cache/memory -google.golang.org/grpc github.com/docker/distribution/registry/client github.com/docker/distribution/registry/client/auth github.com/theupdateframework/notary/cmd/notary +github.com/theupdateframework/notary/trustmanager/remoteks google.golang.org/grpc/health/grpc_health_v1 github.com/theupdateframework/notary/proto -github.com/theupdateframework/notary/trustmanager/remoteks google.golang.org/grpc/health github.com/theupdateframework/notary/signer/client github.com/theupdateframework/notary/signer @@ -1699,20 +1734,20 @@ github.com/theupdateframework/notary/cmd/notary-signer make[1]: Leaving directory '/build/reproducible-path/notary-0.6.1~ds2' dh_auto_test -O--builddirectory=/build/reproducible-path/notary-0.6.1\~ds2/_build -O--buildsystem=golang - cd _build && go test -vet=off -v -p 16 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 15 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.01s) +--- PASS: Test0Dot1Migration (0.02s) === RUN Test0Dot3Migration --- PASS: Test0Dot3Migration (0.01s) === RUN Test0Dot1RepoFormat -time="2025-02-08T00:01:05Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot1RepoFormat (0.18s) +time="2024-01-06T17:41:13Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot1RepoFormat (0.30s) === RUN Test0Dot3RepoFormat -time="2025-02-08T00:01:05Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot3RepoFormat (0.17s) +time="2024-01-06T17:41:13Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot3RepoFormat (0.21s) === RUN TestDownloading0Dot1RepoFormat ---- PASS: TestDownloading0Dot1RepoFormat (0.00s) +--- PASS: TestDownloading0Dot1RepoFormat (0.01s) === RUN TestDownloading0Dot3RepoFormat --- PASS: TestDownloading0Dot3RepoFormat (0.01s) === RUN TestInitRepositoryManagedRolesIncludingRoot @@ -1722,100 +1757,100 @@ === RUN TestInitRepositoryManagedRolesIncludingTargets --- PASS: TestInitRepositoryManagedRolesIncludingTargets (0.00s) === RUN TestInitRepositoryManagedRolesIncludingTimestamp ---- PASS: TestInitRepositoryManagedRolesIncludingTimestamp (0.01s) +--- PASS: TestInitRepositoryManagedRolesIncludingTimestamp (0.02s) === RUN TestInitRepositoryWithCerts ---- PASS: TestInitRepositoryWithCerts (0.02s) +--- PASS: TestInitRepositoryWithCerts (0.04s) === RUN TestMatchKeyIDsWithPublicKeys --- PASS: TestMatchKeyIDsWithPublicKeys (0.01s) === RUN TestInitRepositoryNeedsRemoteTimestampKey --- PASS: TestInitRepositoryNeedsRemoteTimestampKey (0.01s) === RUN TestInitRepositoryNeedsRemoteSnapshotKey ---- PASS: TestInitRepositoryNeedsRemoteSnapshotKey (0.01s) +--- PASS: TestInitRepositoryNeedsRemoteSnapshotKey (0.02s) === RUN TestInitRepoServerOnlyManagesTimestampKey ---- PASS: TestInitRepoServerOnlyManagesTimestampKey (0.10s) +--- PASS: TestInitRepoServerOnlyManagesTimestampKey (0.12s) === RUN TestInitRepoServerManagesTimestampAndSnapshotKeys ---- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.07s) +--- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.10s) === RUN TestInitRepoAttemptsExceeded ---- PASS: TestInitRepoAttemptsExceeded (0.07s) +--- PASS: TestInitRepoAttemptsExceeded (0.11s) === RUN TestInitRepoPasswordInvalid ---- PASS: TestInitRepoPasswordInvalid (0.01s) +--- PASS: TestInitRepoPasswordInvalid (0.02s) === RUN TestAddTargetToTargetRoleByDefault ---- PASS: TestAddTargetToTargetRoleByDefault (0.03s) +--- PASS: TestAddTargetToTargetRoleByDefault (0.04s) === RUN TestAddTargetToSpecifiedValidRoles --- PASS: TestAddTargetToSpecifiedValidRoles (0.03s) === RUN TestAddTargetToSpecifiedInvalidRoles ---- PASS: TestAddTargetToSpecifiedInvalidRoles (0.03s) +--- PASS: TestAddTargetToSpecifiedInvalidRoles (0.04s) === RUN TestAddTargetWithInvalidTarget ---- PASS: TestAddTargetWithInvalidTarget (0.01s) +--- PASS: TestAddTargetWithInvalidTarget (0.02s) === RUN TestAddTargetErrorWritingChanges --- PASS: TestAddTargetErrorWritingChanges (0.02s) === RUN TestRemoveTargetToTargetRoleByDefault ---- PASS: TestRemoveTargetToTargetRoleByDefault (0.03s) +--- PASS: TestRemoveTargetToTargetRoleByDefault (0.04s) === RUN TestRemoveTargetFromSpecifiedValidRoles ---- PASS: TestRemoveTargetFromSpecifiedValidRoles (0.03s) +--- PASS: TestRemoveTargetFromSpecifiedValidRoles (0.04s) === RUN TestRemoveTargetToSpecifiedInvalidRoles ---- PASS: TestRemoveTargetToSpecifiedInvalidRoles (0.03s) +--- PASS: TestRemoveTargetToSpecifiedInvalidRoles (0.04s) === RUN TestRemoveTargetErrorWritingChanges ---- PASS: TestRemoveTargetErrorWritingChanges (0.01s) +--- PASS: TestRemoveTargetErrorWritingChanges (0.02s) === RUN TestListTarget ---- PASS: TestListTarget (0.35s) +--- PASS: TestListTarget (0.48s) === RUN TestListTargetRestrictsDelegationPaths ---- PASS: TestListTargetRestrictsDelegationPaths (0.05s) +--- PASS: TestListTargetRestrictsDelegationPaths (0.07s) === RUN TestValidateRootKey ---- PASS: TestValidateRootKey (0.04s) +--- PASS: TestValidateRootKey (0.05s) === RUN TestGetChangelist ---- PASS: TestGetChangelist (0.04s) +--- PASS: TestGetChangelist (0.07s) === RUN TestPublishBareRepo ---- PASS: TestPublishBareRepo (0.36s) +--- PASS: TestPublishBareRepo (0.49s) === RUN TestPublishUninitializedRepo -time="2025-02-08T00:01:06Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" ---- PASS: TestPublishUninitializedRepo (0.03s) +time="2024-01-06T17:41:15Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +--- PASS: TestPublishUninitializedRepo (0.04s) === RUN TestPublishClientHasSnapshotKey ---- PASS: TestPublishClientHasSnapshotKey (0.32s) +--- PASS: TestPublishClientHasSnapshotKey (0.47s) === RUN TestPublishAfterInitServerHasSnapshotKey ---- PASS: TestPublishAfterInitServerHasSnapshotKey (0.28s) +--- PASS: TestPublishAfterInitServerHasSnapshotKey (0.43s) === RUN TestPublishAfterPullServerHasSnapshotKey ---- PASS: TestPublishAfterPullServerHasSnapshotKey (0.11s) +--- PASS: TestPublishAfterPullServerHasSnapshotKey (0.15s) === RUN TestPublishNoOneHasSnapshotKey ---- PASS: TestPublishNoOneHasSnapshotKey (0.07s) +--- PASS: TestPublishNoOneHasSnapshotKey (0.10s) === RUN TestPublishSnapshotCorrupt ---- PASS: TestPublishSnapshotCorrupt (0.16s) +--- PASS: TestPublishSnapshotCorrupt (0.25s) === RUN TestPublishTargetsCorrupt ---- PASS: TestPublishTargetsCorrupt (0.08s) +--- PASS: TestPublishTargetsCorrupt (0.13s) === RUN TestPublishRootCorrupt -time="2025-02-08T00:01:08Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" -time="2025-02-08T00:01:08Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" ---- PASS: TestPublishRootCorrupt (0.06s) +time="2024-01-06T17:41:17Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +time="2024-01-06T17:41:17Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +--- PASS: TestPublishRootCorrupt (0.09s) === RUN TestPublishSnapshotLocalKeysCreatedFirst ---- PASS: TestPublishSnapshotLocalKeysCreatedFirst (0.00s) +--- PASS: TestPublishSnapshotLocalKeysCreatedFirst (0.01s) === RUN TestPublishDelegations ---- PASS: TestPublishDelegations (0.11s) +--- PASS: TestPublishDelegations (0.18s) === RUN TestPublishDelegationsX509 ---- PASS: TestPublishDelegationsX509 (0.12s) +--- PASS: TestPublishDelegationsX509 (0.16s) === RUN TestPublishTargetsDelegationScopeFailIfNoKeys -time="2025-02-08T00:01:08Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" -time="2025-02-08T00:01:08Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" ---- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (0.09s) +time="2024-01-06T17:41:17Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" +time="2024-01-06T17:41:17Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" +--- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (0.16s) === RUN TestPublishTargetsDelegationSuccessLocallyHasRoles ---- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (0.10s) +--- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (0.14s) === RUN TestPublishTargetsDelegationNoTargetsKeyNeeded ---- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (0.13s) +--- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (0.17s) === RUN TestPublishTargetsDelegationSuccessNeedsToDownloadRoles ---- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (0.12s) +--- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (0.17s) === RUN TestPublishTargetsDelegationFromTwoRepos ---- PASS: TestPublishTargetsDelegationFromTwoRepos (0.12s) +--- PASS: TestPublishTargetsDelegationFromTwoRepos (0.17s) === RUN TestPublishRemoveDelegationKeyFromDelegationRole -time="2025-02-08T00:01:08Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-02-08T00:01: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: 0f6391fb94ce3a2c4a1fee3d7a74aa9311c3c5c346afaf494892f8458e8ea9d0" ---- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.08s) +time="2024-01-06T17:41:18Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T17:41:18Z" 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: 4c08a60a5787144262bbc90fb4f02a82af3f7271f388021d409640e650874c76" +--- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.11s) === RUN TestPublishRemoveDelegation -time="2025-02-08T00:01:08Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-02-08T00:01:08Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-02-08T00:01:08Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-02-08T00:01: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.08s) +time="2024-01-06T17:41:18Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-01-06T17:41:18Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-01-06T17:41:18Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T17:41:18Z" 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.11s) === RUN TestPublishSucceedsDespiteDelegationCorrupt ---- PASS: TestPublishSucceedsDespiteDelegationCorrupt (0.09s) +--- PASS: TestPublishSucceedsDespiteDelegationCorrupt (0.15s) === RUN TestRotateKeyInvalidRole --- PASS: TestRotateKeyInvalidRole (0.05s) === RUN TestRemoteRotationError @@ -1823,39 +1858,39 @@ === RUN TestRemoteRotationEndpointError --- PASS: TestRemoteRotationEndpointError (0.03s) === RUN TestRemoteRotationNoRootKey ---- PASS: TestRemoteRotationNoRootKey (0.05s) +--- PASS: TestRemoteRotationNoRootKey (0.07s) === RUN TestRemoteRotationNoInit -time="2025-02-08T00:01:09Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" ---- PASS: TestRemoteRotationNoInit (0.03s) +time="2024-01-06T17:41:19Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +--- PASS: TestRemoteRotationNoInit (0.05s) === RUN TestRotateBeforePublishFromRemoteKeyToLocalKey ---- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (0.13s) +--- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (0.16s) === RUN TestRotateKeyAfterPublishNoServerManagementChange ---- PASS: TestRotateKeyAfterPublishNoServerManagementChange (0.36s) +--- PASS: TestRotateKeyAfterPublishNoServerManagementChange (0.56s) === RUN TestRotateKeyAfterPublishServerManagementChange ---- PASS: TestRotateKeyAfterPublishServerManagementChange (0.41s) +--- PASS: TestRotateKeyAfterPublishServerManagementChange (0.56s) === RUN TestRotateRootKey ---- PASS: TestRotateRootKey (0.11s) +--- PASS: TestRotateRootKey (0.15s) === RUN TestRotateRootMultiple ---- PASS: TestRotateRootMultiple (0.14s) +--- PASS: TestRotateRootMultiple (0.21s) === RUN TestRotateRootKeyProvided ---- PASS: TestRotateRootKeyProvided (0.12s) +--- PASS: TestRotateRootKeyProvided (0.17s) === RUN TestRotateRootKeyLegacySupport ---- PASS: TestRotateRootKeyLegacySupport (0.16s) +--- PASS: TestRotateRootKeyLegacySupport (0.26s) === RUN TestRemoteServerUnavailableNoLocalCache -time="2025-02-08T00:01:10Z" level=error msg="Could not publish Repository since we could not update: unable to reach trust server at this time: 500." ---- PASS: TestRemoteServerUnavailableNoLocalCache (0.00s) +time="2024-01-06T17:41:21Z" level=error msg="Could not publish Repository since we could not update: unable to reach trust server at this time: 500." +--- PASS: TestRemoteServerUnavailableNoLocalCache (0.01s) === RUN TestAddDelegationChangefileValid --- PASS: TestAddDelegationChangefileValid (0.02s) === RUN TestAddDelegationChangefileApplicable ---- PASS: TestAddDelegationChangefileApplicable (0.01s) +--- PASS: TestAddDelegationChangefileApplicable (0.02s) === RUN TestAddDelegationErrorWritingChanges --- PASS: TestAddDelegationErrorWritingChanges (0.02s) === RUN TestRemoveDelegationChangefileValid ---- PASS: TestRemoveDelegationChangefileValid (0.02s) +--- PASS: TestRemoveDelegationChangefileValid (0.01s) === RUN TestRemoveDelegationChangefileApplicable -time="2025-02-08T00:01:10Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-02-08T00:01:10Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" ---- PASS: TestRemoveDelegationChangefileApplicable (0.01s) +time="2024-01-06T17:41:21Z" 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-06T17:41:21Z" 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.03s) === RUN TestClearAllPathsDelegationChangefileApplicable --- PASS: TestClearAllPathsDelegationChangefileApplicable (0.02s) === RUN TestFullAddDelegationChangefileApplicable @@ -1863,177 +1898,177 @@ === RUN TestFullRemoveDelegationChangefileApplicable --- PASS: TestFullRemoveDelegationChangefileApplicable (0.02s) === RUN TestRemoveDelegationErrorWritingChanges ---- PASS: TestRemoveDelegationErrorWritingChanges (0.01s) +--- PASS: TestRemoveDelegationErrorWritingChanges (0.02s) === RUN TestBootstrapClientBadURL --- PASS: TestBootstrapClientBadURL (0.00s) === RUN TestClientInvalidURL --- PASS: TestClientInvalidURL (0.00s) === RUN TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole ---- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (0.21s) +--- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (0.32s) === RUN TestDeleteRepo ---- PASS: TestDeleteRepo (0.03s) +--- PASS: TestDeleteRepo (0.04s) === RUN TestDeleteRemoteRepo ---- PASS: TestDeleteRemoteRepo (0.08s) +--- PASS: TestDeleteRemoteRepo (0.12s) === RUN TestListRoles ---- PASS: TestListRoles (0.14s) +--- PASS: TestListRoles (0.23s) === RUN TestGetAllTargetInfo ---- PASS: TestGetAllTargetInfo (0.08s) +--- PASS: TestGetAllTargetInfo (0.12s) === RUN TestUpdateSucceedsEvenIfCannotWriteNewRepo -time="2025-02-08T00:01:11Z" level=error msg="could not save root to cache: Non-writable" ---- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.06s) +time="2024-01-06T17:41:22Z" level=error msg="could not save root to cache: Non-writable" +--- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.12s) === RUN TestUpdateSucceedsEvenIfCannotWriteExistingRepo ---- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (0.27s) +--- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (0.43s) === RUN TestUpdateInOfflineMode -time="2025-02-08T00:01:11Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" -time="2025-02-08T00:01:11Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateInOfflineMode (0.02s) +time="2024-01-06T17:41:22Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +time="2024-01-06T17:41:22Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateInOfflineMode (0.03s) === RUN TestUpdateReplacesCorruptOrMissingMetadata -time="2025-02-08T00:01:11Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:11Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateReplacesCorruptOrMissingMetadata (0.49s) +time="2024-01-06T17:41:23Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:23Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateReplacesCorruptOrMissingMetadata (0.78s) === RUN TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign -time="2025-02-08T00:01:12Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:12Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.05s) +time="2024-01-06T17:41:23Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:23Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.10s) === RUN TestUpdateRemoteRootNotExistNoLocalCache ---- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.01s) +--- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.02s) === RUN TestUpdateRemoteRootNotExistCanUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.04s) +--- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.06s) === RUN TestUpdateRemoteRootNotExistCannotUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.04s) +--- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.07s) === RUN TestUpdateRemoteRoot50XNoLocalCache --- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.02s) === RUN TestUpdateRemoteRoot50XCanUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.04s) +--- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.05s) === RUN TestUpdateRemoteRoot50XCannotUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.04s) +--- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.07s) === RUN TestUpdateNonRootRemoteMissingMetadataNoLocalCache ---- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (0.09s) +--- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (0.18s) === RUN TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache -time="2025-02-08T00:01:12Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (0.12s) +time="2024-01-06T17:41:24Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (0.20s) === RUN TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache -time="2025-02-08T00:01:12Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (0.16s) +time="2024-01-06T17:41:24Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (0.26s) === RUN TestUpdateNonRootRemote50XNoLocalCache ---- PASS: TestUpdateNonRootRemote50XNoLocalCache (0.09s) +--- PASS: TestUpdateNonRootRemote50XNoLocalCache (0.15s) === RUN TestUpdateNonRootRemote50XCanUseLocalCache -time="2025-02-08T00:01:12Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (0.12s) +time="2024-01-06T17:41:24Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (0.21s) === RUN TestUpdateNonRootRemote50XCannotUseLocalCache -time="2025-02-08T00:01:13Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (0.17s) +time="2024-01-06T17:41:25Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (0.29s) === RUN TestUpdateRemoteChecksumWrongNoLocalCache ---- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (0.12s) +--- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (0.18s) === RUN TestUpdateRemoteChecksumWrongCanUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (0.16s) +--- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (0.24s) === RUN TestUpdateRemoteChecksumWrongCannotUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (0.23s) +--- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (0.38s) === RUN TestUpdateRootRemoteCorruptedNoLocalCache -time="2025-02-08T00:01:13Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:13Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (0.35s) +time="2024-01-06T17:41:26Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:26Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (0.52s) === RUN TestUpdateRootRemoteCorruptedCanUseLocalCache ---- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (0.75s) +--- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (1.24s) === RUN TestUpdateRootRemoteCorruptedCannotUseLocalCache -time="2025-02-08T00:01:15Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:15Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (0.89s) +time="2024-01-06T17:41:28Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:28Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (1.49s) === RUN TestUpdateNonRootRemoteCorruptedNoLocalCache -time="2025-02-08T00:01:15Z" level=error msg="Metadata for targets expired" -time="2025-02-08T00:01:15Z" level=error msg="Metadata for targets expired" -time="2025-02-08T00:01:15Z" level=error msg="Metadata for snapshot expired" -time="2025-02-08T00:01:16Z" level=error msg="Metadata for snapshot expired" -time="2025-02-08T00:01:16Z" level=error msg="Metadata for timestamp expired" -time="2025-02-08T00:01:16Z" level=error msg="Metadata for timestamp expired" -time="2025-02-08T00:01:16Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-02-08T00:01:16Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-02-08T00:01:16Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-02-08T00:01:16Z" level=error msg="Metadata for targets/a expired" -time="2025-02-08T00:01:16Z" level=warning msg="Error getting targets/a: targets/a expired at Sun Jan 7 00:01:16 UTC 2024" -time="2025-02-08T00:01:16Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-02-08T00:01:16Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-02-08T00:01:16Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-02-08T00:01:16Z" level=error msg="Metadata for targets/a/b expired" -time="2025-02-08T00:01:16Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Sun Jan 7 00:01:16 UTC 2024" -time="2025-02-08T00:01:16Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-02-08T00:01:16Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-02-08T00:01:16Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-02-08T00:01:16Z" level=error msg="Metadata for targets/b expired" -time="2025-02-08T00:01:16Z" level=warning msg="Error getting targets/b: targets/b expired at Sun Jan 7 00:01:16 UTC 2024" ---- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (0.93s) +time="2024-01-06T17:41:29Z" level=error msg="Metadata for targets expired" +time="2024-01-06T17:41:29Z" level=error msg="Metadata for targets expired" +time="2024-01-06T17:41:29Z" level=error msg="Metadata for snapshot expired" +time="2024-01-06T17:41:29Z" level=error msg="Metadata for snapshot expired" +time="2024-01-06T17:41:30Z" level=error msg="Metadata for timestamp expired" +time="2024-01-06T17:41:30Z" level=error msg="Metadata for timestamp expired" +time="2024-01-06T17:41:30Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T17:41:30Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T17:41:30Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T17:41:30Z" level=error msg="Metadata for targets/a expired" +time="2024-01-06T17:41:30Z" level=warning msg="Error getting targets/a: targets/a expired at Mon Dec 5 17:41:30 UTC 2022" +time="2024-01-06T17:41:30Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-01-06T17:41:30Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-01-06T17:41:30Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-01-06T17:41:30Z" level=error msg="Metadata for targets/a/b expired" +time="2024-01-06T17:41:30Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Mon Dec 5 17:41:30 UTC 2022" +time="2024-01-06T17:41:30Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-01-06T17:41:30Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-01-06T17:41:30Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-01-06T17:41:30Z" level=error msg="Metadata for targets/b expired" +time="2024-01-06T17:41:30Z" level=warning msg="Error getting targets/b: targets/b expired at Mon Dec 5 17:41:30 UTC 2022" +--- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (1.61s) === RUN TestUpdateNonRootRemoteCorruptedCanUseLocalCache -time="2025-02-08T00:01:17Z" level=error msg="Metadata for timestamp expired" -time="2025-02-08T00:01:17Z" level=error msg="Metadata for timestamp expired" ---- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (1.21s) +time="2024-01-06T17:41:31Z" level=error msg="Metadata for timestamp expired" +time="2024-01-06T17:41:31Z" level=error msg="Metadata for timestamp expired" +--- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (1.97s) === RUN TestUpdateNonRootRemoteCorruptedCannotUseLocalCache -time="2025-02-08T00:01:18Z" level=error msg="Metadata for targets expired" -time="2025-02-08T00:01:18Z" level=error msg="Metadata for snapshot expired" -time="2025-02-08T00:01:18Z" level=error msg="Metadata for snapshot expired" -time="2025-02-08T00:01:18Z" level=error msg="Metadata for timestamp expired" -time="2025-02-08T00:01:18Z" level=error msg="Metadata for timestamp expired" -time="2025-02-08T00:01:18Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-02-08T00:01:18Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-02-08T00:01:18Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-02-08T00:01:18Z" level=error msg="Metadata for targets/a expired" -time="2025-02-08T00:01:18Z" level=warning msg="Error getting targets/a: targets/a expired at Sun Jan 7 00:01:18 UTC 2024" -time="2025-02-08T00:01:19Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-02-08T00:01:19Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-02-08T00:01:19Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-02-08T00:01:19Z" level=error msg="Metadata for targets/a/b expired" -time="2025-02-08T00:01:19Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Sun Jan 7 00:01:19 UTC 2024" -time="2025-02-08T00:01:19Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-02-08T00:01:19Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-02-08T00:01:19Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2025-02-08T00:01:19Z" level=error msg="Metadata for targets/b expired" -time="2025-02-08T00:01:19Z" level=warning msg="Error getting targets/b: targets/b expired at Sun Jan 7 00:01:19 UTC 2024" ---- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (1.73s) +time="2024-01-06T17:41:33Z" level=error msg="Metadata for targets expired" +time="2024-01-06T17:41:33Z" level=error msg="Metadata for snapshot expired" +time="2024-01-06T17:41:33Z" level=error msg="Metadata for snapshot expired" +time="2024-01-06T17:41:34Z" level=error msg="Metadata for timestamp expired" +time="2024-01-06T17:41:34Z" level=error msg="Metadata for timestamp expired" +time="2024-01-06T17:41:34Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T17:41:34Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T17:41:34Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T17:41:34Z" level=error msg="Metadata for targets/a expired" +time="2024-01-06T17:41:34Z" level=warning msg="Error getting targets/a: targets/a expired at Mon Dec 5 17:41:34 UTC 2022" +time="2024-01-06T17:41:34Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-01-06T17:41:35Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-01-06T17:41:35Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-01-06T17:41:35Z" level=error msg="Metadata for targets/a/b expired" +time="2024-01-06T17:41:35Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Mon Dec 5 17:41:35 UTC 2022" +time="2024-01-06T17:41:35Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-01-06T17:41:35Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-01-06T17:41:35Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2024-01-06T17:41:35Z" level=error msg="Metadata for targets/b expired" +time="2024-01-06T17:41:35Z" level=warning msg="Error getting targets/b: targets/b expired at Mon Dec 5 17:41:35 UTC 2022" +--- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (2.92s) === RUN TestUpdateLocalAndRemoteRootCorrupt -time="2025-02-08T00:01:20Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:20Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" -time="2025-02-08T00:01:21Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateLocalAndRemoteRootCorrupt (1.87s) +time="2024-01-06T17:41:37Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:37Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:38Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateLocalAndRemoteRootCorrupt (3.03s) === RUN TestUpdateRemoteKeyRotated -time="2025-02-08T00:01:21Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2025-02-08T00:01:21Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2025-02-08T00:01:21Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" ---- PASS: TestUpdateRemoteKeyRotated (0.17s) +time="2024-01-06T17:41:39Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2024-01-06T17:41:39Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2024-01-06T17:41:39Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +--- PASS: TestUpdateRemoteKeyRotated (0.30s) === RUN TestValidateRootRotationWithOldRole ---- PASS: TestValidateRootRotationWithOldRole (0.10s) +--- PASS: TestValidateRootRotationWithOldRole (0.14s) === RUN TestRootRoleInvariant ---- PASS: TestRootRoleInvariant (0.08s) +--- PASS: TestRootRoleInvariant (0.15s) === RUN TestBadIntermediateTransitions ---- PASS: TestBadIntermediateTransitions (0.03s) +--- PASS: TestBadIntermediateTransitions (0.05s) === RUN TestExpiredIntermediateTransitions -time="2025-02-08T00:01:21Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" ---- PASS: TestExpiredIntermediateTransitions (0.03s) +time="2024-01-06T17:41:39Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" +--- PASS: TestExpiredIntermediateTransitions (0.06s) === RUN TestDownloadTargetsLarge ---- PASS: TestDownloadTargetsLarge (4.20s) +--- PASS: TestDownloadTargetsLarge (5.74s) === RUN TestDownloadTargetsDeep ---- PASS: TestDownloadTargetsDeep (0.02s) +--- PASS: TestDownloadTargetsDeep (0.03s) === RUN TestDownloadSnapshotLargeDelegationsMany ---- PASS: TestDownloadSnapshotLargeDelegationsMany (3.25s) +--- PASS: TestDownloadSnapshotLargeDelegationsMany (3.97s) === RUN TestRootOnDiskTrustPinning ---- PASS: TestRootOnDiskTrustPinning (0.02s) +--- PASS: TestRootOnDiskTrustPinning (0.03s) === RUN TestApplyTargetsChange --- PASS: TestApplyTargetsChange (0.00s) === RUN TestApplyAddTargetTwice @@ -2071,14 +2106,14 @@ === RUN TestApplyChangelistTargetsToMultipleRoles --- PASS: TestApplyChangelistTargetsToMultipleRoles (0.00s) === RUN TestApplyChangelistTargetsFailsNonexistentRole -time="2025-02-08T00:01:29Z" level=error msg="couldn't add target to targets/level1/level2/level3/level4: tuf: invalid role targets/level1/level2/level3/level4. delegation does not exist" -time="2025-02-08T00:01:29Z" 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-06T17:41:49Z" 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-06T17:41:49Z" level=error msg="couldn't remove target from targets/level1/level2/level3/level4: tuf: invalid role targets/level1/level2/level3/level4. delegation does not exist" --- PASS: TestApplyChangelistTargetsFailsNonexistentRole (0.00s) === RUN TestChangeTargetMetaFailsInvalidRole -time="2025-02-08T00:01:29Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" +time="2024-01-06T17:41:49Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" --- PASS: TestChangeTargetMetaFailsInvalidRole (0.00s) === RUN TestChangeTargetMetaFailsIfPrefixError -time="2025-02-08T00:01:29Z" level=error msg="couldn't add target to targets/level1: Could not add all targets" +time="2024-01-06T17:41:49Z" level=error msg="couldn't add target to targets/level1: Could not add all targets" --- PASS: TestChangeTargetMetaFailsIfPrefixError (0.00s) === RUN TestAllNearExpiry --- PASS: TestAllNearExpiry (0.00s) @@ -2087,7 +2122,7 @@ === RUN TestRotateRemoteKeyOffline --- PASS: TestRotateRemoteKeyOffline (0.00s) PASS -ok github.com/theupdateframework/notary/client 24.257s +ok github.com/theupdateframework/notary/client 36.219s === RUN TestTUFDelegation --- PASS: TestTUFDelegation (0.00s) === RUN TestMemChangelist @@ -2099,14 +2134,14 @@ === RUN TestAdd --- PASS: TestAdd (0.00s) === RUN TestErrorConditions -time="2025-02-08T00:01:01Z" level=warning msg="invalid character '\\x05' looking for beginning of value" +time="2024-01-06T17:41:06Z" 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.018s +ok github.com/theupdateframework/notary/client/changelist 0.016s === RUN TestParseConfigError --- PASS: TestParseConfigError (0.00s) === RUN TestSetupGRPCServer @@ -2116,7 +2151,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.030s === RUN TestPurgeDelegationKeys Usage: delegation [command] @@ -2142,7 +2177,7 @@ - abc --- PASS: TestPurgeDelegationKeys (0.00s) === RUN TestAddInvalidDelegationName ---- PASS: TestAddInvalidDelegationName (0.02s) +--- PASS: TestAddInvalidDelegationName (0.03s) === RUN TestAddInvalidDelegationCert --- PASS: TestAddInvalidDelegationCert (0.00s) === RUN TestAddInvalidShortPubkeyCert @@ -2188,77 +2223,77 @@ Use "delegation [command] --help" for more information about a command. --- PASS: TestRemoveInvalidNumArgs (0.00s) === RUN TestInitWithRootKey ---- PASS: TestInitWithRootKey (0.11s) +--- PASS: TestInitWithRootKey (0.13s) === RUN TestInitWithRootCert ---- PASS: TestInitWithRootCert (0.08s) +--- PASS: TestInitWithRootCert (0.09s) === RUN TestClientTUFInteraction ---- PASS: TestClientTUFInteraction (0.14s) +--- PASS: TestClientTUFInteraction (0.25s) === RUN TestClientDeleteTUFInteraction -time="2025-02-08T00:01:05Z" 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]:40020->[::1]:53: read: connection refused" ---- PASS: TestClientDeleteTUFInteraction (0.16s) +time="2024-01-06T17:41:13Z" 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]:40632->[::1]:53: read: connection refused" +--- PASS: TestClientDeleteTUFInteraction (0.29s) === RUN TestClientTUFAddByHashInteraction ---- PASS: TestClientTUFAddByHashInteraction (0.25s) +--- PASS: TestClientTUFAddByHashInteraction (0.37s) === RUN TestClientDelegationsInteraction -time="2025-02-08T00:01:06Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-02-08T00:01:06Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" ---- PASS: TestClientDelegationsInteraction (0.61s) +time="2024-01-06T17:41:14Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-01-06T17:41:14Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +--- PASS: TestClientDelegationsInteraction (0.90s) === RUN TestClientDelegationsPublishing ---- PASS: TestClientDelegationsPublishing (0.55s) +--- PASS: TestClientDelegationsPublishing (0.81s) === RUN TestClientKeyGenerationRotation ---- PASS: TestClientKeyGenerationRotation (0.67s) +--- PASS: TestClientKeyGenerationRotation (1.12s) === RUN TestKeyRotation ---- PASS: TestKeyRotation (0.10s) +--- PASS: TestKeyRotation (0.16s) === RUN TestKeyRotationNonRoot ---- PASS: TestKeyRotationNonRoot (0.13s) +--- PASS: TestKeyRotationNonRoot (0.22s) === RUN TestDefaultRootKeyGeneration ---- PASS: TestDefaultRootKeyGeneration (0.00s) +--- PASS: TestDefaultRootKeyGeneration (0.01s) === RUN TestLogLevelFlags --- PASS: TestLogLevelFlags (0.00s) === RUN TestClientKeyPassphraseChange ---- PASS: TestClientKeyPassphraseChange (0.12s) +--- PASS: TestClientKeyPassphraseChange (0.19s) === RUN TestPurgeSingleKey Removal of the following keys from all delegations in gun staged for next publish: - - e68bea096b71147c42acab40b99ea0615a25aeffd39c12b8b3622effdbf3281e ---- PASS: TestPurgeSingleKey (0.11s) + - 27926982081002dc05d2daeaeefe05a02d4b520cd66ebbe0a08c6ea69b664118 +--- PASS: TestPurgeSingleKey (0.16s) === RUN TestWitness -time="2025-02-08T00:01:08Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2025-02-08T00:01:08Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2025-02-08T00:01:08Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2025-02-08T00:01:08Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2025-02-08T00:01:08Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-02-08T00:01:08Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-02-08T00:01:08Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" ---- PASS: TestWitness (0.33s) +time="2024-01-06T17:41:17Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-01-06T17:41:17Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-01-06T17:41:18Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-01-06T17:41:18Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2024-01-06T17:41:18Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-01-06T17:41:18Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-01-06T17:41:18Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +--- PASS: TestWitness (0.51s) === RUN TestClientTUFInitWithAutoPublish -time="2025-02-08T00:01:08Z" 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]:46073->[::1]:53: read: connection refused" ---- PASS: TestClientTUFInitWithAutoPublish (0.09s) +time="2024-01-06T17:41: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]:59634->[::1]:53: read: connection refused" +--- PASS: TestClientTUFInitWithAutoPublish (0.14s) === RUN TestClientTUFAddWithAutoPublish -time="2025-02-08T00:01:08Z" 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]:47552->[::1]:53: read: connection refused" -time="2025-02-08T00:01:08Z" level=error msg="Could not publish Repository since we could not update: client is offline" -time="2025-02-08T00:01:08Z" 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]:51426->[::1]:53: read: connection refused" -time="2025-02-08T00:01:08Z" level=error msg="Could not publish Repository since we could not update: client is offline" ---- PASS: TestClientTUFAddWithAutoPublish (0.09s) +time="2024-01-06T17:41: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]:41078->[::1]:53: read: connection refused" +time="2024-01-06T17:41:18Z" level=error msg="Could not publish Repository since we could not update: client is offline" +time="2024-01-06T17:41: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]:48638->[::1]:53: read: connection refused" +time="2024-01-06T17:41:18Z" level=error msg="Could not publish Repository since we could not update: client is offline" +--- PASS: TestClientTUFAddWithAutoPublish (0.14s) === RUN TestClientTUFRemoveWithAutoPublish ---- PASS: TestClientTUFRemoveWithAutoPublish (0.17s) +--- PASS: TestClientTUFRemoveWithAutoPublish (0.29s) === RUN TestClientDelegationAddWithAutoPublish ---- PASS: TestClientDelegationAddWithAutoPublish (0.08s) +--- PASS: TestClientDelegationAddWithAutoPublish (0.13s) === RUN TestClientDelegationRemoveWithAutoPublish -time="2025-02-08T00:01:09Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-02-08T00:01:09Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" ---- PASS: TestClientDelegationRemoveWithAutoPublish (0.16s) +time="2024-01-06T17:41:19Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-01-06T17:41:19Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +--- PASS: TestClientDelegationRemoveWithAutoPublish (0.21s) === RUN TestClientTUFAddByHashWithAutoPublish ---- PASS: TestClientTUFAddByHashWithAutoPublish (0.08s) +--- PASS: TestClientTUFAddByHashWithAutoPublish (0.11s) === RUN TestClientKeyImport -time="2025-02-08T00:01:09Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" -time="2025-02-08T00:01:09Z" 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.03s) +time="2024-01-06T17:41:19Z" 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-06T17:41:19Z" level=warning msg="failed to import key () to store: Cannot have canonical role key without a gun, don't know what gun it belongs to" +--- PASS: TestClientKeyImport (0.04s) === RUN TestAddDelImportKeyPublishFlow -time="2025-02-08T00:01:09Z" level=error msg="couldn't add target to targets/releases: could not find necessary signing keys, at least one of these keys must be available: 0570f9fdca00bdaf62f3f79bf84e70748ba2c8bff926f20dd996bac49e6164fc" ---- PASS: TestAddDelImportKeyPublishFlow (0.19s) +time="2024-01-06T17:41:19Z" level=error msg="couldn't add target to targets/releases: could not find necessary signing keys, at least one of these keys must be available: 0570f9fdca00bdaf62f3f79bf84e70748ba2c8bff926f20dd996bac49e6164fc" +--- PASS: TestAddDelImportKeyPublishFlow (0.28s) === RUN TestExportImportFlow ---- PASS: TestExportImportFlow (0.06s) +--- PASS: TestExportImportFlow (0.09s) === RUN TestDelegationKeyImportExport ---- PASS: TestDelegationKeyImportExport (0.00s) +--- PASS: TestDelegationKeyImportExport (0.01s) === RUN TestImportKeysNoYubikey --- PASS: TestImportKeysNoYubikey (0.01s) === RUN TestExportImportKeysNoYubikey @@ -2276,16 +2311,16 @@ === RUN TestRemoveMultikeysRemoveOnlyChosenKey --- PASS: TestRemoveMultikeysRemoveOnlyChosenKey (0.01s) === RUN TestRotateKeyInvalidRoles -time="2025-02-08T00:01:09Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:41264->[::1]:53: read: connection refused" -time="2025-02-08T00:01:09Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:53419->[::1]:53: read: connection refused" -time="2025-02-08T00:01:09Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:53742->[::1]:53: read: connection refused" -time="2025-02-08T00:01:09Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:46599->[::1]:53: read: connection refused" +time="2024-01-06T17:41:19Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:58873->[::1]:53: read: connection refused" +time="2024-01-06T17:41:19Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:42937->[::1]:53: read: connection refused" +time="2024-01-06T17:41:19Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:46284->[::1]:53: read: connection refused" +time="2024-01-06T17:41:19Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:54196->[::1]:53: read: connection refused" --- PASS: TestRotateKeyInvalidRoles (0.00s) === RUN TestRotateKeyTargetCannotBeServerManaged -time="2025-02-08T00:01:09Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:49199->[::1]:53: read: connection refused" +time="2024-01-06T17:41:19Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:40743->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTargetCannotBeServerManaged (0.00s) === RUN TestRotateKeyTimestampCannotBeLocallyManaged -time="2025-02-08T00:01:09Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:51371->[::1]:53: read: connection refused" +time="2024-01-06T17:41:19Z" level=error msg="could not reach https://notary-server:4443: Get \"https://notary-server:4443/v2/\": dial tcp: lookup notary-server on [::1]:53: read udp [::1]:40176->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTimestampCannotBeLocallyManaged (0.00s) === RUN TestRotateKeyNoGUN Usage: @@ -2293,13 +2328,13 @@ === RUN TestRotateKeyRemoteServerManagesKey Successfully rotated snapshot key for repository docker.com/notary Successfully rotated timestamp key for repository docker.com/notary ---- PASS: TestRotateKeyRemoteServerManagesKey (0.09s) +--- PASS: TestRotateKeyRemoteServerManagesKey (0.14s) === RUN TestRotateKeyBothKeys Successfully rotated targets key for repository docker.com/notary Successfully rotated snapshot key for repository docker.com/notary ---- PASS: TestRotateKeyBothKeys (0.08s) +--- PASS: TestRotateKeyBothKeys (0.10s) === RUN TestRotateKeyRootIsInteractive ---- PASS: TestRotateKeyRootIsInteractive (0.02s) +--- PASS: TestRotateKeyRootIsInteractive (0.04s) === RUN TestChangeKeyPassphraseInvalidID --- PASS: TestChangeKeyPassphraseInvalidID (0.00s) === RUN TestChangeKeyPassphraseInvalidNumArgs @@ -2318,8 +2353,8 @@ === RUN TestImportKeysNonexistentFile --- PASS: TestImportKeysNonexistentFile (0.00s) === RUN TestKeyGeneration -time="2025-02-08T00:01:09Z" level=warning msg="failed to import key () to store: Cannot have canonical role key without a gun, don't know what gun it belongs to" ---- PASS: TestKeyGeneration (0.01s) +time="2024-01-06T17:41:20Z" level=warning msg="failed to import key () to store: Cannot have canonical role key without a gun, don't know what gun it belongs to" +--- PASS: TestKeyGeneration (0.02s) === RUN TestNotaryConfigFileDefault --- PASS: TestNotaryConfigFileDefault (0.00s) === RUN TestRemoteServerDefault @@ -2339,9 +2374,9 @@ === RUN TestConfigFileTLSCannotBeRelativeToCWD --- PASS: TestConfigFileTLSCannotBeRelativeToCWD (0.01s) === RUN TestConfigFileTLSCanBeRelativeToConfigOrAbsolute ---- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.03s) +--- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.05s) === RUN TestConfigFileOverridenByCmdLineFlags ---- PASS: TestConfigFileOverridenByCmdLineFlags (0.03s) +--- PASS: TestConfigFileOverridenByCmdLineFlags (0.04s) === RUN TestConfigFileTrustPinning --- PASS: TestConfigFileTrustPinning (0.00s) === RUN TestPassphraseRetrieverCaching @@ -2355,7 +2390,7 @@ === RUN TestPrettyPrintZeroKeys --- PASS: TestPrettyPrintZeroKeys (0.00s) === RUN TestPrettyPrintRootAndSigningKeys ---- PASS: TestPrettyPrintRootAndSigningKeys (0.02s) +--- PASS: TestPrettyPrintRootAndSigningKeys (0.03s) === RUN TestPrettyPrintZeroTargets --- PASS: TestPrettyPrintZeroTargets (0.00s) === RUN TestPrettyPrintSortedTargets @@ -2367,10 +2402,10 @@ === RUN TestImportRootCert --- PASS: TestImportRootCert (0.00s) === RUN TestTokenAuth -time="2025-02-08T00:01:09Z" 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-06T17:41:20Z" level=error msg="could not reach https://localhost:9999: Get \"https://localhost:9999/v2/\": dial tcp 127.0.0.1:9999: connect: connection refused" --- PASS: TestTokenAuth (0.00s) === RUN TestAdminTokenAuth -time="2025-02-08T00:01:09Z" 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-06T17:41:20Z" 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) @@ -2381,18 +2416,18 @@ === RUN TestAdminTokenAuth401Status --- PASS: TestAdminTokenAuth401Status (0.00s) === RUN TestTokenAuthNon200Non401Status -time="2025-02-08T00:01:09Z" level=error msg="could not reach http://127.0.0.1:38621: 404" +time="2024-01-06T17:41:20Z" level=error msg="could not reach http://127.0.0.1:33473: 404" --- PASS: TestTokenAuthNon200Non401Status (0.00s) === RUN TestAdminTokenAuthNon200Non401Status -time="2025-02-08T00:01:09Z" level=error msg="could not reach http://127.0.0.1:40517: 404" +time="2024-01-06T17:41:20Z" level=error msg="could not reach http://127.0.0.1:33197: 404" --- PASS: TestAdminTokenAuthNon200Non401Status (0.00s) === RUN TestConfigureRepo --- PASS: TestConfigureRepo (0.00s) === RUN TestConfigureRepoRW -Enter username: time="2025-02-08T00:01:09Z" level=error msg="error processing username input: EOF" ---- PASS: TestConfigureRepoRW (0.00s) +Enter username: time="2024-01-06T17:41:20Z" level=error msg="error processing username input: EOF" +--- PASS: TestConfigureRepoRW (0.01s) === RUN TestConfigureRepoAdmin -Enter username: time="2025-02-08T00:01:09Z" level=error msg="error processing username input: EOF" +Enter username: time="2024-01-06T17:41:20Z" level=error msg="error processing username input: EOF" --- PASS: TestConfigureRepoAdmin (0.00s) === RUN TestStatusUnstageAndReset Successfully reset specified changes for repository gun @@ -2405,11 +2440,11 @@ Pushing changes to gun --- PASS: TestGetTrustPinningErrors (0.00s) === RUN TestPasswordStore -Enter username: time="2025-02-08T00:01:09Z" level=error msg="error processing username input: EOF" +Enter username: time="2024-01-06T17:41:20Z" level=error msg="error processing username input: EOF" --- PASS: TestPasswordStore (0.00s) === RUN TestPasswordStoreWithEnvvar -time="2025-02-08T00:01:09Z" level=error msg="Authentication string with zero-legnth username" -time="2025-02-08T00:01:09Z" level=error msg="Could not base64-decode authentication string" +time="2024-01-06T17:41:20Z" level=error msg="Authentication string with zero-legnth username" +time="2024-01-06T17:41:20Z" level=error msg="Could not base64-decode authentication string" --- PASS: TestPasswordStoreWithEnvvar (0.00s) === RUN TestGetPayload --- PASS: TestGetPayload (0.00s) @@ -2418,7 +2453,7 @@ === RUN TestHomeExpand --- PASS: TestHomeExpand (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary 4.735s +ok github.com/theupdateframework/notary/cmd/notary 7.254s === RUN TestBootstrap --- PASS: TestBootstrap (0.00s) === RUN TestGetAddrAndTLSConfigInvalidTLS @@ -2434,42 +2469,42 @@ === RUN TestGetInvalidTrustService --- PASS: TestGetInvalidTrustService (0.00s) === RUN TestGetLocalTrustService -time="2025-02-08T00:01:05Z" level=info msg="Using local signing service, which requires ED25519. Ignoring all other trust_service parameters, including keyAlgorithm" +time="2024-01-06T17:41:13Z" level=info msg="Using local signing service, which requires ED25519. Ignoring all other trust_service parameters, including keyAlgorithm" --- PASS: TestGetLocalTrustService (0.00s) === RUN TestGetTrustServiceInvalidKeyAlgorithm --- PASS: TestGetTrustServiceInvalidKeyAlgorithm (0.00s) === RUN TestGetTrustServiceTLSMissingCertOrKey --- PASS: TestGetTrustServiceTLSMissingCertOrKey (0.00s) === RUN TestGetTrustServiceNoTLSConfig -time="2025-02-08T00:01:05Z" level=info msg="Using remote signing service" +time="2024-01-06T17:41:13Z" level=info msg="Using remote signing service" --- PASS: TestGetTrustServiceNoTLSConfig (0.00s) === RUN TestGetTrustServiceTLSSuccess -time="2025-02-08T00:01:05Z" level=info msg="Using remote signing service" +time="2024-01-06T17:41:13Z" level=info msg="Using remote signing service" --- PASS: TestGetTrustServiceTLSSuccess (0.00s) === RUN TestGetTrustServiceTLSFailure --- PASS: TestGetTrustServiceTLSFailure (0.00s) === RUN TestGetStoreInvalid -time="2025-02-08T00:01:05Z" level=info msg="Using asdf backend" +time="2024-01-06T17:41:13Z" level=info msg="Using asdf backend" --- PASS: TestGetStoreInvalid (0.00s) === RUN TestGetStoreDBStore -time="2025-02-08T00:01:05Z" level=info msg="Using sqlite3 backend" +time="2024-01-06T17:41:13Z" level=info msg="Using sqlite3 backend" --- PASS: TestGetStoreDBStore (0.00s) === RUN TestGetStoreRethinkDBStoreConnectionFails -time="2025-02-08T00:01:05Z" level=info msg="Using rethinkdb backend" +time="2024-01-06T17:41:13Z" level=info msg="Using rethinkdb backend" --- PASS: TestGetStoreRethinkDBStoreConnectionFails (0.00s) === RUN TestGetMemoryStore -time="2025-02-08T00:01:05Z" level=info msg="Using memory backend" +time="2024-01-06T17:41:13Z" level=info msg="Using memory backend" --- PASS: TestGetMemoryStore (0.00s) === RUN TestGetCacheConfig --- PASS: TestGetCacheConfig (0.00s) === RUN TestGetGUNPRefixes --- PASS: TestGetGUNPRefixes (0.00s) === RUN TestSampleConfig -time="2025-02-08T00:01:05Z" level=info msg="Using remote signing service" -time="2025-02-08T00:01:05Z" level=info msg="Using sqlite3 backend" +time="2024-01-06T17:41:13Z" level=info msg="Using remote signing service" +time="2024-01-06T17:41:13Z" level=info msg="Using sqlite3 backend" --- PASS: TestSampleConfig (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary-server 0.027s +ok github.com/theupdateframework/notary/cmd/notary-server 0.032s === RUN TestGetAddrAndTLSConfigInvalidTLS --- PASS: TestGetAddrAndTLSConfigInvalidTLS (0.00s) === RUN TestGetAddrAndTLSConfigNoGRPCAddr @@ -2484,7 +2519,7 @@ main_test.go:121: DM - skipping malfunctioning test, fails on sbuild --- SKIP: TestSetupCryptoServicesRethinkDBStoreConnectionFails (0.00s) === RUN TestSetupCryptoServicesDBStoreSuccess ---- PASS: TestSetupCryptoServicesDBStoreSuccess (0.03s) +--- PASS: TestSetupCryptoServicesDBStoreSuccess (0.04s) === RUN TestSetupCryptoServicesMemoryStore --- PASS: TestSetupCryptoServicesMemoryStore (0.00s) === RUN TestSetupCryptoServicesInvalidStore @@ -2502,17 +2537,17 @@ === RUN TestSampleConfig --- PASS: TestSampleConfig (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary-signer 0.058s +ok github.com/theupdateframework/notary/cmd/notary-signer 0.065s === RUN TestGenerateCertificate ---- PASS: TestGenerateCertificate (0.02s) +--- PASS: TestGenerateCertificate (0.06s) === RUN TestCryptoServiceWithNonEmptyGUN ---- PASS: TestCryptoServiceWithNonEmptyGUN (0.80s) +--- PASS: TestCryptoServiceWithNonEmptyGUN (0.91s) === RUN TestCryptoServiceWithEmptyGUN ---- PASS: TestCryptoServiceWithEmptyGUN (1.01s) +--- PASS: TestCryptoServiceWithEmptyGUN (1.19s) === RUN TestCryptoSignerInterfaceBehavior --- PASS: TestCryptoSignerInterfaceBehavior (0.00s) PASS -ok github.com/theupdateframework/notary/cryptoservice 1.871s +ok github.com/theupdateframework/notary/cryptoservice 2.217s === RUN TestGetPassphraseForUsingDelegationKey --- PASS: TestGetPassphraseForUsingDelegationKey (0.00s) === RUN TestGetPassphraseLimitsShortPassphrases @@ -2528,159 +2563,159 @@ === RUN TestGetPassphrase --- PASS: TestGetPassphrase (0.00s) PASS -ok github.com/theupdateframework/notary/passphrase 0.022s +ok github.com/theupdateframework/notary/passphrase 0.046s ? github.com/theupdateframework/notary/proto [no test files] === RUN TestValidationErrorFormat -time="2025-02-08T00:01:03Z" 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.15.15 http.request.contenttype="multipart/form-data; boundary=f3fa6a6a0a8b0dd2c8fbfa5f4846757100f3350f790bd4955762fdb8dbf1" http.request.host="127.0.0.1:40243" http.request.id=cf2349af-0267-485a-84a2-8df8ec72be1f http.request.method=POST http.request.remoteaddr="127.0.0.1:46598" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype="multipart/form-data; boundary=f3fa6a6a0a8b0dd2c8fbfa5f4846757100f3350f790bd4955762fdb8dbf1" http.request.host="127.0.0.1:40243" http.request.id=cf2349af-0267-485a-84a2-8df8ec72be1f http.request.method=POST http.request.remoteaddr="127.0.0.1:46598" 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=3.688455ms http.response.status=400 http.response.written=260 ---- PASS: TestValidationErrorFormat (0.04s) +time="2024-01-06T17:41:11Z" 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.15.15 http.request.contenttype="multipart/form-data; boundary=60eb34c96d6649ceecfa7e24fc142c6ef89596eed0a8ac14f150fe6a1a61" http.request.host="127.0.0.1:42251" http.request.id=93b8f6fe-6ed6-4105-bc82-f5a3bb02e1c8 http.request.method=POST http.request.remoteaddr="127.0.0.1:46854" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype="multipart/form-data; boundary=60eb34c96d6649ceecfa7e24fc142c6ef89596eed0a8ac14f150fe6a1a61" http.request.host="127.0.0.1:42251" http.request.id=93b8f6fe-6ed6-4105-bc82-f5a3bb02e1c8 http.request.method=POST http.request.remoteaddr="127.0.0.1:46854" 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=9.485016ms http.response.status=400 http.response.written=260 +--- PASS: TestValidationErrorFormat (0.14s) === RUN TestRunBadAddr --- PASS: TestRunBadAddr (0.00s) === RUN TestRunReservedPort --- PASS: TestRunReservedPort (0.00s) === RUN TestRepoPrefixMatches -time="2025-02-08T00:01:03Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum 29ef1457179f2554a32242c01afa4a8587bd131be66386bf656efb863d2280aa" docker.io/notary=gun go.version=go1.15.15 http.request.contenttype="multipart/form-data; boundary=5ad9a689fabfdcade25fa3f0c73c43740ed1e37c141eb385132d953f07dd" http.request.host="127.0.0.1:46625" http.request.id=481d2ccb-5d29-4c05-97f1-3366e743b54c http.request.method=POST http.request.remoteaddr="127.0.0.1:38428" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype="multipart/form-data; boundary=5ad9a689fabfdcade25fa3f0c73c43740ed1e37c141eb385132d953f07dd" http.request.host="127.0.0.1:46625" http.request.id=481d2ccb-5d29-4c05-97f1-3366e743b54c http.request.method=POST http.request.remoteaddr="127.0.0.1:38428" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=3.575981ms http.response.status=0 http.response.written=0 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:46625" http.request.id=f1b7a973-99ce-4651-91bb-77ba88248403 http.request.method=GET http.request.remoteaddr="127.0.0.1:38428" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration="713.508µs" http.response.status=200 http.response.written=683 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:46625" http.request.id=66102ad9-a890-4592-8780-bb332927e0c3 http.request.method=GET http.request.remoteaddr="127.0.0.1:38428" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.aa15dab29e83df56f60e965ac8ea483992e9f49137b9cf8dff1cd655181b44e2.json http.request.useragent=Go-http-client/1.1 http.response.duration="61.06µs" http.response.status=200 http.response.written=683 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:46625" http.request.id=b4ef2451-b7e5-4509-926e-572a7c2c2187 http.request.method=GET http.request.remoteaddr="127.0.0.1:38428" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="581.069µs" http.response.status=200 http.response.written=181 -time="2025-02-08T00:01:03Z" level=info msg="trust data deleted for docker.io/notary" docker.io/notary=gun go.version=go1.15.15 http.request.host="127.0.0.1:46625" http.request.id=2c0b4927-0701-439e-88e4-735dadcdadcf http.request.method=DELETE http.request.remoteaddr="127.0.0.1:38428" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:46625" http.request.id=2c0b4927-0701-439e-88e4-735dadcdadcf http.request.method=DELETE http.request.remoteaddr="127.0.0.1:38428" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="64.967µs" http.response.status=0 http.response.written=0 ---- PASS: TestRepoPrefixMatches (0.03s) +time="2024-01-06T17:41:11Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum f44786f760ceee593dfb5d09ec4c6c94de9557aeb06c10b4d156842fc839bacb" docker.io/notary=gun go.version=go1.15.15 http.request.contenttype="multipart/form-data; boundary=45192dffcc8953b215e1f86a0da46251dfa1fe04ef6cb817693d25942592" http.request.host="127.0.0.1:36035" http.request.id=a3a3e470-e712-4c45-82a8-781571357da7 http.request.method=POST http.request.remoteaddr="127.0.0.1:53046" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype="multipart/form-data; boundary=45192dffcc8953b215e1f86a0da46251dfa1fe04ef6cb817693d25942592" http.request.host="127.0.0.1:36035" http.request.id=a3a3e470-e712-4c45-82a8-781571357da7 http.request.method=POST http.request.remoteaddr="127.0.0.1:53046" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=12.190663ms http.response.status=0 http.response.written=0 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:36035" http.request.id=8f5325a4-c463-40b7-bd0e-cf81a9e3fdbd http.request.method=GET http.request.remoteaddr="127.0.0.1:53046" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration="724.276µs" http.response.status=200 http.response.written=683 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:36035" http.request.id=25be2345-e4da-493e-9985-8f9d8c633939 http.request.method=GET http.request.remoteaddr="127.0.0.1:53046" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.8382e07245baba5c7202722add0f8a6dc4f85a54b0ff56b0bfa036453b3c6145.json http.request.useragent=Go-http-client/1.1 http.response.duration="57.737µs" http.response.status=200 http.response.written=683 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:36035" http.request.id=66635ca9-c283-4382-8cdb-b76a172e0014 http.request.method=GET http.request.remoteaddr="127.0.0.1:53046" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="482.415µs" http.response.status=200 http.response.written=181 +time="2024-01-06T17:41:11Z" level=info msg="trust data deleted for docker.io/notary" docker.io/notary=gun go.version=go1.15.15 http.request.host="127.0.0.1:36035" http.request.id=95a69667-ecfa-44b1-810b-232a3b201936 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:53046" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:36035" http.request.id=95a69667-ecfa-44b1-810b-232a3b201936 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:53046" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="71.007µs" http.response.status=0 http.response.written=0 +--- PASS: TestRepoPrefixMatches (0.06s) === RUN TestRepoPrefixDoesNotMatch --- PASS: TestRepoPrefixDoesNotMatch (0.04s) === RUN TestMetricsEndpoint ---- PASS: TestMetricsEndpoint (0.02s) +--- PASS: TestMetricsEndpoint (0.03s) === RUN TestGetKeysEndpoint -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:34149" http.request.id=c5cd3b1d-8126-4e85-a579-2d3e8a6aad79 http.request.method=GET http.request.remoteaddr="127.0.0.1:54330" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="375.745µs" http.response.status=200 http.response.written=103 -time="2025-02-08T00:01:03Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.15.15 http.request.host="127.0.0.1:34149" http.request.id=ceb0f911-3b16-4bc5-99aa-b5722c71db4c http.request.method=GET http.request.remoteaddr="127.0.0.1:54338" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:34149" http.request.id=ceb0f911-3b16-4bc5-99aa-b5722c71db4c http.request.method=GET http.request.remoteaddr="127.0.0.1:54338" 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="219.7µs" http.response.status=404 http.response.written=104 -time="2025-02-08T00:01:03Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.15.15 http.request.host="127.0.0.1:34149" http.request.id=5604ac08-5232-4dd6-9e50-c94fa3027d43 http.request.method=GET http.request.remoteaddr="127.0.0.1:54346" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:34149" http.request.id=5604ac08-5232-4dd6-9e50-c94fa3027d43 http.request.method=GET http.request.remoteaddr="127.0.0.1:54346" 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="130.261µs" http.response.status=404 http.response.written=104 -time="2025-02-08T00:01:03Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.15.15 http.request.host="127.0.0.1:34149" http.request.id=50581bdf-f406-4922-aee6-6a33b3a9389f http.request.method=GET http.request.remoteaddr="127.0.0.1:54348" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:34149" http.request.id=50581bdf-f406-4922-aee6-6a33b3a9389f http.request.method=GET http.request.remoteaddr="127.0.0.1:54348" 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="186.089µs" http.response.status=404 http.response.written=104 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:34149" http.request.id=c0691342-878d-4d48-ae23-d44c5263b8ee http.request.method=GET http.request.remoteaddr="127.0.0.1:54350" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="275.798µs" http.response.status=200 http.response.written=103 ---- PASS: TestGetKeysEndpoint (0.02s) +time="2024-01-06T17:41:11Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.15.15 http.request.host="127.0.0.1:42135" http.request.id=dbf02cd6-cc1a-45bb-92c3-d956d07e87a7 http.request.method=GET http.request.remoteaddr="127.0.0.1:35078" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:42135" http.request.id=dbf02cd6-cc1a-45bb-92c3-d956d07e87a7 http.request.method=GET http.request.remoteaddr="127.0.0.1:35078" 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="915.229µs" http.response.status=404 http.response.written=104 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:42135" http.request.id=0a135f20-4c3a-44b1-b30c-bfd2d2227abb http.request.method=GET http.request.remoteaddr="127.0.0.1:35086" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="380.991µs" http.response.status=200 http.response.written=103 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:42135" http.request.id=ffceb0d9-c535-4757-a1a4-861f0e258084 http.request.method=GET http.request.remoteaddr="127.0.0.1:35094" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="297.314µs" http.response.status=200 http.response.written=103 +time="2024-01-06T17:41:11Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.15.15 http.request.host="127.0.0.1:42135" http.request.id=570c0837-25fd-4ee4-a051-4863947f93d2 http.request.method=GET http.request.remoteaddr="127.0.0.1:35096" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:42135" http.request.id=570c0837-25fd-4ee4-a051-4863947f93d2 http.request.method=GET http.request.remoteaddr="127.0.0.1:35096" 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="96.988µs" http.response.status=404 http.response.written=104 +time="2024-01-06T17:41:11Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.15.15 http.request.host="127.0.0.1:42135" http.request.id=f17d8133-f8e3-4f8f-a648-d587282f497b http.request.method=GET http.request.remoteaddr="127.0.0.1:35108" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:42135" http.request.id=f17d8133-f8e3-4f8f-a648-d587282f497b http.request.method=GET http.request.remoteaddr="127.0.0.1:35108" 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="62.976µs" http.response.status=404 http.response.written=104 +--- PASS: TestGetKeysEndpoint (0.03s) === RUN TestGetRoleByHash -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:34861" http.request.id=8bd71dbf-b7f5-4b27-b92c-ae57c9b7bd37 http.request.method=GET http.request.remoteaddr="127.0.0.1:47108" http.request.uri=/v2/gun/_trust/tuf/timestamp.aaa6f2c795b9bf45b02a4cd3acea5a8abb833eb789964a8a0ce393f4d4356702.json http.request.useragent=Go-http-client/1.1 http.response.duration="122.41µs" http.response.status=200 http.response.written=115 ---- PASS: TestGetRoleByHash (0.01s) +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:34101" http.request.id=a51a57af-105f-4929-baf2-e175ef26b181 http.request.method=GET http.request.remoteaddr="127.0.0.1:34426" http.request.uri=/v2/gun/_trust/tuf/timestamp.bcb4ed5031c6d12f2140123ae64ff75d18124a621f17dcafa5050ce7638bfd09.json http.request.useragent=Go-http-client/1.1 http.response.duration="153.685µs" http.response.status=200 http.response.written=115 +--- PASS: TestGetRoleByHash (0.02s) === RUN TestGetRoleByVersion -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:40577" http.request.id=adfc9075-cb72-4469-8cac-3952385f5ca7 http.request.method=GET http.request.remoteaddr="127.0.0.1:40476" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="107.345µs" http.response.status=200 http.response.written=114 ---- PASS: TestGetRoleByVersion (0.03s) +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:35813" http.request.id=2725b93d-f89d-4ca6-b0e7-b27fb16b1031 http.request.method=GET http.request.remoteaddr="127.0.0.1:43260" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="88.843µs" http.response.status=200 http.response.written=115 +--- PASS: TestGetRoleByVersion (0.01s) === RUN TestGetCurrentRole -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:41835" http.request.id=3a86722a-37f9-4446-95bb-db6a22bbae14 http.request.method=GET http.request.remoteaddr="127.0.0.1:38610" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="669.297µs" http.response.status=200 http.response.written=495 ---- PASS: TestGetCurrentRole (0.01s) +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:39639" http.request.id=3f5292f4-1b30-4264-8dc3-6ce480c653ca http.request.method=GET http.request.remoteaddr="127.0.0.1:41016" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="704.97µs" http.response.status=200 http.response.written=495 +--- PASS: TestGetCurrentRole (0.02s) === RUN TestRotateKeyEndpoint -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:39199" http.request.id=afb54476-95cc-4231-bab6-7a9a67bb1a81 http.request.method=POST http.request.remoteaddr="127.0.0.1:47470" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="328.55µs" http.response.status=200 http.response.written=103 -time="2025-02-08T00:01:03Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:39199" http.request.id=73a4ff45-c635-42e2-8c75-ce7c9f638564 http.request.method=POST http.request.remoteaddr="127.0.0.1:47484" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:39199" http.request.id=73a4ff45-c635-42e2-8c75-ce7c9f638564 http.request.method=POST http.request.remoteaddr="127.0.0.1:47484" 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="286.994µs" http.response.status=404 http.response.written=104 -time="2025-02-08T00:01:03Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:39199" http.request.id=d3087ac4-b50f-4fb4-a90d-13893352e44d http.request.method=POST http.request.remoteaddr="127.0.0.1:47500" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:39199" http.request.id=d3087ac4-b50f-4fb4-a90d-13893352e44d http.request.method=POST http.request.remoteaddr="127.0.0.1:47500" 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.887µs" http.response.status=404 http.response.written=104 -time="2025-02-08T00:01:03Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:39199" http.request.id=6ca59f82-f823-4636-ae45-476aac057039 http.request.method=POST http.request.remoteaddr="127.0.0.1:47508" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:39199" http.request.id=6ca59f82-f823-4636-ae45-476aac057039 http.request.method=POST http.request.remoteaddr="127.0.0.1:47508" 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="199.371µs" http.response.status=404 http.response.written=104 -time="2025-02-08T00:01:03Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:39199" http.request.id=dec044e1-0d03-4ab8-81cd-29c52b2e10cf http.request.method=POST http.request.remoteaddr="127.0.0.1:47514" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:39199" http.request.id=dec044e1-0d03-4ab8-81cd-29c52b2e10cf http.request.method=POST http.request.remoteaddr="127.0.0.1:47514" 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="111.945µs" http.response.status=404 http.response.written=104 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:39199" http.request.id=412a0e51-56a1-438a-bc0e-86a63927890c http.request.method=POST http.request.remoteaddr="127.0.0.1:47516" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="408.263µs" http.response.status=200 http.response.written=103 ---- PASS: TestRotateKeyEndpoint (0.02s) +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:43857" http.request.id=a25df7e3-b857-470d-a042-d34042ab5215 http.request.method=POST http.request.remoteaddr="127.0.0.1:47126" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="458.896µs" http.response.status=200 http.response.written=103 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:43857" http.request.id=467ea60b-3034-4003-b03e-6aca016e5e5e http.request.method=POST http.request.remoteaddr="127.0.0.1:47134" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="370.111µs" http.response.status=200 http.response.written=103 +time="2024-01-06T17:41:11Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:43857" http.request.id=3a2d09ed-91d2-4b69-bae0-79e9b5911758 http.request.method=POST http.request.remoteaddr="127.0.0.1:47146" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:43857" http.request.id=3a2d09ed-91d2-4b69-bae0-79e9b5911758 http.request.method=POST http.request.remoteaddr="127.0.0.1:47146" 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="187.275µs" http.response.status=404 http.response.written=104 +time="2024-01-06T17:41:11Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:43857" http.request.id=44a862e1-bfa2-4872-8b76-a6f18b2fe5cd http.request.method=POST http.request.remoteaddr="127.0.0.1:47158" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:43857" http.request.id=44a862e1-bfa2-4872-8b76-a6f18b2fe5cd http.request.method=POST http.request.remoteaddr="127.0.0.1:47158" 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="193.311µs" http.response.status=404 http.response.written=104 +time="2024-01-06T17:41:11Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:43857" http.request.id=c0ef8793-a769-4b35-bf28-e36ad8969937 http.request.method=POST http.request.remoteaddr="127.0.0.1:47160" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:43857" http.request.id=c0ef8793-a769-4b35-bf28-e36ad8969937 http.request.method=POST http.request.remoteaddr="127.0.0.1:47160" 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="218.379µs" http.response.status=404 http.response.written=104 +time="2024-01-06T17:41:11Z" level=info msg="metadata not found: You have requested metadata that does not exist.: " go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:43857" http.request.id=fb12c46a-53a6-4018-bee2-0a33dd0b5555 http.request.method=POST http.request.remoteaddr="127.0.0.1:47166" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2024-01-06T17:41:11Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:43857" http.request.id=fb12c46a-53a6-4018-bee2-0a33dd0b5555 http.request.method=POST http.request.remoteaddr="127.0.0.1:47166" 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="171.682µs" http.response.status=404 http.response.written=104 +--- PASS: TestRotateKeyEndpoint (0.05s) PASS -ok github.com/theupdateframework/notary/server 0.245s +ok github.com/theupdateframework/notary/server 0.480s ? github.com/theupdateframework/notary/server/errors [no test files] === RUN Test_changefeed --- PASS: Test_changefeed (0.00s) === RUN Test_checkChangefeedInputs -time="2025-02-08T00:01:03Z" level=error msg="500 GET unable to retrieve storage" -time="2025-02-08T00:01:03Z" level=error msg="400 GET invalid pageSize: not_a_number" +time="2024-01-06T17:41:10Z" level=error msg="500 GET unable to retrieve storage" +time="2024-01-06T17:41:10Z" level=error msg="400 GET invalid pageSize: not_a_number" --- PASS: Test_checkChangefeedInputs (0.00s) === RUN TestMainHandlerGet -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:39613" http.request.id=66ac019e-6c0a-4b1d-9b05-606ff7417ca2 http.request.method=GET http.request.remoteaddr="127.0.0.1:51270" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="97.501µs" http.response.status=200 http.response.written=2 ---- PASS: TestMainHandlerGet (0.00s) +time="2024-01-06T17:41:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:42515" http.request.id=1f51d706-f0aa-478f-9808-86508f710609 http.request.method=GET http.request.remoteaddr="127.0.0.1:59436" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="133.282µs" http.response.status=200 http.response.written=2 +--- PASS: TestMainHandlerGet (0.01s) === RUN TestMainHandlerNotGet -time="2025-02-08T00:01:03Z" level=info msg="generic not found: You have requested a resource that does not exist.: " go.version=go1.15.15 http.request.host="127.0.0.1:44595" http.request.id=fa5606c8-5ed1-4fd2-af56-6400ecea0b1d http.request.method=HEAD http.request.remoteaddr="127.0.0.1:54374" http.request.uri=/ http.request.useragent=Go-http-client/1.1 -time="2025-02-08T00:01:03Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:44595" http.request.id=fa5606c8-5ed1-4fd2-af56-6400ecea0b1d http.request.method=HEAD http.request.remoteaddr="127.0.0.1:54374" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="433.425µs" http.response.status=404 http.response.written=105 +time="2024-01-06T17:41:10Z" level=info msg="generic not found: You have requested a resource that does not exist.: " go.version=go1.15.15 http.request.host="127.0.0.1:38923" http.request.id=de4250cc-2377-44b0-97d8-d9efd8489dc9 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:46188" http.request.uri=/ http.request.useragent=Go-http-client/1.1 +time="2024-01-06T17:41:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:38923" http.request.id=de4250cc-2377-44b0-97d8-d9efd8489dc9 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:46188" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="296.811µs" http.response.status=404 http.response.written=105 --- PASS: TestMainHandlerNotGet (0.00s) === RUN TestKeyHandlersInvalidConfiguration -time="2025-02-08T00:01:03Z" level=error msg="500 GET storage not configured" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=error msg="500 GET storage not configured" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=error msg="500 GET crypto service not configured" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=error msg="500 GET crypto service not configured" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=error msg="500 GET key algorithm not configured" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=error msg="500 GET key algorithm not configured" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=error msg="500 POST storage not configured" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=error msg="500 POST storage not configured" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=error msg="500 POST crypto service not configured" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=error msg="500 POST crypto service not configured" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=error msg="500 POST key algorithm not configured" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=error msg="500 POST key algorithm not configured" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 GET storage not configured" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 GET storage not configured" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 GET crypto service not configured" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 GET crypto service not configured" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 GET key algorithm not configured" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 GET key algorithm not configured" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 POST storage not configured" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 POST storage not configured" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 POST crypto service not configured" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 POST crypto service not configured" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 POST key algorithm not configured" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 POST key algorithm not configured" go.version=go1.15.15 gun=gun --- PASS: TestKeyHandlersInvalidConfiguration (0.00s) === RUN TestKeyHandlersNoRoleOrRepo -time="2025-02-08T00:01:03Z" level=info msg="400 GET no gun in request" =gun go.version=go1.15.15 -time="2025-02-08T00:01:03Z" level=info msg="400 GET no gun in request" =gun go.version=go1.15.15 -time="2025-02-08T00:01:03Z" level=info msg="400 GET no role in request" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=info msg="400 GET no role in request" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=info msg="400 POST no gun in request" =gun go.version=go1.15.15 -time="2025-02-08T00:01:03Z" level=info msg="400 POST no gun in request" =gun go.version=go1.15.15 -time="2025-02-08T00:01:03Z" level=info msg="400 POST no role in request" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=info msg="400 POST no role in request" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=info msg="400 GET no gun in request" =gun go.version=go1.15.15 +time="2024-01-06T17:41:10Z" level=info msg="400 GET no gun in request" =gun go.version=go1.15.15 +time="2024-01-06T17:41:10Z" level=info msg="400 GET no role in request" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=info msg="400 GET no role in request" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=info msg="400 POST no gun in request" =gun go.version=go1.15.15 +time="2024-01-06T17:41:10Z" level=info msg="400 POST no gun in request" =gun go.version=go1.15.15 +time="2024-01-06T17:41:10Z" level=info msg="400 POST no role in request" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=info msg="400 POST no role in request" go.version=go1.15.15 gun=gun --- PASS: TestKeyHandlersNoRoleOrRepo (0.00s) === RUN TestKeyHandlersInvalidRole -time="2025-02-08T00:01:03Z" level=info msg="400 GET root key: " go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=info msg="400 GET targets key: " go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=info msg="400 GET targets/a key: " go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=info msg="400 GET invalidrole key: " go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=info msg="400 POST root key: " go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=info msg="400 POST targets key: " go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=info msg="400 POST targets/a key: " go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=info msg="400 POST invalidrole key: " go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=info msg="400 GET root key: " go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=info msg="400 GET targets key: " go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=info msg="400 GET targets/a key: " go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=info msg="400 GET invalidrole key: " go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=info msg="400 POST root key: " go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=info msg="400 POST targets key: " go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=info msg="400 POST targets/a key: " go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=info msg="400 POST invalidrole key: " go.version=go1.15.15 gun=gun --- PASS: TestKeyHandlersInvalidRole (0.00s) === RUN TestGetKeyHandlerCreatesOnce --- PASS: TestGetKeyHandlerCreatesOnce (0.00s) === RUN TestKeyHandlersInvalidKeyAlgo -time="2025-02-08T00:01:03Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun -time="2025-02-08T00:01:03Z" level=error msg="500 POST snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=error msg="500 POST snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun --- PASS: TestKeyHandlersInvalidKeyAlgo (0.00s) === RUN TestRotateKeyHandlerSuccessfulRotation --- PASS: TestRotateKeyHandlerSuccessfulRotation (0.00s) === RUN TestGetHandlerRoot -time="2025-02-08T00:01:03Z" level=info msg="404 GET root role" go.version=go1.15.15 gun=gun ---- PASS: TestGetHandlerRoot (0.02s) +time="2024-01-06T17:41:10Z" level=info msg="404 GET root role" go.version=go1.15.15 gun=gun +--- PASS: TestGetHandlerRoot (0.05s) === RUN TestGetHandlerTimestamp ---- PASS: TestGetHandlerTimestamp (0.00s) +--- PASS: TestGetHandlerTimestamp (0.01s) === RUN TestGetHandlerSnapshot --- PASS: TestGetHandlerSnapshot (0.00s) === RUN TestGetHandler404 -time="2025-02-08T00:01:03Z" level=info msg="404 GET root role" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=info msg="404 GET root role" go.version=go1.15.15 gun=gun --- PASS: TestGetHandler404 (0.00s) === RUN TestGetHandlerNilData -time="2025-02-08T00:01:03Z" level=info msg="404 GET root role" go.version=go1.15.15 gun=gun +time="2024-01-06T17:41:10Z" level=info msg="404 GET root role" go.version=go1.15.15 gun=gun --- PASS: TestGetHandlerNilData (0.00s) === RUN TestGetHandlerNoStorage -time="2025-02-08T00:01:03Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.15.15 +time="2024-01-06T17:41:10Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.15.15 --- PASS: TestGetHandlerNoStorage (0.00s) === RUN TestAtomicUpdateValidationFailurePropagated ---- PASS: TestAtomicUpdateValidationFailurePropagated (0.03s) +--- PASS: TestAtomicUpdateValidationFailurePropagated (0.02s) === RUN TestAtomicUpdateNonValidationFailureNotPropagated -time="2025-02-08T00:01:03Z" level=info msg="400 POST error validating update" go.version=go1.15.15 testGUN=gun +time="2024-01-06T17:41:10Z" level=info msg="400 POST error validating update" go.version=go1.15.15 testGUN=gun --- PASS: TestAtomicUpdateNonValidationFailureNotPropagated (0.02s) === RUN TestAtomicUpdateVersionErrorPropagated -time="2025-02-08T00:01:03Z" level=info msg="400 POST old version error" go.version=go1.15.15 testGUN=gun ---- PASS: TestAtomicUpdateVersionErrorPropagated (0.03s) +time="2024-01-06T17:41:11Z" level=info msg="400 POST old version error" go.version=go1.15.15 testGUN=gun +--- PASS: TestAtomicUpdateVersionErrorPropagated (0.08s) === RUN TestGetMaybeServerSignedNoCrypto --- PASS: TestGetMaybeServerSignedNoCrypto (0.00s) === RUN TestGetMaybeServerSignedNoKey --- PASS: TestGetMaybeServerSignedNoKey (0.00s) === RUN TestValidateEmptyNew ---- PASS: TestValidateEmptyNew (0.01s) +--- PASS: TestValidateEmptyNew (0.04s) === RUN TestValidateRootCanContainOnlyx509KeysWithRightGun ---- PASS: TestValidateRootCanContainOnlyx509KeysWithRightGun (0.01s) +--- PASS: TestValidateRootCanContainOnlyx509KeysWithRightGun (0.04s) === RUN TestValidatePrevTimestamp ---- PASS: TestValidatePrevTimestamp (0.01s) +--- PASS: TestValidatePrevTimestamp (0.03s) === RUN TestValidatePreviousTimestampCorrupt -time="2025-02-08T00:01:03Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" ---- PASS: TestValidatePreviousTimestampCorrupt (0.01s) +time="2024-01-06T17:41:11Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" +--- PASS: TestValidatePreviousTimestampCorrupt (0.02s) === RUN TestValidateGetCurrentTimestampBroken ---- PASS: TestValidateGetCurrentTimestampBroken (0.01s) +--- PASS: TestValidateGetCurrentTimestampBroken (0.02s) === RUN TestValidateNoNewRoot --- PASS: TestValidateNoNewRoot (0.01s) === RUN TestValidateNoNewTargets @@ -2690,30 +2725,30 @@ === RUN TestValidateOldRoot --- PASS: TestValidateOldRoot (0.01s) === RUN TestValidateOldRootCorrupt ---- PASS: TestValidateOldRootCorrupt (0.01s) +--- PASS: TestValidateOldRootCorrupt (0.03s) === RUN TestValidateOldRootCorruptRootRole ---- PASS: TestValidateOldRootCorruptRootRole (0.01s) +--- PASS: TestValidateOldRootCorruptRootRole (0.02s) === RUN TestValidateRootGetCurrentRootBroken --- PASS: TestValidateRootGetCurrentRootBroken (0.01s) === RUN TestValidateRootRotationWithOldSigs ---- PASS: TestValidateRootRotationWithOldSigs (0.04s) +--- PASS: TestValidateRootRotationWithOldSigs (0.05s) === RUN TestValidateRootRotationMultipleKeysThreshold1 ---- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.02s) +--- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.03s) === RUN TestRootRotationNotSignedWithOldKeysForOldRole ---- PASS: TestRootRotationNotSignedWithOldKeysForOldRole (0.03s) +--- PASS: TestRootRotationNotSignedWithOldKeysForOldRole (0.05s) === RUN TestRootRotationVersionIncrement ---- PASS: TestRootRotationVersionIncrement (0.02s) +--- PASS: TestRootRotationVersionIncrement (0.05s) === RUN TestValidateNoRoot ---- PASS: TestValidateNoRoot (0.01s) +--- PASS: TestValidateNoRoot (0.02s) === RUN TestValidateSnapshotMissingNoSnapshotKey ---- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.01s) +--- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.02s) === RUN TestValidateSnapshotGenerateNoPrev ---- PASS: TestValidateSnapshotGenerateNoPrev (0.02s) +--- PASS: TestValidateSnapshotGenerateNoPrev (0.04s) === RUN TestValidateSnapshotGenerateWithPrev ---- PASS: TestValidateSnapshotGenerateWithPrev (0.01s) +--- PASS: TestValidateSnapshotGenerateWithPrev (0.04s) === RUN TestValidateSnapshotGeneratePrevCorrupt -time="2025-02-08T00:01:04Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" ---- PASS: TestValidateSnapshotGeneratePrevCorrupt (0.01s) +time="2024-01-06T17:41:11Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" +--- PASS: TestValidateSnapshotGeneratePrevCorrupt (0.04s) === RUN TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken --- PASS: TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken (0.01s) === RUN TestValidateSnapshotGenerateNoTargets @@ -2727,54 +2762,54 @@ === RUN TestValidateRootInvalidTimestampThreshold --- PASS: TestValidateRootInvalidTimestampThreshold (0.01s) === RUN TestValidateRootInvalidZeroThreshold ---- PASS: TestValidateRootInvalidZeroThreshold (0.03s) +--- PASS: TestValidateRootInvalidZeroThreshold (0.04s) === RUN TestValidateRootRoleMissing --- PASS: TestValidateRootRoleMissing (0.01s) === RUN TestValidateTargetsRoleMissing --- PASS: TestValidateTargetsRoleMissing (0.01s) === RUN TestValidateSnapshotRoleMissing ---- PASS: TestValidateSnapshotRoleMissing (0.01s) +--- PASS: TestValidateSnapshotRoleMissing (0.02s) === RUN TestValidateRootSigMissing ---- PASS: TestValidateRootSigMissing (0.01s) +--- PASS: TestValidateRootSigMissing (0.02s) === RUN TestValidateTargetsSigMissing -time="2025-02-08T00:01:04Z" level=error msg="ErrBadTargets: tuf: data has no signatures" ---- PASS: TestValidateTargetsSigMissing (0.01s) +time="2024-01-06T17:41:11Z" level=error msg="ErrBadTargets: tuf: data has no signatures" +--- PASS: TestValidateTargetsSigMissing (0.02s) === RUN TestValidateSnapshotSigMissing --- PASS: TestValidateSnapshotSigMissing (0.01s) === RUN TestValidateRootCorrupt --- PASS: TestValidateRootCorrupt (0.01s) === RUN TestValidateTargetsCorrupt -time="2025-02-08T00:01:04Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" +time="2024-01-06T17:41:11Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" --- PASS: TestValidateTargetsCorrupt (0.01s) === RUN TestValidateSnapshotCorrupt --- PASS: TestValidateSnapshotCorrupt (0.01s) === RUN TestValidateRootModifiedSize --- PASS: TestValidateRootModifiedSize (0.01s) === RUN TestValidateTargetsModifiedSize ---- PASS: TestValidateTargetsModifiedSize (0.01s) +--- PASS: TestValidateTargetsModifiedSize (0.02s) === RUN TestValidateRootModifiedHash ---- PASS: TestValidateRootModifiedHash (0.01s) +--- PASS: TestValidateRootModifiedHash (0.04s) === RUN TestValidateTargetsModifiedHash ---- PASS: TestValidateTargetsModifiedHash (0.02s) +--- PASS: TestValidateTargetsModifiedHash (0.05s) === RUN TestGenerateSnapshotRootNotLoaded --- PASS: TestGenerateSnapshotRootNotLoaded (0.00s) === RUN TestGenerateSnapshotNoKey ---- PASS: TestGenerateSnapshotNoKey (0.01s) +--- PASS: TestGenerateSnapshotNoKey (0.02s) === RUN TestLoadTargetsLoadsNothingIfNoUpdates --- PASS: TestLoadTargetsLoadsNothingIfNoUpdates (0.01s) === RUN TestValidateTargetsRequiresStoredParent -time="2025-02-08T00:01:04Z" level=error msg="ErrBadTargets: targets must be loaded first" ---- PASS: TestValidateTargetsRequiresStoredParent (0.01s) +time="2024-01-06T17:41:12Z" level=error msg="ErrBadTargets: targets must be loaded first" +--- PASS: TestValidateTargetsRequiresStoredParent (0.02s) === RUN TestValidateTargetsParentInUpdate -time="2025-02-08T00:01:04Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" ---- PASS: TestValidateTargetsParentInUpdate (0.01s) +time="2024-01-06T17:41:12Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" +--- PASS: TestValidateTargetsParentInUpdate (0.03s) === RUN TestValidateTargetsRoleNotInParent -time="2025-02-08T00:01:04Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" -time="2025-02-08T00:01:04Z" level=error msg="ErrBadTargets: targets has already been loaded" -time="2025-02-08T00:01:04Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" ---- PASS: TestValidateTargetsRoleNotInParent (0.01s) +time="2024-01-06T17:41:12Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +time="2024-01-06T17:41:12Z" level=error msg="ErrBadTargets: targets has already been loaded" +time="2024-01-06T17:41:12Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +--- PASS: TestValidateTargetsRoleNotInParent (0.02s) PASS -ok github.com/theupdateframework/notary/server/handlers 0.700s +ok github.com/theupdateframework/notary/server/handlers 1.292s === RUN TestSnapshotExpired --- PASS: TestSnapshotExpired (0.00s) === RUN TestSnapshotNotExpired @@ -2782,17 +2817,17 @@ === RUN TestGetSnapshotKeyCreate --- PASS: TestGetSnapshotKeyCreate (0.00s) === RUN TestGetSnapshotKeyCreateWithFailingStore -time="2025-02-08T00:01:02Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2024-01-06T17:41:08Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetSnapshotKeyCreateWithFailingStore (0.00s) === RUN TestGetSnapshotKeyCreateWithCorruptedStore -time="2025-02-08T00:01:02Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve snapshot key ID" +time="2024-01-06T17:41:08Z" 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.02s) +--- PASS: TestGetSnapshotKeyExistingMetadata (0.04s) === RUN TestGetSnapshotNoPreviousSnapshot -time="2025-02-08T00:01:02Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" +time="2024-01-06T17:41:08Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" --- PASS: TestGetSnapshotNoPreviousSnapshot (0.00s) === RUN TestGetSnapshotReturnsPreviousSnapshotIfUnexpired --- PASS: TestGetSnapshotReturnsPreviousSnapshotIfUnexpired (0.00s) @@ -2801,9 +2836,9 @@ === RUN TestCannotMakeNewSnapshotIfNoRoot --- PASS: TestCannotMakeNewSnapshotIfNoRoot (0.00s) === RUN TestCreateSnapshotNoKeyInCrypto ---- PASS: TestCreateSnapshotNoKeyInCrypto (0.00s) +--- PASS: TestCreateSnapshotNoKeyInCrypto (0.01s) PASS -ok github.com/theupdateframework/notary/server/snapshot 0.055s +ok github.com/theupdateframework/notary/server/snapshot 0.114s === RUN TestMemoryUpdateCurrentEmpty --- PASS: TestMemoryUpdateCurrentEmpty (0.00s) === RUN TestMemoryUpdateCurrentVersionCheckOldVersionExists @@ -2829,35 +2864,35 @@ === RUN TestRDBTUFFileJSONUnmarshallingFailure --- PASS: TestRDBTUFFileJSONUnmarshallingFailure (0.00s) === RUN TestSQLUpdateCurrentEmpty ---- PASS: TestSQLUpdateCurrentEmpty (0.02s) +--- PASS: TestSQLUpdateCurrentEmpty (0.03s) === RUN TestSQLUpdateCurrentVersionCheckOldVersionExists --- PASS: TestSQLUpdateCurrentVersionCheckOldVersionExists (0.01s) === RUN TestSQLUpdateCurrentVersionCheckOldVersionNotExist --- PASS: TestSQLUpdateCurrentVersionCheckOldVersionNotExist (0.01s) === RUN TestSQLUpdateManyNoConflicts ---- PASS: TestSQLUpdateManyNoConflicts (0.06s) +--- PASS: TestSQLUpdateManyNoConflicts (0.04s) === RUN TestSQLUpdateManyConflictRollback ---- PASS: TestSQLUpdateManyConflictRollback (0.01s) +--- PASS: TestSQLUpdateManyConflictRollback (0.03s) === RUN TestSQLDelete ---- PASS: TestSQLDelete (0.03s) +--- PASS: TestSQLDelete (0.04s) === RUN TestSQLDBCheckHealthTableMissing ---- PASS: TestSQLDBCheckHealthTableMissing (0.00s) +--- PASS: TestSQLDBCheckHealthTableMissing (0.01s) === RUN TestSQLDBCheckHealthDBConnectionFail --- PASS: TestSQLDBCheckHealthDBConnectionFail (0.00s) === RUN TestSQLDBCheckHealthSucceeds ---- PASS: TestSQLDBCheckHealthSucceeds (0.00s) +--- PASS: TestSQLDBCheckHealthSucceeds (0.01s) === RUN TestSQLDBGetChecksum ---- PASS: TestSQLDBGetChecksum (0.01s) +--- PASS: TestSQLDBGetChecksum (0.00s) === RUN TestSQLDBGetChecksumNotFound --- PASS: TestSQLDBGetChecksumNotFound (0.00s) === RUN TestSQLTUFMetaStoreGetCurrent ---- PASS: TestSQLTUFMetaStoreGetCurrent (0.04s) +--- PASS: TestSQLTUFMetaStoreGetCurrent (0.05s) === RUN TestSQLGetChanges ---- PASS: TestSQLGetChanges (0.01s) +--- PASS: TestSQLGetChanges (0.02s) === RUN TestSQLDBGetVersion --- PASS: TestSQLDBGetVersion (0.00s) PASS -ok github.com/theupdateframework/notary/server/storage 0.235s +ok github.com/theupdateframework/notary/server/storage 0.280s === RUN TestTimestampExpired --- PASS: TestTimestampExpired (0.00s) === RUN TestTimestampNotExpired @@ -2865,33 +2900,33 @@ === RUN TestGetTimestampKey --- PASS: TestGetTimestampKey (0.00s) === RUN TestGetTimestampNoPreviousTimestamp -time="2025-02-08T00:01:02Z" level=error msg="Failed to unmarshal existing timestamp" ---- PASS: TestGetTimestampNoPreviousTimestamp (0.02s) +time="2024-01-06T17:41:08Z" level=error msg="Failed to unmarshal existing timestamp" +--- PASS: TestGetTimestampNoPreviousTimestamp (0.03s) === RUN TestGetTimestampReturnsPreviousTimestampIfUnexpired --- PASS: TestGetTimestampReturnsPreviousTimestampIfUnexpired (0.00s) === RUN TestGetTimestampOldTimestampExpired --- PASS: TestGetTimestampOldTimestampExpired (0.01s) === RUN TestCannotMakeNewTimestampIfNoRootOrSnapshot -time="2025-02-08T00:01:02Z" level=error msg="Failed to create a new timestamp" -time="2025-02-08T00:01:02Z" level=error msg="Failed to create a new timestamp" +time="2024-01-06T17:41:08Z" level=error msg="Failed to create a new timestamp" +time="2024-01-06T17:41:08Z" level=error msg="Failed to create a new timestamp" --- PASS: TestCannotMakeNewTimestampIfNoRootOrSnapshot (0.01s) === RUN TestCreateTimestampNoKeyInCrypto -time="2025-02-08T00:01:02Z" level=error msg="Failed to create a new timestamp" +time="2024-01-06T17:41:08Z" level=error msg="Failed to create a new timestamp" --- PASS: TestCreateTimestampNoKeyInCrypto (0.01s) === RUN TestGetTimestampKeyCreateWithFailingStore -time="2025-02-08T00:01:02Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2024-01-06T17:41:08Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetTimestampKeyCreateWithFailingStore (0.00s) === RUN TestGetTimestampKeyCreateWithCorruptedStore -time="2025-02-08T00:01:02Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve timestamp key ID" +time="2024-01-06T17:41:08Z" 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.01s) +--- PASS: TestGetTimestampKeyExistingMetadata (0.00s) PASS -ok github.com/theupdateframework/notary/server/timestamp 0.077s +ok github.com/theupdateframework/notary/server/timestamp 0.113s === RUN TestHealthCheckKMUnhealthy ---- PASS: TestHealthCheckKMUnhealthy (0.00s) +--- PASS: TestHealthCheckKMUnhealthy (0.01s) === RUN TestHealthCheckSignerUnhealthy --- PASS: TestHealthCheckSignerUnhealthy (0.00s) === RUN TestHealthCheckKMTimeout @@ -2907,31 +2942,31 @@ === 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="2025-02-08T00:01:04Z" level=info msg="Sign: Signed message! with KeyID db14e6c6adcfaf62baffd2c6c7cc1a2ddda485a6cedcddf2f6666071dd4088f9" go.version=go1.15.15 ---- PASS: TestGetPrivateKeyAndSignWithExistingKey (0.03s) +time="2024-01-06T17:41:12Z" level=info msg="Sign: Signed message! with KeyID 70cea27289e2592777e392e6beb7ecadbf8433dccbc0556b9ffb922448a0f1e9" go.version=go1.15.15 +--- PASS: TestGetPrivateKeyAndSignWithExistingKey (0.02s) === RUN TestCannotSignWithKeyThatDoesntExist -time="2025-02-08T00:01:04Z" level=error msg="Sign: key 651c5ed6db828a1f19e4f7748101fe2cba7e099afbf88c34caa27733f14134fd not found" go.version=go1.15.15 +time="2024-01-06T17:41:12Z" level=error msg="Sign: key 93c77315f19c193aa36975b09a048b8557361320f445786821abe960816b884e not found" go.version=go1.15.15 --- PASS: TestCannotSignWithKeyThatDoesntExist (0.00s) === RUN TestCryptoSignerInterfaceBehavior -time="2025-02-08T00:01:04Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.15.15 -time="2025-02-08T00:01:04Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.15.15 -time="2025-02-08T00:01:04Z" level=info msg="CreateKey: Created KeyID f2ec332535fbf1fa0a014b71c95503b2ae175dce26633da1bf8400a561e5e56e" go.version=go1.15.15 -time="2025-02-08T00:01:04Z" level=info msg="CreateKey: Created KeyID 17cd4c5e85c09a028bf83423eca65dbba3adbb419d4917d211b4e568b0b1c20a" go.version=go1.15.15 -time="2025-02-08T00:01:04Z" level=error msg="GetKeyInfo: key f2ec332535fbf1fa0a014b71c95503b2ae175dce26633da1bf8400a561e5e56e not found" go.version=go1.15.15 -time="2025-02-08T00:01:04Z" level=error msg="GetKeyInfo: key 17cd4c5e85c09a028bf83423eca65dbba3adbb419d4917d211b4e568b0b1c20a not found" go.version=go1.15.15 ---- PASS: TestCryptoSignerInterfaceBehavior (0.02s) +time="2024-01-06T17:41:12Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.15.15 +time="2024-01-06T17:41:12Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.15.15 +time="2024-01-06T17:41:12Z" level=info msg="CreateKey: Created KeyID 5607885a14df8ea48b5af71fa0b63ab13b053f426d8e86b15421dec02a496439" go.version=go1.15.15 +time="2024-01-06T17:41:12Z" level=info msg="CreateKey: Created KeyID a85971b9f24b448d09ecc9c69c9824fc080ce14da89de4bb82d870953bce63aa" go.version=go1.15.15 +time="2024-01-06T17:41:12Z" level=error msg="GetKeyInfo: key 5607885a14df8ea48b5af71fa0b63ab13b053f426d8e86b15421dec02a496439 not found" go.version=go1.15.15 +time="2024-01-06T17:41:12Z" level=error msg="GetKeyInfo: key a85971b9f24b448d09ecc9c69c9824fc080ce14da89de4bb82d870953bce63aa not found" go.version=go1.15.15 +--- PASS: TestCryptoSignerInterfaceBehavior (0.10s) PASS -ok github.com/theupdateframework/notary/signer 0.071s +ok github.com/theupdateframework/notary/signer 0.164s ? github.com/theupdateframework/notary/signer/api [no test files] ? github.com/theupdateframework/notary/signer/client [no test files] === RUN TestGetSuccessPopulatesCache ---- PASS: TestGetSuccessPopulatesCache (0.04s) +--- PASS: TestGetSuccessPopulatesCache (0.06s) === RUN TestAddKeyPopulatesCacheIfSuccessful ---- PASS: TestAddKeyPopulatesCacheIfSuccessful (0.01s) +--- PASS: TestAddKeyPopulatesCacheIfSuccessful (0.00s) === RUN TestDeleteKeyRemovesKeyFromCache --- PASS: TestDeleteKeyRemovesKeyFromCache (0.02s) === RUN TestRDBPrivateKeyJSONUnmarshalling @@ -2942,30 +2977,30 @@ `nodb` is not officially supported, running under compatibility mode. --- PASS: TestNewSQLKeyDBStorePropagatesDBError (0.00s) === RUN TestSQLDBHealthCheckMissingTable ---- PASS: TestSQLDBHealthCheckMissingTable (0.02s) +--- PASS: TestSQLDBHealthCheckMissingTable (0.01s) === RUN TestSQLDBHealthCheckNoConnection --- PASS: TestSQLDBHealthCheckNoConnection (0.00s) === RUN TestSQLKeyCanOnlyBeAddedOnce (/build/reproducible-path/notary-0.6.1~ds2/_build/src/github.com/theupdateframework/notary/signer/keydbstore/sql_keydbstore.go:98) -[2025-02-08 00:01:03]  UNIQUE constraint failed: private_keys.key_id  ---- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.14s) +[2024-01-06 17:41:10]  UNIQUE constraint failed: private_keys.key_id  +--- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.10s) === RUN TestSQLCreateDelete ---- PASS: TestSQLCreateDelete (0.12s) +--- PASS: TestSQLCreateDelete (0.21s) === RUN TestSQLKeyRotation ---- PASS: TestSQLKeyRotation (0.12s) +--- PASS: TestSQLKeyRotation (0.21s) === RUN TestSQLSigningMarksKeyActive -time="2025-02-08T00:01:03Z" level=error msg="Key 71e76b601c13e8a369977f3ce70dcf720a5a0b31fd9b1384065fb1e24e70dcd1 was just used to sign hash successful, db closed, error when trying to mark key as active: sql: database is closed" ---- PASS: TestSQLSigningMarksKeyActive (0.20s) +time="2024-01-06T17:41:11Z" level=error msg="Key 2fa7d479f8a165896dadebfe5e8b2a54a2d5e05016b21b44369085b34a1456c3 was just used to sign hash successful, db closed, error when trying to mark key as active: sql: database is closed" +--- PASS: TestSQLSigningMarksKeyActive (0.33s) === RUN TestSQLCreateKey (/build/reproducible-path/notary-0.6.1~ds2/_build/src/github.com/theupdateframework/notary/signer/keydbstore/sql_keydbstore.go:197) -[2025-02-08 00:01:03]  sql: database is closed  ---- PASS: TestSQLCreateKey (0.18s) +[2024-01-06 17:41:11]  sql: database is closed  +--- PASS: TestSQLCreateKey (0.19s) === RUN TestSQLUnimplementedInterfaceBehavior --- PASS: TestSQLUnimplementedInterfaceBehavior (0.04s) PASS -ok github.com/theupdateframework/notary/signer/keydbstore 0.918s +ok github.com/theupdateframework/notary/signer/keydbstore 1.202s === RUN TestSet --- PASS: TestSet (0.00s) === RUN TestSetWithNoParentDirectory @@ -2985,7 +3020,7 @@ === RUN TestRemoveFile --- PASS: TestRemoveFile (0.00s) === RUN TestListFiles ---- PASS: TestListFiles (0.00s) +--- PASS: TestListFiles (0.01s) === RUN TestGetPath --- PASS: TestGetPath (0.00s) === RUN TestGetPathProtection @@ -2999,15 +3034,15 @@ === RUN TestFileStoreConsistency --- PASS: TestFileStoreConsistency (0.01s) === RUN TestHTTPStoreGetSized ---- PASS: TestHTTPStoreGetSized (0.01s) +--- PASS: TestHTTPStoreGetSized (0.00s) === RUN TestHTTPStoreGetAllMeta --- PASS: TestHTTPStoreGetAllMeta (0.00s) === RUN TestSetSingleAndSetMultiMeta --- PASS: TestSetSingleAndSetMultiMeta (0.00s) === RUN Test404Error ---- PASS: Test404Error (0.00s) +--- PASS: Test404Error (0.01s) === RUN Test50XErrors ---- PASS: Test50XErrors (0.02s) +--- PASS: Test50XErrors (0.00s) === RUN Test400Error --- PASS: Test400Error (0.00s) === RUN TestTranslateErrorsParse400Errors @@ -3023,7 +3058,7 @@ === RUN TestHTTPStoreGetKey --- PASS: TestHTTPStoreGetKey (0.00s) === RUN TestHTTPStoreGetRotateKeySizeLimited ---- PASS: TestHTTPStoreGetRotateKeySizeLimited (0.01s) +--- PASS: TestHTTPStoreGetRotateKeySizeLimited (0.11s) === RUN TestHTTPOffline --- PASS: TestHTTPOffline (0.00s) === RUN TestErrServerUnavailable @@ -3042,7 +3077,7 @@ === RUN TestMemoryStoreMetadata --- PASS: TestMemoryStoreMetadata (0.00s) PASS -ok github.com/theupdateframework/notary/storage 0.084s +ok github.com/theupdateframework/notary/storage 0.190s ? github.com/theupdateframework/notary/storage/rethinkdb [no test files] === RUN TestExportKeys --- PASS: TestExportKeys (0.00s) @@ -3055,107 +3090,107 @@ === RUN TestImportKeys --- PASS: TestImportKeys (0.00s) === RUN TestImportNoPath ---- PASS: TestImportNoPath (0.00s) +--- PASS: TestImportNoPath (0.01s) === RUN TestNonRootPathInference --- PASS: TestNonRootPathInference (0.00s) === RUN TestBlockHeaderPrecedenceRoleAndGun --- PASS: TestBlockHeaderPrecedenceRoleAndGun (0.00s) === RUN TestBlockHeaderPrecedenceGunFromPath ---- PASS: TestBlockHeaderPrecedenceGunFromPath (0.00s) +--- PASS: TestBlockHeaderPrecedenceGunFromPath (0.01s) === RUN TestImportKeys2InOneFile --- PASS: TestImportKeys2InOneFile (0.00s) === RUN TestImportKeys2InOneFileNoPath --- PASS: TestImportKeys2InOneFileNoPath (0.01s) === RUN TestEncryptedKeyImportFail -time="2025-02-08T00:01:02Z" 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-06T17:41:07Z" 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.01s) +--- PASS: TestEncryptedKeyImportSuccess (0.02s) === RUN TestEncryption --- PASS: TestEncryption (0.01s) === RUN TestAddKey ---- PASS: TestAddKey (0.03s) +--- PASS: TestAddKey (0.04s) === RUN TestKeyStoreInternalState --- PASS: TestKeyStoreInternalState (0.01s) === RUN TestGet ---- PASS: TestGet (0.03s) +--- PASS: TestGet (0.02s) === RUN TestGetLegacyKey ---- PASS: TestGetLegacyKey (0.00s) +--- PASS: TestGetLegacyKey (0.01s) === RUN TestListKeys ---- PASS: TestListKeys (0.03s) +--- PASS: TestListKeys (0.09s) === RUN TestAddGetKeyMemStore --- PASS: TestAddGetKeyMemStore (0.01s) === RUN TestAddGetKeyInfoMemStore ---- PASS: TestAddGetKeyInfoMemStore (0.02s) +--- PASS: TestAddGetKeyInfoMemStore (0.05s) === RUN TestGetDecryptedWithTamperedCipherText -time="2025-02-08T00:01:02Z" level=error msg="PEM block is empty" ---- PASS: TestGetDecryptedWithTamperedCipherText (0.00s) +time="2024-01-06T17:41:07Z" level=error msg="PEM block is empty" +--- PASS: TestGetDecryptedWithTamperedCipherText (0.02s) === RUN TestGetDecryptedWithInvalidPassphrase ---- PASS: TestGetDecryptedWithInvalidPassphrase (0.04s) +--- PASS: TestGetDecryptedWithInvalidPassphrase (0.11s) === RUN TestGetDecryptedWithConsistentlyInvalidPassphrase ---- PASS: TestGetDecryptedWithConsistentlyInvalidPassphrase (0.08s) +--- PASS: TestGetDecryptedWithConsistentlyInvalidPassphrase (0.15s) === RUN TestRemoveKey ---- PASS: TestRemoveKey (0.05s) +--- PASS: TestRemoveKey (0.09s) === RUN TestKeysAreCached --- PASS: TestKeysAreCached (0.02s) PASS -ok github.com/theupdateframework/notary/trustmanager 0.423s +ok github.com/theupdateframework/notary/trustmanager 0.777s === RUN TestRemoteStore -time="2025-02-08T00:01:04Z" level=info msg="listing files from localhost:9888" -time="2025-02-08T00:01:04Z" level=info msg="listing files from localhost:9888" ---- PASS: TestRemoteStore (0.03s) +time="2024-01-06T17:41:11Z" level=info msg="listing files from localhost:9888" +time="2024-01-06T17:41:11Z" level=info msg="listing files from localhost:9888" +--- PASS: TestRemoteStore (0.06s) === RUN TestErrors -time="2025-02-08T00:01:04Z" level=error msg="failed to store: test error" ---- PASS: TestErrors (0.03s) +time="2024-01-06T17:41:11Z" level=error msg="failed to store: test error" +--- PASS: TestErrors (0.06s) === RUN TestNewGRPCStorage --- PASS: TestNewGRPCStorage (0.00s) === RUN TestGRPCStorage --- PASS: TestGRPCStorage (0.00s) PASS -ok github.com/theupdateframework/notary/trustmanager/remoteks 0.077s +ok github.com/theupdateframework/notary/trustmanager/remoteks 0.144s ? github.com/theupdateframework/notary/trustmanager/yubikey [no test files] === RUN TestWildcardMatch --- PASS: TestWildcardMatch (0.00s) === RUN TestValidateRoot -2025/02/08 00:01:03 [INFO] generate received request -2025/02/08 00:01:03 [INFO] received CSR -2025/02/08 00:01:03 [INFO] generating key: ecdsa-256 -2025/02/08 00:01:03 [INFO] encoded CSR -2025/02/08 00:01:03 [INFO] signed certificate with serial number 297521004944359248501313549112989217321606404039 -2025/02/08 00:01:03 [INFO] received CSR -2025/02/08 00:01:03 [INFO] generating key: ecdsa-256 -2025/02/08 00:01:03 [INFO] encoded CSR -2025/02/08 00:01:03 [INFO] signed certificate with serial number 170399696811256093533094941688986501968632874174 -2025/02/08 00:01:03 [INFO] received CSR -2025/02/08 00:01:03 [INFO] generating key: ecdsa-256 -2025/02/08 00:01:03 [INFO] encoded CSR -2025/02/08 00:01:03 [INFO] signed certificate with serial number 669001181116604190733494160668259762748440428580 ---- PASS: TestValidateRoot (0.04s) +2024/01/06 17:41:10 [INFO] generate received request +2024/01/06 17:41:10 [INFO] received CSR +2024/01/06 17:41:10 [INFO] generating key: ecdsa-256 +2024/01/06 17:41:10 [INFO] encoded CSR +2024/01/06 17:41:10 [INFO] signed certificate with serial number 298024092537191068775093441572763102640949959084 +2024/01/06 17:41:10 [INFO] received CSR +2024/01/06 17:41:10 [INFO] generating key: ecdsa-256 +2024/01/06 17:41:10 [INFO] encoded CSR +2024/01/06 17:41:10 [INFO] signed certificate with serial number 596072287542570248663719491968022920076259625317 +2024/01/06 17:41:10 [INFO] received CSR +2024/01/06 17:41:10 [INFO] generating key: ecdsa-256 +2024/01/06 17:41:10 [INFO] encoded CSR +2024/01/06 17:41:10 [INFO] signed certificate with serial number 654529579745771153429012730539447941355552133523 +--- PASS: TestValidateRoot (0.09s) === RUN TestValidateRootWithoutTOFUS --- PASS: TestValidateRootWithoutTOFUS (0.00s) === RUN TestValidateRootWithPinnedCert --- PASS: TestValidateRootWithPinnedCert (0.00s) === RUN TestValidateRootWithPinnedCertAndIntermediates ---- PASS: TestValidateRootWithPinnedCertAndIntermediates (0.01s) +--- PASS: TestValidateRootWithPinnedCertAndIntermediates (0.03s) === RUN TestValidateRootFailuresWithPinnedCert ---- PASS: TestValidateRootFailuresWithPinnedCert (0.00s) +--- PASS: TestValidateRootFailuresWithPinnedCert (0.01s) === RUN TestValidateRootWithPinnedCA ---- PASS: TestValidateRootWithPinnedCA (0.08s) +--- PASS: TestValidateRootWithPinnedCA (0.11s) === RUN TestValidateSuccessfulRootRotation ---- PASS: TestValidateSuccessfulRootRotation (0.11s) +--- PASS: TestValidateSuccessfulRootRotation (0.05s) === RUN TestValidateRootRotationMissingOrigSig ---- PASS: TestValidateRootRotationMissingOrigSig (0.06s) +--- PASS: TestValidateRootRotationMissingOrigSig (0.04s) === RUN TestValidateRootRotationMissingNewSig ---- PASS: TestValidateRootRotationMissingNewSig (0.04s) +--- PASS: TestValidateRootRotationMissingNewSig (0.07s) === RUN TestValidateRootRotationTrustPinning ---- PASS: TestValidateRootRotationTrustPinning (0.06s) +--- PASS: TestValidateRootRotationTrustPinning (0.05s) === RUN TestValidateRootRotationTrustPinningInvalidCA ---- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.03s) +--- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.04s) === RUN TestParsePEMPublicKey -time="2025-02-08T00:01:03Z" level=warning msg="certificate with CN notary is near expiry" ---- PASS: TestParsePEMPublicKey (0.01s) +time="2024-01-06T17:41:10Z" level=warning msg="certificate with CN notary is near expiry" +--- PASS: TestParsePEMPublicKey (0.00s) === RUN TestCheckingCertExpiry -time="2025-02-08T00:01:03Z" level=warning msg="certificate with CN notary is near expiry" +time="2024-01-06T17:41:10Z" level=warning msg="certificate with CN notary is near expiry" --- PASS: TestCheckingCertExpiry (0.01s) === RUN TestValidateRootWithExpiredIntermediate --- PASS: TestValidateRootWithExpiredIntermediate (0.01s) @@ -3164,7 +3199,7 @@ === RUN TestWildcardMatching --- PASS: TestWildcardMatching (0.00s) PASS -ok github.com/theupdateframework/notary/trustpinning 0.484s +ok github.com/theupdateframework/notary/trustpinning 0.580s === RUN TestInitSnapshotNoTargets --- PASS: TestInitSnapshotNoTargets (0.00s) === RUN TestInitRepo @@ -3172,11 +3207,11 @@ === RUN TestUpdateDelegations --- PASS: TestUpdateDelegations (0.00s) === RUN TestPurgeDelegationsKeyFromTop -time="2025-02-08T00:01:02Z" level=warning msg="role targets/sybil has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2025-02-08T00:01:02Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-01-06T17:41:09Z" 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-06T17:41:09Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" --- PASS: TestPurgeDelegationsKeyFromTop (0.00s) === RUN TestPurgeDelegationsKeyFromDeep -time="2025-02-08T00:01:02Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2024-01-06T17:41:09Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" --- PASS: TestPurgeDelegationsKeyFromDeep (0.00s) === RUN TestPurgeDelegationsKeyBadWildRole --- PASS: TestPurgeDelegationsKeyBadWildRole (0.00s) @@ -3189,7 +3224,7 @@ === RUN TestUpdateDelegationsRoleThatIsMissingDelegationKey --- PASS: TestUpdateDelegationsRoleThatIsMissingDelegationKey (0.00s) === RUN TestUpdateDelegationsNotEnoughKeys -time="2025-02-08T00:01:02Z" level=warning msg="role targets/role has fewer keys than its threshold of 2; it will not be usable until keys are added to it" +time="2024-01-06T17:41:09Z" level=warning msg="role targets/role has fewer keys than its threshold of 2; it will not be usable until keys are added to it" --- PASS: TestUpdateDelegationsNotEnoughKeys (0.00s) === RUN TestUpdateDelegationsAddKeyToRole --- PASS: TestUpdateDelegationsAddKeyToRole (0.00s) @@ -3228,7 +3263,7 @@ === RUN TestRemoveTargetsRoleDoesntExist --- PASS: TestRemoveTargetsRoleDoesntExist (0.00s) === RUN TestRemoveTargetsNoSigningKeys ---- PASS: TestRemoveTargetsNoSigningKeys (0.00s) +--- PASS: TestRemoveTargetsNoSigningKeys (0.01s) === RUN TestAddBaseKeysToRoot --- PASS: TestAddBaseKeysToRoot (0.00s) === RUN TestRemoveBaseKeysFromRoot @@ -3256,11 +3291,11 @@ === RUN TestGetDelegationRoleKeyMissing --- PASS: TestGetDelegationRoleKeyMissing (0.00s) === RUN TestSignRootOldKeyCertExists ---- PASS: TestSignRootOldKeyCertExists (0.04s) +--- PASS: TestSignRootOldKeyCertExists (0.06s) === RUN TestSignRootOldKeyCertMissing ---- PASS: TestSignRootOldKeyCertMissing (0.02s) +--- PASS: TestSignRootOldKeyCertMissing (0.06s) === RUN TestRootKeyRotation ---- PASS: TestRootKeyRotation (0.11s) +--- PASS: TestRootKeyRotation (0.13s) === RUN TestBuilderLoadsValidRolesOnly --- PASS: TestBuilderLoadsValidRolesOnly (0.01s) === RUN TestBuilderOnlyAcceptsRootFirstWhenLoading @@ -3270,27 +3305,27 @@ === RUN TestMarkingIsValid --- PASS: TestMarkingIsValid (0.01s) === RUN TestBuilderLoadInvalidDelegations ---- PASS: TestBuilderLoadInvalidDelegations (0.02s) +--- PASS: TestBuilderLoadInvalidDelegations (0.00s) === RUN TestBuilderLoadInvalidDelegationsOldVersion ---- PASS: TestBuilderLoadInvalidDelegationsOldVersion (0.01s) +--- PASS: TestBuilderLoadInvalidDelegationsOldVersion (0.02s) === RUN TestBuilderAcceptRoleOnce --- PASS: TestBuilderAcceptRoleOnce (0.01s) === RUN TestBuilderStopsAcceptingOrProducingDataOnceDone ---- PASS: TestBuilderStopsAcceptingOrProducingDataOnceDone (0.01s) +--- PASS: TestBuilderStopsAcceptingOrProducingDataOnceDone (0.00s) === RUN TestGenerateSnapshotInvalidOperations ---- PASS: TestGenerateSnapshotInvalidOperations (0.05s) +--- PASS: TestGenerateSnapshotInvalidOperations (0.06s) === RUN TestGenerateTimestampInvalidOperations ---- PASS: TestGenerateTimestampInvalidOperations (0.07s) +--- PASS: TestGenerateTimestampInvalidOperations (0.06s) === RUN TestGetConsistentInfo ---- PASS: TestGetConsistentInfo (0.02s) +--- PASS: TestGetConsistentInfo (0.03s) === RUN TestTimestampPreAndPostChecksumming ---- PASS: TestTimestampPreAndPostChecksumming (0.04s) +--- PASS: TestTimestampPreAndPostChecksumming (0.02s) === RUN TestSnapshotLoadedFirstChecksumsOthers ---- PASS: TestSnapshotLoadedFirstChecksumsOthers (0.05s) +--- PASS: TestSnapshotLoadedFirstChecksumsOthers (0.03s) === RUN TestSnapshotLoadedAfterChecksumsOthersRetroactively ---- PASS: TestSnapshotLoadedAfterChecksumsOthersRetroactively (0.05s) +--- PASS: TestSnapshotLoadedAfterChecksumsOthersRetroactively (0.04s) PASS -ok github.com/theupdateframework/notary/tuf 0.648s +ok github.com/theupdateframework/notary/tuf 0.667s === RUN TestMergeStrSlicesExclusive --- PASS: TestMergeStrSlicesExclusive (0.00s) === RUN TestMergeStrSlicesOverlap @@ -3338,7 +3373,7 @@ === RUN TestRootFromSignedCopiesSignatures --- PASS: TestRootFromSignedCopiesSignatures (0.00s) === RUN TestRootFromSignedValidatesRoleData ---- PASS: TestRootFromSignedValidatesRoleData (0.02s) +--- PASS: TestRootFromSignedValidatesRoleData (0.01s) === RUN TestRootFromSignedValidatesRoleType --- PASS: TestRootFromSignedValidatesRoleType (0.00s) === RUN TestRootFromSignedValidatesVersion @@ -3358,7 +3393,7 @@ === RUN TestSnapshotFromSignedCopiesSignatures --- PASS: TestSnapshotFromSignedCopiesSignatures (0.00s) === RUN TestSnapshotFromSignedValidatesMeta ---- PASS: TestSnapshotFromSignedValidatesMeta (0.00s) +--- PASS: TestSnapshotFromSignedValidatesMeta (0.01s) === RUN TestSnapshotFromSignedValidatesRoleType --- PASS: TestSnapshotFromSignedValidatesRoleType (0.00s) === RUN TestSnapshotFromSignedValidatesVersion @@ -3380,7 +3415,7 @@ === RUN TestTargetsFromSignedCopiesSignatures --- PASS: TestTargetsFromSignedCopiesSignatures (0.00s) === RUN TestTargetsFromSignedValidatesDelegations ---- PASS: TestTargetsFromSignedValidatesDelegations (0.00s) +--- PASS: TestTargetsFromSignedValidatesDelegations (0.01s) === RUN TestTargetsFromSignedValidatesRoleType --- PASS: TestTargetsFromSignedValidatesRoleType (0.00s) === RUN TestTargetsFromSignedValidatesRoleName @@ -3424,7 +3459,7 @@ === RUN TestFileMetaEquals --- PASS: TestFileMetaEquals (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/data 0.053s +ok github.com/theupdateframework/notary/tuf/data 0.080s === RUN TestListKeys --- PASS: TestListKeys (0.00s) === RUN TestGetKeys @@ -3450,17 +3485,17 @@ === RUN TestErrInsufficientSignaturesMessaging --- PASS: TestErrInsufficientSignaturesMessaging (0.00s) === RUN TestRSAPSSVerifier ---- PASS: TestRSAPSSVerifier (0.02s) +--- PASS: TestRSAPSSVerifier (0.01s) === RUN TestRSAPSSx509Verifier --- PASS: TestRSAPSSx509Verifier (0.00s) === RUN TestRSAPSSVerifierWithInvalidKeyType --- PASS: TestRSAPSSVerifierWithInvalidKeyType (0.00s) === RUN TestRSAPSSVerifierWithInvalidKeyLength ---- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.08s) +--- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.12s) === RUN TestRSAPSSVerifierWithInvalidKey --- PASS: TestRSAPSSVerifierWithInvalidKey (0.00s) === RUN TestRSAPSSVerifierWithInvalidSignature ---- PASS: TestRSAPSSVerifierWithInvalidSignature (0.02s) +--- PASS: TestRSAPSSVerifierWithInvalidSignature (0.00s) === RUN TestRSAPKCS1v15Verifier --- PASS: TestRSAPKCS1v15Verifier (0.01s) === RUN TestRSAPKCS1v15x509Verifier @@ -3470,12 +3505,12 @@ === RUN TestRSAPKCS1v15VerifierWithInvalidKey --- PASS: TestRSAPKCS1v15VerifierWithInvalidKey (0.00s) === RUN TestRSAPKCS1v15VerifierWithInvalidSignature -time="2025-02-08T00:01:02Z" level=error msg="Failed verification: crypto/rsa: verification error" ---- PASS: TestRSAPKCS1v15VerifierWithInvalidSignature (0.02s) +time="2024-01-06T17:41:08Z" level=error msg="Failed verification: crypto/rsa: verification error" +--- PASS: TestRSAPKCS1v15VerifierWithInvalidSignature (0.01s) === RUN TestECDSAVerifier --- PASS: TestECDSAVerifier (0.00s) === RUN TestECDSAVerifierOtherCurves ---- PASS: TestECDSAVerifierOtherCurves (0.52s) +--- PASS: TestECDSAVerifierOtherCurves (0.60s) === RUN TestECDSAx509Verifier --- PASS: TestECDSAx509Verifier (0.00s) === RUN TestECDSAVerifierWithInvalidKeyType @@ -3513,14 +3548,14 @@ === RUN TestVerifyVersion --- PASS: TestVerifyVersion (0.00s) === RUN TestVerifyExpiry -time="2025-02-08T00:01:03Z" level=error msg="Metadata for root expired" +time="2024-01-06T17:41:09Z" level=error msg="Metadata for root expired" --- PASS: TestVerifyExpiry (0.00s) === RUN TestVerifyPublicKeyMatchesPrivateKeyHappyCase --- PASS: TestVerifyPublicKeyMatchesPrivateKeyHappyCase (0.00s) === RUN TestVerifyPublicKeyMatchesPrivateKeyFails --- PASS: TestVerifyPublicKeyMatchesPrivateKeyFails (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/signed 0.798s +ok github.com/theupdateframework/notary/tuf/signed 0.838s === RUN TestNewSwizzler --- PASS: TestNewSwizzler (0.04s) === RUN TestSwizzlerSetInvalidJSON @@ -3530,29 +3565,29 @@ === RUN TestSwizzlerSetInvalidSigned --- PASS: TestSwizzlerSetInvalidSigned (0.01s) === RUN TestSwizzlerSetInvalidSignedMeta ---- PASS: TestSwizzlerSetInvalidSignedMeta (0.02s) +--- PASS: TestSwizzlerSetInvalidSignedMeta (0.01s) === RUN TestSwizzlerSetInvalidMetadataType --- PASS: TestSwizzlerSetInvalidMetadataType (0.01s) === RUN TestSwizzlerInvalidateMetadataSignatures ---- PASS: TestSwizzlerInvalidateMetadataSignatures (0.01s) +--- PASS: TestSwizzlerInvalidateMetadataSignatures (0.02s) === RUN TestSwizzlerRemoveMetadata ---- PASS: TestSwizzlerRemoveMetadata (0.01s) +--- PASS: TestSwizzlerRemoveMetadata (0.02s) === RUN TestSwizzlerSignMetadataWithInvalidKey ---- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.02s) +--- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.03s) === RUN TestSwizzlerOffsetMetadataVersion ---- PASS: TestSwizzlerOffsetMetadataVersion (0.05s) +--- PASS: TestSwizzlerOffsetMetadataVersion (0.01s) === RUN TestSwizzlerExpireMetadata ---- PASS: TestSwizzlerExpireMetadata (0.02s) +--- PASS: TestSwizzlerExpireMetadata (0.01s) === RUN TestSwizzlerSetThresholdBaseRole --- PASS: TestSwizzlerSetThresholdBaseRole (0.01s) === RUN TestSwizzlerSetThresholdDelegatedRole --- PASS: TestSwizzlerSetThresholdDelegatedRole (0.01s) === RUN TestSwizzlerChangeRootKey ---- PASS: TestSwizzlerChangeRootKey (0.02s) +--- PASS: TestSwizzlerChangeRootKey (0.01s) === RUN TestSwizzlerUpdateSnapshotHashesSpecifiedRoles ---- PASS: TestSwizzlerUpdateSnapshotHashesSpecifiedRoles (0.02s) +--- PASS: TestSwizzlerUpdateSnapshotHashesSpecifiedRoles (0.01s) === RUN TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles ---- PASS: TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles (0.03s) +--- PASS: TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles (0.01s) === RUN TestSwizzlerUpdateTimestamp --- PASS: TestSwizzlerUpdateTimestamp (0.01s) === RUN TestMissingSigningKey @@ -3566,15 +3601,15 @@ === RUN TestSwizzlerMutateTargets --- PASS: TestSwizzlerMutateTargets (0.01s) === RUN TestSwizzlerRotateKeyBaseRole ---- PASS: TestSwizzlerRotateKeyBaseRole (0.01s) +--- PASS: TestSwizzlerRotateKeyBaseRole (0.02s) === RUN TestSwizzlerRotateKeyDelegationRole ---- PASS: TestSwizzlerRotateKeyDelegationRole (0.02s) +--- PASS: TestSwizzlerRotateKeyDelegationRole (0.01s) PASS -ok github.com/theupdateframework/notary/tuf/testutils 0.405s +ok github.com/theupdateframework/notary/tuf/testutils 0.319s ? 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.04s) +--- PASS: TestConvertTUFKeyToPKCS8 (0.05s) === RUN TestParsePKCS8ToTufKey --- PASS: TestParsePKCS8ToTufKey (0.33s) === RUN TestPEMtoPEM @@ -3616,7 +3651,7 @@ === RUN TestNewCertificate --- PASS: TestNewCertificate (0.00s) === RUN TestKeyOperations ---- PASS: TestKeyOperations (0.04s) +--- PASS: TestKeyOperations (0.09s) === RUN TestRSAX509PublickeyID --- PASS: TestRSAX509PublickeyID (0.00s) === RUN TestECDSAX509PublickeyID @@ -3632,9 +3667,9 @@ === RUN TestValidateCertificateWithInvalidExpiry --- PASS: TestValidateCertificateWithInvalidExpiry (0.00s) === RUN TestValidateCertificateWithShortKey ---- PASS: TestValidateCertificateWithShortKey (0.04s) +--- PASS: TestValidateCertificateWithShortKey (0.23s) PASS -ok github.com/theupdateframework/notary/tuf/utils 0.478s +ok github.com/theupdateframework/notary/tuf/utils 0.740s === RUN TestNewSerializableErrorNonValidationError --- PASS: TestNewSerializableErrorNonValidationError (0.00s) === RUN TestNewSerializableErrorValidationError @@ -3650,9 +3685,9 @@ === RUN TestUnmarshalInvalidJSON --- PASS: TestUnmarshalInvalidJSON (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/validation 0.022s +ok github.com/theupdateframework/notary/tuf/validation 0.074s === RUN TestSetSignalTrap ---- PASS: TestSetSignalTrap (0.00s) +--- PASS: TestSetSignalTrap (0.02s) === RUN TestLogLevelSignalHandle Attempt to increase log level failed, will remain at debug level, error: log level can not be set higher than Debug Successfully setting log level to debug @@ -3712,7 +3747,7 @@ === RUN TestParseTLSNoTLSNotRequired --- PASS: TestParseTLSNoTLSNotRequired (0.00s) === RUN TestParseTLSWithTLS ---- PASS: TestParseTLSWithTLS (0.00s) +--- PASS: TestParseTLSWithTLS (0.01s) === RUN TestParseTLSWithTLSRelativeToConfigFile --- PASS: TestParseTLSWithTLSRelativeToConfigFile (0.00s) === RUN TestParseTLSWithEnvironmentVariables @@ -3750,7 +3785,7 @@ === RUN TestDoAuthWildcardImage --- PASS: TestDoAuthWildcardImage (0.00s) PASS -ok github.com/theupdateframework/notary/utils 0.023s +ok github.com/theupdateframework/notary/utils 0.071s ? github.com/theupdateframework/notary/version [no test files] create-stamp debian/debhelper-build-stamp fakeroot debian/rules binary @@ -3805,12 +3840,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/2090345/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/2090345/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/2980511 and its subdirectories -I: Current time: Fri Feb 7 12:02:05 -12 2025 -I: pbuilder-time-stamp: 1738972925 +I: removing directory /srv/workspace/pbuilder/2090345 and its subdirectories +I: Current time: Sun Jan 7 07:42:32 +14 2024 +I: pbuilder-time-stamp: 1704562952