1. No user installed addons are supported, python or otherwise.
2. No, they really are not supported.
3. They are not coming back
4. Read from 1. again

Any mention of illegal streaming sites, addons or any pirated material will not be tolerated. This is not democracy and any offenders will be banned and posts deleted immediately without warning.

Other than that, we hope you enjoy MrMC so far and we welcome any input and feedback you might have.

Team MrMC.

tvOS 12.3 changed handling of content match frame rate ??

Any issues that are tvOS specific
User avatar
Montoya
Posts: 346
Joined: 27 Sep 2017, 22:46

tvOS 12.3 changed handling of content match frame rate ??

Post by Montoya » 16 May 2019, 11:17

With tvOS 12.3, I see changed handling of content frame rate matching in MrMC.

1. Live TV 1080i 50fps content:
Content frame rate matching DOESN'T function

2. Recordings of Live TV (recordings format 1080P 25fps) :
Content frame rate matching functions

3. Movie 1080p 24fps content:
Content frame rate matching functions

Tested with Apple TV 4K set to:
Video format: 1080p SDR 60Hz
Content matching: frame rate

With Plex beta app and server (not in MrMC) I see ALL above scenarios functioning, including scenario 1 (Live TV 1080i 50 fps), in which Plex directs plays the Live TV content in the Plex app.
Apple TV 4K 64GB tvOS 12.3 - Win10 Pro x64 1903 build 18362.175
DVBLink server 6.0.0 build14499 - TVMosaic server build 17611 - Emby server build 4.2.0.1 beta

User avatar
amet
Team MrMC
Posts: 2725
Joined: 26 Oct 2015, 16:59
Location: Dubai/Novi Sad

Re: tvOS 12.3 changed handling of content match frame rate ??

Post by amet » 16 May 2019, 11:42

nothing we are aware of, debuglog maybe?

User avatar
Montoya
Posts: 346
Joined: 27 Sep 2017, 22:46

Re: tvOS 12.3 changed handling of content match frame rate ??

Post by Montoya » 16 May 2019, 12:13

amet wrote:
16 May 2019, 11:42
nothing we are aware of, debuglog maybe?
Which component logging do you want activated ?
Can you also check TVHeadend with your setup ?
Apple TV 4K 64GB tvOS 12.3 - Win10 Pro x64 1903 build 18362.175
DVBLink server 6.0.0 build14499 - TVMosaic server build 17611 - Emby server build 4.2.0.1 beta

User avatar
amet
Team MrMC
Posts: 2725
Joined: 26 Oct 2015, 16:59
Location: Dubai/Novi Sad

Re: tvOS 12.3 changed handling of content match frame rate ??

Post by amet » 16 May 2019, 15:05

Just a regular debuglog will do, video component logging would help

User avatar
Montoya
Posts: 346
Joined: 27 Sep 2017, 22:46

Re: tvOS 12.3 changed handling of content match frame rate ??

Post by Montoya » 16 May 2019, 17:08

amet wrote:
16 May 2019, 15:05
Just a regular debuglog will do, video component logging would help
Log is in your mailbox :D
Apple TV 4K 64GB tvOS 12.3 - Win10 Pro x64 1903 build 18362.175
DVBLink server 6.0.0 build14499 - TVMosaic server build 17611 - Emby server build 4.2.0.1 beta

User avatar
davilla
Team MrMC
Posts: 3989
Joined: 26 Oct 2015, 17:01

Re: tvOS 12.3 changed handling of content match frame rate ??

Post by davilla » 16 May 2019, 18:20

18:36:50.748 T:6191493120 NOTICE: fps: 0.000000, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080, colorrange: Limited, colorspace: bt709, colortransfer: bt709
18:36:50.748 T:6191493120 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 0.00. format: YV12

no framerate info and ffmpeg is tossing a lot of

18:36:50.321 T:6183350272 ERROR: ffmpeg[1708E7000]: [NULL] non-existing SPS 0 referenced in buffering period
18:36:50.321 T:6183350272 ERROR: ffmpeg[1708E7000]: [NULL] SPS unavailable in decode_picture_timing
18:36:50.321 T:6183350272 ERROR: ffmpeg[1708E7000]: [NULL] non-existing PPS 0 referenced

No frame rate change is even attempted, this is not related to tvOS 12.3 but the binary pvr server.

