2015-03-31, 19:15
Don't know if this is the best place to put this, as it's probably not RPi2 specific.
If this post belongs in the TVHeadend section, I apologize for mis-posting.
My setup is a single-seat TVHeadend installation on my RPi2.
Might be worth noting that my RPi2's revision is a21041 (made in PRC, not the UK).
The issue is that while watching any channel, pressing stop has a delay of about one second before actually responding.
** Simply switching channels does not cause it.
Reproduced with builds #0321 - #0330 and build #0202 (the first RPi2 build) - haven't tested the other builds, hopefully those will do for now.
Reproduced with TVHeadend server versions v3.9-2662-ge4cdd3c (uploaded circa #0328 by Milhouse) and v3.9-2496 (from the "Unofficial RPi2 repository")
Being perfectly honest, I haven't tested #0329 because it didn't seem to have any *functional* changes of pvr.hts code. I may be wrong on this one.
The issue does not occur with OpenElec versions 5.0.5, 5.0.6, 5.0.7 (regardless of TVHeadend server version).
The issue does not occur when I watch using Kodi 14.2 on my Win7 PC (the server still being the RPi2 of course) over LAN.
Haven't tested using any version of Kodi v15 on my Win7 yet.
Checked the logs using build #0328 and this is the relevant part IMO:
As mentioned above, build #0202 seems to behave in a similar fashion, but doesn't print all those messages (the debug prints were probably implemented later on).
What I gather from this is the issue seems to depend only on the client version (i.e. the HTSP client version).
Checking the git of pvr.hts, it looks like the messages come from CHTSPDemuxer:arseMuxPacket at src/HTSPDemuxer.cpp
If I understand correctly, the HTSP clients used by Kodi 14.2 and Kodi 15 have many fundamental differences (IIRC most of the code has been rewritten).
Therefore I realize that saying the issue is with pvr.hts version 2.x isn't really saying much. Hopefully some dev can make something of it?
Other than that I must say the nightlies keep looking better and better.
Playing 1080p smoothly with a moderately overclocked RPi2 using OMX acceleration.
Great work!
If this post belongs in the TVHeadend section, I apologize for mis-posting.
My setup is a single-seat TVHeadend installation on my RPi2.
Might be worth noting that my RPi2's revision is a21041 (made in PRC, not the UK).
The issue is that while watching any channel, pressing stop has a delay of about one second before actually responding.
** Simply switching channels does not cause it.
Reproduced with builds #0321 - #0330 and build #0202 (the first RPi2 build) - haven't tested the other builds, hopefully those will do for now.
Reproduced with TVHeadend server versions v3.9-2662-ge4cdd3c (uploaded circa #0328 by Milhouse) and v3.9-2496 (from the "Unofficial RPi2 repository")
Being perfectly honest, I haven't tested #0329 because it didn't seem to have any *functional* changes of pvr.hts code. I may be wrong on this one.
The issue does not occur with OpenElec versions 5.0.5, 5.0.6, 5.0.7 (regardless of TVHeadend server version).
The issue does not occur when I watch using Kodi 14.2 on my Win7 PC (the server still being the RPi2 of course) over LAN.
Haven't tested using any version of Kodi v15 on my Win7 yet.
Checked the logs using build #0328 and this is the relevant part IMO:
Code:
20:47:06 15878.609375 T:1968566272 DEBUG: OnKey: guide (0xe0) pressed, action is Stop
20:47:06 15878.612305 T:1968566272 NOTICE: CDVDPlayer::CloseFile()
20:47:06 15878.612305 T:1968566272 NOTICE: DVDPlayer: waiting for threads to exit
20:47:06 15878.634766 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15878.635742 T:1799570496 DEBUG: Previous line repeats 1 times.
20:47:06 15878.635742 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15878.635742 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15878.636719 T:1799570496 DEBUG: Previous line repeats 1 times.
20:47:06 15878.636719 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15878.701172 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15878.702148 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15878.702148 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15878.702148 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15878.702148 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15878.703125 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15878.765625 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15878.766602 T:1799570496 DEBUG: Previous line repeats 3 times.
20:47:06 15878.766602 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15878.766602 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15878.766602 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15878.836914 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15878.837891 T:1799570496 DEBUG: Previous line repeats 1 times.
20:47:06 15878.837891 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15878.837891 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15878.837891 T:1799570496 DEBUG: Previous line repeats 1 times.
20:47:06 15878.837891 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15878.914062 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15878.914062 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15878.914062 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15878.915039 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15878.915039 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15878.990234 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15878.991211 T:1799570496 DEBUG: Previous line repeats 1 times.
20:47:06 15878.991211 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15878.991211 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15878.992188 T:1799570496 DEBUG: Previous line repeats 3 times.
20:47:06 15878.992188 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15878.992188 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15879.066406 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15879.067383 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15879.067383 T:1799570496 DEBUG: Previous line repeats 1 times.
20:47:06 15879.067383 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15879.067383 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15879.148438 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15879.148438 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15879.149414 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15879.149414 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15879.149414 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15879.232422 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15879.232422 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15879.232422 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15879.233398 T:1799570496 DEBUG: Previous line repeats 5 times.
20:47:06 15879.233398 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15879.233398 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15879.233398 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15879.317383 T:1799570496 DEBUG: Previous line repeats 6 times.
20:47:06 15879.317383 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15879.318359 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15879.318359 T:1799570496 DEBUG: Previous line repeats 1 times.
20:47:06 15879.318359 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15879.318359 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15879.400391 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15879.400391 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15879.400391 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15879.401367 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15879.401367 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15879.401367 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15879.487305 T:1799570496 DEBUG: Previous line repeats 5 times.
20:47:06 15879.487305 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:06 15879.487305 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:06 15879.487305 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:06 15879.487305 T:1799570496 DEBUG: Previous line repeats 1 times.
20:47:06 15879.488281 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:07 15879.560547 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:07 15879.560547 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:07 15879.561523 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:07 15879.561523 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:07 15879.561523 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:07 15879.561523 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:07 15879.562500 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:07 15879.562500 T:1864336448 DEBUG: Previous line repeats 1 times.
20:47:07 15879.562500 T:1864336448 NOTICE: CDVDPlayer::OnExit()
20:47:07 15879.562500 T:1864336448 NOTICE: Closing stream player 1
20:47:07 15879.573242 T:1593828416 NOTICE: thread end: OMXPlayerAudio::OnExit()
20:47:07 15879.573242 T:1593828416 DEBUG: Thread OMXPlayerAudio 1593828416 terminating
20:47:07 15879.575195 T:1906308160 DEBUG: COMXCoreComponent::DecoderEventHandler - OMX.broadcom.audio_mixer Ignoring expected event: eEvent(0x1), nData1(0x8000101c), nData2(0x0), pEventData(0x(nil))
20:47:07 15879.628906 T:1864336448 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_decode handle 0x65e54f20
20:47:07 15879.648438 T:1864336448 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_mixer handle 0x62317c28
20:47:07 15879.650391 T:1864336448 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.audio_render handle 0x61377f50
20:47:07 15879.650391 T:1864336448 NOTICE: Closing stream player 2
20:47:07 15879.650391 T:1739584576 ERROR: OMXPlayerVideo: Got MSGQ_IS_ERROR(-1) Aborting
20:47:07 15879.650391 T:1739584576 NOTICE: thread end: video_thread
20:47:07 15879.650391 T:1739584576 DEBUG: Thread OMXPlayerVideo 1739584576 terminating
20:47:07 15879.654297 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:07 15879.655273 T:1799570496 DEBUG: Previous line repeats 1 times.
20:47:07 15879.655273 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:07 15879.655273 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:07 15879.655273 T:1799570496 DEBUG: Previous line repeats 1 times.
20:47:07 15879.655273 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:07 15879.656250 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:07 15879.656250 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:07 15879.710938 T:1864336448 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_scheduler handle 0x61a8c390
20:47:07 15879.734375 T:1864336448 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_decode handle 0x605e7df8
20:47:07 15879.748047 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:07 15879.749023 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:07 15879.749023 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:07 15879.749023 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 3
20:47:07 15879.749023 T:1864336448 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_fx handle 0x601e9588
20:47:07 15879.750000 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 2
20:47:07 15879.750000 T:1799570496 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Dropped packet with unknown stream index 1
20:47:07 15879.750977 T:1864336448 DEBUG: Previous line repeats 1 times.
20:47:07 15879.750977 T:1864336448 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.video_render handle 0x62319d40
20:47:07 15879.750977 T:1864336448 NOTICE: Closing stream player 3
20:47:07 15879.790039 T:1739584576 NOTICE: Thread JobWorker start, auto delete: true
20:47:07 15879.790039 T:1864336448 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux unsubscribe from 1462100646
20:47:07 15879.791016 T:1739584576 DEBUG: DoWork - Saving file state for video item pvr://channels/tv/כל הערוצים/pvr.hts_1462100646.pvr
20:47:07 15879.791992 T:1864336448 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux successfully unsubscribed 1462100646
20:47:07 15879.792969 T:1864336448 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - demux close
20:47:07 15879.794922 T:1864336448 DEBUG: CDVDInputStreamPVRManager::Close - stream closed
20:47:07 15879.794922 T:1864336448 DEBUG: OMXClock::OMXStop
20:47:07 15879.796875 T:1864336448 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.clock handle 0x62315600
20:47:07 15879.796875 T:1864336448 DEBUG: OnPlayBackStopped: play state was 2, starting 0
20:47:07 15879.796875 T:1864336448 DEBUG: CAnnouncementManager - Announcement: OnStop from xbmc
20:47:07 15879.797852 T:1864336448 DEBUG: GOT ANNOUNCEMENT, type: 1, from xbmc, message OnStop
20:47:07 15879.797852 T:1864336448 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Player, sender=xbmc, message=OnStop)
20:47:07 15879.797852 T:1864336448 DEBUG: Thread DVDPlayer 1864336448 terminating
20:47:07 15879.797852 T:1968566272 NOTICE: DVDPlayer: finished waiting
20:47:07 15879.797852 T:1968566272 DEBUG: CMMALRenderer::UnInitMMAL
20:47:07 15879.818359 T:1968566272 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.image_decode input port 320 output port 321 m_handle 0x28b6c10
20:47:07 15879.820312 T:1968566272 DEBUG: COMXCoreComponent::AllocInputBuffers component(OMX.broadcom.image_decode) - port(320), nBufferCountMin(2), nBufferCountActual(2), nBufferSize(576464), nBufferAlignmen(16)
20:47:07 15879.829102 T:1968566272 DEBUG: COMXCoreComponent::Initialize OMX.broadcom.egl_render input port 220 output port 221 m_handle 0x25608d0
20:47:07 15879.831055 T:1968566272 DEBUG: COMXCoreComponent::UseEGLImage component(OMX.broadcom.egl_render) - port(221), nBufferCountMin(1), nBufferCountActual(1), nBufferSize(15360) nBufferAlignmen(16)
20:47:07 15879.878906 T:1968566272 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.image_decode handle 0x28b6c10
20:47:07 15879.881836 T:1968566272 DEBUG: COMXCoreComponent::Deinitialize : OMX.broadcom.egl_render handle 0x25608d0
20:47:07 15879.881836 T:1968566272 DEBUG: DecodeJpegToTexture: decoded special://skin/backgrounds/tv.jpg 1280x720
20:47:07 15879.916992 T:1968566272 NOTICE: CDVDPlayer::CloseFile()
20:47:07 15879.916992 T:1968566272 NOTICE: DVDPlayer: waiting for threads to exit
20:47:07 15879.916992 T:1968566272 NOTICE: DVDPlayer: finished waiting
20:47:07 15879.917969 T:1968566272 DEBUG: CMMALRenderer::UnInitMMAL
20:47:07 15879.917969 T:1968566272 NOTICE: CDVDPlayer::CloseFile()
As mentioned above, build #0202 seems to behave in a similar fashion, but doesn't print all those messages (the debug prints were probably implemented later on).
What I gather from this is the issue seems to depend only on the client version (i.e. the HTSP client version).
Checking the git of pvr.hts, it looks like the messages come from CHTSPDemuxer:arseMuxPacket at src/HTSPDemuxer.cpp
If I understand correctly, the HTSP clients used by Kodi 14.2 and Kodi 15 have many fundamental differences (IIRC most of the code has been rewritten).
Therefore I realize that saying the issue is with pvr.hts version 2.x isn't really saying much. Hopefully some dev can make something of it?
Other than that I must say the nightlies keep looking better and better.
Playing 1080p smoothly with a moderately overclocked RPi2 using OMX acceleration.
Great work!