search

MiczFlor / RPi-Jukebox-RFID

A Raspberry Pi jukebox, playing local music, podcasts, web radio and streams triggered by RFID cards, web app or home automation. All plug and play via USB. GPIO scripts available.
http://phoniebox.de
MIT License
1.38k stars 397 forks source link

🐛 | Spotify does not play some tracks #1034

Open marvingrieger opened 4 years ago

marvingrieger commented 4 years ago

Bug

What I did

I installed the latest RPi-Jukebox-RFID release (v. 2.0.0.) and tried to play an album using spotify.

What happened

Some tracks do not play. In iris, you can see that the progress bar ("seconds played") gets reset to 0 after 3-4 seconds. However, most tracks work as expected.

I recorded the phenomenon in Iris, you can see it in the following GIF. There, you can see that the first track of the album (here: the theme-song) is not played but gets reset after 3 seconds over and over again. Also, I don't hear any sound. When I skip that track, the next one works as expected as you can see by the time played. Going back to the previous track, it still does not play and gets reset again.

Fehler_Wiedergabe

I expected this to happen

I expect that every track is playable.

Further information that might help

I assume that this problem is somehow related to caching.

When setting allow_cache = false in /etc/mopidy/mopidy.conf, some tracks that previously worked, stop working. However, tracks that previously didn't work, still don't work.

Software

Base image and version

PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"

Branch / Release

master

Installscript

scripts/installscripts/buster-install-default.sh

Hardware

RaspberryPi version

Raspberry Pi 3 Model B Rev 1.2

RFID Reader

13ba:0018 PCPlay Barcode PCP-BCG4209

Soundcard

Built-In

Other notable hardware

/

s-martin commented 4 years ago

Are these local tracks or Spotify?

Are these tracks any different, e.g. length, size, encoding than the tracks which work?

Could you try a working and a not working track and afterwards post the log results from journalctl -u mopidy?

marvingrieger commented 4 years ago

All of these tracks are Spotify-based tracks. In fact, I am playing an album on Spotify which consists of multiple tracks.

Technically, I am not aware of any differences. Of course, they have different length, but I don't see anything special. Also, as written above, changing the allow_cache = false property affects the playback. Here are the Spotify-URIs of the example shown above:

Album No. 1: spotify:album:0VfB018ZTsA9Ai8OWAyUzS Not working (Track 01 - "Theme song"): spotify:track:7AFFqxBwDNjfSGg7wls0kf Working (Track 02): spotify:track:5Bc98Aa2ORHrMfwmTG75oC

