Skip to content

[Bug] Failed to start nameserver under wsl #7515

New issue
New issue
Closed
Closed
[Bug] Failed to start nameserver under wsl#7515
@derecknowayback

Description

@derecknowayback
derecknowayback
opened on Oct 30, 2023

Before Creating the Bug Report

  • I found a bug, not just asking a question, which should be created in GitHub Discussions.

  • I have searched the GitHub Issues and GitHub Discussions of this repository and believe that this is not a duplicate.

  • I have confirmed that this bug belongs to the current repository, not other repositories of RocketMQ.

Runtime platform environment

OS: Ubuntu 22.04 (WSL)

RocketMQ version

5.1.4 release

JDK Version

openjdk version "1.8.0_382"
OpenJDK Runtime Environment (build 1.8.0_382-8u382-ga-1~22.04.1-b05)
OpenJDK 64-Bit Server VM (build 25.382-b05, mixed mode)

Describe the Bug

When I try to start the name server, I hit the following problem:

2023-10-30 08:42:40 INFO main - Start service thread:FileWatchService started:true lastThread:Thread[FileWatchService,5,main]
2023-10-30 08:42:40 INFO main - Try to start service thread:org.apache.rocketmq.namesrv.routeinfo.BatchUnregistrationService started:false lastThread:null
2023-10-30 08:42:40 INFO main - Start service thread:org.apache.rocketmq.namesrv.routeinfo.BatchUnregistrationService started:true lastThread:Thread[org.apache.rocketmq.namesrv.routeinfo.BatchUnregistrationService,5,main]
2023-10-30 08:42:40 INFO main - The Name Server boot success. serializeType=JSON, address 0.0.0.0:9876
2023-10-30 08:42:40 INFO NettyClientScan_thread_1 - createChannel: begin to connect remote host[172.30.44.144:9876] asynchronously
2023-10-30 08:42:41 INFO NettyClientWorkerThread_1 - NETTY CLIENT PIPELINE: CONNECT  UNKNOWN => 172.30.44.144:9876
2023-10-30 08:42:41 INFO NettyClientScan_thread_1 - createChannel: connect remote host[172.30.44.144:9876] success, AbstractBootstrap$PendingRegistrationPromise@4ae2ab3d(success)2023-10-30 08:42:41 INFO NettyServerCodecThread_1 - NETTY SERVER PIPELINE: channelRegistered 172.30.44.144:48294
2023-10-30 08:42:41 INFO NettyServerCodecThread_1 - NETTY SERVER PIPELINE: channelActive, the channel[172.30.44.144:48294]
2023-10-30 08:42:45 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:42:50 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:42:55 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:43:00 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:43:05 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:43:10 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:43:15 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:43:20 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:43:25 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:43:30 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:43:35 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:43:40 INFO NSScheduledThread - --------------------------------------------------------
2023-10-30 08:43:40 INFO NSScheduledThread - configTable SIZE: 0
2023-10-30 08:43:40 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:43:45 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:43:50 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:43:55 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:44:00 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:44:05 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:44:10 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:44:15 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:44:20 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:44:25 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:44:30 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:44:35 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:44:40 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:44:41 WARN NettyClientWorkerThread_1 - NETTY CLIENT PIPELINE: IDLE exception [172.30.44.144:9876]
2023-10-30 08:44:41 INFO NettyClientWorkerThread_1 - closeChannel: the channel[172.30.44.144:9876] was removed from channel table
2023-10-30 08:44:41 INFO NettyClientWorkerThread_1 - NETTY CLIENT PIPELINE: CLOSE 172.30.44.144:9876
2023-10-30 08:44:41 INFO NettyClientWorkerThread_1 - eventCloseChannel: the channel[null] has been removed from the channel table before
2023-10-30 08:44:41 WARN NettyServerCodecThread_1 - NETTY SERVER PIPELINE: IDLE exception [172.30.44.144:48294]
2023-10-30 08:44:41 INFO NettyServerNIOSelector_1 - closeChannel: close the connection to remote address[172.30.44.144:48294] result: true
2023-10-30 08:44:41 INFO NettyClientSelector_1 - closeChannel: close the connection to remote address[172.30.44.144:9876] result: true
2023-10-30 08:44:41 INFO NettyClientWorkerThread_1 - NETTY CLIENT PIPELINE: channelInactive, the channel[172.30.44.144:9876]
2023-10-30 08:44:41 INFO NettyServerCodecThread_1 - NETTY SERVER PIPELINE: channelInactive, the channel[172.30.44.144:48294]
2023-10-30 08:44:41 INFO NettyClientWorkerThread_1 - eventCloseChannel: the channel[null] has been removed from the channel table before
2023-10-30 08:44:41 INFO NettyServerCodecThread_1 - NETTY SERVER PIPELINE: channelUnregistered, the channel[172.30.44.144:48294]
2023-10-30 08:44:41 INFO NettyClientScan_thread_4 - createChannel: begin to connect remote host[172.30.44.144:9876] asynchronously
2023-10-30 08:44:41 INFO NettyClientWorkerThread_2 - NETTY CLIENT PIPELINE: CONNECT  UNKNOWN => 172.30.44.144:9876
2023-10-30 08:44:41 INFO NettyClientScan_thread_4 - createChannel: connect remote host[172.30.44.144:9876] success, AbstractBootstrap$PendingRegistrationPromise@a6ca40f(success)
2023-10-30 08:44:41 INFO NettyServerCodecThread_2 - NETTY SERVER PIPELINE: channelRegistered 172.30.44.144:38614
2023-10-30 08:44:41 INFO NettyServerCodecThread_2 - NETTY SERVER PIPELINE: channelActive, the channel[172.30.44.144:38614]
2023-10-30 08:44:45 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:44:50 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:44:55 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:45:00 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:45:05 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:45:10 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:45:15 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:45:20 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:45:25 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:45:30 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:45:35 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:45:40 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:45:45 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:45:50 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:45:55 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:46:00 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:46:05 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:46:10 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:46:15 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:46:20 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:46:25 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:46:30 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:46:35 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:46:40 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:46:41 WARN NettyClientWorkerThread_2 - NETTY CLIENT PIPELINE: IDLE exception [172.30.44.144:9876]
2023-10-30 08:46:41 INFO NettyClientWorkerThread_2 - closeChannel: the channel[172.30.44.144:9876] was removed from channel table
2023-10-30 08:46:41 INFO NettyClientWorkerThread_2 - NETTY CLIENT PIPELINE: CLOSE 172.30.44.144:9876
2023-10-30 08:46:41 INFO NettyClientWorkerThread_2 - eventCloseChannel: the channel[null] has been removed from the channel table before
2023-10-30 08:46:41 INFO NettyClientSelector_1 - closeChannel: close the connection to remote address[172.30.44.144:9876] result: true
2023-10-30 08:46:41 INFO NettyClientWorkerThread_2 - NETTY CLIENT PIPELINE: channelInactive, the channel[172.30.44.144:9876]
2023-10-30 08:46:41 INFO NettyClientWorkerThread_2 - eventCloseChannel: the channel[null] has been removed from the channel table before
2023-10-30 08:46:41 INFO NettyServerCodecThread_2 - NETTY SERVER PIPELINE: channelInactive, the channel[172.30.44.144:38614]
2023-10-30 08:46:41 INFO NettyServerCodecThread_2 - NETTY SERVER PIPELINE: channelUnregistered, the channel[172.30.44.144:38614]
2023-10-30 08:46:42 INFO NettyClientScan_thread_3 - createChannel: begin to connect remote host[172.30.44.144:9876] asynchronously
2023-10-30 08:46:42 INFO NettyClientWorkerThread_3 - NETTY CLIENT PIPELINE: CONNECT  UNKNOWN => 172.30.44.144:9876
2023-10-30 08:46:42 INFO NettyClientScan_thread_3 - createChannel: connect remote host[172.30.44.144:9876] success, AbstractBootstrap$PendingRegistrationPromise@629ddea2(success)2023-10-30 08:46:42 INFO NettyServerCodecThread_3 - NETTY SERVER PIPELINE: channelRegistered 172.30.44.144:57198
2023-10-30 08:46:42 INFO NettyServerCodecThread_3 - NETTY SERVER PIPELINE: channelActive, the channel[172.30.44.144:57198]
2023-10-30 08:46:45 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:46:50 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:46:55 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:47:00 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:47:05 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:47:10 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:47:15 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:47:20 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:47:25 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:47:30 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:47:35 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:47:40 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:47:45 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:47:50 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:47:55 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:48:00 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:48:05 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:48:10 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:48:15 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:48:20 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:48:25 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:48:30 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:48:35 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:48:40 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:48:42 WARN NettyClientWorkerThread_3 - NETTY CLIENT PIPELINE: IDLE exception [172.30.44.144:9876]
2023-10-30 08:48:42 INFO NettyClientWorkerThread_3 - closeChannel: the channel[172.30.44.144:9876] was removed from channel table
2023-10-30 08:48:42 INFO NettyClientWorkerThread_3 - NETTY CLIENT PIPELINE: CLOSE 172.30.44.144:9876
2023-10-30 08:48:42 INFO NettyClientWorkerThread_3 - eventCloseChannel: the channel[null] has been removed from the channel table before
2023-10-30 08:48:42 INFO NettyClientSelector_1 - closeChannel: close the connection to remote address[172.30.44.144:9876] result: true
2023-10-30 08:48:42 INFO NettyClientWorkerThread_3 - NETTY CLIENT PIPELINE: channelInactive, the channel[172.30.44.144:9876]
2023-10-30 08:48:42 INFO NettyClientWorkerThread_3 - eventCloseChannel: the channel[null] has been removed from the channel table before
2023-10-30 08:48:42 INFO NettyServerCodecThread_3 - NETTY SERVER PIPELINE: channelInactive, the channel[172.30.44.144:57198]
2023-10-30 08:48:42 INFO NettyServerCodecThread_3 - NETTY SERVER PIPELINE: channelUnregistered, the channel[172.30.44.144:57198]
2023-10-30 08:48:43 INFO NettyClientScan_thread_2 - createChannel: begin to connect remote host[172.30.44.144:9876] asynchronously
2023-10-30 08:48:43 INFO NettyClientWorkerThread_4 - NETTY CLIENT PIPELINE: CONNECT  UNKNOWN => 172.30.44.144:9876
2023-10-30 08:48:43 INFO NettyClientScan_thread_2 - createChannel: connect remote host[172.30.44.144:9876] success, AbstractBootstrap$PendingRegistrationPromise@34975990(success)2023-10-30 08:48:43 INFO NettyServerCodecThread_4 - NETTY SERVER PIPELINE: channelRegistered 172.30.44.144:48562
2023-10-30 08:48:43 INFO NettyServerCodecThread_4 - NETTY SERVER PIPELINE: channelActive, the channel[172.30.44.144:48562]
2023-10-30 08:48:45 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:48:50 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:48:55 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:00 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:05 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:10 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:15 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:20 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:25 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:30 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:35 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:40 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:45 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:50 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:55 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:50:00 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:50:05 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:50:10 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:50:15 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:50:20 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:50:25 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:50:30 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:50:35 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:50:40 INFO NSScanScheduledThread - start scanNotActiveBroker

Steps to Reproduce

Just following the quick start tutorial: https://rocketmq.apache.org/docs/quickStart/01quickstart/

### start namesrv
$ nohup sh bin/mqnamesrv &
 
### verify namesrv 
$ tail -f ~/logs/rocketmqlogs/namesrv.log
The Name Server boot success...

What Did You Expect to See?

Nameserver should boot success.

What Did You See Instead?

The log is filled with the following message:

2023-10-30 08:48:45 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:48:50 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:48:55 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:00 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:05 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:10 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:15 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:20 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:25 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:30 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:35 INFO NSScanScheduledThread - start scanNotActiveBroker
2023-10-30 08:49:40 INFO NSScanScheduledThread - start scanNotActiveBroker

Additional Context

No response

Metadata

Metadata

Assignees

No one assigned

    Labels

    No labels
    No labels

    Type

    No type

    Projects

    No projects

    Milestone

    No milestone

    Relationships

    None yet

    Development

    No branches or pull requests

    Issue actions