Mon 4/6 12:12:09.221 Mon Apr 06 12:12:09 PDT 2009: SJQ Application Dump QueueLoader thread is alive... java.lang.Thread.sleep(Native Method) com.google.code.sagetvaddons.sjq.server.QueueLoader.run(QueueLoader.java:96) 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:34) 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:41) java.lang.Thread.run(Unknown Source) A LogCleaner thread is currently active... org.sqlite.NativeDB.step(Native Method) org.sqlite.DB.execute(DB.java:261) org.sqlite.DB.executeUpdate(DB.java:281) org.sqlite.Stmt.executeUpdate(Stmt.java:103) com.google.code.sagetvaddons.sjq.server.DataStore.clearCompletedTaskLogs(DataStore.java:807) com.google.code.sagetvaddons.sjq.server.LogCleaner.run(LogCleaner.java:32) java.lang.Thread.run(Unknown Source) Most recent FileCleaner thread is dead (this is normal behaviour)! Mon 4/6 12:12:09.221 java.sql.SQLException: database locked Mon 4/6 12:12:09.222 at org.sqlite.DB.execute(DB.java:270) Mon 4/6 12:12:09.222 at org.sqlite.DB.executeUpdate(DB.java:281) Mon 4/6 12:12:09.223 at org.sqlite.PrepStmt.executeUpdate(PrepStmt.java:77) Mon 4/6 12:12:09.223 at com.google.code.sagetvaddons.sjq.server.DataStore.markAsProcessed(DataStore.java:686) Mon 4/6 12:12:09.223 at com.google.code.sagetvaddons.sjq.server.TaskQueue.updateTask(TaskQueue.java:181) Mon 4/6 12:12:09.223 at com.google.code.sagetvaddons.sjq.server.TaskQueue.updateTask(TaskQueue.java:191) Mon 4/6 12:12:09.223 at com.google.code.sagetvaddons.sjq.server.SJQServlet.doPost(SJQServlet.java:184) Mon 4/6 12:12:09.225 at com.google.code.sagetvaddons.sjq.server.SJQServlet.doGet(SJQServlet.java:92) Mon 4/6 12:12:09.227 at javax.servlet.http.HttpServlet.service(HttpServlet.java:707) Mon 4/6 12:12:09.230 at javax.servlet.http.HttpServlet.service(HttpServlet.java:820) Mon 4/6 12:12:09.230 at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:487) Mon 4/6 12:12:09.231 at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:362) Mon 4/6 12:12:09.231 at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216) Mon 4/6 12:12:09.232 at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181) Mon 4/6 12:12:09.233 at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:729) Mon 4/6 12:12:09.233 at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405) Mon 4/6 12:12:09.233 at org.mortbay.jetty.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:206) Mon 4/6 12:12:09.233 at org.mortbay.jetty.handler.HandlerCollection.handle(HandlerCollection.java:114) Mon 4/6 12:12:09.236 at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152) Mon 4/6 12:12:09.239 at org.mortbay.jetty.Server.handle(Server.java:324) Mon 4/6 12:12:09.243 at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:505) Mon 4/6 12:12:09.244 at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:829) Mon 4/6 12:12:09.244 at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:513) Mon 4/6 12:12:09.245 at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211) Mon 4/6 12:12:09.245 at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:380) Mon 4/6 12:12:09.245 at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:395) Mon 4/6 12:12:09.245 at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:488) Mon 4/6 12:12:09.587 2009-04-06 12:12:09.401::WARN: /sjq/SJQ Mon 4/6 12:12:09.588 java.lang.RuntimeException: java.sql.SQLException: database locked Mon 4/6 12:12:09.588 at com.google.code.sagetvaddons.sjq.server.DataStore.markAsProcessed(DataStore.java:692) Mon 4/6 12:12:09.588 at com.google.code.sagetvaddons.sjq.server.TaskQueue.updateTask(TaskQueue.java:181) Mon 4/6 12:12:09.588 at com.google.code.sagetvaddons.sjq.server.TaskQueue.updateTask(TaskQueue.java:191) Mon 4/6 12:12:09.589 at com.google.code.sagetvaddons.sjq.server.SJQServlet.doPost(SJQServlet.java:184) Mon 4/6 12:12:09.593 at com.google.code.sagetvaddons.sjq.server.SJQServlet.doGet(SJQServlet.java:92) Mon 4/6 12:12:09.594 at javax.servlet.http.HttpServlet.service(HttpServlet.java:707) Mon 4/6 12:12:09.594 at javax.servlet.http.HttpServlet.service(HttpServlet.java:820) Mon 4/6 12:12:09.594 at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:487) Mon 4/6 12:12:09.594 at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:362) Mon 4/6 12:12:09.595 at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216) Mon 4/6 12:12:09.596 at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181) Mon 4/6 12:12:09.611 at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:729) Mon 4/6 12:12:09.611 at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405) Mon 4/6 12:12:09.611 at org.mortbay.jetty.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:206) Mon 4/6 12:12:09.612 at org.mortbay.jetty.handler.HandlerCollection.handle(HandlerCollection.java:114) Mon 4/6 12:12:09.612 at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152) Mon 4/6 12:12:09.612 at org.mortbay.jetty.Server.handle(Server.java:324) Mon 4/6 12:12:10.044 at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:505) Mon 4/6 12:12:10.045 at org.mortbay.jetty.HttpConnection$RequestHandler.headerComplete(HttpConnection.java:829) Mon 4/6 12:12:10.045 at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:513) Mon 4/6 12:12:10.045 at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211) Mon 4/6 12:12:10.045 at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:380) Mon 4/6 12:12:10.046 at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:395) Mon 4/6 12:12:10.046 at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:488) Mon 4/6 12:12:10.047 Caused by: java.sql.SQLException: database locked Mon 4/6 12:12:10.047 at org.sqlite.DB.execute(DB.java:270) Mon 4/6 12:12:10.047 at org.sqlite.DB.executeUpdate(DB.java:281) Mon 4/6 12:12:10.047 at org.sqlite.PrepStmt.executeUpdate(PrepStmt.java:77) Mon 4/6 12:12:10.048 at com.google.code.sagetvaddons.sjq.server.DataStore.markAsProcessed(DataStore.java:686) Mon 4/6 12:12:10.049 ... 23 more Mon 4/6 12:12:16.969 2009-04-06 12:12:16.584::WARN: /sjq/SJQ Mon 4/6 12:12:16.970 java.lang.RuntimeException: java.sql.SQLException: database is locked Mon 4/6 12:12:16.970 at com.google.code.sagetvaddons.sjq.server.DataStore.dumpLogs(DataStore.java:734) Mon 4/6 12:12:16.970 at com.google.code.sagetvaddons.sjq.server.Logger.flush(Logger.java:114) Mon 4/6 12:12:16.971 at com.google.code.sagetvaddons.sjq.server.SJQServlet.doPost(SJQServlet.java:492) Mon 4/6 12:12:16.972 at javax.servlet.http.HttpServlet.service(HttpServlet.java:727) Mon 4/6 12:12:16.973 at javax.servlet.http.HttpServlet.service(HttpServlet.java:820) Mon 4/6 12:12:16.973 at org.mortbay.jetty.servlet.ServletHolder.handle(ServletHolder.java:487) Mon 4/6 12:12:16.973 at org.mortbay.jetty.servlet.ServletHandler.handle(ServletHandler.java:362) Mon 4/6 12:12:16.973 at org.mortbay.jetty.security.SecurityHandler.handle(SecurityHandler.java:216) Mon 4/6 12:12:16.973 at org.mortbay.jetty.servlet.SessionHandler.handle(SessionHandler.java:181) Mon 4/6 12:12:16.973 at org.mortbay.jetty.handler.ContextHandler.handle(ContextHandler.java:729) Mon 4/6 12:12:16.974 at org.mortbay.jetty.webapp.WebAppContext.handle(WebAppContext.java:405) Mon 4/6 12:12:16.974 at org.mortbay.jetty.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:206) Mon 4/6 12:12:16.975 at org.mortbay.jetty.handler.HandlerCollection.handle(HandlerCollection.java:114) Mon 4/6 12:12:16.975 at org.mortbay.jetty.handler.HandlerWrapper.handle(HandlerWrapper.java:152) Mon 4/6 12:12:16.976 at org.mortbay.jetty.Server.handle(Server.java:324) Mon 4/6 12:12:16.978 at org.mortbay.jetty.HttpConnection.handleRequest(HttpConnection.java:505) Mon 4/6 12:12:16.979 at org.mortbay.jetty.HttpConnection$RequestHandler.content(HttpConnection.java:843) Mon 4/6 12:12:16.979 at org.mortbay.jetty.HttpParser.parseNext(HttpParser.java:647) Mon 4/6 12:12:16.980 at org.mortbay.jetty.HttpParser.parseAvailable(HttpParser.java:211) Mon 4/6 12:12:16.980 at org.mortbay.jetty.HttpConnection.handle(HttpConnection.java:380) Mon 4/6 12:12:16.980 at org.mortbay.io.nio.SelectChannelEndPoint.run(SelectChannelEndPoint.java:395) Mon 4/6 12:12:16.981 at org.mortbay.thread.QueuedThreadPool$PoolThread.run(QueuedThreadPool.java:488) Mon 4/6 12:12:16.981 Caused by: java.sql.SQLException: database is locked Mon 4/6 12:12:16.982 at org.sqlite.DB.throwex(DB.java:288) Mon 4/6 12:12:16.982 at org.sqlite.DB.executeBatch(DB.java:236) Mon 4/6 12:12:16.982 at org.sqlite.PrepStmt.executeBatch(PrepStmt.java:83) Mon 4/6 12:12:16.987 at com.google.code.sagetvaddons.sjq.server.DataStore.dumpLogs(DataStore.java:723) Mon 4/6 12:12:17.004 ... 21 more Mon 4/6 12:12:17.015 === GLOBAL OPTIONS === Mon 4/6 12:12:17.016 MAXPROCS => 7.0 Mon 4/6 12:12:17.017 === GLOBAL OPTIONS === Mon 4/6 12:12:17.017 This client has 8 ClientTask object(s) attached to it. Mon 4/6 12:12:17.018 === ClientTask 'AVIEDLCUT' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\AVIEdlCut.bat "%c%" === ClientTask 'AVIEDLCUT' === Mon 4/6 12:12:17.018 === ClientTask 'SHOWANALYZER' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMENS.bat "%c%" === ClientTask 'SHOWANALYZER' === Mon 4/6 12:12:17.019 === ClientTask 'PASS1XVIDNT' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\SJQpenguin\SJQmediashrinkNT.bat "%c%" /avi /cliBitrate 800 /horizontalScale 448 /onePass /divx /mp3 === ClientTask 'PASS1XVIDNT' === Mon 4/6 12:12:17.020 === ClientTask 'SHOWANALYZERNT' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMENSNT.bat "%c%" === ClientTask 'SHOWANALYZERNT' === Mon 4/6 12:12:17.020 === ClientTask 'COPYEDLJ' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLJ.bat "%c%" === ClientTask 'COPYEDLJ' === Mon 4/6 12:12:17.020 === ClientTask 'PASS1XVID' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\SJQpenguin\SJQmediashrink.bat "%c%" /avi /cliBitrate 800 /horizontalScale 448 /onePass /divx /mp3 === ClientTask 'PASS1XVID' === Mon 4/6 12:12:17.021 === ClientTask 'COPYEDLNAS' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLNAS.bat "%c%" === ClientTask 'COPYEDLNAS' === Mon 4/6 12:12:17.021 === ClientTask 'FIXMPGMEN' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMEN.bat "%c%" === ClientTask 'FIXMPGMEN' === Mon 4/6 12:12:17.560 Mon Apr 06 12:12:17 PDT 2009: SJQ Application Dump QueueLoader thread is alive... java.lang.Thread.sleep(Native Method) com.google.code.sagetvaddons.sjq.server.QueueLoader.run(QueueLoader.java:96) 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:34) java.lang.Thread.run(Unknown Source) InteralTaskClient thread is alive... java.lang.Thread.getStackTrace(Unknown Source) com.google.code.sagetvaddons.sjq.server.Butler.dumpAppState(Butler.java:201) com.google.code.sagetvaddons.sjq.server.Butler.dumpAppTrace(Butler.java:58) com.google.code.sagetvaddons.sjq.server.DataStore.markAsProcessed(DataStore.java:690) com.google.code.sagetvaddons.sjq.server.TaskQueue.updateTask(TaskQueue.java:181) com.google.code.sagetvaddons.sjq.server.TaskQueue.updateTask(TaskQueue.java:191) com.google.code.sagetvaddons.sjq.server.InternalTaskClient.run(InternalTaskClient.java:47) 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)! Mon 4/6 12:12:17.561 java.sql.SQLException: database locked Mon 4/6 12:12:17.561 at org.sqlite.DB.execute(DB.java:270) Mon 4/6 12:12:17.561 at org.sqlite.DB.executeUpdate(DB.java:281) Mon 4/6 12:12:17.561 at org.sqlite.PrepStmt.executeUpdate(PrepStmt.java:77) Mon 4/6 12:12:17.561 at com.google.code.sagetvaddons.sjq.server.DataStore.markAsProcessed(DataStore.java:686) Mon 4/6 12:12:17.561 at com.google.code.sagetvaddons.sjq.server.TaskQueue.updateTask(TaskQueue.java:181) Mon 4/6 12:12:17.561 at com.google.code.sagetvaddons.sjq.server.TaskQueue.updateTask(TaskQueue.java:191) Mon 4/6 12:12:17.562 at com.google.code.sagetvaddons.sjq.server.InternalTaskClient.run(InternalTaskClient.java:47) Mon 4/6 12:12:17.562 at java.lang.Thread.run(Unknown Source) Mon 4/6 12:12:17.572 Exception in thread "SJQ-InternalTaskClnt" Mon 4/6 12:12:17.572 java.lang.RuntimeException: java.sql.SQLException: database locked Mon 4/6 12:12:17.573 at com.google.code.sagetvaddons.sjq.server.DataStore.markAsProcessed(DataStore.java:692) Mon 4/6 12:12:17.573 at com.google.code.sagetvaddons.sjq.server.TaskQueue.updateTask(TaskQueue.java:181) Mon 4/6 12:12:17.573 at com.google.code.sagetvaddons.sjq.server.TaskQueue.updateTask(TaskQueue.java:191) Mon 4/6 12:12:17.579 at com.google.code.sagetvaddons.sjq.server.InternalTaskClient.run(InternalTaskClient.java:47) Mon 4/6 12:12:17.580 at java.lang.Thread.run(Unknown Source) Mon 4/6 12:12:17.580 Caused by: java.sql.SQLException: database locked Mon 4/6 12:12:17.580 at org.sqlite.DB.execute(DB.java:270) Mon 4/6 12:12:17.582 at org.sqlite.DB.executeUpdate(DB.java:281) Mon 4/6 12:12:17.583 at org.sqlite.PrepStmt.executeUpdate(PrepStmt.java:77) Mon 4/6 12:12:17.584 at com.google.code.sagetvaddons.sjq.server.DataStore.markAsProcessed(DataStore.java:686) Mon 4/6 12:12:17.584 ... 4 more Mon 4/6 12:12:19.587 === GLOBAL OPTIONS === Mon 4/6 12:12:19.587 MAXPROCS => 7.0 Mon 4/6 12:12:19.587 === GLOBAL OPTIONS === Mon 4/6 12:12:19.587 This client has 8 ClientTask object(s) attached to it. Mon 4/6 12:12:19.587 === ClientTask 'AVIEDLCUT' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\AVIEdlCut.bat "%c%" === ClientTask 'AVIEDLCUT' === Mon 4/6 12:12:19.588 === ClientTask 'SHOWANALYZER' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMENS.bat "%c%" === ClientTask 'SHOWANALYZER' === Mon 4/6 12:12:19.588 === ClientTask 'PASS1XVIDNT' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\SJQpenguin\SJQmediashrinkNT.bat "%c%" /avi /cliBitrate 800 /horizontalScale 448 /onePass /divx /mp3 === ClientTask 'PASS1XVIDNT' === Mon 4/6 12:12:19.588 === ClientTask 'SHOWANALYZERNT' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMENSNT.bat "%c%" === ClientTask 'SHOWANALYZERNT' === Mon 4/6 12:12:19.588 === ClientTask 'COPYEDLJ' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLJ.bat "%c%" === ClientTask 'COPYEDLJ' === Mon 4/6 12:12:19.588 === ClientTask 'PASS1XVID' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\SJQpenguin\SJQmediashrink.bat "%c%" /avi /cliBitrate 800 /horizontalScale 448 /onePass /divx /mp3 === ClientTask 'PASS1XVID' === Mon 4/6 12:12:19.588 === ClientTask 'COPYEDLNAS' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLNAS.bat "%c%" === ClientTask 'COPYEDLNAS' === Mon 4/6 12:12:19.588 === ClientTask 'FIXMPGMEN' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMEN.bat "%c%" === ClientTask 'FIXMPGMEN' === Mon 4/6 12:12:19.808 === GLOBAL OPTIONS === Mon 4/6 12:12:19.809 MAXPROCS => 7.0 Mon 4/6 12:12:19.809 === GLOBAL OPTIONS === Mon 4/6 12:12:19.809 This client has 8 ClientTask object(s) attached to it. Mon 4/6 12:12:19.819 === ClientTask 'AVIEDLCUT' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\AVIEdlCut.bat "%c%" === ClientTask 'AVIEDLCUT' === Mon 4/6 12:12:19.820 === ClientTask 'SHOWANALYZER' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMENS.bat "%c%" === ClientTask 'SHOWANALYZER' === Mon 4/6 12:12:19.821 === ClientTask 'PASS1XVIDNT' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\SJQpenguin\SJQmediashrinkNT.bat "%c%" /avi /cliBitrate 800 /horizontalScale 448 /onePass /divx /mp3 === ClientTask 'PASS1XVIDNT' === Mon 4/6 12:12:19.821 === ClientTask 'SHOWANALYZERNT' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMENSNT.bat "%c%" === ClientTask 'SHOWANALYZERNT' === Mon 4/6 12:12:19.822 === ClientTask 'COPYEDLJ' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLJ.bat "%c%" === ClientTask 'COPYEDLJ' === Mon 4/6 12:12:19.823 === ClientTask 'PASS1XVID' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\SJQpenguin\SJQmediashrink.bat "%c%" /avi /cliBitrate 800 /horizontalScale 448 /onePass /divx /mp3 === ClientTask 'PASS1XVID' === Mon 4/6 12:12:19.823 === ClientTask 'COPYEDLNAS' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLNAS.bat "%c%" === ClientTask 'COPYEDLNAS' === Mon 4/6 12:12:19.825 === ClientTask 'FIXMPGMEN' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMEN.bat "%c%" === ClientTask 'FIXMPGMEN' === Mon 4/6 12:12:19.852 === GLOBAL OPTIONS === Mon 4/6 12:12:19.853 MAXPROCS => 7.0 Mon 4/6 12:12:19.854 === GLOBAL OPTIONS === Mon 4/6 12:12:19.854 This client has 8 ClientTask object(s) attached to it. Mon 4/6 12:12:19.855 === ClientTask 'AVIEDLCUT' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\AVIEdlCut.bat "%c%" === ClientTask 'AVIEDLCUT' === Mon 4/6 12:12:19.855 === ClientTask 'SHOWANALYZER' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMENS.bat "%c%" === ClientTask 'SHOWANALYZER' === Mon 4/6 12:12:19.855 === ClientTask 'PASS1XVIDNT' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\SJQpenguin\SJQmediashrinkNT.bat "%c%" /avi /cliBitrate 800 /horizontalScale 448 /onePass /divx /mp3 === ClientTask 'PASS1XVIDNT' === Mon 4/6 12:12:19.855 === ClientTask 'SHOWANALYZERNT' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMENSNT.bat "%c%" === ClientTask 'SHOWANALYZERNT' === Mon 4/6 12:12:19.855 === ClientTask 'COPYEDLJ' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLJ.bat "%c%" === ClientTask 'COPYEDLJ' === Mon 4/6 12:12:19.855 === ClientTask 'PASS1XVID' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\SJQpenguin\SJQmediashrink.bat "%c%" /avi /cliBitrate 800 /horizontalScale 448 /onePass /divx /mp3 === ClientTask 'PASS1XVID' === Mon 4/6 12:12:19.855 === ClientTask 'COPYEDLNAS' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLNAS.bat "%c%" === ClientTask 'COPYEDLNAS' === Mon 4/6 12:12:19.856 === ClientTask 'FIXMPGMEN' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMEN.bat "%c%" === ClientTask 'FIXMPGMEN' === Mon 4/6 12:12:21.566 === GLOBAL OPTIONS === Mon 4/6 12:12:21.567 MAXPROCS => 7.0 Mon 4/6 12:12:21.567 === GLOBAL OPTIONS === Mon 4/6 12:12:21.567 This client has 8 ClientTask object(s) attached to it. Mon 4/6 12:12:21.567 === ClientTask 'AVIEDLCUT' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\AVIEdlCut.bat "%c%" === ClientTask 'AVIEDLCUT' === Mon 4/6 12:12:21.567 === ClientTask 'SHOWANALYZER' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMENS.bat "%c%" === ClientTask 'SHOWANALYZER' === Mon 4/6 12:12:21.567 === ClientTask 'PASS1XVIDNT' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\SJQpenguin\SJQmediashrinkNT.bat "%c%" /avi /cliBitrate 800 /horizontalScale 448 /onePass /divx /mp3 === ClientTask 'PASS1XVIDNT' === Mon 4/6 12:12:21.567 === ClientTask 'SHOWANALYZERNT' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMENSNT.bat "%c%" === ClientTask 'SHOWANALYZERNT' === Mon 4/6 12:12:21.568 === ClientTask 'COPYEDLJ' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLJ.bat "%c%" === ClientTask 'COPYEDLJ' === Mon 4/6 12:12:21.568 === ClientTask 'PASS1XVID' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\SJQpenguin\SJQmediashrink.bat "%c%" /avi /cliBitrate 800 /horizontalScale 448 /onePass /divx /mp3 === ClientTask 'PASS1XVID' === Mon 4/6 12:12:21.568 === ClientTask 'COPYEDLNAS' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLNAS.bat "%c%" === ClientTask 'COPYEDLNAS' === Mon 4/6 12:12:21.568 === ClientTask 'FIXMPGMEN' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMEN.bat "%c%" === ClientTask 'FIXMPGMEN' === Mon 4/6 12:12:39.066 SWL: Non-generic favourites monitor started Mon 4/6 12:12:39.067 SWL: Non-generic favourites monitor sleeping for 180 seconds Mon 4/6 12:12:39.067 SWL: Ignore channel favourites monitor started Mon 4/6 12:12:39.068 SWL: Ignore channel favourites monitor sleeping for 180 seconds Mon 4/6 12:12:55.137 === GLOBAL OPTIONS === Mon 4/6 12:12:55.158 MAXPROCS => 2.0 Mon 4/6 12:12:55.158 === GLOBAL OPTIONS === Mon 4/6 12:12:55.159 This client has 6 ClientTask object(s) attached to it. Mon 4/6 12:12:55.159 === ClientTask 'AVIEDLCUT' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\AVIEdlCut.bat "%c%" === ClientTask 'AVIEDLCUT' === Mon 4/6 12:12:55.159 === ClientTask 'SHOWANALYZER' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMENS.bat "%c%" === ClientTask 'SHOWANALYZER' === Mon 4/6 12:12:55.159 === ClientTask 'COPYEDLJ' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLJ.bat "%c%" === ClientTask 'COPYEDLJ' === Mon 4/6 12:12:55.159 === ClientTask 'PASS1XVID' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\SJQpenguin\SJQmediashrink.bat "%c%" /avi /cliBitrate 800 /horizontalScale 448 /onePass /divx /mp3 === ClientTask 'PASS1XVID' === Mon 4/6 12:12:55.159 === ClientTask 'COPYEDLNAS' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLNAS.bat "%c%" === ClientTask 'COPYEDLNAS' === Mon 4/6 12:12:55.160 === ClientTask 'FIXMPGMEN' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMEN.bat "%c%" === ClientTask 'FIXMPGMEN' === Mon 4/6 12:13:04.708 VF processing job null nPlayin=false Mon 4/6 12:13:04.709 VF thread is now waiting for 0:00:00.000 Mon 4/6 12:14:33.813 VF processing job null nPlayin=false Mon 4/6 12:14:33.815 VF thread is now waiting for 0:00:00.000 Mon 4/6 12:14:36.496 BAD Remove of non-existent MediaFile[id=2077534 A[1875213,1875159,"Matlock",17098@0405.10:00,60] host=janedserver encodedBy=Hauppauge WinTV 418 Video Capture DVD Long Play format=AVI 0:50:15 974 kbps [#0 Video[MPEG4-Video 29.97 fps 448x336 4:3 progressive]#1 Audio[MP2 48000 Hz 2 channels 160 kbps idx=1]] \\GForceNAS\sagetvnas\sagetv\Matlock-TheWitnessKillings-1875213-0.avi, Seg0[Sun 4/5 10:00:00.006-Sun 4/5 10:50:15.706]] idxCode=0 Mon 4/6 12:14:42.786 EventThread-SAGETV_PROCESS_LOCAL_UI Hang Detected - hang time = 750 Mon 4/6 12:14:43.536 EventThread-SAGETV_PROCESS_LOCAL_UI Hang Detected - hang time = 1500 Mon 4/6 12:14:44.286 EventThread-SAGETV_PROCESS_LOCAL_UI Hang Detected - hang time = 2250 Mon 4/6 12:14:45.036 EventThread-SAGETV_PROCESS_LOCAL_UI Hang Detected - hang time = 3000 Mon 4/6 12:14:45.787 EventThread-SAGETV_PROCESS_LOCAL_UI Hang Detected - hang time = 3751 Mon 4/6 12:14:46.537 EventThread-SAGETV_PROCESS_LOCAL_UI Hang Detected - hang time = 4501 Mon 4/6 12:14:52.815 VF processing job null nPlayin=false Mon 4/6 12:14:52.815 VF thread is now waiting for 0:00:00.000 Mon 4/6 12:14:52.858 Saving properties file to C:\Program Files\SageTV\SageTV\SageClient.properties Mon 4/6 12:15:39.068 SWL: Ignore channel favourites monitor started Mon 4/6 12:15:39.069 SWL: Ignore channel favourites monitor sleeping for 180 seconds Mon 4/6 12:15:39.069 SWL: Non-generic favourites monitor started Mon 4/6 12:15:39.070 SWL: Non-generic favourites monitor sleeping for 180 seconds Mon 4/6 12:17:21.606 === GLOBAL OPTIONS === Mon 4/6 12:17:21.606 MAXPROCS => 7.0 Mon 4/6 12:17:21.606 === GLOBAL OPTIONS === Mon 4/6 12:17:21.607 This client has 8 ClientTask object(s) attached to it. Mon 4/6 12:17:21.607 === ClientTask 'AVIEDLCUT' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\AVIEdlCut.bat "%c%" === ClientTask 'AVIEDLCUT' === Mon 4/6 12:17:21.607 === ClientTask 'SHOWANALYZER' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMENS.bat "%c%" === ClientTask 'SHOWANALYZER' === Mon 4/6 12:17:21.607 === ClientTask 'PASS1XVIDNT' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\SJQpenguin\SJQmediashrinkNT.bat "%c%" /avi /cliBitrate 800 /horizontalScale 448 /onePass /divx /mp3 === ClientTask 'PASS1XVIDNT' === Mon 4/6 12:17:21.607 === ClientTask 'SHOWANALYZERNT' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMENSNT.bat "%c%" === ClientTask 'SHOWANALYZERNT' === Mon 4/6 12:17:21.607 === ClientTask 'COPYEDLJ' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLJ.bat "%c%" === ClientTask 'COPYEDLJ' === Mon 4/6 12:17:21.607 === ClientTask 'PASS1XVID' === MAX => 2.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\SJQpenguin\SJQmediashrink.bat "%c%" /avi /cliBitrate 800 /horizontalScale 448 /onePass /divx /mp3 === ClientTask 'PASS1XVID' === Mon 4/6 12:17:21.607 === ClientTask 'COPYEDLNAS' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLNAS.bat "%c%" === ClientTask 'COPYEDLNAS' === Mon 4/6 12:17:21.608 === ClientTask 'FIXMPGMEN' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMEN.bat "%c%" === ClientTask 'FIXMPGMEN' === Mon 4/6 12:17:55.177 === GLOBAL OPTIONS === Mon 4/6 12:17:55.178 MAXPROCS => 2.0 Mon 4/6 12:17:55.178 === GLOBAL OPTIONS === Mon 4/6 12:17:55.178 This client has 6 ClientTask object(s) attached to it. Mon 4/6 12:17:55.178 === ClientTask 'AVIEDLCUT' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\AVIEdlCut.bat "%c%" === ClientTask 'AVIEDLCUT' === Mon 4/6 12:17:55.178 === ClientTask 'SHOWANALYZER' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMENS.bat "%c%" === ClientTask 'SHOWANALYZER' === Mon 4/6 12:17:55.178 === ClientTask 'COPYEDLJ' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLJ.bat "%c%" === ClientTask 'COPYEDLJ' === Mon 4/6 12:17:55.178 === ClientTask 'PASS1XVID' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\SJQpenguin\SJQmediashrink.bat "%c%" /avi /cliBitrate 800 /horizontalScale 448 /onePass /divx /mp3 === ClientTask 'PASS1XVID' === Mon 4/6 12:17:55.178 === ClientTask 'COPYEDLNAS' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => NORMAL Run: S:\mysagelocal\CopyEDLNAS.bat "%c%" === ClientTask 'COPYEDLNAS' === Mon 4/6 12:17:55.179 === ClientTask 'FIXMPGMEN' === MAX => 1.0 RETURNCODE => 0.0 MAXRUNRATIO => false MAXRUN => -1.0 CPU => BELOWNORMAL Run: S:\mysagelocal\StartFXMPGMEN.bat "%c%" === ClientTask 'FIXMPGMEN' === Mon 4/6 12:18:39.070 SWL: Non-generic favourites monitor started Mon 4/6 12:18:39.071 SWL: Non-generic favourites monitor sleeping for 180 seconds Mon 4/6 12:18:39.071 SWL: Ignore channel favourites monitor started Mon 4/6 12:18:39.072 SWL: Ignore channel favourites monitor sleeping for 180 seconds Mon 4/6 12:19:55.471 VF processing job null nPlayin=false Mon 4/6 12:19:55.472 VF thread is now waiting for 0:00:00.000 Mon 4/6 12:21:39.072 SWL: Non-generic favourites monitor started Mon 4/6 12:21:39.073 SWL: Non-generic favourites monitor sleeping for 180 seconds Mon 4/6 12:21:39.073 SWL: Ignore channel favourites monitor started Mon 4/6 12:21:39.074 SWL: Ignore channel favourites monitor sleeping for 180 seconds Mon 4/6 12:21:55.294 Mon Apr 06 12:21:55 PDT 2009: SJQ Application Dump QueueLoader thread is alive... java.lang.Thread.sleep(Native Method) com.google.code.sagetvaddons.sjq.server.QueueLoader.run(QueueLoader.java:96) 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:34) java.lang.Thread.run(Unknown Source) InternalTaskClient thread is dead! Most recent LogCleaner thread is dead (this is normal behaviour)! Most recent FileCleaner thread is dead (this is normal behaviour)!