Warning: Permanently added 'localhost' (ED25519) to the list of known hosts.
Warning: Permanently added 'localhost' (ED25519) to the list of known hosts.
Warning: Permanently added 'localhost' (ED25519) to the list of known hosts.
[10:04:24 PDT] Transferring environment variable script to build VM
[10:04:24 PDT] Transferring kokoro_log_reader.py to build VM
[10:04:25 PDT] Transferring source code to build VM
[10:04:26 PDT] Executing build script on build VM



[ID: 2942546] Executing command via SSH:
export KOKORO_BUILD_NUMBER="753"
export KOKORO_JOB_NAME="grpc/node/master/xds_k8s_lb"
source /tmpfs/kokoro-env_vars.sh; cd /tmpfs/src/ ; chmod 755 github/grpc-node/packages/grpc-js-xds/scripts/psm-interop-test-node.sh ; PYTHON_3_VERSION="$(pyenv which python3 2> /dev/null || which python3)" ; PYTHON_2_VERSION="$(pyenv which python2 2> /dev/null || which python2)" ; if "$PYTHON_3_VERSION" -c "import psutil" ; then KOKORO_PYTHON_COMMAND="$PYTHON_3_VERSION" ; else KOKORO_PYTHON_COMMAND="$PYTHON_2_VERSION" ; fi > /dev/null 2>&1 ; echo "export KOKORO_PYTHON_COMMAND="$KOKORO_PYTHON_COMMAND"" > "$HOME/.kokoro_python_vars" ; nohup bash -c "( rm -f /tmpfs/kokoro_build_exit_code ; github/grpc-node/packages/grpc-js-xds/scripts/psm-interop-test-node.sh ; echo \${PIPESTATUS[0]} > /tmpfs/kokoro_build_exit_code ) > /tmpfs/kokoro_build.log 2>&1" > /dev/null 2>&1 & echo $! > /tmpfs/kokoro_build.pid ; source "$HOME/.kokoro_python_vars" ; "$KOKORO_PYTHON_COMMAND" /tmpfs/kokoro_log_reader.py /tmpfs/kokoro_build.log /tmpfs/kokoro_build_exit_code /tmpfs/kokoro_build.pid /tmpfs/kokoro_log_reader.pid --start_byte 0

