Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Related log to Microsoft/Garnet#811 #16

Closed
yuseok-kim-edushare opened this issue Nov 21, 2024 · 7 comments
Closed

Related log to Microsoft/Garnet#811 #16

yuseok-kim-edushare opened this issue Nov 21, 2024 · 7 comments

Comments

@yuseok-kim-edushare
Copy link
Member

Error starting ApplicationContext. To display the condition evaluation report re-run your application with 'debug' enabled.
2024-11-21T13:48:50.836+09:00 ERROR 22012 --- [auth-service] [ main] o.s.boot.SpringApplication : Application run failed

org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'container' defined in class path resource [com/promptoven/authservice/adaptor/redis/RedisConfig.class]: Failed to instantiate [org.springframework.data.redis.listener.RedisMessageListenerContainer]: Factory method 'container' threw exception with message: Unable to connect to Redis
at org.springframework.beans.factory.support.ConstructorResolver.instantiate(ConstructorResolver.java:648) ~[spring-beans-6.1.14.jar:6.1.14]
at org.springframework.beans.factory.support.ConstructorResolver.instantiateUsingFactoryMethod(ConstructorResolver.java:636) ~[spring-beans-6.1.14.jar:6.1.14]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.instantiateUsingFactoryMethod(AbstractAutowireCapableBeanFactory.java:1355) ~[spring-beans-6.1.14.jar:6.1.14]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:1185) ~[spring-beans-6.1.14.jar:6.1.14]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:562) ~[spring-beans-6.1.14.jar:6.1.14]
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:522) ~[spring-beans-6.1.14.jar:6.1.14]
at org.springframework.beans.factory.support.AbstractBeanFactory.lambda$doGetBean$0(AbstractBeanFactory.java:337) ~[spring-beans-6.1.14.jar:6.1.14]
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:234) ~[spring-beans-6.1.14.jar:6.1.14]
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:335) ~[spring-beans-6.1.14.jar:6.1.14]
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:200) ~[spring-beans-6.1.14.jar:6.1.14]
at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:975) ~[spring-beans-6.1.14.jar:6.1.14]
at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:971) ~[spring-context-6.1.14.jar:6.1.14]
at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:625) ~[spring-context-6.1.14.jar:6.1.14]
at org.springframework.boot.web.servlet.context.ServletWebServerApplicationContext.refresh(ServletWebServerApplicationContext.java:146) ~[spring-boot-3.3.5.jar:3.3.5]
at org.springframework.boot.SpringApplication.refresh(SpringApplication.java:754) ~[spring-boot-3.3.5.jar:3.3.5]
at org.springframework.boot.SpringApplication.refreshContext(SpringApplication.java:456) ~[spring-boot-3.3.5.jar:3.3.5]
at org.springframework.boot.SpringApplication.run(SpringApplication.java:335) ~[spring-boot-3.3.5.jar:3.3.5]
at org.springframework.boot.SpringApplication.run(SpringApplication.java:1363) ~[spring-boot-3.3.5.jar:3.3.5]
at org.springframework.boot.SpringApplication.run(SpringApplication.java:1352) ~[spring-boot-3.3.5.jar:3.3.5]
at com.promptoven.authservice.AuthServiceApplication.main(AuthServiceApplication.java:16) ~[main/:na]
Caused by: org.springframework.beans.BeanInstantiationException: Failed to instantiate [org.springframework.data.redis.listener.RedisMessageListenerContainer]: Factory method 'container' threw exception with message: Unable to connect to Redis
at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:178) ~[spring-beans-6.1.14.jar:6.1.14]
at org.springframework.beans.factory.support.ConstructorResolver.instantiate(ConstructorResolver.java:644) ~[spring-beans-6.1.14.jar:6.1.14]
... 19 common frames omitted
Caused by: org.springframework.data.redis.RedisConnectionFailureException: Unable to connect to Redis
at org.springframework.data.redis.connection.lettuce.LettuceConnectionFactory$ExceptionTranslatingConnectionProvider.translateException(LettuceConnectionFactory.java:1849) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.connection.lettuce.LettuceConnectionFactory$ExceptionTranslatingConnectionProvider.getConnection(LettuceConnectionFactory.java:1780) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.connection.lettuce.LettuceConnectionFactory$SharedConnection.getNativeConnection(LettuceConnectionFactory.java:1582) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.connection.lettuce.LettuceConnectionFactory$SharedConnection.lambda$getConnection$0(LettuceConnectionFactory.java:1562) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.connection.lettuce.LettuceConnectionFactory.doInLock(LettuceConnectionFactory.java:1523) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.connection.lettuce.LettuceConnectionFactory$SharedConnection.getConnection(LettuceConnectionFactory.java:1559) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.connection.lettuce.LettuceConnectionFactory.getSharedConnection(LettuceConnectionFactory.java:1245) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.connection.lettuce.LettuceConnectionFactory.getConnection(LettuceConnectionFactory.java:1051) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.core.RedisConnectionUtils.fetchConnection(RedisConnectionUtils.java:195) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.core.RedisConnectionUtils.doGetConnection(RedisConnectionUtils.java:144) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.core.RedisConnectionUtils.getConnection(RedisConnectionUtils.java:105) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.core.RedisTemplate.execute(RedisTemplate.java:398) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.core.RedisTemplate.execute(RedisTemplate.java:378) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.core.RedisTemplate.doWithKeys(RedisTemplate.java:790) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.core.RedisTemplate.hasKey(RedisTemplate.java:576) ~[spring-data-redis-3.3.5.jar:3.3.5]
at com.promptoven.authservice.adaptor.redis.RedisConfig.container(RedisConfig.java:66) ~[main/:na]
at com.promptoven.authservice.adaptor.redis.RedisConfig$$SpringCGLIB$$0.CGLIB$container$0() ~[main/:na]
at com.promptoven.authservice.adaptor.redis.RedisConfig$$SpringCGLIB$$FastClass$$1.invoke() ~[main/:na]
at org.springframework.cglib.proxy.MethodProxy.invokeSuper(MethodProxy.java:258) ~[spring-core-6.1.14.jar:6.1.14]
at org.springframework.context.annotation.ConfigurationClassEnhancer$BeanMethodInterceptor.intercept(ConfigurationClassEnhancer.java:348) ~[spring-context-6.1.14.jar:6.1.14]
at com.promptoven.authservice.adaptor.redis.RedisConfig$$SpringCGLIB$$0.container() ~[main/:na]
at java.base/jdk.internal.reflect.DirectMethodHandleAccessor.invoke(DirectMethodHandleAccessor.java:103) ~[na:na]
at java.base/java.lang.reflect.Method.invoke(Method.java:580) ~[na:na]
at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:146) ~[spring-beans-6.1.14.jar:6.1.14]
... 20 common frames omitted
Caused by: io.lettuce.core.RedisConnectionException: Unable to connect to localhost/:6379
at io.lettuce.core.RedisConnectionException.create(RedisConnectionException.java:78) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
at io.lettuce.core.RedisConnectionException.create(RedisConnectionException.java:56) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
at io.lettuce.core.AbstractRedisClient.getConnection(AbstractRedisClient.java:350) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
at io.lettuce.core.RedisClient.connect(RedisClient.java:215) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
at org.springframework.data.redis.connection.lettuce.StandaloneConnectionProvider.lambda$getConnection$1(StandaloneConnectionProvider.java:112) ~[spring-data-redis-3.3.5.jar:3.3.5]
at java.base/java.util.Optional.orElseGet(Optional.java:364) ~[na:na]
at org.springframework.data.redis.connection.lettuce.StandaloneConnectionProvider.getConnection(StandaloneConnectionProvider.java:112) ~[spring-data-redis-3.3.5.jar:3.3.5]
at org.springframework.data.redis.connection.lettuce.LettuceConnectionFactory$ExceptionTranslatingConnectionProvider.getConnection(LettuceConnectionFactory.java:1778) ~[spring-data-redis-3.3.5.jar:3.3.5]
... 42 common frames omitted
Caused by: io.lettuce.core.RedisCommandTimeoutException: Connection initialization timed out after 5 second(s)
at io.lettuce.core.protocol.RedisHandshakeHandler.lambda$channelRegistered$0(RedisHandshakeHandler.java:67) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
at io.netty.util.concurrent.PromiseTask.runTask(PromiseTask.java:98) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.concurrent.PromiseTask.run(PromiseTask.java:106) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.concurrent.AbstractEventExecutor.runTask(AbstractEventExecutor.java:173) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.concurrent.DefaultEventExecutor.run(DefaultEventExecutor.java:66) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at java.base/java.lang.Thread.run(Thread.java:1583) ~[na:na]

