SageTV Community  

Go Back   SageTV Community > SageTV Development and Customizations > SageTV Customizations
Reload this Page Plugin: Sage Job Queue (SJQ)
Forum Rules FAQs Community Downloads Today's Posts Search

Notices

SageTV Customizations This forums is for discussing and sharing user-created modifications for the SageTV application created by using the SageTV Studio or through the use of external plugins. Use this forum to discuss customizations for SageTV version 6 and earlier, or for the SageTV3 UI.

Closed Thread
Page 29 of 50 « First 192728 29 303139 Last »
 
Thread Tools Search this Thread Display Modes
  #561  
Old 03-17-2009, 07:10 PM
tomfisk's Avatar
tomfisk tomfisk is offline
Sage Advanced User
  
Join Date: Oct 2006
Location: Plainview, MN
Posts: 86
Quote:
Originally Posted by Slugger View Post
Enable debug logging in SageTV, reproduce then get me the output (see a few posts up for what I'm looking for). Are any of your recording directories on a network drive? How large is the media set SJQ is scanning?
Local drives only. Less than a dozen recorded TV shows, 1 imported video. I'll turn on debugging and get back to you when it happens again.
  #562  
Old 03-17-2009, 08:04 PM
Slugger Slugger is offline
SageTVaholic
  
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Quote:
Originally Posted by Peter_h View Post
If a program is being processed, the logo and txt files shouldn't be in danger because the mpeg/ts file is still there right?
Not exactly. Consider what comskip does when processing "TheSimpsons.mpg"...

It creates a file called TheSimpsons.logo.txt and if you tell SJQ to delete txt files then it looks for a file called TheSimpsons.logo.mpg and if it finds it then it won't delete the logo file. Obviously it won't, which is why I added the exception option to the file cleaner.

Quote:
I have comskip set to "cleanup" after it is finished. However, it still outputs 3 files, .edl, txt. and .log.

Can I have the job clear all 3 or do I need separate instances of the cleanup?

For example:

Delete files with an extension matching this regex: edl|txt|log
When no corresponding file has an extension matching this regex: mpg|ts
Never delete files matching this regex:

Is this syntax correct?

Thanks,
Peter
Everything is right, except you'll want to tell SJQ not to delete logo.txt files using the rule I mentioned above.
__________________
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...
  #563  
Old 03-17-2009, 08:05 PM
Slugger Slugger is offline
SageTVaholic
  
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Quote:
Originally Posted by tomfisk View Post
Local drives only. Less than a dozen recorded TV shows, 1 imported video. I'll turn on debugging and get back to you when it happens again.
Interesting... what version of Java are you running?
__________________
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...
  #564  
Old 03-17-2009, 08:47 PM
Slugger Slugger is offline
SageTVaholic
  
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Those helping with the 2.1.2 hanging issues, please upgrade to snapshot 370, it contains more verbose debug output for the QueueLoader and FileCleaner threads. Same type of output, just more of it - I need to know where/when these threads are getting looped - it seems to be while reading the directory listings. When you report findings please let me know which version of Java you're running.
__________________
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...
  #565  
Old 03-18-2009, 07:22 AM
tomfisk's Avatar
tomfisk tomfisk is offline
Sage Advanced User
  
Join Date: Oct 2006
Location: Plainview, MN
Posts: 86
Quote:
Originally Posted by Slugger View Post
Interesting... what version of Java are you running?
Java 1.6.0_11-b03. I've updated to drop 370.

I have the SageTV log from where it failed with build 361 this morning. At some point in this log it looks like SJQ Servlet stopped doing it's thing (?):

Code:
Wed 3/18 7:38:30.969 SJQ ClientMonitor: Started...
Wed 3/18 7:38:30.969 SJQ ClientMonitor: Done...
Wed 3/18 7:38:30.969 SJQ ClientMonitor: Sleeping!
Wed 3/18 7:38:32.024 SJQ Servlet: Request processing started...
Wed 3/18 7:38:32.025 SJQ Servlet: Client restriction check completed...
Wed 3/18 7:38:32.025 SJQ Servlet: Processing 'pop'...
Wed 3/18 7:38:32.026 === GLOBAL OPTIONS ===
Wed 3/18 7:38:32.026 MAXPROCS => 2.0
Wed 3/18 7:38:32.027 === GLOBAL OPTIONS ===
Wed 3/18 7:38:32.027 This client has 4 ClientTask object(s) attached to it.
Wed 3/18 7:38:32.027 === ClientTask 'SHOWANALYZER' ===
MAX => 1.0
RETURNCODE => 0.0
MAXRUNRATIO => false
MAXRUN => -1.0
CPU => NORMAL
Run: H:/Program Files/Dragon Global/ShowAnalyzer/ShowAnalyzer.exe "%c%"
=== ClientTask 'SHOWANALYZER' ===

Wed 3/18 7:38:32.027 === ClientTask 'COMSKIP' ===
MAX => 1.0
RETURNCODE => 1.0
MAXRUNRATIO => false
MAXRUN => -1.0
CPU => NORMAL
Run: H:/sjqc/Comskip.bat "%c%"
=== ClientTask 'COMSKIP' ===

Wed 3/18 7:38:32.027 === ClientTask 'SAGEINACTIVE' ===
MAX => 1.0
RETURNCODE => 0.0
MAXRUNRATIO => false
MAXRUN => -1.0
CPU => NORMAL
Run: H:/sjqc/Clear_Sage_Flag.bat
=== ClientTask 'SAGEINACTIVE' ===

Wed 3/18 7:38:32.027 === ClientTask 'SAGEACTIVE' ===
MAX => 1.0
RETURNCODE => 0.0
MAXRUNRATIO => false
MAXRUN => -1.0
CPU => NORMAL
Run: H:/sjqc/Create_Sage_Flag.bat
=== ClientTask 'SAGEACTIVE' ===

Wed 3/18 7:38:32.028 SJQ Servlet: Request processing completed!
Wed 3/18 7:38:32.031 SJQ Servlet: Request processing started...
Wed 3/18 7:38:32.032 SJQ Servlet: Client restriction check completed...
Wed 3/18 7:38:32.032 SJQ Servlet: Processing 'writeLog'...
Wed 3/18 7:38:32.033 SJQ Servlet: Request processing completed!
Wed 3/18 7:38:32.035 SJQ Servlet: Request processing started...
Wed 3/18 7:38:32.036 SJQ Servlet: Client restriction check completed...
Wed 3/18 7:38:32.037 SJQ Servlet: Processing 'pop'...
Wed 3/18 7:38:32.038 === GLOBAL OPTIONS ===
Wed 3/18 7:38:32.038 MAXPROCS => 2.0
Wed 3/18 7:38:32.040 === GLOBAL OPTIONS ===
Wed 3/18 7:38:32.040 This client has 4 ClientTask object(s) attached to it.
Wed 3/18 7:38:32.041 === ClientTask 'SHOWANALYZER' ===
MAX => 1.0
RETURNCODE => 0.0
MAXRUNRATIO => false
MAXRUN => -1.0
CPU => NORMAL
Run: H:/Program Files/Dragon Global/ShowAnalyzer/ShowAnalyzer.exe "%c%"
=== ClientTask 'SHOWANALYZER' ===

Wed 3/18 7:38:32.041 === ClientTask 'COMSKIP' ===
MAX => 1.0
RETURNCODE => 1.0
MAXRUNRATIO => false
MAXRUN => -1.0
CPU => NORMAL
Run: H:/sjqc/Comskip.bat "%c%"
=== ClientTask 'COMSKIP' ===

Wed 3/18 7:38:32.041 === ClientTask 'SAGEINACTIVE' ===
MAX => 1.0
RETURNCODE => 0.0
MAXRUNRATIO => false
MAXRUN => -1.0
CPU => NORMAL
Run: H:/sjqc/Clear_Sage_Flag.bat
=== ClientTask 'SAGEINACTIVE' ===

Wed 3/18 7:38:32.042 === ClientTask 'SAGEACTIVE' ===
MAX => 1.0
RETURNCODE => 0.0
MAXRUNRATIO => false
MAXRUN => -1.0
CPU => NORMAL
Run: H:/sjqc/Create_Sage_Flag.bat
=== ClientTask 'SAGEACTIVE' ===

Wed 3/18 7:38:32.043 SJQ Servlet: Request processing completed!
Wed 3/18 7:38:32.043 SJQ Servlet: Request processing started...
Wed 3/18 7:38:32.044 SJQ Servlet: Client restriction check completed...
Wed 3/18 7:38:32.045 SJQ Servlet: Processing 'update'...
Wed 3/18 7:38:32.045 SJQ Servlet: Request processing completed!
Wed 3/18 7:38:32.048 SJQ Servlet: Request processing started...
Wed 3/18 7:38:32.049 SJQ Servlet: Client restriction check completed...
Wed 3/18 7:38:32.050 SJQ Servlet: Processing 'writeLog'...
Wed 3/18 7:38:32.051 SJQ Servlet: Request processing completed!
Wed 3/18 7:38:32.096 SJQ Servlet: Request processing started...
Wed 3/18 7:38:32.096 SJQ Servlet: Request processing started...
Wed 3/18 7:38:32.097 SJQ Servlet: Client restriction check completed...
Wed 3/18 7:38:32.097 SJQ Servlet: Processing 'writeLog'...
Wed 3/18 7:38:32.098 SJQ Servlet: Client restriction check completed...
Wed 3/18 7:38:32.098 SJQ Servlet: Processing 'update'...
Wed 3/18 7:38:32.099 SJQ Servlet: Request processing completed!
Wed 3/18 7:38:32.171 SJQ Servlet: Request processing completed!
Wed 3/18 7:38:32.172 SJQ Servlet: Request processing started...
Wed 3/18 7:38:32.173 SJQ Servlet: Client restriction check completed...
Wed 3/18 7:38:32.173 SJQ Servlet: Processing 'pop'...
Wed 3/18 7:38:32.174 === GLOBAL OPTIONS ===
Wed 3/18 7:38:32.174 MAXPROCS => 2.0
Wed 3/18 7:38:32.175 === GLOBAL OPTIONS ===
Wed 3/18 7:38:32.175 This client has 4 ClientTask object(s) attached to it.
Wed 3/18 7:38:32.175 === ClientTask 'SHOWANALYZER' ===
MAX => 1.0
RETURNCODE => 0.0
MAXRUNRATIO => false
MAXRUN => -1.0
CPU => NORMAL
Run: H:/Program Files/Dragon Global/ShowAnalyzer/ShowAnalyzer.exe "%c%"
=== ClientTask 'SHOWANALYZER' ===

Wed 3/18 7:38:32.175 === ClientTask 'COMSKIP' ===
MAX => 1.0
RETURNCODE => 1.0
MAXRUNRATIO => false
MAXRUN => -1.0
CPU => NORMAL
Run: H:/sjqc/Comskip.bat "%c%"
=== ClientTask 'COMSKIP' ===

Wed 3/18 7:38:32.175 === ClientTask 'SAGEINACTIVE' ===
MAX => 1.0
RETURNCODE => 0.0
MAXRUNRATIO => false
MAXRUN => -1.0
CPU => NORMAL
Run: H:/sjqc/Clear_Sage_Flag.bat
=== ClientTask 'SAGEINACTIVE' ===

Wed 3/18 7:38:32.175 === ClientTask 'SAGEACTIVE' ===
MAX => 1.0
RETURNCODE => 0.0
MAXRUNRATIO => false
MAXRUN => -1.0
CPU => NORMAL
Run: H:/sjqc/Create_Sage_Flag.bat
=== ClientTask 'SAGEACTIVE' ===

Wed 3/18 7:38:32.176 SJQ Servlet: Request processing completed!
Wed 3/18 7:38:33.297 SJQ QueueLoader: Cycle started...
Wed 3/18 7:38:33.298 SJQ LogCleaner: Started...
Wed 3/18 7:38:33.299 SJQ FileCleaner: Started...
Wed 3/18 7:38:33.299 SJQ LogCleaner: Server purge completed...
Wed 3/18 7:38:33.380 SJQ QueueLoader: MediaMask retrieved [VT]...
Wed 3/18 7:38:33.380 SJQ QueueLoader: Media list generated [size = 60]...
Wed 3/18 7:38:33.381 SJQ FileCleaner: Video directories scanned [0 file(s) eligible for deletion]...
Wed 3/18 7:38:33.381 SJQ FileCleaner: Done!
Wed 3/18 7:38:58.154 Seeker awoken
Wed 3/18 7:38:58.154 MemStats: Used=24MB Total=44MB Max=532MB
Wed 3/18 7:38:58.155 MARK 1 currRecord=null enc=ATI AVStream Analog Capture clients=[] ir=false
Wed 3/18 7:38:58.155 Seeker in AUTOMATIC mode nextRecord=A[5679805,5679784,"Good Eats",12574@0319.19:30,30] nextTTA=129001845
Wed 3/18 7:38:58.155 newRecord=null
Wed 3/18 7:38:58.155 NOTHING TO RECORD FOR NOW...
Wed 3/18 7:38:58.155 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture clients=[] ir=false
Wed 3/18 7:38:58.156 Seeker in AUTOMATIC mode nextRecord=A[5674428,5674403,"Good Eats",12574@0318.19:00,30] nextTTA=40801845
Wed 3/18 7:38:58.156 newRecord=null
Wed 3/18 7:38:58.156 NOTHING TO RECORD FOR NOW...
Wed 3/18 7:38:58.156 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture #2 clients=[] ir=false
Wed 3/18 7:38:58.156 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Wed 3/18 7:38:58.157 newRecord=null
Wed 3/18 7:38:58.157 NOTHING TO RECORD FOR NOW...
Wed 3/18 7:38:58.157 MARK 1 currRecord=null enc=Silicondust HDHomeRun Tuner 1012A9AB-1 clients=[] ir=false
Wed 3/18 7:38:58.157 Seeker in AUTOMATIC mode nextRecord=A[5670551,5670532,"Nature",31261@0318.16:00,60] nextTTA=30001845
Wed 3/18 7:38:58.157 newRecord=null
Wed 3/18 7:38:58.158 NOTHING TO RECORD FOR NOW...
Wed 3/18 7:38:58.158 MARK 1 currRecord=null enc=Silicondust HDHomeRun Tuner 1012A9AB-0 clients=[] ir=false
Wed 3/18 7:38:58.158 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Wed 3/18 7:38:58.158 newRecord=null
Wed 3/18 7:38:58.158 NOTHING TO RECORD FOR NOW...
Wed 3/18 7:38:58.160 Checking video directories for new files
Wed 3/18 7:38:58.162 Diskspace checking is running
Wed 3/18 7:38:58.163 Verifying existence of all TV media files in database fixDurs=false avoidArchive=true
Wed 3/18 7:38:58.164 Enforcing keep at most limits for the files...
Wed 3/18 7:38:58.164 Seeker clearing unwanted and partial files...
Wed 3/18 7:38:58.165 Seeker waiting for 495 mins.
Wed 3/18 7:38:58.654 Seeker is starting the library import scan...
Wed 3/18 7:38:59.405 Seeker has finished the library import scan.
Wed 3/18 7:40:30.691 SWL: Non-generic favourites monitor started
Wed 3/18 7:40:30.692 SWL: Non-generic favourites monitor sleeping for 180 seconds
Wed 3/18 7:40:30.972 SWL: Ignore channel favourites monitor started
Wed 3/18 7:40:30.973 SWL: Ignore channel favourites monitor sleeping for 180 seconds
Wed 3/18 7:40:31.098 Ministry is waiting for 180 sec
Wed 3/18 7:43:30.673 SWL: Non-generic favourites monitor started
Wed 3/18 7:43:30.674 SWL: Non-generic favourites monitor sleeping for 180 seconds
Wed 3/18 7:43:30.939 SJQ ClientMonitor: Started...
Wed 3/18 7:43:30.940 SJQ ClientMonitor: Done...
Wed 3/18 7:43:30.940 SJQ ClientMonitor: Sleeping!
Wed 3/18 7:43:30.955 SWL: Ignore channel favourites monitor started
Wed 3/18 7:43:30.955 SWL: Ignore channel favourites monitor sleeping for 180 seconds
Wed 3/18 7:43:31.080 Ministry is waiting for 180 sec
Wed 3/18 7:43:32.173 SJQ Servlet: Request processing started...
Wed 3/18 7:43:58.124 Seeker awoken
Wed 3/18 7:43:58.124 MemStats: Used=25MB Total=44MB Max=532MB
Wed 3/18 7:43:58.125 MARK 1 currRecord=null enc=ATI AVStream Analog Capture clients=[] ir=false
Wed 3/18 7:43:58.125 Seeker in AUTOMATIC mode nextRecord=A[5679805,5679784,"Good Eats",12574@0319.19:30,30] nextTTA=128701875
Wed 3/18 7:43:58.126 newRecord=null
Wed 3/18 7:43:58.126 NOTHING TO RECORD FOR NOW...
Wed 3/18 7:43:58.126 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture clients=[] ir=false
Wed 3/18 7:43:58.126 Seeker in AUTOMATIC mode nextRecord=A[5674428,5674403,"Good Eats",12574@0318.19:00,30] nextTTA=40501875
Wed 3/18 7:43:58.126 newRecord=null
Wed 3/18 7:43:58.127 NOTHING TO RECORD FOR NOW...
Wed 3/18 7:43:58.127 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture #2 clients=[] ir=false
Wed 3/18 7:43:58.127 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Wed 3/18 7:43:58.127 newRecord=null
Wed 3/18 7:43:58.127 NOTHING TO RECORD FOR NOW...
Wed 3/18 7:43:58.128 MARK 1 currRecord=null enc=Silicondust HDHomeRun Tuner 1012A9AB-1 clients=[] ir=false
Wed 3/18 7:43:58.128 Seeker in AUTOMATIC mode nextRecord=A[5670551,5670532,"Nature",31261@0318.16:00,60] nextTTA=29701875
Wed 3/18 7:43:58.128 newRecord=null
Wed 3/18 7:43:58.128 NOTHING TO RECORD FOR NOW...
Wed 3/18 7:43:58.128 MARK 1 currRecord=null enc=Silicondust HDHomeRun Tuner 1012A9AB-0 clients=[] ir=false
Wed 3/18 7:43:58.129 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Wed 3/18 7:43:58.129 newRecord=null
Wed 3/18 7:43:58.129 NOTHING TO RECORD FOR NOW...
Wed 3/18 7:43:58.130 Checking video directories for new files
Wed 3/18 7:43:58.133 Diskspace checking is running
Wed 3/18 7:43:58.133 Verifying existence of all TV media files in database fixDurs=false avoidArchive=true
Wed 3/18 7:43:58.134 Enforcing keep at most limits for the files...
Wed 3/18 7:43:58.134 Seeker clearing unwanted and partial files...
Wed 3/18 7:43:58.135 Seeker waiting for 490 mins.
Wed 3/18 7:43:58.624 Seeker is starting the library import scan...
Wed 3/18 7:43:59.376 Seeker has finished the library import scan.
Wed 3/18 7:44:37.999 SageTV.deepSleep(true)
Wed 3/18 7:44:38.000 System power is suspending
Wed 3/18 7:44:38.000 Saving properties file to H:\Program Files\SageTV\SageTV\Sage.properties
Wed 3/18 7:44:38.064 UIManager.deepSleep(true)
Wed 3/18 7:44:38.065 UIManager System power is suspending
Wed 3/18 7:44:38.065 Freeing capture device before entering standby:ATI AVStream Analog Capture
Wed 3/18 7:44:38.066 teardownGraph0 called for device='ATI AVStream Analog Capture'
Wed 3/18 7:44:38.066 teardownGraph0 step-1 
Wed 3/18 7:44:38.066 teardownGraph0 step0 
Wed 3/18 7:44:38.067 teardownGraph0 step1 
Wed 3/18 7:44:38.067 teardownGraph0 step2 
Wed 3/18 7:44:38.069 teardownGraph0 step3 
Wed 3/18 7:44:38.069 teardownGraph0 step4 
Wed 3/18 7:44:38.069 teardownGraph0 step5 
Wed 3/18 7:44:38.069 teardownGraph0 step6. 
Wed 3/18 7:44:38.070 teardownGraph0 pBuilder. 
Wed 3/18 7:44:38.070 teardownGraph0 pGraph. 
Wed 3/18 7:44:38.167 teardownGraph0 step8 
Wed 3/18 7:44:38.167 teardownGraph0 step9 
Wed 3/18 7:44:38.168 teardownGraph0 step10 
Wed 3/18 7:44:38.168 Freeing capture device before entering standby:Hauppauge WinTV PVR PCI II Capture
Wed 3/18 7:44:38.355 teardownGraph0 called for device='Hauppauge WinTV PVR PCI II Capture'
Wed 3/18 7:44:38.355 teardownGraph0 step-1 
Wed 3/18 7:44:38.355 teardownGraph0 step0 
Wed 3/18 7:44:38.355 teardownGraph0 step1 
Wed 3/18 7:44:38.356 teardownGraph0 step2 
Wed 3/18 7:44:38.357 teardownGraph0 step3 
Wed 3/18 7:44:38.357 teardownGraph0 step4 
Wed 3/18 7:44:38.357 teardownGraph0 step5 
Wed 3/18 7:44:38.357 teardownGraph0 step6. 
Wed 3/18 7:44:38.358 teardownGraph0 pBuilder. 
Wed 3/18 7:44:38.358 teardownGraph0 pGraph. 
Wed 3/18 7:44:38.464 teardownGraph0 step8 
Wed 3/18 7:44:38.464 teardownGraph0 step9 
Wed 3/18 7:44:38.464 teardownGraph0 step10 
Wed 3/18 7:44:38.464 Freeing capture device before entering standby:Hauppauge WinTV PVR PCI II Capture #2
Wed 3/18 7:44:38.465 teardownGraph0 called for device='Hauppauge WinTV PVR PCI II Capture'
Wed 3/18 7:44:38.465 teardownGraph0 step-1 
Wed 3/18 7:44:38.465 teardownGraph0 step0 
Wed 3/18 7:44:38.465 teardownGraph0 step1 
Wed 3/18 7:44:38.465 teardownGraph0 step2 
Wed 3/18 7:44:38.466 teardownGraph0 step3 
Wed 3/18 7:44:38.467 teardownGraph0 step4 
Wed 3/18 7:44:38.467 teardownGraph0 step5 
Wed 3/18 7:44:38.467 teardownGraph0 step6. 
Wed 3/18 7:44:38.467 teardownGraph0 pBuilder. 
Wed 3/18 7:44:38.467 teardownGraph0 pGraph. 
Wed 3/18 7:44:38.573 teardownGraph0 step8 
Wed 3/18 7:44:38.573 teardownGraph0 step9 
Wed 3/18 7:44:38.573 teardownGraph0 step10 
Wed 3/18 7:44:38.573 Freeing capture device before entering standby:Silicondust HDHomeRun Tuner 1012A9AB-1
Wed 3/18 7:44:38.576 teardownGraph0 called for device='Silicondust HDHomeRun Tuner 1012A9AB-1'
Wed 3/18 7:44:38.577 QAM:CtrlData.... 0x2cce9180
Wed 3/18 7:44:38.577 QAM: QAM Ctrl relesed Silicondust HDHomeRun Tuner 1012A9AB-1-0
Wed 3/18 7:44:38.577 teardownGraph0 step-1 
Wed 3/18 7:44:38.577 teardownGraph0 step0 
Wed 3/18 7:44:38.577 teardownGraph0 step1 
Wed 3/18 7:44:38.577 teardownGraph0 step2 
Wed 3/18 7:44:38.578 teardownGraph0 step3 
Wed 3/18 7:44:38.578 teardownGraph0 step4 
Wed 3/18 7:44:38.764 teardownGraph0 step5 
Wed 3/18 7:44:38.765 teardownGraph0 step6. 
Wed 3/18 7:44:38.765 teardownGraph0 pBuilder. 
Wed 3/18 7:44:38.765 teardownGraph0 pGraph. 
Wed 3/18 7:44:38.765 teardownGraph0 step8 
Wed 3/18 7:44:38.765 teardownGraph0 step9 
Wed 3/18 7:44:38.766 teardownGraph0 step10 
Wed 3/18 7:44:38.766 Freeing capture device before entering standby:Silicondust HDHomeRun Tuner 1012A9AB-0
Wed 3/18 7:44:38.766 teardownGraph0 called for device='Silicondust HDHomeRun Tuner 1012A9AB-0'
Wed 3/18 7:44:38.766 QAM:CtrlData.... 0x4caff30
Wed 3/18 7:44:38.766 QAM: QAM Ctrl relesed Silicondust HDHomeRun Tuner 1012A9AB-0-0
Wed 3/18 7:44:38.767 teardownGraph0 step-1 
Wed 3/18 7:44:38.767 teardownGraph0 step0 
Wed 3/18 7:44:38.767 teardownGraph0 step1 
Wed 3/18 7:44:38.767 teardownGraph0 step2 
Wed 3/18 7:44:38.768 teardownGraph0 step3 
Wed 3/18 7:44:38.768 teardownGraph0 step4 
Wed 3/18 7:53:06.531 teardownGraph0 step5 
Wed 3/18 7:53:06.674 teardownGraph0 step6. 
Wed 3/18 7:53:06.675 teardownGraph0 pBuilder. 
Wed 3/18 7:53:06.675 teardownGraph0 pGraph. 
Wed 3/18 7:53:06.675 teardownGraph0 step8 
Wed 3/18 7:53:06.675 teardownGraph0 step9 
Wed 3/18 7:53:06.676 teardownGraph0 step10 
Wed 3/18 7:53:06.865 SWL: Ignore channel favourites monitor started
Wed 3/18 7:53:06.865 SWL: Ignore channel favourites monitor sleeping for 180 seconds
Wed 3/18 7:53:06.873 SJQ ClientMonitor: Started...
Wed 3/18 7:53:06.873 SJQ ClientMonitor: Done...
Wed 3/18 7:53:06.874 SJQ ClientMonitor: Sleeping!
Wed 3/18 7:53:06.882 SWL: Non-generic favourites monitor started
Wed 3/18 7:53:06.883 SWL: Non-generic favourites monitor sleeping for 180 seconds
Wed 3/18 7:53:07.220 Scheduler awoken
Wed 3/18 7:53:07.228 Scheduler.updateSchedule() called time=Wed 3/18 7:53:07.228 manual=[] schedules={} scheduleRandSize=1700
Wed 3/18 7:53:07.230 Ministry is waiting for 180 sec
Wed 3/18 7:53:07.320 SageTV.deepSleep(true) is done
Wed 3/18 7:53:13.508 SageTV.deepSleep(false)
Wed 3/18 7:53:13.508 SageTV pausing on wakeup to let drivers load properly...
Wed 3/18 7:53:20.443 Generating album cache...
Wed 3/18 7:53:20.467 Done generating album cache.
Wed 3/18 7:53:20.667 webserver: Reading mime.types
Wed 3/18 7:53:20.725 webserver: read mime types, 176 extensions
Wed 3/18 7:53:29.257 SageTV done waiting on wakeup to let drivers load properly.
Wed 3/18 7:53:29.258 Seeker awoken
Wed 3/18 7:53:29.258 MemStats: Used=26MB Total=44MB Max=532MB
Wed 3/18 7:53:29.259 UIManager.deepSleep(false)
Wed 3/18 7:53:29.259 Seeker removing dead Encoder: ATI AVStream Analog Capture
Wed 3/18 7:53:29.265 SageTV.deepSleep(false) is done
Wed 3/18 7:53:29.265 Seeker removing dead Encoder: Hauppauge WinTV PVR PCI II Capture
Wed 3/18 7:53:29.266 Seeker removing dead Encoder: Hauppauge WinTV PVR PCI II Capture #2
Wed 3/18 7:53:29.266 Seeker removing dead Encoder: Silicondust HDHomeRun Tuner 1012A9AB-1
Wed 3/18 7:53:29.266 Seeker removing dead Encoder: Silicondust HDHomeRun Tuner 1012A9AB-0
Wed 3/18 7:53:29.272 Checking video directories for new files
Wed 3/18 7:53:29.364 Scheduler awoken
Wed 3/18 7:53:29.365 initGraph0 (ver 2.0.2) called capDev='Hauppauge WinTV PVR PCI II Capture-0', Country:'United States of America', TuningMode:Cable, TunerType: Cap:0x10810
Wed 3/18 7:53:29.404 Diskspace checking is running
Wed 3/18 7:53:29.404 Verifying existence of all TV media files in database fixDurs=false avoidArchive=true
Wed 3/18 7:53:29.406 Enforcing keep at most limits for the files...
Wed 3/18 7:53:29.406 Seeker clearing unwanted and partial files...
Wed 3/18 7:53:29.407 Seeker waiting for 153722846657899 mins.
Wed 3/18 7:53:29.858 Seeker is starting the library import scan...
Wed 3/18 7:53:29.912 Add analog filters for device=Hauppauge WinTV PVR PCI II Capture
Wed 3/18 7:53:30.390 Found the pin2 ;)
Wed 3/18 7:53:30.433 Found the pin2 ;)
Wed 3/18 7:53:30.469 switchToConnector0 tuningMode:Cable
Wed 3/18 7:53:30.469 switchToConnector0 751435848 type=1 index=0 country:1 format:1
Wed 3/18 7:53:30.470 Tuning mode:Cable; current tuning type:0 current  tuning model:1
Wed 3/18 7:53:31.025 Crossbar route: video:0, auido:1
Wed 3/18 7:53:31.026 DONE: switchToConnector0 751435848 type=1 index=0
Wed 3/18 7:53:31.029 Set capture brightness: (-128 127)-116 val:-12 hr:0x0 Hauppauge WinTV PVR PCI II Capture-0
Wed 3/18 7:53:31.043 initGraph0 (ver 2.0.2) called capDev='ATI AVStream Analog Capture-0', Country:'United States of America', TuningMode:Cable, TunerType: Cap:0x10800
Wed 3/18 7:53:31.046 Add analog filters for device=ATI AVStream Analog Capture
Wed 3/18 7:53:31.090 Found the pin2 ;)
Wed 3/18 7:53:31.139 Found the pin2 ;)
Wed 3/18 7:53:31.171 switchToConnector0 tuningMode:Cable
Wed 3/18 7:53:31.172 switchToConnector0 80371920 type=1 index=0 country:1 format:1
Wed 3/18 7:53:31.172 Tuning mode:Cable; current tuning type:0 current  tuning model:1
Wed 3/18 7:53:31.215 Seeker has finished the library import scan.
Wed 3/18 7:53:31.215 Seeker invoking System.gc()
Wed 3/18 7:53:31.512 Seeker System.gc() done
Wed 3/18 7:53:31.513 Crossbar route: video:0, auido:3
Wed 3/18 7:53:31.514 DONE: switchToConnector0 80371920 type=1 index=0
Wed 3/18 7:53:31.531 Set capture brightness: (0 255)-127 val:127 hr:0x0 ATI AVStream Analog Capture-0
Wed 3/18 7:53:31.602 initGraph0 (ver 2.0.2) called capDev='Hauppauge WinTV PVR PCI II Capture-1', Country:'United States of America', TuningMode:Cable, TunerType: Cap:0x10810
Wed 3/18 7:53:31.604 Add analog filters for device=Hauppauge WinTV PVR PCI II Capture
Wed 3/18 7:53:31.631 Found the pin2 ;)
Wed 3/18 7:53:31.655 Found the pin2 ;)
Wed 3/18 7:53:31.674 switchToConnector0 tuningMode:Cable
Wed 3/18 7:53:31.675 switchToConnector0 752631816 type=1 index=0 country:1 format:1
Wed 3/18 7:53:31.675 Tuning mode:Cable; current tuning type:0 current  tuning model:1
Wed 3/18 7:53:32.072 Crossbar route: video:0, auido:1
Wed 3/18 7:53:32.072 DONE: switchToConnector0 752631816 type=1 index=0
Wed 3/18 7:53:32.075 Set capture brightness: (-128 127)-116 val:-12 hr:0x0 Hauppauge WinTV PVR PCI II Capture-1
Wed 3/18 7:53:32.088 initGraph0 (ver 2.0.2) called capDev='Silicondust HDHomeRun Tuner 1012A9AB-0-0', Country:'United States of America', TuningMode:Cable, TunerType: Cap:0x49000
Wed 3/18 7:53:33.103 BDA capture is found on location:'Silicondust HDHomeRun Tuner 1012A9AB-0' id:'' for Silicondust HDHomeRun Tuner 1012A9AB-0-0 (@device:sw:{FD0A5AF4-B41D-11D2-9C95-00C04F7971E0}\Silicondust HDHomeRun Tuner 1012A9AB-0) (it's a BDA only).
Wed 3/18 7:53:33.103 Add BDA filters (capture:'@device:sw:{FD0A5AF4-B41D-11D2-9C95-00C04F7971E0}\Silicondust HDHomeRun Tuner 1012A9AB-0') for device=Silicondust HDHomeRun Tuner 1012A9AB-0
Wed 3/18 7:53:33.103 It's a HDHomeRun QAM(US Cable) tuner
Wed 3/18 7:53:33.103 Setup QAM BDATuningSpace (7  0)
Wed 3/18 7:53:33.122 LoadTuneTable QAM
Wed 3/18 7:53:33.151 QAM:Setup BDA QAM
Wed 3/18 7:53:33.222 BDA Filters are loaded
Wed 3/18 7:53:33.223 switchToConnector0 tuningMode:Cable
Wed 3/18 7:53:33.223 initGraph0 (ver 2.0.2) called capDev='Silicondust HDHomeRun Tuner 1012A9AB-1-0', Country:'United States of America', TuningMode:Cable, TunerType: Cap:0x49000

<SageTV guide update content removed>

Wed 3/18 7:53:34.580 NOTHING TO RECORD FOR NOW...
Wed 3/18 7:53:34.580 MARK 1 currRecord=null enc=Hauppauge WinTV PVR PCI II Capture #2 clients=[] ir=false
Wed 3/18 7:53:34.580 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Wed 3/18 7:53:34.580 newRecord=null
Wed 3/18 7:53:34.581 NOTHING TO RECORD FOR NOW...
Wed 3/18 7:53:34.581 MARK 1 currRecord=null enc=Silicondust HDHomeRun Tuner 1012A9AB-1 clients=[] ir=false
Wed 3/18 7:53:34.581 Seeker in AUTOMATIC mode nextRecord=A[5670551,5670532,"Nature",31261@0318.16:00,60] nextTTA=29125422
Wed 3/18 7:53:34.581 newRecord=null
Wed 3/18 7:53:34.581 NOTHING TO RECORD FOR NOW...
Wed 3/18 7:53:34.582 MARK 1 currRecord=null enc=Silicondust HDHomeRun Tuner 1012A9AB-0 clients=[] ir=false
Wed 3/18 7:53:34.582 Seeker in AUTOMATIC mode nextRecord=null nextTTA=9223372036854775807
Wed 3/18 7:53:34.582 newRecord=null
Wed 3/18 7:53:34.582 NOTHING TO RECORD FOR NOW...
Wed 3/18 7:53:34.583 Saving properties file to H:\Program Files\SageTV\SageTV\Sage.properties
Wed 3/18 7:53:34.666 Checking video directories for new files
Wed 3/18 7:53:34.668 Diskspace checking is running
Wed 3/18 7:53:34.671 Verifying existence of all TV media files in database fixDurs=false avoidArchive=true
Wed 3/18 7:53:34.673 Enforcing keep at most limits for the files...
Wed 3/18 7:53:34.673 Seeker clearing unwanted and partial files...
Wed 3/18 7:53:34.673 Seeker waiting for 480 mins.
Wed 3/18 7:53:35.165 Seeker is starting the library import scan...
Wed 3/18 7:53:35.916 Seeker has finished the library import scan.
Wed 3/18 7:54:00.523 SJQ Servlet: Request processing started...
Wed 3/18 7:54:03.303 SJQ Servlet: Request processing started...
Wed 3/18 7:54:06.254 SJQ Servlet: Request processing started...
Wed 3/18 7:54:08.863 SJQ Servlet: Request processing started...
Wed 3/18 7:56:06.852 SWL: Ignore channel favourites monitor started
Wed 3/18 7:56:06.853 SWL: Ignore channel favourites monitor sleeping for 180 seconds
Wed 3/18 7:56:06.868 SWL: Non-generic favourites monitor started
Wed 3/18 7:56:06.868 SWL: Non-generic favourites monitor sleeping for 180 seconds
Wed 3/18 7:56:07.212 Ministry is waiting for 180 sec
  #566  
Old 03-18-2009, 08:27 AM
Slugger Slugger is offline
SageTVaholic
  
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Quote:
Originally Posted by tomfisk View Post
Java 1.6.0_11-b03. I've updated to drop 370.

I have the SageTV log from where it failed with build 361 this morning. At some point in this log it looks like SJQ Servlet stopped doing it's thing (?):
Yeah, the servlet threads get hung up in your case, but the FileCleaner doesn't. So the common issue so far is that the QueueLoader is not completing. The output from build 370 is going to answer a lot of questions for me. I really need you to reproduce this with build 370 and get me that output. When you do, please attach the output instead of pasting it in.

Thanks.
__________________
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...
  #567  
Old 03-18-2009, 02:44 PM
tomfisk's Avatar
tomfisk tomfisk is offline
Sage Advanced User
  
Join Date: Oct 2006
Location: Plainview, MN
Posts: 86
Quote:
Originally Posted by Slugger View Post
Yeah, the servlet threads get hung up in your case, but the FileCleaner doesn't. So the common issue so far is that the QueueLoader is not completing. The output from build 370 is going to answer a lot of questions for me. I really need you to reproduce this with build 370 and get me that output. When you do, please attach the output instead of pasting it in.

Thanks.
OK...just happened. Log is attached.
Attached Files
File Type: txt sagetv_0_extract.txt (104.5 KB, 146 views)
  #568  
Old 03-18-2009, 03:10 PM
Slugger Slugger is offline
SageTVaholic
  
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Quote:
Originally Posted by tomfisk View Post
OK...just happened. Log is attached.
Ok, we're zeroing in on the problem. It's looking like a parser bug of some kind. Snapshot 371 adds some debug tracing to the RulesParser class. Need you to upgrade to 371 and then send me the output the next time it happens.

In the mean time, can you attach your ruleset?

Also, is your CPU being pegged at 100% (or 100% of a core) when SJQ hangs up?
__________________
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...
Last edited by Slugger; 03-18-2009 at 03:22 PM.
  #569  
Old 03-18-2009, 04:20 PM
tomfisk's Avatar
tomfisk tomfisk is offline
Sage Advanced User
  
Join Date: Oct 2006
Location: Plainview, MN
Posts: 86
Quote:
Originally Posted by Slugger View Post
Ok, we're zeroing in on the problem. It's looking like a parser bug of some kind. Snapshot 371 adds some debug tracing to the RulesParser class. Need you to upgrade to 371 and then send me the output the next time it happens.

In the mean time, can you attach your ruleset?

Also, is your CPU being pegged at 100% (or 100% of a core) when SJQ hangs up?
Nope...CPU is definitely not pegging. Ruleset attached and 371 installed.
Attached Files
File Type: txt ruleset.txt (1.3 KB, 161 views)
  #570  
Old 03-19-2009, 09:54 AM
tomfisk's Avatar
tomfisk tomfisk is offline
Sage Advanced User
  
Join Date: Oct 2006
Location: Plainview, MN
Posts: 86
Quote:
Originally Posted by Slugger View Post
Ok, we're zeroing in on the problem. It's looking like a parser bug of some kind. Snapshot 371 adds some debug tracing to the RulesParser class. Need you to upgrade to 371 and then send me the output the next time it happens.
OK...it happened. Log is attached. Hope this helps!
Attached Files
File Type: txt sagetv_0_extract.txt (270.7 KB, 152 views)
  #571  
Old 03-19-2009, 10:16 AM
Slugger Slugger is offline
SageTVaholic
  
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Quote:
Originally Posted by tomfisk View Post
OK...it happened. Log is attached. Hope this helps!
Not what I was expecting. When this happens you can load the login page on the GUI, right? But attempting to submit the password fails? Fails how?

I'm working on a new build to gather some other info... should be ready shortly, but the answers to the above are going to help me in the mean time.
__________________
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...
  #572  
Old 03-19-2009, 10:34 AM
tomfisk's Avatar
tomfisk tomfisk is offline
Sage Advanced User
  
Join Date: Oct 2006
Location: Plainview, MN
Posts: 86
Quote:
Originally Posted by Slugger View Post
Not what I was expecting. When this happens you can load the login page on the GUI, right? But attempting to submit the password fails? Fails how?

I'm working on a new build to gather some other info... should be ready shortly, but the answers to the above are going to help me in the mean time.
Yep...the login page comes up. I type in the password, hit Enter and nothing. Press Login and nothing. If I navigate away from the page then the error "Invalid password!" is displayed before the page changes.
  #573  
Old 03-19-2009, 10:45 AM
Slugger Slugger is offline
SageTVaholic
  
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Ok... please move to snapshot 378... the print statement method of debugging is good for the simple things, but I was starting to put statements everywhere.

378 removes all those noisy print statements and adds a stack dumper. So when things go wrong, load this URL:

http://your_jetty/sjq/SJQ?debug=TRUE

(case sensitive on the debug=TRUE part)

In response, you should get this in your browser:

App trace dumped; check SageTV logs!

And in the SageTV debug logs you should get something like this:

Code:
Thu Mar 19 12:35:31 EDT 2009: SJQ Application Dump
        QueueLoader thread is alive...
                java.lang.Thread.sleep(Native Method)
                com.google.code.sagetvaddons.sjq.server.QueueLoader.run(QueueLoader.java:92)
                java.lang.Thread.run(Unknown Source)
        ClientMonitor thread is alive...
                java.lang.Thread.sleep(Native Method)
                com.google.code.sagetvaddons.sjq.server.ClientMonitor.run(ClientMonitor.java:35)
                java.lang.Thread.run(Unknown Source)
        InteralTaskClient thread is alive...
                java.lang.Thread.sleep(Native Method)
                com.google.code.sagetvaddons.sjq.server.InternalTaskClient.run(InternalTaskClient.java:48)
                java.lang.Thread.run(Unknown Source)
        Most recent LogCleaner thread is dead (this is normal behaviour)!
        Most recent FileCleaner thread is dead (this is normal behaviour)!
This will hopefully tell me what thread is spinning its wheels where or if threads have unexpectedly died off. Please upgrade to 378 and grab this output for me.
__________________
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...
  #574  
Old 03-19-2009, 10:52 AM
tomfisk's Avatar
tomfisk tomfisk is offline
Sage Advanced User
  
Join Date: Oct 2006
Location: Plainview, MN
Posts: 86
Quote:
Originally Posted by Slugger View Post
Ok... please move to snapshot 378... the print statement method of debugging is good for the simple things, but I was starting to put statements everywhere.

378 removes all those noisy print statements and adds a stack dumper. So when things go wrong, load this URL:

http://your_jetty/sjq/SJQ?debug=TRUE

(case sensitive on the debug=TRUE part)

This will hopefully tell me what thread is spinning its wheels where or if threads have unexpectedly died off. Please upgrade to 378 and grab this output for me.
OK...installed 378...should have something soon (as soon as I am outta this meeting with my boss :-)!!!
  #575  
Old 03-19-2009, 04:37 PM
madas madas is offline
Sage Advanced User
  
Join Date: Jun 2008
Posts: 83
I just upgraded to 380 and I cannot login to SJQ at all now. No errors or anything, just sits on the login page

Thu 3/19 18:34:17.073 Thu Mar 19 18:34:17 EDT 2009: SJQ Application Dump
QueueLoader thread is alive...
com.google.code.sagetvaddons.sjq.server.DataStore.getSetting(DataStore.java:824)
com.google.code.sagetvaddons.sjq.server.FileCleaner.<init>(FileCleaner.java:45)
com.google.code.sagetvaddons.sjq.server.QueueLoader.run(QueueLoader.java:47)
java.lang.Thread.run(Unknown Source)
ClientMonitor thread is alive...
java.lang.Thread.sleep(Native Method)
com.google.code.sagetvaddons.sjq.server.ClientMonitor.run(ClientMonitor.java:35)
java.lang.Thread.run(Unknown Source)
InteralTaskClient thread is alive...
com.google.code.sagetvaddons.sjq.server.TaskQueue.pop(TaskQueue.java:120)
com.google.code.sagetvaddons.sjq.server.InternalTaskClient.run(InternalTaskClient.java:39)
java.lang.Thread.run(Unknown Source)
A LogCleaner thread is currently active...
com.google.code.sagetvaddons.sjq.server.DataStore.getSetting(DataStore.java:828)
com.google.code.sagetvaddons.sjq.server.LogCleaner.run(LogCleaner.java:29)
java.lang.Thread.run(Unknown Source)
Most recent FileCleaner thread is dead (this is normal behaviour)!
Last edited by madas; 03-19-2009 at 04:49 PM.
  #576  
Old 03-19-2009, 05:14 PM
Slugger Slugger is offline
SageTVaholic
  
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Quote:
Originally Posted by madas View Post
I just upgraded to 380 and I cannot login to SJQ at all now. No errors or anything, just sits on the login page
Code:
Thu 3/19 18:34:17.073 Thu Mar 19 18:34:17 EDT 2009: SJQ Application Dump
	QueueLoader thread is alive...
		com.google.code.sagetvaddons.sjq.server.DataStore.getSetting(DataStore.java:824)
		com.google.code.sagetvaddons.sjq.server.FileCleaner.<init>(FileCleaner.java:45)
		com.google.code.sagetvaddons.sjq.server.QueueLoader.run(QueueLoader.java:47)
		java.lang.Thread.run(Unknown Source)
	ClientMonitor thread is alive...
		java.lang.Thread.sleep(Native Method)
		com.google.code.sagetvaddons.sjq.server.ClientMonitor.run(ClientMonitor.java:35)
		java.lang.Thread.run(Unknown Source)
	InteralTaskClient thread is alive...
		com.google.code.sagetvaddons.sjq.server.TaskQueue.pop(TaskQueue.java:120)
		com.google.code.sagetvaddons.sjq.server.InternalTaskClient.run(InternalTaskClient.java:39)
		java.lang.Thread.run(Unknown Source)
	A LogCleaner thread is currently active...
		com.google.code.sagetvaddons.sjq.server.DataStore.getSetting(DataStore.java:828)
		com.google.code.sagetvaddons.sjq.server.LogCleaner.run(LogCleaner.java:29)
		java.lang.Thread.run(Unknown Source)
	Most recent FileCleaner thread is dead (this is normal behaviour)!
When you take the stack dump, take 3 or 4 of them about 10-15 seconds apart. What this looks like is that threads are stuck accessing the SQLite database. Three or 4 dumps over the span of 30-60 seconds will give a better picture.

If you can reproduce and get me 3 or 4 dumps from 380 that'd be good. Then go back to 378. Does the same thing happen? Also, if pasting in the stack dumps, please surround them in code tags for easier reading.
__________________
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...
  #577  
Old 03-20-2009, 01:10 AM
madas madas is offline
Sage Advanced User
  
Join Date: Jun 2008
Posts: 83
Here is the debug from 380 (attached)

cannot try the download to 378 at the moment as i'm at an airport. So i'll have to try over the weekend, unless another ver is released since then.

i really miss SJQ now its given up processing! my un-comskipped un-compressed files are unhappy! :-)
Attached Files
File Type: txt sjqdebug.txt (8.1 KB, 148 views)
  #578  