Sourcing test driver install script from: https://raw.githubusercontent.com/grpc/psm-interop/main/.kokoro/psm_interop_kokoro_lib.sh
Install script checksum:
b16c29176e20dc943d90e7e8f3589d40c2042ab3f55c2b60ed7212d596c5a700 /tmp/tmp.cpTnZ9pui2/psm_interop_kokoro_lib.sh
+ [17:04:26 UTC] Starting PSM Interop tests: lb
+ [17:04:26 UTC] Activated GKE cluster: GKE_CLUSTER_NAME=psm-interop-lb-primary GKE_CLUSTER_ZONE=us-central1-a
+ [17:04:26 UTC] Activated secondary GKE cluster: SECONDARY_GKE_CLUSTER_NAME=psm-interop-lb-secondary SECONDARY_GKE_CLUSTER_ZONE=us-west1-b
+ [17:04:26 UTC] Starting Kokoro provisioning
+ [17:04:26 UTC] Kokoro Ubuntu version:
No LSB modules are available.
Distributor ID: Ubuntu
Description: Ubuntu 22.04.4 LTS
Release: 22.04
Codename: jammy
Python 3.10.12
pip 22.0.2 from /usr/lib/python3/dist-packages/pip (python 3.10)
+ [17:04:27 UTC] Sponge properties:
TESTS_FORMAT_VERSION,2
TESTGRID_EXCLUDE,0
GIT_ORIGIN_URL,https://github.com/sergiitk/grpc-node.git
GIT_COMMIT_SHORT,9f9f6e72
+ [17:04:27 UTC] Installing packages with apt, see install-apt.log
+ [17:05:06 UTC] Fetching GKE cluster credentials
Fetching cluster endpoint and auth data.
kubeconfig entry generated for psm-interop-lb-secondary.
Fetching cluster endpoint and auth data.
kubeconfig entry generated for psm-interop-lb-primary.
+ [17:05:12 UTC] Cloning driver to https://github.com/grpc/psm-interop.git branch main to /tmp/tmp.D6gt45aunY/psm-interop
Cloning into '/tmp/tmp.D6gt45aunY/psm-interop'...
+ [17:05:12 UTC] Install python dependencies
+ [17:05:12 UTC] Creating python virtual environment: /tmp/tmp.D6gt45aunY/psm-interop/venv
+ [17:05:20 UTC] Installing Python packages with pip, see install-pip.log
+ [17:05:37 UTC] Generate python code from grpc.testing protos: protos/grpc/testing/test.proto protos/grpc/testing/messages.proto protos/grpc/testing/empty.proto
/tmp/tmp.D6gt45aunY/psm-interop/venv/lib/python3.10/site-packages/grpc_tools/protoc.py:21: DeprecationWarning: pkg_resources is deprecated as an API. See https://setuptools.pypa.io/en/latest/pkg_resources.html
import pkg_resources
+ [17:05:37 UTC] Generated files /tmp/tmp.D6gt45aunY/psm-interop/protos/grpc/testing:
20d9f7b4cbdc299d82e8dec6019bbfc546534b51056824229777cdc9976f16a2 /tmp/tmp.D6gt45aunY/psm-interop/protos/grpc/testing/empty.proto
bc10f5a4e285257916ed1327b8d17dac189ba5376b7dff97be18d5634b2e43c3 /tmp/tmp.D6gt45aunY/psm-interop/protos/grpc/testing/empty_pb2.py
d686e804f171693117b7d030ec4023f205c70c234c8590f6557aa8702f65fe09 /tmp/tmp.D6gt45aunY/psm-interop/protos/grpc/testing/empty_pb2_grpc.py
d686e804f171693117b7d030ec4023f205c70c234c8590f6557aa8702f65fe09 /tmp/tmp.D6gt45aunY/psm-interop/protos/grpc/testing/messages_pb2_grpc.py
aee633ecdbef187b7d4cc485b4d76264a62506639366d0e2532f863254726300 /tmp/tmp.D6gt45aunY/psm-interop/protos/grpc/testing/messages_pb2.py
fc0b620c26462272b1f1719fa46ca343d395cfdf6804d09e69fa0fb63d0e73ee /tmp/tmp.D6gt45aunY/psm-interop/protos/grpc/testing/test_pb2_grpc.py
07f7894ac17798456c040acf1f558b5c6f3e7a690a20d9cd6693b2867b2d877f /tmp/tmp.D6gt45aunY/psm-interop/protos/grpc/testing/messages.proto
81a8fd5745a37450f86af777c41636c8c9eba3244157eeb3c66fafc011d631ba /tmp/tmp.D6gt45aunY/psm-interop/protos/grpc/testing/test_pb2.py
49ac67a669d49b2626b9e96bf3bffc38f3b6cbbfef544426f631c7599f0ab51f /tmp/tmp.D6gt45aunY/psm-interop/protos/grpc/testing/test.proto
+ [17:05:39 UTC] Client image: us-docker.pkg.dev/grpc-testing/psm-interop/node-client:9f9f6e72ef080a731acd4660689df596d78a73ab
Client image not found
+ [17:05:39 UTC] Building xDS interop test app Docker images
Adding credentials for: us-docker.pkg.dev
Docker configuration file updated.
+ [17:05:40 UTC] git submodule update --init --recursive
Submodule 'packages/grpc-js-xds/deps/envoy-api' (https://github.com/envoyproxy/data-plane-api.git) registered for path 'packages/grpc-js-xds/deps/envoy-api'
Submodule 'packages/grpc-js-xds/deps/googleapis' (https://github.com/googleapis/googleapis.git) registered for path 'packages/grpc-js-xds/deps/googleapis'
Submodule 'packages/grpc-js-xds/deps/protoc-gen-validate' (https://github.com/envoyproxy/protoc-gen-validate.git) registered for path 'packages/grpc-js-xds/deps/protoc-gen-validate'
Submodule 'packages/grpc-js-xds/deps/xds' (https://github.com/cncf/xds.git) registered for path 'packages/grpc-js-xds/deps/xds'
Submodule 'packages/grpc-tools/deps/protobuf' (https://github.com/protocolbuffers/protobuf) registered for path 'packages/grpc-tools/deps/protobuf'
Submodule 'packages/proto-loader/deps/gapic-showcase' (https://github.com/googleapis/gapic-showcase.git) registered for path 'packages/proto-loader/deps/gapic-showcase'
Submodule 'packages/proto-loader/deps/googleapis' (https://github.com/googleapis/googleapis.git) registered for path 'packages/proto-loader/deps/googleapis'
Cloning into '/tmpfs/src/github/grpc-node/packages/grpc-js-xds/deps/envoy-api'...
Cloning into '/tmpfs/src/github/grpc-node/packages/grpc-js-xds/deps/googleapis'...
Cloning into '/tmpfs/src/github/grpc-node/packages/grpc-js-xds/deps/protoc-gen-validate'...
Cloning into '/tmpfs/src/github/grpc-node/packages/grpc-js-xds/deps/xds'...
Cloning into '/tmpfs/src/github/grpc-node/packages/grpc-tools/deps/protobuf'...
Cloning into '/tmpfs/src/github/grpc-node/packages/proto-loader/deps/gapic-showcase'...
Cloning into '/tmpfs/src/github/grpc-node/packages/proto-loader/deps/googleapis'...
Submodule path 'packages/grpc-js-xds/deps/envoy-api': checked out '78f198cf96ecdc7120ef640406770aa01af775c4'
Submodule path 'packages/grpc-js-xds/deps/googleapis': checked out '8c53b2cb792234354c13336ac7daee61333deade'
Submodule path 'packages/grpc-js-xds/deps/protoc-gen-validate': checked out '0af61d9dc28712dc0e6f8e1a940855a2ee0cb9ed'
Submodule path 'packages/grpc-js-xds/deps/xds': checked out 'cb28da3451f158a947dfc45090fe92b07b243bc1'
Submodule path 'packages/grpc-tools/deps/protobuf': checked out '7c40b2df1fdf6f414c1c18c789715a9c948a0725'
Submodule 'third_party/benchmark' (https://github.com/google/benchmark.git) registered for path 'packages/grpc-tools/deps/protobuf/third_party/benchmark'
Submodule 'third_party/googletest' (https://github.com/google/googletest.git) registered for path 'packages/grpc-tools/deps/protobuf/third_party/googletest'
Cloning into '/tmpfs/src/github/grpc-node/packages/grpc-tools/deps/protobuf/third_party/benchmark'...
Cloning into '/tmpfs/src/github/grpc-node/packages/grpc-tools/deps/protobuf/third_party/googletest'...
Submodule path 'packages/grpc-tools/deps/protobuf/third_party/benchmark': checked out '5b7683f49e1e9223cf9927b24f6fd3d6bd82e3f8'
Submodule path 'packages/grpc-tools/deps/protobuf/third_party/googletest': checked out '5ec7f0c4a113e2f18ac2c6cc7df51ad6afc24081'
Submodule path 'packages/proto-loader/deps/gapic-showcase': checked out 'b09b3ba9a8db8aae7d5d7c3939853681cc97c293'
Submodule 'schema/api-common-protos' (https://github.com/googleapis/api-common-protos.git) registered for path 'packages/proto-loader/deps/gapic-showcase/schema/api-common-protos'
Cloning into '/tmpfs/src/github/grpc-node/packages/proto-loader/deps/gapic-showcase/schema/api-common-protos'...
Submodule path 'packages/proto-loader/deps/gapic-showcase/schema/api-common-protos': checked out '4c0a203e3658ae0e56d47c817c2c5904116c0ae0'
Submodule path 'packages/proto-loader/deps/googleapis': checked out '8f2eda119e11c8bd0c189b545da18bba9019c83e'
+ [17:06:28 UTC] Cmd finished: git
+ [17:06:28 UTC] git submodule status
78f198cf96ecdc7120ef640406770aa01af775c4 packages/grpc-js-xds/deps/envoy-api (78f198cf)
8c53b2cb792234354c13336ac7daee61333deade packages/grpc-js-xds/deps/googleapis (common-protos-1_3_1-2354-g8c53b2cb7)
0af61d9dc28712dc0e6f8e1a940855a2ee0cb9ed packages/grpc-js-xds/deps/protoc-gen-validate (v0.3.0-java-11-g0af61d9)
cb28da3451f158a947dfc45090fe92b07b243bc1 packages/grpc-js-xds/deps/xds (cb28da3)
7c40b2df1fdf6f414c1c18c789715a9c948a0725 packages/grpc-tools/deps/protobuf (v3.7.0-rc.2-2473-g7c40b2df1)
b09b3ba9a8db8aae7d5d7c3939853681cc97c293 packages/proto-loader/deps/gapic-showcase (v0.11.0-27-gb09b3ba)
8f2eda119e11c8bd0c189b545da18bba9019c83e packages/proto-loader/deps/googleapis (common-protos-1_3_1-2513-g8f2eda119)
+ [17:06:28 UTC] Cmd finished: git
/tmpfs/src/github/grpc-node /tmpfs/src/github/grpc-node
+ [17:06:28 UTC] Building node xDS interop test client
+ [17:06:28 UTC] docker build -f packages/grpc-js-xds/interop/Dockerfile -t us-docker.pkg.dev/grpc-testing/psm-interop/node-client:9f9f6e72ef080a731acd4660689df596d78a73ab .
#0 building with "default" instance using docker driver

#1 [internal] load build definition from Dockerfile
#1 transferring dockerfile: 1.87kB 0.0s done
#1 DONE 0.0s

#2 [internal] load metadata for docker.io/library/node:18-slim
#2 ...

#3 [internal] load metadata for gcr.io/distroless/nodejs18-debian11:latest
#3 DONE 0.4s

#2 [internal] load metadata for docker.io/library/node:18-slim
#2 DONE 0.7s

#4 [internal] load .dockerignore
#4 transferring context: 2B done
#4 DONE 0.0s

#5 [build 1/9] FROM docker.io/library/node:18-slim@sha256:cbfb3c9830932b7b1c2738abf47c66568fc7b06cf782d803e7ddff52b2fc835d
#5 resolve docker.io/library/node:18-slim@sha256:cbfb3c9830932b7b1c2738abf47c66568fc7b06cf782d803e7ddff52b2fc835d 0.0s done
#5 sha256:7aeb27e55a27ddb25ebd3bcd807e60c0ea554427a2207aa70dc3848e15ffd281 1.37kB / 1.37kB done
#5 sha256:193caab64472aba7619422cd3dab250ce4bf156de16f07256a78ec1827ba7c49 7.70kB / 7.70kB done
#5 sha256:b0a0cf830b12453b7e15359a804215a7bcccd3788e2bcecff2a03af64bbd4df7 0B / 29.15MB 0.1s
#5 sha256:a5130b24188f1639e1a9247630fb25fcf90114d659a45942ff54617a886c2475 3.36kB / 3.36kB 0.1s done
#5 sha256:54ccbbd95cdf1f593f9ca089d97f23eb0e7aaa862b99e4e40f34415389141304 0B / 38.15MB 0.1s
#5 sha256:311b11cc471ec585dff9e5761c5ec06cd5cc5632bdeb049a5ce0de1b674b1ba5 0B / 1.71MB 0.1s
#5 sha256:cbfb3c9830932b7b1c2738abf47c66568fc7b06cf782d803e7ddff52b2fc835d 1.21kB / 1.21kB done
#5 sha256:54ccbbd95cdf1f593f9ca089d97f23eb0e7aaa862b99e4e40f34415389141304 7.34MB / 38.15MB 0.3s
#5 sha256:b0a0cf830b12453b7e15359a804215a7bcccd3788e2bcecff2a03af64bbd4df7 7.34MB / 29.15MB 0.4s
#5 sha256:54ccbbd95cdf1f593f9ca089d97f23eb0e7aaa862b99e4e40f34415389141304 12.58MB / 38.15MB 0.4s
#5 sha256:311b11cc471ec585dff9e5761c5ec06cd5cc5632bdeb049a5ce0de1b674b1ba5 1.71MB / 1.71MB 0.4s done
#5 sha256:887f888f15077a011892481474c30a480ca6d30c992396e0b24b29cb9c817f6e 0B / 453B 0.4s
#5 sha256:b0a0cf830b12453b7e15359a804215a7bcccd3788e2bcecff2a03af64bbd4df7 11.53MB / 29.15MB 0.5s
#5 sha256:54ccbbd95cdf1f593f9ca089d97f23eb0e7aaa862b99e4e40f34415389141304 17.83MB / 38.15MB 0.5s
#5 sha256:887f888f15077a011892481474c30a480ca6d30c992396e0b24b29cb9c817f6e 453B / 453B 0.5s done
#5 sha256:b0a0cf830b12453b7e15359a804215a7bcccd3788e2bcecff2a03af64bbd4df7 16.78MB / 29.15MB 0.6s
#5 sha256:54ccbbd95cdf1f593f9ca089d97f23eb0e7aaa862b99e4e40f34415389141304 23.07MB / 38.15MB 0.6s
#5 sha256:b0a0cf830b12453b7e15359a804215a7bcccd3788e2bcecff2a03af64bbd4df7 22.02MB / 29.15MB 0.7s
#5 sha256:54ccbbd95cdf1f593f9ca089d97f23eb0e7aaa862b99e4e40f34415389141304 28.31MB / 38.15MB 0.7s
#5 sha256:b0a0cf830b12453b7e15359a804215a7bcccd3788e2bcecff2a03af64bbd4df7 29.15MB / 29.15MB 0.9s done
#5 sha256:54ccbbd95cdf1f593f9ca089d97f23eb0e7aaa862b99e4e40f34415389141304 38.15MB / 38.15MB 0.9s
#5 sha256:54ccbbd95cdf1f593f9ca089d97f23eb0e7aaa862b99e4e40f34415389141304 38.15MB / 38.15MB 1.0s done
#5 extracting sha256:b0a0cf830b12453b7e15359a804215a7bcccd3788e2bcecff2a03af64bbd4df7
#5 extracting sha256:b0a0cf830b12453b7e15359a804215a7bcccd3788e2bcecff2a03af64bbd4df7 2.1s done
#5 extracting sha256:a5130b24188f1639e1a9247630fb25fcf90114d659a45942ff54617a886c2475 done
#5 extracting sha256:54ccbbd95cdf1f593f9ca089d97f23eb0e7aaa862b99e4e40f34415389141304
#5 ...

#6 [stage-1 1/5] FROM gcr.io/distroless/nodejs18-debian11:latest@sha256:d385b1cc503830c463922e660fd4dbe8ad4a2637f658bfb4ef35b8c34d23a714
#6 resolve gcr.io/distroless/nodejs18-debian11:latest@sha256:d385b1cc503830c463922e660fd4dbe8ad4a2637f658bfb4ef35b8c34d23a714 0.0s done
#6 sha256:d385b1cc503830c463922e660fd4dbe8ad4a2637f658bfb4ef35b8c34d23a714 1.51kB / 1.51kB done
#6 sha256:cf02e889e252679bba4debe6a390b1ad28f7aedde1670a2df4b45b03712d8744 3.05kB / 3.05kB done
#6 sha256:f04862bebe2a6f3256da0e96660fccc1d5f3f928a251a05a0170c057e0b903bf 2.28kB / 2.28kB done
#6 sha256:16100daf795a61c7588657ad2b6afc6eef7300de0c874b6c3d4def1796e28081 103.77kB / 103.77kB 0.5s done
#6 extracting sha256:16100daf795a61c7588657ad2b6afc6eef7300de0c874b6c3d4def1796e28081 done
#6 extracting sha256:e33bce57de289fffd2380f73997dfb7e1ec193877904bed99f28c715d071fdc4 done
#6 sha256:e33bce57de289fffd2380f73997dfb7e1ec193877904bed99f28c715d071fdc4 21.19kB / 21.19kB 0.6s done
#6 extracting sha256:473d8557b1b27974f7dc7c4b4e1a209df0e27e8cae1e3e33b7bb45c969b6fc7e 0.5s done
#6 sha256:473d8557b1b27974f7dc7c4b4e1a209df0e27e8cae1e3e33b7bb45c969b6fc7e 755.28kB / 755.28kB 0.7s done
#6 sha256:b6824ed73363f94b3b2b44084c51c31bc32af77a96861d49e16f91e3ab6bed71 67B / 67B 0.7s done
#6 sha256:7c12895b777bcaa8ccae0605b4de635b68fc32d60fa08f421dc3818bf55ee212 188B / 188B 0.8s done
#6 sha256:33e068de264953dfdc9f9ada207e76b61159721fd64a4820b320d05133a55fb8 122B / 122B 0.9s done
#6 sha256:27be814a09ebd97fac6fb7b82d19f117185e90601009df3fbab6f442f85cd6b3 93B / 93B 1.0s done
#6 sha256:5664b15f108bf9436ce3312090a767300800edbbfd4511aa1a6d64357024d5dd 168B / 168B 1.0s done
#6 sha256:9ef7d74bdfdf3c517b28bd694a9159e94e5f53ff1ca87b39f8ca1ac0be2ed317 320B / 320B 1.0s done
#6 sha256:4aa0ea1413d37a58615488592a0b827ea4b2e48fa5a77cf707d0e35f025e613f 385B / 385B 1.0s done
#6 sha256:9112d77ee5b16873acaa186b816c3c61f5f8eba40730e729e9614a27f40211e0 122.56kB / 122.56kB 1.1s done
#6 sha256:a4ba90834fb4abf3d80bbdaaaef36560ab1bb682f5279d44114d768e119639b9 2.06MB / 2.06MB 1.1s done
#6 sha256:df368711b36276ed02b2040d3e3296b919042d2a05a2bbe9f758e708436c12cf 968.57kB / 968.57kB 1.1s done
#6 sha256:0c9483a07044da732020450f4d4fae4a08507d38c960ea5a57212eac87490e60 5.85MB / 5.85MB 1.3s done
#6 sha256:e89169bec965f3b73bc8a6cf650d362a1da8ae571c5fdccccf7bab8194c03c47 131.15kB / 131.15kB 1.2s done
#6 sha256:7f3501c931c2f262add9c59a46b7469c09f862cfcdb005d9187bc4f8f9964724 741.33kB / 741.33kB 1.3s done
#6 sha256:88934a1bc18c35762f0009512e7a775aec9e1d9531a1781a0caf81013a67ab94 52.41kB / 52.41kB 1.3s done
#6 sha256:54cea410cc31410a8f31c124660c39df69da6068b9022bdd55a094b4601fbe30 50.18MB / 50.18MB 2.0s done
#6 extracting sha256:b6824ed73363f94b3b2b44084c51c31bc32af77a96861d49e16f91e3ab6bed71 done
#6 extracting sha256:7c12895b777bcaa8ccae0605b4de635b68fc32d60fa08f421dc3818bf55ee212 done
#6 extracting sha256:33e068de264953dfdc9f9ada207e76b61159721fd64a4820b320d05133a55fb8 done
#6 extracting sha256:5664b15f108bf9436ce3312090a767300800edbbfd4511aa1a6d64357024d5dd done
#6 extracting sha256:27be814a09ebd97fac6fb7b82d19f117185e90601009df3fbab6f442f85cd6b3 done
#6 extracting sha256:4aa0ea1413d37a58615488592a0b827ea4b2e48fa5a77cf707d0e35f025e613f done
#6 extracting sha256:9ef7d74bdfdf3c517b28bd694a9159e94e5f53ff1ca87b39f8ca1ac0be2ed317 done
#6 extracting sha256:9112d77ee5b16873acaa186b816c3c61f5f8eba40730e729e9614a27f40211e0 done
#6 extracting sha256:0c9483a07044da732020450f4d4fae4a08507d38c960ea5a57212eac87490e60 0.2s done
#6 extracting sha256:a4ba90834fb4abf3d80bbdaaaef36560ab1bb682f5279d44114d768e119639b9 0.0s done
#6 extracting sha256:df368711b36276ed02b2040d3e3296b919042d2a05a2bbe9f758e708436c12cf 0.0s done
#6 extracting sha256:e89169bec965f3b73bc8a6cf650d362a1da8ae571c5fdccccf7bab8194c03c47 done
#6 extracting sha256:7f3501c931c2f262add9c59a46b7469c09f862cfcdb005d9187bc4f8f9964724 0.0s done
#6 extracting sha256:88934a1bc18c35762f0009512e7a775aec9e1d9531a1781a0caf81013a67ab94 done
#6 extracting sha256:54cea410cc31410a8f31c124660c39df69da6068b9022bdd55a094b4601fbe30 2.2s done
#6 DONE 4.9s

#7 [internal] load build context
#7 transferring context: 605.89MB 5.1s
#7 transferring context: 740.41MB 7.6s done
#7 ...

#5 [build 1/9] FROM docker.io/library/node:18-slim@sha256:cbfb3c9830932b7b1c2738abf47c66568fc7b06cf782d803e7ddff52b2fc835d
#5 extracting sha256:54ccbbd95cdf1f593f9ca089d97f23eb0e7aaa862b99e4e40f34415389141304 2.0s done
#5 extracting sha256:311b11cc471ec585dff9e5761c5ec06cd5cc5632bdeb049a5ce0de1b674b1ba5 0.2s done
#5 extracting sha256:887f888f15077a011892481474c30a480ca6d30c992396e0b24b29cb9c817f6e done
#5 DONE 7.8s

#8 [stage-1 2/5] WORKDIR /node/src/grpc-node
#8 DONE 3.0s

#7 [internal] load build context
#7 DONE 7.8s

#9 [build 2/9] WORKDIR /node/src/grpc-node
#9 DONE 0.1s

#10 [build 3/9] COPY . .
#10 DONE 4.5s

#11 [build 4/9] WORKDIR /node/src/grpc-node/packages/proto-loader
#11 DONE 0.0s

#12 [build 5/9] RUN npm install
#12 20.23
#12 20.23 > @grpc/proto-loader@0.7.12 prepare
#12 20.23 > npm run compile
#12 20.23
#12 20.61
#12 20.61 > @grpc/proto-loader@0.7.12 compile
#12 20.61 > tsc -p .
#12 20.61
#12 23.12
#12 23.12 added 302 packages, and audited 303 packages in 23s
#12 23.12
#12 23.12 49 packages are looking for funding
#12 23.12 run `npm fund` for details
#12 23.12
#12 23.12 found 0 vulnerabilities
#12 23.12 npm notice
#12 23.12 npm notice New minor version of npm available! 10.5.0 -> 10.7.0
#12 23.12 npm notice Changelog:
#12 23.12 npm notice Run `npm install -g npm@10.7.0` to update!
#12 23.12 npm notice
#12 DONE 24.1s

#13 [build 6/9] WORKDIR /node/src/grpc-node/packages/grpc-js
#13 DONE 0.0s

#14 [build 7/9] RUN npm install
#14 17.02 npm WARN deprecated flatten@1.0.3: flatten is deprecated in favor of utility frameworks such as lodash.
#14 17.05 npm WARN deprecated urix@0.1.0: Please see https://github.com/lydell/urix#deprecated
#14 17.05 npm WARN deprecated source-map-url@0.4.1: See https://github.com/lydell/source-map-url#deprecated
#14 17.05 npm WARN deprecated resolve-url@0.2.1: https://github.com/lydell/resolve-url#deprecated
#14 17.17 npm WARN deprecated source-map-resolve@0.5.3: See https://github.com/lydell/source-map-resolve#deprecated
#14 19.59 npm WARN deprecated chokidar@2.1.8: Chokidar 2 does not receive security updates since 2019. Upgrade to chokidar 3 with 15x fewer dependencies
#14 19.71 npm WARN deprecated mkdirp@0.5.1: Legacy versions of mkdirp are no longer supported. Please update to mkdirp 1.x. (Note that the API surface has changed to use Promises in 1.x.)
#14 25.47
#14 25.47 > @grpc/grpc-js@1.9.14 prepare
#14 25.47 > npm run generate-types && npm run compile
#14 25.47
#14 25.84
#14 25.84 > @grpc/grpc-js@1.9.14 generate-types
#14 25.84 > proto-loader-gen-types --keepCase --longs String --enums String --defaults --oneofs --includeComments --includeDirs proto/ --include-dirs test/fixtures/ -O src/generated/ --grpcLib ../index channelz.proto
#14 25.84
#14 26.45
#14 26.45 > @grpc/grpc-js@1.9.14 compile
#14 26.45 > tsc -p .
#14 26.45
#14 32.17
#14 32.17 added 706 packages, and audited 708 packages in 32s
#14 32.17
#14 32.17 81 packages are looking for funding
#14 32.17 run `npm fund` for details
#14 32.18
#14 32.18 11 vulnerabilities (1 moderate, 9 high, 1 critical)
#14 32.18
#14 32.18 To address issues that do not require attention, run:
#14 32.18 npm audit fix
#14 32.18
#14 32.18 To address all issues (including breaking changes), run:
#14 32.18 npm audit fix --force
#14 32.18
#14 32.18 Run `npm audit` for details.
#14 DONE 32.3s

#15 [build 8/9] WORKDIR /node/src/grpc-node/packages/grpc-js-xds
#15 DONE 0.0s

#16 [build 9/9] RUN npm install
#16 19.49
#16 19.49 > @grpc/grpc-js-xds@1.9.2 prepare
#16 19.49 > npm run generate-types && npm run compile
#16 19.49
#16 19.88
#16 19.88 > @grpc/grpc-js-xds@1.9.2 generate-types
#16 19.88 > proto-loader-gen-types --keepCase --longs String --enums String --defaults --oneofs --includeComments --includeDirs deps/envoy-api/ deps/xds/ deps/googleapis/ deps/protoc-gen-validate/ -O src/generated/ --grpcLib @grpc/grpc-js envoy/service/discovery/v3/ads.proto envoy/service/load_stats/v3/lrs.proto envoy/config/listener/v3/listener.proto envoy/config/route/v3/route.proto envoy/config/cluster/v3/cluster.proto envoy/config/endpoint/v3/endpoint.proto envoy/extensions/filters/network/http_connection_manager/v3/http_connection_manager.proto udpa/type/v1/typed_struct.proto xds/type/v3/typed_struct.proto envoy/extensions/filters/http/fault/v3/fault.proto envoy/service/status/v3/csds.proto envoy/extensions/load_balancing_policies/wrr_locality/v3/wrr_locality.proto envoy/extensions/load_balancing_policies/ring_hash/v3/ring_hash.proto envoy/extensions/load_balancing_policies/pick_first/v3/pick_first.proto
#16 19.88
#16 21.56
#16 21.56 > @grpc/grpc-js-xds@1.9.2 compile
#16 21.56 > tsc
#16 21.56
#16 26.90
#16 26.90 added 316 packages, and audited 319 packages in 26s
#16 26.90
#16 26.90 63 packages are looking for funding
#16 26.90 run `npm fund` for details
#16 26.90
#16 26.90 found 0 vulnerabilities
#16 DONE 27.3s

#17 [stage-1 3/5] COPY --from=build /node/src/grpc-node/packages/proto-loader ./packages/proto-loader/
#17 DONE 3.0s

#18 [stage-1 4/5] COPY --from=build /node/src/grpc-node/packages/grpc-js ./packages/grpc-js/
#18 DONE 2.6s

#19 [stage-1 5/5] COPY --from=build /node/src/grpc-node/packages/grpc-js-xds ./packages/grpc-js-xds/
#19 DONE 2.4s

#20 exporting to image
#20 exporting layers
#20 exporting layers 2.7s done
#20 writing image sha256:616a15a96ebf81b6816b2f53680ef220a094a3931aa176198498723d0e0decf0 done
#20 naming to us-docker.pkg.dev/grpc-testing/psm-interop/node-client:9f9f6e72ef080a731acd4660689df596d78a73ab done
#20 DONE 2.7s
+ [17:08:21 UTC] Cmd finished: docker
+ [17:08:21 UTC] docker push us-docker.pkg.dev/grpc-testing/psm-interop/node-client:9f9f6e72ef080a731acd4660689df596d78a73ab
The push refers to repository [us-docker.pkg.dev/grpc-testing/psm-interop/node-client]
96a5a85ddce0: Preparing
04c1cd8fed9b: Preparing
d82e6e289eb9: Preparing
3b09a20e44be: Preparing
d92724d2b950: Preparing
f3aaf0cdb0a8: Preparing
6ea409d80cd8: Preparing
f63d186f6e7d: Preparing
6835249f577a: Preparing
24aacbf97031: Preparing
8b27836da143: Preparing
2388d21e8e2b: Preparing
c048279a7d9f: Preparing
1a73b54f556b: Preparing
2a92d6ac9e4f: Preparing
bbb6cacb8c82: Preparing
ac805962e479: Preparing
af5aa97ebe6c: Preparing
4d049f83d9cf: Preparing
9ed498e122b2: Preparing
577c8ee06f39: Preparing
32ae37dc07be: Preparing
6835249f577a: Waiting
24aacbf97031: Waiting
8b27836da143: Waiting
1a73b54f556b: Waiting
2a92d6ac9e4f: Waiting
bbb6cacb8c82: Waiting
2388d21e8e2b: Waiting
ac805962e479: Waiting
af5aa97ebe6c: Waiting
c048279a7d9f: Waiting
4d049f83d9cf: Waiting
9ed498e122b2: Waiting
577c8ee06f39: Waiting
32ae37dc07be: Waiting
f3aaf0cdb0a8: Waiting
6ea409d80cd8: Waiting
f63d186f6e7d: Waiting
d92724d2b950: Layer already exists
f3aaf0cdb0a8: Layer already exists
6ea409d80cd8: Layer already exists
f63d186f6e7d: Layer already exists
3b09a20e44be: Pushed
6835249f577a: Layer already exists
24aacbf97031: Layer already exists
2388d21e8e2b: Layer already exists
c048279a7d9f: Layer already exists
1a73b54f556b: Layer already exists
2a92d6ac9e4f: Layer already exists
bbb6cacb8c82: Layer already exists
ac805962e479: Layer already exists
af5aa97ebe6c: Layer already exists
4d049f83d9cf: Layer already exists
9ed498e122b2: Layer already exists
577c8ee06f39: Layer already exists
32ae37dc07be: Layer already exists
8b27836da143: Pushed
96a5a85ddce0: Pushed
d82e6e289eb9: Pushed
04c1cd8fed9b: Pushed
9f9f6e72ef080a731acd4660689df596d78a73ab: digest: sha256:2836112a7f03ed5b1fa9446768f6be9582277202626cf023d10a64fab798dd7f size: 4919
+ [17:08:39 UTC] Cmd finished: docker
/tmpfs/src/github/grpc-node
+ [17:08:39 UTC] Finished xDS interop test app Docker images
+ [17:08:39 UTC] Tests in lb test suite:
- app_net_test
- baseline_test

+ [17:08:39 UTC] Running lb suite test: app_net_test
+ [17:08:39 UTC] Test driver flags for app_net_test:
--flagfile=config/grpc-testing.cfg
--kube_context=gke_grpc-testing_us-central1-a_psm-interop-lb-primary
--force_cleanup
--collect_app_logs
--log_dir=/tmpfs/src/artifacts/grpc/node/master/xds_k8s_lb/app_net_test
--xml_output_file=/tmpfs/src/artifacts/grpc/node/master/xds_k8s_lb/app_net_test/sponge_log.xml
--testing_version=dev-master
--client_image=us-docker.pkg.dev/grpc-testing/psm-interop/node-client:9f9f6e72ef080a731acd4660689df596d78a73ab
--server_image=us-docker.pkg.dev/grpc-testing/psm-interop/java-server:canonical
--secondary_kube_context=gke_grpc-testing_us-west1-b_psm-interop-lb-secondary

+ [17:08:39 UTC] python -m tests.app_net_test --flagfile=config/grpc-testing.cfg --kube_context=gke_grpc-testing_us-central1-a_psm-interop-lb-primary --force_cleanup --collect_app_logs --log_dir=/tmpfs/src/artifacts/grpc/node/master/xds_k8s_lb/app_net_test --xml_output_file=/tmpfs/src/artifacts/grpc/node/master/xds_k8s_lb/app_net_test/sponge_log.xml --testing_version=dev-master --client_image=us-docker.pkg.dev/grpc-testing/psm-interop/node-client:9f9f6e72ef080a731acd4660689df596d78a73ab --server_image=us-docker.pkg.dev/grpc-testing/psm-interop/java-server:canonical --secondary_kube_context=gke_grpc-testing_us-west1-b_psm-interop-lb-secondary
Running tests under Python 3.10.12: /tmp/tmp.D6gt45aunY/psm-interop/venv/bin/python
I0503 17:08:41.053632 126295029690368 xds_k8s_testcase.py:165] ----- Testing AppNetTest -----
I0503 17:08:41.053824 126295029690368 xds_k8s_testcase.py:166] Logs timezone: UTC
I0503 17:08:41.054073 126295029690368 xds_k8s_testcase.py:111] Detected language and version: TestConfig(client_lang='node', server_lang='java', version='dev-master')
I0503 17:08:41.072256 126295029690368 k8s.py:243] Using kubernetes context "gke_grpc-testing_us-central1-a_psm-interop-lb-primary", active host: https://35.238.98.54
I0503 17:08:41.123199 126295029690368 k8s.py:243] Using kubernetes context "gke_grpc-testing_us-west1-b_psm-interop-lb-secondary", active host: https://35.227.140.120
[ RUN ] AppNetTest.test_ping_pong
I0503 17:08:41.308840 126295029690368 xds_k8s_testcase.py:747] Test run resource prefix: psm-interop, suffix: 20240503-1708-izpqb
I0503 17:08:41.861601 126295029690368 logs.py:31] Log root dir: /tmpfs/src/artifacts/grpc/node/master/xds_k8s_lb/app_net_test
I0503 17:08:42.234661 126295029690368 xds_k8s_testcase.py:742] Found unused xds port: 29584
I0503 17:08:42.234965 126295029690368 xds_k8s_testcase.py:308] --- Starting subTest AppNetTest.test_ping_pong.0_create_health_check ---
I0503 17:08:42.235047 126295029690368 traffic_director.py:197] Creating GRPC Health Check "psm-interop-health-check-20240503-1708-izpqb"
I0503 17:08:42.238316 126295029690368 compute.py:537] Creating compute resource:
---
grpcHealthCheck:
portSpecification: USE_SERVING_PORT
name: psm-interop-health-check-20240503-1708-izpqb
type: GRPC
...

