Build/Test Explorer

  ++ dirname tools/run_tests/dockerize/build_and_run_docker.sh
+ cd tools/run_tests/dockerize/../../..
++ pwd
+ git_root=/tmpfs/altsrc/github/grpc
+ cd -
/tmpfs/altsrc/github/grpc
++ cat tools/dockerfile/test/python_debian11_default_arm64.current_version
+ DOCKER_IMAGE_NAME=us-docker.pkg.dev/grpc-testing/testing-images-public/python_debian11_default_arm64:55eb2e98cc7ff914777976a5482fee5fa62e9cbe@sha256:fccca33a655c7aa89dd7ebd9492cbcc1f636bd2a004cd939d1982cfce3d68326
+ [[ -t 0 ]]
+ DOCKER_TTY_ARGS=()
+ '[' tools/run_tests/dockerize/docker_run.sh '!=' '' ']'
+ DOCKER_CMD_AND_ARGS=(bash -l "/var/local/jenkins/grpc/${DOCKER_RUN_SCRIPT}")
+ DOCKER_RUN_SCRIPT_ARGS=("-e=DOCKER_RUN_SCRIPT_COMMAND=${DOCKER_RUN_SCRIPT_COMMAND}" "$@")
+ '[' /tmpfs/src/keystore '!=' '' ']'
+ MOUNT_KEYSTORE_DIR_ARGS=("-v=${KOKORO_KEYSTORE_DIR}:/kokoro_keystore:ro" "-e=KOKORO_KEYSTORE_DIR=/kokoro_keystore")
+ '[' /tmpfs/src/gfile '!=' '' ']'
+ MOUNT_GFILE_DIR_ARGS=("-v=${KOKORO_GFILE_DIR}:/kokoro_gfile:ro" "-e=KOKORO_GFILE_DIR=/kokoro_gfile")
+ '[' /tmpfs/src '!=' '' ']'
+ MOUNT_ARTIFACTS_DIR_ARGS=("-v=${KOKORO_ARTIFACTS_DIR}:/kokoro_artifacts" "-e=KOKORO_ARTIFACTS_DIR=/kokoro_artifacts")
+ DOCKER_PRIVILEGED_ARGS=("--cap-add=SYS_PTRACE")
+ DOCKER_PROPAGATE_ENV_ARGS=("--env-file=tools/run_tests/dockerize/docker_propagate_env.list")
+ DOCKER_CLEANUP_ARGS=("--rm=true")
+ DOCKER_NETWORK_ARGS=("--sysctl=net.ipv6.conf.all.disable_ipv6=0")
+ DOCKER_IMAGE_IDENTITY_ARGS=("-e=GRPC_TEST_DOCKER_IMAGE_IDENTITY=${DOCKER_IMAGE_NAME}")
+ DOCKER_EXTRA_ARGS_FROM_ENV=(${EXTRA_DOCKER_ARGS} ${DOCKER_EXTRA_ARGS})
+ EXTERNAL_GIT_ROOT=/var/local/jenkins/grpc
+ MOUNT_GIT_ROOT_ARGS=("-v=${git_root}:${EXTERNAL_GIT_ROOT}" "-e=EXTERNAL_GIT_ROOT=${EXTERNAL_GIT_ROOT}")
++ mktemp -d
+ TEMP_REPORT_DIR=/tmp/tmp.6PXWJjDfBd
+ mkdir -p /tmp/tmp.6PXWJjDfBd/reports
+ mkdir -p /tmpfs/altsrc/github/grpc/reports
+ MOUNT_REPORT_DIR_ARGS=("-v=${TEMP_REPORT_DIR}:/var/local/report_dir" "-v=${TEMP_REPORT_DIR}/reports:${EXTERNAL_GIT_ROOT}/reports" "-e=GRPC_TEST_REPORT_BASE_DIR=/var/local/report_dir")
+ '[' '' '!=' '' ']'
+ MOUNT_OUTPUT_DIR_ARGS=()
+ DOCKER_EXIT_CODE=0
+ docker run '-e=DOCKER_RUN_SCRIPT_COMMAND=python3 tools/run_tests/run_tests.py -t -j 16 -x run_tests/python_linux_opt_native_arm64_default/sponge_log.xml --report_suite_name python_linux_opt_native_arm64_default -l python -c opt --iomgr_platform native --arch arm64 --compiler default --bq_result_table aggregate_results --measure_cpu_costs --report_multi_target' -v=/tmpfs/src/keystore:/kokoro_keystore:ro -e=KOKORO_KEYSTORE_DIR=/kokoro_keystore -v=/tmpfs/src/gfile:/kokoro_gfile:ro -e=KOKORO_GFILE_DIR=/kokoro_gfile -v=/tmpfs/src:/kokoro_artifacts -e=KOKORO_ARTIFACTS_DIR=/kokoro_artifacts --cap-add=SYS_PTRACE --env-file=tools/run_tests/dockerize/docker_propagate_env.list --rm=true --sysctl=net.ipv6.conf.all.disable_ipv6=0 -e=GRPC_TEST_DOCKER_IMAGE_IDENTITY=us-docker.pkg.dev/grpc-testing/testing-images-public/python_debian11_default_arm64:55eb2e98cc7ff914777976a5482fee5fa62e9cbe@sha256:fccca33a655c7aa89dd7ebd9492cbcc1f636bd2a004cd939d1982cfce3d68326 -v=/tmpfs/altsrc/github/grpc:/var/local/jenkins/grpc -e=EXTERNAL_GIT_ROOT=/var/local/jenkins/grpc -v=/tmp/tmp.6PXWJjDfBd:/var/local/report_dir -v=/tmp/tmp.6PXWJjDfBd/reports:/var/local/jenkins/grpc/reports -e=GRPC_TEST_REPORT_BASE_DIR=/var/local/report_dir us-docker.pkg.dev/grpc-testing/testing-images-public/python_debian11_default_arm64:55eb2e98cc7ff914777976a5482fee5fa62e9cbe@sha256:fccca33a655c7aa89dd7ebd9492cbcc1f636bd2a004cd939d1982cfce3d68326 bash -l /var/local/jenkins/grpc/tools/run_tests/dockerize/docker_run.sh
Unable to find image 'us-docker.pkg.dev/grpc-testing/testing-images-public/python_debian11_default_arm64:55eb2e98cc7ff914777976a5482fee5fa62e9cbe@sha256:fccca33a655c7aa89dd7ebd9492cbcc1f636bd2a004cd939d1982cfce3d68326' locally
us-docker.pkg.dev/grpc-testing/testing-images-public/python_debian11_default_arm64@sha256:fccca33a655c7aa89dd7ebd9492cbcc1f636bd2a004cd939d1982cfce3d68326: Pulling from grpc-testing/testing-images-public/python_debian11_default_arm64
172730635f67: Pulling fs layer
78b098493928: Pulling fs layer
a267a25b3d15: Pulling fs layer
99824adfd0b2: Pulling fs layer
7fab78ef9ba8: Pulling fs layer
e7640929c854: Pulling fs layer
8902a704886f: Pulling fs layer
6c401b17f04f: Pulling fs layer
21918b9bcd68: Pulling fs layer
9e945f916a31: Pulling fs layer
d50fd8888a9c: Pulling fs layer
2dc67654075d: Pulling fs layer
3286050edc77: Pulling fs layer
83e8c877e019: Pulling fs layer
f91417b5d7cd: Pulling fs layer
99824adfd0b2: Waiting
7fab78ef9ba8: Waiting
e7640929c854: Waiting
8902a704886f: Waiting
6c401b17f04f: Waiting
3286050edc77: Waiting
21918b9bcd68: Waiting
83e8c877e019: Waiting
9e945f916a31: Waiting
d50fd8888a9c: Waiting
2dc67654075d: Waiting
f91417b5d7cd: Waiting
a267a25b3d15: Verifying Checksum
a267a25b3d15: Download complete
99824adfd0b2: Download complete
7fab78ef9ba8: Download complete
172730635f67: Download complete
e7640929c854: Download complete
8902a704886f: Verifying Checksum
8902a704886f: Download complete
6c401b17f04f: Verifying Checksum
6c401b17f04f: Download complete
21918b9bcd68: Verifying Checksum
21918b9bcd68: Download complete
d50fd8888a9c: Verifying Checksum
d50fd8888a9c: Download complete
2dc67654075d: Verifying Checksum
2dc67654075d: Download complete
172730635f67: Pull complete
9e945f916a31: Verifying Checksum
9e945f916a31: Download complete
3286050edc77: Verifying Checksum
3286050edc77: Download complete
78b098493928: Verifying Checksum
78b098493928: Download complete
f91417b5d7cd: Verifying Checksum
f91417b5d7cd: Download complete
83e8c877e019: Download complete
78b098493928: Pull complete
a267a25b3d15: Pull complete
99824adfd0b2: Pull complete
7fab78ef9ba8: Pull complete
e7640929c854: Pull complete
8902a704886f: Pull complete
6c401b17f04f: Pull complete
21918b9bcd68: Pull complete
9e945f916a31: Pull complete
d50fd8888a9c: Pull complete
2dc67654075d: Pull complete
3286050edc77: Pull complete
83e8c877e019: Pull complete
f91417b5d7cd: Pull complete
Digest: sha256:fccca33a655c7aa89dd7ebd9492cbcc1f636bd2a004cd939d1982cfce3d68326
Status: Downloaded newer image for us-docker.pkg.dev/grpc-testing/testing-images-public/python_debian11_default_arm64@sha256:fccca33a655c7aa89dd7ebd9492cbcc1f636bd2a004cd939d1982cfce3d68326
Cloning into '/var/local/git/grpc'...
done.
Entering 'third_party/abseil-cpp'
Cloning into '/var/local/git/grpc/third_party/abseil-cpp'...
done.
Note: switching to '4447c7562e3bc702ade25105912dce503f0c4010'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/benchmark'
Cloning into '/var/local/git/grpc/third_party/benchmark'...
done.
Note: switching to '344117638c8ff7e239044fd0fa7085839fc03021'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/bloaty'
Cloning into '/var/local/git/grpc/third_party/bloaty'...
done.
Note: switching to '60209eb1ccc34d5deefb002d1b7f37545204f7f2'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/boringssl-with-bazel'
Cloning into '/var/local/git/grpc/third_party/boringssl-with-bazel'...
done.
Note: switching to 'b8b3e6e11166719a8ebfa43c0cde9ad7d57a84f6'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/cares/cares'
Cloning into '/var/local/git/grpc/third_party/cares/cares'...
done.
Note: switching to '6360e96b5cf8e5980c887ce58ef727e53d77243a'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/envoy-api'
Cloning into '/var/local/git/grpc/third_party/envoy-api'...
done.
Note: switching to 'f8b75d1efa92bbf534596a013d9ca5873f79dd30'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/googleapis'
Cloning into '/var/local/git/grpc/third_party/googleapis'...
done.
Note: switching to 'fe8ba054ad4f7eca946c2d14a63c3f07c0b586a0'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/googletest'
Cloning into '/var/local/git/grpc/third_party/googletest'...
done.
Note: switching to '2dd1c131950043a8ad5ab0d2dda0e0970596586a'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/opencensus-proto'
Cloning into '/var/local/git/grpc/third_party/opencensus-proto'...
done.
Note: switching to '4aa53e15cbf1a47bc9087e6cfdca214c1eea4e89'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/opentelemetry'
Cloning into '/var/local/git/grpc/third_party/opentelemetry'...
done.
Note: switching to '60fa8754d890b5c55949a8c68dcfd7ab5c2395df'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/opentelemetry-cpp'
Cloning into '/var/local/git/grpc/third_party/opentelemetry-cpp'...
done.
Note: switching to '4bd64c9a336fd438d6c4c9dad2e6b61b0585311f'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/protobuf'
Cloning into '/var/local/git/grpc/third_party/protobuf'...
done.
Note: switching to '10ef3f77683f77fb3c059bf47725c27b3ff41e63'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/protoc-gen-validate'
Cloning into '/var/local/git/grpc/third_party/protoc-gen-validate'...
done.
Note: switching to '32c2415389a3538082507ae537e7edd9578c64ed'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/re2'
Cloning into '/var/local/git/grpc/third_party/re2'...
done.
Note: switching to '0c5616df9c0aaa44c9440d87422012423d91c7d1'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/xds'
Cloning into '/var/local/git/grpc/third_party/xds'...
done.
Note: switching to '3a472e524827f72d1ad621c4983dd5af54c46776'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Entering 'third_party/zlib'
Cloning into '/var/local/git/grpc/third_party/zlib'...
done.
Note: switching to '09155eaa2f9270dc4ed1fa13e2b4b2613e6e4851'.

