starting build "126fe211-e8d6-466f-a5a8-bae7b0779b4a"

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              f2d8f8cce1c63fb01c16aeb4072aa5d04f75dff4 -> FETCH_HEAD
Updating files:   3% (631/18569)
Updating files:   4% (743/18569)
Updating files:   5% (929/18569)
Updating files:   6% (1115/18569)
Updating files:   7% (1300/18569)
Updating files:   8% (1486/18569)
Updating files:   9% (1672/18569)
Updating files:  10% (1857/18569)
Updating files:  11% (2043/18569)
Updating files:  12% (2229/18569)
Updating files:  13% (2414/18569)
Updating files:  14% (2600/18569)
Updating files:  15% (2786/18569)
Updating files:  16% (2972/18569)
Updating files:  17% (3157/18569)
Updating files:  18% (3343/18569)
Updating files:  19% (3529/18569)
Updating files:  20% (3714/18569)
Updating files:  21% (3900/18569)
Updating files:  22% (4086/18569)
Updating files:  23% (4271/18569)
Updating files:  24% (4457/18569)
Updating files:  25% (4643/18569)
Updating files:  26% (4828/18569)
Updating files:  27% (5014/18569)
Updating files:  28% (5200/18569)
Updating files:  29% (5386/18569)
Updating files:  30% (5571/18569)
Updating files:  31% (5757/18569)
Updating files:  32% (5943/18569)
Updating files:  33% (6128/18569)
Updating files:  34% (6314/18569)
Updating files:  35% (6500/18569)
Updating files:  36% (6685/18569)
Updating files:  37% (6871/18569)
Updating files:  38% (7057/18569)
Updating files:  39% (7242/18569)
Updating files:  40% (7428/18569)
Updating files:  41% (7614/18569)
Updating files:  42% (7799/18569)
Updating files:  43% (7985/18569)
Updating files:  44% (8171/18569)
Updating files:  45% (8357/18569)
Updating files:  46% (8542/18569)
Updating files:  47% (8728/18569)
Updating files:  48% (8914/18569)
Updating files:  49% (9099/18569)
Updating files:  50% (9285/18569)
Updating files:  51% (9471/18569)
Updating files:  52% (9656/18569)
Updating files:  53% (9842/18569)
Updating files:  54% (10028/18569)
Updating files:  55% (10213/18569)
Updating files:  56% (10399/18569)
Updating files:  57% (10585/18569)
Updating files:  58% (10771/18569)
Updating files:  59% (10956/18569)
Updating files:  60% (11142/18569)
Updating files:  61% (11328/18569)
Updating files:  62% (11513/18569)
Updating files:  63% (11699/18569)
Updating files:  64% (11885/18569)
Updating files:  65% (12070/18569)
Updating files:  66% (12256/18569)
Updating files:  67% (12442/18569)
Updating files:  68% (12627/18569)
Updating files:  69% (12813/18569)
Updating files:  70% (12999/18569)
Updating files:  71% (13184/18569)
Updating files:  72% (13370/18569)
Updating files:  73% (13556/18569)
Updating files:  74% (13742/18569)
Updating files:  75% (13927/18569)
Updating files:  76% (14113/18569)
Updating files:  77% (14299/18569)
Updating files:  78% (14484/18569)
Updating files:  79% (14670/18569)
Updating files:  80% (14856/18569)
Updating files:  81% (15041/18569)
Updating files:  82% (15227/18569)
Updating files:  83% (15413/18569)
Updating files:  84% (15598/18569)
Updating files:  85% (15784/18569)
Updating files:  86% (15970/18569)
Updating files:  87% (16156/18569)
Updating files:  88% (16341/18569)
Updating files:  89% (16527/18569)
Updating files:  90% (16713/18569)
Updating files:  91% (16898/18569)
Updating files:  92% (17084/18569)
Updating files:  93% (17270/18569)
Updating files:  94% (17455/18569)
Updating files:  95% (17641/18569)
Updating files:  96% (17827/18569)
Updating files:  97% (18012/18569)
Updating files:  98% (18198/18569)
Updating files:  99% (18384/18569)
Updating files:  99% (18437/18569)
Updating files: 100% (18569/18569)
Updating files: 100% (18569/18569), done.
HEAD is now at f2d8f8cc feat(storage): promote gRPC plugin to GA
BUILD
Starting Step #10
Starting Step #4
Starting Step #9
Starting Step #0 - "kaniko-attempt-1"
Starting Step #8
Step #10: Pulling image: gcr.io/google.com/cloudsdktool/cloud-sdk
Step #0 - "kaniko-attempt-1": Pulling image: gcr.io/kaniko-project/executor:v1.22.0
Step #9: Pulling image: gcr.io/google.com/cloudsdktool/cloud-sdk
Step #8: Pulling image: gcr.io/google.com/cloudsdktool/cloud-sdk
Step #4: Pulling image: gcr.io/google.com/cloudsdktool/cloud-sdk
Step #10: Using default tag: latest
Step #8: Using default tag: latest
Step #9: Using default tag: latest
Step #0 - "kaniko-attempt-1": v1.22.0: Pulling from kaniko-project/executor
Step #4: Using default tag: latest
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 #10: latest: Pulling from google.com/cloudsdktool/cloud-sdk
Step #10: ec335f17d0c7: Already exists
Step #10: ea6cde44f009: Pulling fs layer
Step #10: f67ef58fbdad: Pulling fs layer
Step #10: d63c0c279e01: Pulling fs layer
Step #10: 0ce406e5411d: Pulling fs layer
Step #10: 26d493d5ae17: Pulling fs layer
Step #10: 849ae382b032: Pulling fs layer
Step #10: 0ce406e5411d: Waiting
Step #10: 26d493d5ae17: Waiting
Step #10: 849ae382b032: Waiting
Step #9: latest: Pulling from google.com/cloudsdktool/cloud-sdk
Step #9: ec335f17d0c7: Already exists
Step #9: ea6cde44f009: Pulling fs layer
Step #9: f67ef58fbdad: Pulling fs layer
Step #9: d63c0c279e01: Pulling fs layer
Step #9: 0ce406e5411d: Pulling fs layer
Step #9: 26d493d5ae17: Pulling fs layer
Step #9: 849ae382b032: Pulling fs layer
Step #9: 849ae382b032: Waiting
Step #9: 0ce406e5411d: Waiting
Step #9: 26d493d5ae17: Waiting
Step #4: latest: Pulling from google.com/cloudsdktool/cloud-sdk
Step #4: ec335f17d0c7: Already exists
Step #4: ea6cde44f009: Pulling fs layer
Step #4: f67ef58fbdad: Pulling fs layer
Step #4: d63c0c279e01: Pulling fs layer
Step #4: 0ce406e5411d: Pulling fs layer
Step #4: 26d493d5ae17: Pulling fs layer
Step #4: 849ae382b032: Pulling fs layer
Step #4: 849ae382b032: Waiting
Step #4: 0ce406e5411d: Waiting
Step #4: 26d493d5ae17: Waiting
Step #9: d63c0c279e01: Verifying Checksum
Step #9: d63c0c279e01: Download complete
Step #4: d63c0c279e01: Verifying Checksum
Step #4: d63c0c279e01: Download complete
Step #10: d63c0c279e01: Verifying Checksum
Step #10: d63c0c279e01: Download complete
Step #9: ea6cde44f009: Verifying Checksum
Step #9: ea6cde44f009: Download complete
Step #4: ea6cde44f009: Download complete
Step #10: ea6cde44f009: Verifying Checksum
Step #10: ea6cde44f009: Download complete
Step #4: f67ef58fbdad: Verifying Checksum
Step #4: f67ef58fbdad: Download complete
Step #10: f67ef58fbdad: Verifying Checksum
Step #10: f67ef58fbdad: Download complete
Step #9: f67ef58fbdad: Verifying Checksum
Step #9: f67ef58fbdad: Download complete
Step #4: 849ae382b032: Verifying Checksum
Step #10: 849ae382b032: Verifying Checksum
Step #10: 849ae382b032: Download complete
Step #9: 849ae382b032: Verifying Checksum
Step #4: 849ae382b032: Download complete
Step #9: 849ae382b032: Download complete
Step #9: ea6cde44f009: Pull complete
Step #10: ea6cde44f009: Pull complete
Step #4: ea6cde44f009: Pull complete
Step #4: 26d493d5ae17: Download complete
Step #9: 26d493d5ae17: Download complete
Step #10: 26d493d5ae17: Download complete
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:25Z" level=info msg="Using dockerignore file: /workspace/ci/.dockerignore"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:25Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:25Z" level=info msg="Retrieving image ubuntu:focal from registry index.docker.io"
Step #8: latest: Pulling from google.com/cloudsdktool/cloud-sdk
Step #8: ec335f17d0c7: Already exists
Step #8: ea6cde44f009: Already exists
Step #8: f67ef58fbdad: Pulling fs layer
Step #8: d63c0c279e01: Pulling fs layer
Step #8: 0ce406e5411d: Pulling fs layer
Step #8: 26d493d5ae17: Pulling fs layer
Step #8: 849ae382b032: Pulling fs layer
Step #8: 849ae382b032: Download complete
Step #8: d63c0c279e01: Download complete
Step #8: 26d493d5ae17: Download complete
Step #9: f67ef58fbdad: Pull complete
Step #8: f67ef58fbdad: Pull complete
Step #4: f67ef58fbdad: Pull complete
Step #10: f67ef58fbdad: Pull complete
Step #10: d63c0c279e01: Pull complete
Step #9: d63c0c279e01: Pull complete
Step #4: d63c0c279e01: Pull complete
Step #8: d63c0c279e01: Pull complete
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:25Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:25Z" level=info msg="Returning cached image manifest"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:25Z" level=info msg="Built cross stage deps: map[]"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:25Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:25Z" level=info msg="Returning cached image manifest"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:25Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:25Z" level=info msg="Returning cached image manifest"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:25Z" level=info msg="Executing 0 build triggers"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:25Z" level=info msg="Building stage 'ubuntu:focal' [idx: '0', base-idx: '-1']"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:25Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:33bbce6f6492a96cb0b9979b13590cd14d72ffbd228103839e165e5587cb0f50..."
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:26Z" 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-03-30T14:22:26Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:260a0c339b138fcb8b99ba1ff21e6c637518931024834fce7f6dd268539e5d34..."
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:26Z" 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-03-30T14:22:26Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:930f8aaa05455440b0b7e40fe68b79ba9dd6d83a0042c0f0b877ecae5dcb288a..."
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:27Z" level=info msg="Using caching version of cmd: RUN pip3 install setuptools wheel"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:27Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:2cbd8e5020d0204831a45fb986213e3bdc99339e6f79a16466ad8e547d233e08..."
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:28Z" 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-03-30T14:22:28Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:5bfa66435efbdb17b7706507e08b848fc4ea30098ec0416b97a9e9a8d9e244c8..."
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:28Z" level=info msg="Using caching version of cmd: RUN /var/tmp/ci/install-cloud-sdk.sh"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:28Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:4c9c1fa39ff9870be2722e53b87a1db1970f86d8f49f9d3b05bec20b7bf5e83c..."
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:29Z" 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-03-30T14:22:29Z" level=info msg="Unpacking rootfs as cmd COPY . /var/tmp/ci requires it."
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:30Z" level=info msg="ARG NCPU=4"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:30Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:30Z" level=info msg="ARG ARCH=amd64"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:30Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:30Z" level=info msg="ENV DEBIAN_FRONTEND=noninteractive"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:30Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:22:30Z" 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-03-30T14:22:30Z" level=info msg="Found cached layer, extracting to filesystem"
Step #8: 0ce406e5411d: Verifying Checksum
Step #10: 0ce406e5411d: Verifying Checksum
Step #10: 0ce406e5411d: Download complete
Step #8: 0ce406e5411d: Download complete
Step #4: 0ce406e5411d: Download complete
Step #9: 0ce406e5411d: Verifying Checksum
Step #9: 0ce406e5411d: Download complete
Step #8: 0ce406e5411d: Pull complete
Step #10: 0ce406e5411d: Pull complete
Step #4: 0ce406e5411d: Pull complete
Step #9: 0ce406e5411d: Pull complete
Step #8: 26d493d5ae17: Pull complete
Step #4: 26d493d5ae17: Pull complete
Step #10: 26d493d5ae17: Pull complete
Step #9: 26d493d5ae17: Pull complete
Step #8: 849ae382b032: Pull complete
Step #10: 849ae382b032: Pull complete
Step #9: 849ae382b032: Pull complete
Step #4: 849ae382b032: Pull complete
Step #10: Digest: sha256:5bd4e7c2fe3dfcf09a5f927202b05da57b885b5b682279e3d6b93725caf1bc04
Step #8: Digest: sha256:5bd4e7c2fe3dfcf09a5f927202b05da57b885b5b682279e3d6b93725caf1bc04
Step #10: Status: Downloaded newer image for gcr.io/google.com/cloudsdktool/cloud-sdk:latest
Step #8: Status: Downloaded newer image for gcr.io/google.com/cloudsdktool/cloud-sdk:latest
Step #4: Digest: sha256:5bd4e7c2fe3dfcf09a5f927202b05da57b885b5b682279e3d6b93725caf1bc04
Step #4: Status: Image is up to date for gcr.io/google.com/cloudsdktool/cloud-sdk:latest
Step #10: gcr.io/google.com/cloudsdktool/cloud-sdk:latest
Step #8: gcr.io/google.com/cloudsdktool/cloud-sdk:latest
Step #4: gcr.io/google.com/cloudsdktool/cloud-sdk:latest
Step #9: Digest: sha256:5bd4e7c2fe3dfcf09a5f927202b05da57b885b5b682279e3d6b93725caf1bc04
Step #9: Status: Image is up to date for gcr.io/google.com/cloudsdktool/cloud-sdk:latest
Step #9: gcr.io/google.com/cloudsdktool/cloud-sdk:latest
Step #9: + test -z 13858
Step #9: ++ gcloud builds describe --region us-east1 --format 'value(create_time)' 126fe211-e8d6-466f-a5a8-bae7b0779b4a
Step #10: + test -z 13858
Step #10: ++ gcloud builds describe --region us-east1 --format 'value(create_time)' 126fe211-e8d6-466f-a5a8-bae7b0779b4a
Step #4: 2024-03-30T14:23:08Z (+0s): Running:
Step #4: env -C '/h' /workspace/ci/cloudbuild/cache.sh restore --bucket_url=gs://cloud-cpp-testing-resources_cloudbuild/build-cache/google-cloud-cpp --key=13858/ubuntu-focal/cmake-oldest-deps/__default__/h --fallback_key=main/ubuntu-focal/cmake-oldest-deps/__default__/h
Step #4: 2024-03-30T14:23:08Z (+0s): ====> cache.sh: restore
Step #10: + ctime=2024-03-30T14:21:27.744008Z
Step #10: + query=tags=pr
Step #10: + query+=' AND tags=13858'
Step #10: + query+=' AND substitutions.COMMIT_SHA != f2d8f8cce1c63fb01c16aeb4072aa5d04f75dff4'
Step #10: + query+=' AND create_time < 2024-03-30T14:21:27.744008Z'
Step #10: + gcloud builds list --region us-east1 --ongoing '--format=value(id)' --filter 'tags=pr AND tags=13858 AND substitutions.COMMIT_SHA != f2d8f8cce1c63fb01c16aeb4072aa5d04f75dff4 AND create_time < 2024-03-30T14:21:27.744008Z'
Step #10: + xargs -r -t gcloud builds cancel --region us-east1
Step #9: + ctime=2024-03-30T14:21:27.744008Z
Step #9: + query=tags=pr
Step #9: + query+=' AND tags=13858'
Step #9: + query+=' AND tags=cmake-oldest-deps'
Step #9: + query+=' AND substitutions.COMMIT_SHA != f2d8f8cce1c63fb01c16aeb4072aa5d04f75dff4'
Step #9: + query+=' AND create_time > 2024-03-30T14:21:27.744008Z'
Step #9: + gcloud builds list --region us-east1 --limit 1 '--format=value(id)' --filter 'tags=pr AND tags=13858 AND tags=cmake-oldest-deps AND substitutions.COMMIT_SHA != f2d8f8cce1c63fb01c16aeb4072aa5d04f75dff4 AND create_time > 2024-03-30T14:21:27.744008Z'
Step #9: + grep .
Step #4: gs://cloud-cpp-testing-resources_cloudbuild/build-cache/google-cloud-cpp/13858/ubuntu-focal/cmake-oldest-deps/__default__/h/cache.tar.gz:
Step #4:     Creation time:          Fri, 29 Mar 2024 21:49:37 GMT
Step #4:     Update time:            Fri, 29 Mar 2024 21:49:37 GMT
Step #4:     Storage class:          STANDARD
Step #4:     Content-Length:         8965682
Step #4:     Content-Type:           application/x-tar
Step #4:     Hash (crc32c):          tty78A==
Step #4:     Hash (md5):             RpXIabLlwNrUL+gqRUBYvA==
Step #4:     ETag:                   CPqw++25moUDEAE=
Step #4:     Generation:             1711748977449082
Step #4:     Metageneration:         1
Step #4: 2024-03-30T14:23:10Z (+2s): Fetching cache url gs://cloud-cpp-testing-resources_cloudbuild/build-cache/google-cloud-cpp/13858/ubuntu-focal/cmake-oldest-deps/__default__/h/cache.tar.gz
Finished Step #8
Step #10: WARNING: The following filter keys were not present in any resource : create_time, substitutions.COMMIT_SHA, tags
Step #9: WARNING: The following filter keys were not present in any resource : create_time, substitutions.COMMIT_SHA, tags
Finished Step #10
Finished Step #9
ERROR: step exited with non-zero status: 1
Step #4: Copying gs://cloud-cpp-testing-resources_cloudbuild/build-cache/google-cloud-cpp/13858/ubuntu-focal/cmake-oldest-deps/__default__/h/cache.tar.gz to file:///tmp/tmp.njKZthc6eC/cache.tar.gz
Step #4:   
Step #4: ..
Step #4: 
Step #4: Average throughput: 30.5MiB/s
Step #4: ==> 🕑 cache.sh completed in 3.868 seconds
Finished Step #4
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:23:27Z" level=info msg="RUN update-alternatives --install /usr/bin/python python $(which python3) 10"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:23:27Z" level=info msg="Found cached layer, extracting to filesystem"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:23:28Z" level=info msg="RUN pip3 install setuptools wheel"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:23:28Z" level=info msg="Found cached layer, extracting to filesystem"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:23:28Z" 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-03-30T14:23:28Z" level=info msg="Found cached layer, extracting to filesystem"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:23:52Z" level=info msg="COPY . /var/tmp/ci"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:23:52Z" level=info msg="Taking snapshot of files..."
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:23:52Z" level=info msg="WORKDIR /var/tmp/downloads"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:23:52Z" level=info msg="Cmd: workdir"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:23:52Z" level=info msg="Changed working directory to /var/tmp/downloads"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:23:52Z" level=info msg="Creating directory /var/tmp/downloads with uid -1 and gid -1"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:23:52Z" level=info msg="Taking snapshot of files..."
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:23:52Z" level=info msg="RUN /var/tmp/ci/install-cloud-sdk.sh"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:23:52Z" level=info msg="Found cached layer, extracting to filesystem"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:25:53Z" level=info msg="ENV CLOUD_SDK_LOCATION=/usr/local/google-cloud-sdk"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:25:53Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:25:53Z" level=info msg="ENV PATH=${CLOUD_SDK_LOCATION}/bin:${PATH}"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:25:53Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:25:53Z" 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-03-30T14:25:53Z" level=info msg="Found cached layer, extracting to filesystem"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:25:54Z" level=info msg="Pushing image to gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal:126fe211-e8d6-466f-a5a8-bae7b0779b4a"
Step #0 - "kaniko-attempt-1": time="2024-03-30T14:25:55Z" level=info msg="Pushed gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal@sha256:0a5189a09656022ef39dba87b81682ed5a813d97453e9e476c5a5f3ce3918d3d"
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:126fe211-e8d6-466f-a5a8-bae7b0779b4a
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-03-30T14:25:59Z" level=info msg="Using dockerignore file: /workspace/ci/.dockerignore"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:25:59Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:25:59Z" level=info msg="Retrieving image ubuntu:focal from registry index.docker.io"
Step #1 - "download-runner-image-attempt-1": 126fe211-e8d6-466f-a5a8-bae7b0779b4a: Pulling from cloud-cpp-testing-resources/cloudbuild/ubuntu-focal
Step #1 - "download-runner-image-attempt-1": 17d0386c2fff: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": 8431a87cb261: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": f93ce5be0292: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": aa406ccbfce8: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": 9490e3104279: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": 9545481357de: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": 5d83bacf51ab: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": bf799bfb3f45: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": c7b6dde690f8: Pulling fs layer
Step #1 - "download-runner-image-attempt-1": aa406ccbfce8: Waiting
Step #1 - "download-runner-image-attempt-1": 9490e3104279: Waiting
Step #1 - "download-runner-image-attempt-1": 9545481357de: Waiting
Step #1 - "download-runner-image-attempt-1": 5d83bacf51ab: Waiting
Step #1 - "download-runner-image-attempt-1": c7b6dde690f8: Waiting
Step #1 - "download-runner-image-attempt-1": bf799bfb3f45: Waiting
Step #1 - "download-runner-image-attempt-1": f93ce5be0292: Download complete
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:25:59Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:25:59Z" level=info msg="Returning cached image manifest"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:25:59Z" level=info msg="Built cross stage deps: map[]"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:25:59Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:25:59Z" level=info msg="Returning cached image manifest"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:25:59Z" level=info msg="Retrieving image manifest ubuntu:focal"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:25:59Z" level=info msg="Returning cached image manifest"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:25:59Z" level=info msg="Executing 0 build triggers"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:25:59Z" level=info msg="Building stage 'ubuntu:focal' [idx: '0', base-idx: '-1']"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:25:59Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:33bbce6f6492a96cb0b9979b13590cd14d72ffbd228103839e165e5587cb0f50..."
Step #1 - "download-runner-image-attempt-1": aa406ccbfce8: Verifying Checksum
Step #1 - "download-runner-image-attempt-1": aa406ccbfce8: Download complete
Step #1 - "download-runner-image-attempt-1": 17d0386c2fff: Verifying Checksum
Step #1 - "download-runner-image-attempt-1": 17d0386c2fff: Download complete
Step #1 - "download-runner-image-attempt-1": 9545481357de: Verifying Checksum
Step #1 - "download-runner-image-attempt-1": 9545481357de: Download complete
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:00Z" 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-03-30T14:26:00Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:260a0c339b138fcb8b99ba1ff21e6c637518931024834fce7f6dd268539e5d34..."
Step #1 - "download-runner-image-attempt-1": 5d83bacf51ab: Verifying Checksum
Step #1 - "download-runner-image-attempt-1": 5d83bacf51ab: Download complete
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:00Z" 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-03-30T14:26:00Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:930f8aaa05455440b0b7e40fe68b79ba9dd6d83a0042c0f0b877ecae5dcb288a..."
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:00Z" level=info msg="Using caching version of cmd: RUN pip3 install setuptools wheel"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:00Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:2cbd8e5020d0204831a45fb986213e3bdc99339e6f79a16466ad8e547d233e08..."
Step #1 - "download-runner-image-attempt-1": 17d0386c2fff: Pull complete
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:00Z" 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-03-30T14:26:00Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:5bfa66435efbdb17b7706507e08b848fc4ea30098ec0416b97a9e9a8d9e244c8..."
Step #1 - "download-runner-image-attempt-1": 9490e3104279: Download complete
Step #1 - "download-runner-image-attempt-1": c7b6dde690f8: Verifying Checksum
Step #1 - "download-runner-image-attempt-1": c7b6dde690f8: Download complete
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:01Z" level=info msg="Using caching version of cmd: RUN /var/tmp/ci/install-cloud-sdk.sh"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:01Z" level=info msg="Checking for cached layer gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal/cache:4c9c1fa39ff9870be2722e53b87a1db1970f86d8f49f9d3b05bec20b7bf5e83c..."
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:01Z" 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-03-30T14:26:01Z" level=info msg="Unpacking rootfs as cmd COPY . /var/tmp/ci requires it."
Step #1 - "download-runner-image-attempt-1": 8431a87cb261: Download complete
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:02Z" level=info msg="ARG NCPU=4"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:02Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:02Z" level=info msg="ARG ARCH=amd64"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:02Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:02Z" level=info msg="ENV DEBIAN_FRONTEND=noninteractive"
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:02Z" level=info msg="No files changed in this command, skipping snapshotting."
Step #2 - "kaniko-attempt-2": time="2024-03-30T14:26:02Z" 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
...
[Logs truncated due to log size limitations. For full logs, see https://storage.cloud.google.com/cloud-cpp-community-publiclogs/logs/google-cloud-cpp/13858/f2d8f8cce1c63fb01c16aeb4072aa5d04f75dff4/ubuntu-focal-cmake-oldest-deps-__default__/log-126fe211-e8d6-466f-a5a8-bae7b0779b4a.txt.]
...
ssed    0.05 sec
Step #5: 528/558 Test #688: spanner_timestamp_test ....................................................   Passed    0.04 sec
Step #5: 529/558 Test #689: spanner_transaction_test ..................................................   Passed    0.04 sec
Step #5: 530/558 Test #690: spanner_update_instance_request_builder_test ..............................   Passed    0.03 sec
Step #5: 531/558 Test #576: bigtable_table_apply_test .................................................   Passed    0.57 sec
Step #5: 532/558 Test #312: storage_internal_async_insert_object_test .................................   Passed    1.10 sec
Step #5: 533/558 Test #691: spanner_value_test ........................................................   Passed    0.08 sec
Step #5: 534/558 Test #206: storage_client_bucket_test ................................................   Passed    1.39 sec
Step #5: 535/558 Test #667: spanner_internal_transaction_impl_test ....................................   Passed    0.34 sec
Step #5: 536/558 Test #633: pubsublite_internal_resumable_async_streaming_read_write_rpc_test .........   Passed    0.58 sec
Step #5: 537/558 Test #593: bigtable_bigtable_benchmark_test ..........................................   Passed    0.73 sec
Step #5: 538/558 Test #641: spanner_bytes_test ........................................................   Passed    0.71 sec
Step #5: 539/558 Test #643: spanner_client_test .......................................................   Passed    0.79 sec
Step #5: 540/558 Test #653: spanner_internal_connection_impl_test .....................................   Passed    0.79 sec
Step #5: 541/558 Test  #73: common_internal_async_connection_ready_test ...............................   Passed    2.02 sec
Step #5: 542/558 Test #313: storage_internal_async_partial_upload_test ................................   Passed    1.93 sec
Step #5: 543/558 Test #692: spanner_bytes_benchmark ...................................................   Passed    1.51 sec
Step #5: 544/558 Test #626: pubsublite_internal_alarm_registry_impl_test ..............................   Passed    2.01 sec
Step #5: 545/558 Test #466: pubsub_internal_subscription_message_queue_test ...........................   Passed    2.39 sec
Step #5: 546/558 Test #630: pubsublite_internal_multipartition_publisher_test .........................   Passed    2.02 sec
Step #5: 547/558 Test #216: storage_client_test .......................................................   Passed    2.86 sec
Step #5: 548/558 Test #300: storage_internal_crc32c_benchmark .........................................   Passed    3.35 sec
Step #5: 549/558 Test #695: spanner_row_benchmark .....................................................   Passed    2.42 sec
Step #5: 550/558 Test #345: storage_internal_grpc_split_write_object_data_test ........................   Passed    3.84 sec
Step #5: 551/558 Test #334: storage_internal_grpc_make_cord_test ......................................   Passed    3.92 sec
Step #5: 552/558 Test #693: spanner_internal_merge_chunk_benchmark ....................................   Passed    3.68 sec
Step #5: 553/558 Test  #67: common_options_benchmark ..................................................   Passed    6.16 sec
Step #5: 554/558 Test #142: common_internal_oauth2_compute_engine_credentials_test ....................   Passed    6.18 sec
Step #5: 555/558 Test  #69: common_completion_queue_test ..............................................   Passed    7.39 sec
Step #5: 556/558 Test #118: common_completion_queue_benchmark .........................................   Passed   10.75 sec
Step #5: 557/558 Test #694: spanner_numeric_benchmark .................................................   Passed    9.38 sec
Step #5: 558/558 Test #168: common_internal_curl_handle_factory_benchmark .............................   Passed   17.93 sec
Step #5: 
Step #5: 100% tests passed, 0 tests failed out of 558
Step #5: 
Step #5: Total Test time (real) =  18.28 sec
Step #5: 
Step #5: 2024-03-30T14:40:00Z (+640s)
Step #5: ---------------------------------------------------------
Step #5: |   Running Pub/Sub integration tests (with emulator)   |
Step #5: ---------------------------------------------------------
Step #5: 2024-03-30T14:40:00Z (+640s): Launching Cloud Pub/Sub emulator in the background
Step #5: 2024-03-30T14:40:02Z (+642s): Cloud Pub/Sub emulator started at localhost:8085
Step #5: Test project /workspace/cmake-out/build
Step #5:     Start 494: pubsub_endurance
Step #5:     Start 495: pubsub_throughput
Step #5:     Start 498: pubsub_blocking_publisher_integration_test
Step #5:     Start 499: pubsub_publisher_integration_test
Step #5:     Start 500: pubsub_subscriber_integration_test
Step #5:     Start 501: pubsub_subscription_admin_integration_test
Step #5:     Start 502: pubsub_topic_admin_integration_test
Step #5: 1/7 Test #499: pubsub_publisher_integration_test ............   Passed    0.69 sec
Step #5: 2/7 Test #502: pubsub_topic_admin_integration_test ..........   Passed    0.73 sec
Step #5: 3/7 Test #501: pubsub_subscription_admin_integration_test ...   Passed    0.81 sec
Step #5: 4/7 Test #498: pubsub_blocking_publisher_integration_test ...   Passed    1.16 sec
Step #5: 5/7 Test #495: pubsub_throughput ............................   Passed    1.48 sec
Step #5: 6/7 Test #500: pubsub_subscriber_integration_test ...........   Passed    8.20 sec
Step #5: 7/7 Test #494: pubsub_endurance .............................   Passed   10.37 sec
Step #5: 
Step #5: 100% tests passed, 0 tests failed out of 7
Step #5: 
Step #5: Label Time Summary:
Step #5: integration-test             =  23.44 sec*proc (7 tests)
Step #5: integration-test-emulator    =  23.44 sec*proc (7 tests)
Step #5: 
Step #5: Total Test time (real) =  10.39 sec
Step #5: Killing Pub/Sub Emulator [25484] +. done.
Step #5: 
Step #5: 2024-03-30T14:40:12Z (+652s)
Step #5: ---------------------------------------------------------
Step #5: |   Running Storage integration tests (with emulator)   |
Step #5: ---------------------------------------------------------
Step #5: 2024-03-30T14:40:12Z (+652s): Launching Cloud Storage emulator on port 0
Step #5: Successfully connected to emulator [28130]
Step #5: 2024-03-30T14:40:13Z (+653s): Successfully connected to gRPC server at port 43099
Step #5: Test project /workspace/cmake-out/build
Step #5:       Start 354: storage_async_client_integration_test
Step #5:       Start 355: storage_auto_finalize_integration_test
Step #5:       Start 356: storage_bucket_integration_test
Step #5:       Start 357: storage_create_client_integration_test
Step #5:       Start 358: storage_curl_sign_blob_integration_test
Step #5:       Start 359: storage_decompressive_transcoding_integration_test
Step #5:       Start 360: storage_error_injection_integration_test
Step #5:       Start 361: storage_error_parsing_integration_test
Step #5:       Start 362: storage_grpc_bucket_acl_integration_test
Step #5:       Start 363: storage_grpc_bucket_metadata_integration_test
Step #5:       Start 364: storage_grpc_default_object_acl_integration_test
Step #5:       Start 365: storage_grpc_hmac_key_integration_test
Step #5:       Start 366: storage_grpc_integration_test
Step #5:       Start 367: storage_grpc_notification_integration_test
Step #5:       Start 368: storage_grpc_object_acl_integration_test
Step #5:       Start 369: storage_grpc_object_media_integration_test
Step #5:       Start 370: storage_grpc_object_metadata_integration_test
Step #5:       Start 371: storage_grpc_service_account_integration_test
Step #5:       Start 373: storage_object_basic_crud_integration_test
Step #5:       Start 374: storage_object_checksum_integration_test
Step #5:       Start 375: storage_object_compose_many_integration_test
Step #5:       Start 376: storage_object_file_integration_test
Step #5:       Start 377: storage_object_file_multi_threaded_test
Step #5:       Start 378: storage_object_hash_integration_test
Step #5:       Start 379: storage_object_insert_integration_test
Step #5:       Start 380: storage_object_insert_preconditions_integration_test
Step #5:       Start 381: storage_object_integration_test
Step #5:       Start 382: storage_object_list_objects_versions_integration_test
Step #5:       Start 383: storage_object_media_integration_test
Step #5:       Start 384: storage_object_parallel_upload_integration_test
Step #5:       Start 385: storage_object_plenty_clients_serially_integration_test
Step #5:       Start 386: storage_object_plenty_clients_simultaneously_integration_test
Step #5:  1/62 Test #358: storage_curl_sign_blob_integration_test .........................   Passed    0.05 sec
Step #5:       Start 387: storage_object_read_headers_integration_test
Step #5:  2/62 Test #371: storage_grpc_service_account_integration_test ...................   Passed    0.26 sec
Step #5:       Start 388: storage_object_read_large_integration_test
Step #5:  3/62 Test #369: storage_grpc_object_media_integration_test ......................   Passed    0.37 sec
Step #5:       Start 389: storage_object_read_preconditions_integration_test
Step #5:  4/62 Test #361: storage_error_parsing_integration_test ..........................   Passed    0.64 sec
Step #5:       Start 390: storage_object_read_range_integration_test
Step #5:  5/62 Test #365: storage_grpc_hmac_key_integration_test ..........................   Passed    0.66 sec
Step #5:       Start 391: storage_object_read_stream_integration_test
Step #5:  6/62 Test #367: storage_grpc_notification_integration_test ......................   Passed    0.80 sec
Step #5:       Start 392: storage_object_resumable_parallel_upload_integration_test
Step #5:  7/62 Test #391: storage_object_read_stream_integration_test .....................   Passed    0.86 sec
Step #5:       Start 393: storage_object_resumable_write_integration_test
Step #5:  8/62 Test #377: storage_object_file_multi_threaded_test .........................   Passed    1.70 sec
Step #5:       Start 394: storage_object_rewrite_integration_test
Step #5:  9/62 Test #363: storage_grpc_bucket_metadata_integration_test ...................   Passed    1.77 sec
Step #5:       Start 395: storage_object_write_preconditions_integration_test
Step #5: 10/62 Test #362: storage_grpc_bucket_acl_integration_test ........................   Passed    1.78 sec
Step #5:       Start 396: storage_object_write_stream_integration_test
Step #5: 11/62 Test #368: storage_grpc_object_acl_integration_test ........................   Passed    1.79 sec
Step #5:       Start 397: storage_object_write_streambuf_integration_test
Step #5: 12/62 Test #364: storage_grpc_default_object_acl_integration_test ................   Passed    1.80 sec
Step #5:       Start 398: storage_service_account_credentials_integration_test
Step #5: 13/62 Test #398: storage_service_account_credentials_integration_test ............   Passed    0.01 sec
Step #5:       Start 399: storage_service_account_integration_test
Step #5: 14/62 Test #357: storage_create_client_integration_test ..........................   Passed    1.92 sec
Step #5:       Start 400: storage_signed_url_conformance_test
Step #5: 15/62 Test #370: storage_grpc_object_metadata_integration_test ...................   Passed    1.92 sec
Step #5:       Start 402: storage_slow_reader_chunk_integration_test
Step #5: 16/62 Test #400: storage_signed_url_conformance_test .............................   Passed    0.12 sec
Step #5:       Start 403: storage_slow_reader_stream_integration_test
Step #5: 17/62 Test #375: storage_object_compose_many_integration_test ....................   Passed    2.51 sec
Step #5:       Start 404: storage_small_reads_integration_test
Step #5: 18/62 Test #388: storage_object_read_large_integration_test ......................   Passed    2.26 sec
Step #5:       Start 405: storage_smoke_test_async
Step #5: 19/62 Test #355: storage_auto_finalize_integration_test ..........................   Passed    2.58 sec
Step #5:       Start 406: storage_smoke_test_grpc
Step #5: 20/62 Test #382: storage_object_list_objects_versions_integration_test ...........   Passed    2.80 sec
Step #5:       Start 407: storage_smoke_test_rest
Step #5: 21/62 Test #407: storage_smoke_test_rest .........................................   Passed    0.66 sec
Step #5:       Start 408: storage_storage_include_test
Step #5: 22/62 Test #408: storage_storage_include_test ....................................   Passed    0.00 sec
Step #5:       Start 409: storage_thread_integration_test
Step #5: 23/62 Test #396: storage_object_write_stream_integration_test ....................   Passed    2.57 sec
Step #5:       Start 410: storage_tracing_integration_test
Step #5: 24/62 Test #373: storage_object_basic_crud_integration_test ......................   Passed    4.74 sec
Step #5:       Start 412: storage_aggregate_download_throughput_benchmark
Step #5: 25/62 Test #405: storage_smoke_test_async ........................................   Passed    3.62 sec
Step #5:       Start 413: storage_aggregate_upload_throughput_benchmark
Step #5: 26/62 Test #406: storage_smoke_test_grpc .........................................   Passed    3.62 sec
Step #5:       Start 414: storage_create_dataset
Step #5: 27/62 Test #397: storage_object_write_streambuf_integration_test .................   Passed    4.69 sec
Step #5:       Start 415: storage_storage_file_transfer_benchmark
Step #5: 28/62 Test #410: storage_tracing_integration_test ................................   Passed    2.14 sec
Step #5:       Start 416: storage_storage_parallel_uploads_benchmark
Step #5: 29/62 Test #392: storage_object_resumable_parallel_upload_integration_test .......   Passed    5.76 sec
Step #5:       Start 417: storage_storage_throughput_vs_cpu_benchmark
Step #5: 30/62 Test #412: storage_aggregate_download_throughput_benchmark .................   Passed    1.84 sec
Step #5:       Start 418: storage_throughput_experiment_test
Step #5: 31/62 Test #399: storage_service_account_integration_test ........................   Passed    4.82 sec
Step #5: 32/62 Test #404: storage_small_reads_integration_test ............................   Passed    4.40 sec
Step #5: 33/62 Test #415: storage_storage_file_transfer_benchmark .........................   Passed    2.29 sec
Step #5: 34/62 Test #417: storage_storage_throughput_vs_cpu_benchmark .....................   Passed    2.58 sec
Step #5: 35/62 Test #390: storage_object_read_range_integration_test ......................   Passed    8.55 sec
Step #5: 36/62 Test #380: storage_object_insert_preconditions_integration_test ............   Passed   10.53 sec
Step #5: 37/62 Test #389: storage_object_read_preconditions_integration_test ..............   Passed   10.58 sec
Step #5: 38/62 Test #413: storage_aggregate_upload_throughput_benchmark ...................   Passed    5.54 sec
Step #5: 39/62 Test #366: storage_grpc_integration_test ...................................   Passed   11.88 sec
Step #5: 40/62 Test #395: storage_object_write_preconditions_integration_test .............   Passed   10.28 sec
Step #5: 41/62 Test #360: storage_error_injection_integration_test ........................   Passed   12.35 sec
Step #5: 42/62 Test #416: storage_storage_parallel_uploads_benchmark ......................   Passed    6.27 sec
Step #5: 43/62 Test #378: storage_object_hash_integration_test ............................   Passed   12.91 sec
Step #5: 44/62 Test #374: storage_object_checksum_integration_test ........................   Passed   12.94 sec
Step #5: 45/62 Test #376: storage_object_file_integration_test ............................   Passed   14.84 sec
Step #5: 46/62 Test #387: storage_object_read_headers_integration_test ....................   Passed   14.93 sec
Step #5: 47/62 Test #394: storage_object_rewrite_integration_test .........................   Passed   15.15 sec
Step #5: 48/62 Test #354: storage_async_client_integration_test ...........................   Passed   17.16 sec
Step #5: 49/62 Test #356: storage_bucket_integration_test .................................   Passed   17.79 sec
Step #5: 50/62 Test #379: storage_object_insert_integration_test ..........................   Passed   17.91 sec
Step #5: 51/62 Test #393: storage_object_resumable_write_integration_test .................   Passed   16.49 sec
Step #5: 52/62 Test #381: storage_object_integration_test .................................   Passed   18.09 sec
Step #5: 53/62 Test #385: storage_object_plenty_clients_serially_integration_test .........   Passed   18.37 sec
Step #5: 54/62 Test #386: storage_object_plenty_clients_simultaneously_integration_test ...   Passed   18.53 sec
Step #5: 55/62 Test #384: storage_object_parallel_upload_integration_test .................   Passed   20.85 sec
Step #5: 56/62 Test #418: storage_throughput_experiment_test ..............................   Passed   15.57 sec
Step #5: 57/62 Test #409: storage_thread_integration_test .................................   Passed   26.32 sec
Step #5: 58/62 Test #403: storage_slow_reader_stream_integration_test .....................   Passed   37.56 sec
Step #5: 59/62 Test #402: storage_slow_reader_chunk_integration_test ......................   Passed   37.68 sec
Step #5: 60/62 Test #414: storage_create_dataset ..........................................   Passed   37.56 sec
Step #5: 61/62 Test #359: storage_decompressive_transcoding_integration_test ..............   Passed   46.35 sec
Step #5: 62/62 Test #383: storage_object_media_integration_test ...........................   Passed   66.93 sec
Step #5: 
Step #5: 100% tests passed, 0 tests failed out of 62
Step #5: 
Step #5: Label Time Summary:
Step #5: integration-test             = 630.22 sec*proc (62 tests)
Step #5: integration-test-emulator    = 630.22 sec*proc (62 tests)
Step #5: integration-test-gha         =   7.89 sec*proc (3 tests)
Step #5: 
Step #5: Total Test time (real) =  67.00 sec
Step #5: Killing emulator server [28130] ... done.
Step #5: 
Step #5: 2024-03-30T14:41:22Z (+722s)
Step #5: ---------------------------------------------------------
Step #5: |   Running Spanner integration tests (with emulator)   |
Step #5: ---------------------------------------------------------
Step #5: 2024-03-30T14:41:22Z (+722s): Launching Cloud Spanner emulator in the background
Step #5: 2024-03-30T14:41:23Z (+723s): Spanner emulator started at localhost:8787
Step #5: 2024-03-30T14:41:23Z (+723s): Launching Cloud Spanner HTTP emulator in the background
Step #5: 2024-03-30T14:41:25Z (+725s): Spanner HTTP emulator started at http://localhost:8788
Step #5: Test project /workspace/cmake-out/build
Step #5:       Start 696: spanner_backup_extra_integration_test
Step #5:       Start 697: spanner_backup_integration_test
Step #5:       Start 698: spanner_client_integration_test
Step #5:       Start 699: spanner_client_stress_test
Step #5:       Start 700: spanner_data_types_integration_test
Step #5:       Start 701: spanner_database_admin_integration_test
Step #5:       Start 702: spanner_instance_admin_integration_test
Step #5:       Start 703: spanner_session_pool_integration_test
Step #5:       Start 704: spanner_admin_backup_extra_integration_test
Step #5:       Start 705: spanner_admin_backup_integration_test
Step #5:       Start 706: spanner_admin_database_admin_integration_test
Step #5:       Start 707: spanner_admin_instance_admin_integration_test
Step #5:       Start 710: spanner_benchmarks_config_test
Step #5:       Start 711: spanner_multiple_rows_cpu_benchmark
Step #5:       Start 712: spanner_single_row_throughput_benchmark
Step #5:  1/15 Test #697: spanner_backup_integration_test .................   Passed    0.02 sec
Step #5:  2/15 Test #705: spanner_admin_backup_integration_test ...........   Passed    0.01 sec
Step #5:  3/15 Test #710: spanner_benchmarks_config_test ..................   Passed    0.01 sec
Step #5:  4/15 Test #696: spanner_backup_extra_integration_test ...........   Passed    0.05 sec
Step #5:  5/15 Test #704: spanner_admin_backup_extra_integration_test .....   Passed    0.04 sec
Step #5:  6/15 Test #702: spanner_instance_admin_integration_test .........   Passed    0.05 sec
Step #5:  7/15 Test #701: spanner_database_admin_integration_test .........   Passed    0.18 sec
Step #5:  8/15 Test #706: spanner_admin_database_admin_integration_test ...   Passed    0.22 sec
Step #5:  9/15 Test #707: spanner_admin_instance_admin_integration_test ...   Passed    0.29 sec
Step #5: 10/15 Test #700: spanner_data_types_integration_test .............   Passed    0.72 sec
Step #5: 11/15 Test #698: spanner_client_integration_test .................   Passed    1.02 sec
Step #5: 12/15 Test #712: spanner_single_row_throughput_benchmark .........   Passed    4.29 sec
Step #5: 13/15 Test #703: spanner_session_pool_integration_test ...........   Passed    5.05 sec
Step #5: 14/15 Test #699: spanner_client_stress_test ......................   Passed   16.95 sec
Step #5: 15/15 Test #711: spanner_multiple_rows_cpu_benchmark .............   Passed   28.37 sec
Step #5: 
Step #5: 100% tests passed, 0 tests failed out of 15
Step #5: 
Step #5: Label Time Summary:
Step #5: integration-test             =  57.27 sec*proc (15 tests)
Step #5: integration-test-emulator    =  57.27 sec*proc (15 tests)
Step #5: 
Step #5: Total Test time (real) =  28.41 sec
Step #5: Killing Spanner Emulator [29687] +. done.
Step #5: ================ emulator.log ================
Step #5:      1	WARNING: All log messages before absl::InitializeLog() is called are written to STDERR
Step #5:      2	I0000 00:00:1711809682.249544   29687 emulator_main.cc:39] Cloud Spanner Emulator running.
Step #5:      3	I0000 00:00:1711809682.249581   29687 emulator_main.cc:40] Server address: localhost:8787
Step #5:      4	I0000 00:00:1711809685.367972   29976 schema_updater.cc:725] INTERNAL: ZETASQL_RET_CHECK failure (backend/schema/updater/schema_updater.cc:725) false Unsupported ddl statement: 61
Step #5:      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 #5:      6	I0000 00:00:1711809685.607388   30238 spangres_translator.cc:183] EngineSystemCatalog initialized
Step #5:      7	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 #5:      8	I0000 00:00:1711809685.817235   31430 schema_updater.cc:725] INTERNAL: ZETASQL_RET_CHECK failure (backend/schema/updater/schema_updater.cc:725) false Unsupported ddl statement: 61
Step #5: ==============================================
Step #5: Killing Spanner HTTP Emulator [29728] +. done.
Step #5: ============== http_emulator.log =============
Step #5:      1	WARNING: proto:M-BM- file "google/rpc/status.proto" is already registered
Step #5:      2		previously from: "google.golang.org/genproto/googleapis/rpc/status"
Step #5:      3		currently from:  "unknown"
Step #5:      4	See https://protobuf.dev/reference/go/faq#namespace-conflict
Step #5:      5	
Step #5:      6	WARNING: proto:M-BM- file "google/rpc/status.proto" has a name conflict over google.rpc.Status
Step #5:      7		previously from: "google.golang.org/genproto/googleapis/rpc/status"
Step #5:      8		currently from:  "unknown"
Step #5:      9	See https://protobuf.dev/reference/go/faq#namespace-conflict
Step #5:     10	
Step #5:     11	WARNING: proto:M-BM- message google.rpc.Status is already registered
Step #5:     12		previously from: "google.golang.org/genproto/googleapis/rpc/status"
Step #5:     13		currently from:  "unknown"
Step #5:     14	See https://protobuf.dev/reference/go/faq#namespace-conflict
Step #5:     15	
Step #5:     16	WARNING: All log messages before absl::InitializeLog() is called are written to STDERR
Step #5:     17	I0000 00:00:1711809683.279587   29738 emulator_main.cc:39] Cloud Spanner Emulator running.
Step #5:     18	I0000 00:00:1711809683.279620   29738 emulator_main.cc:40] Server address: localhost:8789
Step #5:     19	2024/03/30 14:41:24 gateway.go:144: Cloud Spanner emulator running.
Step #5:     20	2024/03/30 14:41:24 gateway.go:145: REST server listening at localhost:8788
Step #5:     21	2024/03/30 14:41:24 gateway.go:146: gRPC server listening at localhost:8789
Step #5: ==============================================
Step #5: 
Step #5: 2024-03-30T14:41:53Z (+753s)
Step #5: ----------------------------------------------------------
Step #5: |   Running Bigtable integration tests (with emulator)   |
Step #5: ----------------------------------------------------------
Step #5: 2024-03-30T14:41:53Z (+753s): Launching Cloud Bigtable emulators in the background
Step #5: 2024-03-30T14:41:53Z (+753s): Connected to the Cloud Bigtable emulator on localhost:8480
Step #5: 2024-03-30T14:41:53Z (+753s): Connected to the Cloud Bigtable emulator on localhost:8490
Step #5: Test project /workspace/cmake-out/build
Step #5:       Start 598: bigtable_apply_read_latency_benchmark
Step #5:       Start 599: bigtable_endurance_benchmark
Step #5:       Start 600: bigtable_mutation_batcher_throughput_benchmark
Step #5:       Start 601: bigtable_read_sync_vs_async_benchmark
Step #5:       Start 602: bigtable_scan_throughput_benchmark
Step #5:       Start 605: bigtable_admin_integration_test
Step #5:       Start 606: bigtable_data_async_future_integration_test
Step #5:       Start 607: bigtable_data_integration_test
Step #5:       Start 608: bigtable_filters_integration_test
Step #5:       Start 609: bigtable_instance_admin_integration_test
Step #5:       Start 610: bigtable_mutations_integration_test
Step #5:       Start 611: bigtable_table_sample_rows_integration_test
Step #5:  1/12 Test #600: bigtable_mutation_batcher_throughput_benchmark ...   Passed    0.11 sec
Step #5:  2/12 Test #611: bigtable_table_sample_rows_integration_test ......   Passed    0.17 sec
Step #5:  3/12 Test #609: bigtable_instance_admin_integration_test .........   Passed    0.28 sec
Step #5:  4/12 Test #605: bigtable_admin_integration_test ..................   Passed    0.48 sec
Step #5:  5/12 Test #610: bigtable_mutations_integration_test ..............   Passed    0.64 sec
Step #5:  6/12 Test #606: bigtable_data_async_future_integration_test ......   Passed    0.71 sec
Step #5:  7/12 Test #599: bigtable_endurance_benchmark .....................   Passed    1.03 sec
Step #5:  8/12 Test #598: bigtable_apply_read_latency_benchmark ............   Passed    1.69 sec
Step #5:  9/12 Test #601: bigtable_read_sync_vs_async_benchmark ............   Passed    1.72 sec
Step #5: 10/12 Test #608: bigtable_filters_integration_test ................   Passed    3.04 sec
Step #5: 11/12 Test #602: bigtable_scan_throughput_benchmark ...............   Passed    4.77 sec
Step #5: 12/12 Test #607: bigtable_data_integration_test ...................   Passed    6.40 sec
Step #5: 
Step #5: 100% tests passed, 0 tests failed out of 12
Step #5: 
Step #5: Label Time Summary:
Step #5: integration-test             =  21.04 sec*proc (12 tests)
Step #5: integration-test-emulator    =  21.04 sec*proc (12 tests)
Step #5: 
Step #5: Total Test time (real) =   6.44 sec
Step #5: 2024-03-30T14:42:00Z (+760s): Killing Bigtable Emulators...
Step #5: EMULATOR_PID=43600 .+.
Step #5: INSTANCE_ADMIN_EMULATOR_PID=43601 .+.
Step #5: 
Step #5: 2024-03-30T14:42:00Z (+760s)
Step #5: ------------------------------------------------------
Step #5: |   Running REST integration tests (with emulator)   |
Step #5: ------------------------------------------------------
Step #5: 2024-03-30T14:42:00Z (+760s): Launching Cloud Storage emulator on port 0
Step #5: Successfully connected to emulator [44612]
Step #5: 2024-03-30T14:42:01Z (+761s): Successfully connected to gRPC server at port 34927
Step #5: Test project /workspace/cmake-out/build
Step #5:     Start 165: common_internal_curl_rest_client_integration_test
Step #5: 1/1 Test #165: common_internal_curl_rest_client_integration_test ...   Passed    0.62 sec
Step #5: 
Step #5: 100% tests passed, 0 tests failed out of 1
Step #5: 
Step #5: Label Time Summary:
Step #5: integration-test             =   0.62 sec*proc (1 test)
Step #5: integration-test-emulator    =   0.62 sec*proc (1 test)
Step #5: 
Step #5: Total Test time (real) =   0.64 sec
Step #5: Killing emulator server [44612] ... done.
Step #5: ==> 🕑 cmake-oldest-deps completed in 762.160 seconds
Finished Step #5
Starting Step #6
Step #6: Already have image (with digest): gcr.io/google.com/cloudsdktool/cloud-sdk
Step #6: 2024-03-30T14:42:03Z (+0s): Running:
Step #6: env -C '/h' /workspace/ci/cloudbuild/cache.sh save --bucket_url=gs://cloud-cpp-testing-resources_cloudbuild/build-cache/google-cloud-cpp --key=13858/ubuntu-focal/cmake-oldest-deps/__default__/h --path=.cache/bazelisk --path=.cache/ctcache --path=.cache/vcpkg --path=.cache/google-cloud-cpp --path=.local
Step #6: 2024-03-30T14:42:03Z (+0s): ====> cache.sh: save
Step #6: 2024-03-30T14:42:03Z (+0s): Saving ( .local ) to gs://cloud-cpp-testing-resources_cloudbuild/build-cache/google-cloud-cpp/13858/ubuntu-focal/cmake-oldest-deps/__default__/h/cache.tar.gz
Step #6: 28M	.local
Step #6: Copying file:///tmp/tmp.rR9F5ulOVI/cache.tar.gz to gs://cloud-cpp-testing-resources_cloudbuild/build-cache/google-cloud-cpp/13858/ubuntu-focal/cmake-oldest-deps/__default__/h/cache.tar.gz
Step #6:   
Step #6: ..
Step #6: gs://cloud-cpp-testing-resources_cloudbuild/build-cache/google-cloud-cpp/13858/ubuntu-focal/cmake-oldest-deps/__default__/h/cache.tar.gz:
Step #6:     Creation time:          Sat, 30 Mar 2024 14:42:07 GMT
Step #6:     Update time:            Sat, 30 Mar 2024 14:42:07 GMT
Step #6:     Storage class:          STANDARD
Step #6:     Content-Length:         8965534
Step #6:     Content-Type:           application/x-tar
Step #6:     Hash (crc32c):          b1wUCA==
Step #6:     Hash (md5):             o0UxQ0Fi0UK0hVzJwN/6Wg==
Step #6:     ETag:                   CKenzpWcnIUDEAE=
Step #6:     Generation:             1711809727009703
Step #6:     Metageneration:         1
Step #6: ==> 🕑 cache.sh completed in 5.311 seconds
Finished Step #6
Starting Step #7
Step #7: Already have image (with digest): gcr.io/google.com/cloudsdktool/cloud-sdk
Step #7: WARNING: Successfully resolved tag to sha256, but it is recommended to use sha256 directly.
Step #7: Digests:
Step #7: - gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal@sha256:675bed4406d6f335aae4098e2cd3b62b864baff52197afc64070bbb8e3fde3af
Step #7:   Associated tags:
Step #7:  - 126fe211-e8d6-466f-a5a8-bae7b0779b4a
Step #7: Tags:
Step #7: - gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal:126fe211-e8d6-466f-a5a8-bae7b0779b4a
Step #7: Deleted [gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal:126fe211-e8d6-466f-a5a8-bae7b0779b4a].
Step #7: Deleted [gcr.io/cloud-cpp-testing-resources/cloudbuild/ubuntu-focal@sha256:675bed4406d6f335aae4098e2cd3b62b864baff52197afc64070bbb8e3fde3af].
Finished Step #7
PUSH
DONE