SageTV Community  

Go Back   SageTV Community > SageTV Development and Customizations > SageTV v7 Customizations
Forum Rules FAQs Community Downloads Today's Posts Search

Notices

SageTV v7 Customizations This forums is for discussing and sharing user-created modifications for the SageTV version 7 application created by using the SageTV Studio or through the use of external plugins. Use this forum to discuss plugins for SageTV version 7 and newer.

Reply
 
Thread Tools Search this Thread Display Modes
  #1501  
Old 04-06-2014, 08:57 AM
Doubledose's Avatar
Doubledose Doubledose is offline
Sage Aficionado
 
Join Date: Feb 2010
Location: N. E. Indiana
Posts: 309
Thanks slugger
I went in and changed the channels to all upper case. Since this is groovy code and using sjq4. Where do I put the file and what kind of name and file extension should it have? Right now I have it in my comskip folder and I am calling it postprocessing.bat
Thanks again
Wayne
__________________
New setup 1-22
GIGABYTE B560 HD3 MB | Intel I5 6 core CPU | 500 GB WD SSD system drive | (2) 2TB Western Digital SSD drives pooled | 16 GB DDR ram | UHD 630 on board graphics| Win 10 64 bit | SageTV 9 | (5) Sage HD TV extenders | (1) HDHomeRun quad tuner HD OTA | (1) HDHomeRun Dual tuner HD OTA | (2) 1512 HD-PVR2s recording from 2 Dish Network VIP 211K's with Uirt ir control | MX-700 Home theater remote | 60" LED TV | (4) 32" Lcd TV's
Reply With Quote
  #1502  
Old 04-06-2014, 09:38 AM
bikesquid's Avatar
bikesquid bikesquid is offline
Sage Aficionado
 
Join Date: Jan 2010
Location: California's North Coast
Posts: 392
I'm having a lot of strange SJQ things happening lately. client is going 'off-line', sjq just stalling, etc. I've uninstalled the windows sevice client and installed the plugin agent with no change in results.

I'm including the section of the log showing the 'no buffer space available' error. There are also a number of IOerrors I frankly don't understand.

One thing I am seeing is the license validation seems to be happening every 8 seconds... seems excessive to me.

Any suggestions greatly appreciated!
Code:
014-04-05 20:30:08,008 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:30:08,008 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:30:08,014 INFO  [TaskQueue]: Assigned task 438 of type 'MEDIAFILESCANNER' to Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=Sat Apr 05 20:30:08 PDT 2014]
2014-04-05 20:30:08,014 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:30:08,014 INFO  [Listener]: Received connection from: /192.168.0.111:58982
2014-04-05 20:30:08,014 INFO  [Handler]: CMD: GETARGS :: PEER: /192.168.0.111:58982
2014-04-05 20:30:08,014 WARN  [TaskQueue]: Returning 'null' for 438
2014-04-05 20:30:08,014 INFO  [Handler]: CMD: QUIT :: PEER: /192.168.0.111:58982
2014-04-05 20:30:09,212 INFO  [Listener]: Received connection from: /192.168.0.111:58988
2014-04-05 20:30:09,666 INFO  [Listener]: Received connection from: /192.168.0.111:58990
2014-04-05 20:30:13,747 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:30:13,747 INFO  [ActiveTaskManager]: Validated 1 running task(s)!
2014-04-05 20:30:20,210 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:30:28,211 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:30:28,211 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:30:28,515 INFO  [Handler]: CMD: QUIT :: PEER: /192.168.0.111:58990
2014-04-05 20:30:28,518 ERROR [Handler]: IOError: 192.168.0.111:58988
java.net.SocketException: Connection reset
    at java.net.SocketInputStream.read(Unknown Source)
    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)
2014-04-05 20:30:28,521 INFO  [Listener]: Received connection from: /192.168.0.111:58992
2014-04-05 20:30:28,521 INFO  [Handler]: CMD: LOGEXE :: PEER: /192.168.0.111:58992
2014-04-05 20:30:28,525 INFO  [Handler]: CMD: QUIT :: PEER: /192.168.0.111:58992
2014-04-05 20:30:28,526 INFO  [Listener]: Received connection from: /192.168.0.111:58994
2014-04-05 20:30:28,526 INFO  [Handler]: CMD: UPDATE :: PEER: /192.168.0.111:58994
2014-04-05 20:30:28,529 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:30:28,530 INFO  [Handler]: CMD: QUIT :: PEER: /192.168.0.111:58994
2014-04-05 20:30:36,530 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:30:36,530 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:30:50,211 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:30:58,212 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:30:58,212 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:31:10,927 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:30:08.01]
2014-04-05 20:31:10,928 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:31:20,212 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:31:28,212 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:31:28,212 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:31:50,213 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:31:58,213 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:31:58,213 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:32:20,213 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:32:28,214 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:32:28,214 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:32:50,214 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:32:58,214 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:32:58,214 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:33:10,926 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:31:10.928]
2014-04-05 20:33:10,930 WARN  [AgentManager]: IO error with client 'Sage-PC:23344'; marking OFFLINE!
java.net.SocketException: No buffer space available (maximum connections reached?): connect
    at java.net.DualStackPlainSocketImpl.connect0(Native Method)
    at java.net.DualStackPlainSocketImpl.socketConnect(Unknown Source)
    at java.net.AbstractPlainSocketImpl.doConnect(Unknown Source)
    at java.net.AbstractPlainSocketImpl.connectToAddress(Unknown Source)
    at java.net.AbstractPlainSocketImpl.connect(Unknown Source)
    at java.net.PlainSocketImpl.connect(Unknown Source)
    at java.net.SocksSocketImpl.connect(Unknown Source)
    at java.net.Socket.connect(Unknown Source)
    at java.net.Socket.connect(Unknown Source)
    at java.net.Socket.<init>(Unknown Source)
    at java.net.Socket.<init>(Unknown Source)
    at com.google.code.sagetvaddons.sjq.listener.ListenerClient.<init>(ListenerClient.java:41)
    at com.google.code.sagetvaddons.sjq.network.AgentClient.<init>(AgentClient.java:46)
    at com.google.code.sagetvaddons.sjq.server.AgentManager.ping(AgentManager.java:50)
    at com.google.code.sagetvaddons.sjq.server.AgentManager.run(AgentManager.java:99)
    at java.util.TimerThread.mainLoop(Unknown Source)
    at java.util.TimerThread.run(Unknown Source)
