Instead of modifying and using the jre/lib logging.properties file can you try using one for your application alone as described in my previous message (use Java property java.util.logging.config.file and so on)?
On Wednesday, August 23, 2023 at 7:49:39 PM UTC-7 邹会江 wrote: > 1. I modified the logging.properties file under jre/lib: > handlers=java.util.logging.ConsoleHandler > io.grpc.ChannelLogger.level=FINEST > io.grpc.level=FINEST > io.netty.level=FINEST > java.util.logging.ConsoleHandler.level=ALL > > java.util.logging.ConsoleHandler.formatter=java.util.logging.SimpleFormatter > but grpc doesn't have any log output to the console. > > 在2023年8月24日星期四 UTC+8 02:32:08<Sanjay Pujare> 写道： > >> On Wed, Aug 23, 2023 at 1:37 AM '邹会江' via grpc.io < >> grp...@googlegroups.com> wrote: >> >>> Sanjay Pujare： >>> Thank you for your reply! >>> 1. How do i enable trace logging? >>> >> >> It uses java util logging so to enable using JAVA_OPTS: >> >> export JAVA_OPTS="-Djava.util.logging.config.file=/logging.properties" >> >> And in /logging.properties have this: >> >> handlers=java.util.logging.ConsoleHandler >> io.grpc.ChannelLogger.level=FINEST >> io.grpc.level=FINEST >> io.netty.level=FINEST >> java.util.logging.ConsoleHandler.level=ALL >> >> java.util.logging.ConsoleHandler.formatter=java.util.logging.SimpleFormatter >> >> Or you can tweak it to your liking. >> >> >>> 2. DNS resolution,TLS hanshake and TCP connection.In this case,the state >>> of channel should be CONNECTING? >> >> >> Yes. >> >> >>> Therefore,the TCP connectio only affects the CONNECTING time, but not >>> the IDLE time. >>> >> >> Not sure I understand this comment/question. But when it is trying to >> establish the TCP connection, yes the status is CONNECTING >> >> >>> >>> 在2023年8月23日星期三 UTC+8 14:23:02<Sanjay Pujare> 写道： >>> >>>> Most of the time is spent in IDLE -> CONNECTING transition. From this >>>> doc >>>> https://github.com/grpc/grpc/blob/master/doc/connectivity-semantics-and-api.md >>>> >>>> which says for CONNECTING >>>> >>>> CONNECTING: The channel is trying to establish a connection and is >>>> waiting to make progress on one of the steps involved in name resolution, >>>> TCP connection establishment or TLS handshake. This may be used as the >>>> initial state for channels upon creation. >>>> >>>> DNS resolution and TLS handshake (since you are not calling >>>> usePlaintext() on the builder) are possible culprits. Even TCP connection >>>> establishment is a possibility depending on where the target is. If you >>>> enable trace logging you might be able to figure out. >>>> >>>> On Tue, Aug 22, 2023 at 10:08 PM '邹会江' via grpc.io < >>>> grp...@googlegroups.com> wrote: >>>> >>>>> Thank you for your reply! >>>>> 1. The first time: >>>>> The transition from idle state to connecting takes: 3377 ms, >>>>> The transition from idle state to ready takes: 3679 ms >>>>> 2. The second time: >>>>> The transition from idle state to connecting takes: 1643 ms, >>>>> The transition from idle state to ready takes: 1797 ms >>>>> 3.The third time: >>>>> The transition from idle state to connecting takes: 1019 ms, >>>>> The transition from idle state to ready takes: 1049 ms >>>>> 4. What is my enviroment? >>>>> MacBook Pro (16-inch, 2019) >>>>> 2.6 GHz 6 core Intel Core i7 >>>>> 16 GB 2667 MHz DDR4 >>>>> >>>>> 在2023年8月23日星期三 UTC+8 04:31:28<Larry Safran> 写道： >>>>> >>>>>> Another question is whether this is the same the second time you >>>>>> create a channel. The first time, the JVM may be taking time doing >>>>>> class >>>>>> loading. >>>>>> >>>>>> On Tue, Aug 22, 2023 at 8:41 AM 'Yuri Golobokov' via grpc.io < >>>>>> grp...@googlegroups.com> wrote: >>>>>> >>>>>>> Hello, >>>>>>> What is the round-trip time? How much time does DNS resolution take? >>>>>>> >>>>>>> On Tuesday, August 22, 2023 at 12:02:21 AM UTC-7 邹会江 wrote: >>>>>>> >>>>>>>> ### JAVA Code >>>>>>>> >>>>>>>> ``` >>>>>>>> channel = >>>>>>>> ManagedChannelBuilder.forTarget(param.getTarget()).build(); >>>>>>>> long channelStart = System.currentTimeMillis(); >>>>>>>> asyncStub = ASRRouteGrpc.newStub(channel); >>>>>>>> asrResponseStreamObserver = new >>>>>>>> ASRResponseStreamObserver(speechTranscriberListener); >>>>>>>> requestObserver = >>>>>>>> asyncStub.route(asrResponseStreamObserver); >>>>>>>> RouteRequest requestConf = newRequestHead(param); >>>>>>>> traceId = requestConf.getAsrConfig().getTraceId(); >>>>>>>> ConnectivityState currentChannelState = >>>>>>>> channel.getState(true); >>>>>>>> while (currentChannelState != ConnectivityState.READY) { >>>>>>>> CountDownLatch latchUntilChannelReady = new >>>>>>>> CountDownLatch(1); >>>>>>>> channel.notifyWhenStateChanged(currentChannelState, >>>>>>>> latchUntilChannelReady::countDown); >>>>>>>> latchUntilChannelReady.await(); >>>>>>>> currentChannelState = channel.getState(true); >>>>>>>> if (currentChannelState == >>>>>>>> ConnectivityState.CONNECTING) { >>>>>>>> long channelConnecting = >>>>>>>> System.currentTimeMillis(); >>>>>>>> LOGGER.info("The transition from idle state to >>>>>>>> connecting takes: {} ms", channelConnecting - channelStart); >>>>>>>> } >>>>>>>> } >>>>>>>> long channelReady = System.currentTimeMillis(); >>>>>>>> if (LOGGER.isInfoEnabled()) { >>>>>>>> LOGGER.info("Trace id: {}, channel start time: {}, >>>>>>>> channel ready time: {}, creating channel takes: {} ms", traceId, >>>>>>>> channelStart, channelReady, >>>>>>>> channelReady - channelStart); >>>>>>>> } >>>>>>>> ``` >>>>>>>> ### LOGGER >>>>>>>> >>>>>>>> ``` >>>>>>>> 2023-08-22 14:12:34,816 INFO client.ASRClient - The transition >>>>>>>> from idle state to connecting takes: 1009 ms >>>>>>>> 2023-08-22 14:12:34,841 INFO client.ASRClient - Trace id: >>>>>>>> 6afd2c67-e8c5-4cea-bd5c-4b82591a52aa, channel start time: >>>>>>>> 1692684753807, >>>>>>>> channel ready time: 1692684754841, creating channel takes: 1034 ms >>>>>>>> ``` >>>>>>>> ### Description >>>>>>>> #### code logic >>>>>>>> 1. first create channel >>>>>>>> 2. mark a time stamp(channelStart) after the channel is created >>>>>>>> 3. get the state of channel ,the state is IDEL >>>>>>>> 4. when the state of channel changes from IDEL to CONNECTING,mark a >>>>>>>> time stamp(channelConnecting) >>>>>>>> 5. when the state of channel changes from CONNECTING to READY, mark >>>>>>>> a time stamp(channelReady) >>>>>>>> >>>>>>>> #### Log info >>>>>>>> 1. It takes 1009ms for channel from IDLE state to CONNECTING state. >>>>>>>> 2. It takes 25ms for channel from CONNECTIONG state to READY state. >>>>>>>> ### What's i supposed >>>>>>>> 1. Does it take too long for a channel to change from IDLE to >>>>>>>> CONNECTING? >>>>>>>> 2. I want to reduce the time it takes for a channel to go from IDLE >>>>>>>> to CONNECTING. >>>>>>>> >>>>>>> -- >>>>>>> You received this message because you are subscribed to the Google >>>>>>> Groups "grpc.io" group. >>>>>>> To unsubscribe from this group and stop receiving emails from it, >>>>>>> send an email to grpc-io+u...@googlegroups.com. >>>>>>> To view this discussion on the web visit >>>>>>> https://groups.google.com/d/msgid/grpc-io/8f2a15b8-1541-422e-ad66-9a5778e53882n%40googlegroups.com >>>>>>> >>>>>>> <https://groups.google.com/d/msgid/grpc-io/8f2a15b8-1541-422e-ad66-9a5778e53882n%40googlegroups.com?utm_medium=email&utm_source=footer> >>>>>>> . >>>>>>> >>>>>> -- >>>>> You received this message because you are subscribed to the Google >>>>> Groups "grpc.io" group. >>>>> To unsubscribe from this group and stop receiving emails from it, send >>>>> an email to grpc-io+u...@googlegroups.com. >>>>> >>>> To view this discussion on the web visit >>>>> https://groups.google.com/d/msgid/grpc-io/8901e7bd-b684-462f-bf88-3607b6b01713n%40googlegroups.com >>>>> >>>>> <https://groups.google.com/d/msgid/grpc-io/8901e7bd-b684-462f-bf88-3607b6b01713n%40googlegroups.com?utm_medium=email&utm_source=footer> >>>>> . >>>>> >>>> -- >>> You received this message because you are subscribed to the Google >>> Groups "grpc.io" group. >>> To unsubscribe from this group and stop receiving emails from it, send >>> an email to grpc-io+u...@googlegroups.com. >>> >> To view this discussion on the web visit >>> https://groups.google.com/d/msgid/grpc-io/76f2be33-959a-4bf3-9405-1b1748ba3ec0n%40googlegroups.com >>> >>> <https://groups.google.com/d/msgid/grpc-io/76f2be33-959a-4bf3-9405-1b1748ba3ec0n%40googlegroups.com?utm_medium=email&utm_source=footer> >>> . >>> >> -- You received this message because you are subscribed to the Google Groups "grpc.io" group. To unsubscribe from this group and stop receiving emails from it, send an email to grpc-io+unsubscr...@googlegroups.com. To view this discussion on the web visit https://groups.google.com/d/msgid/grpc-io/5875ecb7-1653-447f-b0fd-3845f57fab4fn%40googlegroups.com.
