Web viewer getting error

Channels DVR Server
#1

I’m trying to watch the World Series on my laptop. Windows 10, using Chrome.

2017/11/01 21:08:01 [TNR] Opened connection to 107119AB for ch59.1
2017/11/01 21:08:10 [WRN] Buffer for 107119AB ch59.1 is more than 50% full (clients=1, len=16777948)
2017/11/01 21:08:15 [WRN] Buffer for 107119AB ch59.1 is more than 75% full (clients=1, len=25166000)
2017/11/01 21:08:19 [WRN] Buffer for 107119AB ch59.1 is more than 95% full (clients=1, len=31877600)
2017/11/01 21:08:19 [WRN] Buffer for 107119AB ch59.1 is more than 99% full (clients=1, len=33219788)
2017/11/01 21:08:29 [HLS] Starting transcoder for channel 59.1 (encoder=h264_vaapi, resolution=1080, deinterlacer=blend, bitrate=6000)
libva info: VA-API version 0.40.0
libva info: va_getDriverName() returns 0
libva info: va_openDriver() returns 0
[ac3 @ 0x2387b80] frame sync error
Error while decoding stream #0:1: Invalid data found when processing input
[aac @ 0x23f06c0] Queue input is backward in time
[hls @ 0x2318ac0] Non-monotonous DTS in output stream 0:1; previous: 4800, current: 3840; changing to 4801. This may result in incorrect timestamps in the output file.
2017/11/01 21:08:29 [HLS] Stopping transcoder session ANY-ch59.1 @ 0s
2017/11/01 21:08:29 [TNR] Closed connection to 107119AB for ch59.1

#2

Hmm that’s strange. Does it work if you switch to Software transcoding?

#3

I will try it and get back to you. I noticed that it was downloading the latest update push and also detecting commercials for a 30 minute show at the same time. Once the comskip completed I restarted the stream and it appears to be fine now.

#4

It continues to freeze then recover itself.