I0503 17:08:42.898435 126295029690368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756122309-6178fc58bf1b3-2f98cb61-8af3af31
I0503 17:08:45.157382 126295029690368 xds_k8s_testcase.py:313] --- Finished subTest AppNetTest.test_ping_pong.0_create_health_check ---
I0503 17:08:45.157563 126295029690368 xds_k8s_testcase.py:308] --- Starting subTest AppNetTest.test_ping_pong.1_create_backend_service ---
I0503 17:08:45.157636 126295029690368 traffic_director.py:224] Creating GRPC Backend Service "psm-interop-backend-service-20240503-1708-izpqb"
I0503 17:08:45.165343 126295029690368 compute.py:537] Creating compute resource:
---
healthChecks:
- https://www.googleapis.com/compute/v1/projects/grpc-testing/global/healthChecks/psm-interop-health-check-20240503-1708-izpqb
loadBalancingScheme: INTERNAL_SELF_MANAGED
name: psm-interop-backend-service-20240503-1708-izpqb
protocol: GRPC
...

I0503 17:08:45.745293 126295029690368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756125167-6178fc5b79023-16c93333-ce527153
I0503 17:09:35.118274 126295029690368 xds_k8s_testcase.py:313] --- Finished subTest AppNetTest.test_ping_pong.1_create_backend_service ---
I0503 17:09:35.118520 126295029690368 xds_k8s_testcase.py:308] --- Starting subTest AppNetTest.test_ping_pong.2_create_mesh ---
I0503 17:09:35.118595 126295029690368 traffic_director.py:844] Creating Mesh psm-interop-mesh-20240503-1708-izpqb
I0503 17:09:35.120853 126295029690368 api.py:490] Creating networkservices resource:
--- {}
...

I0503 17:09:35.716968 126295029690368 api.py:546] Waiting 600 sec for networkservices operation id: projects/grpc-testing/locations/global/operations/operation-1714756175223-6178fc8b358ff-72f3361b-828cee4d
I0503 17:09:40.044581 126295029690368 api.py:512] Loaded projects/grpc-testing/locations/global/meshes/psm-interop-mesh-20240503-1708-izpqb:
---
createTime: '2024-05-03T17:09:35.264018815Z'
name: projects/grpc-testing/locations/global/meshes/psm-interop-mesh-20240503-1708-izpqb
selfLink: https://networkservices.googleapis.com/v1alpha1/projects/grpc-testing/locations/global/meshes/psm-interop-mesh-20240503-1708-izpqb
updateTime: '2024-05-03T17:09:39.156373759Z'
...

