2014-01-28, 15:56
Hello,
I am experiencing an error as follows:
- XBMC Gotham nightly from 2014-01-24 running on ATV1200 with 720x567@50Hz component output enabled (but same error with HDMI)
- HW video decoding for AMLogic enabled in settings
- Live TV running with pvr.hts from a TVheadend backend on synology Diskstation, works flawlessly with XBMC nightly on Win32
When switching channels either with Channel up on the remote control or via the TV channels menu, in ca. 1/4 I get a black video screen with audio only playing. Sometimes switching up and down again cures the problem, so basically every channel can be played. When the video is missing, I can see plot information pop up above the Video HMI.
With HW decoding switched off and thus video decoding with ffmpeg, channel switching works perfectly.
Log file: http://xbmclogs.com/show.php?id=115793
Explanation:
Basically, a clean start of XBMC, activate Live TV
From 647. on I start playing the very first TV channel and press 'Up' repeatedly after each channel is showing.
The first 4 channels are playing fine.
At 1138. I press Up for the 5th channel, which plays audio only.
A series of errors show up, which were not thrown with the previous 4 channels:
1163. 21:09:05 T:1651441656 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
...
1183. 21:09:05 T:1680473552 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true (-1)
...
1187. 21:09:05 T:1682982720 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
1188. 21:09:05 T:1682982720 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
...
I do not really know if these errors are to be taken seriously or could be ignored for the problem under investigation.
Then the Decoder is started, 1237 and following:
21:09:05 T:1682982720 DEBUG: FactoryCodec - Video: amcodec - Opening
21:09:05 T:1682982720 INFO: DVDVideoCodecAmlogic: Opened Amlogic Codec
21:09:05 T:1682982720 DEBUG: FactoryCodec - Video: am-mpeg2 - Opened
21:09:05 T:1554405200 DEBUG: CAMLCodec::CloseDecoder
21:09:05 T:1554405200 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(0.000000, 1)
21:09:05 T:1554405200 DEBUG: DVDVideoCodecAmlogic: detected mpeg2 aspect ratio(1.777778), framerate(25.000000), video_rate(3840)
21:09:05 T:1554405200 DEBUG: CAMLCodec::OpenDecoder, android version 16
21:09:05 T:1554405200 DEBUG: codecid_to_vformat, id(2) -> vformat(0)
21:09:05 T:1554405200 DEBUG: codec_tag_to_vdec_type, codec_tag(2) -> vdec_type(0)
21:09:05 T:1554405200 DEBUG: CAMLCodec::OpenDecoder hints.width(704), hints.height(576), hints.codec(2), hints.codec_tag(0), hints.pid(1)
21:09:05 T:1554405200 DEBUG: CAMLCodec::OpenDecoder hints.fpsrate(25000), hints.fpsscale(1000), hints.rfpsrate(25000), hints.rfpsscale(1000), video_rate(3840)
21:09:05 T:1554405200 DEBUG: CAMLCodec::OpenDecoder hints.aspect(1.777778), video_ratio.num(1), video_ratio.den(1)
21:09:05 T:1554405200 DEBUG: CAMLCodec::OpenDecoder hints.orientation(0), hints.forced_aspect(0), hints.extrasize(86)
21:09:05 T:1554405200 DEBUG: CAMLCodec::SetSpeed, speed(1000)
21:09:05 T:1553624800 DEBUG: CAMLCodec::Process Started
21:09:05 T:1554405200 DEBUG: ERROR check in pts error!
21:09:05 T:1554405200 DEBUG: check in pts failed
which results in repeated "check in pts error" messages while playing audio only.
This particular channel is 704x576 instead of 720x576, but that should not represent a major challenge since 704x576 is a usual DVB-T format.
As I understand the code, check in pts results in an ioctl passing the pts data to the running HW decoder. So I interpret the error as the decoder not running properly despite "process started" msg. Maybe the decoder fails on the firmware side, since the XBMC side and interface to the decoder seems to be up and running. But that is a mere guess, not knowing precisely what goes on inside, and not having fully understood the DVDPlayer and AMLCodec architecture.
Is someone else experiencing this behaviour, perhaps on a different AmLogic device or with a different PVR client?
Any ideas what I could investigate to track down the cause of this error?
Will add information as I dig deeper into this issue.
Thank you in advance!!!
Cheers
Michael
I am experiencing an error as follows:
- XBMC Gotham nightly from 2014-01-24 running on ATV1200 with 720x567@50Hz component output enabled (but same error with HDMI)
- HW video decoding for AMLogic enabled in settings
- Live TV running with pvr.hts from a TVheadend backend on synology Diskstation, works flawlessly with XBMC nightly on Win32
When switching channels either with Channel up on the remote control or via the TV channels menu, in ca. 1/4 I get a black video screen with audio only playing. Sometimes switching up and down again cures the problem, so basically every channel can be played. When the video is missing, I can see plot information pop up above the Video HMI.
With HW decoding switched off and thus video decoding with ffmpeg, channel switching works perfectly.
Log file: http://xbmclogs.com/show.php?id=115793
Explanation:
Basically, a clean start of XBMC, activate Live TV
From 647. on I start playing the very first TV channel and press 'Up' repeatedly after each channel is showing.
The first 4 channels are playing fine.
At 1138. I press Up for the 5th channel, which plays audio only.
A series of errors show up, which were not thrown with the previous 4 channels:
1163. 21:09:05 T:1651441656 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
...
1183. 21:09:05 T:1680473552 ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true (-1)
...
1187. 21:09:05 T:1682982720 WARNING: CDVDMessageQueue(audio)::Put MSGQ_NOT_INITIALIZED
1188. 21:09:05 T:1682982720 WARNING: CDVDMessageQueue(video)::Put MSGQ_NOT_INITIALIZED
...
I do not really know if these errors are to be taken seriously or could be ignored for the problem under investigation.
Then the Decoder is started, 1237 and following:
21:09:05 T:1682982720 DEBUG: FactoryCodec - Video: amcodec - Opening
21:09:05 T:1682982720 INFO: DVDVideoCodecAmlogic: Opened Amlogic Codec
21:09:05 T:1682982720 DEBUG: FactoryCodec - Video: am-mpeg2 - Opened
21:09:05 T:1554405200 DEBUG: CAMLCodec::CloseDecoder
21:09:05 T:1554405200 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(0.000000, 1)
21:09:05 T:1554405200 DEBUG: DVDVideoCodecAmlogic: detected mpeg2 aspect ratio(1.777778), framerate(25.000000), video_rate(3840)
21:09:05 T:1554405200 DEBUG: CAMLCodec::OpenDecoder, android version 16
21:09:05 T:1554405200 DEBUG: codecid_to_vformat, id(2) -> vformat(0)
21:09:05 T:1554405200 DEBUG: codec_tag_to_vdec_type, codec_tag(2) -> vdec_type(0)
21:09:05 T:1554405200 DEBUG: CAMLCodec::OpenDecoder hints.width(704), hints.height(576), hints.codec(2), hints.codec_tag(0), hints.pid(1)
21:09:05 T:1554405200 DEBUG: CAMLCodec::OpenDecoder hints.fpsrate(25000), hints.fpsscale(1000), hints.rfpsrate(25000), hints.rfpsscale(1000), video_rate(3840)
21:09:05 T:1554405200 DEBUG: CAMLCodec::OpenDecoder hints.aspect(1.777778), video_ratio.num(1), video_ratio.den(1)
21:09:05 T:1554405200 DEBUG: CAMLCodec::OpenDecoder hints.orientation(0), hints.forced_aspect(0), hints.extrasize(86)
21:09:05 T:1554405200 DEBUG: CAMLCodec::SetSpeed, speed(1000)
21:09:05 T:1553624800 DEBUG: CAMLCodec::Process Started
21:09:05 T:1554405200 DEBUG: ERROR check in pts error!
21:09:05 T:1554405200 DEBUG: check in pts failed
which results in repeated "check in pts error" messages while playing audio only.
This particular channel is 704x576 instead of 720x576, but that should not represent a major challenge since 704x576 is a usual DVB-T format.
As I understand the code, check in pts results in an ioctl passing the pts data to the running HW decoder. So I interpret the error as the decoder not running properly despite "process started" msg. Maybe the decoder fails on the firmware side, since the XBMC side and interface to the decoder seems to be up and running. But that is a mere guess, not knowing precisely what goes on inside, and not having fully understood the DVDPlayer and AMLCodec architecture.
Is someone else experiencing this behaviour, perhaps on a different AmLogic device or with a different PVR client?
Any ideas what I could investigate to track down the cause of this error?
Will add information as I dig deeper into this issue.
Thank you in advance!!!
Cheers
Michael