2017/11/01 21:08:29 [HLS] Starting transcoder for channel 59.1 (encoder=h264_vaapi, resolution=1080, deinterlacer=blend, bitrate=6000)
libva info: VA-API version 0.40.0
libva info: va_getDriverName() returns 0
libva info: va_openDriver() returns 0
[ac3 @ 0x2387b80] frame sync error
Error while decoding stream #0:1: Invalid data found when processing input
[aac @ 0x23f06c0] Queue input is backward in time
[hls @ 0x2318ac0] Non-monotonous DTS in output stream 0:1; previous: 4800, current: 3840; changing to 4801. This may result in incorrect timestamps in the output file.
2017/11/01 21:08:29 [HLS] Stopping transcoder session ANY-ch59.1 @ 0s
2017/11/01 21:08:29 [TNR] Closed connection to 107119AB for ch59.1
2017/11/01 21:09:09 [WRN] Buffer for 107119AB ch13.1 is more than 50% full (clients=1, len=16777816)
2017/11/01 21:09:14 [WRN] Buffer for 107119AB ch13.1 is more than 75% full (clients=1, len=25166000)
2017/11/01 21:09:19 [WRN] Buffer for 107119AB ch13.1 is more than 95% full (clients=1, len=31877600)
2017/11/01 21:09:20 [WRN] Buffer for 107119AB ch13.1 is more than 99% full (clients=1, len=33219788)
2017/11/01 21:10:14 [WRN] Buffer for 107119AB ch13.1 is more than 50% full (clients=1, len=16777684)
2017/11/01 21:10:19 [WRN] Buffer for 107119AB ch13.1 is more than 75% full (clients=1, len=25165868)
2017/11/01 21:10:23 [WRN] Buffer for 107119AB ch13.1 is more than 95% full (clients=1, len=31877600)
2017/11/01 21:10:24 [WRN] Buffer for 107119AB ch13.1 is more than 99% full (clients=1, len=33219788)
2017/11/01 21:13:29 [DVR] Commercial detection finished with 8 markers.
2017/11/01 21:13:30 [SYS] Created database snapshot: backup-20171101.211330
2017/11/01 21:13:30 [SYS] Removing old backup backup-20170417.203323
2017/11/01 21:14:54 [TNR] Opened connection to 107119AB for ch59.1
2017/11/01 21:14:54 [HLS] Starting transcoder for channel 59.1 (encoder=h264_vaapi, resolution=1080, deinterlacer=blend, bitrate=6000)
libva info: VA-API version 0.40.0
libva info: va_getDriverName() returns 0
libva info: va_openDriver() returns 0
[ac3 @ 0x2c31ac0] frame sync error
Error while decoding stream #0:1: Invalid data found when processing input
[aac @ 0x2ca22a0] Queue input is backward in time
[hls @ 0x2c410a0] Non-monotonous DTS in output stream 0:1; previous: 4800, current: 3840; changing to 4801. This may result in incorrect timestamps in the output file.
Unrepairable underflow!
2017/11/01 21:24:08 [HLS] Stopping transcoder session ANY-ch59.1 @ 9m12.568678s
2017/11/01 21:24:08 [TNR] Closed connection to 107119AB for ch59.1
2017/11/01 21:25:58 [TNR] Opened connection to 107119AB for ch59.1
2017/11/01 21:25:58 [HLS] Starting transcoder for channel 59.1 (encoder=libx264, resolution=1080, deinterlacer=blend, bitrate=6000)
[ac3 @ 0x27126a0] frame sync error
Error while decoding stream #0:1: Invalid data found when processing input
[aac @ 0x2731720] Queue input is backward in time
[hls @ 0x268f0a0] Non-monotonous DTS in output stream 0:1; previous: 4800, current: 3840; changing to 4801. This may result in incorrect timestamps in the output file.
2017/11/01 21:43:43 [WRN] Buffer for 107119AB ch59.1 is more than 50% full (clients=1, len=16777684)
2017/11/01 21:43:44 [WRN] Buffer for 107119AB ch13.1 is more than 50% full (clients=1, len=16777684)
2017/11/01 21:43:48 [WRN] Buffer for 107119AB ch59.1 is more than 75% full (clients=1, len=25165868)
2017/11/01 21:43:50 [WRN] Buffer for 107119AB ch13.1 is more than 75% full (clients=1, len=25165868)
2017/11/01 21:43:52 [WRN] Buffer for 107119AB ch59.1 is more than 95% full (clients=1, len=31877600)
2017/11/01 21:43:52 [WRN] Buffer for 107119AB ch59.1 is more than 99% full (clients=1, len=33219788)
2017/11/01 21:43:54 [WRN] Buffer for 107119AB ch13.1 is more than 95% full (clients=1, len=31877600)
2017/11/01 21:43:55 [WRN] Buffer for 107119AB ch13.1 is more than 99% full (clients=1, len=33219788)
[mpegts @ 0x2636500] Dropped corrupted packet (stream = 1)
[mpegts @ 0x2636500] Dropped corrupted packet (stream = 0)
[ac3 @ 0x27126a0] frame sync error
Error while decoding stream #0:1: Invalid data found when processing input
[aac @ 0x2731720] Queue input is backward in time
[hls @ 0x268f0a0] Non-monotonous DTS in output stream 0:1; previous: 96951360, current: 96950400; changing to 96951361. This may result in incorrect timestamps in the output file.
2017/11/01 21:44:53 [WRN] Buffer for 107119AB ch59.1 is more than 50% full (clients=1, len=16777684)
2017/11/01 21:44:55 [WRN] Buffer for 107119AB ch13.1 is more than 50% full (clients=1, len=16777684)
2017/11/01 21:44:57 [WRN] Buffer for 107119AB ch59.1 is more than 75% full (clients=1, len=25166000)
2017/11/01 21:45:00 [WRN] Buffer for 107119AB ch13.1 is more than 75% full (clients=1, len=25166000)
2017/11/01 21:45:01 [WRN] Buffer for 107119AB ch59.1 is more than 95% full (clients=1, len=31877468)
2017/11/01 21:45:02 [WRN] Buffer for 107119AB ch59.1 is more than 99% full (clients=1, len=33219656)
2017/11/01 21:45:04 [WRN] Buffer for 107119AB ch13.1 is more than 95% full (clients=1, len=31877600)
2017/11/01 21:45:05 [WRN] Buffer for 107119AB ch13.1 is more than 99% full (clients=1, len=33219920)
2017/11/01 21:45:26 [WRN] Buffer for 107119AB ch13.1 is more than 50% full (clients=1, len=16777684)
2017/11/01 21:45:31 [WRN] Buffer for 107119AB ch13.1 is more than 75% full (clients=1, len=25165868)
2017/11/01 21:45:36 [WRN] Buffer for 107119AB ch13.1 is more than 95% full (clients=1, len=31877468)
2017/11/01 21:45:36 [WRN] Buffer for 107119AB ch13.1 is more than 99% full (clients=1, len=33219788)
2017/11/01 21:46:30 [WRN] Buffer for 107119AB ch13.1 is more than 50% full (clients=1, len=16777684)
2017/11/01 21:46:36 [WRN] Buffer for 107119AB ch13.1 is more than 75% full (clients=1, len=25166000)
2017/11/01 21:46:41 [WRN] Buffer for 107119AB ch13.1 is more than 95% full (clients=1, len=31877468)
2017/11/01 21:46:42 [WRN] Buffer for 107119AB ch13.1 is more than 99% full (clients=1, len=33219920)
[mpegts @ 0x2636500] Dropped corrupted packet (stream = 1)
[mpegts @ 0x2636500] Dropped corrupted packet (stream = 0)
[ac3 @ 0x27126a0] frame sync error
Error while decoding stream #0:1: Invalid data found when processing input
2017/11/01 21:47:14 [WRN] Buffer for 107119AB ch59.1 is more than 50% full (clients=1, len=16777684)
2017/11/01 21:47:17 [WRN] Buffer for 107119AB ch13.1 is more than 50% full (clients=1, len=16777816)
2017/11/01 21:47:19 [WRN] Buffer for 107119AB ch59.1 is more than 75% full (clients=1, len=25166000)
2017/11/01 21:47:23 [WRN] Buffer for 107119AB ch59.1 is more than 95% full (clients=1, len=31880100)
2017/11/01 21:48:16 [WRN] Buffer for 107119AB ch59.1 is more than 50% full (clients=1, len=16777684)
2017/11/01 21:48:18 [WRN] Buffer for 107119AB ch13.1 is more than 50% full (clients=1, len=16777684)
2017/11/01 21:48:21 [WRN] Buffer for 107119AB ch59.1 is more than 75% full (clients=1, len=25166000)
2017/11/01 21:48:23 [WRN] Buffer for 107119AB ch13.1 is more than 75% full (clients=1, len=25165868)
2017/11/01 21:48:25 [WRN] Buffer for 107119AB ch59.1 is more than 95% full (clients=1, len=31877468)
2017/11/01 21:48:25 [WRN] Buffer for 107119AB ch59.1 is more than 99% full (clients=1, len=33219788)
2017/11/01 21:48:28 [WRN] Buffer for 107119AB ch13.1 is more than 95% full (clients=1, len=31877468)
2017/11/01 21:48:29 [WRN] Buffer for 107119AB ch13.1 is more than 99% full (clients=1, len=33219788)
[mpegts @ 0x2636500] Dropped corrupted packet (stream = 0)
[mpegts @ 0x2636500] Dropped corrupted packet (stream = 1)
[mpegts @ 0x2636500] Dropped corrupted packet (stream = 0)
[ac3 @ 0x27126a0] frame sync error
Error while decoding stream #0:1: Invalid data found when processing input