2014-04-05 20:33:10,937 INFO  [Plugin]: Event received: SystemMessagePosted
2014-04-05 20:33:20,215 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:33:28,215 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:33:28,215 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:33:50,215 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:33:58,216 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:33:58,216 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:34:20,215 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:34:28,216 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:34:28,216 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:34:50,216 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:34:58,216 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:34:58,216 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:35:10,927 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=OFFLINE,lastUpdate=2014-04-05 20:33:10.93]
2014-04-05 20:35:10,928 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:35:20,217 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:35:28,217 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:35:28,217 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:35:50,217 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:35:58,218 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:35:58,218 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:36:20,217 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:36:28,217 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:36:28,217 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:36:50,218 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:36:58,218 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:36:58,218 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:37:10,926 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:35:10.929]
2014-04-05 20:37:10,928 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:37:20,218 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:37:28,219 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:37:28,219 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:37:50,219 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:37:58,220 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:37:58,220 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:38:20,220 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:38:28,220 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:38:28,220 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:38:50,221 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:38:58,221 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:38:58,221 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:39:10,928 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:37:10.928]
2014-04-05 20:39:10,930 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:39:20,221 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:39:28,222 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:39:28,222 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:39:50,222 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:39:58,222 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:39:58,222 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:40:20,223 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:40:28,223 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:40:28,223 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:40:50,223 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:40:58,224 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:40:58,224 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:41:10,928 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:39:10.93]
2014-04-05 20:41:10,930 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:41:20,223 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:41:28,224 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:41:28,224 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:41:50,224 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:41:58,224 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:41:58,224 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:42:20,225 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:42:28,225 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:42:28,225 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:42:50,225 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:42:58,226 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:42:58,226 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:43:10,928 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:41:10.93]
2014-04-05 20:43:10,930 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:43:20,225 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:43:28,225 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:43:28,225 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:43:50,226 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:43:58,226 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:43:58,226 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:44:20,226 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:44:28,227 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:44:28,227 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:44:50,227 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:44:58,228 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:44:58,228 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:45:00,008 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:45:00,009 INFO  [CronTaskAdder]: Task 439 of type 'MEDIAFILESCANNER' added to queue via crontab!
2014-04-05 20:45:08,009 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:45:08,009 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:45:08,015 ERROR [AgentClient]: IOError
java.io.EOFException
    at java.io.ObjectInputStream$BlockDataInputStream.peekByte(Unknown Source)
    at java.io.ObjectInputStream.readObject0(Unknown Source)
    at java.io.ObjectInputStream.readObject(Unknown Source)
    at com.google.code.sagetvaddons.sjq.listener.ListenerClient.readObj(ListenerClient.java:60)
    at com.google.code.sagetvaddons.sjq.network.AgentClient.exe(AgentClient.java:104)
    at com.google.code.sagetvaddons.sjq.server.TaskQueue$QueueProcessor.run(TaskQueue.java:149)
    at java.util.TimerThread.mainLoop(Unknown Source)
    at java.util.TimerThread.run(Unknown Source)
2014-04-05 20:45:10,928 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:45:08.011]
2014-04-05 20:45:10,929 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:45:20,228 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 21:00:00,007 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 21:15:00,007 INFO  [CronTaskAdder]: Task 441 of type 'MEDIAFILESCANNER' added to queue via crontab!
2014-04-05 22:00:03,460 INFO  [Plugin]: Event received: RecordingStopped
2014-04-05 22:00:03,462 INFO  [TvRecordingTaskLoader]: Added task 'RENAMECOMSKIP' to queue! [442]
2014-04-06 06:00:11,743 INFO  [Plugin]: Event received: RecordingStarted
2014-04-06 07:00:03,341 INFO  [Plugin]: Event received: RecordingStopped
2014-04-06 07:00:03,343 INFO  [TvRecordingTaskLoader]: Added task 'RENAMECOMSKIP' to queue! [443]
2014-04-06 08:14:32,701 INFO  [Listener]: Received connection from: /127.0.0.1:61765
2014-04-06 08:14:47,703 INFO  [Handler]: CMD: PINGC :: PEER: /127.0.0.1:61765
2014-04-06 08:14:47,713 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:45:10.929]
2014-04-06 08:14:47,718 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-06 08:15:03,863 INFO  [Listener]: Received connection from: /127.0.0.1:61769
2014-04-06 08:15:10,701 INFO  [Handler]: CMD: KILLALL :: PEER: /127.0.0.1:61769
2014-04-06 08:15:10,704 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-06 08:15:18,862 INFO  [Handler]: CMD: PINGC :: PEER: /127.0.0.1:61769
2014-04-06 08:15:18,862 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-06 08:14:47.718]
2014-04-06 08:15:18,869 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-06 08:15:35,878 INFO  [Plugin]: SJQ timer thread has been stopped!
2014-04-06 08:15:35,878 INFO  [Plugin]: SJQ server agent has been stopped!
2014-04-06 08:15:35,878 INFO  [Plugin]: Stopping SJQ crontab...
2014-04-06 08:15:35,878 INFO  [Plugin]: SJQ crontab has been stopped!
2014-04-06 08:15:38,700 ERROR [Handler]: IOError: 127.0.0.1:61769
java.net.SocketException: Connection reset
    at java.net.SocketInputStream.read(Unknown Source)
    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)
2014-04-06 08:15:38,700 ERROR [Handler]: IOError: 127.0.0.1:61765
java.net.SocketException: Connection reset
    at java.net.SocketInputStream.read(Unknown Source)
    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)
2014-04-06 08:15:38,865 WARN  [Listener]: Shutting down listener...
2014-04-06 08:15:38,865 WARN  [Plugin]: SJQ server agent has stopped!
2014-04-06 08:20:05,453 INFO  [Plugin]: sagetv-addons license successfully validated!
2014-04-06 08:20:05,455 INFO  [Plugin]: SJQ timer thread has been started!
2014-04-06 08:20:05,456 INFO  [Plugin]: Server agent has started!
2014-04-06 08:20:05,456 INFO  [Plugin]: Server crontab has started!
2014-04-06 08:20:20,458 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-06 08:20:20,468 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-06 08:15:18.869]
2014-04-06 08:20:20,478 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-06 08:20:28,459 INFO  [TaskQueue]: Running queue processor now!
2014-04-06 08:20:28,465 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-06 08:20:50,472 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-06 08:20:50,690 INFO  [Listener]: Received connection from: /127.0.0.1:61893
2014-04-06 08:21:05,693 INFO  [Handler]: CMD: PINGC :: PEER: /127.0.0.1:61893
2014-04-06 08:21:05,694 ERROR [CommandFactory]: Error
java.lang.ClassNotFoundException: com.google.code.sagetvaddons.sjq.server.commands.Pingc
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.security.AccessController.doPrivileged(Native Method)
    at java.net.URLClassLoader.findClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at java.lang.Class.forName0(Native Method)
    at java.lang.Class.forName(Unknown Source)
    at com.google.code.sagetvaddons.sjq.listener.CommandFactory.get(CommandFactory.java:30)
    at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:64)
    at java.lang.Thread.run(Unknown Source)
2014-04-06 08:21:09,993 INFO  [Listener]: Received connection from: /127.0.0.1:61908
2014-04-06 08:21:22,844 INFO  [Handler]: CMD: RMTASK :: PEER: /127.0.0.1:61908
2014-04-06 08:21:22,844 ERROR [CommandFactory]: Error
java.lang.ClassNotFoundException: com.google.code.sagetvaddons.sjq.server.commands.Rmtask
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.security.AccessController.doPrivileged(Native Method)
    at java.net.URLClassLoader.findClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at java.lang.Class.forName0(Native Method)
    at java.lang.Class.forName(Unknown Source)
    at com.google.code.sagetvaddons.sjq.listener.CommandFactory.get(CommandFactory.java:30)
    at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:64)
    at java.lang.Thread.run(Unknown Source)
