Jump to content

Music playback broken - Emby 4.2.0.17 and plugin 4.1.0.28a


Saviq
 Share

Recommended Posts

Saviq

I think it's updating Emby that broke Music playback for me altogether for me:

2019-06-20 08:37:57.900 T:4091480464   ERROR: CCurlFile::Stat - Failed: HTTP response code said error(22) for https://media.server:443/emby/Audio/77265/stream.flac
2019-06-20 08:37:57.960 T:3824149360  NOTICE: EMBY.objects.player -> [ onPlayBackStarted ]
2019-06-20 08:37:57.961 T:3824149360  NOTICE: EMBY.hooks.player -> [ played info ] {}
2019-06-20 08:37:57.966 T:3824149360  NOTICE: EMBY.objects.monitor -> [ xbmc/Info.OnChanged ]
2019-06-20 08:37:57.973 T:3549160304  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Player.OnPlay ]
2019-06-20 08:37:57.976 T:3549160304  NOTICE: EMBY.objects.monitor ->  [ play ] kodi_id: 706 media: song
2019-06-20 08:37:58.233 T:3434083184   ERROR: CCurlFile::Stat - Failed: HTTP response code said error(22) for https://media.server:443/emby/Audio/77265/stream.flac
2019-06-20 08:37:58.266 T:3434083184   ERROR: CCurlFile::FillBuffer - Failed: HTTP returned error 401
2019-06-20 08:37:58.266 T:3434083184   ERROR: CCurlFile::Open failed with code 401 for https://media.server:443/emby/Audio/77265/stream.flac:
2019-06-20 08:37:58.421 T:3434083184   ERROR: CCurlFile::FillBuffer - Failed: HTTP returned error 401
2019-06-20 08:37:58.422 T:3434083184   ERROR: CCurlFile::Open failed with code 401 for https://media.server:443/emby/Audio/77265/stream.flac:
2019-06-20 08:37:58.426 T:3434083184   ERROR: Open - failed to open source <https://media.server:443/emby/Audio/77265/stream.flac>
2019-06-20 08:37:58.426 T:3434083184   ERROR: Init: Error opening file musicdb://recentlyplayedalbums/40/706.flac?albumid=40
2019-06-20 08:37:58.426 T:3434083184   ERROR: CAudioDecoder: Unable to Init Codec while loading file https://media.server:443/emby/Audio/77265/stream.flac
2019-06-20 08:37:58.426 T:3434083184 WARNING: PAPlayer::QueueNextFileEx - Failed to create the decoder
2019-06-20 08:37:58.499 T:3824149360  NOTICE: EMBY.objects.monitor -> [ xbmc/Player.OnAVStart ]
2019-06-20 08:37:58.501 T:3824149360  NOTICE: EMBY.objects.player -> [ onAVStarted ]
2019-06-20 08:37:58.501 T:3824149360   ERROR: EXCEPTION: XBMC is not playing any file
2019-06-20 08:37:58.502 T:3824149360  NOTICE: EMBY.helper.wrapper -> ERROR:: XBMC is not playing any file
2019-06-20 08:37:58.503 T:3824149360  NOTICE: EMBY.hooks.player -> Filename is invalid
2019-06-20 08:37:58.505 T:3824149360  NOTICE: EMBY.objects.monitor -> [ xbmc/Player.OnStop ]
2019-06-20 08:37:58.664 T:3824149360  NOTICE: EMBY.hooks.player -> [ played info ] {}
2019-06-20 08:37:58.682 T:3824149360  NOTICE: EMBY.hooks.player -> --<<[ playback ]
2019-06-20 08:37:59.010 T:3832542064   ERROR: EXCEPTION: XBMC is not playing any media file
2019-06-20 08:37:59.011 T:3832542064   ERROR: EXCEPTION Thrown (PythonToCppException) : -->Python callback/script returned the following error<--
                                             - NOTE: IGNORING THIS CAN LEAD TO MEMORY LEAKS!
                                            Error Type: <type 'exceptions.RuntimeError'>
                                            Error Contents: XBMC is not playing any media file
                                            Traceback (most recent call last):
                                              File "/storage/.kodi/addons/service.autosubs/default.py", line 119, in onPlayBackStarted
                                                totalTime = xbmc.Player().getTotalTime()
                                            RuntimeError: XBMC is not playing any media file
                                            -->End of Python script error report<--
Link to comment
Share on other sites

Saviq

Hmm¿? Playing FLACs from file just fine…

2019-06-20 20:36:58.685 T:3362780016  NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder flac
2019-06-20 20:36:58.754 T:3544183664  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-06-20 20:36:58.756 T:3544183664  NOTICE: EMBY.objects.monitor -> {u'item': {u'album': u'RAMMSTEIN', u'track': 1, u'title': u'DEUTSCHLAND', u'type': u'song', u'artist': [u'Rammstein']}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 1}
2019-06-20 20:36:58.855 T:3837141872  NOTICE: EMBY.objects.player -> [ onPlayBackStarted ]
2019-06-20 20:36:58.857 T:3837141872  NOTICE: EMBY.hooks.player -> [ played info ] {}
2019-06-20 20:36:58.861 T:3837141872  NOTICE: EMBY.objects.monitor -> [ xbmc/Info.OnChanged ]
2019-06-20 20:36:58.879 T:3294696304  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Player.OnPlay ]
2019-06-20 20:36:59.856 T:3837141872  NOTICE: EMBY.objects.monitor -> [ xbmc/Player.OnAVStart ]
2019-06-20 20:36:59.859 T:3837141872  NOTICE: EMBY.objects.player -> [ onAVStarted ]
2019-06-20 20:37:04.938 T:4057985904 WARNING: ActiveAE::RunStages - viz ran out of free buffers
2019-06-20 20:37:05.073 T:3639550832   ERROR: EXCEPTION: XBMC is not playing any media file
2019-06-20 20:37:05.173 T:3837141872  NOTICE: EMBY.objects.monitor -> [ xbmc/Player.OnStop ]
2019-06-20 20:37:05.181 T:3837141872  NOTICE: EMBY.hooks.player -> [ played info ] {}
2019-06-20 20:37:05.267 T:3837141872  NOTICE: EMBY.hooks.player -> --<[ playback ]

From Emby, I can't play mp3s either…

2019-06-20 20:37:48.590 T:4089399696   ERROR: CCurlFile::Stat - Failed: HTTP response code said error(22) for https://media.sawicz.net:443/emby/Audio/83562/stream.mp3
2019-06-20 20:37:48.735 T:3837141872  NOTICE: EMBY.objects.monitor -> [ xbmc/Info.OnChanged ]
2019-06-20 20:37:48.737 T:3837141872  NOTICE: EMBY.objects.player -> [ onPlayBackStarted ]
2019-06-20 20:37:48.738 T:3837141872  NOTICE: EMBY.hooks.player -> [ played info ] {}
2019-06-20 20:37:48.753 T:3187159920  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Player.OnPlay ]
2019-06-20 20:37:48.755 T:3187159920  NOTICE: EMBY.objects.monitor ->  [ play ] kodi_id: 560 media: song
2019-06-20 20:37:48.954 T:3294696304   ERROR: CCurlFile::Stat - Failed: HTTP response code said error(22) for https://media.sawicz.net:443/emby/Audio/83562/stream.mp3
2019-06-20 20:37:49.279 T:3294696304   ERROR: CCurlFile::FillBuffer - Failed: HTTP returned error 401
2019-06-20 20:37:49.279 T:3294696304   ERROR: CCurlFile::Open failed with code 401 for https://media.sawicz.net:443/emby/Audio/83562/stream.mp3:
2019-06-20 20:37:49.497 T:3294696304   ERROR: CCurlFile::FillBuffer - Failed: HTTP returned error 401
2019-06-20 20:37:49.497 T:3294696304   ERROR: CCurlFile::Open failed with code 401 for https://media.sawicz.net:443/emby/Audio/83562/stream.mp3:
2019-06-20 20:37:49.521 T:3294696304   ERROR: Open - failed to open source <https://media.sawicz.net:443/emby/Audio/83562/stream.mp3>
2019-06-20 20:37:49.521 T:3294696304   ERROR: Init: Error opening file musicdb://recentlyplayedalbums/31/560.mp3?albumid=31
2019-06-20 20:37:49.522 T:3294696304   ERROR: CAudioDecoder: Unable to Init Codec while loading file https://media.sawicz.net:443/emby/Audio/83562/stream.mp3
2019-06-20 20:37:49.522 T:3294696304 WARNING: PAPlayer::QueueNextFileEx - Failed to create the decoder
2019-06-20 20:37:49.539 T:3837141872  NOTICE: EMBY.objects.monitor -> [ xbmc/Player.OnAVStart ]
2019-06-20 20:37:49.541 T:3837141872  NOTICE: EMBY.objects.player -> [ onAVStarted ]
2019-06-20 20:37:49.542 T:3837141872   ERROR: EXCEPTION: XBMC is not playing any file
2019-06-20 20:37:49.543 T:3837141872  NOTICE: EMBY.helper.wrapper -> ERROR:: XBMC is not playing any file
2019-06-20 20:37:49.544 T:3837141872  NOTICE: EMBY.hooks.player -> Filename is invalid
2019-06-20 20:37:49.545 T:3837141872  NOTICE: EMBY.objects.monitor -> [ xbmc/Player.OnStop ]
2019-06-20 20:37:49.660 T:3698299760   ERROR: EXCEPTION: XBMC is not playing any media file
2019-06-20 20:37:49.697 T:3698299760   ERROR: EXCEPTION Thrown (PythonToCppException) : -->Python callback/script returned the following error<--
                                             - NOTE: IGNORING THIS CAN LEAD TO MEMORY LEAKS!
                                            Error Type: <type 'exceptions.RuntimeError'>
                                            Error Contents: XBMC is not playing any media file
                                            Traceback (most recent call last):
                                              File "/storage/.kodi/addons/service.autosubs/default.py", line 119, in onPlayBackStarted
                                                totalTime = xbmc.Player().getTotalTime()
                                            RuntimeError: XBMC is not playing any media file
                                            -->End of Python script error report<--
