NextEnd: "Sports event monitor did not start"

[mvallevand]

First chance for an NBA test and no problems with the API here on last night's Raptors game

Code:

2019-03-30 22:10:08.341    [DEBUG][1]    LivePvrData.Data.Net.Responses.StatusResponse [Title=NBA Basketball, Status=Final, IsValid=True, IsActive=False, IsComplete=True, IsError=False, ]
2019-03-30 22:10:08.341    [DEBUG][1]    Sports event has ended
2019-03-30 22:10:08.342    [DEBUG][1]    Monitor status = final
2019-03-30 22:10:08.346    [DEBUG][1]    Check to see if EP030312261146 stop exists
2019-03-30 22:10:08.346    [DEBUG][1]    Extend file written -00:19:51.6580000
2019-03-30 22:10:08.357    [DEBUG][1]    Cancelling wake up request for for 'EP030312261146'
2019-03-30 22:10:08.358    [DEBUG][1]    Cancelling wake up request for for 'EP030312261146-backup'
2019-03-30 22:10:08.364    [DEBUG][1]    CancelAllWakeupRequests
2019-03-30 22:10:08.367    [DEBUG][1]    Checking to cancel live recording
2019-03-30 22:10:09.449    [DEBUG][1]    Cancelled in progress recording

Note I always use the cancel option and because it ended 20 minutes early the recording was stopped.

Martin

[antenna]

You could be onto something with the time zones. I recorded two games over the weekend that were taking place in Eastern or Central time zones, and they extended just fine. I recorded one last night in Pacific Time Zone and it failed. I don't think I had ever considered that connection before.

[mvallevand]

I have tonight's game Lakers / Warriors game scheduled so I can check.

Martin

[antenna]

How'd it go on your end? I recorded the game as a test, too. Same problem as other West games.

Code:

2019-04-04 21:37:01.882    [DEBUG][1]    Schedule oid 15903 Status STATUS_IN_PROGRESS
2019-04-04 21:37:01.883    [DEBUG][1]    Waiting
2019-04-04 21:39:01.894    [DEBUG][1]    Schedule oid 15903 Status STATUS_IN_PROGRESS
2019-04-04 21:39:01.895    [DEBUG][1]    Waiting
2019-04-04 21:41:01.907    [DEBUG][1]    Schedule oid 15903 Status STATUS_IN_PROGRESS
2019-04-04 21:41:02.420    [DEBUG][1]    LivePvrData.Data.Net.Responses.StatusResponse [Title=NBA Basketball, Status=Start Delay, IsValid=True, IsActive=False, IsComplete=False, IsError=False, ]
2019-04-04 21:41:02.420    [DEBUG][1]    Sports event monitor did not start
2019-04-04 21:41:02.420    [DEBUG][1]    Monitor status = none
2019-04-04 21:41:02.430    [DEBUG][1]    Cancelling wake up request for for 'EP030312261183'
2019-04-04 21:41:02.430    [DEBUG][1]    Cancelling wake up request for for 'EP030312261183-backup'
2019-04-04 21:41:02.438    [DEBUG][1]    CancelAllWakeupRequests

[mvallevand]

Thanks for the nudge I forgot to look but my log has the same thing. Lakers Clippers is on tonight but I doubt I well have time to test anything by then. It's a server side change, and my test server software is sitting on another PC I haven't got running right now.

Martin

[antenna]

No problem. Just for my own curiosity's sake, I'll record Blazers/Nuggets on ESPN. I've tested East (working), Central (working), and West (failing) time zones, and that'll complete the picture.

[antenna]

Closing the loop: Mountain and West fail, East and Central seem to work. At least, based on recent recordings. I just checked this morning.

[mvallevand]

I confirmed the issue with the API after midnight here last night watching the behaviour at 2pm I just have to figure out how to do it.

Martin

[mvallevand]

I updated the server with a possible fix. I will try an NHL game that starts later tonight to see how that works. My next NBA late game is Tuesday.

Martin

[antenna]

I had a West game I could record tonight. Looks like a different issue popped up:

Code:

2019-04-07 17:59:02.253    [DEBUG][1]    NextEnd, Version=2.0.6274.30072, Culture=neutral, PublicKeyToken=null
2019-04-07 17:59:02.265    [INFO][1]    About to connect to remote recording service
2019-04-07 17:59:02.285    [DEBUG][1]    RecordingServiceProxy@exit
2019-04-07 17:59:02.291    [DEBUG][1]    About to look for plugin directories in: C:\Users\Public\NPVR\Plugins\
2019-04-07 17:59:02.292    [DEBUG][1]    Skipping non-existant plugin directory: C:\Program Files (x86)\NPVR\Plugins\
2019-04-07 17:59:02.292    [DEBUG][1]    Command Line --oid 15984
2019-04-07 17:59:02.292    [DEBUG][1]    NextPVR extending = False
2019-04-07 17:59:02.292    [DEBUG][1]    Preparing this event
2019-04-07 17:59:02.293    [DEBUG][1]    NBA Basketball Denver Nuggets at Portland Trail Blazers 2019-04-07 18:00:00
2019-04-07 17:59:02.921    [DEBUG][1]    Error from livePVR -a NBA Basketball Denver Nuggets at Portland Trail Blazers 4/8/2019 1:00:00 AM
2019-04-07 17:59:02.921    [ERROR][1]    LivePvrData.Data.Net.Responses.ErrorResponse [Message=Unexpected error, StackTrace=java.lang.NoClassDefFoundError: Could not initialize class com.github.livepvrdata.DataStore
    at com.github.livepvrdata.monitors.EventMonitor.findAlternatives(EventMonitor.java:70)
    at com.github.livepvrdata.monitors.EventMonitor.generateSearchTerms(EventMonitor.java:58)
    at com.github.livepvrdata.monitors.EventMonitor$generateSearchTerms.call(Unknown Source)
    at com.github.livepvrdata.monitors.espn.json.Monitor.execute(Monitor.groovy:170)
    at com.github.livepvrdata.monitors.espn.json.Monitor$execute$0.call(Unknown Source)
    at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callSafe(AbstractCallSite.java:75)
    at com.github.livepvrdata.web.StatusServlet$_doGet_closure1.doCall(StatusServlet.groovy:38)
    at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
    at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
    at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
    at java.lang.reflect.Method.invoke(Method.java:606)
    at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:90)
    at groovy.lang.MetaMethod.doMethodInvoke(MetaMethod.java:233)
    at org.codehaus.groovy.runtime.metaclass.ClosureMetaClass.invokeMethod(ClosureMetaClass.java:272)
    at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:906)
    at groovy.lang.Closure.call(Closure.java:415)
    at groovy.lang.Closure.call(Closure.java:428)
    at org.codehaus.groovy.runtime.IOGroovyMethods.withStream(IOGroovyMethods.java:1249)
    at org.codehaus.groovy.runtime.dgm$708.invoke(Unknown Source)
    at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite$PojoMetaMethodSiteNoUnwrapNoCoerce.invoke(PojoMetaMethodSite.java:271)
    at org.codehaus.groovy.runtime.callsite.PojoMetaMethodSite.call(PojoMetaMethodSite.java:53)
    at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:116)
    at com.github.livepvrdata.web.StatusServlet.doGet(StatusServlet.groovy:36)
    at com.github.livepvrdata.web.StatusServlet$doGet.callCurrent(Unknown Source)
    at com.github.livepvrdata.web.StatusServlet.doPost(StatusServlet.groovy:47)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:650)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:731)
    at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:684)
    at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:503)
    at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
    at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:557)
    at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:231)
    at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1088)
    at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:429)
    at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
    at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1022)
    at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
    at org.eclipse.jetty.server.handler.ContextHandlerCollection.handle(ContextHandlerCollection.java:255)
    at org.eclipse.jetty.server.handler.HandlerCollection.handle(HandlerCollection.java:154)
    at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
    at org.eclipse.jetty.server.Server.handle(Server.java:370)
    at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:494)
    at org.eclipse.jetty.server.AbstractHttpConnection.headerComplete(AbstractHttpConnection.java:971)
    at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.headerComplete(AbstractHttpConnection.java:1033)
    at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:651)
    at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:235)
    at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
    at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:696)
    at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:53)
    at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
    at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
    at java.lang.Thread.run(Thread.java:748)
, IsError=True, ]
2019-04-07 17:59:02.922    [DEBUG][1]    Monitor status = error