CMAK icon indicating copy to clipboard operation
CMAK copied to clipboard

kafka-manager is blocked and it takes more time to start play.api.Play and listen on 9000

Open LiXiao-Yu opened this issue 4 years ago • 3 comments

here is the logs:

2020-10-14 10:07:09,544 - [INFO] k.m.a.KafkaManagerActor - Updating internal state... 2020-10-14 10:07:19,544 - [INFO] k.m.a.KafkaManagerActor - Updating internal state... 2020-10-14 10:07:29,544 - [INFO] k.m.a.KafkaManagerActor - Updating internal state... 2020-10-14 10:07:39,544 - [INFO] k.m.a.KafkaManagerActor - Updating internal state... 2020-10-14 10:07:49,543 - [INFO] k.m.a.KafkaManagerActor - Updating internal state... 2020-10-14 10:07:59,543 - [INFO] k.m.a.KafkaManagerActor - Updating internal state... 2020-10-14 10:08:09,543 - [INFO] k.m.a.KafkaManagerActor - Updating internal state... 2020-10-14 10:08:19,544 - [INFO] k.m.a.KafkaManagerActor - Updating internal state... 2020-10-14 10:08:28,147 - [INFO] play.api.Play - Application started (Prod) 2020-10-14 10:08:29,486 - [INFO] p.c.s.AkkaHttpServer - Listening for HTTP on /0:0:0:0:0:0:0:0:9000 2020-10-14 10:08:29,544 - [INFO] k.m.a.KafkaManagerActor - Updating internal state... 2020-10-14 10:08:39,543 - [INFO] k.m.a.KafkaManagerActor - Updating internal state... 2020-10-14 10:08:49,543 - [INFO] k.m.a.KafkaManagerActor - Updating internal state... 2020-10-14 10:08:59,544 - [INFO] k.m.a.KafkaManagerActor - Updating internal state... 2020-10-14 10:09:09,544 - [INFO] k.m.a.KafkaManagerActor - Updating internal state... 2020-10-14 10:09:19,543 - [INFO] k.m.a.KafkaManagerActor - Updating internal state...

It takes more than 15min to start play.api.Play, and listen on 9000. the jstack shows:

"main" #1 prio=5 os_prio=0 tid=0x00007f745404f000 nid=0x2429 runnable [0x00007f745df95000] java.lang.Thread.State: RUNNABLE at java.io.FileInputStream.readBytes(Native Method) at java.io.FileInputStream.read(FileInputStream.java:255) at sun.security.provider.NativePRNG$RandomIO.readFully(NativePRNG.java:424) at sun.security.provider.NativePRNG$RandomIO.implGenerateSeed(NativePRNG.java:441) - locked <0x00000000ee0ae920> (a java.lang.Object) at sun.security.provider.NativePRNG$RandomIO.access$500(NativePRNG.java:331) at sun.security.provider.NativePRNG.engineGenerateSeed(NativePRNG.java:226) at java.security.SecureRandom.generateSeed(SecureRandom.java:533) at controllers.BasicAuthenticationFilterConfiguration$.$anonfun$parse$5(BasicAuthenticationFilter.scala:315) at controllers.BasicAuthenticationFilterConfiguration$$$Lambda$808/1198515856.apply(Unknown Source) at scala.Option.getOrElse(Option.scala:138) at controllers.BasicAuthenticationFilterConfiguration$.parse(BasicAuthenticationFilter.scala:315) at controllers.BasicAuthenticationFilter$.apply(BasicAuthenticationFilter.scala:382) at loader.ApplicationComponents.httpFilters$lzycompute(KafkaManagerLoader.scala:48) - locked <0x00000000edf2ae88> (a loader.ApplicationComponents) at loader.ApplicationComponents.httpFilters(KafkaManagerLoader.scala:48) at play.api.BuiltInComponents.httpRequestHandler(Application.scala:321) at play.api.BuiltInComponents.httpRequestHandler$(Application.scala:321) at play.api.BuiltInComponentsFromContext.httpRequestHandler$lzycompute(ApplicationLoader.scala:122) - locked <0x00000000edf2ae88> (a loader.ApplicationComponents) at play.api.BuiltInComponentsFromContext.httpRequestHandler(ApplicationLoader.scala:122) at play.api.BuiltInComponents.application(Application.scala:324) at play.api.BuiltInComponents.application$(Application.scala:323) at play.api.BuiltInComponentsFromContext.application$lzycompute(ApplicationLoader.scala:122) - locked <0x00000000edf2ae88> (a loader.ApplicationComponents) at play.api.BuiltInComponentsFromContext.application(ApplicationLoader.scala:122) at loader.KafkaManagerLoader.load(KafkaManagerLoader.scala:29) at play.core.server.ProdServerStart$.start(ProdServerStart.scala:51) at play.core.server.ProdServerStart$.main(ProdServerStart.scala:25) at play.core.server.ProdServerStart.main(ProdServerStart.scala)

"VM Thread" os_prio=0 tid=0x00007f74540cf000 nid=0x242a runnable

"VM Periodic Task Thread" os_prio=0 tid=0x00007f7454120000 nid=0x2431 waiting on condition

JNI global references: 1564

i have no idea how to fix it and everything that i did does not work, eventhough i use the newest cmak. i use kafka1.0.0 in my mechine.

LiXiao-Yu avatar Oct 14 '20 02:10 LiXiao-Yu

Hm, how many cpus/threads does the machine have?

hiralsp avatar Oct 28 '20 17:10 hiralsp

Hm, how many cpus/threads does the machine have?

it is a standalone Kafka~ The machine has 1 cpus and 1 threads. I tried 2 cpus and 2 threads, and it take more than 5 min to listening for HTTP:9000 . Is it necessary to make sure more cpus? Kafka works well when only 1 cpus.

LiXiao-Yu avatar Nov 27 '20 03:11 LiXiao-Yu

@LiXiao-Yu Were you able to solve this? I am facing same issue.

nagri avatar Apr 26 '23 11:04 nagri