User avatar
Montoya
Posts: 346
Joined: 27 Sep 2017, 22:46

Re: tvOS 12.3 changed handling of content match frame rate ??

Post by Montoya » 16 May 2019, 18:30

Well, why was it working in pre tvOS 12.3 ?
Always saw frame rate change with DVBLink and TVMosaic.

Plex beta does frame rate matching with same PVR’s in tvOS 12.3
Apple TV 4K 64GB tvOS 12.3 - Win10 Pro x64 1903 build 18362.175
DVBLink server 6.0.0 build14499 - TVMosaic server build 17611 - Emby server build 4.2.0.1 beta

User avatar
Montoya
Posts: 346
Joined: 27 Sep 2017, 22:46

Re: tvOS 12.3 changed handling of content match frame rate ??

Post by Montoya » 16 May 2019, 19:51

@Davilla

Regarding PVR’s, why are same PVR’s in Kodi 18.2 doing refresh rate switching with same channels on same display, if you say it’s a PVR Server issue ?!
Apple TV 4K 64GB tvOS 12.3 - Win10 Pro x64 1903 build 18362.175
DVBLink server 6.0.0 build14499 - TVMosaic server build 17611 - Emby server build 4.2.0.1 beta

User avatar
davilla
Team MrMC
Posts: 3989
Joined: 26 Oct 2015, 17:01

Re: tvOS 12.3 changed handling of content match frame rate ??

Post by davilla » 16 May 2019, 20:09

Montoya wrote:
16 May 2019, 19:51
@Davilla

Regarding PVR’s, why are same PVR’s in Kodi 18.2 doing refresh rate switching with same channels on same display, if you say it’s a PVR Server issue ?!
I see errors from ffmpeg complaining about SPS/PPS. With h264, those need to be present or the stream is garbage. Maybe Kodi 18.2 uses a different server API, or not. Maybe resolved when we sync up, or not. All I can say is ffmpeg is not happy with what it is getting.

User avatar
Montoya
Posts: 346
Joined: 27 Sep 2017, 22:46

Re: tvOS 12.3 changed handling of content match frame rate ??

Post by Montoya » 16 May 2019, 20:30

davilla wrote:
16 May 2019, 20:09
Montoya wrote:
16 May 2019, 19:51
@Davilla

Regarding PVR’s, why are same PVR’s in Kodi 18.2 doing refresh rate switching with same channels on same display, if you say it’s a PVR Server issue ?!
I see errors from ffmpeg complaining about SPS/PPS. With h264, those need to be present or the stream is garbage. Maybe Kodi 18.2 uses a different server API, or not. Maybe resolved when we sync up, or not. All I can say is ffmpeg is not happy with what it is getting.
MrMC could always handle the errors you describe, getting the right fps from stream.

Below a cut/paste from MrMC log dated 6th of april 2018 (MrMC 3.6-1 Git:2018-04-05-b855c86):
Snippet:

Code: Select all


17:29:40 T:6182268928  NOTICE:  fps: 25.000000, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080, colorrange: Limited, colorspace: bt709, colortransfer: bt709

With info error handling:

Code: Select all