So far, I observed the problem only on the first track of an album. I.e., tracks after the first one were not affected (at least, I didn't observe this so far).

My primary use case for the Phoniebox are audiobooks. That means, I have multiple Spotify-based albums on the Phoniebox. The first track usually is the "theme song". I assumed that this track might be "shared" between the audiobooks, therefore I suspected a caching problem (see above). However. the Spotify-URIs for the theme song are different. For example, the the URI of the theme song of Album No. 2 is spotify:track:4SkritXhMzYwjwrGS0VG2T

The log does not give much information. Here is the output of journalctl -u mopidy after starting an album. As described, the first track failed whereafter I skipped it.

-- Logs begin at Sun 2020-07-05 14:07:17 BST, end at Sun 2020-07-05 14:09:31 BST. --
Jul 05 14:07:29 raspberrypi systemd[1]: Starting Mopidy music server...
Jul 05 14:07:29 raspberrypi systemd[1]: Started Mopidy music server.
Jul 05 14:07:33 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.__main__ Starting Mopidy 3.0.2
Jul 05 14:07:33 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.config Loading config from builtin defaults
Jul 05 14:07:33 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.config Loading config from file:///usr/share/mopidy/conf.d/mopidy.conf
Jul 05 14:07:33 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.config Loading config from file:///usr/share/mopidy/conf.d/mopidy-local.conf
Jul 05 14:07:33 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.config Loading config from file:///etc/mopidy/mopidy.conf
Jul 05 14:07:33 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.config Loading config from command line options
Jul 05 14:07:33 raspberrypi mopidy[570]: ERROR    [MainThread] mopidy.internal.log Loading logging config '/etc/mopidy/logging.conf' failed. 'formatters'
Jul 05 14:07:34 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.__main__ Enabled extensions: file, http, local, mpd, stream, iris, softwaremixer, spotify, m3u
Jul 05 14:07:34 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.__main__ Disabled extensions: none
Jul 05 14:07:35 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.commands Starting Mopidy mixer: SoftwareMixer
Jul 05 14:07:35 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.commands Mixer volume set to 30
Jul 05 14:07:35 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.commands Starting Mopidy audio
Jul 05 14:07:35 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.commands Starting Mopidy backends: FileBackend, M3UBackend, StreamBackend, SpotifyBackend, LocalBackend
Jul 05 14:07:35 raspberrypi mopidy[570]: INFO     [Audio-2] mopidy.audio.actor Audio output set to "alsasink"
Jul 05 14:07:36 raspberrypi mopidy[570]: INFO     [SpotifyEventLoop] mopidy_spotify.backend Logged in to Spotify in online mode
Jul 05 14:07:36 raspberrypi mopidy[570]: INFO     [SpotifyBackend-6] mopidy_spotify.web Logged into Spotify Web API as XXX
Jul 05 14:08:09 raspberrypi mopidy[570]: INFO     [SpotifyBackend-6] mopidy_spotify.playlists Refreshed 58 Spotify playlists
Jul 05 14:08:09 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.commands Starting Mopidy core
Jul 05 14:08:09 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.commands Starting Mopidy frontends: IrisFrontend, HttpFrontend, MpdFrontend
Jul 05 14:08:09 raspberrypi mopidy[570]: INFO     [IrisFrontend-11] mopidy_iris.core Starting Iris 3.45.0
Jul 05 14:08:09 raspberrypi mopidy[570]: INFO     [HttpFrontend-13] mopidy.http.actor HTTP server running at [::ffff:0.0.0.0]:6680
Jul 05 14:08:09 raspberrypi mopidy[570]: INFO     [MainThread] mopidy_mpd.actor MPD server running at [::ffff:0.0.0.0]:6600
Jul 05 14:08:09 raspberrypi mopidy[570]: INFO     [MainThread] mopidy.commands Starting GLib mainloop
Jul 05 14:08:10 raspberrypi mopidy[570]: INFO     [MpdSession-15] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58206
Jul 05 14:08:11 raspberrypi mopidy[570]: INFO     [MpdSession-16] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58210
Jul 05 14:08:16 raspberrypi mopidy[570]: INFO     [MpdSession-17] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58218
Jul 05 14:08:21 raspberrypi mopidy[570]: INFO     [MpdSession-18] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58222
Jul 05 14:08:26 raspberrypi mopidy[570]: INFO     [MpdSession-19] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58226
Jul 05 14:08:31 raspberrypi mopidy[570]: INFO     [MpdSession-20] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58230
Jul 05 14:08:36 raspberrypi mopidy[570]: INFO     [MpdSession-21] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58234
Jul 05 14:08:42 raspberrypi mopidy[570]: INFO     [MpdSession-22] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58238
Jul 05 14:08:48 raspberrypi mopidy[570]: INFO     [MpdSession-23] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58242
Jul 05 14:08:54 raspberrypi mopidy[570]: INFO     [MpdSession-24] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58246
Jul 05 14:09:00 raspberrypi mopidy[570]: INFO     [MpdSession-25] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58250
Jul 05 14:09:05 raspberrypi mopidy[570]: INFO     [MpdSession-26] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58254
Jul 05 14:09:10 raspberrypi mopidy[570]: INFO     [MpdSession-27] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58258
Jul 05 14:09:15 raspberrypi mopidy[570]: INFO     [MpdSession-28] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58262
Jul 05 14:09:21 raspberrypi mopidy[570]: INFO     [MpdSession-29] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58266
Jul 05 14:09:22 raspberrypi mopidy[570]: INFO     [MpdSession-30] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58270
Jul 05 14:09:22 raspberrypi mopidy[570]: INFO     [MpdSession-31] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58274
Jul 05 14:09:22 raspberrypi mopidy[570]: INFO     [MpdSession-32] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58278
Jul 05 14:09:22 raspberrypi mopidy[570]: INFO     [MpdSession-33] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58282
Jul 05 14:09:23 raspberrypi mopidy[570]: INFO     [MpdSession-35] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58286
Jul 05 14:09:23 raspberrypi mopidy[570]: INFO     [MpdSession-36] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58290
Jul 05 14:09:23 raspberrypi mopidy[570]: INFO     [MpdSession-37] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58294
Jul 05 14:09:24 raspberrypi mopidy[570]: INFO     [MpdSession-39] mopidy_mpd.session New MPD connection from [::ffff:127.0.0.1]:58298
marvingrieger commented 4 years ago

Ok, I enabled the mopidy-logging by creating a /etc/mopidy/logging.conf and setting the log-level to debug.

When the track is not played and gets reset all the time, this is what is logged:

2020-07-05 14:27:42,990 DEBUG [571:MpdSession-19] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45870: command_list_begin
2020-07-05 14:27:42,991 DEBUG [571:MpdSession-19] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45870: load "Conni - Folge 01"
2020-07-05 14:27:42,992 DEBUG [571:MpdSession-19] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45870: command_list_end
2020-07-05 14:27:43,047 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_tl_tracks","params":{},"jsonrpc":"2.0","id":27}'
2020-07-05 14:27:43,128 DEBUG [571:SpotifyBackend-6] urllib3.connectionpool: https://api.spotify.com:443 "GET /v1/me/playlists?limit=50 HTTP/1.1" 200 None
2020-07-05 14:27:43,148 DEBUG [571:SpotifyBackend-6] mopidy_spotify.utils: playlists.as_list() took 137ms
2020-07-05 14:27:43,154 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 27, "result": []}'
2020-07-05 14:27:43,180 DEBUG [571:Dummy-20] spotify.session: Notify main thread
2020-07-05 14:27:43,181 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Notification received; processing events
2020-07-05 14:27:43,186 DEBUG [571:SpotifyBackend-6] spotify.session: Notify main thread
2020-07-05 14:27:43,188 DEBUG [571:SpotifyEventLoop] spotify.session: Notify main thread
2020-07-05 14:27:43,188 DEBUG [571:SpotifyEventLoop] spotify.album: albumbrowse_complete_callback called
2020-07-05 14:27:43,189 DEBUG [571:SpotifyEventLoop] spotify.session: Metadata updated
2020-07-05 14:27:43,189 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Waiting 2.997s for new events
2020-07-05 14:27:43,191 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Notification received; processing events
2020-07-05 14:27:43,192 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Waiting 2.993s for new events
2020-07-05 14:27:43,192 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Notification received; processing events
2020-07-05 14:27:43,193 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Waiting 2.992s for new events
2020-07-05 14:27:43,245 DEBUG [571:Core-10] mopidy.core.tracklist: Triggering event: tracklist_changed()
2020-07-05 14:27:43,246 DEBUG [571:Core-10] mopidy.listener: Sending tracklist_changed to CoreListener: {}
2020-07-05 14:27:43,249 DEBUG [571:MpdSession-19] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45870: OK
2020-07-05 14:27:43,251 DEBUG [571:MpdFrontend-14] mopidy.listener: Sending playlist to MpdSession: {}
2020-07-05 14:27:43,256 DEBUG [571:MpdSession-19] mopidy_mpd.network: Client most likely disconnected.
2020-07-05 14:27:43,262 DEBUG [571:MpdSession-19] pykka: Unregistered MpdSession (urn:uuid:e7293f21-3558-49e2-92ac-28b16c2ce0f0)
2020-07-05 14:27:43,264 DEBUG [571:MpdSession-19] pykka: Stopped MpdSession (urn:uuid:e7293f21-3558-49e2-92ac-28b16c2ce0f0)
2020-07-05 14:27:43,266 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_tl_tracks","params":{},"jsonrpc":"2.0","id":28}'
2020-07-05 14:27:43,266 DEBUG [571:MpdSession-19] mopidy_mpd.network: Already stopping: Actor is shutting down.
2020-07-05 14:27:43,292 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 28, "result": [{"__model__": "TlTrack", "tlid": 1, "track": {"__model__": "Track", "uri": "spotify:track:7AFFqxBwDNjfSGg7wls0kf", "name": "Intro Theme Song \\"Meine Freundin Conni\\"", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 1, "disc_no": 1, "date": "2012", "length": 38000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 2, "track": {"__model__": "Track", "uri": "spotify:track:5Bc98Aa2ORHrMfwmTG75oC", "name": "Conni zieht um - Teil 01", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 2, "disc_no": 1, "date": "2012", "length": 92000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 3, "track": {"__model__": "Track", "uri": "spotify:track:0tWls6T6mjGkwcM7Rg8RBo", "name": "Conni zieht um - Teil 02", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 3, "disc_no": 1, "date": "2012", "length": 113000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 4, "track": {"__model__": "Track", "uri": "spotify:track:6BuSSiLgUo0Y6N0GWCHzLP", "name": "Conni zieht um - Teil 03", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 4, "disc_no": 1, "date": "2012", "length": 80000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 5, "track": {"__model__": "Track", "uri": "spotify:track:6is6z4aat233fuTPiShwu0", "name": "Conni zieht um - Teil 04", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 5, "disc_no": 1, "date": "2012", "length": 91000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 6, "track": {"__model__": "Track", "uri": "spotify:track:6t8jMqrgyr49M6uC3QbYDT", "name": "Conni zieht um - Teil 05", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 6, "disc_no": 1, "date": "2012", "length": 81000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 7, "track": {"__model__": "Track", "uri": "spotify:track:0uxwIRkcl816R1dnXFjDtI", "name": "Conni zieht um - Teil 06", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 7, "disc_no": 1, "date": "2012", "length": 78000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 8, "track": {"__model__": "Track", "uri": "spotify:track:2He6sXV8J3fjlp5tzBXRtI", "name": "Conni zieht um - Teil 07", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 8, "disc_no": 1, "date": "2012", "length": 80000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 9, "track": {"__model__": "Track", "uri": "spotify:track:53LpemvjYj1hkRiGuJwyPu", "name": "Conni zieht um - Teil 08", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 9, "disc_no": 1, "date": "2012", "length": 82000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 10, "track": {"__model__": "Track", "uri": "spotify:track:77SaOYRHTWTnnpUUqTO7Ou", "name": "Conni zieht um - Teil 09", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 10, "disc_no": 1, "date": "2012", "length": 117000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 11, "track": {"__model__": "Track", "uri": "spotify:track:33KYZC9vRn1U8l9vAKlQtk", "name": "Conni bekommt eine Katze - Teil 01", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 11, "disc_no": 1, "date": "2012", "length": 83000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 12, "track": {"__model__": "Track", "uri": "spotify:track:0YNSi7yq8Ix7aj5BfaCNXj", "name": "Conni bekommt eine Katze - Teil 02", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 12, "disc_no": 1, "date": "2012", "length": 85000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 13, "track": {"__model__": "Track", "uri": "spotify:track:0BGTlEHBdUTc2LkartKmrR", "name": "Conni bekommt eine Katze - Teil 03", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 13, "disc_no": 1, "date": "2012", "length": 87000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 14, "track": {"__model__": "Track", "uri": "spotify:track:51xNmQ2KDWmZYOCRk2Lbhz", "name": "Conni bekommt eine Katze - Teil 04", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 14, "disc_no": 1, "date": "2012", "length": 93000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 15, "track": {"__model__": "Track", "uri": "spotify:track:5vfEzkm2s8zp5fImd3hW5h", "name": "Conni bekommt eine Katze - Teil 05", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 15, "disc_no": 1, "date": "2012", "length": 86000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 16, "track": {"__model__": "Track", "uri": "spotify:track:6eUDJnNYTwgEpkPbJMfhOh", "name": "Conni bekommt eine Katze - Teil 06", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 16, "disc_no": 1, "date": "2012", "length": 92000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 17, "track": {"__model__": "Track", "uri": "spotify:track:5CC8ZvJ9ImEjOIOTeFic6L", "name": "Conni bekommt eine Katze - Teil 07", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 17, "disc_no": 1, "date": "2012", "length": 86000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 18, "track": {"__model__": "Track", "uri": "spotify:track:45x97AOHB67oq4qgYk373X", "name": "Conni bekommt eine Katze - Teil 08", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 18, "disc_no": 1, "date": "2012", "length": 133000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 19, "track": {"__model__": "Track", "uri": "spotify:track:3xr6MneKJubZ0zTrfQrKT1", "name": "Conni macht das Seepferdchen - Teil 01", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 19, "disc_no": 1, "date": "2012", "length": 83000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 20, "track": {"__model__": "Track", "uri": "spotify:track:1wkBKijtRuYwmlTJZow5Bc", "name": "Conni macht das Seepferdchen - Teil 02", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 20, "disc_no": 1, "date": "2012", "length": 112000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 21, "track": {"__model__": "Track", "uri": "spotify:track:27Y27cCBCXEevwc61qeI5X", "name": "Conni macht das Seepferdchen - Teil 03", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 21, "disc_no": 1, "date": "2012", "length": 117000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 22, "track": {"__model__": "Track", "uri": "spotify:track:0LYZ568HtDQb6GaFHWVsma", "name": "Conni macht das Seepferdchen - Teil 04", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 22, "disc_no": 1, "date": "2012", "length": 82000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 23, "track": {"__model__": "Track", "uri": "spotify:track:1ZJUapctFyWKmw89gGgCVL", "name": "Conni macht das Seepferdchen - Teil 05", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 23, "disc_no": 1, "date": "2012", "length": 80000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 24, "track": {"__model__": "Track", "uri": "spotify:track:15KWXHpt377cCMMzlLq0TC", "name": "Conni macht das Seepferdchen - Teil 06", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 24, "disc_no": 1, "date": "2012", "length": 91000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 25, "track": {"__model__": "Track", "uri": "spotify:track:6ouvTUcumOx9Qhh2oLoMMu", "name": "Conni macht das Seepferdchen - Teil 07", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 25, "disc_no": 1, "date": "2012", "length": 119000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 26, "track": {"__model__": "Track", "uri": "spotify:track:2rBgZlXAACWLP7e5oLmEii", "name": "Conni geht zelten - Teil 01", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 26, "disc_no": 1, "date": "2012", "length": 103000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 27, "track": {"__model__": "Track", "uri": "spotify:track:7LtOouHls9X2u6ZBIAX6zX", "name": "Conni geht zelten - Teil 02", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 27, "disc_no": 1, "date": "2012", "length": 83000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 28, "track": {"__model__": "Track", "uri": "spotify:track:1sIropZDJXiK9rvYglcoDV", "name": "Conni geht zelten - Teil 03", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 28, "disc_no": 1, "date": "2012", "length": 86000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 29, "track": {"__model__": "Track", "uri": "spotify:track:4IC6GgrWttLfVzUUMQsaTU", "name": "Conni geht zelten - Teil 04", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 29, "disc_no": 1, "date": "2012", "length": 71000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 30, "track": {"__model__": "Track", "uri": "spotify:track:1jUTNOmgZdzyRhWAK35ccC", "name": "Conni geht zelten - Teil 05", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 30, "disc_no": 1, "date": "2012", "length": 80000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 31, "track": {"__model__": "Track", "uri": "spotify:track:6E4xHaj047Qf36vYX7aE8i", "name": "Conni geht zelten - Teil 06", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 31, "disc_no": 1, "date": "2012", "length": 92000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 32, "track": {"__model__": "Track", "uri": "spotify:track:04APLFpe4SigUrz2bYR05M", "name": "Conni geht zelten - Teil 07", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 32, "disc_no": 1, "date": "2012", "length": 80000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 33, "track": {"__model__": "Track", "uri": "spotify:track:3RO8APcWyGKycqekCwxE71", "name": "Conni geht zelten - Teil 08", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 33, "disc_no": 1, "date": "2012", "length": 64000, "bitrate": 160}}, {"__model__": "TlTrack", "tlid": 34, "track": {"__model__": "Track", "uri": "spotify:track:6HWdCtKss7VmaVWGBRfN5z", "name": "Conni geht zelten - Teil 09", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 34, "disc_no": 1, "date": "2012", "length": 85000, "bitrate": 160}}]}'
2020-07-05 14:27:43,301 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_state","params":{},"jsonrpc":"2.0","id":29}'
2020-07-05 14:27:43,312 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 29, "result": "stopped"}'
2020-07-05 14:27:43,316 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.mixer.get_volume","params":{},"jsonrpc":"2.0","id":30}'
2020-07-05 14:27:43,331 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 30, "result": 30}'
2020-07-05 14:27:43,332 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.mixer.get_mute","params":{},"jsonrpc":"2.0","id":31}'
2020-07-05 14:27:43,336 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 31, "result": false}'
2020-07-05 14:27:43,338 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_consume","params":{},"jsonrpc":"2.0","id":32}'
2020-07-05 14:27:43,339 DEBUG [571:MainThread] pykka: Registered MpdSession (urn:uuid:ee85b26e-7d1c-43b0-b976-6d34c48f17ca)
2020-07-05 14:27:43,341 DEBUG [571:MainThread] pykka: Starting MpdSession (urn:uuid:ee85b26e-7d1c-43b0-b976-6d34c48f17ca)
2020-07-05 14:27:43,343 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 32, "result": false}'
2020-07-05 14:27:43,344 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_random","params":{},"jsonrpc":"2.0","id":33}'
2020-07-05 14:27:43,345 INFO [571:MpdSession-21] mopidy_mpd.session: New MPD connection from [::ffff:127.0.0.1]:45874
2020-07-05 14:27:43,346 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 33, "result": false}'
2020-07-05 14:27:43,349 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_repeat","params":{},"jsonrpc":"2.0","id":34}'
2020-07-05 14:27:43,352 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 34, "result": false}'
2020-07-05 14:27:43,352 DEBUG [571:MpdSession-21] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45874: single "0"
2020-07-05 14:27:43,353 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_current_tl_track","params":{},"jsonrpc":"2.0","id":35}'
2020-07-05 14:27:43,355 DEBUG [571:MpdSession-21] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45874: OK
2020-07-05 14:27:43,357 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 35, "result": null}'
2020-07-05 14:27:43,358 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_time_position","params":{},"jsonrpc":"2.0","id":36}'
2020-07-05 14:27:43,361 DEBUG [571:MpdSession-21] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45874: command_list_ok_begin
2020-07-05 14:27:43,362 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 36, "result": 0}'
2020-07-05 14:27:43,363 DEBUG [571:MpdSession-21] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45874: status
2020-07-05 14:27:43,364 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_state","params":{},"jsonrpc":"2.0","id":37}'
2020-07-05 14:27:43,365 DEBUG [571:MpdSession-21] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45874: currentsong
2020-07-05 14:27:43,367 DEBUG [571:MpdSession-21] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45874: command_list_end
2020-07-05 14:27:43,368 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 37, "result": "stopped"}'
2020-07-05 14:27:43,378 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.mixer.get_volume","params":{},"jsonrpc":"2.0","id":38}'
2020-07-05 14:27:43,384 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 38, "result": 30}'
2020-07-05 14:27:43,386 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.mixer.get_mute","params":{},"jsonrpc":"2.0","id":39}'
2020-07-05 14:27:43,387 DEBUG [571:MpdSession-21] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45874: 
    volume: 30
    repeat: 0
    random: 0
    single: 0
    consume: 0
    playlist: 2
    playlistlength: 34
    xfade: 0
    state: stop
    nextsong: 0
    nextsongid: 1
    list_OK
    list_OK
    OK
2020-07-05 14:27:43,392 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 39, "result": false}'
2020-07-05 14:27:43,394 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_consume","params":{},"jsonrpc":"2.0","id":40}'
2020-07-05 14:27:43,395 DEBUG [571:MpdSession-21] mopidy_mpd.network: Client most likely disconnected.
2020-07-05 14:27:43,398 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 40, "result": false}'
2020-07-05 14:27:43,399 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_random","params":{},"jsonrpc":"2.0","id":41}'
2020-07-05 14:27:43,400 DEBUG [571:MpdSession-21] pykka: Unregistered MpdSession (urn:uuid:ee85b26e-7d1c-43b0-b976-6d34c48f17ca)
2020-07-05 14:27:43,403 DEBUG [571:MpdSession-21] pykka: Stopped MpdSession (urn:uuid:ee85b26e-7d1c-43b0-b976-6d34c48f17ca)
2020-07-05 14:27:43,404 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 41, "result": false}'
2020-07-05 14:27:43,405 DEBUG [571:MpdSession-21] mopidy_mpd.network: Already stopping: Actor is shutting down.
2020-07-05 14:27:43,406 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_repeat","params":{},"jsonrpc":"2.0","id":42}'
2020-07-05 14:27:43,414 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 42, "result": false}'
2020-07-05 14:27:43,416 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_current_tl_track","params":{},"jsonrpc":"2.0","id":43}'
2020-07-05 14:27:43,418 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 43, "result": null}'
2020-07-05 14:27:43,420 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_time_position","params":{},"jsonrpc":"2.0","id":44}'
2020-07-05 14:27:43,423 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 44, "result": 0}'
2020-07-05 14:27:43,442 DEBUG [571:MainThread] pykka: Registered MpdSession (urn:uuid:6af65c70-84c7-4566-92ee-9f5f40bdd3f7)
2020-07-05 14:27:43,442 DEBUG [571:MainThread] pykka: Starting MpdSession (urn:uuid:6af65c70-84c7-4566-92ee-9f5f40bdd3f7)
2020-07-05 14:27:43,443 INFO [571:MpdSession-22] mopidy_mpd.session: New MPD connection from [::ffff:127.0.0.1]:45878
2020-07-05 14:27:43,445 DEBUG [571:MpdSession-22] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45878: random "0"
2020-07-05 14:27:43,447 DEBUG [571:MpdSession-22] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45878: OK
2020-07-05 14:27:43,448 DEBUG [571:MpdSession-22] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45878: command_list_ok_begin
2020-07-05 14:27:43,449 DEBUG [571:MpdSession-22] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45878: status
2020-07-05 14:27:43,450 DEBUG [571:MpdSession-22] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45878: currentsong
2020-07-05 14:27:43,451 DEBUG [571:MpdSession-22] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45878: command_list_end
2020-07-05 14:27:43,459 DEBUG [571:MpdSession-22] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45878: 
    volume: 30
    repeat: 0
    random: 0
    single: 0
    consume: 0
    playlist: 2
    playlistlength: 34
    xfade: 0
    state: stop
    nextsong: 0
    nextsongid: 1
    list_OK
    list_OK
    OK
2020-07-05 14:27:43,460 DEBUG [571:MpdSession-22] mopidy_mpd.network: Client most likely disconnected.
2020-07-05 14:27:43,462 DEBUG [571:MpdSession-22] pykka: Unregistered MpdSession (urn:uuid:6af65c70-84c7-4566-92ee-9f5f40bdd3f7)
2020-07-05 14:27:43,462 DEBUG [571:MpdSession-22] pykka: Stopped MpdSession (urn:uuid:6af65c70-84c7-4566-92ee-9f5f40bdd3f7)
2020-07-05 14:27:43,463 DEBUG [571:MpdSession-22] mopidy_mpd.network: Already stopping: Actor is shutting down.
2020-07-05 14:27:43,504 DEBUG [571:MainThread] pykka: Registered MpdSession (urn:uuid:574e0e44-ab54-4d40-975d-2b02e22cb184)
2020-07-05 14:27:43,505 DEBUG [571:MainThread] pykka: Starting MpdSession (urn:uuid:574e0e44-ab54-4d40-975d-2b02e22cb184)
2020-07-05 14:27:43,506 INFO [571:MpdSession-23] mopidy_mpd.session: New MPD connection from [::ffff:127.0.0.1]:45882
2020-07-05 14:27:43,508 DEBUG [571:MpdSession-23] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45882: play 
2020-07-05 14:27:43,525 DEBUG [571:Audio-2] mopidy.audio.gst: Changing state to GST_STATE_READY: result=GST_STATE_CHANGE_SUCCESS
2020-07-05 14:27:43,526 DEBUG [571:SpotifyBackend-6] mopidy_spotify.playback: Audio requested change of track; loading and starting Spotify player
2020-07-05 14:27:43,531 DEBUG [571:MainThread] mopidy.audio.gst: Got STATE_CHANGED bus message: old=GST_STATE_NULL new=GST_STATE_READY pending=GST_STATE_VOID_PENDING
2020-07-05 14:27:43,532 DEBUG [571:SpotifyBackend-6] spotify.session: Notify main thread
2020-07-05 14:27:43,533 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Notification received; processing events
2020-07-05 14:27:43,534 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Waiting 2.651s for new events
2020-07-05 14:27:43,539 DEBUG [571:Audio-2] mopidy.audio.gst: Sending TAG event for track 'spotify:track:7AFFqxBwDNjfSGg7wls0kf': 'taglist, artist=(string)"Meine\\ Freundin\\ Conni", title=(string)"Intro\\ Theme\\ Song\\ \\"Meine\\ Freundin\\ Conni\\"", album=(string)"01:\\ Conni\\ zieht\\ um\\ /\\ Conni\\ bekommt\\ eine\\ Katze\\ /\\ Conni\\ macht\\ das\\ Seepferdchen\\ /\\ Conni\\ geht\\ zelten\\ \\(Vier\\ H\\303\\266rspiele\\ zur\\ TV-Serie\\)";'
2020-07-05 14:27:43,562 DEBUG [571:Audio-2] mopidy.audio.gst: Got source-setup signal: element=GstAppSrc
2020-07-05 14:27:43,587 DEBUG [571:Audio-2] mopidy.audio.gst: Changing state to GST_STATE_PLAYING: result=GST_STATE_CHANGE_ASYNC
2020-07-05 14:27:43,589 DEBUG [571:SpotifyBackend-6] mopidy_spotify.playback: Audio requested seek to 0
2020-07-05 14:27:43,590 DEBUG [571:SpotifyBackend-6] mopidy_spotify.playback: Skipping seek due to issue mopidy/mopidy#300
2020-07-05 14:27:43,591 DEBUG [571:MpdSession-23] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45882: OK
2020-07-05 14:27:43,620 DEBUG [571:MainThread] mopidy.audio.gst: Got STREAM_START bus message
2020-07-05 14:27:43,621 DEBUG [571:MainThread] mopidy.audio.actor: Audio event: stream_changed(uri='appsrc://')
2020-07-05 14:27:43,622 DEBUG [571:MainThread] mopidy.listener: Sending stream_changed to AudioListener: {'uri': 'appsrc://'}
2020-07-05 14:27:43,624 DEBUG [571:Core-10] mopidy.core.playback: Changing state: stopped -> playing
2020-07-05 14:27:43,624 DEBUG [571:Core-10] mopidy.core.playback: Triggering playback state change event
2020-07-05 14:27:43,625 DEBUG [571:Core-10] mopidy.listener: Sending playback_state_changed to CoreListener: {'old_state': 'stopped', 'new_state': 'playing'}
2020-07-05 14:27:43,626 DEBUG [571:Core-10] mopidy.core.playback: Triggering track playback started event
2020-07-05 14:27:43,627 DEBUG [571:MpdFrontend-14] mopidy.listener: Sending player to MpdSession: {}
2020-07-05 14:27:43,628 DEBUG [571:Core-10] mopidy.listener: Sending track_playback_started to CoreListener: {'tl_track': TlTrack(tlid=1, track=Track(album=Album(artists=[Artist(name='Meine Freundin Conni', uri='spotify:artist:0HerMOOFZXxIF3Yg9BVl0W')], date='2012', name='01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)', uri='spotify:album:0VfB018ZTsA9Ai8OWAyUzS'), artists=[Artist(name='Meine Freundin Conni', uri='spotify:artist:0HerMOOFZXxIF3Yg9BVl0W')], bitrate=160, date='2012', disc_no=1, length=38000, name='Intro Theme Song "Meine Freundin Conni"', track_no=1, uri='spotify:track:7AFFqxBwDNjfSGg7wls0kf'))}
2020-07-05 14:27:43,640 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_time_position","params":{},"jsonrpc":"2.0","id":45}'
2020-07-05 14:27:43,644 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:27:43,646 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 45, "result": 0}'
2020-07-05 14:27:43,668 DEBUG [571:Dummy-9] spotify.session: libspotify log message: 13:27:43.668 E [ap:4210] ChannelError(1, 0, streams)
2020-07-05 14:27:43,670 DEBUG [571:Dummy-9] spotify.session: libspotify log message: 13:27:43.670 E [file_streamer_simple.cpp:677] handleApErrorCode. Fatal Error 105
2020-07-05 14:27:43,852 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_time_position","params":{},"jsonrpc":"2.0","id":46}'
2020-07-05 14:27:43,854 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:27:43,856 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 46, "result": 0}'
2020-07-05 14:27:44,073 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_next_tlid","params":{},"jsonrpc":"2.0","id":47}'
2020-07-05 14:27:44,078 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 47, "result": 2}'
2020-07-05 14:27:44,177 DEBUG [571:MainThread] pykka: Registered MpdSession (urn:uuid:66a4d1b1-a6a0-4b08-b26e-d538a8b2f58a)
2020-07-05 14:27:44,178 DEBUG [571:MainThread] pykka: Starting MpdSession (urn:uuid:66a4d1b1-a6a0-4b08-b26e-d538a8b2f58a)
2020-07-05 14:27:44,179 INFO [571:MpdSession-24] mopidy_mpd.session: New MPD connection from [::ffff:127.0.0.1]:45886
2020-07-05 14:27:44,260 DEBUG [571:MpdSession-24] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45886: status
2020-07-05 14:27:44,274 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_next_tlid","params":{},"jsonrpc":"2.0","id":48}'
2020-07-05 14:27:44,282 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:27:44,286 DEBUG [571:MpdSession-24] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45886: 
    volume: 30
    repeat: 0
    random: 0
    single: 0
    consume: 0
    playlist: 2
    playlistlength: 34
    xfade: 0
    state: play
    song: 0
    songid: 1
    nextsong: 1
    nextsongid: 2
    time: 0:38
    elapsed: 0.000
    bitrate: 160
    OK
2020-07-05 14:27:44,288 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 48, "result": 2}'
2020-07-05 14:27:44,290 DEBUG [571:MpdSession-24] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45886: currentsong
2020-07-05 14:27:44,300 DEBUG [571:MpdSession-24] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45886: 
    file: spotify:track:7AFFqxBwDNjfSGg7wls0kf
    Time: 38
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Intro Theme Song "Meine Freundin Conni"
    Date: 2012
    Track: 1
    Pos: 0
    Id: 1
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    OK
2020-07-05 14:27:44,302 DEBUG [571:MpdSession-24] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45886: close
2020-07-05 14:27:44,304 DEBUG [571:MpdSession-24] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45886: OK
2020-07-05 14:27:44,306 DEBUG [571:MpdSession-24] pykka: Unregistered MpdSession (urn:uuid:66a4d1b1-a6a0-4b08-b26e-d538a8b2f58a)
2020-07-05 14:27:44,311 DEBUG [571:MpdSession-24] pykka: Stopped MpdSession (urn:uuid:66a4d1b1-a6a0-4b08-b26e-d538a8b2f58a)
2020-07-05 14:27:44,312 DEBUG [571:MpdSession-24] mopidy_mpd.network: Actor is shutting down.
2020-07-05 14:27:44,479 DEBUG [571:MainThread] pykka: Registered MpdSession (urn:uuid:ccee7ba3-891c-4c1c-bf82-979a15685a70)
2020-07-05 14:27:44,481 DEBUG [571:MainThread] pykka: Starting MpdSession (urn:uuid:ccee7ba3-891c-4c1c-bf82-979a15685a70)
2020-07-05 14:27:44,483 INFO [571:MpdSession-25] mopidy_mpd.session: New MPD connection from [::ffff:127.0.0.1]:45890
2020-07-05 14:27:44,563 DEBUG [571:MpdSession-25] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45890: playlistinfo
2020-07-05 14:27:44,580 DEBUG [571:MpdSession-25] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45890: 
    file: spotify:track:7AFFqxBwDNjfSGg7wls0kf
    Time: 38
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Intro Theme Song "Meine Freundin Conni"
    Date: 2012
    Track: 1
    Pos: 0
    Id: 1
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:5Bc98Aa2ORHrMfwmTG75oC
    Time: 92
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni zieht um - Teil 01
    Date: 2012
    Track: 2
    Pos: 1
    Id: 2
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:0tWls6T6mjGkwcM7Rg8RBo
    Time: 113
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni zieht um - Teil 02
    Date: 2012
    Track: 3
    Pos: 2
    Id: 3
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:6BuSSiLgUo0Y6N0GWCHzLP
    Time: 80
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni zieht um - Teil 03
    Date: 2012
    Track: 4
    Pos: 3
    Id: 4
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:6is6z4aat233fuTPiShwu0
    Time: 91
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni zieht um - Teil 04
    Date: 2012
    Track: 5
    Pos: 4
    Id: 5
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:6t8jMqrgyr49M6uC3QbYDT
    Time: 81
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni zieht um - Teil 05
    Date: 2012
    Track: 6
    Pos: 5
    Id: 6
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:0uxwIRkcl816R1dnXFjDtI
    Time: 78
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni zieht um - Teil 06
    Date: 2012
    Track: 7
    Pos: 6
    Id: 7
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:2He6sXV8J3fjlp5tzBXRtI
    Time: 80
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni zieht um - Teil 07
    Date: 2012
    Track: 8
    Pos: 7
    Id: 8
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:53LpemvjYj1hkRiGuJwyPu
    Time: 82
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni zieht um - Teil 08
    Date: 2012
    Track: 9
    Pos: 8
    Id: 9
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:77SaOYRHTWTnnpUUqTO7Ou
    Time: 117
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni zieht um - Teil 09
    Date: 2012
    Track: 10
    Pos: 9
    Id: 10
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:33KYZC9vRn1U8l9vAKlQtk
    Time: 83
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni bekommt eine Katze - Teil 01
    Date: 2012
    Track: 11
    Pos: 10
    Id: 11
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:0YNSi7yq8Ix7aj5BfaCNXj
    Time: 85
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni bekommt eine Katze - Teil 02
    Date: 2012
    Track: 12
    Pos: 11
    Id: 12
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:0BGTlEHBdUTc2LkartKmrR
    Time: 87
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni bekommt eine Katze - Teil 03
    Date: 2012
    Track: 13
    Pos: 12
    Id: 13
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:51xNmQ2KDWmZYOCRk2Lbhz
    Time: 93
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni bekommt eine Katze - Teil 04
    Date: 2012
    Track: 14
    Pos: 13
    Id: 14
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:5vfEzkm2s8zp5fImd3hW5h
    Time: 86
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni bekommt eine Katze - Teil 05
    Date: 2012
    Track: 15
    Pos: 14
    Id: 15
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:6eUDJnNYTwgEpkPbJMfhOh
    Time: 92
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni bekommt eine Katze - Teil 06
    Date: 2012
    Track: 16
    Pos: 15
    Id: 16
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:5CC8ZvJ9ImEjOIOTeFic6L
    Time: 86
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni bekommt eine Katze - Teil 07
    Date: 2012
    Track: 17
    Pos: 16
    Id: 17
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:45x97AOHB67oq4qgYk373X
    Time: 133
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni bekommt eine Katze - Teil 08
    Date: 2012
    Track: 18
    Pos: 17
    Id: 18
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:3xr6MneKJubZ0zTrfQrKT1
    Time: 83
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni macht das Seepferdchen - Teil 01
    Date: 2012
    Track: 19
    Pos: 18
    Id: 19
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:1wkBKijtRuYwmlTJZow5Bc
    Time: 112
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni macht das Seepferdchen - Teil 02
    Date: 2012
    Track: 20
    Pos: 19
    Id: 20
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:27Y27cCBCXEevwc61qeI5X
    Time: 117
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni macht das Seepferdchen - Teil 03
    Date: 2012
    Track: 21
    Pos: 20
    Id: 21
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:0LYZ568HtDQb6GaFHWVsma
    Time: 82
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni macht das Seepferdchen - Teil 04
    Date: 2012
    Track: 22
    Pos: 21
    Id: 22
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:1ZJUapctFyWKmw89gGgCVL
    Time: 80
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni macht das Seepferdchen - Teil 05
    Date: 2012
    Track: 23
    Pos: 22
    Id: 23
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:15KWXHpt377cCMMzlLq0TC
    Time: 91
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni macht das Seepferdchen - Teil 06
    Date: 2012
    Track: 24
    Pos: 23
    Id: 24
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:6ouvTUcumOx9Qhh2oLoMMu
    Time: 119
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni macht das Seepferdchen - Teil 07
    Date: 2012
    Track: 25
    Pos: 24
    Id: 25
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:2rBgZlXAACWLP7e5oLmEii
    Time: 103
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni geht zelten - Teil 01
    Date: 2012
    Track: 26
    Pos: 25
    Id: 26
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:7LtOouHls9X2u6ZBIAX6zX
    Time: 83
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni geht zelten - Teil 02
    Date: 2012
    Track: 27
    Pos: 26
    Id: 27
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:1sIropZDJXiK9rvYglcoDV
    Time: 86
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni geht zelten - Teil 03
    Date: 2012
    Track: 28
    Pos: 27
    Id: 28
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:4IC6GgrWttLfVzUUMQsaTU
    Time: 71
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni geht zelten - Teil 04
    Date: 2012
    Track: 29
    Pos: 28
    Id: 29
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:1jUTNOmgZdzyRhWAK35ccC
    Time: 80
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni geht zelten - Teil 05
    Date: 2012
    Track: 30
    Pos: 29
    Id: 30
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:6E4xHaj047Qf36vYX7aE8i
    Time: 92
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni geht zelten - Teil 06
    Date: 2012
    Track: 31
    Pos: 30
    Id: 31
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:04APLFpe4SigUrz2bYR05M
    Time: 80
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni geht zelten - Teil 07
    Date: 2012
    Track: 32
    Pos: 31
    Id: 32
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:3RO8APcWyGKycqekCwxE71
    Time: 64
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni geht zelten - Teil 08
    Date: 2012
    Track: 33
    Pos: 32
    Id: 33
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    file: spotify:track:6HWdCtKss7VmaVWGBRfN5z
    Time: 85
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni geht zelten - Teil 09
    Date: 2012
    Track: 34
    Pos: 33
    Id: 34
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    OK
2020-07-05 14:27:44,582 DEBUG [571:MpdSession-25] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45890: close
2020-07-05 14:27:44,584 DEBUG [571:MpdSession-25] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45890: OK
2020-07-05 14:27:44,585 DEBUG [571:MpdSession-25] pykka: Unregistered MpdSession (urn:uuid:ccee7ba3-891c-4c1c-bf82-979a15685a70)
2020-07-05 14:27:44,586 DEBUG [571:MpdSession-25] pykka: Stopped MpdSession (urn:uuid:ccee7ba3-891c-4c1c-bf82-979a15685a70)
2020-07-05 14:27:44,586 DEBUG [571:MpdSession-25] mopidy_mpd.network: Actor is shutting down.
2020-07-05 14:27:44,599 DEBUG [571:MpdSession-23] mopidy_mpd.network: Client most likely disconnected.
2020-07-05 14:27:44,601 DEBUG [571:MpdSession-23] pykka: Unregistered MpdSession (urn:uuid:574e0e44-ab54-4d40-975d-2b02e22cb184)
2020-07-05 14:27:44,601 DEBUG [571:MpdSession-23] pykka: Stopped MpdSession (urn:uuid:574e0e44-ab54-4d40-975d-2b02e22cb184)
2020-07-05 14:27:44,602 DEBUG [571:MpdSession-23] mopidy_mpd.network: Already stopping: Actor is shutting down.
2020-07-05 14:27:44,667 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_next_tlid","params":{},"jsonrpc":"2.0","id":49}'
2020-07-05 14:27:44,669 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 49, "result": 2}'
2020-07-05 14:27:45,106 DEBUG [571:MainThread] pykka: Registered MpdSession (urn:uuid:14c220be-f3b5-4305-9406-fb7b495391d8)
2020-07-05 14:27:45,106 DEBUG [571:MainThread] pykka: Starting MpdSession (urn:uuid:14c220be-f3b5-4305-9406-fb7b495391d8)
2020-07-05 14:27:45,107 INFO [571:MpdSession-26] mopidy_mpd.session: New MPD connection from [::ffff:127.0.0.1]:45894
2020-07-05 14:27:45,148 DEBUG [571:MpdSession-26] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45894: status
2020-07-05 14:27:45,156 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:27:45,158 DEBUG [571:MpdSession-26] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45894: 
    volume: 30
    repeat: 0
    random: 0
    single: 0
    consume: 0
    playlist: 2
    playlistlength: 34
    xfade: 0
    state: play
    song: 0
    songid: 1
    nextsong: 1
    nextsongid: 2
    time: 0:38
    elapsed: 0.000
    bitrate: 160
    OK
2020-07-05 14:27:45,159 DEBUG [571:MpdSession-26] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45894: currentsong
2020-07-05 14:27:45,163 DEBUG [571:MpdSession-26] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45894: 
    file: spotify:track:7AFFqxBwDNjfSGg7wls0kf
    Time: 38
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Intro Theme Song "Meine Freundin Conni"
    Date: 2012
    Track: 1
    Pos: 0
    Id: 1
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    OK
2020-07-05 14:27:45,164 DEBUG [571:MpdSession-26] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45894: close
2020-07-05 14:27:45,165 DEBUG [571:MpdSession-26] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45894: OK
2020-07-05 14:27:45,167 DEBUG [571:MpdSession-26] pykka: Unregistered MpdSession (urn:uuid:14c220be-f3b5-4305-9406-fb7b495391d8)
2020-07-05 14:27:45,168 DEBUG [571:MpdSession-26] pykka: Stopped MpdSession (urn:uuid:14c220be-f3b5-4305-9406-fb7b495391d8)
2020-07-05 14:27:45,168 DEBUG [571:MpdSession-26] mopidy_mpd.network: Actor is shutting down.
2020-07-05 14:27:46,187 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Timeout reached; processing events
2020-07-05 14:27:46,188 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Waiting 0.008s for new events
2020-07-05 14:27:46,197 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Timeout reached; processing events
2020-07-05 14:27:46,198 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Waiting 1.990s for new events
2020-07-05 14:27:46,226 DEBUG [571:Dummy-9] spotify.session: Notify main thread
2020-07-05 14:27:46,228 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Notification received; processing events
2020-07-05 14:27:46,228 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Waiting 1.959s for new events
2020-07-05 14:27:46,259 DEBUG [571:Dummy-9] spotify.session: Notify main thread
2020-07-05 14:27:46,260 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Notification received; processing events
2020-07-05 14:27:46,261 DEBUG [571:SpotifyEventLoop] spotify.session: Notify main thread
2020-07-05 14:27:46,262 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Waiting 1.927s for new events
2020-07-05 14:27:46,263 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Notification received; processing events
2020-07-05 14:27:46,264 DEBUG [571:SpotifyEventLoop] spotify.session: User info updated
2020-07-05 14:27:46,264 DEBUG [571:SpotifyEventLoop] spotify.session: User info updated
2020-07-05 14:27:46,265 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Waiting 1.924s for new events
2020-07-05 14:27:48,190 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Timeout reached; processing events
2020-07-05 14:27:48,191 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Waiting 15.152s for new events
2020-07-05 14:27:48,856 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_time_position","params":{},"jsonrpc":"2.0","id":50}'
2020-07-05 14:27:48,860 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:27:48,863 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 50, "result": 0}'
2020-07-05 14:27:49,478 DEBUG [571:MainThread] pykka: Registered MpdSession (urn:uuid:3d191526-008e-40b6-bf62-1fcf98c2e934)
2020-07-05 14:27:49,480 DEBUG [571:MainThread] pykka: Starting MpdSession (urn:uuid:3d191526-008e-40b6-bf62-1fcf98c2e934)
2020-07-05 14:27:49,482 INFO [571:MpdSession-27] mopidy_mpd.session: New MPD connection from [::ffff:127.0.0.1]:45898
2020-07-05 14:27:49,555 DEBUG [571:MpdSession-27] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45898: status
2020-07-05 14:27:49,575 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:27:49,578 DEBUG [571:MpdSession-27] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45898: 
    volume: 30
    repeat: 0
    random: 0
    single: 0
    consume: 0
    playlist: 2
    playlistlength: 34
    xfade: 0
    state: play
    song: 0
    songid: 1
    nextsong: 1
    nextsongid: 2
    time: 0:38
    elapsed: 0.000
    bitrate: 160
    OK
2020-07-05 14:27:49,580 DEBUG [571:MpdSession-27] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45898: currentsong
2020-07-05 14:27:49,586 DEBUG [571:MpdSession-27] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45898: 
    file: spotify:track:7AFFqxBwDNjfSGg7wls0kf
    Time: 38
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Intro Theme Song "Meine Freundin Conni"
    Date: 2012
    Track: 1
    Pos: 0
    Id: 1
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    OK
2020-07-05 14:27:49,588 DEBUG [571:MpdSession-27] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45898: close
2020-07-05 14:27:49,590 DEBUG [571:MpdSession-27] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45898: OK
2020-07-05 14:27:49,592 DEBUG [571:MpdSession-27] pykka: Unregistered MpdSession (urn:uuid:3d191526-008e-40b6-bf62-1fcf98c2e934)
2020-07-05 14:27:49,593 DEBUG [571:MpdSession-27] pykka: Stopped MpdSession (urn:uuid:3d191526-008e-40b6-bf62-1fcf98c2e934)
2020-07-05 14:27:49,594 DEBUG [571:MpdSession-27] mopidy_mpd.network: Actor is shutting down.
2020-07-05 14:27:53,694 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_state","params":{},"jsonrpc":"2.0","id":51}'
2020-07-05 14:27:53,698 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 51, "result": "playing"}'
2020-07-05 14:27:53,704 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.mixer.get_volume","params":{},"jsonrpc":"2.0","id":52}'
2020-07-05 14:27:53,709 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 52, "result": 30}'
2020-07-05 14:27:53,712 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.mixer.get_mute","params":{},"jsonrpc":"2.0","id":53}'
2020-07-05 14:27:53,717 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 53, "result": false}'
2020-07-05 14:27:53,719 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_consume","params":{},"jsonrpc":"2.0","id":54}'
2020-07-05 14:27:53,723 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 54, "result": false}'
2020-07-05 14:27:53,725 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_random","params":{},"jsonrpc":"2.0","id":55}'
2020-07-05 14:27:53,728 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 55, "result": false}'
2020-07-05 14:27:53,730 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_repeat","params":{},"jsonrpc":"2.0","id":56}'
2020-07-05 14:27:53,734 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 56, "result": false}'
2020-07-05 14:27:53,736 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_current_tl_track","params":{},"jsonrpc":"2.0","id":57}'
2020-07-05 14:27:53,740 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 57, "result": {"__model__": "TlTrack", "tlid": 1, "track": {"__model__": "Track", "uri": "spotify:track:7AFFqxBwDNjfSGg7wls0kf", "name": "Intro Theme Song \\"Meine Freundin Conni\\"", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 1, "disc_no": 1, "date": "2012", "length": 38000, "bitrate": 160}}}'
2020-07-05 14:27:53,742 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_time_position","params":{},"jsonrpc":"2.0","id":58}'
2020-07-05 14:27:53,746 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:27:53,749 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 58, "result": 0}'
2020-07-05 14:27:53,861 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_time_position","params":{},"jsonrpc":"2.0","id":59}'
2020-07-05 14:27:53,865 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:27:53,868 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 59, "result": 0}'
2020-07-05 14:27:54,639 DEBUG [571:MainThread] pykka: Registered MpdSession (urn:uuid:3214afc8-a8c8-47c1-8f5c-dd69f2eb0fee)
2020-07-05 14:27:54,640 DEBUG [571:MainThread] pykka: Starting MpdSession (urn:uuid:3214afc8-a8c8-47c1-8f5c-dd69f2eb0fee)
2020-07-05 14:27:54,642 INFO [571:MpdSession-28] mopidy_mpd.session: New MPD connection from [::ffff:127.0.0.1]:45902
2020-07-05 14:27:54,716 DEBUG [571:MpdSession-28] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45902: status
2020-07-05 14:27:54,724 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:27:54,726 DEBUG [571:MpdSession-28] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45902: 
    volume: 30
    repeat: 0
    random: 0
    single: 0
    consume: 0
    playlist: 2
    playlistlength: 34
    xfade: 0
    state: play
    song: 0
    songid: 1
    nextsong: 1
    nextsongid: 2
    time: 0:38
    elapsed: 0.000
    bitrate: 160
    OK