I0503 17:09:40.044867 126295029690368 xds_k8s_testcase.py:313] --- Finished subTest AppNetTest.test_ping_pong.2_create_mesh ---
I0503 17:09:40.044950 126295029690368 xds_k8s_testcase.py:308] --- Starting subTest AppNetTest.test_ping_pong.3_create_grpc_route ---
I0503 17:09:40.045025 126295029690368 traffic_director.py:875] Creating GrpcRoute psm-interop-grpc-route-20240503-1708-izpqb
I0503 17:09:40.048372 126295029690368 api.py:490] Creating networkservices resource:
---
hostnames: psm-grpc-server:29584
meshes:
- projects/grpc-testing/locations/global/meshes/psm-interop-mesh-20240503-1708-izpqb
rules:
- action:
destinations:
- serviceName: projects/grpc-testing/locations/global/backendServices/psm-interop-backend-service-20240503-1708-izpqb
...

I0503 17:09:40.533033 126295029690368 api.py:546] Waiting 600 sec for networkservices operation id: projects/grpc-testing/locations/global/operations/operation-1714756180080-6178fc8fd76e9-e0285147-de3f388d
I0503 17:09:46.888526 126295029690368 api.py:512] Loaded projects/grpc-testing/locations/global/grpcRoutes/psm-interop-grpc-route-20240503-1708-izpqb:
---
createTime: '2024-05-03T17:09:40.201897480Z'
hostnames:
- psm-grpc-server:29584
meshes:
- projects/grpc-testing/locations/global/meshes/psm-interop-mesh-20240503-1708-izpqb
name: projects/grpc-testing/locations/global/grpcRoutes/psm-interop-grpc-route-20240503-1708-izpqb
rules:
- action:
destinations:
- serviceName: projects/grpc-testing/locations/global/backendServices/psm-interop-backend-service-20240503-1708-izpqb
selfLink: https://networkservices.googleapis.com/v1alpha1/projects/grpc-testing/locations/global/grpcRoutes/psm-interop-grpc-route-20240503-1708-izpqb
updateTime: '2024-05-03T17:09:45.249948648Z'
...

I0503 17:09:46.888797 126295029690368 xds_k8s_testcase.py:313] --- Finished subTest AppNetTest.test_ping_pong.3_create_grpc_route ---
I0503 17:09:46.888872 126295029690368 xds_k8s_testcase.py:308] --- Starting subTest AppNetTest.test_ping_pong.4_start_test_server ---
I0503 17:09:46.888956 126295029690368 k8s_xds_server_runner.py:186] Deploying xDS test server "psm-grpc-server" to k8s namespace psm-interop-server-20240503-1708-izpqb: test_port=8080 maintenance_port=8080 secure_mode=False replica_count=1
I0503 17:09:46.889247 126295029690368 k8s_base_runner.py:1152] GCP Logs Explorer link to psm-grpc-server:
https://console.cloud.google.com/logs/query;query=resource.type%3D%22k8s_container%22%0Aresource.labels.project_id%3D%22grpc-testing%22%0Aresource.labels.container_name%3D%22psm-grpc-server%22%0Aresource.labels.namespace_name%3D%22psm-interop-server-20240503-1708-izpqb%22;timeRange=2024-05-03T17:09:46.889053Z%2F2024-05-03T17:39:46.889053Z?project=grpc-testing
I0503 17:09:46.894598 126295029690368 k8s_base_runner.py:347] Rendered template kubernetes-manifests/namespace.yaml:
---
apiVersion: v1
kind: Namespace
metadata:
name: psm-interop-server-20240503-1708-izpqb
labels:
name: psm-interop-server-20240503-1708-izpqb
owner: xds-k8s-interop-test
...

I0503 17:09:46.922655 126295029690368 k8s_base_runner.py:367] Namespace psm-interop-server-20240503-1708-izpqb created
I0503 17:09:46.925823 126295029690368 k8s_base_runner.py:347] Rendered template kubernetes-manifests/server.service.yaml:
---
apiVersion: v1
kind: Service
metadata:
name: psm-grpc-server
namespace: psm-interop-server-20240503-1708-izpqb
labels:
owner: xds-k8s-interop-test
annotations:
cloud.google.com/neg: '{"exposed_ports": {"8080":{"name":"psm-interop-server-20240503-1708-izpqb-psm-grpc-server"}}}'
spec:
type: ClusterIP
selector:
app: psm-grpc-server
ports:
- port: 8080
protocol: TCP
targetPort: 8080
...

I0503 17:09:46.963706 126295029690368 k8s_base_runner.py:367] Service psm-grpc-server created
I0503 17:09:46.963981 126295029690368 k8s_base_runner.py:1054] Waiting for 'cloud.google.com/neg-status' annotation for a NEG at port 8080 to be assigned to Kubernetes Service psm-grpc-server in namespace psm-interop-server-20240503-1708-izpqb
I0503 17:09:46.993459 126295029690368 k8s_base_runner.py:1068] Detected 'cloud.google.com/neg-status' annotation for Kubernetes Service psm-grpc-server, namespace psm-interop-server-20240503-1708-izpqb: neg_name=psm-interop-server-20240503-1708-izpqb-psm-grpc-server, port=8080, zones=['us-central1-a']
I0503 17:09:46.993679 126295029690368 k8s_base_runner.py:452] Granting roles/iam.workloadIdentityUser to serviceAccount:grpc-testing.svc.id.goog[psm-interop-server-20240503-1708-izpqb/psm-grpc-server] for GCP Service Account xds-k8s-interop-tests@grpc-testing.iam.gserviceaccount.com
I0503 17:09:47.456381 126295029690368 k8s_base_runner.py:347] Rendered template kubernetes-manifests/service-account.yaml:
---
apiVersion: v1
kind: ServiceAccount
metadata:
name: psm-grpc-server
namespace: psm-interop-server-20240503-1708-izpqb
labels:
owner: xds-k8s-interop-test
annotations:
iam.gke.io/gcp-service-account: xds-k8s-interop-tests@grpc-testing.iam.gserviceaccount.com
...

I0503 17:09:47.494365 126295029690368 k8s_base_runner.py:367] ServiceAccount psm-grpc-server created
I0503 17:09:47.514218 126295029690368 k8s_base_runner.py:347] Rendered template kubernetes-manifests/server.deployment.yaml:
---
apiVersion: apps/v1
kind: Deployment
metadata:
name: psm-grpc-server
namespace: psm-interop-server-20240503-1708-izpqb
labels:
app: psm-grpc-server
deployment_id: psm-grpc-server-xqv2h1yq
owner: xds-k8s-interop-test
spec:
replicas: 1
selector:
matchLabels:
app: psm-grpc-server
deployment_id: psm-grpc-server-xqv2h1yq
template:
metadata:
labels:
app: psm-grpc-server
deployment_id: psm-grpc-server-xqv2h1yq
owner: xds-k8s-interop-test
spec:
serviceAccountName: psm-grpc-server
containers:
- name: psm-grpc-server
image: us-docker.pkg.dev/grpc-testing/psm-interop/java-server:canonical
imagePullPolicy: Always
startupProbe:
tcpSocket:
port: 8080
periodSeconds: 3
failureThreshold: 1000

args:
- "--port=8080"
ports:
- containerPort: 8080
env:
- name: GRPC_XDS_BOOTSTRAP
value: "/tmp/grpc-xds/td-grpc-bootstrap.json"
- name: GRPC_XDS_EXPERIMENTAL_V3_SUPPORT
value: "true"
- name: GRPC_EXPERIMENTAL_XDS_ENABLE_OVERRIDE_HOST
value: "true"
volumeMounts:
- mountPath: /tmp/grpc-xds/
name: grpc-td-conf
readOnly: true

resources:
limits:
cpu: 800m
memory: 512Mi
requests:
cpu: 100m
memory: 512Mi
initContainers:
- name: grpc-td-init
image: us-docker.pkg.dev/grpc-testing/trafficdirector/td-grpc-bootstrap:2bf1b5ed00f852ffea8d24759c6fa673acc9ef10
imagePullPolicy: Always
args:
- "--output=/tmp/bootstrap/td-grpc-bootstrap.json"
- "--vpc-network-name=default-vpc"
- "--node-metadata=app=psm-interop-server-20240503-1708-izpqb-psm-grpc-server"
resources:
limits:
cpu: 100m
memory: 100Mi
requests:
cpu: 10m
memory: 100Mi
volumeMounts:
- mountPath: /tmp/bootstrap/
name: grpc-td-conf

volumes:
- name: grpc-td-conf
emptyDir:
medium: Memory

...

I0503 17:09:47.563849 126295029690368 k8s_base_runner.py:367] Deployment psm-grpc-server created
I0503 17:09:47.564100 126295029690368 k8s_base_runner.py:962] Waiting for deployment psm-grpc-server to initialize 1 pod(s)
I0503 17:09:47.606893 126295029690368 k8s_base_runner.py:972] Deployment psm-grpc-server initialized 1 pod(s): ['psm-grpc-server-5589cb7995-qcxzq']
I0503 17:09:47.607170 126295029690368 k8s_base_runner.py:982] Waiting for pod psm-grpc-server-5589cb7995-qcxzq to start
I0503 17:09:50.691472 126295029690368 k8s_base_runner.py:985] Pod psm-grpc-server-5589cb7995-qcxzq ready, IP: 10.120.11.39
I0503 17:09:50.691764 126295029690368 k8s_base_runner.py:1030] Enabling log collection from pod psm-grpc-server-5589cb7995-qcxzq to app_net_test/test_app_logs/psm-interop-server-20240503-1708-izpqb_psm-grpc-server-5589cb7995-qcxzq.log
I0503 17:09:50.692481 126294201177664 k8s_log_collector.py:71] [ns/psm-interop-server-20240503-1708-izpqb] Starting log collection thread 126294201177664 for psm-grpc-server-5589cb7995-qcxzq
I0503 17:09:50.692846 126295029690368 k8s_base_runner.py:944] Waiting for deployment psm-grpc-server to report 1 available replica(s)
I0503 17:09:55.777791 126295029690368 k8s_base_runner.py:953] Deployment psm-grpc-server has 1 replicas available
I0503 17:09:55.778145 126295029690368 xds_k8s_testcase.py:313] --- Finished subTest AppNetTest.test_ping_pong.4_start_test_server ---
I0503 17:09:55.778235 126295029690368 xds_k8s_testcase.py:308] --- Starting subTest AppNetTest.test_ping_pong.5_setup_server_backends ---
I0503 17:09:55.788654 126295029690368 traffic_director.py:262] Waiting for Network Endpoint Groups to load endpoints.
I0503 17:09:55.877935 126295029690368 traffic_director.py:265] Loaded NEG "psm-interop-server-20240503-1708-izpqb-psm-grpc-server" in zone us-central1-a
I0503 17:09:55.878169 126295029690368 traffic_director.py:283] Adding backends to Backend Service psm-interop-backend-service-20240503-1708-izpqb: {ComputeV1.ZonalGcpResource(name='psm-interop-server-20240503-1708-izpqb-psm-grpc-server', url='https://www.googleapis.com/compute/v1/projects/grpc-testing/zones/us-central1-a/networkEndpointGroups/psm-interop-server-20240503-1708-izpqb-psm-grpc-server', zone='us-central1-a')}
I0503 17:09:55.883408 126295029690368 compute.py:544] Patching compute resource:
---
backends:
- balancingMode: RATE
group: https://www.googleapis.com/compute/v1/projects/grpc-testing/zones/us-central1-a/networkEndpointGroups/psm-interop-server-20240503-1708-izpqb-psm-grpc-server
maxRatePerEndpoint: 5
...

I0503 17:09:57.249966 126295029690368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756195892-6178fc9eebbbd-aab0ff0d-2c57c04e
I0503 17:10:57.574043 126295029690368 traffic_director.py:300] Waiting for Backend Service psm-interop-backend-service-20240503-1708-izpqb to report all backends healthy: ['psm-interop-server-20240503-1708-izpqb-psm-grpc-server']
I0503 17:11:18.561527 126295029690368 compute.py:494] Backend psm-interop-server-20240503-1708-izpqb-psm-grpc-server in zone us-central1-a reported healthy
I0503 17:11:22.566323 126295029690368 xds_k8s_testcase.py:313] --- Finished subTest AppNetTest.test_ping_pong.5_setup_server_backends ---
I0503 17:11:22.566598 126295029690368 xds_k8s_testcase.py:308] --- Starting subTest AppNetTest.test_ping_pong.6_start_test_client ---
I0503 17:11:22.566717 126295029690368 k8s_xds_client_runner.py:119] Deploying xDS test client "psm-grpc-client" to k8s namespace psm-interop-client-20240503-1708-izpqb: server_target=xds:///psm-grpc-server:29584 rpc=UnaryCall qps=25 metadata='' secure_mode=False print_response=False
I0503 17:11:22.567052 126295029690368 k8s_base_runner.py:1152] GCP Logs Explorer link to psm-grpc-client:
https://console.cloud.google.com/logs/query;query=resource.type%3D%22k8s_container%22%0Aresource.labels.project_id%3D%22grpc-testing%22%0Aresource.labels.container_name%3D%22psm-grpc-client%22%0Aresource.labels.namespace_name%3D%22psm-interop-client-20240503-1708-izpqb%22;timeRange=2024-05-03T17:11:22.566829Z%2F2024-05-03T17:41:22.566829Z?project=grpc-testing
I0503 17:11:22.569496 126295029690368 k8s_base_runner.py:347] Rendered template kubernetes-manifests/namespace.yaml:
---
apiVersion: v1
kind: Namespace
metadata:
name: psm-interop-client-20240503-1708-izpqb
labels:
name: psm-interop-client-20240503-1708-izpqb
owner: xds-k8s-interop-test
...