2019-06-20 20:37:49.748 T:3837141872  NOTICE: EMBY.hooks.player -> [ played info ] {}
2019-06-20 20:37:49.753 T:3837141872  NOTICE: EMBY.hooks.player -> --<<[ playback ]
2019-06-20 20:37:53.929 T:3639550832   ERROR: EXCEPTION: XBMC is not playing any media file

I checked on two user profiles, both behave the same. When I visit the URL that gives 401, I get:

Access token is invalid or expired.

I really doubt it is a Kodi problem, as I was using this same setup just fine until a couple days ago.

Edited by Saviq
  • Like 1
Link to comment
Share on other sites

Angelblue05

Ok I am being told by Luke it’s a change that has been reinforced with audio urls. It needs the api key attached to it.

 

Now there is a good chance this will break music support in Kodi for addon playback. I may have to change it back to direct paths only or disabling music sync to Kodi support...

Link to comment
Share on other sites

We'll find a solution. We might just need a new url that can include the apikey without a query string param.

  • Like 1
Link to comment
Share on other sites

Angelblue05

We’ll figure something out, thanks for the info Luke.

 

I’ll make whatever necessary adjustments to support music. Hopefully moving forward, Kodi will have better music support.

Link to comment
Share on other sites

  • 2 weeks later...
  • 1 month later...
Angelblue05

@@Luke can you update status?

This has already been resolved. Just repair your music library. Check the changelog thread, I always post it.

Link to comment
Share on other sites

Angelblue05

Provide your Kodi log trying to play music then. Because music playback works here and the OP confirmed that as well. Thanks.

Edited by Angelblue05
Link to comment
Share on other sites

Angelblue05

Thanks for the log. It's returning error 400 strangely. Can you tell me how you are accessing that music so we can try to replicate this? If you go directly under songs, does it work?

 

@@sualfred

 

check line 65 of the log. What is that?

Link to comment
Share on other sites

sualfred

You mean " Init: Error opening file musicdb://recentlyplayedalbums/37/568.mp3?albumid=37" ?

He is accessing the last played albums. In Embuary go the music hub and then "library" to find it. It's just a internal sorting node.

  • Like 1
Link to comment
Share on other sites

Angelblue05

Ok thank you. Yep that works here too.

 

Adam

 

In the add-on settings > advanced > disable mask sensitive information in log temporarily. Try playing, check your log, search for "EMBY.hooks.webservice -> path:" And test the path in your browser, does it work?

Link to comment
Share on other sites

adam.niescierowicz

Thanks for the log. It's returning error 400 strangely. Can you tell me how you are accessing that music so we can try to replicate this? If you go directly under songs, does it work?

Unfortunately, music doesn't work anywhere. I checked:

- Kodi->Music->Last Played

- Kodi->Music->Authors

- Kodi->Music->Albums

- Addons->Music->Emby->Music->{Authors,Albums,Songs,*}

 

I think that problems is in the song url
ERROR: CCurlFile::Open failed with code 400 for http://127.0.0.1:57578/emby/Audio/557006/stream.mp3 

here server address is 127.0.0.1 and real server address is outside od loopback interface. 

Link to comment
Share on other sites

sualfred

The addon has its own internal webserver shipped that is used as wrapper to access the files on the server and to create the required actions in/for the Kodi player. That's why you see this localhost url.

What happens if you open the url in the browser from the same machine? (Edit: While Kodi is running )

Edited by sualfred
Link to comment
Share on other sites

I stopped playing for me again, too, looking at the log:

2019-08-20 09:05:07.754 T:3501192048 WARNING: <[ webservice/3574451216/0 ]
2019-08-20 09:05:07.780 T:3253207920  NOTICE: EMBY.hooks.webservice -> path: http://{emby-server}/emby/Audio/202220/stream.m4a?api_key={emby-token}
2019-08-20 09:05:07.784 T:3501192048 WARNING: <[ webservice/3574451216/1 ]
2019-08-20 09:05:07.838 T:3491222384  NOTICE: EMBY.objects.player -> [ onPlayBackStarted ]
2019-08-20 09:05:07.840 T:3491222384  NOTICE: EMBY.hooks.player -> [ skip stop ] http://{emby-server}/emby/Videos/198455/stream?static=true&MediaSourceId={media-id}&api_key={emby-token}
2019-08-20 09:05:07.842 T:3491222384  NOTICE: EMBY.objects.monitor -> [ xbmc/Info.OnChanged ]
2019-08-20 09:05:07.846 T:3253207920  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Player.OnPlay ]
2019-08-20 09:05:07.847 T:3253207920  NOTICE: EMBY.objects.monitor ->  [ play ] kodi_id: 8317 media: song
2019-08-20 09:05:20.112 T:3261600624   ERROR: Open - Error, could not open file http://10.0.5.143:8096/emby/Audio/202220/stream.m4a?api_key={emby-token}
2019-08-20 09:05:20.122 T:3261600624   ERROR: Init: Error creating demuxer
2019-08-20 09:05:20.123 T:3261600624   ERROR: CAudioDecoder: Unable to Init Codec while loading file http://127.0.0.1:57578/emby/Audio/202220/stream.m4a
2019-08-20 09:05:20.123 T:3261600624 WARNING: PAPlayer::QueueNextFileEx - Failed to create the decoder
2019-08-20 09:05:20.171 T:3491222384  NOTICE: EMBY.objects.monitor -> [ xbmc/Player.OnAVStart ]
2019-08-20 09:05:20.173 T:3491222384  NOTICE: EMBY.objects.player -> [ onAVStarted ]
2019-08-20 09:05:20.174 T:3491222384   ERROR: EXCEPTION: XBMC is not playing any file
2019-08-20 09:05:20.175 T:3491222384  NOTICE: EMBY.helper.wrapper -> ERROR:: XBMC is not playing any file
2019-08-20 09:05:20.176 T:3491222384  NOTICE: EMBY.hooks.player -> Filename is invalid
2019-08-20 09:05:20.178 T:3491222384  NOTICE: EMBY.objects.monitor -> [ xbmc/Player.OnStop ]
2019-08-20 09:05:20.417 T:3491222384  NOTICE: EMBY.hooks.player -> [ skip stop ] http://{emby-server}/emby/Videos/198455/stream?static=true&MediaSourceId={media-id}&api_key={emby-token}
2019-08-20 09:05:20.424 T:3491222384  NOTICE: EMBY.hooks.player -> --<<[ playback ]

My Emby installation is behind a reverse proxy, and it's failing to stream from the internal IP: http://10.0.5.143:8096/. It should be using the external URL as configured in Emby server.

Edited by Saviq
Link to comment
Share on other sites

  • 1 month later...
nerdwork

I'm getting music playback issues, on my Vero4K and Kodi 18.4 on my MacBook Pro. Logs here are from MBP.

 

I've set up a fresh installation of Kodi, added Emby Add-on and synced Movies, TV and Music. Movies and TV work fine.

 

If I select a song to play Music -> Album -> Song it fails playing the first time. If I cancel trying to play, then select the same song again it works. Seeing this repeatedly but just posting a snippet here, from Kodi log and Emby Server log - failed play and subsequent successful song play:

 

Kodi Log

