TVheadend, surging playback of HD-TV recordings

Help and Support Vero
1

Hello,

Environment:
HW: Vero (but same issue with Pi2)
OS: OSMC 2015-08.01
SW: TVheadend Server 4.0.5 (OSMC preinstalled), TVheadend HTSP CLient 2.1.16 (OSMC preinstalled)

Situation:
My Vero and Pi2 are configured to use IPTV via TVheadend from an “AVM FRITZ!WAN Repeater DVB-C” device which is a popular WLAN repeater in Germany also hosting 2 DVB-C tuners.

Problem:
Playback of HD-recordings by TVheadend starts surging after some seconds if playback is started via the “Live TV - Recordings” menu.
Pressing OK while the play back you can see in the progress bar with very short recordings that the buffered data is getting smaller and smaller till the issue starts. Also on the right side of the progress bar the icons for Video Settings/Audio Settings/Bookmarks and Subtitle are missing.

The data resides on an external HDD, ext4, connected by USB.

Workaround:
If the playback of these recordings is started using the “Videos → Files” menu, all is running in a perfect way without any problem! So, this a current workaraound but from here I do not have acces to the EPG information shown in the “TV Live - recordings” menu.

With activated debugging the problem isn’t reproducable at all! I tried anyway to upload the logs after starting playback in “TV Live -recording” menu with activated debugging:
http://paste.osmc.io/exoqikuxqy (so it was displayed after upload from My OSMC)

The mediainfo text for such a problem recording you can find at:
http://paste.osmc.io/acolecavan.pl

With thx in advance for all your great work,
JimKnopf

2

Meanwhile I discovered that a reboot of the Vero (or the Pi2) also makes the playback to work in the Recordings menu of TV Live which is now a second workaround.
So the instructions to activate debugging and then reboot the device led to the Situation the problem could not be reproduced. Will try to activate debugging, reproduce the issue and deactivate debugging before uploading the logs on next occurance.

3

Again I ran into this strange issue. Activated Debugging, reproduced issue, deactivated Debugging and uploaded logs.

screenshot001.png1920×1080 60.9 KB

After a reboot of the Vero, the recorded TV session could be played without any problems in the recording menu of TV Live.

4

Simply providing the link itself http://paste.osmc.io/olotoseyur would make things far easier for those attempting to assist you. Would also be far less forum resources wasted on your screenshot… Thanks…

5

Can you provide a sample file?

I’m not a TVHE guru but it looks to me like there is some error in the formatting of the recordings.

    19:35:20 T:1956687872  NOTICE: DVDPlayer: Opening: pvr://recordings/active///heute, TV (ZDF HD), 20150924_170000.pvr
19:35:20 T:1956687872 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:35:20 T:1956687872   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
19:35:20 T:1956687872   DEBUG: LinuxRendererGL: Cleaning up GL resources
19:35:20 T:1128363040  NOTICE: Thread DVDPlayer start, auto delete: false
19:35:20 T:1128363040  NOTICE: Creating InputStream
19:35:20 T:1128363040   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs open file=dvr/418758033
19:35:20 T:1128363040   DEBUG: Open - playback has started on recording pvr://recordings/active///heute, TV (ZDF HD), 20150924_170000.pvr ()
19:35:20 T:1128363040   DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://recordings/active///heute, TV (ZDF HD), 20150924_170000.pvr
19:35:20 T:1128363040  NOTICE: Creating Demuxer
19:35:20 T:1128363040   DEBUG: Open - probing detected format [mpegts]
19:35:20 T:1128363040   DEBUG: Open - avformat_find_stream_info starting
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] non-existing SPS 0 referenced in buffering period
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] non-existing PPS 0 referenced
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] non-existing SPS 0 referenced in buffering period
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] non-existing PPS 0 referenced
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] decode_slice_header error
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] no frame!
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] non-existing SPS 0 referenced in buffering period
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] non-existing PPS 0 referenced
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] non-existing SPS 0 referenced in buffering period
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] non-existing PPS 0 referenced
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] decode_slice_header error
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] no frame!
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [mp3] Header missing
19:35:20 T:1128363040   ERROR: Previous line repeats 1 times.
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] non-existing SPS 0 referenced in buffering period
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] non-existing PPS 0 referenced
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] non-existing SPS 0 referenced in buffering period
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] non-existing PPS 0 referenced
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] decode_slice_header error
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [h264] no frame!
19:35:20 T:1128363040   ERROR: ffmpeg[43417420]: [mp3] Header missing
19:35:20 T:1128363040   ERROR: Previous line repeats 1 times.
6

