TV Server is running, recording, can see tv guide but not watch anything on clients (1 Viewer)

[TommySharp]

Just upgraded everthing to 1.5 final last night... TV server on it's own on my main PC and clients throughout the house. It was working perfectly last night on all clients....

Today when I try watch live TV or recorded TV I get an error as below. Can confirm that I can see the tv guide etc just fine. Rebooted clients and server to see if this would help but no luck.... TV server seems to be recording just fine as I can see lots of files created today.

Any ideas?

03-10-2013 18:34:45.953 [ 0] [11f0]
03-10-2013 18:34:45.953 [ 0] [11f0] =================== New filter instance =========================
03-10-2013 18:34:45.953 [ 0] [11f0] Logging format: Date Time [InstanceID] [ThreadID] Message....
03-10-2013 18:34:45.953 [ 0] [11f0] =================================================================
03-10-2013 18:34:45.953 [ 0] [11f0] demux: Start file read thread
03-10-2013 18:34:45.953 [ 0] [11f0] CRTSPClient::CRTSPClient()
03-10-2013 18:34:45.953 [ 0] [11f0] CMemoryBuffer::ctor
03-10-2013 18:34:45.953 [ fc09ae8] [11f0] ------------- v0.5.72 -------------
03-10-2013 18:34:45.953 [ fc09ae8] [11f0] CTsReaderFilter::ctor
03-10-2013 18:34:45.953 [ fc09ae8] [11f0] CRTSPClient::Initialize()
03-10-2013 18:34:45.953 [ fc09ae8] [11f0] CRTSPClient::createClient()
03-10-2013 18:34:45.953 [ fc09ae8] [11f0] ----- UseFPSfromDTSPTS -----
03-10-2013 18:34:45.953 [ fc09ae8] [11f0] --- Buffering delay = 0 ms
03-10-2013 18:34:45.953 [ fc09ae8] [111c] CDeMultiplexer::ThreadProc start(), threadID:0x111c
03-10-2013 18:34:45.953 [ fc09ae8] [11f0] Slow motion video allowed during zapping
03-10-2013 18:34:45.953 [ fc09ae8] [11f0] Wait for seeking to eof - false - constructor
03-10-2013 18:34:45.953 [ fc09ae8] [11f0] CTsReaderFilter:tart duration thread
03-10-2013 18:34:45.953 [ fc09ae8] [11f0] CTsReaderFilter::timeGetTime():0x14ac05, m_tGTStartTime:0xc014ac04, GET_TIME_NOW:0x40000001
03-10-2013 18:34:45.953 [ fc09ae8] [11f0] SetRelaxedMode
03-10-2013 18:34:45.953 [ fc09ae8] [11f0] Normal discontinuities filtering
03-10-2013 18:34:45.953 [ fc09ae8] [11f0] CALLBACK SET
03-10-2013 18:34:45.953 [ fc09ae8] [11f0] SetRequestAudioChangeCallback SET
03-10-2013 18:34:45.953 [ fc09ae8] [17cc] CTsReaderFilter::ThreadProc start(), threadID:0x17cc
03-10-2013 18:34:45.960 [ fc09ae8] [11f0] CTsReaderFilter::Load()
03-10-2013 18:34:45.960 [ fc09ae8] [11f0] open rtsp:rtsp://ROSIE:554/stream6.0
03-10-2013 18:34:45.960 [ fc09ae8] [11f0] CRTSPClient::OpenStream()
03-10-2013 18:34:45.960 [ fc09ae8] [11f0] CRTSPClient::getSDPDescriptionFromURL()
03-10-2013 18:34:45.965 [ fc09ae8] [11f0] Failed to get a SDP description from URL rtsp://ROSIE:554/stream6.0 Failed to read response: No error
03-10-2013 18:34:45.965 [ fc09ae8] [11f0] CRTSPClient::shutdown()
03-10-2013 18:34:45.979 [ fc09ae8] [11f0] filt:IID_IMediaSeeking()
03-10-2013 18:34:46.040 [ fc09ae8] [11f0] CTsReaderFilter::dtor
03-10-2013 18:34:46.040 [ fc09ae8] [17cc] CTsReaderFilter::ThreadProc stopped()
03-10-2013 18:34:46.040 [ fc09ae8] [11f0] audPin:dtor()
03-10-2013 18:34:46.040 [ fc09ae8] [11f0] vidPin:dtor()
03-10-2013 18:34:46.040 [ fc09ae8] [11f0] subPin:dtor()
03-10-2013 18:34:46.040 [ fc09ae8] [11f0] CTsReaderFilter::dtor - finished
03-10-2013 18:34:46.040 [ fc09ae8] [11f0] CMemoryBuffer::dtor
03-10-2013 18:34:46.040 [ fc09ae8] [11f0] memorybuffer: Clear() 0
03-10-2013 18:34:46.040 [ fc09ae8] [11f0] memorybuffer: Clear() done
03-10-2013 18:34:46.040 [ fc09ae8] [11f0] CRTSPClient::~CRTSPClient()
03-10-2013 18:34:46.040 [ fc09ae8] [11f0] CDeMultiplexer::dtor
03-10-2013 18:34:46.041 [ fc09ae8] [111c] CDeMultiplexer::ThreadProc stopped()
03-10-2013 18:34:46.041 [ fc09ae8] [11f0] demux:flushing
03-10-2013 18:34:46.041 [ fc09ae8] [11f0] demux:flush audio
03-10-2013 18:34:46.041 [ fc09ae8] [11f0] demux:flush video
03-10-2013 18:34:46.041 [ fc09ae8] [11f0] demux:flush subtitle
03-10-2013 18:34:46.041 [ fc09ae8] [11f0] CDeMultiplexer::dtor - finished[DOUBLEPOST=1380779391][/DOUBLEPOST]Now that's weird........!