2019-10-11 16:00:29.541 T:123145361600512 WARNING: <[ webservice/105553281684512/0 ]
2019-10-11 16:00:29.543 T:123145361600512 WARNING: <[ webservice/105553280355568/0 ]
2019-10-11 16:00:29.545 T:123145391046656  NOTICE: EMBY.hooks.webservice -> path: http://192.168.1.150:8096/emby/Audio/29634/stream.m4a?api_key=XXXXXXXXX
2019-10-11 16:00:29.545 T:123145361600512 WARNING: <[ webservice/105553148691744/1 ]
2019-10-11 16:00:29.638 T:123145391046656  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnClear ]
2019-10-11 16:00:29.640 T:123145395253248  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:00:29.640 T:123145395253248  NOTICE: EMBY.objects.monitor -> [ reset autoplay ]
2019-10-11 16:00:29.641 T:123145399459840  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:00:29.641 T:123145399459840  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10618}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 1}
2019-10-11 16:00:29.642 T:123145395253248  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10617}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 0}
2019-10-11 16:00:29.643 T:123145399459840  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:00:29.643 T:123145399459840  NOTICE: Previous line repeats 1 times.
2019-10-11 16:00:29.643 T:123145399459840  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10619}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 2}
2019-10-11 16:00:29.644 T:123145395253248  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10620}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 3}
2019-10-11 16:00:29.645 T:123145399459840  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:00:29.645 T:123145399459840  NOTICE: Previous line repeats 1 times.
2019-10-11 16:00:29.645 T:123145399459840  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10621}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 4}
2019-10-11 16:00:29.646 T:123145395253248  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10622}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 5}
2019-10-11 16:00:29.646 T:123145391046656  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:00:29.646 T:123145391046656  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10623}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 6}
2019-10-11 16:00:29.647 T:123145395253248  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:00:29.647 T:123145395253248  NOTICE: Previous line repeats 1 times.
2019-10-11 16:00:29.647 T:123145395253248  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10624}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 7}
2019-10-11 16:00:29.648 T:123145399459840  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10625}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 8}
2019-10-11 16:00:29.648 T:123145395253248  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:00:29.649 T:123145395253248  NOTICE: Previous line repeats 1 times.
2019-10-11 16:00:29.649 T:123145395253248  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10626}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 9}
2019-10-11 16:00:29.649 T:123145399459840  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:00:29.649 T:123145391046656  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10627}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 10}
2019-10-11 16:00:29.650 T:123145399459840  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10628}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 11}
2019-10-11 16:00:29.650 T:123145395253248  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:00:29.652 T:123145395253248  NOTICE: Previous line repeats 1 times.
2019-10-11 16:00:29.652 T:123145395253248  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10629}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 12}
2019-10-11 16:00:29.653 T:123145391046656  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10630}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 13}
2019-10-11 16:00:29.653 T:123145399459840  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:00:29.654 T:123145399459840  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10631}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 14}
2019-10-11 16:00:29.654 T:123145391046656  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:00:29.655 T:123145391046656  NOTICE: Previous line repeats 1 times.
2019-10-11 16:00:29.655 T:123145391046656  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10632}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 15}
2019-10-11 16:00:29.655 T:123145395253248  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10633}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 16}
2019-10-11 16:00:29.655 T:123145365807104  NOTICE: EMBY.objects.player -> [ onPlayBackStarted ]
2019-10-11 16:00:29.656 T:123145399459840  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:00:29.656 T:123145399459840  NOTICE: Previous line repeats 1 times.
2019-10-11 16:00:29.656 T:123145399459840  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10635}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 18}
2019-10-11 16:00:29.656 T:123145391046656  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10634}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 17}
2019-10-11 16:00:29.657 T:123145365807104  NOTICE: EMBY.objects.monitor -> [ xbmc/Info.OnChanged ]
2019-10-11 16:00:29.659 T:123145395253248  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Player.OnPlay ]
2019-10-11 16:00:29.659 T:123145395253248  NOTICE: EMBY.objects.monitor ->  [ play ] kodi_id: 10617 media: song
2019-10-11 16:00:33.452 T:123145351413760   ERROR: Open - Error, could not open file http://192.168.1.150:8096/emby/Audio/29634/stream.m4a?api_key=XXXXXXXX
2019-10-11 16:00:33.453 T:123145351413760   ERROR: Init: Error creating demuxer
2019-10-11 16:00:33.453 T:123145351413760   ERROR: CAudioDecoder: Unable to Init Codec while loading file http://127.0.0.1:57578/emby/Audio/29634/stream.m4a
2019-10-11 16:00:33.453 T:123145351413760 WARNING: PAPlayer::QueueNextFileEx - Failed to create the decoder
2019-10-11 16:00:33.466 T:123145361600512 WARNING: <[ webservice/105553282985808/0 ]
2019-10-11 16:00:33.467 T:123145391046656  NOTICE: EMBY.hooks.webservice -> path: http://192.168.1.150:8096/emby/Audio/29649/stream.m4a?api_key=XXXXXXXXXX
2019-10-11 16:00:33.467 T:123145361600512 WARNING: <[ webservice/105553280308176/1 ]
2019-10-11 16:00:33.532 T:123145365807104  NOTICE: EMBY.objects.monitor -> [ xbmc/Player.OnAVStart ]
2019-10-11 16:00:33.533 T:123145365807104  NOTICE: EMBY.objects.player -> [ onAVStarted ]
2019-10-11 16:00:33.533 T:123145365807104  NOTICE: EMBY.hooks.player -> -->[ play/29634 ] {u'PlaySessionId': u'431388f009b243ddafe91785faae64fe', 'Muted': False, u'SubtitleStreamIndex': None, 'Server': <emby.client.EmbyClient object at 0x60000262fea0>, u'MediaSourceId': u'29634', u'AudioStreamIndex': None, u'CurrentEpisode': None, u'ServerId': None, u'Runtime': 859340000, u'Type': u'Audio', u'PlayMethod': u'DirectStream', 'Volume': 100, u'LiveStreamId': None, u'SubsMapping': None, u'CurrentPosition': 4, 'Track': False, 'Paused': False, u'DeviceId': u'240F8449A87848D38BBB2FA57CF6D2BC', 'File': 'http://127.0.0.1:57578/emby/Audio/29634/stream.m4a', u'PlayOption': u'Native', u'Path': u'http://127.0.0.1:57578/emby/Audio/29634/stream.m4a', u'Id': u'29634', u'AutoSwitched': None}
2019-10-11 16:00:34.153 T:123145365807104  NOTICE: EMBY.entrypoint.service -> [ onNotification/plugin.video.emby/UserDataChanged ]
2019-10-11 16:00:34.154 T:123145365807104  NOTICE: EMBY.entrypoint.service -> [ UserDataChanged ] {u'UserId': u'5d46c5fa93b244e39ad3e7e6d5bf1873', u'UserDataList': [{u'ItemId': u'29634', u'IsFavorite': False, u'Played': True, u'LastPlayedDate': u'2019-10-11T05:00:34.0000000+00:00', u'PlaybackPositionTicks': 0, u'PlayCount': 1}, {u'ItemId': u'29596', u'PlayCount': 0, u'PlaybackPositionTicks': 0, u'IsFavorite': False, u'Played': False}]}
2019-10-11 16:00:34.154 T:123145365807104  NOTICE: EMBY.library -> ---[ userdata:2 ]
2019-10-11 16:00:35.005 T:123145386840064  NOTICE: EMBY.library -> -->[ q:download/105553157308960 ]
2019-10-11 16:00:36.025 T:123145391046656  NOTICE: EMBY.downloader -> --<[ q:download/105553157308960/2 ]
2019-10-11 16:00:36.025 T:123145391046656  NOTICE: EMBY.downloader -> --[ q:download/remove ] []
2019-10-11 16:00:37.658 T:123145391046656  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/ReportProgressRequested ]
2019-10-11 16:00:38.017 T:123145386840064  NOTICE: EMBY.library -> -->[ q:userdata/MusicAlbum/105553155417248 ]
2019-10-11 16:00:38.059 T:123145395253248  NOTICE: EMBY.objects.core.music -> USERDATA album [792] 29596: ...and Out Come the Wolves
2019-10-11 16:00:38.335 T:123145350877184   ERROR: Open - Error, could not open file http://192.168.1.150:8096/emby/Audio/29649/stream.m4a?api_key=XXXXXXXXXXX
2019-10-11 16:00:38.336 T:123145350877184   ERROR: Init: Error creating demuxer
2019-10-11 16:00:38.336 T:123145350877184   ERROR: CAudioDecoder: Unable to Init Codec while loading file http://127.0.0.1:57578/emby/Audio/29649/stream.m4a
2019-10-11 16:00:38.336 T:123145350877184 WARNING: PAPlayer::QueueNextFileEx - Failed to create the decoder
2019-10-11 16:00:38.374 T:123145365807104  NOTICE: EMBY.objects.monitor -> [ xbmc/Player.OnAVStart ]
2019-10-11 16:00:38.374 T:123145365807104  NOTICE: EMBY.objects.player -> [ onPlayBackStarted ]
2019-10-11 16:00:38.375 T:123145365807104  NOTICE: EMBY.hooks.player -> [ played info ] {u'PlaySessionId': u'431388f009b243ddafe91785faae64fe', 'Muted': False, u'SubtitleStreamIndex': -1, 'Server': <emby.client.EmbyClient object at 0x60000262fea0>, u'MediaSourceId': u'29634', u'AudioStreamIndex': 1, u'CurrentEpisode': None, u'ServerId': None, u'Runtime': 859340000, u'Type': u'Audio', u'PlayMethod': u'DirectStream', 'Volume': 100, u'LiveStreamId': None, u'SubsMapping': None, u'CurrentPosition': 4, 'Track': True, 'Paused': False, u'DeviceId': u'240F8449A87848D38BBB2FA57CF6D2BC', 'File': 'http://127.0.0.1:57578/emby/Audio/29634/stream.m4a', u'PlayOption': u'Native', u'Path': u'http://127.0.0.1:57578/emby/Audio/29634/stream.m4a', u'Id': u'29634', u'AutoSwitched': None}
2019-10-11 16:00:38.403 T:123145365807104  NOTICE: EMBY.objects.monitor -> [ xbmc/Info.OnChanged ]
2019-10-11 16:00:38.404 T:123145399459840  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Player.OnPlay ]
2019-10-11 16:00:38.404 T:123145399459840  NOTICE: EMBY.objects.monitor ->  [ play ] kodi_id: 10618 media: song
2019-10-11 16:00:38.455 T:123145361600512 WARNING: <[ webservice/105553148691744/0 ]
2019-10-11 16:00:38.458 T:123145403666432  NOTICE: EMBY.hooks.webservice -> path: http://192.168.1.150:8096/emby/Audio/29632/stream.m4a?api_key=XXXXXXXXXX
2019-10-11 16:00:38.458 T:123145361600512 WARNING: <[ webservice/105553281450016/1 ]
2019-10-11 16:00:38.508 T:123145365807104  NOTICE: EMBY.objects.player -> [ onAVStarted ]
2019-10-11 16:00:38.509 T:123145365807104  NOTICE: EMBY.hooks.player -> -->[ play/29649 ] {u'PlaySessionId': u'e38ee4f8f00c4be49c770a28bba6f9d0', 'Muted': False, u'SubtitleStreamIndex': None, 'Server': <emby.client.EmbyClient object at 0x60000262fea0>, u'MediaSourceId': u'29649', u'AudioStreamIndex': None, u'CurrentEpisode': None, u'ServerId': None, u'Runtime': 1480670000, u'Type': u'Audio', u'PlayMethod': u'DirectStream', 'Volume': 100, u'LiveStreamId': None, u'SubsMapping': None, u'CurrentPosition': 4, 'Track': False, 'Paused': False, u'DeviceId': u'240F8449A87848D38BBB2FA57CF6D2BC', 'File': 'http://127.0.0.1:57578/emby/Audio/29649/stream.m4a', u'PlayOption': u'Native', u'Path': u'http://127.0.0.1:57578/emby/Audio/29649/stream.m4a', u'Id': u'29649', u'AutoSwitched': None}
2019-10-11 16:00:39.060 T:123145395253248  NOTICE: EMBY.database -> [emby] 1 rows updated.
2019-10-11 16:00:39.064 T:123145395253248  NOTICE: EMBY.library -> --<[ q:userdata/105553155417248 ]
2019-10-11 16:00:39.133 T:123145365807104  NOTICE: EMBY.entrypoint.service -> [ onNotification/plugin.video.emby/UserDataChanged ]
2019-10-11 16:00:39.134 T:123145365807104  NOTICE: EMBY.entrypoint.service -> [ UserDataChanged ] {u'UserId': u'5d46c5fa93b244e39ad3e7e6d5bf1873', u'UserDataList': [{u'ItemId': u'29634', u'IsFavorite': False, u'Played': True, u'LastPlayedDate': u'2019-10-11T05:00:34.0000000+00:00', u'PlaybackPositionTicks': 0, u'PlayCount': 1}, {u'ItemId': u'29596', u'PlayCount': 0, u'PlaybackPositionTicks': 0, u'IsFavorite': False, u'Played': False}, {u'ItemId': u'29649', u'IsFavorite': False, u'Played': True, u'LastPlayedDate': u'2019-10-11T05:00:39.0000000+00:00', u'PlaybackPositionTicks': 0, u'PlayCount': 1}]}
2019-10-11 16:00:39.135 T:123145365807104  NOTICE: EMBY.library -> ---[ userdata:3 ]
2019-10-11 16:00:39.442 T:123145365807104  NOTICE: EMBY.objects.monitor -> [ xbmc/Player.OnStop ]
2019-10-11 16:00:41.061 T:123145386840064  NOTICE: EMBY.library -> -->[ q:download/105553155494816 ]
2019-10-11 16:00:41.064 T:123145386840064  NOTICE: EMBY.library -> -->[ q:userdata/Audio/105553156702304 ]
2019-10-11 16:00:41.108 T:123145395253248  NOTICE: EMBY.objects.core.music -> USERDATA song [10617] 29634: Maxwell Murder
2019-10-11 16:00:41.164 T:123145395253248  NOTICE: Previous line repeats 1 times.
2019-10-11 16:00:41.164 T:123145395253248  NOTICE: EMBY.objects.core.music -> USERDATA song [10618] 29649: The 11th Hour
2019-10-11 16:00:42.112 T:123145391046656  NOTICE: EMBY.downloader -> --<[ q:download/105553155494816/3 ]
2019-10-11 16:00:42.113 T:123145391046656  NOTICE: EMBY.downloader -> --[ q:download/remove ] []
2019-10-11 16:00:42.169 T:123145395253248  NOTICE: EMBY.database -> [emby] 3 rows updated.
2019-10-11 16:00:42.173 T:123145395253248  NOTICE: EMBY.database -> [music] 3 rows updated.
2019-10-11 16:00:42.174 T:123145395253248  NOTICE: EMBY.library -> --<[ q:userdata/105553156702304 ]
2019-10-11 16:00:43.712 T:123145351413760   ERROR: Open - Error, could not open file http://192.168.1.150:8096/emby/Audio/29632/stream.m4a?api_key=XXXXXXXXXXX
2019-10-11 16:00:43.713 T:123145351413760   ERROR: Init: Error creating demuxer
2019-10-11 16:00:43.713 T:123145351413760   ERROR: CAudioDecoder: Unable to Init Codec while loading file http://127.0.0.1:57578/emby/Audio/29632/stream.m4a
2019-10-11 16:00:43.713 T:123145351413760 WARNING: PAPlayer::QueueNextFileEx - Failed to create the decoder
2019-10-11 16:00:43.742 T:123145365807104  NOTICE: EMBY.objects.monitor -> [ xbmc/Player.OnAVStart ]
2019-10-11 16:00:43.843 T:123145365807104  NOTICE: EMBY.hooks.player -> [ skip stop ] http://127.0.0.1:57578/emby/Audio/29634/stream.m4a
2019-10-11 16:00:43.843 T:123145365807104  NOTICE: EMBY.hooks.player -> [ played info ] {u'PlaySessionId': u'e38ee4f8f00c4be49c770a28bba6f9d0', 'Muted': False, u'SubtitleStreamIndex': None, 'Server': <emby.client.EmbyClient object at 0x60000262fea0>, u'MediaSourceId': u'29649', u'AudioStreamIndex': None, u'CurrentEpisode': None, u'ServerId': None, u'Runtime': 1480670000, u'Type': u'Audio', u'PlayMethod': u'DirectStream', 'Volume': 100, u'LiveStreamId': None, u'SubsMapping': None, u'CurrentPosition': 4, 'Track': True, 'Paused': False, u'DeviceId': u'240F8449A87848D38BBB2FA57CF6D2BC', 'File': 'http://127.0.0.1:57578/emby/Audio/29649/stream.m4a', u'PlayOption': u'Native', u'Path': u'http://127.0.0.1:57578/emby/Audio/29649/stream.m4a', u'Id': u'29649', u'AutoSwitched': None}
2019-10-11 16:00:43.874 T:123145365807104  NOTICE: EMBY.hooks.player -> --<[ playback ]
2019-10-11 16:00:43.874 T:123145365807104  NOTICE: EMBY.objects.player -> [ onAVStarted ]
2019-10-11 16:00:43.874 T:123145365807104   ERROR: EXCEPTION: XBMC is not playing any file
2019-10-11 16:00:43.874 T:123145365807104  NOTICE: EMBY.helper.wrapper -> ERROR:: XBMC is not playing any file
2019-10-11 16:00:43.874 T:123145365807104  NOTICE: EMBY.hooks.player -> Filename is invalid
2019-10-11 16:00:44.642 T:123145365807104  NOTICE: EMBY.entrypoint.service -> [ onNotification/plugin.video.emby/UserDataChanged ]
2019-10-11 16:00:44.643 T:123145365807104  NOTICE: EMBY.entrypoint.service -> [ UserDataChanged ] {u'UserId': u'5d46c5fa93b244e39ad3e7e6d5bf1873', u'UserDataList': [{u'ItemId': u'29649', u'IsFavorite': False, u'Played': True, u'LastPlayedDate': u'2019-10-11T05:00:39.0000000+00:00', u'PlaybackPositionTicks': 0, u'PlayCount': 1}, {u'ItemId': u'29596', u'PlayCount': 0, u'PlaybackPositionTicks': 0, u'IsFavorite': False, u'Played': False}]}
2019-10-11 16:00:44.643 T:123145365807104  NOTICE: EMBY.library -> ---[ userdata:2 ]
2019-10-11 16:00:46.800 T:123145386840064  NOTICE: EMBY.library -> -->[ q:download/105553157455328 ]
2019-10-11 16:00:46.800 T:123145386840064  NOTICE: EMBY.library -> -->[ q:userdata/MusicAlbum/105553157444256 ]
2019-10-11 16:00:46.840 T:123145395253248  NOTICE: EMBY.objects.core.music -> USERDATA album [792] 29596: ...and Out Come the Wolves
2019-10-11 16:00:47.849 T:123145391046656  NOTICE: Previous line repeats 1 times.
2019-10-11 16:00:47.849 T:123145391046656  NOTICE: EMBY.downloader -> --<[ q:download/105553157455328/2 ]
2019-10-11 16:00:47.849 T:123145391046656  NOTICE: EMBY.downloader -> --[ q:download/remove ] []
2019-10-11 16:00:47.881 T:123145395253248  NOTICE: EMBY.database -> [emby] 2 rows updated.
2019-10-11 16:00:47.885 T:123145395253248  NOTICE: EMBY.library -> --<[ q:userdata/105553157444256 ]
2019-10-11 16:00:49.844 T:123145386840064  NOTICE: EMBY.library -> -->[ q:userdata/Audio/105553158499872 ]
2019-10-11 16:00:49.883 T:123145391046656  NOTICE: EMBY.objects.core.music -> USERDATA song [10618] 29649: The 11th Hour
2019-10-11 16:00:50.887 T:123145391046656  NOTICE: EMBY.database -> [emby] 1 rows updated.
2019-10-11 16:00:50.889 T:123145391046656  NOTICE: EMBY.database -> [music] 1 rows updated.
2019-10-11 16:00:50.896 T:123145391046656  NOTICE: EMBY.library -> --<[ q:userdata/105553158499872 ]
2019-10-11 16:00:52.910 T:123145386840064  NOTICE: EMBY.library -> --[ sync/2019-10-11T04:58:52z ]
2019-10-11 16:01:12.703 T:123145361600512 WARNING: <[ webservice/105553281273312/0 ]
2019-10-11 16:01:12.704 T:123145361600512 WARNING: <[ webservice/105553211619984/0 ]
2019-10-11 16:01:12.757 T:123145391046656  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnClear ]
2019-10-11 16:01:12.758 T:123145395253248  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:01:12.758 T:123145395253248  NOTICE: EMBY.objects.monitor -> [ reset autoplay ]
2019-10-11 16:01:12.759 T:123145395253248  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10617}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 0}
2019-10-11 16:01:12.760 T:123145395253248  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:01:12.760 T:123145395253248  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10618}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 1}
2019-10-11 16:01:12.761 T:123145391046656  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:01:12.762 T:123145391046656  NOTICE: Previous line repeats 1 times.
2019-10-11 16:01:12.762 T:123145391046656  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10619}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 2}
2019-10-11 16:01:12.762 T:123145399459840  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:01:12.762 T:123145395253248  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10620}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 3}
2019-10-11 16:01:12.763 T:123145391046656  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:01:12.763 T:123145399459840  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10621}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 4}
2019-10-11 16:01:12.763 T:123145395253248  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:01:12.764 T:123145391046656  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10622}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 5}
2019-10-11 16:01:12.764 T:123145399459840  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:01:12.764 T:123145395253248  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10623}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 6}
2019-10-11 16:01:12.764 T:123145399459840  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10624}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 7}
2019-10-11 16:01:12.764 T:123145391046656  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:01:12.765 T:123145391046656  NOTICE: Previous line repeats 1 times.
2019-10-11 16:01:12.765 T:123145391046656  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10625}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 8}
2019-10-11 16:01:12.765 T:123145395253248  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10626}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 9}
2019-10-11 16:01:12.766 T:123145391046656  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:01:12.766 T:123145391046656  NOTICE: Previous line repeats 1 times.
2019-10-11 16:01:12.766 T:123145391046656  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10627}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 10}
2019-10-11 16:01:12.766 T:123145399459840  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:01:12.767 T:123145395253248  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10628}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 11}
2019-10-11 16:01:12.767 T:123145399459840  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10629}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 12}
2019-10-11 16:01:12.767 T:123145391046656  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:01:12.768 T:123145391046656  NOTICE: Previous line repeats 2 times.
2019-10-11 16:01:12.768 T:123145391046656  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10630}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 13}
2019-10-11 16:01:12.768 T:123145399459840  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10631}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 14}
2019-10-11 16:01:12.768 T:123145395253248  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10632}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 15}
2019-10-11 16:01:12.769 T:123145391046656  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:01:12.769 T:123145365807104  NOTICE: Previous line repeats 1 times.
2019-10-11 16:01:12.769 T:123145365807104  NOTICE: EMBY.objects.player -> [ onPlayBackStarted ]
2019-10-11 16:01:12.769 T:123145391046656  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10633}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 16}
2019-10-11 16:01:12.770 T:123145399459840  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10634}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 17}
2019-10-11 16:01:12.770 T:123145391046656  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Playlist.OnAdd ]
2019-10-11 16:01:12.771 T:123145391046656  NOTICE: EMBY.objects.monitor -> {u'item': {u'type': u'song', u'id': 10635}, u'playlistid': 0, 'MonitorMethod': 'Playlist.OnAdd', u'position': 18}
2019-10-11 16:01:12.771 T:123145365807104  NOTICE: EMBY.objects.monitor -> [ xbmc/Info.OnChanged ]
2019-10-11 16:01:12.774 T:123145399459840  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/Player.OnPlay ]
2019-10-11 16:01:12.774 T:123145399459840  NOTICE: EMBY.objects.monitor ->  [ play ] kodi_id: 10617 media: song
2019-10-11 16:01:12.809 T:123145403666432  NOTICE: EMBY.hooks.webservice -> path: http://192.168.1.150:8096/emby/Audio/29634/stream.m4a?api_key=XXXXXXXXXXX
2019-10-11 16:01:12.809 T:123145361600512 WARNING: <[ webservice/105553282629664/1 ]
2019-10-11 16:01:12.942 T:123145351413760  NOTICE: CDVDAudioCodecFFmpeg::Open() Successful opened audio decoder aac
2019-10-11 16:01:12.947 T:123145403666432  NOTICE: EMBY.hooks.webservice -> path: http://192.168.1.150:8096/emby/Audio/29634/stream.m4a?api_key=XXXXXXXXXXXX
2019-10-11 16:01:12.947 T:123145361600512 WARNING: <[ webservice/105553281390960/1 ]
2019-10-11 16:01:13.079 T:123145365807104  NOTICE: EMBY.objects.monitor -> [ xbmc/Player.OnAVStart ]
2019-10-11 16:01:13.081 T:123145365807104  NOTICE: EMBY.objects.player -> [ onAVStarted ]
2019-10-11 16:01:13.082 T:123145365807104  NOTICE: EMBY.hooks.player -> -->[ play/29634 ] {u'PlaySessionId': u'47c8805ffe1c4f8db947bc9dc8f64fe5', 'Muted': False, u'SubtitleStreamIndex': None, 'Server': <emby.client.EmbyClient object at 0x60000262fea0>, u'MediaSourceId': u'29634', u'AudioStreamIndex': None, u'CurrentEpisode': None, u'ServerId': None, u'Runtime': 859340000, u'Type': u'Audio', u'PlayMethod': u'DirectStream', 'Volume': 100, u'LiveStreamId': None, u'SubsMapping': None, u'CurrentPosition': 0, 'Track': False, 'Paused': False, u'DeviceId': u'240F8449A87848D38BBB2FA57CF6D2BC', 'File': 'http://127.0.0.1:57578/emby/Audio/29634/stream.m4a', u'PlayOption': u'Native', u'Path': u'http://127.0.0.1:57578/emby/Audio/29634/stream.m4a', u'Id': u'29634', u'AutoSwitched': None}
2019-10-11 16:01:13.906 T:123145365807104  NOTICE: EMBY.entrypoint.service -> [ onNotification/plugin.video.emby/UserDataChanged ]
2019-10-11 16:01:13.907 T:123145365807104  NOTICE: EMBY.entrypoint.service -> [ UserDataChanged ] {u'UserId': u'5d46c5fa93b244e39ad3e7e6d5bf1873', u'UserDataList': [{u'ItemId': u'29634', u'IsFavorite': False, u'Played': True, u'LastPlayedDate': u'2019-10-11T05:01:13.0000000+00:00', u'PlaybackPositionTicks': 0, u'PlayCount': 2}, {u'ItemId': u'29596', u'PlayCount': 0, u'PlaybackPositionTicks': 0, u'IsFavorite': False, u'Played': False}]}
2019-10-11 16:01:13.907 T:123145365807104  NOTICE: EMBY.library -> ---[ userdata:2 ]
2019-10-11 16:01:14.132 T:123145386840064  NOTICE: EMBY.library -> -->[ q:download/105553157031776 ]
2019-10-11 16:01:15.148 T:123145391046656  NOTICE: EMBY.downloader -> --<[ q:download/105553157031776/2 ]
2019-10-11 16:01:15.149 T:123145391046656  NOTICE: EMBY.downloader -> --[ q:download/remove ] []
2019-10-11 16:01:17.153 T:123145386840064  NOTICE: EMBY.library -> -->[ q:userdata/MusicAlbum/105553157039520 ]
2019-10-11 16:01:17.187 T:123145391046656  NOTICE: EMBY.objects.core.music -> USERDATA album [792] 29596: ...and Out Come the Wolves
2019-10-11 16:01:17.204 T:123145395253248  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/ReportProgressRequested ]
2019-10-11 16:01:18.188 T:123145391046656  NOTICE: EMBY.database -> [emby] 1 rows updated.
2019-10-11 16:01:18.190 T:123145391046656  NOTICE: EMBY.library -> --<[ q:userdata/105553157039520 ]
2019-10-11 16:01:20.190 T:123145386840064  NOTICE: EMBY.library -> -->[ q:userdata/Audio/105553158759008 ]
2019-10-11 16:01:20.230 T:123145391046656  NOTICE: EMBY.objects.core.music -> USERDATA song [10617] 29634: Maxwell Murder
2019-10-11 16:01:21.232 T:123145391046656  NOTICE: EMBY.database -> [emby] 1 rows updated.
2019-10-11 16:01:21.234 T:123145391046656  NOTICE: EMBY.database -> [music] 1 rows updated.
2019-10-11 16:01:21.235 T:123145391046656  NOTICE: EMBY.library -> --<[ q:userdata/105553158759008 ]
2019-10-11 16:01:22.210 T:123145391046656  NOTICE: EMBY.hooks.monitor -> -->[ q:monitor/ReportProgressRequested ]
2019-10-11 16:01:22.210 T:123145391046656  NOTICE: EMBY.objects.player -> [ no next episode detected ]
2019-10-11 16:01:22.925 T:123145365807104  NOTICE: EMBY.objects.monitor -> [ xbmc/Player.OnStop ]
2019-10-11 16:01:23.029 T:123145365807104  NOTICE: EMBY.hooks.player -> [ played info ] {u'PlaySessionId': u'47c8805ffe1c4f8db947bc9dc8f64fe5', 'Muted': False, u'SubtitleStreamIndex': -1, 'Server': <emby.client.EmbyClient object at 0x60000262fea0>, u'MediaSourceId': u'29634', u'AudioStreamIndex': 1, u'CurrentEpisode': None, u'ServerId': None, u'Runtime': 859340000, u'Type': u'Audio', u'PlayMethod': u'DirectStream', 'Volume': 100, u'LiveStreamId': None, u'SubsMapping': None, u'CurrentPosition': 9, 'Track': True, 'Paused': False, u'DeviceId': u'240F8449A87848D38BBB2FA57CF6D2BC', 'File': 'http://127.0.0.1:57578/emby/Audio/29634/stream.m4a', u'PlayOption': u'Native', u'Path': u'http://127.0.0.1:57578/emby/Audio/29634/stream.m4a', u'Id': u'29634', u'AutoSwitched': None}
2019-10-11 16:01:23.059 T:123145365807104  NOTICE: EMBY.hooks.player -> --<[ playback ]
2019-10-11 16:01:23.251 T:123145386840064  NOTICE: EMBY.library -> --[ sync/2019-10-11T04:59:23z ]
2019-10-11 16:01:23.670 T:123145365807104  NOTICE: EMBY.entrypoint.service -> [ onNotification/plugin.video.emby/UserDataChanged ]
2019-10-11 16:01:23.671 T:123145365807104  NOTICE: EMBY.entrypoint.service -> [ UserDataChanged ] {u'UserId': u'5d46c5fa93b244e39ad3e7e6d5bf1873', u'UserDataList': [{u'ItemId': u'29634', u'IsFavorite': False, u'Played': True, u'LastPlayedDate': u'2019-10-11T05:01:13.0000000+00:00', u'PlaybackPositionTicks': 0, u'PlayCount': 2}, {u'ItemId': u'29596', u'PlayCount': 0, u'PlaybackPositionTicks': 0, u'IsFavorite': False, u'Played': False}]}
2019-10-11 16:01:23.671 T:123145365807104  NOTICE: EMBY.library -> ---[ userdata:2 ]
2019-10-11 16:01:26.274 T:123145386840064  NOTICE: EMBY.library -> -->[ q:download/105553157342944 ]
2019-10-11 16:01:27.290 T:123145391046656  NOTICE: EMBY.downloader -> --<[ q:download/105553157342944/2 ]
2019-10-11 16:01:27.291 T:123145391046656  NOTICE: EMBY.downloader -> --[ q:download/remove ] []
2019-10-11 16:01:29.303 T:123145386840064  NOTICE: EMBY.library -> -->[ q:userdata/MusicAlbum/105553158588448 ]
2019-10-11 16:01:29.338 T:123145391046656  NOTICE: EMBY.objects.core.music -> USERDATA album [792] 29596: ...and Out Come the Wolves
2019-10-11 16:01:30.341 T:123145391046656  NOTICE: EMBY.database -> [emby] 1 rows updated.
2019-10-11 16:01:30.344 T:123145391046656  NOTICE: EMBY.library -> --<[ q:userdata/105553158588448 ]
2019-10-11 16:01:32.345 T:123145386840064  NOTICE: EMBY.library -> -->[ q:userdata/Audio/105553159165664 ]
2019-10-11 16:01:32.382 T:123145391046656  NOTICE: EMBY.objects.core.music -> USERDATA song [10617] 29634: Maxwell Murder
2019-10-11 16:01:33.386 T:123145391046656  NOTICE: EMBY.database -> [emby] 1 rows updated.
2019-10-11 16:01:33.388 T:123145391046656  NOTICE: EMBY.database -> [music] 1 rows updated.
2019-10-11 16:01:33.395 T:123145391046656  NOTICE: EMBY.library -> --<[ q:userdata/105553159165664 ]
2019-10-11 16:01:35.414 T:123145386840064  NOTICE: EMBY.library -> --[ sync/2019-10-11T04:59:35z ]