@yuseok-kim-edushare
Copy link
Member Author

@yuseok-kim-edushare
Copy link
Member Author

@yuseok-kim-edushare
Copy link
Member Author

image

Problem Causing by Spring Data Redis

@yuseok-kim-edushare
Copy link
Member Author

2024-11-21T14:10:01.986+09:00 DEBUG 22700 --- [auth-service] [ main] i.l.c.r.AddressResolverGroupProvider : Starting without optional netty's non-blocking DNS resolver library
2024-11-21T14:10:01.987+09:00 DEBUG 22700 --- [auth-service] [ main] i.l.c.resource.DefaultClientResources : -Dio.netty.eventLoopThreads: 12
2024-11-21T14:10:01.991+09:00 DEBUG 22700 --- [auth-service] [ main] i.l.c.r.DefaultEventLoopGroupProvider : Creating executor io.netty.util.concurrent.DefaultEventExecutorGroup
2024-11-21T14:10:02.044+09:00 DEBUG 22700 --- [auth-service] [ main] i.l.core.event.jfr.EventRecorderHolder : Starting with JFR support
2024-11-21T14:10:02.235+09:00 DEBUG 22700 --- [auth-service] [ main] io.lettuce.core.RedisClient : Trying to get a Redis connection for: redis://localhost?timeout=5s
2024-11-21T14:10:02.347+09:00 DEBUG 22700 --- [auth-service] [ main] i.lettuce.core.resource.KqueueProvider : Starting without optional kqueue library
2024-11-21T14:10:02.348+09:00 DEBUG 22700 --- [auth-service] [ main] i.lettuce.core.resource.IOUringProvider : Starting without optional io_uring library
2024-11-21T14:10:02.348+09:00 DEBUG 22700 --- [auth-service] [ main] io.lettuce.core.resource.EpollProvider : Starting without optional epoll library
2024-11-21T14:10:02.351+09:00 DEBUG 22700 --- [auth-service] [ main] i.l.c.r.DefaultEventLoopGroupProvider : Allocating executor io.netty.channel.nio.NioEventLoopGroup
2024-11-21T14:10:02.351+09:00 DEBUG 22700 --- [auth-service] [ main] i.l.c.r.DefaultEventLoopGroupProvider : Creating executor io.netty.channel.nio.NioEventLoopGroup
2024-11-21T14:10:02.369+09:00 DEBUG 22700 --- [auth-service] [ main] i.l.c.r.DefaultEventLoopGroupProvider : Adding reference to io.netty.channel.nio.NioEventLoopGroup@21ca613b, existing ref count 0
2024-11-21T14:10:02.404+09:00 DEBUG 22700 --- [auth-service] [ main] io.lettuce.core.RedisClient : Resolved SocketAddress localhost/:6379 using redis://localhost?timeout=5s
2024-11-21T14:10:02.404+09:00 DEBUG 22700 --- [auth-service] [ main] io.lettuce.core.AbstractRedisClient : Connecting to Redis at localhost/:6379
2024-11-21T14:10:02.445+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0xc05a218f, [id: 0xe86b770e] (inactive), epid=0x1, chid=0x1] channelRegistered()
2024-11-21T14:10:02.469+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0xc05a218f, /127.0.0.1:60266 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] write(ctx, AsyncCommand [type=HELLO, output=GenericMapOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
2024-11-21T14:10:02.472+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandEncoder : [channel=0xc05a218f, /127.0.0.1:60266 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=HELLO, output=GenericMapOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
2024-11-21T14:10:02.476+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0xc05a218f, /127.0.0.1:60266 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] Received: 184 bytes, 1 commands in the stack
2024-11-21T14:10:02.476+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0xc05a218f, /127.0.0.1:60266 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] Stack contains: 1 commands
2024-11-21T14:10:02.483+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] i.l.core.protocol.RedisStateMachine : Decode done, empty stack: true
2024-11-21T14:10:02.492+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0xc05a218f, /127.0.0.1:60266 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] Completing command LatencyMeteredCommand [type=HELLO, output=GenericMapOutput [output={server=redis, version=7.2.5, garnet_version=1.0.39, proto=3, id=63, mode=standalone, role=master}, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
2024-11-21T14:10:07.462+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] io.lettuce.core.AbstractRedisClient : Connecting to Redis at localhost/:6379, initialization: {}

io.lettuce.core.RedisCommandTimeoutException: Connection initialization timed out after 5 second(s)
at io.lettuce.core.protocol.RedisHandshakeHandler.lambda$channelRegistered$0(RedisHandshakeHandler.java:67) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
at io.netty.util.concurrent.PromiseTask.runTask(PromiseTask.java:98) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.concurrent.PromiseTask.run(PromiseTask.java:106) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.concurrent.AbstractEventExecutor.runTask(AbstractEventExecutor.java:173) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.concurrent.DefaultEventExecutor.run(DefaultEventExecutor.java:66) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at java.base/java.lang.Thread.run(Thread.java:1583) ~[na:na]

2024-11-21T14:10:07.465+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] io.lettuce.core.RedisChannelHandler : closeAsync()
2024-11-21T14:10:07.465+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.DefaultEndpoint : [unknown, epid=0x1] closeAsync()
2024-11-21T14:10:07.466+09:00 WARN 22700 --- [auth-service] [ main] ConfigServletWebServerApplicationContext : Exception encountered during context initialization - cancelling refresh attempt: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'container' defined in class path resource [com/promptoven/authservice/adaptor/redis/RedisConfig.class]: Failed to instantiate [org.springframework.data.redis.listener.RedisMessageListenerContainer]: Factory method 'container' threw exception with message: Unable to connect to Redis
2024-11-21T14:10:07.466+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0xc05a218f, /127.0.0.1:60266 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] channelInactive()
2024-11-21T14:10:07.467+09:00 DEBUG 22700 --- [auth-service] [ main] io.lettuce.core.AbstractRedisClient : Initiate shutdown (0, 100, MILLISECONDS)
2024-11-21T14:10:07.467+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.DefaultEndpoint : [unknown, epid=0x1] deactivating endpoint handler
2024-11-21T14:10:07.467+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0xc05a218f, /127.0.0.1:60266 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] channelInactive() done
2024-11-21T14:10:07.468+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] i.l.core.protocol.ConnectionWatchdog : [unknown, last known addr=null] channelInactive()
2024-11-21T14:10:07.468+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] i.l.core.protocol.ConnectionWatchdog : [unknown, last known addr=null] ConnectionWatchdog not armed
2024-11-21T14:10:07.468+09:00 DEBUG 22700 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0xc05a218f, /127.0.0.1:60266 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] channelUnregistered()
2024-11-21T14:10:07.469+09:00 DEBUG 22700 --- [auth-service] [ main] i.l.c.resource.DefaultClientResources : Initiate shutdown (0, 100, MILLISECONDS)
2024-11-21T14:10:07.471+09:00 DEBUG 22700 --- [auth-service] [ main] i.l.c.r.DefaultEventLoopGroupProvider : Initiate shutdown (0, 100, MILLISECONDS)
2024-11-21T14:10:07.471+09:00 DEBUG 22700 --- [auth-service] [ main] i.l.c.r.DefaultEventLoopGroupProvider : Release executor io.netty.channel.nio.NioEventLoopGroup@21ca613b

