Application log: was not able to stop controller thread (solved)

Meikelv · May 18, 2017, 6:51am

Hi,
I found a lot of error messages in my application.log
Some examples:

"ERROR","Thread-6","05/18/2017","05:21:47","controller","controler thread [880230017] forced to stop after 65009ms"
"ERROR","Thread-6","05/18/2017","06:01:47","controler","was not able to stop controller thread running for 50003ms;java.lang.Throwable;java.lang.Throwable
	at java.net.DualStackPlainDatagramSocketImpl.socketReceiveOrPeekData(Native Method)
	at java.net.DualStackPlainDatagramSocketImpl.receive0(DualStackPlainDatagramSocketImpl.java:124)
	at java.net.AbstractPlainDatagramSocketImpl.receive(AbstractPlainDatagramSocketImpl.java:143)
	at java.net.DatagramSocket.receive(DatagramSocket.java:812)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:72)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:92)
	at lucee.runtime.config.ConfigImpl.reloadTimeServerOffset(ConfigImpl.java:476)
	at lucee.runtime.engine.Controler.control(Controler.java:239)
	at lucee.runtime.engine.Controler.control(Controler.java:222)
	at lucee.runtime.engine.Controler.access$000(Controler.java:59)
	at lucee.runtime.engine.Controler$ControlerThread.run(Controler.java:110)
"
"ERROR","Thread-6","05/18/2017","06:01:52","controler","was not able to stop controller thread running for 55010ms;java.lang.Throwable;java.lang.Throwable
	at java.net.DualStackPlainDatagramSocketImpl.socketReceiveOrPeekData(Native Method)
	at java.net.DualStackPlainDatagramSocketImpl.receive0(DualStackPlainDatagramSocketImpl.java:124)
	at java.net.AbstractPlainDatagramSocketImpl.receive(AbstractPlainDatagramSocketImpl.java:143)
	at java.net.DatagramSocket.receive(DatagramSocket.java:812)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:72)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:92)
	at lucee.runtime.config.ConfigImpl.reloadTimeServerOffset(ConfigImpl.java:476)
	at lucee.runtime.engine.Controler.control(Controler.java:239)
	at lucee.runtime.engine.Controler.control(Controler.java:222)
	at lucee.runtime.engine.Controler.access$000(Controler.java:59)
	at lucee.runtime.engine.Controler$ControlerThread.run(Controler.java:110)
"
"ERROR","Thread-6","05/18/2017","06:01:57","controler","was not able to stop controller thread running for 60012ms;java.lang.Throwable;java.lang.Throwable
	at java.net.DualStackPlainDatagramSocketImpl.socketReceiveOrPeekData(Native Method)
	at java.net.DualStackPlainDatagramSocketImpl.receive0(DualStackPlainDatagramSocketImpl.java:124)
	at java.net.AbstractPlainDatagramSocketImpl.receive(AbstractPlainDatagramSocketImpl.java:143)
	at java.net.DatagramSocket.receive(DatagramSocket.java:812)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:72)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:92)
	at lucee.runtime.config.ConfigImpl.reloadTimeServerOffset(ConfigImpl.java:476)
	at lucee.runtime.engine.Controler.control(Controler.java:239)
	at lucee.runtime.engine.Controler.control(Controler.java:222)
	at lucee.runtime.engine.Controler.access$000(Controler.java:59)
	at lucee.runtime.engine.Controler$ControlerThread.run(Controler.java:110)
"
"ERROR","Thread-6","05/18/2017","06:02:02","controller","controler thread [1636708893] forced to stop after 65013ms"
"ERROR","Thread-6","05/18/2017","07:01:42","controler","was not able to stop controller thread running for 50003ms;java.lang.Throwable;java.lang.Throwable
	at java.net.DualStackPlainDatagramSocketImpl.socketReceiveOrPeekData(Native Method)
	at java.net.DualStackPlainDatagramSocketImpl.receive0(DualStackPlainDatagramSocketImpl.java:124)
	at java.net.AbstractPlainDatagramSocketImpl.receive(AbstractPlainDatagramSocketImpl.java:143)
	at java.net.DatagramSocket.receive(DatagramSocket.java:812)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:72)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:92)
	at lucee.runtime.config.ConfigImpl.reloadTimeServerOffset(ConfigImpl.java:476)
	at lucee.runtime.engine.Controler.control(Controler.java:303)
	at lucee.runtime.engine.Controler.control(Controler.java:222)
	at lucee.runtime.engine.Controler.access$000(Controler.java:59)
	at lucee.runtime.engine.Controler$ControlerThread.run(Controler.java:110)
"
"ERROR","Thread-6","05/18/2017","07:01:47","controler","was not able to stop controller thread running for 55009ms;java.lang.Throwable;java.lang.Throwable
	at java.net.DualStackPlainDatagramSocketImpl.socketReceiveOrPeekData(Native Method)
	at java.net.DualStackPlainDatagramSocketImpl.receive0(DualStackPlainDatagramSocketImpl.java:124)
	at java.net.AbstractPlainDatagramSocketImpl.receive(AbstractPlainDatagramSocketImpl.java:143)
	at java.net.DatagramSocket.receive(DatagramSocket.java:812)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:72)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:92)
	at lucee.runtime.config.ConfigImpl.reloadTimeServerOffset(ConfigImpl.java:476)
	at lucee.runtime.engine.Controler.control(Controler.java:303)
	at lucee.runtime.engine.Controler.control(Controler.java:222)
	at lucee.runtime.engine.Controler.access$000(Controler.java:59)
	at lucee.runtime.engine.Controler$ControlerThread.run(Controler.java:110)
