Scheduled Recordings Failed (MP 1.22) (1 Viewer)

joecrow · May 1, 2019

Sorry not sure what happened there, attached now.

Owlsroost · May 1, 2019

The [ERROR] entries in TVService.log do point to a network problem:

Code:

[2019-05-01 19:01:23,334] [Log    ] [7        ] [DEBUG] - EpgGrabber:_epgTimer_Elapsed: timerInterval changed from 1s to 30s
[2019-05-01 19:01:23,336] [EPG    ] [DVB EPG timer] [INFO ] - Grab for card:#3 transponder #1/3 channel: Das Erste HD
[2019-05-01 19:01:23,341] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2019-05-01 19:01:23,342] [EPG    ] [DVB EPG timer] [INFO ] - EpgCard: grab epg on card: #3 transponder: #0 ch:Das Erste HD
[2019-05-01 19:01:23,354] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetWakeupTimer()
[2019-05-01 19:01:23,356] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting Reboot: 05/05/2019 11:00:00
[2019-05-01 19:01:23,367] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Set wakeup timer to wakeup system at 05/05/2019 10:59:00
[2019-05-01 19:01:23,368] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: CheckForStandby()
[2019-05-01 19:01:25,353] [Log    ] [DVB EPG timer] [ERROR] - Exception   :System.Net.Sockets.SocketException (0x80004005): An attempt was made to access a socket in a way forbidden by its access permissions 192.168.178.100:31456

Server stack trace:
   at System.Net.Sockets.Socket.Connect(IPAddress[] addresses, Int32 port)
   at System.Runtime.Remoting.Channels.RemoteConnection.CreateNewSocket(AddressFamily family)
   at System.Runtime.Remoting.Channels.RemoteConnection.CreateNewSocket()
   at System.Runtime.Remoting.Channels.RemoteConnection.GetSocket()
   at System.Runtime.Remoting.Channels.SocketCache.GetSocket(String machinePortAndSid, Boolean openNew)
   at System.Runtime.Remoting.Channels.Tcp.TcpClientTransportSink.SendRequestWithRetry(IMessage msg, ITransportHeaders requestHeaders, Stream requestStream)
   at System.Runtime.Remoting.Channels.Tcp.TcpClientTransportSink.ProcessMessage(IMessage msg, ITransportHeaders requestHeaders, Stream requestStream, ITransportHeaders& responseHeaders, Stream& responseStream)
   at System.Runtime.Remoting.Channels.BinaryClientFormatterSink.SyncProcessMessage(IMessage msg)

Exception rethrown at [0]:
   at System.Runtime.Remoting.Proxies.RealProxy.HandleReturnMessage(IMessage reqMsg, IMessage retMsg)
   at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke(MessageData& msgData, Int32 type)
   at TvControl.IController.IsRecording(IUser& user)
   at TvService.EpgCard.IsCardIdle(Int32 cardId)
   at TvService.EpgCard.GrabEpgForChannel(Channel channel, IChannel tuning, Card card)
   at TvService.EpgCard.GrabEpg()
   at TvService.EpgGrabber.GrabEpgOnCard(EpgCard epgCard)
   at TvService.EpgGrabber._epgTimer_Elapsed(Object sender, ElapsedEventArgs e)

[2019-05-01 19:01:25,378] [Log    ] [PS StandbyWakeup] [ERROR] - EpgGrabbing: unable to connect to controller at: NEWCORWIN
[2019-05-01 19:01:27,386] [Log    ] [PS StandbyWakeup] [ERROR] - EpgGrabbing: unable to connect to controller at: NEWCORWIN
[2019-05-01 19:01:29,395] [Log    ] [PS StandbyWakeup] [ERROR] - EpgGrabbing: unable to connect to controller at: NEWCORWIN
[2019-05-01 19:01:31,404] [Log    ] [PS StandbyWakeup] [ERROR] - EpgGrabbing: unable to connect to controller at: NEWCORWIN
[2019-05-01 19:01:33,412] [Log    ] [PS StandbyWakeup] [ERROR] - EpgGrabbing: unable to connect to controller at: NEWCORWIN
[2019-05-01 19:01:34,681] [Log    ] [3        ] [DEBUG] - Detecting BDA/WDM devices...
[2019-05-01 19:01:34,683] [Log    ] [3        ] [DEBUG] - Detect legacy AM filter devices
[2019-05-01 19:01:34,690] [Log    ] [3        ] [DEBUG] - Detect AM KS crossbar devices
[2019-05-01 19:01:34,692] [Log    ] [3        ] [DEBUG] - Detect AM KS TV tuner devices
[2019-05-01 19:01:34,693] [Log    ] [3        ] [DEBUG] - Detect BDA source devices
[2019-05-01 19:01:35,418] [Log    ] [PS StandbyWakeup] [ERROR] - EpgGrabbing: unable to connect to controller at: NEWCORWIN
[2019-05-01 19:01:35,421] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetStandbyMode(StandbyAllowed)
[2019-05-01 19:01:35,423] [Log    ] [PS StandbyWakeup] [INFO ] - PS: System changed from busy state to idle state
[2019-05-01 19:01:35,424] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: System is idle and may go to standby
[2019-05-01 19:01:35,427] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Active standby is disabled - standby is handled by Windows
[2019-05-01 19:01:50,470] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2019-05-01 19:01:50,475] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2019-05-01 19:01:50,489] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetWakeupTimer()
[2019-05-01 19:01:50,491] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting Reboot: 05/05/2019 11:00:00
[2019-05-01 19:01:50,502] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Set wakeup timer to wakeup system at 05/05/2019 10:59:00
[2019-05-01 19:01:50,504] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: CheckForStandby()
[2019-05-01 19:01:52,513] [Log    ] [PS StandbyWakeup] [ERROR] - EpgGrabbing: unable to connect to controller at: NEWCORWIN
[2019-05-01 19:01:53,335] [EPG    ] [DVB EPG timer] [INFO ] - Grab for card:#3 transponder #2/3 channel: ZDF HD
[2019-05-01 19:01:53,337] [EPG    ] [DVB EPG timer] [INFO ] - EpgCard: grab epg on card: #3 transponder: #1 ch:ZDF HD
[2019-05-01 19:01:54,521] [Log    ] [PS StandbyWakeup] [ERROR] - EpgGrabbing: unable to connect to controller at: NEWCORWIN
[2019-05-01 19:01:55,344] [Log    ] [DVB EPG timer] [ERROR] - Exception   :System.Net.Sockets.SocketException (0x80004005): An attempt was made to access a socket in a way forbidden by its access permissions 192.168.178.100:31456