I0503 17:11:22.612224 126295029690368 k8s_base_runner.py:367] Namespace psm-interop-client-20240503-1708-izpqb created
I0503 17:11:22.612466 126295029690368 k8s_base_runner.py:452] Granting roles/iam.workloadIdentityUser to serviceAccount:grpc-testing.svc.id.goog[psm-interop-client-20240503-1708-izpqb/psm-grpc-client] for GCP Service Account xds-k8s-interop-tests@grpc-testing.iam.gserviceaccount.com
I0503 17:11:22.950463 126295029690368 k8s_base_runner.py:347] Rendered template kubernetes-manifests/service-account.yaml:
---
apiVersion: v1
kind: ServiceAccount
metadata:
name: psm-grpc-client
namespace: psm-interop-client-20240503-1708-izpqb
labels:
owner: xds-k8s-interop-test
annotations:
iam.gke.io/gcp-service-account: xds-k8s-interop-tests@grpc-testing.iam.gserviceaccount.com
...

I0503 17:11:22.979204 126295029690368 k8s_base_runner.py:367] ServiceAccount psm-grpc-client created
I0503 17:11:22.989644 126295029690368 k8s_base_runner.py:347] Rendered template kubernetes-manifests/client.deployment.yaml:
---
apiVersion: apps/v1
kind: Deployment
metadata:
name: psm-grpc-client
namespace: psm-interop-client-20240503-1708-izpqb
labels:
app: psm-grpc-client
deployment_id: psm-grpc-client-u6xgh0lz
owner: xds-k8s-interop-test
spec:
replicas: 1
selector:
matchLabels:
app: psm-grpc-client
deployment_id: psm-grpc-client-u6xgh0lz
template:
metadata:
labels:
app: psm-grpc-client
deployment_id: psm-grpc-client-u6xgh0lz
owner: xds-k8s-interop-test
spec:
serviceAccountName: psm-grpc-client
containers:
- name: psm-grpc-client
image: us-docker.pkg.dev/grpc-testing/psm-interop/node-client:9f9f6e72ef080a731acd4660689df596d78a73ab
imagePullPolicy: Always
startupProbe:
tcpSocket:
port: 8079
periodSeconds: 3
failureThreshold: 1000
args:
- "--server=xds:///psm-grpc-server:29584"
- "--stats_port=8079"
- "--qps=25"
- "--rpc=UnaryCall"
- "--metadata="
- "--print_response=False"
ports:
- containerPort: 8079
env:
- name: GRPC_XDS_BOOTSTRAP
value: "/tmp/grpc-xds/td-grpc-bootstrap.json"
- name: GRPC_XDS_EXPERIMENTAL_ENABLE_RING_HASH
value: "true"
- name: GRPC_XDS_EXPERIMENTAL_ENABLE_RETRY
value: "true"
- name: GRPC_EXPERIMENTAL_ENABLE_OUTLIER_DETECTION
value: "true"
- name: GRPC_EXPERIMENTAL_XDS_CUSTOM_LB_CONFIG
value: "true"
- name: GRPC_EXPERIMENTAL_XDS_ENABLE_OVERRIDE_HOST
value: "true"
volumeMounts:
- mountPath: /tmp/grpc-xds/
name: grpc-td-conf
readOnly: true
resources:
limits:
cpu: 800m
memory: 512Mi
requests:
cpu: 100m
memory: 512Mi
initContainers:
- name: grpc-td-init
image: us-docker.pkg.dev/grpc-testing/trafficdirector/td-grpc-bootstrap:2bf1b5ed00f852ffea8d24759c6fa673acc9ef10
imagePullPolicy: Always
args:
- "--output=/tmp/bootstrap/td-grpc-bootstrap.json"
- "--vpc-network-name=default-vpc"
- "--config-mesh-experimental=psm-interop-mesh-20240503-1708-izpqb"
resources:
limits:
cpu: 100m
memory: 100Mi
requests:
cpu: 10m
memory: 100Mi
volumeMounts:
- mountPath: /tmp/bootstrap/
name: grpc-td-conf
volumes:
- name: grpc-td-conf
emptyDir:
medium: Memory
...

I0503 17:11:23.029153 126295029690368 k8s_base_runner.py:367] Deployment psm-grpc-client created
I0503 17:11:23.029391 126295029690368 k8s_base_runner.py:962] Waiting for deployment psm-grpc-client to initialize 1 pod(s)
I0503 17:11:23.071341 126295029690368 k8s_base_runner.py:972] Deployment psm-grpc-client initialized 1 pod(s): ['psm-grpc-client-9548fbf7d-4hdjp']
I0503 17:11:23.071607 126295029690368 k8s_base_runner.py:982] Waiting for pod psm-grpc-client-9548fbf7d-4hdjp to start
I0503 17:11:44.391158 126295029690368 k8s_base_runner.py:985] Pod psm-grpc-client-9548fbf7d-4hdjp ready, IP: 10.120.13.232
I0503 17:11:44.391472 126295029690368 k8s_base_runner.py:1030] Enabling log collection from pod psm-grpc-client-9548fbf7d-4hdjp to app_net_test/test_app_logs/psm-interop-client-20240503-1708-izpqb_psm-grpc-client-9548fbf7d-4hdjp.log
I0503 17:11:44.392108 126293244900928 k8s_log_collector.py:71] [ns/psm-interop-client-20240503-1708-izpqb] Starting log collection thread 126293244900928 for psm-grpc-client-9548fbf7d-4hdjp
I0503 17:11:44.392395 126295029690368 k8s_base_runner.py:944] Waiting for deployment psm-grpc-client to report 1 available replica(s)
I0503 17:11:47.462540 126295029690368 k8s_base_runner.py:953] Deployment psm-grpc-client has 1 replicas available
I0503 17:11:47.463045 126295029690368 client_app.py:292] [psm-grpc-client-9548fbf7d-4hdjp] ADS: Waiting for active calls to xDS control plane to trafficdirector.googleapis.com:443
I0503 17:11:47.511261 126295029690368 client_app.py:320] [psm-grpc-client-9548fbf7d-4hdjp] xDS control plane channel:
I0503 17:11:57.536243 126295029690368 client_app.py:320] [psm-grpc-client-9548fbf7d-4hdjp] xDS control plane channel:
I0503 17:12:07.559190 126295029690368 client_app.py:320] [psm-grpc-client-9548fbf7d-4hdjp] xDS control plane channel:
I0503 17:12:17.583211 126295029690368 client_app.py:320] [psm-grpc-client-9548fbf7d-4hdjp] xDS control plane channel:
I0503 17:12:27.607597 126295029690368 client_app.py:320] [psm-grpc-client-9548fbf7d-4hdjp] xDS control plane channel:
I0503 17:12:43.636344 126295029690368 client_app.py:320] [psm-grpc-client-9548fbf7d-4hdjp] xDS control plane channel:
I0503 17:13:08.663771 126295029690368 client_app.py:320] [psm-grpc-client-9548fbf7d-4hdjp] xDS control plane channel:
I0503 17:13:10.674764 126295029690368 client_app.py:331] [psm-grpc-client-9548fbf7d-4hdjp] Detected active calls to xDS control plane trafficdirector.googleapis.com:443, channel:
I0503 17:13:10.675079 126295029690368 client_app.py:302] [psm-grpc-client-9548fbf7d-4hdjp] ADS: Detected active calls to xDS control plane trafficdirector.googleapis.com:443
I0503 17:13:10.675440 126295029690368 client_app.py:252] [psm-grpc-client-9548fbf7d-4hdjp] Waiting to report a READY channel to xds:///psm-grpc-server:29584
I0503 17:13:10.680075 126295029690368 client_app.py:379] [psm-grpc-client-9548fbf7d-4hdjp] Server channel:
I0503 17:13:10.689095 126295029690368 client_app.py:392] [psm-grpc-client-9548fbf7d-4hdjp] Found subchannel in state READY:
I0503 17:13:10.689592 126295029690368 client_app.py:263] [psm-grpc-client-9548fbf7d-4hdjp] Channel to xds:///psm-grpc-server:29584 transitioned to state READY:
I0503 17:13:10.689790 126295029690368 xds_k8s_testcase.py:313] --- Finished subTest AppNetTest.test_ping_pong.6_start_test_client ---
I0503 17:13:10.689889 126295029690368 xds_k8s_testcase.py:308] --- Starting subTest AppNetTest.test_ping_pong.7_assert_xds_config_exists ---
I0503 17:13:10.690184 126295029690368 grpc.py:75] [psm-grpc-client-9548fbf7d-4hdjp:8079] >> RPC ClientStatusDiscoveryService.FetchClientStatus(request=ClientStatusRequest({}), wait_for_ready=True, timeout=30)
I0503 17:13:10.706109 126295029690368 xds_k8s_testcase.py:313] --- Finished subTest AppNetTest.test_ping_pong.7_assert_xds_config_exists ---
I0503 17:13:10.706360 126295029690368 xds_k8s_testcase.py:308] --- Starting subTest AppNetTest.test_ping_pong.8_assert_successful_rpcs ---
I0503 17:13:10.706656 126295029690368 grpc.py:75] [psm-grpc-client-9548fbf7d-4hdjp:8079] >> RPC LoadBalancerStatsService.GetClientStats(request=LoadBalancerStatsRequest({'numRpcs': 100, 'timeoutSec': 1200}), timeout=1200, wait_for_ready=True)
I0503 17:13:14.760560 126295029690368 xds_k8s_testcase.py:678] [psm-grpc-client-9548fbf7d-4hdjp] << Received LoadBalancerStatsResponse:
num_failures: 0
rpcs_by_peer:
psm-grpc-server-5589cb7995-qcxzq: 100
rpcs_by_method:
UnaryCall:
psm-grpc-server-5589cb7995-qcxzq: 100

I0503 17:13:14.760838 126295029690368 xds_k8s_testcase.py:313] --- Finished subTest AppNetTest.test_ping_pong.8_assert_successful_rpcs ---
I0503 17:13:14.760988 126295029690368 xds_k8s_testcase.py:766] ----- TestMethod AppNetTest.test_ping_pong teardown -----
I0503 17:13:14.802518 126295029690368 traffic_director.py:915] Deleting HttpRoute psm-interop-http-route-20240503-1708-izpqb
I0503 17:13:14.853324 126295029690368 traffic_director.py:904] Deleting GrpcRoute psm-interop-grpc-route-20240503-1708-izpqb
I0503 17:13:15.170591 126295029690368 api.py:546] Waiting 600 sec for networkservices operation id: projects/grpc-testing/locations/global/operations/operation-1714756394900-6178fd5cb5b8c-88ff260d-42cf1ce3
I0503 17:13:21.404722 126295029690368 traffic_director.py:858] Deleting Mesh psm-interop-mesh-20240503-1708-izpqb
I0503 17:13:21.721404 126295029690368 api.py:546] Waiting 600 sec for networkservices operation id: projects/grpc-testing/locations/global/operations/operation-1714756401453-6178fd62f595d-0e1db35e-0b6387bf
I0503 17:13:40.380490 126295029690368 traffic_director.py:680] Deleting Forwarding rule "psm-interop-forwarding-rule-20240503-1708-izpqb"
I0503 17:13:40.494788 126295029690368 traffic_director.py:719] Deleting alternative Forwarding rule "psm-interop-forwarding-rule-alt-20240503-1708-izpqb"
I0503 17:13:40.656683 126295029690368 traffic_director.py:611] Deleting HTTP Target proxy "psm-interop-target-proxy-20240503-1708-izpqb"
I0503 17:13:41.032913 126295029690368 traffic_director.py:599] Deleting Target GRPC proxy "psm-interop-target-proxy-20240503-1708-izpqb"
I0503 17:13:41.551476 126295029690368 traffic_director.py:639] Deleting alternative Target GRPC proxy "psm-interop-target-proxy-alt-20240503-1708-izpqb"
I0503 17:13:41.981745 126295029690368 traffic_director.py:531] Deleting URL Map "psm-interop-url-map-20240503-1708-izpqb"
I0503 17:13:42.332560 126295029690368 traffic_director.py:567] Deleting alternative URL Map "psm-interop-url-map-alt-20240503-1708-izpqb"
I0503 17:13:42.844635 126295029690368 traffic_director.py:249] Deleting Backend Service "psm-interop-backend-service-20240503-1708-izpqb"
I0503 17:13:43.449451 126295029690368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756422852-6178fd775de00-42d85641-69882ed5
I0503 17:14:11.526534 126295029690368 traffic_director.py:338] Deleting Alternative Backend Service "psm-interop-backend-service-alt-20240503-1708-izpqb"
I0503 17:14:11.997745 126295029690368 traffic_director.py:411] Deleting Affinity Backend Service "psm-interop-backend-service-affinity-20240503-1708-izpqb"
I0503 17:14:12.529381 126295029690368 traffic_director.py:208] Deleting Health Check "psm-interop-health-check-20240503-1708-izpqb"
I0503 17:14:13.122023 126295029690368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756452600-6178fd93bc8ce-56049d5e-d6a1557b
I0503 17:14:17.551869 126295029690368 k8s_base_runner.py:873] Deleting deployment psm-grpc-client
I0503 17:14:57.724764 126295029690368 k8s_base_runner.py:888] Deployment psm-grpc-client deleted
I0503 17:14:57.725104 126295029690368 k8s_base_runner.py:471] Revoking roles/iam.workloadIdentityUser from serviceAccount:grpc-testing.svc.id.goog[psm-interop-client-20240503-1708-izpqb/psm-grpc-client] for GCP Service Account xds-k8s-interop-tests@grpc-testing.iam.gserviceaccount.com
I0503 17:14:58.033877 126295029690368 k8s_base_runner.py:907] Deleting service account psm-grpc-client
I0503 17:14:58.081979 126295029690368 k8s_base_runner.py:921] Service account psm-grpc-client deleted
I0503 17:14:58.082170 126295029690368 k8s_base_runner.py:924] Deleting namespace psm-interop-client-20240503-1708-izpqb
I0503 17:15:28.175134 126295029690368 k8s_base_runner.py:941] Namespace psm-interop-client-20240503-1708-izpqb deleted
I0503 17:15:28.175435 126295029690368 k8s_base_runner.py:873] Deleting deployment psm-grpc-server
I0503 17:15:38.261009 126295029690368 k8s_base_runner.py:888] Deployment psm-grpc-server deleted
I0503 17:15:38.261321 126295029690368 k8s_base_runner.py:891] Deleting service psm-grpc-server
I0503 17:15:39.325872 126295029690368 k8s_base_runner.py:904] Service psm-grpc-server deleted
I0503 17:15:39.326125 126295029690368 k8s_base_runner.py:471] Revoking roles/iam.workloadIdentityUser from serviceAccount:grpc-testing.svc.id.goog[psm-interop-server-20240503-1708-izpqb/psm-grpc-server] for GCP Service Account xds-k8s-interop-tests@grpc-testing.iam.gserviceaccount.com
I0503 17:15:40.862525 126295029690368 k8s_base_runner.py:907] Deleting service account psm-grpc-server
I0503 17:15:41.019459 126295029690368 k8s_base_runner.py:921] Service account psm-grpc-server deleted
I0503 17:15:41.019693 126295029690368 k8s_base_runner.py:924] Deleting namespace psm-interop-server-20240503-1708-izpqb
I0503 17:16:41.177618 126295029690368 k8s_base_runner.py:941] Namespace psm-interop-server-20240503-1708-izpqb deleted
I0503 17:16:41.178132 126295029690368 xds_k8s_testcase.py:790] ----- Test client/server logs -----
I0503 17:16:41.178465 126295029690368 k8s_base_runner.py:1152] GCP Logs Explorer link to psm-grpc-client-u6xgh0lz:
https://console.cloud.google.com/logs/query;query=resource.type%3D%22k8s_container%22%0Aresource.labels.project_id%3D%22grpc-testing%22%0Aresource.labels.container_name%3D%22psm-grpc-client%22%0Aresource.labels.namespace_name%3D%22psm-interop-client-20240503-1708-izpqb%22%0Alabels.%22k8s-pod%2Fdeployment_id%22%3D%22psm-grpc-client-u6xgh0lz%22;timeRange=2024-05-03T17:11:22.566817Z%2F2024-05-03T17:15:28.175390Z;cursorTimestamp=2024-05-03T17:11:47.462792Z?project=grpc-testing
I0503 17:16:41.178612 126295029690368 k8s_base_runner.py:1152] GCP Logs Explorer link to psm-grpc-server-xqv2h1yq:
https://console.cloud.google.com/logs/query;query=resource.type%3D%22k8s_container%22%0Aresource.labels.project_id%3D%22grpc-testing%22%0Aresource.labels.container_name%3D%22psm-grpc-server%22%0Aresource.labels.namespace_name%3D%22psm-interop-server-20240503-1708-izpqb%22%0Alabels.%22k8s-pod%2Fdeployment_id%22%3D%22psm-grpc-server-xqv2h1yq%22;timeRange=2024-05-03T17:09:46.889044Z%2F2024-05-03T17:16:41.177895Z;cursorTimestamp=2024-05-03T17:09:55.778058Z?project=grpc-testing
[ OK ] AppNetTest.test_ping_pong
I0503 17:16:41.178935 126295029690368 base_testcase.py:67] ----- PSM Test Case PASSED: AppNetTest.test_ping_pong -----