2020-07-05 14:27:54,728 DEBUG [571:MpdSession-28] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45902: currentsong
2020-07-05 14:27:54,731 DEBUG [571:MpdSession-28] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45902: 
    file: spotify:track:7AFFqxBwDNjfSGg7wls0kf
    Time: 38
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Intro Theme Song "Meine Freundin Conni"
    Date: 2012
    Track: 1
    Pos: 0
    Id: 1
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    OK
2020-07-05 14:27:54,733 DEBUG [571:MpdSession-28] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45902: close
2020-07-05 14:27:54,734 DEBUG [571:MpdSession-28] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45902: OK
2020-07-05 14:27:54,736 DEBUG [571:MpdSession-28] pykka: Unregistered MpdSession (urn:uuid:3214afc8-a8c8-47c1-8f5c-dd69f2eb0fee)
2020-07-05 14:27:54,736 DEBUG [571:MpdSession-28] pykka: Stopped MpdSession (urn:uuid:3214afc8-a8c8-47c1-8f5c-dd69f2eb0fee)
2020-07-05 14:27:54,737 DEBUG [571:MpdSession-28] mopidy_mpd.network: Actor is shutting down.
2020-07-05 14:28:00,762 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_time_position","params":{},"jsonrpc":"2.0","id":60}'
2020-07-05 14:28:00,766 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:28:00,770 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 60, "result": 0}'
2020-07-05 14:28:00,790 DEBUG [571:MainThread] pykka: Registered MpdSession (urn:uuid:f35c5027-4c5b-4c1d-af42-8a3a711504d2)
2020-07-05 14:28:00,791 DEBUG [571:MainThread] pykka: Starting MpdSession (urn:uuid:f35c5027-4c5b-4c1d-af42-8a3a711504d2)
2020-07-05 14:28:00,793 INFO [571:MpdSession-29] mopidy_mpd.session: New MPD connection from [::ffff:127.0.0.1]:45906
2020-07-05 14:28:00,866 DEBUG [571:MpdSession-29] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45906: status
2020-07-05 14:28:00,883 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:28:00,887 DEBUG [571:MpdSession-29] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45906: 
    volume: 30
    repeat: 0
    random: 0
    single: 0
    consume: 0
    playlist: 2
    playlistlength: 34
    xfade: 0
    state: play
    song: 0
    songid: 1
    nextsong: 1
    nextsongid: 2
    time: 0:38
    elapsed: 0.000
    bitrate: 160
    OK