This might be helpful? unable to play Canal Digital HD Channels. XBMC Log included - Tvheadend

7

Hi ActionA, thx for your Attention. To be honest, the provided link isn’t helpful for me since it seems to be another situation (no recording playback), neither finding of the root cause nor a solution or idea for another Workaround is provided.

Sure, I can upload such recording but the last one is 5 GB in size; just let me know where to upload this data.

8

It would be preferable if you could create a ~100-200mb snippet either a software such as mkvmerge. You could provide it via Dropbox or Google drive or similar.

9

Ok, used mkvtoolnix to cut the original ts container file in pieces which are now in mkv container format.
The first 200 MB piece (belongs to the extra time before target start time of recording) I uploaded here

https://drive.google.com/open?id=0B67NtpiwvnIKWERpb2oyOExQZjA

Remember:

  • The original recording will always play perfectly starting the playback from the Video->Files menu of osmc.
  • The original recording will also play in a perfect way from the TV Live → Recordings menu, if I reboot the Vero once after the recording finished.
  • The playback is choppy if started in the TV Live → Recordings menu after recording is finished without additional actions.
10

While your overall issue sounds a bit different this might somehow be related to a issue that came with Kodi 15 and is fixed in the nightly files.

11

If you briefly play a different Recorded TV video that was recorded earlier then stop it does the new Recorded TV show now play correctly??

12

@Dilligraf: No, watching another earlier recording (playing fine) and then start the playback of such new one results in the same surging effects.

Additional Information: If I pause such choppy playback and then continue, the playback is smooth for around 12 seconds … so for me it’s a buffering issue (at least as a sideeffect of the issue).

Does it make sense to upload two further log collections?

  • one with active Debugging playing such new recording
  • the second after a reboot of the Vero and active debugging playing the same recording

Perhaps if you compare the logs you can find out something from the delta? Just an idea but before I waste your space of the upload server I would like to see a GO for this.

13

Ok, I reproduced the issue with debugging on and took another kodil.log snapshot later after the reboot from the same playback and compared the delta. I marked the first significant difference by putting blank lines around the specific line:

Playback (no issue) after reboot of the Vero:

13:11:01 T:1956458496   DEBUG: Loading settings for pvr://recordings/active///Terra X, TV (ZDF_neo HD), 20150927_100500.pvr
13:11:01 T:1956458496   DEBUG: CPlayerCoreFactory::GetPlayers(pvr://recordings/active///Terra X, TV (ZDF_neo HD), 20150927_100500.pvr)
13:11:01 T:1956458496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
13:11:01 T:1956458496   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
13:11:01 T:1956458496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/mms/udp
13:11:01 T:1956458496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
13:11:01 T:1956458496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
13:11:01 T:1956458496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
13:11:01 T:1956458496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
13:11:01 T:1956458496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
13:11:01 T:1956458496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
13:11:01 T:1956458496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
13:11:01 T:1956458496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
13:11:01 T:1956458496   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
13:11:01 T:1956458496   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
13:11:01 T:1956458496   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
13:11:01 T:1956458496   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
13:11:01 T:1956458496   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
13:11:01 T:1956458496   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
13:11:01 T:1956458496   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
13:11:01 T:1956458496  NOTICE: DVDPlayer: Opening: pvr://recordings/active///Terra X, TV (ZDF_neo HD), 20150927_100500.pvr
13:11:01 T:1956458496 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
13:11:01 T:1956458496   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
13:11:01 T:1956458496   DEBUG: LinuxRendererGL: Cleaning up GL resources
13:11:01 T:1461261344  NOTICE: Thread DVDPlayer start, auto delete: false
13:11:01 T:1461261344  NOTICE: Creating InputStream
13:11:01 T:1461261344   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs open file=dvr/2143195726
13:11:01 T:1461261344   DEBUG: Open - playback has started on recording pvr://recordings/active///Terra X, TV (ZDF_neo HD), 20150927_100500.pvr ()
13:11:01 T:1461261344   DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://recordings/active///Terra X, TV (ZDF_neo HD), 20150927_100500.pvr
13:11:01 T:1461261344  NOTICE: Creating Demuxer
13:11:01 T:1461261344   DEBUG: Open - probing detected format [mpegts]
13:11:01 T:1461261344   DEBUG: Open - avformat_find_stream_info starting
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [mp3] Header missing
13:11:02 T:1461261344   ERROR: Previous line repeats 3 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] non-existing PPS 0 referenced
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] decode_slice_header error
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] no frame!
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [mp3] Header missing
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] non-existing PPS 0 referenced
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] decode_slice_header error
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] no frame!
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] non-existing PPS 0 referenced
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] decode_slice_header error
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] no frame!
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] non-existing PPS 0 referenced
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] decode_slice_header error
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] no frame!
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [mp3] Header missing
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] non-existing PPS 0 referenced
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] decode_slice_header error
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] no frame!
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] non-existing PPS 0 referenced
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] decode_slice_header error
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] no frame!
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] non-existing PPS 0 referenced
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] decode_slice_header error
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] no frame!
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [mp3] Header missing
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] non-existing PPS 0 referenced
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] decode_slice_header error
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] no frame!
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] non-existing PPS 0 referenced
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] decode_slice_header error
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] no frame!
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] non-existing PPS 0 referenced
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] decode_slice_header error
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] no frame!
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [mp3] Header missing
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] non-existing PPS 0 referenced
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] decode_slice_header error
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] no frame!
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] non-existing PPS 0 referenced
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] decode_slice_header error
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] no frame!
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] non-existing PPS 0 referenced
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] decode_slice_header error
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] no frame!
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [mp3] Header missing
13:11:02 T:1461261344   ERROR: Previous line repeats 7 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] non-existing PPS 0 referenced
13:11:02 T:1461261344   ERROR: Previous line repeats 1 times.
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] decode_slice_header error
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [h264] no frame!
13:11:02 T:1956458496   DEBUG: ------ Window Init (DialogBusy.xml) ------
13:11:02 T:1956458496   DEBUG: LIRC: Update - NEW at 103015:160 0 KEY_OK_UP linux-input-layer (KEY_OK_UP)
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [mp3] Header missing
13:11:02 T:1461261344   ERROR: Previous line repeats 8 times.
13:11:02 T:1461261344   DEBUG: ffmpeg[57191420]: [h264] Increasing reorder buffer to 1
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [mp3] Header missing
13:11:02 T:1461261344   ERROR: Previous line repeats 3 times.
13:11:02 T:1461261344   DEBUG: ffmpeg[57191420]: [mpegts] max_analyze_duration 500000 reached at 580000 microseconds
13:11:02 T:1461261344    INFO: ffmpeg[57191420]: [mpegts] decoding for stream 6 failed
13:11:02 T:1461261344   DEBUG: ffmpeg[57191420]: [NULL] start time for stream 5 is not set in estimate_timings_from_pts
13:11:02 T:1461261344   DEBUG: ffmpeg[57191420]: [mpegts] PES packet size mismatch
13:11:02 T:1461261344   DEBUG: Previous line repeats 4 times.
13:11:02 T:1461261344   DEBUG: ffmpeg[57191420]: [mpegts] Could not find codec parameters for stream 6 (Audio: mp3, 0 channels, s16p): unspecified frame size
13:11:02 T:1461261344   DEBUG: ffmpeg[57191420]: [mpegts] Consider increasing the value for the 'analyzeduration' and 'probesize' options
13:11:02 T:1461261344   DEBUG: Open - av_find_stream_info finished
13:11:02 T:1461261344    INFO: ffmpeg[57191420]: Input #0, mpegts, from 'pvr://recordings/active///Terra X, TV (ZDF_neo HD), 20150927_100500.pvr':
13:11:02 T:1461261344    INFO: ffmpeg[57191420]:   Duration: 00:57:30.77, start: 46784.979467, bitrate: 8102 kb/s
13:11:02 T:1461261344    INFO: ffmpeg[57191420]:   Program 41103
13:11:02 T:1461261344    INFO: ffmpeg[57191420]:     Metadata:
13:11:02 T:1461261344    INFO: ffmpeg[57191420]:       service_name    : ZDF_neo HD
13:11:02 T:1461261344    INFO: ffmpeg[57191420]:       service_provider: Unitymedia
13:11:02 T:1461261344    INFO: ffmpeg[57191420]:     Stream #0:0[0x229]: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709), 50 fps, 50 tbr, 90k tbn, 100 tbc
13:11:02 T:1461261344    INFO: ffmpeg[57191420]:     Stream #0:1[0x22a](ger): Audio: mp2 ([4][0][0][0] / 0x0004), 48000 Hz, stereo, s16p, 256 kb/s
13:11:02 T:1461261344    INFO: ffmpeg[57191420]:     Stream #0:2[0x22b](mis): Audio: mp2 ([4][0][0][0] / 0x0004), 48000 Hz, stereo, s16p, 192 kb/s
13:11:02 T:1461261344    INFO: ffmpeg[57191420]:     Stream #0:3[0x22c](ger): Audio: ac3 ([129][0][0][0] / 0x0081), 48000 Hz, stereo, fltp, 448 kb/s
13:11:02 T:1461261344    INFO: ffmpeg[57191420]:     Stream #0:4[0x22d](mul): Audio: mp2 ([4][0][0][0] / 0x0004), 48000 Hz, stereo, s16p, 192 kb/s
13:11:02 T:1461261344    INFO: ffmpeg[57191420]:     Stream #0:5[0x669](ger): Subtitle: dvb_subtitle ([6][0][0][0] / 0x0006)
13:11:02 T:1461261344    INFO: ffmpeg[57191420]:   No Program
13:11:02 T:1461261344    INFO: ffmpeg[57191420]:     Stream #0:6[0x22e]: Audio: mp3, 0 channels, s16p
13:11:02 T:1461261344   DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
13:11:02 T:1461261344   DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1
13:11:02 T:1461261344   DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2
13:11:02 T:1461261344   DEBUG: CDVDDemuxFFmpeg::AddStream(3, ...) -> 3
13:11:02 T:1461261344   DEBUG: CDVDDemuxFFmpeg::AddStream(4, ...) -> 4
13:11:02 T:1461261344   DEBUG: CDVDDemuxFFmpeg::AddStream(5, ...) -> 5
13:11:02 T:1461261344  NOTICE: Opening stream: 0 source: 256
13:11:02 T:1461261344  NOTICE: Creating video codec with codec id: 28
13:11:02 T:1461261344   DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:no VAAPI:no iMXVPU:yes MMAL:no
13:11:02 T:1461261344   DEBUG: FactoryCodec - Video: iMX-xxx - Opening
13:11:02 T:1461261344   DEBUG: Let's decode with iMX VPU
13:11:02 T:1461261344   DEBUG: Decode: MEDIAINFO: fpsrate 50 / fpsscale 1
13:11:02 T:1461261344   DEBUG: Decode: MEDIAINFO: CodecID 28
13:11:02 T:1461261344   DEBUG: Decode: MEDIAINFO: StreamType 2
13:11:02 T:1461261344   DEBUG: Decode: MEDIAINFO: Level 40
13:11:02 T:1461261344   DEBUG: Decode: MEDIAINFO: Profile 100
13:11:02 T:1461261344   DEBUG: Decode: MEDIAINFO: PTS_invalid 0
13:11:02 T:1461261344   DEBUG: Decode: MEDIAINFO: Tag 27
13:11:02 T:1461261344   DEBUG: Decode: MEDIAINFO: 0x720
13:11:02 T:1461261344   DEBUG: Decode: MEDIAINFO: 0 / 720
13:11:02 T:1461261344   DEBUG: Decode: aspect 0.000000 - forced aspect 0
13:11:02 T:1461261344   DEBUG: FactoryCodec - Video: iMX-h264 - Opened
13:11:02 T:1461261344  NOTICE: Creating video thread
13:11:02 T:1478038560  NOTICE: Thread DVDPlayerVideo start, auto delete: false
13:11:02 T:1478038560  NOTICE: running thread: video_thread
13:11:02 T:1461261344  NOTICE: Opening stream: 3 source: 256
13:11:02 T:1461261344  NOTICE: Finding audio codec for: 86019
13:11:02 T:1461261344   DEBUG: FactoryCodec - Audio: passthrough - Opening
13:11:02 T:1461261344   DEBUG: FactoryCodec - Audio: passthrough - Opened
13:11:02 T:1461261344  NOTICE: Creating audio thread
13:11:02 T:1461261344  NOTICE: Opening stream: 5 source: 256
13:11:02 T:1461261344   DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening
13:11:02 T:1461261344   DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opened
13:11:02 T:1461261344   DEBUG: ReadEditDecisionLists - Checking for edit decision list (EDL) for PVR recording: pvr://recordings/active///Terra X, TV (ZDF_neo HD), 20150927_100500.pvr
13:11:02 T:1461261344   DEBUG: ReadPvr - Reading Edl for recording: Terra X
13:11:02 T:1478038560   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
13:11:02 T:1461261344   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - dvr get cutpoints id=2143195726
13:11:02 T:1469649952  NOTICE: Thread DVDPlayerAudio start, auto delete: false
13:11:02 T:1469649952  NOTICE: running thread: CDVDPlayerAudio::Process()
13:11:02 T:1469649952   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
13:11:02 T:1461261344   DEBUG: OnPlayBackStarted: play state was 1, starting 1