2014-04-06 08:21:31,895 INFO  [Listener]: Received connection from: /127.0.0.1:61928
2014-04-06 08:21:38,548 INFO  [Handler]: CMD: KILLALL :: PEER: /127.0.0.1:61928
2014-04-06 08:21:38,548 ERROR [CommandFactory]: Error
java.lang.ClassNotFoundException: com.google.code.sagetvaddons.sjq.server.commands.Killall
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.security.AccessController.doPrivileged(Native Method)
    at java.net.URLClassLoader.findClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at java.lang.Class.forName0(Native Method)
    at java.lang.Class.forName(Unknown Source)
    at com.google.code.sagetvaddons.sjq.listener.CommandFactory.get(CommandFactory.java:30)
    at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:64)
    at java.lang.Thread.run(Unknown Source)
2014-04-06 08:21:46,903 INFO  [Handler]: CMD: PINGC :: PEER: /127.0.0.1:61928
2014-04-06 08:21:46,903 ERROR [CommandFactory]: Error
java.lang.ClassNotFoundException: com.google.code.sagetvaddons.sjq.server.commands.Pingc
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.security.AccessController.doPrivileged(Native Method)
    at java.net.URLClassLoader.findClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at java.lang.Class.forName0(Native Method)
    at java.lang.Class.forName(Unknown Source)
    at com.google.code.sagetvaddons.sjq.listener.CommandFactory.get(CommandFactory.java:30)
    at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:64)
    at java.lang.Thread.run(Unknown Source)
2014-04-06 08:22:26,570 INFO  [Plugin]: SJQ timer thread has been stopped!
2014-04-06 08:22:26,570 INFO  [Plugin]: SJQ server agent has been stopped!
2014-04-06 08:22:26,570 INFO  [Plugin]: Stopping SJQ crontab...
2014-04-06 08:22:26,570 INFO  [Plugin]: SJQ crontab has been stopped!
2014-04-06 08:22:26,898 WARN  [Listener]: Shutting down listener...
2014-04-06 08:22:26,898 WARN  [Plugin]: SJQ server agent has stopped!
Reply With Quote
  #1503  
Old 04-06-2014, 09:51 AM
Slugger Slugger is offline
SageTVaholic
 
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Quote:
Thanks slugger
I went in and changed the channels to all upper case. Since this is groovy code and using sjq4. Where do I put the file and what kind of name and file extension should it have? Right now I have it in my comskip folder and I am calling it postprocessing.bat
Thanks again
Wayne
Unfortunately, I can't write a step by step on how to set it up. Too many variables. You need to follow the user guide/tutorial. That's the best I can do for you at this stage (given that this plugin has been end of life'd since 2012). With that said, I'm still here, I still use most of the plugins I wrote over the years so I still provide moderate support. Helping with some code chunks here and there is one thing, but detailed, step by step setup instructions are left to the user guide and tutorials. Specific questions arising from there are fine.

That file can't be a batch file, it's groovy code and must have a .groovy extension. Please work thru the user guide and tutorial for details on where to install it, etc.

User Guide: https://code.google.com/p/sagetv-add.../Sjq4UserGuide

Tutorial of interest: https://code.google.com/p/sagetv-add...i/Sjq4Comskip1
__________________
Twitter: @ddb_db
Server: Intel i5-4570 Quad Core, 16GB RAM, 1 x 128GB OS SSD (Win7 Pro x64 SP1), 1 x 2TB media drive
Capture: 2 x Colossus
STB Controller: 1 x USB-UIRT
Software:Java 1.7.0_71; SageTV 7.1.9
Clients: 1 x HD300, 2 x HD200, 1 x SageClient, 1 x PlaceShifter
Plugins: Too many to list now...
Reply With Quote
  #1504  
Old 04-06-2014, 09:58 AM
Slugger Slugger is offline
SageTVaholic
 
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Quote:
Originally Posted by bikesquid View Post
I'm having a lot of strange SJQ things happening lately. client is going 'off-line', sjq just stalling, etc. I've uninstalled the windows sevice client and installed the plugin agent with no change in results.

I'm including the section of the log showing the 'no buffer space available' error. There are also a number of IOerrors I frankly don't understand.

One thing I am seeing is the license validation seems to be happening every 8 seconds... seems excessive to me.

Any suggestions greatly appreciated!
Code:
014-04-05 20:30:08,008 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:30:08,008 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:30:08,014 INFO  [TaskQueue]: Assigned task 438 of type 'MEDIAFILESCANNER' to Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=Sat Apr 05 20:30:08 PDT 2014]
2014-04-05 20:30:08,014 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:30:08,014 INFO  [Listener]: Received connection from: /192.168.0.111:58982
2014-04-05 20:30:08,014 INFO  [Handler]: CMD: GETARGS :: PEER: /192.168.0.111:58982
2014-04-05 20:30:08,014 WARN  [TaskQueue]: Returning 'null' for 438
2014-04-05 20:30:08,014 INFO  [Handler]: CMD: QUIT :: PEER: /192.168.0.111:58982
2014-04-05 20:30:09,212 INFO  [Listener]: Received connection from: /192.168.0.111:58988
2014-04-05 20:30:09,666 INFO  [Listener]: Received connection from: /192.168.0.111:58990
2014-04-05 20:30:13,747 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:30:13,747 INFO  [ActiveTaskManager]: Validated 1 running task(s)!
2014-04-05 20:30:20,210 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:30:28,211 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:30:28,211 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:30:28,515 INFO  [Handler]: CMD: QUIT :: PEER: /192.168.0.111:58990
2014-04-05 20:30:28,518 ERROR [Handler]: IOError: 192.168.0.111:58988
java.net.SocketException: Connection reset
    at java.net.SocketInputStream.read(Unknown Source)
    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)
2014-04-05 20:30:28,521 INFO  [Listener]: Received connection from: /192.168.0.111:58992
2014-04-05 20:30:28,521 INFO  [Handler]: CMD: LOGEXE :: PEER: /192.168.0.111:58992
2014-04-05 20:30:28,525 INFO  [Handler]: CMD: QUIT :: PEER: /192.168.0.111:58992
2014-04-05 20:30:28,526 INFO  [Listener]: Received connection from: /192.168.0.111:58994
2014-04-05 20:30:28,526 INFO  [Handler]: CMD: UPDATE :: PEER: /192.168.0.111:58994
2014-04-05 20:30:28,529 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:30:28,530 INFO  [Handler]: CMD: QUIT :: PEER: /192.168.0.111:58994
2014-04-05 20:30:36,530 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:30:36,530 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:30:50,211 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:30:58,212 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:30:58,212 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:31:10,927 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:30:08.01]
2014-04-05 20:31:10,928 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:31:20,212 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:31:28,212 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:31:28,212 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:31:50,213 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:31:58,213 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:31:58,213 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:32:20,213 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:32:28,214 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:32:28,214 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:32:50,214 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:32:58,214 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:32:58,214 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:33:10,926 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:31:10.928]
2014-04-05 20:33:10,930 WARN  [AgentManager]: IO error with client 'Sage-PC:23344'; marking OFFLINE!
java.net.SocketException: No buffer space available (maximum connections reached?): connect
    at java.net.DualStackPlainSocketImpl.connect0(Native Method)
    at java.net.DualStackPlainSocketImpl.socketConnect(Unknown Source)
    at java.net.AbstractPlainSocketImpl.doConnect(Unknown Source)
    at java.net.AbstractPlainSocketImpl.connectToAddress(Unknown Source)
    at java.net.AbstractPlainSocketImpl.connect(Unknown Source)
    at java.net.PlainSocketImpl.connect(Unknown Source)
    at java.net.SocksSocketImpl.connect(Unknown Source)
    at java.net.Socket.connect(Unknown Source)
    at java.net.Socket.connect(Unknown Source)
    at java.net.Socket.<init>(Unknown Source)
    at java.net.Socket.<init>(Unknown Source)
    at com.google.code.sagetvaddons.sjq.listener.ListenerClient.<init>(ListenerClient.java:41)
    at com.google.code.sagetvaddons.sjq.network.AgentClient.<init>(AgentClient.java:46)
    at com.google.code.sagetvaddons.sjq.server.AgentManager.ping(AgentManager.java:50)
    at com.google.code.sagetvaddons.sjq.server.AgentManager.run(AgentManager.java:99)
    at java.util.TimerThread.mainLoop(Unknown Source)
    at java.util.TimerThread.run(Unknown Source)