2020-07-05 14:28:00,889 DEBUG [571:MpdSession-29] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45906: currentsong
2020-07-05 14:28:00,895 DEBUG [571:MpdSession-29] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45906: 
    file: spotify:track:7AFFqxBwDNjfSGg7wls0kf
    Time: 38
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Intro Theme Song "Meine Freundin Conni"
    Date: 2012
    Track: 1
    Pos: 0
    Id: 1
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    OK
2020-07-05 14:28:00,897 DEBUG [571:MpdSession-29] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45906: close
2020-07-05 14:28:00,900 DEBUG [571:MpdSession-29] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45906: OK
2020-07-05 14:28:00,903 DEBUG [571:MpdSession-29] pykka: Unregistered MpdSession (urn:uuid:f35c5027-4c5b-4c1d-af42-8a3a711504d2)
2020-07-05 14:28:00,905 DEBUG [571:MpdSession-29] pykka: Stopped MpdSession (urn:uuid:f35c5027-4c5b-4c1d-af42-8a3a711504d2)
2020-07-05 14:28:00,906 DEBUG [571:MpdSession-29] mopidy_mpd.network: Actor is shutting down.
2020-07-05 14:28:03,344 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Timeout reached; processing events
2020-07-05 14:28:03,345 DEBUG [571:SpotifyEventLoop] spotify.session: libspotify log message: 13:28:03.345 I [offline-mgr:2056] 0 files unlocked. 0 images unlocked
2020-07-05 14:28:03,346 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Waiting 22.884s for new events
2020-07-05 14:28:03,873 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_time_position","params":{},"jsonrpc":"2.0","id":61}'
2020-07-05 14:28:03,877 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:28:03,880 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 61, "result": 0}'
2020-07-05 14:28:05,958 DEBUG [571:MainThread] pykka: Registered MpdSession (urn:uuid:d9da308f-1eb7-4292-bea5-a48b8d5dfe08)
2020-07-05 14:28:05,959 DEBUG [571:MainThread] pykka: Starting MpdSession (urn:uuid:d9da308f-1eb7-4292-bea5-a48b8d5dfe08)
2020-07-05 14:28:05,960 INFO [571:MpdSession-30] mopidy_mpd.session: New MPD connection from [::ffff:127.0.0.1]:45910
2020-07-05 14:28:06,036 DEBUG [571:MpdSession-30] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45910: status
2020-07-05 14:28:06,050 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:28:06,053 DEBUG [571:MpdSession-30] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45910: 
    volume: 30
    repeat: 0
    random: 0
    single: 0
    consume: 0
    playlist: 2
    playlistlength: 34
    xfade: 0
    state: play
    song: 0
    songid: 1
    nextsong: 1
    nextsongid: 2
    time: 0:38
    elapsed: 0.000
    bitrate: 160
    OK