13:11:02 T:1461261344   DEBUG: CDVDPlayer::SetCaching - caching state 3

13:11:02 T:1956458496   DEBUG: PlayFile: OpenFile succeed, play state 2
13:11:02 T:1956458496   DEBUG: OnPlayBackStarted: play state was 2, starting 0
13:11:02 T:1461261344   ERROR: ffmpeg[57191420]: [NULL] non-existing PPS 0 referenced
13:11:02 T:1956458496   ERROR: Previous line repeats 1 times.
13:11:02 T:1956458496   DEBUG: ------ Window Init (VideoOverlay.xml) ------

Choppy playback after recording and try to playback from TV-Live -> Recording menu:

13:02:25 T:1956335616   DEBUG: Loading settings for pvr://recordings/active///Terra X, TV (ZDF_neo HD), 20150927_100500.pvr
13:02:26 T:1956335616   DEBUG: CPlayerCoreFactory::GetPlayers(pvr://recordings/active///Terra X, TV (ZDF_neo HD), 20150927_100500.pvr)
13:02:26 T:1956335616   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: system rules
13:02:26 T:1956335616   DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
13:02:26 T:1956335616   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: hdhomerun/mms/udp
13:02:26 T:1956335616   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: lastfm/shout
13:02:26 T:1956335616   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtmp
13:02:26 T:1956335616   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: rtsp
13:02:26 T:1956335616   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: streams
13:02:26 T:1956335616   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvd
13:02:26 T:1956335616   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: dvdimage
13:02:26 T:1956335616   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: sdp/asf
13:02:26 T:1956335616   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: nsv
13:02:26 T:1956335616   DEBUG: CPlayerSelectionRule::GetPlayers: considering rule: radio
13:02:26 T:1956335616   DEBUG: CPlayerCoreFactory::GetPlayers: matched 0 rules with players
13:02:26 T:1956335616   DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
13:02:26 T:1956335616   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
13:02:26 T:1956335616   DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
13:02:26 T:1956335616   DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
13:02:26 T:1956335616   DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
13:02:26 T:1956335616  NOTICE: DVDPlayer: Opening: pvr://recordings/active///Terra X, TV (ZDF_neo HD), 20150927_100500.pvr
13:02:26 T:1956335616 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
13:02:26 T:1956335616   DEBUG: CRenderManager::UpdateDisplayLatency - Latency set to 0 msec
13:02:26 T:1956335616   DEBUG: LinuxRendererGL: Cleaning up GL resources
13:02:26 T:1891763232  NOTICE: Thread DVDPlayer start, auto delete: false
13:02:26 T:1891763232  NOTICE: Creating InputStream
13:02:26 T:1891763232   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - vfs open file=dvr/2143195726
13:02:26 T:1891763232   DEBUG: Open - playback has started on recording pvr://recordings/active///Terra X, TV (ZDF_neo HD), 20150927_100500.pvr ()
13:02:26 T:1891763232   DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://recordings/active///Terra X, TV (ZDF_neo HD), 20150927_100500.pvr
13:02:26 T:1891763232  NOTICE: Creating Demuxer
13:02:26 T:1891763232   DEBUG: Open - probing detected format [mpegts]
13:02:26 T:1891763232   DEBUG: Open - avformat_find_stream_info starting
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [mp3] Header missing
13:02:26 T:1891763232   ERROR: Previous line repeats 3 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] non-existing PPS 0 referenced
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] decode_slice_header error
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] no frame!
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [mp3] Header missing
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] non-existing PPS 0 referenced
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] decode_slice_header error
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] no frame!
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] non-existing PPS 0 referenced
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] decode_slice_header error
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] no frame!
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] non-existing PPS 0 referenced
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] decode_slice_header error
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] no frame!
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [mp3] Header missing
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] non-existing PPS 0 referenced
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] decode_slice_header error
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] no frame!
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] non-existing PPS 0 referenced
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] decode_slice_header error
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] no frame!
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] non-existing PPS 0 referenced
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] decode_slice_header error
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] no frame!
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [mp3] Header missing
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] non-existing PPS 0 referenced
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] decode_slice_header error
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] no frame!
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] non-existing PPS 0 referenced
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] decode_slice_header error
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] no frame!
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] non-existing PPS 0 referenced
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] decode_slice_header error
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] no frame!
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [mp3] Header missing
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] non-existing PPS 0 referenced
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] decode_slice_header error
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] no frame!
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] non-existing PPS 0 referenced
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] decode_slice_header error
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] no frame!
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] non-existing PPS 0 referenced
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] decode_slice_header error
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] no frame!
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [mp3] Header missing
13:02:26 T:1891763232   ERROR: Previous line repeats 7 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] non-existing PPS 0 referenced
13:02:26 T:1891763232   ERROR: Previous line repeats 1 times.
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] decode_slice_header error
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [h264] no frame!
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [mp3] Header missing
13:02:26 T:1956335616   DEBUG: ------ Window Init (DialogBusy.xml) ------
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [mp3] Header missing
13:02:26 T:1956335616   ERROR: Previous line repeats 6 times.
13:02:26 T:1956335616   DEBUG: LIRC: Update - NEW at 19187289:160 0 KEY_OK_UP linux-input-layer (KEY_OK_UP)
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [mp3] Header missing
13:02:26 T:1891763232   DEBUG: ffmpeg[70C20420]: [h264] Increasing reorder buffer to 1
13:02:26 T:1891763232   ERROR: ffmpeg[70C20420]: [mp3] Header missing
13:02:26 T:1891763232   ERROR: Previous line repeats 3 times.
13:02:26 T:1891763232   DEBUG: ffmpeg[70C20420]: [mpegts] max_analyze_duration 500000 reached at 580000 microseconds
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]: [mpegts] decoding for stream 6 failed
13:02:26 T:1891763232   DEBUG: ffmpeg[70C20420]: [NULL] start time for stream 5 is not set in estimate_timings_from_pts
13:02:26 T:1891763232   DEBUG: ffmpeg[70C20420]: [mpegts] PES packet size mismatch
13:02:26 T:1891763232   DEBUG: Previous line repeats 4 times.
13:02:26 T:1891763232   DEBUG: ffmpeg[70C20420]: [mpegts] Could not find codec parameters for stream 6 (Audio: mp3, 0 channels, s16p): unspecified frame size
13:02:26 T:1891763232   DEBUG: ffmpeg[70C20420]: [mpegts] Consider increasing the value for the 'analyzeduration' and 'probesize' options
13:02:26 T:1891763232   DEBUG: Open - av_find_stream_info finished
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]: Input #0, mpegts, from 'pvr://recordings/active///Terra X, TV (ZDF_neo HD), 20150927_100500.pvr':
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]:   Duration: 00:57:30.77, start: 46784.979467, bitrate: 8102 kb/s
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]:   Program 41103
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]:     Metadata:
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]:       service_name    : ZDF_neo HD
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]:       service_provider: Unitymedia
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]:     Stream #0:0[0x229]: Video: h264 (High) ([27][0][0][0] / 0x001B), yuv420p(tv, bt709), 50 fps, 50 tbr, 90k tbn, 100 tbc
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]:     Stream #0:1[0x22a](ger): Audio: mp2 ([4][0][0][0] / 0x0004), 48000 Hz, stereo, s16p, 256 kb/s
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]:     Stream #0:2[0x22b](mis): Audio: mp2 ([4][0][0][0] / 0x0004), 48000 Hz, stereo, s16p, 192 kb/s
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]:     Stream #0:3[0x22c](ger): Audio: ac3 ([129][0][0][0] / 0x0081), 48000 Hz, stereo, fltp, 448 kb/s
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]:     Stream #0:4[0x22d](mul): Audio: mp2 ([4][0][0][0] / 0x0004), 48000 Hz, stereo, s16p, 192 kb/s
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]:     Stream #0:5[0x669](ger): Subtitle: dvb_subtitle ([6][0][0][0] / 0x0006)
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]:   No Program
13:02:26 T:1891763232    INFO: ffmpeg[70C20420]:     Stream #0:6[0x22e]: Audio: mp3, 0 channels, s16p
13:02:26 T:1891763232   DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
13:02:26 T:1891763232   DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1
13:02:26 T:1891763232   DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2
13:02:26 T:1891763232   DEBUG: CDVDDemuxFFmpeg::AddStream(3, ...) -> 3
13:02:26 T:1891763232   DEBUG: CDVDDemuxFFmpeg::AddStream(4, ...) -> 4
13:02:26 T:1891763232   DEBUG: CDVDDemuxFFmpeg::AddStream(5, ...) -> 5
13:02:26 T:1891763232  NOTICE: Opening stream: 0 source: 256
13:02:26 T:1891763232  NOTICE: Creating video codec with codec id: 28
13:02:26 T:1891763232   DEBUG: CDVDFactoryCodec: compiled in hardware support: AMCodec:no MediaCodec:no OpenMax:no libstagefright:no VDPAU:no VAAPI:no iMXVPU:yes MMAL:no
13:02:26 T:1891763232   DEBUG: FactoryCodec - Video: iMX-xxx - Opening
13:02:26 T:1891763232   DEBUG: Let's decode with iMX VPU
13:02:26 T:1891763232   DEBUG: Decode: MEDIAINFO: fpsrate 50 / fpsscale 1
13:02:26 T:1891763232   DEBUG: Decode: MEDIAINFO: CodecID 28
13:02:26 T:1891763232   DEBUG: Decode: MEDIAINFO: StreamType 2
13:02:26 T:1891763232   DEBUG: Decode: MEDIAINFO: Level 40
13:02:26 T:1891763232   DEBUG: Decode: MEDIAINFO: Profile 100
13:02:26 T:1891763232   DEBUG: Decode: MEDIAINFO: PTS_invalid 0
13:02:26 T:1891763232   DEBUG: Decode: MEDIAINFO: Tag 27
13:02:26 T:1891763232   DEBUG: Decode: MEDIAINFO: 0x720
13:02:26 T:1891763232   DEBUG: Decode: MEDIAINFO: 0 / 720
13:02:26 T:1891763232   DEBUG: Decode: aspect 0.000000 - forced aspect 0
13:02:26 T:1891763232   DEBUG: FactoryCodec - Video: iMX-h264 - Opened
13:02:26 T:1891763232  NOTICE: Creating video thread
13:02:26 T:1514140704  NOTICE: Thread DVDPlayerVideo start, auto delete: false
13:02:26 T:1514140704  NOTICE: running thread: video_thread
13:02:26 T:1891763232  NOTICE: Opening stream: 3 source: 256
13:02:26 T:1891763232  NOTICE: Finding audio codec for: 86019
13:02:26 T:1891763232   DEBUG: FactoryCodec - Audio: passthrough - Opening
13:02:26 T:1891763232   DEBUG: FactoryCodec - Audio: passthrough - Opened
13:02:26 T:1891763232  NOTICE: Creating audio thread
13:02:26 T:1891763232  NOTICE: Opening stream: 5 source: 256
13:02:26 T:1891763232   DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opening
13:02:26 T:1891763232   DEBUG: FactoryCodec - Overlay: FFmpeg Subtitle Decoder - Opened
13:02:26 T:1891763232   DEBUG: ReadEditDecisionLists - Checking for edit decision list (EDL) for PVR recording: pvr://recordings/active///Terra X, TV (ZDF_neo HD), 20150927_100500.pvr
13:02:26 T:1891763232   DEBUG: ReadPvr - Reading Edl for recording: Terra X
13:02:26 T:1891763232   DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - dvr get cutpoints id=2143195726
13:02:26 T:1514140704   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
13:02:26 T:1688073248  NOTICE: Thread DVDPlayerAudio start, auto delete: false
13:02:26 T:1688073248  NOTICE: running thread: CDVDPlayerAudio::Process()
13:02:26 T:1688073248   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE

13:02:26 T:1891763232   DEBUG: CDVDPlayer::SetCaching - caching state 2

13:02:26 T:1891763232   DEBUG: OnPlayBackStarted: play state was 1, starting 1
13:02:26 T:1956335616   DEBUG: PlayFile: OpenFile succeed, play state 2
13:02:26 T:1956335616   DEBUG: OnPlayBackStarted: play state was 2, starting 0
13:02:26 T:1956335616   DEBUG: ------ Window Init (VideoOverlay.xml) ------
14

Meanwhile detected that even after the reboot the playback from the TV-Live-recordings menu isn’t perfect means there are micro-judders every 4-5 seconds if there is a lot of movement in the video.

Till now the only good workaround is to play the recordings from the Video-Files menu within OSMC. Since the sparse feedback here, I fear I’m somewhat alone with this and have to live with it. :cry:

15

Just to give feedback about progress to this:
Although OSMC 2015-09.1 seems to improve the situation, the micro judders were still visible from time to time.
Meanwhile I switched the TVheadend recording location away from an external hard disk and use a mounted smb drive from a NAS device instead. With that all works like a champ till now, also after OSMC 2015-09.2 update.
I’ve tried 3 different USB hard disks and several USB 3.0 sticks before, all with ext4 but no luck. ok2cl