2014-04-05 20:33:10,937 INFO  [Plugin]: Event received: SystemMessagePosted
2014-04-05 20:33:20,215 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:33:28,215 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:33:28,215 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:33:50,215 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:33:58,216 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:33:58,216 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:34:20,215 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:34:28,216 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:34:28,216 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:34:50,216 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:34:58,216 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:34:58,216 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:35:10,927 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=OFFLINE,lastUpdate=2014-04-05 20:33:10.93]
2014-04-05 20:35:10,928 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:35:20,217 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:35:28,217 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:35:28,217 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:35:50,217 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:35:58,218 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:35:58,218 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:36:20,217 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:36:28,217 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:36:28,217 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:36:50,218 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:36:58,218 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:36:58,218 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:37:10,926 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:35:10.929]
2014-04-05 20:37:10,928 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:37:20,218 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:37:28,219 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:37:28,219 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:37:50,219 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:37:58,220 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:37:58,220 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:38:20,220 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:38:28,220 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:38:28,220 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:38:50,221 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:38:58,221 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:38:58,221 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:39:10,928 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:37:10.928]
2014-04-05 20:39:10,930 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:39:20,221 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:39:28,222 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:39:28,222 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:39:50,222 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:39:58,222 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:39:58,222 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:40:20,223 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:40:28,223 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:40:28,223 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:40:50,223 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:40:58,224 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:40:58,224 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:41:10,928 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:39:10.93]
2014-04-05 20:41:10,930 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:41:20,223 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:41:28,224 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:41:28,224 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:41:50,224 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:41:58,224 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:41:58,224 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:42:20,225 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:42:28,225 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:42:28,225 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:42:50,225 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:42:58,226 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:42:58,226 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:43:10,928 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:41:10.93]
2014-04-05 20:43:10,930 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:43:20,225 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:43:28,225 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:43:28,225 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:43:50,226 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:43:58,226 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:43:58,226 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:44:20,226 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:44:28,227 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:44:28,227 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:44:50,227 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:44:58,228 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:44:58,228 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:45:00,008 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 20:45:00,009 INFO  [CronTaskAdder]: Task 439 of type 'MEDIAFILESCANNER' added to queue via crontab!
2014-04-05 20:45:08,009 INFO  [TaskQueue]: Running queue processor now!
2014-04-05 20:45:08,009 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-05 20:45:08,015 ERROR [AgentClient]: IOError
java.io.EOFException
    at java.io.ObjectInputStream$BlockDataInputStream.peekByte(Unknown Source)
    at java.io.ObjectInputStream.readObject0(Unknown Source)
    at java.io.ObjectInputStream.readObject(Unknown Source)
    at com.google.code.sagetvaddons.sjq.listener.ListenerClient.readObj(ListenerClient.java:60)
    at com.google.code.sagetvaddons.sjq.network.AgentClient.exe(AgentClient.java:104)
    at com.google.code.sagetvaddons.sjq.server.TaskQueue$QueueProcessor.run(TaskQueue.java:149)
    at java.util.TimerThread.mainLoop(Unknown Source)
    at java.util.TimerThread.run(Unknown Source)
2014-04-05 20:45:10,928 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:45:08.011]
2014-04-05 20:45:10,929 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-05 20:45:20,228 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 21:00:00,007 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-05 21:15:00,007 INFO  [CronTaskAdder]: Task 441 of type 'MEDIAFILESCANNER' added to queue via crontab!
2014-04-05 22:00:03,460 INFO  [Plugin]: Event received: RecordingStopped
2014-04-05 22:00:03,462 INFO  [TvRecordingTaskLoader]: Added task 'RENAMECOMSKIP' to queue! [442]
2014-04-06 06:00:11,743 INFO  [Plugin]: Event received: RecordingStarted
2014-04-06 07:00:03,341 INFO  [Plugin]: Event received: RecordingStopped
2014-04-06 07:00:03,343 INFO  [TvRecordingTaskLoader]: Added task 'RENAMECOMSKIP' to queue! [443]
2014-04-06 08:14:32,701 INFO  [Listener]: Received connection from: /127.0.0.1:61765
2014-04-06 08:14:47,703 INFO  [Handler]: CMD: PINGC :: PEER: /127.0.0.1:61765
2014-04-06 08:14:47,713 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-05 20:45:10.929]
2014-04-06 08:14:47,718 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-06 08:15:03,863 INFO  [Listener]: Received connection from: /127.0.0.1:61769
2014-04-06 08:15:10,701 INFO  [Handler]: CMD: KILLALL :: PEER: /127.0.0.1:61769
2014-04-06 08:15:10,704 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-06 08:15:18,862 INFO  [Handler]: CMD: PINGC :: PEER: /127.0.0.1:61769
2014-04-06 08:15:18,862 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-06 08:14:47.718]
2014-04-06 08:15:18,869 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-06 08:15:35,878 INFO  [Plugin]: SJQ timer thread has been stopped!
2014-04-06 08:15:35,878 INFO  [Plugin]: SJQ server agent has been stopped!
2014-04-06 08:15:35,878 INFO  [Plugin]: Stopping SJQ crontab...
2014-04-06 08:15:35,878 INFO  [Plugin]: SJQ crontab has been stopped!
2014-04-06 08:15:38,700 ERROR [Handler]: IOError: 127.0.0.1:61769
java.net.SocketException: Connection reset
    at java.net.SocketInputStream.read(Unknown Source)
    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)
2014-04-06 08:15:38,700 ERROR [Handler]: IOError: 127.0.0.1:61765
java.net.SocketException: Connection reset
    at java.net.SocketInputStream.read(Unknown Source)
    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)
