it is not up to the expectations after editing `max_connection_xxx` params【in kubernetes】 about grpc-node

[murgatroid99]

I don't think that error message comes from this library. As a result, I don't think the client is actually failing to reconnect. I think it is connecting successfully, and the server is sending that error. You could confirm this by running the client and server with the environment variables GRPC_TRACE=all and GRPC_VERBOSITY=DEBUG, and performing your test and sharing the logs that are output.

[wang-xiaowu]

I don't think that error message comes from this library. As a result, I don't think the client is actually failing to reconnect. I think it is connecting successfully, and the server is sending that error. You could confirm this by running the client and server with the environment variables GRPC_TRACE=all and GRPC_VERBOSITY=DEBUG, and performing your test and sharing the logs that are output.

i have added the env variables in my server and client, but show nothing.
and btw, i have interceptors in the client, they output nothing either, can it prove no requests reached?

# export|grep GRPC
declare -x GRPC_TRACE="true"
declare -x GRPC_VERBOSITY="DEBUG"

and by this doc, i didn't see the 'true' value is included within GRPC_TRACE

[wang-xiaowu]

ok, maybe it should be all

• this is the server's log after redeploying the server deployment

2024-05-15T14:55:37: PM2 log: Launching in no daemon mode
2024-05-15T14:55:37: PM2 log: App [pm2-metrics:0] starting in -fork mode-
2024-05-15T14:55:37: PM2 log: App [pm2-metrics:0] online
2024-05-15T14:55:37: PM2 log: App [sapi_category_query_new:1] starting in -fork mode-
2024-05-15T14:55:37: PM2 log: App [sapi_category_query_new:1] online
{"level":30,"time":1715756137755,"pid":18,"hostname":"sapi-category-query-new-master-77d6ff65b-885wg","msg":"pm2-prometheus-exporter listening at 0.0.0.0:9209","v":1}
{"message":"环境: production\n","timestamp":"2024-05-15 14:55:38.463","type":"out","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"sapi_category_query_new 服务配置参数加载成功\n","timestamp":"2024-05-15 14:55:38.464","type":"out","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:55:38.744Z | v1.10.7 29 | server | (1) Server constructed\n","timestamp":"2024-05-15 14:55:38.744","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:55:38.746Z | v1.10.7 29 | server | (1) bindAsync port=0.0.0.0:34049\n","timestamp":"2024-05-15 14:55:38.746","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:55:38.747Z | v1.10.7 29 | dns_resolver | Resolver constructed for target dns:0.0.0.0:34049\n","timestamp":"2024-05-15 14:55:38.747","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:55:38.748Z | v1.10.7 29 | dns_resolver | Returning IP address for target dns:0.0.0.0:34049\n","timestamp":"2024-05-15 14:55:38.748","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:55:38.760Z | v1.10.7 29 | server | (1) Attempting to bind 0.0.0.0:34049\n","timestamp":"2024-05-15 14:55:38.760","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:55:38.763Z | v1.10.7 29 | server | (1) Successfully bound 0.0.0.0:34049\n","timestamp":"2024-05-15 14:55:38.763","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"gRPC server is listening on 34049\n","timestamp":"2024-05-15 14:55:38.764","type":"out","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:55:46.932Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:38050\n","timestamp":"2024-05-15 14:55:46.932","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:55:46.932Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:38050\n","timestamp":"2024-05-15 14:55:46.933","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:55:46.933Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:38056\nD 2024-05-15T06:55:46.933Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:38056\n","timestamp":"2024-05-15 14:55:46.933","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:55:56.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:55590\n","timestamp":"2024-05-15 14:55:56.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:55:56.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:55590\n","timestamp":"2024-05-15 14:55:56.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:55:56.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:55578\nD 2024-05-15T06:55:56.931Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:55578\n","timestamp":"2024-05-15 14:55:56.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:06.929Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:39158\n","timestamp":"2024-05-15 14:56:06.929","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:06.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:39158\n","timestamp":"2024-05-15 14:56:06.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:06.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:39156\nD 2024-05-15T06:56:06.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:39156\n","timestamp":"2024-05-15 14:56:06.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:16.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:47092\n","timestamp":"2024-05-15 14:56:16.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:16.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:47092\nD 2024-05-15T06:56:16.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:47102\n","timestamp":"2024-05-15 14:56:16.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:16.931Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:47102\n","timestamp":"2024-05-15 14:56:16.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:26.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:54204\n","timestamp":"2024-05-15 14:56:26.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:26.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:54204\n","timestamp":"2024-05-15 14:56:26.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:26.931Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:54214\nD 2024-05-15T06:56:26.931Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:54214\n","timestamp":"2024-05-15 14:56:26.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:36.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:58012\n","timestamp":"2024-05-15 14:56:36.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:36.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:58012\nD 2024-05-15T06:56:36.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:58020\nD 2024-05-15T06:56:36.931Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:58020\n","timestamp":"2024-05-15 14:56:36.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:46.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:42776\n","timestamp":"2024-05-15 14:56:46.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:46.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:42776\n","timestamp":"2024-05-15 14:56:46.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:46.931Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:42790\nD 2024-05-15T06:56:46.931Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:42790\n","timestamp":"2024-05-15 14:56:46.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:56.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:38668\n","timestamp":"2024-05-15 14:56:56.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:56:56.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:38668\nD 2024-05-15T06:56:56.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:38656\nD 2024-05-15T06:56:56.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:38656\n","timestamp":"2024-05-15 14:56:56.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:57:06.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:48628\n","timestamp":"2024-05-15 14:57:06.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:57:06.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:48628\n","timestamp":"2024-05-15 14:57:06.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:57:06.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:48622\nD 2024-05-15T06:57:06.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:48622\n","timestamp":"2024-05-15 14:57:06.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:57:16.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:55050\n","timestamp":"2024-05-15 14:57:16.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:57:16.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:55050\nD 2024-05-15T06:57:16.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:55056\nD 2024-05-15T06:57:16.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:55056\n","timestamp":"2024-05-15 14:57:16.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:57:26.929Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:39364\n","timestamp":"2024-05-15 14:57:26.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:57:26.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:39364\n","timestamp":"2024-05-15 14:57:26.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:57:26.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:39378\nD 2024-05-15T06:57:26.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:39378\n","timestamp":"2024-05-15 14:57:26.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:57:36.929Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:37486\n","timestamp":"2024-05-15 14:57:36.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:57:36.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:37486\nD 2024-05-15T06:57:36.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:37484\nD 2024-05-15T06:57:36.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:37484\n","timestamp":"2024-05-15 14:57:36.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:57:46.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:35848\n","timestamp":"2024-05-15 14:57:46.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:57:46.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:35848\nD 2024-05-15T06:57:46.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:35846\nD 2024-05-15T06:57:46.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:35846\n","timestamp":"2024-05-15 14:57:46.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:57:56.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:43018\n","timestamp":"2024-05-15 14:57:56.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:57:56.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:43018\nD 2024-05-15T06:57:56.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:43030\nD 2024-05-15T06:57:56.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:43030\n","timestamp":"2024-05-15 14:57:56.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:58:06.929Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:54260\n","timestamp":"2024-05-15 14:58:06.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:58:06.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:54260\nD 2024-05-15T06:58:06.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:54262\nD 2024-05-15T06:58:06.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:54262\n","timestamp":"2024-05-15 14:58:06.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:58:16.929Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:58244\n","timestamp":"2024-05-15 14:58:16.929","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:58:16.929Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:58244\nD 2024-05-15T06:58:16.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:58258\nD 2024-05-15T06:58:16.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:58258\n","timestamp":"2024-05-15 14:58:16.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:58:26.929Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:50186\n","timestamp":"2024-05-15 14:58:26.929","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:58:26.929Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:50186\nD 2024-05-15T06:58:26.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:50188\nD 2024-05-15T06:58:26.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:50188\n","timestamp":"2024-05-15 14:58:26.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:58:36.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:51830\n","timestamp":"2024-05-15 14:58:36.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:58:36.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:51830\nD 2024-05-15T06:58:36.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:51828\nD 2024-05-15T06:58:36.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:51828\n","timestamp":"2024-05-15 14:58:36.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:58:46.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:59752\n","timestamp":"2024-05-15 14:58:46.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:58:46.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:59752\nD 2024-05-15T06:58:46.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:59768\nD 2024-05-15T06:58:46.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:59768\n","timestamp":"2024-05-15 14:58:46.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:58:56.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:58444\n","timestamp":"2024-05-15 14:58:56.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:58:56.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:58444\nD 2024-05-15T06:58:56.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:58458\nD 2024-05-15T06:58:56.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:58458\n","timestamp":"2024-05-15 14:58:56.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:59:06.929Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:33452\n","timestamp":"2024-05-15 14:59:06.929","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:59:06.929Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:33452\nD 2024-05-15T06:59:06.929Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:33454\nD 2024-05-15T06:59:06.929Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:33454\n","timestamp":"2024-05-15 14:59:06.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:59:16.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:51406\n","timestamp":"2024-05-15 14:59:16.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:59:16.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:51406\nD 2024-05-15T06:59:16.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:51410\nD 2024-05-15T06:59:16.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:51410\n","timestamp":"2024-05-15 14:59:16.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:59:26.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:58544\n","timestamp":"2024-05-15 14:59:26.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:59:26.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:58544\nD 2024-05-15T06:59:26.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:58558\nD 2024-05-15T06:59:26.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:58558\n","timestamp":"2024-05-15 14:59:26.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:59:36.929Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:38684\n","timestamp":"2024-05-15 14:59:36.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:59:36.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:38684\nD 2024-05-15T06:59:36.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:38686\nD 2024-05-15T06:59:36.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:38686\n","timestamp":"2024-05-15 14:59:36.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:59:46.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:51784\n","timestamp":"2024-05-15 14:59:46.930","type":"err","process_id":1,"app_name":"sapi_category_query_new"}
{"message":"D 2024-05-15T06:59:46.930Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:51784\nD 2024-05-15T06:59:46.930Z | v1.10.7 29 | server | (1) Connection established by client 10.42.4.1:51782\nD 2024-05-15T06:59:46.931Z | v1.10.7 29 | server | (1) Enable idle timeout for 10.42.4.1:51782\n","timestamp":"2024-05-15 14:59:46.931","type":"err","process_id":1,"app_name":"sapi_category_query_new"}

