-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2024-09-01 14:43:38 CEST. -- Sep 01 14:42:08 volumio volumio[1188]: Searching plugin music_service/spop Sep 01 14:42:08 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: spop , search Sep 01 14:42:24 volumio volumio[1188]: info: VolumeController::SetAlsaVolume37 Sep 01 14:42:24 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Sep 01 14:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 2 Sep 01 14:42:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 2 Sep 01 14:42:24 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:42:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:42:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 37 Sep 01 14:42:24 volumio volumio[1188]: SPOTIFY: SPOTIFY VOLUME 39 Sep 01 14:42:24 volumio volumio[1188]: SPOTIFY: VOLUMIO VOLUME 37 Sep 01 14:42:24 volumio volumio[1188]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 01 14:42:24 volumio volumio[1188]: info: Setting Spotify Volume from Volumio: 37 Sep 01 14:42:24 volumio volumio[1188]: info: VolumeController::SetAlsaVolume35 Sep 01 14:42:24 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Sep 01 14:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 2 Sep 01 14:42:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 2 Sep 01 14:42:24 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:42:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:42:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:42:24 volumio volumio[1188]: SPOTIFY: SPOTIFY VOLUME 37 Sep 01 14:42:24 volumio volumio[1188]: SPOTIFY: VOLUMIO VOLUME 35 Sep 01 14:42:24 volumio volumio[1188]: SPOTIFY: DELTA VOLUME ENOUGH: true Sep 01 14:42:24 volumio volumio[1188]: info: Setting Spotify Volume from Volumio: 35 Sep 01 14:42:25 volumio volumio[1188]: info: CorePlayQueue::getTrack 2 Sep 01 14:42:25 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Sep 01 14:42:25 volumio volumio[1188]: info: Prefetching next song Sep 01 14:42:25 volumio volumio[1188]: info: [1725194545809] ControllerSpotify::prefetch Sep 01 14:42:25 volumio volumio[1188]: info: Sending Spotify command with payload to local API: /player/add_to_queue Sep 01 14:42:26 volumio volumio[1188]: SPOTIFY: SETTING SPOTIFY VOLUME 35 Sep 01 14:42:26 volumio volumio[1188]: info: Sending Spotify command with payload to local API: /player/volume Sep 01 14:42:30 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:42:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Sep 01 14:42:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:42:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Sep 01 14:42:30 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:42:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:42:30 volumio volumio[1188]: info: [LastFM] Current track has sufficient metadata: title (Schimmliges Brot) and artist (Foyer Des Arts) passed on explicitly Sep 01 14:42:30 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:42:31 volumio volumio[1188]: error: An error occurred while searching Error: getaddrinfo ENOTFOUND api.spotify.com Sep 01 14:42:31 volumio volumio[1188]: Searching plugin music_service/spop Sep 01 14:42:31 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: spop , search Sep 01 14:42:38 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Sep 01 14:42:38 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Sep 01 14:42:43 volumio go-librespot[1416]: time="2024-09-01T14:42:43+02:00" level=error msg="did not receive last pong from dealer, 60s passed" Sep 01 14:42:45 volumio volumio[1188]: Searching plugin music_service/spop Sep 01 14:42:45 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: spop , search Sep 01 14:42:48 volumio go-librespot[1416]: time="2024-09-01T14:42:48+02:00" level=error msg="failed receiving dealer message" error="failed to get reader: WebSocket closed: sent close frame: status = StatusServiceRestart and reason = \"\"" Sep 01 14:42:50 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 01 14:42:50 volumio volumio[1188]: info: CURURI: music-library Sep 01 14:42:50 volumio volumio[1188]: info: Preload queue cleared Sep 01 14:42:51 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 01 14:42:51 volumio volumio[1188]: info: CURURI: music-library/INTERNAL Sep 01 14:42:51 volumio volumio[1188]: info: Preload queue cleared Sep 01 14:42:51 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/Böhse Onkelz - Böse Menschen - Böse Lieder.mp3 Sep 01 14:42:51 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/System7 - Alpha Wave ( Plastikman acid house remix ).mp3 Sep 01 14:42:51 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/Winx - Don't Laugh.wav Sep 01 14:42:51 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/Böhse Onkelz - Böse Menschen - Böse Lieder.mp3 in service mpd Sep 01 14:42:51 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FB%C3%B6hse%20Onkelz%20-%20B%C3%B6se%20Menschen%20-%20B%C3%B6se%20Lieder.mp3&metadata=false Sep 01 14:42:51 volumio volumio[1188]: info: URI /mnt/INTERNAL/Böhse Onkelz - Böse Menschen - Böse Lieder.mp3 Sep 01 14:42:51 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/System7 - Alpha Wave ( Plastikman acid house remix ).mp3 in service mpd Sep 01 14:42:51 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FSystem7%20-%20Alpha%20Wave%20(%20Plastikman%20acid%20house%20remix%20).mp3&metadata=false Sep 01 14:42:51 volumio volumio[1188]: info: URI /mnt/INTERNAL/System7 - Alpha Wave ( Plastikman acid house remix ).mp3 Sep 01 14:42:51 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/Winx - Don't Laugh.wav in service mpd Sep 01 14:42:51 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWinx%20-%20Don't%20Laugh.wav&metadata=false Sep 01 14:42:51 volumio volumio[1188]: info: URI /mnt/INTERNAL/Winx - Don't Laugh.wav Sep 01 14:42:54 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 01 14:42:54 volumio volumio[1188]: info: CURURI: music-library/INTERNAL/WTG Sep 01 14:42:54 volumio volumio[1188]: info: Preload queue cleared Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving the Guns - 13 - Gesetzestreue - Schlachtrufe WTG.mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving the Guns - 13 - Gesetzestreue - Schlachtrufe WTG.opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving the Guns - Armutszeugnis (feat. Pöbel MC) [Official Video].mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Brüh im Lichte (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Dasein (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Falsch (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Gartenzaun (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Hut (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Ihr_Wir (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Pflaster (Official Video).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Schrotgewehr (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Tageslicht (feat. Cheddar Mike und Pöbel MC) (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Überlegen (feat. Genosse Soselo) (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Überlegen (feat. Genosse Soselo) (Audio).opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Verfall (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Pflaster (Official Video).opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Falsch (Audio).opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Brüh im Lichte (Audio).opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving the Guns - Armutszeugnis (feat. Pöbel MC) [Official Video].opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Schrotgewehr (Audio).opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Verfall (Audio).opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Gartenzaun (Audio).opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Ihr_Wir (Audio).opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Tageslicht (feat. Cheddar Mike und Pöbel MC) (Audio).opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Dasein (Audio).opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Waving The Guns - Hut (Audio).opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Intro.opus Sep 01 14:42:54 volumio volumio[1188]: info: Preloading song: music-library/INTERNAL/WTG/Outro.opus Sep 01 14:42:54 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving the Guns - 13 - Gesetzestreue - Schlachtrufe WTG.mp3 in service mpd Sep 01 14:42:54 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20the%20Guns%20-%2013%20-%20Gesetzestreue%20-%20Schlachtrufe%20WTG.mp3&metadata=false Sep 01 14:42:54 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving the Guns - 13 - Gesetzestreue - Schlachtrufe WTG.mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving the Guns - 13 - Gesetzestreue - Schlachtrufe WTG.opus in service mpd Sep 01 14:42:54 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Waving%20The%20Guns//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20the%20Guns%20-%2013%20-%20Gesetzestreue%20-%20Schlachtrufe%20WTG.opus&metadata=false Sep 01 14:42:54 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving the Guns - 13 - Gesetzestreue - Schlachtrufe WTG.opus Sep 01 14:42:54 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving the Guns - Armutszeugnis (feat. Pöbel MC) [Official Video].mp3 in service mpd Sep 01 14:42:54 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20the%20Guns%20-%20Armutszeugnis%20(feat.%20P%C3%B6bel%20MC)%20%5BOfficial%20Video%5D.mp3&metadata=false Sep 01 14:42:54 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving the Guns - Armutszeugnis (feat. Pöbel MC) [Official Video].mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Brüh im Lichte (Audio).mp3 in service mpd Sep 01 14:42:54 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Br%C3%BCh%20im%20Lichte%20(Audio).mp3&metadata=false Sep 01 14:42:54 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Brüh im Lichte (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Dasein (Audio).mp3 in service mpd Sep 01 14:42:54 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Dasein%20(Audio).mp3&metadata=false Sep 01 14:42:54 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Dasein (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Falsch (Audio).mp3 in service mpd Sep 01 14:42:54 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Falsch%20(Audio).mp3&metadata=false Sep 01 14:42:54 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Falsch (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Gartenzaun (Audio).mp3 in service mpd Sep 01 14:42:54 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Gartenzaun%20(Audio).mp3&metadata=false Sep 01 14:42:54 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Gartenzaun (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Hut (Audio).mp3 in service mpd Sep 01 14:42:54 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Hut%20(Audio).mp3&metadata=false Sep 01 14:42:54 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Hut (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Ihr_Wir (Audio).mp3 in service mpd Sep 01 14:42:54 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Ihr_Wir%20(Audio).mp3&metadata=false Sep 01 14:42:54 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Ihr_Wir (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3 in service mpd Sep 01 14:42:54 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20the%20Guns%20-%20Keine%20Lieder%20%C3%BCber%20Liebe.mp3&metadata=false Sep 01 14:42:54 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3 in service mpd Sep 01 14:42:54 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20M-I-Doppel-L-I-Dance%20(Audio).mp3&metadata=false Sep 01 14:42:54 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3 Sep 01 14:42:54 volumio volumio[1188]: error: An error occurred while searching Error: getaddrinfo ENOTFOUND api.spotify.com Sep 01 14:42:54 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Pflaster (Official Video).mp3 in service mpd Sep 01 14:42:54 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Pflaster%20(Official%20Video).mp3&metadata=false Sep 01 14:42:54 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Pflaster (Official Video).mp3 Sep 01 14:42:54 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Schrotgewehr (Audio).mp3 in service mpd Sep 01 14:42:54 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Schrotgewehr%20(Audio).mp3&metadata=false Sep 01 14:42:54 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Schrotgewehr (Audio).mp3 Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Tageslicht (feat. Cheddar Mike und Pöbel MC) (Audio).mp3 in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Tageslicht%20(feat.%20Cheddar%20Mike%20und%20P%C3%B6bel%20MC)%20(Audio).mp3&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Tageslicht (feat. Cheddar Mike und Pöbel MC) (Audio).mp3 Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Überlegen (feat. Genosse Soselo) (Audio).mp3 in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20%C3%9Cberlegen%20(feat.%20Genosse%20Soselo)%20(Audio).mp3&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Überlegen (feat. Genosse Soselo) (Audio).mp3 Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Überlegen (feat. Genosse Soselo) (Audio).opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Audiolith//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20%C3%9Cberlegen%20(feat.%20Genosse%20Soselo)%20(Audio).opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Überlegen (feat. Genosse Soselo) (Audio).opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Verfall (Audio).mp3 in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Verfall%20(Audio).mp3&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Verfall (Audio).mp3 Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Pflaster (Official Video).opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Audiolith//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Pflaster%20(Official%20Video).opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Pflaster (Official Video).opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Falsch (Audio).opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Audiolith//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Falsch%20(Audio).opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Falsch (Audio).opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Brüh im Lichte (Audio).opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Audiolith//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Br%C3%BCh%20im%20Lichte%20(Audio).opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Brüh im Lichte (Audio).opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Audiolith//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20the%20Guns%20-%20Keine%20Lieder%20%C3%BCber%20Liebe.opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Audiolith//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20M-I-Doppel-L-I-Dance%20(Audio).opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving the Guns - Armutszeugnis (feat. Pöbel MC) [Official Video].opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Audiolith//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20the%20Guns%20-%20Armutszeugnis%20(feat.%20P%C3%B6bel%20MC)%20%5BOfficial%20Video%5D.opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving the Guns - Armutszeugnis (feat. Pöbel MC) [Official Video].opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Schrotgewehr (Audio).opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Audiolith//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Schrotgewehr%20(Audio).opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Schrotgewehr (Audio).opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Verfall (Audio).opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Audiolith//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Verfall%20(Audio).opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Verfall (Audio).opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Gartenzaun (Audio).opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Audiolith//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Gartenzaun%20(Audio).opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Gartenzaun (Audio).opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Ihr_Wir (Audio).opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Audiolith//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Ihr_Wir%20(Audio).opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Ihr_Wir (Audio).opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Tageslicht (feat. Cheddar Mike und Pöbel MC) (Audio).opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Audiolith//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Tageslicht%20(feat.%20Cheddar%20Mike%20und%20P%C3%B6bel%20MC)%20(Audio).opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Tageslicht (feat. Cheddar Mike und Pöbel MC) (Audio).opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Dasein (Audio).opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Audiolith//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Dasein%20(Audio).opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Dasein (Audio).opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Waving The Guns - Hut (Audio).opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Audiolith//medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FWaving%20The%20Guns%20-%20Hut%20(Audio).opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Waving The Guns - Hut (Audio).opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Intro.opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Waving%20The%20Guns/Totschlagargumente/medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FIntro.opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Intro.opus Sep 01 14:42:55 volumio volumio[1188]: info: Exploding uri music-library/INTERNAL/WTG/Outro.opus in service mpd Sep 01 14:42:55 volumio volumio[1188]: info: ALBUMART /albumart?cacheid=531&web=Waving%20The%20Guns/Totschlagargumente/medium&path=%2Fmnt%2FINTERNAL%2FWTG%2FOutro.opus&metadata=false Sep 01 14:42:55 volumio volumio[1188]: info: URI /mnt/INTERNAL/WTG/Outro.opus Sep 01 14:43:00 volumio wpa_supplicant[910]: RRM: Ignoring radio measurement request: Not RRM network Sep 01 14:43:01 volumio volumio[1188]: info: Preload queue cleared Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::ClearQueue Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::stop Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::stPlaybackTimer Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::serviceStop Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 3 Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::serviceStop Sep 01 14:43:01 volumio volumio[1188]: info: Spotify Stop Sep 01 14:43:01 volumio volumio[1188]: SPOTIFY: SPOTIFY STOP Sep 01 14:43:01 volumio volumio[1188]: SPOTIFY: {"status":"play","position":3,"title":"Schimmliges Brot","artist":"Foyer Des Arts","album":"Die John-Peel-Session","albumart":"https://i.scdn.co/image/ab67616d0000b273c3e331c98fe44cd115bbe74a","uri":"spotify:track:3VxBwhLMAJVaB5K3BZNjvY","trackType":"spotify","codec":"ogg","seek":501,"duration":155,"samplerate":"320 kbps","bitdepth":"16 bit","random":false,"repeat":false,"repeatSingle":false,"consume":false,"volume":35,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Sep 01 14:43:01 volumio volumio[1188]: info: Sending Spotify command to local API: /player/pause Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::clearPlayQueue Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::saveQueue Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::addQueueItems Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::addQueueItems Sep 01 14:43:01 volumio volumio[1188]: info: Preload queue cleared Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving the Guns - 13 - Gesetzestreue - Schlachtrufe WTG.mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving the Guns - 13 - Gesetzestreue - Schlachtrufe WTG.mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving the Guns - 13 - Gesetzestreue - Schlachtrufe WTG.opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving the Guns - 13 - Gesetzestreue - Schlachtrufe WTG.opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving the Guns - Armutszeugnis (feat. Pöbel MC) [Official Video].mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving the Guns - Armutszeugnis (feat. Pöbel MC) [Official Video].mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Brüh im Lichte (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Brüh im Lichte (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Dasein (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Dasein (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Falsch (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Falsch (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Gartenzaun (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Gartenzaun (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Hut (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Hut (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Ihr_Wir (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Ihr_Wir (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Pflaster (Official Video).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Pflaster (Official Video).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Schrotgewehr (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Schrotgewehr (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Tageslicht (feat. Cheddar Mike und Pöbel MC) (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Tageslicht (feat. Cheddar Mike und Pöbel MC) (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Überlegen (feat. Genosse Soselo) (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Überlegen (feat. Genosse Soselo) (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Überlegen (feat. Genosse Soselo) (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Überlegen (feat. Genosse Soselo) (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Verfall (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Verfall (Audio).mp3 Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Pflaster (Official Video).opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Pflaster (Official Video).opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Falsch (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Falsch (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Brüh im Lichte (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Brüh im Lichte (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving the Guns - Armutszeugnis (feat. Pöbel MC) [Official Video].opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving the Guns - Armutszeugnis (feat. Pöbel MC) [Official Video].opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Schrotgewehr (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Schrotgewehr (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Verfall (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Verfall (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Gartenzaun (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Gartenzaun (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Ihr_Wir (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Ihr_Wir (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Tageslicht (feat. Cheddar Mike und Pöbel MC) (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Tageslicht (feat. Cheddar Mike und Pöbel MC) (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Dasein (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Dasein (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Hut (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Hut (Audio).opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Intro.opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Intro.opus Sep 01 14:43:01 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Outro.opus Sep 01 14:43:01 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Outro.opus Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::saveQueue Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::play index 9 Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::stop Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::play index undefined Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3 Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:43:01 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand stop took 11 milliseconds Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 14:43:01 volumio volumio[1188]: info: Sep 01 14:43:01 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:01 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand clear took 2 milliseconds Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3" Sep 01 14:43:01 volumio volumio[1188]: info: Sep 01 14:43:01 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:01 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:01 volumio volumio[1188]: info: Sep 01 14:43:01 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:01 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:01 volumio volumio[1188]: error: updateQueue error: null Sep 01 14:43:01 volumio volumio[1188]: info: Sep 01 14:43:01 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:01 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:01 volumio volumio[1188]: info: ------------------------------ 10ms Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand add "INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3" took 8 milliseconds Sep 01 14:43:01 volumio volumio[1188]: info: ------------------------------ 5ms Sep 01 14:43:01 volumio volumio[1188]: info: ------------------------------ 4ms Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Sep 01 14:43:01 volumio volumio[1188]: info: Sep 01 14:43:01 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:01 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:01 volumio volumio[1188]: info: Sep 01 14:43:01 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:01 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:01 volumio volumio[1188]: error: Upnp client error: Error: This socket has been ended by the other party Sep 01 14:43:01 volumio volumio[1188]: info: ------------------------------ 8ms Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand play took 5 milliseconds Sep 01 14:43:01 volumio volumio[1188]: info: ------------------------------ 4ms Sep 01 14:43:01 volumio volumio[1188]: info: ------------------------------ 3ms Sep 01 14:43:01 volumio volumio[1188]: info: Sep 01 14:43:01 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:01 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:01 volumio volumio[1188]: info: Sep 01 14:43:01 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:01 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:01 volumio volumio[1188]: info: Sep 01 14:43:01 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:01 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand status took 19 milliseconds Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:01 volumio volumio[1188]: info: Sep 01 14:43:01 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:01 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand status took 22 milliseconds Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand status took 23 milliseconds Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand clearerror took 6 milliseconds Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:01 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:01 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:01 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:01 volumio volumio[1188]: info: ------------------------------ 36ms Sep 01 14:43:01 volumio volumio[1188]: info: Sep 01 14:43:01 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:01 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:01 volumio volumio[1188]: info: Sep 01 14:43:01 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:01 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:01 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand status took 27 milliseconds Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand clearerror took 25 milliseconds Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand clearerror took 24 milliseconds Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 21 milliseconds Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand status took 14 milliseconds Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand status took 13 milliseconds Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:01 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:01 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:01 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:01 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:01 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:01 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:01 volumio volumio[1188]: info: ------------------------------ 59ms Sep 01 14:43:01 volumio volumio[1188]: info: ------------------------------ 59ms Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 01 14:43:01 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:01 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:01 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:01 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:01 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:01 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:01 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:01 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:01 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:01 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:01 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:01 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:01 volumio volumio[1188]: info: ------------------------------ 58ms Sep 01 14:43:01 volumio volumio[1188]: info: ------------------------------ 43ms Sep 01 14:43:01 volumio volumio[1188]: info: ------------------------------ 42ms Sep 01 14:43:01 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:01 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:01 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:01 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:01 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:01 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Sep 01 14:43:01 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: info: Preload queue cleared Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::ClearQueue Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::stop Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::clearPlayQueue Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::saveQueue Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::addQueueItems Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::addQueueItems Sep 01 14:43:05 volumio volumio[1188]: info: Preload queue cleared Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving the Guns - 13 - Gesetzestreue - Schlachtrufe WTG.mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving the Guns - 13 - Gesetzestreue - Schlachtrufe WTG.mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving the Guns - 13 - Gesetzestreue - Schlachtrufe WTG.opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving the Guns - 13 - Gesetzestreue - Schlachtrufe WTG.opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving the Guns - Armutszeugnis (feat. Pöbel MC) [Official Video].mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving the Guns - Armutszeugnis (feat. Pöbel MC) [Official Video].mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Brüh im Lichte (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Brüh im Lichte (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Dasein (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Dasein (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Falsch (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Falsch (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Gartenzaun (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Gartenzaun (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Hut (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Hut (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Ihr_Wir (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Ihr_Wir (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Pflaster (Official Video).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Pflaster (Official Video).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Schrotgewehr (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Schrotgewehr (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Tageslicht (feat. Cheddar Mike und Pöbel MC) (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Tageslicht (feat. Cheddar Mike und Pöbel MC) (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Überlegen (feat. Genosse Soselo) (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Überlegen (feat. Genosse Soselo) (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Überlegen (feat. Genosse Soselo) (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Überlegen (feat. Genosse Soselo) (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Verfall (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Verfall (Audio).mp3 Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Pflaster (Official Video).opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Pflaster (Official Video).opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Falsch (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Falsch (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Brüh im Lichte (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Brüh im Lichte (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving the Guns - Armutszeugnis (feat. Pöbel MC) [Official Video].opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving the Guns - Armutszeugnis (feat. Pöbel MC) [Official Video].opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Schrotgewehr (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Schrotgewehr (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Verfall (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Verfall (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Gartenzaun (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Gartenzaun (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Ihr_Wir (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Ihr_Wir (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Tageslicht (feat. Cheddar Mike und Pöbel MC) (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Tageslicht (feat. Cheddar Mike und Pöbel MC) (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Dasein (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Dasein (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Waving The Guns - Hut (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Waving The Guns - Hut (Audio).opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Intro.opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Intro.opus Sep 01 14:43:05 volumio volumio[1188]: info: Adding Item to queue: music-library/INTERNAL/WTG/Outro.opus Sep 01 14:43:05 volumio volumio[1188]: info: Using cached record of: music-library/INTERNAL/WTG/Outro.opus Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::saveQueue Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::play index 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::stop Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::play index undefined Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3 Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:43:05 volumio volumio[1188]: info: Sep 01 14:43:05 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand stop took 5 milliseconds Sep 01 14:43:05 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 14:43:05 volumio volumio[1188]: info: Sep 01 14:43:05 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:05 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand status took 1 milliseconds Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand clear took 2 milliseconds Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3" Sep 01 14:43:05 volumio volumio[1188]: info: Sep 01 14:43:05 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:05 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:05 volumio volumio[1188]: info: Sep 01 14:43:05 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:05 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:05 volumio volumio[1188]: error: updateQueue error: null Sep 01 14:43:05 volumio volumio[1188]: info: Sep 01 14:43:05 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:05 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:05 volumio volumio[1188]: info: ------------------------------ 4ms Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand add "INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3" took 4 milliseconds Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:05 volumio volumio[1188]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 01 14:43:05 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:05 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:05 volumio volumio[1188]: info: No code Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:05 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:05 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:05 volumio volumio[1188]: info: No code Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:05 volumio volumio[1188]: info: ------------------------------ 23ms Sep 01 14:43:05 volumio volumio[1188]: info: ------------------------------ 29ms Sep 01 14:43:05 volumio volumio[1188]: info: ------------------------------ 28ms Sep 01 14:43:05 volumio volumio[1188]: info: Sep 01 14:43:05 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:05 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:05 volumio volumio[1188]: info: Sep 01 14:43:05 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:05 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:05 volumio volumio[1188]: info: Sep 01 14:43:05 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:05 volumio volumio[1188]: info: Sep 01 14:43:05 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:05 volumio volumio[1188]: info: ------------------------------ 31ms Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand play took 28 milliseconds Sep 01 14:43:05 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:05 volumio volumio[1188]: info: ------------------------------ 4ms Sep 01 14:43:05 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:05 volumio volumio[1188]: info: ------------------------------ 3ms Sep 01 14:43:05 volumio volumio[1188]: info: Sep 01 14:43:05 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:05 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:05 volumio volumio[1188]: info: Sep 01 14:43:05 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:05 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:05 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:05 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:05 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:05 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:05 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:05 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand status took 22 milliseconds Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand status took 23 milliseconds Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand status took 22 milliseconds Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand status took 22 milliseconds Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand clearerror took 8 milliseconds Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand clearerror took 8 milliseconds Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand clearerror took 7 milliseconds Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand clearerror took 7 milliseconds Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 01 14:43:05 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:05 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:05 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:05 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:05 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:05 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:05 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:05 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:05 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:05 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:05 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:05 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:05 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:05 volumio volumio[1188]: info: ------------------------------ 47ms Sep 01 14:43:05 volumio volumio[1188]: info: ------------------------------ 48ms Sep 01 14:43:05 volumio volumio[1188]: info: ------------------------------ 46ms Sep 01 14:43:05 volumio volumio[1188]: info: ------------------------------ 45ms Sep 01 14:43:05 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:05 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:05 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:05 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:13 volumio go-librespot[1416]: time="2024-09-01T14:43:13+02:00" level=error msg="did not receive last pong from dealer, 90s passed" Sep 01 14:43:15 volumio volumio[1188]: info: CoreCommandRouter::volumioGetState Sep 01 14:43:15 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::play index undefined Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3 Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand stop took 1 milliseconds Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 14:43:17 volumio volumio[1188]: info: Sep 01 14:43:17 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:17 volumio volumio[1188]: info: Sep 01 14:43:17 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:17 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:17 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand clear took 1 milliseconds Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3" Sep 01 14:43:17 volumio volumio[1188]: info: Sep 01 14:43:17 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:17 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:17 volumio volumio[1188]: info: Sep 01 14:43:17 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:17 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:17 volumio volumio[1188]: error: updateQueue error: null Sep 01 14:43:17 volumio volumio[1188]: info: Sep 01 14:43:17 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:17 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand status took 3 milliseconds Sep 01 14:43:17 volumio volumio[1188]: info: ------------------------------ 5ms Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand add "INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3" took 4 milliseconds Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:17 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:17 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:17 volumio volumio[1188]: info: No code Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:17 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:17 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:17 volumio volumio[1188]: info: No code Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:17 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:17 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:17 volumio volumio[1188]: info: No code Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:17 volumio volumio[1188]: info: ------------------------------ 36ms Sep 01 14:43:17 volumio volumio[1188]: info: ------------------------------ 34ms Sep 01 14:43:17 volumio volumio[1188]: info: ------------------------------ 34ms Sep 01 14:43:17 volumio volumio[1188]: info: Sep 01 14:43:17 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:17 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:17 volumio volumio[1188]: info: Sep 01 14:43:17 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:17 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:17 volumio volumio[1188]: info: Sep 01 14:43:17 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:17 volumio volumio[1188]: info: Sep 01 14:43:17 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:17 volumio volumio[1188]: info: ------------------------------ 37ms Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand play took 36 milliseconds Sep 01 14:43:17 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:17 volumio volumio[1188]: info: ------------------------------ 3ms Sep 01 14:43:17 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:17 volumio volumio[1188]: info: ------------------------------ 3ms Sep 01 14:43:17 volumio volumio[1188]: info: Sep 01 14:43:17 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:17 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:17 volumio volumio[1188]: info: Sep 01 14:43:17 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:17 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:17 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:17 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:17 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:17 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:17 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:17 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:17 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:17 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:17 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand status took 31 milliseconds Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand status took 31 milliseconds Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand status took 31 milliseconds Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand status took 30 milliseconds Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand clearerror took 10 milliseconds Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand clearerror took 9 milliseconds Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand clearerror took 8 milliseconds Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 01 14:43:17 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:17 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:17 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:17 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:17 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:17 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:17 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:17 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:17 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:17 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:17 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:17 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:17 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:17 volumio volumio[1188]: info: ------------------------------ 62ms Sep 01 14:43:17 volumio volumio[1188]: info: ------------------------------ 61ms Sep 01 14:43:17 volumio volumio[1188]: info: ------------------------------ 60ms Sep 01 14:43:17 volumio volumio[1188]: info: ------------------------------ 59ms Sep 01 14:43:17 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:17 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:17 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:17 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::play index undefined Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3 Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand stop took 1 milliseconds Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 14:43:20 volumio volumio[1188]: info: Sep 01 14:43:20 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:20 volumio volumio[1188]: info: Sep 01 14:43:20 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:20 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:20 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand clear took 1 milliseconds Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3" Sep 01 14:43:20 volumio volumio[1188]: info: Sep 01 14:43:20 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:20 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:20 volumio volumio[1188]: info: Sep 01 14:43:20 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:20 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:20 volumio volumio[1188]: error: updateQueue error: null Sep 01 14:43:20 volumio volumio[1188]: info: Sep 01 14:43:20 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:20 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand status took 3 milliseconds Sep 01 14:43:20 volumio volumio[1188]: info: ------------------------------ 5ms Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand add "INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3" took 4 milliseconds Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand status took 4 milliseconds Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:20 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:20 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:20 volumio volumio[1188]: info: No code Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:20 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:20 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:20 volumio volumio[1188]: info: No code Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:20 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:20 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:20 volumio volumio[1188]: info: No code Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:20 volumio volumio[1188]: info: ------------------------------ 34ms Sep 01 14:43:20 volumio volumio[1188]: info: ------------------------------ 33ms Sep 01 14:43:20 volumio volumio[1188]: info: ------------------------------ 33ms Sep 01 14:43:20 volumio volumio[1188]: info: Sep 01 14:43:20 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:20 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:20 volumio volumio[1188]: info: Sep 01 14:43:20 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:20 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:20 volumio volumio[1188]: info: Sep 01 14:43:20 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:20 volumio volumio[1188]: info: Sep 01 14:43:20 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:20 volumio volumio[1188]: info: ------------------------------ 38ms Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand play took 34 milliseconds Sep 01 14:43:20 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:20 volumio volumio[1188]: info: ------------------------------ 6ms Sep 01 14:43:20 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:20 volumio volumio[1188]: info: ------------------------------ 5ms Sep 01 14:43:20 volumio volumio[1188]: info: Sep 01 14:43:20 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:20 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:20 volumio volumio[1188]: info: Sep 01 14:43:20 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:20 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:20 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:20 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:20 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:20 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:20 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:20 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:20 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:20 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:20 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand status took 26 milliseconds Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand status took 27 milliseconds Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand status took 27 milliseconds Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand status took 27 milliseconds Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand clearerror took 15 milliseconds Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand clearerror took 14 milliseconds Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand clearerror took 13 milliseconds Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand clearerror took 12 milliseconds Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 01 14:43:20 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:20 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:20 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:20 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:20 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:20 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:20 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:20 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:20 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:20 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:20 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:20 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:20 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:20 volumio volumio[1188]: info: ------------------------------ 62ms Sep 01 14:43:20 volumio volumio[1188]: info: ------------------------------ 61ms Sep 01 14:43:20 volumio volumio[1188]: info: ------------------------------ 60ms Sep 01 14:43:20 volumio volumio[1188]: info: ------------------------------ 59ms Sep 01 14:43:20 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:20 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:20 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:20 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::play index undefined Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3 Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand stop took 1 milliseconds Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 14:43:24 volumio volumio[1188]: info: Sep 01 14:43:24 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:24 volumio volumio[1188]: info: Sep 01 14:43:24 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:24 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:24 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand clear took 1 milliseconds Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3" Sep 01 14:43:24 volumio volumio[1188]: info: Sep 01 14:43:24 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:24 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:24 volumio volumio[1188]: info: Sep 01 14:43:24 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:24 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:24 volumio volumio[1188]: error: updateQueue error: null Sep 01 14:43:24 volumio volumio[1188]: info: Sep 01 14:43:24 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:24 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand status took 3 milliseconds Sep 01 14:43:24 volumio volumio[1188]: info: ------------------------------ 5ms Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand add "INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3" took 4 milliseconds Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:24 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:24 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:24 volumio volumio[1188]: info: No code Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:24 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:24 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:24 volumio volumio[1188]: info: No code Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:24 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:24 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:24 volumio volumio[1188]: info: No code Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:24 volumio volumio[1188]: info: ------------------------------ 32ms Sep 01 14:43:24 volumio volumio[1188]: info: ------------------------------ 30ms Sep 01 14:43:24 volumio volumio[1188]: info: ------------------------------ 33ms Sep 01 14:43:24 volumio volumio[1188]: info: Sep 01 14:43:24 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:24 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:24 volumio volumio[1188]: info: Sep 01 14:43:24 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:24 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:24 volumio volumio[1188]: info: Sep 01 14:43:24 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:24 volumio volumio[1188]: info: Sep 01 14:43:24 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:24 volumio volumio[1188]: info: ------------------------------ 36ms Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand play took 35 milliseconds Sep 01 14:43:24 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:24 volumio volumio[1188]: info: ------------------------------ 3ms Sep 01 14:43:24 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:24 volumio volumio[1188]: info: ------------------------------ 2ms Sep 01 14:43:24 volumio volumio[1188]: info: Sep 01 14:43:24 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:24 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:24 volumio volumio[1188]: info: Sep 01 14:43:24 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:24 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand status took 18 milliseconds Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand status took 19 milliseconds Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand status took 18 milliseconds Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand status took 18 milliseconds Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand clearerror took 20 milliseconds Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand clearerror took 19 milliseconds Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand clearerror took 18 milliseconds Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand clearerror took 17 milliseconds Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 13 milliseconds Sep 01 14:43:24 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:24 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:24 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:24 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:24 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:24 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:24 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:24 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:24 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:24 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:24 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:24 volumio volumio[1188]: info: ------------------------------ 54ms Sep 01 14:43:24 volumio volumio[1188]: info: ------------------------------ 55ms Sep 01 14:43:24 volumio volumio[1188]: info: ------------------------------ 53ms Sep 01 14:43:24 volumio volumio[1188]: info: ------------------------------ 52ms Sep 01 14:43:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:24 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioNext Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::next Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::stop Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::play index undefined Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3 Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand stop took 1 milliseconds Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 14:43:30 volumio volumio[1188]: info: Sep 01 14:43:30 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:30 volumio volumio[1188]: info: Sep 01 14:43:30 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:30 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand clear took 1 milliseconds Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3" Sep 01 14:43:30 volumio volumio[1188]: info: Sep 01 14:43:30 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:30 volumio volumio[1188]: info: Sep 01 14:43:30 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:30 volumio volumio[1188]: error: updateQueue error: null Sep 01 14:43:30 volumio volumio[1188]: info: Sep 01 14:43:30 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:30 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand status took 4 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: ------------------------------ 4ms Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand add "INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3" took 3 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand status took 3 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:30 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: No code Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:30 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: No code Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:30 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: No code Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: ------------------------------ 37ms Sep 01 14:43:30 volumio volumio[1188]: info: ------------------------------ 36ms Sep 01 14:43:30 volumio volumio[1188]: info: ------------------------------ 36ms Sep 01 14:43:30 volumio volumio[1188]: info: Sep 01 14:43:30 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:30 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:30 volumio volumio[1188]: info: Sep 01 14:43:30 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:30 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:30 volumio volumio[1188]: info: ------------------------------ 41ms Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand play took 40 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: ------------------------------ 4ms Sep 01 14:43:30 volumio volumio[1188]: info: ------------------------------ 4ms Sep 01 14:43:30 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:30 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:30 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:30 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:30 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:30 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:30 volumio volumio[1188]: info: Sep 01 14:43:30 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:30 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:30 volumio volumio[1188]: info: Sep 01 14:43:30 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:30 volumio volumio[1188]: info: Sep 01 14:43:30 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:30 volumio volumio[1188]: info: Sep 01 14:43:30 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand status took 15 milliseconds Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand status took 5 milliseconds Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand status took 5 milliseconds Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:30 volumio volumio[1188]: info: Sep 01 14:43:30 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:30 volumio volumio[1188]: info: Sep 01 14:43:30 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand status took 13 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand clearerror took 11 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand clearerror took 10 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand clearerror took 10 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand status took 4 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand status took 3 milliseconds Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":200,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","trackType":"mp3"} Sep 01 14:43:30 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":200,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","trackType":"mp3"} Sep 01 14:43:30 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":200,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","trackType":"mp3"} Sep 01 14:43:30 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: ------------------------------ 40ms Sep 01 14:43:30 volumio volumio[1188]: info: ------------------------------ 30ms Sep 01 14:43:30 volumio volumio[1188]: info: ------------------------------ 30ms Sep 01 14:43:30 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:30 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:30 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand clearerror took 27 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 25 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 24 milliseconds Sep 01 14:43:30 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 25 milliseconds Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:30 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":200,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","trackType":"mp3"} Sep 01 14:43:30 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":200,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","trackType":"mp3"} Sep 01 14:43:30 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":200,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","trackType":"mp3"} Sep 01 14:43:30 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:30 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:30 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:30 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:30 volumio volumio[1188]: info: ------------------------------ 51ms Sep 01 14:43:30 volumio volumio[1188]: info: ------------------------------ 42ms Sep 01 14:43:30 volumio volumio[1188]: info: ------------------------------ 42ms Sep 01 14:43:30 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:30 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:30 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::play index undefined Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3 Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:43:31 volumio volumio[1188]: info: Sep 01 14:43:31 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand stop took 1 milliseconds Sep 01 14:43:31 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 14:43:31 volumio volumio[1188]: info: Sep 01 14:43:31 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:31 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand status took 3 milliseconds Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand clear took 2 milliseconds Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3" Sep 01 14:43:31 volumio volumio[1188]: info: Sep 01 14:43:31 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:31 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:31 volumio volumio[1188]: info: Sep 01 14:43:31 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:31 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:31 volumio volumio[1188]: error: updateQueue error: null Sep 01 14:43:31 volumio volumio[1188]: info: Sep 01 14:43:31 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:31 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:31 volumio volumio[1188]: info: ------------------------------ 5ms Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand add "INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3" took 4 milliseconds Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand status took 4 milliseconds Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand status took 4 milliseconds Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:31 volumio volumio[1188]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 01 14:43:31 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:31 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:31 volumio volumio[1188]: info: No code Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:31 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:31 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:31 volumio volumio[1188]: info: No code Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:31 volumio volumio[1188]: info: ------------------------------ 30ms Sep 01 14:43:31 volumio volumio[1188]: info: ------------------------------ 32ms Sep 01 14:43:31 volumio volumio[1188]: info: ------------------------------ 31ms Sep 01 14:43:31 volumio volumio[1188]: info: Sep 01 14:43:31 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:31 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:31 volumio volumio[1188]: info: Sep 01 14:43:31 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:31 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:31 volumio volumio[1188]: info: Sep 01 14:43:31 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:31 volumio volumio[1188]: info: Sep 01 14:43:31 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:31 volumio volumio[1188]: info: ------------------------------ 32ms Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand play took 29 milliseconds Sep 01 14:43:31 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:31 volumio volumio[1188]: info: ------------------------------ 3ms Sep 01 14:43:31 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:31 volumio volumio[1188]: info: ------------------------------ 2ms Sep 01 14:43:31 volumio volumio[1188]: info: Sep 01 14:43:31 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:31 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:31 volumio volumio[1188]: info: Sep 01 14:43:31 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:31 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:31 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:31 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:31 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:31 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:31 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:31 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand status took 17 milliseconds Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand status took 17 milliseconds Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand status took 17 milliseconds Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand status took 17 milliseconds Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand clearerror took 7 milliseconds Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand clearerror took 7 milliseconds Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand clearerror took 6 milliseconds Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand clearerror took 5 milliseconds Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 01 14:43:31 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:31 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:31 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":200,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","trackType":"mp3"} Sep 01 14:43:31 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:31 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":200,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","trackType":"mp3"} Sep 01 14:43:31 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:31 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":200,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","trackType":"mp3"} Sep 01 14:43:31 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:31 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":200,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving The Guns - M-I-Doppel-L-I-Dance (Audio).mp3","trackType":"mp3"} Sep 01 14:43:31 volumio volumio[1188]: verbose: CURRENT POSITION 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:31 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:31 volumio volumio[1188]: info: CorePlayQueue::getTrack 10 Sep 01 14:43:31 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:31 volumio volumio[1188]: info: ------------------------------ 43ms Sep 01 14:43:31 volumio volumio[1188]: info: ------------------------------ 43ms Sep 01 14:43:31 volumio volumio[1188]: info: ------------------------------ 42ms Sep 01 14:43:31 volumio volumio[1188]: info: ------------------------------ 41ms Sep 01 14:43:31 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:31 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:31 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:31 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:32 volumio volumio[1188]: info: CoreCommandRouter::volumioPrevious Sep 01 14:43:32 volumio volumio[1188]: info: CoreStateMachine::previous Sep 01 14:43:32 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Sep 01 14:43:32 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Sep 01 14:43:32 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:32 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:32 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:32 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:32 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:33 volumio volumio[1188]: error: error Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::play index undefined Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3 Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand stop took 1 milliseconds Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Sep 01 14:43:36 volumio volumio[1188]: info: Sep 01 14:43:36 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:36 volumio volumio[1188]: info: Sep 01 14:43:36 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:36 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:36 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand clear took 1 milliseconds Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3" Sep 01 14:43:36 volumio volumio[1188]: info: Sep 01 14:43:36 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:36 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:36 volumio volumio[1188]: info: Sep 01 14:43:36 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:36 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:36 volumio volumio[1188]: error: updateQueue error: null Sep 01 14:43:36 volumio volumio[1188]: info: Sep 01 14:43:36 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:36 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand status took 3 milliseconds Sep 01 14:43:36 volumio volumio[1188]: info: ------------------------------ 4ms Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand add "INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3" took 4 milliseconds Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand status took 2 milliseconds Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:36 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:36 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:36 volumio volumio[1188]: info: No code Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:36 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:36 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:36 volumio volumio[1188]: info: No code Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:36 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 01 14:43:36 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:36 volumio volumio[1188]: info: No code Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:36 volumio volumio[1188]: info: ------------------------------ 27ms Sep 01 14:43:36 volumio volumio[1188]: info: ------------------------------ 26ms Sep 01 14:43:36 volumio volumio[1188]: info: ------------------------------ 26ms Sep 01 14:43:36 volumio volumio[1188]: info: Sep 01 14:43:36 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:36 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:36 volumio volumio[1188]: info: Sep 01 14:43:36 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Sep 01 14:43:36 volumio volumio[1188]: info: Ignoring MPD Status Update Sep 01 14:43:36 volumio volumio[1188]: info: Sep 01 14:43:36 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:36 volumio volumio[1188]: info: Sep 01 14:43:36 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:36 volumio volumio[1188]: info: ------------------------------ 32ms Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand play took 34 milliseconds Sep 01 14:43:36 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:36 volumio volumio[1188]: info: ------------------------------ 6ms Sep 01 14:43:36 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:36 volumio volumio[1188]: info: ------------------------------ 6ms Sep 01 14:43:36 volumio volumio[1188]: info: Sep 01 14:43:36 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:36 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:36 volumio volumio[1188]: info: Sep 01 14:43:36 volumio volumio[1188]: ---------------------------- MPD announces state update: player Sep 01 14:43:36 volumio volumio[1188]: info: ControllerMpd::getState Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Sep 01 14:43:36 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:36 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:36 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:36 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:36 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:36 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand status took 22 milliseconds Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand status took 21 milliseconds Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand status took 20 milliseconds Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand status took 20 milliseconds Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::parseState Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 01 14:43:36 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:36 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:36 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand clearerror took 18 milliseconds Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand clearerror took 17 milliseconds Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand clearerror took 16 milliseconds Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand clearerror took 15 milliseconds Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 01 14:43:36 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:36 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Sep 01 14:43:36 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:36 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:36 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:36 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:36 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:36 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:36 volumio volumio[1188]: info: ControllerMpd::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":321,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Waving the Guns - Keine Lieder über Liebe.mp3","artist":null,"album":null,"uri":"INTERNAL/WTG/Waving the Guns - Keine Lieder über Liebe.mp3","trackType":"mp3"} Sep 01 14:43:36 volumio volumio[1188]: verbose: CURRENT POSITION 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Sep 01 14:43:36 volumio volumio[1188]: info: CoreStateMachine::pushState Sep 01 14:43:36 volumio volumio[1188]: info: CorePlayQueue::getTrack 9 Sep 01 14:43:36 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Sep 01 14:43:36 volumio volumio[1188]: info: ------------------------------ 57ms Sep 01 14:43:36 volumio volumio[1188]: info: ------------------------------ 56ms Sep 01 14:43:36 volumio volumio[1188]: info: ------------------------------ 52ms Sep 01 14:43:36 volumio volumio[1188]: info: ------------------------------ 51ms Sep 01 14:43:36 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:36 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:36 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:36 volumio volumio[1188]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Sep 01 14:43:36 volumio volumio[1188]: error: An error occurred while searching Error: getaddrinfo ENOTFOUND api.spotify.com Sep 01 14:43:37 volumio volumio[1188]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 14:43:37 volumio volumio[1188]: Error: connect EHOSTUNREACH 130.211.19.189:80 Sep 01 14:43:37 volumio volumio[1188]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Sep 01 14:43:37 volumio volumio[1188]: errno: -113, Sep 01 14:43:37 volumio volumio[1188]: code: 'EHOSTUNREACH', Sep 01 14:43:37 volumio volumio[1188]: syscall: 'connect', Sep 01 14:43:37 volumio volumio[1188]: address: '130.211.19.189', Sep 01 14:43:37 volumio volumio[1188]: port: 80 Sep 01 14:43:37 volumio volumio[1188]: } Sep 01 14:43:37 volumio volumio[1188]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 01 14:43:38 volumio sudo[3741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-01 14:42 Sep 01 14:43:38 volumio sudo[3741]: pam_unix(sudo:session): session opened for user root by (uid=0) 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" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"