2014-04-06 08:15:38,865 WARN  [Listener]: Shutting down listener...
2014-04-06 08:15:38,865 WARN  [Plugin]: SJQ server agent has stopped!
2014-04-06 08:20:05,453 INFO  [Plugin]: sagetv-addons license successfully validated!
2014-04-06 08:20:05,455 INFO  [Plugin]: SJQ timer thread has been started!
2014-04-06 08:20:05,456 INFO  [Plugin]: Server agent has started!
2014-04-06 08:20:05,456 INFO  [Plugin]: Server crontab has started!
2014-04-06 08:20:20,458 INFO  [TaskQueue]: Scheduling queue processor for ~8 seconds from now!
2014-04-06 08:20:20,468 INFO  [AgentManager]: Pinging Client[host=Sage-PC:23344,state=ONLINE,lastUpdate=2014-04-06 08:15:18.869]
2014-04-06 08:20:20,478 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-06 08:20:28,459 INFO  [TaskQueue]: Running queue processor now!
2014-04-06 08:20:28,465 INFO  [TaskQueue]: Enforcing licensing restrictions... license is valid!
2014-04-06 08:20:50,472 INFO  [ListenerClient]: Disconnected from Sage-PC:23344
2014-04-06 08:20:50,690 INFO  [Listener]: Received connection from: /127.0.0.1:61893
2014-04-06 08:21:05,693 INFO  [Handler]: CMD: PINGC :: PEER: /127.0.0.1:61893
2014-04-06 08:21:05,694 ERROR [CommandFactory]: Error
java.lang.ClassNotFoundException: com.google.code.sagetvaddons.sjq.server.commands.Pingc
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.security.AccessController.doPrivileged(Native Method)
    at java.net.URLClassLoader.findClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at java.lang.Class.forName0(Native Method)
    at java.lang.Class.forName(Unknown Source)
    at com.google.code.sagetvaddons.sjq.listener.CommandFactory.get(CommandFactory.java:30)
    at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:64)
    at java.lang.Thread.run(Unknown Source)
2014-04-06 08:21:09,993 INFO  [Listener]: Received connection from: /127.0.0.1:61908
2014-04-06 08:21:22,844 INFO  [Handler]: CMD: RMTASK :: PEER: /127.0.0.1:61908
2014-04-06 08:21:22,844 ERROR [CommandFactory]: Error
java.lang.ClassNotFoundException: com.google.code.sagetvaddons.sjq.server.commands.Rmtask
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.security.AccessController.doPrivileged(Native Method)
    at java.net.URLClassLoader.findClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at java.lang.Class.forName0(Native Method)
    at java.lang.Class.forName(Unknown Source)
    at com.google.code.sagetvaddons.sjq.listener.CommandFactory.get(CommandFactory.java:30)
    at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:64)
    at java.lang.Thread.run(Unknown Source)
2014-04-06 08:21:31,895 INFO  [Listener]: Received connection from: /127.0.0.1:61928
2014-04-06 08:21:38,548 INFO  [Handler]: CMD: KILLALL :: PEER: /127.0.0.1:61928
2014-04-06 08:21:38,548 ERROR [CommandFactory]: Error
java.lang.ClassNotFoundException: com.google.code.sagetvaddons.sjq.server.commands.Killall
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.security.AccessController.doPrivileged(Native Method)
    at java.net.URLClassLoader.findClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at java.lang.Class.forName0(Native Method)
    at java.lang.Class.forName(Unknown Source)
    at com.google.code.sagetvaddons.sjq.listener.CommandFactory.get(CommandFactory.java:30)
    at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:64)
    at java.lang.Thread.run(Unknown Source)
2014-04-06 08:21:46,903 INFO  [Handler]: CMD: PINGC :: PEER: /127.0.0.1:61928
2014-04-06 08:21:46,903 ERROR [CommandFactory]: Error
java.lang.ClassNotFoundException: com.google.code.sagetvaddons.sjq.server.commands.Pingc
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.net.URLClassLoader$1.run(Unknown Source)
    at java.security.AccessController.doPrivileged(Native Method)
    at java.net.URLClassLoader.findClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at sun.misc.Launcher$AppClassLoader.loadClass(Unknown Source)
    at java.lang.ClassLoader.loadClass(Unknown Source)
    at java.lang.Class.forName0(Native Method)
    at java.lang.Class.forName(Unknown Source)
    at com.google.code.sagetvaddons.sjq.listener.CommandFactory.get(CommandFactory.java:30)
    at com.google.code.sagetvaddons.sjq.listener.Handler.run(Handler.java:64)
    at java.lang.Thread.run(Unknown Source)
2014-04-06 08:22:26,570 INFO  [Plugin]: SJQ timer thread has been stopped!
2014-04-06 08:22:26,570 INFO  [Plugin]: SJQ server agent has been stopped!
2014-04-06 08:22:26,570 INFO  [Plugin]: Stopping SJQ crontab...
2014-04-06 08:22:26,570 INFO  [Plugin]: SJQ crontab has been stopped!
2014-04-06 08:22:26,898 WARN  [Listener]: Shutting down listener...
2014-04-06 08:22:26,898 WARN  [Plugin]: SJQ server agent has stopped!
I vaguely recalled someone reporting this problem not too long ago so I scrolled back thru the thread and it was you.

http://forums.sagetv.com/forums/show...postcount=1421

Did you apply that Windows hotfix? The error appears to be a Windows 7 bug.
__________________
Twitter: @ddb_db
Server: Intel i5-4570 Quad Core, 16GB RAM, 1 x 128GB OS SSD (Win7 Pro x64 SP1), 1 x 2TB media drive
Capture: 2 x Colossus
STB Controller: 1 x USB-UIRT
Software:Java 1.7.0_71; SageTV 7.1.9
Clients: 1 x HD300, 2 x HD200, 1 x SageClient, 1 x PlaceShifter
Plugins: Too many to list now...
Reply With Quote
  #1505  
Old 04-06-2014, 10:18 AM
bikesquid's Avatar
bikesquid bikesquid is offline
Sage Aficionado
 
Join Date: Jan 2010
Location: California's North Coast
Posts: 392
Quote:
Originally Posted by Slugger View Post
I vaguely recalled someone reporting this problem not too long ago so I scrolled back thru the thread and it was you.

http://forums.sagetv.com/forums/show...postcount=1421

Did you apply that Windows hotfix? The error appears to be a Windows 7 bug.
Yup, I've been struggling ever since with these buffer issues every other day or so. The hotfix didn't change anything

I'm not sure if the buffer issue is a cause or symptom of another problem. Have gone through reinstalling windows, sage, sjq, java, etc. Even tried using a newer wrapper.

I had a few hard disk problems with a wonky raid so till I sorted that out I didn't want to be pushing this too far. The raid issue has been fixed for a couple months now and all else is right with the world except this and taxes....
Reply With Quote
  #1506  
Old 04-06-2014, 10:46 AM
Slugger Slugger is offline
SageTVaholic
 
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Quote:
Originally Posted by bikesquid View Post
Yup, I've been struggling ever since with these buffer issues every other day or so. The hotfix didn't change anything

I'm not sure if the buffer issue is a cause or symptom of another problem. Have gone through reinstalling windows, sage, sjq, java, etc. Even tried using a newer wrapper.

I had a few hard disk problems with a wonky raid so till I sorted that out I didn't want to be pushing this too far. The raid issue has been fixed for a couple months now and all else is right with the world except this and taxes....
I can't give you much more advice. I'm running Windows 7 x64 myself, I've gone back thru the last 50MB of SJQ logs and I've never had this error in any of the logs.

So if you've applied the hotfix and that hasn't helped then you've got to look at what's exhausting the sockets on your system. Do you have other network apps running on the box? An (always) active torrent client would chew up sockets in a hurry, for example.

What's the output of netstat look like on the system?
__________________
Twitter: @ddb_db
Server: Intel i5-4570 Quad Core, 16GB RAM, 1 x 128GB OS SSD (Win7 Pro x64 SP1), 1 x 2TB media drive
Capture: 2 x Colossus
STB Controller: 1 x USB-UIRT
Software:Java 1.7.0_71; SageTV 7.1.9
Clients: 1 x HD300, 2 x HD200, 1 x SageClient, 1 x PlaceShifter
Plugins: Too many to list now...
Reply With Quote
  #1507  