----------------------------------------------------------------------
Ran 1 test in 480.129s

OK
+ [17:16:41 UTC] Cmd finished: python
+ [17:16:41 UTC] Finished lb suite test: app_net_test

+ [17:16:41 UTC] Running lb suite test: baseline_test
+ [17:16:41 UTC] Test driver flags for baseline_test:
--flagfile=config/grpc-testing.cfg
--kube_context=gke_grpc-testing_us-central1-a_psm-interop-lb-primary
--force_cleanup
--collect_app_logs
--log_dir=/tmpfs/src/artifacts/grpc/node/master/xds_k8s_lb/baseline_test
--xml_output_file=/tmpfs/src/artifacts/grpc/node/master/xds_k8s_lb/baseline_test/sponge_log.xml
--testing_version=dev-master
--client_image=us-docker.pkg.dev/grpc-testing/psm-interop/node-client:9f9f6e72ef080a731acd4660689df596d78a73ab
--server_image=us-docker.pkg.dev/grpc-testing/psm-interop/java-server:canonical
--secondary_kube_context=gke_grpc-testing_us-west1-b_psm-interop-lb-secondary

+ [17:16:41 UTC] python -m tests.baseline_test --flagfile=config/grpc-testing.cfg --kube_context=gke_grpc-testing_us-central1-a_psm-interop-lb-primary --force_cleanup --collect_app_logs --log_dir=/tmpfs/src/artifacts/grpc/node/master/xds_k8s_lb/baseline_test --xml_output_file=/tmpfs/src/artifacts/grpc/node/master/xds_k8s_lb/baseline_test/sponge_log.xml --testing_version=dev-master --client_image=us-docker.pkg.dev/grpc-testing/psm-interop/node-client:9f9f6e72ef080a731acd4660689df596d78a73ab --server_image=us-docker.pkg.dev/grpc-testing/psm-interop/java-server:canonical --secondary_kube_context=gke_grpc-testing_us-west1-b_psm-interop-lb-secondary
Running tests under Python 3.10.12: /tmp/tmp.D6gt45aunY/psm-interop/venv/bin/python
I0503 17:16:42.715105 131344721338368 xds_k8s_testcase.py:165] ----- Testing BaselineTest -----
I0503 17:16:42.715257 131344721338368 xds_k8s_testcase.py:166] Logs timezone: UTC
I0503 17:16:42.715502 131344721338368 xds_k8s_testcase.py:111] Detected language and version: TestConfig(client_lang='node', server_lang='java', version='dev-master')
I0503 17:16:42.734340 131344721338368 k8s.py:243] Using kubernetes context "gke_grpc-testing_us-central1-a_psm-interop-lb-primary", active host: https://35.238.98.54
I0503 17:16:42.751356 131344721338368 k8s.py:243] Using kubernetes context "gke_grpc-testing_us-west1-b_psm-interop-lb-secondary", active host: https://35.227.140.120
[ RUN ] BaselineTest.test_traffic_director_grpc_setup
I0503 17:16:42.756315 131344721338368 xds_k8s_testcase.py:747] Test run resource prefix: psm-interop, suffix: 20240503-1716-sc0uf
I0503 17:16:43.098394 131344721338368 logs.py:31] Log root dir: /tmpfs/src/artifacts/grpc/node/master/xds_k8s_lb/baseline_test
I0503 17:16:43.480673 131344721338368 xds_k8s_testcase.py:742] Found unused xds port: 28327
I0503 17:16:43.481019 131344721338368 xds_k8s_testcase.py:308] --- Starting subTest BaselineTest.test_traffic_director_grpc_setup.0_create_health_check ---
I0503 17:16:43.481111 131344721338368 traffic_director.py:197] Creating GRPC Health Check "psm-interop-health-check-20240503-1716-sc0uf"
I0503 17:16:43.484324 131344721338368 compute.py:537] Creating compute resource:
---
grpcHealthCheck:
portSpecification: USE_SERVING_PORT
name: psm-interop-health-check-20240503-1716-sc0uf
type: GRPC
...

I0503 17:16:44.112119 131344721338368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756603544-6178fe23b035b-98ad5ad2-40eb256a
I0503 17:16:46.439677 131344721338368 xds_k8s_testcase.py:313] --- Finished subTest BaselineTest.test_traffic_director_grpc_setup.0_create_health_check ---
I0503 17:16:46.439882 131344721338368 xds_k8s_testcase.py:308] --- Starting subTest BaselineTest.test_traffic_director_grpc_setup.1_create_backend_service ---
I0503 17:16:46.439956 131344721338368 traffic_director.py:224] Creating GRPC Backend Service "psm-interop-backend-service-20240503-1716-sc0uf"
I0503 17:16:46.447665 131344721338368 compute.py:537] Creating compute resource:
---
healthChecks:
- https://www.googleapis.com/compute/v1/projects/grpc-testing/global/healthChecks/psm-interop-health-check-20240503-1716-sc0uf
loadBalancingScheme: INTERNAL_SELF_MANAGED
name: psm-interop-backend-service-20240503-1716-sc0uf
protocol: GRPC
...

I0503 17:16:47.392168 131344721338368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756606449-6178fe26758fb-3e0e1663-97aeb5ba
I0503 17:17:38.909890 131344721338368 xds_k8s_testcase.py:313] --- Finished subTest BaselineTest.test_traffic_director_grpc_setup.1_create_backend_service ---
I0503 17:17:38.910062 131344721338368 xds_k8s_testcase.py:308] --- Starting subTest BaselineTest.test_traffic_director_grpc_setup.2_create_url_map ---
I0503 17:17:38.910130 131344721338368 traffic_director.py:485] Creating URL map "psm-interop-url-map-20240503-1716-sc0uf": psm-grpc-server:28327 -> psm-interop-backend-service-20240503-1716-sc0uf
I0503 17:17:38.921435 131344721338368 compute.py:537] Creating compute resource:
---
defaultService: https://www.googleapis.com/compute/v1/projects/grpc-testing/global/backendServices/psm-interop-backend-service-20240503-1716-sc0uf
hostRules:
- hosts:
- psm-grpc-server:28327
pathMatcher: psm-interop-path-matcher-20240503-1716-sc0uf
name: psm-interop-url-map-20240503-1716-sc0uf
pathMatchers:
- defaultService: https://www.googleapis.com/compute/v1/projects/grpc-testing/global/backendServices/psm-interop-backend-service-20240503-1716-sc0uf
name: psm-interop-path-matcher-20240503-1716-sc0uf
...

I0503 17:17:39.682958 131344721338368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756658928-6178fe58819f6-5b962c95-4c93a812
I0503 17:17:41.991909 131344721338368 xds_k8s_testcase.py:313] --- Finished subTest BaselineTest.test_traffic_director_grpc_setup.2_create_url_map ---
I0503 17:17:41.992087 131344721338368 xds_k8s_testcase.py:308] --- Starting subTest BaselineTest.test_traffic_director_grpc_setup.3_create_target_proxy ---
I0503 17:17:41.992164 131344721338368 traffic_director.py:584] Creating target psm-interop-target-proxy-20240503-1716-sc0uf proxy "GRPC" to URL map psm-interop-url-map-20240503-1716-sc0uf
I0503 17:17:41.994077 131344721338368 compute.py:537] Creating compute resource:
---
name: psm-interop-target-proxy-20240503-1716-sc0uf
url_map: https://www.googleapis.com/compute/v1/projects/grpc-testing/global/urlMaps/psm-interop-url-map-20240503-1716-sc0uf
validate_for_proxyless: true
...

I0503 17:17:42.791123 131344721338368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756662001-6178fe5b6fe15-5a22ccd3-59e031ce
I0503 17:17:45.087878 131344721338368 xds_k8s_testcase.py:313] --- Finished subTest BaselineTest.test_traffic_director_grpc_setup.3_create_target_proxy ---
I0503 17:17:45.088094 131344721338368 xds_k8s_testcase.py:308] --- Starting subTest BaselineTest.test_traffic_director_grpc_setup.4_create_forwarding_rule ---
I0503 17:17:45.088176 131344721338368 traffic_director.py:660] Creating forwarding rule "psm-interop-forwarding-rule-20240503-1716-sc0uf" in network "default-vpc": 0.0.0.0:28327 -> https://www.googleapis.com/compute/v1/projects/grpc-testing/global/targetGrpcProxies/psm-interop-target-proxy-20240503-1716-sc0uf
I0503 17:17:45.090764 131344721338368 compute.py:537] Creating compute resource:
---
IPAddress: 0.0.0.0
loadBalancingScheme: INTERNAL_SELF_MANAGED
name: psm-interop-forwarding-rule-20240503-1716-sc0uf
network: global/networks/default-vpc
portRange: 28327
target: https://www.googleapis.com/compute/v1/projects/grpc-testing/global/targetGrpcProxies/psm-interop-target-proxy-20240503-1716-sc0uf
...

I0503 17:17:46.094794 131344721338368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756665092-6178fe5e62b60-6acc451d-3e919538
I0503 17:18:33.185024 131344721338368 xds_k8s_testcase.py:313] --- Finished subTest BaselineTest.test_traffic_director_grpc_setup.4_create_forwarding_rule ---
I0503 17:18:33.185220 131344721338368 xds_k8s_testcase.py:308] --- Starting subTest BaselineTest.test_traffic_director_grpc_setup.5_start_test_server ---
I0503 17:18:33.185317 131344721338368 k8s_xds_server_runner.py:186] Deploying xDS test server "psm-grpc-server" to k8s namespace psm-interop-server-20240503-1716-sc0uf: test_port=8080 maintenance_port=8080 secure_mode=False replica_count=1
I0503 17:18:33.185595 131344721338368 k8s_base_runner.py:1152] GCP Logs Explorer link to psm-grpc-server:
https://console.cloud.google.com/logs/query;query=resource.type%3D%22k8s_container%22%0Aresource.labels.project_id%3D%22grpc-testing%22%0Aresource.labels.container_name%3D%22psm-grpc-server%22%0Aresource.labels.namespace_name%3D%22psm-interop-server-20240503-1716-sc0uf%22;timeRange=2024-05-03T17:18:33.185420Z%2F2024-05-03T17:48:33.185420Z?project=grpc-testing
I0503 17:18:33.191051 131344721338368 k8s_base_runner.py:347] Rendered template kubernetes-manifests/namespace.yaml:
---
apiVersion: v1
kind: Namespace
metadata:
name: psm-interop-server-20240503-1716-sc0uf
labels:
name: psm-interop-server-20240503-1716-sc0uf
owner: xds-k8s-interop-test
...