• this is the client's log(including the content before redeploying the server deployment)

2024-05-15T14:54:09: PM2 log: Launching in no daemon mode
2024-05-15T14:54:09: PM2 log: App [gsapi_category_query_new:0] starting in -fork mode-
2024-05-15T14:54:09: PM2 log: App [gsapi_category_query_new:0] online
{"message":"环境: release\n","timestamp":"2024-05-15 14:54:10.702","type":"out","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"gsapi_category_query_new 服务配置参数加载成功\n","timestamp":"2024-05-15 14:54:10.703","type":"out","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:54:10.734Z | v1.10.7 18 | resolving_load_balancer | dns:///sapi-category-query-new.sopei-grpc:34049 IDLE -> IDLE\n","timestamp":"2024-05-15 14:54:10.735","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:54:10.735Z | v1.10.7 18 | connectivity_state | (1) dns:///sapi-category-query-new.sopei-grpc:34049 IDLE -> IDLE\nD 2024-05-15T06:54:10.735Z | v1.10.7 18 | dns_resolver | Resolver constructed for target dns:///sapi-category-query-new.sopei-grpc:34049\n","timestamp":"2024-05-15 14:54:10.736","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:54:10.737Z | v1.10.7 18 | channel | (1) dns:///sapi-category-query-new.sopei-grpc:34049 Channel constructed with options {\n  \"grpc.service_config\": \"{\\\"loadBalancingConfig\\\":[{\\\"round_robin\\\":{}}]}\",\n  \"grpc.keepalive_time_ms\": 10000,\n  \"grpc.keepalive_timeout_ms\": 1000,\n  \"grpc.keepalive_permit_without_calls\": 1,\n  \"grpc.dns_min_time_between_resolutions_ms\": 2000\n}\n","timestamp":"2024-05-15 14:54:10.737","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:54:10.747Z | v1.10.7 18 | channel_stacktrace | (1) Channel constructed \n    at new InternalChannel (/user/src/app/node_modules/@grpc/grpc-js/src/internal-channel.ts:412:19)\n    at new ChannelImplementation (/user/src/app/node_modules/@grpc/grpc-js/src/channel.ts:115:28)\n    at new Client (/user/src/app/node_modules/@grpc/grpc-js/src/client.ts:158:30)\n    at new ServiceClientImpl (/user/src/app/node_modules/@grpc/grpc-js/src/make-client.ts:129:3)\n    at Object.<anonymous> (/user/src/app/sapi/helloworld.js:14:16)\n    at Module._compile (node:internal/modules/cjs/loader:1105:14)\n    at Module._compile (/user/src/app/node_modules/pirates/lib/index.js:117:24)\n    at Module._extensions..js (node:internal/modules/cjs/loader:1159:10)\n    at Object.newLoader [as .js] (/user/src/app/node_modules/pirates/lib/index.js:121:7)\n    at Module.load (node:internal/modules/cjs/loader:981:32)\n    at Function.Module._load (node:internal/modules/cjs/loader:822:12)\n    at Module.require (node:internal/modules/cjs/loader:1005:19)\n    at Module.Hook._require.Module.require (/usr/local/lib/node_modules/pm2/node_modules/require-in-the-middle/index.js:101:39)\n    at require (node:internal/modules/cjs/helpers:102:18)\n    at Object.<anonymous> (/user/src/app/controller/partsku.js:1:1)\n    at Module._compile (node:internal/modules/cjs/loader:1105:14)\n    at Module._compile (/user/src/app/node_modules/pirates/lib/index.js:117:24)\n    at Module._extensions..js (node:internal/modules/cjs/loader:1159:10)\n    at Object.newLoader [as .js] (/user/src/app/node_modules/pirates/lib/index.js:121:7)\n    at Module.load (node:internal/modules/cjs/loader:981:32)\n    at Function.Module._load (node:internal/modules/cjs/loader:822:12)\n    at Module.require (node:internal/modules/cjs/loader:1005:19)\n    at Module.Hook._require.Module.require (/usr/local/lib/node_modules/pm2/node_modules/require-in-the-middle/index.js:101:39)\n    at require (node:internal/modules/cjs/helpers:102:18)\n    at Object.<anonymous> (/user/src/app/routes/partsku.js:2:1)\n    at Module._compile (node:internal/modules/cjs/loader:1105:14)\n    at Module._compile (/user/src/app/node_modules/pirates/lib/index.js:117:24)\n    at Module._extensions..js (node:internal/modules/cjs/loader:1159:10)\n    at Object.newLoader [as .js] (/user/src/app/node_modules/pirates/lib/index.js:121:7)\n    at Module.load (node:internal/modules/cjs/loader:981:32)\n    at Function.Module._load (node:internal/modules/cjs/loader:822:12)\n    at Module.require (node:internal/modules/cjs/loader:1005:19)\n    at Module.Hook._require.Module.require (/usr/local/lib/node_modules/pm2/node_modules/require-in-the-middle/index.js:101:39)\n    at require (node:internal/modules/cjs/helpers:102:18)\n    at Object.<anonymous> (/user/src/app/routes/index.js:1:1)\n    at Module._compile (node:internal/modules/cjs/loader:1105:14)\n    at Module._compile (/user/src/app/node_modules/pirates/lib/index.js:117:24)\n    at Module._extensions..js (node:internal/modules/cjs/loader:1159:10)\n    at Object.newLoader [as .js] (/user/src/app/node_modules/pirates/lib/index.js:121:7)\n    at Module.load (node:internal/modules/cjs/loader:981:32)\n    at Function.Module._load (node:internal/modules/cjs/loader:822:12)\n    at Module.require (node:internal/modules/cjs/loader:1005:19)\n    at Module.Hook._require.Module.require (/usr/local/lib/node_modules/pm2/node_modules/require-in-the-middle/index.js:101:39)\n    at require (node:internal/modules/cjs/helpers:102:18)\n    at Object.<anonymous> (/user/src/app/app.js:8:1)\n    at Module._compile (node:internal/modules/cjs/loader:1105:14)\n    at Module._compile (/user/src/app/node_modules/pirates/lib/index.js:117:24)\n    at Module._extensions..js (node:internal/modules/cjs/loader:1159:10)\n    at Object.newLoader [as .js] (/user/src/app/node_modules/pirates/lib/index.js:121:7)\n    at Module.load (node:internal/modules/cjs/loader:981:32)\n","timestamp":"2024-05-15 14:54:10.747","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:54:11.052Z | v1.10.7 18 | resolving_load_balancer | dns:///sapi-category-query-new.sopei-grpc:34049 IDLE -> IDLE\n","timestamp":"2024-05-15 14:54:11.052","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:54:11.052Z | v1.10.7 18 | connectivity_state | (2) dns:///sapi-category-query-new.sopei-grpc:34049 IDLE -> IDLE\nD 2024-05-15T06:54:11.052Z | v1.10.7 18 | dns_resolver | Resolver constructed for target dns:///sapi-category-query-new.sopei-grpc:34049\n","timestamp":"2024-05-15 14:54:11.052","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:54:11.053Z | v1.10.7 18 | channel | (2) dns:///sapi-category-query-new.sopei-grpc:34049 Channel constructed with options {\n  \"grpc.service_config\": \"{\\\"loadBalancingConfig\\\":[{\\\"round_robin\\\":{}}]}\"\n}\n","timestamp":"2024-05-15 14:54:11.053","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:54:11.057Z | v1.10.7 18 | channel_stacktrace | (2) Channel constructed \n    at new InternalChannel (/user/src/app/node_modules/@grpc/grpc-js/src/internal-channel.ts:412:19)\n    at new ChannelImplementation (/user/src/app/node_modules/@grpc/grpc-js/src/channel.ts:115:28)\n    at new Client (/user/src/app/node_modules/@grpc/grpc-js/src/client.ts:158:30)\n    at new ServiceClientImpl (/user/src/app/node_modules/@grpc/grpc-js/src/make-client.ts:129:3)\n    at Object.<anonymous> (/user/src/app/sapi/sapiEs.js:14:16)\n    at Module._compile (node:internal/modules/cjs/loader:1105:14)\n    at Module._compile (/user/src/app/node_modules/pirates/lib/index.js:117:24)\n    at Module._extensions..js (node:internal/modules/cjs/loader:1159:10)\n    at Object.newLoader [as .js] (/user/src/app/node_modules/pirates/lib/index.js:121:7)\n    at Module.load (node:internal/modules/cjs/loader:981:32)\n    at Function.Module._load (node:internal/modules/cjs/loader:822:12)\n    at Module.require (node:internal/modules/cjs/loader:1005:19)\n    at Module.Hook._require.Module.require (/usr/local/lib/node_modules/pm2/node_modules/require-in-the-middle/index.js:101:39)\n    at require (node:internal/modules/cjs/helpers:102:18)\n    at Object.<anonymous> (/user/src/app/service/common.js:3:1)\n    at Module._compile (node:internal/modules/cjs/loader:1105:14)\n    at Module._compile (/user/src/app/node_modules/pirates/lib/index.js:117:24)\n    at Module._extensions..js (node:internal/modules/cjs/loader:1159:10)\n    at Object.newLoader [as .js] (/user/src/app/node_modules/pirates/lib/index.js:121:7)\n    at Module.load (node:internal/modules/cjs/loader:981:32)\n    at Function.Module._load (node:internal/modules/cjs/loader:822:12)\n    at Module.require (node:internal/modules/cjs/loader:1005:19)\n    at Module.Hook._require.Module.require (/usr/local/lib/node_modules/pm2/node_modules/require-in-the-middle/index.js:101:39)\n    at require (node:internal/modules/cjs/helpers:102:18)\n    at Object.<anonymous> (/user/src/app/controller/partsku.js:6:1)\n    at Module._compile (node:internal/modules/cjs/loader:1105:14)\n    at Module._compile (/user/src/app/node_modules/pirates/lib/index.js:117:24)\n    at Module._extensions..js (node:internal/modules/cjs/loader:1159:10)\n    at Object.newLoader [as .js] (/user/src/app/node_modules/pirates/lib/index.js:121:7)\n    at Module.load (node:internal/modules/cjs/loader:981:32)\n    at Function.Module._load (node:internal/modules/cjs/loader:822:12)\n    at Module.require (node:internal/modules/cjs/loader:1005:19)\n    at Module.Hook._require.Module.require (/usr/local/lib/node_modules/pm2/node_modules/require-in-the-middle/index.js:101:39)\n    at require (node:internal/modules/cjs/helpers:102:18)\n    at Object.<anonymous> (/user/src/app/routes/partsku.js:2:1)\n    at Module._compile (node:internal/modules/cjs/loader:1105:14)\n    at Module._compile (/user/src/app/node_modules/pirates/lib/index.js:117:24)\n    at Module._extensions..js (node:internal/modules/cjs/loader:1159:10)\n    at Object.newLoader [as .js] (/user/src/app/node_modules/pirates/lib/index.js:121:7)\n    at Module.load (node:internal/modules/cjs/loader:981:32)\n    at Function.Module._load (node:internal/modules/cjs/loader:822:12)\n    at Module.require (node:internal/modules/cjs/loader:1005:19)\n    at Module.Hook._require.Module.require (/usr/local/lib/node_modules/pm2/node_modules/require-in-the-middle/index.js:101:39)\n    at require (node:internal/modules/cjs/helpers:102:18)\n    at Object.<anonymous> (/user/src/app/routes/index.js:1:1)\n    at Module._compile (node:internal/modules/cjs/loader:1105:14)\n    at Module._compile (/user/src/app/node_modules/pirates/lib/index.js:117:24)\n    at Module._extensions..js (node:internal/modules/cjs/loader:1159:10)\n    at Object.newLoader [as .js] (/user/src/app/node_modules/pirates/lib/index.js:121:7)\n    at Module.load (node:internal/modules/cjs/loader:981:32)\n","timestamp":"2024-05-15 14:54:11.057","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"strategyFiles: [ 'engineoil.js', 'gearbox.js' ]\n","timestamp":"2024-05-15 14:54:11.151","type":"out","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"Listening on port: 33013\n","timestamp":"2024-05-15 14:54:11.276","type":"out","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"Api prefix: /gsapi/category/query/v2.0\n","timestamp":"2024-05-15 14:54:11.277","type":"out","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.910Z | v1.10.7 18 | channel | (1) dns:///sapi-category-query-new.sopei-grpc:34049 createResolvingCall [0] method=\"/helloworld.Greeter/SayHello\", deadline=2024-05-15T06:55:30.909Z\n","timestamp":"2024-05-15 14:55:20.910","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.910Z | v1.10.7 18 | resolving_call | [0] Created\nD 2024-05-15T06:55:20.910Z | v1.10.7 18 | resolving_call | [0] Deadline: 2024-05-15T06:55:30.909Z\nD 2024-05-15T06:55:20.910Z | v1.10.7 18 | resolving_call | [0] Deadline will be reached in 9999ms\n","timestamp":"2024-05-15 14:55:20.911","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.911Z | v1.10.7 18 | resolving_call | [0] start called\n","timestamp":"2024-05-15 14:55:20.912","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.912Z | v1.10.7 18 | dns_resolver | Looking up DNS hostname sapi-category-query-new.sopei-grpc\n","timestamp":"2024-05-15 14:55:20.912","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.912Z | v1.10.7 18 | resolving_load_balancer | dns:///sapi-category-query-new.sopei-grpc:34049 IDLE -> CONNECTING\nD 2024-05-15T06:55:20.913Z | v1.10.7 18 | connectivity_state | (1) dns:///sapi-category-query-new.sopei-grpc:34049 IDLE -> CONNECTING\nD 2024-05-15T06:55:20.913Z | v1.10.7 18 | channel | (1) dns:///sapi-category-query-new.sopei-grpc:34049 callRefTimer.ref | configSelectionQueue.length=1 pickQueue.length=0\nD 2024-05-15T06:55:20.913Z | v1.10.7 18 | resolving_call | [0] startRead called\n","timestamp":"2024-05-15 14:55:20.913","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.914Z | v1.10.7 18 | resolving_call | [0] write() called with message of length 8\n","timestamp":"2024-05-15 14:55:20.914","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.914Z | v1.10.7 18 | resolving_call | [0] halfClose called\n","timestamp":"2024-05-15 14:55:20.915","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.915Z | v1.10.7 18 | dns_resolver | Resolved addresses for target dns:///sapi-category-query-new.sopei-grpc:34049: [10.42.4.144:34049]\n","timestamp":"2024-05-15 14:55:20.915","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.915Z | v1.10.7 18 | round_robin | Connect to endpoint list [10.42.4.144:34049]\n","timestamp":"2024-05-15 14:55:20.916","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.917Z | v1.10.7 18 | subchannel | (1) 10.42.4.144:34049 Subchannel constructed with options {\n  \"grpc.service_config\": \"{\\\"loadBalancingConfig\\\":[{\\\"round_robin\\\":{}}]}\",\n  \"grpc.keepalive_time_ms\": 10000,\n  \"grpc.keepalive_timeout_ms\": 1000,\n  \"grpc.keepalive_permit_without_calls\": 1,\n  \"grpc.dns_min_time_between_resolutions_ms\": 2000\n}\n","timestamp":"2024-05-15 14:55:20.917","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.917Z | v1.10.7 18 | subchannel_refcount | (1) 10.42.4.144:34049 refcount 0 -> 1\nD 2024-05-15T06:55:20.917Z | v1.10.7 18 | subchannel_refcount | (1) 10.42.4.144:34049 refcount 1 -> 2\n","timestamp":"2024-05-15 14:55:20.917","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.917Z | v1.10.7 18 | pick_first | Start connecting to subchannel with address 10.42.4.144:34049\nD 2024-05-15T06:55:20.918Z | v1.10.7 18 | pick_first | IDLE -> CONNECTING\n","timestamp":"2024-05-15 14:55:20.918","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.918Z | v1.10.7 18 | round_robin | IDLE -> CONNECTING\nD 2024-05-15T06:55:20.918Z | v1.10.7 18 | resolving_load_balancer | dns:///sapi-category-query-new.sopei-grpc:34049 CONNECTING -> CONNECTING\nD 2024-05-15T06:55:20.918Z | v1.10.7 18 | connectivity_state | (1) dns:///sapi-category-query-new.sopei-grpc:34049 CONNECTING -> CONNECTING\n","timestamp":"2024-05-15 14:55:20.918","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.918Z | v1.10.7 18 | channel | (1) dns:///sapi-category-query-new.sopei-grpc:34049 callRefTimer.unref | configSelectionQueue.length=0 pickQueue.length=0\n","timestamp":"2024-05-15 14:55:20.919","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.919Z | v1.10.7 18 | subchannel | (1) 10.42.4.144:34049 IDLE -> CONNECTING\n","timestamp":"2024-05-15 14:55:20.920","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.920Z | v1.10.7 18 | transport | dns:///sapi-category-query-new.sopei-grpc:34049 creating HTTP/2 session to 10.42.4.144:34049\n","timestamp":"2024-05-15 14:55:20.920","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.922Z | v1.10.7 18 | channel | (1) dns:///sapi-category-query-new.sopei-grpc:34049 createRetryingCall [1] method=\"/helloworld.Greeter/SayHello\"\n","timestamp":"2024-05-15 14:55:20.922","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.922Z | v1.10.7 18 | resolving_call | [0] Created child [1]\nD 2024-05-15T06:55:20.922Z | v1.10.7 18 | retrying_call | [1] start called\n","timestamp":"2024-05-15 14:55:20.922","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.922Z | v1.10.7 18 | channel | (1) dns:///sapi-category-query-new.sopei-grpc:34049 createLoadBalancingCall [2] method=\"/helloworld.Greeter/SayHello\"\nD 2024-05-15T06:55:20.922Z | v1.10.7 18 | retrying_call | [1] Created child call [2] for attempt 1\nD 2024-05-15T06:55:20.922Z | v1.10.7 18 | load_balancing_call | [2] start called\n","timestamp":"2024-05-15 14:55:20.923","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.923Z | v1.10.7 18 | load_balancing_call | [2] Pick called\nD 2024-05-15T06:55:20.923Z | v1.10.7 18 | load_balancing_call | [2] Pick result: QUEUE subchannel: null status: undefined undefined\n","timestamp":"2024-05-15 14:55:20.923","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.923Z | v1.10.7 18 | channel | (1) dns:///sapi-category-query-new.sopei-grpc:34049 callRefTimer.ref | configSelectionQueue.length=0 pickQueue.length=1\nD 2024-05-15T06:55:20.923Z | v1.10.7 18 | retrying_call | [1] startRead called\nD 2024-05-15T06:55:20.923Z | v1.10.7 18 | load_balancing_call | [2] startRead called\n","timestamp":"2024-05-15 14:55:20.924","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.924Z | v1.10.7 18 | retrying_call | [1] write() called with message of length 13\n","timestamp":"2024-05-15 14:55:20.924","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.924Z | v1.10.7 18 | load_balancing_call | [2] write() called with message of length 13\nD 2024-05-15T06:55:20.925Z | v1.10.7 18 | retrying_call | [1] halfClose called\n","timestamp":"2024-05-15 14:55:20.925","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.926Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:55:20.926","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.926Z | v1.10.7 18 | subchannel | (1) 10.42.4.144:34049 CONNECTING -> READY\nD 2024-05-15T06:55:20.927Z | v1.10.7 18 | pick_first | Pick subchannel with address 10.42.4.144:34049\nD 2024-05-15T06:55:20.927Z | v1.10.7 18 | subchannel_refcount | (1) 10.42.4.144:34049 refcount 2 -> 3\n","timestamp":"2024-05-15 14:55:20.927","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.927Z | v1.10.7 18 | subchannel_refcount | (1) 10.42.4.144:34049 refcount 3 -> 2\nD 2024-05-15T06:55:20.927Z | v1.10.7 18 | pick_first | CONNECTING -> READY\nD 2024-05-15T06:55:20.927Z | v1.10.7 18 | round_robin | CONNECTING -> READY\nD 2024-05-15T06:55:20.927Z | v1.10.7 18 | resolving_load_balancer | dns:///sapi-category-query-new.sopei-grpc:34049 CONNECTING -> READY\nD 2024-05-15T06:55:20.927Z | v1.10.7 18 | channel | (1) dns:///sapi-category-query-new.sopei-grpc:34049 callRefTimer.unref | configSelectionQueue.length=0 pickQueue.length=0\nD 2024-05-15T06:55:20.927Z | v1.10.7 18 | load_balancing_call | [2] Pick called\n","timestamp":"2024-05-15 14:55:20.927","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.927Z | v1.10.7 18 | load_balancing_call | [2] Pick result: COMPLETE subchannel: (1) 10.42.4.144:34049 status: undefined undefined\nD 2024-05-15T06:55:20.928Z | v1.10.7 18 | connectivity_state | (1) dns:///sapi-category-query-new.sopei-grpc:34049 CONNECTING -> READY\n","timestamp":"2024-05-15 14:55:20.928","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.930Z | v1.10.7 18 | transport_flowctrl | (1) 10.42.4.144:34049 local window size: 65535 remote window size: 65535\n","timestamp":"2024-05-15 14:55:20.930","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.930Z | v1.10.7 18 | transport_internals | (1) 10.42.4.144:34049 session.closed=false session.destroyed=false session.socket.destroyed=false\n","timestamp":"2024-05-15 14:55:20.930","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.930Z | v1.10.7 18 | load_balancing_call | [2] Created child call [3]\nD 2024-05-15T06:55:20.930Z | v1.10.7 18 | subchannel_call | [3] write() called with message of length 13\nD 2024-05-15T06:55:20.930Z | v1.10.7 18 | subchannel_call | [3] sending data chunk of length 13\n","timestamp":"2024-05-15 14:55:20.931","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.932Z | v1.10.7 18 | load_balancing_call | [2] halfClose called\n","timestamp":"2024-05-15 14:55:20.932","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.932Z | v1.10.7 18 | subchannel_call | [3] end() called\nD 2024-05-15T06:55:20.932Z | v1.10.7 18 | subchannel_call | [3] calling end() on HTTP/2 stream\n","timestamp":"2024-05-15 14:55:20.932","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.933Z | v1.10.7 18 | transport | (1) 10.42.4.144:34049 local settings acknowledged by remote: {\"headerTableSize\":4096,\"enablePush\":true,\"initialWindowSize\":65535,\"maxFrameSize\":16384,\"maxConcurrentStreams\":4294967295,\"maxHeaderListSize\":4294967295,\"maxHeaderSize\":4294967295,\"enableConnectProtocol\":false}\n","timestamp":"2024-05-15 14:55:20.933","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.944Z | v1.10.7 18 | subchannel_call | [3] Received server headers:\n\t\t:status: 200\n\t\tcontent-type: application/grpc+proto\n\t\tgrpc-accept-encoding: identity,deflate,gzip\n\t\tgrpc-encoding: identity\n\t\tdate: Wed, 15 May 2024 06:55:20 GMT\n\t\tx-envoy-upstream-service-time: 11\n\t\tserver: envoy\n\n","timestamp":"2024-05-15 14:55:20.944","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.945Z | v1.10.7 18 | load_balancing_call | [2] Received metadata\n","timestamp":"2024-05-15 14:55:20.945","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.945Z | v1.10.7 18 | retrying_call | [1] Received metadata from child [2]\nD 2024-05-15T06:55:20.945Z | v1.10.7 18 | retrying_call | [1] Committing call [2] at index 0\nD 2024-05-15T06:55:20.945Z | v1.10.7 18 | resolving_call | [0] Received metadata\nD 2024-05-15T06:55:20.945Z | v1.10.7 18 | subchannel_call | [3] receive HTTP/2 data frame of length 33\n","timestamp":"2024-05-15 14:55:20.945","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.946Z | v1.10.7 18 | subchannel_call | [3] parsed message of length 33\n","timestamp":"2024-05-15 14:55:20.946","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.946Z | v1.10.7 18 | subchannel_call | [3] pushing to reader message of length 33\nD 2024-05-15T06:55:20.946Z | v1.10.7 18 | load_balancing_call | [2] Received message\n","timestamp":"2024-05-15 14:55:20.946","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.946Z | v1.10.7 18 | retrying_call | [1] Received message from child [2]\nD 2024-05-15T06:55:20.946Z | v1.10.7 18 | resolving_call | [0] Received message\nD 2024-05-15T06:55:20.947Z | v1.10.7 18 | resolving_call | [0] Finished filtering received message\n","timestamp":"2024-05-15 14:55:20.947","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.952Z | v1.10.7 18 | subchannel_call | [3] Received server trailers:\n\t\tgrpc-status: 0\n\t\tgrpc-message: OK\n\n","timestamp":"2024-05-15 14:55:20.952","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.952Z | v1.10.7 18 | subchannel_call | [3] received status code 0 from server\nD 2024-05-15T06:55:20.952Z | v1.10.7 18 | subchannel_call | [3] received status details string \"OK\" from server\n","timestamp":"2024-05-15 14:55:20.953","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.953Z | v1.10.7 18 | subchannel_call | [3] ended with status: code=0 details=\"OK\"\n","timestamp":"2024-05-15 14:55:20.953","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.954Z | v1.10.7 18 | load_balancing_call | [2] Received status\n","timestamp":"2024-05-15 14:55:20.954","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.954Z | v1.10.7 18 | load_balancing_call | [2] ended with status: code=0 details=\"OK\" start time=2024-05-15T06:55:20.922Z\nD 2024-05-15T06:55:20.954Z | v1.10.7 18 | retrying_call | [1] Received status from child [2]\n","timestamp":"2024-05-15 14:55:20.954","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.954Z | v1.10.7 18 | retrying_call | [1] state=COMMITTED handling status with progress PROCESSED from child [2] in state ACTIVE\nD 2024-05-15T06:55:20.954Z | v1.10.7 18 | retrying_call | [1] ended with status: code=0 details=\"OK\" start time=2024-05-15T06:55:20.922Z\nD 2024-05-15T06:55:20.955Z | v1.10.7 18 | resolving_call | [0] Received status\n","timestamp":"2024-05-15 14:55:20.955","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.955Z | v1.10.7 18 | resolving_call | [0] ended with status: code=0 details=\"OK\"\n","timestamp":"2024-05-15 14:55:20.955","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:20.955Z | v1.10.7 18 | subchannel_call | [3] HTTP/2 stream closed with code 0\n","timestamp":"2024-05-15 14:55:20.956","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"\u001b[34m[2024-05-15 14:55:20.957] [ACC]\u001b[39m ~ gsapi_category_query_new ~ a17346d2-f118-4697-813e-bb34d235bc08 ~ gsapi-category-query-new-master-749cd76bfc-kg67n ~ 127.0.0.1 ~ /gsapi/category/query/v2.0/test2 ~ HTTP/1.1 ~ POST ~ 200 ~ 57 ~ - ~ curl/7.74.0 ~ 49 ~ 0\n","timestamp":"2024-05-15 14:55:20.958","type":"out","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:30.926Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:55:30.926","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:30.926Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\n","timestamp":"2024-05-15 14:55:30.927","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:30.927Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:55:30.927","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:40.927Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:55:40.927","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:40.927Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:55:40.927Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:55:40.927","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:50.927Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:55:50.927","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:55:50.928Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:55:50.928Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:55:50.928","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:56:00.928Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:56:00.928","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:56:00.928Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:56:00.928Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:56:00.928","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:56:10.929Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:56:10.929","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:56:10.930Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\n","timestamp":"2024-05-15 14:56:10.930","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:56:10.930Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:56:10.930","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:56:20.929Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:56:20.930","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:56:20.930Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:56:20.930Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:56:20.930","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:56:30.930Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:56:30.931","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:56:30.931Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:56:30.931Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:56:30.931","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:56:40.931Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:56:40.931","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:56:40.932Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:56:40.932Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:56:40.932","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:56:50.932Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:56:50.932","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:56:50.932Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:56:50.933Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:56:50.933","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:57:00.933Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:57:00.933","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:57:00.933Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:57:00.933Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:57:00.933","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:57:10.933Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:57:10.934","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:57:10.934Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:57:10.934Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:57:10.934","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:57:20.934Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:57:20.934","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:57:20.934Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:57:20.934Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:57:20.934","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:57:30.934Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:57:30.934","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:57:30.934Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:57:30.934Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:57:30.935","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:57:40.934Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:57:40.934","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:57:40.934Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:57:40.934Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:57:40.934","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:57:50.934Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:57:50.934","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:57:50.935Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:57:50.935Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:57:50.935","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:00.935Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:58:00.936","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:00.936Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:58:00.936Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:58:00.936","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:10.935Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:58:10.936","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:10.936Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:58:10.936Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:58:10.936","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:20.937Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:58:20.937","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:20.937Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:58:20.937Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:58:20.937","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:28.883Z | v1.10.7 18 | channel | (1) dns:///sapi-category-query-new.sopei-grpc:34049 createResolvingCall [4] method=\"/helloworld.Greeter/SayHello\", deadline=2024-05-15T06:58:38.883Z\n","timestamp":"2024-05-15 14:58:28.883","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:28.883Z | v1.10.7 18 | resolving_call | [4] Created\nD 2024-05-15T06:58:28.883Z | v1.10.7 18 | resolving_call | [4] Deadline: 2024-05-15T06:58:38.883Z\nD 2024-05-15T06:58:28.883Z | v1.10.7 18 | resolving_call | [4] Deadline will be reached in 10000ms\nD 2024-05-15T06:58:28.884Z | v1.10.7 18 | resolving_call | [4] start called\nD 2024-05-15T06:58:28.884Z | v1.10.7 18 | resolving_call | [4] startRead called\nD 2024-05-15T06:58:28.884Z | v1.10.7 18 | resolving_call | [4] write() called with message of length 8\nD 2024-05-15T06:58:28.884Z | v1.10.7 18 | resolving_call | [4] halfClose called\n","timestamp":"2024-05-15 14:58:28.884","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:28.884Z | v1.10.7 18 | channel | (1) dns:///sapi-category-query-new.sopei-grpc:34049 createRetryingCall [5] method=\"/helloworld.Greeter/SayHello\"\nD 2024-05-15T06:58:28.884Z | v1.10.7 18 | resolving_call | [4] Created child [5]\nD 2024-05-15T06:58:28.884Z | v1.10.7 18 | retrying_call | [5] start called\nD 2024-05-15T06:58:28.884Z | v1.10.7 18 | channel | (1) dns:///sapi-category-query-new.sopei-grpc:34049 createLoadBalancingCall [6] method=\"/helloworld.Greeter/SayHello\"\nD 2024-05-15T06:58:28.884Z | v1.10.7 18 | retrying_call | [5] Created child call [6] for attempt 1\nD 2024-05-15T06:58:28.884Z | v1.10.7 18 | load_balancing_call | [6] start called\nD 2024-05-15T06:58:28.884Z | v1.10.7 18 | load_balancing_call | [6] Pick called\nD 2024-05-15T06:58:28.884Z | v1.10.7 18 | load_balancing_call | [6] Pick result: COMPLETE subchannel: (1) 10.42.4.144:34049 status: undefined undefined\nD 2024-05-15T06:58:28.884Z | v1.10.7 18 | retrying_call | [5] startRead called\nD 2024-05-15T06:58:28.884Z | v1.10.7 18 | load_balancing_call | [6] startRead called\n","timestamp":"2024-05-15 14:58:28.885","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:28.885Z | v1.10.7 18 | transport_flowctrl | (1) 10.42.4.144:34049 local window size: 65502 remote window size: 268435443\nD 2024-05-15T06:58:28.885Z | v1.10.7 18 | transport_internals | (1) 10.42.4.144:34049 session.closed=false session.destroyed=false session.socket.destroyed=false\nD 2024-05-15T06:58:28.885Z | v1.10.7 18 | load_balancing_call | [6] Created child call [7]\nD 2024-05-15T06:58:28.885Z | v1.10.7 18 | retrying_call | [5] write() called with message of length 13\nD 2024-05-15T06:58:28.885Z | v1.10.7 18 | load_balancing_call | [6] write() called with message of length 13\nD 2024-05-15T06:58:28.885Z | v1.10.7 18 | subchannel_call | [7] write() called with message of length 13\nD 2024-05-15T06:58:28.885Z | v1.10.7 18 | subchannel_call | [7] sending data chunk of length 13\nD 2024-05-15T06:58:28.885Z | v1.10.7 18 | retrying_call | [5] halfClose called\n","timestamp":"2024-05-15 14:58:28.885","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:28.885Z | v1.10.7 18 | load_balancing_call | [6] halfClose called\nD 2024-05-15T06:58:28.885Z | v1.10.7 18 | subchannel_call | [7] end() called\nD 2024-05-15T06:58:28.885Z | v1.10.7 18 | subchannel_call | [7] calling end() on HTTP/2 stream\n","timestamp":"2024-05-15 14:58:28.886","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:30.937Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:58:30.937","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:30.937Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:58:30.937Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:58:30.938","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:38.885Z | v1.10.7 18 | resolving_call | [4] cancelWithStatus code: 4 details: \"Deadline exceeded after 10.001s,name resolution: 0.001s,LB pick: 0.001s,remote_addr=10.42.4.144:34049\"\n","timestamp":"2024-05-15 14:58:38.885","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:38.885Z | v1.10.7 18 | retrying_call | [5] cancelWithStatus code: 4 details: \"Deadline exceeded after 10.001s,name resolution: 0.001s,LB pick: 0.001s,remote_addr=10.42.4.144:34049\"\nD 2024-05-15T06:58:38.885Z | v1.10.7 18 | retrying_call | [5] ended with status: code=4 details=\"Deadline exceeded after 10.001s,name resolution: 0.001s,LB pick: 0.001s,remote_addr=10.42.4.144:34049\" start time=2024-05-15T06:58:28.884Z\nD 2024-05-15T06:58:38.885Z | v1.10.7 18 | load_balancing_call | [6] cancelWithStatus code: 4 details: \"Deadline exceeded after 10.001s,name resolution: 0.001s,LB pick: 0.001s,remote_addr=10.42.4.144:34049\"\nD 2024-05-15T06:58:38.885Z | v1.10.7 18 | subchannel_call | [7] cancelWithStatus code: 4 details: \"Deadline exceeded after 10.001s,name resolution: 0.001s,LB pick: 0.001s,remote_addr=10.42.4.144:34049\"\nD 2024-05-15T06:58:38.885Z | v1.10.7 18 | subchannel_call | [7] ended with status: code=4 details=\"Deadline exceeded after 10.001s,name resolution: 0.001s,LB pick: 0.001s,remote_addr=10.42.4.144:34049\"\n","timestamp":"2024-05-15 14:58:38.885","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:38.885Z | v1.10.7 18 | subchannel_call | [7] close http2 stream with code 8\nD 2024-05-15T06:58:38.886Z | v1.10.7 18 | load_balancing_call | [6] ended with status: code=4 details=\"Deadline exceeded after 10.001s,name resolution: 0.001s,LB pick: 0.001s,remote_addr=10.42.4.144:34049\" start time=2024-05-15T06:58:28.884Z\nD 2024-05-15T06:58:38.886Z | v1.10.7 18 | retrying_call | [5] Received status from child [6]\nD 2024-05-15T06:58:38.886Z | v1.10.7 18 | retrying_call | [5] state=TRANSPARENT_ONLY handling status with progress PROCESSED from child [6] in state ACTIVE\nD 2024-05-15T06:58:38.886Z | v1.10.7 18 | retrying_call | [5] ended with status: code=4 details=\"Deadline exceeded after 10.001s,name resolution: 0.001s,LB pick: 0.001s,remote_addr=10.42.4.144:34049\" start time=2024-05-15T06:58:28.884Z\nD 2024-05-15T06:58:38.886Z | v1.10.7 18 | resolving_call | [4] ended with status: code=4 details=\"Deadline exceeded after 10.001s,name resolution: 0.001s,LB pick: 0.001s,remote_addr=10.42.4.144:34049\"\nD 2024-05-15T06:58:38.886Z | v1.10.7 18 | resolving_call | [4] Received status\nD 2024-05-15T06:58:38.886Z | v1.10.7 18 | load_balancing_call | [6] Received status\n","timestamp":"2024-05-15 14:58:38.886","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:38.886Z | v1.10.7 18 | resolving_call | [4] Received status\n","timestamp":"2024-05-15 14:58:38.887","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"\u001b[91m[2024-05-15 14:58:38.902] [ERROR]\u001b[39m ~ gsapi_category_query_new ~ acc3cf06-033e-492e-857e-78d664e58aa0 ~ gsapi-category-query-new-master-749cd76bfc-kg67n ~ POST-/gsapi/category/query/v2.0/test2 ~ 4 DEADLINE_EXCEEDED: Deadline exceeded after 10.001s,name resolution: 0.001s,LB pick: 0.001s,remote_addr=10.42.4.144:34049\n","timestamp":"2024-05-15 14:58:38.902","type":"out","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"\u001b[91m[2024-05-15 14:58:38.903] [ERROR]\u001b[39m ~ gsapi_category_query_new ~ acc3cf06-033e-492e-857e-78d664e58aa0 ~ gsapi-category-query-new-master-749cd76bfc-kg67n ~ POST-/gsapi/category/query/v2.0/test2 ~ Error: next() called multiple times\n    at dispatch (/user/src/app/node_modules/koa-compose/index.js:36:45)\n    at ratelimit (/user/src/app/node_modules/koa-sopei-ratelimit/index.js:186:11)\n    at processTicksAndRejections (node:internal/process/task_queues:96:5)\n    at /user/src/app/app.js:20:5\n    at /user/src/app/node_modules/koa-sopei-log/index.js:76:7\n    at /user/src/app/node_modules/koa-sopei-apm/index.js:20:13\n","timestamp":"2024-05-15 14:58:38.903","type":"out","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"\u001b[34m[2024-05-15 14:58:38.903] [ACC]\u001b[39m ~ gsapi_category_query_new ~ acc3cf06-033e-492e-857e-78d664e58aa0 ~ gsapi-category-query-new-master-749cd76bfc-kg67n ~ 127.0.0.1 ~ /gsapi/category/query/v2.0/test2 ~ HTTP/1.1 ~ POST ~ 200 ~ 64 ~ - ~ curl/7.74.0 ~ 10021 ~ 4003\n","timestamp":"2024-05-15 14:58:38.903","type":"out","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:38.904Z | v1.10.7 18 | subchannel_call | [7] HTTP/2 stream closed with code 8\n","timestamp":"2024-05-15 14:58:38.904","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:40.938Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:58:40.939","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:40.939Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\n","timestamp":"2024-05-15 14:58:40.939","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:40.939Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:58:40.940","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:50.939Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:58:50.939","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:58:50.939Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:58:50.940Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:58:50.940","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:00.940Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:59:00.940","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:00.940Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:59:00.940Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:59:00.940","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:10.941Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:59:10.942","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:10.942Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:59:10.942Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:59:10.942","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:11.226Z | v1.10.7 18 | channel | (1) dns:///sapi-category-query-new.sopei-grpc:34049 createResolvingCall [8] method=\"/helloworld.Greeter/SayHello\", deadline=2024-05-15T06:59:21.226Z\n","timestamp":"2024-05-15 14:59:11.227","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:11.227Z | v1.10.7 18 | resolving_call | [8] Created\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | resolving_call | [8] Deadline: 2024-05-15T06:59:21.226Z\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | resolving_call | [8] Deadline will be reached in 9999ms\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | resolving_call | [8] start called\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | resolving_call | [8] startRead called\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | resolving_call | [8] write() called with message of length 8\n","timestamp":"2024-05-15 14:59:11.227","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:11.227Z | v1.10.7 18 | resolving_call | [8] halfClose called\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | channel | (1) dns:///sapi-category-query-new.sopei-grpc:34049 createRetryingCall [9] method=\"/helloworld.Greeter/SayHello\"\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | resolving_call | [8] Created child [9]\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | retrying_call | [9] start called\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | channel | (1) dns:///sapi-category-query-new.sopei-grpc:34049 createLoadBalancingCall [10] method=\"/helloworld.Greeter/SayHello\"\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | retrying_call | [9] Created child call [10] for attempt 1\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | load_balancing_call | [10] start called\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | load_balancing_call | [10] Pick called\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | load_balancing_call | [10] Pick result: COMPLETE subchannel: (1) 10.42.4.144:34049 status: undefined undefined\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | retrying_call | [9] startRead called\nD 2024-05-15T06:59:11.227Z | v1.10.7 18 | load_balancing_call | [10] startRead called\n","timestamp":"2024-05-15 14:59:11.228","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:11.228Z | v1.10.7 18 | transport_flowctrl | (1) 10.42.4.144:34049 local window size: 65502 remote window size: 268435430\nD 2024-05-15T06:59:11.228Z | v1.10.7 18 | transport_internals | (1) 10.42.4.144:34049 session.closed=false session.destroyed=false session.socket.destroyed=false\nD 2024-05-15T06:59:11.228Z | v1.10.7 18 | load_balancing_call | [10] Created child call [11]\n","timestamp":"2024-05-15 14:59:11.228","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:11.229Z | v1.10.7 18 | retrying_call | [9] write() called with message of length 13\n","timestamp":"2024-05-15 14:59:11.229","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:11.229Z | v1.10.7 18 | load_balancing_call | [10] write() called with message of length 13\nD 2024-05-15T06:59:11.229Z | v1.10.7 18 | subchannel_call | [11] write() called with message of length 13\nD 2024-05-15T06:59:11.229Z | v1.10.7 18 | subchannel_call | [11] sending data chunk of length 13\nD 2024-05-15T06:59:11.229Z | v1.10.7 18 | retrying_call | [9] halfClose called\n","timestamp":"2024-05-15 14:59:11.229","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:11.229Z | v1.10.7 18 | load_balancing_call | [10] halfClose called\nD 2024-05-15T06:59:11.229Z | v1.10.7 18 | subchannel_call | [11] end() called\nD 2024-05-15T06:59:11.229Z | v1.10.7 18 | subchannel_call | [11] calling end() on HTTP/2 stream\n","timestamp":"2024-05-15 14:59:11.230","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:20.478Z | v1.10.7 18 | subchannel_call | [11] Received server headers:\n\t\t:status: 200\n\t\tcontent-type: application/grpc\n\t\tgrpc-status: 14\n\t\tgrpc-message: upstream connect error or disconnect/reset before headers. retried and the latest reset reason: connection failure, transport failure reason: delayed connect error: 113\n\t\tdate: Wed, 15 May 2024 06:59:20 GMT\n\t\tserver: envoy\n\n","timestamp":"2024-05-15 14:59:20.478","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:20.478Z | v1.10.7 18 | subchannel_call | [11] Received server trailers:\n\t\t:status: 200\n\t\tcontent-type: application/grpc\n\t\tgrpc-status: 14\n\t\tgrpc-message: upstream connect error or disconnect/reset before headers. retried and the latest reset reason: connection failure, transport failure reason: delayed connect error: 113\n\t\tdate: Wed, 15 May 2024 06:59:20 GMT\n\t\tserver: envoy\n\nD 2024-05-15T06:59:20.478Z | v1.10.7 18 | subchannel_call | [11] received status code 14 from server\nD 2024-05-15T06:59:20.478Z | v1.10.7 18 | subchannel_call | [11] received status details string \"upstream connect error or disconnect/reset before headers. retried and the latest reset reason: connection failure, transport failure reason: delayed connect error: 113\" from server\nD 2024-05-15T06:59:20.479Z | v1.10.7 18 | subchannel_call | [11] ended with status: code=14 details=\"upstream connect error or disconnect/reset before headers. retried and the latest reset reason: connection failure, transport failure reason: delayed connect error: 113\"\nD 2024-05-15T06:59:20.479Z | v1.10.7 18 | load_balancing_call | [10] Received status\nD 2024-05-15T06:59:20.479Z | v1.10.7 18 | load_balancing_call | [10] ended with status: code=14 details=\"upstream connect error or disconnect/reset before headers. retried and the latest reset reason: connection failure, transport failure reason: delayed connect error: 113\" start time=2024-05-15T06:59:11.227Z\nD 2024-05-15T06:59:20.479Z | v1.10.7 18 | retrying_call | [9] Received status from child [10]\nD 2024-05-15T06:59:20.479Z | v1.10.7 18 | retrying_call | [9] state=TRANSPARENT_ONLY handling status with progress PROCESSED from child [10] in state ACTIVE\nD 2024-05-15T06:59:20.479Z | v1.10.7 18 | retrying_call | [9] ended with status: code=14 details=\"upstream connect error or disconnect/reset before headers. retried and the latest reset reason: connection failure, transport failure reason: delayed connect error: 113\" start time=2024-05-15T06:59:11.227Z\n","timestamp":"2024-05-15 14:59:20.479","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:20.479Z | v1.10.7 18 | resolving_call | [8] Received status\nD 2024-05-15T06:59:20.479Z | v1.10.7 18 | resolving_call | [8] ended with status: code=14 details=\"upstream connect error or disconnect/reset before headers. retried and the latest reset reason: connection failure, transport failure reason: delayed connect error: 113\"\n","timestamp":"2024-05-15 14:59:20.480","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"\u001b[91m[2024-05-15 14:59:20.480] [ERROR]\u001b[39m ~ gsapi_category_query_new ~ 8072e1a7-d605-4776-9147-52390330c575 ~ gsapi-category-query-new-master-749cd76bfc-kg67n ~ POST-/gsapi/category/query/v2.0/test2 ~ 14 UNAVAILABLE: upstream connect error or disconnect/reset before headers. retried and the latest reset reason: connection failure, transport failure reason: delayed connect error: 113\n","timestamp":"2024-05-15 14:59:20.480","type":"out","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"\u001b[91m[2024-05-15 14:59:20.481] [ERROR]\u001b[39m ~ gsapi_category_query_new ~ 8072e1a7-d605-4776-9147-52390330c575 ~ gsapi-category-query-new-master-749cd76bfc-kg67n ~ POST-/gsapi/category/query/v2.0/test2 ~ Error: next() called multiple times\n    at dispatch (/user/src/app/node_modules/koa-compose/index.js:36:45)\n    at ratelimit (/user/src/app/node_modules/koa-sopei-ratelimit/index.js:186:11)\n    at processTicksAndRejections (node:internal/process/task_queues:96:5)\n    at /user/src/app/app.js:20:5\n    at /user/src/app/node_modules/koa-sopei-log/index.js:76:7\n    at /user/src/app/node_modules/koa-sopei-apm/index.js:20:13\n","timestamp":"2024-05-15 14:59:20.481","type":"out","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"\u001b[34m[2024-05-15 14:59:20.481] [ACC]\u001b[39m ~ gsapi_category_query_new ~ 8072e1a7-d605-4776-9147-52390330c575 ~ gsapi-category-query-new-master-749cd76bfc-kg67n ~ 127.0.0.1 ~ /gsapi/category/query/v2.0/test2 ~ HTTP/1.1 ~ POST ~ 200 ~ 64 ~ - ~ curl/7.74.0 ~ 9256 ~ 4003\n","timestamp":"2024-05-15 14:59:20.481","type":"out","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:20.483Z | v1.10.7 18 | subchannel_call | [11] HTTP/2 stream closed with code 0\n","timestamp":"2024-05-15 14:59:20.483","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:20.941Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Sending ping with timeout 1000ms\n","timestamp":"2024-05-15 14:59:20.941","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}
{"message":"D 2024-05-15T06:59:20.941Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Received ping response\nD 2024-05-15T06:59:20.941Z | v1.10.7 18 | keepalive | (1) 10.42.4.144:34049 Starting keepalive timer for 10000ms\n","timestamp":"2024-05-15 14:59:20.942","type":"err","process_id":0,"app_name":"gsapi_category_query_new"}

[wang-xiaowu]

I don't think that error message comes from this library. As a result, I don't think the client is actually failing to reconnect. I think it is connecting successfully, and the server is sending that error. You could confirm this by running the client and server with the environment variables GRPC_TRACE=all and GRPC_VERBOSITY=DEBUG, and performing your test and sharing the logs that are output.

you are right, i notice the envoy in the log, i forgot disabled istio injection of the client.