I get some trouble playing my tv recordings. After a few seconds the playback stops shortly and the progress bar is to be be seen, then the playback runs flawlessly to the end. I can reproduce this on several episodes.
Code: Select all
19:30:26.922 T:6102740992 NOTICE: Found 1 Lists of Devices
19:30:26.922 T:6102740992 NOTICE: Enumerated DARWINIOS devices:
19:30:26.922 T:6102740992 NOTICE: Device 1
19:30:26.922 T:6102740992 NOTICE: m_deviceName : default
19:30:26.922 T:6102740992 NOTICE: m_displayName : Default
19:30:26.922 T:6102740992 NOTICE: m_displayNameExtra:
19:30:26.922 T:6102740992 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
19:30:26.922 T:6102740992 NOTICE: m_channels : FL,FR,FC,LFE,BL,BR
19:30:26.922 T:6102740992 NOTICE: m_sampleRates : 44100,48000
19:30:26.922 T:6102740992 NOTICE: m_dataFormats : AE_FMT_S16LE,AE_FMT_FLOAT
19:30:26.922 T:6102740992 NOTICE: m_streamTypes : No passthrough capabilities
19:30:26.928 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting channels 6
19:30:26.928 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting samplerate 48000.000000
19:30:26.928 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting buffer duration to 0.128000
19:30:26.933 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() total latency = 0.066000
19:30:26.933 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setup audio format: [lpcm] Mixable Interleaved 6 Channel 32-bit Floating Point LE (48000Hz)
19:30:26.933 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() audio route = HDMIOutput
19:30:26.933 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() sampleRate 48000.000000
19:30:26.933 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputLatency 0.034000
19:30:26.933 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() IOBufferDuration 0.032000
19:30:26.933 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputNumberOfChannels 6
19:30:26.933 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() maximumOutputNumberOfChannels 6
19:30:27.017 T:6102740992 NOTICE: Found 1 Lists of Devices
19:30:27.017 T:6102740992 NOTICE: Enumerated DARWINIOS devices:
19:30:27.017 T:6102740992 NOTICE: Device 1
19:30:27.018 T:6102740992 NOTICE: m_deviceName : default
19:30:27.018 T:6102740992 NOTICE: m_displayName : Default
19:30:27.018 T:6102740992 NOTICE: m_displayNameExtra:
19:30:27.018 T:6102740992 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
19:30:27.018 T:6102740992 NOTICE: m_channels : FL,FR,FC,LFE,BL,BR
19:30:27.018 T:6102740992 NOTICE: m_sampleRates : 44100,48000
19:30:27.018 T:6102740992 NOTICE: m_dataFormats : AE_FMT_S16LE,AE_FMT_FLOAT
19:30:27.018 T:6102740992 NOTICE: m_streamTypes : No passthrough capabilities
19:30:27.029 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting channels 6
19:30:27.029 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting samplerate 48000.000000
19:30:27.029 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting buffer duration to 0.128000
19:30:28.882 T:4426979328 NOTICE: starting zeroconf publishing
19:30:28.884 T:4426979328 NOTICE: WebServer: Started the webserver
19:30:28.886 T:4426979328 NOTICE: starting upnp client
19:30:28.891 T:6114783232 NOTICE: ES: Starting UDP Event server on 127.0.0.1:9777
19:30:28.892 T:6114783232 NOTICE: UDP: Listening on port 9777
19:30:29.139 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() total latency = 0.066000
19:30:29.139 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setup audio format: [lpcm] Mixable Interleaved 6 Channel 32-bit Floating Point LE (48000Hz)
19:30:29.141 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() audio route = HDMIOutput
19:30:29.141 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() sampleRate 48000.000000
19:30:29.141 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputLatency 0.034000
19:30:29.141 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() IOBufferDuration 0.032000
19:30:29.141 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputNumberOfChannels 6
19:30:29.141 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() maximumOutputNumberOfChannels 6
19:30:29.171 T:6102740992 NOTICE: Found 1 Lists of Devices
19:30:29.171 T:6102740992 NOTICE: Enumerated DARWINIOS devices:
19:30:29.171 T:6102740992 NOTICE: Device 1
19:30:29.171 T:6102740992 NOTICE: m_deviceName : default
19:30:29.171 T:6102740992 NOTICE: m_displayName : Default
19:30:29.171 T:6102740992 NOTICE: m_displayNameExtra:
19:30:29.171 T:6102740992 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
19:30:29.171 T:6102740992 NOTICE: m_channels : FL,FR,FC,LFE,BL,BR
19:30:29.171 T:6102740992 NOTICE: m_sampleRates : 44100,48000
19:30:29.171 T:6102740992 NOTICE: m_dataFormats : AE_FMT_S16LE,AE_FMT_FLOAT
19:30:29.171 T:6102740992 NOTICE: m_streamTypes : No passthrough capabilities
19:30:29.174 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting channels 6
19:30:29.174 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting samplerate 48000.000000
19:30:29.174 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting buffer duration to 0.128000
19:30:30.216 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() total latency = 0.066000
19:30:30.217 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setup audio format: [lpcm] Mixable Interleaved 6 Channel 32-bit Floating Point LE (48000Hz)
19:30:30.217 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() audio route = HDMIOutput
19:30:30.217 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() sampleRate 48000.000000
19:30:30.217 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputLatency 0.034000
19:30:30.217 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() IOBufferDuration 0.032000
19:30:30.217 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputNumberOfChannels 6
19:30:30.217 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() maximumOutputNumberOfChannels 6
19:30:30.257 T:6102740992 NOTICE: Found 1 Lists of Devices
19:30:30.257 T:6102740992 NOTICE: Enumerated DARWINIOS devices:
19:30:30.257 T:6102740992 NOTICE: Device 1
19:30:30.257 T:6102740992 NOTICE: m_deviceName : default
19:30:30.257 T:6102740992 NOTICE: m_displayName : Default
19:30:30.257 T:6102740992 NOTICE: m_displayNameExtra:
19:30:30.257 T:6102740992 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
19:30:30.257 T:6102740992 NOTICE: m_channels : FL,FR,FC,LFE,BL,BR
19:30:30.257 T:6102740992 NOTICE: m_sampleRates : 44100,48000
19:30:30.257 T:6102740992 NOTICE: m_dataFormats : AE_FMT_S16LE,AE_FMT_FLOAT
19:30:30.257 T:6102740992 NOTICE: m_streamTypes : No passthrough capabilities
19:30:30.260 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting channels 6
19:30:30.260 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting samplerate 48000.000000
19:30:30.260 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting buffer duration to 0.128000
19:30:31.349 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() total latency = 0.066000
19:30:31.349 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setup audio format: [lpcm] Mixable Interleaved 6 Channel 32-bit Floating Point LE (48000Hz)
19:30:31.350 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() audio route = HDMIOutput
19:30:31.352 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() sampleRate 48000.000000
19:30:31.352 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputLatency 0.034000
19:30:31.353 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() IOBufferDuration 0.032000
19:30:31.353 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputNumberOfChannels 6
19:30:31.353 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() maximumOutputNumberOfChannels 6
19:30:31.389 T:6102740992 NOTICE: Found 1 Lists of Devices
19:30:31.389 T:6102740992 NOTICE: Enumerated DARWINIOS devices:
19:30:31.389 T:6102740992 NOTICE: Device 1
19:30:31.389 T:6102740992 NOTICE: m_deviceName : default
19:30:31.389 T:6102740992 NOTICE: m_displayName : Default
19:30:31.389 T:6102740992 NOTICE: m_displayNameExtra:
19:30:31.389 T:6102740992 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
19:30:31.389 T:6102740992 NOTICE: m_channels : FL,FR,FC,LFE,BL,BR
19:30:31.389 T:6102740992 NOTICE: m_sampleRates : 44100,48000
19:30:31.389 T:6102740992 NOTICE: m_dataFormats : AE_FMT_S16LE,AE_FMT_FLOAT
19:30:31.389 T:6102740992 NOTICE: m_streamTypes : No passthrough capabilities
19:30:31.392 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting channels 6
19:30:31.392 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting samplerate 48000.000000
19:30:31.392 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting buffer duration to 0.128000
19:30:32.475 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() total latency = 0.066000
19:30:32.475 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setup audio format: [lpcm] Mixable Interleaved 6 Channel 32-bit Floating Point LE (48000Hz)
19:30:32.479 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() audio route = HDMIOutput
19:30:32.479 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() sampleRate 48000.000000
19:30:32.482 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputLatency 0.034000
19:30:32.482 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() IOBufferDuration 0.032000
19:30:32.482 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputNumberOfChannels 6
19:30:32.483 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() maximumOutputNumberOfChannels 6
19:30:46.584 T:6101594112 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM"
19:30:46.584 T:6101594112 NOTICE: Enabled debug logging due to GUI setting. Level 2.
19:30:53.915 T:6101594112 DEBUG: found key /userdata/profiles.xml
19:30:53.917 T:6101594112 DEBUG: Previous line repeats 1 times.
19:30:53.917 T:6101594112 DEBUG: NSUSerDefaults: compressed /userdata/profiles.xml from 941 to 346
19:30:53.918 T:6101594112 DEBUG: found key /userdata/guisettings.xml
19:30:53.925 T:6101594112 DEBUG: Previous line repeats 1 times.
19:30:53.925 T:6101594112 DEBUG: NSUSerDefaults: compressed /userdata/guisettings.xml from 30680 to 6197
19:30:53.927 T:6101594112 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
19:30:54.030 T:6101594112 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------
19:30:54.031 T:6101594112 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
19:30:54.032 T:6101594112 DEBUG: ------ Window Init (Settings.xml) ------
19:30:54.999 T:6101594112 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
19:30:55.123 T:6101594112 DEBUG: ------ Window Deinit (Settings.xml) ------
19:30:55.124 T:6101594112 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
19:30:55.124 T:6101594112 DEBUG: ------ Window Init (Home.xml) ------
19:30:55.127 T:6101594112 DEBUG: CGUIWindowHome::SetupServices() - serverType(mrmc) , serverUUID(mrmc)
19:30:55.128 T:6101594112 DEBUG: CMultiPathDirectory::GetDirectory(multipath://multipath%3a%2f%2fspecial%253a%252f%252fprofile%252fplaylists%252fmusic%2fspecial%253a%252f%252fprofile%252fplaylists%252fmixed%2f/multipath%3a%2f%2fspecial%253a%252f%252fprofile%252fplaylists%252fvideo%2fspecial%253a%252f%252fprofile%252fplaylists%252fmixed%2f/)
19:30:55.128 T:6101594112 DEBUG: Getting Directory (multipath://special%3a%2f%2fprofile%2fplaylists%2fmusic/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
19:30:55.128 T:6101594112 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fmusic/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
19:30:55.128 T:6101594112 DEBUG: Getting Directory (special://profile/playlists/music)
19:30:55.129 T:6101594112 DEBUG: Getting Directory (special://profile/playlists/mixed)
19:30:55.129 T:6101594112 DEBUG: CMultiPathDirectory::MergeItems, items = 0
19:30:55.129 T:6101594112 DEBUG: Getting Directory (multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
19:30:55.129 T:6101594112 DEBUG: CMultiPathDirectory::GetDirectory(multipath://special%3a%2f%2fprofile%2fplaylists%2fvideo/special%3a%2f%2fprofile%2fplaylists%2fmixed/)
19:30:55.129 T:6101594112 DEBUG: Getting Directory (special://profile/playlists/video)
19:30:55.130 T:6101594112 DEBUG: Getting Directory (special://profile/playlists/mixed)
19:30:55.130 T:6101594112 DEBUG: CMultiPathDirectory::MergeItems, items = 0
19:30:55.130 T:6101594112 DEBUG: Previous line repeats 1 times.
19:30:55.130 T:6101594112 DEBUG: CGUIWindowHome::SetupStaticHomeButtons()
19:30:55.130 T:6110195712 DEBUG: CHomeShelfJob::UpdateMusic() - Running HomeShelf screen update
19:30:55.156 T:6110195712 DEBUG: GetRecentlyAddedAlbums query: SELECT albumview.*, albumartistview.* FROM (SELECT idAlbum FROM album WHERE strAlbum != '' ORDER BY idAlbum DESC LIMIT 10) AS recentalbums JOIN albumview ON albumview.idAlbum = recentalbums.idAlbum JOIN albumartistview ON albumview.idAlbum = albumartistview.idAlbum ORDER BY albumview.idAlbum desc, albumartistview.iOrder
19:30:55.179 T:6110195712 DEBUG: CHomeShelfJob::UpdateVideos() - Running HomeShelf screen update
19:30:55.330 T:6110195712 NOTICE: WakeOnAccess [192.168.70.44] trigged by accessing : smb://192.168.70.44/XBMC/TV/Legends (2014)/Season 2/Legends (2014) S02E01-thumb.jpg
19:30:55.331 T:6110195712 NOTICE: WakeOnAccess success exit, server already running
19:30:58.551 T:6101594112 DEBUG: Activating window ID: 10025
19:30:58.664 T:6101594112 DEBUG: ------ Window Deinit (Home.xml) ------
19:30:58.687 T:6101594112 DEBUG: ------ Window Init (MyVideoNav.xml) ------
19:30:58.984 T:6101594112 ERROR: CGUITextureManager::GetTexturePath: could not find texture 'thumbs/OverlayWatched.png'
19:31:05.229 T:6101594112 ERROR: Previous line repeats 17 times.
19:31:05.229 T:6101594112 DEBUG: ------ Window Init (DialogBusy.xml) ------
19:31:07.154 T:6101594112 DEBUG: WindowVideoNav::GetDirectory
19:31:08.033 T:6101594112 DEBUG: ------ Window Deinit (DialogBusy.xml) ------
19:31:13.667 T:6101594112 DEBUG: found key /userdata/addon_data/metadata.tvdb.com/settings.xml
19:31:13.795 T:6101594112 DEBUG: ------ Window Init (DialogVideoInfo.xml) ------
19:31:16.224 T:6101594112 DEBUG: ------ Window Deinit (DialogVideoInfo.xml) ------
19:31:16.255 T:6101594112 DEBUG: OnPlayMedia smb://192.168.70.44/XBMC/TV/Watchmen/Season 1/Watchmen S01E04.mkv
19:31:16.280 T:6101594112 DEBUG: Loading settings for smb://192.168.70.44/XBMC/TV/Watchmen/Season 1/Watchmen S01E04.mkv
19:31:16.309 T:6101594112 DEBUG: CPlayerCoreFactory::GetPlayers(smb://192.168.70.44/XBMC/TV/Watchmen/Season 1/Watchmen S01E04.mkv)
19:31:16.309 T:6101594112 DEBUG: CPlayerSelectionRule::GetPlayers: matches rule: system rules
19:31:16.309 T:6101594112 DEBUG: CPlayerCoreFactory::GetPlayers: adding videodefaultplayer (1)
19:31:16.309 T:6101594112 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=0
19:31:16.309 T:6101594112 DEBUG: CPlayerCoreFactory::GetPlayers: for video=1, audio=1
19:31:16.309 T:6101594112 DEBUG: CPlayerCoreFactory::GetPlayers: adding player: DVDPlayer (1)
19:31:16.309 T:6101594112 DEBUG: CPlayerCoreFactory::GetPlayers: added 1 players
19:31:16.310 T:6101594112 NOTICE: CDVDPlayer::Opening: smb://192.168.70.44/XBMC/TV/Watchmen/Season 1/Watchmen S01E04.mkv
19:31:16.310 T:6101594112 WARNING: CDVDMessageQueue(player)::Put MSGQ_NOT_INITIALIZED
19:31:16.311 T:6098726912 NOTICE: Creating InputStream
19:31:16.314 T:6098726912 DEBUG: CDSMFile::Open - opened XBMC/TV/Watchmen/Season 1/Watchmen S01E04.mkv, fd=268533800
19:31:16.314 T:6098726912 DEBUG: ScanForExternalSubtitles: Searching for subtitles...
19:31:16.315 T:6098726912 DEBUG: ScanForExternalSubtitles: END (total time: 1 ms)
19:31:16.315 T:6098726912 NOTICE: Creating Demuxer
19:31:16.315 T:6098726912 DEBUG: Open - m_ioContext->max_packet_size(1) = 32768
19:31:16.336 T:6098726912 DEBUG: Read - max read bytes = 32768
19:31:16.336 T:6098726912 DEBUG: Open - probing detected format [matroska,webm]
19:31:16.336 T:6098726912 DEBUG: Open - avformat_find_stream_info starting
19:31:16.337 T:6098726912 DEBUG: ffmpeg[16B833000]: [h264] Reinit context to 1920x1088, pix_fmt: yuv420p
19:31:16.347 T:6098726912 DEBUG: Open - av_find_stream_info finished
19:31:16.347 T:6098726912 INFO: ffmpeg[16B833000]: Input #0, matroska,webm, smb://192.168.70.44/XBMC/TV/Watchmen/Season 1/Watchmen S01E04.mk':
19:31:16.347 T:6098726912 INFO: ffmpeg[16B833000]: Metadata:
19:31:16.347 T:6098726912 INFO: ffmpeg[16B833000]: CREATION_TIME : 2019-11-26T15:34:53Z
19:31:16.347 T:6098726912 INFO: ffmpeg[16B833000]: ENCODER : Lavf57.7.2
19:31:16.347 T:6098726912 INFO: ffmpeg[16B833000]: Duration: 00:49:47.73, start: 0.000000, bitrate: 3450 kb/s
19:31:16.347 T:6098726912 INFO: ffmpeg[16B833000]: Stream #0:0: Video: h264 (High), yuv420p(tv, bt709, progressive), 1920x1080 [SAR 1:1 DAR 16:9], 25 fps, 25 tbr, 1k tbn, 50 tbc (default)
19:31:16.347 T:6098726912 INFO: ffmpeg[16B833000]: Stream #0:1(ger): Audio: ac3, 48000 Hz, 5.1(side), fltp, 384 kb/s (default)
19:31:16.347 T:6098726912 INFO: ffmpeg[16B833000]: Metadata:
19:31:16.347 T:6098726912 INFO: ffmpeg[16B833000]: title : Surround
19:31:16.347 T:6098726912 INFO: ffmpeg[16B833000]: Stream #0:2(eng): Audio: ac3, 48000 Hz, stereo, fltp, 192 kb/s
19:31:16.347 T:6098726912 INFO: ffmpeg[16B833000]: Metadata:
19:31:16.347 T:6098726912 INFO: ffmpeg[16B833000]: title : Stereo
19:31:16.347 T:6098726912 DEBUG: CDVDDemuxFFmpeg::AddStream(0, ...) -> 0
19:31:16.347 T:6098726912 DEBUG: CDVDDemuxFFmpeg::AddStream(1, ...) -> 1
19:31:16.347 T:6098726912 DEBUG: CDVDDemuxFFmpeg::AddStream(2, ...) -> 2
19:31:16.347 T:6098726912 NOTICE: Opening stream: 0 source: 256
19:31:16.347 T:6098726912 NOTICE: Creating video codec with codec id: 28
19:31:16.347 T:6098726912 DEBUG: FactoryCodec - Video: vtb - Opening
19:31:16.347 T:6098726912 NOTICE: Constructing new format description
19:31:16.377 T:6098726912 DEBUG: VideoToolBox: opened width(1920), height(1080)
19:31:16.377 T:6098726912 DEBUG: FactoryCodec - Video: vtb-h264 - Opened
19:31:16.377 T:6098726912 NOTICE: Creating video thread
19:31:16.377 T:6098726912 NOTICE: Opening stream: 1 source: 256
19:31:16.377 T:6098726912 NOTICE: Finding audio codec for: 86019
19:31:16.378 T:6102167552 NOTICE: running thread: video_thread
19:31:16.382 T:6098726912 DEBUG: FactoryCodec - Audio: FFmpeg - Opening
19:31:16.383 T:6098726912 DEBUG: FactoryCodec - Audio: FFmpeg - Opened
19:31:16.383 T:6098726912 NOTICE: Creating audio thread
19:31:16.384 T:6106181632 NOTICE: running thread: CDVDPlayerAudio::Process()
19:31:16.384 T:6098726912 DEBUG: ReadEditDecisionLists - Checking for edit decision lists (EDL) on local drive or remote share for: smb://192.168.70.44/XBMC/TV/Watchmen/Season 1/Watchmen S01E04.mkv
19:31:16.385 T:6098726912 DEBUG: OnPlayBackStarted: play state was 1, starting 1
19:31:16.386 T:6101594112 DEBUG: PlayFile: OpenFile succeed, play state 2
19:31:16.386 T:6102167552 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
19:31:16.386 T:6102167552 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
19:31:16.386 T:6101594112 DEBUG: OnPlayBackStarted: play state was 2, starting 0
19:31:16.398 T:6106181632 NOTICE: Creating audio stream (codec id: 86019, channels: 6, sample rate: 48000, no pass-through)
19:31:16.399 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:31:16.399 T:6106181632 DEBUG: CDVDPlayerAudio:: synctype set to 0: clock feedback
19:31:16.401 T:6101594112 DEBUG: CGUIInfoManager::SetCurrentMovie(smb://192.168.70.44/XBMC/TV/Watchmen/Season 1/Watchmen S01E04.mkv)
19:31:16.404 T:6098726912 DEBUG: Read - max read bytes = 54235
19:31:16.407 T:6102740992 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:31:16.409 T:6102167552 DEBUG: Previous line repeats 1 times.
19:31:16.409 T:6102167552 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
19:31:16.411 T:6098726912 DEBUG: Read - max read bytes = 63821
19:31:16.411 T:6098726912 DEBUG: CDVDPlayer::HandleMessages - player started DVDPLAYER_AUDIO
19:31:16.415 T:6098726912 DEBUG: Read - max read bytes = 65535
19:31:16.415 T:6102167552 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
19:31:16.508 T:6102167552 DEBUG: Previous line repeats 15 times.
19:31:16.508 T:6102167552 DEBUG: GetPicture - VTBDecoderDecode dts(-4503599627370496.000000), pts(7000.000000), old_pts(12068000.000000)
19:31:16.508 T:6102167552 NOTICE: fps: 25.000000, pwidth: 1920, pheight: 1080, dwidth: 1920, dheight: 1080, colorrange: Limited, colorspace: bt709, colortransfer: bt709
19:31:16.508 T:6102167552 DEBUG: OutputPicture - change configuration. 1920x1080. framerate: 25.00. format: BGRA
19:31:16.508 T:6102167552 NOTICE: Display resolution DESKTOP : 3840x2160@ 60.00 - Full Screen (16)
19:31:16.510 T:6102167552 DEBUG: displayRateSwitch request: refreshRate = 25.00, dynamicRange = SDR
19:31:16.510 T:6102167552 DEBUG: CXBMCRenderManager::Configure - 4
19:31:16.545 T:6101594112 DEBUG: Activating window ID: 12005
19:31:16.545 T:6101594112 DEBUG: ------ Window Deinit (MyVideoNav.xml) ------
19:31:16.545 T:6101594112 DEBUG: ------ Window Init (VideoFullScreen.xml) ------
19:31:16.545 T:6101594112 NOTICE: Using GL_TEXTURE_2D
19:31:16.545 T:6101594112 DEBUG: GL: Requested render method: 0
19:31:16.545 T:6101594112 NOTICE: GL: Selecting Single Pass YUV 2 RGB shader
19:31:16.545 T:6101594112 ERROR: GL: BaseYUV2RGBGLSLShader - unsupported format 12
19:31:16.548 T:6101594112 ERROR: Previous line repeats 1 times.
19:31:16.548 T:6101594112 NOTICE: GL: NPOT texture support detected
19:31:16.550 T:6098726912 DEBUG: CDVDPlayer::HandleMessages - player started DVDPLAYER_VIDEO
19:31:16.550 T:6098726912 DEBUG: CDVDPlayer::Sync - Audio - pts: 288000.000000, cache: 484544.188959, totalcache: 685333.311558
19:31:16.550 T:6098726912 DEBUG: CDVDPlayer::Sync - Video - pts: 7000.000000, cache: 50000.000000, totalcache: 100000.000000
19:31:16.550 T:6098726912 DEBUG: CDVDPlayer::LogCacheState(HandlePlaySpeed) - CACHESTATE_DONE
19:31:16.550 T:6102167552 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(-196544.188959)
19:31:16.550 T:6106181632 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(-196544.188959)
19:31:16.550 T:6106181632 DEBUG: CDVDAudio::Resume - resume audio stream
19:31:16.550 T:6102167552 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
19:31:16.550 T:6102740992 DEBUG: ActiveAE - start sync of audio stream
19:31:16.555 T:6102167552 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
19:31:16.651 T:6102740992 DEBUG: ActiveAE::SyncStream - average error of 28.002668, start adjusting
19:31:16.651 T:6102740992 DEBUG: ActiveAE::SyncStream - average error 7.002668 below threshold of 30.000000
19:31:17.674 T:6106181632 DEBUG: CDVDClock::ErrorAdjust - CDVDPlayerAudio::OutputPacket - error:-68757.021924, adjusted:-68757.021924
19:31:18.759 T:6106181632 DEBUG: CDVDClock::ErrorAdjust - CDVDPlayerAudio::OutputPacket - error:-27965.918557, adjusted:-27965.918557
19:31:29.444 T:6101020672 DEBUG: DumpAudioDescriptions: AVAudioSessionRouteChangeReasonOldDeviceUnavailable
19:31:29.444 T:6102740992 DEBUG: CActiveAE - device change event
19:31:29.444 T:6101020672 DEBUG: DumpAudioDescriptions: input count = 0
19:31:29.444 T:6101020672 DEBUG: DumpAudioDescriptions: output count = 0
19:31:29.475 T:6102740992 DEBUG: EnumerateDevices:Device(default)
19:31:29.475 T:6102740992 NOTICE: Found 1 Lists of Devices
19:31:29.475 T:6102740992 NOTICE: Enumerated DARWINIOS devices:
19:31:29.475 T:6102740992 NOTICE: Device 1
19:31:29.475 T:6102740992 NOTICE: m_deviceName : default
19:31:29.475 T:6102740992 NOTICE: m_displayName : Default
19:31:29.475 T:6102740992 NOTICE: m_displayNameExtra:
19:31:29.475 T:6102740992 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
19:31:29.475 T:6102740992 NOTICE: m_channels : FL,FR,FC,LFE,BL,BR
19:31:29.475 T:6102740992 NOTICE: m_sampleRates : 44100,48000
19:31:29.475 T:6102740992 NOTICE: m_dataFormats : AE_FMT_S16LE,AE_FMT_FLOAT
19:31:29.475 T:6102740992 NOTICE: m_streamTypes : No passthrough capabilities
19:31:29.475 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:31:29.475 T:6103314432 INFO: CActiveAESink::OpenSink - initialize sink
19:31:29.475 T:6103314432 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
19:31:29.476 T:6103314432 ERROR: CActiveAESink::StateMachine - OpenSink failed: 1
19:31:29.476 T:6102740992 ERROR: ActiveAE::InitSink - returned error
19:31:29.981 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:31:29.982 T:6103314432 INFO: CActiveAESink::OpenSink - initialize sink
19:31:29.982 T:6103314432 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
19:31:29.984 T:6103314432 ERROR: CActiveAESink::StateMachine - OpenSink failed: 1
19:31:29.984 T:6102740992 ERROR: ActiveAE::InitSink - returned error
19:31:30.237 T:6102167552 WARNING: CRenderManager::WaitForBuffer - timeout waiting for buffer
19:31:30.490 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:31:30.491 T:6103314432 INFO: CActiveAESink::OpenSink - initialize sink
19:31:30.491 T:6103314432 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
19:31:30.493 T:6103314432 ERROR: CActiveAESink::StateMachine - OpenSink failed: 1
19:31:30.493 T:6102740992 ERROR: ActiveAE::InitSink - returned error
19:31:30.995 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:31:30.996 T:6103314432 INFO: CActiveAESink::OpenSink - initialize sink
19:31:30.996 T:6103314432 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
19:31:30.997 T:6103314432 ERROR: CActiveAESink::StateMachine - OpenSink failed: 1
19:31:30.997 T:6102740992 ERROR: ActiveAE::InitSink - returned error
19:31:31.432 T:6106181632 ERROR: CDVDAudio::AddPacketsRenderer - timeout adding data to renderer
19:31:31.433 T:6106181632 DEBUG: CDVDAudio::Pause - pausing audio stream
19:31:31.433 T:6102740992 WARNING: CActiveAE::StateMachine - signal: 6 from port: OutputControlPort not handled for state: 1
19:31:31.503 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:31:31.504 T:6103314432 INFO: CActiveAESink::OpenSink - initialize sink
19:31:31.504 T:6103314432 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
19:31:31.506 T:6103314432 ERROR: CActiveAESink::StateMachine - OpenSink failed: 1
19:31:31.506 T:6102740992 ERROR: ActiveAE::InitSink - returned error
19:31:31.744 T:6101020672 DEBUG: DumpAudioDescriptions: AVAudioSessionRouteChangeReasonNewDeviceAvailable
19:31:31.744 T:6102740992 WARNING: CActiveAE::StateMachine - signal: 3 from port: OutputControlPort not handled for state: 1
19:31:31.761 T:6101020672 DEBUG: DumpAudioDescriptions: input count = 0
19:31:31.761 T:6101020672 DEBUG: DumpAudioDescriptions: output count = 1
19:31:31.761 T:6101020672 DEBUG: DumpAudioDescriptions : portName, ATV-4K
19:31:31.762 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:31:31.762 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 1
19:31:31.762 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:31:31.762 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 2
19:31:32.011 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:31:32.011 T:6103314432 INFO: CActiveAESink::OpenSink - initialize sink
19:31:32.011 T:6103314432 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
19:31:32.012 T:6103314432 DEBUG: virtual bool CAESinkDARWINIOS::Initialize(AEAudioFormat &, std::string &): AudioStreamBasicDescription: [lpcm] Mixable Interleaved 6 Channel 32-bit Floating Point LE (48000Hz) pcm
19:31:32.014 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting channels 6
19:31:32.014 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting samplerate 48000.000000
19:31:32.014 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting buffer duration to 0.128000
19:31:33.167 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() total latency = 0.066000
19:31:33.167 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setup audio format: [lpcm] Mixable Interleaved 6 Channel 32-bit Floating Point LE (48000Hz)
19:31:33.170 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() audio route = HDMIOutput
19:31:33.171 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() sampleRate 48000.000000
19:31:33.171 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputLatency 0.034000
19:31:33.171 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() IOBufferDuration 0.032000
19:31:33.171 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputNumberOfChannels 6
19:31:33.171 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() maximumOutputNumberOfChannels 6
19:31:33.171 T:6103314432 DEBUG: DumpAudioDescriptions: void dumpAVAudioSessionProperties()
19:31:33.172 T:6103314432 DEBUG: DumpAudioDescriptions: input count = 0
19:31:33.173 T:6103314432 DEBUG: DumpAudioDescriptions: output count = 1
19:31:33.173 T:6103314432 DEBUG: DumpAudioDescriptions : portName, ATV-4K
19:31:33.173 T:6103314432 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:31:33.173 T:6103314432 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 1
19:31:33.173 T:6103314432 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:31:33.173 T:6103314432 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 2
19:31:33.182 T:6103314432 DEBUG: CActiveAESink::OpenSink - DARWINIOS Initialized:
19:31:33.182 T:6103314432 DEBUG: Output Device : Default
19:31:33.182 T:6103314432 DEBUG: Sample Rate : 48000
19:31:33.182 T:6103314432 DEBUG: Sample Format : AE_FMT_FLOAT
19:31:33.182 T:6103314432 DEBUG: Channel Count : 6
19:31:33.182 T:6103314432 DEBUG: Channel Layout: FL,FC,FR,BL,BR,LFE
19:31:33.182 T:6103314432 DEBUG: Frames : 1024
19:31:33.182 T:6103314432 DEBUG: Frame Size : 24
19:31:33.182 T:6103314432 DEBUG: CActiveAESink::GenerateNoise - noiseFactor = 0.000010
19:31:33.186 T:6102740992 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:31:33.196 T:6101020672 DEBUG: Previous line repeats 2 times.
19:31:33.196 T:6101020672 DEBUG: DumpAudioDescriptions: AVAudioSessionRouteChangeReasonCategoryChange
19:31:33.197 T:6101020672 DEBUG: DumpAudioDescriptions: input count = 0
19:31:33.197 T:6101020672 DEBUG: DumpAudioDescriptions: output count = 1
19:31:33.197 T:6101020672 DEBUG: DumpAudioDescriptions : portName, ATV-4K
19:31:33.197 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:31:33.197 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 1
19:31:33.197 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:31:33.197 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 2
19:31:33.198 T:6101020672 DEBUG: DumpAudioDescriptions: AVAudioSessionRouteChangeReasonCategoryChange
19:31:33.199 T:6101020672 DEBUG: DumpAudioDescriptions: input count = 0
19:31:33.199 T:6101020672 DEBUG: DumpAudioDescriptions: output count = 1
19:31:33.199 T:6101020672 DEBUG: DumpAudioDescriptions : portName, ATV-4K
19:31:33.199 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:31:33.199 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 1
19:31:33.199 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:31:33.199 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 2
19:31:37.381 T:6101594112 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
19:31:39.368 T:4426979328 DEBUG: enterActiveDelayed: AVPlayerHDRMode = 2
19:31:39.377 T:6102167552 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 0
19:31:39.379 T:6106181632 DEBUG: CDVDAudio::Resume - resume audio stream
19:31:39.380 T:6102740992 DEBUG: ActiveAE - start sync of audio stream
19:31:39.382 T:6102167552 DEBUG: CDVDPlayerVideo::CalcDropRequirement - hurry: 1
19:31:39.484 T:6102740992 DEBUG: ActiveAE::SyncStream - average error of -638.204938, start adjusting
19:31:39.511 T:6101594112 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
19:31:39.677 T:6102740992 DEBUG: ActiveAE::SyncStream - average error -12.973899 below threshold of 30.000000
19:31:40.700 T:6102740992 DEBUG: ActiveAE::SyncStream - average error -103.950332 above threshold of 100.000000
19:31:40.701 T:6102740992 DEBUG: ActiveAE::SyncStream - average error -18.616999 below threshold of 30.000000
19:31:41.725 T:6106181632 DEBUG: CDVDClock::ErrorAdjust - CDVDPlayerAudio::OutputPacket - error:-34302.710339, adjusted:-34302.710339
19:31:45.802 T:6101594112 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate
19:31:46.002 T:6101594112 DEBUG: ------ Window Deinit (VideoFullScreen.xml) ------
19:31:46.003 T:6101594112 DEBUG: found key /userdata/profiles.xml
19:31:46.004 T:6101594112 DEBUG: Previous line repeats 1 times.
19:31:46.004 T:6101594112 DEBUG: NSUSerDefaults: compressed /userdata/profiles.xml from 941 to 346
19:31:46.004 T:6101594112 DEBUG: found key /userdata/guisettings.xml
19:31:46.005 T:6101594112 DEBUG: Previous line repeats 1 times.
19:31:46.005 T:6101594112 DEBUG: NSUSerDefaults: compressed /userdata/guisettings.xml from 30680 to 6197
19:31:46.006 T:6101594112 DEBUG: CGUIWindowManager::PreviousWindow: Activate new
19:31:46.030 T:6101594112 DEBUG: ------ Window Init (MyVideoNav.xml) ------
19:31:46.092 T:6101594112 DEBUG: WindowVideoNav::GetDirectory
19:31:46.164 T:6101594112 NOTICE: CDVDPlayer::CloseFile()
19:31:46.164 T:6101594112 NOTICE: DVDPlayer: waiting for threads to exit
19:31:46.166 T:6098726912 NOTICE: CDVDPlayer::OnExit()
19:31:46.166 T:6098726912 NOTICE: Closing stream player 1
19:31:46.166 T:6098726912 NOTICE: Waiting for audio thread to exit
19:31:46.205 T:6106181632 NOTICE: thread end: CDVDPlayerAudio::OnExit()
19:31:46.205 T:6098726912 NOTICE: Closing audio device
19:31:46.239 T:6098726912 DEBUG: CDVDAudio::Flush - flush audio stream
19:31:46.240 T:6102740992 DEBUG: CActiveAE::DiscardStream - audio stream deleted
19:31:46.240 T:6102740992 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:31:46.241 T:6102740992 DEBUG: Previous line repeats 2 times.
19:31:46.241 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:31:46.242 T:6098726912 NOTICE: Deleting audio codec
19:31:46.242 T:6098726912 NOTICE: Closing stream player 2
19:31:46.242 T:6098726912 NOTICE: waiting for video thread to exit
19:31:46.243 T:6102740992 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:31:46.269 T:6102167552 DEBUG: Previous line repeats 1 times.
19:31:46.269 T:6102167552 NOTICE: thread end: video_thread
19:31:46.273 T:6098726912 NOTICE: deleting video codec
19:31:46.283 T:6098726912 DEBUG: CDSMFile::Close closing fd 268533800
19:31:46.284 T:6098726912 DEBUG: OnPlayBackStopped: play state was 2, starting 0
19:31:46.286 T:6101594112 NOTICE: DVDPlayer: finished waiting
19:31:46.290 T:6101594112 DEBUG: displayRateReset
19:31:46.320 T:6101594112 NOTICE: CDVDPlayer::CloseFile()
19:31:46.320 T:6101594112 NOTICE: DVDPlayer: waiting for threads to exit
19:31:46.320 T:6101594112 NOTICE: DVDPlayer: finished waiting
19:31:46.320 T:6101594112 NOTICE: CDVDPlayer::CloseFile()
19:31:46.320 T:6101594112 NOTICE: DVDPlayer: waiting for threads to exit
19:31:46.320 T:6101594112 NOTICE: DVDPlayer: finished waiting
19:31:46.823 T:6097580032 DEBUG: DoWork - Saving file state for video item smb://192.168.70.44/XBMC/TV/Watchmen/Season 1/Watchmen S01E04.mkv
19:31:59.311 T:6102740992 DEBUG: CActiveAE - device change event
19:31:59.311 T:6101020672 DEBUG: DumpAudioDescriptions: AVAudioSessionRouteChangeReasonOldDeviceUnavailable
19:31:59.312 T:6101020672 DEBUG: DumpAudioDescriptions: input count = 0
19:31:59.312 T:6101020672 DEBUG: DumpAudioDescriptions: output count = 0
19:31:59.339 T:6102740992 DEBUG: EnumerateDevices:Device(default)
19:31:59.339 T:6102740992 NOTICE: Found 1 Lists of Devices
19:31:59.339 T:6102740992 NOTICE: Enumerated DARWINIOS devices:
19:31:59.339 T:6102740992 NOTICE: Device 1
19:31:59.339 T:6102740992 NOTICE: m_deviceName : default
19:31:59.339 T:6102740992 NOTICE: m_displayName : Default
19:31:59.339 T:6102740992 NOTICE: m_displayNameExtra:
19:31:59.339 T:6102740992 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
19:31:59.339 T:6102740992 NOTICE: m_channels : FL,FR,FC,LFE,BL,BR
19:31:59.339 T:6102740992 NOTICE: m_sampleRates : 44100,48000
19:31:59.339 T:6102740992 NOTICE: m_dataFormats : AE_FMT_S16LE,AE_FMT_FLOAT
19:31:59.339 T:6102740992 NOTICE: m_streamTypes : No passthrough capabilities
19:31:59.340 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:31:59.340 T:6103314432 INFO: CActiveAESink::OpenSink - initialize sink
19:31:59.340 T:6103314432 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
19:31:59.341 T:6103314432 ERROR: CActiveAESink::StateMachine - OpenSink failed: 1
19:31:59.341 T:6102740992 ERROR: ActiveAE::InitSink - returned error
19:31:59.847 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:31:59.848 T:6103314432 INFO: CActiveAESink::OpenSink - initialize sink
19:31:59.848 T:6103314432 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
19:31:59.850 T:6103314432 ERROR: CActiveAESink::StateMachine - OpenSink failed: 1
19:31:59.850 T:6102740992 ERROR: ActiveAE::InitSink - returned error
19:32:00.356 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:32:00.357 T:6103314432 INFO: CActiveAESink::OpenSink - initialize sink
19:32:00.357 T:6103314432 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
19:32:00.359 T:6103314432 ERROR: CActiveAESink::StateMachine - OpenSink failed: 1
19:32:00.359 T:6102740992 ERROR: ActiveAE::InitSink - returned error
19:32:00.865 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:32:00.866 T:6103314432 INFO: CActiveAESink::OpenSink - initialize sink
19:32:00.866 T:6103314432 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
19:32:00.868 T:6103314432 ERROR: CActiveAESink::StateMachine - OpenSink failed: 1
19:32:00.868 T:6102740992 ERROR: ActiveAE::InitSink - returned error
19:32:01.372 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:32:01.373 T:6103314432 INFO: CActiveAESink::OpenSink - initialize sink
19:32:01.374 T:6103314432 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
19:32:01.375 T:6103314432 ERROR: CActiveAESink::StateMachine - OpenSink failed: 1
19:32:01.376 T:6102740992 ERROR: ActiveAE::InitSink - returned error
19:32:01.603 T:6101020672 DEBUG: DumpAudioDescriptions: AVAudioSessionRouteChangeReasonNewDeviceAvailable
19:32:01.603 T:6102740992 WARNING: CActiveAE::StateMachine - signal: 3 from port: OutputControlPort not handled for state: 1
19:32:01.621 T:6101020672 DEBUG: DumpAudioDescriptions: input count = 0
19:32:01.621 T:6101020672 DEBUG: DumpAudioDescriptions: output count = 1
19:32:01.621 T:6101020672 DEBUG: DumpAudioDescriptions : portName, ATV-4K
19:32:01.621 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:32:01.621 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 1
19:32:01.621 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:32:01.621 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 2
19:32:01.879 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:32:01.879 T:6103314432 INFO: CActiveAESink::OpenSink - initialize sink
19:32:01.879 T:6103314432 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
19:32:01.880 T:6103314432 DEBUG: virtual bool CAESinkDARWINIOS::Initialize(AEAudioFormat &, std::string &): AudioStreamBasicDescription: [lpcm] Mixable Interleaved 6 Channel 32-bit Floating Point LE (48000Hz) pcm
19:32:01.883 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting channels 6
19:32:01.883 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting samplerate 48000.000000
19:32:01.883 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting buffer duration to 0.128000
19:32:03.028 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() total latency = 0.066000
19:32:03.028 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setup audio format: [lpcm] Mixable Interleaved 6 Channel 32-bit Floating Point LE (48000Hz)
19:32:03.030 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() audio route = HDMIOutput
19:32:03.031 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() sampleRate 48000.000000
19:32:03.031 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputLatency 0.034000
19:32:03.032 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() IOBufferDuration 0.032000
19:32:03.032 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputNumberOfChannels 6
19:32:03.032 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() maximumOutputNumberOfChannels 6
19:32:03.032 T:6103314432 DEBUG: DumpAudioDescriptions: void dumpAVAudioSessionProperties()
19:32:03.033 T:6103314432 DEBUG: DumpAudioDescriptions: input count = 0
19:32:03.034 T:6103314432 DEBUG: DumpAudioDescriptions: output count = 1
19:32:03.034 T:6103314432 DEBUG: DumpAudioDescriptions : portName, ATV-4K
19:32:03.034 T:6103314432 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:32:03.034 T:6103314432 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 1
19:32:03.034 T:6103314432 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:32:03.034 T:6103314432 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 2
19:32:03.043 T:6103314432 DEBUG: CActiveAESink::OpenSink - DARWINIOS Initialized:
19:32:03.043 T:6103314432 DEBUG: Output Device : Default
19:32:03.043 T:6103314432 DEBUG: Sample Rate : 48000
19:32:03.043 T:6103314432 DEBUG: Sample Format : AE_FMT_FLOAT
19:32:03.043 T:6103314432 DEBUG: Channel Count : 6
19:32:03.043 T:6103314432 DEBUG: Channel Layout: FL,FC,FR,BL,BR,LFE
19:32:03.043 T:6103314432 DEBUG: Frames : 1024
19:32:03.043 T:6103314432 DEBUG: Frame Size : 24
19:32:03.052 T:6103314432 DEBUG: CActiveAESink::GenerateNoise - noiseFactor = 0.000010
19:32:03.053 T:6102740992 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:32:03.056 T:6101020672 DEBUG: DumpAudioDescriptions: AVAudioSessionRouteChangeReasonCategoryChange
19:32:03.057 T:6101020672 DEBUG: DumpAudioDescriptions: input count = 0
19:32:03.058 T:6101020672 DEBUG: DumpAudioDescriptions: output count = 1
19:32:03.058 T:6101020672 DEBUG: DumpAudioDescriptions : portName, ATV-4K
19:32:03.058 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:32:03.058 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 1
19:32:03.058 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:32:03.058 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 2
19:32:03.058 T:6101020672 DEBUG: DumpAudioDescriptions: AVAudioSessionRouteChangeReasonCategoryChange
19:32:03.059 T:6101020672 DEBUG: DumpAudioDescriptions: input count = 0
19:32:03.059 T:6101020672 DEBUG: DumpAudioDescriptions: output count = 1
19:32:03.059 T:6101020672 DEBUG: DumpAudioDescriptions : portName, ATV-4K
19:32:03.059 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:32:03.059 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 1
19:32:03.059 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:32:03.059 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 2
19:32:09.368 T:4426979328 DEBUG: enterActiveDelayed: AVPlayerHDRMode = 2
19:32:09.368 T:6102740992 DEBUG: CActiveAE - device change event
19:32:09.410 T:6102740992 DEBUG: EnumerateDevices:Device(default)
19:32:09.410 T:6102740992 NOTICE: Found 1 Lists of Devices
19:32:09.410 T:6102740992 NOTICE: Enumerated DARWINIOS devices:
19:32:09.410 T:6102740992 NOTICE: Device 1
19:32:09.410 T:6102740992 NOTICE: m_deviceName : default
19:32:09.410 T:6102740992 NOTICE: m_displayName : Default
19:32:09.410 T:6102740992 NOTICE: m_displayNameExtra:
19:32:09.410 T:6102740992 NOTICE: m_deviceType : AE_DEVTYPE_HDMI
19:32:09.410 T:6102740992 NOTICE: m_channels : FL,FR,FC,LFE,BL,BR
19:32:09.410 T:6102740992 NOTICE: m_sampleRates : 44100,48000
19:32:09.410 T:6102740992 NOTICE: m_dataFormats : AE_FMT_S16LE,AE_FMT_FLOAT
19:32:09.410 T:6102740992 NOTICE: m_streamTypes : No passthrough capabilities
19:32:09.410 T:6102740992 INFO: CActiveAE::ApplySettings - Forcing samplerate to 48000
19:32:09.410 T:6103314432 INFO: CActiveAESink::OpenSink - initialize sink
19:32:09.410 T:6103314432 DEBUG: CActiveAESink::OpenSink - trying to open device DARWINIOS:default
19:32:09.411 T:6103314432 DEBUG: virtual bool CAESinkDARWINIOS::Initialize(AEAudioFormat &, std::string &): AudioStreamBasicDescription: [lpcm] Mixable Interleaved 6 Channel 32-bit Floating Point LE (48000Hz) pcm
19:32:09.415 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting channels 6
19:32:09.415 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting samplerate 48000.000000
19:32:09.415 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setting buffer duration to 0.128000
19:32:10.488 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() total latency = 0.066000
19:32:10.488 T:6103314432 NOTICE: bool CAAudioUnitSink::setupAudio() setup audio format: [lpcm] Mixable Interleaved 6 Channel 32-bit Floating Point LE (48000Hz)
19:32:10.489 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() audio route = HDMIOutput
19:32:10.490 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() sampleRate 48000.000000
19:32:10.490 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputLatency 0.034000
19:32:10.490 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() IOBufferDuration 0.032000
19:32:10.490 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() outputNumberOfChannels 6
19:32:10.490 T:6103314432 NOTICE: void dumpAVAudioSessionProperties() maximumOutputNumberOfChannels 6
19:32:10.490 T:6103314432 DEBUG: DumpAudioDescriptions: void dumpAVAudioSessionProperties()
19:32:10.491 T:6103314432 DEBUG: DumpAudioDescriptions: input count = 0
19:32:10.492 T:6103314432 DEBUG: DumpAudioDescriptions: output count = 1
19:32:10.492 T:6103314432 DEBUG: DumpAudioDescriptions : portName, ATV-4K
19:32:10.492 T:6103314432 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:32:10.492 T:6103314432 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 1
19:32:10.492 T:6103314432 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:32:10.492 T:6103314432 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 2
19:32:10.499 T:6103314432 DEBUG: CActiveAESink::OpenSink - DARWINIOS Initialized:
19:32:10.499 T:6103314432 DEBUG: Output Device : Default
19:32:10.499 T:6103314432 DEBUG: Sample Rate : 48000
19:32:10.499 T:6103314432 DEBUG: Sample Format : AE_FMT_FLOAT
19:32:10.499 T:6103314432 DEBUG: Channel Count : 6
19:32:10.499 T:6103314432 DEBUG: Channel Layout: FL,FC,FR,BL,BR,LFE
19:32:10.499 T:6103314432 DEBUG: Frames : 1024
19:32:10.499 T:6103314432 DEBUG: Frame Size : 24
19:32:10.499 T:6103314432 DEBUG: CActiveAESink::GenerateNoise - noiseFactor = 0.000010
19:32:10.500 T:6102740992 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted
19:32:10.506 T:6101020672 DEBUG: DumpAudioDescriptions: AVAudioSessionRouteChangeReasonCategoryChange
19:32:10.507 T:6101020672 DEBUG: DumpAudioDescriptions: input count = 0
19:32:10.508 T:6101020672 DEBUG: DumpAudioDescriptions: output count = 1
19:32:10.508 T:6101020672 DEBUG: DumpAudioDescriptions : portName, ATV-4K
19:32:10.508 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:32:10.508 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 1
19:32:10.508 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:32:10.508 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 2
19:32:10.508 T:6101020672 DEBUG: DumpAudioDescriptions: AVAudioSessionRouteChangeReasonCategoryChange
19:32:10.509 T:6101020672 DEBUG: DumpAudioDescriptions: input count = 0
19:32:10.510 T:6101020672 DEBUG: DumpAudioDescriptions: output count = 1
19:32:10.510 T:6101020672 DEBUG: DumpAudioDescriptions : portName, ATV-4K
19:32:10.510 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:32:10.510 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 1
19:32:10.510 T:6101020672 DEBUG: DumpAudioDescriptions: channelLabel, -1
19:32:10.510 T:6101020672 DEBUG: DumpAudioDescriptions: channelName , ATV-4K 2