@yuseok-kim-edushare
Copy link
Member Author

2024-11-21T17:51:14.075+09:00 DEBUG 5056 --- [auth-service] [ main] io.lettuce.core.RedisClient : Resolved SocketAddress localhost/:6379 using redis://localhost?timeout=5s
2024-11-21T17:51:14.076+09:00 DEBUG 5056 --- [auth-service] [ main] io.lettuce.core.AbstractRedisClient : Connecting to Redis at localhost/:6379
2024-11-21T17:51:14.140+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0x30f89199, [id: 0x3232c643] (inactive), epid=0x1, chid=0x1] channelRegistered()
2024-11-21T17:51:14.171+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] write(ctx, AsyncCommand [type=HELLO, output=GenericMapOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], promise)
2024-11-21T17:51:14.175+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandEncoder : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=HELLO, output=GenericMapOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
2024-11-21T17:51:14.178+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] Received: 181 bytes, 1 commands in the stack
2024-11-21T17:51:14.178+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] Stack contains: 1 commands
2024-11-21T17:51:14.183+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.l.core.protocol.RedisStateMachine : Decode done, empty stack: true
2024-11-21T17:51:14.191+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] Completing command LatencyMeteredCommand [type=HELLO, output=GenericMapOutput [output={server=redis, version=7.2.5, garnet_version=1.0.39, proto=3, id=63, mode=standalone, role=master}, error='null'], commandType=io.lettuce.core.protocol.AsyncCommand]
2024-11-21T17:51:14.193+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] write(ctx, [AsyncCommand [type=CLIENT, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command], AsyncCommand [type=CLIENT, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]], promise)
2024-11-21T17:51:14.194+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandEncoder : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=CLIENT, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
2024-11-21T17:51:14.194+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandEncoder : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379] writing command AsyncCommand [type=CLIENT, output=StatusOutput [output=null, error='null'], commandType=io.lettuce.core.protocol.Command]
2024-11-21T17:51:14.194+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] Received: 44 bytes, 2 commands in the stack
2024-11-21T17:51:14.194+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] Stack contains: 2 commands
2024-11-21T17:51:14.194+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.l.core.protocol.RedisStateMachine : Decode done, empty stack: true
2024-11-21T17:51:14.194+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] Completing command LatencyMeteredCommand [type=CLIENT, output=StatusOutput [output=null, error='ERR unknown command'], commandType=io.lettuce.core.protocol.AsyncCommand]
2024-11-21T17:51:14.195+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] Stack contains: 1 commands
2024-11-21T17:51:14.195+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.l.core.protocol.RedisStateMachine : Decode done, empty stack: true
2024-11-21T17:51:14.195+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] Completing command LatencyMeteredCommand [type=CLIENT, output=StatusOutput [output=null, error='ERR unknown command'], commandType=io.lettuce.core.protocol.AsyncCommand]
2024-11-21T17:51:14.196+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] io.lettuce.core.AbstractRedisClient : Connecting to Redis at localhost/:6379, initialization: {}