I0503 17:18:33.231922 131344721338368 k8s_base_runner.py:367] Namespace psm-interop-server-20240503-1716-sc0uf created
I0503 17:18:33.234822 131344721338368 k8s_base_runner.py:347] Rendered template kubernetes-manifests/server.service.yaml:
---
apiVersion: v1
kind: Service
metadata:
name: psm-grpc-server
namespace: psm-interop-server-20240503-1716-sc0uf
labels:
owner: xds-k8s-interop-test
annotations:
cloud.google.com/neg: '{"exposed_ports": {"8080":{"name":"psm-interop-server-20240503-1716-sc0uf-psm-grpc-server"}}}'
spec:
type: ClusterIP
selector:
app: psm-grpc-server
ports:
- port: 8080
protocol: TCP
targetPort: 8080
...

I0503 17:18:33.267095 131344721338368 k8s_base_runner.py:367] Service psm-grpc-server created
I0503 17:18:33.267285 131344721338368 k8s_base_runner.py:1054] Waiting for 'cloud.google.com/neg-status' annotation for a NEG at port 8080 to be assigned to Kubernetes Service psm-grpc-server in namespace psm-interop-server-20240503-1716-sc0uf
I0503 17:18:33.297872 131344721338368 k8s_base_runner.py:1068] Detected 'cloud.google.com/neg-status' annotation for Kubernetes Service psm-grpc-server, namespace psm-interop-server-20240503-1716-sc0uf: neg_name=psm-interop-server-20240503-1716-sc0uf-psm-grpc-server, port=8080, zones=['us-central1-a']
I0503 17:18:33.298045 131344721338368 k8s_base_runner.py:452] Granting roles/iam.workloadIdentityUser to serviceAccount:grpc-testing.svc.id.goog[psm-interop-server-20240503-1716-sc0uf/psm-grpc-server] for GCP Service Account xds-k8s-interop-tests@grpc-testing.iam.gserviceaccount.com
I0503 17:18:33.743885 131344721338368 k8s_base_runner.py:347] Rendered template kubernetes-manifests/service-account.yaml:
---
apiVersion: v1
kind: ServiceAccount
metadata:
name: psm-grpc-server
namespace: psm-interop-server-20240503-1716-sc0uf
labels:
owner: xds-k8s-interop-test
annotations:
iam.gke.io/gcp-service-account: xds-k8s-interop-tests@grpc-testing.iam.gserviceaccount.com
...

I0503 17:18:33.769147 131344721338368 k8s_base_runner.py:367] ServiceAccount psm-grpc-server created
I0503 17:18:33.784995 131344721338368 k8s_base_runner.py:347] Rendered template kubernetes-manifests/server.deployment.yaml:
---
apiVersion: apps/v1
kind: Deployment
metadata:
name: psm-grpc-server
namespace: psm-interop-server-20240503-1716-sc0uf
labels:
app: psm-grpc-server
deployment_id: psm-grpc-server-8sct8wo3
owner: xds-k8s-interop-test
spec:
replicas: 1
selector:
matchLabels:
app: psm-grpc-server
deployment_id: psm-grpc-server-8sct8wo3
template:
metadata:
labels:
app: psm-grpc-server
deployment_id: psm-grpc-server-8sct8wo3
owner: xds-k8s-interop-test
spec:
serviceAccountName: psm-grpc-server
containers:
- name: psm-grpc-server
image: us-docker.pkg.dev/grpc-testing/psm-interop/java-server:canonical
imagePullPolicy: Always
startupProbe:
tcpSocket:
port: 8080
periodSeconds: 3
failureThreshold: 1000

args:
- "--port=8080"
ports:
- containerPort: 8080
env:
- name: GRPC_XDS_BOOTSTRAP
value: "/tmp/grpc-xds/td-grpc-bootstrap.json"
- name: GRPC_XDS_EXPERIMENTAL_V3_SUPPORT
value: "true"
- name: GRPC_EXPERIMENTAL_XDS_ENABLE_OVERRIDE_HOST
value: "true"
volumeMounts:
- mountPath: /tmp/grpc-xds/
name: grpc-td-conf
readOnly: true

resources:
limits:
cpu: 800m
memory: 512Mi
requests:
cpu: 100m
memory: 512Mi
initContainers:
- name: grpc-td-init
image: us-docker.pkg.dev/grpc-testing/trafficdirector/td-grpc-bootstrap:2bf1b5ed00f852ffea8d24759c6fa673acc9ef10
imagePullPolicy: Always
args:
- "--output=/tmp/bootstrap/td-grpc-bootstrap.json"
- "--vpc-network-name=default-vpc"
- "--node-metadata=app=psm-interop-server-20240503-1716-sc0uf-psm-grpc-server"
resources:
limits:
cpu: 100m
memory: 100Mi
requests:
cpu: 10m
memory: 100Mi
volumeMounts:
- mountPath: /tmp/bootstrap/
name: grpc-td-conf

volumes:
- name: grpc-td-conf
emptyDir:
medium: Memory

...

I0503 17:18:33.821244 131344721338368 k8s_base_runner.py:367] Deployment psm-grpc-server created
I0503 17:18:33.821459 131344721338368 k8s_base_runner.py:962] Waiting for deployment psm-grpc-server to initialize 1 pod(s)
I0503 17:18:34.869155 131344721338368 k8s_base_runner.py:972] Deployment psm-grpc-server initialized 1 pod(s): ['psm-grpc-server-5d479d7874-zp9wd']
I0503 17:18:34.869462 131344721338368 k8s_base_runner.py:982] Waiting for pod psm-grpc-server-5d479d7874-zp9wd to start
I0503 17:18:37.952551 131344721338368 k8s_base_runner.py:985] Pod psm-grpc-server-5d479d7874-zp9wd ready, IP: 10.120.17.58
I0503 17:18:37.952845 131344721338368 k8s_base_runner.py:1030] Enabling log collection from pod psm-grpc-server-5d479d7874-zp9wd to baseline_test/test_app_logs/psm-interop-server-20240503-1716-sc0uf_psm-grpc-server-5d479d7874-zp9wd.log
I0503 17:18:37.953493 131344625034816 k8s_log_collector.py:71] [ns/psm-interop-server-20240503-1716-sc0uf] Starting log collection thread 131344625034816 for psm-grpc-server-5d479d7874-zp9wd
I0503 17:18:37.953861 131344721338368 k8s_base_runner.py:944] Waiting for deployment psm-grpc-server to report 1 available replica(s)
I0503 17:18:42.029202 131344721338368 k8s_base_runner.py:953] Deployment psm-grpc-server has 1 replicas available
I0503 17:18:42.029571 131344721338368 xds_k8s_testcase.py:313] --- Finished subTest BaselineTest.test_traffic_director_grpc_setup.5_start_test_server ---
I0503 17:18:42.029656 131344721338368 xds_k8s_testcase.py:308] --- Starting subTest BaselineTest.test_traffic_director_grpc_setup.6_add_server_backends_to_backend_service ---
I0503 17:18:42.036332 131344721338368 traffic_director.py:262] Waiting for Network Endpoint Groups to load endpoints.
I0503 17:18:42.132065 131344721338368 traffic_director.py:265] Loaded NEG "psm-interop-server-20240503-1716-sc0uf-psm-grpc-server" in zone us-central1-a
I0503 17:18:42.132264 131344721338368 traffic_director.py:283] Adding backends to Backend Service psm-interop-backend-service-20240503-1716-sc0uf: {ComputeV1.ZonalGcpResource(name='psm-interop-server-20240503-1716-sc0uf-psm-grpc-server', url='https://www.googleapis.com/compute/v1/projects/grpc-testing/zones/us-central1-a/networkEndpointGroups/psm-interop-server-20240503-1716-sc0uf-psm-grpc-server', zone='us-central1-a')}
I0503 17:18:42.136818 131344721338368 compute.py:544] Patching compute resource:
---
backends:
- balancingMode: RATE
group: https://www.googleapis.com/compute/v1/projects/grpc-testing/zones/us-central1-a/networkEndpointGroups/psm-interop-server-20240503-1716-sc0uf-psm-grpc-server
maxRatePerEndpoint: 5
...

I0503 17:18:42.864511 131344721338368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756722139-6178fe94ca17a-c9468885-8c09c693
I0503 17:19:40.522979 131344721338368 traffic_director.py:300] Waiting for Backend Service psm-interop-backend-service-20240503-1716-sc0uf to report all backends healthy: ['psm-interop-server-20240503-1716-sc0uf-psm-grpc-server']
I0503 17:19:44.843869 131344721338368 compute.py:494] Backend psm-interop-server-20240503-1716-sc0uf-psm-grpc-server in zone us-central1-a reported healthy
I0503 17:19:48.848570 131344721338368 xds_k8s_testcase.py:313] --- Finished subTest BaselineTest.test_traffic_director_grpc_setup.6_add_server_backends_to_backend_service ---
I0503 17:19:48.848751 131344721338368 xds_k8s_testcase.py:308] --- Starting subTest BaselineTest.test_traffic_director_grpc_setup.7_start_test_client ---
I0503 17:19:48.848840 131344721338368 k8s_xds_client_runner.py:119] Deploying xDS test client "psm-grpc-client" to k8s namespace psm-interop-client-20240503-1716-sc0uf: server_target=xds:///psm-grpc-server:28327 rpc=UnaryCall qps=25 metadata='' secure_mode=False print_response=False
I0503 17:19:48.849076 131344721338368 k8s_base_runner.py:1152] GCP Logs Explorer link to psm-grpc-client:
https://console.cloud.google.com/logs/query;query=resource.type%3D%22k8s_container%22%0Aresource.labels.project_id%3D%22grpc-testing%22%0Aresource.labels.container_name%3D%22psm-grpc-client%22%0Aresource.labels.namespace_name%3D%22psm-interop-client-20240503-1716-sc0uf%22;timeRange=2024-05-03T17:19:48.848933Z%2F2024-05-03T17:49:48.848933Z?project=grpc-testing
I0503 17:19:48.851073 131344721338368 k8s_base_runner.py:347] Rendered template kubernetes-manifests/namespace.yaml:
---
apiVersion: v1
kind: Namespace
metadata:
name: psm-interop-client-20240503-1716-sc0uf
labels:
name: psm-interop-client-20240503-1716-sc0uf
owner: xds-k8s-interop-test
...

I0503 17:19:48.891372 131344721338368 k8s_base_runner.py:367] Namespace psm-interop-client-20240503-1716-sc0uf created
I0503 17:19:48.891574 131344721338368 k8s_base_runner.py:452] Granting roles/iam.workloadIdentityUser to serviceAccount:grpc-testing.svc.id.goog[psm-interop-client-20240503-1716-sc0uf/psm-grpc-client] for GCP Service Account xds-k8s-interop-tests@grpc-testing.iam.gserviceaccount.com
I0503 17:19:49.256192 131344721338368 k8s_base_runner.py:347] Rendered template kubernetes-manifests/service-account.yaml:
---
apiVersion: v1
kind: ServiceAccount
metadata:
name: psm-grpc-client
namespace: psm-interop-client-20240503-1716-sc0uf
labels:
owner: xds-k8s-interop-test
annotations:
iam.gke.io/gcp-service-account: xds-k8s-interop-tests@grpc-testing.iam.gserviceaccount.com
...

I0503 17:19:49.294146 131344721338368 k8s_base_runner.py:367] ServiceAccount psm-grpc-client created
I0503 17:19:49.303751 131344721338368 k8s_base_runner.py:347] Rendered template kubernetes-manifests/client.deployment.yaml:
---
apiVersion: apps/v1
kind: Deployment
metadata:
name: psm-grpc-client
namespace: psm-interop-client-20240503-1716-sc0uf
labels:
app: psm-grpc-client
deployment_id: psm-grpc-client-iws4fk03
owner: xds-k8s-interop-test
spec:
replicas: 1
selector:
matchLabels:
app: psm-grpc-client
deployment_id: psm-grpc-client-iws4fk03
template:
metadata:
labels:
app: psm-grpc-client
deployment_id: psm-grpc-client-iws4fk03
owner: xds-k8s-interop-test
spec:
serviceAccountName: psm-grpc-client
containers:
- name: psm-grpc-client
image: us-docker.pkg.dev/grpc-testing/psm-interop/node-client:9f9f6e72ef080a731acd4660689df596d78a73ab
imagePullPolicy: Always
startupProbe:
tcpSocket:
port: 8079
periodSeconds: 3
failureThreshold: 1000
args:
- "--server=xds:///psm-grpc-server:28327"
- "--stats_port=8079"
- "--qps=25"
- "--rpc=UnaryCall"
- "--metadata="
- "--print_response=False"
ports:
- containerPort: 8079
env:
- name: GRPC_XDS_BOOTSTRAP
value: "/tmp/grpc-xds/td-grpc-bootstrap.json"
- name: GRPC_XDS_EXPERIMENTAL_ENABLE_RING_HASH
value: "true"
- name: GRPC_XDS_EXPERIMENTAL_ENABLE_RETRY
value: "true"
- name: GRPC_EXPERIMENTAL_ENABLE_OUTLIER_DETECTION
value: "true"
- name: GRPC_EXPERIMENTAL_XDS_CUSTOM_LB_CONFIG
value: "true"
- name: GRPC_EXPERIMENTAL_XDS_ENABLE_OVERRIDE_HOST
value: "true"
volumeMounts:
- mountPath: /tmp/grpc-xds/
name: grpc-td-conf
readOnly: true
resources:
limits:
cpu: 800m
memory: 512Mi
requests:
cpu: 100m
memory: 512Mi
initContainers:
- name: grpc-td-init
image: us-docker.pkg.dev/grpc-testing/trafficdirector/td-grpc-bootstrap:2bf1b5ed00f852ffea8d24759c6fa673acc9ef10
imagePullPolicy: Always
args:
- "--output=/tmp/bootstrap/td-grpc-bootstrap.json"
- "--vpc-network-name=default-vpc"
resources:
limits:
cpu: 100m
memory: 100Mi
requests:
cpu: 10m
memory: 100Mi
volumeMounts:
- mountPath: /tmp/bootstrap/
name: grpc-td-conf
volumes:
- name: grpc-td-conf
emptyDir:
medium: Memory
...

