This job view page is being replaced by Spyglass soon. Check out the new job view.
PRpuerco: Read and publish CVE information during anago/stage
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2021-04-14 17:41
Elapsed48s
Revision6eb94bd17c86654eece7b3551a46e811b99ee0e7
Refs 1996

No Test Failures!


Error lines from build-log.txt

... skipping 826 lines ...
time="2021-04-14T17:42:04Z" level=info msg="(cafec0ffee) TRANSACTION VERIFIED: {Action: \"INSERT\", FQIN: \"us.gcr.io/k8s-artifacts-prod/kas-network-proxy/proxy-agent@sha256:c419394f3fa40c32352be5a6ec5865270376d4351a3756bb1893be3f28fcba32\", PQIN: \"us.gcr.io/k8s-artifacts-prod/kas-network-proxy/proxy-agent:v0.0.8\", Path: \"us.gcr.io/k8s-artifacts-prod/kas-network-proxy/proxy-agent\", Digest: \"sha256:c419394f3fa40c32352be5a6ec5865270376d4351a3756bb1893be3f28fcba32\", Tag: \"v0.0.8\"}: agrees with manifest\n"
time="2021-04-14T17:42:04Z" level=info msg="Request {gcr.io/k8s-staging-kas-network-proxy   true}: OK\n"
time="2021-04-14T17:42:04Z" level=info msg="Request {gcr.io/k8s-staging-kas-network-proxy/proxy-agent   false}: OK\n"
time="2021-04-14T17:42:04Z" level=info msg="Request {{gcr.io/k8s-staging-kas-network-proxy   true} proxy-agent v0.0.8 sha256:c419394f3fa40c32352be5a6ec5865270376d4351a3756bb1893be3f28fcba32}: OK\n"
time="2021-04-14T17:42:04Z" level=info msg="(cafec0ffee): looking for child digest sha256:8735603bbd7153b8bfc8d2460481282bb44e2e830e5b237738e5c3e2a58c8f45"
time="2021-04-14T17:42:04Z" level=info msg="(cafec0ffee) TRANSACTION VERIFIED: {Action: \"INSERT\", FQIN: \"us.gcr.io/k8s-artifacts-prod/kas-network-proxy/proxy-agent@sha256:8735603bbd7153b8bfc8d2460481282bb44e2e830e5b237738e5c3e2a58c8f45\", PQIN: \"\", Path: \"us.gcr.io/k8s-artifacts-prod/kas-network-proxy/proxy-agent\", Digest: \"sha256:8735603bbd7153b8bfc8d2460481282bb44e2e830e5b237738e5c3e2a58c8f45\", Tag: \"\"}: agrees with manifest (parent digest sha256:c419394f3fa40c32352be5a6ec5865270376d4351a3756bb1893be3f28fcba32)\n"
time="2021-04-14T17:42:04Z" level=error msg="(cafec0ffee) TRANSACTION REJECTED: could not find matching source registry for us.gcr.io/k8s-artifacts-prod/kas-network-proxy/proxy-agent-white-powder@sha256:c419394f3fa40c32352be5a6ec5865270376d4351a3756bb1893be3f28fcba32"
time="2021-04-14T17:42:04Z" level=info msg="Request {gcr.io/k8s-staging-kas-network-proxy   true}: OK\n"
time="2021-04-14T17:42:04Z" level=info msg="Request {gcr.io/k8s-staging-kas-network-proxy/proxy-agent   false}: OK\n"
time="2021-04-14T17:42:04Z" level=info msg="Request {{gcr.io/k8s-staging-kas-network-proxy   true} proxy-agent v0.0.8 sha256:c419394f3fa40c32352be5a6ec5865270376d4351a3756bb1893be3f28fcba32}: OK\n"
time="2021-04-14T17:42:04Z" level=info msg="(cafec0ffee): looking for child digest sha256:c419394f3fa40c32352be5a6ec5865270376d4351a3756bb1893be3f28fcba32"
time="2021-04-14T17:42:04Z" level=error msg="(cafec0ffee) TRANSACTION REJECTED: {Action: \"INSERT\", FQIN: \"us.gcr.io/k8s-artifacts-prod/kas-network-proxy/proxy-agent@sha256:c419394f3fa40c32352be5a6ec5865270376d4351a3756bb1893be3f28fcba32\", PQIN: \"us.gcr.io/k8s-artifacts-prod/kas-network-proxy/proxy-agent:evil\", Path: \"us.gcr.io/k8s-artifacts-prod/kas-network-proxy/proxy-agent\", Digest: \"sha256:c419394f3fa40c32352be5a6ec5865270376d4351a3756bb1893be3f28fcba32\", Tag: \"evil\"}: could not validate"
time="2021-04-14T17:42:04Z" level=error msg="(cafec0ffee) TRANSACTION REJECTED: validation failure: {Action: \"DELETE\", FQIN: \"us.gcr.io/k8s-artifacts-prod/kas-network-proxy/proxy-agent@sha256:c419394f3fa40c32352be5a6ec5865270376d4351a3756bb1893be3f28fcba32\", PQIN: \"\", Path: \"us.gcr.io/k8s-artifacts-prod/kas-network-proxy/proxy-agent\", Digest: \"sha256:c419394f3fa40c32352be5a6ec5865270376d4351a3756bb1893be3f28fcba32\", Tag: \"\"}: deletions are prohibited"
time="2021-04-14T17:42:04Z" level=error msg="(cafec0ffee) TRANSACTION REJECTED: validation failure: {Action: \"DELETE\", FQIN: \"\", PQIN: \"us.gcr.io/k8s-artifacts-prod/kas-network-proxy/proxy-agent:v0.0.8\", Path: \"us.gcr.io/k8s-artifacts-prod/kas-network-proxy/proxy-agent\", Digest: \"\", Tag: \"v0.0.8\"}: deletions are prohibited"
time="2021-04-14T17:42:04Z" level=error msg="(cafec0ffee) TRANSACTION REJECTED: validation failure: {Action: \"DELETE\", FQIN: \"us.gcr.io/k8s-artifacts-prod/secret@sha256:c419394f3fa40c32352be5a6ec5865270376d4351a3756bb1893be3f28fcba32\", PQIN: \"\", Path: \"us.gcr.io/k8s-artifacts-prod/secret\", Digest: \"sha256:c419394f3fa40c32352be5a6ec5865270376d4351a3756bb1893be3f28fcba32\", Tag: \"\"}: deletions are prohibited"
time="2021-04-14T17:42:04Z" level=error msg="(cafec0ffee) TRANSACTION REJECTED: validation failure: {Action: \"DELETE\", FQIN: \"\", PQIN: \"us.gcr.io/k8s-artifacts-prod/secret:v0.0.8\", Path: \"us.gcr.io/k8s-artifacts-prod/secret\", Digest: \"\", Tag: \"v0.0.8\"}: deletions are prohibited"
time="2021-04-14T17:42:04Z" level=info msg="Request {gcr.io/k8s-staging-etcd   true}: OK\n"
time="2021-04-14T17:42:04Z" level=info msg="Request {gcr.io/k8s-staging-etcd/etcd   false}: OK\n"
time="2021-04-14T17:42:04Z" level=info msg="Request {{gcr.io/k8s-staging-etcd   true} etcd 3.4.7-2 sha256:bcdd5657b1edc1a2eb27356f33dd66b9400d4a084209c33461c7a7da0a32ebb3}: OK\n"
time="2021-04-14T17:42:04Z" level=info msg="(cafec0ffee): looking for child digest sha256:0873d877318546c6569e1abfafd75e0625c202d24435299c4d2e57eeebea52ee"
time="2021-04-14T17:42:04Z" level=info msg="(cafec0ffee) TRANSACTION VERIFIED: {Action: \"INSERT\", FQIN: \"us.gcr.io/k8s-artifacts-prod/kubernetes/etcd@sha256:0873d877318546c6569e1abfafd75e0625c202d24435299c4d2e57eeebea52ee\", PQIN: \"\", Path: \"us.gcr.io/k8s-artifacts-prod/kubernetes/etcd\", Digest: \"sha256:0873d877318546c6569e1abfafd75e0625c202d24435299c4d2e57eeebea52ee\", Tag: \"\"}: agrees with manifest (parent digest sha256:bcdd5657b1edc1a2eb27356f33dd66b9400d4a084209c33461c7a7da0a32ebb3)\n"
time="2021-04-14T17:42:04Z" level=info msg="Request {gcr.io/google-containers   true}: OK\n"
... skipping 12 lines ...
?   	k8s.io/release/pkg/cip/container	[no test files]
=== RUN   TestImageRemovalCheck
--- PASS: TestImageRemovalCheck (0.00s)
=== RUN   TestImageSizeCheck
--- PASS: TestImageSizeCheck (0.00s)
=== RUN   TestImageVulnCheck
time="2021-04-14T17:42:05Z" level=error msg="{\n  \"ImageName\": \"foo\",\n  \"Digest\": \"sha256:000\",\n  \"OccurrenceName\": \"\",\n  \"Vulnerability\": {\n    \"severity\": 2,\n    \"fix_available\": true\n  }\n}"
time="2021-04-14T17:42:05Z" level=info msg="Request {{   false} {foo } sha256:000 {   false} {foo }}: OK\n"
time="2021-04-14T17:42:05Z" level=error msg="{\n  \"ImageName\": \"bar\",\n  \"Digest\": \"sha256:111\",\n  \"OccurrenceName\": \"\",\n  \"Vulnerability\": {\n    \"severity\": 3,\n    \"fix_available\": true\n  }\n}"
time="2021-04-14T17:42:05Z" level=info msg="Request {{   false} {bar } sha256:111 {   false} {bar/1 }}: OK\n"
time="2021-04-14T17:42:05Z" level=error msg="Request {{{   false} {foo } sha256:000 {   false} {foo }} <nil>}: error(s) encountered: [{Vulnerability Occurrence w/ Fix Available {\n  \"ImageName\": \"foo\",\n  \"Digest\": \"sha256:000\",\n  \"OccurrenceName\": \"\",\n  \"Vulnerability\": {\n    \"severity\": 4,\n    \"fix_available\": true\n  }\n}}]\n"
time="2021-04-14T17:42:05Z" level=error msg="Request {{{   false} {bar } sha256:111 {   false} {bar/1 }} <nil>}: error(s) encountered: [{Vulnerability Occurrence w/ Fix Available {\n  \"ImageName\": \"bar\",\n  \"Digest\": \"sha256:111\",\n  \"OccurrenceName\": \"\",\n  \"Vulnerability\": {\n    \"severity\": 4,\n    \"fix_available\": true\n  }\n}}]\n"
time="2021-04-14T17:42:05Z" level=error msg="Request {{{   false} {bar } sha256:111 {   false} {bar/1 }} <nil>}: error(s) encountered: [{Vulnerability Occurrence w/ Fix Available {\n  \"ImageName\": \"bar\",\n  \"Digest\": \"sha256:111\",\n  \"OccurrenceName\": \"\",\n  \"Vulnerability\": {\n    \"severity\": 5,\n    \"fix_available\": true\n  }\n}} {Vulnerability Occurrence w/ Fix Available {\n  \"ImageName\": \"bar\",\n  \"Digest\": \"sha256:111\",\n  \"OccurrenceName\": \"\",\n  \"Vulnerability\": {\n    \"severity\": 4,\n    \"fix_available\": true\n  }\n}}]\n"
time="2021-04-14T17:42:05Z" level=error msg="Request {{{   false} {foo } sha256:000 {   false} {foo }} <nil>}: error(s) encountered: [{Vulnerability Occurrence w/ Fix Available {\n  \"ImageName\": \"foo\",\n  \"Digest\": \"sha256:000\",\n  \"OccurrenceName\": \"\",\n  \"Vulnerability\": {\n    \"severity\": 4,\n    \"fix_available\": true\n  }\n}}]\n"
time="2021-04-14T17:42:05Z" level=error msg="Request {{{   false} {bar } sha256:111 {   false} {bar/1 }} <nil>}: error(s) encountered: [{Vulnerability Occurrence w/ Fix Available {\n  \"ImageName\": \"bar\",\n  \"Digest\": \"sha256:111\",\n  \"OccurrenceName\": \"\",\n  \"Vulnerability\": {\n    \"severity\": 4,\n    \"fix_available\": true\n  }\n}}]\n"
time="2021-04-14T17:42:05Z" level=error msg="{\n  \"ImageName\": \"foo\",\n  \"Digest\": \"sha256:000\",\n  \"OccurrenceName\": \"\",\n  \"Vulnerability\": {\n    \"severity\": 4\n  }\n}"
time="2021-04-14T17:42:05Z" level=error msg="{\n  \"ImageName\": \"foo\",\n  \"Digest\": \"sha256:000\",\n  \"OccurrenceName\": \"\",\n  \"Vulnerability\": {\n    \"severity\": 5\n  }\n}"
time="2021-04-14T17:42:05Z" level=info msg="Request {{   false} {foo } sha256:000 {   false} {foo }}: OK\n"
--- PASS: TestImageVulnCheck (0.01s)
=== RUN   TestFindManifest
time="2021-04-14T17:42:05Z" level=info msg="*looking at \"/home/prow/go/src/k8s.io/release/pkg/cip/dockerregistry/inventory_test/TestFindManifest/singleton\""
time="2021-04-14T17:42:05Z" level=info msg="1 manifests parsed"
time="2021-04-14T17:42:05Z" level=info msg="*looking at \"/home/prow/go/src/k8s.io/release/pkg/cip/dockerregistry/inventory_test/TestFindManifest/singleton\""
... skipping 26 lines ...
time="2021-04-14T17:42:05Z" level=info msg="redundant promotion: multiple edges want to promote the same digest to the same destination endpoint asia.gcr.io/some-prod/foo-controller:1.0:"
time="2021-04-14T17:42:05Z" level=info msg="{{gcr.io/foo-staging sa@robot.com  true} {foo-controller 1.0} sha256:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa {asia.gcr.io/some-prod sa@robot.com  false} {foo-controller 1.0}}"
time="2021-04-14T17:42:05Z" level=info msg="{{gcr.io/bar-staging sa@robot.com  true} {foo-controller 1.0} sha256:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa {asia.gcr.io/some-prod sa@robot.com  false} {foo-controller 1.0}}"
time="2021-04-14T17:42:05Z" level=info msg="using the first one: {{gcr.io/foo-staging sa@robot.com  true} {foo-controller 1.0} sha256:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa {asia.gcr.io/some-prod sa@robot.com  false} {foo-controller 1.0}}"
time="2021-04-14T17:42:05Z" level=info msg="*looking at \"/home/prow/go/src/k8s.io/release/pkg/cip/dockerregistry/inventory_test/TestValidateThinManifestsFromDir/valid/malformed-directory-tree-structure-bad-prefix-is-ignored\""
time="2021-04-14T17:42:05Z" level=info msg="*looking at \"/home/prow/go/src/k8s.io/release/pkg/cip/dockerregistry/inventory_test/TestValidateThinManifestsFromDir/invalid/overlapping-destination-vertices-different-digest\""
time="2021-04-14T17:42:05Z" level=error msg="multiple edges want to promote *different* images (digests) to the same destination endpoint asia.gcr.io/some-prod/foo-controller:1.0:"
time="2021-04-14T17:42:05Z" level=error msg="  for digest sha256:bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb:\n"
time="2021-04-14T17:42:05Z" level=error msg="{{gcr.io/foo-staging sa@robot.com  true} {foo-controller 1.0} sha256:bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb {asia.gcr.io/some-prod sa@robot.com  false} {foo-controller 1.0}}\n"
time="2021-04-14T17:42:05Z" level=error msg="  for digest sha256:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa:\n"
time="2021-04-14T17:42:05Z" level=error msg="{{gcr.io/foo-staging sa@robot.com  true} {foo-controller 1.0} sha256:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa {asia.gcr.io/some-prod sa@robot.com  false} {foo-controller 1.0}}\n"
time="2021-04-14T17:42:05Z" level=error msg="multiple edges want to promote *different* images (digests) to the same destination endpoint us.gcr.io/some-prod/foo-controller:1.0:"
time="2021-04-14T17:42:05Z" level=error msg="  for digest sha256:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa:\n"
time="2021-04-14T17:42:05Z" level=error msg="{{gcr.io/foo-staging sa@robot.com  true} {foo-controller 1.0} sha256:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa {us.gcr.io/some-prod sa@robot.com  false} {foo-controller 1.0}}\n"
time="2021-04-14T17:42:05Z" level=error msg="  for digest sha256:bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb:\n"
time="2021-04-14T17:42:05Z" level=error msg="{{gcr.io/foo-staging sa@robot.com  true} {foo-controller 1.0} sha256:bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb {us.gcr.io/some-prod sa@robot.com  false} {foo-controller 1.0}}\n"
time="2021-04-14T17:42:05Z" level=error msg="multiple edges want to promote *different* images (digests) to the same destination endpoint eu.gcr.io/some-prod/foo-controller:1.0:"
time="2021-04-14T17:42:05Z" level=error msg="  for digest sha256:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa:\n"
time="2021-04-14T17:42:05Z" level=error msg="{{gcr.io/foo-staging sa@robot.com  true} {foo-controller 1.0} sha256:aaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaaa {eu.gcr.io/some-prod sa@robot.com  false} {foo-controller 1.0}}\n"
time="2021-04-14T17:42:05Z" level=error msg="  for digest sha256:bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb:\n"
time="2021-04-14T17:42:05Z" level=error msg="{{gcr.io/foo-staging sa@robot.com  true} {foo-controller 1.0} sha256:bbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbbb {eu.gcr.io/some-prod sa@robot.com  false} {foo-controller 1.0}}\n"
time="2021-04-14T17:42:05Z" level=info msg="*looking at \"/home/prow/go/src/k8s.io/release/pkg/cip/dockerregistry/inventory_test/TestValidateThinManifestsFromDir/invalid/malformed-directory-tree-structure\""
time="2021-04-14T17:42:05Z" level=info msg="*looking at \"/home/prow/go/src/k8s.io/release/pkg/cip/dockerregistry/inventory_test/TestValidateThinManifestsFromDir/invalid/malformed-directory-tree-structure-nested\""
time="2021-04-14T17:42:05Z" level=warning msg="stat /home/prow/go/src/k8s.io/release/pkg/cip/dockerregistry/inventory_test/TestValidateThinManifestsFromDir/invalid/malformed-directory-tree-structure-nested/manifests/b/promoter-manifest.yaml: no such file or directory"
time="2021-04-14T17:42:05Z" level=warning msg="stat /home/prow/go/src/k8s.io/release/pkg/cip/dockerregistry/inventory_test/TestValidateThinManifestsFromDir/invalid/malformed-directory-tree-structure-nested/manifests/images/promoter-manifest.yaml: no such file or directory"
--- PASS: TestValidateThinManifestsFromDir (0.01s)
=== RUN   TestParseImageDigest
... skipping 36 lines ...
=== RUN   TestSetManipulationsRegInvImageTag
--- PASS: TestSetManipulationsRegInvImageTag (0.00s)
=== RUN   TestToPromotionEdges
time="2021-04-14T17:42:05Z" level=info msg="edge {{gcr.io/foo robot  true} {a 0.9} sha256:000 {gcr.io/bar robot  false} {a 0.9}}: skipping because it was already promoted (case 1)\n"
time="2021-04-14T17:42:05Z" level=info msg="edge {{gcr.io/foo robot  true} {a 0.9} sha256:000 {gcr.io/cat robot  false} {a 0.9}}: skipping because it was already promoted (case 1)\n"
time="2021-04-14T17:42:05Z" level=info msg="edge {{gcr.io/foo robot  true} {a 0.9} sha256:000 {gcr.io/bar robot  false} {a 0.9}}: skipping because it was already promoted (case 1)\n"
time="2021-04-14T17:42:05Z" level=error msg="edge {{gcr.io/foo robot  true} {c 3.0} sha256:222 {gcr.io/cat robot  false} {c 3.0}}: tag 3.0: ERROR: tag move detected from sha256:222 to sha256:333"
time="2021-04-14T17:42:05Z" level=error msg="edge {{gcr.io/foo robot  true} {c 2.0} sha256:333 {gcr.io/bar robot  false} {c 2.0}}: tag 2.0: ERROR: tag move detected from sha256:333 to sha256:222"
time="2021-04-14T17:42:05Z" level=error msg="edge {{gcr.io/foo robot  true} {c 2.0} sha256:333 {gcr.io/cat robot  false} {c 2.0}}: tag 2.0: ERROR: tag move detected from sha256:333 to sha256:222"
time="2021-04-14T17:42:05Z" level=error msg="edge {{gcr.io/foo robot  true} {c 3.0} sha256:222 {gcr.io/bar robot  false} {c 3.0}}: tag 3.0: ERROR: tag move detected from sha256:222 to sha256:333"
--- PASS: TestToPromotionEdges (0.01s)
=== RUN   TestCheckOverlappingEdges
time="2021-04-14T17:42:05Z" level=error msg="multiple edges want to promote *different* images (digests) to the same destination endpoint gcr.io/bar/a:0.9:"
time="2021-04-14T17:42:05Z" level=error msg="  for digest sha256:111:\n"
time="2021-04-14T17:42:05Z" level=error msg="{{gcr.io/foo robot  true} {b 0.9} sha256:111 {gcr.io/bar robot  false} {a 0.9}}\n"
time="2021-04-14T17:42:05Z" level=error msg="  for digest sha256:000:\n"
time="2021-04-14T17:42:05Z" level=error msg="{{gcr.io/foo robot  true} {a 0.9} sha256:000 {gcr.io/bar robot  false} {a 0.9}}\n"
--- PASS: TestCheckOverlappingEdges (0.00s)
=== RUN   TestRunChecks
time="2021-04-14T17:42:05Z" level=error msg="there was an error in the pull request check"
time="2021-04-14T17:42:05Z" level=error msg="there was an error in the pull request check"
time="2021-04-14T17:42:05Z" level=error msg="there was an error in the pull request check"
--- PASS: TestRunChecks (0.00s)
=== RUN   TestPromotion
time="2021-04-14T17:42:05Z" level=info msg="Nothing to promote."
time="2021-04-14T17:42:05Z" level=info msg="edge {{gcr.io/foo robot  true} {a 0.9} sha256:000 {gcr.io/bar robot  false} {a 0.9}}: skipping because it was already promoted (case 1)\n"
time="2021-04-14T17:42:05Z" level=info msg="edge {{gcr.io/foo robot  true} {a 0.9} sha256:000 {gcr.io/cat robot  false} {a 0.9}}: skipping because it was already promoted (case 1)\n"
time="2021-04-14T17:42:05Z" level=info msg="Nothing to promote."
... skipping 16 lines ...
time="2021-04-14T17:42:05Z" level=info msg="edge {{gcr.io/foo robot  true} {a 0.9} sha256:000 {gcr.io/cat robot  false} {a 0.9}}: skipping because it was already promoted (case 1)\n"
time="2021-04-14T17:42:05Z" level=info msg="Pending promotions:"
time="2021-04-14T17:42:05Z" level=info msg="  {{gcr.io/foo robot  true} {a 0.9} sha256:000 {gcr.io/bar robot  false} {a 0.9}}\n"
time="2021-04-14T17:42:05Z" level=info msg="---------- BEGIN PROMOTION (DRY RUN) ----------"
time="2021-04-14T17:42:05Z" level=info msg="Request <nil>: OK\n"
No requests captured.
time="2021-04-14T17:42:05Z" level=error msg="edge {{gcr.io/foo robot  true} {a good} sha256:bad {gcr.io/cat robot  false} {a good}}: tag good: ERROR: tag move detected from sha256:bad to sha256:600d"
time="2021-04-14T17:42:05Z" level=error msg="edge {{gcr.io/foo robot  true} {a good} sha256:bad {gcr.io/bar robot  false} {a good}}: tag good: ERROR: tag move detected from sha256:bad to sha256:600d"
time="2021-04-14T17:42:05Z" level=info msg="Nothing to promote."
time="2021-04-14T17:42:05Z" level=info msg="edge {{gcr.io/foo robot  true} {a 0.9} sha256:000 {us.gcr.io/dog/some/subdir/path/foo robot  false} {a 0.9}}: regular promotion (neither digest nor pqin exists in dst)\n"
time="2021-04-14T17:42:05Z" level=info msg="Pending promotions:"
time="2021-04-14T17:42:05Z" level=info msg="  {{gcr.io/foo robot  true} {a 0.9} sha256:000 {us.gcr.io/dog/some/subdir/path/foo robot  false} {a 0.9}}\n"
time="2021-04-14T17:42:05Z" level=info msg="---------- BEGIN PROMOTION (DRY RUN) ----------"
time="2021-04-14T17:42:05Z" level=info msg="Request <nil>: OK\n"
... skipping 4 lines ...
time="2021-04-14T17:42:05Z" level=info msg="---------- BEGIN PROMOTION (DRY RUN) ----------"
time="2021-04-14T17:42:05Z" level=info msg="Request <nil>: OK\n"
No requests captured.
time="2021-04-14T17:42:05Z" level=info msg="edge {{gcr.io/foo robot  true} {a 0.9} sha256:000 {gcr.io/bar robot  false} {a 0.9}}: skipping because it was already promoted (case 1)\n"
time="2021-04-14T17:42:05Z" level=info msg="edge {{gcr.io/foo robot  true} {a 0.9} sha256:000 {gcr.io/cat robot  false} {a 0.9}}: skipping because it was already promoted (case 1)\n"
time="2021-04-14T17:42:05Z" level=info msg="Nothing to promote."
time="2021-04-14T17:42:05Z" level=error msg="edge {{gcr.io/foo robot  true} {a 0.8} sha256:333 {gcr.io/cat robot  false} {a 0.8}}: skipping gcr.io/foo/a@sha256:333 because it is _LOST_ (can't find it in src registry!)\n"
time="2021-04-14T17:42:05Z" level=error msg="edge {{gcr.io/foo robot  true} {b also-missing} sha256:bbb {gcr.io/bar robot  false} {b also-missing}}: skipping gcr.io/foo/b@sha256:bbb because it is _LOST_ (can't find it in src registry!)\n"
time="2021-04-14T17:42:05Z" level=error msg="edge {{gcr.io/foo robot  true} {b also-missing} sha256:bbb {gcr.io/cat robot  false} {b also-missing}}: skipping gcr.io/foo/b@sha256:bbb because it is _LOST_ (can't find it in src registry!)\n"
time="2021-04-14T17:42:05Z" level=error msg="edge {{gcr.io/foo robot  true} {a missing-from-src} sha256:000 {gcr.io/bar robot  false} {a missing-from-src}}: skipping gcr.io/foo/a@sha256:000 because it is _LOST_ (can't find it in src registry!)\n"
time="2021-04-14T17:42:05Z" level=error msg="edge {{gcr.io/foo robot  true} {a missing-from-src} sha256:000 {gcr.io/cat robot  false} {a missing-from-src}}: skipping gcr.io/foo/a@sha256:000 because it is _LOST_ (can't find it in src registry!)\n"
time="2021-04-14T17:42:05Z" level=info msg="edge {{gcr.io/foo robot  true} {a 0.8} sha256:333 {gcr.io/bar robot  false} {a 0.8}}: skipping because it was already promoted (case 1)\n"
time="2021-04-14T17:42:05Z" level=info msg="Nothing to promote."
time="2021-04-14T17:42:05Z" level=info msg="edge {{gcr.io/foo robot  true} {a 1.0} sha256:000 {gcr.io/cat robot  false} {a 1.0}}: digest \"sha256:000\" already exists, but does not have the pqin we want ([0.9])\n"
time="2021-04-14T17:42:05Z" level=info msg="edge {{gcr.io/foo robot  true} {a 0.9} sha256:000 {gcr.io/bar robot  false} {a 0.9}}: skipping because it was already promoted (case 1)\n"
time="2021-04-14T17:42:05Z" level=info msg="edge {{gcr.io/foo robot  true} {a 1.0} sha256:000 {gcr.io/bar robot  false} {a 1.0}}: digest \"sha256:000\" already exists, but does not have the pqin we want ([0.9])\n"
time="2021-04-14T17:42:05Z" level=info msg="edge {{gcr.io/foo robot  true} {a 0.9} sha256:000 {gcr.io/cat robot  false} {a 0.9}}: skipping because it was already promoted (case 1)\n"
... skipping 16 lines ...
--- PASS: TestPromotion (0.00s)
=== RUN   TestExecRequests
time="2021-04-14T17:42:05Z" level=info msg="---------- BEGIN PROMOTION (DRY RUN) ----------"
time="2021-04-14T17:42:05Z" level=info msg="Request {0 gcr.io/foo gcr.io/bar robot a a sha256:000  0.9}: OK\n"
time="2021-04-14T17:42:05Z" level=info msg="Request {0 gcr.io/foo gcr.io/cat robot a a sha256:000  0.9}: OK\n"
time="2021-04-14T17:42:05Z" level=info msg="---------- BEGIN PROMOTION (DRY RUN) ----------"
time="2021-04-14T17:42:05Z" level=error msg="Request {{0 gcr.io/foo gcr.io/cat robot a a sha256:000  0.9} <nil>}: error(s) encountered: [{Running TestExecRequests This request results in an error}]\n"
time="2021-04-14T17:42:05Z" level=error msg="Request {{0 gcr.io/foo gcr.io/bar robot a a sha256:000  0.9} <nil>}: error(s) encountered: [{Running TestExecRequests This request results in an error}]\n"
--- PASS: TestExecRequests (0.00s)
=== RUN   TestGarbageCollection
No requests captured.
time="2021-04-14T17:42:05Z" level=info msg="Request <nil>: OK\n"
time="2021-04-14T17:42:05Z" level=info msg="Request <nil>: OK\n"
No requests captured.
... skipping 53 lines ...
time="2021-04-14T17:42:05Z" level=info msg="TOOL_ORG: kubernetes"
time="2021-04-14T17:42:05Z" level=info msg="TOOL_REPO: release"
time="2021-04-14T17:42:05Z" level=info msg="TOOL_REF: master"
time="2021-04-14T17:42:05Z" level=info msg="KUBE_CROSS_VERSION: v1.16.1-1"
time="2021-04-14T17:42:05Z" level=info msg="KUBE_CROSS_VERSION_LATEST: v1.16.1-1"
time="2021-04-14T17:42:05Z" level=info msg="Listing last 5 GCB jobs:"
    gcb_test.go:101: Test case: error on list jobs