Old 04-06-2014, 11:12 AM
Slugger Slugger is offline
SageTVaholic
 
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
http://evilware.com/20110329/evolve/...ce-tweaks.html

About 3/4 down the page. Search for MaxUserPort. You might want to investigate your current settings and increase the MaxUserPort to something higher and/or tweak the TcpTimedWaitDelay settings. I'm not using any of these tweaks, so YMMV, but just pointing them out as something else to try.
__________________
Twitter: @ddb_db
Server: Intel i5-4570 Quad Core, 16GB RAM, 1 x 128GB OS SSD (Win7 Pro x64 SP1), 1 x 2TB media drive
Capture: 2 x Colossus
STB Controller: 1 x USB-UIRT
Software:Java 1.7.0_71; SageTV 7.1.9
Clients: 1 x HD300, 2 x HD200, 1 x SageClient, 1 x PlaceShifter
Plugins: Too many to list now...
Reply With Quote
  #1508  
Old 04-06-2014, 02:16 PM
bikesquid's Avatar
bikesquid bikesquid is offline
Sage Aficionado
 
Join Date: Jan 2010
Location: California's North Coast
Posts: 392
Thanks Slugger.
Always appreciate the help!

This was all working perfectly for several years, then after sage took a break for a year from recording/processing this is what started when it got those tasks back and I've just not been able to fix it. It is possible it's related to the motherboard swap and using the different on-board net card/driver....

Having read the link you sent I did do two things before I did the netstat (probably not the ideal order)
1- registry edit TcpTimedWaitDelay=30 which may clean up the mass of connections sjq seems to make (it was at default 120) and
2 - netsh int ipv4 set dynamicportrange protocol=tcp start=1025 num=64511
which seems like it'll max out the option.

Here's the netstat from just now:

This machine doesn't do anything but sage and Logitech Media Server. no torrenting, no nothin... some file-server duties but that's very light.

Code:
Active Connections

  Proto  Local Address          Foreign Address        State
  TCP    127.0.0.1:5354         Sage-PC:49155          ESTABLISHED
  TCP    127.0.0.1:5354         Sage-PC:49156          ESTABLISHED
  TCP    127.0.0.1:5354         Sage-PC:62053          ESTABLISHED
  TCP    127.0.0.1:5354         Sage-PC:62054          ESTABLISHED
  TCP    127.0.0.1:5354         Sage-PC:62055          ESTABLISHED
  TCP    127.0.0.1:5354         Sage-PC:62059          ESTABLISHED
  TCP    127.0.0.1:5354         Sage-PC:62060          ESTABLISHED
  TCP    127.0.0.1:5354         Sage-PC:62079          ESTABLISHED
  TCP    127.0.0.1:9092         Sage-PC:62137          ESTABLISHED
  TCP    127.0.0.1:9092         Sage-PC:62169          ESTABLISHED
  TCP    127.0.0.1:9092         Sage-PC:62171          ESTABLISHED
  TCP    127.0.0.1:23347        Sage-PC:62139          ESTABLISHED
  TCP    127.0.0.1:23347        Sage-PC:62170          ESTABLISHED
  TCP    127.0.0.1:23347        Sage-PC:62181          ESTABLISHED
  TCP    127.0.0.1:27015        Sage-PC:49160          ESTABLISHED
  TCP    127.0.0.1:27015        Sage-PC:62051          ESTABLISHED
  TCP    127.0.0.1:27015        Sage-PC:62067          ESTABLISHED
  TCP    127.0.0.1:42024        Sage-PC:62045          ESTABLISHED
  TCP    127.0.0.1:42024        Sage-PC:62086          ESTABLISHED
  TCP    127.0.0.1:49155        Sage-PC:5354           ESTABLISHED
  TCP    127.0.0.1:49156        Sage-PC:5354           ESTABLISHED
  TCP    127.0.0.1:49160        Sage-PC:27015          ESTABLISHED
  TCP    127.0.0.1:62022        Sage-PC:62023          ESTABLISHED
  TCP    127.0.0.1:62023        Sage-PC:62022          ESTABLISHED
  TCP    127.0.0.1:62027        Sage-PC:62028          ESTABLISHED
  TCP    127.0.0.1:62028        Sage-PC:62027          ESTABLISHED
  TCP    127.0.0.1:62029        Sage-PC:62030          ESTABLISHED
  TCP    127.0.0.1:62030        Sage-PC:62029          ESTABLISHED
  TCP    127.0.0.1:62032        Sage-PC:62033          ESTABLISHED
  TCP    127.0.0.1:62033        Sage-PC:62032          ESTABLISHED
  TCP    127.0.0.1:62045        Sage-PC:42024          ESTABLISHED
  TCP    127.0.0.1:62051        Sage-PC:27015          ESTABLISHED
  TCP    127.0.0.1:62053        Sage-PC:5354           ESTABLISHED
  TCP    127.0.0.1:62054        Sage-PC:5354           ESTABLISHED
  TCP    127.0.0.1:62055        Sage-PC:5354           ESTABLISHED
  TCP    127.0.0.1:62059        Sage-PC:5354           ESTABLISHED
  TCP    127.0.0.1:62060        Sage-PC:5354           ESTABLISHED
  TCP    127.0.0.1:62067        Sage-PC:27015          ESTABLISHED
  TCP    127.0.0.1:62079        Sage-PC:5354           ESTABLISHED
  TCP    127.0.0.1:62086        Sage-PC:42024          ESTABLISHED
  TCP    127.0.0.1:62091        Sage-PC:62092          ESTABLISHED
  TCP    127.0.0.1:62092        Sage-PC:62091          ESTABLISHED
  TCP    127.0.0.1:62137        Sage-PC:9092           ESTABLISHED
  TCP    127.0.0.1:62139        Sage-PC:23347          ESTABLISHED
  TCP    127.0.0.1:62169        Sage-PC:9092           ESTABLISHED
  TCP    127.0.0.1:62170        Sage-PC:23347          ESTABLISHED
  TCP    127.0.0.1:62171        Sage-PC:9092           ESTABLISHED
  TCP    127.0.0.1:62181        Sage-PC:23347          ESTABLISHED
  TCP    192.168.0.111:445      K-PC:3423              ESTABLISHED
  TCP    192.168.0.111:445      B-PC:49190             ESTABLISHED
  TCP    192.168.0.111:3689     android-82ce7d1d5415ecb3:46737  ESTABLISHED
  TCP    192.168.0.111:3689     android-23808cf16d1ba48d:43430  ESTABLISHED
  TCP    192.168.0.111:9092     Sage-PC:62088          ESTABLISHED
  TCP    192.168.0.111:9092     Sage-PC:62122          ESTABLISHED
  TCP    192.168.0.111:9092     Sage-PC:62144          ESTABLISHED
  TCP    192.168.0.111:9092     Sage-PC:62183          ESTABLISHED
  TCP    192.168.0.111:9092     Sage-PC:63645          ESTABLISHED
  TCP    192.168.0.111:9092     Sage-PC:63647          ESTABLISHED
  TCP    192.168.0.111:23344    Sage-PC:63652          TIME_WAIT
  TCP    192.168.0.111:62088    Sage-PC:9092           ESTABLISHED
  TCP    192.168.0.111:62107    ec2-176-34-188-189:http  CLOSE_WAIT
  TCP    192.168.0.111:62113    ec2-176-34-188-189:http  CLOSE_WAIT
  TCP    192.168.0.111:62122    Sage-PC:9092           ESTABLISHED
  TCP    192.168.0.111:62141    ec2-176-34-188-189:http  CLOSE_WAIT
  TCP    192.168.0.111:62144    Sage-PC:9092           ESTABLISHED
  TCP    192.168.0.111:62168    ec2-54-247-188-60:http  CLOSE_WAIT
  TCP    192.168.0.111:62180    ec2-54-247-188-60:http  CLOSE_WAIT
  TCP    192.168.0.111:62183    Sage-PC:9092           ESTABLISHED
  TCP    192.168.0.111:62191    ec2-54-247-188-60:http  CLOSE_WAIT
  TCP    192.168.0.111:62203    ec2-54-247-188-60:http  CLOSE_WAIT
  TCP    192.168.0.111:63600    SqueezeMain:5002       ESTABLISHED
  TCP    192.168.0.111:63601    SqueezeOffice:5002     ESTABLISHED
  TCP    192.168.0.111:63620    ec2-54-247-188-60:http  CLOSE_WAIT
  TCP    192.168.0.111:63645    Sage-PC:9092           ESTABLISHED
  TCP    192.168.0.111:63647    Sage-PC:9092           ESTABLISHED
  TCP    [fe80::4c35:1261:bf07:cdf3%10]:3389  B-PC:65395             ESTABLISHED
  TCP    [fe80::4c35:1261:bf07:cdf3%10]:3587  B-PC:49341             ESTABLISHED
  TCP    [fe80::4c35:1261:bf07:cdf3%10]:3587  K-PC:3442              ESTABLISHED
  TCP    [fe80::4c35:1261:bf07:cdf3%10]:63650  K-PC:p2pgroup         TIME_WAIT
  TCP    [fe80::4c35:1261:bf07:cdf3%10]:63651  B-PC:p2pgroup         TIME_WAIT