Old 03-20-2009, 09:24 AM
Slugger Slugger is offline
SageTVaholic
  
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
Seems like the various SJQ threads are deadlocked trying to get access to the SQLite database.

This would be so much better if I could reproduce it here, but I've just been unable to. I need to know which thread holds the lock and why it's not giving it up. Seems like the servlet itself is not playing nice or older LogCleaner thread(s) are not completing as expected.

If you're willing to help me track this down, PM me and we can get together over MSN or something on the weekend? We need to attach the JDK JConsole tool to the JVM to get a listing of all the threads that are deadlocked. It'll also tell me which locks each thread holds and which one holds the one everyone else wants to get. So if you don't have the JDK installed, you'll need that then PM me and we'll go from there.
__________________
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...
  #579  
Old 03-21-2009, 08:06 AM
Slugger Slugger is offline
SageTVaholic
  
Join Date: Mar 2007
Location: Kingston, ON
Posts: 4,008
I think I've identified the underlying problem everyone's been having. Please upgrade to snapshot 383. I've still been unable to reproduce the reported symptoms on my system, so without further debug data from a thread dump of a symptomatic JVM, I'm still going at this a bit blind, but I did find a significant synchronization problem in the servlet itself that would possibly manifest itself as sudden hanging or crashing of SJQ.