time="2021-04-14T17:42:05Z" level=info msg="Running GCB with the following options: &{Options:{objStore:<nil> BuildDir: ConfigDir: CloudbuildFile: LogDir: ScratchBucket: Project: AllowDirty:false NoSource:false Async:false DiskSize: Variant: EnvPassthrough:} NoMock:false Stage:false Release:false Stream:false BuildAtHead:false Branch:master ReleaseType: BuildVersion: GcpUser:test-user LogLevel: LastJobs:10}"
time="2021-04-14T17:42:05Z" level=info msg="Trying to retrieve kube-cross version for branch master"
time="2021-04-14T17:42:05Z" level=info msg="Sending GET request to https://raw.githubusercontent.com/kubernetes/kubernetes/master/build/build-image/cross/VERSION"
time="2021-04-14T17:42:05Z" level=info msg="Retrieved kube-cross version: v1.16.1-1"
time="2021-04-14T17:42:05Z" level=info msg="Listing GCB substitutions prior to build submission..."
time="2021-04-14T17:42:05Z" level=info msg="TYPE: "
... skipping 414 lines ...
Branch 'release-1.17' set up to track remote branch 'release-1.17' from 'origin'.
--- PASS: TestPushToRemoteSuccessBranchTracked (0.10s)
=== RUN   TestPushToRemoteFailureBranchNotExisting
Switched to a new branch 'release-1.17'
Switched to a new branch 'release-1.17'
Branch 'release-1.17' set up to track remote branch 'release-1.17' from 'origin'.
error: src refspec some-branch does not match any.
error: failed to push some refs to '/tmp/k8s-test-bare-1784788289'
--- PASS: TestPushToRemoteFailureBranchNotExisting (0.10s)
=== RUN   TestLSRemoteSuccess
Switched to a new branch 'release-1.17'
Switched to a new branch 'release-1.17'
Branch 'release-1.17' set up to track remote branch 'release-1.17' from 'origin'.
--- PASS: TestLSRemoteSuccess (0.10s)
... skipping 3708 lines ...
=== CONT  TestGatherNotes/when_GetPullRequest(...)_returns_an_error
=== CONT  TestGatherNotes/empty_commit_list
=== CONT  TestGatherNotes/when_we_get_PRs_they_get_filtered_based_on_the_content_of_the_PR_body
=== CONT  TestGatherNotes/when_ListPullRequestsWithCommit(...)_returns_an_error
=== CONT  TestGatherNotes/when_we_get_PRs_they_get_filtered_based_on_the_content_of_the_PR_body
    notes_gatherer_test.go:375: Expected the result to be of size 7, got 13
