Sep 03 14:48:03 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 14:48:03 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 14:48:03 volumio volumio[1033]: info: Discovery: Getting this device information Sep 03 14:48:03 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Sep 03 14:48:03 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:03 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:48:11 volumio go-librespot[1395]: time="2025-09-03T14:48:11+02:00" level=debug msg="fetched chunk 13/13, size: 175169" uri="spotify:track:6PTgSuFz9JqQ1o0jTYOuvX" Sep 03 14:48:21 volumio go-librespot[1395]: time="2025-09-03T14:48:21+02:00" level=trace msg="sent dealer ping" Sep 03 14:48:21 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 03 14:48:21 volumio dbus-daemon[621]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.1859' (uid=0 pid=1918 comm="timedatectl show --property=NTPSynchronized --valu") Sep 03 14:48:21 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 03 14:48:21 volumio go-librespot[1395]: time="2025-09-03T14:48:21+02:00" level=trace msg="received dealer pong" Sep 03 14:48:21 volumio dbus-daemon[621]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 03 14:48:21 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 03 14:48:21 volumio setdatetime-helper.sh[1917]: Time is already synchronized. Sep 03 14:48:21 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 03 14:48:21 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 03 14:48:26 volumio volumio[1033]: info: CoreCommandRouter::volumioNext Sep 03 14:48:26 volumio volumio[1033]: info: CoreStateMachine::next Sep 03 14:48:26 volumio volumio[1033]: info: CoreStateMachine::stop Sep 03 14:48:26 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:26 volumio volumio[1033]: info: CoreStateMachine::stPlaybackTimer Sep 03 14:48:26 volumio volumio[1033]: info: CoreStateMachine::updateTrackBlock Sep 03 14:48:26 volumio volumio[1033]: info: CorePlayQueue::getTrackBlock Sep 03 14:48:26 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:26 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:26 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:26 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:26 volumio volumio[1033]: info: CoreStateMachine::serviceStop Sep 03 14:48:26 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:26 volumio volumio[1033]: info: CoreCommandRouter::serviceStop Sep 03 14:48:26 volumio volumio[1033]: info: Spotify Stop Sep 03 14:48:26 volumio volumio[1033]: SPOTIFY: SPOTIFY STOP Sep 03 14:48:26 volumio volumio[1033]: SPOTIFY: {"status":"play","position":0,"title":"Dior (feat. Chrystal)","artist":"MK","album":"Dior (feat. Chrystal)","albumart":"https://i.scdn.co/image/ab67616d0000b27335fef9ba72ca05ba91f9b7e8","uri":"spotify:track:6PTgSuFz9JqQ1o0jTYOuvX","trackType":"spotify","codec":"ogg","seek":1,"duration":169,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":false,"repeat":null,"repeatSingle":false,"consume":false,"volume":61,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Sep 03 14:48:26 volumio volumio[1033]: info: Sending Spotify command to local API: /player/pause Sep 03 14:48:26 volumio volumio[1033]: info: CoreStateMachine::play index undefined Sep 03 14:48:26 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:26 volumio volumio[1033]: info: CorePlayQueue::getTrack 1 Sep 03 14:48:26 volumio volumio[1033]: info: CoreStateMachine::startPlaybackTimer Sep 03 14:48:26 volumio volumio[1033]: info: CorePlayQueue::getTrack 1 Sep 03 14:48:26 volumio volumio[1033]: info: [1756903706872] ControllerSpotify::clearAddPlayTrack Sep 03 14:48:26 volumio volumio[1033]: info: Sending Spotify command with payload to local API: /player/play Sep 03 14:48:26 volumio volumio[1033]: info: CoreStateMachine::updateTrackBlock Sep 03 14:48:26 volumio volumio[1033]: info: CorePlayQueue::getTrackBlock Sep 03 14:48:26 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:26 volumio go-librespot[1395]: time="2025-09-03T14:48:26+02:00" level=debug msg="pause track at 142538ms" Sep 03 14:48:26 volumio go-librespot[1395]: time="2025-09-03T14:48:26+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=trace msg="emitting websocket event: paused" Sep 03 14:48:27 volumio volumio[1033]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:6PTgSuFz9JqQ1o0jTYOuvX","play_origin":"go-librespot"}} Sep 03 14:48:27 volumio volumio[1033]: SPOTIFY: PUSH STATE SPOTIFY Sep 03 14:48:27 volumio volumio[1033]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 03 14:48:27 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:27 volumio volumio[1033]: info: CorePlayQueue::getTrack 1 Sep 03 14:48:27 volumio volumio[1033]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 03 14:48:27 volumio volumio[1033]: verbose: CURRENT POSITION 1 Sep 03 14:48:27 volumio volumio[1033]: info: CoreStateMachine::syncState stateService pause Sep 03 14:48:27 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:27 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:27 volumio volumio[1033]: info: CorePlayQueue::getTrack 1 Sep 03 14:48:27 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:27 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:27 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=debug msg="resolved context of track" uri="spotify:track:1CPZ5BxNNd0n0nF4Orb9JS" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:1CPZ5BxNNd0n0nF4Orb9JS" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:1CPZ5BxNNd0n0nF4Orb9JS" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=trace msg="emitting websocket event: will_play" Sep 03 14:48:27 volumio volumio[1033]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:1CPZ5BxNNd0n0nF4Orb9JS","play_origin":"go-librespot"}} Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=debug msg="selected format OGG_VORBIS_320 (ad547e77244b315fcc04d7c8b99af689ed33b5e3)" uri="spotify:track:1CPZ5BxNNd0n0nF4Orb9JS" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=debug msg="requested aes key for file ad547e77244b315fcc04d7c8b99af689ed33b5e3, gid: 1CPZ5BxNNd0n0nF4Orb9JS" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:1CPZ5BxNNd0n0nF4Orb9JS" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=debug msg="fetched first chunk of 15, total size is 7797163 bytes" uri="spotify:track:1CPZ5BxNNd0n0nF4Orb9JS" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=info msg="loaded track \"Golden\" (paused: false, position: 0ms, duration: 194607ms, prefetched: false)" uri="spotify:track:1CPZ5BxNNd0n0nF4Orb9JS" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=trace msg="scheduling prefetch in 164s" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=trace msg="emitting websocket event: metadata" Sep 03 14:48:27 volumio volumio[1033]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:1CPZ5BxNNd0n0nF4Orb9JS","name":"Golden","artist_names":["HUNTR/X","EJAE","AUDREY NUNA","REI AMI","KPop Demon Hunters Cast"],"album_name":"KPop Demon Hunters (Soundtrack from the Netflix Film)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e024dcb6c5df15cf74596ab25a4","position":0,"duration":194607,"release_date":"year:2025 month:6 day:20","track_number":4,"disc_number":1}} Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:1CPZ5BxNNd0n0nF4Orb9JS" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=trace msg="emitting websocket event: playing" Sep 03 14:48:27 volumio volumio[1033]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1CPZ5BxNNd0n0nF4Orb9JS","resume":false,"play_origin":"go-librespot"}} Sep 03 14:48:27 volumio volumio[1033]: SPOTIFY: PUSH STATE SPOTIFY Sep 03 14:48:27 volumio volumio[1033]: SPOTIFY: {"status":"play","service":"spop","title":"Golden","artist":"HUNTR/X, EJAE, AUDREY NUNA, REI AMI, KPop Demon Hunters Cast","album":"KPop Demon Hunters (Soundtrack from the Netflix Film)","albumart":"https://i.scdn.co/image/ab67616d00001e024dcb6c5df15cf74596ab25a4","uri":"spotify:track:1CPZ5BxNNd0n0nF4Orb9JS","trackType":"spotify","seek":0,"duration":194,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 03 14:48:27 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:27 volumio volumio[1033]: info: CorePlayQueue::getTrack 1 Sep 03 14:48:27 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Golden","artist":"HUNTR/X, EJAE, AUDREY NUNA, REI AMI, KPop Demon Hunters Cast","album":"KPop Demon Hunters (Soundtrack from the Netflix Film)","albumart":"https://i.scdn.co/image/ab67616d00001e024dcb6c5df15cf74596ab25a4","uri":"spotify:track:1CPZ5BxNNd0n0nF4Orb9JS","trackType":"spotify","seek":0,"duration":194,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 03 14:48:27 volumio volumio[1033]: verbose: CURRENT POSITION 1 Sep 03 14:48:27 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:27 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:1CPZ5BxNNd0n0nF4Orb9JS" Sep 03 14:48:27 volumio go-librespot[1395]: time="2025-09-03T14:48:27+02:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:1CPZ5BxNNd0n0nF4Orb9JS" Sep 03 14:48:27 volumio volumio[1033]: SPOTIFY: PUSH STATE SPOTIFY Sep 03 14:48:27 volumio volumio[1033]: SPOTIFY: {"status":"play","service":"spop","title":"Golden","artist":"HUNTR/X, EJAE, AUDREY NUNA, REI AMI, KPop Demon Hunters Cast","album":"KPop Demon Hunters (Soundtrack from the Netflix Film)","albumart":"https://i.scdn.co/image/ab67616d00001e024dcb6c5df15cf74596ab25a4","uri":"spotify:track:1CPZ5BxNNd0n0nF4Orb9JS","trackType":"spotify","seek":0,"duration":194,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 03 14:48:27 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:27 volumio volumio[1033]: info: CorePlayQueue::getTrack 1 Sep 03 14:48:27 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Golden","artist":"HUNTR/X, EJAE, AUDREY NUNA, REI AMI, KPop Demon Hunters Cast","album":"KPop Demon Hunters (Soundtrack from the Netflix Film)","albumart":"https://i.scdn.co/image/ab67616d00001e024dcb6c5df15cf74596ab25a4","uri":"spotify:track:1CPZ5BxNNd0n0nF4Orb9JS","trackType":"spotify","seek":0,"duration":194,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 03 14:48:27 volumio volumio[1033]: verbose: CURRENT POSITION 1 Sep 03 14:48:27 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:27 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:27 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:27 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:27 volumio volumio[1033]: info: CorePlayQueue::getTrack 1 Sep 03 14:48:27 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:27 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:27 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:27 volumio volumio[1033]: info: CorePlayQueue::getTrack 1 Sep 03 14:48:27 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:27 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:27 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:30 volumio go-librespot[1395]: time="2025-09-03T14:48:30+02:00" level=trace msg="received accesspoint ping" Sep 03 14:48:30 volumio go-librespot[1395]: time="2025-09-03T14:48:30+02:00" level=trace msg="received accesspoint pong ack" Sep 03 14:48:33 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 14:48:33 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 14:48:33 volumio volumio[1033]: info: Discovery: Getting this device information Sep 03 14:48:33 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Sep 03 14:48:33 volumio volumio[1033]: info: CorePlayQueue::getTrack 1 Sep 03 14:48:33 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:48:41 volumio go-librespot[1395]: time="2025-09-03T14:48:41+02:00" level=debug msg="fetched chunk 4/14, size: 524288" uri="spotify:track:1CPZ5BxNNd0n0nF4Orb9JS" Sep 03 14:48:49 volumio volumio[1033]: info: Preload queue cleared Sep 03 14:48:49 volumio volumio[1033]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 14:48:49 volumio volumio[1033]: info: CoreStateMachine::ClearQueue Sep 03 14:48:49 volumio volumio[1033]: info: CoreStateMachine::stop Sep 03 14:48:49 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:49 volumio volumio[1033]: info: CoreStateMachine::stPlaybackTimer Sep 03 14:48:49 volumio volumio[1033]: info: CoreStateMachine::updateTrackBlock Sep 03 14:48:49 volumio volumio[1033]: info: CorePlayQueue::getTrackBlock Sep 03 14:48:49 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:49 volumio volumio[1033]: info: CorePlayQueue::getTrack 1 Sep 03 14:48:49 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:49 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:49 volumio volumio[1033]: info: CoreStateMachine::serviceStop Sep 03 14:48:49 volumio volumio[1033]: info: CorePlayQueue::getTrack 1 Sep 03 14:48:49 volumio volumio[1033]: info: CoreCommandRouter::serviceStop Sep 03 14:48:49 volumio volumio[1033]: info: Spotify Stop Sep 03 14:48:49 volumio volumio[1033]: SPOTIFY: SPOTIFY STOP Sep 03 14:48:49 volumio volumio[1033]: SPOTIFY: {"status":"play","position":1,"title":"Golden","artist":"HUNTR/X","album":"KPop Demon Hunters (Soundtrack from the Netflix Film)","albumart":"https://i.scdn.co/image/ab67616d0000b2734dcb6c5df15cf74596ab25a4","uri":"spotify:track:1CPZ5BxNNd0n0nF4Orb9JS","trackType":"spotify","codec":"ogg","seek":0,"duration":194,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":false,"repeat":null,"repeatSingle":false,"consume":false,"volume":61,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Sep 03 14:48:49 volumio volumio[1033]: info: Sending Spotify command to local API: /player/pause Sep 03 14:48:49 volumio volumio[1033]: info: CorePlayQueue::clearPlayQueue Sep 03 14:48:49 volumio volumio[1033]: info: CorePlayQueue::saveQueue Sep 03 14:48:49 volumio volumio[1033]: info: CoreCommandRouter::volumioPushQueue Sep 03 14:48:49 volumio volumio[1033]: info: CoreStateMachine::addQueueItems Sep 03 14:48:49 volumio volumio[1033]: info: CorePlayQueue::addQueueItems Sep 03 14:48:49 volumio volumio[1033]: info: Preload queue cleared Sep 03 14:48:49 volumio volumio[1033]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s110482 Sep 03 14:48:49 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 03 14:48:49 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:49 volumio go-librespot[1395]: time="2025-09-03T14:48:49+02:00" level=debug msg="pause track at 22438ms" Sep 03 14:48:49 volumio go-librespot[1395]: time="2025-09-03T14:48:49+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 03 14:48:49 volumio go-librespot[1395]: time="2025-09-03T14:48:49+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 03 14:48:49 volumio go-librespot[1395]: time="2025-09-03T14:48:49+02:00" level=trace msg="emitting websocket event: paused" Sep 03 14:48:49 volumio volumio[1033]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1CPZ5BxNNd0n0nF4Orb9JS","play_origin":"go-librespot"}} Sep 03 14:48:49 volumio volumio[1033]: SPOTIFY: PUSH STATE SPOTIFY Sep 03 14:48:49 volumio volumio[1033]: SPOTIFY: {"status":"pause","service":"spop","title":"Golden","artist":"HUNTR/X, EJAE, AUDREY NUNA, REI AMI, KPop Demon Hunters Cast","album":"KPop Demon Hunters (Soundtrack from the Netflix Film)","albumart":"https://i.scdn.co/image/ab67616d00001e024dcb6c5df15cf74596ab25a4","uri":"spotify:track:1CPZ5BxNNd0n0nF4Orb9JS","trackType":"spotify","seek":22000,"duration":194,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 03 14:48:49 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:49 volumio volumio[1033]: info: CorePlayQueue::getTrack 1 Sep 03 14:48:49 volumio volumio[1033]: verbose: STATE SERVICE {"status":"pause","service":"spop","title":"Golden","artist":"HUNTR/X, EJAE, AUDREY NUNA, REI AMI, KPop Demon Hunters Cast","album":"KPop Demon Hunters (Soundtrack from the Netflix Film)","albumart":"https://i.scdn.co/image/ab67616d00001e024dcb6c5df15cf74596ab25a4","uri":"spotify:track:1CPZ5BxNNd0n0nF4Orb9JS","trackType":"spotify","seek":22000,"duration":194,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 03 14:48:49 volumio volumio[1033]: verbose: CURRENT POSITION 1 Sep 03 14:48:49 volumio volumio[1033]: info: CoreStateMachine::syncState stateService pause Sep 03 14:48:49 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:49 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:49 volumio volumio[1033]: info: CorePlayQueue::getTrack 1 Sep 03 14:48:49 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:49 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:49 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::volumioPushQueue Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::saveQueue Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::updateTrackBlock Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::getTrackBlock Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::volumioPlay Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::play index 0 Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::stop Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::play index undefined Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::startPlaybackTimer Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:50 volumio volumio[1033]: info: [1756903730093] ControllerWebradio::clearAddPlayTrack Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand stop took 1 milliseconds Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand clear Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand clear took 1 milliseconds Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand load "https://azuracast.novi-net.net/radio/8010/radiomax.mp3" Sep 03 14:48:50 volumio volumio[1033]: info: Sep 03 14:48:50 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:50 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:50 volumio volumio[1033]: info: Sep 03 14:48:50 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:50 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:50 volumio volumio[1033]: info: Sep 03 14:48:50 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:50 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand add "https://azuracast.novi-net.net/radio/8010/radiomax.mp3" Sep 03 14:48:50 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:48:50 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:48:50 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:48:50 volumio volumio[1033]: info: ------------------------------ 450ms Sep 03 14:48:50 volumio volumio[1033]: info: ------------------------------ 449ms Sep 03 14:48:50 volumio volumio[1033]: info: ------------------------------ 449ms Sep 03 14:48:50 volumio volumio[1033]: info: Sep 03 14:48:50 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:50 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand add "https://azuracast.novi-net.net/radio/8010/radiomax.mp3" took 2 milliseconds Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand play Sep 03 14:48:50 volumio volumio[1033]: info: Sep 03 14:48:50 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:50 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:50 volumio volumio[1033]: info: Sep 03 14:48:50 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:50 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:50 volumio volumio[1033]: info: ------------------------------ 3ms Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand play took 2 milliseconds Sep 03 14:48:50 volumio volumio[1033]: info: ------------------------------ 1ms Sep 03 14:48:50 volumio volumio[1033]: info: ------------------------------ 1ms Sep 03 14:48:50 volumio volumio[1033]: info: Sep 03 14:48:50 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:50 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:50 volumio volumio[1033]: info: Sep 03 14:48:50 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:50 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:50 volumio volumio[1033]: info: Sep 03 14:48:50 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:50 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:50 volumio volumio[1033]: info: Sep 03 14:48:50 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:50 volumio volumio[1033]: info: Sep 03 14:48:50 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:50 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Sep 03 14:48:50 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand status took 2 milliseconds Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand status took 1 milliseconds Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:50 volumio volumio[1033]: info: Sep 03 14:48:50 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:50 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:50 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:50 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiomax.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8010/radiomax.mp3","trackType":"mp3"} Sep 03 14:48:50 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:50 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:50 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiomax.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8010/radiomax.mp3","trackType":"mp3"} Sep 03 14:48:50 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:50 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:50 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:50 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiomax.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8010/radiomax.mp3","trackType":"mp3"} Sep 03 14:48:50 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:50 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:50 volumio volumio[1033]: info: ------------------------------ 15ms Sep 03 14:48:50 volumio volumio[1033]: info: ------------------------------ 21ms Sep 03 14:48:50 volumio volumio[1033]: info: ------------------------------ 21ms Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand status took 18 milliseconds Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:50 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:50 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiomax.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8010/radiomax.mp3","trackType":"mp3"} Sep 03 14:48:50 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:50 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:50 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:50 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiomax.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8010/radiomax.mp3","trackType":"mp3"} Sep 03 14:48:50 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:50 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:50 volumio volumio[1033]: info: ------------------------------ 31ms Sep 03 14:48:50 volumio volumio[1033]: info: ------------------------------ 32ms Sep 03 14:48:50 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:50 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:50 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:50 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:50 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:50 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:50 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:50 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:50 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 03 14:48:50 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:50 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:50 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiomax.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8010/radiomax.mp3","trackType":"mp3"} Sep 03 14:48:50 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:50 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:50 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:50 volumio volumio[1033]: info: ------------------------------ 46ms Sep 03 14:48:50 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:50 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:51 volumio go-librespot[1395]: time="2025-09-03T14:48:51+02:00" level=trace msg="sent dealer ping" Sep 03 14:48:51 volumio go-librespot[1395]: time="2025-09-03T14:48:51+02:00" level=trace msg="received dealer pong" Sep 03 14:48:51 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Sep 03 14:48:56 volumio volumio[1033]: info: Preload queue cleared Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::ClearQueue Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::stop Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::stPlaybackTimer Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::updateTrackBlock Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrackBlock Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::serviceStop Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::serviceStop Sep 03 14:48:56 volumio volumio[1033]: info: [1756903736581] ControllerWebradio::stop Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::clearPlayQueue Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::saveQueue Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::volumioPushQueue Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::addQueueItems Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::addQueueItems Sep 03 14:48:56 volumio volumio[1033]: info: Preload queue cleared Sep 03 14:48:56 volumio volumio[1033]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s73240 Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 03 14:48:56 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:56 volumio volumio[1033]: info: Sep 03 14:48:56 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:56 volumio volumio[1033]: info: sendMpdCommand stop took 21 milliseconds Sep 03 14:48:56 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:56 volumio volumio[1033]: info: Sep 03 14:48:56 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:56 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:56 volumio volumio[1033]: info: Sep 03 14:48:56 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:56 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:56 volumio volumio[1033]: info: sendMpdCommand status took 2 milliseconds Sep 03 14:48:56 volumio volumio[1033]: info: sendMpdCommand status took 1 milliseconds Sep 03 14:48:56 volumio volumio[1033]: info: sendMpdCommand status took 1 milliseconds Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:56 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 14:48:56 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 14:48:56 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:56 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiomax.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8010/radiomax.mp3","trackType":"mp3"} Sep 03 14:48:56 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:56 volumio volumio[1033]: info: No code Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:56 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiomax.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8010/radiomax.mp3","trackType":"mp3"} Sep 03 14:48:56 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:56 volumio volumio[1033]: info: No code Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:56 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radiomax.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8010/radiomax.mp3","trackType":"mp3"} Sep 03 14:48:56 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:56 volumio volumio[1033]: info: No code Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:56 volumio volumio[1033]: info: ------------------------------ 21ms Sep 03 14:48:56 volumio volumio[1033]: info: ------------------------------ 20ms Sep 03 14:48:56 volumio volumio[1033]: info: ------------------------------ 20ms Sep 03 14:48:56 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:56 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:56 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:56 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:56 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:56 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:56 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:56 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:56 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::volumioPushQueue Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::saveQueue Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::updateTrackBlock Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrackBlock Sep 03 14:48:56 volumio volumio[1033]: info: CoreCommandRouter::volumioPlay Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::play index 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::stop Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::play index undefined Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: info: CoreStateMachine::startPlaybackTimer Sep 03 14:48:56 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:56 volumio volumio[1033]: info: [1756903736912] ControllerWebradio::clearAddPlayTrack Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 14:48:56 volumio volumio[1033]: info: sendMpdCommand stop took 1 milliseconds Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand clear Sep 03 14:48:56 volumio volumio[1033]: info: Sep 03 14:48:56 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:56 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:56 volumio volumio[1033]: info: sendMpdCommand clear took 0 milliseconds Sep 03 14:48:56 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand load "http://stream1.radio-banovina.hr:9998/" Sep 03 14:48:56 volumio volumio[1033]: info: Sep 03 14:48:56 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:56 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:56 volumio volumio[1033]: info: Sep 03 14:48:56 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:56 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:56 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:48:56 volumio volumio[1033]: info: ------------------------------ 2ms Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand add "http://stream1.radio-banovina.hr:9998/" Sep 03 14:48:57 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:48:57 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 151ms Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 151ms Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand add "http://stream1.radio-banovina.hr:9998/" took 1 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand play Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:57 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:57 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:57 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand play took 2 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 2ms Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 1ms Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 0ms Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand status took 4 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand status took 2 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://stream1.radio-banovina.hr:9998/","trackType":"hr:9998/"} Sep 03 14:48:57 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 6ms Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand status took 1 milliseconds Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://stream1.radio-banovina.hr:9998/","trackType":"hr:9998/"} Sep 03 14:48:57 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:57 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://stream1.radio-banovina.hr:9998/","trackType":"hr:9998/"} Sep 03 14:48:57 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:57 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://stream1.radio-banovina.hr:9998/","trackType":"hr:9998/"} Sep 03 14:48:57 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:57 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 19ms Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 20ms Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 19ms Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://stream1.radio-banovina.hr:9998/","trackType":"hr:9998/"} Sep 03 14:48:57 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:57 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://stream1.radio-banovina.hr:9998/","trackType":"hr:9998/"} Sep 03 14:48:57 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:57 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 26ms Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 25ms Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: info: Preload queue cleared Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::ClearQueue Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::stop Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::stPlaybackTimer Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::updateTrackBlock Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrackBlock Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::serviceStop Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::serviceStop Sep 03 14:48:57 volumio volumio[1033]: info: [1756903737280] ControllerWebradio::stop Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::clearPlayQueue Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::saveQueue Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushQueue Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::addQueueItems Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::addQueueItems Sep 03 14:48:57 volumio volumio[1033]: info: Preload queue cleared Sep 03 14:48:57 volumio volumio[1033]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s50230 Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand stop took 11 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand status took 2 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand status took 1 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand status took 1 milliseconds Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://stream1.radio-banovina.hr:9998/","trackType":"hr:9998/"} Sep 03 14:48:57 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: No code Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://stream1.radio-banovina.hr:9998/","trackType":"hr:9998/"} Sep 03 14:48:57 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: No code Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://stream1.radio-banovina.hr:9998/","trackType":"hr:9998/"} Sep 03 14:48:57 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: No code Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 24ms Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 23ms Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 23ms Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPushQueue Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::saveQueue Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::updateTrackBlock Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrackBlock Sep 03 14:48:57 volumio volumio[1033]: info: CoreCommandRouter::volumioPlay Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::play index 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::stop Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::play index undefined Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: info: CoreStateMachine::startPlaybackTimer Sep 03 14:48:57 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:57 volumio volumio[1033]: info: [1756903737519] ControllerWebradio::clearAddPlayTrack Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand stop took 1 milliseconds Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand clear Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:57 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:57 volumio volumio[1033]: info: sendMpdCommand clear took 0 milliseconds Sep 03 14:48:57 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand load "https://s8.iqstreaming.com:2020/stream/rhzk1" Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:57 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:57 volumio volumio[1033]: info: Sep 03 14:48:57 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:57 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:57 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:48:57 volumio volumio[1033]: info: ------------------------------ 3ms Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand add "https://s8.iqstreaming.com:2020/stream/rhzk1" Sep 03 14:48:58 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:48:58 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 574ms Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 574ms Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:58 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand add "https://s8.iqstreaming.com:2020/stream/rhzk1" took 1 milliseconds Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand play Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:58 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:58 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 2ms Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand play took 2 milliseconds Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 1ms Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 2ms Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand status took 2 milliseconds Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand status took 5 milliseconds Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand status took 2 milliseconds Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rhzk1","artist":null,"album":null,"uri":"https://s8.iqstreaming.com:2020/stream/rhzk1","trackType":""} Sep 03 14:48:58 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rhzk1","artist":null,"album":null,"uri":"https://s8.iqstreaming.com:2020/stream/rhzk1","trackType":""} Sep 03 14:48:58 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:58 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 11ms Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 13ms Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand status took 8 milliseconds Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rhzk1","artist":null,"album":null,"uri":"https://s8.iqstreaming.com:2020/stream/rhzk1","trackType":""} Sep 03 14:48:58 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:58 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rhzk1","artist":null,"album":null,"uri":"https://s8.iqstreaming.com:2020/stream/rhzk1","trackType":""} Sep 03 14:48:58 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:58 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rhzk1","artist":null,"album":null,"uri":"https://s8.iqstreaming.com:2020/stream/rhzk1","trackType":""} Sep 03 14:48:58 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:58 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 25ms Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 24ms Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 23ms Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 19 milliseconds Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rhzk1","artist":null,"album":null,"uri":"https://s8.iqstreaming.com:2020/stream/rhzk1","trackType":""} Sep 03 14:48:58 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:58 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 35ms Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:58 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:58 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:58 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:58 volumio volumio[1033]: info: Sep 03 14:48:58 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 3ms Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand status took 4 milliseconds Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 4ms Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand status took 4 milliseconds Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 3ms Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand status took 2 milliseconds Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rhzk1","artist":"No Name","album":null,"uri":"https://s8.iqstreaming.com:2020/stream/rhzk1","trackType":""} Sep 03 14:48:58 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:58 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":606,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rhzk1","artist":"No Name","album":null,"uri":"https://s8.iqstreaming.com:2020/stream/rhzk1","trackType":""} Sep 03 14:48:58 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:58 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 15ms Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 15ms Sep 03 14:48:58 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 03 14:48:58 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:58 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"rhzk1","artist":"No Name","album":null,"uri":"https://s8.iqstreaming.com:2020/stream/rhzk1","trackType":""} Sep 03 14:48:58 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:58 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:58 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:58 volumio volumio[1033]: info: ------------------------------ 18ms Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:58 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: info: Preload queue cleared Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::ClearQueue Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::stop Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::stPlaybackTimer Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::updateTrackBlock Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrackBlock Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::serviceStop Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::serviceStop Sep 03 14:48:59 volumio volumio[1033]: info: [1756903739238] ControllerWebradio::stop Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::clearPlayQueue Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::saveQueue Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushQueue Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::addQueueItems Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::addQueueItems Sep 03 14:48:59 volumio volumio[1033]: info: Preload queue cleared Sep 03 14:48:59 volumio volumio[1033]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s73239 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand stop took 13 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand status took 2 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand status took 1 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand status took 1 milliseconds Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rhzk1","artist":"No Name","album":null,"uri":"https://s8.iqstreaming.com:2020/stream/rhzk1","trackType":""} Sep 03 14:48:59 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: No code Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rhzk1","artist":"No Name","album":null,"uri":"https://s8.iqstreaming.com:2020/stream/rhzk1","trackType":""} Sep 03 14:48:59 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: No code Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rhzk1","artist":"No Name","album":null,"uri":"https://s8.iqstreaming.com:2020/stream/rhzk1","trackType":""} Sep 03 14:48:59 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: No code Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 20ms Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 20ms Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 20ms Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushQueue Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::saveQueue Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::updateTrackBlock Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrackBlock Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPlay Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::play index 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::stop Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::play index undefined Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::startPlaybackTimer Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: [1756903739468] ControllerWebradio::clearAddPlayTrack Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand stop took 2 milliseconds Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand clear Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:59 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand clear took 0 milliseconds Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand load "https://azuracast.novi-net.net/radio/8050/radio105.mp3" Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:59 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:59 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:59 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 2ms Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand add "https://azuracast.novi-net.net/radio/8050/radio105.mp3" Sep 03 14:48:59 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:48:59 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 50ms Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 50ms Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand add "https://azuracast.novi-net.net/radio/8050/radio105.mp3" took 1 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand play Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:59 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:59 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:48:59 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand play took 3 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 2ms Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 2ms Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 1ms Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand status took 4 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand status took 4 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand status took 1 milliseconds Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio105.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8050/radio105.mp3","trackType":"mp3"} Sep 03 14:48:59 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 7ms Sep 03 14:48:59 volumio volumio[1033]: info: Sep 03 14:48:59 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand status took 1 milliseconds Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio105.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8050/radio105.mp3","trackType":"mp3"} Sep 03 14:48:59 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:59 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio105.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8050/radio105.mp3","trackType":"mp3"} Sep 03 14:48:59 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:59 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio105.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8050/radio105.mp3","trackType":"mp3"} Sep 03 14:48:59 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:59 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 21ms Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 21ms Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 19ms Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 03 14:48:59 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:59 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio105.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8050/radio105.mp3","trackType":"mp3"} Sep 03 14:48:59 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:59 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio105.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8050/radio105.mp3","trackType":"mp3"} Sep 03 14:48:59 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:48:59 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:48:59 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 27ms Sep 03 14:48:59 volumio volumio[1033]: info: ------------------------------ 24ms Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:48:59 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: info: Preload queue cleared Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::ClearQueue Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::stop Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::stPlaybackTimer Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::updateTrackBlock Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrackBlock Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::serviceStop Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::serviceStop Sep 03 14:49:00 volumio volumio[1033]: info: [1756903740472] ControllerWebradio::stop Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::clearPlayQueue Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::saveQueue Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushQueue Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::addQueueItems Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::addQueueItems Sep 03 14:49:00 volumio volumio[1033]: info: Preload queue cleared Sep 03 14:49:00 volumio volumio[1033]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s8910 Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand stop took 9 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand status took 2 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand status took 1 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand status took 1 milliseconds Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio105.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8050/radio105.mp3","trackType":"mp3"} Sep 03 14:49:00 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: No code Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio105.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8050/radio105.mp3","trackType":"mp3"} Sep 03 14:49:00 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: No code Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio105.mp3","artist":null,"album":null,"uri":"https://azuracast.novi-net.net/radio/8050/radio105.mp3","trackType":"mp3"} Sep 03 14:49:00 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState stateService stop Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: No code Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 20ms Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 19ms Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 19ms Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushQueue Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::saveQueue Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::updateTrackBlock Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrackBlock Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPlay Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::play index 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::stop Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::play index undefined Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::startPlaybackTimer Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: info: [1756903740703] ControllerWebradio::clearAddPlayTrack Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand stop took 2 milliseconds Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand clear Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:49:00 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand clear took 1 milliseconds Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand load "http://live.radio101.hr:9531/" Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:49:00 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:49:00 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:49:00 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 3ms Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand add "http://live.radio101.hr:9531/" Sep 03 14:49:00 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:49:00 volumio volumio[1033]: error: updateQueue error: null Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 132ms Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 131ms Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:49:00 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand add "http://live.radio101.hr:9531/" took 2 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand play Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:49:00 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:49:00 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 2ms Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand play took 1 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 2ms Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 1ms Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:49:00 volumio volumio[1033]: info: Sep 03 14:49:00 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand status took 2 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand status took 2 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand status took 1 milliseconds Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://live.radio101.hr:9531/","trackType":"hr:9531/"} Sep 03 14:49:00 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus stop Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://live.radio101.hr:9531/","trackType":"hr:9531/"} Sep 03 14:49:00 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:49:00 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://live.radio101.hr:9531/","trackType":"hr:9531/"} Sep 03 14:49:00 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:49:00 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 14ms Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 18ms Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 18ms Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 03 14:49:00 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 11 milliseconds Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:49:00 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://live.radio101.hr:9531/","trackType":"hr:9531/"} Sep 03 14:49:00 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:49:00 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://live.radio101.hr:9531/","trackType":"hr:9531/"} Sep 03 14:49:00 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:49:00 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:00 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"http://live.radio101.hr:9531/","trackType":"hr:9531/"} Sep 03 14:49:00 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:49:00 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:00 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 29ms Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 27ms Sep 03 14:49:00 volumio volumio[1033]: info: ------------------------------ 28ms Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:00 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:01 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:01 volumio volumio[1033]: info: Sep 03 14:49:01 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:49:01 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:49:01 volumio volumio[1033]: info: Sep 03 14:49:01 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:49:01 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:49:01 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:49:01 volumio volumio[1033]: info: Sep 03 14:49:01 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:49:01 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:49:01 volumio volumio[1033]: info: Sep 03 14:49:01 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:49:01 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:49:01 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:49:01 volumio volumio[1033]: info: Sep 03 14:49:01 volumio volumio[1033]: ---------------------------- MPD announces system playlist update Sep 03 14:49:01 volumio volumio[1033]: info: Ignoring MPD Status Update Sep 03 14:49:01 volumio volumio[1033]: info: Sep 03 14:49:01 volumio volumio[1033]: ---------------------------- MPD announces state update: player Sep 03 14:49:01 volumio volumio[1033]: info: ControllerMpd::getState Sep 03 14:49:01 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:49:01 volumio volumio[1033]: info: ------------------------------ 5ms Sep 03 14:49:01 volumio volumio[1033]: info: sendMpdCommand status took 3 milliseconds Sep 03 14:49:01 volumio volumio[1033]: info: ------------------------------ 2ms Sep 03 14:49:01 volumio volumio[1033]: info: sendMpdCommand status took 2 milliseconds Sep 03 14:49:01 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:49:01 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:49:01 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:49:01 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:49:01 volumio volumio[1033]: info: ------------------------------ 2ms Sep 03 14:49:01 volumio volumio[1033]: info: sendMpdCommand status took 2 milliseconds Sep 03 14:49:01 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 14:49:01 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 03 14:49:01 volumio volumio[1033]: verbose: ControllerMpd::parseState Sep 03 14:49:01 volumio volumio[1033]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:49:01 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:49:01 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:49:01 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:49:01 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:49:01 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:01 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Rudimental - Free Ft. Emeli Sande","artist":"Radio 101","album":null,"uri":"http://live.radio101.hr:9531/","trackType":"hr:9531/"} Sep 03 14:49:01 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:49:01 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:49:01 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:49:01 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:49:01 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:01 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:49:01 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:01 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:01 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:01 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:49:01 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:49:01 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:01 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Rudimental - Free Ft. Emeli Sande","artist":"Radio 101","album":null,"uri":"http://live.radio101.hr:9531/","trackType":"hr:9531/"} Sep 03 14:49:01 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:49:01 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:49:01 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:49:01 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:49:01 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:01 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:01 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:01 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:01 volumio volumio[1033]: info: ------------------------------ 18ms Sep 03 14:49:01 volumio volumio[1033]: info: ------------------------------ 16ms Sep 03 14:49:01 volumio volumio[1033]: info: sendMpdCommand playlistinfo took 12 milliseconds Sep 03 14:49:01 volumio volumio[1033]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:49:01 volumio volumio[1033]: info: ControllerMpd::pushState Sep 03 14:49:01 volumio volumio[1033]: info: CoreCommandRouter::servicePushState Sep 03 14:49:01 volumio volumio[1033]: info: CorePlayQueue::getTrack 0 Sep 03 14:49:01 volumio volumio[1033]: verbose: STATE SERVICE {"status":"play","position":0,"seek":985,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Rudimental - Free Ft. Emeli Sande","artist":"Radio 101","album":null,"uri":"http://live.radio101.hr:9531/","trackType":"hr:9531/"} Sep 03 14:49:01 volumio volumio[1033]: verbose: CURRENT POSITION 0 Sep 03 14:49:01 volumio volumio[1033]: info: CoreStateMachine::syncState stateService play Sep 03 14:49:01 volumio volumio[1033]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:49:01 volumio volumio[1033]: info: Received an update from plugin. extracting info from payload Sep 03 14:49:01 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:01 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:01 volumio volumio[1033]: info: CoreStateMachine::pushState Sep 03 14:49:01 volumio volumio[1033]: info: CoreCommandRouter::volumioPushState Sep 03 14:49:01 volumio volumio[1033]: info: ------------------------------ 20ms Sep 03 14:49:01 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:01 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:01 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:01 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:01 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:01 volumio volumio[1033]: SPOTIFY: RECEIVED VOLUMIO VOLUME 61 Sep 03 14:49:03 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 14:49:03 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 14:49:03 volumio volumio[1033]: info: Discovery: Getting this device information Sep 03 14:49:03 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:03 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:49:06 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 14:49:06 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 14:49:06 volumio volumio[1033]: info: Discovery: Getting this device information Sep 03 14:49:06 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:06 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:49:07 volumio volumio[1033]: verbose: New Socket.io Connection to 192.168.100.51:3000 from 192.168.100.37 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Sep 03 14:49:07 volumio volumio[1033]: verbose: New Socket.io Connection to 192.168.100.51:3000 from 192.168.100.37 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Sep 03 14:49:08 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 14:49:08 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 14:49:08 volumio volumio[1033]: info: Discovery: Getting this device information Sep 03 14:49:08 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:08 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:49:21 volumio go-librespot[1395]: time="2025-09-03T14:49:21+02:00" level=trace msg="sent dealer ping" Sep 03 14:49:21 volumio go-librespot[1395]: time="2025-09-03T14:49:21+02:00" level=trace msg="received dealer pong" Sep 03 14:49:28 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Sep 03 14:49:28 volumio dbus-daemon[621]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.1861' (uid=0 pid=2105 comm="timedatectl show --property=NTPSynchronized --valu") Sep 03 14:49:28 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Sep 03 14:49:28 volumio dbus-daemon[621]: [system] Successfully activated service 'org.freedesktop.timedate1' Sep 03 14:49:28 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Sep 03 14:49:28 volumio setdatetime-helper.sh[2104]: Time is already synchronized. Sep 03 14:49:28 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Sep 03 14:49:28 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Sep 03 14:49:33 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 03 14:49:33 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 03 14:49:33 volumio volumio[1033]: info: Discovery: Getting this device information Sep 03 14:49:33 volumio volumio[1033]: info: CoreCommandRouter::volumioGetState Sep 03 14:49:33 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 03 14:49:51 volumio go-librespot[1395]: time="2025-09-03T14:49:51+02:00" level=trace msg="sent dealer ping" Sep 03 14:49:51 volumio go-librespot[1395]: time="2025-09-03T14:49:51+02:00" level=trace msg="received dealer pong" Sep 03 14:49:56 volumio volumio[1033]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Sep 03 14:49:56 volumio volumio[1033]: Use XMLElement.setAttribute instead Sep 03 14:49:56 volumio volumio[1033]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:49:56 volumio volumio[1033]: TypeError: children[i].attr(...).value is not a function Sep 03 14:49:56 volumio volumio[1033]: at /volumio/app/plugins/music_service/webradio/index.js:428:46 Sep 03 14:49:56 volumio volumio[1033]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Sep 03 14:49:56 volumio volumio[1033]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:49:57 volumio sudo[2223]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-09-03 14:48' Sep 03 14:49:57 volumio sudo[2223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="4a70cb031e64a8e498efc3e29470650871ff7d54" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat May 31 04:09:10 UTC 2025" VOLUMIO_VERSION="4.005" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f9bd4447a30d4b2c74b3880e7a50e48b"