2020-05-14, 12:55 AM
Running version 5.0.4.200428, nextpvr Web App. playing a recorded show. When I either seek ahead or seek back in the play back, after the first of second try I get the message "Streaming Failed (transcoder exited)" in red at the bottom of the screen, the play back recovers and the playback continues. After a few seek/forward or seek/back, the playback gets stuck in a loop and eventually quits. Is there something missing or misconfigured? Never had this issue with 4.2.5 or earlier releases
Here is a segment of the log file:
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] no frame!
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] SPS unavailable in decode_picture_timing
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] non-existing PPS 0 referenced
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] SPS unavailable in decode_picture_timing
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] non-existing PPS 0 referenced
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] decode_slice_header error
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] no frame!
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] SPS unavailable in decode_picture_timing
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] non-existing PPS 0 referenced
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] SPS unavailable in decode_picture_timing
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] non-existing PPS 0 referenced
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] decode_slice_header error
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] no frame!
2020-05-13 14:41:54.395 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] Increasing reorder buffer to 2
2020-05-13 14:41:54.411 [DEBUG][171] [FFMPEG]: [mpegts @ 05a553c0] PES packet size mismatch
2020-05-13 14:41:54.411 [DEBUG][171] [FFMPEG]: Last message repeated 1 times
2020-05-13 14:41:54.411 [DEBUG][171] [FFMPEG]: [mpegts @ 05a553c0] Could not find codec parameters for stream 3 (Unknown: none (ETV1 / 0x31565445)): unknown codec
2020-05-13 14:41:54.412 [DEBUG][171] [FFMPEG]: Consider increasing the value for the 'analyzeduration' and 'probesize' options
2020-05-13 14:41:54.412 [DEBUG][171] [FFMPEG]: [mpegts @ 05a553c0] Could not find codec parameters for stream 4 (Unknown: none (ETV1 / 0x31565445)): unknown codec
2020-05-13 14:41:54.412 [DEBUG][171] [FFMPEG]: Consider increasing the value for the 'analyzeduration' and 'probesize' options
2020-05-13 14:41:54.419 [DEBUG][171] [FFMPEG]: Input #0, mpegts, from 'file:C:\Users\Public\Recorded TV\NPTV Recorded TV\Spy in the Wild, A Nature Miniseries\Season 02\Spy in the Wild, A Nature Miniseries_2020-05-06_20002100.ts':
2020-05-13 14:41:54.419 [DEBUG][171] [FFMPEG]: Duration: 01:02:59.85, start: 3218.460878, bitrate: 4199 kb/s
2020-05-13 14:41:54.419 [DEBUG][171] [FFMPEG]: Program 11096
2020-05-13 14:41:54.419 [DEBUG][171] [FFMPEG]: Stream #0:0[0x177e]: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(progressive), 1280x720 [SAR 1:1 DAR 16:9], Closed Captions, 59.94 fps, 59.94 tbr, 90k tbn, 119.88 tbc
2020-05-13 14:41:54.420 [DEBUG][171] [FFMPEG]: Stream #0:1[0x177f](eng): Audio: ac3 ([129][0][0][0] / 0x0081), 48000 Hz, stereo, fltp, 192 kb/s
2020-05-13 14:41:54.420 [DEBUG][171] [FFMPEG]: Stream #0:2[0x1780](spa): Audio: ac3 ([129][0][0][0] / 0x0081), 48000 Hz, stereo, fltp, 192 kb/s (visual impaired)
2020-05-13 14:41:54.420 [DEBUG][171] [FFMPEG]: Stream #0:3[0x1781]: Unknown: none (ETV1 / 0x31565445)
2020-05-13 14:41:54.420 [DEBUG][171] [FFMPEG]: Stream #0:4[0x1782]: Unknown: none (ETV1 / 0x31565445)
2020-05-13 14:41:54.421 [DEBUG][171] [FFMPEG]: Stream mapping:
2020-05-13 14:41:54.421 [DEBUG][171] [FFMPEG]: Stream #0:0 -> #0:0 (h264 (native) -> h264 (libx264))
2020-05-13 14:41:54.421 [DEBUG][171] [FFMPEG]: Stream #0:1 -> #0:1 (ac3 (native) -> aac (native))
2020-05-13 14:41:54.421 [DEBUG][171] [FFMPEG]: Press [q] to stop, [?] for help
2020-05-13 14:41:54.499 [DEBUG][171] [FFMPEG]: [libx264 @ 061180c0] using SAR=1/1
2020-05-13 14:41:54.499 [DEBUG][171] [FFMPEG]: [libx264 @ 061180c0] MB rate (489110) > level limit (245760)
2020-05-13 14:41:54.499 [DEBUG][171] [FFMPEG]: [libx264 @ 061180c0] using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2
2020-05-13 14:41:54.502 [DEBUG][87] [FFMPEG]: [libx264 @ 061180c0] profile High, level 4.1, 4:2:0, 8-bit
2020-05-13 14:41:54.502 [DEBUG][87] [FFMPEG]: [hls @ 05f94800] Opening 'C:\Users\Public\NPVR-data\web\temp\Spy in the Wild, A Nature Miniseries-c1dae37b08f94ddb929ae56b87d2b9fd0.ts' for writing
2020-05-13 14:41:54.503 [DEBUG][87] [FFMPEG]: Output #0, hls, to 'C:\Users\Public\NPVR-data\web\temp\Spy in the Wild, A Nature Miniseries-c1dae37b08f94ddb929ae56b87d2b9fd.m3u8':
2020-05-13 14:41:54.503 [DEBUG][87] [FFMPEG]: Metadata:
2020-05-13 14:41:54.503 [DEBUG][87] [FFMPEG]: encoder : Lavf58.29.100
2020-05-13 14:41:54.503 [DEBUG][87] [FFMPEG]: Stream #0:0: Video: h264 (libx264), yuv420p, 1920x1080 [SAR 1:1 DAR 16:9], q=-1--1, 8000 kb/s, 59.94 fps, 90k tbn, 59.94 tbc
2020-05-13 14:41:54.504 [DEBUG][87] [FFMPEG]: Metadata:
2020-05-13 14:41:54.504 [DEBUG][87] [FFMPEG]: encoder : Lavc58.54.100 libx264
2020-05-13 14:41:54.504 [DEBUG][87] [FFMPEG]: Side data:
2020-05-13 14:41:54.504 [DEBUG][87] [FFMPEG]: cpb: bitrate max/min/avg: 8000000/0/8000000 buffer size: 16000000 vbv_delay: -1
2020-05-13 14:41:54.504 [DEBUG][87] [FFMPEG]: Stream #0:1: Audio: aac (LC), 48000 Hz, stereo, fltp, 192 kb/s
2020-05-13 14:41:54.504 [DEBUG][87] [FFMPEG]: Metadata:
2020-05-13 14:41:54.504 [DEBUG][87] [FFMPEG]: encoder : Lavc58.54.100 aac
2020-05-13 14:41:54.929 [DEBUG][171] [FFMPEG]: frame= 68 fps=0.0 q=24.0 size=N/A time=00:00:02.01 bitrate=N/A speed=4.02x
2020-05-13 14:41:55.275 [DEBUG][90] InitiateTranscode@exit
2020-05-13 14:41:55.276 [DEBUG][90] {"stat":"ok"}
2020-05-13 14:41:55.279 [DEBUG][90] Got request [::1]: /services/service (recording.transcode.initiate)
2020-05-13 14:41:55.279 [DEBUG][90] method=recording.transcode.initiate
2020-05-13 14:41:55.279 [DEBUG][90] parameters:
2020-05-13 14:41:55.279 [DEBUG][90] method: recording.transcode.initiate
2020-05-13 14:41:55.279 [DEBUG][90] recording_id: 850
2020-05-13 14:41:55.279 [DEBUG][90] profile: 1080p
2020-05-13 14:41:55.280 [DEBUG][90] seek: 488
2020-05-13 14:41:55.280 [DEBUG][90] format: json
2020-05-13 14:41:55.280 [DEBUG][90] sid: 35a79f34-cae4-43da-a6cf-9988c2a57e68
2020-05-13 14:41:55.280 [DEBUG][90] client_ip: ::1
2020-05-13 14:41:55.280 [DEBUG][90] user_agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/81.0.4044.138 Safari/537.36 Edg/81.0.416.72
2020-05-13 14:41:55.280 [DEBUG][90] host_callback: localhost:8866
2020-05-13 14:41:55.280 [DEBUG][90] InitiateTranscode
2020-05-13 14:41:55.284 [DEBUG][90] InitiateTranscode@2..
2020-05-13 14:41:55.718 [DEBUG][90] False
2020-05-13 14:41:55.784 [DEBUG][87] Got request [::1]: /services/service (recording.transcode.status)
2020-05-13 14:41:55.784 [DEBUG][87] method=recording.transcode.status
2020-05-13 14:41:55.784 [DEBUG][87] parameters:
2020-05-13 14:41:55.784 [DEBUG][87] method: recording.transcode.status
2020-05-13 14:41:55.785 [DEBUG][87] format: json
2020-05-13 14:41:55.785 [DEBUG][87] sid: 35a79f34-cae4-43da-a6cf-9988c2a57e68
2020-05-13 14:41:55.785 [DEBUG][87] client_ip: ::1
2020-05-13 14:41:55.785 [DEBUG][87] user_agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/81.0.4044.138 Safari/537.36 Edg/81.0.416.72
2020-05-13 14:41:55.785 [DEBUG][87] host_callback: localhost:8866
2020-05-13 14:41:55.785 [DEBUG][87] GetStatus()@nochild
2020-05-13 14:41:55.786 [DEBUG][87] {"status": "Streaming Failed (transcoder exited)","final": true,"duration": 0,"percentage": 0}
2020-05-13 14:41:55.917 [DEBUG][87] Got request [::1]: /services/service (recording.transcode.lease)
Here is a segment of the log file:
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] no frame!
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] SPS unavailable in decode_picture_timing
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] non-existing PPS 0 referenced
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] SPS unavailable in decode_picture_timing
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] non-existing PPS 0 referenced
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] decode_slice_header error
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] no frame!
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] SPS unavailable in decode_picture_timing
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] non-existing PPS 0 referenced
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] SPS unavailable in decode_picture_timing
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] non-existing PPS 0 referenced
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] decode_slice_header error
2020-05-13 14:41:54.386 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] no frame!
2020-05-13 14:41:54.395 [DEBUG][171] [FFMPEG]: [h264 @ 05a73d80] Increasing reorder buffer to 2
2020-05-13 14:41:54.411 [DEBUG][171] [FFMPEG]: [mpegts @ 05a553c0] PES packet size mismatch
2020-05-13 14:41:54.411 [DEBUG][171] [FFMPEG]: Last message repeated 1 times
2020-05-13 14:41:54.411 [DEBUG][171] [FFMPEG]: [mpegts @ 05a553c0] Could not find codec parameters for stream 3 (Unknown: none (ETV1 / 0x31565445)): unknown codec
2020-05-13 14:41:54.412 [DEBUG][171] [FFMPEG]: Consider increasing the value for the 'analyzeduration' and 'probesize' options
2020-05-13 14:41:54.412 [DEBUG][171] [FFMPEG]: [mpegts @ 05a553c0] Could not find codec parameters for stream 4 (Unknown: none (ETV1 / 0x31565445)): unknown codec
2020-05-13 14:41:54.412 [DEBUG][171] [FFMPEG]: Consider increasing the value for the 'analyzeduration' and 'probesize' options
2020-05-13 14:41:54.419 [DEBUG][171] [FFMPEG]: Input #0, mpegts, from 'file:C:\Users\Public\Recorded TV\NPTV Recorded TV\Spy in the Wild, A Nature Miniseries\Season 02\Spy in the Wild, A Nature Miniseries_2020-05-06_20002100.ts':
2020-05-13 14:41:54.419 [DEBUG][171] [FFMPEG]: Duration: 01:02:59.85, start: 3218.460878, bitrate: 4199 kb/s
2020-05-13 14:41:54.419 [DEBUG][171] [FFMPEG]: Program 11096
2020-05-13 14:41:54.419 [DEBUG][171] [FFMPEG]: Stream #0:0[0x177e]: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(progressive), 1280x720 [SAR 1:1 DAR 16:9], Closed Captions, 59.94 fps, 59.94 tbr, 90k tbn, 119.88 tbc
2020-05-13 14:41:54.420 [DEBUG][171] [FFMPEG]: Stream #0:1[0x177f](eng): Audio: ac3 ([129][0][0][0] / 0x0081), 48000 Hz, stereo, fltp, 192 kb/s
2020-05-13 14:41:54.420 [DEBUG][171] [FFMPEG]: Stream #0:2[0x1780](spa): Audio: ac3 ([129][0][0][0] / 0x0081), 48000 Hz, stereo, fltp, 192 kb/s (visual impaired)
2020-05-13 14:41:54.420 [DEBUG][171] [FFMPEG]: Stream #0:3[0x1781]: Unknown: none (ETV1 / 0x31565445)
2020-05-13 14:41:54.420 [DEBUG][171] [FFMPEG]: Stream #0:4[0x1782]: Unknown: none (ETV1 / 0x31565445)
2020-05-13 14:41:54.421 [DEBUG][171] [FFMPEG]: Stream mapping:
2020-05-13 14:41:54.421 [DEBUG][171] [FFMPEG]: Stream #0:0 -> #0:0 (h264 (native) -> h264 (libx264))
2020-05-13 14:41:54.421 [DEBUG][171] [FFMPEG]: Stream #0:1 -> #0:1 (ac3 (native) -> aac (native))
2020-05-13 14:41:54.421 [DEBUG][171] [FFMPEG]: Press [q] to stop, [?] for help
2020-05-13 14:41:54.499 [DEBUG][171] [FFMPEG]: [libx264 @ 061180c0] using SAR=1/1
2020-05-13 14:41:54.499 [DEBUG][171] [FFMPEG]: [libx264 @ 061180c0] MB rate (489110) > level limit (245760)
2020-05-13 14:41:54.499 [DEBUG][171] [FFMPEG]: [libx264 @ 061180c0] using cpu capabilities: MMX2 SSE2Fast SSSE3 SSE4.2 AVX FMA3 BMI2 AVX2
2020-05-13 14:41:54.502 [DEBUG][87] [FFMPEG]: [libx264 @ 061180c0] profile High, level 4.1, 4:2:0, 8-bit
2020-05-13 14:41:54.502 [DEBUG][87] [FFMPEG]: [hls @ 05f94800] Opening 'C:\Users\Public\NPVR-data\web\temp\Spy in the Wild, A Nature Miniseries-c1dae37b08f94ddb929ae56b87d2b9fd0.ts' for writing
2020-05-13 14:41:54.503 [DEBUG][87] [FFMPEG]: Output #0, hls, to 'C:\Users\Public\NPVR-data\web\temp\Spy in the Wild, A Nature Miniseries-c1dae37b08f94ddb929ae56b87d2b9fd.m3u8':
2020-05-13 14:41:54.503 [DEBUG][87] [FFMPEG]: Metadata:
2020-05-13 14:41:54.503 [DEBUG][87] [FFMPEG]: encoder : Lavf58.29.100
2020-05-13 14:41:54.503 [DEBUG][87] [FFMPEG]: Stream #0:0: Video: h264 (libx264), yuv420p, 1920x1080 [SAR 1:1 DAR 16:9], q=-1--1, 8000 kb/s, 59.94 fps, 90k tbn, 59.94 tbc
2020-05-13 14:41:54.504 [DEBUG][87] [FFMPEG]: Metadata:
2020-05-13 14:41:54.504 [DEBUG][87] [FFMPEG]: encoder : Lavc58.54.100 libx264
2020-05-13 14:41:54.504 [DEBUG][87] [FFMPEG]: Side data:
2020-05-13 14:41:54.504 [DEBUG][87] [FFMPEG]: cpb: bitrate max/min/avg: 8000000/0/8000000 buffer size: 16000000 vbv_delay: -1
2020-05-13 14:41:54.504 [DEBUG][87] [FFMPEG]: Stream #0:1: Audio: aac (LC), 48000 Hz, stereo, fltp, 192 kb/s
2020-05-13 14:41:54.504 [DEBUG][87] [FFMPEG]: Metadata:
2020-05-13 14:41:54.504 [DEBUG][87] [FFMPEG]: encoder : Lavc58.54.100 aac
2020-05-13 14:41:54.929 [DEBUG][171] [FFMPEG]: frame= 68 fps=0.0 q=24.0 size=N/A time=00:00:02.01 bitrate=N/A speed=4.02x
2020-05-13 14:41:55.275 [DEBUG][90] InitiateTranscode@exit
2020-05-13 14:41:55.276 [DEBUG][90] {"stat":"ok"}
2020-05-13 14:41:55.279 [DEBUG][90] Got request [::1]: /services/service (recording.transcode.initiate)
2020-05-13 14:41:55.279 [DEBUG][90] method=recording.transcode.initiate
2020-05-13 14:41:55.279 [DEBUG][90] parameters:
2020-05-13 14:41:55.279 [DEBUG][90] method: recording.transcode.initiate
2020-05-13 14:41:55.279 [DEBUG][90] recording_id: 850
2020-05-13 14:41:55.279 [DEBUG][90] profile: 1080p
2020-05-13 14:41:55.280 [DEBUG][90] seek: 488
2020-05-13 14:41:55.280 [DEBUG][90] format: json
2020-05-13 14:41:55.280 [DEBUG][90] sid: 35a79f34-cae4-43da-a6cf-9988c2a57e68
2020-05-13 14:41:55.280 [DEBUG][90] client_ip: ::1
2020-05-13 14:41:55.280 [DEBUG][90] user_agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/81.0.4044.138 Safari/537.36 Edg/81.0.416.72
2020-05-13 14:41:55.280 [DEBUG][90] host_callback: localhost:8866
2020-05-13 14:41:55.280 [DEBUG][90] InitiateTranscode
2020-05-13 14:41:55.284 [DEBUG][90] InitiateTranscode@2..
2020-05-13 14:41:55.718 [DEBUG][90] False
2020-05-13 14:41:55.784 [DEBUG][87] Got request [::1]: /services/service (recording.transcode.status)
2020-05-13 14:41:55.784 [DEBUG][87] method=recording.transcode.status
2020-05-13 14:41:55.784 [DEBUG][87] parameters:
2020-05-13 14:41:55.784 [DEBUG][87] method: recording.transcode.status
2020-05-13 14:41:55.785 [DEBUG][87] format: json
2020-05-13 14:41:55.785 [DEBUG][87] sid: 35a79f34-cae4-43da-a6cf-9988c2a57e68
2020-05-13 14:41:55.785 [DEBUG][87] client_ip: ::1
2020-05-13 14:41:55.785 [DEBUG][87] user_agent: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/81.0.4044.138 Safari/537.36 Edg/81.0.416.72
2020-05-13 14:41:55.785 [DEBUG][87] host_callback: localhost:8866
2020-05-13 14:41:55.785 [DEBUG][87] GetStatus()@nochild
2020-05-13 14:41:55.786 [DEBUG][87] {"status": "Streaming Failed (transcoder exited)","final": true,"duration": 0,"percentage": 0}
2020-05-13 14:41:55.917 [DEBUG][87] Got request [::1]: /services/service (recording.transcode.lease)