search

alibaba / nacos

an easy-to-use dynamic service discovery, configuration and service management platform for building cloud native applications.
https://nacos.io
Apache License 2.0
30.38k stars 12.86k forks source link

nacos客户端开启鉴权后无法注册nacos服务端（nacos client can not request nacos server） #11370

Closed studyyoursister closed 1 year ago

studyyoursister commented 1 year ago

Describe the bug

Connected to the target VM, address: '127.0.0.1:63364', transport: 'socket'

. _

/\ / ' () _ \ \ \ \

( ( )__ | ' | '| | ' \/ _` | \ \ \ \

\/ __)| |)| | | | | || (_| | ) ) ) )

' |__| .|| ||| |__, | / / / /

=========|_|==============|__/=////

:: Spring Boot :: (v2.7.17)

cloud-test-demo 2023-11-13 17:25:34.627 WARN 49000 --- [ main] c.a.c.n.c.NacosPropertySourceBuilder : [TID: N/A] Ignore the empty nacos configuration and get it based on dataId[cloud-test-demo] & group[test]

cloud-test-demo 2023-11-13 17:25:34.657 WARN 49000 --- [ main] c.a.c.n.c.NacosPropertySourceBuilder : [TID: N/A] Ignore the empty nacos configuration and get it based on dataId[cloud-test-demo.yaml] & group[test]

cloud-test-demo 2023-11-13 17:25:34.688 WARN 49000 --- [ main] c.a.c.n.c.NacosPropertySourceBuilder : [TID: N/A] Ignore the empty nacos configuration and get it based on dataId[cloud-test-demo-test-dev.yaml] & group[test]

cloud-test-demo 2023-11-13 17:25:34.689 INFO 49000 --- [ main] b.c.PropertySourceBootstrapConfiguration : [TID: N/A] Located property source: [BootstrapPropertySource {name='bootstrapProperties-cloud-test-demo-test-dev.yaml,test'}, BootstrapPropertySource {name='bootstrapProperties-cloud-test-demo.yaml,test'}, BootstrapPropertySource {name='bootstrapProperties-cloud-test-demo,test'}, BootstrapPropertySource {name='bootstrapProperties-cloud-test-demo-middleware.yml,test'}, BootstrapPropertySource {name='bootstrapProperties-cloud-test-demo.yml,test'}]

cloud-test-demo 2023-11-13 17:25:34.713 INFO 49000 --- [ main] c.s.c.v.protocol.version.Application : [TID: N/A] The following 1 profile is active: "test-dev"

cloud-test-demo 2023-11-13 17:25:35.154 DEBUG 49000 --- [ main] o.s.c.openfeign.FeignClientsRegistrar$1 : [TID: N/A] Identified candidate component class: file [E:\workspace\cloass]

cloud-test-demo 2023-11-13 17:25:35.419 INFO 49000 --- [ main] .s.d.r.c.RepositoryConfigurationDelegate : [TID: N/A] Multiple Spring Data modules found, entering strict repository configuration mode

cloud-test-demo 2023-11-13 17:25:35.423 INFO 49000 --- [ main] .s.d.r.c.RepositoryConfigurationDelegate : [TID: N/A] Bootstrapping Spring Data Redis repositories in DEFAULT mode.

cloud-test-demo 2023-11-13 17:25:35.442 INFO 49000 --- [ main] .s.d.r.c.RepositoryConfigurationDelegate : [TID: N/A] Finished Spring Data repository scanning in 9 ms. Found 0 Redis repository interfaces.

cloud-test-demo 2023-11-13 17:25:35.571 INFO 49000 --- [ main] o.s.cloud.context.scope.GenericScope : [TID: N/A] BeanFactory id=66e5c815-273a-34e7-aa46-484d6c366c33

cloud-test-demo 2023-11-13 17:25:35.981 INFO 49000 --- [ main] o.s.b.w.embedded.tomcat.TomcatWebServer : [TID: N/A] Tomcat initialized with port(s): 8012 (http)

cloud-test-demo 2023-11-13 17:25:36.052 INFO 49000 --- [ main] o.apache.catalina.core.StandardService : [TID: N/A] Starting service [Tomcat]

cloud-test-demo 2023-11-13 17:25:36.052 INFO 49000 --- [ main] org.apache.catalina.core.StandardEngine : [TID: N/A] Starting Servlet engine: [Apache Tomcat/9.0.82]

cloud-test-demo 2023-11-13 17:25:36.301 INFO 49000 --- [ main] o.a.c.c.C.[Tomcat].[localhost].[/] : [TID: N/A] Initializing Spring embedded WebApplicationContext

cloud-test-demo 2023-11-13 17:25:36.301 INFO 49000 --- [ main] w.s.c.ServletWebServerApplicationContext : [TID: N/A] Root WebApplicationContext: initialization completed in 1575 ms

| |. __ | _

| | |\/|)(| | |\ |)|||\ 

 /               |         

                    3.5.3.1

cloud-test-demo 2023-11-13 17:25:37.420 INFO 49000 --- [ main] o.s.c.openfeign.FeignClientFactoryBean : [TID: N/A] For 'master-data' URL not provided. Will try picking an instance via load-balancing.

cloud-test-demo 2023-11-13 17:25:37.941 INFO 49000 --- [ main] c.z.m.s.c.d.DruidMetricsConfiguration : [TID: N/A] finish register metrics to micrometer

cloud-test-demo 2023-11-13 17:25:37.952 INFO 49000 --- [ main] s.c.d.DynamicConfigListenerConfiguration : [TID: N/A] [DynamicConfigApplicationListener] DynamicConfigApplicationListener listener on

cloud-test-demo 2023-11-13 17:25:37.954 INFO 49000 --- [ main] s.c.d.DynamicConfigListenerConfiguration : [TID: N/A] [NacosListener] NacosListener listener on

