Re: Home Assistant support for MrMC on AppleTV?
Posted: 09 Jul 2019, 10:18
Let’s see the debuglog please
Code: Select all
01:09:42.792 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.Seek", "params": {"playerid": 1, "value": "smallbackward"}, "id": 1486657765}
01:09:42.797 T:6133280768 DEBUG: CDVDPlayer::LogCacheState(HandleMessages) - CACHESTATE_WAITFILL
01:09:42.797 T:6133280768 DEBUG: demuxer seek to: 2012286.000000 (ms)
01:09:42.809 T:6133280768 DEBUG: demuxer seek to: 2012286.000000 (ms), success
01:09:42.809 T:6133280768 DEBUG: CDVDPlayer::FlushBuffers - flushing buffers
01:09:42.856 T:6137868288 DEBUG: CDVDAudio::Pause - pausing audio stream
01:09:42.879 T:6137868288 DEBUG: CDVDAudio::Flush - flush audio stream
01:09:42.879 T:6137868288 DEBUG: CDVDAudio::Pause - pausing audio stream
01:09:42.879 T:6137868288 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
01:09:42.879 T:6135574528 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
01:09:42.884 T:6135001088 DEBUG: Process - CDVDVideoCodecAVFoundation::Pause
01:09:42.895 T:6131560448 ERROR: CDVDMsgGeneralSynchronize - timeout
01:09:42.922 T:6137294848 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
01:09:42.981 T:6133280768 ERROR: CDVDMsgGeneralSynchronize - timeout
01:09:42.981 T:6133280768 DEBUG: CDVDPlayer::HandleMessages - player started DVDPLAYER_VIDEO
01:09:43.000 T:6135001088 DEBUG: Process - CDVDVideoCodecAVFoundation::Reset
01:09:43.004 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 1999094588}
01:09:43.005 T:6133280768 DEBUG: CDVDPlayer::HandleMessages - player started DVDPLAYER_AUDIO
01:09:43.005 T:6133280768 DEBUG: CDVDPlayer::Sync - Audio - pts: 2012384000.000000, cache: 487440.936217, totalcache: 685333.311558
01:09:43.005 T:6133280768 DEBUG: CDVDPlayer::Sync - Video - pts: -4503599627370496.000000, cache: 50000.000000, totalcache: 100000.000000
01:09:43.005 T:6133280768 DEBUG: CDVDPlayer::LogCacheState(HandlePlaySpeed) - CACHESTATE_DONE
01:09:43.008 T:6135574528 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(2011896559.063783)
01:09:43.008 T:6137868288 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(2011896559.063783)
01:09:43.008 T:6137868288 DEBUG: CDVDAudio::Resume - resume audio stream
01:09:43.008 T:6135574528 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
01:09:43.012 T:6138441728 DEBUG: ActiveAE - start sync of audio stream
01:09:43.017 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": [["volume", "muted"]], "id": 1914481128}
01:09:43.033 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetProperties", "params": [1, ["time", "totaltime", "speed", "live"]], "id": 1608172994}
01:09:43.081 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetItem", "params": [1, ["title", "file", "uniqueid", "thumbnail", "artist", "albumartist", "showtitle", "album", "season", "episode"]], "id": 1556716579}
01:09:43.102 T:6135001088 DEBUG: Process - CDVDVideoCodecAVFoundation::Start player_s(2011.993540)
01:09:43.134 T:6138441728 DEBUG: ActiveAE::SyncStream - average error of 2.767844, start adjusting
01:09:43.134 T:6138441728 DEBUG: ActiveAE::SyncStream - average error 0.767844 below threshold of 30.000000
01:09:43.206 T:4391660480 DEBUG: adjusting playback timeBase_s(2011.103653) player_s(2012.097290), sampleBuffers(4), trackerQueue(34)
01:09:43.848 T:6132707328 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Player.GetActivePlayers","id":1}
01:09:43.849 T:6148763648 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Player.GetProperties","id":1,"params":{"playerid":1,"properties":["playlistid","speed","position","totaltime","time"]}}
01:09:43.850 T:6146469888 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Playlist.GetItems","id":1,"params":{"playlistid":1,"properties":["title","season","episode","plot","runtime","showtitle","thumbnail"]}}
01:09:44.161 T:6137868288 DEBUG: CDVDClock::ErrorAdjust - CDVDPlayerAudio::OutputPacket - error:-13663.451308, adjusted:-13663.451308
01:09:45.079 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.Seek", "params": {"playerid": 1, "value": "smallbackward"}, "id": 1983663989}
01:09:45.080 T:6133280768 DEBUG: CDVDPlayer::LogCacheState(HandleMessages) - CACHESTATE_WAITFILL
01:09:45.080 T:6133280768 DEBUG: demuxer seek to: 1983956.000000 (ms)
01:09:45.104 T:6133280768 DEBUG: demuxer seek to: 1983956.000000 (ms), success
01:09:45.104 T:6133280768 DEBUG: CDVDPlayer::FlushBuffers - flushing buffers
01:09:45.119 T:6137868288 DEBUG: CDVDAudio::Pause - pausing audio stream
01:09:45.157 T:6135001088 DEBUG: Process - CDVDVideoCodecAVFoundation::Pause
01:09:45.183 T:6131560448 ERROR: CDVDMsgGeneralSynchronize - timeout
01:09:45.184 T:6137868288 DEBUG: CDVDAudio::Flush - flush audio stream
01:09:45.184 T:6137868288 DEBUG: CDVDAudio::Pause - pausing audio stream
01:09:45.184 T:6137868288 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
01:09:45.184 T:6135574528 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
01:09:45.264 T:6135001088 DEBUG: Process - CDVDVideoCodecAVFoundation::Reset
01:09:45.286 T:6131560448 ERROR: CDVDMsgGeneralSynchronize - timeout
01:09:45.286 T:6133280768 ERROR: Previous line repeats 1 times.
01:09:45.286 T:6133280768 DEBUG: CDVDPlayer::HandleMessages - player started DVDPLAYER_VIDEO
01:09:45.324 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 940786596}
01:09:45.326 T:6133280768 DEBUG: CDVDPlayer::HandleMessages - player started DVDPLAYER_AUDIO
01:09:45.326 T:6133280768 DEBUG: CDVDPlayer::Sync - Audio - pts: 1983584000.000000, cache: 494421.727884, totalcache: 685333.311558
01:09:45.326 T:6133280768 DEBUG: CDVDPlayer::Sync - Video - pts: -4503599627370496.000000, cache: 50000.000000, totalcache: 100000.000000
01:09:45.326 T:6133280768 DEBUG: CDVDPlayer::LogCacheState(HandlePlaySpeed) - CACHESTATE_DONE
01:09:45.327 T:6135574528 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(1983089578.272116)
01:09:45.327 T:6135574528 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
01:09:45.329 T:6137868288 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(1983089578.272116)
01:09:45.329 T:6137868288 DEBUG: CDVDAudio::Resume - resume audio stream
01:09:45.334 T:6138441728 DEBUG: ActiveAE - start sync of audio stream
01:09:45.345 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": [["volume", "muted"]], "id": 1218417712}
01:09:45.358 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetProperties", "params": [1, ["time", "totaltime", "speed", "live"]], "id": 797718671}
01:09:45.369 T:6135001088 DEBUG: Process - CDVDVideoCodecAVFoundation::Start player_s(1983.131123)
01:09:45.370 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetItem", "params": [1, ["title", "file", "uniqueid", "thumbnail", "artist", "albumartist", "showtitle", "album", "season", "episode"]], "id": 1823743273}
01:09:45.440 T:6138441728 DEBUG: ActiveAE::SyncStream - average error of 25.274180, start adjusting
01:09:45.440 T:6138441728 DEBUG: ActiveAE::SyncStream - average error 4.274180 below threshold of 30.000000
01:09:45.518 T:6148763648 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Player.GetProperties","id":1,"params":{"playerid":1,"properties":["playlistid","speed","position","totaltime","time"]}}
01:09:45.518 T:6146469888 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Player.GetActivePlayers","id":1}
01:09:45.518 T:6132707328 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Playlist.GetItems","id":1,"params":{"playlistid":1,"properties":["title","season","episode","plot","runtime","showtitle","thumbnail"]}}
01:09:46.465 T:6137868288 DEBUG: CDVDClock::ErrorAdjust - CDVDPlayerAudio::OutputPacket - error:-10824.291586, adjusted:-10824.291586
01:09:46.741 T:6132707328 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Player.GetActivePlayers","id":1}
01:09:46.741 T:6148763648 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Player.GetProperties","id":1,"params":{"playerid":1,"properties":["playlistid","speed","position","totaltime","time"]}}
01:09:46.741 T:6146469888 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Playlist.GetItems","id":1,"params":{"playlistid":1,"properties":["title","season","episode","plot","runtime","showtitle","thumbnail"]}}
01:09:47.847 T:6146469888 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Player.GetActivePlayers","id":1}
01:09:47.848 T:6148763648 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Player.GetProperties","id":1,"params":{"playerid":1,"properties":["playlistid","speed","position","totaltime","time"]}}
01:09:47.849 T:6132707328 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Playlist.GetItems","id":1,"params":{"playlistid":1,"properties":["title","season","episode","plot","runtime","showtitle","thumbnail"]}}
01:09:47.890 T:6137294848 DEBUG: ------ Window Deinit (DialogSeekBar.xml) ------
01:09:48.473 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.Seek", "params": {"playerid": 1, "value": "bigbackward"}, "id": 1005884628}
01:09:48.483 T:6133280768 DEBUG: CDVDPlayer::LogCacheState(HandleMessages) - CACHESTATE_WAITFILL
01:09:48.484 T:6133280768 DEBUG: demuxer seek to: 1386224.000000 (ms)
01:09:48.508 T:6137294848 DEBUG: ------ Window Init (DialogSeekBar.xml) ------
01:09:48.515 T:6137868288 DEBUG: CDVDAudio::Pause - pausing audio stream
01:09:48.515 T:6133280768 DEBUG: demuxer seek to: 1386224.000000 (ms), success
01:09:48.515 T:6133280768 DEBUG: CDVDPlayer::FlushBuffers - flushing buffers
01:09:48.572 T:6135001088 DEBUG: Process - CDVDVideoCodecAVFoundation::Pause
01:09:48.576 T:6137868288 DEBUG: CDVDAudio::Flush - flush audio stream
01:09:48.576 T:6137868288 DEBUG: CDVDAudio::Pause - pausing audio stream
01:09:48.576 T:6137868288 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_SYNCHRONIZE
01:09:48.576 T:6135574528 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_SYNCHRONIZE
01:09:48.576 T:6131560448 ERROR: CDVDMsgGeneralSynchronize - timeout
01:09:48.675 T:6135001088 DEBUG: Process - CDVDVideoCodecAVFoundation::Reset
01:09:48.677 T:6133280768 ERROR: CDVDMsgGeneralSynchronize - timeout
01:09:48.678 T:6133280768 DEBUG: CDVDPlayer::HandleMessages - player started DVDPLAYER_VIDEO
01:09:48.708 T:6133280768 DEBUG: CDVDPlayer::HandleMessages - player started DVDPLAYER_AUDIO
01:09:48.708 T:6133280768 DEBUG: CDVDPlayer::Sync - Audio - pts: 1386528000.000000, cache: 462291.727884, totalcache: 685333.311558
01:09:48.708 T:6133280768 DEBUG: CDVDPlayer::Sync - Video - pts: -4503599627370496.000000, cache: 50000.000000, totalcache: 100000.000000
01:09:48.708 T:6133280768 DEBUG: CDVDPlayer::LogCacheState(HandlePlaySpeed) - CACHESTATE_DONE
01:09:48.709 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetActivePlayers", "id": 637233088}
01:09:48.710 T:6135574528 DEBUG: CDVDPlayerVideo - CDVDMsg::GENERAL_RESYNC(1386065708.272116)
01:09:48.710 T:6135574528 INFO: CDVDPlayerVideo - Stillframe left, switching to normal playback
01:09:48.713 T:6137868288 DEBUG: CDVDPlayerAudio - CDVDMsg::GENERAL_RESYNC(1386065708.272116)
01:09:48.713 T:6137868288 DEBUG: CDVDAudio::Resume - resume audio stream
01:09:48.718 T:6138441728 DEBUG: ActiveAE - start sync of audio stream
01:09:48.728 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Application.GetProperties", "params": [["volume", "muted"]], "id": 1806041797}
01:09:48.751 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetProperties", "params": [1, ["time", "totaltime", "speed", "live"]], "id": 1842945585}
01:09:48.770 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.GetItem", "params": [1, ["title", "file", "uniqueid", "thumbnail", "artist", "albumartist", "showtitle", "album", "season", "episode"]], "id": 83583144}
01:09:48.777 T:6135001088 DEBUG: Process - CDVDVideoCodecAVFoundation::Start player_s(1386.133415)
01:09:48.830 T:6138441728 DEBUG: ActiveAE::SyncStream - average error of -17.347622, start adjusting
01:09:48.830 T:6138441728 DEBUG: ActiveAE::SyncStream - average error -0.014288 below threshold of 30.000000
01:09:49.812 T:6132707328 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Player.GetProperties","id":1,"params":{"playerid":1,"properties":["playlistid","speed","position","totaltime","time"]}}
01:09:49.813 T:6146469888 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Player.GetActivePlayers","id":1}
01:09:49.813 T:6148763648 DEBUG: JSONRPC: Incoming request: {"jsonrpc":"2.0","method":"Playlist.GetItems","id":1,"params":{"playlistid":1,"properties":["title","season","episode","plot","runtime","showtitle","thumbnail"]}}
01:09:49.923 T:6137868288 DEBUG: CDVDClock::ErrorAdjust - CDVDPlayerAudio::OutputPacket - error:-14788.651670, adjusted:-14788.651670
01:09:50.330 T:6131560448 DEBUG: JSONRPC: Incoming request: {"jsonrpc": "2.0", "method": "Player.Seek", "params": {"playerid": 1, "value": "bigbackward"}, "id": 1827146470}
01:09:50.340 T:6133280768 DEBUG: CDVDPlayer::LogCacheState(HandleMessages) - CACHESTATE_WAITFILL
01:09:50.341 T:6133280768 DEBUG: demuxer seek to: 787672.000000 (ms)
01:09:50.370 T:6133280768 DEBUG: demuxer seek to: 787672.000000 (ms), success
01:09:50.370 T:6133280768 DEBUG: CDVDPlayer::FlushBuffers - flushing buffers
01:09:50.398 T:6137868288 DEBUG: CDVDAudio::Pause - pausing audio stream
01:09:50.430 T:6135001088 DEBUG: Process - CDVDVideoCodecAVFoundation::PauseCode: Select all
<advancedsettings>
<video>
<timeseekforward>15</timeseekforward> <!-- Time to seek forward in sec - short seek. Defaults to 30. -->
<timeseekbackward>-15</timeseekbackward> <!-- Time to seek backward in sec - short seek. Defaults to -30. -->
<timeseekforwardbig>60</timeseekforwardbig> <!-- Time seek forward in sec- long seek. Def 600 (10 min). -->
<timeseekbackwardbig>-60</timeseekbackwardbig> <!-- Time seek back in sec - long seek. Def to -600 (10 min). -->
</video>
<advancedsettings>