I had my server running overnight last night, and from 10:40 to around 12:40 I had a lot of heavy network usage unrelated to the server. During that period my logs show a lot of channels being opened and closed. At the end of that period, the server fails to create a thread due to an OOMError. During this time, UAExpert was connected to the server, and subscribed to ten tags. None of the tags were changing, so there shouldn’t be a lot of OPC-related traffic.

Two questions:

1. Do you know why heavy network traffic would cause the channels to be opened and closed like this?

2. Do you think the OOMError could be caused by a memory leak in the toolkit?

Here’s the log – it’s in reverse order:

2013-07-16 00:41:50,733 [Non-Blocking-Work-Executor-1] ERROR – Uncaught Exception in Thread Thread[Non-Blocking-Work-Executor-1,5,Non-Blocking-Work-Executor-Group]
java.lang.OutOfMemoryError: unable to create new native thread
at java.lang.Thread.start0(Native Method)
at java.lang.Thread.start(Thread.java:691)
at java.util.concurrent.ThreadPoolExecutor.addWorker(ThreadPoolExecutor.java:943)
at java.util.concurrent.ThreadPoolExecutor.processWorkerExit(ThreadPoolExecutor.java:992)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:722)
2013-07-16 00:40:49,901 [Blocking-Work-Executor-7581] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=52)
2013-07-16 00:40:49,892 [Blocking-Work-Executor-7581] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=52, creationTime=Jul 16, 2013 12:40:49 AM, lifetime=3600000)
2013-07-16 00:40:49,831 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55667 connected
2013-07-16 00:40:49,801 [Non-Blocking-Work-Executor-1] INFO – Channel closed: Id=51, open channels=0
2013-07-16 00:40:49,798 [Non-Blocking-Work-Executor-1] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=51, creationTime=Jul 16, 2013 12:37:28 AM, lifetime=3600000)
2013-07-16 00:37:36,343 [Selector] INFO – Channel closed: Id=50, open channels=0
2013-07-16 00:37:36,343 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=50, creationTime=Jul 16, 2013 12:25:27 AM, lifetime=3600000)
2013-07-16 00:37:28,604 [Blocking-Work-Executor-7505] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=51)
2013-07-16 00:37:28,596 [Blocking-Work-Executor-7505] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=51, creationTime=Jul 16, 2013 12:37:28 AM, lifetime=3600000)
2013-07-16 00:37:28,573 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55652 connected
2013-07-16 00:25:31,838 [Selector] INFO – Channel closed: Id=49, open channels=0
2013-07-16 00:25:31,833 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=49, creationTime=Jul 16, 2013 12:16:24 AM, lifetime=3600000)
2013-07-16 00:25:27,979 [Blocking-Work-Executor-7259] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=50)
2013-07-16 00:25:27,969 [Blocking-Work-Executor-7259] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=50, creationTime=Jul 16, 2013 12:25:27 AM, lifetime=3600000)
2013-07-16 00:25:27,947 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55623 connected
2013-07-16 00:16:24,345 [Blocking-Work-Executor-7085] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=49)
2013-07-16 00:16:24,334 [Blocking-Work-Executor-7085] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=49, creationTime=Jul 16, 2013 12:16:24 AM, lifetime=3600000)
2013-07-16 00:16:24,313 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55601 connected
2013-07-16 00:16:24,297 [Selector] INFO – Channel closed: Id=48, open channels=0
2013-07-16 00:16:24,297 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=48, creationTime=Jul 16, 2013 12:15:25 AM, lifetime=3600000)
2013-07-16 00:15:25,243 [Selector] INFO – Channel closed: Id=47, open channels=0
2013-07-16 00:15:25,242 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=47, creationTime=Jul 16, 2013 12:12:56 AM, lifetime=3600000)
2013-07-16 00:15:25,085 [Blocking-Work-Executor-7068] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=48)
2013-07-16 00:15:25,076 [Blocking-Work-Executor-7068] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=48, creationTime=Jul 16, 2013 12:15:25 AM, lifetime=3600000)
2013-07-16 00:15:25,054 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55591 connected
2013-07-16 00:12:56,855 [Blocking-Work-Executor-7030] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=47)
2013-07-16 00:12:56,841 [Blocking-Work-Executor-7030] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=47, creationTime=Jul 16, 2013 12:12:56 AM, lifetime=3600000)
2013-07-16 00:12:56,821 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55580 connected
2013-07-16 00:12:56,812 [Selector] INFO – Channel closed: Id=46, open channels=0
2013-07-16 00:12:56,812 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=46, creationTime=Jul 16, 2013 12:11:57 AM, lifetime=3600000)
2013-07-16 00:11:57,205 [Blocking-Work-Executor-7008] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=46)
2013-07-16 00:11:57,194 [Blocking-Work-Executor-7008] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=46, creationTime=Jul 16, 2013 12:11:57 AM, lifetime=3600000)
2013-07-16 00:11:57,171 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55575 connected
2013-07-16 00:11:57,161 [Selector] INFO – Channel closed: Id=45, open channels=0
2013-07-16 00:11:57,161 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=45, creationTime=Jul 16, 2013 12:10:57 AM, lifetime=3600000)
2013-07-16 00:10:57,574 [Blocking-Work-Executor-6990] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=45)
2013-07-16 00:10:57,564 [Blocking-Work-Executor-6990] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=45, creationTime=Jul 16, 2013 12:10:57 AM, lifetime=3600000)
2013-07-16 00:10:57,541 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55567 connected
2013-07-16 00:10:57,513 [Selector] INFO – Channel closed: Id=44, open channels=0
2013-07-16 00:10:57,512 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=44, creationTime=Jul 16, 2013 12:09:53 AM, lifetime=3600000)
2013-07-16 00:10:02,853 [Selector] INFO – Channel closed: Id=43, open channels=0
2013-07-16 00:10:02,853 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=43, creationTime=Jul 16, 2013 12:08:55 AM, lifetime=3600000)
2013-07-16 00:09:53,102 [Blocking-Work-Executor-6972] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=44)
2013-07-16 00:09:53,088 [Blocking-Work-Executor-6972] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=44, creationTime=Jul 16, 2013 12:09:53 AM, lifetime=3600000)
2013-07-16 00:09:53,070 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55561 connected
2013-07-16 00:09:01,705 [Selector] INFO – Channel closed: Id=42, open channels=0
2013-07-16 00:09:01,704 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=42, creationTime=Jul 15, 2013 11:55:56 PM, lifetime=3600000)
2013-07-16 00:08:55,225 [Blocking-Work-Executor-6957] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=43)
2013-07-16 00:08:55,215 [Blocking-Work-Executor-6957] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=43, creationTime=Jul 16, 2013 12:08:55 AM, lifetime=3600000)
2013-07-16 00:08:55,196 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55553 connected
2013-07-15 23:59:30,621 [Timer-0] INFO – Time sync result with jasper.skywolf.net: 15 Jul 23:59:19 ntpdate[19865]: adjust time server 50.116.39.180 offset 0.022228 sec; timeout = 15 seconds
2013-07-15 23:59:15,619 [Timer-0] INFO – Starting time sync with jasper.skywolf.net
2013-07-15 23:55:56,072 [Blocking-Work-Executor-6696] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=42)
2013-07-15 23:55:56,055 [Blocking-Work-Executor-6696] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=42, creationTime=Jul 15, 2013 11:55:56 PM, lifetime=3600000)
2013-07-15 23:55:56,035 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55515 connected
2013-07-15 23:55:56,026 [Selector] INFO – Channel closed: Id=41, open channels=0
2013-07-15 23:55:56,026 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=41, creationTime=Jul 15, 2013 11:51:52 PM, lifetime=3600000)
2013-07-15 23:51:59,811 [Selector] INFO – Channel closed: Id=40, open channels=0
2013-07-15 23:51:59,811 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=40, creationTime=Jul 15, 2013 11:49:49 PM, lifetime=3600000)
2013-07-15 23:51:52,539 [Blocking-Work-Executor-6630] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=41)
2013-07-15 23:51:52,525 [Blocking-Work-Executor-6630] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=41, creationTime=Jul 15, 2013 11:51:52 PM, lifetime=3600000)
2013-07-15 23:51:52,507 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55495 connected
2013-07-15 23:49:49,789 [Blocking-Work-Executor-6595] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=40)
2013-07-15 23:49:49,774 [Blocking-Work-Executor-6595] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=40, creationTime=Jul 15, 2013 11:49:49 PM, lifetime=3600000)
2013-07-15 23:49:49,733 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55490 connected
2013-07-15 23:49:49,704 [Selector] INFO – Channel closed: Id=39, open channels=0
2013-07-15 23:49:49,704 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=39, creationTime=Jul 15, 2013 11:48:45 PM, lifetime=3600000)
2013-07-15 23:48:45,320 [Blocking-Work-Executor-6576] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=39)
2013-07-15 23:48:45,309 [Blocking-Work-Executor-6576] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=39, creationTime=Jul 15, 2013 11:48:45 PM, lifetime=3600000)
2013-07-15 23:48:45,290 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55483 connected
2013-07-15 23:48:45,270 [Selector] INFO – Channel closed: Id=38, open channels=0
2013-07-15 23:48:45,269 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=38, creationTime=Jul 15, 2013 11:47:49 PM, lifetime=3600000)
2013-07-15 23:47:53,623 [Selector] INFO – Channel closed: Id=37, open channels=0
2013-07-15 23:47:53,622 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=37, creationTime=Jul 15, 2013 11:46:54 PM, lifetime=3600000)
2013-07-15 23:47:49,864 [Blocking-Work-Executor-6560] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=38)
2013-07-15 23:47:49,851 [Blocking-Work-Executor-6560] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=38, creationTime=Jul 15, 2013 11:47:49 PM, lifetime=3600000)
2013-07-15 23:47:49,831 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55480 connected
2013-07-15 23:47:19,423 [Selector] INFO – Channel closed: Id=36, open channels=0
2013-07-15 23:47:19,422 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=36, creationTime=Jul 15, 2013 11:36:21 PM, lifetime=3600000)
2013-07-15 23:46:54,752 [Blocking-Work-Executor-6546] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=37)
2013-07-15 23:46:54,741 [Blocking-Work-Executor-6546] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=37, creationTime=Jul 15, 2013 11:46:54 PM, lifetime=3600000)
2013-07-15 23:46:54,724 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55477 connected
2013-07-15 23:36:23,863 [Selector] INFO – Channel closed: Id=35, open channels=0
2013-07-15 23:36:23,862 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=35, creationTime=Jul 15, 2013 11:35:18 PM, lifetime=3600000)
2013-07-15 23:36:21,352 [Blocking-Work-Executor-6372] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=36)
2013-07-15 23:36:21,340 [Blocking-Work-Executor-6372] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=36, creationTime=Jul 15, 2013 11:36:21 PM, lifetime=3600000)
2013-07-15 23:36:21,321 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55444 connected
2013-07-15 23:35:18,820 [Blocking-Work-Executor-6351] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=35)
2013-07-15 23:35:18,813 [Blocking-Work-Executor-6351] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=35, creationTime=Jul 15, 2013 11:35:18 PM, lifetime=3600000)
2013-07-15 23:35:18,796 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55440 connected
2013-07-15 23:35:18,480 [Selector] INFO – Channel closed: Id=34, open channels=0
2013-07-15 23:35:18,478 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=34, creationTime=Jul 15, 2013 11:24:21 PM, lifetime=3600000)
2013-07-15 23:24:21,868 [Blocking-Work-Executor-6153] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=34)
2013-07-15 23:24:21,858 [Blocking-Work-Executor-6153] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=34, creationTime=Jul 15, 2013 11:24:21 PM, lifetime=3600000)
2013-07-15 23:24:21,833 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55418 connected
2013-07-15 23:24:21,824 [Selector] INFO – Channel closed: Id=33, open channels=0
2013-07-15 23:24:21,823 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=33, creationTime=Jul 15, 2013 11:23:22 PM, lifetime=3600000)
2013-07-15 23:23:22,473 [Blocking-Work-Executor-6137] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=33)
2013-07-15 23:23:22,465 [Blocking-Work-Executor-6137] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=33, creationTime=Jul 15, 2013 11:23:22 PM, lifetime=3600000)
2013-07-15 23:23:22,447 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55413 connected
2013-07-15 23:23:22,438 [Selector] INFO – Channel closed: Id=32, open channels=0
2013-07-15 23:23:22,437 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=32, creationTime=Jul 15, 2013 11:22:23 PM, lifetime=3600000)
2013-07-15 23:22:23,092 [Blocking-Work-Executor-6121] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=32)
2013-07-15 23:22:23,082 [Blocking-Work-Executor-6121] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=32, creationTime=Jul 15, 2013 11:22:23 PM, lifetime=3600000)
2013-07-15 23:22:23,066 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55408 connected
2013-07-15 23:22:23,059 [Selector] INFO – Channel closed: Id=31, open channels=0
2013-07-15 23:22:23,057 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=31, creationTime=Jul 15, 2013 11:21:18 PM, lifetime=3600000)
2013-07-15 23:21:18,687 [Blocking-Work-Executor-6104] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=31)
2013-07-15 23:21:18,675 [Blocking-Work-Executor-6104] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=31, creationTime=Jul 15, 2013 11:21:18 PM, lifetime=3600000)
2013-07-15 23:21:18,659 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55407 connected
2013-07-15 23:21:18,650 [Selector] INFO – Channel closed: Id=30, open channels=0
2013-07-15 23:21:18,649 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=30, creationTime=Jul 15, 2013 11:20:19 PM, lifetime=3600000)
2013-07-15 23:20:19,492 [Blocking-Work-Executor-6089] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=30)
2013-07-15 23:20:19,484 [Blocking-Work-Executor-6089] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=30, creationTime=Jul 15, 2013 11:20:19 PM, lifetime=3600000)
2013-07-15 23:20:19,467 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55402 connected
2013-07-15 23:20:19,451 [Selector] INFO – Channel closed: Id=29, open channels=0
2013-07-15 23:20:19,450 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=29, creationTime=Jul 15, 2013 11:19:15 PM, lifetime=3600000)
2013-07-15 23:19:15,283 [Blocking-Work-Executor-6071] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=29)
2013-07-15 23:19:15,274 [Blocking-Work-Executor-6071] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=29, creationTime=Jul 15, 2013 11:19:15 PM, lifetime=3600000)
2013-07-15 23:19:15,258 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55398 connected
2013-07-15 23:19:15,242 [Selector] INFO – Channel closed: Id=28, open channels=0
2013-07-15 23:19:15,241 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=28, creationTime=Jul 15, 2013 11:18:15 PM, lifetime=3600000)
2013-07-15 23:18:15,900 [Blocking-Work-Executor-6055] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=28)
2013-07-15 23:18:15,892 [Blocking-Work-Executor-6055] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=28, creationTime=Jul 15, 2013 11:18:15 PM, lifetime=3600000)
2013-07-15 23:18:15,876 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55395 connected
2013-07-15 23:18:15,851 [Selector] INFO – Channel closed: Id=27, open channels=0
2013-07-15 23:18:15,850 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=27, creationTime=Jul 15, 2013 11:10:43 PM, lifetime=3600000)
2013-07-15 23:10:43,538 [Blocking-Work-Executor-5926] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=27)
2013-07-15 23:10:43,530 [Blocking-Work-Executor-5926] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=27, creationTime=Jul 15, 2013 11:10:43 PM, lifetime=3600000)
2013-07-15 23:10:43,513 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55364 connected
2013-07-15 23:10:43,502 [Selector] INFO – Channel closed: Id=26, open channels=0
2013-07-15 23:10:43,500 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=26, creationTime=Jul 15, 2013 11:05:19 PM, lifetime=3600000)
2013-07-15 23:05:19,013 [Blocking-Work-Executor-5826] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=26)
2013-07-15 23:05:19,006 [Blocking-Work-Executor-5826] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=26, creationTime=Jul 15, 2013 11:05:19 PM, lifetime=3600000)
2013-07-15 23:05:18,990 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55352 connected
2013-07-15 23:05:18,979 [Selector] INFO – Channel closed: Id=25, open channels=0
2013-07-15 23:05:18,979 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=25, creationTime=Jul 15, 2013 11:03:17 PM, lifetime=3600000)
2013-07-15 23:03:17,177 [Blocking-Work-Executor-5793] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=25)
2013-07-15 23:03:17,169 [Blocking-Work-Executor-5793] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=25, creationTime=Jul 15, 2013 11:03:17 PM, lifetime=3600000)
2013-07-15 23:03:17,151 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55348 connected
2013-07-15 23:03:17,142 [Selector] INFO – Channel closed: Id=24, open channels=0
2013-07-15 23:03:17,141 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=24, creationTime=Jul 15, 2013 11:02:17 PM, lifetime=3600000)
2013-07-15 23:02:17,815 [Blocking-Work-Executor-5778] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=24)
2013-07-15 23:02:17,807 [Blocking-Work-Executor-5778] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=24, creationTime=Jul 15, 2013 11:02:17 PM, lifetime=3600000)
2013-07-15 23:02:17,792 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55343 connected
2013-07-15 23:02:17,781 [Selector] INFO – Channel closed: Id=23, open channels=0
2013-07-15 23:02:17,781 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=23, creationTime=Jul 15, 2013 11:01:18 PM, lifetime=3600000)
2013-07-15 23:01:18,470 [Blocking-Work-Executor-5762] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=23)
2013-07-15 23:01:18,462 [Blocking-Work-Executor-5762] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=23, creationTime=Jul 15, 2013 11:01:18 PM, lifetime=3600000)
2013-07-15 23:01:18,448 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55342 connected
2013-07-15 23:01:18,439 [Selector] INFO – Channel closed: Id=22, open channels=0
2013-07-15 23:01:18,438 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=22, creationTime=Jul 15, 2013 11:00:14 PM, lifetime=3600000)
2013-07-15 23:00:14,091 [Blocking-Work-Executor-5745] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=22)
2013-07-15 23:00:14,082 [Blocking-Work-Executor-5745] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=22, creationTime=Jul 15, 2013 11:00:14 PM, lifetime=3600000)
2013-07-15 23:00:14,067 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55339 connected
2013-07-15 23:00:14,057 [Selector] INFO – Channel closed: Id=21, open channels=0
2013-07-15 23:00:14,057 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=21, creationTime=Jul 15, 2013 10:59:14 PM, lifetime=3600000)
2013-07-15 22:59:30,622 [Timer-0] INFO – Time sync result with jasper.skywolf.net: 15 Jul 22:59:19 ntpdate[17323]: adjust time server 50.116.39.180 offset 0.008357 sec; timeout = 15 seconds
2013-07-15 22:59:15,619 [Timer-0] INFO – Starting time sync with jasper.skywolf.net
2013-07-15 22:59:14,690 [Blocking-Work-Executor-5729] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=21)
2013-07-15 22:59:14,682 [Blocking-Work-Executor-5729] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=21, creationTime=Jul 15, 2013 10:59:14 PM, lifetime=3600000)
2013-07-15 22:59:14,668 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55335 connected
2013-07-15 22:59:14,647 [Selector] INFO – Channel closed: Id=20, open channels=0
2013-07-15 22:59:14,647 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=20, creationTime=Jul 15, 2013 10:58:15 PM, lifetime=3600000)
2013-07-15 22:58:15,310 [Blocking-Work-Executor-5713] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=20)
2013-07-15 22:58:15,296 [Blocking-Work-Executor-5713] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=20, creationTime=Jul 15, 2013 10:58:15 PM, lifetime=3600000)
2013-07-15 22:58:15,270 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55317 connected
2013-07-15 22:58:15,244 [Selector] INFO – Channel closed: Id=19, open channels=0
2013-07-15 22:58:15,244 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=19, creationTime=Jul 15, 2013 10:57:15 PM, lifetime=3600000)
2013-07-15 22:57:15,914 [Blocking-Work-Executor-5698] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=19)
2013-07-15 22:57:15,907 [Blocking-Work-Executor-5698] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=19, creationTime=Jul 15, 2013 10:57:15 PM, lifetime=3600000)
2013-07-15 22:57:15,892 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55315 connected
2013-07-15 22:57:15,882 [Selector] INFO – Channel closed: Id=18, open channels=0
2013-07-15 22:57:15,881 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=18, creationTime=Jul 15, 2013 10:56:11 PM, lifetime=3600000)
2013-07-15 22:56:11,532 [Blocking-Work-Executor-5682] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=18)
2013-07-15 22:56:11,525 [Blocking-Work-Executor-5682] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=18, creationTime=Jul 15, 2013 10:56:11 PM, lifetime=3600000)
2013-07-15 22:56:11,508 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55311 connected
2013-07-15 22:56:11,492 [Selector] INFO – Channel closed: Id=17, open channels=0
2013-07-15 22:56:11,491 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=17, creationTime=Jul 15, 2013 10:55:11 PM, lifetime=3600000)
2013-07-15 22:55:11,967 [Blocking-Work-Executor-5668] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=17)
2013-07-15 22:55:11,960 [Blocking-Work-Executor-5668] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=17, creationTime=Jul 15, 2013 10:55:11 PM, lifetime=3600000)
2013-07-15 22:55:11,940 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55296 connected
2013-07-15 22:55:11,933 [Selector] INFO – Channel closed: Id=16, open channels=0
2013-07-15 22:55:11,933 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=16, creationTime=Jul 15, 2013 10:54:12 PM, lifetime=3600000)
2013-07-15 22:54:12,781 [Blocking-Work-Executor-5656] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=16)
2013-07-15 22:54:12,774 [Blocking-Work-Executor-5656] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=16, creationTime=Jul 15, 2013 10:54:12 PM, lifetime=3600000)
2013-07-15 22:54:12,754 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55294 connected
2013-07-15 22:54:12,748 [Selector] INFO – Channel closed: Id=15, open channels=0
2013-07-15 22:54:12,748 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=15, creationTime=Jul 15, 2013 10:53:08 PM, lifetime=3600000)
2013-07-15 22:53:08,590 [Blocking-Work-Executor-5642] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=15)
2013-07-15 22:53:08,579 [Blocking-Work-Executor-5642] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=15, creationTime=Jul 15, 2013 10:53:08 PM, lifetime=3600000)
2013-07-15 22:53:08,565 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55290 connected
2013-07-15 22:53:08,552 [Selector] INFO – Channel closed: Id=14, open channels=0
2013-07-15 22:53:08,551 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=14, creationTime=Jul 15, 2013 10:52:10 PM, lifetime=3600000)
2013-07-15 22:52:20,193 [Selector] INFO – Channel closed: Id=13, open channels=0
2013-07-15 22:52:20,193 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=13, creationTime=Jul 15, 2013 10:51:08 PM, lifetime=3600000)
2013-07-15 22:52:10,849 [Blocking-Work-Executor-5630] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=14)
2013-07-15 22:52:10,841 [Blocking-Work-Executor-5630] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=14, creationTime=Jul 15, 2013 10:52:10 PM, lifetime=3600000)
2013-07-15 22:52:10,827 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55285 connected
2013-07-15 22:51:08,674 [Blocking-Work-Executor-5617] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=13)
2013-07-15 22:51:08,667 [Blocking-Work-Executor-5617] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=13, creationTime=Jul 15, 2013 10:51:08 PM, lifetime=3600000)
2013-07-15 22:51:08,653 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55284 connected
2013-07-15 22:51:08,638 [Selector] INFO – Channel closed: Id=12, open channels=0
2013-07-15 22:51:08,638 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=12, creationTime=Jul 15, 2013 10:50:09 PM, lifetime=3600000)
2013-07-15 22:50:09,359 [Blocking-Work-Executor-5604] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=12)
2013-07-15 22:50:09,352 [Blocking-Work-Executor-5604] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=12, creationTime=Jul 15, 2013 10:50:09 PM, lifetime=3600000)
2013-07-15 22:50:09,338 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55282 connected
2013-07-15 22:50:09,329 [Selector] INFO – Channel closed: Id=11, open channels=0
2013-07-15 22:50:09,329 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=11, creationTime=Jul 15, 2013 10:49:10 PM, lifetime=3600000)
2013-07-15 22:49:21,073 [Selector] INFO – Channel closed: Id=10, open channels=0
2013-07-15 22:49:21,073 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=10, creationTime=Jul 15, 2013 10:47:09 PM, lifetime=3600000)
2013-07-15 22:49:10,027 [Blocking-Work-Executor-5590] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=11)
2013-07-15 22:49:10,022 [Blocking-Work-Executor-5590] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=11, creationTime=Jul 15, 2013 10:49:10 PM, lifetime=3600000)
2013-07-15 22:49:10,008 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55277 connected
2013-07-15 22:47:23,323 [Selector] INFO – Channel closed: Id=9, open channels=0
2013-07-15 22:47:23,322 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=9, creationTime=Jul 15, 2013 10:46:09 PM, lifetime=3600000)
2013-07-15 22:47:09,534 [Blocking-Work-Executor-5564] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=10)
2013-07-15 22:47:09,528 [Blocking-Work-Executor-5564] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=10, creationTime=Jul 15, 2013 10:47:09 PM, lifetime=3600000)
2013-07-15 22:47:09,510 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55262 connected
2013-07-15 22:46:22,122 [Selector] INFO – Channel closed: Id=8, open channels=0
2013-07-15 22:46:22,121 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=8, creationTime=Jul 15, 2013 10:45:09 PM, lifetime=3600000)
2013-07-15 22:46:09,527 [Blocking-Work-Executor-5552] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=9)
2013-07-15 22:46:09,521 [Blocking-Work-Executor-5552] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=9, creationTime=Jul 15, 2013 10:46:09 PM, lifetime=3600000)
2013-07-15 22:46:09,508 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55258 connected
2013-07-15 22:45:16,893 [Selector] INFO – Channel closed: Id=7, open channels=0
2013-07-15 22:45:16,892 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=7, creationTime=Jul 15, 2013 10:44:09 PM, lifetime=3600000)
2013-07-15 22:45:09,251 [Blocking-Work-Executor-5540] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=8)
2013-07-15 22:45:09,245 [Blocking-Work-Executor-5540] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=8, creationTime=Jul 15, 2013 10:45:09 PM, lifetime=3600000)
2013-07-15 22:45:09,231 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55252 connected
2013-07-15 22:44:17,104 [Selector] INFO – Channel closed: Id=6, open channels=0
2013-07-15 22:44:17,103 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=6, creationTime=Jul 15, 2013 10:43:12 PM, lifetime=3600000)
2013-07-15 22:44:09,109 [Blocking-Work-Executor-5526] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=7)
2013-07-15 22:44:09,103 [Blocking-Work-Executor-5526] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=7, creationTime=Jul 15, 2013 10:44:09 PM, lifetime=3600000)
2013-07-15 22:44:09,089 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55239 connected
2013-07-15 22:43:16,673 [Selector] INFO – Channel closed: Id=5, open channels=0
2013-07-15 22:43:16,672 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=5, creationTime=Jul 15, 2013 10:42:09 PM, lifetime=3600000)
2013-07-15 22:43:12,324 [Blocking-Work-Executor-5515] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=6)
2013-07-15 22:43:12,318 [Blocking-Work-Executor-5515] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=6, creationTime=Jul 15, 2013 10:43:12 PM, lifetime=3600000)
2013-07-15 22:43:12,304 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55227 connected
2013-07-15 22:42:15,752 [Selector] INFO – Channel closed: Id=4, open channels=0
2013-07-15 22:42:15,751 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=4, creationTime=Jul 15, 2013 10:41:10 PM, lifetime=3600000)
2013-07-15 22:42:09,027 [Blocking-Work-Executor-5503] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=5)
2013-07-15 22:42:09,021 [Blocking-Work-Executor-5503] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=5, creationTime=Jul 15, 2013 10:42:09 PM, lifetime=3600000)
2013-07-15 22:42:09,008 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55222 connected
2013-07-15 22:41:16,863 [Selector] INFO – Channel closed: Id=3, open channels=0
2013-07-15 22:41:16,862 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=2, secureChannelId=3, creationTime=Jul 15, 2013 10:02:24 PM, lifetime=3600000)
2013-07-15 22:41:10,762 [Blocking-Work-Executor-5492] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=4)
2013-07-15 22:41:10,757 [Blocking-Work-Executor-5492] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=4, creationTime=Jul 15, 2013 10:41:10 PM, lifetime=3600000)
2013-07-15 22:41:10,744 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:55214 connected
2013-07-15 22:02:24,709 [Blocking-Work-Executor-5034] INFO – SecureChannel renewed; SecurityToken(Id=2, secureChannelId=3, creationTime=Jul 15, 2013 10:02:24 PM, lifetime=3600000)
2013-07-15 21:59:30,622 [Timer-0] INFO – Time sync result with jasper.skywolf.net: 15 Jul 21:59:19 ntpdate[15043]: adjust time server 50.116.39.180 offset 0.025373 sec; timeout = 15 seconds
2013-07-15 21:59:15,620 [Timer-0] INFO – Starting time sync with jasper.skywolf.net
2013-07-15 21:17:25,212 [Selector] INFO – Channel closed: Id=2, open channels=0
2013-07-15 21:17:25,211 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=1, secureChannelId=2, creationTime=Jul 15, 2013 9:15:24 PM, lifetime=3600000)
2013-07-15 21:17:24,660 [Blocking-Work-Executor-4491] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=3)
2013-07-15 21:17:24,654 [Blocking-Work-Executor-4491] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=3, creationTime=Jul 15, 2013 9:17:24 PM, lifetime=3600000)
2013-07-15 21:17:24,641 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:54983 connected
2013-07-15 21:15:30,619 [Selector] INFO – Channel closed: Id=1, open channels=0
2013-07-15 21:15:30,617 [Selector] INFO – Secure Channel closed, token=SecurityToken(Id=6, secureChannelId=1, creationTime=Jul 15, 2013 8:54:08 PM, lifetime=3600000)
2013-07-15 21:15:24,464 [Blocking-Work-Executor-4458] INFO – Session activated: , SecureChannel=SecureChannel (state=Open, id=2)
2013-07-15 21:15:24,458 [Blocking-Work-Executor-4458] INFO – SecureChannel opened; SecurityToken(Id=1, secureChannelId=2, creationTime=Jul 15, 2013 9:15:24 PM, lifetime=3600000)
2013-07-15 21:15:24,445 [Non-Blocking-Work-Executor-1] INFO – UATcpServer(/192.168.1.222:52520): /192.168.1.152:54972 connected