Is it only on channel 6073?
No, it happens with Channel 6161 NBCSN and also many times with channel 6171 NBCSN Philly. Mostly see it on 6073 Fox News though, since that is a daily recording instead of one off sports from the other channels. I will keep searching through the recordings to see if other TVE channels are affected.
Oh and I also noticed about a week ago that when I had back to back recordings from 6073 that the timestamps would be all screwed up and when I would watch them back I would get shows all mixed in together as I watched, like say Tucker would play mostly all the way through, then I would start Hannity and it would have Tucker's show half way into it, then Hannity would start, and so on and so on into the subsequent shows. I thought maybe it had something to do with the overlap from the extended recordings I make by one minute on each end as default for recording series shows, so they had that one minute overlap between each show. I changed it to having no one minute extension before and after each show and that seemed to help some, but I still am now getting these errors.
Update:
I also see shorted recordings on Locast 5902, 5903, TVEs 6108, 6111, 6106, 6140, etc. so I am sure it's a systemic issue.
This means there's some type of home network or internet issue.
It's the same Cancelling stream issue with the Locast streams too?
That's real strange because I just went in tweaked all my internet items/routers including added another router as an access point, but this Shield doesn't access that. I have no issues whatsoever with any other internet connected devices, including a secondary Channels DVR Server that I watch and stream from remotely from PA using the older Shield that I had here last year with these issues. I never get these errors anymore on that system.
When I do speed tests I always consistently get my max that I am supposed to at ~235 down and 23 up, with no lag, hiccups, dropped packets, etc. I can watch ANY other app like Netflix, Hulu, AppleTV+, YouTube, Prime, etc. with no issues whatsoever, including full 4K UHD HDR/Dolby Vision.
I haven't seen the Locast errors in the logs yet. I just went through my recordings and listed any that had their recording times cut short. I can try to go back and find this info, but that will be WAY back in terms of log entries and will be quite the chore time wise! Is there a way to quick search the logs?
Could it be an issue with the Shield itself? Maybe its network hardware? It happens whether I use wired or wifi, 2GHz or 5GHz.
Another little tidbit. The recording of "The Story with Martha MacCallum" today on 6073 shows the recording time of 25 hours, 54 minutes! It is only a one hour show! All the other normally scheduled shows on the same channel all got cut off from recording at around 20 minutes or so, plus or minus a few minutes.
Here are the logs for MacCallum timeframe:
2020/09/04 12:59:00.023730 [DVR] Waiting 59.976295131s until next job 1599260400-50 The Story With Martha MacCallum
2020/09/04 12:59:05.977384 [TVE] stream timestamps: nbcsn-philly: start_at=2020-09-04T12:29:05-10:00 current_at=2020-09-04T12:58:42-10:00 end_at=2020-09-04T12:58:54-10:00
2020/09/04 12:59:05.977471 [TNR] Opened connection to TVE-Comcast_SSO for ch6171 NBCSN-PHILLY
2020/09/04 12:59:05.979629 [DVR] Recording for job 1599260340-6 from TVE-Comcast_SSO ch6171 into "TV/MLB Baseball/MLB Baseball Philadelphia Phillies at New York Mets 2020-09-04-1259.mpg" for 4h0m59.976146121s
2020/09/04 12:59:05.989901 [IDX] Generating video index for job 1599260340-6
2020/09/04 13:00:00.015986 [DVR] Starting job 1599260400-50 The Story With Martha MacCallum on ch=[6073]
2020/09/04 13:00:00.016064 [DVR] Waiting 59m59.983944425s until next job 1599264000-37 Tucker Carlson Tonight
2020/09/04 13:00:00.016218 [TNR] Sharing existing connection to TVE-Comcast_SSO for ch6073 FNC (clients=2, len=0)
2020/09/04 13:00:00.296583 [DVR] Recording for job 1599260400-50 from TVE-Comcast_SSO ch6073 into "TV/The Story With Martha MacCallum/The Story With Martha MacCallum S2020E178 2020-09-04-1300.mpg" for 59m59.983893123s
2020/09/04 13:00:00.307404 [IDX] Generating video index for job 1599260400-50
2020/09/04 13:01:08.037924 [MTS] Statistics for "TV/Special Report With Bret Baier/Special Report With Bret Baier S2020E178 2020-09-04-1159.mpg": skipped=0 unhandled_packets=0 discontinuity_detected=170 transport_errors=0 invalid_pts=0 invalid_dts=0 saw_pcr=false saw_pmt=true highest_pts=3735.141889
2020/09/04 13:01:08.315390 [DVR] Finished job 1599256740-38 Special Report With Bret Baier
2020/09/04 13:01:08.338436 [DVR] Waiting 58m51.661576274s until next job 1599264000-37 Tucker Carlson Tonight
2020/09/04 13:01:08.436914 [DVR] Processing file-894: TV/Special Report With Bret Baier/Special Report With Bret Baier S2020E178 2020-09-04-1159.mpg
2020/09/04 13:07:04.304939 [TNR] Cancelling stream TVE-Comcast_SSO ch6073 after 2m0s read timeout
2020/09/04 13:07:05.647698 [MTS] Statistics for "TV/The Story With Martha MacCallum/The Story With Martha MacCallum S2020E178 2020-09-04-1300.mpg": skipped=0 unhandled_packets=0 discontinuity_detected=170 transport_errors=0 invalid_pts=0 invalid_dts=0 saw_pcr=false saw_pmt=true highest_pts=3972.695889
2020/09/04 13:07:06.926179 [DVR] Job 1599260400-50 The Story With Martha MacCallum ended prematurely: 52m53.073844171s
2020/09/04 13:07:06.955203 [DVR] Starting job 1599260400-50 The Story With Martha MacCallum on ch=[6073]
2020/09/04 13:07:06.955284 [DVR] Waiting 52m53.044726306s until next job 1599264000-37 Tucker Carlson Tonight
2020/09/04 13:07:06.957768 [TVE] action=auth mvpd=Comcast_SSO requestor=fbc-fox
2020/09/04 13:07:07.157427 [TNR] Closed connection to TVE-Comcast_SSO for ch6073 FNC
2020/09/04 13:07:09.392498 [TVE] action=mock
2020/09/04 13:07:09.394228 [TVE] action=navigate url=https://sp.auth.adobe.com/adobe-services/authenticate/saml?noflash=true&mso_id=Comcast_SSO&requestor_id=fbc-fox&no_iframe=true&domain_name=adobe.com&redirect_url=https%3A%2F%2Fsp.auth.adobe.com%2Fadobe-services%2FcompletePassiveAuthentication
2020/09/04 13:07:09.396658 [TVE] action=request type=Document method=GET url=https://sp.auth.adobe.com/adobe-services/authenticate/saml
2020/09/04 13:07:09.997480 [TVE] action=request type=Document method=GET url=https://oauth.xfinity.com/oauth/authorize
2020/09/04 13:07:10.783808 [TVE] action=request type=Document method=GET url=https://login.xfinity.com/login
2020/09/04 13:07:10.783847 [TVE] action=auth_domain domain=login.xfinity.com
2020/09/04 13:07:11.476054 [TVE] action=wait_for_page
2020/09/04 13:07:19.437095 [TVE] action=page_ready
2020/09/04 13:07:19.437183 [TVE] action=wait_for_page done=true reason=page_ready
2020/09/04 13:07:19.437217 [TVE] action=fill_form u=dharper1
2020/09/04 13:07:19.963577 [TVE] action=request type=Document method=POST url=https://login.xfinity.com/login
2020/09/04 13:07:19.965938 [TVE] action=wait_for_auth
2020/09/04 13:07:21.364083 [TVE] action=request type=Document method=GET url=https://oauth.xfinity.com/oauth/authorize
2020/09/04 13:07:21.801876 [TVE] action=request type=Document method=GET url=https://sp.auth.adobe.com/adobe-services/oauth2
2020/09/04 13:07:23.185363 [TVE] action=request type=Document method=GET url=https://sp.auth.adobe.com/adobe-services/completePassiveAuthentication
2020/09/04 13:07:23.185415 [TVE] action=authed
2020/09/04 13:07:23.192404 [TVE] action=cookies num_domains=2 num_cookies=22
2020/09/04 13:07:23.200126 [TVE] action=error_response type=Document error=net::ERR_ABORTED
2020/09/04 13:07:30.446659 [TVE] stream timestamps: fnc: start_at=2020-09-04T13:07:26-10:00 current_at=2020-09-04T13:07:46-10:00 end_at=2020-09-04T13:07:54-10:00
2020/09/04 13:07:30.446807 [TNR] Opened connection to TVE-Comcast_SSO for ch6073 FNC
2020/09/04 13:07:30.682386 [DVR] Recording for job 1599260400-50 from TVE-Comcast_SSO ch6073 into "TV/The Story With Martha MacCallum/The Story With Martha MacCallum S2020E178 2020-09-04-1300.mpg" for 52m53.044662713s
2020/09/04 13:09:30.447766 [TNR] Cancelling stream TVE-Comcast_SSO ch6073 after 2m0s read timeout
2020/09/04 13:09:30.982893 [MTS] Statistics for "TV/The Story With Martha MacCallum/The Story With Martha MacCallum S2020E178 2020-09-04-1300.mpg": skipped=0 unhandled_packets=0 discontinuity_detected=0 transport_errors=0 invalid_pts=0 invalid_dts=0 saw_pcr=false saw_pmt=false highest_pts=0.000000
2020/09/04 13:09:31.546290 [DVR] Job 1599260400-50 The Story With Martha MacCallum ended prematurely: 50m28.453729955s
2020/09/04 13:09:31.551481 [TNR] Closed connection to TVE-Comcast_SSO for ch6073 FNC
2020/09/04 13:09:31.579845 [DVR] Starting job 1599260400-50 The Story With Martha MacCallum on ch=[6073]
2020/09/04 13:09:31.579979 [DVR] Waiting 50m28.420028966s until next job 1599264000-37 Tucker Carlson Tonight
2020/09/04 13:09:33.623511 [TVE] stream timestamps: fnc: start_at=2020-09-04T13:09:34-10:00 current_at=2020-09-04T13:09:54-10:00 end_at=2020-09-04T13:10:02-10:00
2020/09/04 13:09:33.623627 [TNR] Opened connection to TVE-Comcast_SSO for ch6073 FNC
2020/09/04 13:09:33.625757 [DVR] Recording for job 1599260400-50 from TVE-Comcast_SSO ch6073 into "TV/The Story With Martha MacCallum/The Story With Martha MacCallum S2020E178 2020-09-04-1300.mpg" for 50m28.419980059s
2020/09/04 13:12:54.300723 [TNR] Cancelling stream TVE-Comcast_SSO ch6073 after 2m0s read timeout
2020/09/04 13:12:57.140416 [MTS] Statistics for "TV/The Story With Martha MacCallum/The Story With Martha MacCallum S2020E178 2020-09-04-1300.mpg": skipped=0 unhandled_packets=0 discontinuity_detected=0 transport_errors=0 invalid_pts=0 invalid_dts=0 saw_pcr=false saw_pmt=true highest_pts=665.802656
2020/09/04 13:13:11.689556 [TNR] Closed connection to TVE-Comcast_SSO for ch6073 FNC
2020/09/04 13:13:12.147741 [DVR] Job 1599260400-50 The Story With Martha MacCallum ended prematurely: 46m47.852290091s
2020/09/04 13:13:12.167935 [DVR] Starting job 1599260400-50 The Story With Martha MacCallum on ch=[6073]
2020/09/04 13:13:12.168004 [DVR] Waiting 46m47.832005248s until next job 1599264000-37 Tucker Carlson Tonight
2020/09/04 13:13:15.088873 [TVE] stream timestamps: fnc: start_at=2020-09-04T13:13:14-10:00 current_at=2020-09-04T13:13:34-10:00 end_at=2020-09-04T13:13:43-10:00
2020/09/04 13:13:15.089001 [TNR] Opened connection to TVE-Comcast_SSO for ch6073 FNC
2020/09/04 13:13:15.231079 [DVR] Recording for job 1599260400-50 from TVE-Comcast_SSO ch6073 into "TV/The Story With Martha MacCallum/The Story With Martha MacCallum S2020E178 2020-09-04-1300.mpg" for 46m47.831953685s
2020/09/04 14:00:00.021920 [DVR] Marking expired job 1599260400-50 The Story With Martha MacCallum
2020/09/04 14:00:00.026028 [DVR] Starting job 1599264000-37 Tucker Carlson Tonight on ch=[6073]
2020/09/04 14:00:00.026076 [DVR] Waiting 59m59.973936163s until next job 1599267600-39 Hannity
2020/09/04 14:00:00.026305 [TNR] Sharing existing connection to TVE-Comcast_SSO for ch6073 FNC (clients=2, len=0)
2020/09/04 14:00:02.924931 [MTS] Statistics for "TV/The Story With Martha MacCallum/The Story With Martha MacCallum S2020E178 2020-09-04-1300.mpg": skipped=0 unhandled_packets=0 discontinuity_detected=34 transport_errors=0 invalid_pts=0 invalid_dts=0 saw_pcr=false saw_pmt=true highest_pts=1410.534344
2020/09/04 14:00:04.754878 [DVR] Recording for job 1599264000-37 from TVE-Comcast_SSO ch6073 into "TV/Tucker Carlson Tonight/Tucker Carlson Tonight S2020E178 2020-09-04-1400.mpg" for 59m59.973864704s
2020/09/04 14:00:04.756002 [DVR] Finished job 1599260400-50 The Story With Martha MacCallum
2020/09/04 14:00:04.770534 [IDX] Generating video index for job 1599264000-37
2020/09/04 14:00:04.777307 [DVR] Waiting 59m55.222704654s until next job 1599267600-39 Hannity
2020/09/04 14:00:04.878594 [DVR] Processing file-896: TV/The Story With Martha MacCallum/The Story With Martha MacCallum S2020E178 2020-09-04-1300.mpg
2020/09/04 14:02:03.597367 [IDX] Generating video index for file-896: The Story With Martha MacCallum S2020E178 2020-09-04-1300.mpg
2020/09/04 14:03:35.037444 [IDX] Finished video index generation for file-896 in 91s
The TVE connection to channel 6073 timed out again. If you try to view the channel live from the guide does it work?
The same thing happens as what I described here with recordings I watch:
Or I get "Tuner Not Available" many times as well, if the video actually doesn't start playing and then exhibiting the symptoms above.
Nobody has replied regrding the TLS errors being thrown into my Channels DVR logs from my MS Surface Pro whenever I wake it up and use it from sleep, even though it isn't being used for Channels at all other than to look at server properties and logs themselves. Any ideas? Could it be related to my issues?
Reading back through, I wonder if your shield is having clock issues. When these issues happen, are the times shown in the logs accurate? Certificates will break if the clock gets too far off, and a wonky clock could cause recordings to end early as well. Just a thought.
Yes that’s been mentioned before. This is a brand new Shield and USB HDD. I did switch from automatic time setting to manual and back again but still have the same issues. Yes the times are accurate.
Could it be maybe the time differences between where the Xfinity TVE is authorized from in PA where I own that home and pay for an Xfinity package as compared to where I watch and record and host the server here in HI, six hours earlier? In other words a show that say starts in PA at 8pm via my authorized TVE in that timezone, but it’s being recorded on a server here on my Shield with a timezone using HI?
Interesting theory. You could easily test it by setting the shield time zone to match the server. If the problems go away, that would confirm it’s related to the difference. Easy to try.
1 Like
Yes those were my thoughts exactly! Will try and report back after a few days.
I think we may be on to something! Everything looks good so far after switching the Shield's time to Eastern time. No errors in the logs after a few recordings and I watched a bunch of live TV last night with no issues either. Only errors are those crazy TLS errors when I open the logs using my MS Surface.
I'll keep monitoring. This could be a great sticky solution on making sure the server's time zones match your TVE and Locast base location, at least on the Shield as a server!
1 Like
I think I spoke too soon once again. The cutoff recording time errors are back. Now I see this under the troubleshooting tab:
And this from the Server Settings page shows the time as it is locally (10:18pm) when I did the update, even though I changed the time on my Shield which hosts the Channels DVR Server to EDT (4:18am) where the TVE is authorizes from.
Where does Channels DVR Server itself get its time from? Is it not from its host (Shield, PC, NAS, etc.)?
And the shield is still showing the time zone set to what you put it to? I’m not 100% on how Channels determines time zone on the shield, the Devs will have to comment on that.
Time zone has zero effect on streams. It is purely cosmetic.
OK, so what could possibly be the issues then? Isn’t there any clues in all the log entries I posted?