2020-07-05 14:28:06,054 DEBUG [571:MpdSession-30] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45910: currentsong
2020-07-05 14:28:06,060 DEBUG [571:MpdSession-30] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45910: 
    file: spotify:track:7AFFqxBwDNjfSGg7wls0kf
    Time: 38
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Intro Theme Song "Meine Freundin Conni"
    Date: 2012
    Track: 1
    Pos: 0
    Id: 1
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    OK
2020-07-05 14:28:06,061 DEBUG [571:MpdSession-30] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45910: close
2020-07-05 14:28:06,063 DEBUG [571:MpdSession-30] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45910: OK
2020-07-05 14:28:06,065 DEBUG [571:MpdSession-30] pykka: Unregistered MpdSession (urn:uuid:d9da308f-1eb7-4292-bea5-a48b8d5dfe08)
2020-07-05 14:28:06,065 DEBUG [571:MpdSession-30] pykka: Stopped MpdSession (urn:uuid:d9da308f-1eb7-4292-bea5-a48b8d5dfe08)
2020-07-05 14:28:06,066 DEBUG [571:MpdSession-30] mopidy_mpd.network: Actor is shutting down.
2020-07-05 14:28:06,095 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_state","params":{},"jsonrpc":"2.0","id":62}'
2020-07-05 14:28:06,097 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 62, "result": "playing"}'
2020-07-05 14:28:06,101 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.mixer.get_volume","params":{},"jsonrpc":"2.0","id":63}'
2020-07-05 14:28:06,103 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 63, "result": 30}'
2020-07-05 14:28:06,105 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.mixer.get_mute","params":{},"jsonrpc":"2.0","id":64}'
2020-07-05 14:28:06,108 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 64, "result": false}'
2020-07-05 14:28:06,114 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_consume","params":{},"jsonrpc":"2.0","id":65}'
2020-07-05 14:28:06,116 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 65, "result": false}'
2020-07-05 14:28:06,118 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_random","params":{},"jsonrpc":"2.0","id":66}'
2020-07-05 14:28:06,120 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 66, "result": false}'
2020-07-05 14:28:06,121 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_repeat","params":{},"jsonrpc":"2.0","id":67}'
2020-07-05 14:28:06,123 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 67, "result": false}'
2020-07-05 14:28:06,125 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_current_tl_track","params":{},"jsonrpc":"2.0","id":68}'
2020-07-05 14:28:06,128 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 68, "result": {"__model__": "TlTrack", "tlid": 1, "track": {"__model__": "Track", "uri": "spotify:track:7AFFqxBwDNjfSGg7wls0kf", "name": "Intro Theme Song \\"Meine Freundin Conni\\"", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "album": {"__model__": "Album", "uri": "spotify:album:0VfB018ZTsA9Ai8OWAyUzS", "name": "01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier H\\u00f6rspiele zur TV-Serie)", "artists": [{"__model__": "Artist", "uri": "spotify:artist:0HerMOOFZXxIF3Yg9BVl0W", "name": "Meine Freundin Conni"}], "date": "2012"}, "track_no": 1, "disc_no": 1, "date": "2012", "length": 38000, "bitrate": 160}}}'
2020-07-05 14:28:06,129 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_time_position","params":{},"jsonrpc":"2.0","id":69}'
2020-07-05 14:28:06,131 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:28:06,133 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 69, "result": 0}'
2020-07-05 14:28:07,470 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.next","params":{},"jsonrpc":"2.0","id":70}'
2020-07-05 14:28:07,474 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:28:07,487 DEBUG [571:Audio-2] mopidy.audio.gst: Changing state to GST_STATE_READY: result=GST_STATE_CHANGE_SUCCESS