Emby Server Log

2019-10-11 16:00:19.287 Info HttpServer: HTTP Response 206 to 192.168.1.241. Time: 3ms. http://192.168.1.150:8096/emby/Items/29634/Images/Primary/0?EnableImageEnhancers=True&Tag=64959f344463245c3875a95892b5fdf6
2019-10-11 16:00:30.091 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Audio/29634/stream.m4a. UserAgent: Kodi/18.4 (Macintosh; Intel Mac OS X 10_14_6) App_Bitness/64 Version/18.4-Git:20190831-3ade758ceb
2019-10-11 16:00:30.094 Info App: ProcessRun 'StreamTranscode 4c5ccf' Execute: /Applications/EmbyServer.app/Contents/MacOS/ffmpeg -i file:"/Volumes/Qmultimedia/music/Rancid/Rancid [1995] ...and Out Come the Wolves/01 - Maxwell Murder.m4a" -threads 0 -vn -ac 2 -ar 44100 -id3v2_version 3 -write_id3v1 1 -y "/Users/shaunlowe/.config/emby-server/transcoding-temp/9dc0e57a896242d49cf1c34bc7a86749.m4a"
2019-10-11 16:00:30.103 Info App: ProcessRun 'StreamTranscode 4c5ccf' Started.
2019-10-11 16:00:30.213 Info App: ffmpeg successfully started
2019-10-11 16:00:30.218 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items/29634. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:30.223 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 5ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items/29634
2019-10-11 16:00:31.331 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Audio/29634/stream.m4a. UserAgent: Kodi/18.4 (Macintosh; Intel Mac OS X 10_14_6) App_Bitness/64 Version/18.4-Git:20190831-3ade758ceb
2019-10-11 16:00:31.331 Info HttpServer: SocketException: http://192.168.1.150:8096/emby/Audio/29634/stream.m4a?api_key=XXXXXXXXXXXXXXX
2019-10-11 16:00:31.331 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 1240ms. http://192.168.1.150:8096/emby/Audio/29634/stream.m4a
2019-10-11 16:00:31.706 Info App: ProcessRun 'StreamTranscode 4c5ccf' Process exited with code 0
2019-10-11 16:00:31.706 Info App: FFMpeg exited with code 0
2019-10-11 16:00:33.873 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 2542ms. http://192.168.1.150:8096/emby/Audio/29634/stream.m4a
2019-10-11 16:00:34.012 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Audio/29649/stream.m4a. UserAgent: Kodi/18.4 (Macintosh; Intel Mac OS X 10_14_6) App_Bitness/64 Version/18.4-Git:20190831-3ade758ceb
2019-10-11 16:00:34.015 Info App: ProcessRun 'StreamTranscode ba6176' Execute: /Applications/EmbyServer.app/Contents/MacOS/ffmpeg -i file:"/Volumes/Qmultimedia/music/Rancid/Rancid [1995] ...and Out Come the Wolves/02 - The 11th Hour.m4a" -threads 0 -vn -ac 2 -ar 44100 -id3v2_version 3 -write_id3v1 1 -y "/Users/shaunlowe/.config/emby-server/transcoding-temp/ff6b776f62ee9f9dd32686c730b1dd3e.m4a"
2019-10-11 16:00:34.024 Info App: ProcessRun 'StreamTranscode ba6176' Started.
2019-10-11 16:00:34.080 Info HttpServer: HTTP POST http://192.168.1.150:8096/emby/Sessions/Playing. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:34.085 Info SessionManager: Playback start reported by app Kodi 4.1.14 playing Maxwell Murder. Started at 4000 ms
2019-10-11 16:00:34.085 Info HttpServer: HTTP Response 204 to 192.168.1.241. Time: 6ms. http://192.168.1.150:8096/emby/Sessions/Playing
2019-10-11 16:00:34.091 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A33.546Z&hasUserId=true. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:34.096 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 4ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A33.546Z&hasUserId=true
2019-10-11 16:00:34.096 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A33.547Z&hasUserId=false. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:34.099 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 4ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A33.547Z&hasUserId=false
2019-10-11 16:00:34.100 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A33.547Z&hasUserId=true. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:34.100 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A33.548Z&hasUserId=false. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:34.105 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 5ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A33.547Z&hasUserId=true
2019-10-11 16:00:34.114 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 14ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A33.548Z&hasUserId=false
2019-10-11 16:00:34.129 Info App: ffmpeg successfully started
2019-10-11 16:00:35.139 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Audio/29649/stream.m4a. UserAgent: Kodi/18.4 (Macintosh; Intel Mac OS X 10_14_6) App_Bitness/64 Version/18.4-Git:20190831-3ade758ceb
2019-10-11 16:00:35.140 Info HttpServer: SocketException: http://192.168.1.150:8096/emby/Audio/29649/stream.m4a?api_key=XXXXXXXXXXX
2019-10-11 16:00:35.140 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 1127ms. http://192.168.1.150:8096/emby/Audio/29649/stream.m4a
2019-10-11 16:00:35.554 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:35.559 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 5ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596
2019-10-11 16:00:36.640 Info App: ProcessRun 'StreamTranscode ba6176' Process exited with code 0
2019-10-11 16:00:36.640 Info App: FFMpeg exited with code 0
2019-10-11 16:00:37.011 Info HttpServer: HTTP SUBSCRIBE http://192.168.1.150:8096/dlna/ddd98d3a76a8409e874beb4b916dc02b/contentdirectory/events. UserAgent: 
2019-10-11 16:00:37.011 Info HttpServer: HTTP Response 200 to 192.168.1.7. Time: 0ms. http://192.168.1.150:8096/dlna/ddd98d3a76a8409e874beb4b916dc02b/contentdirectory/events
2019-10-11 16:00:37.357 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:37.360 Info HttpServer: HTTP Response 200 to 192.168.1.10. Time: 3ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596
2019-10-11 16:00:38.205 Info HttpServer: HTTP POST http://192.168.1.150:8096/emby/Sessions/Playing/Progress. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:38.206 Info HttpServer: HTTP Response 204 to 192.168.1.241. Time: 1ms. http://192.168.1.150:8096/emby/Sessions/Playing/Progress
2019-10-11 16:00:38.834 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 3695ms. http://192.168.1.150:8096/emby/Audio/29649/stream.m4a
2019-10-11 16:00:38.920 Info HttpServer: HTTP POST http://192.168.1.150:8096/emby/Sessions/Playing/Stopped. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:38.921 Info SessionManager: Playback stopped reported by app Kodi 4.1.14 playing Maxwell Murder. Stopped at 4000 ms
2019-10-11 16:00:38.922 Info HttpServer: HTTP Response 204 to 192.168.1.241. Time: 1ms. http://192.168.1.150:8096/emby/Sessions/Playing/Stopped
2019-10-11 16:00:38.927 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A38.382Z&hasUserId=true. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:38.927 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A38.383Z&hasUserId=false. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:38.928 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A38.384Z&hasUserId=true. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:38.929 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A38.384Z&hasUserId=false. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:38.929 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items/29634. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:38.931 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 4ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A38.383Z&hasUserId=false
2019-10-11 16:00:38.932 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 3ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items/29634
2019-10-11 16:00:38.933 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 6ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A38.382Z&hasUserId=true
2019-10-11 16:00:38.935 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 7ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A38.384Z&hasUserId=true
2019-10-11 16:00:38.936 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 7ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A38.384Z&hasUserId=false
2019-10-11 16:00:38.959 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items/29649. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:38.961 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 2ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items/29649
2019-10-11 16:00:39.002 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Audio/29632/stream.m4a. UserAgent: Kodi/18.4 (Macintosh; Intel Mac OS X 10_14_6) App_Bitness/64 Version/18.4-Git:20190831-3ade758ceb
2019-10-11 16:00:39.005 Info App: ProcessRun 'StreamTranscode 4b00d9' Execute: /Applications/EmbyServer.app/Contents/MacOS/ffmpeg -i file:"/Volumes/Qmultimedia/music/Rancid/Rancid [1995] ...and Out Come the Wolves/03 - Roots Radicals.m4a" -threads 0 -vn -ac 2 -ar 44100 -id3v2_version 3 -write_id3v1 1 -y "/Users/shaunlowe/.config/emby-server/transcoding-temp/c521fb2bf23a4c6a90db3f4be56bff2b.m4a"
2019-10-11 16:00:39.014 Info App: ProcessRun 'StreamTranscode 4b00d9' Started.
2019-10-11 16:00:39.055 Info HttpServer: HTTP POST http://192.168.1.150:8096/emby/Sessions/Playing. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:39.061 Info SessionManager: Playback start reported by app Kodi 4.1.14 playing The 11th Hour. Started at 4000 ms
2019-10-11 16:00:39.061 Info HttpServer: HTTP Response 204 to 192.168.1.241. Time: 7ms. http://192.168.1.150:8096/emby/Sessions/Playing
2019-10-11 16:00:39.068 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A38.522Z&hasUserId=true. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:39.068 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A38.522Z&hasUserId=false. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:39.073 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 5ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A38.522Z&hasUserId=true
2019-10-11 16:00:39.073 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A38.525Z&hasUserId=true. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:39.079 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 6ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A38.525Z&hasUserId=true
2019-10-11 16:00:39.082 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 14ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A38.522Z&hasUserId=false
2019-10-11 16:00:39.082 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A38.526Z&hasUserId=false. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:39.085 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 12ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A38.526Z&hasUserId=false
2019-10-11 16:00:39.169 Info App: ffmpeg successfully started
2019-10-11 16:00:40.176 Info HttpServer: SocketException: http://192.168.1.150:8096/emby/Audio/29632/stream.m4a?api_key=XXXXXXXXXXX
2019-10-11 16:00:40.177 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 1174ms. http://192.168.1.150:8096/emby/Audio/29632/stream.m4a
2019-10-11 16:00:40.177 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Audio/29632/stream.m4a. UserAgent: Kodi/18.4 (Macintosh; Intel Mac OS X 10_14_6) App_Bitness/64 Version/18.4-Git:20190831-3ade758ceb
2019-10-11 16:00:40.456 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596%2C29649. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:40.464 Info HttpServer: HTTP Response 200 to 192.168.1.10. Time: 8ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596%2C29649
2019-10-11 16:00:41.617 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596%2C29649. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:41.622 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 6ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596%2C29649
2019-10-11 16:00:42.008 Info App: ProcessRun 'StreamTranscode 4b00d9' Process exited with code 0
2019-10-11 16:00:42.008 Info App: FFMpeg exited with code 0
2019-10-11 16:00:44.187 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 4010ms. http://192.168.1.150:8096/emby/Audio/29632/stream.m4a
2019-10-11 16:00:44.389 Info HttpServer: HTTP POST http://192.168.1.150:8096/emby/Sessions/Playing/Stopped. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:44.390 Info SessionManager: Playback stopped reported by app Kodi 4.1.14 playing The 11th Hour. Stopped at 4000 ms
2019-10-11 16:00:44.390 Info HttpServer: HTTP Response 204 to 192.168.1.241. Time: 1ms. http://192.168.1.150:8096/emby/Sessions/Playing/Stopped
2019-10-11 16:00:44.395 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A43.851Z&hasUserId=true. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:44.397 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A43.852Z&hasUserId=false. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:44.397 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A43.852Z&hasUserId=true. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:44.397 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A43.853Z&hasUserId=false. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:00:44.399 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 4ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A43.851Z&hasUserId=true
2019-10-11 16:00:44.399 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items/29649. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:44.403 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 4ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items/29649
2019-10-11 16:00:44.406 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 9ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A00%3A43.852Z&hasUserId=true
2019-10-11 16:00:44.409 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 12ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A43.853Z&hasUserId=false
2019-10-11 16:00:44.412 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 15ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A00%3A43.852Z&hasUserId=false
2019-10-11 16:00:46.546 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29649%2C29596. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:46.549 Info HttpServer: HTTP Response 200 to 192.168.1.10. Time: 3ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29649%2C29596
2019-10-11 16:00:47.382 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29649%2C29596. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:00:47.384 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 3ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29649%2C29596
2019-10-11 16:01:13.330 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items/29634. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:01:13.332 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 2ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items/29634
2019-10-11 16:01:13.455 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Audio/29634/stream.m4a. UserAgent: Kodi/18.4 (Macintosh; Intel Mac OS X 10_14_6) App_Bitness/64 Version/18.4-Git:20190831-3ade758ceb
2019-10-11 16:01:13.462 Info HttpServer: SocketException: http://192.168.1.150:8096/emby/Audio/29634/stream.m4a?api_key=XXXXXXXXXXXXXX
2019-10-11 16:01:13.462 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 8ms. http://192.168.1.150:8096/emby/Audio/29634/stream.m4a
2019-10-11 16:01:13.462 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Audio/29634/stream.m4a. UserAgent: Kodi/18.4 (Macintosh; Intel Mac OS X 10_14_6) App_Bitness/64 Version/18.4-Git:20190831-3ade758ceb
2019-10-11 16:01:13.492 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Audio/29634/stream.m4a. UserAgent: Kodi/18.4 (Macintosh; Intel Mac OS X 10_14_6) App_Bitness/64 Version/18.4-Git:20190831-3ade758ceb
2019-10-11 16:01:13.496 Info HttpServer: SocketException: http://192.168.1.150:8096/emby/Audio/29634/stream.m4a?api_key=XXXXXXXXXXXXXXX
2019-10-11 16:01:13.496 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 4ms. http://192.168.1.150:8096/emby/Audio/29634/stream.m4a
2019-10-11 16:01:13.627 Info HttpServer: HTTP POST http://192.168.1.150:8096/emby/Sessions/Playing. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:01:13.629 Info SessionManager: Playback start reported by app Kodi 4.1.14 playing Maxwell Murder. Started at 0 ms
2019-10-11 16:01:13.629 Info HttpServer: HTTP Response 204 to 192.168.1.241. Time: 2ms. http://192.168.1.150:8096/emby/Sessions/Playing
2019-10-11 16:01:13.634 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A01%3A13.090Z&hasUserId=true. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:01:13.634 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A01%3A13.090Z&hasUserId=false. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:01:13.636 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A01%3A13.091Z&hasUserId=false. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:01:13.637 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 3ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A01%3A13.090Z&hasUserId=true
2019-10-11 16:01:13.639 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 4ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A01%3A13.090Z&hasUserId=false
2019-10-11 16:01:13.640 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 4ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A01%3A13.091Z&hasUserId=false
2019-10-11 16:01:13.641 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A01%3A13.090Z&hasUserId=true. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:01:13.643 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 2ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A01%3A13.090Z&hasUserId=true
2019-10-11 16:01:14.681 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:01:14.683 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 3ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596
2019-10-11 16:01:15.565 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 2102ms. http://192.168.1.150:8096/emby/Audio/29634/stream.m4a
2019-10-11 16:01:16.936 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:01:16.939 Info HttpServer: HTTP Response 200 to 192.168.1.10. Time: 3ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596
2019-10-11 16:01:17.749 Info HttpServer: HTTP POST http://192.168.1.150:8096/emby/Sessions/Playing/Progress. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:01:17.751 Info HttpServer: HTTP Response 204 to 192.168.1.241. Time: 2ms. http://192.168.1.150:8096/emby/Sessions/Playing/Progress
2019-10-11 16:01:23.575 Info HttpServer: HTTP POST http://192.168.1.150:8096/emby/Sessions/Playing/Stopped. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:01:23.576 Info SessionManager: Playback stopped reported by app Kodi 4.1.14 playing Maxwell Murder. Stopped at 9000 ms
2019-10-11 16:01:23.576 Info HttpServer: HTTP Response 204 to 192.168.1.241. Time: 1ms. http://192.168.1.150:8096/emby/Sessions/Playing/Stopped
2019-10-11 16:01:23.582 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A01%3A23.037Z&hasUserId=true. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:01:23.582 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A01%3A23.038Z&hasUserId=false. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:01:23.583 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A01%3A23.038Z&hasUserId=true. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:01:23.583 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items/29634. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:01:23.585 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 4ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A01%3A23.037Z&hasUserId=true
2019-10-11 16:01:23.586 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 2ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items/29634
2019-10-11 16:01:23.588 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 6ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A01%3A23.038Z&hasUserId=false
2019-10-11 16:01:23.590 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 6ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=7&minDate=2019-10-10T05%3A01%3A23.038Z&hasUserId=true
2019-10-11 16:01:23.591 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A01%3A23.038Z&hasUserId=false. UserAgent: Mozilla/5.0 (Macintosh; Intel Mac OS X 10.14; rv:69.0) Gecko/20100101 Firefox/69.0
2019-10-11 16:01:23.593 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 2ms. http://192.168.1.150:8096/emby/System/ActivityLog/Entries?startIndex=0&limit=4&minDate=2019-10-04T05%3A01%3A23.038Z&hasUserId=false
2019-10-11 16:01:26.068 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:01:26.070 Info HttpServer: HTTP Response 200 to 192.168.1.10. Time: 3ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596
2019-10-11 16:01:26.823 Info HttpServer: HTTP GET http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596. UserAgent: Emby-Kodi/4.1.14
2019-10-11 16:01:26.826 Info HttpServer: HTTP Response 200 to 192.168.1.241. Time: 3ms. http://192.168.1.150:8096/emby/Users/5d46c5fa93b244e39ad3e7e6d5bf1873/Items?Fields=Path%2CGenres%2CSortName%2CStudios%2CWriter%2CTaglines%2CLocalTrailerCount%2CVideo3DFormat%2COfficialRating%2CCumulativeRunTimeTicks%2CItemCounts%2CPremiereDate%2CProductionYear%2CMetascore%2CAirTime%2CDateCreated%2CPeople%2COverview%2CCommunityRating%2CStartDate%2CCriticRating%2CCriticRatingSummary%2CEtag%2CShortOverview%2CProductionLocations%2CTags%2CProviderIds%2CParentId%2CRemoteTrailers%2CSpecialEpisodeNumbers%2CStatus%2CEndDate%2CMediaSources%2CVoteCount%2CRecursiveItemCount%2CPrimaryImageAspectRatio%2CDisplayOrder%2CPresentationUniqueKey%2COriginalTitle&Ids=29634%2C29596
2019-10-11 16:01:50.003 Info HttpServer: HTTP SUBSCRIBE http://192.168.1.150:8096/dlna/ddd98d3a76a8409e874beb4b916dc02b/contentdirectory/events. UserAgent: 
2019-10-11 16:01:50.003 Info HttpServer: HTTP Response 200 to 192.168.1.7. Time: 0ms. http://192.168.1.150:8096/dlna/ddd98d3a76a8409e874beb4b916dc02b/contentdirectory/events

API Keys removed.

 

I can perform further testing on this and other devices and provide full logs if needed.

Link to comment
Share on other sites

nerdwork

Hi there, how does the web app compare?

No issues with web app or iOS apps. I don’t think it’s a server issue, it’s something with Kodi addon or Kodi itself. I’d like to nail the issue down, happy to try any testing you suggest.

Link to comment
Share on other sites

nerdwork

Are you still running into this?

 

 

I am Luke. Just tested again on my Vero4K, same issue persists.

 

Any other logs or anything I can grab?

Link to comment
Share on other sites

Guest
This topic is now closed to further replies.
 Share

×
×
  • Create New...