Late last night I enabled the Comskip Launcher plugin. Just disabled it now, restarted TV service and everything is working now????

 

[TommySharp]

Was working fine for a day or so... Decided to enable ComSkip again and it was working great for 2 or 3 days....

Now come home from work today and get the same issue...

Failed to get a SDP description from URL rtsp://ROSIE:554/stream6.0 Failed to read response: No error

Anyone able to suggest anything? ComSkip is still enabled but if I stop and restart the TVService processes then it all works fine again, I wonder for how long....

 

[TommySharp]

Sorry, client and server logs attached logs attached.

Server : WHS Home Server 2011, i3 processor 4GB RAM
Client : Zotac Nano ID64 : i5, 4GB Ram, Windows 7, HD4000 graphics

 

[TommySharp]

Anyone able to lend me a hand on this? Works fine for a while (few days) and then just randomly stops working. Recycling the TV service seems to bring it back....

 

[mm1352000]

What happens if you ping ROSIE from the client when the problem happens?
I'd wager the server's IP address changed (DHCP) or the streaming server fell over. Unfortunately we don't have the streaming server log file from when the error occurred - it got overwritten when you restarted the TV service.
Next time this happens, please rescue the server and client logs before restarting the service. It would also be helpful to have application event logs from the server (which are grabbed by the watchdog tool) to confirm what (if anything) Windows has been doing with networking.

 

[TommySharp]

Thanks for the response, will get together more log files when it happens again.

When the issue does happen I can still browse the tv guide and see listings of recorded shows so the client must be happy talking to the TV server. The Rosie server has a fixed IP address and also has some videos in shared folders which are all still fully accessible when this issue happens.

 

[TommySharp]

It doesn't look like the watchdog tool gets installed if you just install a standalone TV server. I've attached the logs and it's "Bath Crashers" that I've just tried to watch from a client and this errored out.

I combed through the windows event logs and nothing jumps out as an issue there...

 

[mm1352000]

It doesn't look like the watchdog tool gets installed if you just install a standalone TV server.

You're right - it doesn't.

I've attached the logs and it's "Bath Crashers" that I've just tried to watch from a client and this errored out.

I combed through the windows event logs and nothing jumps out as an issue there...

Please could you add the streaming server log file from the server - it is critical.

 

[mm1352000]

Hmmm, just looking at the earlier part of your TV service log:

[2013-10-28 05:17:26,839] [Log ] [TVService] [INFO ] - Controller: local ip address:127.0.0.1
[2013-10-28 05:17:26,886] [Log ] [TVService] [INFO ] - Controller: server running on ROSIE

That's not ideal. You mentioned you had set up a static IP address... so this would indicate your adapter was up and running when the TV service started... which can cause issues for the streaming server... and yet it looks like you successfully watched a recording last night.

[edit: <doh> ...would indicate your adaptor was not up and running...]

 

[TommySharp]

This is the content of the streaming server.log file..... Hopefully this is what you're looking for although there isn't much in there?

28-10-2013 05:17:28.492 -------------- v1.0.5 ---------------
28-10-2013 05:17:28.492 Stream serveretup stream server for ip: 127.0.0.1
28-10-2013 13:45:59.14 Stream server: add timeshift mpeg-2 transport stream stream6.0 filename:R:\TimeShifting\live6-0.ts.tsbuffer
28-10-2013 13:45:59.17 Stream server: url for stream is rtsp://127.0.0.1/stream6.0
28-10-2013 13:46:04.286 Stream servertream server: remove stream stream6.0
28-10-2013 20:43:05.668 Stream server: add timeshift mpeg-2 transport stream stream6.0 filename:R:\TimeShifting\live6-0.ts.tsbuffer
28-10-2013 20:43:05.668 Stream server: url for stream is rtsp://127.0.0.1/stream6.0
28-10-2013 20:43:06.479 Stream servertream server: remove stream stream6.0
29-10-2013 20:22:21.782 Stream server: add mpeg-2 ts stream 53DF50AC filename:R:\Recordings\Oddities\Oddities - 2013-10-28 - E203.ts
29-10-2013 20:22:21.782 Stream server: url for stream is rtsp://127.0.0.1/53DF50AC
30-10-2013 23:05:39.126 Stream server: add mpeg-2 ts stream 31F4BD18 filename:R:\Recordings\Bath Crashers\Bath Crashers - 2013-10-30 - E406.ts
30-10-2013 23:05:39.126 Stream server: url for stream is rtsp://127.0.0.1/31F4BD18
30-10-2013 23:18:46.254 Stream server: add mpeg-2 ts stream 68F1F4EC filename:R:\Recordings\Candice Tells All\Candice Tells All - 2013-10-30 - E114.ts
30-10-2013 23:18:46.254 Stream server: url for stream is rtsp://127.0.0.1/68F1F4EC[DOUBLEPOST=1383170179][/DOUBLEPOST]I've just cracked open some of the older streaming server.log files and the ip address in there is my static one of 192.168.1.99.... But in the latest log file it seems to be 127.0.0.1.... The client must be trying to access the stream on 127.0.0.1 which wouldn't work....

Is there any way to make sure that the tv server stops trying to broadcast on 127.0.0.1?[DOUBLEPOST=1383170369][/DOUBLEPOST]My server does reboot automatically every sunday night so maybe when it comes back up the network interface has not been initialised properly yet and that's why the tv service broadcasts on 127.0.0.1. Maybe if I delay the startup of the tv service then the 192.168.1.99 IP address will be properly assigned by the time the tv service starts?