You can see, that the elapsed time is alwyays 0.000 as the track is not played.

marvingrieger commented 4 years ago

After skipping the "broken" track, the next track immediately starts. This is what gets logged:

2020-07-05 14:28:07,489 DEBUG [571:SpotifyBackend-6] mopidy_spotify.playback: Audio requested change of track; loading and starting Spotify player
2020-07-05 14:28:07,491 DEBUG [571:SpotifyBackend-6] spotify.session: Notify main thread
2020-07-05 14:28:07,493 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Notification received; processing events
2020-07-05 14:28:07,493 DEBUG [571:SpotifyBackend-6] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,496 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Waiting 18.732s for new events
2020-07-05 14:28:07,508 DEBUG [571:Audio-2] mopidy.audio.gst: Sending TAG event for track 'spotify:track:5Bc98Aa2ORHrMfwmTG75oC': 'taglist, artist=(string)"Meine\\ Freundin\\ Conni", title=(string)"Conni\\ zieht\\ um\\ -\\ Teil\\ 01", album=(string)"01:\\ Conni\\ zieht\\ um\\ /\\ Conni\\ bekommt\\ eine\\ Katze\\ /\\ Conni\\ macht\\ das\\ Seepferdchen\\ /\\ Conni\\ geht\\ zelten\\ \\(Vier\\ H\\303\\266rspiele\\ zur\\ TV-Serie\\)";'
2020-07-05 14:28:07,513 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,515 DEBUG [571:MainThread] mopidy.audio.gst: Got STATE_CHANGED bus message: old=GST_STATE_READY new=GST_STATE_READY pending=GST_STATE_VOID_PENDING
2020-07-05 14:28:07,517 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,519 DEBUG [571:Audio-2] mopidy.audio.gst: Got source-setup signal: element=GstAppSrc
2020-07-05 14:28:07,521 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,526 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,528 DEBUG [571:Audio-2] mopidy.audio.gst: Changing state to GST_STATE_PLAYING: result=GST_STATE_CHANGE_ASYNC
2020-07-05 14:28:07,532 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,533 DEBUG [571:SpotifyBackend-6] mopidy_spotify.playback: Audio requested seek to 0
2020-07-05 14:28:07,538 DEBUG [571:SpotifyBackend-6] mopidy_spotify.playback: Skipping seek due to issue mopidy/mopidy#300
2020-07-05 14:28:07,537 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 70, "result": null}'
2020-07-05 14:28:07,544 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,554 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,555 DEBUG [571:HttpServer] mopidy_iris.handlers: Iris websocket message received: {"jsonrpc":"2.0","id":159395568755145,"method":"broadcast","params":{"method":"notification","params":{"notification":{"content":"159395565550064 skipped \"Intro Theme Song \"Meine Freundin Conni\"\"","icon":null}}}}
2020-07-05 14:28:07,560 DEBUG [571:HttpServer] mopidy_iris.core: {'method': 'notification', 'params': {'notification': {'content': '159395565550064 skipped "Intro Theme Song "Meine Freundin Conni""', 'icon': None}}, 'connection_id': 'STO7YP6BTLFY'}
2020-07-05 14:28:07,561 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,562 DEBUG [571:HttpServer] mopidy_iris.core: {'id': 159395568755145, 'jsonrpc': '2.0', 'method': 'broadcast', 'result': {'message': 'Broadcast to 1 connections'}, 'recipient': 'STO7YP6BTLFY'}
2020-07-05 14:28:07,567 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,573 DEBUG [571:MainThread] mopidy.audio.gst: Got STREAM_START bus message
2020-07-05 14:28:07,574 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,575 DEBUG [571:MainThread] mopidy.audio.actor: Audio event: stream_changed(uri='appsrc://')
2020-07-05 14:28:07,578 DEBUG [571:MainThread] mopidy.listener: Sending stream_changed to AudioListener: {'uri': 'appsrc://'}
2020-07-05 14:28:07,580 DEBUG [571:Core-10] mopidy.core.playback: Triggering track playback ended event
2020-07-05 14:28:07,581 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,582 DEBUG [571:Core-10] mopidy.listener: Sending track_playback_ended to CoreListener: {'tl_track': TlTrack(tlid=1, track=Track(album=Album(artists=[Artist(name='Meine Freundin Conni', uri='spotify:artist:0HerMOOFZXxIF3Yg9BVl0W')], date='2012', name='01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)', uri='spotify:album:0VfB018ZTsA9Ai8OWAyUzS'), artists=[Artist(name='Meine Freundin Conni', uri='spotify:artist:0HerMOOFZXxIF3Yg9BVl0W')], bitrate=160, date='2012', disc_no=1, length=38000, name='Intro Theme Song "Meine Freundin Conni"', track_no=1, uri='spotify:track:7AFFqxBwDNjfSGg7wls0kf')), 'time_position': 0}
2020-07-05 14:28:07,586 DEBUG [571:Core-10] mopidy.core.playback: Changing state: playing -> playing
2020-07-05 14:28:07,589 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,590 DEBUG [571:Core-10] mopidy.core.playback: Triggering playback state change event
2020-07-05 14:28:07,593 DEBUG [571:Core-10] mopidy.listener: Sending playback_state_changed to CoreListener: {'old_state': 'playing', 'new_state': 'playing'}
2020-07-05 14:28:07,594 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,595 DEBUG [571:Core-10] mopidy.core.playback: Triggering track playback started event
2020-07-05 14:28:07,596 DEBUG [571:MpdFrontend-14] mopidy.listener: Sending player to MpdSession: {}
2020-07-05 14:28:07,599 DEBUG [571:Core-10] mopidy.listener: Sending track_playback_started to CoreListener: {'tl_track': TlTrack(tlid=2, track=Track(album=Album(artists=[Artist(name='Meine Freundin Conni', uri='spotify:artist:0HerMOOFZXxIF3Yg9BVl0W')], date='2012', name='01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)', uri='spotify:album:0VfB018ZTsA9Ai8OWAyUzS'), artists=[Artist(name='Meine Freundin Conni', uri='spotify:artist:0HerMOOFZXxIF3Yg9BVl0W')], bitrate=160, date='2012', disc_no=1, length=92000, name='Conni zieht um - Teil 01', track_no=2, uri='spotify:track:5Bc98Aa2ORHrMfwmTG75oC'))}
2020-07-05 14:28:07,600 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,606 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,609 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,610 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,612 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,614 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,617 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,618 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_time_position","params":{},"jsonrpc":"2.0","id":71}'
2020-07-05 14:28:07,620 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,623 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,624 DEBUG [571:Audio-2] mopidy.audio.actor: Position query failed
2020-07-05 14:28:07,626 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 71, "result": 0}'
2020-07-05 14:28:07,627 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,629 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,631 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,633 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,634 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,636 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,638 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,640 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,642 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,643 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,645 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,646 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,648 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,650 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,652 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,654 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,655 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,657 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,658 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,660 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,662 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,664 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,666 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,667 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,669 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,671 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,672 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,674 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,676 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,678 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,679 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,681 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,683 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,684 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,686 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,688 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,689 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,691 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,693 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,693 DEBUG [571:Dummy-9] spotify.session: Notify main thread
2020-07-05 14:28:07,694 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Notification received; processing events
2020-07-05 14:28:07,695 DEBUG [571:SpotifyEventLoop] spotify.eventloop: Waiting 18.534s for new events
2020-07-05 14:28:07,826 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,829 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,830 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,831 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,833 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,834 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,835 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,837 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,838 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,841 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,842 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,843 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,845 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,846 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,847 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,848 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,849 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,851 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,852 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,853 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,854 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,855 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,856 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,857 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,859 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,861 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,862 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,863 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,864 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,865 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,866 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,867 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,868 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,869 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,871 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,872 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,873 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,874 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,875 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,876 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,878 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,879 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,880 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,881 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,882 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,883 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,883 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,885 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,886 DEBUG [571:Dummy-31] mopidy.audio.gst: Got SEGMENT pad event: rate=1.0 format=time start=0 stop=18446744073709551615 position=0
2020-07-05 14:28:07,888 DEBUG [571:Dummy-31] mopidy.audio.actor: Audio event: position_changed(position=0)
2020-07-05 14:28:07,889 DEBUG [571:Dummy-31] mopidy.listener: Sending position_changed to AudioListener: {'position': 0}
2020-07-05 14:28:07,890 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,895 DEBUG [571:MainThread] mopidy.audio.gst: Got TAG bus message: tags={'artist': ['Meine Freundin Conni'], 'title': ['Conni zieht um - Teil 01'], 'album': ['01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)']}
2020-07-05 14:28:07,895 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,896 DEBUG [571:MainThread] mopidy.audio.actor: Audio event: tags_changed(tags=['artist', 'title', 'album'])
2020-07-05 14:28:07,899 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,899 DEBUG [571:MainThread] mopidy.listener: Sending tags_changed to AudioListener: {'tags': ['artist', 'title', 'album']}
2020-07-05 14:28:07,904 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,906 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,907 DEBUG [571:MainThread] mopidy.audio.gst: Got STATE_CHANGED bus message: old=GST_STATE_READY new=GST_STATE_PAUSED pending=GST_STATE_PLAYING
2020-07-05 14:28:07,909 DEBUG [571:MainThread] mopidy.audio.gst: Got ASYNC_DONE bus message.
2020-07-05 14:28:07,911 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,913 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,916 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,919 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,922 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,925 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,928 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,930 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,931 DEBUG [571:MainThread] mopidy.audio.gst: Got STATE_CHANGED bus message: old=GST_STATE_PAUSED new=GST_STATE_PLAYING pending=GST_STATE_VOID_PENDING
2020-07-05 14:28:07,932 DEBUG [571:MainThread] mopidy.audio.actor: Audio event: state_changed(old_state=stopped, new_state=playing, target_state=None)
2020-07-05 14:28:07,933 DEBUG [571:MainThread] mopidy.listener: Sending state_changed to AudioListener: {'old_state': 'stopped', 'new_state': 'playing', 'target_state': None}
2020-07-05 14:28:07,934 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,936 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,938 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,940 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,942 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,944 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,945 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,947 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,948 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,949 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,949 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,949 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,951 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,952 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,953 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,955 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,956 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,958 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,959 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,961 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,962 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,963 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,964 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,966 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,967 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,968 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,970 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,971 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,972 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,974 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,975 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,976 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,977 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,978 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,979 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,980 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,981 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,982 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,983 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,984 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,986 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,986 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,987 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,987 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:07,989 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,991 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,993 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,995 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,997 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:07,999 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,001 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,003 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,005 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,007 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,009 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,011 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,013 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,015 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,017 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,019 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,021 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,023 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,025 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,027 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,029 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,031 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,031 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,032 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,032 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,130 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,130 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,132 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,133 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,135 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,135 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,137 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,138 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,140 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,141 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,142 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,144 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,145 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,146 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,147 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,149 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,150 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,152 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,153 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,154 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,155 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,157 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,159 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,160 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,161 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,162 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,165 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,167 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,168 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,170 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,172 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,174 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,176 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,178 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,180 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,182 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,184 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,186 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,188 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,190 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,192 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,194 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,196 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,197 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,200 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,202 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,203 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,204 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,205 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,205 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,330 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,333 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,335 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,337 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,339 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,342 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,344 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,346 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,348 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,351 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,353 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,355 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,357 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,360 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,362 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,365 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,368 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,370 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,373 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,377 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,379 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,382 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,384 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,386 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,387 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,390 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,390 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,394 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,395 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,399 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,400 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,400 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,401 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,403 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,406 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,410 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,411 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,413 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,415 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,417 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,419 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,421 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,423 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,424 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,426 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,428 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,430 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,432 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,434 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,436 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,438 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,440 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,441 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,443 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,445 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,446 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,447 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,447 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,529 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,530 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,531 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,532 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,533 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,534 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,535 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,536 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,538 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,539 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,540 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,541 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,542 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,543 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,544 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,545 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,547 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,548 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,550 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,551 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,552 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,553 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,555 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,557 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,558 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,559 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,561 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,563 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,565 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,567 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,568 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,570 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,572 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,574 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,576 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,577 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,579 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,580 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,580 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,581 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,633 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.tracklist.get_next_tlid","params":{},"jsonrpc":"2.0","id":72}'
2020-07-05 14:28:08,636 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 72, "result": 3}'
2020-07-05 14:28:08,730 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,730 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,731 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,733 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,734 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,736 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,737 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,738 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,739 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,740 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,742 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,743 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,744 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,746 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,746 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,748 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,750 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,752 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,754 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,756 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,756 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,757 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,757 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,930 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,931 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,932 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,933 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,934 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,935 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,936 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,937 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,939 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,940 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,942 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,944 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,946 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,947 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,949 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:08,950 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,951 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:08,951 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,130 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,131 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,133 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,135 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,137 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,143 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,145 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,147 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,150 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,151 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,154 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,157 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,161 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,164 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,166 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,170 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,173 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,174 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,175 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,176 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,233 DEBUG [571:HttpServer] mopidy.http.handlers: Received WebSocket message from 192.168.142.60: '{"method":"core.playback.get_time_position","params":{},"jsonrpc":"2.0","id":73}'
2020-07-05 14:28:09,239 DEBUG [571:HttpServer] mopidy.http.handlers: Sent WebSocket message to 192.168.142.60: '{"jsonrpc": "2.0", "id": 73, "result": 1332}'
2020-07-05 14:28:09,330 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,333 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,335 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,337 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,340 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,344 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,346 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,348 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,350 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,351 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,354 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,355 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,359 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,360 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,363 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,364 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,365 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,366 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,369 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,372 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,375 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,378 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,381 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,384 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,387 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,388 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,389 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,390 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,530 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,531 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,532 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,533 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,535 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,536 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,537 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,538 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,539 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,540 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,541 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,544 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,545 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,547 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,549 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,550 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,550 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,551 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,730 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,731 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,732 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,733 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,734 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,737 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,738 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,739 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,741 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,742 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,744 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,746 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,748 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,750 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,751 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,751 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,752 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,930 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,930 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,931 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,933 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,934 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,935 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,936 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,937 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,938 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,939 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,941 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,943 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,945 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,947 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,948 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,950 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:09,951 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,951 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:09,952 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,130 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,131 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,133 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,134 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,137 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,140 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,142 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,145 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,147 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,148 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,150 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,153 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,156 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,159 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,162 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,165 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,168 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,170 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,171 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,172 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,330 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,331 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,334 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,336 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,338 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,343 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,345 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,347 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,350 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,351 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,354 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,357 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,360 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,363 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,366 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,368 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,369 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,370 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,372 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,530 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,531 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,533 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,535 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,537 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,540 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,542 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,544 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,545 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,548 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,551 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,554 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,557 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,560 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,563 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,565 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,566 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,567 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,568 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,730 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,731 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,734 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,737 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,739 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,741 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,743 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,746 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,748 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,749 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,752 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,755 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,758 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,761 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,764 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,766 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,767 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,768 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,770 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,939 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,940 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,942 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,944 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,948 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,951 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,954 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,961 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,962 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,965 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,968 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,971 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,973 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,977 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,980 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,982 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:10,983 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,984 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:10,985 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:11,130 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:11,131 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:11,133 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:11,136 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:11,139 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:11,142 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:11,145 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:11,149 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:11,152 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:11,154 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:11,159 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:11,162 DEBUG [571:MainThread] pykka: Registered MpdSession (urn:uuid:7b9f119b-e700-4594-bea2-2ca4441b5c28)
2020-07-05 14:28:11,165 DEBUG [571:MainThread] pykka: Starting MpdSession (urn:uuid:7b9f119b-e700-4594-bea2-2ca4441b5c28)
2020-07-05 14:28:11,169 INFO [571:MpdSession-32] mopidy_mpd.session: New MPD connection from [::ffff:127.0.0.1]:45914
2020-07-05 14:28:11,171 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:11,177 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:11,180 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:11,183 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:11,185 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:11,188 DEBUG [571:Dummy-9] spotify.session: Music delivery of 2048 frames, 2048 consumed
2020-07-05 14:28:11,190 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:11,191 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:11,192 DEBUG [571:Dummy-9] spotify.session: Audio buffer stats requested, but no listener
2020-07-05 14:28:11,241 DEBUG [571:MpdSession-32] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45914: status
2020-07-05 14:28:11,253 DEBUG [571:MpdSession-32] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45914: 
    volume: 30
    repeat: 0
    random: 0
    single: 0
    consume: 0
    playlist: 2
    playlistlength: 34
    xfade: 0
    state: play
    song: 1
    songid: 2
    nextsong: 2
    nextsongid: 3
    time: 3:92
    elapsed: 3.346
    bitrate: 160
    OK