Server stack trace:
   at System.Net.Sockets.Socket.Connect(IPAddress[] addresses, Int32 port)
   at System.Runtime.Remoting.Channels.RemoteConnection.CreateNewSocket(AddressFamily family)
   at System.Runtime.Remoting.Channels.RemoteConnection.CreateNewSocket()
   at System.Runtime.Remoting.Channels.RemoteConnection.GetSocket()
   at System.Runtime.Remoting.Channels.SocketCache.GetSocket(String machinePortAndSid, Boolean openNew)
   at System.Runtime.Remoting.Channels.Tcp.TcpClientTransportSink.SendRequestWithRetry(IMessage msg, ITransportHeaders requestHeaders, Stream requestStream)
   at System.Runtime.Remoting.Channels.Tcp.TcpClientTransportSink.ProcessMessage(IMessage msg, ITransportHeaders requestHeaders, Stream requestStream, ITransportHeaders& responseHeaders, Stream& responseStream)
   at System.Runtime.Remoting.Channels.BinaryClientFormatterSink.SyncProcessMessage(IMessage msg)

Exception rethrown at [0]:
   at System.Runtime.Remoting.Proxies.RealProxy.HandleReturnMessage(IMessage reqMsg, IMessage retMsg)
   at System.Runtime.Remoting.Proxies.RealProxy.PrivateInvoke(MessageData& msgData, Int32 type)
   at TvControl.IController.IsRecording(IUser& user)
   at TvService.EpgCard.IsCardIdle(Int32 cardId)
   at TvService.EpgCard.GrabEpgForChannel(Channel channel, IChannel tuning, Card card)
   at TvService.EpgCard.GrabEpg()
   at TvService.EpgGrabber.GrabEpgOnCard(EpgCard epgCard)
   at TvService.EpgGrabber._epgTimer_Elapsed(Object sender, ElapsedEventArgs e)

[2019-05-01 19:01:56,531] [Log    ] [PS StandbyWakeup] [ERROR] - EpgGrabbing: unable to connect to controller at: NEWCORWIN
[2019-05-01 19:01:58,540] [Log    ] [PS StandbyWakeup] [ERROR] - EpgGrabbing: unable to connect to controller at: NEWCORWIN
[2019-05-01 19:02:00,548] [Log    ] [PS StandbyWakeup] [ERROR] - EpgGrabbing: unable to connect to controller at: NEWCORWIN
[2019-05-01 19:02:02,554] [Log    ] [PS StandbyWakeup] [ERROR] - EpgGrabbing: unable to connect to controller at: NEWCORWIN
[2019-05-01 19:02:02,556] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetStandbyMode(StandbyAllowed)
[2019-05-01 19:02:02,557] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: System is idle and may go to standby
[2019-05-01 19:02:02,558] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Active standby is disabled - standby is handled by Windows
[2019-05-01 19:02:17,591] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: StandbyWakeupThread triggered by check interval
[2019-05-01 19:02:17,596] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: LoadSettings()
[2019-05-01 19:02:17,607] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: SetWakeupTimer()
[2019-05-01 19:02:17,609] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Inspecting Reboot: 05/05/2019 11:00:00
[2019-05-01 19:02:17,621] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: Set wakeup timer to wakeup system at 05/05/2019 10:59:00
[2019-05-01 19:02:17,622] [Log    ] [PS StandbyWakeup] [DEBUG] - PS: CheckForStandby()
[2019-05-01 19:02:19,631] [Log    ] [PS StandbyWakeup] [ERROR] - EpgGrabbing: unable to connect to controller at: NEWCORWIN
[2019-05-01 19:02:21,638] [Log    ] [PS StandbyWakeup] [ERROR] - EpgGrabbing: unable to connect to controller at: NEWCORWIN

joecrow · May 2, 2019

Owlsroost said:
The [ERROR] entries in TVService.log do point to a network problem:

Ah ha, yes that could be a clue. I do use a vpn and its control program was updated recently. It maybe that if the vpn is connected when going to sleep it could take a while before it reconnects on wake up (the old version did not attempt to reconnect) and may be blocking network traffic while doing so. I will give it a few days of making sure I disconnect the vpn before sleep and if that works I can set up a schedule to kill the vpn program before sleep. I will update here when I know more.
Many thanks

Scheduled Recordings Failed (MP 1.22) (1 Viewer)

joecrow

Test Group

Attachments

Owlsroost

Retired Team Member

joecrow

Test Group

Users who are viewing this thread