If you are still having problems with 383 then please generate the stack dumps as described above and get them to me, but then I also really need someone who can reproduce these problems with some regularity to work with me in generating thread dumps from the JVM.

If you move to 383 then please report back your findings.
__________________
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...
  #580  
Old 03-22-2009, 03:48 PM
madas madas is offline
Sage Advanced User
  
Join Date: Jun 2008
Posts: 83
Not looking good.

I cannot even get the DEBUG command to work, just comes back blank (after about a minute of waiting)
Closed Thread
Page 29 of 50 « First 192728 29 303139 Last »

« Previous Thread | Next Thread »

Currently Active Users Viewing This Thread: 1 (0 members and 1 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: Sage Job Queue (SJQ) v3 Slugger SageTV Customizations 1355 07-25-2013 07:44 AM
Sage Job Queue (SJQ) new release notifications Slugger Customization Announcements 3 12-17-2009 09:59 AM
Sage Job Queue Completed tasks problem raffmanlt SageTV Customizations 2 08-18-2009 07:34 PM
Comskip Monitor VS Sage Job Queue SJQ personalt SageTV Customizations 6 03-02-2009 10:27 AM
Plugin: SJQ v1.1.0RC1 Available - Testers Needed Slugger SageTV Customizations 35 04-21-2008 08:12 AM


All times are GMT -6. The time now is 10:00 AM.

Contact Us - SageTV, LLC - Archive - Privacy Statement - Top

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