You are in 'detached HEAD' state. You can look around, make experimental
changes and commit them, and you can discard any commits you make in this
state without impacting any branches by switching back to a branch.

If you want to create a new branch to retain commits you create, you may
do so (now or later) by using -c with the switch command. Example:

  git switch -c <new-branch-name>

Or undo this operation with:

  git switch -

Turn off this advice by setting config variable advice.detachedHead to false

Submodule 'third_party/abseil-cpp' (https://github.com/abseil/abseil-cpp.git) registered for path 'third_party/abseil-cpp'
Submodule 'third_party/benchmark' (https://github.com/google/benchmark) registered for path 'third_party/benchmark'
Submodule 'third_party/bloaty' (https://github.com/google/bloaty.git) registered for path 'third_party/bloaty'
Submodule 'third_party/boringssl-with-bazel' (https://github.com/google/boringssl.git) registered for path 'third_party/boringssl-with-bazel'
Submodule 'third_party/cares/cares' (https://github.com/c-ares/c-ares.git) registered for path 'third_party/cares/cares'
Submodule 'third_party/envoy-api' (https://github.com/envoyproxy/data-plane-api.git) registered for path 'third_party/envoy-api'
Submodule 'third_party/googleapis' (https://github.com/googleapis/googleapis.git) registered for path 'third_party/googleapis'
Submodule 'third_party/googletest' (https://github.com/google/googletest.git) registered for path 'third_party/googletest'
Submodule 'third_party/opencensus-proto' (https://github.com/census-instrumentation/opencensus-proto.git) registered for path 'third_party/opencensus-proto'
Submodule 'third_party/opentelemetry' (https://github.com/open-telemetry/opentelemetry-proto.git) registered for path 'third_party/opentelemetry'
Submodule 'third_party/opentelemetry-cpp' (https://github.com/open-telemetry/opentelemetry-cpp) registered for path 'third_party/opentelemetry-cpp'
Submodule 'third_party/protobuf' (https://github.com/protocolbuffers/protobuf.git) registered for path 'third_party/protobuf'
Submodule 'third_party/protoc-gen-validate' (https://github.com/envoyproxy/protoc-gen-validate.git) registered for path 'third_party/protoc-gen-validate'
Submodule 'third_party/re2' (https://github.com/google/re2.git) registered for path 'third_party/re2'
Submodule 'third_party/xds' (https://github.com/cncf/xds.git) registered for path 'third_party/xds'
Submodule 'third_party/zlib' (https://github.com/madler/zlib) registered for path 'third_party/zlib'
2024-10-31 15:05:28,267 START: /var/local/git/grpc/tools/run_tests/helper_scripts/build_python.sh
2024-10-31 15:14:27,824 PASSED: /var/local/git/grpc/tools/run_tests/helper_scripts/build_python.sh [time=539.5sec, retries=0:0; cpu_cost=6.5; estimated=1.0]
2024-10-31 15:14:27,830 starting port_server, with log file /tmp/tmpfawz2yw4
2024-10-31 15:14:28,879 port server is up and ready
2024-10-31 15:14:28,881 START: py39.native.tests.unit._server_test.ServerHandlerTest
2024-10-31 15:14:28,884 START: py39.native.tests.unit.beta._utilities_test.ChannelConnectivityTest
2024-10-31 15:14:28,887 START: py39.native.tests.unit._cython._server_test.Test
2024-10-31 15:14:28,889 START: py39.native.tests.unit._utilities_test.UtilityTest
2024-10-31 15:14:28,892 START: py39.native.tests.unit._auth_test.GoogleCallCredentialsTest
2024-10-31 15:14:28,895 START: py39.xds_protos
2024-10-31 15:14:28,897 START: py39.native.tests.observability._observability_plugin_test.ObservabilityPluginTest
2024-10-31 15:14:28,900 START: py39.native.tests.unit._auth_context_test.AuthContextTest
2024-10-31 15:14:28,903 START: py39.native.tests.fork._fork_interop_test.ForkInteropTest
2024-10-31 15:14:28,906 START: py39.native.tests.protoc_plugin._split_definitions_test.SameProtoGrpcBeforeProtoProtocStyleTest
2024-10-31 15:14:28,908 START: py39.native.tests.unit._cython._channel_test.ChannelTest
2024-10-31 15:14:28,911 START: py39.asyncio.tests_aio.unit.server_time_remaining_test.TestServerTimeRemaining
2024-10-31 15:14:28,914 START: py39.native.tests.unit.beta._not_found_test.NotFoundTest
2024-10-31 15:14:28,917 START: py39.native.tests.status._grpc_status_test.StatusTest
2024-10-31 15:14:28,920 START: py39.asyncio.tests_aio.unit._metadata_test.TestMetadataWithServer
2024-10-31 15:14:28,923 START: py39.asyncio.tests_aio.unit.secure_call_test.TestUnaryStreamSecureCall
2024-10-31 15:14:29,937 PASSED: py39.xds_protos [time=1.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:29,941 START: py39.asyncio.tests_aio.channelz.channelz_servicer_test.ChannelzServicerTest
2024-10-31 15:14:30,448 PASSED: py39.asyncio.tests_aio.unit._metadata_test.TestMetadataWithServer [time=1.5sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:30,449 START: py39.asyncio.tests_aio.unit.done_callback_test.TestServerSideDoneCallback
2024-10-31 15:14:30,452 PASSED: py39.asyncio.tests_aio.unit.server_time_remaining_test.TestServerTimeRemaining [time=1.5sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:30,452 START: py39.native.tests.protoc_plugin.beta_python_plugin_test.PythonPluginTest
2024-10-31 15:14:30,845 PASSED: py39.native.tests.unit.beta._utilities_test.ChannelConnectivityTest [time=2.0sec, retries=0:0; cpu_cost=0.9; estimated=1.0]
2024-10-31 15:14:30,845 START: py39.asyncio.tests_aio.unit.wait_for_connection_test.TestWaitForConnection
2024-10-31 15:14:30,854 PASSED: py39.native.tests.unit._auth_test.GoogleCallCredentialsTest [time=2.0sec, retries=0:0; cpu_cost=0.9; estimated=1.0]
2024-10-31 15:14:30,854 START: py39.asyncio.tests_aio.unit.call_test.TestUnaryUnaryCall
2024-10-31 15:14:30,878 PASSED: py39.native.tests.unit._utilities_test.UtilityTest [time=2.0sec, retries=0:0; cpu_cost=0.9; estimated=1.0]
2024-10-31 15:14:30,878 START: py39.asyncio.tests_aio.unit.channel_argument_test.TestChannelArgument
2024-10-31 15:14:30,990 PASSED: py39.native.tests.protoc_plugin._split_definitions_test.SameProtoGrpcBeforeProtoProtocStyleTest [time=2.1sec, retries=0:0; cpu_cost=0.9; estimated=1.0]
2024-10-31 15:14:30,990 START: py39.asyncio.tests_aio.unit.server_test.TestServer
2024-10-31 15:14:31,007 PASSED: py39.native.tests.unit.beta._not_found_test.NotFoundTest [time=2.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:31,008 START: py39.asyncio.tests_aio.unit.close_channel_test.TestCloseChannel
2024-10-31 15:14:31,049 PASSED: py39.native.tests.status._grpc_status_test.StatusTest [time=2.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:31,049 START: py39.native.tests.unit._cython._read_some_but_not_all_responses_test.ReadSomeButNotAllResponsesTest
2024-10-31 15:14:31,103 PASSED: py39.native.tests.unit._auth_context_test.AuthContextTest [time=2.2sec, retries=0:0; cpu_cost=0.9; estimated=1.0]
2024-10-31 15:14:31,103 START: py39.native.tests.unit._version_test.VersionTest
2024-10-31 15:14:31,113 PASSED: py39.native.tests.fork._fork_interop_test.ForkInteropTest [time=2.2sec, retries=0:0; cpu_cost=0.9; estimated=1.0]
2024-10-31 15:14:31,113 START: py39.asyncio.tests_aio.unit.client_unary_unary_interceptor_test.TestUnaryUnaryClientInterceptor
2024-10-31 15:14:31,468 PASSED: py39.native.tests.unit._server_test.ServerHandlerTest [time=2.6sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:31,469 START: py39.asyncio.tests_aio.unit.done_callback_test.TestClientSideDoneCallback
2024-10-31 15:14:31,707 PASSED: py39.asyncio.tests_aio.unit.channel_argument_test.TestChannelArgument [time=0.8sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:31,707 START: py39.native.tests.admin.admin_test.TestAdmin
2024-10-31 15:14:31,859 PASSED: py39.asyncio.tests_aio.unit.wait_for_connection_test.TestWaitForConnection [time=1.0sec, retries=0:0; cpu_cost=0.9; estimated=1.0]
2024-10-31 15:14:31,859 START: py39.native.tests.unit._server_ssl_cert_config_test.ServerSSLCertReloadTestWithClientAuth
2024-10-31 15:14:31,864 PASSED: py39.asyncio.tests_aio.unit.call_test.TestUnaryUnaryCall [time=1.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:31,864 START: py39.native.tests.protoc_plugin._split_definitions_test.SplitProtoProtoBeforeGrpcProtocStyleTest
2024-10-31 15:14:32,026 PASSED: py39.asyncio.tests_aio.unit.close_channel_test.TestCloseChannel [time=1.0sec, retries=0:0; cpu_cost=0.8; estimated=1.0]
2024-10-31 15:14:32,026 START: py39.native.tests.unit._cython.cygrpc_test.TypeSmokeTest
2024-10-31 15:14:32,080 PASSED: py39.native.tests.unit._cython._read_some_but_not_all_responses_test.ReadSomeButNotAllResponsesTest [time=1.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:32,080 START: py39.native.tests.unit._cython._fork_test.ForkWindowsTester
2024-10-31 15:14:32,131 PASSED: py39.native.tests.unit._version_test.VersionTest [time=1.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:32,131 START: py39.native.tests.unit._server_wait_for_termination_test.ServerWaitForTerminationTest
2024-10-31 15:14:32,220 PASSED: py39.asyncio.tests_aio.channelz.channelz_servicer_test.ChannelzServicerTest [time=2.3sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:32,220 START: py39.native.tests.unit._cython._no_messages_single_server_completion_queue_test.Test
2024-10-31 15:14:32,306 PASSED: py39.asyncio.tests_aio.unit.done_callback_test.TestClientSideDoneCallback [time=0.8sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:32,306 START: py39.native.tests.unit._session_cache_test.SSLSessionCacheTest
2024-10-31 15:14:32,453 PASSED: py39.asyncio.tests_aio.unit.secure_call_test.TestUnaryStreamSecureCall [time=3.5sec, retries=0:0; cpu_cost=0.5; estimated=1.0]
2024-10-31 15:14:32,453 START: py39.native.tests.unit._auth_test.AccessTokenAuthMetadataPluginTest
2024-10-31 15:14:32,809 PASSED: py39.asyncio.tests_aio.unit.client_unary_unary_interceptor_test.TestUnaryUnaryClientInterceptor [time=1.7sec, retries=0:0; cpu_cost=0.5; estimated=1.0]
2024-10-31 15:14:32,809 START: py39.native.tests.unit._server_test.ServerTest
2024-10-31 15:14:32,820 PASSED: py39.native.tests.admin.admin_test.TestAdmin [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:32,821 START: py39.native.tests.channelz._channelz_servicer_test.ChannelzServicerTest
2024-10-31 15:14:33,029 PASSED: py39.native.tests.protoc_plugin._split_definitions_test.SplitProtoProtoBeforeGrpcProtocStyleTest [time=1.2sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:33,030 START: py39.native.tests.unit._api_test.ChannelTest
2024-10-31 15:14:33,101 PASSED: py39.native.tests.unit._cython._fork_test.ForkWindowsTester [time=1.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:33,101 START: py39.asyncio.tests_aio.unit.channel_test.TestChannel
2024-10-31 15:14:33,150 PASSED: py39.native.tests.unit._cython.cygrpc_test.TypeSmokeTest [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:33,151 START: py39.asyncio.tests_aio.unit.metadata_test.TestMetadata
2024-10-31 15:14:33,448 PASSED: py39.native.tests.unit._session_cache_test.SSLSessionCacheTest [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:33,448 START: py39.native.tests.interop._insecure_intraop_test.InsecureIntraopTest
2024-10-31 15:14:33,489 PASSED: py39.native.tests.unit._auth_test.AccessTokenAuthMetadataPluginTest [time=1.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:33,489 START: py39.native.tests.unit._signal_handling_test.SignalHandlingTest
2024-10-31 15:14:34,047 PASSED: py39.native.tests.observability._observability_plugin_test.ObservabilityPluginTest [time=5.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:34,048 START: py39.asyncio.tests_aio.unit.call_test.TestUnaryStreamCall
2024-10-31 15:14:34,054 PASSED: py39.asyncio.tests_aio.unit.metadata_test.TestMetadata [time=0.9sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:34,055 START: py39.native.tests.protoc_plugin._split_definitions_test.SplitProtoGrpcBeforeProtoProtocStyleTest
2024-10-31 15:14:34,176 PASSED: py39.native.tests.unit._api_test.ChannelTest [time=1.1sec, retries=0:0; cpu_cost=1.1; estimated=1.0]
2024-10-31 15:14:34,177 START: py39.native.tests._sanity._sanity_test.SanityTest
2024-10-31 15:14:34,325 PASSED: py39.asyncio.tests_aio.unit.channel_test.TestChannel [time=1.2sec, retries=0:0; cpu_cost=0.8; estimated=1.0]
2024-10-31 15:14:34,326 START: py39.native.tests.reflection._reflection_client_test.ReflectionClientTest
2024-10-31 15:14:34,801 PASSED: py39.native.tests.unit._server_ssl_cert_config_test.ServerSSLCertReloadTestWithClientAuth [time=2.9sec, retries=0:0; cpu_cost=0.8; estimated=1.0]
2024-10-31 15:14:34,802 START: py39.native.tests.unit._abort_test.AbortTest
2024-10-31 15:14:34,805 PASSED: py39.native.tests.interop._insecure_intraop_test.InsecureIntraopTest [time=1.4sec, retries=0:0; cpu_cost=1.1; estimated=1.0]
2024-10-31 15:14:34,805 START: py39.native.tests.reflection._reflection_servicer_test.ReflectionServicerTest
2024-10-31 15:14:34,825 PASSED: py39.native.tests.unit._cython._server_test.Test [time=5.9sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:34,825 START: py39.asyncio.tests_aio.unit._metadata_test.TestTypeMetadata
2024-10-31 15:14:35,064 PASSED: py39.native.tests.channelz._channelz_servicer_test.ChannelzServicerTest [time=2.2sec, retries=0:0; cpu_cost=1.3; estimated=1.0]
2024-10-31 15:14:35,064 START: py39.native.tests.unit._reconnect_test.ReconnectTest
2024-10-31 15:14:35,173 PASSED: py39.native.tests.protoc_plugin._split_definitions_test.SplitProtoGrpcBeforeProtoProtocStyleTest [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:35,174 START: py39.asyncio.tests_aio.unit.client_stream_unary_interceptor_test.TestStreamUnaryClientInterceptor
2024-10-31 15:14:35,321 PASSED: py39.asyncio.tests_aio.unit.done_callback_test.TestServerSideDoneCallback [time=4.9sec, retries=0:0; cpu_cost=0.2; estimated=1.0]
2024-10-31 15:14:35,321 START: py39.native.tests.protoc_plugin._split_definitions_test.SameProtoSingleProtocExecutionProtocStyleTest
2024-10-31 15:14:35,394 PASSED: py39.native.tests.unit._signal_handling_test.SignalHandlingTest [time=1.9sec, retries=0:0; cpu_cost=1.2; estimated=1.0]
2024-10-31 15:14:35,394 START: py39.native.tests.protoc_plugin._split_definitions_test.WellKnownTypesTest
2024-10-31 15:14:35,615 PASSED: py39.native.tests._sanity._sanity_test.SanityTest [time=1.4sec, retries=0:0; cpu_cost=1.1; estimated=1.0]
2024-10-31 15:14:35,616 START: py39.native.tests.protoc_plugin._python_plugin_test.ModuleMainTest
2024-10-31 15:14:35,619 PASSED: py39.asyncio.tests_aio.unit._metadata_test.TestTypeMetadata [time=0.8sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:35,619 START: py39.native.tests.csds.csds_test.TestCsds
2024-10-31 15:14:35,678 PASSED: py39.native.tests.reflection._reflection_client_test.ReflectionClientTest [time=1.4sec, retries=0:0; cpu_cost=1.1; estimated=1.0]
2024-10-31 15:14:35,678 START: py39.native.tests.protoc_plugin._python_plugin_test.PythonPluginTest
2024-10-31 15:14:35,939 PASSED: py39.native.tests.unit._server_test.ServerTest [time=3.1sec, retries=0:0; cpu_cost=0.4; estimated=1.0]
2024-10-31 15:14:35,939 START: py39.native.tests.unit.beta._implementations_test.ChannelCredentialsTest
2024-10-31 15:14:36,002 PASSED: py39.native.tests.unit._abort_test.AbortTest [time=1.2sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:36,002 START: py39.native.tests.testing._server_test.FirstServiceServicerTest
2024-10-31 15:14:36,041 PASSED: py39.native.tests.reflection._reflection_servicer_test.ReflectionServicerTest [time=1.2sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:36,041 START: py39.native.tests.unit._credentials_test.CredentialsTest
2024-10-31 15:14:36,282 PASSED: py39.asyncio.tests_aio.unit.client_stream_unary_interceptor_test.TestStreamUnaryClientInterceptor [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:36,282 START: py39.native.tests.unit._metadata_flags_test.MetadataFlagsTest
2024-10-31 15:14:36,415 PASSED: py39.native.tests.protoc_plugin._split_definitions_test.WellKnownTypesTest [time=1.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:36,415 START: py39.native.tests.unit._server_ssl_cert_config_test.ServerSSLCertConfigFetcherParamsChecks
2024-10-31 15:14:36,465 PASSED: py39.native.tests.protoc_plugin._split_definitions_test.SameProtoSingleProtocExecutionProtocStyleTest [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:36,466 START: py39.native.tests.protoc_plugin._split_definitions_test.SameProtoProtoBeforeGrpcProtocStyleTest
2024-10-31 15:14:36,714 PASSED: py39.native.tests.protoc_plugin._python_plugin_test.ModuleMainTest [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:36,714 START: py39.native.tests.unit._logging_test.LoggingTest
2024-10-31 15:14:36,962 PASSED: py39.native.tests.unit.beta._implementations_test.ChannelCredentialsTest [time=1.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:36,962 START: py39.native.tests.unit._cython._fork_test.ForkPosixTester
2024-10-31 15:14:37,166 PASSED: py39.native.tests.unit._credentials_test.CredentialsTest [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:37,166 START: py39.native.tests.testing._time_test.StrictRealTimeTest
2024-10-31 15:14:37,247 PASSED: py39.native.tests.unit._server_wait_for_termination_test.ServerWaitForTerminationTest [time=5.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:37,248 START: py39.asyncio.tests_aio.unit.call_test.TestStreamUnaryCall
2024-10-31 15:14:37,444 PASSED: py39.native.tests.unit._server_ssl_cert_config_test.ServerSSLCertConfigFetcherParamsChecks [time=1.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:37,444 START: py39.asyncio.tests_aio.unit.client_unary_stream_interceptor_test.TestUnaryStreamClientInterceptor
2024-10-31 15:14:37,603 PASSED: py39.native.tests.protoc_plugin._split_definitions_test.SameProtoProtoBeforeGrpcProtocStyleTest [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:37,603 START: py39.native.tests.unit.beta._implementations_test.CallCredentialsTest
2024-10-31 15:14:37,694 PASSED: py39.native.tests.testing._server_test.FirstServiceServicerTest [time=1.7sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:37,694 START: py39.asyncio.tests_aio.unit.timeout_test.TestTimeout
2024-10-31 15:14:38,031 PASSED: py39.native.tests.unit._cython._fork_test.ForkPosixTester [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:38,031 START: py39.native.tests.protoc_plugin._split_definitions_test.SameProtoMid2016ProtocStyleTest
2024-10-31 15:14:38,207 PASSED: py39.native.tests.unit._logging_test.LoggingTest [time=1.5sec, retries=0:0; cpu_cost=1.2; estimated=1.0]
2024-10-31 15:14:38,207 START: py39.native.tests.unit._dns_resolver_test.DNSResolverTest
2024-10-31 15:14:38,510 PASSED: py39.asyncio.tests_aio.unit.client_unary_stream_interceptor_test.TestUnaryStreamClientInterceptor [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:38,510 START: py39.asyncio.tests_aio.reflection.reflection_servicer_test.ReflectionServicerTest
2024-10-31 15:14:38,644 PASSED: py39.native.tests.unit.beta._implementations_test.CallCredentialsTest [time=1.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:38,644 START: py39.native.tests.unit._server_shutdown_test.ServerShutdown
2024-10-31 15:14:38,697 PASSED: py39.native.tests.unit._metadata_flags_test.MetadataFlagsTest [time=2.4sec, retries=0:0; cpu_cost=1.1; estimated=1.0]
2024-10-31 15:14:38,698 START: py39.asyncio.tests_aio.unit.context_peer_test.TestContextPeer
2024-10-31 15:14:38,771 PASSED: py39.native.tests.csds.csds_test.TestCsds [time=3.2sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:38,772 START: py39.native.tests.observability._observability_api_test.AllTest
2024-10-31 15:14:39,130 PASSED: py39.native.tests.protoc_plugin._split_definitions_test.SameProtoMid2016ProtocStyleTest [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:39,131 START: py39.native.tests.unit._local_credentials_test.LocalCredentialsTest
2024-10-31 15:14:39,375 PASSED: py39.asyncio.tests_aio.reflection.reflection_servicer_test.ReflectionServicerTest [time=0.9sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:39,375 START: py39.native.tests.unit.beta._beta_features_test.ContextManagementAndLifecycleTest
2024-10-31 15:14:39,440 PASSED: py39.asyncio.tests_aio.unit.context_peer_test.TestContextPeer [time=0.7sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:39,440 START: py39.asyncio.tests_aio.status.grpc_status_test.StatusTest
2024-10-31 15:14:39,481 PASSED: py39.native.tests.unit._dns_resolver_test.DNSResolverTest [time=1.3sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:39,482 START: py39.native.tests.unit.beta._beta_features_test.BetaFeaturesTest
2024-10-31 15:14:39,765 PASSED: py39.native.tests.observability._observability_api_test.AllTest [time=1.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:39,766 START: py39.asyncio.tests_aio.unit.secure_call_test.TestUnaryUnarySecureCall
2024-10-31 15:14:40,089 PASSED: py39.native.tests.unit._cython._no_messages_single_server_completion_queue_test.Test [time=7.9sec, retries=0:0; cpu_cost=1.4; estimated=1.0]
2024-10-31 15:14:40,090 START: py39.native.tests.unit._dynamic_stubs_test.DynamicStubTest
2024-10-31 15:14:40,235 PASSED: py39.asyncio.tests_aio.status.grpc_status_test.StatusTest [time=0.8sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:40,235 START: py39.native.tests.unit.beta._connectivity_channel_test.ConnectivityStatesTest
2024-10-31 15:14:40,242 PASSED: py39.native.tests.unit._local_credentials_test.LocalCredentialsTest [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:40,242 START: py39.native.tests.testing._time_test.StrictFakeTimeTest
2024-10-31 15:14:40,676 PASSED: py39.native.tests.unit.beta._beta_features_test.BetaFeaturesTest [time=1.2sec, retries=0:0; cpu_cost=1.1; estimated=1.0]
2024-10-31 15:14:40,677 START: py39.native.tests.health_check._health_servicer_test.HealthServicerBackwardsCompatibleWatchTest
2024-10-31 15:14:40,817 PASSED: py39.asyncio.tests_aio.unit.call_test.TestUnaryStreamCall [time=6.8sec, retries=0:0; cpu_cost=0.3; estimated=1.0]
2024-10-31 15:14:40,817 START: py39.asyncio.tests_aio.unit.client_unary_unary_interceptor_test.TestInterceptedUnaryUnaryCall
2024-10-31 15:14:41,144 PASSED: py39.asyncio.tests_aio.unit.call_test.TestStreamUnaryCall [time=3.9sec, retries=0:0; cpu_cost=0.2; estimated=1.0]
2024-10-31 15:14:41,145 START: py39.native.tests.unit._contextvars_propagation_test.ContextVarsPropagationTest
2024-10-31 15:14:41,281 PASSED: py39.native.tests.unit.beta._connectivity_channel_test.ConnectivityStatesTest [time=1.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:41,281 START: py39.native.tests.unit._cython.cygrpc_test.SecureServerSecureClient
2024-10-31 15:14:41,286 PASSED: py39.native.tests.unit._dynamic_stubs_test.DynamicStubTest [time=1.2sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:41,287 START: py39.native.tests.unit._channel_connectivity_test.ChannelConnectivityTest
2024-10-31 15:14:41,304 PASSED: py39.native.tests.unit._reconnect_test.ReconnectTest [time=6.2sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:41,304 START: py39.native.tests.unit._empty_message_test.EmptyMessageTest
2024-10-31 15:14:41,345 PASSED: py39.native.tests.unit._server_shutdown_test.ServerShutdown [time=2.7sec, retries=0:0; cpu_cost=1.6; estimated=1.0]
2024-10-31 15:14:41,346 START: py39.native.tests.protoc_plugin._split_definitions_test.SplitProtoMid2016ProtocStyleTest
2024-10-31 15:14:41,964 PASSED: py39.asyncio.tests_aio.unit.client_unary_unary_interceptor_test.TestInterceptedUnaryUnaryCall [time=1.1sec, retries=0:0; cpu_cost=0.8; estimated=1.0]
2024-10-31 15:14:41,965 START: py39.native.tests.unit._channel_ready_future_test.ChannelReadyFutureTest
2024-10-31 15:14:42,076 PASSED: py39.native.tests.unit.beta._beta_features_test.ContextManagementAndLifecycleTest [time=2.7sec, retries=0:0; cpu_cost=1.2; estimated=1.0]
2024-10-31 15:14:42,076 START: py39.native.tests.health_check._health_servicer_test.HealthServicerTest
2024-10-31 15:14:42,498 PASSED: py39.native.tests.protoc_plugin._split_definitions_test.SplitProtoMid2016ProtocStyleTest [time=1.2sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:42,498 START: py39.native.tests.unit._api_test.AllTest
2024-10-31 15:14:42,567 PASSED: py39.asyncio.tests_aio.unit.secure_call_test.TestUnaryUnarySecureCall [time=2.8sec, retries=0:0; cpu_cost=0.3; estimated=1.0]
2024-10-31 15:14:42,567 START: py39.asyncio.tests_aio.unit.secure_call_test.TestStreamStreamSecureCall
2024-10-31 15:14:42,876 PASSED: py39.native.tests.unit._empty_message_test.EmptyMessageTest [time=1.6sec, retries=0:0; cpu_cost=1.2; estimated=1.0]
2024-10-31 15:14:42,877 START: py39.asyncio.tests_aio.unit.client_stream_stream_interceptor_test.TestStreamStreamClientInterceptor
2024-10-31 15:14:43,283 PASSED: py39.native.tests.testing._time_test.StrictFakeTimeTest [time=3.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:43,284 START: py39.native.tests.unit._rpc_part_2_test.RPCPart2Test
2024-10-31 15:14:43,503 PASSED: py39.native.tests.unit._api_test.AllTest [time=1.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:43,504 START: py39.native.tests.unit._invocation_defects_test.InvocationDefectsTest
2024-10-31 15:14:43,672 PASSED: py39.native.tests.unit._contextvars_propagation_test.ContextVarsPropagationTest [time=2.5sec, retries=0:0; cpu_cost=1.3; estimated=1.0]
2024-10-31 15:14:43,672 START: py39.native.tests.unit._compression_test.CompressionTest
2024-10-31 15:14:43,752 PASSED: py39.asyncio.tests_aio.unit.client_stream_stream_interceptor_test.TestStreamStreamClientInterceptor [time=0.9sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:43,752 START: py39.native.tests.observability._csm_observability_plugin_test.MetadataExchangeTest
2024-10-31 15:14:44,264 PASSED: py39.asyncio.tests_aio.unit.server_test.TestServer [time=13.3sec, retries=0:0; cpu_cost=0.1; estimated=1.0]
2024-10-31 15:14:44,264 START: py39.native.tests.unit.framework.foundation._logging_pool_test.LoggingPoolTest
2024-10-31 15:14:44,413 PASSED: py39.native.tests.unit._cython.cygrpc_test.SecureServerSecureClient [time=3.1sec, retries=0:0; cpu_cost=0.4; estimated=1.0]
2024-10-31 15:14:44,413 START: py39.native.tests.unit._server_ssl_cert_config_test.ServerSSLCertReloadTestWithoutClientAuth
2024-10-31 15:14:44,447 PASSED: py39.native.tests.testing._time_test.StrictRealTimeTest [time=7.3sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:44,447 START: py39.native.tests.unit._exit_test.ExitTest
2024-10-31 15:14:44,579 PASSED: py39.asyncio.tests_aio.unit.timeout_test.TestTimeout [time=6.9sec, retries=0:0; cpu_cost=0.1; estimated=1.0]
2024-10-31 15:14:44,579 START: py39.native.tests.csds.csds_test.TestCsdsStream
2024-10-31 15:14:44,882 PASSED: py39.native.tests.unit._invocation_defects_test.InvocationDefectsTest [time=1.4sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:44,882 START: py39.native.tests.observability._open_telemetry_observability_test.OpenTelemetryObservabilityTest
2024-10-31 15:14:45,337 PASSED: py39.asyncio.tests_aio.unit.secure_call_test.TestStreamStreamSecureCall [time=2.8sec, retries=0:0; cpu_cost=0.3; estimated=1.0]
2024-10-31 15:14:45,337 START: py39.native.tests.protoc_plugin._python_plugin_test.SimpleStubsPluginTest
2024-10-31 15:14:45,438 PASSED: py39.native.tests.unit.framework.foundation._logging_pool_test.LoggingPoolTest [time=1.2sec, retries=0:0; cpu_cost=0.9; estimated=1.0]
2024-10-31 15:14:45,439 START: py39.native.tests.unit._server_ssl_cert_config_test.ServerSSLCertReloadTestCertConfigReuse
2024-10-31 15:14:45,889 PASSED: py39.native.tests.health_check._health_servicer_test.HealthServicerBackwardsCompatibleWatchTest [time=5.2sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:45,890 START: py39.asyncio.tests_aio.unit.call_test.TestStreamStreamCall
2024-10-31 15:14:45,987 PASSED: py39.native.tests.unit._exit_test.ExitTest [time=1.5sec, retries=0:0; cpu_cost=0.9; estimated=1.0]
2024-10-31 15:14:45,987 START: py39.asyncio.tests_aio._sanity._sanity_test.AioSanityTest
2024-10-31 15:14:46,819 PASSED: py39.native.tests.csds.csds_test.TestCsdsStream [time=2.2sec, retries=0:0; cpu_cost=0.9; estimated=1.0]
2024-10-31 15:14:46,819 START: py39.native.tests.unit._cython._no_messages_server_completion_queue_per_call_test.Test
2024-10-31 15:14:46,824 PASSED: py39.asyncio.tests_aio._sanity._sanity_test.AioSanityTest [time=0.8sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:46,825 START: py39.native.tests.protoc_plugin._split_definitions_test.SplitProtoSingleProtocExecutionProtocStyleTest
2024-10-31 15:14:46,941 PASSED: py39.native.tests.protoc_plugin._python_plugin_test.SimpleStubsPluginTest [time=1.6sec, retries=0:0; cpu_cost=0.9; estimated=1.0]
2024-10-31 15:14:46,942 START: py39.asyncio.tests_aio.health_check.health_servicer_test.HealthServicerTest
2024-10-31 15:14:47,312 PASSED: py39.native.tests.unit._channel_ready_future_test.ChannelReadyFutureTest [time=5.3sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:47,312 START: py39.native.tests.unit._channel_close_test.ChannelCloseTest
2024-10-31 15:14:47,453 PASSED: py39.native.tests.health_check._health_servicer_test.HealthServicerTest [time=5.4sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:47,453 START: py39.native.tests.unit._interceptor_test.InterceptorTest
2024-10-31 15:14:47,935 PASSED: py39.native.tests.protoc_plugin._split_definitions_test.SplitProtoSingleProtocExecutionProtocStyleTest [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:47,935 START: py39.asyncio.tests_aio.unit.abort_test.TestAbort
2024-10-31 15:14:48,124 PASSED: py39.native.tests.unit._server_ssl_cert_config_test.ServerSSLCertReloadTestWithoutClientAuth [time=3.7sec, retries=0:0; cpu_cost=0.7; estimated=1.0]
2024-10-31 15:14:48,125 START: py39.asyncio.tests_aio.unit.auth_context_test.TestAuthContext
2024-10-31 15:14:48,182 PASSED: py39.native.tests.unit._server_ssl_cert_config_test.ServerSSLCertReloadTestCertConfigReuse [time=2.7sec, retries=0:0; cpu_cost=0.8; estimated=1.0]
2024-10-31 15:14:48,183 START: py39.asyncio.tests_aio.unit.server_interceptor_test.TestServerInterceptor
2024-10-31 15:14:48,719 PASSED: py39.asyncio.tests_aio.unit.abort_test.TestAbort [time=0.8sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:48,719 START: py39.native.tests.unit._xds_credentials_test.XdsCredentialsTest
2024-10-31 15:14:48,915 ++ dirname /var/local/git/grpc/tools/run_tests/helper_scripts/run_python.sh
+ cd /var/local/git/grpc/tools/run_tests/helper_scripts/../../..
++ pwd
+ PYTHON=/var/local/git/grpc/py39/bin/python
++ pwd
+ ROOT=/var/local/git/grpc
+ /var/local/git/grpc/py39/bin/python /var/local/git/grpc/src/python/grpcio_tests/setup.py test_aio
running test_aio
Running       tests_aio.unit.call_test.TestStreamStreamCall.test_async_generator
WARNING: All log messages before absl::InitializeLog() is called are written to STDERR
W0000 00:00:1730387686.567508   32632 log.cc:109] Log level DEBUG is not suitable for production. Prefer WARNING or ERROR. However if you see this message in a debug environment or test environment it is safe to ignore this message.
Running       tests_aio.unit.call_test.TestStreamStreamCall.test_cancel
Running       tests_aio.unit.call_test.TestStreamStreamCall.test_cancel_after_done_writing
Running       tests_aio.unit.call_test.TestStreamStreamCall.test_cancel_with_ongoing_read
I0000 00:00:1730387686.659276   33034 chttp2_transport.cc:1154] ipv6:%5B::1%5D:34651: Got goaway [2] err=UNAVAILABLE:GOAWAY received; Error code: 2; Debug Text: Cancelling all calls {grpc_status:14, http2_error:2, created_time:"2024-10-31T15:14:46.659258375+00:00"}
Running       tests_aio.unit.call_test.TestStreamStreamCall.test_cancel_with_pending_read
Running       tests_aio.unit.call_test.TestStreamStreamCall.test_early_cancel
Running       tests_aio.unit.call_test.TestStreamStreamCall.test_empty_ping_pong
Running       tests_aio.unit.call_test.TestStreamStreamCall.test_error_in_async_generator
Running       tests_aio.unit.call_test.TestStreamStreamCall.test_late_cancel
Running       tests_aio.unit.call_test.TestStreamStreamCall.test_normal_iterable_requests
Running       tests_aio.unit.call_test.TestStreamStreamCall.test_read_write_after_done_writing
Running       tests_aio.unit.call_test.TestStreamStreamCall.test_too_many_reads
[95m [2024-10-31 15:14:46.562082]Testing gRPC Python...
[0m[94m [2024-10-31 15:14:46.565269]START         tests_aio.unit.call_test.TestStreamStreamCall.test_async_generator
[0m[92m [2024-10-31 15:14:46.595558]SUCCESS       tests_aio.unit.call_test.TestStreamStreamCall.test_async_generator[Duration: 0:00:00.030289]
[0m[94m [2024-10-31 15:14:46.600990]START         tests_aio.unit.call_test.TestStreamStreamCall.test_cancel
[0m[92m [2024-10-31 15:14:46.627280]SUCCESS       tests_aio.unit.call_test.TestStreamStreamCall.test_cancel[Duration: 0:00:00.026290]
[0m[94m [2024-10-31 15:14:46.632818]START         tests_aio.unit.call_test.TestStreamStreamCall.test_cancel_after_done_writing
[0m[91m [2024-10-31 15:14:46.643918]FAILURE       tests_aio.unit.call_test.TestStreamStreamCall.test_cancel_after_done_writing[Duration: 0:00:00.011094]
[0m[94m [2024-10-31 15:14:46.649387]START         tests_aio.unit.call_test.TestStreamStreamCall.test_cancel_with_ongoing_read
[0m[92m [2024-10-31 15:14:46.656636]SUCCESS       tests_aio.unit.call_test.TestStreamStreamCall.test_cancel_with_ongoing_read[Duration: 0:00:00.007249]
[0m[94m [2024-10-31 15:14:46.663185]START         tests_aio.unit.call_test.TestStreamStreamCall.test_cancel_with_pending_read
[0m[92m [2024-10-31 15:14:46.675288]SUCCESS       tests_aio.unit.call_test.TestStreamStreamCall.test_cancel_with_pending_read[Duration: 0:00:00.012103]
[0m[94m [2024-10-31 15:14:46.680391]START         tests_aio.unit.call_test.TestStreamStreamCall.test_early_cancel
[0m[92m [2024-10-31 15:14:46.685706]SUCCESS       tests_aio.unit.call_test.TestStreamStreamCall.test_early_cancel[Duration: 0:00:00.005315]
[0m[94m [2024-10-31 15:14:46.690971]START         tests_aio.unit.call_test.TestStreamStreamCall.test_empty_ping_pong
[0m[92m [2024-10-31 15:14:46.708348]SUCCESS       tests_aio.unit.call_test.TestStreamStreamCall.test_empty_ping_pong[Duration: 0:00:00.017377]
[0m[94m [2024-10-31 15:14:46.714230]START         tests_aio.unit.call_test.TestStreamStreamCall.test_error_in_async_generator
[0m[92m [2024-10-31 15:14:48.724267]SUCCESS       tests_aio.unit.call_test.TestStreamStreamCall.test_error_in_async_generator[Duration: 0:00:02.010037]
[0m[94m [2024-10-31 15:14:48.729725]START         tests_aio.unit.call_test.TestStreamStreamCall.test_late_cancel
[0m[92m [2024-10-31 15:14:48.739607]SUCCESS       tests_aio.unit.call_test.TestStreamStreamCall.test_late_cancel[Duration: 0:00:00.009882]
[0m[94m [2024-10-31 15:14:48.744913]START         tests_aio.unit.call_test.TestStreamStreamCall.test_normal_iterable_requests
[0m[92m [2024-10-31 15:14:48.762946]SUCCESS       tests_aio.unit.call_test.TestStreamStreamCall.test_normal_iterable_requests[Duration: 0:00:00.018033]
[0m[94m [2024-10-31 15:14:48.768655]START         tests_aio.unit.call_test.TestStreamStreamCall.test_read_write_after_done_writing
[0m[92m [2024-10-31 15:14:48.787347]SUCCESS       tests_aio.unit.call_test.TestStreamStreamCall.test_read_write_after_done_writing[Duration: 0:00:00.018692]
[0m[94m [2024-10-31 15:14:48.792502]START         tests_aio.unit.call_test.TestStreamStreamCall.test_too_many_reads
[0m[92m [2024-10-31 15:14:48.811553]SUCCESS       tests_aio.unit.call_test.TestStreamStreamCall.test_too_many_reads[Duration: 0:00:00.019051]
[0m12 tests finished:
	11 successful
	1 unsuccessful
	0 skipped
	0 expected failures
	0 unexpected successes
Interrupted Tests:
	[]

Errors/Failures: 
[91mtests_aio.unit.call_test.TestStreamStreamCall.test_cancel_after_done_writing[0m
[1mtraceback:[0m
Traceback (most recent call last):
  File "/usr/lib/python3.9/unittest/case.py", line 59, in testPartExecutor
    yield
  File "/usr/lib/python3.9/unittest/case.py", line 593, in run
    self._callTestMethod(testMethod)
  File "/usr/lib/python3.9/unittest/case.py", line 550, in _callTestMethod
    method()
  File "/var/local/git/grpc/src/python/grpcio_tests/tests_aio/unit/_test_base.py", line 31, in wrapper
    return loop.run_until_complete(f(*args, **kwargs))
  File "/usr/lib/python3.9/asyncio/base_events.py", line 642, in run_until_complete
    return future.result()
  File "/var/local/git/grpc/src/python/grpcio_tests/tests_aio/unit/call_test.py", line 821, in test_cancel_after_done_writing
    self.assertTrue(call.cancel())
  File "/usr/lib/python3.9/unittest/case.py", line 682, in assertTrue
    raise self.failureException(msg)
AssertionError: False is not true

[1mstdout:[0m
None
[1mstderr:[0m
None

Unexpected successes: []
Test failure
real 3.02
user 0.76
sys 0.18

2024-10-31 15:14:48,915 FAILED: py39.asyncio.tests_aio.unit.call_test.TestStreamStreamCall [ret=1, pid=32604, time=3.0sec]
2024-10-31 15:14:48,916 START: py39.native.tests.unit._metadata_test.MetadataTest
2024-10-31 15:14:49,025 PASSED: py39.asyncio.tests_aio.unit.auth_context_test.TestAuthContext [time=0.9sec, retries=0:0; cpu_cost=0.9; estimated=1.0]
2024-10-31 15:14:49,026 START: py39.native.tests.unit._resource_exhausted_test.ResourceExhaustedTest
2024-10-31 15:14:49,086 PASSED: py39.asyncio.tests_aio.unit.server_interceptor_test.TestServerInterceptor [time=0.9sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:49,087 START: py39.native.tests.unit._channel_args_test.ChannelArgsTest
2024-10-31 15:14:49,296 PASSED: py39.native.tests.observability._csm_observability_plugin_test.MetadataExchangeTest [time=5.5sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:49,296 START: py39.native.tests.testing._client_test.ClientTest
2024-10-31 15:14:49,972 PASSED: py39.native.tests.unit._xds_credentials_test.XdsCredentialsTest [time=1.3sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:49,972 START: py39.asyncio.tests_aio.unit.aio_rpc_error_test.TestAioRpcError
2024-10-31 15:14:50,334 PASSED: py39.native.tests.unit._channel_args_test.ChannelArgsTest [time=1.2sec, retries=0:0; cpu_cost=0.9; estimated=1.0]
2024-10-31 15:14:50,335 START: py39.asyncio.tests_aio.interop.local_interop_test.InsecureLocalInteropTest
2024-10-31 15:14:50,599 PASSED: py39.native.tests.unit._metadata_test.MetadataTest [time=1.7sec, retries=0:0; cpu_cost=1.1; estimated=1.0]
2024-10-31 15:14:50,599 START: py39.native.tests.unit._cython._cancel_many_calls_test.CancelManyCallsTest
2024-10-31 15:14:50,702 PASSED: py39.asyncio.tests_aio.unit.aio_rpc_error_test.TestAioRpcError [time=0.7sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:50,702 START: py39.native.tests.unit._metadata_code_details_test.MetadataCodeDetailsTest
2024-10-31 15:14:51,003 PASSED: py39.native.tests.unit._interceptor_test.InterceptorTest [time=3.5sec, retries=0:0; cpu_cost=1.1; estimated=1.0]
2024-10-31 15:14:51,004 START: py39.native.tests.unit._error_message_encoding_test.ErrorMessageEncodingTest
2024-10-31 15:14:51,027 PASSED: py39.native.tests.unit._channel_connectivity_test.ChannelConnectivityTest [time=9.7sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:51,027 START: py39.native.tests.unit._api_test.ChannelConnectivityTest
2024-10-31 15:14:51,737 PASSED: py39.native.tests.testing._client_test.ClientTest [time=2.4sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:51,737 START: py39.native.tests.unit._rpc_part_1_test.RPCPart1Test
2024-10-31 15:14:51,833 PASSED: py39.native.tests.unit._cython._cancel_many_calls_test.CancelManyCallsTest [time=1.2sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:51,833 START: py39.native.tests.unit._grpc_shutdown_test.GrpcShutdownTest
2024-10-31 15:14:52,069 PASSED: py39.native.tests.unit._api_test.ChannelConnectivityTest [time=1.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:52,069 START: py39.asyncio.tests_aio.unit.channel_ready_test.TestChannelReady
2024-10-31 15:14:52,139 PASSED: py39.native.tests.unit._error_message_encoding_test.ErrorMessageEncodingTest [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:52,139 START: py39.asyncio.tests_aio.unit.compression_test.TestCompression
2024-10-31 15:14:52,412 PASSED: py39.native.tests.protoc_plugin.beta_python_plugin_test.PythonPluginTest [time=22.0sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:52,412 START: py39.native.tests.unit._invalid_metadata_test.InvalidMetadataTest
2024-10-31 15:14:52,456 PASSED: py39.asyncio.tests_aio.interop.local_interop_test.InsecureLocalInteropTest [time=2.1sec, retries=0:0; cpu_cost=0.5; estimated=1.0]
2024-10-31 15:14:52,457 START: py39.asyncio.tests_aio.unit.connectivity_test.TestConnectivityState
2024-10-31 15:14:52,694 PASSED: py39.asyncio.tests_aio.health_check.health_servicer_test.HealthServicerTest [time=5.8sec, retries=0:0; cpu_cost=0.3; estimated=1.0]
2024-10-31 15:14:52,694 START: py39.asyncio.tests_aio.unit.outside_init_test.TestOutsideInit
2024-10-31 15:14:53,005 PASSED: py39.native.tests.unit._resource_exhausted_test.ResourceExhaustedTest [time=4.0sec, retries=0:0; cpu_cost=1.1; estimated=1.0]
2024-10-31 15:14:53,005 START: py39.asyncio.tests_aio.unit.init_test.TestInit
2024-10-31 15:14:53,057 PASSED: py39.asyncio.tests_aio.unit.compression_test.TestCompression [time=0.9sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:53,057 START: py39.asyncio.tests_aio.interop.local_interop_test.SecureLocalInteropTest
2024-10-31 15:14:53,247 PASSED: py39.native.tests.unit._compression_test.CompressionTest [time=9.6sec, retries=0:0; cpu_cost=1.1; estimated=1.0]
2024-10-31 15:14:53,248 START: py39.native.tests.unit._cython.cygrpc_test.InsecureServerInsecureClient
2024-10-31 15:14:53,729 PASSED: py39.native.tests.unit._invalid_metadata_test.InvalidMetadataTest [time=1.3sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:53,729 START: py39.asyncio.tests_aio.unit.wait_for_ready_test.TestWaitForReady
2024-10-31 15:14:53,805 PASSED: py39.asyncio.tests_aio.unit.init_test.TestInit [time=0.8sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:53,805 START: py39.native.tests.unit._metadata_code_details_test.InspectContextTest
2024-10-31 15:14:53,894 PASSED: py39.asyncio.tests_aio.unit.channel_ready_test.TestChannelReady [time=1.8sec, retries=0:0; cpu_cost=0.4; estimated=1.0]
2024-10-31 15:14:53,894 START: py39.native.tests.interop._secure_intraop_test.SecureIntraopTest
2024-10-31 15:14:54,262 PASSED: py39.native.tests.unit._metadata_code_details_test.MetadataCodeDetailsTest [time=3.6sec, retries=0:0; cpu_cost=1.2; estimated=1.0]
2024-10-31 15:14:54,262 START: py39.native.tests.observability._csm_observability_plugin_test.CSMObservabilityPluginTest
2024-10-31 15:14:54,325 PASSED: py39.native.tests.unit._cython.cygrpc_test.InsecureServerInsecureClient [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:54,325 START: py39.asyncio.tests_aio.unit.compatibility_test.TestCompatibility
2024-10-31 15:14:54,900 PASSED: py39.native.tests.unit._metadata_code_details_test.InspectContextTest [time=1.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:55,618 PASSED: py39.asyncio.tests_aio.unit.compatibility_test.TestCompatibility [time=1.3sec, retries=0:0; cpu_cost=1.2; estimated=1.0]
2024-10-31 15:14:55,889 PASSED: py39.asyncio.tests_aio.unit.outside_init_test.TestOutsideInit [time=3.2sec, retries=0:0; cpu_cost=0.4; estimated=1.0]
2024-10-31 15:14:55,980 PASSED: py39.native.tests.interop._secure_intraop_test.SecureIntraopTest [time=2.1sec, retries=0:0; cpu_cost=0.7; estimated=1.0]
2024-10-31 15:14:56,363 PASSED: py39.native.tests.observability._csm_observability_plugin_test.CSMObservabilityPluginTest [time=2.1sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:57,195 PASSED: py39.asyncio.tests_aio.interop.local_interop_test.SecureLocalInteropTest [time=4.1sec, retries=0:0; cpu_cost=0.3; estimated=1.0]
2024-10-31 15:14:57,285 PASSED: py39.asyncio.tests_aio.unit.connectivity_test.TestConnectivityState [time=4.8sec, retries=0:0; cpu_cost=0.2; estimated=1.0]
2024-10-31 15:14:57,506 PASSED: py39.native.tests.protoc_plugin._python_plugin_test.PythonPluginTest [time=21.8sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:58,552 PASSED: py39.asyncio.tests_aio.unit.wait_for_ready_test.TestWaitForReady [time=4.8sec, retries=0:0; cpu_cost=0.2; estimated=1.0]
2024-10-31 15:14:58,930 PASSED: py39.native.tests.unit._channel_close_test.ChannelCloseTest [time=11.6sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:14:59,052 PASSED: py39.native.tests.unit._cython._no_messages_server_completion_queue_per_call_test.Test [time=12.2sec, retries=0:0; cpu_cost=1.3; estimated=1.0]
2024-10-31 15:15:02,991 PASSED: py39.native.tests.unit._grpc_shutdown_test.GrpcShutdownTest [time=11.2sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:15:06,381 PASSED: py39.native.tests.unit._rpc_part_2_test.RPCPart2Test [time=23.1sec, retries=0:0; cpu_cost=1.1; estimated=1.0]
2024-10-31 15:15:09,244 PASSED: py39.native.tests.unit._rpc_part_1_test.RPCPart1Test [time=17.5sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:15:10,839 PASSED: py39.native.tests.unit._cython._channel_test.ChannelTest [time=41.9sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:15:16,500 PASSED: py39.native.tests.observability._open_telemetry_observability_test.OpenTelemetryObservabilityTest [time=31.6sec, retries=0:0; cpu_cost=1.0; estimated=1.0]
2024-10-31 15:15:16,500 FAILED: py39.asyncio.tests_aio.unit.call_test.TestStreamStreamCall
2024-10-31 15:15:16,566 Attempting refresh to obtain initial access_token
Warning: Table aggregate_results already exists
2024-10-31 15:15:17,119 FAILED: Some tests failed
Cacheable calls:    194 /  371 (52.29%)
  Hits:              15 /  194 ( 7.73%)
    Direct:           9 /   15 (60.00%)
    Preprocessed:     6 /   15 (40.00%)
  Misses:           179 /  194 (92.27%)
Uncacheable calls:  177 /  371 (47.71%)
Local storage:
  Cache size (GB): 0.00 / 5.00 ( 0.03%)
  Hits:              11 /  244 ( 4.51%)
  Misses:           233 /  244 (95.49%)
Remote storage:
  Hits:               4 /  233 ( 1.72%)
  Misses:           229 /  233 (98.28%)
+ DOCKER_EXIT_CODE=2
+ '[' /tmpfs/src/github/grpc '!=' '' ']'
+ mkdir -p /tmpfs/src/github/grpc
+ cp -r /tmp/tmp.6PXWJjDfBd/reports /tmp/tmp.6PXWJjDfBd/run_tests /tmpfs/src/github/grpc
+ '[' '' '!=' '' ']'
+ exit 2

=== run_tests.py DEBUG INFO ===
command: "tools/run_tests/run_tests.py --use_docker -t -j 16 -x run_tests/python_linux_opt_native_arm64_default/sponge_log.xml --report_suite_name python_linux_opt_native_arm64_default -l python -c opt --iomgr_platform native --arch arm64 --compiler default --bq_result_table aggregate_results --measure_cpu_costs --report_multi_target"
dockerfile: tools/dockerfile/test/python_debian11_default_arm64
kokoro job name: grpc/core/master/linux/arm64/grpc_basictests_python
===============================