2011-04-22 09:06:14,538 INFO [Listener]: Received connection from: /192.168.1.111:49861 2011-04-22 09:06:14,539 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:49861 2011-04-22 09:06:14,543 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:49861 2011-04-22 09:06:14,544 INFO [Listener]: Received connection from: /192.168.1.111:49863 2011-04-22 09:06:14,544 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:49863 2011-04-22 09:06:14,547 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:06:14,547 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:49863 2011-04-22 09:06:22,547 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:06:31,167 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:06:13.748] 2011-04-22 09:06:31,169 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:06:35,745 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:06:43,745 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:06:43,755 INFO [TaskQueue]: Assigned task 50092 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:06:43 MDT 2011] 2011-04-22 09:06:43,755 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:06:43,963 INFO [Listener]: Received connection from: /192.168.1.111:49899 2011-04-22 09:06:44,519 ERROR [Handler]: IOError: 192.168.1.111:49899 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:06:44,522 INFO [Listener]: Received connection from: /192.168.1.111:49904 2011-04-22 09:06:44,523 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:49904 2011-04-22 09:06:44,527 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:49904 2011-04-22 09:06:44,528 INFO [Listener]: Received connection from: /192.168.1.111:49906 2011-04-22 09:06:44,528 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:49906 2011-04-22 09:06:44,531 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:06:44,532 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:49906 2011-04-22 09:06:52,532 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:07:05,746 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:07:13,746 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:07:13,753 INFO [TaskQueue]: Assigned task 50092 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:07:13 MDT 2011] 2011-04-22 09:07:13,753 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:07:13,980 INFO [Listener]: Received connection from: /192.168.1.111:49941 2011-04-22 09:07:14,535 ERROR [Handler]: IOError: 192.168.1.111:49941 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:07:14,539 INFO [Listener]: Received connection from: /192.168.1.111:49949 2011-04-22 09:07:14,539 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:49949 2011-04-22 09:07:14,544 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:49949 2011-04-22 09:07:14,544 INFO [Listener]: Received connection from: /192.168.1.111:49951 2011-04-22 09:07:14,545 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:49951 2011-04-22 09:07:14,548 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:07:14,548 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:49951 2011-04-22 09:07:22,548 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:07:35,746 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:07:43,747 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:07:43,754 INFO [TaskQueue]: Assigned task 50092 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:07:43 MDT 2011] 2011-04-22 09:07:43,754 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:07:43,962 INFO [Listener]: Received connection from: /192.168.1.111:50004 2011-04-22 09:07:44,513 ERROR [Handler]: IOError: 192.168.1.111:50004 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:07:44,517 INFO [Listener]: Received connection from: /192.168.1.111:50008 2011-04-22 09:07:44,517 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:50008 2011-04-22 09:07:44,523 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50008 2011-04-22 09:07:44,523 INFO [Listener]: Received connection from: /192.168.1.111:50010 2011-04-22 09:07:44,523 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:50010 2011-04-22 09:07:44,527 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:07:44,527 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50010 2011-04-22 09:07:52,527 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:08:05,746 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:08:13,746 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:08:13,753 INFO [TaskQueue]: Assigned task 50092 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:08:13 MDT 2011] 2011-04-22 09:08:13,753 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:08:13,975 INFO [Listener]: Received connection from: /192.168.1.111:50072 2011-04-22 09:08:14,529 ERROR [Handler]: IOError: 192.168.1.111:50072 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:08:14,533 INFO [Listener]: Received connection from: /192.168.1.111:50075 2011-04-22 09:08:14,533 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:50075 2011-04-22 09:08:14,539 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50075 2011-04-22 09:08:14,540 INFO [Listener]: Received connection from: /192.168.1.111:50077 2011-04-22 09:08:14,540 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:50077 2011-04-22 09:08:14,544 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:08:14,544 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50077 2011-04-22 09:08:22,545 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:08:31,167 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:08:13.748] 2011-04-22 09:08:31,169 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:08:35,747 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:08:43,747 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:08:43,757 INFO [TaskQueue]: Assigned task 50092 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:08:43 MDT 2011] 2011-04-22 09:08:43,757 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:08:43,963 INFO [Listener]: Received connection from: /192.168.1.111:50131 2011-04-22 09:08:44,517 ERROR [Handler]: IOError: 192.168.1.111:50131 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:08:44,520 INFO [Listener]: Received connection from: /192.168.1.111:50136 2011-04-22 09:08:44,521 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:50136 2011-04-22 09:08:44,527 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50136 2011-04-22 09:08:44,527 INFO [Listener]: Received connection from: /192.168.1.111:50138 2011-04-22 09:08:44,527 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:50138 2011-04-22 09:08:44,531 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:08:44,531 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50138 2011-04-22 09:08:52,532 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:09:05,747 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:09:13,748 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:09:13,755 INFO [TaskQueue]: Assigned task 50092 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:09:13 MDT 2011] 2011-04-22 09:09:13,755 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:09:13,971 INFO [Listener]: Received connection from: /192.168.1.111:50203 2011-04-22 09:09:14,527 ERROR [Handler]: IOError: 192.168.1.111:50203 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:09:14,530 INFO [Listener]: Received connection from: /192.168.1.111:50206 2011-04-22 09:09:14,531 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:50206 2011-04-22 09:09:14,537 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50206 2011-04-22 09:09:14,538 INFO [Listener]: Received connection from: /192.168.1.111:50208 2011-04-22 09:09:14,538 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:50208 2011-04-22 09:09:14,542 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:09:14,542 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50208 2011-04-22 09:09:22,542 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:09:35,748 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:09:43,749 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:09:43,756 INFO [TaskQueue]: Assigned task 50092 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:09:43 MDT 2011] 2011-04-22 09:09:43,756 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:09:43,979 INFO [Listener]: Received connection from: /192.168.1.111:50251 2011-04-22 09:09:44,533 ERROR [Handler]: IOError: 192.168.1.111:50251 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:09:44,537 INFO [Listener]: Received connection from: /192.168.1.111:50254 2011-04-22 09:09:44,537 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:50254 2011-04-22 09:09:44,544 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50254 2011-04-22 09:09:44,544 INFO [Listener]: Received connection from: /192.168.1.111:50256 2011-04-22 09:09:44,544 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:50256 2011-04-22 09:09:44,548 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:09:44,548 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50256 2011-04-22 09:09:52,548 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:10:05,748 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:10:13,748 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:10:13,755 INFO [TaskQueue]: Assigned task 50092 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:10:13 MDT 2011] 2011-04-22 09:10:13,755 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:10:13,975 INFO [Listener]: Received connection from: /192.168.1.111:50306 2011-04-22 09:10:14,534 ERROR [Handler]: IOError: 192.168.1.111:50306 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:10:14,539 INFO [Listener]: Received connection from: /192.168.1.111:50315 2011-04-22 09:10:14,539 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:50315 2011-04-22 09:10:14,545 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50315 2011-04-22 09:10:14,546 INFO [Listener]: Received connection from: /192.168.1.111:50317 2011-04-22 09:10:14,546 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:50317 2011-04-22 09:10:14,549 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:10:14,550 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50317 2011-04-22 09:10:22,550 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:10:31,167 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:10:13.75] 2011-04-22 09:10:31,169 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:10:35,749 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:10:43,749 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:10:43,759 INFO [TaskQueue]: Assigned task 50092 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:10:43 MDT 2011] 2011-04-22 09:10:43,759 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:10:43,978 INFO [Listener]: Received connection from: /192.168.1.111:50414 2011-04-22 09:10:44,533 ERROR [Handler]: IOError: 192.168.1.111:50414 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:10:44,536 INFO [Listener]: Received connection from: /192.168.1.111:50417 2011-04-22 09:10:44,537 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:50417 2011-04-22 09:10:44,543 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50417 2011-04-22 09:10:44,543 INFO [Listener]: Received connection from: /192.168.1.111:50419 2011-04-22 09:10:44,544 INFO [Handler]: CMD: GETARGS :: PEER: /192.168.1.111:50419 2011-04-22 09:10:44,544 WARN [TaskQueue]: Returning 'null' for 50092 2011-04-22 09:10:44,544 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50419 2011-04-22 09:10:44,734 INFO [Listener]: Received connection from: /192.168.1.111:50422 2011-04-22 09:10:45,074 ERROR [Handler]: IOError: 192.168.1.111:50422 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:10:45,077 INFO [Listener]: Received connection from: /192.168.1.111:50424 2011-04-22 09:10:45,077 INFO [Handler]: CMD: LOGEXE :: PEER: /192.168.1.111:50424 2011-04-22 09:10:45,081 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50424 2011-04-22 09:10:45,082 INFO [Listener]: Received connection from: /192.168.1.111:50426 2011-04-22 09:10:45,082 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:50426 2011-04-22 09:10:45,085 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:10:45,085 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50426 2011-04-22 09:10:53,086 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:11:05,749 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:11:09,466 INFO [Handler]: CMD: RMTASK :: PEER: /127.0.0.1:49380 2011-04-22 09:11:13,750 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:11:35,750 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:11:43,750 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:12:05,750 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:12:13,750 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:12:16,828 INFO [Listener]: Received connection from: /127.0.0.1:50617 2011-04-22 09:12:19,847 INFO [Handler]: CMD: ADDTASK :: PEER: /127.0.0.1:50617 2011-04-22 09:12:19,856 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:12:19,886 INFO [Handler]: CMD: QUIT :: PEER: /127.0.0.1:50617 2011-04-22 09:12:27,857 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:12:27,864 INFO [TaskQueue]: Assigned task 50094 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:12:27 MDT 2011] 2011-04-22 09:12:27,864 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:12:28,090 INFO [Listener]: Received connection from: /192.168.1.111:50639 2011-04-22 09:12:28,644 ERROR [Handler]: IOError: 192.168.1.111:50639 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:12:28,648 INFO [Listener]: Received connection from: /192.168.1.111:50642 2011-04-22 09:12:28,649 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:50642 2011-04-22 09:12:28,652 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50642 2011-04-22 09:12:28,653 INFO [Listener]: Received connection from: /192.168.1.111:50644 2011-04-22 09:12:28,653 INFO [Handler]: CMD: GETARGS :: PEER: /192.168.1.111:50644 2011-04-22 09:12:28,653 WARN [TaskQueue]: Returning 'null' for 50094 2011-04-22 09:12:28,653 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50644 2011-04-22 09:12:28,843 INFO [Listener]: Received connection from: /192.168.1.111:50647 2011-04-22 09:12:29,187 ERROR [Handler]: IOError: 192.168.1.111:50647 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:12:29,190 INFO [Listener]: Received connection from: /192.168.1.111:50649 2011-04-22 09:12:29,190 INFO [Handler]: CMD: LOGEXE :: PEER: /192.168.1.111:50649 2011-04-22 09:12:29,194 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50649 2011-04-22 09:12:29,195 INFO [Listener]: Received connection from: /192.168.1.111:50651 2011-04-22 09:12:29,195 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:50651 2011-04-22 09:12:29,198 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:12:29,198 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50651 2011-04-22 09:12:31,167 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:12:27.859] 2011-04-22 09:12:31,169 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:12:35,750 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:12:37,198 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:12:43,751 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:12:52,632 INFO [Listener]: Received connection from: /127.0.0.1:50678 2011-04-22 09:13:05,750 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:13:12,650 INFO [DataStore]: Cleaned up 0 row(s) from task queue! 2011-04-22 09:13:13,751 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:13:35,750 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:13:43,127 INFO [Listener]: Received connection from: /127.0.0.1:50729 2011-04-22 09:13:43,750 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:13:53,157 INFO [Handler]: CMD: QUIT :: PEER: /127.0.0.1:50729 2011-04-22 09:14:01,156 INFO [Listener]: Received connection from: /127.0.0.1:50752 2011-04-22 09:14:05,751 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:14:10,512 INFO [Handler]: CMD: ADDTASK :: PEER: /127.0.0.1:50752 2011-04-22 09:14:10,520 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:14:10,540 INFO [Handler]: CMD: QUIT :: PEER: /127.0.0.1:50752 2011-04-22 09:14:13,751 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:14:13,759 INFO [TaskQueue]: Assigned task 50095 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:14:13 MDT 2011] 2011-04-22 09:14:13,760 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:14:13,981 INFO [Listener]: Received connection from: /192.168.1.111:50770 2011-04-22 09:14:14,537 ERROR [Handler]: IOError: 192.168.1.111:50770 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:14:14,541 INFO [Listener]: Received connection from: /192.168.1.111:50772 2011-04-22 09:14:14,541 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:50772 2011-04-22 09:14:14,545 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50772 2011-04-22 09:14:14,545 INFO [Listener]: Received connection from: /192.168.1.111:50774 2011-04-22 09:14:14,546 INFO [Handler]: CMD: GETARGS :: PEER: /192.168.1.111:50774 2011-04-22 09:14:14,546 WARN [TaskQueue]: Returning 'null' for 50095 2011-04-22 09:14:14,546 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50774 2011-04-22 09:14:14,736 INFO [Listener]: Received connection from: /192.168.1.111:50777 2011-04-22 09:14:15,075 ERROR [Handler]: IOError: 192.168.1.111:50777 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:14:15,078 INFO [Listener]: Received connection from: /192.168.1.111:50779 2011-04-22 09:14:15,079 INFO [Handler]: CMD: LOGEXE :: PEER: /192.168.1.111:50779 2011-04-22 09:14:15,083 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50779 2011-04-22 09:14:15,083 INFO [Listener]: Received connection from: /192.168.1.111:50781 2011-04-22 09:14:15,083 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:50781 2011-04-22 09:14:15,087 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:14:15,101 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50781 2011-04-22 09:14:18,365 INFO [Listener]: Received connection from: /127.0.0.1:50785 2011-04-22 09:14:23,102 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:14:31,167 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:14:13.753] 2011-04-22 09:14:31,169 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:14:35,751 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:14:43,752 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:15:00,010 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:15:00,010 INFO [CronTaskAdder]: Task 50096 of type 'MEDIAFILESCANNER' added to queue via crontab! 2011-04-22 09:15:03,519 ERROR [Handler]: IOError: 127.0.0.1:50678 java.io.EOFException at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:15:03,520 ERROR [Handler]: IOError: 127.0.0.1:49380 java.io.EOFException at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:15:05,751 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:15:08,010 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:15:08,018 INFO [TaskQueue]: Assigned task 50096 of type 'MEDIAFILESCANNER' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:15:08 MDT 2011] 2011-04-22 09:15:08,018 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:15:08,018 INFO [Listener]: Received connection from: /192.168.1.111:50858 2011-04-22 09:15:08,018 INFO [Handler]: CMD: GETARGS :: PEER: /192.168.1.111:50858 2011-04-22 09:15:08,019 WARN [TaskQueue]: Returning 'null' for 50096 2011-04-22 09:15:08,019 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50858 2011-04-22 09:15:08,239 INFO [Listener]: Received connection from: /192.168.1.111:50863 2011-04-22 09:15:08,792 INFO [Listener]: Received connection from: /192.168.1.111:50866 2011-04-22 09:15:13,751 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:15:15,908 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50866 2011-04-22 09:15:15,910 ERROR [Handler]: IOError: 192.168.1.111:50863 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:15:15,914 INFO [Listener]: Received connection from: /192.168.1.111:50875 2011-04-22 09:15:15,914 INFO [Handler]: CMD: LOGEXE :: PEER: /192.168.1.111:50875 2011-04-22 09:15:15,918 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50875 2011-04-22 09:15:15,919 INFO [Listener]: Received connection from: /192.168.1.111:50877 2011-04-22 09:15:15,919 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:50877 2011-04-22 09:15:15,922 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:15:15,922 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50877 2011-04-22 09:15:23,922 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:15:35,752 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:15:43,752 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:16:05,752 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:16:13,753 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:16:31,168 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:15:08.012] 2011-04-22 09:16:31,170 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:16:33,009 INFO [Handler]: CMD: RMTASK :: PEER: /127.0.0.1:50785 2011-04-22 09:16:33,016 INFO [Handler]: CMD: RMTASK :: PEER: /127.0.0.1:50785 2011-04-22 09:16:33,016 INFO [Handler]: CMD: RMTASK :: PEER: /127.0.0.1:50785 2011-04-22 09:16:33,017 INFO [Handler]: CMD: RMTASK :: PEER: /127.0.0.1:50785 2011-04-22 09:16:33,017 INFO [Handler]: CMD: RMTASK :: PEER: /127.0.0.1:50785 2011-04-22 09:16:33,018 INFO [Handler]: CMD: RMTASK :: PEER: /127.0.0.1:50785 2011-04-22 09:16:33,019 INFO [Handler]: CMD: RMTASK :: PEER: /127.0.0.1:50785 2011-04-22 09:16:33,747 INFO [Handler]: CMD: QUIT :: PEER: /127.0.0.1:50785 2011-04-22 09:16:35,752 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:16:42,284 INFO [Listener]: Received connection from: /127.0.0.1:50956 2011-04-22 09:16:43,753 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:16:45,840 INFO [Handler]: CMD: ADDTASK :: PEER: /127.0.0.1:50956 2011-04-22 09:16:45,847 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:16:45,867 INFO [Handler]: CMD: QUIT :: PEER: /127.0.0.1:50956 2011-04-22 09:16:53,847 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:16:53,854 INFO [TaskQueue]: Assigned task 50097 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:16:53 MDT 2011] 2011-04-22 09:16:53,854 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:16:54,073 INFO [Listener]: Received connection from: /192.168.1.111:50977 2011-04-22 09:16:54,627 ERROR [Handler]: IOError: 192.168.1.111:50977 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:16:54,630 INFO [Listener]: Received connection from: /192.168.1.111:50981 2011-04-22 09:16:54,631 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:50981 2011-04-22 09:16:54,634 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50981 2011-04-22 09:16:54,635 INFO [Listener]: Received connection from: /192.168.1.111:50983 2011-04-22 09:16:54,635 INFO [Handler]: CMD: GETARGS :: PEER: /192.168.1.111:50983 2011-04-22 09:16:54,635 WARN [TaskQueue]: Returning 'null' for 50097 2011-04-22 09:16:54,635 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:50983 2011-04-22 09:16:54,826 INFO [Listener]: Received connection from: /192.168.1.111:50986 2011-04-22 09:16:56,160 INFO [Listener]: Received connection from: /127.0.0.1:50989 2011-04-22 09:17:03,357 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:17:03,357 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:17:04,664 INFO [Listener]: Received connection from: /127.0.0.1:50995 2011-04-22 09:17:05,757 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:17:13,760 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:17:35,766 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:17:43,766 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:18:03,376 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:18:03,376 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:18:05,777 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:18:13,778 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:18:31,167 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:16:53.849] 2011-04-22 09:18:31,169 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:18:35,777 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:18:43,782 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:19:03,356 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:19:03,356 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:19:05,777 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:19:13,777 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:19:35,777 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:19:43,778 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:20:03,358 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:20:03,358 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:20:05,777 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:20:13,778 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:20:31,184 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:18:31.169] 2011-04-22 09:20:31,185 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:20:35,790 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:20:43,805 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:21:03,359 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:21:03,359 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:21:05,796 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:21:13,796 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:21:35,811 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:21:43,812 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:22:03,359 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:22:03,359 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:22:05,812 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:22:13,812 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:22:31,184 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:20:31.185] 2011-04-22 09:22:31,185 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:22:35,813 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:22:43,817 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:23:03,359 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:23:03,359 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:23:05,813 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:23:13,814 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:23:35,814 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:23:43,815 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:24:03,362 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:24:03,362 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:24:05,814 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:24:13,832 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:24:31,199 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:22:31.185] 2011-04-22 09:24:31,201 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:24:35,815 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:24:43,815 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:25:03,361 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:25:03,361 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:25:05,815 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:25:13,816 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:25:35,816 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:25:43,820 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:26:03,363 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:26:03,363 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:26:05,816 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:26:13,816 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:26:31,214 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:24:31.201] 2011-04-22 09:26:31,216 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:26:35,816 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:26:43,817 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:27:03,366 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:27:03,366 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:27:05,816 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:27:13,817 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:27:35,817 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:27:43,817 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:28:03,363 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:28:03,363 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:28:05,818 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:28:13,818 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:28:31,213 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:26:31.216] 2011-04-22 09:28:31,215 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:28:35,818 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:28:43,819 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:29:03,365 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:29:03,365 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:29:05,818 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:29:13,831 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:29:35,819 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:29:43,829 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:30:00,010 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:30:00,010 INFO [CronTaskAdder]: Task 50098 of type 'MEDIAFILESCANNER' added to queue via crontab! 2011-04-22 09:30:03,365 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:30:03,365 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:30:05,820 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:30:08,011 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:30:08,018 INFO [TaskQueue]: Assigned task 50098 of type 'MEDIAFILESCANNER' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:30:08 MDT 2011] 2011-04-22 09:30:08,018 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:30:08,018 INFO [Listener]: Received connection from: /192.168.1.111:51715 2011-04-22 09:30:08,018 INFO [Handler]: CMD: GETARGS :: PEER: /192.168.1.111:51715 2011-04-22 09:30:08,019 WARN [TaskQueue]: Returning 'null' for 50098 2011-04-22 09:30:08,019 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:51715 2011-04-22 09:30:08,320 INFO [Listener]: Received connection from: /192.168.1.111:51718 2011-04-22 09:30:09,033 INFO [Listener]: Received connection from: /192.168.1.111:51721 2011-04-22 09:30:13,820 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:30:17,903 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:51721 2011-04-22 09:30:17,907 ERROR [Handler]: IOError: 192.168.1.111:51718 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:30:17,910 INFO [Listener]: Received connection from: /192.168.1.111:51728 2011-04-22 09:30:17,911 INFO [Handler]: CMD: LOGEXE :: PEER: /192.168.1.111:51728 2011-04-22 09:30:17,916 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:51728 2011-04-22 09:30:17,916 INFO [Listener]: Received connection from: /192.168.1.111:51730 2011-04-22 09:30:17,917 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:51730 2011-04-22 09:30:17,921 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:30:17,921 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:51730 2011-04-22 09:30:25,922 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:30:31,215 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:30:08.013] 2011-04-22 09:30:31,217 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:30:35,820 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:30:43,821 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:31:03,367 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:31:03,367 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:31:05,820 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:31:13,828 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:31:35,821 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:31:43,831 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:32:03,367 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:32:03,367 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:32:05,821 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:32:13,822 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:32:31,494 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:30:31.217] 2011-04-22 09:32:31,496 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:32:35,823 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:32:43,823 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:33:03,368 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:33:03,368 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:33:05,823 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:33:12,650 INFO [DataStore]: Cleaned up 0 row(s) from task queue! 2011-04-22 09:33:13,823 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:33:35,823 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:33:43,838 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:34:03,369 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:34:03,369 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:34:05,823 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:34:13,824 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:34:31,498 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:32:31.496] 2011-04-22 09:34:31,500 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:34:35,827 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:34:43,828 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:35:03,368 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:35:03,368 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:35:05,828 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:35:13,828 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:35:35,838 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:35:43,839 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:36:03,385 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:36:03,385 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:36:05,838 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:36:13,839 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:36:31,499 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:34:31.5] 2011-04-22 09:36:31,500 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:36:35,838 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:36:43,838 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:37:03,385 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:37:03,385 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:37:05,838 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:37:13,864 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:37:35,851 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:37:43,852 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:38:03,386 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:38:03,386 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:38:05,851 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:38:13,865 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:38:31,497 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:36:31.5] 2011-04-22 09:38:31,500 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:38:35,852 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:38:43,852 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:39:03,385 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:39:03,385 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:39:05,852 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:39:13,854 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:39:35,853 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:39:43,854 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:40:03,387 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:40:03,387 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:40:05,859 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:40:13,859 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:40:31,519 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:38:31.5] 2011-04-22 09:40:31,521 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:40:35,859 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:40:43,860 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:41:03,387 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:41:03,387 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:41:05,859 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:41:13,860 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:41:35,860 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:41:43,860 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:42:03,404 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:42:03,404 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:42:05,861 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:42:13,861 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:42:31,517 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:40:31.522] 2011-04-22 09:42:31,519 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:42:35,861 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:42:43,862 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:43:03,403 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:43:03,403 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:43:05,861 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:43:13,862 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:43:35,873 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:43:43,873 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:44:03,404 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:44:03,404 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:44:05,874 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:44:13,881 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:44:31,519 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:42:31.519] 2011-04-22 09:44:31,533 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:44:35,874 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:44:43,875 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:45:00,008 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:45:00,008 INFO [CronTaskAdder]: Task 50099 of type 'MEDIAFILESCANNER' added to queue via crontab! 2011-04-22 09:45:03,419 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:45:03,419 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:45:05,874 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:45:08,027 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:45:08,034 INFO [TaskQueue]: Assigned task 50099 of type 'MEDIAFILESCANNER' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 09:45:08 MDT 2011] 2011-04-22 09:45:08,034 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:45:08,034 INFO [Listener]: Received connection from: /192.168.1.111:52937 2011-04-22 09:45:08,034 INFO [Handler]: CMD: GETARGS :: PEER: /192.168.1.111:52937 2011-04-22 09:45:08,035 WARN [TaskQueue]: Returning 'null' for 50099 2011-04-22 09:45:08,035 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:52937 2011-04-22 09:45:08,335 INFO [Listener]: Received connection from: /192.168.1.111:52940 2011-04-22 09:45:09,109 INFO [Listener]: Received connection from: /192.168.1.111:52942 2011-04-22 09:45:13,876 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:45:18,448 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:52942 2011-04-22 09:45:18,451 ERROR [Handler]: IOError: 192.168.1.111:52940 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:45:18,457 INFO [Listener]: Received connection from: /192.168.1.111:52951 2011-04-22 09:45:18,457 INFO [Handler]: CMD: LOGEXE :: PEER: /192.168.1.111:52951 2011-04-22 09:45:18,462 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:52951 2011-04-22 09:45:18,463 INFO [Listener]: Received connection from: /192.168.1.111:52953 2011-04-22 09:45:18,463 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:52953 2011-04-22 09:45:18,470 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:45:18,470 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:52953 2011-04-22 09:45:26,470 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:45:35,875 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:45:43,875 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:46:03,404 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:46:03,404 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:46:05,892 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:46:13,893 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:46:31,519 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:45:08.029] 2011-04-22 09:46:31,520 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:46:35,892 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:46:43,896 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:47:03,405 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:47:03,405 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:47:05,894 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:47:13,895 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:47:35,895 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:47:43,895 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:48:03,406 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:48:03,406 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:48:05,906 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:48:13,907 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:48:31,530 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:46:31.52] 2011-04-22 09:48:31,532 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:48:35,906 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:48:43,906 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:49:03,408 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:49:03,408 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:49:05,906 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:49:13,916 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:49:35,906 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:49:43,913 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:50:03,414 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:50:03,414 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:50:05,906 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:50:13,907 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:50:31,531 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:48:31.532] 2011-04-22 09:50:31,532 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:50:35,912 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:50:43,912 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:51:03,414 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:51:03,414 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:51:05,923 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:51:13,923 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:51:35,923 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:51:43,924 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:52:03,415 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:52:03,415 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:52:05,923 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:52:13,923 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:52:31,531 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:50:31.532] 2011-04-22 09:52:31,535 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:52:35,924 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:52:43,933 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:53:03,415 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:53:03,415 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:53:05,924 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:53:12,653 INFO [DataStore]: Cleaned up 0 row(s) from task queue! 2011-04-22 09:53:13,925 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:53:35,924 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:53:43,931 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:54:03,416 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:54:03,416 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:54:05,924 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:54:13,924 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:54:31,531 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:52:31.535] 2011-04-22 09:54:31,533 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:54:35,925 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:54:43,937 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:55:03,415 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:55:03,416 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:55:05,939 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:55:13,940 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:55:35,948 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:55:43,948 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:56:03,438 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:56:03,438 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:56:05,948 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:56:13,948 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:56:31,532 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:54:31.534] 2011-04-22 09:56:31,533 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:56:35,965 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:56:43,966 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:57:03,455 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:57:03,455 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:57:05,965 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:57:13,966 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:57:35,969 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:57:43,969 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:58:03,454 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:58:03,454 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 09:58:05,970 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:58:13,971 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:58:31,531 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 09:56:31.533] 2011-04-22 09:58:31,534 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 09:58:35,970 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:58:37,600 ERROR [Handler]: IOError: 192.168.1.111:50986 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 09:58:37,614 INFO [Listener]: Received connection from: /192.168.1.111:53709 2011-04-22 09:58:37,614 INFO [Handler]: CMD: LOGEXE :: PEER: /192.168.1.111:53709 2011-04-22 09:58:37,625 ERROR [DataStore]: SQL Error org.h2.jdbc.JdbcSQLException: Connection is broken: "session closed" [90067-145] at org.h2.message.DbException.getJdbcSQLException(DbException.java:327) at org.h2.message.DbException.get(DbException.java:167) at org.h2.message.DbException.get(DbException.java:144) at org.h2.engine.SessionRemote.checkClosed(SessionRemote.java:485) at org.h2.engine.SessionRemote.removeServer(SessionRemote.java:414) at org.h2.command.CommandRemote.prepare(CommandRemote.java:81) at org.h2.command.CommandRemote.(CommandRemote.java:47) at org.h2.engine.SessionRemote.prepareCommand(SessionRemote.java:421) at org.h2.jdbc.JdbcConnection.prepareCommand(JdbcConnection.java:1060) at org.h2.jdbc.JdbcConnection.rollbackInternal(JdbcConnection.java:1381) at org.h2.jdbc.JdbcConnection.close(JdbcConnection.java:315) at com.google.code.sagetvaddons.sjq.server.DataStore.close(DataStore.java:1121) at com.google.code.sagetvaddons.sjq.server.DataStore.finalize(DataStore.java:170) at java.lang.ref.Finalizer.invokeFinalizeMethod(Native Method) at java.lang.ref.Finalizer.runFinalizer(Unknown Source) at java.lang.ref.Finalizer.access$100(Unknown Source) at java.lang.ref.Finalizer$FinalizerThread.run(Unknown Source) 2011-04-22 09:58:37,672 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:53709 2011-04-22 09:58:37,673 INFO [Listener]: Received connection from: /192.168.1.111:53711 2011-04-22 09:58:37,673 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:53711 2011-04-22 09:58:37,677 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:58:37,677 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:53711 2011-04-22 09:58:43,971 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:59:05,970 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:59:13,970 INFO [TaskQueue]: Running queue processor now! 2011-04-22 09:59:35,971 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 09:59:43,971 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:00:00,010 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:00:00,010 INFO [CronTaskAdder]: Task 50100 of type 'MEDIAFILESCANNER' added to queue via crontab! 2011-04-22 10:00:05,971 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:00:08,011 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:00:08,020 INFO [TaskQueue]: Assigned task 50100 of type 'MEDIAFILESCANNER' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 10:00:08 MDT 2011] 2011-04-22 10:00:08,020 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:00:08,020 INFO [Listener]: Received connection from: /192.168.1.111:53801 2011-04-22 10:00:08,020 INFO [Handler]: CMD: GETARGS :: PEER: /192.168.1.111:53801 2011-04-22 10:00:08,021 WARN [TaskQueue]: Returning 'null' for 50100 2011-04-22 10:00:08,021 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:53801 2011-04-22 10:00:08,240 INFO [Listener]: Received connection from: /192.168.1.111:53805 2011-04-22 10:00:08,793 INFO [Listener]: Received connection from: /192.168.1.111:53809 2011-04-22 10:00:13,971 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:00:16,143 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:53809 2011-04-22 10:00:16,146 ERROR [Handler]: IOError: 192.168.1.111:53805 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:00:16,149 INFO [Listener]: Received connection from: /192.168.1.111:53814 2011-04-22 10:00:16,150 INFO [Handler]: CMD: LOGEXE :: PEER: /192.168.1.111:53814 2011-04-22 10:00:16,154 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:53814 2011-04-22 10:00:16,155 INFO [Listener]: Received connection from: /192.168.1.111:53816 2011-04-22 10:00:16,155 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:53816 2011-04-22 10:00:16,158 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:00:16,158 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:53816 2011-04-22 10:00:24,158 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:00:31,534 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:00:08.014] 2011-04-22 10:00:31,536 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:00:35,971 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:00:43,972 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:01:05,971 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:01:13,971 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:01:35,972 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:01:43,972 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:02:05,972 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:02:13,973 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:02:31,536 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:00:31.536] 2011-04-22 10:02:31,538 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:02:35,972 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:02:43,972 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:03:05,972 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:03:13,972 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:03:35,972 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:03:43,973 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:04:05,972 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:04:13,973 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:04:31,536 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:02:31.539] 2011-04-22 10:04:31,538 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:04:35,972 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:04:43,972 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:05:05,973 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:05:13,973 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:05:35,973 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:05:43,974 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:06:05,973 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:06:13,973 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:06:31,536 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:04:31.538] 2011-04-22 10:06:31,538 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:06:35,974 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:06:43,974 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:07:05,974 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:07:13,975 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:07:35,975 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:07:43,976 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:08:05,975 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:08:13,975 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:08:31,536 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:06:31.538] 2011-04-22 10:08:31,538 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:08:35,976 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:08:43,976 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:09:05,976 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:09:13,977 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:09:35,977 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:09:43,977 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:10:05,977 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:10:13,977 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:10:31,537 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:08:31.538] 2011-04-22 10:10:31,539 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:10:35,977 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:10:43,978 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:11:05,977 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:11:13,978 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:11:35,978 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:11:43,978 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:12:05,979 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:12:13,979 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:12:31,536 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:10:31.539] 2011-04-22 10:12:31,538 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:12:35,979 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:12:43,980 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:13:05,979 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:13:12,652 INFO [DataStore]: Cleaned up 0 row(s) from task queue! 2011-04-22 10:13:13,979 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:13:35,980 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:13:43,980 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:14:05,980 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:14:13,981 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:14:31,537 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:12:31.538] 2011-04-22 10:14:31,539 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:14:35,980 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:14:43,981 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:15:00,011 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:15:00,011 INFO [CronTaskAdder]: Task 50101 of type 'MEDIAFILESCANNER' added to queue via crontab! 2011-04-22 10:15:05,980 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:15:08,011 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:15:08,020 INFO [TaskQueue]: Assigned task 50101 of type 'MEDIAFILESCANNER' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 10:15:08 MDT 2011] 2011-04-22 10:15:08,020 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:15:08,020 INFO [Listener]: Received connection from: /192.168.1.111:55018 2011-04-22 10:15:08,020 INFO [Handler]: CMD: GETARGS :: PEER: /192.168.1.111:55018 2011-04-22 10:15:08,020 WARN [TaskQueue]: Returning 'null' for 50101 2011-04-22 10:15:08,021 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:55018 2011-04-22 10:15:08,251 INFO [Listener]: Received connection from: /192.168.1.111:55022 2011-04-22 10:15:08,805 INFO [Listener]: Received connection from: /192.168.1.111:55025 2011-04-22 10:15:13,980 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:15:16,320 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:55025 2011-04-22 10:15:16,323 ERROR [Handler]: IOError: 192.168.1.111:55022 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:15:16,327 INFO [Listener]: Received connection from: /192.168.1.111:55037 2011-04-22 10:15:16,328 INFO [Handler]: CMD: LOGEXE :: PEER: /192.168.1.111:55037 2011-04-22 10:15:16,332 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:55037 2011-04-22 10:15:16,332 INFO [Listener]: Received connection from: /192.168.1.111:55039 2011-04-22 10:15:16,333 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:55039 2011-04-22 10:15:16,336 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:15:16,336 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:55039 2011-04-22 10:15:24,337 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:15:35,981 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:15:43,981 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:16:05,981 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:16:13,982 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:16:31,538 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:15:08.014] 2011-04-22 10:16:31,540 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:16:35,981 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:16:43,982 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:17:05,981 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:17:13,981 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:17:35,981 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:17:43,982 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:18:05,981 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:18:13,982 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:18:31,540 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:16:31.54] 2011-04-22 10:18:31,543 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:18:35,981 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:18:43,981 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:19:05,982 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:19:13,982 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:19:35,982 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:19:43,983 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:20:05,982 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:20:13,983 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:20:31,540 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:18:31.543] 2011-04-22 10:20:31,542 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:20:35,982 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:20:43,982 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:21:05,982 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:21:13,983 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:21:35,983 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:21:43,984 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:22:05,983 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:22:13,983 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:22:31,540 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:20:31.542] 2011-04-22 10:22:31,542 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:22:35,984 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:22:43,984 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:23:05,984 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:23:13,985 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:23:35,985 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:23:43,986 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:24:05,985 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:24:13,985 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:24:31,540 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:22:31.542] 2011-04-22 10:24:31,542 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:24:35,985 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:24:43,986 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:25:05,985 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:25:13,986 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:25:35,986 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:25:43,986 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:26:05,986 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:26:13,986 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:26:31,541 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:24:31.542] 2011-04-22 10:26:31,543 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:26:35,986 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:26:43,987 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:27:05,986 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:27:13,987 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:27:35,987 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:27:43,987 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:28:05,988 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:28:13,988 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:28:31,540 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:26:31.543] 2011-04-22 10:28:31,542 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:28:35,988 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:28:43,989 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:29:05,988 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:29:13,988 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:29:35,989 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:29:43,989 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:30:00,010 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:30:00,010 INFO [CronTaskAdder]: Task 50102 of type 'MEDIAFILESCANNER' added to queue via crontab! 2011-04-22 10:30:05,989 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:30:07,970 INFO [Listener]: Received connection from: /127.0.0.1:55902 2011-04-22 10:30:08,010 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:30:08,019 INFO [TaskQueue]: Assigned task 50102 of type 'MEDIAFILESCANNER' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 10:30:08 MDT 2011] 2011-04-22 10:30:08,019 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:30:08,019 INFO [Listener]: Received connection from: /192.168.1.111:55904 2011-04-22 10:30:08,019 INFO [Handler]: CMD: GETARGS :: PEER: /192.168.1.111:55904 2011-04-22 10:30:08,019 WARN [TaskQueue]: Returning 'null' for 50102 2011-04-22 10:30:08,020 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:55904 2011-04-22 10:30:08,256 INFO [Listener]: Received connection from: /192.168.1.111:55908 2011-04-22 10:30:08,810 INFO [Listener]: Received connection from: /192.168.1.111:55910 2011-04-22 10:30:11,899 INFO [Listener]: Received connection from: /127.0.0.1:55915 2011-04-22 10:30:13,990 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:30:16,209 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:55910 2011-04-22 10:30:16,211 ERROR [Handler]: IOError: 192.168.1.111:55908 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:30:16,215 INFO [Listener]: Received connection from: /192.168.1.111:55921 2011-04-22 10:30:16,215 INFO [Handler]: CMD: LOGEXE :: PEER: /192.168.1.111:55921 2011-04-22 10:30:16,220 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:55921 2011-04-22 10:30:16,220 INFO [Listener]: Received connection from: /192.168.1.111:55923 2011-04-22 10:30:16,220 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:55923 2011-04-22 10:30:16,223 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:30:16,224 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:55923 2011-04-22 10:30:23,673 ERROR [Handler]: IOError: 127.0.0.1:50995 java.io.EOFException at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:30:23,674 ERROR [Handler]: IOError: 127.0.0.1:50989 java.io.EOFException at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:30:23,674 ERROR [Handler]: IOError: 127.0.0.1:49319 java.io.EOFException at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:30:24,224 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:30:31,541 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:30:08.013] 2011-04-22 10:30:31,543 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:30:35,989 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:30:43,990 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:31:05,989 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:31:13,989 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:31:35,990 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:31:43,990 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:32:05,990 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:32:13,991 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:32:31,807 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:30:31.543] 2011-04-22 10:32:31,808 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:32:35,990 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:32:43,990 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:33:05,990 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:33:12,652 INFO [DataStore]: Cleaned up 0 row(s) from task queue! 2011-04-22 10:33:13,990 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:33:35,990 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:33:43,991 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:34:05,990 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:34:13,991 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:34:31,811 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:32:31.808] 2011-04-22 10:34:31,814 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:34:35,990 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:34:43,990 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:35:05,991 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:35:13,991 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:35:31,529 INFO [Handler]: CMD: QUIT :: PEER: /127.0.0.1:55915 2011-04-22 10:35:35,991 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:35:43,992 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:35:49,363 INFO [Listener]: Received connection from: /127.0.0.1:56297 2011-04-22 10:35:53,489 INFO [Handler]: CMD: ADDTASK :: PEER: /127.0.0.1:56297 2011-04-22 10:35:53,493 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:35:53,523 INFO [Handler]: CMD: QUIT :: PEER: /127.0.0.1:56297 2011-04-22 10:36:01,494 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:36:01,504 INFO [TaskQueue]: Assigned task 50103 of type 'MEDIAFILESCANNER' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 10:36:01 MDT 2011] 2011-04-22 10:36:01,504 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:36:01,504 INFO [Listener]: Received connection from: /192.168.1.111:56310 2011-04-22 10:36:01,504 INFO [Handler]: CMD: GETARGS :: PEER: /192.168.1.111:56310 2011-04-22 10:36:01,504 WARN [TaskQueue]: Returning 'null' for 50103 2011-04-22 10:36:01,505 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56310 2011-04-22 10:36:01,746 INFO [Listener]: Received connection from: /192.168.1.111:56313 2011-04-22 10:36:02,308 INFO [Listener]: Received connection from: /192.168.1.111:56315 2011-04-22 10:36:03,477 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:36:03,477 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 10:36:05,992 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:36:07,096 INFO [Handler]: CMD: ADDTASK :: PEER: /192.168.1.111:56315 2011-04-22 10:36:07,120 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:36:07,127 INFO [Handler]: CMD: ADDTASK :: PEER: /192.168.1.111:56315 2011-04-22 10:36:07,133 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56315 2011-04-22 10:36:07,136 ERROR [Handler]: IOError: 192.168.1.111:56313 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:36:07,139 INFO [Listener]: Received connection from: /192.168.1.111:56325 2011-04-22 10:36:07,140 INFO [Handler]: CMD: LOGEXE :: PEER: /192.168.1.111:56325 2011-04-22 10:36:07,143 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56325 2011-04-22 10:36:07,144 INFO [Listener]: Received connection from: /192.168.1.111:56327 2011-04-22 10:36:07,144 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:56327 2011-04-22 10:36:07,147 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56327 2011-04-22 10:36:13,992 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:36:13,999 INFO [TaskQueue]: Assigned task 50104 of type 'CUT' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 10:36:13 MDT 2011] 2011-04-22 10:36:13,999 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:36:14,006 INFO [TaskQueue]: Assigned task 50105 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 10:36:14 MDT 2011] 2011-04-22 10:36:14,006 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:36:14,209 INFO [Listener]: Received connection from: /192.168.1.111:56340 2011-04-22 10:36:14,209 INFO [Listener]: Received connection from: /192.168.1.111:56339 2011-04-22 10:36:14,777 ERROR [Handler]: IOError: 192.168.1.111:56339 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:36:14,782 INFO [Listener]: Received connection from: /192.168.1.111:56343 2011-04-22 10:36:14,782 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:56343 2011-04-22 10:36:14,786 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56343 2011-04-22 10:36:14,787 INFO [Listener]: Received connection from: /192.168.1.111:56345 2011-04-22 10:36:14,787 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:56345 2011-04-22 10:36:14,790 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:36:14,791 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56345 2011-04-22 10:36:14,814 ERROR [Handler]: IOError: 192.168.1.111:56340 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:36:14,818 INFO [Listener]: Received connection from: /192.168.1.111:56347 2011-04-22 10:36:14,818 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:56347 2011-04-22 10:36:14,822 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56347 2011-04-22 10:36:14,822 INFO [Listener]: Received connection from: /192.168.1.111:56349 2011-04-22 10:36:14,823 INFO [Handler]: CMD: GETARGS :: PEER: /192.168.1.111:56349 2011-04-22 10:36:14,823 WARN [TaskQueue]: Returning 'null' for 50104 2011-04-22 10:36:14,823 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56349 2011-04-22 10:36:15,014 INFO [Listener]: Received connection from: /192.168.1.111:56352 2011-04-22 10:36:22,791 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:36:31,810 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:36:14.001] 2011-04-22 10:36:31,812 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:36:35,993 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:36:43,993 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:36:43,998 INFO [TaskQueue]: Assigned task 50105 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 10:36:43 MDT 2011] 2011-04-22 10:36:43,998 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:36:44,201 INFO [Listener]: Received connection from: /192.168.1.111:56393 2011-04-22 10:36:44,781 ERROR [Handler]: IOError: 192.168.1.111:56393 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:36:44,786 INFO [Listener]: Received connection from: /192.168.1.111:56397 2011-04-22 10:36:44,786 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:56397 2011-04-22 10:36:44,791 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56397 2011-04-22 10:36:44,791 INFO [Listener]: Received connection from: /192.168.1.111:56399 2011-04-22 10:36:44,791 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:56399 2011-04-22 10:36:44,795 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:36:44,795 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56399 2011-04-22 10:36:52,796 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:37:03,480 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:37:03,480 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 10:37:05,993 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:37:13,994 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:37:14,004 INFO [TaskQueue]: Assigned task 50105 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 10:37:13 MDT 2011] 2011-04-22 10:37:14,004 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:37:14,219 INFO [Listener]: Received connection from: /192.168.1.111:56436 2011-04-22 10:37:14,792 ERROR [Handler]: IOError: 192.168.1.111:56436 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:37:14,796 INFO [Listener]: Received connection from: /192.168.1.111:56439 2011-04-22 10:37:14,797 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:56439 2011-04-22 10:37:14,801 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56439 2011-04-22 10:37:14,802 INFO [Listener]: Received connection from: /192.168.1.111:56441 2011-04-22 10:37:14,802 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:56441 2011-04-22 10:37:14,805 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:37:14,806 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56441 2011-04-22 10:37:22,805 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:37:35,994 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:37:43,995 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:37:44,002 INFO [TaskQueue]: Assigned task 50105 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 10:37:43 MDT 2011] 2011-04-22 10:37:44,003 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:37:44,219 INFO [Listener]: Received connection from: /192.168.1.111:56488 2011-04-22 10:37:44,789 ERROR [Handler]: IOError: 192.168.1.111:56488 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:37:44,793 INFO [Listener]: Received connection from: /192.168.1.111:56492 2011-04-22 10:37:44,793 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:56492 2011-04-22 10:37:44,797 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56492 2011-04-22 10:37:44,798 INFO [Listener]: Received connection from: /192.168.1.111:56494 2011-04-22 10:37:44,798 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:56494 2011-04-22 10:37:44,801 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:37:44,802 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56494 2011-04-22 10:37:52,801 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:38:03,481 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:38:03,481 INFO [ActiveTaskManager]: Validated 1 running task(s)! 2011-04-22 10:38:05,994 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:38:13,994 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:38:14,001 INFO [TaskQueue]: Assigned task 50105 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 10:38:13 MDT 2011] 2011-04-22 10:38:14,001 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:38:14,225 INFO [Listener]: Received connection from: /192.168.1.111:56563 2011-04-22 10:38:14,798 ERROR [Handler]: IOError: 192.168.1.111:56563 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:38:14,802 INFO [Listener]: Received connection from: /192.168.1.111:56567 2011-04-22 10:38:14,802 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:56567 2011-04-22 10:38:14,806 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56567 2011-04-22 10:38:14,807 INFO [Listener]: Received connection from: /192.168.1.111:56569 2011-04-22 10:38:14,807 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:56569 2011-04-22 10:38:14,810 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:38:14,810 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56569 2011-04-22 10:38:22,811 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:38:24,102 ERROR [Handler]: IOError: 192.168.1.111:56352 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:38:24,105 INFO [Listener]: Received connection from: /192.168.1.111:56591 2011-04-22 10:38:24,106 INFO [Handler]: CMD: LOGEXE :: PEER: /192.168.1.111:56591 2011-04-22 10:38:24,110 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56591 2011-04-22 10:38:24,110 INFO [Listener]: Received connection from: /192.168.1.111:56593 2011-04-22 10:38:24,110 INFO [Handler]: CMD: UPDATE :: PEER: /192.168.1.111:56593 2011-04-22 10:38:24,114 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:38:24,114 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56593 2011-04-22 10:38:31,810 INFO [AgentManager]: Pinging Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=2011-04-22 10:38:13.996] 2011-04-22 10:38:31,812 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:38:32,114 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:38:35,995 INFO [TaskQueue]: Scheduling queue processor for ~8 seconds from now! 2011-04-22 10:38:43,995 INFO [TaskQueue]: Running queue processor now! 2011-04-22 10:38:44,003 INFO [TaskQueue]: Assigned task 50105 of type 'HANDBRAKE' to Client[host=192.168.1.111:23344,state=ONLINE,lastUpdate=Fri Apr 22 10:38:43 MDT 2011] 2011-04-22 10:38:44,004 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:38:44,215 INFO [Listener]: Received connection from: /192.168.1.111:56628 2011-04-22 10:38:44,779 ERROR [Handler]: IOError: 192.168.1.111:56628 java.net.SocketException: Connection reset at java.net.SocketInputStream.read(Unknown Source) at java.net.SocketInputStream.read(Unknown Source) at java.io.ObjectInputStream$PeekInputStream.peek(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readBlockHeader(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.refill(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.read(Unknown Source) at java.io.DataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUnsignedShort(Unknown Source) at java.io.ObjectInputStream$BlockDataInputStream.readUTF(Unknown Source) at java.io.ObjectInputStream.readUTF(Unknown Source) at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:62) at java.lang.Thread.run(Unknown Source) 2011-04-22 10:38:44,783 INFO [Listener]: Received connection from: /192.168.1.111:56632 2011-04-22 10:38:44,783 INFO [Handler]: CMD: LOGTEST :: PEER: /192.168.1.111:56632 2011-04-22 10:38:44,788 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56632 2011-04-22 10:38:44,788 INFO [Listener]: Received connection from: /192.168.1.111:56634 2011-04-22 10:38:44,788 INFO [Handler]: CMD: GETARGS :: PEER: /192.168.1.111:56634 2011-04-22 10:38:44,788 WARN [TaskQueue]: Returning 'null' for 50105 2011-04-22 10:38:44,788 INFO [Handler]: CMD: QUIT :: PEER: /192.168.1.111:56634 2011-04-22 10:38:44,982 INFO [Listener]: Received connection from: /192.168.1.111:56637 2011-04-22 10:39:03,479 INFO [ListenerClient]: Disconnected from 192.168.1.111:23344 2011-04-22 10:39:03,479 INFO [ActiveTaskManager]: Validated 1 running task(s)!