2020-07-05 14:28:11,254 DEBUG [571:MpdSession-32] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45914: currentsong
2020-07-05 14:28:11,257 DEBUG [571:MpdSession-32] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45914: 
    file: spotify:track:5Bc98Aa2ORHrMfwmTG75oC
    Time: 92
    Artist: Meine Freundin Conni
    Album: 01: Conni zieht um / Conni bekommt eine Katze / Conni macht das Seepferdchen / Conni geht zelten (Vier Hörspiele zur TV-Serie)
    Title: Conni zieht um - Teil 01
    Date: 2012
    Track: 2
    Pos: 1
    Id: 2
    AlbumArtist: Meine Freundin Conni
    Disc: 1
    X-AlbumUri: spotify:album:0VfB018ZTsA9Ai8OWAyUzS
    OK
2020-07-05 14:28:11,258 DEBUG [571:MpdSession-32] mopidy_mpd.session: Request from [::ffff:127.0.0.1]:45914: close
2020-07-05 14:28:11,260 DEBUG [571:MpdSession-32] mopidy_mpd.session: Response to [::ffff:127.0.0.1]:45914: OK
2020-07-05 14:28:11,262 DEBUG [571:MpdSession-32] pykka: Unregistered MpdSession (urn:uuid:7b9f119b-e700-4594-bea2-2ca4441b5c28)
2020-07-05 14:28:11,263 DEBUG [571:MpdSession-32] pykka: Stopped MpdSession (urn:uuid:7b9f119b-e700-4594-bea2-2ca4441b5c28)
2020-07-05 14:28:11,263 DEBUG [571:MpdSession-32] mopidy_mpd.network: Actor is shutting down.
sonicdee commented 4 years ago

for me it's kind of the same issue, I've added some new kids audio drama, this are inside a playlist, each, I just swap the tracks, to keed the card, - and there had been a lot of issues with the first track (only) not playing, deleting this first track in my playlist works - with all not playable cards ... strange

s-martin commented 4 years ago

@kingosticks, do you have any idea?

s-martin commented 4 years ago

Could you check, if the issue #1039 may be related?

Simse90 commented 4 years ago

It is the same problem. This occurs often in the first track because the intros are often under 60s. Choose a playlist with more shorter tracks (<60 seconds) and you will see that all shorter tracks will not be played. Has nothing to do with track #1.

s-martin commented 4 years ago

So as mentioned in #1039 the issue may be tracks shorter than 60secs won't be played.

I still don't know, what's the issue. This may be a configuration issue or a bug in mopidy-spotify.