cloud-test-demo 2023-11-13 17:25:38.097 INFO 49000 --- [ main] c.a.c.sentinel.SentinelWebMvcConfigurer : [TID: N/A] [Sentinel Starter] register SentinelWebInterceptor with urlPatterns: [/**].

INFO: Sentinel log output type is: file

INFO: Sentinel log charset is: utf-8

INFO: Sentinel log base directory is: C:\Users\ulfeff\logs\csp\

INFO: Sentinel log name use pid is: false

INFO: Sentinel log level is: INFO

cloud-test-demo 2023-11-13 17:25:39.619 WARN 49000 --- [ main] iguration$LoadBalancerCaffeineWarnLogger : [TID: N/A] Spring Cloud LoadBalancer is currently working with the default cache. While this cache implementation is useful for development and tests, it's recommended to use Caffeine cache in production.You can switch to using Caffeine cache, by adding it and org.springframework.cache.caffeine.CaffeineCacheManager to the classpath.

cloud-test-demo 2023-11-13 17:25:39.623 INFO 49000 --- [ main] o.s.b.a.e.web.EndpointLinksResolver : [TID: N/A] Exposing 3 endpoint(s) beneath base path '/actuator'

cloud-test-demo 2023-11-13 17:25:39.760 INFO 49000 --- [ main] o.a.k.clients.consumer.ConsumerConfig : [TID: N/A] ConsumerConfig values:

cloud-test-demo 2023-11-13 17:25:39.868 INFO 49000 --- [ main] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] Kafka version: 3.4.0

cloud-test-demo 2023-11-13 17:25:39.868 INFO 49000 --- [ main] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] Kafka commitId: 2e1947d240607d53

cloud-test-demo 2023-11-13 17:25:39.869 INFO 49000 --- [ main] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] Kafka startTimeMs: 1699867539866

cloud-test-demo 2023-11-13 17:25:39.887 INFO 49000 --- [ main] o.a.k.clients.consumer.KafkaConsumer : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Subscribed to topic(s): ch-cloud-test-appvercollection

cloud-test-demo 2023-11-13 17:25:39.902 INFO 49000 --- [ main] o.a.k.clients.consumer.ConsumerConfig : [TID: N/A] ConsumerConfig values: cloud-test-demo 2023-11-13 17:25:39.914 INFO 49000 --- [ main] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] Kafka version: 3.4.0

cloud-test-demo 2023-11-13 17:25:39.914 INFO 49000 --- [ main] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] Kafka commitId: 2e1947d240607d53

cloud-test-demo 2023-11-13 17:25:39.914 INFO 49000 --- [ main] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] Kafka startTimeMs: 1699867539914

cloud-test-demo 2023-11-13 17:25:39.918 INFO 49000 --- [ main] o.a.k.clients.consumer.KafkaConsumer : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Subscribed to topic(s): topic-cloud-gateway-test-consistent-appver

cloud-test-demo 2023-11-13 17:25:39.920 INFO 49000 --- [ main] o.a.k.clients.consumer.ConsumerConfig : [TID: N/A] ConsumerConfig values: cloud-test-demo 2023-11-13 17:25:39.930 INFO 49000 --- [ main] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] Kafka version: 3.4.0

cloud-test-demo 2023-11-13 17:25:39.930 INFO 49000 --- [ main] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] Kafka commitId: 2e1947d240607d53

cloud-test-demo 2023-11-13 17:25:39.930 INFO 49000 --- [ main] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] Kafka startTimeMs: 1699867539930

cloud-test-demo 2023-11-13 17:25:39.934 INFO 49000 --- [ main] o.a.k.clients.consumer.KafkaConsumer : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Subscribed to topic(s): ch-cloud-test-state

cloud-test-demo 2023-11-13 17:25:39.936 INFO 49000 --- [ main] o.a.k.clients.consumer.ConsumerConfig : [TID: N/A] ConsumerConfig values:

cloud-test-demo 2023-11-13 17:25:39.945 INFO 49000 --- [ main] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] Kafka version: 3.4.0

cloud-test-demo 2023-11-13 17:25:39.946 INFO 49000 --- [ main] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] Kafka commitId: 2e1947d240607d53

cloud-test-demo 2023-11-13 17:25:39.946 INFO 49000 --- [ main] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] Kafka startTimeMs: 1699867539945

cloud-test-demo 2023-11-13 17:25:39.948 INFO 49000 --- [ main] o.a.k.clients.consumer.KafkaConsumer : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Subscribed to topic(s): ch-cloud-test-real-appver

cloud-test-demo 2023-11-13 17:25:39.966 INFO 49000 --- [ main] o.s.b.w.embedded.tomcat.TomcatWebServer : [TID: N/A] Tomcat started on port(s): 8012 (http) with context path ''

cloud-test-demo 2023-11-13 17:25:40.310 ERROR 49000 --- [ main] c.a.c.n.registry.NacosServiceRegistry : [TID: N/A] nacos registry, cloud-test-demo register failed...NacosRegistration{nacosDiscoveryProperties=NacosDiscoveryProperties{serverAddr='nacos-tmp.z-cch.cn:8848', username='bpuser', password='bpuser11', endpoint='', namespace='public', watchDelay=30000, logName='', service='cloud-test-demo', weight=1.0, clusterName='DEFAULT', group='public', namingLoadCacheAtStart='false', metadata={IPv6=null, preserved.register.source=SPRING_CLOUD}, registerEnabled=true, ip='2.0.0.1', networkInterface='', port=8012, secure=false, accessKey='', secretKey='', heartBeatInterval=null, heartBeatTimeout=null, ipDeleteTimeout=null, instanceEnabled=true, ephemeral=true, failureToleranceEnabled=false}, ipDeleteTimeout=null, failFast=true}},

com.alibaba.nacos.api.exception.NacosException: failed to req API:/nacos/v1/ns/instance after all servers([nacos-tmp.z-cch.cn:8848]) tried: <!doctype html>HTTP Status 400 – Bad Request

HTTP Status 400 – Bad Request

at com.alibaba.nacos.client.naming.net.NamingProxy.reqApi(NamingProxy.java:561) ~[nacos-client-1.4.4.jar:na]

at com.alibaba.nacos.client.naming.net.NamingProxy.reqApi(NamingProxy.java:503) ~[nacos-client-1.4.4.jar:na]

at com.alibaba.nacos.client.naming.net.NamingProxy.reqApi(NamingProxy.java:498) ~[nacos-client-1.4.4.jar:na]

at com.alibaba.nacos.client.naming.net.NamingProxy.registerService(NamingProxy.java:251) ~[nacos-client-1.4.4.jar:na]

at com.alibaba.nacos.client.naming.NacosNamingService.registerInstance(NacosNamingService.java:218) ~[nacos-client-1.4.4.jar:na]

at com.alibaba.cloud.nacos.registry.NacosServiceRegistry.register(NacosServiceRegistry.java:75) ~[spring-cloud-starter-alibaba-nacos-discovery-2021.0.5.0.jar:2021.0.5.0]

at org.springframework.cloud.client.serviceregistry.AbstractAutoServiceRegistration.register(AbstractAutoServiceRegistration.java:232) [spring-cloud-commons-3.1.7.jar:3.1.7]

at com.alibaba.cloud.nacos.registry.NacosAutoServiceRegistration.register(NacosAutoServiceRegistration.java:78) [spring-cloud-starter-alibaba-nacos-discovery-2021.0.5.0.jar:2021.0.5.0]

at org.springframework.cloud.client.serviceregistry.AbstractAutoServiceRegistration.start(AbstractAutoServiceRegistration.java:133) [spring-cloud-commons-3.1.7.jar:3.1.7]

at org.springframework.cloud.client.serviceregistry.AbstractAutoServiceRegistration.bind(AbstractAutoServiceRegistration.java:98) [spring-cloud-commons-3.1.7.jar:3.1.7]

at org.springframework.cloud.client.serviceregistry.AbstractAutoServiceRegistration.onApplicationEvent(AbstractAutoServiceRegistration.java:86) [spring-cloud-commons-3.1.7.jar:3.1.7]

at org.springframework.cloud.client.serviceregistry.AbstractAutoServiceRegistration.onApplicationEvent(AbstractAutoServiceRegistration.java:47) [spring-cloud-commons-3.1.7.jar:3.1.7]

at org.springframework.context.event.SimpleApplicationEventMulticaster.doInvokeListener(SimpleApplicationEventMulticaster.java:178) [spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.event.SimpleApplicationEventMulticaster.invokeListener(SimpleApplicationEventMulticaster.java:171) [spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:145) [spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:421) [spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:378) [spring-context-5.3.30.jar:5.3.30]

at org.springframework.boot.web.servlet.context.WebServerStartStopLifecycle.start(WebServerStartStopLifecycle.java:46) [spring-boot-2.7.17.jar:2.7.17]

at org.springframework.context.support.DefaultLifecycleProcessor.doStart(DefaultLifecycleProcessor.java:179) [spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.support.DefaultLifecycleProcessor.access$200(DefaultLifecycleProcessor.java:54) [spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.support.DefaultLifecycleProcessor$LifecycleGroup.start(DefaultLifecycleProcessor.java:357) [spring-context-5.3.30.jar:5.3.30]

at java.lang.Iterable.forEach(Iterable.java:75) ~[na:1.8.0_152]

at org.springframework.context.support.DefaultLifecycleProcessor.startBeans(DefaultLifecycleProcessor.java:156) [spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.support.DefaultLifecycleProcessor.onRefresh(DefaultLifecycleProcessor.java:124) [spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.support.AbstractApplicationContext.finishRefresh(AbstractApplicationContext.java:938) [spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:586) [spring-context-5.3.30.jar:5.3.30]

at org.springframework.boot.web.servlet.context.ServletWebServerApplicationContext.refresh(ServletWebServerApplicationContext.java:147) ~[spring-boot-2.7.17.jar:2.7.17]

at org.springframework.boot.SpringApplication.refresh(SpringApplication.java:732) ~[spring-boot-2.7.17.jar:2.7.17]

at org.springframework.boot.SpringApplication.refreshContext(SpringApplication.java:409) ~[spring-boot-2.7.17.jar:2.7.17]

at org.springframework.boot.SpringApplication.run(SpringApplication.java:308) ~[spring-boot-2.7.17.jar:2.7.17]

at org.springframework.boot.SpringApplication.run(SpringApplication.java:1300) ~[spring-boot-2.7.17.jar:2.7.17]

at org.springframework.boot.SpringApplication.run(SpringApplication.java:1289) ~[spring-boot-2.7.17.jar:2.7.17]

at com.ch.cloud.vehicle.protocol.version.Application.main(Application.java:26) ~[classes/:na]

cloud-test-demo 2023-11-13 17:25:40.311 WARN 49000 --- [ main] ConfigServletWebServerApplicationContext : [TID: N/A] Exception encountered during context initialization - cancelling refresh attempt: org.springframework.context.ApplicationContextException: Failed to start bean 'webServerStartStop'; nested exception is java.lang.reflect.UndeclaredThrowableException

cloud-test-demo 2023-11-13 17:25:40.332 INFO 49000 --- [ntainer#3-0-C-1] org.apache.kafka.clients.Metadata : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Cluster ID: eFw2x-eXTuilUib5x2bisg

cloud-test-demo 2023-11-13 17:25:40.332 INFO 49000 --- [ntainer#0-0-C-1] org.apache.kafka.clients.Metadata : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Cluster ID: eFw2x-eXTuilUib5x2bisg

cloud-test-demo 2023-11-13 17:25:40.332 INFO 49000 --- [ntainer#1-0-C-1] org.apache.kafka.clients.Metadata : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Cluster ID: eFw2x-eXTuilUib5x2bisg

cloud-test-demo 2023-11-13 17:25:40.332 INFO 49000 --- [ntainer#2-0-C-1] org.apache.kafka.clients.Metadata : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Cluster ID: eFw2x-eXTuilUib5x2bisg

cloud-test-demo 2023-11-13 17:25:40.333 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Discovered group coordinator 10.0.0.1:9092 (id: 2147483645 rack: null)

cloud-test-demo 2023-11-13 17:25:40.333 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Discovered group coordinator 10.0.0.1:9092 (id: 2147483645 rack: null)

cloud-test-demo 2023-11-13 17:25:40.333 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Discovered group coordinator 10.0.0.1:9092 (id: 2147483645 rack: null)

cloud-test-demo 2023-11-13 17:25:40.333 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Discovered group coordinator 10.0.0.1:9092 (id: 2147483645 rack: null)

cloud-test-demo 2023-11-13 17:25:40.336 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] (Re-)joining group

cloud-test-demo 2023-11-13 17:25:40.336 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] (Re-)joining group

cloud-test-demo 2023-11-13 17:25:40.336 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] (Re-)joining group

cloud-test-demo 2023-11-13 17:25:40.336 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] (Re-)joining group

cloud-test-demo 2023-11-13 17:25:40.468 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Request joining group due to: need to re-join with the given member-id: consumer-ch-cloud-test-demo-1-1f56c920-8798-403a-907c-e357d064bc3a

cloud-test-demo 2023-11-13 17:25:40.468 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Request joining group due to: need to re-join with the given member-id: consumer-ch-cloud-test-demo-2-8a8ff237-b8c9-4354-94d6-00d85d2a60c4

cloud-test-demo 2023-11-13 17:25:40.468 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Request joining group due to: need to re-join with the given member-id: consumer-ch-cloud-test-demo-3-3e98e11e-c238-4919-a7bc-d04db61a9b6a

cloud-test-demo 2023-11-13 17:25:40.468 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Request joining group due to: need to re-join with the given member-id: consumer-ch-cloud-test-demo-4-cff5d343-d3b1-47db-86d5-0f73e07f7fd6

cloud-test-demo 2023-11-13 17:25:40.468 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Request joining group due to: rebalance failed due to 'The group member needs to have a valid member id before actually entering a consumer group.' (MemberIdRequiredException)

cloud-test-demo 2023-11-13 17:25:40.468 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Request joining group due to: rebalance failed due to 'The group member needs to have a valid member id before actually entering a consumer group.' (MemberIdRequiredException)

cloud-test-demo 2023-11-13 17:25:40.468 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] (Re-)joining group

cloud-test-demo 2023-11-13 17:25:40.468 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Request joining group due to: rebalance failed due to 'The group member needs to have a valid member id before actually entering a consumer group.' (MemberIdRequiredException)

cloud-test-demo 2023-11-13 17:25:40.468 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] (Re-)joining group

cloud-test-demo 2023-11-13 17:25:40.468 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] (Re-)joining group

cloud-test-demo 2023-11-13 17:25:40.468 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Request joining group due to: rebalance failed due to 'The group member needs to have a valid member id before actually entering a consumer group.' (MemberIdRequiredException)

cloud-test-demo 2023-11-13 17:25:40.469 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] (Re-)joining group

cloud-test-demo 2023-11-13 17:25:42.366 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Successfully joined group with generation Generation{generationId=79, memberId='consumer-ch-cloud-test-demo-2-8a8ff237-b8c9-4354-94d6-00d85d2a60c4', protocol='range'}

cloud-test-demo 2023-11-13 17:25:42.367 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Successfully joined group with generation Generation{generationId=79, memberId='consumer-ch-cloud-test-demo-1-1f56c920-8798-403a-907c-e357d064bc3a', protocol='range'}

cloud-test-demo 2023-11-13 17:25:42.367 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Successfully joined group with generation Generation{generationId=79, memberId='consumer-ch-cloud-test-demo-4-cff5d343-d3b1-47db-86d5-0f73e07f7fd6', protocol='range'}

cloud-test-demo 2023-11-13 17:25:42.367 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Successfully joined group with generation Generation{generationId=79, memberId='consumer-ch-cloud-test-demo-3-3e98e11e-c238-4919-a7bc-d04db61a9b6a', protocol='range'}

cloud-test-demo 2023-11-13 17:25:42.753 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Successfully synced group in generation Generation{generationId=79, memberId='consumer-ch-cloud-test-demo-2-8a8ff237-b8c9-4354-94d6-00d85d2a60c4', protocol='range'}

cloud-test-demo 2023-11-13 17:25:42.753 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Successfully synced group in generation Generation{generationId=79, memberId='consumer-ch-cloud-test-demo-4-cff5d343-d3b1-47db-86d5-0f73e07f7fd6', protocol='range'}

cloud-test-demo 2023-11-13 17:25:42.753 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Successfully synced group in generation Generation{generationId=79, memberId='consumer-ch-cloud-test-demo-1-1f56c920-8798-403a-907c-e357d064bc3a', protocol='range'}

cloud-test-demo 2023-11-13 17:25:42.753 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Successfully synced group in generation Generation{generationId=79, memberId='consumer-ch-cloud-test-demo-3-3e98e11e-c238-4919-a7bc-d04db61a9b6a', protocol='range'}

cloud-test-demo 2023-11-13 17:25:42.756 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Notifying assignor about the new Assignment(partitions=[ch-cloud-test-real-appver-2])

cloud-test-demo 2023-11-13 17:25:42.756 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Notifying assignor about the new Assignment(partitions=[topic-cloud-gateway-test-consistent-appver-2])

cloud-test-demo 2023-11-13 17:25:42.756 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Notifying assignor about the new Assignment(partitions=[ch-cloud-test-state-0, ch-cloud-test-state-1])

cloud-test-demo 2023-11-13 17:25:42.756 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Notifying assignor about the new Assignment(partitions=[ch-cloud-test-appvercollection-0, ch-cloud-test-appvercollection-1])

cloud-test-demo 2023-11-13 17:25:42.758 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Adding newly assigned partitions: ch-cloud-test-state-0, ch-cloud-test-state-1

cloud-test-demo 2023-11-13 17:25:42.758 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Adding newly assigned partitions: topic-cloud-gateway-test-consistent-appver-2

cloud-test-demo 2023-11-13 17:25:42.758 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Adding newly assigned partitions: ch-cloud-test-appvercollection-0, ch-cloud-test-appvercollection-1

cloud-test-demo 2023-11-13 17:25:42.758 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Adding newly assigned partitions: ch-cloud-test-real-appver-2

cloud-test-demo 2023-11-13 17:25:42.829 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Setting offset for partition ch-cloud-test-state-0 to the committed offset FetchPosition{offset=3511, offsetEpoch=Optional.empty, currentLeader=LeaderAndEpoch{leader=Optional[10.0.0.1:9092 (id: 2 rack: null)], epoch=absent}}

cloud-test-demo 2023-11-13 17:25:42.829 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Setting offset for partition ch-cloud-test-appvercollection-0 to the committed offset FetchPosition{offset=0, offsetEpoch=Optional.empty, currentLeader=LeaderAndEpoch{leader=Optional[10.177.0.87:9092 (id: 3 rack: null)], epoch=absent}}

cloud-test-demo 2023-11-13 17:25:42.829 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Setting offset for partition topic-cloud-gateway-test-consistent-appver-2 to the committed offset FetchPosition{offset=3, offsetEpoch=Optional.empty, currentLeader=LeaderAndEpoch{leader=Optional[10.177.0.145:9092 (id: 1 rack: null)], epoch=absent}}

cloud-test-demo 2023-11-13 17:25:42.829 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Setting offset for partition ch-cloud-test-real-appver-2 to the committed offset FetchPosition{offset=1, offsetEpoch=Optional.empty, currentLeader=LeaderAndEpoch{leader=Optional[10.177.0.87:9092 (id: 3 rack: null)], epoch=absent}}

cloud-test-demo 2023-11-13 17:25:42.829 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Setting offset for partition ch-cloud-test-state-1 to the committed offset FetchPosition{offset=3683, offsetEpoch=Optional.empty, currentLeader=LeaderAndEpoch{leader=Optional[10.177.0.87:9092 (id: 3 rack: null)], epoch=absent}}

cloud-test-demo 2023-11-13 17:25:42.829 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Setting offset for partition ch-cloud-test-appvercollection-1 to the committed offset FetchPosition{offset=0, offsetEpoch=Optional.empty, currentLeader=LeaderAndEpoch{leader=Optional[10.177.0.145:9092 (id: 1 rack: null)], epoch=absent}}

cloud-test-demo 2023-11-13 17:25:42.935 INFO 49000 --- [ntainer#2-0-C-1] o.s.k.l.KafkaMessageListenerContainer : [TID: N/A] ch-cloud-test-demo: partitions assigned: [ch-cloud-test-appvercollection-0, ch-cloud-test-appvercollection-1]

cloud-test-demo 2023-11-13 17:25:42.935 INFO 49000 --- [ntainer#1-0-C-1] o.s.k.l.KafkaMessageListenerContainer : [TID: N/A] ch-cloud-test-demo: partitions assigned: [ch-cloud-test-real-appver-2]

cloud-test-demo 2023-11-13 17:25:42.935 INFO 49000 --- [ntainer#0-0-C-1] o.s.k.l.KafkaMessageListenerContainer : [TID: N/A] ch-cloud-test-demo: partitions assigned: [ch-cloud-test-state-0, ch-cloud-test-state-1]

cloud-test-demo 2023-11-13 17:25:42.936 INFO 49000 --- [ntainer#3-0-C-1] o.s.k.l.KafkaMessageListenerContainer : [TID: N/A] ch-cloud-test-demo: partitions assigned: [topic-cloud-gateway-test-consistent-appver-2]

cloud-test-demo 2023-11-13 17:25:49.409 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Revoke previously assigned partitions ch-cloud-test-appvercollection-0, ch-cloud-test-appvercollection-1

cloud-test-demo 2023-11-13 17:25:49.409 INFO 49000 --- [ntainer#2-0-C-1] o.s.k.l.KafkaMessageListenerContainer : [TID: N/A] ch-cloud-test-demo: partitions revoked: [ch-cloud-test-appvercollection-0, ch-cloud-test-appvercollection-1]

cloud-test-demo 2023-11-13 17:25:49.409 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Member consumer-ch-cloud-test-demo-1-1f56c920-8798-403a-907c-e357d064bc3a sending LeaveGroup request to coordinator 10.0.0.1:9092 (id: 2147483645 rack: null) due to the consumer unsubscribed from all topics

cloud-test-demo 2023-11-13 17:25:49.411 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Resetting generation and member id due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.411 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Request joining group due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.411 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.clients.consumer.KafkaConsumer : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Unsubscribed all topics or patterns and assigned partitions

cloud-test-demo 2023-11-13 17:25:49.412 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Resetting generation and member id due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.412 INFO 49000 --- [ntainer#2-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-1, groupId=ch-cloud-test-demo] Request joining group due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.445 INFO 49000 --- [ntainer#2-0-C-1] org.apache.kafka.common.metrics.Metrics : [TID: N/A] Metrics scheduler closed

cloud-test-demo 2023-11-13 17:25:49.445 INFO 49000 --- [ntainer#2-0-C-1] org.apache.kafka.common.metrics.Metrics : [TID: N/A] Closing reporter org.apache.kafka.common.metrics.JmxReporter

cloud-test-demo 2023-11-13 17:25:49.446 INFO 49000 --- [ntainer#2-0-C-1] org.apache.kafka.common.metrics.Metrics : [TID: N/A] Metrics reporters closed

cloud-test-demo 2023-11-13 17:25:49.448 INFO 49000 --- [ntainer#2-0-C-1] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] App info kafka.consumer for consumer-ch-cloud-test-demo-1 unregistered

cloud-test-demo 2023-11-13 17:25:49.448 INFO 49000 --- [ntainer#2-0-C-1] o.s.k.l.KafkaMessageListenerContainer : [TID: N/A] ch-cloud-test-demo: Consumer stopped

cloud-test-demo 2023-11-13 17:25:49.449 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Revoke previously assigned partitions topic-cloud-gateway-test-consistent-appver-2

cloud-test-demo 2023-11-13 17:25:49.449 INFO 49000 --- [ntainer#3-0-C-1] o.s.k.l.KafkaMessageListenerContainer : [TID: N/A] ch-cloud-test-demo: partitions revoked: [topic-cloud-gateway-test-consistent-appver-2]

cloud-test-demo 2023-11-13 17:25:49.449 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Member consumer-ch-cloud-test-demo-2-8a8ff237-b8c9-4354-94d6-00d85d2a60c4 sending LeaveGroup request to coordinator 10.0.0.1:9092 (id: 2147483645 rack: null) due to the consumer unsubscribed from all topics

cloud-test-demo 2023-11-13 17:25:49.450 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Resetting generation and member id due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.450 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Request joining group due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.450 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.clients.consumer.KafkaConsumer : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Unsubscribed all topics or patterns and assigned partitions

cloud-test-demo 2023-11-13 17:25:49.450 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Resetting generation and member id due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.450 INFO 49000 --- [ntainer#3-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-2, groupId=ch-cloud-test-demo] Request joining group due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.486 INFO 49000 --- [ntainer#3-0-C-1] org.apache.kafka.common.metrics.Metrics : [TID: N/A] Metrics scheduler closed

cloud-test-demo 2023-11-13 17:25:49.486 INFO 49000 --- [ntainer#3-0-C-1] org.apache.kafka.common.metrics.Metrics : [TID: N/A] Closing reporter org.apache.kafka.common.metrics.JmxReporter

cloud-test-demo 2023-11-13 17:25:49.487 INFO 49000 --- [ntainer#3-0-C-1] org.apache.kafka.common.metrics.Metrics : [TID: N/A] Metrics reporters closed

cloud-test-demo 2023-11-13 17:25:49.488 INFO 49000 --- [ntainer#3-0-C-1] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] App info kafka.consumer for consumer-ch-cloud-test-demo-2 unregistered

cloud-test-demo 2023-11-13 17:25:49.488 INFO 49000 --- [ntainer#3-0-C-1] o.s.k.l.KafkaMessageListenerContainer : [TID: N/A] ch-cloud-test-demo: Consumer stopped

cloud-test-demo 2023-11-13 17:25:49.488 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Revoke previously assigned partitions ch-cloud-test-state-0, ch-cloud-test-state-1

cloud-test-demo 2023-11-13 17:25:49.488 INFO 49000 --- [ntainer#0-0-C-1] o.s.k.l.KafkaMessageListenerContainer : [TID: N/A] ch-cloud-test-demo: partitions revoked: [ch-cloud-test-state-0, ch-cloud-test-state-1]

cloud-test-demo 2023-11-13 17:25:49.488 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Member consumer-ch-cloud-test-demo-3-3e98e11e-c238-4919-a7bc-d04db61a9b6a sending LeaveGroup request to coordinator10.0.0.1:9092 (id: 2147483645 rack: null) due to the consumer unsubscribed from all topics

cloud-test-demo 2023-11-13 17:25:49.488 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Resetting generation and member id due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.488 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Request joining group due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.489 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.clients.consumer.KafkaConsumer : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Unsubscribed all topics or patterns and assigned partitions

cloud-test-demo 2023-11-13 17:25:49.489 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Resetting generation and member id due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.489 INFO 49000 --- [ntainer#0-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-3, groupId=ch-cloud-test-demo] Request joining group due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.519 INFO 49000 --- [ntainer#0-0-C-1] org.apache.kafka.common.metrics.Metrics : [TID: N/A] Metrics scheduler closed

cloud-test-demo 2023-11-13 17:25:49.519 INFO 49000 --- [ntainer#0-0-C-1] org.apache.kafka.common.metrics.Metrics : [TID: N/A] Closing reporter org.apache.kafka.common.metrics.JmxReporter

cloud-test-demo 2023-11-13 17:25:49.520 INFO 49000 --- [ntainer#0-0-C-1] org.apache.kafka.common.metrics.Metrics : [TID: N/A] Metrics reporters closed

cloud-test-demo 2023-11-13 17:25:49.521 INFO 49000 --- [ntainer#0-0-C-1] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] App info kafka.consumer for consumer-ch-cloud-test-demo-3 unregistered

cloud-test-demo 2023-11-13 17:25:49.521 INFO 49000 --- [ntainer#0-0-C-1] o.s.k.l.KafkaMessageListenerContainer : [TID: N/A] ch-cloud-test-demo: Consumer stopped

cloud-test-demo 2023-11-13 17:25:49.521 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Revoke previously assigned partitions ch-cloud-test-real-appver-2

cloud-test-demo 2023-11-13 17:25:49.521 INFO 49000 --- [ntainer#1-0-C-1] o.s.k.l.KafkaMessageListenerContainer : [TID: N/A] ch-cloud-test-demo: partitions revoked: [ch-cloud-test-real-appver-2]

cloud-test-demo 2023-11-13 17:25:49.521 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Member consumer-ch-cloud-test-demo-4-cff5d343-d3b1-47db-86d5-0f73e07f7fd6 sending LeaveGroup request to coordinator 10.0.0.1:9092 (id: 2147483645 rack: null) due to the consumer unsubscribed from all topics

cloud-test-demo 2023-11-13 17:25:49.521 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Resetting generation and member id due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.521 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Request joining group due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.521 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.clients.consumer.KafkaConsumer : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Unsubscribed all topics or patterns and assigned partitions

cloud-test-demo 2023-11-13 17:25:49.522 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Resetting generation and member id due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.522 INFO 49000 --- [ntainer#1-0-C-1] o.a.k.c.c.internals.ConsumerCoordinator : [TID: N/A] [Consumer clientId=consumer-ch-cloud-test-demo-4, groupId=ch-cloud-test-demo] Request joining group due to: consumer pro-actively leaving the group

cloud-test-demo 2023-11-13 17:25:49.553 INFO 49000 --- [ntainer#1-0-C-1] org.apache.kafka.common.metrics.Metrics : [TID: N/A] Metrics scheduler closed

cloud-test-demo 2023-11-13 17:25:49.553 INFO 49000 --- [ntainer#1-0-C-1] org.apache.kafka.common.metrics.Metrics : [TID: N/A] Closing reporter org.apache.kafka.common.metrics.JmxReporter

cloud-test-demo 2023-11-13 17:25:49.553 INFO 49000 --- [ntainer#1-0-C-1] org.apache.kafka.common.metrics.Metrics : [TID: N/A] Metrics reporters closed

cloud-test-demo 2023-11-13 17:25:49.554 INFO 49000 --- [ntainer#1-0-C-1] o.a.kafka.common.utils.AppInfoParser : [TID: N/A] App info kafka.consumer for consumer-ch-cloud-test-demo-4 unregistered

cloud-test-demo 2023-11-13 17:25:49.554 INFO 49000 --- [ntainer#1-0-C-1] o.s.k.l.KafkaMessageListenerContainer : [TID: N/A] ch-cloud-test-demo: Consumer stopped

cloud-test-demo 2023-11-13 17:25:49.570 INFO 49000 --- [ main] com.alibaba.druid.pool.DruidDataSource : [TID: N/A] {dataSource-0} closing ...

cloud-test-demo 2023-11-13 17:25:49.749 INFO 49000 --- [ main] o.apache.catalina.core.StandardService : [TID: N/A] Stopping service [Tomcat]

cloud-test-demo 2023-11-13 17:25:49.772 INFO 49000 --- [ main] ConditionEvaluationReportLoggingListener : [TID: N/A]

Error starting ApplicationContext. To display the conditions report re-run your application with 'debug' enabled.

cloud-test-demo 2023-11-13 17:25:49.793 ERROR 49000 --- [ main] o.s.boot.SpringApplication : [TID: N/A] Application run failed

org.springframework.context.ApplicationContextException: Failed to start bean 'webServerStartStop'; nested exception is java.lang.reflect.UndeclaredThrowableException

at org.springframework.context.support.DefaultLifecycleProcessor.doStart(DefaultLifecycleProcessor.java:182) ~[spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.support.DefaultLifecycleProcessor.access$200(DefaultLifecycleProcessor.java:54) ~[spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.support.DefaultLifecycleProcessor$LifecycleGroup.start(DefaultLifecycleProcessor.java:357) ~[spring-context-5.3.30.jar:5.3.30]

at java.lang.Iterable.forEach(Iterable.java:75) ~[na:1.8.0_152]

at org.springframework.context.support.DefaultLifecycleProcessor.startBeans(DefaultLifecycleProcessor.java:156) ~[spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.support.DefaultLifecycleProcessor.onRefresh(DefaultLifecycleProcessor.java:124) ~[spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.support.AbstractApplicationContext.finishRefresh(AbstractApplicationContext.java:938) ~[spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:586) ~[spring-context-5.3.30.jar:5.3.30]

at org.springframework.boot.web.servlet.context.ServletWebServerApplicationContext.refresh(ServletWebServerApplicationContext.java:147) ~[spring-boot-2.7.17.jar:2.7.17]

at org.springframework.boot.SpringApplication.refresh(SpringApplication.java:732) [spring-boot-2.7.17.jar:2.7.17]

at org.springframework.boot.SpringApplication.refreshContext(SpringApplication.java:409) [spring-boot-2.7.17.jar:2.7.17]

at org.springframework.boot.SpringApplication.run(SpringApplication.java:308) [spring-boot-2.7.17.jar:2.7.17]

at org.springframework.boot.SpringApplication.run(SpringApplication.java:1300) [spring-boot-2.7.17.jar:2.7.17]

at org.springframework.boot.SpringApplication.run(SpringApplication.java:1289) [spring-boot-2.7.17.jar:2.7.17]

at com.ch.cloud.vehicle.protocol.version.Application.main(Application.java:26) [classes/:na]

Caused by: java.lang.reflect.UndeclaredThrowableException: null

at org.springframework.util.ReflectionUtils.rethrowRuntimeException(ReflectionUtils.java:147) ~[spring-core-5.3.30.jar:5.3.30]

at com.alibaba.cloud.nacos.registry.NacosServiceRegistry.register(NacosServiceRegistry.java:83) ~[spring-cloud-starter-alibaba-nacos-discovery-2021.0.5.0.jar:2021.0.5.0]

at org.springframework.cloud.client.serviceregistry.AbstractAutoServiceRegistration.register(AbstractAutoServiceRegistration.java:232) ~[spring-cloud-commons-3.1.7.jar:3.1.7]

at com.alibaba.cloud.nacos.registry.NacosAutoServiceRegistration.register(NacosAutoServiceRegistration.java:78) ~[spring-cloud-starter-alibaba-nacos-discovery-2021.0.5.0.jar:2021.0.5.0]

at org.springframework.cloud.client.serviceregistry.AbstractAutoServiceRegistration.start(AbstractAutoServiceRegistration.java:133) ~[spring-cloud-commons-3.1.7.jar:3.1.7]

at org.springframework.cloud.client.serviceregistry.AbstractAutoServiceRegistration.bind(AbstractAutoServiceRegistration.java:98) ~[spring-cloud-commons-3.1.7.jar:3.1.7]

at org.springframework.cloud.client.serviceregistry.AbstractAutoServiceRegistration.onApplicationEvent(AbstractAutoServiceRegistration.java:86) ~[spring-cloud-commons-3.1.7.jar:3.1.7]

at org.springframework.cloud.client.serviceregistry.AbstractAutoServiceRegistration.onApplicationEvent(AbstractAutoServiceRegistration.java:47) ~[spring-cloud-commons-3.1.7.jar:3.1.7]

at org.springframework.context.event.SimpleApplicationEventMulticaster.doInvokeListener(SimpleApplicationEventMulticaster.java:178) ~[spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.event.SimpleApplicationEventMulticaster.invokeListener(SimpleApplicationEventMulticaster.java:171) ~[spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:145) ~[spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:421) ~[spring-context-5.3.30.jar:5.3.30]

at org.springframework.context.support.AbstractApplicationContext.publishEvent(AbstractApplicationContext.java:378) ~[spring-context-5.3.30.jar:5.3.30]

at org.springframework.boot.web.servlet.context.WebServerStartStopLifecycle.start(WebServerStartStopLifecycle.java:46) ~[spring-boot-2.7.17.jar:2.7.17]

at org.springframework.context.support.DefaultLifecycleProcessor.doStart(DefaultLifecycleProcessor.java:179) ~[spring-context-5.3.30.jar:5.3.30]

... 14 common frames omitted

Caused by: com.alibaba.nacos.api.exception.NacosException: failed to req API:/nacos/v1/ns/instance after all servers([nacos-tmp.z-cch.cn:8848]) tried: <!doctype html>HTTP Status 400 – Bad Request

HTTP Status 400 – Bad Request

at com.alibaba.nacos.client.naming.net.NamingProxy.reqApi(NamingProxy.java:561) ~[nacos-client-1.4.4.jar:na]

at com.alibaba.nacos.client.naming.net.NamingProxy.reqApi(NamingProxy.java:503) ~[nacos-client-1.4.4.jar:na]

at com.alibaba.nacos.client.naming.net.NamingProxy.reqApi(NamingProxy.java:498) ~[nacos-client-1.4.4.jar:na]

at com.alibaba.nacos.client.naming.net.NamingProxy.registerService(NamingProxy.java:251) ~[nacos-client-1.4.4.jar:na]

at com.alibaba.nacos.client.naming.NacosNamingService.registerInstance(NacosNamingService.java:218) ~[nacos-client-1.4.4.jar:na]

at com.alibaba.cloud.nacos.registry.NacosServiceRegistry.register(NacosServiceRegistry.java:75) ~[spring-cloud-starter-alibaba-nacos-discovery-2021.0.5.0.jar:2021.0.5.0]

... 27 common frames omitted

cloud-test-demo 2023-11-13 17:25:50.575 WARN 49000 --- [ Thread-4] c.a.n.common.http.HttpClientBeanHolder : [TID: N/A] [HttpClientBeanHolder] Start destroying common HttpClient

cloud-test-demo 2023-11-13 17:25:50.575 WARN 49000 --- [ Thread-32] c.a.nacos.common.notify.NotifyCenter : [TID: N/A] [NotifyCenter] Start destroying Publisher

cloud-test-demo 2023-11-13 17:25:50.576 WARN 49000 --- [ Thread-32] c.a.nacos.common.notify.NotifyCenter : [TID: N/A] [NotifyCenter] Destruction of the end

cloud-test-demo 2023-11-13 17:25:50.576 WARN 49000 --- [ Thread-4] c.a.n.common.http.HttpClientBeanHolder : [TID: N/A] [HttpClientBeanHolder] Destruction of the end

Disconnected from the target VM, address: '127.0.0.1:63364', transport: 'socket'

Process finished with exit code 1

Expected behavior 官方文档说是2.服务端兼容1.客户端 当前我们

请各位大大看下是怎么回事

KomachiSion commented 1 year ago
  1. 是兼容的
  2. 400错误说明你客户端发起的http请求是存在问题的，直接就被tomcat拦截了，没走到具体的逻辑上。
  3. 400错误通常出现在以下情况：1）使用了vip或vpn等代理，将你的请求包进行了修改，比如ppv2等协议；2）服务端设置有问题，过小的http header size等，导致服务端无法解析http请求。
studyyoursister commented 1 year ago
  1. 是兼容的
  2. 400错误说明你客户端发起的http请求是存在问题的，直接就被tomcat拦截了，没走到具体的逻辑上。
  3. 400错误通常出现在以下情况：1）使用了vip或vpn等代理，将你的请求包进行了修改，比如ppv2等协议；2）服务端设置有问题，过小的http header size等，导致服务端无法解析http请求。

针对1和3，我们的客户端环境是一样的，没有变过，仅仅变更了客户端的nacos版本：1.4.4,2.0.3,2.0.4,2.2.0,2.2.3,2.2.4, 测试下来只有1.4.4有如上问题，导致客户端无法注册。如果是请求的问题，为什么其它版本的客户端就没有问题？

KomachiSion commented 1 year ago

因为你列的所有客户端版本里， 只有1.4.4 是http通信， 其他的是grpc通信

KomachiSion commented 1 year ago

No more response from author, and community can't reproduce this problem.