17:29:29 T:6170800128   DEBUG: PVRManager - OpenLiveStream - opening live stream on channel 'Ziggo Sport Racing HD'
17:29:29 T:6170800128   DEBUG: opening live stream for channel 'Ziggo Sport Racing HD'
17:29:30 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Player.GetActivePlayers
17:29:30 T:6165639168   DEBUG: ------ Window Init (DialogBusy.xml) ------
17:29:31 T:6170800128   DEBUG: CFileCache::Open - opening <stream/timeshift> using cache
17:29:31 T:6170800128   DEBUG: CurlFile::Open(0x110aa7a00) http://192.168.1.170:xxxx/stream/timeshift?handle=22
17:29:31 T:6170800128   DEBUG: PVRFile - Open - playback has started on filename pvr://channels/tv/All channels/pvr.dvblink_221.pvr
17:29:31 T:6170800128   ERROR: AddOnLog: DVBLink PVR Client: TimeShiftBuffer::GetBufferParams - 1
17:29:31 T:6170800128   DEBUG: CDVDInputStreamPVRManager::Open - stream opened: pvr://channels/tv/All channels/pvr.dvblink_221.pvr
17:29:31 T:6170800128  NOTICE: Creating Demuxer
17:29:31 T:6170800128   DEBUG: Open - m_ioContext->max_packet_size(1) = 32768
17:29:31 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Player.GetActivePlayers
17:29:31 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Playlist.GetItems
17:29:31 T:6178828288   DEBUG: webserver: request received for /jsonrpc?Player.GetProperties
17:29:31 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Player.GetItem
17:29:31 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Player.GetProperties
17:29:31 T:6170800128   DEBUG: Open - probing detected format [mpegts]
17:29:31 T:6170800128   ERROR: AddOnLog: DVBLink PVR Client: TimeShiftBuffer::Seek - 1
17:29:31 T:6170800128   ERROR: AddOnLog: DVBLink PVR Client: TimeShiftBuffer::GetBufferParams - 1
17:29:31 T:6170800128   DEBUG: CFileCache::Open - opening <stream/timeshift> using cache
17:29:31 T:6170800128   DEBUG: CurlFile::Open(0x1111c1e00) http://192.168.1.170:xxxx/stream/timeshift?handle=22
17:29:31 T:6170800128    INFO: ffmpeg[16FCEF000]: Input #0, mpegts, pvr://channels/tv/All channels/pvr.dvblink_221.pv':
17:29:31 T:6170800128    INFO: ffmpeg[16FCEF000]:   Duration: N/A, bitrate: N/A
17:29:31 T:6170800128    INFO: ffmpeg[16FCEF000]:   Program 19370
17:29:31 T:6170800128    INFO: ffmpeg[16FCEF000]:     Stream #0:0[0x125d]: Video: h264 ([27][0][0][0] / 0x001B), none, 90k tbn
17:29:31 T:6170800128    INFO: ffmpeg[16FCEF000]:     Stream #0:1[0x1267](dut): Audio: mp3 ([3][0][0][0] / 0x0003), 0 channels
17:29:31 T:6170800128    INFO: ffmpeg[16FCEF000]:     Stream #0:2[0x1268](ina): Audio: mp3 ([3][0][0][0] / 0x0003), 0 channels
17:29:31 T:6170800128   DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
17:29:31 T:6170800128   DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1
17:29:31 T:6170800128   DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2
17:29:31 T:6170800128   ERROR: AddOnLog: DVBLink PVR Client: TimeShiftBuffer::GetBufferParams - 1
17:29:31 T:6170800128  NOTICE: Opening stream: 0 source: 256
17:29:31 T:6170800128  NOTICE: Creating video codec with codec id: 28
17:29:31 T:6170800128   DEBUG: FactoryCodec - Video: vtb - Opening
17:29:31 T:6170800128   ERROR: CBitstreamConverter::Open avcC data too small or missing
17:29:31 T:6170800128   DEBUG: FactoryCodec - Video: vtb - Failed
17:29:31 T:6170800128   DEBUG: FactoryCodec - Video: avf- - Opening
17:29:31 T:6170800128   ERROR: CBitstreamConverter::Open avcC data too small or missing
17:29:31 T:6170800128   DEBUG: FactoryCodec - Video: avf- - Failed
17:29:31 T:6170800128   DEBUG: FactoryCodec - Video:  - Opening
17:29:31 T:6170800128  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
17:29:31 T:6170800128   DEBUG: CDVDVideoCodecFFmpeg - open frame threaded with 4 threads
17:29:31 T:6170800128   DEBUG: FactoryCodec - Video: ff-h264 - Opened
17:29:31 T:6170800128  NOTICE: Creating video thread
17:29:31 T:6182268928  NOTICE: running thread: video_thread
17:29:31 T:6170800128  NOTICE: Opening stream: 1 source: 256
17:29:31 T:6170800128  NOTICE: Finding audio codec for: 86017
17:29:31 T:6170800128   DEBUG: FactoryCodec - Audio: FFmpeg - Opening
17:29:31 T:6170800128   DEBUG: FactoryCodec - Audio: FFmpeg - Opened
17:29:31 T:6170800128  NOTICE: Creating audio thread
17:29:31 T:6183415808  NOTICE: running thread: CDVDPlayerAudio::Process()
17:29:31 T:6170800128   DEBUG: OnPlayBackStarted: play state was 1, starting 1
17:29:31 T:6165639168   DEBUG: PlayFile: OpenFile succeed, play state 2
17:29:31 T:6165639168   DEBUG: OnPlayBackStarted: play state was 2, starting 0
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:31 T:6165639168   DEBUG: CGUIInfoManager::SetCurrentMovie(pvr://channels/tv/All channels/pvr.dvblink_221.pvr)
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:31 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6175961088   ERROR: AddOnLog: DVBLink PVR Client: TimeShiftBuffer::GetBufferParams - 1
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6165639168   DEBUG: ------ Window Deinit (DialogBusy.xml) ------
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] SPS unavailable in decode_picture_timing
17:29:32 T:6170800128   ERROR: ffmpeg[16FCEF000]: [NULL] non-existing PPS 0 referenced
17:29:32 T:6170800128   DEBUG: CDVDDemuxFFmpeg::Read() fetching extradata, extradata_size(71)
17:29:32 T:6170800128   DEBUG: ffmpeg[16FCEF000]: [h264] Ignoring NAL type 9 in extradata
17:29:32 T:6170800128   DEBUG: ffmpeg[16FCEF000]: [h264] Reinit context to 1920x1088, pix_fmt: yuv420p
17:29:32 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Player.GetActivePlayers
17:29:32 T:6178828288   DEBUG: webserver: request received for /jsonrpc?Player.GetProperties
17:29:32 T:6170800128   DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
17:29:32 T:6170800128  NOTICE: Closing stream player 2
17:29:32 T:6170800128  NOTICE: waiting for video thread to exit
17:29:32 T:6182268928   ERROR: Got MSGQ_ABORT or MSGO_IS_ERROR return true
17:29:32 T:6182268928  NOTICE: thread end: video_thread
17:29:32 T:6170800128  NOTICE: deleting video codec
17:29:32 T:6170800128  NOTICE: Opening stream: 0 source: 256
17:29:32 T:6170800128  NOTICE: Creating video codec with codec id: 28
17:29:32 T:6170800128   DEBUG: FactoryCodec - Video: vtb - Opening
17:29:32 T:6170800128    INFO: CBitstreamConverter::Open annexb to bitstream init
17:29:32 T:6170800128  NOTICE: Open - interlaced content.
17:29:32 T:6170800128   DEBUG: FactoryCodec - Video: vtb - Failed
17:29:32 T:6170800128   DEBUG: FactoryCodec - Video: avf- - Opening
17:29:32 T:6170800128    INFO: CBitstreamConverter::Open annexb to bitstream init
17:29:32 T:6170800128  NOTICE: Open - interlaced content.
17:29:32 T:6170800128   DEBUG: FactoryCodec - Video: avf- - Failed
17:29:32 T:6170800128   DEBUG: FactoryCodec - Video:  - Opening
17:29:32 T:6170800128  NOTICE: CDVDVideoCodecFFmpeg::Open() Using codec: H.264 / AVC / MPEG-4 AVC / MPEG-4 part 10
17:29:32 T:6170800128   DEBUG: CDVDVideoCodecFFmpeg - open frame threaded with 4 threads
17:29:32 T:6170800128   DEBUG: ffmpeg[16FCEF000]: [h264] Ignoring NAL type 9 in extradata
17:29:32 T:6170800128   DEBUG: FactoryCodec - Video: ff-h264 - Opened
17:29:32 T:6170800128  NOTICE: Creating video thread
17:29:32 T:6182268928  NOTICE: running thread: video_thread
17:29:32 T:6182268928    INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
17:29:32 T:6182268928   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
17:29:32 T:6179401728   DEBUG: Previous line repeats 1 times.
17:29:32 T:6179401728   DEBUG: ffmpeg[170523000]: [h264] Reinit context to 1920x1088, pix_fmt: yuv420p
17:29:32 T:6182268928   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
17:29:32 T:6179975168   DEBUG: ffmpeg[1705AF000]: [h264] Increasing reorder buffer to 1
17:29:32 T:6182268928   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
17:29:32 T:6177107968   DEBUG: webserver: request received for /image/image://http%253a%252f%252f192.168.1.170%253axxxx%252fstream%252fplayback%253fobject%253d13868c28-a9ad-4432-a90d-c4f161b57b99%25253aNL_logo%25252fZiggo%252bSport%252bRacing%252bHD.png/
17:29:32 T:6181122048   DEBUG: ffmpeg[1706C7000]: [h264] Increasing reorder buffer to 2
17:29:32 T:6178828288   DEBUG: webserver: request received for /jsonrpc?Playlist.GetItems
17:29:32 T:6183415808   DEBUG: CDVDPlayerAudio: Sample rate changed, checking for passthrough
17:29:32 T:6183415808   DEBUG: FactoryCodec - Audio: passthrough - Opening
17:29:32 T:6183415808   DEBUG: SETTING_AUDIOOUTPUT_PASSTHROUGH is false
17:29:32 T:6183415808   DEBUG: FactoryCodec - Audio: passthrough - Failed
17:29:32 T:6183415808   DEBUG: FactoryCodec - Audio: FFmpeg - Opening
17:29:32 T:6183415808   DEBUG: FactoryCodec - Audio: FFmpeg - Opened
17:29:32 T:6183415808  NOTICE: Creating audio stream (codec id: 86017, channels: 2, sample rate: 48000, no pass-through)
17:29:32 T:6183415808   DEBUG: CDVDPlayerAudio:: synctype set to 1: resample
17:29:32 T:6167359488    INFO: CActiveAESink::OpenSink - initialize sink
17:29:32 T:6167359488   DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
17:29:32 T:6167359488   DEBUG: virtual bool CAESinkDARWINIOS::Initialize(AEAudioFormat &, std::string &): AudioStreamBasicDescription: [lpcm] Mixable Interleaved 2 Channel 32-bit Floating Point LE (48000Hz) pcm
17:29:32 T:6167359488  NOTICE: bool CAAudioUnitSink::setupAudio() setting channels 2
17:29:32 T:6167359488  NOTICE: bool CAAudioUnitSink::setupAudio() setting samplerate 48000.000000
17:29:32 T:6167359488  NOTICE: bool CAAudioUnitSink::setupAudio() setting buffer duration to 0.032000
17:29:32 T:6178828288   DEBUG: webserver: request received for /jsonrpc?Player.GetItem
17:29:32 T:6177107968   DEBUG: CCurlFile::GetMimeType - http://192.168.1.170:xxxx/stream/playback?object=13868c28-a9ad-4432-a90d-c4f161b57b99%3aNL_logo%2fZiggo+Sport+Racing+HD.png -> video/mpeg
17:29:32 T:6167359488  NOTICE: bool CAAudioUnitSink::setupAudio() total latency = 0.044500
17:29:32 T:6167359488  NOTICE: bool CAAudioUnitSink::setupAudio() setup audio format: [lpcm] Mixable Interleaved 2 Channel 32-bit Floating Point LE (48000Hz)
17:29:32 T:6167359488  NOTICE: void dumpAVAudioSessionProperties() audio route = HDMIOutput
17:29:32 T:6167359488  NOTICE: void dumpAVAudioSessionProperties() sampleRate 48000.000000
17:29:32 T:6167359488  NOTICE: void dumpAVAudioSessionProperties() outputLatency 0.001833
17:29:32 T:6167359488  NOTICE: void dumpAVAudioSessionProperties() IOBufferDuration 0.042667
17:29:32 T:6167359488  NOTICE: void dumpAVAudioSessionProperties() outputNumberOfChannels 2
17:29:32 T:6167359488  NOTICE: void dumpAVAudioSessionProperties() maximumOutputNumberOfChannels 6
17:29:32 T:6167359488   DEBUG: DumpAudioDescriptions: void dumpAVAudioSessionProperties()
17:29:32 T:6167359488   DEBUG: DumpAudioDescriptions: input count = 0
17:29:32 T:6182268928   DEBUG: ffmpeg[1707DF000]: [src] w:1920 h:1080 pixfmt:yuv420p tb:1/50 fr:0/1 sar:1/1 sws_param:
17:29:32 T:6167359488   DEBUG: DumpAudioDescriptions: output count = 1
17:29:32 T:6167359488   DEBUG: DumpAudioDescriptions : portName, Apple TV 4K 64GB
17:29:32 T:6167359488   DEBUG: DumpAudioDescriptions: channelLabel, 1
17:29:32 T:6167359488   DEBUG: DumpAudioDescriptions: channelName , Apple TV 4K 64GB 1
17:29:32 T:6167359488   DEBUG: DumpAudioDescriptions: channelLabel, 2
17:29:32 T:6167359488   DEBUG: DumpAudioDescriptions: channelName , Apple TV 4K 64GB 2
17:29:32 T:6182268928   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
17:29:32 T:6167359488   DEBUG: CActiveAESink::OpenSink - DARWINIOS Initialized:
17:29:32 T:6167359488   DEBUG:   Output Device : Default
17:29:32 T:6167359488   DEBUG:   Sample Rate   : 48000
17:29:32 T:6167359488   DEBUG:   Sample Format : AE_FMT_FLOAT
17:29:32 T:6167359488   DEBUG:   Channel Count : 2
17:29:32 T:6167359488   DEBUG:   Channel Layout: FL,FR
17:29:32 T:6167359488   DEBUG:   Frames        : 1024
17:29:32 T:6167359488   DEBUG:   Frame Size    : 8
17:29:32 T:6167359488   DEBUG: CActiveAESink::GenerateNoise - noiseFactor = 0.000010
17:29:32 T:6166786048   DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
17:29:32 T:6182268928   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
17:29:32 T:6182268928   DEBUG: Previous line repeats 4 times.
17:29:32 T:6182268928  NOTICE:  fps: 0.000000, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080, colorrange: Limited, colorspace: bt709, colortransfer: bt709
17:29:32 T:6182268928   DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 0.00. format: YV12
17:29:32 T:6182268928   DEBUG: CXBMCRenderManager::Configure - 5
17:29:32 T:6165639168  NOTICE: Using GL_TEXTURE_2D
17:29:32 T:6165639168   DEBUG: GL: Requested render method: 0
17:29:32 T:6165639168  NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
17:29:32 T:6165639168  NOTICE: GL: NPOT texture support detected
17:29:32 T:6165639168   DEBUG: Activating window ID: 12005
17:29:32 T:6165639168   DEBUG: ------ Window Deinit (MyPVRChannels.xml) ------
17:29:32 T:6165639168   DEBUG: ------ Window Init (VideoFullScreen.xml) ------
17:29:32 T:6165639168    INFO: Loading skin file: VideoFullScreen.xml, load type: KEEP_IN_MEMORY
17:29:32 T:6170800128   DEBUG: CDVDPlayer::HandleMessages - player started DVDPLAYER_VIDEO
17:29:32 T:6170800128   DEBUG: CDVDPlayer::HandleMessages - player started DVDPLAYER_AUDIO
17:29:32 T:6170800128   DEBUG: CDVDPlayer::Sync - Audio - pts: 13988913466.000000, cache: 459409.935743, totalcache: 685333.311558
17:29:32 T:6170800128   DEBUG: CDVDPlayer::Sync - Video - pts: 13989769466.000000, cache: 50000.000000, totalcache: 100000.000000
17:29:32 T:6170800128   DEBUG: CDVDPlayer::LogCacheState(HandlePlaySpeed) - CACHESTATE_DONE
17:29:32 T:6183415808   DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(13987908132.688442)
17:29:32 T:6183415808   DEBUG: CDVDAudio::Resume - resume audio stream
17:29:32 T:6182268928   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(13987908132.688442)
17:29:32 T:6182268928   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
17:29:32 T:6166786048   DEBUG: ActiveAE - start sync of audio stream
17:29:33 T:6170800128   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
17:29:33 T:6182268928   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
17:29:33 T:6166786048   DEBUG: ActiveAE::SyncStream - average error of 562.962635, start adjusting
17:29:33 T:6175961088   ERROR: AddOnLog: DVBLink PVR Client: TimeShiftBuffer::GetBufferParams - 1
17:29:33 T:6166786048   ERROR: Previous line repeats 1 times.
17:29:33 T:6166786048   DEBUG: ActiveAE::SyncStream - average error 20.987394, last average error: 562.962635
17:29:33 T:6166786048   DEBUG: ActiveAE::SyncStream - average error of 21.269943, start adjusting
17:29:33 T:6166786048   DEBUG: ActiveAE::SyncStream - average error 0.269943 below threshold of 30.000000
17:29:33 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Player.GetProperties
17:29:33 T:6178828288   DEBUG: webserver: request received for /jsonrpc?Player.GetActivePlayers
17:29:33 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Playlist.GetItems
17:29:33 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Player.GetItem
17:29:34 T:6175961088   ERROR: AddOnLog: DVBLink PVR Client: TimeShiftBuffer::GetBufferParams - 1
17:29:34 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Playlist.GetItems
17:29:34 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Player.GetActivePlayers
17:29:34 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Player.GetProperties
17:29:34 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Player.GetItem
17:29:35 T:6182268928  NOTICE: CDVDVideoCodecFFmpeg::CDropControl: calculated diff time: 40000
17:29:35 T:6170800128   ERROR: AddOnLog: DVBLink PVR Client: TimeShiftBuffer::GetBufferParams - 1
17:29:35 T:6188003328   ERROR: Previous line repeats 1 times.
17:29:35 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Player.GetActivePlayers
17:29:35 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Player.GetProperties
17:29:35 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Playlist.GetItems
17:29:35 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Player.GetItem
17:29:36 T:6175961088   ERROR: AddOnLog: DVBLink PVR Client: TimeShiftBuffer::GetBufferParams - 1
17:29:36 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Player.GetActivePlayers
17:29:36 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Player.GetProperties
17:29:36 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Playlist.GetItems
17:29:36 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Player.GetItem
17:29:37 T:6188003328   ERROR: AddOnLog: DVBLink PVR Client: TimeShiftBuffer::GetBufferParams - 1
17:29:37 T:6188003328   ERROR: Previous line repeats 1 times.
17:29:37 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Player.GetActivePlayers
17:29:37 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Player.GetProperties
17:29:37 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Playlist.GetItems
17:29:38 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Player.GetItem
17:29:38 T:6175961088   ERROR: AddOnLog: DVBLink PVR Client: TimeShiftBuffer::GetBufferParams - 1
17:29:38 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Player.GetActivePlayers
17:29:39 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Playlist.GetItems
17:29:39 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Player.GetProperties
17:29:39 T:6177107968   ERROR: AddOnLog: DVBLink PVR Client: TimeShiftBuffer::GetBufferParams - 1
17:29:39 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Player.GetItem
17:29:39 T:6175961088   ERROR: AddOnLog: DVBLink PVR Client: TimeShiftBuffer::GetBufferParams - 1
17:29:39 T:6170800128   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: 0.000000
17:29:39 T:6170800128   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
17:29:39 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Player.GetActivePlayers
17:29:40 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Playlist.GetItems
17:29:40 T:6177107968   DEBUG: webserver: request received for /jsonrpc?Player.GetProperties
17:29:40 T:6188003328   DEBUG: webserver: request received for /jsonrpc?Player.GetItem
17:29:40 T:6170800128   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: 0.000000
17:29:40 T:6170800128   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: -0.010000
17:29:40 T:6182268928   DEBUG: CalcFrameRate framerate was:25.000000 calculated:25.000000
17:29:40 T:6182268928  NOTICE:  fps: 25.000000, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080, colorrange: Limited, colorspace: bt709, colortransfer: bt709
17:29:40 T:6182268928   DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 25.00. format: YV12
17:29:40 T:6175961088   ERROR: AddOnLog: DVBLink PVR Client: TimeShiftBuffer::GetBufferParams - 1
17:29:40 T:6182268928  NOTICE: Display resolution DESKTOP : 1920x1080@ 50.00 - Full Screen (16)
17:29:40 T:6182268928   DEBUG: displayRateSwitch request: refreshRate = 25.00, dynamicRange = SDR
17:29:40 T:6182268928   DEBUG: CXBMCRenderManager::Configure - 5
17:29:40 T:6165639168  NOTICE: Using GL_TEXTURE_2D
17:29:40 T:7113503424   DEBUG: CWinSystemIOS::AnnounceOnLostDevice
17:29:40 T:6165639168   DEBUG: GL: Requested render method: 0
17:29:40 T:6165639168  NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
17:29:40 T:6165639168  NOTICE: GL: NPOT texture support detected
17:29:40 T:6182268928   DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
17:29:40 T:6170800128   DEBUG: CDVDPlayer::HandlePlaySpeed set clock adjust: 0.000000
17:29:40 T:7113503424  NOTICE: CDVDPlayer: OnLostDevice received
17:29:40 T:7113503424   DEBUG: displayModeSwitchInProgress == YES, refreshRate = 25.00, dynamicRange = SDR
17:29:40 T:6182268928   DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_PAUSE: 1
Apple TV 4K 64GB tvOS 12.3 - Win10 Pro x64 1903 build 18362.175
DVBLink server 6.0.0 build14499 - TVMosaic server build 17611 - Emby server build 4.2.0.1 beta

Post Reply