Reply With Quote
  #1509  
Old 04-06-2014, 02:49 PM
Slugger Slugger is offline
SageTVaholic
 
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Well keep us posted. On that netstat output, there's only one connection from SJQ:

Code:
TCP    192.168.0.111:23344    Sage-PC:63652          TIME_WAIT
The rest of the connections from that output are not from SJQ.
__________________
Twitter: @ddb_db
Server: Intel i5-4570 Quad Core, 16GB RAM, 1 x 128GB OS SSD (Win7 Pro x64 SP1), 1 x 2TB media drive
Capture: 2 x Colossus
STB Controller: 1 x USB-UIRT
Software:Java 1.7.0_71; SageTV 7.1.9
Clients: 1 x HD300, 2 x HD200, 1 x SageClient, 1 x PlaceShifter
Plugins: Too many to list now...
Reply With Quote
  #1510  
Old 04-06-2014, 11:14 PM
bikesquid's Avatar
bikesquid bikesquid is offline
Sage Aficionado
 
Join Date: Jan 2010
Location: California's North Coast
Posts: 392
Not that this points to SJQ but as it's related to the 'problem' I'm having I thought I'd post the error here as there isn't a sagetv to report it to....
I'll post in the general sage thread as well as I realize this is well outside sjq territory!

After the various errors the UI often gets unresponsive and the sage service stops. This is often when it's recording two shows, and in the background editing files via the sjq scripts.

At the windows event viewer I see this error:
Code:
Faulting application name: SageTVService.exe, version: 6.0.13.1, time stamp: 0x4df93b47
Faulting module name: SageTVService.exe, version: 6.0.13.1, time stamp: 0x4df93b47
Exception code: 0x40000015
Fault offset: 0x00009811
Faulting process id: 0xfbc
Faulting application start time: 0x01cf51ae74995517
Faulting application path: C:\Program Files (x86)\SageTV\SageTV\SageTVService.exe
Faulting module path: C:\Program Files (x86)\SageTV\SageTV\SageTVService.exe
Report Id: f2326aed-be10-11e3-af1a-902b34d2efda
Reply With Quote
  #1511  
Old 04-27-2014, 08:36 PM
ptaylor ptaylor is offline
Sage Aficionado
 
Join Date: May 2004
Posts: 308
Twice in about the last 3 weeks I've had the "Jobs.db" directory fill up with gigabytes of jump, filling up my drive to the point that things in SageTV stop recording.

In each case, I've wiped out that directory and things seem fine afterward for a while... I'm not sure what is precipitating the problem, as nothing has really changed in months...
Reply With Quote
  #1512  
Old 04-28-2014, 05:11 AM
Slugger Slugger is offline
SageTVaholic
 
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
This was always one of the great mysteries with SJQv4. I could never reproduce this issue locally, but I'm pretty sure it had something to do with a bug in the H2 database driver with the way it handled LOB columns in a database.

Another user, phelme, seems to have remedied this issue by manually upgrading the H2 jar on his system. The details of what he did are here and an update here. Maybe he'll report back in again? That might be your best bet and it's about the only advice I can offer with this issue.
__________________
Twitter: @ddb_db
Server: Intel i5-4570 Quad Core, 16GB RAM, 1 x 128GB OS SSD (Win7 Pro x64 SP1), 1 x 2TB media drive
Capture: 2 x Colossus
STB Controller: 1 x USB-UIRT
Software:Java 1.7.0_71; SageTV 7.1.9
Clients: 1 x HD300, 2 x HD200, 1 x SageClient, 1 x PlaceShifter
Plugins: Too many to list now...
Reply With Quote
  #1513  
Old 04-28-2014, 04:45 PM
ptaylor ptaylor is offline
Sage Aficionado
 
Join Date: May 2004
Posts: 308
Alright then... I stopped Sage, copied in h2 version 1.3.176 (renaming it to match the version number of the old one), and restarted Sage. (I kept the old version just in case)

The problem has only happened twice for me, both times within the last month, so hopefully this will solve it.
Reply With Quote
  #1514  
Old 04-28-2014, 06:41 PM
wayner wayner is offline
SageTVaholic
 
Join Date: Jan 2008
Location: Toronto, ON
Posts: 7,491
Java error