I0503 17:19:49.340873 131344721338368 k8s_base_runner.py:367] Deployment psm-grpc-client created
I0503 17:19:49.341084 131344721338368 k8s_base_runner.py:962] Waiting for deployment psm-grpc-client to initialize 1 pod(s)
I0503 17:19:50.383276 131344721338368 k8s_base_runner.py:972] Deployment psm-grpc-client initialized 1 pod(s): ['psm-grpc-client-75d64d5df4-8vw9l']
I0503 17:19:50.383504 131344721338368 k8s_base_runner.py:982] Waiting for pod psm-grpc-client-75d64d5df4-8vw9l to start
I0503 17:20:05.603379 131344721338368 k8s_base_runner.py:985] Pod psm-grpc-client-75d64d5df4-8vw9l ready, IP: 10.120.11.40
I0503 17:20:05.603634 131344721338368 k8s_base_runner.py:1030] Enabling log collection from pod psm-grpc-client-75d64d5df4-8vw9l to baseline_test/test_app_logs/psm-interop-client-20240503-1716-sc0uf_psm-grpc-client-75d64d5df4-8vw9l.log
I0503 17:20:05.604226 131344616642112 k8s_log_collector.py:71] [ns/psm-interop-client-20240503-1716-sc0uf] Starting log collection thread 131344616642112 for psm-grpc-client-75d64d5df4-8vw9l
I0503 17:20:05.604536 131344721338368 k8s_base_runner.py:944] Waiting for deployment psm-grpc-client to report 1 available replica(s)
I0503 17:20:08.666417 131344721338368 k8s_base_runner.py:953] Deployment psm-grpc-client has 1 replicas available
I0503 17:20:08.666846 131344721338368 client_app.py:292] [psm-grpc-client-75d64d5df4-8vw9l] ADS: Waiting for active calls to xDS control plane to trafficdirector.googleapis.com:443
I0503 17:20:08.701654 131344721338368 client_app.py:320] [psm-grpc-client-75d64d5df4-8vw9l] xDS control plane channel:
I0503 17:20:18.722964 131344721338368 client_app.py:320] [psm-grpc-client-75d64d5df4-8vw9l] xDS control plane channel:
I0503 17:20:28.743955 131344721338368 client_app.py:320] [psm-grpc-client-75d64d5df4-8vw9l] xDS control plane channel:
I0503 17:20:38.765029 131344721338368 client_app.py:320] [psm-grpc-client-75d64d5df4-8vw9l] xDS control plane channel:
I0503 17:20:48.786203 131344721338368 client_app.py:320] [psm-grpc-client-75d64d5df4-8vw9l] xDS control plane channel:
I0503 17:21:04.812136 131344721338368 client_app.py:320] [psm-grpc-client-75d64d5df4-8vw9l] xDS control plane channel:
I0503 17:21:29.846349 131344721338368 client_app.py:320] [psm-grpc-client-75d64d5df4-8vw9l] xDS control plane channel:
I0503 17:21:31.854605 131344721338368 client_app.py:331] [psm-grpc-client-75d64d5df4-8vw9l] Detected active calls to xDS control plane trafficdirector.googleapis.com:443, channel:
I0503 17:21:31.854977 131344721338368 client_app.py:302] [psm-grpc-client-75d64d5df4-8vw9l] ADS: Detected active calls to xDS control plane trafficdirector.googleapis.com:443
I0503 17:21:31.855271 131344721338368 client_app.py:252] [psm-grpc-client-75d64d5df4-8vw9l] Waiting to report a READY channel to xds:///psm-grpc-server:28327
I0503 17:21:31.858313 131344721338368 client_app.py:379] [psm-grpc-client-75d64d5df4-8vw9l] Server channel:
I0503 17:21:31.864548 131344721338368 client_app.py:392] [psm-grpc-client-75d64d5df4-8vw9l] Found subchannel in state READY:
I0503 17:21:31.864855 131344721338368 client_app.py:263] [psm-grpc-client-75d64d5df4-8vw9l] Channel to xds:///psm-grpc-server:28327 transitioned to state READY:
I0503 17:21:31.864985 131344721338368 xds_k8s_testcase.py:313] --- Finished subTest BaselineTest.test_traffic_director_grpc_setup.7_start_test_client ---
I0503 17:21:31.865061 131344721338368 xds_k8s_testcase.py:308] --- Starting subTest BaselineTest.test_traffic_director_grpc_setup.8_test_client_xds_config_exists ---
I0503 17:21:31.865272 131344721338368 grpc.py:75] [psm-grpc-client-75d64d5df4-8vw9l:8079] >> RPC ClientStatusDiscoveryService.FetchClientStatus(request=ClientStatusRequest({}), timeout=30, wait_for_ready=True)
I0503 17:21:31.878093 131344721338368 xds_k8s_testcase.py:313] --- Finished subTest BaselineTest.test_traffic_director_grpc_setup.8_test_client_xds_config_exists ---
I0503 17:21:31.878282 131344721338368 xds_k8s_testcase.py:308] --- Starting subTest BaselineTest.test_traffic_director_grpc_setup.9_test_server_received_rpcs_from_test_client ---
I0503 17:21:31.878491 131344721338368 grpc.py:75] [psm-grpc-client-75d64d5df4-8vw9l:8079] >> RPC LoadBalancerStatsService.GetClientStats(request=LoadBalancerStatsRequest({'numRpcs': 100, 'timeoutSec': 1200}), timeout=1200, wait_for_ready=True)
I0503 17:21:35.900407 131344721338368 xds_k8s_testcase.py:678] [psm-grpc-client-75d64d5df4-8vw9l] << Received LoadBalancerStatsResponse:
num_failures: 0
rpcs_by_peer:
psm-grpc-server-5d479d7874-zp9wd: 100
rpcs_by_method:
UnaryCall:
psm-grpc-server-5d479d7874-zp9wd: 100

I0503 17:21:35.900628 131344721338368 xds_k8s_testcase.py:313] --- Finished subTest BaselineTest.test_traffic_director_grpc_setup.9_test_server_received_rpcs_from_test_client ---
I0503 17:21:35.900727 131344721338368 xds_k8s_testcase.py:766] ----- TestMethod BaselineTest.test_traffic_director_grpc_setup teardown -----
I0503 17:21:35.941983 131344721338368 traffic_director.py:680] Deleting Forwarding rule "psm-interop-forwarding-rule-20240503-1716-sc0uf"
I0503 17:21:36.495295 131344721338368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756895950-6178ff3a8c743-20823f2e-41dbc098
I0503 17:21:59.871150 131344721338368 traffic_director.py:719] Deleting alternative Forwarding rule "psm-interop-forwarding-rule-alt-20240503-1716-sc0uf"
I0503 17:21:59.977256 131344721338368 traffic_director.py:611] Deleting HTTP Target proxy "psm-interop-target-proxy-20240503-1716-sc0uf"
I0503 17:22:00.553817 131344721338368 traffic_director.py:599] Deleting Target GRPC proxy "psm-interop-target-proxy-20240503-1716-sc0uf"
I0503 17:22:01.219752 131344721338368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756920557-6178ff5204012-7960e873-d4940d19
I0503 17:22:03.440375 131344721338368 traffic_director.py:639] Deleting alternative Target GRPC proxy "psm-interop-target-proxy-alt-20240503-1716-sc0uf"
I0503 17:22:03.628315 131344721338368 traffic_director.py:531] Deleting URL Map "psm-interop-url-map-20240503-1716-sc0uf"
I0503 17:22:04.272056 131344721338368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756923638-6178ff54f4323-1b81e33b-2a75c3a7
I0503 17:22:06.453405 131344721338368 traffic_director.py:567] Deleting alternative URL Map "psm-interop-url-map-alt-20240503-1716-sc0uf"
I0503 17:22:06.775542 131344721338368 traffic_director.py:249] Deleting Backend Service "psm-interop-backend-service-20240503-1716-sc0uf"
I0503 17:22:07.333914 131344721338368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756926785-6178ff57f48f8-d656959b-46a46df7
I0503 17:22:35.548806 131344721338368 traffic_director.py:338] Deleting Alternative Backend Service "psm-interop-backend-service-alt-20240503-1716-sc0uf"
I0503 17:22:35.885951 131344721338368 traffic_director.py:411] Deleting Affinity Backend Service "psm-interop-backend-service-affinity-20240503-1716-sc0uf"
I0503 17:22:36.197254 131344721338368 traffic_director.py:208] Deleting Health Check "psm-interop-health-check-20240503-1716-sc0uf"
I0503 17:22:36.785881 131344721338368 compute.py:614] Waiting 600 sec for compute operation id: operation-1714756956272-6178ff7413879-5cd1e763-e2305f27
I0503 17:22:41.242136 131344721338368 k8s_base_runner.py:873] Deleting deployment psm-grpc-client
I0503 17:23:21.397989 131344721338368 k8s_base_runner.py:888] Deployment psm-grpc-client deleted
I0503 17:23:21.398247 131344721338368 k8s_base_runner.py:471] Revoking roles/iam.workloadIdentityUser from serviceAccount:grpc-testing.svc.id.goog[psm-interop-client-20240503-1716-sc0uf/psm-grpc-client] for GCP Service Account xds-k8s-interop-tests@grpc-testing.iam.gserviceaccount.com
I0503 17:23:21.737758 131344721338368 k8s_base_runner.py:907] Deleting service account psm-grpc-client
I0503 17:23:21.797199 131344721338368 k8s_base_runner.py:921] Service account psm-grpc-client deleted
I0503 17:23:21.797443 131344721338368 k8s_base_runner.py:924] Deleting namespace psm-interop-client-20240503-1716-sc0uf
I0503 17:23:51.897297 131344721338368 k8s_base_runner.py:941] Namespace psm-interop-client-20240503-1716-sc0uf deleted
I0503 17:23:51.897527 131344721338368 k8s_base_runner.py:873] Deleting deployment psm-grpc-server
I0503 17:24:01.986074 131344721338368 k8s_base_runner.py:888] Deployment psm-grpc-server deleted
I0503 17:24:01.986332 131344721338368 k8s_base_runner.py:891] Deleting service psm-grpc-server
I0503 17:24:03.040078 131344721338368 k8s_base_runner.py:904] Service psm-grpc-server deleted
I0503 17:24:03.040288 131344721338368 k8s_base_runner.py:471] Revoking roles/iam.workloadIdentityUser from serviceAccount:grpc-testing.svc.id.goog[psm-interop-server-20240503-1716-sc0uf/psm-grpc-server] for GCP Service Account xds-k8s-interop-tests@grpc-testing.iam.gserviceaccount.com
I0503 17:24:03.548567 131344721338368 k8s_base_runner.py:907] Deleting service account psm-grpc-server
I0503 17:24:03.591274 131344721338368 k8s_base_runner.py:921] Service account psm-grpc-server deleted
I0503 17:24:03.591461 131344721338368 k8s_base_runner.py:924] Deleting namespace psm-interop-server-20240503-1716-sc0uf
I0503 17:25:03.747286 131344721338368 k8s_base_runner.py:941] Namespace psm-interop-server-20240503-1716-sc0uf deleted
I0503 17:25:03.747713 131344721338368 xds_k8s_testcase.py:790] ----- Test client/server logs -----
I0503 17:25:03.748021 131344721338368 k8s_base_runner.py:1152] GCP Logs Explorer link to psm-grpc-client-iws4fk03:
https://console.cloud.google.com/logs/query;query=resource.type%3D%22k8s_container%22%0Aresource.labels.project_id%3D%22grpc-testing%22%0Aresource.labels.container_name%3D%22psm-grpc-client%22%0Aresource.labels.namespace_name%3D%22psm-interop-client-20240503-1716-sc0uf%22%0Alabels.%22k8s-pod%2Fdeployment_id%22%3D%22psm-grpc-client-iws4fk03%22;timeRange=2024-05-03T17:19:48.848926Z%2F2024-05-03T17:23:51.897490Z;cursorTimestamp=2024-05-03T17:20:08.666636Z?project=grpc-testing
I0503 17:25:03.748185 131344721338368 k8s_base_runner.py:1152] GCP Logs Explorer link to psm-grpc-server-8sct8wo3:
https://console.cloud.google.com/logs/query;query=resource.type%3D%22k8s_container%22%0Aresource.labels.project_id%3D%22grpc-testing%22%0Aresource.labels.container_name%3D%22psm-grpc-server%22%0Aresource.labels.namespace_name%3D%22psm-interop-server-20240503-1716-sc0uf%22%0Alabels.%22k8s-pod%2Fdeployment_id%22%3D%22psm-grpc-server-8sct8wo3%22;timeRange=2024-05-03T17:18:33.185408Z%2F2024-05-03T17:25:03.747496Z;cursorTimestamp=2024-05-03T17:18:42.029482Z?project=grpc-testing
[ OK ] BaselineTest.test_traffic_director_grpc_setup
I0503 17:25:03.748463 131344721338368 base_testcase.py:67] ----- PSM Test Case PASSED: BaselineTest.test_traffic_director_grpc_setup -----

----------------------------------------------------------------------
Ran 1 test in 501.036s

OK
+ [17:25:04 UTC] Cmd finished: python
+ [17:25:04 UTC] Finished lb suite test: baseline_test

+ [17:25:04 UTC] Failed test suites: 0
+ [17:25:04 UTC] PSM Interop tests completed: lb


[ID: 2942546] Command finished after 1238 secs, exit value: 0


Warning: Permanently added 'localhost' (ED25519) to the list of known hosts.
[10:25:04 PDT] Collecting build artifacts from build VM
[10:25:09 PDT] Kokoro builder finished