Diff of the two buildlogs: -- --- b1/build.log 2024-01-08 23:49:57.119620730 +0000 +++ b2/build.log 2024-01-08 23:53:11.287876779 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Mon Jan 8 11:46:40 -12 2024 -I: pbuilder-time-stamp: 1704757600 +I: Current time: Mon Feb 10 20:12:57 +14 2025 +I: pbuilder-time-stamp: 1739167977 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bullseye-reproducible-base.tgz] I: copying local configuration @@ -18,7 +18,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.1h49UBC8/trustedkeys.kbx': General error +gpgv: keyblock resource '/tmp/dpkg-verify-sig.0ssoUsfO/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 @@ -35,51 +35,82 @@ 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/30934/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/5191/tmp/hooks/D01_modify_environment starting +debug: Running on ionos16-i386. +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 Feb 10 06:13 /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/5191/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/5191/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='i386' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all,-fixfilepath parallel=8 ' - DISTRIBUTION='bullseye' - HOME='/root' - HOST_ARCH='i386' + 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]="i686-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=i386 + 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=i686 + HOST_ARCH=i386 IFS=' ' - INVOCATION_ID='c0467d9673fb4aaebe121f32f0d6d371' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - LD_LIBRARY_PATH='/usr/lib/libeatmydata' - LD_PRELOAD='libeatmydata.so' - 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='30934' - PS1='# ' - PS2='> ' + INVOCATION_ID=4c19e78501b8432b82a947e967965ebe + LANG=C + LANGUAGE=de_CH:de + LC_ALL=C + LD_LIBRARY_PATH=/usr/lib/libeatmydata + LD_PRELOAD=libeatmydata.so + MACHTYPE=i686-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=5191 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.VopuyoP6/pbuilderrc_Qsgu --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.VopuyoP6/b1 --logfile b1/build.log notary_0.6.1~ds2-6.dsc' - SUDO_GID='112' - SUDO_UID='107' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://78.137.99.97: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.VopuyoP6/pbuilderrc_v41A --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.VopuyoP6/b2 --logfile b2/build.log notary_0.6.1~ds2-6.dsc' + SUDO_GID=112 + SUDO_UID=107 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://85.184.249.68:3128 I: uname -a - Linux ionos2-i386 6.1.0-17-686-pae #1 SMP PREEMPT_DYNAMIC Debian 6.1.69-1 (2023-12-30) i686 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 5776 -rwxr-xr-x 1 root root 1367848 Mar 27 2022 bash @@ -139,7 +170,7 @@ -rwxr-xr-x 1 root root 46984 Sep 22 2020 rmdir -rwxr-xr-x 1 root root 22292 Sep 27 2020 run-parts -rwxr-xr-x 1 root root 125036 Dec 22 2018 sed - lrwxrwxrwx 1 root root 4 Jan 7 09:24 sh -> dash + lrwxrwxrwx 1 root root 9 Feb 10 06:13 sh -> /bin/bash -rwxr-xr-x 1 root root 34696 Sep 22 2020 sleep -rwxr-xr-x 1 root root 83880 Sep 22 2020 stty -rwsr-xr-x 1 root root 79396 Jan 20 2022 su @@ -165,7 +196,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/30934/tmp/hooks/D02_print_environment finished +I: user script /srv/workspace/pbuilder/5191/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -458,7 +489,7 @@ Get: 203 http://deb.debian.org/debian bullseye/main i386 golang-gopkg-fatih-pool.v2-dev all 0.0~git20171010.010e0b7-3 [5612 B] Get: 204 http://deb.debian.org/debian bullseye/main i386 golang-gopkg-gorethink-gorethink.v3-dev all 3.0.5-1.1 [113 kB] Get: 205 http://deb.debian.org/debian bullseye/main i386 openssl i386 1.1.1w-0+deb11u1 [869 kB] -Fetched 116 MB in 8s (15.0 MB/s) +Fetched 116 MB in 3s (40.3 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 ... 17763 files and directories currently installed.) @@ -1300,7 +1331,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/5191/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for bullseye +I: user script /srv/workspace/pbuilder/5191/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 @@ -1328,15 +1363,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 @@ -1364,40 +1399,40 @@ 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 8 -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 +crypto/internal/subtle +unicode/utf8 internal/unsafeheader +runtime/internal/atomic internal/race -unicode/utf8 +unicode/utf16 +crypto/subtle math/bits +vendor/golang.org/x/crypto/cryptobyte/asn1 +sync/atomic internal/cpu -runtime/internal/atomic encoding -sync/atomic runtime/internal/sys unicode -unicode/utf16 -golang.org/x/sys/internal/unsafeheader -crypto/internal/subtle -crypto/subtle -internal/testlog -vendor/golang.org/x/crypto/cryptobyte/asn1 internal/nettrace -math -internal/bytealg -runtime/internal/math container/list +golang.org/x/sys/internal/unsafeheader vendor/golang.org/x/crypto/internal/subtle -runtime/cgo google.golang.org/grpc/serviceconfig -github.com/lib/pq/oid github.com/theupdateframework/notary/version +runtime/cgo +github.com/lib/pq/oid +math +internal/testlog +internal/bytealg +runtime/internal/math runtime internal/reflectlite sync internal/singleflight google.golang.org/grpc/internal/buffer -math/rand google.golang.org/grpc/internal/grpcsync +math/rand errors sort internal/oserror @@ -1408,443 +1443,443 @@ syscall github.com/beorn7/perks/quantile hash -bytes crypto/internal/randutil -strings text/tabwriter +bytes +strings crypto/hmac hash/crc32 +hash/fnv golang.org/x/crypto/pbkdf2 vendor/golang.org/x/crypto/hkdf -hash/fnv crypto crypto/rc4 -reflect -vendor/golang.org/x/text/transform -golang.org/x/text/transform -golang.org/x/crypto/blowfish encoding/base32 -github.com/dvsekhvalnov/jose2go/padding -bufio +golang.org/x/crypto/blowfish +reflect +github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg path +google.golang.org/grpc/encoding regexp/syntax html -google.golang.org/grpc/encoding -github.com/prometheus/common/internal/bitbucket.org/ww/goautoneg +vendor/golang.org/x/text/transform +bufio +github.com/dvsekhvalnov/jose2go/padding +golang.org/x/text/transform internal/syscall/execenv internal/syscall/unix time regexp -google.golang.org/grpc/internal/grpcrand +google.golang.org/grpc/backoff +github.com/bugsnag/bugsnag-go/headers google.golang.org/grpc/keepalive context -google.golang.org/grpc/backoff +google.golang.org/grpc/internal/grpcrand internal/poll -github.com/bugsnag/bugsnag-go/headers google.golang.org/grpc/internal/backoff github.com/jinzhu/inflection google.golang.org/grpc/tap golang.org/x/net/context github.com/cenkalti/backoff -encoding/binary internal/fmtsort +encoding/binary os -encoding/base64 crypto/md5 +encoding/base64 crypto/sha512 -crypto/sha256 crypto/cipher +crypto/sha1 +vendor/golang.org/x/crypto/poly1305 +github.com/cespare/xxhash +crypto/sha256 crypto/ed25519/internal/edwards25519 golang.org/x/sys/unix -crypto/sha1 -encoding/pem -crypto/aes -crypto/des -github.com/theupdateframework/notary fmt +os/signal +google.golang.org/grpc/internal/envconfig +github.com/bugsnag/bugsnag-go/device path/filepath +runtime/debug +encoding/pem +github.com/dvsekhvalnov/jose2go/base64url net +crypto/des vendor/golang.org/x/crypto/chacha20 -google.golang.org/grpc/internal/envconfig -vendor/golang.org/x/crypto/poly1305 -github.com/bugsnag/bugsnag-go/device -os/signal -github.com/cespare/xxhash +crypto/aes +github.com/theupdateframework/notary +github.com/dvsekhvalnov/jose2go/compact io/ioutil -vendor/golang.org/x/crypto/chacha20poly1305 github.com/spf13/afero/mem os/exec +vendor/golang.org/x/crypto/chacha20poly1305 github.com/prometheus/procfs/internal/util -runtime/debug -github.com/dvsekhvalnov/jose2go/base64url -github.com/dvsekhvalnov/jose2go/compact -encoding/json +github.com/bugsnag/panicwrap log -github.com/docker/go/canonical/json encoding/hex -math/big +github.com/docker/go/canonical/json +encoding/json +mime/quotedprintable +vendor/golang.org/x/crypto/curve25519 net/url +vendor/golang.org/x/net/http2/hpack mime -mime/quotedprintable compress/flate -vendor/golang.org/x/crypto/curve25519 -vendor/golang.org/x/text/unicode/bidi +math/big vendor/golang.org/x/text/unicode/norm -vendor/golang.org/x/net/http2/hpack net/http/internal -compress/gzip -vendor/golang.org/x/text/secure/bidirule -github.com/sirupsen/logrus -github.com/theupdateframework/notary/tuf/validation github.com/miekg/pkcs11 +vendor/golang.org/x/text/unicode/bidi flag encoding/csv -github.com/fsnotify/fsnotify github.com/hashicorp/hcl/hcl/token golang.org/x/text/unicode/norm -crypto/rand -crypto/elliptic -encoding/asn1 -crypto/dsa -vendor/golang.org/x/net/idna -github.com/docker/distribution/uuid -crypto/rsa -crypto/ed25519 github.com/hashicorp/hcl/hcl/ast -golang.org/x/crypto/ed25519 github.com/hashicorp/hcl/hcl/scanner -vendor/golang.org/x/crypto/cryptobyte -crypto/x509/pkix github.com/hashicorp/hcl/json/token -github.com/pelletier/go-toml +text/template/parse github.com/hashicorp/hcl/hcl/parser github.com/hashicorp/hcl/json/scanner -text/template/parse +github.com/fsnotify/fsnotify +vendor/golang.org/x/text/secure/bidirule github.com/spf13/jwalterweatherman -github.com/hashicorp/hcl/hcl/printer -github.com/hashicorp/hcl/json/parser -crypto/ecdsa github.com/subosito/gotenv +compress/gzip +github.com/hashicorp/hcl/json/parser gopkg.in/ini.v1 -github.com/hashicorp/hcl -gopkg.in/yaml.v2 -github.com/golang/protobuf/proto golang.org/x/net/internal/timeseries -text/template +gopkg.in/yaml.v2 google.golang.org/grpc/grpclog +github.com/hashicorp/hcl/hcl/printer google.golang.org/grpc/metadata -crypto/x509 -net/textproto -vendor/golang.org/x/net/http/httpproxy -github.com/mitchellh/mapstructure -github.com/spf13/pflag -mime/multipart -vendor/golang.org/x/net/http/httpguts -html/template -google.golang.org/grpc/connectivity -google.golang.org/grpc/credentials/internal -google.golang.org/grpc/internal +github.com/hashicorp/hcl google.golang.org/grpc/attributes +google.golang.org/grpc/connectivity google.golang.org/grpc/codes google.golang.org/grpc/internal/balancerload golang.org/x/text/unicode/bidi +github.com/theupdateframework/notary/tuf/validation +github.com/sirupsen/logrus +google.golang.org/grpc/internal +github.com/golang/protobuf/proto +crypto/rand +crypto/elliptic +encoding/asn1 +crypto/dsa +github.com/pelletier/go-toml golang.org/x/net/http2/hpack -google.golang.org/grpc/internal/syscall -github.com/theupdateframework/notary/tuf/data -crypto/tls -google.golang.org/grpc/stats -github.com/golang/protobuf/ptypes/empty -google.golang.org/grpc/encoding/proto -github.com/golang/protobuf/ptypes/any -github.com/golang/protobuf/ptypes/duration -github.com/spf13/cast -github.com/golang/protobuf/ptypes/timestamp -golang.org/x/text/secure/bidirule -google.golang.org/genproto/googleapis/rpc/status -github.com/golang/protobuf/ptypes -google.golang.org/grpc/binarylog/grpc_binarylog_v1 -golang.org/x/net/idna -google.golang.org/grpc/naming -github.com/theupdateframework/notary/client/changelist -github.com/theupdateframework/notary/tuf/utils -google.golang.org/grpc/status +github.com/docker/distribution/uuid +crypto/rsa +crypto/ed25519 +vendor/golang.org/x/net/idna github.com/pkg/errors -github.com/bugsnag/panicwrap +text/template +golang.org/x/crypto/ed25519 database/sql/driver github.com/bugsnag/bugsnag-go/errors -google.golang.org/grpc/internal/binarylog github.com/opencontainers/go-digest -golang.org/x/net/http/httpguts github.com/opencontainers/image-spec/specs-go -github.com/prometheus/client_model/go -github.com/matttproud/golang_protobuf_extensions/pbutil -github.com/gofrs/uuid -database/sql +golang.org/x/text/secure/bidirule github.com/prometheus/common/model -github.com/prometheus/procfs/internal/fs github.com/docker/distribution/digestset github.com/opencontainers/image-spec/specs-go/v1 -github.com/prometheus/procfs -github.com/prometheus/client_golang/prometheus/internal -github.com/docker/distribution/reference -github.com/spf13/cobra -golang.org/x/term +golang.org/x/net/idna +database/sql +github.com/prometheus/procfs/internal/fs golang.org/x/crypto/bcrypt +golang.org/x/term +vendor/golang.org/x/crypto/cryptobyte +crypto/x509/pkix +github.com/docker/distribution/reference github.com/lib/pq/scram os/user -golang.org/x/crypto/ssh/terminal go/token github.com/hailocab/go-hostpool -github.com/theupdateframework/notary/passphrase -gopkg.in/fatih/pool.v2 -net/http/httptrace -google.golang.org/grpc/credentials -github.com/docker/go-connections/tlsconfig -github.com/go-sql-driver/mysql -github.com/docker/libtrust -net/http -google.golang.org/grpc/resolver -google.golang.org/grpc/internal/channelz -google.golang.org/grpc/balancer -google.golang.org/grpc/balancer/base -google.golang.org/grpc/balancer/roundrobin -google.golang.org/grpc/internal/resolver/dns -google.golang.org/grpc/internal/resolver/passthrough -google.golang.org/grpc/peer -go/scanner gopkg.in/gorethink/gorethink.v3/encoding -gopkg.in/gorethink/gorethink.v3/ql2 gopkg.in/gorethink/gorethink.v3/types -go/ast internal/profile runtime/pprof +html/template runtime/trace +go/scanner +golang.org/x/crypto/ssh/terminal +crypto/ecdsa github.com/dvsekhvalnov/jose2go/arrays -gopkg.in/dancannon/gorethink.v3 +github.com/davecgh/go-spew/spew +github.com/theupdateframework/notary/passphrase github.com/dvsekhvalnov/jose2go/aes github.com/dvsekhvalnov/jose2go/kdf -github.com/dvsekhvalnov/jose2go/keys/ecc -github.com/davecgh/go-spew/spew -github.com/dvsekhvalnov/jose2go -github.com/jinzhu/gorm github.com/pmezard/go-difflib/difflib +go/ast gopkg.in/yaml.v3 testing +github.com/spf13/cast +github.com/jinzhu/gorm +net/textproto +vendor/golang.org/x/net/http/httpproxy +google.golang.org/grpc/credentials/internal +google.golang.org/grpc/stats +github.com/mitchellh/mapstructure +google.golang.org/grpc/internal/syscall +github.com/spf13/pflag +crypto/x509 +google.golang.org/grpc/naming +github.com/gofrs/uuid +github.com/golang/protobuf/ptypes/empty +google.golang.org/grpc/encoding/proto +github.com/golang/protobuf/ptypes/any +github.com/golang/protobuf/ptypes/duration +mime/multipart +vendor/golang.org/x/net/http/httpguts +github.com/golang/protobuf/ptypes/timestamp +golang.org/x/net/http/httpguts +github.com/matttproud/golang_protobuf_extensions/pbutil +gopkg.in/fatih/pool.v2 +github.com/prometheus/procfs +gopkg.in/gorethink/gorethink.v3/ql2 +github.com/prometheus/client_model/go +google.golang.org/grpc/binarylog/grpc_binarylog_v1 +google.golang.org/genproto/googleapis/rpc/status +github.com/golang/protobuf/ptypes +google.golang.org/grpc/status +google.golang.org/grpc/internal/binarylog +crypto/tls +github.com/dvsekhvalnov/jose2go/keys/ecc +github.com/theupdateframework/notary/tuf/data +github.com/prometheus/client_golang/prometheus/internal +github.com/dvsekhvalnov/jose2go +github.com/spf13/cobra +github.com/theupdateframework/notary/client/changelist +github.com/theupdateframework/notary/tuf/utils +net/http/httptrace +google.golang.org/grpc/credentials +github.com/docker/go-connections/tlsconfig +github.com/docker/libtrust github.com/lib/pq +github.com/go-sql-driver/mysql +gopkg.in/dancannon/gorethink.v3 +net/http +google.golang.org/grpc/resolver +google.golang.org/grpc/internal/channelz +google.golang.org/grpc/peer +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/theupdateframework/notary/storage -golang.org/x/net/trace +github.com/docker/distribution/registry/auth +github.com/docker/distribution/registry/api/errcode github.com/magiconair/properties -github.com/bugsnag/bugsnag-go/sessions +golang.org/x/net/trace net/http/httputil +github.com/theupdateframework/notary/storage +github.com/docker/distribution/registry/client/auth/challenge +github.com/bugsnag/bugsnag-go/sessions +github.com/gorilla/mux +expvar +github.com/docker/distribution/registry/client/transport +github.com/docker/distribution github.com/spf13/afero golang.org/x/net/http2 +github.com/prometheus/common/expfmt +net/http/pprof +net/http/httptest +github.com/theupdateframework/notary/server/errors github.com/bugsnag/bugsnag-go github.com/theupdateframework/notary/trustmanager -github.com/gorilla/mux -github.com/docker/distribution/registry/api/errcode -github.com/docker/distribution/registry/auth +github.com/theupdateframework/notary/server/storage +github.com/stretchr/testify/assert +github.com/docker/distribution/context +github.com/docker/distribution/registry/api/v2 +github.com/prometheus/client_golang/prometheus +github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus github.com/theupdateframework/notary/cryptoservice github.com/theupdateframework/notary/tuf/signed github.com/Shopify/logrus-bugsnag -github.com/docker/distribution -github.com/docker/distribution/registry/client/auth/challenge -github.com/docker/distribution/registry/client/transport -expvar -github.com/prometheus/common/expfmt +github.com/docker/distribution/health +github.com/docker/distribution/registry/auth/token +github.com/docker/distribution/registry/auth/htpasswd github.com/spf13/viper -github.com/theupdateframework/notary/server/errors -github.com/docker/distribution/context github.com/theupdateframework/notary/trustmanager/yubikey github.com/theupdateframework/notary/trustpinning -github.com/theupdateframework/notary/server/storage -github.com/docker/distribution/registry/api/v2 +github.com/theupdateframework/notary/signer/keydbstore +github.com/theupdateframework/notary/tuf/testutils/keys +github.com/stretchr/testify/require github.com/theupdateframework/notary/tuf -github.com/docker/distribution/health -github.com/docker/distribution/registry/auth/htpasswd github.com/theupdateframework/notary/utils -github.com/docker/distribution/registry/auth/token -net/http/pprof -github.com/theupdateframework/notary/signer/keydbstore -github.com/prometheus/client_golang/prometheus -github.com/theupdateframework/notary/client -github.com/theupdateframework/notary/vendor/github.com/prometheus/client_golang/prometheus +github.com/prometheus/client_golang/prometheus/promhttp +github.com/theupdateframework/notary/tuf/testutils/interfaces github.com/theupdateframework/notary/server/snapshot -net/http/httptest -github.com/theupdateframework/notary/tuf/testutils/keys -google.golang.org/grpc/internal/transport +github.com/theupdateframework/notary/tuf/testutils +github.com/theupdateframework/notary/client github.com/theupdateframework/notary/server/timestamp +google.golang.org/grpc/internal/transport github.com/theupdateframework/notary/server/handlers -github.com/stretchr/testify/assert -github.com/prometheus/client_golang/prometheus/promhttp github.com/theupdateframework/notary/server -github.com/stretchr/testify/require -google.golang.org/grpc github.com/docker/go-metrics -github.com/theupdateframework/notary/tuf/testutils/interfaces -github.com/theupdateframework/notary/tuf/testutils github.com/docker/distribution/metrics github.com/docker/distribution/registry/storage/cache github.com/docker/distribution/registry/storage/cache/memory github.com/docker/distribution/registry/client github.com/docker/distribution/registry/client/auth +google.golang.org/grpc +github.com/theupdateframework/notary/cmd/notary google.golang.org/grpc/health/grpc_health_v1 -github.com/theupdateframework/notary/trustmanager/remoteks github.com/theupdateframework/notary/proto -github.com/theupdateframework/notary/cmd/notary +github.com/theupdateframework/notary/trustmanager/remoteks google.golang.org/grpc/health -github.com/theupdateframework/notary/cmd/escrow github.com/theupdateframework/notary/signer github.com/theupdateframework/notary/signer/client +github.com/theupdateframework/notary/cmd/escrow github.com/theupdateframework/notary/signer/api -github.com/theupdateframework/notary/cmd/notary-signer github.com/theupdateframework/notary/cmd/notary-server +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 8 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.04s) +--- PASS: Test0Dot1Migration (0.03s) === RUN Test0Dot3Migration ---- PASS: Test0Dot3Migration (0.03s) +--- PASS: Test0Dot3Migration (0.02s) === RUN Test0Dot1RepoFormat -time="2024-01-08T23:48:12Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot1RepoFormat (0.61s) +time="2025-02-10T06:14:23Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot1RepoFormat (0.34s) === RUN Test0Dot3RepoFormat -time="2024-01-08T23:48:13Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: Test0Dot3RepoFormat (0.74s) +time="2025-02-10T06:14:23Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: Test0Dot3RepoFormat (0.35s) === RUN TestDownloading0Dot1RepoFormat ---- PASS: TestDownloading0Dot1RepoFormat (0.03s) +--- PASS: TestDownloading0Dot1RepoFormat (0.01s) === RUN TestDownloading0Dot3RepoFormat ---- PASS: TestDownloading0Dot3RepoFormat (0.05s) +--- PASS: TestDownloading0Dot3RepoFormat (0.01s) === RUN TestInitRepositoryManagedRolesIncludingRoot --- PASS: TestInitRepositoryManagedRolesIncludingRoot (0.01s) === RUN TestInitRepositoryManagedRolesInvalidRole --- PASS: TestInitRepositoryManagedRolesInvalidRole (0.01s) === RUN TestInitRepositoryManagedRolesIncludingTargets ---- PASS: TestInitRepositoryManagedRolesIncludingTargets (0.01s) +--- PASS: TestInitRepositoryManagedRolesIncludingTargets (0.00s) === RUN TestInitRepositoryManagedRolesIncludingTimestamp ---- PASS: TestInitRepositoryManagedRolesIncludingTimestamp (0.05s) +--- PASS: TestInitRepositoryManagedRolesIncludingTimestamp (0.02s) === RUN TestInitRepositoryWithCerts ---- PASS: TestInitRepositoryWithCerts (0.07s) +--- PASS: TestInitRepositoryWithCerts (0.03s) === RUN TestMatchKeyIDsWithPublicKeys ---- PASS: TestMatchKeyIDsWithPublicKeys (0.03s) +--- PASS: TestMatchKeyIDsWithPublicKeys (0.01s) === RUN TestInitRepositoryNeedsRemoteTimestampKey ---- PASS: TestInitRepositoryNeedsRemoteTimestampKey (0.04s) +--- PASS: TestInitRepositoryNeedsRemoteTimestampKey (0.03s) === RUN TestInitRepositoryNeedsRemoteSnapshotKey ---- PASS: TestInitRepositoryNeedsRemoteSnapshotKey (0.03s) +--- PASS: TestInitRepositoryNeedsRemoteSnapshotKey (0.01s) === RUN TestInitRepoServerOnlyManagesTimestampKey ---- PASS: TestInitRepoServerOnlyManagesTimestampKey (0.29s) +--- PASS: TestInitRepoServerOnlyManagesTimestampKey (0.15s) === RUN TestInitRepoServerManagesTimestampAndSnapshotKeys ---- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.25s) +--- PASS: TestInitRepoServerManagesTimestampAndSnapshotKeys (0.12s) === RUN TestInitRepoAttemptsExceeded ---- PASS: TestInitRepoAttemptsExceeded (0.17s) +--- PASS: TestInitRepoAttemptsExceeded (0.08s) === RUN TestInitRepoPasswordInvalid --- PASS: TestInitRepoPasswordInvalid (0.02s) === RUN TestAddTargetToTargetRoleByDefault ---- PASS: TestAddTargetToTargetRoleByDefault (0.12s) +--- PASS: TestAddTargetToTargetRoleByDefault (0.05s) === RUN TestAddTargetToSpecifiedValidRoles ---- PASS: TestAddTargetToSpecifiedValidRoles (0.15s) +--- PASS: TestAddTargetToSpecifiedValidRoles (0.06s) === RUN TestAddTargetToSpecifiedInvalidRoles ---- PASS: TestAddTargetToSpecifiedInvalidRoles (0.11s) +--- PASS: TestAddTargetToSpecifiedInvalidRoles (0.05s) === RUN TestAddTargetWithInvalidTarget ---- PASS: TestAddTargetWithInvalidTarget (0.06s) +--- PASS: TestAddTargetWithInvalidTarget (0.03s) === RUN TestAddTargetErrorWritingChanges ---- PASS: TestAddTargetErrorWritingChanges (0.06s) +--- PASS: TestAddTargetErrorWritingChanges (0.03s) === RUN TestRemoveTargetToTargetRoleByDefault ---- PASS: TestRemoveTargetToTargetRoleByDefault (0.11s) +--- PASS: TestRemoveTargetToTargetRoleByDefault (0.05s) === RUN TestRemoveTargetFromSpecifiedValidRoles ---- PASS: TestRemoveTargetFromSpecifiedValidRoles (0.12s) +--- PASS: TestRemoveTargetFromSpecifiedValidRoles (0.05s) === RUN TestRemoveTargetToSpecifiedInvalidRoles ---- PASS: TestRemoveTargetToSpecifiedInvalidRoles (0.12s) +--- PASS: TestRemoveTargetToSpecifiedInvalidRoles (0.06s) === RUN TestRemoveTargetErrorWritingChanges ---- PASS: TestRemoveTargetErrorWritingChanges (0.05s) +--- PASS: TestRemoveTargetErrorWritingChanges (0.03s) === RUN TestListTarget ---- PASS: TestListTarget (1.27s) +--- PASS: TestListTarget (0.72s) === RUN TestListTargetRestrictsDelegationPaths ---- PASS: TestListTargetRestrictsDelegationPaths (0.20s) +--- PASS: TestListTargetRestrictsDelegationPaths (0.12s) === RUN TestValidateRootKey ---- PASS: TestValidateRootKey (0.10s) +--- PASS: TestValidateRootKey (0.07s) === RUN TestGetChangelist ---- PASS: TestGetChangelist (0.10s) +--- PASS: TestGetChangelist (0.09s) === RUN TestPublishBareRepo ---- PASS: TestPublishBareRepo (0.84s) +--- PASS: TestPublishBareRepo (0.61s) === RUN TestPublishUninitializedRepo -time="2024-01-08T23:48:18Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" +time="2025-02-10T06:14:26Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" --- PASS: TestPublishUninitializedRepo (0.06s) === RUN TestPublishClientHasSnapshotKey ---- PASS: TestPublishClientHasSnapshotKey (0.81s) +--- PASS: TestPublishClientHasSnapshotKey (0.63s) === RUN TestPublishAfterInitServerHasSnapshotKey ---- PASS: TestPublishAfterInitServerHasSnapshotKey (0.75s) +--- PASS: TestPublishAfterInitServerHasSnapshotKey (0.60s) === RUN TestPublishAfterPullServerHasSnapshotKey ---- PASS: TestPublishAfterPullServerHasSnapshotKey (0.27s) +--- PASS: TestPublishAfterPullServerHasSnapshotKey (0.20s) === RUN TestPublishNoOneHasSnapshotKey ---- PASS: TestPublishNoOneHasSnapshotKey (0.15s) +--- PASS: TestPublishNoOneHasSnapshotKey (0.11s) === RUN TestPublishSnapshotCorrupt ---- PASS: TestPublishSnapshotCorrupt (0.42s) +--- PASS: TestPublishSnapshotCorrupt (0.33s) === RUN TestPublishTargetsCorrupt ---- PASS: TestPublishTargetsCorrupt (0.23s) +--- PASS: TestPublishTargetsCorrupt (0.16s) === RUN TestPublishRootCorrupt -time="2024-01-08T23:48:20Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" -time="2024-01-08T23:48:21Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" ---- PASS: TestPublishRootCorrupt (0.16s) +time="2025-02-10T06:14:28Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +time="2025-02-10T06:14:28Z" level=error msg="Could not publish Repository since we could not update: invalid character 'h' in literal true (expecting 'r')" +--- PASS: TestPublishRootCorrupt (0.11s) === RUN TestPublishSnapshotLocalKeysCreatedFirst --- PASS: TestPublishSnapshotLocalKeysCreatedFirst (0.01s) === RUN TestPublishDelegations ---- PASS: TestPublishDelegations (0.31s) +--- PASS: TestPublishDelegations (0.22s) === RUN TestPublishDelegationsX509 ---- PASS: TestPublishDelegationsX509 (0.32s) +--- PASS: TestPublishDelegationsX509 (0.24s) === RUN TestPublishTargetsDelegationScopeFailIfNoKeys -time="2024-01-08T23:48:21Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" -time="2024-01-08T23:48:21Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" ---- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (0.26s) +time="2025-02-10T06:14:29Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" +time="2025-02-10T06:14:29Z" level=error msg="couldn't add target to targets/a/b: tuf: invalid role targets/a/b. delegation does not exist" +--- PASS: TestPublishTargetsDelegationScopeFailIfNoKeys (0.21s) === RUN TestPublishTargetsDelegationSuccessLocallyHasRoles ---- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (0.28s) +--- PASS: TestPublishTargetsDelegationSuccessLocallyHasRoles (0.22s) === RUN TestPublishTargetsDelegationNoTargetsKeyNeeded ---- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (0.34s) +--- PASS: TestPublishTargetsDelegationNoTargetsKeyNeeded (0.26s) === RUN TestPublishTargetsDelegationSuccessNeedsToDownloadRoles ---- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (0.33s) +--- PASS: TestPublishTargetsDelegationSuccessNeedsToDownloadRoles (0.24s) === RUN TestPublishTargetsDelegationFromTwoRepos ---- PASS: TestPublishTargetsDelegationFromTwoRepos (0.29s) +--- PASS: TestPublishTargetsDelegationFromTwoRepos (0.21s) === RUN TestPublishRemoveDelegationKeyFromDelegationRole -time="2024-01-08T23:48:23Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-08T23:48:23Z" level=error msg="couldn't add target to targets/a: could not find necessary signing keys, at least one of these keys must be available: 296a9af34a260a145976dade8f869c9ca4f7d92e6ccf764205aec6010c298530" ---- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.19s) +time="2025-02-10T06:14:30Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-02-10T06:14:30Z" 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: 2d773f683e18193d37ec588715d8b5f2b0b05b5eef5591f05ec3342f451ad315" +--- PASS: TestPublishRemoveDelegationKeyFromDelegationRole (0.14s) === RUN TestPublishRemoveDelegation -time="2024-01-08T23:48:23Z" 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-08T23:48:23Z" 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-08T23:48:23Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-08T23:48:23Z" level=error msg="couldn't add target to targets/a: could not find necessary signing keys, at least one of these keys must be available: " ---- PASS: TestPublishRemoveDelegation (0.19s) +time="2025-02-10T06:14:30Z" 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-10T06:14:30Z" 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-10T06:14:30Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-02-10T06:14:30Z" 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.14s) === RUN TestPublishSucceedsDespiteDelegationCorrupt ---- PASS: TestPublishSucceedsDespiteDelegationCorrupt (0.24s) +--- PASS: TestPublishSucceedsDespiteDelegationCorrupt (0.18s) === RUN TestRotateKeyInvalidRole ---- PASS: TestRotateKeyInvalidRole (0.11s) +--- PASS: TestRotateKeyInvalidRole (0.08s) === RUN TestRemoteRotationError ---- PASS: TestRemoteRotationError (0.02s) +--- PASS: TestRemoteRotationError (0.03s) === RUN TestRemoteRotationEndpointError ---- PASS: TestRemoteRotationEndpointError (0.07s) +--- PASS: TestRemoteRotationEndpointError (0.05s) === RUN TestRemoteRotationNoRootKey ---- PASS: TestRemoteRotationNoRootKey (0.11s) +--- PASS: TestRemoteRotationNoRootKey (0.08s) === RUN TestRemoteRotationNoInit -time="2024-01-08T23:48:24Z" level=info msg="No TUF data found locally or remotely - initializing repository docker.com/notary for the first time" ---- PASS: TestRemoteRotationNoInit (0.07s) +time="2025-02-10T06:14:31Z" 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.35s) +--- PASS: TestRotateBeforePublishFromRemoteKeyToLocalKey (0.27s) === RUN TestRotateKeyAfterPublishNoServerManagementChange ---- PASS: TestRotateKeyAfterPublishNoServerManagementChange (1.01s) +--- PASS: TestRotateKeyAfterPublishNoServerManagementChange (0.74s) === RUN TestRotateKeyAfterPublishServerManagementChange ---- PASS: TestRotateKeyAfterPublishServerManagementChange (1.12s) +--- PASS: TestRotateKeyAfterPublishServerManagementChange (0.79s) === RUN TestRotateRootKey ---- PASS: TestRotateRootKey (0.33s) +--- PASS: TestRotateRootKey (0.23s) === RUN TestRotateRootMultiple ---- PASS: TestRotateRootMultiple (0.44s) +--- PASS: TestRotateRootMultiple (0.31s) === RUN TestRotateRootKeyProvided ---- PASS: TestRotateRootKeyProvided (0.34s) +--- PASS: TestRotateRootKeyProvided (0.23s) === RUN TestRotateRootKeyLegacySupport ---- PASS: TestRotateRootKeyLegacySupport (0.50s) +--- PASS: TestRotateRootKeyLegacySupport (0.34s) === RUN TestRemoteServerUnavailableNoLocalCache -time="2024-01-08T23:48:28Z" level=error msg="Could not publish Repository since we could not update: unable to reach trust server at this time: 500." +time="2025-02-10T06:14:34Z" level=error msg="Could not publish Repository since we could not update: unable to reach trust server at this time: 500." --- PASS: TestRemoteServerUnavailableNoLocalCache (0.00s) === RUN TestAddDelegationChangefileValid --- PASS: TestAddDelegationChangefileValid (0.03s) @@ -1853,189 +1888,189 @@ === RUN TestAddDelegationErrorWritingChanges --- PASS: TestAddDelegationErrorWritingChanges (0.03s) === RUN TestRemoveDelegationChangefileValid ---- PASS: TestRemoveDelegationChangefileValid (0.04s) +--- PASS: TestRemoveDelegationChangefileValid (0.03s) === RUN TestRemoveDelegationChangefileApplicable -time="2024-01-08T23:48:28Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2024-01-08T23:48:28Z" level=warning msg="role targets/a has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2025-02-10T06:14:34Z" 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-10T06:14:34Z" 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.03s) === RUN TestFullAddDelegationChangefileApplicable ---- PASS: TestFullAddDelegationChangefileApplicable (0.04s) +--- PASS: TestFullAddDelegationChangefileApplicable (0.03s) === RUN TestFullRemoveDelegationChangefileApplicable ---- PASS: TestFullRemoveDelegationChangefileApplicable (0.04s) +--- PASS: TestFullRemoveDelegationChangefileApplicable (0.03s) === RUN TestRemoveDelegationErrorWritingChanges ---- PASS: TestRemoveDelegationErrorWritingChanges (0.04s) +--- PASS: TestRemoveDelegationErrorWritingChanges (0.03s) === RUN TestBootstrapClientBadURL --- PASS: TestBootstrapClientBadURL (0.00s) === RUN TestClientInvalidURL --- PASS: TestClientInvalidURL (0.00s) === RUN TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole ---- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (0.61s) +--- PASS: TestPublishTargetsDelegationCanUseUserKeyWithArbitraryRole (0.44s) === RUN TestDeleteRepo ---- PASS: TestDeleteRepo (0.07s) +--- PASS: TestDeleteRepo (0.05s) === RUN TestDeleteRemoteRepo ---- PASS: TestDeleteRemoteRepo (0.19s) +--- PASS: TestDeleteRemoteRepo (0.14s) === RUN TestListRoles ---- PASS: TestListRoles (0.43s) +--- PASS: TestListRoles (0.32s) === RUN TestGetAllTargetInfo ---- PASS: TestGetAllTargetInfo (0.27s) +--- PASS: TestGetAllTargetInfo (0.20s) === RUN TestUpdateSucceedsEvenIfCannotWriteNewRepo -time="2024-01-08T23:48:30Z" level=error msg="could not save root to cache: Non-writable" ---- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.22s) +time="2025-02-10T06:14:35Z" level=error msg="could not save root to cache: Non-writable" +--- PASS: TestUpdateSucceedsEvenIfCannotWriteNewRepo (0.15s) === RUN TestUpdateSucceedsEvenIfCannotWriteExistingRepo ---- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (0.99s) +--- PASS: TestUpdateSucceedsEvenIfCannotWriteExistingRepo (0.71s) === RUN TestUpdateInOfflineMode -time="2024-01-08T23:48:31Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" -time="2024-01-08T23:48:31Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateInOfflineMode (0.10s) +time="2025-02-10T06:14:36Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +time="2025-02-10T06:14:36Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateInOfflineMode (0.07s) === RUN TestUpdateReplacesCorruptOrMissingMetadata -time="2024-01-08T23:48:33Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:48:33Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateReplacesCorruptOrMissingMetadata (1.90s) +time="2025-02-10T06:14:36Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:14:36Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateReplacesCorruptOrMissingMetadata (1.36s) === RUN TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign -time="2024-01-08T23:48:33Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:48:33Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.17s) +time="2025-02-10T06:14:38Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:14:38Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateFailsIfServerRootKeyChangedWithoutMultiSign (0.12s) === RUN TestUpdateRemoteRootNotExistNoLocalCache ---- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.09s) +--- PASS: TestUpdateRemoteRootNotExistNoLocalCache (0.06s) === RUN TestUpdateRemoteRootNotExistCanUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.17s) +--- PASS: TestUpdateRemoteRootNotExistCanUseLocalCache (0.12s) === RUN TestUpdateRemoteRootNotExistCannotUseLocalCache ---- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.19s) +--- PASS: TestUpdateRemoteRootNotExistCannotUseLocalCache (0.14s) === RUN TestUpdateRemoteRoot50XNoLocalCache ---- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.09s) +--- PASS: TestUpdateRemoteRoot50XNoLocalCache (0.07s) === RUN TestUpdateRemoteRoot50XCanUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.17s) +--- PASS: TestUpdateRemoteRoot50XCanUseLocalCache (0.13s) === RUN TestUpdateRemoteRoot50XCannotUseLocalCache ---- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.19s) +--- PASS: TestUpdateRemoteRoot50XCannotUseLocalCache (0.14s) === RUN TestUpdateNonRootRemoteMissingMetadataNoLocalCache ---- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (0.44s) +--- PASS: TestUpdateNonRootRemoteMissingMetadataNoLocalCache (0.32s) === RUN TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache -time="2024-01-08T23:48:35Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (0.58s) +time="2025-02-10T06:14:39Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCanUseLocalCache (0.41s) === RUN TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache -time="2024-01-08T23:48:35Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (0.70s) +time="2025-02-10T06:14:39Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemoteMissingMetadataCannotUseLocalCache (0.51s) === RUN TestUpdateNonRootRemote50XNoLocalCache ---- PASS: TestUpdateNonRootRemote50XNoLocalCache (0.44s) +--- PASS: TestUpdateNonRootRemote50XNoLocalCache (0.31s) === RUN TestUpdateNonRootRemote50XCanUseLocalCache -time="2024-01-08T23:48:36Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (0.58s) +time="2025-02-10T06:14:40Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCanUseLocalCache (0.42s) === RUN TestUpdateNonRootRemote50XCannotUseLocalCache -time="2024-01-08T23:48:37Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" ---- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (0.70s) +time="2025-02-10T06:14:40Z" level=warning msg="Error while downloading remote metadata, using cached timestamp - this might not be the latest version available remotely" +--- PASS: TestUpdateNonRootRemote50XCannotUseLocalCache (0.52s) === RUN TestUpdateRemoteChecksumWrongNoLocalCache ---- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (0.57s) +--- PASS: TestUpdateRemoteChecksumWrongNoLocalCache (0.41s) === RUN TestUpdateRemoteChecksumWrongCanUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (0.77s) +--- PASS: TestUpdateRemoteChecksumWrongCanUseLocalCache (0.55s) === RUN TestUpdateRemoteChecksumWrongCannotUseLocalCache ---- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (0.93s) +--- PASS: TestUpdateRemoteChecksumWrongCannotUseLocalCache (0.66s) === RUN TestUpdateRootRemoteCorruptedNoLocalCache -time="2024-01-08T23:48:41Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:48:41Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (1.85s) +time="2025-02-10T06:14:43Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:14:43Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedNoLocalCache (1.32s) === RUN TestUpdateRootRemoteCorruptedCanUseLocalCache ---- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (3.34s) +--- PASS: TestUpdateRootRemoteCorruptedCanUseLocalCache (2.38s) === RUN TestUpdateRootRemoteCorruptedCannotUseLocalCache -time="2024-01-08T23:48:47Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:48:47Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (3.70s) +time="2025-02-10T06:14:47Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:14:47Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateRootRemoteCorruptedCannotUseLocalCache (2.68s) === RUN TestUpdateNonRootRemoteCorruptedNoLocalCache -time="2024-01-08T23:48:49Z" level=error msg="Metadata for targets expired" -time="2024-01-08T23:48:49Z" level=error msg="Metadata for targets expired" -time="2024-01-08T23:48:50Z" level=error msg="Metadata for snapshot expired" -time="2024-01-08T23:48:50Z" level=error msg="Metadata for snapshot expired" -time="2024-01-08T23:48:51Z" level=error msg="Metadata for timestamp expired" -time="2024-01-08T23:48:51Z" level=error msg="Metadata for timestamp expired" -time="2024-01-08T23:48:51Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-08T23:48:51Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-08T23:48:51Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-08T23:48:51Z" level=error msg="Metadata for targets/a expired" -time="2024-01-08T23:48:51Z" level=warning msg="Error getting targets/a: targets/a expired at Wed Dec 7 23:48:51 UTC 2022" -time="2024-01-08T23:48:52Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-01-08T23:48:52Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-01-08T23:48:52Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-01-08T23:48:52Z" level=error msg="Metadata for targets/a/b expired" -time="2024-01-08T23:48:52Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Wed Dec 7 23:48:52 UTC 2022" -time="2024-01-08T23:48:53Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-01-08T23:48:53Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-01-08T23:48:53Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-01-08T23:48:53Z" level=error msg="Metadata for targets/b expired" -time="2024-01-08T23:48:53Z" level=warning msg="Error getting targets/b: targets/b expired at Wed Dec 7 23:48:53 UTC 2022" ---- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (4.45s) +time="2025-02-10T06:14:49Z" level=error msg="Metadata for targets expired" +time="2025-02-10T06:14:49Z" level=error msg="Metadata for targets expired" +time="2025-02-10T06:14:50Z" level=error msg="Metadata for snapshot expired" +time="2025-02-10T06:14:50Z" level=error msg="Metadata for snapshot expired" +time="2025-02-10T06:14:50Z" level=error msg="Metadata for timestamp expired" +time="2025-02-10T06:14:50Z" level=error msg="Metadata for timestamp expired" +time="2025-02-10T06:14:51Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-02-10T06:14:51Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-02-10T06:14:51Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-02-10T06:14:51Z" level=error msg="Metadata for targets/a expired" +time="2025-02-10T06:14:51Z" level=warning msg="Error getting targets/a: targets/a expired at Tue Jan 9 06:14:51 UTC 2024" +time="2025-02-10T06:14:51Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-02-10T06:14:51Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-02-10T06:14:51Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-02-10T06:14:51Z" level=error msg="Metadata for targets/a/b expired" +time="2025-02-10T06:14:51Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Tue Jan 9 06:14:51 UTC 2024" +time="2025-02-10T06:14:52Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-02-10T06:14:52Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-02-10T06:14:52Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-02-10T06:14:52Z" level=error msg="Metadata for targets/b expired" +time="2025-02-10T06:14:52Z" level=warning msg="Error getting targets/b: targets/b expired at Tue Jan 9 06:14:52 UTC 2024" +--- PASS: TestUpdateNonRootRemoteCorruptedNoLocalCache (3.37s) === RUN TestUpdateNonRootRemoteCorruptedCanUseLocalCache -time="2024-01-08T23:48:56Z" level=error msg="Metadata for timestamp expired" -time="2024-01-08T23:48:56Z" level=error msg="Metadata for timestamp expired" ---- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (5.68s) +time="2025-02-10T06:14:54Z" level=error msg="Metadata for timestamp expired" +time="2025-02-10T06:14:54Z" level=error msg="Metadata for timestamp expired" +--- PASS: TestUpdateNonRootRemoteCorruptedCanUseLocalCache (5.27s) === RUN TestUpdateNonRootRemoteCorruptedCannotUseLocalCache -time="2024-01-08T23:49:00Z" level=error msg="Metadata for targets expired" -time="2024-01-08T23:49:01Z" level=error msg="Metadata for snapshot expired" -time="2024-01-08T23:49:01Z" level=error msg="Metadata for snapshot expired" -time="2024-01-08T23:49:02Z" level=error msg="Metadata for timestamp expired" -time="2024-01-08T23:49:02Z" level=error msg="Metadata for timestamp expired" -time="2024-01-08T23:49:03Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-08T23:49:03Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-08T23:49:03Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-08T23:49:03Z" level=error msg="Metadata for targets/a expired" -time="2024-01-08T23:49:03Z" level=warning msg="Error getting targets/a: targets/a expired at Wed Dec 7 23:49:03 UTC 2022" -time="2024-01-08T23:49:04Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-01-08T23:49:04Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-01-08T23:49:04Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-01-08T23:49:04Z" level=error msg="Metadata for targets/a/b expired" -time="2024-01-08T23:49:04Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Wed Dec 7 23:49:04 UTC 2022" -time="2024-01-08T23:49:05Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-01-08T23:49:05Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-01-08T23:49:05Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" -time="2024-01-08T23:49:05Z" level=error msg="Metadata for targets/b expired" -time="2024-01-08T23:49:05Z" level=warning msg="Error getting targets/b: targets/b expired at Wed Dec 7 23:49:05 UTC 2022" ---- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (7.24s) +time="2025-02-10T06:14:58Z" level=error msg="Metadata for targets expired" +time="2025-02-10T06:14:59Z" level=error msg="Metadata for snapshot expired" +time="2025-02-10T06:14:59Z" level=error msg="Metadata for snapshot expired" +time="2025-02-10T06:15:00Z" level=error msg="Metadata for timestamp expired" +time="2025-02-10T06:15:00Z" level=error msg="Metadata for timestamp expired" +time="2025-02-10T06:15:01Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-02-10T06:15:01Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-02-10T06:15:01Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-02-10T06:15:01Z" level=error msg="Metadata for targets/a expired" +time="2025-02-10T06:15:01Z" level=warning msg="Error getting targets/a: targets/a expired at Tue Jan 9 06:15:01 UTC 2024" +time="2025-02-10T06:15:02Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-02-10T06:15:02Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-02-10T06:15:02Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-02-10T06:15:02Z" level=error msg="Metadata for targets/a/b expired" +time="2025-02-10T06:15:02Z" level=warning msg="Error getting targets/a/b: targets/a/b expired at Tue Jan 9 06:15:02 UTC 2024" +time="2025-02-10T06:15:03Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-02-10T06:15:03Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-02-10T06:15:03Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +time="2025-02-10T06:15:04Z" level=error msg="Metadata for targets/b expired" +time="2025-02-10T06:15:04Z" level=warning msg="Error getting targets/b: targets/b expired at Tue Jan 9 06:15:04 UTC 2024" +--- PASS: TestUpdateNonRootRemoteCorruptedCannotUseLocalCache (6.71s) === RUN TestUpdateLocalAndRemoteRootCorrupt -time="2024-01-08T23:49:09Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:09Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:12Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:12Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:13Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:13Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:13Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:13Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:13Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:13Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:13Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:13Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:13Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:13Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:14Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:14Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:14Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:14Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:14Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:14Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:14Z" level=error msg="Metadata for root expired" -time="2024-01-08T23:49:14Z" level=error msg="Metadata for root expired" ---- PASS: TestUpdateLocalAndRemoteRootCorrupt (8.03s) +time="2025-02-10T06:15:08Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:08Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:13Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:13Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:13Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:13Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:13Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:13Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:13Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:13Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:13Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:14Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:14Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:14Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:14Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:14Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:15Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:15Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:15Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:15Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:15Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:15:15Z" level=error msg="Metadata for root expired" +--- PASS: TestUpdateLocalAndRemoteRootCorrupt (11.03s) === RUN TestUpdateRemoteKeyRotated -time="2024-01-08T23:49:15Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" -time="2024-01-08T23:49:15Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" -time="2024-01-08T23:49:15Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" ---- PASS: TestUpdateRemoteKeyRotated (0.74s) +time="2025-02-10T06:15:16Z" level=warning msg="Error getting targets/a: valid signatures did not meet threshold for targets/a" +time="2025-02-10T06:15:16Z" level=warning msg="Error getting targets/a/b: valid signatures did not meet threshold for targets/a/b" +time="2025-02-10T06:15:16Z" level=warning msg="Error getting targets/b: valid signatures did not meet threshold for targets/b" +--- PASS: TestUpdateRemoteKeyRotated (1.06s) === RUN TestValidateRootRotationWithOldRole ---- PASS: TestValidateRootRotationWithOldRole (0.34s) +--- PASS: TestValidateRootRotationWithOldRole (0.67s) === RUN TestRootRoleInvariant ---- PASS: TestRootRoleInvariant (0.30s) +--- PASS: TestRootRoleInvariant (0.41s) === RUN TestBadIntermediateTransitions ---- PASS: TestBadIntermediateTransitions (0.12s) +--- PASS: TestBadIntermediateTransitions (0.17s) === RUN TestExpiredIntermediateTransitions -time="2024-01-08T23:49:16Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" ---- PASS: TestExpiredIntermediateTransitions (0.14s) +time="2025-02-10T06:15:18Z" level=warning msg="root is nearing expiry, you should re-sign the role metadata" +--- PASS: TestExpiredIntermediateTransitions (0.21s) === RUN TestDownloadTargetsLarge ---- PASS: TestDownloadTargetsLarge (4.57s) +--- PASS: TestDownloadTargetsLarge (7.50s) === RUN TestDownloadTargetsDeep ---- PASS: TestDownloadTargetsDeep (0.10s) +--- PASS: TestDownloadTargetsDeep (0.14s) === RUN TestDownloadSnapshotLargeDelegationsMany ---- PASS: TestDownloadSnapshotLargeDelegationsMany (4.17s) +--- PASS: TestDownloadSnapshotLargeDelegationsMany (5.65s) === RUN TestRootOnDiskTrustPinning ---- PASS: TestRootOnDiskTrustPinning (0.07s) +--- PASS: TestRootOnDiskTrustPinning (0.10s) === RUN TestApplyTargetsChange --- PASS: TestApplyTargetsChange (0.01s) === RUN TestApplyAddTargetTwice @@ -2073,14 +2108,14 @@ === RUN TestApplyChangelistTargetsToMultipleRoles --- PASS: TestApplyChangelistTargetsToMultipleRoles (0.01s) === RUN TestApplyChangelistTargetsFailsNonexistentRole -time="2024-01-08T23:49:25Z" 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-08T23:49:25Z" 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="2025-02-10T06:15:31Z" 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-10T06:15:31Z" 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.01s) === RUN TestChangeTargetMetaFailsInvalidRole -time="2024-01-08T23:49:25Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" +time="2025-02-10T06:15:31Z" level=error msg="couldn't add target to ruhroh: tuf: invalid role ruhroh. does not exist" --- PASS: TestChangeTargetMetaFailsInvalidRole (0.01s) === RUN TestChangeTargetMetaFailsIfPrefixError -time="2024-01-08T23:49:25Z" level=error msg="couldn't add target to targets/level1: Could not add all targets" +time="2025-02-10T06:15:31Z" level=error msg="couldn't add target to targets/level1: Could not add all targets" --- PASS: TestChangeTargetMetaFailsIfPrefixError (0.01s) === RUN TestAllNearExpiry --- PASS: TestAllNearExpiry (0.01s) @@ -2089,7 +2124,7 @@ === RUN TestRotateRemoteKeyOffline --- PASS: TestRotateRemoteKeyOffline (0.00s) PASS -ok github.com/theupdateframework/notary/client 73.001s +ok github.com/theupdateframework/notary/client 68.308s === RUN TestTUFDelegation --- PASS: TestTUFDelegation (0.00s) === RUN TestMemChangelist @@ -2101,14 +2136,14 @@ === RUN TestAdd --- PASS: TestAdd (0.00s) === RUN TestErrorConditions -time="2024-01-08T23:48:08Z" level=warning msg="invalid character '\\x05' looking for beginning of value" +time="2025-02-10T06:14:20Z" 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.039s +ok github.com/theupdateframework/notary/client/changelist 0.013s === RUN TestParseConfigError --- PASS: TestParseConfigError (0.00s) === RUN TestSetupGRPCServer @@ -2118,7 +2153,7 @@ === RUN TestSetupNetListener --- PASS: TestSetupNetListener (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/escrow 0.018s +ok github.com/theupdateframework/notary/cmd/escrow 0.021s === RUN TestPurgeDelegationKeys Usage: delegation [command] @@ -2148,7 +2183,7 @@ === RUN TestAddInvalidDelegationCert --- PASS: TestAddInvalidDelegationCert (0.00s) === RUN TestAddInvalidShortPubkeyCert ---- PASS: TestAddInvalidShortPubkeyCert (0.01s) +--- PASS: TestAddInvalidShortPubkeyCert (0.00s) === RUN TestRemoveInvalidDelegationName --- PASS: TestRemoveInvalidDelegationName (0.00s) === RUN TestRemoveAllInvalidDelegationName @@ -2190,81 +2225,81 @@ Use "delegation [command] --help" for more information about a command. --- PASS: TestRemoveInvalidNumArgs (0.00s) === RUN TestInitWithRootKey ---- PASS: TestInitWithRootKey (0.28s) +--- PASS: TestInitWithRootKey (0.15s) === RUN TestInitWithRootCert ---- PASS: TestInitWithRootCert (0.19s) +--- PASS: TestInitWithRootCert (0.13s) === RUN TestClientTUFInteraction ---- PASS: TestClientTUFInteraction (0.56s) +--- PASS: TestClientTUFInteraction (0.30s) === RUN TestClientDeleteTUFInteraction -time="2024-01-08T23:48: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]:41956->[::1]:53: read: connection refused" ---- PASS: TestClientDeleteTUFInteraction (0.59s) +time="2025-02-10T06:14:24Z" 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]:60586->[::1]:53: read: connection refused" +--- PASS: TestClientDeleteTUFInteraction (0.27s) === RUN TestClientTUFAddByHashInteraction ---- PASS: TestClientTUFAddByHashInteraction (1.26s) +--- PASS: TestClientTUFAddByHashInteraction (0.47s) === RUN TestClientDelegationsInteraction -time="2024-01-08T23:48:15Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" -time="2024-01-08T23:48:15Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" ---- PASS: TestClientDelegationsInteraction (2.14s) +time="2025-02-10T06:14:25Z" 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-10T06:14:25Z" 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 (1.11s) === RUN TestClientDelegationsPublishing ---- PASS: TestClientDelegationsPublishing (1.71s) +--- PASS: TestClientDelegationsPublishing (1.31s) === RUN TestClientKeyGenerationRotation ---- PASS: TestClientKeyGenerationRotation (1.73s) +--- PASS: TestClientKeyGenerationRotation (1.35s) === RUN TestKeyRotation ---- PASS: TestKeyRotation (0.25s) +--- PASS: TestKeyRotation (0.17s) === RUN TestKeyRotationNonRoot ---- PASS: TestKeyRotationNonRoot (0.35s) +--- PASS: TestKeyRotationNonRoot (0.24s) === RUN TestDefaultRootKeyGeneration --- PASS: TestDefaultRootKeyGeneration (0.01s) === RUN TestLogLevelFlags --- PASS: TestLogLevelFlags (0.00s) === RUN TestClientKeyPassphraseChange ---- PASS: TestClientKeyPassphraseChange (0.26s) +--- PASS: TestClientKeyPassphraseChange (0.20s) === RUN TestPurgeSingleKey Removal of the following keys from all delegations in gun staged for next publish: - - 8eda3a6963e91c8364b8ca4be3a936c822a02d33c4eda72c85b8feeea432f8b1 ---- PASS: TestPurgeSingleKey (0.28s) + - 259fea4cb7e13665bc129b17a5952228e25f3de5c6638463e23406fbc3f4e0e2 +--- PASS: TestPurgeSingleKey (0.21s) === RUN TestWitness -time="2024-01-08T23:48:22Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-01-08T23:48:22Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-01-08T23:48:22Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-01-08T23:48:22Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" -time="2024-01-08T23:48:22Z" 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-08T23:48:22Z" 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-08T23:48:22Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" ---- PASS: TestWitness (0.84s) +time="2025-02-10T06:14:29Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2025-02-10T06:14:29Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2025-02-10T06:14:29Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2025-02-10T06:14:29Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +time="2025-02-10T06:14:30Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2025-02-10T06:14:30Z" level=warning msg="role targets/delegation has fewer keys than its threshold of 1; it will not be usable until keys are added to it" +time="2025-02-10T06:14:30Z" level=warning msg="Error getting targets/delegation: valid signatures did not meet threshold for targets/delegation" +--- PASS: TestWitness (0.67s) === RUN TestClientTUFInitWithAutoPublish -time="2024-01-08T23:48:22Z" 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]:33992->[::1]:53: read: connection refused" ---- PASS: TestClientTUFInitWithAutoPublish (0.19s) +time="2025-02-10T06:14:30Z" 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]:45195->[::1]:53: read: connection refused" +--- PASS: TestClientTUFInitWithAutoPublish (0.16s) === RUN TestClientTUFAddWithAutoPublish -time="2024-01-08T23:48:23Z" 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]:50468->[::1]:53: read: connection refused" -time="2024-01-08T23:48:23Z" level=error msg="Could not publish Repository since we could not update: client is offline" -time="2024-01-08T23:48:23Z" 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]:59815->[::1]:53: read: connection refused" -time="2024-01-08T23:48:23Z" level=error msg="Could not publish Repository since we could not update: client is offline" ---- PASS: TestClientTUFAddWithAutoPublish (0.22s) +time="2025-02-10T06:14:30Z" 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]:53841->[::1]:53: read: connection refused" +time="2025-02-10T06:14:30Z" level=error msg="Could not publish Repository since we could not update: client is offline" +time="2025-02-10T06:14:30Z" 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]:51587->[::1]:53: read: connection refused" +time="2025-02-10T06:14:30Z" level=error msg="Could not publish Repository since we could not update: client is offline" +--- PASS: TestClientTUFAddWithAutoPublish (0.15s) === RUN TestClientTUFRemoveWithAutoPublish ---- PASS: TestClientTUFRemoveWithAutoPublish (0.43s) +--- PASS: TestClientTUFRemoveWithAutoPublish (0.32s) === RUN TestClientDelegationAddWithAutoPublish ---- PASS: TestClientDelegationAddWithAutoPublish (0.21s) +--- PASS: TestClientDelegationAddWithAutoPublish (0.16s) === RUN TestClientDelegationRemoveWithAutoPublish -time="2024-01-08T23:48:24Z" 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-08T23:48:24Z" 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.42s) +time="2025-02-10T06:14:31Z" 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-10T06:14:31Z" 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.32s) === RUN TestClientTUFAddByHashWithAutoPublish ---- PASS: TestClientTUFAddByHashWithAutoPublish (0.20s) +--- PASS: TestClientTUFAddByHashWithAutoPublish (0.14s) === RUN TestClientKeyImport -time="2024-01-08T23:48:24Z" level=warning msg="failed to import key to store: Invalid key generated, key may be encrypted and does not contain path header" -time="2024-01-08T23:48:24Z" level=warning msg="failed to import key () to store: Cannot have canonical role key without a gun, don't know what gun it belongs to" +time="2025-02-10T06:14:31Z" 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-10T06:14:31Z" 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.08s) === RUN TestAddDelImportKeyPublishFlow -time="2024-01-08T23:48:24Z" 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.48s) +time="2025-02-10T06:14:31Z" 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.34s) === RUN TestExportImportFlow ---- PASS: TestExportImportFlow (0.13s) +--- PASS: TestExportImportFlow (0.09s) === RUN TestDelegationKeyImportExport --- PASS: TestDelegationKeyImportExport (0.01s) === RUN TestImportKeysNoYubikey --- PASS: TestImportKeysNoYubikey (0.01s) === RUN TestExportImportKeysNoYubikey ---- PASS: TestExportImportKeysNoYubikey (0.01s) +--- PASS: TestExportImportKeysNoYubikey (0.02s) === RUN TestRemoveIfNoKey --- PASS: TestRemoveIfNoKey (0.00s) === RUN TestRemoveOneKeyAbort @@ -2278,16 +2313,16 @@ === RUN TestRemoveMultikeysRemoveOnlyChosenKey --- PASS: TestRemoveMultikeysRemoveOnlyChosenKey (0.01s) === RUN TestRotateKeyInvalidRoles -time="2024-01-08T23:48:25Z" 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]:36819->[::1]:53: read: connection refused" -time="2024-01-08T23:48:25Z" 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]:53704->[::1]:53: read: connection refused" -time="2024-01-08T23:48:25Z" 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]:44263->[::1]:53: read: connection refused" -time="2024-01-08T23:48:25Z" 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]:48433->[::1]:53: read: connection refused" +time="2025-02-10T06:14:31Z" 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]:32882->[::1]:53: read: connection refused" +time="2025-02-10T06:14:31Z" 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]:55803->[::1]:53: read: connection refused" +time="2025-02-10T06:14:31Z" 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]:35923->[::1]:53: read: connection refused" +time="2025-02-10T06:14:31Z" 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]:54220->[::1]:53: read: connection refused" --- PASS: TestRotateKeyInvalidRoles (0.00s) === RUN TestRotateKeyTargetCannotBeServerManaged -time="2024-01-08T23:48:25Z" 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]:53454->[::1]:53: read: connection refused" +time="2025-02-10T06:14:31Z" 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]:58857->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTargetCannotBeServerManaged (0.00s) === RUN TestRotateKeyTimestampCannotBeLocallyManaged -time="2024-01-08T23:48:25Z" 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]:35136->[::1]:53: read: connection refused" +time="2025-02-10T06:14:31Z" 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]:42655->[::1]:53: read: connection refused" --- PASS: TestRotateKeyTimestampCannotBeLocallyManaged (0.00s) === RUN TestRotateKeyNoGUN Usage: @@ -2295,13 +2330,13 @@ === RUN TestRotateKeyRemoteServerManagesKey Successfully rotated snapshot key for repository docker.com/notary Successfully rotated timestamp key for repository docker.com/notary ---- PASS: TestRotateKeyRemoteServerManagesKey (0.21s) +--- PASS: TestRotateKeyRemoteServerManagesKey (0.15s) === RUN TestRotateKeyBothKeys Successfully rotated targets key for repository docker.com/notary Successfully rotated snapshot key for repository docker.com/notary ---- PASS: TestRotateKeyBothKeys (0.18s) +--- PASS: TestRotateKeyBothKeys (0.14s) === RUN TestRotateKeyRootIsInteractive ---- PASS: TestRotateKeyRootIsInteractive (0.04s) +--- PASS: TestRotateKeyRootIsInteractive (0.03s) === RUN TestChangeKeyPassphraseInvalidID --- PASS: TestChangeKeyPassphraseInvalidID (0.00s) === RUN TestChangeKeyPassphraseInvalidNumArgs @@ -2320,7 +2355,7 @@ === RUN TestImportKeysNonexistentFile --- PASS: TestImportKeysNonexistentFile (0.00s) === RUN TestKeyGeneration -time="2024-01-08T23:48:25Z" level=warning msg="failed to import key () to store: Cannot have canonical role key without a gun, don't know what gun it belongs to" +time="2025-02-10T06:14:32Z" 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) @@ -2339,11 +2374,11 @@ === RUN TestBareCommandPrintsUsageAndNoError --- PASS: TestBareCommandPrintsUsageAndNoError (0.00s) === RUN TestConfigFileTLSCannotBeRelativeToCWD ---- PASS: TestConfigFileTLSCannotBeRelativeToCWD (0.01s) +--- PASS: TestConfigFileTLSCannotBeRelativeToCWD (0.00s) === RUN TestConfigFileTLSCanBeRelativeToConfigOrAbsolute ---- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.09s) +--- PASS: TestConfigFileTLSCanBeRelativeToConfigOrAbsolute (0.06s) === RUN TestConfigFileOverridenByCmdLineFlags ---- PASS: TestConfigFileOverridenByCmdLineFlags (0.09s) +--- PASS: TestConfigFileOverridenByCmdLineFlags (0.06s) === RUN TestConfigFileTrustPinning --- PASS: TestConfigFileTrustPinning (0.00s) === RUN TestPassphraseRetrieverCaching @@ -2369,10 +2404,10 @@ === RUN TestImportRootCert --- PASS: TestImportRootCert (0.00s) === RUN TestTokenAuth -time="2024-01-08T23:48:25Z" level=error msg="could not reach https://localhost:9999: Get \"https://localhost:9999/v2/\": dial tcp 127.0.0.1:9999: connect: connection refused" +time="2025-02-10T06:14:32Z" level=error msg="could not reach https://localhost:9999: Get \"https://localhost:9999/v2/\": dial tcp 127.0.0.1:9999: connect: connection refused" --- PASS: TestTokenAuth (0.00s) === RUN TestAdminTokenAuth -time="2024-01-08T23:48:25Z" level=error msg="could not reach https://localhost:9999: Get \"https://localhost:9999/v2/\": dial tcp 127.0.0.1:9999: connect: connection refused" +time="2025-02-10T06:14:32Z" 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) @@ -2383,18 +2418,18 @@ === RUN TestAdminTokenAuth401Status --- PASS: TestAdminTokenAuth401Status (0.00s) === RUN TestTokenAuthNon200Non401Status -time="2024-01-08T23:48:25Z" level=error msg="could not reach http://127.0.0.1:35777: 404" +time="2025-02-10T06:14:32Z" level=error msg="could not reach http://127.0.0.1:44349: 404" --- PASS: TestTokenAuthNon200Non401Status (0.00s) === RUN TestAdminTokenAuthNon200Non401Status -time="2024-01-08T23:48:25Z" level=error msg="could not reach http://127.0.0.1:34387: 404" +time="2025-02-10T06:14:32Z" level=error msg="could not reach http://127.0.0.1:35457: 404" --- PASS: TestAdminTokenAuthNon200Non401Status (0.00s) === RUN TestConfigureRepo --- PASS: TestConfigureRepo (0.00s) === RUN TestConfigureRepoRW -Enter username: time="2024-01-08T23:48:25Z" level=error msg="error processing username input: EOF" +Enter username: time="2025-02-10T06:14:32Z" level=error msg="error processing username input: EOF" --- PASS: TestConfigureRepoRW (0.00s) === RUN TestConfigureRepoAdmin -Enter username: time="2024-01-08T23:48:25Z" level=error msg="error processing username input: EOF" +Enter username: time="2025-02-10T06:14:32Z" level=error msg="error processing username input: EOF" --- PASS: TestConfigureRepoAdmin (0.00s) === RUN TestStatusUnstageAndReset Successfully reset specified changes for repository gun @@ -2407,11 +2442,11 @@ Pushing changes to gun --- PASS: TestGetTrustPinningErrors (0.00s) === RUN TestPasswordStore -Enter username: time="2024-01-08T23:48:25Z" level=error msg="error processing username input: EOF" +Enter username: time="2025-02-10T06:14:32Z" level=error msg="error processing username input: EOF" --- PASS: TestPasswordStore (0.00s) === RUN TestPasswordStoreWithEnvvar -time="2024-01-08T23:48:25Z" level=error msg="Authentication string with zero-legnth username" -time="2024-01-08T23:48:25Z" level=error msg="Could not base64-decode authentication string" +time="2025-02-10T06:14:32Z" level=error msg="Authentication string with zero-legnth username" +time="2025-02-10T06:14:32Z" level=error msg="Could not base64-decode authentication string" --- PASS: TestPasswordStoreWithEnvvar (0.00s) === RUN TestGetPayload --- PASS: TestGetPayload (0.00s) @@ -2420,7 +2455,7 @@ === RUN TestHomeExpand --- PASS: TestHomeExpand (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary 13.606s +ok github.com/theupdateframework/notary/cmd/notary 8.989s === RUN TestBootstrap --- PASS: TestBootstrap (0.00s) === RUN TestGetAddrAndTLSConfigInvalidTLS @@ -2436,42 +2471,42 @@ === RUN TestGetInvalidTrustService --- PASS: TestGetInvalidTrustService (0.00s) === RUN TestGetLocalTrustService -time="2024-01-08T23:48:13Z" level=info msg="Using local signing service, which requires ED25519. Ignoring all other trust_service parameters, including keyAlgorithm" +time="2025-02-10T06:14:23Z" level=info msg="Using local signing service, which requires ED25519. Ignoring all other trust_service parameters, including keyAlgorithm" --- PASS: TestGetLocalTrustService (0.00s) === RUN TestGetTrustServiceInvalidKeyAlgorithm --- PASS: TestGetTrustServiceInvalidKeyAlgorithm (0.00s) === RUN TestGetTrustServiceTLSMissingCertOrKey --- PASS: TestGetTrustServiceTLSMissingCertOrKey (0.00s) === RUN TestGetTrustServiceNoTLSConfig -time="2024-01-08T23:48:13Z" level=info msg="Using remote signing service" +time="2025-02-10T06:14:23Z" level=info msg="Using remote signing service" --- PASS: TestGetTrustServiceNoTLSConfig (0.00s) === RUN TestGetTrustServiceTLSSuccess -time="2024-01-08T23:48:13Z" level=info msg="Using remote signing service" +time="2025-02-10T06:14:23Z" level=info msg="Using remote signing service" --- PASS: TestGetTrustServiceTLSSuccess (0.00s) === RUN TestGetTrustServiceTLSFailure --- PASS: TestGetTrustServiceTLSFailure (0.00s) === RUN TestGetStoreInvalid -time="2024-01-08T23:48:13Z" level=info msg="Using asdf backend" +time="2025-02-10T06:14:23Z" level=info msg="Using asdf backend" --- PASS: TestGetStoreInvalid (0.00s) === RUN TestGetStoreDBStore -time="2024-01-08T23:48:13Z" level=info msg="Using sqlite3 backend" +time="2025-02-10T06:14:23Z" level=info msg="Using sqlite3 backend" --- PASS: TestGetStoreDBStore (0.00s) === RUN TestGetStoreRethinkDBStoreConnectionFails -time="2024-01-08T23:48:13Z" level=info msg="Using rethinkdb backend" +time="2025-02-10T06:14:23Z" level=info msg="Using rethinkdb backend" --- PASS: TestGetStoreRethinkDBStoreConnectionFails (0.00s) === RUN TestGetMemoryStore -time="2024-01-08T23:48:13Z" level=info msg="Using memory backend" +time="2025-02-10T06:14:23Z" level=info msg="Using memory backend" --- PASS: TestGetMemoryStore (0.00s) === RUN TestGetCacheConfig --- PASS: TestGetCacheConfig (0.00s) === RUN TestGetGUNPRefixes --- PASS: TestGetGUNPRefixes (0.00s) === RUN TestSampleConfig -time="2024-01-08T23:48:13Z" level=info msg="Using remote signing service" -time="2024-01-08T23:48:13Z" level=info msg="Using sqlite3 backend" +time="2025-02-10T06:14:23Z" level=info msg="Using remote signing service" +time="2025-02-10T06:14:23Z" level=info msg="Using sqlite3 backend" --- PASS: TestSampleConfig (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary-server 0.039s +ok github.com/theupdateframework/notary/cmd/notary-server 0.017s === RUN TestGetAddrAndTLSConfigInvalidTLS --- PASS: TestGetAddrAndTLSConfigInvalidTLS (0.00s) === RUN TestGetAddrAndTLSConfigNoGRPCAddr @@ -2486,9 +2521,9 @@ main_test.go:121: DM - skipping malfunctioning test, fails on sbuild --- SKIP: TestSetupCryptoServicesRethinkDBStoreConnectionFails (0.00s) === RUN TestSetupCryptoServicesDBStoreSuccess ---- PASS: TestSetupCryptoServicesDBStoreSuccess (0.08s) +--- PASS: TestSetupCryptoServicesDBStoreSuccess (0.03s) === RUN TestSetupCryptoServicesMemoryStore ---- PASS: TestSetupCryptoServicesMemoryStore (0.01s) +--- PASS: TestSetupCryptoServicesMemoryStore (0.00s) === RUN TestSetupCryptoServicesInvalidStore --- PASS: TestSetupCryptoServicesInvalidStore (0.00s) === RUN TestSetupGRPCServerInvalidAddress @@ -2504,17 +2539,17 @@ === RUN TestSampleConfig --- PASS: TestSampleConfig (0.00s) PASS -ok github.com/theupdateframework/notary/cmd/notary-signer 0.122s +ok github.com/theupdateframework/notary/cmd/notary-signer 0.045s === RUN TestGenerateCertificate --- PASS: TestGenerateCertificate (0.01s) === RUN TestCryptoServiceWithNonEmptyGUN ---- PASS: TestCryptoServiceWithNonEmptyGUN (1.57s) +--- PASS: TestCryptoServiceWithNonEmptyGUN (1.41s) === RUN TestCryptoServiceWithEmptyGUN ---- PASS: TestCryptoServiceWithEmptyGUN (1.10s) +--- PASS: TestCryptoServiceWithEmptyGUN (1.08s) === RUN TestCryptoSignerInterfaceBehavior ---- PASS: TestCryptoSignerInterfaceBehavior (0.02s) +--- PASS: TestCryptoSignerInterfaceBehavior (0.01s) PASS -ok github.com/theupdateframework/notary/cryptoservice 2.724s +ok github.com/theupdateframework/notary/cryptoservice 2.531s === RUN TestGetPassphraseForUsingDelegationKey --- PASS: TestGetPassphraseForUsingDelegationKey (0.00s) === RUN TestGetPassphraseLimitsShortPassphrases @@ -2530,156 +2565,156 @@ === RUN TestGetPassphrase --- PASS: TestGetPassphrase (0.00s) PASS -ok github.com/theupdateframework/notary/passphrase 0.011s +ok github.com/theupdateframework/notary/passphrase 0.017s ? github.com/theupdateframework/notary/proto [no test files] === RUN TestValidationErrorFormat -time="2024-01-08T23:48:10Z" 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=3a1cd543d408a3ff2edc4ec0526ce54786131fcc3be653ef4afc28090d20" http.request.host="127.0.0.1:39047" http.request.id=76d3fd1b-e14c-4585-baf1-19fa32460050 http.request.method=POST http.request.remoteaddr="127.0.0.1:49506" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype="multipart/form-data; boundary=3a1cd543d408a3ff2edc4ec0526ce54786131fcc3be653ef4afc28090d20" http.request.host="127.0.0.1:39047" http.request.id=76d3fd1b-e14c-4585-baf1-19fa32460050 http.request.method=POST http.request.remoteaddr="127.0.0.1:49506" 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=10.068831ms http.response.status=400 http.response.written=260 +time="2025-02-10T06:14:21Z" 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=b8fe8f8a77cc43c725fa7eb7bd05e2d8e6f690749eaea030335fa10d6d7b" http.request.host="127.0.0.1:36933" http.request.id=6624fd23-330e-4134-985b-f432356063da http.request.method=POST http.request.remoteaddr="127.0.0.1:43364" http.request.uri=/v2/docker.com/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2025-02-10T06:14:21Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype="multipart/form-data; boundary=b8fe8f8a77cc43c725fa7eb7bd05e2d8e6f690749eaea030335fa10d6d7b" http.request.host="127.0.0.1:36933" http.request.id=6624fd23-330e-4134-985b-f432356063da http.request.method=POST http.request.remoteaddr="127.0.0.1:43364" 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=5.263989ms http.response.status=400 http.response.written=260 --- PASS: TestValidationErrorFormat (0.05s) === RUN TestRunBadAddr --- PASS: TestRunBadAddr (0.00s) === RUN TestRunReservedPort --- PASS: TestRunReservedPort (0.00s) === RUN TestRepoPrefixMatches -time="2024-01-08T23:48:10Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum e7d501f15b138fd34d2279204ead95ae5da1deb212b1560b9cafd462224e0ba3" docker.io/notary=gun go.version=go1.15.15 http.request.contenttype="multipart/form-data; boundary=809a10d88c3d6cf86f7e1fda66fbc734a58096b6b7a6356bf4f4933196b8" http.request.host="127.0.0.1:41539" http.request.id=a4221cd4-85d8-41f4-8822-174d2e1be9a7 http.request.method=POST http.request.remoteaddr="127.0.0.1:50712" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype="multipart/form-data; boundary=809a10d88c3d6cf86f7e1fda66fbc734a58096b6b7a6356bf4f4933196b8" http.request.host="127.0.0.1:41539" http.request.id=a4221cd4-85d8-41f4-8822-174d2e1be9a7 http.request.method=POST http.request.remoteaddr="127.0.0.1:50712" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=17.303145ms http.response.status=0 http.response.written=0 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:41539" http.request.id=c01633dc-6077-42ce-b288-0704e21b9b99 http.request.method=GET http.request.remoteaddr="127.0.0.1:50712" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration="654.436µs" http.response.status=200 http.response.written=683 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:41539" http.request.id=93f40476-03d3-445b-adc0-e12c1e7d708d http.request.method=GET http.request.remoteaddr="127.0.0.1:50712" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.82b28dcfb57a6117b7f8454e96a5ffb6efa84190f8c09d9644a8fc301b34d4a1.json http.request.useragent=Go-http-client/1.1 http.response.duration="151.116µs" http.response.status=200 http.response.written=683 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:41539" http.request.id=e41b2b15-5a43-410d-a50b-469097a54286 http.request.method=GET http.request.remoteaddr="127.0.0.1:50712" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="503.083µs" http.response.status=200 http.response.written=181 -time="2024-01-08T23:48:10Z" 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:41539" http.request.id=bfdaa6f9-00b8-4b29-abdd-47bb39faabf1 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:50712" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:41539" http.request.id=bfdaa6f9-00b8-4b29-abdd-47bb39faabf1 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:50712" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="74.715µs" http.response.status=0 http.response.written=0 +time="2025-02-10T06:14:22Z" level=info msg="updated docker.io/notary to timestamp version 1, checksum 623a38ddd290bc7759be85492b1f51b7a504b49f6ce390825b1a5f2763b60ab7" docker.io/notary=gun go.version=go1.15.15 http.request.contenttype="multipart/form-data; boundary=0b97857d384b8e3a647b4258dabfab376d1a4fad9557faa760ef6e028757" http.request.host="127.0.0.1:36619" http.request.id=e455dc50-52fa-4645-aa35-92d92b59baaf http.request.method=POST http.request.remoteaddr="127.0.0.1:36830" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype="multipart/form-data; boundary=0b97857d384b8e3a647b4258dabfab376d1a4fad9557faa760ef6e028757" http.request.host="127.0.0.1:36619" http.request.id=e455dc50-52fa-4645-aa35-92d92b59baaf http.request.method=POST http.request.remoteaddr="127.0.0.1:36830" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration=30.085929ms http.response.status=0 http.response.written=0 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:36619" http.request.id=f464ac02-49d6-499e-adf9-19a03a51fc68 http.request.method=GET http.request.remoteaddr="127.0.0.1:36830" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.json http.request.useragent=Go-http-client/1.1 http.response.duration="689.529µs" http.response.status=200 http.response.written=683 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:36619" http.request.id=38957b92-55e3-4e24-bb53-adc1d45f41fb http.request.method=GET http.request.remoteaddr="127.0.0.1:36830" http.request.uri=/v2/docker.io/notary/_trust/tuf/snapshot.db91d24293f821b27339ddea91af61554bda948db9179cbc1c2478b38e34619a.json http.request.useragent=Go-http-client/1.1 http.response.duration="48.267µs" http.response.status=200 http.response.written=683 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:36619" http.request.id=c3ac1b5d-d3d9-4303-bf12-e11adcdea69e http.request.method=GET http.request.remoteaddr="127.0.0.1:36830" http.request.uri=/v2/docker.io/notary/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="511.56µs" http.response.status=200 http.response.written=181 +time="2025-02-10T06:14:22Z" 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:36619" http.request.id=dd4ea33e-4750-4946-831c-00857f16e175 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:36830" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:36619" http.request.id=dd4ea33e-4750-4946-831c-00857f16e175 http.request.method=DELETE http.request.remoteaddr="127.0.0.1:36830" http.request.uri=/v2/docker.io/notary/_trust/tuf/ http.request.useragent=Go-http-client/1.1 http.response.duration="138.412µs" http.response.status=0 http.response.written=0 --- PASS: TestRepoPrefixMatches (0.07s) === RUN TestRepoPrefixDoesNotMatch --- PASS: TestRepoPrefixDoesNotMatch (0.05s) === RUN TestMetricsEndpoint --- PASS: TestMetricsEndpoint (0.01s) === RUN TestGetKeysEndpoint -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:38741" http.request.id=956c6203-f04d-4c08-93a4-c10983d876b6 http.request.method=GET http.request.remoteaddr="127.0.0.1:54178" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="577.696µs" http.response.status=200 http.response.written=103 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:38741" http.request.id=30f05ca2-17cc-49e5-bad4-3b741fce7e4b http.request.method=GET http.request.remoteaddr="127.0.0.1:54180" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="428.355µs" http.response.status=200 http.response.written=103 -time="2024-01-08T23:48:10Z" 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:38741" http.request.id=03f04922-6732-4c31-abac-2443a97c9b5d http.request.method=GET http.request.remoteaddr="127.0.0.1:54190" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:38741" http.request.id=03f04922-6732-4c31-abac-2443a97c9b5d http.request.method=GET http.request.remoteaddr="127.0.0.1:54190" 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="95.611µs" http.response.status=404 http.response.written=104 -time="2024-01-08T23:48:10Z" 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:38741" http.request.id=2e07b813-7500-42a3-97cd-8fbf61ddf63e http.request.method=GET http.request.remoteaddr="127.0.0.1:54200" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:38741" http.request.id=2e07b813-7500-42a3-97cd-8fbf61ddf63e http.request.method=GET http.request.remoteaddr="127.0.0.1:54200" 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="68.391µs" http.response.status=404 http.response.written=104 -time="2024-01-08T23:48:10Z" 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:38741" http.request.id=8da3fb24-485a-4858-ae29-a2518bee5b5b http.request.method=GET http.request.remoteaddr="127.0.0.1:54204" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:38741" http.request.id=8da3fb24-485a-4858-ae29-a2518bee5b5b http.request.method=GET http.request.remoteaddr="127.0.0.1:54204" 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="198.324µs" http.response.status=404 http.response.written=104 ---- PASS: TestGetKeysEndpoint (0.02s) +time="2025-02-10T06:14:22Z" 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:38669" http.request.id=f428abb8-bdc8-4f7c-8ed6-981486fb7808 http.request.method=GET http.request.remoteaddr="127.0.0.1:45518" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:38669" http.request.id=f428abb8-bdc8-4f7c-8ed6-981486fb7808 http.request.method=GET http.request.remoteaddr="127.0.0.1:45518" 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="131.542µs" http.response.status=404 http.response.written=104 +time="2025-02-10T06:14:22Z" 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:38669" http.request.id=971d34d5-593a-48f1-baf1-d83887c2f043 http.request.method=GET http.request.remoteaddr="127.0.0.1:45520" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:38669" http.request.id=971d34d5-593a-48f1-baf1-d83887c2f043 http.request.method=GET http.request.remoteaddr="127.0.0.1:45520" 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="75.445µs" http.response.status=404 http.response.written=104 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:38669" http.request.id=c2a91753-5b10-4ebe-b483-401892b25d14 http.request.method=GET http.request.remoteaddr="127.0.0.1:45534" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="622.718µs" http.response.status=200 http.response.written=103 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:38669" http.request.id=1ad75791-2185-4c5e-9363-a788af85ca5c http.request.method=GET http.request.remoteaddr="127.0.0.1:45540" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="518.946µs" http.response.status=200 http.response.written=103 +time="2025-02-10T06:14:22Z" 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:38669" http.request.id=4497dfa4-3b84-4855-ac2b-0544a3b82bfd http.request.method=GET http.request.remoteaddr="127.0.0.1:45544" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:38669" http.request.id=4497dfa4-3b84-4855-ac2b-0544a3b82bfd http.request.method=GET http.request.remoteaddr="127.0.0.1:45544" 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="83.314µs" http.response.status=404 http.response.written=104 +--- PASS: TestGetKeysEndpoint (0.01s) === RUN TestGetRoleByHash -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:40415" http.request.id=5570e9bc-99cb-434b-831b-2b3498f8a248 http.request.method=GET http.request.remoteaddr="127.0.0.1:56774" http.request.uri=/v2/gun/_trust/tuf/timestamp.31fb390f06e81f1f67d0c0c6dc375b956f40c0c4fda19d0617e39e451d639039.json http.request.useragent=Go-http-client/1.1 http.response.duration="72.195µs" http.response.status=200 http.response.written=115 ---- PASS: TestGetRoleByHash (0.02s) +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:43843" http.request.id=614d070d-1611-4d53-86ab-bec48dc38666 http.request.method=GET http.request.remoteaddr="127.0.0.1:41962" http.request.uri=/v2/gun/_trust/tuf/timestamp.10ddab7933d8ca5045f00d0d9b9a221a99a16037ac98ac6a7aa43b93aa7e5e69.json http.request.useragent=Go-http-client/1.1 http.response.duration="98.791µs" http.response.status=200 http.response.written=115 +--- PASS: TestGetRoleByHash (0.01s) === RUN TestGetRoleByVersion -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:39145" http.request.id=ed7ff1de-28e7-4065-87cc-b7bcc4dedb4c http.request.method=GET http.request.remoteaddr="127.0.0.1:58956" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="71.041µs" http.response.status=200 http.response.written=115 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:40795" http.request.id=09ec4450-082d-4ab0-9957-08d19da5499a http.request.method=GET http.request.remoteaddr="127.0.0.1:57254" http.request.uri=/v2/gun/_trust/tuf/1.timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="64.356µs" http.response.status=200 http.response.written=115 --- PASS: TestGetRoleByVersion (0.00s) === RUN TestGetCurrentRole -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:38517" http.request.id=a6a9cc72-b55e-41bf-9f40-71d1145814ce http.request.method=GET http.request.remoteaddr="127.0.0.1:47450" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="585.412µs" http.response.status=200 http.response.written=495 ---- PASS: TestGetCurrentRole (0.05s) +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:38283" http.request.id=9ecc0eec-2ae7-4fe7-ad23-d297087b6d9d http.request.method=GET http.request.remoteaddr="127.0.0.1:36074" http.request.uri=/v2/gun/_trust/tuf/timestamp.json http.request.useragent=Go-http-client/1.1 http.response.duration="667.728µs" http.response.status=200 http.response.written=495 +--- PASS: TestGetCurrentRole (0.04s) === RUN TestRotateKeyEndpoint -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:46593" http.request.id=5af89f57-f57c-43d6-a830-a54d2798c1ae http.request.method=POST http.request.remoteaddr="127.0.0.1:51250" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="574.576µs" http.response.status=200 http.response.written=103 -time="2024-01-08T23:48:10Z" 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:46593" http.request.id=6621630d-287c-479d-be86-042cd36906c2 http.request.method=POST http.request.remoteaddr="127.0.0.1:51260" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:46593" http.request.id=6621630d-287c-479d-be86-042cd36906c2 http.request.method=POST http.request.remoteaddr="127.0.0.1:51260" 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="83.531µs" http.response.status=404 http.response.written=104 -time="2024-01-08T23:48:10Z" 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:46593" http.request.id=d3f95ae0-7d33-4cad-a24f-51fde3d08875 http.request.method=POST http.request.remoteaddr="127.0.0.1:51264" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:46593" http.request.id=d3f95ae0-7d33-4cad-a24f-51fde3d08875 http.request.method=POST http.request.remoteaddr="127.0.0.1:51264" 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="89.712µs" http.response.status=404 http.response.written=104 -time="2024-01-08T23:48:10Z" 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:46593" http.request.id=9817f848-3394-41e7-9c10-eee2426f0542 http.request.method=POST http.request.remoteaddr="127.0.0.1:51268" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:46593" http.request.id=9817f848-3394-41e7-9c10-eee2426f0542 http.request.method=POST http.request.remoteaddr="127.0.0.1:51268" 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="72.84µs" http.response.status=404 http.response.written=104 -time="2024-01-08T23:48:10Z" 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:46593" http.request.id=267cc45b-d45b-410a-aeb6-57a4580203c9 http.request.method=POST http.request.remoteaddr="127.0.0.1:51274" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:46593" http.request.id=267cc45b-d45b-410a-aeb6-57a4580203c9 http.request.method=POST http.request.remoteaddr="127.0.0.1:51274" 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="92.992µs" http.response.status=404 http.response.written=104 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:46593" http.request.id=395e71a3-4721-4659-acd8-714c0ecc2902 http.request.method=POST http.request.remoteaddr="127.0.0.1:51286" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="452.515µs" http.response.status=200 http.response.written=103 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:36521" http.request.id=95ddeb34-319d-4637-900a-5fa0a89fad62 http.request.method=POST http.request.remoteaddr="127.0.0.1:56928" http.request.uri=/v2/gun/_trust/tuf/snapshot.key http.request.useragent=Go-http-client/1.1 http.response.duration="630.446µs" http.response.status=200 http.response.written=103 +time="2025-02-10T06:14:22Z" 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:36521" http.request.id=0e6b156f-b251-47c8-8d91-30823b9a66e5 http.request.method=POST http.request.remoteaddr="127.0.0.1:56938" http.request.uri=/v2/gun/_trust/tuf/targets.key http.request.useragent=Go-http-client/1.1 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:36521" http.request.id=0e6b156f-b251-47c8-8d91-30823b9a66e5 http.request.method=POST http.request.remoteaddr="127.0.0.1:56938" 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="137.405µs" http.response.status=404 http.response.written=104 +time="2025-02-10T06:14:22Z" 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:36521" http.request.id=b9f914e0-d694-43b6-8144-6fe159992077 http.request.method=POST http.request.remoteaddr="127.0.0.1:56950" http.request.uri=/v2/gun/_trust/tuf/root.key http.request.useragent=Go-http-client/1.1 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:36521" http.request.id=b9f914e0-d694-43b6-8144-6fe159992077 http.request.method=POST http.request.remoteaddr="127.0.0.1:56950" 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="117.976µs" http.response.status=404 http.response.written=104 +time="2025-02-10T06:14:22Z" 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:36521" http.request.id=79b254de-32b9-4720-97c0-5521a1121391 http.request.method=POST http.request.remoteaddr="127.0.0.1:56952" http.request.uri=/v2/gun/_trust/tuf/targets/delegation.key http.request.useragent=Go-http-client/1.1 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:36521" http.request.id=79b254de-32b9-4720-97c0-5521a1121391 http.request.method=POST http.request.remoteaddr="127.0.0.1:56952" 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="109.779µs" http.response.status=404 http.response.written=104 +time="2025-02-10T06:14:22Z" 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:36521" http.request.id=75d3c8e1-44d2-401a-8e25-a46d7d7a6f95 http.request.method=POST http.request.remoteaddr="127.0.0.1:56956" http.request.uri=/v2/gun/_trust/tuf/somerandomrole.key http.request.useragent=Go-http-client/1.1 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:36521" http.request.id=75d3c8e1-44d2-401a-8e25-a46d7d7a6f95 http.request.method=POST http.request.remoteaddr="127.0.0.1:56956" 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="119.609µs" http.response.status=404 http.response.written=104 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.contenttype=text/plain http.request.host="127.0.0.1:36521" http.request.id=05dce9e4-44d0-4e64-88f5-efa8e0d1f471 http.request.method=POST http.request.remoteaddr="127.0.0.1:56962" http.request.uri=/v2/gun/_trust/tuf/timestamp.key http.request.useragent=Go-http-client/1.1 http.response.duration="526.825µs" http.response.status=200 http.response.written=103 --- PASS: TestRotateKeyEndpoint (0.01s) PASS -ok github.com/theupdateframework/notary/server 0.312s +ok github.com/theupdateframework/notary/server 0.273s ? github.com/theupdateframework/notary/server/errors [no test files] === RUN Test_changefeed --- PASS: Test_changefeed (0.00s) === RUN Test_checkChangefeedInputs -time="2024-01-08T23:48:10Z" level=error msg="500 GET unable to retrieve storage" -time="2024-01-08T23:48:10Z" level=error msg="400 GET invalid pageSize: not_a_number" +time="2025-02-10T06:14:22Z" level=error msg="500 GET unable to retrieve storage" +time="2025-02-10T06:14:22Z" level=error msg="400 GET invalid pageSize: not_a_number" --- PASS: Test_checkChangefeedInputs (0.00s) === RUN TestMainHandlerGet -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:46499" http.request.id=909df5e6-d764-48c9-8731-939a743e9c64 http.request.method=GET http.request.remoteaddr="127.0.0.1:44328" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="33.681µs" http.response.status=200 http.response.written=2 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:45233" http.request.id=b45b1f47-6e54-4214-9f7f-ab164273b5b9 http.request.method=GET http.request.remoteaddr="127.0.0.1:58120" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.duration="68.446µs" http.response.status=200 http.response.written=2 --- PASS: TestMainHandlerGet (0.00s) === RUN TestMainHandlerNotGet -time="2024-01-08T23:48: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:39477" http.request.id=744ef8f8-9b79-42e9-83b8-85d9d3e0b5a7 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:56810" http.request.uri=/ http.request.useragent=Go-http-client/1.1 -time="2024-01-08T23:48:10Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:39477" http.request.id=744ef8f8-9b79-42e9-83b8-85d9d3e0b5a7 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:56810" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="147.793µs" http.response.status=404 http.response.written=105 +time="2025-02-10T06:14:22Z" 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:33285" http.request.id=378237b1-72a3-4376-b176-3fe7189bcfb7 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:43038" http.request.uri=/ http.request.useragent=Go-http-client/1.1 +time="2025-02-10T06:14:22Z" level=info msg="response completed" go.version=go1.15.15 http.request.host="127.0.0.1:33285" http.request.id=378237b1-72a3-4376-b176-3fe7189bcfb7 http.request.method=HEAD http.request.remoteaddr="127.0.0.1:43038" http.request.uri=/ http.request.useragent=Go-http-client/1.1 http.response.contenttype="application/json; charset=utf-8" http.response.duration="217.982µs" http.response.status=404 http.response.written=105 --- PASS: TestMainHandlerNotGet (0.00s) === RUN TestKeyHandlersInvalidConfiguration -time="2024-01-08T23:48:10Z" level=error msg="500 GET storage not configured" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=error msg="500 GET storage not configured" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=error msg="500 GET crypto service not configured" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=error msg="500 GET crypto service not configured" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=error msg="500 GET key algorithm not configured" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=error msg="500 GET key algorithm not configured" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=error msg="500 POST storage not configured" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=error msg="500 POST storage not configured" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=error msg="500 POST crypto service not configured" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=error msg="500 POST crypto service not configured" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=error msg="500 POST key algorithm not configured" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=error msg="500 POST key algorithm not configured" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 GET storage not configured" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 GET storage not configured" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 GET crypto service not configured" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 GET crypto service not configured" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 GET key algorithm not configured" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 GET key algorithm not configured" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 POST storage not configured" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 POST storage not configured" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 POST crypto service not configured" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 POST crypto service not configured" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 POST key algorithm not configured" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 POST key algorithm not configured" go.version=go1.15.15 gun=gun --- PASS: TestKeyHandlersInvalidConfiguration (0.00s) === RUN TestKeyHandlersNoRoleOrRepo -time="2024-01-08T23:48:10Z" level=info msg="400 GET no gun in request" =gun go.version=go1.15.15 -time="2024-01-08T23:48:10Z" level=info msg="400 GET no gun in request" =gun go.version=go1.15.15 -time="2024-01-08T23:48:10Z" level=info msg="400 GET no role in request" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=info msg="400 GET no role in request" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=info msg="400 POST no gun in request" =gun go.version=go1.15.15 -time="2024-01-08T23:48:10Z" level=info msg="400 POST no gun in request" =gun go.version=go1.15.15 -time="2024-01-08T23:48:10Z" level=info msg="400 POST no role in request" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=info msg="400 POST no role in request" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=info msg="400 GET no gun in request" =gun go.version=go1.15.15 +time="2025-02-10T06:14:22Z" level=info msg="400 GET no gun in request" =gun go.version=go1.15.15 +time="2025-02-10T06:14:22Z" level=info msg="400 GET no role in request" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=info msg="400 GET no role in request" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=info msg="400 POST no gun in request" =gun go.version=go1.15.15 +time="2025-02-10T06:14:22Z" level=info msg="400 POST no gun in request" =gun go.version=go1.15.15 +time="2025-02-10T06:14:22Z" level=info msg="400 POST no role in request" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=info msg="400 POST no role in request" go.version=go1.15.15 gun=gun --- PASS: TestKeyHandlersNoRoleOrRepo (0.00s) === RUN TestKeyHandlersInvalidRole -time="2024-01-08T23:48:10Z" level=info msg="400 GET root key: " go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=info msg="400 GET targets key: " go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=info msg="400 GET targets/a key: " go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=info msg="400 GET invalidrole key: " go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=info msg="400 POST root key: " go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=info msg="400 POST targets key: " go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=info msg="400 POST targets/a key: " go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=info msg="400 POST invalidrole key: " go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=info msg="400 GET root key: " go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=info msg="400 GET targets key: " go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=info msg="400 GET targets/a key: " go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=info msg="400 GET invalidrole key: " go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=info msg="400 POST root key: " go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=info msg="400 POST targets key: " go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=info msg="400 POST targets/a key: " go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" 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="2024-01-08T23:48:10Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun -time="2024-01-08T23:48:10Z" level=error msg="500 POST snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 GET timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 GET snapshot key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=error msg="500 POST timestamp key: only ED25519 supported by this cryptoservice" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" 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="2024-01-08T23:48:10Z" level=info msg="404 GET root role" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=info msg="404 GET root role" go.version=go1.15.15 gun=gun --- PASS: TestGetHandlerRoot (0.02s) === RUN TestGetHandlerTimestamp ---- PASS: TestGetHandlerTimestamp (0.03s) +--- PASS: TestGetHandlerTimestamp (0.02s) === RUN TestGetHandlerSnapshot --- PASS: TestGetHandlerSnapshot (0.02s) === RUN TestGetHandler404 -time="2024-01-08T23:48:10Z" level=info msg="404 GET root role" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=info msg="404 GET root role" go.version=go1.15.15 gun=gun --- PASS: TestGetHandler404 (0.00s) === RUN TestGetHandlerNilData -time="2024-01-08T23:48:10Z" level=info msg="404 GET root role" go.version=go1.15.15 gun=gun +time="2025-02-10T06:14:22Z" level=info msg="404 GET root role" go.version=go1.15.15 gun=gun --- PASS: TestGetHandlerNilData (0.00s) === RUN TestGetHandlerNoStorage -time="2024-01-08T23:48:10Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.15.15 +time="2025-02-10T06:14:22Z" level=error msg="500 GET: no storage exists" =gun go.version=go1.15.15 --- PASS: TestGetHandlerNoStorage (0.00s) === RUN TestAtomicUpdateValidationFailurePropagated ---- PASS: TestAtomicUpdateValidationFailurePropagated (0.07s) +--- PASS: TestAtomicUpdateValidationFailurePropagated (0.04s) === RUN TestAtomicUpdateNonValidationFailureNotPropagated -time="2024-01-08T23:48:10Z" level=info msg="400 POST error validating update" go.version=go1.15.15 testGUN=gun +time="2025-02-10T06:14:22Z" level=info msg="400 POST error validating update" go.version=go1.15.15 testGUN=gun --- PASS: TestAtomicUpdateNonValidationFailureNotPropagated (0.04s) === RUN TestAtomicUpdateVersionErrorPropagated -time="2024-01-08T23:48:10Z" level=info msg="400 POST old version error" go.version=go1.15.15 testGUN=gun +time="2025-02-10T06:14:22Z" level=info msg="400 POST old version error" go.version=go1.15.15 testGUN=gun --- PASS: TestAtomicUpdateVersionErrorPropagated (0.05s) === RUN TestGetMaybeServerSignedNoCrypto --- PASS: TestGetMaybeServerSignedNoCrypto (0.00s) === RUN TestGetMaybeServerSignedNoKey --- PASS: TestGetMaybeServerSignedNoKey (0.00s) === RUN TestValidateEmptyNew ---- PASS: TestValidateEmptyNew (0.06s) +--- PASS: TestValidateEmptyNew (0.05s) === RUN TestValidateRootCanContainOnlyx509KeysWithRightGun ---- PASS: TestValidateRootCanContainOnlyx509KeysWithRightGun (0.07s) +--- PASS: TestValidateRootCanContainOnlyx509KeysWithRightGun (0.05s) === RUN TestValidatePrevTimestamp ---- PASS: TestValidatePrevTimestamp (0.07s) +--- PASS: TestValidatePrevTimestamp (0.05s) === RUN TestValidatePreviousTimestampCorrupt -time="2024-01-08T23:48:11Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" +time="2025-02-10T06:14:22Z" level=error msg="Failed to unmarshal existing timestamp for GUN docker.com/notary" --- PASS: TestValidatePreviousTimestampCorrupt (0.05s) === RUN TestValidateGetCurrentTimestampBroken --- PASS: TestValidateGetCurrentTimestampBroken (0.05s) @@ -2690,93 +2725,93 @@ === RUN TestValidateOnlySnapshot --- PASS: TestValidateOnlySnapshot (0.04s) === RUN TestValidateOldRoot ---- PASS: TestValidateOldRoot (0.07s) +--- PASS: TestValidateOldRoot (0.06s) === RUN TestValidateOldRootCorrupt --- PASS: TestValidateOldRootCorrupt (0.04s) === RUN TestValidateOldRootCorruptRootRole --- PASS: TestValidateOldRootCorruptRootRole (0.04s) === RUN TestValidateRootGetCurrentRootBroken ---- PASS: TestValidateRootGetCurrentRootBroken (0.05s) +--- PASS: TestValidateRootGetCurrentRootBroken (0.04s) === RUN TestValidateRootRotationWithOldSigs ---- PASS: TestValidateRootRotationWithOldSigs (0.16s) +--- PASS: TestValidateRootRotationWithOldSigs (0.18s) === RUN TestValidateRootRotationMultipleKeysThreshold1 ---- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.14s) +--- PASS: TestValidateRootRotationMultipleKeysThreshold1 (0.10s) === RUN TestRootRotationNotSignedWithOldKeysForOldRole ---- PASS: TestRootRotationNotSignedWithOldKeysForOldRole (0.20s) +--- PASS: TestRootRotationNotSignedWithOldKeysForOldRole (0.13s) === RUN TestRootRotationVersionIncrement ---- PASS: TestRootRotationVersionIncrement (0.10s) +--- PASS: TestRootRotationVersionIncrement (0.09s) === RUN TestValidateNoRoot ---- PASS: TestValidateNoRoot (0.04s) +--- PASS: TestValidateNoRoot (0.02s) === RUN TestValidateSnapshotMissingNoSnapshotKey ---- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.05s) +--- PASS: TestValidateSnapshotMissingNoSnapshotKey (0.02s) === RUN TestValidateSnapshotGenerateNoPrev ---- PASS: TestValidateSnapshotGenerateNoPrev (0.06s) +--- PASS: TestValidateSnapshotGenerateNoPrev (0.03s) === RUN TestValidateSnapshotGenerateWithPrev ---- PASS: TestValidateSnapshotGenerateWithPrev (0.07s) +--- PASS: TestValidateSnapshotGenerateWithPrev (0.04s) === RUN TestValidateSnapshotGeneratePrevCorrupt -time="2024-01-08T23:48:12Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" ---- PASS: TestValidateSnapshotGeneratePrevCorrupt (0.06s) +time="2025-02-10T06:14:23Z" level=error msg="Failed to unmarshal existing snapshot for GUN docker.com/notary" +--- PASS: TestValidateSnapshotGeneratePrevCorrupt (0.05s) === RUN TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken ---- PASS: TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken (0.06s) +--- PASS: TestValidateSnapshotGenerateStoreGetCurrentSnapshotBroken (0.05s) === RUN TestValidateSnapshotGenerateNoTargets ---- PASS: TestValidateSnapshotGenerateNoTargets (0.05s) +--- PASS: TestValidateSnapshotGenerateNoTargets (0.04s) === RUN TestValidateSnapshotGenerate ---- PASS: TestValidateSnapshotGenerate (0.06s) +--- PASS: TestValidateSnapshotGenerate (0.04s) === RUN TestValidateRootNoTimestampKey ---- PASS: TestValidateRootNoTimestampKey (0.04s) +--- PASS: TestValidateRootNoTimestampKey (0.02s) === RUN TestValidateRootInvalidTimestampKey ---- PASS: TestValidateRootInvalidTimestampKey (0.04s) +--- PASS: TestValidateRootInvalidTimestampKey (0.02s) === RUN TestValidateRootInvalidTimestampThreshold ---- PASS: TestValidateRootInvalidTimestampThreshold (0.06s) +--- PASS: TestValidateRootInvalidTimestampThreshold (0.03s) === RUN TestValidateRootInvalidZeroThreshold ---- PASS: TestValidateRootInvalidZeroThreshold (0.14s) +--- PASS: TestValidateRootInvalidZeroThreshold (0.09s) === RUN TestValidateRootRoleMissing ---- PASS: TestValidateRootRoleMissing (0.03s) +--- PASS: TestValidateRootRoleMissing (0.02s) === RUN TestValidateTargetsRoleMissing ---- PASS: TestValidateTargetsRoleMissing (0.03s) +--- PASS: TestValidateTargetsRoleMissing (0.02s) === RUN TestValidateSnapshotRoleMissing --- PASS: TestValidateSnapshotRoleMissing (0.03s) === RUN TestValidateRootSigMissing ---- PASS: TestValidateRootSigMissing (0.03s) +--- PASS: TestValidateRootSigMissing (0.02s) === RUN TestValidateTargetsSigMissing -time="2024-01-08T23:48:13Z" level=error msg="ErrBadTargets: tuf: data has no signatures" ---- PASS: TestValidateTargetsSigMissing (0.04s) +time="2025-02-10T06:14:23Z" level=error msg="ErrBadTargets: tuf: data has no signatures" +--- PASS: TestValidateTargetsSigMissing (0.02s) === RUN TestValidateSnapshotSigMissing ---- PASS: TestValidateSnapshotSigMissing (0.04s) +--- PASS: TestValidateSnapshotSigMissing (0.03s) === RUN TestValidateRootCorrupt --- PASS: TestValidateRootCorrupt (0.03s) === RUN TestValidateTargetsCorrupt -time="2024-01-08T23:48:13Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" ---- PASS: TestValidateTargetsCorrupt (0.04s) +time="2025-02-10T06:14:23Z" level=error msg="ErrBadTargets: invalid character '\\u0084' looking for beginning of value" +--- PASS: TestValidateTargetsCorrupt (0.03s) === RUN TestValidateSnapshotCorrupt ---- PASS: TestValidateSnapshotCorrupt (0.04s) +--- PASS: TestValidateSnapshotCorrupt (0.03s) === RUN TestValidateRootModifiedSize ---- PASS: TestValidateRootModifiedSize (0.04s) +--- PASS: TestValidateRootModifiedSize (0.02s) === RUN TestValidateTargetsModifiedSize ---- PASS: TestValidateTargetsModifiedSize (0.05s) +--- PASS: TestValidateTargetsModifiedSize (0.03s) === RUN TestValidateRootModifiedHash ---- PASS: TestValidateRootModifiedHash (0.05s) +--- PASS: TestValidateRootModifiedHash (0.04s) === RUN TestValidateTargetsModifiedHash ---- PASS: TestValidateTargetsModifiedHash (0.05s) +--- PASS: TestValidateTargetsModifiedHash (0.03s) === RUN TestGenerateSnapshotRootNotLoaded --- PASS: TestGenerateSnapshotRootNotLoaded (0.00s) === RUN TestGenerateSnapshotNoKey ---- PASS: TestGenerateSnapshotNoKey (0.05s) +--- PASS: TestGenerateSnapshotNoKey (0.02s) === RUN TestLoadTargetsLoadsNothingIfNoUpdates ---- PASS: TestLoadTargetsLoadsNothingIfNoUpdates (0.04s) +--- PASS: TestLoadTargetsLoadsNothingIfNoUpdates (0.02s) === RUN TestValidateTargetsRequiresStoredParent -time="2024-01-08T23:48:13Z" level=error msg="ErrBadTargets: targets must be loaded first" ---- PASS: TestValidateTargetsRequiresStoredParent (0.08s) +time="2025-02-10T06:14:24Z" level=error msg="ErrBadTargets: targets must be loaded first" +--- PASS: TestValidateTargetsRequiresStoredParent (0.03s) === RUN TestValidateTargetsParentInUpdate -time="2024-01-08T23:48:13Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" ---- PASS: TestValidateTargetsParentInUpdate (0.06s) +time="2025-02-10T06:14:24Z" level=error msg="ErrBadTargets: invalid character 'I' looking for beginning of value" +--- PASS: TestValidateTargetsParentInUpdate (0.03s) === RUN TestValidateTargetsRoleNotInParent -time="2024-01-08T23:48:13Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" -time="2024-01-08T23:48:13Z" level=error msg="ErrBadTargets: targets has already been loaded" -time="2024-01-08T23:48:13Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" ---- PASS: TestValidateTargetsRoleNotInParent (0.07s) +time="2025-02-10T06:14:24Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +time="2025-02-10T06:14:24Z" level=error msg="ErrBadTargets: targets has already been loaded" +time="2025-02-10T06:14:24Z" level=error msg="ErrBadTargets: tuf: invalid role targets/level1. delegation does not exist" +--- PASS: TestValidateTargetsRoleNotInParent (0.03s) PASS -ok github.com/theupdateframework/notary/server/handlers 3.002s +ok github.com/theupdateframework/notary/server/handlers 2.182s === RUN TestSnapshotExpired --- PASS: TestSnapshotExpired (0.00s) === RUN TestSnapshotNotExpired @@ -2784,28 +2819,28 @@ === RUN TestGetSnapshotKeyCreate --- PASS: TestGetSnapshotKeyCreate (0.00s) === RUN TestGetSnapshotKeyCreateWithFailingStore -time="2024-01-08T23:48:10Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2025-02-10T06:14:21Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetSnapshotKeyCreateWithFailingStore (0.00s) === RUN TestGetSnapshotKeyCreateWithCorruptedStore -time="2024-01-08T23:48:10Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve snapshot key ID" +time="2025-02-10T06:14:21Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve snapshot key ID" --- PASS: TestGetSnapshotKeyCreateWithCorruptedStore (0.00s) === RUN TestGetSnapshotKeyCreateWithInvalidAlgo --- PASS: TestGetSnapshotKeyCreateWithInvalidAlgo (0.00s) === RUN TestGetSnapshotKeyExistingMetadata ---- PASS: TestGetSnapshotKeyExistingMetadata (0.05s) +--- PASS: TestGetSnapshotKeyExistingMetadata (0.02s) === RUN TestGetSnapshotNoPreviousSnapshot -time="2024-01-08T23:48:10Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" ---- PASS: TestGetSnapshotNoPreviousSnapshot (0.04s) +time="2025-02-10T06:14:21Z" level=error msg="Failed to unmarshal existing snapshot for GUN gun" +--- PASS: TestGetSnapshotNoPreviousSnapshot (0.01s) === RUN TestGetSnapshotReturnsPreviousSnapshotIfUnexpired ---- PASS: TestGetSnapshotReturnsPreviousSnapshotIfUnexpired (0.03s) +--- PASS: TestGetSnapshotReturnsPreviousSnapshotIfUnexpired (0.02s) === RUN TestGetSnapshotOldSnapshotExpired ---- PASS: TestGetSnapshotOldSnapshotExpired (0.07s) +--- PASS: TestGetSnapshotOldSnapshotExpired (0.03s) === RUN TestCannotMakeNewSnapshotIfNoRoot ---- PASS: TestCannotMakeNewSnapshotIfNoRoot (0.03s) +--- PASS: TestCannotMakeNewSnapshotIfNoRoot (0.01s) === RUN TestCreateSnapshotNoKeyInCrypto ---- PASS: TestCreateSnapshotNoKeyInCrypto (0.04s) +--- PASS: TestCreateSnapshotNoKeyInCrypto (0.02s) PASS -ok github.com/theupdateframework/notary/server/snapshot 0.295s +ok github.com/theupdateframework/notary/server/snapshot 0.114s === RUN TestMemoryUpdateCurrentEmpty --- PASS: TestMemoryUpdateCurrentEmpty (0.00s) === RUN TestMemoryUpdateCurrentVersionCheckOldVersionExists @@ -2831,35 +2866,35 @@ === RUN TestRDBTUFFileJSONUnmarshallingFailure --- PASS: TestRDBTUFFileJSONUnmarshallingFailure (0.00s) === RUN TestSQLUpdateCurrentEmpty ---- PASS: TestSQLUpdateCurrentEmpty (0.12s) +--- PASS: TestSQLUpdateCurrentEmpty (0.06s) === RUN TestSQLUpdateCurrentVersionCheckOldVersionExists ---- PASS: TestSQLUpdateCurrentVersionCheckOldVersionExists (0.08s) +--- PASS: TestSQLUpdateCurrentVersionCheckOldVersionExists (0.03s) === RUN TestSQLUpdateCurrentVersionCheckOldVersionNotExist ---- PASS: TestSQLUpdateCurrentVersionCheckOldVersionNotExist (0.05s) +--- PASS: TestSQLUpdateCurrentVersionCheckOldVersionNotExist (0.03s) === RUN TestSQLUpdateManyNoConflicts ---- PASS: TestSQLUpdateManyNoConflicts (0.06s) +--- PASS: TestSQLUpdateManyNoConflicts (0.04s) === RUN TestSQLUpdateManyConflictRollback ---- PASS: TestSQLUpdateManyConflictRollback (0.03s) +--- PASS: TestSQLUpdateManyConflictRollback (0.02s) === RUN TestSQLDelete ---- PASS: TestSQLDelete (0.11s) +--- PASS: TestSQLDelete (0.07s) === RUN TestSQLDBCheckHealthTableMissing ---- PASS: TestSQLDBCheckHealthTableMissing (0.06s) +--- PASS: TestSQLDBCheckHealthTableMissing (0.02s) === RUN TestSQLDBCheckHealthDBConnectionFail ---- PASS: TestSQLDBCheckHealthDBConnectionFail (0.07s) +--- PASS: TestSQLDBCheckHealthDBConnectionFail (0.01s) === RUN TestSQLDBCheckHealthSucceeds ---- PASS: TestSQLDBCheckHealthSucceeds (0.03s) +--- PASS: TestSQLDBCheckHealthSucceeds (0.02s) === RUN TestSQLDBGetChecksum ---- PASS: TestSQLDBGetChecksum (0.05s) +--- PASS: TestSQLDBGetChecksum (0.03s) === RUN TestSQLDBGetChecksumNotFound ---- PASS: TestSQLDBGetChecksumNotFound (0.03s) +--- PASS: TestSQLDBGetChecksumNotFound (0.02s) === RUN TestSQLTUFMetaStoreGetCurrent ---- PASS: TestSQLTUFMetaStoreGetCurrent (0.13s) +--- PASS: TestSQLTUFMetaStoreGetCurrent (0.10s) === RUN TestSQLGetChanges ---- PASS: TestSQLGetChanges (0.15s) +--- PASS: TestSQLGetChanges (0.07s) === RUN TestSQLDBGetVersion ---- PASS: TestSQLDBGetVersion (0.03s) +--- PASS: TestSQLDBGetVersion (0.02s) PASS -ok github.com/theupdateframework/notary/server/storage 1.009s +ok github.com/theupdateframework/notary/server/storage 0.557s === RUN TestTimestampExpired --- PASS: TestTimestampExpired (0.00s) === RUN TestTimestampNotExpired @@ -2867,33 +2902,33 @@ === RUN TestGetTimestampKey --- PASS: TestGetTimestampKey (0.00s) === RUN TestGetTimestampNoPreviousTimestamp -time="2024-01-08T23:48:11Z" level=error msg="Failed to unmarshal existing timestamp" +time="2025-02-10T06:14:21Z" level=error msg="Failed to unmarshal existing timestamp" --- PASS: TestGetTimestampNoPreviousTimestamp (0.03s) === RUN TestGetTimestampReturnsPreviousTimestampIfUnexpired --- PASS: TestGetTimestampReturnsPreviousTimestampIfUnexpired (0.03s) === RUN TestGetTimestampOldTimestampExpired ---- PASS: TestGetTimestampOldTimestampExpired (0.04s) +--- PASS: TestGetTimestampOldTimestampExpired (0.05s) === RUN TestCannotMakeNewTimestampIfNoRootOrSnapshot -time="2024-01-08T23:48:12Z" level=error msg="Failed to create a new timestamp" -time="2024-01-08T23:48:12Z" level=error msg="Failed to create a new timestamp" +time="2025-02-10T06:14:21Z" level=error msg="Failed to create a new timestamp" +time="2025-02-10T06:14:21Z" level=error msg="Failed to create a new timestamp" --- PASS: TestCannotMakeNewTimestampIfNoRootOrSnapshot (0.04s) === RUN TestCreateTimestampNoKeyInCrypto -time="2024-01-08T23:48:12Z" level=error msg="Failed to create a new timestamp" ---- PASS: TestCreateTimestampNoKeyInCrypto (0.04s) +time="2025-02-10T06:14:21Z" level=error msg="Failed to create a new timestamp" +--- PASS: TestCreateTimestampNoKeyInCrypto (0.06s) === RUN TestGetTimestampKeyCreateWithFailingStore -time="2024-01-08T23:48:12Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" +time="2025-02-10T06:14:21Z" level=error msg="Error when retrieving root role for GUN gun: failing store failed" --- PASS: TestGetTimestampKeyCreateWithFailingStore (0.00s) === RUN TestGetTimestampKeyCreateWithCorruptedStore -time="2024-01-08T23:48:12Z" level=error msg="Failed to unmarshal existing root for GUN gun to retrieve timestamp key ID" +time="2025-02-10T06:14:21Z" 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.02s) +--- PASS: TestGetTimestampKeyExistingMetadata (0.04s) PASS -ok github.com/theupdateframework/notary/server/timestamp 0.236s +ok github.com/theupdateframework/notary/server/timestamp 0.265s === RUN TestHealthCheckKMUnhealthy ---- PASS: TestHealthCheckKMUnhealthy (0.00s) +--- PASS: TestHealthCheckKMUnhealthy (0.01s) === RUN TestHealthCheckSignerUnhealthy --- PASS: TestHealthCheckSignerUnhealthy (0.00s) === RUN TestHealthCheckKMTimeout @@ -2909,25 +2944,25 @@ === RUN TestHealthCheckSignerConnectionDied --- PASS: TestHealthCheckSignerConnectionDied (0.00s) === RUN TestHealthCheckForOverallStatus ---- PASS: TestHealthCheckForOverallStatus (0.01s) +--- PASS: TestHealthCheckForOverallStatus (0.00s) === RUN TestHealthCheckNonexistentService --- PASS: TestHealthCheckNonexistentService (0.00s) === RUN TestGetPrivateKeyAndSignWithExistingKey -time="2024-01-08T23:48:13Z" level=info msg="Sign: Signed message! with KeyID c4c95746de28654ce85fb7c6c48a345f6da325153b45125b320f68ced09387f2" go.version=go1.15.15 ---- PASS: TestGetPrivateKeyAndSignWithExistingKey (0.02s) +time="2025-02-10T06:14:23Z" level=info msg="Sign: Signed message! with KeyID eba815b10ca365892137d2f1dce1ebb092c30b597fce5ecfb4ec61e7a9c4cd66" go.version=go1.15.15 +--- PASS: TestGetPrivateKeyAndSignWithExistingKey (0.01s) === RUN TestCannotSignWithKeyThatDoesntExist -time="2024-01-08T23:48:13Z" level=error msg="Sign: key 10acbf13177e8debc1df832de7724c843f9b16f81bfa494707818fce740aa8a5 not found" go.version=go1.15.15 +time="2025-02-10T06:14:23Z" level=error msg="Sign: key 2bc89759bac2d38428dc2d44f92c4d4985c5dfbe6c19849c36bfbf761ad7e48e not found" go.version=go1.15.15 --- PASS: TestCannotSignWithKeyThatDoesntExist (0.00s) === RUN TestCryptoSignerInterfaceBehavior -time="2024-01-08T23:48:13Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.15.15 -time="2024-01-08T23:48:13Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.15.15 -time="2024-01-08T23:48:13Z" level=info msg="CreateKey: Created KeyID c3a5623e4401d3a42072e658805661418b4a5157727839c101fa87c18a535d1a" go.version=go1.15.15 -time="2024-01-08T23:48:13Z" level=info msg="CreateKey: Created KeyID b31faa440700d1cef726350cb3c920643c7f13cde4b0b97031bdc8297761164a" go.version=go1.15.15 -time="2024-01-08T23:48:13Z" level=error msg="GetKeyInfo: key c3a5623e4401d3a42072e658805661418b4a5157727839c101fa87c18a535d1a not found" go.version=go1.15.15 -time="2024-01-08T23:48:13Z" level=error msg="GetKeyInfo: key b31faa440700d1cef726350cb3c920643c7f13cde4b0b97031bdc8297761164a not found" go.version=go1.15.15 +time="2025-02-10T06:14:23Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.15.15 +time="2025-02-10T06:14:23Z" level=error msg="GetKeyInfo: key nonexistent not found" go.version=go1.15.15 +time="2025-02-10T06:14:23Z" level=info msg="CreateKey: Created KeyID 828fee3f0f8a5b9526499a2c8ad4e8cb42f85ef88df07c9132ad582621e07912" go.version=go1.15.15 +time="2025-02-10T06:14:23Z" level=info msg="CreateKey: Created KeyID f7ea09bc093141c965c9e19b6e8b51d713c70859e3da572fa3a0b016ffb45c0c" go.version=go1.15.15 +time="2025-02-10T06:14:23Z" level=error msg="GetKeyInfo: key 828fee3f0f8a5b9526499a2c8ad4e8cb42f85ef88df07c9132ad582621e07912 not found" go.version=go1.15.15 +time="2025-02-10T06:14:23Z" level=error msg="GetKeyInfo: key f7ea09bc093141c965c9e19b6e8b51d713c70859e3da572fa3a0b016ffb45c0c not found" go.version=go1.15.15 --- PASS: TestCryptoSignerInterfaceBehavior (0.02s) PASS -ok github.com/theupdateframework/notary/signer 0.070s +ok github.com/theupdateframework/notary/signer 0.059s ? github.com/theupdateframework/notary/signer/api [no test files] ? github.com/theupdateframework/notary/signer/client [no test files] === RUN TestGetSuccessPopulatesCache @@ -2944,30 +2979,30 @@ `nodb` is not officially supported, running under compatibility mode. --- PASS: TestNewSQLKeyDBStorePropagatesDBError (0.00s) === RUN TestSQLDBHealthCheckMissingTable ---- PASS: TestSQLDBHealthCheckMissingTable (0.06s) +--- PASS: TestSQLDBHealthCheckMissingTable (0.21s) === RUN TestSQLDBHealthCheckNoConnection --- PASS: TestSQLDBHealthCheckNoConnection (0.02s) === RUN TestSQLKeyCanOnlyBeAddedOnce (/build/reproducible-path/notary-0.6.1~ds2/_build/src/github.com/theupdateframework/notary/signer/keydbstore/sql_keydbstore.go:98) -[2024-01-08 23:48:14]  UNIQUE constraint failed: private_keys.key_id  ---- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.17s) +[2025-02-10 06:14:22]  UNIQUE constraint failed: private_keys.key_id  +--- PASS: TestSQLKeyCanOnlyBeAddedOnce (0.12s) === RUN TestSQLCreateDelete ---- PASS: TestSQLCreateDelete (0.23s) +--- PASS: TestSQLCreateDelete (0.18s) === RUN TestSQLKeyRotation ---- PASS: TestSQLKeyRotation (0.20s) +--- PASS: TestSQLKeyRotation (0.21s) === RUN TestSQLSigningMarksKeyActive -time="2024-01-08T23:48:15Z" level=error msg="Key f4bb8690a8c635ed8443d297835681f17aae525f2bb4b9c0d5881abec185cae9 was just used to sign hash successful, db closed, error when trying to mark key as active: sql: database is closed" ---- PASS: TestSQLSigningMarksKeyActive (0.39s) -=== RUN TestSQLCreateKey +time="2025-02-10T06:14:22Z" level=error msg="Key 15bb54893453aae8f41c168d9e3663d574c7bd8dabd82111d4377af6ef43601a was just used to sign hash successful, db closed, error when trying to mark key as active: sql: database is closed" (/build/reproducible-path/notary-0.6.1~ds2/_build/src/github.com/theupdateframework/notary/signer/keydbstore/sql_keydbstore.go:197) -[2024-01-08 23:48:15]  sql: database is closed  ---- PASS: TestSQLCreateKey (0.28s) +[2025-02-10 06:14:22]  sql: database is closed  +--- PASS: TestSQLSigningMarksKeyActive (0.28s) +=== RUN TestSQLCreateKey +--- PASS: TestSQLCreateKey (0.23s) === RUN TestSQLUnimplementedInterfaceBehavior ---- PASS: TestSQLUnimplementedInterfaceBehavior (0.10s) +--- PASS: TestSQLUnimplementedInterfaceBehavior (0.06s) PASS -ok github.com/theupdateframework/notary/signer/keydbstore 1.517s +ok github.com/theupdateframework/notary/signer/keydbstore 1.370s === RUN TestSet --- PASS: TestSet (0.00s) === RUN TestSetWithNoParentDirectory @@ -2995,21 +3030,21 @@ === RUN TestGetData --- PASS: TestGetData (0.00s) === RUN TestCreateDirectory ---- PASS: TestCreateDirectory (0.01s) +--- PASS: TestCreateDirectory (0.00s) === RUN TestCreatePrivateDirectory --- PASS: TestCreatePrivateDirectory (0.00s) === RUN TestFileStoreConsistency --- PASS: TestFileStoreConsistency (0.00s) === RUN TestHTTPStoreGetSized ---- PASS: TestHTTPStoreGetSized (0.01s) +--- PASS: TestHTTPStoreGetSized (0.00s) === RUN TestHTTPStoreGetAllMeta ---- PASS: TestHTTPStoreGetAllMeta (0.01s) +--- PASS: TestHTTPStoreGetAllMeta (0.00s) === RUN TestSetSingleAndSetMultiMeta --- PASS: TestSetSingleAndSetMultiMeta (0.00s) === RUN Test404Error --- PASS: Test404Error (0.00s) === RUN Test50XErrors ---- PASS: Test50XErrors (0.01s) +--- PASS: Test50XErrors (0.02s) === RUN Test400Error --- PASS: Test400Error (0.00s) === RUN TestTranslateErrorsParse400Errors @@ -3044,7 +3079,7 @@ === RUN TestMemoryStoreMetadata --- PASS: TestMemoryStoreMetadata (0.00s) PASS -ok github.com/theupdateframework/notary/storage 0.074s +ok github.com/theupdateframework/notary/storage 0.065s ? github.com/theupdateframework/notary/storage/rethinkdb [no test files] === RUN TestExportKeys --- PASS: TestExportKeys (0.00s) @@ -3055,7 +3090,7 @@ === RUN TestExport2InOneFile --- PASS: TestExport2InOneFile (0.00s) === RUN TestImportKeys ---- PASS: TestImportKeys (0.00s) +--- PASS: TestImportKeys (0.01s) === RUN TestImportNoPath --- PASS: TestImportNoPath (0.01s) === RUN TestNonRootPathInference @@ -3069,70 +3104,70 @@ === RUN TestImportKeys2InOneFileNoPath --- PASS: TestImportKeys2InOneFileNoPath (0.03s) === RUN TestEncryptedKeyImportFail -time="2024-01-08T23:48:14Z" 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-10T06:14:21Z" 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.01s) === RUN TestEncryptedKeyImportSuccess ---- PASS: TestEncryptedKeyImportSuccess (0.01s) +--- PASS: TestEncryptedKeyImportSuccess (0.03s) === RUN TestEncryption ---- PASS: TestEncryption (0.03s) +--- PASS: TestEncryption (0.02s) === RUN TestAddKey ---- PASS: TestAddKey (0.05s) +--- PASS: TestAddKey (0.06s) === RUN TestKeyStoreInternalState ---- PASS: TestKeyStoreInternalState (0.02s) +--- PASS: TestKeyStoreInternalState (0.03s) === RUN TestGet --- PASS: TestGet (0.01s) === RUN TestGetLegacyKey --- PASS: TestGetLegacyKey (0.00s) === RUN TestListKeys ---- PASS: TestListKeys (0.05s) +--- PASS: TestListKeys (0.06s) === RUN TestAddGetKeyMemStore --- PASS: TestAddGetKeyMemStore (0.01s) === RUN TestAddGetKeyInfoMemStore ---- PASS: TestAddGetKeyInfoMemStore (0.02s) +--- PASS: TestAddGetKeyInfoMemStore (0.03s) === RUN TestGetDecryptedWithTamperedCipherText -time="2024-01-08T23:48:14Z" level=error msg="PEM block is empty" +time="2025-02-10T06:14:21Z" level=error msg="PEM block is empty" --- PASS: TestGetDecryptedWithTamperedCipherText (0.01s) === RUN TestGetDecryptedWithInvalidPassphrase --- PASS: TestGetDecryptedWithInvalidPassphrase (0.04s) === RUN TestGetDecryptedWithConsistentlyInvalidPassphrase ---- PASS: TestGetDecryptedWithConsistentlyInvalidPassphrase (0.06s) +--- PASS: TestGetDecryptedWithConsistentlyInvalidPassphrase (0.07s) === RUN TestRemoveKey ---- PASS: TestRemoveKey (0.10s) +--- PASS: TestRemoveKey (0.05s) === RUN TestKeysAreCached --- PASS: TestKeysAreCached (0.02s) PASS -ok github.com/theupdateframework/notary/trustmanager 0.517s +ok github.com/theupdateframework/notary/trustmanager 0.566s === RUN TestRemoteStore -time="2024-01-08T23:48:14Z" level=info msg="listing files from localhost:9888" -time="2024-01-08T23:48:14Z" level=info msg="listing files from localhost:9888" +time="2025-02-10T06:14:22Z" level=info msg="listing files from localhost:9888" +time="2025-02-10T06:14:22Z" level=info msg="listing files from localhost:9888" --- PASS: TestRemoteStore (0.14s) === RUN TestErrors -time="2024-01-08T23:48:14Z" level=error msg="failed to store: test error" ---- PASS: TestErrors (0.15s) +time="2025-02-10T06:14:23Z" level=error msg="failed to store: test error" +--- PASS: TestErrors (0.13s) === RUN TestNewGRPCStorage --- PASS: TestNewGRPCStorage (0.00s) === RUN TestGRPCStorage --- PASS: TestGRPCStorage (0.00s) PASS -ok github.com/theupdateframework/notary/trustmanager/remoteks 0.307s +ok github.com/theupdateframework/notary/trustmanager/remoteks 0.283s ? github.com/theupdateframework/notary/trustmanager/yubikey [no test files] === RUN TestWildcardMatch --- PASS: TestWildcardMatch (0.00s) === RUN TestValidateRoot -2024/01/08 23:48:15 [INFO] generate received request -2024/01/08 23:48:15 [INFO] received CSR -2024/01/08 23:48:15 [INFO] generating key: ecdsa-256 -2024/01/08 23:48:15 [INFO] encoded CSR -2024/01/08 23:48:15 [INFO] signed certificate with serial number 555577074006019738741809749504310406741954865889 -2024/01/08 23:48:15 [INFO] received CSR -2024/01/08 23:48:15 [INFO] generating key: ecdsa-256 -2024/01/08 23:48:15 [INFO] encoded CSR -2024/01/08 23:48:15 [INFO] signed certificate with serial number 610414718126362950543494641371661626699981694154 -2024/01/08 23:48:15 [INFO] received CSR -2024/01/08 23:48:15 [INFO] generating key: ecdsa-256 -2024/01/08 23:48:15 [INFO] encoded CSR -2024/01/08 23:48:15 [INFO] signed certificate with serial number 298867070260203401883712048167683341957995115477 ---- PASS: TestValidateRoot (0.09s) +2025/02/10 06:14:22 [INFO] generate received request +2025/02/10 06:14:22 [INFO] received CSR +2025/02/10 06:14:22 [INFO] generating key: ecdsa-256 +2025/02/10 06:14:22 [INFO] encoded CSR +2025/02/10 06:14:22 [INFO] signed certificate with serial number 648755063661029170444481305644820029492245599314 +2025/02/10 06:14:22 [INFO] received CSR +2025/02/10 06:14:22 [INFO] generating key: ecdsa-256 +2025/02/10 06:14:22 [INFO] encoded CSR +2025/02/10 06:14:22 [INFO] signed certificate with serial number 680977958671066988662752461986149875645674744179 +2025/02/10 06:14:22 [INFO] received CSR +2025/02/10 06:14:22 [INFO] generating key: ecdsa-256 +2025/02/10 06:14:22 [INFO] encoded CSR +2025/02/10 06:14:22 [INFO] signed certificate with serial number 517232831954337284756660835099775330207735311340 +--- PASS: TestValidateRoot (0.08s) === RUN TestValidateRootWithoutTOFUS --- PASS: TestValidateRootWithoutTOFUS (0.00s) === RUN TestValidateRootWithPinnedCert @@ -3144,29 +3179,29 @@ === RUN TestValidateRootWithPinnedCA --- PASS: TestValidateRootWithPinnedCA (0.08s) === RUN TestValidateSuccessfulRootRotation ---- PASS: TestValidateSuccessfulRootRotation (0.14s) +--- PASS: TestValidateSuccessfulRootRotation (0.11s) === RUN TestValidateRootRotationMissingOrigSig --- PASS: TestValidateRootRotationMissingOrigSig (0.09s) === RUN TestValidateRootRotationMissingNewSig ---- PASS: TestValidateRootRotationMissingNewSig (0.09s) +--- PASS: TestValidateRootRotationMissingNewSig (0.10s) === RUN TestValidateRootRotationTrustPinning ---- PASS: TestValidateRootRotationTrustPinning (0.09s) +--- PASS: TestValidateRootRotationTrustPinning (0.10s) === RUN TestValidateRootRotationTrustPinningInvalidCA ---- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.07s) +--- PASS: TestValidateRootRotationTrustPinningInvalidCA (0.06s) === RUN TestParsePEMPublicKey -time="2024-01-08T23:48:16Z" level=warning msg="certificate with CN notary is near expiry" +time="2025-02-10T06:14:22Z" level=warning msg="certificate with CN notary is near expiry" --- PASS: TestParsePEMPublicKey (0.01s) === RUN TestCheckingCertExpiry -time="2024-01-08T23:48:16Z" level=warning msg="certificate with CN notary is near expiry" ---- PASS: TestCheckingCertExpiry (0.01s) +time="2025-02-10T06:14:22Z" level=warning msg="certificate with CN notary is near expiry" +--- PASS: TestCheckingCertExpiry (0.02s) === RUN TestValidateRootWithExpiredIntermediate ---- PASS: TestValidateRootWithExpiredIntermediate (0.01s) +--- PASS: TestValidateRootWithExpiredIntermediate (0.02s) === RUN TestCheckingWildcardCert ---- PASS: TestCheckingWildcardCert (0.01s) +--- PASS: TestCheckingWildcardCert (0.02s) === RUN TestWildcardMatching --- PASS: TestWildcardMatching (0.00s) PASS -ok github.com/theupdateframework/notary/trustpinning 0.761s +ok github.com/theupdateframework/notary/trustpinning 0.738s === RUN TestInitSnapshotNoTargets --- PASS: TestInitSnapshotNoTargets (0.00s) === RUN TestInitRepo @@ -3174,11 +3209,11 @@ === RUN TestUpdateDelegations --- PASS: TestUpdateDelegations (0.00s) === RUN TestPurgeDelegationsKeyFromTop -time="2024-01-08T23:48:16Z" 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-08T23:48:16Z" 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="2025-02-10T06:14:21Z" 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-10T06:14:21Z" level=warning msg="role targets/vimes/carrot has fewer keys than its threshold of 1; it will not be usable until keys are added to it" --- PASS: TestPurgeDelegationsKeyFromTop (0.00s) === RUN TestPurgeDelegationsKeyFromDeep -time="2024-01-08T23:48:16Z" 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="2025-02-10T06:14:21Z" 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) @@ -3191,7 +3226,7 @@ === RUN TestUpdateDelegationsRoleThatIsMissingDelegationKey --- PASS: TestUpdateDelegationsRoleThatIsMissingDelegationKey (0.00s) === RUN TestUpdateDelegationsNotEnoughKeys -time="2024-01-08T23:48:16Z" 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="2025-02-10T06:14:21Z" 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) @@ -3236,7 +3271,7 @@ === RUN TestRemoveBaseKeysFromRoot --- PASS: TestRemoveBaseKeysFromRoot (0.01s) === RUN TestReplaceBaseKeysInRoot ---- PASS: TestReplaceBaseKeysInRoot (0.02s) +--- PASS: TestReplaceBaseKeysInRoot (0.03s) === RUN TestGetAllRoles --- PASS: TestGetAllRoles (0.00s) === RUN TestGetBaseRoles @@ -3260,39 +3295,39 @@ === RUN TestSignRootOldKeyCertExists --- PASS: TestSignRootOldKeyCertExists (0.07s) === RUN TestSignRootOldKeyCertMissing ---- PASS: TestSignRootOldKeyCertMissing (0.05s) +--- PASS: TestSignRootOldKeyCertMissing (0.06s) === RUN TestRootKeyRotation ---- PASS: TestRootKeyRotation (0.20s) +--- PASS: TestRootKeyRotation (0.19s) === RUN TestBuilderLoadsValidRolesOnly --- PASS: TestBuilderLoadsValidRolesOnly (0.05s) === RUN TestBuilderOnlyAcceptsRootFirstWhenLoading --- PASS: TestBuilderOnlyAcceptsRootFirstWhenLoading (0.00s) === RUN TestBuilderOnlyAcceptsDelegationsAfterParent ---- PASS: TestBuilderOnlyAcceptsDelegationsAfterParent (0.02s) +--- PASS: TestBuilderOnlyAcceptsDelegationsAfterParent (0.01s) === RUN TestMarkingIsValid --- PASS: TestMarkingIsValid (0.02s) === RUN TestBuilderLoadInvalidDelegations ---- PASS: TestBuilderLoadInvalidDelegations (0.04s) +--- PASS: TestBuilderLoadInvalidDelegations (0.05s) === RUN TestBuilderLoadInvalidDelegationsOldVersion ---- PASS: TestBuilderLoadInvalidDelegationsOldVersion (0.04s) +--- PASS: TestBuilderLoadInvalidDelegationsOldVersion (0.05s) === RUN TestBuilderAcceptRoleOnce --- PASS: TestBuilderAcceptRoleOnce (0.02s) === RUN TestBuilderStopsAcceptingOrProducingDataOnceDone ---- PASS: TestBuilderStopsAcceptingOrProducingDataOnceDone (0.01s) +--- PASS: TestBuilderStopsAcceptingOrProducingDataOnceDone (0.02s) === RUN TestGenerateSnapshotInvalidOperations ---- PASS: TestGenerateSnapshotInvalidOperations (0.16s) +--- PASS: TestGenerateSnapshotInvalidOperations (0.18s) === RUN TestGenerateTimestampInvalidOperations ---- PASS: TestGenerateTimestampInvalidOperations (0.12s) +--- PASS: TestGenerateTimestampInvalidOperations (0.11s) === RUN TestGetConsistentInfo ---- PASS: TestGetConsistentInfo (0.05s) +--- PASS: TestGetConsistentInfo (0.04s) === RUN TestTimestampPreAndPostChecksumming ---- PASS: TestTimestampPreAndPostChecksumming (0.06s) +--- PASS: TestTimestampPreAndPostChecksumming (0.07s) === RUN TestSnapshotLoadedFirstChecksumsOthers ---- PASS: TestSnapshotLoadedFirstChecksumsOthers (0.05s) +--- PASS: TestSnapshotLoadedFirstChecksumsOthers (0.06s) === RUN TestSnapshotLoadedAfterChecksumsOthersRetroactively ---- PASS: TestSnapshotLoadedAfterChecksumsOthersRetroactively (0.11s) +--- PASS: TestSnapshotLoadedAfterChecksumsOthersRetroactively (0.12s) PASS -ok github.com/theupdateframework/notary/tuf 1.238s +ok github.com/theupdateframework/notary/tuf 1.284s === RUN TestMergeStrSlicesExclusive --- PASS: TestMergeStrSlicesExclusive (0.00s) === RUN TestMergeStrSlicesOverlap @@ -3426,7 +3461,7 @@ === RUN TestFileMetaEquals --- PASS: TestFileMetaEquals (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/data 0.037s +ok github.com/theupdateframework/notary/tuf/data 0.053s === RUN TestListKeys --- PASS: TestListKeys (0.00s) === RUN TestGetKeys @@ -3434,7 +3469,7 @@ === RUN TestBasicSign --- PASS: TestBasicSign (0.00s) === RUN TestReSign ---- PASS: TestReSign (0.00s) +--- PASS: TestReSign (0.01s) === RUN TestMultiSign --- PASS: TestMultiSign (0.00s) === RUN TestSignReturnsNoSigs @@ -3458,13 +3493,13 @@ === RUN TestRSAPSSVerifierWithInvalidKeyType --- PASS: TestRSAPSSVerifierWithInvalidKeyType (0.00s) === RUN TestRSAPSSVerifierWithInvalidKeyLength ---- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.12s) +--- PASS: TestRSAPSSVerifierWithInvalidKeyLength (0.09s) === RUN TestRSAPSSVerifierWithInvalidKey --- PASS: TestRSAPSSVerifierWithInvalidKey (0.00s) === RUN TestRSAPSSVerifierWithInvalidSignature ---- PASS: TestRSAPSSVerifierWithInvalidSignature (0.02s) +--- PASS: TestRSAPSSVerifierWithInvalidSignature (0.01s) === RUN TestRSAPKCS1v15Verifier ---- PASS: TestRSAPKCS1v15Verifier (0.02s) +--- PASS: TestRSAPKCS1v15Verifier (0.01s) === RUN TestRSAPKCS1v15x509Verifier --- PASS: TestRSAPKCS1v15x509Verifier (0.00s) === RUN TestRSAPKCS1v15VerifierWithInvalidKeyType @@ -3472,12 +3507,12 @@ === RUN TestRSAPKCS1v15VerifierWithInvalidKey --- PASS: TestRSAPKCS1v15VerifierWithInvalidKey (0.00s) === RUN TestRSAPKCS1v15VerifierWithInvalidSignature -time="2024-01-08T23:48:16Z" level=error msg="Failed verification: crypto/rsa: verification error" ---- PASS: TestRSAPKCS1v15VerifierWithInvalidSignature (0.02s) +time="2025-02-10T06:14:21Z" level=error msg="Failed verification: crypto/rsa: verification error" +--- PASS: TestRSAPKCS1v15VerifierWithInvalidSignature (0.01s) === RUN TestECDSAVerifier --- PASS: TestECDSAVerifier (0.01s) === RUN TestECDSAVerifierOtherCurves ---- PASS: TestECDSAVerifierOtherCurves (0.53s) +--- PASS: TestECDSAVerifierOtherCurves (0.43s) === RUN TestECDSAx509Verifier --- PASS: TestECDSAx509Verifier (0.00s) === RUN TestECDSAVerifierWithInvalidKeyType @@ -3505,7 +3540,7 @@ === RUN TestIsValidNotExported --- PASS: TestIsValidNotExported (0.00s) === RUN TestMoreThanEnoughSigs ---- PASS: TestMoreThanEnoughSigs (0.00s) +--- PASS: TestMoreThanEnoughSigs (0.01s) === RUN TestValidSigWithIncorrectKeyID --- PASS: TestValidSigWithIncorrectKeyID (0.00s) === RUN TestDuplicateSigs @@ -3515,70 +3550,70 @@ === RUN TestVerifyVersion --- PASS: TestVerifyVersion (0.00s) === RUN TestVerifyExpiry -time="2024-01-08T23:48:16Z" level=error msg="Metadata for root expired" +time="2025-02-10T06:14:22Z" 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.836s +ok github.com/theupdateframework/notary/tuf/signed 0.690s === RUN TestNewSwizzler --- PASS: TestNewSwizzler (0.05s) === RUN TestSwizzlerSetInvalidJSON ---- PASS: TestSwizzlerSetInvalidJSON (0.05s) +--- PASS: TestSwizzlerSetInvalidJSON (0.06s) === RUN TestSwizzlerAddExtraSpace ---- PASS: TestSwizzlerAddExtraSpace (0.05s) +--- PASS: TestSwizzlerAddExtraSpace (0.03s) === RUN TestSwizzlerSetInvalidSigned ---- PASS: TestSwizzlerSetInvalidSigned (0.05s) +--- PASS: TestSwizzlerSetInvalidSigned (0.04s) === RUN TestSwizzlerSetInvalidSignedMeta ---- PASS: TestSwizzlerSetInvalidSignedMeta (0.04s) +--- PASS: TestSwizzlerSetInvalidSignedMeta (0.05s) === RUN TestSwizzlerSetInvalidMetadataType --- PASS: TestSwizzlerSetInvalidMetadataType (0.05s) === RUN TestSwizzlerInvalidateMetadataSignatures ---- PASS: TestSwizzlerInvalidateMetadataSignatures (0.05s) +--- PASS: TestSwizzlerInvalidateMetadataSignatures (0.04s) === RUN TestSwizzlerRemoveMetadata ---- PASS: TestSwizzlerRemoveMetadata (0.05s) +--- PASS: TestSwizzlerRemoveMetadata (0.03s) === RUN TestSwizzlerSignMetadataWithInvalidKey ---- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.05s) +--- PASS: TestSwizzlerSignMetadataWithInvalidKey (0.03s) === RUN TestSwizzlerOffsetMetadataVersion ---- PASS: TestSwizzlerOffsetMetadataVersion (0.05s) +--- PASS: TestSwizzlerOffsetMetadataVersion (0.03s) === RUN TestSwizzlerExpireMetadata ---- PASS: TestSwizzlerExpireMetadata (0.05s) +--- PASS: TestSwizzlerExpireMetadata (0.03s) === RUN TestSwizzlerSetThresholdBaseRole --- PASS: TestSwizzlerSetThresholdBaseRole (0.05s) === RUN TestSwizzlerSetThresholdDelegatedRole --- PASS: TestSwizzlerSetThresholdDelegatedRole (0.05s) === RUN TestSwizzlerChangeRootKey ---- PASS: TestSwizzlerChangeRootKey (0.06s) +--- PASS: TestSwizzlerChangeRootKey (0.04s) === RUN TestSwizzlerUpdateSnapshotHashesSpecifiedRoles ---- PASS: TestSwizzlerUpdateSnapshotHashesSpecifiedRoles (0.05s) +--- PASS: TestSwizzlerUpdateSnapshotHashesSpecifiedRoles (0.03s) === RUN TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles ---- PASS: TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles (0.05s) +--- PASS: TestSwizzlerUpdateSnapshotHashesNoSpecifiedRoles (0.03s) === RUN TestSwizzlerUpdateTimestamp ---- PASS: TestSwizzlerUpdateTimestamp (0.05s) +--- PASS: TestSwizzlerUpdateTimestamp (0.03s) === RUN TestMissingSigningKey ---- PASS: TestMissingSigningKey (0.04s) +--- PASS: TestMissingSigningKey (0.03s) === RUN TestSwizzlerMutateRoot ---- PASS: TestSwizzlerMutateRoot (0.05s) +--- PASS: TestSwizzlerMutateRoot (0.03s) === RUN TestSwizzlerMutateTimestamp ---- PASS: TestSwizzlerMutateTimestamp (0.05s) +--- PASS: TestSwizzlerMutateTimestamp (0.03s) === RUN TestSwizzlerMutateSnapshot ---- PASS: TestSwizzlerMutateSnapshot (0.04s) +--- PASS: TestSwizzlerMutateSnapshot (0.03s) === RUN TestSwizzlerMutateTargets ---- PASS: TestSwizzlerMutateTargets (0.04s) +--- PASS: TestSwizzlerMutateTargets (0.03s) === RUN TestSwizzlerRotateKeyBaseRole ---- PASS: TestSwizzlerRotateKeyBaseRole (0.04s) +--- PASS: TestSwizzlerRotateKeyBaseRole (0.03s) === RUN TestSwizzlerRotateKeyDelegationRole --- PASS: TestSwizzlerRotateKeyDelegationRole (0.05s) PASS -ok github.com/theupdateframework/notary/tuf/testutils 1.186s +ok github.com/theupdateframework/notary/tuf/testutils 0.897s ? 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) === RUN TestParsePKCS8ToTufKey ---- PASS: TestParsePKCS8ToTufKey (0.38s) +--- PASS: TestParsePKCS8ToTufKey (0.31s) === RUN TestPEMtoPEM --- PASS: TestPEMtoPEM (0.00s) === RUN TestRoleListLen @@ -3634,9 +3669,9 @@ === RUN TestValidateCertificateWithInvalidExpiry --- PASS: TestValidateCertificateWithInvalidExpiry (0.00s) === RUN TestValidateCertificateWithShortKey ---- PASS: TestValidateCertificateWithShortKey (0.12s) +--- PASS: TestValidateCertificateWithShortKey (0.11s) PASS -ok github.com/theupdateframework/notary/tuf/utils 0.620s +ok github.com/theupdateframework/notary/tuf/utils 0.533s === RUN TestNewSerializableErrorNonValidationError --- PASS: TestNewSerializableErrorNonValidationError (0.00s) === RUN TestNewSerializableErrorValidationError @@ -3652,7 +3687,7 @@ === RUN TestUnmarshalInvalidJSON --- PASS: TestUnmarshalInvalidJSON (0.00s) PASS -ok github.com/theupdateframework/notary/tuf/validation 0.011s +ok github.com/theupdateframework/notary/tuf/validation 0.006s === RUN TestSetSignalTrap --- PASS: TestSetSignalTrap (0.00s) === RUN TestLogLevelSignalHandle @@ -3752,7 +3787,7 @@ === RUN TestDoAuthWildcardImage --- PASS: TestDoAuthWildcardImage (0.00s) PASS -ok github.com/theupdateframework/notary/utils 0.022s +ok github.com/theupdateframework/notary/utils 0.019s ? github.com/theupdateframework/notary/version [no test files] create-stamp debian/debhelper-build-stamp fakeroot debian/rules binary @@ -3788,8 +3823,8 @@ dh_missing -O--builddirectory=/build/reproducible-path/notary-0.6.1\~ds2/_build -O--buildsystem=golang dh_strip -O--builddirectory=/build/reproducible-path/notary-0.6.1\~ds2/_build -O--buildsystem=golang dh_strip: warning: Could not find the BuildID in debian/notary/usr/bin/notary-server -dh_strip: warning: Could not find the BuildID in debian/notary/usr/bin/notary-signer dh_strip: warning: Could not find the BuildID in debian/notary/usr/bin/escrow +dh_strip: warning: Could not find the BuildID in debian/notary/usr/bin/notary-signer dh_makeshlibs -O--builddirectory=/build/reproducible-path/notary-0.6.1\~ds2/_build -O--buildsystem=golang dh_shlibdeps -O--builddirectory=/build/reproducible-path/notary-0.6.1\~ds2/_build -O--buildsystem=golang dh_installdeb -O--builddirectory=/build/reproducible-path/notary-0.6.1\~ds2/_build -O--buildsystem=golang @@ -3797,8 +3832,8 @@ dh_gencontrol -O--builddirectory=/build/reproducible-path/notary-0.6.1\~ds2/_build -O--buildsystem=golang dh_md5sums -O--builddirectory=/build/reproducible-path/notary-0.6.1\~ds2/_build -O--buildsystem=golang dh_builddeb -O--builddirectory=/build/reproducible-path/notary-0.6.1\~ds2/_build -O--buildsystem=golang -dpkg-deb: building package 'notary' in '../notary_0.6.1~ds2-6_i386.deb'. dpkg-deb: building package 'notary-dbgsym' in '../notary-dbgsym_0.6.1~ds2-6_i386.deb'. +dpkg-deb: building package 'notary' in '../notary_0.6.1~ds2-6_i386.deb'. dpkg-deb: building package 'golang-github-docker-notary-dev' in '../golang-github-docker-notary-dev_0.6.1~ds2-6_all.deb'. dpkg-genbuildinfo --build=binary dpkg-genchanges --build=binary >../notary_0.6.1~ds2-6_i386.changes @@ -3807,12 +3842,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/5191/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/5191/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/30934 and its subdirectories -I: Current time: Mon Jan 8 11:49:56 -12 2024 -I: pbuilder-time-stamp: 1704757796 +I: removing directory /srv/workspace/pbuilder/5191 and its subdirectories +I: Current time: Mon Feb 10 20:16:07 +14 2025 +I: pbuilder-time-stamp: 1739168167