java.util.concurrent.CompletionException: io.lettuce.core.RedisCommandExecutionException: ERR unknown command
at java.base/java.util.concurrent.CompletableFuture.encodeThrowable(CompletableFuture.java:332) ~[na:na]
at java.base/java.util.concurrent.CompletableFuture.completeThrowable(CompletableFuture.java:347) ~[na:na]
at java.base/java.util.concurrent.CompletableFuture$BiRelay.tryFire(CompletableFuture.java:1498) ~[na:na]
at java.base/java.util.concurrent.CompletableFuture$CoCompletion.tryFire(CompletableFuture.java:1219) ~[na:na]
at java.base/java.util.concurrent.CompletableFuture.postComplete(CompletableFuture.java:510) ~[na:na]
at java.base/java.util.concurrent.CompletableFuture.completeExceptionally(CompletableFuture.java:2194) ~[na:na]
at io.lettuce.core.protocol.AsyncCommand.doCompleteExceptionally(AsyncCommand.java:139) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
at io.lettuce.core.protocol.AsyncCommand.completeResult(AsyncCommand.java:120) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
at io.lettuce.core.protocol.AsyncCommand.complete(AsyncCommand.java:111) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
at io.lettuce.core.protocol.CommandWrapper.complete(CommandWrapper.java:63) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
at io.lettuce.core.protocol.CommandHandler.complete(CommandHandler.java:745) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
at io.lettuce.core.protocol.CommandHandler.decode(CommandHandler.java:680) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
at io.lettuce.core.protocol.CommandHandler.channelRead(CommandHandler.java:597) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:442) ~[netty-transport-4.1.114.Final.jar:4.1.114.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[netty-transport-4.1.114.Final.jar:4.1.114.Final]
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:412) ~[netty-transport-4.1.114.Final.jar:4.1.114.Final]
at io.netty.channel.DefaultChannelPipeline$HeadContext.channelRead(DefaultChannelPipeline.java:1357) ~[netty-transport-4.1.114.Final.jar:4.1.114.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:440) ~[netty-transport-4.1.114.Final.jar:4.1.114.Final]
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:420) ~[netty-transport-4.1.114.Final.jar:4.1.114.Final]
at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:868) ~[netty-transport-4.1.114.Final.jar:4.1.114.Final]
at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:166) ~[netty-transport-4.1.114.Final.jar:4.1.114.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:788) ~[netty-transport-4.1.114.Final.jar:4.1.114.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:724) ~[netty-transport-4.1.114.Final.jar:4.1.114.Final]
at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:650) ~[netty-transport-4.1.114.Final.jar:4.1.114.Final]
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:562) ~[netty-transport-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.concurrent.SingleThreadEventExecutor$4.run(SingleThreadEventExecutor.java:997) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.internal.ThreadExecutorMap$2.run(ThreadExecutorMap.java:74) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at io.netty.util.concurrent.FastThreadLocalRunnable.run(FastThreadLocalRunnable.java:30) ~[netty-common-4.1.114.Final.jar:4.1.114.Final]
at java.base/java.lang.Thread.run(Thread.java:1583) ~[na:na]
Caused by: io.lettuce.core.RedisCommandExecutionException: ERR unknown command
at io.lettuce.core.internal.ExceptionFactory.createExecutionException(ExceptionFactory.java:147) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
at io.lettuce.core.internal.ExceptionFactory.createExecutionException(ExceptionFactory.java:116) ~[lettuce-core-6.3.2.RELEASE.jar:6.3.2.RELEASE/8941aea]
... 22 common frames omitted

2024-11-21T17:51:14.199+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] io.lettuce.core.RedisChannelHandler : closeAsync()
2024-11-21T17:51:14.199+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.DefaultEndpoint : [unknown, epid=0x1] closeAsync()
2024-11-21T17:51:14.200+09:00 WARN 5056 --- [auth-service] [ main] ConfigServletWebServerApplicationContext : Exception encountered during context initialization - cancelling refresh attempt: org.springframework.beans.factory.BeanCreationException: Error creating bean with name 'container' defined in class path resource [com/promptoven/authservice/adaptor/redis/RedisConfig.class]: Failed to instantiate [org.springframework.data.redis.listener.RedisMessageListenerContainer]: Factory method 'container' threw exception with message: Unable to connect to Redis
2024-11-21T17:51:14.200+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] channelInactive()
2024-11-21T17:51:14.201+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.DefaultEndpoint : [unknown, epid=0x1] deactivating endpoint handler
2024-11-21T17:51:14.202+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] channelInactive() done
2024-11-21T17:51:14.202+09:00 DEBUG 5056 --- [auth-service] [ main] io.lettuce.core.AbstractRedisClient : Initiate shutdown (0, 100, MILLISECONDS)
2024-11-21T17:51:14.203+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.l.core.protocol.ConnectionWatchdog : [unknown, last known addr=null] channelInactive()
2024-11-21T17:51:14.203+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.l.core.protocol.ConnectionWatchdog : [unknown, last known addr=null] ConnectionWatchdog not armed
2024-11-21T17:51:14.203+09:00 DEBUG 5056 --- [auth-service] [ioEventLoop-4-1] i.lettuce.core.protocol.CommandHandler : [channel=0x30f89199, /127.0.0.1:63941 -> localhost/127.0.0.1:6379, epid=0x1, chid=0x1] channelUnregistered()

@yuseok-kim-edushare
Copy link
Member Author

After Fix Hello's Response Type
then occur New Exception

@yuseok-kim-edushare
Copy link
Member Author

Some day need to implement other RESP Client commands,
but at least now, can use RESP 3 mode with little trick Image
thus, just can use garnet

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

1 participant