#5

Seems like the transcoder is struggling to keep up.

You could try a lower resolution, and also enable the Hardware deinterlacer.

On the DVR web UI, what does it say in the CPU section?

Has this been a problem before?

#6

OSHewlett-Packard HP EliteBook Folio 9480m
Linux Ubuntu
16.04 (kernel: 4.4.0-31-generic)
CPU
4 cores / Intel® Core™ i5-4310U CPU @ 2.00GHz
load averages: 2.65 2.76 2.27
RAM7.68 GB
47.8% free

I haven’t been using Channels for awhile…I returned my HDHomeRun and just been keeping tabs on the progress until I was ready to try again. I just bought the Quatro to give that a try. Only been using it for 2 days now. Firmware is up to date on the Quatro.

#7

Load averages are a bit high, which indicates the machine is busy doing something else. That could be why the transcoder is having issues.

Under the web player, what does it say at the end for rate. Is it 1x or higher? Or dipping below 1x?

#8

When it worked, yes it was over 1, now I can’t get the web viewer to start. Been seeing a spinning circle for 5 minutes now…So odd. This is the same laptop I used before and it worked fine for everything I used it for Channels. Only difference is I was using Windows. This is a fresh Ubuntu install

#9

I went up to the laptop and ran top. Turns out Firefox was running hot for some reason. Saw it consuming upwards of 70% CPU. Killed that and load averages are coming down. Going to try a fresh reboot after this round of recordings and see if that clears anything up.

#10

Fresh reboot, latest update is installed. The transcoder is now not starting at all.

2017/11/01 22:38:10 [HLS] Starting transcoder for channel 59.1 (encoder=libx264, resolution=720, deinterlacer=blend, bitrate=4000)
[ac3 @ 0x1f7fca0] frame sync error
Error while decoding stream #0:1: Invalid data found when processing input
[aac @ 0x1fa1c40] Queue input is backward in time
[hls @ 0x1fb2360] Non-monotonous DTS in output stream 0:1; previous: 4800, current: 3840; changing to 4801. This may result in incorrect timestamps in the output file.

#11

Ah, I did notice that the load averages before were quite high.

Maybe try switching back to Hardware now?

#12

I can’t. The option for hardware is no longer showing up. Software is the only option showing.