--- FAIL: TestGatherNotes (0.00s)
    --- PASS: TestGatherNotes/when_commit_messages_hold_PR_numbers,_we_use_those_and_don't_query_to_get_a_list_of_PRs_for_a_SHA (0.00s)
    --- PASS: TestGatherNotes/when_no_PR_number_can_be_parsed_from_the_commit_message,_we_try_to_get_a_PR_by_SHA (0.00s)
    --- PASS: TestGatherNotes/empty_commit_list (0.00s)
    --- PASS: TestGatherNotes/when_GetPullRequest(...)_returns_an_error (0.00s)
    --- PASS: TestGatherNotes/when_ListPullRequestsWithCommit(...)_returns_an_error (0.00s)
    --- FAIL: TestGatherNotes/when_we_get_PRs_they_get_filtered_based_on_the_content_of_the_PR_body (0.00s)
=== RUN   TestNewProviderFromInitString
--- PASS: TestNewProviderFromInitString (0.00s)
=== RUN   TestParseReleaseNotesMap
--- PASS: TestParseReleaseNotesMap (0.00s)
=== RUN   TestGetMapsForPR
--- PASS: TestGetMapsForPR (0.00s)
... skipping 25 lines ...
=== RUN   TestApplyMap
--- PASS: TestApplyMap (0.00s)
=== RUN   TestDependencyChangesSuccess
--- PASS: TestDependencyChangesSuccess (0.00s)
=== RUN   TestDependencyChangesFailure
--- PASS: TestDependencyChangesFailure (0.00s)
FAIL
coverage: 50.4% of statements
FAIL	k8s.io/release/pkg/notes	0.041s
=== RUN   TestFileMetadata
--- PASS: TestFileMetadata (0.00s)
=== RUN   TestDocument_RenderMarkdownTemplateFailure
=== RUN   TestDocument_RenderMarkdownTemplateFailure/given_template_exist_but_is_empty
=== RUN   TestDocument_RenderMarkdownTemplateFailure/given_bad_template_spec
=== RUN   TestDocument_RenderMarkdownTemplateFailure/given_bad_template_contents
... skipping 913 lines ...
=== RUN   TestGetMarkerPath
time="2021-04-14T17:42:07Z" level=info msg="Version marker path is gs://k8s-release-dev/ci"
--- PASS: TestGetMarkerPath (0.00s)
=== RUN   TestNormalizePath
--- PASS: TestNormalizePath (0.00s)
=== RUN   TestIsPathNormalized
time="2021-04-14T17:42:07Z" level=error msg="GCS path (k8s-release-dev/g) should be prefixed with `gs://`"
time="2021-04-14T17:42:07Z" level=error msg="GCS path (gs://k8s-release-dev/gs:/k8s-release-dev/ci-no-bootstrap/fast/v1.20.0-beta.1.655+d20e3246bade17) should be prefixed with `gs:/`"
--- PASS: TestIsPathNormalized (0.00s)
PASS
coverage: 50.4% of statements
ok  	k8s.io/release/pkg/object	0.033s	coverage: 50.4% of statements
?   	k8s.io/release/pkg/object/objectfakes	[no test files]
=== RUN   TestHash
... skipping 598 lines ...
ok  	k8s.io/release/pkg/version	0.015s	coverage: 93.8% of statements
=== RUN   TestGenerateVulnerabilityBreakdown
--- PASS: TestGenerateVulnerabilityBreakdown (0.00s)
PASS
coverage: 19.6% of statements
ok  	k8s.io/release/pkg/vulndash/adapter	0.014s	coverage: 19.6% of statements
FAIL
make: *** [Makefile:67: test-go-unit] Error 1