starting build "40128dda-b87f-4327-8b97-b11be1abb01a"

FETCHSOURCE
hint: Using 'master' as the name for the initial branch. This default branch name
hint: is subject to change. To configure the initial branch name to use in all
hint: of your new repositories, which will suppress this warning, call:
hint: 
hint: 	git config --global init.defaultBranch <name>
hint: 
hint: Names commonly chosen instead of 'master' are 'main', 'trunk' and
hint: 'development'. The just-created branch can be renamed via this command:
hint: 
hint: 	git branch -m <name>
Initialized empty Git repository in /workspace/.git/
From https://github.com/googleapis/google-cloud-cpp
 * branch              69fd9d8ebdbfe878734369742bcebae8a055f414 -> FETCH_HEAD
Updating files:   1% (264/19258)
Updating files:   2% (386/19258)
Updating files:   3% (578/19258)
Updating files:   4% (771/19258)
Updating files:   5% (963/19258)
Updating files:   6% (1156/19258)
Updating files:   7% (1349/19258)
Updating files:   8% (1541/19258)
Updating files:   9% (1734/19258)
Updating files:  10% (1926/19258)
Updating files:  11% (2119/19258)
Updating files:  12% (2311/19258)
Updating files:  13% (2504/19258)
Updating files:  14% (2697/19258)
Updating files:  15% (2889/19258)
Updating files:  16% (3082/19258)
Updating files:  17% (3274/19258)
Updating files:  18% (3467/19258)
Updating files:  19% (3660/19258)
Updating files:  20% (3852/19258)
Updating files:  21% (4045/19258)
Updating files:  22% (4237/19258)
Updating files:  23% (4430/19258)
Updating files:  24% (4622/19258)
Updating files:  25% (4815/19258)
Updating files:  26% (5008/19258)
Updating files:  27% (5200/19258)
Updating files:  28% (5393/19258)
Updating files:  29% (5585/19258)
Updating files:  30% (5778/19258)
Updating files:  31% (5970/19258)
Updating files:  32% (6163/19258)
Updating files:  33% (6356/19258)
Updating files:  34% (6548/19258)
Updating files:  35% (6741/19258)
Updating files:  36% (6933/19258)
Updating files:  37% (7126/19258)
Updating files:  38% (7319/19258)
Updating files:  39% (7511/19258)
Updating files:  40% (7704/19258)
Updating files:  41% (7896/19258)
Updating files:  42% (8089/19258)
Updating files:  43% (8281/19258)
Updating files:  44% (8474/19258)
Updating files:  45% (8667/19258)
Updating files:  46% (8859/19258)
Updating files:  47% (9052/19258)
Updating files:  48% (9244/19258)
Updating files:  49% (9437/19258)
Updating files:  50% (9629/19258)
Updating files:  51% (9822/19258)
Updating files:  52% (10015/19258)
Updating files:  53% (10207/19258)
Updating files:  54% (10400/19258)
Updating files:  55% (10592/19258)
Updating files:  56% (10785/19258)
Updating files:  57% (10978/19258)
Updating files:  58% (11170/19258)
Updating files:  59% (11363/19258)
Updating files:  60% (11555/19258)
Updating files:  61% (11748/19258)
Updating files:  62% (11940/19258)
Updating files:  63% (12133/19258)
Updating files:  64% (12326/19258)
Updating files:  65% (12518/19258)
Updating files:  66% (12711/19258)
Updating files:  67% (12903/19258)
Updating files:  68% (13096/19258)
Updating files:  69% (13289/19258)
Updating files:  70% (13481/19258)
Updating files:  71% (13674/19258)
Updating files:  72% (13866/19258)
Updating files:  73% (14059/19258)
Updating files:  74% (14251/19258)
Updating files:  75% (14444/19258)
Updating files:  76% (14637/19258)
Updating files:  77% (14829/19258)
Updating files:  78% (15022/19258)
Updating files:  79% (15214/19258)
Updating files:  80% (15407/19258)
Updating files:  81% (15599/19258)
Updating files:  82% (15792/19258)
Updating files:  83% (15985/19258)
Updating files:  83% (16028/19258)
Updating files:  84% (16177/19258)
Updating files:  85% (16370/19258)
Updating files:  86% (16562/19258)
Updating files:  87% (16755/19258)
Updating files:  88% (16948/19258)
Updating files:  89% (17140/19258)
Updating files:  90% (17333/19258)
Updating files:  91% (17525/19258)
Updating files:  92% (17718/19258)
Updating files:  93% (17910/19258)
Updating files:  94% (18103/19258)
Updating files:  95% (18296/19258)
Updating files:  96% (18488/19258)
Updating files:  97% (18681/19258)
Updating files:  98% (18873/19258)
Updating files:  99% (19066/19258)
Updating files: 100% (19258/19258)
Updating files: 100% (19258/19258), done.
HEAD is now at 69fd9d8e fix(spanner): correct use of Interval offset in Timestamp addition
BUILD
Starting Step #7
Starting Step #0 - "kaniko-attempt-1"
Starting Step #6
Step #0 - "kaniko-attempt-1": Pulling image: gcr.io/kaniko-project/executor:v1.22.0
Step #7: Pulling image: gcr.io/google.com/cloudsdktool/cloud-sdk
Step #6: Pulling image: gcr.io/google.com/cloudsdktool/cloud-sdk
Step #6: Using default tag: latest
Step #7: Using default tag: latest
Step #0 - "kaniko-attempt-1": v1.22.0: Pulling from kaniko-project/executor
Step #0 - "kaniko-attempt-1": Digest: sha256:808488de16595e217ef292fc68011b24583430e0c5633d50676088104c29cdc4
Step #0 - "kaniko-attempt-1": Status: Downloaded newer image for gcr.io/kaniko-project/executor:v1.22.0
Step #0 - "kaniko-attempt-1": gcr.io/kaniko-project/executor:v1.22.0
Step #7: latest: Pulling from google.com/cloudsdktool/cloud-sdk
Step #7: 646e886fa3cf: Already exists
Step #7: 00f95c1aebdb: Pulling fs layer
Step #7: 871d386ae88f: Pulling fs layer
Step #7: 329ced68eddc: Pulling fs layer
Step #7: 55ca8bf88d3f: Pulling fs layer
Step #7: 3f1221b02a36: Pulling fs layer
Step #7: e73fde60ae16: Pulling fs layer
Step #7: 55ca8bf88d3f: Waiting
Step #7: 3f1221b02a36: Waiting
Step #7: e73fde60ae16: Waiting
Step #6: latest: Pulling from google.com/cloudsdktool/cloud-sdk
Step #6: 646e886fa3cf: Already exists
Step #6: 00f95c1aebdb: Pulling fs layer
Step #6: 871d386ae88f: Pulling fs layer
Step #6: 329ced68eddc: Pulling fs layer
Step #6: 55ca8bf88d3f: Pulling fs layer
Step #6: 3f1221b02a36: Pulling fs layer
Step #6: e73fde60ae16: Pulling fs layer
Step #6: e73fde60ae16: Waiting
Step #6: 55ca8bf88d3f: Waiting
Step #6: 3f1221b02a36: Waiting
Step #7: 329ced68eddc: Verifying Checksum
Step #7: 329ced68eddc: Download complete
Step #6: 329ced68eddc: Verifying Checksum
Step #6: 329ced68eddc: Download complete
Step #6: 871d386ae88f: Verifying Checksum
Step #6: 871d386ae88f: Download complete
Step #7: 871d386ae88f: Verifying Checksum
Step #7: 871d386ae88f: Download complete
Step #6: 00f95c1aebdb: Download complete
Step #7: 00f95c1aebdb: Verifying Checksum
Step #7: 00f95c1aebdb: Download complete
Step #7: e73fde60ae16: Verifying Checksum
Step #7: e73fde60ae16: Download complete
Step #6: e73fde60ae16: Verifying Checksum
Step #6: e73fde60ae16: Download complete
Step #6: 00f95c1aebdb: Pull complete
Step #7: 00f95c1aebdb: Pull complete
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:19Z" level=info msg="Using dockerignore file: /workspace/ci/.dockerignore"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:19Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:19Z" level=info msg="Retrieving image ubuntu:focal from registry index.docker.io"
Step #6: 3f1221b02a36: Verifying Checksum
Step #6: 3f1221b02a36: Download complete
Step #7: 3f1221b02a36: Verifying Checksum
Step #7: 3f1221b02a36: Download complete
Step #6: 871d386ae88f: Pull complete
Step #7: 871d386ae88f: Pull complete
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:20Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:20Z" level=info msg="Returning cached image manifest"
Step #6: 329ced68eddc: Pull complete
Step #7: 329ced68eddc: Pull complete
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:20Z" level=info msg="Built cross stage deps: map[]"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:20Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:20Z" level=info msg="Returning cached image manifest"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:20Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:20Z" level=info msg="Returning cached image manifest"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:20Z" level=info msg="Executing 0 build triggers"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:20Z" level=info msg="Building stage 'ubuntu:focal' [idx: '0', base-idx: '-1']"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:20Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:6de5431eedfa7afe6d3bfde5d20ae6995e8950c924622b7b231f8459c8832ab7..."
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:20Z" level=info msg="Using caching version of cmd: RUN apt-get update &&     apt-get --no-install-recommends install -y         automake         build-essential         clang         clang-9         cmake         ctags         curl         gawk         git         gcc         g++         cmake         libcurl4-openssl-dev         libssl-dev         libtool         llvm-9         lsb-release         make         ninja-build         patch         pkg-config         python3         python3-dev         python3-pip         tar         unzip         zip         wget         zlib1g-dev         apt-utils         ca-certificates         apt-transport-https"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:20Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:03898dd42999c9657be3128ea9ec610b5e96facf4ddf924e89cf6d52b8df836c..."
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:20Z" level=info msg="Using caching version of cmd: RUN update-alternatives --install /usr/bin/python python $(which python3) 10"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:20Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:2d27f0a2ae548edea2204337eb98b2d9c99f77e499f11b898d4aa4fc4a09996a..."
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:21Z" level=info msg="Using caching version of cmd: RUN pip3 install setuptools wheel"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:21Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:8b4fa34bfaccda086afcebe6a25c9e5b677af184e32de226ba9e1c44845a60a1..."
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:21Z" level=info msg="Using caching version of cmd: RUN apt update && (apt install -y openjdk-11-jre || apt install -y openjdk-9-jre)"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:21Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:9eabed4c3537b51e3ac029f2f61ecd5b7c5817edc6e78830160c51b01c065afb..."
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:21Z" level=info msg="Using caching version of cmd: RUN /var/tmp/ci/install-cloud-sdk.sh"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:21Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:b53192933c87b05212231a1c4067a1ff919551ce0cf71baf4332e59cb23e5e45..."
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:22Z" level=info msg="Using caching version of cmd: RUN curl -o /usr/bin/bazelisk -sSL \"https://github.com/bazelbuild/bazelisk/releases/download/v1.19.0/bazelisk-linux-${ARCH}\" &&     chmod +x /usr/bin/bazelisk &&     ln -s /usr/bin/bazelisk /usr/bin/bazel"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:22Z" level=info msg="Unpacking rootfs as cmd COPY . /var/tmp/ci requires it."
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:23Z" level=info msg="ARG NCPU=4"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:23Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:23Z" level=info msg="ARG ARCH=amd64"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:23Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:23Z" level=info msg="ENV DEBIAN_FRONTEND=noninteractive"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:23Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:23Z" level=info msg="RUN apt-get update &&     apt-get --no-install-recommends install -y         automake         build-essential         clang         clang-9         cmake         ctags         curl         gawk         git         gcc         g++         cmake         libcurl4-openssl-dev         libssl-dev         libtool         llvm-9         lsb-release         make         ninja-build         patch         pkg-config         python3         python3-dev         python3-pip         tar         unzip         zip         wget         zlib1g-dev         apt-utils         ca-certificates         apt-transport-https"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:27:23Z" level=info msg="Found cached layer, extracting to filesystem"
Step #6: 55ca8bf88d3f: Verifying Checksum
Step #6: 55ca8bf88d3f: Download complete
Step #7: 55ca8bf88d3f: Verifying Checksum
Step #7: 55ca8bf88d3f: Download complete
Step #7: 55ca8bf88d3f: Pull complete
Step #6: 55ca8bf88d3f: Pull complete
Step #7: 3f1221b02a36: Pull complete
Step #6: 3f1221b02a36: Pull complete
Step #6: e73fde60ae16: Pull complete
Step #7: e73fde60ae16: Pull complete
Step #6: Digest: sha256:aa962f47d8c60e3b132ec7f1efdf8475fa693b9cbe6d1a6c6e1079a4b27ca450
Step #7: Digest: sha256:aa962f47d8c60e3b132ec7f1efdf8475fa693b9cbe6d1a6c6e1079a4b27ca450
Step #7: Status: Downloaded newer image for gcr.io/google.com/cloudsdktool/cloud-sdk:latest
Step #6: Status: Downloaded newer image for gcr.io/google.com/cloudsdktool/cloud-sdk:latest
Step #6: gcr.io/google.com/cloudsdktool/cloud-sdk:latest
Step #7: gcr.io/google.com/cloudsdktool/cloud-sdk:latest
Step #7: + test -z 14121
Step #7: ++ gcloud builds describe --region us-east1 --format 'value(create_time)' 40128dda-b87f-4327-8b97-b11be1abb01a
Step #7: + ctime=2024-05-03T17:26:14.105429Z
Step #7: + query=tags=pr
Step #7: + query+=' AND tags=14121'
Step #7: + query+=' AND substitutions.COMMIT_SHA != 69fd9d8ebdbfe878734369742bcebae8a055f414'
Step #7: + query+=' AND create_time < 2024-05-03T17:26:14.105429Z'
Step #7: + gcloud builds list --region us-east1 --ongoing '--format=value(id)' --filter 'tags=pr AND tags=14121 AND substitutions.COMMIT_SHA != 69fd9d8ebdbfe878734369742bcebae8a055f414 AND create_time < 2024-05-03T17:26:14.105429Z'
Step #7: + xargs -r -t gcloud builds cancel --region us-east1
Step #7: WARNING: The following filter keys were not present in any resource : create_time, substitutions.COMMIT_SHA, tags
Finished Step #6
Finished Step #7
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:20Z" level=info msg="RUN update-alternatives --install /usr/bin/python python $(which python3) 10"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:20Z" level=info msg="Found cached layer, extracting to filesystem"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:21Z" level=info msg="RUN pip3 install setuptools wheel"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:21Z" level=info msg="Found cached layer, extracting to filesystem"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:22Z" level=info msg="RUN apt update && (apt install -y openjdk-11-jre || apt install -y openjdk-9-jre)"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:22Z" level=info msg="Found cached layer, extracting to filesystem"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:47Z" level=info msg="COPY . /var/tmp/ci"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:47Z" level=info msg="Taking snapshot of files..."
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:47Z" level=info msg="WORKDIR /var/tmp/downloads"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:47Z" level=info msg="Cmd: workdir"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:47Z" level=info msg="Changed working directory to /var/tmp/downloads"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:47Z" level=info msg="Creating directory /var/tmp/downloads with uid -1 and gid -1"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:47Z" level=info msg="Taking snapshot of files..."
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:47Z" level=info msg="RUN /var/tmp/ci/install-cloud-sdk.sh"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:28:47Z" level=info msg="Found cached layer, extracting to filesystem"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:30:38Z" level=info msg="ENV CLOUD_SDK_LOCATION=/usr/local/google-cloud-sdk"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:30:38Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:30:38Z" level=info msg="ENV PATH=${CLOUD_SDK_LOCATION}/bin:${PATH}"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:30:38Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:30:38Z" level=info msg="RUN curl -o /usr/bin/bazelisk -sSL \"https://github.com/bazelbuild/bazelisk/releases/download/v1.19.0/bazelisk-linux-${ARCH}\" &&     chmod +x /usr/bin/bazelisk &&     ln -s /usr/bin/bazelisk /usr/bin/bazel"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:30:38Z" level=info msg="Found cached layer, extracting to filesystem"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:30:39Z" level=info msg="Pushing image to gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal:40128dda-b87f-4327-8b97-b11be1abb01a"
Step #0 - "kaniko-attempt-1": time="2024-05-03T17:30:40Z" level=info msg="Pushed gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal@sha256:a061ce48575049afe027893a648201211b2a72db16d1cb03c04bc7b1905a8345"
Finished Step #0 - "kaniko-attempt-1"
Starting Step #2 - "kaniko-attempt-2"
Starting Step #1 - "download-runner-image-attempt-1"
Step #1 - "download-runner-image-attempt-1": Pulling image: gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal:40128dda-b87f-4327-8b97-b11be1abb01a
Step #2 - "kaniko-attempt-2": Already have image (with digest): gcr.io/kaniko-project/executor:v1.22.0
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:44Z" level=info msg="Using dockerignore file: /workspace/ci/.dockerignore"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:44Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:44Z" level=info msg="Retrieving image ubuntu:focal from registry index.docker.io"
Step #1 - "download-runner-image-attempt-1": 40128dda-b87f-4327-8b97-b11be1abb01a: Pulling from cloud-cpp-testing-resources/cloudbuild/ubuntu-focal
Step #1 - "download-runner-image-attempt-1": d4c3c94e5e10: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": c1e0b52bb8bd: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": 912f525d607c: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": 4d634c6cdd73: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": f56312335e89: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": 769e5cbfc4c0: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": 87f9756b4af8: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": b9f4f9eaf93e: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": f4319e00a786: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": f56312335e89: Waiting
Step #1 - "download-runner-image-attempt-1": 769e5cbfc4c0: Waiting
Step #1 - "download-runner-image-attempt-1": 87f9756b4af8: Waiting
Step #1 - "download-runner-image-attempt-1": b9f4f9eaf93e: Waiting
Step #1 - "download-runner-image-attempt-1": f4319e00a786: Waiting
Step #1 - "download-runner-image-attempt-1": 4d634c6cdd73: Waiting
Step #1 - "download-runner-image-attempt-1": 912f525d607c: Download complete
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:44Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:44Z" level=info msg="Returning cached image manifest"
Step #1 - "download-runner-image-attempt-1": 4d634c6cdd73: Verifying Checksum
Step #1 - "download-runner-image-attempt-1": 4d634c6cdd73: Download complete
Step #1 - "download-runner-image-attempt-1": d4c3c94e5e10: Verifying Checksum
Step #1 - "download-runner-image-attempt-1": d4c3c94e5e10: Download complete
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:45Z" level=info msg="Built cross stage deps: map[]"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:45Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:45Z" level=info msg="Returning cached image manifest"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:45Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:45Z" level=info msg="Returning cached image manifest"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:45Z" level=info msg="Executing 0 build triggers"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:45Z" level=info msg="Building stage 'ubuntu:focal' [idx: '0', base-idx: '-1']"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:45Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:6de5431eedfa7afe6d3bfde5d20ae6995e8950c924622b7b231f8459c8832ab7..."
Step #1 - "download-runner-image-attempt-1": 769e5cbfc4c0: Download complete
Step #1 - "download-runner-image-attempt-1": 87f9756b4af8: Verifying Checksum
Step #1 - "download-runner-image-attempt-1": 87f9756b4af8: Download complete
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:45Z" level=info msg="Using caching version of cmd: RUN apt-get update &&     apt-get --no-install-recommends install -y         automake         build-essential         clang         clang-9         cmake         ctags         curl         gawk         git         gcc         g++         cmake         libcurl4-openssl-dev         libssl-dev         libtool         llvm-9         lsb-release         make         ninja-build         patch         pkg-config         python3         python3-dev         python3-pip         tar         unzip         zip         wget         zlib1g-dev         apt-utils         ca-certificates         apt-transport-https"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:45Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:03898dd42999c9657be3128ea9ec610b5e96facf4ddf924e89cf6d52b8df836c..."
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:45Z" level=info msg="Using caching version of cmd: RUN update-alternatives --install /usr/bin/python python $(which python3) 10"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:45Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:2d27f0a2ae548edea2204337eb98b2d9c99f77e499f11b898d4aa4fc4a09996a..."
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:45Z" level=info msg="Using caching version of cmd: RUN pip3 install setuptools wheel"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:45Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:8b4fa34bfaccda086afcebe6a25c9e5b677af184e32de226ba9e1c44845a60a1..."
Step #1 - "download-runner-image-attempt-1": d4c3c94e5e10: Pull complete
Step #1 - "download-runner-image-attempt-1": f56312335e89: Verifying Checksum
Step #1 - "download-runner-image-attempt-1": f56312335e89: Download complete
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:46Z" level=info msg="Using caching version of cmd: RUN apt update && (apt install -y openjdk-11-jre || apt install -y openjdk-9-jre)"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:46Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:9eabed4c3537b51e3ac029f2f61ecd5b7c5817edc6e78830160c51b01c065afb..."
Step #1 - "download-runner-image-attempt-1": f4319e00a786: Verifying Checksum
Step #1 - "download-runner-image-attempt-1": f4319e00a786: Download complete
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:46Z" level=info msg="Using caching version of cmd: RUN /var/tmp/ci/install-cloud-sdk.sh"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:46Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:b53192933c87b05212231a1c4067a1ff919551ce0cf71baf4332e59cb23e5e45..."
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:46Z" level=info msg="Using caching version of cmd: RUN curl -o /usr/bin/bazelisk -sSL \"https://github.com/bazelbuild/bazelisk/releases/download/v1.19.0/bazelisk-linux-${ARCH}\" &&     chmod +x /usr/bin/bazelisk &&     ln -s /usr/bin/bazelisk /usr/bin/bazel"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:46Z" level=info msg="Unpacking rootfs as cmd COPY . /var/tmp/ci requires it."
Step #1 - "download-runner-image-attempt-1": c1e0b52bb8bd: Verifying Checksum
Step #1 - "download-runner-image-attempt-1": c1e0b52bb8bd: Download complete
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:48Z" level=info msg="ARG NCPU=4"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:48Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:48Z" level=info msg="ARG ARCH=amd64"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:48Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:48Z" level=info msg="ENV DEBIAN_FRONTEND=noninteractive"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:48Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:48Z" level=info msg="RUN apt-get update &&     apt-get --no-install-recommends install -y         automake         build-essential         clang         clang-9         cmake         ctags         curl         gawk         git         gcc         g++         cmake         libcurl4-openssl-dev         libssl-dev         libtool         llvm-9         lsb-release         make         ninja-build         patch         pkg-config         python3         python3-dev         python3-pip         tar         unzip         zip         wget         zlib1g-dev         apt-utils         ca-certificates         apt-transport-https"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:30:48Z" level=info msg="Found cached layer, extracting to filesystem"
Step #1 - "download-runner-image-attempt-1": b9f4f9eaf93e: Verifying Checksum
Step #1 - "download-runner-image-attempt-1": b9f4f9eaf93e: Download complete
Step #1 - "download-runner-image-attempt-1": c1e0b52bb8bd: Pull complete
Step #1 - "download-runner-image-attempt-1": 912f525d607c: Pull complete
Step #1 - "download-runner-image-attempt-1": 4d634c6cdd73: Pull complete
Step #1 - "download-runner-image-attempt-1": f56312335e89: Pull complete
Step #1 - "download-runner-image-attempt-1": 769e5cbfc4c0: Pull complete
Step #1 - "download-runner-image-attempt-1": 87f9756b4af8: Pull complete
Step #1 - "download-runner-image-attempt-1": b9f4f9eaf93e: Pull complete
Step #1 - "download-runner-image-attempt-1": f4319e00a786: Pull complete
Step #1 - "download-runner-image-attempt-1": Digest: sha256:a061ce48575049afe027893a648201211b2a72db16d1cb03c04bc7b1905a8345
Step #1 - "download-runner-image-attempt-1": Status: Downloaded newer image for gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal:40128dda-b87f-4327-8b97-b11be1abb01a
Step #1 - "download-runner-image-attempt-1": gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal:40128dda-b87f-4327-8b97-b11be1abb01a
Finished Step #1 - "download-runner-image-attempt-1"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:31:46Z" level=info msg="RUN update-alternatives --install /usr/bin/python python $(which python3) 10"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:31:46Z" level=info msg="Found cached layer, extracting to filesystem"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:31:46Z" level=info msg="RUN pip3 install setuptools wheel"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:31:46Z" level=info msg="Found cached layer, extracting to filesystem"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:31:47Z" level=info msg="RUN apt update && (apt install -y openjdk-11-jre || apt install -y openjdk-9-jre)"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:31:47Z" level=info msg="Found cached layer, extracting to filesystem"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:32:10Z" level=info msg="COPY . /var/tmp/ci"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:32:10Z" level=info msg="Taking snapshot of files..."
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:32:10Z" level=info msg="WORKDIR /var/tmp/downloads"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:32:10Z" level=info msg="Cmd: workdir"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:32:10Z" level=info msg="Changed working directory to /var/tmp/downloads"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:32:10Z" level=info msg="Creating directory /var/tmp/downloads with uid -1 and gid -1"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:32:10Z" level=info msg="Taking snapshot of files..."
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:32:10Z" level=info msg="RUN /var/tmp/ci/install-cloud-sdk.sh"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:32:10Z" level=info msg="Found cached layer, extracting to filesystem"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:34:03Z" level=info msg="ENV CLOUD_SDK_LOCATION=/usr/local/google-cloud-sdk"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:34:03Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:34:03Z" level=info msg="ENV PATH=${CLOUD_SDK_LOCATION}/bin:${PATH}"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:34:03Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:34:03Z" level=info msg="RUN curl -o /usr/bin/bazelisk -sSL \"https://github.com/bazelbuild/bazelisk/releases/download/v1.19.0/bazelisk-linux-${ARCH}\" &&     chmod +x /usr/bin/bazelisk &&     ln -s /usr/bin/bazelisk /usr/bin/bazel"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:34:03Z" level=info msg="Found cached layer, extracting to filesystem"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:34:04Z" level=info msg="Pushing image to gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal:40128dda-b87f-4327-8b97-b11be1abb01a"
Step #2 - "kaniko-attempt-2": time="2024-05-03T17:34:05Z" level=info msg="Pushed gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal@sha256:cf8e921a74a3a9320924210c3f46deb6c4c1145a56cd3e9b4e0e217fcc0f6a07"
Finished Step #2 - "kaniko-attempt-2
...
[Logs truncated due to log size limitations. For full logs, see https://storage.cloud.google.com/cloud-cpp-community-publiclogs/logs/google-cloud-cpp/14121/69fd9d8ebdbfe878734369742bcebae8a055f414/ubuntu-focal-cmake-oldest-deps-__default__/log-40128dda-b87f-4327-8b97-b11be1abb01a.txt.]
...
nner_row_test ..........................................................   Passed    0.02 sec
Step #4:         Start 702: spanner_testing_random_database_name_test
Step #4: 533/572 Test #698: spanner_session_pool_options_test .........................................   Passed    0.01 sec
Step #4:         Start 703: spanner_timestamp_test
Step #4: 534/572 Test #662: spanner_database_admin_connection_test ....................................   Passed    0.28 sec
Step #4:         Start 704: spanner_transaction_test
Step #4: 535/572 Test #699: spanner_spanner_version_test ..............................................   Passed    0.02 sec
Step #4:         Start 705: spanner_update_instance_request_builder_test
Step #4: 536/572 Test #578: bigtable_internal_mutate_rows_limiter_test ................................   Passed    0.53 sec
Step #4:         Start 706: spanner_value_test
Step #4: 537/572 Test #700: spanner_sql_statement_test ................................................   Passed    0.03 sec
Step #4:         Start 707: spanner_bytes_benchmark
Step #4: 538/572 Test #702: spanner_testing_random_database_name_test .................................   Passed    0.02 sec
Step #4:         Start 708: spanner_internal_merge_chunk_benchmark
Step #4: 539/572 Test #703: spanner_timestamp_test ....................................................   Passed    0.02 sec
Step #4:         Start 709: spanner_numeric_benchmark
Step #4: 540/572 Test #704: spanner_transaction_test ..................................................   Passed    0.02 sec
Step #4:         Start 710: spanner_row_benchmark
Step #4: 541/572 Test #705: spanner_update_instance_request_builder_test ..............................   Passed    0.02 sec
Step #4: 542/572 Test #701: spanner_testing_cleanup_stale_databases_test ..............................   Passed    0.04 sec
Step #4: 543/572 Test #596: bigtable_rpc_retry_policy_test ............................................   Passed    0.49 sec
Step #4: 544/572 Test #598: bigtable_table_apply_test .................................................   Passed    0.53 sec
Step #4: 545/572 Test #706: spanner_value_test ........................................................   Passed    0.08 sec
Step #4: 546/572 Test #331: storage_internal_async_insert_object_test .................................   Passed    1.19 sec
Step #4: 547/572 Test #488: pubsub_internal_subscription_session_test .................................   Passed    0.94 sec
Step #4: 548/572 Test #681: spanner_internal_transaction_impl_test ....................................   Passed    0.34 sec
Step #4: 549/572 Test #647: pubsublite_internal_resumable_async_streaming_read_write_rpc_test .........   Passed    0.58 sec
Step #4: 550/572 Test #615: bigtable_bigtable_benchmark_test ..........................................   Passed    0.73 sec
Step #4: 551/572 Test #655: spanner_bytes_test ........................................................   Passed    0.72 sec
Step #4: 552/572 Test  #74: common_internal_async_connection_ready_test ...............................   Passed    2.02 sec
Step #4: 553/572 Test #657: spanner_client_test .......................................................   Passed    0.75 sec
Step #4: 554/572 Test #218: storage_client_bucket_test ................................................   Passed    1.82 sec
Step #4: 555/572 Test #667: spanner_internal_connection_impl_test .....................................   Passed    0.90 sec
Step #4: 556/572 Test #332: storage_internal_async_partial_upload_test ................................   Passed    1.99 sec
Step #4: 557/572 Test #640: pubsublite_internal_alarm_registry_impl_test ..............................   Passed    2.01 sec
Step #4: 558/572 Test #707: spanner_bytes_benchmark ...................................................   Passed    1.66 sec
Step #4: 559/572 Test #644: pubsublite_internal_multipartition_publisher_test .........................   Passed    2.02 sec
Step #4: 560/572 Test #710: spanner_row_benchmark .....................................................   Passed    1.75 sec
Step #4: 561/572 Test #487: pubsub_internal_subscription_message_queue_test ...........................   Passed    2.57 sec
Step #4: 562/572 Test #228: storage_client_test .......................................................   Passed    3.08 sec
Step #4: 563/572 Test #312: storage_internal_crc32c_benchmark .........................................   Passed    3.41 sec
Step #4: 564/572 Test #366: storage_internal_grpc_split_write_object_data_test ........................   Passed    3.90 sec
Step #4: 565/572 Test #354: storage_internal_grpc_make_cord_test ......................................   Passed    4.07 sec
Step #4: 566/572 Test #708: spanner_internal_merge_chunk_benchmark ....................................   Passed    3.49 sec
Step #4: 567/572 Test  #68: common_options_benchmark ..................................................   Passed    6.72 sec
Step #4: 568/572 Test  #70: common_completion_queue_test ..............................................   Passed    8.03 sec
Step #4: 569/572 Test #143: common_internal_oauth2_compute_engine_credentials_test ....................   Passed    8.05 sec
Step #4: 570/572 Test #119: common_completion_queue_benchmark .........................................   Passed   10.22 sec
Step #4: 571/572 Test #709: spanner_numeric_benchmark .................................................   Passed    8.86 sec
Step #4: 572/572 Test #170: common_internal_curl_handle_factory_benchmark .............................   Passed   18.98 sec
Step #4: 
Step #4: 100% tests passed, 0 tests failed out of 572
Step #4: 
Step #4: Total Test time (real) =  19.35 sec
Step #4: 
Step #4: 2024-05-03T17:45:43Z (+693s)
Step #4: ---------------------------------------------------------
Step #4: |   Running Pub/Sub integration tests (with emulator)   |
Step #4: ---------------------------------------------------------
Step #4: 2024-05-03T17:45:43Z (+693s): Launching Cloud Pub/Sub emulator in the background
Step #4: 2024-05-03T17:45:45Z (+695s): Cloud Pub/Sub emulator started at localhost:8085
Step #4: Test project /workspace/cmake-out/build
Step #4:     Start 516: pubsub_endurance
Step #4:     Start 517: pubsub_throughput
Step #4:     Start 520: pubsub_blocking_publisher_integration_test
Step #4:     Start 521: pubsub_publisher_integration_test
Step #4:     Start 522: pubsub_subscriber_integration_test
Step #4:     Start 523: pubsub_subscription_admin_integration_test
Step #4:     Start 524: pubsub_topic_admin_integration_test
Step #4: 1/7 Test #524: pubsub_topic_admin_integration_test ..........   Passed    0.63 sec
Step #4: 2/7 Test #521: pubsub_publisher_integration_test ............   Passed    0.69 sec
Step #4: 3/7 Test #520: pubsub_blocking_publisher_integration_test ...   Passed    0.72 sec
Step #4: 4/7 Test #523: pubsub_subscription_admin_integration_test ...   Passed    0.78 sec
Step #4: 5/7 Test #517: pubsub_throughput ............................   Passed    1.47 sec
Step #4: 6/7 Test #522: pubsub_subscriber_integration_test ...........   Passed    8.68 sec
Step #4: 7/7 Test #516: pubsub_endurance .............................   Passed   10.38 sec
Step #4: 
Step #4: 100% tests passed, 0 tests failed out of 7
Step #4: 
Step #4: Label Time Summary:
Step #4: integration-test             =  23.34 sec*proc (7 tests)
Step #4: integration-test-emulator    =  23.34 sec*proc (7 tests)
Step #4: 
Step #4: Total Test time (real) =  10.41 sec
Step #4: Killing Pub/Sub Emulator [25694] +. done.
Step #4: 
Step #4: 2024-05-03T17:45:55Z (+705s)
Step #4: ---------------------------------------------------------
Step #4: |   Running Storage integration tests (with emulator)   |
Step #4: ---------------------------------------------------------
Step #4: 2024-05-03T17:45:55Z (+705s): Launching Cloud Storage emulator on port 0
Step #4: Successfully connected to emulator [28459]
Step #4: 2024-05-03T17:45:56Z (+706s): Successfully connected to gRPC server at port 40263
Step #4: Test project /workspace/cmake-out/build
Step #4:       Start 375: storage_async_client_integration_test
Step #4:       Start 376: storage_auto_finalize_integration_test
Step #4:       Start 377: storage_bucket_integration_test
Step #4:       Start 378: storage_create_client_integration_test
Step #4:       Start 379: storage_curl_sign_blob_integration_test
Step #4:       Start 380: storage_decompressive_transcoding_integration_test
Step #4:       Start 381: storage_error_injection_integration_test
Step #4:       Start 382: storage_error_parsing_integration_test
Step #4:       Start 383: storage_grpc_bucket_acl_integration_test
Step #4:       Start 384: storage_grpc_bucket_metadata_integration_test
Step #4:       Start 385: storage_grpc_default_object_acl_integration_test
Step #4:       Start 386: storage_grpc_hmac_key_integration_test
Step #4:       Start 387: storage_grpc_integration_test
Step #4:       Start 388: storage_grpc_notification_integration_test
Step #4:       Start 389: storage_grpc_object_acl_integration_test
Step #4:       Start 390: storage_grpc_object_media_integration_test
Step #4:       Start 391: storage_grpc_object_metadata_integration_test
Step #4:       Start 392: storage_grpc_service_account_integration_test
Step #4:       Start 394: storage_object_basic_crud_integration_test
Step #4:       Start 395: storage_object_checksum_integration_test
Step #4:       Start 396: storage_object_compose_many_integration_test
Step #4:       Start 397: storage_object_file_integration_test
Step #4:       Start 398: storage_object_file_multi_threaded_test
Step #4:       Start 399: storage_object_hash_integration_test
Step #4:       Start 400: storage_object_insert_integration_test
Step #4:       Start 401: storage_object_insert_preconditions_integration_test
Step #4:       Start 402: storage_object_integration_test
Step #4:       Start 403: storage_object_list_objects_versions_integration_test
Step #4:       Start 404: storage_object_media_integration_test
Step #4:       Start 405: storage_object_parallel_upload_integration_test
Step #4:       Start 406: storage_object_plenty_clients_serially_integration_test
Step #4:       Start 407: storage_object_plenty_clients_simultaneously_integration_test
Step #4:  1/62 Test #379: storage_curl_sign_blob_integration_test .........................   Passed    0.06 sec
Step #4:       Start 408: storage_object_read_headers_integration_test
Step #4:  2/62 Test #392: storage_grpc_service_account_integration_test ...................   Passed    0.28 sec
Step #4:       Start 409: storage_object_read_large_integration_test
Step #4:  3/62 Test #390: storage_grpc_object_media_integration_test ......................   Passed    0.31 sec
Step #4:       Start 410: storage_object_read_preconditions_integration_test
Step #4:  4/62 Test #382: storage_error_parsing_integration_test ..........................   Passed    1.06 sec
Step #4:       Start 411: storage_object_read_range_integration_test
Step #4:  5/62 Test #388: storage_grpc_notification_integration_test ......................   Passed    1.43 sec
Step #4:       Start 412: storage_object_read_stream_integration_test
Step #4:  6/62 Test #386: storage_grpc_hmac_key_integration_test ..........................   Passed    1.59 sec
Step #4:       Start 413: storage_object_resumable_parallel_upload_integration_test
Step #4:  7/62 Test #398: storage_object_file_multi_threaded_test .........................   Passed    2.86 sec
Step #4:       Start 414: storage_object_resumable_write_integration_test
Step #4:  8/62 Test #412: storage_object_read_stream_integration_test .....................   Passed    1.92 sec
Step #4:       Start 415: storage_object_rewrite_integration_test
Step #4:  9/62 Test #383: storage_grpc_bucket_acl_integration_test ........................   Passed    3.38 sec
Step #4:       Start 416: storage_object_write_preconditions_integration_test
Step #4: 10/62 Test #384: storage_grpc_bucket_metadata_integration_test ...................   Passed    3.39 sec
Step #4:       Start 417: storage_object_write_stream_integration_test
Step #4: 11/62 Test #409: storage_object_read_large_integration_test ......................   Passed    3.10 sec
Step #4:       Start 418: storage_object_write_streambuf_integration_test
Step #4: 12/62 Test #391: storage_grpc_object_metadata_integration_test ...................   Passed    3.39 sec
Step #4:       Start 419: storage_service_account_credentials_integration_test
Step #4: 13/62 Test #385: storage_grpc_default_object_acl_integration_test ................   Passed    3.41 sec
Step #4:       Start 420: storage_service_account_integration_test
Step #4: 14/62 Test #419: storage_service_account_credentials_integration_test ............   Passed    0.01 sec
Step #4:       Start 421: storage_signed_url_conformance_test
Step #4: 15/62 Test #389: storage_grpc_object_acl_integration_test ........................   Passed    3.41 sec
Step #4:       Start 423: storage_slow_reader_chunk_integration_test
Step #4: 16/62 Test #378: storage_create_client_integration_test ..........................   Passed    3.45 sec
Step #4:       Start 424: storage_slow_reader_stream_integration_test
Step #4: 17/62 Test #396: storage_object_compose_many_integration_test ....................   Passed    3.45 sec
Step #4:       Start 425: storage_small_reads_integration_test
Step #4: 18/62 Test #403: storage_object_list_objects_versions_integration_test ...........   Passed    3.49 sec
Step #4:       Start 426: storage_smoke_test_async
Step #4: 19/62 Test #421: storage_signed_url_conformance_test .............................   Passed    0.14 sec
Step #4:       Start 427: storage_smoke_test_grpc
Step #4: 20/62 Test #426: storage_smoke_test_async ........................................   Passed    0.10 sec
Step #4:       Start 428: storage_smoke_test_rest
Step #4: 21/62 Test #427: storage_smoke_test_grpc .........................................   Passed    0.14 sec
Step #4:       Start 429: storage_storage_include_test
Step #4: 22/62 Test #429: storage_storage_include_test ....................................   Passed    0.00 sec
Step #4:       Start 430: storage_thread_integration_test
Step #4: 23/62 Test #376: storage_auto_finalize_integration_test ..........................   Passed    3.85 sec
Step #4:       Start 431: storage_tracing_integration_test
Step #4: 24/62 Test #428: storage_smoke_test_rest .........................................   Passed    0.22 sec
Step #4:       Start 433: storage_aggregate_download_throughput_benchmark
Step #4: 25/62 Test #394: storage_object_basic_crud_integration_test ......................   Passed    4.72 sec
Step #4:       Start 434: storage_aggregate_upload_throughput_benchmark
Step #4: 26/62 Test #431: storage_tracing_integration_test ................................   Passed    1.56 sec
Step #4:       Start 435: storage_create_dataset
Step #4: 27/62 Test #433: storage_aggregate_download_throughput_benchmark .................   Passed    1.57 sec
Step #4:       Start 436: storage_storage_file_transfer_benchmark
Step #4: 28/62 Test #417: storage_object_write_stream_integration_test ....................   Passed    3.96 sec
Step #4:       Start 437: storage_storage_parallel_uploads_benchmark
Step #4: 29/62 Test #413: storage_object_resumable_parallel_upload_integration_test .......   Passed    6.33 sec
Step #4:       Start 438: storage_storage_throughput_vs_cpu_benchmark
Step #4: 30/62 Test #420: storage_service_account_integration_test ........................   Passed    4.87 sec
Step #4:       Start 439: storage_throughput_experiment_test
Step #4: 31/62 Test #436: storage_storage_file_transfer_benchmark .........................   Passed    3.17 sec
Step #4: 32/62 Test #418: storage_object_write_streambuf_integration_test .................   Passed    5.23 sec
Step #4: 33/62 Test #425: storage_small_reads_integration_test ............................   Passed    5.18 sec
Step #4: 34/62 Test #438: storage_storage_throughput_vs_cpu_benchmark .....................   Passed    1.69 sec
Step #4: 35/62 Test #401: storage_object_insert_preconditions_integration_test ............   Passed   11.05 sec
Step #4: 36/62 Test #411: storage_object_read_range_integration_test ......................   Passed   10.24 sec
Step #4: 37/62 Test #434: storage_aggregate_upload_throughput_benchmark ...................   Passed    6.84 sec
Step #4: 38/62 Test #410: storage_object_read_preconditions_integration_test ..............   Passed   11.26 sec
Step #4: 39/62 Test #381: storage_error_injection_integration_test ........................   Passed   12.45 sec
Step #4: 40/62 Test #416: storage_object_write_preconditions_integration_test .............   Passed    9.84 sec
Step #4: 41/62 Test #387: storage_grpc_integration_test ...................................   Passed   13.22 sec
Step #4: 42/62 Test #437: storage_storage_parallel_uploads_benchmark ......................   Passed    6.06 sec
Step #4: 43/62 Test #399: storage_object_hash_integration_test ............................   Passed   13.40 sec
Step #4: 44/62 Test #395: storage_object_checksum_integration_test ........................   Passed   13.83 sec
Step #4: 45/62 Test #397: storage_object_file_integration_test ............................   Passed   15.20 sec
Step #4: 46/62 Test #375: storage_async_client_integration_test ...........................   Passed   17.47 sec
Step #4: 47/62 Test #415: storage_object_rewrite_integration_test .........................   Passed   14.23 sec
Step #4: 48/62 Test #408: storage_object_read_headers_integration_test ....................   Passed   17.76 sec
Step #4: 49/62 Test #414: storage_object_resumable_write_integration_test .................   Passed   15.53 sec
Step #4: 50/62 Test #402: storage_object_integration_test .................................   Passed   18.61 sec
Step #4: 51/62 Test #377: storage_bucket_integration_test .................................   Passed   18.70 sec
Step #4: 52/62 Test #400: storage_object_insert_integration_test ..........................   Passed   18.72 sec
Step #4: 53/62 Test #406: storage_object_plenty_clients_serially_integration_test .........   Passed   19.17 sec
Step #4: 54/62 Test #407: storage_object_plenty_clients_simultaneously_integration_test ...   Passed   19.21 sec
Step #4: 55/62 Test #405: storage_object_parallel_upload_integration_test .................   Passed   20.99 sec
Step #4: 56/62 Test #439: storage_throughput_experiment_test ..............................   Passed   14.96 sec
Step #4: 57/62 Test #430: storage_thread_integration_test .................................   Passed   25.34 sec
Step #4: 58/62 Test #423: storage_slow_reader_chunk_integration_test ......................   Passed   37.74 sec
Step #4: 59/62 Test #424: storage_slow_reader_stream_integration_test .....................   Passed   37.85 sec
Step #4: 60/62 Test #435: storage_create_dataset ..........................................   Passed   36.95 sec
Step #4: 61/62 Test #380: storage_decompressive_transcoding_integration_test ..............   Passed   45.89 sec
Step #4: 62/62 Test #404: storage_object_media_integration_test ...........................   Passed   84.39 sec
Step #4: 
Step #4: 100% tests passed, 0 tests failed out of 62
Step #4: 
Step #4: Label Time Summary:
Step #4: integration-test             = 669.64 sec*proc (62 tests)
Step #4: integration-test-emulator    = 669.64 sec*proc (62 tests)
Step #4: integration-test-gha         =   0.45 sec*proc (3 tests)
Step #4: 
Step #4: Total Test time (real) =  84.47 sec
Step #4: Killing emulator server [28459] ... done.
Step #4: 
Step #4: 2024-05-03T17:47:28Z (+798s)
Step #4: ---------------------------------------------------------
Step #4: |   Running Spanner integration tests (with emulator)   |
Step #4: ---------------------------------------------------------
Step #4: 2024-05-03T17:47:28Z (+798s): Launching Cloud Spanner emulator in the background
Step #4: 2024-05-03T17:47:29Z (+799s): Spanner emulator started at localhost:8787
Step #4: 2024-05-03T17:47:29Z (+799s): Launching Cloud Spanner HTTP emulator in the background
Step #4: 2024-05-03T17:47:31Z (+801s): Spanner HTTP emulator started at http://localhost:8788
Step #4: Test project /workspace/cmake-out/build
Step #4:       Start 711: spanner_backup_extra_integration_test
Step #4:       Start 712: spanner_backup_integration_test
Step #4:       Start 713: spanner_client_integration_test
Step #4:       Start 714: spanner_client_stress_test
Step #4:       Start 715: spanner_data_types_integration_test
Step #4:       Start 716: spanner_database_admin_integration_test
Step #4:       Start 717: spanner_instance_admin_integration_test
Step #4:       Start 718: spanner_session_pool_integration_test
Step #4:       Start 719: spanner_admin_backup_extra_integration_test
Step #4:       Start 720: spanner_admin_backup_integration_test
Step #4:       Start 721: spanner_admin_database_admin_integration_test
Step #4:       Start 722: spanner_admin_instance_admin_integration_test
Step #4:       Start 725: spanner_benchmarks_config_test
Step #4:       Start 726: spanner_multiple_rows_cpu_benchmark
Step #4:       Start 727: spanner_single_row_throughput_benchmark
Step #4:  1/15 Test #712: spanner_backup_integration_test .................   Passed    0.03 sec
Step #4:  2/15 Test #720: spanner_admin_backup_integration_test ...........   Passed    0.01 sec
Step #4:  3/15 Test #725: spanner_benchmarks_config_test ..................   Passed    0.01 sec
Step #4:  4/15 Test #711: spanner_backup_extra_integration_test ...........   Passed    0.05 sec
Step #4:  5/15 Test #719: spanner_admin_backup_extra_integration_test .....   Passed    0.05 sec
Step #4:  6/15 Test #717: spanner_instance_admin_integration_test .........   Passed    0.06 sec
Step #4:  7/15 Test #716: spanner_database_admin_integration_test .........   Passed    0.18 sec
Step #4:  8/15 Test #721: spanner_admin_database_admin_integration_test ...   Passed    0.21 sec
Step #4:  9/15 Test #722: spanner_admin_instance_admin_integration_test ...   Passed    0.28 sec
Step #4: 10/15 Test #715: spanner_data_types_integration_test .............   Passed    0.66 sec
Step #4: 11/15 Test #727: spanner_single_row_throughput_benchmark .........   Passed    4.69 sec
Step #4: 12/15 Test #718: spanner_session_pool_integration_test ...........   Passed    5.07 sec
Step #4: 13/15 Test #713: spanner_client_integration_test .................   Passed   10.61 sec
Step #4: 14/15 Test #714: spanner_client_stress_test ......................   Passed   17.00 sec
Step #4: 15/15 Test #726: spanner_multiple_rows_cpu_benchmark .............   Passed   30.56 sec
Step #4: 
Step #4: 100% tests passed, 0 tests failed out of 15
Step #4: 
Step #4: Label Time Summary:
Step #4: integration-test             =  69.47 sec*proc (15 tests)
Step #4: integration-test-emulator    =  69.47 sec*proc (15 tests)
Step #4: 
Step #4: Total Test time (real) =  30.61 sec
Step #4: Killing Spanner Emulator [30014] +. done.
Step #4: ================ emulator.log ================
Step #4:      1	WARNING: All log messages before absl::InitializeLog() is called are written to STDERR
Step #4:      2	I0000 00:00:1714758448.559786   30014 emulator_main.cc:39] Cloud Spanner Emulator running.
Step #4:      3	I0000 00:00:1714758448.559827   30014 emulator_main.cc:40] Server address: localhost:8787
Step #4:      4	I0000 00:00:1714758451.770743   30672 schema_updater.cc:789] INTERNAL: ZETASQL_RET_CHECK failure (backend/schema/updater/schema_updater.cc:789) false Unsupported ddl statement: 61
Step #4:      5	external/com_googlesource_code_re2/re2/re2.cc:842: DFA out of memory: pattern length 41, program size 117214, list count 29752, bytemap range 106
Step #4:      6	external/com_googlesource_code_re2/re2/re2.cc:842: DFA out of memory: pattern length 27, program size 117373, list count 29802, bytemap range 105
Step #4:      7	I0000 00:00:1714758452.036541   30360 spangres_translator.cc:187] EngineSystemCatalog initialized
Step #4:      8	I0000 00:00:1714758452.566602   32899 schema_updater.cc:789] INTERNAL: ZETASQL_RET_CHECK failure (backend/schema/updater/schema_updater.cc:789) false Unsupported ddl statement: 61
Step #4: ==============================================
Step #4: Killing Spanner HTTP Emulator [30055] +. done.
Step #4: ============== http_emulator.log =============
Step #4:      1	WARNING: proto: file "google/rpc/status.proto" is already registered
Step #4:      2		previously from: "google.golang.org/genproto/googleapis/rpc/status"
Step #4:      3		currently from:  "unknown"
Step #4:      4	See https://protobuf.dev/reference/go/faq#namespace-conflict
Step #4:      5	
Step #4:      6	WARNING: proto: file "google/rpc/status.proto" has a name conflict over google.rpc.Status
Step #4:      7		previously from: "google.golang.org/genproto/googleapis/rpc/status"
Step #4:      8		currently from:  "unknown"
Step #4:      9	See https://protobuf.dev/reference/go/faq#namespace-conflict
Step #4:     10	
Step #4:     11	WARNING: proto: message google.rpc.Status is already registered
Step #4:     12		previously from: "google.golang.org/genproto/googleapis/rpc/status"
Step #4:     13		currently from:  "unknown"
Step #4:     14	See https://protobuf.dev/reference/go/faq#namespace-conflict
Step #4:     15	
Step #4:     16	WARNING: All log messages before absl::InitializeLog() is called are written to STDERR
Step #4:     17	I0000 00:00:1714758449.591787   30065 emulator_main.cc:39] Cloud Spanner Emulator running.
Step #4:     18	I0000 00:00:1714758449.591817   30065 emulator_main.cc:40] Server address: localhost:8789
Step #4:     19	2024/05/03 17:47:30 gateway.go:144: Cloud Spanner emulator running.
Step #4:     20	2024/05/03 17:47:30 gateway.go:145: REST server listening at localhost:8788
Step #4:     21	2024/05/03 17:47:30 gateway.go:146: gRPC server listening at localhost:8789
Step #4: ==============================================
Step #4: 
Step #4: 2024-05-03T17:48:02Z (+832s)
Step #4: ----------------------------------------------------------
Step #4: |   Running Bigtable integration tests (with emulator)   |
Step #4: ----------------------------------------------------------
Step #4: 2024-05-03T17:48:02Z (+832s): Launching Cloud Bigtable emulators in the background
Step #4: 2024-05-03T17:48:02Z (+832s): Connected to the Cloud Bigtable emulator on localhost:8480
Step #4: 2024-05-03T17:48:02Z (+832s): Connected to the Cloud Bigtable emulator on localhost:8490
Step #4: Test project /workspace/cmake-out/build
Step #4:       Start 620: bigtable_apply_read_latency_benchmark
Step #4:       Start 621: bigtable_endurance_benchmark
Step #4:       Start 622: bigtable_mutation_batcher_throughput_benchmark
Step #4:       Start 623: bigtable_read_sync_vs_async_benchmark
Step #4:       Start 624: bigtable_scan_throughput_benchmark
Step #4:       Start 627: bigtable_admin_integration_test
Step #4:       Start 628: bigtable_data_async_future_integration_test
Step #4:       Start 629: bigtable_data_integration_test
Step #4:       Start 630: bigtable_filters_integration_test
Step #4:       Start 631: bigtable_instance_admin_integration_test
Step #4:       Start 632: bigtable_mutations_integration_test
Step #4:       Start 633: bigtable_table_sample_rows_integration_test
Step #4:  1/12 Test #622: bigtable_mutation_batcher_throughput_benchmark ...   Passed    0.14 sec
Step #4:  2/12 Test #633: bigtable_table_sample_rows_integration_test ......   Passed    0.16 sec
Step #4:  3/12 Test #631: bigtable_instance_admin_integration_test .........   Passed    0.27 sec
Step #4:  4/12 Test #627: bigtable_admin_integration_test ..................   Passed    0.55 sec
Step #4:  5/12 Test #632: bigtable_mutations_integration_test ..............   Passed    0.72 sec
Step #4:  6/12 Test #628: bigtable_data_async_future_integration_test ......   Passed    0.81 sec
Step #4:  7/12 Test #621: bigtable_endurance_benchmark .....................   Passed    1.03 sec
Step #4:  8/12 Test #623: bigtable_read_sync_vs_async_benchmark ............   Passed    1.74 sec
Step #4:  9/12 Test #620: bigtable_apply_read_latency_benchmark ............   Passed    1.78 sec
Step #4: 10/12 Test #630: bigtable_filters_integration_test ................   Passed    3.18 sec
Step #4: 11/12 Test #624: bigtable_scan_throughput_benchmark ...............   Passed    4.72 sec
Step #4: 12/12 Test #629: bigtable_data_integration_test ...................   Passed    6.13 sec
Step #4: 
Step #4: 100% tests passed, 0 tests failed out of 12
Step #4: 
Step #4: Label Time Summary:
Step #4: integration-test             =  21.24 sec*proc (12 tests)
Step #4: integration-test-emulator    =  21.24 sec*proc (12 tests)
Step #4: 
Step #4: Total Test time (real) =   6.16 sec
Step #4: 2024-05-03T17:48:08Z (+838s): Killing Bigtable Emulators...
Step #4: EMULATOR_PID=43804 .+.
Step #4: INSTANCE_ADMIN_EMULATOR_PID=43805 .+.
Step #4: 
Step #4: 2024-05-03T17:48:08Z (+838s)
Step #4: ------------------------------------------------------
Step #4: |   Running REST integration tests (with emulator)   |
Step #4: ------------------------------------------------------
Step #4: 2024-05-03T17:48:08Z (+838s): Launching Cloud Storage emulator on port 0
Step #4: Successfully connected to emulator [44822]
Step #4: 2024-05-03T17:48:09Z (+839s): Successfully connected to gRPC server at port 33991
Step #4: Test project /workspace/cmake-out/build
Step #4:     Start 167: common_internal_curl_rest_client_integration_test
Step #4: 1/1 Test #167: common_internal_curl_rest_client_integration_test ...   Passed    0.60 sec
Step #4: 
Step #4: 100% tests passed, 0 tests failed out of 1
Step #4: 
Step #4: Label Time Summary:
Step #4: integration-test             =   0.60 sec*proc (1 test)
Step #4: integration-test-emulator    =   0.60 sec*proc (1 test)
Step #4: 
Step #4: Total Test time (real) =   0.62 sec
Step #4: Killing emulator server [44822] ... done.
Step #4: ==> 🕑 cmake-oldest-deps completed in 840.344 seconds
Finished Step #4
Starting Step #5
Step #5: Already have image (with digest): gcr.io/google.com/cloudsdktool/cloud-sdk
Step #5: WARNING: Successfully resolved tag to sha256, but it is recommended to use sha256 directly.
Step #5: Digests:
Step #5: - gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal@sha256:cf8e921a74a3a9320924210c3f46deb6c4c1145a56cd3e9b4e0e217fcc0f6a07
Step #5:   Associated tags:
Step #5:  - 40128dda-b87f-4327-8b97-b11be1abb01a
Step #5: Tags:
Step #5: - gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal:40128dda-b87f-4327-8b97-b11be1abb01a
Step #5: Deleted [gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal:40128dda-b87f-4327-8b97-b11be1abb01a].
Step #5: Deleted [gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal@sha256:cf8e921a74a3a9320924210c3f46deb6c4c1145a56cd3e9b4e0e217fcc0f6a07].
Finished Step #5
PUSH
DONE