I will note that prior to the reboot, no recordings were in progress. When I rebooted, 2 recordings were in progress. Both recordings have since stopped and they were scheduled to stop at 11.

This is recurring in the log over and over again:

2017/11/01 22:53:33 [DVR] Error running job 1509587970-14 Designated Survivor: stat /media/scott/DVR: no such file or directory
2017/11/01 22:53:33 [TNR] Closed connection to 107119AB for ch29.1
2017/11/01 22:53:33 [DVR] Error running job 1509587970-2 Criminal Minds: stat /media/scott/DVR: no such file or directory
2017/11/01 22:53:34 [DVR] Starting job 1509587970-14 Designated Survivor on ch=[6.1]
2017/11/01 22:53:34 [TNR] Opened connection to 107119AB for ch6.1
2017/11/01 22:53:34 [DVR] Starting job 1509587970-2 Criminal Minds on ch=[29.1]
2017/11/01 22:53:34 [DVR] Waiting 21h5m55.269755283s until next job 1509667170-13 The This Old House Hour
2017/11/01 22:53:34 [DVR] Waiting 21h5m55.264018465s until next job 1509667170-13 The This Old House Hour
2017/11/01 22:53:35 [TNR] Opened connection to 107119AB for ch29.1
2017/11/01 22:53:35 [TNR] Closed connection to 107119AB for ch6.1
2017/11/01 22:53:35 [DVR] Error running job 1509587970-14 Designated Survivor: stat /media/scott/DVR: no such file or directory
2017/11/01 22:53:35 [TNR] Closed connection to 107119AB for ch29.1
2017/11/01 22:53:35 [DVR] Error running job 1509587970-2 Criminal Minds: stat /media/scott/DVR: no such file or directory

Another note: When I setup this DVR, I restored from my previous backup which was when I ran Windows. Should I rebuild everything? If so what are the steps to reset the DVR back to default that it formats the external HD?

#13

This means your drive isn’t mounted or available.

#14

Ok I will check that out

#15

The drive is definitely available:

/dev/sdb1 1953513468 12652208 1940861260 1% /media/scott/DVR

I am almost thinking that I want to rebuild the FS on my external drive and start from scratch. I’m also seeing more errors in my log today now pointing to guide data and commercial detection failing.

2017/11/02 01:30:40 [DVR] Commercial detection failed with stat /media/scott/DVR: no such file or directory
2017/11/02 09:44:00 [DVR] Fetching guide data for 43 stations in USA-OTA46055 @ 2017-11-02 9:30AM
2017/11/02 09:44:00 [DVR] Error fetching guide data: gracenote error 1005: invalid_lineup_id
2017/11/02 09:44:00 [DVR] Fetching guide data for 43 stations in USA-OTA46055 @ 2017-11-02 3:30PM
2017/11/02 09:44:00 [DVR] Error fetching guide data: gracenote error 1005: invalid_lineup_id
2017/11/02 09:44:00 [DVR] Fetching guide data for 43 stations in USA-OTA46055 @ 2017-11-02 9:30PM
2017/11/02 09:44:00 [DVR] Error fetching guide data: gracenote error 1005: invalid_lineup_id
2017/11/02 09:44:00 [DVR] Fetching guide data for 43 stations in USA-OTA46055 @ 2017-11-03 3:30AM
2017/11/02 09:44:00 [DVR] Error fetching guide data: gracenote error 1005: invalid_lineup_id
2017/11/02 09:44:00 [DVR] Fetching guide data for 43 stations in USA-OTA46055 @ 2017-11-03 9:30AM
2017/11/02 09:44:00 [DVR] Error fetching guide data: gracenote error 1005: invalid_lineup_id
2017/11/02 09:44:00 [DVR] Fetching guide data for 43 stations in USA-OTA46055 @ 2017-11-03 3:30PM
2017/11/02 09:44:01 [DVR] Error fetching guide data: gracenote error 1005: invalid_lineup_id

#16

Ugh. Something is going on with our guide provider today. I have reached out to see what’s up.

The DVR should still have the next 21 days of data cached from before. Please let me know if its all missing for some reason.

I suspect this is a permissions issue then. Check what user the dvr is running as (ps aux | grep channels-dvr) and compare with the owner of the directory and mount (ls -alhd /media /media/scott /media/scott/DVR)

#17

A post was split to a new topic: DVR problems on SHIELD

#18

I think I fixed it after a couple days of playing. The external HD has been plugged into my dock for my laptop and was causing all sorts of issues. I just today tried to plug it into the laptop itself and I was successful at having 2 web viewer streams running at once with load averages staying pretty minimal without any stuttering.

1 Like