"
"ERROR","Thread-6","05/18/2017","07:01:52","controler","was not able to stop controller thread running for 60011ms;java.lang.Throwable;java.lang.Throwable
	at java.net.DualStackPlainDatagramSocketImpl.socketReceiveOrPeekData(Native Method)
	at java.net.DualStackPlainDatagramSocketImpl.receive0(DualStackPlainDatagramSocketImpl.java:124)
	at java.net.AbstractPlainDatagramSocketImpl.receive(AbstractPlainDatagramSocketImpl.java:143)
	at java.net.DatagramSocket.receive(DatagramSocket.java:812)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:72)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:92)
	at lucee.runtime.config.ConfigImpl.reloadTimeServerOffset(ConfigImpl.java:476)
	at lucee.runtime.engine.Controler.control(Controler.java:303)
	at lucee.runtime.engine.Controler.control(Controler.java:222)
	at lucee.runtime.engine.Controler.access$000(Controler.java:59)
	at lucee.runtime.engine.Controler$ControlerThread.run(Controler.java:110)
"
"ERROR","Thread-6","05/18/2017","07:01:57","controller","controler thread [1707093818] forced to stop after 65012ms"
"ERROR","Thread-6","05/18/2017","08:01:42","controler","was not able to stop controller thread running for 50003ms;java.lang.Throwable;java.lang.Throwable
	at java.net.DualStackPlainDatagramSocketImpl.socketReceiveOrPeekData(Native Method)
	at java.net.DualStackPlainDatagramSocketImpl.receive0(DualStackPlainDatagramSocketImpl.java:124)
	at java.net.AbstractPlainDatagramSocketImpl.receive(AbstractPlainDatagramSocketImpl.java:143)
	at java.net.DatagramSocket.receive(DatagramSocket.java:812)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:72)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:92)
	at lucee.runtime.config.ConfigImpl.reloadTimeServerOffset(ConfigImpl.java:476)
	at lucee.runtime.engine.Controler.control(Controler.java:303)
	at lucee.runtime.engine.Controler.control(Controler.java:222)
	at lucee.runtime.engine.Controler.access$000(Controler.java:59)
	at lucee.runtime.engine.Controler$ControlerThread.run(Controler.java:110)
"
"ERROR","Thread-6","05/18/2017","08:01:47","controler","was not able to stop controller thread running for 55007ms;java.lang.Throwable;java.lang.Throwable
	at java.net.DualStackPlainDatagramSocketImpl.socketReceiveOrPeekData(Native Method)
	at java.net.DualStackPlainDatagramSocketImpl.receive0(DualStackPlainDatagramSocketImpl.java:124)
	at java.net.AbstractPlainDatagramSocketImpl.receive(AbstractPlainDatagramSocketImpl.java:143)
	at java.net.DatagramSocket.receive(DatagramSocket.java:812)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:72)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:92)
	at lucee.runtime.config.ConfigImpl.reloadTimeServerOffset(ConfigImpl.java:476)
	at lucee.runtime.engine.Controler.control(Controler.java:303)
	at lucee.runtime.engine.Controler.control(Controler.java:222)
	at lucee.runtime.engine.Controler.access$000(Controler.java:59)
	at lucee.runtime.engine.Controler$ControlerThread.run(Controler.java:110)
"
"ERROR","Thread-6","05/18/2017","08:01:52","controler","was not able to stop controller thread running for 60009ms;java.lang.Throwable;java.lang.Throwable
	at java.net.DualStackPlainDatagramSocketImpl.socketReceiveOrPeekData(Native Method)
	at java.net.DualStackPlainDatagramSocketImpl.receive0(DualStackPlainDatagramSocketImpl.java:124)
	at java.net.AbstractPlainDatagramSocketImpl.receive(AbstractPlainDatagramSocketImpl.java:143)
	at java.net.DatagramSocket.receive(DatagramSocket.java:812)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:72)
	at lucee.runtime.net.ntp.NtpClient.getOffset(NtpClient.java:92)
	at lucee.runtime.config.ConfigImpl.reloadTimeServerOffset(ConfigImpl.java:476)
	at lucee.runtime.engine.Controler.control(Controler.java:303)
	at lucee.runtime.engine.Controler.control(Controler.java:222)
	at lucee.runtime.engine.Controler.access$000(Controler.java:59)
	at lucee.runtime.engine.Controler$ControlerThread.run(Controler.java:110)
"
"ERROR","Thread-6","05/18/2017","08:01:57","controller","controler thread [433779773] forced to stop after 65010ms"

I forgot something :

Version 	Lucee 5.2.1.9
Servlet Container 	Apache Tomcat/8.5.11
Betriebssystem 	Windows Server 2012 R2 (6.3) 64bit

Any ideas what I can do?

Thank you

Michael

Meikelv · May 23, 2017, 6:01am

No one with a tip?

Michael

bdw429s · May 23, 2017, 3:38pm

@micstriit Do you have any input on this topic?

Meikelv · May 24, 2017, 8:15am

I found the error

My server has problems with the in the adminstrator defined ntp server (pool.ntp.org).
I changed it into a local server, which has its own synchronisation and now I didn’t get any error messages (since 18 hours).

Thanks to all who have thought about this topic.

Greetings

Michael