In a SJQ groovy script I am uploaded files to an FTP server. I was getting timeout errors so I saw a post to add: "ftp.setControlKeepAliveTimeout(300)" so that a NOOP is sent every 5 minutes to prevent timeouts. (from SO: http://stackoverflow.com/questions/1...g-large-a-file)

But when I run my code I get:
groovy.lang.MissingMethodException: No signature of method: org.apache.commons.net.ftp.FTPClient.setControlKeepAliveTimeout() is applicable for argument types: (java.lang.Integer) values: [300]

Any idea what could be wrong? I have the commons.net 3.3 jar installed. Can it be something else. Here is the relevant part of my code - and it works fine (other than the timeout on large files) until I have the setKeepAlive line

Code:
import org.apache.commons.net.ftp.FTP;
import org.apache.commons.net.ftp.FTPClient;
fileSize=(new File (localFile)).size()/1000000
ftp = new FTPClient()
ftp.connect(ftphost)
ftp.login(ftpuser, ftppwd)
ftp.setFileType(FTP.BINARY_FILE_TYPE)
ftp.setBufferSize(1024*1024)
ftp.enterLocalPassiveMode()
ftp.setControlKeepAliveTimeout(300)
fis = new FileInputStream((localFile))
ftp.changeWorkingDirectory(hostDir)
startTime=System.currentTimeMillis()
ftp.storeFile("ptest2.mp4", fis)
__________________
New Server - Sage9 on unRAID 2xHD-PVR, HDHR for OTA
Old Server - Sage7 on Win7Pro-i660CPU with 4.6TB, HD-PVR, HDHR OTA, HVR-1850 OTA
Clients - 2xHD-300, 8xHD-200 Extenders, Client+2xPlaceshifter and a WHS which acts as a backup Sage server
Reply With Quote
  #1515  
Old 04-28-2014, 07:45 PM
Slugger Slugger is offline
SageTVaholic
 
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Method takes a long parameter, surprised groovy didn't just coerce the int, but anyway, instead of passing 300, try 300L to make it a long instead of an int.

I'm sure if I upgraded the groovy jar to something newer, it'd probably just do it for you.
__________________
Twitter: @ddb_db
Server: Intel i5-4570 Quad Core, 16GB RAM, 1 x 128GB OS SSD (Win7 Pro x64 SP1), 1 x 2TB media drive
Capture: 2 x Colossus
STB Controller: 1 x USB-UIRT
Software:Java 1.7.0_71; SageTV 7.1.9
Clients: 1 x HD300, 2 x HD200, 1 x SageClient, 1 x PlaceShifter
Plugins: Too many to list now...
Reply With Quote
  #1516  
Old 04-28-2014, 08:05 PM
wayner wayner is offline
SageTVaholic
 
Join Date: Jan 2008
Location: Toronto, ON
Posts: 7,491
That didn't seem to help either as the error is now:
groovy.lang.MissingMethodException: No signature of method: org.apache.commons.net.ftp.FTPClient.setControlKeepAliveTimeout() is applicable for argument types: (java.lang.Long) values: [300]
__________________
New Server - Sage9 on unRAID 2xHD-PVR, HDHR for OTA
Old Server - Sage7 on Win7Pro-i660CPU with 4.6TB, HD-PVR, HDHR OTA, HVR-1850 OTA
Clients - 2xHD-300, 8xHD-200 Extenders, Client+2xPlaceshifter and a WHS which acts as a backup Sage server
Reply With Quote
  #1517  
Old 04-28-2014, 08:14 PM
Slugger Slugger is offline
SageTVaholic
 
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Then groovy can't find the method. Javadocs say it was added in 3.0. You sure you're using 3.3? If you're running from the sage plugin client, I can almost guarantee you're not running 3.x; have a look at the jar in the JARs folder of the Sage install, likely very old. If you're using a standalone client, double check the jar version because groovy says the method doesn't exist.
__________________
Twitter: @ddb_db
Server: Intel i5-4570 Quad Core, 16GB RAM, 1 x 128GB OS SSD (Win7 Pro x64 SP1), 1 x 2TB media drive
Capture: 2 x Colossus
STB Controller: 1 x USB-UIRT
Software:Java 1.7.0_71; SageTV 7.1.9
Clients: 1 x HD300, 2 x HD200, 1 x SageClient, 1 x PlaceShifter
Plugins: Too many to list now...
Reply With Quote
  #1518  
Old 04-28-2014, 08:21 PM
wayner wayner is offline
SageTVaholic
 
Join Date: Jan 2008
Location: Toronto, ON
Posts: 7,491
D'oh - dual commons.net jars. There was a 1.4.1 and I added the 3.3 to both the Groovy Dev Env lib folder and to the Sage Jars folder. To make sure that Sage is using 3.3 do I have to restart the SageTV service or just move the 1.4.1 out of the SageTV jars folder?
__________________
New Server - Sage9 on unRAID 2xHD-PVR, HDHR for OTA
Old Server - Sage7 on Win7Pro-i660CPU with 4.6TB, HD-PVR, HDHR OTA, HVR-1850 OTA
Clients - 2xHD-300, 8xHD-200 Extenders, Client+2xPlaceshifter and a WHS which acts as a backup Sage server
Reply With Quote
  #1519  
Old 04-28-2014, 08:24 PM
Slugger Slugger is offline
SageTVaholic
 
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
A restart would be necessary to guarantee it's using 3.3. And a restart is recommended asap because you've got conflicting jars on the classpath, and that's never a good thing.
__________________
Twitter: @ddb_db
Server: Intel i5-4570 Quad Core, 16GB RAM, 1 x 128GB OS SSD (Win7 Pro x64 SP1), 1 x 2TB media drive
Capture: 2 x Colossus
STB Controller: 1 x USB-UIRT
Software:Java 1.7.0_71; SageTV 7.1.9
Clients: 1 x HD300, 2 x HD200, 1 x SageClient, 1 x PlaceShifter
Plugins: Too many to list now...
Reply With Quote
  #1520  
Old 04-29-2014, 07:27 PM
wayner wayner is offline
SageTVaholic
 
Join Date: Jan 2008
Location: Toronto, ON
Posts: 7,491
Weird stuff - I deleted the old commons.net in the Groovy Dev Env on my client PC and everything runs fine there.

I deleted the old JAR on the Sage server and run the Groovy routine via a SJQ job and I still get the error. I noticed that there were some JARs for Webfeedencoder. I nuked those and restarted but I am still getting the same error. So maybe there is still a conflict. I searched for all JARs in the SageTV folder and the only ones are in the SageTV/JARs folder and are exactly the same as the JARs in my Sagegroovy/lib folder.

Is there any way to troubleshoot/debug this like figuring out exactly what JAR is being used by the JVM within SJQ for org.apache.commons.net.ftp.FTPClient?
__________________
New Server - Sage9 on unRAID 2xHD-PVR, HDHR for OTA
Old Server - Sage7 on Win7Pro-i660CPU with 4.6TB, HD-PVR, HDHR OTA, HVR-1850 OTA
Clients - 2xHD-300, 8xHD-200 Extenders, Client+2xPlaceshifter and a WHS which acts as a backup Sage server
Reply With Quote
Reply


Currently Active Users Viewing This Thread: 4 (0 members and 4 guests)
 

Posting Rules
You may not post new threads
You may not post replies
You may not post attachments
You may not edit your posts

BB code is On
Smilies are On
[IMG] code is On
HTML code is Off

Forum Jump

Similar Threads
Thread Thread Starter Forum Replies Last Post
Plugin: MizookLCD (Alternate SageTV LCDSmartie Plugin) cslatt SageTV Customizations 48 06-11-2012 10:44 AM
SJQv4: Technology Preview Slugger SageTV v7 Customizations 39 12-17-2010 01:17 PM
SageTV Plugin Developers: Any way to see stats for your plugin? mkanet SageTV Software 4 12-12-2010 10:33 PM
MediaPlayer Plugin/STV Import: Winamp Media Player Plugin deria SageTV Customizations 447 12-11-2010 07:38 PM
SJQv4: Design Discussion Slugger SageTV v7 Customizations 26 10-18-2010 08:22 AM


All times are GMT -6. The time now is 03:39 AM.


Powered by vBulletin® Version 3.8.11
Copyright ©2000 - 2023, vBulletin Solutions Inc.
Copyright 2003-2005 SageTV, LLC. All rights reserved.